session: fix processInfo time when using driver to prepare and execute query (#41151)

[ti-chi-bot]

This is an automated cherry-pick of #41151

What problem does this PR solve?

Issue Number: close #41057

Problem Summary:
globalMemoryControl use sql start time to check whether a sql is finished. If the time is not updated, the killing will never finish.

What is changed and how it works?

Check List

Tests

• Unit test
• Integration test
• Manual test (add detailed scripts or steps below)

1. Use go-sql-driver to prapare sql select sleep(?)
2. execute using args 10
3. Sleep 10s in test
4. Loop Step 2-3, and show processlists

tidb> show processlist;
+---------------+------+-----------------+------+---------+------+------------+------------------+
| Id            | User | Host            | db   | Command | Time | State      | Info             |
+---------------+------+-----------------+------+---------+------+------------+------------------+
| 2199023255955 | root | 127.0.0.1:33122 | NULL | Query   |    0 | autocommit | show processlist |
| 2199023255957 | root | 127.0.0.1:53912 | test | Sleep   |   19 | autocommit | NULL             |
+---------------+------+-----------------+------+---------+------+------------+------------------+
2 rows in set (0.001 sec)

tidb> show processlist;
+---------------+------+-----------------+------+---------+------+------------+------------------+
| Id            | User | Host            | db   | Command | Time | State      | Info             |
+---------------+------+-----------------+------+---------+------+------------+------------------+
| 2199023255955 | root | 127.0.0.1:33122 | NULL | Query   |    0 | autocommit | show processlist |
| 2199023255957 | root | 127.0.0.1:53912 | test | Execute |    0 | autocommit | NULL             |
+---------------+------+-----------------+------+---------+------+------------+------------------+
2 rows in set (0.001 sec)

We can see the Time have been reset.

Manual test 2:
Before the pr, killing is not finished.

[2023/02/09 14:04:06.388 +08:00] [WARN] [servermemorylimit.go:126] ["global memory controller tries to kill the top1 memory consumer"] [connID=3464673289308209561] ["sql digest"=620e7d8f9d39e8d05c0d44a1ac5d1b480ba12174de9fa330e1a95e8aee27c081] ["sql text"=] [tidb_server_memory_limit=536870912] ["heap inuse"=5
76815104] ["sql memory usage"=377011523]
[2023/02/09 14:04:06.389 +08:00] [WARN] [tracker.go:458] ["global memory controller, NeedKill signal is received successfully"] [connID=3464673289308209561]
[2023/02/09 14:04:11.485 +08:00] [WARN] [servermemorylimit.go:96] ["global memory controller failed to kill the top-consumer in 5s"] [connID=3464673289308209561] ["sql digest"=620e7d8f9d39e8d05c0d44a1ac5d1b480ba12174de9fa330e1a95e8aee27c081] ["sql text"=] ["sql memory usage"=0]
[2023/02/09 14:04:16.585 +08:00] [WARN] [servermemorylimit.go:96] ["global memory controller failed to kill the top-consumer in 10s"] [connID=3464673289308209561] ["sql digest"=620e7d8f9d39e8d05c0d44a1ac5d1b480ba12174de9fa330e1a95e8aee27c081] ["sql text"=] ["sql memory usage"=33506376]
[2023/02/09 14:04:21.684 +08:00] [WARN] [servermemorylimit.go:96] ["global memory controller failed to kill the top-consumer in 15s"] [connID=3464673289308209561] ["sql digest"=620e7d8f9d39e8d05c0d44a1ac5d1b480ba12174de9fa330e1a95e8aee27c081] ["sql text"=] ["sql memory usage"=2789854714]
[2023/02/09 14:04:26.764 +08:00] [WARN] [servermemorylimit.go:96] ["global memory controller failed to kill the top-consumer in 20s"] [connID=3464673289308209561] ["sql digest"=620e7d8f9d39e8d05c0d44a1ac5d1b480ba12174de9fa330e1a95e8aee27c081] ["sql text"=] ["sql memory usage"=4554466769]
[2023/02/09 14:04:32.023 +08:00] [WARN] [servermemorylimit.go:96] ["global memory controller failed to kill the top-consumer in 25s"] [connID=3464673289308209561] ["sql digest"=620e7d8f9d39e8d05c0d44a1ac5d1b480ba12174de9fa330e1a95e8aee27c081] ["sql text"=] ["sql memory usage"=4554466769]
[2023/02/09 14:04:37.287 +08:00] [WARN] [servermemorylimit.go:96] ["global memory controller failed to kill the top-consumer in 30s"] [connID=3464673289308209561] ["sql digest"=620e7d8f9d39e8d05c0d44a1ac5d1b480ba12174de9fa330e1a95e8aee27c081] ["sql text"=] ["sql memory usage"=5317908437]
[2023/02/09 14:04:42.385 +08:00] [WARN] [servermemorylimit.go:96] ["global memory controller failed to kill the top-consumer in 35s"] [connID=3464673289308209561] ["sql digest"=620e7d8f9d39e8d05c0d44a1ac5d1b480ba12174de9fa330e1a95e8aee27c081] ["sql text"=] ["sql memory usage"=6715429525]
[2023/02/09 14:04:47.386 +08:00] [WARN] [servermemorylimit.go:96] ["global memory controller failed to kill the top-consumer in 40s"] [connID=3464673289308209561] ["sql digest"=620e7d8f9d39e8d05c0d44a1ac5d1b480ba12174de9fa330e1a95e8aee27c081] ["sql text"=] ["sql memory usage"=7924455893]
[2023/02/09 14:04:52.534 +08:00] [WARN] [servermemorylimit.go:96] ["global memory controller failed to kill the top-consumer in 46s"] [connID=3464673289308209561] ["sql digest"=620e7d8f9d39e8d05c0d44a1ac5d1b480ba12174de9fa330e1a95e8aee27c081] ["sql text"=] ["sql memory usage"=8986996949]
[2023/02/09 14:04:57.612 +08:00] [WARN] [servermemorylimit.go:96] ["global memory controller failed to kill the top-consumer in 51s"] [connID=3464673289308209561] ["sql digest"=620e7d8f9d39e8d05c0d44a1ac5d1b480ba12174de9fa330e1a95e8aee27c081] ["sql text"=] ["sql memory usage"=10132238165]
[2023/02/09 14:05:02.704 +08:00] [WARN] [servermemorylimit.go:96] ["global memory controller failed to kill the top-consumer in 56s"] [connID=3464673289308209561] ["sql digest"=620e7d8f9d39e8d05c0d44a1ac5d1b480ba12174de9fa330e1a95e8aee27c081] ["sql text"=] ["sql memory usage"=11226425045]

After this pr, can cancel the running sql.

[2023/02/09 14:11:33.960 +08:00] [WARN] [servermemorylimit.go:126] ["global memory controller tries to kill the top1 memory consumer"] [connID=4469145331002507667] ["sql digest"=620e7d8f9d39e8d05c0d44a1ac5d1b480ba12174de9fa330e1a95e8aee27c081] ["sql text"=] [tidb_server_memory_limit=536870912] ["heap inuse"=562315264] ["sql memory usage"=388879163]
[2023/02/09 14:11:33.979 +08:00] [WARN] [tracker.go:458] ["global memory controller, NeedKill signal is received successfully"] [connID=4469145331002507667]
[2023/02/09 14:11:34.092 +08:00] [WARN] [servermemorylimit.go:112] ["global memory controller killed the top1 memory consumer successfully"]
[2023/02/09 14:11:44.574 +08:00] [WARN] [servermemorylimit.go:126] ["global memory controller tries to kill the top1 memory consumer"] [connID=4469145331002507667] ["sql digest"=620e7d8f9d39e8d05c0d44a1ac5d1b480ba12174de9fa330e1a95e8aee27c081] ["sql text"=] [tidb_server_memory_limit=536870912] ["heap inuse"=664477696] ["sql memory usage"=450551538]
[2023/02/09 14:11:44.576 +08:00] [WARN] [tracker.go:458] ["global memory controller, NeedKill signal is received successfully"] [connID=4469145331002507667]
[2023/02/09 14:11:44.676 +08:00] [WARN] [servermemorylimit.go:112] ["global memory controller killed the top1 memory consumer successfully"]
[2023/02/09 14:11:55.164 +08:00] [WARN] [servermemorylimit.go:126] ["global memory controller tries to kill the top1 memory consumer"] [connID=4469145331002507667] ["sql digest"=620e7d8f9d39e8d05c0d44a1ac5d1b480ba12174de9fa330e1a95e8aee27c081] ["sql text"=] [tidb_server_memory_limit=536870912] ["heap inuse"=553074688] ["sql memory usage"=380063432]
[2023/02/09 14:11:55.164 +08:00] [WARN] [tracker.go:458] ["global memory controller, NeedKill signal is received successfully"] [connID=4469145331002507667]
[2023/02/09 14:11:55.274 +08:00] [WARN] [servermemorylimit.go:112] ["global memory controller killed the top1 memory consumer successfully"]

• No code

Side effects

• Performance regression: Consumes more CPU
• Performance regression: Consumes more Memory
• Breaking backward compatibility

Documentation

• Affects user behaviors
• Contains syntax changes
• Contains variable changes
• Contains experimental features
• Changes MySQL compatibility

Release note

Please refer to Release Notes Language Style Guide to write a quality release note.

None

[ti-chi-bot]

[REVIEW NOTIFICATION]

This pull request has been approved by:

• XuHuaiyu
• guo-shaoge

To complete the pull request process, please ask the reviewers in the list to review by filling /cc @reviewer in the comment.
After your PR has acquired the required number of LGTMs, you can assign this pull request to the committer in the list by filling /assign @committer in the comment to help you merge this pull request.

The full list of commands accepted by this bot can be found here.

Reviewer can indicate their review by submitting an approval review.
Reviewer can cancel approval by submitting a request changes review.

[wuhuizuo]

/hold high priority for trunk branch

[ti-chi-bot]

/unhold

[wshwsh12]

/merge

[ti-chi-bot]

This pull request has been accepted and is ready to merge.

Commit hash: b0e4508

[wshwsh12]

/retest