KryukovaLidiya opened a new issue #13250: URL: https://github.com/apache/pulsar/issues/13250
**Describe the bug** At the end of the test org.apache.pulsar.testclient.PerformanceProducer, the aggregated statistics are printed and the latency prints twice, the second time all the values are unexpected. **To Reproduce** Steps to reproduce the behavior: 1. Run server bin/pulsar standalone & 2. Run test bin/pulsar-perf produce --rate 100000 --warmup-time 60 --test-duration 120 my-topic 3. We are waiting for the end of the program, the duration is 2 minutes - you can reduce it in the parameters above, this does not affect. 4. See error `2021-12-12T12:31:26,853-0800 [main] INFO org.apache.pulsar.testclient.PerformanceProducer - Throughput produced: 952843 msg --- 95275.2 msg/s --- 744.3 Mbit/s --- failure 0.0 msg/s --- Latency: mean: 0.000 ms - med: 0.000 - 95pct: 0.000 - 99pct: 0.000 - 99.9pct: 0.000 - 99.99pct: 0.000 - Max: 0.000 2021-12-12T12:31:36,902-0800 [main] INFO org.apache.pulsar.testclient.PerformanceProducer - Throughput produced: 1960299 msg --- 100010.0 msg/s --- 781.3 Mbit/s --- failure 0.0 msg/s --- Latency: mean: 0.000 ms - med: 0.000 - 95pct: 0.000 - 99pct: 0.000 - 99.9pct: 0.000 - 99.99pct: 0.000 - Max: 0.000 2021-12-12T12:31:46,937-0800 [main] INFO org.apache.pulsar.testclient.PerformanceProducer - Throughput produced: 2963950 msg --- 99993.1 msg/s --- 781.2 Mbit/s --- failure 0.0 msg/s --- Latency: mean: 0.000 ms - med: 0.000 - 95pct: 0.000 - 99pct: 0.000 - 99.9pct: 0.000 - 99.99pct: 0.000 - Max: 0.000 2021-12-12T12:31:56,971-0800 [main] INFO org.apache.pulsar.testclient.PerformanceProducer - Throughput produced: 3967370 msg --- 100007.3 msg/s --- 781.3 Mbit/s --- failure 0.0 msg/s --- Latency: mean: 0.000 ms - med: 0.000 - 95pct: 0.000 - 99pct: 0.000 - 99.9pct: 0.000 - 99.99pct: 0.000 - Max: 0.000 2021-12-12T12:32:07,004-0800 [main] INFO org.apache.pulsar.testclient.PerformanceProducer - Throughput produced: 4970671 msg --- 99991.3 msg/s --- 781.2 Mbit/s --- failure 0.0 msg/s --- Latency: mean: 0.000 ms - med: 0.000 - 95pct: 0.000 - 99pct: 0.000 - 99.9pct: 0.000 - 99.99pct: 0.000 - Max: 0.000 2021-12-12T12:32:17,040-0800 [main] INFO org.apache.pulsar.testclient.PerformanceProducer - Throughput produced: 5974097 msg --- 100009.6 msg/s --- 781.3 Mbit/s --- failure 0.0 msg/s --- Latency: mean: 0.000 ms - med: 0.000 - 95pct: 0.000 - 99pct: 0.000 - 99.9pct: 0.000 - 99.99pct: 0.000 - Max: 0.000 2021-12-12T12:32:27,096-0800 [main] INFO org.apache.pulsar.testclient.PerformanceProducer - Throughput produced: 6977500 msg --- 99988.5 msg/s --- 781.2 Mbit/s --- failure 0.0 msg/s --- Latency: mean: 1.725 ms - med: 1.408 - 95pct: 2.317 - 99pct: 5.145 - 99.9pct: 179.914 - 99.99pct: 188.419 - Max: 188.440 2021-12-12T12:32:37,131-0800 [main] INFO org.apache.pulsar.testclient.PerformanceProducer - Throughput produced: 7985534 msg --- 100002.7 msg/s --- 781.3 Mbit/s --- failure 0.0 msg/s --- Latency: mean: 1.678 ms - med: 1.392 - 95pct: 2.299 - 99pct: 5.424 - 99.9pct: 90.452 - 99.99pct: 95.011 - Max: 95.025 2021-12-12T12:32:47,142-0800 [main] INFO org.apache.pulsar.testclient.PerformanceProducer - Throughput produced: 8986802 msg --- 99999.9 msg/s --- 781.2 Mbit/s --- failure 0.0 msg/s --- Latency: mean: 1.592 ms - med: 1.311 - 95pct: 2.196 - 99pct: 2.412 - 99.9pct: 90.526 - 99.99pct: 189.146 - Max: 189.159 2021-12-12T12:32:57,154-0800 [main] INFO org.apache.pulsar.testclient.PerformanceProducer - Throughput produced: 9987882 msg --- 100002.4 msg/s --- 781.3 Mbit/s --- failure 0.0 msg/s --- Latency: mean: 1.528 ms - med: 1.328 - 95pct: 2.221 - 99pct: 3.164 - 99.9pct: 87.186 - 99.99pct: 91.479 - Max: 91.492 2021-12-12T12:33:07,163-0800 [main] INFO org.apache.pulsar.testclient.PerformanceProducer - Throughput produced: 10989027 msg --- 99999.7 msg/s --- 781.2 Mbit/s --- failure 0.0 msg/s --- Latency: mean: 1.394 ms - med: 1.283 - 95pct: 2.179 - 99pct: 2.243 - 99.9pct: 22.594 - 99.99pct: 93.225 - Max: 93.238 2021-12-12T12:33:17,174-0800 [main] INFO org.apache.pulsar.testclient.PerformanceProducer - Throughput produced: 11990075 msg --- 100006.8 msg/s --- 781.3 Mbit/s --- failure 0.0 msg/s --- Latency: mean: 1.598 ms - med: 1.379 - 95pct: 2.289 - 99pct: 4.895 - 99.9pct: 94.244 - 99.99pct: 100.249 - Max: 100.263 2021-12-12T12:33:17,267-0800 [pulsar-perf-producer-exec-1-1] INFO org.apache.pulsar.testclient.PerformanceProducer - ------------- DONE (reached the maximum duration: [120 seconds] of production) -------------- 2021-12-12T12:33:17,276-0800 [pulsar-perf-producer-exec-1-1] INFO org.apache.pulsar.testclient.PerformanceProducer - Aggregated latency stats --- Latency: mean: 1.585 ms - med: 1.362 - 95pct: 2.255 - 99pct: 3.630 - 99.9pct: 90.237 - 99.99pct: 186.290 - 99.999pct: 189.151 - Max: 189.159 2021-12-12T12:33:22,277-0800 [Thread-1] INFO org.apache.pulsar.testclient.PerformanceProducer - Aggregated throughput stats --- 12000011 records sent --- 95630.164 msg/s --- 747.111 Mbit/s 2021-12-12T12:33:22,296-0800 [Thread-1] INFO org.apache.pulsar.testclient.PerformanceProducer - Aggregated latency stats --- Latency: mean: 7.397 ms - med: 7.407 - 95pct: 7.413 - 99pct: 7.414 - 99.9pct: 7.416 - 99.99pct: 7.416 - 99.999pct: 7.416 - Max: 7.416` **Expected behavior** Probably we either shouldn't print a second time, or the values should be the same **Desktop (please complete the following information):** Сoffeelake, OS: CentOS Linux 7 (Core) **Additional Information** For the first time, Aggregated latency stats prints here: https://github.com/apache/pulsar/blob/38fb839154462fc5c6b0b4293f02762ed4021cd9/pulsar-testclient/src/main/java/org/apache/pulsar/testclient/PerformanceProducer.java#L671 And the second time it happens here: https://github.com/apache/pulsar/blob/38fb839154462fc5c6b0b4293f02762ed4021cd9/pulsar-testclient/src/main/java/org/apache/pulsar/testclient/PerformanceProducer.java#L409 -- 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-unsubscr...@pulsar.apache.org For queries about this service, please contact Infrastructure at: us...@infra.apache.org
