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.

Sign up for GitHub

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

Jump to bottom

[Bug]: Data recovery fails #435

Open
suyestyle opened this issue Sep 26, 2024 · 12 comments
Open

[Bug]: Data recovery fails #435

suyestyle opened this issue Sep 26, 2024 · 12 comments

Comments

@suyestyle
Copy link

suyestyle commented Sep 26, 2024 •
edited
Loading

Current Behavior

I successfully backed up the data using the milvus-backup, but a message was displayed indicating that I could not find the backup file during data restoration

[root@ backup]#  ./milvus-backup create -n firstbackup4
0.4.24 (Built on 2024-09-20T02:08:15Z from Git SHA 290692046dd95b1140d3c0d376cf7a5e7ade7971)
config:backup.yaml
[2024/09/26 14:12:57.859 +08:00] [INFO] [logutil/logutil.go:165] ["Log directory"] [configDir=]
[2024/09/26 14:12:57.859 +08:00] [INFO] [logutil/logutil.go:166] ["Set log file to "] [path=logs/backup.log]
[2024/09/26 14:12:57.859 +08:00] [INFO] [core/backup_impl_create_backup.go:31] ["receive CreateBackupRequest"] [requestId=6029acd4-7bce-11ef-abca-525400ae5c5f] [backupName=firstbackup4] [collections="[]"] [databaseCollections=] [async=false] [force=false] [metaOnly=false]
[2024/09/26 14:12:57.859 +08:00] [INFO] [core/backup_context.go:117] ["{Base:0xc0003e8580 MaxSegmentGroupSize:2147483648 BackupCollectionParallelism:4 BackupCopyDataParallelism:128 RestoreParallelism:2 KeepTempFiles:false GcPauseEnable:true GcPauseSeconds:7200 GcPauseAddress:http://192.168.24.124:55002}"]
[2024/09/26 14:12:57.859 +08:00] [INFO] [core/backup_context.go:118] ["{Base:0xc0003e8580 Enabled:true DebugMode:false SimpleResponse:true}"]
[2024/09/26 14:12:57.966 +08:00] [INFO] [storage/minio_chunk_manager.go:144] ["minio chunk manager init success."] [bucketname=milvus-1255305554] [root=backup]
[2024/09/26 14:12:58.001 +08:00] [INFO] [core/backup_impl_create_backup.go:628] ["Pause Milvus GC response"] [response="404 page not found"] [address=http://192.168.24.124:55002] [pauseSeconds=7200]
[2024/09/26 14:12:58.003 +08:00] [INFO] [core/backup_impl_create_backup.go:684] ["collections to backup"] [collections="[default.quick_setup]"]
[2024/09/26 14:12:58.003 +08:00] [INFO] [core/backup_impl_create_backup.go:230] ["start backup collection"] [db=default] [collection=quick_setup]
[2024/09/26 14:12:58.006 +08:00] [INFO] [core/backup_impl_create_backup.go:263] ["try to get index"] [collection_name=quick_setup]
[2024/09/26 14:12:58.010 +08:00] [INFO] [core/backup_impl_create_backup.go:283] ["field index"] [collection_name=quick_setup] [field_name=id] ["index info"="[]"]
[2024/09/26 14:12:58.013 +08:00] [INFO] [core/backup_impl_create_backup.go:283] ["field index"] [collection_name=quick_setup] [field_name=vector] ["index info"="[{}]"]
[2024/09/26 14:12:58.019 +08:00] [INFO] [core/backup_impl_create_backup.go:369] ["GetPersistentSegmentInfo before flush from milvus"] [databaseName=default] [collectionName=quick_setup] [segmentNumBeforeFlush=0]
[2024/09/26 14:12:58.026 +08:00] [INFO] [core/backup_impl_create_backup.go:397] ["flush segments"] [databaseName=default] [collectionName=quick_setup] [newSealedSegmentIDs="[]"] [flushedSegmentIDs="[]"] [timeOfSeal=1727331177] [BackupTimestamp=0] [channelCPs={}]
[2024/09/26 14:12:58.028 +08:00] [INFO] [core/backup_impl_create_backup.go:419] ["GetPersistentSegmentInfo after flush from milvus"] [databaseName=default] [collectionName=quick_setup] [segmentIDsBeforeFlush="[]"] [segmentIDsAfterFlush="[]"] [newL0SegmentsIDs="[]"]
[2024/09/26 14:12:58.028 +08:00] [INFO] [core/backup_impl_create_backup.go:514] ["finish build partition info"] [collectionName=quick_setup] [partitionNum=1] [l0SegmentsNum=0]
[2024/09/26 14:12:58.028 +08:00] [INFO] [core/backup_impl_create_backup.go:703] ["Finish prepare all collections meta"]
[2024/09/26 14:12:58.028 +08:00] [INFO] [core/backup_impl_create_backup.go:708] ["before backupCollectionExecute"] [collectionID=450591213173465433] [collection=quick_setup]
[2024/09/26 14:12:58.029 +08:00] [INFO] [core/backup_impl_create_backup.go:529] [backupCollectionExecute] [collectionMeta="id:\"6029acd4-7bce-11ef-abca-525400ae5c5f\" start_time:1727331178 collection_id:450591213173465433 db_name:\"default\" collection_name:\"quick_setup\" schema:<name:\"quick_setup\" fields:<fieldID:100 name:\"id\" is_primary_key:true data_type:Int64 > fields:<fieldID:101 name:\"vector\" data_type:FloatVector type_params:<key:\"dim\" value:\"5\" > > enable_dynamic_field:true > shards_num:1 consistency_level:Bounded has_index:true index_infos:<field_name:\"vector\" index_name:\"vector\" index_type:\"AUTOINDEX\" params:<key:\"index_type\" value:\"AUTOINDEX\" > params:<key:\"metric_type\" value:\"COSINE\" > > load_state:\"NotLoad\" backup_physical_timestamp:1727331177 "]
[2024/09/26 14:12:58.029 +08:00] [INFO] [core/backup_impl_create_backup.go:564] ["Begin copy data"] [dbName=default] [collectionName=quick_setup] [collectionID=450591213173465433] [partitionID=450591213173465434]
[2024/09/26 14:12:58.029 +08:00] [INFO] [core/backup_impl_create_backup.go:604] ["Finish copy data"] [dbName=default] [collectionName=quick_setup]
[2024/09/26 14:12:58.029 +08:00] [INFO] [core/backup_meta_manager.go:501] ["Get backup"] [state=BACKUP_SUCCESS] [backupedSize=0] [totalSize=0] [progress=100]
[2024/09/26 14:12:58.029 +08:00] [INFO] [core/backup_impl_create_backup.go:754] ["Final backupInfo"] [backupInfo="id:\"6029acd4-7bce-11ef-abca-525400ae5c5f\" state_code:BACKUP_SUCCESS start_time:1727331178000 end_time:1727331178029 progress:100 name:\"firstbackup4\" collection_backups:<id:\"6029acd4-7bce-11ef-abca-525400ae5c5f\" start_time:1727331178 end_time:1727331178 collection_id:450591213173465433 db_name:\"default\" collection_name:\"quick_setup\" schema:<name:\"quick_setup\" fields:<fieldID:100 name:\"id\" is_primary_key:true data_type:Int64 > fields:<fieldID:101 name:\"vector\" data_type:FloatVector type_params:<key:\"dim\" value:\"5\" > > enable_dynamic_field:true > shards_num:1 consistency_level:Bounded partition_backups:<partition_id:450591213173465434 partition_name:\"_default\" collection_id:450591213173465433 load_state:\"NotLoad\" > has_index:true index_infos:<field_name:\"vector\" index_name:\"vector\" index_type:\"AUTOINDEX\" params:<key:\"index_type\" value:\"AUTOINDEX\" > params:<key:\"metric_type\" value:\"COSINE\" > > load_state:\"NotLoad\" backup_physical_timestamp:1727331177 > milvus_version:\"v2.3.4\" "]
[2024/09/26 14:12:58.029 +08:00] [INFO] [core/backup_impl_create_backup.go:757] ["collection meta"] [value="{\"infos\":[{\"id\":\"6029acd4-7bce-11ef-abca-525400ae5c5f\",\"state_code\":0,\"start_time\":1727331178,\"end_time\":1727331178,\"progress\":0,\"collection_id\":450591213173465433,\"db_name\":\"default\",\"collection_name\":\"quick_setup\",\"schema\":{\"name\":\"quick_setup\",\"fields\":[{\"fieldID\":100,\"name\":\"id\",\"is_primary_key\":true,\"data_type\":5},{\"fieldID\":101,\"name\":\"vector\",\"data_type\":101,\"type_params\":[{\"key\":\"dim\",\"value\":\"5\"}]}],\"enable_dynamic_field\":true},\"shards_num\":1,\"consistency_level\":2,\"partition_backups\":[{\"partition_id\":450591213173465434,\"partition_name\":\"_default\",\"collection_id\":450591213173465433,\"size\":0,\"load_state\":\"NotLoad\"}],\"size\":0,\"has_index\":true,\"index_infos\":[{\"field_name\":\"vector\",\"index_name\":\"vector\",\"index_type\":\"AUTOINDEX\",\"params\":{\"index_type\":\"AUTOINDEX\",\"metric_type\":\"COSINE\"}}],\"load_state\":\"NotLoad\",\"backup_physical_timestamp\":1727331177}]}"]
[2024/09/26 14:12:58.237 +08:00] [INFO] [core/backup_impl_create_backup.go:787] ["finish writeBackupInfoMeta"] [path=backup/firstbackup4/] [backupName=firstbackup4] ["backup meta"="{\"id\":\"6029acd4-7bce-11ef-abca-525400ae5c5f\",\"state_code\":2,\"start_time\":1727331178000,\"end_time\":1727331178029,\"progress\":100,\"name\":\"firstbackup4\",\"size\":0,\"milvus_version\":\"v2.3.4\"}"]
[2024/09/26 14:12:58.237 +08:00] [INFO] [core/backup_impl_create_backup.go:744] ["finish backup all collections"] [requestId=6029acd4-7bce-11ef-abca-525400ae5c5f] [backupName=firstbackup4] [collections="[]"] [async=false]
[2024/09/26 14:12:58.237 +08:00] [INFO] [core/backup_impl_create_backup.go:645] ["Resume Milvus GC response"] [response="404 page not found"] [address=http://192.168.24.124:55002]
success
duration:1 s
[root@ backup]#  ./milvus-backup restore --restore_index -n firstbackup4 -s _recover
0.4.24 (Built on 2024-09-20T02:08:15Z from Git SHA 290692046dd95b1140d3c0d376cf7a5e7ade7971)
[2024/09/26 14:13:03.500 +08:00] [INFO] [logutil/logutil.go:165] ["Log directory"] [configDir=]
[2024/09/26 14:13:03.500 +08:00] [INFO] [logutil/logutil.go:166] ["Set log file to "] [path=logs/backup.log]
[2024/09/26 14:13:03.501 +08:00] [INFO] [cmd/restore.go:47] ["restore cmd input args"] [args="[]"]
[2024/09/26 14:13:03.501 +08:00] [INFO] [core/backup_impl_restore_backup.go:29] ["receive RestoreBackupRequest"] [requestId=63868159-7bce-11ef-88c6-525400ae5c5f] [backupName=firstbackup4] [onlyMeta=false] [restoreIndex=true] [useAutoIndex=false] [dropExistCollection=false] [dropExistIndex=false] [skipCreateCollection=false] [collections="[]"] [CollectionSuffix=_recover] [CollectionRenames={}] [async=false] [bucketName=] [path=] [databaseCollections=] [skipDiskQuotaCheck=false] [maxShardNum=0]
[2024/09/26 14:13:03.501 +08:00] [INFO] [core/backup_context.go:117] ["{Base:0xc0003eab00 MaxSegmentGroupSize:2147483648 BackupCollectionParallelism:4 BackupCopyDataParallelism:128 RestoreParallelism:2 KeepTempFiles:false GcPauseEnable:true GcPauseSeconds:7200 GcPauseAddress:http://192.168.24.124:55002}"]
[2024/09/26 14:13:03.501 +08:00] [INFO] [core/backup_context.go:118] ["{Base:0xc0003eab00 Enabled:true DebugMode:false SimpleResponse:true}"]
[2024/09/26 14:13:03.501 +08:00] [INFO] [core/backup_context.go:308] ["receive GetBackupRequest"] [requestId=63868a33-7bce-11ef-88c6-525400ae5c5f] [backupName=firstbackup4] [backupId=] [bucketName=] [path=]
[2024/09/26 14:13:03.631 +08:00] [INFO] [storage/minio_chunk_manager.go:144] ["minio chunk manager init success."] [bucketname=milvus-1255305554] [root=backup]
[2024/09/26 14:13:03.685 +08:00] [WARN] [core/backup_context.go:581] ["read backup meta file not exist"] [path=backup/firstbackup4/meta/backup_meta.json] []
[2024/09/26 14:13:03.685 +08:00] [INFO] [core/backup_context.go:385] ["finish GetBackupRequest"] [requestId=63868a33-7bce-11ef-88c6-525400ae5c5f] [backupName=firstbackup4] [backupId=] [bucketName=] [path=]
[2024/09/26 14:13:03.685 +08:00] [ERROR] [core/backup_impl_restore_backup.go:89] ["fail to get backup"] [backupName=firstbackup4] [msg="not found"] [stack="github.com/zilliztech/milvus-backup/core.(*BackupContext).RestoreBackup\n\t/home/runner/work/milvus-backup/milvus-backup/core/backup_impl_restore_backup.go:89\ngithub.com/zilliztech/milvus-backup/cmd.glob..func7\n\t/home/runner/work/milvus-backup/milvus-backup/cmd/restore.go:84\ngithub.com/spf13/cobra.(*Command).execute\n\t/home/runner/go/pkg/mod/github.com/spf13/[email protected]/command.go:876\ngithub.com/spf13/cobra.(*Command).ExecuteC\n\t/home/runner/go/pkg/mod/github.com/spf13/[email protected]/command.go:990\ngithub.com/spf13/cobra.(*Command).Execute\n\t/home/runner/go/pkg/mod/github.com/spf13/[email protected]/command.go:918\ngithub.com/zilliztech/milvus-backup/cmd.Execute\n\t/home/runner/work/milvus-backup/milvus-backup/cmd/root.go:35\nmain.main\n\t/home/runner/work/milvus-backup/milvus-backup/main.go:24\nruntime.main\n\t/opt/hostedtoolcache/go/1.18.10/x64/src/runtime/proc.go:250"]
not found
duration:0 s
[root@ backup]# ./milvus-backup list
0.4.24 (Built on 2024-09-20T02:08:15Z from Git SHA 290692046dd95b1140d3c0d376cf7a5e7ade7971)
config:backup.yaml
[2024/09/26 14:26:41.003 +08:00] [INFO] [logutil/logutil.go:165] ["Log directory"] [configDir=]
[2024/09/26 14:26:41.003 +08:00] [INFO] [logutil/logutil.go:166] ["Set log file to "] [path=logs/backup.log]
[2024/09/26 14:26:41.004 +08:00] [INFO] [core/backup_context.go:400] ["receive ListBackupsRequest"] [requestId=4acb8630-7bd0-11ef-8ffb-525400ae5c5f] [collectionName=]
[2024/09/26 14:26:41.004 +08:00] [INFO] [core/backup_context.go:117] ["{Base:0xc000612580 MaxSegmentGroupSize:2147483648 BackupCollectionParallelism:4 BackupCopyDataParallelism:128 RestoreParallelism:2 KeepTempFiles:false GcPauseEnable:false GcPauseSeconds:7200 GcPauseAddress:http://192.168.24.124:55002}"]
[2024/09/26 14:26:41.004 +08:00] [INFO] [core/backup_context.go:118] ["{Base:0xc000612580 Enabled:true DebugMode:false SimpleResponse:true}"]
[2024/09/26 14:26:41.084 +08:00] [INFO] [storage/minio_chunk_manager.go:144] ["minio chunk manager init success."] [bucketname=milvus-1255305554] [root=backup]
[2024/09/26 14:26:41.106 +08:00] [INFO] [core/backup_context.go:426] ["List Backups' path"] [backup_paths="[]"]
[2024/09/26 14:26:41.106 +08:00] [INFO] [core/backup_context.go:465] ["return ListBackupsResponse"] [requestId=4acb8630-7bd0-11ef-8ffb-525400ae5c5f] [code=0] [msg=success] ["data: list_backup_names"="[]"]
>> Backups:

Expected Behavior

No response

Steps To Reproduce

No response

Environment

cat configs/backup.yaml

# Configures the system log output.
log:
  level: info # Only supports debug, info, warn, error, panic, or fatal. Default 'info'.
  console: true # whether print log to console
  file:
    rootPath: "logs/backup.log"

http:
  simpleResponse: true

# milvus proxy address, compatible to milvus.yaml
milvus:
  address: 192.168.1.100
  port: 52002
  authorizationEnabled: false
  # tls mode values [0, 1, 2]
  # 0 is close, 1 is one-way authentication, 2 is two-way authentication.
  tlsMode: 0
  user: "root"
  password: "Milvus"

# Related configuration of minio, which is responsible for data persistence for Milvus.
minio:
  # Milvus storage configs, make them the same with milvus config
  storageType: "s3" # support storage type: local, minio, s3, aws, gcp, ali(aliyun), azure, tc(tencent)
  address: "xxxx" # Address of MinIO/S3
  port: 443   # Port of MinIO/S3
  accessKeyID: "xxxx"  # accessKeyID of MinIO/S3
  secretAccessKey: "xxxx"  # MinIO/S3 encryption string
  useSSL: true # Access to MinIO/S3 with SSL
  useIAM: false
  iamEndpoint: ""
  bucketName: "xxxx" # Milvus Bucket name in MinIO/S3, make it the same as your milvus instance
  rootPath: "files" # Milvus storage root path in MinIO/S3, make it the same as your milvus instance

  # Backup storage configs, the storage you want to put the backup data
  backupStorageType: "s3" # support storage type: local, minio, s3, aws, gcp, ali(aliyun), azure, tc(tencent)
  backupAddress: "xxxx" # Address of MinIO/S3
  backupPort: 443   # Port of MinIO/S3
  backupAccessKeyID: "xxxx"  # accessKeyID of MinIO/S3
  backupSecretAccessKey: "xxxx" # MinIO/S3 encryption string
  backupBucketName: "xxxx" # Bucket name to store backup data. Backup data will store to backupBucketName/backupRootPath
  backupRootPath: "backup" # Rootpath to store backup data. Backup data will store to backupBucketName/backupRootPath

  # If you need to back up or restore data between two different storage systems, direct client-side copying is not supported. 
  # Set this option to true to enable data transfer through Milvus Backup.
  # Note: This option will be automatically set to true if `minio.storageType` and `minio.backupStorageType` differ.
  # However, if they are the same but belong to different services, you must manually set this option to `true`.
  crossStorage: "false"
  
backup:
  maxSegmentGroupSize: 2G

  parallelism: 
    # collection level parallelism to backup
    backupCollection: 4
    # thread pool to copy data. reduce it if blocks your storage's network bandwidth
    copydata: 128
    # Collection level parallelism to restore
    restoreCollection: 2
  
  # keep temporary files during restore, only use to debug 
  keepTempFiles: false
  
  # Pause GC during backup through Milvus Http API. 
  gcPause:
    enable: false
    seconds: 7200
    address: http://192.168.1.100:55002

Anything else?

No response
@suyestyle suyestyle changed the title [Bug]: [Bug]: Data recovery fails Sep 26, 2024
@wayblink
Copy link
Contributor

--restore_index -n

Please run ./milvus-backup check and see the output.

Have you check whether the path 'backup/firstbackup4/meta/backup_meta.json' exists?

What is your S3 provider? I guess it may related to the path assembly. Try add / before or after backup

@suyestyle
Copy link
Author 

[root backup]# ./milvus-backup check
0.4.24 (Built on 2024-09-20T02:08:15Z from Git SHA 290692046dd95b1140d3c0d376cf7a5e7ade7971)
config:backup.yaml
[2024/09/27 09:42:34.550 +08:00] [INFO] [logutil/logutil.go:165] ["Log directory"] [configDir=]
[2024/09/27 09:42:34.550 +08:00] [INFO] [logutil/logutil.go:166] ["Set log file to "] [path=logs/backup.log]
[2024/09/27 09:42:34.803 +08:00] [INFO] [storage/minio_chunk_manager.go:144] ["minio chunk manager init success."] [bucketname=milvus-1255305554] [root=files]
Milvus storage is empty. Please verify whether your cluster is really empty. If not, the configs(minio address, port, bucket, rootPath) may be wrong
Milvus version: v2.3.4
Storage:
milvus-bucket: milvus-126565866
milvus-rootpath: files
backup-bucket: milvus-126565866
backup-rootpath: backup

@suyestyle
Copy link
Author

suyestyle commented Sep 27, 2024 •
edited
Loading

Is there something wrong with my profile? Now the backup can be successful, but when checking, the message is empty, and the backup file cannot be found when restoring.

@wayblink
Copy link
Contributor 

[root backup]# ./milvus-backup check
0.4.24 (Built on 2024-09-20T02:08:15Z from Git SHA 290692046dd95b1140d3c0d376cf7a5e7ade7971)
config:backup.yaml
[2024/09/27 09:42:34.550 +08:00] [INFO] [logutil/logutil.go:165] ["Log directory"] [configDir=]
[2024/09/27 09:42:34.550 +08:00] [INFO] [logutil/logutil.go:166] ["Set log file to "] [path=logs/backup.log]
[2024/09/27 09:42:34.803 +08:00] [INFO] [storage/minio_chunk_manager.go:144] ["minio chunk manager init success."] [bucketname=milvus-1255305554] [root=files]
Milvus storage is empty. Please verify whether your cluster is really empty. If not, the configs(minio address, port, bucket, rootPath) may be wrong
Milvus version: v2.3.4
Storage:
milvus-bucket: milvus-126565866
milvus-rootpath: files
backup-bucket: milvus-126565866
backup-rootpath: backup

Milvus storage is empty. Please verify whether your cluster is really empty. If not, the configs(minio address, port, bucket, rootPath) may be wrong

You are checking the source milvus or the target cluster? If is the source.
This message indicate your config might be wrong. Please config the minio part the same as your milvus cluster.

If is the target, please run ./milvus-backup list and ./milvus-backup get -n {your_backup_name} to see if it exists.

@suyestyle
Copy link
Author

I use the same configuration file when performing backup and restore.

The backup can be successful, but the recovery cannot be successful.

See above for my profile

@suyestyle
Copy link
Author

A problem has been found. The backup execution prompt was successful, but it was not seen on the object store（cos from Tencent）. Only a meta directory was found

@wayblink
Copy link
Contributor

@suyestyle mentioned above. The config may be wrong. No binlog data is backuped actually. Please config the minio part the same as your milvus cluster.

@suyestyle
Copy link
Author

suyestyle commented Oct 15, 2024 •
edited
Loading

I modified the configuration according to the actual situation of my environment by referring to the official configuration. If there are errors in the configuration file, please help me correct them.

I use object storage (Tencent's cos) instead of minio.

Thank you very much.

@suyestyle
Copy link
Author

Now the backup is reporting an error.

0.4.24 (Built on 2024-09-20T02:08:15Z from Git SHA 290692046dd95b1140d3c0d376cf7a5e7ade7971)
config:backup.yaml
[2024/10/15 16:34:21.328 +08:00] [INFO] [logutil/logutil.go:165] ["Log directory"] [configDir=]
[2024/10/15 16:34:21.328 +08:00] [INFO] [logutil/logutil.go:166] ["Set log file to "] [path=logs/backup.log]
[2024/10/15 16:34:21.329 +08:00] [INFO] [core/backup_impl_create_backup.go:31] ["receive CreateBackupRequest"] [requestId=468d9812-8ad0-11ef-88cf-525400ae5c5f] [backupName=backup20241015] [collections="[]"] [databaseCollections=] [async=false] [force=false] [metaOnly=false]
[2024/10/15 16:34:21.329 +08:00] [INFO] [core/backup_context.go:117] ["{Base:0xc0003e8580 MaxSegmentGroupSize:2147483648 BackupCollectionParallelism:4 BackupCopyDataParallelism:128 RestoreParallelism:2 KeepTempFiles:false GcPauseEnable:false GcPauseSeconds:7200 GcPauseAddress:http://192.168.24.124:55002}"]
[2024/10/15 16:34:21.329 +08:00] [INFO] [core/backup_context.go:118] ["{Base:0xc0003e8580 Enabled:true DebugMode:false SimpleResponse:true}"]
[2024/10/15 16:34:21.329 +08:00] [DEBUG] [core/backup_context.go:192] ["create backup storage client"] [address=milvus-1255305554.cos.ap-beijing.myqcloud.com:443] [bucket=milvus-1255305554] [backupBucket=milvus-1255305554]
[2024/10/15 16:34:21.439 +08:00] [INFO] [storage/minio_chunk_manager.go:144] ["minio chunk manager init success."] [bucketname=milvus-1255305554] [root=backup]
[2024/10/15 16:34:21.460 +08:00] [DEBUG] [core/backup_context.go:68] ["Start Milvus client"] [endpoint=192.168.24.124:52002]
[2024/10/15 16:34:21.463 +08:00] [DEBUG] [core/backup_impl_create_backup.go:136] ["Request collection names"] [request_collection_names="[]"] [request_db_collections=] [length=0]
[2024/10/15 16:34:21.467 +08:00] [DEBUG] [core/backup_impl_create_backup.go:202] ["List 1 collections"]
[2024/10/15 16:34:21.467 +08:00] [INFO] [core/backup_impl_create_backup.go:684] ["collections to backup"] [collections="[default.ssss]"]
[2024/10/15 16:34:21.468 +08:00] [INFO] [core/backup_impl_create_backup.go:230] ["start backup collection"] [db=default] [collection=ssss]
[2024/10/15 16:34:21.470 +08:00] [INFO] [core/backup_impl_create_backup.go:263] ["try to get index"] [collection_name=ssss]
[2024/10/15 16:34:21.473 +08:00] [INFO] [core/backup_impl_create_backup.go:283] ["field index"] [collection_name=ssss] [field_name=id] ["index info"="[]"]
[2024/10/15 16:34:21.476 +08:00] [INFO] [core/backup_impl_create_backup.go:283] ["field index"] [collection_name=ssss] [field_name=name] ["index info"="[{}]"]
[2024/10/15 16:34:21.482 +08:00] [INFO] [core/backup_impl_create_backup.go:369] ["GetPersistentSegmentInfo before flush from milvus"] [databaseName=default] [collectionName=ssss] [segmentNumBeforeFlush=1]
[2024/10/15 16:37:12.568 +08:00] [ERROR] [core/backup_impl_create_backup.go:375] ["fail to flush the collection"] [databaseName=default] [collectionName=ssss] [error="attempt #59: channel not found[channel=by-dev-rootcoord-dml_0_453241817591190296v0]"] [errorVerbose="attempt #59: channel not found[channel=by-dev-rootcoord-dml_0_453241817591190296v0]\n(1) attached stack trace\n  -- stack trace:\n  | github.com/milvus-io/milvus-sdk-go/v2/client.handleRespStatus\n  | \t/home/runner/go/pkg/mod/github.com/milvus-io/milvus-sdk-go/[email protected]/client/collection.go:39\n  | github.com/milvus-io/milvus-sdk-go/v2/client.(*GrpcClient).FlushV2\n  | \t/home/runner/go/pkg/mod/github.com/milvus-io/milvus-sdk-go/[email protected]/client/insert.go:240\n  | github.com/zilliztech/milvus-backup/core.(*MilvusClient).FlushV2\n  | \t/home/runner/work/milvus-backup/milvus-backup/core/milvus_sdk_wrapper.go:94\n  | github.com/zilliztech/milvus-backup/core.(*BackupContext).backupCollectionPrepare\n  | \t/home/runner/work/milvus-backup/milvus-backup/core/backup_impl_create_backup.go:373\n  | github.com/zilliztech/milvus-backup/core.(*BackupContext).executeCreateBackup.func1.1\n  | \t/home/runner/work/milvus-backup/milvus-backup/core/backup_impl_create_backup.go:691\n  | github.com/zilliztech/milvus-backup/internal/util/retry.Do\n  | \t/home/runner/work/milvus-backup/milvus-backup/internal/util/retry/retry.go:37\n  | github.com/zilliztech/milvus-backup/core.(*BackupContext).executeCreateBackup.func1\n  | \t/home/runner/work/milvus-backup/milvus-backup/core/backup_impl_create_backup.go:690\n  | github.com/zilliztech/milvus-backup/internal/common.(*WorkerPool).work.func1\n  | \t/home/runner/work/milvus-backup/milvus-backup/internal/common/workerpool.go:70\n  | golang.org/x/sync/errgroup.(*Group).Go.func1\n  | \t/home/runner/go/pkg/mod/golang.org/x/[email protected]/errgroup/errgroup.go:75\n  | runtime.goexit\n  | \t/opt/hostedtoolcache/go/1.18.10/x64/src/runtime/asm_amd64.s:1571\nWraps: (2) attempt #59: channel not found[channel=by-dev-rootcoord-dml_0_453241817591190296v0]\nError types: (1) *withstack.withStack (2) *errutil.leafError"] [stack="github.com/zilliztech/milvus-backup/core.(*BackupContext).backupCollectionPrepare\n\t/home/runner/work/milvus-backup/milvus-backup/core/backup_impl_create_backup.go:375\ngithub.com/zilliztech/milvus-backup/core.(*BackupContext).executeCreateBackup.func1.1\n\t/home/runner/work/milvus-backup/milvus-backup/core/backup_impl_create_backup.go:691\ngithub.com/zilliztech/milvus-backup/internal/util/retry.Do\n\t/home/runner/work/milvus-backup/milvus-backup/internal/util/retry/retry.go:37\ngithub.com/zilliztech/milvus-backup/core.(*BackupContext).executeCreateBackup.func1\n\t/home/runner/work/milvus-backup/milvus-backup/core/backup_impl_create_backup.go:690\ngithub.com/zilliztech/milvus-backup/internal/common.(*WorkerPool).work.func1\n\t/home/runner/work/milvus-backup/milvus-backup/internal/common/workerpool.go:70\ngolang.org/x/sync/errgroup.(*Group).Go.func1\n\t/home/runner/go/pkg/mod/golang.org/x/[email protected]/errgroup/errgroup.go:75"]
[2024/10/15 16:37:12.568 +08:00] [DEBUG] [retry/retry.go:39] ["retry func failed"] ["retry time"=0] [error="attempt #59: channel not found[channel=by-dev-rootcoord-dml_0_453241817591190296v0]"] [errorVerbose="attempt #59: channel not found[channel=by-dev-rootcoord-dml_0_453241817591190296v0]\n(1) attached stack trace\n  -- stack trace:\n  | github.com/milvus-io/milvus-sdk-go/v2/client.handleRespStatus\n  | \t/home/runner/go/pkg/mod/github.com/milvus-io/milvus-sdk-go/[email protected]/client/collection.go:39\n  | github.com/milvus-io/milvus-sdk-go/v2/client.(*GrpcClient).FlushV2\n  | \t/home/runner/go/pkg/mod/github.com/milvus-io/milvus-sdk-go/[email protected]/client/insert.go:240\n  | github.com/zilliztech/milvus-backup/core.(*MilvusClient).FlushV2\n  | \t/home/runner/work/milvus-backup/milvus-backup/core/milvus_sdk_wrapper.go:94\n  | github.com/zilliztech/milvus-backup/core.(*BackupContext).backupCollectionPrepare\n  | \t/home/runner/work/milvus-backup/milvus-backup/core/backup_impl_create_backup.go:373\n  | github.com/zilliztech/milvus-backup/core.(*BackupContext).executeCreateBackup.func1.1\n  | \t/home/runner/work/milvus-backup/milvus-backup/core/backup_impl_create_backup.go:691\n  | github.com/zilliztech/milvus-backup/internal/util/retry.Do\n  | \t/home/runner/work/milvus-backup/milvus-backup/internal/util/retry/retry.go:37\n  | github.com/zilliztech/milvus-backup/core.(*BackupContext).executeCreateBackup.func1\n  | \t/home/runner/work/milvus-backup/milvus-backup/core/backup_impl_create_backup.go:690\n  | github.com/zilliztech/milvus-backup/internal/common.(*WorkerPool).work.func1\n  | \t/home/runner/work/milvus-backup/milvus-backup/internal/common/workerpool.go:70\n  | golang.org/x/sync/errgroup.(*Group).Go.func1\n  | \t/home/runner/go/pkg/mod/golang.org/x/[email protected]/errgroup/errgroup.go:75\n  | runtime.goexit\n  | \t/opt/hostedtoolcache/go/1.18.10/x64/src/runtime/asm_amd64.s:1571\nWraps: (2) attempt #59: channel not found[channel=by-dev-rootcoord-dml_0_453241817591190296v0]\nError types: (1) *withstack.withStack (2) *errutil.leafError"]
[2024/10/15 16:39:12.578 +08:00] [INFO] [core/backup_impl_create_backup.go:230] ["start backup collection"] [db=default] [collection=ssss]
[2024/10/15 16:39:12.580 +08:00] [INFO] [core/backup_impl_create_backup.go:263] ["try to get index"] [collection_name=ssss]
[2024/10/15 16:39:12.584 +08:00] [INFO] [core/backup_impl_create_backup.go:283] ["field index"] [collection_name=ssss] [field_name=id] ["index info"="[]"]
[2024/10/15 16:39:12.588 +08:00] [INFO] [core/backup_impl_create_backup.go:283] ["field index"] [collection_name=ssss] [field_name=name] ["index info"="[{}]"]
[2024/10/15 16:39:12.593 +08:00] [INFO] [core/backup_impl_create_backup.go:369] ["GetPersistentSegmentInfo before flush from milvus"] [databaseName=default] [collectionName=ssss] [segmentNumBeforeFlush=1]
[2024/10/15 16:42:03.678 +08:00] [ERROR] [core/backup_impl_create_backup.go:375] ["fail to flush the collection"] [databaseName=default] [collectionName=ssss] [error="attempt #59: channel not found[channel=by-dev-rootcoord-dml_0_453241817591190296v0]"] [errorVerbose="attempt #59: channel not found[channel=by-dev-rootcoord-dml_0_453241817591190296v0]\n(1) attached stack trace\n  -- stack trace:\n  | github.com/milvus-io/milvus-sdk-go/v2/client.handleRespStatus\n  | \t/home/runner/go/pkg/mod/github.com/milvus-io/milvus-sdk-go/[email protected]/client/collection.go:39\n  | github.com/milvus-io/milvus-sdk-go/v2/client.(*GrpcClient).FlushV2\n  | \t/home/runner/go/pkg/mod/github.com/milvus-io/milvus-sdk-go/[email protected]/client/insert.go:240\n  | github.com/zilliztech/milvus-backup/core.(*MilvusClient).FlushV2\n  | \t/home/runner/work/milvus-backup/milvus-backup/core/milvus_sdk_wrapper.go:94\n  | github.com/zilliztech/milvus-backup/core.(*BackupContext).backupCollectionPrepare\n  | \t/home/runner/work/milvus-backup/milvus-backup/core/backup_impl_create_backup.go:373\n  | github.com/zilliztech/milvus-backup/core.(*BackupContext).executeCreateBackup.func1.1\n  | \t/home/runner/work/milvus-backup/milvus-backup/core/backup_impl_create_backup.go:691\n  | github.com/zilliztech/milvus-backup/internal/util/retry.Do\n  | \t/home/runner/work/milvus-backup/milvus-backup/internal/util/retry/retry.go:37\n  | github.com/zilliztech/milvus-backup/core.(*BackupContext).executeCreateBackup.func1\n  | \t/home/runner/work/milvus-backup/milvus-backup/core/backup_impl_create_backup.go:690\n  | github.com/zilliztech/milvus-backup/internal/common.(*WorkerPool).work.func1\n  | \t/home/runner/work/milvus-backup/milvus-backup/internal/common/workerpool.go:70\n  | golang.org/x/sync/errgroup.(*Group).Go.func1\n  | \t/home/runner/go/pkg/mod/golang.org/x/[email protected]/errgroup/errgroup.go:75\n  | runtime.goexit\n  | \t/opt/hostedtoolcache/go/1.18.10/x64/src/runtime/asm_amd64.s:1571\nWraps: (2) attempt #59: channel not found[channel=by-dev-rootcoord-dml_0_453241817591190296v0]\nError types: (1) *withstack.withStack (2) *errutil.leafError"] [stack="github.com/zilliztech/milvus-backup/core.(*BackupContext).backupCollectionPrepare\n\t/home/runner/work/milvus-backup/milvus-backup/core/backup_impl_create_backup.go:375\ngithub.com/zilliztech/milvus-backup/core.(*BackupContext).executeCreateBackup.func1.1\n\t/home/runner/work/milvus-backup/milvus-backup/core/backup_impl_create_backup.go:691\ngithub.com/zilliztech/milvus-backup/internal/util/retry.Do\n\t/home/runner/work/milvus-backup/milvus-backup/internal/util/retry/retry.go:37\ngithub.com/zilliztech/milvus-backup/core.(*BackupContext).executeCreateBackup.func1\n\t/home/runner/work/milvus-backup/milvus-backup/core/backup_impl_create_backup.go:690\ngithub.com/zilliztech/milvus-backup/internal/common.(*WorkerPool).work.func1\n\t/home/runner/work/milvus-backup/milvus-backup/internal/common/workerpool.go:70\ngolang.org/x/sync/errgroup.(*Group).Go.func1\n\t/home/runner/go/pkg/mod/golang.org/x/[email protected]/errgroup/errgroup.go:75"]
[2024/10/15 16:46:03.686 +08:00] [INFO] [core/backup_impl_create_backup.go:230] ["start backup collection"] [db=default] [collection=ssss]
[2024/10/15 16:46:03.689 +08:00] [INFO] [core/backup_impl_create_backup.go:263] ["try to get index"] [collection_name=ssss]
[2024/10/15 16:46:03.692 +08:00] [INFO] [core/backup_impl_create_backup.go:283] ["field index"] [collection_name=ssss] [field_name=id] ["index info"="[]"]
[2024/10/15 16:46:03.695 +08:00] [INFO] [core/backup_impl_create_backup.go:283] ["field index"] [collection_name=ssss] [field_name=name] ["index info"="[{}]"]
[2024/10/15 16:46:03.701 +08:00] [INFO] [core/backup_impl_create_backup.go:369] ["GetPersistentSegmentInfo before flush from milvus"] [databaseName=default] [collectionName=ssss] [segmentNumBeforeFlush=1]

@wayblink
Copy link
Contributor

I modified the configuration according to the actual situation of my environment by referring to the official configuration. If there are errors in the configuration file, please help me correct them.

I use object storage (Tencent's cos) instead of minio.

Thank you very much.

I can't check the correctness by eye as it is all related to your env. Please make sure it is the same with the milvus config.

@wayblink
Copy link
Contributor

Now the backup is reporting an error.

0.4.24 (Built on 2024-09-20T02:08:15Z from Git SHA 290692046dd95b1140d3c0d376cf7a5e7ade7971)
config:backup.yaml
[2024/10/15 16:34:21.328 +08:00] [INFO] [logutil/logutil.go:165] ["Log directory"] [configDir=]
[2024/10/15 16:34:21.328 +08:00] [INFO] [logutil/logutil.go:166] ["Set log file to "] [path=logs/backup.log]
[2024/10/15 16:34:21.329 +08:00] [INFO] [core/backup_impl_create_backup.go:31] ["receive CreateBackupRequest"] [requestId=468d9812-8ad0-11ef-88cf-525400ae5c5f] [backupName=backup20241015] [collections="[]"] [databaseCollections=] [async=false] [force=false] [metaOnly=false]
[2024/10/15 16:34:21.329 +08:00] [INFO] [core/backup_context.go:117] ["{Base:0xc0003e8580 MaxSegmentGroupSize:2147483648 BackupCollectionParallelism:4 BackupCopyDataParallelism:128 RestoreParallelism:2 KeepTempFiles:false GcPauseEnable:false GcPauseSeconds:7200 GcPauseAddress:http://192.168.24.124:55002}"]
[2024/10/15 16:34:21.329 +08:00] [INFO] [core/backup_context.go:118] ["{Base:0xc0003e8580 Enabled:true DebugMode:false SimpleResponse:true}"]
[2024/10/15 16:34:21.329 +08:00] [DEBUG] [core/backup_context.go:192] ["create backup storage client"] [address=milvus-1255305554.cos.ap-beijing.myqcloud.com:443] [bucket=milvus-1255305554] [backupBucket=milvus-1255305554]
[2024/10/15 16:34:21.439 +08:00] [INFO] [storage/minio_chunk_manager.go:144] ["minio chunk manager init success."] [bucketname=milvus-1255305554] [root=backup]
[2024/10/15 16:34:21.460 +08:00] [DEBUG] [core/backup_context.go:68] ["Start Milvus client"] [endpoint=192.168.24.124:52002]
[2024/10/15 16:34:21.463 +08:00] [DEBUG] [core/backup_impl_create_backup.go:136] ["Request collection names"] [request_collection_names="[]"] [request_db_collections=] [length=0]
[2024/10/15 16:34:21.467 +08:00] [DEBUG] [core/backup_impl_create_backup.go:202] ["List 1 collections"]
[2024/10/15 16:34:21.467 +08:00] [INFO] [core/backup_impl_create_backup.go:684] ["collections to backup"] [collections="[default.ssss]"]
[2024/10/15 16:34:21.468 +08:00] [INFO] [core/backup_impl_create_backup.go:230] ["start backup collection"] [db=default] [collection=ssss]
[2024/10/15 16:34:21.470 +08:00] [INFO] [core/backup_impl_create_backup.go:263] ["try to get index"] [collection_name=ssss]
[2024/10/15 16:34:21.473 +08:00] [INFO] [core/backup_impl_create_backup.go:283] ["field index"] [collection_name=ssss] [field_name=id] ["index info"="[]"]
[2024/10/15 16:34:21.476 +08:00] [INFO] [core/backup_impl_create_backup.go:283] ["field index"] [collection_name=ssss] [field_name=name] ["index info"="[{}]"]
[2024/10/15 16:34:21.482 +08:00] [INFO] [core/backup_impl_create_backup.go:369] ["GetPersistentSegmentInfo before flush from milvus"] [databaseName=default] [collectionName=ssss] [segmentNumBeforeFlush=1]
[2024/10/15 16:37:12.568 +08:00] [ERROR] [core/backup_impl_create_backup.go:375] ["fail to flush the collection"] [databaseName=default] [collectionName=ssss] [error="attempt #59: channel not found[channel=by-dev-rootcoord-dml_0_453241817591190296v0]"] [errorVerbose="attempt #59: channel not found[channel=by-dev-rootcoord-dml_0_453241817591190296v0]\n(1) attached stack trace\n  -- stack trace:\n  | github.com/milvus-io/milvus-sdk-go/v2/client.handleRespStatus\n  | \t/home/runner/go/pkg/mod/github.com/milvus-io/milvus-sdk-go/[email protected]/client/collection.go:39\n  | github.com/milvus-io/milvus-sdk-go/v2/client.(*GrpcClient).FlushV2\n  | \t/home/runner/go/pkg/mod/github.com/milvus-io/milvus-sdk-go/[email protected]/client/insert.go:240\n  | github.com/zilliztech/milvus-backup/core.(*MilvusClient).FlushV2\n  | \t/home/runner/work/milvus-backup/milvus-backup/core/milvus_sdk_wrapper.go:94\n  | github.com/zilliztech/milvus-backup/core.(*BackupContext).backupCollectionPrepare\n  | \t/home/runner/work/milvus-backup/milvus-backup/core/backup_impl_create_backup.go:373\n  | github.com/zilliztech/milvus-backup/core.(*BackupContext).executeCreateBackup.func1.1\n  | \t/home/runner/work/milvus-backup/milvus-backup/core/backup_impl_create_backup.go:691\n  | github.com/zilliztech/milvus-backup/internal/util/retry.Do\n  | \t/home/runner/work/milvus-backup/milvus-backup/internal/util/retry/retry.go:37\n  | github.com/zilliztech/milvus-backup/core.(*BackupContext).executeCreateBackup.func1\n  | \t/home/runner/work/milvus-backup/milvus-backup/core/backup_impl_create_backup.go:690\n  | github.com/zilliztech/milvus-backup/internal/common.(*WorkerPool).work.func1\n  | \t/home/runner/work/milvus-backup/milvus-backup/internal/common/workerpool.go:70\n  | golang.org/x/sync/errgroup.(*Group).Go.func1\n  | \t/home/runner/go/pkg/mod/golang.org/x/[email protected]/errgroup/errgroup.go:75\n  | runtime.goexit\n  | \t/opt/hostedtoolcache/go/1.18.10/x64/src/runtime/asm_amd64.s:1571\nWraps: (2) attempt #59: channel not found[channel=by-dev-rootcoord-dml_0_453241817591190296v0]\nError types: (1) *withstack.withStack (2) *errutil.leafError"] [stack="github.com/zilliztech/milvus-backup/core.(*BackupContext).backupCollectionPrepare\n\t/home/runner/work/milvus-backup/milvus-backup/core/backup_impl_create_backup.go:375\ngithub.com/zilliztech/milvus-backup/core.(*BackupContext).executeCreateBackup.func1.1\n\t/home/runner/work/milvus-backup/milvus-backup/core/backup_impl_create_backup.go:691\ngithub.com/zilliztech/milvus-backup/internal/util/retry.Do\n\t/home/runner/work/milvus-backup/milvus-backup/internal/util/retry/retry.go:37\ngithub.com/zilliztech/milvus-backup/core.(*BackupContext).executeCreateBackup.func1\n\t/home/runner/work/milvus-backup/milvus-backup/core/backup_impl_create_backup.go:690\ngithub.com/zilliztech/milvus-backup/internal/common.(*WorkerPool).work.func1\n\t/home/runner/work/milvus-backup/milvus-backup/internal/common/workerpool.go:70\ngolang.org/x/sync/errgroup.(*Group).Go.func1\n\t/home/runner/go/pkg/mod/golang.org/x/[email protected]/errgroup/errgroup.go:75"]
[2024/10/15 16:37:12.568 +08:00] [DEBUG] [retry/retry.go:39] ["retry func failed"] ["retry time"=0] [error="attempt #59: channel not found[channel=by-dev-rootcoord-dml_0_453241817591190296v0]"] [errorVerbose="attempt #59: channel not found[channel=by-dev-rootcoord-dml_0_453241817591190296v0]\n(1) attached stack trace\n  -- stack trace:\n  | github.com/milvus-io/milvus-sdk-go/v2/client.handleRespStatus\n  | \t/home/runner/go/pkg/mod/github.com/milvus-io/milvus-sdk-go/[email protected]/client/collection.go:39\n  | github.com/milvus-io/milvus-sdk-go/v2/client.(*GrpcClient).FlushV2\n  | \t/home/runner/go/pkg/mod/github.com/milvus-io/milvus-sdk-go/[email protected]/client/insert.go:240\n  | github.com/zilliztech/milvus-backup/core.(*MilvusClient).FlushV2\n  | \t/home/runner/work/milvus-backup/milvus-backup/core/milvus_sdk_wrapper.go:94\n  | github.com/zilliztech/milvus-backup/core.(*BackupContext).backupCollectionPrepare\n  | \t/home/runner/work/milvus-backup/milvus-backup/core/backup_impl_create_backup.go:373\n  | github.com/zilliztech/milvus-backup/core.(*BackupContext).executeCreateBackup.func1.1\n  | \t/home/runner/work/milvus-backup/milvus-backup/core/backup_impl_create_backup.go:691\n  | github.com/zilliztech/milvus-backup/internal/util/retry.Do\n  | \t/home/runner/work/milvus-backup/milvus-backup/internal/util/retry/retry.go:37\n  | github.com/zilliztech/milvus-backup/core.(*BackupContext).executeCreateBackup.func1\n  | \t/home/runner/work/milvus-backup/milvus-backup/core/backup_impl_create_backup.go:690\n  | github.com/zilliztech/milvus-backup/internal/common.(*WorkerPool).work.func1\n  | \t/home/runner/work/milvus-backup/milvus-backup/internal/common/workerpool.go:70\n  | golang.org/x/sync/errgroup.(*Group).Go.func1\n  | \t/home/runner/go/pkg/mod/golang.org/x/[email protected]/errgroup/errgroup.go:75\n  | runtime.goexit\n  | \t/opt/hostedtoolcache/go/1.18.10/x64/src/runtime/asm_amd64.s:1571\nWraps: (2) attempt #59: channel not found[channel=by-dev-rootcoord-dml_0_453241817591190296v0]\nError types: (1) *withstack.withStack (2) *errutil.leafError"]
[2024/10/15 16:39:12.578 +08:00] [INFO] [core/backup_impl_create_backup.go:230] ["start backup collection"] [db=default] [collection=ssss]
[2024/10/15 16:39:12.580 +08:00] [INFO] [core/backup_impl_create_backup.go:263] ["try to get index"] [collection_name=ssss]
[2024/10/15 16:39:12.584 +08:00] [INFO] [core/backup_impl_create_backup.go:283] ["field index"] [collection_name=ssss] [field_name=id] ["index info"="[]"]
[2024/10/15 16:39:12.588 +08:00] [INFO] [core/backup_impl_create_backup.go:283] ["field index"] [collection_name=ssss] [field_name=name] ["index info"="[{}]"]
[2024/10/15 16:39:12.593 +08:00] [INFO] [core/backup_impl_create_backup.go:369] ["GetPersistentSegmentInfo before flush from milvus"] [databaseName=default] [collectionName=ssss] [segmentNumBeforeFlush=1]
[2024/10/15 16:42:03.678 +08:00] [ERROR] [core/backup_impl_create_backup.go:375] ["fail to flush the collection"] [databaseName=default] [collectionName=ssss] [error="attempt #59: channel not found[channel=by-dev-rootcoord-dml_0_453241817591190296v0]"] [errorVerbose="attempt #59: channel not found[channel=by-dev-rootcoord-dml_0_453241817591190296v0]\n(1) attached stack trace\n  -- stack trace:\n  | github.com/milvus-io/milvus-sdk-go/v2/client.handleRespStatus\n  | \t/home/runner/go/pkg/mod/github.com/milvus-io/milvus-sdk-go/[email protected]/client/collection.go:39\n  | github.com/milvus-io/milvus-sdk-go/v2/client.(*GrpcClient).FlushV2\n  | \t/home/runner/go/pkg/mod/github.com/milvus-io/milvus-sdk-go/[email protected]/client/insert.go:240\n  | github.com/zilliztech/milvus-backup/core.(*MilvusClient).FlushV2\n  | \t/home/runner/work/milvus-backup/milvus-backup/core/milvus_sdk_wrapper.go:94\n  | github.com/zilliztech/milvus-backup/core.(*BackupContext).backupCollectionPrepare\n  | \t/home/runner/work/milvus-backup/milvus-backup/core/backup_impl_create_backup.go:373\n  | github.com/zilliztech/milvus-backup/core.(*BackupContext).executeCreateBackup.func1.1\n  | \t/home/runner/work/milvus-backup/milvus-backup/core/backup_impl_create_backup.go:691\n  | github.com/zilliztech/milvus-backup/internal/util/retry.Do\n  | \t/home/runner/work/milvus-backup/milvus-backup/internal/util/retry/retry.go:37\n  | github.com/zilliztech/milvus-backup/core.(*BackupContext).executeCreateBackup.func1\n  | \t/home/runner/work/milvus-backup/milvus-backup/core/backup_impl_create_backup.go:690\n  | github.com/zilliztech/milvus-backup/internal/common.(*WorkerPool).work.func1\n  | \t/home/runner/work/milvus-backup/milvus-backup/internal/common/workerpool.go:70\n  | golang.org/x/sync/errgroup.(*Group).Go.func1\n  | \t/home/runner/go/pkg/mod/golang.org/x/[email protected]/errgroup/errgroup.go:75\n  | runtime.goexit\n  | \t/opt/hostedtoolcache/go/1.18.10/x64/src/runtime/asm_amd64.s:1571\nWraps: (2) attempt #59: channel not found[channel=by-dev-rootcoord-dml_0_453241817591190296v0]\nError types: (1) *withstack.withStack (2) *errutil.leafError"] [stack="github.com/zilliztech/milvus-backup/core.(*BackupContext).backupCollectionPrepare\n\t/home/runner/work/milvus-backup/milvus-backup/core/backup_impl_create_backup.go:375\ngithub.com/zilliztech/milvus-backup/core.(*BackupContext).executeCreateBackup.func1.1\n\t/home/runner/work/milvus-backup/milvus-backup/core/backup_impl_create_backup.go:691\ngithub.com/zilliztech/milvus-backup/internal/util/retry.Do\n\t/home/runner/work/milvus-backup/milvus-backup/internal/util/retry/retry.go:37\ngithub.com/zilliztech/milvus-backup/core.(*BackupContext).executeCreateBackup.func1\n\t/home/runner/work/milvus-backup/milvus-backup/core/backup_impl_create_backup.go:690\ngithub.com/zilliztech/milvus-backup/internal/common.(*WorkerPool).work.func1\n\t/home/runner/work/milvus-backup/milvus-backup/internal/common/workerpool.go:70\ngolang.org/x/sync/errgroup.(*Group).Go.func1\n\t/home/runner/go/pkg/mod/golang.org/x/[email protected]/errgroup/errgroup.go:75"]
[2024/10/15 16:46:03.686 +08:00] [INFO] [core/backup_impl_create_backup.go:230] ["start backup collection"] [db=default] [collection=ssss]
[2024/10/15 16:46:03.689 +08:00] [INFO] [core/backup_impl_create_backup.go:263] ["try to get index"] [collection_name=ssss]
[2024/10/15 16:46:03.692 +08:00] [INFO] [core/backup_impl_create_backup.go:283] ["field index"] [collection_name=ssss] [field_name=id] ["index info"="[]"]
[2024/10/15 16:46:03.695 +08:00] [INFO] [core/backup_impl_create_backup.go:283] ["field index"] [collection_name=ssss] [field_name=name] ["index info"="[{}]"]
[2024/10/15 16:46:03.701 +08:00] [INFO] [core/backup_impl_create_backup.go:369] ["GetPersistentSegmentInfo before flush from milvus"] [databaseName=default] [collectionName=ssss] [segmentNumBeforeFlush=1]

This is a milvus inner exception, you have to see the milvus log to find the issue.

@suyestyle
Copy link
Author

suyestyle commented Oct 15, 2024 •
edited
Loading

milvus log

[2024/10/15 12:14:08.026 +00:00] [ERROR] [retry/retry.go:46] ["retry func failed"] [traceID=e3a9fac6bffef57db76392840734a5ae] ["retry time"=0] [error="channel not found[channel=by-dev-rootcoord-dml_0_453241817591190296v0]"] [stack="github.com/milvus-io/milvus/pkg/util/retry.Do\n\t/go/src/github.com/milvus-io/milvus/pkg/util/retry/retry.go:46\ngithub.com/milvus-io/milvus/internal/datacoord.(*Server).Flush\n\t/go/src/github.com/milvus-io/milvus/internal/datacoord/services.go:116\ngithub.com/milvus-io/milvus/internal/distributed/datacoord.(*Server).Flush\n\t/go/src/github.com/milvus-io/milvus/internal/distributed/datacoord/service.go:283\ngithub.com/milvus-io/milvus/internal/proto/datapb._DataCoord_Flush_Handler.func1\n\t/go/src/github.com/milvus-io/milvus/internal/proto/datapb/data_coord.pb.go:6536\ngithub.com/milvus-io/milvus/pkg/util/interceptor.ServerIDValidationUnaryServerInterceptor.func1\n\t/go/src/github.com/milvus-io/milvus/pkg/util/interceptor/server_id_interceptor.go:54\ngithub.com/grpc-ecosystem/go-grpc-middleware.ChainUnaryServer.func1.1.1\n\t/go/pkg/mod/github.com/grpc-ecosystem/[email protected]/chain.go:25\ngithub.com/milvus-io/milvus/pkg/util/interceptor.ClusterValidationUnaryServerInterceptor.func1\n\t/go/src/github.com/milvus-io/milvus/pkg/util/interceptor/cluster_interceptor.go:48\ngithub.com/grpc-ecosystem/go-grpc-middleware.ChainUnaryServer.func1.1.1\n\t/go/pkg/mod/github.com/grpc-ecosystem/[email protected]/chain.go:25\ngithub.com/milvus-io/milvus/pkg/util/logutil.UnaryTraceLoggerInterceptor\n\t/go/src/github.com/milvus-io/milvus/pkg/util/logutil/grpc_interceptor.go:23\ngithub.com/grpc-ecosystem/go-grpc-middleware.ChainUnaryServer.func1.1.1\n\t/go/pkg/mod/github.com/grpc-ecosystem/[email protected]/chain.go:25\ngo.opentelemetry.io/contrib/instrumentation/google.golang.org/grpc/otelgrpc.UnaryServerInterceptor.func1\n\t/go/pkg/mod/go.opentelemetry.io/contrib/instrumentation/google.golang.org/grpc/[email protected]/interceptor.go:342\ngithub.com/grpc-ecosystem/go-grpc-middleware.ChainUnaryServer.func1.1.1\n\t/go/pkg/mod/github.com/grpc-ecosystem/[email protected]/chain.go:25\ngithub.com/grpc-ecosystem/go-grpc-middleware.ChainUnaryServer.func1\n\t/go/pkg/mod/github.com/grpc-ecosystem/[email protected]/chain.go:34\ngithub.com/milvus-io/milvus/internal/proto/datapb._DataCoord_Flush_Handler\n\t/go/src/github.com/milvus-io/milvus/internal/proto/datapb/data_coord.pb.go:6538\ngoogle.golang.org/grpc.(*Server).processUnaryRPC\n\t/go/pkg/mod/google.golang.org/[email protected]/server.go:1345\ngoogle.golang.org/grpc.(*Server).handleStream\n\t/go/pkg/mod/google.golang.org/[email protected]/server.go:1722\ngoogle.golang.org/grpc.(*Server).serveStreams.func1.2\n\t/go/pkg/mod/google.golang.org/[email protected]/server.go:966"]

Backup log

0.4.24 (Built on 2024-09-20T02:08:15Z from Git SHA 290692046dd95b1140d3c0d376cf7a5e7ade7971)
config:backup.yaml
[2024/10/15 20:18:20.391 +08:00] [INFO] [logutil/logutil.go:165] ["Log directory"] [configDir=]
[2024/10/15 20:18:20.391 +08:00] [INFO] [logutil/logutil.go:166] ["Set log file to "] [path=logs/backup.log]
[2024/10/15 20:18:20.392 +08:00] [INFO] [core/backup_impl_create_backup.go:31] ["receive CreateBackupRequest"] [requestId=90dbee73-8aef-11ef-851a-525400ae5c5f] [backupName=backup20241015] [collections="[]"] [databaseCollections=] [async=false] [force=false] [metaOnly=false]
[2024/10/15 20:18:20.392 +08:00] [INFO] [core/backup_context.go:117] ["{Base:0xc0003e8580 MaxSegmentGroupSize:2147483648 BackupCollectionParallelism:4 BackupCopyDataParallelism:128 RestoreParallelism:2 KeepTempFiles:false GcPauseEnable:false GcPauseSeconds:7200 GcPauseAddress:http://192.168.24.124:55002}"]
[2024/10/15 20:18:20.392 +08:00] [INFO] [core/backup_context.go:118] ["{Base:0xc0003e8580 Enabled:true DebugMode:false SimpleResponse:true}"]
[2024/10/15 20:18:20.392 +08:00] [DEBUG] [core/backup_context.go:192] ["create backup storage client"] [address=milvus-1255305554.cos.ap-beijing.myqcloud.com:443] [bucket=milvus-1255305554] [backupBucket=milvus-1255305554]
[2024/10/15 20:18:20.541 +08:00] [INFO] [storage/minio_chunk_manager.go:144] ["minio chunk manager init success."] [bucketname=milvus-1255305554] [root=backup]
[2024/10/15 20:18:20.578 +08:00] [DEBUG] [core/backup_context.go:68] ["Start Milvus client"] [endpoint=192.168.24.124:52002]
[2024/10/15 20:18:20.582 +08:00] [DEBUG] [core/backup_impl_create_backup.go:136] ["Request collection names"] [request_collection_names="[]"] [request_db_collections=] [length=0]
[2024/10/15 20:18:20.587 +08:00] [DEBUG] [core/backup_impl_create_backup.go:202] ["List 1 collections"]
[2024/10/15 20:18:20.587 +08:00] [INFO] [core/backup_impl_create_backup.go:684] ["collections to backup"] [collections="[default.ssss]"]
[2024/10/15 20:18:20.587 +08:00] [INFO] [core/backup_impl_create_backup.go:230] ["start backup collection"] [db=default] [collection=ssss]
[2024/10/15 20:18:20.589 +08:00] [INFO] [core/backup_impl_create_backup.go:263] ["try to get index"] [collection_name=ssss]
[2024/10/15 20:18:20.593 +08:00] [INFO] [core/backup_impl_create_backup.go:283] ["field index"] [collection_name=ssss] [field_name=id] ["index info"="[]"]
[2024/10/15 20:18:20.597 +08:00] [INFO] [core/backup_impl_create_backup.go:283] ["field index"] [collection_name=ssss] [field_name=name] ["index info"="[{}]"]
[2024/10/15 20:18:20.603 +08:00] [INFO] [core/backup_impl_create_backup.go:369] ["GetPersistentSegmentInfo before flush from milvus"] [databaseName=default] [collectionName=ssss] [segmentNumBeforeFlush=1]
[2024/10/15 20:21:11.689 +08:00] [ERROR] [core/backup_impl_create_backup.go:375] ["fail to flush the collection"] [databaseName=default] [collectionName=ssss] [error="attempt #59: channel not found[channel=by-dev-rootcoord-dml_0_453241817591190296v0]"] [errorVerbose="attempt #59: channel not found[channel=by-dev-rootcoord-dml_0_453241817591190296v0]\n(1) attached stack trace\n  -- stack trace:\n  | github.com/milvus-io/milvus-sdk-go/v2/client.handleRespStatus\n  | \t/home/runner/go/pkg/mod/github.com/milvus-io/milvus-sdk-go/[email protected]/client/collection.go:39\n  | github.com/milvus-io/milvus-sdk-go/v2/client.(*GrpcClient).FlushV2\n  | \t/home/runner/go/pkg/mod/github.com/milvus-io/milvus-sdk-go/[email protected]/client/insert.go:240\n  | github.com/zilliztech/milvus-backup/core.(*MilvusClient).FlushV2\n  | \t/home/runner/work/milvus-backup/milvus-backup/core/milvus_sdk_wrapper.go:94\n  | github.com/zilliztech/milvus-backup/core.(*BackupContext).backupCollectionPrepare\n  | \t/home/runner/work/milvus-backup/milvus-backup/core/backup_impl_create_backup.go:373\n  | github.com/zilliztech/milvus-backup/core.(*BackupContext).executeCreateBackup.func1.1\n  | \t/home/runner/work/milvus-backup/milvus-backup/core/backup_impl_create_backup.go:691\n  | github.com/zilliztech/milvus-backup/internal/util/retry.Do\n  | \t/home/runner/work/milvus-backup/milvus-backup/internal/util/retry/retry.go:37\n  | github.com/zilliztech/milvus-backup/core.(*BackupContext).executeCreateBackup.func1\n  | \t/home/runner/work/milvus-backup/milvus-backup/core/backup_impl_create_backup.go:690\n  | github.com/zilliztech/milvus-backup/internal/common.(*WorkerPool).work.func1\n  | \t/home/runner/work/milvus-backup/milvus-backup/internal/common/workerpool.go:70\n  | golang.org/x/sync/errgroup.(*Group).Go.func1\n  | \t/home/runner/go/pkg/mod/golang.org/x/[email protected]/errgroup/errgroup.go:75\n  | runtime.goexit\n  | \t/opt/hostedtoolcache/go/1.18.10/x64/src/runtime/asm_amd64.s:1571\nWraps: (2) attempt #59: channel not found[channel=by-dev-rootcoord-dml_0_453241817591190296v0]\nError types: (1) *withstack.withStack (2) *errutil.leafError"] [stack="github.com/zilliztech/milvus-backup/core.(*BackupContext).backupCollectionPrepare\n\t/home/runner/work/milvus-backup/milvus-backup/core/backup_impl_create_backup.go:375\ngithub.com/zilliztech/milvus-backup/core.(*BackupContext).executeCreateBackup.func1.1\n\t/home/runner/work/milvus-backup/milvus-backup/core/backup_impl_create_backup.go:691\ngithub.com/zilliztech/milvus-backup/internal/util/retry.Do\n\t/home/runner/work/milvus-backup/milvus-backup/internal/util/retry/retry.go:37\ngithub.com/zilliztech/milvus-backup/core.(*BackupContext).executeCreateBackup.func1\n\t/home/runner/work/milvus-backup/milvus-backup/core/backup_impl_create_backup.go:690\ngithub.com/zilliztech/milvus-backup/internal/common.(*WorkerPool).work.func1\n\t/home/runner/work/milvus-backup/milvus-backup/internal/common/workerpool.go:70\ngolang.org/x/sync/errgroup.(*Group).Go.func1\n\t/home/runner/go/pkg/mod/golang.org/x/[email protected]/errgroup/errgroup.go:75"]
[2024/10/15 20:21:11.689 +08:00] [DEBUG] [retry/retry.go:39] ["retry func failed"] ["retry time"=0] [error="attempt #59: channel not found[channel=by-dev-rootcoord-dml_0_453241817591190296v0]"] [errorVerbose="attempt #59: channel not found[channel=by-dev-rootcoord-dml_0_453241817591190296v0]\n(1) attached stack trace\n  -- stack trace:\n  | github.com/milvus-io/milvus-sdk-go/v2/client.handleRespStatus\n  | \t/home/runner/go/pkg/mod/github.com/milvus-io/milvus-sdk-go/[email protected]/client/collection.go:39\n  | github.com/milvus-io/milvus-sdk-go/v2/client.(*GrpcClient).FlushV2\n  | \t/home/runner/go/pkg/mod/github.com/milvus-io/milvus-sdk-go/[email protected]/client/insert.go:240\n  | github.com/zilliztech/milvus-backup/core.(*MilvusClient).FlushV2\n  | \t/home/runner/work/milvus-backup/milvus-backup/core/milvus_sdk_wrapper.go:94\n  | github.com/zilliztech/milvus-backup/core.(*BackupContext).backupCollectionPrepare\n  | \t/home/runner/work/milvus-backup/milvus-backup/core/backup_impl_create_backup.go:373\n  | github.com/zilliztech/milvus-backup/core.(*BackupContext).executeCreateBackup.func1.1\n  | \t/home/runner/work/milvus-backup/milvus-backup/core/backup_impl_create_backup.go:691\n  | github.com/zilliztech/milvus-backup/internal/util/retry.Do\n  | \t/home/runner/work/milvus-backup/milvus-backup/internal/util/retry/retry.go:37\n  | github.com/zilliztech/milvus-backup/core.(*BackupContext).executeCreateBackup.func1\n  | \t/home/runner/work/milvus-backup/milvus-backup/core/backup_impl_create_backup.go:690\n  | github.com/zilliztech/milvus-backup/internal/common.(*WorkerPool).work.func1\n  | \t/home/runner/work/milvus-backup/milvus-backup/internal/common/workerpool.go:70\n  | golang.org/x/sync/errgroup.(*Group).Go.func1\n  | \t/home/runner/go/pkg/mod/golang.org/x/[email protected]/errgroup/errgroup.go:75\n  | runtime.goexit\n  | \t/opt/hostedtoolcache/go/1.18.10/x64/src/runtime/asm_amd64.s:1571\nWraps: (2) attempt #59: channel not found[channel=by-dev-rootcoord-dml_0_453241817591190296v0]\nError types: (1) *withstack.withStack (2) *errutil.leafError"]
^C

milvus all log

[2024/10/15 20:20:01.355 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=ms] [collections="[]"]
[2024/10/15 20:20:01.355 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=default] [collections="[ssss]"]
[2024/10/15 20:20:02.654 +00:00] [INFO] [datacoord/session_manager.go:322] ["SessionManagerImpl.FlushChannels start"] [traceID=925c0caa4652e949d5e69f2264e1cc62] [nodeID=42] [flushTs=2024/10/15 20:18:20.577 +00:00] [channels="[by-dev-rootcoord-dml_0_453241817591190296v0]"]
[2024/10/15 20:20:02.655 +00:00] [INFO] [datanode/services.go:550] ["DataNode receives FlushChannels request"] [traceID=925c0caa4652e949d5e69f2264e1cc62] [nodeId=42] [flushTs=2024/10/15 20:18:20.577 +00:00] [channels="[by-dev-rootcoord-dml_0_453241817591190296v0]"]
[2024/10/15 20:20:02.655 +00:00] [WARN] [datacoord/session_manager.go:326] ["SessionManagerImpl.FlushChannels failed"] [traceID=925c0caa4652e949d5e69f2264e1cc62] [nodeID=42] [flushTs=2024/10/15 20:18:20.577 +00:00] [channels="[by-dev-rootcoord-dml_0_453241817591190296v0]"] [error="channel not found[channel=by-dev-rootcoord-dml_0_453241817591190296v0]"]
[2024/10/15 20:20:02.860 +00:00] [INFO] [datacoord/index_service.go:513] ["receive DescribeIndex request"] [traceID=7a84fc90d3231c9bd7fa3758abb4b047] [collectionID=453241817591190296] [indexName=] [timestamp=0]
[2024/10/15 20:20:02.860 +00:00] [INFO] [datacoord/index_service.go:449] ["completeIndexInfo success"] [collectionID=453241817591190296] [indexID=453241817591190327] [totalRows=0] [indexRows=0] [pendingIndexRows=0] [state=Finished] [failReason=]
[2024/10/15 20:20:02.860 +00:00] [INFO] [datacoord/index_service.go:560] ["DescribeIndex success"] [traceID=7a84fc90d3231c9bd7fa3758abb4b047] [collectionID=453241817591190296] [indexName=]
[2024/10/15 20:20:02.861 +00:00] [INFO] [querynodev2/services.go:1366] ["sync action"] [traceID=87b89092aafab11b89ec8ad7b9cd3290] [collectionID=453241817591190296] [channel=by-dev-rootcoord-dml_0_453241817591190296v0] [currentNodeID=42] [Action=UpdateVersion] [TargetVersion=1728994792860960572]
[2024/10/15 20:20:02.861 +00:00] [INFO] [delegator/distribution.go:294] ["Update readable segment version"] [oldVersion=1728994782861216512] [newVersion=1728994792860960572] [growingSegmentNum=0] [sealedSegmentNum=0]
[2024/10/15 20:20:02.861 +00:00] [INFO] [observers/target_observer.go:473] ["observer trigger update current target"] [collectionID=453241817591190296]
[2024/10/15 20:20:02.861 +00:00] [INFO] [datacoord/services.go:757] ["get recovery info request received"] [traceID=87ced68681a8f5c08b7b640480bad223] [collectionID=453241817591190296] [partitionIDs="[]"]
[2024/10/15 20:20:02.861 +00:00] [INFO] [datacoord/handler.go:116] [GetQueryVChanPositions] [collectionID=453241817591190296] [channel=by-dev-rootcoord-dml_0_453241817591190296v0] [numOfSegments=1] ["indexed segment"=0]
[2024/10/15 20:20:02.861 +00:00] [INFO] [datacoord/handler.go:281] ["segment position not found, setting channel seek position to channel start position"] [channel=by-dev-rootcoord-dml_0_453241817591190296v0] [posTs=453242054227263491] [posTime=2024/10/15 08:33:44.927 +00:00]
[2024/10/15 20:20:02.861 +00:00] [INFO] [datacoord/handler.go:315] ["channel seek position set from collection start position"] [channel=by-dev-rootcoord-dml_0_453241817591190296v0] [posTs=453242054227263491] [posTime=2024/10/15 08:33:44.927 +00:00]
[2024/10/15 20:20:02.861 +00:00] [INFO] [datacoord/services.go:772] ["datacoord append channelInfo in GetRecoveryInfo"] [traceID=87ced68681a8f5c08b7b640480bad223] [collectionID=453241817591190296] [partitionIDs="[]"] [channel=by-dev-rootcoord-dml_0_453241817591190296v0] ["# of unflushed segments"=0] ["# of flushed segments"=0] ["# of dropped segments"=0] ["# of indexed segments"=0]
[2024/10/15 20:20:03.152 +00:00] [WARN] [kafka/kafka_consumer.go:138] ["consume msg failed"] [topic=by-dev-rootcoord-dml_4] [groupID=datanode-42-by-dev-rootcoord-dml_4_450590887436092108v0-true] [error="Local: Timed out"]
[2024/10/15 20:20:03.828 +00:00] [INFO] [grpclog/grpclog.go:37] ["[transport]transport: closing: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:20:03.828 +00:00] [INFO] [grpclog/grpclog.go:37] ["[core][Server #10] grpc: Server.Serve failed to create ServerTransport: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:20:03.924 +00:00] [INFO] [grpclog/grpclog.go:37] ["[transport]transport: closing: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:20:03.924 +00:00] [INFO] [grpclog/grpclog.go:37] ["[core][Server #22] grpc: Server.Serve failed to create ServerTransport: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:20:04.356 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=ms] [collections="[]"]
[2024/10/15 20:20:04.356 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=default] [collections="[ssss]"]
[2024/10/15 20:20:05.070 +00:00] [INFO] [grpclog/grpclog.go:37] ["[transport]transport: closing: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:20:05.070 +00:00] [INFO] [grpclog/grpclog.go:37] ["[core][Server #18] grpc: Server.Serve failed to create ServerTransport: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:20:05.656 +00:00] [INFO] [datacoord/session_manager.go:322] ["SessionManagerImpl.FlushChannels start"] [traceID=925c0caa4652e949d5e69f2264e1cc62] [nodeID=42] [flushTs=2024/10/15 20:18:20.577 +00:00] [channels="[by-dev-rootcoord-dml_0_453241817591190296v0]"]
[2024/10/15 20:20:05.656 +00:00] [INFO] [datanode/services.go:550] ["DataNode receives FlushChannels request"] [traceID=925c0caa4652e949d5e69f2264e1cc62] [nodeId=42] [flushTs=2024/10/15 20:18:20.577 +00:00] [channels="[by-dev-rootcoord-dml_0_453241817591190296v0]"]
[2024/10/15 20:20:05.656 +00:00] [WARN] [datacoord/session_manager.go:326] ["SessionManagerImpl.FlushChannels failed"] [traceID=925c0caa4652e949d5e69f2264e1cc62] [nodeID=42] [flushTs=2024/10/15 20:18:20.577 +00:00] [channels="[by-dev-rootcoord-dml_0_453241817591190296v0]"] [error="channel not found[channel=by-dev-rootcoord-dml_0_453241817591190296v0]"]
[2024/10/15 20:20:06.281 +00:00] [WARN] [rootcoord/quota_center.go:742] ["failed to get collection rate limit config"] [collectionID=450591213173465433] [error="collection not found[collection=450591213173465433]"]
[2024/10/15 20:20:07.356 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=ms] [collections="[]"]
[2024/10/15 20:20:07.356 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=default] [collections="[ssss]"]
[2024/10/15 20:20:08.656 +00:00] [INFO] [datacoord/session_manager.go:322] ["SessionManagerImpl.FlushChannels start"] [traceID=925c0caa4652e949d5e69f2264e1cc62] [nodeID=42] [flushTs=2024/10/15 20:18:20.577 +00:00] [channels="[by-dev-rootcoord-dml_0_453241817591190296v0]"]
[2024/10/15 20:20:08.657 +00:00] [INFO] [datanode/services.go:550] ["DataNode receives FlushChannels request"] [traceID=925c0caa4652e949d5e69f2264e1cc62] [nodeId=42] [flushTs=2024/10/15 20:18:20.577 +00:00] [channels="[by-dev-rootcoord-dml_0_453241817591190296v0]"]
[2024/10/15 20:20:08.657 +00:00] [WARN] [datacoord/session_manager.go:326] ["SessionManagerImpl.FlushChannels failed"] [traceID=925c0caa4652e949d5e69f2264e1cc62] [nodeID=42] [flushTs=2024/10/15 20:18:20.577 +00:00] [channels="[by-dev-rootcoord-dml_0_453241817591190296v0]"] [error="channel not found[channel=by-dev-rootcoord-dml_0_453241817591190296v0]"]
[2024/10/15 20:20:09.580 +00:00] [INFO] [grpclog/grpclog.go:37] ["[transport]transport: closing: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:20:09.580 +00:00] [INFO] [grpclog/grpclog.go:37] ["[core][Server #28] grpc: Server.Serve failed to create ServerTransport: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[GIN] 2024/10/15 - 12:20:09 | 404 |      11.874µs |  192.168.24.124 | GET      "/metrics"
[2024/10/15 20:20:10.356 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=ms] [collections="[]"]
[2024/10/15 20:20:10.356 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=default] [collections="[ssss]"]
[2024/10/15 20:20:11.001 +00:00] [INFO] [grpclog/grpclog.go:37] ["[transport]transport: closing: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:20:11.001 +00:00] [INFO] [grpclog/grpclog.go:37] ["[core][Server #6] grpc: Server.Serve failed to create ServerTransport: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:20:11.657 +00:00] [INFO] [datacoord/session_manager.go:322] ["SessionManagerImpl.FlushChannels start"] [traceID=925c0caa4652e949d5e69f2264e1cc62] [nodeID=42] [flushTs=2024/10/15 20:18:20.577 +00:00] [channels="[by-dev-rootcoord-dml_0_453241817591190296v0]"]
[2024/10/15 20:20:11.658 +00:00] [INFO] [datanode/services.go:550] ["DataNode receives FlushChannels request"] [traceID=925c0caa4652e949d5e69f2264e1cc62] [nodeId=42] [flushTs=2024/10/15 20:18:20.577 +00:00] [channels="[by-dev-rootcoord-dml_0_453241817591190296v0]"]
[2024/10/15 20:20:11.658 +00:00] [WARN] [datacoord/session_manager.go:326] ["SessionManagerImpl.FlushChannels failed"] [traceID=925c0caa4652e949d5e69f2264e1cc62] [nodeID=42] [flushTs=2024/10/15 20:18:20.577 +00:00] [channels="[by-dev-rootcoord-dml_0_453241817591190296v0]"] [error="channel not found[channel=by-dev-rootcoord-dml_0_453241817591190296v0]"]
[2024/10/15 20:20:11.658 +00:00] [ERROR] [retry/retry.go:46] ["retry func failed"] [traceID=925c0caa4652e949d5e69f2264e1cc62] ["retry time"=40] [error="channel not found[channel=by-dev-rootcoord-dml_0_453241817591190296v0]"] [stack="github.com/milvus-io/milvus/pkg/util/retry.Do\n\t/go/src/github.com/milvus-io/milvus/pkg/util/retry/retry.go:46\ngithub.com/milvus-io/milvus/internal/datacoord.(*Server).Flush\n\t/go/src/github.com/milvus-io/milvus/internal/datacoord/services.go:116\ngithub.com/milvus-io/milvus/internal/distributed/datacoord.(*Server).Flush\n\t/go/src/github.com/milvus-io/milvus/internal/distributed/datacoord/service.go:283\ngithub.com/milvus-io/milvus/internal/proto/datapb._DataCoord_Flush_Handler.func1\n\t/go/src/github.com/milvus-io/milvus/internal/proto/datapb/data_coord.pb.go:6536\ngithub.com/milvus-io/milvus/pkg/util/interceptor.ServerIDValidationUnaryServerInterceptor.func1\n\t/go/src/github.com/milvus-io/milvus/pkg/util/interceptor/server_id_interceptor.go:54\ngithub.com/grpc-ecosystem/go-grpc-middleware.ChainUnaryServer.func1.1.1\n\t/go/pkg/mod/github.com/grpc-ecosystem/[email protected]/chain.go:25\ngithub.com/milvus-io/milvus/pkg/util/interceptor.ClusterValidationUnaryServerInterceptor.func1\n\t/go/src/github.com/milvus-io/milvus/pkg/util/interceptor/cluster_interceptor.go:48\ngithub.com/grpc-ecosystem/go-grpc-middleware.ChainUnaryServer.func1.1.1\n\t/go/pkg/mod/github.com/grpc-ecosystem/[email protected]/chain.go:25\ngithub.com/milvus-io/milvus/pkg/util/logutil.UnaryTraceLoggerInterceptor\n\t/go/src/github.com/milvus-io/milvus/pkg/util/logutil/grpc_interceptor.go:23\ngithub.com/grpc-ecosystem/go-grpc-middleware.ChainUnaryServer.func1.1.1\n\t/go/pkg/mod/github.com/grpc-ecosystem/[email protected]/chain.go:25\ngo.opentelemetry.io/contrib/instrumentation/google.golang.org/grpc/otelgrpc.UnaryServerInterceptor.func1\n\t/go/pkg/mod/go.opentelemetry.io/contrib/instrumentation/google.golang.org/grpc/[email protected]/interceptor.go:342\ngithub.com/grpc-ecosystem/go-grpc-middleware.ChainUnaryServer.func1.1.1\n\t/go/pkg/mod/github.com/grpc-ecosystem/[email protected]/chain.go:25\ngithub.com/grpc-ecosystem/go-grpc-middleware.ChainUnaryServer.func1\n\t/go/pkg/mod/github.com/grpc-ecosystem/[email protected]/chain.go:34\ngithub.com/milvus-io/milvus/internal/proto/datapb._DataCoord_Flush_Handler\n\t/go/src/github.com/milvus-io/milvus/internal/proto/datapb/data_coord.pb.go:6538\ngoogle.golang.org/grpc.(*Server).processUnaryRPC\n\t/go/pkg/mod/google.golang.org/[email protected]/server.go:1345\ngoogle.golang.org/grpc.(*Server).handleStream\n\t/go/pkg/mod/google.golang.org/[email protected]/server.go:1722\ngoogle.golang.org/grpc.(*Server).serveStreams.func1.2\n\t/go/pkg/mod/google.golang.org/[email protected]/server.go:966"]
[2024/10/15 20:20:12.860 +00:00] [INFO] [datacoord/index_service.go:513] ["receive DescribeIndex request"] [traceID=226dec43a6f1a2a3f6f493b38575706d] [collectionID=453241817591190296] [indexName=] [timestamp=0]
[2024/10/15 20:20:12.860 +00:00] [INFO] [datacoord/index_service.go:449] ["completeIndexInfo success"] [collectionID=453241817591190296] [indexID=453241817591190327] [totalRows=0] [indexRows=0] [pendingIndexRows=0] [state=Finished] [failReason=]
[2024/10/15 20:20:12.860 +00:00] [INFO] [datacoord/index_service.go:560] ["DescribeIndex success"] [traceID=226dec43a6f1a2a3f6f493b38575706d] [collectionID=453241817591190296] [indexName=]
[2024/10/15 20:20:12.860 +00:00] [INFO] [querynodev2/services.go:1366] ["sync action"] [traceID=bff1a0fb17486c234ed3fc5f030d07e8] [collectionID=453241817591190296] [channel=by-dev-rootcoord-dml_0_453241817591190296v0] [currentNodeID=42] [Action=UpdateVersion] [TargetVersion=1728994802861370500]
[2024/10/15 20:20:12.860 +00:00] [INFO] [delegator/distribution.go:294] ["Update readable segment version"] [oldVersion=1728994792860960572] [newVersion=1728994802861370500] [growingSegmentNum=0] [sealedSegmentNum=0]
[2024/10/15 20:20:12.860 +00:00] [INFO] [observers/target_observer.go:473] ["observer trigger update current target"] [collectionID=453241817591190296]
[2024/10/15 20:20:12.861 +00:00] [INFO] [datacoord/services.go:757] ["get recovery info request received"] [traceID=57ebd20e9365c749392605d4196c6ab8] [collectionID=453241817591190296] [partitionIDs="[]"]
[2024/10/15 20:20:12.861 +00:00] [INFO] [datacoord/handler.go:116] [GetQueryVChanPositions] [collectionID=453241817591190296] [channel=by-dev-rootcoord-dml_0_453241817591190296v0] [numOfSegments=1] ["indexed segment"=0]
[2024/10/15 20:20:12.861 +00:00] [INFO] [datacoord/handler.go:281] ["segment position not found, setting channel seek position to channel start position"] [channel=by-dev-rootcoord-dml_0_453241817591190296v0] [posTs=453242054227263491] [posTime=2024/10/15 08:33:44.927 +00:00]
[2024/10/15 20:20:12.861 +00:00] [INFO] [datacoord/handler.go:315] ["channel seek position set from collection start position"] [channel=by-dev-rootcoord-dml_0_453241817591190296v0] [posTs=453242054227263491] [posTime=2024/10/15 08:33:44.927 +00:00]
[2024/10/15 20:20:12.861 +00:00] [INFO] [datacoord/services.go:772] ["datacoord append channelInfo in GetRecoveryInfo"] [traceID=57ebd20e9365c749392605d4196c6ab8] [collectionID=453241817591190296] [partitionIDs="[]"] [channel=by-dev-rootcoord-dml_0_453241817591190296v0] ["# of unflushed segments"=0] ["# of flushed segments"=0] ["# of dropped segments"=0] ["# of indexed segments"=0]
[2024/10/15 20:20:13.083 +00:00] [INFO] [grpclog/grpclog.go:37] ["[transport]transport: closing: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:20:13.083 +00:00] [INFO] [grpclog/grpclog.go:37] ["[core][Server #14] grpc: Server.Serve failed to create ServerTransport: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:20:13.152 +00:00] [WARN] [kafka/kafka_consumer.go:138] ["consume msg failed"] [topic=by-dev-rootcoord-dml_4] [groupID=datanode-42-by-dev-rootcoord-dml_4_450590887436092108v0-true] [error="Local: Timed out"]
[2024/10/15 20:20:13.356 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=ms] [collections="[]"]
[2024/10/15 20:20:13.356 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=default] [collections="[ssss]"]
[2024/10/15 20:20:14.659 +00:00] [INFO] [datacoord/session_manager.go:322] ["SessionManagerImpl.FlushChannels start"] [traceID=925c0caa4652e949d5e69f2264e1cc62] [nodeID=42] [flushTs=2024/10/15 20:18:20.577 +00:00] [channels="[by-dev-rootcoord-dml_0_453241817591190296v0]"]
[2024/10/15 20:20:14.659 +00:00] [INFO] [datanode/services.go:550] ["DataNode receives FlushChannels request"] [traceID=925c0caa4652e949d5e69f2264e1cc62] [nodeId=42] [flushTs=2024/10/15 20:18:20.577 +00:00] [channels="[by-dev-rootcoord-dml_0_453241817591190296v0]"]
[2024/10/15 20:20:14.659 +00:00] [WARN] [datacoord/session_manager.go:326] ["SessionManagerImpl.FlushChannels failed"] [traceID=925c0caa4652e949d5e69f2264e1cc62] [nodeID=42] [flushTs=2024/10/15 20:18:20.577 +00:00] [channels="[by-dev-rootcoord-dml_0_453241817591190296v0]"] [error="channel not found[channel=by-dev-rootcoord-dml_0_453241817591190296v0]"]
[2024/10/15 20:20:16.356 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=ms] [collections="[]"]
[2024/10/15 20:20:16.356 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=default] [collections="[ssss]"]
[2024/10/15 20:20:17.660 +00:00] [INFO] [datacoord/session_manager.go:322] ["SessionManagerImpl.FlushChannels start"] [traceID=925c0caa4652e949d5e69f2264e1cc62] [nodeID=42] [flushTs=2024/10/15 20:18:20.577 +00:00] [channels="[by-dev-rootcoord-dml_0_453241817591190296v0]"]
[2024/10/15 20:20:17.660 +00:00] [INFO] [datanode/services.go:550] ["DataNode receives FlushChannels request"] [traceID=925c0caa4652e949d5e69f2264e1cc62] [nodeId=42] [flushTs=2024/10/15 20:18:20.577 +00:00] [channels="[by-dev-rootcoord-dml_0_453241817591190296v0]"]
[2024/10/15 20:20:17.660 +00:00] [WARN] [datacoord/session_manager.go:326] ["SessionManagerImpl.FlushChannels failed"] [traceID=925c0caa4652e949d5e69f2264e1cc62] [nodeID=42] [flushTs=2024/10/15 20:18:20.577 +00:00] [channels="[by-dev-rootcoord-dml_0_453241817591190296v0]"] [error="channel not found[channel=by-dev-rootcoord-dml_0_453241817591190296v0]"]
[2024/10/15 20:20:18.281 +00:00] [WARN] [rootcoord/quota_center.go:742] ["failed to get collection rate limit config"] [collectionID=450591213173465433] [error="collection not found[collection=450591213173465433]"]
[2024/10/15 20:20:18.829 +00:00] [INFO] [grpclog/grpclog.go:37] ["[transport]transport: closing: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:20:18.829 +00:00] [INFO] [grpclog/grpclog.go:37] ["[core][Server #10] grpc: Server.Serve failed to create ServerTransport: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:20:18.924 +00:00] [INFO] [grpclog/grpclog.go:37] ["[transport]transport: closing: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:20:18.924 +00:00] [INFO] [grpclog/grpclog.go:37] ["[core][Server #22] grpc: Server.Serve failed to create ServerTransport: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:20:19.356 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=ms] [collections="[]"]
[2024/10/15 20:20:19.356 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=default] [collections="[ssss]"]
[2024/10/15 20:20:20.070 +00:00] [INFO] [grpclog/grpclog.go:37] ["[transport]transport: closing: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:20:20.070 +00:00] [INFO] [grpclog/grpclog.go:37] ["[core][Server #18] grpc: Server.Serve failed to create ServerTransport: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:20:20.661 +00:00] [INFO] [datacoord/session_manager.go:322] ["SessionManagerImpl.FlushChannels start"] [traceID=925c0caa4652e949d5e69f2264e1cc62] [nodeID=42] [flushTs=2024/10/15 20:18:20.577 +00:00] [channels="[by-dev-rootcoord-dml_0_453241817591190296v0]"]
[2024/10/15 20:20:20.662 +00:00] [INFO] [datanode/services.go:550] ["DataNode receives FlushChannels request"] [traceID=925c0caa4652e949d5e69f2264e1cc62] [nodeId=42] [flushTs=2024/10/15 20:18:20.577 +00:00] [channels="[by-dev-rootcoord-dml_0_453241817591190296v0]"]
[2024/10/15 20:20:20.662 +00:00] [WARN] [datacoord/session_manager.go:326] ["SessionManagerImpl.FlushChannels failed"] [traceID=925c0caa4652e949d5e69f2264e1cc62] [nodeID=42] [flushTs=2024/10/15 20:18:20.577 +00:00] [channels="[by-dev-rootcoord-dml_0_453241817591190296v0]"] [error="channel not found[channel=by-dev-rootcoord-dml_0_453241817591190296v0]"]
[2024/10/15 20:20:22.355 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=ms] [collections="[]"]
[2024/10/15 20:20:22.355 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=default] [collections="[ssss]"]
[2024/10/15 20:20:22.860 +00:00] [INFO] [datacoord/index_service.go:513] ["receive DescribeIndex request"] [traceID=a7d390a0f48690afeff91021d2e34a7a] [collectionID=453241817591190296] [indexName=] [timestamp=0]
[2024/10/15 20:20:22.860 +00:00] [INFO] [datacoord/index_service.go:449] ["completeIndexInfo success"] [collectionID=453241817591190296] [indexID=453241817591190327] [totalRows=0] [indexRows=0] [pendingIndexRows=0] [state=Finished] [failReason=]
[2024/10/15 20:20:22.860 +00:00] [INFO] [datacoord/index_service.go:560] ["DescribeIndex success"] [traceID=a7d390a0f48690afeff91021d2e34a7a] [collectionID=453241817591190296] [indexName=]
[2024/10/15 20:20:22.861 +00:00] [INFO] [querynodev2/services.go:1366] ["sync action"] [traceID=fea72df0e232febe418e897503cada83] [collectionID=453241817591190296] [channel=by-dev-rootcoord-dml_0_453241817591190296v0] [currentNodeID=42] [Action=UpdateVersion] [TargetVersion=1728994812860999121]
[2024/10/15 20:20:22.861 +00:00] [INFO] [delegator/distribution.go:294] ["Update readable segment version"] [oldVersion=1728994802861370500] [newVersion=1728994812860999121] [growingSegmentNum=0] [sealedSegmentNum=0]
[2024/10/15 20:20:22.861 +00:00] [INFO] [observers/target_observer.go:473] ["observer trigger update current target"] [collectionID=453241817591190296]
[2024/10/15 20:20:22.861 +00:00] [INFO] [datacoord/services.go:757] ["get recovery info request received"] [traceID=13643b4538d8d3cfb249a8885cd1ca95] [collectionID=453241817591190296] [partitionIDs="[]"]
[2024/10/15 20:20:22.861 +00:00] [INFO] [datacoord/handler.go:116] [GetQueryVChanPositions] [collectionID=453241817591190296] [channel=by-dev-rootcoord-dml_0_453241817591190296v0] [numOfSegments=1] ["indexed segment"=0]
[2024/10/15 20:20:22.861 +00:00] [INFO] [datacoord/handler.go:281] ["segment position not found, setting channel seek position to channel start position"] [channel=by-dev-rootcoord-dml_0_453241817591190296v0] [posTs=453242054227263491] [posTime=2024/10/15 08:33:44.927 +00:00]
[2024/10/15 20:20:22.861 +00:00] [INFO] [datacoord/handler.go:315] ["channel seek position set from collection start position"] [channel=by-dev-rootcoord-dml_0_453241817591190296v0] [posTs=453242054227263491] [posTime=2024/10/15 08:33:44.927 +00:00]
[2024/10/15 20:20:22.861 +00:00] [INFO] [datacoord/services.go:772] ["datacoord append channelInfo in GetRecoveryInfo"] [traceID=13643b4538d8d3cfb249a8885cd1ca95] [collectionID=453241817591190296] [partitionIDs="[]"] [channel=by-dev-rootcoord-dml_0_453241817591190296v0] ["# of unflushed segments"=0] ["# of flushed segments"=0] ["# of dropped segments"=0] ["# of indexed segments"=0]
[2024/10/15 20:20:23.152 +00:00] [WARN] [kafka/kafka_consumer.go:138] ["consume msg failed"] [topic=by-dev-rootcoord-dml_4] [groupID=datanode-42-by-dev-rootcoord-dml_4_450590887436092108v0-true] [error="Local: Timed out"]
[2024/10/15 20:20:23.662 +00:00] [INFO] [datacoord/session_manager.go:322] ["SessionManagerImpl.FlushChannels start"] [traceID=925c0caa4652e949d5e69f2264e1cc62] [nodeID=42] [flushTs=2024/10/15 20:18:20.577 +00:00] [channels="[by-dev-rootcoord-dml_0_453241817591190296v0]"]
[2024/10/15 20:20:23.663 +00:00] [INFO] [datanode/services.go:550] ["DataNode receives FlushChannels request"] [traceID=925c0caa4652e949d5e69f2264e1cc62] [nodeId=42] [flushTs=2024/10/15 20:18:20.577 +00:00] [channels="[by-dev-rootcoord-dml_0_453241817591190296v0]"]
[2024/10/15 20:20:23.663 +00:00] [WARN] [datacoord/session_manager.go:326] ["SessionManagerImpl.FlushChannels failed"] [traceID=925c0caa4652e949d5e69f2264e1cc62] [nodeID=42] [flushTs=2024/10/15 20:18:20.577 +00:00] [channels="[by-dev-rootcoord-dml_0_453241817591190296v0]"] [error="channel not found[channel=by-dev-rootcoord-dml_0_453241817591190296v0]"]
[2024/10/15 20:20:23.663 +00:00] [ERROR] [retry/retry.go:46] ["retry func failed"] [traceID=925c0caa4652e949d5e69f2264e1cc62] ["retry time"=44] [error="channel not found[channel=by-dev-rootcoord-dml_0_453241817591190296v0]"] [stack="github.com/milvus-io/milvus/pkg/util/retry.Do\n\t/go/src/github.com/milvus-io/milvus/pkg/util/retry/retry.go:46\ngithub.com/milvus-io/milvus/internal/datacoord.(*Server).Flush\n\t/go/src/github.com/milvus-io/milvus/internal/datacoord/services.go:116\ngithub.com/milvus-io/milvus/internal/distributed/datacoord.(*Server).Flush\n\t/go/src/github.com/milvus-io/milvus/internal/distributed/datacoord/service.go:283\ngithub.com/milvus-io/milvus/internal/proto/datapb._DataCoord_Flush_Handler.func1\n\t/go/src/github.com/milvus-io/milvus/internal/proto/datapb/data_coord.pb.go:6536\ngithub.com/milvus-io/milvus/pkg/util/interceptor.ServerIDValidationUnaryServerInterceptor.func1\n\t/go/src/github.com/milvus-io/milvus/pkg/util/interceptor/server_id_interceptor.go:54\ngithub.com/grpc-ecosystem/go-grpc-middleware.ChainUnaryServer.func1.1.1\n\t/go/pkg/mod/github.com/grpc-ecosystem/[email protected]/chain.go:25\ngithub.com/milvus-io/milvus/pkg/util/interceptor.ClusterValidationUnaryServerInterceptor.func1\n\t/go/src/github.com/milvus-io/milvus/pkg/util/interceptor/cluster_interceptor.go:48\ngithub.com/grpc-ecosystem/go-grpc-middleware.ChainUnaryServer.func1.1.1\n\t/go/pkg/mod/github.com/grpc-ecosystem/[email protected]/chain.go:25\ngithub.com/milvus-io/milvus/pkg/util/logutil.UnaryTraceLoggerInterceptor\n\t/go/src/github.com/milvus-io/milvus/pkg/util/logutil/grpc_interceptor.go:23\ngithub.com/grpc-ecosystem/go-grpc-middleware.ChainUnaryServer.func1.1.1\n\t/go/pkg/mod/github.com/grpc-ecosystem/[email protected]/chain.go:25\ngo.opentelemetry.io/contrib/instrumentation/google.golang.org/grpc/otelgrpc.UnaryServerInterceptor.func1\n\t/go/pkg/mod/go.opentelemetry.io/contrib/instrumentation/google.golang.org/grpc/[email protected]/interceptor.go:342\ngithub.com/grpc-ecosystem/go-grpc-middleware.ChainUnaryServer.func1.1.1\n\t/go/pkg/mod/github.com/grpc-ecosystem/[email protected]/chain.go:25\ngithub.com/grpc-ecosystem/go-grpc-middleware.ChainUnaryServer.func1\n\t/go/pkg/mod/github.com/grpc-ecosystem/[email protected]/chain.go:34\ngithub.com/milvus-io/milvus/internal/proto/datapb._DataCoord_Flush_Handler\n\t/go/src/github.com/milvus-io/milvus/internal/proto/datapb/data_coord.pb.go:6538\ngoogle.golang.org/grpc.(*Server).processUnaryRPC\n\t/go/pkg/mod/google.golang.org/[email protected]/server.go:1345\ngoogle.golang.org/grpc.(*Server).handleStream\n\t/go/pkg/mod/google.golang.org/[email protected]/server.go:1722\ngoogle.golang.org/grpc.(*Server).serveStreams.func1.2\n\t/go/pkg/mod/google.golang.org/[email protected]/server.go:966"]
[2024/10/15 20:20:24.580 +00:00] [INFO] [grpclog/grpclog.go:37] ["[transport]transport: closing: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:20:24.580 +00:00] [INFO] [grpclog/grpclog.go:37] ["[core][Server #28] grpc: Server.Serve failed to create ServerTransport: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[GIN] 2024/10/15 - 12:20:24 | 404 |       13.02µs |  192.168.24.124 | GET      "/metrics"
[2024/10/15 20:20:25.355 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=ms] [collections="[]"]
[2024/10/15 20:20:25.356 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=default] [collections="[ssss]"]
[2024/10/15 20:20:26.000 +00:00] [INFO] [grpclog/grpclog.go:37] ["[transport]transport: closing: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:20:26.000 +00:00] [INFO] [grpclog/grpclog.go:37] ["[core][Server #6] grpc: Server.Serve failed to create ServerTransport: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:20:26.664 +00:00] [INFO] [datacoord/session_manager.go:322] ["SessionManagerImpl.FlushChannels start"] [traceID=925c0caa4652e949d5e69f2264e1cc62] [nodeID=42] [flushTs=2024/10/15 20:18:20.577 +00:00] [channels="[by-dev-rootcoord-dml_0_453241817591190296v0]"]
[2024/10/15 20:20:26.664 +00:00] [INFO] [datanode/services.go:550] ["DataNode receives FlushChannels request"] [traceID=925c0caa4652e949d5e69f2264e1cc62] [nodeId=42] [flushTs=2024/10/15 20:18:20.577 +00:00] [channels="[by-dev-rootcoord-dml_0_453241817591190296v0]"]
[2024/10/15 20:20:26.664 +00:00] [WARN] [datacoord/session_manager.go:326] ["SessionManagerImpl.FlushChannels failed"] [traceID=925c0caa4652e949d5e69f2264e1cc62] [nodeID=42] [flushTs=2024/10/15 20:18:20.577 +00:00] [channels="[by-dev-rootcoord-dml_0_453241817591190296v0]"] [error="channel not found[channel=by-dev-rootcoord-dml_0_453241817591190296v0]"]
[2024/10/15 20:20:27.281 +00:00] [WARN] [rootcoord/quota_center.go:742] ["failed to get collection rate limit config"] [collectionID=450591213173465433] [error="collection not found[collection=450591213173465433]"]
[2024/10/15 20:20:28.084 +00:00] [INFO] [grpclog/grpclog.go:37] ["[transport]transport: closing: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:20:28.084 +00:00] [INFO] [grpclog/grpclog.go:37] ["[core][Server #14] grpc: Server.Serve failed to create ServerTransport: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:20:28.356 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=ms] [collections="[]"]
[2024/10/15 20:20:28.357 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=default] [collections="[ssss]"]
[2024/10/15 20:20:29.665 +00:00] [INFO] [datacoord/session_manager.go:322] ["SessionManagerImpl.FlushChannels start"] [traceID=925c0caa4652e949d5e69f2264e1cc62] [nodeID=42] [flushTs=2024/10/15 20:18:20.577 +00:00] [channels="[by-dev-rootcoord-dml_0_453241817591190296v0]"]
[2024/10/15 20:20:29.665 +00:00] [INFO] [datanode/services.go:550] ["DataNode receives FlushChannels request"] [traceID=925c0caa4652e949d5e69f2264e1cc62] [nodeId=42] [flushTs=2024/10/15 20:18:20.577 +00:00] [channels="[by-dev-rootcoord-dml_0_453241817591190296v0]"]
[2024/10/15 20:20:29.665 +00:00] [WARN] [datacoord/session_manager.go:326] ["SessionManagerImpl.FlushChannels failed"] [traceID=925c0caa4652e949d5e69f2264e1cc62] [nodeID=42] [flushTs=2024/10/15 20:18:20.577 +00:00] [channels="[by-dev-rootcoord-dml_0_453241817591190296v0]"] [error="channel not found[channel=by-dev-rootcoord-dml_0_453241817591190296v0]"]
[2024/10/15 20:20:31.356 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=ms] [collections="[]"]
[2024/10/15 20:20:31.356 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=default] [collections="[ssss]"]
[2024/10/15 20:20:32.666 +00:00] [INFO] [datacoord/session_manager.go:322] ["SessionManagerImpl.FlushChannels start"] [traceID=925c0caa4652e949d5e69f2264e1cc62] [nodeID=42] [flushTs=2024/10/15 20:18:20.577 +00:00] [channels="[by-dev-rootcoord-dml_0_453241817591190296v0]"]
[2024/10/15 20:20:32.667 +00:00] [INFO] [datanode/services.go:550] ["DataNode receives FlushChannels request"] [traceID=925c0caa4652e949d5e69f2264e1cc62] [nodeId=42] [flushTs=2024/10/15 20:18:20.577 +00:00] [channels="[by-dev-rootcoord-dml_0_453241817591190296v0]"]
[2024/10/15 20:20:32.667 +00:00] [WARN] [datacoord/session_manager.go:326] ["SessionManagerImpl.FlushChannels failed"] [traceID=925c0caa4652e949d5e69f2264e1cc62] [nodeID=42] [flushTs=2024/10/15 20:18:20.577 +00:00] [channels="[by-dev-rootcoord-dml_0_453241817591190296v0]"] [error="channel not found[channel=by-dev-rootcoord-dml_0_453241817591190296v0]"]
[2024/10/15 20:20:32.860 +00:00] [INFO] [datacoord/index_service.go:513] ["receive DescribeIndex request"] [traceID=88c3a58c3da7094b3b8e9dfb8fe6c052] [collectionID=453241817591190296] [indexName=] [timestamp=0]
[2024/10/15 20:20:32.860 +00:00] [INFO] [datacoord/index_service.go:449] ["completeIndexInfo success"] [collectionID=453241817591190296] [indexID=453241817591190327] [totalRows=0] [indexRows=0] [pendingIndexRows=0] [state=Finished] [failReason=]
[2024/10/15 20:20:32.861 +00:00] [INFO] [datacoord/index_service.go:560] ["DescribeIndex success"] [traceID=88c3a58c3da7094b3b8e9dfb8fe6c052] [collectionID=453241817591190296] [indexName=]
[2024/10/15 20:20:32.861 +00:00] [INFO] [querynodev2/services.go:1366] ["sync action"] [traceID=7facea9d58e9aa51593dc597efa9fed5] [collectionID=453241817591190296] [channel=by-dev-rootcoord-dml_0_453241817591190296v0] [currentNodeID=42] [Action=UpdateVersion] [TargetVersion=1728994822861653173]
[2024/10/15 20:20:32.861 +00:00] [INFO] [delegator/distribution.go:294] ["Update readable segment version"] [oldVersion=1728994812860999121] [newVersion=1728994822861653173] [growingSegmentNum=0] [sealedSegmentNum=0]
[2024/10/15 20:20:32.861 +00:00] [INFO] [observers/target_observer.go:473] ["observer trigger update current target"] [collectionID=453241817591190296]
[2024/10/15 20:20:32.861 +00:00] [INFO] [datacoord/services.go:757] ["get recovery info request received"] [traceID=a5e5f67b0ceb93f524085267d86405ff] [collectionID=453241817591190296] [partitionIDs="[]"]
[2024/10/15 20:20:32.862 +00:00] [INFO] [datacoord/handler.go:116] [GetQueryVChanPositions] [collectionID=453241817591190296] [channel=by-dev-rootcoord-dml_0_453241817591190296v0] [numOfSegments=1] ["indexed segment"=0]
[2024/10/15 20:20:32.862 +00:00] [INFO] [datacoord/handler.go:281] ["segment position not found, setting channel seek position to channel start position"] [channel=by-dev-rootcoord-dml_0_453241817591190296v0] [posTs=453242054227263491] [posTime=2024/10/15 08:33:44.927 +00:00]
[2024/10/15 20:20:32.862 +00:00] [INFO] [datacoord/handler.go:315] ["channel seek position set from collection start position"] [channel=by-dev-rootcoord-dml_0_453241817591190296v0] [posTs=453242054227263491] [posTime=2024/10/15 08:33:44.927 +00:00]
[2024/10/15 20:20:32.862 +00:00] [INFO] [datacoord/services.go:772] ["datacoord append channelInfo in GetRecoveryInfo"] [traceID=a5e5f67b0ceb93f524085267d86405ff] [collectionID=453241817591190296] [partitionIDs="[]"] [channel=by-dev-rootcoord-dml_0_453241817591190296v0] ["# of unflushed segments"=0] ["# of flushed segments"=0] ["# of dropped segments"=0] ["# of indexed segments"=0]
[2024/10/15 20:20:33.152 +00:00] [WARN] [kafka/kafka_consumer.go:138] ["consume msg failed"] [topic=by-dev-rootcoord-dml_4] [groupID=datanode-42-by-dev-rootcoord-dml_4_450590887436092108v0-true] [error="Local: Timed out"]
[2024/10/15 20:20:33.829 +00:00] [INFO] [grpclog/grpclog.go:37] ["[transport]transport: closing: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:20:33.829 +00:00] [INFO] [grpclog/grpclog.go:37] ["[core][Server #10] grpc: Server.Serve failed to create ServerTransport: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:20:33.923 +00:00] [INFO] [grpclog/grpclog.go:37] ["[transport]transport: closing: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:20:33.923 +00:00] [INFO] [grpclog/grpclog.go:37] ["[core][Server #22] grpc: Server.Serve failed to create ServerTransport: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:20:34.355 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=ms] [collections="[]"]
[2024/10/15 20:20:34.355 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=default] [collections="[ssss]"]
[2024/10/15 20:20:35.070 +00:00] [INFO] [grpclog/grpclog.go:37] ["[transport]transport: closing: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:20:35.070 +00:00] [INFO] [grpclog/grpclog.go:37] ["[core][Server #18] grpc: Server.Serve failed to create ServerTransport: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:20:35.668 +00:00] [INFO] [datacoord/session_manager.go:322] ["SessionManagerImpl.FlushChannels start"] [traceID=925c0caa4652e949d5e69f2264e1cc62] [nodeID=42] [flushTs=2024/10/15 20:18:20.577 +00:00] [channels="[by-dev-rootcoord-dml_0_453241817591190296v0]"]
[2024/10/15 20:20:35.668 +00:00] [INFO] [datanode/services.go:550] ["DataNode receives FlushChannels request"] [traceID=925c0caa4652e949d5e69f2264e1cc62] [nodeId=42] [flushTs=2024/10/15 20:18:20.577 +00:00] [channels="[by-dev-rootcoord-dml_0_453241817591190296v0]"]
[2024/10/15 20:20:35.668 +00:00] [WARN] [datacoord/session_manager.go:326] ["SessionManagerImpl.FlushChannels failed"] [traceID=925c0caa4652e949d5e69f2264e1cc62] [nodeID=42] [flushTs=2024/10/15 20:18:20.577 +00:00] [channels="[by-dev-rootcoord-dml_0_453241817591190296v0]"] [error="channel not found[channel=by-dev-rootcoord-dml_0_453241817591190296v0]"]
[2024/10/15 20:20:35.668 +00:00] [ERROR] [retry/retry.go:46] ["retry func failed"] [traceID=925c0caa4652e949d5e69f2264e1cc62] ["retry time"=48] [error="channel not found[channel=by-dev-rootcoord-dml_0_453241817591190296v0]"] [stack="github.com/milvus-io/milvus/pkg/util/retry.Do\n\t/go/src/github.com/milvus-io/milvus/pkg/util/retry/retry.go:46\ngithub.com/milvus-io/milvus/internal/datacoord.(*Server).Flush\n\t/go/src/github.com/milvus-io/milvus/internal/datacoord/services.go:116\ngithub.com/milvus-io/milvus/internal/distributed/datacoord.(*Server).Flush\n\t/go/src/github.com/milvus-io/milvus/internal/distributed/datacoord/service.go:283\ngithub.com/milvus-io/milvus/internal/proto/datapb._DataCoord_Flush_Handler.func1\n\t/go/src/github.com/milvus-io/milvus/internal/proto/datapb/data_coord.pb.go:6536\ngithub.com/milvus-io/milvus/pkg/util/interceptor.ServerIDValidationUnaryServerInterceptor.func1\n\t/go/src/github.com/milvus-io/milvus/pkg/util/interceptor/server_id_interceptor.go:54\ngithub.com/grpc-ecosystem/go-grpc-middleware.ChainUnaryServer.func1.1.1\n\t/go/pkg/mod/github.com/grpc-ecosystem/[email protected]/chain.go:25\ngithub.com/milvus-io/milvus/pkg/util/interceptor.ClusterValidationUnaryServerInterceptor.func1\n\t/go/src/github.com/milvus-io/milvus/pkg/util/interceptor/cluster_interceptor.go:48\ngithub.com/grpc-ecosystem/go-grpc-middleware.ChainUnaryServer.func1.1.1\n\t/go/pkg/mod/github.com/grpc-ecosystem/[email protected]/chain.go:25\ngithub.com/milvus-io/milvus/pkg/util/logutil.UnaryTraceLoggerInterceptor\n\t/go/src/github.com/milvus-io/milvus/pkg/util/logutil/grpc_interceptor.go:23\ngithub.com/grpc-ecosystem/go-grpc-middleware.ChainUnaryServer.func1.1.1\n\t/go/pkg/mod/github.com/grpc-ecosystem/[email protected]/chain.go:25\ngo.opentelemetry.io/contrib/instrumentation/google.golang.org/grpc/otelgrpc.UnaryServerInterceptor.func1\n\t/go/pkg/mod/go.opentelemetry.io/contrib/instrumentation/google.golang.org/grpc/[email protected]/interceptor.go:342\ngithub.com/grpc-ecosystem/go-grpc-middleware.ChainUnaryServer.func1.1.1\n\t/go/pkg/mod/github.com/grpc-ecosystem/[email protected]/chain.go:25\ngithub.com/grpc-ecosystem/go-grpc-middleware.ChainUnaryServer.func1\n\t/go/pkg/mod/github.com/grpc-ecosystem/[email protected]/chain.go:34\ngithub.com/milvus-io/milvus/internal/proto/datapb._DataCoord_Flush_Handler\n\t/go/src/github.com/milvus-io/milvus/internal/proto/datapb/data_coord.pb.go:6538\ngoogle.golang.org/grpc.(*Server).processUnaryRPC\n\t/go/pkg/mod/google.golang.org/[email protected]/server.go:1345\ngoogle.golang.org/grpc.(*Server).handleStream\n\t/go/pkg/mod/google.golang.org/[email protected]/server.go:1722\ngoogle.golang.org/grpc.(*Server).serveStreams.func1.2\n\t/go/pkg/mod/google.golang.org/[email protected]/server.go:966"]
[2024/10/15 20:20:36.281 +00:00] [WARN] [rootcoord/quota_center.go:742] ["failed to get collection rate limit config"] [collectionID=450591213173465433] [error="collection not found[collection=450591213173465433]"]
[2024/10/15 20:20:37.356 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=ms] [collections="[]"]
[2024/10/15 20:20:37.356 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=default] [collections="[ssss]"]
[2024/10/15 20:20:38.670 +00:00] [INFO] [datacoord/session_manager.go:322] ["SessionManagerImpl.FlushChannels start"] [traceID=925c0caa4652e949d5e69f2264e1cc62] [nodeID=42] [flushTs=2024/10/15 20:18:20.577 +00:00] [channels="[by-dev-rootcoord-dml_0_453241817591190296v0]"]
[2024/10/15 20:20:38.670 +00:00] [INFO] [datanode/services.go:550] ["DataNode receives FlushChannels request"] [traceID=925c0caa4652e949d5e69f2264e1cc62] [nodeId=42] [flushTs=2024/10/15 20:18:20.577 +00:00] [channels="[by-dev-rootcoord-dml_0_453241817591190296v0]"]
[2024/10/15 20:20:38.670 +00:00] [WARN] [datacoord/session_manager.go:326] ["SessionManagerImpl.FlushChannels failed"] [traceID=925c0caa4652e949d5e69f2264e1cc62] [nodeID=42] [flushTs=2024/10/15 20:18:20.577 +00:00] [channels="[by-dev-rootcoord-dml_0_453241817591190296v0]"] [error="channel not found[channel=by-dev-rootcoord-dml_0_453241817591190296v0]"]
[2024/10/15 20:20:39.580 +00:00] [INFO] [grpclog/grpclog.go:37] ["[transport]transport: closing: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:20:39.580 +00:00] [INFO] [grpclog/grpclog.go:37] ["[core][Server #28] grpc: Server.Serve failed to create ServerTransport: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[GIN] 2024/10/15 - 12:20:39 | 404 |      10.077µs |  192.168.24.124 | GET      "/metrics"
[2024/10/15 20:20:40.355 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=ms] [collections="[]"]
[2024/10/15 20:20:40.355 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=default] [collections="[ssss]"]
[2024/10/15 20:20:41.001 +00:00] [INFO] [grpclog/grpclog.go:37] ["[transport]transport: closing: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:20:41.001 +00:00] [INFO] [grpclog/grpclog.go:37] ["[core][Server #6] grpc: Server.Serve failed to create ServerTransport: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:20:41.671 +00:00] [INFO] [datacoord/session_manager.go:322] ["SessionManagerImpl.FlushChannels start"] [traceID=925c0caa4652e949d5e69f2264e1cc62] [nodeID=42] [flushTs=2024/10/15 20:18:20.577 +00:00] [channels="[by-dev-rootcoord-dml_0_453241817591190296v0]"]
[2024/10/15 20:20:41.671 +00:00] [INFO] [datanode/services.go:550] ["DataNode receives FlushChannels request"] [traceID=925c0caa4652e949d5e69f2264e1cc62] [nodeId=42] [flushTs=2024/10/15 20:18:20.577 +00:00] [channels="[by-dev-rootcoord-dml_0_453241817591190296v0]"]
[2024/10/15 20:20:41.671 +00:00] [WARN] [datacoord/session_manager.go:326] ["SessionManagerImpl.FlushChannels failed"] [traceID=925c0caa4652e949d5e69f2264e1cc62] [nodeID=42] [flushTs=2024/10/15 20:18:20.577 +00:00] [channels="[by-dev-rootcoord-dml_0_453241817591190296v0]"] [error="channel not found[channel=by-dev-rootcoord-dml_0_453241817591190296v0]"]
[2024/10/15 20:20:42.859 +00:00] [INFO] [datacoord/index_service.go:513] ["receive DescribeIndex request"] [traceID=15fdb7431466c5216e2a7221a6ffe7ce] [collectionID=453241817591190296] [indexName=] [timestamp=0]
[2024/10/15 20:20:42.860 +00:00] [INFO] [datacoord/index_service.go:449] ["completeIndexInfo success"] [collectionID=453241817591190296] [indexID=453241817591190327] [totalRows=0] [indexRows=0] [pendingIndexRows=0] [state=Finished] [failReason=]
[2024/10/15 20:20:42.860 +00:00] [INFO] [datacoord/index_service.go:560] ["DescribeIndex success"] [traceID=15fdb7431466c5216e2a7221a6ffe7ce] [collectionID=453241817591190296] [indexName=]
[2024/10/15 20:20:42.860 +00:00] [INFO] [querynodev2/services.go:1366] ["sync action"] [traceID=64d7ae11658e6d53ab5699838310b196] [collectionID=453241817591190296] [channel=by-dev-rootcoord-dml_0_453241817591190296v0] [currentNodeID=42] [Action=UpdateVersion] [TargetVersion=1728994832861734815]
[2024/10/15 20:20:42.860 +00:00] [INFO] [delegator/distribution.go:294] ["Update readable segment version"] [oldVersion=1728994822861653173] [newVersion=1728994832861734815] [growingSegmentNum=0] [sealedSegmentNum=0]
[2024/10/15 20:20:42.860 +00:00] [INFO] [observers/target_observer.go:473] ["observer trigger update current target"] [collectionID=453241817591190296]
[2024/10/15 20:20:42.861 +00:00] [INFO] [datacoord/services.go:757] ["get recovery info request received"] [traceID=3bfafd0779414f04e553ad3bf0652f51] [collectionID=453241817591190296] [partitionIDs="[]"]
[2024/10/15 20:20:42.861 +00:00] [INFO] [datacoord/handler.go:116] [GetQueryVChanPositions] [collectionID=453241817591190296] [channel=by-dev-rootcoord-dml_0_453241817591190296v0] [numOfSegments=1] ["indexed segment"=0]
[2024/10/15 20:20:42.861 +00:00] [INFO] [datacoord/handler.go:281] ["segment position not found, setting channel seek position to channel start position"] [channel=by-dev-rootcoord-dml_0_453241817591190296v0] [posTs=453242054227263491] [posTime=2024/10/15 08:33:44.927 +00:00]
[2024/10/15 20:20:42.861 +00:00] [INFO] [datacoord/handler.go:315] ["channel seek position set from collection start position"] [channel=by-dev-rootcoord-dml_0_453241817591190296v0] [posTs=453242054227263491] [posTime=2024/10/15 08:33:44.927 +00:00]
[2024/10/15 20:20:42.861 +00:00] [INFO] [datacoord/services.go:772] ["datacoord append channelInfo in GetRecoveryInfo"] [traceID=3bfafd0779414f04e553ad3bf0652f51] [collectionID=453241817591190296] [partitionIDs="[]"] [channel=by-dev-rootcoord-dml_0_453241817591190296v0] ["# of unflushed segments"=0] ["# of flushed segments"=0] ["# of dropped segments"=0] ["# of indexed segments"=0]
[2024/10/15 20:20:43.084 +00:00] [INFO] [grpclog/grpclog.go:37] ["[transport]transport: closing: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:20:43.084 +00:00] [INFO] [grpclog/grpclog.go:37] ["[core][Server #14] grpc: Server.Serve failed to create ServerTransport: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:20:43.152 +00:00] [WARN] [kafka/kafka_consumer.go:138] ["consume msg failed"] [topic=by-dev-rootcoord-dml_4] [groupID=datanode-42-by-dev-rootcoord-dml_4_450590887436092108v0-true] [error="Local: Timed out"]
[2024/10/15 20:20:43.356 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=ms] [collections="[]"]
[2024/10/15 20:20:43.356 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=default] [collections="[ssss]"]
[2024/10/15 20:20:44.672 +00:00] [INFO] [datacoord/session_manager.go:322] ["SessionManagerImpl.FlushChannels start"] [traceID=925c0caa4652e949d5e69f2264e1cc62] [nodeID=42] [flushTs=2024/10/15 20:18:20.577 +00:00] [channels="[by-dev-rootcoord-dml_0_453241817591190296v0]"]
[2024/10/15 20:20:44.672 +00:00] [INFO] [datanode/services.go:550] ["DataNode receives FlushChannels request"] [traceID=925c0caa4652e949d5e69f2264e1cc62] [nodeId=42] [flushTs=2024/10/15 20:18:20.577 +00:00] [channels="[by-dev-rootcoord-dml_0_453241817591190296v0]"]
[2024/10/15 20:20:44.672 +00:00] [WARN] [datacoord/session_manager.go:326] ["SessionManagerImpl.FlushChannels failed"] [traceID=925c0caa4652e949d5e69f2264e1cc62] [nodeID=42] [flushTs=2024/10/15 20:18:20.577 +00:00] [channels="[by-dev-rootcoord-dml_0_453241817591190296v0]"] [error="channel not found[channel=by-dev-rootcoord-dml_0_453241817591190296v0]"]
[2024/10/15 20:20:46.356 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=ms] [collections="[]"]
[2024/10/15 20:20:46.356 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=default] [collections="[ssss]"]
[2024/10/15 20:20:47.673 +00:00] [INFO] [datacoord/session_manager.go:322] ["SessionManagerImpl.FlushChannels start"] [traceID=925c0caa4652e949d5e69f2264e1cc62] [nodeID=42] [flushTs=2024/10/15 20:18:20.577 +00:00] [channels="[by-dev-rootcoord-dml_0_453241817591190296v0]"]
[2024/10/15 20:20:47.673 +00:00] [INFO] [datanode/services.go:550] ["DataNode receives FlushChannels request"] [traceID=925c0caa4652e949d5e69f2264e1cc62] [nodeId=42] [flushTs=2024/10/15 20:18:20.577 +00:00] [channels="[by-dev-rootcoord-dml_0_453241817591190296v0]"]
[2024/10/15 20:20:47.674 +00:00] [WARN] [datacoord/session_manager.go:326] ["SessionManagerImpl.FlushChannels failed"] [traceID=925c0caa4652e949d5e69f2264e1cc62] [nodeID=42] [flushTs=2024/10/15 20:18:20.577 +00:00] [channels="[by-dev-rootcoord-dml_0_453241817591190296v0]"] [error="channel not found[channel=by-dev-rootcoord-dml_0_453241817591190296v0]"]
[2024/10/15 20:20:47.674 +00:00] [ERROR] [retry/retry.go:46] ["retry func failed"] [traceID=925c0caa4652e949d5e69f2264e1cc62] ["retry time"=52] [error="channel not found[channel=by-dev-rootcoord-dml_0_453241817591190296v0]"] [stack="github.com/milvus-io/milvus/pkg/util/retry.Do\n\t/go/src/github.com/milvus-io/milvus/pkg/util/retry/retry.go:46\ngithub.com/milvus-io/milvus/internal/datacoord.(*Server).Flush\n\t/go/src/github.com/milvus-io/milvus/internal/datacoord/services.go:116\ngithub.com/milvus-io/milvus/internal/distributed/datacoord.(*Server).Flush\n\t/go/src/github.com/milvus-io/milvus/internal/distributed/datacoord/service.go:283\ngithub.com/milvus-io/milvus/internal/proto/datapb._DataCoord_Flush_Handler.func1\n\t/go/src/github.com/milvus-io/milvus/internal/proto/datapb/data_coord.pb.go:6536\ngithub.com/milvus-io/milvus/pkg/util/interceptor.ServerIDValidationUnaryServerInterceptor.func1\n\t/go/src/github.com/milvus-io/milvus/pkg/util/interceptor/server_id_interceptor.go:54\ngithub.com/grpc-ecosystem/go-grpc-middleware.ChainUnaryServer.func1.1.1\n\t/go/pkg/mod/github.com/grpc-ecosystem/[email protected]/chain.go:25\ngithub.com/milvus-io/milvus/pkg/util/interceptor.ClusterValidationUnaryServerInterceptor.func1\n\t/go/src/github.com/milvus-io/milvus/pkg/util/interceptor/cluster_interceptor.go:48\ngithub.com/grpc-ecosystem/go-grpc-middleware.ChainUnaryServer.func1.1.1\n\t/go/pkg/mod/github.com/grpc-ecosystem/[email protected]/chain.go:25\ngithub.com/milvus-io/milvus/pkg/util/logutil.UnaryTraceLoggerInterceptor\n\t/go/src/github.com/milvus-io/milvus/pkg/util/logutil/grpc_interceptor.go:23\ngithub.com/grpc-ecosystem/go-grpc-middleware.ChainUnaryServer.func1.1.1\n\t/go/pkg/mod/github.com/grpc-ecosystem/[email protected]/chain.go:25\ngo.opentelemetry.io/contrib/instrumentation/google.golang.org/grpc/otelgrpc.UnaryServerInterceptor.func1\n\t/go/pkg/mod/go.opentelemetry.io/contrib/instrumentation/google.golang.org/grpc/[email protected]/interceptor.go:342\ngithub.com/grpc-ecosystem/go-grpc-middleware.ChainUnaryServer.func1.1.1\n\t/go/pkg/mod/github.com/grpc-ecosystem/[email protected]/chain.go:25\ngithub.com/grpc-ecosystem/go-grpc-middleware.ChainUnaryServer.func1\n\t/go/pkg/mod/github.com/grpc-ecosystem/[email protected]/chain.go:34\ngithub.com/milvus-io/milvus/internal/proto/datapb._DataCoord_Flush_Handler\n\t/go/src/github.com/milvus-io/milvus/internal/proto/datapb/data_coord.pb.go:6538\ngoogle.golang.org/grpc.(*Server).processUnaryRPC\n\t/go/pkg/mod/google.golang.org/[email protected]/server.go:1345\ngoogle.golang.org/grpc.(*Server).handleStream\n\t/go/pkg/mod/google.golang.org/[email protected]/server.go:1722\ngoogle.golang.org/grpc.(*Server).serveStreams.func1.2\n\t/go/pkg/mod/google.golang.org/[email protected]/server.go:966"]
[2024/10/15 20:20:48.281 +00:00] [WARN] [rootcoord/quota_center.go:742] ["failed to get collection rate limit config"] [collectionID=450591213173465433] [error="collection not found[collection=450591213173465433]"]
[2024/10/15 20:20:48.828 +00:00] [INFO] [grpclog/grpclog.go:37] ["[transport]transport: closing: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:20:48.828 +00:00] [INFO] [grpclog/grpclog.go:37] ["[core][Server #10] grpc: Server.Serve failed to create ServerTransport: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:20:48.924 +00:00] [INFO] [grpclog/grpclog.go:37] ["[transport]transport: closing: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:20:48.924 +00:00] [INFO] [grpclog/grpclog.go:37] ["[core][Server #22] grpc: Server.Serve failed to create ServerTransport: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:20:49.356 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=ms] [collections="[]"]
[2024/10/15 20:20:49.356 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=default] [collections="[ssss]"]
[2024/10/15 20:20:50.070 +00:00] [INFO] [grpclog/grpclog.go:37] ["[transport]transport: closing: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:20:50.070 +00:00] [INFO] [grpclog/grpclog.go:37] ["[core][Server #18] grpc: Server.Serve failed to create ServerTransport: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:20:50.674 +00:00] [INFO] [datacoord/session_manager.go:322] ["SessionManagerImpl.FlushChannels start"] [traceID=925c0caa4652e949d5e69f2264e1cc62] [nodeID=42] [flushTs=2024/10/15 20:18:20.577 +00:00] [channels="[by-dev-rootcoord-dml_0_453241817591190296v0]"]
[2024/10/15 20:20:50.675 +00:00] [INFO] [datanode/services.go:550] ["DataNode receives FlushChannels request"] [traceID=925c0caa4652e949d5e69f2264e1cc62] [nodeId=42] [flushTs=2024/10/15 20:18:20.577 +00:00] [channels="[by-dev-rootcoord-dml_0_453241817591190296v0]"]
[2024/10/15 20:20:50.675 +00:00] [WARN] [datacoord/session_manager.go:326] ["SessionManagerImpl.FlushChannels failed"] [traceID=925c0caa4652e949d5e69f2264e1cc62] [nodeID=42] [flushTs=2024/10/15 20:18:20.577 +00:00] [channels="[by-dev-rootcoord-dml_0_453241817591190296v0]"] [error="channel not found[channel=by-dev-rootcoord-dml_0_453241817591190296v0]"]
[2024/10/15 20:20:52.356 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=ms] [collections="[]"]
[2024/10/15 20:20:52.356 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=default] [collections="[ssss]"]
[2024/10/15 20:20:52.860 +00:00] [INFO] [datacoord/index_service.go:513] ["receive DescribeIndex request"] [traceID=618a55e25cba3fc9f276ab8b078c9a4d] [collectionID=453241817591190296] [indexName=] [timestamp=0]
[2024/10/15 20:20:52.860 +00:00] [INFO] [datacoord/index_service.go:449] ["completeIndexInfo success"] [collectionID=453241817591190296] [indexID=453241817591190327] [totalRows=0] [indexRows=0] [pendingIndexRows=0] [state=Finished] [failReason=]
[2024/10/15 20:20:52.861 +00:00] [INFO] [datacoord/index_service.go:560] ["DescribeIndex success"] [traceID=618a55e25cba3fc9f276ab8b078c9a4d] [collectionID=453241817591190296] [indexName=]
[2024/10/15 20:20:52.861 +00:00] [INFO] [querynodev2/services.go:1366] ["sync action"] [traceID=fe134a3f1899c3013661cc487ed32652] [collectionID=453241817591190296] [channel=by-dev-rootcoord-dml_0_453241817591190296v0] [currentNodeID=42] [Action=UpdateVersion] [TargetVersion=1728994842860900363]
[2024/10/15 20:20:52.861 +00:00] [INFO] [delegator/distribution.go:294] ["Update readable segment version"] [oldVersion=1728994832861734815] [newVersion=1728994842860900363] [growingSegmentNum=0] [sealedSegmentNum=0]
[2024/10/15 20:20:52.861 +00:00] [INFO] [observers/target_observer.go:473] ["observer trigger update current target"] [collectionID=453241817591190296]
[2024/10/15 20:20:52.862 +00:00] [INFO] [datacoord/services.go:757] ["get recovery info request received"] [traceID=bdbc6ff1c8a31e966d23807e40c0403a] [collectionID=453241817591190296] [partitionIDs="[]"]
[2024/10/15 20:20:52.862 +00:00] [INFO] [datacoord/handler.go:116] [GetQueryVChanPositions] [collectionID=453241817591190296] [channel=by-dev-rootcoord-dml_0_453241817591190296v0] [numOfSegments=1] ["indexed segment"=0]
[2024/10/15 20:20:52.862 +00:00] [INFO] [datacoord/handler.go:281] ["segment position not found, setting channel seek position to channel start position"] [channel=by-dev-rootcoord-dml_0_453241817591190296v0] [posTs=453242054227263491] [posTime=2024/10/15 08:33:44.927 +00:00]
[2024/10/15 20:20:52.862 +00:00] [INFO] [datacoord/handler.go:315] ["channel seek position set from collection start position"] [channel=by-dev-rootcoord-dml_0_453241817591190296v0] [posTs=453242054227263491] [posTime=2024/10/15 08:33:44.927 +00:00]
[2024/10/15 20:20:52.862 +00:00] [INFO] [datacoord/services.go:772] ["datacoord append channelInfo in GetRecoveryInfo"] [traceID=bdbc6ff1c8a31e966d23807e40c0403a] [collectionID=453241817591190296] [partitionIDs="[]"] [channel=by-dev-rootcoord-dml_0_453241817591190296v0] ["# of unflushed segments"=0] ["# of flushed segments"=0] ["# of dropped segments"=0] ["# of indexed segments"=0]
[2024/10/15 20:20:53.153 +00:00] [WARN] [kafka/kafka_consumer.go:138] ["consume msg failed"] [topic=by-dev-rootcoord-dml_4] [groupID=datanode-42-by-dev-rootcoord-dml_4_450590887436092108v0-true] [error="Local: Timed out"]
[2024/10/15 20:20:53.675 +00:00] [INFO] [datacoord/session_manager.go:322] ["SessionManagerImpl.FlushChannels start"] [traceID=925c0caa4652e949d5e69f2264e1cc62] [nodeID=42] [flushTs=2024/10/15 20:18:20.577 +00:00] [channels="[by-dev-rootcoord-dml_0_453241817591190296v0]"]
[2024/10/15 20:20:53.676 +00:00] [INFO] [datanode/services.go:550] ["DataNode receives FlushChannels request"] [traceID=925c0caa4652e949d5e69f2264e1cc62] [nodeId=42] [flushTs=2024/10/15 20:18:20.577 +00:00] [channels="[by-dev-rootcoord-dml_0_453241817591190296v0]"]
[2024/10/15 20:20:53.676 +00:00] [WARN] [datacoord/session_manager.go:326] ["SessionManagerImpl.FlushChannels failed"] [traceID=925c0caa4652e949d5e69f2264e1cc62] [nodeID=42] [flushTs=2024/10/15 20:18:20.577 +00:00] [channels="[by-dev-rootcoord-dml_0_453241817591190296v0]"] [error="channel not found[channel=by-dev-rootcoord-dml_0_453241817591190296v0]"]
[2024/10/15 20:20:54.579 +00:00] [INFO] [grpclog/grpclog.go:37] ["[transport]transport: closing: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:20:54.579 +00:00] [INFO] [grpclog/grpclog.go:37] ["[core][Server #28] grpc: Server.Serve failed to create ServerTransport: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[GIN] 2024/10/15 - 12:20:54 | 404 |      12.403µs |  192.168.24.124 | GET      "/metrics"
[2024/10/15 20:20:55.356 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=ms] [collections="[]"]
[2024/10/15 20:20:55.356 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=default] [collections="[ssss]"]
[2024/10/15 20:20:56.001 +00:00] [INFO] [grpclog/grpclog.go:37] ["[transport]transport: closing: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:20:56.001 +00:00] [INFO] [grpclog/grpclog.go:37] ["[core][Server #6] grpc: Server.Serve failed to create ServerTransport: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:20:56.677 +00:00] [INFO] [datacoord/session_manager.go:322] ["SessionManagerImpl.FlushChannels start"] [traceID=925c0caa4652e949d5e69f2264e1cc62] [nodeID=42] [flushTs=2024/10/15 20:18:20.577 +00:00] [channels="[by-dev-rootcoord-dml_0_453241817591190296v0]"]
[2024/10/15 20:20:56.677 +00:00] [INFO] [datanode/services.go:550] ["DataNode receives FlushChannels request"] [traceID=925c0caa4652e949d5e69f2264e1cc62] [nodeId=42] [flushTs=2024/10/15 20:18:20.577 +00:00] [channels="[by-dev-rootcoord-dml_0_453241817591190296v0]"]
[2024/10/15 20:20:56.677 +00:00] [WARN] [datacoord/session_manager.go:326] ["SessionManagerImpl.FlushChannels failed"] [traceID=925c0caa4652e949d5e69f2264e1cc62] [nodeID=42] [flushTs=2024/10/15 20:18:20.577 +00:00] [channels="[by-dev-rootcoord-dml_0_453241817591190296v0]"] [error="channel not found[channel=by-dev-rootcoord-dml_0_453241817591190296v0]"]
[2024/10/15 20:20:57.281 +00:00] [WARN] [rootcoord/quota_center.go:742] ["failed to get collection rate limit config"] [collectionID=452761450472627529] [error="collection not found[collection=452761450472627529]"]
[2024/10/15 20:20:58.084 +00:00] [INFO] [grpclog/grpclog.go:37] ["[transport]transport: closing: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:20:58.084 +00:00] [INFO] [grpclog/grpclog.go:37] ["[core][Server #14] grpc: Server.Serve failed to create ServerTransport: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:20:58.356 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=ms] [collections="[]"]
[2024/10/15 20:20:58.356 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=default] [collections="[ssss]"]
[2024/10/15 20:20:59.678 +00:00] [INFO] [datacoord/session_manager.go:322] ["SessionManagerImpl.FlushChannels start"] [traceID=925c0caa4652e949d5e69f2264e1cc62] [nodeID=42] [flushTs=2024/10/15 20:18:20.577 +00:00] [channels="[by-dev-rootcoord-dml_0_453241817591190296v0]"]
[2024/10/15 20:20:59.678 +00:00] [INFO] [datanode/services.go:550] ["DataNode receives FlushChannels request"] [traceID=925c0caa4652e949d5e69f2264e1cc62] [nodeId=42] [flushTs=2024/10/15 20:18:20.577 +00:00] [channels="[by-dev-rootcoord-dml_0_453241817591190296v0]"]
[2024/10/15 20:20:59.678 +00:00] [WARN] [datacoord/session_manager.go:326] ["SessionManagerImpl.FlushChannels failed"] [traceID=925c0caa4652e949d5e69f2264e1cc62] [nodeID=42] [flushTs=2024/10/15 20:18:20.577 +00:00] [channels="[by-dev-rootcoord-dml_0_453241817591190296v0]"] [error="channel not found[channel=by-dev-rootcoord-dml_0_453241817591190296v0]"]
[2024/10/15 20:20:59.678 +00:00] [ERROR] [retry/retry.go:46] ["retry func failed"] [traceID=925c0caa4652e949d5e69f2264e1cc62] ["retry time"=56] [error="channel not found[channel=by-dev-rootcoord-dml_0_453241817591190296v0]"] [stack="github.com/milvus-io/milvus/pkg/util/retry.Do\n\t/go/src/github.com/milvus-io/milvus/pkg/util/retry/retry.go:46\ngithub.com/milvus-io/milvus/internal/datacoord.(*Server).Flush\n\t/go/src/github.com/milvus-io/milvus/internal/datacoord/services.go:116\ngithub.com/milvus-io/milvus/internal/distributed/datacoord.(*Server).Flush\n\t/go/src/github.com/milvus-io/milvus/internal/distributed/datacoord/service.go:283\ngithub.com/milvus-io/milvus/internal/proto/datapb._DataCoord_Flush_Handler.func1\n\t/go/src/github.com/milvus-io/milvus/internal/proto/datapb/data_coord.pb.go:6536\ngithub.com/milvus-io/milvus/pkg/util/interceptor.ServerIDValidationUnaryServerInterceptor.func1\n\t/go/src/github.com/milvus-io/milvus/pkg/util/interceptor/server_id_interceptor.go:54\ngithub.com/grpc-ecosystem/go-grpc-middleware.ChainUnaryServer.func1.1.1\n\t/go/pkg/mod/github.com/grpc-ecosystem/[email protected]/chain.go:25\ngithub.com/milvus-io/milvus/pkg/util/interceptor.ClusterValidationUnaryServerInterceptor.func1\n\t/go/src/github.com/milvus-io/milvus/pkg/util/interceptor/cluster_interceptor.go:48\ngithub.com/grpc-ecosystem/go-grpc-middleware.ChainUnaryServer.func1.1.1\n\t/go/pkg/mod/github.com/grpc-ecosystem/[email protected]/chain.go:25\ngithub.com/milvus-io/milvus/pkg/util/logutil.UnaryTraceLoggerInterceptor\n\t/go/src/github.com/milvus-io/milvus/pkg/util/logutil/grpc_interceptor.go:23\ngithub.com/grpc-ecosystem/go-grpc-middleware.ChainUnaryServer.func1.1.1\n\t/go/pkg/mod/github.com/grpc-ecosystem/[email protected]/chain.go:25\ngo.opentelemetry.io/contrib/instrumentation/google.golang.org/grpc/otelgrpc.UnaryServerInterceptor.func1\n\t/go/pkg/mod/go.opentelemetry.io/contrib/instrumentation/google.golang.org/grpc/[email protected]/interceptor.go:342\ngithub.com/grpc-ecosystem/go-grpc-middleware.ChainUnaryServer.func1.1.1\n\t/go/pkg/mod/github.com/grpc-ecosystem/[email protected]/chain.go:25\ngithub.com/grpc-ecosystem/go-grpc-middleware.ChainUnaryServer.func1\n\t/go/pkg/mod/github.com/grpc-ecosystem/[email protected]/chain.go:34\ngithub.com/milvus-io/milvus/internal/proto/datapb._DataCoord_Flush_Handler\n\t/go/src/github.com/milvus-io/milvus/internal/proto/datapb/data_coord.pb.go:6538\ngoogle.golang.org/grpc.(*Server).processUnaryRPC\n\t/go/pkg/mod/google.golang.org/[email protected]/server.go:1345\ngoogle.golang.org/grpc.(*Server).handleStream\n\t/go/pkg/mod/google.golang.org/[email protected]/server.go:1722\ngoogle.golang.org/grpc.(*Server).serveStreams.func1.2\n\t/go/pkg/mod/google.golang.org/[email protected]/server.go:966"]
[2024/10/15 20:21:01.356 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=ms] [collections="[]"]
[2024/10/15 20:21:01.356 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=default] [collections="[ssss]"]
[2024/10/15 20:21:02.680 +00:00] [INFO] [datacoord/session_manager.go:322] ["SessionManagerImpl.FlushChannels start"] [traceID=925c0caa4652e949d5e69f2264e1cc62] [nodeID=42] [flushTs=2024/10/15 20:18:20.577 +00:00] [channels="[by-dev-rootcoord-dml_0_453241817591190296v0]"]
[2024/10/15 20:21:02.680 +00:00] [INFO] [datanode/services.go:550] ["DataNode receives FlushChannels request"] [traceID=925c0caa4652e949d5e69f2264e1cc62] [nodeId=42] [flushTs=2024/10/15 20:18:20.577 +00:00] [channels="[by-dev-rootcoord-dml_0_453241817591190296v0]"]
[2024/10/15 20:21:02.680 +00:00] [WARN] [datacoord/session_manager.go:326] ["SessionManagerImpl.FlushChannels failed"] [traceID=925c0caa4652e949d5e69f2264e1cc62] [nodeID=42] [flushTs=2024/10/15 20:18:20.577 +00:00] [channels="[by-dev-rootcoord-dml_0_453241817591190296v0]"] [error="channel not found[channel=by-dev-rootcoord-dml_0_453241817591190296v0]"]
[2024/10/15 20:21:02.860 +00:00] [INFO] [datacoord/index_service.go:513] ["receive DescribeIndex request"] [traceID=dd94aa0a42448c9e39b2230f7c47522c] [collectionID=453241817591190296] [indexName=] [timestamp=0]
[2024/10/15 20:21:02.860 +00:00] [INFO] [datacoord/index_service.go:449] ["completeIndexInfo success"] [collectionID=453241817591190296] [indexID=453241817591190327] [totalRows=0] [indexRows=0] [pendingIndexRows=0] [state=Finished] [failReason=]
[2024/10/15 20:21:02.860 +00:00] [INFO] [datacoord/index_service.go:560] ["DescribeIndex success"] [traceID=dd94aa0a42448c9e39b2230f7c47522c] [collectionID=453241817591190296] [indexName=]
[2024/10/15 20:21:02.861 +00:00] [INFO] [querynodev2/services.go:1366] ["sync action"] [traceID=ca8ebeed7bb189b03d31ee7f5a086a43] [collectionID=453241817591190296] [channel=by-dev-rootcoord-dml_0_453241817591190296v0] [currentNodeID=42] [Action=UpdateVersion] [TargetVersion=1728994852861892079]
[2024/10/15 20:21:02.861 +00:00] [INFO] [delegator/distribution.go:294] ["Update readable segment version"] [oldVersion=1728994842860900363] [newVersion=1728994852861892079] [growingSegmentNum=0] [sealedSegmentNum=0]
[2024/10/15 20:21:02.861 +00:00] [INFO] [observers/target_observer.go:473] ["observer trigger update current target"] [collectionID=453241817591190296]
[2024/10/15 20:21:02.861 +00:00] [INFO] [datacoord/services.go:757] ["get recovery info request received"] [traceID=0327cd9fc09151754cc5109265d48076] [collectionID=453241817591190296] [partitionIDs="[]"]
[2024/10/15 20:21:02.861 +00:00] [INFO] [datacoord/handler.go:116] [GetQueryVChanPositions] [collectionID=453241817591190296] [channel=by-dev-rootcoord-dml_0_453241817591190296v0] [numOfSegments=1] ["indexed segment"=0]
[2024/10/15 20:21:02.861 +00:00] [INFO] [datacoord/handler.go:281] ["segment position not found, setting channel seek position to channel start position"] [channel=by-dev-rootcoord-dml_0_453241817591190296v0] [posTs=453242054227263491] [posTime=2024/10/15 08:33:44.927 +00:00]
[2024/10/15 20:21:02.861 +00:00] [INFO] [datacoord/handler.go:315] ["channel seek position set from collection start position"] [channel=by-dev-rootcoord-dml_0_453241817591190296v0] [posTs=453242054227263491] [posTime=2024/10/15 08:33:44.927 +00:00]
[2024/10/15 20:21:02.861 +00:00] [INFO] [datacoord/services.go:772] ["datacoord append channelInfo in GetRecoveryInfo"] [traceID=0327cd9fc09151754cc5109265d48076] [collectionID=453241817591190296] [partitionIDs="[]"] [channel=by-dev-rootcoord-dml_0_453241817591190296v0] ["# of unflushed segments"=0] ["# of flushed segments"=0] ["# of dropped segments"=0] ["# of indexed segments"=0]
[2024/10/15 20:21:03.153 +00:00] [WARN] [kafka/kafka_consumer.go:138] ["consume msg failed"] [topic=by-dev-rootcoord-dml_4] [groupID=datanode-42-by-dev-rootcoord-dml_4_450590887436092108v0-true] [error="Local: Timed out"]
[2024/10/15 20:21:03.829 +00:00] [INFO] [grpclog/grpclog.go:37] ["[transport]transport: closing: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:21:03.829 +00:00] [INFO] [grpclog/grpclog.go:37] ["[core][Server #10] grpc: Server.Serve failed to create ServerTransport: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:21:03.874 +00:00] [INFO] [gc/gc_tuner.go:90] ["GC Tune done"] ["previous GOGC"=200] ["heapuse "=85] ["total memory"=176] ["next GC"=160] ["new GOGC"=200] [gc-pause=75.019µs] [gc-pause-end=1728994863867661103]
[2024/10/15 20:21:03.924 +00:00] [INFO] [grpclog/grpclog.go:37] ["[transport]transport: closing: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:21:03.924 +00:00] [INFO] [grpclog/grpclog.go:37] ["[core][Server #22] grpc: Server.Serve failed to create ServerTransport: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:21:04.356 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=ms] [collections="[]"]
[2024/10/15 20:21:04.356 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=default] [collections="[ssss]"]
[2024/10/15 20:21:05.070 +00:00] [INFO] [grpclog/grpclog.go:37] ["[transport]transport: closing: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:21:05.070 +00:00] [INFO] [grpclog/grpclog.go:37] ["[core][Server #18] grpc: Server.Serve failed to create ServerTransport: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:21:05.680 +00:00] [INFO] [datacoord/session_manager.go:322] ["SessionManagerImpl.FlushChannels start"] [traceID=925c0caa4652e949d5e69f2264e1cc62] [nodeID=42] [flushTs=2024/10/15 20:18:20.577 +00:00] [channels="[by-dev-rootcoord-dml_0_453241817591190296v0]"]
[2024/10/15 20:21:05.681 +00:00] [INFO] [datanode/services.go:550] ["DataNode receives FlushChannels request"] [traceID=925c0caa4652e949d5e69f2264e1cc62] [nodeId=42] [flushTs=2024/10/15 20:18:20.577 +00:00] [channels="[by-dev-rootcoord-dml_0_453241817591190296v0]"]
[2024/10/15 20:21:05.681 +00:00] [WARN] [datacoord/session_manager.go:326] ["SessionManagerImpl.FlushChannels failed"] [traceID=925c0caa4652e949d5e69f2264e1cc62] [nodeID=42] [flushTs=2024/10/15 20:18:20.577 +00:00] [channels="[by-dev-rootcoord-dml_0_453241817591190296v0]"] [error="channel not found[channel=by-dev-rootcoord-dml_0_453241817591190296v0]"]
[2024/10/15 20:21:06.282 +00:00] [WARN] [rootcoord/quota_center.go:742] ["failed to get collection rate limit config"] [collectionID=450591213173465433] [error="collection not found[collection=450591213173465433]"]
[2024/10/15 20:21:07.356 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=ms] [collections="[]"]
[2024/10/15 20:21:07.356 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=default] [collections="[ssss]"]
[2024/10/15 20:21:08.681 +00:00] [INFO] [datacoord/session_manager.go:322] ["SessionManagerImpl.FlushChannels start"] [traceID=925c0caa4652e949d5e69f2264e1cc62] [nodeID=42] [flushTs=2024/10/15 20:18:20.577 +00:00] [channels="[by-dev-rootcoord-dml_0_453241817591190296v0]"]
[2024/10/15 20:21:08.682 +00:00] [INFO] [datanode/services.go:550] ["DataNode receives FlushChannels request"] [traceID=925c0caa4652e949d5e69f2264e1cc62] [nodeId=42] [flushTs=2024/10/15 20:18:20.577 +00:00] [channels="[by-dev-rootcoord-dml_0_453241817591190296v0]"]
[2024/10/15 20:21:08.682 +00:00] [WARN] [datacoord/session_manager.go:326] ["SessionManagerImpl.FlushChannels failed"] [traceID=925c0caa4652e949d5e69f2264e1cc62] [nodeID=42] [flushTs=2024/10/15 20:18:20.577 +00:00] [channels="[by-dev-rootcoord-dml_0_453241817591190296v0]"] [error="channel not found[channel=by-dev-rootcoord-dml_0_453241817591190296v0]"]
[2024/10/15 20:21:09.580 +00:00] [INFO] [grpclog/grpclog.go:37] ["[transport]transport: closing: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:21:09.580 +00:00] [INFO] [grpclog/grpclog.go:37] ["[core][Server #28] grpc: Server.Serve failed to create ServerTransport: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[GIN] 2024/10/15 - 12:21:09 | 404 |      10.528µs |  192.168.24.124 | GET      "/metrics"
[2024/10/15 20:21:10.356 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=ms] [collections="[]"]
[2024/10/15 20:21:10.356 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=default] [collections="[ssss]"]
[2024/10/15 20:21:11.000 +00:00] [INFO] [grpclog/grpclog.go:37] ["[transport]transport: closing: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:21:11.000 +00:00] [INFO] [grpclog/grpclog.go:37] ["[core][Server #6] grpc: Server.Serve failed to create ServerTransport: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:21:11.688 +00:00] [WARN] [proxy/task_scheduler.go:458] ["Failed to execute task: "] [traceID=925c0caa4652e949d5e69f2264e1cc62] [error="attempt #59: channel not found[channel=by-dev-rootcoord-dml_0_453241817591190296v0]"]
[2024/10/15 20:21:11.688 +00:00] [WARN] [proxy/impl.go:2766] ["Flush failed to WaitToFinish"] [traceID=925c0caa4652e949d5e69f2264e1cc62] [role=proxy] [db=default] [collections="[ssss]"] [error="attempt #59: channel not found[channel=by-dev-rootcoord-dml_0_453241817591190296v0]"] [BeginTs=453245586788057133] [EndTs=453245586788057133]
[2024/10/15 20:21:12.860 +00:00] [INFO] [datacoord/index_service.go:513] ["receive DescribeIndex request"] [traceID=e37647fcf9b5cc79af14da3a6d37c94e] [collectionID=453241817591190296] [indexName=] [timestamp=0]
[2024/10/15 20:21:12.860 +00:00] [INFO] [datacoord/index_service.go:449] ["completeIndexInfo success"] [collectionID=453241817591190296] [indexID=453241817591190327] [totalRows=0] [indexRows=0] [pendingIndexRows=0] [state=Finished] [failReason=]
[2024/10/15 20:21:12.860 +00:00] [INFO] [datacoord/index_service.go:560] ["DescribeIndex success"] [traceID=e37647fcf9b5cc79af14da3a6d37c94e] [collectionID=453241817591190296] [indexName=]
[2024/10/15 20:21:12.861 +00:00] [INFO] [querynodev2/services.go:1366] ["sync action"] [traceID=28ec743678e5c540f3fb754dcf64a6c4] [collectionID=453241817591190296] [channel=by-dev-rootcoord-dml_0_453241817591190296v0] [currentNodeID=42] [Action=UpdateVersion] [TargetVersion=1728994862861354467]
[2024/10/15 20:21:12.861 +00:00] [INFO] [delegator/distribution.go:294] ["Update readable segment version"] [oldVersion=1728994852861892079] [newVersion=1728994862861354467] [growingSegmentNum=0] [sealedSegmentNum=0]
[2024/10/15 20:21:12.861 +00:00] [INFO] [observers/target_observer.go:473] ["observer trigger update current target"] [collectionID=453241817591190296]
[2024/10/15 20:21:12.861 +00:00] [INFO] [datacoord/services.go:757] ["get recovery info request received"] [traceID=10374120f786c28fa4e676895f7340a2] [collectionID=453241817591190296] [partitionIDs="[]"]
[2024/10/15 20:21:12.861 +00:00] [INFO] [datacoord/handler.go:116] [GetQueryVChanPositions] [collectionID=453241817591190296] [channel=by-dev-rootcoord-dml_0_453241817591190296v0] [numOfSegments=1] ["indexed segment"=0]
[2024/10/15 20:21:12.861 +00:00] [INFO] [datacoord/handler.go:281] ["segment position not found, setting channel seek position to channel start position"] [channel=by-dev-rootcoord-dml_0_453241817591190296v0] [posTs=453242054227263491] [posTime=2024/10/15 08:33:44.927 +00:00]
[2024/10/15 20:21:12.861 +00:00] [INFO] [datacoord/handler.go:315] ["channel seek position set from collection start position"] [channel=by-dev-rootcoord-dml_0_453241817591190296v0] [posTs=453242054227263491] [posTime=2024/10/15 08:33:44.927 +00:00]
[2024/10/15 20:21:12.861 +00:00] [INFO] [datacoord/services.go:772] ["datacoord append channelInfo in GetRecoveryInfo"] [traceID=10374120f786c28fa4e676895f7340a2] [collectionID=453241817591190296] [partitionIDs="[]"] [channel=by-dev-rootcoord-dml_0_453241817591190296v0] ["# of unflushed segments"=0] ["# of flushed segments"=0] ["# of dropped segments"=0] ["# of indexed segments"=0]
[2024/10/15 20:21:13.084 +00:00] [INFO] [grpclog/grpclog.go:37] ["[transport]transport: closing: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:21:13.084 +00:00] [INFO] [grpclog/grpclog.go:37] ["[core][Server #14] grpc: Server.Serve failed to create ServerTransport: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:21:13.153 +00:00] [WARN] [kafka/kafka_consumer.go:138] ["consume msg failed"] [topic=by-dev-rootcoord-dml_4] [groupID=datanode-42-by-dev-rootcoord-dml_4_450590887436092108v0-true] [error="Local: Timed out"]
[2024/10/15 20:21:13.355 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=ms] [collections="[]"]
[2024/10/15 20:21:13.355 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=default] [collections="[ssss]"]
[2024/10/15 20:21:16.279 +00:00] [INFO] [grpclog/grpclog.go:37] ["[transport]transport: closing: EOF"]
[2024/10/15 20:21:16.280 +00:00] [INFO] [grpclog/grpclog.go:37] ["[transport]transport: loopyWriter exiting with error: transport closed by client"]
[2024/10/15 20:21:16.356 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=ms] [collections="[]"]
[2024/10/15 20:21:16.356 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=default] [collections="[ssss]"]
[2024/10/15 20:21:18.280 +00:00] [WARN] [rootcoord/quota_center.go:742] ["failed to get collection rate limit config"] [collectionID=450591213173465433] [error="collection not found[collection=450591213173465433]"]
[2024/10/15 20:21:18.828 +00:00] [INFO] [grpclog/grpclog.go:37] ["[transport]transport: closing: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:21:18.828 +00:00] [INFO] [grpclog/grpclog.go:37] ["[core][Server #10] grpc: Server.Serve failed to create ServerTransport: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:21:18.924 +00:00] [INFO] [grpclog/grpclog.go:37] ["[transport]transport: closing: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:21:18.924 +00:00] [INFO] [grpclog/grpclog.go:37] ["[core][Server #22] grpc: Server.Serve failed to create ServerTransport: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:21:19.356 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=ms] [collections="[]"]
[2024/10/15 20:21:19.356 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=default] [collections="[ssss]"]
[2024/10/15 20:21:20.069 +00:00] [INFO] [grpclog/grpclog.go:37] ["[transport]transport: closing: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:21:20.069 +00:00] [INFO] [grpclog/grpclog.go:37] ["[core][Server #18] grpc: Server.Serve failed to create ServerTransport: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:21:22.356 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=ms] [collections="[]"]
[2024/10/15 20:21:22.356 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=default] [collections="[ssss]"]
[2024/10/15 20:21:22.860 +00:00] [INFO] [datacoord/index_service.go:513] ["receive DescribeIndex request"] [traceID=257b5945656c294b999493e2dd526e0c] [collectionID=453241817591190296] [indexName=] [timestamp=0]
[2024/10/15 20:21:22.860 +00:00] [INFO] [datacoord/index_service.go:449] ["completeIndexInfo success"] [collectionID=453241817591190296] [indexID=453241817591190327] [totalRows=0] [indexRows=0] [pendingIndexRows=0] [state=Finished] [failReason=]
[2024/10/15 20:21:22.860 +00:00] [INFO] [datacoord/index_service.go:560] ["DescribeIndex success"] [traceID=257b5945656c294b999493e2dd526e0c] [collectionID=453241817591190296] [indexName=]
[2024/10/15 20:21:22.861 +00:00] [INFO] [querynodev2/services.go:1366] ["sync action"] [traceID=048871037fcfd3c30cc1c6d7d7e79955] [collectionID=453241817591190296] [channel=by-dev-rootcoord-dml_0_453241817591190296v0] [currentNodeID=42] [Action=UpdateVersion] [TargetVersion=1728994872861596219]
[2024/10/15 20:21:22.861 +00:00] [INFO] [delegator/distribution.go:294] ["Update readable segment version"] [oldVersion=1728994862861354467] [newVersion=1728994872861596219] [growingSegmentNum=0] [sealedSegmentNum=0]
[2024/10/15 20:21:22.861 +00:00] [INFO] [observers/target_observer.go:473] ["observer trigger update current target"] [collectionID=453241817591190296]
[2024/10/15 20:21:22.861 +00:00] [INFO] [datacoord/services.go:757] ["get recovery info request received"] [traceID=1b68e8f2c681fb83608633a14761be71] [collectionID=453241817591190296] [partitionIDs="[]"]
[2024/10/15 20:21:22.861 +00:00] [INFO] [datacoord/handler.go:116] [GetQueryVChanPositions] [collectionID=453241817591190296] [channel=by-dev-rootcoord-dml_0_453241817591190296v0] [numOfSegments=1] ["indexed segment"=0]
[2024/10/15 20:21:22.861 +00:00] [INFO] [datacoord/handler.go:281] ["segment position not found, setting channel seek position to channel start position"] [channel=by-dev-rootcoord-dml_0_453241817591190296v0] [posTs=453242054227263491] [posTime=2024/10/15 08:33:44.927 +00:00]
[2024/10/15 20:21:22.861 +00:00] [INFO] [datacoord/handler.go:315] ["channel seek position set from collection start position"] [channel=by-dev-rootcoord-dml_0_453241817591190296v0] [posTs=453242054227263491] [posTime=2024/10/15 08:33:44.927 +00:00]
[2024/10/15 20:21:22.861 +00:00] [INFO] [datacoord/services.go:772] ["datacoord append channelInfo in GetRecoveryInfo"] [traceID=1b68e8f2c681fb83608633a14761be71] [collectionID=453241817591190296] [partitionIDs="[]"] [channel=by-dev-rootcoord-dml_0_453241817591190296v0] ["# of unflushed segments"=0] ["# of flushed segments"=0] ["# of dropped segments"=0] ["# of indexed segments"=0]
[2024/10/15 20:21:23.153 +00:00] [WARN] [kafka/kafka_consumer.go:138] ["consume msg failed"] [topic=by-dev-rootcoord-dml_4] [groupID=datanode-42-by-dev-rootcoord-dml_4_450590887436092108v0-true] [error="Local: Timed out"]
[2024/10/15 20:21:24.579 +00:00] [INFO] [grpclog/grpclog.go:37] ["[transport]transport: closing: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:21:24.579 +00:00] [INFO] [grpclog/grpclog.go:37] ["[core][Server #28] grpc: Server.Serve failed to create ServerTransport: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[GIN] 2024/10/15 - 12:21:24 | 404 |      11.381µs |  192.168.24.124 | GET      "/metrics"
[2024/10/15 20:21:25.356 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=default] [collections="[ssss]"]
[2024/10/15 20:21:25.356 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=ms] [collections="[]"]
[2024/10/15 20:21:26.001 +00:00] [INFO] [grpclog/grpclog.go:37] ["[transport]transport: closing: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:21:26.001 +00:00] [INFO] [grpclog/grpclog.go:37] ["[core][Server #6] grpc: Server.Serve failed to create ServerTransport: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:21:27.281 +00:00] [WARN] [rootcoord/quota_center.go:742] ["failed to get collection rate limit config"] [collectionID=450591213173465433] [error="collection not found[collection=450591213173465433]"]
[2024/10/15 20:21:28.083 +00:00] [INFO] [grpclog/grpclog.go:37] ["[transport]transport: closing: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:21:28.083 +00:00] [INFO] [grpclog/grpclog.go:37] ["[core][Server #14] grpc: Server.Serve failed to create ServerTransport: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:21:28.356 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=ms] [collections="[]"]
[2024/10/15 20:21:28.356 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=default] [collections="[ssss]"]
[2024/10/15 20:21:31.356 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=ms] [collections="[]"]
[2024/10/15 20:21:31.356 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=default] [collections="[ssss]"]
[2024/10/15 20:21:32.860 +00:00] [INFO] [datacoord/index_service.go:513] ["receive DescribeIndex request"] [traceID=6d417d9a07b376c35216131263ba4fc8] [collectionID=453241817591190296] [indexName=] [timestamp=0]
[2024/10/15 20:21:32.860 +00:00] [INFO] [datacoord/index_service.go:449] ["completeIndexInfo success"] [collectionID=453241817591190296] [indexID=453241817591190327] [totalRows=0] [indexRows=0] [pendingIndexRows=0] [state=Finished] [failReason=]
[2024/10/15 20:21:32.860 +00:00] [INFO] [datacoord/index_service.go:560] ["DescribeIndex success"] [traceID=6d417d9a07b376c35216131263ba4fc8] [collectionID=453241817591190296] [indexName=]
[2024/10/15 20:21:32.860 +00:00] [INFO] [querynodev2/services.go:1366] ["sync action"] [traceID=658e0098d45150fe9f36e7b10f17b964] [collectionID=453241817591190296] [channel=by-dev-rootcoord-dml_0_453241817591190296v0] [currentNodeID=42] [Action=UpdateVersion] [TargetVersion=1728994882861628851]
[2024/10/15 20:21:32.861 +00:00] [INFO] [delegator/distribution.go:294] ["Update readable segment version"] [oldVersion=1728994872861596219] [newVersion=1728994882861628851] [growingSegmentNum=0] [sealedSegmentNum=0]
[2024/10/15 20:21:32.861 +00:00] [INFO] [observers/target_observer.go:473] ["observer trigger update current target"] [collectionID=453241817591190296]
[2024/10/15 20:21:32.861 +00:00] [INFO] [datacoord/services.go:757] ["get recovery info request received"] [traceID=8d48f82f687aad76f5f26d46142a8ebb] [collectionID=453241817591190296] [partitionIDs="[]"]
[2024/10/15 20:21:32.861 +00:00] [INFO] [datacoord/handler.go:116] [GetQueryVChanPositions] [collectionID=453241817591190296] [channel=by-dev-rootcoord-dml_0_453241817591190296v0] [numOfSegments=1] ["indexed segment"=0]
[2024/10/15 20:21:32.861 +00:00] [INFO] [datacoord/handler.go:281] ["segment position not found, setting channel seek position to channel start position"] [channel=by-dev-rootcoord-dml_0_453241817591190296v0] [posTs=453242054227263491] [posTime=2024/10/15 08:33:44.927 +00:00]
[2024/10/15 20:21:32.861 +00:00] [INFO] [datacoord/handler.go:315] ["channel seek position set from collection start position"] [channel=by-dev-rootcoord-dml_0_453241817591190296v0] [posTs=453242054227263491] [posTime=2024/10/15 08:33:44.927 +00:00]
[2024/10/15 20:21:32.861 +00:00] [INFO] [datacoord/services.go:772] ["datacoord append channelInfo in GetRecoveryInfo"] [traceID=8d48f82f687aad76f5f26d46142a8ebb] [collectionID=453241817591190296] [partitionIDs="[]"] [channel=by-dev-rootcoord-dml_0_453241817591190296v0] ["# of unflushed segments"=0] ["# of flushed segments"=0] ["# of dropped segments"=0] ["# of indexed segments"=0]
[2024/10/15 20:21:33.153 +00:00] [WARN] [kafka/kafka_consumer.go:138] ["consume msg failed"] [topic=by-dev-rootcoord-dml_4] [groupID=datanode-42-by-dev-rootcoord-dml_4_450590887436092108v0-true] [error="Local: Timed out"]
[2024/10/15 20:21:33.828 +00:00] [INFO] [grpclog/grpclog.go:37] ["[transport]transport: closing: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:21:33.828 +00:00] [INFO] [grpclog/grpclog.go:37] ["[core][Server #10] grpc: Server.Serve failed to create ServerTransport: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:21:33.924 +00:00] [INFO] [grpclog/grpclog.go:37] ["[transport]transport: closing: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:21:33.924 +00:00] [INFO] [grpclog/grpclog.go:37] ["[core][Server #22] grpc: Server.Serve failed to create ServerTransport: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:21:34.356 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=ms] [collections="[]"]
[2024/10/15 20:21:34.356 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=default] [collections="[ssss]"]
[2024/10/15 20:21:35.070 +00:00] [INFO] [grpclog/grpclog.go:37] ["[transport]transport: closing: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:21:35.070 +00:00] [INFO] [grpclog/grpclog.go:37] ["[core][Server #18] grpc: Server.Serve failed to create ServerTransport: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:21:36.281 +00:00] [WARN] [rootcoord/quota_center.go:742] ["failed to get collection rate limit config"] [collectionID=450591213173465433] [error="collection not found[collection=450591213173465433]"]
[2024/10/15 20:21:37.355 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=ms] [collections="[]"]
[2024/10/15 20:21:37.355 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=default] [collections="[ssss]"]
[2024/10/15 20:21:39.580 +00:00] [INFO] [grpclog/grpclog.go:37] ["[transport]transport: closing: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:21:39.580 +00:00] [INFO] [grpclog/grpclog.go:37] ["[core][Server #28] grpc: Server.Serve failed to create ServerTransport: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[GIN] 2024/10/15 - 12:21:39 | 404 |      12.788µs |  192.168.24.124 | GET      "/metrics"
[2024/10/15 20:21:40.356 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=ms] [collections="[]"]
[2024/10/15 20:21:40.356 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=default] [collections="[ssss]"]
[2024/10/15 20:21:41.001 +00:00] [INFO] [grpclog/grpclog.go:37] ["[transport]transport: closing: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:21:41.001 +00:00] [INFO] [grpclog/grpclog.go:37] ["[core][Server #6] grpc: Server.Serve failed to create ServerTransport: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:21:42.860 +00:00] [INFO] [datacoord/index_service.go:513] ["receive DescribeIndex request"] [traceID=a0dfbf33dc009bca35cc15800d5396db] [collectionID=453241817591190296] [indexName=] [timestamp=0]
[2024/10/15 20:21:42.860 +00:00] [INFO] [datacoord/index_service.go:449] ["completeIndexInfo success"] [collectionID=453241817591190296] [indexID=453241817591190327] [totalRows=0] [indexRows=0] [pendingIndexRows=0] [state=Finished] [failReason=]
[2024/10/15 20:21:42.860 +00:00] [INFO] [datacoord/index_service.go:560] ["DescribeIndex success"] [traceID=a0dfbf33dc009bca35cc15800d5396db] [collectionID=453241817591190296] [indexName=]
[2024/10/15 20:21:42.860 +00:00] [INFO] [querynodev2/services.go:1366] ["sync action"] [traceID=d24795d92a87d0153b0264b0bb94ed9b] [collectionID=453241817591190296] [channel=by-dev-rootcoord-dml_0_453241817591190296v0] [currentNodeID=42] [Action=UpdateVersion] [TargetVersion=1728994892861220229]
[2024/10/15 20:21:42.860 +00:00] [INFO] [delegator/distribution.go:294] ["Update readable segment version"] [oldVersion=1728994882861628851] [newVersion=1728994892861220229] [growingSegmentNum=0] [sealedSegmentNum=0]
[2024/10/15 20:21:42.860 +00:00] [INFO] [observers/target_observer.go:473] ["observer trigger update current target"] [collectionID=453241817591190296]
[2024/10/15 20:21:42.861 +00:00] [INFO] [datacoord/services.go:757] ["get recovery info request received"] [traceID=800ce302a810227e4a4f2cf504709bd6] [collectionID=453241817591190296] [partitionIDs="[]"]
[2024/10/15 20:21:42.861 +00:00] [INFO] [datacoord/handler.go:116] [GetQueryVChanPositions] [collectionID=453241817591190296] [channel=by-dev-rootcoord-dml_0_453241817591190296v0] [numOfSegments=1] ["indexed segment"=0]
[2024/10/15 20:21:42.861 +00:00] [INFO] [datacoord/handler.go:281] ["segment position not found, setting channel seek position to channel start position"] [channel=by-dev-rootcoord-dml_0_453241817591190296v0] [posTs=453242054227263491] [posTime=2024/10/15 08:33:44.927 +00:00]
[2024/10/15 20:21:42.861 +00:00] [INFO] [datacoord/handler.go:315] ["channel seek position set from collection start position"] [channel=by-dev-rootcoord-dml_0_453241817591190296v0] [posTs=453242054227263491] [posTime=2024/10/15 08:33:44.927 +00:00]
[2024/10/15 20:21:42.861 +00:00] [INFO] [datacoord/services.go:772] ["datacoord append channelInfo in GetRecoveryInfo"] [traceID=800ce302a810227e4a4f2cf504709bd6] [collectionID=453241817591190296] [partitionIDs="[]"] [channel=by-dev-rootcoord-dml_0_453241817591190296v0] ["# of unflushed segments"=0] ["# of flushed segments"=0] ["# of dropped segments"=0] ["# of indexed segments"=0]
[2024/10/15 20:21:43.084 +00:00] [INFO] [grpclog/grpclog.go:37] ["[transport]transport: closing: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:21:43.084 +00:00] [INFO] [grpclog/grpclog.go:37] ["[core][Server #14] grpc: Server.Serve failed to create ServerTransport: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:21:43.153 +00:00] [WARN] [kafka/kafka_consumer.go:138] ["consume msg failed"] [topic=by-dev-rootcoord-dml_4] [groupID=datanode-42-by-dev-rootcoord-dml_4_450590887436092108v0-true] [error="Local: Timed out"]
[2024/10/15 20:21:43.355 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=ms] [collections="[]"]
[2024/10/15 20:21:43.355 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=default] [collections="[ssss]"]
[2024/10/15 20:21:46.355 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=ms] [collections="[]"]
[2024/10/15 20:21:46.355 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=default] [collections="[ssss]"]
[2024/10/15 20:21:48.281 +00:00] [WARN] [rootcoord/quota_center.go:742] ["failed to get collection rate limit config"] [collectionID=450591213173465433] [error="collection not found[collection=450591213173465433]"]
[2024/10/15 20:21:48.829 +00:00] [INFO] [grpclog/grpclog.go:37] ["[transport]transport: closing: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:21:48.829 +00:00] [INFO] [grpclog/grpclog.go:37] ["[core][Server #10] grpc: Server.Serve failed to create ServerTransport: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:21:48.924 +00:00] [INFO] [grpclog/grpclog.go:37] ["[transport]transport: closing: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:21:48.924 +00:00] [INFO] [grpclog/grpclog.go:37] ["[core][Server #22] grpc: Server.Serve failed to create ServerTransport: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:21:49.356 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=ms] [collections="[]"]
[2024/10/15 20:21:49.356 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=default] [collections="[ssss]"]
[2024/10/15 20:21:50.069 +00:00] [INFO] [grpclog/grpclog.go:37] ["[transport]transport: closing: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:21:50.069 +00:00] [INFO] [grpclog/grpclog.go:37] ["[core][Server #18] grpc: Server.Serve failed to create ServerTransport: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:21:52.356 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=ms] [collections="[]"]
[2024/10/15 20:21:52.356 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=default] [collections="[ssss]"]
[2024/10/15 20:21:52.860 +00:00] [INFO] [datacoord/index_service.go:513] ["receive DescribeIndex request"] [traceID=4ccab2b81123421f5bdee2ff114bbad9] [collectionID=453241817591190296] [indexName=] [timestamp=0]
[2024/10/15 20:21:52.860 +00:00] [INFO] [datacoord/index_service.go:449] ["completeIndexInfo success"] [collectionID=453241817591190296] [indexID=453241817591190327] [totalRows=0] [indexRows=0] [pendingIndexRows=0] [state=Finished] [failReason=]
[2024/10/15 20:21:52.860 +00:00] [INFO] [datacoord/index_service.go:560] ["DescribeIndex success"] [traceID=4ccab2b81123421f5bdee2ff114bbad9] [collectionID=453241817591190296] [indexName=]
[2024/10/15 20:21:52.861 +00:00] [INFO] [querynodev2/services.go:1366] ["sync action"] [traceID=d2fc03f290411e637077a77e4a337f44] [collectionID=453241817591190296] [channel=by-dev-rootcoord-dml_0_453241817591190296v0] [currentNodeID=42] [Action=UpdateVersion] [TargetVersion=1728994902860889271]
[2024/10/15 20:21:52.861 +00:00] [INFO] [delegator/distribution.go:294] ["Update readable segment version"] [oldVersion=1728994892861220229] [newVersion=1728994902860889271] [growingSegmentNum=0] [sealedSegmentNum=0]
[2024/10/15 20:21:52.861 +00:00] [INFO] [observers/target_observer.go:473] ["observer trigger update current target"] [collectionID=453241817591190296]
[2024/10/15 20:21:52.861 +00:00] [INFO] [datacoord/services.go:757] ["get recovery info request received"] [traceID=9020a71d29425a45c1a7e4699a5a7c79] [collectionID=453241817591190296] [partitionIDs="[]"]
[2024/10/15 20:21:52.861 +00:00] [INFO] [datacoord/handler.go:116] [GetQueryVChanPositions] [collectionID=453241817591190296] [channel=by-dev-rootcoord-dml_0_453241817591190296v0] [numOfSegments=1] ["indexed segment"=0]
[2024/10/15 20:21:52.861 +00:00] [INFO] [datacoord/handler.go:281] ["segment position not found, setting channel seek position to channel start position"] [channel=by-dev-rootcoord-dml_0_453241817591190296v0] [posTs=453242054227263491] [posTime=2024/10/15 08:33:44.927 +00:00]
[2024/10/15 20:21:52.861 +00:00] [INFO] [datacoord/handler.go:315] ["channel seek position set from collection start position"] [channel=by-dev-rootcoord-dml_0_453241817591190296v0] [posTs=453242054227263491] [posTime=2024/10/15 08:33:44.927 +00:00]
[2024/10/15 20:21:52.861 +00:00] [INFO] [datacoord/services.go:772] ["datacoord append channelInfo in GetRecoveryInfo"] [traceID=9020a71d29425a45c1a7e4699a5a7c79] [collectionID=453241817591190296] [partitionIDs="[]"] [channel=by-dev-rootcoord-dml_0_453241817591190296v0] ["# of unflushed segments"=0] ["# of flushed segments"=0] ["# of dropped segments"=0] ["# of indexed segments"=0]
[2024/10/15 20:21:53.153 +00:00] [WARN] [kafka/kafka_consumer.go:138] ["consume msg failed"] [topic=by-dev-rootcoord-dml_4] [groupID=datanode-42-by-dev-rootcoord-dml_4_450590887436092108v0-true] [error="Local: Timed out"]
[2024/10/15 20:21:54.580 +00:00] [INFO] [grpclog/grpclog.go:37] ["[transport]transport: closing: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:21:54.580 +00:00] [INFO] [grpclog/grpclog.go:37] ["[core][Server #28] grpc: Server.Serve failed to create ServerTransport: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[GIN] 2024/10/15 - 12:21:54 | 404 |      10.827µs |  192.168.24.124 | GET      "/metrics"
[2024/10/15 20:21:55.355 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=default] [collections="[ssss]"]
[2024/10/15 20:21:55.356 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=ms] [collections="[]"]
[2024/10/15 20:21:56.001 +00:00] [INFO] [grpclog/grpclog.go:37] ["[transport]transport: closing: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:21:56.001 +00:00] [INFO] [grpclog/grpclog.go:37] ["[core][Server #6] grpc: Server.Serve failed to create ServerTransport: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:21:57.281 +00:00] [WARN] [rootcoord/quota_center.go:742] ["failed to get collection rate limit config"] [collectionID=450591213173465433] [error="collection not found[collection=450591213173465433]"]
[2024/10/15 20:21:58.083 +00:00] [INFO] [grpclog/grpclog.go:37] ["[transport]transport: closing: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:21:58.083 +00:00] [INFO] [grpclog/grpclog.go:37] ["[core][Server #14] grpc: Server.Serve failed to create ServerTransport: connection error: desc = \"transport: http2Server.HandleStreams received bogus greeting from client: \\\"GET /metrics HTTP/1.1\\\\r\\\\nH\\\"\""]
[2024/10/15 20:21:58.355 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=ms] [collections="[]"]
[2024/10/15 20:21:58.355 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=default] [collections="[ssss]"]
[2024/10/15 20:22:01.356 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=ms] [collections="[]"]
[2024/10/15 20:22:01.356 +00:00] [INFO] [proxy/meta_cache.go:900] ["expire all shard leader cache"] [database=default] [collections="[ssss]"]

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
None yet
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
2 participants
@wayblink @suyestyle