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]: Index failed during rolling upgrade #38741

Open
1 task done
zhuwenxing opened this issue Dec 25, 2024 · 3 comments
Open
1 task done

[Bug]: Index failed during rolling upgrade #38741

zhuwenxing opened this issue Dec 25, 2024 · 3 comments
Assignees
@weiliu1031
Labels
kind/bug Issues or changes related a bug triage/accepted Indicates an issue or PR is ready to be actively worked on.
Milestone
2.5.1

Comments

@zhuwenxing
Copy link
Contributor

Is there an existing issue for this?

  • I have searched the existing issues

Environment

- Milvus version:v2.4.17 --> master-20241223-f499ca47-amd64
- Deployment mode(standalone or cluster):cluster
- MQ type(rocksmq, pulsar or kafka):    
- SDK version(e.g. pymilvus v2.0.0rc2):
- OS(Ubuntu or CentOS): 
- CPU/Memory: 
- GPU: 
- Others:

Current Behavior

[2024-12-23T17:42:56.949Z] FAILED testcases/test_single_request_operation_for_rolling_update.py::TestOperations::test_operations[9-10] - pytest_assume.plugin.FailedAssumption: 

[2024-12-23T17:42:56.949Z] 2 Failed Assumptions:

[2024-12-23T17:42:56.949Z] 

[2024-12-23T17:42:56.949Z] chaos_commons.py:124: AssumptionFailure

[2024-12-23T17:42:56.949Z] >>	pytest.assume(

[2024-12-23T17:42:56.949Z] AssertionError: Expect Succ: Op.index succ rate 0.9594594594594594, total: 74, average time: 19.8179

[2024-12-23T17:42:56.949Z] assert False

[2024-12-23T17:42:56.949Z] 

[2024-12-23T17:42:56.949Z] testcases/test_single_request_operation_for_rolling_update.py:153: AssumptionFailure

[2024-12-23T17:42:56.949Z] >>	pytest.assume(rto <= rto_threshold,  f"{self.health_checkers[k].c_name} {k} rto expect {rto_threshold}s but get {rto}s, {self.health_checkers[k].fail_records}")

[2024-12-23T17:42:56.949Z] AssertionError: IndexChecker__d1b5pX2j Op.index rto expect 10s but get 366.90567898750305s, [('failure', 36, '2024-12-23 17:16:50.265773', 1734974210.2658098), ('failure', 37, '2024-12-23 17:18:51.530762', 1734974331.5308142), ('failure', 38, '2024-12-23 17:20:53.856480', 1734974453.856523), ('success', 39, '2024-12-23 17:22:57.171459', 1734974577.1714888)]

[2024-12-23T17:42:56.949Z] assert False

[2024-12-23T17:42:56.949Z] FAILED testcases/test_single_request_operation_for_rolling_update.py::TestOperations::test_operations[3-10] - pytest_assume.plugin.FailedAssumption: 

[2024-12-23T17:42:56.949Z] 2 Failed Assumptions:

[2024-12-23T17:42:56.949Z] 

[2024-12-23T17:42:56.949Z] chaos_commons.py:124: AssumptionFailure

[2024-12-23T17:42:56.949Z] >>	pytest.assume(

[2024-12-23T17:42:56.949Z] AssertionError: Expect Succ: Op.index succ rate 0.958904109589041, total: 73, average time: 19.9359

[2024-12-23T17:42:56.949Z] assert False

[2024-12-23T17:42:56.949Z] 

[2024-12-23T17:42:56.949Z] testcases/test_single_request_operation_for_rolling_update.py:153: AssumptionFailure

[2024-12-23T17:42:56.949Z] >>	pytest.assume(rto <= rto_threshold,  f"{self.health_checkers[k].c_name} {k} rto expect {rto_threshold}s but get {rto}s, {self.health_checkers[k].fail_records}")

[2024-12-23T17:42:56.949Z] AssertionError: IndexChecker__xQe95wlv Op.index rto expect 10s but get 366.17002153396606s, [('failure', 36, '2024-12-23 17:17:02.068743', 1734974222.0687613), ('failure', 37, '2024-12-23 17:19:05.445734', 1734974345.4457502), ('failure', 38, '2024-12-23 17:21:06.761849', 1734974466.7618792), ('success', 39, '2024-12-23 17:23:08.238754', 1734974588.238783)]

[2024-12-23T17:42:56.949Z] assert False

[2024-12-23T17:42:56.949Z] FAILED testcases/test_single_request_operation_for_rolling_update.py::TestOperations::test_operations[8-10] - pytest_assume.plugin.FailedAssumption: 

[2024-12-23T17:42:56.949Z] 2 Failed Assumptions:

[2024-12-23T17:42:56.949Z] 

[2024-12-23T17:42:56.949Z] chaos_commons.py:124: AssumptionFailure

[2024-12-23T17:42:56.949Z] >>	pytest.assume(

[2024-12-23T17:42:56.949Z] AssertionError: Expect Succ: Op.index succ rate 0.958904109589041, total: 73, average time: 19.9970

[2024-12-23T17:42:56.949Z] assert False

[2024-12-23T17:42:56.949Z] 

[2024-12-23T17:42:56.949Z] testcases/test_single_request_operation_for_rolling_update.py:153: AssumptionFailure

[2024-12-23T17:42:56.949Z] >>	pytest.assume(rto <= rto_threshold,  f"{self.health_checkers[k].c_name} {k} rto expect {rto_threshold}s but get {rto}s, {self.health_checkers[k].fail_records}")

[2024-12-23T17:42:56.949Z] AssertionError: IndexChecker__edQskzPB Op.index rto expect 10s but get 364.3369879722595s, [('failure', 36, '2024-12-23 17:16:48.731750', 1734974208.7317827), ('failure', 37, '2024-12-23 17:18:50.074741', 1734974330.0747588), ('failure', 38, '2024-12-23 17:20:51.417225', 1734974451.4172559), ('success', 39, '2024-12-23 17:22:53.068747', 1734974573.0687706)]

[2024-12-23T17:42:56.949Z] assert False

[2024-12-23T17:42:56.949Z] FAILED testcases/test_single_request_operation_for_rolling_update.py::TestOperations::test_operations[7-10] - pytest_assume.plugin.FailedAssumption: 

[2024-12-23T17:42:56.949Z] 2 Failed Assumptions:

[2024-12-23T17:42:56.949Z] 

[2024-12-23T17:42:56.949Z] chaos_commons.py:124: AssumptionFailure

[2024-12-23T17:42:56.949Z] >>	pytest.assume(

[2024-12-23T17:42:56.949Z] AssertionError: Expect Succ: Op.index succ rate 0.9594594594594594, total: 74, average time: 19.8185

[2024-12-23T17:42:56.949Z] assert False

[2024-12-23T17:42:56.949Z] 

[2024-12-23T17:42:56.949Z] testcases/test_single_request_operation_for_rolling_update.py:153: AssumptionFailure

[2024-12-23T17:42:56.949Z] >>	pytest.assume(rto <= rto_threshold,  f"{self.health_checkers[k].c_name} {k} rto expect {rto_threshold}s but get {rto}s, {self.health_checkers[k].fail_records}")

[2024-12-23T17:42:56.949Z] AssertionError: IndexChecker__VFBcWJw4 Op.index rto expect 10s but get 364.1275360584259s, [('failure', 36, '2024-12-23 17:16:58.804896', 1734974218.804926), ('failure', 37, '2024-12-23 17:19:00.022746', 1734974340.0227847), ('failure', 38, '2024-12-23 17:21:01.207465', 1734974461.2075007), ('success', 39, '2024-12-23 17:23:02.932438', 1734974582.932462)]

[2024-12-23T17:42:56.949Z] assert False

[2024-12-23T17:42:56.949Z] FAILED testcases/test_single_request_operation_for_rolling_update.py::TestOperations::test_operations[2-10] - pytest_assume.plugin.FailedAssumption: 

[2024-12-23T17:42:56.949Z] 2 Failed Assumptions:

[2024-12-23T17:42:56.949Z] 

[2024-12-23T17:42:56.949Z] chaos_commons.py:124: AssumptionFailure

[2024-12-23T17:42:56.949Z] >>	pytest.assume(

[2024-12-23T17:42:56.949Z] AssertionError: Expect Succ: Op.index succ rate 0.9571428571428572, total: 70, average time: 21.1751

[2024-12-23T17:42:56.949Z] assert False

[2024-12-23T17:42:56.949Z] 

[2024-12-23T17:42:56.949Z] testcases/test_single_request_operation_for_rolling_update.py:153: AssumptionFailure

[2024-12-23T17:42:56.949Z] >>	pytest.assume(rto <= rto_threshold,  f"{self.health_checkers[k].c_name} {k} rto expect {rto_threshold}s but get {rto}s, {self.health_checkers[k].fail_records}")

[2024-12-23T17:42:56.949Z] AssertionError: IndexChecker__p1AQFq8h Op.index rto expect 10s but get 364.7519919872284s, [('failure', 32, '2024-12-23 17:16:54.095749', 1734974214.0957804), ('failure', 33, '2024-12-23 17:18:55.443125', 1734974335.4431539), ('failure', 34, '2024-12-23 17:20:57.151761', 1734974457.151787), ('success', 35, '2024-12-23 17:22:58.847746', 1734974578.8477724)]

[2024-12-23T17:42:56.949Z] assert False

[2024-12-23T17:42:56.949Z] FAILED testcases/test_single_request_operation_for_rolling_update.py::TestOperations::test_operations[4-10] - pytest_assume.plugin.FailedAssumption: 

[2024-12-23T17:42:56.949Z] 2 Failed Assumptions:

[2024-12-23T17:42:56.949Z] 

[2024-12-23T17:42:56.949Z] chaos_commons.py:124: AssumptionFailure

[2024-12-23T17:42:56.949Z] >>	pytest.assume(

[2024-12-23T17:42:56.949Z] AssertionError: Expect Succ: Op.index succ rate 0.9583333333333334, total: 72, average time: 20.5062

[2024-12-23T17:42:56.949Z] assert False

[2024-12-23T17:42:56.949Z] 

[2024-12-23T17:42:56.949Z] testcases/test_single_request_operation_for_rolling_update.py:153: AssumptionFailure

[2024-12-23T17:42:56.949Z] >>	pytest.assume(rto <= rto_threshold,  f"{self.health_checkers[k].c_name} {k} rto expect {rto_threshold}s but get {rto}s, {self.health_checkers[k].fail_records}")

[2024-12-23T17:42:56.949Z] AssertionError: IndexChecker__iMxl1dLG Op.index rto expect 10s but get 366.6630003452301s, [('failure', 34, '2024-12-23 17:17:00.529751', 1734974220.5297801), ('failure', 35, '2024-12-23 17:19:02.822762', 1734974342.8227925), ('failure', 36, '2024-12-23 17:21:05.626751', 1734974465.6267788), ('success', 37, '2024-12-23 17:23:07.192754', 1734974587.1927805)]

[2024-12-23T17:42:56.949Z] assert False

[2024-12-23T17:42:56.949Z] FAILED testcases/test_single_request_operation_for_rolling_update.py::TestOperations::test_operations[10-10] - pytest_assume.plugin.FailedAssumption: 

[2024-12-23T17:42:56.949Z] 2 Failed Assumptions:

[2024-12-23T17:42:56.949Z] 

[2024-12-23T17:42:56.949Z] chaos_commons.py:124: AssumptionFailure

[2024-12-23T17:42:56.949Z] >>	pytest.assume(

[2024-12-23T17:42:56.949Z] AssertionError: Expect Succ: Op.index succ rate 0.9571428571428572, total: 70, average time: 20.9236

[2024-12-23T17:42:56.949Z] assert False

[2024-12-23T17:42:56.949Z] 

[2024-12-23T17:42:56.949Z] testcases/test_single_request_operation_for_rolling_update.py:153: AssumptionFailure

[2024-12-23T17:42:56.950Z] >>	pytest.assume(rto <= rto_threshold,  f"{self.health_checkers[k].c_name} {k} rto expect {rto_threshold}s but get {rto}s, {self.health_checkers[k].fail_records}")

[2024-12-23T17:42:56.950Z] AssertionError: IndexChecker__z3rR2ud9 Op.index rto expect 10s but get 364.2149658203125s, [('failure', 32, '2024-12-23 17:16:55.224799', 1734974215.2248394), ('failure', 33, '2024-12-23 17:18:56.478588', 1734974336.4786143), ('failure', 34, '2024-12-23 17:20:57.964738', 1734974457.9647596), ('success', 35, '2024-12-23 17:22:59.439753', 1734974579.4398053)]

[2024-12-23T17:42:56.950Z] assert False

[2024-12-23T17:42:56.950Z] FAILED testcases/test_single_request_operation_for_rolling_update.py::TestOperations::test_operations[5-10] - pytest_assume.plugin.FailedAssumption: 

[2024-12-23T17:42:56.950Z] 2 Failed Assumptions:

[2024-12-23T17:42:56.950Z] 

[2024-12-23T17:42:56.950Z] chaos_commons.py:124: AssumptionFailure

[2024-12-23T17:42:56.950Z] >>	pytest.assume(

[2024-12-23T17:42:56.950Z] AssertionError: Expect Succ: Op.index succ rate 0.9545454545454546, total: 66, average time: 22.5251

[2024-12-23T17:42:56.950Z] assert False

[2024-12-23T17:42:56.950Z] 

[2024-12-23T17:42:56.950Z] testcases/test_single_request_operation_for_rolling_update.py:153: AssumptionFailure

[2024-12-23T17:42:56.950Z] >>	pytest.assume(rto <= rto_threshold,  f"{self.health_checkers[k].c_name} {k} rto expect {rto_threshold}s but get {rto}s, {self.health_checkers[k].fail_records}")

[2024-12-23T17:42:56.950Z] AssertionError: IndexChecker__WkfTHRMw Op.index rto expect 10s but get 363.8909728527069s, [('failure', 28, '2024-12-23 17:16:48.740758', 1734974208.7407942), ('failure', 29, '2024-12-23 17:18:49.941748', 1734974329.9417803), ('failure', 30, '2024-12-23 17:20:51.202859', 1734974451.2028792), ('success', 31, '2024-12-23 17:22:52.631742', 1734974572.631767)]

[2024-12-23T17:42:56.950Z] assert False

[2024-12-23T17:42:56.950Z] FAILED testcases/test_single_request_operation_for_rolling_update.py::TestOperations::test_operations[6-10] - pytest_assume.plugin.FailedAssumption: 

[2024-12-23T17:42:56.950Z] 2 Failed Assumptions:

[2024-12-23T17:42:56.950Z] 

[2024-12-23T17:42:56.950Z] chaos_commons.py:124: AssumptionFailure

[2024-12-23T17:42:56.950Z] >>	pytest.assume(

[2024-12-23T17:42:56.950Z] AssertionError: Expect Succ: Op.index succ rate 0.9565217391304348, total: 69, average time: 21.4263

[2024-12-23T17:42:56.950Z] assert False

[2024-12-23T17:42:56.950Z] 

[2024-12-23T17:42:56.950Z] testcases/test_single_request_operation_for_rolling_update.py:153: AssumptionFailure

[2024-12-23T17:42:56.950Z] >>	pytest.assume(rto <= rto_threshold,  f"{self.health_checkers[k].c_name} {k} rto expect {rto_threshold}s but get {rto}s, {self.health_checkers[k].fail_records}")

[2024-12-23T17:42:56.950Z] AssertionError: IndexChecker__1EWEhoRH Op.index rto expect 10s but get 366.40900230407715s, [('failure', 32, '2024-12-23 17:16:51.780754', 1734974211.7807827), ('failure', 33, '2024-12-23 17:18:53.049746', 1734974333.0497706), ('failure', 34, '2024-12-23 17:20:56.371745', 1734974456.3717666), ('success', 35, '2024-12-23 17:22:58.189757', 1734974578.189785)]

[2024-12-23T17:42:56.950Z] assert False

[2024-12-23T17:42:56.950Z] FAILED testcases/test_single_request_operation_for_rolling_update.py::TestOperations::test_operations[1-10] - pytest_assume.plugin.FailedAssumption: 

[2024-12-23T17:42:56.950Z] 2 Failed Assumptions:

[2024-12-23T17:42:56.950Z] 

[2024-12-23T17:42:56.950Z] chaos_commons.py:124: AssumptionFailure

[2024-12-23T17:42:56.950Z] >>	pytest.assume(

[2024-12-23T17:42:56.950Z] AssertionError: Expect Succ: Op.index succ rate 0.958904109589041, total: 73, average time: 19.9821

[2024-12-23T17:42:56.950Z] assert False

[2024-12-23T17:42:56.950Z] 

[2024-12-23T17:42:56.950Z] testcases/test_single_request_operation_for_rolling_update.py:153: AssumptionFailure

[2024-12-23T17:42:56.950Z] >>	pytest.assume(rto <= rto_threshold,  f"{self.health_checkers[k].c_name} {k} rto expect {rto_threshold}s but get {rto}s, {self.health_checkers[k].fail_records}")

[2024-12-23T17:42:56.950Z] AssertionError: IndexChecker__RxZ7Qcgf Op.index rto expect 10s but get 364.4219825267792s, [('failure', 32, '2024-12-23 17:16:56.784752', 1734974216.784777), ('failure', 33, '2024-12-23 17:18:58.246904', 1734974338.246924), ('failure', 34, '2024-12-23 17:20:59.669795', 1734974459.669835), ('success', 35, '2024-12-23 17:23:01.206738', 1734974581.2067595)]

[2024-12-23T17:42:56.950Z] assert False

Expected Behavior

No response

Steps To Reproduce

No response

Milvus Log

failed job: https://qa-jenkins.milvus.io/blue/organizations/jenkins/rolling_update_for_operator_test_simple/detail/rolling_update_for_operator_test_simple/5688/pipeline

log:

artifacts-kafka-mixcoord-5688-server-logs.tar.gz

Anything else?

No response
@zhuwenxing zhuwenxing added kind/bug Issues or changes related a bug needs-triage Indicates an issue or PR lacks a `triage/foo` label and requires one. labels Dec 25, 2024
@yanliang567
Copy link
Contributor

/assign @weiliu1031
/unassign

@yanliang567 yanliang567 added this to the 2.5.1 milestone Dec 25, 2024
@yanliang567 yanliang567 added triage/accepted Indicates an issue or PR is ready to be actively worked on. and removed needs-triage Indicates an issue or PR lacks a `triage/foo` label and requires one. labels Dec 25, 2024
@xiaofan-luan
Copy link
Collaborator

@zhuwenxing
did the index succeed finally?
usually we don't evaluate index recover speed. If the indexnode crash sometimes we need tot wait for reschedule

@weiliu1031
Copy link
Contributor

weiliu1031 commented Dec 27, 2024 •
edited
Loading

image

  1. 2024/12/23 17:18:51 - The index_service received a request to create an index.
  2. 2024/12/23 17:20:03 - The index_service triggered the first segment's index task.
  3. 2024/12/23 17:20:49 - The index_service triggered the third segment's index task.
  4. 2024/12/23 17:20:52 - The SDK waited for the index creation to complete but timed out after 2 minutes.
  5. 2024/12/23 17:23 - Both index tasks were completed.

The index was eventually created successfully, but two issues were identified:

  1. The index_service took too long—approximately 70 seconds—from receiving the create index request to generating the index tasks. which is blocked by stat task.
  2. The index tasks also took nearly 2 minutes to complete.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees

@weiliu1031 weiliu1031

Labels
kind/bug Issues or changes related a bug triage/accepted Indicates an issue or PR is ready to be actively worked on.
Projects
None yet
Milestone
2.5.1
Development

No branches or pull requests
4 participants
@zhuwenxing @yanliang567 @xiaofan-luan @weiliu1031