2024-12-17T15:21:04.685: current working dir: /root/qa/mctester.
2024-12-17T15:21:04.782: current logging dir: ../mctester/www.
2024-12-17T15:21:04.782: ---------------------------------------------------------------
2024-12-17T15:21:04.783: mctester entry: 2024-12-17T15:21:04.783 mode=50
2024-12-17T15:21:04.783: ---------------------------------------------------------------
2024-12-17T15:21:04.896: EXE: 1/git describe --tags.
2024-12-17T15:21:04.913: Row 1: v1.1-201-g90c2042.
2024-12-17T15:21:04.914: EXE: 1/git rev-list --count HEAD.
2024-12-17T15:21:04.919: Row 1: 258.
2024-12-17T15:21:04.920: EXE: 1/git rev-parse HEAD.
2024-12-17T15:21:04.921: Row 1: 90c2042af9ce0b055e0a62dafd703b27e21c4128.
2024-12-17T15:21:04.922: mctester: v1.1.258 (90c2042af9ce0b055e0a62dafd703b27e21c4128).
2024-12-17T15:21:04.922: Entering into a performance tests mode.
2024-12-17T15:21:04.923: ------ test: start, 2024-12-17T15:21:04.923
2024-12-17T15:21:04.923: ------ test: 2000
2024-12-17T15:21:04.924: CMD: ssh -o ServerAliveInterval=60 -o ServerAliveCountMax=3 root@megatron.headai.com "top -b -n 1"
2024-12-17T15:21:04.924: EXE: 1/ssh -o ServerAliveInterval=60 -o ServerAliveCountMax=3 root@megatron.headai.com "top -b -n 1".
2024-12-17T15:21:35.251: Row 1: top - 15:21:35 up 91 days, 7:48, 0 users, load average: 0.40, 0.14, 0.05.
2024-12-17T15:21:35.252: Row 2: Tasks: 359 total, 1 running, 358 sleeping, 0 stopped, 0 zombie.
2024-12-17T15:21:35.252: 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-17T15:21:35.252: Row 4: KiB Mem : 13202036+total, 680348 free, 60822372 used, 70517648 buff/cache.
2024-12-17T15:21:35.253: Row 5: KiB Swap: 0 total, 0 free, 0 used. 69608560 avail Mem .
2024-12-17T15:21:35.253: Row 6: .
2024-12-17T15:21:35.253: Row 7: PID USER PR NI VIRT RES SHR S %CPU %MEM TIME+ COMMAND.
2024-12-17T15:21:35.256: Row 8: 1978 mysql 20 0 99.645g 0.048t 1.145g S 100.0 38.7 13642:53 mysqld.
2024-12-17T15:21:35.257: Row 9: 1 root 20 0 43660 2552 1268 S 0.0 0.0 3:23.76 init.
2024-12-17T15:21:35.257: Row 10: 2 root 20 0 0 0 0 S 0.0 0.0 0:01.23 kthreadd.
2024-12-17T15:21:35.257: Row 11: 3 root 20 0 0 0 0 S 0.0 0.0 4:28.70 ksoftirqd+.
2024-12-17T15:21:35.257: Row 12: 5 root 0 -20 0 0 0 S 0.0 0.0 0:00.00 kworker/0+.
2024-12-17T15:21:35.257: Row 13: 7 root 20 0 0 0 0 S 0.0 0.0 29:52.84 rcu_sched.
2024-12-17T15:21:35.259: Row 14: 8 root 20 0 0 0 0 S 0.0 0.0 0:00.00 rcu_bh.
2024-12-17T15:21:35.261: Row 15: 9 root rt 0 0 0 0 S 0.0 0.0 0:05.47 migration+.
2024-12-17T15:21:35.262: Row 16: 10 root rt 0 0 0 0 S 0.0 0.0 0:22.02 watchdog/0.
2024-12-17T15:21:35.263: Row 17: 11 root rt 0 0 0 0 S 0.0 0.0 0:20.10 watchdog/1.
2024-12-17T15:21:35.263: Row 18: 12 root rt 0 0 0 0 S 0.0 0.0 0:10.69 migration+.
2024-12-17T15:21:35.263: Row 19: 13 root 20 0 0 0 0 S 0.0 0.0 0:01.78 ksoftirqd+.
2024-12-17T15:21:35.263: Row 20: 15 root 0 -20 0 0 0 S 0.0 0.0 0:00.00 kworker/1+.
2024-12-17T15:21:35.264: Row 21: 16 root rt 0 0 0 0 S 0.0 0.0 0:20.54 watchdog/2.
2024-12-17T15:21:35.264: Row 22: 17 root rt 0 0 0 0 S 0.0 0.0 0:04.55 migration+.
2024-12-17T15:21:35.264: Row 23: 18 root 20 0 0 0 0 S 0.0 0.0 0:01.43 ksoftirqd+.
2024-12-17T15:21:35.264: Row 24: 20 root 0 -20 0 0 0 S 0.0 0.0 0:00.00 kworker/2+.
2024-12-17T15:21:35.331: SUC: 30407ms Uptime 91 days, 1min 0.40, 5min 0.14, 15min 0.05, mem:132020360 total / 680348 free / 60822372 used / 70517648 cache / 69608560 avail
2024-12-17T15:21:35.345: ------ test: 2001
2024-12-17T15:21:35.345: CMD: top -b -E k -n 1
2024-12-17T15:21:35.345: EXE: 1/top -b -E k -n 1.
2024-12-17T15:21:35.501: Row 1: top - 15:21:35 up 35 days, 22:03, 0 users, load average: 0.15, 0.07, 0.02.
2024-12-17T15:21:35.501: Row 2: Tasks: 100 total, 1 running, 98 sleeping, 0 stopped, 1 zombie.
2024-12-17T15:21:35.501: Row 3: %Cpu(s): 6.2 us, 0.0 sy, 0.0 ni, 93.8 id, 0.0 wa, 0.0 hi, 0.0 si, 0.0 st.
2024-12-17T15:21:35.502: Row 4: KiB Mem : 1907632 total, 504240 free, 395936 used, 1007456 buff/cache.
2024-12-17T15:21:35.502: Row 5: KiB Swap: 0 total, 0 free, 0 used. 1294904 avail Mem .
2024-12-17T15:21:35.502: Row 6: .
2024-12-17T15:21:35.502: Row 7: PID USER PR NI VIRT RES SHR S %CPU %MEM TIME+ COMMAND.
2024-12-17T15:21:35.502: Row 8: 1 root 20 0 167784 11964 7080 S 0.0 0.6 0:56.83 systemd.
2024-12-17T15:21:35.502: Row 9: 2 root 20 0 0 0 0 S 0.0 0.0 0:00.09 kthreadd.
2024-12-17T15:21:35.502: Row 10: 3 root 0 -20 0 0 0 I 0.0 0.0 0:00.00 rcu_gp.
2024-12-17T15:21:35.503: Row 11: 4 root 0 -20 0 0 0 I 0.0 0.0 0:00.00 rcu_par+.
2024-12-17T15:21:35.503: Row 12: 5 root 0 -20 0 0 0 I 0.0 0.0 0:00.00 slub_fl+.
2024-12-17T15:21:35.503: Row 13: 6 root 0 -20 0 0 0 I 0.0 0.0 0:00.00 netns.
2024-12-17T15:21:35.503: Row 14: 8 root 0 -20 0 0 0 I 0.0 0.0 0:00.00 kworker+.
2024-12-17T15:21:35.503: Row 15: 10 root 0 -20 0 0 0 I 0.0 0.0 0:00.00 mm_perc+.
2024-12-17T15:21:35.503: Row 16: 11 root 20 0 0 0 0 S 0.0 0.0 0:00.00 rcu_tas+.
2024-12-17T15:21:35.504: Row 17: 12 root 20 0 0 0 0 S 0.0 0.0 0:00.00 rcu_tas+.
2024-12-17T15:21:35.504: Row 18: 13 root 20 0 0 0 0 S 0.0 0.0 0:30.83 ksoftir+.
2024-12-17T15:21:35.504: Row 19: 14 root 20 0 0 0 0 I 0.0 0.0 0:53.08 rcu_sch+.
2024-12-17T15:21:35.504: Row 20: 15 root rt 0 0 0 0 S 0.0 0.0 0:07.82 migrati+.
2024-12-17T15:21:35.504: Row 21: 16 root -51 0 0 0 0 S 0.0 0.0 0:00.00 idle_in+.
2024-12-17T15:21:35.504: Row 22: 18 root 20 0 0 0 0 S 0.0 0.0 0:00.00 cpuhp/0.
2024-12-17T15:21:35.505: Row 23: 19 root 20 0 0 0 0 S 0.0 0.0 0:00.00 kdevtmp+.
2024-12-17T15:21:35.505: Row 24: 20 root 0 -20 0 0 0 I 0.0 0.0 0:00.00 inet_fr+.
2024-12-17T15:21:35.517: SUC: 172ms Uptime 35 days, 1min 0.15, 5min 0.07, 15min 0.02, mem:1907632 total / 504240 free / 395936 used / 1007456 cache / 1294904 avail
2024-12-17T15:21:35.520: ------ test: 2002
2024-12-17T15:21:35.520: CMD: df -h
2024-12-17T15:21:35.521: EXE: 1/df -h.
2024-12-17T15:21:35.523: Row 1: Filesystem Size Used Avail Use% Mounted on.
2024-12-17T15:21:35.523: Row 2: tmpfs 187M 20M 167M 11% /run.
2024-12-17T15:21:35.523: Row 3: /dev/vda2 20G 14G 5.2G 73% /.
2024-12-17T15:21:35.523: Row 4: tmpfs 932M 0 932M 0% /dev/shm.
2024-12-17T15:21:35.523: Row 5: tmpfs 5.0M 0 5.0M 0% /run/lock.
2024-12-17T15:21:35.526: SUC: 6ms Diskfree qa.headai.com: tmpfs:11%, /dev/vda2:73%, tmpfs:0%, tmpfs:0%.
2024-12-17T15:21:35.526: ------ test: 2003
2024-12-17T15:21:35.526: CMD: ssh -o ServerAliveInterval=60 -o ServerAliveCountMax=3 root@megatron.headai.com "df -h"
2024-12-17T15:21:35.527: EXE: 1/ssh -o ServerAliveInterval=60 -o ServerAliveCountMax=3 root@megatron.headai.com "df -h".
2024-12-17T15:22:05.748: Row 1: Filesystem Size Used Avail Use% Mounted on.
2024-12-17T15:22:05.748: Row 2: udev 63G 4.0K 63G 1% /dev.
2024-12-17T15:22:05.749: Row 3: tmpfs 13G 544M 13G 5% /run.
2024-12-17T15:22:05.749: Row 4: /dev/vda1 757G 512G 215G 71% /.
2024-12-17T15:22:05.749: Row 5: none 4.0K 0 4.0K 0% /sys/fs/cgroup.
2024-12-17T15:22:05.749: Row 6: none 5.0M 0 5.0M 0% /run/lock.
2024-12-17T15:22:05.750: Row 7: cgmfs 100K 0 100K 0% /run/cgmanager/fs.
2024-12-17T15:22:05.752: Row 8: none 63G 0 63G 0% /run/shm.
2024-12-17T15:22:05.752: Row 9: none 100M 0 100M 0% /run/user.
2024-12-17T15:22:05.753: Row 10: /dev/vdb 985G 230G 705G 25% /opt/tomcat/webapps/ROOT/analysis.
2024-12-17T15:22:05.753: Row 11: tmpfs 100K 0 100K 0% /var/lib/lxd/shmounts.
2024-12-17T15:22:05.753: Row 12: tmpfs 100K 0 100K 0% /var/lib/lxd/devlxd.
2024-12-17T15:22:05.753: Row 13: tmpfs 13G 0 13G 0% /run/user/0.
2024-12-17T15:22:05.759: SUC: 30233ms 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-17T15:22:05.759: ------ test: 2004
2024-12-17T15:22:05.760: URL: https://headai.com
2024-12-17T15:22:06.366: SUC: 606ms, Trusted by 100+ customers and partners globally, length 181609.
2024-12-17T15:22:06.368: ------ test: 2005
2024-12-17T15:22:06.828: SQL: show global status like 'threads_connected';.
2024-12-17T15:22:06.843: Results: value=2/200.
2024-12-17T15:22:06.844: SQL: value, lines: 1, ret=1, 2.
2024-12-17T15:22:06.849: SUC: 481ms 2
2024-12-17T15:22:06.850: ------ test: 2006
2024-12-17T15:22:06.956: SQL: SHOW PROCESSLIST;.
2024-12-17T15:22:06.958: Row 1: 25393901,root,localhost:45462,null,Query,0,starting,SHOW PROCESSLIST.
2024-12-17T15:22:06.958: SQL: COUNTNOTLINES:Command, lines: 1, ret=1, 1.
2024-12-17T15:22:06.959: SUC: 109ms 1
2024-12-17T15:22:06.960: checkJavaStack starting: 2007.
2024-12-17T15:22:37.209: ------ test: 2007
2024-12-17T15:22:37.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-17T15:23:07.566: Row 1: "https-openssl-nio-443-exec-72" #261389 daemon prio=5 os_prio=0 tid=0x00007f0070231800 nid=0x180dd runnable [0x00007f01821f8000].
2024-12-17T15:23:07.567: Row 2: java.lang.Thread.State: RUNNABLE.
2024-12-17T15:23:07.567: Row 3: at java.lang.String.indexOf(String.java:1769).
2024-12-17T15:23:07.567: Row 4: at java.lang.String.indexOf(String.java:1718).
2024-12-17T15:23:07.567: Row 5: at org.apache.commons.lang3.StringUtils.replace(StringUtils.java:5362).
2024-12-17T15:23:07.567: Row 6: at org.apache.commons.lang3.StringUtils.replace(StringUtils.java:5318).
2024-12-17T15:23:07.567: Row 7: at org.apache.commons.lang3.StringUtils.replace(StringUtils.java:5258).
2024-12-17T15:23:07.567: Row 8: at h2.core2021.semanticSimilarity_v3(core2021.java:1400).
2024-12-17T15:23:07.567: Row 9: at h2.core2021.text2keywords_v3(core2021.java:727).
2024-12-17T15:23:07.568: Row 10: at h2.BuildKnowledgeGraph_v5.build_a_map_on_fly_v4_1(BuildKnowledgeGraph_v5.java:1727).
2024-12-17T15:23:07.568: Row 11: at h2.SkillsForecast_methods.build_a_map_on_fly_v4(SkillsForecast_methods.java:5221).
2024-12-17T15:23:07.568: Row 12: at h2.helpers.check_pending_works(helpers.java:5270).
2024-12-17T15:23:07.568: Row 13: at h1.mc_api.doPost(mc_api.java:4576).
2024-12-17T15:23:07.568: Row 14: at h1.mc_api.doGet(mc_api.java:38).
2024-12-17T15:23:07.575: SUC: 2007, 30365ms, lines: 14.
2024-12-17T15:23:07.576: checkJavaStack end: 2007, pid/ret=14.
2024-12-17T15:23:17.576: checkJavaStack starting: 2007.
2024-12-17T15:23:47.774: ------ test: 2007
2024-12-17T15:23:47.775: 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-17T15:24:18.103: Row 1: "https-openssl-nio-443-exec-72" #261389 daemon prio=5 os_prio=0 tid=0x00007f0070231800 nid=0x180dd runnable [0x00007f01821f8000].
2024-12-17T15:24:18.103: Row 2: java.lang.Thread.State: RUNNABLE.
2024-12-17T15:24:18.103: Row 3: at java.lang.String.indexOf(String.java:1769).
2024-12-17T15:24:18.103: Row 4: at java.lang.String.indexOf(String.java:1718).
2024-12-17T15:24:18.103: Row 5: at org.apache.commons.lang3.StringUtils.replace(StringUtils.java:5362).
2024-12-17T15:24:18.104: Row 6: at org.apache.commons.lang3.StringUtils.replace(StringUtils.java:5318).
2024-12-17T15:24:18.104: Row 7: at org.apache.commons.lang3.StringUtils.replace(StringUtils.java:5258).
2024-12-17T15:24:18.104: Row 8: at h2.core2021.semanticSimilarity_v3(core2021.java:1401).
2024-12-17T15:24:18.104: Row 9: at h2.core2021.text2keywords_v3(core2021.java:727).
2024-12-17T15:24:18.104: Row 10: at h2.BuildKnowledgeGraph_v5.build_a_map_on_fly_v4_1(BuildKnowledgeGraph_v5.java:1727).
2024-12-17T15:24:18.104: Row 11: at h2.SkillsForecast_methods.build_a_map_on_fly_v4(SkillsForecast_methods.java:5221).
2024-12-17T15:24:18.104: Row 12: at h2.helpers.check_pending_works(helpers.java:5270).
2024-12-17T15:24:18.104: Row 13: at h1.mc_api.doPost(mc_api.java:4576).
2024-12-17T15:24:18.104: Row 14: at h1.mc_api.doGet(mc_api.java:38).
2024-12-17T15:24:18.111: SUC: 2007, 30336ms, lines: 14.
2024-12-17T15:24:18.112: checkJavaStack end: 2007, pid/ret=14.
2024-12-17T15:24:28.112: checkJavaStack starting: 2007.
2024-12-17T15:24:58.322: ------ test: 2007
2024-12-17T15:24:58.322: 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-17T15:25:28.691: SUC: 2007, 30369ms, lines: 4096.
2024-12-17T15:25:28.692: checkJavaStack end: 2007, pid/ret=4096.
2024-12-17T15:25:38.692: checkJavaStack starting: 2007.
2024-12-17T15:26:08.892: ------ test: 2007
2024-12-17T15:26:08.892: 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-17T15:26:39.212: SUC: 2007, 30320ms, lines: 4096.
2024-12-17T15:26:39.213: checkJavaStack end: 2007, pid/ret=4096.
2024-12-17T15:26:49.213: checkJavaStack starting: 2007.
2024-12-17T15:27:19.411: ------ test: 2007
2024-12-17T15:27:19.412: 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-17T15:27:49.747: SUC: 2007, 30335ms, lines: 4096.
2024-12-17T15:27:49.748: checkJavaStack end: 2007, pid/ret=4096.
2024-12-17T15:27:59.748: checkJavaStack starting: 2007.
2024-12-17T15:28:29.924: ------ test: 2007
2024-12-17T15:28:29.924: 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-17T15:29:00.248: SUC: 2007, 30324ms, lines: 4096.
2024-12-17T15:29:00.249: checkJavaStack end: 2007, pid/ret=4096.
2024-12-17T15:29:10.249: checkJavaStack starting: 2007.
2024-12-17T15:29:40.474: ------ test: 2007
2024-12-17T15:29:40.475: 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-17T15:30:10.838: SUC: 2007, 30363ms, lines: 4096.
2024-12-17T15:30:10.838: checkJavaStack end: 2007, pid/ret=4096.
2024-12-17T15:30:20.839: checkJavaStack starting: 2007.
2024-12-17T15:30:51.030: ------ test: 2007
2024-12-17T15:30:51.030: 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-17T15:31:21.345: SUC: 2007, 30315ms, lines: 4096.
2024-12-17T15:31:21.346: checkJavaStack end: 2007, pid/ret=4096.
2024-12-17T15:31:31.346: checkJavaStack starting: 2007.
2024-12-17T15:32:01.534: ------ test: 2007
2024-12-17T15:32:01.535: 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-17T15:32:31.848: Row 1: "https-openssl-nio-443-exec-81" #261398 daemon prio=5 os_prio=0 tid=0x00007f009818a800 nid=0x18155 runnable [0x00007f017ada1000].
2024-12-17T15:32:31.848: Row 2: java.lang.Thread.State: RUNNABLE.
2024-12-17T15:32:31.849: Row 3: at java.net.SocketOutputStream.socketWrite0(Native Method).
2024-12-17T15:32:31.849: Row 4: at java.net.SocketOutputStream.socketWrite(SocketOutputStream.java:111).
2024-12-17T15:32:31.849: Row 5: at java.net.SocketOutputStream.write(SocketOutputStream.java:155).
2024-12-17T15:32:31.849: Row 6: at java.io.BufferedOutputStream.flushBuffer(BufferedOutputStream.java:82).
2024-12-17T15:32:31.849: Row 7: at java.io.BufferedOutputStream.flush(BufferedOutputStream.java:140).
2024-12-17T15:32:31.853: Row 8: - locked <0x00000007b4acbfd0> (a java.io.BufferedOutputStream).
2024-12-17T15:32:31.853: Row 9: at com.mysql.jdbc.MysqlIO.send(MysqlIO.java:3643).
2024-12-17T15:32:31.853: Row 10: at com.mysql.jdbc.MysqlIO.sendCommand(MysqlIO.java:2417).
2024-12-17T15:32:31.854: Row 11: at com.mysql.jdbc.MysqlIO.sqlQueryDirect(MysqlIO.java:2582).
2024-12-17T15:32:31.854: Row 12: at com.mysql.jdbc.ConnectionImpl.execSQL(ConnectionImpl.java:2526).
2024-12-17T15:32:31.854: Row 13: - locked <0x00000007b4abe560> (a com.mysql.jdbc.JDBC4Connection).
2024-12-17T15:32:31.854: Row 14: at com.mysql.jdbc.ConnectionImpl.execSQL(ConnectionImpl.java:2484).
2024-12-17T15:32:31.854: Row 15: at com.mysql.jdbc.StatementImpl.executeQuery(StatementImpl.java:1446).
2024-12-17T15:32:31.854: Row 16: - locked <0x00000007b4abe560> (a com.mysql.jdbc.JDBC4Connection).
2024-12-17T15:32:31.854: Row 17: at com.mysql.jdbc.ConnectionImpl.loadServerVariables(ConnectionImpl.java:3828).
2024-12-17T15:32:31.854: Row 18: at com.mysql.jdbc.ConnectionImpl.initializePropsFromServer(ConnectionImpl.java:3268).
2024-12-17T15:32:31.854: Row 19: at com.mysql.jdbc.ConnectionImpl.connectOneTryOnly(ConnectionImpl.java:2278).
2024-12-17T15:32:31.854: Row 20: at com.mysql.jdbc.ConnectionImpl.createNewIO(ConnectionImpl.java:2064).
2024-12-17T15:32:31.855: Row 21: - locked <0x00000007b4abe560> (a com.mysql.jdbc.JDBC4Connection).
2024-12-17T15:32:31.855: Row 22: at com.mysql.jdbc.ConnectionImpl.(ConnectionImpl.java:790).
2024-12-17T15:32:31.855: Row 23: at com.mysql.jdbc.JDBC4Connection.(JDBC4Connection.java:44).
2024-12-17T15:32:31.856: Row 24: at sun.reflect.GeneratedConstructorAccessor31.newInstance(Unknown Source).
2024-12-17T15:32:31.860: SUC: 2007, 30325ms, lines: 33.
2024-12-17T15:32:31.860: checkJavaStack end: 2007, pid/ret=33.
2024-12-17T15:32:41.860: checkJavaStack starting: 2007.
2024-12-17T15:33:12.059: ------ test: 2007
2024-12-17T15:33:12.059: 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-17T15:33:42.361: SUC: 2007, 30302ms, lines: 4096.
2024-12-17T15:33:42.362: checkJavaStack end: 2007, pid/ret=4096.
2024-12-17T15:33:52.362: checkJavaStack starting: 2007.
2024-12-17T15:34:22.558: ------ test: 2007
2024-12-17T15:34:22.558: 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-17T15:34:52.873: SUC: 2007, 30315ms, lines: 4096.
2024-12-17T15:34:52.873: checkJavaStack end: 2007, pid/ret=4096.
2024-12-17T15:35:02.879: checkJavaStack starting: 2007.
2024-12-17T15:35:33.079: ------ test: 2007
2024-12-17T15:35:33.079: 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-17T15:36:03.435: SUC: 2007, 30356ms, lines: 4096.
2024-12-17T15:36:03.435: checkJavaStack end: 2007, pid/ret=4096.
2024-12-17T15:36:13.436: checkJavaStack starting: 2007.
2024-12-17T15:36:43.635: ------ test: 2007
2024-12-17T15:36:43.635: 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-17T15:37:13.996: SUC: 2007, 30361ms, lines: 4096.
2024-12-17T15:37:13.996: checkJavaStack end: 2007, pid/ret=4096.
2024-12-17T15:37:23.997: checkJavaStack starting: 2007.
2024-12-17T15:37:54.194: ------ test: 2007
2024-12-17T15:37:54.194: 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-17T15:38:24.519: SUC: 2007, 30325ms, lines: 4096.
2024-12-17T15:38:24.519: checkJavaStack end: 2007, pid/ret=4096.
2024-12-17T15:38:34.520: checkJavaStack starting: 2007.
2024-12-17T15:39:04.719: ------ test: 2007
2024-12-17T15:39:04.719: 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-17T15:39:35.080: SUC: 2007, 30361ms, lines: 4096.
2024-12-17T15:39:35.080: checkJavaStack end: 2007, pid/ret=4096.
2024-12-17T15:39:45.081: checkJavaStack starting: 2007.
2024-12-17T15:40:15.289: ------ test: 2007
2024-12-17T15:40:15.290: 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-17T15:40:45.600: SUC: 2007, 30310ms, lines: 4096.
2024-12-17T15:40:45.600: checkJavaStack end: 2007, pid/ret=4096.
2024-12-17T15:40:55.601: checkJavaStack starting: 2007.
2024-12-17T15:41:25.799: ------ test: 2007
2024-12-17T15:41:25.801: 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-17T15:41:56.105: SUC: 2007, 30304ms, lines: 4096.
2024-12-17T15:41:56.106: checkJavaStack end: 2007, pid/ret=4096.
2024-12-17T15:42:06.106: checkJavaStack starting: 2007.
2024-12-17T15:42:36.301: ------ test: 2007
2024-12-17T15:42:36.301: 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-17T15:43:06.618: SUC: 2007, 30317ms, lines: 4096.
2024-12-17T15:43:06.619: checkJavaStack end: 2007, pid/ret=4096.
2024-12-17T15:43:16.619: checkJavaStack starting: 2007.
2024-12-17T15:43:46.818: ------ test: 2007
2024-12-17T15:43:46.818: 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-17T15:44:17.154: SUC: 2007, 30336ms, lines: 4096.
2024-12-17T15:44:17.154: checkJavaStack end: 2007, pid/ret=4096.
2024-12-17T15:44:27.155: checkJavaStack starting: 2007.
2024-12-17T15:44:57.348: ------ test: 2007
2024-12-17T15:44:57.348: 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-17T15:45:27.703: SUC: 2007, 30355ms, lines: 4096.
2024-12-17T15:45:27.703: checkJavaStack end: 2007, pid/ret=4096.
2024-12-17T15:45:37.704: checkJavaStack starting: 2007.
2024-12-17T15:46:07.905: ------ test: 2007
2024-12-17T15:46:07.906: 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-17T15:46:38.218: SUC: 2007, 30312ms, lines: 4096.
2024-12-17T15:46:38.219: checkJavaStack end: 2007, pid/ret=4096.
2024-12-17T15:46:48.219: checkJavaStack starting: 2007.
2024-12-17T15:47:18.412: ------ test: 2007
2024-12-17T15:47:18.413: 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-17T15:47:48.749: SUC: 2007, 30336ms, lines: 4096.
2024-12-17T15:47:48.750: checkJavaStack end: 2007, pid/ret=4096.
2024-12-17T15:47:58.750: checkJavaStack starting: 2007.
2024-12-17T15:48:28.950: ------ test: 2007
2024-12-17T15:48:28.950: 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-17T15:48:59.238: SUC: 2007, 30288ms, lines: 4096.
2024-12-17T15:48:59.238: checkJavaStack end: 2007, pid/ret=4096.
2024-12-17T15:49:09.238: checkJavaStack starting: 2007.
2024-12-17T15:49:39.431: ------ test: 2007
2024-12-17T15:49:39.431: 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-17T15:50:09.750: SUC: 2007, 30319ms, lines: 4096.
2024-12-17T15:50:09.751: checkJavaStack end: 2007, pid/ret=4096.
2024-12-17T15:50:19.751: checkJavaStack starting: 2007.
2024-12-17T15:50:50.013: ------ test: 2007
2024-12-17T15:50:50.013: 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-17T15:51:20.330: SUC: 2007, 30317ms, lines: 4096.
2024-12-17T15:51:20.330: checkJavaStack end: 2007, pid/ret=4096.
2024-12-17T15:51:30.330: checkJavaStack starting: 2007.
2024-12-17T15:52:00.557: ------ test: 2007
2024-12-17T15:52:00.557: 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-17T15:52:30.874: SUC: 2007, 30317ms, lines: 4096.
2024-12-17T15:52:30.874: checkJavaStack end: 2007, pid/ret=4096.
2024-12-17T15:52:40.875: checkJavaStack starting: 2007.
2024-12-17T15:53:11.077: ------ test: 2007
2024-12-17T15:53:11.080: 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-17T15:53:41.401: SUC: 2007, 30320ms, lines: 4096.
2024-12-17T15:53:41.401: checkJavaStack end: 2007, pid/ret=4096.
2024-12-17T15:53:51.401: checkJavaStack starting: 2007.
2024-12-17T15:54:21.598: ------ test: 2007
2024-12-17T15:54:21.599: 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-17T15:54:51.919: SUC: 2007, 30320ms, lines: 4096.
2024-12-17T15:54:51.919: checkJavaStack end: 2007, pid/ret=4096.
2024-12-17T15:55:01.919: checkJavaStack starting: 2007.
2024-12-17T15:55:32.158: ------ test: 2007
2024-12-17T15:55:32.158: 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-17T15:56:02.480: SUC: 2007, 30321ms, lines: 4096.
2024-12-17T15:56:02.480: checkJavaStack end: 2007, pid/ret=4096.
2024-12-17T15:56:12.480: checkJavaStack starting: 2007.
2024-12-17T15:56:42.676: ------ test: 2007
2024-12-17T15:56:42.676: 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-17T15:57:12.980: SUC: 2007, 30304ms, lines: 4096.
2024-12-17T15:57:12.980: checkJavaStack end: 2007, pid/ret=4096.
2024-12-17T15:57:22.981: checkJavaStack starting: 2007.
2024-12-17T15:57:53.176: ------ test: 2007
2024-12-17T15:57:53.177: 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-17T15:58:23.512: SUC: 2007, 30335ms, lines: 4096.
2024-12-17T15:58:23.513: checkJavaStack end: 2007, pid/ret=4096.
2024-12-17T15:58:33.513: checkJavaStack starting: 2007.
2024-12-17T15:59:03.711: ------ test: 2007
2024-12-17T15:59:03.711: 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-17T15:59:34.065: SUC: 2007, 30354ms, lines: 4096.
2024-12-17T15:59:34.066: checkJavaStack end: 2007, pid/ret=4096.
2024-12-17T15:59:34.066: ------ test: end, 2309143ms. Score 39/39.
2024-12-17T15:59:34.066: Test duration: 38m29s.