You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@pulsar.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2018/07/31 21:41:54 UTC

Build failed in Jenkins: pulsar-master #668

See <https://builds.apache.org/job/pulsar-master/668/display/redirect?page=changes>

Changes:

[github] Derive source/sink arg-class name from function-class for file-url

[github] handle subscription-already-exist exception on partitioned-topic for

------------------------------------------
[...truncated 1.07 MB...]
2018-07-31 21:41:31.052 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-31 21:41:31.913 INFO  HandlerBase:53 | [persistent://sample/standalone/ns1/my-python-test_publish_compact_and_consume, my-sub1, 2] Getting connection from pool
2018-07-31 21:41:31.917 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:33636 -> 127.0.0.1:8885] 
2018-07-31 21:41:31.980 INFO  ConsumerImpl:761 | [persistent://sample/standalone/ns1/my-python-test_publish_compact_and_consume, my-sub1, 2] Closed consumer 2
2018-07-31 21:41:31.982 INFO  ConsumerImpl:761 | [persistent://sample/standalone/ns1/my-python-test_publish_compact_and_consume, my-sub2, 1] Closed consumer 1
.2018-07-31 21:41:31.983 INFO  ConnectionPool:63 | Created connection for pulsar://localhost:8885
2018-07-31 21:41:31.983 INFO  ClientConnection:285 | [127.0.0.1:33650 -> 127.0.0.1:8885] Connected to broker
2018-07-31 21:41:31.986 INFO  HandlerBase:53 | [persistent://sample/standalone/ns1/my-python-test-producer, reader-f36a11, 0] Getting connection from pool
2018-07-31 21:41:31.989 INFO  ConsumerImpl:168 | [persistent://sample/standalone/ns1/my-python-test-producer, reader-f36a11, 0] Created consumer on broker [127.0.0.1:33650 -> 127.0.0.1:8885] 
2018-07-31 21:41:31.990 INFO  ConsumerImpl:761 | [persistent://sample/standalone/ns1/my-python-test-producer, reader-f36a11, 0] Closed consumer 0
.2018-07-31 21:41:31.990 INFO  ConnectionPool:63 | Created connection for pulsar://localhost:8885
2018-07-31 21:41:31.991 INFO  ClientConnection:285 | [127.0.0.1:33652 -> 127.0.0.1:8885] Connected to broker
2018-07-31 21:41:31.993 INFO  HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-reader-has-message-available, ] Getting connection from pool
2018-07-31 21:41:32.070 INFO  ProducerImpl:154 | [persistent://sample/standalone/ns/my-python-topic-reader-has-message-available, ] Created producer on broker [127.0.0.1:33652 -> 127.0.0.1:8885] 
2018-07-31 21:41:32.072 INFO  HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-reader-has-message-available, reader-2c8fca, 0] Getting connection from pool
2018-07-31 21:41:32.077 INFO  ConsumerImpl:168 | [persistent://sample/standalone/ns/my-python-topic-reader-has-message-available, reader-2c8fca, 0] Created consumer on broker [127.0.0.1:33652 -> 127.0.0.1:8885] 
2018-07-31 21:41:32.294 INFO  ConsumerImpl:761 | [persistent://sample/standalone/ns/my-python-topic-reader-has-message-available, reader-2c8fca, 0] Closed consumer 0
2018-07-31 21:41:32.295 INFO  ProducerImpl:466 | [persistent://sample/standalone/ns/my-python-topic-reader-has-message-available, standalone-0-94] Closed producer
.2018-07-31 21:41:32.296 INFO  ConnectionPool:63 | Created connection for pulsar://localhost:8885
2018-07-31 21:41:32.297 INFO  ClientConnection:285 | [127.0.0.1:33654 -> 127.0.0.1:8885] Connected to broker
2018-07-31 21:41:32.300 INFO  HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-reader-on-last-message, ] Getting connection from pool
2018-07-31 21:41:32.367 INFO  ProducerImpl:154 | [persistent://sample/standalone/ns/my-python-topic-reader-on-last-message, ] Created producer on broker [127.0.0.1:33654 -> 127.0.0.1:8885] 
2018-07-31 21:41:32.469 INFO  HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-reader-on-last-message, reader-12a6e6, 0] Getting connection from pool
2018-07-31 21:41:32.473 INFO  ConsumerImpl:168 | [persistent://sample/standalone/ns/my-python-topic-reader-on-last-message, reader-12a6e6, 0] Created consumer on broker [127.0.0.1:33654 -> 127.0.0.1:8885] 
2018-07-31 21:41:32.595 INFO  ConsumerImpl:761 | [persistent://sample/standalone/ns/my-python-topic-reader-on-last-message, reader-12a6e6, 0] Closed consumer 0
2018-07-31 21:41:32.595 INFO  ProducerImpl:466 | [persistent://sample/standalone/ns/my-python-topic-reader-on-last-message, standalone-0-95] Closed producer
.2018-07-31 21:41:32.596 INFO  ConnectionPool:63 | Created connection for pulsar://localhost:8885
2018-07-31 21:41:32.597 INFO  ClientConnection:285 | [127.0.0.1:33656 -> 127.0.0.1:8885] Connected to broker
2018-07-31 21:41:32.600 INFO  HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message, ] Getting connection from pool
2018-07-31 21:41:32.643 INFO  ProducerImpl:154 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message, ] Created producer on broker [127.0.0.1:33656 -> 127.0.0.1:8885] 
2018-07-31 21:41:32.745 INFO  HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message, reader-92b226, 0] Getting connection from pool
2018-07-31 21:41:32.750 INFO  ConsumerImpl:168 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message, reader-92b226, 0] Created consumer on broker [127.0.0.1:33656 -> 127.0.0.1:8885] 
2018-07-31 21:41:32.755 INFO  HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message, reader-7eeb5c, 1] Getting connection from pool
2018-07-31 21:41:32.758 INFO  ConsumerImpl:168 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message, reader-7eeb5c, 1] Created consumer on broker [127.0.0.1:33656 -> 127.0.0.1:8885] 
2018-07-31 21:41:32.762 INFO  ConsumerImpl:761 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message, reader-92b226, 0] Closed consumer 0
2018-07-31 21:41:32.763 INFO  ConsumerImpl:761 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message, reader-7eeb5c, 1] Closed consumer 1
2018-07-31 21:41:32.763 INFO  ProducerImpl:466 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message, standalone-0-96] Closed producer
.2018-07-31 21:41:32.764 INFO  ConnectionPool:63 | Created connection for pulsar://localhost:8885
2018-07-31 21:41:32.765 INFO  ClientConnection:285 | [127.0.0.1:33658 -> 127.0.0.1:8885] Connected to broker
2018-07-31 21:41:32.768 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-31 21:41:32.768 INFO  HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message-with-batches, ] Getting connection from pool
2018-07-31 21:41:32.810 INFO  ProducerImpl:154 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message-with-batches, ] Created producer on broker [127.0.0.1:33658 -> 127.0.0.1:8885] 
2018-07-31 21:41:33.836 INFO  HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message-with-batches, reader-b66107, 0] Getting connection from pool
2018-07-31 21:41:33.840 INFO  ConsumerImpl:168 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message-with-batches, reader-b66107, 0] Created consumer on broker [127.0.0.1:33658 -> 127.0.0.1:8885] 
2018-07-31 21:41:33.845 INFO  HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message-with-batches, reader-4d7ee3, 1] Getting connection from pool
2018-07-31 21:41:33.848 INFO  ConsumerImpl:168 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message-with-batches, reader-4d7ee3, 1] Created consumer on broker [127.0.0.1:33658 -> 127.0.0.1:8885] 
2018-07-31 21:41:33.851 INFO  ConsumerImpl:761 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message-with-batches, reader-b66107, 0] Closed consumer 0
2018-07-31 21:41:33.851 INFO  ConsumerImpl:761 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message-with-batches, reader-4d7ee3, 1] Closed consumer 1
2018-07-31 21:41:33.852 INFO  ProducerImpl:466 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message-with-batches, standalone-0-97] Closed producer
.2018-07-31 21:41:33.852 INFO  ConnectionPool:63 | Created connection for pulsar://localhost:8885
2018-07-31 21:41:33.853 INFO  ClientConnection:285 | [127.0.0.1:33660 -> 127.0.0.1:8885] Connected to broker
2018-07-31 21:41:33.856 INFO  HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-reader-simple, reader-f58e16, 0] Getting connection from pool
2018-07-31 21:41:33.903 INFO  ConsumerImpl:168 | [persistent://sample/standalone/ns/my-python-topic-reader-simple, reader-f58e16, 0] Created consumer on broker [127.0.0.1:33660 -> 127.0.0.1:8885] 
2018-07-31 21:41:33.905 INFO  HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-reader-simple, ] Getting connection from pool
2018-07-31 21:41:33.909 INFO  ProducerImpl:154 | [persistent://sample/standalone/ns/my-python-topic-reader-simple, ] Created producer on broker [127.0.0.1:33660 -> 127.0.0.1:8885] 
2018-07-31 21:41:34.037 INFO  ConsumerImpl:761 | [persistent://sample/standalone/ns/my-python-topic-reader-simple, reader-f58e16, 0] Closed consumer 0
2018-07-31 21:41:34.038 INFO  ProducerImpl:466 | [persistent://sample/standalone/ns/my-python-topic-reader-simple, standalone-0-98] Closed producer
.2018-07-31 21:41:34.039 INFO  ConnectionPool:63 | Created connection for pulsar://localhost:8885
2018-07-31 21:41:34.040 INFO  ClientConnection:285 | [127.0.0.1:33662 -> 127.0.0.1:8885] Connected to broker
2018-07-31 21:41:34.043 INFO  HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-seek, my-sub, 0] Getting connection from pool
2018-07-31 21:41:34.159 INFO  ConsumerImpl:168 | [persistent://sample/standalone/ns/my-python-topic-seek, my-sub, 0] Created consumer on broker [127.0.0.1:33662 -> 127.0.0.1:8885] 
2018-07-31 21:41:34.162 INFO  HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-seek, ] Getting connection from pool
2018-07-31 21:41:34.167 INFO  ProducerImpl:154 | [persistent://sample/standalone/ns/my-python-topic-seek, ] Created producer on broker [127.0.0.1:33662 -> 127.0.0.1:8885] 
2018-07-31 21:41:35.079 INFO  HandlerBase:129 | [persistent://sample/standalone/ns/my-python-topic-seek, my-sub, 0] Schedule reconnection in 0.1 s
2018-07-31 21:41:35.085 INFO  ConsumerImpl:893 | [persistent://sample/standalone/ns/my-python-topic-seek, my-sub, 0] Seek successfully
2018-07-31 21:41:35.179 INFO  HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-seek, my-sub, 0] Getting connection from pool
2018-07-31 21:41:35.182 INFO  ConsumerImpl:168 | [persistent://sample/standalone/ns/my-python-topic-seek, my-sub, 0] Created consumer on broker [127.0.0.1:33662 -> 127.0.0.1:8885] 
2018-07-31 21:41:35.586 INFO  ProducerImpl:466 | [persistent://sample/standalone/ns/my-python-topic-seek, standalone-0-99] Closed producer
2018-07-31 21:41:35.587 INFO  ConsumerImpl:761 | [persistent://sample/standalone/ns/my-python-topic-seek, my-sub, 0] Closed consumer 0
.2018-07-31 21:41:35.588 INFO  ConnectionPool:63 | Created connection for pulsar://localhost:8885
2018-07-31 21:41:35.588 INFO  ClientConnection:285 | [127.0.0.1:33664 -> 127.0.0.1:8885] Connected to broker
2018-07-31 21:41:35.591 INFO  HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic, ] Getting connection from pool
2018-07-31 21:41:35.596 INFO  ProducerImpl:154 | [persistent://sample/standalone/ns/my-python-topic, ] Created producer on broker [127.0.0.1:33664 -> 127.0.0.1:8885] 
2018-07-31 21:41:35.618 INFO  ProducerImpl:466 | [persistent://sample/standalone/ns/my-python-topic, standalone-0-100] Closed producer
.2018-07-31 21:41:35.619 INFO  ConnectionPool:63 | Created connection for pulsar+ssl://localhost:9886
2018-07-31 21:41:35.620 INFO  ClientConnection:285 | [127.0.0.1:60248 -> 127.0.0.1:9886] Connected to broker
2018-07-31 21:41:35.650 INFO  HandlerBase:53 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, my-sub, 0] Getting connection from pool
2018-07-31 21:41:35.652 INFO  ConnectionPool:63 | Created connection for pulsar://localhost:9886
2018-07-31 21:41:35.653 INFO  ClientConnection:287 | [127.0.0.1:60250 -> 127.0.0.1:9886] Connected to broker through proxy. Logical broker: pulsar://localhost:9886
2018-07-31 21:41:35.825 INFO  ConsumerImpl:168 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, my-sub, 0] Created consumer on broker [127.0.0.1:60250 -> 127.0.0.1:9886] 
2018-07-31 21:41:35.829 INFO  HandlerBase:53 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, ] Getting connection from pool
2018-07-31 21:41:35.838 INFO  ProducerImpl:154 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, ] Created producer on broker [127.0.0.1:60250 -> 127.0.0.1:9886] 
2018-07-31 21:41:35.972 INFO  ProducerImpl:466 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, cluster-0-1] Closed producer
2018-07-31 21:41:35.975 INFO  ConsumerImpl:761 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, my-sub, 0] Closed consumer 0
.2018-07-31 21:41:35.976 INFO  ConnectionPool:63 | Created connection for pulsar+ssl://localhost:9886
2018-07-31 21:41:35.977 INFO  ClientConnection:285 | [127.0.0.1:60252 -> 127.0.0.1:9886] Connected to broker
2018-07-31 21:41:36.010 INFO  HandlerBase:53 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, my-sub, 0] Getting connection from pool
2018-07-31 21:41:36.012 INFO  ConnectionPool:63 | Created connection for pulsar://localhost:9886
2018-07-31 21:41:36.013 INFO  ClientConnection:287 | [127.0.0.1:60254 -> 127.0.0.1:9886] Connected to broker through proxy. Logical broker: pulsar://localhost:9886
2018-07-31 21:41:36.046 INFO  ConsumerImpl:168 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, my-sub, 0] Created consumer on broker [127.0.0.1:60254 -> 127.0.0.1:9886] 
2018-07-31 21:41:36.050 INFO  HandlerBase:53 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, ] Getting connection from pool
2018-07-31 21:41:36.064 INFO  ProducerImpl:154 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, ] Created producer on broker [127.0.0.1:60254 -> 127.0.0.1:9886] 
2018-07-31 21:41:36.090 INFO  ProducerImpl:466 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, cluster-0-2] Closed producer
2018-07-31 21:41:36.092 INFO  ConsumerImpl:761 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, my-sub, 0] Closed consumer 0
.2018-07-31 21:41:36.094 INFO  ConnectionPool:63 | Created connection for pulsar+ssl://localhost:9886
2018-07-31 21:41:36.094 INFO  ClientConnection:285 | [127.0.0.1:60256 -> 127.0.0.1:9886] Connected to broker
2018-07-31 21:41:36.117 INFO  HandlerBase:53 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, my-sub, 0] Getting connection from pool
2018-07-31 21:41:36.120 INFO  ConnectionPool:63 | Created connection for pulsar://localhost:9886
2018-07-31 21:41:36.121 INFO  ClientConnection:287 | [127.0.0.1:60258 -> 127.0.0.1:9886] Connected to broker through proxy. Logical broker: pulsar://localhost:9886
2018-07-31 21:41:36.156 INFO  ConsumerImpl:168 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, my-sub, 0] Created consumer on broker [127.0.0.1:60258 -> 127.0.0.1:9886] 
2018-07-31 21:41:36.160 INFO  HandlerBase:53 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, ] Getting connection from pool
2018-07-31 21:41:36.171 INFO  ProducerImpl:154 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, ] Created producer on broker [127.0.0.1:60258 -> 127.0.0.1:9886] 
2018-07-31 21:41:36.232 INFO  ProducerImpl:466 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, cluster-0-3] Closed producer
2018-07-31 21:41:36.233 INFO  ConsumerImpl:761 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, my-sub, 0] Closed consumer 0
.2018-07-31 21:41:36.234 INFO  ConnectionPool:63 | Created connection for pulsar://localhost:8885
2018-07-31 21:41:36.235 INFO  ClientConnection:285 | [127.0.0.1:33678 -> 127.0.0.1:8885] Connected to broker
2018-07-31 21:41:36.239 INFO  HandlerBase:53 | [persistent://public/default/my-v2-topic-producer-consumer, my-sub, 0] Getting connection from pool
2018-07-31 21:41:36.366 INFO  ConsumerImpl:168 | [persistent://public/default/my-v2-topic-producer-consumer, my-sub, 0] Created consumer on broker [127.0.0.1:33678 -> 127.0.0.1:8885] 
2018-07-31 21:41:36.369 INFO  HandlerBase:53 | [persistent://public/default/my-v2-topic-producer-consumer, ] Getting connection from pool
2018-07-31 21:41:36.371 INFO  ProducerImpl:154 | [persistent://public/default/my-v2-topic-producer-consumer, ] Created producer on broker [127.0.0.1:33678 -> 127.0.0.1:8885] 
2018-07-31 21:41:36.494 INFO  ProducerImpl:466 | [persistent://public/default/my-v2-topic-producer-consumer, standalone-0-101] Closed producer
2018-07-31 21:41:36.494 INFO  ConsumerImpl:761 | [persistent://public/default/my-v2-topic-producer-consumer, my-sub, 0] Closed consumer 0
.2018-07-31 21:41:36.495 INFO  HTTPLookupService:161 | Curl Lookup Request sent forhttp://localhost:8765/admin/v2/persistent/public/default/my-v2-topic-producer-consumer/partitions
2018-07-31 21:41:36.508 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-31 21:41:36.508 INFO  HTTPLookupService:214 | parsePartitionData = { LookupDataResult [brokerUrl_ = ] [brokerUrlSsl_ = ] [partitions = 0] [authoritative = 0] [redirect = 0] proxyThroughServiceUrl = 0] }
2018-07-31 21:41:36.508 INFO  HandlerBase:53 | [persistent://public/default/my-v2-topic-producer-consumer, my-sub, 0] Getting connection from pool
2018-07-31 21:41:36.508 INFO  HTTPLookupService:161 | Curl Lookup Request sent forhttp://localhost:8765/lookup/v2/topic/persistent/public/default/my-v2-topic-producer-consumer
2018-07-31 21:41:36.517 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-31 21:41:36.517 INFO  HTTPLookupService:243 | parseLookupData = { LookupDataResult [brokerUrl_ = pulsar://localhost:8885] [brokerUrlSsl_ = ] [partitions = 0] [authoritative = 0] [redirect = 0] proxyThroughServiceUrl = 0] }
2018-07-31 21:41:36.517 INFO  ConnectionPool:63 | Created connection for pulsar://localhost:8885
2018-07-31 21:41:36.517 INFO  ClientConnection:285 | [127.0.0.1:33684 -> 127.0.0.1:8885] Connected to broker
2018-07-31 21:41:36.520 INFO  ConsumerImpl:168 | [persistent://public/default/my-v2-topic-producer-consumer, my-sub, 0] Created consumer on broker [127.0.0.1:33684 -> 127.0.0.1:8885] 
2018-07-31 21:41:36.520 INFO  HTTPLookupService:161 | Curl Lookup Request sent forhttp://localhost:8765/admin/v2/persistent/public/default/my-v2-topic-producer-consumer/partitions
2018-07-31 21:41:36.528 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-31 21:41:36.529 INFO  HTTPLookupService:214 | parsePartitionData = { LookupDataResult [brokerUrl_ = ] [brokerUrlSsl_ = ] [partitions = 0] [authoritative = 0] [redirect = 0] proxyThroughServiceUrl = 0] }
2018-07-31 21:41:36.529 INFO  HandlerBase:53 | [persistent://public/default/my-v2-topic-producer-consumer, ] Getting connection from pool
2018-07-31 21:41:36.529 INFO  HTTPLookupService:161 | Curl Lookup Request sent forhttp://localhost:8765/lookup/v2/topic/persistent/public/default/my-v2-topic-producer-consumer
2018-07-31 21:41:36.536 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-31 21:41:36.536 INFO  HTTPLookupService:243 | parseLookupData = { LookupDataResult [brokerUrl_ = pulsar://localhost:8885] [brokerUrlSsl_ = ] [partitions = 0] [authoritative = 0] [redirect = 0] proxyThroughServiceUrl = 0] }
2018-07-31 21:41:36.537 INFO  ProducerImpl:154 | [persistent://public/default/my-v2-topic-producer-consumer, ] Created producer on broker [127.0.0.1:33684 -> 127.0.0.1:8885] 
2018-07-31 21:41:36.654 INFO  ProducerImpl:466 | [persistent://public/default/my-v2-topic-producer-consumer, standalone-0-102] Closed producer
2018-07-31 21:41:36.654 INFO  ConsumerImpl:761 | [persistent://public/default/my-v2-topic-producer-consumer, my-sub, 0] Closed consumer 0
.
----------------------------------------------------------------------
Ran 27 tests in 9.715s

OK
/pulsar/pulsar-client-cpp/python /pulsar/pulsar-client-cpp
/pulsar/pulsar-client-cpp
[pulsar-master] $ /bin/bash -xe /tmp/jenkins3281406310107021106.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 486, 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] ------------------------------------------------------------------------
[INFO] 
[INFO] --- git-commit-id-plugin:2.2.4:revision (git-info) @ docker-images ---
[INFO] 
[INFO] --- maven-dependency-plugin:2.10:build-classpath (build-classpath) @ docker-images ---
[INFO] No dependencies found.
[INFO] Skipped writing classpath file '<https://builds.apache.org/job/pulsar-master/ws/docker/target/classpath.txt'.>  No changes found.
[INFO] 
[INFO] --- maven-remote-resources-plugin:1.5:process (process-resource-bundles) @ docker-images ---
[INFO] 
[INFO] --- maven-site-plugin:3.5.1:attach-descriptor (attach-descriptor) @ docker-images ---
[INFO] 
[INFO] ------------------------------------------------------------------------
[INFO] Building Apache Pulsar :: Docker Images :: Pulsar Latest Version 2.2.0-incubating-SNAPSHOT
[INFO] ------------------------------------------------------------------------
[WARNING] The POM for org.apache.pulsar:pulsar-server-distribution:tar.gz:bin:2.2.0-incubating-20180730.131105-26 is missing, no dependency information available
[INFO] 
[INFO] --- git-commit-id-plugin:2.2.4:revision (git-info) @ pulsar-docker-image ---
[INFO] 
[INFO] --- maven-dependency-plugin:2.10:build-classpath (build-classpath) @ pulsar-docker-image ---
[INFO] No dependencies found.
[INFO] Skipped writing classpath file '<https://builds.apache.org/job/pulsar-master/ws/docker/pulsar/target/classpath.txt'.>  No changes found.
[INFO] 
[INFO] --- maven-remote-resources-plugin:1.5:process (process-resource-bundles) @ pulsar-docker-image ---
[INFO] Failure detected.
[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO] 
[INFO] Apache Pulsar :: Docker Images ..................... SUCCESS [  3.873 s]
[INFO] Apache Pulsar :: Docker Images :: Pulsar Latest Version FAILURE [  0.088 s]
[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: 4.622 s
[INFO] Finished at: 2018-07-31T21:41:44Z
[INFO] Final Memory: 34M/1411M
[INFO] ------------------------------------------------------------------------
[ERROR] Failed to execute goal org.apache.maven.plugins:maven-remote-resources-plugin:1.5:process (process-resource-bundles) on project pulsar-docker-image: Failed to resolve dependencies for one or more projects in the reactor. Reason: Unable to get dependency information for org.apache.pulsar:pulsar-server-distribution:tar.gz:bin:2.2.0-incubating-SNAPSHOT: Failed to process POM for org.apache.pulsar:pulsar-server-distribution:tar.gz:bin:2.2.0-incubating-SNAPSHOT: Non-resolvable parent POM org.apache.pulsar:distribution:2.2.0-incubating-SNAPSHOT for org.apache.pulsar:pulsar-server-distribution:[unknown-version]: Failure to find org.apache.pulsar:distribution:pom:2.2.0-incubating-20180730.131016-32 in http://repository.apache.org/snapshots was cached in the local repository, resolution will not be reattempted until the update interval of Nexus has elapsed or updates are forced
[ERROR]   org.apache.pulsar:pulsar-server-distribution:tar.gz:2.2.0-incubating-20180730.131105-26
[ERROR] 
[ERROR] from the specified remote repositories:
[ERROR]   central (https://repo1.maven.org/maven2, releases=true, snapshots=true),
[ERROR]   bintray-yahoo-maven (https://yahoo.bintray.com/maven, releases=true, snapshots=false),
[ERROR]   Nexus (http://repository.apache.org/snapshots, releases=false, snapshots=true)
[ERROR] Path to dependency: 
[ERROR] 	1) org.apache.pulsar:pulsar-docker-image:pom:2.2.0-incubating-SNAPSHOT
[ERROR] 
[ERROR] 
[ERROR] -> [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/MojoExecutionException
[ERROR] 
[ERROR] After correcting the problems, you can resume the build with the command
[ERROR]   mvn <goals> -rf :pulsar-docker-image
[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 noreply@github.com
Not sending mail to unregistered user guosijie@gmail.com
Not sending mail to unregistered user y.shiga.91+yush1ga@gmail.com

Build failed in Jenkins: pulsar-master #672

Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/pulsar-master/672/display/redirect?page=changes>

Changes:

[github] [website] Move `Concepts and Architecture` page into a seperate category

------------------------------------------
[...truncated 1.10 MB...]
2018-08-01 23:59:07.860 INFO  ConsumerImpl:168 | [persistent://sample/standalone/ns1/my-python-test-producer-deduplication-1533167947.55, my-sub, 0] Created consumer on broker [127.0.0.1:44226 -> 127.0.0.1:8885] 
2018-08-01 23:59:08.923 INFO  ProducerImpl:466 | [persistent://sample/standalone/ns1/my-python-test-producer-deduplication-1533167947.55, my-producer-name] Closed producer
2018-08-01 23:59:08.925 INFO  HandlerBase:53 | [persistent://sample/standalone/ns1/my-python-test-producer-deduplication-1533167947.55, my-producer-name] Getting connection from pool
2018-08-01 23:59:08.927 INFO  ProducerImpl:154 | [persistent://sample/standalone/ns1/my-python-test-producer-deduplication-1533167947.55, my-producer-name] Created producer on broker [127.0.0.1:44226 -> 127.0.0.1:8885] 
.2018-08-01 23:59:09.929 INFO  ConnectionPool:63 | Created connection for pulsar://localhost:8885
2018-08-01 23:59:09.930 INFO  ClientConnection:285 | [127.0.0.1:44228 -> 127.0.0.1:8885] Connected to broker
2018-08-01 23:59:09.933 INFO  HandlerBase:53 | [persistent://public/default/my-python-test-producer, ] Getting connection from pool
2018-08-01 23:59:09.986 INFO  ProducerImpl:154 | [persistent://public/default/my-python-test-producer, ] Created producer on broker [127.0.0.1:44228 -> 127.0.0.1:8885] 
2018-08-01 23:59:10.012 INFO  ProducerImpl:466 | [persistent://public/default/my-python-test-producer, standalone-0-92] Closed producer
.2018-08-01 23:59:10.012 INFO  ConnectionPool:63 | Created connection for pulsar://localhost:8885
2018-08-01 23:59:10.013 INFO  ClientConnection:285 | [127.0.0.1:44230 -> 127.0.0.1:8885] Connected to broker
2018-08-01 23:59:10.016 INFO  HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic, ] Getting connection from pool
2018-08-01 23:59:10.065 INFO  ProducerImpl:154 | [persistent://sample/standalone/ns/my-python-topic, ] Created producer on broker [127.0.0.1:44230 -> 127.0.0.1:8885] 
2018-08-01 23:59:10.065 WARN  ConsumerImpl:97 | [persistent://sample/standalone/ns1/my-python-test-producer-deduplication-1533167947.55, my-sub, 0] Destroyed consumer which was not properly closed
2018-08-01 23:59:10.167 INFO  ProducerImpl:466 | [persistent://sample/standalone/ns/my-python-topic, standalone-0-93] Closed producer
.2018-08-01 23:59:10.214 INFO  ConnectionPool:63 | Created connection for pulsar://localhost:8885
2018-08-01 23:59:10.215 INFO  ClientConnection:285 | [127.0.0.1:44234 -> 127.0.0.1:8885] Connected to broker
2018-08-01 23:59:10.218 INFO  HandlerBase:53 | [persistent://sample/standalone/ns1/my-python-test-producer-sequence-after-reconnection-1533167950.21, my-producer-name] Getting connection from pool
2018-08-01 23:59:10.343 INFO  ProducerImpl:154 | [persistent://sample/standalone/ns1/my-python-test-producer-sequence-after-reconnection-1533167950.21, my-producer-name] Created producer on broker [127.0.0.1:44234 -> 127.0.0.1:8885] 
2018-08-01 23:59:10.511 INFO  ProducerImpl:466 | [persistent://sample/standalone/ns1/my-python-test-producer-sequence-after-reconnection-1533167950.21, my-producer-name] Closed producer
2018-08-01 23:59:10.514 INFO  HandlerBase:53 | [persistent://sample/standalone/ns1/my-python-test-producer-sequence-after-reconnection-1533167950.21, my-producer-name] Getting connection from pool
2018-08-01 23:59:10.516 INFO  ProducerImpl:154 | [persistent://sample/standalone/ns1/my-python-test-producer-sequence-after-reconnection-1533167950.21, my-producer-name] Created producer on broker [127.0.0.1:44234 -> 127.0.0.1:8885] 
.2018-08-01 23:59:10.695 INFO  ConnectionPool:63 | Created connection for pulsar://localhost:8885
2018-08-01 23:59:10.696 INFO  ClientConnection:285 | [127.0.0.1:44236 -> 127.0.0.1:8885] Connected to broker
2018-08-01 23:59:10.699 INFO  HandlerBase:53 | [persistent://sample/standalone/ns1/my-python-test_publish_compact_and_consume, my-producer-name] Getting connection from pool
2018-08-01 23:59:10.860 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:44236 -> 127.0.0.1:8885] 
2018-08-01 23:59:10.862 INFO  HandlerBase:53 | [persistent://sample/standalone/ns1/my-python-test_publish_compact_and_consume, my-sub1, 0] Getting connection from pool
2018-08-01 23:59:11.070 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:44236 -> 127.0.0.1:8885] 
2018-08-01 23:59:11.073 INFO  ConsumerImpl:761 | [persistent://sample/standalone/ns1/my-python-test_publish_compact_and_consume, my-sub1, 0] Closed consumer 0
2018-08-01 23:59:11.075 INFO  HandlerBase:53 | [persistent://sample/standalone/ns1/my-python-test_publish_compact_and_consume, my-sub2, 1] Getting connection from pool
2018-08-01 23:59:11.226 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:44236 -> 127.0.0.1:8885] 
2018-08-01 23:59:11.320 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-08-01 23:59:12.252 INFO  HandlerBase:53 | [persistent://sample/standalone/ns1/my-python-test_publish_compact_and_consume, my-sub1, 2] Getting connection from pool
2018-08-01 23:59:12.255 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:44236 -> 127.0.0.1:8885] 
2018-08-01 23:59:12.304 INFO  ConsumerImpl:761 | [persistent://sample/standalone/ns1/my-python-test_publish_compact_and_consume, my-sub1, 2] Closed consumer 2
2018-08-01 23:59:12.305 INFO  ConsumerImpl:761 | [persistent://sample/standalone/ns1/my-python-test_publish_compact_and_consume, my-sub2, 1] Closed consumer 1
.2018-08-01 23:59:12.306 INFO  ConnectionPool:63 | Created connection for pulsar://localhost:8885
2018-08-01 23:59:12.307 INFO  ClientConnection:285 | [127.0.0.1:44252 -> 127.0.0.1:8885] Connected to broker
2018-08-01 23:59:12.312 INFO  HandlerBase:53 | [persistent://sample/standalone/ns1/my-python-test-producer, reader-4c4d6d, 0] Getting connection from pool
2018-08-01 23:59:12.315 INFO  ConsumerImpl:168 | [persistent://sample/standalone/ns1/my-python-test-producer, reader-4c4d6d, 0] Created consumer on broker [127.0.0.1:44252 -> 127.0.0.1:8885] 
2018-08-01 23:59:12.317 INFO  ConsumerImpl:761 | [persistent://sample/standalone/ns1/my-python-test-producer, reader-4c4d6d, 0] Closed consumer 0
.2018-08-01 23:59:12.318 INFO  ConnectionPool:63 | Created connection for pulsar://localhost:8885
2018-08-01 23:59:12.318 INFO  ClientConnection:285 | [127.0.0.1:44254 -> 127.0.0.1:8885] Connected to broker
2018-08-01 23:59:12.323 INFO  HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-reader-has-message-available, ] Getting connection from pool
2018-08-01 23:59:12.408 INFO  ProducerImpl:154 | [persistent://sample/standalone/ns/my-python-topic-reader-has-message-available, ] Created producer on broker [127.0.0.1:44254 -> 127.0.0.1:8885] 
2018-08-01 23:59:12.410 INFO  HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-reader-has-message-available, reader-56a25f, 0] Getting connection from pool
2018-08-01 23:59:12.414 INFO  ConsumerImpl:168 | [persistent://sample/standalone/ns/my-python-topic-reader-has-message-available, reader-56a25f, 0] Created consumer on broker [127.0.0.1:44254 -> 127.0.0.1:8885] 
2018-08-01 23:59:12.762 INFO  ConsumerImpl:761 | [persistent://sample/standalone/ns/my-python-topic-reader-has-message-available, reader-56a25f, 0] Closed consumer 0
2018-08-01 23:59:12.763 INFO  ProducerImpl:466 | [persistent://sample/standalone/ns/my-python-topic-reader-has-message-available, standalone-0-94] Closed producer
.2018-08-01 23:59:12.764 INFO  ConnectionPool:63 | Created connection for pulsar://localhost:8885
2018-08-01 23:59:12.765 INFO  ClientConnection:285 | [127.0.0.1:44256 -> 127.0.0.1:8885] Connected to broker
2018-08-01 23:59:12.769 INFO  HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-reader-on-last-message, ] Getting connection from pool
2018-08-01 23:59:12.831 INFO  ProducerImpl:154 | [persistent://sample/standalone/ns/my-python-topic-reader-on-last-message, ] Created producer on broker [127.0.0.1:44256 -> 127.0.0.1:8885] 
2018-08-01 23:59:12.962 INFO  HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-reader-on-last-message, reader-dec395, 0] Getting connection from pool
2018-08-01 23:59:12.966 INFO  ConsumerImpl:168 | [persistent://sample/standalone/ns/my-python-topic-reader-on-last-message, reader-dec395, 0] Created consumer on broker [127.0.0.1:44256 -> 127.0.0.1:8885] 
2018-08-01 23:59:13.058 INFO  ConsumerImpl:761 | [persistent://sample/standalone/ns/my-python-topic-reader-on-last-message, reader-dec395, 0] Closed consumer 0
2018-08-01 23:59:13.059 INFO  ProducerImpl:466 | [persistent://sample/standalone/ns/my-python-topic-reader-on-last-message, standalone-0-95] Closed producer
.2018-08-01 23:59:13.059 INFO  ConnectionPool:63 | Created connection for pulsar://localhost:8885
2018-08-01 23:59:13.060 INFO  ClientConnection:285 | [127.0.0.1:44258 -> 127.0.0.1:8885] Connected to broker
2018-08-01 23:59:13.064 INFO  HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message, ] Getting connection from pool
2018-08-01 23:59:13.122 INFO  ProducerImpl:154 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message, ] Created producer on broker [127.0.0.1:44258 -> 127.0.0.1:8885] 
2018-08-01 23:59:13.229 INFO  HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message, reader-404838, 0] Getting connection from pool
2018-08-01 23:59:13.233 INFO  ConsumerImpl:168 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message, reader-404838, 0] Created consumer on broker [127.0.0.1:44258 -> 127.0.0.1:8885] 
2018-08-01 23:59:13.240 INFO  HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message, reader-db4035, 1] Getting connection from pool
2018-08-01 23:59:13.243 INFO  ConsumerImpl:168 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message, reader-db4035, 1] Created consumer on broker [127.0.0.1:44258 -> 127.0.0.1:8885] 
2018-08-01 23:59:13.247 INFO  ConsumerImpl:761 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message, reader-404838, 0] Closed consumer 0
2018-08-01 23:59:13.247 INFO  ConsumerImpl:761 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message, reader-db4035, 1] Closed consumer 1
2018-08-01 23:59:13.248 INFO  ProducerImpl:466 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message, standalone-0-96] Closed producer
.2018-08-01 23:59:13.249 INFO  ConnectionPool:63 | Created connection for pulsar://localhost:8885
2018-08-01 23:59:13.250 INFO  ClientConnection:285 | [127.0.0.1:44260 -> 127.0.0.1:8885] Connected to broker
2018-08-01 23:59:13.253 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-08-01 23:59:13.253 INFO  HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message-with-batches, ] Getting connection from pool
2018-08-01 23:59:13.314 INFO  ProducerImpl:154 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message-with-batches, ] Created producer on broker [127.0.0.1:44260 -> 127.0.0.1:8885] 
2018-08-01 23:59:14.345 INFO  HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message-with-batches, reader-991201, 0] Getting connection from pool
2018-08-01 23:59:14.350 INFO  ConsumerImpl:168 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message-with-batches, reader-991201, 0] Created consumer on broker [127.0.0.1:44260 -> 127.0.0.1:8885] 
2018-08-01 23:59:14.357 INFO  HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message-with-batches, reader-aa4ea9, 1] Getting connection from pool
2018-08-01 23:59:14.361 INFO  ConsumerImpl:168 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message-with-batches, reader-aa4ea9, 1] Created consumer on broker [127.0.0.1:44260 -> 127.0.0.1:8885] 
2018-08-01 23:59:14.365 INFO  ConsumerImpl:761 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message-with-batches, reader-991201, 0] Closed consumer 0
2018-08-01 23:59:14.366 INFO  ConsumerImpl:761 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message-with-batches, reader-aa4ea9, 1] Closed consumer 1
2018-08-01 23:59:14.367 INFO  ProducerImpl:466 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message-with-batches, standalone-0-97] Closed producer
.2018-08-01 23:59:14.368 INFO  ConnectionPool:63 | Created connection for pulsar://localhost:8885
2018-08-01 23:59:14.369 INFO  ClientConnection:285 | [127.0.0.1:44262 -> 127.0.0.1:8885] Connected to broker
2018-08-01 23:59:14.372 INFO  HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-reader-simple, reader-f422a4, 0] Getting connection from pool
2018-08-01 23:59:14.431 INFO  ConsumerImpl:168 | [persistent://sample/standalone/ns/my-python-topic-reader-simple, reader-f422a4, 0] Created consumer on broker [127.0.0.1:44262 -> 127.0.0.1:8885] 
2018-08-01 23:59:14.433 INFO  HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-reader-simple, ] Getting connection from pool
2018-08-01 23:59:14.441 INFO  ProducerImpl:154 | [persistent://sample/standalone/ns/my-python-topic-reader-simple, ] Created producer on broker [127.0.0.1:44262 -> 127.0.0.1:8885] 
2018-08-01 23:59:14.562 INFO  ConsumerImpl:761 | [persistent://sample/standalone/ns/my-python-topic-reader-simple, reader-f422a4, 0] Closed consumer 0
2018-08-01 23:59:14.563 INFO  ProducerImpl:466 | [persistent://sample/standalone/ns/my-python-topic-reader-simple, standalone-0-98] Closed producer
.2018-08-01 23:59:14.564 INFO  ConnectionPool:63 | Created connection for pulsar://localhost:8885
2018-08-01 23:59:14.565 INFO  ClientConnection:285 | [127.0.0.1:44264 -> 127.0.0.1:8885] Connected to broker
2018-08-01 23:59:14.569 INFO  HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-seek, my-sub, 0] Getting connection from pool
2018-08-01 23:59:14.718 INFO  ConsumerImpl:168 | [persistent://sample/standalone/ns/my-python-topic-seek, my-sub, 0] Created consumer on broker [127.0.0.1:44264 -> 127.0.0.1:8885] 
2018-08-01 23:59:14.722 INFO  HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-seek, ] Getting connection from pool
2018-08-01 23:59:14.729 INFO  ProducerImpl:154 | [persistent://sample/standalone/ns/my-python-topic-seek, ] Created producer on broker [127.0.0.1:44264 -> 127.0.0.1:8885] 
2018-08-01 23:59:15.661 INFO  HandlerBase:129 | [persistent://sample/standalone/ns/my-python-topic-seek, my-sub, 0] Schedule reconnection in 0.1 s
2018-08-01 23:59:15.676 INFO  ConsumerImpl:893 | [persistent://sample/standalone/ns/my-python-topic-seek, my-sub, 0] Seek successfully
2018-08-01 23:59:15.761 INFO  HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-seek, my-sub, 0] Getting connection from pool
2018-08-01 23:59:15.767 INFO  ConsumerImpl:168 | [persistent://sample/standalone/ns/my-python-topic-seek, my-sub, 0] Created consumer on broker [127.0.0.1:44264 -> 127.0.0.1:8885] 
2018-08-01 23:59:16.177 INFO  ProducerImpl:466 | [persistent://sample/standalone/ns/my-python-topic-seek, standalone-0-99] Closed producer
2018-08-01 23:59:16.178 INFO  ConsumerImpl:761 | [persistent://sample/standalone/ns/my-python-topic-seek, my-sub, 0] Closed consumer 0
.2018-08-01 23:59:16.179 INFO  ConnectionPool:63 | Created connection for pulsar://localhost:8885
2018-08-01 23:59:16.180 INFO  ClientConnection:285 | [127.0.0.1:44266 -> 127.0.0.1:8885] Connected to broker
2018-08-01 23:59:16.184 INFO  HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic, ] Getting connection from pool
2018-08-01 23:59:16.191 INFO  ProducerImpl:154 | [persistent://sample/standalone/ns/my-python-topic, ] Created producer on broker [127.0.0.1:44266 -> 127.0.0.1:8885] 
2018-08-01 23:59:16.209 INFO  ProducerImpl:466 | [persistent://sample/standalone/ns/my-python-topic, standalone-0-100] Closed producer
.2018-08-01 23:59:16.211 INFO  ConnectionPool:63 | Created connection for pulsar+ssl://localhost:9886
2018-08-01 23:59:16.211 INFO  ClientConnection:285 | [127.0.0.1:49452 -> 127.0.0.1:9886] Connected to broker
2018-08-01 23:59:16.253 INFO  HandlerBase:53 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, my-sub, 0] Getting connection from pool
2018-08-01 23:59:16.256 INFO  ConnectionPool:63 | Created connection for pulsar://localhost:9886
2018-08-01 23:59:16.257 INFO  ClientConnection:287 | [127.0.0.1:49454 -> 127.0.0.1:9886] Connected to broker through proxy. Logical broker: pulsar://localhost:9886
2018-08-01 23:59:16.418 INFO  ConsumerImpl:168 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, my-sub, 0] Created consumer on broker [127.0.0.1:49454 -> 127.0.0.1:9886] 
2018-08-01 23:59:16.423 INFO  HandlerBase:53 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, ] Getting connection from pool
2018-08-01 23:59:16.433 INFO  ProducerImpl:154 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, ] Created producer on broker [127.0.0.1:49454 -> 127.0.0.1:9886] 
2018-08-01 23:59:16.564 INFO  ProducerImpl:466 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, cluster-0-1] Closed producer
2018-08-01 23:59:16.566 INFO  ConsumerImpl:761 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, my-sub, 0] Closed consumer 0
.2018-08-01 23:59:16.568 INFO  ConnectionPool:63 | Created connection for pulsar+ssl://localhost:9886
2018-08-01 23:59:16.568 INFO  ClientConnection:285 | [127.0.0.1:49456 -> 127.0.0.1:9886] Connected to broker
2018-08-01 23:59:16.602 INFO  HandlerBase:53 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, my-sub, 0] Getting connection from pool
2018-08-01 23:59:16.605 INFO  ConnectionPool:63 | Created connection for pulsar://localhost:9886
2018-08-01 23:59:16.605 INFO  ClientConnection:287 | [127.0.0.1:49458 -> 127.0.0.1:9886] Connected to broker through proxy. Logical broker: pulsar://localhost:9886
2018-08-01 23:59:16.638 INFO  ConsumerImpl:168 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, my-sub, 0] Created consumer on broker [127.0.0.1:49458 -> 127.0.0.1:9886] 
2018-08-01 23:59:16.642 INFO  HandlerBase:53 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, ] Getting connection from pool
2018-08-01 23:59:16.658 INFO  ProducerImpl:154 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, ] Created producer on broker [127.0.0.1:49458 -> 127.0.0.1:9886] 
2018-08-01 23:59:16.679 INFO  ProducerImpl:466 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, cluster-0-2] Closed producer
2018-08-01 23:59:16.681 INFO  ConsumerImpl:761 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, my-sub, 0] Closed consumer 0
.2018-08-01 23:59:16.682 INFO  ConnectionPool:63 | Created connection for pulsar+ssl://localhost:9886
2018-08-01 23:59:16.683 INFO  ClientConnection:285 | [127.0.0.1:49460 -> 127.0.0.1:9886] Connected to broker
2018-08-01 23:59:16.707 INFO  HandlerBase:53 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, my-sub, 0] Getting connection from pool
2018-08-01 23:59:16.709 INFO  ConnectionPool:63 | Created connection for pulsar://localhost:9886
2018-08-01 23:59:16.709 INFO  ClientConnection:287 | [127.0.0.1:49462 -> 127.0.0.1:9886] Connected to broker through proxy. Logical broker: pulsar://localhost:9886
2018-08-01 23:59:16.734 INFO  ConsumerImpl:168 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, my-sub, 0] Created consumer on broker [127.0.0.1:49462 -> 127.0.0.1:9886] 
2018-08-01 23:59:16.736 INFO  HandlerBase:53 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, ] Getting connection from pool
2018-08-01 23:59:16.742 INFO  ProducerImpl:154 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, ] Created producer on broker [127.0.0.1:49462 -> 127.0.0.1:9886] 
2018-08-01 23:59:16.755 INFO  ProducerImpl:466 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, cluster-0-3] Closed producer
2018-08-01 23:59:16.756 INFO  ConsumerImpl:761 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, my-sub, 0] Closed consumer 0
.2018-08-01 23:59:16.757 INFO  ConnectionPool:63 | Created connection for pulsar://localhost:8885
2018-08-01 23:59:16.757 INFO  ClientConnection:285 | [127.0.0.1:44280 -> 127.0.0.1:8885] Connected to broker
2018-08-01 23:59:16.760 INFO  HandlerBase:53 | [persistent://public/default/my-v2-topic-producer-consumer, my-sub, 0] Getting connection from pool
2018-08-01 23:59:16.882 INFO  ConsumerImpl:168 | [persistent://public/default/my-v2-topic-producer-consumer, my-sub, 0] Created consumer on broker [127.0.0.1:44280 -> 127.0.0.1:8885] 
2018-08-01 23:59:16.885 INFO  HandlerBase:53 | [persistent://public/default/my-v2-topic-producer-consumer, ] Getting connection from pool
2018-08-01 23:59:16.888 INFO  ProducerImpl:154 | [persistent://public/default/my-v2-topic-producer-consumer, ] Created producer on broker [127.0.0.1:44280 -> 127.0.0.1:8885] 
2018-08-01 23:59:17.011 INFO  ProducerImpl:466 | [persistent://public/default/my-v2-topic-producer-consumer, standalone-0-101] Closed producer
2018-08-01 23:59:17.011 INFO  ConsumerImpl:761 | [persistent://public/default/my-v2-topic-producer-consumer, my-sub, 0] Closed consumer 0
.2018-08-01 23:59:17.012 INFO  HTTPLookupService:161 | Curl Lookup Request sent forhttp://localhost:8765/admin/v2/persistent/public/default/my-v2-topic-producer-consumer/partitions
2018-08-01 23:59:17.029 INFO  HTTPLookupService:173 | Response received for url http://localhost:8765/admin/v2/persistent/public/default/my-v2-topic-producer-consumer/partitions code 200
2018-08-01 23:59:17.029 INFO  HTTPLookupService:214 | parsePartitionData = { LookupDataResult [brokerUrl_ = ] [brokerUrlSsl_ = ] [partitions = 0] [authoritative = 0] [redirect = 0] proxyThroughServiceUrl = 0] }
2018-08-01 23:59:17.029 INFO  HandlerBase:53 | [persistent://public/default/my-v2-topic-producer-consumer, my-sub, 0] Getting connection from pool
2018-08-01 23:59:17.029 INFO  HTTPLookupService:161 | Curl Lookup Request sent forhttp://localhost:8765/lookup/v2/topic/persistent/public/default/my-v2-topic-producer-consumer
2018-08-01 23:59:17.039 INFO  HTTPLookupService:173 | Response received for url http://localhost:8765/lookup/v2/topic/persistent/public/default/my-v2-topic-producer-consumer code 200
2018-08-01 23:59:17.039 INFO  HTTPLookupService:243 | parseLookupData = { LookupDataResult [brokerUrl_ = pulsar://localhost:8885] [brokerUrlSsl_ = ] [partitions = 0] [authoritative = 0] [redirect = 0] proxyThroughServiceUrl = 0] }
2018-08-01 23:59:17.039 INFO  ConnectionPool:63 | Created connection for pulsar://localhost:8885
2018-08-01 23:59:17.040 INFO  ClientConnection:285 | [127.0.0.1:44286 -> 127.0.0.1:8885] Connected to broker
2018-08-01 23:59:17.042 INFO  ConsumerImpl:168 | [persistent://public/default/my-v2-topic-producer-consumer, my-sub, 0] Created consumer on broker [127.0.0.1:44286 -> 127.0.0.1:8885] 
2018-08-01 23:59:17.043 INFO  HTTPLookupService:161 | Curl Lookup Request sent forhttp://localhost:8765/admin/v2/persistent/public/default/my-v2-topic-producer-consumer/partitions
2018-08-01 23:59:17.053 INFO  HTTPLookupService:173 | Response received for url http://localhost:8765/admin/v2/persistent/public/default/my-v2-topic-producer-consumer/partitions code 200
2018-08-01 23:59:17.053 INFO  HTTPLookupService:214 | parsePartitionData = { LookupDataResult [brokerUrl_ = ] [brokerUrlSsl_ = ] [partitions = 0] [authoritative = 0] [redirect = 0] proxyThroughServiceUrl = 0] }
2018-08-01 23:59:17.053 INFO  HandlerBase:53 | [persistent://public/default/my-v2-topic-producer-consumer, ] Getting connection from pool
2018-08-01 23:59:17.054 INFO  HTTPLookupService:161 | Curl Lookup Request sent forhttp://localhost:8765/lookup/v2/topic/persistent/public/default/my-v2-topic-producer-consumer
2018-08-01 23:59:17.062 INFO  HTTPLookupService:173 | Response received for url http://localhost:8765/lookup/v2/topic/persistent/public/default/my-v2-topic-producer-consumer code 200
2018-08-01 23:59:17.062 INFO  HTTPLookupService:243 | parseLookupData = { LookupDataResult [brokerUrl_ = pulsar://localhost:8885] [brokerUrlSsl_ = ] [partitions = 0] [authoritative = 0] [redirect = 0] proxyThroughServiceUrl = 0] }
2018-08-01 23:59:17.063 INFO  ProducerImpl:154 | [persistent://public/default/my-v2-topic-producer-consumer, ] Created producer on broker [127.0.0.1:44286 -> 127.0.0.1:8885] 
2018-08-01 23:59:17.177 INFO  ProducerImpl:466 | [persistent://public/default/my-v2-topic-producer-consumer, standalone-0-102] Closed producer
2018-08-01 23:59:17.177 INFO  ConsumerImpl:761 | [persistent://public/default/my-v2-topic-producer-consumer, my-sub, 0] Closed consumer 0
.
----------------------------------------------------------------------
Ran 27 tests in 11.318s

OK
/pulsar/pulsar-client-cpp/python /pulsar/pulsar-client-cpp
/pulsar/pulsar-client-cpp
[pulsar-master] $ /bin/bash -xe /tmp/jenkins8808319319621220205.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 486, 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: https://repo1.maven.org/maven2/org/apache/pulsar/buildtools/2.2.0-incubating-SNAPSHOT/maven-metadata.xml
Downloading: http://repository.apache.org/snapshots/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.8 kB/s)
Downloading: https://repo1.maven.org/maven2/org/apache/pulsar/buildtools/2.2.0-incubating-SNAPSHOT/buildtools-2.2.0-incubating-20180801.124042-34.pom
Downloading: http://repository.apache.org/snapshots/org/apache/pulsar/buildtools/2.2.0-incubating-SNAPSHOT/buildtools-2.2.0-incubating-20180801.124042-34.pom
[WARNING] The POM for org.apache.pulsar:buildtools:jar:2.2.0-incubating-20180801.124042-34 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-20180801.124042-34.jar
[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO] 
[INFO] Apache Pulsar :: Docker Images ..................... FAILURE [  1.705 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: 2.265 s
[INFO] Finished at: 2018-08-01T23:59:22Z
[INFO] Final Memory: 19M/1084M
[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-20180801.124042-34 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 noreply@github.com
Not sending mail to unregistered user guosijie@gmail.com
Not sending mail to unregistered user y.shiga.91+yush1ga@gmail.com

Build failed in Jenkins: pulsar-master #671

Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/pulsar-master/671/display/redirect?page=changes>

Changes:

[github] [website] fix swagger file url (#2277)

------------------------------------------
[...truncated 1.05 MB...]
2018-08-01 19:11:55.664 INFO  HandlerBase:53 | [persistent://sample/standalone/ns1/my-python-test-producer-deduplication-1533150714.37, my-producer-name] Getting connection from pool
2018-08-01 19:11:55.666 INFO  ProducerImpl:154 | [persistent://sample/standalone/ns1/my-python-test-producer-deduplication-1533150714.37, my-producer-name] Created producer on broker [127.0.0.1:55266 -> 127.0.0.1:8885] 
.2018-08-01 19:11:56.667 INFO  ConnectionPool:63 | Created connection for pulsar://localhost:8885
2018-08-01 19:11:56.668 INFO  ClientConnection:285 | [127.0.0.1:55268 -> 127.0.0.1:8885] Connected to broker
2018-08-01 19:11:56.673 INFO  HandlerBase:53 | [persistent://public/default/my-python-test-producer, ] Getting connection from pool
2018-08-01 19:11:56.718 INFO  ProducerImpl:154 | [persistent://public/default/my-python-test-producer, ] Created producer on broker [127.0.0.1:55268 -> 127.0.0.1:8885] 
2018-08-01 19:11:56.746 INFO  ProducerImpl:466 | [persistent://public/default/my-python-test-producer, standalone-0-92] Closed producer
.2018-08-01 19:11:56.747 INFO  ConnectionPool:63 | Created connection for pulsar://localhost:8885
2018-08-01 19:11:56.748 INFO  ClientConnection:285 | [127.0.0.1:55270 -> 127.0.0.1:8885] Connected to broker
2018-08-01 19:11:56.751 INFO  HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic, ] Getting connection from pool
2018-08-01 19:11:56.796 INFO  ProducerImpl:154 | [persistent://sample/standalone/ns/my-python-topic, ] Created producer on broker [127.0.0.1:55270 -> 127.0.0.1:8885] 
2018-08-01 19:11:56.797 WARN  ConsumerImpl:97 | [persistent://sample/standalone/ns1/my-python-test-producer-deduplication-1533150714.37, my-sub, 0] Destroyed consumer which was not properly closed
2018-08-01 19:11:56.899 INFO  ProducerImpl:466 | [persistent://sample/standalone/ns/my-python-topic, standalone-0-93] Closed producer
.2018-08-01 19:11:56.953 INFO  ConnectionPool:63 | Created connection for pulsar://localhost:8885
2018-08-01 19:11:56.955 INFO  ClientConnection:285 | [127.0.0.1:55274 -> 127.0.0.1:8885] Connected to broker
2018-08-01 19:11:56.959 INFO  HandlerBase:53 | [persistent://sample/standalone/ns1/my-python-test-producer-sequence-after-reconnection-1533150716.95, my-producer-name] Getting connection from pool
2018-08-01 19:11:57.101 INFO  ProducerImpl:154 | [persistent://sample/standalone/ns1/my-python-test-producer-sequence-after-reconnection-1533150716.95, my-producer-name] Created producer on broker [127.0.0.1:55274 -> 127.0.0.1:8885] 
2018-08-01 19:11:57.196 INFO  ProducerImpl:466 | [persistent://sample/standalone/ns1/my-python-test-producer-sequence-after-reconnection-1533150716.95, my-producer-name] Closed producer
2018-08-01 19:11:57.198 INFO  HandlerBase:53 | [persistent://sample/standalone/ns1/my-python-test-producer-sequence-after-reconnection-1533150716.95, my-producer-name] Getting connection from pool
2018-08-01 19:11:57.200 INFO  ProducerImpl:154 | [persistent://sample/standalone/ns1/my-python-test-producer-sequence-after-reconnection-1533150716.95, my-producer-name] Created producer on broker [127.0.0.1:55274 -> 127.0.0.1:8885] 
.2018-08-01 19:11:57.279 INFO  ConnectionPool:63 | Created connection for pulsar://localhost:8885
2018-08-01 19:11:57.280 INFO  ClientConnection:285 | [127.0.0.1:55276 -> 127.0.0.1:8885] Connected to broker
2018-08-01 19:11:57.285 INFO  HandlerBase:53 | [persistent://sample/standalone/ns1/my-python-test_publish_compact_and_consume, my-producer-name] Getting connection from pool
2018-08-01 19:11:57.392 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:55276 -> 127.0.0.1:8885] 
2018-08-01 19:11:57.394 INFO  HandlerBase:53 | [persistent://sample/standalone/ns1/my-python-test_publish_compact_and_consume, my-sub1, 0] Getting connection from pool
2018-08-01 19:11:57.450 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:55276 -> 127.0.0.1:8885] 
2018-08-01 19:11:57.453 INFO  ConsumerImpl:761 | [persistent://sample/standalone/ns1/my-python-test_publish_compact_and_consume, my-sub1, 0] Closed consumer 0
2018-08-01 19:11:57.454 INFO  HandlerBase:53 | [persistent://sample/standalone/ns1/my-python-test_publish_compact_and_consume, my-sub2, 1] Getting connection from pool
2018-08-01 19:11:57.509 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:55276 -> 127.0.0.1:8885] 
2018-08-01 19:11:57.555 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 still running
{"status":"RUNNING","lastError":""}
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-08-01 19:11:59.255 INFO  HandlerBase:53 | [persistent://sample/standalone/ns1/my-python-test_publish_compact_and_consume, my-sub1, 2] Getting connection from pool
2018-08-01 19:11:59.257 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:55276 -> 127.0.0.1:8885] 
2018-08-01 19:11:59.299 INFO  ConsumerImpl:761 | [persistent://sample/standalone/ns1/my-python-test_publish_compact_and_consume, my-sub1, 2] Closed consumer 2
2018-08-01 19:11:59.302 INFO  ConsumerImpl:761 | [persistent://sample/standalone/ns1/my-python-test_publish_compact_and_consume, my-sub2, 1] Closed consumer 1
.2018-08-01 19:11:59.302 INFO  ConnectionPool:63 | Created connection for pulsar://localhost:8885
2018-08-01 19:11:59.303 INFO  ClientConnection:285 | [127.0.0.1:55302 -> 127.0.0.1:8885] Connected to broker
2018-08-01 19:11:59.305 INFO  HandlerBase:53 | [persistent://sample/standalone/ns1/my-python-test-producer, reader-e3af21, 0] Getting connection from pool
2018-08-01 19:11:59.309 INFO  ConsumerImpl:168 | [persistent://sample/standalone/ns1/my-python-test-producer, reader-e3af21, 0] Created consumer on broker [127.0.0.1:55302 -> 127.0.0.1:8885] 
2018-08-01 19:11:59.311 INFO  ConsumerImpl:761 | [persistent://sample/standalone/ns1/my-python-test-producer, reader-e3af21, 0] Closed consumer 0
.2018-08-01 19:11:59.311 INFO  ConnectionPool:63 | Created connection for pulsar://localhost:8885
2018-08-01 19:11:59.312 INFO  ClientConnection:285 | [127.0.0.1:55304 -> 127.0.0.1:8885] Connected to broker
2018-08-01 19:11:59.315 INFO  HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-reader-has-message-available, ] Getting connection from pool
2018-08-01 19:11:59.370 INFO  ProducerImpl:154 | [persistent://sample/standalone/ns/my-python-topic-reader-has-message-available, ] Created producer on broker [127.0.0.1:55304 -> 127.0.0.1:8885] 
2018-08-01 19:11:59.372 INFO  HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-reader-has-message-available, reader-758710, 0] Getting connection from pool
2018-08-01 19:11:59.375 INFO  ConsumerImpl:168 | [persistent://sample/standalone/ns/my-python-topic-reader-has-message-available, reader-758710, 0] Created consumer on broker [127.0.0.1:55304 -> 127.0.0.1:8885] 
2018-08-01 19:11:59.563 INFO  ConsumerImpl:761 | [persistent://sample/standalone/ns/my-python-topic-reader-has-message-available, reader-758710, 0] Closed consumer 0
2018-08-01 19:11:59.564 INFO  ProducerImpl:466 | [persistent://sample/standalone/ns/my-python-topic-reader-has-message-available, standalone-0-94] Closed producer
.2018-08-01 19:11:59.565 INFO  ConnectionPool:63 | Created connection for pulsar://localhost:8885
2018-08-01 19:11:59.566 INFO  ClientConnection:285 | [127.0.0.1:55306 -> 127.0.0.1:8885] Connected to broker
2018-08-01 19:11:59.569 INFO  HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-reader-on-last-message, ] Getting connection from pool
2018-08-01 19:11:59.620 INFO  ProducerImpl:154 | [persistent://sample/standalone/ns/my-python-topic-reader-on-last-message, ] Created producer on broker [127.0.0.1:55306 -> 127.0.0.1:8885] 
2018-08-01 19:11:59.722 INFO  HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-reader-on-last-message, reader-224078, 0] Getting connection from pool
2018-08-01 19:11:59.726 INFO  ConsumerImpl:168 | [persistent://sample/standalone/ns/my-python-topic-reader-on-last-message, reader-224078, 0] Created consumer on broker [127.0.0.1:55306 -> 127.0.0.1:8885] 
2018-08-01 19:11:59.822 INFO  ConsumerImpl:761 | [persistent://sample/standalone/ns/my-python-topic-reader-on-last-message, reader-224078, 0] Closed consumer 0
2018-08-01 19:11:59.823 INFO  ProducerImpl:466 | [persistent://sample/standalone/ns/my-python-topic-reader-on-last-message, standalone-0-95] Closed producer
.2018-08-01 19:11:59.824 INFO  ConnectionPool:63 | Created connection for pulsar://localhost:8885
2018-08-01 19:11:59.825 INFO  ClientConnection:285 | [127.0.0.1:55308 -> 127.0.0.1:8885] Connected to broker
2018-08-01 19:11:59.828 INFO  HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message, ] Getting connection from pool
2018-08-01 19:11:59.886 INFO  ProducerImpl:154 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message, ] Created producer on broker [127.0.0.1:55308 -> 127.0.0.1:8885] 
2018-08-01 19:12:00.039 INFO  HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message, reader-431dc2, 0] Getting connection from pool
2018-08-01 19:12:00.043 INFO  ConsumerImpl:168 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message, reader-431dc2, 0] Created consumer on broker [127.0.0.1:55308 -> 127.0.0.1:8885] 
2018-08-01 19:12:00.047 INFO  HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message, reader-871b00, 1] Getting connection from pool
2018-08-01 19:12:00.050 INFO  ConsumerImpl:168 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message, reader-871b00, 1] Created consumer on broker [127.0.0.1:55308 -> 127.0.0.1:8885] 
2018-08-01 19:12:00.053 INFO  ConsumerImpl:761 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message, reader-431dc2, 0] Closed consumer 0
2018-08-01 19:12:00.053 INFO  ConsumerImpl:761 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message, reader-871b00, 1] Closed consumer 1
2018-08-01 19:12:00.054 INFO  ProducerImpl:466 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message, standalone-0-96] Closed producer
.2018-08-01 19:12:00.055 INFO  ConnectionPool:63 | Created connection for pulsar://localhost:8885
2018-08-01 19:12:00.055 INFO  ClientConnection:285 | [127.0.0.1:55310 -> 127.0.0.1:8885] Connected to broker
2018-08-01 19:12:00.058 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-08-01 19:12:00.058 INFO  HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message-with-batches, ] Getting connection from pool
2018-08-01 19:12:00.135 INFO  ProducerImpl:154 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message-with-batches, ] Created producer on broker [127.0.0.1:55310 -> 127.0.0.1:8885] 
2018-08-01 19:12:01.172 INFO  HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message-with-batches, reader-4d4356, 0] Getting connection from pool
2018-08-01 19:12:01.176 INFO  ConsumerImpl:168 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message-with-batches, reader-4d4356, 0] Created consumer on broker [127.0.0.1:55310 -> 127.0.0.1:8885] 
2018-08-01 19:12:01.181 INFO  HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message-with-batches, reader-08ad6e, 1] Getting connection from pool
2018-08-01 19:12:01.184 INFO  ConsumerImpl:168 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message-with-batches, reader-08ad6e, 1] Created consumer on broker [127.0.0.1:55310 -> 127.0.0.1:8885] 
2018-08-01 19:12:01.187 INFO  ConsumerImpl:761 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message-with-batches, reader-4d4356, 0] Closed consumer 0
2018-08-01 19:12:01.188 INFO  ConsumerImpl:761 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message-with-batches, reader-08ad6e, 1] Closed consumer 1
2018-08-01 19:12:01.188 INFO  ProducerImpl:466 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message-with-batches, standalone-0-97] Closed producer
.2018-08-01 19:12:01.189 INFO  ConnectionPool:63 | Created connection for pulsar://localhost:8885
2018-08-01 19:12:01.189 INFO  ClientConnection:285 | [127.0.0.1:55312 -> 127.0.0.1:8885] Connected to broker
2018-08-01 19:12:01.192 INFO  HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-reader-simple, reader-1f120b, 0] Getting connection from pool
2018-08-01 19:12:01.236 INFO  ConsumerImpl:168 | [persistent://sample/standalone/ns/my-python-topic-reader-simple, reader-1f120b, 0] Created consumer on broker [127.0.0.1:55312 -> 127.0.0.1:8885] 
2018-08-01 19:12:01.238 INFO  HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-reader-simple, ] Getting connection from pool
2018-08-01 19:12:01.242 INFO  ProducerImpl:154 | [persistent://sample/standalone/ns/my-python-topic-reader-simple, ] Created producer on broker [127.0.0.1:55312 -> 127.0.0.1:8885] 
2018-08-01 19:12:01.365 INFO  ConsumerImpl:761 | [persistent://sample/standalone/ns/my-python-topic-reader-simple, reader-1f120b, 0] Closed consumer 0
2018-08-01 19:12:01.366 INFO  ProducerImpl:466 | [persistent://sample/standalone/ns/my-python-topic-reader-simple, standalone-0-98] Closed producer
.2018-08-01 19:12:01.367 INFO  ConnectionPool:63 | Created connection for pulsar://localhost:8885
2018-08-01 19:12:01.367 INFO  ClientConnection:285 | [127.0.0.1:55314 -> 127.0.0.1:8885] Connected to broker
2018-08-01 19:12:01.370 INFO  HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-seek, my-sub, 0] Getting connection from pool
2018-08-01 19:12:01.509 INFO  ConsumerImpl:168 | [persistent://sample/standalone/ns/my-python-topic-seek, my-sub, 0] Created consumer on broker [127.0.0.1:55314 -> 127.0.0.1:8885] 
2018-08-01 19:12:01.512 INFO  HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-seek, ] Getting connection from pool
2018-08-01 19:12:01.516 INFO  ProducerImpl:154 | [persistent://sample/standalone/ns/my-python-topic-seek, ] Created producer on broker [127.0.0.1:55314 -> 127.0.0.1:8885] 
2018-08-01 19:12:02.482 INFO  HandlerBase:129 | [persistent://sample/standalone/ns/my-python-topic-seek, my-sub, 0] Schedule reconnection in 0.1 s
2018-08-01 19:12:02.487 INFO  ConsumerImpl:893 | [persistent://sample/standalone/ns/my-python-topic-seek, my-sub, 0] Seek successfully
2018-08-01 19:12:02.582 INFO  HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-seek, my-sub, 0] Getting connection from pool
2018-08-01 19:12:02.587 INFO  ConsumerImpl:168 | [persistent://sample/standalone/ns/my-python-topic-seek, my-sub, 0] Created consumer on broker [127.0.0.1:55314 -> 127.0.0.1:8885] 
2018-08-01 19:12:02.989 INFO  ProducerImpl:466 | [persistent://sample/standalone/ns/my-python-topic-seek, standalone-0-99] Closed producer
2018-08-01 19:12:02.989 INFO  ConsumerImpl:761 | [persistent://sample/standalone/ns/my-python-topic-seek, my-sub, 0] Closed consumer 0
.2018-08-01 19:12:02.990 INFO  ConnectionPool:63 | Created connection for pulsar://localhost:8885
2018-08-01 19:12:02.991 INFO  ClientConnection:285 | [127.0.0.1:55316 -> 127.0.0.1:8885] Connected to broker
2018-08-01 19:12:02.994 INFO  HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic, ] Getting connection from pool
2018-08-01 19:12:03.000 INFO  ProducerImpl:154 | [persistent://sample/standalone/ns/my-python-topic, ] Created producer on broker [127.0.0.1:55316 -> 127.0.0.1:8885] 
2018-08-01 19:12:03.021 INFO  ProducerImpl:466 | [persistent://sample/standalone/ns/my-python-topic, standalone-0-100] Closed producer
.2018-08-01 19:12:03.022 INFO  ConnectionPool:63 | Created connection for pulsar+ssl://localhost:9886
2018-08-01 19:12:03.023 INFO  ClientConnection:285 | [127.0.0.1:53668 -> 127.0.0.1:9886] Connected to broker
2018-08-01 19:12:03.063 INFO  HandlerBase:53 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, my-sub, 0] Getting connection from pool
2018-08-01 19:12:03.066 INFO  ConnectionPool:63 | Created connection for pulsar://localhost:9886
2018-08-01 19:12:03.066 INFO  ClientConnection:287 | [127.0.0.1:53670 -> 127.0.0.1:9886] Connected to broker through proxy. Logical broker: pulsar://localhost:9886
2018-08-01 19:12:03.233 INFO  ConsumerImpl:168 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, my-sub, 0] Created consumer on broker [127.0.0.1:53670 -> 127.0.0.1:9886] 
2018-08-01 19:12:03.238 INFO  HandlerBase:53 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, ] Getting connection from pool
2018-08-01 19:12:03.247 INFO  ProducerImpl:154 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, ] Created producer on broker [127.0.0.1:53670 -> 127.0.0.1:9886] 
2018-08-01 19:12:03.381 INFO  ProducerImpl:466 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, cluster-0-1] Closed producer
2018-08-01 19:12:03.384 INFO  ConsumerImpl:761 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, my-sub, 0] Closed consumer 0
.2018-08-01 19:12:03.386 INFO  ConnectionPool:63 | Created connection for pulsar+ssl://localhost:9886
2018-08-01 19:12:03.387 INFO  ClientConnection:285 | [127.0.0.1:53672 -> 127.0.0.1:9886] Connected to broker
2018-08-01 19:12:03.424 INFO  HandlerBase:53 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, my-sub, 0] Getting connection from pool
2018-08-01 19:12:03.428 INFO  ConnectionPool:63 | Created connection for pulsar://localhost:9886
2018-08-01 19:12:03.429 INFO  ClientConnection:287 | [127.0.0.1:53674 -> 127.0.0.1:9886] Connected to broker through proxy. Logical broker: pulsar://localhost:9886
2018-08-01 19:12:03.467 INFO  ConsumerImpl:168 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, my-sub, 0] Created consumer on broker [127.0.0.1:53674 -> 127.0.0.1:9886] 
2018-08-01 19:12:03.471 INFO  HandlerBase:53 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, ] Getting connection from pool
2018-08-01 19:12:03.489 INFO  ProducerImpl:154 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, ] Created producer on broker [127.0.0.1:53674 -> 127.0.0.1:9886] 
2018-08-01 19:12:03.518 INFO  ProducerImpl:466 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, cluster-0-2] Closed producer
2018-08-01 19:12:03.519 INFO  ConsumerImpl:761 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, my-sub, 0] Closed consumer 0
.2018-08-01 19:12:03.521 INFO  ConnectionPool:63 | Created connection for pulsar+ssl://localhost:9886
2018-08-01 19:12:03.521 INFO  ClientConnection:285 | [127.0.0.1:53676 -> 127.0.0.1:9886] Connected to broker
2018-08-01 19:12:03.556 INFO  HandlerBase:53 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, my-sub, 0] Getting connection from pool
2018-08-01 19:12:03.560 INFO  ConnectionPool:63 | Created connection for pulsar://localhost:9886
2018-08-01 19:12:03.560 INFO  ClientConnection:287 | [127.0.0.1:53678 -> 127.0.0.1:9886] Connected to broker through proxy. Logical broker: pulsar://localhost:9886
2018-08-01 19:12:03.595 INFO  ConsumerImpl:168 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, my-sub, 0] Created consumer on broker [127.0.0.1:53678 -> 127.0.0.1:9886] 
2018-08-01 19:12:03.598 INFO  HandlerBase:53 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, ] Getting connection from pool
2018-08-01 19:12:03.607 INFO  ProducerImpl:154 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, ] Created producer on broker [127.0.0.1:53678 -> 127.0.0.1:9886] 
2018-08-01 19:12:03.649 INFO  ProducerImpl:466 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, cluster-0-3] Closed producer
2018-08-01 19:12:03.651 INFO  ConsumerImpl:761 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, my-sub, 0] Closed consumer 0
.2018-08-01 19:12:03.652 INFO  ConnectionPool:63 | Created connection for pulsar://localhost:8885
2018-08-01 19:12:03.652 INFO  ClientConnection:285 | [127.0.0.1:55330 -> 127.0.0.1:8885] Connected to broker
2018-08-01 19:12:03.656 INFO  HandlerBase:53 | [persistent://public/default/my-v2-topic-producer-consumer, my-sub, 0] Getting connection from pool
2018-08-01 19:12:03.791 INFO  ConsumerImpl:168 | [persistent://public/default/my-v2-topic-producer-consumer, my-sub, 0] Created consumer on broker [127.0.0.1:55330 -> 127.0.0.1:8885] 
2018-08-01 19:12:03.794 INFO  HandlerBase:53 | [persistent://public/default/my-v2-topic-producer-consumer, ] Getting connection from pool
2018-08-01 19:12:03.796 INFO  ProducerImpl:154 | [persistent://public/default/my-v2-topic-producer-consumer, ] Created producer on broker [127.0.0.1:55330 -> 127.0.0.1:8885] 
2018-08-01 19:12:03.922 INFO  ProducerImpl:466 | [persistent://public/default/my-v2-topic-producer-consumer, standalone-0-101] Closed producer
2018-08-01 19:12:03.922 INFO  ConsumerImpl:761 | [persistent://public/default/my-v2-topic-producer-consumer, my-sub, 0] Closed consumer 0
.2018-08-01 19:12:03.923 INFO  HTTPLookupService:161 | Curl Lookup Request sent forhttp://localhost:8765/admin/v2/persistent/public/default/my-v2-topic-producer-consumer/partitions
2018-08-01 19:12:03.935 INFO  HTTPLookupService:173 | Response received for url http://localhost:8765/admin/v2/persistent/public/default/my-v2-topic-producer-consumer/partitions code 200
2018-08-01 19:12:03.935 INFO  HTTPLookupService:214 | parsePartitionData = { LookupDataResult [brokerUrl_ = ] [brokerUrlSsl_ = ] [partitions = 0] [authoritative = 0] [redirect = 0] proxyThroughServiceUrl = 0] }
2018-08-01 19:12:03.936 INFO  HandlerBase:53 | [persistent://public/default/my-v2-topic-producer-consumer, my-sub, 0] Getting connection from pool
2018-08-01 19:12:03.936 INFO  HTTPLookupService:161 | Curl Lookup Request sent forhttp://localhost:8765/lookup/v2/topic/persistent/public/default/my-v2-topic-producer-consumer
2018-08-01 19:12:03.944 INFO  HTTPLookupService:173 | Response received for url http://localhost:8765/lookup/v2/topic/persistent/public/default/my-v2-topic-producer-consumer code 200
2018-08-01 19:12:03.944 INFO  HTTPLookupService:243 | parseLookupData = { LookupDataResult [brokerUrl_ = pulsar://localhost:8885] [brokerUrlSsl_ = ] [partitions = 0] [authoritative = 0] [redirect = 0] proxyThroughServiceUrl = 0] }
2018-08-01 19:12:03.945 INFO  ConnectionPool:63 | Created connection for pulsar://localhost:8885
2018-08-01 19:12:03.945 INFO  ClientConnection:285 | [127.0.0.1:55336 -> 127.0.0.1:8885] Connected to broker
2018-08-01 19:12:03.947 INFO  ConsumerImpl:168 | [persistent://public/default/my-v2-topic-producer-consumer, my-sub, 0] Created consumer on broker [127.0.0.1:55336 -> 127.0.0.1:8885] 
2018-08-01 19:12:03.948 INFO  HTTPLookupService:161 | Curl Lookup Request sent forhttp://localhost:8765/admin/v2/persistent/public/default/my-v2-topic-producer-consumer/partitions
2018-08-01 19:12:03.957 INFO  HTTPLookupService:173 | Response received for url http://localhost:8765/admin/v2/persistent/public/default/my-v2-topic-producer-consumer/partitions code 200
2018-08-01 19:12:03.957 INFO  HTTPLookupService:214 | parsePartitionData = { LookupDataResult [brokerUrl_ = ] [brokerUrlSsl_ = ] [partitions = 0] [authoritative = 0] [redirect = 0] proxyThroughServiceUrl = 0] }
2018-08-01 19:12:03.957 INFO  HandlerBase:53 | [persistent://public/default/my-v2-topic-producer-consumer, ] Getting connection from pool
2018-08-01 19:12:03.957 INFO  HTTPLookupService:161 | Curl Lookup Request sent forhttp://localhost:8765/lookup/v2/topic/persistent/public/default/my-v2-topic-producer-consumer
2018-08-01 19:12:03.966 INFO  HTTPLookupService:173 | Response received for url http://localhost:8765/lookup/v2/topic/persistent/public/default/my-v2-topic-producer-consumer code 200
2018-08-01 19:12:03.966 INFO  HTTPLookupService:243 | parseLookupData = { LookupDataResult [brokerUrl_ = pulsar://localhost:8885] [brokerUrlSsl_ = ] [partitions = 0] [authoritative = 0] [redirect = 0] proxyThroughServiceUrl = 0] }
2018-08-01 19:12:03.967 INFO  ProducerImpl:154 | [persistent://public/default/my-v2-topic-producer-consumer, ] Created producer on broker [127.0.0.1:55336 -> 127.0.0.1:8885] 
2018-08-01 19:12:04.090 INFO  ProducerImpl:466 | [persistent://public/default/my-v2-topic-producer-consumer, standalone-0-102] Closed producer
2018-08-01 19:12:04.090 INFO  ConsumerImpl:761 | [persistent://public/default/my-v2-topic-producer-consumer, my-sub, 0] Closed consumer 0
.
----------------------------------------------------------------------
Ran 27 tests in 11.140s

OK
/pulsar/pulsar-client-cpp/python /pulsar/pulsar-client-cpp
/pulsar/pulsar-client-cpp
[pulsar-master] $ /bin/bash -xe /tmp/jenkins7586620745416992773.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 486, 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] ------------------------------------------------------------------------
[WARNING] The POM for org.apache.pulsar:buildtools:jar:2.2.0-incubating-20180731.124044-33 is missing, no dependency information available
[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO] 
[INFO] Apache Pulsar :: Docker Images ..................... FAILURE [  0.596 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: 1.288 s
[INFO] Finished at: 2018-08-01T19:12:08Z
[INFO] Final Memory: 24M/1449M
[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: Failure to find org.apache.pulsar:buildtools:jar:2.2.0-incubating-20180731.124044-33 in http://repository.apache.org/snapshots was cached in the local repository, resolution will not be reattempted until the update interval of Nexus has elapsed or updates are forced -> [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 noreply@github.com
Not sending mail to unregistered user guosijie@gmail.com
Not sending mail to unregistered user y.shiga.91+yush1ga@gmail.com

Build failed in Jenkins: pulsar-master #670

Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/pulsar-master/670/display/redirect?page=changes>

Changes:

[github]  [website] Render Pulsar REST API in ReDoc and Fix endpoint template

[guosijie] Namespace level policy for offload deletion lag (#2256)

------------------------------------------
[...truncated 1.07 MB...]
2018-08-01 11:09:11.145 INFO  ConsumerImpl:168 | [persistent://sample/standalone/ns1/my-python-test-producer-deduplication-1533121750.9, my-sub, 0] Created consumer on broker [127.0.0.1:38648 -> 127.0.0.1:8885] 
2018-08-01 11:09:12.189 INFO  ProducerImpl:466 | [persistent://sample/standalone/ns1/my-python-test-producer-deduplication-1533121750.9, my-producer-name] Closed producer
2018-08-01 11:09:12.190 INFO  HandlerBase:53 | [persistent://sample/standalone/ns1/my-python-test-producer-deduplication-1533121750.9, my-producer-name] Getting connection from pool
2018-08-01 11:09:12.192 INFO  ProducerImpl:154 | [persistent://sample/standalone/ns1/my-python-test-producer-deduplication-1533121750.9, my-producer-name] Created producer on broker [127.0.0.1:38648 -> 127.0.0.1:8885] 
.2018-08-01 11:09:13.194 INFO  ConnectionPool:63 | Created connection for pulsar://localhost:8885
2018-08-01 11:09:13.195 INFO  ClientConnection:285 | [127.0.0.1:38656 -> 127.0.0.1:8885] Connected to broker
2018-08-01 11:09:13.198 INFO  HandlerBase:53 | [persistent://public/default/my-python-test-producer, ] Getting connection from pool
2018-08-01 11:09:13.254 INFO  ProducerImpl:154 | [persistent://public/default/my-python-test-producer, ] Created producer on broker [127.0.0.1:38656 -> 127.0.0.1:8885] 
2018-08-01 11:09:13.274 INFO  ProducerImpl:466 | [persistent://public/default/my-python-test-producer, standalone-0-92] Closed producer
.2018-08-01 11:09:13.275 INFO  ConnectionPool:63 | Created connection for pulsar://localhost:8885
2018-08-01 11:09:13.275 INFO  ClientConnection:285 | [127.0.0.1:38658 -> 127.0.0.1:8885] Connected to broker
2018-08-01 11:09:13.277 INFO  HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic, ] Getting connection from pool
2018-08-01 11:09:13.323 INFO  ProducerImpl:154 | [persistent://sample/standalone/ns/my-python-topic, ] Created producer on broker [127.0.0.1:38658 -> 127.0.0.1:8885] 
2018-08-01 11:09:13.324 WARN  ConsumerImpl:97 | [persistent://sample/standalone/ns1/my-python-test-producer-deduplication-1533121750.9, my-sub, 0] Destroyed consumer which was not properly closed
2018-08-01 11:09:13.425 INFO  ProducerImpl:466 | [persistent://sample/standalone/ns/my-python-topic, standalone-0-93] Closed producer
.2018-08-01 11:09:13.445 INFO  ConnectionPool:63 | Created connection for pulsar://localhost:8885
2018-08-01 11:09:13.446 INFO  ClientConnection:285 | [127.0.0.1:38662 -> 127.0.0.1:8885] Connected to broker
2018-08-01 11:09:13.448 INFO  HandlerBase:53 | [persistent://sample/standalone/ns1/my-python-test-producer-sequence-after-reconnection-1533121753.45, my-producer-name] Getting connection from pool
2018-08-01 11:09:13.571 INFO  ProducerImpl:154 | [persistent://sample/standalone/ns1/my-python-test-producer-sequence-after-reconnection-1533121753.45, my-producer-name] Created producer on broker [127.0.0.1:38662 -> 127.0.0.1:8885] 
2018-08-01 11:09:13.700 INFO  ProducerImpl:466 | [persistent://sample/standalone/ns1/my-python-test-producer-sequence-after-reconnection-1533121753.45, my-producer-name] Closed producer
2018-08-01 11:09:13.702 INFO  HandlerBase:53 | [persistent://sample/standalone/ns1/my-python-test-producer-sequence-after-reconnection-1533121753.45, my-producer-name] Getting connection from pool
2018-08-01 11:09:13.704 INFO  ProducerImpl:154 | [persistent://sample/standalone/ns1/my-python-test-producer-sequence-after-reconnection-1533121753.45, my-producer-name] Created producer on broker [127.0.0.1:38662 -> 127.0.0.1:8885] 
.2018-08-01 11:09:13.791 INFO  ConnectionPool:63 | Created connection for pulsar://localhost:8885
2018-08-01 11:09:13.792 INFO  ClientConnection:285 | [127.0.0.1:38664 -> 127.0.0.1:8885] Connected to broker
2018-08-01 11:09:13.795 INFO  HandlerBase:53 | [persistent://sample/standalone/ns1/my-python-test_publish_compact_and_consume, my-producer-name] Getting connection from pool
2018-08-01 11:09:13.912 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:38664 -> 127.0.0.1:8885] 
2018-08-01 11:09:13.914 INFO  HandlerBase:53 | [persistent://sample/standalone/ns1/my-python-test_publish_compact_and_consume, my-sub1, 0] Getting connection from pool
2018-08-01 11:09:13.970 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:38664 -> 127.0.0.1:8885] 
2018-08-01 11:09:13.973 INFO  ConsumerImpl:761 | [persistent://sample/standalone/ns1/my-python-test_publish_compact_and_consume, my-sub1, 0] Closed consumer 0
2018-08-01 11:09:13.975 INFO  HandlerBase:53 | [persistent://sample/standalone/ns1/my-python-test_publish_compact_and_consume, my-sub2, 1] Getting connection from pool
2018-08-01 11:09:14.037 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:38664 -> 127.0.0.1:8885] 
2018-08-01 11:09:14.066 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-08-01 11:09:14.943 INFO  HandlerBase:53 | [persistent://sample/standalone/ns1/my-python-test_publish_compact_and_consume, my-sub1, 2] Getting connection from pool
2018-08-01 11:09:14.946 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:38664 -> 127.0.0.1:8885] 
2018-08-01 11:09:14.994 INFO  ConsumerImpl:761 | [persistent://sample/standalone/ns1/my-python-test_publish_compact_and_consume, my-sub1, 2] Closed consumer 2
2018-08-01 11:09:14.995 INFO  ConsumerImpl:761 | [persistent://sample/standalone/ns1/my-python-test_publish_compact_and_consume, my-sub2, 1] Closed consumer 1
.2018-08-01 11:09:14.996 INFO  ConnectionPool:63 | Created connection for pulsar://localhost:8885
2018-08-01 11:09:14.997 INFO  ClientConnection:285 | [127.0.0.1:38678 -> 127.0.0.1:8885] Connected to broker
2018-08-01 11:09:15.000 INFO  HandlerBase:53 | [persistent://sample/standalone/ns1/my-python-test-producer, reader-fd931d, 0] Getting connection from pool
2018-08-01 11:09:15.003 INFO  ConsumerImpl:168 | [persistent://sample/standalone/ns1/my-python-test-producer, reader-fd931d, 0] Created consumer on broker [127.0.0.1:38678 -> 127.0.0.1:8885] 
2018-08-01 11:09:15.005 INFO  ConsumerImpl:761 | [persistent://sample/standalone/ns1/my-python-test-producer, reader-fd931d, 0] Closed consumer 0
.2018-08-01 11:09:15.005 INFO  ConnectionPool:63 | Created connection for pulsar://localhost:8885
2018-08-01 11:09:15.006 INFO  ClientConnection:285 | [127.0.0.1:38680 -> 127.0.0.1:8885] Connected to broker
2018-08-01 11:09:15.009 INFO  HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-reader-has-message-available, ] Getting connection from pool
2018-08-01 11:09:15.066 INFO  ProducerImpl:154 | [persistent://sample/standalone/ns/my-python-topic-reader-has-message-available, ] Created producer on broker [127.0.0.1:38680 -> 127.0.0.1:8885] 
2018-08-01 11:09:15.069 INFO  HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-reader-has-message-available, reader-0cf5f6, 0] Getting connection from pool
2018-08-01 11:09:15.075 INFO  ConsumerImpl:168 | [persistent://sample/standalone/ns/my-python-topic-reader-has-message-available, reader-0cf5f6, 0] Created consumer on broker [127.0.0.1:38680 -> 127.0.0.1:8885] 
2018-08-01 11:09:15.292 INFO  ConsumerImpl:761 | [persistent://sample/standalone/ns/my-python-topic-reader-has-message-available, reader-0cf5f6, 0] Closed consumer 0
2018-08-01 11:09:15.293 INFO  ProducerImpl:466 | [persistent://sample/standalone/ns/my-python-topic-reader-has-message-available, standalone-0-94] Closed producer
.2018-08-01 11:09:15.294 INFO  ConnectionPool:63 | Created connection for pulsar://localhost:8885
2018-08-01 11:09:15.295 INFO  ClientConnection:285 | [127.0.0.1:38682 -> 127.0.0.1:8885] Connected to broker
2018-08-01 11:09:15.299 INFO  HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-reader-on-last-message, ] Getting connection from pool
2018-08-01 11:09:15.359 INFO  ProducerImpl:154 | [persistent://sample/standalone/ns/my-python-topic-reader-on-last-message, ] Created producer on broker [127.0.0.1:38682 -> 127.0.0.1:8885] 
2018-08-01 11:09:15.459 INFO  HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-reader-on-last-message, reader-fd726f, 0] Getting connection from pool
2018-08-01 11:09:15.463 INFO  ConsumerImpl:168 | [persistent://sample/standalone/ns/my-python-topic-reader-on-last-message, reader-fd726f, 0] Created consumer on broker [127.0.0.1:38682 -> 127.0.0.1:8885] 
2018-08-01 11:09:15.557 INFO  ConsumerImpl:761 | [persistent://sample/standalone/ns/my-python-topic-reader-on-last-message, reader-fd726f, 0] Closed consumer 0
2018-08-01 11:09:15.558 INFO  ProducerImpl:466 | [persistent://sample/standalone/ns/my-python-topic-reader-on-last-message, standalone-0-95] Closed producer
.2018-08-01 11:09:15.559 INFO  ConnectionPool:63 | Created connection for pulsar://localhost:8885
2018-08-01 11:09:15.560 INFO  ClientConnection:285 | [127.0.0.1:38684 -> 127.0.0.1:8885] Connected to broker
2018-08-01 11:09:15.563 INFO  HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message, ] Getting connection from pool
2018-08-01 11:09:15.641 INFO  ProducerImpl:154 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message, ] Created producer on broker [127.0.0.1:38684 -> 127.0.0.1:8885] 
2018-08-01 11:09:15.742 INFO  HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message, reader-71e801, 0] Getting connection from pool
2018-08-01 11:09:15.747 INFO  ConsumerImpl:168 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message, reader-71e801, 0] Created consumer on broker [127.0.0.1:38684 -> 127.0.0.1:8885] 
2018-08-01 11:09:15.753 INFO  HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message, reader-96e0ae, 1] Getting connection from pool
2018-08-01 11:09:15.756 INFO  ConsumerImpl:168 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message, reader-96e0ae, 1] Created consumer on broker [127.0.0.1:38684 -> 127.0.0.1:8885] 
2018-08-01 11:09:15.759 INFO  ConsumerImpl:761 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message, reader-71e801, 0] Closed consumer 0
2018-08-01 11:09:15.760 INFO  ConsumerImpl:761 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message, reader-96e0ae, 1] Closed consumer 1
2018-08-01 11:09:15.761 INFO  ProducerImpl:466 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message, standalone-0-96] Closed producer
.2018-08-01 11:09:15.761 INFO  ConnectionPool:63 | Created connection for pulsar://localhost:8885
2018-08-01 11:09:15.762 INFO  ClientConnection:285 | [127.0.0.1:38686 -> 127.0.0.1:8885] Connected to broker
2018-08-01 11:09:15.765 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-08-01 11:09:15.766 INFO  HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message-with-batches, ] Getting connection from pool
2018-08-01 11:09:15.824 INFO  ProducerImpl:154 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message-with-batches, ] Created producer on broker [127.0.0.1:38686 -> 127.0.0.1:8885] 
2018-08-01 11:09:16.851 INFO  HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message-with-batches, reader-9bfafa, 0] Getting connection from pool
2018-08-01 11:09:16.853 INFO  ConsumerImpl:168 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message-with-batches, reader-9bfafa, 0] Created consumer on broker [127.0.0.1:38686 -> 127.0.0.1:8885] 
2018-08-01 11:09:16.857 INFO  HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message-with-batches, reader-f84fb2, 1] Getting connection from pool
2018-08-01 11:09:16.859 INFO  ConsumerImpl:168 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message-with-batches, reader-f84fb2, 1] Created consumer on broker [127.0.0.1:38686 -> 127.0.0.1:8885] 
2018-08-01 11:09:16.861 INFO  ConsumerImpl:761 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message-with-batches, reader-9bfafa, 0] Closed consumer 0
2018-08-01 11:09:16.861 INFO  ConsumerImpl:761 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message-with-batches, reader-f84fb2, 1] Closed consumer 1
2018-08-01 11:09:16.861 INFO  ProducerImpl:466 | [persistent://sample/standalone/ns/my-python-topic-reader-on-specific-message-with-batches, standalone-0-97] Closed producer
.2018-08-01 11:09:16.862 INFO  ConnectionPool:63 | Created connection for pulsar://localhost:8885
2018-08-01 11:09:16.862 INFO  ClientConnection:285 | [127.0.0.1:38688 -> 127.0.0.1:8885] Connected to broker
2018-08-01 11:09:16.864 INFO  HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-reader-simple, reader-a1bb1c, 0] Getting connection from pool
2018-08-01 11:09:16.915 INFO  ConsumerImpl:168 | [persistent://sample/standalone/ns/my-python-topic-reader-simple, reader-a1bb1c, 0] Created consumer on broker [127.0.0.1:38688 -> 127.0.0.1:8885] 
2018-08-01 11:09:16.917 INFO  HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-reader-simple, ] Getting connection from pool
2018-08-01 11:09:16.921 INFO  ProducerImpl:154 | [persistent://sample/standalone/ns/my-python-topic-reader-simple, ] Created producer on broker [127.0.0.1:38688 -> 127.0.0.1:8885] 
2018-08-01 11:09:17.043 INFO  ConsumerImpl:761 | [persistent://sample/standalone/ns/my-python-topic-reader-simple, reader-a1bb1c, 0] Closed consumer 0
2018-08-01 11:09:17.043 INFO  ProducerImpl:466 | [persistent://sample/standalone/ns/my-python-topic-reader-simple, standalone-0-98] Closed producer
.2018-08-01 11:09:17.044 INFO  ConnectionPool:63 | Created connection for pulsar://localhost:8885
2018-08-01 11:09:17.045 INFO  ClientConnection:285 | [127.0.0.1:38690 -> 127.0.0.1:8885] Connected to broker
2018-08-01 11:09:17.048 INFO  HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-seek, my-sub, 0] Getting connection from pool
2018-08-01 11:09:17.172 INFO  ConsumerImpl:168 | [persistent://sample/standalone/ns/my-python-topic-seek, my-sub, 0] Created consumer on broker [127.0.0.1:38690 -> 127.0.0.1:8885] 
2018-08-01 11:09:17.175 INFO  HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-seek, ] Getting connection from pool
2018-08-01 11:09:17.180 INFO  ProducerImpl:154 | [persistent://sample/standalone/ns/my-python-topic-seek, ] Created producer on broker [127.0.0.1:38690 -> 127.0.0.1:8885] 
2018-08-01 11:09:18.110 INFO  HandlerBase:129 | [persistent://sample/standalone/ns/my-python-topic-seek, my-sub, 0] Schedule reconnection in 0.1 s
2018-08-01 11:09:18.116 INFO  ConsumerImpl:893 | [persistent://sample/standalone/ns/my-python-topic-seek, my-sub, 0] Seek successfully
2018-08-01 11:09:18.210 INFO  HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic-seek, my-sub, 0] Getting connection from pool
2018-08-01 11:09:18.214 INFO  ConsumerImpl:168 | [persistent://sample/standalone/ns/my-python-topic-seek, my-sub, 0] Created consumer on broker [127.0.0.1:38690 -> 127.0.0.1:8885] 
2018-08-01 11:09:18.618 INFO  ProducerImpl:466 | [persistent://sample/standalone/ns/my-python-topic-seek, standalone-0-99] Closed producer
2018-08-01 11:09:18.618 INFO  ConsumerImpl:761 | [persistent://sample/standalone/ns/my-python-topic-seek, my-sub, 0] Closed consumer 0
.2018-08-01 11:09:18.619 INFO  ConnectionPool:63 | Created connection for pulsar://localhost:8885
2018-08-01 11:09:18.620 INFO  ClientConnection:285 | [127.0.0.1:38692 -> 127.0.0.1:8885] Connected to broker
2018-08-01 11:09:18.622 INFO  HandlerBase:53 | [persistent://sample/standalone/ns/my-python-topic, ] Getting connection from pool
2018-08-01 11:09:18.627 INFO  ProducerImpl:154 | [persistent://sample/standalone/ns/my-python-topic, ] Created producer on broker [127.0.0.1:38692 -> 127.0.0.1:8885] 
2018-08-01 11:09:18.641 INFO  ProducerImpl:466 | [persistent://sample/standalone/ns/my-python-topic, standalone-0-100] Closed producer
.2018-08-01 11:09:18.642 INFO  ConnectionPool:63 | Created connection for pulsar+ssl://localhost:9886
2018-08-01 11:09:18.643 INFO  ClientConnection:285 | [127.0.0.1:37044 -> 127.0.0.1:9886] Connected to broker
2018-08-01 11:09:18.684 INFO  HandlerBase:53 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, my-sub, 0] Getting connection from pool
2018-08-01 11:09:18.687 INFO  ConnectionPool:63 | Created connection for pulsar://localhost:9886
2018-08-01 11:09:18.687 INFO  ClientConnection:287 | [127.0.0.1:37046 -> 127.0.0.1:9886] Connected to broker through proxy. Logical broker: pulsar://localhost:9886
2018-08-01 11:09:18.851 INFO  ConsumerImpl:168 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, my-sub, 0] Created consumer on broker [127.0.0.1:37046 -> 127.0.0.1:9886] 
2018-08-01 11:09:18.855 INFO  HandlerBase:53 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, ] Getting connection from pool
2018-08-01 11:09:18.864 INFO  ProducerImpl:154 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, ] Created producer on broker [127.0.0.1:37046 -> 127.0.0.1:9886] 
2018-08-01 11:09:18.999 INFO  ProducerImpl:466 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, cluster-0-1] Closed producer
2018-08-01 11:09:19.001 INFO  ConsumerImpl:761 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, my-sub, 0] Closed consumer 0
.2018-08-01 11:09:19.003 INFO  ConnectionPool:63 | Created connection for pulsar+ssl://localhost:9886
2018-08-01 11:09:19.004 INFO  ClientConnection:285 | [127.0.0.1:37048 -> 127.0.0.1:9886] Connected to broker
2018-08-01 11:09:19.034 INFO  HandlerBase:53 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, my-sub, 0] Getting connection from pool
2018-08-01 11:09:19.037 INFO  ConnectionPool:63 | Created connection for pulsar://localhost:9886
2018-08-01 11:09:19.037 INFO  ClientConnection:287 | [127.0.0.1:37050 -> 127.0.0.1:9886] Connected to broker through proxy. Logical broker: pulsar://localhost:9886
2018-08-01 11:09:19.059 INFO  ConsumerImpl:168 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, my-sub, 0] Created consumer on broker [127.0.0.1:37050 -> 127.0.0.1:9886] 
2018-08-01 11:09:19.061 INFO  HandlerBase:53 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, ] Getting connection from pool
2018-08-01 11:09:19.076 INFO  ProducerImpl:154 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, ] Created producer on broker [127.0.0.1:37050 -> 127.0.0.1:9886] 
2018-08-01 11:09:19.113 INFO  ProducerImpl:466 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, cluster-0-2] Closed producer
2018-08-01 11:09:19.114 INFO  ConsumerImpl:761 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, my-sub, 0] Closed consumer 0
.2018-08-01 11:09:19.116 INFO  ConnectionPool:63 | Created connection for pulsar+ssl://localhost:9886
2018-08-01 11:09:19.117 INFO  ClientConnection:285 | [127.0.0.1:37052 -> 127.0.0.1:9886] Connected to broker
2018-08-01 11:09:19.147 INFO  HandlerBase:53 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, my-sub, 0] Getting connection from pool
2018-08-01 11:09:19.150 INFO  ConnectionPool:63 | Created connection for pulsar://localhost:9886
2018-08-01 11:09:19.150 INFO  ClientConnection:287 | [127.0.0.1:37054 -> 127.0.0.1:9886] Connected to broker through proxy. Logical broker: pulsar://localhost:9886
2018-08-01 11:09:19.187 INFO  ConsumerImpl:168 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, my-sub, 0] Created consumer on broker [127.0.0.1:37054 -> 127.0.0.1:9886] 
2018-08-01 11:09:19.190 INFO  HandlerBase:53 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, ] Getting connection from pool
2018-08-01 11:09:19.198 INFO  ProducerImpl:154 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, ] Created producer on broker [127.0.0.1:37054 -> 127.0.0.1:9886] 
2018-08-01 11:09:19.243 INFO  ProducerImpl:466 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, cluster-0-3] Closed producer
2018-08-01 11:09:19.245 INFO  ConsumerImpl:761 | [persistent://property/cluster/namespace/my-python-topic-producer-consumer, my-sub, 0] Closed consumer 0
.2018-08-01 11:09:19.246 INFO  ConnectionPool:63 | Created connection for pulsar://localhost:8885
2018-08-01 11:09:19.247 INFO  ClientConnection:285 | [127.0.0.1:38706 -> 127.0.0.1:8885] Connected to broker
2018-08-01 11:09:19.251 INFO  HandlerBase:53 | [persistent://public/default/my-v2-topic-producer-consumer, my-sub, 0] Getting connection from pool
2018-08-01 11:09:19.378 INFO  ConsumerImpl:168 | [persistent://public/default/my-v2-topic-producer-consumer, my-sub, 0] Created consumer on broker [127.0.0.1:38706 -> 127.0.0.1:8885] 
2018-08-01 11:09:19.382 INFO  HandlerBase:53 | [persistent://public/default/my-v2-topic-producer-consumer, ] Getting connection from pool
2018-08-01 11:09:19.384 INFO  ProducerImpl:154 | [persistent://public/default/my-v2-topic-producer-consumer, ] Created producer on broker [127.0.0.1:38706 -> 127.0.0.1:8885] 
2018-08-01 11:09:19.509 INFO  ProducerImpl:466 | [persistent://public/default/my-v2-topic-producer-consumer, standalone-0-101] Closed producer
2018-08-01 11:09:19.509 INFO  ConsumerImpl:761 | [persistent://public/default/my-v2-topic-producer-consumer, my-sub, 0] Closed consumer 0
.2018-08-01 11:09:19.510 INFO  HTTPLookupService:161 | Curl Lookup Request sent forhttp://localhost:8765/admin/v2/persistent/public/default/my-v2-topic-producer-consumer/partitions
2018-08-01 11:09:19.522 INFO  HTTPLookupService:173 | Response received for url http://localhost:8765/admin/v2/persistent/public/default/my-v2-topic-producer-consumer/partitions code 200
2018-08-01 11:09:19.522 INFO  HTTPLookupService:214 | parsePartitionData = { LookupDataResult [brokerUrl_ = ] [brokerUrlSsl_ = ] [partitions = 0] [authoritative = 0] [redirect = 0] proxyThroughServiceUrl = 0] }
2018-08-01 11:09:19.522 INFO  HandlerBase:53 | [persistent://public/default/my-v2-topic-producer-consumer, my-sub, 0] Getting connection from pool
2018-08-01 11:09:19.522 INFO  HTTPLookupService:161 | Curl Lookup Request sent forhttp://localhost:8765/lookup/v2/topic/persistent/public/default/my-v2-topic-producer-consumer
2018-08-01 11:09:19.532 INFO  HTTPLookupService:173 | Response received for url http://localhost:8765/lookup/v2/topic/persistent/public/default/my-v2-topic-producer-consumer code 200
2018-08-01 11:09:19.532 INFO  HTTPLookupService:243 | parseLookupData = { LookupDataResult [brokerUrl_ = pulsar://localhost:8885] [brokerUrlSsl_ = ] [partitions = 0] [authoritative = 0] [redirect = 0] proxyThroughServiceUrl = 0] }
2018-08-01 11:09:19.532 INFO  ConnectionPool:63 | Created connection for pulsar://localhost:8885
2018-08-01 11:09:19.533 INFO  ClientConnection:285 | [127.0.0.1:38712 -> 127.0.0.1:8885] Connected to broker
2018-08-01 11:09:19.535 INFO  ConsumerImpl:168 | [persistent://public/default/my-v2-topic-producer-consumer, my-sub, 0] Created consumer on broker [127.0.0.1:38712 -> 127.0.0.1:8885] 
2018-08-01 11:09:19.536 INFO  HTTPLookupService:161 | Curl Lookup Request sent forhttp://localhost:8765/admin/v2/persistent/public/default/my-v2-topic-producer-consumer/partitions
2018-08-01 11:09:19.544 INFO  HTTPLookupService:173 | Response received for url http://localhost:8765/admin/v2/persistent/public/default/my-v2-topic-producer-consumer/partitions code 200
2018-08-01 11:09:19.545 INFO  HTTPLookupService:214 | parsePartitionData = { LookupDataResult [brokerUrl_ = ] [brokerUrlSsl_ = ] [partitions = 0] [authoritative = 0] [redirect = 0] proxyThroughServiceUrl = 0] }
2018-08-01 11:09:19.545 INFO  HandlerBase:53 | [persistent://public/default/my-v2-topic-producer-consumer, ] Getting connection from pool
2018-08-01 11:09:19.545 INFO  HTTPLookupService:161 | Curl Lookup Request sent forhttp://localhost:8765/lookup/v2/topic/persistent/public/default/my-v2-topic-producer-consumer
2018-08-01 11:09:19.552 INFO  HTTPLookupService:173 | Response received for url http://localhost:8765/lookup/v2/topic/persistent/public/default/my-v2-topic-producer-consumer code 200
2018-08-01 11:09:19.552 INFO  HTTPLookupService:243 | parseLookupData = { LookupDataResult [brokerUrl_ = pulsar://localhost:8885] [brokerUrlSsl_ = ] [partitions = 0] [authoritative = 0] [redirect = 0] proxyThroughServiceUrl = 0] }
2018-08-01 11:09:19.553 INFO  ProducerImpl:154 | [persistent://public/default/my-v2-topic-producer-consumer, ] Created producer on broker [127.0.0.1:38712 -> 127.0.0.1:8885] 
2018-08-01 11:09:19.696 INFO  ProducerImpl:466 | [persistent://public/default/my-v2-topic-producer-consumer, standalone-0-102] Closed producer
2018-08-01 11:09:19.697 INFO  ConsumerImpl:761 | [persistent://public/default/my-v2-topic-producer-consumer, my-sub, 0] Closed consumer 0
.
----------------------------------------------------------------------
Ran 27 tests in 9.627s

OK
/pulsar/pulsar-client-cpp/python /pulsar/pulsar-client-cpp
/pulsar/pulsar-client-cpp
[pulsar-master] $ /bin/bash -xe /tmp/jenkins6284566787009275624.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 486, 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.4 kB/s)
Downloading: https://repo1.maven.org/maven2/org/apache/pulsar/buildtools/2.2.0-incubating-SNAPSHOT/buildtools-2.2.0-incubating-20180731.124044-33.pom
Downloading: http://repository.apache.org/snapshots/org/apache/pulsar/buildtools/2.2.0-incubating-SNAPSHOT/buildtools-2.2.0-incubating-20180731.124044-33.pom
[WARNING] The POM for org.apache.pulsar:buildtools:jar:2.2.0-incubating-20180731.124044-33 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-20180731.124044-33.jar
[INFO] ------------------------------------------------------------------------
[INFO] Reactor Summary:
[INFO] 
[INFO] Apache Pulsar :: Docker Images ..................... FAILURE [  2.035 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: 2.721 s
[INFO] Finished at: 2018-08-01T11:09:25Z
[INFO] Final Memory: 21M/1160M
[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-20180731.124044-33 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 noreply@github.com
Not sending mail to unregistered user guosijie@gmail.com
Not sending mail to unregistered user y.shiga.91+yush1ga@gmail.com

Jenkins build is unstable: pulsar-master #669

Posted by Apache Jenkins Server <je...@builds.apache.org>.
See <https://builds.apache.org/job/pulsar-master/669/display/redirect?page=changes>