2026-05-11T17:21:04.340: current working dir: /root/qa/mctester.
2026-05-11T17:21:04.381: current logging dir: ../mctester/www.
2026-05-11T17:21:04.381: ---------------------------------------------------------------
2026-05-11T17:21:04.387: mctester entry: 2026-05-11T17:21:04.381705 mode=50
2026-05-11T17:21:04.387: ---------------------------------------------------------------
2026-05-11T17:21:04.468: EXE: 1/git describe --tags.
2026-05-11T17:21:04.486: Row 1: v1.1-908-g517fe92.
2026-05-11T17:21:04.487: EXE: 1/git rev-list --count HEAD.
2026-05-11T17:21:04.499: Row 1: 965.
2026-05-11T17:21:04.500: EXE: 1/git rev-parse HEAD.
2026-05-11T17:21:04.503: Row 1: 517fe92682da9e2494a57d87e174206d49069707.
2026-05-11T17:21:04.512: mctester: v1.1.965 (517fe92682da9e2494a57d87e174206d49069707).
2026-05-11T17:21:04.512: Entering into a performance tests mode.
2026-05-11T17:21:04.514: ------ test: start, 2026-05-11T17:21:04.512
2026-05-11T17:21:04.515: ------ test: 2000
2026-05-11T17:21:04.516: CMD: ssh -o ServerAliveInterval=60 -o ServerAliveCountMax=3 root@megatron.headai.com "top -b -n 1"
2026-05-11T17:21:04.517: EXE: 1/ssh -o ServerAliveInterval=60 -o ServerAliveCountMax=3 root@megatron.headai.com "top -b -n 1".
2026-05-11T17:21:04.756: Row 1: top - 17:21:04 up 60 days, 22:36, 0 users, load average: 0.87, 0.98, 1.73.
2026-05-11T17:21:04.756: Row 2: Tasks: 584 total, 1 running, 583 sleeping, 0 stopped, 0 zombie.
2026-05-11T17:21:04.757: Row 3: %Cpu(s): 1.9 us, 0.1 sy, 0.0 ni, 98.0 id, 0.0 wa, 0.0 hi, 0.0 si, 0.0 st.
2026-05-11T17:21:04.757: Row 4: KiB Mem : 13202036+total, 720992 free, 48454860 used, 82844512 buff/cache.
2026-05-11T17:21:04.757: Row 5: KiB Swap: 0 total, 0 free, 0 used. 82573088 avail Mem .
2026-05-11T17:21:04.757: Row 6: .
2026-05-11T17:21:04.757: Row 7: PID USER PR NI VIRT RES SHR S %CPU %MEM TIME+ COMMAND.
2026-05-11T17:21:04.758: Row 8: 2215 mysql 20 0 0.194t 0.057t 0.023t S 143.8 46.3 12351:50 mysqld.
2026-05-11T17:21:04.758: Row 9: 40774 root 20 0 25.580g 0.010t 14940 S 6.2 8.5 10165:41 java.
2026-05-11T17:21:04.758: Row 10: 94836 root 20 0 44208 4068 3156 R 6.2 0.0 0:00.01 top.
2026-05-11T17:21:04.758: Row 11: 1 root 20 0 43624 3124 1828 S 0.0 0.0 3:59.10 init.
2026-05-11T17:21:04.759: Row 12: 2 root 20 0 0 0 0 S 0.0 0.0 0:00.26 kthreadd.
2026-05-11T17:21:04.759: Row 13: 3 root 20 0 0 0 0 S 0.0 0.0 0:34.67 ksoftirqd+.
2026-05-11T17:21:04.759: Row 14: 5 root 0 -20 0 0 0 S 0.0 0.0 0:00.00 kworker/0+.
2026-05-11T17:21:04.759: Row 15: 7 root 20 0 0 0 0 S 0.0 0.0 54:50.67 rcu_sched.
2026-05-11T17:21:04.760: Row 16: 8 root 20 0 0 0 0 S 0.0 0.0 0:00.00 rcu_bh.
2026-05-11T17:21:04.761: Row 17: 9 root rt 0 0 0 0 S 0.0 0.0 0:01.37 migration+.
2026-05-11T17:21:04.761: Row 18: 10 root rt 0 0 0 0 S 0.0 0.0 0:28.88 watchdog/0.
2026-05-11T17:21:04.761: Row 19: 11 root rt 0 0 0 0 S 0.0 0.0 0:25.03 watchdog/1.
2026-05-11T17:21:04.762: Row 20: 12 root rt 0 0 0 0 S 0.0 0.0 0:05.56 migration+.
2026-05-11T17:21:04.762: Row 21: 13 root 20 0 0 0 0 S 0.0 0.0 0:01.14 ksoftirqd+.
2026-05-11T17:21:04.762: Row 22: 14 root 20 0 0 0 0 S 0.0 0.0 0:00.00 kworker/1+.
2026-05-11T17:21:04.762: Row 23: 15 root 0 -20 0 0 0 S 0.0 0.0 0:00.00 kworker/1+.
2026-05-11T17:21:04.762: Row 24: 16 root rt 0 0 0 0 S 0.0 0.0 0:26.06 watchdog/2.
2026-05-11T17:21:04.832: SUC: 310ms Uptime 60 days, 1min 0.87, 5min 0.98, 15min 1.73, mem:132020360 total / 720992 free / 48454860 used / 82844512 cache / 82573088 avail
2026-05-11T17:21:04.843: ------ test: 2001
2026-05-11T17:21:04.843: CMD: top -b -E k -n 1
2026-05-11T17:21:04.843: EXE: 1/top -b -E k -n 1.
2026-05-11T17:21:05.006: Row 1: top - 17:21:05 up 159 days, 41 min, 2 users, load average: 7.65, 7.82, 6.78.
2026-05-11T17:21:05.006: Row 2: Tasks: 250 total, 1 running, 248 sleeping, 1 stopped, 0 zombie.
2026-05-11T17:21:05.006: Row 3: %Cpu(s): 4.3 us, 0.4 sy, 0.0 ni, 95.3 id, 0.0 wa, 0.0 hi, 0.0 si, 0.0 st.
2026-05-11T17:21:05.007: Row 4: KiB Mem : 32827244 total, 14281744 free, 2232772 used, 16312728 buff/cache.
2026-05-11T17:21:05.007: Row 5: KiB Swap: 16777212 total, 16776944 free, 268 used. 29769348 avail Mem .
2026-05-11T17:21:05.008: Row 6: .
2026-05-11T17:21:05.008: Row 7: PID USER PR NI VIRT RES SHR S %CPU %MEM TIME+ COMMAND.
2026-05-11T17:21:05.008: Row 8: 1579480 root 20 0 5054624 82520 27352 S 60.0 0.3 0:01.19 java.
2026-05-11T17:21:05.008: Row 9: 1 root 20 0 167696 13108 8168 S 0.0 0.0 8:33.99 systemd.
2026-05-11T17:21:05.009: Row 10: 2 root 20 0 0 0 0 S 0.0 0.0 0:03.76 kthreadd.
2026-05-11T17:21:05.009: Row 11: 3 root 0 -20 0 0 0 I 0.0 0.0 0:00.00 rcu_gp.
2026-05-11T17:21:05.009: Row 12: 4 root 0 -20 0 0 0 I 0.0 0.0 0:00.00 rcu_par+.
2026-05-11T17:21:05.009: Row 13: 5 root 0 -20 0 0 0 I 0.0 0.0 0:00.00 slub_fl+.
2026-05-11T17:21:05.009: Row 14: 6 root 0 -20 0 0 0 I 0.0 0.0 0:00.00 netns.
2026-05-11T17:21:05.009: Row 15: 8 root 0 -20 0 0 0 I 0.0 0.0 0:00.00 kworker+.
2026-05-11T17:21:05.010: Row 16: 10 root 0 -20 0 0 0 I 0.0 0.0 0:00.00 mm_perc+.
2026-05-11T17:21:05.010: Row 17: 11 root 20 0 0 0 0 S 0.0 0.0 0:00.00 rcu_tas+.
2026-05-11T17:21:05.010: Row 18: 12 root 20 0 0 0 0 S 0.0 0.0 0:00.00 rcu_tas+.
2026-05-11T17:21:05.010: Row 19: 13 root 20 0 0 0 0 S 0.0 0.0 0:00.33 ksoftir+.
2026-05-11T17:21:05.010: Row 20: 14 root 20 0 0 0 0 I 0.0 0.0 32:19.17 rcu_sch+.
2026-05-11T17:21:05.011: Row 21: 15 root rt 0 0 0 0 S 0.0 0.0 0:32.82 migrati+.
2026-05-11T17:21:05.011: Row 22: 16 root -51 0 0 0 0 S 0.0 0.0 0:00.00 idle_in+.
2026-05-11T17:21:05.011: Row 23: 18 root 20 0 0 0 0 S 0.0 0.0 0:00.00 cpuhp/0.
2026-05-11T17:21:05.011: Row 24: 19 root 20 0 0 0 0 S 0.0 0.0 0:00.00 cpuhp/1.
2026-05-11T17:21:05.023: SUC: 180ms Uptime 159 days, 1min 7.65, 5min 7.82, 15min 6.78, mem:32827244 total / 14281744 free / 2232772 used / 16312728 cache / 29769348 avail
2026-05-11T17:21:05.024: ------ test: 2002
2026-05-11T17:21:05.024: CMD: df -h
2026-05-11T17:21:05.024: EXE: 1/df -h.
2026-05-11T17:21:05.027: Row 1: Filesystem Size Used Avail Use% Mounted on.
2026-05-11T17:21:05.028: Row 2: tmpfs 3.2G 330M 2.9G 11% /run.
2026-05-11T17:21:05.028: Row 3: /dev/vda2 59G 51G 6.0G 90% /.
2026-05-11T17:21:05.028: Row 4: tmpfs 16G 0 16G 0% /dev/shm.
2026-05-11T17:21:05.028: Row 5: tmpfs 5.0M 0 5.0M 0% /run/lock.
2026-05-11T17:21:05.028: Row 6: tmpfs 3.2G 4.0K 3.2G 1% /run/user/0.
2026-05-11T17:21:05.031: SUC: 6ms Diskfree qa.headai.com: tmpfs:11%, /dev/vda2:90%, tmpfs:0%, tmpfs:0%, tmpfs:1%.
2026-05-11T17:21:05.031: ------ test: 2003
2026-05-11T17:21:05.031: CMD: ssh -o ServerAliveInterval=60 -o ServerAliveCountMax=3 root@megatron.headai.com "df -h"
2026-05-11T17:21:05.032: EXE: 1/ssh -o ServerAliveInterval=60 -o ServerAliveCountMax=3 root@megatron.headai.com "df -h".
2026-05-11T17:21:05.104: Row 1: Filesystem Size Used Avail Use% Mounted on.
2026-05-11T17:21:05.104: Row 2: udev 63G 4.0K 63G 1% /dev.
2026-05-11T17:21:05.104: Row 3: tmpfs 13G 5.5M 13G 1% /run.
2026-05-11T17:21:05.104: Row 4: /dev/vda1 757G 342G 385G 48% /.
2026-05-11T17:21:05.104: Row 5: none 4.0K 0 4.0K 0% /sys/fs/cgroup.
2026-05-11T17:21:05.104: Row 6: none 5.0M 0 5.0M 0% /run/lock.
2026-05-11T17:21:05.105: Row 7: none 63G 0 63G 0% /run/shm.
2026-05-11T17:21:05.106: Row 8: none 100M 0 100M 0% /run/user.
2026-05-11T17:21:05.106: Row 9: cgmfs 100K 0 100K 0% /run/cgmanager/fs.
2026-05-11T17:21:05.106: Row 10: /dev/vdb 985G 298G 637G 32% /opt/tomcat/webapps/ROOT/analysis.
2026-05-11T17:21:05.106: Row 11: tmpfs 100K 0 100K 0% /var/lib/lxd/shmounts.
2026-05-11T17:21:05.106: Row 12: tmpfs 100K 0 100K 0% /var/lib/lxd/devlxd.
2026-05-11T17:21:05.107: SUC: 76ms Diskfree megatron.headai.com: udev:1%, tmpfs:1%, /dev/vda1:48%, none:0%, none:0%, none:0%, none:0%, cgmfs:0%, /dev/vdb:32%, tmpfs:0%, tmpfs:0%.
2026-05-11T17:21:05.108: ------ test: 2004
2026-05-11T17:21:05.108: URL: https://headai.com
2026-05-11T17:21:05.631: SUC: 514ms, 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 […]" />, length 210717.
2026-05-11T17:21:05.633: ------ test: 2005
2026-05-11T17:21:05.892: SQL: show global status like 'threads_connected';.
2026-05-11T17:21:05.921: Results: value=171/200.
2026-05-11T17:21:05.926: SQL: value, lines: 1, ret=-2, 171.
2026-05-11T17:21:05.928: WRN: 294ms 171
2026-05-11T17:21:05.929: ------ test: 2006
2026-05-11T17:21:06.007: SQL: SHOW PROCESSLIST;.
2026-05-11T17:21:06.017: Row 1: 43580234,root,localhost:51802,competences,Query,45,Creating sort index,SELECT id,url,title,description,MATCH (title) AGAINST ('datatalous* data-analytiikka* data-analyysi*.
2026-05-11T17:21:06.017: Row 2: 43580362,root,localhost:52174,null,Query,0,starting,SHOW PROCESSLIST.
2026-05-11T17:21:06.017: SQL: COUNTNOTLINES:Command, lines: 2, ret=2, 2.
2026-05-11T17:21:06.018: SUC: 89ms 2
2026-05-11T17:21:06.019: checkJavaStack starting: 2007.
2026-05-11T17:21:06.121: ------ test: 2007
2026-05-11T17:21:06.121: EXE: 5/"jstack 40774 | 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-05-11T17:21:06.375: Row 1: "http-nio-127.0.0.1-8080-exec-678" #264129 daemon prio=5 os_prio=0 tid=0x00007f2c64018000 nid=0x18f9d runnable [0x00007f2cb5e39000].
2026-05-11T17:21:06.376: Row 2: java.lang.Thread.State: RUNNABLE.
2026-05-11T17:21:06.376: Row 3: at java.net.SocketInputStream.socketRead0(Native Method).
2026-05-11T17:21:06.376: Row 4: at java.net.SocketInputStream.socketRead(SocketInputStream.java:116).
2026-05-11T17:21:06.376: Row 5: at java.net.SocketInputStream.read(SocketInputStream.java:171).
2026-05-11T17:21:06.376: Row 6: at java.net.SocketInputStream.read(SocketInputStream.java:141).
2026-05-11T17:21:06.377: Row 7: at com.mysql.jdbc.util.ReadAheadInputStream.fill(ReadAheadInputStream.java:100).
2026-05-11T17:21:06.377: Row 8: at com.mysql.jdbc.util.ReadAheadInputStream.readFromUnderlyingStreamIfNecessary(ReadAheadInputStream.java:143).
2026-05-11T17:21:06.377: Row 9: at com.mysql.jdbc.util.ReadAheadInputStream.read(ReadAheadInputStream.java:173).
2026-05-11T17:21:06.377: Row 10: - locked <0x00000007750c1c20> (a com.mysql.jdbc.util.ReadAheadInputStream).
2026-05-11T17:21:06.377: Row 11: at com.mysql.jdbc.MysqlIO.readFully(MysqlIO.java:2911).
2026-05-11T17:21:06.377: Row 12: at com.mysql.jdbc.MysqlIO.reuseAndReadPacket(MysqlIO.java:3337).
2026-05-11T17:21:06.378: Row 13: at com.mysql.jdbc.MysqlIO.reuseAndReadPacket(MysqlIO.java:3327).
2026-05-11T17:21:06.378: Row 14: at com.mysql.jdbc.MysqlIO.checkErrorPacket(MysqlIO.java:3814).
2026-05-11T17:21:06.378: Row 15: at com.mysql.jdbc.MysqlIO.sendCommand(MysqlIO.java:2435).
2026-05-11T17:21:06.379: Row 16: at com.mysql.jdbc.MysqlIO.sqlQueryDirect(MysqlIO.java:2582).
2026-05-11T17:21:06.379: Row 17: at com.mysql.jdbc.ConnectionImpl.execSQL(ConnectionImpl.java:2530).
2026-05-11T17:21:06.379: Row 18: - locked <0x00000007750b8258> (a com.mysql.jdbc.JDBC4Connection).
2026-05-11T17:21:06.380: Row 19: at com.mysql.jdbc.PreparedStatement.executeInternal(PreparedStatement.java:1907).
2026-05-11T17:21:06.380: Row 20: - locked <0x00000007750b8258> (a com.mysql.jdbc.JDBC4Connection).
2026-05-11T17:21:06.380: Row 21: at com.mysql.jdbc.PreparedStatement.executeQuery(PreparedStatement.java:2030).
2026-05-11T17:21:06.380: Row 22: - locked <0x00000007750b8258> (a com.mysql.jdbc.JDBC4Connection).
2026-05-11T17:21:06.380: Row 23: at h2.BuildKnowledgeGraph_v7.build_a_map_on_fly_v4_1(BuildKnowledgeGraph_v7.java:740).
2026-05-11T17:21:06.380: Row 24: at h2.helpers.check_pending_works(helpers.java:5424).
2026-05-11T17:21:06.381: SUC: 2007, 260ms, lines: 26.
2026-05-11T17:21:06.383: checkJavaStack end: 2007, pid/ret=26.
2026-05-11T17:21:16.383: checkJavaStack starting: 2007.
2026-05-11T17:21:16.482: ------ test: 2007
2026-05-11T17:21:16.482: EXE: 5/"jstack 40774 | 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-05-11T17:21:16.695: SUC: 2007, 213ms, lines: 4096.
2026-05-11T17:21:16.696: checkJavaStack end: 2007, pid/ret=4096.
2026-05-11T17:21:26.696: checkJavaStack starting: 2007.
2026-05-11T17:21:26.796: ------ test: 2007
2026-05-11T17:21:26.797: EXE: 5/"jstack 40774 | 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-05-11T17:21:27.047: Row 1: "http-nio-127.0.0.1-8080-exec-694" #264145 daemon prio=5 os_prio=0 tid=0x00007f2c58024000 nid=0x19053 runnable [0x00007f2cb431b000].
2026-05-11T17:21:27.047: Row 2: java.lang.Thread.State: RUNNABLE.
2026-05-11T17:21:27.047: Row 3: at java.net.SocketInputStream.socketRead0(Native Method).
2026-05-11T17:21:27.047: Row 4: at java.net.SocketInputStream.socketRead(SocketInputStream.java:116).
2026-05-11T17:21:27.048: Row 5: at java.net.SocketInputStream.read(SocketInputStream.java:171).
2026-05-11T17:21:27.048: Row 6: at java.net.SocketInputStream.read(SocketInputStream.java:141).
2026-05-11T17:21:27.048: Row 7: at com.mysql.jdbc.util.ReadAheadInputStream.fill(ReadAheadInputStream.java:100).
2026-05-11T17:21:27.048: Row 8: at com.mysql.jdbc.util.ReadAheadInputStream.readFromUnderlyingStreamIfNecessary(ReadAheadInputStream.java:143).
2026-05-11T17:21:27.048: Row 9: at com.mysql.jdbc.util.ReadAheadInputStream.read(ReadAheadInputStream.java:173).
2026-05-11T17:21:27.048: Row 10: - locked <0x000000071cd66f20> (a com.mysql.jdbc.util.ReadAheadInputStream).
2026-05-11T17:21:27.048: Row 11: at com.mysql.jdbc.MysqlIO.readFully(MysqlIO.java:2911).
2026-05-11T17:21:27.049: Row 12: at com.mysql.jdbc.MysqlIO.reuseAndReadPacket(MysqlIO.java:3337).
2026-05-11T17:21:27.049: Row 13: at com.mysql.jdbc.MysqlIO.reuseAndReadPacket(MysqlIO.java:3327).
2026-05-11T17:21:27.049: Row 14: at com.mysql.jdbc.MysqlIO.checkErrorPacket(MysqlIO.java:3814).
2026-05-11T17:21:27.049: Row 15: at com.mysql.jdbc.MysqlIO.sendCommand(MysqlIO.java:2435).
2026-05-11T17:21:27.049: Row 16: at com.mysql.jdbc.MysqlIO.sqlQueryDirect(MysqlIO.java:2582).
2026-05-11T17:21:27.049: Row 17: at com.mysql.jdbc.ConnectionImpl.execSQL(ConnectionImpl.java:2530).
2026-05-11T17:21:27.049: Row 18: - locked <0x000000071cd5d558> (a com.mysql.jdbc.JDBC4Connection).
2026-05-11T17:21:27.049: Row 19: at com.mysql.jdbc.PreparedStatement.executeInternal(PreparedStatement.java:1907).
2026-05-11T17:21:27.049: Row 20: - locked <0x000000071cd5d558> (a com.mysql.jdbc.JDBC4Connection).
2026-05-11T17:21:27.050: Row 21: at com.mysql.jdbc.PreparedStatement.executeQuery(PreparedStatement.java:2030).
2026-05-11T17:21:27.050: Row 22: - locked <0x000000071cd5d558> (a com.mysql.jdbc.JDBC4Connection).
2026-05-11T17:21:27.050: Row 23: at h2.helpers.checkIfMultiple(helpers.java:9842).
2026-05-11T17:21:27.050: Row 24: at h2.helpers.delete_data_from_long_queries(helpers.java:9777).
2026-05-11T17:21:27.050: SUC: 2007, 254ms, lines: 26.
2026-05-11T17:21:27.051: checkJavaStack end: 2007, pid/ret=26.
2026-05-11T17:21:37.051: checkJavaStack starting: 2007.
2026-05-11T17:21:37.149: ------ test: 2007
2026-05-11T17:21:37.150: EXE: 5/"jstack 40774 | 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-05-11T17:21:37.400: Row 1: "http-nio-127.0.0.1-8080-exec-771" #384572 daemon prio=5 os_prio=0 tid=0x00007f2c5800e000 nid=0x1988d runnable [0x00007f2c4e5d1000].
2026-05-11T17:21:37.400: Row 2: java.lang.Thread.State: RUNNABLE.
2026-05-11T17:21:37.401: Row 3: at java.net.SocketInputStream.socketRead0(Native Method).
2026-05-11T17:21:37.401: Row 4: at java.net.SocketInputStream.socketRead(SocketInputStream.java:116).
2026-05-11T17:21:37.401: Row 5: at java.net.SocketInputStream.read(SocketInputStream.java:171).
2026-05-11T17:21:37.401: Row 6: at java.net.SocketInputStream.read(SocketInputStream.java:141).
2026-05-11T17:21:37.401: Row 7: at com.mysql.jdbc.util.ReadAheadInputStream.fill(ReadAheadInputStream.java:100).
2026-05-11T17:21:37.402: Row 8: at com.mysql.jdbc.util.ReadAheadInputStream.readFromUnderlyingStreamIfNecessary(ReadAheadInputStream.java:143).
2026-05-11T17:21:37.402: Row 9: at com.mysql.jdbc.util.ReadAheadInputStream.read(ReadAheadInputStream.java:173).
2026-05-11T17:21:37.402: Row 10: - locked <0x00000007aa419520> (a com.mysql.jdbc.util.ReadAheadInputStream).
2026-05-11T17:21:37.402: Row 11: at com.mysql.jdbc.MysqlIO.readFully(MysqlIO.java:2911).
2026-05-11T17:21:37.402: Row 12: at com.mysql.jdbc.MysqlIO.reuseAndReadPacket(MysqlIO.java:3337).
2026-05-11T17:21:37.402: Row 13: at com.mysql.jdbc.MysqlIO.reuseAndReadPacket(MysqlIO.java:3327).
2026-05-11T17:21:37.402: Row 14: at com.mysql.jdbc.MysqlIO.checkErrorPacket(MysqlIO.java:3814).
2026-05-11T17:21:37.402: Row 15: at com.mysql.jdbc.MysqlIO.sendCommand(MysqlIO.java:2435).
2026-05-11T17:21:37.402: Row 16: at com.mysql.jdbc.MysqlIO.sqlQueryDirect(MysqlIO.java:2582).
2026-05-11T17:21:37.402: Row 17: at com.mysql.jdbc.ConnectionImpl.execSQL(ConnectionImpl.java:2530).
2026-05-11T17:21:37.403: Row 18: - locked <0x00000007aa40fb38> (a com.mysql.jdbc.JDBC4Connection).
2026-05-11T17:21:37.403: Row 19: at com.mysql.jdbc.PreparedStatement.executeInternal(PreparedStatement.java:1907).
2026-05-11T17:21:37.403: Row 20: - locked <0x00000007aa40fb38> (a com.mysql.jdbc.JDBC4Connection).
2026-05-11T17:21:37.403: Row 21: at com.mysql.jdbc.PreparedStatement.executeQuery(PreparedStatement.java:2030).
2026-05-11T17:21:37.403: Row 22: - locked <0x00000007aa40fb38> (a com.mysql.jdbc.JDBC4Connection).
2026-05-11T17:21:37.403: Row 23: at h2.helpers.check_pending_works(helpers.java:4955).
2026-05-11T17:21:37.404: Row 24: at h1.mc_api.doPost(mc_api.java:4578).
2026-05-11T17:21:37.405: SUC: 2007, 255ms, lines: 25.
2026-05-11T17:21:37.406: checkJavaStack end: 2007, pid/ret=25.
2026-05-11T17:21:47.406: checkJavaStack starting: 2007.
2026-05-11T17:21:47.497: ------ test: 2007
2026-05-11T17:21:47.497: EXE: 5/"jstack 40774 | 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-05-11T17:21:47.784: Row 1: "http-nio-127.0.0.1-8080-exec-762" #311019 daemon prio=5 os_prio=0 tid=0x00007f2c5801e800 nid=0x41aa runnable [0x00007f2c46be9000].
2026-05-11T17:21:47.784: Row 2: java.lang.Thread.State: RUNNABLE.
2026-05-11T17:21:47.785: Row 3: at h2.skills_compass.get_suggestion_with_interests(skills_compass.java:1449).
2026-05-11T17:21:47.785: Row 4: at h2.skills_compass.get_suggestion(skills_compass.java:23).
2026-05-11T17:21:47.785: Row 5: at h2.Utils.doPost(Utils.java:867).
2026-05-11T17:21:47.785: Row 6: at h2.Utils.doGet(Utils.java:32).
2026-05-11T17:21:47.787: SUC: 2007, 290ms, lines: 6.
2026-05-11T17:21:47.787: checkJavaStack end: 2007, pid/ret=6.
2026-05-11T17:21:57.788: checkJavaStack starting: 2007.
2026-05-11T17:21:57.880: ------ test: 2007
2026-05-11T17:21:57.881: EXE: 5/"jstack 40774 | 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-05-11T17:21:58.087: Row 1: "http-nio-127.0.0.1-8080-exec-678" #264129 daemon prio=5 os_prio=0 tid=0x00007f2c64018000 nid=0x18f9d runnable [0x00007f2cb5e3a000].
2026-05-11T17:21:58.087: Row 2: java.lang.Thread.State: RUNNABLE.
2026-05-11T17:21:58.087: Row 3: at java.net.SocketInputStream.socketRead0(Native Method).
2026-05-11T17:21:58.087: Row 4: at java.net.SocketInputStream.socketRead(SocketInputStream.java:116).
2026-05-11T17:21:58.087: Row 5: at java.net.SocketInputStream.read(SocketInputStream.java:171).
2026-05-11T17:21:58.087: Row 6: at java.net.SocketInputStream.read(SocketInputStream.java:141).
2026-05-11T17:21:58.087: Row 7: at com.mysql.jdbc.util.ReadAheadInputStream.fill(ReadAheadInputStream.java:100).
2026-05-11T17:21:58.088: Row 8: at com.mysql.jdbc.util.ReadAheadInputStream.readFromUnderlyingStreamIfNecessary(ReadAheadInputStream.java:143).
2026-05-11T17:21:58.088: Row 9: at com.mysql.jdbc.util.ReadAheadInputStream.read(ReadAheadInputStream.java:173).
2026-05-11T17:21:58.088: Row 10: - locked <0x00000007aab9b5e0> (a com.mysql.jdbc.util.ReadAheadInputStream).
2026-05-11T17:21:58.088: Row 11: at com.mysql.jdbc.MysqlIO.readFully(MysqlIO.java:2911).
2026-05-11T17:21:58.088: Row 12: at com.mysql.jdbc.MysqlIO.reuseAndReadPacket(MysqlIO.java:3337).
2026-05-11T17:21:58.088: Row 13: at com.mysql.jdbc.MysqlIO.reuseAndReadPacket(MysqlIO.java:3327).
2026-05-11T17:21:58.088: Row 14: at com.mysql.jdbc.MysqlIO.checkErrorPacket(MysqlIO.java:3814).
2026-05-11T17:21:58.088: Row 15: at com.mysql.jdbc.MysqlIO.sendCommand(MysqlIO.java:2435).
2026-05-11T17:21:58.088: Row 16: at com.mysql.jdbc.MysqlIO.sqlQueryDirect(MysqlIO.java:2582).
2026-05-11T17:21:58.089: Row 17: at com.mysql.jdbc.ConnectionImpl.execSQL(ConnectionImpl.java:2530).
2026-05-11T17:21:58.089: Row 18: - locked <0x00000007aab91bf8> (a com.mysql.jdbc.JDBC4Connection).
2026-05-11T17:21:58.089: Row 19: at com.mysql.jdbc.PreparedStatement.executeInternal(PreparedStatement.java:1907).
2026-05-11T17:21:58.090: Row 20: - locked <0x00000007aab91bf8> (a com.mysql.jdbc.JDBC4Connection).
2026-05-11T17:21:58.090: Row 21: at com.mysql.jdbc.PreparedStatement.executeQuery(PreparedStatement.java:2030).
2026-05-11T17:21:58.090: Row 22: - locked <0x00000007aab91bf8> (a com.mysql.jdbc.JDBC4Connection).
2026-05-11T17:21:58.090: Row 23: at h2.helpers.check_pending_works(helpers.java:4983).
2026-05-11T17:21:58.090: Row 24: at h1.mc_api.doPost(mc_api.java:4578).
2026-05-11T17:21:58.091: SUC: 2007, 209ms, lines: 25.
2026-05-11T17:21:58.091: checkJavaStack end: 2007, pid/ret=25.
2026-05-11T17:22:08.091: checkJavaStack starting: 2007.
2026-05-11T17:22:08.185: ------ test: 2007
2026-05-11T17:22:08.186: EXE: 5/"jstack 40774 | 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-05-11T17:22:08.420: SUC: 2007, 235ms, lines: 4096.
2026-05-11T17:22:08.421: checkJavaStack end: 2007, pid/ret=4096.
2026-05-11T17:22:18.421: checkJavaStack starting: 2007.
2026-05-11T17:22:18.513: ------ test: 2007
2026-05-11T17:22:18.514: EXE: 5/"jstack 40774 | 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-05-11T17:22:18.742: Row 1: "http-nio-127.0.0.1-8080-exec-762" #311019 daemon prio=5 os_prio=0 tid=0x00007f2c5801e800 nid=0x41aa runnable [0x00007f2c46be9000].
2026-05-11T17:22:18.742: Row 2: java.lang.Thread.State: RUNNABLE.
2026-05-11T17:22:18.742: Row 3: at java.net.SocketInputStream.socketRead0(Native Method).
2026-05-11T17:22:18.742: Row 4: at java.net.SocketInputStream.socketRead(SocketInputStream.java:116).
2026-05-11T17:22:18.742: Row 5: at java.net.SocketInputStream.read(SocketInputStream.java:171).
2026-05-11T17:22:18.742: Row 6: at java.net.SocketInputStream.read(SocketInputStream.java:141).
2026-05-11T17:22:18.743: Row 7: at com.mysql.jdbc.util.ReadAheadInputStream.fill(ReadAheadInputStream.java:100).
2026-05-11T17:22:18.743: Row 8: at com.mysql.jdbc.util.ReadAheadInputStream.readFromUnderlyingStreamIfNecessary(ReadAheadInputStream.java:143).
2026-05-11T17:22:18.743: Row 9: at com.mysql.jdbc.util.ReadAheadInputStream.read(ReadAheadInputStream.java:173).
2026-05-11T17:22:18.743: Row 10: - locked <0x0000000768549f50> (a com.mysql.jdbc.util.ReadAheadInputStream).
2026-05-11T17:22:18.743: Row 11: at com.mysql.jdbc.MysqlIO.readFully(MysqlIO.java:2911).
2026-05-11T17:22:18.743: Row 12: at com.mysql.jdbc.MysqlIO.reuseAndReadPacket(MysqlIO.java:3337).
2026-05-11T17:22:18.743: Row 13: at com.mysql.jdbc.MysqlIO.reuseAndReadPacket(MysqlIO.java:3327).
2026-05-11T17:22:18.743: Row 14: at com.mysql.jdbc.MysqlIO.checkErrorPacket(MysqlIO.java:3814).
2026-05-11T17:22:18.743: Row 15: at com.mysql.jdbc.MysqlIO.sendCommand(MysqlIO.java:2435).
2026-05-11T17:22:18.743: Row 16: at com.mysql.jdbc.MysqlIO.sqlQueryDirect(MysqlIO.java:2582).
2026-05-11T17:22:18.744: Row 17: at com.mysql.jdbc.ConnectionImpl.execSQL(ConnectionImpl.java:2526).
2026-05-11T17:22:18.744: Row 18: - locked <0x0000000768540588> (a com.mysql.jdbc.JDBC4Connection).
2026-05-11T17:22:18.744: Row 19: at com.mysql.jdbc.ConnectionImpl.setAutoCommit(ConnectionImpl.java:4846).
2026-05-11T17:22:18.744: Row 20: - locked <0x0000000768540588> (a com.mysql.jdbc.JDBC4Connection).
2026-05-11T17:22:18.744: Row 21: at com.mysql.jdbc.ConnectionImpl.initializePropsFromServer(ConnectionImpl.java:3375).
2026-05-11T17:22:18.744: Row 22: at com.mysql.jdbc.ConnectionImpl.connectOneTryOnly(ConnectionImpl.java:2278).
2026-05-11T17:22:18.744: Row 23: at com.mysql.jdbc.ConnectionImpl.createNewIO(ConnectionImpl.java:2064).
2026-05-11T17:22:18.744: Row 24: - locked <0x0000000768540588> (a com.mysql.jdbc.JDBC4Connection).
2026-05-11T17:22:18.745: SUC: 2007, 231ms, lines: 39.
2026-05-11T17:22:18.745: checkJavaStack end: 2007, pid/ret=39.
2026-05-11T17:22:28.746: checkJavaStack starting: 2007.
2026-05-11T17:22:28.837: ------ test: 2007
2026-05-11T17:22:28.837: EXE: 5/"jstack 40774 | 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-05-11T17:22:29.129: SUC: 2007, 292ms, lines: 4096.
2026-05-11T17:22:29.130: checkJavaStack end: 2007, pid/ret=4096.
2026-05-11T17:22:39.130: checkJavaStack starting: 2007.
2026-05-11T17:22:39.221: ------ test: 2007
2026-05-11T17:22:39.221: EXE: 5/"jstack 40774 | 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-05-11T17:22:39.432: SUC: 2007, 210ms, lines: 4096.
2026-05-11T17:22:39.432: checkJavaStack end: 2007, pid/ret=4096.
2026-05-11T17:22:49.433: checkJavaStack starting: 2007.
2026-05-11T17:22:49.525: ------ test: 2007
2026-05-11T17:22:49.525: EXE: 5/"jstack 40774 | 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-05-11T17:22:49.829: SUC: 2007, 303ms, lines: 4096.
2026-05-11T17:22:49.829: checkJavaStack end: 2007, pid/ret=4096.
2026-05-11T17:22:59.830: checkJavaStack starting: 2007.
2026-05-11T17:22:59.922: ------ test: 2007
2026-05-11T17:22:59.922: EXE: 5/"jstack 40774 | 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-05-11T17:23:00.152: SUC: 2007, 230ms, lines: 4096.
2026-05-11T17:23:00.153: checkJavaStack end: 2007, pid/ret=4096.
2026-05-11T17:23:10.154: checkJavaStack starting: 2007.
2026-05-11T17:23:10.246: ------ test: 2007
2026-05-11T17:23:10.247: EXE: 5/"jstack 40774 | 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-05-11T17:23:10.456: SUC: 2007, 210ms, lines: 4096.
2026-05-11T17:23:10.457: checkJavaStack end: 2007, pid/ret=4096.
2026-05-11T17:23:20.457: checkJavaStack starting: 2007.
2026-05-11T17:23:20.551: ------ test: 2007
2026-05-11T17:23:20.552: EXE: 5/"jstack 40774 | 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-05-11T17:23:20.806: SUC: 2007, 255ms, lines: 4096.
2026-05-11T17:23:20.807: checkJavaStack end: 2007, pid/ret=4096.
2026-05-11T17:23:30.807: checkJavaStack starting: 2007.
2026-05-11T17:23:30.901: ------ test: 2007
2026-05-11T17:23:30.901: EXE: 5/"jstack 40774 | 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-05-11T17:23:31.143: Row 1: "http-nio-127.0.0.1-8080-exec-771" #384572 daemon prio=5 os_prio=0 tid=0x00007f2c5800e000 nid=0x1988d runnable [0x00007f2c4e5d1000].
2026-05-11T17:23:31.143: Row 2: java.lang.Thread.State: RUNNABLE.
2026-05-11T17:23:31.143: Row 3: at java.util.regex.Pattern$Start.match(Pattern.java:3475).
2026-05-11T17:23:31.144: Row 4: at java.util.regex.Matcher.search(Matcher.java:1248).
2026-05-11T17:23:31.144: Row 5: at java.util.regex.Matcher.find(Matcher.java:637).
2026-05-11T17:23:31.144: Row 6: at java.util.regex.Matcher.replaceAll(Matcher.java:951).
2026-05-11T17:23:31.144: Row 7: at java.lang.String.replaceAll(String.java:2223).
2026-05-11T17:23:31.144: Row 8: at org.apache.commons.lang3.StringUtils.replaceAll(StringUtils.java:5178).
2026-05-11T17:23:31.144: Row 9: at h1.mc_core.cleanData(mc_core.java:14080).
2026-05-11T17:23:31.144: Row 10: at h2.helpers.autocomplete(helpers.java:7047).
2026-05-11T17:23:31.144: Row 11: at h2.Utils.doPost(Utils.java:924).
2026-05-11T17:23:31.144: Row 12: at h2.Utils.doGet(Utils.java:32).
2026-05-11T17:23:31.146: SUC: 2007, 245ms, lines: 12.
2026-05-11T17:23:31.147: checkJavaStack end: 2007, pid/ret=12.
2026-05-11T17:23:41.147: checkJavaStack starting: 2007.
2026-05-11T17:23:41.244: ------ test: 2007
2026-05-11T17:23:41.244: EXE: 5/"jstack 40774 | 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-05-11T17:23:41.480: SUC: 2007, 236ms, lines: 4096.
2026-05-11T17:23:41.481: checkJavaStack end: 2007, pid/ret=4096.
2026-05-11T17:23:51.481: checkJavaStack starting: 2007.
2026-05-11T17:23:51.576: ------ test: 2007
2026-05-11T17:23:51.576: EXE: 5/"jstack 40774 | 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-05-11T17:23:51.792: SUC: 2007, 216ms, lines: 4096.
2026-05-11T17:23:51.793: checkJavaStack end: 2007, pid/ret=4096.
2026-05-11T17:24:01.793: checkJavaStack starting: 2007.
2026-05-11T17:24:01.888: ------ test: 2007
2026-05-11T17:24:01.888: EXE: 5/"jstack 40774 | 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-05-11T17:24:02.145: SUC: 2007, 257ms, lines: 4096.
2026-05-11T17:24:02.146: checkJavaStack end: 2007, pid/ret=4096.
2026-05-11T17:24:12.146: checkJavaStack starting: 2007.
2026-05-11T17:24:12.250: ------ test: 2007
2026-05-11T17:24:12.251: EXE: 5/"jstack 40774 | 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-05-11T17:24:12.467: SUC: 2007, 213ms, lines: 4096.
2026-05-11T17:24:12.467: checkJavaStack end: 2007, pid/ret=4096.
2026-05-11T17:24:22.468: checkJavaStack starting: 2007.
2026-05-11T17:24:22.562: ------ test: 2007
2026-05-11T17:24:22.563: EXE: 5/"jstack 40774 | 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-05-11T17:24:22.794: SUC: 2007, 231ms, lines: 4096.
2026-05-11T17:24:22.795: checkJavaStack end: 2007, pid/ret=4096.
2026-05-11T17:24:32.795: checkJavaStack starting: 2007.
2026-05-11T17:24:32.891: ------ test: 2007
2026-05-11T17:24:32.891: EXE: 5/"jstack 40774 | 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-05-11T17:24:33.093: SUC: 2007, 201ms, lines: 4096.
2026-05-11T17:24:33.094: checkJavaStack end: 2007, pid/ret=4096.
2026-05-11T17:24:43.094: checkJavaStack starting: 2007.
2026-05-11T17:24:43.185: ------ test: 2007
2026-05-11T17:24:43.185: EXE: 5/"jstack 40774 | 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-05-11T17:24:43.451: SUC: 2007, 265ms, lines: 4096.
2026-05-11T17:24:43.452: checkJavaStack end: 2007, pid/ret=4096.
2026-05-11T17:24:53.452: checkJavaStack starting: 2007.
2026-05-11T17:24:53.548: ------ test: 2007
2026-05-11T17:24:53.550: EXE: 5/"jstack 40774 | 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-05-11T17:24:53.769: SUC: 2007, 218ms, lines: 4096.
2026-05-11T17:24:53.770: checkJavaStack end: 2007, pid/ret=4096.
2026-05-11T17:25:03.770: checkJavaStack starting: 2007.
2026-05-11T17:25:03.863: ------ test: 2007
2026-05-11T17:25:03.864: EXE: 5/"jstack 40774 | 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-05-11T17:25:04.082: SUC: 2007, 218ms, lines: 4096.
2026-05-11T17:25:04.083: checkJavaStack end: 2007, pid/ret=4096.
2026-05-11T17:25:14.083: checkJavaStack starting: 2007.
2026-05-11T17:25:14.180: ------ test: 2007
2026-05-11T17:25:14.181: EXE: 5/"jstack 40774 | 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-05-11T17:25:14.417: SUC: 2007, 236ms, lines: 4096.
2026-05-11T17:25:14.418: checkJavaStack end: 2007, pid/ret=4096.
2026-05-11T17:25:24.418: checkJavaStack starting: 2007.
2026-05-11T17:25:24.513: ------ test: 2007
2026-05-11T17:25:24.513: EXE: 5/"jstack 40774 | 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-05-11T17:25:24.778: SUC: 2007, 265ms, lines: 4096.
2026-05-11T17:25:24.779: checkJavaStack end: 2007, pid/ret=4096.
2026-05-11T17:25:34.779: checkJavaStack starting: 2007.
2026-05-11T17:25:34.873: ------ test: 2007
2026-05-11T17:25:34.873: EXE: 5/"jstack 40774 | 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-05-11T17:25:35.114: SUC: 2007, 240ms, lines: 4096.
2026-05-11T17:25:35.115: checkJavaStack end: 2007, pid/ret=4096.
2026-05-11T17:25:45.115: checkJavaStack starting: 2007.
2026-05-11T17:25:45.211: ------ test: 2007
2026-05-11T17:25:45.211: EXE: 5/"jstack 40774 | 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-05-11T17:25:45.421: SUC: 2007, 210ms, lines: 4096.
2026-05-11T17:25:45.422: checkJavaStack end: 2007, pid/ret=4096.
2026-05-11T17:25:55.422: checkJavaStack starting: 2007.
2026-05-11T17:25:55.517: ------ test: 2007
2026-05-11T17:25:55.518: EXE: 5/"jstack 40774 | 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-05-11T17:25:55.786: SUC: 2007, 267ms, lines: 4096.
2026-05-11T17:25:55.786: checkJavaStack end: 2007, pid/ret=4096.
2026-05-11T17:26:05.787: checkJavaStack starting: 2007.
2026-05-11T17:26:05.883: ------ test: 2007
2026-05-11T17:26:05.884: EXE: 5/"jstack 40774 | 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-05-11T17:26:06.101: SUC: 2007, 217ms, lines: 4096.
2026-05-11T17:26:06.103: checkJavaStack end: 2007, pid/ret=4096.
2026-05-11T17:26:16.103: checkJavaStack starting: 2007.
2026-05-11T17:26:16.200: ------ test: 2007
2026-05-11T17:26:16.200: EXE: 5/"jstack 40774 | 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-05-11T17:26:16.423: SUC: 2007, 223ms, lines: 4096.
2026-05-11T17:26:16.425: checkJavaStack end: 2007, pid/ret=4096.
2026-05-11T17:26:26.425: checkJavaStack starting: 2007.
2026-05-11T17:26:26.519: ------ test: 2007
2026-05-11T17:26:26.519: EXE: 5/"jstack 40774 | 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-05-11T17:26:26.755: SUC: 2007, 236ms, lines: 4096.
2026-05-11T17:26:26.755: checkJavaStack end: 2007, pid/ret=4096.
2026-05-11T17:26:26.758: ------ test: end, 322244ms. Score 39/39.
2026-05-11T17:26:26.760: Test duration: 5m22s.