You are viewing a plain text version of this content. The canonical link for it is here.
Posted to commits@pulsar.apache.org by GitBox <gi...@apache.org> on 2022/03/16 13:12:12 UTC
[GitHub] [pulsar] BewareMyPower opened a new issue #14719: Flaky-test: C++ ReaderTest.testReferenceLeak
BewareMyPower opened a new issue #14719:
URL: https://github.com/apache/pulsar/issues/14719
ReaderTest.testReferenceLeak is flaky. It fails sporadically.
[example failure](https://github.com/apache/pulsar/runs/5567332966?check_suite_focus=true#step:11:568)
```
[ RUN ] ReaderTest.testReferenceLeak
2022-03-16 10:03:01.669 INFO [140180921047808] ClientConnection:189 | [<none> -> pulsar://localhost:6650] Create ClientConnection, timeout=10000
2022-03-16 10:03:01.669 INFO [140180921047808] ConnectionPool:96 | Created connection for pulsar://localhost:6650
2022-03-16 10:03:01.670 INFO [140180798908160] ClientConnection:375 | [127.0.0.1:57130 -> 127.0.0.1:6650] Connected to broker
2022-03-16 10:03:01.676 INFO [140180798908160] HandlerBase:64 | [persistent://public/default/testReferenceLeak, ] Getting connection from pool
2022-03-16 10:03:01.711 INFO [140180798908160] ProducerImpl:189 | [persistent://public/default/testReferenceLeak, ] Created producer on broker [127.0.0.1:57130 -> 127.0.0.1:6650]
2022-03-16 10:03:01.746 INFO [140180798908160] HandlerBase:64 | [persistent://public/default/testReferenceLeak, reader-ef351ebab8, 0] Getting connection from pool
2022-03-16 10:03:01.748 INFO [140180798908160] ConsumerImpl:224 | [persistent://public/default/testReferenceLeak, reader-ef351ebab8, 0] Created consumer on broker [127.0.0.1:57130 -> 127.0.0.1:6650]
2022-03-16 10:03:01.748 INFO [140180921047808] ReaderTest:447 | 1 consumer use count 3
2022-03-16 10:03:01.748 INFO [140180921047808] ReaderTest:448 | 1 reader use count 0
2022-03-16 10:03:01.752 INFO [140180921047808] ProducerImpl:686 | [persistent://public/default/testReferenceLeak, standalone-0-387] Closing producer for topic persistent://public/default/testReferenceLeak
2022-03-16 10:03:01.753 INFO [140180798908160] ProducerImpl:729 | [persistent://public/default/testReferenceLeak, standalone-0-387] Closed producer
2022-03-16 10:03:01.753 INFO [140180921047808] ConsumerImpl:999 | [persistent://public/default/testReferenceLeak, reader-ef351ebab8, 0] Closing consumer for topic persistent://public/default/testReferenceLeak
2022-03-16 10:03:01.753 INFO [140180798908160] ConsumerImpl:1055 | [persistent://public/default/testReferenceLeak, reader-ef351ebab8, 0] Closed consumer 0
/pulsar/pulsar-client-cpp/tests/ReaderTest.cc:463: Failure
Value of: readerPtr.use_count()
Actual: 0
Expected: 1
2022-03-16 10:03:01.753 INFO [140180921047808] ProducerImpl:653 | Producer - [persistent://public/default/testReferenceLeak, standalone-0-387] , [batchMessageContainer = { BatchMessageContainer [size = 0] [bytes = 0] [maxSize = 1000] [maxBytes = 131072] [topicName = persistent://public/default/testReferenceLeak] [numberOfBatchesSent_ = 11] [averageBatchSize_ = 0.909091] }]
2022-03-16 10:03:01.754 INFO [140180921047808] ClientConnection:1560 | [127.0.0.1:57130 -> 127.0.0.1:6650] Connection closed
[ FAILED ] ReaderTest.testReferenceLeak (86 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: commits-unsubscribe@pulsar.apache.org
For queries about this service, please contact Infrastructure at:
users@infra.apache.org
[GitHub] [pulsar] BewareMyPower edited a comment on issue #14719: Flaky-test: C++ ReaderTest.testReferenceLeak
Posted by GitBox <gi...@apache.org>.
BewareMyPower edited a comment on issue #14719:
URL: https://github.com/apache/pulsar/issues/14719#issuecomment-1077486695
I can reproduce it when the test was iterated for 50 times.
```
2022-03-24 18:40:25.090 INFO [0x11d86e600] ClientConnection:189 | [<none> -> pulsar://localhost:6650] Create ClientConnection, timeout=10000
2022-03-24 18:40:25.090 INFO [0x11d86e600] ConnectionPool:96 | Created connection for pulsar://localhost:6650
2022-03-24 18:40:25.091 INFO [0x700007665000] ClientConnection:375 | [127.0.0.1:54854 -> 127.0.0.1:6650] Connected to broker
2022-03-24 18:40:25.092 INFO [0x700007665000] HandlerBase:64 | [persistent://public/default/testReferenceLeak, ] Getting connection from pool
2022-03-24 18:40:25.092 INFO [0x700007665000] ProducerImpl:189 | [persistent://public/default/testReferenceLeak, ] Created producer on broker [127.0.0.1:54854 -> 127.0.0.1:6650]
2022-03-24 18:40:25.110 INFO [0x700007665000] HandlerBase:64 | [persistent://public/default/testReferenceLeak, reader-45b5d48620, 0] Getting connection from pool
2022-03-24 18:40:25.111 INFO [0x700007665000] ConsumerImpl:224 | [persistent://public/default/testReferenceLeak, reader-45b5d48620, 0] Created consumer on broker [127.0.0.1:54854 -> 127.0.0.1:6650]
2022-03-24 18:40:25.111 INFO [0x11d86e600] ReaderTest:447 | 1 consumer use count 1
2022-03-24 18:40:25.111 INFO [0x11d86e600] ReaderTest:448 | 1 reader use count 0
2022-03-24 18:40:25.116 INFO [0x11d86e600] ProducerImpl:686 | [persistent://public/default/testReferenceLeak, standalone-0-344] Closing producer for topic persistent://public/default/testReferenceLeak
2022-03-24 18:40:25.118 INFO [0x700007665000] ProducerImpl:729 | [persistent://public/default/testReferenceLeak, standalone-0-344] Closed producer
2022-03-24 18:40:25.118 INFO [0x11d86e600] ConsumerImpl:999 | [persistent://public/default/testReferenceLeak, reader-45b5d48620, 0] Closing consumer for topic persistent://public/default/testReferenceLeak
2022-03-24 18:40:25.118 INFO [0x700007665000] ConsumerImpl:1055 | [persistent://public/default/testReferenceLeak, reader-45b5d48620, 0] Closed consumer 0
pulsar-client-cpp/tests/ReaderTest.cc:463: Failure
Expected equality of these values:
1
readerPtr.use_count()
Which is: 0
2022-03-24 18:40:25.119 INFO [0x11d86e600] ProducerImpl:653 | Producer - [persistent://public/default/testReferenceLeak, standalone-0-344] , [batchMessageContainer = { BatchMessageContainer [size = 0] [bytes = 0] [maxSize = 1000] [maxBytes = 131072] [topicName = persistent://public/default/testReferenceLeak] [numberOfBatchesSent_ = 11] [averageBatchSize_ = 0.909091] }]
2022-03-24 18:40:25.119 INFO [0x11d86e600] ClientConnection:1559 | [127.0.0.1:54854 -> 127.0.0.1:6650] Connection closed
2022-03-24 18:40:25.119 INFO [0x11d86e600] ClientConnection:263 | [127.0.0.1:54854 -> 127.0.0.1:6650] Destroyed connection
```
```
2022-03-24 18:40:34.076 INFO [0x1141e3600] ClientConnection:189 | [<none> -> pulsar://localhost:6650] Create ClientConnection, timeout=10000
2022-03-24 18:40:34.076 INFO [0x1141e3600] ConnectionPool:96 | Created connection for pulsar://localhost:6650
2022-03-24 18:40:34.076 INFO [0x70000a907000] ClientConnection:375 | [127.0.0.1:54945 -> 127.0.0.1:6650] Connected to broker
2022-03-24 18:40:34.077 INFO [0x70000a907000] HandlerBase:64 | [persistent://public/default/testReferenceLeak, ] Getting connection from pool
2022-03-24 18:40:34.078 INFO [0x70000a907000] ProducerImpl:189 | [persistent://public/default/testReferenceLeak, ] Created producer on broker [127.0.0.1:54945 -> 127.0.0.1:6650]
2022-03-24 18:40:34.095 INFO [0x70000a907000] HandlerBase:64 | [persistent://public/default/testReferenceLeak, reader-4b66d62984, 0] Getting connection from pool
2022-03-24 18:40:34.096 INFO [0x70000a907000] ConsumerImpl:224 | [persistent://public/default/testReferenceLeak, reader-4b66d62984, 0] Created consumer on broker [127.0.0.1:54945 -> 127.0.0.1:6650]
2022-03-24 18:40:34.097 INFO [0x1141e3600] ReaderTest:447 | 1 consumer use count 1
2022-03-24 18:40:34.097 INFO [0x1141e3600] ReaderTest:448 | 1 reader use count 1
2022-03-24 18:40:34.101 INFO [0x1141e3600] ProducerImpl:686 | [persistent://public/default/testReferenceLeak, standalone-0-414] Closing producer for topic persistent://public/default/testReferenceLeak
2022-03-24 18:40:34.103 INFO [0x70000a907000] ProducerImpl:729 | [persistent://public/default/testReferenceLeak, standalone-0-414] Closed producer
2022-03-24 18:40:34.103 INFO [0x1141e3600] ConsumerImpl:999 | [persistent://public/default/testReferenceLeak, reader-4b66d62984, 0] Closing consumer for topic persistent://public/default/testReferenceLeak
2022-03-24 18:40:34.104 INFO [0x70000a907000] ConsumerImpl:1055 | [persistent://public/default/testReferenceLeak, reader-4b66d62984, 0] Closed consumer 0
pulsar-client-cpp/tests/ReaderTest.cc:462: Failure
Expected equality of these values:
1
consumerPtr.use_count()
Which is: 2
```
--
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: commits-unsubscribe@pulsar.apache.org
For queries about this service, please contact Infrastructure at:
users@infra.apache.org
[GitHub] [pulsar] lhotari closed issue #14719: Flaky-test: C++ ReaderTest.testReferenceLeak
Posted by GitBox <gi...@apache.org>.
lhotari closed issue #14719:
URL: https://github.com/apache/pulsar/issues/14719
--
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: commits-unsubscribe@pulsar.apache.org
For queries about this service, please contact Infrastructure at:
users@infra.apache.org
[GitHub] [pulsar] BewareMyPower commented on issue #14719: Flaky-test: C++ ReaderTest.testReferenceLeak
Posted by GitBox <gi...@apache.org>.
BewareMyPower commented on issue #14719:
URL: https://github.com/apache/pulsar/issues/14719#issuecomment-1077486695
I can reproduce it when the test was iterated for 50 times.
```
2022-03-24 18:40:25.090 INFO [0x11d86e600] ClientConnection:189 | [<none> -> pulsar://localhost:6650] Create ClientConnection, timeout=10000
2022-03-24 18:40:25.090 INFO [0x11d86e600] ConnectionPool:96 | Created connection for pulsar://localhost:6650
2022-03-24 18:40:25.091 INFO [0x700007665000] ClientConnection:375 | [127.0.0.1:54854 -> 127.0.0.1:6650] Connected to broker
2022-03-24 18:40:25.092 INFO [0x700007665000] HandlerBase:64 | [persistent://public/default/testReferenceLeak, ] Getting connection from pool
2022-03-24 18:40:25.092 INFO [0x700007665000] ProducerImpl:189 | [persistent://public/default/testReferenceLeak, ] Created producer on broker [127.0.0.1:54854 -> 127.0.0.1:6650]
2022-03-24 18:40:25.110 INFO [0x700007665000] HandlerBase:64 | [persistent://public/default/testReferenceLeak, reader-45b5d48620, 0] Getting connection from pool
2022-03-24 18:40:25.111 INFO [0x700007665000] ConsumerImpl:224 | [persistent://public/default/testReferenceLeak, reader-45b5d48620, 0] Created consumer on broker [127.0.0.1:54854 -> 127.0.0.1:6650]
2022-03-24 18:40:25.111 INFO [0x11d86e600] ReaderTest:447 | 1 consumer use count 1
2022-03-24 18:40:25.111 INFO [0x11d86e600] ReaderTest:448 | 1 reader use count 0
2022-03-24 18:40:25.116 INFO [0x11d86e600] ProducerImpl:686 | [persistent://public/default/testReferenceLeak, standalone-0-344] Closing producer for topic persistent://public/default/testReferenceLeak
2022-03-24 18:40:25.118 INFO [0x700007665000] ProducerImpl:729 | [persistent://public/default/testReferenceLeak, standalone-0-344] Closed producer
2022-03-24 18:40:25.118 INFO [0x11d86e600] ConsumerImpl:999 | [persistent://public/default/testReferenceLeak, reader-45b5d48620, 0] Closing consumer for topic persistent://public/default/testReferenceLeak
2022-03-24 18:40:25.118 INFO [0x700007665000] ConsumerImpl:1055 | [persistent://public/default/testReferenceLeak, reader-45b5d48620, 0] Closed consumer 0
/Users/xuyunze/github.com/BewareMyPower/pulsar/pulsar-client-cpp/tests/ReaderTest.cc:463: Failure
Expected equality of these values:
1
readerPtr.use_count()
Which is: 0
2022-03-24 18:40:25.119 INFO [0x11d86e600] ProducerImpl:653 | Producer - [persistent://public/default/testReferenceLeak, standalone-0-344] , [batchMessageContainer = { BatchMessageContainer [size = 0] [bytes = 0] [maxSize = 1000] [maxBytes = 131072] [topicName = persistent://public/default/testReferenceLeak] [numberOfBatchesSent_ = 11] [averageBatchSize_ = 0.909091] }]
2022-03-24 18:40:25.119 INFO [0x11d86e600] ClientConnection:1559 | [127.0.0.1:54854 -> 127.0.0.1:6650] Connection closed
2022-03-24 18:40:25.119 INFO [0x11d86e600] ClientConnection:263 | [127.0.0.1:54854 -> 127.0.0.1:6650] Destroyed connection
```
```
2022-03-24 18:40:34.076 INFO [0x1141e3600] ClientConnection:189 | [<none> -> pulsar://localhost:6650] Create ClientConnection, timeout=10000
2022-03-24 18:40:34.076 INFO [0x1141e3600] ConnectionPool:96 | Created connection for pulsar://localhost:6650
2022-03-24 18:40:34.076 INFO [0x70000a907000] ClientConnection:375 | [127.0.0.1:54945 -> 127.0.0.1:6650] Connected to broker
2022-03-24 18:40:34.077 INFO [0x70000a907000] HandlerBase:64 | [persistent://public/default/testReferenceLeak, ] Getting connection from pool
2022-03-24 18:40:34.078 INFO [0x70000a907000] ProducerImpl:189 | [persistent://public/default/testReferenceLeak, ] Created producer on broker [127.0.0.1:54945 -> 127.0.0.1:6650]
2022-03-24 18:40:34.095 INFO [0x70000a907000] HandlerBase:64 | [persistent://public/default/testReferenceLeak, reader-4b66d62984, 0] Getting connection from pool
2022-03-24 18:40:34.096 INFO [0x70000a907000] ConsumerImpl:224 | [persistent://public/default/testReferenceLeak, reader-4b66d62984, 0] Created consumer on broker [127.0.0.1:54945 -> 127.0.0.1:6650]
2022-03-24 18:40:34.097 INFO [0x1141e3600] ReaderTest:447 | 1 consumer use count 1
2022-03-24 18:40:34.097 INFO [0x1141e3600] ReaderTest:448 | 1 reader use count 1
2022-03-24 18:40:34.101 INFO [0x1141e3600] ProducerImpl:686 | [persistent://public/default/testReferenceLeak, standalone-0-414] Closing producer for topic persistent://public/default/testReferenceLeak
2022-03-24 18:40:34.103 INFO [0x70000a907000] ProducerImpl:729 | [persistent://public/default/testReferenceLeak, standalone-0-414] Closed producer
2022-03-24 18:40:34.103 INFO [0x1141e3600] ConsumerImpl:999 | [persistent://public/default/testReferenceLeak, reader-4b66d62984, 0] Closing consumer for topic persistent://public/default/testReferenceLeak
2022-03-24 18:40:34.104 INFO [0x70000a907000] ConsumerImpl:1055 | [persistent://public/default/testReferenceLeak, reader-4b66d62984, 0] Closed consumer 0
pulsar-client-cpp/tests/ReaderTest.cc:462: Failure
Expected equality of these values:
1
consumerPtr.use_count()
Which is: 2
```
--
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: commits-unsubscribe@pulsar.apache.org
For queries about this service, please contact Infrastructure at:
users@infra.apache.org