Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Lost splits #1987

Closed
yangjinming1062 opened this issue Sep 21, 2022 · 19 comments
Closed

Lost splits #1987

yangjinming1062 opened this issue Sep 21, 2022 · 19 comments
Labels
bug Something isn't working

Comments

@yangjinming1062
Copy link

yangjinming1062 commented Sep 21, 2022

Describe the bug
I used the same way to query, the first time the results, after a few minutes again query query failed
image

Expected behavior
Same query same result.

Configuration:
Please provide:

  1. quickwit --version:0.3.1
  2. The index_config.yaml
    `---
    version: 0 # File format version.

index_id: traceback

doc_mapping:
field_mappings:
- name: id
type: u64
fast: true
- name: raw_content
type: text
tokenizer: default
record: position
search_settings:
default_search_fields: [raw_content]

sources:
- source_id: source-kafka
source_type: kafka
params:
topic: UserAction
client_params:
bootstrap.servers: $(KAFKA)
group.id: FullText
security.protocol: PLAINTEXT
`

@yangjinming1062 yangjinming1062 added the bug Something isn't working label Sep 21, 2022
@yangjinming1062 yangjinming1062 changed the title The query results are inconsistent Lost splits Sep 21, 2022
@PSeitz
Copy link
Contributor

PSeitz commented Sep 21, 2022

Can you share the logs of your indexer? In the other issue, it seems you were using kubernetes and local file system as data dir. Maybe you are using an ephemeral file system?

@fulmicoton
Copy link
Contributor

This is most likely the same misconfiguration as #1989. We are happy to help but need more information.

@fulmicoton
Copy link
Contributor

fulmicoton commented Sep 22, 2022

Reopening as it might not be related to #1989.
@yangjinming1062 can you tell us if your problem is fixed or not.

If the problem persists, can you confirm that this is a one server setting and share some of the indexer logs?

In your source settings, you seem to have used a template variable. Can you confirm that this is not really the configuration?
I don't think env variables are supported in 0.3.1. (they are in main).

sources:
- source_id: source-kafka
source_type: kafka
params:
topic: UserAction
client_params:
bootstrap.servers: $(KAFKA) <----- This is not supported in quickwit 0.3.1
group.id: FullText
security.protocol: PLAINTEXT

@yangjinming1062
Copy link
Author

$(KAFKA)

Thanks, I have some scripts to replace the $(KAFKA) with a truly IP such as 192.168.0.10, so I'm sure the configuration is right.

@yangjinming1062
Copy link
Author

I give some detail on the #1989, but yes, this problem doesn't like 1989, so I will provide all my configuration.

quickwit.yaml

version: 0
#node_id: quickwit-default

listen_address: 0.0.0.0
rest_listen_port: 7280
gossip_listen_port: 7281
grpc_listen_port: 7282

metastore_uri: postgres://root:7WVWjiveUUeX.LfAA3HJBx.*iP9kAk7a@$(HOST):5432
#metastore_uri: file://./qwdata/WT#polling_interval=30s
#data_dir: file://./qwdata/WT

index.yaml

version: 0 # File format version.

index_id: traceback

doc_mapping:
    field_mappings:
        - name: id
          type: u64
          fast: true
        - name: raw_content
          type: text
          tokenizer: default
          record: position
search_settings:
    default_search_fields: [raw_content]

sources:
    - source_id: source-kafka
      source_type: kafka
      params:
          topic: UserAction
          client_params:
              bootstrap.servers: 127.0.0.1
              group.id: FullText
              security.protocol: PLAINTEXT

docker-compose.yaml

services:
    postgres:
        image: postgres:14.5
        container_name: container_postgres
        restart: always
        environment:
          POSTGRES_USER: root
          POSTGRES_PASSWORD: 7WVWjiveUUeX.LfAA3HJBx.*iP9kAk7a
        ports:
        - 5432:5432
        volumes:
        - /opt/data/postgresql:/var/lib/postgresql/data

    quickwit_init:
        image: private_registry/quickwit:latest
        container_name: quickwit_init
        command: index create --index-config /quickwit/config/traceback.yaml --overwrite
        volumes:
            - /opt/data/quickwit:/quickwit/qwdata

    quickwit_service:
        restart: always
        image: private_registry/quickwit:latest
        container_name: quickwit_service
        command: run
        volumes:
            - /opt/data/quickwit:/quickwit/qwdata
        ports:
            - "7280:7280"
        depends_on:
            - quickwit_init

private quickwit's Dockerfile

FROM private_registry/some_base_image:latest as builder
WORKDIR /install

COPY quickwit/quickwit.yaml ./quickwit.yaml
COPY quickwit/traceback.yaml ./traceback.yaml
COPY quickwit/init.py ./init.py  # replace the env variables  such like $(KAFKA)
RUN python init.py

# ----------------
FROM quickwit/quickwit:latest as quickwit

COPY --from=builder /install ./config

ENTRYPOINT ["/usr/local/bin/quickwit"]

some error log

2022-09-22T09:43:53.676Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{index=traceback gen=0}:{actor=Uploader}:{msg_id=2479 num_splits=1}: quickwit_indexing::split_store::indexing_split_store: store-in-cache
2022-09-22T09:43:53.677Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{index=traceback gen=0}:{actor=Uploader}:{msg_id=2479 num_splits=1}: quickwit_indexing::actors::uploader: success-stage-and-store-splits
2022-09-22T09:43:53.678Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{index=traceback gen=0}:{actor=Publisher}:{msg_id=2479}: quickwit_metastore::checkpoint: delta=∆(00000000000000000000:(00000000000000008418..00000000000000008421]) checkpoint=Ckpt(00000000000000000000:00000000000000008418)
2022-09-22T09:43:53.924Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{index=traceback gen=0}:{actor=Publisher}:{msg_id=2479}: quickwit_indexing::actors::publisher: publish-new-splits new_split="01GDJ97ZF793CAN8PS14P1ZP6H" tts=68.47921 checkpoint_delta=∆(00000000000000000000:(00000000000000008418..00000000000000008421])
2022-09-22T09:43:53.924Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{index=traceback gen=0}:{actor=Publisher}:{msg_id=2479}: quickwit_metastore::checkpoint: delta=∆(00000000000000000000:(00000000000000008418..00000000000000008421]) checkpoint=Ckpt()
2022-09-22T09:44:14.545Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{index=traceback gen=0}:{actor=GarbageCollector}:{msg_id=2100}: quickwit_indexing::actors::garbage_collector: garbage-collect-operation
2022-09-22T09:44:14.545Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{index=traceback gen=0}:{actor=GarbageCollector}:{msg_id=2100}: quickwit_indexing::actors::garbage_collector: garbage-collect-operation
2022-09-22T09:44:47.612Z ERROR {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=2853}: quickwit_actors::actor_handle: actor-timeout actor="Indexer-wild-RzsI"
2022-09-22T09:44:47.612Z ERROR {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=2853}: quickwit_indexing::actors::indexing_pipeline: indexing pipeline error. index=traceback gen=1 healthy=["SourceActor-floral-a1gT", "Packager-winter-AsNQ", "Uploader-green-Trde", "quickwit_indexing::actors::sequencer::Sequencer<quickwit_indexing::actors::publisher::Publisher>-black-xKC8", "Publisher-rough-J4rK", "GarbageCollector-muddy-rP1K", "MergePlanner-shy-MPi2", "MergeSplitDownloader-muddy-fslV", "MergeExecutor-small-5TfP", "MergePackager-lively-oUch", "MergeUploader-damp-lKb2", "quickwit_indexing::actors::sequencer::Sequencer<quickwit_indexing::actors::publisher::Publisher>-wispy-HWAJ", "MergePublisher-withered-5DfU"] failure_or_unhealthy_actors=["Indexer-wild-RzsI"] success=[]
2022-09-22T09:44:47.612Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{index=traceback gen=0}:{actor=Publisher}: quickwit_actors::runner: actor-exit actor_id=Publisher-rough-J4rK exit_status=Killed
2022-09-22T09:44:47.612Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{index=traceback gen=0}:{actor=Uploader}: quickwit_actors::runner: actor-exit actor_id=Uploader-green-Trde exit_status=Killed
2022-09-22T09:44:47.612Z ERROR {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{index=traceback gen=0}:{actor=Publisher}: quickwit_actors::actor: actor-failure actor_name="Publisher-rough-J4rK" actor_exit_status=Killed
2022-09-22T09:44:47.612Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{index=traceback gen=0}:{actor=GarbageCollector}: quickwit_actors::runner: actor-exit actor_id=GarbageCollector-muddy-rP1K exit_status=Killed
2022-09-22T09:44:47.612Z ERROR {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{index=traceback gen=0}:{actor=Uploader}: quickwit_actors::actor: actor-failure actor_name="Uploader-green-Trde" actor_exit_status=Killed
2022-09-22T09:44:47.612Z ERROR {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{index=traceback gen=0}:{actor=GarbageCollector}: quickwit_actors::actor: actor-failure actor_name="GarbageCollector-muddy-rP1K" actor_exit_status=Killed
2022-09-22T09:44:47.612Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{index=traceback gen=0}:{actor=MergePackager}: quickwit_actors::runner: actor-exit actor_id=MergePackager-lively-oUch exit_status=Killed
2022-09-22T09:44:47.612Z ERROR {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{index=traceback gen=0}:{actor=MergePackager}: quickwit_actors::actor: actor-failure actor_name="MergePackager-lively-oUch" actor_exit_status=Killed
2022-09-22T09:44:47.612Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{index=traceback gen=0}:{actor=MergeExecutor}: quickwit_actors::runner: actor-exit actor_id=MergeExecutor-small-5TfP exit_status=Killed
2022-09-22T09:44:47.612Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{index=traceback gen=0}:{actor=Packager}: quickwit_actors::runner: actor-exit actor_id=Packager-winter-AsNQ exit_status=Killed
2022-09-22T09:44:47.612Z ERROR {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{index=traceback gen=0}:{actor=Packager}: quickwit_actors::actor: actor-failure actor_name="Packager-winter-AsNQ" actor_exit_status=Killed
2022-09-22T09:44:47.612Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{index=traceback gen=0}:{actor=MergePlanner}: quickwit_actors::runner: actor-exit actor_id=MergePlanner-shy-MPi2 exit_status=Killed
2022-09-22T09:44:47.612Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{index=traceback gen=0}:{actor=MergeSplitDownloader}: quickwit_actors::runner: actor-exit actor_id=MergeSplitDownloader-muddy-fslV exit_status=Killed
2022-09-22T09:44:47.612Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{index=traceback gen=0}:{actor=MergeUploader}: quickwit_actors::runner: actor-exit actor_id=MergeUploader-damp-lKb2 exit_status=Killed
2022-09-22T09:44:47.612Z ERROR {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{index=traceback gen=0}:{actor=MergeSplitDownloader}: quickwit_actors::actor: actor-failure actor_name="MergeSplitDownloader-muddy-fslV" actor_exit_status=Killed
2022-09-22T09:44:47.612Z ERROR {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{index=traceback gen=0}:{actor=MergeExecutor}: quickwit_actors::actor: actor-failure actor_name="MergeExecutor-small-5TfP" actor_exit_status=Killed
2022-09-22T09:44:47.612Z ERROR {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{index=traceback gen=0}:{actor=MergeUploader}: quickwit_actors::actor: actor-failure actor_name="MergeUploader-damp-lKb2" actor_exit_status=Killed
2022-09-22T09:44:47.612Z ERROR {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{index=traceback gen=0}:{actor=MergePlanner}: quickwit_actors::actor: actor-failure actor_name="MergePlanner-shy-MPi2" actor_exit_status=Killed
2022-09-22T09:44:47.612Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{index=traceback gen=0}:{actor=MergePublisher}: quickwit_actors::runner: actor-exit actor_id=MergePublisher-withered-5DfU exit_status=Killed
2022-09-22T09:44:47.612Z ERROR {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{index=traceback gen=0}:{actor=MergePublisher}: quickwit_actors::actor: actor-failure actor_name="MergePublisher-withered-5DfU" actor_exit_status=Killed
2022-09-22T09:44:47.668Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{index=traceback gen=0}:{actor=quickwit_indexing::actors::sequencer::Sequencer<quickwit_indexing::actors::publisher::Publisher>}: quickwit_actors::runner: actor-exit actor_id=quickwit_indexing::actors::sequencer::Sequencer<quickwit_indexing::actors::publisher::Publisher>-wispy-HWAJ exit_status=Killed
2022-09-22T09:44:47.669Z ERROR {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{index=traceback gen=0}:{actor=quickwit_indexing::actors::sequencer::Sequencer<quickwit_indexing::actors::publisher::Publisher>}: quickwit_actors::actor: actor-failure actor_name="quickwit_indexing::actors::sequencer::Sequencer<quickwit_indexing::actors::publisher::Publisher>-wispy-HWAJ" actor_exit_status=Killed
2022-09-22T09:44:47.785Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{index=traceback gen=0}:{actor=quickwit_indexing::actors::sequencer::Sequencer<quickwit_indexing::actors::publisher::Publisher>}: quickwit_actors::runner: actor-exit actor_id=quickwit_indexing::actors::sequencer::Sequencer<quickwit_indexing::actors::publisher::Publisher>-black-xKC8 exit_status=Killed
2022-09-22T09:44:47.785Z ERROR {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{index=traceback gen=0}:{actor=quickwit_indexing::actors::sequencer::Sequencer<quickwit_indexing::actors::publisher::Publisher>}: quickwit_actors::actor: actor-failure actor_name="quickwit_indexing::actors::sequencer::Sequencer<quickwit_indexing::actors::publisher::Publisher>-black-xKC8" actor_exit_status=Killed
2022-09-22T09:44:48.536Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{index=traceback gen=0}:{actor=KafkaSource{source_id=source-kafka}}: quickwit_actors::runner: actor-exit actor_id=SourceActor-floral-a1gT exit_status=Killed
2022-09-22T09:44:48.536Z ERROR {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{index=traceback gen=0}:{actor=KafkaSource{source_id=source-kafka}}: quickwit_actors::actor: actor-failure actor_name="SourceActor-floral-a1gT" actor_exit_status=Killed
2022-09-22T09:44:51.020Z ERROR {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=2161}: quickwit_actors::actor_handle: actor-timeout actor="IndexingPipeline-small-VCAd"
2022-09-22T09:44:51.021Z ERROR {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=2161}: quickwit_indexing::actors::indexing_service: Indexing pipeline failed. index_id=traceback source_id=source-kafka
2022-09-22T09:44:51.888Z ERROR {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{index=traceback gen=0}:{actor=Indexer}: quickwit_actors::runner: actor-failure cause=An IO error occurred: 'Directory kill switch was activated.'

Caused by:
    Directory kill switch was activated. exit_status=Failure(An IO error occurred: 'Directory kill switch was activated.'

Caused by:
    Directory kill switch was activated.)
2022-09-22T09:44:51.888Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{index=traceback gen=0}:{actor=Indexer}: quickwit_actors::runner: actor-exit actor_id=Indexer-wild-RzsI exit_status=Failure(cause=An IO error occurred: 'Directory kill switch was activated.'

Caused by:
    Directory kill switch was activated.)
2022-09-22T09:44:51.888Z ERROR {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{index=traceback gen=0}:{actor=Indexer}: quickwit_actors::actor: actor-failure actor_name="Indexer-wild-RzsI" actor_exit_status=Failure(An IO error occurred: 'Directory kill switch was activated.'

Caused by:
    Directory kill switch was activated.)
2022-09-22T09:44:51.888Z ERROR {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{index=traceback gen=0}:{actor=Indexer}: quickwit_actors::actor: exit activating-kill-switch actor=Indexer-wild-RzsI exit_status=Failure(An IO error occurred: 'Directory kill switch was activated.'

Caused by:
    Directory kill switch was activated.)
2022-09-22T09:44:54.890Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=2860}:{index=traceback gen=1}: quickwit_indexing::actors::indexing_pipeline: spawn-indexing-pipeline root_dir=/quickwit/qwdata/indexing/traceback/source-kafka merge_policy=StableMultitenantWithTimestampMergePolicy { demux_enabled: false, demux_factor: 8, demux_field_name: None, min_level_num_docs: 100000, merge_enabled: true, merge_factor: 10, max_merge_factor: 12, split_num_docs_target: 10000000 }
2022-09-22T09:44:54.893Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=2860}:{index=traceback gen=1}:{actor=GarbageCollector}: quickwit_indexing::actors::garbage_collector: garbage-collect-operation
2022-09-22T09:44:54.899Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=2860}:{index=traceback gen=1}: quickwit_indexing::source::kafka_source: Starting Kafka source. topic=UserAction assignment=TPL {UserAction/0: offset=Offset(8422) metadata=""}
2022-09-22T09:44:58.979Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=2860}:{index=traceback gen=1}:{actor=Indexer}:{msg_id=4}: quickwit_indexing::actors::indexer: new-split split_id=01GDJ9C0TH0KWCA5AYDKY2DV1T
2022-09-22T09:45:14.148Z  INFO quickwit_serve::search_api::rest_handler: search index_id=traceback request=SearchRequestQueryString { query: "575061115614664283", aggs: None, search_fields: Some(["id"]), start_timestamp: None, end_timestamp: None, max_hits: 20, start_offset: 0, format: PrettyJson, sort_by_field: None }
2022-09-22T09:45:14.154Z  INFO leaf_search: quickwit_search::service: leaf_search index="traceback" splits=[SplitIdAndFooterOffsets { split_id: "01GDJ849CYJGFE9Z4Y4Q9T4J21", split_footer_start: 1689453, split_footer_end: 1694084 }, SplitIdAndFooterOffsets { split_id: "01GDJ8V7WTE1KN88X52Z12VFZC", split_footer_start: 4754, split_footer_end: 8651 }, SplitIdAndFooterOffsets { split_id: "01GDJ97ZF793CAN8PS14P1ZP6H", split_footer_start: 5759, split_footer_end: 9665 }]

@yangjinming1062
Copy link
Author

The splits lost again, and I confirm container never restart.

@fulmicoton
Copy link
Contributor

@yangjinming1062 thank you for the report.

The error in the logs are unrelated to your problem.
Some explanation: Quickwit's indexing relies on an actor model.
For extra resilience, if an actor does not record any progress for more than 3s, a supervisor kills all actors and restarts the pipeline. The other ERROR lines are just all of the actors in the pipeline being killed by the supervisor. It looks spectacular but it is actually business as usual.

This can happen from time to time, if the hardware hangs etc.

This is what I see on this line.

> 2022-09-22T09:44:47.612Z ERROR {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=2853}: quickwit_indexing::actors::indexing_pipeline: indexing pipeline error. index=traceback gen=1 healthy=["SourceActor-floral-a1gT", "Packager-winter-AsNQ", "Uploader-green-Trde", "quickwit_indexing::actors::sequencer::Sequencer<quickwit_indexing::actors::publisher::Publisher>-black-xKC8", "Publisher-rough-J4rK", "GarbageCollector-muddy-rP1K", "MergePlanner-shy-MPi2", "MergeSplitDownloader-muddy-fslV", "MergeExecutor-small-5TfP", "MergePackager-lively-oUch", "MergeUploader-damp-lKb2", "quickwit_indexing::actors::sequencer::Sequencer<quickwit_indexing::actors::publisher::Publisher>-wispy-HWAJ", "MergePublisher-withered-5DfU"] failure_or_unhealthy_actors=["Indexer-wild-RzsI"] success=[]

Give me a minute to have a look at your docker-compose configuration.

@fulmicoton
Copy link
Contributor

@yangjinming1062 I could not find anything wrong with your configuration..
One thing that we can do to see if it is a problem on postgres or some quickwit misbehavior is this:

Can you add the following command attribute to your postgres docker config:

postgres:
    image: postgres:14.5
    command: ["postgres", "-c", "logging_collector=on", "-c", "log_directory=/var/lib/postgresql/data/pglogs", "-c", "log_filename=postgresql.log", "-c", "log_statement=all"]
    ...

It will force postgresql to log all of the statement sent to it into the same directory in your host computer. We can them have a look at them.

If the file is more or less empty, it means docker trashed the volume.
If we see statement deleting tables or split rows, then quickwit is misbehaving.

@yangjinming1062
Copy link
Author

@yangjinming1062 I could not find anything wrong with your configuration.. One thing that we can do to see if it is a problem on postgres or some quickwit misbehavior is this:

Can you add the following command attribute to your postgres docker config:

postgres:
    image: postgres:14.5
    command: ["postgres", "-c", "logging_collector=on", "-c", "log_directory=/var/lib/postgresql/data/pglogs", "-c", "log_filename=postgresql.log", "-c", "log_statement=all"]
    ...

It will force postgresql to log all of the statement sent to it into the same directory in your host computer. We can them have a look at them.

If the file is more or less empty, it means docker trashed the volume. If we see statement deleting tables or split rows, then quickwit is misbehaving.

OK, I will add the postgres docker config and try again.

@yangjinming1062
Copy link
Author

image
I try another way: I used the default quickwit.yaml and I confirm the quickwit_service container dosen't restart. At the first time , the index created_at and update_at is another time, and documents is about 900+. after about 2 hour, when I refresh the web UI I saw the index created_at and updated_at has changed. so may be the problem isn't lost splits, but index changed?

@yangjinming1062
Copy link
Author

yangjinming1062 commented Sep 23, 2022

It seem like the --overwrite command will delete old index. In my real docker-compose.yaml, there are some other service and gitab CI will rebuild quickwit_init each time.
But every created_at interval is very stable is one hour, which makes me feel very weird. I saw 03:09→04:09→05:09. After fixed the --overwrite, I'll keep looking at the problem and make sure it is fixed.

@fulmicoton
Copy link
Contributor

fulmicoton commented Sep 23, 2022

ah yes mystery solved then! good find!

Thank you for your perseverance!
--overwrite does indeed trash your index and recreates a new one..

@yangjinming1062
Copy link
Author

yangjinming1062 commented Sep 23, 2022

ah yes mystery solved then! good find!

Thank you for your perseverance! --overwrite does indeed trash your index and recreates a new one..

This time I confirm the index not changed, but split lost.

first time split

[
  {
    "split_state": "Published",
    "update_timestamp": 1663913636,
    "version": "1",
    "split_id": "01GDMFPBPQMZ69Z5406WVT12HK",
    "num_docs": 38,
    "size_in_bytes": 641899,
    "time_range": null,
    "create_timestamp": 1663913636,
    "tags": [],
    "demux_num_ops": 0,
    "footer_offsets": {
      "start": 181978,
      "end": 186001
    }
  },
  {
    "split_state": "MarkedForDeletion",
    "update_timestamp": 1663913636,
    "version": "1",
    "split_id": "01GDME12J1R0873HYJWMPDXKD4",
    "num_docs": 2,
    "size_in_bytes": 7524,
    "time_range": null,
    "create_timestamp": 1663911949,
    "tags": [],
    "demux_num_ops": 0,
    "footer_offsets": {
      "start": 5748,
      "end": 9638
    }
  },
  {
    "split_state": "MarkedForDeletion",
    "update_timestamp": 1663913636,
    "version": "1",
    "split_id": "01GDMEGQDNYTJKT6W1V1PMTTVS",
    "num_docs": 3,
    "size_in_bytes": 8031,
    "time_range": null,
    "create_timestamp": 1663912463,
    "tags": [],
    "demux_num_ops": 0,
    "footer_offsets": {
      "start": 5162,
      "end": 9011
    }
  },
  {
    "split_state": "MarkedForDeletion",
    "update_timestamp": 1663913636,
    "version": "1",
    "split_id": "01GDMEXW5HEDMWP1DYKH62D841",
    "num_docs": 2,
    "size_in_bytes": 25347,
    "time_range": null,
    "create_timestamp": 1663912899,
    "tags": [],
    "demux_num_ops": 0,
    "footer_offsets": {
      "start": 16999,
      "end": 20938
    }
  },
  {
    "split_state": "MarkedForDeletion",
    "update_timestamp": 1663913636,
    "version": "1",
    "split_id": "01GDMF25ZEMXGKGMPX8NXPVAFG",
    "num_docs": 3,
    "size_in_bytes": 9541,
    "time_range": null,
    "create_timestamp": 1663913034,
    "tags": [],
    "demux_num_ops": 0,
    "footer_offsets": {
      "start": 6106,
      "end": 10002
    }
  },
  {
    "split_state": "MarkedForDeletion",
    "update_timestamp": 1663913636,
    "version": "1",
    "split_id": "01GDMF6YE8M5QB40Q9TBH5NJX8",
    "num_docs": 7,
    "size_in_bytes": 23294,
    "time_range": null,
    "create_timestamp": 1663913190,
    "tags": [],
    "demux_num_ops": 0,
    "footer_offsets": {
      "start": 10503,
      "end": 14354
    }
  },
  {
    "split_state": "MarkedForDeletion",
    "update_timestamp": 1663913636,
    "version": "1",
    "split_id": "01GDMF8XFDK1T3JKCK5DVYPMBZ",
    "num_docs": 7,
    "size_in_bytes": 436813,
    "time_range": null,
    "create_timestamp": 1663913255,
    "tags": [],
    "demux_num_ops": 0,
    "footer_offsets": {
      "start": 108988,
      "end": 112896
    }
  },
  {
    "split_state": "MarkedForDeletion",
    "update_timestamp": 1663913636,
    "version": "1",
    "split_id": "01GDMFAV1ZGF42Y1FY0EBM7R42",
    "num_docs": 7,
    "size_in_bytes": 105571,
    "time_range": null,
    "create_timestamp": 1663913319,
    "tags": [],
    "demux_num_ops": 0,
    "footer_offsets": {
      "start": 52219,
      "end": 56165
    }
  },
  {
    "split_state": "MarkedForDeletion",
    "update_timestamp": 1663913636,
    "version": "1",
    "split_id": "01GDMFHM1EPJW847ZMP94YCCGG",
    "num_docs": 5,
    "size_in_bytes": 17020,
    "time_range": null,
    "create_timestamp": 1663913540,
    "tags": [],
    "demux_num_ops": 0,
    "footer_offsets": {
      "start": 9386,
      "end": 13222
    }
  },
  {
    "split_state": "MarkedForDeletion",
    "update_timestamp": 1663913636,
    "version": "1",
    "split_id": "01GDMDF3VRK64DSX7755MD7MT9",
    "num_docs": 1,
    "size_in_bytes": 5326,
    "time_range": null,
    "create_timestamp": 1663911363,
    "tags": [],
    "demux_num_ops": 0,
    "footer_offsets": {
      "start": 5264,
      "end": 9112
    }
  },
  {
    "split_state": "MarkedForDeletion",
    "update_timestamp": 1663913636,
    "version": "1",
    "split_id": "01GDMFMGCWD0Y87FQE75HCCEYN",
    "num_docs": 1,
    "size_in_bytes": 3432,
    "time_range": null,
    "create_timestamp": 1663913635,
    "tags": [],
    "demux_num_ops": 0,
    "footer_offsets": {
      "start": 4588,
      "end": 8451
    }
  }
]

img_v2_68d25f99-615b-41eb-b446-f448510793ag

second time split

[
  {
    "split_state": "Published",
    "update_timestamp": 1663913636,
    "version": "1",
    "split_id": "01GDMFPBPQMZ69Z5406WVT12HK",
    "num_docs": 38,
    "size_in_bytes": 641899,
    "time_range": null,
    "create_timestamp": 1663913636,
    "tags": [],
    "demux_num_ops": 0,
    "footer_offsets": {
      "start": 181978,
      "end": 186001
    }
  },
  {
    "split_state": "Published",
    "update_timestamp": 1663913861,
    "version": "1",
    "split_id": "01GDMFVDRRF7ZYTXC3FXHHYJSX",
    "num_docs": 1,
    "size_in_bytes": 5319,
    "time_range": null,
    "create_timestamp": 1663913861,
    "tags": [],
    "demux_num_ops": 0,
    "footer_offsets": {
      "start": 5926,
      "end": 9799
    }
  }
]

image

some logs

2022-09-23T06:07:35.833Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=Uploader}:{msg_id=1711 num_splits=1}: quickwit_indexing::split_store::indexing_split_store: store-in-cache
2022-09-23T06:07:35.833Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=Uploader}:{msg_id=1711 num_splits=1}: quickwit_indexing::actors::uploader: success-stage-and-store-splits
2022-09-23T06:07:35.835Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=Publisher}:{msg_id=1711}: quickwit_metastore::checkpoint: delta=∆(00000000000000000000:(00000000000000008484..00000000000000008491]) checkpoint=Ckpt(00000000000000000000:00000000000000008484)
2022-09-23T06:07:35.850Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=Publisher}:{msg_id=1711}: quickwit_indexing::actors::publisher: publish-new-splits new_split="01GDMF8XFDK1T3JKCK5DVYPMBZ" tts=60.58312 checkpoint_delta=∆(00000000000000000000:(00000000000000008484..00000000000000008491])
2022-09-23T06:07:35.850Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=Publisher}:{msg_id=1711}: quickwit_metastore::checkpoint: delta=∆(00000000000000000000:(00000000000000008484..00000000000000008491]) checkpoint=Ckpt()
2022-09-23T06:07:38.543Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=Indexer}:{msg_id=1720}: quickwit_indexing::actors::indexer: new-split split_id=01GDMFAV1ZGF42Y1FY0EBM7R42
2022-09-23T06:08:12.619Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{index=traceback gen=0}:{actor=GarbageCollector}:{msg_id=14700}: quickwit_indexing::actors::garbage_collector: garbage-collect-operation
2022-09-23T06:08:20.935Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=GarbageCollector}:{msg_id=1500}: quickwit_indexing::actors::garbage_collector: garbage-collect-operation
2022-09-23T06:08:38.544Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=Indexer}:{msg_id=1844}: quickwit_indexing::actors::indexer: send-to-packager commit_trigger=Timeout split="01GDMFAV1ZGF42Y1FY0EBM7R42" num_docs=7
2022-09-23T06:08:38.545Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=Packager}:{msg_id=1527 num_splits=1}: quickwit_indexing::actors::packager: start-packaging-splits split_ids=["01GDMFAV1ZGF42Y1FY0EBM7R42"]
2022-09-23T06:08:38.545Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=Packager}:{msg_id=1527 num_splits=1}: quickwit_indexing::actors::packager: commit-split split_id=01GDMFAV1ZGF42Y1FY0EBM7R42
2022-09-23T06:08:39.702Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=Packager}:{msg_id=1527 num_splits=1}: quickwit_indexing::actors::packager: create-packaged-split split_id="01GDMFAV1ZGF42Y1FY0EBM7R42"
2022-09-23T06:08:39.704Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=Uploader}:{msg_id=1840 num_splits=1}: quickwit_indexing::actors::uploader: start-stage-and-store-splits split_ids=["01GDMFAV1ZGF42Y1FY0EBM7R42"]
2022-09-23T06:08:39.704Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=Uploader}:{msg_id=1840 num_splits=1}: quickwit_indexing::actors::uploader: staging-split split_id="01GDMFAV1ZGF42Y1FY0EBM7R42"
2022-09-23T06:08:39.714Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=Uploader}:{msg_id=1840 num_splits=1}: quickwit_indexing::actors::uploader: storing-split split_id="01GDMFAV1ZGF42Y1FY0EBM7R42"
2022-09-23T06:08:39.714Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=Uploader}:{msg_id=1840 num_splits=1}: quickwit_indexing::split_store::indexing_split_store: store-split-remote-start
2022-09-23T06:08:39.716Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=Uploader}:{msg_id=1840 num_splits=1}: quickwit_indexing::split_store::indexing_split_store: store-split-remote-success split_size_in_megabytes=0 num_docs=7 elapsed_secs=0.002010749 throughput_mb_s=0 is_mature=false
2022-09-23T06:08:39.716Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=Uploader}:{msg_id=1840 num_splits=1}: quickwit_indexing::split_store::indexing_split_store: store-in-cache
2022-09-23T06:08:39.716Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=Uploader}:{msg_id=1840 num_splits=1}: quickwit_indexing::actors::uploader: success-stage-and-store-splits
2022-09-23T06:08:39.718Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=Publisher}:{msg_id=1840}: quickwit_metastore::checkpoint: delta=∆(00000000000000000000:(00000000000000008491..00000000000000008498]) checkpoint=Ckpt(00000000000000000000:00000000000000008491)
2022-09-23T06:08:39.723Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=Publisher}:{msg_id=1840}: quickwit_indexing::actors::publisher: publish-new-splits new_split="01GDMFAV1ZGF42Y1FY0EBM7R42" tts=61.180065 checkpoint_delta=∆(00000000000000000000:(00000000000000008491..00000000000000008498])
2022-09-23T06:08:39.723Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=Publisher}:{msg_id=1840}: quickwit_metastore::checkpoint: delta=∆(00000000000000000000:(00000000000000008491..00000000000000008498]) checkpoint=Ckpt()
2022-09-23T06:09:12.627Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{index=traceback gen=0}:{actor=GarbageCollector}:{msg_id=14800}: quickwit_indexing::actors::garbage_collector: garbage-collect-operation
2022-09-23T06:09:20.940Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=GarbageCollector}:{msg_id=1600}: quickwit_indexing::actors::garbage_collector: garbage-collect-operation
2022-09-23T06:10:12.635Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{index=traceback gen=0}:{actor=GarbageCollector}:{msg_id=14900}: quickwit_indexing::actors::garbage_collector: garbage-collect-operation
2022-09-23T06:10:20.945Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=GarbageCollector}:{msg_id=1700}: quickwit_indexing::actors::garbage_collector: garbage-collect-operation
2022-09-23T06:11:12.640Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{index=traceback gen=0}:{actor=GarbageCollector}:{msg_id=15000}: quickwit_indexing::actors::garbage_collector: garbage-collect-operation
2022-09-23T06:11:20.413Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=Indexer}:{msg_id=2168}: quickwit_indexing::actors::indexer: new-split split_id=01GDMFHM1EPJW847ZMP94YCCGG
2022-09-23T06:11:20.950Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=GarbageCollector}:{msg_id=1800}: quickwit_indexing::actors::garbage_collector: garbage-collect-operation
2022-09-23T06:12:12.648Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{index=traceback gen=0}:{actor=GarbageCollector}:{msg_id=15100}: quickwit_indexing::actors::garbage_collector: garbage-collect-operation
2022-09-23T06:12:20.415Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=Indexer}:{msg_id=2292}: quickwit_indexing::actors::indexer: send-to-packager commit_trigger=Timeout split="01GDMFHM1EPJW847ZMP94YCCGG" num_docs=5
2022-09-23T06:12:20.415Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=Packager}:{msg_id=1895 num_splits=1}: quickwit_indexing::actors::packager: start-packaging-splits split_ids=["01GDMFHM1EPJW847ZMP94YCCGG"]
2022-09-23T06:12:20.415Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=Packager}:{msg_id=1895 num_splits=1}: quickwit_indexing::actors::packager: commit-split split_id=01GDMFHM1EPJW847ZMP94YCCGG
2022-09-23T06:12:20.910Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=Packager}:{msg_id=1895 num_splits=1}: quickwit_indexing::actors::packager: create-packaged-split split_id="01GDMFHM1EPJW847ZMP94YCCGG"
2022-09-23T06:12:20.912Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=Uploader}:{msg_id=2282 num_splits=1}: quickwit_indexing::actors::uploader: start-stage-and-store-splits split_ids=["01GDMFHM1EPJW847ZMP94YCCGG"]
2022-09-23T06:12:20.912Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=Uploader}:{msg_id=2282 num_splits=1}: quickwit_indexing::actors::uploader: staging-split split_id="01GDMFHM1EPJW847ZMP94YCCGG"
2022-09-23T06:12:20.923Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=Uploader}:{msg_id=2282 num_splits=1}: quickwit_indexing::actors::uploader: storing-split split_id="01GDMFHM1EPJW847ZMP94YCCGG"
2022-09-23T06:12:20.923Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=Uploader}:{msg_id=2282 num_splits=1}: quickwit_indexing::split_store::indexing_split_store: store-split-remote-start
2022-09-23T06:12:20.925Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=Uploader}:{msg_id=2282 num_splits=1}: quickwit_indexing::split_store::indexing_split_store: store-split-remote-success split_size_in_megabytes=0 num_docs=5 elapsed_secs=0.001998272 throughput_mb_s=0 is_mature=false
2022-09-23T06:12:20.925Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=Uploader}:{msg_id=2282 num_splits=1}: quickwit_indexing::split_store::indexing_split_store: store-in-cache
2022-09-23T06:12:20.925Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=Uploader}:{msg_id=2282 num_splits=1}: quickwit_indexing::actors::uploader: success-stage-and-store-splits
2022-09-23T06:12:20.927Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=Publisher}:{msg_id=2282}: quickwit_metastore::checkpoint: delta=∆(00000000000000000000:(00000000000000008498..00000000000000008503]) checkpoint=Ckpt(00000000000000000000:00000000000000008498)
2022-09-23T06:12:20.934Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=Publisher}:{msg_id=2282}: quickwit_indexing::actors::publisher: publish-new-splits new_split="01GDMFHM1EPJW847ZMP94YCCGG" tts=60.520683 checkpoint_delta=∆(00000000000000000000:(00000000000000008498..00000000000000008503])
2022-09-23T06:12:20.934Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=Publisher}:{msg_id=2282}: quickwit_metastore::checkpoint: delta=∆(00000000000000000000:(00000000000000008498..00000000000000008503]) checkpoint=Ckpt()
2022-09-23T06:12:20.955Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=GarbageCollector}:{msg_id=1900}: quickwit_indexing::actors::garbage_collector: garbage-collect-operation
2022-09-23T06:12:54.894Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=Indexer}:{msg_id=2361}: quickwit_indexing::actors::indexer: new-split split_id=01GDMFMGCWD0Y87FQE75HCCEYN
2022-09-23T06:13:12.656Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{index=traceback gen=0}:{actor=GarbageCollector}:{msg_id=15200}: quickwit_indexing::actors::garbage_collector: garbage-collect-operation
2022-09-23T06:13:20.960Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=GarbageCollector}:{msg_id=2000}: quickwit_indexing::actors::garbage_collector: garbage-collect-operation
2022-09-23T06:13:54.896Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=Indexer}:{msg_id=2482}: quickwit_indexing::actors::indexer: send-to-packager commit_trigger=Timeout split="01GDMFMGCWD0Y87FQE75HCCEYN" num_docs=1
2022-09-23T06:13:54.896Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=Packager}:{msg_id=2052 num_splits=1}: quickwit_indexing::actors::packager: start-packaging-splits split_ids=["01GDMFMGCWD0Y87FQE75HCCEYN"]
2022-09-23T06:13:54.896Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=Packager}:{msg_id=2052 num_splits=1}: quickwit_indexing::actors::packager: commit-split split_id=01GDMFMGCWD0Y87FQE75HCCEYN
2022-09-23T06:13:55.515Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=Packager}:{msg_id=2052 num_splits=1}: quickwit_indexing::actors::packager: create-packaged-split split_id="01GDMFMGCWD0Y87FQE75HCCEYN"
2022-09-23T06:13:55.517Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=Uploader}:{msg_id=2472 num_splits=1}: quickwit_indexing::actors::uploader: start-stage-and-store-splits split_ids=["01GDMFMGCWD0Y87FQE75HCCEYN"]
2022-09-23T06:13:55.517Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=Uploader}:{msg_id=2472 num_splits=1}: quickwit_indexing::actors::uploader: staging-split split_id="01GDMFMGCWD0Y87FQE75HCCEYN"
2022-09-23T06:13:55.531Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=Uploader}:{msg_id=2472 num_splits=1}: quickwit_indexing::actors::uploader: storing-split split_id="01GDMFMGCWD0Y87FQE75HCCEYN"
2022-09-23T06:13:55.531Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=Uploader}:{msg_id=2472 num_splits=1}: quickwit_indexing::split_store::indexing_split_store: store-split-remote-start
2022-09-23T06:13:55.533Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=Uploader}:{msg_id=2472 num_splits=1}: quickwit_indexing::split_store::indexing_split_store: store-split-remote-success split_size_in_megabytes=0 num_docs=1 elapsed_secs=0.002262846 throughput_mb_s=0 is_mature=false
2022-09-23T06:13:55.533Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=Uploader}:{msg_id=2472 num_splits=1}: quickwit_indexing::split_store::indexing_split_store: store-in-cache
2022-09-23T06:13:55.534Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=Uploader}:{msg_id=2472 num_splits=1}: quickwit_indexing::actors::uploader: success-stage-and-store-splits
2022-09-23T06:13:55.535Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=Publisher}:{msg_id=2472}: quickwit_metastore::checkpoint: delta=∆(00000000000000000000:(00000000000000008503..00000000000000008504]) checkpoint=Ckpt(00000000000000000000:00000000000000008503)
2022-09-23T06:13:55.542Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=Publisher}:{msg_id=2472}: quickwit_indexing::actors::publisher: publish-new-splits new_split="01GDMFMGCWD0Y87FQE75HCCEYN" tts=60.647934 checkpoint_delta=∆(00000000000000000000:(00000000000000008503..00000000000000008504])
2022-09-23T06:13:55.542Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=Publisher}:{msg_id=2472}: quickwit_metastore::checkpoint: delta=∆(00000000000000000000:(00000000000000008503..00000000000000008504]) checkpoint=Ckpt()
2022-09-23T06:13:55.543Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=MergePlanner}:{msg_id=2058}: quickwit_indexing::actors::merge_planner: planning-merge merge_operation=Merge(merged_split_id=01GDMFPBPQMZ69Z5406WVT12HK,splits=[01GDMDF3VRK64DSX7755MD7MT9,01GDMFMGCWD0Y87FQE75HCCEYN,01GDME12J1R0873HYJWMPDXKD4,01GDMEXW5HEDMWP1DYKH62D841,01GDMEGQDNYTJKT6W1V1PMTTVS,01GDMF25ZEMXGKGMPX8NXPVAFG,01GDMFHM1EPJW847ZMP94YCCGG,01GDMF6YE8M5QB40Q9TBH5NJX8,01GDMF8XFDK1T3JKCK5DVYPMBZ,01GDMFAV1ZGF42Y1FY0EBM7R42,])
2022-09-23T06:13:55.543Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=MergeSplitDownloader}:merge{msg_id=2054 merge_split_id=01GDMFPBPQMZ69Z5406WVT12HK num_docs=38 num_splits=10}: quickwit_indexing::actors::merge_split_downloader: download-merge-splits dir=/quickwit/qwdata/indexing/traceback/source-kafka/scratch/merge-lLcKIs
2022-09-23T06:13:55.545Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=MergeExecutor}:merge{msg_id=2054 dir=/quickwit/qwdata/indexing/traceback/source-kafka/scratch/merge-lLcKIs merge_split_id=01GDMFPBPQMZ69Z5406WVT12HK in_merge_split_ids=["01GDMDF3VRK64DSX7755MD7MT9", "01GDMFMGCWD0Y87FQE75HCCEYN", "01GDME12J1R0873HYJWMPDXKD4", "01GDMEXW5HEDMWP1DYKH62D841", "01GDMEGQDNYTJKT6W1V1PMTTVS", "01GDMF25ZEMXGKGMPX8NXPVAFG", "01GDMFHM1EPJW847ZMP94YCCGG", "01GDMF6YE8M5QB40Q9TBH5NJX8", "01GDMF8XFDK1T3JKCK5DVYPMBZ", "01GDMFAV1ZGF42Y1FY0EBM7R42"] num_docs=38 num_splits=10}: quickwit_indexing::actors::merge_executor: merge-start
2022-09-23T06:13:56.174Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=MergeExecutor}:merge{msg_id=2054 dir=/quickwit/qwdata/indexing/traceback/source-kafka/scratch/merge-lLcKIs merge_split_id=01GDMFPBPQMZ69Z5406WVT12HK in_merge_split_ids=["01GDMDF3VRK64DSX7755MD7MT9", "01GDMFMGCWD0Y87FQE75HCCEYN", "01GDME12J1R0873HYJWMPDXKD4", "01GDMEXW5HEDMWP1DYKH62D841", "01GDMEGQDNYTJKT6W1V1PMTTVS", "01GDMF25ZEMXGKGMPX8NXPVAFG", "01GDMFHM1EPJW847ZMP94YCCGG", "01GDMF6YE8M5QB40Q9TBH5NJX8", "01GDMF8XFDK1T3JKCK5DVYPMBZ", "01GDMFAV1ZGF42Y1FY0EBM7R42"] num_docs=38 num_splits=10}: quickwit_indexing::actors::merge_executor: merge-success elapsed_secs=0.6291424632072449
2022-09-23T06:13:56.175Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=MergePackager}:{msg_id=2055 num_splits=1}: quickwit_indexing::actors::packager: start-packaging-splits split_ids=["01GDMFPBPQMZ69Z5406WVT12HK"]
2022-09-23T06:13:56.175Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=MergePackager}:{msg_id=2055 num_splits=1}: quickwit_indexing::actors::packager: commit-split split_id=01GDMFPBPQMZ69Z5406WVT12HK
2022-09-23T06:13:56.266Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=MergePackager}:{msg_id=2055 num_splits=1}: quickwit_indexing::actors::packager: create-packaged-split split_id="01GDMFPBPQMZ69Z5406WVT12HK"
2022-09-23T06:13:56.267Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=MergeUploader}:{msg_id=2055 num_splits=1}: quickwit_indexing::actors::uploader: start-stage-and-store-splits split_ids=["01GDMFPBPQMZ69Z5406WVT12HK"]
2022-09-23T06:13:56.267Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=MergeUploader}:{msg_id=2055 num_splits=1}: quickwit_indexing::actors::uploader: staging-split split_id="01GDMFPBPQMZ69Z5406WVT12HK"
2022-09-23T06:13:56.272Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=MergeUploader}:{msg_id=2055 num_splits=1}: quickwit_indexing::actors::uploader: storing-split split_id="01GDMFPBPQMZ69Z5406WVT12HK"
2022-09-23T06:13:56.272Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=MergeUploader}:{msg_id=2055 num_splits=1}: quickwit_indexing::split_store::indexing_split_store: store-split-remote-start
2022-09-23T06:13:56.276Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=MergeUploader}:{msg_id=2055 num_splits=1}: quickwit_indexing::split_store::indexing_split_store: store-split-remote-success split_size_in_megabytes=0 num_docs=38 elapsed_secs=0.0035249 throughput_mb_s=0 is_mature=false
2022-09-23T06:13:56.276Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=MergeUploader}:{msg_id=2055 num_splits=1}: quickwit_indexing::split_store::indexing_split_store: store-in-cache
2022-09-23T06:13:56.276Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=MergeUploader}:{msg_id=2055 num_splits=1}: quickwit_indexing::actors::uploader: success-stage-and-store-splits
2022-09-23T06:13:56.282Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=MergePublisher}:{msg_id=2055}: quickwit_indexing::actors::publisher: replace-splits new_splits=["01GDMFPBPQMZ69Z5406WVT12HK"] replaced_splits=["01GDMFHM1EPJW847ZMP94YCCGG", "01GDMEGQDNYTJKT6W1V1PMTTVS", "01GDMF6YE8M5QB40Q9TBH5NJX8", "01GDMFAV1ZGF42Y1FY0EBM7R42", "01GDMFMGCWD0Y87FQE75HCCEYN", "01GDME12J1R0873HYJWMPDXKD4", "01GDMF25ZEMXGKGMPX8NXPVAFG", "01GDMEXW5HEDMWP1DYKH62D841", "01GDMDF3VRK64DSX7755MD7MT9", "01GDMF8XFDK1T3JKCK5DVYPMBZ"]
2022-09-23T06:14:12.664Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{index=traceback gen=0}:{actor=GarbageCollector}:{msg_id=15300}: quickwit_indexing::actors::garbage_collector: garbage-collect-operation
2022-09-23T06:14:17.286Z  INFO quickwit_serve::index_api::rest_handler: get-index index_id=traceback
2022-09-23T06:14:17.286Z  INFO quickwit_serve::index_api::rest_handler: get-index index_id=traceback
2022-09-23T06:14:20.965Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=GarbageCollector}:{msg_id=2100}: quickwit_indexing::actors::garbage_collector: garbage-collect-operation
2022-09-23T06:14:21.339Z  INFO quickwit_serve::index_api::rest_handler: get-index index_id=traceback
2022-09-23T06:14:21.339Z  INFO quickwit_serve::index_api::rest_handler: get-index index_id=traceback
2022-09-23T06:14:31.248Z  INFO quickwit_serve::index_api::rest_handler: get-index index_id=traceback
2022-09-23T06:14:31.253Z  INFO quickwit_serve::index_api::rest_handler: get-index index_id=traceback
2022-09-23T06:14:42.716Z  INFO quickwit_serve::index_api::rest_handler: get-index index_id=traceback
2022-09-23T06:14:42.720Z  INFO quickwit_serve::index_api::rest_handler: get-index index_id=traceback
2022-09-23T06:15:12.672Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{index=traceback gen=0}:{actor=GarbageCollector}:{msg_id=15400}: quickwit_indexing::actors::garbage_collector: garbage-collect-operation
2022-09-23T06:15:20.970Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=GarbageCollector}:{msg_id=2200}: quickwit_indexing::actors::garbage_collector: garbage-collect-operation
2022-09-23T06:15:43.946Z  INFO quickwit_serve::index_api::rest_handler: get-index index_id=traceback
2022-09-23T06:15:43.947Z  INFO quickwit_serve::index_api::rest_handler: get-index index_id=traceback
2022-09-23T06:15:55.303Z  INFO quickwit_serve::index_api::rest_handler: get-index index_id=traceback
2022-09-23T06:15:55.305Z  INFO quickwit_serve::index_api::rest_handler: get-index index_id=traceback
2022-09-23T06:16:12.678Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{index=traceback gen=0}:{actor=GarbageCollector}:{msg_id=15500}: quickwit_indexing::actors::garbage_collector: garbage-collect-operation
2022-09-23T06:16:12.821Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{index=traceback gen=0}:{actor=GarbageCollector}:{msg_id=15500}: quickwit_indexing::actors::garbage_collector: gc-delete deleted_files={"01GDMF6YE8M5QB40Q9TBH5NJX8.split", "01GDME12J1R0873HYJWMPDXKD4.split", "01GDMF8XFDK1T3JKCK5DVYPMBZ.split", "01GDMDF3VRK64DSX7755MD7MT9.split", "01GDMEXW5HEDMWP1DYKH62D841.split", "01GDMFAV1ZGF42Y1FY0EBM7R42.split", "01GDMFMGCWD0Y87FQE75HCCEYN.split", "01GDMF25ZEMXGKGMPX8NXPVAFG.split", "01GDMEGQDNYTJKT6W1V1PMTTVS.split", "01GDMFHM1EPJW847ZMP94YCCGG.split"}
2022-09-23T06:16:20.975Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=GarbageCollector}:{msg_id=2300}: quickwit_indexing::actors::garbage_collector: garbage-collect-operation
2022-09-23T06:16:41.725Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=Indexer}:{msg_id=2816}: quickwit_indexing::actors::indexer: new-split split_id=01GDMFVDRRF7ZYTXC3FXHHYJSX
2022-09-23T06:17:12.823Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{index=traceback gen=0}:{actor=GarbageCollector}:{msg_id=15600}: quickwit_indexing::actors::garbage_collector: garbage-collect-operation
2022-09-23T06:17:20.980Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=GarbageCollector}:{msg_id=2400}: quickwit_indexing::actors::garbage_collector: garbage-collect-operation
2022-09-23T06:17:41.726Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=Indexer}:{msg_id=2937}: quickwit_indexing::actors::indexer: send-to-packager commit_trigger=Timeout split="01GDMFVDRRF7ZYTXC3FXHHYJSX" num_docs=1
2022-09-23T06:17:41.727Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=Packager}:{msg_id=2429 num_splits=1}: quickwit_indexing::actors::packager: start-packaging-splits split_ids=["01GDMFVDRRF7ZYTXC3FXHHYJSX"]
2022-09-23T06:17:41.727Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=Packager}:{msg_id=2429 num_splits=1}: quickwit_indexing::actors::packager: commit-split split_id=01GDMFVDRRF7ZYTXC3FXHHYJSX
2022-09-23T06:17:41.840Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=Packager}:{msg_id=2429 num_splits=1}: quickwit_indexing::actors::packager: create-packaged-split split_id="01GDMFVDRRF7ZYTXC3FXHHYJSX"
2022-09-23T06:17:41.842Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=Uploader}:{msg_id=2925 num_splits=1}: quickwit_indexing::actors::uploader: start-stage-and-store-splits split_ids=["01GDMFVDRRF7ZYTXC3FXHHYJSX"]
2022-09-23T06:17:41.842Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=Uploader}:{msg_id=2925 num_splits=1}: quickwit_indexing::actors::uploader: staging-split split_id="01GDMFVDRRF7ZYTXC3FXHHYJSX"
2022-09-23T06:17:41.871Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=Uploader}:{msg_id=2925 num_splits=1}: quickwit_indexing::actors::uploader: storing-split split_id="01GDMFVDRRF7ZYTXC3FXHHYJSX"
2022-09-23T06:17:41.871Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=Uploader}:{msg_id=2925 num_splits=1}: quickwit_indexing::split_store::indexing_split_store: store-split-remote-start
2022-09-23T06:17:41.872Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=Uploader}:{msg_id=2925 num_splits=1}: quickwit_indexing::split_store::indexing_split_store: store-split-remote-success split_size_in_megabytes=0 num_docs=1 elapsed_secs=0.001572812 throughput_mb_s=0 is_mature=false
2022-09-23T06:17:41.872Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=Uploader}:{msg_id=2925 num_splits=1}: quickwit_indexing::split_store::indexing_split_store: store-in-cache
2022-09-23T06:17:41.873Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=Uploader}:{msg_id=2925 num_splits=1}: quickwit_indexing::actors::uploader: success-stage-and-store-splits
2022-09-23T06:17:41.876Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=Publisher}:{msg_id=2924}: quickwit_metastore::checkpoint: delta=∆(00000000000000000000:(00000000000000008504..00000000000000008505]) checkpoint=Ckpt(00000000000000000000:00000000000000008504)
2022-09-23T06:17:41.884Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=Publisher}:{msg_id=2924}: quickwit_indexing::actors::publisher: publish-new-splits new_split="01GDMFVDRRF7ZYTXC3FXHHYJSX" tts=60.15959 checkpoint_delta=∆(00000000000000000000:(00000000000000008504..00000000000000008505])
2022-09-23T06:17:41.884Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=Publisher}:{msg_id=2924}: quickwit_metastore::checkpoint: delta=∆(00000000000000000000:(00000000000000008504..00000000000000008505]) checkpoint=Ckpt()
2022-09-23T06:17:46.426Z  INFO quickwit_serve::index_api::rest_handler: get-index index_id=traceback
2022-09-23T06:17:46.427Z  INFO quickwit_serve::index_api::rest_handler: get-index index_id=traceback
2022-09-23T06:17:53.457Z  INFO quickwit_serve::index_api::rest_handler: get-index index_id=traceback
2022-09-23T06:17:53.460Z  INFO quickwit_serve::index_api::rest_handler: get-index index_id=traceback
2022-09-23T06:18:12.830Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{index=traceback gen=0}:{actor=GarbageCollector}:{msg_id=15700}: quickwit_indexing::actors::garbage_collector: garbage-collect-operation
2022-09-23T06:18:20.985Z  INFO {actor=quickwit_indexing::actors::indexing_service::IndexingService}:{msg_id=1}::{msg_id=17930}:{index=traceback gen=3}:{actor=GarbageCollector}:{msg_id=2500}: quickwit_indexing::actors::garbage_collector: garbage-collect-operation

split_id: 01GDMFPBPQMZ69Z5406WVT12HK in both,, but other is different.

db log

postgresql.log

@fulmicoton fulmicoton reopened this Sep 23, 2022
@fulmicoton
Copy link
Contributor

@yangjinming1062

This is normal behavior.
These splits were marked for deletion.

"split_state": "MarkedForDeletion",

More explanation:
You don't receive many documents. Quickwit triggers the emission of a split 30s or so, to make sure your documents are searchable.
The splits hence created contain 1~7 docs here.

After creating 10 such splits, Quickwit triggered a merge, and merged these 10 splits into one -> 01GDMFPBPQMZ69Z5406WVT12HK.

If you sum the number of docs, you should see they match.
When the merged split was published, the old splits were not deleted right away, but marked for deletion.

This grace period is just a way to make sure htat we do not disrupt ongoing search queries.
A little bit later the split effectively get deleted.

@yangjinming1062
Copy link
Author

his grace period is just a way to make sure htat we do not disrupt ongoing search queries

Thank you, now I know the merge mechanism. But why did the number of documents change from 76 to 39? Maybe that means I can't find those 37 documents anymore?

@fulmicoton
Copy link
Contributor

@yangjinming1062

But why did the number of documents change from 76 to 39?

Excellent point. Let me investigate

@fulmicoton
Copy link
Contributor

fulmicoton commented Sep 23, 2022

It is a bug in the UI. The UI is simply summing num docs over all splits. I'm opening an issue.

@yangjinming1062
Copy link
Author

It is a bug in the UI. The UI is simply summing num docs over all splits. I'm opening an issue.

image
image
Sorry, I don't think it's just a UI bug, when I use the API, the result is the same as using the UI. Maybe the essence of the merge mechanism ?

@fulmicoton
Copy link
Contributor

fulmicoton commented Sep 23, 2022

@yangjinming1062 search is meant to ignore splits marked as deleted. If it does not do that it is a bug.

Are you sure you just did not simply index more document since last time?

Just to be sure can you send the current list of splits (I see you have 7 splits right now).
On a "*" query, without time filtering, the number that you see in num_hits should be the same number as if you summed the number of docs in the splits that are "Published".

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
bug Something isn't working
Projects
None yet
Development

No branches or pull requests

3 participants