matrixone icon indicating copy to clipboard operation
matrixone copied to clipboard

[Bug]: 特定SQL查询时间过长

Open xiaoshuwei opened this issue 1 year ago • 31 comments

Is there an existing issue for the same bug?

  • [X] I have checked the existing issues.

Branch Name

1.2-dev

Commit ID

994f3da

Other Environment Information

- Hardware parameters:
- OS type:
- Others:
aliyun dev环境

Actual Behavior

日志如下:

2024/06/26 07:01:50.466740 +0000 WARN meta/meta.go:95 trace {"elapsed": "20.322383672s", "rows": 1, "sql": "/* cloud_nonuser */SELECT SUM(size) AS size FROM (SELECT mo_table_size(reldatabase, relname) AS size FROM mo_catalog.mo_tables WHERE relkind IN('r', 'e', 'cluster', 'v') AND reldatabase = 'mysql') AS t;"}
2024/06/26 07:01:57.088838 +0000 WARN meta/meta.go:95 trace {"elapsed": "26.944445574s", "rows": 1, "sql": "/* cloud_nonuser */SELECT SUM(size) AS size FROM (SELECT mo_table_size(reldatabase, relname) AS size FROM mo_catalog.mo_tables WHERE relkind IN('r', 'e', 'cluster', 'v') AND reldatabase = 'tpcc_100') AS t;"}
2024/06/26 07:01:57.187651 +0000 WARN meta/meta.go:95 trace {"elapsed": "27.043348178s", "rows": 1, "sql": "/* cloud_nonuser */SELECT SUM(size) AS size FROM (SELECT mo_table_size(reldatabase, relname) AS size FROM mo_catalog.mo_tables WHERE relkind IN('r', 'e', 'cluster', 'v') AND reldatabase = 'system_metrics') AS t;"}
2024/06/26 07:01:57.922575 +0000 WARN meta/meta.go:95 trace {"elapsed": "27.77832579s", "rows": 1, "sql": "/* cloud_nonuser */SELECT SUM(size) AS size FROM (SELECT mo_table_size(reldatabase, relname) AS size FROM mo_catalog.mo_tables WHERE relkind IN('r', 'e', 'cluster', 'v') AND reldatabase = 'tpcc_10') AS t;"}
2024/06/26 07:02:08.899664 +0000 WARN meta/meta.go:95 trace {"elapsed": "38.755162271s", "rows": 1, "sql": "/* cloud_nonuser */SELECT SUM(size) AS size FROM (SELECT mo_table_size(reldatabase, relname) AS size FROM mo_catalog.mo_tables WHERE relkind IN('r', 'e', 'cluster', 'v') AND reldatabase = 'mo_catalog') AS t;"}
2024/06/26 07:02:16.975262 +0000 WARN meta/meta.go:95 trace {"elapsed": "46.830928621s", "rows": 1, "sql": "/* cloud_nonuser */SELECT SUM(size) AS size FROM (SELECT mo_table_size(reldatabase, relname) AS size FROM mo_catalog.mo_tables WHERE relkind IN('r', 'e', 'cluster', 'v') AND reldatabase = 'information_schema') AS t;"}

发生事件为东八区2024/06/26 15:01:50.466740,实例ID为01903344-4d60-7fb3-8a5d-02dafaf20ebc。不稳定复现。

Expected Behavior

正常执行时间,如:

2024/06/26 06:07:59.153671 +0000 WARN meta/meta.go:95 trace {"elapsed": "861.54913ms", "rows": 1, "sql": "/* cloud_nonuser */SELECT SUM(size) AS size FROM (SELECT mo_table_size(reldatabase, relname) AS size FROM mo_catalog.mo_tables WHERE relkind IN('r', 'e', 'cluster', 'v') AND reldatabase = 'mo_sample_data_tpch_sf1') AS t;"}

Steps to Reproduce

不稳定复现

Additional information

No response

xiaoshuwei avatar Jun 26 '24 08:06 xiaoshuwei

@aunjgr please help on this one

volgariver6 avatar Jun 26 '24 08:06 volgariver6

on leave

aunjgr avatar Jul 03 '24 15:07 aunjgr

The similar issue occurred in Prod env after upgrade MO to v1.2.1-27b34c670-2024-06-28:

2024/07/04 13:35:05.364552 +0000 ERROR meta/meta.go:95 trace {"error": "context canceled", "elapsed": "59.948734333s", "rows": 0, "sql": "/* cloud_nonuser */SELECT SUM(size) AS size FROM (SELECT mo_table_size(reldatabase, relname) AS size FROM mo_catalog.mo_tables WHERE relkind IN('r', 'e', 'cluster', 'v') AND reldatabase = 'write_test_0424') AS t;"}
2024/07/04 13:36:56.815791 +0000 ERROR meta/meta.go:95 trace {"error": "context canceled", "elapsed": "59.948137034s", "rows": 0, "sql": "/* cloud_nonuser */SELECT SUM(size) AS size FROM (SELECT mo_table_size(reldatabase, relname) AS size FROM mo_catalog.mo_tables WHERE relkind IN('r', 'e', 'cluster', 'v') AND reldatabase = 'write_test_0424') AS t;"}
 2024/07/04 13:39:55.694969 +0000 WARN models/meta.go:222 trace {"elapsed": "22.750829979s", "rows": 452, "sql": "SHOW TABLE STATUS FROM `test4`"}
 2024/07/04 13:40:23.879890 +0000 WARN models/meta.go:222 trace {"elapsed": "18.981660653s", "rows": 452, "sql": "SHOW TABLE STATUS FROM `test`"}
 2024/07/04 13:40:29.409728 +0000 WARN models/meta.go:222 trace {"elapsed": "18.671528182s", "rows": 452, "sql": "SHOW TABLE STATUS FROM `test4`"}

DanielZhangQD avatar Jul 05 '24 03:07 DanielZhangQD

@zengyan1 你先看看吧,谢谢

aunjgr avatar Jul 09 '24 13:07 aunjgr

还没看

zengyan1 avatar Jul 12 '24 12:07 zengyan1

还没看

zengyan1 avatar Jul 17 '24 10:07 zengyan1

还没看

zengyan1 avatar Jul 22 '24 12:07 zengyan1

还没看

zengyan1 avatar Jul 25 '24 11:07 zengyan1

还没看

zengyan1 avatar Jul 30 '24 13:07 zengyan1

还没看

zengyan1 avatar Aug 02 '24 11:08 zengyan1

还没看

zengyan1 avatar Aug 08 '24 12:08 zengyan1

还没看

zengyan1 avatar Aug 14 '24 11:08 zengyan1

还没看

zengyan1 avatar Aug 20 '24 10:08 zengyan1

还没看

zengyan1 avatar Aug 26 '24 12:08 zengyan1

还没看

zengyan1 avatar Aug 30 '24 12:08 zengyan1

还没看

zengyan1 avatar Sep 04 '24 11:09 zengyan1

还没看

zengyan1 avatar Sep 10 '24 12:09 zengyan1

还没看

zengyan1 avatar Sep 14 '24 11:09 zengyan1

还没看

zengyan1 avatar Sep 20 '24 11:09 zengyan1

还没看

zengyan1 avatar Sep 27 '24 10:09 zengyan1

还没看

zengyan1 avatar Oct 09 '24 10:10 zengyan1

还没看

zengyan1 avatar Oct 15 '24 10:10 zengyan1

还没看

zengyan1 avatar Oct 19 '24 10:10 zengyan1

暂无进展,预计2.0不会修复

zengyan1 avatar Oct 23 '24 12:10 zengyan1

暂无进展

zengyan1 avatar Oct 29 '24 11:10 zengyan1

Not enough info. No repro steps, no env.

fengttt avatar Nov 01 '24 06:11 fengttt

depend on https://github.com/matrixorigin/matrixone/issues/15708

sukki37 avatar Nov 04 '24 08:11 sukki37

in progress

gouhongshen avatar Nov 07 '24 10:11 gouhongshen

经和徐鹏沟通,这个目前不一定来的及在2.0.1版本解决,可以考虑放到2.1.0版本解决

aressu1985 avatar Nov 12 '24 07:11 aressu1985

inprogress

gouhongshen avatar Nov 15 '24 10:11 gouhongshen