archmangler commented on issue #15045:
URL: https://github.com/apache/pulsar/issues/15045#issuecomment-1090337911

   Thanks @tjiuming for your response. On the broker side I see this:
   
   ```
   pulsar-broker-0 pulsar-broker 2022-04-06T22:26:05.869117680+08:00 
14:26:05.868 [pulsar-io-24-2] INFO  org.apache.pulsar.broker.service.ServerCnx 
- New connection from /192.168.4.54:36310
   pulsar-broker-2 pulsar-broker 2022-04-06T22:26:05.876304494+08:00 
14:26:05.876 [pulsar-io-24-1] INFO  org.apache.pulsar.broker.service.ServerCnx 
- New connection from /192.168.4.54:48210
   pulsar-broker-2 pulsar-broker 2022-04-06T22:26:05.876877877+08:00 
14:26:05.876 [pulsar-io-24-1] INFO  org.apache.pulsar.broker.service.ServerCnx 
- [/192.168.4.54:48210] Subscribing on topic 
persistent://ragnarok/transactions/requests / reader-d6ddeb848f
   pulsar-broker-2 pulsar-broker 2022-04-06T22:26:05.877030498+08:00 
14:26:05.876 [pulsar-io-24-1] INFO  
org.apache.pulsar.broker.service.persistent.PersistentTopic - 
[persistent://ragnarok/transactions/requests][reader-d6ddeb848f] Creating 
non-durable subscription at msg id -1:-1:-1:-1
   pulsar-broker-2 pulsar-broker 2022-04-06T22:26:05.877233753+08:00 
14:26:05.877 [pulsar-io-24-1] INFO  
org.apache.bookkeeper.mledger.impl.NonDurableCursorImpl - 
[ragnarok/transactions/persistent/requests] Created non-durable cursor 
read-position=162:0 mark-delete-position=162:-1
   pulsar-broker-2 pulsar-broker 2022-04-06T22:26:05.877311049+08:00 
14:26:05.877 [pulsar-io-24-1] INFO  
org.apache.bookkeeper.mledger.impl.ManagedLedgerImpl - 
[ragnarok/transactions/persistent/requests] Opened new cursor: 
NonDurableCursorImpl{ledger=ragnarok/transactions/persistent/requests, 
ackPos=162:-1, readPos=162:0}
   pulsar-broker-2 pulsar-broker 2022-04-06T22:26:05.877443482+08:00 
14:26:05.877 [pulsar-io-24-1] INFO  
org.apache.bookkeeper.mledger.impl.ManagedCursorImpl - 
[ragnarok/transactions/persistent/requests-reader-d6ddeb848f] Rewind from 162:0 
to 162:0
   pulsar-broker-2 pulsar-broker 2022-04-06T22:26:05.877834039+08:00 
14:26:05.877 [pulsar-io-24-1] ERROR 
org.apache.pulsar.broker.service.persistent.PersistentTopic - 
[persistent://ragnarok/transactions/requests] Failed to create subscription: 
reader-d6ddeb848f error: java.util.concurrent.CompletionException: 
java.lang.NullPointerException
   pulsar-broker-2 pulsar-broker 2022-04-06T22:26:05.877848619+08:00 
14:26:05.877 [pulsar-io-24-1] WARN  org.apache.pulsar.broker.service.ServerCnx 
- 
[/192.168.4.54:48210][persistent://ragnarok/transactions/requests][reader-d6ddeb848f]
 Failed to create consumer: consumerId=0, 
java.util.concurrent.CompletionException: java.lang.NullPointerException
   pulsar-broker-2 pulsar-broker 2022-04-06T22:26:05.878862443+08:00 
14:26:05.878 [pulsar-io-24-1] INFO  org.apache.pulsar.broker.service.ServerCnx 
- Closed connection from /192.168.4.54:48210
   pulsar-broker-0 pulsar-broker 2022-04-06T22:26:05.879334129+08:00 
14:26:05.879 [pulsar-io-24-2] INFO  org.apache.pulsar.broker.service.ServerCnx 
- Closed connection from /192.168.4.54:36310
   pulsar-broker-0 pulsar-broker 2022-04-06T22:26:07.338668498+08:00 
14:26:07.338 [pulsar-load-manager-2-1] INFO  
org.apache.pulsar.broker.loadbalance.impl.ModularLoadManagerImpl - Writing 
local data to ZooKeeper because maximum change 11.808785796165466% exceeded 
threshold 10%; time since last report written is 180.0 seconds
   pulsar-broker-0 pulsar-broker 2022-04-06T22:26:07.348366859+08:00 
14:26:07.348 [pulsar-ordered-OrderedExecutor-6-0-EventThread] INFO  
org.apache.pulsar.zookeeper.ZooKeeperDataCache - [State:CONNECTED Timeout:30000 
sessionid:0x200003ccebc0097 local:/192.168.2.72:52102 
remoteserver:pulsar-zookeeper/192.168.5.171:2181 lastZxid:4294985310 xid:777 
sent:777 recv:843 queuedpkts:0 pendingresp:0 queuedevents:0] Received ZooKeeper 
watch event: WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/loadbalance/brokers/pulsar-broker-0.pulsar-broker.pulsar.svc.cluster.local:8080
   pulsar-broker-2 pulsar-broker 2022-04-06T22:26:07.349049969+08:00 
14:26:07.348 [pulsar-ordered-OrderedExecutor-6-0-EventThread] INFO  
org.apache.pulsar.zookeeper.ZooKeeperDataCache - [State:CONNECTED Timeout:30000 
sessionid:0x100003ceea30079 local:/192.168.5.36:33404 
remoteserver:pulsar-zookeeper/192.168.2.172:2181 lastZxid:4294985309 xid:1077 
sent:1077 recv:1163 queuedpkts:0 pendingresp:0 queuedevents:0] Received 
ZooKeeper watch event: WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/loadbalance/brokers/pulsar-broker-0.pulsar-broker.pulsar.svc.cluster.local:8080
   pulsar-broker-1 pulsar-broker 2022-04-06T22:26:07.348748508+08:00 
14:26:07.348 [pulsar-ordered-OrderedExecutor-4-0-EventThread] INFO  
org.apache.pulsar.zookeeper.ZooKeeperDataCache - [State:CONNECTED Timeout:30000 
sessionid:0x300003cf05d007d local:/192.168.6.225:50406 
remoteserver:pulsar-zookeeper/192.168.7.44:2181 lastZxid:4294985309 xid:822 
sent:822 recv:900 queuedpkts:0 pendingresp:0 queuedevents:0] Received ZooKeeper 
watch event: WatchedEvent state:SyncConnected type:NodeDataChanged 
path:/loadbalance/brokers/pulsar-broker-0.pulsar-broker.pulsar.svc.cluster.local:8080
   pulsar-broker-2 pulsar-broker 2022-04-06T22:26:09.340012935+08:00 
14:26:09.336 [pulsar-web-42-6] INFO  org.eclipse.jetty.server.RequestLog - 
192.168.4.44 - - [06/Apr/2022:14:26:09 +0000] "GET /status.html HTTP/1.1" 200 2 
"-" "kube-probe/1.21+" 1
   pulsar-broker-2 pulsar-broker 2022-04-06T22:26:09.340058219+08:00 
14:26:09.336 [pulsar-web-42-8] INFO  org.eclipse.jetty.server.RequestLog - 
192.168.4.44 - - [06/Apr/2022:14:26:09 +0000] "GET /status.html HTTP/1.1" 200 2 
"-" "kube-probe/1.21+" 1
   ```


-- 
This is an automated message from the Apache Git Service.
To respond to the message, please log on to GitHub and use the
URL above to go to the specific comment.

To unsubscribe, e-mail: [email protected]

For queries about this service, please contact Infrastructure at:
[email protected]

Reply via email to