Closed wangting0128 closed 3 weeks ago
[2024/08/13 14:34:08.616 +00:00] [INFO] [segments/segment_loader.go:853] ["load field binlogs done for sealed segment with index"] [traceID=2467f96cc30ab7ed953ca31a88b77d4a] [collectionID=451809678468808897] [partitionID=451809678468808898] [segmentID=451809678470037289] [rowCount=500186] [fieldID=100] [binlog="[{\"entries_num\":41399,\"timestamp_from\":451816313806651394,\"timestamp_to\":451816375332110337,\"log_path\":\"file/insert_log/451809678468808897/451809678468808898/451809678470037289/100/451809678470037358\",\"log_size\":53719,\"logID\":451809678470037358,\"memory_size\":331192},{\"entries_num\":40599,\"timestamp_from\":451816372317192194,\"timestamp_to\":451816391912456193,\"log_path\":\"file/insert_log/451809678468808897/451809678468808898/451809678470037289/100/451809678470037384\",\"log_size\":42206,\"logID\":451809678470037384,\"memory_size\":324792},{\"entries_num\":40699,\"timestamp_from\":451816377743572993,\"timestamp_to\":451816392961032193,\"log_path\":\"file/insert_log/451809678468808897/451809678468808898/451809678470037289/100/451809678470037406\",\"log_size\":42326,\"logID\":451809678470037406,\"memory_size\":325592},{\"entries_num\":40499,\"timestamp_from\":451816349392699393,\"timestamp_to\":451816385267367939,\"log_path\":\"file/insert_log/451809678468808897/451809678468808898/451809678470037289/100/451809678470037449\",\"log_size\":42123,\"logID\":451809678470037449,\"memory_size\":323992},{\"entries_num\":40599,\"timestamp_from\":451816351739150337,\"timestamp_to\":451816371832225793,\"log_path\":\"file/insert_log/451809678468808897/451809678468808898/451809678470037289/100/451809678470037491\",\"log_size\":42225,\"logID\":451809678470037491,\"memory_size\":324792},{\"entries_num\":40599,\"timestamp_from\":451816356811374593,\"timestamp_to\":451816366707572738,\"log_path\":\"file/insert_log/451809678468808897/451809678468808898/451809678470037289/100/451809678470037538\",\"log_size\":42550,\"logID\":451809678470037538,\"memory_size\":324792},{\"entries_num\":40599,\"timestamp_from\":451816334699528193,\"timestamp_to\":451816363679809537,\"log_path\":\"file/insert_log/451809678468808897/451809678468808898/451809678470037289/100/451809678470037579\",\"log_size\":42212,\"logID\":451809678470037579,\"memory_size\":324792},{\"entries_num\":40699,\"timestamp_from\":451816330269556739,\"timestamp_to\":451816348921102337,\"log_path\":\"file/insert_log/451809678468808897/451809678468808898/451809678470037289/100/451809678470037611\",\"log_size\":42324,\"logID\":451809678470037611,\"memory_size\":325592},{\"entries_num\":40499,\"timestamp_from\":451816325629345793,\"timestamp_to\":451816334266990593,\"log_path\":\"file/insert_log/451809678468808897/451809678468808898/451809678470037289/100/451809678470037678\",\"log_size\":42115,\"logID\":451809678470037678,\"memory_size\":323992},{\"entries_num\":40799,\"timestamp_from\":451816320871694337,\"timestamp_to\":451816411651899393,\"log_path\":\"file/insert_log/451809678468808897/451809678468808898/451809678470037289/100/451809678470037713\",\"log_size\":42414,\"logID\":451809678470037713,\"memory_size\":326392},{\"entries_num\":40599,\"timestamp_from\":451816400969531393,\"timestamp_to\":451816412635201539,\"log_path\":\"file/insert_log/451809678468808897/451809678468808898/451809678470037289/100/451809678470037757\",\"log_size\":42226,\"logID\":451809678470037757,\"memory_size\":324792},{\"entries_num\":40599,\"timestamp_from\":451816393472475137,\"timestamp_to\":451816407746215937,\"log_path\":\"file/insert_log/451809678468808897/451809678468808898/451809678470037289/100/451809678470037807\",\"log_size\":42221,\"logID\":451809678470037807,\"memory_size\":324792},{\"entries_num\":11998,\"timestamp_from\":451816395962843139,\"timestamp_to\":451816397915553793,\"log_path\":\"file/insert_log/451809678468808897/451809678468808898/451809678470037289/100/451809678470037839\",\"log_size\":12777,\"logID\":451809678470037839,\"memory_size\":95984}]"] [current_index_version=5] [load_duration=44m58.271465815s]
@xiaocai2333 so the loading is too slow with many scalar fields? @wangting0128 can we calculate the data size we need to load and the speed of loading? /assign @xiaocai2333 /unassign
@xiaocai2333 so the loading is too slow with many scalar fields? @wangting0128 can we calculate the data size we need to load and the speed of loading? /assign @xiaocai2333 /unassign
2024-08-13 21:49:10.344 | I20240813 13:49:10.344883 86 load_index_c.cpp:236] [SERVER][AppendIndexV2][milvus] [collection=451809678468808897][segment=451809678470037289][field=100][enable_mmap=false] load index 451809678468808930 |
-- | -- | --
| | 2024-08-13 21:49:10.566 | I20240813 13:49:10.566550 86 load_index_c.cpp:300] [SERVER][AppendIndexV2][milvus] [collection=451809678468808897][segment=451809678470037289][field=100][enable_mmap=false] load index 451809678468808930 done |
| | 2024-08-13 21:49:12.027 | I20240813 13:49:12.027810 248 load_index_c.cpp:236] [SERVER][AppendIndexV2][milvus] [collection=451809678468808897][segment=451809678470036939][field=100][enable_mmap=false] load index 451809678468808930 |
| | 2024-08-13 21:49:12.250 | I20240813 13:49:12.250844 248 load_index_c.cpp:300] [SERVER][AppendIndexV2][milvus] [collection=451809678468808897][segment=451809678470036939][field=100][enable_mmap=false] load index 451809678468808930 done |
| | 2024-08-13 22:33:13.023 | [2024/08/13 14:33:13.023 +00:00] [INFO] [segments/segment.go:1207] ["updateSegmentIndex done"] [traceID=2467f96cc30ab7ed953ca31a88b77d4a] [collectionID=451809678468808897] [partitionID=451809678468808898] [segmentID=451809678470036939] [fieldID=100] |
| | 2024-08-13 22:33:13.023 | [2024/08/13 14:33:13.023 +00:00] [INFO] [segments/segment_loader.go:853] ["load field binlogs done for sealed segment with index"] [traceID=2467f96cc30ab7ed953ca31a88b77d4a] [collectionID=451809678468808897] [partitionID=451809678468808898] [segmentID=451809678470036939] [rowCount=499814] [fieldID=100] [binlog="[{\"entries_num\":41299,\"timestamp_from\":451816313806651394,\"timestamp_to\":451816357309448193,\"log_path\":\"file/insert_log/451809678468808897/451809678468808898/451809678470036939/100/451809678470036979\",\"log_size\":48660,\"logID\":451809678470036979,\"memory_size\":330392},{\"entries_num\":40599,\"timestamp_from\":451816349392699393,\"timestamp_to\":451816361176072193,\"log_path\":\"file/insert_log/451809678468808897/451809678468808898/451809678470036939/100/451809678470037023\",\"log_size\":42218,\"logID\":451809678470037023,\"memory_size\":324792},{\"entries_num\":40599,\"timestamp_from\":451816351739150337,\"timestamp_to\":451816366707572738,\"log_path\":\"file/insert_log/451809678468808897/451809678468808898/451809678470036939/100/451809678470037059\",\"log_size\":42201,\"logID\":451809678470037059,\"memory_size\":324792},{\"entries_num\":40599,\"timestamp_from\":451816342314811393,\"timestamp_to\":451816369433608196,\"log_path\":\"file/insert_log/451809678468808897/451809678468808898/451809678470036939/100/451809678470037101\",\"log_size\":42219,\"logID\":451809678470037101,\"memory_size\":324792},{\"entries_num\":40599,\"timestamp_from\":451816334699528193,\"timestamp_to\":451816348921102337,\"log_path\":\"file/insert_log/451809678468808897/451809678468808898/451809678470036939/100/451809678470037175\",\"log_size\":42313,\"logID\":451809678470037175,\"memory_size\":324792},{\"entries_num\":40599,\"timestamp_from\":451816327975534593,\"timestamp_to\":451816339090440193,\"log_path\":\"file/insert_log/451809678468808897/451809678468808898/451809678470036939/100/451809678470037210\",\"log_size\":42233,\"logID\":451809678470037210,\"memory_size\":324792},{\"entries_num\":40599,\"timestamp_from\":451816328906407938,\"timestamp_to\":451816377088212995,\"log_path\":\"file/insert_log/451809678468808897/451809678468808898/451809678470036939/100/451809678470037248\",\"log_size\":42192,\"logID\":451809678470037248,\"memory_size\":324792},{\"entries_num\":40599,\"timestamp_from\":451816377088212995,\"timestamp_to\":451816390864142337,\"log_path\":\"file/insert_log/451809678468808897/451809678468808898/451809678470036939/100/451809678470037275\",\"log_size\":42364,\"logID\":451809678470037275,\"memory_size\":324792},{\"entries_num\":40599,\"timestamp_from\":451816325629345793,\"timestamp_to\":451816392961032193,\"log_path\":\"file/insert_log/451809678468808897/451809678468808898/451809678470036939/100/451809678470037309\",\"log_size\":42212,\"logID\":451809678470037309,\"memory_size\":324792},{\"entries_num\":40899,\"timestamp_from\":451816320871694337,\"timestamp_to\":451816409279758339,\"log_path\":\"file/insert_log/451809678468808897/451809678468808898/451809678470036939/100/451809678470037356\",\"log_size\":42522,\"logID\":451809678470037356,\"memory_size\":327192},{\"entries_num\":40599,\"timestamp_from\":451816393472475137,\"timestamp_to\":451816412635201539,\"log_path\":\"file/insert_log/451809678468808897/451809678468808898/451809678470036939/100/451809678470037412\",\"log_size\":42218,\"logID\":451809678470037412,\"memory_size\":324792},{\"entries_num\":40599,\"timestamp_from\":451816395962843139,\"timestamp_to\":451816407746215937,\"log_path\":\"file/insert_log/451809678468808897/451809678468808898/451809678470036939/100/451809678470037444\",\"log_size\":42539,\"logID\":451809678470037444,\"memory_size\":324792},{\"entries_num\":11626,\"timestamp_from\":451816400969531393,\"timestamp_to\":451816402922766337,\"log_path\":\"file/insert_log/451809678468808897/451809678468808898/451809678470036939/100/451809678470037475\",\"log_size\":12393,\"logID\":451809678470037475,\"memory_size\":93008}]"] [current_index_version=5] [load_duration=44m0.995517542s] |
| | 2024-08-13 22:34:08.616 | [2024/08/13 14:34:08.616 +00:00] [INFO] [segments/segment.go:1207] ["updateSegmentIndex done"] [traceID=2467f96cc30ab7ed953ca31a88b77d4a] [collectionID=451809678468808897] [partitionID=451809678468808898] [segmentID=451809678470037289] [fieldID=100] |
| | 2024-08-13 22:34:08.616 | [2024/08/13 14:34:08.616 +00:00] [INFO] [segments/segment_loader.go:853] ["load field binlogs done for sealed segment with index"] [traceID=2467f96cc30ab7ed953ca31a88b77d4a] [collectionID=451809678468808897] [partitionID=451809678468808898] [segmentID=451809678470037289] [rowCount=500186] [fieldID=100] [binlog="[{\"entries_num\":41399,\"timestamp_from\":451816313806651394,\"timestamp_to\":451816375332110337,\"log_path\":\"file/insert_log/451809678468808897/451809678468808898/451809678470037289/100/451809678470037358\",\"log_size\":53719,\"logID\":451809678470037358,\"memory_size\":331192},{\"entries_num\":40599,\"timestamp_from\":451816372317192194,\"timestamp_to\":451816391912456193,\"log_path\":\"file/insert_log/451809678468808897/451809678468808898/451809678470037289/100/451809678470037384\",\"log_size\":42206,\"logID\":451809678470037384,\"memory_size\":324792},{\"entries_num\":40699,\"timestamp_from\":451816377743572993,\"timestamp_to\":451816392961032193,\"log_path\":\"file/insert_log/451809678468808897/451809678468808898/451809678470037289/100/451809678470037406\",\"log_size\":42326,\"logID\":451809678470037406,\"memory_size\":325592},{\"entries_num\":40499,\"timestamp_from\":451816349392699393,\"timestamp_to\":451816385267367939,\"log_path\":\"file/insert_log/451809678468808897/451809678468808898/451809678470037289/100/451809678470037449\",\"log_size\":42123,\"logID\":451809678470037449,\"memory_size\":323992},{\"entries_num\":40599,\"timestamp_from\":451816351739150337,\"timestamp_to\":451816371832225793,\"log_path\":\"file/insert_log/451809678468808897/451809678468808898/451809678470037289/100/451809678470037491\",\"log_size\":42225,\"logID\":451809678470037491,\"memory_size\":324792},{\"entries_num\":40599,\"timestamp_from\":451816356811374593,\"timestamp_to\":451816366707572738,\"log_path\":\"file/insert_log/451809678468808897/451809678468808898/451809678470037289/100/451809678470037538\",\"log_size\":42550,\"logID\":451809678470037538,\"memory_size\":324792},{\"entries_num\":40599,\"timestamp_from\":451816334699528193,\"timestamp_to\":451816363679809537,\"log_path\":\"file/insert_log/451809678468808897/451809678468808898/451809678470037289/100/451809678470037579\",\"log_size\":42212,\"logID\":451809678470037579,\"memory_size\":324792},{\"entries_num\":40699,\"timestamp_from\":451816330269556739,\"timestamp_to\":451816348921102337,\"log_path\":\"file/insert_log/451809678468808897/451809678468808898/451809678470037289/100/451809678470037611\",\"log_size\":42324,\"logID\":451809678470037611,\"memory_size\":325592},{\"entries_num\":40499,\"timestamp_from\":451816325629345793,\"timestamp_to\":451816334266990593,\"log_path\":\"file/insert_log/451809678468808897/451809678468808898/451809678470037289/100/451809678470037678\",\"log_size\":42115,\"logID\":451809678470037678,\"memory_size\":323992},{\"entries_num\":40799,\"timestamp_from\":451816320871694337,\"timestamp_to\":451816411651899393,\"log_path\":\"file/insert_log/451809678468808897/451809678468808898/451809678470037289/100/451809678470037713\",\"log_size\":42414,\"logID\":451809678470037713,\"memory_size\":326392},{\"entries_num\":40599,\"timestamp_from\":451816400969531393,\"timestamp_to\":451816412635201539,\"log_path\":\"file/insert_log/451809678468808897/451809678468808898/451809678470037289/100/451809678470037757\",\"log_size\":42226,\"logID\":451809678470037757,\"memory_size\":324792},{\"entries_num\":40599,\"timestamp_from\":451816393472475137,\"timestamp_to\":451816407746215937,\"log_path\":\"file/insert_log/451809678468808897/451809678468808898/451809678470037289/100/451809678470037807\",\"log_size\":42221,\"logID\":451809678470037807,\"memory_size\":324792},{\"entries_num\":11998,\"timestamp_from\":451816395962843139,\"timestamp_to\":451816397915553793,\"log_path\":\"file/insert_log/451809678468808897/451809678468808898/451809678470037289/100/451809678470037839\",\"log_size\":12777,\"logID\":451809678470037839,\"memory_size\":95984}]"] [current_index_version=5] [load_duration=44m58.271465815s]
It seems that it got stuck in the cgo pool and didn't exit in time. PTAL @MrPresent-Han /assign @MrPresent-Han
Reverse lookup of original data takes too long.
for (int i = 0; i < row_count; ++i) {
insert_record_.insert_pk(int64_index->Reverse_Lookup(i), i);
}
/assign @zhagnlu
反向查找原始数据的时间太长。
for (int i = 0; i < row_count; ++i) { insert_record_.insert_pk(int64_index->Reverse_Lookup(i), i); }
/分配@zhagnlu
can you explian this code? why load need to reverse_lookup?
@zhagnlu
A cache may take too much memory for the index.
Actaully, It doesn't really make sense to create bitmap index on primary key because primary key is always unique.
Let't ban the behaviour to do so.
Load with 40 minutes seems to be really one, is primary key field load the only problem?
The PR that prohibits the primary key from building the bitmap index has been merged, and the test is being rerun after removing the primary key bitmap index
image:master-20240828-56b3490a-amd64 argo task:fouramf-bgq75-rerun
Is there an existing issue for this?
Environment
Current Behavior
argo task: fouramf-b8lc8
server:
client pod name: fouramf-b8lc8-4126907731 client log:
schema:
Expected Behavior
No response
Steps To Reproduce
Milvus Log
No response
Anything else?
test config: