See https://jenkins.osmocom.org/jenkins/job/ttcn3-stp-test-latest/2211/display/redirect
Changes:
------------------------------------------ [...truncated 3.45 MiB...] [38;5;43m20251206124827773 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(as-client){AS_DOWN}: Received Event ASPAS-ASP_INACTIVE.ind (xua_asp_fsm.c:405)[0;m [38;5;43m20251206124827773 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(as-client){AS_DOWN}: state_chg to AS_INACTIVE (xua_as_fsm.c:357)[0;m [38;5;43m20251206124827773 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(as-client){AS_INACTIVE}: Broadcasting NOTIFY Type State Change:AS Inactive () to all non-DOWN ASPs (xua_as_fsm.c:116)[0;m [38;5;43m20251206124827773 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m xua_default_lm(asp-client0){WAIT_ASP_UP}: Received primitive M-ASP_UP.confirm (xua_default_lm_fsm.c:403)[0;m [38;5;43m20251206124827773 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m xua_default_lm(asp-client0){WAIT_ASP_UP}: Received Event ASP-UP.conf (xua_default_lm_fsm.c:410)[0;m [38;5;43m20251206124827773 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m xua_default_lm(asp-client0){WAIT_ASP_UP}: state_chg to WAIT_NOTIFY (xua_default_lm_fsm.c:182)[0;m [38;5;43m20251206124827773 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(asp-client0) xua_cli_read_cb(): sctp_recvmsg() returned 24 (flags=0x0) (ss7_asp.c:1308)[0;m [38;5;49m20251206124827773 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(asp-client0) Received M3UA Message (MGMT:NOTIFY) (m3ua.c:811)[0;m [38;5;49m20251206124827773 [1;33mDLM3UA[0;m[38;5;49m [1;33mNOTICE[0;m[38;5;49m 0: ASP(asp-client0) Received NOTIFY Type State Change:AS Inactive () (m3ua.c:707)[0;m [38;5;43m20251206124827773 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m xua_default_lm(asp-client0){WAIT_NOTIFY}: Received primitive M-NOTIFY.indication (xua_default_lm_fsm.c:403)[0;m [38;5;43m20251206124827773 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m xua_default_lm(asp-client0){WAIT_NOTIFY}: Received Event NOTIFY.ind (xua_default_lm_fsm.c:410)[0;m [38;5;43m20251206124827773 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m xua_default_lm(asp-client0){WAIT_NOTIFY}: state_chg to ACTIVE (xua_default_lm_fsm.c:258)[0;m [38;5;43m20251206124827773 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_ASP(asp-client0){ASP_INACTIVE}: Received Event M-ASP_ACTIVE.req (xua_default_lm_fsm.c:259)[0;m [38;5;43m20251206124827773 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(asp-client0) Tx 24 bytes: [L2]> 01 00 04 01 00 00 00 18 00 0b 00 08 00 00 00 02 00 06 00 08 00 00 04 1f (ss7_asp.c:1400)[0;m MTC@030e1a54e5a2: [0] Answering M3UA BEAT { connId := 2, remName := "::ffff:127.0.0.1", remPort := 2906, locName := "::ffff:127.0.0.1", locPort := 10002, msg := { m3UA_BEAT := { version := '01'O, reserved := '00'O, messageClassAndType := '0303'O, messageLength := 8, messageParameters := { heartbeat_Data := omit } } } } [38;5;43m20251206124827774 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(asp-client0) xua_cli_read_cb(): sctp_recvmsg() returned 8 (flags=0x0) (ss7_asp.c:1308)[0;m [38;5;49m20251206124827774 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(asp-client0) Received M3UA Message (ASPSM:BEAT-ACK) (m3ua.c:811)[0;m [38;5;43m20251206124827774 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_ASP(asp-client0){ASP_INACTIVE}: Received Event ASPSM_BEAT_ACK (m3ua.c:772)[0;m [38;5;43m20251206124827774 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_ASP(asp-client0){ASP_INACTIVE}: Rx HEARTBEAT ACK (xua_asp_fsm.c:868)[0;m MTC@030e1a54e5a2: setverdict(pass): pass -> pass, component reason not changed MTC@030e1a54e5a2: Clearing M3UA... [38;5;43m20251206124827978 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(asp-client0) xua_cli_read_cb(): sctp_recvmsg() returned 24 (flags=0x0) (ss7_asp.c:1308)[0;m [38;5;49m20251206124827978 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(asp-client0) Received M3UA Message (ASPTM:ACTIVE-ACK) (m3ua.c:811)[0;m [38;5;43m20251206124827978 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_ASP(asp-client0){ASP_INACTIVE}: Received Event ASPTM-ASP_AC_ACK (m3ua.c:772)[0;m [38;5;43m20251206124827978 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_ASP(asp-client0){ASP_INACTIVE}: T(ack) stopped (xua_asp_fsm.c:420)[0;m [38;5;43m20251206124827978 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_ASP(asp-client0){ASP_INACTIVE}: state_chg to ASP_ACTIVE (xua_asp_fsm.c:662)[0;m [38;5;43m20251206124827978 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(as-client){AS_INACTIVE}: Received Event ASPAS-ASP_ACTIVE.ind (xua_asp_fsm.c:405)[0;m [38;5;43m20251206124827978 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(as-client){AS_INACTIVE}: state_chg to AS_ACTIVE (xua_as_fsm.c:565)[0;m [38;5;43m20251206124827978 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(as-client){AS_ACTIVE}: Broadcasting NOTIFY Type State Change:AS Active () to all non-DOWN ASPs (xua_as_fsm.c:116)[0;m [38;5;43m20251206124827978 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m xua_default_lm(asp-client0){ACTIVE}: Received primitive M-ASP_ACTIVE.confirm (xua_default_lm_fsm.c:403)[0;m [38;5;43m20251206124827978 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m xua_default_lm(asp-client0){ACTIVE}: Ignoring primitive M-ASP_ACTIVE.confirm (xua_default_lm_fsm.c:406)[0;m [38;5;43m20251206124827978 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(asp-client0) xua_cli_read_cb(): sctp_recvmsg() returned 24 (flags=0x0) (ss7_asp.c:1308)[0;m [38;5;49m20251206124827978 [1;34mDLM3UA[0;m[38;5;49m [1;34mDEBUG[0;m[38;5;49m 0: ASP(asp-client0) Received M3UA Message (MGMT:NOTIFY) (m3ua.c:811)[0;m [38;5;49m20251206124827978 [1;33mDLM3UA[0;m[38;5;49m [1;33mNOTICE[0;m[38;5;49m 0: ASP(asp-client0) Received NOTIFY Type State Change:AS Active () (m3ua.c:707)[0;m [38;5;43m20251206124827979 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m xua_default_lm(asp-client0){ACTIVE}: Received primitive M-NOTIFY.indication (xua_default_lm_fsm.c:403)[0;m [38;5;43m20251206124827979 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m xua_default_lm(asp-client0){ACTIVE}: Received Event NOTIFY.ind (xua_default_lm_fsm.c:410)[0;m MTC@030e1a54e5a2: setverdict(pass): pass -> pass reason: "M3UA cleared", component reason not changed 20251206124830276 [1;32mDLGLOBAL[0;m [1;32mINFO[0;m Closing telnet connection r=127.0.0.1:47714<->l=127.0.0.1:4239 (telnet_interface.c:138) [38;5;23m20251206124830276 [1;33mDLINP[0;m[38;5;23m [1;33mNOTICE[0;m[38;5;23m asp-client0 NOTIFICATION SHUTDOWN_EVENT flags=0x0 (stream.c:348)[0;m [38;5;43m20251206124830276 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(asp-client0) xua_cli_read_cb(): sctp_recvmsg() returned 160 (flags=0x80) (ss7_asp.c:1308)[0;m [38;5;43m20251206124830276 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m 0: ASP(asp-client0) xUA CLNT SCTP NOTIFICATION 32773 flags=0x0 (ss7_asp.c:998)[0;m [38;5;43m20251206124830276 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m 0: ASP(asp-client0) xUA CLNT SHUTDOWN_EVENT (ss7_asp.c:1021)[0;m [38;5;23m20251206124830276 [1;33mDLINP[0;m[38;5;23m [1;33mNOTICE[0;m[38;5;23m asp-client0 NOTIFICATION ASSOC_CHANGE flags=0x0 SHUTDOWN_COMP (stream.c:348)[0;m [38;5;43m20251206124830276 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(asp-client0) xua_cli_read_cb(): sctp_recvmsg() returned 168 (flags=0x80) (ss7_asp.c:1308)[0;m [38;5;43m20251206124830276 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m 0: ASP(asp-client0) xUA CLNT SCTP NOTIFICATION 32769 flags=0x0 (ss7_asp.c:998)[0;m [38;5;43m20251206124830276 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m 0: ASP(asp-client0) xUA CLNT SCTP_ASSOC_CHANGE: SHUTDOWN_COMP (ss7_asp.c:1006)[0;m [38;5;23m20251206124830276 [1;33mDLINP[0;m[38;5;23m [1;33mNOTICE[0;m[38;5;23m CLICONN(asp-client0,r=::1:10002<->l=::ffff:127.0.0.1:2906){CONNECTED} connection closed with srv (stream_cli.c:631)[0;m [38;5;23m20251206124830276 [1;34mDLINP[0;m[38;5;23m [1;34mDEBUG[0;m[38;5;23m CLICONN(asp-client0,r=::1:10002<->l=::ffff:127.0.0.1:2906){CONNECTED} close() (stream_cli.c:179)[0;m [38;5;43m20251206124830276 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m 0: ASP(asp-client0) disconnect_cb() from lower layers, reconnecting (ss7_asp.c:1248)[0;m [38;5;43m20251206124830276 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m 0: ASP(asp-client0) Closing and reconnecting ASP (ss7_asp.c:1238)[0;m [38;5;23m20251206124830276 [1;34mDLINP[0;m[38;5;23m [1;34mDEBUG[0;m[38;5;23m CLICONN(asp-client0,r=::1:10002<->l=::ffff:127.0.0.1:2906){CLOSED} close() (stream_cli.c:179)[0;m [38;5;43m20251206124830276 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_ASP(asp-client0){ASP_ACTIVE}: Received Event SCTP-COMM_DOWN.ind (ss7_asp.c:1230)[0;m [38;5;43m20251206124830276 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_ASP(asp-client0){ASP_ACTIVE}: state_chg to ASP_DOWN (xua_asp_fsm.c:859)[0;m [38;5;43m20251206124830276 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_ASP(asp-client0){ASP_DOWN}: T(beat) stopped (xua_asp_fsm.c:429)[0;m [38;5;43m20251206124830276 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(as-client){AS_ACTIVE}: Received Event ASPAS-ASP_DOWN.ind (xua_asp_fsm.c:405)[0;m [38;5;43m20251206124830276 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(as-client){AS_ACTIVE}: state_chg to AS_PENDING (xua_as_fsm.c:592)[0;m [38;5;43m20251206124830276 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(as-client){AS_PENDING}: Broadcasting NOTIFY Type State Change:AS Pending () to all non-DOWN ASPs (xua_as_fsm.c:116)[0;m [38;5;43m20251206124830276 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m xua_default_lm(asp-client0){ACTIVE}: Received primitive M-ASP_DOWN.indication (xua_default_lm_fsm.c:403)[0;m [38;5;43m20251206124830276 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m xua_default_lm(asp-client0){ACTIVE}: Ignoring primitive M-ASP_DOWN.indication (xua_default_lm_fsm.c:406)[0;m [38;5;43m20251206124830276 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m xua_default_lm(asp-client0){ACTIVE}: Received primitive M-SCTP_RELEASE.indication (xua_default_lm_fsm.c:403)[0;m [38;5;43m20251206124830276 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m xua_default_lm(asp-client0){ACTIVE}: Received Event SCTP-RELEASE.ind (xua_default_lm_fsm.c:410)[0;m [38;5;43m20251206124830276 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m xua_default_lm(asp-client0){ACTIVE}: state_chg to IDLE (xua_default_lm_fsm.c:324)[0;m [38;5;23m20251206124830276 [1;34mDLINP[0;m[38;5;23m [1;34mDEBUG[0;m[38;5;23m CLICONN(asp-client0,r=::1:10002<->l=::ffff:127.0.0.1:2906){CLOSED} close() (stream_cli.c:179)[0;m [38;5;23m20251206124830276 [1;32mDLINP[0;m[38;5;23m [1;32mINFO[0;m[38;5;23m CLICONN(asp-client0,r=::1:10002<->l=::ffff:127.0.0.1:2906){WAIT_RECONNECT} retrying reconnect in 5 seconds... (stream_cli.c:244)[0;m [38;5;23m20251206124830276 [1;32mDLINP[0;m[38;5;23m [1;32mINFO[0;m[38;5;23m CLICONN(asp-client0,r=::1:10002<->l=::ffff:127.0.0.1:2906){WAIT_RECONNECT} retrying reconnect in 5 seconds... (stream_cli.c:244)[0;m [38;5;43m20251206124830276 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: ASP(asp-client0) xua_cli_read_cb(): sctp_recvmsg() returned 0 (flags=0x0) (ss7_asp.c:1308)[0;m [38;5;43m20251206124830276 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m 0: ASP(asp-client0) Closing and reconnecting ASP (ss7_asp.c:1238)[0;m [38;5;23m20251206124830276 [1;34mDLINP[0;m[38;5;23m [1;34mDEBUG[0;m[38;5;23m CLICONN(asp-client0,r=::1:10002<->l=::ffff:127.0.0.1:2906){WAIT_RECONNECT} close() (stream_cli.c:179)[0;m [38;5;43m20251206124830276 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_ASP(asp-client0){ASP_DOWN}: Received Event SCTP-COMM_DOWN.ind (ss7_asp.c:1230)[0;m [38;5;43m20251206124830276 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_ASP(asp-client0){ASP_DOWN}: state_chg to ASP_DOWN (xua_asp_fsm.c:859)[0;m [38;5;43m20251206124830276 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_ASP(asp-client0){ASP_DOWN}: T(beat) stopped (xua_asp_fsm.c:429)[0;m [38;5;43m20251206124830276 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(as-client){AS_PENDING}: Received Event ASPAS-ASP_DOWN.ind (xua_asp_fsm.c:405)[0;m [38;5;43m20251206124830276 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m xua_default_lm(asp-client0){IDLE}: Received primitive M-ASP_DOWN.indication (xua_default_lm_fsm.c:403)[0;m [38;5;43m20251206124830276 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m xua_default_lm(asp-client0){IDLE}: Ignoring primitive M-ASP_DOWN.indication (xua_default_lm_fsm.c:406)[0;m [38;5;43m20251206124830276 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m xua_default_lm(asp-client0){IDLE}: Received primitive M-SCTP_RELEASE.indication (xua_default_lm_fsm.c:403)[0;m [38;5;43m20251206124830276 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m xua_default_lm(asp-client0){IDLE}: Received Event SCTP-RELEASE.ind (xua_default_lm_fsm.c:410)[0;m [38;5;43m20251206124830276 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m xua_default_lm(asp-client0){IDLE}: state_chg to IDLE (xua_default_lm_fsm.c:324)[0;m [38;5;23m20251206124830276 [1;34mDLINP[0;m[38;5;23m [1;34mDEBUG[0;m[38;5;23m CLICONN(asp-client0,r=::1:10002<->l=::ffff:127.0.0.1:2906){CLOSED} close() (stream_cli.c:179)[0;m MTC@030e1a54e5a2: Setting final verdict of the test case. MTC@030e1a54e5a2: Local verdict of MTC: pass MTC@030e1a54e5a2: No PTCs were created. MTC@030e1a54e5a2: Test case TC_clnt_asp_act_tmt_loadshare finished. Verdict: pass MTC@030e1a54e5a2: Starting external command `/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/ttcn3-tcpdump-stop.sh STP_Tests_M3UA.TC_clnt_asp_act_tmt_loadshare pass'. [38;5;23m20251206124830276 [1;32mDLINP[0;m[38;5;23m [1;32mINFO[0;m[38;5;23m CLICONN(asp-client0,r=::1:10002<->l=::ffff:127.0.0.1:2906){WAIT_RECONNECT} retrying reconnect in 5 seconds... (stream_cli.c:244)[0;m Sat Dec 6 12:48:30 UTC 2025 [1;32m====== STP_Tests_M3UA.TC_clnt_asp_act_tmt_loadshare pass ======[0m
Saving talloc report from 127.0.0.1:4239 to STP_Tests_M3UA.TC_clnt_asp_act_tmt_loadshare.talloc 20251206124830301 [1;32mDLGLOBAL[0;m [1;32mINFO[0;m Accept()ed new telnet connection r=127.0.0.1:47722<->l=127.0.0.1:4239 (telnet_interface.c:192) 20251206124830904 [1;32mDLGLOBAL[0;m [1;32mINFO[0;m Closing telnet connection r=127.0.0.1:47722<->l=127.0.0.1:4239 (telnet_interface.c:138) Waiting for packet dumper to finish... 0 (prev_count=-1, count=35024) [38;5;23m20251206124831297 [1;34mDLINP[0;m[38;5;23m [1;34mDEBUG[0;m[38;5;23m CLICONN(asp-client61-norctx0,r=::1:11061<->l=::ffff:127.0.0.1:2907){WAIT_RECONNECT} reconnecting (stream_cli.c:1352)[0;m [38;5;23m20251206124831297 [1;34mDLINP[0;m[38;5;23m [1;34mDEBUG[0;m[38;5;23m CLICONN(asp-client60-norctx0,r=::1:11060<->l=::ffff:127.0.0.1:2907){WAIT_RECONNECT} reconnecting (stream_cli.c:1352)[0;m [38;5;23m20251206124831297 [1;31mDLINP[0;m[38;5;23m [1;31mERROR[0;m[38;5;23m CLICONN(asp-client61-norctx0,r=::1:11061<->l=::ffff:127.0.0.1:2907){CONNECTING} connect failed (-111) (stream_cli.c:424)[0;m [38;5;23m20251206124831297 [1;34mDLINP[0;m[38;5;23m [1;34mDEBUG[0;m[38;5;23m CLICONN(asp-client61-norctx0,r=::1:11061<->l=::ffff:127.0.0.1:2907){CONNECTING} close() (stream_cli.c:179)[0;m [38;5;23m20251206124831297 [1;32mDLINP[0;m[38;5;23m [1;32mINFO[0;m[38;5;23m CLICONN(asp-client61-norctx0,r=::1:11061<->l=::ffff:127.0.0.1:2907){WAIT_RECONNECT} retrying reconnect in 5 seconds... (stream_cli.c:244)[0;m [38;5;23m20251206124831297 [1;31mDLINP[0;m[38;5;23m [1;31mERROR[0;m[38;5;23m CLICONN(asp-client60-norctx0,r=::1:11060<->l=::ffff:127.0.0.1:2907){CONNECTING} connect failed (-111) (stream_cli.c:424)[0;m [38;5;23m20251206124831297 [1;34mDLINP[0;m[38;5;23m [1;34mDEBUG[0;m[38;5;23m CLICONN(asp-client60-norctx0,r=::1:11060<->l=::ffff:127.0.0.1:2907){CONNECTING} close() (stream_cli.c:179)[0;m [38;5;23m20251206124831297 [1;32mDLINP[0;m[38;5;23m [1;32mINFO[0;m[38;5;23m CLICONN(asp-client60-norctx0,r=::1:11060<->l=::ffff:127.0.0.1:2907){WAIT_RECONNECT} retrying reconnect in 5 seconds... (stream_cli.c:244)[0;m Waiting for packet dumper to finish... 1 (prev_count=35024, count=38372) [38;5;43m20251206124832277 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(as-client){AS_PENDING}: Received Event AS-T_REC_EXPD.ind (xua_as_fsm.c:349)[0;m [38;5;43m20251206124832278 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(as-client){AS_PENDING}: T(r) expired; dropping queued messages (xua_as_fsm.c:665)[0;m [38;5;43m20251206124832278 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(as-client){AS_PENDING}: state_chg to AS_DOWN (xua_as_fsm.c:675)[0;m MTC@030e1a54e5a2: External command `/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/ttcn3-tcpdump-stop.sh STP_Tests_M3UA.TC_clnt_asp_act_tmt_loadshare pass' was executed successfully (exit status: 0). MC@030e1a54e5a2: Test execution finished. Execution of [EXECUTE] section finished. emtc MC@030e1a54e5a2: Terminating MTC. MC@030e1a54e5a2: MTC terminated. MC2> exit MC@030e1a54e5a2: Shutting down session. MC@030e1a54e5a2: Shutdown complete.
Comparing expected results '/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/stp/expected-results.xml' against results in 'junit-xml-650.log' -------------------- [1;32mpass[0;m STP_Tests_M3UA.TC_connect_asp_up [1;32mpass[0;m STP_Tests_M3UA.TC_beat [1;32mpass[0;m STP_Tests_M3UA.TC_beat_payload [1;32mpass[0;m STP_Tests_M3UA.TC_beat_timeout [1;32mpass[0;m STP_Tests_M3UA.TC_asp_act [1;32mpass[0;m STP_Tests_M3UA.TC_asp_act_override [1;32mpass[0;m STP_Tests_M3UA.TC_asp_act_loadshare [1;32mpass[0;m STP_Tests_M3UA.TC_asp_act_broadcast [1;32mpass[0;m STP_Tests_M3UA.TC_tmt_override [1;32mpass[0;m STP_Tests_M3UA.TC_tmt_loadshare_roundrobin [1;32mpass[0;m STP_Tests_M3UA.TC_tmt_loadshare_sls [1;32mpass[0;m STP_Tests_M3UA.TC_tmt_broadcast [1;32mpass[0;m STP_Tests_M3UA.TC_act_rctx_data_no_rctx [1;32mpass[0;m STP_Tests_M3UA.TC_m3ua_sctp_srv_adm_shutdown [1;32mpass[0;m STP_Tests_M3UA.TC_combinedlset_loadshare [1;32mpass[0;m STP_Tests_M3UA.TC_inaccessible_sp_duna [1;32mpass[0;m STP_Tests_M3UA.TC_inaccessible_sp_duna2 [1;32mpass[0;m STP_Tests_M3UA.TC_rkm_reg_static_notpermitted [1;32mpass[0;m STP_Tests_M3UA.TC_rkm_reg_static_permitted [1;32mpass[0;m STP_Tests_M3UA.TC_rkm_reg_dynamic_permitted [1;32mpass[0;m STP_Tests_M3UA.TC_rkm_unreg_never_registered [1;32mpass[0;m STP_Tests_M3UA.TC_rkm_unreg_invalid [1;32mpass[0;m STP_Tests_M3UA.TC_rkm_unreg_registered [1;32mpass[0;m STP_Tests_M3UA.TC_rkm_unreg_active [1;32mpass[0;m STP_Tests_M3UA.TC_dyn_asp_as_rkm_permitted [1;32mpass[0;m STP_Tests_M3UA.TC_dyn_2asp_as_rkm_permitted [1;32mpass[0;m STP_Tests_M3UA.TC_clnt_connect_asp_up [1;32mpass[0;m STP_Tests_M3UA.TC_clnt_beat_timeout [1;32mpass[0;m STP_Tests_M3UA.TC_clnt_asp_act [1;32mpass[0;m STP_Tests_M3UA.TC_clnt_sg_to_asp [1;32mpass[0;m STP_Tests_M3UA.TC_clnt_asp_to_sg [1;32mpass[0;m STP_Tests_M3UA.TC_clnt_sg_to_asp_norctx [1;32mpass[0;m STP_Tests_M3UA.TC_clnt_asp_to_sg_norctx [1;32mpass[0;m STP_Tests_M3UA.TC_clnt_quirk_no_notify_asp_act [1;32mpass[0;m STP_Tests_M3UA.TC_clnt_no_daud_in_asp [1;32mpass[0;m STP_Tests_M3UA.TC_clnt_quirk_daud_in_asp [1;32mpass[0;m STP_Tests_M3UA.TC_clnt_no_snm_inactive [1;32mpass[0;m STP_Tests_M3UA.TC_clnt_quirk_snm_inactive [1;32mpass[0;m STP_Tests_M3UA.TC_clnt_sctp_adm_shutdown [1;32mpass[0;m STP_Tests_M3UA.TC_ssnm_aspac_dava_aspia_duna [1;32mpass[0;m STP_Tests_M3UA.TC_ssnm_distribution_dava_duna [1;32mpass[0;m STP_Tests_M3UA.TC_ssnm_distribution_dava_duna_multipc [1;32mpass[0;m STP_Tests_M3UA.TC_ssnm_distribution_dupu [1;32mpass[0;m STP_Tests_M3UA.TC_ssnm_distribution_scon [1;32mpass[0;m STP_Tests_M3UA.TC_clnt_asp_act_tmt_loadshare
Summary: [1;32mpass[0;m: 45 [1;34mskip[0;m: 39
[0;94m[testenv][m3ua] Testsuite is done[0m [1;34m[testenv][m3ua] Stopping testsuite (3093911)[0m [1;34m[testenv][m3ua] Merging log files[0m [0;94m[testenv][m3ua] + ['podman', 'exec', '-e', 'CCACHE_DIR=/home/osmocom-build/ccache/testenv', '-e', 'TESTENV_CACHE_DIR=/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/_cache', '-e', 'TESTENV_SRC_DIR=/home/osmocom-build/jenkins/workspace', '-e', 'TESTENV_INSTALL_DIR=/', '-e', 'TERM=dumb', '-e', 'PATH=/opt/eclipse-titan-11.1.0/bin:/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/_testenv/data/scripts:/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/_testenv/data/scripts/qemu:/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/stp:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin', '-e', 'HOME=/home/osmocom-build', '-e', 'PYTHONUNBUFFERED=1', '-e', 'BUILDDIR=/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/_cache/podman/titan-11.1.0-debian-trixie', '-e', 'TTCN3_DIR=/opt/eclipse-titan-11.1.0', '-t', '-w', '/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/logs/m3ua/testsuite', 'testenv-stp-all-osmocom-latest-20251206-1238-45e3b039-3', 'sh', '-c', '/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/log_merge.sh STP_Tests --rm >/dev/null'][0m [1;34m[testenv][m3ua] Formatting log files[0m [0;94m[testenv][m3ua] + ['podman', 'exec', '-e', 'CCACHE_DIR=/home/osmocom-build/ccache/testenv', '-e', 'TESTENV_CACHE_DIR=/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/_cache', '-e', 'TESTENV_SRC_DIR=/home/osmocom-build/jenkins/workspace', '-e', 'TESTENV_INSTALL_DIR=/', '-e', 'TERM=dumb', '-e', 'PATH=/opt/eclipse-titan-11.1.0/bin:/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/_testenv/data/scripts:/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/_testenv/data/scripts/qemu:/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/stp:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin', '-e', 'HOME=/home/osmocom-build', '-e', 'PYTHONUNBUFFERED=1', '-e', 'BUILDDIR=/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/_cache/podman/titan-11.1.0-debian-trixie', '-e', 'TTCN3_DIR=/opt/eclipse-titan-11.1.0', '-t', '-w', '/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/logs/m3ua/testsuite', 'testenv-stp-all-osmocom-latest-20251206-1238-45e3b039-3', 'sh', '-c', '/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/_testenv/data/scripts/log_format.sh'][0m [1;34m[testenv][m3ua] Stopping stp (3093831)[0m [1;34m[testenv] Stopping podman container (restart)[0m [0;94m[testenv] + ['podman', 'kill', 'testenv-stp-all-osmocom-latest-20251206-1238-45e3b039-3'][0m testenv-stp-all-osmocom-latest-20251206-1238-45e3b039-3 [0;94m[testenv] + ['podman', 'run', '--rm', '--name', 'testenv-stp-all-osmocom-latest-20251206-1238-45e3b039-4', '--log-driver', 'json-file', '--log-opt', 'path=/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/logs/podman/testenv-stp-all-osmocom-latest-20251206-1238-45e3b039-4.log', '--security-opt=seccomp=/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/_testenv/data/podman/seccomp.json', '--cap-add=NET_ADMIN', '--cap-add=NET_RAW', '--cap-add=SYS_ADMIN', '--device=/dev/net/tun', '--volume', '/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/_cache/podman/var-cache-apt-debian-trixie:/var/cache/apt', '--volume', '/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/_cache/podman/var-lib-apt-debian-trixie:/var/lib/apt', '--sysctl', 'net.ipv4.conf.all.send_redirects=0', '--sysctl', 'net.ipv4.conf.default.send_redirects=0', '-e', 'PODMAN=1', '-e', 'TESTENV_BINARY_REPO=1', '--volume', '/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/logs:/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/logs', '--volume', '/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/_cache:/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/_cache', '--volume', '/home/osmocom-build/ccache/testenv:/home/osmocom-build/ccache/testenv', '--volume', '/home/osmocom-build/jenkins/workspace:/home/osmocom-build/jenkins/workspace', 'debian-trixie-osmo-ttcn3-testenv', '/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/_testenv/data/scripts/testenv-podman-main.sh'][0m [0;94m[testenv] + ['podman', 'exec', '-e', 'CCACHE_DIR=/home/osmocom-build/ccache/testenv', '-e', 'TESTENV_CACHE_DIR=/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/_cache', '-e', 'TESTENV_SRC_DIR=/home/osmocom-build/jenkins/workspace', '-e', 'TESTENV_INSTALL_DIR=/', '-e', 'TERM=dumb', '-e', 'PATH=/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/_testenv/data/scripts:/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/_testenv/data/scripts/qemu:/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/stp:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin', '-e', 'HOME=/home/osmocom-build', '-e', 'PYTHONUNBUFFERED=1', '-t', 'testenv-stp-all-osmocom-latest-20251206-1238-45e3b039-4', 'rm', '/etc/apt/apt.conf.d/docker-clean'][0m Running testenv-podman-main.sh [0;94m[testenv][tcap] + ['podman', 'exec', '-e', 'CCACHE_DIR=/home/osmocom-build/ccache/testenv', '-e', 'TESTENV_CACHE_DIR=/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/_cache', '-e', 'TESTENV_SRC_DIR=/home/osmocom-build/jenkins/workspace', '-e', 'TESTENV_INSTALL_DIR=/', '-e', 'TERM=dumb', '-e', 'PATH=/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/_testenv/data/scripts:/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/_testenv/data/scripts/qemu:/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/stp:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin', '-e', 'HOME=/home/osmocom-build', '-e', 'PYTHONUNBUFFERED=1', '-t', 'testenv-stp-all-osmocom-latest-20251206-1238-45e3b039-4', 'sh', '-c', "echo 'deb [signed-by=/obs.key] https://downloads.osmocom.org/packages/osmocom:/latest/Debian_13/ ./' > /etc/apt/sources.list.d/osmocom.list"][0m [0;94m[testenv][tcap] + ['podman', 'exec', '-e', 'CCACHE_DIR=/home/osmocom-build/ccache/testenv', '-e', 'TESTENV_CACHE_DIR=/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/_cache', '-e', 'TESTENV_SRC_DIR=/home/osmocom-build/jenkins/workspace', '-e', 'TESTENV_INSTALL_DIR=/', '-e', 'TERM=dumb', '-e', 'PATH=/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/_testenv/data/scripts:/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/_testenv/data/scripts/qemu:/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/stp:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin', '-e', 'HOME=/home/osmocom-build', '-e', 'PYTHONUNBUFFERED=1', '-t', 'testenv-stp-all-osmocom-latest-20251206-1238-45e3b039-4', 'sh', '-c', 'echo 'Package: *\nPin: origin "downloads.osmocom.org"\nPin-Priority: 1100\n' > /etc/apt/preferences.d/osmocom-apt-pin'][0m [0;94m[testenv] feed_watchdog_loop: podman container has stopped[0m [0;94m[testenv][tcap] + ['podman', 'exec', '-e', 'CCACHE_DIR=/home/osmocom-build/ccache/testenv', '-e', 'TESTENV_CACHE_DIR=/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/_cache', '-e', 'TESTENV_SRC_DIR=/home/osmocom-build/jenkins/workspace', '-e', 'TESTENV_INSTALL_DIR=/', '-e', 'TERM=dumb', '-e', 'PATH=/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/_testenv/data/scripts:/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/_testenv/data/scripts/qemu:/home/osmocom-build/jenkins/workspace/ttcn3-stp-test-latest/stp:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin', '-e', 'HOME=/home/osmocom-build', '-e', 'PYTHONUNBUFFERED=1', '-t', 'testenv-stp-all-osmocom-latest-20251206-1238-45e3b039-4', 'apt-get', '-q', 'update'][0m Ign:1 http://deb.debian.org/debian trixie InRelease Ign:2 https://downloads.osmocom.org/packages/osmocom:/latest/Debian_13 ./ InRelease Ign:3 http://deb.debian.org/debian trixie-updates InRelease Ign:2 https://downloads.osmocom.org/packages/osmocom:/latest/Debian_13 ./ InRelease FATAL: command execution failed Command Close created at at hudson.remoting.Command.<init>(Command.java:69) at hudson.remoting.Channel$CloseCommand.<init>(Channel.java:1431) at hudson.remoting.Channel.close(Channel.java:1610) at hudson.remoting.Channel.close(Channel.java:1574) at hudson.remoting.Channel$CloseCommand.execute(Channel.java:1437) at hudson.remoting.Channel$1.handle(Channel.java:664) at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:86) Caused: hudson.remoting.Channel$OrderlyShutdown at hudson.remoting.Channel$CloseCommand.execute(Channel.java:1438) at hudson.remoting.Channel$1.handle(Channel.java:664) at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:86) Caused: hudson.remoting.ChannelClosedException: Channel "hudson.remoting.Channel@1a00e8d8:build4-deb12build-ansible": Remote call on build4-deb12build-ansible failed. The channel is closing down or has closed down at hudson.remoting.Channel.call(Channel.java:1105) at hudson.remoting.RemoteInvocationHandler.invoke(RemoteInvocationHandler.java:306) at jdk.proxy2/jdk.proxy2.$Proxy68.isAlive(Unknown Source) at hudson.Launcher$RemoteLauncher$ProcImpl.isAlive(Launcher.java:1212) at hudson.Launcher$RemoteLauncher$ProcImpl.join(Launcher.java:1204) at hudson.tasks.CommandInterpreter.join(CommandInterpreter.java:195) at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:145) at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:92) at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20) at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:818) at hudson.model.Build$BuildExecution.build(Build.java:199) at hudson.model.Build$BuildExecution.doRun(Build.java:164) at hudson.model.AbstractBuild$AbstractBuildExecution.run(AbstractBuild.java:527) at hudson.model.Run.execute(Run.java:1833) at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:44) at hudson.model.ResourceController.execute(ResourceController.java:101) at hudson.model.Executor.run(Executor.java:446) FATAL: Unable to delete script file /tmp/jenkins5406105115446944055.sh Command Close created at at hudson.remoting.Command.<init>(Command.java:69) at hudson.remoting.Channel$CloseCommand.<init>(Channel.java:1431) at hudson.remoting.Channel.close(Channel.java:1610) at hudson.remoting.Channel.close(Channel.java:1574) at hudson.remoting.Channel$CloseCommand.execute(Channel.java:1437) at hudson.remoting.Channel$1.handle(Channel.java:664) at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:86) Caused: hudson.remoting.Channel$OrderlyShutdown at hudson.remoting.Channel$CloseCommand.execute(Channel.java:1438) at hudson.remoting.Channel$1.handle(Channel.java:664) at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:86) Caused: hudson.remoting.ChannelClosedException: Channel "hudson.remoting.Channel@1a00e8d8:build4-deb12build-ansible": Remote call on build4-deb12build-ansible failed. The channel is closing down or has closed down at hudson.remoting.Channel.call(Channel.java:1105) at hudson.FilePath.act(FilePath.java:1207) at hudson.FilePath.act(FilePath.java:1196) at hudson.FilePath.delete(FilePath.java:1743) at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:163) at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:92) at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20) at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:818) at hudson.model.Build$BuildExecution.build(Build.java:199) at hudson.model.Build$BuildExecution.doRun(Build.java:164) at hudson.model.AbstractBuild$AbstractBuildExecution.run(AbstractBuild.java:527) at hudson.model.Run.execute(Run.java:1833) at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:44) at hudson.model.ResourceController.execute(ResourceController.java:101) at hudson.model.Executor.run(Executor.java:446) Build step 'Execute shell' marked build as failure ERROR: Step ‘Publish JUnit test result report’ failed: no workspace for ttcn3-stp-test-latest #2211