2025-02-26T15:21:04.815: current working dir: /root/qa/mctester.
2025-02-26T15:21:04.923: current logging dir: ../mctester/www.
2025-02-26T15:21:04.923: ---------------------------------------------------------------
2025-02-26T15:21:04.923: mctester entry: 2025-02-26T15:21:04.923 mode=50
2025-02-26T15:21:04.924: ---------------------------------------------------------------
2025-02-26T15:21:05.043: EXE: 1/git describe --tags.
2025-02-26T15:21:05.055: Row 1: v1.1-257-g3195fc1.
2025-02-26T15:21:05.056: EXE: 1/git rev-list --count HEAD.
2025-02-26T15:21:05.061: Row 1: 314.
2025-02-26T15:21:05.062: EXE: 1/git rev-parse HEAD.
2025-02-26T15:21:05.064: Row 1: 3195fc1a5f05079bbbf1e5bfcbcc4741c44bc972.
2025-02-26T15:21:05.064: mctester: v1.1.314 (3195fc1a5f05079bbbf1e5bfcbcc4741c44bc972).
2025-02-26T15:21:05.064: Entering into a performance tests mode.
2025-02-26T15:21:05.065: ------ test: start, 2025-02-26T15:21:05.065
2025-02-26T15:21:05.067: ------ test: 2000
2025-02-26T15:21:05.069: CMD: ssh -o ServerAliveInterval=60 -o ServerAliveCountMax=3 root@megatron.headai.com "top -b -n 1"
2025-02-26T15:21:05.070: EXE: 1/ssh -o ServerAliveInterval=60 -o ServerAliveCountMax=3 root@megatron.headai.com "top -b -n 1".
2025-02-26T15:21:05.103: stderr: kex_exchange_identification: Connection closed by remote host.
2025-02-26T15:21:05.103: stderr: Connection closed by 185.20.136.38 port 22.
2025-02-26T15:21:05.103: Abnormal exit: 255, lines: 0, output: .
2025-02-26T15:21:05.103: ERR: 34ms
2025-02-26T15:21:05.116: ------ test: 2001
2025-02-26T15:21:05.117: CMD: top -b -E k -n 1
2025-02-26T15:21:05.117: EXE: 1/top -b -E k -n 1.
2025-02-26T15:21:05.278: Row 1: top - 15:21:05 up 106 days, 22:03, 0 users, load average: 0.00, 0.01, 0.00.
2025-02-26T15:21:05.332: Row 2: Tasks: 111 total, 1 running, 109 sleeping, 0 stopped, 1 zombie.
2025-02-26T15:21:05.332: Row 3: %Cpu(s): 0.0 us, 6.2 sy, 0.0 ni, 93.8 id, 0.0 wa, 0.0 hi, 0.0 si, 0.0 st.
2025-02-26T15:21:05.333: Row 4: KiB Mem : 1907632 total, 503284 free, 581052 used, 823296 buff/cache.
2025-02-26T15:21:05.333: Row 5: KiB Swap: 0 total, 0 free, 0 used. 1110016 avail Mem .
2025-02-26T15:21:05.333: Row 6: .
2025-02-26T15:21:05.333: Row 7: PID USER PR NI VIRT RES SHR S %CPU %MEM TIME+ COMMAND.
2025-02-26T15:21:05.333: Row 8: 1 root 20 0 167764 10420 5536 S 0.0 0.5 2:48.44 systemd.
2025-02-26T15:21:05.333: Row 9: 2 root 20 0 0 0 0 S 0.0 0.0 0:00.29 kthreadd.
2025-02-26T15:21:05.334: Row 10: 3 root 0 -20 0 0 0 I 0.0 0.0 0:00.00 rcu_gp.
2025-02-26T15:21:05.334: Row 11: 4 root 0 -20 0 0 0 I 0.0 0.0 0:00.00 rcu_par+.
2025-02-26T15:21:05.334: Row 12: 5 root 0 -20 0 0 0 I 0.0 0.0 0:00.00 slub_fl+.
2025-02-26T15:21:05.337: Row 13: 6 root 0 -20 0 0 0 I 0.0 0.0 0:00.00 netns.
2025-02-26T15:21:05.343: Row 14: 8 root 0 -20 0 0 0 I 0.0 0.0 0:00.00 kworker+.
2025-02-26T15:21:05.343: Row 15: 10 root 0 -20 0 0 0 I 0.0 0.0 0:00.00 mm_perc+.
2025-02-26T15:21:05.343: Row 16: 11 root 20 0 0 0 0 S 0.0 0.0 0:00.00 rcu_tas+.
2025-02-26T15:21:05.343: Row 17: 12 root 20 0 0 0 0 S 0.0 0.0 0:00.00 rcu_tas+.
2025-02-26T15:21:05.344: Row 18: 13 root 20 0 0 0 0 S 0.0 0.0 1:30.46 ksoftir+.
2025-02-26T15:21:05.344: Row 19: 14 root 20 0 0 0 0 I 0.0 0.0 2:39.98 rcu_sch+.
2025-02-26T15:21:05.344: Row 20: 15 root rt 0 0 0 0 S 0.0 0.0 0:22.55 migrati+.
2025-02-26T15:21:05.344: Row 21: 16 root -51 0 0 0 0 S 0.0 0.0 0:00.00 idle_in+.
2025-02-26T15:21:05.344: Row 22: 18 root 20 0 0 0 0 S 0.0 0.0 0:00.00 cpuhp/0.
2025-02-26T15:21:05.344: Row 23: 19 root 20 0 0 0 0 S 0.0 0.0 0:00.00 kdevtmp+.
2025-02-26T15:21:05.344: Row 24: 20 root 0 -20 0 0 0 I 0.0 0.0 0:00.00 inet_fr+.
2025-02-26T15:21:05.375: SUC: 258ms Uptime 106 days, 1min 0.00, 5min 0.01, 15min 0.00, mem:1907632 total / 503284 free / 581052 used / 823296 cache / 1110016 avail
2025-02-26T15:21:05.377: ------ test: 2002
2025-02-26T15:21:05.378: CMD: df -h
2025-02-26T15:21:05.378: EXE: 1/df -h.
2025-02-26T15:21:05.380: Row 1: Filesystem Size Used Avail Use% Mounted on.
2025-02-26T15:21:05.381: Row 2: tmpfs 187M 20M 167M 11% /run.
2025-02-26T15:21:05.381: Row 3: /dev/vda2 20G 15G 3.8G 80% /.
2025-02-26T15:21:05.381: Row 4: tmpfs 932M 0 932M 0% /dev/shm.
2025-02-26T15:21:05.382: Row 5: tmpfs 5.0M 0 5.0M 0% /run/lock.
2025-02-26T15:21:05.382: SUC: 5ms Diskfree qa.headai.com: tmpfs:11%, /dev/vda2:80%, tmpfs:0%, tmpfs:0%.
2025-02-26T15:21:05.383: ------ test: 2003
2025-02-26T15:21:05.384: CMD: ssh -o ServerAliveInterval=60 -o ServerAliveCountMax=3 root@megatron.headai.com "df -h"
2025-02-26T15:21:05.385: EXE: 1/ssh -o ServerAliveInterval=60 -o ServerAliveCountMax=3 root@megatron.headai.com "df -h".
2025-02-26T15:21:05.418: stderr: kex_exchange_identification: read: Connection reset by peer.
2025-02-26T15:21:05.419: stderr: Connection reset by 185.20.136.38 port 22.
2025-02-26T15:21:05.421: Abnormal exit: 255, lines: 0, output: .
2025-02-26T15:21:05.422: ERR: 38ms
2025-02-26T15:21:05.422: ------ test: 2004
2025-02-26T15:21:05.422: URL: https://headai.com
2025-02-26T15:21:06.110: SUC: 687ms, Trusted by 100+ customers and partners globally, length 192944.
2025-02-26T15:21:06.113: ------ test: 2005
2025-02-26T15:21:06.590: SQL: show global status like 'threads_connected';.
2025-02-26T15:21:06.607: Results: value=4/200.
2025-02-26T15:21:06.607: SQL: value, lines: 1, ret=1, 4.
2025-02-26T15:21:06.613: SUC: 500ms 4
2025-02-26T15:21:06.615: ------ test: 2006
2025-02-26T15:21:06.719: SQL: SHOW PROCESSLIST;.
2025-02-26T15:21:06.723: Row 1: 11901339,root,localhost:33308,competences,Query,14,Sending data,SELECT id,url,title,description FROM jobs.items_2021 WHERE (1=1) AND length(description)>100 AND ye.
2025-02-26T15:21:06.723: Row 2: 11901361,root,localhost:33364,null,Query,0,starting,SHOW PROCESSLIST.
2025-02-26T15:21:06.724: SQL: COUNTNOTLINES:Command, lines: 2, ret=2, 2.
2025-02-26T15:21:06.724: SUC: 109ms 2
2025-02-26T15:21:06.729: checkJavaStack starting: 2007.
2025-02-26T15:21:06.849: ------ test: 2007
2025-02-26T15:21:06.849: EXE: 5/"jstack 1968 | 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".
2025-02-26T15:21:06.889: stderr: kex_exchange_identification: Connection closed by remote host.
2025-02-26T15:21:06.889: stderr: Connection closed by 185.20.136.38 port 22.
2025-02-26T15:21:06.889: SUC: 2007, 40ms, lines: 4096.
2025-02-26T15:21:06.890: checkJavaStack end: 2007, pid/ret=4096.
2025-02-26T15:21:16.891: checkJavaStack starting: 2007.
2025-02-26T15:21:17.005: ------ test: 2007
2025-02-26T15:21:17.005: EXE: 5/"jstack 1968 | 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".
2025-02-26T15:21:17.040: stderr: kex_exchange_identification: Connection closed by remote host.
2025-02-26T15:21:17.040: stderr: Connection closed by 185.20.136.38 port 22.
2025-02-26T15:21:17.040: SUC: 2007, 35ms, lines: 4096.
2025-02-26T15:21:17.041: checkJavaStack end: 2007, pid/ret=4096.
2025-02-26T15:21:27.041: checkJavaStack starting: 2007.
2025-02-26T15:21:27.162: ------ test: 2007
2025-02-26T15:21:27.162: EXE: 5/"jstack 1968 | 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".
2025-02-26T15:21:27.446: Row 1: "https-openssl-nio-443-exec-2056" #1517935 daemon prio=5 os_prio=0 tid=0x00007fdd1410c000 nid=0x914d runnable [0x00007fdf733fc000].
2025-02-26T15:21:27.446: Row 2: java.lang.Thread.State: RUNNABLE.
2025-02-26T15:21:27.447: Row 3: at h1.body.wordToBody(body.java:286).
2025-02-26T15:21:27.447: Row 4: at h2.core2021.semanticSimilarity_v3(core2021.java:1394).
2025-02-26T15:21:27.447: Row 5: at h2.core2021.text2keywords_v3(core2021.java:728).
2025-02-26T15:21:27.447: Row 6: at h2.BuildKnowledgeGraph_v5.build_a_map_on_fly_v4_1(BuildKnowledgeGraph_v5.java:1731).
2025-02-26T15:21:27.447: Row 7: at h2.SkillsForecast_methods.build_a_map_on_fly_v4(SkillsForecast_methods.java:5221).
2025-02-26T15:21:27.447: Row 8: at h2.helpers.check_pending_works(helpers.java:5302).
2025-02-26T15:21:27.448: Row 9: at h1.mc_api.doPost(mc_api.java:4576).
2025-02-26T15:21:27.448: Row 10: at h1.mc_api.doGet(mc_api.java:38).
2025-02-26T15:21:27.448: Row 11: "https-openssl-nio-443-exec-80" #52135 daemon prio=5 os_prio=0 tid=0x00007fdfa0558000 nid=0xcd98 waiting on condition [0x00007fdf833f1000].
2025-02-26T15:21:27.448: Row 12: java.lang.Thread.State: TIMED_WAITING (sleeping).
2025-02-26T15:21:27.448: Row 13: at java.lang.Thread.sleep(Native Method).
2025-02-26T15:21:27.448: Row 14: at h2.jotpa_excel_builder.automated_builder(jotpa_excel_builder.java:3027).
2025-02-26T15:21:27.448: Row 15: at h2.Utils.doPost(Utils.java:276).
2025-02-26T15:21:27.448: Row 16: at h2.Utils.doGet(Utils.java:30).
2025-02-26T15:21:27.449: Row 17: "https-openssl-nio-443-exec-68" #52116 daemon prio=5 os_prio=0 tid=0x00007fdfcc032800 nid=0xccfe waiting on condition [0x00007fdff46e2000].
2025-02-26T15:21:27.449: Row 18: java.lang.Thread.State: TIMED_WAITING (sleeping).
2025-02-26T15:21:27.449: Row 19: at java.lang.Thread.sleep(Native Method).
2025-02-26T15:21:27.454: Row 20: at h2.jotpa_excel_builder.automated_builder(jotpa_excel_builder.java:3027).
2025-02-26T15:21:27.455: Row 21: at h2.Utils.doPost(Utils.java:276).
2025-02-26T15:21:27.455: Row 22: at h2.Utils.doGet(Utils.java:30).
2025-02-26T15:21:27.456: Row 23: "https-openssl-nio-443-exec-39" #465 daemon prio=5 os_prio=0 tid=0x00007fdfb001b000 nid=0x17bb waiting on condition [0x00007fdf835f3000].
2025-02-26T15:21:27.456: Row 24: java.lang.Thread.State: TIMED_WAITING (sleeping).
2025-02-26T15:21:27.458: SUC: 2007, 296ms, lines: 28.
2025-02-26T15:21:27.460: checkJavaStack end: 2007, pid/ret=28.
2025-02-26T15:21:37.461: checkJavaStack starting: 2007.
2025-02-26T15:21:37.577: ------ test: 2007
2025-02-26T15:21:37.578: EXE: 5/"jstack 1968 | 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".
2025-02-26T15:21:37.814: Row 1: "https-openssl-nio-443-exec-2056" #1517935 daemon prio=5 os_prio=0 tid=0x00007fdd1410c000 nid=0x914d runnable [0x00007fdf733fc000].
2025-02-26T15:21:37.815: Row 2: java.lang.Thread.State: RUNNABLE.
2025-02-26T15:21:37.815: Row 3: at h1.body.wordToBody(body.java:286).
2025-02-26T15:21:37.815: Row 4: at h2.core2021.semanticSimilarity_v3(core2021.java:1370).
2025-02-26T15:21:37.815: Row 5: at h2.core2021.text2keywords_v3(core2021.java:728).
2025-02-26T15:21:37.815: Row 6: at h2.BuildKnowledgeGraph_v5.build_a_map_on_fly_v4_1(BuildKnowledgeGraph_v5.java:1731).
2025-02-26T15:21:37.815: Row 7: at h2.SkillsForecast_methods.build_a_map_on_fly_v4(SkillsForecast_methods.java:5221).
2025-02-26T15:21:37.815: Row 8: at h2.helpers.check_pending_works(helpers.java:5302).
2025-02-26T15:21:37.815: Row 9: at h1.mc_api.doPost(mc_api.java:4576).
2025-02-26T15:21:37.815: Row 10: at h1.mc_api.doGet(mc_api.java:38).
2025-02-26T15:21:37.816: Row 11: "https-openssl-nio-443-exec-80" #52135 daemon prio=5 os_prio=0 tid=0x00007fdfa0558000 nid=0xcd98 waiting on condition [0x00007fdf833f1000].
2025-02-26T15:21:37.816: Row 12: java.lang.Thread.State: TIMED_WAITING (sleeping).
2025-02-26T15:21:37.816: Row 13: at java.lang.Thread.sleep(Native Method).
2025-02-26T15:21:37.816: Row 14: at h2.jotpa_excel_builder.automated_builder(jotpa_excel_builder.java:3027).
2025-02-26T15:21:37.818: Row 15: at h2.Utils.doPost(Utils.java:276).
2025-02-26T15:21:37.818: Row 16: at h2.Utils.doGet(Utils.java:30).
2025-02-26T15:21:37.818: Row 17: "https-openssl-nio-443-exec-68" #52116 daemon prio=5 os_prio=0 tid=0x00007fdfcc032800 nid=0xccfe waiting on condition [0x00007fdff46e2000].
2025-02-26T15:21:37.818: Row 18: java.lang.Thread.State: TIMED_WAITING (sleeping).
2025-02-26T15:21:37.818: Row 19: at java.lang.Thread.sleep(Native Method).
2025-02-26T15:21:37.818: Row 20: at h2.jotpa_excel_builder.automated_builder(jotpa_excel_builder.java:3027).
2025-02-26T15:21:37.819: Row 21: at h2.Utils.doPost(Utils.java:276).
2025-02-26T15:21:37.819: Row 22: at h2.Utils.doGet(Utils.java:30).
2025-02-26T15:21:37.819: Row 23: "https-openssl-nio-443-exec-39" #465 daemon prio=5 os_prio=0 tid=0x00007fdfb001b000 nid=0x17bb waiting on condition [0x00007fdf835f3000].
2025-02-26T15:21:37.824: Row 24: java.lang.Thread.State: TIMED_WAITING (sleeping).
2025-02-26T15:21:37.825: SUC: 2007, 247ms, lines: 28.
2025-02-26T15:21:37.825: checkJavaStack end: 2007, pid/ret=28.
2025-02-26T15:21:47.825: checkJavaStack starting: 2007.
2025-02-26T15:21:47.941: ------ test: 2007
2025-02-26T15:21:47.942: EXE: 5/"jstack 1968 | 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".
2025-02-26T15:21:47.975: stderr: kex_exchange_identification: Connection closed by remote host.
2025-02-26T15:21:47.975: stderr: Connection closed by 185.20.136.38 port 22.
2025-02-26T15:21:47.976: SUC: 2007, 35ms, lines: 4096.
2025-02-26T15:21:47.976: checkJavaStack end: 2007, pid/ret=4096.
2025-02-26T15:21:57.976: checkJavaStack starting: 2007.
2025-02-26T15:21:58.010: stderr: kex_exchange_identification: read: Connection reset by peer.
2025-02-26T15:21:58.010: stderr: Connection reset by 185.20.136.38 port 22.
2025-02-26T15:21:58.010: Abnormal exit: 255, lines: 0, output: .
2025-02-26T15:21:58.010: checkJavaStack end: 2007, pid/ret=0.
2025-02-26T15:21:58.011: ------ test: end, 52946ms. Score 12/10.
2025-02-26T15:21:58.011: Test duration: 0m52s.