bitnami / containers

Bitnami container images
https://bitnami.com
Other
3.29k stars 4.78k forks source link

Zookeeper sometimes fails to start accepting connections #5586

Closed florian-besser closed 1 year ago

florian-besser commented 2 years ago

Name and Version

bitnami/zookeeper:3.7.1

What steps will reproduce the bug?

Use the following docker-compose file:

version: "3.7"
services:
  zookeeper:
    container_name: zookeeper
    networks:
      - mynet
    image: bitnami/zookeeper:3.7.1
    ports:
      - 2181:2181
    environment:
      - ALLOW_ANONYMOUS_LOGIN=yes
    restart: always
    deploy:
      resources:
        limits:
          memory: 256M
  kafka:
    container_name: kafka
    networks:
      - mynet
    depends_on:
      - "zookeeper"
    image: bitnami/kafka:2.8.1
    ports:
      - 9092:9092
    environment:
      - ALLOW_PLAINTEXT_LISTENER=yes
      - KAFKA_BROKER_ID=500
      - KAFKA_CFG_BROKER_ID_GENERATION_ENABLE=false
      - KAFKA_CFG_ZOOKEEPER_CONNECT=zookeeper:2181
      - KAFKA_LISTENERS=PLAINTEXT://:9092,DOCKER://:29092
      - KAFKA_ADVERTISED_LISTENERS=PLAINTEXT://localhost:9092,DOCKER://kafka:29092
      - KAFKA_LISTENER_SECURITY_PROTOCOL_MAP=PLAINTEXT:PLAINTEXT,DOCKER:PLAINTEXT
      - KAFKA_HEAP_OPTS=-Xmx512m -Xms512m
    restart: always
    deploy:
      resources:
        limits:
          memory: 512M
networks:
  mynet:
    name: mynet

Most of the time both apps come up and work perfectly fine.

Sometimes Zookeeper refuses connections (see below)

What is the expected behavior?

Both apps should come up all the time; or the container should self-detect it is unhealthy and restart until it is healthy.

What do you see instead?

Kafka produces the following log and then ends in a restart spiral:

[2022-09-08 09:23:40,180] INFO Client environment:java.vendor=BellSoft (org.apache.zookeeper.ZooKeeper)
[2022-09-08 09:23:40,180] INFO Client environment:java.home=/opt/bitnami/java (org.apache.zookeeper.ZooKeeper)
[2022-09-08 09:23:40,180] INFO Client environment:java.class.path=/opt/bitnami/kafka/bin/../libs/activation-1.1.1.jar:/opt/bitnami/kafka/bin/../libs/aopalliance-repackaged-2.6.1.jar:/opt/bitnami/kafka/bin/../libs/argparse4j-0.7.0.jar:/opt/bitnami/kafka/bin/../libs/audience-annotations-0.5.0.jar:/opt/bitnami/kafka/bin/../libs/commons-cli-1.4.jar:/opt/bitnami/kafka/bin/../libs/commons-lang3-3.8.1.jar:/opt/bitnami/kafka/bin/../libs/connect-api-2.8.1.jar:/opt/bitnami/kafka/bin/../libs/connect-basic-auth-extension-2.8.1.jar:/opt/bitnami/kafka/bin/../libs/connect-file-2.8.1.jar:/opt/bitnami/kafka/bin/../libs/connect-json-2.8.1.jar:/opt/bitnami/kafka/bin/../libs/connect-mirror-2.8.1.jar:/opt/bitnami/kafka/bin/../libs/connect-mirror-client-2.8.1.jar:/opt/bitnami/kafka/bin/../libs/connect-runtime-2.8.1.jar:/opt/bitnami/kafka/bin/../libs/connect-transforms-2.8.1.jar:/opt/bitnami/kafka/bin/../libs/hk2-api-2.6.1.jar:/opt/bitnami/kafka/bin/../libs/hk2-locator-2.6.1.jar:/opt/bitnami/kafka/bin/../libs/hk2-utils-2.6.1.jar:/opt/bitnami/kafka/bin/../libs/jackson-annotations-2.10.5.jar:/opt/bitnami/kafka/bin/../libs/jackson-core-2.10.5.jar:/opt/bitnami/kafka/bin/../libs/jackson-databind-2.10.5.1.jar:/opt/bitnami/kafka/bin/../libs/jackson-dataformat-csv-2.10.5.jar:/opt/bitnami/kafka/bin/../libs/jackson-datatype-jdk8-2.10.5.jar:/opt/bitnami/kafka/bin/../libs/jackson-jaxrs-base-2.10.5.jar:/opt/bitnami/kafka/bin/../libs/jackson-jaxrs-json-provider-2.10.5.jar:/opt/bitnami/kafka/bin/../libs/jackson-module-jaxb-annotations-2.10.5.jar:/opt/bitnami/kafka/bin/../libs/jackson-module-paranamer-2.10.5.jar:/opt/bitnami/kafka/bin/../libs/jackson-module-scala_2.12-2.10.5.jar:/opt/bitnami/kafka/bin/../libs/jakarta.activation-api-1.2.1.jar:/opt/bitnami/kafka/bin/../libs/jakarta.annotation-api-1.3.5.jar:/opt/bitnami/kafka/bin/../libs/jakarta.inject-2.6.1.jar:/opt/bitnami/kafka/bin/../libs/jakarta.validation-api-2.0.2.jar:/opt/bitnami/kafka/bin/../libs/jakarta.ws.rs-api-2.1.6.jar:/opt/bitnami/kafka/bin/../libs/jakarta.xml.bind-api-2.3.2.jar:/opt/bitnami/kafka/bin/../libs/javassist-3.27.0-GA.jar:/opt/bitnami/kafka/bin/../libs/javax.servlet-api-3.1.0.jar:/opt/bitnami/kafka/bin/../libs/javax.ws.rs-api-2.1.1.jar:/opt/bitnami/kafka/bin/../libs/jaxb-api-2.3.0.jar:/opt/bitnami/kafka/bin/../libs/jersey-client-2.34.jar:/opt/bitnami/kafka/bin/../libs/jersey-common-2.34.jar:/opt/bitnami/kafka/bin/../libs/jersey-container-servlet-2.34.jar:/opt/bitnami/kafka/bin/../libs/jersey-container-servlet-core-2.34.jar:/opt/bitnami/kafka/bin/../libs/jersey-hk2-2.34.jar:/opt/bitnami/kafka/bin/../libs/jersey-server-2.34.jar:/opt/bitnami/kafka/bin/../libs/jetty-client-9.4.43.v20210629.jar:/opt/bitnami/kafka/bin/../libs/jetty-continuation-9.4.43.v20210629.jar:/opt/bitnami/kafka/bin/../libs/jetty-http-9.4.43.v20210629.jar:/opt/bitnami/kafka/bin/../libs/jetty-io-9.4.43.v20210629.jar:/opt/bitnami/kafka/bin/../libs/jetty-security-9.4.43.v20210629.jar:/opt/bitnami/kafka/bin/../libs/jetty-server-9.4.43.v20210629.jar:/opt/bitnami/kafka/bin/../libs/jetty-servlet-9.4.43.v20210629.jar:/opt/bitnami/kafka/bin/../libs/jetty-servlets-9.4.43.v20210629.jar:/opt/bitnami/kafka/bin/../libs/jetty-util-9.4.43.v20210629.jar:/opt/bitnami/kafka/bin/../libs/jetty-util-ajax-9.4.43.v20210629.jar:/opt/bitnami/kafka/bin/../libs/jline-3.12.1.jar:/opt/bitnami/kafka/bin/../libs/jopt-simple-5.0.4.jar:/opt/bitnami/kafka/bin/../libs/kafka-clients-2.8.1.jar:/opt/bitnami/kafka/bin/../libs/kafka-log4j-appender-2.8.1.jar:/opt/bitnami/kafka/bin/../libs/kafka-metadata-2.8.1.jar:/opt/bitnami/kafka/bin/../libs/kafka-raft-2.8.1.jar:/opt/bitnami/kafka/bin/../libs/kafka-shell-2.8.1.jar:/opt/bitnami/kafka/bin/../libs/kafka-streams-2.8.1.jar:/opt/bitnami/kafka/bin/../libs/kafka-streams-examples-2.8.1.jar:/opt/bitnami/kafka/bin/../libs/kafka-streams-scala_2.12-2.8.1.jar:/opt/bitnami/kafka/bin/../libs/kafka-streams-test-utils-2.8.1.jar:/opt/bitnami/kafka/bin/../libs/kafka-tools-2.8.1.jar:/opt/bitnami/kafka/bin/../libs/kafka_2.12-2.8.1-sources.jar:/opt/bitnami/kafka/bin/../libs/kafka_2.12-2.8.1.jar:/opt/bitnami/kafka/bin/../libs/log4j-1.2.17.jar:/opt/bitnami/kafka/bin/../libs/lz4-java-1.7.1.jar:/opt/bitnami/kafka/bin/../libs/maven-artifact-3.8.1.jar:/opt/bitnami/kafka/bin/../libs/metrics-core-2.2.0.jar:/opt/bitnami/kafka/bin/../libs/netty-buffer-4.1.62.Final.jar:/opt/bitnami/kafka/bin/../libs/netty-codec-4.1.62.Final.jar:/opt/bitnami/kafka/bin/../libs/netty-common-4.1.62.Final.jar:/opt/bitnami/kafka/bin/../libs/netty-handler-4.1.62.Final.jar:/opt/bitnami/kafka/bin/../libs/netty-resolver-4.1.62.Final.jar:/opt/bitnami/kafka/bin/../libs/netty-transport-4.1.62.Final.jar:/opt/bitnami/kafka/bin/../libs/netty-transport-native-epoll-4.1.62.Final.jar:/opt/bitnami/kafka/bin/../libs/netty-transport-native-unix-common-4.1.62.Final.jar:/opt/bitnami/kafka/bin/../libs/osgi-resource-locator-1.0.3.jar:/opt/bitnami/kafka/bin/../libs/paranamer-2.8.jar:/opt/bitnami/kafka/bin/../libs/plexus-utils-3.2.1.jar:/opt/bitnami/kafka/bin/../libs/reflections-0.9.12.jar:/opt/bitnami/kafka/bin/../libs/rocksdbjni-5.18.4.jar:/opt/bitnami/kafka/bin/../libs/scala-collection-compat_2.12-2.3.0.jar:/opt/bitnami/kafka/bin/../libs/scala-java8-compat_2.12-0.9.1.jar:/opt/bitnami/kafka/bin/../libs/scala-library-2.12.13.jar:/opt/bitnami/kafka/bin/../libs/scala-logging_2.12-3.9.2.jar:/opt/bitnami/kafka/bin/../libs/scala-reflect-2.12.13.jar:/opt/bitnami/kafka/bin/../libs/slf4j-api-1.7.30.jar:/opt/bitnami/kafka/bin/../libs/slf4j-log4j12-1.7.30.jar:/opt/bitnami/kafka/bin/../libs/snappy-java-1.1.8.1.jar:/opt/bitnami/kafka/bin/../libs/zookeeper-3.5.9.jar:/opt/bitnami/kafka/bin/../libs/zookeeper-jute-3.5.9.jar:/opt/bitnami/kafka/bin/../libs/zstd-jni-1.4.9-1.jar (org.apache.zookeeper.ZooKeeper)
[2022-09-08 09:23:40,182] INFO Client environment:java.library.path=/usr/java/packages/lib:/usr/lib64:/lib64:/lib:/usr/lib (org.apache.zookeeper.ZooKeeper)
[2022-09-08 09:23:40,183] INFO Client environment:java.io.tmpdir=/tmp (org.apache.zookeeper.ZooKeeper)
[2022-09-08 09:23:40,183] INFO Client environment:java.compiler=<NA> (org.apache.zookeeper.ZooKeeper)
[2022-09-08 09:23:40,183] INFO Client environment:os.name=Linux (org.apache.zookeeper.ZooKeeper)
[2022-09-08 09:23:40,184] INFO Client environment:os.arch=amd64 (org.apache.zookeeper.ZooKeeper)
[2022-09-08 09:23:40,184] INFO Client environment:os.version=5.10.104-linuxkit (org.apache.zookeeper.ZooKeeper)
[2022-09-08 09:23:40,184] INFO Client environment:user.name=? (org.apache.zookeeper.ZooKeeper)
[2022-09-08 09:23:40,184] INFO Client environment:user.home=? (org.apache.zookeeper.ZooKeeper)
[2022-09-08 09:23:40,184] INFO Client environment:user.dir=/ (org.apache.zookeeper.ZooKeeper)
[2022-09-08 09:23:40,184] INFO Client environment:os.memory.free=499MB (org.apache.zookeeper.ZooKeeper)
[2022-09-08 09:23:40,184] INFO Client environment:os.memory.max=512MB (org.apache.zookeeper.ZooKeeper)
[2022-09-08 09:23:40,185] INFO Client environment:os.memory.total=512MB (org.apache.zookeeper.ZooKeeper)
[2022-09-08 09:23:40,197] INFO Initiating client connection, connectString=zookeeper:2181 sessionTimeout=18000 watcher=kafka.zookeeper.ZooKeeperClient$ZooKeeperClientWatcher$@363042d7 (org.apache.zookeeper.ZooKeeper)
[2022-09-08 09:23:40,222] INFO jute.maxbuffer value is 4194304 Bytes (org.apache.zookeeper.ClientCnxnSocket)
[2022-09-08 09:23:40,250] INFO zookeeper.request.timeout value is 0. feature enabled= (org.apache.zookeeper.ClientCnxn)
[2022-09-08 09:23:40,267] INFO [ZooKeeperClient Kafka server] Waiting until connected. (kafka.zookeeper.ZooKeeperClient)
[2022-09-08 09:23:40,305] INFO Opening socket connection to server zookeeper/172.21.0.4:2181. Will not attempt to authenticate using SASL (unknown error) (org.apache.zookeeper.ClientCnxn)
[2022-09-08 09:23:58,276] INFO [ZooKeeperClient Kafka server] Closing. (kafka.zookeeper.ZooKeeperClient)
[2022-09-08 09:23:58,289] WARN Client session timed out, have not heard from server in 18035ms for sessionid 0x0 (org.apache.zookeeper.ClientCnxn)
[2022-09-08 09:23:58,417] INFO Session: 0x0 closed (org.apache.zookeeper.ZooKeeper)
[2022-09-08 09:23:58,423] INFO EventThread shut down for session: 0x0 (org.apache.zookeeper.ClientCnxn)
[2022-09-08 09:23:58,430] INFO [ZooKeeperClient Kafka server] Closed. (kafka.zookeeper.ZooKeeperClient)
[2022-09-08 09:23:58,450] ERROR Fatal error during KafkaServer startup. Prepare to shutdown (kafka.server.KafkaServer)
kafka.zookeeper.ZooKeeperClientTimeoutException: Timed out waiting for connection while in state: CONNECTING
        at kafka.zookeeper.ZooKeeperClient.$anonfun$waitUntilConnected$3(ZooKeeperClient.scala:271)
        at kafka.zookeeper.ZooKeeperClient.waitUntilConnected(ZooKeeperClient.scala:267)
        at kafka.zookeeper.ZooKeeperClient.<init>(ZooKeeperClient.scala:125)
        at kafka.zk.KafkaZkClient$.apply(KafkaZkClient.scala:1948)
        at kafka.server.KafkaServer.createZkClient$1(KafkaServer.scala:431)
        at kafka.server.KafkaServer.initZkClient(KafkaServer.scala:456)
        at kafka.server.KafkaServer.startup(KafkaServer.scala:191)
        at kafka.Kafka$.main(Kafka.scala:109)
        at kafka.Kafka.main(Kafka.scala)
[2022-09-08 09:23:58,466] INFO shutting down (kafka.server.KafkaServer)
[2022-09-08 09:23:58,530] INFO App info kafka.server for 500 unregistered (org.apache.kafka.common.utils.AppInfoParser)
[2022-09-08 09:23:58,536] INFO shut down completed (kafka.server.KafkaServer)
[2022-09-08 09:23:58,537] ERROR Exiting Kafka. (kafka.Kafka$)
[2022-09-08 09:23:58,547] INFO shutting down (kafka.server.KafkaServer)

Please note the ZooKeeperClientTimeoutException towards the end of the log.

At the same time Zookeeper does not seem to log any errors:

zookeeper 08:56:54.01 
zookeeper 08:56:54.04 Welcome to the Bitnami zookeeper container
zookeeper 08:56:54.07 Subscribe to project updates by watching https://github.com/bitnami/containers
zookeeper 08:56:54.10 Submit issues and feature requests at https://github.com/bitnami/containers/issues
zookeeper 08:56:54.13 
zookeeper 08:56:54.16 INFO  ==> ** Starting ZooKeeper setup **
zookeeper 08:56:54.48 WARN  ==> You have set the environment variable ALLOW_ANONYMOUS_LOGIN=yes. For safety reasons, do not use this flag in a production environment.
zookeeper 08:56:54.60 INFO  ==> Initializing ZooKeeper...
zookeeper 08:56:54.62 INFO  ==> No injected configuration file found, creating default config files...
zookeeper 08:56:55.60 INFO  ==> No additional servers were specified. ZooKeeper will run in standalone mode...
zookeeper 08:56:55.67 INFO  ==> Deploying ZooKeeper from scratch...
zookeeper 08:56:55.70 INFO  ==> ** ZooKeeper setup finished! **

zookeeper 08:56:55.83 INFO  ==> ** Starting ZooKeeper **
/opt/bitnami/java/bin/java
ZooKeeper JMX enabled by default
Using config: /opt/bitnami/zookeeper/bin/../conf/zoo.cfg
2022-09-08 08:57:00,292 [myid:] - INFO  [main:QuorumPeerConfig@174] - Reading configuration from: /opt/bitnami/zookeeper/bin/../conf/zoo.cfg
2022-09-08 08:57:00,371 [myid:] - INFO  [main:QuorumPeerConfig@444] - clientPortAddress is 0.0.0.0:2181
2022-09-08 08:57:00,371 [myid:] - INFO  [main:QuorumPeerConfig@448] - secureClientPort is not set
2022-09-08 08:57:00,372 [myid:] - INFO  [main:QuorumPeerConfig@464] - observerMasterPort is not set
2022-09-08 08:57:00,372 [myid:] - INFO  [main:QuorumPeerConfig@481] - metricsProvider.className is org.apache.zookeeper.metrics.impl.DefaultMetricsProvider
2022-09-08 08:57:00,396 [myid:1] - INFO  [main:DatadirCleanupManager@78] - autopurge.snapRetainCount set to 3
2022-09-08 08:57:00,396 [myid:1] - INFO  [main:DatadirCleanupManager@79] - autopurge.purgeInterval set to 0
2022-09-08 08:57:00,397 [myid:1] - INFO  [main:DatadirCleanupManager@101] - Purge task is not scheduled.
2022-09-08 08:57:00,397 [myid:1] - WARN  [main:QuorumPeerMain@139] - Either no config or no quorum defined in config, running in standalone mode
2022-09-08 08:57:00,406 [myid:1] - INFO  [main:ManagedUtil@46] - Log4j 1.2 jmx support not found; jmx disabled.
2022-09-08 08:57:00,409 [myid:1] - INFO  [main:QuorumPeerConfig@174] - Reading configuration from: /opt/bitnami/zookeeper/bin/../conf/zoo.cfg
2022-09-08 08:57:00,412 [myid:1] - INFO  [main:QuorumPeerConfig@444] - clientPortAddress is 0.0.0.0:2181
2022-09-08 08:57:00,412 [myid:1] - INFO  [main:QuorumPeerConfig@448] - secureClientPort is not set
2022-09-08 08:57:00,413 [myid:1] - INFO  [main:QuorumPeerConfig@464] - observerMasterPort is not set
2022-09-08 08:57:00,413 [myid:1] - INFO  [main:QuorumPeerConfig@481] - metricsProvider.className is org.apache.zookeeper.metrics.impl.DefaultMetricsProvider
2022-09-08 08:57:00,416 [myid:1] - INFO  [main:ZooKeeperServerMain@123] - Starting server
2022-09-08 08:57:00,500 [myid:1] - INFO  [main:ServerMetrics@62] - ServerMetrics initialized with provider org.apache.zookeeper.metrics.impl.DefaultMetricsProvider@2898ac89
2022-09-08 08:57:00,524 [myid:1] - INFO  [main:DigestAuthenticationProvider@47] - ACL digest algorithm is: SHA1
2022-09-08 08:57:00,525 [myid:1] - INFO  [main:DigestAuthenticationProvider@61] - zookeeper.DigestAuthenticationProvider.enabled = true
2022-09-08 08:57:00,540 [myid:1] - INFO  [main:FileTxnSnapLog@124] - zookeeper.snapshot.trust.empty : false
2022-09-08 08:57:00,608 [myid:1] - INFO  [main:ZookeeperBanner@42] - 
2022-09-08 08:57:00,609 [myid:1] - INFO  [main:ZookeeperBanner@42] -   ______                  _                                          
2022-09-08 08:57:00,609 [myid:1] - INFO  [main:ZookeeperBanner@42] -  |___  /                 | |                                         
2022-09-08 08:57:00,609 [myid:1] - INFO  [main:ZookeeperBanner@42] -     / /    ___     ___   | | __   ___    ___   _ __     ___   _ __   
2022-09-08 08:57:00,610 [myid:1] - INFO  [main:ZookeeperBanner@42] -    / /    / _ \   / _ \  | |/ /  / _ \  / _ \ | '_ \   / _ \ | '__|
2022-09-08 08:57:00,610 [myid:1] - INFO  [main:ZookeeperBanner@42] -   / /__  | (_) | | (_) | |   <  |  __/ |  __/ | |_) | |  __/ | |    
2022-09-08 08:57:00,611 [myid:1] - INFO  [main:ZookeeperBanner@42] -  /_____|  \___/   \___/  |_|\_\  \___|  \___| | .__/   \___| |_|
2022-09-08 08:57:00,611 [myid:1] - INFO  [main:ZookeeperBanner@42] -                                               | |                     
2022-09-08 08:57:00,611 [myid:1] - INFO  [main:ZookeeperBanner@42] -                                               |_|                     
2022-09-08 08:57:00,612 [myid:1] - INFO  [main:ZookeeperBanner@42] - 
2022-09-08 08:57:00,621 [myid:1] - INFO  [main:Environment@98] - Server environment:zookeeper.version=3.7.1-a2fb57c55f8e59cdd76c34b357ad5181df1258d5, built on 2022-05-07 06:45 UTC
2022-09-08 08:57:00,622 [myid:1] - INFO  [main:Environment@98] - Server environment:host.name=cf5b443804a5
2022-09-08 08:57:00,622 [myid:1] - INFO  [main:Environment@98] - Server environment:java.version=11.0.15.1
2022-09-08 08:57:00,622 [myid:1] - INFO  [main:Environment@98] - Server environment:java.vendor=BellSoft
2022-09-08 08:57:00,623 [myid:1] - INFO  [main:Environment@98] - Server environment:java.home=/opt/bitnami/java
2022-09-08 08:57:00,623 [myid:1] - INFO  [main:Environment@98] - Server environment:java.class.path=/opt/bitnami/zookeeper/bin/../zookeeper-server/target/classes:/opt/bitnami/zookeeper/bin/../build/classes:/opt/bitnami/zookeeper/bin/../zookeeper-server/target/lib/*.jar:/opt/bitnami/zookeeper/bin/../build/lib/*.jar:/opt/bitnami/zookeeper/bin/../lib/zookeeper-prometheus-metrics-3.7.1.jar:/opt/bitnami/zookeeper/bin/../lib/zookeeper-jute-3.7.1.jar:/opt/bitnami/zookeeper/bin/../lib/zookeeper-3.7.1.jar:/opt/bitnami/zookeeper/bin/../lib/snappy-java-1.1.7.7.jar:/opt/bitnami/zookeeper/bin/../lib/slf4j-reload4j-1.7.35.jar:/opt/bitnami/zookeeper/bin/../lib/slf4j-api-1.7.35.jar:/opt/bitnami/zookeeper/bin/../lib/simpleclient_servlet-0.9.0.jar:/opt/bitnami/zookeeper/bin/../lib/simpleclient_hotspot-0.9.0.jar:/opt/bitnami/zookeeper/bin/../lib/simpleclient_common-0.9.0.jar:/opt/bitnami/zookeeper/bin/../lib/simpleclient-0.9.0.jar:/opt/bitnami/zookeeper/bin/../lib/reload4j-1.2.19.jar:/opt/bitnami/zookeeper/bin/../lib/netty-transport-native-unix-common-4.1.76.Final.jar:/opt/bitnami/zookeeper/bin/../lib/netty-transport-native-epoll-4.1.76.Final.jar:/opt/bitnami/zookeeper/bin/../lib/netty-transport-classes-epoll-4.1.76.Final.jar:/opt/bitnami/zookeeper/bin/../lib/netty-transport-4.1.76.Final.jar:/opt/bitnami/zookeeper/bin/../lib/netty-resolver-4.1.76.Final.jar:/opt/bitnami/zookeeper/bin/../lib/netty-handler-4.1.76.Final.jar:/opt/bitnami/zookeeper/bin/../lib/netty-common-4.1.76.Final.jar:/opt/bitnami/zookeeper/bin/../lib/netty-codec-4.1.76.Final.jar:/opt/bitnami/zookeeper/bin/../lib/netty-buffer-4.1.76.Final.jar:/opt/bitnami/zookeeper/bin/../lib/metrics-core-4.1.12.1.jar:/opt/bitnami/zookeeper/bin/../lib/jline-2.14.6.jar:/opt/bitnami/zookeeper/bin/../lib/jetty-util-ajax-9.4.43.v20210629.jar:/opt/bitnami/zookeeper/bin/../lib/jetty-util-9.4.43.v20210629.jar:/opt/bitnami/zookeeper/bin/../lib/jetty-servlet-9.4.43.v20210629.jar:/opt/bitnami/zookeeper/bin/../lib/jetty-server-9.4.43.v20210629.jar:/opt/bitnami/zookeeper/bin/../lib/jetty-security-9.4.43.v20210629.jar:/opt/bitnami/zookeeper/bin/../lib/jetty-io-9.4.43.v20210629.jar:/opt/bitnami/zookeeper/bin/../lib/jetty-http-9.4.43.v20210629.jar:/opt/bitnami/zookeeper/bin/../lib/javax.servlet-api-3.1.0.jar:/opt/bitnami/zookeeper/bin/../lib/jackson-databind-2.13.2.1.jar:/opt/bitnami/zookeeper/bin/../lib/jackson-core-2.13.2.jar:/opt/bitnami/zookeeper/bin/../lib/jackson-annotations-2.13.2.jar:/opt/bitnami/zookeeper/bin/../lib/commons-cli-1.4.jar:/opt/bitnami/zookeeper/bin/../lib/audience-annotations-0.12.0.jar:/opt/bitnami/zookeeper/bin/../zookeeper-*.jar:/opt/bitnami/zookeeper/bin/../zookeeper-server/src/main/resources/lib/*.jar:/opt/bitnami/zookeeper/bin/../conf:
2022-09-08 08:57:00,624 [myid:1] - INFO  [main:Environment@98] - Server environment:java.library.path=/usr/java/packages/lib:/usr/lib64:/lib64:/lib:/usr/lib
2022-09-08 08:57:00,625 [myid:1] - INFO  [main:Environment@98] - Server environment:java.io.tmpdir=/tmp
2022-09-08 08:57:00,625 [myid:1] - INFO  [main:Environment@98] - Server environment:java.compiler=<NA>
2022-09-08 08:57:00,625 [myid:1] - INFO  [main:Environment@98] - Server environment:os.name=Linux
2022-09-08 08:57:00,625 [myid:1] - INFO  [main:Environment@98] - Server environment:os.arch=amd64
2022-09-08 08:57:00,626 [myid:1] - INFO  [main:Environment@98] - Server environment:os.version=5.10.104-linuxkit
2022-09-08 08:57:00,626 [myid:1] - INFO  [main:Environment@98] - Server environment:user.name=?
2022-09-08 08:57:00,626 [myid:1] - INFO  [main:Environment@98] - Server environment:user.home=?
2022-09-08 08:57:00,626 [myid:1] - INFO  [main:Environment@98] - Server environment:user.dir=/
2022-09-08 08:57:00,627 [myid:1] - INFO  [main:Environment@98] - Server environment:os.memory.free=1009MB
2022-09-08 08:57:00,627 [myid:1] - INFO  [main:Environment@98] - Server environment:os.memory.max=1024MB
2022-09-08 08:57:00,627 [myid:1] - INFO  [main:Environment@98] - Server environment:os.memory.total=1024MB
2022-09-08 08:57:00,628 [myid:1] - INFO  [main:ZooKeeperServer@138] - zookeeper.enableEagerACLCheck = false
2022-09-08 08:57:00,629 [myid:1] - INFO  [main:ZooKeeperServer@151] - zookeeper.digest.enabled = true
2022-09-08 08:57:00,629 [myid:1] - INFO  [main:ZooKeeperServer@155] - zookeeper.closeSessionTxn.enabled = true
2022-09-08 08:57:00,629 [myid:1] - INFO  [main:ZooKeeperServer@1505] - zookeeper.flushDelay=0
2022-09-08 08:57:00,630 [myid:1] - INFO  [main:ZooKeeperServer@1514] - zookeeper.maxWriteQueuePollTime=0
2022-09-08 08:57:00,630 [myid:1] - INFO  [main:ZooKeeperServer@1523] - zookeeper.maxBatchSize=1000
2022-09-08 08:57:00,632 [myid:1] - INFO  [main:ZooKeeperServer@260] - zookeeper.intBufferStartingSizeBytes = 1024
2022-09-08 08:57:00,639 [myid:1] - INFO  [main:BlueThrottle@141] - Weighed connection throttling is disabled
2022-09-08 08:57:00,646 [myid:1] - INFO  [main:ZooKeeperServer@1306] - minSessionTimeout set to 4000
2022-09-08 08:57:00,646 [myid:1] - INFO  [main:ZooKeeperServer@1315] - maxSessionTimeout set to 40000
2022-09-08 08:57:00,651 [myid:1] - INFO  [main:ResponseCache@45] - getData response cache size is initialized with value 400.
2022-09-08 08:57:00,652 [myid:1] - INFO  [main:ResponseCache@45] - getChildren response cache size is initialized with value 400.
2022-09-08 08:57:00,656 [myid:1] - INFO  [main:RequestPathMetricsCollector@109] - zookeeper.pathStats.slotCapacity = 60
2022-09-08 08:57:00,659 [myid:1] - INFO  [main:RequestPathMetricsCollector@110] - zookeeper.pathStats.slotDuration = 15
2022-09-08 08:57:00,660 [myid:1] - INFO  [main:RequestPathMetricsCollector@111] - zookeeper.pathStats.maxDepth = 6
2022-09-08 08:57:00,661 [myid:1] - INFO  [main:RequestPathMetricsCollector@112] - zookeeper.pathStats.initialDelay = 5
2022-09-08 08:57:00,661 [myid:1] - INFO  [main:RequestPathMetricsCollector@113] - zookeeper.pathStats.delay = 5
2022-09-08 08:57:00,661 [myid:1] - INFO  [main:RequestPathMetricsCollector@114] - zookeeper.pathStats.enabled = false
2022-09-08 08:57:00,681 [myid:1] - INFO  [main:ZooKeeperServer@1542] - The max bytes for all large requests are set to 104857600
2022-09-08 08:57:00,681 [myid:1] - INFO  [main:ZooKeeperServer@1556] - The large request threshold is set to -1
2022-09-08 08:57:00,684 [myid:1] - INFO  [main:AuthenticationHelper@66] - zookeeper.enforce.auth.enabled = false
2022-09-08 08:57:00,685 [myid:1] - INFO  [main:AuthenticationHelper@67] - zookeeper.enforce.auth.schemes = []
2022-09-08 08:57:00,685 [myid:1] - INFO  [main:ZooKeeperServer@361] - Created server with tickTime 2000 minSessionTimeout 4000 maxSessionTimeout 40000 clientPortListenBacklog -1 datadir /bitnami/zookeeper/data/version-2 snapdir /bitnami/zookeeper/data/version-2
2022-09-08 08:57:00,838 [myid:1] - INFO  [main:Log@170] - Logging initialized @4557ms to org.eclipse.jetty.util.log.Slf4jLog
2022-09-08 08:57:01,342 [myid:1] - WARN  [main:ContextHandler@1656] - o.e.j.s.ServletContextHandler@5745ca0e{/,null,STOPPED} contextPath ends with /*
2022-09-08 08:57:01,343 [myid:1] - WARN  [main:ContextHandler@1667] - Empty contextPath
2022-09-08 08:57:01,500 [myid:1] - INFO  [main:Server@375] - jetty-9.4.43.v20210629; built: 2021-06-30T11:07:22.254Z; git: 526006ecfa3af7f1a27ef3a288e2bef7ea9dd7e8; jvm 11.0.15.1+2-LTS
2022-09-08 08:57:01,718 [myid:1] - INFO  [main:DefaultSessionIdManager@334] - DefaultSessionIdManager workerName=node0
2022-09-08 08:57:01,718 [myid:1] - INFO  [main:DefaultSessionIdManager@339] - No SessionScavenger set, using defaults
2022-09-08 08:57:01,731 [myid:1] - INFO  [main:HouseKeeper@132] - node0 Scavenging every 600000ms
2022-09-08 08:57:01,759 [myid:1] - WARN  [main:ConstraintSecurityHandler@759] - ServletContext@o.e.j.s.ServletContextHandler@5745ca0e{/,null,STARTING} has uncovered http methods for path: /*
2022-09-08 08:57:01,818 [myid:1] - INFO  [main:ContextHandler@915] - Started o.e.j.s.ServletContextHandler@5745ca0e{/,null,AVAILABLE}
2022-09-08 08:57:01,920 [myid:1] - INFO  [main:AbstractConnector@331] - Started ServerConnector@1cf6d1be{HTTP/1.1, (http/1.1)}{0.0.0.0:8080}
2022-09-08 08:57:01,922 [myid:1] - INFO  [main:Server@415] - Started @5642ms
2022-09-08 08:57:01,922 [myid:1] - INFO  [main:JettyAdminServer@190] - Started AdminServer on address 0.0.0.0, port 8080 and command URL /commands
2022-09-08 08:57:01,947 [myid:1] - INFO  [main:ServerCnxnFactory@169] - Using org.apache.zookeeper.server.NIOServerCnxnFactory as server connection factory
2022-09-08 08:57:01,955 [myid:1] - WARN  [main:ServerCnxnFactory@309] - maxCnxns is not configured, using default value 0.
2022-09-08 08:57:01,965 [myid:1] - INFO  [main:NIOServerCnxnFactory@652] - Configuring NIO connection handler with 10s sessionless connection timeout, 2 selector thread(s), 16 worker threads, and 64 kB direct buffers.
2022-09-08 08:57:01,971 [myid:1] - INFO  [main:NIOServerCnxnFactory@660] - binding to port 0.0.0.0/0.0.0.0:2181
2022-09-08 08:57:02,060 [myid:1] - INFO  [main:WatchManagerFactory@42] - Using org.apache.zookeeper.server.watch.WatchManager as watch manager
2022-09-08 08:57:02,061 [myid:1] - INFO  [main:WatchManagerFactory@42] - Using org.apache.zookeeper.server.watch.WatchManager as watch manager
2022-09-08 08:57:02,073 [myid:1] - INFO  [main:ZKDatabase@133] - zookeeper.snapshotSizeFactor = 0.33
2022-09-08 08:57:02,073 [myid:1] - INFO  [main:ZKDatabase@153] - zookeeper.commitLogCount=500
2022-09-08 08:57:02,123 [myid:1] - INFO  [main:SnapStream@61] - zookeeper.snapshot.compression.method = CHECKED
2022-09-08 08:57:02,124 [myid:1] - INFO  [main:FileTxnSnapLog@479] - Snapshotting: 0x0 to /bitnami/zookeeper/data/version-2/snapshot.0
2022-09-08 08:57:02,144 [myid:1] - INFO  [main:ZKDatabase@290] - Snapshot loaded in 70 ms, highest zxid is 0x0, digest is 1371985504
2022-09-08 08:57:02,150 [myid:1] - INFO  [main:FileTxnSnapLog@479] - Snapshotting: 0x0 to /bitnami/zookeeper/data/version-2/snapshot.0
2022-09-08 08:57:02,152 [myid:1] - INFO  [main:ZooKeeperServer@543] - Snapshot taken in 2 ms
2022-09-08 08:57:02,199 [myid:1] - INFO  [ProcessThread(sid:0 cport:2181)::PrepRequestProcessor@137] - PrepRequestProcessor (sid:0) started, reconfigEnabled=false
2022-09-08 08:57:02,204 [myid:1] - INFO  [main:RequestThrottler@75] - zookeeper.request_throttler.shutdownTimeout = 10000
2022-09-08 08:57:02,332 [myid:1] - INFO  [main:ContainerManager@84] - Using checkIntervalMs=60000 maxPerMinute=10000 maxNeverUsedIntervalMs=0
2022-09-08 08:57:02,338 [myid:1] - INFO  [main:ZKAuditProvider@42] - ZooKeeper audit is disabled.
2022-09-08 08:57:11,164 [myid:1] - INFO  [SyncThread:0:FileTxnLog@284] - Creating new log file: log.1

I would like to see some WARN / ERROR lines in ZK.

To debug whether the issue was in Kafka or in ZK I did the following:

docker exec -it zookeeper  bash
(Inside ZK:)
I have no name!@cf5b443804a5:/$ curl -v -m 5 localhost:2181
*   Trying 127.0.0.1:2181...
*   Trying ::1:2181...
* Immediate connect fail for ::1: Cannot assign requested address
* After 2487ms connect time, move on!
* connect to 127.0.0.1 port 2181 failed: Connection timed out
* Failed to connect to localhost port 2181: Connection timed out
* Closing connection 0
curl: (28) Failed to connect to localhost port 2181: Connection timed out

So it seems ZK somehow is unable to receive traffic.

Additional information

After a simple docker restart zookeeper things start working again.

Mauraza commented 2 years ago

Hi @florian-besser,

Could you add BITNAMI_DEBUG=true to your docker-compose to see if there is more information?

version: "3.7"
services:
  zookeeper:
    container_name: zookeeper
    networks:
      - mynet
    image: bitnami/zookeeper:3.7.1
    ports:
      - 2181:2181
    environment:
      - ALLOW_ANONYMOUS_LOGIN=yes
      - BITNAMI_DEBUG=true
    restart: always
    deploy:
      resources:
        limits:
          memory: 256M
  kafka:
    container_name: kafka
    networks:
      - mynet
    depends_on:
      - "zookeeper"
    image: bitnami/kafka:2.8.1
    ports:
      - 9092:9092
    environment:
      - ALLOW_PLAINTEXT_LISTENER=yes
      - KAFKA_BROKER_ID=500
      - KAFKA_CFG_BROKER_ID_GENERATION_ENABLE=false
      - KAFKA_CFG_ZOOKEEPER_CONNECT=zookeeper:2181
      - KAFKA_LISTENERS=PLAINTEXT://:9092,DOCKER://:29092
      - KAFKA_ADVERTISED_LISTENERS=PLAINTEXT://localhost:9092,DOCKER://kafka:29092
      - KAFKA_LISTENER_SECURITY_PROTOCOL_MAP=PLAINTEXT:PLAINTEXT,DOCKER:PLAINTEXT
      - KAFKA_HEAP_OPTS=-Xmx512m -Xms512m
      - BITNAMI_DEBUG=true
    restart: always
    deploy:
      resources:
        limits:
          memory: 512M
networks:
  mynet:
    name: mynet
florian-besser commented 2 years ago

I have added the variable and will report back once the issue shows itself again.

github-actions[bot] commented 1 year ago

This Issue has been automatically marked as "stale" because it has not had recent activity (for 15 days). It will be closed if no further activity occurs. Thanks for the feedback.

florian-besser commented 1 year ago

I have encountered a similar issue again:

Zookeeper logs seem normal:

zookeeper 06:39:26.52 
zookeeper 06:39:26.55 Welcome to the Bitnami zookeeper container
zookeeper 06:39:26.58 Subscribe to project updates by watching https://github.com/bitnami/containers
zookeeper 06:39:26.60 Submit issues and feature requests at https://github.com/bitnami/containers/issues
zookeeper 06:39:26.62 
zookeeper 06:39:26.66 INFO  ==> ** Starting ZooKeeper setup **
zookeeper 06:39:26.82 DEBUG ==> Validating settings in ZOO_* env vars...
zookeeper 06:39:26.99 WARN  ==> You have set the environment variable ALLOW_ANONYMOUS_LOGIN=yes. For safety reasons, do not use this flag in a production environment.
zookeeper 06:39:27.13 INFO  ==> Initializing ZooKeeper...
zookeeper 06:39:27.15 INFO  ==> No injected configuration file found, creating default config files...
zookeeper 06:39:28.15 INFO  ==> No additional servers were specified. ZooKeeper will run in standalone mode...
zookeeper 06:39:28.18 DEBUG ==> Setting '-Xmx1024m -Xms1024m' heap options...
zookeeper 06:39:28.26 INFO  ==> Deploying ZooKeeper from scratch...
zookeeper 06:39:28.29 INFO  ==> ** ZooKeeper setup finished! **

zookeeper 06:39:28.44 INFO  ==> ** Starting ZooKeeper **
/opt/bitnami/java/bin/java
ZooKeeper JMX enabled by default
Using config: /opt/bitnami/zookeeper/bin/../conf/zoo.cfg
2022-10-05 06:39:32,968 [myid:] - INFO  [main:QuorumPeerConfig@174] - Reading configuration from: /opt/bitnami/zookeeper/bin/../conf/zoo.cfg
2022-10-05 06:39:33,053 [myid:] - INFO  [main:QuorumPeerConfig@444] - clientPortAddress is 0.0.0.0:2181
2022-10-05 06:39:33,054 [myid:] - INFO  [main:QuorumPeerConfig@448] - secureClientPort is not set
2022-10-05 06:39:33,055 [myid:] - INFO  [main:QuorumPeerConfig@464] - observerMasterPort is not set
2022-10-05 06:39:33,055 [myid:] - INFO  [main:QuorumPeerConfig@481] - metricsProvider.className is org.apache.zookeeper.metrics.impl.DefaultMetricsProvider
2022-10-05 06:39:33,084 [myid:1] - INFO  [main:DatadirCleanupManager@78] - autopurge.snapRetainCount set to 3
2022-10-05 06:39:33,085 [myid:1] - INFO  [main:DatadirCleanupManager@79] - autopurge.purgeInterval set to 0
2022-10-05 06:39:33,085 [myid:1] - INFO  [main:DatadirCleanupManager@101] - Purge task is not scheduled.
2022-10-05 06:39:33,086 [myid:1] - WARN  [main:QuorumPeerMain@139] - Either no config or no quorum defined in config, running in standalone mode
2022-10-05 06:39:33,097 [myid:1] - INFO  [main:ManagedUtil@46] - Log4j 1.2 jmx support not found; jmx disabled.
2022-10-05 06:39:33,102 [myid:1] - INFO  [main:QuorumPeerConfig@174] - Reading configuration from: /opt/bitnami/zookeeper/bin/../conf/zoo.cfg
2022-10-05 06:39:33,105 [myid:1] - INFO  [main:QuorumPeerConfig@444] - clientPortAddress is 0.0.0.0:2181
2022-10-05 06:39:33,106 [myid:1] - INFO  [main:QuorumPeerConfig@448] - secureClientPort is not set
2022-10-05 06:39:33,106 [myid:1] - INFO  [main:QuorumPeerConfig@464] - observerMasterPort is not set
2022-10-05 06:39:33,107 [myid:1] - INFO  [main:QuorumPeerConfig@481] - metricsProvider.className is org.apache.zookeeper.metrics.impl.DefaultMetricsProvider
2022-10-05 06:39:33,109 [myid:1] - INFO  [main:ZooKeeperServerMain@123] - Starting server
2022-10-05 06:39:33,290 [myid:1] - INFO  [main:ServerMetrics@62] - ServerMetrics initialized with provider org.apache.zookeeper.metrics.impl.DefaultMetricsProvider@2898ac89
2022-10-05 06:39:33,313 [myid:1] - INFO  [main:DigestAuthenticationProvider@47] - ACL digest algorithm is: SHA1
2022-10-05 06:39:33,314 [myid:1] - INFO  [main:DigestAuthenticationProvider@61] - zookeeper.DigestAuthenticationProvider.enabled = true
2022-10-05 06:39:33,331 [myid:1] - INFO  [main:FileTxnSnapLog@124] - zookeeper.snapshot.trust.empty : false
2022-10-05 06:39:33,406 [myid:1] - INFO  [main:ZookeeperBanner@42] - 
2022-10-05 06:39:33,407 [myid:1] - INFO  [main:ZookeeperBanner@42] -   ______                  _                                          
2022-10-05 06:39:33,407 [myid:1] - INFO  [main:ZookeeperBanner@42] -  |___  /                 | |                                         
2022-10-05 06:39:33,408 [myid:1] - INFO  [main:ZookeeperBanner@42] -     / /    ___     ___   | | __   ___    ___   _ __     ___   _ __   
2022-10-05 06:39:33,408 [myid:1] - INFO  [main:ZookeeperBanner@42] -    / /    / _ \   / _ \  | |/ /  / _ \  / _ \ | '_ \   / _ \ | '__|
2022-10-05 06:39:33,409 [myid:1] - INFO  [main:ZookeeperBanner@42] -   / /__  | (_) | | (_) | |   <  |  __/ |  __/ | |_) | |  __/ | |    
2022-10-05 06:39:33,409 [myid:1] - INFO  [main:ZookeeperBanner@42] -  /_____|  \___/   \___/  |_|\_\  \___|  \___| | .__/   \___| |_|
2022-10-05 06:39:33,410 [myid:1] - INFO  [main:ZookeeperBanner@42] -                                               | |                     
2022-10-05 06:39:33,410 [myid:1] - INFO  [main:ZookeeperBanner@42] -                                               |_|                     
2022-10-05 06:39:33,410 [myid:1] - INFO  [main:ZookeeperBanner@42] - 
2022-10-05 06:39:33,420 [myid:1] - INFO  [main:Environment@98] - Server environment:zookeeper.version=3.7.1-a2fb57c55f8e59cdd76c34b357ad5181df1258d5, built on 2022-05-07 06:45 UTC
2022-10-05 06:39:33,422 [myid:1] - INFO  [main:Environment@98] - Server environment:host.name=8664b0eff951
2022-10-05 06:39:33,422 [myid:1] - INFO  [main:Environment@98] - Server environment:java.version=11.0.15.1
2022-10-05 06:39:33,422 [myid:1] - INFO  [main:Environment@98] - Server environment:java.vendor=BellSoft
2022-10-05 06:39:33,422 [myid:1] - INFO  [main:Environment@98] - Server environment:java.home=/opt/bitnami/java
2022-10-05 06:39:33,423 [myid:1] - INFO  [main:Environment@98] - Server environment:java.class.path=/opt/bitnami/zookeeper/bin/../zookeeper-server/target/classes:/opt/bitnami/zookeeper/bin/../build/classes:/opt/bitnami/zookeeper/bin/../zookeeper-server/target/lib/*.jar:/opt/bitnami/zookeeper/bin/../build/lib/*.jar:/opt/bitnami/zookeeper/bin/../lib/zookeeper-prometheus-metrics-3.7.1.jar:/opt/bitnami/zookeeper/bin/../lib/zookeeper-jute-3.7.1.jar:/opt/bitnami/zookeeper/bin/../lib/zookeeper-3.7.1.jar:/opt/bitnami/zookeeper/bin/../lib/snappy-java-1.1.7.7.jar:/opt/bitnami/zookeeper/bin/../lib/slf4j-reload4j-1.7.35.jar:/opt/bitnami/zookeeper/bin/../lib/slf4j-api-1.7.35.jar:/opt/bitnami/zookeeper/bin/../lib/simpleclient_servlet-0.9.0.jar:/opt/bitnami/zookeeper/bin/../lib/simpleclient_hotspot-0.9.0.jar:/opt/bitnami/zookeeper/bin/../lib/simpleclient_common-0.9.0.jar:/opt/bitnami/zookeeper/bin/../lib/simpleclient-0.9.0.jar:/opt/bitnami/zookeeper/bin/../lib/reload4j-1.2.19.jar:/opt/bitnami/zookeeper/bin/../lib/netty-transport-native-unix-common-4.1.76.Final.jar:/opt/bitnami/zookeeper/bin/../lib/netty-transport-native-epoll-4.1.76.Final.jar:/opt/bitnami/zookeeper/bin/../lib/netty-transport-classes-epoll-4.1.76.Final.jar:/opt/bitnami/zookeeper/bin/../lib/netty-transport-4.1.76.Final.jar:/opt/bitnami/zookeeper/bin/../lib/netty-resolver-4.1.76.Final.jar:/opt/bitnami/zookeeper/bin/../lib/netty-handler-4.1.76.Final.jar:/opt/bitnami/zookeeper/bin/../lib/netty-common-4.1.76.Final.jar:/opt/bitnami/zookeeper/bin/../lib/netty-codec-4.1.76.Final.jar:/opt/bitnami/zookeeper/bin/../lib/netty-buffer-4.1.76.Final.jar:/opt/bitnami/zookeeper/bin/../lib/metrics-core-4.1.12.1.jar:/opt/bitnami/zookeeper/bin/../lib/jline-2.14.6.jar:/opt/bitnami/zookeeper/bin/../lib/jetty-util-ajax-9.4.43.v20210629.jar:/opt/bitnami/zookeeper/bin/../lib/jetty-util-9.4.43.v20210629.jar:/opt/bitnami/zookeeper/bin/../lib/jetty-servlet-9.4.43.v20210629.jar:/opt/bitnami/zookeeper/bin/../lib/jetty-server-9.4.43.v20210629.jar:/opt/bitnami/zookeeper/bin/../lib/jetty-security-9.4.43.v20210629.jar:/opt/bitnami/zookeeper/bin/../lib/jetty-io-9.4.43.v20210629.jar:/opt/bitnami/zookeeper/bin/../lib/jetty-http-9.4.43.v20210629.jar:/opt/bitnami/zookeeper/bin/../lib/javax.servlet-api-3.1.0.jar:/opt/bitnami/zookeeper/bin/../lib/jackson-databind-2.13.2.1.jar:/opt/bitnami/zookeeper/bin/../lib/jackson-core-2.13.2.jar:/opt/bitnami/zookeeper/bin/../lib/jackson-annotations-2.13.2.jar:/opt/bitnami/zookeeper/bin/../lib/commons-cli-1.4.jar:/opt/bitnami/zookeeper/bin/../lib/audience-annotations-0.12.0.jar:/opt/bitnami/zookeeper/bin/../zookeeper-*.jar:/opt/bitnami/zookeeper/bin/../zookeeper-server/src/main/resources/lib/*.jar:/opt/bitnami/zookeeper/bin/../conf:
2022-10-05 06:39:33,424 [myid:1] - INFO  [main:Environment@98] - Server environment:java.library.path=/usr/java/packages/lib:/usr/lib64:/lib64:/lib:/usr/lib
2022-10-05 06:39:33,425 [myid:1] - INFO  [main:Environment@98] - Server environment:java.io.tmpdir=/tmp
2022-10-05 06:39:33,425 [myid:1] - INFO  [main:Environment@98] - Server environment:java.compiler=<NA>
2022-10-05 06:39:33,426 [myid:1] - INFO  [main:Environment@98] - Server environment:os.name=Linux
2022-10-05 06:39:33,426 [myid:1] - INFO  [main:Environment@98] - Server environment:os.arch=amd64
2022-10-05 06:39:33,427 [myid:1] - INFO  [main:Environment@98] - Server environment:os.version=5.10.104-linuxkit
2022-10-05 06:39:33,428 [myid:1] - INFO  [main:Environment@98] - Server environment:user.name=?
2022-10-05 06:39:33,428 [myid:1] - INFO  [main:Environment@98] - Server environment:user.home=?
2022-10-05 06:39:33,428 [myid:1] - INFO  [main:Environment@98] - Server environment:user.dir=/
2022-10-05 06:39:33,429 [myid:1] - INFO  [main:Environment@98] - Server environment:os.memory.free=1009MB
2022-10-05 06:39:33,429 [myid:1] - INFO  [main:Environment@98] - Server environment:os.memory.max=1024MB
2022-10-05 06:39:33,430 [myid:1] - INFO  [main:Environment@98] - Server environment:os.memory.total=1024MB
2022-10-05 06:39:33,431 [myid:1] - INFO  [main:ZooKeeperServer@138] - zookeeper.enableEagerACLCheck = false
2022-10-05 06:39:33,432 [myid:1] - INFO  [main:ZooKeeperServer@151] - zookeeper.digest.enabled = true
2022-10-05 06:39:33,433 [myid:1] - INFO  [main:ZooKeeperServer@155] - zookeeper.closeSessionTxn.enabled = true
2022-10-05 06:39:33,434 [myid:1] - INFO  [main:ZooKeeperServer@1505] - zookeeper.flushDelay=0
2022-10-05 06:39:33,437 [myid:1] - INFO  [main:ZooKeeperServer@1514] - zookeeper.maxWriteQueuePollTime=0
2022-10-05 06:39:33,438 [myid:1] - INFO  [main:ZooKeeperServer@1523] - zookeeper.maxBatchSize=1000
2022-10-05 06:39:33,439 [myid:1] - INFO  [main:ZooKeeperServer@260] - zookeeper.intBufferStartingSizeBytes = 1024
2022-10-05 06:39:33,447 [myid:1] - INFO  [main:BlueThrottle@141] - Weighed connection throttling is disabled
2022-10-05 06:39:33,469 [myid:1] - INFO  [main:ZooKeeperServer@1306] - minSessionTimeout set to 4000
2022-10-05 06:39:33,472 [myid:1] - INFO  [main:ZooKeeperServer@1315] - maxSessionTimeout set to 40000
2022-10-05 06:39:33,484 [myid:1] - INFO  [main:ResponseCache@45] - getData response cache size is initialized with value 400.
2022-10-05 06:39:33,488 [myid:1] - INFO  [main:ResponseCache@45] - getChildren response cache size is initialized with value 400.
2022-10-05 06:39:33,498 [myid:1] - INFO  [main:RequestPathMetricsCollector@109] - zookeeper.pathStats.slotCapacity = 60
2022-10-05 06:39:33,499 [myid:1] - INFO  [main:RequestPathMetricsCollector@110] - zookeeper.pathStats.slotDuration = 15
2022-10-05 06:39:33,500 [myid:1] - INFO  [main:RequestPathMetricsCollector@111] - zookeeper.pathStats.maxDepth = 6
2022-10-05 06:39:33,501 [myid:1] - INFO  [main:RequestPathMetricsCollector@112] - zookeeper.pathStats.initialDelay = 5
2022-10-05 06:39:33,501 [myid:1] - INFO  [main:RequestPathMetricsCollector@113] - zookeeper.pathStats.delay = 5
2022-10-05 06:39:33,502 [myid:1] - INFO  [main:RequestPathMetricsCollector@114] - zookeeper.pathStats.enabled = false
2022-10-05 06:39:33,529 [myid:1] - INFO  [main:ZooKeeperServer@1542] - The max bytes for all large requests are set to 104857600
2022-10-05 06:39:33,530 [myid:1] - INFO  [main:ZooKeeperServer@1556] - The large request threshold is set to -1
2022-10-05 06:39:33,534 [myid:1] - INFO  [main:AuthenticationHelper@66] - zookeeper.enforce.auth.enabled = false
2022-10-05 06:39:33,535 [myid:1] - INFO  [main:AuthenticationHelper@67] - zookeeper.enforce.auth.schemes = []
2022-10-05 06:39:33,535 [myid:1] - INFO  [main:ZooKeeperServer@361] - Created server with tickTime 2000 minSessionTimeout 4000 maxSessionTimeout 40000 clientPortListenBacklog -1 datadir /bitnami/zookeeper/data/version-2 snapdir /bitnami/zookeeper/data/version-2
2022-10-05 06:39:33,749 [myid:1] - INFO  [main:Log@170] - Logging initialized @4876ms to org.eclipse.jetty.util.log.Slf4jLog
2022-10-05 06:39:34,397 [myid:1] - WARN  [main:ContextHandler@1656] - o.e.j.s.ServletContextHandler@5745ca0e{/,null,STOPPED} contextPath ends with /*
2022-10-05 06:39:34,398 [myid:1] - WARN  [main:ContextHandler@1667] - Empty contextPath
2022-10-05 06:39:34,552 [myid:1] - INFO  [main:Server@375] - jetty-9.4.43.v20210629; built: 2021-06-30T11:07:22.254Z; git: 526006ecfa3af7f1a27ef3a288e2bef7ea9dd7e8; jvm 11.0.15.1+2-LTS
2022-10-05 06:39:34,757 [myid:1] - INFO  [main:DefaultSessionIdManager@334] - DefaultSessionIdManager workerName=node0
2022-10-05 06:39:34,758 [myid:1] - INFO  [main:DefaultSessionIdManager@339] - No SessionScavenger set, using defaults
2022-10-05 06:39:34,768 [myid:1] - INFO  [main:HouseKeeper@132] - node0 Scavenging every 660000ms
2022-10-05 06:39:34,792 [myid:1] - WARN  [main:ConstraintSecurityHandler@759] - ServletContext@o.e.j.s.ServletContextHandler@5745ca0e{/,null,STARTING} has uncovered http methods for path: /*
2022-10-05 06:39:34,856 [myid:1] - INFO  [main:ContextHandler@915] - Started o.e.j.s.ServletContextHandler@5745ca0e{/,null,AVAILABLE}
2022-10-05 06:39:34,984 [myid:1] - INFO  [main:AbstractConnector@331] - Started ServerConnector@1cf6d1be{HTTP/1.1, (http/1.1)}{0.0.0.0:8080}
2022-10-05 06:39:34,988 [myid:1] - INFO  [main:Server@415] - Started @6115ms
2022-10-05 06:39:34,989 [myid:1] - INFO  [main:JettyAdminServer@190] - Started AdminServer on address 0.0.0.0, port 8080 and command URL /commands
2022-10-05 06:39:35,021 [myid:1] - INFO  [main:ServerCnxnFactory@169] - Using org.apache.zookeeper.server.NIOServerCnxnFactory as server connection factory
2022-10-05 06:39:35,033 [myid:1] - WARN  [main:ServerCnxnFactory@309] - maxCnxns is not configured, using default value 0.
2022-10-05 06:39:35,048 [myid:1] - INFO  [main:NIOServerCnxnFactory@652] - Configuring NIO connection handler with 10s sessionless connection timeout, 2 selector thread(s), 16 worker threads, and 64 kB direct buffers.
2022-10-05 06:39:35,057 [myid:1] - INFO  [main:NIOServerCnxnFactory@660] - binding to port 0.0.0.0/0.0.0.0:2181
2022-10-05 06:39:35,187 [myid:1] - INFO  [main:WatchManagerFactory@42] - Using org.apache.zookeeper.server.watch.WatchManager as watch manager
2022-10-05 06:39:35,188 [myid:1] - INFO  [main:WatchManagerFactory@42] - Using org.apache.zookeeper.server.watch.WatchManager as watch manager
2022-10-05 06:39:35,198 [myid:1] - INFO  [main:ZKDatabase@133] - zookeeper.snapshotSizeFactor = 0.33
2022-10-05 06:39:35,199 [myid:1] - INFO  [main:ZKDatabase@153] - zookeeper.commitLogCount=500
2022-10-05 06:39:35,254 [myid:1] - INFO  [main:SnapStream@61] - zookeeper.snapshot.compression.method = CHECKED
2022-10-05 06:39:35,255 [myid:1] - INFO  [main:FileTxnSnapLog@479] - Snapshotting: 0x0 to /bitnami/zookeeper/data/version-2/snapshot.0
2022-10-05 06:39:35,280 [myid:1] - INFO  [main:ZKDatabase@290] - Snapshot loaded in 80 ms, highest zxid is 0x0, digest is 1371985504
2022-10-05 06:39:35,290 [myid:1] - INFO  [main:FileTxnSnapLog@479] - Snapshotting: 0x0 to /bitnami/zookeeper/data/version-2/snapshot.0
2022-10-05 06:39:35,292 [myid:1] - INFO  [main:ZooKeeperServer@543] - Snapshot taken in 2 ms
2022-10-05 06:39:35,357 [myid:1] - INFO  [ProcessThread(sid:0 cport:2181)::PrepRequestProcessor@137] - PrepRequestProcessor (sid:0) started, reconfigEnabled=false
2022-10-05 06:39:35,362 [myid:1] - INFO  [main:RequestThrottler@75] - zookeeper.request_throttler.shutdownTimeout = 10000
2022-10-05 06:39:35,505 [myid:1] - INFO  [main:ContainerManager@84] - Using checkIntervalMs=60000 maxPerMinute=10000 maxNeverUsedIntervalMs=0
2022-10-05 06:39:35,512 [myid:1] - INFO  [main:ZKAuditProvider@42] - ZooKeeper audit is disabled.

However Kafka is stuck now:

kafka 06:39:26.91 
kafka 06:39:26.95 Welcome to the Bitnami kafka container
kafka 06:39:26.98 Subscribe to project updates by watching https://github.com/bitnami/bitnami-docker-kafka
kafka 06:39:27.00 Submit issues and feature requests at https://github.com/bitnami/bitnami-docker-kafka/issues
kafka 06:39:27.02 
kafka 06:39:27.05 INFO  ==> ** Starting Kafka setup **
kafka 06:39:27.91 DEBUG ==> Validating settings in KAFKA_* env vars...
kafka 06:39:27.99 WARN  ==> You set the environment variable ALLOW_PLAINTEXT_LISTENER=yes. For safety reasons, do not use this flag in a production environment.
kafka 06:39:28.16 INFO  ==> Initializing Kafka...
kafka 06:39:28.26 INFO  ==> No injected configuration files found, creating default config files
kafka 06:39:30.26 INFO  ==> ** Kafka setup finished! **

kafka 06:39:30.39 INFO  ==> ** Starting Kafka **

The containers both report healthy and do NOT restart but no connection can be established to Kafka. Here is the output of docker ps:

CONTAINER ID   IMAGE                     COMMAND                  CREATED          STATUS          PORTS                                                  NAMES
7c5f63ab62eb   bitnami/kafka:2.8.1       "/opt/bitnami/script…"   14 minutes ago   Up 13 minutes   0.0.0.0:9092->9092/tcp                                 kafka
d9a835779e64   postgres:14               "docker-entrypoint.s…"   14 minutes ago   Up 13 minutes   0.0.0.0:5432->5432/tcp                                 postgres
8664b0eff951   bitnami/zookeeper:3.7.1   "/opt/bitnami/script…"   14 minutes ago   Up 13 minutes   2888/tcp, 3888/tcp, 0.0.0.0:2181->2181/tcp, 8080/tcp   zookeeper
c101cc8b79da   redis:6.2                 "docker-entrypoint.s…"   14 minutes ago   Up 13 minutes   0.0.0.0:6379->6379/tcp                                 redis

A docker restart kafka fixes the issue, but I would rather Kafka came up consistently!

Mauraza commented 1 year ago

Hi @florian-besser,

Did you try to increase zookeeper.request.timeout? now is 0, you can add this environment variable KAFKA_CFG_ZOOKEEPER_CONNECTION_TIMEOUT_MS with 60000 (milliseconds) in your docker-compose.yaml

florian-besser commented 1 year ago

I have set the environment variable; will let you know how things progress.

I'm no network expert but I'm a bit puzzled how a local connection from one locally running container to another locally running container can exhibit this behavior. I would expect local connections to either connect or fail immediately, and not to block forever. Basically I'd expect that either ZK is not listening on the port in question and the connection would fail immediately, or that ZK is listening and then the connection should be established relatively quickly.

If the case I've hit can genuinely occur randomly I would suggest setting a large (but finite) default timeout rather than leaving the default timeout "infinite".

Mauraza commented 1 year ago

I don't know what could be wrong with your network. If you look for this error, it has happened to other people in different situations. This problem is not related to the bitnami container, the problem is related to the zookeeper and the environment.

florian-besser commented 1 year ago

I have encountered the issue once more; here are the requested logs:

kafka:

kafka 02:43:15.04 
kafka 02:43:15.08 Welcome to the Bitnami kafka container
kafka 02:43:15.10 Subscribe to project updates by watching https://github.com/bitnami/bitnami-docker-kafka
kafka 02:43:15.13 Submit issues and feature requests at https://github.com/bitnami/bitnami-docker-kafka/issues
kafka 02:43:15.15 
kafka 02:43:15.18 INFO  ==> ** Starting Kafka setup **
kafka 02:43:16.05 DEBUG ==> Validating settings in KAFKA_* env vars...
kafka 02:43:16.13 WARN  ==> You set the environment variable ALLOW_PLAINTEXT_LISTENER=yes. For safety reasons, do not use this flag in a production environment.
kafka 02:43:16.32 INFO  ==> Initializing Kafka...
kafka 02:43:16.41 INFO  ==> No injected configuration files found, creating default config files
kafka 02:43:18.78 INFO  ==> ** Kafka setup finished! **

kafka 02:43:18.91 INFO  ==> ** Starting Kafka **
[2022-10-11 02:43:28,778] INFO Registered kafka:type=kafka.Log4jController MBean (kafka.utils.Log4jControllerRegistration$)
[2022-10-11 02:43:32,092] INFO Setting -D jdk.tls.rejectClientInitiatedRenegotiation=true to disable client-initiated TLS renegotiation (org.apache.zookeeper.common.X509Util)
[2022-10-11 02:43:32,668] INFO Registered signal handlers for TERM, INT, HUP (org.apache.kafka.common.utils.LoggingSignalHandler)
[2022-10-11 02:43:32,694] INFO starting (kafka.server.KafkaServer)
[2022-10-11 02:43:32,696] INFO Connecting to zookeeper on zookeeper:2181 (kafka.server.KafkaServer)
[2022-10-11 02:43:32,783] INFO [ZooKeeperClient Kafka server] Initializing a new session to zookeeper:2181. (kafka.zookeeper.ZooKeeperClient)
[2022-10-11 02:43:32,808] INFO Client environment:zookeeper.version=3.5.9-83df9301aa5c2a5d284a9940177808c01bc35cef, built on 01/06/2021 20:03 GMT (org.apache.zookeeper.ZooKeeper)
[2022-10-11 02:43:32,809] INFO Client environment:host.name=7c5f63ab62eb (org.apache.zookeeper.ZooKeeper)
[2022-10-11 02:43:32,809] INFO Client environment:java.version=11.0.15 (org.apache.zookeeper.ZooKeeper)
[2022-10-11 02:43:32,809] INFO Client environment:java.vendor=BellSoft (org.apache.zookeeper.ZooKeeper)
[2022-10-11 02:43:32,809] INFO Client environment:java.home=/opt/bitnami/java (org.apache.zookeeper.ZooKeeper)
[2022-10-11 02:43:32,809] INFO Client environment:java.class.path=/opt/bitnami/kafka/bin/../libs/activation-1.1.1.jar:/opt/bitnami/kafka/bin/../libs/aopalliance-repackaged-2.6.1.jar:/opt/bitnami/kafka/bin/../libs/argparse4j-0.7.0.jar:/opt/bitnami/kafka/bin/../libs/audience-annotations-0.5.0.jar:/opt/bitnami/kafka/bin/../libs/commons-cli-1.4.jar:/opt/bitnami/kafka/bin/../libs/commons-lang3-3.8.1.jar:/opt/bitnami/kafka/bin/../libs/connect-api-2.8.1.jar:/opt/bitnami/kafka/bin/../libs/connect-basic-auth-extension-2.8.1.jar:/opt/bitnami/kafka/bin/../libs/connect-file-2.8.1.jar:/opt/bitnami/kafka/bin/../libs/connect-json-2.8.1.jar:/opt/bitnami/kafka/bin/../libs/connect-mirror-2.8.1.jar:/opt/bitnami/kafka/bin/../libs/connect-mirror-client-2.8.1.jar:/opt/bitnami/kafka/bin/../libs/connect-runtime-2.8.1.jar:/opt/bitnami/kafka/bin/../libs/connect-transforms-2.8.1.jar:/opt/bitnami/kafka/bin/../libs/hk2-api-2.6.1.jar:/opt/bitnami/kafka/bin/../libs/hk2-locator-2.6.1.jar:/opt/bitnami/kafka/bin/../libs/hk2-utils-2.6.1.jar:/opt/bitnami/kafka/bin/../libs/jackson-annotations-2.10.5.jar:/opt/bitnami/kafka/bin/../libs/jackson-core-2.10.5.jar:/opt/bitnami/kafka/bin/../libs/jackson-databind-2.10.5.1.jar:/opt/bitnami/kafka/bin/../libs/jackson-dataformat-csv-2.10.5.jar:/opt/bitnami/kafka/bin/../libs/jackson-datatype-jdk8-2.10.5.jar:/opt/bitnami/kafka/bin/../libs/jackson-jaxrs-base-2.10.5.jar:/opt/bitnami/kafka/bin/../libs/jackson-jaxrs-json-provider-2.10.5.jar:/opt/bitnami/kafka/bin/../libs/jackson-module-jaxb-annotations-2.10.5.jar:/opt/bitnami/kafka/bin/../libs/jackson-module-paranamer-2.10.5.jar:/opt/bitnami/kafka/bin/../libs/jackson-module-scala_2.12-2.10.5.jar:/opt/bitnami/kafka/bin/../libs/jakarta.activation-api-1.2.1.jar:/opt/bitnami/kafka/bin/../libs/jakarta.annotation-api-1.3.5.jar:/opt/bitnami/kafka/bin/../libs/jakarta.inject-2.6.1.jar:/opt/bitnami/kafka/bin/../libs/jakarta.validation-api-2.0.2.jar:/opt/bitnami/kafka/bin/../libs/jakarta.ws.rs-api-2.1.6.jar:/opt/bitnami/kafka/bin/../libs/jakarta.xml.bind-api-2.3.2.jar:/opt/bitnami/kafka/bin/../libs/javassist-3.27.0-GA.jar:/opt/bitnami/kafka/bin/../libs/javax.servlet-api-3.1.0.jar:/opt/bitnami/kafka/bin/../libs/javax.ws.rs-api-2.1.1.jar:/opt/bitnami/kafka/bin/../libs/jaxb-api-2.3.0.jar:/opt/bitnami/kafka/bin/../libs/jersey-client-2.34.jar:/opt/bitnami/kafka/bin/../libs/jersey-common-2.34.jar:/opt/bitnami/kafka/bin/../libs/jersey-container-servlet-2.34.jar:/opt/bitnami/kafka/bin/../libs/jersey-container-servlet-core-2.34.jar:/opt/bitnami/kafka/bin/../libs/jersey-hk2-2.34.jar:/opt/bitnami/kafka/bin/../libs/jersey-server-2.34.jar:/opt/bitnami/kafka/bin/../libs/jetty-client-9.4.43.v20210629.jar:/opt/bitnami/kafka/bin/../libs/jetty-continuation-9.4.43.v20210629.jar:/opt/bitnami/kafka/bin/../libs/jetty-http-9.4.43.v20210629.jar:/opt/bitnami/kafka/bin/../libs/jetty-io-9.4.43.v20210629.jar:/opt/bitnami/kafka/bin/../libs/jetty-security-9.4.43.v20210629.jar:/opt/bitnami/kafka/bin/../libs/jetty-server-9.4.43.v20210629.jar:/opt/bitnami/kafka/bin/../libs/jetty-servlet-9.4.43.v20210629.jar:/opt/bitnami/kafka/bin/../libs/jetty-servlets-9.4.43.v20210629.jar:/opt/bitnami/kafka/bin/../libs/jetty-util-9.4.43.v20210629.jar:/opt/bitnami/kafka/bin/../libs/jetty-util-ajax-9.4.43.v20210629.jar:/opt/bitnami/kafka/bin/../libs/jline-3.12.1.jar:/opt/bitnami/kafka/bin/../libs/jopt-simple-5.0.4.jar:/opt/bitnami/kafka/bin/../libs/kafka-clients-2.8.1.jar:/opt/bitnami/kafka/bin/../libs/kafka-log4j-appender-2.8.1.jar:/opt/bitnami/kafka/bin/../libs/kafka-metadata-2.8.1.jar:/opt/bitnami/kafka/bin/../libs/kafka-raft-2.8.1.jar:/opt/bitnami/kafka/bin/../libs/kafka-shell-2.8.1.jar:/opt/bitnami/kafka/bin/../libs/kafka-streams-2.8.1.jar:/opt/bitnami/kafka/bin/../libs/kafka-streams-examples-2.8.1.jar:/opt/bitnami/kafka/bin/../libs/kafka-streams-scala_2.12-2.8.1.jar:/opt/bitnami/kafka/bin/../libs/kafka-streams-test-utils-2.8.1.jar:/opt/bitnami/kafka/bin/../libs/kafka-tools-2.8.1.jar:/opt/bitnami/kafka/bin/../libs/kafka_2.12-2.8.1-sources.jar:/opt/bitnami/kafka/bin/../libs/kafka_2.12-2.8.1.jar:/opt/bitnami/kafka/bin/../libs/log4j-1.2.17.jar:/opt/bitnami/kafka/bin/../libs/lz4-java-1.7.1.jar:/opt/bitnami/kafka/bin/../libs/maven-artifact-3.8.1.jar:/opt/bitnami/kafka/bin/../libs/metrics-core-2.2.0.jar:/opt/bitnami/kafka/bin/../libs/netty-buffer-4.1.62.Final.jar:/opt/bitnami/kafka/bin/../libs/netty-codec-4.1.62.Final.jar:/opt/bitnami/kafka/bin/../libs/netty-common-4.1.62.Final.jar:/opt/bitnami/kafka/bin/../libs/netty-handler-4.1.62.Final.jar:/opt/bitnami/kafka/bin/../libs/netty-resolver-4.1.62.Final.jar:/opt/bitnami/kafka/bin/../libs/netty-transport-4.1.62.Final.jar:/opt/bitnami/kafka/bin/../libs/netty-transport-native-epoll-4.1.62.Final.jar:/opt/bitnami/kafka/bin/../libs/netty-transport-native-unix-common-4.1.62.Final.jar:/opt/bitnami/kafka/bin/../libs/osgi-resource-locator-1.0.3.jar:/opt/bitnami/kafka/bin/../libs/paranamer-2.8.jar:/opt/bitnami/kafka/bin/../libs/plexus-utils-3.2.1.jar:/opt/bitnami/kafka/bin/../libs/reflections-0.9.12.jar:/opt/bitnami/kafka/bin/../libs/rocksdbjni-5.18.4.jar:/opt/bitnami/kafka/bin/../libs/scala-collection-compat_2.12-2.3.0.jar:/opt/bitnami/kafka/bin/../libs/scala-java8-compat_2.12-0.9.1.jar:/opt/bitnami/kafka/bin/../libs/scala-library-2.12.13.jar:/opt/bitnami/kafka/bin/../libs/scala-logging_2.12-3.9.2.jar:/opt/bitnami/kafka/bin/../libs/scala-reflect-2.12.13.jar:/opt/bitnami/kafka/bin/../libs/slf4j-api-1.7.30.jar:/opt/bitnami/kafka/bin/../libs/slf4j-log4j12-1.7.30.jar:/opt/bitnami/kafka/bin/../libs/snappy-java-1.1.8.1.jar:/opt/bitnami/kafka/bin/../libs/zookeeper-3.5.9.jar:/opt/bitnami/kafka/bin/../libs/zookeeper-jute-3.5.9.jar:/opt/bitnami/kafka/bin/../libs/zstd-jni-1.4.9-1.jar (org.apache.zookeeper.ZooKeeper)
[2022-10-11 02:43:32,810] INFO Client environment:java.library.path=/usr/java/packages/lib:/usr/lib64:/lib64:/lib:/usr/lib (org.apache.zookeeper.ZooKeeper)
[2022-10-11 02:43:32,810] INFO Client environment:java.io.tmpdir=/tmp (org.apache.zookeeper.ZooKeeper)
[2022-10-11 02:43:32,810] INFO Client environment:java.compiler=<NA> (org.apache.zookeeper.ZooKeeper)
[2022-10-11 02:43:32,810] INFO Client environment:os.name=Linux (org.apache.zookeeper.ZooKeeper)
[2022-10-11 02:43:32,810] INFO Client environment:os.arch=amd64 (org.apache.zookeeper.ZooKeeper)
[2022-10-11 02:43:32,810] INFO Client environment:os.version=5.10.104-linuxkit (org.apache.zookeeper.ZooKeeper)
[2022-10-11 02:43:32,810] INFO Client environment:user.name=? (org.apache.zookeeper.ZooKeeper)
[2022-10-11 02:43:32,810] INFO Client environment:user.home=? (org.apache.zookeeper.ZooKeeper)
[2022-10-11 02:43:32,810] INFO Client environment:user.dir=/ (org.apache.zookeeper.ZooKeeper)
[2022-10-11 02:43:32,810] INFO Client environment:os.memory.free=499MB (org.apache.zookeeper.ZooKeeper)
[2022-10-11 02:43:32,810] INFO Client environment:os.memory.max=512MB (org.apache.zookeeper.ZooKeeper)
[2022-10-11 02:43:32,811] INFO Client environment:os.memory.total=512MB (org.apache.zookeeper.ZooKeeper)
[2022-10-11 02:43:32,821] INFO Initiating client connection, connectString=zookeeper:2181 sessionTimeout=18000 watcher=kafka.zookeeper.ZooKeeperClient$ZooKeeperClientWatcher$@363042d7 (org.apache.zookeeper.ZooKeeper)
[2022-10-11 02:43:32,844] INFO jute.maxbuffer value is 4194304 Bytes (org.apache.zookeeper.ClientCnxnSocket)
[2022-10-11 02:43:32,875] INFO zookeeper.request.timeout value is 0. feature enabled= (org.apache.zookeeper.ClientCnxn)
[2022-10-11 02:43:32,897] INFO [ZooKeeperClient Kafka server] Waiting until connected. (kafka.zookeeper.ZooKeeperClient)
[2022-10-11 02:43:32,949] INFO Opening socket connection to server zookeeper/172.18.0.2:2181. Will not attempt to authenticate using SASL (unknown error) (org.apache.zookeeper.ClientCnxn)
[2022-10-11 02:43:32,989] INFO Socket connection established, initiating session, client: /172.18.0.4:43382, server: zookeeper/172.18.0.2:2181 (org.apache.zookeeper.ClientCnxn)
[2022-10-11 02:43:33,198] INFO Session establishment complete on server zookeeper/172.18.0.2:2181, sessionid = 0x10000005fdc0000, negotiated timeout = 18000 (org.apache.zookeeper.ClientCnxn)
[2022-10-11 02:43:33,228] INFO [ZooKeeperClient Kafka server] Connected. (kafka.zookeeper.ZooKeeperClient)
[2022-10-11 02:43:34,027] INFO [feature-zk-node-event-process-thread]: Starting (kafka.server.FinalizedFeatureChangeListener$ChangeNotificationProcessorThread)
[2022-10-11 02:43:34,998] INFO Updated cache from existing <empty> to latest FinalizedFeaturesAndEpoch(features=Features{}, epoch=0). (kafka.server.FinalizedFeatureCache)
[2022-10-11 02:43:35,024] INFO Cluster ID = ovO6bACBT6aGw4tda-ipAQ (kafka.server.KafkaServer)
[2022-10-11 02:43:35,623] INFO KafkaConfig values: 
        advertised.host.name = null
        advertised.listeners = PLAINTEXT://localhost:9092,DOCKER://kafka:29092
        advertised.port = null
        alter.config.policy.class.name = null
        alter.log.dirs.replication.quota.window.num = 11
        alter.log.dirs.replication.quota.window.size.seconds = 1
        authorizer.class.name = 
        auto.create.topics.enable = true
        auto.leader.rebalance.enable = true
        background.threads = 10
        broker.heartbeat.interval.ms = 2000
        broker.id = 500
        broker.id.generation.enable = false
        broker.rack = null
        broker.session.timeout.ms = 9000
        client.quota.callback.class = null
        compression.type = producer
        connection.failed.authentication.delay.ms = 100
        connections.max.idle.ms = 600000
        connections.max.reauth.ms = 0
        control.plane.listener.name = null
        controlled.shutdown.enable = true
        controlled.shutdown.max.retries = 3
        controlled.shutdown.retry.backoff.ms = 5000
        controller.listener.names = null
        controller.quorum.append.linger.ms = 25
        controller.quorum.election.backoff.max.ms = 1000
        controller.quorum.election.timeout.ms = 1000
        controller.quorum.fetch.timeout.ms = 2000
        controller.quorum.request.timeout.ms = 2000
        controller.quorum.retry.backoff.ms = 20
        controller.quorum.voters = []
        controller.quota.window.num = 11
        controller.quota.window.size.seconds = 1
        controller.socket.timeout.ms = 30000
        create.topic.policy.class.name = null
        default.replication.factor = 1
        delegation.token.expiry.check.interval.ms = 3600000
        delegation.token.expiry.time.ms = 86400000
        delegation.token.master.key = null
        delegation.token.max.lifetime.ms = 604800000
        delegation.token.secret.key = null
        delete.records.purgatory.purge.interval.requests = 1
        delete.topic.enable = true
        fetch.max.bytes = 57671680
        fetch.purgatory.purge.interval.requests = 1000
        group.initial.rebalance.delay.ms = 0
        group.max.session.timeout.ms = 1800000
        group.max.size = 2147483647
        group.min.session.timeout.ms = 6000
        host.name = 
        initial.broker.registration.timeout.ms = 60000
        inter.broker.listener.name = null
        inter.broker.protocol.version = 2.8-IV1
        kafka.metrics.polling.interval.secs = 10
        kafka.metrics.reporters = []
        leader.imbalance.check.interval.seconds = 300
        leader.imbalance.per.broker.percentage = 10
        listener.security.protocol.map = PLAINTEXT:PLAINTEXT,DOCKER:PLAINTEXT
        listeners = PLAINTEXT://:9092,DOCKER://:29092
        log.cleaner.backoff.ms = 15000
        log.cleaner.dedupe.buffer.size = 134217728
        log.cleaner.delete.retention.ms = 86400000
        log.cleaner.enable = true
        log.cleaner.io.buffer.load.factor = 0.9
        log.cleaner.io.buffer.size = 524288
        log.cleaner.io.max.bytes.per.second = 1.7976931348623157E308
        log.cleaner.max.compaction.lag.ms = 9223372036854775807
        log.cleaner.min.cleanable.ratio = 0.5
        log.cleaner.min.compaction.lag.ms = 0
        log.cleaner.threads = 1
        log.cleanup.policy = [delete]
        log.dir = /tmp/kafka-logs
        log.dirs = /bitnami/kafka/data
        log.flush.interval.messages = 9223372036854775807
        log.flush.interval.ms = null
        log.flush.offset.checkpoint.interval.ms = 60000
        log.flush.scheduler.interval.ms = 9223372036854775807
        log.flush.start.offset.checkpoint.interval.ms = 60000
        log.index.interval.bytes = 4096
        log.index.size.max.bytes = 10485760
        log.message.downconversion.enable = true
        log.message.format.version = 2.8-IV1
        log.message.timestamp.difference.max.ms = 9223372036854775807
        log.message.timestamp.type = CreateTime
        log.preallocate = false
        log.retention.bytes = -1
        log.retention.check.interval.ms = 300000
        log.retention.hours = 168
        log.retention.minutes = null
        log.retention.ms = null
        log.roll.hours = 168
        log.roll.jitter.hours = 0
        log.roll.jitter.ms = null
        log.roll.ms = null
        log.segment.bytes = 1073741824
        log.segment.delete.delay.ms = 60000
        max.connection.creation.rate = 2147483647
        max.connections = 2147483647
        max.connections.per.ip = 2147483647
        max.connections.per.ip.overrides = 
        max.incremental.fetch.session.cache.slots = 1000
        message.max.bytes = 1048588
        metadata.log.dir = null
        metric.reporters = []
        metrics.num.samples = 2
        metrics.recording.level = INFO
        metrics.sample.window.ms = 30000
        min.insync.replicas = 1
        node.id = -1
        num.io.threads = 8
        num.network.threads = 3
        num.partitions = 1
        num.recovery.threads.per.data.dir = 1
        num.replica.alter.log.dirs.threads = null
        num.replica.fetchers = 1
        offset.metadata.max.bytes = 4096
        offsets.commit.required.acks = -1
        offsets.commit.timeout.ms = 5000
        offsets.load.buffer.size = 5242880
        offsets.retention.check.interval.ms = 600000
        offsets.retention.minutes = 10080
        offsets.topic.compression.codec = 0
        offsets.topic.num.partitions = 50
        offsets.topic.replication.factor = 1
        offsets.topic.segment.bytes = 104857600
        password.encoder.cipher.algorithm = AES/CBC/PKCS5Padding
        password.encoder.iterations = 4096
        password.encoder.key.length = 128
        password.encoder.keyfactory.algorithm = null
        password.encoder.old.secret = null
        password.encoder.secret = null
        port = 9092
        principal.builder.class = null
        process.roles = []
        producer.purgatory.purge.interval.requests = 1000
        queued.max.request.bytes = -1
        queued.max.requests = 500
        quota.consumer.default = 9223372036854775807
        quota.producer.default = 9223372036854775807
        quota.window.num = 11
        quota.window.size.seconds = 1
        replica.fetch.backoff.ms = 1000
        replica.fetch.max.bytes = 1048576
        replica.fetch.min.bytes = 1
        replica.fetch.response.max.bytes = 10485760
        replica.fetch.wait.max.ms = 500
        replica.high.watermark.checkpoint.interval.ms = 5000
        replica.lag.time.max.ms = 30000
        replica.selector.class = null
        replica.socket.receive.buffer.bytes = 65536
        replica.socket.timeout.ms = 30000
        replication.quota.window.num = 11
        replication.quota.window.size.seconds = 1
        request.timeout.ms = 30000
        reserved.broker.max.id = 1000
        sasl.client.callback.handler.class = null
        sasl.enabled.mechanisms = [PLAIN, SCRAM-SHA-256, SCRAM-SHA-512]
        sasl.jaas.config = null
        sasl.kerberos.kinit.cmd = /usr/bin/kinit
        sasl.kerberos.min.time.before.relogin = 60000
        sasl.kerberos.principal.to.local.rules = [DEFAULT]
        sasl.kerberos.service.name = null
        sasl.kerberos.ticket.renew.jitter = 0.05
        sasl.kerberos.ticket.renew.window.factor = 0.8
        sasl.login.callback.handler.class = null
        sasl.login.class = null
        sasl.login.refresh.buffer.seconds = 300
        sasl.login.refresh.min.period.seconds = 60
        sasl.login.refresh.window.factor = 0.8
        sasl.login.refresh.window.jitter = 0.05
        sasl.mechanism.controller.protocol = GSSAPI
        sasl.mechanism.inter.broker.protocol = 
        sasl.server.callback.handler.class = null
        security.inter.broker.protocol = PLAINTEXT
        security.providers = null
        socket.connection.setup.timeout.max.ms = 30000
        socket.connection.setup.timeout.ms = 10000
        socket.receive.buffer.bytes = 102400
        socket.request.max.bytes = 104857600
        socket.send.buffer.bytes = 102400
        ssl.cipher.suites = []
        ssl.client.auth = none
        ssl.enabled.protocols = [TLSv1.2, TLSv1.3]
        ssl.endpoint.identification.algorithm = https
        ssl.engine.factory.class = null
        ssl.key.password = null
        ssl.keymanager.algorithm = SunX509
        ssl.keystore.certificate.chain = null
        ssl.keystore.key = null
        ssl.keystore.location = null
        ssl.keystore.password = null
        ssl.keystore.type = JKS
        ssl.principal.mapping.rules = DEFAULT
        ssl.protocol = TLSv1.3
        ssl.provider = null
        ssl.secure.random.implementation = null
        ssl.trustmanager.algorithm = PKIX
        ssl.truststore.certificates = null
        ssl.truststore.location = null
        ssl.truststore.password = null
        ssl.truststore.type = JKS
        transaction.abort.timed.out.transaction.cleanup.interval.ms = 10000
        transaction.max.timeout.ms = 900000
        transaction.remove.expired.transaction.cleanup.interval.ms = 3600000
        transaction.state.log.load.buffer.size = 5242880
        transaction.state.log.min.isr = 1
        transaction.state.log.num.partitions = 50
        transaction.state.log.replication.factor = 1
        transaction.state.log.segment.bytes = 104857600
        transactional.id.expiration.ms = 604800000
        unclean.leader.election.enable = false
        zookeeper.clientCnxnSocket = null
        zookeeper.connect = zookeeper:2181
        zookeeper.connection.timeout.ms = 18000
        zookeeper.max.in.flight.requests = 10
        zookeeper.session.timeout.ms = 18000
        zookeeper.set.acl = false
        zookeeper.ssl.cipher.suites = null
        zookeeper.ssl.client.enable = false
        zookeeper.ssl.crl.enable = false
        zookeeper.ssl.enabled.protocols = null
        zookeeper.ssl.endpoint.identification.algorithm = HTTPS
        zookeeper.ssl.keystore.location = null
        zookeeper.ssl.keystore.password = null
        zookeeper.ssl.keystore.type = null
        zookeeper.ssl.ocsp.enable = false
        zookeeper.ssl.protocol = TLSv1.2
        zookeeper.ssl.truststore.location = null
        zookeeper.ssl.truststore.password = null
        zookeeper.ssl.truststore.type = null
        zookeeper.sync.time.ms = 2000
 (kafka.server.KafkaConfig)
[2022-10-11 02:43:35,689] INFO KafkaConfig values: 
        advertised.host.name = null
        advertised.listeners = PLAINTEXT://localhost:9092,DOCKER://kafka:29092
        advertised.port = null
        alter.config.policy.class.name = null
        alter.log.dirs.replication.quota.window.num = 11
        alter.log.dirs.replication.quota.window.size.seconds = 1
        authorizer.class.name = 
        auto.create.topics.enable = true
        auto.leader.rebalance.enable = true
        background.threads = 10
        broker.heartbeat.interval.ms = 2000
        broker.id = 500
        broker.id.generation.enable = false
        broker.rack = null
        broker.session.timeout.ms = 9000
        client.quota.callback.class = null
        compression.type = producer
        connection.failed.authentication.delay.ms = 100
        connections.max.idle.ms = 600000
        connections.max.reauth.ms = 0
        control.plane.listener.name = null
        controlled.shutdown.enable = true
        controlled.shutdown.max.retries = 3
        controlled.shutdown.retry.backoff.ms = 5000
        controller.listener.names = null
        controller.quorum.append.linger.ms = 25
        controller.quorum.election.backoff.max.ms = 1000
        controller.quorum.election.timeout.ms = 1000
        controller.quorum.fetch.timeout.ms = 2000
        controller.quorum.request.timeout.ms = 2000
        controller.quorum.retry.backoff.ms = 20
        controller.quorum.voters = []
        controller.quota.window.num = 11
        controller.quota.window.size.seconds = 1
        controller.socket.timeout.ms = 30000
        create.topic.policy.class.name = null
        default.replication.factor = 1
        delegation.token.expiry.check.interval.ms = 3600000
        delegation.token.expiry.time.ms = 86400000
        delegation.token.master.key = null
        delegation.token.max.lifetime.ms = 604800000
        delegation.token.secret.key = null
        delete.records.purgatory.purge.interval.requests = 1
        delete.topic.enable = true
        fetch.max.bytes = 57671680
        fetch.purgatory.purge.interval.requests = 1000
        group.initial.rebalance.delay.ms = 0
        group.max.session.timeout.ms = 1800000
        group.max.size = 2147483647
        group.min.session.timeout.ms = 6000
        host.name = 
        initial.broker.registration.timeout.ms = 60000
        inter.broker.listener.name = null
        inter.broker.protocol.version = 2.8-IV1
        kafka.metrics.polling.interval.secs = 10
        kafka.metrics.reporters = []
        leader.imbalance.check.interval.seconds = 300
        leader.imbalance.per.broker.percentage = 10
        listener.security.protocol.map = PLAINTEXT:PLAINTEXT,DOCKER:PLAINTEXT
        listeners = PLAINTEXT://:9092,DOCKER://:29092
        log.cleaner.backoff.ms = 15000
        log.cleaner.dedupe.buffer.size = 134217728
        log.cleaner.delete.retention.ms = 86400000
        log.cleaner.enable = true
        log.cleaner.io.buffer.load.factor = 0.9
        log.cleaner.io.buffer.size = 524288
        log.cleaner.io.max.bytes.per.second = 1.7976931348623157E308
        log.cleaner.max.compaction.lag.ms = 9223372036854775807
        log.cleaner.min.cleanable.ratio = 0.5
        log.cleaner.min.compaction.lag.ms = 0
        log.cleaner.threads = 1
        log.cleanup.policy = [delete]
        log.dir = /tmp/kafka-logs
        log.dirs = /bitnami/kafka/data
        log.flush.interval.messages = 9223372036854775807
        log.flush.interval.ms = null
        log.flush.offset.checkpoint.interval.ms = 60000
        log.flush.scheduler.interval.ms = 9223372036854775807
        log.flush.start.offset.checkpoint.interval.ms = 60000
        log.index.interval.bytes = 4096
        log.index.size.max.bytes = 10485760
        log.message.downconversion.enable = true
        log.message.format.version = 2.8-IV1
        log.message.timestamp.difference.max.ms = 9223372036854775807
        log.message.timestamp.type = CreateTime
        log.preallocate = false
        log.retention.bytes = -1
        log.retention.check.interval.ms = 300000
        log.retention.hours = 168
        log.retention.minutes = null
        log.retention.ms = null
        log.roll.hours = 168
        log.roll.jitter.hours = 0
        log.roll.jitter.ms = null
        log.roll.ms = null
        log.segment.bytes = 1073741824
        log.segment.delete.delay.ms = 60000
        max.connection.creation.rate = 2147483647
        max.connections = 2147483647
        max.connections.per.ip = 2147483647
        max.connections.per.ip.overrides = 
        max.incremental.fetch.session.cache.slots = 1000
        message.max.bytes = 1048588
        metadata.log.dir = null
        metric.reporters = []
        metrics.num.samples = 2
        metrics.recording.level = INFO
        metrics.sample.window.ms = 30000
        min.insync.replicas = 1
        node.id = -1
        num.io.threads = 8
        num.network.threads = 3
        num.partitions = 1
        num.recovery.threads.per.data.dir = 1
        num.replica.alter.log.dirs.threads = null
        num.replica.fetchers = 1
        offset.metadata.max.bytes = 4096
        offsets.commit.required.acks = -1
        offsets.commit.timeout.ms = 5000
        offsets.load.buffer.size = 5242880
        offsets.retention.check.interval.ms = 600000
        offsets.retention.minutes = 10080
        offsets.topic.compression.codec = 0
        offsets.topic.num.partitions = 50
        offsets.topic.replication.factor = 1
        offsets.topic.segment.bytes = 104857600
        password.encoder.cipher.algorithm = AES/CBC/PKCS5Padding
        password.encoder.iterations = 4096
        password.encoder.key.length = 128
        password.encoder.keyfactory.algorithm = null
        password.encoder.old.secret = null
        password.encoder.secret = null
        port = 9092
        principal.builder.class = null
        process.roles = []
        producer.purgatory.purge.interval.requests = 1000
        queued.max.request.bytes = -1
        queued.max.requests = 500
        quota.consumer.default = 9223372036854775807
        quota.producer.default = 9223372036854775807
        quota.window.num = 11
        quota.window.size.seconds = 1
        replica.fetch.backoff.ms = 1000
        replica.fetch.max.bytes = 1048576
        replica.fetch.min.bytes = 1
        replica.fetch.response.max.bytes = 10485760
        replica.fetch.wait.max.ms = 500
        replica.high.watermark.checkpoint.interval.ms = 5000
        replica.lag.time.max.ms = 30000
        replica.selector.class = null
        replica.socket.receive.buffer.bytes = 65536
        replica.socket.timeout.ms = 30000
        replication.quota.window.num = 11
        replication.quota.window.size.seconds = 1
        request.timeout.ms = 30000
        reserved.broker.max.id = 1000
        sasl.client.callback.handler.class = null
        sasl.enabled.mechanisms = [PLAIN, SCRAM-SHA-256, SCRAM-SHA-512]
        sasl.jaas.config = null
        sasl.kerberos.kinit.cmd = /usr/bin/kinit
        sasl.kerberos.min.time.before.relogin = 60000
        sasl.kerberos.principal.to.local.rules = [DEFAULT]
        sasl.kerberos.service.name = null
        sasl.kerberos.ticket.renew.jitter = 0.05
        sasl.kerberos.ticket.renew.window.factor = 0.8
        sasl.login.callback.handler.class = null
        sasl.login.class = null
        sasl.login.refresh.buffer.seconds = 300
        sasl.login.refresh.min.period.seconds = 60
        sasl.login.refresh.window.factor = 0.8
        sasl.login.refresh.window.jitter = 0.05
        sasl.mechanism.controller.protocol = GSSAPI
        sasl.mechanism.inter.broker.protocol = 
        sasl.server.callback.handler.class = null
        security.inter.broker.protocol = PLAINTEXT
        security.providers = null
        socket.connection.setup.timeout.max.ms = 30000
        socket.connection.setup.timeout.ms = 10000
        socket.receive.buffer.bytes = 102400
        socket.request.max.bytes = 104857600
        socket.send.buffer.bytes = 102400
        ssl.cipher.suites = []
        ssl.client.auth = none
        ssl.enabled.protocols = [TLSv1.2, TLSv1.3]
        ssl.endpoint.identification.algorithm = https
        ssl.engine.factory.class = null
        ssl.key.password = null
        ssl.keymanager.algorithm = SunX509
        ssl.keystore.certificate.chain = null
        ssl.keystore.key = null
        ssl.keystore.location = null
        ssl.keystore.password = null
        ssl.keystore.type = JKS
        ssl.principal.mapping.rules = DEFAULT
        ssl.protocol = TLSv1.3
        ssl.provider = null
        ssl.secure.random.implementation = null
        ssl.trustmanager.algorithm = PKIX
        ssl.truststore.certificates = null
        ssl.truststore.location = null
        ssl.truststore.password = null
        ssl.truststore.type = JKS
        transaction.abort.timed.out.transaction.cleanup.interval.ms = 10000
        transaction.max.timeout.ms = 900000
        transaction.remove.expired.transaction.cleanup.interval.ms = 3600000
        transaction.state.log.load.buffer.size = 5242880
        transaction.state.log.min.isr = 1
        transaction.state.log.num.partitions = 50
        transaction.state.log.replication.factor = 1
        transaction.state.log.segment.bytes = 104857600
        transactional.id.expiration.ms = 604800000
        unclean.leader.election.enable = false
        zookeeper.clientCnxnSocket = null
        zookeeper.connect = zookeeper:2181
        zookeeper.connection.timeout.ms = 18000
        zookeeper.max.in.flight.requests = 10
        zookeeper.session.timeout.ms = 18000
        zookeeper.set.acl = false
        zookeeper.ssl.cipher.suites = null
        zookeeper.ssl.client.enable = false
        zookeeper.ssl.crl.enable = false
        zookeeper.ssl.enabled.protocols = null
        zookeeper.ssl.endpoint.identification.algorithm = HTTPS
        zookeeper.ssl.keystore.location = null
        zookeeper.ssl.keystore.password = null
        zookeeper.ssl.keystore.type = null
        zookeeper.ssl.ocsp.enable = false
        zookeeper.ssl.protocol = TLSv1.2
        zookeeper.ssl.truststore.location = null
        zookeeper.ssl.truststore.password = null
        zookeeper.ssl.truststore.type = null
        zookeeper.sync.time.ms = 2000
 (kafka.server.KafkaConfig)
[2022-10-11 02:43:35,933] INFO [ThrottledChannelReaper-Fetch]: Starting (kafka.server.ClientQuotaManager$ThrottledChannelReaper)
[2022-10-11 02:43:35,941] INFO [ThrottledChannelReaper-Produce]: Starting (kafka.server.ClientQuotaManager$ThrottledChannelReaper)
[2022-10-11 02:43:35,955] INFO [ThrottledChannelReaper-Request]: Starting (kafka.server.ClientQuotaManager$ThrottledChannelReaper)
[2022-10-11 02:43:35,963] INFO [ThrottledChannelReaper-ControllerMutation]: Starting (kafka.server.ClientQuotaManager$ThrottledChannelReaper)
[2022-10-11 02:43:36,257] INFO Loading logs from log dirs ArrayBuffer(/bitnami/kafka/data) (kafka.log.LogManager)
[2022-10-11 02:43:36,275] INFO Attempting recovery for all logs in /bitnami/kafka/data since no clean shutdown file was found (kafka.log.LogManager)
[2022-10-11 02:43:36,728] INFO [Log partition=__consumer_offsets-0, dir=/bitnami/kafka/data] Recovering unflushed segment 0 (kafka.log.Log)
[2022-10-11 02:43:36,741] INFO [Log partition=__consumer_offsets-0, dir=/bitnami/kafka/data] Loading producer state till offset 0 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:36,891] INFO [ProducerStateManager partition=__consumer_offsets-0] Writing producer snapshot at offset 4 (kafka.log.ProducerStateManager)
[2022-10-11 02:43:37,305] INFO [Log partition=__consumer_offsets-0, dir=/bitnami/kafka/data] Loading producer state till offset 4 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:37,318] INFO [ProducerStateManager partition=__consumer_offsets-0] Loading producer state from snapshot file 'SnapshotFile(/bitnami/kafka/data/__consumer_offsets-0/00000000000000000004.snapshot,4)' (kafka.log.ProducerStateManager)
[2022-10-11 02:43:37,501] INFO Completed load of Log(dir=/bitnami/kafka/data/__consumer_offsets-0, topic=__consumer_offsets, partition=0, highWatermark=0, lastStableOffset=0, logStartOffset=0, logEndOffset=4) with 1 segments in 1106ms (1/53 loaded in /bitnami/kafka/data) (kafka.log.LogManager)
[2022-10-11 02:43:37,520] INFO [Log partition=__consumer_offsets-17, dir=/bitnami/kafka/data] Recovering unflushed segment 0 (kafka.log.Log)
[2022-10-11 02:43:37,521] INFO [Log partition=__consumer_offsets-17, dir=/bitnami/kafka/data] Loading producer state till offset 0 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:37,534] INFO [ProducerStateManager partition=__consumer_offsets-17] Writing producer snapshot at offset 9 (kafka.log.ProducerStateManager)
[2022-10-11 02:43:37,556] INFO [Log partition=__consumer_offsets-17, dir=/bitnami/kafka/data] Loading producer state till offset 9 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:37,557] INFO [ProducerStateManager partition=__consumer_offsets-17] Loading producer state from snapshot file 'SnapshotFile(/bitnami/kafka/data/__consumer_offsets-17/00000000000000000009.snapshot,9)' (kafka.log.ProducerStateManager)
[2022-10-11 02:43:37,573] INFO Completed load of Log(dir=/bitnami/kafka/data/__consumer_offsets-17, topic=__consumer_offsets, partition=17, highWatermark=0, lastStableOffset=0, logStartOffset=0, logEndOffset=9) with 1 segments in 69ms (2/53 loaded in /bitnami/kafka/data) (kafka.log.LogManager)
[2022-10-11 02:43:37,582] INFO [Log partition=__consumer_offsets-22, dir=/bitnami/kafka/data] Recovering unflushed segment 0 (kafka.log.Log)
[2022-10-11 02:43:37,586] INFO [Log partition=__consumer_offsets-22, dir=/bitnami/kafka/data] Loading producer state till offset 0 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:37,635] INFO [ProducerStateManager partition=__consumer_offsets-22] Writing producer snapshot at offset 3 (kafka.log.ProducerStateManager)
[2022-10-11 02:43:37,654] INFO [Log partition=__consumer_offsets-22, dir=/bitnami/kafka/data] Loading producer state till offset 3 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:37,655] INFO [ProducerStateManager partition=__consumer_offsets-22] Loading producer state from snapshot file 'SnapshotFile(/bitnami/kafka/data/__consumer_offsets-22/00000000000000000003.snapshot,3)' (kafka.log.ProducerStateManager)
[2022-10-11 02:43:37,682] INFO Completed load of Log(dir=/bitnami/kafka/data/__consumer_offsets-22, topic=__consumer_offsets, partition=22, highWatermark=0, lastStableOffset=0, logStartOffset=0, logEndOffset=3) with 1 segments in 108ms (3/53 loaded in /bitnami/kafka/data) (kafka.log.LogManager)
[2022-10-11 02:43:37,707] INFO [Log partition=__consumer_offsets-21, dir=/bitnami/kafka/data] Recovering unflushed segment 0 (kafka.log.Log)
[2022-10-11 02:43:37,708] INFO [Log partition=__consumer_offsets-21, dir=/bitnami/kafka/data] Loading producer state till offset 0 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:37,720] INFO [ProducerStateManager partition=__consumer_offsets-21] Writing producer snapshot at offset 12 (kafka.log.ProducerStateManager)
[2022-10-11 02:43:37,736] INFO [Log partition=__consumer_offsets-21, dir=/bitnami/kafka/data] Loading producer state till offset 12 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:37,737] INFO [ProducerStateManager partition=__consumer_offsets-21] Loading producer state from snapshot file 'SnapshotFile(/bitnami/kafka/data/__consumer_offsets-21/00000000000000000012.snapshot,12)' (kafka.log.ProducerStateManager)
[2022-10-11 02:43:37,756] INFO Completed load of Log(dir=/bitnami/kafka/data/__consumer_offsets-21, topic=__consumer_offsets, partition=21, highWatermark=0, lastStableOffset=0, logStartOffset=0, logEndOffset=12) with 1 segments in 74ms (4/53 loaded in /bitnami/kafka/data) (kafka.log.LogManager)
[2022-10-11 02:43:37,776] INFO [Log partition=__consumer_offsets-6, dir=/bitnami/kafka/data] Recovering unflushed segment 0 (kafka.log.Log)
[2022-10-11 02:43:37,784] INFO [Log partition=__consumer_offsets-6, dir=/bitnami/kafka/data] Loading producer state till offset 0 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:37,846] INFO [ProducerStateManager partition=__consumer_offsets-6] Writing producer snapshot at offset 35 (kafka.log.ProducerStateManager)
[2022-10-11 02:43:37,865] INFO [Log partition=__consumer_offsets-6, dir=/bitnami/kafka/data] Loading producer state till offset 35 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:37,866] INFO [ProducerStateManager partition=__consumer_offsets-6] Loading producer state from snapshot file 'SnapshotFile(/bitnami/kafka/data/__consumer_offsets-6/00000000000000000035.snapshot,35)' (kafka.log.ProducerStateManager)
[2022-10-11 02:43:37,902] INFO Completed load of Log(dir=/bitnami/kafka/data/__consumer_offsets-6, topic=__consumer_offsets, partition=6, highWatermark=0, lastStableOffset=0, logStartOffset=0, logEndOffset=35) with 1 segments in 144ms (5/53 loaded in /bitnami/kafka/data) (kafka.log.LogManager)
[2022-10-11 02:43:37,926] INFO [Log partition=__consumer_offsets-8, dir=/bitnami/kafka/data] Recovering unflushed segment 0 (kafka.log.Log)
[2022-10-11 02:43:37,929] INFO [Log partition=__consumer_offsets-8, dir=/bitnami/kafka/data] Loading producer state till offset 0 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:37,941] INFO [ProducerStateManager partition=__consumer_offsets-8] Writing producer snapshot at offset 18 (kafka.log.ProducerStateManager)
[2022-10-11 02:43:37,956] INFO [Log partition=__consumer_offsets-8, dir=/bitnami/kafka/data] Loading producer state till offset 18 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:37,957] INFO [ProducerStateManager partition=__consumer_offsets-8] Loading producer state from snapshot file 'SnapshotFile(/bitnami/kafka/data/__consumer_offsets-8/00000000000000000018.snapshot,18)' (kafka.log.ProducerStateManager)
[2022-10-11 02:43:37,977] INFO Completed load of Log(dir=/bitnami/kafka/data/__consumer_offsets-8, topic=__consumer_offsets, partition=8, highWatermark=0, lastStableOffset=0, logStartOffset=0, logEndOffset=18) with 1 segments in 74ms (6/53 loaded in /bitnami/kafka/data) (kafka.log.LogManager)
[2022-10-11 02:43:37,988] INFO [Log partition=__consumer_offsets-26, dir=/bitnami/kafka/data] Recovering unflushed segment 0 (kafka.log.Log)
[2022-10-11 02:43:37,989] INFO [Log partition=__consumer_offsets-26, dir=/bitnami/kafka/data] Loading producer state till offset 0 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:38,021] INFO [ProducerStateManager partition=__consumer_offsets-26] Writing producer snapshot at offset 37 (kafka.log.ProducerStateManager)
[2022-10-11 02:43:38,045] INFO [Log partition=__consumer_offsets-26, dir=/bitnami/kafka/data] Loading producer state till offset 37 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:38,046] INFO [ProducerStateManager partition=__consumer_offsets-26] Loading producer state from snapshot file 'SnapshotFile(/bitnami/kafka/data/__consumer_offsets-26/00000000000000000037.snapshot,37)' (kafka.log.ProducerStateManager)
[2022-10-11 02:43:38,068] INFO Completed load of Log(dir=/bitnami/kafka/data/__consumer_offsets-26, topic=__consumer_offsets, partition=26, highWatermark=0, lastStableOffset=0, logStartOffset=0, logEndOffset=37) with 1 segments in 91ms (7/53 loaded in /bitnami/kafka/data) (kafka.log.LogManager)
[2022-10-11 02:43:38,086] INFO [Log partition=__consumer_offsets-1, dir=/bitnami/kafka/data] Recovering unflushed segment 0 (kafka.log.Log)
[2022-10-11 02:43:38,089] INFO [Log partition=__consumer_offsets-1, dir=/bitnami/kafka/data] Loading producer state till offset 0 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:38,115] INFO [ProducerStateManager partition=__consumer_offsets-1] Writing producer snapshot at offset 6 (kafka.log.ProducerStateManager)
[2022-10-11 02:43:38,132] INFO [Log partition=__consumer_offsets-1, dir=/bitnami/kafka/data] Loading producer state till offset 6 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:38,133] INFO [ProducerStateManager partition=__consumer_offsets-1] Loading producer state from snapshot file 'SnapshotFile(/bitnami/kafka/data/__consumer_offsets-1/00000000000000000006.snapshot,6)' (kafka.log.ProducerStateManager)
[2022-10-11 02:43:38,154] INFO Completed load of Log(dir=/bitnami/kafka/data/__consumer_offsets-1, topic=__consumer_offsets, partition=1, highWatermark=0, lastStableOffset=0, logStartOffset=0, logEndOffset=6) with 1 segments in 86ms (8/53 loaded in /bitnami/kafka/data) (kafka.log.LogManager)
[2022-10-11 02:43:38,167] INFO [Log partition=__consumer_offsets-43, dir=/bitnami/kafka/data] Recovering unflushed segment 0 (kafka.log.Log)
[2022-10-11 02:43:38,169] INFO [Log partition=__consumer_offsets-43, dir=/bitnami/kafka/data] Loading producer state till offset 0 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:38,175] INFO [ProducerStateManager partition=__consumer_offsets-43] Writing producer snapshot at offset 7 (kafka.log.ProducerStateManager)
[2022-10-11 02:43:38,185] INFO [Log partition=__consumer_offsets-43, dir=/bitnami/kafka/data] Loading producer state till offset 7 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:38,186] INFO [ProducerStateManager partition=__consumer_offsets-43] Loading producer state from snapshot file 'SnapshotFile(/bitnami/kafka/data/__consumer_offsets-43/00000000000000000007.snapshot,7)' (kafka.log.ProducerStateManager)
[2022-10-11 02:43:38,204] INFO Completed load of Log(dir=/bitnami/kafka/data/__consumer_offsets-43, topic=__consumer_offsets, partition=43, highWatermark=0, lastStableOffset=0, logStartOffset=0, logEndOffset=7) with 1 segments in 49ms (9/53 loaded in /bitnami/kafka/data) (kafka.log.LogManager)
[2022-10-11 02:43:38,218] INFO [Log partition=backend.GSG_Order.dlq-0, dir=/bitnami/kafka/data] Recovering unflushed segment 0 (kafka.log.Log)
[2022-10-11 02:43:38,219] INFO [Log partition=backend.GSG_Order.dlq-0, dir=/bitnami/kafka/data] Loading producer state till offset 0 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:38,231] INFO [ProducerStateManager partition=backend.GSG_Order.dlq-0] Writing producer snapshot at offset 22 (kafka.log.ProducerStateManager)
[2022-10-11 02:43:38,241] INFO [Log partition=backend.GSG_Order.dlq-0, dir=/bitnami/kafka/data] Loading producer state till offset 22 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:38,241] INFO [ProducerStateManager partition=backend.GSG_Order.dlq-0] Loading producer state from snapshot file 'SnapshotFile(/bitnami/kafka/data/backend.GSG_Order.dlq-0/00000000000000000022.snapshot,22)' (kafka.log.ProducerStateManager)
[2022-10-11 02:43:38,261] INFO Completed load of Log(dir=/bitnami/kafka/data/backend.GSG_Order.dlq-0, topic=backend.GSG_Order.dlq, partition=0, highWatermark=0, lastStableOffset=0, logStartOffset=0, logEndOffset=22) with 1 segments in 55ms (10/53 loaded in /bitnami/kafka/data) (kafka.log.LogManager)
[2022-10-11 02:43:38,269] INFO [Log partition=__consumer_offsets-15, dir=/bitnami/kafka/data] Recovering unflushed segment 0 (kafka.log.Log)
[2022-10-11 02:43:38,270] INFO [Log partition=__consumer_offsets-15, dir=/bitnami/kafka/data] Loading producer state till offset 0 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:38,278] INFO [ProducerStateManager partition=__consumer_offsets-15] Writing producer snapshot at offset 29 (kafka.log.ProducerStateManager)
[2022-10-11 02:43:38,287] INFO [Log partition=__consumer_offsets-15, dir=/bitnami/kafka/data] Loading producer state till offset 29 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:38,288] INFO [ProducerStateManager partition=__consumer_offsets-15] Loading producer state from snapshot file 'SnapshotFile(/bitnami/kafka/data/__consumer_offsets-15/00000000000000000029.snapshot,29)' (kafka.log.ProducerStateManager)
[2022-10-11 02:43:38,298] INFO Completed load of Log(dir=/bitnami/kafka/data/__consumer_offsets-15, topic=__consumer_offsets, partition=15, highWatermark=0, lastStableOffset=0, logStartOffset=0, logEndOffset=29) with 1 segments in 36ms (11/53 loaded in /bitnami/kafka/data) (kafka.log.LogManager)
[2022-10-11 02:43:38,308] INFO [Log partition=__consumer_offsets-13, dir=/bitnami/kafka/data] Recovering unflushed segment 0 (kafka.log.Log)
[2022-10-11 02:43:38,309] INFO [Log partition=__consumer_offsets-13, dir=/bitnami/kafka/data] Loading producer state till offset 0 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:38,314] INFO [ProducerStateManager partition=__consumer_offsets-13] Writing producer snapshot at offset 9 (kafka.log.ProducerStateManager)
[2022-10-11 02:43:38,323] INFO [Log partition=__consumer_offsets-13, dir=/bitnami/kafka/data] Loading producer state till offset 9 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:38,323] INFO [ProducerStateManager partition=__consumer_offsets-13] Loading producer state from snapshot file 'SnapshotFile(/bitnami/kafka/data/__consumer_offsets-13/00000000000000000009.snapshot,9)' (kafka.log.ProducerStateManager)
[2022-10-11 02:43:38,333] INFO Completed load of Log(dir=/bitnami/kafka/data/__consumer_offsets-13, topic=__consumer_offsets, partition=13, highWatermark=0, lastStableOffset=0, logStartOffset=0, logEndOffset=9) with 1 segments in 35ms (12/53 loaded in /bitnami/kafka/data) (kafka.log.LogManager)
[2022-10-11 02:43:38,347] INFO [Log partition=__consumer_offsets-23, dir=/bitnami/kafka/data] Recovering unflushed segment 0 (kafka.log.Log)
[2022-10-11 02:43:38,348] INFO [Log partition=__consumer_offsets-23, dir=/bitnami/kafka/data] Loading producer state till offset 0 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:38,357] INFO [ProducerStateManager partition=__consumer_offsets-23] Writing producer snapshot at offset 4 (kafka.log.ProducerStateManager)
[2022-10-11 02:43:38,367] INFO [Log partition=__consumer_offsets-23, dir=/bitnami/kafka/data] Loading producer state till offset 4 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:38,368] INFO [ProducerStateManager partition=__consumer_offsets-23] Loading producer state from snapshot file 'SnapshotFile(/bitnami/kafka/data/__consumer_offsets-23/00000000000000000004.snapshot,4)' (kafka.log.ProducerStateManager)
[2022-10-11 02:43:38,377] INFO Completed load of Log(dir=/bitnami/kafka/data/__consumer_offsets-23, topic=__consumer_offsets, partition=23, highWatermark=0, lastStableOffset=0, logStartOffset=0, logEndOffset=4) with 1 segments in 42ms (13/53 loaded in /bitnami/kafka/data) (kafka.log.LogManager)
[2022-10-11 02:43:38,385] INFO [Log partition=__consumer_offsets-48, dir=/bitnami/kafka/data] Recovering unflushed segment 0 (kafka.log.Log)
[2022-10-11 02:43:38,387] INFO [Log partition=__consumer_offsets-48, dir=/bitnami/kafka/data] Loading producer state till offset 0 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:38,410] INFO [ProducerStateManager partition=__consumer_offsets-48] Writing producer snapshot at offset 114 (kafka.log.ProducerStateManager)
[2022-10-11 02:43:38,419] INFO [Log partition=__consumer_offsets-48, dir=/bitnami/kafka/data] Loading producer state till offset 114 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:38,420] INFO [ProducerStateManager partition=__consumer_offsets-48] Loading producer state from snapshot file 'SnapshotFile(/bitnami/kafka/data/__consumer_offsets-48/00000000000000000114.snapshot,114)' (kafka.log.ProducerStateManager)
[2022-10-11 02:43:38,431] INFO Completed load of Log(dir=/bitnami/kafka/data/__consumer_offsets-48, topic=__consumer_offsets, partition=48, highWatermark=0, lastStableOffset=0, logStartOffset=0, logEndOffset=114) with 1 segments in 54ms (14/53 loaded in /bitnami/kafka/data) (kafka.log.LogManager)
[2022-10-11 02:43:38,441] INFO [Log partition=__consumer_offsets-25, dir=/bitnami/kafka/data] Recovering unflushed segment 0 (kafka.log.Log)
[2022-10-11 02:43:38,442] INFO [Log partition=__consumer_offsets-25, dir=/bitnami/kafka/data] Loading producer state till offset 0 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:38,461] INFO [Log partition=__consumer_offsets-25, dir=/bitnami/kafka/data] Loading producer state till offset 0 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:38,481] INFO Completed load of Log(dir=/bitnami/kafka/data/__consumer_offsets-25, topic=__consumer_offsets, partition=25, highWatermark=0, lastStableOffset=0, logStartOffset=0, logEndOffset=0) with 1 segments in 50ms (15/53 loaded in /bitnami/kafka/data) (kafka.log.LogManager)
[2022-10-11 02:43:38,498] INFO [Log partition=__consumer_offsets-46, dir=/bitnami/kafka/data] Recovering unflushed segment 0 (kafka.log.Log)
[2022-10-11 02:43:38,500] INFO [Log partition=__consumer_offsets-46, dir=/bitnami/kafka/data] Loading producer state till offset 0 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:38,514] INFO [ProducerStateManager partition=__consumer_offsets-46] Writing producer snapshot at offset 12 (kafka.log.ProducerStateManager)
[2022-10-11 02:43:38,526] INFO [Log partition=__consumer_offsets-46, dir=/bitnami/kafka/data] Loading producer state till offset 12 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:38,527] INFO [ProducerStateManager partition=__consumer_offsets-46] Loading producer state from snapshot file 'SnapshotFile(/bitnami/kafka/data/__consumer_offsets-46/00000000000000000012.snapshot,12)' (kafka.log.ProducerStateManager)
[2022-10-11 02:43:38,540] INFO Completed load of Log(dir=/bitnami/kafka/data/__consumer_offsets-46, topic=__consumer_offsets, partition=46, highWatermark=0, lastStableOffset=0, logStartOffset=0, logEndOffset=12) with 1 segments in 55ms (16/53 loaded in /bitnami/kafka/data) (kafka.log.LogManager)
[2022-10-11 02:43:38,549] INFO [Log partition=__consumer_offsets-7, dir=/bitnami/kafka/data] Recovering unflushed segment 0 (kafka.log.Log)
[2022-10-11 02:43:38,550] INFO [Log partition=__consumer_offsets-7, dir=/bitnami/kafka/data] Loading producer state till offset 0 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:38,559] INFO [ProducerStateManager partition=__consumer_offsets-7] Writing producer snapshot at offset 7 (kafka.log.ProducerStateManager)
[2022-10-11 02:43:38,570] INFO [Log partition=__consumer_offsets-7, dir=/bitnami/kafka/data] Loading producer state till offset 7 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:38,570] INFO [ProducerStateManager partition=__consumer_offsets-7] Loading producer state from snapshot file 'SnapshotFile(/bitnami/kafka/data/__consumer_offsets-7/00000000000000000007.snapshot,7)' (kafka.log.ProducerStateManager)
[2022-10-11 02:43:38,585] INFO Completed load of Log(dir=/bitnami/kafka/data/__consumer_offsets-7, topic=__consumer_offsets, partition=7, highWatermark=0, lastStableOffset=0, logStartOffset=0, logEndOffset=7) with 1 segments in 45ms (17/53 loaded in /bitnami/kafka/data) (kafka.log.LogManager)
[2022-10-11 02:43:38,595] INFO [Log partition=__consumer_offsets-31, dir=/bitnami/kafka/data] Recovering unflushed segment 0 (kafka.log.Log)
[2022-10-11 02:43:38,596] INFO [Log partition=__consumer_offsets-31, dir=/bitnami/kafka/data] Loading producer state till offset 0 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:38,603] INFO [Log partition=__consumer_offsets-31, dir=/bitnami/kafka/data] Loading producer state till offset 0 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:38,614] INFO Completed load of Log(dir=/bitnami/kafka/data/__consumer_offsets-31, topic=__consumer_offsets, partition=31, highWatermark=0, lastStableOffset=0, logStartOffset=0, logEndOffset=0) with 1 segments in 29ms (18/53 loaded in /bitnami/kafka/data) (kafka.log.LogManager)
[2022-10-11 02:43:38,623] INFO [Log partition=__consumer_offsets-40, dir=/bitnami/kafka/data] Recovering unflushed segment 0 (kafka.log.Log)
[2022-10-11 02:43:38,623] INFO [Log partition=__consumer_offsets-40, dir=/bitnami/kafka/data] Loading producer state till offset 0 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:38,627] INFO [ProducerStateManager partition=__consumer_offsets-40] Writing producer snapshot at offset 12 (kafka.log.ProducerStateManager)
[2022-10-11 02:43:38,635] INFO [Log partition=__consumer_offsets-40, dir=/bitnami/kafka/data] Loading producer state till offset 12 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:38,635] INFO [ProducerStateManager partition=__consumer_offsets-40] Loading producer state from snapshot file 'SnapshotFile(/bitnami/kafka/data/__consumer_offsets-40/00000000000000000012.snapshot,12)' (kafka.log.ProducerStateManager)
[2022-10-11 02:43:38,643] INFO Completed load of Log(dir=/bitnami/kafka/data/__consumer_offsets-40, topic=__consumer_offsets, partition=40, highWatermark=0, lastStableOffset=0, logStartOffset=0, logEndOffset=12) with 1 segments in 29ms (19/53 loaded in /bitnami/kafka/data) (kafka.log.LogManager)
[2022-10-11 02:43:38,654] INFO [Log partition=__consumer_offsets-27, dir=/bitnami/kafka/data] Recovering unflushed segment 0 (kafka.log.Log)
[2022-10-11 02:43:38,655] INFO [Log partition=__consumer_offsets-27, dir=/bitnami/kafka/data] Loading producer state till offset 0 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:38,664] INFO [ProducerStateManager partition=__consumer_offsets-27] Writing producer snapshot at offset 15 (kafka.log.ProducerStateManager)
[2022-10-11 02:43:38,675] INFO [Log partition=__consumer_offsets-27, dir=/bitnami/kafka/data] Loading producer state till offset 15 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:38,676] INFO [ProducerStateManager partition=__consumer_offsets-27] Loading producer state from snapshot file 'SnapshotFile(/bitnami/kafka/data/__consumer_offsets-27/00000000000000000015.snapshot,15)' (kafka.log.ProducerStateManager)
[2022-10-11 02:43:38,685] INFO Completed load of Log(dir=/bitnami/kafka/data/__consumer_offsets-27, topic=__consumer_offsets, partition=27, highWatermark=0, lastStableOffset=0, logStartOffset=0, logEndOffset=15) with 1 segments in 41ms (20/53 loaded in /bitnami/kafka/data) (kafka.log.LogManager)
[2022-10-11 02:43:38,694] INFO [Log partition=__consumer_offsets-5, dir=/bitnami/kafka/data] Recovering unflushed segment 0 (kafka.log.Log)
[2022-10-11 02:43:38,695] INFO [Log partition=__consumer_offsets-5, dir=/bitnami/kafka/data] Loading producer state till offset 0 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:38,702] INFO [ProducerStateManager partition=__consumer_offsets-5] Writing producer snapshot at offset 23 (kafka.log.ProducerStateManager)
[2022-10-11 02:43:38,710] INFO [Log partition=__consumer_offsets-5, dir=/bitnami/kafka/data] Loading producer state till offset 23 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:38,710] INFO [ProducerStateManager partition=__consumer_offsets-5] Loading producer state from snapshot file 'SnapshotFile(/bitnami/kafka/data/__consumer_offsets-5/00000000000000000023.snapshot,23)' (kafka.log.ProducerStateManager)
[2022-10-11 02:43:38,724] INFO Completed load of Log(dir=/bitnami/kafka/data/__consumer_offsets-5, topic=__consumer_offsets, partition=5, highWatermark=0, lastStableOffset=0, logStartOffset=0, logEndOffset=23) with 1 segments in 38ms (21/53 loaded in /bitnami/kafka/data) (kafka.log.LogManager)
[2022-10-11 02:43:38,732] INFO [Log partition=__consumer_offsets-34, dir=/bitnami/kafka/data] Recovering unflushed segment 0 (kafka.log.Log)
[2022-10-11 02:43:38,733] INFO [Log partition=__consumer_offsets-34, dir=/bitnami/kafka/data] Loading producer state till offset 0 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:38,737] INFO [ProducerStateManager partition=__consumer_offsets-34] Writing producer snapshot at offset 15 (kafka.log.ProducerStateManager)
[2022-10-11 02:43:38,746] INFO [Log partition=__consumer_offsets-34, dir=/bitnami/kafka/data] Loading producer state till offset 15 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:38,746] INFO [ProducerStateManager partition=__consumer_offsets-34] Loading producer state from snapshot file 'SnapshotFile(/bitnami/kafka/data/__consumer_offsets-34/00000000000000000015.snapshot,15)' (kafka.log.ProducerStateManager)
[2022-10-11 02:43:38,755] INFO Completed load of Log(dir=/bitnami/kafka/data/__consumer_offsets-34, topic=__consumer_offsets, partition=34, highWatermark=0, lastStableOffset=0, logStartOffset=0, logEndOffset=15) with 1 segments in 30ms (22/53 loaded in /bitnami/kafka/data) (kafka.log.LogManager)
[2022-10-11 02:43:38,769] INFO [Log partition=__consumer_offsets-39, dir=/bitnami/kafka/data] Recovering unflushed segment 0 (kafka.log.Log)
[2022-10-11 02:43:38,770] INFO [Log partition=__consumer_offsets-39, dir=/bitnami/kafka/data] Loading producer state till offset 0 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:38,778] INFO [ProducerStateManager partition=__consumer_offsets-39] Writing producer snapshot at offset 12 (kafka.log.ProducerStateManager)
[2022-10-11 02:43:38,785] INFO [Log partition=__consumer_offsets-39, dir=/bitnami/kafka/data] Loading producer state till offset 12 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:38,785] INFO [ProducerStateManager partition=__consumer_offsets-39] Loading producer state from snapshot file 'SnapshotFile(/bitnami/kafka/data/__consumer_offsets-39/00000000000000000012.snapshot,12)' (kafka.log.ProducerStateManager)
[2022-10-11 02:43:38,796] INFO Completed load of Log(dir=/bitnami/kafka/data/__consumer_offsets-39, topic=__consumer_offsets, partition=39, highWatermark=0, lastStableOffset=0, logStartOffset=0, logEndOffset=12) with 1 segments in 40ms (23/53 loaded in /bitnami/kafka/data) (kafka.log.LogManager)
[2022-10-11 02:43:38,805] INFO [Log partition=__consumer_offsets-32, dir=/bitnami/kafka/data] Recovering unflushed segment 0 (kafka.log.Log)
[2022-10-11 02:43:38,806] INFO [Log partition=__consumer_offsets-32, dir=/bitnami/kafka/data] Loading producer state till offset 0 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:38,811] INFO [ProducerStateManager partition=__consumer_offsets-32] Writing producer snapshot at offset 3 (kafka.log.ProducerStateManager)
[2022-10-11 02:43:38,819] INFO [Log partition=__consumer_offsets-32, dir=/bitnami/kafka/data] Loading producer state till offset 3 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:38,820] INFO [ProducerStateManager partition=__consumer_offsets-32] Loading producer state from snapshot file 'SnapshotFile(/bitnami/kafka/data/__consumer_offsets-32/00000000000000000003.snapshot,3)' (kafka.log.ProducerStateManager)
[2022-10-11 02:43:38,829] INFO Completed load of Log(dir=/bitnami/kafka/data/__consumer_offsets-32, topic=__consumer_offsets, partition=32, highWatermark=0, lastStableOffset=0, logStartOffset=0, logEndOffset=3) with 1 segments in 33ms (24/53 loaded in /bitnami/kafka/data) (kafka.log.LogManager)
[2022-10-11 02:43:38,841] INFO [Log partition=__consumer_offsets-45, dir=/bitnami/kafka/data] Recovering unflushed segment 0 (kafka.log.Log)
[2022-10-11 02:43:38,842] INFO [Log partition=__consumer_offsets-45, dir=/bitnami/kafka/data] Loading producer state till offset 0 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:38,846] INFO [ProducerStateManager partition=__consumer_offsets-45] Writing producer snapshot at offset 9 (kafka.log.ProducerStateManager)
[2022-10-11 02:43:38,855] INFO [Log partition=__consumer_offsets-45, dir=/bitnami/kafka/data] Loading producer state till offset 9 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:38,855] INFO [ProducerStateManager partition=__consumer_offsets-45] Loading producer state from snapshot file 'SnapshotFile(/bitnami/kafka/data/__consumer_offsets-45/00000000000000000009.snapshot,9)' (kafka.log.ProducerStateManager)
[2022-10-11 02:43:38,865] INFO Completed load of Log(dir=/bitnami/kafka/data/__consumer_offsets-45, topic=__consumer_offsets, partition=45, highWatermark=0, lastStableOffset=0, logStartOffset=0, logEndOffset=9) with 1 segments in 35ms (25/53 loaded in /bitnami/kafka/data) (kafka.log.LogManager)
[2022-10-11 02:43:38,874] INFO [Log partition=__consumer_offsets-18, dir=/bitnami/kafka/data] Recovering unflushed segment 0 (kafka.log.Log)
[2022-10-11 02:43:38,874] INFO [Log partition=__consumer_offsets-18, dir=/bitnami/kafka/data] Loading producer state till offset 0 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:38,878] INFO [ProducerStateManager partition=__consumer_offsets-18] Writing producer snapshot at offset 6 (kafka.log.ProducerStateManager)
[2022-10-11 02:43:38,889] INFO [Log partition=__consumer_offsets-18, dir=/bitnami/kafka/data] Loading producer state till offset 6 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:38,889] INFO [ProducerStateManager partition=__consumer_offsets-18] Loading producer state from snapshot file 'SnapshotFile(/bitnami/kafka/data/__consumer_offsets-18/00000000000000000006.snapshot,6)' (kafka.log.ProducerStateManager)
[2022-10-11 02:43:38,897] INFO Completed load of Log(dir=/bitnami/kafka/data/__consumer_offsets-18, topic=__consumer_offsets, partition=18, highWatermark=0, lastStableOffset=0, logStartOffset=0, logEndOffset=6) with 1 segments in 31ms (26/53 loaded in /bitnami/kafka/data) (kafka.log.LogManager)
[2022-10-11 02:43:38,903] INFO [Log partition=__consumer_offsets-24, dir=/bitnami/kafka/data] Recovering unflushed segment 0 (kafka.log.Log)
[2022-10-11 02:43:38,904] INFO [Log partition=__consumer_offsets-24, dir=/bitnami/kafka/data] Loading producer state till offset 0 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:38,907] INFO [ProducerStateManager partition=__consumer_offsets-24] Writing producer snapshot at offset 9 (kafka.log.ProducerStateManager)
[2022-10-11 02:43:38,915] INFO [Log partition=__consumer_offsets-24, dir=/bitnami/kafka/data] Loading producer state till offset 9 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:38,915] INFO [ProducerStateManager partition=__consumer_offsets-24] Loading producer state from snapshot file 'SnapshotFile(/bitnami/kafka/data/__consumer_offsets-24/00000000000000000009.snapshot,9)' (kafka.log.ProducerStateManager)
[2022-10-11 02:43:38,923] INFO Completed load of Log(dir=/bitnami/kafka/data/__consumer_offsets-24, topic=__consumer_offsets, partition=24, highWatermark=0, lastStableOffset=0, logStartOffset=0, logEndOffset=9) with 1 segments in 26ms (27/53 loaded in /bitnami/kafka/data) (kafka.log.LogManager)
[2022-10-11 02:43:38,931] INFO [Log partition=__consumer_offsets-30, dir=/bitnami/kafka/data] Recovering unflushed segment 0 (kafka.log.Log)
[2022-10-11 02:43:38,931] INFO [Log partition=__consumer_offsets-30, dir=/bitnami/kafka/data] Loading producer state till offset 0 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:38,937] INFO [ProducerStateManager partition=__consumer_offsets-30] Writing producer snapshot at offset 30 (kafka.log.ProducerStateManager)
[2022-10-11 02:43:38,946] INFO [Log partition=__consumer_offsets-30, dir=/bitnami/kafka/data] Loading producer state till offset 30 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:38,946] INFO [ProducerStateManager partition=__consumer_offsets-30] Loading producer state from snapshot file 'SnapshotFile(/bitnami/kafka/data/__consumer_offsets-30/00000000000000000030.snapshot,30)' (kafka.log.ProducerStateManager)
[2022-10-11 02:43:38,955] INFO Completed load of Log(dir=/bitnami/kafka/data/__consumer_offsets-30, topic=__consumer_offsets, partition=30, highWatermark=0, lastStableOffset=0, logStartOffset=0, logEndOffset=30) with 1 segments in 31ms (28/53 loaded in /bitnami/kafka/data) (kafka.log.LogManager)
[2022-10-11 02:43:38,961] INFO [Log partition=__consumer_offsets-29, dir=/bitnami/kafka/data] Recovering unflushed segment 0 (kafka.log.Log)
[2022-10-11 02:43:38,962] INFO [Log partition=__consumer_offsets-29, dir=/bitnami/kafka/data] Loading producer state till offset 0 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:38,968] INFO [ProducerStateManager partition=__consumer_offsets-29] Writing producer snapshot at offset 23 (kafka.log.ProducerStateManager)
[2022-10-11 02:43:38,976] INFO [Log partition=__consumer_offsets-29, dir=/bitnami/kafka/data] Loading producer state till offset 23 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:38,976] INFO [ProducerStateManager partition=__consumer_offsets-29] Loading producer state from snapshot file 'SnapshotFile(/bitnami/kafka/data/__consumer_offsets-29/00000000000000000023.snapshot,23)' (kafka.log.ProducerStateManager)
[2022-10-11 02:43:38,989] INFO Completed load of Log(dir=/bitnami/kafka/data/__consumer_offsets-29, topic=__consumer_offsets, partition=29, highWatermark=0, lastStableOffset=0, logStartOffset=0, logEndOffset=23) with 1 segments in 34ms (29/53 loaded in /bitnami/kafka/data) (kafka.log.LogManager)
[2022-10-11 02:43:38,996] INFO [Log partition=__consumer_offsets-14, dir=/bitnami/kafka/data] Recovering unflushed segment 0 (kafka.log.Log)
[2022-10-11 02:43:38,997] INFO [Log partition=__consumer_offsets-14, dir=/bitnami/kafka/data] Loading producer state till offset 0 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:39,002] INFO [ProducerStateManager partition=__consumer_offsets-14] Writing producer snapshot at offset 23 (kafka.log.ProducerStateManager)
[2022-10-11 02:43:39,009] INFO [Log partition=__consumer_offsets-14, dir=/bitnami/kafka/data] Loading producer state till offset 23 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:39,010] INFO [ProducerStateManager partition=__consumer_offsets-14] Loading producer state from snapshot file 'SnapshotFile(/bitnami/kafka/data/__consumer_offsets-14/00000000000000000023.snapshot,23)' (kafka.log.ProducerStateManager)
[2022-10-11 02:43:39,018] INFO Completed load of Log(dir=/bitnami/kafka/data/__consumer_offsets-14, topic=__consumer_offsets, partition=14, highWatermark=0, lastStableOffset=0, logStartOffset=0, logEndOffset=23) with 1 segments in 29ms (30/53 loaded in /bitnami/kafka/data) (kafka.log.LogManager)
[2022-10-11 02:43:39,024] INFO [Log partition=__consumer_offsets-2, dir=/bitnami/kafka/data] Recovering unflushed segment 0 (kafka.log.Log)
[2022-10-11 02:43:39,025] INFO [Log partition=__consumer_offsets-2, dir=/bitnami/kafka/data] Loading producer state till offset 0 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:39,031] INFO [ProducerStateManager partition=__consumer_offsets-2] Writing producer snapshot at offset 28 (kafka.log.ProducerStateManager)
[2022-10-11 02:43:39,038] INFO [Log partition=__consumer_offsets-2, dir=/bitnami/kafka/data] Loading producer state till offset 28 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:39,039] INFO [ProducerStateManager partition=__consumer_offsets-2] Loading producer state from snapshot file 'SnapshotFile(/bitnami/kafka/data/__consumer_offsets-2/00000000000000000028.snapshot,28)' (kafka.log.ProducerStateManager)
[2022-10-11 02:43:39,048] INFO Completed load of Log(dir=/bitnami/kafka/data/__consumer_offsets-2, topic=__consumer_offsets, partition=2, highWatermark=0, lastStableOffset=0, logStartOffset=0, logEndOffset=28) with 1 segments in 29ms (31/53 loaded in /bitnami/kafka/data) (kafka.log.LogManager)
[2022-10-11 02:43:39,060] INFO [Log partition=__consumer_offsets-16, dir=/bitnami/kafka/data] Recovering unflushed segment 0 (kafka.log.Log)
[2022-10-11 02:43:39,062] INFO [Log partition=__consumer_offsets-16, dir=/bitnami/kafka/data] Loading producer state till offset 0 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:39,065] INFO [ProducerStateManager partition=__consumer_offsets-16] Writing producer snapshot at offset 6 (kafka.log.ProducerStateManager)
[2022-10-11 02:43:39,075] INFO [Log partition=__consumer_offsets-16, dir=/bitnami/kafka/data] Loading producer state till offset 6 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:39,076] INFO [ProducerStateManager partition=__consumer_offsets-16] Loading producer state from snapshot file 'SnapshotFile(/bitnami/kafka/data/__consumer_offsets-16/00000000000000000006.snapshot,6)' (kafka.log.ProducerStateManager)
[2022-10-11 02:43:39,088] INFO Completed load of Log(dir=/bitnami/kafka/data/__consumer_offsets-16, topic=__consumer_offsets, partition=16, highWatermark=0, lastStableOffset=0, logStartOffset=0, logEndOffset=6) with 1 segments in 40ms (32/53 loaded in /bitnami/kafka/data) (kafka.log.LogManager)
[2022-10-11 02:43:39,098] INFO [Log partition=__consumer_offsets-49, dir=/bitnami/kafka/data] Recovering unflushed segment 0 (kafka.log.Log)
[2022-10-11 02:43:39,099] INFO [Log partition=__consumer_offsets-49, dir=/bitnami/kafka/data] Loading producer state till offset 0 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:39,109] INFO [ProducerStateManager partition=__consumer_offsets-49] Writing producer snapshot at offset 9 (kafka.log.ProducerStateManager)
[2022-10-11 02:43:39,123] INFO [Log partition=__consumer_offsets-49, dir=/bitnami/kafka/data] Loading producer state till offset 9 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:39,124] INFO [ProducerStateManager partition=__consumer_offsets-49] Loading producer state from snapshot file 'SnapshotFile(/bitnami/kafka/data/__consumer_offsets-49/00000000000000000009.snapshot,9)' (kafka.log.ProducerStateManager)
[2022-10-11 02:43:39,136] INFO Completed load of Log(dir=/bitnami/kafka/data/__consumer_offsets-49, topic=__consumer_offsets, partition=49, highWatermark=0, lastStableOffset=0, logStartOffset=0, logEndOffset=9) with 1 segments in 47ms (33/53 loaded in /bitnami/kafka/data) (kafka.log.LogManager)
[2022-10-11 02:43:39,142] INFO [Log partition=__consumer_offsets-36, dir=/bitnami/kafka/data] Recovering unflushed segment 0 (kafka.log.Log)
[2022-10-11 02:43:39,143] INFO [Log partition=__consumer_offsets-36, dir=/bitnami/kafka/data] Loading producer state till offset 0 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:39,146] INFO [ProducerStateManager partition=__consumer_offsets-36] Writing producer snapshot at offset 3 (kafka.log.ProducerStateManager)
[2022-10-11 02:43:39,153] INFO [Log partition=__consumer_offsets-36, dir=/bitnami/kafka/data] Loading producer state till offset 3 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:39,154] INFO [ProducerStateManager partition=__consumer_offsets-36] Loading producer state from snapshot file 'SnapshotFile(/bitnami/kafka/data/__consumer_offsets-36/00000000000000000003.snapshot,3)' (kafka.log.ProducerStateManager)
[2022-10-11 02:43:39,162] INFO Completed load of Log(dir=/bitnami/kafka/data/__consumer_offsets-36, topic=__consumer_offsets, partition=36, highWatermark=0, lastStableOffset=0, logStartOffset=0, logEndOffset=3) with 1 segments in 25ms (34/53 loaded in /bitnami/kafka/data) (kafka.log.LogManager)
[2022-10-11 02:43:39,272] INFO [Log partition=backend.GSG_Order-0, dir=/bitnami/kafka/data] Recovering unflushed segment 0 (kafka.log.Log)
[2022-10-11 02:43:39,274] INFO [Log partition=backend.GSG_Order-0, dir=/bitnami/kafka/data] Loading producer state till offset 0 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:39,499] INFO [ProducerStateManager partition=backend.GSG_Order-0] Writing producer snapshot at offset 1568 (kafka.log.ProducerStateManager)
[2022-10-11 02:43:39,559] INFO [Log partition=backend.GSG_Order-0, dir=/bitnami/kafka/data] Loading producer state till offset 1568 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:39,561] INFO [ProducerStateManager partition=backend.GSG_Order-0] Loading producer state from snapshot file 'SnapshotFile(/bitnami/kafka/data/backend.GSG_Order-0/00000000000000001568.snapshot,1568)' (kafka.log.ProducerStateManager)
[2022-10-11 02:43:39,597] INFO Completed load of Log(dir=/bitnami/kafka/data/backend.GSG_Order-0, topic=backend.GSG_Order, partition=0, highWatermark=0, lastStableOffset=0, logStartOffset=0, logEndOffset=1568) with 1 segments in 435ms (35/53 loaded in /bitnami/kafka/data) (kafka.log.LogManager)
[2022-10-11 02:43:39,610] INFO [Log partition=__consumer_offsets-41, dir=/bitnami/kafka/data] Recovering unflushed segment 0 (kafka.log.Log)
[2022-10-11 02:43:39,611] INFO [Log partition=__consumer_offsets-41, dir=/bitnami/kafka/data] Loading producer state till offset 0 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:39,617] INFO [ProducerStateManager partition=__consumer_offsets-41] Writing producer snapshot at offset 3 (kafka.log.ProducerStateManager)
[2022-10-11 02:43:39,626] INFO [Log partition=__consumer_offsets-41, dir=/bitnami/kafka/data] Loading producer state till offset 3 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:39,627] INFO [ProducerStateManager partition=__consumer_offsets-41] Loading producer state from snapshot file 'SnapshotFile(/bitnami/kafka/data/__consumer_offsets-41/00000000000000000003.snapshot,3)' (kafka.log.ProducerStateManager)
[2022-10-11 02:43:39,636] INFO Completed load of Log(dir=/bitnami/kafka/data/__consumer_offsets-41, topic=__consumer_offsets, partition=41, highWatermark=0, lastStableOffset=0, logStartOffset=0, logEndOffset=3) with 1 segments in 37ms (36/53 loaded in /bitnami/kafka/data) (kafka.log.LogManager)
[2022-10-11 02:43:39,647] INFO [Log partition=__consumer_offsets-9, dir=/bitnami/kafka/data] Recovering unflushed segment 0 (kafka.log.Log)
[2022-10-11 02:43:39,650] INFO [Log partition=__consumer_offsets-9, dir=/bitnami/kafka/data] Loading producer state till offset 0 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:39,658] INFO [ProducerStateManager partition=__consumer_offsets-9] Writing producer snapshot at offset 27 (kafka.log.ProducerStateManager)
[2022-10-11 02:43:39,667] INFO [Log partition=__consumer_offsets-9, dir=/bitnami/kafka/data] Loading producer state till offset 27 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:39,668] INFO [ProducerStateManager partition=__consumer_offsets-9] Loading producer state from snapshot file 'SnapshotFile(/bitnami/kafka/data/__consumer_offsets-9/00000000000000000027.snapshot,27)' (kafka.log.ProducerStateManager)
[2022-10-11 02:43:39,679] INFO Completed load of Log(dir=/bitnami/kafka/data/__consumer_offsets-9, topic=__consumer_offsets, partition=9, highWatermark=0, lastStableOffset=0, logStartOffset=0, logEndOffset=27) with 1 segments in 41ms (37/53 loaded in /bitnami/kafka/data) (kafka.log.LogManager)
[2022-10-11 02:43:39,687] INFO [Log partition=__consumer_offsets-12, dir=/bitnami/kafka/data] Recovering unflushed segment 0 (kafka.log.Log)
[2022-10-11 02:43:39,688] INFO [Log partition=__consumer_offsets-12, dir=/bitnami/kafka/data] Loading producer state till offset 0 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:39,692] INFO [ProducerStateManager partition=__consumer_offsets-12] Writing producer snapshot at offset 9 (kafka.log.ProducerStateManager)
[2022-10-11 02:43:39,699] INFO [Log partition=__consumer_offsets-12, dir=/bitnami/kafka/data] Loading producer state till offset 9 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:39,700] INFO [ProducerStateManager partition=__consumer_offsets-12] Loading producer state from snapshot file 'SnapshotFile(/bitnami/kafka/data/__consumer_offsets-12/00000000000000000009.snapshot,9)' (kafka.log.ProducerStateManager)
[2022-10-11 02:43:39,711] INFO Completed load of Log(dir=/bitnami/kafka/data/__consumer_offsets-12, topic=__consumer_offsets, partition=12, highWatermark=0, lastStableOffset=0, logStartOffset=0, logEndOffset=9) with 1 segments in 30ms (38/53 loaded in /bitnami/kafka/data) (kafka.log.LogManager)
[2022-10-11 02:43:39,721] INFO [Log partition=__consumer_offsets-20, dir=/bitnami/kafka/data] Recovering unflushed segment 0 (kafka.log.Log)
[2022-10-11 02:43:39,722] INFO [Log partition=__consumer_offsets-20, dir=/bitnami/kafka/data] Loading producer state till offset 0 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:39,727] INFO [ProducerStateManager partition=__consumer_offsets-20] Writing producer snapshot at offset 12 (kafka.log.ProducerStateManager)
[2022-10-11 02:43:39,733] INFO [Log partition=__consumer_offsets-20, dir=/bitnami/kafka/data] Loading producer state till offset 12 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:39,734] INFO [ProducerStateManager partition=__consumer_offsets-20] Loading producer state from snapshot file 'SnapshotFile(/bitnami/kafka/data/__consumer_offsets-20/00000000000000000012.snapshot,12)' (kafka.log.ProducerStateManager)
[2022-10-11 02:43:39,741] INFO Completed load of Log(dir=/bitnami/kafka/data/__consumer_offsets-20, topic=__consumer_offsets, partition=20, highWatermark=0, lastStableOffset=0, logStartOffset=0, logEndOffset=12) with 1 segments in 29ms (39/53 loaded in /bitnami/kafka/data) (kafka.log.LogManager)
[2022-10-11 02:43:39,752] INFO [Log partition=__consumer_offsets-42, dir=/bitnami/kafka/data] Recovering unflushed segment 0 (kafka.log.Log)
[2022-10-11 02:43:39,753] INFO [Log partition=__consumer_offsets-42, dir=/bitnami/kafka/data] Loading producer state till offset 0 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:39,757] INFO [ProducerStateManager partition=__consumer_offsets-42] Writing producer snapshot at offset 12 (kafka.log.ProducerStateManager)
[2022-10-11 02:43:39,769] INFO [Log partition=__consumer_offsets-42, dir=/bitnami/kafka/data] Loading producer state till offset 12 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:39,769] INFO [ProducerStateManager partition=__consumer_offsets-42] Loading producer state from snapshot file 'SnapshotFile(/bitnami/kafka/data/__consumer_offsets-42/00000000000000000012.snapshot,12)' (kafka.log.ProducerStateManager)
[2022-10-11 02:43:39,779] INFO Completed load of Log(dir=/bitnami/kafka/data/__consumer_offsets-42, topic=__consumer_offsets, partition=42, highWatermark=0, lastStableOffset=0, logStartOffset=0, logEndOffset=12) with 1 segments in 37ms (40/53 loaded in /bitnami/kafka/data) (kafka.log.LogManager)
[2022-10-11 02:43:39,788] INFO [Log partition=__consumer_offsets-19, dir=/bitnami/kafka/data] Recovering unflushed segment 0 (kafka.log.Log)
[2022-10-11 02:43:39,789] INFO [Log partition=__consumer_offsets-19, dir=/bitnami/kafka/data] Loading producer state till offset 0 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:39,800] INFO [ProducerStateManager partition=__consumer_offsets-19] Writing producer snapshot at offset 152 (kafka.log.ProducerStateManager)
[2022-10-11 02:43:39,807] INFO [Log partition=__consumer_offsets-19, dir=/bitnami/kafka/data] Loading producer state till offset 152 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:39,807] INFO [ProducerStateManager partition=__consumer_offsets-19] Loading producer state from snapshot file 'SnapshotFile(/bitnami/kafka/data/__consumer_offsets-19/00000000000000000152.snapshot,152)' (kafka.log.ProducerStateManager)
[2022-10-11 02:43:39,820] INFO Completed load of Log(dir=/bitnami/kafka/data/__consumer_offsets-19, topic=__consumer_offsets, partition=19, highWatermark=0, lastStableOffset=0, logStartOffset=0, logEndOffset=152) with 1 segments in 41ms (41/53 loaded in /bitnami/kafka/data) (kafka.log.LogManager)
[2022-10-11 02:43:39,829] INFO [Log partition=__consumer_offsets-44, dir=/bitnami/kafka/data] Recovering unflushed segment 0 (kafka.log.Log)
[2022-10-11 02:43:39,830] INFO [Log partition=__consumer_offsets-44, dir=/bitnami/kafka/data] Loading producer state till offset 0 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:39,834] INFO [ProducerStateManager partition=__consumer_offsets-44] Writing producer snapshot at offset 3 (kafka.log.ProducerStateManager)
[2022-10-11 02:43:39,841] INFO [Log partition=__consumer_offsets-44, dir=/bitnami/kafka/data] Loading producer state till offset 3 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:39,842] INFO [ProducerStateManager partition=__consumer_offsets-44] Loading producer state from snapshot file 'SnapshotFile(/bitnami/kafka/data/__consumer_offsets-44/00000000000000000003.snapshot,3)' (kafka.log.ProducerStateManager)
[2022-10-11 02:43:39,848] INFO Completed load of Log(dir=/bitnami/kafka/data/__consumer_offsets-44, topic=__consumer_offsets, partition=44, highWatermark=0, lastStableOffset=0, logStartOffset=0, logEndOffset=3) with 1 segments in 28ms (42/53 loaded in /bitnami/kafka/data) (kafka.log.LogManager)
[2022-10-11 02:43:39,855] INFO [Log partition=__consumer_offsets-3, dir=/bitnami/kafka/data] Recovering unflushed segment 0 (kafka.log.Log)
[2022-10-11 02:43:39,856] INFO [Log partition=__consumer_offsets-3, dir=/bitnami/kafka/data] Loading producer state till offset 0 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:39,859] INFO [ProducerStateManager partition=__consumer_offsets-3] Writing producer snapshot at offset 12 (kafka.log.ProducerStateManager)
[2022-10-11 02:43:39,868] INFO [Log partition=__consumer_offsets-3, dir=/bitnami/kafka/data] Loading producer state till offset 12 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:39,869] INFO [ProducerStateManager partition=__consumer_offsets-3] Loading producer state from snapshot file 'SnapshotFile(/bitnami/kafka/data/__consumer_offsets-3/00000000000000000012.snapshot,12)' (kafka.log.ProducerStateManager)
[2022-10-11 02:43:39,878] INFO Completed load of Log(dir=/bitnami/kafka/data/__consumer_offsets-3, topic=__consumer_offsets, partition=3, highWatermark=0, lastStableOffset=0, logStartOffset=0, logEndOffset=12) with 1 segments in 30ms (43/53 loaded in /bitnami/kafka/data) (kafka.log.LogManager)
[2022-10-11 02:43:39,887] INFO [Log partition=__consumer_offsets-33, dir=/bitnami/kafka/data] Recovering unflushed segment 0 (kafka.log.Log)
[2022-10-11 02:43:39,888] INFO [Log partition=__consumer_offsets-33, dir=/bitnami/kafka/data] Loading producer state till offset 0 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:39,894] INFO [ProducerStateManager partition=__consumer_offsets-33] Writing producer snapshot at offset 6 (kafka.log.ProducerStateManager)
[2022-10-11 02:43:39,902] INFO [Log partition=__consumer_offsets-33, dir=/bitnami/kafka/data] Loading producer state till offset 6 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:39,903] INFO [ProducerStateManager partition=__consumer_offsets-33] Loading producer state from snapshot file 'SnapshotFile(/bitnami/kafka/data/__consumer_offsets-33/00000000000000000006.snapshot,6)' (kafka.log.ProducerStateManager)
[2022-10-11 02:43:39,915] INFO Completed load of Log(dir=/bitnami/kafka/data/__consumer_offsets-33, topic=__consumer_offsets, partition=33, highWatermark=0, lastStableOffset=0, logStartOffset=0, logEndOffset=6) with 1 segments in 35ms (44/53 loaded in /bitnami/kafka/data) (kafka.log.LogManager)
[2022-10-11 02:43:39,924] INFO [Log partition=__consumer_offsets-37, dir=/bitnami/kafka/data] Recovering unflushed segment 0 (kafka.log.Log)
[2022-10-11 02:43:39,925] INFO [Log partition=__consumer_offsets-37, dir=/bitnami/kafka/data] Loading producer state till offset 0 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:39,930] INFO [ProducerStateManager partition=__consumer_offsets-37] Writing producer snapshot at offset 6 (kafka.log.ProducerStateManager)
[2022-10-11 02:43:39,939] INFO [Log partition=__consumer_offsets-37, dir=/bitnami/kafka/data] Loading producer state till offset 6 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:39,940] INFO [ProducerStateManager partition=__consumer_offsets-37] Loading producer state from snapshot file 'SnapshotFile(/bitnami/kafka/data/__consumer_offsets-37/00000000000000000006.snapshot,6)' (kafka.log.ProducerStateManager)
[2022-10-11 02:43:39,948] INFO Completed load of Log(dir=/bitnami/kafka/data/__consumer_offsets-37, topic=__consumer_offsets, partition=37, highWatermark=0, lastStableOffset=0, logStartOffset=0, logEndOffset=6) with 1 segments in 31ms (45/53 loaded in /bitnami/kafka/data) (kafka.log.LogManager)
[2022-10-11 02:43:39,957] INFO [Log partition=__consumer_offsets-4, dir=/bitnami/kafka/data] Recovering unflushed segment 0 (kafka.log.Log)
[2022-10-11 02:43:39,958] INFO [Log partition=__consumer_offsets-4, dir=/bitnami/kafka/data] Loading producer state till offset 0 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:39,961] INFO [ProducerStateManager partition=__consumer_offsets-4] Writing producer snapshot at offset 12 (kafka.log.ProducerStateManager)
[2022-10-11 02:43:39,973] INFO [Log partition=__consumer_offsets-4, dir=/bitnami/kafka/data] Loading producer state till offset 12 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:39,973] INFO [ProducerStateManager partition=__consumer_offsets-4] Loading producer state from snapshot file 'SnapshotFile(/bitnami/kafka/data/__consumer_offsets-4/00000000000000000012.snapshot,12)' (kafka.log.ProducerStateManager)
[2022-10-11 02:43:39,979] INFO Completed load of Log(dir=/bitnami/kafka/data/__consumer_offsets-4, topic=__consumer_offsets, partition=4, highWatermark=0, lastStableOffset=0, logStartOffset=0, logEndOffset=12) with 1 segments in 31ms (46/53 loaded in /bitnami/kafka/data) (kafka.log.LogManager)
[2022-10-11 02:43:39,988] INFO [Log partition=__consumer_offsets-10, dir=/bitnami/kafka/data] Recovering unflushed segment 0 (kafka.log.Log)
[2022-10-11 02:43:39,990] INFO [Log partition=__consumer_offsets-10, dir=/bitnami/kafka/data] Loading producer state till offset 0 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:39,997] INFO [ProducerStateManager partition=__consumer_offsets-10] Writing producer snapshot at offset 23 (kafka.log.ProducerStateManager)
[2022-10-11 02:43:40,012] INFO [Log partition=__consumer_offsets-10, dir=/bitnami/kafka/data] Loading producer state till offset 23 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:40,014] INFO [ProducerStateManager partition=__consumer_offsets-10] Loading producer state from snapshot file 'SnapshotFile(/bitnami/kafka/data/__consumer_offsets-10/00000000000000000023.snapshot,23)' (kafka.log.ProducerStateManager)
[2022-10-11 02:43:40,022] INFO Completed load of Log(dir=/bitnami/kafka/data/__consumer_offsets-10, topic=__consumer_offsets, partition=10, highWatermark=0, lastStableOffset=0, logStartOffset=0, logEndOffset=23) with 1 segments in 42ms (47/53 loaded in /bitnami/kafka/data) (kafka.log.LogManager)
[2022-10-11 02:43:40,032] INFO [Log partition=__consumer_offsets-47, dir=/bitnami/kafka/data] Recovering unflushed segment 0 (kafka.log.Log)
[2022-10-11 02:43:40,034] INFO [Log partition=__consumer_offsets-47, dir=/bitnami/kafka/data] Loading producer state till offset 0 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:40,044] INFO [Log partition=__consumer_offsets-47, dir=/bitnami/kafka/data] Loading producer state till offset 0 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:40,055] INFO Completed load of Log(dir=/bitnami/kafka/data/__consumer_offsets-47, topic=__consumer_offsets, partition=47, highWatermark=0, lastStableOffset=0, logStartOffset=0, logEndOffset=0) with 1 segments in 33ms (48/53 loaded in /bitnami/kafka/data) (kafka.log.LogManager)
[2022-10-11 02:43:40,089] INFO [Log partition=__consumer_offsets-11, dir=/bitnami/kafka/data] Recovering unflushed segment 0 (kafka.log.Log)
[2022-10-11 02:43:40,090] INFO [Log partition=__consumer_offsets-11, dir=/bitnami/kafka/data] Loading producer state till offset 0 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:40,096] INFO [ProducerStateManager partition=__consumer_offsets-11] Writing producer snapshot at offset 12 (kafka.log.ProducerStateManager)
[2022-10-11 02:43:40,105] INFO [Log partition=__consumer_offsets-11, dir=/bitnami/kafka/data] Loading producer state till offset 12 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:40,106] INFO [ProducerStateManager partition=__consumer_offsets-11] Loading producer state from snapshot file 'SnapshotFile(/bitnami/kafka/data/__consumer_offsets-11/00000000000000000012.snapshot,12)' (kafka.log.ProducerStateManager)
[2022-10-11 02:43:40,116] INFO Completed load of Log(dir=/bitnami/kafka/data/__consumer_offsets-11, topic=__consumer_offsets, partition=11, highWatermark=0, lastStableOffset=0, logStartOffset=0, logEndOffset=12) with 1 segments in 59ms (49/53 loaded in /bitnami/kafka/data) (kafka.log.LogManager)
[2022-10-11 02:43:40,136] INFO [Log partition=__consumer_offsets-35, dir=/bitnami/kafka/data] Recovering unflushed segment 0 (kafka.log.Log)
[2022-10-11 02:43:40,137] INFO [Log partition=__consumer_offsets-35, dir=/bitnami/kafka/data] Loading producer state till offset 0 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:40,145] INFO [ProducerStateManager partition=__consumer_offsets-35] Writing producer snapshot at offset 6 (kafka.log.ProducerStateManager)
[2022-10-11 02:43:40,160] INFO [Log partition=__consumer_offsets-35, dir=/bitnami/kafka/data] Loading producer state till offset 6 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:40,163] INFO [ProducerStateManager partition=__consumer_offsets-35] Loading producer state from snapshot file 'SnapshotFile(/bitnami/kafka/data/__consumer_offsets-35/00000000000000000006.snapshot,6)' (kafka.log.ProducerStateManager)
[2022-10-11 02:43:40,179] INFO Completed load of Log(dir=/bitnami/kafka/data/__consumer_offsets-35, topic=__consumer_offsets, partition=35, highWatermark=0, lastStableOffset=0, logStartOffset=0, logEndOffset=6) with 1 segments in 63ms (50/53 loaded in /bitnami/kafka/data) (kafka.log.LogManager)
[2022-10-11 02:43:40,193] INFO [Log partition=__consumer_offsets-38, dir=/bitnami/kafka/data] Recovering unflushed segment 0 (kafka.log.Log)
[2022-10-11 02:43:40,196] INFO [Log partition=__consumer_offsets-38, dir=/bitnami/kafka/data] Loading producer state till offset 0 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:40,208] INFO [ProducerStateManager partition=__consumer_offsets-38] Writing producer snapshot at offset 9 (kafka.log.ProducerStateManager)
[2022-10-11 02:43:40,222] INFO [Log partition=__consumer_offsets-38, dir=/bitnami/kafka/data] Loading producer state till offset 9 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:40,223] INFO [ProducerStateManager partition=__consumer_offsets-38] Loading producer state from snapshot file 'SnapshotFile(/bitnami/kafka/data/__consumer_offsets-38/00000000000000000009.snapshot,9)' (kafka.log.ProducerStateManager)
[2022-10-11 02:43:40,229] INFO Completed load of Log(dir=/bitnami/kafka/data/__consumer_offsets-38, topic=__consumer_offsets, partition=38, highWatermark=0, lastStableOffset=0, logStartOffset=0, logEndOffset=9) with 1 segments in 46ms (51/53 loaded in /bitnami/kafka/data) (kafka.log.LogManager)
[2022-10-11 02:43:40,240] INFO [Log partition=backend.GSG_Transfer-0, dir=/bitnami/kafka/data] Recovering unflushed segment 0 (kafka.log.Log)
[2022-10-11 02:43:40,242] INFO [Log partition=backend.GSG_Transfer-0, dir=/bitnami/kafka/data] Loading producer state till offset 0 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:40,256] INFO [ProducerStateManager partition=backend.GSG_Transfer-0] Writing producer snapshot at offset 148 (kafka.log.ProducerStateManager)
[2022-10-11 02:43:40,274] INFO [Log partition=backend.GSG_Transfer-0, dir=/bitnami/kafka/data] Loading producer state till offset 148 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:40,278] INFO [ProducerStateManager partition=backend.GSG_Transfer-0] Loading producer state from snapshot file 'SnapshotFile(/bitnami/kafka/data/backend.GSG_Transfer-0/00000000000000000148.snapshot,148)' (kafka.log.ProducerStateManager)
[2022-10-11 02:43:40,300] INFO Completed load of Log(dir=/bitnami/kafka/data/backend.GSG_Transfer-0, topic=backend.GSG_Transfer, partition=0, highWatermark=0, lastStableOffset=0, logStartOffset=0, logEndOffset=148) with 1 segments in 69ms (52/53 loaded in /bitnami/kafka/data) (kafka.log.LogManager)
[2022-10-11 02:43:40,318] INFO [Log partition=__consumer_offsets-28, dir=/bitnami/kafka/data] Recovering unflushed segment 0 (kafka.log.Log)
[2022-10-11 02:43:40,321] INFO [Log partition=__consumer_offsets-28, dir=/bitnami/kafka/data] Loading producer state till offset 0 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:40,328] INFO [ProducerStateManager partition=__consumer_offsets-28] Writing producer snapshot at offset 17 (kafka.log.ProducerStateManager)
[2022-10-11 02:43:40,346] INFO [Log partition=__consumer_offsets-28, dir=/bitnami/kafka/data] Loading producer state till offset 17 with message format version 2 (kafka.log.Log)
[2022-10-11 02:43:40,347] INFO [ProducerStateManager partition=__consumer_offsets-28] Loading producer state from snapshot file 'SnapshotFile(/bitnami/kafka/data/__consumer_offsets-28/00000000000000000017.snapshot,17)' (kafka.log.ProducerStateManager)
[2022-10-11 02:43:40,356] INFO Completed load of Log(dir=/bitnami/kafka/data/__consumer_offsets-28, topic=__consumer_offsets, partition=28, highWatermark=0, lastStableOffset=0, logStartOffset=0, logEndOffset=17) with 1 segments in 54ms (53/53 loaded in /bitnami/kafka/data) (kafka.log.LogManager)
[2022-10-11 02:43:40,402] INFO Loaded 53 logs in 4144ms. (kafka.log.LogManager)
[2022-10-11 02:43:40,410] INFO Starting log cleanup with a period of 300000 ms. (kafka.log.LogManager)
[2022-10-11 02:43:40,417] INFO Starting log flusher with a default period of 9223372036854775807 ms. (kafka.log.LogManager)
[2022-10-11 02:43:40,479] INFO Starting the log cleaner (kafka.log.LogCleaner)
[2022-10-11 02:43:40,907] INFO [kafka-log-cleaner-thread-0]: Starting (kafka.log.LogCleaner)
[2022-10-11 02:43:43,912] INFO Updated connection-accept-rate max connection creation rate to 2147483647 (kafka.network.ConnectionQuotas)
[2022-10-11 02:43:44,028] INFO Awaiting socket connections on 0.0.0.0:9092. (kafka.network.Acceptor)
[2022-10-11 02:43:44,313] INFO [SocketServer listenerType=ZK_BROKER, nodeId=500] Created data-plane acceptor and processors for endpoint : ListenerName(PLAINTEXT) (kafka.network.SocketServer)
[2022-10-11 02:43:44,321] INFO Updated connection-accept-rate max connection creation rate to 2147483647 (kafka.network.ConnectionQuotas)
[2022-10-11 02:43:44,321] INFO Awaiting socket connections on 0.0.0.0:29092. (kafka.network.Acceptor)
[2022-10-11 02:43:44,457] INFO [SocketServer listenerType=ZK_BROKER, nodeId=500] Created data-plane acceptor and processors for endpoint : ListenerName(DOCKER) (kafka.network.SocketServer)
[2022-10-11 02:43:44,719] INFO [broker-500-to-controller-send-thread]: Starting (kafka.server.BrokerToControllerRequestThread)
[2022-10-11 02:43:44,901] INFO [ExpirationReaper-500-Produce]: Starting (kafka.server.DelayedOperationPurgatory$ExpiredOperationReaper)
[2022-10-11 02:43:44,916] INFO [ExpirationReaper-500-Fetch]: Starting (kafka.server.DelayedOperationPurgatory$ExpiredOperationReaper)
[2022-10-11 02:43:44,931] INFO [ExpirationReaper-500-DeleteRecords]: Starting (kafka.server.DelayedOperationPurgatory$ExpiredOperationReaper)
[2022-10-11 02:43:44,942] INFO [ExpirationReaper-500-ElectLeader]: Starting (kafka.server.DelayedOperationPurgatory$ExpiredOperationReaper)

Zookeeper:

zookeeper 02:43:15.04 
zookeeper 02:43:15.08 Welcome to the Bitnami zookeeper container
zookeeper 02:43:15.10 Subscribe to project updates by watching https://github.com/bitnami/containers
zookeeper 02:43:15.13 Submit issues and feature requests at https://github.com/bitnami/containers/issues
zookeeper 02:43:15.15 
zookeeper 02:43:15.18 INFO  ==> ** Starting ZooKeeper setup **
zookeeper 02:43:15.34 DEBUG ==> Validating settings in ZOO_* env vars...
zookeeper 02:43:15.51 WARN  ==> You have set the environment variable ALLOW_ANONYMOUS_LOGIN=yes. For safety reasons, do not use this flag in a production environment.
zookeeper 02:43:15.65 INFO  ==> Initializing ZooKeeper...
zookeeper 02:43:15.68 INFO  ==> User injected custom configuration detected!
zookeeper 02:43:15.76 INFO  ==> Deploying ZooKeeper with persisted data...
zookeeper 02:43:15.78 INFO  ==> ** ZooKeeper setup finished! **

zookeeper 02:43:15.94 INFO  ==> ** Starting ZooKeeper **
/opt/bitnami/java/bin/java
ZooKeeper JMX enabled by default
Using config: /opt/bitnami/zookeeper/bin/../conf/zoo.cfg
2022-10-11 02:43:20,583 [myid:] - INFO  [main:QuorumPeerConfig@174] - Reading configuration from: /opt/bitnami/zookeeper/bin/../conf/zoo.cfg
2022-10-11 02:43:20,646 [myid:] - INFO  [main:QuorumPeerConfig@444] - clientPortAddress is 0.0.0.0:2181
2022-10-11 02:43:20,647 [myid:] - INFO  [main:QuorumPeerConfig@448] - secureClientPort is not set
2022-10-11 02:43:20,648 [myid:] - INFO  [main:QuorumPeerConfig@464] - observerMasterPort is not set
2022-10-11 02:43:20,648 [myid:] - INFO  [main:QuorumPeerConfig@481] - metricsProvider.className is org.apache.zookeeper.metrics.impl.DefaultMetricsProvider
2022-10-11 02:43:20,672 [myid:1] - INFO  [main:DatadirCleanupManager@78] - autopurge.snapRetainCount set to 3
2022-10-11 02:43:20,672 [myid:1] - INFO  [main:DatadirCleanupManager@79] - autopurge.purgeInterval set to 0
2022-10-11 02:43:20,673 [myid:1] - INFO  [main:DatadirCleanupManager@101] - Purge task is not scheduled.
2022-10-11 02:43:20,673 [myid:1] - WARN  [main:QuorumPeerMain@139] - Either no config or no quorum defined in config, running in standalone mode
2022-10-11 02:43:20,681 [myid:1] - INFO  [main:ManagedUtil@46] - Log4j 1.2 jmx support not found; jmx disabled.
2022-10-11 02:43:20,684 [myid:1] - INFO  [main:QuorumPeerConfig@174] - Reading configuration from: /opt/bitnami/zookeeper/bin/../conf/zoo.cfg
2022-10-11 02:43:20,686 [myid:1] - INFO  [main:QuorumPeerConfig@444] - clientPortAddress is 0.0.0.0:2181
2022-10-11 02:43:20,687 [myid:1] - INFO  [main:QuorumPeerConfig@448] - secureClientPort is not set
2022-10-11 02:43:20,687 [myid:1] - INFO  [main:QuorumPeerConfig@464] - observerMasterPort is not set
2022-10-11 02:43:20,687 [myid:1] - INFO  [main:QuorumPeerConfig@481] - metricsProvider.className is org.apache.zookeeper.metrics.impl.DefaultMetricsProvider
2022-10-11 02:43:20,689 [myid:1] - INFO  [main:ZooKeeperServerMain@123] - Starting server
2022-10-11 02:43:20,763 [myid:1] - INFO  [main:ServerMetrics@62] - ServerMetrics initialized with provider org.apache.zookeeper.metrics.impl.DefaultMetricsProvider@2898ac89
2022-10-11 02:43:20,777 [myid:1] - INFO  [main:DigestAuthenticationProvider@47] - ACL digest algorithm is: SHA1
2022-10-11 02:43:20,778 [myid:1] - INFO  [main:DigestAuthenticationProvider@61] - zookeeper.DigestAuthenticationProvider.enabled = true
2022-10-11 02:43:20,787 [myid:1] - INFO  [main:FileTxnSnapLog@124] - zookeeper.snapshot.trust.empty : false
2022-10-11 02:43:20,840 [myid:1] - INFO  [main:ZookeeperBanner@42] - 
2022-10-11 02:43:20,841 [myid:1] - INFO  [main:ZookeeperBanner@42] -   ______                  _                                          
2022-10-11 02:43:20,841 [myid:1] - INFO  [main:ZookeeperBanner@42] -  |___  /                 | |                                         
2022-10-11 02:43:20,841 [myid:1] - INFO  [main:ZookeeperBanner@42] -     / /    ___     ___   | | __   ___    ___   _ __     ___   _ __   
2022-10-11 02:43:20,842 [myid:1] - INFO  [main:ZookeeperBanner@42] -    / /    / _ \   / _ \  | |/ /  / _ \  / _ \ | '_ \   / _ \ | '__|
2022-10-11 02:43:20,842 [myid:1] - INFO  [main:ZookeeperBanner@42] -   / /__  | (_) | | (_) | |   <  |  __/ |  __/ | |_) | |  __/ | |    
2022-10-11 02:43:20,842 [myid:1] - INFO  [main:ZookeeperBanner@42] -  /_____|  \___/   \___/  |_|\_\  \___|  \___| | .__/   \___| |_|
2022-10-11 02:43:20,843 [myid:1] - INFO  [main:ZookeeperBanner@42] -                                               | |                     
2022-10-11 02:43:20,843 [myid:1] - INFO  [main:ZookeeperBanner@42] -                                               |_|                     
2022-10-11 02:43:20,844 [myid:1] - INFO  [main:ZookeeperBanner@42] - 
2022-10-11 02:43:20,855 [myid:1] - INFO  [main:Environment@98] - Server environment:zookeeper.version=3.7.1-a2fb57c55f8e59cdd76c34b357ad5181df1258d5, built on 2022-05-07 06:45 UTC
2022-10-11 02:43:20,856 [myid:1] - INFO  [main:Environment@98] - Server environment:host.name=8664b0eff951
2022-10-11 02:43:20,856 [myid:1] - INFO  [main:Environment@98] - Server environment:java.version=11.0.15.1
2022-10-11 02:43:20,856 [myid:1] - INFO  [main:Environment@98] - Server environment:java.vendor=BellSoft
2022-10-11 02:43:20,857 [myid:1] - INFO  [main:Environment@98] - Server environment:java.home=/opt/bitnami/java
2022-10-11 02:43:20,857 [myid:1] - INFO  [main:Environment@98] - Server environment:java.class.path=/opt/bitnami/zookeeper/bin/../zookeeper-server/target/classes:/opt/bitnami/zookeeper/bin/../build/classes:/opt/bitnami/zookeeper/bin/../zookeeper-server/target/lib/*.jar:/opt/bitnami/zookeeper/bin/../build/lib/*.jar:/opt/bitnami/zookeeper/bin/../lib/zookeeper-prometheus-metrics-3.7.1.jar:/opt/bitnami/zookeeper/bin/../lib/zookeeper-jute-3.7.1.jar:/opt/bitnami/zookeeper/bin/../lib/zookeeper-3.7.1.jar:/opt/bitnami/zookeeper/bin/../lib/snappy-java-1.1.7.7.jar:/opt/bitnami/zookeeper/bin/../lib/slf4j-reload4j-1.7.35.jar:/opt/bitnami/zookeeper/bin/../lib/slf4j-api-1.7.35.jar:/opt/bitnami/zookeeper/bin/../lib/simpleclient_servlet-0.9.0.jar:/opt/bitnami/zookeeper/bin/../lib/simpleclient_hotspot-0.9.0.jar:/opt/bitnami/zookeeper/bin/../lib/simpleclient_common-0.9.0.jar:/opt/bitnami/zookeeper/bin/../lib/simpleclient-0.9.0.jar:/opt/bitnami/zookeeper/bin/../lib/reload4j-1.2.19.jar:/opt/bitnami/zookeeper/bin/../lib/netty-transport-native-unix-common-4.1.76.Final.jar:/opt/bitnami/zookeeper/bin/../lib/netty-transport-native-epoll-4.1.76.Final.jar:/opt/bitnami/zookeeper/bin/../lib/netty-transport-classes-epoll-4.1.76.Final.jar:/opt/bitnami/zookeeper/bin/../lib/netty-transport-4.1.76.Final.jar:/opt/bitnami/zookeeper/bin/../lib/netty-resolver-4.1.76.Final.jar:/opt/bitnami/zookeeper/bin/../lib/netty-handler-4.1.76.Final.jar:/opt/bitnami/zookeeper/bin/../lib/netty-common-4.1.76.Final.jar:/opt/bitnami/zookeeper/bin/../lib/netty-codec-4.1.76.Final.jar:/opt/bitnami/zookeeper/bin/../lib/netty-buffer-4.1.76.Final.jar:/opt/bitnami/zookeeper/bin/../lib/metrics-core-4.1.12.1.jar:/opt/bitnami/zookeeper/bin/../lib/jline-2.14.6.jar:/opt/bitnami/zookeeper/bin/../lib/jetty-util-ajax-9.4.43.v20210629.jar:/opt/bitnami/zookeeper/bin/../lib/jetty-util-9.4.43.v20210629.jar:/opt/bitnami/zookeeper/bin/../lib/jetty-servlet-9.4.43.v20210629.jar:/opt/bitnami/zookeeper/bin/../lib/jetty-server-9.4.43.v20210629.jar:/opt/bitnami/zookeeper/bin/../lib/jetty-security-9.4.43.v20210629.jar:/opt/bitnami/zookeeper/bin/../lib/jetty-io-9.4.43.v20210629.jar:/opt/bitnami/zookeeper/bin/../lib/jetty-http-9.4.43.v20210629.jar:/opt/bitnami/zookeeper/bin/../lib/javax.servlet-api-3.1.0.jar:/opt/bitnami/zookeeper/bin/../lib/jackson-databind-2.13.2.1.jar:/opt/bitnami/zookeeper/bin/../lib/jackson-core-2.13.2.jar:/opt/bitnami/zookeeper/bin/../lib/jackson-annotations-2.13.2.jar:/opt/bitnami/zookeeper/bin/../lib/commons-cli-1.4.jar:/opt/bitnami/zookeeper/bin/../lib/audience-annotations-0.12.0.jar:/opt/bitnami/zookeeper/bin/../zookeeper-*.jar:/opt/bitnami/zookeeper/bin/../zookeeper-server/src/main/resources/lib/*.jar:/opt/bitnami/zookeeper/bin/../conf:
2022-10-11 02:43:20,858 [myid:1] - INFO  [main:Environment@98] - Server environment:java.library.path=/usr/java/packages/lib:/usr/lib64:/lib64:/lib:/usr/lib
2022-10-11 02:43:20,859 [myid:1] - INFO  [main:Environment@98] - Server environment:java.io.tmpdir=/tmp
2022-10-11 02:43:20,859 [myid:1] - INFO  [main:Environment@98] - Server environment:java.compiler=<NA>
2022-10-11 02:43:20,859 [myid:1] - INFO  [main:Environment@98] - Server environment:os.name=Linux
2022-10-11 02:43:20,859 [myid:1] - INFO  [main:Environment@98] - Server environment:os.arch=amd64
2022-10-11 02:43:20,860 [myid:1] - INFO  [main:Environment@98] - Server environment:os.version=5.10.104-linuxkit
2022-10-11 02:43:20,860 [myid:1] - INFO  [main:Environment@98] - Server environment:user.name=?
2022-10-11 02:43:20,860 [myid:1] - INFO  [main:Environment@98] - Server environment:user.home=?
2022-10-11 02:43:20,861 [myid:1] - INFO  [main:Environment@98] - Server environment:user.dir=/
2022-10-11 02:43:20,861 [myid:1] - INFO  [main:Environment@98] - Server environment:os.memory.free=1009MB
2022-10-11 02:43:20,861 [myid:1] - INFO  [main:Environment@98] - Server environment:os.memory.max=1024MB
2022-10-11 02:43:20,861 [myid:1] - INFO  [main:Environment@98] - Server environment:os.memory.total=1024MB
2022-10-11 02:43:20,863 [myid:1] - INFO  [main:ZooKeeperServer@138] - zookeeper.enableEagerACLCheck = false
2022-10-11 02:43:20,864 [myid:1] - INFO  [main:ZooKeeperServer@151] - zookeeper.digest.enabled = true
2022-10-11 02:43:20,865 [myid:1] - INFO  [main:ZooKeeperServer@155] - zookeeper.closeSessionTxn.enabled = true
2022-10-11 02:43:20,866 [myid:1] - INFO  [main:ZooKeeperServer@1505] - zookeeper.flushDelay=0
2022-10-11 02:43:20,866 [myid:1] - INFO  [main:ZooKeeperServer@1514] - zookeeper.maxWriteQueuePollTime=0
2022-10-11 02:43:20,867 [myid:1] - INFO  [main:ZooKeeperServer@1523] - zookeeper.maxBatchSize=1000
2022-10-11 02:43:20,867 [myid:1] - INFO  [main:ZooKeeperServer@260] - zookeeper.intBufferStartingSizeBytes = 1024
2022-10-11 02:43:20,874 [myid:1] - INFO  [main:BlueThrottle@141] - Weighed connection throttling is disabled
2022-10-11 02:43:20,880 [myid:1] - INFO  [main:ZooKeeperServer@1306] - minSessionTimeout set to 4000
2022-10-11 02:43:20,881 [myid:1] - INFO  [main:ZooKeeperServer@1315] - maxSessionTimeout set to 40000
2022-10-11 02:43:20,884 [myid:1] - INFO  [main:ResponseCache@45] - getData response cache size is initialized with value 400.
2022-10-11 02:43:20,885 [myid:1] - INFO  [main:ResponseCache@45] - getChildren response cache size is initialized with value 400.
2022-10-11 02:43:20,889 [myid:1] - INFO  [main:RequestPathMetricsCollector@109] - zookeeper.pathStats.slotCapacity = 60
2022-10-11 02:43:20,890 [myid:1] - INFO  [main:RequestPathMetricsCollector@110] - zookeeper.pathStats.slotDuration = 15
2022-10-11 02:43:20,891 [myid:1] - INFO  [main:RequestPathMetricsCollector@111] - zookeeper.pathStats.maxDepth = 6
2022-10-11 02:43:20,891 [myid:1] - INFO  [main:RequestPathMetricsCollector@112] - zookeeper.pathStats.initialDelay = 5
2022-10-11 02:43:20,892 [myid:1] - INFO  [main:RequestPathMetricsCollector@113] - zookeeper.pathStats.delay = 5
2022-10-11 02:43:20,892 [myid:1] - INFO  [main:RequestPathMetricsCollector@114] - zookeeper.pathStats.enabled = false
2022-10-11 02:43:20,910 [myid:1] - INFO  [main:ZooKeeperServer@1542] - The max bytes for all large requests are set to 104857600
2022-10-11 02:43:20,912 [myid:1] - INFO  [main:ZooKeeperServer@1556] - The large request threshold is set to -1
2022-10-11 02:43:20,916 [myid:1] - INFO  [main:AuthenticationHelper@66] - zookeeper.enforce.auth.enabled = false
2022-10-11 02:43:20,917 [myid:1] - INFO  [main:AuthenticationHelper@67] - zookeeper.enforce.auth.schemes = []
2022-10-11 02:43:20,917 [myid:1] - INFO  [main:ZooKeeperServer@361] - Created server with tickTime 2000 minSessionTimeout 4000 maxSessionTimeout 40000 clientPortListenBacklog -1 datadir /bitnami/zookeeper/data/version-2 snapdir /bitnami/zookeeper/data/version-2
2022-10-11 02:43:21,054 [myid:1] - INFO  [main:Log@170] - Logging initialized @4604ms to org.eclipse.jetty.util.log.Slf4jLog
2022-10-11 02:43:21,562 [myid:1] - WARN  [main:ContextHandler@1656] - o.e.j.s.ServletContextHandler@5745ca0e{/,null,STOPPED} contextPath ends with /*
2022-10-11 02:43:21,562 [myid:1] - WARN  [main:ContextHandler@1667] - Empty contextPath
2022-10-11 02:43:21,699 [myid:1] - INFO  [main:Server@375] - jetty-9.4.43.v20210629; built: 2021-06-30T11:07:22.254Z; git: 526006ecfa3af7f1a27ef3a288e2bef7ea9dd7e8; jvm 11.0.15.1+2-LTS
2022-10-11 02:43:21,899 [myid:1] - INFO  [main:DefaultSessionIdManager@334] - DefaultSessionIdManager workerName=node0
2022-10-11 02:43:21,899 [myid:1] - INFO  [main:DefaultSessionIdManager@339] - No SessionScavenger set, using defaults
2022-10-11 02:43:21,913 [myid:1] - INFO  [main:HouseKeeper@132] - node0 Scavenging every 660000ms
2022-10-11 02:43:21,933 [myid:1] - WARN  [main:ConstraintSecurityHandler@759] - ServletContext@o.e.j.s.ServletContextHandler@5745ca0e{/,null,STARTING} has uncovered http methods for path: /*
2022-10-11 02:43:21,985 [myid:1] - INFO  [main:ContextHandler@915] - Started o.e.j.s.ServletContextHandler@5745ca0e{/,null,AVAILABLE}
2022-10-11 02:43:22,084 [myid:1] - INFO  [main:AbstractConnector@331] - Started ServerConnector@1cf6d1be{HTTP/1.1, (http/1.1)}{0.0.0.0:8080}
2022-10-11 02:43:22,086 [myid:1] - INFO  [main:Server@415] - Started @5637ms
2022-10-11 02:43:22,086 [myid:1] - INFO  [main:JettyAdminServer@190] - Started AdminServer on address 0.0.0.0, port 8080 and command URL /commands
2022-10-11 02:43:22,112 [myid:1] - INFO  [main:ServerCnxnFactory@169] - Using org.apache.zookeeper.server.NIOServerCnxnFactory as server connection factory
2022-10-11 02:43:22,117 [myid:1] - WARN  [main:ServerCnxnFactory@309] - maxCnxns is not configured, using default value 0.
2022-10-11 02:43:22,125 [myid:1] - INFO  [main:NIOServerCnxnFactory@652] - Configuring NIO connection handler with 10s sessionless connection timeout, 2 selector thread(s), 16 worker threads, and 64 kB direct buffers.
2022-10-11 02:43:22,131 [myid:1] - INFO  [main:NIOServerCnxnFactory@660] - binding to port 0.0.0.0/0.0.0.0:2181
2022-10-11 02:43:22,236 [myid:1] - INFO  [main:WatchManagerFactory@42] - Using org.apache.zookeeper.server.watch.WatchManager as watch manager
2022-10-11 02:43:22,237 [myid:1] - INFO  [main:WatchManagerFactory@42] - Using org.apache.zookeeper.server.watch.WatchManager as watch manager
2022-10-11 02:43:22,246 [myid:1] - INFO  [main:ZKDatabase@133] - zookeeper.snapshotSizeFactor = 0.33
2022-10-11 02:43:22,247 [myid:1] - INFO  [main:ZKDatabase@153] - zookeeper.commitLogCount=500
2022-10-11 02:43:22,288 [myid:1] - INFO  [main:SnapStream@61] - zookeeper.snapshot.compression.method = CHECKED
2022-10-11 02:43:22,294 [myid:1] - INFO  [main:FileSnap@85] - Reading snapshot /bitnami/zookeeper/data/version-2/snapshot.0
2022-10-11 02:43:22,313 [myid:1] - INFO  [main:DataTree@1716] - The digest value is empty in snapshot
2022-10-11 02:43:22,525 [myid:1] - INFO  [main:FileTxnSnapLog@372] - 152 txns loaded in 184 ms
2022-10-11 02:43:22,527 [myid:1] - INFO  [main:ZKDatabase@290] - Snapshot loaded in 279 ms, highest zxid is 0x98, digest is 309770985247
2022-10-11 02:43:22,540 [myid:1] - INFO  [main:FileTxnSnapLog@479] - Snapshotting: 0x98 to /bitnami/zookeeper/data/version-2/snapshot.98
2022-10-11 02:43:22,588 [myid:1] - INFO  [main:ZooKeeperServer@543] - Snapshot taken in 48 ms
2022-10-11 02:43:22,640 [myid:1] - INFO  [ProcessThread(sid:0 cport:2181)::PrepRequestProcessor@137] - PrepRequestProcessor (sid:0) started, reconfigEnabled=false
2022-10-11 02:43:22,644 [myid:1] - INFO  [main:RequestThrottler@75] - zookeeper.request_throttler.shutdownTimeout = 10000
2022-10-11 02:43:22,756 [myid:1] - INFO  [main:ContainerManager@84] - Using checkIntervalMs=60000 maxPerMinute=10000 maxNeverUsedIntervalMs=0
2022-10-11 02:43:22,763 [myid:1] - INFO  [main:ZKAuditProvider@42] - ZooKeeper audit is disabled.
2022-10-11 02:43:33,079 [myid:1] - INFO  [SyncThread:0:FileTxnLog@284] - Creating new log file: log.99
2022-10-11 02:43:42,057 [myid:1] - INFO  [SessionTracker:ZooKeeperServer@628] - Expiring session 0x10002aff7350000, timeout of 18000ms exceeded
2022-10-11 02:44:02,057 [myid:1] - INFO  [SessionTracker:ZooKeeperServer@628] - Expiring session 0x10000005fdc0000, timeout of 18000ms exceeded

However when debugging this time Kafka is not accepting connections; ZK seems fine:

curl -v -m 5 localhost:9092
*   Trying 127.0.0.1:9092...
* Connected to localhost (127.0.0.1) port 9092 (#0)
> GET / HTTP/1.1
> Host: localhost:9092
> User-Agent: curl/7.79.1
> Accept: */*
> 
* Operation timed out after 5004 milliseconds with 0 bytes received
* Closing connection 0
curl: (28) Operation timed out after 5004 milliseconds with 0 bytes received

A docker restart kafka fixed the issue.

I'm running a bog standard Mac Pro (M1) with Docker desktop; there are no special networks configs present. As such I'm not quite happy with a statement that implies my network has somehow issues - running dockerized images on MacOS should be within the scope of all involved pieces.

Mauraza commented 1 year ago

There are a lot of issues with M1, https://github.com/docker/for-mac/issues/5736, and https://github.com/confluentinc/kafka-images/issues/80... Did you check these issues?

carrodher commented 1 year ago

Hi, we have created this issue: https://github.com/bitnami/charts/issues/7305, which is pinned in the Bitnami Helm Charts repository, on this way, we can funnel all the conversations in a single place regarding ARM64 support.

We will close the rest of the existing issues just to avoid duplications, please visit the issue mentioned above to see any news (when possible) about this topic.

carrodher commented 1 year ago

Hi all, we are more than happy to announce that from now on the Bitnami container catalog is available as multi-arch in Docker Hub 🎉 🎉

Screenshot 2023-02-24 at 08 16 50

This means you don’t need to specify anything when pulling the container images from Docker Hub, Docker (or any other software) will automatically pull the container image matching the host platform from which the pull command was issued.

Here you can find more info about this announcement.


As usual, please create a new GitHub issue if you would like to report any bug or problem or directly contribute by creating a PR. Here you can find the contributing guidelines.