2024-12-18T00:21:05.161: current working dir: /root/qa/mctester.
2024-12-18T00:21:05.265: current logging dir: ../mctester/www.
2024-12-18T00:21:05.266: ---------------------------------------------------------------
2024-12-18T00:21:05.266: mctester entry: 2024-12-18T00:21:05.266 mode=50
2024-12-18T00:21:05.266: ---------------------------------------------------------------
2024-12-18T00:21:05.380: EXE: 1/git describe --tags.
2024-12-18T00:21:05.393: Row 1: v1.1-201-g90c2042.
2024-12-18T00:21:05.394: EXE: 1/git rev-list --count HEAD.
2024-12-18T00:21:05.399: Row 1: 258.
2024-12-18T00:21:05.402: EXE: 1/git rev-parse HEAD.
2024-12-18T00:21:05.403: Row 1: 90c2042af9ce0b055e0a62dafd703b27e21c4128.
2024-12-18T00:21:05.404: mctester: v1.1.258 (90c2042af9ce0b055e0a62dafd703b27e21c4128).
2024-12-18T00:21:05.404: Entering into a performance tests mode.
2024-12-18T00:21:05.404: ------ test: start, 2024-12-18T00:21:05.404
2024-12-18T00:21:05.407: ------ test: 2000
2024-12-18T00:21:05.407: CMD: ssh -o ServerAliveInterval=60 -o ServerAliveCountMax=3 root@megatron.headai.com "top -b -n 1"
2024-12-18T00:21:05.407: EXE: 1/ssh -o ServerAliveInterval=60 -o ServerAliveCountMax=3 root@megatron.headai.com "top -b -n 1".
2024-12-18T00:21:35.739: Row 1: top - 00:21:35 up 91 days, 16:48, 1 user, load average: 0.32, 0.11, 0.06.
2024-12-18T00:21:35.740: Row 2: Tasks: 370 total, 1 running, 369 sleeping, 0 stopped, 0 zombie.
2024-12-18T00:21:35.740: 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-18T00:21:35.741: Row 4: KiB Mem : 13202036+total, 5028632 free, 54438308 used, 72553432 buff/cache.
2024-12-18T00:21:35.741: Row 5: KiB Swap: 0 total, 0 free, 0 used. 75992144 avail Mem .
2024-12-18T00:21:35.741: Row 6: .
2024-12-18T00:21:35.741: Row 7: PID USER PR NI VIRT RES SHR S %CPU %MEM TIME+ COMMAND.
2024-12-18T00:21:35.745: Row 8: 1978 mysql 20 0 99.646g 0.048t 1.146g S 106.2 38.7 13677:31 mysqld.
2024-12-18T00:21:35.745: Row 9: 1 root 20 0 43660 3844 2560 S 0.0 0.0 3:24.49 init.
2024-12-18T00:21:35.745: Row 10: 2 root 20 0 0 0 0 S 0.0 0.0 0:01.23 kthreadd.
2024-12-18T00:21:35.745: Row 11: 3 root 20 0 0 0 0 S 0.0 0.0 4:29.36 ksoftirqd+.
2024-12-18T00:21:35.745: Row 12: 5 root 0 -20 0 0 0 S 0.0 0.0 0:00.00 kworker/0+.
2024-12-18T00:21:35.746: Row 13: 7 root 20 0 0 0 0 S 0.0 0.0 30:00.48 rcu_sched.
2024-12-18T00:21:35.747: Row 14: 8 root 20 0 0 0 0 S 0.0 0.0 0:00.00 rcu_bh.
2024-12-18T00:21:35.751: Row 15: 9 root rt 0 0 0 0 S 0.0 0.0 0:05.49 migration+.
2024-12-18T00:21:35.751: Row 16: 10 root rt 0 0 0 0 S 0.0 0.0 0:22.10 watchdog/0.
2024-12-18T00:21:35.751: Row 17: 11 root rt 0 0 0 0 S 0.0 0.0 0:20.18 watchdog/1.
2024-12-18T00:21:35.751: Row 18: 12 root rt 0 0 0 0 S 0.0 0.0 0:10.77 migration+.
2024-12-18T00:21:35.751: Row 19: 13 root 20 0 0 0 0 S 0.0 0.0 0:01.78 ksoftirqd+.
2024-12-18T00:21:35.752: Row 20: 15 root 0 -20 0 0 0 S 0.0 0.0 0:00.00 kworker/1+.
2024-12-18T00:21:35.752: Row 21: 16 root rt 0 0 0 0 S 0.0 0.0 0:20.62 watchdog/2.
2024-12-18T00:21:35.752: Row 22: 17 root rt 0 0 0 0 S 0.0 0.0 0:04.58 migration+.
2024-12-18T00:21:35.752: Row 23: 18 root 20 0 0 0 0 S 0.0 0.0 0:01.44 ksoftirqd+.
2024-12-18T00:21:35.752: Row 24: 20 root 0 -20 0 0 0 S 0.0 0.0 0:00.00 kworker/2+.
2024-12-18T00:21:35.821: SUC: 30414ms Uptime 91 days, 1min 0.32, 5min 0.11, 15min 0.06, mem:132020360 total / 5028632 free / 54438308 used / 72553432 cache / 75992144 avail
2024-12-18T00:21:35.834: ------ test: 2001
2024-12-18T00:21:35.834: CMD: top -b -E k -n 1
2024-12-18T00:21:35.834: EXE: 1/top -b -E k -n 1.
2024-12-18T00:21:35.990: Row 1: top - 00:21:35 up 36 days, 7:03, 0 users, load average: 0.20, 0.08, 0.02.
2024-12-18T00:21:35.991: Row 2: Tasks: 110 total, 1 running, 108 sleeping, 0 stopped, 1 zombie.
2024-12-18T00:21:35.991: Row 3: %Cpu(s): 0.0 us, 0.0 sy, 0.0 ni,100.0 id, 0.0 wa, 0.0 hi, 0.0 si, 0.0 st.
2024-12-18T00:21:35.991: Row 4: KiB Mem : 1907632 total, 458528 free, 406188 used, 1042916 buff/cache.
2024-12-18T00:21:35.991: Row 5: KiB Swap: 0 total, 0 free, 0 used. 1284652 avail Mem .
2024-12-18T00:21:35.991: Row 6: .
2024-12-18T00:21:35.991: Row 7: PID USER PR NI VIRT RES SHR S %CPU %MEM TIME+ COMMAND.
2024-12-18T00:21:35.991: Row 8: 1 root 20 0 167784 11968 7084 S 0.0 0.6 0:57.31 systemd.
2024-12-18T00:21:35.992: Row 9: 2 root 20 0 0 0 0 S 0.0 0.0 0:00.09 kthreadd.
2024-12-18T00:21:35.992: Row 10: 3 root 0 -20 0 0 0 I 0.0 0.0 0:00.00 rcu_gp.
2024-12-18T00:21:35.992: Row 11: 4 root 0 -20 0 0 0 I 0.0 0.0 0:00.00 rcu_par+.
2024-12-18T00:21:35.992: Row 12: 5 root 0 -20 0 0 0 I 0.0 0.0 0:00.00 slub_fl+.
2024-12-18T00:21:35.992: Row 13: 6 root 0 -20 0 0 0 I 0.0 0.0 0:00.00 netns.
2024-12-18T00:21:35.992: Row 14: 8 root 0 -20 0 0 0 I 0.0 0.0 0:00.00 kworker+.
2024-12-18T00:21:35.992: Row 15: 10 root 0 -20 0 0 0 I 0.0 0.0 0:00.00 mm_perc+.
2024-12-18T00:21:35.993: Row 16: 11 root 20 0 0 0 0 S 0.0 0.0 0:00.00 rcu_tas+.
2024-12-18T00:21:35.993: Row 17: 12 root 20 0 0 0 0 S 0.0 0.0 0:00.00 rcu_tas+.
2024-12-18T00:21:35.993: Row 18: 13 root 20 0 0 0 0 S 0.0 0.0 0:31.09 ksoftir+.
2024-12-18T00:21:35.993: Row 19: 14 root 20 0 0 0 0 I 0.0 0.0 0:53.58 rcu_sch+.
2024-12-18T00:21:35.999: Row 20: 15 root rt 0 0 0 0 S 0.0 0.0 0:07.90 migrati+.
2024-12-18T00:21:35.999: Row 21: 16 root -51 0 0 0 0 S 0.0 0.0 0:00.00 idle_in+.
2024-12-18T00:21:35.999: Row 22: 18 root 20 0 0 0 0 S 0.0 0.0 0:00.00 cpuhp/0.
2024-12-18T00:21:35.999: Row 23: 19 root 20 0 0 0 0 S 0.0 0.0 0:00.00 kdevtmp+.
2024-12-18T00:21:35.999: Row 24: 20 root 0 -20 0 0 0 I 0.0 0.0 0:00.00 inet_fr+.
2024-12-18T00:21:36.009: SUC: 175ms Uptime 36 days, 1min 0.20, 5min 0.08, 15min 0.02, mem:1907632 total / 458528 free / 406188 used / 1042916 cache / 1284652 avail
2024-12-18T00:21:36.010: ------ test: 2002
2024-12-18T00:21:36.010: CMD: df -h
2024-12-18T00:21:36.010: EXE: 1/df -h.
2024-12-18T00:21:36.012: Row 1: Filesystem Size Used Avail Use% Mounted on.
2024-12-18T00:21:36.013: Row 2: tmpfs 187M 20M 167M 11% /run.
2024-12-18T00:21:36.013: Row 3: /dev/vda2 20G 14G 5.2G 73% /.
2024-12-18T00:21:36.013: Row 4: tmpfs 932M 0 932M 0% /dev/shm.
2024-12-18T00:21:36.013: Row 5: tmpfs 5.0M 0 5.0M 0% /run/lock.
2024-12-18T00:21:36.015: SUC: 5ms Diskfree qa.headai.com: tmpfs:11%, /dev/vda2:73%, tmpfs:0%, tmpfs:0%.
2024-12-18T00:21:36.016: ------ test: 2003
2024-12-18T00:21:36.016: CMD: ssh -o ServerAliveInterval=60 -o ServerAliveCountMax=3 root@megatron.headai.com "df -h"
2024-12-18T00:21:36.016: EXE: 1/ssh -o ServerAliveInterval=60 -o ServerAliveCountMax=3 root@megatron.headai.com "df -h".
2024-12-18T00:22:06.198: Row 1: Filesystem Size Used Avail Use% Mounted on.
2024-12-18T00:22:06.198: Row 2: udev 63G 4.0K 63G 1% /dev.
2024-12-18T00:22:06.199: Row 3: tmpfs 13G 544M 13G 5% /run.
2024-12-18T00:22:06.199: Row 4: /dev/vda1 757G 512G 214G 71% /.
2024-12-18T00:22:06.199: Row 5: none 4.0K 0 4.0K 0% /sys/fs/cgroup.
2024-12-18T00:22:06.199: Row 6: none 5.0M 0 5.0M 0% /run/lock.
2024-12-18T00:22:06.199: Row 7: cgmfs 100K 0 100K 0% /run/cgmanager/fs.
2024-12-18T00:22:06.199: Row 8: none 63G 0 63G 0% /run/shm.
2024-12-18T00:22:06.199: Row 9: none 100M 0 100M 0% /run/user.
2024-12-18T00:22:06.199: Row 10: /dev/vdb 985G 230G 705G 25% /opt/tomcat/webapps/ROOT/analysis.
2024-12-18T00:22:06.200: Row 11: tmpfs 100K 0 100K 0% /var/lib/lxd/shmounts.
2024-12-18T00:22:06.200: Row 12: tmpfs 100K 0 100K 0% /var/lib/lxd/devlxd.
2024-12-18T00:22:06.200: Row 13: tmpfs 13G 0 13G 0% /run/user/0.
2024-12-18T00:22:06.203: SUC: 30187ms 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-18T00:22:06.209: ------ test: 2004
2024-12-18T00:22:06.210: URL: https://headai.com
2024-12-18T00:22:06.869: SUC: 658ms, Trusted by 100+ customers and partners globally, length 185391.
2024-12-18T00:22:06.874: ------ test: 2005
2024-12-18T00:22:07.356: SQL: show global status like 'threads_connected';.
2024-12-18T00:22:07.372: Results: value=2/200.
2024-12-18T00:22:07.372: SQL: value, lines: 1, ret=1, 2.
2024-12-18T00:22:07.377: SUC: 503ms 2
2024-12-18T00:22:07.379: ------ test: 2006
2024-12-18T00:22:07.478: SQL: SHOW PROCESSLIST;.
2024-12-18T00:22:07.480: Row 1: 25521655,root,localhost:34308,null,Query,0,starting,SHOW PROCESSLIST.
2024-12-18T00:22:07.480: SQL: COUNTNOTLINES:Command, lines: 1, ret=1, 1.
2024-12-18T00:22:07.482: SUC: 103ms 1
2024-12-18T00:22:07.485: checkJavaStack starting: 2007.
2024-12-18T00:22:37.693: ------ test: 2007
2024-12-18T00:22:37.693: EXE: 5/"jstack 65414 | 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-18T00:23:08.006: Row 1: "https-openssl-nio-443-exec-5" #36 daemon prio=5 os_prio=0 tid=0x00007f0fec70d000 nid=0x1008a runnable [0x00007f0f85bc8000].
2024-12-18T00:23:08.006: Row 2: java.lang.Thread.State: RUNNABLE.
2024-12-18T00:23:08.007: Row 3: at java.net.SocketInputStream.socketRead0(Native Method).
2024-12-18T00:23:08.007: Row 4: at java.net.SocketInputStream.socketRead(SocketInputStream.java:116).
2024-12-18T00:23:08.007: Row 5: at java.net.SocketInputStream.read(SocketInputStream.java:171).
2024-12-18T00:23:08.007: Row 6: at java.net.SocketInputStream.read(SocketInputStream.java:141).
2024-12-18T00:23:08.007: Row 7: at com.mysql.jdbc.util.ReadAheadInputStream.fill(ReadAheadInputStream.java:100).
2024-12-18T00:23:08.007: Row 8: at com.mysql.jdbc.util.ReadAheadInputStream.readFromUnderlyingStreamIfNecessary(ReadAheadInputStream.java:143).
2024-12-18T00:23:08.007: Row 9: at com.mysql.jdbc.util.ReadAheadInputStream.read(ReadAheadInputStream.java:173).
2024-12-18T00:23:08.008: Row 10: - locked <0x00000007237fe488> (a com.mysql.jdbc.util.ReadAheadInputStream).
2024-12-18T00:23:08.008: Row 11: at com.mysql.jdbc.MysqlIO.readFully(MysqlIO.java:2911).
2024-12-18T00:23:08.008: Row 12: at com.mysql.jdbc.MysqlIO.reuseAndReadPacket(MysqlIO.java:3337).
2024-12-18T00:23:08.008: Row 13: at com.mysql.jdbc.MysqlIO.reuseAndReadPacket(MysqlIO.java:3327).
2024-12-18T00:23:08.008: Row 14: at com.mysql.jdbc.MysqlIO.checkErrorPacket(MysqlIO.java:3814).
2024-12-18T00:23:08.008: Row 15: at com.mysql.jdbc.MysqlIO.sendCommand(MysqlIO.java:2435).
2024-12-18T00:23:08.008: Row 16: at com.mysql.jdbc.MysqlIO.sqlQueryDirect(MysqlIO.java:2582).
2024-12-18T00:23:08.008: Row 17: at com.mysql.jdbc.ConnectionImpl.execSQL(ConnectionImpl.java:2530).
2024-12-18T00:23:08.009: Row 18: - locked <0x00000007237f4a10> (a com.mysql.jdbc.JDBC4Connection).
2024-12-18T00:23:08.009: Row 19: at com.mysql.jdbc.PreparedStatement.executeInternal(PreparedStatement.java:1907).
2024-12-18T00:23:08.009: Row 20: - locked <0x00000007237f4a10> (a com.mysql.jdbc.JDBC4Connection).
2024-12-18T00:23:08.009: Row 21: at com.mysql.jdbc.PreparedStatement.executeQuery(PreparedStatement.java:2030).
2024-12-18T00:23:08.021: Row 22: - locked <0x00000007237f4a10> (a com.mysql.jdbc.JDBC4Connection).
2024-12-18T00:23:08.022: Row 23: at h2.BuildKnowledgeGraph_v5.build_a_map_on_fly_v4_1(BuildKnowledgeGraph_v5.java:1543).
2024-12-18T00:23:08.022: Row 24: at h2.SkillsForecast_methods.build_a_map_on_fly_v4(SkillsForecast_methods.java:5221).
2024-12-18T00:23:08.023: SUC: 2007, 30330ms, lines: 27.
2024-12-18T00:23:08.024: checkJavaStack end: 2007, pid/ret=27.
2024-12-18T00:23:18.024: checkJavaStack starting: 2007.
2024-12-18T00:23:48.526: ------ test: 2007
2024-12-18T00:23:48.526: EXE: 5/"jstack 65414 | 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-18T00:24:18.903: Row 1: "https-openssl-nio-443-exec-5" #36 daemon prio=5 os_prio=0 tid=0x00007f0fec70d000 nid=0x1008a runnable [0x00007f0f85bc8000].
2024-12-18T00:24:19.394: Row 2: java.lang.Thread.State: RUNNABLE.
2024-12-18T00:24:19.394: Row 3: at java.lang.String.indexOf(String.java:1769).
2024-12-18T00:24:19.394: Row 4: at java.lang.String.indexOf(String.java:1718).
2024-12-18T00:24:19.394: Row 5: at org.apache.commons.lang3.StringUtils.replace(StringUtils.java:5362).
2024-12-18T00:24:19.395: Row 6: at org.apache.commons.lang3.StringUtils.replace(StringUtils.java:5318).
2024-12-18T00:24:19.395: Row 7: at org.apache.commons.lang3.StringUtils.replace(StringUtils.java:5258).
2024-12-18T00:24:19.395: Row 8: at h2.core2021.semanticSimilarity_v3(core2021.java:1403).
2024-12-18T00:24:19.395: Row 9: at h2.core2021.text2keywords_v3(core2021.java:727).
2024-12-18T00:24:19.395: Row 10: at h2.BuildKnowledgeGraph_v5.build_a_map_on_fly_v4_1(BuildKnowledgeGraph_v5.java:1727).
2024-12-18T00:24:19.396: Row 11: at h2.SkillsForecast_methods.build_a_map_on_fly_v4(SkillsForecast_methods.java:5221).
2024-12-18T00:24:19.396: Row 12: at h2.helpers.check_pending_works(helpers.java:5270).
2024-12-18T00:24:19.397: Row 13: at h1.mc_api.doPost(mc_api.java:4576).
2024-12-18T00:24:19.397: Row 14: at h1.mc_api.doGet(mc_api.java:38).
2024-12-18T00:24:19.397: SUC: 2007, 30871ms, lines: 14.
2024-12-18T00:24:19.397: checkJavaStack end: 2007, pid/ret=14.
2024-12-18T00:24:29.398: checkJavaStack starting: 2007.
2024-12-18T00:24:59.762: ------ test: 2007
2024-12-18T00:24:59.762: EXE: 5/"jstack 65414 | 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-18T00:25:30.098: Row 1: "https-openssl-nio-443-exec-5" #36 daemon prio=5 os_prio=0 tid=0x00007f0fec70d000 nid=0x1008a runnable [0x00007f0f85bc8000].
2024-12-18T00:25:30.098: Row 2: java.lang.Thread.State: RUNNABLE.
2024-12-18T00:25:30.098: Row 3: at h1.body.wordToBody(body.java:286).
2024-12-18T00:25:30.098: Row 4: at h2.core2021.semanticSimilarity_v3(core2021.java:1369).
2024-12-18T00:25:30.099: Row 5: at h2.core2021.text2keywords_v3(core2021.java:727).
2024-12-18T00:25:30.099: Row 6: at h2.BuildKnowledgeGraph_v5.build_a_map_on_fly_v4_1(BuildKnowledgeGraph_v5.java:1727).
2024-12-18T00:25:30.099: Row 7: at h2.SkillsForecast_methods.build_a_map_on_fly_v4(SkillsForecast_methods.java:5221).
2024-12-18T00:25:30.099: Row 8: at h2.helpers.check_pending_works(helpers.java:5270).
2024-12-18T00:25:30.099: Row 9: at h1.mc_api.doPost(mc_api.java:4576).
2024-12-18T00:25:30.099: Row 10: at h1.mc_api.doGet(mc_api.java:38).
2024-12-18T00:25:30.104: SUC: 2007, 30342ms, lines: 10.
2024-12-18T00:25:30.109: checkJavaStack end: 2007, pid/ret=10.
2024-12-18T00:25:40.110: checkJavaStack starting: 2007.
2024-12-18T00:26:10.308: ------ test: 2007
2024-12-18T00:26:10.308: EXE: 5/"jstack 65414 | 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-18T00:26:40.651: SUC: 2007, 30343ms, lines: 4096.
2024-12-18T00:26:40.652: checkJavaStack end: 2007, pid/ret=4096.
2024-12-18T00:26:50.652: checkJavaStack starting: 2007.
2024-12-18T00:27:20.851: ------ test: 2007
2024-12-18T00:27:20.851: EXE: 5/"jstack 65414 | 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-18T00:27:51.172: SUC: 2007, 30321ms, lines: 4096.
2024-12-18T00:27:51.173: checkJavaStack end: 2007, pid/ret=4096.
2024-12-18T00:28:01.173: checkJavaStack starting: 2007.
2024-12-18T00:28:31.372: ------ test: 2007
2024-12-18T00:28:31.372: EXE: 5/"jstack 65414 | 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-18T00:29:01.672: SUC: 2007, 30300ms, lines: 4096.
2024-12-18T00:29:01.673: checkJavaStack end: 2007, pid/ret=4096.
2024-12-18T00:29:11.673: checkJavaStack starting: 2007.
2024-12-18T00:29:41.851: ------ test: 2007
2024-12-18T00:29:41.852: EXE: 5/"jstack 65414 | 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-18T00:30:12.183: SUC: 2007, 30331ms, lines: 4096.
2024-12-18T00:30:12.183: checkJavaStack end: 2007, pid/ret=4096.
2024-12-18T00:30:22.184: checkJavaStack starting: 2007.
2024-12-18T00:30:52.374: ------ test: 2007
2024-12-18T00:30:52.375: EXE: 5/"jstack 65414 | 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-18T00:31:22.702: SUC: 2007, 30327ms, lines: 4096.
2024-12-18T00:31:22.718: checkJavaStack end: 2007, pid/ret=4096.
2024-12-18T00:31:32.718: checkJavaStack starting: 2007.
2024-12-18T00:32:02.914: ------ test: 2007
2024-12-18T00:32:02.914: EXE: 5/"jstack 65414 | 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-18T00:32:33.269: SUC: 2007, 30355ms, lines: 4096.
2024-12-18T00:32:33.269: checkJavaStack end: 2007, pid/ret=4096.
2024-12-18T00:32:43.270: checkJavaStack starting: 2007.
2024-12-18T00:33:13.467: ------ test: 2007
2024-12-18T00:33:13.468: EXE: 5/"jstack 65414 | 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-18T00:33:43.779: SUC: 2007, 30311ms, lines: 4096.
2024-12-18T00:33:43.779: checkJavaStack end: 2007, pid/ret=4096.
2024-12-18T00:33:53.780: checkJavaStack starting: 2007.
2024-12-18T00:34:23.976: ------ test: 2007
2024-12-18T00:34:23.976: EXE: 5/"jstack 65414 | 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-18T00:34:54.291: SUC: 2007, 30315ms, lines: 4096.
2024-12-18T00:34:54.291: checkJavaStack end: 2007, pid/ret=4096.
2024-12-18T00:35:04.292: checkJavaStack starting: 2007.
2024-12-18T00:35:34.488: ------ test: 2007
2024-12-18T00:35:34.488: EXE: 5/"jstack 65414 | 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-18T00:36:04.835: SUC: 2007, 30347ms, lines: 4096.
2024-12-18T00:36:04.836: checkJavaStack end: 2007, pid/ret=4096.
2024-12-18T00:36:14.836: checkJavaStack starting: 2007.
2024-12-18T00:36:45.023: ------ test: 2007
2024-12-18T00:36:45.023: EXE: 5/"jstack 65414 | 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-18T00:37:15.324: SUC: 2007, 30301ms, lines: 4096.
2024-12-18T00:37:15.324: checkJavaStack end: 2007, pid/ret=4096.
2024-12-18T00:37:25.324: checkJavaStack starting: 2007.
2024-12-18T00:37:55.510: ------ test: 2007
2024-12-18T00:37:55.510: EXE: 5/"jstack 65414 | 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-18T00:38:25.810: SUC: 2007, 30300ms, lines: 4096.
2024-12-18T00:38:25.811: checkJavaStack end: 2007, pid/ret=4096.
2024-12-18T00:38:35.811: checkJavaStack starting: 2007.
2024-12-18T00:39:06.094: ------ test: 2007
2024-12-18T00:39:06.094: EXE: 5/"jstack 65414 | 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-18T00:39:36.396: SUC: 2007, 30302ms, lines: 4096.
2024-12-18T00:39:36.396: checkJavaStack end: 2007, pid/ret=4096.
2024-12-18T00:39:46.397: checkJavaStack starting: 2007.
2024-12-18T00:40:16.595: ------ test: 2007
2024-12-18T00:40:16.595: EXE: 5/"jstack 65414 | 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-18T00:40:46.892: SUC: 2007, 30297ms, lines: 4096.
2024-12-18T00:40:46.938: checkJavaStack end: 2007, pid/ret=4096.
2024-12-18T00:40:56.938: checkJavaStack starting: 2007.
2024-12-18T00:41:27.107: ------ test: 2007
2024-12-18T00:41:27.107: EXE: 5/"jstack 65414 | 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-18T00:41:27.337: WRN: stderr, bash: warning: setlocale: LC_ALL: cannot change locale (C.utf8).
2024-12-18T00:41:27.338: WRN: 2007, 230ms, lines: -1.
2024-12-18T00:41:27.338: fileio: /var/www/html/ivves/notices.txt, mode: 3, length: 572.
2024-12-18T00:41:27.341: fileio: /var/www/html/ivves/notices.txt, mode: 2, length: 572.
2024-12-18T00:41:27.381: checkJavaStack end: 2007, pid/ret=-1.
2024-12-18T00:41:37.386: checkJavaStack starting: 2007.
2024-12-18T00:41:37.493: WRN: stderr, bash: warning: setlocale: LC_ALL: cannot change locale (C.utf8).
2024-12-18T00:41:37.494: checkJavaStack end: 2007, pid/ret=0.
2024-12-18T00:41:37.495: ------ test: end, 1232091ms. Score 24/23.
2024-12-18T00:41:37.495: Test duration: 20m32s.