beihai0xff commented on issue #12539:
URL:
https://github.com/apache/dolphinscheduler/issues/12539#issuecomment-1291667811
> Can you provide more detail logs of `standalone-server` through `docker
logs xxx`? @beihai0xff
```log
bk.SimpleLedgerAllocator - Ledger allocator for
/stream/storage/000000000000000001/000000000000000001/000000000000000000/checkpoints/f4900860-b1a0-4027-ac56-5e21191f2880/metadata/<default>/allocation
moved version from 2 to 3.
2022-10-26T08:03:11,416+0000 [main-EventThread] INFO
org.apache.distributedlog.bk.SimpleLedgerAllocator - Ledger allocator
/stream/storage/000000000000000001/000000000000000001/000000000000000000/checkpoints/f4900860-b1a0-4027-ac56-5e21191f2880/metadata/<default>/allocation
moved to phase ALLOCATED : version = 3.
2022-10-26T08:03:11,430+0000 [DLM-/stream/storage-OrderedScheduler-1-0] INFO
org.apache.distributedlog.BKLogSegmentWriter - Flushing before closing log
segment
000000000000000001/000000000000000001/000000000000000000/checkpoints/f4900860-b1a0-4027-ac56-5e21191f2880/metadata:<default>:inprogress_000000000000000001
2022-10-26T08:03:11,447+0000 [BookKeeperClientWorker-OrderedExecutor-0-0]
INFO org.apache.distributedlog.BKLogSegmentWriter - Closing
BKPerStreamLogWriter (abort=false) for
000000000000000001/000000000000000001/000000000000000000/checkpoints/f4900860-b1a0-4027-ac56-5e21191f2880/metadata:<default>:inprogress_000000000000000001
: lastDLSN = DLSN{logSegmentSequenceNo=1, entryId=0, slotId=0}
outstandingTransmits = 1 writesPendingTransmit = 0
2022-10-26T08:03:11,447+0000 [BookKeeperClientWorker-OrderedExecutor-0-0]
INFO org.apache.distributedlog.BKLogSegmentWriter - Stream
000000000000000001/000000000000000001/000000000000000000/checkpoints/f4900860-b1a0-4027-ac56-5e21191f2880/metadata:<default>:inprogress_000000000000000001
aborted 0 writes
2022-10-26T08:03:11,466+0000
[DLM-/stream/storage-OrderedScheduler-1-0-EventThread] INFO
org.apache.distributedlog.BKLogWriteHandler - Try storing max sequence number 1
in completing
/stream/storage/000000000000000001/000000000000000001/000000000000000000/checkpoints/f4900860-b1a0-4027-ac56-5e21191f2880/metadata/<default>/ledgers/inprogress_000000000000000001.
2022-10-26T08:03:11,468+0000
[DLM-/stream/storage-OrderedScheduler-1-0-EventThread] INFO
org.apache.distributedlog.impl.ZKLogSegmentMetadataStore - Delete segment :
[LogSegmentId:514, firstTxId:305, lastTxId:-999,
version:VERSION_V5_SEQUENCE_ID, completionTime:0, recordCount:0, regionId:0,
status:0, logSegmentSequenceNumber:1, lastEntryId:-1, lastSlotId:-1,
inprogress:true, minActiveDLSN:DLSN{logSegmentSequenceNo=1, entryId=0,
slotId=0}, startSequenceId:-1]
2022-10-26T08:03:11,484+0000 [main-EventThread] INFO
org.apache.distributedlog.logsegment.PerStreamLogSegmentCache -
000000000000000001/000000000000000001/000000000000000000/checkpoints/f4900860-b1a0-4027-ac56-5e21191f2880/metadata
added log segment (logrecs_000000000000000001 : [LogSegmentId:514,
firstTxId:305, lastTxId:305, version:VERSION_V5_SEQUENCE_ID,
completionTime:1666771391467, recordCount:1, regionId:0, status:0,
logSegmentSequenceNumber:1, lastEntryId:0, lastSlotId:0, inprogress:false,
minActiveDLSN:DLSN{logSegmentSequenceNo=1, entryId=0, slotId=0},
startSequenceId:0]) to cache.
2022-10-26T08:03:11,499+0000 [DLM-/stream/storage-OrderedScheduler-1-0] INFO
org.apache.distributedlog.BKLogWriteHandler - Completed
inprogress_000000000000000001 to logrecs_000000000000000001 for
000000000000000001/000000000000000001/000000000000000000/checkpoints/f4900860-b1a0-4027-ac56-5e21191f2880/metadata:<default>
: [LogSegmentId:514, firstTxId:305, lastTxId:305,
version:VERSION_V5_SEQUENCE_ID, completionTime:1666771391467, recordCount:1,
regionId:0, status:0, logSegmentSequenceNumber:1, lastEntryId:0, lastSlotId:0,
inprogress:false, minActiveDLSN:DLSN{logSegmentSequenceNo=1, entryId=0,
slotId=0}, startSequenceId:0]
2022-10-26T08:03:11,503+0000 [DLM-/stream/storage-OrderedScheduler-2-0] INFO
org.apache.distributedlog.bk.SimpleLedgerAllocator - Abort ledger allocator
without cleaning up on
/stream/storage/000000000000000001/000000000000000001/000000000000000000/checkpoints/f4900860-b1a0-4027-ac56-5e21191f2880/metadata/<default>/allocation.
2022-10-26T08:03:11,528+0000 [main-EventThread] INFO
org.apache.distributedlog.bk.SimpleLedgerAllocator - Ledger allocator for
/stream/storage/000000000000000001/000000000000000001/000000000000000000/checkpoints/7df133f2-dbeb-44ea-a01f-890349759c1e/MANIFEST-000008/<default>/allocation
moved version from -1 to 3.
2022-10-26T08:03:11,532+0000 [main-EventThread] INFO
org.apache.distributedlog.logsegment.PerStreamLogSegmentCache -
000000000000000001/000000000000000001/000000000000000000/checkpoints/7df133f2-dbeb-44ea-a01f-890349759c1e/MANIFEST-000008
added log segment (logrecs_000000000000000001 : [LogSegmentId:488,
firstTxId:138, lastTxId:138, version:VERSION_V5_SEQUENCE_ID,
completionTime:1666770491046, recordCount:1, regionId:0, status:0,
logSegmentSequenceNumber:1, lastEntryId:0, lastSlotId:0, inprogress:false,
minActiveDLSN:DLSN{logSegmentSequenceNo=1, entryId=0, slotId=0},
startSequenceId:0]) to cache.
2022-10-26T08:03:11,533+0000 [DLM-/stream/storage-OrderedScheduler-1-0] INFO
org.apache.distributedlog.BKLogWriteHandler - Deleting ledger
[LogSegmentId:488, firstTxId:138, lastTxId:138, version:VERSION_V5_SEQUENCE_ID,
completionTime:1666770491046, recordCount:1, regionId:0, status:0,
logSegmentSequenceNumber:1, lastEntryId:0, lastSlotId:0, inprogress:false,
minActiveDLSN:DLSN{logSegmentSequenceNo=1, entryId=0, slotId=0},
startSequenceId:0] for
000000000000000001/000000000000000001/000000000000000000/checkpoints/7df133f2-dbeb-44ea-a01f-890349759c1e/MANIFEST-000008:<default>
2022-10-26T08:03:11,537+0000 [BookKeeperClientWorker-OrderedExecutor-0-0]
INFO org.apache.distributedlog.impl.ZKLogSegmentMetadataStore - Delete segment
: [LogSegmentId:488, firstTxId:138, lastTxId:138,
version:VERSION_V5_SEQUENCE_ID, completionTime:1666770491046, recordCount:1,
regionId:0, status:0, logSegmentSequenceNumber:1, lastEntryId:0, lastSlotId:0,
inprogress:false, minActiveDLSN:DLSN{logSegmentSequenceNo=1, entryId=0,
slotId=0}, startSequenceId:0]
2022-10-26T08:03:11,565+0000 [main-EventThread] INFO
org.apache.distributedlog.bk.SimpleLedgerAllocator - Ledger allocator for
/stream/storage/000000000000000001/000000000000000001/000000000000000000/checkpoints/7df133f2-dbeb-44ea-a01f-890349759c1e/metadata/<default>/allocation
moved version from -1 to 3.
2022-10-26T08:03:11,579+0000 [main-EventThread] INFO
org.apache.distributedlog.logsegment.PerStreamLogSegmentCache -
000000000000000001/000000000000000001/000000000000000000/checkpoints/7df133f2-dbeb-44ea-a01f-890349759c1e/metadata
added log segment (logrecs_000000000000000001 : [LogSegmentId:490,
firstTxId:305, lastTxId:305, version:VERSION_V5_SEQUENCE_ID,
completionTime:1666770491228, recordCount:1, regionId:0, status:0,
logSegmentSequenceNumber:1, lastEntryId:0, lastSlotId:0, inprogress:false,
minActiveDLSN:DLSN{logSegmentSequenceNo=1, entryId=0, slotId=0},
startSequenceId:0]) to cache.
2022-10-26T08:03:11,581+0000 [DLM-/stream/storage-OrderedScheduler-1-0] INFO
org.apache.distributedlog.BKLogWriteHandler - Deleting ledger
[LogSegmentId:490, firstTxId:305, lastTxId:305, version:VERSION_V5_SEQUENCE_ID,
completionTime:1666770491228, recordCount:1, regionId:0, status:0,
logSegmentSequenceNumber:1, lastEntryId:0, lastSlotId:0, inprogress:false,
minActiveDLSN:DLSN{logSegmentSequenceNo=1, entryId=0, slotId=0},
startSequenceId:0] for
000000000000000001/000000000000000001/000000000000000000/checkpoints/7df133f2-dbeb-44ea-a01f-890349759c1e/metadata:<default>
2022-10-26T08:03:11,585+0000 [BookKeeperClientWorker-OrderedExecutor-0-0]
INFO org.apache.distributedlog.impl.ZKLogSegmentMetadataStore - Delete segment
: [LogSegmentId:490, firstTxId:305, lastTxId:305,
version:VERSION_V5_SEQUENCE_ID, completionTime:1666770491228, recordCount:1,
regionId:0, status:0, logSegmentSequenceNumber:1, lastEntryId:0, lastSlotId:0,
inprogress:false, minActiveDLSN:DLSN{logSegmentSequenceNo=1, entryId=0,
slotId=0}, startSequenceId:0]
2022-10-26T08:03:11,615+0000 [main-EventThread] INFO
org.apache.distributedlog.bk.SimpleLedgerAllocator - Ledger allocator for
/stream/storage/000000000000000001/000000000000000001/000000000000000000/checkpoints/7df133f2-dbeb-44ea-a01f-890349759c1e/OPTIONS-000012/<default>/allocation
moved version from -1 to 3.
2022-10-26T08:03:11,624+0000 [main-EventThread] INFO
org.apache.distributedlog.logsegment.PerStreamLogSegmentCache -
000000000000000001/000000000000000001/000000000000000000/checkpoints/7df133f2-dbeb-44ea-a01f-890349759c1e/OPTIONS-000012
added log segment (logrecs_000000000000000001 : [LogSegmentId:484,
firstTxId:8249, lastTxId:8249, version:VERSION_V5_SEQUENCE_ID,
completionTime:1666770490717, recordCount:1, regionId:0, status:0,
logSegmentSequenceNumber:1, lastEntryId:0, lastSlotId:0, inprogress:false,
minActiveDLSN:DLSN{logSegmentSequenceNo=1, entryId=0, slotId=0},
startSequenceId:0]) to cache.
2022-10-26T08:03:11,625+0000 [DLM-/stream/storage-OrderedScheduler-3-0] INFO
org.apache.distributedlog.BKLogWriteHandler - Deleting ledger
[LogSegmentId:484, firstTxId:8249, lastTxId:8249,
version:VERSION_V5_SEQUENCE_ID, completionTime:1666770490717, recordCount:1,
regionId:0, status:0, logSegmentSequenceNumber:1, lastEntryId:0, lastSlotId:0,
inprogress:false, minActiveDLSN:DLSN{logSegmentSequenceNo=1, entryId=0,
slotId=0}, startSequenceId:0] for
000000000000000001/000000000000000001/000000000000000000/checkpoints/7df133f2-dbeb-44ea-a01f-890349759c1e/OPTIONS-000012:<default>
2022-10-26T08:03:11,629+0000 [BookKeeperClientWorker-OrderedExecutor-0-0]
INFO org.apache.distributedlog.impl.ZKLogSegmentMetadataStore - Delete segment
: [LogSegmentId:484, firstTxId:8249, lastTxId:8249,
version:VERSION_V5_SEQUENCE_ID, completionTime:1666770490717, recordCount:1,
regionId:0, status:0, logSegmentSequenceNumber:1, lastEntryId:0, lastSlotId:0,
inprogress:false, minActiveDLSN:DLSN{logSegmentSequenceNo=1, entryId=0,
slotId=0}, startSequenceId:0]
2022-10-26T08:03:11,660+0000 [main-EventThread] INFO
org.apache.distributedlog.bk.SimpleLedgerAllocator - Ledger allocator for
/stream/storage/000000000000000001/000000000000000001/000000000000000000/checkpoints/7df133f2-dbeb-44ea-a01f-890349759c1e/CURRENT/<default>/allocation
moved version from -1 to 3.
2022-10-26T08:03:11,666+0000 [main-EventThread] INFO
org.apache.distributedlog.logsegment.PerStreamLogSegmentCache -
000000000000000001/000000000000000001/000000000000000000/checkpoints/7df133f2-dbeb-44ea-a01f-890349759c1e/CURRENT
added log segment (logrecs_000000000000000001 : [LogSegmentId:486,
firstTxId:16, lastTxId:16, version:VERSION_V5_SEQUENCE_ID,
completionTime:1666770490897, recordCount:1, regionId:0, status:0,
logSegmentSequenceNumber:1, lastEntryId:0, lastSlotId:0, inprogress:false,
minActiveDLSN:DLSN{logSegmentSequenceNo=1, entryId=0, slotId=0},
startSequenceId:0]) to cache.
2022-10-26T08:03:11,667+0000 [DLM-/stream/storage-OrderedScheduler-2-0] INFO
org.apache.distributedlog.BKLogWriteHandler - Deleting ledger
[LogSegmentId:486, firstTxId:16, lastTxId:16, version:VERSION_V5_SEQUENCE_ID,
completionTime:1666770490897, recordCount:1, regionId:0, status:0,
logSegmentSequenceNumber:1, lastEntryId:0, lastSlotId:0, inprogress:false,
minActiveDLSN:DLSN{logSegmentSequenceNo=1, entryId=0, slotId=0},
startSequenceId:0] for
000000000000000001/000000000000000001/000000000000000000/checkpoints/7df133f2-dbeb-44ea-a01f-890349759c1e/CURRENT:<default>
2022-10-26T08:03:11,672+0000 [BookKeeperClientWorker-OrderedExecutor-0-0]
INFO org.apache.distributedlog.impl.ZKLogSegmentMetadataStore - Delete segment
: [LogSegmentId:486, firstTxId:16, lastTxId:16, version:VERSION_V5_SEQUENCE_ID,
completionTime:1666770490897, recordCount:1, regionId:0, status:0,
logSegmentSequenceNumber:1, lastEntryId:0, lastSlotId:0, inprogress:false,
minActiveDLSN:DLSN{logSegmentSequenceNo=1, entryId=0, slotId=0},
startSequenceId:0]
2022-10-26T08:03:11,699+0000 [main-EventThread] INFO
org.apache.distributedlog.bk.SimpleLedgerAllocator - Ledger allocator for
/stream/storage/000000000000000001/000000000000000001/000000000000000000/checkpoints/7df133f2-dbeb-44ea-a01f-890349759c1e/<default>/allocation
moved version from -1 to 0.
2022-10-26T08:03:11,713+0000 [io-checkpoint-scheduler-OrderedScheduler-1-0]
INFO
org.apache.bookkeeper.statelib.impl.rocksdb.checkpoint.RocksdbCheckpointTask -
Delete remote checkpoint 7df133f2-dbeb-44ea-a01f-890349759c1e from checkpoint
store at
000000000000000001/000000000000000001/000000000000000000/checkpoints/7df133f2-dbeb-44ea-a01f-890349759c1e
2022-10-26T08:03:11,718+0000 [io-checkpoint-scheduler-OrderedScheduler-1-0]
INFO
org.apache.bookkeeper.statelib.impl.rocksdb.checkpoint.RocksdbCheckpointTask -
Create a local checkpoint of state store
000000000000000000/000000000000000001/000000000000000000 at
/pulsar/data/standalone/bookkeeper/ranges/data/ranges/000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932
2022-10-26T08:03:11,760+0000 [main-EventThread] INFO
org.apache.distributedlog.bk.SimpleLedgerAllocator - Ledger allocator for
/stream/storage/000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/OPTIONS-000012/<default>/allocation
moved version from -1 to 0.
2022-10-26T08:03:11,776+0000 [main-EventThread] INFO
org.apache.distributedlog.BKLogWriteHandler - Initiating Recovery For
000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/OPTIONS-000012:<default>
: []
2022-10-26T08:03:11,785+0000 [io-checkpoint-scheduler-OrderedScheduler-1-0]
INFO org.apache.distributedlog.BKLogWriteHandler - Initiating Recovery For
000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/OPTIONS-000012:<default>
: []
2022-10-26T08:03:11,799+0000 [DLM-/stream/storage-OrderedScheduler-1-0] INFO
org.apache.distributedlog.bk.SimpleLedgerAllocator - Ledger allocator
/stream/storage/000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/OPTIONS-000012/<default>/allocation
moved to phase ALLOCATING : version = 0.
2022-10-26T08:03:11,813+0000
[DLM-/stream/storage-OrderedScheduler-1-0-EventThread] INFO
org.apache.bookkeeper.client.LedgerCreateOp - Ensemble: [127.0.0.1:3181] for
ledger: 516
2022-10-26T08:03:11,816+0000 [main-EventThread] INFO
org.apache.distributedlog.bk.SimpleLedgerAllocator - Ledger allocator for
/stream/storage/000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/OPTIONS-000012/<default>/allocation
moved version from 0 to 1.
2022-10-26T08:03:11,816+0000 [main-EventThread] INFO
org.apache.distributedlog.bk.SimpleLedgerAllocator - Ledger allocator
/stream/storage/000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/OPTIONS-000012/<default>/allocation
moved to phase ALLOCATED : version = 1.
2022-10-26T08:03:11,816+0000 [main-EventThread] INFO
org.apache.distributedlog.bk.SimpleLedgerAllocator - Ledger allocator
/stream/storage/000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/OPTIONS-000012/<default>/allocation
moved to phase HANDING_OVER : version = 1.
2022-10-26T08:03:11,817+0000 [main-EventThread] INFO
org.apache.distributedlog.BKLogWriteHandler - No max ledger sequence number
found while creating log segment 1 for
000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/OPTIONS-000012:<default>.
2022-10-26T08:03:11,822+0000 [main-EventThread] INFO
org.apache.distributedlog.bk.SimpleLedgerAllocator - Ledger allocator
/stream/storage/000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/OPTIONS-000012/<default>/allocation
moved to phase HANDED_OVER : version = 1.
2022-10-26T08:03:11,822+0000 [main-EventThread] INFO
org.apache.distributedlog.bk.SimpleLedgerAllocator - Ledger allocator for
/stream/storage/000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/OPTIONS-000012/<default>/allocation
moved version from 1 to 2.
2022-10-26T08:03:11,822+0000 [main-EventThread] INFO
org.apache.distributedlog.bk.SimpleLedgerAllocator - Ledger allocator
/stream/storage/000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/OPTIONS-000012/<default>/allocation
moved to phase ALLOCATING : version = 2.
2022-10-26T08:03:11,824+0000 [main-EventThread] INFO
org.apache.distributedlog.logsegment.PerStreamLogSegmentCache -
000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/OPTIONS-000012
added log segment (inprogress_000000000000000001 : [LogSegmentId:516,
firstTxId:8249, lastTxId:-999, version:VERSION_V5_SEQUENCE_ID,
completionTime:0, recordCount:0, regionId:0, status:0,
logSegmentSequenceNumber:1, lastEntryId:-1, lastSlotId:-1, inprogress:true,
minActiveDLSN:DLSN{logSegmentSequenceNo=1, entryId=0, slotId=0},
startSequenceId:-1]) to cache.
2022-10-26T08:03:11,862+0000
[DLM-/stream/storage-OrderedScheduler-1-0-EventThread] INFO
org.apache.bookkeeper.client.LedgerCreateOp - Ensemble: [127.0.0.1:3181] for
ledger: 517
2022-10-26T08:03:11,877+0000 [main-EventThread] INFO
org.apache.distributedlog.BKLogWriteHandler - Deleting log segments older than
1666512191837 for
000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/OPTIONS-000012:<default>
: []
2022-10-26T08:03:11,881+0000 [main-EventThread] INFO
org.apache.distributedlog.bk.SimpleLedgerAllocator - Ledger allocator for
/stream/storage/000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/OPTIONS-000012/<default>/allocation
moved version from 2 to 3.
2022-10-26T08:03:11,881+0000 [main-EventThread] INFO
org.apache.distributedlog.bk.SimpleLedgerAllocator - Ledger allocator
/stream/storage/000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/OPTIONS-000012/<default>/allocation
moved to phase ALLOCATED : version = 3.
2022-10-26T08:03:11,886+0000 [DLM-/stream/storage-OrderedScheduler-1-0] INFO
org.apache.distributedlog.BKLogSegmentWriter - Flushing before closing log
segment
000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/OPTIONS-000012:<default>:inprogress_000000000000000001
2022-10-26T08:03:11,892+0000 [BookKeeperClientWorker-OrderedExecutor-0-0]
INFO org.apache.distributedlog.BKLogSegmentWriter - Closing
BKPerStreamLogWriter (abort=false) for
000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/OPTIONS-000012:<default>:inprogress_000000000000000001
: lastDLSN = DLSN{logSegmentSequenceNo=1, entryId=0, slotId=0}
outstandingTransmits = 1 writesPendingTransmit = 0
2022-10-26T08:03:11,893+0000 [BookKeeperClientWorker-OrderedExecutor-0-0]
INFO org.apache.distributedlog.BKLogSegmentWriter - Stream
000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/OPTIONS-000012:<default>:inprogress_000000000000000001
aborted 0 writes
2022-10-26T08:03:11,902+0000
[DLM-/stream/storage-OrderedScheduler-1-0-EventThread] INFO
org.apache.distributedlog.BKLogWriteHandler - Try storing max sequence number 1
in completing
/stream/storage/000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/OPTIONS-000012/<default>/ledgers/inprogress_000000000000000001.
2022-10-26T08:03:11,903+0000
[DLM-/stream/storage-OrderedScheduler-1-0-EventThread] INFO
org.apache.distributedlog.impl.ZKLogSegmentMetadataStore - Delete segment :
[LogSegmentId:516, firstTxId:8249, lastTxId:-999,
version:VERSION_V5_SEQUENCE_ID, completionTime:0, recordCount:0, regionId:0,
status:0, logSegmentSequenceNumber:1, lastEntryId:-1, lastSlotId:-1,
inprogress:true, minActiveDLSN:DLSN{logSegmentSequenceNo=1, entryId=0,
slotId=0}, startSequenceId:-1]
2022-10-26T08:03:11,916+0000 [main-EventThread] INFO
org.apache.distributedlog.logsegment.PerStreamLogSegmentCache -
000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/OPTIONS-000012
added log segment (logrecs_000000000000000001 : [LogSegmentId:516,
firstTxId:8249, lastTxId:8249, version:VERSION_V5_SEQUENCE_ID,
completionTime:1666771391903, recordCount:1, regionId:0, status:0,
logSegmentSequenceNumber:1, lastEntryId:0, lastSlotId:0, inprogress:false,
minActiveDLSN:DLSN{logSegmentSequenceNo=1, entryId=0, slotId=0},
startSequenceId:0]) to cache.
2022-10-26T08:03:11,917+0000 [DLM-/stream/storage-OrderedScheduler-3-0] INFO
org.apache.distributedlog.BKLogWriteHandler - Completed
inprogress_000000000000000001 to logrecs_000000000000000001 for
000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/OPTIONS-000012:<default>
: [LogSegmentId:516, firstTxId:8249, lastTxId:8249,
version:VERSION_V5_SEQUENCE_ID, completionTime:1666771391903, recordCount:1,
regionId:0, status:0, logSegmentSequenceNumber:1, lastEntryId:0, lastSlotId:0,
inprogress:false, minActiveDLSN:DLSN{logSegmentSequenceNo=1, entryId=0,
slotId=0}, startSequenceId:0]
2022-10-26T08:03:11,919+0000 [DLM-/stream/storage-OrderedScheduler-0-0] INFO
org.apache.distributedlog.bk.SimpleLedgerAllocator - Abort ledger allocator
without cleaning up on
/stream/storage/000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/OPTIONS-000012/<default>/allocation.
2022-10-26T08:03:11,940+0000 [main-EventThread] INFO
org.apache.distributedlog.bk.SimpleLedgerAllocator - Ledger allocator for
/stream/storage/000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/CURRENT/<default>/allocation
moved version from -1 to 0.
2022-10-26T08:03:11,955+0000 [main-EventThread] INFO
org.apache.distributedlog.BKLogWriteHandler - Initiating Recovery For
000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/CURRENT:<default>
: []
2022-10-26T08:03:11,963+0000 [io-checkpoint-scheduler-OrderedScheduler-1-0]
INFO org.apache.distributedlog.BKLogWriteHandler - Initiating Recovery For
000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/CURRENT:<default>
: []
2022-10-26T08:03:11,986+0000 [DLM-/stream/storage-OrderedScheduler-0-0] INFO
org.apache.distributedlog.bk.SimpleLedgerAllocator - Ledger allocator
/stream/storage/000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/CURRENT/<default>/allocation
moved to phase ALLOCATING : version = 0.
2022-10-26T08:03:12,005+0000
[DLM-/stream/storage-OrderedScheduler-1-0-EventThread] INFO
org.apache.bookkeeper.client.LedgerCreateOp - Ensemble: [127.0.0.1:3181] for
ledger: 518
2022-10-26T08:03:12,012+0000 [main-EventThread] INFO
org.apache.distributedlog.bk.SimpleLedgerAllocator - Ledger allocator for
/stream/storage/000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/CURRENT/<default>/allocation
moved version from 0 to 1.
2022-10-26T08:03:12,012+0000 [main-EventThread] INFO
org.apache.distributedlog.bk.SimpleLedgerAllocator - Ledger allocator
/stream/storage/000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/CURRENT/<default>/allocation
moved to phase ALLOCATED : version = 1.
2022-10-26T08:03:12,012+0000 [main-EventThread] INFO
org.apache.distributedlog.bk.SimpleLedgerAllocator - Ledger allocator
/stream/storage/000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/CURRENT/<default>/allocation
moved to phase HANDING_OVER : version = 1.
2022-10-26T08:03:12,013+0000 [main-EventThread] INFO
org.apache.distributedlog.BKLogWriteHandler - No max ledger sequence number
found while creating log segment 1 for
000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/CURRENT:<default>.
2022-10-26T08:03:12,018+0000 [main-EventThread] INFO
org.apache.distributedlog.bk.SimpleLedgerAllocator - Ledger allocator
/stream/storage/000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/CURRENT/<default>/allocation
moved to phase HANDED_OVER : version = 1.
2022-10-26T08:03:12,018+0000 [main-EventThread] INFO
org.apache.distributedlog.bk.SimpleLedgerAllocator - Ledger allocator for
/stream/storage/000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/CURRENT/<default>/allocation
moved version from 1 to 2.
2022-10-26T08:03:12,018+0000 [main-EventThread] INFO
org.apache.distributedlog.bk.SimpleLedgerAllocator - Ledger allocator
/stream/storage/000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/CURRENT/<default>/allocation
moved to phase ALLOCATING : version = 2.
2022-10-26T08:03:12,021+0000 [main-EventThread] INFO
org.apache.distributedlog.logsegment.PerStreamLogSegmentCache -
000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/CURRENT
added log segment (inprogress_000000000000000001 : [LogSegmentId:518,
firstTxId:16, lastTxId:-999, version:VERSION_V5_SEQUENCE_ID, completionTime:0,
recordCount:0, regionId:0, status:0, logSegmentSequenceNumber:1,
lastEntryId:-1, lastSlotId:-1, inprogress:true,
minActiveDLSN:DLSN{logSegmentSequenceNo=1, entryId=0, slotId=0},
startSequenceId:-1]) to cache.
2022-10-26T08:03:12,049+0000
[DLM-/stream/storage-OrderedScheduler-1-0-EventThread] INFO
org.apache.bookkeeper.client.LedgerCreateOp - Ensemble: [127.0.0.1:3181] for
ledger: 519
2022-10-26T08:03:12,071+0000 [main-EventThread] INFO
org.apache.distributedlog.BKLogWriteHandler - Deleting log segments older than
1666512192032 for
000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/CURRENT:<default>
: []
2022-10-26T08:03:12,073+0000 [main-EventThread] INFO
org.apache.distributedlog.bk.SimpleLedgerAllocator - Ledger allocator for
/stream/storage/000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/CURRENT/<default>/allocation
moved version from 2 to 3.
2022-10-26T08:03:12,073+0000 [main-EventThread] INFO
org.apache.distributedlog.bk.SimpleLedgerAllocator - Ledger allocator
/stream/storage/000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/CURRENT/<default>/allocation
moved to phase ALLOCATED : version = 3.
2022-10-26T08:03:12,080+0000 [DLM-/stream/storage-OrderedScheduler-2-0] INFO
org.apache.distributedlog.BKLogSegmentWriter - Flushing before closing log
segment
000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/CURRENT:<default>:inprogress_000000000000000001
2022-10-26T08:03:12,084+0000 [BookKeeperClientWorker-OrderedExecutor-0-0]
INFO org.apache.distributedlog.BKLogSegmentWriter - Closing
BKPerStreamLogWriter (abort=false) for
000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/CURRENT:<default>:inprogress_000000000000000001
: lastDLSN = DLSN{logSegmentSequenceNo=1, entryId=0, slotId=0}
outstandingTransmits = 1 writesPendingTransmit = 0
2022-10-26T08:03:12,085+0000 [BookKeeperClientWorker-OrderedExecutor-0-0]
INFO org.apache.distributedlog.BKLogSegmentWriter - Stream
000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/CURRENT:<default>:inprogress_000000000000000001
aborted 0 writes
2022-10-26T08:03:12,104+0000
[DLM-/stream/storage-OrderedScheduler-1-0-EventThread] INFO
org.apache.distributedlog.BKLogWriteHandler - Try storing max sequence number 1
in completing
/stream/storage/000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/CURRENT/<default>/ledgers/inprogress_000000000000000001.
2022-10-26T08:03:12,107+0000
[DLM-/stream/storage-OrderedScheduler-1-0-EventThread] INFO
org.apache.distributedlog.impl.ZKLogSegmentMetadataStore - Delete segment :
[LogSegmentId:518, firstTxId:16, lastTxId:-999, version:VERSION_V5_SEQUENCE_ID,
completionTime:0, recordCount:0, regionId:0, status:0,
logSegmentSequenceNumber:1, lastEntryId:-1, lastSlotId:-1, inprogress:true,
minActiveDLSN:DLSN{logSegmentSequenceNo=1, entryId=0, slotId=0},
startSequenceId:-1]
2022-10-26T08:03:12,119+0000 [main-EventThread] INFO
org.apache.distributedlog.logsegment.PerStreamLogSegmentCache -
000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/CURRENT
added log segment (logrecs_000000000000000001 : [LogSegmentId:518,
firstTxId:16, lastTxId:16, version:VERSION_V5_SEQUENCE_ID,
completionTime:1666771392106, recordCount:1, regionId:0, status:0,
logSegmentSequenceNumber:1, lastEntryId:0, lastSlotId:0, inprogress:false,
minActiveDLSN:DLSN{logSegmentSequenceNo=1, entryId=0, slotId=0},
startSequenceId:0]) to cache.
2022-10-26T08:03:12,120+0000 [DLM-/stream/storage-OrderedScheduler-1-0] INFO
org.apache.distributedlog.BKLogWriteHandler - Completed
inprogress_000000000000000001 to logrecs_000000000000000001 for
000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/CURRENT:<default>
: [LogSegmentId:518, firstTxId:16, lastTxId:16,
version:VERSION_V5_SEQUENCE_ID, completionTime:1666771392106, recordCount:1,
regionId:0, status:0, logSegmentSequenceNumber:1, lastEntryId:0, lastSlotId:0,
inprogress:false, minActiveDLSN:DLSN{logSegmentSequenceNo=1, entryId=0,
slotId=0}, startSequenceId:0]
2022-10-26T08:03:12,122+0000 [DLM-/stream/storage-OrderedScheduler-3-0] INFO
org.apache.distributedlog.bk.SimpleLedgerAllocator - Abort ledger allocator
without cleaning up on
/stream/storage/000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/CURRENT/<default>/allocation.
2022-10-26T08:03:12,145+0000 [main-EventThread] INFO
org.apache.distributedlog.bk.SimpleLedgerAllocator - Ledger allocator for
/stream/storage/000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/MANIFEST-000008/<default>/allocation
moved version from -1 to 0.
2022-10-26T08:03:12,157+0000 [main-EventThread] INFO
org.apache.distributedlog.BKLogWriteHandler - Initiating Recovery For
000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/MANIFEST-000008:<default>
: []
2022-10-26T08:03:12,165+0000 [io-checkpoint-scheduler-OrderedScheduler-1-0]
INFO org.apache.distributedlog.BKLogWriteHandler - Initiating Recovery For
000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/MANIFEST-000008:<default>
: []
2022-10-26T08:03:12,175+0000 [DLM-/stream/storage-OrderedScheduler-2-0] INFO
org.apache.distributedlog.bk.SimpleLedgerAllocator - Ledger allocator
/stream/storage/000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/MANIFEST-000008/<default>/allocation
moved to phase ALLOCATING : version = 0.
2022-10-26T08:03:12,187+0000
[DLM-/stream/storage-OrderedScheduler-1-0-EventThread] INFO
org.apache.bookkeeper.client.LedgerCreateOp - Ensemble: [127.0.0.1:3181] for
ledger: 520
2022-10-26T08:03:12,190+0000 [main-EventThread] INFO
org.apache.distributedlog.bk.SimpleLedgerAllocator - Ledger allocator for
/stream/storage/000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/MANIFEST-000008/<default>/allocation
moved version from 0 to 1.
2022-10-26T08:03:12,190+0000 [main-EventThread] INFO
org.apache.distributedlog.bk.SimpleLedgerAllocator - Ledger allocator
/stream/storage/000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/MANIFEST-000008/<default>/allocation
moved to phase ALLOCATED : version = 1.
2022-10-26T08:03:12,190+0000 [main-EventThread] INFO
org.apache.distributedlog.bk.SimpleLedgerAllocator - Ledger allocator
/stream/storage/000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/MANIFEST-000008/<default>/allocation
moved to phase HANDING_OVER : version = 1.
2022-10-26T08:03:12,191+0000 [main-EventThread] INFO
org.apache.distributedlog.BKLogWriteHandler - No max ledger sequence number
found while creating log segment 1 for
000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/MANIFEST-000008:<default>.
2022-10-26T08:03:12,196+0000 [main-EventThread] INFO
org.apache.distributedlog.bk.SimpleLedgerAllocator - Ledger allocator
/stream/storage/000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/MANIFEST-000008/<default>/allocation
moved to phase HANDED_OVER : version = 1.
2022-10-26T08:03:12,196+0000 [main-EventThread] INFO
org.apache.distributedlog.bk.SimpleLedgerAllocator - Ledger allocator for
/stream/storage/000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/MANIFEST-000008/<default>/allocation
moved version from 1 to 2.
2022-10-26T08:03:12,196+0000 [main-EventThread] INFO
org.apache.distributedlog.bk.SimpleLedgerAllocator - Ledger allocator
/stream/storage/000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/MANIFEST-000008/<default>/allocation
moved to phase ALLOCATING : version = 2.
2022-10-26T08:03:12,197+0000 [main-EventThread] INFO
org.apache.distributedlog.logsegment.PerStreamLogSegmentCache -
000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/MANIFEST-000008
added log segment (inprogress_000000000000000001 : [LogSegmentId:520,
firstTxId:138, lastTxId:-999, version:VERSION_V5_SEQUENCE_ID, completionTime:0,
recordCount:0, regionId:0, status:0, logSegmentSequenceNumber:1,
lastEntryId:-1, lastSlotId:-1, inprogress:true,
minActiveDLSN:DLSN{logSegmentSequenceNo=1, entryId=0, slotId=0},
startSequenceId:-1]) to cache.
2022-10-26T08:03:12,211+0000 [main-EventThread] INFO
org.apache.distributedlog.BKLogWriteHandler - Deleting log segments older than
1666512192204 for
000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/MANIFEST-000008:<default>
: []
2022-10-26T08:03:12,215+0000
[DLM-/stream/storage-OrderedScheduler-1-0-EventThread] INFO
org.apache.bookkeeper.client.LedgerCreateOp - Ensemble: [127.0.0.1:3181] for
ledger: 521
2022-10-26T08:03:12,235+0000 [main-EventThread] INFO
org.apache.distributedlog.bk.SimpleLedgerAllocator - Ledger allocator for
/stream/storage/000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/MANIFEST-000008/<default>/allocation
moved version from 2 to 3.
2022-10-26T08:03:12,237+0000 [main-EventThread] INFO
org.apache.distributedlog.bk.SimpleLedgerAllocator - Ledger allocator
/stream/storage/000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/MANIFEST-000008/<default>/allocation
moved to phase ALLOCATED : version = 3.
2022-10-26T08:03:12,248+0000 [DLM-/stream/storage-OrderedScheduler-3-0] INFO
org.apache.distributedlog.BKLogSegmentWriter - Flushing before closing log
segment
000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/MANIFEST-000008:<default>:inprogress_000000000000000001
2022-10-26T08:03:12,252+0000 [BookKeeperClientWorker-OrderedExecutor-0-0]
INFO org.apache.distributedlog.BKLogSegmentWriter - Closing
BKPerStreamLogWriter (abort=false) for
000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/MANIFEST-000008:<default>:inprogress_000000000000000001
: lastDLSN = DLSN{logSegmentSequenceNo=1, entryId=0, slotId=0}
outstandingTransmits = 1 writesPendingTransmit = 0
2022-10-26T08:03:12,253+0000 [BookKeeperClientWorker-OrderedExecutor-0-0]
INFO org.apache.distributedlog.BKLogSegmentWriter - Stream
000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/MANIFEST-000008:<default>:inprogress_000000000000000001
aborted 0 writes
2022-10-26T08:03:12,262+0000
[DLM-/stream/storage-OrderedScheduler-1-0-EventThread] INFO
org.apache.distributedlog.BKLogWriteHandler - Try storing max sequence number 1
in completing
/stream/storage/000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/MANIFEST-000008/<default>/ledgers/inprogress_000000000000000001.
2022-10-26T08:03:12,264+0000
[DLM-/stream/storage-OrderedScheduler-1-0-EventThread] INFO
org.apache.distributedlog.impl.ZKLogSegmentMetadataStore - Delete segment :
[LogSegmentId:520, firstTxId:138, lastTxId:-999,
version:VERSION_V5_SEQUENCE_ID, completionTime:0, recordCount:0, regionId:0,
status:0, logSegmentSequenceNumber:1, lastEntryId:-1, lastSlotId:-1,
inprogress:true, minActiveDLSN:DLSN{logSegmentSequenceNo=1, entryId=0,
slotId=0}, startSequenceId:-1]
2022-10-26T08:03:12,273+0000 [main-EventThread] INFO
org.apache.distributedlog.logsegment.PerStreamLogSegmentCache -
000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/MANIFEST-000008
added log segment (logrecs_000000000000000001 : [LogSegmentId:520,
firstTxId:138, lastTxId:138, version:VERSION_V5_SEQUENCE_ID,
completionTime:1666771392264, recordCount:1, regionId:0, status:0,
logSegmentSequenceNumber:1, lastEntryId:0, lastSlotId:0, inprogress:false,
minActiveDLSN:DLSN{logSegmentSequenceNo=1, entryId=0, slotId=0},
startSequenceId:0]) to cache.
2022-10-26T08:03:12,275+0000 [DLM-/stream/storage-OrderedScheduler-1-0] INFO
org.apache.distributedlog.BKLogWriteHandler - Completed
inprogress_000000000000000001 to logrecs_000000000000000001 for
000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/MANIFEST-000008:<default>
: [LogSegmentId:520, firstTxId:138, lastTxId:138,
version:VERSION_V5_SEQUENCE_ID, completionTime:1666771392264, recordCount:1,
regionId:0, status:0, logSegmentSequenceNumber:1, lastEntryId:0, lastSlotId:0,
inprogress:false, minActiveDLSN:DLSN{logSegmentSequenceNo=1, entryId=0,
slotId=0}, startSequenceId:0]
2022-10-26T08:03:12,276+0000 [DLM-/stream/storage-OrderedScheduler-2-0] INFO
org.apache.distributedlog.bk.SimpleLedgerAllocator - Abort ledger allocator
without cleaning up on
/stream/storage/000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/MANIFEST-000008/<default>/allocation.
2022-10-26T08:03:12,299+0000 [main-EventThread] INFO
org.apache.distributedlog.bk.SimpleLedgerAllocator - Ledger allocator for
/stream/storage/000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/metadata/<default>/allocation
moved version from -1 to 0.
2022-10-26T08:03:12,313+0000 [main-EventThread] INFO
org.apache.distributedlog.BKLogWriteHandler - Initiating Recovery For
000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/metadata:<default>
: []
2022-10-26T08:03:12,320+0000 [io-checkpoint-scheduler-OrderedScheduler-1-0]
INFO org.apache.distributedlog.BKLogWriteHandler - Initiating Recovery For
000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/metadata:<default>
: []
2022-10-26T08:03:12,330+0000 [DLM-/stream/storage-OrderedScheduler-2-0] INFO
org.apache.distributedlog.bk.SimpleLedgerAllocator - Ledger allocator
/stream/storage/000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/metadata/<default>/allocation
moved to phase ALLOCATING : version = 0.
2022-10-26T08:03:12,339+0000
[DLM-/stream/storage-OrderedScheduler-1-0-EventThread] INFO
org.apache.bookkeeper.client.LedgerCreateOp - Ensemble: [127.0.0.1:3181] for
ledger: 522
2022-10-26T08:03:12,342+0000 [main-EventThread] INFO
org.apache.distributedlog.bk.SimpleLedgerAllocator - Ledger allocator for
/stream/storage/000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/metadata/<default>/allocation
moved version from 0 to 1.
2022-10-26T08:03:12,342+0000 [main-EventThread] INFO
org.apache.distributedlog.bk.SimpleLedgerAllocator - Ledger allocator
/stream/storage/000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/metadata/<default>/allocation
moved to phase ALLOCATED : version = 1.
2022-10-26T08:03:12,343+0000 [main-EventThread] INFO
org.apache.distributedlog.bk.SimpleLedgerAllocator - Ledger allocator
/stream/storage/000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/metadata/<default>/allocation
moved to phase HANDING_OVER : version = 1.
2022-10-26T08:03:12,343+0000 [main-EventThread] INFO
org.apache.distributedlog.BKLogWriteHandler - No max ledger sequence number
found while creating log segment 1 for
000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/metadata:<default>.
2022-10-26T08:03:12,347+0000 [main-EventThread] INFO
org.apache.distributedlog.bk.SimpleLedgerAllocator - Ledger allocator
/stream/storage/000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/metadata/<default>/allocation
moved to phase HANDED_OVER : version = 1.
2022-10-26T08:03:12,348+0000 [main-EventThread] INFO
org.apache.distributedlog.bk.SimpleLedgerAllocator - Ledger allocator for
/stream/storage/000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/metadata/<default>/allocation
moved version from 1 to 2.
2022-10-26T08:03:12,348+0000 [main-EventThread] INFO
org.apache.distributedlog.bk.SimpleLedgerAllocator - Ledger allocator
/stream/storage/000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/metadata/<default>/allocation
moved to phase ALLOCATING : version = 2.
2022-10-26T08:03:12,348+0000 [main-EventThread] INFO
org.apache.distributedlog.logsegment.PerStreamLogSegmentCache -
000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/metadata
added log segment (inprogress_000000000000000001 : [LogSegmentId:522,
firstTxId:305, lastTxId:-999, version:VERSION_V5_SEQUENCE_ID, completionTime:0,
recordCount:0, regionId:0, status:0, logSegmentSequenceNumber:1,
lastEntryId:-1, lastSlotId:-1, inprogress:true,
minActiveDLSN:DLSN{logSegmentSequenceNo=1, entryId=0, slotId=0},
startSequenceId:-1]) to cache.
2022-10-26T08:03:12,379+0000
[DLM-/stream/storage-OrderedScheduler-1-0-EventThread] INFO
org.apache.bookkeeper.client.LedgerCreateOp - Ensemble: [127.0.0.1:3181] for
ledger: 523
2022-10-26T08:03:12,381+0000 [main-EventThread] INFO
org.apache.distributedlog.BKLogWriteHandler - Deleting log segments older than
1666512192357 for
000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/metadata:<default>
: []
2022-10-26T08:03:12,396+0000 [main-EventThread] INFO
org.apache.distributedlog.bk.SimpleLedgerAllocator - Ledger allocator for
/stream/storage/000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/metadata/<default>/allocation
moved version from 2 to 3.
2022-10-26T08:03:12,397+0000 [main-EventThread] INFO
org.apache.distributedlog.bk.SimpleLedgerAllocator - Ledger allocator
/stream/storage/000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/metadata/<default>/allocation
moved to phase ALLOCATED : version = 3.
2022-10-26T08:03:12,398+0000 [DLM-/stream/storage-OrderedScheduler-0-0] INFO
org.apache.distributedlog.BKLogSegmentWriter - Flushing before closing log
segment
000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/metadata:<default>:inprogress_000000000000000001
2022-10-26T08:03:12,402+0000 [BookKeeperClientWorker-OrderedExecutor-0-0]
INFO org.apache.distributedlog.BKLogSegmentWriter - Closing
BKPerStreamLogWriter (abort=false) for
000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/metadata:<default>:inprogress_000000000000000001
: lastDLSN = DLSN{logSegmentSequenceNo=1, entryId=0, slotId=0}
outstandingTransmits = 1 writesPendingTransmit = 0
2022-10-26T08:03:12,402+0000 [BookKeeperClientWorker-OrderedExecutor-0-0]
INFO org.apache.distributedlog.BKLogSegmentWriter - Stream
000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/metadata:<default>:inprogress_000000000000000001
aborted 0 writes
2022-10-26T08:03:12,412+0000
[DLM-/stream/storage-OrderedScheduler-1-0-EventThread] INFO
org.apache.distributedlog.BKLogWriteHandler - Try storing max sequence number 1
in completing
/stream/storage/000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/metadata/<default>/ledgers/inprogress_000000000000000001.
2022-10-26T08:03:12,414+0000
[DLM-/stream/storage-OrderedScheduler-1-0-EventThread] INFO
org.apache.distributedlog.impl.ZKLogSegmentMetadataStore - Delete segment :
[LogSegmentId:522, firstTxId:305, lastTxId:-999,
version:VERSION_V5_SEQUENCE_ID, completionTime:0, recordCount:0, regionId:0,
status:0, logSegmentSequenceNumber:1, lastEntryId:-1, lastSlotId:-1,
inprogress:true, minActiveDLSN:DLSN{logSegmentSequenceNo=1, entryId=0,
slotId=0}, startSequenceId:-1]
2022-10-26T08:03:12,424+0000 [main-EventThread] INFO
org.apache.distributedlog.logsegment.PerStreamLogSegmentCache -
000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/metadata
added log segment (logrecs_000000000000000001 : [LogSegmentId:522,
firstTxId:305, lastTxId:305, version:VERSION_V5_SEQUENCE_ID,
completionTime:1666771392414, recordCount:1, regionId:0, status:0,
logSegmentSequenceNumber:1, lastEntryId:0, lastSlotId:0, inprogress:false,
minActiveDLSN:DLSN{logSegmentSequenceNo=1, entryId=0, slotId=0},
startSequenceId:0]) to cache.
2022-10-26T08:03:12,426+0000 [DLM-/stream/storage-OrderedScheduler-3-0] INFO
org.apache.distributedlog.BKLogWriteHandler - Completed
inprogress_000000000000000001 to logrecs_000000000000000001 for
000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/metadata:<default>
: [LogSegmentId:522, firstTxId:305, lastTxId:305,
version:VERSION_V5_SEQUENCE_ID, completionTime:1666771392414, recordCount:1,
regionId:0, status:0, logSegmentSequenceNumber:1, lastEntryId:0, lastSlotId:0,
inprogress:false, minActiveDLSN:DLSN{logSegmentSequenceNo=1, entryId=0,
slotId=0}, startSequenceId:0]
2022-10-26T08:03:12,427+0000 [DLM-/stream/storage-OrderedScheduler-2-0] INFO
org.apache.distributedlog.bk.SimpleLedgerAllocator - Abort ledger allocator
without cleaning up on
/stream/storage/000000000000000000/000000000000000001/000000000000000000/checkpoints/6458c87c-1381-43a1-8115-cd2def9cf932/metadata/<default>/allocation.
2022-10-26T08:03:12,449+0000 [main-EventThread] INFO
org.apache.distributedlog.bk.SimpleLedgerAllocator - Ledger allocator for
/stream/storage/000000000000000000/000000000000000001/000000000000000000/checkpoints/5aeb7588-5444-44a1-8f86-a2d27dec12b8/MANIFEST-000008/<default>/allocation
moved version from -1 to 3.
2022-10-26T08:03:12,454+0000 [main-EventThread] INFO
org.apache.distributedlog.logsegment.PerStreamLogSegmentCache -
000000000000000000/000000000000000001/000000000000000000/checkpoints/5aeb7588-5444-44a1-8f86-a2d27dec12b8/MANIFEST-000008
added log segment (logrecs_000000000000000001 : [LogSegmentId:496,
firstTxId:138, lastTxId:138, version:VERSION_V5_SEQUENCE_ID,
completionTime:1666770492778, recordCount:1, regionId:0, status:0,
logSegmentSequenceNumber:1, lastEntryId:0, lastSlotId:0, inprogress:false,
minActiveDLSN:DLSN{logSegmentSequenceNo=1, entryId=0, slotId=0},
startSequenceId:0]) to cache.
2022-10-26T08:03:12,455+0000 [DLM-/stream/storage-OrderedScheduler-3-0] INFO
org.apache.distributedlog.BKLogWriteHandler - Deleting ledger
[LogSegmentId:496, firstTxId:138, lastTxId:138, version:VERSION_V5_SEQUENCE_ID,
completionTime:1666770492778, recordCount:1, regionId:0, status:0,
logSegmentSequenceNumber:1, lastEntryId:0, lastSlotId:0, inprogress:false,
minActiveDLSN:DLSN{logSegmentSequenceNo=1, entryId=0, slotId=0},
startSequenceId:0] for
000000000000000000/000000000000000001/000000000000000000/checkpoints/5aeb7588-5444-44a1-8f86-a2d27dec12b8/MANIFEST-000008:<default>
2022-10-26T08:03:12,461+0000 [BookKeeperClientWorker-OrderedExecutor-0-0]
INFO org.apache.distributedlog.impl.ZKLogSegmentMetadataStore - Delete segment
: [LogSegmentId:496, firstTxId:138, lastTxId:138,
version:VERSION_V5_SEQUENCE_ID, completionTime:1666770492778, recordCount:1,
regionId:0, status:0, logSegmentSequenceNumber:1, lastEntryId:0, lastSlotId:0,
inprogress:false, minActiveDLSN:DLSN{logSegmentSequenceNo=1, entryId=0,
slotId=0}, startSequenceId:0]
2022-10-26T08:03:12,488+0000 [main-EventThread] INFO
org.apache.distributedlog.bk.SimpleLedgerAllocator - Ledger allocator for
/stream/storage/000000000000000000/000000000000000001/000000000000000000/checkpoints/5aeb7588-5444-44a1-8f86-a2d27dec12b8/metadata/<default>/allocation
moved version from -1 to 3.
2022-10-26T08:03:12,494+0000 [main-EventThread] INFO
org.apache.distributedlog.logsegment.PerStreamLogSegmentCache -
000000000000000000/000000000000000001/000000000000000000/checkpoints/5aeb7588-5444-44a1-8f86-a2d27dec12b8/metadata
added log segment (logrecs_000000000000000001 : [LogSegmentId:498,
firstTxId:305, lastTxId:305, version:VERSION_V5_SEQUENCE_ID,
completionTime:1666770492977, recordCount:1, regionId:0, status:0,
logSegmentSequenceNumber:1, lastEntryId:0, lastSlotId:0, inprogress:false,
minActiveDLSN:DLSN{logSegmentSequenceNo=1, entryId=0, slotId=0},
startSequenceId:0]) to cache.
2022-10-26T08:03:12,495+0000 [DLM-/stream/storage-OrderedScheduler-3-0] INFO
org.apache.distributedlog.BKLogWriteHandler - Deleting ledger
[LogSegmentId:498, firstTxId:305, lastTxId:305, version:VERSION_V5_SEQUENCE_ID,
completionTime:1666770492977, recordCount:1, regionId:0, status:0,
logSegmentSequenceNumber:1, lastEntryId:0, lastSlotId:0, inprogress:false,
minActiveDLSN:DLSN{logSegmentSequenceNo=1, entryId=0, slotId=0},
startSequenceId:0] for
000000000000000000/000000000000000001/000000000000000000/checkpoints/5aeb7588-5444-44a1-8f86-a2d27dec12b8/metadata:<default>
2022-10-26T08:03:12,498+0000 [BookKeeperClientWorker-OrderedExecutor-0-0]
INFO org.apache.distributedlog.impl.ZKLogSegmentMetadataStore - Delete segment
: [LogSegmentId:498, firstTxId:305, lastTxId:305,
version:VERSION_V5_SEQUENCE_ID, completionTime:1666770492977, recordCount:1,
regionId:0, status:0, logSegmentSequenceNumber:1, lastEntryId:0, lastSlotId:0,
inprogress:false, minActiveDLSN:DLSN{logSegmentSequenceNo=1, entryId=0,
slotId=0}, startSequenceId:0]
2022-10-26T08:03:12,526+0000 [main-EventThread] INFO
org.apache.distributedlog.bk.SimpleLedgerAllocator - Ledger allocator for
/stream/storage/000000000000000000/000000000000000001/000000000000000000/checkpoints/5aeb7588-5444-44a1-8f86-a2d27dec12b8/OPTIONS-000012/<default>/allocation
moved version from -1 to 3.
2022-10-26T08:03:12,531+0000 [main-EventThread] INFO
org.apache.distributedlog.logsegment.PerStreamLogSegmentCache -
000000000000000000/000000000000000001/000000000000000000/checkpoints/5aeb7588-5444-44a1-8f86-a2d27dec12b8/OPTIONS-000012
added log segment (logrecs_000000000000000001 : [LogSegmentId:492,
firstTxId:8249, lastTxId:8249, version:VERSION_V5_SEQUENCE_ID,
completionTime:1666770492197, recordCount:1, regionId:0, status:0,
logSegmentSequenceNumber:1, lastEntryId:0, lastSlotId:0, inprogress:false,
minActiveDLSN:DLSN{logSegmentSequenceNo=1, entryId=0, slotId=0},
startSequenceId:0]) to cache.
2022-10-26T08:03:12,533+0000 [DLM-/stream/storage-OrderedScheduler-3-0] INFO
org.apache.distributedlog.BKLogWriteHandler - Deleting ledger
[LogSegmentId:492, firstTxId:8249, lastTxId:8249,
version:VERSION_V5_SEQUENCE_ID, completionTime:1666770492197, recordCount:1,
regionId:0, status:0, logSegmentSequenceNumber:1, lastEntryId:0, lastSlotId:0,
inprogress:false, minActiveDLSN:DLSN{logSegmentSequenceNo=1, entryId=0,
slotId=0}, startSequenceId:0] for
000000000000000000/000000000000000001/000000000000000000/checkpoints/5aeb7588-5444-44a1-8f86-a2d27dec12b8/OPTIONS-000012:<default>
2022-10-26T08:03:12,537+0000 [BookKeeperClientWorker-OrderedExecutor-0-0]
INFO org.apache.distributedlog.impl.ZKLogSegmentMetadataStore - Delete segment
: [LogSegmentId:492, firstTxId:8249, lastTxId:8249,
version:VERSION_V5_SEQUENCE_ID, completionTime:1666770492197, recordCount:1,
regionId:0, status:0, logSegmentSequenceNumber:1, lastEntryId:0, lastSlotId:0,
inprogress:false, minActiveDLSN:DLSN{logSegmentSequenceNo=1, entryId=0,
slotId=0}, startSequenceId:0]
2022-10-26T08:03:12,566+0000 [main-EventThread] INFO
org.apache.distributedlog.bk.SimpleLedgerAllocator - Ledger allocator for
/stream/storage/000000000000000000/000000000000000001/000000000000000000/checkpoints/5aeb7588-5444-44a1-8f86-a2d27dec12b8/CURRENT/<default>/allocation
moved version from -1 to 3.
2022-10-26T08:03:12,570+0000 [main-EventThread] INFO
org.apache.distributedlog.logsegment.PerStreamLogSegmentCache -
000000000000000000/000000000000000001/000000000000000000/checkpoints/5aeb7588-5444-44a1-8f86-a2d27dec12b8/CURRENT
added log segment (logrecs_000000000000000001 : [LogSegmentId:494,
firstTxId:16, lastTxId:16, version:VERSION_V5_SEQUENCE_ID,
completionTime:1666770492622, recordCount:1, regionId:0, status:0,
logSegmentSequenceNumber:1, lastEntryId:0, lastSlotId:0, inprogress:false,
minActiveDLSN:DLSN{logSegmentSequenceNo=1, entryId=0, slotId=0},
startSequenceId:0]) to cache.
2022-10-26T08:03:12,571+0000 [DLM-/stream/storage-OrderedScheduler-2-0] INFO
org.apache.distributedlog.BKLogWriteHandler - Deleting ledger
[LogSegmentId:494, firstTxId:16, lastTxId:16, version:VERSION_V5_SEQUENCE_ID,
completionTime:1666770492622, recordCount:1, regionId:0, status:0,
logSegmentSequenceNumber:1, lastEntryId:0, lastSlotId:0, inprogress:false,
minActiveDLSN:DLSN{logSegmentSequenceNo=1, entryId=0, slotId=0},
startSequenceId:0] for
000000000000000000/000000000000000001/000000000000000000/checkpoints/5aeb7588-5444-44a1-8f86-a2d27dec12b8/CURRENT:<default>
2022-10-26T08:03:12,576+0000 [BookKeeperClientWorker-OrderedExecutor-0-0]
INFO org.apache.distributedlog.impl.ZKLogSegmentMetadataStore - Delete segment
: [LogSegmentId:494, firstTxId:16, lastTxId:16, version:VERSION_V5_SEQUENCE_ID,
completionTime:1666770492622, recordCount:1, regionId:0, status:0,
logSegmentSequenceNumber:1, lastEntryId:0, lastSlotId:0, inprogress:false,
minActiveDLSN:DLSN{logSegmentSequenceNo=1, entryId=0, slotId=0},
startSequenceId:0]
2022-10-26T08:03:12,601+0000 [main-EventThread] INFO
org.apache.distributedlog.bk.SimpleLedgerAllocator - Ledger allocator for
/stream/storage/000000000000000000/000000000000000001/000000000000000000/checkpoints/5aeb7588-5444-44a1-8f86-a2d27dec12b8/<default>/allocation
moved version from -1 to 0.
2022-10-26T08:03:12,615+0000 [io-checkpoint-scheduler-OrderedScheduler-1-0]
INFO
org.apache.bookkeeper.statelib.impl.rocksdb.checkpoint.RocksdbCheckpointTask -
Delete remote checkpoint 5aeb7588-5444-44a1-8f86-a2d27dec12b8 from checkpoint
store at
000000000000000000/000000000000000001/000000000000000000/checkpoints/5aeb7588-5444-44a1-8f86-a2d27dec12b8
2022-10-26T08:03:16,280+0000 [pulsar-web-56-6] INFO
org.eclipse.jetty.server.RequestLog - 127.0.0.1 - - [26/Oct/2022:08:03:16
+0000] "GET
/admin/v2/persistent/public/functions/coordinate/stats?getPreciseBacklog=false&subscriptionBacklogSize=false&getEarliestTimeInBacklog=false
HTTP/1.1" 200 2183 "-" "Pulsar-Java-v2.10.1" 107
2022-10-26T08:03:16,320+0000 [pulsar-web-56-7] INFO
org.eclipse.jetty.server.RequestLog - 127.0.0.1 - - [26/Oct/2022:08:03:16
+0000] "GET
/admin/v2/persistent/public/functions/coordinate/stats?getPreciseBacklog=false&subscriptionBacklogSize=false&getEarliestTimeInBacklog=false
HTTP/1.1" 200 2183 "-" "Pulsar-Java-v2.10.1" 18
2022-10-26T08:03:46,075+0000 [pulsar-web-56-1] INFO
org.eclipse.jetty.server.RequestLog - 127.0.0.1 - - [26/Oct/2022:08:03:46
+0000] "GET
/admin/v2/persistent/public/functions/coordinate/stats?getPreciseBacklog=false&subscriptionBacklogSize=false&getEarliestTimeInBacklog=false
HTTP/1.1" 200 2183 "-" "Pulsar-Java-v2.10.1" 7
2022-10-26T08:03:46,087+0000 [pulsar-web-56-5] INFO
org.eclipse.jetty.server.RequestLog - 127.0.0.1 - - [26/Oct/2022:08:03:46
+0000] "GET
/admin/v2/persistent/public/functions/coordinate/stats?getPreciseBacklog=false&subscriptionBacklogSize=false&getEarliestTimeInBacklog=false
HTTP/1.1" 200 2183 "-" "Pulsar-Java-v2.10.1" 7
2022-10-26T08:03:46,098+0000 [pulsar-web-56-3] INFO
org.eclipse.jetty.server.RequestLog - 127.0.0.1 - - [26/Oct/2022:08:03:46
+0000] "GET
/admin/v2/persistent/public/functions/coordinate/stats?getPreciseBacklog=false&subscriptionBacklogSize=false&getEarliestTimeInBacklog=false
HTTP/1.1" 200 2183 "-" "Pulsar-Java-v2.10.1" 7
2022-10-26T08:04:16,645+0000 [pulsar-web-56-8] INFO
org.eclipse.jetty.server.RequestLog - 127.0.0.1 - - [26/Oct/2022:08:04:16
+0000] "GET
/admin/v2/persistent/public/functions/coordinate/stats?getPreciseBacklog=false&subscriptionBacklogSize=false&getEarliestTimeInBacklog=false
HTTP/1.1" 200 2183 "-" "Pulsar-Java-v2.10.1" 322
2022-10-26T08:04:16,740+0000 [pulsar-web-56-6] INFO
org.eclipse.jetty.server.RequestLog - 127.0.0.1 - - [26/Oct/2022:08:04:16
+0000] "GET
/admin/v2/persistent/public/functions/coordinate/stats?getPreciseBacklog=false&subscriptionBacklogSize=false&getEarliestTimeInBacklog=false
HTTP/1.1" 200 2183 "-" "Pulsar-Java-v2.10.1" 29
2022-10-26T08:04:46,076+0000 [pulsar-web-56-5] INFO
org.eclipse.jetty.server.RequestLog - 127.0.0.1 - - [26/Oct/2022:08:04:46
+0000] "GET
/admin/v2/persistent/public/functions/coordinate/stats?getPreciseBacklog=false&subscriptionBacklogSize=false&getEarliestTimeInBacklog=false
HTTP/1.1" 200 2183 "-" "Pulsar-Java-v2.10.1" 7
2022-10-26T08:04:46,088+0000 [pulsar-web-56-3] INFO
org.eclipse.jetty.server.RequestLog - 127.0.0.1 - - [26/Oct/2022:08:04:46
+0000] "GET
/admin/v2/persistent/public/functions/coordinate/stats?getPreciseBacklog=false&subscriptionBacklogSize=false&getEarliestTimeInBacklog=false
HTTP/1.1" 200 2183 "-" "Pulsar-Java-v2.10.1" 6
2022-10-26T08:04:46,097+0000 [pulsar-web-56-7] INFO
org.eclipse.jetty.server.RequestLog - 127.0.0.1 - - [26/Oct/2022:08:04:46
+0000] "GET
/admin/v2/persistent/public/functions/coordinate/stats?getPreciseBacklog=false&subscriptionBacklogSize=false&getEarliestTimeInBacklog=false
HTTP/1.1" 200 2183 "-" "Pulsar-Java-v2.10.1" 5
2022-10-26T08:05:16,068+0000 [pulsar-web-56-4] INFO
org.eclipse.jetty.server.RequestLog - 127.0.0.1 - - [26/Oct/2022:08:05:16
+0000] "GET
/admin/v2/persistent/public/functions/coordinate/stats?getPreciseBacklog=false&subscriptionBacklogSize=false&getEarliestTimeInBacklog=false
HTTP/1.1" 200 2183 "-" "Pulsar-Java-v2.10.1" 5
2022-10-26T08:05:16,077+0000 [pulsar-web-56-1] INFO
org.eclipse.jetty.server.RequestLog - 127.0.0.1 - - [26/Oct/2022:08:05:16
+0000] "GET
/admin/v2/persistent/public/functions/coordinate/stats?getPreciseBacklog=false&subscriptionBacklogSize=false&getEarliestTimeInBacklog=false
HTTP/1.1" 200 2183 "-" "Pulsar-Java-v2.10.1" 5
2022-10-26T08:05:46,069+0000 [pulsar-web-56-8] INFO
org.eclipse.jetty.server.RequestLog - 127.0.0.1 - - [26/Oct/2022:08:05:46
+0000] "GET
/admin/v2/persistent/public/functions/coordinate/stats?getPreciseBacklog=false&subscriptionBacklogSize=false&getEarliestTimeInBacklog=false
HTTP/1.1" 200 2183 "-" "Pulsar-Java-v2.10.1" 6
2022-10-26T08:05:46,083+0000 [pulsar-web-56-6] INFO
org.eclipse.jetty.server.RequestLog - 127.0.0.1 - - [26/Oct/2022:08:05:46
+0000] "GET
/admin/v2/persistent/public/functions/coordinate/stats?getPreciseBacklog=false&subscriptionBacklogSize=false&getEarliestTimeInBacklog=false
HTTP/1.1" 200 2183 "-" "Pulsar-Java-v2.10.1" 5
2022-10-26T08:05:46,094+0000 [pulsar-web-56-5] INFO
org.eclipse.jetty.server.RequestLog - 127.0.0.1 - - [26/Oct/2022:08:05:46
+0000] "GET
/admin/v2/persistent/public/functions/coordinate/stats?getPreciseBacklog=false&subscriptionBacklogSize=false&getEarliestTimeInBacklog=false
HTTP/1.1" 200 2183 "-" "Pulsar-Java-v2.10.1" 7
2022-10-26T08:06:16,071+0000 [pulsar-web-56-3] INFO
org.eclipse.jetty.server.RequestLog - 127.0.0.1 - - [26/Oct/2022:08:06:16
+0000] "GET
/admin/v2/persistent/public/functions/coordinate/stats?getPreciseBacklog=false&subscriptionBacklogSize=false&getEarliestTimeInBacklog=false
HTTP/1.1" 200 2183 "-" "Pulsar-Java-v2.10.1" 6
2022-10-26T08:06:16,081+0000 [pulsar-web-56-7] INFO
org.eclipse.jetty.server.RequestLog - 127.0.0.1 - - [26/Oct/2022:08:06:16
+0000] "GET
/admin/v2/persistent/public/functions/coordinate/stats?getPreciseBacklog=false&subscriptionBacklogSize=false&getEarliestTimeInBacklog=false
HTTP/1.1" 200 2183 "-" "Pulsar-Java-v2.10.1" 5
2022-10-26T08:06:46,070+0000 [pulsar-web-56-4] INFO
org.eclipse.jetty.server.RequestLog - 127.0.0.1 - - [26/Oct/2022:08:06:46
+0000] "GET
/admin/v2/persistent/public/functions/coordinate/stats?getPreciseBacklog=false&subscriptionBacklogSize=false&getEarliestTimeInBacklog=false
HTTP/1.1" 200 2183 "-" "Pulsar-Java-v2.10.1" 7
2022-10-26T08:06:46,081+0000 [pulsar-web-56-1] INFO
org.eclipse.jetty.server.RequestLog - 127.0.0.1 - - [26/Oct/2022:08:06:46
+0000] "GET
/admin/v2/persistent/public/functions/coordinate/stats?getPreciseBacklog=false&subscriptionBacklogSize=false&getEarliestTimeInBacklog=false
HTTP/1.1" 200 2183 "-" "Pulsar-Java-v2.10.1" 5
2022-10-26T08:06:46,094+0000 [pulsar-web-56-8] INFO
org.eclipse.jetty.server.RequestLog - 127.0.0.1 - - [26/Oct/2022:08:06:46
+0000] "GET
/admin/v2/persistent/public/functions/coordinate/stats?getPreciseBacklog=false&subscriptionBacklogSize=false&getEarliestTimeInBacklog=false
HTTP/1.1" 200 2183 "-" "Pulsar-Java-v2.10.1" 7
2022-10-26T08:07:16,067+0000 [pulsar-web-56-6] INFO
org.eclipse.jetty.server.RequestLog - 127.0.0.1 - - [26/Oct/2022:08:07:16
+0000] "GET
/admin/v2/persistent/public/functions/coordinate/stats?getPreciseBacklog=false&subscriptionBacklogSize=false&getEarliestTimeInBacklog=false
HTTP/1.1" 200 2183 "-" "Pulsar-Java-v2.10.1" 8
2022-10-26T08:07:16,078+0000 [pulsar-web-56-5] INFO
org.eclipse.jetty.server.RequestLog - 127.0.0.1 - - [26/Oct/2022:08:07:16
+0000] "GET
/admin/v2/persistent/public/functions/coordinate/stats?getPreciseBacklog=false&subscriptionBacklogSize=false&getEarliestTimeInBacklog=false
HTTP/1.1" 200 2183 "-" "Pulsar-Java-v2.10.1" 7
2022-10-26T08:07:46,063+0000 [pulsar-web-56-3] INFO
org.eclipse.jetty.server.RequestLog - 127.0.0.1 - - [26/Oct/2022:08:07:46
+0000] "GET
/admin/v2/persistent/public/functions/coordinate/stats?getPreciseBacklog=false&subscriptionBacklogSize=false&getEarliestTimeInBacklog=false
HTTP/1.1" 200 2183 "-" "Pulsar-Java-v2.10.1" 5
2022-10-26T08:07:46,076+0000 [pulsar-web-56-7] INFO
org.eclipse.jetty.server.RequestLog - 127.0.0.1 - - [26/Oct/2022:08:07:46
+0000] "GET
/admin/v2/persistent/public/functions/coordinate/stats?getPreciseBacklog=false&subscriptionBacklogSize=false&getEarliestTimeInBacklog=false
HTTP/1.1" 200 2183 "-" "Pulsar-Java-v2.10.1" 5
2022-10-26T08:07:46,087+0000 [pulsar-web-56-4] INFO
org.eclipse.jetty.server.RequestLog - 127.0.0.1 - - [26/Oct/2022:08:07:46
+0000] "GET
/admin/v2/persistent/public/functions/coordinate/stats?getPreciseBacklog=false&subscriptionBacklogSize=false&getEarliestTimeInBacklog=false
HTTP/1.1" 200 2183 "-" "Pulsar-Java-v2.10.1" 5
2022-10-26T08:08:16,069+0000 [pulsar-web-56-1] INFO
org.eclipse.jetty.server.RequestLog - 127.0.0.1 - - [26/Oct/2022:08:08:16
+0000] "GET
/admin/v2/persistent/public/functions/coordinate/stats?getPreciseBacklog=false&subscriptionBacklogSize=false&getEarliestTimeInBacklog=false
HTTP/1.1" 200 2183 "-" "Pulsar-Java-v2.10.1" 14
2022-10-26T08:08:16,089+0000 [pulsar-web-56-8] INFO
org.eclipse.jetty.server.RequestLog - 127.0.0.1 - - [26/Oct/2022:08:08:16
+0000] "GET
/admin/v2/persistent/public/functions/coordinate/stats?getPreciseBacklog=false&subscriptionBacklogSize=false&getEarliestTimeInBacklog=false
HTTP/1.1" 200 2183 "-" "Pulsar-Java-v2.10.1" 13
2022-10-26T08:08:46,071+0000 [pulsar-web-56-6] INFO
org.eclipse.jetty.server.RequestLog - 127.0.0.1 - - [26/Oct/2022:08:08:46
+0000] "GET
/admin/v2/persistent/public/functions/coordinate/stats?getPreciseBacklog=false&subscriptionBacklogSize=false&getEarliestTimeInBacklog=false
HTTP/1.1" 200 2183 "-" "Pulsar-Java-v2.10.1" 11
2022-10-26T08:08:46,090+0000 [pulsar-web-56-5] INFO
org.eclipse.jetty.server.RequestLog - 127.0.0.1 - - [26/Oct/2022:08:08:46
+0000] "GET
/admin/v2/persistent/public/functions/coordinate/stats?getPreciseBacklog=false&subscriptionBacklogSize=false&getEarliestTimeInBacklog=false
HTTP/1.1" 200 2183 "-" "Pulsar-Java-v2.10.1" 9
2022-10-26T08:08:46,124+0000 [pulsar-web-56-3] INFO
org.eclipse.jetty.server.RequestLog - 127.0.0.1 - - [26/Oct/2022:08:08:46
+0000] "GET
/admin/v2/persistent/public/functions/coordinate/stats?getPreciseBacklog=false&subscriptionBacklogSize=false&getEarliestTimeInBacklog=false
HTTP/1.1" 200 2183 "-" "Pulsar-Java-v2.10.1" 26
2022-10-26T08:08:51,577+0000 [pulsar-io-29-4] INFO
org.apache.pulsar.broker.service.ServerCnx -
[PersistentTopic{topic=persistent://public/functions/metadata}][c-standalone-fw-localhost-8080-leader]
Closing producer on cnx /127.0.0.1:43492. producerId=1
2022-10-26T08:08:51,742+0000 [pulsar-io-29-4] INFO
org.apache.pulsar.broker.service.ServerCnx -
[PersistentTopic{topic=persistent://public/functions/metadata}][c-standalone-fw-localhost-8080-leader]
Closed producer on cnx /127.0.0.1:43492. producerId=1
2022-10-26T08:08:51,775+0000 [pulsar-client-io-71-1] INFO
org.apache.pulsar.client.impl.ProducerImpl -
[persistent://public/functions/metadata]
[c-standalone-fw-localhost-8080-leader] Closed Producer
2022-10-26T08:08:51,829+0000 [Thread-0] INFO
org.apache.pulsar.functions.worker.FunctionAssignmentTailer - Closing function
assignment tailer
2022-10-26T08:08:51,837+0000 [Thread-0] INFO
org.apache.pulsar.functions.worker.ClusterServiceCoordinator - Stopping Cluster
Service Coordinator for worker c-standalone-fw-localhost-8080
2022-10-26T08:08:51,841+0000 [Thread-0] INFO
org.apache.pulsar.functions.worker.ClusterServiceCoordinator - Stopped Cluster
Service Coordinator for worker c-standalone-fw-localhost-8080
2022-10-26T08:08:51,842+0000 [Thread-0] INFO
org.apache.pulsar.functions.worker.SchedulerManager - Closing scheduler manager
2022-10-26T08:08:51,913+0000 [pulsar-io-29-4] INFO
org.apache.pulsar.broker.service.ServerCnx -
[PersistentTopic{topic=persistent://public/functions/assignments}][c-standalone-fw-localhost-8080-scheduler-manager]
Closing producer on cnx /127.0.0.1:43492. producerId=0
2022-10-26T08:08:51,914+0000 [pulsar-io-29-4] INFO
org.apache.pulsar.broker.service.ServerCnx -
[PersistentTopic{topic=persistent://public/functions/assignments}][c-standalone-fw-localhost-8080-scheduler-manager]
Closed producer on cnx /127.0.0.1:43492. producerId=0
2022-10-26T08:08:51,915+0000 [pulsar-client-io-71-1] INFO
org.apache.pulsar.client.impl.ProducerImpl -
[persistent://public/functions/assignments]
[c-standalone-fw-localhost-8080-scheduler-manager] Closed Producer
2022-10-26T08:08:51,924+0000 [pulsar-io-29-4] INFO
org.apache.pulsar.broker.service.ServerCnx - [/127.0.0.1:43492] Closing
consumer: consumerId=0
2022-10-26T08:08:51,926+0000 [pulsar-io-29-4] INFO
org.apache.pulsar.broker.service.AbstractDispatcherSingleActiveConsumer -
Removing consumer
Consumer{subscription=PersistentSubscription{topic=persistent://public/functions/coordinate,
name=participants}, consumerId=0,
consumerName=c-standalone-fw-localhost-8080:localhost:8080,
address=/127.0.0.1:43492}
2022-10-26T08:08:51,951+0000 [pulsar-io-29-4] INFO
org.apache.pulsar.broker.service.ServerCnx - [/127.0.0.1:43492] Closed
consumer, consumerId=0
2022-10-26T08:08:51,957+0000 [pulsar-client-io-71-1] INFO
org.apache.pulsar.client.impl.ConsumerImpl -
[persistent://public/functions/coordinate] [participants] Closed consumer
2022-10-26T08:08:51,968+0000 [Thread-0] INFO
org.apache.pulsar.client.impl.PulsarClientImpl - Client closing. URL:
pulsar://localhost:6650
2022-10-26T08:08:52,081+0000 [pulsar-client-io-71-1] INFO
org.apache.pulsar.client.impl.ClientCnx - [id: 0x31e4387c, L:/127.0.0.1:43492 !
R:localhost/127.0.0.1:6650] Disconnected
2022-10-26T08:08:52,081+0000 [pulsar-io-29-4] INFO
org.apache.pulsar.broker.service.ServerCnx - Closed connection from
/127.0.0.1:43492
2022-10-26T08:08:53,060+0000 [db-storage-cleanup-10-1] INFO
org.apache.bookkeeper.bookie.storage.ldb.EntryLocationIndex - Deleting indexes
for ledgers: [480, 496, 498, 482, 484, 486, 488, 490, 476, 492, 494, 478]
2022-10-26T08:08:53,093+0000 [db-storage-cleanup-10-1] INFO
org.apache.bookkeeper.bookie.storage.ldb.EntryLocationIndex - Deleted indexes
for 0 entries from 12 ledgers in 0.03 seconds
2022-10-26T08:08:54,548+0000 [Thread-0] INFO
org.apache.distributedlog.BookKeeperClient - BookKeeper Client closed
bk:distributedlog://127.0.0.1:2181/pulsar/functions:factory_writer_shared
2022-10-26T08:08:54,552+0000 [Thread-0] INFO
org.apache.distributedlog.ZooKeeperClient - Close zookeeper client
dlzk:distributedlog://127.0.0.1:2181/pulsar/functions:factory_writer_shared.
2022-10-26T08:08:54,553+0000 [Thread-0] INFO
org.apache.distributedlog.ZooKeeperClient - Closing zookeeper client
dlzk:distributedlog://127.0.0.1:2181/pulsar/functions:factory_writer_shared.
2022-10-26T08:08:54,681+0000 [Thread-0] INFO org.apache.zookeeper.ZooKeeper
- Session: 0x10000060c7d000c closed
2022-10-26T08:08:54,681+0000 [Thread-0] INFO
org.apache.distributedlog.ZooKeeperClient - Closed zookeeper client
dlzk:distributedlog://127.0.0.1:2181/pulsar/functions:factory_writer_shared.
2022-10-26T08:08:54,683+0000 [main-EventThread] INFO
org.apache.zookeeper.ClientCnxn - EventThread shut down for session:
0x10000060c7d000c
2022-10-26T08:08:54,694+0000 [Thread-0] INFO
org.apache.distributedlog.impl.BKNamespaceDriver - Release external resources
used by channel factory.
2022-10-26T08:08:54,694+0000 [Thread-0] INFO
org.apache.distributedlog.impl.BKNamespaceDriver - Stopped request timer
2022-10-26T08:08:54,699+0000 [Thread-0] INFO
org.apache.distributedlog.BKDistributedLogNamespace - Executor Service Stopped.
2022-10-26T08:08:54,699+0000 [Thread-0] INFO
org.apache.pulsar.broker.PulsarService - Closing PulsarService
2022-10-26T08:08:54,709+0000 [Thread-0] WARN
org.eclipse.jetty.server.AbstractConnector -
java.io.IOException: No such file or directory
at sun.nio.ch.NativeThread.signal(Native Method) ~[?:?]
at
sun.nio.ch.ServerSocketChannelImpl.implCloseSelectableChannel(ServerSocketChannelImpl.java:365)
~[?:?]
at
java.nio.channels.spi.AbstractSelectableChannel.implCloseChannel(AbstractSelectableChannel.java:242)
~[?:?]
at
java.nio.channels.spi.AbstractInterruptibleChannel.close(AbstractInterruptibleChannel.java:112)
~[?:?]
at
org.eclipse.jetty.server.ServerConnector.close(ServerConnector.java:371)
[org.eclipse.jetty-jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927]
at
org.eclipse.jetty.server.AbstractNetworkConnector.shutdown(AbstractNetworkConnector.java:104)
[org.eclipse.jetty-jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927]
at org.eclipse.jetty.server.Server.doStop(Server.java:444)
[org.eclipse.jetty-jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927]
at
org.eclipse.jetty.util.component.AbstractLifeCycle.stop(AbstractLifeCycle.java:94)
[org.eclipse.jetty-jetty-util-9.4.44.v20210927.jar:9.4.44.v20210927]
at org.apache.pulsar.broker.web.WebService.close(WebService.java:266)
[org.apache.pulsar-pulsar-broker-2.10.1.jar:2.10.1]
at
org.apache.pulsar.broker.PulsarService.closeAsync(PulsarService.java:411)
[org.apache.pulsar-pulsar-broker-2.10.1.jar:2.10.1]
at org.apache.pulsar.broker.PulsarService.close(PulsarService.java:370)
[org.apache.pulsar-pulsar-broker-2.10.1.jar:2.10.1]
at
org.apache.pulsar.PulsarStandaloneStarter$1.run(PulsarStandaloneStarter.java:116)
[org.apache.pulsar-pulsar-broker-2.10.1.jar:2.10.1]
```
--
This is an automated message from the Apache Git Service.
To respond to the message, please log on to GitHub and use the
URL above to go to the specific comment.
To unsubscribe, e-mail: [email protected]
For queries about this service, please contact Infrastructure at:
[email protected]
