BewareMyPower commented on issue #13849:
URL: https://github.com/apache/pulsar/issues/13849#issuecomment-1078668057


   Another error.
   
   ```
   2022-03-25 13:24:26.779 INFO  [0x1011f4600] ProducerTest:167 | Start of run 0
   2022-03-25 13:24:26.814 INFO  [0x1011f4600] ClientConnection:189 | [<none> 
-> pulsar://localhost:6650] Create ClientConnection, timeout=10000
   2022-03-25 13:24:26.814 INFO  [0x1011f4600] ConnectionPool:96 | Created 
connection for pulsar://localhost:6650
   2022-03-25 13:24:26.815 INFO  [0x70000fcc7000] ClientConnection:375 | 
[127.0.0.1:55382 -> 127.0.0.1:6650] Connected to broker
   2022-03-25 13:24:26.817 INFO  [0x70000fcc7000] HandlerBase:64 | 
[persistent://public/default/testProducerIsConnectedPartitioned-16481858660-partition-0,
 ] Getting connection from pool
   2022-03-25 13:24:26.848 INFO  [0x70000fcc7000] ProducerImpl:188 | 
[persistent://public/default/testProducerIsConnectedPartitioned-16481858660-partition-0,
 ] Created producer on broker [127.0.0.1:55382 -> 127.0.0.1:6650] 
   2022-03-25 13:24:26.860 INFO  [0x70001090f000] ProducerTest:209 | Closing
   2022-03-25 13:24:26.860 INFO  [0x70001090f000] ProducerImpl:682 | 
[persistent://public/default/testProducerIsConnectedPartitioned-16481858660-partition-0,
 standalone-0-781] Closing producer for topic 
persistent://public/default/testProducerIsConnectedPartitioned-16481858660-partition-0
   2022-03-25 13:24:26.860 INFO  [0x70001090f000] ProducerTest:211 | Close 
called
   2022-03-25 13:24:26.861 INFO  [0x70000fcc7000] ProducerImpl:725 | 
[persistent://public/default/testProducerIsConnectedPartitioned-16481858660-partition-0,
 standalone-0-781] Closed producer
   2022-03-25 13:24:26.861 INFO  [0x70001090f000] ProducerTest:216 | Closed
   2022-03-25 13:24:26.861 INFO  [0x70001088c000] ProducerTest:199 | closedAt 
set to 10
   2022-03-25 13:24:26.977 INFO  [0x1011f4600] ClientImpl:496 | Closing Pulsar 
client with 1 producers and 0 consumers
   2022-03-25 13:24:26.977 INFO  [0x1011f4600] ClientConnection:1559 | 
[127.0.0.1:55382 -> 127.0.0.1:6650] Connection closed
   2022-03-25 13:24:26.977 INFO  [0x1011f4600] ClientConnection:263 | 
[127.0.0.1:55382 -> 127.0.0.1:6650] Destroyed connection
   2022-03-25 13:24:26.977 INFO  [0x1011f4600] ProducerTest:242 | End of run 0
   2022-03-25 13:24:26.977 INFO  [0x1011f4600] ProducerTest:167 | Start of run 1
   2022-03-25 13:24:27.008 INFO  [0x1011f4600] ClientConnection:189 | [<none> 
-> pulsar://localhost:6650] Create ClientConnection, timeout=10000
   2022-03-25 13:24:27.009 INFO  [0x1011f4600] ConnectionPool:96 | Created 
connection for pulsar://localhost:6650
   2022-03-25 13:24:27.010 INFO  [0x70000fcc7000] ClientConnection:375 | 
[127.0.0.1:55384 -> 127.0.0.1:6650] Connected to broker
   2022-03-25 13:24:27.011 INFO  [0x70000fcc7000] HandlerBase:64 | 
[persistent://public/default/testProducerIsConnectedPartitioned-16481858661-partition-4,
 ] Getting connection from pool
   2022-03-25 13:24:27.042 INFO  [0x70000fcc7000] ProducerImpl:188 | 
[persistent://public/default/testProducerIsConnectedPartitioned-16481858661-partition-4,
 ] Created producer on broker [127.0.0.1:55384 -> 127.0.0.1:6650] 
   2022-03-25 13:24:27.054 INFO  [0x700010992000] ProducerTest:209 | Closing
   2022-03-25 13:24:27.055 INFO  [0x700010992000] ProducerImpl:682 | 
[persistent://public/default/testProducerIsConnectedPartitioned-16481858661-partition-4,
 standalone-0-782] Closing producer for topic 
persistent://public/default/testProducerIsConnectedPartitioned-16481858661-partition-4
   2022-03-25 13:24:27.055 INFO  [0x700010992000] ProducerTest:211 | Close 
called
   2022-03-25 13:24:27.056 INFO  [0x70001090f000] ProducerTest:199 | closedAt 
set to 10
   2022-03-25 13:24:27.057 INFO  [0x70000fcc7000] ProducerImpl:725 | 
[persistent://public/default/testProducerIsConnectedPartitioned-16481858661-partition-4,
 standalone-0-782] Closed producer
   2022-03-25 13:24:27.057 INFO  [0x700010992000] ProducerTest:216 | Closed
   2022-03-25 13:24:27.172 INFO  [0x1011f4600] ClientImpl:496 | Closing Pulsar 
client with 1 producers and 0 consumers
   2022-03-25 13:24:27.172 INFO  [0x1011f4600] ClientConnection:1559 | 
[127.0.0.1:55384 -> 127.0.0.1:6650] Connection closed
   2022-03-25 13:24:27.172 INFO  [0x1011f4600] ClientConnection:263 | 
[127.0.0.1:55384 -> 127.0.0.1:6650] Destroyed connection
   2022-03-25 13:24:27.172 INFO  [0x1011f4600] ProducerTest:242 | End of run 1
   2022-03-25 13:24:27.172 INFO  [0x1011f4600] ProducerTest:167 | Start of run 2
   2022-03-25 13:24:27.204 INFO  [0x1011f4600] ClientConnection:189 | [<none> 
-> pulsar://localhost:6650] Create ClientConnection, timeout=10000
   2022-03-25 13:24:27.204 INFO  [0x1011f4600] ConnectionPool:96 | Created 
connection for pulsar://localhost:6650
   2022-03-25 13:24:27.205 INFO  [0x70000fcc7000] ClientConnection:375 | 
[127.0.0.1:55386 -> 127.0.0.1:6650] Connected to broker
   2022-03-25 13:24:27.208 INFO  [0x70000fcc7000] HandlerBase:64 | 
[persistent://public/default/testProducerIsConnectedPartitioned-16481858672-partition-3,
 ] Getting connection from pool
   2022-03-25 13:24:27.237 INFO  [0x70000fcc7000] ProducerImpl:188 | 
[persistent://public/default/testProducerIsConnectedPartitioned-16481858672-partition-3,
 ] Created producer on broker [127.0.0.1:55386 -> 127.0.0.1:6650] 
   2022-03-25 13:24:27.249 INFO  [0x700010a15000] ProducerTest:209 | Closing
   2022-03-25 13:24:27.250 INFO  [0x700010a15000] ProducerImpl:682 | 
[persistent://public/default/testProducerIsConnectedPartitioned-16481858672-partition-3,
 standalone-0-783] Closing producer for topic 
persistent://public/default/testProducerIsConnectedPartitioned-16481858672-partition-3
   2022-03-25 13:24:27.250 INFO  [0x700010a15000] ProducerTest:211 | Close 
called
   2022-03-25 13:24:27.251 INFO  [0x700010992000] ProducerTest:199 | closedAt 
set to 10
   2022-03-25 13:24:27.251 INFO  [0x70000fcc7000] ProducerImpl:725 | 
[persistent://public/default/testProducerIsConnectedPartitioned-16481858672-partition-3,
 standalone-0-783] Closed producer
   2022-03-25 13:24:27.251 INFO  [0x700010a15000] ProducerTest:216 | Closed
   2022-03-25 13:24:27.367 INFO  [0x1011f4600] ClientImpl:496 | Closing Pulsar 
client with 1 producers and 0 consumers
   2022-03-25 13:24:27.367 INFO  [0x1011f4600] ClientConnection:1559 | 
[127.0.0.1:55386 -> 127.0.0.1:6650] Connection closed
   2022-03-25 13:24:27.367 INFO  [0x1011f4600] ClientConnection:263 | 
[127.0.0.1:55386 -> 127.0.0.1:6650] Destroyed connection
   2022-03-25 13:24:27.367 INFO  [0x1011f4600] ProducerTest:242 | End of run 2
   2022-03-25 13:24:27.368 INFO  [0x1011f4600] ProducerTest:167 | Start of run 3
   2022-03-25 13:24:27.398 INFO  [0x1011f4600] ClientConnection:189 | [<none> 
-> pulsar://localhost:6650] Create ClientConnection, timeout=10000
   2022-03-25 13:24:27.398 INFO  [0x1011f4600] ConnectionPool:96 | Created 
connection for pulsar://localhost:6650
   2022-03-25 13:24:27.399 INFO  [0x70000fcc7000] ClientConnection:375 | 
[127.0.0.1:55388 -> 127.0.0.1:6650] Connected to broker
   2022-03-25 13:24:27.401 INFO  [0x70000fcc7000] HandlerBase:64 | 
[persistent://public/default/testProducerIsConnectedPartitioned-16481858673-partition-7,
 ] Getting connection from pool
   2022-03-25 13:24:27.432 INFO  [0x70000fcc7000] ProducerImpl:188 | 
[persistent://public/default/testProducerIsConnectedPartitioned-16481858673-partition-7,
 ] Created producer on broker [127.0.0.1:55388 -> 127.0.0.1:6650] 
   2022-03-25 13:24:27.444 INFO  [0x700010a98000] ProducerTest:209 | Closing
   2022-03-25 13:24:27.444 INFO  [0x700010a98000] ProducerImpl:682 | 
[persistent://public/default/testProducerIsConnectedPartitioned-16481858673-partition-7,
 standalone-0-784] Closing producer for topic 
persistent://public/default/testProducerIsConnectedPartitioned-16481858673-partition-7
   2022-03-25 13:24:27.444 INFO  [0x700010a98000] ProducerTest:211 | Close 
called
   2022-03-25 13:24:27.445 INFO  [0x70000fcc7000] ProducerImpl:725 | 
[persistent://public/default/testProducerIsConnectedPartitioned-16481858673-partition-7,
 standalone-0-784] Closed producer
   2022-03-25 13:24:27.445 INFO  [0x700010a98000] ProducerTest:216 | Closed
   2022-03-25 13:24:27.445 INFO  [0x700010a15000] ProducerTest:199 | closedAt 
set to 10
   2022-03-25 13:24:27.560 INFO  [0x1011f4600] ClientImpl:496 | Closing Pulsar 
client with 1 producers and 0 consumers
   2022-03-25 13:24:27.560 INFO  [0x1011f4600] ClientConnection:1559 | 
[127.0.0.1:55388 -> 127.0.0.1:6650] Connection closed
   2022-03-25 13:24:27.560 INFO  [0x1011f4600] ClientConnection:263 | 
[127.0.0.1:55388 -> 127.0.0.1:6650] Destroyed connection
   2022-03-25 13:24:27.560 INFO  [0x1011f4600] ProducerTest:242 | End of run 3
   2022-03-25 13:24:27.560 INFO  [0x1011f4600] ProducerTest:167 | Start of run 4
   2022-03-25 13:24:27.593 INFO  [0x1011f4600] ClientConnection:189 | [<none> 
-> pulsar://localhost:6650] Create ClientConnection, timeout=10000
   2022-03-25 13:24:27.594 INFO  [0x1011f4600] ConnectionPool:96 | Created 
connection for pulsar://localhost:6650
   2022-03-25 13:24:27.595 INFO  [0x70000fcc7000] ClientConnection:375 | 
[127.0.0.1:55393 -> 127.0.0.1:6650] Connected to broker
   2022-03-25 13:24:27.597 INFO  [0x70000fcc7000] HandlerBase:64 | 
[persistent://public/default/testProducerIsConnectedPartitioned-16481858674-partition-9,
 ] Getting connection from pool
   2022-03-25 13:24:27.628 INFO  [0x70000fcc7000] ProducerImpl:188 | 
[persistent://public/default/testProducerIsConnectedPartitioned-16481858674-partition-9,
 ] Created producer on broker [127.0.0.1:55393 -> 127.0.0.1:6650] 
   2022-03-25 13:24:27.640 INFO  [0x700010b1b000] ProducerTest:209 | Closing
   2022-03-25 13:24:27.641 INFO  [0x700010b1b000] ProducerImpl:682 | 
[persistent://public/default/testProducerIsConnectedPartitioned-16481858674-partition-9,
 standalone-0-785] Closing producer for topic 
persistent://public/default/testProducerIsConnectedPartitioned-16481858674-partition-9
   2022-03-25 13:24:27.641 INFO  [0x700010b1b000] ProducerTest:211 | Close 
called
   2022-03-25 13:24:27.642 INFO  [0x700010a98000] ProducerTest:199 | closedAt 
set to 10
   2022-03-25 13:24:27.643 INFO  [0x70000fcc7000] ProducerImpl:725 | 
[persistent://public/default/testProducerIsConnectedPartitioned-16481858674-partition-9,
 standalone-0-785] Closed producer
   2022-03-25 13:24:27.643 INFO  [0x700010b1b000] ProducerTest:216 | Closed
   2022-03-25 13:24:27.757 INFO  [0x1011f4600] ClientImpl:496 | Closing Pulsar 
client with 1 producers and 0 consumers
   2022-03-25 13:24:27.757 INFO  [0x1011f4600] ClientConnection:1559 | 
[127.0.0.1:55393 -> 127.0.0.1:6650] Connection closed
   2022-03-25 13:24:27.757 INFO  [0x1011f4600] ClientConnection:263 | 
[127.0.0.1:55393 -> 127.0.0.1:6650] Destroyed connection
   2022-03-25 13:24:27.757 INFO  [0x1011f4600] ProducerTest:242 | End of run 4
   2022-03-25 13:24:27.757 INFO  [0x1011f4600] ProducerTest:167 | Start of run 5
   2022-03-25 13:24:27.789 INFO  [0x1011f4600] ClientConnection:189 | [<none> 
-> pulsar://localhost:6650] Create ClientConnection, timeout=10000
   2022-03-25 13:24:27.789 INFO  [0x1011f4600] ConnectionPool:96 | Created 
connection for pulsar://localhost:6650
   2022-03-25 13:24:27.790 INFO  [0x70000fcc7000] ClientConnection:375 | 
[127.0.0.1:55395 -> 127.0.0.1:6650] Connected to broker
   2022-03-25 13:24:27.793 INFO  [0x70000fcc7000] HandlerBase:64 | 
[persistent://public/default/testProducerIsConnectedPartitioned-16481858675-partition-0,
 ] Getting connection from pool
   2022-03-25 13:24:27.822 INFO  [0x70000fcc7000] ProducerImpl:188 | 
[persistent://public/default/testProducerIsConnectedPartitioned-16481858675-partition-0,
 ] Created producer on broker [127.0.0.1:55395 -> 127.0.0.1:6650] 
   2022-03-25 13:24:27.835 INFO  [0x700010b9e000] ProducerTest:209 | Closing
   2022-03-25 13:24:27.835 INFO  [0x700010b9e000] ProducerImpl:682 | 
[persistent://public/default/testProducerIsConnectedPartitioned-16481858675-partition-0,
 standalone-0-786] Closing producer for topic 
persistent://public/default/testProducerIsConnectedPartitioned-16481858675-partition-0
   2022-03-25 13:24:27.835 INFO  [0x700010b9e000] ProducerTest:211 | Close 
called
   2022-03-25 13:24:27.836 INFO  [0x70000fcc7000] ProducerImpl:725 | 
[persistent://public/default/testProducerIsConnectedPartitioned-16481858675-partition-0,
 standalone-0-786] Closed producer
   2022-03-25 13:24:27.836 INFO  [0x700010b9e000] ProducerTest:216 | Closed
   2022-03-25 13:24:27.836 INFO  [0x700010b1b000] ProducerTest:199 | closedAt 
set to 10
   2022-03-25 13:24:27.952 INFO  [0x1011f4600] ClientImpl:496 | Closing Pulsar 
client with 1 producers and 0 consumers
   2022-03-25 13:24:27.952 INFO  [0x1011f4600] ClientConnection:1559 | 
[127.0.0.1:55395 -> 127.0.0.1:6650] Connection closed
   2022-03-25 13:24:27.953 INFO  [0x1011f4600] ClientConnection:263 | 
[127.0.0.1:55395 -> 127.0.0.1:6650] Destroyed connection
   2022-03-25 13:24:27.953 INFO  [0x1011f4600] ProducerTest:242 | End of run 5
   2022-03-25 13:24:27.953 INFO  [0x1011f4600] ProducerTest:167 | Start of run 6
   2022-03-25 13:24:27.982 INFO  [0x1011f4600] ClientConnection:189 | [<none> 
-> pulsar://localhost:6650] Create ClientConnection, timeout=10000
   2022-03-25 13:24:27.982 INFO  [0x1011f4600] ConnectionPool:96 | Created 
connection for pulsar://localhost:6650
   2022-03-25 13:24:27.983 INFO  [0x70000fcc7000] ClientConnection:375 | 
[127.0.0.1:55397 -> 127.0.0.1:6650] Connected to broker
   2022-03-25 13:24:27.985 INFO  [0x70000fcc7000] HandlerBase:64 | 
[persistent://public/default/testProducerIsConnectedPartitioned-16481858676-partition-3,
 ] Getting connection from pool
   2022-03-25 13:24:28.017 INFO  [0x70000fcc7000] ProducerImpl:188 | 
[persistent://public/default/testProducerIsConnectedPartitioned-16481858676-partition-3,
 ] Created producer on broker [127.0.0.1:55397 -> 127.0.0.1:6650] 
   2022-03-25 13:24:28.029 INFO  [0x700010c21000] ProducerTest:209 | Closing
   2022-03-25 13:24:28.029 INFO  [0x700010c21000] ProducerImpl:682 | 
[persistent://public/default/testProducerIsConnectedPartitioned-16481858676-partition-3,
 standalone-0-787] Closing producer for topic 
persistent://public/default/testProducerIsConnectedPartitioned-16481858676-partition-3
   2022-03-25 13:24:28.029 INFO  [0x700010c21000] ProducerTest:211 | Close 
called
   2022-03-25 13:24:28.030 INFO  [0x70000fcc7000] ProducerImpl:725 | 
[persistent://public/default/testProducerIsConnectedPartitioned-16481858676-partition-3,
 standalone-0-787] Closed producer
   2022-03-25 13:24:28.030 INFO  [0x700010b9e000] ProducerTest:199 | closedAt 
set to 10
   2022-03-25 13:24:28.030 INFO  [0x700010c21000] ProducerTest:216 | Closed
   2022-03-25 13:24:28.146 INFO  [0x1011f4600] ClientImpl:496 | Closing Pulsar 
client with 1 producers and 0 consumers
   2022-03-25 13:24:28.147 INFO  [0x1011f4600] ClientConnection:1559 | 
[127.0.0.1:55397 -> 127.0.0.1:6650] Connection closed
   2022-03-25 13:24:28.147 INFO  [0x1011f4600] ProducerTest:242 | End of run 6
   2022-03-25 13:24:28.147 INFO  [0x1011f4600] ProducerTest:167 | Start of run 7
   2022-03-25 13:24:28.179 INFO  [0x1011f4600] ClientConnection:189 | [<none> 
-> pulsar://localhost:6650] Create ClientConnection, timeout=10000
   2022-03-25 13:24:28.179 INFO  [0x1011f4600] ConnectionPool:96 | Created 
connection for pulsar://localhost:6650
   2022-03-25 13:24:28.181 INFO  [0x70000fcc7000] ClientConnection:375 | 
[127.0.0.1:55399 -> 127.0.0.1:6650] Connected to broker
   2022-03-25 13:24:28.183 INFO  [0x70000fcc7000] HandlerBase:64 | 
[persistent://public/default/testProducerIsConnectedPartitioned-16481858687-partition-3,
 ] Getting connection from pool
   2022-03-25 13:24:28.213 INFO  [0x70000fcc7000] ProducerImpl:188 | 
[persistent://public/default/testProducerIsConnectedPartitioned-16481858687-partition-3,
 ] Created producer on broker [127.0.0.1:55399 -> 127.0.0.1:6650] 
   2022-03-25 13:24:28.225 INFO  [0x700010ca4000] ProducerTest:209 | Closing
   2022-03-25 13:24:28.225 INFO  [0x700010ca4000] ProducerImpl:682 | 
[persistent://public/default/testProducerIsConnectedPartitioned-16481858687-partition-3,
 standalone-0-788] Closing producer for topic 
persistent://public/default/testProducerIsConnectedPartitioned-16481858687-partition-3
   2022-03-25 13:24:28.225 INFO  [0x700010ca4000] ProducerTest:211 | Close 
called
   2022-03-25 13:24:28.226 INFO  [0x70000fcc7000] ProducerImpl:725 | 
[persistent://public/default/testProducerIsConnectedPartitioned-16481858687-partition-3,
 standalone-0-788] Closed producer
   2022-03-25 13:24:28.226 INFO  [0x700010ca4000] ProducerTest:216 | Closed
   2022-03-25 13:24:28.226 INFO  [0x700010c21000] ProducerTest:199 | closedAt 
set to 10
   2022-03-25 13:24:28.341 INFO  [0x1011f4600] ClientImpl:496 | Closing Pulsar 
client with 1 producers and 0 consumers
   2022-03-25 13:24:28.341 INFO  [0x1011f4600] ClientConnection:1559 | 
[127.0.0.1:55399 -> 127.0.0.1:6650] Connection closed
   2022-03-25 13:24:28.341 INFO  [0x1011f4600] ClientConnection:263 | 
[127.0.0.1:55399 -> 127.0.0.1:6650] Destroyed connection
   2022-03-25 13:24:28.341 INFO  [0x1011f4600] ProducerTest:242 | End of run 7
   2022-03-25 13:24:28.341 INFO  [0x1011f4600] ProducerTest:167 | Start of run 8
   2022-03-25 13:24:28.372 INFO  [0x1011f4600] ClientConnection:189 | [<none> 
-> pulsar://localhost:6650] Create ClientConnection, timeout=10000
   2022-03-25 13:24:28.372 INFO  [0x1011f4600] ConnectionPool:96 | Created 
connection for pulsar://localhost:6650
   2022-03-25 13:24:28.373 INFO  [0x70000fcc7000] ClientConnection:375 | 
[127.0.0.1:55401 -> 127.0.0.1:6650] Connected to broker
   2022-03-25 13:24:28.375 INFO  [0x70000fcc7000] HandlerBase:64 | 
[persistent://public/default/testProducerIsConnectedPartitioned-16481858688-partition-6,
 ] Getting connection from pool
   2022-03-25 13:24:28.407 INFO  [0x70000fcc7000] ProducerImpl:188 | 
[persistent://public/default/testProducerIsConnectedPartitioned-16481858688-partition-6,
 ] Created producer on broker [127.0.0.1:55401 -> 127.0.0.1:6650] 
   2022-03-25 13:24:28.419 INFO  [0x700010d27000] ProducerTest:209 | Closing
   2022-03-25 13:24:28.420 INFO  [0x700010d27000] ProducerImpl:682 | 
[persistent://public/default/testProducerIsConnectedPartitioned-16481858688-partition-6,
 standalone-0-789] Closing producer for topic 
persistent://public/default/testProducerIsConnectedPartitioned-16481858688-partition-6
   2022-03-25 13:24:28.420 INFO  [0x700010d27000] ProducerTest:211 | Close 
called
   2022-03-25 13:24:28.420 INFO  [0x70000fcc7000] ProducerImpl:725 | 
[persistent://public/default/testProducerIsConnectedPartitioned-16481858688-partition-6,
 standalone-0-789] Closed producer
   2022-03-25 13:24:28.420 INFO  [0x700010ca4000] ProducerTest:199 | closedAt 
set to 10
   2022-03-25 13:24:28.421 INFO  [0x700010d27000] ProducerTest:216 | Closed
   2022-03-25 13:24:28.536 INFO  [0x1011f4600] ClientImpl:496 | Closing Pulsar 
client with 1 producers and 0 consumers
   2022-03-25 13:24:28.536 INFO  [0x1011f4600] ClientConnection:1559 | 
[127.0.0.1:55401 -> 127.0.0.1:6650] Connection closed
   2022-03-25 13:24:28.536 INFO  [0x1011f4600] ClientConnection:263 | 
[127.0.0.1:55401 -> 127.0.0.1:6650] Destroyed connection
   2022-03-25 13:24:28.536 INFO  [0x1011f4600] ProducerTest:242 | End of run 8
   2022-03-25 13:24:28.537 INFO  [0x1011f4600] ProducerTest:167 | Start of run 9
   2022-03-25 13:24:28.569 INFO  [0x1011f4600] ClientConnection:189 | [<none> 
-> pulsar://localhost:6650] Create ClientConnection, timeout=10000
   2022-03-25 13:24:28.569 INFO  [0x1011f4600] ConnectionPool:96 | Created 
connection for pulsar://localhost:6650
   2022-03-25 13:24:28.570 INFO  [0x70000fcc7000] ClientConnection:375 | 
[127.0.0.1:55403 -> 127.0.0.1:6650] Connected to broker
   2022-03-25 13:24:28.572 INFO  [0x70000fcc7000] HandlerBase:64 | 
[persistent://public/default/testProducerIsConnectedPartitioned-16481858689-partition-2,
 ] Getting connection from pool
   2022-03-25 13:24:28.603 INFO  [0x70000fcc7000] ProducerImpl:188 | 
[persistent://public/default/testProducerIsConnectedPartitioned-16481858689-partition-2,
 ] Created producer on broker [127.0.0.1:55403 -> 127.0.0.1:6650] 
   2022-03-25 13:24:28.615 INFO  [0x700010daa000] ProducerTest:209 | Closing
   2022-03-25 13:24:28.615 INFO  [0x700010daa000] ProducerImpl:682 | 
[persistent://public/default/testProducerIsConnectedPartitioned-16481858689-partition-2,
 standalone-0-790] Closing producer for topic 
persistent://public/default/testProducerIsConnectedPartitioned-16481858689-partition-2
   2022-03-25 13:24:28.615 INFO  [0x700010daa000] ProducerTest:211 | Close 
called
   2022-03-25 13:24:28.616 INFO  [0x70000fcc7000] ProducerImpl:725 | 
[persistent://public/default/testProducerIsConnectedPartitioned-16481858689-partition-2,
 standalone-0-790] Closed producer
   2022-03-25 13:24:28.616 INFO  [0x700010d27000] ProducerTest:199 | closedAt 
set to 10
   2022-03-25 13:24:28.616 INFO  [0x700010daa000] ProducerTest:216 | Closed
   2022-03-25 13:24:28.730 INFO  [0x1011f4600] ClientImpl:496 | Closing Pulsar 
client with 1 producers and 0 consumers
   2022-03-25 13:24:28.731 INFO  [0x1011f4600] ClientConnection:1559 | 
[127.0.0.1:55403 -> 127.0.0.1:6650] Connection closed
   2022-03-25 13:24:28.731 INFO  [0x1011f4600] ProducerTest:242 | End of run 9
   2022-03-25 13:24:28.731 INFO  [0x70000fcc7000] ClientConnection:263 | 
[127.0.0.1:55403 -> 127.0.0.1:6650] Destroyed connection
   2022-03-25 13:24:28.731 INFO  [0x1011f4600] ProducerTest:167 | Start of run 
10
   2022-03-25 13:24:28.764 INFO  [0x1011f4600] ClientConnection:189 | [<none> 
-> pulsar://localhost:6650] Create ClientConnection, timeout=10000
   2022-03-25 13:24:28.764 INFO  [0x1011f4600] ConnectionPool:96 | Created 
connection for pulsar://localhost:6650
   2022-03-25 13:24:28.765 INFO  [0x70000fcc7000] ClientConnection:375 | 
[127.0.0.1:55408 -> 127.0.0.1:6650] Connected to broker
   2022-03-25 13:24:28.768 INFO  [0x70000fcc7000] HandlerBase:64 | 
[persistent://public/default/testProducerIsConnectedPartitioned-164818586810-partition-6,
 ] Getting connection from pool
   2022-03-25 13:24:28.797 INFO  [0x70000fcc7000] ProducerImpl:188 | 
[persistent://public/default/testProducerIsConnectedPartitioned-164818586810-partition-6,
 ] Created producer on broker [127.0.0.1:55408 -> 127.0.0.1:6650] 
   2022-03-25 13:24:28.809 INFO  [0x700010e2d000] ProducerTest:209 | Closing
   2022-03-25 13:24:28.809 INFO  [0x700010e2d000] ProducerImpl:682 | 
[persistent://public/default/testProducerIsConnectedPartitioned-164818586810-partition-6,
 standalone-0-791] Closing producer for topic 
persistent://public/default/testProducerIsConnectedPartitioned-164818586810-partition-6
   2022-03-25 13:24:28.809 INFO  [0x700010e2d000] ProducerTest:211 | Close 
called
   2022-03-25 13:24:28.810 INFO  [0x700010daa000] ProducerTest:199 | closedAt 
set to 10
   2022-03-25 13:24:28.811 INFO  [0x70000fcc7000] ProducerImpl:725 | 
[persistent://public/default/testProducerIsConnectedPartitioned-164818586810-partition-6,
 standalone-0-791] Closed producer
   2022-03-25 13:24:28.811 INFO  [0x700010e2d000] ProducerTest:216 | Closed
   2022-03-25 13:24:28.926 INFO  [0x1011f4600] ClientImpl:496 | Closing Pulsar 
client with 1 producers and 0 consumers
   2022-03-25 13:24:28.927 INFO  [0x1011f4600] ClientConnection:1559 | 
[127.0.0.1:55408 -> 127.0.0.1:6650] Connection closed
   2022-03-25 13:24:28.927 INFO  [0x1011f4600] ClientConnection:263 | 
[127.0.0.1:55408 -> 127.0.0.1:6650] Destroyed connection
   2022-03-25 13:24:28.927 INFO  [0x1011f4600] ProducerTest:242 | End of run 10
   2022-03-25 13:24:28.927 INFO  [0x1011f4600] ProducerTest:167 | Start of run 
11
   2022-03-25 13:24:28.959 INFO  [0x1011f4600] ClientConnection:189 | [<none> 
-> pulsar://localhost:6650] Create ClientConnection, timeout=10000
   2022-03-25 13:24:28.959 INFO  [0x1011f4600] ConnectionPool:96 | Created 
connection for pulsar://localhost:6650
   2022-03-25 13:24:28.960 INFO  [0x70000fcc7000] ClientConnection:375 | 
[127.0.0.1:55410 -> 127.0.0.1:6650] Connected to broker
   2022-03-25 13:24:28.962 INFO  [0x70000fcc7000] HandlerBase:64 | 
[persistent://public/default/testProducerIsConnectedPartitioned-164818586811-partition-7,
 ] Getting connection from pool
   2022-03-25 13:24:28.992 INFO  [0x70000fcc7000] ProducerImpl:188 | 
[persistent://public/default/testProducerIsConnectedPartitioned-164818586811-partition-7,
 ] Created producer on broker [127.0.0.1:55410 -> 127.0.0.1:6650] 
   2022-03-25 13:24:29.005 INFO  [0x700010eb0000] ProducerTest:209 | Closing
   2022-03-25 13:24:29.005 INFO  [0x700010eb0000] ProducerImpl:682 | 
[persistent://public/default/testProducerIsConnectedPartitioned-164818586811-partition-7,
 standalone-0-792] Closing producer for topic 
persistent://public/default/testProducerIsConnectedPartitioned-164818586811-partition-7
   2022-03-25 13:24:29.005 INFO  [0x700010eb0000] ProducerTest:211 | Close 
called
   2022-03-25 13:24:29.006 INFO  [0x70000fcc7000] ProducerImpl:725 | 
[persistent://public/default/testProducerIsConnectedPartitioned-164818586811-partition-7,
 standalone-0-792] Closed producer
   2022-03-25 13:24:29.006 INFO  [0x700010eb0000] ProducerTest:216 | Closed
   2022-03-25 13:24:29.006 INFO  [0x700010e2d000] ProducerTest:199 | closedAt 
set to 10
   2022-03-25 13:24:29.122 INFO  [0x1011f4600] ClientImpl:496 | Closing Pulsar 
client with 1 producers and 0 consumers
   2022-03-25 13:24:29.122 INFO  [0x1011f4600] ClientConnection:1559 | 
[127.0.0.1:55410 -> 127.0.0.1:6650] Connection closed
   2022-03-25 13:24:29.122 INFO  [0x1011f4600] ClientConnection:263 | 
[127.0.0.1:55410 -> 127.0.0.1:6650] Destroyed connection
   2022-03-25 13:24:29.122 INFO  [0x1011f4600] ProducerTest:242 | End of run 11
   2022-03-25 13:24:29.122 INFO  [0x1011f4600] ProducerTest:167 | Start of run 
12
   2022-03-25 13:24:29.154 INFO  [0x1011f4600] ClientConnection:189 | [<none> 
-> pulsar://localhost:6650] Create ClientConnection, timeout=10000
   2022-03-25 13:24:29.154 INFO  [0x1011f4600] ConnectionPool:96 | Created 
connection for pulsar://localhost:6650
   2022-03-25 13:24:29.155 INFO  [0x70000fcc7000] ClientConnection:375 | 
[127.0.0.1:55412 -> 127.0.0.1:6650] Connected to broker
   2022-03-25 13:24:29.158 INFO  [0x70000fcc7000] HandlerBase:64 | 
[persistent://public/default/testProducerIsConnectedPartitioned-164818586912-partition-4,
 ] Getting connection from pool
   2022-03-25 13:24:29.187 INFO  [0x70000fcc7000] ProducerImpl:188 | 
[persistent://public/default/testProducerIsConnectedPartitioned-164818586912-partition-4,
 ] Created producer on broker [127.0.0.1:55412 -> 127.0.0.1:6650] 
   2022-03-25 13:24:29.199 INFO  [0x700010f33000] ProducerTest:209 | Closing
   2022-03-25 13:24:29.199 INFO  [0x700010f33000] ProducerImpl:682 | 
[persistent://public/default/testProducerIsConnectedPartitioned-164818586912-partition-4,
 standalone-0-793] Closing producer for topic 
persistent://public/default/testProducerIsConnectedPartitioned-164818586912-partition-4
   2022-03-25 13:24:29.199 INFO  [0x700010f33000] ProducerTest:211 | Close 
called
   2022-03-25 13:24:29.200 INFO  [0x700010eb0000] ProducerTest:199 | closedAt 
set to 10
   2022-03-25 13:24:29.201 INFO  [0x70000fcc7000] ProducerImpl:725 | 
[persistent://public/default/testProducerIsConnectedPartitioned-164818586912-partition-4,
 standalone-0-793] Closed producer
   2022-03-25 13:24:29.201 INFO  [0x700010f33000] ProducerTest:216 | Closed
   2022-03-25 13:24:29.315 INFO  [0x1011f4600] ClientImpl:496 | Closing Pulsar 
client with 1 producers and 0 consumers
   2022-03-25 13:24:29.316 INFO  [0x1011f4600] ClientConnection:1559 | 
[127.0.0.1:55412 -> 127.0.0.1:6650] Connection closed
   2022-03-25 13:24:29.316 INFO  [0x1011f4600] ClientConnection:263 | 
[127.0.0.1:55412 -> 127.0.0.1:6650] Destroyed connection
   2022-03-25 13:24:29.316 INFO  [0x1011f4600] ProducerTest:242 | End of run 12
   2022-03-25 13:24:29.316 INFO  [0x1011f4600] ProducerTest:167 | Start of run 
13
   2022-03-25 13:24:29.348 INFO  [0x1011f4600] ClientConnection:189 | [<none> 
-> pulsar://localhost:6650] Create ClientConnection, timeout=10000
   2022-03-25 13:24:29.348 INFO  [0x1011f4600] ConnectionPool:96 | Created 
connection for pulsar://localhost:6650
   2022-03-25 13:24:29.349 INFO  [0x70000fcc7000] ClientConnection:375 | 
[127.0.0.1:55414 -> 127.0.0.1:6650] Connected to broker
   2022-03-25 13:24:29.351 INFO  [0x70000fcc7000] HandlerBase:64 | 
[persistent://public/default/testProducerIsConnectedPartitioned-164818586913-partition-5,
 ] Getting connection from pool
   2022-03-25 13:24:29.382 INFO  [0x70000fcc7000] ProducerImpl:188 | 
[persistent://public/default/testProducerIsConnectedPartitioned-164818586913-partition-5,
 ] Created producer on broker [127.0.0.1:55414 -> 127.0.0.1:6650] 
   2022-03-25 13:24:29.394 INFO  [0x700010fb6000] ProducerTest:209 | Closing
   2022-03-25 13:24:29.394 INFO  [0x700010fb6000] ProducerImpl:682 | 
[persistent://public/default/testProducerIsConnectedPartitioned-164818586913-partition-5,
 standalone-0-794] Closing producer for topic 
persistent://public/default/testProducerIsConnectedPartitioned-164818586913-partition-5
   2022-03-25 13:24:29.394 INFO  [0x700010fb6000] ProducerTest:211 | Close 
called
   2022-03-25 13:24:29.395 INFO  [0x70000fcc7000] ProducerImpl:725 | 
[persistent://public/default/testProducerIsConnectedPartitioned-164818586913-partition-5,
 standalone-0-794] Closed producer
   2022-03-25 13:24:29.395 INFO  [0x700010f33000] ProducerTest:199 | closedAt 
set to 10
   2022-03-25 13:24:29.395 INFO  [0x700010fb6000] ProducerTest:216 | Closed
   2022-03-25 13:24:29.511 INFO  [0x1011f4600] ClientImpl:496 | Closing Pulsar 
client with 1 producers and 0 consumers
   2022-03-25 13:24:29.511 INFO  [0x1011f4600] ClientConnection:1559 | 
[127.0.0.1:55414 -> 127.0.0.1:6650] Connection closed
   2022-03-25 13:24:29.511 INFO  [0x1011f4600] ClientConnection:263 | 
[127.0.0.1:55414 -> 127.0.0.1:6650] Destroyed connection
   2022-03-25 13:24:29.511 INFO  [0x1011f4600] ProducerTest:242 | End of run 13
   2022-03-25 13:24:29.512 INFO  [0x1011f4600] ProducerTest:167 | Start of run 
14
   2022-03-25 13:24:29.544 INFO  [0x1011f4600] ClientConnection:189 | [<none> 
-> pulsar://localhost:6650] Create ClientConnection, timeout=10000
   2022-03-25 13:24:29.544 INFO  [0x1011f4600] ConnectionPool:96 | Created 
connection for pulsar://localhost:6650
   2022-03-25 13:24:29.545 INFO  [0x70000fcc7000] ClientConnection:375 | 
[127.0.0.1:55416 -> 127.0.0.1:6650] Connected to broker
   2022-03-25 13:24:29.548 INFO  [0x70000fcc7000] HandlerBase:64 | 
[persistent://public/default/testProducerIsConnectedPartitioned-164818586914-partition-0,
 ] Getting connection from pool
   2022-03-25 13:24:29.578 INFO  [0x70000fcc7000] ProducerImpl:188 | 
[persistent://public/default/testProducerIsConnectedPartitioned-164818586914-partition-0,
 ] Created producer on broker [127.0.0.1:55416 -> 127.0.0.1:6650] 
   2022-03-25 13:24:29.590 INFO  [0x700011039000] ProducerTest:209 | Closing
   2022-03-25 13:24:29.590 INFO  [0x700011039000] ProducerImpl:682 | 
[persistent://public/default/testProducerIsConnectedPartitioned-164818586914-partition-0,
 standalone-0-795] Closing producer for topic 
persistent://public/default/testProducerIsConnectedPartitioned-164818586914-partition-0
   2022-03-25 13:24:29.591 INFO  [0x700011039000] ProducerTest:211 | Close 
called
   2022-03-25 13:24:29.591 INFO  [0x700010fb6000] ProducerTest:199 | closedAt 
set to 10
   2022-03-25 13:24:29.592 INFO  [0x70000fcc7000] ProducerImpl:725 | 
[persistent://public/default/testProducerIsConnectedPartitioned-164818586914-partition-0,
 standalone-0-795] Closed producer
   2022-03-25 13:24:29.592 INFO  [0x700011039000] ProducerTest:216 | Closed
   2022-03-25 13:24:29.707 INFO  [0x1011f4600] ClientImpl:496 | Closing Pulsar 
client with 1 producers and 0 consumers
   2022-03-25 13:24:29.707 INFO  [0x1011f4600] ClientConnection:1559 | 
[127.0.0.1:55416 -> 127.0.0.1:6650] Connection closed
   2022-03-25 13:24:29.707 INFO  [0x1011f4600] ClientConnection:263 | 
[127.0.0.1:55416 -> 127.0.0.1:6650] Destroyed connection
   2022-03-25 13:24:29.708 INFO  [0x1011f4600] ProducerTest:242 | End of run 14
   2022-03-25 13:24:29.708 INFO  [0x1011f4600] ProducerTest:167 | Start of run 
15
   2022-03-25 13:24:29.739 INFO  [0x1011f4600] ClientConnection:189 | [<none> 
-> pulsar://localhost:6650] Create ClientConnection, timeout=10000
   2022-03-25 13:24:29.739 INFO  [0x1011f4600] ConnectionPool:96 | Created 
connection for pulsar://localhost:6650
   2022-03-25 13:24:29.740 INFO  [0x70000fcc7000] ClientConnection:375 | 
[127.0.0.1:55418 -> 127.0.0.1:6650] Connected to broker
   2022-03-25 13:24:29.742 INFO  [0x70000fcc7000] HandlerBase:64 | 
[persistent://public/default/testProducerIsConnectedPartitioned-164818586915-partition-0,
 ] Getting connection from pool
   2022-03-25 13:24:29.772 INFO  [0x70000fcc7000] ProducerImpl:188 | 
[persistent://public/default/testProducerIsConnectedPartitioned-164818586915-partition-0,
 ] Created producer on broker [127.0.0.1:55418 -> 127.0.0.1:6650] 
   2022-03-25 13:24:29.783 INFO  [0x7000110bc000] ProducerTest:209 | Closing
   2022-03-25 13:24:29.783 INFO  [0x7000110bc000] ProducerImpl:682 | 
[persistent://public/default/testProducerIsConnectedPartitioned-164818586915-partition-0,
 standalone-0-796] Closing producer for topic 
persistent://public/default/testProducerIsConnectedPartitioned-164818586915-partition-0
   2022-03-25 13:24:29.783 INFO  [0x7000110bc000] ProducerTest:211 | Close 
called
   2022-03-25 13:24:29.784 INFO  [0x700011039000] ProducerTest:199 | closedAt 
set to 10
   2022-03-25 13:24:29.785 INFO  [0x70000fcc7000] ProducerImpl:725 | 
[persistent://public/default/testProducerIsConnectedPartitioned-164818586915-partition-0,
 standalone-0-796] Closed producer
   2022-03-25 13:24:29.786 INFO  [0x7000110bc000] ProducerTest:216 | Closed
   pulsar-client-cpp/tests/ProducerTest.cc:229: Failure
   Expected equality of these values:
     true
     promises[i].isComplete()
       Which is: false
   [  FAILED  ] 
ProducerTest.testSendAsyncCloseAsyncConcurrentlyWithLazyProducers (3121 ms)
   ```


-- 
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