See <https://builds.apache.org/job/pulsar-master/614/display/redirect?page=changes>
Changes: [guosijie] Slow consumer prevents offloaded ledger cleanup (#2126) [guosijie] Add authentication to admin proxy (#2056) [mmerli] Use container name in logs (#2139) [github] add flatbuffer option to serialize kinesis-message in KinesisSink ------------------------------------------ [...truncated 1.04 MB...] 2018-07-13 03:19:27.236 INFO HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-listener, my-sub, 0] Getting connection from pool 2018-07-13 03:19:27.417 INFO ConsumerImpl:168 | [persistent://sample/standalone/ns/my-python-topic-listener, my-sub, 0] Created consumer on broker [127.0.0.1:44862 -> 127.0.0.1:8885] 2018-07-13 03:19:27.419 INFO HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-listener, ] Getting connection from pool 2018-07-13 03:19:27.426 INFO ProducerImpl:154 | [persistent://sample/standalone/ns/my-python-topic-listener, ] Created producer on broker [127.0.0.1:44862 -> 127.0.0.1:8885] Got message: Message(prod=standalone-0-81, seq=0, publish_time=1531451967426, payload_size=7, msg_id=(173,0,-1,-1), props={}) Got message: Message(prod=standalone-0-81, seq=1, publish_time=1531451967449, payload_size=7, msg_id=(173,1,-1,-1), props={}) Got message: Message(prod=standalone-0-81, seq=2, publish_time=1531451967457, payload_size=7, msg_id=(173,2,-1,-1), props={}) 2018-07-13 03:19:27.575 INFO ProducerImpl:466 | [persistent://sample/standalone/ns/my-python-topic-listener, standalone-0-81] Closed producer 2018-07-13 03:19:27.575 INFO ConsumerImpl:761 | [persistent://sample/standalone/ns/my-python-topic-listener, my-sub, 0] Closed consumer 0 ...2018-07-13 03:19:27.577 INFO ConnectionPool:63 | Created connection for pulsar://localhost:8885 2018-07-13 03:19:27.578 INFO ClientConnection:279 | [127.0.0.1:44864 -> 127.0.0.1:8885] Connected to broker 2018-07-13 03:19:27.581 INFO HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-producer-consumer, my-sub, 0] Getting connection from pool 2018-07-13 03:19:27.730 INFO ConsumerImpl:168 | [persistent://sample/standalone/ns/my-python-topic-producer-consumer, my-sub, 0] Created consumer on broker [127.0.0.1:44864 -> 127.0.0.1:8885] 2018-07-13 03:19:27.733 INFO HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-producer-consumer, ] Getting connection from pool 2018-07-13 03:19:27.740 INFO ProducerImpl:154 | [persistent://sample/standalone/ns/my-python-topic-producer-consumer, ] Created producer on broker [127.0.0.1:44864 -> 127.0.0.1:8885] 2018-07-13 03:19:27.868 INFO ProducerImpl:466 | [persistent://sample/standalone/ns/my-python-topic-producer-consumer, standalone-0-82] Closed producer 2018-07-13 03:19:27.868 INFO ConsumerImpl:761 | [persistent://sample/standalone/ns/my-python-topic-producer-consumer, my-sub, 0] Closed consumer 0 .2018-07-13 03:19:27.908 INFO ConnectionPool:63 | Created connection for pulsar://localhost:8885 2018-07-13 03:19:27.909 INFO ClientConnection:279 | [127.0.0.1:44868 -> 127.0.0.1:8885] Connected to broker 2018-07-13 03:19:27.912 INFO HandlerBase:53 | [persistent://sample/standalone/ns1/my-python-test-producer-deduplication-1531451967.91, my-producer-name] Getting connection from pool 2018-07-13 03:19:28.125 INFO ProducerImpl:154 | [persistent://sample/standalone/ns1/my-python-test-producer-deduplication-1531451967.91, my-producer-name] Created producer on broker [127.0.0.1:44868 -> 127.0.0.1:8885] 2018-07-13 03:19:28.127 INFO HandlerBase:53 | [persistent://sample/standalone/ns1/my-python-test-producer-deduplication-1531451967.91, my-sub, 0] Getting connection from pool 2018-07-13 03:19:28.191 INFO ConsumerImpl:168 | [persistent://sample/standalone/ns1/my-python-test-producer-deduplication-1531451967.91, my-sub, 0] Created consumer on broker [127.0.0.1:44868 -> 127.0.0.1:8885] 2018-07-13 03:19:29.251 INFO ProducerImpl:466 | [persistent://sample/standalone/ns1/my-python-test-producer-deduplication-1531451967.91, my-producer-name] Closed producer 2018-07-13 03:19:29.253 INFO HandlerBase:53 | [persistent://sample/standalone/ns1/my-python-test-producer-deduplication-1531451967.91, my-producer-name] Getting connection from pool 2018-07-13 03:19:29.255 INFO ProducerImpl:154 | [persistent://sample/standalone/ns1/my-python-test-producer-deduplication-1531451967.91, my-producer-name] Created producer on broker [127.0.0.1:44868 -> 127.0.0.1:8885] .2018-07-13 03:19:30.257 INFO ConnectionPool:63 | Created connection for pulsar://localhost:8885 2018-07-13 03:19:30.257 INFO ClientConnection:279 | [127.0.0.1:44870 -> 127.0.0.1:8885] Connected to broker 2018-07-13 03:19:30.261 INFO HandlerBase:53 | [persistent://public/default/my-python-test-producer, ] Getting connection from pool 2018-07-13 03:19:30.314 INFO ProducerImpl:154 | [persistent://public/default/my-python-test-producer, ] Created producer on broker [127.0.0.1:44870 -> 127.0.0.1:8885] 2018-07-13 03:19:30.342 INFO ProducerImpl:466 | [persistent://public/default/my-python-test-producer, standalone-0-83] Closed producer .2018-07-13 03:19:30.342 INFO ConnectionPool:63 | Created connection for pulsar://localhost:8885 2018-07-13 03:19:30.343 INFO ClientConnection:279 | [127.0.0.1:44872 -> 127.0.0.1:8885] Connected to broker 2018-07-13 03:19:30.346 INFO HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic, ] Getting connection from pool 2018-07-13 03:19:30.403 INFO ProducerImpl:154 | [persistent://sample/standalone/ns/my-python-topic, ] Created producer on broker [127.0.0.1:44872 -> 127.0.0.1:8885] 2018-07-13 03:19:30.506 INFO ProducerImpl:466 | [persistent://sample/standalone/ns/my-python-topic, standalone-0-84] Closed producer .2018-07-13 03:19:30.533 INFO ConnectionPool:63 | Created connection for pulsar://localhost:8885 2018-07-13 03:19:30.533 INFO ClientConnection:279 | [127.0.0.1:44876 -> 127.0.0.1:8885] Connected to broker 2018-07-13 03:19:30.536 INFO HandlerBase:53 | [persistent://sample/standalone/ns1/my-python-test-producer-sequence-after-reconnection-1531451970.53, my-producer-name] Getting connection from pool 2018-07-13 03:19:30.674 INFO ProducerImpl:154 | [persistent://sample/standalone/ns1/my-python-test-producer-sequence-after-reconnection-1531451970.53, my-producer-name] Created producer on broker [127.0.0.1:44876 -> 127.0.0.1:8885] 2018-07-13 03:19:30.776 INFO ProducerImpl:466 | [persistent://sample/standalone/ns1/my-python-test-producer-sequence-after-reconnection-1531451970.53, my-producer-name] Closed producer 2018-07-13 03:19:30.777 INFO HandlerBase:53 | [persistent://sample/standalone/ns1/my-python-test-producer-sequence-after-reconnection-1531451970.53, my-producer-name] Getting connection from pool 2018-07-13 03:19:30.779 INFO ProducerImpl:154 | [persistent://sample/standalone/ns1/my-python-test-producer-sequence-after-reconnection-1531451970.53, my-producer-name] Created producer on broker [127.0.0.1:44876 -> 127.0.0.1:8885] .2018-07-13 03:19:30.866 INFO ConnectionPool:63 | Created connection for pulsar://localhost:8885 2018-07-13 03:19:30.866 INFO ClientConnection:279 | [127.0.0.1:44878 -> 127.0.0.1:8885] Connected to broker 2018-07-13 03:19:30.869 INFO HandlerBase:53 | [persistent://sample/standalone/ns1/my-python-test_publish_compact_and_consume, my-producer-name] Getting connection from pool 2018-07-13 03:19:30.983 INFO ProducerImpl:154 | [persistent://sample/standalone/ns1/my-python-test_publish_compact_and_consume, my-producer-name] Created producer on broker [127.0.0.1:44878 -> 127.0.0.1:8885] 2018-07-13 03:19:30.984 INFO HandlerBase:53 | [persistent://sample/standalone/ns1/my-python-test_publish_compact_and_consume, my-sub1, 0] Getting connection from pool 2018-07-13 03:19:31.041 INFO ConsumerImpl:168 | [persistent://sample/standalone/ns1/my-python-test_publish_compact_and_consume, my-sub1, 0] Created consumer on broker [127.0.0.1:44878 -> 127.0.0.1:8885] 2018-07-13 03:19:31.043 INFO ConsumerImpl:761 | [persistent://sample/standalone/ns1/my-python-test_publish_compact_and_consume, my-sub1, 0] Closed consumer 0 2018-07-13 03:19:31.045 INFO HandlerBase:53 | [persistent://sample/standalone/ns1/my-python-test_publish_compact_and_consume, my-sub2, 1] Getting connection from pool 2018-07-13 03:19:31.124 INFO ConsumerImpl:168 | [persistent://sample/standalone/ns1/my-python-test_publish_compact_and_consume, my-sub2, 1] Created consumer on broker [127.0.0.1:44878 -> 127.0.0.1:8885] 2018-07-13 03:19:31.159 INFO ProducerImpl:466 | [persistent://sample/standalone/ns1/my-python-test_publish_compact_and_consume, my-producer-name] Closed producer 2018-07-13 03:19:31.159 WARN ConsumerImpl:97 | [persistent://sample/standalone/ns1/my-python-test-producer-deduplication-1531451967.91, my-sub, 0] Destroyed consumer which was not properly closed Compact still running {"status":"RUNNING","lastError":""} Compact still running {"status":"RUNNING","lastError":""} Compact still running {"status":"RUNNING","lastError":""} Compact Complete now {"status":"SUCCESS","lastError":""} 2018-07-13 03:19:31.984 INFO HandlerBase:53 | [persistent://sample/standalone/ns1/my-python-test_publish_compact_and_consume, my-sub1, 2] Getting connection from pool 2018-07-13 03:19:31.986 INFO ConsumerImpl:168 | [persistent://sample/standalone/ns1/my-python-test_publish_compact_and_consume, my-sub1, 2] Created consumer on broker [127.0.0.1:44878 -> 127.0.0.1:8885] 2018-07-13 03:19:32.034 INFO ConsumerImpl:761 | [persistent://sample/standalone/ns1/my-python-test_publish_compact_and_consume, my-sub1, 2] Closed consumer 2 2018-07-13 03:19:32.035 INFO ConsumerImpl:761 | [persistent://sample/standalone/ns1/my-python-test_publish_compact_and_consume, my-sub2, 1] Closed consumer 1 .2018-07-13 03:19:32.036 INFO ConnectionPool:63 | Created connection for pulsar://localhost:8885 2018-07-13 03:19:32.036 INFO ClientConnection:279 | [127.0.0.1:44892 -> 127.0.0.1:8885] Connected to broker 2018-07-13 03:19:32.039 INFO HandlerBase:53 | [persistent://sample/standalone/ns1/my-python-test-producer, reader-cc1e16, 0] Getting connection from pool 2018-07-13 03:19:32.041 INFO ConsumerImpl:168 | [persistent://sample/standalone/ns1/my-python-test-producer, reader-cc1e16, 0] Created consumer on broker [127.0.0.1:44892 -> 127.0.0.1:8885] 2018-07-13 03:19:32.042 INFO ConsumerImpl:761 | [persistent://sample/standalone/ns1/my-python-test-producer, reader-cc1e16, 0] Closed consumer 0 .2018-07-13 03:19:32.043 INFO ConnectionPool:63 | Created connection for pulsar://localhost:8885 2018-07-13 03:19:32.043 INFO ClientConnection:279 | [127.0.0.1:44894 -> 127.0.0.1:8885] Connected to broker 2018-07-13 03:19:32.046 INFO HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-reader-has-message-available, ] Getting connection from pool 2018-07-13 03:19:32.137 INFO ProducerImpl:154 | [persistent://sample/standalone/ns/my-python-topic-reader-has-message-available, ] Created producer on broker [127.0.0.1:44894 -> 127.0.0.1:8885] 2018-07-13 03:19:32.139 INFO HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-reader-has-message-available, reader-36d17d, 0] Getting connection from pool 2018-07-13 03:19:32.143 INFO ConsumerImpl:168 | [persistent://sample/standalone/ns/my-python-topic-reader-has-message-available, reader-36d17d, 0] Created consumer on broker [127.0.0.1:44894 -> 127.0.0.1:8885] 2018-07-13 03:19:32.350 INFO ConsumerImpl:761 | [persistent://sample/standalone/ns/my-python-topic-reader-has-message-available, reader-36d17d, 0] Closed consumer 0 2018-07-13 03:19:32.352 INFO ProducerImpl:466 | [persistent://sample/standalone/ns/my-python-topic-reader-has-message-available, standalone-0-85] Closed producer .2018-07-13 03:19:32.353 INFO ConnectionPool:63 | Created connection for pulsar://localhost:8885 2018-07-13 03:19:32.353 INFO ClientConnection:279 | [127.0.0.1:44896 -> 127.0.0.1:8885] Connected to broker 2018-07-13 03:19:32.356 INFO HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-reader-on-last-message, ] Getting connection from pool 2018-07-13 03:19:32.412 INFO ProducerImpl:154 | [persistent://sample/standalone/ns/my-python-topic-reader-on-last-message, ] Created producer on broker [127.0.0.1:44896 -> 127.0.0.1:8885] 2018-07-13 03:19:32.517 INFO HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-reader-on-last-message, reader-ea5c42, 0] Getting connection from pool 2018-07-13 03:19:32.521 INFO ConsumerImpl:168 | [persistent://sample/standalone/ns/my-python-topic-reader-on-last-message, reader-ea5c42, 0] Created consumer on broker [127.0.0.1:44896 -> 127.0.0.1:8885] 2018-07-13 03:19:32.615 INFO ConsumerImpl:761 | [persistent://sample/standalone/ns/my-python-topic-reader-on-last-message, reader-ea5c42, 0] Closed consumer 0 2018-07-13 03:19:32.618 INFO ProducerImpl:466 | [persistent://sample/standalone/ns/my-python-topic-reader-on-last-message, standalone-0-86] Closed producer .2018-07-13 03:19:32.618 INFO ConnectionPool:63 | Created connection for pulsar://localhost:8885 2018-07-13 03:19:32.619 INFO ClientConnection:279 | [127.0.0.1:44898 -> 127.0.0.1:8885] Connected to broker 2018-07-13 03:19:32.622 INFO HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message, ] Getting connection from pool 2018-07-13 03:19:32.694 INFO ProducerImpl:154 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message, ] Created producer on broker [127.0.0.1:44898 -> 127.0.0.1:8885] 2018-07-13 03:19:32.792 INFO HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message, reader-b2d5f7, 0] Getting connection from pool 2018-07-13 03:19:32.796 INFO ConsumerImpl:168 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message, reader-b2d5f7, 0] Created consumer on broker [127.0.0.1:44898 -> 127.0.0.1:8885] 2018-07-13 03:19:32.804 INFO HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message, reader-8677ca, 1] Getting connection from pool 2018-07-13 03:19:32.807 INFO ConsumerImpl:168 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message, reader-8677ca, 1] Created consumer on broker [127.0.0.1:44898 -> 127.0.0.1:8885] 2018-07-13 03:19:32.812 INFO ConsumerImpl:761 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message, reader-b2d5f7, 0] Closed consumer 0 2018-07-13 03:19:32.812 INFO ConsumerImpl:761 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message, reader-8677ca, 1] Closed consumer 1 2018-07-13 03:19:32.814 INFO ProducerImpl:466 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message, standalone-0-87] Closed producer .2018-07-13 03:19:32.815 INFO ConnectionPool:63 | Created connection for pulsar://localhost:8885 2018-07-13 03:19:32.816 INFO ClientConnection:279 | [127.0.0.1:44900 -> 127.0.0.1:8885] Connected to broker 2018-07-13 03:19:32.819 INFO BatchMessageContainer:41 | { BatchContainer [size = 0] [batchSizeInBytes_ = 0] [maxAllowedMessageBatchSizeInBytes_ = 131072] [maxAllowedNumMessagesInBatch_ = 1000] [topicName = persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message-with-batches] [producerName_ = ] [batchSizeInBytes_ = 0] [numberOfBatchesSent = 0] [averageBatchSize = 0]} BatchMessageContainer constructed 2018-07-13 03:19:32.820 INFO HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message-with-batches, ] Getting connection from pool 2018-07-13 03:19:32.879 INFO ProducerImpl:154 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message-with-batches, ] Created producer on broker [127.0.0.1:44900 -> 127.0.0.1:8885] 2018-07-13 03:19:33.909 INFO HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message-with-batches, reader-504094, 0] Getting connection from pool 2018-07-13 03:19:33.912 INFO ConsumerImpl:168 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message-with-batches, reader-504094, 0] Created consumer on broker [127.0.0.1:44900 -> 127.0.0.1:8885] 2018-07-13 03:19:33.918 INFO HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message-with-batches, reader-604ec8, 1] Getting connection from pool 2018-07-13 03:19:33.921 INFO ConsumerImpl:168 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message-with-batches, reader-604ec8, 1] Created consumer on broker [127.0.0.1:44900 -> 127.0.0.1:8885] 2018-07-13 03:19:33.924 INFO ConsumerImpl:761 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message-with-batches, reader-504094, 0] Closed consumer 0 2018-07-13 03:19:33.925 INFO ConsumerImpl:761 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message-with-batches, reader-604ec8, 1] Closed consumer 1 2018-07-13 03:19:33.927 INFO ProducerImpl:466 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message-with-batches, standalone-0-88] Closed producer .2018-07-13 03:19:33.928 INFO ConnectionPool:63 | Created connection for pulsar://localhost:8885 2018-07-13 03:19:33.929 INFO ClientConnection:279 | [127.0.0.1:44902 -> 127.0.0.1:8885] Connected to broker 2018-07-13 03:19:33.932 INFO HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-reader-simple, reader-e80ca0, 0] Getting connection from pool 2018-07-13 03:19:33.986 INFO ConsumerImpl:168 | [persistent://sample/standalone/ns/my-python-topic-reader-simple, reader-e80ca0, 0] Created consumer on broker [127.0.0.1:44902 -> 127.0.0.1:8885] 2018-07-13 03:19:33.987 INFO HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-reader-simple, ] Getting connection from pool 2018-07-13 03:19:33.993 INFO ProducerImpl:154 | [persistent://sample/standalone/ns/my-python-topic-reader-simple, ] Created producer on broker [127.0.0.1:44902 -> 127.0.0.1:8885] 2018-07-13 03:19:34.134 INFO ConsumerImpl:761 | [persistent://sample/standalone/ns/my-python-topic-reader-simple, reader-e80ca0, 0] Closed consumer 0 2018-07-13 03:19:34.135 INFO ProducerImpl:466 | [persistent://sample/standalone/ns/my-python-topic-reader-simple, standalone-0-89] Closed producer .2018-07-13 03:19:34.135 INFO ConnectionPool:63 | Created connection for pulsar://localhost:8885 2018-07-13 03:19:34.136 INFO ClientConnection:279 | [127.0.0.1:44904 -> 127.0.0.1:8885] Connected to broker 2018-07-13 03:19:34.139 INFO HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-seek, my-sub, 0] Getting connection from pool 2018-07-13 03:19:34.275 INFO ConsumerImpl:168 | [persistent://sample/standalone/ns/my-python-topic-seek, my-sub, 0] Created consumer on broker [127.0.0.1:44904 -> 127.0.0.1:8885] 2018-07-13 03:19:34.278 INFO HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-seek, ] Getting connection from pool 2018-07-13 03:19:34.281 INFO ProducerImpl:154 | [persistent://sample/standalone/ns/my-python-topic-seek, ] Created producer on broker [127.0.0.1:44904 -> 127.0.0.1:8885] 2018-07-13 03:19:35.233 INFO HandlerBase:129 | [persistent://sample/standalone/ns/my-python-topic-seek, my-sub, 0] Schedule reconnection in 0.1 s 2018-07-13 03:19:35.240 INFO ConsumerImpl:893 | [persistent://sample/standalone/ns/my-python-topic-seek, my-sub, 0] Seek successfully 2018-07-13 03:19:35.333 INFO HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-seek, my-sub, 0] Getting connection from pool 2018-07-13 03:19:35.339 INFO ConsumerImpl:168 | [persistent://sample/standalone/ns/my-python-topic-seek, my-sub, 0] Created consumer on broker [127.0.0.1:44904 -> 127.0.0.1:8885] 2018-07-13 03:19:35.742 INFO ProducerImpl:466 | [persistent://sample/standalone/ns/my-python-topic-seek, standalone-0-90] Closed producer 2018-07-13 03:19:35.742 INFO ConsumerImpl:761 | [persistent://sample/standalone/ns/my-python-topic-seek, my-sub, 0] Closed consumer 0 .2018-07-13 03:19:35.743 INFO ConnectionPool:63 | Created connection for pulsar://localhost:8885 2018-07-13 03:19:35.744 INFO ClientConnection:279 | [127.0.0.1:44914 -> 127.0.0.1:8885] Connected to broker 2018-07-13 03:19:35.747 INFO HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic, ] Getting connection from pool 2018-07-13 03:19:35.753 INFO ProducerImpl:154 | [persistent://sample/standalone/ns/my-python-topic, ] Created producer on broker [127.0.0.1:44914 -> 127.0.0.1:8885] 2018-07-13 03:19:35.774 INFO ProducerImpl:466 | [persistent://sample/standalone/ns/my-python-topic, standalone-0-91] Closed producer .2018-07-13 03:19:35.776 INFO ConnectionPool:63 | Created connection for pulsar+ssl://localhost:9886 2018-07-13 03:19:35.776 INFO ClientConnection:279 | [127.0.0.1:43266 -> 127.0.0.1:9886] Connected to broker 2018-07-13 03:19:35.815 INFO HandlerBase:53 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, my-sub, 0] Getting connection from pool 2018-07-13 03:19:35.818 INFO ConnectionPool:63 | Created connection for pulsar://localhost:9886 2018-07-13 03:19:35.819 INFO ClientConnection:281 | [127.0.0.1:43268 -> 127.0.0.1:9886] Connected to broker through proxy. Logical broker: pulsar://localhost:9886 2018-07-13 03:19:36.064 INFO ConsumerImpl:168 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, my-sub, 0] Created consumer on broker [127.0.0.1:43268 -> 127.0.0.1:9886] 2018-07-13 03:19:36.069 INFO HandlerBase:53 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, ] Getting connection from pool 2018-07-13 03:19:36.081 INFO ProducerImpl:154 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, ] Created producer on broker [127.0.0.1:43268 -> 127.0.0.1:9886] 2018-07-13 03:19:36.211 INFO ProducerImpl:466 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, cluster-0-1] Closed producer 2018-07-13 03:19:36.214 INFO ConsumerImpl:761 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, my-sub, 0] Closed consumer 0 .2018-07-13 03:19:36.215 INFO ConnectionPool:63 | Created connection for pulsar://localhost:8885 2018-07-13 03:19:36.216 INFO ClientConnection:279 | [127.0.0.1:44920 -> 127.0.0.1:8885] Connected to broker 2018-07-13 03:19:36.219 INFO HandlerBase:53 | [persistent://public/default/my-v2-topic-producer-consumer, my-sub, 0] Getting connection from pool 2018-07-13 03:19:36.383 INFO ConsumerImpl:168 | [persistent://public/default/my-v2-topic-producer-consumer, my-sub, 0] Created consumer on broker [127.0.0.1:44920 -> 127.0.0.1:8885] 2018-07-13 03:19:36.386 INFO HandlerBase:53 | [persistent://public/default/my-v2-topic-producer-consumer, ] Getting connection from pool 2018-07-13 03:19:36.390 INFO ProducerImpl:154 | [persistent://public/default/my-v2-topic-producer-consumer, ] Created producer on broker [127.0.0.1:44920 -> 127.0.0.1:8885] 2018-07-13 03:19:36.517 INFO ProducerImpl:466 | [persistent://public/default/my-v2-topic-producer-consumer, standalone-0-92] Closed producer 2018-07-13 03:19:36.518 INFO ConsumerImpl:761 | [persistent://public/default/my-v2-topic-producer-consumer, my-sub, 0] Closed consumer 0 .2018-07-13 03:19:36.519 INFO HTTPLookupService:161 | Curl Lookup Request sent forhttp://localhost:8765/admin/v2/persistent/public/default/my-v2-topic-producer-consumer/partitions 2018-07-13 03:19:36.531 INFO HTTPLookupService:173 | Response received for url http://localhost:8765/admin/v2/persistent/public/default/my-v2-topic-producer-consumer/partitions code 200 2018-07-13 03:19:36.531 INFO HTTPLookupService:214 | parsePartitionData = { LookupDataResult [brokerUrl_ = ] [brokerUrlSsl_ = ] [partitions = 0] [authoritative = 0] [redirect = 0] proxyThroughServiceUrl = 0] } 2018-07-13 03:19:36.532 INFO HandlerBase:53 | [persistent://public/default/my-v2-topic-producer-consumer, my-sub, 0] Getting connection from pool 2018-07-13 03:19:36.532 INFO HTTPLookupService:161 | Curl Lookup Request sent forhttp://localhost:8765/lookup/v2/topic/persistent/public/default/my-v2-topic-producer-consumer 2018-07-13 03:19:36.541 INFO HTTPLookupService:173 | Response received for url http://localhost:8765/lookup/v2/topic/persistent/public/default/my-v2-topic-producer-consumer code 200 2018-07-13 03:19:36.541 INFO HTTPLookupService:243 | parseLookupData = { LookupDataResult [brokerUrl_ = pulsar://localhost:8885] [brokerUrlSsl_ = ] [partitions = 0] [authoritative = 0] [redirect = 0] proxyThroughServiceUrl = 0] } 2018-07-13 03:19:36.541 INFO ConnectionPool:63 | Created connection for pulsar://localhost:8885 2018-07-13 03:19:36.542 INFO ClientConnection:279 | [127.0.0.1:44926 -> 127.0.0.1:8885] Connected to broker 2018-07-13 03:19:36.545 INFO ConsumerImpl:168 | [persistent://public/default/my-v2-topic-producer-consumer, my-sub, 0] Created consumer on broker [127.0.0.1:44926 -> 127.0.0.1:8885] 2018-07-13 03:19:36.545 INFO HTTPLookupService:161 | Curl Lookup Request sent forhttp://localhost:8765/admin/v2/persistent/public/default/my-v2-topic-producer-consumer/partitions 2018-07-13 03:19:36.554 INFO HTTPLookupService:173 | Response received for url http://localhost:8765/admin/v2/persistent/public/default/my-v2-topic-producer-consumer/partitions code 200 2018-07-13 03:19:36.554 INFO HTTPLookupService:214 | parsePartitionData = { LookupDataResult [brokerUrl_ = ] [brokerUrlSsl_ = ] [partitions = 0] [authoritative = 0] [redirect = 0] proxyThroughServiceUrl = 0] } 2018-07-13 03:19:36.554 INFO HandlerBase:53 | [persistent://public/default/my-v2-topic-producer-consumer, ] Getting connection from pool 2018-07-13 03:19:36.555 INFO HTTPLookupService:161 | Curl Lookup Request sent forhttp://localhost:8765/lookup/v2/topic/persistent/public/default/my-v2-topic-producer-consumer 2018-07-13 03:19:36.562 INFO HTTPLookupService:173 | Response received for url http://localhost:8765/lookup/v2/topic/persistent/public/default/my-v2-topic-producer-consumer code 200 2018-07-13 03:19:36.563 INFO HTTPLookupService:243 | parseLookupData = { LookupDataResult [brokerUrl_ = pulsar://localhost:8885] [brokerUrlSsl_ = ] [partitions = 0] [authoritative = 0] [redirect = 0] proxyThroughServiceUrl = 0] } 2018-07-13 03:19:36.564 INFO ProducerImpl:154 | [persistent://public/default/my-v2-topic-producer-consumer, ] Created producer on broker [127.0.0.1:44926 -> 127.0.0.1:8885] 2018-07-13 03:19:36.685 INFO ProducerImpl:466 | [persistent://public/default/my-v2-topic-producer-consumer, standalone-0-93] Closed producer 2018-07-13 03:19:36.686 INFO ConsumerImpl:761 | [persistent://public/default/my-v2-topic-producer-consumer, my-sub, 0] Closed consumer 0 . ---------------------------------------------------------------------- Ran 24 tests in 9.819s OK /pulsar/pulsar-client-cpp/python /pulsar/pulsar-client-cpp /pulsar/pulsar-client-cpp [pulsar-master] $ /bin/bash -xe /tmp/jenkins2047474591866970674.sh + echo 'Building Docker images' Building Docker images + docker/build.sh [INFO] Scanning for projects... [WARNING] [WARNING] Some problems were encountered while building the effective model for org.apache.pulsar:docker-images:pom:2.2.0-incubating-SNAPSHOT [WARNING] 'dependencyManagement.dependencies.dependency.(groupId:artifactId:type:classifier)' must be unique: commons-io:commons-io:jar -> duplicate declaration of version 2.5 @ org.apache.pulsar:pulsar:2.2.0-incubating-SNAPSHOT, <https://builds.apache.org/job/pulsar-master/ws/pom.xml,> line 481, column 19 [WARNING] [WARNING] It is highly recommended to fix these problems because they threaten the stability of your build. [WARNING] [WARNING] For this reason, future Maven versions might no longer support building such malformed projects. [WARNING] [INFO] ------------------------------------------------------------------------ [INFO] Reactor Build Order: [INFO] [INFO] Apache Pulsar :: Docker Images [INFO] Apache Pulsar :: Docker Images :: Pulsar Latest Version [INFO] Apache Pulsar :: Docker Images :: Grafana [INFO] [INFO] ------------------------------------------------------------------------ [INFO] Building Apache Pulsar :: Docker Images 2.2.0-incubating-SNAPSHOT [INFO] ------------------------------------------------------------------------ Downloading: http://repository.apache.org/snapshots/org/apache/pulsar/buildtools/2.2.0-incubating-SNAPSHOT/maven-metadata.xml Downloading: https://repo1.maven.org/maven2/org/apache/pulsar/buildtools/2.2.0-incubating-SNAPSHOT/maven-metadata.xml Progress (1): 813 B Downloaded: http://repository.apache.org/snapshots/org/apache/pulsar/buildtools/2.2.0-incubating-SNAPSHOT/maven-metadata.xml (813 B at 1.4 kB/s) Downloading: https://repo1.maven.org/maven2/org/apache/pulsar/buildtools/2.2.0-incubating-SNAPSHOT/buildtools-2.2.0-incubating-20180712.124143-16.pom Downloading: http://repository.apache.org/snapshots/org/apache/pulsar/buildtools/2.2.0-incubating-SNAPSHOT/buildtools-2.2.0-incubating-20180712.124143-16.pom [WARNING] The POM for org.apache.pulsar:buildtools:jar:2.2.0-incubating-20180712.124143-16 is missing, no dependency information available Downloading: http://repository.apache.org/snapshots/org/apache/pulsar/buildtools/2.2.0-incubating-SNAPSHOT/buildtools-2.2.0-incubating-20180712.124143-16.jar [INFO] ------------------------------------------------------------------------ [INFO] Reactor Summary: [INFO] [INFO] Apache Pulsar :: Docker Images ..................... FAILURE [ 2.029 s] [INFO] Apache Pulsar :: Docker Images :: Pulsar Latest Version SKIPPED [INFO] Apache Pulsar :: Docker Images :: Grafana .......... SKIPPED [INFO] ------------------------------------------------------------------------ [INFO] BUILD FAILURE [INFO] ------------------------------------------------------------------------ [INFO] Total time: 2.586 s [INFO] Finished at: 2018-07-13T03:19:42Z [INFO] Final Memory: 23M/1159M [INFO] ------------------------------------------------------------------------ [ERROR] Failed to execute goal on project docker-images: Could not resolve dependencies for project org.apache.pulsar:docker-images:pom:2.2.0-incubating-SNAPSHOT: Could not find artifact org.apache.pulsar:buildtools:jar:2.2.0-incubating-20180712.124143-16 in Nexus (http://repository.apache.org/snapshots) -> [Help 1] [ERROR] [ERROR] To see the full stack trace of the errors, re-run Maven with the -e switch. [ERROR] Re-run Maven using the -X switch to enable full debug logging. [ERROR] [ERROR] For more information about the errors and possible solutions, please read the following articles: [ERROR] [Help 1] http://cwiki.apache.org/confluence/display/MAVEN/DependencyResolutionException [INFO] Scanning for projects... [ERROR] [ERROR] Some problems were encountered while processing the POMs: [FATAL] Non-resolvable parent POM for org.apache.pulsar:dashboard-docker-image:[unknown-version]: Could not find artifact org.apache.pulsar:docker-images:pom:2.1.0-incubating-SNAPSHOT and 'parent.relativePath' points at wrong local POM @ line 23, column 11 @ [ERROR] The build could not read 1 project -> [Help 1] [ERROR] [ERROR] The project org.apache.pulsar:dashboard-docker-image:[unknown-version] (<https://builds.apache.org/job/pulsar-master/ws/docker/../dashboard/pom.xml)> has 1 error [ERROR] Non-resolvable parent POM for org.apache.pulsar:dashboard-docker-image:[unknown-version]: Could not find artifact org.apache.pulsar:docker-images:pom:2.1.0-incubating-SNAPSHOT and 'parent.relativePath' points at wrong local POM @ line 23, column 11 -> [Help 2] [ERROR] [ERROR] To see the full stack trace of the errors, re-run Maven with the -e switch. [ERROR] Re-run Maven using the -X switch to enable full debug logging. [ERROR] [ERROR] For more information about the errors and possible solutions, please read the following articles: [ERROR] [Help 1] http://cwiki.apache.org/confluence/display/MAVEN/ProjectBuildingException [ERROR] [Help 2] http://cwiki.apache.org/confluence/display/MAVEN/UnresolvableModelException Build step 'Execute shell' marked build as failure Not sending mail to unregistered user git...@alasdairhodge.co.uk Not sending mail to unregistered user guosi...@gmail.com Not sending mail to unregistered user y.shiga.91+yush...@gmail.com