See https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test-io_uring/202/display/redirect
Changes:
------------------------------------------ [...truncated 16.83 MiB...] MTC@d63b308baed3: Local verdict of PTC VirtCallAgent-STATS(392): none (pass -> pass) MTC@d63b308baed3: Local verdict of PTC IPA-CTRL-CLI-IPA(393): none (pass -> pass) MTC@d63b308baed3: Test case TC_two_crcx_mdcx_and_rtp_osmux_ipv4_ipv6 finished. Verdict: pass MTC@d63b308baed3: Starting external command `https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test-io_uring/ws/_cache/podman/osmo-ttcn3-hacks/ttcn3-tcpdump-stop.sh MGCP_Test.TC_two_crcx_mdcx_and_rtp_osmux_ipv4_ipv6 pass'. Wed Oct 2 11:55:16 UTC 2024 [1;32m====== MGCP_Test.TC_two_crcx_mdcx_and_rtp_osmux_ipv4_ipv6 pass ======[0m
Saving talloc report from 127.0.0.1:4243 to MGCP_Test.TC_two_crcx_mdcx_and_rtp_osmux_ipv4_ipv6.talloc Waiting for packet dumper to finish... 0 (prev_count=-1, count=438504) Waiting for packet dumper to finish... 1 (prev_count=438504, count=468156) MTC@d63b308baed3: External command `https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test-io_uring/ws/_cache/podman/osmo-ttcn3-hacks/ttcn3-tcpdump-stop.sh MGCP_Test.TC_two_crcx_mdcx_and_rtp_osmux_ipv4_ipv6 pass' was executed successfully (exit status: 0). MTC@d63b308baed3: Starting external command `https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test-io_uring/ws/_cache/podman/osmo-ttcn3-hacks/ttcn3-tcpdump-start.sh MGCP_Test.TC_two_crcx_mdcx_and_rtp_osmux_ipv6_ipv4'. ------ MGCP_Test.TC_two_crcx_mdcx_and_rtp_osmux_ipv6_ipv4 ------ Wed Oct 2 11:55:18 UTC 2024 /usr/bin/dumpcap -q -s 1520 -n -i any -w "https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test-io_uring/202/artifact/logs/testsuite/MGCP_Test.TC_two_crcx_mdcx_and_rtp_osmux_ipv6_ipv4.pcap" >https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test-io_uring/202/artifact/logs/testsuite/MGCP_Test.TC_two_crcx_mdcx_and_rtp_osmux_ipv6_ipv4.pcap.stdout 2>/tmp/cmderr & Waiting for packet dumper to start... 0 MTC@d63b308baed3: External command `https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test-io_uring/ws/_cache/podman/osmo-ttcn3-hacks/ttcn3-tcpdump-start.sh MGCP_Test.TC_two_crcx_mdcx_and_rtp_osmux_ipv6_ipv4' was executed successfully (exit status: 0). MTC@d63b308baed3: Test case TC_two_crcx_mdcx_and_rtp_osmux_ipv6_ipv4 started. [38;5;51m20241002115519625 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Received message: line #00: DLCX 36094 rtpbridge/2@mgw MGCP 1.0 (mgcp_msg.c:67)[0;m [38;5;51m20241002115519625 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> endpoint:rtpbridge/2@mgw (trunk:0) found endpoint: rtpbridge/2@mgw (mgcp_endp.c:279)[0;m [38;5;51m20241002115519625 [1;32mDLMGCP[0;m[38;5;51m [1;32mINFO[0;m[38;5;51m <0013> DLCX: executing request handler "DeleteConnection" for endpoint resource "rtpbridge/2@mgw" (mgcp_protocol.c:441)[0;m [38;5;51m20241002115519625 [1;33mDLMGCP[0;m[38;5;51m [1;33mNOTICE[0;m[38;5;51m <0013> endpoint:rtpbridge/2@mgw DLCX: deleting connection(s) ... (mgcp_protocol.c:1382)[0;m [38;5;51m20241002115519625 [1;31mDLMGCP[0;m[38;5;51m [1;31mERROR[0;m[38;5;51m <0013> endpoint:rtpbridge/2@mgw DLCX: endpoint is not holding a connection. (mgcp_protocol.c:1399)[0;m [38;5;51m20241002115519625 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> endpoint:rtpbridge/2@mgw Generated response: code=515 (mgcp_protocol.c:230)[0;m [38;5;51m20241002115519625 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Generated response: line #00: 515 36094 FAIL (mgcp_msg.c:67)[0;m [38;5;51m20241002115519629 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Received message: line #00: CRCX 36095 rtpbridge/2@mgw MGCP 1.0 (mgcp_msg.c:67)[0;m [38;5;51m20241002115519629 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Received message: line #01: M: recvonly (mgcp_msg.c:67)[0;m [38;5;51m20241002115519629 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Received message: line #02: C: 1227 (mgcp_msg.c:67)[0;m [38;5;51m20241002115519629 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Received message: line #03: L: p:20, a:AMR (mgcp_msg.c:67)[0;m [38;5;51m20241002115519629 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Received message: line #04: v=0 (mgcp_msg.c:67)[0;m [38;5;51m20241002115519629 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Received message: line #05: o=- 23 42 IN IP6 ::1 (mgcp_msg.c:67)[0;m [38;5;51m20241002115519629 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Received message: line #06: s=- (mgcp_msg.c:67)[0;m [38;5;51m20241002115519629 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Received message: line #07: c=IN IP6 ::1 (mgcp_msg.c:67)[0;m [38;5;51m20241002115519629 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Received message: line #08: t=0 0 (mgcp_msg.c:67)[0;m [38;5;51m20241002115519629 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Received message: line #09: m=audio 10000 RTP/AVP 112 (mgcp_msg.c:67)[0;m [38;5;51m20241002115519629 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Received message: line #10: a=rtpmap:112 AMR/8000 (mgcp_msg.c:67)[0;m [38;5;51m20241002115519629 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Received message: line #11: a=ptime:20 (mgcp_msg.c:67)[0;m [38;5;51m20241002115519629 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> endpoint:rtpbridge/2@mgw (trunk:0) found endpoint: rtpbridge/2@mgw (mgcp_endp.c:279)[0;m [38;5;51m20241002115519629 [1;32mDLMGCP[0;m[38;5;51m [1;32mINFO[0;m[38;5;51m <0013> CRCX: executing request handler "CreateConnection" for endpoint resource "rtpbridge/2@mgw" (mgcp_protocol.c:441)[0;m [38;5;51m20241002115519629 [1;33mDLMGCP[0;m[38;5;51m [1;33mNOTICE[0;m[38;5;51m <0013> endpoint:rtpbridge/2@mgw CRCX: creating new connection ... (mgcp_protocol.c:881)[0;m [38;5;51m20241002115519629 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> endpoint:rtpbridge/2@mgw conn:(1227/rtp C:16342088 r=NULL:0<->l=:0) (mgcp_msg.c:118)[0;m [38;5;51m20241002115519629 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> endpoint:rtpbridge/2@mgw CI:16342088 connection mode 'recvonly' 1 (mgcp_msg.c:120)[0;m [38;5;51m20241002115519629 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> endpoint:rtpbridge/2@mgw CI:16342088 output_enabled 0 (mgcp_msg.c:125)[0;m [38;5;51m20241002115519629 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> local CX options: lco->pkt_period_max: 20, lco->codec: AMR (mgcp_protocol.c:701)[0;m [38;5;51m20241002115519629 [1;33mDLMGCP[0;m[38;5;51m [1;33mNOTICE[0;m[38;5;51m <0013> endpoint:rtpbridge/2@mgw CI:16342088 Got media info via SDP: port:10000, addr:::1, duration:20, payload-types:[0;m[38;5;51m112=AMR[0;m[38;5;51m [0;m[38;5;51m (mgcp_sdp.c:444)[0;m [38;5;51m20241002115519629 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> endpoint:rtpbridge/2@mgw conn:(1227/rtp C:16342088 r=::1:10000<->l=:0) codecs[0]:(pt:112=DYNAMIC, audio:AMR/8000 subt=AMR, rate=8000, ch=1, t=20/1000)[0;m[38;5;51m [selected][0;m[38;5;51m (mgcp_codec.c:79)[0;m [38;5;51m20241002115519629 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> endpoint:rtpbridge/2@mgw Configuring RTP endpoint: local port 10000 (mgcp_protocol.c:726)[0;m [38;5;51m20241002115519629 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> endpoint:rtpbridge/2@mgw CI:16342088 CRCX: Creating connection: port: 5944 (mgcp_protocol.c:1112)[0;m [38;5;51m20241002115519629 [1;33mDLMGCP[0;m[38;5;51m [1;33mNOTICE[0;m[38;5;51m <0013> endpoint:rtpbridge/2@mgw CI:16342088 CRCX: connection successfully created (mgcp_protocol.c:1121)[0;m [38;5;51m20241002115519629 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> endpoint:rtpbridge/2@mgw Generated response: code=200 (mgcp_protocol.c:230)[0;m [38;5;51m20241002115519629 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Generated response: line #00: 200 36095 OK (mgcp_msg.c:67)[0;m [38;5;51m20241002115519629 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Generated response: line #01: I: 16342088 (mgcp_msg.c:67)[0;m [38;5;51m20241002115519629 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Generated response: line #02: v=0 (mgcp_msg.c:67)[0;m [38;5;51m20241002115519629 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Generated response: line #03: o=- 16342088 23 IN IP6 ::1 (mgcp_msg.c:67)[0;m [38;5;51m20241002115519629 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Generated response: line #04: s=- (mgcp_msg.c:67)[0;m [38;5;51m20241002115519629 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Generated response: line #05: c=IN IP6 ::1 (mgcp_msg.c:67)[0;m [38;5;51m20241002115519629 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Generated response: line #06: t=0 0 (mgcp_msg.c:67)[0;m [38;5;51m20241002115519629 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Generated response: line #07: m=audio 5944 RTP/AVP 112 (mgcp_msg.c:67)[0;m [38;5;51m20241002115519629 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Generated response: line #08: a=rtpmap:112 AMR/8000 (mgcp_msg.c:67)[0;m [38;5;51m20241002115519629 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Generated response: line #09: a=ptime:20 (mgcp_msg.c:67)[0;m [38;5;51m20241002115519631 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Received message: line #00: CRCX 36096 rtpbridge/2@mgw MGCP 1.0 (mgcp_msg.c:67)[0;m [38;5;51m20241002115519631 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Received message: line #01: M: recvonly (mgcp_msg.c:67)[0;m [38;5;51m20241002115519631 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Received message: line #02: C: 1227 (mgcp_msg.c:67)[0;m [38;5;51m20241002115519631 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Received message: line #03: L: p:20, a:AMR (mgcp_msg.c:67)[0;m [38;5;51m20241002115519631 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Received message: line #04: X-OSMUX: 2 (mgcp_msg.c:67)[0;m [38;5;51m20241002115519631 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Received message: line #05: v=0 (mgcp_msg.c:67)[0;m [38;5;51m20241002115519631 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Received message: line #06: o=- 23 42 IN IP4 127.0.0.1 (mgcp_msg.c:67)[0;m [38;5;51m20241002115519631 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Received message: line #07: s=- (mgcp_msg.c:67)[0;m [38;5;51m20241002115519631 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Received message: line #08: c=IN IP4 127.0.0.1 (mgcp_msg.c:67)[0;m [38;5;51m20241002115519631 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Received message: line #09: t=0 0 (mgcp_msg.c:67)[0;m [38;5;51m20241002115519631 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Received message: line #10: m=audio 1985 RTP/AVP 110 (mgcp_msg.c:67)[0;m [38;5;51m20241002115519631 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Received message: line #11: a=rtpmap:110 AMR/8000 (mgcp_msg.c:67)[0;m [38;5;51m20241002115519631 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Received message: line #12: a=ptime:20 (mgcp_msg.c:67)[0;m [38;5;51m20241002115519631 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> endpoint:rtpbridge/2@mgw (trunk:0) found endpoint: rtpbridge/2@mgw (mgcp_endp.c:279)[0;m [38;5;51m20241002115519631 [1;32mDLMGCP[0;m[38;5;51m [1;32mINFO[0;m[38;5;51m <0013> CRCX: executing request handler "CreateConnection" for endpoint resource "rtpbridge/2@mgw" (mgcp_protocol.c:441)[0;m [38;5;51m20241002115519631 [1;33mDLMGCP[0;m[38;5;51m [1;33mNOTICE[0;m[38;5;51m <0013> endpoint:rtpbridge/2@mgw CRCX: creating new connection ... (mgcp_protocol.c:881)[0;m [38;5;51m20241002115519631 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> MGCP client offered Osmux CID 2 (mgcp_msg.c:207)[0;m [38;5;51m20241002115519631 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> endpoint:rtpbridge/2@mgw conn:(1227/rtp C:E23B81FD r=NULL:0<->l=:0) (mgcp_msg.c:118)[0;m [38;5;51m20241002115519631 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> endpoint:rtpbridge/2@mgw CI:E23B81FD connection mode 'recvonly' 1 (mgcp_msg.c:120)[0;m [38;5;51m20241002115519631 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> endpoint:rtpbridge/2@mgw CI:E23B81FD output_enabled 0 (mgcp_msg.c:125)[0;m [38;5;51m20241002115519631 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> local CX options: lco->pkt_period_max: 20, lco->codec: AMR (mgcp_protocol.c:701)[0;m [38;5;51m20241002115519631 [1;33mDLMGCP[0;m[38;5;51m [1;33mNOTICE[0;m[38;5;51m <0013> endpoint:rtpbridge/2@mgw CI:E23B81FD Got media info via SDP: port:1985, addr:127.0.0.1, duration:20, payload-types:[0;m[38;5;51m110=AMR[0;m[38;5;51m [0;m[38;5;51m (mgcp_sdp.c:444)[0;m [38;5;51m20241002115519631 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> endpoint:rtpbridge/2@mgw conn:(1227/rtp C:E23B81FD r=127.0.0.1:1985<->l=:0 CID=16) codecs[0]:(pt:110=DYNAMIC, audio:AMR/8000 subt=AMR, rate=8000, ch=1, t=20/1000)[0;m[38;5;51m [selected][0;m[38;5;51m (mgcp_codec.c:79)[0;m [38;5;51m20241002115519631 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> endpoint:rtpbridge/2@mgw Configuring RTP endpoint: local port 1985 (mgcp_protocol.c:726)[0;m [38;5;51m20241002115519631 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> endpoint:rtpbridge/2@mgw CI:E23B81FD CRCX: Creating connection: port: 5946 (mgcp_protocol.c:1112)[0;m [38;5;51m20241002115519631 [1;33mDLMGCP[0;m[38;5;51m [1;33mNOTICE[0;m[38;5;51m <0013> endpoint:rtpbridge/2@mgw CI:E23B81FD CRCX: connection successfully created (mgcp_protocol.c:1121)[0;m [38;5;51m20241002115519631 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> endpoint:rtpbridge/2@mgw Generated response: code=200 (mgcp_protocol.c:230)[0;m [38;5;51m20241002115519631 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Generated response: line #00: 200 36096 OK (mgcp_msg.c:67)[0;m [38;5;51m20241002115519632 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Generated response: line #01: I: E23B81FD (mgcp_msg.c:67)[0;m [38;5;51m20241002115519632 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Generated response: line #02: X-Osmux: 16 (mgcp_msg.c:67)[0;m [38;5;51m20241002115519632 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Generated response: line #03: v=0 (mgcp_msg.c:67)[0;m [38;5;51m20241002115519632 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Generated response: line #04: o=- E23B81FD 23 IN IP4 127.0.0.1 (mgcp_msg.c:67)[0;m [38;5;51m20241002115519632 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Generated response: line #05: s=- (mgcp_msg.c:67)[0;m [38;5;51m20241002115519632 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Generated response: line #06: c=IN IP4 127.0.0.1 (mgcp_msg.c:67)[0;m [38;5;51m20241002115519632 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Generated response: line #07: t=0 0 (mgcp_msg.c:67)[0;m [38;5;51m20241002115519632 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Generated response: line #08: m=audio 1984 RTP/AVP 110 (mgcp_msg.c:67)[0;m [38;5;51m20241002115519632 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Generated response: line #09: a=rtpmap:110 AMR/8000 (mgcp_msg.c:67)[0;m [38;5;51m20241002115519632 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Generated response: line #10: a=ptime:20 (mgcp_msg.c:67)[0;m RTPEM0(394)@d63b308baed3: Warning: Re-starting timer T_transmit, which is already active (running or expired). [38;5;51m20241002115521138 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Received message: line #00: MDCX 36097 rtpbridge/2@mgw MGCP 1.0 (mgcp_msg.c:67)[0;m [38;5;51m20241002115521138 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Received message: line #01: M: sendrecv (mgcp_msg.c:67)[0;m [38;5;51m20241002115521138 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Received message: line #02: C: 1227 (mgcp_msg.c:67)[0;m [38;5;51m20241002115521138 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Received message: line #03: I: 16342088 (mgcp_msg.c:67)[0;m [38;5;51m20241002115521138 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Received message: line #04: L: p:20, a:AMR (mgcp_msg.c:67)[0;m [38;5;51m20241002115521138 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Received message: line #05: v=0 (mgcp_msg.c:67)[0;m [38;5;51m20241002115521138 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Received message: line #06: o=- 23 42 IN IP6 ::1 (mgcp_msg.c:67)[0;m [38;5;51m20241002115521138 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Received message: line #07: s=- (mgcp_msg.c:67)[0;m [38;5;51m20241002115521138 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Received message: line #08: c=IN IP6 ::1 (mgcp_msg.c:67)[0;m [38;5;51m20241002115521138 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Received message: line #09: t=0 0 (mgcp_msg.c:67)[0;m [38;5;51m20241002115521138 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Received message: line #10: m=audio 10000 RTP/AVP 112 (mgcp_msg.c:67)[0;m [38;5;51m20241002115521138 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Received message: line #11: a=rtpmap:112 AMR/8000 (mgcp_msg.c:67)[0;m [38;5;51m20241002115521138 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Received message: line #12: a=ptime:20 (mgcp_msg.c:67)[0;m [38;5;51m20241002115521138 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> endpoint:rtpbridge/2@mgw (trunk:0) found endpoint: rtpbridge/2@mgw (mgcp_endp.c:279)[0;m [38;5;51m20241002115521138 [1;32mDLMGCP[0;m[38;5;51m [1;32mINFO[0;m[38;5;51m <0013> MDCX: executing request handler "ModifiyConnection" for endpoint resource "rtpbridge/2@mgw" (mgcp_protocol.c:441)[0;m [38;5;51m20241002115521138 [1;33mDLMGCP[0;m[38;5;51m [1;33mNOTICE[0;m[38;5;51m <0013> endpoint:rtpbridge/2@mgw MDCX: modifying existing connection ... (mgcp_protocol.c:1155)[0;m [38;5;51m20241002115521138 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> endpoint:rtpbridge/2@mgw conn:(1227/rtp C:16342088 r=::1:10000<->l=::1:5944) (mgcp_msg.c:118)[0;m [38;5;51m20241002115521138 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> endpoint:rtpbridge/2@mgw CI:16342088 connection mode 'sendrecv' 3 (mgcp_msg.c:120)[0;m [38;5;51m20241002115521138 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> endpoint:rtpbridge/2@mgw CI:16342088 output_enabled 1 (mgcp_msg.c:125)[0;m [38;5;51m20241002115521138 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> local CX options: lco->pkt_period_max: 20, lco->codec: AMR (mgcp_protocol.c:701)[0;m [38;5;51m20241002115521138 [1;33mDLMGCP[0;m[38;5;51m [1;33mNOTICE[0;m[38;5;51m <0013> endpoint:rtpbridge/2@mgw CI:16342088 Got media info via SDP: port:10000, addr:::1, duration:20, payload-types:[0;m[38;5;51m112=AMR[0;m[38;5;51m [0;m[38;5;51m (mgcp_sdp.c:444)[0;m [38;5;51m20241002115521138 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> endpoint:rtpbridge/2@mgw conn:(1227/rtp C:16342088 r=::1:10000<->l=::1:5944) codecs[0]:(pt:112=DYNAMIC, audio:AMR/8000 subt=AMR, rate=8000, ch=1, t=20/1000)[0;m[38;5;51m [selected][0;m[38;5;51m (mgcp_codec.c:79)[0;m [38;5;51m20241002115521138 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> endpoint:rtpbridge/2@mgw Configuring RTP endpoint: local port 10000 (mgcp_protocol.c:726)[0;m [38;5;51m20241002115521138 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> endpoint:rtpbridge/2@mgw CI:16342088 MDCX: modified conn:(1227/rtp C:16342088 r=::1:10000<->l=::1:5944) (mgcp_protocol.c:1338)[0;m [38;5;51m20241002115521138 [1;33mDLMGCP[0;m[38;5;51m [1;33mNOTICE[0;m[38;5;51m <0013> endpoint:rtpbridge/2@mgw CI:16342088 MDCX: connection successfully modified (mgcp_protocol.c:1351)[0;m [38;5;51m20241002115521138 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> endpoint:rtpbridge/2@mgw Generated response: code=200 (mgcp_protocol.c:230)[0;m [38;5;51m20241002115521138 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Generated response: line #00: 200 36097 OK (mgcp_msg.c:67)[0;m [38;5;51m20241002115521138 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Generated response: line #01: v=0 (mgcp_msg.c:67)[0;m [38;5;51m20241002115521138 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Generated response: line #02: o=- 16342088 23 IN IP6 ::1 (mgcp_msg.c:67)[0;m [38;5;51m20241002115521138 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Generated response: line #03: s=- (mgcp_msg.c:67)[0;m [38;5;51m20241002115521138 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Generated response: line #04: c=IN IP6 ::1 (mgcp_msg.c:67)[0;m [38;5;51m20241002115521138 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Generated response: line #05: t=0 0 (mgcp_msg.c:67)[0;m [38;5;51m20241002115521138 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Generated response: line #06: m=audio 5944 RTP/AVP 112 (mgcp_msg.c:67)[0;m [38;5;51m20241002115521138 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Generated response: line #07: a=rtpmap:112 AMR/8000 (mgcp_msg.c:67)[0;m [38;5;51m20241002115521138 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Generated response: line #08: a=ptime:20 (mgcp_msg.c:67)[0;m OsmuxEM(397)@d63b308baed3: Warning: Re-starting timer T_transmit, which is already active (running or expired). [38;5;51m20241002115521642 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Received message: line #00: MDCX 36098 rtpbridge/2@mgw MGCP 1.0 (mgcp_msg.c:67)[0;m [38;5;51m20241002115521642 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Received message: line #01: M: sendrecv (mgcp_msg.c:67)[0;m [38;5;51m20241002115521642 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Received message: line #02: C: 1227 (mgcp_msg.c:67)[0;m [38;5;51m20241002115521642 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Received message: line #03: I: E23B81FD (mgcp_msg.c:67)[0;m [38;5;51m20241002115521642 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Received message: line #04: L: p:20, a:AMR (mgcp_msg.c:67)[0;m [38;5;51m20241002115521642 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Received message: line #05: X-OSMUX: 2 (mgcp_msg.c:67)[0;m [38;5;51m20241002115521642 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Received message: line #06: v=0 (mgcp_msg.c:67)[0;m [38;5;51m20241002115521642 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Received message: line #07: o=- 23 42 IN IP4 127.0.0.1 (mgcp_msg.c:67)[0;m [38;5;51m20241002115521642 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Received message: line #08: s=- (mgcp_msg.c:67)[0;m [38;5;51m20241002115521642 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Received message: line #09: c=IN IP4 127.0.0.1 (mgcp_msg.c:67)[0;m [38;5;51m20241002115521642 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Received message: line #10: t=0 0 (mgcp_msg.c:67)[0;m [38;5;51m20241002115521642 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Received message: line #11: m=audio 1985 RTP/AVP 110 (mgcp_msg.c:67)[0;m [38;5;51m20241002115521642 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Received message: line #12: a=rtpmap:110 AMR/8000 (mgcp_msg.c:67)[0;m [38;5;51m20241002115521642 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Received message: line #13: a=ptime:20 (mgcp_msg.c:67)[0;m [38;5;51m20241002115521642 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> endpoint:rtpbridge/2@mgw (trunk:0) found endpoint: rtpbridge/2@mgw (mgcp_endp.c:279)[0;m [38;5;51m20241002115521642 [1;32mDLMGCP[0;m[38;5;51m [1;32mINFO[0;m[38;5;51m <0013> MDCX: executing request handler "ModifiyConnection" for endpoint resource "rtpbridge/2@mgw" (mgcp_protocol.c:441)[0;m [38;5;51m20241002115521642 [1;33mDLMGCP[0;m[38;5;51m [1;33mNOTICE[0;m[38;5;51m <0013> endpoint:rtpbridge/2@mgw MDCX: modifying existing connection ... (mgcp_protocol.c:1155)[0;m [38;5;51m20241002115521642 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> MGCP client offered Osmux CID 2 (mgcp_msg.c:207)[0;m [38;5;51m20241002115521642 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> endpoint:rtpbridge/2@mgw conn:(1227/rtp C:E23B81FD r=127.0.0.1:1985<->l=127.0.0.1:5946 CID=16) (mgcp_msg.c:118)[0;m [38;5;51m20241002115521642 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> endpoint:rtpbridge/2@mgw CI:E23B81FD connection mode 'sendrecv' 3 (mgcp_msg.c:120)[0;m [38;5;51m20241002115521642 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> endpoint:rtpbridge/2@mgw CI:E23B81FD output_enabled 1 (mgcp_msg.c:125)[0;m [38;5;51m20241002115521642 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> local CX options: lco->pkt_period_max: 20, lco->codec: AMR (mgcp_protocol.c:701)[0;m [38;5;51m20241002115521642 [1;33mDLMGCP[0;m[38;5;51m [1;33mNOTICE[0;m[38;5;51m <0013> endpoint:rtpbridge/2@mgw CI:E23B81FD Got media info via SDP: port:1985, addr:127.0.0.1, duration:20, payload-types:[0;m[38;5;51m110=AMR[0;m[38;5;51m [0;m[38;5;51m (mgcp_sdp.c:444)[0;m [38;5;51m20241002115521642 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> endpoint:rtpbridge/2@mgw conn:(1227/rtp C:E23B81FD r=127.0.0.1:1985<->l=127.0.0.1:5946 CID=16) codecs[0]:(pt:110=DYNAMIC, audio:AMR/8000 subt=AMR, rate=8000, ch=1, t=20/1000)[0;m[38;5;51m [selected][0;m[38;5;51m (mgcp_codec.c:79)[0;m [38;5;51m20241002115521642 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> endpoint:rtpbridge/2@mgw Configuring RTP endpoint: local port 1985 (mgcp_protocol.c:726)[0;m [38;5;51m20241002115521642 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> endpoint:rtpbridge/2@mgw CI:E23B81FD MDCX: modified conn:(1227/rtp C:E23B81FD r=127.0.0.1:1985<->l=127.0.0.1:5946 CID=16) (mgcp_protocol.c:1338)[0;m [38;5;51m20241002115521642 [1;33mDLMGCP[0;m[38;5;51m [1;33mNOTICE[0;m[38;5;51m <0013> endpoint:rtpbridge/2@mgw CI:E23B81FD MDCX: connection successfully modified (mgcp_protocol.c:1351)[0;m [38;5;51m20241002115521642 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> endpoint:rtpbridge/2@mgw Generated response: code=200 (mgcp_protocol.c:230)[0;m [38;5;51m20241002115521642 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Generated response: line #00: 200 36098 OK (mgcp_msg.c:67)[0;m [38;5;51m20241002115521642 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Generated response: line #01: X-Osmux: 16 (mgcp_msg.c:67)[0;m [38;5;51m20241002115521642 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Generated response: line #02: v=0 (mgcp_msg.c:67)[0;m [38;5;51m20241002115521642 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Generated response: line #03: o=- E23B81FD 23 IN IP4 127.0.0.1 (mgcp_msg.c:67)[0;m [38;5;51m20241002115521642 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Generated response: line #04: s=- (mgcp_msg.c:67)[0;m [38;5;51m20241002115521642 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Generated response: line #05: c=IN IP4 127.0.0.1 (mgcp_msg.c:67)[0;m [38;5;51m20241002115521642 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Generated response: line #06: t=0 0 (mgcp_msg.c:67)[0;m [38;5;51m20241002115521642 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Generated response: line #07: m=audio 1984 RTP/AVP 110 (mgcp_msg.c:67)[0;m [38;5;51m20241002115521642 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Generated response: line #08: a=rtpmap:110 AMR/8000 (mgcp_msg.c:67)[0;m [38;5;51m20241002115521642 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Generated response: line #09: a=ptime:20 (mgcp_msg.c:67)[0;m MTC@d63b308baed3: stats: { num_pkts_tx := 196, bytes_payload_tx := 3332, num_pkts_rx := 115, bytes_payload_rx := 1955, num_pkts_rx_err_seq := 0, num_pkts_rx_err_ts := 0, num_pkts_rx_err_pt := 0, num_pkts_rx_err_disabled := 0, num_pkts_rx_err_payload := 0 } MTC@d63b308baed3: stats: { num_pkts_tx := 41, bytes_payload_tx := 2460, num_pkts_rx := 24, bytes_payload_rx := 1440, num_pkts_rx_err_seq := 0, num_pkts_rx_err_disabled := 0, num_pkts_rx_err_payload := 0 } [38;5;51m20241002115523752 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Received message: line #00: DLCX 36099 rtpbridge/2@mgw MGCP 1.0 (mgcp_msg.c:67)[0;m [38;5;51m20241002115523752 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Received message: line #01: C: 1227 (mgcp_msg.c:67)[0;m [38;5;51m20241002115523752 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> endpoint:rtpbridge/2@mgw (trunk:0) found endpoint: rtpbridge/2@mgw (mgcp_endp.c:279)[0;m [38;5;51m20241002115523752 [1;32mDLMGCP[0;m[38;5;51m [1;32mINFO[0;m[38;5;51m <0013> DLCX: executing request handler "DeleteConnection" for endpoint resource "rtpbridge/2@mgw" (mgcp_protocol.c:441)[0;m [38;5;51m20241002115523752 [1;33mDLMGCP[0;m[38;5;51m [1;33mNOTICE[0;m[38;5;51m <0013> endpoint:rtpbridge/2@mgw DLCX: deleting connection(s) ... (mgcp_protocol.c:1382)[0;m [38;5;51m20241002115523752 [1;33mDLMGCP[0;m[38;5;51m [1;33mNOTICE[0;m[38;5;51m <0013> endpoint:rtpbridge/2@mgw DLCX: missing ci (connectionIdentifier), will remove all connections (2 total) at once (mgcp_protocol.c:1477)[0;m [38;5;51m20241002115523752 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> endpoint:rtpbridge/2@mgw Releasing endpoint (mgcp_endp.c:662)[0;m [38;5;51m20241002115523752 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> endpoint:rtpbridge/2@mgw Releasing endpoint (mgcp_endp.c:662)[0;m [38;5;51m20241002115523752 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> endpoint:rtpbridge/2@mgw Generated response: code=200 (mgcp_protocol.c:230)[0;m [38;5;51m20241002115523752 [1;34mDLMGCP[0;m[38;5;51m [1;34mDEBUG[0;m[38;5;51m <0013> Generated response: line #00: 200 36099 OK (mgcp_msg.c:67)[0;m MTC@d63b308baed3: setverdict(pass): none -> pass OsmuxEM(397)@d63b308baed3: Final verdict of PTC: none RTPEM2(396)@d63b308baed3: Final verdict of PTC: none RTPEM0(394)@d63b308baed3: Final verdict of PTC: none IPA-CTRL-CLI-IPA(399)@d63b308baed3: Final verdict of PTC: none RTPEM1(395)@d63b308baed3: Final verdict of PTC: none VirtCallAgent-STATS(398)@d63b308baed3: Final verdict of PTC: none MTC@d63b308baed3: Setting final verdict of the test case. MTC@d63b308baed3: Local verdict of MTC: pass MTC@d63b308baed3: Local verdict of PTC RTPEM0(394): none (pass -> pass) MTC@d63b308baed3: Local verdict of PTC RTPEM1(395): none (pass -> pass) MTC@d63b308baed3: Local verdict of PTC RTPEM2(396): none (pass -> pass) MTC@d63b308baed3: Local verdict of PTC OsmuxEM(397): none (pass -> pass) MTC@d63b308baed3: Local verdict of PTC VirtCallAgent-STATS(398): none (pass -> pass) MTC@d63b308baed3: Local verdict of PTC IPA-CTRL-CLI-IPA(399): none (pass -> pass) MTC@d63b308baed3: Test case TC_two_crcx_mdcx_and_rtp_osmux_ipv6_ipv4 finished. Verdict: pass MTC@d63b308baed3: Starting external command `https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test-io_uring/ws/_cache/podman/osmo-ttcn3-hacks/ttcn3-tcpdump-stop.sh MGCP_Test.TC_two_crcx_mdcx_and_rtp_osmux_ipv6_ipv4 pass'. Wed Oct 2 11:55:23 UTC 2024 [1;32m====== MGCP_Test.TC_two_crcx_mdcx_and_rtp_osmux_ipv6_ipv4 pass ======[0m
Saving talloc report from 127.0.0.1:4243 to MGCP_Test.TC_two_crcx_mdcx_and_rtp_osmux_ipv6_ipv4.talloc Waiting for packet dumper to finish... 0 (prev_count=-1, count=434976) Waiting for packet dumper to finish... 1 (prev_count=434976, count=472560) MTC@d63b308baed3: External command `https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test-io_uring/ws/_cache/podman/osmo-ttcn3-hacks/ttcn3-tcpdump-stop.sh MGCP_Test.TC_two_crcx_mdcx_and_rtp_osmux_ipv6_ipv4 pass' was executed successfully (exit status: 0). MTC@d63b308baed3: Starting external command `https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test-io_uring/ws/_cache/podman/osmo-ttcn3-hacks/ttcn3-tcpdump-start.sh MGCP_Test.TC_two_crcx_mdcx_and_iuup'. ------ MGCP_Test.TC_two_crcx_mdcx_and_iuup ------ Wed Oct 2 11:55:25 UTC 2024 /usr/bin/dumpcap -q -s 1520 -n -i any -w "https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test-io_uring/202/artifact/logs/testsuite/MGCP_Test.TC_two_crcx_mdcx_and_iuup.pcap" >https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test-io_uring/202/artifact/logs/testsuite/MGCP_Test.TC_two_crcx_mdcx_and_iuup.pcap.stdout 2>/tmp/cmderr & Waiting for packet dumper to start... 0 Error: can only start exec sessions when their container is running: container state improper [1;91m[testenv] podman container crashed![0m [0;94m[testenv] Testsuite is done[0m [1;34m[testenv] Stopping testsuite (651799)[0m [1;34m[testenv] Merging log files[0m [0;94m[testenv] + ['podman', 'exec', '-e', 'CCACHE_DIR=/home/osmocom-build/ccache/testenv', '-e', 'TESTENV_CACHE_DIR=https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test-io_uring/ws/_cache', '-e', 'TESTENV_SRC_DIR=/home/osmocom-build/jenkins/workspace', '-e', 'TERM=dumb', '-e', 'TESTENV_GIT_DIR=https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test-io_uring/ws/_cache/git', '-e', 'PATH=https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test-io_uring/ws/_testenv/data/scripts:https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test-io_uring/ws/_cache/podman/osmo-ttcn3-hacks/mgw:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin', '-e', 'HOME=/home/osmocom-build', '-t', '-w', 'https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test-io_uring/202/artifact/logs/testsuite', 'testenv-mgw-osmocom-nightly-20241002-1148-3fb3d642', 'sh', '-c', 'https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test-io_uring/ws/_cache/podman/osmo-ttcn3-hacks/log_merge.sh MGCP_Test --rm >/dev/null'][0m Error: no container with name or ID "testenv-mgw-osmocom-nightly-20241002-1148-3fb3d642" found: no such container [1;91m[testenv] Command failed unexpectedly[0m [0;94m[testenv] Command: ['podman', 'exec', '-e', 'CCACHE_DIR=/home/osmocom-build/ccache/testenv', '-e', 'TESTENV_CACHE_DIR=https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test-io_uring/ws/_cache', '-e', 'TESTENV_SRC_DIR=/home/osmocom-build/jenkins/workspace', '-e', 'TERM=dumb', '-e', 'TESTENV_GIT_DIR=https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test-io_uring/ws/_cache/git', '-e', 'PATH=https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test-io_uring/ws/_testenv/data/scripts:https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test-io_uring/ws/_cache/podman/osmo-ttcn3-hacks/mgw:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin', '-e', 'HOME=/home/osmocom-build', '-t', '-w', 'https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test-io_uring/202/artifact/logs/testsuite', 'testenv-mgw-osmocom-nightly-20241002-1148-3fb3d642', 'sh', '-c', 'https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test-io_uring/ws/_cache/podman/osmo-ttcn3-hacks/log_merge.sh MGCP_Test --rm >/dev/null'][0m [0;94m[testenv] Returncode: 125[0m Traceback (most recent call last): File "https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test-io_uring/ws/./testenv.py", line 115, in <module> main() File "https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test-io_uring/ws/./testenv.py", line 104, in main run() File "https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test-io_uring/ws/./testenv.py", line 63, in run testenv.testsuite.run(cfg) File "https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test-io_uring/ws/_testenv/testenv/testsuite.py", line 204, in run merge_log_files(cfg) File "https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test-io_uring/ws/_testenv/testenv/testsuite.py", line 130, in merge_log_files testenv.cmd.run(cmd, cwd=cwd) File "https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test-io_uring/ws/_testenv/testenv/cmd.py", line 93, in run return testenv.podman.exec_cmd(cmd, check=check, env=env, *args, **kwargs) ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test-io_uring/ws/_testenv/testenv/podman.py", line 148, in exec_cmd testenv.cmd.run( File "https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test-io_uring/ws/_testenv/testenv/cmd.py", line 110, in run exit_error_cmd(p, "Command failed unexpectedly") File "https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test-io_uring/ws/_testenv/testenv/cmd.py", line 59, in exit_error_cmd raise RuntimeError("shell command related error, find details right above this python trace") RuntimeError: shell command related error, find details right above this python trace [1;34m[testenv] Logs saved to: https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test-io_uring/202/artifact... [0m Build step 'Execute shell' marked build as failure Recording test results [Checks API] No suitable checks publisher found.