Unstable test explainfor_test.go:79: testSerialSuite.TestExplainFor

[zhouqiang-cl]

Bug Report

Please answer these questions before submitting your issue. Thanks!

[2021-09-26T02:50:10.965Z] FAIL: explainfor_test.go:79: testSerialSuite.TestExplainFor

[2021-09-26T02:50:10.965Z] 

[2021-09-26T02:50:10.965Z] explainfor_test.go:125:

[2021-09-26T02:50:10.965Z]     check()

[2021-09-26T02:50:10.965Z] explainfor_test.go:120:

[2021-09-26T02:50:10.965Z]     c.Assert(buf.String(), Matches, ""+

[2021-09-26T02:50:10.965Z]         "TableReader_5 10000.00 0 root  time:.*, loops:1, cop_task: {num:.*, max:.*, proc_keys: 0, rpc_num: 1, rpc_time:.*} data:TableFullScan_4 N/A N/A\n"+

[2021-09-26T02:50:10.965Z]         "└─TableFullScan_4 10000.00 0 cop.* table:t1 tikv_task:{time:.*, loops:0} keep order:false, stats:pseudo N/A N/A")

[2021-09-26T02:50:10.965Z] ... value string = "" +

[2021-09-26T02:50:10.965Z] ...     "TableReader_5 10000.00 0 root  time:3.55ms, loops:1, cop_task: {num: 1, max: 2.84ms, proc_keys: 0, tot_proc: 2ms, rpc_num: 1, rpc_time: 2.59ms, copr_cache_hit_ratio: 0.00} data:TableFullScan_4 N/A N/A\n" +

[2021-09-26T02:50:10.965Z] ...     "└─TableFullScan_4 10000.00 0 cop[tikv] table:t1 tikv_task:{time:0s, loops:0} keep order:false, stats:pseudo N/A N/A"

[2021-09-26T02:50:10.965Z] ... regex string = "" +

[2021-09-26T02:50:10.965Z] ...     "TableReader_5 10000.00 0 root  time:.*, loops:1, cop_task: {num:.*, max:.*, proc_keys: 0, rpc_num: 1, rpc_time:.*} data:TableFullScan_4 N/A N/A\n" +

[2021-09-26T02:50:10.965Z] ...     "└─TableFullScan_4 10000.00 0 cop.* table:t1 tikv_task:{time:.*, loops:0} keep order:false, stats:pseudo N/A N/A"

1. Minimal reproduce step (Required)

in ci https://ci.pingcap.net/blue/organizations/jenkins/tidb-unit-test-nightly/detail/tidb-unit-test-nightly/11386/pipeline

2. What did you expect to see? (Required)

3. What did you see instead (Required)

4. What is your TiDB version? (Required)

[unconsolable]

Another occurrence https://ci.pingcap.net/blue/organizations/jenkins/tidb_ghpr_check_2/detail/tidb_ghpr_check_2/35837/pipeline/

[2021-09-27T07:02:32.362Z] FAIL: explainfor_test.go:79: testSerialSuite.TestExplainFor
[2021-09-27T07:02:32.362Z] 
[2021-09-27T07:02:32.362Z] explainfor_test.go:125:
[2021-09-27T07:02:32.362Z]     check()
[2021-09-27T07:02:32.362Z] explainfor_test.go:120:
[2021-09-27T07:02:32.362Z]     c.Assert(buf.String(), Matches, ""+
[2021-09-27T07:02:32.362Z]         "TableReader_5 10000.00 0 root  time:.*, loops:1, cop_task: {num:.*, max:.*, proc_keys: 0, rpc_num: 1, rpc_time:.*} data:TableFullScan_4 N/A N/A\n"+
[2021-09-27T07:02:32.362Z]         "└─TableFullScan_4 10000.00 0 cop.* table:t1 tikv_task:{time:.*, loops:0} keep order:false, stats:pseudo N/A N/A")
[2021-09-27T07:02:32.362Z] ... value string = "" +
[2021-09-27T07:02:32.362Z] ...     "TableReader_5 10000.00 0 root  time:1.54ms, loops:1, cop_task: {num: 1, max: 1.25ms, proc_keys: 0, tot_proc: 1ms, rpc_num: 1, rpc_time: 1.12ms, copr_cache_hit_ratio: 0.00} data:TableFullScan_4 N/A N/A\n" +
[2021-09-27T07:02:32.362Z] ...     "└─TableFullScan_4 10000.00 0 cop[tikv] table:t1 tikv_task:{time:0s, loops:0} keep order:false, stats:pseudo N/A N/A"
[2021-09-27T07:02:32.362Z] ... regex string = "" +
[2021-09-27T07:02:32.362Z] ...     "TableReader_5 10000.00 0 root  time:.*, loops:1, cop_task: {num:.*, max:.*, proc_keys: 0, rpc_num: 1, rpc_time:.*} data:TableFullScan_4 N/A N/A\n" +
[2021-09-27T07:02:32.362Z] ...     "└─TableFullScan_4 10000.00 0 cop.* table:t1 tikv_task:{time:.*, loops:0} keep order:false, stats:pseudo N/A N/A"

[github-actions]

Please check whether the issue should be labeled with 'affects-x.y' or 'fixes-x.y.z', and then remove 'needs-more-info' label.