Uploaded image for project: 'VOLTHA'
  1. VOLTHA
  2. VOL-401

Voltha not able to send events/alarms to kafka in clustered mode

    XMLWordPrintable

    Details

    • Type: Bug
    • Status: Resolved (View Workflow)
    • Priority: Medium
    • Resolution: Cannot Reproduce
    • Affects Version/s: None
    • Fix Version/s: None
    • Component/s: Deprecated - vOLTHA HA
    • Labels:
      None

      Description

      Error seen in voltha:

      Unhandled error in Deferred:
      20170827T230836.581 ERROR MainThread kafka_proxy.send_message {topic: voltha.kpis, msg: {"prefixes": {"voltha.internal.vcore_vcore.1.t3s48j3taokur069v55d6c9gw": {"metrics":

      {"deferreds": 62, "rss-mb": 108}

      }}, "type": "slice", "ts": 1503875306}, e: Unable to load metadata from configured hosts: <twisted.python.failure.Failure afkak.common.KafkaUnavailableError: All servers [[('a95ce937fe10', 9092)]] failed to process request>, event: failed-to-send-kafka-msg, instance_id: vcore_vcore.1.t3s48j3taokur069v55d6c9gw}

      Logs of kafka:

      waiting for kafka to be ready
      [2017-08-27 23:10:17,122] INFO KafkaConfig values:
      advertised.host.name = null
      advertised.listeners = PLAINTEXT://:9092
      advertised.port = null
      alter.config.policy.class.name = null
      authorizer.class.name =
      auto.create.topics.enable = true
      auto.leader.rebalance.enable = true
      background.threads = 10
      broker.id = -1
      broker.id.generation.enable = true
      broker.rack = null
      compression.type = producer
      connections.max.idle.ms = 600000
      controlled.shutdown.enable = true
      controlled.shutdown.max.retries = 3
      controlled.shutdown.retry.backoff.ms = 5000
      controller.socket.timeout.ms = 30000
      create.topic.policy.class.name = null
      default.replication.factor = 1
      delete.records.purgatory.purge.interval.requests = 1
      delete.topic.enable = false
      fetch.purgatory.purge.interval.requests = 1000
      group.initial.rebalance.delay.ms = 0
      group.max.session.timeout.ms = 300000
      group.min.session.timeout.ms = 6000
      host.name =
      inter.broker.listener.name = null
      inter.broker.protocol.version = 0.11.0-IV2
      leader.imbalance.check.interval.seconds = 300
      leader.imbalance.per.broker.percentage = 10
      listener.security.protocol.map = SSL:SSL,SASL_PLAINTEXT:SASL_PLAINTEXT,TRACE:TRACE,SASL_SSL:SASL_SSL,PLAINTEXT:PLAINTEXT
      listeners = PLAINTEXT://:9092
      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.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 = /kafka/kafka-logs-303cb76bb32d
      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.format.version = 0.11.0-IV2
      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.connections.per.ip = 2147483647
      max.connections.per.ip.overrides =
      message.max.bytes = 1000012
      metric.reporters = []
      metrics.num.samples = 2
      metrics.recording.level = INFO
      metrics.sample.window.ms = 30000
      min.insync.replicas = 1
      num.io.threads = 8
      num.network.threads = 3
      num.partitions = 1
      num.recovery.threads.per.data.dir = 1
      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 = 1440
      offsets.topic.compression.codec = 0
      offsets.topic.num.partitions = 50
      offsets.topic.replication.factor = 1
      offsets.topic.segment.bytes = 104857600
      port = 9092
      principal.builder.class = class org.apache.kafka.common.security.auth.DefaultPrincipalBuilder
      producer.purgatory.purge.interval.requests = 1000
      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 = 10000
      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.enabled.mechanisms = [GSSAPI]
      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.mechanism.inter.broker.protocol = GSSAPI
      security.inter.broker.protocol = PLAINTEXT
      socket.receive.buffer.bytes = 102400
      socket.request.max.bytes = 104857600
      socket.send.buffer.bytes = 102400
      ssl.cipher.suites = null
      ssl.client.auth = none
      ssl.enabled.protocols = [TLSv1.2, TLSv1.1, TLSv1]
      ssl.endpoint.identification.algorithm = null
      ssl.key.password = null
      ssl.keymanager.algorithm = SunX509
      ssl.keystore.location = null
      ssl.keystore.password = null
      ssl.keystore.type = JKS
      ssl.protocol = TLS
      ssl.provider = null
      ssl.secure.random.implementation = null
      ssl.trustmanager.algorithm = PKIX
      ssl.truststore.location = null
      ssl.truststore.password = null
      ssl.truststore.type = JKS
      transaction.abort.timed.out.transaction.cleanup.interval.ms = 60000
      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.connect = zk1:2181,zk2:2181,zk3:2181
      zookeeper.connection.timeout.ms = 6000
      zookeeper.session.timeout.ms = 6000
      zookeeper.set.acl = false
      zookeeper.sync.time.ms = 2000
      (kafka.server.KafkaConfig)
      [2017-08-27 23:10:17,203] INFO starting (kafka.server.KafkaServer)
      [2017-08-27 23:10:17,205] INFO Connecting to zookeeper on zk1:2181,zk2:2181,zk3:2181 (kafka.server.KafkaServer)
      [2017-08-27 23:10:17,218] INFO Starting ZkClient event thread. (org.I0Itec.zkclient.ZkEventThread)
      [2017-08-27 23:10:17,225] INFO Client environment:zookeeper.version=3.4.10-39d3a4f269333c922ed3db283be479f9deacaa0f, built on 03/23/2017 10:13 GMT (org.apache.zookeeper.ZooKeeper)
      [2017-08-27 23:10:17,225] INFO Client environment:host.name=303cb76bb32d (org.apache.zookeeper.ZooKeeper)
      [2017-08-27 23:10:17,225] INFO Client environment:java.version=1.8.0_131 (org.apache.zookeeper.ZooKeeper)
      [2017-08-27 23:10:17,226] INFO Client environment:java.vendor=Oracle Corporation (org.apache.zookeeper.ZooKeeper)
      [2017-08-27 23:10:17,226] INFO Client environment:java.home=/opt/jdk1.8.0_131/jre (org.apache.zookeeper.ZooKeeper)
      [2017-08-27 23:10:17,226] INFO Client environment:java.class.path=:/opt/kafka/bin/../libs/aopalliance-repackaged-2.5.0-b05.jar:/opt/kafka/bin/../libs/argparse4j-0.7.0.jar:/opt/kafka/bin/../libs/commons-lang3-3.5.jar:/opt/kafka/bin/../libs/connect-api-0.11.0.0.jar:/opt/kafka/bin/../libs/connect-file-0.11.0.0.jar:/opt/kafka/bin/../libs/connect-json-0.11.0.0.jar:/opt/kafka/bin/../libs/connect-runtime-0.11.0.0.jar:/opt/kafka/bin/../libs/connect-transforms-0.11.0.0.jar:/opt/kafka/bin/../libs/guava-20.0.jar:/opt/kafka/bin/../libs/hk2-api-2.5.0-b05.jar:/opt/kafka/bin/../libs/hk2-locator-2.5.0-b05.jar:/opt/kafka/bin/../libs/hk2-utils-2.5.0-b05.jar:/opt/kafka/bin/../libs/jackson-annotations-2.8.5.jar:/opt/kafka/bin/../libs/jackson-core-2.8.5.jar:/opt/kafka/bin/../libs/jackson-databind-2.8.5.jar:/opt/kafka/bin/../libs/jackson-jaxrs-base-2.8.5.jar:/opt/kafka/bin/../libs/jackson-jaxrs-json-provider-2.8.5.jar:/opt/kafka/bin/../libs/jackson-module-jaxb-annotations-2.8.5.jar:/opt/kafka/bin/../libs/javassist-3.21.0-GA.jar:/opt/kafka/bin/../libs/javax.annotation-api-1.2.jar:/opt/kafka/bin/../libs/javax.inject-1.jar:/opt/kafka/bin/../libs/javax.inject-2.5.0-b05.jar:/opt/kafka/bin/../libs/javax.servlet-api-3.1.0.jar:/opt/kafka/bin/../libs/javax.ws.rs-api-2.0.1.jar:/opt/kafka/bin/../libs/jersey-client-2.24.jar:/opt/kafka/bin/../libs/jersey-common-2.24.jar:/opt/kafka/bin/../libs/jersey-container-servlet-2.24.jar:/opt/kafka/bin/../libs/jersey-container-servlet-core-2.24.jar:/opt/kafka/bin/../libs/jersey-guava-2.24.jar:/opt/kafka/bin/../libs/jersey-media-jaxb-2.24.jar:/opt/kafka/bin/../libs/jersey-server-2.24.jar:/opt/kafka/bin/../libs/jetty-continuation-9.2.15.v20160210.jar:/opt/kafka/bin/../libs/jetty-http-9.2.15.v20160210.jar:/opt/kafka/bin/../libs/jetty-io-9.2.15.v20160210.jar:/opt/kafka/bin/../libs/jetty-security-9.2.15.v20160210.jar:/opt/kafka/bin/../libs/jetty-server-9.2.15.v20160210.jar:/opt/kafka/bin/../libs/jetty-servlet-9.2.15.v20160210.jar:/opt/kafka/bin/../libs/jetty-servlets-9.2.15.v20160210.jar:/opt/kafka/bin/../libs/jetty-util-9.2.15.v20160210.jar:/opt/kafka/bin/../libs/jopt-simple-5.0.3.jar:/opt/kafka/bin/../libs/kafka-clients-0.11.0.0.jar:/opt/kafka/bin/../libs/kafka-log4j-appender-0.11.0.0.jar:/opt/kafka/bin/../libs/kafka-streams-0.11.0.0.jar:/opt/kafka/bin/../libs/kafka-streams-examples-0.11.0.0.jar:/opt/kafka/bin/../libs/kafka-tools-0.11.0.0.jar:/opt/kafka/bin/../libs/kafka_2.12-0.11.0.0-sources.jar:/opt/kafka/bin/../libs/kafka_2.12-0.11.0.0-test-sources.jar:/opt/kafka/bin/../libs/kafka_2.12-0.11.0.0.jar:/opt/kafka/bin/../libs/log4j-1.2.17.jar:/opt/kafka/bin/../libs/lz4-1.3.0.jar:/opt/kafka/bin/../libs/maven-artifact-3.5.0.jar:/opt/kafka/bin/../libs/metrics-core-2.2.0.jar:/opt/kafka/bin/../libs/osgi-resource-locator-1.0.1.jar:/opt/kafka/bin/../libs/plexus-utils-3.0.24.jar:/opt/kafka/bin/../libs/reflections-0.9.11.jar:/opt/kafka/bin/../libs/rocksdbjni-5.0.1.jar:/opt/kafka/bin/../libs/scala-library-2.12.2.jar:/opt/kafka/bin/../libs/scala-parser-combinators_2.12-1.0.4.jar:/opt/kafka/bin/../libs/slf4j-api-1.7.25.jar:/opt/kafka/bin/../libs/slf4j-log4j12-1.7.25.jar:/opt/kafka/bin/../libs/snappy-java-1.1.2.6.jar:/opt/kafka/bin/../libs/validation-api-1.1.0.Final.jar:/opt/kafka/bin/../libs/zkclient-0.10.jar:/opt/kafka/bin/../libs/zookeeper-3.4.10.jar (org.apache.zookeeper.ZooKeeper)
      [2017-08-27 23:10:17,226] INFO Client environment:java.library.path=/usr/java/packages/lib/amd64:/usr/lib64:/lib64:/lib:/usr/lib (org.apache.zookeeper.ZooKeeper)
      [2017-08-27 23:10:17,226] INFO Client environment:java.io.tmpdir=/tmp (org.apache.zookeeper.ZooKeeper)
      [2017-08-27 23:10:17,226] INFO Client environment:java.compiler=<NA> (org.apache.zookeeper.ZooKeeper)
      [2017-08-27 23:10:17,226] INFO Client environment:os.name=Linux (org.apache.zookeeper.ZooKeeper)
      [2017-08-27 23:10:17,226] INFO Client environment:os.arch=amd64 (org.apache.zookeeper.ZooKeeper)
      [2017-08-27 23:10:17,226] INFO Client environment:os.version=4.4.0-62-generic (org.apache.zookeeper.ZooKeeper)
      [2017-08-27 23:10:17,227] INFO Client environment:user.name=root (org.apache.zookeeper.ZooKeeper)
      [2017-08-27 23:10:17,227] INFO Client environment:user.home=/root (org.apache.zookeeper.ZooKeeper)
      [2017-08-27 23:10:17,227] INFO Client environment:user.dir=/ (org.apache.zookeeper.ZooKeeper)
      [2017-08-27 23:10:17,228] INFO Initiating client connection, connectString=zk1:2181,zk2:2181,zk3:2181 sessionTimeout=6000 watcher=org.I0Itec.zkclient.ZkClient@6d2a209c (org.apache.zookeeper.ZooKeeper)
      [2017-08-27 23:10:17,240] INFO Waiting for keeper state SyncConnected (org.I0Itec.zkclient.ZkClient)
      [2017-08-27 23:10:23,241] INFO Terminate ZkClient event thread. (org.I0Itec.zkclient.ZkEventThread)
      waiting for kafka to be ready
      [2017-08-27 23:10:27,255] INFO Opening socket connection to server 172.33.33.4/172.33.33.4:2181. Will not attempt to authenticate using SASL (unknown error) (org.apache.zookeeper.ClientCnxn)
      [2017-08-27 23:10:27,265] INFO Socket connection established to 172.33.33.4/172.33.33.4:2181, initiating session (org.apache.zookeeper.ClientCnxn)
      [2017-08-27 23:10:27,281] INFO Session establishment complete on server 172.33.33.4/172.33.33.4:2181, sessionid = 0x25e25f1fe0a0006, negotiated timeout = 6000 (org.apache.zookeeper.ClientCnxn)
      [2017-08-27 23:10:27,287] INFO Session: 0x25e25f1fe0a0006 closed (org.apache.zookeeper.ZooKeeper)
      [2017-08-27 23:10:27,289] FATAL Fatal error during KafkaServer startup. Prepare to shutdown (kafka.server.KafkaServer)
      org.I0Itec.zkclient.exception.ZkTimeoutException: Unable to connect to zookeeper server 'zk1:2181,zk2:2181,zk3:2181' with timeout of 6000 ms
      at org.I0Itec.zkclient.ZkClient.connect(ZkClient.java:1233)
      at org.I0Itec.zkclient.ZkClient.<init>(ZkClient.java:157)
      at org.I0Itec.zkclient.ZkClient.<init>(ZkClient.java:131)
      at kafka.utils.ZkUtils$.createZkClientAndConnection(ZkUtils.scala:103)
      at kafka.utils.ZkUtils$.apply(ZkUtils.scala:85)
      at kafka.server.KafkaServer.initZk(KafkaServer.scala:341)
      at kafka.server.KafkaServer.startup(KafkaServer.scala:191)
      at kafka.server.KafkaServerStartable.startup(KafkaServerStartable.scala:38)
      at kafka.Kafka$.main(Kafka.scala:65)
      at kafka.Kafka.main(Kafka.scala)
      [2017-08-27 23:10:27,290] INFO EventThread shut down for session: 0x25e25f1fe0a0006 (org.apache.zookeeper.ClientCnxn)
      [2017-08-27 23:10:27,294] INFO shutting down (kafka.server.KafkaServer)
      [2017-08-27 23:10:27,305] INFO shut down completed (kafka.server.KafkaServer)
      [2017-08-27 23:10:27,306] FATAL Exiting Kafka. (kafka.server.KafkaServerStartable)
      [2017-08-27 23:10:27,310] INFO shutting down (kafka.server.KafkaServer)

        Attachments

        No reviews matched the request. Check your Options in the drop-down menu of this sections header.

          Activity

            People

            Assignee:
            dbainbri David Bainbridge
            Reporter:
            sathishg Sathish G
            Watchers:
            9 Start watching this issue

              Dates

              Created:
              Updated:
              Resolved:

                Gerrit Reviews

                There are no open Gerrit changes