2024-12-17T14:21:04.786: current working dir: /root/qa/mctester.
2024-12-17T14:21:04.888: current logging dir: ../mctester/www.
2024-12-17T14:21:04.889: ---------------------------------------------------------------
2024-12-17T14:21:04.889: mctester entry: 2024-12-17T14:21:04.889 mode=50
2024-12-17T14:21:04.889: ---------------------------------------------------------------
2024-12-17T14:21:05.004: EXE: 1/git describe --tags.
2024-12-17T14:21:05.089: Row 1: v1.1-201-g90c2042.
2024-12-17T14:21:05.090: EXE: 1/git rev-list --count HEAD.
2024-12-17T14:21:05.095: Row 1: 258.
2024-12-17T14:21:05.095: EXE: 1/git rev-parse HEAD.
2024-12-17T14:21:05.097: Row 1: 90c2042af9ce0b055e0a62dafd703b27e21c4128.
2024-12-17T14:21:05.098: mctester: v1.1.258 (90c2042af9ce0b055e0a62dafd703b27e21c4128).
2024-12-17T14:21:05.098: Entering into a performance tests mode.
2024-12-17T14:21:05.098: ------ test: start, 2024-12-17T14:21:05.098
2024-12-17T14:21:05.099: ------ test: 2000
2024-12-17T14:21:05.100: CMD: ssh -o ServerAliveInterval=60 -o ServerAliveCountMax=3 root@megatron.headai.com "top -b -n 1"
2024-12-17T14:21:05.100: EXE: 1/ssh -o ServerAliveInterval=60 -o ServerAliveCountMax=3 root@megatron.headai.com "top -b -n 1".
2024-12-17T14:21:35.452: Row 1: top - 14:21:35 up 91 days, 6:48, 0 users, load average: 0.31, 0.08, 0.02.
2024-12-17T14:21:35.452: Row 2: Tasks: 364 total, 1 running, 363 sleeping, 0 stopped, 0 zombie.
2024-12-17T14:21:35.453: Row 3: %Cpu(s): 3.2 us, 0.1 sy, 0.0 ni, 96.6 id, 0.1 wa, 0.0 hi, 0.0 si, 0.0 st.
2024-12-17T14:21:35.453: Row 4: KiB Mem : 13202036+total, 613024 free, 60820904 used, 70586448 buff/cache.
2024-12-17T14:21:35.453: Row 5: KiB Swap: 0 total, 0 free, 0 used. 69609968 avail Mem .
2024-12-17T14:21:35.454: Row 6: .
2024-12-17T14:21:35.454: Row 7: PID USER PR NI VIRT RES SHR S %CPU %MEM TIME+ COMMAND.
2024-12-17T14:21:35.461: Row 8: 1978 mysql 20 0 99.645g 0.048t 1.144g S 73.3 38.7 13639:40 mysqld.
2024-12-17T14:21:35.461: Row 9: 1 root 20 0 43660 2552 1268 S 0.0 0.0 3:23.64 init.
2024-12-17T14:21:35.462: Row 10: 2 root 20 0 0 0 0 S 0.0 0.0 0:01.23 kthreadd.
2024-12-17T14:21:35.462: Row 11: 3 root 20 0 0 0 0 S 0.0 0.0 4:28.69 ksoftirqd+.
2024-12-17T14:21:35.462: Row 12: 5 root 0 -20 0 0 0 S 0.0 0.0 0:00.00 kworker/0+.
2024-12-17T14:21:35.462: Row 13: 7 root 20 0 0 0 0 S 0.0 0.0 29:52.35 rcu_sched.
2024-12-17T14:21:35.462: Row 14: 8 root 20 0 0 0 0 S 0.0 0.0 0:00.00 rcu_bh.
2024-12-17T14:21:35.462: Row 15: 9 root rt 0 0 0 0 S 0.0 0.0 0:05.47 migration+.
2024-12-17T14:21:35.463: Row 16: 10 root rt 0 0 0 0 S 0.0 0.0 0:22.01 watchdog/0.
2024-12-17T14:21:35.463: Row 17: 11 root rt 0 0 0 0 S 0.0 0.0 0:20.09 watchdog/1.
2024-12-17T14:21:35.463: Row 18: 12 root rt 0 0 0 0 S 0.0 0.0 0:10.68 migration+.
2024-12-17T14:21:35.463: Row 19: 13 root 20 0 0 0 0 S 0.0 0.0 0:01.78 ksoftirqd+.
2024-12-17T14:21:35.463: Row 20: 15 root 0 -20 0 0 0 S 0.0 0.0 0:00.00 kworker/1+.
2024-12-17T14:21:35.463: Row 21: 16 root rt 0 0 0 0 S 0.0 0.0 0:20.53 watchdog/2.
2024-12-17T14:21:35.464: Row 22: 17 root rt 0 0 0 0 S 0.0 0.0 0:04.55 migration+.
2024-12-17T14:21:35.464: Row 23: 18 root 20 0 0 0 0 S 0.0 0.0 0:01.43 ksoftirqd+.
2024-12-17T14:21:35.464: Row 24: 20 root 0 -20 0 0 0 S 0.0 0.0 0:00.00 kworker/2+.
2024-12-17T14:21:35.538: SUC: 30438ms Uptime 91 days, 1min 0.31, 5min 0.08, 15min 0.02, mem:132020360 total / 613024 free / 60820904 used / 70586448 cache / 69609968 avail
2024-12-17T14:21:35.552: ------ test: 2001
2024-12-17T14:21:35.552: CMD: top -b -E k -n 1
2024-12-17T14:21:35.552: EXE: 1/top -b -E k -n 1.
2024-12-17T14:21:35.708: Row 1: top - 14:21:35 up 35 days, 21:03, 0 users, load average: 0.00, 0.00, 0.00.
2024-12-17T14:21:35.708: Row 2: Tasks: 99 total, 1 running, 97 sleeping, 0 stopped, 1 zombie.
2024-12-17T14:21:35.709: Row 3: %Cpu(s): 0.0 us, 0.0 sy, 0.0 ni,100.0 id, 0.0 wa, 0.0 hi, 0.0 si, 0.0 st.
2024-12-17T14:21:35.709: Row 4: KiB Mem : 1907632 total, 511124 free, 391968 used, 1004540 buff/cache.
2024-12-17T14:21:35.709: Row 5: KiB Swap: 0 total, 0 free, 0 used. 1298872 avail Mem .
2024-12-17T14:21:35.709: Row 6: .
2024-12-17T14:21:35.709: Row 7: PID USER PR NI VIRT RES SHR S %CPU %MEM TIME+ COMMAND.
2024-12-17T14:21:35.710: Row 8: 1 root 20 0 167784 11964 7080 S 0.0 0.6 0:56.78 systemd.
2024-12-17T14:21:35.710: Row 9: 2 root 20 0 0 0 0 S 0.0 0.0 0:00.09 kthreadd.
2024-12-17T14:21:35.710: Row 10: 3 root 0 -20 0 0 0 I 0.0 0.0 0:00.00 rcu_gp.
2024-12-17T14:21:35.710: Row 11: 4 root 0 -20 0 0 0 I 0.0 0.0 0:00.00 rcu_par+.
2024-12-17T14:21:35.710: Row 12: 5 root 0 -20 0 0 0 I 0.0 0.0 0:00.00 slub_fl+.
2024-12-17T14:21:35.710: Row 13: 6 root 0 -20 0 0 0 I 0.0 0.0 0:00.00 netns.
2024-12-17T14:21:35.711: Row 14: 8 root 0 -20 0 0 0 I 0.0 0.0 0:00.00 kworker+.
2024-12-17T14:21:35.711: Row 15: 10 root 0 -20 0 0 0 I 0.0 0.0 0:00.00 mm_perc+.
2024-12-17T14:21:35.711: Row 16: 11 root 20 0 0 0 0 S 0.0 0.0 0:00.00 rcu_tas+.
2024-12-17T14:21:35.711: Row 17: 12 root 20 0 0 0 0 S 0.0 0.0 0:00.00 rcu_tas+.
2024-12-17T14:21:35.711: Row 18: 13 root 20 0 0 0 0 S 0.0 0.0 0:30.80 ksoftir+.
2024-12-17T14:21:35.711: Row 19: 14 root 20 0 0 0 0 I 0.0 0.0 0:53.03 rcu_sch+.
2024-12-17T14:21:35.711: Row 20: 15 root rt 0 0 0 0 S 0.0 0.0 0:07.81 migrati+.
2024-12-17T14:21:35.712: Row 21: 16 root -51 0 0 0 0 S 0.0 0.0 0:00.00 idle_in+.
2024-12-17T14:21:35.712: Row 22: 18 root 20 0 0 0 0 S 0.0 0.0 0:00.00 cpuhp/0.
2024-12-17T14:21:35.712: Row 23: 19 root 20 0 0 0 0 S 0.0 0.0 0:00.00 kdevtmp+.
2024-12-17T14:21:35.712: Row 24: 20 root 0 -20 0 0 0 I 0.0 0.0 0:00.00 inet_fr+.
2024-12-17T14:21:35.721: SUC: 169ms Uptime 35 days, 1min 0.00, 5min 0.00, 15min 0.00, mem:1907632 total / 511124 free / 391968 used / 1004540 cache / 1298872 avail
2024-12-17T14:21:35.725: ------ test: 2002
2024-12-17T14:21:35.726: CMD: df -h
2024-12-17T14:21:35.726: EXE: 1/df -h.
2024-12-17T14:21:35.728: Row 1: Filesystem Size Used Avail Use% Mounted on.
2024-12-17T14:21:35.729: Row 2: tmpfs 187M 20M 167M 11% /run.
2024-12-17T14:21:35.729: Row 3: /dev/vda2 20G 14G 5.2G 73% /.
2024-12-17T14:21:35.729: Row 4: tmpfs 932M 0 932M 0% /dev/shm.
2024-12-17T14:21:35.729: Row 5: tmpfs 5.0M 0 5.0M 0% /run/lock.
2024-12-17T14:21:35.731: SUC: 5ms Diskfree qa.headai.com: tmpfs:11%, /dev/vda2:73%, tmpfs:0%, tmpfs:0%.
2024-12-17T14:21:35.731: ------ test: 2003
2024-12-17T14:21:35.732: CMD: ssh -o ServerAliveInterval=60 -o ServerAliveCountMax=3 root@megatron.headai.com "df -h"
2024-12-17T14:21:35.732: EXE: 1/ssh -o ServerAliveInterval=60 -o ServerAliveCountMax=3 root@megatron.headai.com "df -h".
2024-12-17T14:22:05.980: Row 1: Filesystem Size Used Avail Use% Mounted on.
2024-12-17T14:22:05.981: Row 2: udev 63G 4.0K 63G 1% /dev.
2024-12-17T14:22:05.981: Row 3: tmpfs 13G 544M 13G 5% /run.
2024-12-17T14:22:05.981: Row 4: /dev/vda1 757G 512G 215G 71% /.
2024-12-17T14:22:05.981: Row 5: none 4.0K 0 4.0K 0% /sys/fs/cgroup.
2024-12-17T14:22:05.982: Row 6: none 5.0M 0 5.0M 0% /run/lock.
2024-12-17T14:22:05.982: Row 7: cgmfs 100K 0 100K 0% /run/cgmanager/fs.
2024-12-17T14:22:05.984: Row 8: none 63G 0 63G 0% /run/shm.
2024-12-17T14:22:05.984: Row 9: none 100M 0 100M 0% /run/user.
2024-12-17T14:22:05.985: Row 10: /dev/vdb 985G 230G 705G 25% /opt/tomcat/webapps/ROOT/analysis.
2024-12-17T14:22:05.986: Row 11: tmpfs 100K 0 100K 0% /var/lib/lxd/shmounts.
2024-12-17T14:22:05.986: Row 12: tmpfs 100K 0 100K 0% /var/lib/lxd/devlxd.
2024-12-17T14:22:05.986: Row 13: tmpfs 13G 0 13G 0% /run/user/0.
2024-12-17T14:22:05.989: SUC: 30257ms Diskfree megatron.headai.com: udev:1%, tmpfs:5%, /dev/vda1:71%, none:0%, none:0%, cgmfs:0%, none:0%, none:0%, /dev/vdb:25%, tmpfs:0%, tmpfs:0%, tmpfs:0%.
2024-12-17T14:22:05.990: ------ test: 2004
2024-12-17T14:22:05.990: URL: https://headai.com
2024-12-17T14:22:06.582: SUC: 587ms, Trusted by 100+ customers and partners globally, length 181609.
2024-12-17T14:22:06.585: ------ test: 2005
2024-12-17T14:22:07.052: SQL: show global status like 'threads_connected';.
2024-12-17T14:22:07.072: Results: value=2/200.
2024-12-17T14:22:07.072: SQL: value, lines: 1, ret=1, 2.
2024-12-17T14:22:07.073: SUC: 488ms 2
2024-12-17T14:22:07.079: ------ test: 2006
2024-12-17T14:22:07.175: SQL: SHOW PROCESSLIST;.
2024-12-17T14:22:07.179: Row 1: 25389856,root,localhost:34520,null,Query,0,starting,SHOW PROCESSLIST.
2024-12-17T14:22:07.179: SQL: COUNTNOTLINES:Command, lines: 1, ret=1, 1.
2024-12-17T14:22:07.180: SUC: 101ms 1
2024-12-17T14:22:07.181: checkJavaStack starting: 2007.
2024-12-17T14:22:37.702: ------ test: 2007
2024-12-17T14:22:37.702: EXE: 5/"jstack 90461 | awk '/nid=/ {if (in_block && has_h1_h2) {for (i = start_line; i <= last_h1_h2; i++) print lines[i]} start_line = NR; last_h1_h2 = 0; has_h1_h2 = 0; in_block = 1} /h1|h2/ {has_h1_h2 = 1; last_h1_h2 = NR} {lines[NR] = $0} END {if (in_block && has_h1_h2) {for (i = start_line; i <= last_h1_h2; i++) print lines[i]}}' || true".
2024-12-17T14:23:08.067: Row 1: "https-openssl-nio-443-exec-77" #261394 daemon prio=5 os_prio=0 tid=0x00007f00a8053800 nid=0x1814c runnable [0x00007f0181bf2000].
2024-12-17T14:23:08.079: Row 2: java.lang.Thread.State: RUNNABLE.
2024-12-17T14:23:08.079: Row 3: at h1.body.wordToBody(body.java:302).
2024-12-17T14:23:08.079: Row 4: at h2.core2021.semanticSimilarity_v3(core2021.java:1393).
2024-12-17T14:23:08.079: Row 5: at h2.core2021.text2keywords_v3(core2021.java:727).
2024-12-17T14:23:08.079: Row 6: at h2.BuildKnowledgeGraph_v5.build_a_map_on_fly_v4_1(BuildKnowledgeGraph_v5.java:1727).
2024-12-17T14:23:08.079: Row 7: at h2.SkillsForecast_methods.build_a_map_on_fly_v4(SkillsForecast_methods.java:5221).
2024-12-17T14:23:08.079: Row 8: at h2.helpers.check_pending_works(helpers.java:5270).
2024-12-17T14:23:08.080: Row 9: at h1.mc_api.doPost(mc_api.java:4576).
2024-12-17T14:23:08.080: Row 10: at h1.mc_api.doGet(mc_api.java:38).
2024-12-17T14:23:08.080: SUC: 2007, 30378ms, lines: 10.
2024-12-17T14:23:08.080: checkJavaStack end: 2007, pid/ret=10.
2024-12-17T14:23:18.081: checkJavaStack starting: 2007.
2024-12-17T14:23:48.279: ------ test: 2007
2024-12-17T14:23:48.279: EXE: 5/"jstack 90461 | awk '/nid=/ {if (in_block && has_h1_h2) {for (i = start_line; i <= last_h1_h2; i++) print lines[i]} start_line = NR; last_h1_h2 = 0; has_h1_h2 = 0; in_block = 1} /h1|h2/ {has_h1_h2 = 1; last_h1_h2 = NR} {lines[NR] = $0} END {if (in_block && has_h1_h2) {for (i = start_line; i <= last_h1_h2; i++) print lines[i]}}' || true".
2024-12-17T14:24:18.588: Row 1: "https-openssl-nio-443-exec-77" #261394 daemon prio=5 os_prio=0 tid=0x00007f00a8053800 nid=0x1814c runnable [0x00007f0181bf2000].
2024-12-17T14:24:18.588: Row 2: java.lang.Thread.State: RUNNABLE.
2024-12-17T14:24:18.588: Row 3: at h1.body.wordToBody(body.java:286).
2024-12-17T14:24:18.588: Row 4: at h2.core2021.semanticSimilarity_v3(core2021.java:1393).
2024-12-17T14:24:18.588: Row 5: at h2.core2021.text2keywords_v3(core2021.java:727).
2024-12-17T14:24:18.589: Row 6: at h2.BuildKnowledgeGraph_v5.build_a_map_on_fly_v4_1(BuildKnowledgeGraph_v5.java:1727).
2024-12-17T14:24:18.589: Row 7: at h2.SkillsForecast_methods.build_a_map_on_fly_v4(SkillsForecast_methods.java:5221).
2024-12-17T14:24:18.589: Row 8: at h2.helpers.check_pending_works(helpers.java:5270).
2024-12-17T14:24:18.589: Row 9: at h1.mc_api.doPost(mc_api.java:4576).
2024-12-17T14:24:18.589: Row 10: at h1.mc_api.doGet(mc_api.java:38).
2024-12-17T14:24:18.594: SUC: 2007, 30315ms, lines: 10.
2024-12-17T14:24:18.595: checkJavaStack end: 2007, pid/ret=10.
2024-12-17T14:24:28.596: checkJavaStack starting: 2007.
2024-12-17T14:24:58.822: ------ test: 2007
2024-12-17T14:24:58.822: EXE: 5/"jstack 90461 | awk '/nid=/ {if (in_block && has_h1_h2) {for (i = start_line; i <= last_h1_h2; i++) print lines[i]} start_line = NR; last_h1_h2 = 0; has_h1_h2 = 0; in_block = 1} /h1|h2/ {has_h1_h2 = 1; last_h1_h2 = NR} {lines[NR] = $0} END {if (in_block && has_h1_h2) {for (i = start_line; i <= last_h1_h2; i++) print lines[i]}}' || true".
2024-12-17T14:25:29.143: SUC: 2007, 30321ms, lines: 4096.
2024-12-17T14:25:29.144: checkJavaStack end: 2007, pid/ret=4096.
2024-12-17T14:25:39.144: checkJavaStack starting: 2007.
2024-12-17T14:26:09.343: ------ test: 2007
2024-12-17T14:26:09.344: EXE: 5/"jstack 90461 | awk '/nid=/ {if (in_block && has_h1_h2) {for (i = start_line; i <= last_h1_h2; i++) print lines[i]} start_line = NR; last_h1_h2 = 0; has_h1_h2 = 0; in_block = 1} /h1|h2/ {has_h1_h2 = 1; last_h1_h2 = NR} {lines[NR] = $0} END {if (in_block && has_h1_h2) {for (i = start_line; i <= last_h1_h2; i++) print lines[i]}}' || true".
2024-12-17T14:26:39.662: SUC: 2007, 30318ms, lines: 4096.
2024-12-17T14:26:39.663: checkJavaStack end: 2007, pid/ret=4096.
2024-12-17T14:26:49.663: checkJavaStack starting: 2007.
2024-12-17T14:27:19.866: ------ test: 2007
2024-12-17T14:27:19.866: EXE: 5/"jstack 90461 | awk '/nid=/ {if (in_block && has_h1_h2) {for (i = start_line; i <= last_h1_h2; i++) print lines[i]} start_line = NR; last_h1_h2 = 0; has_h1_h2 = 0; in_block = 1} /h1|h2/ {has_h1_h2 = 1; last_h1_h2 = NR} {lines[NR] = $0} END {if (in_block && has_h1_h2) {for (i = start_line; i <= last_h1_h2; i++) print lines[i]}}' || true".
2024-12-17T14:27:50.153: SUC: 2007, 30287ms, lines: 4096.
2024-12-17T14:27:50.153: checkJavaStack end: 2007, pid/ret=4096.
2024-12-17T14:28:00.154: checkJavaStack starting: 2007.
2024-12-17T14:28:30.341: ------ test: 2007
2024-12-17T14:28:30.341: EXE: 5/"jstack 90461 | awk '/nid=/ {if (in_block && has_h1_h2) {for (i = start_line; i <= last_h1_h2; i++) print lines[i]} start_line = NR; last_h1_h2 = 0; has_h1_h2 = 0; in_block = 1} /h1|h2/ {has_h1_h2 = 1; last_h1_h2 = NR} {lines[NR] = $0} END {if (in_block && has_h1_h2) {for (i = start_line; i <= last_h1_h2; i++) print lines[i]}}' || true".
2024-12-17T14:29:00.634: SUC: 2007, 30293ms, lines: 4096.
2024-12-17T14:29:00.635: checkJavaStack end: 2007, pid/ret=4096.
2024-12-17T14:29:10.635: checkJavaStack starting: 2007.
2024-12-17T14:29:40.821: ------ test: 2007
2024-12-17T14:29:40.821: EXE: 5/"jstack 90461 | awk '/nid=/ {if (in_block && has_h1_h2) {for (i = start_line; i <= last_h1_h2; i++) print lines[i]} start_line = NR; last_h1_h2 = 0; has_h1_h2 = 0; in_block = 1} /h1|h2/ {has_h1_h2 = 1; last_h1_h2 = NR} {lines[NR] = $0} END {if (in_block && has_h1_h2) {for (i = start_line; i <= last_h1_h2; i++) print lines[i]}}' || true".
2024-12-17T14:30:11.134: SUC: 2007, 30313ms, lines: 4096.
2024-12-17T14:30:11.135: checkJavaStack end: 2007, pid/ret=4096.
2024-12-17T14:30:21.135: checkJavaStack starting: 2007.
2024-12-17T14:30:51.330: ------ test: 2007
2024-12-17T14:30:51.330: EXE: 5/"jstack 90461 | awk '/nid=/ {if (in_block && has_h1_h2) {for (i = start_line; i <= last_h1_h2; i++) print lines[i]} start_line = NR; last_h1_h2 = 0; has_h1_h2 = 0; in_block = 1} /h1|h2/ {has_h1_h2 = 1; last_h1_h2 = NR} {lines[NR] = $0} END {if (in_block && has_h1_h2) {for (i = start_line; i <= last_h1_h2; i++) print lines[i]}}' || true".
2024-12-17T14:31:21.729: SUC: 2007, 30399ms, lines: 4096.
2024-12-17T14:31:21.731: checkJavaStack end: 2007, pid/ret=4096.
2024-12-17T14:31:31.731: checkJavaStack starting: 2007.
2024-12-17T14:32:01.945: ------ test: 2007
2024-12-17T14:32:01.945: EXE: 5/"jstack 90461 | awk '/nid=/ {if (in_block && has_h1_h2) {for (i = start_line; i <= last_h1_h2; i++) print lines[i]} start_line = NR; last_h1_h2 = 0; has_h1_h2 = 0; in_block = 1} /h1|h2/ {has_h1_h2 = 1; last_h1_h2 = NR} {lines[NR] = $0} END {if (in_block && has_h1_h2) {for (i = start_line; i <= last_h1_h2; i++) print lines[i]}}' || true".
2024-12-17T14:32:32.280: Row 1: "https-openssl-nio-443-exec-85" #261403 daemon prio=5 os_prio=0 tid=0x00007f00900ca800 nid=0x18161 runnable [0x00007f0180ee8000].
2024-12-17T14:32:32.280: Row 2: java.lang.Thread.State: RUNNABLE.
2024-12-17T14:32:32.280: Row 3: at java.net.SocketInputStream.socketRead0(Native Method).
2024-12-17T14:32:32.280: Row 4: at java.net.SocketInputStream.socketRead(SocketInputStream.java:116).
2024-12-17T14:32:32.281: Row 5: at java.net.SocketInputStream.read(SocketInputStream.java:171).
2024-12-17T14:32:32.281: Row 6: at java.net.SocketInputStream.read(SocketInputStream.java:141).
2024-12-17T14:32:32.281: Row 7: at com.mysql.jdbc.util.ReadAheadInputStream.fill(ReadAheadInputStream.java:100).
2024-12-17T14:32:32.281: Row 8: at com.mysql.jdbc.util.ReadAheadInputStream.readFromUnderlyingStreamIfNecessary(ReadAheadInputStream.java:143).
2024-12-17T14:32:32.281: Row 9: at com.mysql.jdbc.util.ReadAheadInputStream.read(ReadAheadInputStream.java:173).
2024-12-17T14:32:32.283: Row 10: - locked <0x00000007548f0df0> (a com.mysql.jdbc.util.ReadAheadInputStream).
2024-12-17T14:32:32.283: Row 11: at com.mysql.jdbc.MysqlIO.readFully(MysqlIO.java:2911).
2024-12-17T14:32:32.283: Row 12: at com.mysql.jdbc.MysqlIO.reuseAndReadPacket(MysqlIO.java:3337).
2024-12-17T14:32:32.283: Row 13: at com.mysql.jdbc.MysqlIO.reuseAndReadPacket(MysqlIO.java:3327).
2024-12-17T14:32:32.283: Row 14: at com.mysql.jdbc.MysqlIO.checkErrorPacket(MysqlIO.java:3814).
2024-12-17T14:32:32.285: Row 15: at com.mysql.jdbc.MysqlIO.sendCommand(MysqlIO.java:2435).
2024-12-17T14:32:32.285: Row 16: at com.mysql.jdbc.MysqlIO.sqlQueryDirect(MysqlIO.java:2582).
2024-12-17T14:32:32.285: Row 17: at com.mysql.jdbc.ConnectionImpl.execSQL(ConnectionImpl.java:2526).
2024-12-17T14:32:32.285: Row 18: - locked <0x00000007548e7408> (a com.mysql.jdbc.JDBC4Connection).
2024-12-17T14:32:32.285: Row 19: at com.mysql.jdbc.ConnectionImpl.execSQL(ConnectionImpl.java:2484).
2024-12-17T14:32:32.286: Row 20: at com.mysql.jdbc.StatementImpl.executeQuery(StatementImpl.java:1446).
2024-12-17T14:32:32.286: Row 21: - locked <0x00000007548e7408> (a com.mysql.jdbc.JDBC4Connection).
2024-12-17T14:32:32.286: Row 22: at com.mysql.jdbc.ConnectionImpl.isReadOnly(ConnectionImpl.java:3601).
2024-12-17T14:32:32.286: Row 23: at com.mysql.jdbc.ConnectionImpl.isReadOnly(ConnectionImpl.java:3576).
2024-12-17T14:32:32.286: Row 24: at com.mysql.jdbc.PreparedStatement.executeUpdate(PreparedStatement.java:2107).
2024-12-17T14:32:32.292: SUC: 2007, 30347ms, lines: 30.
2024-12-17T14:32:32.292: checkJavaStack end: 2007, pid/ret=30.
2024-12-17T14:32:42.292: checkJavaStack starting: 2007.
2024-12-17T14:33:12.494: ------ test: 2007
2024-12-17T14:33:12.495: EXE: 5/"jstack 90461 | awk '/nid=/ {if (in_block && has_h1_h2) {for (i = start_line; i <= last_h1_h2; i++) print lines[i]} start_line = NR; last_h1_h2 = 0; has_h1_h2 = 0; in_block = 1} /h1|h2/ {has_h1_h2 = 1; last_h1_h2 = NR} {lines[NR] = $0} END {if (in_block && has_h1_h2) {for (i = start_line; i <= last_h1_h2; i++) print lines[i]}}' || true".
2024-12-17T14:33:42.799: SUC: 2007, 30304ms, lines: 4096.
2024-12-17T14:33:42.799: checkJavaStack end: 2007, pid/ret=4096.
2024-12-17T14:33:52.800: checkJavaStack starting: 2007.
2024-12-17T14:34:230000: ------ test: 2007
2024-12-17T14:34:23.001: EXE: 5/"jstack 90461 | awk '/nid=/ {if (in_block && has_h1_h2) {for (i = start_line; i <= last_h1_h2; i++) print lines[i]} start_line = NR; last_h1_h2 = 0; has_h1_h2 = 0; in_block = 1} /h1|h2/ {has_h1_h2 = 1; last_h1_h2 = NR} {lines[NR] = $0} END {if (in_block && has_h1_h2) {for (i = start_line; i <= last_h1_h2; i++) print lines[i]}}' || true".
2024-12-17T14:34:53.377: SUC: 2007, 30376ms, lines: 4096.
2024-12-17T14:34:53.378: checkJavaStack end: 2007, pid/ret=4096.
2024-12-17T14:35:03.378: checkJavaStack starting: 2007.
2024-12-17T14:35:33.584: ------ test: 2007
2024-12-17T14:35:33.584: EXE: 5/"jstack 90461 | awk '/nid=/ {if (in_block && has_h1_h2) {for (i = start_line; i <= last_h1_h2; i++) print lines[i]} start_line = NR; last_h1_h2 = 0; has_h1_h2 = 0; in_block = 1} /h1|h2/ {has_h1_h2 = 1; last_h1_h2 = NR} {lines[NR] = $0} END {if (in_block && has_h1_h2) {for (i = start_line; i <= last_h1_h2; i++) print lines[i]}}' || true".
2024-12-17T14:36:03.886: SUC: 2007, 30302ms, lines: 4096.
2024-12-17T14:36:03.930: checkJavaStack end: 2007, pid/ret=4096.
2024-12-17T14:36:13.930: checkJavaStack starting: 2007.
2024-12-17T14:36:44.127: ------ test: 2007
2024-12-17T14:36:44.127: EXE: 5/"jstack 90461 | awk '/nid=/ {if (in_block && has_h1_h2) {for (i = start_line; i <= last_h1_h2; i++) print lines[i]} start_line = NR; last_h1_h2 = 0; has_h1_h2 = 0; in_block = 1} /h1|h2/ {has_h1_h2 = 1; last_h1_h2 = NR} {lines[NR] = $0} END {if (in_block && has_h1_h2) {for (i = start_line; i <= last_h1_h2; i++) print lines[i]}}' || true".
2024-12-17T14:37:14.429: SUC: 2007, 30302ms, lines: 4096.
2024-12-17T14:37:14.430: checkJavaStack end: 2007, pid/ret=4096.
2024-12-17T14:37:24.430: checkJavaStack starting: 2007.
2024-12-17T14:37:54.624: ------ test: 2007
2024-12-17T14:37:54.625: EXE: 5/"jstack 90461 | awk '/nid=/ {if (in_block && has_h1_h2) {for (i = start_line; i <= last_h1_h2; i++) print lines[i]} start_line = NR; last_h1_h2 = 0; has_h1_h2 = 0; in_block = 1} /h1|h2/ {has_h1_h2 = 1; last_h1_h2 = NR} {lines[NR] = $0} END {if (in_block && has_h1_h2) {for (i = start_line; i <= last_h1_h2; i++) print lines[i]}}' || true".
2024-12-17T14:38:24.943: SUC: 2007, 30318ms, lines: 4096.
2024-12-17T14:38:24.943: checkJavaStack end: 2007, pid/ret=4096.
2024-12-17T14:38:34.943: checkJavaStack starting: 2007.
2024-12-17T14:39:05.138: ------ test: 2007
2024-12-17T14:39:05.139: EXE: 5/"jstack 90461 | awk '/nid=/ {if (in_block && has_h1_h2) {for (i = start_line; i <= last_h1_h2; i++) print lines[i]} start_line = NR; last_h1_h2 = 0; has_h1_h2 = 0; in_block = 1} /h1|h2/ {has_h1_h2 = 1; last_h1_h2 = NR} {lines[NR] = $0} END {if (in_block && has_h1_h2) {for (i = start_line; i <= last_h1_h2; i++) print lines[i]}}' || true".
2024-12-17T14:39:35.492: SUC: 2007, 30353ms, lines: 4096.
2024-12-17T14:39:35.492: checkJavaStack end: 2007, pid/ret=4096.
2024-12-17T14:39:45.493: checkJavaStack starting: 2007.
2024-12-17T14:40:15.690: ------ test: 2007
2024-12-17T14:40:15.690: EXE: 5/"jstack 90461 | awk '/nid=/ {if (in_block && has_h1_h2) {for (i = start_line; i <= last_h1_h2; i++) print lines[i]} start_line = NR; last_h1_h2 = 0; has_h1_h2 = 0; in_block = 1} /h1|h2/ {has_h1_h2 = 1; last_h1_h2 = NR} {lines[NR] = $0} END {if (in_block && has_h1_h2) {for (i = start_line; i <= last_h1_h2; i++) print lines[i]}}' || true".
2024-12-17T14:40:46.011: SUC: 2007, 30321ms, lines: 4096.
2024-12-17T14:40:46.012: checkJavaStack end: 2007, pid/ret=4096.
2024-12-17T14:40:56.012: checkJavaStack starting: 2007.
2024-12-17T14:41:26.210: ------ test: 2007
2024-12-17T14:41:26.210: EXE: 5/"jstack 90461 | awk '/nid=/ {if (in_block && has_h1_h2) {for (i = start_line; i <= last_h1_h2; i++) print lines[i]} start_line = NR; last_h1_h2 = 0; has_h1_h2 = 0; in_block = 1} /h1|h2/ {has_h1_h2 = 1; last_h1_h2 = NR} {lines[NR] = $0} END {if (in_block && has_h1_h2) {for (i = start_line; i <= last_h1_h2; i++) print lines[i]}}' || true".
2024-12-17T14:41:56.540: SUC: 2007, 30330ms, lines: 4096.
2024-12-17T14:41:56.540: checkJavaStack end: 2007, pid/ret=4096.
2024-12-17T14:42:06.541: checkJavaStack starting: 2007.
2024-12-17T14:42:36.738: ------ test: 2007
2024-12-17T14:42:36.739: EXE: 5/"jstack 90461 | awk '/nid=/ {if (in_block && has_h1_h2) {for (i = start_line; i <= last_h1_h2; i++) print lines[i]} start_line = NR; last_h1_h2 = 0; has_h1_h2 = 0; in_block = 1} /h1|h2/ {has_h1_h2 = 1; last_h1_h2 = NR} {lines[NR] = $0} END {if (in_block && has_h1_h2) {for (i = start_line; i <= last_h1_h2; i++) print lines[i]}}' || true".
2024-12-17T14:43:07.070: Row 1: "https-openssl-nio-443-exec-82" #261399 daemon prio=5 os_prio=0 tid=0x00007f00942c9000 nid=0x18157 runnable [0x00007f0181ff7000].
2024-12-17T14:43:07.071: Row 2: java.lang.Thread.State: RUNNABLE.
2024-12-17T14:43:07.071: Row 3: at java.net.SocketInputStream.socketRead0(Native Method).
2024-12-17T14:43:07.071: Row 4: at java.net.SocketInputStream.socketRead(SocketInputStream.java:116).
2024-12-17T14:43:07.071: Row 5: at java.net.SocketInputStream.read(SocketInputStream.java:171).
2024-12-17T14:43:07.071: Row 6: at java.net.SocketInputStream.read(SocketInputStream.java:141).
2024-12-17T14:43:07.071: Row 7: at com.mysql.jdbc.util.ReadAheadInputStream.fill(ReadAheadInputStream.java:100).
2024-12-17T14:43:07.071: Row 8: at com.mysql.jdbc.util.ReadAheadInputStream.readFromUnderlyingStreamIfNecessary(ReadAheadInputStream.java:143).
2024-12-17T14:43:07.072: Row 9: at com.mysql.jdbc.util.ReadAheadInputStream.read(ReadAheadInputStream.java:173).
2024-12-17T14:43:07.072: Row 10: - locked <0x0000000765886fb0> (a com.mysql.jdbc.util.ReadAheadInputStream).
2024-12-17T14:43:07.072: Row 11: at com.mysql.jdbc.MysqlIO.readFully(MysqlIO.java:2911).
2024-12-17T14:43:07.072: Row 12: at com.mysql.jdbc.MysqlIO.reuseAndReadPacket(MysqlIO.java:3337).
2024-12-17T14:43:07.072: Row 13: at com.mysql.jdbc.MysqlIO.reuseAndReadPacket(MysqlIO.java:3327).
2024-12-17T14:43:07.072: Row 14: at com.mysql.jdbc.MysqlIO.checkErrorPacket(MysqlIO.java:3814).
2024-12-17T14:43:07.072: Row 15: at com.mysql.jdbc.MysqlIO.sendCommand(MysqlIO.java:2435).
2024-12-17T14:43:07.072: Row 16: at com.mysql.jdbc.MysqlIO.sqlQueryDirect(MysqlIO.java:2582).
2024-12-17T14:43:07.072: Row 17: at com.mysql.jdbc.ConnectionImpl.execSQL(ConnectionImpl.java:2530).
2024-12-17T14:43:07.073: Row 18: - locked <0x000000076587d5e8> (a com.mysql.jdbc.JDBC4Connection).
2024-12-17T14:43:07.073: Row 19: at com.mysql.jdbc.PreparedStatement.executeInternal(PreparedStatement.java:1907).
2024-12-17T14:43:07.073: Row 20: - locked <0x000000076587d5e8> (a com.mysql.jdbc.JDBC4Connection).
2024-12-17T14:43:07.073: Row 21: at com.mysql.jdbc.PreparedStatement.executeQuery(PreparedStatement.java:2030).
2024-12-17T14:43:07.073: Row 22: - locked <0x000000076587d5e8> (a com.mysql.jdbc.JDBC4Connection).
2024-12-17T14:43:07.073: Row 23: at h2.helpers.store_query(helpers.java:4193).
2024-12-17T14:43:07.077: Row 24: at h2.TextToGraph.doPost(TextToGraph.java:153).
2024-12-17T14:43:07.078: SUC: 2007, 30339ms, lines: 24.
2024-12-17T14:43:07.079: checkJavaStack end: 2007, pid/ret=24.
2024-12-17T14:43:17.079: checkJavaStack starting: 2007.
2024-12-17T14:43:47.278: ------ test: 2007
2024-12-17T14:43:47.279: EXE: 5/"jstack 90461 | awk '/nid=/ {if (in_block && has_h1_h2) {for (i = start_line; i <= last_h1_h2; i++) print lines[i]} start_line = NR; last_h1_h2 = 0; has_h1_h2 = 0; in_block = 1} /h1|h2/ {has_h1_h2 = 1; last_h1_h2 = NR} {lines[NR] = $0} END {if (in_block && has_h1_h2) {for (i = start_line; i <= last_h1_h2; i++) print lines[i]}}' || true".
2024-12-17T14:44:17.593: SUC: 2007, 30314ms, lines: 4096.
2024-12-17T14:44:17.594: checkJavaStack end: 2007, pid/ret=4096.
2024-12-17T14:44:27.594: checkJavaStack starting: 2007.
2024-12-17T14:44:57.789: ------ test: 2007
2024-12-17T14:44:57.789: EXE: 5/"jstack 90461 | awk '/nid=/ {if (in_block && has_h1_h2) {for (i = start_line; i <= last_h1_h2; i++) print lines[i]} start_line = NR; last_h1_h2 = 0; has_h1_h2 = 0; in_block = 1} /h1|h2/ {has_h1_h2 = 1; last_h1_h2 = NR} {lines[NR] = $0} END {if (in_block && has_h1_h2) {for (i = start_line; i <= last_h1_h2; i++) print lines[i]}}' || true".
2024-12-17T14:45:28.095: SUC: 2007, 30306ms, lines: 4096.
2024-12-17T14:45:28.095: checkJavaStack end: 2007, pid/ret=4096.
2024-12-17T14:45:38.096: checkJavaStack starting: 2007.
2024-12-17T14:46:08.291: ------ test: 2007
2024-12-17T14:46:08.292: EXE: 5/"jstack 90461 | awk '/nid=/ {if (in_block && has_h1_h2) {for (i = start_line; i <= last_h1_h2; i++) print lines[i]} start_line = NR; last_h1_h2 = 0; has_h1_h2 = 0; in_block = 1} /h1|h2/ {has_h1_h2 = 1; last_h1_h2 = NR} {lines[NR] = $0} END {if (in_block && has_h1_h2) {for (i = start_line; i <= last_h1_h2; i++) print lines[i]}}' || true".
2024-12-17T14:46:38.611: SUC: 2007, 30319ms, lines: 4096.
2024-12-17T14:46:38.612: checkJavaStack end: 2007, pid/ret=4096.
2024-12-17T14:46:48.612: checkJavaStack starting: 2007.
2024-12-17T14:47:18.808: ------ test: 2007
2024-12-17T14:47:18.808: EXE: 5/"jstack 90461 | awk '/nid=/ {if (in_block && has_h1_h2) {for (i = start_line; i <= last_h1_h2; i++) print lines[i]} start_line = NR; last_h1_h2 = 0; has_h1_h2 = 0; in_block = 1} /h1|h2/ {has_h1_h2 = 1; last_h1_h2 = NR} {lines[NR] = $0} END {if (in_block && has_h1_h2) {for (i = start_line; i <= last_h1_h2; i++) print lines[i]}}' || true".
2024-12-17T14:47:49.177: SUC: 2007, 30369ms, lines: 4096.
2024-12-17T14:47:49.177: checkJavaStack end: 2007, pid/ret=4096.
2024-12-17T14:47:59.177: checkJavaStack starting: 2007.
2024-12-17T14:48:29.372: ------ test: 2007
2024-12-17T14:48:29.373: EXE: 5/"jstack 90461 | awk '/nid=/ {if (in_block && has_h1_h2) {for (i = start_line; i <= last_h1_h2; i++) print lines[i]} start_line = NR; last_h1_h2 = 0; has_h1_h2 = 0; in_block = 1} /h1|h2/ {has_h1_h2 = 1; last_h1_h2 = NR} {lines[NR] = $0} END {if (in_block && has_h1_h2) {for (i = start_line; i <= last_h1_h2; i++) print lines[i]}}' || true".
2024-12-17T14:48:59.730: SUC: 2007, 30357ms, lines: 4096.
2024-12-17T14:48:59.730: checkJavaStack end: 2007, pid/ret=4096.
2024-12-17T14:49:09.730: checkJavaStack starting: 2007.
2024-12-17T14:49:39.923: ------ test: 2007
2024-12-17T14:49:39.923: EXE: 5/"jstack 90461 | awk '/nid=/ {if (in_block && has_h1_h2) {for (i = start_line; i <= last_h1_h2; i++) print lines[i]} start_line = NR; last_h1_h2 = 0; has_h1_h2 = 0; in_block = 1} /h1|h2/ {has_h1_h2 = 1; last_h1_h2 = NR} {lines[NR] = $0} END {if (in_block && has_h1_h2) {for (i = start_line; i <= last_h1_h2; i++) print lines[i]}}' || true".
2024-12-17T14:50:10.289: SUC: 2007, 30366ms, lines: 4096.
2024-12-17T14:50:10.290: checkJavaStack end: 2007, pid/ret=4096.
2024-12-17T14:50:20.290: checkJavaStack starting: 2007.
2024-12-17T14:50:50.484: ------ test: 2007
2024-12-17T14:50:50.485: EXE: 5/"jstack 90461 | awk '/nid=/ {if (in_block && has_h1_h2) {for (i = start_line; i <= last_h1_h2; i++) print lines[i]} start_line = NR; last_h1_h2 = 0; has_h1_h2 = 0; in_block = 1} /h1|h2/ {has_h1_h2 = 1; last_h1_h2 = NR} {lines[NR] = $0} END {if (in_block && has_h1_h2) {for (i = start_line; i <= last_h1_h2; i++) print lines[i]}}' || true".
2024-12-17T14:51:20.810: SUC: 2007, 30325ms, lines: 4096.
2024-12-17T14:51:20.810: checkJavaStack end: 2007, pid/ret=4096.
2024-12-17T14:51:30.813: checkJavaStack starting: 2007.
2024-12-17T14:52:01.007: ------ test: 2007
2024-12-17T14:52:01.008: EXE: 5/"jstack 90461 | awk '/nid=/ {if (in_block && has_h1_h2) {for (i = start_line; i <= last_h1_h2; i++) print lines[i]} start_line = NR; last_h1_h2 = 0; has_h1_h2 = 0; in_block = 1} /h1|h2/ {has_h1_h2 = 1; last_h1_h2 = NR} {lines[NR] = $0} END {if (in_block && has_h1_h2) {for (i = start_line; i <= last_h1_h2; i++) print lines[i]}}' || true".
2024-12-17T14:52:31.320: SUC: 2007, 30312ms, lines: 4096.
2024-12-17T14:52:31.320: checkJavaStack end: 2007, pid/ret=4096.
2024-12-17T14:52:41.321: checkJavaStack starting: 2007.
2024-12-17T14:53:11.516: ------ test: 2007
2024-12-17T14:53:11.516: EXE: 5/"jstack 90461 | awk '/nid=/ {if (in_block && has_h1_h2) {for (i = start_line; i <= last_h1_h2; i++) print lines[i]} start_line = NR; last_h1_h2 = 0; has_h1_h2 = 0; in_block = 1} /h1|h2/ {has_h1_h2 = 1; last_h1_h2 = NR} {lines[NR] = $0} END {if (in_block && has_h1_h2) {for (i = start_line; i <= last_h1_h2; i++) print lines[i]}}' || true".
2024-12-17T14:53:41.874: SUC: 2007, 30357ms, lines: 4096.
2024-12-17T14:53:41.874: checkJavaStack end: 2007, pid/ret=4096.
2024-12-17T14:53:51.874: checkJavaStack starting: 2007.
2024-12-17T14:54:22.069: ------ test: 2007
2024-12-17T14:54:22.069: EXE: 5/"jstack 90461 | awk '/nid=/ {if (in_block && has_h1_h2) {for (i = start_line; i <= last_h1_h2; i++) print lines[i]} start_line = NR; last_h1_h2 = 0; has_h1_h2 = 0; in_block = 1} /h1|h2/ {has_h1_h2 = 1; last_h1_h2 = NR} {lines[NR] = $0} END {if (in_block && has_h1_h2) {for (i = start_line; i <= last_h1_h2; i++) print lines[i]}}' || true".
2024-12-17T14:54:52.385: SUC: 2007, 30316ms, lines: 4096.
2024-12-17T14:54:52.386: checkJavaStack end: 2007, pid/ret=4096.
2024-12-17T14:55:02.386: checkJavaStack starting: 2007.
2024-12-17T14:55:32.595: ------ test: 2007
2024-12-17T14:55:32.595: EXE: 5/"jstack 90461 | awk '/nid=/ {if (in_block && has_h1_h2) {for (i = start_line; i <= last_h1_h2; i++) print lines[i]} start_line = NR; last_h1_h2 = 0; has_h1_h2 = 0; in_block = 1} /h1|h2/ {has_h1_h2 = 1; last_h1_h2 = NR} {lines[NR] = $0} END {if (in_block && has_h1_h2) {for (i = start_line; i <= last_h1_h2; i++) print lines[i]}}' || true".
2024-12-17T14:56:02.924: SUC: 2007, 30329ms, lines: 4096.
2024-12-17T14:56:02.924: checkJavaStack end: 2007, pid/ret=4096.
2024-12-17T14:56:12.925: checkJavaStack starting: 2007.
2024-12-17T14:56:43.125: ------ test: 2007
2024-12-17T14:56:43.125: EXE: 5/"jstack 90461 | awk '/nid=/ {if (in_block && has_h1_h2) {for (i = start_line; i <= last_h1_h2; i++) print lines[i]} start_line = NR; last_h1_h2 = 0; has_h1_h2 = 0; in_block = 1} /h1|h2/ {has_h1_h2 = 1; last_h1_h2 = NR} {lines[NR] = $0} END {if (in_block && has_h1_h2) {for (i = start_line; i <= last_h1_h2; i++) print lines[i]}}' || true".
2024-12-17T14:57:13.445: SUC: 2007, 30320ms, lines: 4096.
2024-12-17T14:57:13.447: checkJavaStack end: 2007, pid/ret=4096.
2024-12-17T14:57:23.447: checkJavaStack starting: 2007.
2024-12-17T14:57:53.640: ------ test: 2007
2024-12-17T14:57:53.641: EXE: 5/"jstack 90461 | awk '/nid=/ {if (in_block && has_h1_h2) {for (i = start_line; i <= last_h1_h2; i++) print lines[i]} start_line = NR; last_h1_h2 = 0; has_h1_h2 = 0; in_block = 1} /h1|h2/ {has_h1_h2 = 1; last_h1_h2 = NR} {lines[NR] = $0} END {if (in_block && has_h1_h2) {for (i = start_line; i <= last_h1_h2; i++) print lines[i]}}' || true".
2024-12-17T14:58:23.944: SUC: 2007, 30303ms, lines: 4096.
2024-12-17T14:58:23.944: checkJavaStack end: 2007, pid/ret=4096.
2024-12-17T14:58:33.945: checkJavaStack starting: 2007.
2024-12-17T14:59:04.142: ------ test: 2007
2024-12-17T14:59:04.142: EXE: 5/"jstack 90461 | awk '/nid=/ {if (in_block && has_h1_h2) {for (i = start_line; i <= last_h1_h2; i++) print lines[i]} start_line = NR; last_h1_h2 = 0; has_h1_h2 = 0; in_block = 1} /h1|h2/ {has_h1_h2 = 1; last_h1_h2 = NR} {lines[NR] = $0} END {if (in_block && has_h1_h2) {for (i = start_line; i <= last_h1_h2; i++) print lines[i]}}' || true".
2024-12-17T14:59:34.462: SUC: 2007, 30320ms, lines: 4096.
2024-12-17T14:59:34.463: checkJavaStack end: 2007, pid/ret=4096.
2024-12-17T14:59:34.463: ------ test: end, 2309365ms. Score 39/39.
2024-12-17T14:59:34.463: Test duration: 38m29s.