2026-03-29T20:21:04.612: current working dir: /root/qa/mctester.
2026-03-29T20:21:04.655: current logging dir: ../mctester/www.
2026-03-29T20:21:04.655: ---------------------------------------------------------------
2026-03-29T20:21:04.661: mctester entry: 2026-03-29T20:21:04.656021 mode=50
2026-03-29T20:21:04.661: ---------------------------------------------------------------
2026-03-29T20:21:04.738: EXE: 1/git describe --tags.
2026-03-29T20:21:04.753: Row 1: v1.1-843-g38dd566.
2026-03-29T20:21:04.755: EXE: 1/git rev-list --count HEAD.
2026-03-29T20:21:04.765: Row 1: 900.
2026-03-29T20:21:04.766: EXE: 1/git rev-parse HEAD.
2026-03-29T20:21:04.769: Row 1: 38dd566bee632d8f3d4e205b4854763bc8c837af.
2026-03-29T20:21:04.778: mctester: v1.1.900 (38dd566bee632d8f3d4e205b4854763bc8c837af).
2026-03-29T20:21:04.779: Entering into a performance tests mode.
2026-03-29T20:21:04.780: ------ test: start, 2026-03-29T20:21:04.779
2026-03-29T20:21:04.782: ------ test: 2000
2026-03-29T20:21:04.783: CMD: ssh -o ServerAliveInterval=60 -o ServerAliveCountMax=3 root@megatron.headai.com "top -b -n 1"
2026-03-29T20:21:04.783: EXE: 1/ssh -o ServerAliveInterval=60 -o ServerAliveCountMax=3 root@megatron.headai.com "top -b -n 1".
2026-03-29T20:21:05.023: Row 1: top - 20:21:05 up 18 days, 1:36, 0 users, load average: 0.14, 0.27, 0.79.
2026-03-29T20:21:05.024: Row 2: Tasks: 533 total, 1 running, 532 sleeping, 0 stopped, 0 zombie.
2026-03-29T20:21:05.024: Row 3: %Cpu(s): 1.8 us, 0.1 sy, 0.0 ni, 98.1 id, 0.0 wa, 0.0 hi, 0.0 si, 0.0 st.
2026-03-29T20:21:05.025: Row 4: KiB Mem : 13202036+total, 1734572 free, 39452460 used, 90833328 buff/cache.
2026-03-29T20:21:05.025: Row 5: KiB Swap: 0 total, 0 free, 0 used. 91545968 avail Mem .
2026-03-29T20:21:05.025: Row 6: .
2026-03-29T20:21:05.025: Row 7: PID USER PR NI VIRT RES SHR S %CPU %MEM TIME+ COMMAND.
2026-03-29T20:21:05.025: Row 8: 1 root 20 0 43624 4080 2784 S 0.0 0.0 1:14.34 init.
2026-03-29T20:21:05.026: Row 9: 2 root 20 0 0 0 0 S 0.0 0.0 0:00.07 kthreadd.
2026-03-29T20:21:05.026: Row 10: 3 root 20 0 0 0 0 S 0.0 0.0 0:13.75 ksoftirqd+.
2026-03-29T20:21:05.026: Row 11: 5 root 0 -20 0 0 0 S 0.0 0.0 0:00.00 kworker/0+.
2026-03-29T20:21:05.026: Row 12: 7 root 20 0 0 0 0 S 0.0 0.0 15:33.94 rcu_sched.
2026-03-29T20:21:05.027: Row 13: 8 root 20 0 0 0 0 S 0.0 0.0 0:00.00 rcu_bh.
2026-03-29T20:21:05.027: Row 14: 9 root rt 0 0 0 0 S 0.0 0.0 0:00.46 migration+.
2026-03-29T20:21:05.028: Row 15: 10 root rt 0 0 0 0 S 0.0 0.0 0:09.44 watchdog/0.
2026-03-29T20:21:05.028: Row 16: 11 root rt 0 0 0 0 S 0.0 0.0 0:07.42 watchdog/1.
2026-03-29T20:21:05.028: Row 17: 12 root rt 0 0 0 0 S 0.0 0.0 0:01.77 migration+.
2026-03-29T20:21:05.029: Row 18: 13 root 20 0 0 0 0 S 0.0 0.0 0:00.33 ksoftirqd+.
2026-03-29T20:21:05.029: Row 19: 14 root 20 0 0 0 0 S 0.0 0.0 0:00.00 kworker/1+.
2026-03-29T20:21:05.029: Row 20: 15 root 0 -20 0 0 0 S 0.0 0.0 0:00.00 kworker/1+.
2026-03-29T20:21:05.029: Row 21: 16 root rt 0 0 0 0 S 0.0 0.0 0:07.70 watchdog/2.
2026-03-29T20:21:05.029: Row 22: 17 root rt 0 0 0 0 S 0.0 0.0 0:00.68 migration+.
2026-03-29T20:21:05.030: Row 23: 18 root 20 0 0 0 0 S 0.0 0.0 0:00.34 ksoftirqd+.
2026-03-29T20:21:05.030: Row 24: 19 root 20 0 0 0 0 S 0.0 0.0 0:00.00 kworker/2+.
2026-03-29T20:21:05.100: SUC: 310ms Uptime 18 days, 1min 0.14, 5min 0.27, 15min 0.79, mem:132020360 total / 1734572 free / 39452460 used / 90833328 cache / 91545968 avail
2026-03-29T20:21:05.111: ------ test: 2001
2026-03-29T20:21:05.111: CMD: top -b -E k -n 1
2026-03-29T20:21:05.111: EXE: 1/top -b -E k -n 1.
2026-03-29T20:21:05.274: Row 1: top - 20:21:05 up 116 days, 3:41, 0 users, load average: 7.24, 7.78, 7.92.
2026-03-29T20:21:05.274: Row 2: Tasks: 245 total, 1 running, 243 sleeping, 1 stopped, 0 zombie.
2026-03-29T20:21:05.274: Row 3: %Cpu(s): 3.9 us, 0.4 sy, 0.0 ni, 95.7 id, 0.0 wa, 0.0 hi, 0.0 si, 0.0 st.
2026-03-29T20:21:05.275: Row 4: KiB Mem : 32827244 total, 19607464 free, 1724492 used, 11495288 buff/cache.
2026-03-29T20:21:05.275: Row 5: KiB Swap: 16777212 total, 16776944 free, 268 used. 30267016 avail Mem .
2026-03-29T20:21:05.275: Row 6: .
2026-03-29T20:21:05.276: Row 7: PID USER PR NI VIRT RES SHR S %CPU %MEM TIME+ COMMAND.
2026-03-29T20:21:05.276: Row 8: 3438669 root 20 0 5054624 76296 27228 S 50.0 0.2 0:01.17 java.
2026-03-29T20:21:05.276: Row 9: 1 root 20 0 167736 13056 8168 S 0.0 0.0 6:43.90 systemd.
2026-03-29T20:21:05.277: Row 10: 2 root 20 0 0 0 0 S 0.0 0.0 0:02.47 kthreadd.
2026-03-29T20:21:05.277: Row 11: 3 root 0 -20 0 0 0 I 0.0 0.0 0:00.00 rcu_gp.
2026-03-29T20:21:05.277: Row 12: 4 root 0 -20 0 0 0 I 0.0 0.0 0:00.00 rcu_par+.
2026-03-29T20:21:05.277: Row 13: 5 root 0 -20 0 0 0 I 0.0 0.0 0:00.00 slub_fl+.
2026-03-29T20:21:05.277: Row 14: 6 root 0 -20 0 0 0 I 0.0 0.0 0:00.00 netns.
2026-03-29T20:21:05.277: Row 15: 8 root 0 -20 0 0 0 I 0.0 0.0 0:00.00 kworker+.
2026-03-29T20:21:05.278: Row 16: 10 root 0 -20 0 0 0 I 0.0 0.0 0:00.00 mm_perc+.
2026-03-29T20:21:05.278: Row 17: 11 root 20 0 0 0 0 S 0.0 0.0 0:00.00 rcu_tas+.
2026-03-29T20:21:05.278: Row 18: 12 root 20 0 0 0 0 S 0.0 0.0 0:00.00 rcu_tas+.
2026-03-29T20:21:05.278: Row 19: 13 root 20 0 0 0 0 S 0.0 0.0 0:00.22 ksoftir+.
2026-03-29T20:21:05.278: Row 20: 14 root 20 0 0 0 0 I 0.0 0.0 23:48.92 rcu_sch+.
2026-03-29T20:21:05.279: Row 21: 15 root rt 0 0 0 0 S 0.0 0.0 0:24.08 migrati+.
2026-03-29T20:21:05.279: Row 22: 16 root -51 0 0 0 0 S 0.0 0.0 0:00.00 idle_in+.
2026-03-29T20:21:05.279: Row 23: 18 root 20 0 0 0 0 S 0.0 0.0 0:00.00 cpuhp/0.
2026-03-29T20:21:05.279: Row 24: 19 root 20 0 0 0 0 S 0.0 0.0 0:00.00 cpuhp/1.
2026-03-29T20:21:05.302: SUC: 191ms Uptime 116 days, 1min 7.24, 5min 7.78, 15min 7.92, mem:32827244 total / 19607464 free / 1724492 used / 11495288 cache / 30267016 avail
2026-03-29T20:21:05.303: ------ test: 2002
2026-03-29T20:21:05.303: CMD: df -h
2026-03-29T20:21:05.303: EXE: 1/df -h.
2026-03-29T20:21:05.305: Row 1: Filesystem Size Used Avail Use% Mounted on.
2026-03-29T20:21:05.305: Row 2: tmpfs 3.2G 338M 2.9G 11% /run.
2026-03-29T20:21:05.306: Row 3: /dev/vda2 59G 47G 9.9G 83% /.
2026-03-29T20:21:05.306: Row 4: tmpfs 16G 0 16G 0% /dev/shm.
2026-03-29T20:21:05.307: Row 5: tmpfs 5.0M 0 5.0M 0% /run/lock.
2026-03-29T20:21:05.308: SUC: 5ms Diskfree qa.headai.com: tmpfs:11%, /dev/vda2:83%, tmpfs:0%, tmpfs:0%.
2026-03-29T20:21:05.309: ------ test: 2003
2026-03-29T20:21:05.309: CMD: ssh -o ServerAliveInterval=60 -o ServerAliveCountMax=3 root@megatron.headai.com "df -h"
2026-03-29T20:21:05.310: EXE: 1/ssh -o ServerAliveInterval=60 -o ServerAliveCountMax=3 root@megatron.headai.com "df -h".
2026-03-29T20:21:05.387: Row 1: Filesystem Size Used Avail Use% Mounted on.
2026-03-29T20:21:05.387: Row 2: udev 63G 4.0K 63G 1% /dev.
2026-03-29T20:21:05.387: Row 3: tmpfs 13G 5.5M 13G 1% /run.
2026-03-29T20:21:05.387: Row 4: /dev/vda1 757G 322G 405G 45% /.
2026-03-29T20:21:05.387: Row 5: none 4.0K 0 4.0K 0% /sys/fs/cgroup.
2026-03-29T20:21:05.388: Row 6: none 5.0M 0 5.0M 0% /run/lock.
2026-03-29T20:21:05.388: Row 7: none 63G 0 63G 0% /run/shm.
2026-03-29T20:21:05.388: Row 8: none 100M 0 100M 0% /run/user.
2026-03-29T20:21:05.389: Row 9: cgmfs 100K 0 100K 0% /run/cgmanager/fs.
2026-03-29T20:21:05.389: Row 10: /dev/vdb 985G 289G 646G 31% /opt/tomcat/webapps/ROOT/analysis.
2026-03-29T20:21:05.389: Row 11: tmpfs 100K 0 100K 0% /var/lib/lxd/shmounts.
2026-03-29T20:21:05.389: Row 12: tmpfs 100K 0 100K 0% /var/lib/lxd/devlxd.
2026-03-29T20:21:05.390: SUC: 81ms Diskfree megatron.headai.com: udev:1%, tmpfs:1%, /dev/vda1:45%, none:0%, none:0%, none:0%, none:0%, cgmfs:0%, /dev/vdb:31%, tmpfs:0%, tmpfs:0%.
2026-03-29T20:21:05.390: ------ test: 2004
2026-03-29T20:21:05.391: URL: https://headai.com
2026-03-29T20:21:16.101: DIF: 10704ms expected 5000ms, meets direction. Headai is a deep tech company enabling Decision Intelligence for future-focused organizations through automated analysis of unstructured text data. Unlock Hidden Insights Optimize Workforce Strategies Enable Decision Intelligence Improve Operational Efficiency Metis Training tool for creating machine-readable data Learn how to create high-quality, machine-readable data for better job recommendations and labor market […]" />
2026-03-29T20:21:16.102: ------ test: 2005
2026-03-29T20:21:16.354: SQL: show global status like 'threads_connected';.
2026-03-29T20:21:16.380: Results: value=3/200.
2026-03-29T20:21:16.386: SQL: value, lines: 1, ret=1, 3.
2026-03-29T20:21:16.387: SUC: 285ms 3
2026-03-29T20:21:16.388: ------ test: 2006
2026-03-29T20:21:16.467: SQL: SHOW PROCESSLIST;.
2026-03-29T20:21:16.472: Row 1: 8133718,root,localhost:54756,analysis,Query,0,Sending data,SELECT SUBSTRING_INDEX(token, '||', 1) as token_clean, count(id) as count FROM analysis.long_queries.
2026-03-29T20:21:16.472: Row 2: 8133720,root,localhost:54758,null,Query,0,starting,SHOW PROCESSLIST.
2026-03-29T20:21:16.473: SQL: COUNTNOTLINES:Command, lines: 2, ret=2, 2.
2026-03-29T20:21:16.473: SUC: 85ms 2
2026-03-29T20:21:16.474: checkJavaStack starting: 2007.
2026-03-29T20:21:16.572: ------ test: 2007
2026-03-29T20:21:16.573: EXE: 5/"jstack 24842 | 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".
2026-03-29T20:21:16.868: Row 1: "https-openssl-nio-443-exec-28" #944 daemon prio=5 os_prio=0 tid=0x00007feb5c02e000 nid=0x78ae runnable [0x00007febc421f000].
2026-03-29T20:21:16.868: Row 2: java.lang.Thread.State: RUNNABLE.
2026-03-29T20:21:16.868: Row 3: at java.net.SocketInputStream.socketRead0(Native Method).
2026-03-29T20:21:16.869: Row 4: at java.net.SocketInputStream.socketRead(SocketInputStream.java:116).
2026-03-29T20:21:16.869: Row 5: at java.net.SocketInputStream.read(SocketInputStream.java:171).
2026-03-29T20:21:16.869: Row 6: at java.net.SocketInputStream.read(SocketInputStream.java:141).
2026-03-29T20:21:16.869: Row 7: at com.mysql.jdbc.util.ReadAheadInputStream.fill(ReadAheadInputStream.java:100).
2026-03-29T20:21:16.870: Row 8: at com.mysql.jdbc.util.ReadAheadInputStream.readFromUnderlyingStreamIfNecessary(ReadAheadInputStream.java:143).
2026-03-29T20:21:16.870: Row 9: at com.mysql.jdbc.util.ReadAheadInputStream.read(ReadAheadInputStream.java:173).
2026-03-29T20:21:16.870: Row 10: - locked <0x00000007136b7f60> (a com.mysql.jdbc.util.ReadAheadInputStream).
2026-03-29T20:21:16.870: Row 11: at com.mysql.jdbc.MysqlIO.readFully(MysqlIO.java:2911).
2026-03-29T20:21:16.870: Row 12: at com.mysql.jdbc.MysqlIO.reuseAndReadPacket(MysqlIO.java:3337).
2026-03-29T20:21:16.870: Row 13: at com.mysql.jdbc.MysqlIO.reuseAndReadPacket(MysqlIO.java:3327).
2026-03-29T20:21:16.871: Row 14: at com.mysql.jdbc.MysqlIO.checkErrorPacket(MysqlIO.java:3814).
2026-03-29T20:21:16.871: Row 15: at com.mysql.jdbc.MysqlIO.sendCommand(MysqlIO.java:2435).
2026-03-29T20:21:16.871: Row 16: at com.mysql.jdbc.MysqlIO.sqlQueryDirect(MysqlIO.java:2582).
2026-03-29T20:21:16.871: Row 17: at com.mysql.jdbc.ConnectionImpl.execSQL(ConnectionImpl.java:2530).
2026-03-29T20:21:16.871: Row 18: - locked <0x00000007136ae578> (a com.mysql.jdbc.JDBC4Connection).
2026-03-29T20:21:16.872: Row 19: at com.mysql.jdbc.PreparedStatement.executeInternal(PreparedStatement.java:1907).
2026-03-29T20:21:16.872: Row 20: - locked <0x00000007136ae578> (a com.mysql.jdbc.JDBC4Connection).
2026-03-29T20:21:16.872: Row 21: at com.mysql.jdbc.PreparedStatement.executeQuery(PreparedStatement.java:2030).
2026-03-29T20:21:16.872: Row 22: - locked <0x00000007136ae578> (a com.mysql.jdbc.JDBC4Connection).
2026-03-29T20:21:16.872: Row 23: at h2.helpers.check_pending_works(helpers.java:4959).
2026-03-29T20:21:16.872: Row 24: at h1.mc_api.doPost(mc_api.java:4578).
2026-03-29T20:21:16.874: SUC: 2007, 300ms, lines: 25.
2026-03-29T20:21:16.877: checkJavaStack end: 2007, pid/ret=25.
2026-03-29T20:21:26.877: checkJavaStack starting: 2007.
2026-03-29T20:21:29.971: ------ test: 2007
2026-03-29T20:21:29.972: EXE: 5/"jstack 24842 | 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".
2026-03-29T20:21:30.232: Row 1: "https-openssl-nio-443-exec-25" #941 daemon prio=5 os_prio=0 tid=0x00007feb50c89000 nid=0x789f runnable [0x00007febc4421000].
2026-03-29T20:21:30.233: Row 2: java.lang.Thread.State: RUNNABLE.
2026-03-29T20:21:30.233: Row 3: at java.net.SocketInputStream.socketRead0(Native Method).
2026-03-29T20:21:30.233: Row 4: at java.net.SocketInputStream.socketRead(SocketInputStream.java:116).
2026-03-29T20:21:30.233: Row 5: at java.net.SocketInputStream.read(SocketInputStream.java:171).
2026-03-29T20:21:30.233: Row 6: at java.net.SocketInputStream.read(SocketInputStream.java:141).
2026-03-29T20:21:30.233: Row 7: at com.mysql.jdbc.util.ReadAheadInputStream.fill(ReadAheadInputStream.java:100).
2026-03-29T20:21:30.233: Row 8: at com.mysql.jdbc.util.ReadAheadInputStream.readFromUnderlyingStreamIfNecessary(ReadAheadInputStream.java:143).
2026-03-29T20:21:30.233: Row 9: at com.mysql.jdbc.util.ReadAheadInputStream.read(ReadAheadInputStream.java:173).
2026-03-29T20:21:30.234: Row 10: - locked <0x0000000705e1dd88> (a com.mysql.jdbc.util.ReadAheadInputStream).
2026-03-29T20:21:30.234: Row 11: at com.mysql.jdbc.MysqlIO.readFully(MysqlIO.java:2911).
2026-03-29T20:21:30.234: Row 12: at com.mysql.jdbc.MysqlIO.reuseAndReadPacket(MysqlIO.java:3337).
2026-03-29T20:21:30.234: Row 13: at com.mysql.jdbc.MysqlIO.reuseAndReadPacket(MysqlIO.java:3327).
2026-03-29T20:21:30.234: Row 14: at com.mysql.jdbc.MysqlIO.checkErrorPacket(MysqlIO.java:3814).
2026-03-29T20:21:30.234: Row 15: at com.mysql.jdbc.MysqlIO.sendCommand(MysqlIO.java:2435).
2026-03-29T20:21:30.234: Row 16: at com.mysql.jdbc.MysqlIO.sqlQueryDirect(MysqlIO.java:2582).
2026-03-29T20:21:30.234: Row 17: at com.mysql.jdbc.ConnectionImpl.execSQL(ConnectionImpl.java:2530).
2026-03-29T20:21:30.235: Row 18: - locked <0x0000000705e143a0> (a com.mysql.jdbc.JDBC4Connection).
2026-03-29T20:21:30.235: Row 19: at com.mysql.jdbc.PreparedStatement.executeInternal(PreparedStatement.java:1907).
2026-03-29T20:21:30.235: Row 20: - locked <0x0000000705e143a0> (a com.mysql.jdbc.JDBC4Connection).
2026-03-29T20:21:30.235: Row 21: at com.mysql.jdbc.PreparedStatement.executeQuery(PreparedStatement.java:2030).
2026-03-29T20:21:30.235: Row 22: - locked <0x0000000705e143a0> (a com.mysql.jdbc.JDBC4Connection).
2026-03-29T20:21:30.235: Row 23: at h2.helpers.check_pending_works(helpers.java:4959).
2026-03-29T20:21:30.236: Row 24: at h1.mc_api.doPost(mc_api.java:4578).
2026-03-29T20:21:30.236: SUC: 2007, 265ms, lines: 25.
2026-03-29T20:21:30.237: checkJavaStack end: 2007, pid/ret=25.
2026-03-29T20:21:40.237: checkJavaStack starting: 2007.
2026-03-29T20:21:40.328: ------ test: 2007
2026-03-29T20:21:40.329: EXE: 5/"jstack 24842 | 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".
2026-03-29T20:21:40.560: Row 1: "https-openssl-nio-443-exec-31" #947 daemon prio=5 os_prio=0 tid=0x00007feb8c023000 nid=0x78f6 runnable [0x00007febc4523000].
2026-03-29T20:21:40.561: Row 2: java.lang.Thread.State: RUNNABLE.
2026-03-29T20:21:40.561: Row 3: at h2.skills_compass.get_suggestion_with_interests(skills_compass.java:1449).
2026-03-29T20:21:40.561: Row 4: at h2.skills_compass.get_suggestion(skills_compass.java:23).
2026-03-29T20:21:40.561: Row 5: at h2.Utils.doPost(Utils.java:854).
2026-03-29T20:21:40.561: Row 6: at h2.Utils.doGet(Utils.java:31).
2026-03-29T20:21:40.564: SUC: 2007, 236ms, lines: 6.
2026-03-29T20:21:40.565: checkJavaStack end: 2007, pid/ret=6.
2026-03-29T20:21:50.565: checkJavaStack starting: 2007.
2026-03-29T20:21:50.660: ------ test: 2007
2026-03-29T20:21:50.660: EXE: 5/"jstack 24842 | 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".
2026-03-29T20:21:50.906: SUC: 2007, 246ms, lines: 4096.
2026-03-29T20:21:50.907: checkJavaStack end: 2007, pid/ret=4096.
2026-03-29T20:22:00.908: checkJavaStack starting: 2007.
2026-03-29T20:22:00.999: ------ test: 2007
2026-03-29T20:22:01.000: EXE: 5/"jstack 24842 | 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".
2026-03-29T20:22:01.211: Row 1: "https-openssl-nio-443-exec-17" #929 daemon prio=5 os_prio=0 tid=0x00007feb8001e800 nid=0x7885 runnable [0x00007febc542f000].
2026-03-29T20:22:01.211: Row 2: java.lang.Thread.State: RUNNABLE.
2026-03-29T20:22:01.212: Row 3: at java.net.SocketInputStream.socketRead0(Native Method).
2026-03-29T20:22:01.212: Row 4: at java.net.SocketInputStream.socketRead(SocketInputStream.java:116).
2026-03-29T20:22:01.212: Row 5: at java.net.SocketInputStream.read(SocketInputStream.java:171).
2026-03-29T20:22:01.212: Row 6: at java.net.SocketInputStream.read(SocketInputStream.java:141).
2026-03-29T20:22:01.212: Row 7: at com.mysql.jdbc.util.ReadAheadInputStream.fill(ReadAheadInputStream.java:100).
2026-03-29T20:22:01.212: Row 8: at com.mysql.jdbc.util.ReadAheadInputStream.readFromUnderlyingStreamIfNecessary(ReadAheadInputStream.java:143).
2026-03-29T20:22:01.212: Row 9: at com.mysql.jdbc.util.ReadAheadInputStream.read(ReadAheadInputStream.java:173).
2026-03-29T20:22:01.212: Row 10: - locked <0x00000006ad826db0> (a com.mysql.jdbc.util.ReadAheadInputStream).
2026-03-29T20:22:01.212: Row 11: at com.mysql.jdbc.MysqlIO.readFully(MysqlIO.java:2911).
2026-03-29T20:22:01.213: Row 12: at com.mysql.jdbc.MysqlIO.reuseAndReadPacket(MysqlIO.java:3337).
2026-03-29T20:22:01.213: Row 13: at com.mysql.jdbc.MysqlIO.reuseAndReadPacket(MysqlIO.java:3327).
2026-03-29T20:22:01.213: Row 14: at com.mysql.jdbc.MysqlIO.checkErrorPacket(MysqlIO.java:3814).
2026-03-29T20:22:01.213: Row 15: at com.mysql.jdbc.MysqlIO.sendCommand(MysqlIO.java:2435).
2026-03-29T20:22:01.213: Row 16: at com.mysql.jdbc.MysqlIO.sqlQueryDirect(MysqlIO.java:2582).
2026-03-29T20:22:01.213: Row 17: at com.mysql.jdbc.ConnectionImpl.execSQL(ConnectionImpl.java:2530).
2026-03-29T20:22:01.213: Row 18: - locked <0x00000006ad81d3e8> (a com.mysql.jdbc.JDBC4Connection).
2026-03-29T20:22:01.213: Row 19: at com.mysql.jdbc.PreparedStatement.executeInternal(PreparedStatement.java:1907).
2026-03-29T20:22:01.213: Row 20: - locked <0x00000006ad81d3e8> (a com.mysql.jdbc.JDBC4Connection).
2026-03-29T20:22:01.214: Row 21: at com.mysql.jdbc.PreparedStatement.executeQuery(PreparedStatement.java:2030).
2026-03-29T20:22:01.214: Row 22: - locked <0x00000006ad81d3e8> (a com.mysql.jdbc.JDBC4Connection).
2026-03-29T20:22:01.214: Row 23: at h2.helpers.store_query(helpers.java:4257).
2026-03-29T20:22:01.214: Row 24: at h2.BuildScorecard.doPost(BuildScorecard.java:229).
2026-03-29T20:22:01.214: SUC: 2007, 215ms, lines: 25.
2026-03-29T20:22:01.215: checkJavaStack end: 2007, pid/ret=25.
2026-03-29T20:22:11.215: checkJavaStack starting: 2007.
2026-03-29T20:22:11.309: ------ test: 2007
2026-03-29T20:22:11.310: EXE: 5/"jstack 24842 | 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".
2026-03-29T20:22:11.555: SUC: 2007, 245ms, lines: 4096.
2026-03-29T20:22:11.556: checkJavaStack end: 2007, pid/ret=4096.
2026-03-29T20:22:21.557: checkJavaStack starting: 2007.
2026-03-29T20:22:21.677: ------ test: 2007
2026-03-29T20:22:21.677: EXE: 5/"jstack 24842 | 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".
2026-03-29T20:22:21.898: Row 1: "https-openssl-nio-443-exec-19" #933 daemon prio=5 os_prio=0 tid=0x00007feb54054000 nid=0x788e runnable [0x00007feb7b7f6000].
2026-03-29T20:22:21.898: Row 2: java.lang.Thread.State: RUNNABLE.
2026-03-29T20:22:21.898: Row 3: at java.net.SocketInputStream.socketRead0(Native Method).
2026-03-29T20:22:21.898: Row 4: at java.net.SocketInputStream.socketRead(SocketInputStream.java:116).
2026-03-29T20:22:21.898: Row 5: at java.net.SocketInputStream.read(SocketInputStream.java:171).
2026-03-29T20:22:21.899: Row 6: at java.net.SocketInputStream.read(SocketInputStream.java:141).
2026-03-29T20:22:21.899: Row 7: at com.mysql.jdbc.util.ReadAheadInputStream.fill(ReadAheadInputStream.java:100).
2026-03-29T20:22:21.899: Row 8: at com.mysql.jdbc.util.ReadAheadInputStream.readFromUnderlyingStreamIfNecessary(ReadAheadInputStream.java:143).
2026-03-29T20:22:21.899: Row 9: at com.mysql.jdbc.util.ReadAheadInputStream.read(ReadAheadInputStream.java:173).
2026-03-29T20:22:21.899: Row 10: - locked <0x00000007afe0ab80> (a com.mysql.jdbc.util.ReadAheadInputStream).
2026-03-29T20:22:21.899: Row 11: at com.mysql.jdbc.MysqlIO.readFully(MysqlIO.java:2911).
2026-03-29T20:22:21.899: Row 12: at com.mysql.jdbc.MysqlIO.reuseAndReadPacket(MysqlIO.java:3337).
2026-03-29T20:22:21.899: Row 13: at com.mysql.jdbc.MysqlIO.reuseAndReadPacket(MysqlIO.java:3327).
2026-03-29T20:22:21.899: Row 14: at com.mysql.jdbc.MysqlIO.checkErrorPacket(MysqlIO.java:3814).
2026-03-29T20:22:21.899: Row 15: at com.mysql.jdbc.MysqlIO.sendCommand(MysqlIO.java:2435).
2026-03-29T20:22:21.900: Row 16: at com.mysql.jdbc.MysqlIO.sqlQueryDirect(MysqlIO.java:2582).
2026-03-29T20:22:21.900: Row 17: at com.mysql.jdbc.ConnectionImpl.execSQL(ConnectionImpl.java:2530).
2026-03-29T20:22:21.900: Row 18: - locked <0x00000007afe01198> (a com.mysql.jdbc.JDBC4Connection).
2026-03-29T20:22:21.900: Row 19: at com.mysql.jdbc.PreparedStatement.executeInternal(PreparedStatement.java:1907).
2026-03-29T20:22:21.900: Row 20: - locked <0x00000007afe01198> (a com.mysql.jdbc.JDBC4Connection).
2026-03-29T20:22:21.900: Row 21: at com.mysql.jdbc.PreparedStatement.executeQuery(PreparedStatement.java:2030).
2026-03-29T20:22:21.900: Row 22: - locked <0x00000007afe01198> (a com.mysql.jdbc.JDBC4Connection).
2026-03-29T20:22:21.900: Row 23: at h2.helpers.check_pending_works(helpers.java:4945).
2026-03-29T20:22:21.900: Row 24: at h1.mc_api.doPost(mc_api.java:4578).
2026-03-29T20:22:21.901: SUC: 2007, 224ms, lines: 25.
2026-03-29T20:22:21.902: checkJavaStack end: 2007, pid/ret=25.
2026-03-29T20:22:31.902: checkJavaStack starting: 2007.
2026-03-29T20:22:31.998: ------ test: 2007
2026-03-29T20:22:31.998: EXE: 5/"jstack 24842 | 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".
2026-03-29T20:22:32.250: Row 1: "https-openssl-nio-443-exec-25" #941 daemon prio=5 os_prio=0 tid=0x00007feb50c89000 nid=0x789f runnable [0x00007febc4421000].
2026-03-29T20:22:32.250: Row 2: java.lang.Thread.State: RUNNABLE.
2026-03-29T20:22:32.250: Row 3: at java.net.SocketInputStream.socketRead0(Native Method).
2026-03-29T20:22:32.251: Row 4: at java.net.SocketInputStream.socketRead(SocketInputStream.java:116).
2026-03-29T20:22:32.251: Row 5: at java.net.SocketInputStream.read(SocketInputStream.java:171).
2026-03-29T20:22:32.251: Row 6: at java.net.SocketInputStream.read(SocketInputStream.java:141).
2026-03-29T20:22:32.251: Row 7: at com.mysql.jdbc.util.ReadAheadInputStream.fill(ReadAheadInputStream.java:100).
2026-03-29T20:22:32.251: Row 8: at com.mysql.jdbc.util.ReadAheadInputStream.readFromUnderlyingStreamIfNecessary(ReadAheadInputStream.java:143).
2026-03-29T20:22:32.251: Row 9: at com.mysql.jdbc.util.ReadAheadInputStream.read(ReadAheadInputStream.java:173).
2026-03-29T20:22:32.251: Row 10: - locked <0x00000007aee718c8> (a com.mysql.jdbc.util.ReadAheadInputStream).
2026-03-29T20:22:32.251: Row 11: at com.mysql.jdbc.MysqlIO.readFully(MysqlIO.java:2911).
2026-03-29T20:22:32.251: Row 12: at com.mysql.jdbc.MysqlIO.reuseAndReadPacket(MysqlIO.java:3337).
2026-03-29T20:22:32.251: Row 13: at com.mysql.jdbc.MysqlIO.reuseAndReadPacket(MysqlIO.java:3327).
2026-03-29T20:22:32.251: Row 14: at com.mysql.jdbc.MysqlIO.checkErrorPacket(MysqlIO.java:3814).
2026-03-29T20:22:32.252: Row 15: at com.mysql.jdbc.MysqlIO.sendCommand(MysqlIO.java:2435).
2026-03-29T20:22:32.252: Row 16: at com.mysql.jdbc.MysqlIO.sqlQueryDirect(MysqlIO.java:2582).
2026-03-29T20:22:32.252: Row 17: at com.mysql.jdbc.ConnectionImpl.execSQL(ConnectionImpl.java:2530).
2026-03-29T20:22:32.252: Row 18: - locked <0x00000007aee67ee0> (a com.mysql.jdbc.JDBC4Connection).
2026-03-29T20:22:32.252: Row 19: at com.mysql.jdbc.PreparedStatement.executeInternal(PreparedStatement.java:1907).
2026-03-29T20:22:32.252: Row 20: - locked <0x00000007aee67ee0> (a com.mysql.jdbc.JDBC4Connection).
2026-03-29T20:22:32.252: Row 21: at com.mysql.jdbc.PreparedStatement.executeQuery(PreparedStatement.java:2030).
2026-03-29T20:22:32.252: Row 22: - locked <0x00000007aee67ee0> (a com.mysql.jdbc.JDBC4Connection).
2026-03-29T20:22:32.252: Row 23: at h2.helpers.check_pending_works(helpers.java:4945).
2026-03-29T20:22:32.252: Row 24: at h1.mc_api.doPost(mc_api.java:4578).
2026-03-29T20:22:32.254: SUC: 2007, 256ms, lines: 25.
2026-03-29T20:22:32.255: checkJavaStack end: 2007, pid/ret=25.
2026-03-29T20:22:42.255: checkJavaStack starting: 2007.
2026-03-29T20:22:42.347: ------ test: 2007
2026-03-29T20:22:42.348: EXE: 5/"jstack 24842 | 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".
2026-03-29T20:22:42.566: Row 1: "https-openssl-nio-443-exec-31" #947 daemon prio=5 os_prio=0 tid=0x00007feb8c023000 nid=0x78f6 runnable [0x00007febc4522000].
2026-03-29T20:22:42.567: Row 2: java.lang.Thread.State: RUNNABLE.
2026-03-29T20:22:42.567: Row 3: at java.net.SocketInputStream.socketRead0(Native Method).
2026-03-29T20:22:42.567: Row 4: at java.net.SocketInputStream.socketRead(SocketInputStream.java:116).
2026-03-29T20:22:42.567: Row 5: at java.net.SocketInputStream.read(SocketInputStream.java:171).
2026-03-29T20:22:42.567: Row 6: at java.net.SocketInputStream.read(SocketInputStream.java:141).
2026-03-29T20:22:42.567: Row 7: at com.mysql.jdbc.util.ReadAheadInputStream.fill(ReadAheadInputStream.java:100).
2026-03-29T20:22:42.567: Row 8: at com.mysql.jdbc.util.ReadAheadInputStream.readFromUnderlyingStreamIfNecessary(ReadAheadInputStream.java:143).
2026-03-29T20:22:42.567: Row 9: at com.mysql.jdbc.util.ReadAheadInputStream.read(ReadAheadInputStream.java:173).
2026-03-29T20:22:42.567: Row 10: - locked <0x00000007ac9f0be0> (a com.mysql.jdbc.util.ReadAheadInputStream).
2026-03-29T20:22:42.567: Row 11: at com.mysql.jdbc.MysqlIO.readFully(MysqlIO.java:2911).
2026-03-29T20:22:42.568: Row 12: at com.mysql.jdbc.MysqlIO.reuseAndReadPacket(MysqlIO.java:3337).
2026-03-29T20:22:42.568: Row 13: at com.mysql.jdbc.MysqlIO.reuseAndReadPacket(MysqlIO.java:3327).
2026-03-29T20:22:42.568: Row 14: at com.mysql.jdbc.MysqlIO.checkErrorPacket(MysqlIO.java:3814).
2026-03-29T20:22:42.568: Row 15: at com.mysql.jdbc.MysqlIO.sendCommand(MysqlIO.java:2435).
2026-03-29T20:22:42.568: Row 16: at com.mysql.jdbc.MysqlIO.sqlQueryDirect(MysqlIO.java:2582).
2026-03-29T20:22:42.568: Row 17: at com.mysql.jdbc.ConnectionImpl.execSQL(ConnectionImpl.java:2530).
2026-03-29T20:22:42.568: Row 18: - locked <0x00000007ac9e71f8> (a com.mysql.jdbc.JDBC4Connection).
2026-03-29T20:22:42.568: Row 19: at com.mysql.jdbc.PreparedStatement.executeInternal(PreparedStatement.java:1907).
2026-03-29T20:22:42.568: Row 20: - locked <0x00000007ac9e71f8> (a com.mysql.jdbc.JDBC4Connection).
2026-03-29T20:22:42.568: Row 21: at com.mysql.jdbc.PreparedStatement.executeQuery(PreparedStatement.java:2030).
2026-03-29T20:22:42.568: Row 22: - locked <0x00000007ac9e71f8> (a com.mysql.jdbc.JDBC4Connection).
2026-03-29T20:22:42.568: Row 23: at h2.helpers.check_pending_works(helpers.java:4959).
2026-03-29T20:22:42.568: Row 24: at h1.mc_api.doPost(mc_api.java:4578).
2026-03-29T20:22:42.570: SUC: 2007, 222ms, lines: 25.
2026-03-29T20:22:42.570: checkJavaStack end: 2007, pid/ret=25.
2026-03-29T20:22:52.570: checkJavaStack starting: 2007.
2026-03-29T20:22:52.660: ------ test: 2007
2026-03-29T20:22:52.661: EXE: 5/"jstack 24842 | 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".
2026-03-29T20:22:52.884: Row 1: "https-openssl-nio-443-exec-17" #929 daemon prio=5 os_prio=0 tid=0x00007feb8001e800 nid=0x7885 runnable [0x00007febc542e000].
2026-03-29T20:22:52.884: Row 2: java.lang.Thread.State: RUNNABLE.
2026-03-29T20:22:52.884: Row 3: at java.net.SocketInputStream.socketRead0(Native Method).
2026-03-29T20:22:52.884: Row 4: at java.net.SocketInputStream.socketRead(SocketInputStream.java:116).
2026-03-29T20:22:52.884: Row 5: at java.net.SocketInputStream.read(SocketInputStream.java:171).
2026-03-29T20:22:52.884: Row 6: at java.net.SocketInputStream.read(SocketInputStream.java:141).
2026-03-29T20:22:52.884: Row 7: at sun.security.ssl.SSLSocketInputRecord.read(SSLSocketInputRecord.java:457).
2026-03-29T20:22:52.885: Row 8: at sun.security.ssl.SSLSocketInputRecord.bytesInCompletePacket(SSLSocketInputRecord.java:68).
2026-03-29T20:22:52.885: Row 9: at sun.security.ssl.SSLSocketImpl.readApplicationRecord(SSLSocketImpl.java:1332).
2026-03-29T20:22:52.885: Row 10: at sun.security.ssl.SSLSocketImpl.access$300(SSLSocketImpl.java:73).
2026-03-29T20:22:52.885: Row 11: at sun.security.ssl.SSLSocketImpl$AppInputStream.read(SSLSocketImpl.java:948).
2026-03-29T20:22:52.885: Row 12: at net.snowflake.client.jdbc.internal.apache.http.impl.io.SessionInputBufferImpl.streamRead(SessionInputBufferImpl.java:137).
2026-03-29T20:22:52.885: Row 13: at net.snowflake.client.jdbc.internal.apache.http.impl.io.SessionInputBufferImpl.fillBuffer(SessionInputBufferImpl.java:153).
2026-03-29T20:22:52.885: Row 14: at net.snowflake.client.jdbc.internal.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java:280).
2026-03-29T20:22:52.885: Row 15: at net.snowflake.client.jdbc.internal.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:138).
2026-03-29T20:22:52.885: Row 16: at net.snowflake.client.jdbc.internal.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:56).
2026-03-29T20:22:52.885: Row 17: at net.snowflake.client.jdbc.internal.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259).
2026-03-29T20:22:52.886: Row 18: at net.snowflake.client.jdbc.internal.apache.http.impl.DefaultBHttpClientConnection.receiveResponseHeader(DefaultBHttpClientConnection.java:163).
2026-03-29T20:22:52.886: Row 19: at net.snowflake.client.jdbc.internal.apache.http.impl.conn.CPoolProxy.receiveResponseHeader(CPoolProxy.java:157).
2026-03-29T20:22:52.886: Row 20: at net.snowflake.client.jdbc.internal.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273).
2026-03-29T20:22:52.886: Row 21: at net.snowflake.client.jdbc.internal.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125).
2026-03-29T20:22:52.886: Row 22: at net.snowflake.client.jdbc.internal.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:272).
2026-03-29T20:22:52.886: Row 23: at net.snowflake.client.jdbc.internal.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:186).
2026-03-29T20:22:52.886: Row 24: at net.snowflake.client.jdbc.internal.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:89).
2026-03-29T20:22:52.887: SUC: 2007, 227ms, lines: 44.
2026-03-29T20:22:52.888: checkJavaStack end: 2007, pid/ret=44.
2026-03-29T20:23:02.888: checkJavaStack starting: 2007.
2026-03-29T20:23:02.981: ------ test: 2007
2026-03-29T20:23:02.982: EXE: 5/"jstack 24842 | 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".
2026-03-29T20:23:03.278: SUC: 2007, 295ms, lines: 4096.
2026-03-29T20:23:03.279: checkJavaStack end: 2007, pid/ret=4096.
2026-03-29T20:23:13.279: checkJavaStack starting: 2007.
2026-03-29T20:23:13.372: ------ test: 2007
2026-03-29T20:23:13.372: EXE: 5/"jstack 24842 | 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".
2026-03-29T20:23:13.657: SUC: 2007, 285ms, lines: 4096.
2026-03-29T20:23:13.658: checkJavaStack end: 2007, pid/ret=4096.
2026-03-29T20:23:23.658: checkJavaStack starting: 2007.
2026-03-29T20:23:23.776: ------ test: 2007
2026-03-29T20:23:23.777: EXE: 5/"jstack 24842 | 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".
2026-03-29T20:23:24.075: SUC: 2007, 299ms, lines: 4096.
2026-03-29T20:23:24.076: checkJavaStack end: 2007, pid/ret=4096.
2026-03-29T20:23:34.076: checkJavaStack starting: 2007.
2026-03-29T20:23:34.167: ------ test: 2007
2026-03-29T20:23:34.167: EXE: 5/"jstack 24842 | 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".
2026-03-29T20:23:34.402: SUC: 2007, 235ms, lines: 4096.
2026-03-29T20:23:34.403: checkJavaStack end: 2007, pid/ret=4096.
2026-03-29T20:23:44.403: checkJavaStack starting: 2007.
2026-03-29T20:23:44.496: ------ test: 2007
2026-03-29T20:23:44.496: EXE: 5/"jstack 24842 | 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".
2026-03-29T20:23:44.741: SUC: 2007, 245ms, lines: 4096.
2026-03-29T20:23:44.742: checkJavaStack end: 2007, pid/ret=4096.
2026-03-29T20:23:54.743: checkJavaStack starting: 2007.
2026-03-29T20:23:54.835: ------ test: 2007
2026-03-29T20:23:54.836: EXE: 5/"jstack 24842 | 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".
2026-03-29T20:23:55.083: SUC: 2007, 247ms, lines: 4096.
2026-03-29T20:23:55.084: checkJavaStack end: 2007, pid/ret=4096.
2026-03-29T20:24:05.084: checkJavaStack starting: 2007.
2026-03-29T20:24:05.178: ------ test: 2007
2026-03-29T20:24:05.178: EXE: 5/"jstack 24842 | 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".
2026-03-29T20:24:05.406: SUC: 2007, 228ms, lines: 4096.
2026-03-29T20:24:05.406: checkJavaStack end: 2007, pid/ret=4096.
2026-03-29T20:24:15.406: checkJavaStack starting: 2007.
2026-03-29T20:24:15.501: ------ test: 2007
2026-03-29T20:24:15.501: EXE: 5/"jstack 24842 | 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".
2026-03-29T20:24:15.706: SUC: 2007, 204ms, lines: 4096.
2026-03-29T20:24:15.706: checkJavaStack end: 2007, pid/ret=4096.
2026-03-29T20:24:25.706: checkJavaStack starting: 2007.
2026-03-29T20:24:25.824: ------ test: 2007
2026-03-29T20:24:25.825: EXE: 5/"jstack 24842 | 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".
2026-03-29T20:24:26.064: SUC: 2007, 239ms, lines: 4096.
2026-03-29T20:24:26.064: checkJavaStack end: 2007, pid/ret=4096.
2026-03-29T20:24:36.065: checkJavaStack starting: 2007.
2026-03-29T20:24:36.155: ------ test: 2007
2026-03-29T20:24:36.155: EXE: 5/"jstack 24842 | 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".
2026-03-29T20:24:36.402: SUC: 2007, 247ms, lines: 4096.
2026-03-29T20:24:36.402: checkJavaStack end: 2007, pid/ret=4096.
2026-03-29T20:24:46.403: checkJavaStack starting: 2007.
2026-03-29T20:24:46.496: ------ test: 2007
2026-03-29T20:24:46.496: EXE: 5/"jstack 24842 | 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".
2026-03-29T20:24:46.734: SUC: 2007, 238ms, lines: 4096.
2026-03-29T20:24:46.735: checkJavaStack end: 2007, pid/ret=4096.
2026-03-29T20:24:56.735: checkJavaStack starting: 2007.
2026-03-29T20:24:56.828: ------ test: 2007
2026-03-29T20:24:56.828: EXE: 5/"jstack 24842 | 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".
2026-03-29T20:24:57.056: SUC: 2007, 228ms, lines: 4096.
2026-03-29T20:24:57.056: checkJavaStack end: 2007, pid/ret=4096.
2026-03-29T20:25:07.057: checkJavaStack starting: 2007.
2026-03-29T20:25:07.153: ------ test: 2007
2026-03-29T20:25:07.153: EXE: 5/"jstack 24842 | 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".
2026-03-29T20:25:07.355: SUC: 2007, 202ms, lines: 4096.
2026-03-29T20:25:07.356: checkJavaStack end: 2007, pid/ret=4096.
2026-03-29T20:25:17.356: checkJavaStack starting: 2007.
2026-03-29T20:25:17.449: ------ test: 2007
2026-03-29T20:25:17.449: EXE: 5/"jstack 24842 | 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".
2026-03-29T20:25:17.712: SUC: 2007, 263ms, lines: 4096.
2026-03-29T20:25:17.713: checkJavaStack end: 2007, pid/ret=4096.
2026-03-29T20:25:27.713: checkJavaStack starting: 2007.
2026-03-29T20:25:27.832: ------ test: 2007
2026-03-29T20:25:27.833: EXE: 5/"jstack 24842 | 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".
2026-03-29T20:25:28.073: SUC: 2007, 240ms, lines: 4096.
2026-03-29T20:25:28.074: checkJavaStack end: 2007, pid/ret=4096.
2026-03-29T20:25:38.074: checkJavaStack starting: 2007.
2026-03-29T20:25:38.168: ------ test: 2007
2026-03-29T20:25:38.168: EXE: 5/"jstack 24842 | 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".
2026-03-29T20:25:38.398: SUC: 2007, 230ms, lines: 4096.
2026-03-29T20:25:38.399: checkJavaStack end: 2007, pid/ret=4096.
2026-03-29T20:25:48.399: checkJavaStack starting: 2007.
2026-03-29T20:25:48.492: ------ test: 2007
2026-03-29T20:25:48.492: EXE: 5/"jstack 24842 | 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".
2026-03-29T20:25:48.709: SUC: 2007, 217ms, lines: 4096.
2026-03-29T20:25:48.710: checkJavaStack end: 2007, pid/ret=4096.
2026-03-29T20:25:58.711: checkJavaStack starting: 2007.
2026-03-29T20:25:58.804: ------ test: 2007
2026-03-29T20:25:58.804: EXE: 5/"jstack 24842 | 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".
2026-03-29T20:25:59.054: SUC: 2007, 250ms, lines: 4096.
2026-03-29T20:25:59.055: checkJavaStack end: 2007, pid/ret=4096.
2026-03-29T20:26:09.056: checkJavaStack starting: 2007.
2026-03-29T20:26:09.149: ------ test: 2007
2026-03-29T20:26:09.150: EXE: 5/"jstack 24842 | 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".
2026-03-29T20:26:09.380: SUC: 2007, 231ms, lines: 4096.
2026-03-29T20:26:09.381: checkJavaStack end: 2007, pid/ret=4096.
2026-03-29T20:26:19.382: checkJavaStack starting: 2007.
2026-03-29T20:26:19.479: ------ test: 2007
2026-03-29T20:26:19.479: EXE: 5/"jstack 24842 | 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".
2026-03-29T20:26:19.694: SUC: 2007, 215ms, lines: 4096.
2026-03-29T20:26:19.695: checkJavaStack end: 2007, pid/ret=4096.
2026-03-29T20:26:29.695: checkJavaStack starting: 2007.
2026-03-29T20:26:29.821: ------ test: 2007
2026-03-29T20:26:29.821: EXE: 5/"jstack 24842 | 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".
2026-03-29T20:26:30.066: SUC: 2007, 245ms, lines: 4096.
2026-03-29T20:26:30.067: checkJavaStack end: 2007, pid/ret=4096.
2026-03-29T20:26:40.067: checkJavaStack starting: 2007.
2026-03-29T20:26:40.159: ------ test: 2007
2026-03-29T20:26:40.160: EXE: 5/"jstack 24842 | 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".
2026-03-29T20:26:40.371: SUC: 2007, 211ms, lines: 4096.
2026-03-29T20:26:40.372: checkJavaStack end: 2007, pid/ret=4096.
2026-03-29T20:26:40.375: ------ test: end, 335593ms. Score 39/39.
2026-03-29T20:26:40.377: Test duration: 5m35s.