This message was deleted.
# troubleshooting
s
This message was deleted.
t
I’m not sure this is related to Druid 25.0.0. But Kafka ingestion lag started to increase a lot more since I’ve upgraded
v
one change is that in 25.0 tasks are managed using http threads. What is druid.server.http.numThreads on the indexer and how many tasks are running?
t
Hello Vijay, thanks for having a look.
druid.server.http.numThreads=60
on indexers. And I have 140 running tasks at the moment with 20 indexers (7 slots each)
and ~30 pending tasks
v
my thinking was that your tasks are not able to chat with the overlord and hence the overlord marks them as failed. But you seem to have enough http threads
I see below error
Copy code
2023-01-10T09:48:25,212 ERROR [[index_kafka_beam-advanced_9a6effeac5537f0_jhlgbjal]-threading-task-runner-executor-2] org.apache.druid.indexing.seekablestream.SeekableStreamIndexTaskRunner - Error while publishing segments for sequenceNumber[SequenceMetadata{sequenceId=0, sequenceName='index_kafka_beam-advanced_9a6effeac5537f0_0', assignments=[], startOffsets={42=83080096093}, exclusiveStartPartitions=[], endOffsets={42=83085285426}, sentinel=false, checkpointed=true}]
what is your deep storage?
t
GCS
Around 90% of my tasks publishes to GCS correctly
The indexer which ran the task in logs successfully publishes segments for other Kafka tasks
v
how big are these segments?
GCS has a size limit of 2GB for single writes (the indexer does not do a multi part write). If the file is larger than 2 GB then GCS will reject the file
t
At ingestion time, segments size is 300MB max
v
is this failure always for one specific kafka topic or partition?
t
On different Kafka topics (4)
I’ll check about partitions
It seems to happen on all partitions
v
I see that the kafka task is pausing a lot. Have you set druid.indexer.tasklock.forceTimeChunkLock? Also are there many kafka tasks writing to the same time chunk with different priorities?
t
Yes I did set this param, I have index parallel tasks that run every hour to reprocess segments on now -3 hours
So as long as I don’t have ingestion lag > to 3 hours, it’s supposed to be fine
v
you set druid.indexer.tasklock.forceTimeChunkLock to true or false?
t
True
False
v
I can see
Copy code
Adding lock on interval[2023-01-10T09:00:00.000Z/2023-01-10T10:00:00.000Z]
this means the timechunk is being locked.
can you paste the ingestion spec here?
t
Yep, here it is.
Copy code
{
  "type": "kafka",
  "spec": {
    "dataSchema": {
      "dataSource": "beam-advanced",
      "timestampSpec": {
        "column": "timestamp",
        "format": "millis",
        "missingValue": null
      },
      "dimensionsSpec": {
        "dimensions":
      },
      "metricsSpec": [
      ],
      "granularitySpec": {
        "type": "uniform",
        "segmentGranularity": "HOUR",
        "queryGranularity": "HOUR",
        "rollup": true,
        "intervals": []
      },
      "transformSpec": {
        "filter": null,
        "transforms": []
      }
    },
    "ioConfig": {
      "topic": "beam-druid-advanced",
      "inputFormat": {
        "type": "avro_stream",
        "avroBytesDecoder": {
          "type": "schema_registry",
          "url": "<http://schema-registry:80>",
          "capacity": 2147483647,
          "urls": null,
          "config": null,
          "headers": null
        },
        "binaryAsString": false,
        "extractUnionsByType": false
      },
      "replicas": 1,
      "taskCount": 20,
      "taskDuration": "PT1800S",
      "consumerProperties": {
        "bootstrap.servers": "10.132.0.164:9092,10.132.0.4:9092,10.132.0.94:9092"
      },
      "autoScalerConfig": {
        "autoScalerStrategy": "lagBased",
        "lagCollectionIntervalMillis": 30000,
        "lagCollectionRangeMillis": 600000,
        "scaleActionStartDelayMillis": 300000,
        "scaleActionPeriodMillis": 600000,
        "scaleOutThreshold": 1800000,
        "scaleInThreshold": 600000,
        "triggerScaleOutFractionThreshold": 0.3,
        "triggerScaleInFractionThreshold": 0.9,
        "taskCountMax": 60,
        "taskCountMin": 20,
        "scaleInStep": 3,
        "scaleOutStep": 10,
        "enableTaskAutoScaler": true,
        "minTriggerScaleActionFrequencyMillis": 600000
      },
      "pollTimeout": 100,
      "startDelay": "PT5S",
      "period": "PT30S",
      "useEarliestOffset": false,
      "completionTimeout": "PT3600S",
      "lateMessageRejectionPeriod": null,
      "earlyMessageRejectionPeriod": null,
      "lateMessageRejectionStartDateTime": null,
      "configOverrides": null,
      "idleConfig": null,
      "stream": "beam-druid-advanced",
      "useEarliestSequenceNumber": false,
      "type": "kafka"
    },
    "tuningConfig": {
      "type": "kafka",
      "appendableIndexSpec": {
        "type": "onheap",
        "preserveExistingMetrics": false
      },
      "maxRowsInMemory": 5000000,
      "maxBytesInMemory": 0,
      "skipBytesInMemoryOverheadCheck": false,
      "maxRowsPerSegment": 5000000,
      "maxTotalRows": null,
      "intermediatePersistPeriod": "PT10M",
      "maxPendingPersists": 0,
      "indexSpec": {
        "bitmap": {
          "type": "roaring",
          "compressRunOnSerialization": true
        },
        "dimensionCompression": "lz4",
        "stringDictionaryEncoding": {
          "type": "utf8"
        },
        "metricCompression": "lz4",
        "longEncoding": "longs"
      },
      "indexSpecForIntermediatePersists": {
        "bitmap": {
          "type": "roaring",
          "compressRunOnSerialization": true
        },
        "dimensionCompression": "lz4",
        "stringDictionaryEncoding": {
          "type": "utf8"
        },
        "metricCompression": "lz4",
        "longEncoding": "longs"
      },
      "reportParseExceptions": false,
      "handoffConditionTimeout": 0,
      "resetOffsetAutomatically": false,
      "segmentWriteOutMediumFactory": null,
      "workerThreads": null,
      "chatThreads": null,
      "chatRetries": 8,
      "httpTimeout": "PT10S",
      "shutdownTimeout": "PT80S",
      "offsetFetchPeriod": "PT30S",
      "intermediateHandoffPeriod": "P2147483647D",
      "logParseExceptions": false,
      "maxParseExceptions": 2147483647,
      "maxSavedParseExceptions": 0,
      "skipSequenceNumberAvailabilityCheck": false,
      "repartitionTransitionDuration": "PT120S"
    }
  },
  "context": null
}
I’ve removed Dimensions and Metrics to reduce lines number
v
can you check whether the segment beam-advanced_2023-01-10T090000.000Z_2023-01-10T100000.000Z_2023-01-10T090000.409Z_95 is there in deep storage?
also can you attach the complete indexer log here?
t
beam-advanced_2023-01-10T09:00:00.000Z_2023-01-10T10:00:00.000Z_2023-01-10T09:00:00.409Z_95
is in deepstorage
Are you looking for a specific log? Complete indexer log file is quite large
v
I wanted to see if there are any metastore related errors in the log
t
I’m not seeing any on last 7 days log files
But I see some on Overlord
Copy code
2023-01-10T14:16:09,456 ERROR [qtp1663774813-96] org.apache.druid.metadata.IndexerSQLMetadataStorageCoordinator - Not updating metadata, existing state[KafkaDataSourceMetadata{SeekableStreamStartSequenceNumbers=SeekableStreamEndSequenceNumbers{stream='beam-druid-advanced', partitionSequenceNumberMap={0=83136890594, 1=83138034196, 2=83142206416, 3=83135746794, 4=83133102661, 5=83144589079, 6=83138958041, 7=83145688514, 8=83141158957, 9=83143154144, 10=83143377395, 11=83132968161, 12=83136163084, 13=83145338046, 14=83144143176, 15=83131158304, 16=83143757903, 17=83130350940, 18=83141565120, 19=83137301513, 20=83140701183, 21=83138387779, 22=83125760363, 23=83128492684, 24=83137951965, 25=83139573435, 26=83137124298, 27=83136648498, 28=83133421578, 29=83138551553, 30=83136084816, 31=83146705126, 32=83141078392, 33=83137933885, 34=83139135901, 35=83142592127, 36=83142232655, 37=83141149242, 38=83141173313, 39=83136128662, 40=83140164200, 41=83136588235, 42=83138380656, 43=83131970025, 44=83139131020, 45=83139589776, 46=83136159710, 47=83131186934, 48=83134270883, 49=83139117220, 50=83130774219, 51=83135892414, 52=83133012142, 53=83132213354, 54=83139511875, 55=83140863214, 56=83143443909, 57=83130369404, 58=83135860610, 59=83141289695, 60=83137290560, 61=83137620915, 62=83142399139, 63=83135925945, 64=83132652012, 65=83144988737, 66=83139403081, 67=83145262277, 68=83141437820, 69=83143349294, 70=83142930825, 71=83133358162, 72=83136528507, 73=83144896804, 74=83144360302, 75=83131352965, 76=83143312677, 77=83130667084, 78=83141979067, 79=83136933725, 80=83140993117, 81=83138605595, 82=83125341478, 83=83128839288, 84=83138390276, 85=83139092232, 86=83137297550, 87=83136866808, 88=83132964994, 89=83138872724}}}] in metadata store doesn't match to the new start state[KafkaDataSourceMetadata{SeekableStreamStartSequenceNumbers=SeekableStreamStartSequenceNumbers{stream='beam-druid-advanced', partitionSequenceNumberMap={18=83142413921, 38=83142027332, 58=83136723830, 78=83142821027}, exclusivePartitions=[]}}].
v
Try resetting the supervisor…that will most likely solve this problem
t
I’ve already tried to reset the supervisor yesterday
I will try again, thanks for your help Vijay
v
has there been any change to the topic name?
t
Nope