See <https://builds.apache.org/job/pulsar-master/629/display/redirect?page=changes>
Changes: [mmerli] Inherit I/O streams in functions process runtime (#2178) [github] Add integration test for kafka sink (#2170) [github] Add integration test for kafka source (#2171) [guosijie] Don't dirty the tree when building in CI (#2175) [github] Issue 2121: Improve logging around "Namespace not served by this [mmerli] Issue 1288: Provide documentation for running BookKeeper auto-recovery [github] Moved Record interface as part of functions api (#2184) [guosijie] augmenting protoschema with info for parsing (#2181) [mmerli] Allow construction of c++ builtin auth plugins via factory (#2177) ------------------------------------------ [...truncated 1.06 MB...] 2018-07-18 23:53:13.358 INFO ConsumerImpl:168 | [persistent://sample/standalone/ns1/my-python-test-producer-deduplication-1531957992.76, my-sub, 0] Created consumer on broker [127.0.0.1:45422 -> 127.0.0.1:8885] 2018-07-18 23:53:14.479 INFO ProducerImpl:466 | [persistent://sample/standalone/ns1/my-python-test-producer-deduplication-1531957992.76, my-producer-name] Closed producer 2018-07-18 23:53:14.481 INFO HandlerBase:53 | [persistent://sample/standalone/ns1/my-python-test-producer-deduplication-1531957992.76, my-producer-name] Getting connection from pool 2018-07-18 23:53:14.483 INFO ProducerImpl:154 | [persistent://sample/standalone/ns1/my-python-test-producer-deduplication-1531957992.76, my-producer-name] Created producer on broker [127.0.0.1:45422 -> 127.0.0.1:8885] .2018-07-18 23:53:15.485 INFO ConnectionPool:63 | Created connection for pulsar://localhost:8885 2018-07-18 23:53:15.486 INFO ClientConnection:285 | [127.0.0.1:45424 -> 127.0.0.1:8885] Connected to broker 2018-07-18 23:53:15.489 INFO HandlerBase:53 | [persistent://public/default/my-python-test-producer, ] Getting connection from pool 2018-07-18 23:53:15.628 INFO ProducerImpl:154 | [persistent://public/default/my-python-test-producer, ] Created producer on broker [127.0.0.1:45424 -> 127.0.0.1:8885] 2018-07-18 23:53:15.677 INFO ProducerImpl:466 | [persistent://public/default/my-python-test-producer, standalone-0-83] Closed producer .2018-07-18 23:53:15.678 INFO ConnectionPool:63 | Created connection for pulsar://localhost:8885 2018-07-18 23:53:15.679 INFO ClientConnection:285 | [127.0.0.1:45426 -> 127.0.0.1:8885] Connected to broker 2018-07-18 23:53:15.681 INFO HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic, ] Getting connection from pool 2018-07-18 23:53:15.806 INFO ProducerImpl:154 | [persistent://sample/standalone/ns/my-python-topic, ] Created producer on broker [127.0.0.1:45426 -> 127.0.0.1:8885] 2018-07-18 23:53:15.807 WARN ConsumerImpl:97 | [persistent://sample/standalone/ns1/my-python-test-producer-deduplication-1531957992.76, my-sub, 0] Destroyed consumer which was not properly closed 2018-07-18 23:53:15.908 INFO ProducerImpl:466 | [persistent://sample/standalone/ns/my-python-topic, standalone-0-84] Closed producer .2018-07-18 23:53:15.944 INFO ConnectionPool:63 | Created connection for pulsar://localhost:8885 2018-07-18 23:53:15.945 INFO ClientConnection:285 | [127.0.0.1:45430 -> 127.0.0.1:8885] Connected to broker 2018-07-18 23:53:15.948 INFO HandlerBase:53 | [persistent://sample/standalone/ns1/my-python-test-producer-sequence-after-reconnection-1531957995.94, my-producer-name] Getting connection from pool 2018-07-18 23:53:16.285 INFO ProducerImpl:154 | [persistent://sample/standalone/ns1/my-python-test-producer-sequence-after-reconnection-1531957995.94, my-producer-name] Created producer on broker [127.0.0.1:45430 -> 127.0.0.1:8885] 2018-07-18 23:53:16.603 INFO ProducerImpl:466 | [persistent://sample/standalone/ns1/my-python-test-producer-sequence-after-reconnection-1531957995.94, my-producer-name] Closed producer 2018-07-18 23:53:16.604 INFO HandlerBase:53 | [persistent://sample/standalone/ns1/my-python-test-producer-sequence-after-reconnection-1531957995.94, my-producer-name] Getting connection from pool 2018-07-18 23:53:16.606 INFO ProducerImpl:154 | [persistent://sample/standalone/ns1/my-python-test-producer-sequence-after-reconnection-1531957995.94, my-producer-name] Created producer on broker [127.0.0.1:45430 -> 127.0.0.1:8885] .2018-07-18 23:53:16.919 INFO ConnectionPool:63 | Created connection for pulsar://localhost:8885 2018-07-18 23:53:16.919 INFO ClientConnection:285 | [127.0.0.1:45432 -> 127.0.0.1:8885] Connected to broker 2018-07-18 23:53:16.922 INFO HandlerBase:53 | [persistent://sample/standalone/ns1/my-python-test_publish_compact_and_consume, my-producer-name] Getting connection from pool 2018-07-18 23:53:17.210 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:45432 -> 127.0.0.1:8885] 2018-07-18 23:53:17.212 INFO HandlerBase:53 | [persistent://sample/standalone/ns1/my-python-test_publish_compact_and_consume, my-sub1, 0] Getting connection from pool 2018-07-18 23:53:17.377 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:45432 -> 127.0.0.1:8885] 2018-07-18 23:53:17.379 INFO ConsumerImpl:761 | [persistent://sample/standalone/ns1/my-python-test_publish_compact_and_consume, my-sub1, 0] Closed consumer 0 2018-07-18 23:53:17.381 INFO HandlerBase:53 | [persistent://sample/standalone/ns1/my-python-test_publish_compact_and_consume, my-sub2, 1] Getting connection from pool 2018-07-18 23:53:17.502 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:45432 -> 127.0.0.1:8885] 2018-07-18 23:53:17.594 INFO ProducerImpl:466 | [persistent://sample/standalone/ns1/my-python-test_publish_compact_and_consume, my-producer-name] Closed producer 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-18 23:53:18.421 INFO HandlerBase:53 | [persistent://sample/standalone/ns1/my-python-test_publish_compact_and_consume, my-sub1, 2] Getting connection from pool 2018-07-18 23:53:18.423 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:45432 -> 127.0.0.1:8885] 2018-07-18 23:53:18.469 INFO ConsumerImpl:761 | [persistent://sample/standalone/ns1/my-python-test_publish_compact_and_consume, my-sub1, 2] Closed consumer 2 2018-07-18 23:53:18.470 INFO ConsumerImpl:761 | [persistent://sample/standalone/ns1/my-python-test_publish_compact_and_consume, my-sub2, 1] Closed consumer 1 .2018-07-18 23:53:18.471 INFO ConnectionPool:63 | Created connection for pulsar://localhost:8885 2018-07-18 23:53:18.471 INFO ClientConnection:285 | [127.0.0.1:45454 -> 127.0.0.1:8885] Connected to broker 2018-07-18 23:53:18.473 INFO HandlerBase:53 | [persistent://sample/standalone/ns1/my-python-test-producer, reader-646382, 0] Getting connection from pool 2018-07-18 23:53:18.475 INFO ConsumerImpl:168 | [persistent://sample/standalone/ns1/my-python-test-producer, reader-646382, 0] Created consumer on broker [127.0.0.1:45454 -> 127.0.0.1:8885] 2018-07-18 23:53:18.476 INFO ConsumerImpl:761 | [persistent://sample/standalone/ns1/my-python-test-producer, reader-646382, 0] Closed consumer 0 .2018-07-18 23:53:18.477 INFO ConnectionPool:63 | Created connection for pulsar://localhost:8885 2018-07-18 23:53:18.477 INFO ClientConnection:285 | [127.0.0.1:45456 -> 127.0.0.1:8885] Connected to broker 2018-07-18 23:53:18.481 INFO HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-reader-has-message-available, ] Getting connection from pool 2018-07-18 23:53:18.590 INFO ProducerImpl:154 | [persistent://sample/standalone/ns/my-python-topic-reader-has-message-available, ] Created producer on broker [127.0.0.1:45456 -> 127.0.0.1:8885] 2018-07-18 23:53:18.592 INFO HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-reader-has-message-available, reader-fbc116, 0] Getting connection from pool 2018-07-18 23:53:18.596 INFO ConsumerImpl:168 | [persistent://sample/standalone/ns/my-python-topic-reader-has-message-available, reader-fbc116, 0] Created consumer on broker [127.0.0.1:45456 -> 127.0.0.1:8885] 2018-07-18 23:53:19.278 INFO ConsumerImpl:761 | [persistent://sample/standalone/ns/my-python-topic-reader-has-message-available, reader-fbc116, 0] Closed consumer 0 2018-07-18 23:53:19.279 INFO ProducerImpl:466 | [persistent://sample/standalone/ns/my-python-topic-reader-has-message-available, standalone-0-85] Closed producer .2018-07-18 23:53:19.280 INFO ConnectionPool:63 | Created connection for pulsar://localhost:8885 2018-07-18 23:53:19.281 INFO ClientConnection:285 | [127.0.0.1:45458 -> 127.0.0.1:8885] Connected to broker 2018-07-18 23:53:19.284 INFO HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-reader-on-last-message, ] Getting connection from pool 2018-07-18 23:53:19.423 INFO ProducerImpl:154 | [persistent://sample/standalone/ns/my-python-topic-reader-on-last-message, ] Created producer on broker [127.0.0.1:45458 -> 127.0.0.1:8885] 2018-07-18 23:53:19.770 INFO HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-reader-on-last-message, reader-eacf22, 0] Getting connection from pool 2018-07-18 23:53:19.773 INFO ConsumerImpl:168 | [persistent://sample/standalone/ns/my-python-topic-reader-on-last-message, reader-eacf22, 0] Created consumer on broker [127.0.0.1:45458 -> 127.0.0.1:8885] 2018-07-18 23:53:20.095 INFO ConsumerImpl:761 | [persistent://sample/standalone/ns/my-python-topic-reader-on-last-message, reader-eacf22, 0] Closed consumer 0 2018-07-18 23:53:20.096 INFO ProducerImpl:466 | [persistent://sample/standalone/ns/my-python-topic-reader-on-last-message, standalone-0-86] Closed producer .2018-07-18 23:53:20.097 INFO ConnectionPool:63 | Created connection for pulsar://localhost:8885 2018-07-18 23:53:20.097 INFO ClientConnection:285 | [127.0.0.1:45460 -> 127.0.0.1:8885] Connected to broker 2018-07-18 23:53:20.100 INFO HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message, ] Getting connection from pool 2018-07-18 23:53:20.214 INFO ProducerImpl:154 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message, ] Created producer on broker [127.0.0.1:45460 -> 127.0.0.1:8885] 2018-07-18 23:53:20.562 INFO HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message, reader-5f510d, 0] Getting connection from pool 2018-07-18 23:53:20.565 INFO ConsumerImpl:168 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message, reader-5f510d, 0] Created consumer on broker [127.0.0.1:45460 -> 127.0.0.1:8885] 2018-07-18 23:53:20.570 INFO HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message, reader-06c4d4, 1] Getting connection from pool 2018-07-18 23:53:20.573 INFO ConsumerImpl:168 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message, reader-06c4d4, 1] Created consumer on broker [127.0.0.1:45460 -> 127.0.0.1:8885] 2018-07-18 23:53:20.576 INFO ConsumerImpl:761 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message, reader-5f510d, 0] Closed consumer 0 2018-07-18 23:53:20.577 INFO ConsumerImpl:761 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message, reader-06c4d4, 1] Closed consumer 1 2018-07-18 23:53:20.578 INFO ProducerImpl:466 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message, standalone-0-87] Closed producer .2018-07-18 23:53:20.579 INFO ConnectionPool:63 | Created connection for pulsar://localhost:8885 2018-07-18 23:53:20.579 INFO ClientConnection:285 | [127.0.0.1:45462 -> 127.0.0.1:8885] Connected to broker 2018-07-18 23:53:20.582 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-18 23:53:20.582 INFO HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message-with-batches, ] Getting connection from pool 2018-07-18 23:53:20.731 INFO ProducerImpl:154 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message-with-batches, ] Created producer on broker [127.0.0.1:45462 -> 127.0.0.1:8885] 2018-07-18 23:53:21.771 INFO HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message-with-batches, reader-da0113, 0] Getting connection from pool 2018-07-18 23:53:21.774 INFO ConsumerImpl:168 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message-with-batches, reader-da0113, 0] Created consumer on broker [127.0.0.1:45462 -> 127.0.0.1:8885] 2018-07-18 23:53:21.780 INFO HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message-with-batches, reader-f7a455, 1] Getting connection from pool 2018-07-18 23:53:21.783 INFO ConsumerImpl:168 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message-with-batches, reader-f7a455, 1] Created consumer on broker [127.0.0.1:45462 -> 127.0.0.1:8885] 2018-07-18 23:53:21.786 INFO ConsumerImpl:761 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message-with-batches, reader-da0113, 0] Closed consumer 0 2018-07-18 23:53:21.787 INFO ConsumerImpl:761 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message-with-batches, reader-f7a455, 1] Closed consumer 1 2018-07-18 23:53:21.788 INFO ProducerImpl:466 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message-with-batches, standalone-0-88] Closed producer .2018-07-18 23:53:21.788 INFO ConnectionPool:63 | Created connection for pulsar://localhost:8885 2018-07-18 23:53:21.789 INFO ClientConnection:285 | [127.0.0.1:45464 -> 127.0.0.1:8885] Connected to broker 2018-07-18 23:53:21.792 INFO HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-reader-simple, reader-0afe00, 0] Getting connection from pool 2018-07-18 23:53:21.949 INFO ConsumerImpl:168 | [persistent://sample/standalone/ns/my-python-topic-reader-simple, reader-0afe00, 0] Created consumer on broker [127.0.0.1:45464 -> 127.0.0.1:8885] 2018-07-18 23:53:21.950 INFO HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-reader-simple, ] Getting connection from pool 2018-07-18 23:53:21.956 INFO ProducerImpl:154 | [persistent://sample/standalone/ns/my-python-topic-reader-simple, ] Created producer on broker [127.0.0.1:45464 -> 127.0.0.1:8885] 2018-07-18 23:53:22.097 INFO ConsumerImpl:761 | [persistent://sample/standalone/ns/my-python-topic-reader-simple, reader-0afe00, 0] Closed consumer 0 2018-07-18 23:53:22.098 INFO ProducerImpl:466 | [persistent://sample/standalone/ns/my-python-topic-reader-simple, standalone-0-89] Closed producer .2018-07-18 23:53:22.099 INFO ConnectionPool:63 | Created connection for pulsar://localhost:8885 2018-07-18 23:53:22.099 INFO ClientConnection:285 | [127.0.0.1:45466 -> 127.0.0.1:8885] Connected to broker 2018-07-18 23:53:22.103 INFO HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-seek, my-sub, 0] Getting connection from pool 2018-07-18 23:53:22.437 INFO ConsumerImpl:168 | [persistent://sample/standalone/ns/my-python-topic-seek, my-sub, 0] Created consumer on broker [127.0.0.1:45466 -> 127.0.0.1:8885] 2018-07-18 23:53:22.440 INFO HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-seek, ] Getting connection from pool 2018-07-18 23:53:22.446 INFO ProducerImpl:154 | [persistent://sample/standalone/ns/my-python-topic-seek, ] Created producer on broker [127.0.0.1:45466 -> 127.0.0.1:8885] 2018-07-18 23:53:25.706 INFO HandlerBase:129 | [persistent://sample/standalone/ns/my-python-topic-seek, my-sub, 0] Schedule reconnection in 0.1 s 2018-07-18 23:53:25.743 INFO ConsumerImpl:893 | [persistent://sample/standalone/ns/my-python-topic-seek, my-sub, 0] Seek successfully 2018-07-18 23:53:25.806 INFO HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-seek, my-sub, 0] Getting connection from pool 2018-07-18 23:53:25.811 INFO ConsumerImpl:168 | [persistent://sample/standalone/ns/my-python-topic-seek, my-sub, 0] Created consumer on broker [127.0.0.1:45466 -> 127.0.0.1:8885] 2018-07-18 23:53:26.245 INFO ProducerImpl:466 | [persistent://sample/standalone/ns/my-python-topic-seek, standalone-0-90] Closed producer 2018-07-18 23:53:26.246 INFO ConsumerImpl:761 | [persistent://sample/standalone/ns/my-python-topic-seek, my-sub, 0] Closed consumer 0 .2018-07-18 23:53:26.247 INFO ConnectionPool:63 | Created connection for pulsar://localhost:8885 2018-07-18 23:53:26.248 INFO ClientConnection:285 | [127.0.0.1:45472 -> 127.0.0.1:8885] Connected to broker 2018-07-18 23:53:26.251 INFO HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic, ] Getting connection from pool 2018-07-18 23:53:26.256 INFO ProducerImpl:154 | [persistent://sample/standalone/ns/my-python-topic, ] Created producer on broker [127.0.0.1:45472 -> 127.0.0.1:8885] 2018-07-18 23:53:26.269 INFO ProducerImpl:466 | [persistent://sample/standalone/ns/my-python-topic, standalone-0-91] Closed producer .2018-07-18 23:53:26.270 INFO ConnectionPool:63 | Created connection for pulsar+ssl://localhost:9886 2018-07-18 23:53:26.271 INFO ClientConnection:285 | [127.0.0.1:52106 -> 127.0.0.1:9886] Connected to broker 2018-07-18 23:53:26.308 INFO HandlerBase:53 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, my-sub, 0] Getting connection from pool 2018-07-18 23:53:26.311 INFO ConnectionPool:63 | Created connection for pulsar://localhost:9886 2018-07-18 23:53:26.312 INFO ClientConnection:287 | [127.0.0.1:52108 -> 127.0.0.1:9886] Connected to broker through proxy. Logical broker: pulsar://localhost:9886 2018-07-18 23:53:26.714 INFO ConsumerImpl:168 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, my-sub, 0] Created consumer on broker [127.0.0.1:52108 -> 127.0.0.1:9886] 2018-07-18 23:53:26.719 INFO HandlerBase:53 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, ] Getting connection from pool 2018-07-18 23:53:26.729 INFO ProducerImpl:154 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, ] Created producer on broker [127.0.0.1:52108 -> 127.0.0.1:9886] 2018-07-18 23:53:26.900 INFO ProducerImpl:466 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, cluster-0-1] Closed producer 2018-07-18 23:53:26.903 INFO ConsumerImpl:761 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, my-sub, 0] Closed consumer 0 .2018-07-18 23:53:26.905 INFO ConnectionPool:63 | Created connection for pulsar+ssl://localhost:9886 2018-07-18 23:53:26.906 INFO ClientConnection:285 | [127.0.0.1:52110 -> 127.0.0.1:9886] Connected to broker 2018-07-18 23:53:26.940 INFO HandlerBase:53 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, my-sub, 0] Getting connection from pool 2018-07-18 23:53:26.943 INFO ConnectionPool:63 | Created connection for pulsar://localhost:9886 2018-07-18 23:53:26.944 INFO ClientConnection:287 | [127.0.0.1:52112 -> 127.0.0.1:9886] Connected to broker through proxy. Logical broker: pulsar://localhost:9886 2018-07-18 23:53:26.975 INFO ConsumerImpl:168 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, my-sub, 0] Created consumer on broker [127.0.0.1:52112 -> 127.0.0.1:9886] 2018-07-18 23:53:26.978 INFO HandlerBase:53 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, ] Getting connection from pool 2018-07-18 23:53:26.995 INFO ProducerImpl:154 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, ] Created producer on broker [127.0.0.1:52112 -> 127.0.0.1:9886] 2018-07-18 23:53:27.025 INFO ProducerImpl:466 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, cluster-0-2] Closed producer 2018-07-18 23:53:27.026 INFO ConsumerImpl:761 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, my-sub, 0] Closed consumer 0 .2018-07-18 23:53:27.028 INFO ConnectionPool:63 | Created connection for pulsar+ssl://localhost:9886 2018-07-18 23:53:27.029 INFO ClientConnection:285 | [127.0.0.1:52114 -> 127.0.0.1:9886] Connected to broker 2018-07-18 23:53:27.057 INFO HandlerBase:53 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, my-sub, 0] Getting connection from pool 2018-07-18 23:53:27.059 INFO ConnectionPool:63 | Created connection for pulsar://localhost:9886 2018-07-18 23:53:27.060 INFO ClientConnection:287 | [127.0.0.1:52116 -> 127.0.0.1:9886] Connected to broker through proxy. Logical broker: pulsar://localhost:9886 2018-07-18 23:53:27.090 INFO ConsumerImpl:168 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, my-sub, 0] Created consumer on broker [127.0.0.1:52116 -> 127.0.0.1:9886] 2018-07-18 23:53:27.093 INFO HandlerBase:53 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, ] Getting connection from pool 2018-07-18 23:53:27.103 INFO ProducerImpl:154 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, ] Created producer on broker [127.0.0.1:52116 -> 127.0.0.1:9886] 2018-07-18 23:53:27.148 INFO ProducerImpl:466 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, cluster-0-3] Closed producer 2018-07-18 23:53:27.149 INFO ConsumerImpl:761 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, my-sub, 0] Closed consumer 0 .2018-07-18 23:53:27.150 INFO ConnectionPool:63 | Created connection for pulsar://localhost:8885 2018-07-18 23:53:27.150 INFO ClientConnection:285 | [127.0.0.1:45486 -> 127.0.0.1:8885] Connected to broker 2018-07-18 23:53:27.154 INFO HandlerBase:53 | [persistent://public/default/my-v2-topic-producer-consumer, my-sub, 0] Getting connection from pool 2018-07-18 23:53:27.794 INFO ConsumerImpl:168 | [persistent://public/default/my-v2-topic-producer-consumer, my-sub, 0] Created consumer on broker [127.0.0.1:45486 -> 127.0.0.1:8885] 2018-07-18 23:53:27.797 INFO HandlerBase:53 | [persistent://public/default/my-v2-topic-producer-consumer, ] Getting connection from pool 2018-07-18 23:53:27.799 INFO ProducerImpl:154 | [persistent://public/default/my-v2-topic-producer-consumer, ] Created producer on broker [127.0.0.1:45486 -> 127.0.0.1:8885] 2018-07-18 23:53:27.936 INFO ProducerImpl:466 | [persistent://public/default/my-v2-topic-producer-consumer, standalone-0-92] Closed producer 2018-07-18 23:53:27.937 INFO ConsumerImpl:761 | [persistent://public/default/my-v2-topic-producer-consumer, my-sub, 0] Closed consumer 0 .2018-07-18 23:53:27.938 INFO HTTPLookupService:161 | Curl Lookup Request sent forhttp://localhost:8765/admin/v2/persistent/public/default/my-v2-topic-producer-consumer/partitions 2018-07-18 23:53:27.949 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-18 23:53:27.949 INFO HTTPLookupService:214 | parsePartitionData = { LookupDataResult [brokerUrl_ = ] [brokerUrlSsl_ = ] [partitions = 0] [authoritative = 0] [redirect = 0] proxyThroughServiceUrl = 0] } 2018-07-18 23:53:27.949 INFO HandlerBase:53 | [persistent://public/default/my-v2-topic-producer-consumer, my-sub, 0] Getting connection from pool 2018-07-18 23:53:27.949 INFO HTTPLookupService:161 | Curl Lookup Request sent forhttp://localhost:8765/lookup/v2/topic/persistent/public/default/my-v2-topic-producer-consumer 2018-07-18 23:53:27.957 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-18 23:53:27.957 INFO HTTPLookupService:243 | parseLookupData = { LookupDataResult [brokerUrl_ = pulsar://localhost:8885] [brokerUrlSsl_ = ] [partitions = 0] [authoritative = 0] [redirect = 0] proxyThroughServiceUrl = 0] } 2018-07-18 23:53:27.958 INFO ConnectionPool:63 | Created connection for pulsar://localhost:8885 2018-07-18 23:53:27.958 INFO ClientConnection:285 | [127.0.0.1:45492 -> 127.0.0.1:8885] Connected to broker 2018-07-18 23:53:27.961 INFO ConsumerImpl:168 | [persistent://public/default/my-v2-topic-producer-consumer, my-sub, 0] Created consumer on broker [127.0.0.1:45492 -> 127.0.0.1:8885] 2018-07-18 23:53:27.961 INFO HTTPLookupService:161 | Curl Lookup Request sent forhttp://localhost:8765/admin/v2/persistent/public/default/my-v2-topic-producer-consumer/partitions 2018-07-18 23:53:27.970 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-18 23:53:27.970 INFO HTTPLookupService:214 | parsePartitionData = { LookupDataResult [brokerUrl_ = ] [brokerUrlSsl_ = ] [partitions = 0] [authoritative = 0] [redirect = 0] proxyThroughServiceUrl = 0] } 2018-07-18 23:53:27.971 INFO HandlerBase:53 | [persistent://public/default/my-v2-topic-producer-consumer, ] Getting connection from pool 2018-07-18 23:53:27.971 INFO HTTPLookupService:161 | Curl Lookup Request sent forhttp://localhost:8765/lookup/v2/topic/persistent/public/default/my-v2-topic-producer-consumer 2018-07-18 23:53:27.978 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-18 23:53:27.978 INFO HTTPLookupService:243 | parseLookupData = { LookupDataResult [brokerUrl_ = pulsar://localhost:8885] [brokerUrlSsl_ = ] [partitions = 0] [authoritative = 0] [redirect = 0] proxyThroughServiceUrl = 0] } 2018-07-18 23:53:27.980 INFO ProducerImpl:154 | [persistent://public/default/my-v2-topic-producer-consumer, ] Created producer on broker [127.0.0.1:45492 -> 127.0.0.1:8885] 2018-07-18 23:53:28.112 INFO ProducerImpl:466 | [persistent://public/default/my-v2-topic-producer-consumer, standalone-0-93] Closed producer 2018-07-18 23:53:28.113 INFO ConsumerImpl:761 | [persistent://public/default/my-v2-topic-producer-consumer, my-sub, 0] Closed consumer 0 . ---------------------------------------------------------------------- Ran 27 tests in 17.186s OK /pulsar/pulsar-client-cpp/python /pulsar/pulsar-client-cpp /pulsar/pulsar-client-cpp [pulsar-master] $ /bin/bash -xe /tmp/jenkins1951894198258427502.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 484, 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] Apache Pulsar :: Docker Images :: Pulsar Latest Version (Include All Components) [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.3 kB/s) Downloading: https://repo1.maven.org/maven2/org/apache/pulsar/buildtools/2.2.0-incubating-SNAPSHOT/buildtools-2.2.0-incubating-20180718.171019-22.pom Downloading: http://repository.apache.org/snapshots/org/apache/pulsar/buildtools/2.2.0-incubating-SNAPSHOT/buildtools-2.2.0-incubating-20180718.171019-22.pom [WARNING] The POM for org.apache.pulsar:buildtools:jar:2.2.0-incubating-20180718.171019-22 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-20180718.171019-22.jar [INFO] ------------------------------------------------------------------------ [INFO] Reactor Summary: [INFO] [INFO] Apache Pulsar :: Docker Images ..................... FAILURE [ 4.385 s] [INFO] Apache Pulsar :: Docker Images :: Pulsar Latest Version SKIPPED [INFO] Apache Pulsar :: Docker Images :: Grafana .......... SKIPPED [INFO] Apache Pulsar :: Docker Images :: Pulsar Latest Version (Include All Components) SKIPPED [INFO] ------------------------------------------------------------------------ [INFO] BUILD FAILURE [INFO] ------------------------------------------------------------------------ [INFO] Total time: 5.208 s [INFO] Finished at: 2018-07-18T23:53:38Z [INFO] Final Memory: 19M/1118M [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-20180718.171019-22 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