obdiag version: 3.7.1
[2025-11-04 18:31:44.694] [DEBUG] - cmd: obdiag gather plan_monitor
[2025-11-04 18:31:44.694] [DEBUG] - opts: {'config_password': None, 'inner_config': None, 'trace_id': 'YB42AC16057E-000642C14260835B-0-0', 'store_dir': './', 'env': "{db_connect='-h172.22.5.126 -P2881 -uroot@ob_business -paaAA11__wU -Doceanbase'}", 'skip': None, 'c': '/root/.obdiag/config.yml', 'config': ['db_host=172.22.5.126', 'db_port=2881', 'tenant_sys.user=root@sys', 'tenant_sys.password=aaAA11__']}
[2025-11-04 18:31:44.694] [DEBUG] - mkdir /opt/oceanbase-diagnostic-tool/conf/inner_config.yml
[2025-11-04 18:31:44.701] [INFO] obdiag version: 3.7.1
[2025-11-04 18:31:44.701] [INFO] gather_plan_monitor start ...
[2025-11-04 18:31:44.701] [DEBUG] - start get_observer_version_by_sql . input: 172.22.5.126:2881
[2025-11-04 18:31:44.705] [DEBUG] - connect databse ...
[2025-11-04 18:31:44.708] [DEBUG] - get_observer_version_by_sql ob_version_info is ('5.7.25-OceanBase_CE-v4.3.5.4',)
[2025-11-04 18:31:44.711] [DEBUG] - connect databse ...
[2025-11-04 18:31:44.716] [DEBUG] - get host info: [{'ip': '172.22.5.104'}, {'ip': '172.22.5.123'}, {'ip': '172.22.5.126'}, {'ip': '172.22.5.24'}, {'ip': '172.22.5.3'}, {'ip': '172.22.5.33'}, {'ip': '172.22.5.39'}, {'ip': '172.22.5.59'}, {'ip': '172.22.5.66'}]
[2025-11-04 18:31:44.716] [DEBUG] - update nodes config: [{'ip': '172.22.5.104'}, {'ip': '172.22.5.123'}, {'ip': '172.22.5.126'}, {'ip': '172.22.5.24'}, {'ip': '172.22.5.3'}, {'ip': '172.22.5.33'}, {'ip': '172.22.5.39'}, {'ip': '172.22.5.59'}, {'ip': '172.22.5.66'}]
[2025-11-04 18:31:44.723] [DEBUG] - connect databse ...
[2025-11-04 18:31:44.728] [DEBUG] - connect databse ...
[2025-11-04 18:31:44.732] [DEBUG] - Detected MySQL mode successful, Database version: OceanBase_CE 4.3.5.4 (r104000042025090916-5cf5b925a25bf888aebaa288e251b85b1924e98a) (Built Sep  9 2025 17:46:36)
[2025-11-04 18:31:44.732] [DEBUG] - Use ./obdiag_gather_pack_20251104183144 as pack dir.
[2025-11-04 18:31:44.732] [DEBUG] - mkdir ./obdiag_gather_pack_20251104183144
[2025-11-04 18:31:44.733] [DEBUG] - [cs resource path] : /opt/oceanbase-diagnostic-tool/resources
[2025-11-04 18:31:44.734] [DEBUG] - [sql plan monitor report task] start
[2025-11-04 18:31:47.642] [DEBUG] - TraceID : YB42AC16057E-000642C14260835B-0-0 
[2025-11-04 18:31:47.643] [DEBUG] - SQL : select * from test.mz_ghb 
[2025-11-04 18:31:47.643] [DEBUG] - SVR_IP : 172.22.5.126 
[2025-11-04 18:31:47.643] [DEBUG] - SVR_PORT : 2882 
[2025-11-04 18:31:47.643] [DEBUG] - DB: test 
[2025-11-04 18:31:47.643] [DEBUG] - PLAN_ID: 307 
[2025-11-04 18:31:47.643] [DEBUG] - TENANT_NAME: ob_business 
[2025-11-04 18:31:47.643] [DEBUG] - TENANT_ID: 1002 
[2025-11-04 18:31:47.643] [DEBUG] - [sql plan monitor report task] report header
[2025-11-04 18:31:47.643] [DEBUG] - report header complete
[2025-11-04 18:31:47.643] [DEBUG] - [sql plan monitor report task] report sql_audit
[2025-11-04 18:31:47.644] [DEBUG] - select sql_audit from ob with SQL: 
[2025-11-04 18:31:50.472] [DEBUG] - sql_audit_result: +-----------------------------------+---------------------------+-------------+----------------------------+----------------------------+--------------+-------------+-----------+---------+---------+-----------+------------------------------+--------------+----------+--------------+
[2025-11-04 18:31:50.473] [DEBUG] - report sql_audit_result to file start ...
[2025-11-04 18:31:50.473] [DEBUG] - report sql_audit_result end
[2025-11-04 18:31:50.473] [DEBUG] - [sql plan monitor report task] report plan explain, sql: [select * from test.mz_ghb]
[2025-11-04 18:31:50.508] [DEBUG] - execute SQL: explain extended select * from test.mz_ghb
[2025-11-04 18:31:50.912] [INFO] test.mz_ghb -> oceanbase.DBA_OB_TABLE_STAT_STALE_INFO IS_STALE=NO
[2025-11-04 18:31:50.936] [DEBUG] - The statistics are up-to-date. The last collection time for the mz_ghb table is 2025-11-04 18:24:04??No action needed.
[2025-11-04 18:31:50.936] [DEBUG] - the result of optimization_info_warn is None
[2025-11-04 18:31:50.936] [DEBUG] - report sql_explain_result_sql complete
[2025-11-04 18:31:50.936] [DEBUG] - report sql_explain_result_sql complete
[2025-11-04 18:31:50.955] [DEBUG] - report sql_explain_result complete
[2025-11-04 18:31:50.955] [DEBUG] - [sql plan monitor report task] report plan cache
[2025-11-04 18:31:50.957] [DEBUG] - select plan_explain from ob complete
[2025-11-04 18:31:50.957] [DEBUG] - report plan_explain_sql complete
[2025-11-04 18:31:50.959] [DEBUG] - report plan_explain complete
[2025-11-04 18:31:50.959] [INFO] execute SQL: SELECT DBMS_XPLAN.DISPLAY_CURSOR(307, 'all', '172.22.5.126',  2882, 1002) FROM DUAL
[2025-11-04 18:31:50.976] [DEBUG] - display_cursor report complete
[2025-11-04 18:31:50.976] [DEBUG] - [sql plan monitor report task] report table schema
[2025-11-04 18:31:50.982] [DEBUG] - connect databse ...
[2025-11-04 18:31:50.987] [DEBUG] - connect databse ...
[2025-11-04 18:31:50.990] [DEBUG] - get observer version, by sql
[2025-11-04 18:31:50.990] [DEBUG] - start get_observer_version_by_sql . input: 172.22.5.126:2881
[2025-11-04 18:31:50.994] [DEBUG] - connect databse ...
[2025-11-04 18:31:50.997] [DEBUG] - get_observer_version_by_sql ob_version_info is ('5.7.25-OceanBase_CE-v4.3.5.4',)
[2025-11-04 18:31:51.094] [INFO] table count (('mz_ghb', 0),)
[2025-11-04 18:47:06.440] [INFO] data size (('172.22.5.104', 'test', 'mz_ghb', 'USER TABLE', Decimal('0.00'), Decimal('0.00')), ('172.22.5.104', 'test', '', 'INDEX', Decimal('0.00'), Decimal('0.00')), ('172.22.5.123', 'test', 'mz_ghb', 'USER TABLE', Decimal('0.00'), Decimal('0.00')), ('172.22.5.123', 'test', '', 'INDEX', Decimal('0.00'), Decimal('0.00')), ('172.22.5.126', 'test', 'mz_ghb', 'USER TABLE', Decimal('0.00'), Decimal('0.00')), ('172.22.5.126', 'test', '', 'INDEX', Decimal('0.00'), Decimal('0.00')), ('172.22.5.24', 'test', 'mz_ghb', 'USER TABLE', Decimal('0.00'), Decimal('0.00')), ('172.22.5.24', 'test', '', 'INDEX', Decimal('0.00'), Decimal('0.00')), ('172.22.5.3', 'test', 'mz_ghb', 'USER TABLE', Decimal('0.00'), Decimal('0.00')), ('172.22.5.3', 'test', '', 'INDEX', Decimal('0.00'), Decimal('0.00')), ('172.22.5.33', 'test', 'mz_ghb', 'USER TABLE', Decimal('0.00'), Decimal('0.00')), ('172.22.5.33', 'test', '', 'INDEX', Decimal('0.00'), Decimal('0.00')), ('172.22.5.39', 'test', 'mz_ghb', 'USER TABLE', Decimal('0.00'), Decimal('0.00')), ('172.22.5.39', 'test', '', 'INDEX', Decimal('0.00'), Decimal('0.00')), ('172.22.5.59', 'test', 'mz_ghb', 'USER TABLE', Decimal('0.00'), Decimal('0.00')), ('172.22.5.59', 'test', '', 'INDEX', Decimal('0.00'), Decimal('0.00')), ('172.22.5.66', 'test', 'mz_ghb', 'USER TABLE', Decimal('0.00'), Decimal('0.00')), ('172.22.5.66', 'test', '', 'INDEX', Decimal('0.00'), Decimal('0.00')))
[2025-11-04 18:47:06.444] [DEBUG] - table info file path:./obdiag_tabledump_result_20251104183144.txt
[2025-11-04 18:47:08.366] [DEBUG] - [ash report task] report ash, sql: [
[2025-11-04 18:47:08.366] [DEBUG]     SELECT SQL_PLAN_LINE_ID OP_ID, IF(EVENT = '', 'CPU + WAIT FOR CPU', EVENT) AS EVENT, WAIT_CLASS, COUNT(1) EVENT_CNT, ROUND(COUNT(1) * 100 / SUM(COUNT(1)) OVER (), 2) AS Percent, MAX(P1TEXT) P1TEXT, MAX(P1) P1, MAX(P2TEXT) P2TEXT,  MAX(P2) P2, MAX(P3TEXT) P3TEXT, MAX(P3) P3
[2025-11-04 18:47:08.366] [DEBUG]      FROM OCEANBASE.GV$ACTIVE_SESSION_HISTORY A WHERE CON_ID = 1002 AND TRACE_ID = 'YB42AC16057E-000642C14260835B-0-0' GROUP BY A.SQL_PLAN_LINE_ID, A.EVENT, A.WAIT_CLASS ORDER BY 1 ASC, 4 DESC
[2025-11-04 18:47:08.366] [DEBUG]     ]
[2025-11-04 18:47:08.386] [DEBUG] - execute SQL: 
[2025-11-04 18:47:08.387] [DEBUG] - ash report complete
[2025-11-04 18:47:08.390] [DEBUG] - init sql plan monitor stat complete
[2025-11-04 18:47:08.390] [DEBUG] - [sql plan monitor report task] report sql_audit details
[2025-11-04 18:47:14.776] [DEBUG] - report full sql audit complete
[2025-11-04 18:47:14.776] [DEBUG] - [sql plan monitor report task] report sql plan monitor dfo
[2025-11-04 18:47:14.851] [WARNING] failed to find sql_plan_monitor data, please add hint /*+ monitor*/ to your SQL before executing it.
[2025-11-04 18:47:14.852] [DEBUG] - report SQL_PLAN_MONITOR DFO complete
[2025-11-04 18:47:14.903] [DEBUG] - report SQL_PLAN_MONITOR DFO SCHED complete
[2025-11-04 18:47:14.958] [DEBUG] - report SQL_PLAN_MONITOR DFO graph data complete
[2025-11-04 18:47:14.958] [DEBUG] - [db time display task] report db time display
[2025-11-04 18:47:14.989] [DEBUG] - execute SQL: 
[2025-11-04 18:47:14.990] [DEBUG] - DB Time display complete
[2025-11-04 18:47:14.990] [DEBUG] - report db time display complete
[2025-11-04 18:47:14.990] [DEBUG] - [sql plan monitor report task] report sql plan monitor group by server
[2025-11-04 18:47:15.051] [DEBUG] - report SQL_PLAN_MONITOR SQC complete
[2025-11-04 18:47:15.107] [DEBUG] - report SQL_PLAN_MONITOR SQC operator priority start, DATA: <script> var svr_agg_serial_v1 = [{start:0}];</script><p>算子优先视图</p><div class='bar' id='svr_agg_serial_v1'></div>
[2025-11-04 18:47:15.108] [DEBUG] - report SQL_PLAN_MONITOR SQC operator priority complete
[2025-11-04 18:47:15.174] [DEBUG] - report SQL_PLAN_MONITOR SQC operator priority start, DATA: <script> var svr_agg_serial_v2 = [{start:0}];</script><p>算子优先视图</p><div class='bar' id='svr_agg_serial_v2'></div>
[2025-11-04 18:47:15.175] [DEBUG] - report SQL_PLAN_MONITOR SQC server priority complete
[2025-11-04 18:47:15.175] [DEBUG] - report SQL_PLAN_MONITOR fast preview complete
[2025-11-04 18:47:15.175] [DEBUG] - [sql plan monitor report task] sql plan monitor detail operator
[2025-11-04 18:47:15.248] [DEBUG] - report SQL_PLAN_MONITOR details complete
[2025-11-04 18:47:15.302] [DEBUG] - report SQL_PLAN_MONITOR details operator priority complete
[2025-11-04 18:47:15.302] [DEBUG] - [sql plan monitor report task] sql plan monitor group by priority
[2025-11-04 18:47:15.356] [DEBUG] - report SQL_PLAN_MONITOR details server priority complete
[2025-11-04 18:47:15.357] [DEBUG] - get observer commit id, by sql
[2025-11-04 18:47:15.362] [DEBUG] - connect databse ...
[2025-11-04 18:47:15.370] [DEBUG] - report footer complete
[2025-11-04 18:47:15.370] [DEBUG] - [sql plan monitor report task] end
[2025-11-04 18:47:15.371] [INFO] 
[2025-11-04 18:47:15.371] [INFO] Gather Sql Plan Monitor Summary:
[2025-11-04 18:47:15.371] [INFO] +-----------+-----------+--------+-------------------------------------+
[2025-11-04 18:47:15.371] [INFO] | Cluster   | Status    | Time   | PackPath                            |
[2025-11-04 18:47:15.371] [INFO] +===========+===========+========+=====================================+
[2025-11-04 18:47:15.371] [INFO] |           | Completed | 930 s  | ./obdiag_gather_pack_20251104183144 |
[2025-11-04 18:47:15.371] [INFO] +-----------+-----------+--------+-------------------------------------+
[2025-11-04 18:47:15.372] [INFO] Trace ID: 75c18ff6-b969-11f0-8b7a-fa163e403b9f
[2025-11-04 18:47:15.372] [INFO] If you want to view detailed obdiag logs, please run: obdiag display-trace 75c18ff6-b969-11f0-8b7a-fa163e403b9f