jenkins-notifications January 2023

jenkins-notifications@lists.osmocom.org

1 participants
107 discussions

Build failed in Jenkins: TTCN3-centos-bsc-test-latest #681

by jenkins＠lists.osmocom.org

See <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-bsc-test-latest/681/di…> Changes: [Oliver Smith] ttcn3-*/jenkins*.sh: use $SUBNET everywhere [Oliver Smith] network_create: find free subnet automatically ------------------------------------------ [...truncated 86.78 KB...] VirtSMLC-SCCP(68)@f5d59393ffa8: Final verdict of PTC: none VirtSMLC-BSSAP_LE(69)@f5d59393ffa8: Final verdict of PTC: none IPA-BTS0-TRX0-RSL-RSL(74)@f5d59393ffa8: Final verdict of PTC: none VirtSMLC-M3UA(70)@f5d59393ffa8: Final verdict of PTC: none IPA-BTS0-TRX0-RSL-IPA(73)@f5d59393ffa8: Final verdict of PTC: none VirtMGW-MGCP-0(72)@f5d59393ffa8: Final verdict of PTC: none MTC@f5d59393ffa8: Setting final verdict of the test case. MTC@f5d59393ffa8: Local verdict of MTC: pass MTC@f5d59393ffa8: Local verdict of PTC VirtMSC-STATS(64): none (pass -> pass) MTC@f5d59393ffa8: Local verdict of PTC VirtMSC-SCCP(65): none (pass -> pass) MTC@f5d59393ffa8: Local verdict of PTC VirtMSC-RAN(66): none (pass -> pass) MTC@f5d59393ffa8: Local verdict of PTC VirtMSC-M3UA(67): none (pass -> pass) MTC@f5d59393ffa8: Local verdict of PTC VirtSMLC-SCCP(68): none (pass -> pass) MTC@f5d59393ffa8: Local verdict of PTC VirtSMLC-BSSAP_LE(69): none (pass -> pass) MTC@f5d59393ffa8: Local verdict of PTC VirtSMLC-M3UA(70): none (pass -> pass) MTC@f5d59393ffa8: Local verdict of PTC IPA-CTRL-CLI-IPA(71): none (pass -> pass) MTC@f5d59393ffa8: Local verdict of PTC VirtMGW-MGCP-0(72): none (pass -> pass) MTC@f5d59393ffa8: Local verdict of PTC IPA-BTS0-TRX0-RSL-IPA(73): none (pass -> pass) MTC@f5d59393ffa8: Local verdict of PTC IPA-BTS0-TRX0-RSL-RSL(74): none (pass -> pass) MTC@f5d59393ffa8: Local verdict of PTC TC_stat_num_bts_connected_1(75): none (pass -> pass) MTC@f5d59393ffa8: Test case TC_stat_num_bts_connected_1 finished. Verdict: pass MTC@f5d59393ffa8: Starting external command `../ttcn3-tcpdump-stop.sh BSC_Tests.TC_stat_num_bts_connected_1 pass'. Tue Jan 17 06:01:05 UTC 2023 [1;32m====== BSC_Tests.TC_stat_num_bts_connected_1 pass ======[0m Saving talloc report from 172.18.138.20:4242 to BSC_Tests.TC_stat_num_bts_connected_1.talloc Waiting for packet dumper to finish... 0 (prev_count=-1, count=265384) Waiting for packet dumper to finish... 1 (prev_count=265384, count=442883) MTC@f5d59393ffa8: External command `../ttcn3-tcpdump-stop.sh BSC_Tests.TC_stat_num_bts_connected_1 pass' was executed successfully (exit status: 0). MTC@f5d59393ffa8: Starting external command `../ttcn3-tcpdump-start.sh BSC_Tests.TC_stat_num_bts_connected_2'. ------ BSC_Tests.TC_stat_num_bts_connected_2 ------ Tue Jan 17 06:01:10 UTC 2023 NOTE: unable to use dumpcap due to missing capabilities or suid bit Waiting for packet dumper to start... 0 MTC@f5d59393ffa8: External command `../ttcn3-tcpdump-start.sh BSC_Tests.TC_stat_num_bts_connected_2' was executed successfully (exit status: 0). MTC@f5d59393ffa8: Test case TC_stat_num_bts_connected_2 started. MTC@f5d59393ffa8: Connecting BSSAP RAN_Emulation to SCCP_SP_PORT VirtMSC-M3UA(79)@f5d59393ffa8: ************************************************* VirtMSC-M3UA(79)@f5d59393ffa8: M3UA emulation initiated, the test can be started VirtMSC-M3UA(79)@f5d59393ffa8: ************************************************* MTC@f5d59393ffa8: Starting RAN_Emulation VirtMSC-SCCP(77)@f5d59393ffa8: v_sccp_pdu_maxlen:268 MTC@f5d59393ffa8: Connecting BSSAP_LE_Emulation to SCCP_SP_PORT MTC@f5d59393ffa8: Starting BSSAP_LE_Emulation VirtSMLC-M3UA(82)@f5d59393ffa8: ************************************************* VirtSMLC-M3UA(82)@f5d59393ffa8: M3UA emulation initiated, the test can be started VirtSMLC-M3UA(82)@f5d59393ffa8: ************************************************* VirtSMLC-SCCP(80)@f5d59393ffa8: v_sccp_pdu_maxlen:268 VirtMSC-M3UA(79)@f5d59393ffa8: SCTP_ConnectResult -> connection established from: "172.18.138.203":23905 to server: "172.18.138.200":2905 association #8 VirtSMLC-M3UA(82)@f5d59393ffa8: SCTP_ConnectResult -> connection established from: "172.18.138.203":23908 to server: "172.18.138.200":2905 association #8 VirtMSC-RAN(78)@f5d59393ffa8: BSSMAP: Received RESET-ACK in response to RESET, we're ready to go! VirtSMLC-BSSAP_LE(81)@f5d59393ffa8: BSSMAP-LE: Received RESET-ACK in response to RESET, we're ready to go! IPA-BTS0-TRX0-RSL-IPA(85)@f5d59393ffa8: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } IPA-BTS0-TRX0-RSL-IPA(85)@f5d59393ffa8: CCM Rx:{ msg_type := IPAC_MSGT_ID_GET (4), u := { get := { { len := 1, tag := IPAC_IDTAG_UNITID (8) }, { len := 1, tag := IPAC_IDTAG_MACADDR (7) }, { len := 1, tag := IPAC_IDTAG_LOCATION1 (2) }, { len := 1, tag := IPAC_IDTAG_LOCATION2 (3) }, { len := 1, tag := IPAC_IDTAG_EQUIPVERS (4) }, { len := 1, tag := IPAC_IDTAG_SWVERSION (5) }, { len := 1, tag := IPAC_IDTAG_UNITNAME (1) }, { len := 1, tag := IPAC_IDTAG_SERNR (0) } } } } IPA-BTS0-TRX0-RSL-IPA(85)@f5d59393ffa8: CCM Tx:{ msg_type := IPAC_MSGT_ID_RESP (5), u := { resp := { { len := 0, tag := IPAC_IDTAG_UNITID (8), data := '313233342F302F3000'O }, { len := 0, tag := IPAC_IDTAG_MACADDR (7), data := '00'O }, { len := 0, tag := IPAC_IDTAG_LOCATION1 (2), data := '00'O }, { len := 0, tag := IPAC_IDTAG_LOCATION2 (3), data := '00'O }, { len := 0, tag := IPAC_IDTAG_EQUIPVERS (4), data := '00'O }, { len := 0, tag := IPAC_IDTAG_SWVERSION (5), data := '00'O }, { len := 0, tag := IPAC_IDTAG_UNITNAME (1), data := '4F736D6F636F6D205454434E2D33204254532053696D756C61746F7200'O }, { len := 0, tag := IPAC_IDTAG_SERNR (0), data := '00'O } } } } IPA-BTS0-TRX0-RSL-IPA(85)@f5d59393ffa8: CCM Rx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } IPA-BTS1-TRX0-RSL-IPA(87)@f5d59393ffa8: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } IPA-BTS1-TRX0-RSL-IPA(87)@f5d59393ffa8: CCM Rx:{ msg_type := IPAC_MSGT_ID_GET (4), u := { get := { { len := 1, tag := IPAC_IDTAG_UNITID (8) }, { len := 1, tag := IPAC_IDTAG_MACADDR (7) }, { len := 1, tag := IPAC_IDTAG_LOCATION1 (2) }, { len := 1, tag := IPAC_IDTAG_LOCATION2 (3) }, { len := 1, tag := IPAC_IDTAG_EQUIPVERS (4) }, { len := 1, tag := IPAC_IDTAG_SWVERSION (5) }, { len := 1, tag := IPAC_IDTAG_UNITNAME (1) }, { len := 1, tag := IPAC_IDTAG_SERNR (0) } } } } IPA-BTS1-TRX0-RSL-IPA(87)@f5d59393ffa8: CCM Tx:{ msg_type := IPAC_MSGT_ID_RESP (5), u := { resp := { { len := 0, tag := IPAC_IDTAG_UNITID (8), data := '313233352F302F3000'O }, { len := 0, tag := IPAC_IDTAG_MACADDR (7), data := '00'O }, { len := 0, tag := IPAC_IDTAG_LOCATION1 (2), data := '00'O }, { len := 0, tag := IPAC_IDTAG_LOCATION2 (3), data := '00'O }, { len := 0, tag := IPAC_IDTAG_EQUIPVERS (4), data := '00'O }, { len := 0, tag := IPAC_IDTAG_SWVERSION (5), data := '00'O }, { len := 0, tag := IPAC_IDTAG_UNITNAME (1), data := '4F736D6F636F6D205454434E2D33204254532053696D756C61746F7200'O }, { len := 0, tag := IPAC_IDTAG_SERNR (0), data := '00'O } } } } IPA-BTS1-TRX0-RSL-IPA(87)@f5d59393ffa8: CCM Rx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } MTC@f5d59393ffa8: "TC_stat_num_bts_connected_2() start" TC_stat_num_bts_connected_2(89)@f5d59393ffa8: "TC_stat_num_bts_connected_2": using IMSI '001012065754995'H VirtMSC-STATS(76)@f5d59393ffa8: EXP match: { name := "TTCN3.bsc.0.num_bts.oml_connected", val := 3, mtype := "g", srate := omit } vs { name := "TTCN3.bsc.0.num_bts.oml_connected", mtype := "g", min := 2, max := 4 } VirtMSC-STATS(76)@f5d59393ffa8: EXP match: { name := "TTCN3.bsc.0.num_bts.all_trx_rsl_connected", val := 2, mtype := "g", srate := omit } vs { name := "TTCN3.bsc.0.num_bts.all_trx_rsl_connected", mtype := "g", min := 2, max := 2 } VirtMSC-STATS(76)@f5d59393ffa8: EXP match: { name := "TTCN3.bsc.0.num_bts.total", val := 4, mtype := "g", srate := omit } vs { name := "TTCN3.bsc.0.num_bts.total", mtype := "g", min := 4, max := 4 } VirtMSC-STATS(76)@f5d59393ffa8: EXP match: { name := "TTCN3.bsc.0.num_trx.rsl_connected", val := 2, mtype := "g", srate := omit } vs { name := "TTCN3.bsc.0.num_trx.rsl_connected", mtype := "g", min := 2, max := 2 } VirtMSC-STATS(76)@f5d59393ffa8: EXP match: { name := "TTCN3.bsc.0.num_trx.total", val := 7, mtype := "g", srate := omit } vs { name := "TTCN3.bsc.0.num_trx.total", mtype := "g", min := 7, max := 7 } TC_stat_num_bts_connected_2(89)@f5d59393ffa8: Final verdict of PTC: none MTC@f5d59393ffa8: talloc reports "struct bsc_subscr" x 0, expecting 0 MTC@f5d59393ffa8: talloc reports "struct gsm_subscriber_connection" x 0, expecting 0 VirtMSC-RAN(78)@f5d59393ffa8: Final verdict of PTC: none VirtMSC-STATS(76)@f5d59393ffa8: Final verdict of PTC: none VirtMSC-M3UA(79)@f5d59393ffa8: Final verdict of PTC: none VirtMSC-SCCP(77)@f5d59393ffa8: Final verdict of PTC: none IPA-CTRL-CLI-IPA(83)@f5d59393ffa8: Final verdict of PTC: none MTC@f5d59393ffa8: setverdict(pass): none -> pass IPA-BTS0-TRX0-RSL-RSL(86)@f5d59393ffa8: Final verdict of PTC: none VirtSMLC-BSSAP_LE(81)@f5d59393ffa8: Final verdict of PTC: none VirtSMLC-SCCP(80)@f5d59393ffa8: Final verdict of PTC: none IPA-BTS0-TRX0-RSL-IPA(85)@f5d59393ffa8: Final verdict of PTC: none IPA-BTS1-TRX0-RSL-RSL(88)@f5d59393ffa8: Final verdict of PTC: none VirtMGW-MGCP-0(84)@f5d59393ffa8: Final verdict of PTC: none VirtSMLC-M3UA(82)@f5d59393ffa8: Final verdict of PTC: none IPA-BTS1-TRX0-RSL-IPA(87)@f5d59393ffa8: Final verdict of PTC: none MTC@f5d59393ffa8: Setting final verdict of the test case. MTC@f5d59393ffa8: Local verdict of MTC: pass MTC@f5d59393ffa8: Local verdict of PTC VirtMSC-STATS(76): none (pass -> pass) MTC@f5d59393ffa8: Local verdict of PTC VirtMSC-SCCP(77): none (pass -> pass) MTC@f5d59393ffa8: Local verdict of PTC VirtMSC-RAN(78): none (pass -> pass) MTC@f5d59393ffa8: Local verdict of PTC VirtMSC-M3UA(79): none (pass -> pass) MTC@f5d59393ffa8: Local verdict of PTC VirtSMLC-SCCP(80): none (pass -> pass) MTC@f5d59393ffa8: Local verdict of PTC VirtSMLC-BSSAP_LE(81): none (pass -> pass) MTC@f5d59393ffa8: Local verdict of PTC VirtSMLC-M3UA(82): none (pass -> pass) MTC@f5d59393ffa8: Local verdict of PTC IPA-CTRL-CLI-IPA(83): none (pass -> pass) MTC@f5d59393ffa8: Local verdict of PTC VirtMGW-MGCP-0(84): none (pass -> pass) MTC@f5d59393ffa8: Local verdict of PTC IPA-BTS0-TRX0-RSL-IPA(85): none (pass -> pass) MTC@f5d59393ffa8: Local verdict of PTC IPA-BTS0-TRX0-RSL-RSL(86): none (pass -> pass) MTC@f5d59393ffa8: Local verdict of PTC IPA-BTS1-TRX0-RSL-IPA(87): none (pass -> pass) MTC@f5d59393ffa8: Local verdict of PTC IPA-BTS1-TRX0-RSL-RSL(88): none (pass -> pass) MTC@f5d59393ffa8: Local verdict of PTC TC_stat_num_bts_connected_2(89): none (pass -> pass) MTC@f5d59393ffa8: Test case TC_stat_num_bts_connected_2 finished. Verdict: pass MTC@f5d59393ffa8: Starting external command `../ttcn3-tcpdump-stop.sh BSC_Tests.TC_stat_num_bts_connected_2 pass'. Tue Jan 17 06:01:17 UTC 2023 [1;32m====== BSC_Tests.TC_stat_num_bts_connected_2 pass ======[0m Saving talloc report from 172.18.138.20:4242 to BSC_Tests.TC_stat_num_bts_connected_2.talloc Waiting for packet dumper to finish... 0 (prev_count=-1, count=550659) Waiting for packet dumper to finish... 1 (prev_count=550659, count=702780) MTC@f5d59393ffa8: External command `../ttcn3-tcpdump-stop.sh BSC_Tests.TC_stat_num_bts_connected_2 pass' was executed successfully (exit status: 0). MTC@f5d59393ffa8: Starting external command `../ttcn3-tcpdump-start.sh BSC_Tests.TC_stat_num_bts_connected_3'. ------ BSC_Tests.TC_stat_num_bts_connected_3 ------ Tue Jan 17 06:01:22 UTC 2023 NOTE: unable to use dumpcap due to missing capabilities or suid bit Waiting for packet dumper to start... 0 MTC@f5d59393ffa8: External command `../ttcn3-tcpdump-start.sh BSC_Tests.TC_stat_num_bts_connected_3' was executed successfully (exit status: 0). MTC@f5d59393ffa8: Test case TC_stat_num_bts_connected_3 started. MTC@f5d59393ffa8: Connecting BSSAP RAN_Emulation to SCCP_SP_PORT MTC@f5d59393ffa8: Starting RAN_Emulation VirtMSC-M3UA(93)@f5d59393ffa8: ************************************************* VirtMSC-M3UA(93)@f5d59393ffa8: M3UA emulation initiated, the test can be started VirtMSC-M3UA(93)@f5d59393ffa8: ************************************************* VirtMSC-SCCP(91)@f5d59393ffa8: v_sccp_pdu_maxlen:268 MTC@f5d59393ffa8: Connecting BSSAP_LE_Emulation to SCCP_SP_PORT MTC@f5d59393ffa8: Starting BSSAP_LE_Emulation VirtSMLC-M3UA(96)@f5d59393ffa8: ************************************************* VirtSMLC-M3UA(96)@f5d59393ffa8: M3UA emulation initiated, the test can be started VirtSMLC-M3UA(96)@f5d59393ffa8: ************************************************* VirtSMLC-SCCP(94)@f5d59393ffa8: v_sccp_pdu_maxlen:268 VirtMSC-M3UA(93)@f5d59393ffa8: SCTP_ConnectResult -> connection established from: "172.18.138.203":23905 to server: "172.18.138.200":2905 association #8 VirtSMLC-M3UA(96)@f5d59393ffa8: SCTP_ConnectResult -> connection established from: "172.18.138.203":23908 to server: "172.18.138.200":2905 association #8 VirtMSC-RAN(92)@f5d59393ffa8: BSSMAP: Received RESET-ACK in response to RESET, we're ready to go! VirtSMLC-BSSAP_LE(95)@f5d59393ffa8: BSSMAP-LE: Received RESET-ACK in response to RESET, we're ready to go! IPA-BTS0-TRX0-RSL-IPA(99)@f5d59393ffa8: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } IPA-BTS0-TRX0-RSL-IPA(99)@f5d59393ffa8: CCM Rx:{ msg_type := IPAC_MSGT_ID_GET (4), u := { get := { { len := 1, tag := IPAC_IDTAG_UNITID (8) }, { len := 1, tag := IPAC_IDTAG_MACADDR (7) }, { len := 1, tag := IPAC_IDTAG_LOCATION1 (2) }, { len := 1, tag := IPAC_IDTAG_LOCATION2 (3) }, { len := 1, tag := IPAC_IDTAG_EQUIPVERS (4) }, { len := 1, tag := IPAC_IDTAG_SWVERSION (5) }, { len := 1, tag := IPAC_IDTAG_UNITNAME (1) }, { len := 1, tag := IPAC_IDTAG_SERNR (0) } } } } IPA-BTS0-TRX0-RSL-IPA(99)@f5d59393ffa8: CCM Tx:{ msg_type := IPAC_MSGT_ID_RESP (5), u := { resp := { { len := 0, tag := IPAC_IDTAG_UNITID (8), data := '313233342F302F3000'O }, { len := 0, tag := IPAC_IDTAG_MACADDR (7), data := '00'O }, { len := 0, tag := IPAC_IDTAG_LOCATION1 (2), data := '00'O }, { len := 0, tag := IPAC_IDTAG_LOCATION2 (3), data := '00'O }, { len := 0, tag := IPAC_IDTAG_EQUIPVERS (4), data := '00'O }, { len := 0, tag := IPAC_IDTAG_SWVERSION (5), data := '00'O }, { len := 0, tag := IPAC_IDTAG_UNITNAME (1), data := '4F736D6F636F6D205454434E2D33204254532053696D756C61746F7200'O }, { len := 0, tag := IPAC_IDTAG_SERNR (0), data := '00'O } } } } IPA-BTS0-TRX0-RSL-IPA(99)@f5d59393ffa8: CCM Rx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } IPA-BTS1-TRX0-RSL-IPA(101)@f5d59393ffa8: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } IPA-BTS1-TRX0-RSL-IPA(101)@f5d59393ffa8: CCM Rx:{ msg_type := IPAC_MSGT_ID_GET (4), u := { get := { { len := 1, tag := IPAC_IDTAG_UNITID (8) }, { len := 1, tag := IPAC_IDTAG_MACADDR (7) }, { len := 1, tag := IPAC_IDTAG_LOCATION1 (2) }, { len := 1, tag := IPAC_IDTAG_LOCATION2 (3) }, { len := 1, tag := IPAC_IDTAG_EQUIPVERS (4) }, { len := 1, tag := IPAC_IDTAG_SWVERSION (5) }, { len := 1, tag := IPAC_IDTAG_UNITNAME (1) }, { len := 1, tag := IPAC_IDTAG_SERNR (0) } } } } IPA-BTS1-TRX0-RSL-IPA(101)@f5d59393ffa8: CCM Tx:{ msg_type := IPAC_MSGT_ID_RESP (5), u := { resp := { { len := 0, tag := IPAC_IDTAG_UNITID (8), data := '313233352F302F3000'O }, { len := 0, tag := IPAC_IDTAG_MACADDR (7), data := '00'O }, { len := 0, tag := IPAC_IDTAG_LOCATION1 (2), data := '00'O }, { len := 0, tag := IPAC_IDTAG_LOCATION2 (3), data := '00'O }, { len := 0, tag := IPAC_IDTAG_EQUIPVERS (4), data := '00'O }, { len := 0, tag := IPAC_IDTAG_SWVERSION (5), data := '00'O }, { len := 0, tag := IPAC_IDTAG_UNITNAME (1), data := '4F736D6F636F6D205454434E2D33204254532053696D756C61746F7200'O }, { len := 0, tag := IPAC_IDTAG_SERNR (0), data := '00'O } } } } IPA-BTS1-TRX0-RSL-IPA(101)@f5d59393ffa8: CCM Rx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } IPA-BTS2-TRX0-RSL-IPA(103)@f5d59393ffa8: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } IPA-BTS2-TRX0-RSL-IPA(103)@f5d59393ffa8: CCM Rx:{ msg_type := IPAC_MSGT_ID_GET (4), u := { get := { { len := 1, tag := IPAC_IDTAG_UNITID (8) }, { len := 1, tag := IPAC_IDTAG_MACADDR (7) }, { len := 1, tag := IPAC_IDTAG_LOCATION1 (2) }, { len := 1, tag := IPAC_IDTAG_LOCATION2 (3) }, { len := 1, tag := IPAC_IDTAG_EQUIPVERS (4) }, { len := 1, tag := IPAC_IDTAG_SWVERSION (5) }, { len := 1, tag := IPAC_IDTAG_UNITNAME (1) }, { len := 1, tag := IPAC_IDTAG_SERNR (0) } } } } IPA-BTS2-TRX0-RSL-IPA(103)@f5d59393ffa8: CCM Tx:{ msg_type := IPAC_MSGT_ID_RESP (5), u := { resp := { { len := 0, tag := IPAC_IDTAG_UNITID (8), data := '313233362F302F3000'O }, { len := 0, tag := IPAC_IDTAG_MACADDR (7), data := '00'O }, { len := 0, tag := IPAC_IDTAG_LOCATION1 (2), data := '00'O }, { len := 0, tag := IPAC_IDTAG_LOCATION2 (3), data := '00'O }, { len := 0, tag := IPAC_IDTAG_EQUIPVERS (4), data := '00'O }, { len := 0, tag := IPAC_IDTAG_SWVERSION (5), data := '00'O }, { len := 0, tag := IPAC_IDTAG_UNITNAME (1), data := '4F736D6F636F6D205454434E2D33204254532053696D756C61746F7200'O }, { len := 0, tag := IPAC_IDTAG_SERNR (0), data := '00'O } } } } IPA-BTS2-TRX0-RSL-IPA(103)@f5d59393ffa8: CCM Rx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } IPA-BTS2-TRX1-RSL-IPA(105)@f5d59393ffa8: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } IPA-BTS2-TRX1-RSL-IPA(105)@f5d59393ffa8: CCM Rx:{ msg_type := IPAC_MSGT_ID_GET (4), u := { get := { { len := 1, tag := IPAC_IDTAG_UNITID (8) }, { len := 1, tag := IPAC_IDTAG_MACADDR (7) }, { len := 1, tag := IPAC_IDTAG_LOCATION1 (2) }, { len := 1, tag := IPAC_IDTAG_LOCATION2 (3) }, { len := 1, tag := IPAC_IDTAG_EQUIPVERS (4) }, { len := 1, tag := IPAC_IDTAG_SWVERSION (5) }, { len := 1, tag := IPAC_IDTAG_UNITNAME (1) }, { len := 1, tag := IPAC_IDTAG_SERNR (0) } } } } IPA-BTS2-TRX1-RSL-IPA(105)@f5d59393ffa8: CCM Tx:{ msg_type := IPAC_MSGT_ID_RESP (5), u := { resp := { { len := 0, tag := IPAC_IDTAG_UNITID (8), data := '313233362F302F3100'O }, { len := 0, tag := IPAC_IDTAG_MACADDR (7), data := '00'O }, { len := 0, tag := IPAC_IDTAG_LOCATION1 (2), data := '00'O }, { len := 0, tag := IPAC_IDTAG_LOCATION2 (3), data := '00'O }, { len := 0, tag := IPAC_IDTAG_EQUIPVERS (4), data := '00'O }, { len := 0, tag := IPAC_IDTAG_SWVERSION (5), data := '00'O }, { len := 0, tag := IPAC_IDTAG_UNITNAME (1), data := '4F736D6F636F6D205454434E2D33204254532053696D756C61746F7200'O }, { len := 0, tag := IPAC_IDTAG_SERNR (0), data := '00'O } } } } IPA-BTS2-TRX1-RSL-IPA(105)@f5d59393ffa8: CCM Rx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } IPA-BTS2-TRX2-RSL-IPA(107)@f5d59393ffa8: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } IPA-BTS2-TRX2-RSL-IPA(107)@f5d59393ffa8: CCM Rx:{ msg_type := IPAC_MSGT_ID_GET (4), u := { get := { { len := 1, tag := IPAC_IDTAG_UNITID (8) }, { len := 1, tag := IPAC_IDTAG_MACADDR (7) }, { len := 1, tag := IPAC_IDTAG_LOCATION1 (2) }, { len := 1, tag := IPAC_IDTAG_LOCATION2 (3) }, { len := 1, tag := IPAC_IDTAG_EQUIPVERS (4) }, { len := 1, tag := IPAC_IDTAG_SWVERSION (5) }, { len := 1, tag := IPAC_IDTAG_UNITNAME (1) }, { len := 1, tag := IPAC_IDTAG_SERNR (0) } } } } IPA-BTS2-TRX2-RSL-IPA(107)@f5d59393ffa8: CCM Tx:{ msg_type := IPAC_MSGT_ID_RESP (5), u := { resp := { { len := 0, tag := IPAC_IDTAG_UNITID (8), data := '313233362F302F3200'O }, { len := 0, tag := IPAC_IDTAG_MACADDR (7), data := '00'O }, { len := 0, tag := IPAC_IDTAG_LOCATION1 (2), data := '00'O }, { len := 0, tag := IPAC_IDTAG_LOCATION2 (3), data := '00'O }, { len := 0, tag := IPAC_IDTAG_EQUIPVERS (4), data := '00'O }, { len := 0, tag := IPAC_IDTAG_SWVERSION (5), data := '00'O }, { len := 0, tag := IPAC_IDTAG_UNITNAME (1), data := '4F736D6F636F6D205454434E2D33204254532053696D756C61746F7200'O }, { len := 0, tag := IPAC_IDTAG_SERNR (0), data := '00'O } } } } IPA-BTS2-TRX2-RSL-IPA(107)@f5d59393ffa8: CCM Rx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } IPA-BTS2-TRX3-RSL-IPA(109)@f5d59393ffa8: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } IPA-BTS2-TRX3-RSL-IPA(109)@f5d59393ffa8: CCM Rx:{ msg_type := IPAC_MSGT_ID_GET (4), u := { get := { { len := 1, tag := IPAC_IDTAG_UNITID (8) }, { len := 1, tag := IPAC_IDTAG_MACADDR (7) }, { len := 1, tag := IPAC_IDTAG_LOCATION1 (2) }, { len := 1, tag := IPAC_IDTAG_LOCATION2 (3) }, { len := 1, tag := IPAC_IDTAG_EQUIPVERS (4) }, { len := 1, tag := IPAC_IDTAG_SWVERSION (5) }, { len := 1, tag := IPAC_IDTAG_UNITNAME (1) }, { len := 1, tag := IPAC_IDTAG_SERNR (0) } } } } IPA-BTS2-TRX3-RSL-IPA(109)@f5d59393ffa8: CCM Tx:{ msg_type := IPAC_MSGT_ID_RESP (5), u := { resp := { { len := 0, tag := IPAC_IDTAG_UNITID (8), data := '313233362F302F3300'O }, { len := 0, tag := IPAC_IDTAG_MACADDR (7), data := '00'O }, { len := 0, tag := IPAC_IDTAG_LOCATION1 (2), data := '00'O }, { len := 0, tag := IPAC_IDTAG_LOCATION2 (3), data := '00'O }, { len := 0, tag := IPAC_IDTAG_EQUIPVERS (4), data := '00'O }, { len := 0, tag := IPAC_IDTAG_SWVERSION (5), data := '00'O }, { len := 0, tag := IPAC_IDTAG_UNITNAME (1), data := '4F736D6F636F6D205454434E2D33204254532053696D756C61746F7200'O }, { len := 0, tag := IPAC_IDTAG_SERNR (0), data := '00'O } } } } IPA-BTS2-TRX3-RSL-IPA(109)@f5d59393ffa8: CCM Rx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } MTC@f5d59393ffa8: "TC_stat_num_bts_connected_3() start" TC_stat_num_bts_connected_3(111)@f5d59393ffa8: "TC_stat_num_bts_connected_3": using IMSI '001014447633765'H VirtMSC-STATS(90)@f5d59393ffa8: EXP match: { name := "TTCN3.bsc.0.num_bts.oml_connected", val := 3, mtype := "g", srate := omit } vs { name := "TTCN3.bsc.0.num_bts.oml_connected", mtype := "g", min := 3, max := 4 } VirtMSC-STATS(90)@f5d59393ffa8: EXP match: { name := "TTCN3.bsc.0.num_bts.all_trx_rsl_connected", val := 3, mtype := "g", srate := omit } vs { name := "TTCN3.bsc.0.num_bts.all_trx_rsl_connected", mtype := "g", min := 3, max := 3 } VirtMSC-STATS(90)@f5d59393ffa8: EXP match: { name := "TTCN3.bsc.0.num_bts.total", val := 4, mtype := "g", srate := omit } vs { name := "TTCN3.bsc.0.num_bts.total", mtype := "g", min := 4, max := 4 } VirtMSC-STATS(90)@f5d59393ffa8: EXP match: { name := "TTCN3.bsc.0.num_trx.rsl_connected", val := 6, mtype := "g", srate := omit } vs { name := "TTCN3.bsc.0.num_trx.rsl_connected", mtype := "g", min := 6, max := 6 } VirtMSC-STATS(90)@f5d59393ffa8: EXP match: { name := "TTCN3.bsc.0.num_trx.total", val := 7, mtype := "g", srate := omit } vs { name := "TTCN3.bsc.0.num_trx.total", mtype := "g", min := 7, max := 7 } TC_stat_num_bts_connected_3(111)@f5d59393ffa8: Final verdict of PTC: none MTC@f5d59393ffa8: talloc reports "struct bsc_subscr" x 0, expecting 0 MTC@f5d59393ffa8: talloc reports "struct gsm_subscriber_connection" x 0, expecting 0 VirtMSC-M3UA(93)@f5d59393ffa8: Final verdict of PTC: none VirtMSC-SCCP(91)@f5d59393ffa8: Final verdict of PTC: none IPA-CTRL-CLI-IPA(97)@f5d59393ffa8: Final verdict of PTC: none VirtMSC-STATS(90)@f5d59393ffa8: Final verdict of PTC: none VirtMSC-RAN(92)@f5d59393ffa8: Final verdict of PTC: none MTC@f5d59393ffa8: setverdict(pass): none -> pass VirtSMLC-SCCP(94)@f5d59393ffa8: Final verdict of PTC: none IPA-BTS1-TRX0-RSL-RSL(102)@f5d59393ffa8: Final verdict of PTC: none IPA-BTS2-TRX3-RSL-RSL(110)@f5d59393ffa8: Final verdict of PTC: none VirtSMLC-M3UA(96)@f5d59393ffa8: Final verdict of PTC: none IPA-BTS0-TRX0-RSL-IPA(99)@f5d59393ffa8: Final verdict of PTC: none IPA-BTS2-TRX2-RSL-IPA(107)@f5d59393ffa8: Final verdict of PTC: none IPA-BTS2-TRX1-RSL-IPA(105)@f5d59393ffa8: Final verdict of PTC: none IPA-BTS2-TRX3-RSL-IPA(109)@f5d59393ffa8: Final verdict of PTC: none IPA-BTS2-TRX0-RSL-IPA(103)@f5d59393ffa8: Final verdict of PTC: none IPA-BTS2-TRX1-RSL-RSL(106)@f5d59393ffa8: Final verdict of PTC: none IPA-BTS2-TRX0-RSL-RSL(104)@f5d59393ffa8: Final verdict of PTC: none IPA-BTS1-TRX0-RSL-IPA(101)@f5d59393ffa8: Final verdict of PTC: none VirtMGW-MGCP-0(98)@f5d59393ffa8: Final verdict of PTC: none IPA-BTS0-TRX0-RSL-RSL(100)@f5d59393ffa8: Final verdict of PTC: none VirtSMLC-BSSAP_LE(95)@f5d59393ffa8: Final verdict of PTC: none IPA-BTS2-TRX2-RSL-RSL(108)@f5d59393ffa8: Final verdict of PTC: none MTC@f5d59393ffa8: Setting final verdict of the test case. MTC@f5d59393ffa8: Local verdict of MTC: pass MTC@f5d59393ffa8: Local verdict of PTC VirtMSC-STATS(90): none (pass -> pass) MTC@f5d59393ffa8: Local verdict of PTC VirtMSC-SCCP(91): none (pass -> pass) MTC@f5d59393ffa8: Local verdict of PTC VirtMSC-RAN(92): none (pass -> pass) MTC@f5d59393ffa8: Local verdict of PTC VirtMSC-M3UA(93): none (pass -> pass) MTC@f5d59393ffa8: Local verdict of PTC VirtSMLC-SCCP(94): none (pass -> pass) MTC@f5d59393ffa8: Local verdict of PTC VirtSMLC-BSSAP_LE(95): none (pass -> pass) MTC@f5d59393ffa8: Local verdict of PTC VirtSMLC-M3UA(96): none (pass -> pass) MTC@f5d59393ffa8: Local verdict of PTC IPA-CTRL-CLI-IPA(97): none (pass -> pass) MTC@f5d59393ffa8: Local verdict of PTC VirtMGW-MGCP-0(98): none (pass -> pass) MTC@f5d59393ffa8: Local verdict of PTC IPA-BTS0-TRX0-RSL-IPA(99): none (pass -> pass) MTC@f5d59393ffa8: Local verdict of PTC IPA-BTS0-TRX0-RSL-RSL(100): none (pass -> pass) MTC@f5d59393ffa8: Local verdict of PTC IPA-BTS1-TRX0-RSL-IPA(101): none (pass -> pass) MTC@f5d59393ffa8: Local verdict of PTC IPA-BTS1-TRX0-RSL-RSL(102): none (pass -> pass) MTC@f5d59393ffa8: Local verdict of PTC IPA-BTS2-TRX0-RSL-IPA(103): none (pass -> pass) MTC@f5d59393ffa8: Local verdict of PTC IPA-BTS2-TRX0-RSL-RSL(104): none (pass -> pass) MTC@f5d59393ffa8: Local verdict of PTC IPA-BTS2-TRX1-RSL-IPA(105): none (pass -> pass) MTC@f5d59393ffa8: Local verdict of PTC IPA-BTS2-TRX1-RSL-RSL(106): none (pass -> pass) MTC@f5d59393ffa8: Local verdict of PTC IPA-BTS2-TRX2-RSL-IPA(107): none (pass -> pass) MTC@f5d59393ffa8: Local verdict of PTC IPA-BTS2-TRX2-RSL-RSL(108): none (pass -> pass) MTC@f5d59393ffa8: Local verdict of PTC IPA-BTS2-TRX3-RSL-IPA(109): none (pass -> pass) MTC@f5d59393ffa8: Local verdict of PTC IPA-BTS2-TRX3-RSL-RSL(110): none (pass -> pass) MTC@f5d59393ffa8: Local verdict of PTC TC_stat_num_bts_connected_3(111): none (pass -> pass) MTC@f5d59393ffa8: Test case TC_stat_num_bts_connected_3 finished. Verdict: pass MTC@f5d59393ffa8: Starting external command `../ttcn3-tcpdump-stop.sh BSC_Tests.TC_stat_num_bts_connected_3 pass'. Tue Jan 17 06:01:30 UTC 2023 [1;32m====== BSC_Tests.TC_stat_num_bts_connected_3 pass ======[0m Saving talloc report from 172.18.138.20:4242 to BSC_Tests.TC_stat_num_bts_connected_3.talloc Waiting for packet dumper to finish... 0 (prev_count=-1, count=611231) Waiting for packet dumper to finish... 1 (prev_count=611231, count=623339) + clean_up_common + set +e + set +x ### Clean up ### + trap - EXIT INT TERM 0 + type clean_up + network_clean + docker network inspect ttcn3-bsc-test + grep Name + cut -d : -f2 + awk -F" NR>1{print $2} + xargs -rn1 docker kill + network_remove + set +x Removing network ttcn3-bsc-test + docker network remove ttcn3-bsc-test Error: No such network: ttcn3-bsc-test + rm -rf <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-bsc-test-latest/681/ar…> + fix_perms + set +x Fixing permissions + docker run --rm -v <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-bsc-test-latest/ws/logs>:/data -v <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-bsc-test-latest/ws/_ca…>:/cache --name jenkins-TTCN3-centos-bsc-test-latest-681-cleaner debian:bullseye chmod -R a+rX /data/ /cache/ + collect_logs + cat <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-bsc-test-latest/681/ar…> Build step 'Execute shell' marked build as failure Recording test results [Checks API] No suitable checks publisher found.

2 years, 5 months

Build failed in Jenkins: ttcn3-bsc-test-latest #1571

by jenkins＠lists.osmocom.org

See <https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-latest/1571/display/…> Changes: [Oliver Smith] ttcn3-*/jenkins*.sh: use $SUBNET everywhere [Oliver Smith] network_create: find free subnet automatically ------------------------------------------ [...truncated 34.10 KB...] ---> Using cache ---> c99caabf8a09 Step 7/11 : WORKDIR /tmp ---> Using cache ---> 882e67cdf6dc Step 8/11 : VOLUME /data ---> Using cache ---> 7247e1d128a9 Step 9/11 : COPY osmo-bts.cfg /data/osmo-bts.cfg ---> Using cache ---> 6a54f5d0a4ff Step 10/11 : WORKDIR /data ---> Using cache ---> 39adcfe733f6 Step 11/11 : CMD ["/bin/sh", "-c", "/usr/bin/osmo-bts-virtual -c /data/osmo-bts.cfg -i 172.18.0.230 >>/data/osmo-bts-virtual.log 2>&1"] ---> Using cache ---> a8377f3b93a8 [Warning] One or more build-args [OSMO_SGSN_BRANCH UID OSMO_HLR_BRANCH OSMO_NITB_BRANCH OSMO_PCU_BRANCH OSMO_GGSN_BRANCH OSMO_HNBGW_BRANCH OPEN5GS_BRANCH OSMOCOM_REPO_TESTSUITE_MIRROR OSMO_BSC_BRANCH OSMO_SIP_BRANCH OSMO_UECUPS_BRANCH OSMO_HNODEB_BRANCH OSMO_TTCN3_BRANCH LIBOSMOCORE_BRANCH OSMO_GBPROXY_BRANCH OSMO_GSM_TESTER_BRANCH OSMOCOM_REPO_MIRROR OSMO_CBC_BRANCH OSMO_STP_BRANCH UPSTREAM_DISTRO OSMO_IUH_BRANCH OSMO_MSC_BRANCH REGISTRY OSMOCOM_REPO_PATH OSMO_BB_BRANCH OSMO_BTS_BRANCH OSMO_MGW_BRANCH] were not consumed Successfully built a8377f3b93a8 Successfully tagged osmocom-build/osmo-bts-latest:latest rm -rf .common make: Leaving directory '<https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-latest/ws/osmo-bts-l…'> + docker_image_exists osmo-bts-latest + docker images -q osmocom-build/osmo-bts-latest + test -n a8377f3b93a8 + list_osmo_packages debian-bullseye osmo-bts-latest + local distro=debian-bullseye + local image=osmo-bts-latest + local docker_run_sh=docker run --rm --entrypoint=/bin/sh osmocom-build/osmo-bts-latest -c + [ -n ] + set +x ### Installed Osmocom packages in: osmo-bts-latest ### ii libosmoabis10:amd64 1.3.0 amd64 GSM A-bis handling ii libosmocodec0:amd64 1.7.0 amd64 Osmo codec library ii libosmocoding0:amd64 1.7.0 amd64 Osmo coding library ii libosmocore19:amd64 1.7.0 amd64 Osmo Core library ii libosmoctrl0:amd64 1.7.0 amd64 Osmo control library ii libosmogsm18:amd64 1.7.0 amd64 Osmo GSM utility library ii libosmotrau2:amd64 1.3.0 amd64 GSM trau handling ii libosmovty9:amd64 1.7.0 amd64 Osmo VTY library ii osmo-bts-trx 1.5.0 amd64 osmo-bts-trx GSM BTS with osmo-trx ii osmo-bts-virtual 1.5.0 amd64 Virtual Osmocom GSM BTS (no RF hardware; GSMTAP/UDP) ii osmocom-latest 1.0.0 amd64 Dummy package, which conflicts with: osmocom-nightly osmocom-next + [ registry.osmocom.org = registry.osmocom.org ] + docker_depends ttcn3-bsc-test + local feed + echo debian-bullseye-titan + depends=debian-bullseye-titan + [ -n debian-bullseye-titan ] + docker_images_require debian-bullseye-titan + local i + local from_line + local pull_arg + local upstream_distro_arg + local distro_arg + local depends + local dir + [ registry.osmocom.org = registry.osmocom.org ] + docker pull registry.osmocom.org/osmocom-build/debian-bullseye-titan Using default tag: latest latest: Pulling from osmocom-build/debian-bullseye-titan Digest: sha256:33c02949d71e8f89cbe9ab1eed644c821e98199744d8b90820c9b324dff3d6d6 Status: Image is up to date for registry.osmocom.org/osmocom-build/debian-bullseye-titan:latest registry.osmocom.org/osmocom-build/debian-bullseye-titan:latest + continue + docker_distro_from_image_name ttcn3-bsc-test + echo debian-bullseye + distro_arg=debian-bullseye + [ -z ] + docker_upstream_distro_from_image_name ttcn3-bsc-test + echo debian:bullseye + upstream_distro_arg=debian:bullseye + docker_dir_from_image_name ttcn3-bsc-test + echo ttcn3-bsc-test + dir=ttcn3-bsc-test + pull_arg=--pull + grep ^FROM ../ttcn3-bsc-test/Dockerfile + from_line=FROM $REGISTRY/$USER/debian-bullseye-titan + echo FROM $REGISTRY/$USER/debian-bullseye-titan + grep -q $USER + pull_arg= + set +x Building image: ttcn3-bsc-test (export NO_DOCKER_IMAGE_BUILD=1 to prevent this) + make -C ../ttcn3-bsc-test BUILD_ARGS= UPSTREAM_DISTRO=debian:bullseye DISTRO=debian-bullseye IMAGE=osmocom-build/ttcn3-bsc-test make: Entering directory '<https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-latest/ws/ttcn3-bsc-…'> awk: fatal: cannot open file `.release' for reading: No such file or directory awk: fatal: cannot open file `.release' for reading: No such file or directory awk: fatal: cannot open file `.release' for reading: No such file or directory rm -rf .common cp -r <https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-latest/ws/common> .common INFO: .release created release=0.0.0 docker build \ --build-arg USER=osmocom-build \ --build-arg UID=1000 \ --build-arg REGISTRY=registry.osmocom.org \ --build-arg OSMO_TTCN3_BRANCH=master \ --build-arg UPSTREAM_DISTRO=debian:bullseye \ --build-arg DISTRO=debian-bullseye \ --build-arg OSMOCOM_REPO_MIRROR=https://downloads.osmocom.org \ --build-arg OSMOCOM_REPO_PATH=packages/osmocom: \ --build-arg OSMOCOM_REPO_VERSION=latest \ --build-arg OSMOCOM_REPO_TESTSUITE_MIRROR=https://downloads.osmocom.org \ --build-arg LIBOSMOCORE_BRANCH=master \ --build-arg OSMO_BB_BRANCH=master \ --build-arg OSMO_BSC_BRANCH=master \ --build-arg OSMO_BTS_BRANCH=master \ --build-arg OSMO_CBC_BRANCH=master \ --build-arg OSMO_GBPROXY_BRANCH=master \ --build-arg OSMO_GGSN_BRANCH=master \ --build-arg OSMO_GSM_TESTER_BRANCH=master \ --build-arg OSMO_HLR_BRANCH=master \ --build-arg OSMO_HNBGW_BRANCH=master \ --build-arg OSMO_HNODEB_BRANCH=master \ --build-arg OSMO_IUH_BRANCH=master \ --build-arg OSMO_MGW_BRANCH=master \ --build-arg OSMO_MSC_BRANCH=master \ --build-arg OSMO_NITB_BRANCH=master \ --build-arg OSMO_PCU_BRANCH=master \ --build-arg OSMO_SGSN_BRANCH=master \ --build-arg OSMO_SIP_BRANCH=master \ --build-arg OSMO_STP_BRANCH=master \ --build-arg OSMO_UECUPS_BRANCH=master \ --build-arg OPEN5GS_BRANCH=main \ -t osmocom-build/ttcn3-bsc-test:latest . Sending build context to Docker daemon 63.49kB Step 1/9 : ARG REGISTRY Step 2/9 : ARG USER Step 3/9 : FROM $REGISTRY/$USER/debian-bullseye-titan ---> c52b3a0afa2b Step 4/9 : ARG OSMO_TTCN3_BRANCH="master" ---> Using cache ---> 7fd781eb90de Step 5/9 : ADD https://gerrit.osmocom.org/plugins/gitiles/osmo-ttcn3-hacks/+/$OSMO_TTCN3_B… /tmp/commit ---> Using cache ---> 00095faa6a88 Step 6/9 : RUN ttcn3-docker-prepare "$OSMO_TTCN3_BRANCH" bsc ---> Using cache ---> 23880a8fc9d1 Step 7/9 : VOLUME /data ---> Using cache ---> 68dee03cf0b3 Step 8/9 : COPY BSC_Tests.cfg /data/BSC_Tests.cfg ---> Using cache ---> 56e21f65d434 Step 9/9 : CMD ttcn3-docker-run bsc BSC_Tests ---> Using cache ---> 647f9ca87494 [Warning] One or more build-args [OPEN5GS_BRANCH OSMO_STP_BRANCH OSMO_UECUPS_BRANCH OSMOCOM_REPO_MIRROR OSMO_GSM_TESTER_BRANCH OSMO_HNODEB_BRANCH OSMOCOM_REPO_PATH OSMO_BB_BRANCH OSMO_MSC_BRANCH OSMO_SGSN_BRANCH OSMO_SIP_BRANCH OSMO_BTS_BRANCH OSMO_GGSN_BRANCH OSMO_IUH_BRANCH OSMO_BSC_BRANCH OSMO_NITB_BRANCH OSMO_HLR_BRANCH UPSTREAM_DISTRO LIBOSMOCORE_BRANCH OSMO_CBC_BRANCH OSMO_GBPROXY_BRANCH OSMOCOM_REPO_TESTSUITE_MIRROR OSMO_HNBGW_BRANCH UID OSMO_PCU_BRANCH DISTRO OSMOCOM_REPO_VERSION OSMO_MGW_BRANCH] were not consumed Successfully built 647f9ca87494 Successfully tagged osmocom-build/ttcn3-bsc-test:latest rm -rf .common make: Leaving directory '<https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-latest/ws/ttcn3-bsc-…'> + docker_image_exists ttcn3-bsc-test + docker images -q osmocom-build/ttcn3-bsc-test + test -n 647f9ca87494 + list_osmo_packages debian-bullseye ttcn3-bsc-test + local distro=debian-bullseye + local image=ttcn3-bsc-test + local docker_run_sh=docker run --rm --entrypoint=/bin/sh osmocom-build/ttcn3-bsc-test -c + [ -n ] + return + set_clean_up_trap + trap clean_up_common EXIT INT TERM 0 + set -e + mkdir <https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-latest/1571/artifact…> + cp BSC_Tests.cfg <https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-latest/1571/artifact…> + write_mp_osmo_repo <https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-latest/1571/artifact…> + local repo=nightly + local config=<https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-latest/1571/artifact…> + local line + [ -e <https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-latest/1571/artifact…> ] + repo=latest + line=Misc_Helpers.mp_osmo_repo := "latest" + sed -i s/\[MODULE_PARAMETERS\]/\[MODULE_PARAMETERS\]\nMisc_Helpers.mp_osmo_repo := "latest"/g <https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-latest/1571/artifact…> + mkdir <https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-latest/1571/artifact…> + cp osmo-stp.cfg <https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-latest/1571/artifact…> + mkdir <https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-latest/1571/artifact…> + cp osmo-bsc.cfg <https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-latest/1571/artifact…> + mkdir <https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-latest/1571/artifact…> + network_create + docker network ls + grep -q ttcn3-bsc-test + SUBNET=2653239 + seq 1 30 + echo (2653239 + 1) % 256 + bc + SUBNET=56 + SUB4=172.18.56.0/24 + SUB6=fd02:db8:56::/64 + set +x Creating network ttcn3-bsc-test, trying SUBNET=56... + docker network create --internal --subnet 172.18.56.0/24 --ipv6 --subnet fd02:db8:56::/64 ttcn3-bsc-test d6ed663b5c5b28985a9969ce7cfde16c9ca7282707908f5c2b213cf1fbdbc0ce + set +x ### Network ttcn3-bsc-test created (SUBNET=56) ### + return + network_replace_subnet_in_configs + set +x Applying SUBNET=56 to: <https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-latest/1571/artifact…> Applying SUBNET=56 to: <https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-latest/1571/artifact…> Applying SUBNET=56 to: <https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-latest/1571/artifact…> + echo Starting container with STP Starting container with STP + docker_network_params 56 200 + NET=56 + ADDR_SUFIX=200 + echo --network ttcn3-bsc-test --ip 172.18.56.200 --ip6 fd02:db8:56::200 + docker run --rm --network ttcn3-bsc-test --ip 172.18.56.200 --ip6 fd02:db8:56::200 --ulimit core=-1 -v <https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-latest/1571/artifact…>:/data --name jenkins-ttcn3-bsc-test-latest-1571-stp -d osmocom-build/osmo-stp-latest 408b20f362b8660ea2b162329292d23c3e4ebe0d75c2a87e967bdba53c58b853 docker: Error response from daemon: network ttcn3-bsc-test not found. + clean_up_common + set +e + set +x ### Clean up ### + trap - EXIT INT TERM 0 + type clean_up + network_clean + docker network inspect ttcn3-bsc-test + grep Name + cut -d : -f2 + awk -F" NR>1{print $2} + xargs -rn1 docker kill Error: No such network: ttcn3-bsc-test + network_remove + set +x Removing network ttcn3-bsc-test + docker network remove ttcn3-bsc-test Error: No such network: ttcn3-bsc-test + rm -rf <https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-latest/1571/artifact…> + fix_perms + set +x Fixing permissions + docker run --rm -v <https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-latest/ws/logs>:/data -v <https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-latest/ws/_cache>:/cache --name jenkins-ttcn3-bsc-test-latest-1571-cleaner debian:bullseye chmod -R a+rX /data/ /cache/ + collect_logs + cat <https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-latest/1571/artifact…> cat: '<https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-latest/1571/artifact…'>: No such file or directory + true Build step 'Execute shell' marked build as failure Recording test results ERROR: Step ‘Publish JUnit test result report’ failed: No test report files were found. Configuration error?

2 years, 5 months

Build failed in Jenkins: ttcn3-sgsn-test-latest #1561

by jenkins＠lists.osmocom.org

See <https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-latest/1561/display…> Changes: [Oliver Smith] ttcn3-*/jenkins*.sh: use $SUBNET everywhere [Oliver Smith] network_create: find free subnet automatically ------------------------------------------ [...truncated 63.10 KB...] TC_attach_umts_aka_umts_res(59)@02e2e6faa13c: setverdict(pass): none -> pass TC_attach_umts_aka_umts_res-BVCI196(43)@02e2e6faa13c: Removing Client IMSI='262420000001002'H, index=0 TC_attach_umts_aka_umts_res(59)@02e2e6faa13c: Final verdict of PTC: pass SGSN_Test-GSUP(57)@02e2e6faa13c: Final verdict of PTC: none -NSVCI98(50)@02e2e6faa13c: Final verdict of PTC: none -NSVCI99-provIP(53)@02e2e6faa13c: Final verdict of PTC: none SGSN_Test-Gb0-BSSGP0(42)@02e2e6faa13c: Final verdict of PTC: none -NSVCI99(55)@02e2e6faa13c: Final verdict of PTC: none TC_attach_umts_aka_umts_res-BVCI220(54)@02e2e6faa13c: Final verdict of PTC: none SGSN_Test-Gb1-NS1(45)@02e2e6faa13c: Final verdict of PTC: none SGSN_Test-Gb0-NS0(41)@02e2e6faa13c: Final verdict of PTC: none SGSN_Test-GTP(58)@02e2e6faa13c: Final verdict of PTC: none SGSN_Test-Gb2-NS2(51)@02e2e6faa13c: Final verdict of PTC: none SGSN_Test-Gb2-BSSGP2(52)@02e2e6faa13c: Final verdict of PTC: none -NSVCI97-provIP(44)@02e2e6faa13c: Final verdict of PTC: none -NSVCI98-provIP(48)@02e2e6faa13c: Final verdict of PTC: none -NSVCI97(46)@02e2e6faa13c: Final verdict of PTC: none SGSN_Test-GSUP-IPA(56)@02e2e6faa13c: Final verdict of PTC: none TC_attach_umts_aka_umts_res-BVCI196(43)@02e2e6faa13c: Final verdict of PTC: none TC_attach_umts_aka_umts_res-BVCI210(49)@02e2e6faa13c: Final verdict of PTC: none SGSN_Test-Gb1-BSSGP1(47)@02e2e6faa13c: Final verdict of PTC: none MTC@02e2e6faa13c: Setting final verdict of the test case. MTC@02e2e6faa13c: Local verdict of MTC: none MTC@02e2e6faa13c: Local verdict of PTC SGSN_Test-Gb0-NS0(41): none (none -> none) MTC@02e2e6faa13c: Local verdict of PTC SGSN_Test-Gb0-BSSGP0(42): none (none -> none) MTC@02e2e6faa13c: Local verdict of PTC TC_attach_umts_aka_umts_res-BVCI196(43): none (none -> none) MTC@02e2e6faa13c: Local verdict of PTC -NSVCI97-provIP(44): none (none -> none) MTC@02e2e6faa13c: Local verdict of PTC SGSN_Test-Gb1-NS1(45): none (none -> none) MTC@02e2e6faa13c: Local verdict of PTC -NSVCI97(46): none (none -> none) MTC@02e2e6faa13c: Local verdict of PTC SGSN_Test-Gb1-BSSGP1(47): none (none -> none) MTC@02e2e6faa13c: Local verdict of PTC -NSVCI98-provIP(48): none (none -> none) MTC@02e2e6faa13c: Local verdict of PTC TC_attach_umts_aka_umts_res-BVCI210(49): none (none -> none) MTC@02e2e6faa13c: Local verdict of PTC -NSVCI98(50): none (none -> none) MTC@02e2e6faa13c: Local verdict of PTC SGSN_Test-Gb2-NS2(51): none (none -> none) MTC@02e2e6faa13c: Local verdict of PTC SGSN_Test-Gb2-BSSGP2(52): none (none -> none) MTC@02e2e6faa13c: Local verdict of PTC -NSVCI99-provIP(53): none (none -> none) MTC@02e2e6faa13c: Local verdict of PTC TC_attach_umts_aka_umts_res-BVCI220(54): none (none -> none) MTC@02e2e6faa13c: Local verdict of PTC -NSVCI99(55): none (none -> none) MTC@02e2e6faa13c: Local verdict of PTC SGSN_Test-GSUP-IPA(56): none (none -> none) MTC@02e2e6faa13c: Local verdict of PTC SGSN_Test-GSUP(57): none (none -> none) MTC@02e2e6faa13c: Local verdict of PTC SGSN_Test-GTP(58): none (none -> none) MTC@02e2e6faa13c: Local verdict of PTC TC_attach_umts_aka_umts_res(59): pass (none -> pass) MTC@02e2e6faa13c: Test case TC_attach_umts_aka_umts_res finished. Verdict: pass MTC@02e2e6faa13c: Starting external command `../ttcn3-tcpdump-stop.sh SGSN_Tests.TC_attach_umts_aka_umts_res pass'. Tue Jan 17 05:30:29 UTC 2023 [1;32m====== SGSN_Tests.TC_attach_umts_aka_umts_res pass ======[0m Saving talloc report from 172.18.99.10:4245 to SGSN_Tests.TC_attach_umts_aka_umts_res.talloc Waiting for packet dumper to finish... 0 (prev_count=-1, count=87778) Waiting for packet dumper to finish... 1 (prev_count=87778, count=89127) MTC@02e2e6faa13c: External command `../ttcn3-tcpdump-stop.sh SGSN_Tests.TC_attach_umts_aka_umts_res pass' was executed successfully (exit status: 0). MTC@02e2e6faa13c: Starting external command `../ttcn3-tcpdump-start.sh SGSN_Tests.TC_attach_umts_aka_gsm_sres'. ------ SGSN_Tests.TC_attach_umts_aka_gsm_sres ------ Tue Jan 17 05:30:32 UTC 2023 NOTE: unable to use dumpcap due to missing capabilities or suid bit Waiting for packet dumper to start... 0 MTC@02e2e6faa13c: External command `../ttcn3-tcpdump-start.sh SGSN_Tests.TC_attach_umts_aka_gsm_sres' was executed successfully (exit status: 0). MTC@02e2e6faa13c: Test case TC_attach_umts_aka_gsm_sres started. SGSN_Test-Gb0-NS0(60)@02e2e6faa13c: Creating NSIP provider for "172.18.99.103":21010 SGSN_Test-Gb0-BSSGP0(61)@02e2e6faa13c: BVCI(0) State Transition: BVC_S_WAIT_RESET (0) -> BVC_S_WAIT_NS_ALIVE_UNBLOCKED (1) SGSN_Test-Gb0-NS0(60)@02e2e6faa13c: Starting NSVC component for { provider := { ip := { address_family := AF_INET (2), local_udp_port := 21010, local_ip := "172.18.99.103", remote_udp_port := 23000, remote_ip := "172.18.99.10", data_weight := 1, signalling_weight := 1 } }, nsvci := 97 } -NSVCI97(64)@02e2e6faa13c: NSVC 97 State Transition: NSVC_S_DEAD_BLOCKED (1) -> NSVC_S_DEAD_BLOCKED (1) -NSVCI97(64)@02e2e6faa13c: Provider Link came up: sending NS-RESET -NSVCI97(64)@02e2e6faa13c: NSVC 97 State Transition: NSVC_S_WAIT_RESET (2) -> NSVC_S_ALIVE_BLOCKED (3) -NSVCI97(64)@02e2e6faa13c: Rx NS-ALIVE-ACK: stopping Tns-alive; starting Tns-test -NSVCI97(64)@02e2e6faa13c: NSVC 97 State Transition: NSVC_S_ALIVE_BLOCKED (3) -> NSVC_S_ALIVE_UNBLOCKED (4) SGSN_Test-Gb1-NS1(65)@02e2e6faa13c: Creating NSIP provider for "172.18.99.103":21011 SGSN_Test-Gb0-BSSGP0(61)@02e2e6faa13c: BVCI(0) Rx BVC-RESET-ACK SGSN_Test-Gb0-BSSGP0(61)@02e2e6faa13c: BVCI(0) State Transition: BVC_S_WAIT_NS_ALIVE_UNBLOCKED (1) -> BVC_S_UNBLOCKED (3) SGSN_Test-Gb1-BSSGP1(66)@02e2e6faa13c: BVCI(0) State Transition: BVC_S_WAIT_RESET (0) -> BVC_S_WAIT_NS_ALIVE_UNBLOCKED (1) TC_attach_umts_aka_gsm_sres-BVCI196(63)@02e2e6faa13c: BVCI(196) State Transition: BVC_S_BLOCKED (2) -> BVC_S_UNBLOCKED (3) SGSN_Test-Gb1-NS1(65)@02e2e6faa13c: Starting NSVC component for { provider := { ip := { address_family := AF_INET (2), local_udp_port := 21011, local_ip := "172.18.99.103", remote_udp_port := 23000, remote_ip := "172.18.99.10", data_weight := 1, signalling_weight := 1 } }, nsvci := 98 } -NSVCI98(69)@02e2e6faa13c: NSVC 98 State Transition: NSVC_S_DEAD_BLOCKED (1) -> NSVC_S_DEAD_BLOCKED (1) -NSVCI98(69)@02e2e6faa13c: Provider Link came up: sending NS-RESET -NSVCI98(69)@02e2e6faa13c: NSVC 98 State Transition: NSVC_S_WAIT_RESET (2) -> NSVC_S_ALIVE_BLOCKED (3) -NSVCI98(69)@02e2e6faa13c: Rx NS-ALIVE-ACK: stopping Tns-alive; starting Tns-test -NSVCI98(69)@02e2e6faa13c: NSVC 98 State Transition: NSVC_S_ALIVE_BLOCKED (3) -> NSVC_S_ALIVE_UNBLOCKED (4) SGSN_Test-Gb2-NS2(70)@02e2e6faa13c: Creating NSIP provider for "172.18.99.103":21012 SGSN_Test-Gb1-BSSGP1(66)@02e2e6faa13c: BVCI(0) Rx BVC-RESET-ACK SGSN_Test-Gb1-BSSGP1(66)@02e2e6faa13c: BVCI(0) State Transition: BVC_S_WAIT_NS_ALIVE_UNBLOCKED (1) -> BVC_S_UNBLOCKED (3) SGSN_Test-Gb2-BSSGP2(71)@02e2e6faa13c: BVCI(0) State Transition: BVC_S_WAIT_RESET (0) -> BVC_S_WAIT_NS_ALIVE_UNBLOCKED (1) TC_attach_umts_aka_gsm_sres-BVCI210(68)@02e2e6faa13c: BVCI(210) State Transition: BVC_S_BLOCKED (2) -> BVC_S_UNBLOCKED (3) SGSN_Test-Gb2-NS2(70)@02e2e6faa13c: Starting NSVC component for { provider := { ip := { address_family := AF_INET (2), local_udp_port := 21012, local_ip := "172.18.99.103", remote_udp_port := 23000, remote_ip := "172.18.99.10", data_weight := 1, signalling_weight := 1 } }, nsvci := 99 } -NSVCI99(74)@02e2e6faa13c: NSVC 99 State Transition: NSVC_S_DEAD_BLOCKED (1) -> NSVC_S_DEAD_BLOCKED (1) -NSVCI99(74)@02e2e6faa13c: Provider Link came up: sending NS-RESET -NSVCI99(74)@02e2e6faa13c: NSVC 99 State Transition: NSVC_S_WAIT_RESET (2) -> NSVC_S_ALIVE_BLOCKED (3) -NSVCI99(74)@02e2e6faa13c: Rx NS-ALIVE-ACK: stopping Tns-alive; starting Tns-test -NSVCI99(74)@02e2e6faa13c: NSVC 99 State Transition: NSVC_S_ALIVE_BLOCKED (3) -> NSVC_S_ALIVE_UNBLOCKED (4) SGSN_Test-Gb2-BSSGP2(71)@02e2e6faa13c: BVCI(0) Rx BVC-RESET-ACK SGSN_Test-Gb2-BSSGP2(71)@02e2e6faa13c: BVCI(0) State Transition: BVC_S_WAIT_NS_ALIVE_UNBLOCKED (1) -> BVC_S_UNBLOCKED (3) TC_attach_umts_aka_gsm_sres-BVCI220(72)@02e2e6faa13c: BVCI(220) State Transition: BVC_S_BLOCKED (2) -> BVC_S_UNBLOCKED (3) SGSN_Test-GSUP-IPA(75)@02e2e6faa13c: Established a new IPA connection (conn_id=2) SGSN_Test-GSUP-IPA(75)@02e2e6faa13c: CCM Tx:{ msg_type := IPAC_MSGT_ID_GET (4), u := { get := { { len := 1, tag := IPAC_IDTAG_UNITNAME (1) }, { len := 1, tag := IPAC_IDTAG_UNITID (8) } } } } SGSN_Test-GSUP-IPA(75)@02e2e6faa13c: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit } SGSN_Test-GSUP-IPA(75)@02e2e6faa13c: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit } SGSN_Test-GSUP-IPA(75)@02e2e6faa13c: CCM Rx:{ msg_type := IPAC_MSGT_ID_RESP (5), u := { resp := { { len := 24, tag := IPAC_IDTAG_UNITNAME (1), data := '5347534E2D30302D30302D30302D30302D30302D303000'O }, { len := 7, tag := IPAC_IDTAG_UNITID (8), data := '302F302F3000'O } } } } SGSN_Test-GSUP-IPA(75)@02e2e6faa13c: IPA ID RESP: { { len := 24, tag := IPAC_IDTAG_UNITNAME (1), data := '5347534E2D30302D30302D30302D30302D30302D303000'O }, { len := 7, tag := IPAC_IDTAG_UNITID (8), data := '302F302F3000'O } } SGSN_Test-GSUP-IPA(75)@02e2e6faa13c: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } SGSN_Test-GSUP-IPA(75)@02e2e6faa13c: CCM Rx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } TC_attach_umts_aka_gsm_sres-BVCI196(63)@02e2e6faa13c: Adding Client=TC_attach_umts_aka_gsm_sres(78), IMSI='262420000001003'H, TLLI='DA1A0B85'O, index=0 SGSN_Test-GSUP(76)@02e2e6faa13c: Created GsupExpect[0] for "262420000001003" to be handled at TC_attach_umts_aka_gsm_sres(78) TC_attach_umts_aka_gsm_sres(78)@02e2e6faa13c: GSUP sends 2G and 3G auth tuples{ tag := OSMO_GSUP_AUTH_TUPLE_IE (3), len := 0, val := { auth_tuple := { { tag := OSMO_GSUP_RAND_IE (32), len := 0, val := { rand := 'C7EA567D0BA75AF419B7A4EE327C79FB'O } }, { tag := OSMO_GSUP_SRES_IE (33), len := 0, val := { sres := '0A770680'O } }, { tag := OSMO_GSUP_KC_IE (34), len := 0, val := { kc := '39433953CE796AB7'O } }, { tag := OSMO_GSUP_IK_IE (35), len := 0, val := { ik := '54B5C7459C2C8AA0934E8B33308FD828'O } }, { tag := OSMO_GSUP_CK_IE (36), len := 0, val := { ck := '5726F1C681C52C908FEC48A88F0CFE77'O } }, { tag := OSMO_GSUP_AUTN_IE (37), len := 0, val := { autn := 'B139691EF07327C367AFD85A179D22CD'O } }, { tag := OSMO_GSUP_RES_IE (39), len := 0, val := { res := 'AF4F681DDECDAF3A'O } } } } } SGSN_Test-GSUP(76)@02e2e6faa13c: Found GsupExpect[0] for "262420000001003" handled at TC_attach_umts_aka_gsm_sres(78) SGSN_Test-GSUP(76)@02e2e6faa13c: Added IMSI table entry 0TC_attach_umts_aka_gsm_sres(78)"262420000001003" TC_attach_umts_aka_gsm_sres(78)@02e2e6faa13c: setverdict(pass): none -> pass TC_attach_umts_aka_gsm_sres-BVCI196(63)@02e2e6faa13c: Removing Client IMSI='262420000001003'H, index=0 TC_attach_umts_aka_gsm_sres(78)@02e2e6faa13c: Final verdict of PTC: pass -NSVCI98(69)@02e2e6faa13c: Final verdict of PTC: none SGSN_Test-Gb0-BSSGP0(61)@02e2e6faa13c: Final verdict of PTC: none SGSN_Test-Gb1-NS1(65)@02e2e6faa13c: Final verdict of PTC: none SGSN_Test-Gb2-BSSGP2(71)@02e2e6faa13c: Final verdict of PTC: none SGSN_Test-Gb1-BSSGP1(66)@02e2e6faa13c: Final verdict of PTC: none TC_attach_umts_aka_gsm_sres-BVCI196(63)@02e2e6faa13c: Final verdict of PTC: none TC_attach_umts_aka_gsm_sres-BVCI210(68)@02e2e6faa13c: Final verdict of PTC: none SGSN_Test-Gb0-NS0(60)@02e2e6faa13c: Final verdict of PTC: none TC_attach_umts_aka_gsm_sres-BVCI220(72)@02e2e6faa13c: Final verdict of PTC: none -NSVCI97(64)@02e2e6faa13c: Final verdict of PTC: none -NSVCI97-provIP(62)@02e2e6faa13c: Final verdict of PTC: none SGSN_Test-GTP(77)@02e2e6faa13c: Final verdict of PTC: none -NSVCI98-provIP(67)@02e2e6faa13c: Final verdict of PTC: none SGSN_Test-Gb2-NS2(70)@02e2e6faa13c: Final verdict of PTC: none SGSN_Test-GSUP(76)@02e2e6faa13c: Final verdict of PTC: none SGSN_Test-GSUP-IPA(75)@02e2e6faa13c: Final verdict of PTC: none -NSVCI99(74)@02e2e6faa13c: Final verdict of PTC: none -NSVCI99-provIP(73)@02e2e6faa13c: Final verdict of PTC: none MTC@02e2e6faa13c: Setting final verdict of the test case. MTC@02e2e6faa13c: Local verdict of MTC: none MTC@02e2e6faa13c: Local verdict of PTC SGSN_Test-Gb0-NS0(60): none (none -> none) MTC@02e2e6faa13c: Local verdict of PTC SGSN_Test-Gb0-BSSGP0(61): none (none -> none) MTC@02e2e6faa13c: Local verdict of PTC -NSVCI97-provIP(62): none (none -> none) MTC@02e2e6faa13c: Local verdict of PTC TC_attach_umts_aka_gsm_sres-BVCI196(63): none (none -> none) MTC@02e2e6faa13c: Local verdict of PTC -NSVCI97(64): none (none -> none) MTC@02e2e6faa13c: Local verdict of PTC SGSN_Test-Gb1-NS1(65): none (none -> none) MTC@02e2e6faa13c: Local verdict of PTC SGSN_Test-Gb1-BSSGP1(66): none (none -> none) MTC@02e2e6faa13c: Local verdict of PTC -NSVCI98-provIP(67): none (none -> none) MTC@02e2e6faa13c: Local verdict of PTC TC_attach_umts_aka_gsm_sres-BVCI210(68): none (none -> none) MTC@02e2e6faa13c: Local verdict of PTC -NSVCI98(69): none (none -> none) MTC@02e2e6faa13c: Local verdict of PTC SGSN_Test-Gb2-NS2(70): none (none -> none) MTC@02e2e6faa13c: Local verdict of PTC SGSN_Test-Gb2-BSSGP2(71): none (none -> none) MTC@02e2e6faa13c: Local verdict of PTC TC_attach_umts_aka_gsm_sres-BVCI220(72): none (none -> none) MTC@02e2e6faa13c: Local verdict of PTC -NSVCI99-provIP(73): none (none -> none) MTC@02e2e6faa13c: Local verdict of PTC -NSVCI99(74): none (none -> none) MTC@02e2e6faa13c: Local verdict of PTC SGSN_Test-GSUP-IPA(75): none (none -> none) MTC@02e2e6faa13c: Local verdict of PTC SGSN_Test-GSUP(76): none (none -> none) MTC@02e2e6faa13c: Local verdict of PTC SGSN_Test-GTP(77): none (none -> none) MTC@02e2e6faa13c: Local verdict of PTC TC_attach_umts_aka_gsm_sres(78): pass (none -> pass) MTC@02e2e6faa13c: Test case TC_attach_umts_aka_gsm_sres finished. Verdict: pass MTC@02e2e6faa13c: Starting external command `../ttcn3-tcpdump-stop.sh SGSN_Tests.TC_attach_umts_aka_gsm_sres pass'. Tue Jan 17 05:30:34 UTC 2023 [1;32m====== SGSN_Tests.TC_attach_umts_aka_gsm_sres pass ======[0m Saving talloc report from 172.18.99.10:4245 to SGSN_Tests.TC_attach_umts_aka_gsm_sres.talloc Waiting for packet dumper to finish... 0 (prev_count=-1, count=87775) MTC@02e2e6faa13c: External command `../ttcn3-tcpdump-stop.sh SGSN_Tests.TC_attach_umts_aka_gsm_sres pass' was executed successfully (exit status: 0). MTC@02e2e6faa13c: Starting external command `../ttcn3-tcpdump-start.sh SGSN_Tests.TC_attach_auth_id_timeout'. ------ SGSN_Tests.TC_attach_auth_id_timeout ------ Tue Jan 17 05:30:36 UTC 2023 NOTE: unable to use dumpcap due to missing capabilities or suid bit Waiting for packet dumper to start... 0 MTC@02e2e6faa13c: External command `../ttcn3-tcpdump-start.sh SGSN_Tests.TC_attach_auth_id_timeout' was executed successfully (exit status: 0). MTC@02e2e6faa13c: Test case TC_attach_auth_id_timeout started. SGSN_Test-Gb0-NS0(79)@02e2e6faa13c: Creating NSIP provider for "172.18.99.103":21010 SGSN_Test-Gb0-BSSGP0(80)@02e2e6faa13c: BVCI(0) State Transition: BVC_S_WAIT_RESET (0) -> BVC_S_WAIT_NS_ALIVE_UNBLOCKED (1) SGSN_Test-Gb0-NS0(79)@02e2e6faa13c: Starting NSVC component for { provider := { ip := { address_family := AF_INET (2), local_udp_port := 21010, local_ip := "172.18.99.103", remote_udp_port := 23000, remote_ip := "172.18.99.10", data_weight := 1, signalling_weight := 1 } }, nsvci := 97 } -NSVCI97(83)@02e2e6faa13c: NSVC 97 State Transition: NSVC_S_DEAD_BLOCKED (1) -> NSVC_S_DEAD_BLOCKED (1) -NSVCI97(83)@02e2e6faa13c: Provider Link came up: sending NS-RESET -NSVCI97(83)@02e2e6faa13c: NSVC 97 State Transition: NSVC_S_WAIT_RESET (2) -> NSVC_S_ALIVE_BLOCKED (3) -NSVCI97(83)@02e2e6faa13c: Rx NS-ALIVE-ACK: stopping Tns-alive; starting Tns-test -NSVCI97(83)@02e2e6faa13c: NSVC 97 State Transition: NSVC_S_ALIVE_BLOCKED (3) -> NSVC_S_ALIVE_UNBLOCKED (4) SGSN_Test-Gb1-NS1(84)@02e2e6faa13c: Creating NSIP provider for "172.18.99.103":21011 SGSN_Test-Gb0-BSSGP0(80)@02e2e6faa13c: BVCI(0) Rx BVC-RESET-ACK SGSN_Test-Gb0-BSSGP0(80)@02e2e6faa13c: BVCI(0) State Transition: BVC_S_WAIT_NS_ALIVE_UNBLOCKED (1) -> BVC_S_UNBLOCKED (3) SGSN_Test-Gb1-BSSGP1(85)@02e2e6faa13c: BVCI(0) State Transition: BVC_S_WAIT_RESET (0) -> BVC_S_WAIT_NS_ALIVE_UNBLOCKED (1) SGSN_Test-Gb1-NS1(84)@02e2e6faa13c: Starting NSVC component for { provider := { ip := { address_family := AF_INET (2), local_udp_port := 21011, local_ip := "172.18.99.103", remote_udp_port := 23000, remote_ip := "172.18.99.10", data_weight := 1, signalling_weight := 1 } }, nsvci := 98 } TC_attach_auth_id_timeout-BVCI196(82)@02e2e6faa13c: BVCI(196) State Transition: BVC_S_BLOCKED (2) -> BVC_S_UNBLOCKED (3) -NSVCI98(88)@02e2e6faa13c: NSVC 98 State Transition: NSVC_S_DEAD_BLOCKED (1) -> NSVC_S_DEAD_BLOCKED (1) -NSVCI98(88)@02e2e6faa13c: Provider Link came up: sending NS-RESET -NSVCI98(88)@02e2e6faa13c: NSVC 98 State Transition: NSVC_S_WAIT_RESET (2) -> NSVC_S_ALIVE_BLOCKED (3) -NSVCI98(88)@02e2e6faa13c: Rx NS-ALIVE-ACK: stopping Tns-alive; starting Tns-test -NSVCI98(88)@02e2e6faa13c: NSVC 98 State Transition: NSVC_S_ALIVE_BLOCKED (3) -> NSVC_S_ALIVE_UNBLOCKED (4) SGSN_Test-Gb2-NS2(89)@02e2e6faa13c: Creating NSIP provider for "172.18.99.103":21012 SGSN_Test-Gb1-BSSGP1(85)@02e2e6faa13c: BVCI(0) Rx BVC-RESET-ACK SGSN_Test-Gb1-BSSGP1(85)@02e2e6faa13c: BVCI(0) State Transition: BVC_S_WAIT_NS_ALIVE_UNBLOCKED (1) -> BVC_S_UNBLOCKED (3) SGSN_Test-Gb2-BSSGP2(90)@02e2e6faa13c: BVCI(0) State Transition: BVC_S_WAIT_RESET (0) -> BVC_S_WAIT_NS_ALIVE_UNBLOCKED (1) TC_attach_auth_id_timeout-BVCI210(87)@02e2e6faa13c: BVCI(210) State Transition: BVC_S_BLOCKED (2) -> BVC_S_UNBLOCKED (3) SGSN_Test-Gb2-NS2(89)@02e2e6faa13c: Starting NSVC component for { provider := { ip := { address_family := AF_INET (2), local_udp_port := 21012, local_ip := "172.18.99.103", remote_udp_port := 23000, remote_ip := "172.18.99.10", data_weight := 1, signalling_weight := 1 } }, nsvci := 99 } -NSVCI99(94)@02e2e6faa13c: NSVC 99 State Transition: NSVC_S_DEAD_BLOCKED (1) -> NSVC_S_DEAD_BLOCKED (1) -NSVCI99(94)@02e2e6faa13c: Provider Link came up: sending NS-RESET -NSVCI99(94)@02e2e6faa13c: NSVC 99 State Transition: NSVC_S_WAIT_RESET (2) -> NSVC_S_ALIVE_BLOCKED (3) -NSVCI99(94)@02e2e6faa13c: Rx NS-ALIVE-ACK: stopping Tns-alive; starting Tns-test -NSVCI99(94)@02e2e6faa13c: NSVC 99 State Transition: NSVC_S_ALIVE_BLOCKED (3) -> NSVC_S_ALIVE_UNBLOCKED (4) SGSN_Test-Gb2-BSSGP2(90)@02e2e6faa13c: BVCI(0) Rx BVC-RESET-ACK SGSN_Test-Gb2-BSSGP2(90)@02e2e6faa13c: BVCI(0) State Transition: BVC_S_WAIT_NS_ALIVE_UNBLOCKED (1) -> BVC_S_UNBLOCKED (3) TC_attach_auth_id_timeout-BVCI220(91)@02e2e6faa13c: BVCI(220) State Transition: BVC_S_BLOCKED (2) -> BVC_S_UNBLOCKED (3) SGSN_Test-GSUP-IPA(93)@02e2e6faa13c: Established a new IPA connection (conn_id=2) SGSN_Test-GSUP-IPA(93)@02e2e6faa13c: CCM Tx:{ msg_type := IPAC_MSGT_ID_GET (4), u := { get := { { len := 1, tag := IPAC_IDTAG_UNITNAME (1) }, { len := 1, tag := IPAC_IDTAG_UNITID (8) } } } } SGSN_Test-GSUP-IPA(93)@02e2e6faa13c: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit } SGSN_Test-GSUP-IPA(93)@02e2e6faa13c: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit } SGSN_Test-GSUP-IPA(93)@02e2e6faa13c: CCM Rx:{ msg_type := IPAC_MSGT_ID_RESP (5), u := { resp := { { len := 24, tag := IPAC_IDTAG_UNITNAME (1), data := '5347534E2D30302D30302D30302D30302D30302D303000'O }, { len := 7, tag := IPAC_IDTAG_UNITID (8), data := '302F302F3000'O } } } } SGSN_Test-GSUP-IPA(93)@02e2e6faa13c: IPA ID RESP: { { len := 24, tag := IPAC_IDTAG_UNITNAME (1), data := '5347534E2D30302D30302D30302D30302D30302D303000'O }, { len := 7, tag := IPAC_IDTAG_UNITID (8), data := '302F302F3000'O } } SGSN_Test-GSUP-IPA(93)@02e2e6faa13c: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } SGSN_Test-GSUP-IPA(93)@02e2e6faa13c: CCM Rx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } TC_attach_auth_id_timeout-BVCI196(82)@02e2e6faa13c: Adding Client=TC_attach_auth_id_timeout(97), IMSI='262420000000002'H, TLLI='E1E54217'O, index=0 SGSN_Test-GSUP(95)@02e2e6faa13c: Created GsupExpect[0] for "262420000000002" to be handled at TC_attach_auth_id_timeout(97) -NSVCI97(83)@02e2e6faa13c: Tns-test expired: sending NS-ALIVE -NSVCI97(83)@02e2e6faa13c: Rx NS-ALIVE-ACK: stopping Tns-alive; starting Tns-test -NSVCI98(88)@02e2e6faa13c: Tns-test expired: sending NS-ALIVE -NSVCI98(88)@02e2e6faa13c: Rx NS-ALIVE-ACK: stopping Tns-alive; starting Tns-test -NSVCI99(94)@02e2e6faa13c: Tns-test expired: sending NS-ALIVE -NSVCI99(94)@02e2e6faa13c: Rx NS-ALIVE-ACK: stopping Tns-alive; starting Tns-test -NSVCI97(83)@02e2e6faa13c: Tns-test expired: sending NS-ALIVE -NSVCI97(83)@02e2e6faa13c: Rx NS-ALIVE-ACK: stopping Tns-alive; starting Tns-test -NSVCI98(88)@02e2e6faa13c: Tns-test expired: sending NS-ALIVE -NSVCI98(88)@02e2e6faa13c: Rx NS-ALIVE-ACK: stopping Tns-alive; starting Tns-test -NSVCI99(94)@02e2e6faa13c: Tns-test expired: sending NS-ALIVE -NSVCI99(94)@02e2e6faa13c: Rx NS-ALIVE-ACK: stopping Tns-alive; starting Tns-test SGSN_Test-GSUP-IPA(93)@02e2e6faa13c: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit } SGSN_Test-GSUP-IPA(93)@02e2e6faa13c: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit } + clean_up_common + set +e + set +x ### Clean up ### + trap - EXIT INT TERM 0 + type clean_up + network_clean + docker network inspect ttcn3-sgsn-test + grep Name + cut -d : -f2 + awk -F" NR>1{print $2} + xargs -rn1 docker kill jenkins-ttcn3-sgsn-test-latest-1561-stp jenkins-ttcn3-sgsn-test-latest-1561-sgsn + network_remove + set +x Removing network ttcn3-sgsn-test + docker network remove ttcn3-sgsn-test Error: No such network: ttcn3-sgsn-test + rm -rf <https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-latest/1561/artifac…> + fix_perms + set +x Fixing permissions + docker run --rm -v <https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-latest/ws/logs>:/data -v <https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-latest/ws/_cache>:/cache --name jenkins-ttcn3-sgsn-test-latest-1561-cleaner debian:bullseye chmod -R a+rX /data/ /cache/ + collect_logs + cat <https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-latest/1561/artifac…> Build step 'Execute shell' marked build as failure Recording test results [Checks API] No suitable checks publisher found.

2 years, 5 months

Build failed in Jenkins: ttcn3-pcu-test-sns #1434

by jenkins＠lists.osmocom.org

See <https://jenkins.osmocom.org/jenkins/job/ttcn3-pcu-test-sns/1434/display/red…> Changes: [Oliver Smith] ttcn3-*/jenkins*.sh: use $SUBNET everywhere [Oliver Smith] network_create: find free subnet automatically ------------------------------------------ [...truncated 35.66 KB...] make[4]: Leaving directory '/tmp/osmo-pcu/contrib/systemd' make[3]: Leaving directory '/tmp/osmo-pcu/contrib/systemd' make[3]: Entering directory '/tmp/osmo-pcu/contrib' make[4]: Entering directory '/tmp/osmo-pcu/contrib' make[4]: Nothing to be done for 'install-exec-am'. make[4]: Nothing to be done for 'install-data-am'. make[4]: Leaving directory '/tmp/osmo-pcu/contrib' make[3]: Leaving directory '/tmp/osmo-pcu/contrib' make[2]: Leaving directory '/tmp/osmo-pcu/contrib' make[2]: Entering directory '/tmp/osmo-pcu' make[3]: Entering directory '/tmp/osmo-pcu' make[3]: Nothing to be done for 'install-exec-am'. make[3]: Nothing to be done for 'install-data-am'. make[3]: Leaving directory '/tmp/osmo-pcu' make[2]: Leaving directory '/tmp/osmo-pcu' make[1]: Leaving directory '/tmp/osmo-pcu' Removing intermediate container 5660663f2108 ---> 4e18c8c2188d Step 11/14 : VOLUME /data ---> Running in 979e0e5d98d5 Removing intermediate container 979e0e5d98d5 ---> 8f182c8ca70b Step 12/14 : COPY osmo-pcu.cfg /data/osmo-pcu.cfg ---> 58cb891b1838 Step 13/14 : WORKDIR /data ---> Running in 3039a957451e Removing intermediate container 3039a957451e ---> e869c456f2ef Step 14/14 : CMD ["/usr/local/bin/osmo-pcu", "-i", "172.18.0.230"] ---> Running in 9046357cd200 Removing intermediate container 9046357cd200 ---> 577edb4beb54 [Warning] One or more build-args [OSMO_BSC_BRANCH REGISTRY UID OSMO_UECUPS_BRANCH OSMO_BTS_BRANCH OSMO_HLR_BRANCH OSMO_SIP_BRANCH OSMO_HNODEB_BRANCH OSMO_STP_BRANCH UPSTREAM_DISTRO LIBOSMOCORE_BRANCH OPEN5GS_BRANCH OSMO_GGSN_BRANCH OSMO_MGW_BRANCH OSMO_NITB_BRANCH OSMO_TTCN3_BRANCH OSMOCOM_REPO_MIRROR OSMOCOM_REPO_TESTSUITE_MIRROR OSMO_CBC_BRANCH OSMO_IUH_BRANCH OSMO_SGSN_BRANCH OSMO_BB_BRANCH OSMO_GBPROXY_BRANCH OSMO_GSM_TESTER_BRANCH OSMO_HNBGW_BRANCH OSMOCOM_REPO_VERSION OSMO_MSC_BRANCH OSMOCOM_REPO_PATH] were not consumed Successfully built 577edb4beb54 Successfully tagged osmocom-build/osmo-pcu-master:latest rm -rf .common make: Leaving directory '<https://jenkins.osmocom.org/jenkins/job/ttcn3-pcu-test-sns/ws/osmo-pcu-mast…'> + docker_image_exists osmo-pcu-master + docker images -q osmocom-build/osmo-pcu-master + test -n 577edb4beb54 + list_osmo_packages debian-bullseye osmo-pcu-master + local distro=debian-bullseye + local image=osmo-pcu-master + local docker_run_sh=docker run --rm --entrypoint=/bin/sh osmocom-build/osmo-pcu-master -c + [ -n ] + set +x ### Installed Osmocom packages in: osmo-pcu-master ### ii libosmocodec0:amd64 1.7.0.96.a13d.202301170007 amd64 Osmo codec library ii libosmocoding0:amd64 1.7.0.96.a13d.202301170007 amd64 Osmo coding library ii libosmocore 1.7.0.96.a13d.202301170007 amd64 Open Source MObile COMmunications CORE library (metapackage) ii libosmocore-dev:amd64 1.7.0.96.a13d.202301170007 amd64 Development headers for Open Source MObile COMmunications CORE library ii libosmocore19:amd64 1.7.0.96.a13d.202301170007 amd64 Osmo Core library ii libosmoctrl0:amd64 1.7.0.96.a13d.202301170007 amd64 Osmo control library ii libosmogb14:amd64 1.7.0.96.a13d.202301170007 amd64 Osmo GPRS GB library ii libosmogsm18:amd64 1.7.0.96.a13d.202301170007 amd64 Osmo GSM utility library ii libosmosim2:amd64 1.7.0.96.a13d.202301170007 amd64 Osmo SIM library ii libosmousb0:amd64 1.7.0.96.a13d.202301170007 amd64 Osmo USB library ii libosmovty9:amd64 1.7.0.96.a13d.202301170007 amd64 Osmo VTY library ii osmocom-nightly 202301170007 amd64 Dummy package, conflicts with ['osmocom-2022q1', 'osmocom-2022q2', 'osmocom-2023q1', 'osmocom-latest', 'osmocom-master'] + [ registry.osmocom.org = registry.osmocom.org ] + docker_depends ttcn3-pcu-test + local feed + echo debian-bullseye-titan + depends=debian-bullseye-titan + [ -n debian-bullseye-titan ] + docker_images_require debian-bullseye-titan + local i + local from_line + local pull_arg + local upstream_distro_arg + local distro_arg + local depends + local dir + [ registry.osmocom.org = registry.osmocom.org ] + docker pull registry.osmocom.org/osmocom-build/debian-bullseye-titan Using default tag: latest latest: Pulling from osmocom-build/debian-bullseye-titan Digest: sha256:33c02949d71e8f89cbe9ab1eed644c821e98199744d8b90820c9b324dff3d6d6 Status: Image is up to date for registry.osmocom.org/osmocom-build/debian-bullseye-titan:latest registry.osmocom.org/osmocom-build/debian-bullseye-titan:latest + continue + docker_distro_from_image_name ttcn3-pcu-test + echo debian-bullseye + distro_arg=debian-bullseye + [ -z ] + docker_upstream_distro_from_image_name ttcn3-pcu-test + echo debian:bullseye + upstream_distro_arg=debian:bullseye + docker_dir_from_image_name ttcn3-pcu-test + echo ttcn3-pcu-test + dir=ttcn3-pcu-test + pull_arg=--pull + grep ^FROM ../ttcn3-pcu-test/Dockerfile + from_line=FROM $REGISTRY/$USER/debian-bullseye-titan + echo FROM $REGISTRY/$USER/debian-bullseye-titan + grep -q $USER + pull_arg= + set +x Building image: ttcn3-pcu-test (export NO_DOCKER_IMAGE_BUILD=1 to prevent this) + make -C ../ttcn3-pcu-test BUILD_ARGS= UPSTREAM_DISTRO=debian:bullseye DISTRO=debian-bullseye IMAGE=osmocom-build/ttcn3-pcu-test make: Entering directory '<https://jenkins.osmocom.org/jenkins/job/ttcn3-pcu-test-sns/ws/ttcn3-pcu-test'> awk: fatal: cannot open file `.release' for reading: No such file or directory awk: fatal: cannot open file `.release' for reading: No such file or directory awk: fatal: cannot open file `.release' for reading: No such file or directory rm -rf .common cp -r <https://jenkins.osmocom.org/jenkins/job/ttcn3-pcu-test-sns/ws/common> .common INFO: .release created release=0.0.0 docker build \ --build-arg USER=osmocom-build \ --build-arg UID=1000 \ --build-arg REGISTRY=registry.osmocom.org \ --build-arg OSMO_TTCN3_BRANCH=master \ --build-arg UPSTREAM_DISTRO=debian:bullseye \ --build-arg DISTRO=debian-bullseye \ --build-arg OSMOCOM_REPO_MIRROR=https://downloads.osmocom.org \ --build-arg OSMOCOM_REPO_PATH=packages/osmocom: \ --build-arg OSMOCOM_REPO_VERSION=latest \ --build-arg OSMOCOM_REPO_TESTSUITE_MIRROR=https://downloads.osmocom.org \ --build-arg LIBOSMOCORE_BRANCH=master \ --build-arg OSMO_BB_BRANCH=master \ --build-arg OSMO_BSC_BRANCH=master \ --build-arg OSMO_BTS_BRANCH=master \ --build-arg OSMO_CBC_BRANCH=master \ --build-arg OSMO_GBPROXY_BRANCH=master \ --build-arg OSMO_GGSN_BRANCH=master \ --build-arg OSMO_GSM_TESTER_BRANCH=master \ --build-arg OSMO_HLR_BRANCH=master \ --build-arg OSMO_HNBGW_BRANCH=master \ --build-arg OSMO_HNODEB_BRANCH=master \ --build-arg OSMO_IUH_BRANCH=master \ --build-arg OSMO_MGW_BRANCH=master \ --build-arg OSMO_MSC_BRANCH=master \ --build-arg OSMO_NITB_BRANCH=master \ --build-arg OSMO_PCU_BRANCH=master \ --build-arg OSMO_SGSN_BRANCH=master \ --build-arg OSMO_SIP_BRANCH=master \ --build-arg OSMO_STP_BRANCH=master \ --build-arg OSMO_UECUPS_BRANCH=master \ --build-arg OPEN5GS_BRANCH=main \ -t osmocom-build/ttcn3-pcu-test:latest . Sending build context to Docker daemon 34.82kB Step 1/9 : ARG REGISTRY Step 2/9 : ARG USER Step 3/9 : FROM $REGISTRY/$USER/debian-bullseye-titan ---> c52b3a0afa2b Step 4/9 : ARG OSMO_TTCN3_BRANCH="master" ---> Using cache ---> 7fd781eb90de Step 5/9 : ADD https://gerrit.osmocom.org/plugins/gitiles/osmo-ttcn3-hacks/+/$OSMO_TTCN3_B… /tmp/commit ---> Using cache ---> 00095faa6a88 Step 6/9 : RUN ttcn3-docker-prepare "$OSMO_TTCN3_BRANCH" pcu ---> Using cache ---> c0743dc759cc Step 7/9 : VOLUME /data ---> Using cache ---> 108c9c58179d Step 8/9 : COPY PCU_Tests.cfg /data/PCU_Tests.cfg ---> Using cache ---> 544a51b7626c Step 9/9 : CMD ttcn3-docker-run pcu PCU_Tests ---> Using cache ---> e8bc26a3cc10 [Warning] One or more build-args [OSMO_BSC_BRANCH OSMO_SIP_BRANCH OPEN5GS_BRANCH OSMO_BB_BRANCH OSMO_BTS_BRANCH OSMO_CBC_BRANCH OSMO_HNBGW_BRANCH UPSTREAM_DISTRO OSMO_MGW_BRANCH OSMO_NITB_BRANCH OSMO_STP_BRANCH OSMOCOM_REPO_MIRROR LIBOSMOCORE_BRANCH OSMOCOM_REPO_PATH OSMOCOM_REPO_TESTSUITE_MIRROR OSMO_GGSN_BRANCH OSMO_HNODEB_BRANCH OSMO_IUH_BRANCH OSMO_SGSN_BRANCH OSMO_GBPROXY_BRANCH OSMO_HLR_BRANCH OSMO_MSC_BRANCH OSMO_PCU_BRANCH OSMO_UECUPS_BRANCH UID DISTRO OSMOCOM_REPO_VERSION OSMO_GSM_TESTER_BRANCH] were not consumed Successfully built e8bc26a3cc10 Successfully tagged osmocom-build/ttcn3-pcu-test:latest rm -rf .common make: Leaving directory '<https://jenkins.osmocom.org/jenkins/job/ttcn3-pcu-test-sns/ws/ttcn3-pcu-test'> + docker_image_exists ttcn3-pcu-test + docker images -q osmocom-build/ttcn3-pcu-test + test -n e8bc26a3cc10 + list_osmo_packages debian-bullseye ttcn3-pcu-test + local distro=debian-bullseye + local image=ttcn3-pcu-test + local docker_run_sh=docker run --rm --entrypoint=/bin/sh osmocom-build/ttcn3-pcu-test -c + [ -n ] + return + set_clean_up_trap + trap clean_up_common EXIT INT TERM 0 + set -e + mkdir <https://jenkins.osmocom.org/jenkins/job/ttcn3-pcu-test-sns/1434/artifact/lo…> + mkdir <https://jenkins.osmocom.org/jenkins/job/ttcn3-pcu-test-sns/1434/artifact/lo…> + cp sns/PCU_Tests.cfg <https://jenkins.osmocom.org/jenkins/job/ttcn3-pcu-test-sns/1434/artifact/lo…> + write_mp_osmo_repo <https://jenkins.osmocom.org/jenkins/job/ttcn3-pcu-test-sns/1434/artifact/lo…> + local repo=nightly + local config=<https://jenkins.osmocom.org/jenkins/job/ttcn3-pcu-test-sns/1434/artifact/lo…> + local line + [ -e <https://jenkins.osmocom.org/jenkins/job/ttcn3-pcu-test-sns/1434/artifact/lo…> ] + line=Misc_Helpers.mp_osmo_repo := "nightly" + sed -i s/\[MODULE_PARAMETERS\]/\[MODULE_PARAMETERS\]\nMisc_Helpers.mp_osmo_repo := "nightly"/g <https://jenkins.osmocom.org/jenkins/job/ttcn3-pcu-test-sns/1434/artifact/lo…> + mkdir <https://jenkins.osmocom.org/jenkins/job/ttcn3-pcu-test-sns/1434/artifact/lo…> + mkdir <https://jenkins.osmocom.org/jenkins/job/ttcn3-pcu-test-sns/1434/artifact/lo…> + cp sns/osmo-pcu.cfg <https://jenkins.osmocom.org/jenkins/job/ttcn3-pcu-test-sns/1434/artifact/lo…> + mkdir <https://jenkins.osmocom.org/jenkins/job/ttcn3-pcu-test-sns/1434/artifact/lo…> + network_create + docker network ls + grep -q ttcn3-pcu-test-sns + set +x Removing stale network and containers... + network_clean + docker network inspect ttcn3-pcu-test-sns + grep Name + cut -d : -f2 + awk -F" NR>1{print $2} + xargs -rn1 docker kill jenkins-TTCN3-centos-pcu-test-sns-978-ttcn3-pcu-test-sns jenkins-TTCN3-centos-pcu-test-sns-978-pcu-sns + network_remove + set +x Removing network ttcn3-pcu-test-sns + docker network remove ttcn3-pcu-test-sns Error: No such network: ttcn3-pcu-test-sns + clean_up_common + set +e + set +x ### Clean up ### + trap - EXIT INT TERM 0 + type clean_up + network_clean + docker network inspect ttcn3-pcu-test-sns + grep Name + cut -d : -f2 + awk -F" NR>1{print $2} + xargs -rn1 docker kill Error: No such network: ttcn3-pcu-test-sns + network_remove + set +x Removing network ttcn3-pcu-test-sns + docker network remove ttcn3-pcu-test-sns Error: No such network: ttcn3-pcu-test-sns + rm -rf <https://jenkins.osmocom.org/jenkins/job/ttcn3-pcu-test-sns/1434/artifact/lo…> + fix_perms + set +x Fixing permissions + docker run --rm -v <https://jenkins.osmocom.org/jenkins/job/ttcn3-pcu-test-sns/ws/logs>:/data -v <https://jenkins.osmocom.org/jenkins/job/ttcn3-pcu-test-sns/ws/_cache>:/cache --name jenkins-ttcn3-pcu-test-sns-1434-cleaner debian:bullseye chmod -R a+rX /data/ /cache/ + collect_logs + cat <https://jenkins.osmocom.org/jenkins/job/ttcn3-pcu-test-sns/1434/artifact/lo…> cat: '<https://jenkins.osmocom.org/jenkins/job/ttcn3-pcu-test-sns/1434/artifact/lo…'>: No such file or directory + true Build step 'Execute shell' marked build as failure Recording test results ERROR: Step ‘Publish JUnit test result report’ failed: No test report files were found. Configuration error?

2 years, 5 months

Build failed in Jenkins: TTCN3-centos-pcu-test-sns #978

by jenkins＠lists.osmocom.org

See <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-pcu-test-sns/978/displ…> Changes: [Oliver Smith] ttcn3-*/jenkins*.sh: use $SUBNET everywhere [Oliver Smith] network_create: find free subnet automatically ------------------------------------------ [...truncated 17.02 KB...] + from_line=FROM $REGISTRY/$USER/debian-bullseye-titan + echo FROM $REGISTRY/$USER/debian-bullseye-titan + grep -q $USER + pull_arg= + set +x Building image: ttcn3-pcu-test (export NO_DOCKER_IMAGE_BUILD=1 to prevent this) + make -C ../ttcn3-pcu-test BUILD_ARGS= UPSTREAM_DISTRO=debian:bullseye DISTRO=debian-bullseye IMAGE=osmocom-build/ttcn3-pcu-test make: Entering directory '<https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-pcu-test-sns/ws/ttcn3-…'> awk: fatal: cannot open file `.release' for reading: No such file or directory awk: fatal: cannot open file `.release' for reading: No such file or directory awk: fatal: cannot open file `.release' for reading: No such file or directory rm -rf .common cp -r <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-pcu-test-sns/ws/common> .common INFO: .release created release=0.0.0 docker build \ --build-arg USER=osmocom-build \ --build-arg UID=1000 \ --build-arg REGISTRY=registry.osmocom.org \ --build-arg OSMO_TTCN3_BRANCH=master \ --build-arg UPSTREAM_DISTRO=debian:bullseye \ --build-arg DISTRO=debian-bullseye \ --build-arg OSMOCOM_REPO_MIRROR=https://downloads.osmocom.org \ --build-arg OSMOCOM_REPO_PATH=packages/osmocom: \ --build-arg OSMOCOM_REPO_VERSION=latest \ --build-arg OSMOCOM_REPO_TESTSUITE_MIRROR=https://downloads.osmocom.org \ --build-arg LIBOSMOCORE_BRANCH=master \ --build-arg OSMO_BB_BRANCH=master \ --build-arg OSMO_BSC_BRANCH=master \ --build-arg OSMO_BTS_BRANCH=master \ --build-arg OSMO_CBC_BRANCH=master \ --build-arg OSMO_GBPROXY_BRANCH=master \ --build-arg OSMO_GGSN_BRANCH=master \ --build-arg OSMO_GSM_TESTER_BRANCH=master \ --build-arg OSMO_HLR_BRANCH=master \ --build-arg OSMO_HNBGW_BRANCH=master \ --build-arg OSMO_HNODEB_BRANCH=master \ --build-arg OSMO_IUH_BRANCH=master \ --build-arg OSMO_MGW_BRANCH=master \ --build-arg OSMO_MSC_BRANCH=master \ --build-arg OSMO_NITB_BRANCH=master \ --build-arg OSMO_PCU_BRANCH=master \ --build-arg OSMO_SGSN_BRANCH=master \ --build-arg OSMO_SIP_BRANCH=master \ --build-arg OSMO_STP_BRANCH=master \ --build-arg OSMO_UECUPS_BRANCH=master \ --build-arg OPEN5GS_BRANCH=main \ -t osmocom-build/ttcn3-pcu-test:latest . Sending build context to Docker daemon 34.82kB Step 1/9 : ARG REGISTRY Step 2/9 : ARG USER Step 3/9 : FROM $REGISTRY/$USER/debian-bullseye-titan ---> c52b3a0afa2b Step 4/9 : ARG OSMO_TTCN3_BRANCH="master" ---> Using cache ---> 7fd781eb90de Step 5/9 : ADD https://gerrit.osmocom.org/plugins/gitiles/osmo-ttcn3-hacks/+/$OSMO_TTCN3_B… /tmp/commit ---> Using cache ---> 00095faa6a88 Step 6/9 : RUN ttcn3-docker-prepare "$OSMO_TTCN3_BRANCH" pcu ---> Using cache ---> c0743dc759cc Step 7/9 : VOLUME /data ---> Using cache ---> 108c9c58179d Step 8/9 : COPY PCU_Tests.cfg /data/PCU_Tests.cfg ---> Using cache ---> 544a51b7626c Step 9/9 : CMD ttcn3-docker-run pcu PCU_Tests ---> Using cache ---> e8bc26a3cc10 [Warning] One or more build-args [UPSTREAM_DISTRO OSMO_BSC_BRANCH OSMO_MGW_BRANCH OSMO_PCU_BRANCH OSMO_UECUPS_BRANCH UID OSMO_GGSN_BRANCH OSMO_IUH_BRANCH OSMO_NITB_BRANCH OSMO_SIP_BRANCH LIBOSMOCORE_BRANCH OSMO_HNODEB_BRANCH OSMO_SGSN_BRANCH OSMO_HNBGW_BRANCH OSMO_STP_BRANCH OPEN5GS_BRANCH OSMOCOM_REPO_MIRROR OSMOCOM_REPO_PATH OSMO_CBC_BRANCH DISTRO OSMOCOM_REPO_VERSION OSMO_GSM_TESTER_BRANCH OSMO_BB_BRANCH OSMO_GBPROXY_BRANCH OSMOCOM_REPO_TESTSUITE_MIRROR OSMO_BTS_BRANCH OSMO_HLR_BRANCH OSMO_MSC_BRANCH] were not consumed Successfully built e8bc26a3cc10 Successfully tagged osmocom-build/ttcn3-pcu-test:latest rm -rf .common make: Leaving directory '<https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-pcu-test-sns/ws/ttcn3-…'> + docker_image_exists ttcn3-pcu-test + docker images -q osmocom-build/ttcn3-pcu-test + test -n e8bc26a3cc10 + list_osmo_packages debian-bullseye ttcn3-pcu-test + local distro=debian-bullseye + local image=ttcn3-pcu-test + local docker_run_sh=docker run --rm --entrypoint=/bin/sh osmocom-build/ttcn3-pcu-test -c + [ -n ] + return + set_clean_up_trap + trap clean_up_common EXIT INT TERM 0 + set -e + mkdir <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-pcu-test-sns/978/artif…> + mkdir <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-pcu-test-sns/978/artif…> + cp sns/PCU_Tests.cfg <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-pcu-test-sns/978/artif…> + write_mp_osmo_repo <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-pcu-test-sns/978/artif…> + local repo=nightly + local config=<https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-pcu-test-sns/978/artif…> + local line + [ -e <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-pcu-test-sns/978/artif…> ] + line=Misc_Helpers.mp_osmo_repo := "nightly" + sed -i s/\[MODULE_PARAMETERS\]/\[MODULE_PARAMETERS\]\nMisc_Helpers.mp_osmo_repo := "nightly"/g <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-pcu-test-sns/978/artif…> + mkdir <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-pcu-test-sns/978/artif…> + mkdir <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-pcu-test-sns/978/artif…> + cp sns/osmo-pcu.cfg <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-pcu-test-sns/978/artif…> + mkdir <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-pcu-test-sns/978/artif…> + network_create + docker network ls + grep -q ttcn3-pcu-test-sns + SUBNET=2626550 + seq 1 30 + echo (2626550 + 1) % 256 + bc + SUBNET=247 + SUB4=172.18.247.0/24 + SUB6=fd02:db8:247::/64 + set +x Creating network ttcn3-pcu-test-sns, trying SUBNET=247... + docker network create --internal --subnet 172.18.247.0/24 --ipv6 --subnet fd02:db8:247::/64 ttcn3-pcu-test-sns d676d8d7d14acdd61ce58c58e34a763701a90fb9e04b3785cb4e340a92b0c7ab + set +x ### Network ttcn3-pcu-test-sns created (SUBNET=247) ### + return + network_replace_subnet_in_configs + set +x Applying SUBNET=247 to: <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-pcu-test-sns/978/artif…> Applying SUBNET=247 to: <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-pcu-test-sns/978/artif…> + echo Starting container with PCU Starting container with PCU + docker_network_params 247 101 + NET=247 + ADDR_SUFIX=101 + echo --network ttcn3-pcu-test-sns --ip 172.18.247.101 --ip6 fd02:db8:247::101 + docker run --rm --network ttcn3-pcu-test-sns --ip 172.18.247.101 --ip6 fd02:db8:247::101 --ulimit core=-1 -v <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-pcu-test-sns/978/artif…>:/data -v <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-pcu-test-sns/978/artif…>:/data/unix --name jenkins-TTCN3-centos-pcu-test-sns-978-pcu-sns -d osmocom-build/osmo-pcu-master-centos8 /bin/sh -c /usr/local/bin/respawn.sh osmo-pcu -c /data/osmo-pcu.cfg -i 172.18.247.10 >>/data/osmo-pcu.log 2>&1 529085e925cdd186eb1c744a7856e71cad2a19d7a87b41ef5fef43ed86ee6743 + echo Starting container with PCU testsuite Starting container with PCU testsuite + docker_network_params 247 10 + NET=247 + ADDR_SUFIX=10 + echo --network ttcn3-pcu-test-sns --ip 172.18.247.10 --ip6 fd02:db8:247::10 + docker run --rm --network ttcn3-pcu-test-sns --ip 172.18.247.10 --ip6 fd02:db8:247::10 --ulimit core=-1 -e TTCN3_PCAP_PATH=/data -v <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-pcu-test-sns/978/artif…>:/data -v <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-pcu-test-sns/978/artif…>:/data/unix --name jenkins-TTCN3-centos-pcu-test-sns-978-ttcn3-pcu-test-sns osmocom-build/ttcn3-pcu-test + SUBDIR=pcu + SUITE=PCU_Tests + '[' -n '' ']' + cd /data + /osmo-ttcn3-hacks/start-testsuite.sh /osmo-ttcn3-hacks/pcu/PCU_Tests ttcn3_start: Starting the test suite ttcn3_start: warning: TTCN3_DIR environment variable is not set spawn mctr_cli PCU_Tests.cfg ************************************************************************* * TTCN-3 Test Executor - Main Controller 2 * * Version: 8.0.0 * * Copyright (c) 2000-2021 Ericsson Telecom AB * * All rights reserved. This program and the accompanying materials * * are made available under the terms of the Eclipse Public License v2.0 * * which accompanies this distribution, and is available at * * https://www.eclipse.org/org/documents/epl-2.0/EPL-2.0.html * ************************************************************************* Using configuration file: PCU_Tests.cfg MC@17a1f07e5819: Unix server socket created successfully. MC@17a1f07e5819: Listening on TCP port 46065. 17a1f07e5819 is the default MC2> spawn /osmo-ttcn3-hacks/pcu/PCU_Tests 17a1f07e5819 46065 TTCN-3 Host Controller (parallel mode), version 8.0.0 MC@17a1f07e5819: New HC connected from 17a1f07e5819 [172.18.247.10]. 17a1f07e5819: Linux 5.10.0-19-amd64 on x86_64. cmtc MC@17a1f07e5819: Downloading configuration file to all HCs. construct junitlogger Initializing `JUnitLogger' (v2.0): JUnitLogger writes JUnit-compatible XML HC@17a1f07e5819: Warning: Option `SourceInfoFormat' was given more than once in section [LOGGING] of the configuration file. HC@17a1f07e5819: Warning: Option `SourceInfoFormat' was given more than once in section [LOGGING] of the configuration file. HC@17a1f07e5819: Warning: Option `LoggerPlugins' was given more than once in section [LOGGING] of the configuration file. HC@17a1f07e5819: Warning: Option `FileMask' was given more than once in section [LOGGING] of the configuration file. HC@17a1f07e5819: Warning: Option `ConsoleMask' was given more than once in section [LOGGING] of the configuration file. HC@17a1f07e5819: Warning: Option `BeginTestCase' was given more than once in section [EXTERNAL_COMMANDS] of the configuration file. HC@17a1f07e5819: Warning: Option `EndTestCase' was given more than once in section [EXTERNAL_COMMANDS] of the configuration file. HC@17a1f07e5819: Warning: Option `ConsoleMask' was given more than once in section [LOGGING] of the configuration file. HC@17a1f07e5819: Warning: Option `ConsoleMask' was given more than once in section [LOGGING] of the configuration file. HC@17a1f07e5819: Warning: Option `ConsoleMask' was given more than once in section [LOGGING] of the configuration file. HC@17a1f07e5819: Warning: A plug-in from the same path `libjunitlogger2' is already active, skipping plug-in MC@17a1f07e5819: Configuration file was processed on all HCs. MC@17a1f07e5819: Creating MTC on host 17a1f07e5819. MC@17a1f07e5819: MTC is created. MC2> smtc Executing all items of [EXECUTE] section. MC2> MTC@17a1f07e5819: Starting external command `../ttcn3-tcpdump-start.sh PCU_Tests_SNS.TC_sns_po_size_success'. ------ PCU_Tests_SNS.TC_sns_po_size_success ------ Tue Jan 17 05:15:04 UTC 2023 NOTE: unable to use dumpcap due to missing capabilities or suid bit Waiting for packet dumper to start... 0 MTC@17a1f07e5819: External command `../ttcn3-tcpdump-start.sh PCU_Tests_SNS.TC_sns_po_size_success' was executed successfully (exit status: 0). MTC@17a1f07e5819: Test case TC_sns_po_size_success started. MTC@17a1f07e5819: Start timer g_T_guard: 60 s MTC@17a1f07e5819: Start timer T: 5 s MTC@17a1f07e5819: f_incoming_sns_size(idx=0) MTC@17a1f07e5819: f_ns_exp() expecting { pDU_SNS_Size := { nsPduType := '12'O, nSEI_NS := { iEI := '04'O, ext := '1'B, lengthIndicator := { length1 := 2 }, nSEI := '04D2'O }, resetFlag := { iEI := '0A'O ("\n"), resetBIT := '1'B, spare := '0000000'B }, maxNumberOfNSVCs := { iEI := '07'O ("\a"), maxNumberOfNSVCs := ? }, numberOfIP4_Endpoints := ?, numberOfIP6_Endpoints := omit } } MTC@17a1f07e5819: Start timer T: 1 s MTC@17a1f07e5819: Test case TC_sns_po_size_success finished. Verdict: pass MTC@17a1f07e5819: Starting external command `../ttcn3-tcpdump-stop.sh PCU_Tests_SNS.TC_sns_po_size_success pass'. Tue Jan 17 05:15:10 UTC 2023 [1;32m====== PCU_Tests_SNS.TC_sns_po_size_success pass ======[0m Waiting for packet dumper to finish... 0 (prev_count=-1, count=22066) Waiting for packet dumper to finish... 1 (prev_count=22066, count=27499) MTC@17a1f07e5819: External command `../ttcn3-tcpdump-stop.sh PCU_Tests_SNS.TC_sns_po_size_success pass' was executed successfully (exit status: 0). MTC@17a1f07e5819: Starting external command `../ttcn3-tcpdump-start.sh PCU_Tests_SNS.TC_sns_po_size_nack'. ------ PCU_Tests_SNS.TC_sns_po_size_nack ------ Tue Jan 17 05:15:12 UTC 2023 NOTE: unable to use dumpcap due to missing capabilities or suid bit Waiting for packet dumper to start... 0 MTC@17a1f07e5819: External command `../ttcn3-tcpdump-start.sh PCU_Tests_SNS.TC_sns_po_size_nack' was executed successfully (exit status: 0). MTC@17a1f07e5819: Test case TC_sns_po_size_nack started. MTC@17a1f07e5819: Start timer g_T_guard: 60 s MTC@17a1f07e5819: Start timer T: 5 s MTC@17a1f07e5819: f_incoming_sns_size(idx=0) MTC@17a1f07e5819: f_ns_exp() expecting { pDU_SNS_Size := { nsPduType := '12'O, nSEI_NS := { iEI := '04'O, ext := '1'B, lengthIndicator := { length1 := 2 }, nSEI := '04D2'O }, resetFlag := { iEI := '0A'O ("\n"), resetBIT := '1'B, spare := '0000000'B }, maxNumberOfNSVCs := { iEI := '07'O ("\a"), maxNumberOfNSVCs := ? }, numberOfIP4_Endpoints := ?, numberOfIP6_Endpoints := omit } } MTC@17a1f07e5819: Start timer T: 10 s + clean_up_common + set +e + set +x ### Clean up ### + trap - EXIT INT TERM 0 + type clean_up + network_clean + docker network inspect ttcn3-pcu-test-sns + grep Name + cut -d : -f2 + + awk -F" NR>1{print $2} xargs -rn1 docker kill jenkins-TTCN3-centos-pcu-test-sns-978-pcu-sns + network_remove + set +x Removing network ttcn3-pcu-test-sns + docker network remove ttcn3-pcu-test-sns ttcn3-pcu-test-sns + rm -rf <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-pcu-test-sns/978/artif…> + fix_perms + set +x Fixing permissions + docker run --rm -v <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-pcu-test-sns/ws/logs>:/data -v <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-pcu-test-sns/ws/_cache>:/cache --name jenkins-TTCN3-centos-pcu-test-sns-978-cleaner debian:bullseye chmod -R a+rX /data/ /cache/ + collect_logs + cat <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-pcu-test-sns/978/artif…> Build step 'Execute shell' marked build as failure Recording test results [Checks API] No suitable checks publisher found.

2 years, 5 months

Build failed in Jenkins: TTCN3-centos-pcu-test-sns-latest #682

by jenkins＠lists.osmocom.org

See <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-pcu-test-sns-latest/68…> Changes: [Oliver Smith] ttcn3-*/jenkins*.sh: use $SUBNET everywhere [Oliver Smith] network_create: find free subnet automatically ------------------------------------------ [...truncated 15.07 KB...] + local distro_arg + local depends + local dir + [ registry.osmocom.org = registry.osmocom.org ] + docker pull registry.osmocom.org/osmocom-build/debian-bullseye-titan Using default tag: latest latest: Pulling from osmocom-build/debian-bullseye-titan Digest: sha256:33c02949d71e8f89cbe9ab1eed644c821e98199744d8b90820c9b324dff3d6d6 Status: Image is up to date for registry.osmocom.org/osmocom-build/debian-bullseye-titan:latest registry.osmocom.org/osmocom-build/debian-bullseye-titan:latest + continue + docker_distro_from_image_name ttcn3-pcu-test + echo debian-bullseye + distro_arg=debian-bullseye + [ -z ] + docker_upstream_distro_from_image_name ttcn3-pcu-test + echo debian:bullseye + upstream_distro_arg=debian:bullseye + docker_dir_from_image_name ttcn3-pcu-test + echo ttcn3-pcu-test + dir=ttcn3-pcu-test + pull_arg=--pull + grep ^FROM ../ttcn3-pcu-test/Dockerfile + from_line=FROM $REGISTRY/$USER/debian-bullseye-titan + echo FROM $REGISTRY/$USER/debian-bullseye-titan + grep -q $USER + pull_arg= + set +x Building image: ttcn3-pcu-test (export NO_DOCKER_IMAGE_BUILD=1 to prevent this) + make -C ../ttcn3-pcu-test BUILD_ARGS= UPSTREAM_DISTRO=debian:bullseye DISTRO=debian-bullseye IMAGE=osmocom-build/ttcn3-pcu-test make: Entering directory '<https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-pcu-test-sns-latest/ws…'> awk: fatal: cannot open file `.release' for reading: No such file or directory awk: fatal: cannot open file `.release' for reading: No such file or directory awk: fatal: cannot open file `.release' for reading: No such file or directory rm -rf .common cp -r <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-pcu-test-sns-latest/ws…> .common INFO: .release created release=0.0.0 docker build \ --build-arg USER=osmocom-build \ --build-arg UID=1000 \ --build-arg REGISTRY=registry.osmocom.org \ --build-arg OSMO_TTCN3_BRANCH=master \ --build-arg UPSTREAM_DISTRO=debian:bullseye \ --build-arg DISTRO=debian-bullseye \ --build-arg OSMOCOM_REPO_MIRROR=https://downloads.osmocom.org \ --build-arg OSMOCOM_REPO_PATH=packages/osmocom: \ --build-arg OSMOCOM_REPO_VERSION=latest \ --build-arg OSMOCOM_REPO_TESTSUITE_MIRROR=https://downloads.osmocom.org \ --build-arg LIBOSMOCORE_BRANCH=master \ --build-arg OSMO_BB_BRANCH=master \ --build-arg OSMO_BSC_BRANCH=master \ --build-arg OSMO_BTS_BRANCH=master \ --build-arg OSMO_CBC_BRANCH=master \ --build-arg OSMO_GBPROXY_BRANCH=master \ --build-arg OSMO_GGSN_BRANCH=master \ --build-arg OSMO_GSM_TESTER_BRANCH=master \ --build-arg OSMO_HLR_BRANCH=master \ --build-arg OSMO_HNBGW_BRANCH=master \ --build-arg OSMO_HNODEB_BRANCH=master \ --build-arg OSMO_IUH_BRANCH=master \ --build-arg OSMO_MGW_BRANCH=master \ --build-arg OSMO_MSC_BRANCH=master \ --build-arg OSMO_NITB_BRANCH=master \ --build-arg OSMO_PCU_BRANCH=master \ --build-arg OSMO_SGSN_BRANCH=master \ --build-arg OSMO_SIP_BRANCH=master \ --build-arg OSMO_STP_BRANCH=master \ --build-arg OSMO_UECUPS_BRANCH=master \ --build-arg OPEN5GS_BRANCH=main \ -t osmocom-build/ttcn3-pcu-test:latest . Sending build context to Docker daemon 34.82kB Step 1/9 : ARG REGISTRY Step 2/9 : ARG USER Step 3/9 : FROM $REGISTRY/$USER/debian-bullseye-titan ---> c52b3a0afa2b Step 4/9 : ARG OSMO_TTCN3_BRANCH="master" ---> Using cache ---> 1b7dc1b2b28d Step 5/9 : ADD https://gerrit.osmocom.org/plugins/gitiles/osmo-ttcn3-hacks/+/$OSMO_TTCN3_B… /tmp/commit ---> Using cache ---> 25938111891d Step 6/9 : RUN ttcn3-docker-prepare "$OSMO_TTCN3_BRANCH" pcu ---> Using cache ---> abb8bdc8ead2 Step 7/9 : VOLUME /data ---> Using cache ---> 530ed21012f6 Step 8/9 : COPY PCU_Tests.cfg /data/PCU_Tests.cfg ---> Using cache ---> 9d8272e48238 Step 9/9 : CMD ttcn3-docker-run pcu PCU_Tests ---> Using cache ---> 23825082ba8c [Warning] One or more build-args [OSMO_GGSN_BRANCH OSMO_HLR_BRANCH OSMO_PCU_BRANCH OSMO_SGSN_BRANCH OSMOCOM_REPO_MIRROR OSMOCOM_REPO_PATH OSMO_MGW_BRANCH OSMO_MSC_BRANCH UID OSMO_NITB_BRANCH UPSTREAM_DISTRO OSMO_BSC_BRANCH OSMO_BTS_BRANCH OSMO_GBPROXY_BRANCH OSMO_GSM_TESTER_BRANCH OSMO_SIP_BRANCH OSMOCOM_REPO_TESTSUITE_MIRROR OSMOCOM_REPO_VERSION OSMO_BB_BRANCH OSMO_UECUPS_BRANCH DISTRO OSMO_HNODEB_BRANCH OSMO_STP_BRANCH OSMO_IUH_BRANCH LIBOSMOCORE_BRANCH OPEN5GS_BRANCH OSMO_CBC_BRANCH OSMO_HNBGW_BRANCH] were not consumed Successfully built 23825082ba8c Successfully tagged osmocom-build/ttcn3-pcu-test:latest rm -rf .common make: Leaving directory '<https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-pcu-test-sns-latest/ws…'> + docker_image_exists ttcn3-pcu-test + docker images -q osmocom-build/ttcn3-pcu-test + test -n 23825082ba8c + list_osmo_packages debian-bullseye ttcn3-pcu-test + local distro=debian-bullseye + local image=ttcn3-pcu-test + local docker_run_sh=docker run --rm --entrypoint=/bin/sh osmocom-build/ttcn3-pcu-test -c + [ -n ] + return + set_clean_up_trap + trap clean_up_common EXIT INT TERM 0 + set -e + mkdir <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-pcu-test-sns-latest/68…> + mkdir <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-pcu-test-sns-latest/68…> + cp sns/PCU_Tests.cfg <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-pcu-test-sns-latest/68…> + write_mp_osmo_repo <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-pcu-test-sns-latest/68…> + local repo=nightly + local config=<https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-pcu-test-sns-latest/68…> + local line + [ -e <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-pcu-test-sns-latest/68…> ] + repo=latest + line=Misc_Helpers.mp_osmo_repo := "latest" + sed -i s/\[MODULE_PARAMETERS\]/\[MODULE_PARAMETERS\]\nMisc_Helpers.mp_osmo_repo := "latest"/g <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-pcu-test-sns-latest/68…> + mkdir <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-pcu-test-sns-latest/68…> + mkdir <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-pcu-test-sns-latest/68…> + cp sns/osmo-pcu.cfg <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-pcu-test-sns-latest/68…> + mkdir <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-pcu-test-sns-latest/68…> + network_create + docker network ls + grep -q ttcn3-pcu-test-sns + SUBNET=2114740 + seq 1 30 + echo (2114740 + 1) % 256 + bc + SUBNET=181 + SUB4=172.18.181.0/24 + SUB6=fd02:db8:181::/64 + set +x Creating network ttcn3-pcu-test-sns, trying SUBNET=181... + docker network create --internal --subnet 172.18.181.0/24 --ipv6 --subnet fd02:db8:181::/64 ttcn3-pcu-test-sns 8d427dec36682d6862310b8a2b95a576554733565a225bcb22df8649064820ca + set +x ### Network ttcn3-pcu-test-sns created (SUBNET=181) ### + return + network_replace_subnet_in_configs + set +x Applying SUBNET=181 to: <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-pcu-test-sns-latest/68…> Applying SUBNET=181 to: <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-pcu-test-sns-latest/68…> + echo Starting container with PCU Starting container with PCU + docker_network_params 181 101 + NET=181 + ADDR_SUFIX=101 + echo --network ttcn3-pcu-test-sns --ip 172.18.181.101 --ip6 fd02:db8:181::101 + docker run --rm --network ttcn3-pcu-test-sns --ip 172.18.181.101 --ip6 fd02:db8:181::101 --ulimit core=-1 -v <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-pcu-test-sns-latest/68…>:/data -v <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-pcu-test-sns-latest/68…>:/data/unix --name jenkins-TTCN3-centos-pcu-test-sns-latest-682-pcu-sns -d osmocom-build/osmo-pcu-latest-centos8 /bin/sh -c /usr/local/bin/respawn.sh osmo-pcu -c /data/osmo-pcu.cfg -i 172.18.181.10 >>/data/osmo-pcu.log 2>&1 cc03c351ddaf073b2b4921c21e499024c738409e030559f03ecc9cbdadba5800 + echo Starting container with PCU testsuite Starting container with PCU testsuite + docker_network_params 181 10 + NET=181 + ADDR_SUFIX=10 + echo --network ttcn3-pcu-test-sns --ip 172.18.181.10 --ip6 fd02:db8:181::10 + docker run --rm --network ttcn3-pcu-test-sns --ip 172.18.181.10 --ip6 fd02:db8:181::10 --ulimit core=-1 -e TTCN3_PCAP_PATH=/data -v <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-pcu-test-sns-latest/68…>:/data -v <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-pcu-test-sns-latest/68…>:/data/unix --name jenkins-TTCN3-centos-pcu-test-sns-latest-682-ttcn3-pcu-test-sns osmocom-build/ttcn3-pcu-test + SUBDIR=pcu + SUITE=PCU_Tests + '[' -n '' ']' + cd /data + /osmo-ttcn3-hacks/start-testsuite.sh /osmo-ttcn3-hacks/pcu/PCU_Tests ttcn3_start: Starting the test suite ttcn3_start: warning: TTCN3_DIR environment variable is not set spawn mctr_cli PCU_Tests.cfg ************************************************************************* * TTCN-3 Test Executor - Main Controller 2 * * Version: 8.0.0 * * Copyright (c) 2000-2021 Ericsson Telecom AB * * All rights reserved. This program and the accompanying materials * * are made available under the terms of the Eclipse Public License v2.0 * * which accompanies this distribution, and is available at * * https://www.eclipse.org/org/documents/epl-2.0/EPL-2.0.html * ************************************************************************* Using configuration file: PCU_Tests.cfg MC@f69e7f2efd69: Unix server socket created successfully. MC@f69e7f2efd69: Listening on TCP port 45529. f69e7f2efd69 is the default MC2> spawn /osmo-ttcn3-hacks/pcu/PCU_Tests f69e7f2efd69 45529 TTCN-3 Host Controller (parallel mode), version 8.0.0 MC@f69e7f2efd69: New HC connected from f69e7f2efd69 [172.18.181.10]. f69e7f2efd69: Linux 5.10.0-14-amd64 on x86_64. cmtc MC@f69e7f2efd69: Downloading configuration file to all HCs. construct junitlogger Initializing `JUnitLogger' (v2.0): JUnitLogger writes JUnit-compatible XML HC@f69e7f2efd69: Warning: Option `SourceInfoFormat' was given more than once in section [LOGGING] of the configuration file. HC@f69e7f2efd69: Warning: Option `SourceInfoFormat' was given more than once in section [LOGGING] of the configuration file. HC@f69e7f2efd69: Warning: Option `LoggerPlugins' was given more than once in section [LOGGING] of the configuration file. HC@f69e7f2efd69: Warning: Option `FileMask' was given more than once in section [LOGGING] of the configuration file. HC@f69e7f2efd69: Warning: Option `ConsoleMask' was given more than once in section [LOGGING] of the configuration file. HC@f69e7f2efd69: Warning: Option `BeginTestCase' was given more than once in section [EXTERNAL_COMMANDS] of the configuration file. HC@f69e7f2efd69: Warning: Option `EndTestCase' was given more than once in section [EXTERNAL_COMMANDS] of the configuration file. HC@f69e7f2efd69: Warning: Option `ConsoleMask' was given more than once in section [LOGGING] of the configuration file. HC@f69e7f2efd69: Warning: Option `ConsoleMask' was given more than once in section [LOGGING] of the configuration file. HC@f69e7f2efd69: Warning: Option `ConsoleMask' was given more than once in section [LOGGING] of the configuration file. HC@f69e7f2efd69: Warning: A plug-in from the same path `libjunitlogger2' is already active, skipping plug-in MC@f69e7f2efd69: Configuration file was processed on all HCs. MC@f69e7f2efd69: Creating MTC on host f69e7f2efd69. MC@f69e7f2efd69: MTC is created. MC2> smtc Executing all items of [EXECUTE] section. MC2> MTC@f69e7f2efd69: Starting external command `../ttcn3-tcpdump-start.sh PCU_Tests_SNS.TC_sns_po_size_success'. ------ PCU_Tests_SNS.TC_sns_po_size_success ------ Tue Jan 17 05:15:07 UTC 2023 NOTE: unable to use dumpcap due to missing capabilities or suid bit Waiting for packet dumper to start... 0 MTC@f69e7f2efd69: External command `../ttcn3-tcpdump-start.sh PCU_Tests_SNS.TC_sns_po_size_success' was executed successfully (exit status: 0). MTC@f69e7f2efd69: Test case TC_sns_po_size_success started. MTC@f69e7f2efd69: Start timer g_T_guard: 60 s MTC@f69e7f2efd69: Start timer T: 5 s + clean_up_common + set +e + set +x ### Clean up ### + trap - EXIT INT TERM 0 + type clean_up + network_clean + docker network inspect ttcn3-pcu-test-sns + grep Name + cut -d : -f2 + awk -F" NR>1{print $2} + xargs -rn1 docker kill + network_remove + set +x Removing network ttcn3-pcu-test-sns + docker network remove ttcn3-pcu-test-sns Error: No such network: ttcn3-pcu-test-sns + rm -rf <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-pcu-test-sns-latest/68…> + fix_perms + set +x Fixing permissions + docker run --rm -v <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-pcu-test-sns-latest/ws…>:/data -v <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-pcu-test-sns-latest/ws…>:/cache --name jenkins-TTCN3-centos-pcu-test-sns-latest-682-cleaner debian:bullseye chmod -R a+rX /data/ /cache/ + collect_logs + cat <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-pcu-test-sns-latest/68…> Build step 'Execute shell' marked build as failure Recording test results [Checks API] No suitable checks publisher found.

2 years, 5 months

Build failed in Jenkins: ttcn3-pcu-test-latest #1440

by jenkins＠lists.osmocom.org

See <https://jenkins.osmocom.org/jenkins/job/ttcn3-pcu-test-latest/1440/display/…> Changes: [Oliver Smith] ttcn3-*/jenkins*.sh: use $SUBNET everywhere [Oliver Smith] network_create: find free subnet automatically ------------------------------------------ [...truncated 31.58 KB...] MTC@b3f56d9298a2: Start timer T: 2 s PCUIF(17)@b3f56d9298a2: "Init PCU interface on '/data/unix/pcu_bts', waiting for connection..." PCUIF(17)@b3f56d9298a2: Start timer T: 5 s PCUIF(17)@b3f56d9298a2: Start timer T_Conn: 10 s TC_pcuif_suspend_active_tbf-STATS(19)@b3f56d9298a2: Start timer T: 2 s TC_pcuif_suspend_active_tbf-STATS(19)@b3f56d9298a2: Start timer T: 2 s PCUIF(17)@b3f56d9298a2: OsmoPCU is now connected BTS(18)@b3f56d9298a2: Rx TXT.ind from the PCU, version is "1.1.0" MTC@b3f56d9298a2: Start timer T: 10 s BTS(18)@b3f56d9298a2: "Rx ACT.req from the PCU: TRX0/TS7" -NSVCI1234(16)@b3f56d9298a2: NSVC 1234 State Transition: NSVC_S_DEAD_BLOCKED (1) -> NSVC_S_ALIVE_BLOCKED (3) -NSVCI1234(16)@b3f56d9298a2: Rx NS-RESET: Sending NS-ALIVE -NSVCI1234(16)@b3f56d9298a2: Start timer Tns_alive: 3 s -NSVCI1234(16)@b3f56d9298a2: Start timer Tns_test: 10 s BTS(18)@b3f56d9298a2: "Rx ACT.req from the PCU: TRX1/TS7" BTS(18)@b3f56d9298a2: "Rx ACT.req from the PCU: TRX2/TS7" -NSVCI1234(16)@b3f56d9298a2: Warning: Stopping inactive timer Tns_block. -NSVCI1234(16)@b3f56d9298a2: NSVC 1234 State Transition: NSVC_S_ALIVE_BLOCKED (3) -> NSVC_S_ALIVE_UNBLOCKED (4) BTS(18)@b3f56d9298a2: "Rx ACT.req from the PCU: TRX3/TS7" BTS(18)@b3f56d9298a2: "Rx ACT.req from the PCU: TRX4/TS7" -NSVCI1234(16)@b3f56d9298a2: Rx NS-ALIVE-ACK: stopping Tns-alive; starting Tns-test -NSVCI1234(16)@b3f56d9298a2: Warning: Re-starting timer Tns_test, which is already active (running or expired). -NSVCI1234(16)@b3f56d9298a2: Start timer Tns_test: 10 s BTS(18)@b3f56d9298a2: "Rx ACT.req from the PCU: TRX5/TS7" BTS(18)@b3f56d9298a2: "Rx ACT.req from the PCU: TRX6/TS7" BTS(18)@b3f56d9298a2: "Rx ACT.req from the PCU: TRX7/TS7" 13@b3f56d9298a2: Rx BVC-RESET for Signaling BVCI=0 13@b3f56d9298a2: BVCI(0) State Transition: BVC_S_WAIT_RESET (0) -> BVC_S_UNBLOCKED (3) TC_pcuif_suspend_active_tbf-BVCI1234(14)@b3f56d9298a2: Rx BVC-RESET from BVCI=1234 TC_pcuif_suspend_active_tbf-BVCI1234(14)@b3f56d9298a2: BVCI(1234) State Transition: BVC_S_BLOCKED (2) -> BVC_S_UNBLOCKED (3) MTC@b3f56d9298a2: BSSGP successfully initialized MTC@b3f56d9298a2: Sending RACH.ind on fn=1337 with RA=120, TA=0 MTC@b3f56d9298a2: Start timer T: 2 s MTC@b3f56d9298a2: Rx Immediate Assignment: { header := { l2_plen := { l2_plen := 11, zero_one := '01'B }, skip_indicator := 0, rr_protocol_discriminator := 6, message_type := IMMEDIATE_ASSIGNMENT (63) }, payload := { imm_ass := { ded_or_tbf := { spare := '0'B, tma := false, downlink := false, tbf := true }, page_mode := PAGE_MODE_NORMAL (0), chan_desc := omit, pkt_chan_desc := { channel_Type_spare := 1, tn := 7, tsc := 7, presence := '0'B, zero := { hopping := '0'B, spare := '0'B, arfcn := 871, indirect := omit }, one := omit }, req_ref := { ra := '01111000'B, t1p := 1, t3 := 11, t2 := 11 }, timing_advance := 0, mobile_allocation := { len := 0, ma := ''B }, rest_octets := { presence := '11'B, ll := omit, lh := omit, hl := omit, hh := { pa_disc := '0'B, pa := { uldl := { ass_disc := '0'B, ass := { ul := { presence := '1'B, dynamic := { tfi_assignment := 0, polling := '0'B, spare := '0'B, usf := 0, usf_granularity := '0'B, p0_present := '0'B, p0 := omit, pr_mode := omit, ch_coding_cmd := CH_CODING_CS2 (1), tlli_block_chan_coding := '1'B, alpha_present := '0'B, alpha := omit, gamma := 0, ta_index_present := '0'B, ta_index := omit, tbf_starting_time_present := '0'B, tbf_starting_time := omit }, single := omit } } } } } } } } } MTC@b3f56d9298a2: Rx Uplink TBF GPRS assignment: { presence := '1'B, dynamic := { tfi_assignment := 0, polling := '0'B, spare := '0'B, usf := 0, usf_granularity := '0'B, p0_present := '0'B, p0 := omit, pr_mode := omit, ch_coding_cmd := CH_CODING_CS2 (1), tlli_block_chan_coding := '1'B, alpha_present := '0'B, alpha := omit, gamma := 0, ta_index_present := '0'B, ta_index := omit, tbf_starting_time_present := '0'B, tbf_starting_time := omit }, single := omit } BTS(18)@b3f56d9298a2: Warning: dec_RlcmacDlCtrlBlock(): Data remained at the end of the stream after successful decoding: '4B2B2B2B2B'O ("K++++") MTC@b3f56d9298a2: Start timer T: 2 s MTC@b3f56d9298a2: Rx Immediate Assignment: { header := { l2_plen := { l2_plen := 11, zero_one := '01'B }, skip_indicator := 0, rr_protocol_discriminator := 6, message_type := IMMEDIATE_ASSIGNMENT (63) }, payload := { imm_ass := { ded_or_tbf := { spare := '0'B, tma := false, downlink := true, tbf := true }, page_mode := PAGE_MODE_NORMAL (0), chan_desc := omit, pkt_chan_desc := { channel_Type_spare := 1, tn := 7, tsc := 7, presence := '0'B, zero := { hopping := '0'B, spare := '0'B, arfcn := 871, indirect := omit }, one := omit }, req_ref := { ra := '01111101'B, t1p := 16, t3 := 8, t2 := 8 }, timing_advance := 0, mobile_allocation := { len := 0, ma := ''B }, rest_octets := { presence := '11'B, ll := omit, lh := omit, hl := omit, hh := { pa_disc := '0'B, pa := { uldl := { ass_disc := '1'B, ass := { dl := { tlli := '00000001'O, group1_present := '1'B, group1 := { tfi_assignment := 0, rlc_mode := '0'B, alpha_present := '0'B, alpha := 0, gamma := 4, polling := '0'B, ta_valid := '1'B }, ta_index_present := '1'B, ta_index := 2, tbf_starting_time_present := '1'B, tbf_starting_time := { t1 := 12, t3 := 43, t2 := 5 }, p0_present := '0'B, p0 := omit, pr_mode := omit } } } } } } } } } MTC@b3f56d9298a2: Rx Downlink TBF assignment: { ccch := { tlli := '00000001'O, group1_present := '1'B, group1 := { tfi_assignment := 0, rlc_mode := '0'B, alpha_present := '0'B, alpha := 0, gamma := 4, polling := '0'B, ta_valid := '1'B }, ta_index_present := '1'B, ta_index := 2, tbf_starting_time_present := '1'B, tbf_starting_time := { t1 := 12, t3 := 43, t2 := 5 }, p0_present := '0'B, p0 := omit, pr_mode := omit } } MTC@b3f56d9298a2: Start timer T: 0.2 s MTC@b3f56d9298a2: Start timer T: 2 s MTC@b3f56d9298a2: Start timer T: 2 s MTC@b3f56d9298a2: Rx Immediate Assignment: { header := { l2_plen := { l2_plen := 11, zero_one := '01'B }, skip_indicator := 0, rr_protocol_discriminator := 6, message_type := IMMEDIATE_ASSIGNMENT (63) }, payload := { imm_ass := { ded_or_tbf := { spare := '0'B, tma := false, downlink := true, tbf := true }, page_mode := PAGE_MODE_NORMAL (0), chan_desc := omit, pkt_chan_desc := { channel_Type_spare := 1, tn := 7, tsc := 7, presence := '0'B, zero := { hopping := '0'B, spare := '0'B, arfcn := 871, indirect := omit }, one := omit }, req_ref := { ra := '01111101'B, t1p := 16, t3 := 22, t2 := 21 }, timing_advance := 0, mobile_allocation := { len := 0, ma := ''B }, rest_octets := { presence := '11'B, ll := omit, lh := omit, hl := omit, hh := { pa_disc := '0'B, pa := { uldl := { ass_disc := '1'B, ass := { dl := { tlli := '00000001'O, group1_present := '1'B, group1 := { tfi_assignment := 0, rlc_mode := '0'B, alpha_present := '0'B, alpha := 0, gamma := 4, polling := '0'B, ta_valid := '1'B }, ta_index_present := '1'B, ta_index := 2, tbf_starting_time_present := '1'B, tbf_starting_time := { t1 := 12, t3 := 43, t2 := 5 }, p0_present := '0'B, p0 := omit, pr_mode := omit } } } } } } } } } MTC@b3f56d9298a2: Rx Downlink TBF assignment: { ccch := { tlli := '00000001'O, group1_present := '1'B, group1 := { tfi_assignment := 0, rlc_mode := '0'B, alpha_present := '0'B, alpha := 0, gamma := 4, polling := '0'B, ta_valid := '1'B }, ta_index_present := '1'B, ta_index := 2, tbf_starting_time_present := '1'B, tbf_starting_time := { t1 := 12, t3 := 43, t2 := 5 }, p0_present := '0'B, p0 := omit, pr_mode := omit } } BTS(18)@b3f56d9298a2: Shutting down virtual BTS #0... MTC@b3f56d9298a2: Test case TC_pcuif_suspend_active_tbf finished. Verdict: pass MTC@b3f56d9298a2: Starting external command `../ttcn3-tcpdump-stop.sh PCU_Tests.TC_pcuif_suspend_active_tbf pass'. Tue Jan 17 05:00:18 UTC 2023 [1;32m====== PCU_Tests.TC_pcuif_suspend_active_tbf pass ======[0m Waiting for packet dumper to finish... 0 (prev_count=-1, count=70951) Waiting for packet dumper to finish... 1 (prev_count=70951, count=170421) MTC@b3f56d9298a2: External command `../ttcn3-tcpdump-stop.sh PCU_Tests.TC_pcuif_suspend_active_tbf pass' was executed successfully (exit status: 0). MTC@b3f56d9298a2: Starting external command `../ttcn3-tcpdump-start.sh PCU_Tests.TC_ta_ptcch_idle'. ------ PCU_Tests.TC_ta_ptcch_idle ------ Tue Jan 17 05:00:20 UTC 2023 NOTE: unable to use dumpcap due to missing capabilities or suid bit Waiting for packet dumper to start... 0 MTC@b3f56d9298a2: External command `../ttcn3-tcpdump-start.sh PCU_Tests.TC_ta_ptcch_idle' was executed successfully (exit status: 0). MTC@b3f56d9298a2: Test case TC_ta_ptcch_idle started. MTC@b3f56d9298a2: Start timer g_T_guard: 60 s MTC@b3f56d9298a2: Start timer T: 2 s MTC@b3f56d9298a2: Start timer T: 2 s MTC@b3f56d9298a2: Start timer T: 2 s MTC@b3f56d9298a2: Start timer T: 2 s MTC@b3f56d9298a2: Start timer T: 2 s MTC@b3f56d9298a2: Start timer T: 2 s MTC@b3f56d9298a2: Start timer T: 2 s MTC@b3f56d9298a2: Start timer T: 2 s MTC@b3f56d9298a2: Start timer T: 2 s PCUIF(21)@b3f56d9298a2: "Init PCU interface on '/data/unix/pcu_bts', waiting for connection..." PCUIF(21)@b3f56d9298a2: Start timer T: 5 s PCUIF(21)@b3f56d9298a2: Start timer T_Conn: 10 s TC_ta_ptcch_idle-STATS(23)@b3f56d9298a2: Start timer T: 2 s TC_ta_ptcch_idle-STATS(23)@b3f56d9298a2: Start timer T: 2 s PCUIF(21)@b3f56d9298a2: OsmoPCU is now connected BTS(22)@b3f56d9298a2: Rx TXT.ind from the PCU, version is "1.1.0" MTC@b3f56d9298a2: Start timer T: 5 s BTS(22)@b3f56d9298a2: "Rx ACT.req from the PCU: TRX0/TS7" BTS(22)@b3f56d9298a2: "Rx ACT.req from the PCU: TRX1/TS7" BTS(22)@b3f56d9298a2: "Rx ACT.req from the PCU: TRX2/TS7" BTS(22)@b3f56d9298a2: "Rx ACT.req from the PCU: TRX3/TS7" BTS(22)@b3f56d9298a2: "Rx ACT.req from the PCU: TRX4/TS7" BTS(22)@b3f56d9298a2: "Rx ACT.req from the PCU: TRX5/TS7" BTS(22)@b3f56d9298a2: "Rx ACT.req from the PCU: TRX6/TS7" BTS(22)@b3f56d9298a2: "Rx ACT.req from the PCU: TRX7/TS7" MTC@b3f56d9298a2: Decoded PTCCH/D message: <unbound> BTS(22)@b3f56d9298a2: Shutting down virtual BTS #0... MTC@b3f56d9298a2: Test case TC_ta_ptcch_idle finished. Verdict: pass MTC@b3f56d9298a2: Starting external command `../ttcn3-tcpdump-stop.sh PCU_Tests.TC_ta_ptcch_idle pass'. Tue Jan 17 05:00:24 UTC 2023 [1;32m====== PCU_Tests.TC_ta_ptcch_idle pass ======[0m Waiting for packet dumper to finish... 0 (prev_count=-1, count=43290) Waiting for packet dumper to finish... 1 (prev_count=43290, count=114346) MTC@b3f56d9298a2: External command `../ttcn3-tcpdump-stop.sh PCU_Tests.TC_ta_ptcch_idle pass' was executed successfully (exit status: 0). MTC@b3f56d9298a2: Starting external command `../ttcn3-tcpdump-start.sh PCU_Tests.TC_ta_rach_imm_ass'. ------ PCU_Tests.TC_ta_rach_imm_ass ------ Tue Jan 17 05:00:26 UTC 2023 NOTE: unable to use dumpcap due to missing capabilities or suid bit Waiting for packet dumper to start... 0 MTC@b3f56d9298a2: External command `../ttcn3-tcpdump-start.sh PCU_Tests.TC_ta_rach_imm_ass' was executed successfully (exit status: 0). MTC@b3f56d9298a2: Test case TC_ta_rach_imm_ass started. MTC@b3f56d9298a2: Start timer g_T_guard: 60 s MTC@b3f56d9298a2: Start timer T: 2 s MTC@b3f56d9298a2: Start timer T: 2 s MTC@b3f56d9298a2: Start timer T: 2 s MTC@b3f56d9298a2: Start timer T: 2 s MTC@b3f56d9298a2: Start timer T: 2 s MTC@b3f56d9298a2: Start timer T: 2 s MTC@b3f56d9298a2: Start timer T: 2 s MTC@b3f56d9298a2: Start timer T: 2 s MTC@b3f56d9298a2: Start timer T: 2 s PCUIF(25)@b3f56d9298a2: "Init PCU interface on '/data/unix/pcu_bts', waiting for connection..." PCUIF(25)@b3f56d9298a2: Start timer T: 5 s PCUIF(25)@b3f56d9298a2: Start timer T_Conn: 10 s TC_ta_rach_imm_ass-STATS(27)@b3f56d9298a2: Start timer T: 2 s TC_ta_rach_imm_ass-STATS(27)@b3f56d9298a2: Start timer T: 2 s PCUIF(25)@b3f56d9298a2: OsmoPCU is now connected BTS(26)@b3f56d9298a2: Rx TXT.ind from the PCU, version is "1.1.0" MTC@b3f56d9298a2: Sending RACH.ind on fn=1337 with RA=120, TA=0 MTC@b3f56d9298a2: Start timer T: 2 s BTS(26)@b3f56d9298a2: "Rx ACT.req from the PCU: TRX0/TS7" BTS(26)@b3f56d9298a2: "Rx ACT.req from the PCU: TRX1/TS7" BTS(26)@b3f56d9298a2: "Rx ACT.req from the PCU: TRX2/TS7" BTS(26)@b3f56d9298a2: "Rx ACT.req from the PCU: TRX3/TS7" BTS(26)@b3f56d9298a2: "Rx ACT.req from the PCU: TRX4/TS7" BTS(26)@b3f56d9298a2: "Rx ACT.req from the PCU: TRX5/TS7" BTS(26)@b3f56d9298a2: "Rx ACT.req from the PCU: TRX6/TS7" BTS(26)@b3f56d9298a2: "Rx ACT.req from the PCU: TRX7/TS7" MTC@b3f56d9298a2: Rx Immediate Assignment: { header := { l2_plen := { l2_plen := 11, zero_one := '01'B }, skip_indicator := 0, rr_protocol_discriminator := 6, message_type := IMMEDIATE_ASSIGNMENT (63) }, payload := { imm_ass := { ded_or_tbf := { spare := '0'B, tma := false, downlink := false, tbf := true }, page_mode := PAGE_MODE_NORMAL (0), chan_desc := omit, pkt_chan_desc := { channel_Type_spare := 1, tn := 7, tsc := 7, presence := '0'B, zero := { hopping := '0'B, spare := '0'B, arfcn := 871, indirect := omit }, one := omit }, req_ref := { ra := '01111000'B, t1p := 1, t3 := 11, t2 := 11 }, timing_advance := 0, mobile_allocation := { len := 0, ma := ''B }, rest_octets := { presence := '11'B, ll := omit, lh := omit, hl := omit, hh := { pa_disc := '0'B, pa := { uldl := { ass_disc := '0'B, ass := { ul := { presence := '1'B, dynamic := { tfi_assignment := 0, polling := '0'B, spare := '0'B, usf := 0, usf_granularity := '0'B, p0_present := '0'B, p0 := omit, pr_mode := omit, ch_coding_cmd := CH_CODING_CS2 (1), tlli_block_chan_coding := '1'B, alpha_present := '0'B, alpha := omit, gamma := 0, ta_index_present := '0'B, ta_index := omit, tbf_starting_time_present := '0'B, tbf_starting_time := omit }, single := omit } } } } } } } } } MTC@b3f56d9298a2: Rx Uplink TBF GPRS assignment: { presence := '1'B, dynamic := { tfi_assignment := 0, polling := '0'B, spare := '0'B, usf := 0, usf_granularity := '0'B, p0_present := '0'B, p0 := omit, pr_mode := omit, ch_coding_cmd := CH_CODING_CS2 (1), tlli_block_chan_coding := '1'B, alpha_present := '0'B, alpha := omit, gamma := 0, ta_index_present := '0'B, ta_index := omit, tbf_starting_time_present := '0'B, tbf_starting_time := omit }, single := omit } MTC@b3f56d9298a2: Sending RACH.ind on fn=1353 with RA=120, TA=16 MTC@b3f56d9298a2: Start timer T: 2 s MTC@b3f56d9298a2: Rx Immediate Assignment: { header := { l2_plen := { l2_plen := 11, zero_one := '01'B }, skip_indicator := 0, rr_protocol_discriminator := 6, message_type := IMMEDIATE_ASSIGNMENT (63) }, payload := { imm_ass := { ded_or_tbf := { spare := '0'B, tma := false, downlink := false, tbf := true }, page_mode := PAGE_MODE_NORMAL (0), chan_desc := omit, pkt_chan_desc := { channel_Type_spare := 1, tn := 7, tsc := 7, presence := '0'B, zero := { hopping := '0'B, spare := '0'B, arfcn := 872, indirect := omit }, one := omit }, req_ref := { ra := '01111000'B, t1p := 1, t3 := 27, t2 := 1 }, timing_advance := 16, mobile_allocation := { len := 0, ma := ''B }, rest_octets := { presence := '11'B, ll := omit, lh := omit, hl := omit, hh := { pa_disc := '0'B, pa := { uldl := { ass_disc := '0'B, ass := { ul := { presence := '1'B, dynamic := { tfi_assignment := 0, polling := '0'B, spare := '0'B, usf := 0, usf_granularity := '0'B, p0_present := '0'B, p0 := omit, pr_mode := omit, ch_coding_cmd := CH_CODING_CS2 (1), tlli_block_chan_coding := '1'B, alpha_present := '0'B, alpha := omit, gamma := 0, ta_index_present := '0'B, ta_index := omit, tbf_starting_time_present := '0'B, tbf_starting_time := omit }, single := omit } } } } } } } } } MTC@b3f56d9298a2: Rx Uplink TBF GPRS assignment: { presence := '1'B, dynamic := { tfi_assignment := 0, polling := '0'B, spare := '0'B, usf := 0, usf_granularity := '0'B, p0_present := '0'B, p0 := omit, pr_mode := omit, ch_coding_cmd := CH_CODING_CS2 (1), tlli_block_chan_coding := '1'B, alpha_present := '0'B, alpha := omit, gamma := 0, ta_index_present := '0'B, ta_index := omit, tbf_starting_time_present := '0'B, tbf_starting_time := omit }, single := omit } MTC@b3f56d9298a2: Sending RACH.ind on fn=1369 with RA=120, TA=32 MTC@b3f56d9298a2: Start timer T: 2 s MTC@b3f56d9298a2: Rx Immediate Assignment: { header := { l2_plen := { l2_plen := 11, zero_one := '01'B }, skip_indicator := 0, rr_protocol_discriminator := 6, message_type := IMMEDIATE_ASSIGNMENT (63) }, payload := { imm_ass := { ded_or_tbf := { spare := '0'B, tma := false, downlink := false, tbf := true }, page_mode := PAGE_MODE_NORMAL (0), chan_desc := omit, pkt_chan_desc := { channel_Type_spare := 1, tn := 7, tsc := 7, presence := '0'B, zero := { hopping := '0'B, spare := '0'B, arfcn := 873, indirect := omit }, one := omit }, req_ref := { ra := '01111000'B, t1p := 1, t3 := 43, t2 := 17 }, timing_advance := 32, mobile_allocation := { len := 0, ma := ''B }, rest_octets := { presence := '11'B, ll := omit, lh := omit, hl := omit, hh := { pa_disc := '0'B, pa := { uldl := { ass_disc := '0'B, ass := { ul := { presence := '1'B, dynamic := { tfi_assignment := 0, polling := '0'B, spare := '0'B, usf := 0, usf_granularity := '0'B, p0_present := '0'B, p0 := omit, pr_mode := omit, ch_coding_cmd := CH_CODING_CS2 (1), tlli_block_chan_coding := '1'B, alpha_present := '0'B, alpha := omit, gamma := 0, ta_index_present := '0'B, ta_index := omit, tbf_starting_time_present := '0'B, tbf_starting_time := omit }, single := omit } } } } } } } } } MTC@b3f56d9298a2: Rx Uplink TBF GPRS assignment: { presence := '1'B, dynamic := { tfi_assignment := 0, polling := '0'B, spare := '0'B, usf := 0, usf_granularity := '0'B, p0_present := '0'B, p0 := omit, pr_mode := omit, ch_coding_cmd := CH_CODING_CS2 (1), tlli_block_chan_coding := '1'B, alpha_present := '0'B, alpha := omit, gamma := 0, ta_index_present := '0'B, ta_index := omit, tbf_starting_time_present := '0'B, tbf_starting_time := omit }, single := omit } MTC@b3f56d9298a2: Sending RACH.ind on fn=1385 with RA=120, TA=48 MTC@b3f56d9298a2: Start timer T: 2 s MTC@b3f56d9298a2: Rx Immediate Assignment: { header := { l2_plen := { l2_plen := 11, zero_one := '01'B }, skip_indicator := 0, rr_protocol_discriminator := 6, message_type := IMMEDIATE_ASSIGNMENT (63) }, payload := { imm_ass := { ded_or_tbf := { spare := '0'B, tma := false, downlink := false, tbf := true }, page_mode := PAGE_MODE_NORMAL (0), chan_desc := omit, pkt_chan_desc := { channel_Type_spare := 1, tn := 7, tsc := 7, presence := '0'B, zero := { hopping := '0'B, spare := '0'B, arfcn := 874, indirect := omit }, one := omit }, req_ref := { ra := '01111000'B, t1p := 1, t3 := 8, t2 := 7 }, timing_advance := 48, mobile_allocation := { len := 0, ma := ''B }, rest_octets := { presence := '11'B, ll := omit, lh := omit, hl := omit, hh := { pa_disc := '0'B, pa := { uldl := { ass_disc := '0'B, ass := { ul := { presence := '1'B, dynamic := { tfi_assignment := 0, polling := '0'B, spare := '0'B, usf := 0, usf_granularity := '0'B, p0_present := '0'B, p0 := omit, pr_mode := omit, ch_coding_cmd := CH_CODING_CS2 (1), tlli_block_chan_coding := '1'B, alpha_present := '0'B, alpha := omit, gamma := 0, ta_index_present := '0'B, ta_index := omit, tbf_starting_time_present := '0'B, tbf_starting_time := omit }, single := omit } } } } } } } } } MTC@b3f56d9298a2: Rx Uplink TBF GPRS assignment: { presence := '1'B, dynamic := { tfi_assignment := 0, polling := '0'B, spare := '0'B, usf := 0, usf_granularity := '0'B, p0_present := '0'B, p0 := omit, pr_mode := omit, ch_coding_cmd := CH_CODING_CS2 (1), tlli_block_chan_coding := '1'B, alpha_present := '0'B, alpha := omit, gamma := 0, ta_index_present := '0'B, ta_index := omit, tbf_starting_time_present := '0'B, tbf_starting_time := omit }, single := omit } BTS(26)@b3f56d9298a2: Shutting down virtual BTS #0... MTC@b3f56d9298a2: Test case TC_ta_rach_imm_ass finished. Verdict: pass MTC@b3f56d9298a2: Starting external command `../ttcn3-tcpdump-stop.sh PCU_Tests.TC_ta_rach_imm_ass pass'. Tue Jan 17 05:00:29 UTC 2023 [1;32m====== PCU_Tests.TC_ta_rach_imm_ass pass ======[0m Waiting for packet dumper to finish... 0 (prev_count=-1, count=6298) Waiting for packet dumper to finish... 1 (prev_count=6298, count=77643) MTC@b3f56d9298a2: External command `../ttcn3-tcpdump-stop.sh PCU_Tests.TC_ta_rach_imm_ass pass' was executed successfully (exit status: 0). MTC@b3f56d9298a2: Starting external command `../ttcn3-tcpdump-start.sh PCU_Tests.TC_ta_ul_ack_nack_first_block'. ------ PCU_Tests.TC_ta_ul_ack_nack_first_block ------ Tue Jan 17 05:00:31 UTC 2023 NOTE: unable to use dumpcap due to missing capabilities or suid bit Waiting for packet dumper to start... 0 MTC@b3f56d9298a2: External command `../ttcn3-tcpdump-start.sh PCU_Tests.TC_ta_ul_ack_nack_first_block' was executed successfully (exit status: 0). MTC@b3f56d9298a2: Test case TC_ta_ul_ack_nack_first_block started. 29@b3f56d9298a2: Creating NSIP provider for "172.18.105.10":23000 TC_ta_ul_ack_nack_first_block-BVCI1234(31)@b3f56d9298a2: Adding Client=mtc, IMSI='262420000000001'H, TLLI='FFFFFFFF'O, index=0 MTC@b3f56d9298a2: Start timer g_T_guard: 60 s 29@b3f56d9298a2: Starting NSVC component for { provider := { ip := { address_family := AF_INET (2), local_udp_port := 23000, local_ip := "172.18.105.10", remote_udp_port := 23000, remote_ip := "172.18.105.101", data_weight := 1, signalling_weight := 1 } }, nsvci := 1234 } -NSVCI1234(33)@b3f56d9298a2: NSVC 1234 State Transition: NSVC_S_DEAD_BLOCKED (1) -> NSVC_S_DEAD_BLOCKED (1) -NSVCI1234(33)@b3f56d9298a2: Provider Link came up: waiting for NS-RESET MTC@b3f56d9298a2: Start timer T: 2 s MTC@b3f56d9298a2: Start timer T: 2 s MTC@b3f56d9298a2: Start timer T: 2 s MTC@b3f56d9298a2: Start timer T: 2 s MTC@b3f56d9298a2: Start timer T: 2 s MTC@b3f56d9298a2: Start timer T: 2 s MTC@b3f56d9298a2: Start timer T: 2 s MTC@b3f56d9298a2: Start timer T: 2 s MTC@b3f56d9298a2: Start timer T: 2 s PCUIF(34)@b3f56d9298a2: "Init PCU interface on '/data/unix/pcu_bts', waiting for connection..." PCUIF(34)@b3f56d9298a2: Start timer T: 5 s PCUIF(34)@b3f56d9298a2: Start timer T_Conn: 10 s TC_ta_ul_ack_nack_first_block-STATS(36)@b3f56d9298a2: Start timer T: 2 s TC_ta_ul_ack_nack_first_block-STATS(36)@b3f56d9298a2: Start timer T: 2 s PCUIF(34)@b3f56d9298a2: OsmoPCU is now connected BTS(35)@b3f56d9298a2: Rx TXT.ind from the PCU, version is "1.1.0" MTC@b3f56d9298a2: Start timer T: 10 s -NSVCI1234(33)@b3f56d9298a2: NSVC 1234 State Transition: NSVC_S_DEAD_BLOCKED (1) -> NSVC_S_ALIVE_BLOCKED (3) BTS(35)@b3f56d9298a2: "Rx ACT.req from the PCU: TRX0/TS7" -NSVCI1234(33)@b3f56d9298a2: Rx NS-RESET: Sending NS-ALIVE -NSVCI1234(33)@b3f56d9298a2: Start timer Tns_alive: 3 s -NSVCI1234(33)@b3f56d9298a2: Start timer Tns_test: 10 s BTS(35)@b3f56d9298a2: "Rx ACT.req from the PCU: TRX1/TS7" BTS(35)@b3f56d9298a2: "Rx ACT.req from the PCU: TRX2/TS7" -NSVCI1234(33)@b3f56d9298a2: Warning: Stopping inactive timer Tns_block. -NSVCI1234(33)@b3f56d9298a2: NSVC 1234 State Transition: NSVC_S_ALIVE_BLOCKED (3) -> NSVC_S_ALIVE_UNBLOCKED (4) BTS(35)@b3f56d9298a2: "Rx ACT.req from the PCU: TRX3/TS7" BTS(35)@b3f56d9298a2: "Rx ACT.req from the PCU: TRX4/TS7" -NSVCI1234(33)@b3f56d9298a2: Rx NS-ALIVE-ACK: stopping Tns-alive; starting Tns-test -NSVCI1234(33)@b3f56d9298a2: Warning: Re-starting timer Tns_test, which is already active (running or expired). -NSVCI1234(33)@b3f56d9298a2: Start timer Tns_test: 10 s BTS(35)@b3f56d9298a2: "Rx ACT.req from the PCU: TRX5/TS7" BTS(35)@b3f56d9298a2: "Rx ACT.req from the PCU: TRX6/TS7" BTS(35)@b3f56d9298a2: "Rx ACT.req from the PCU: TRX7/TS7" 30@b3f56d9298a2: Rx BVC-RESET for Signaling BVCI=0 30@b3f56d9298a2: BVCI(0) State Transition: BVC_S_WAIT_RESET (0) -> BVC_S_UNBLOCKED (3) TC_ta_ul_ack_nack_first_block-BVCI1234(31)@b3f56d9298a2: Rx BVC-RESET from BVCI=1234 TC_ta_ul_ack_nack_first_block-BVCI1234(31)@b3f56d9298a2: BVCI(1234) State Transition: BVC_S_BLOCKED (2) -> BVC_S_UNBLOCKED (3) MTC@b3f56d9298a2: BSSGP successfully initialized MTC@b3f56d9298a2: Sending RACH.ind on fn=1337 with RA=120, TA=0 MTC@b3f56d9298a2: Start timer T: 2 s MTC@b3f56d9298a2: Rx Immediate Assignment: { header := { l2_plen := { l2_plen := 11, zero_one := '01'B }, skip_indicator := 0, rr_protocol_discriminator := 6, message_type := IMMEDIATE_ASSIGNMENT (63) }, payload := { imm_ass := { ded_or_tbf := { spare := '0'B, tma := false, downlink := false, tbf := true }, page_mode := PAGE_MODE_NORMAL (0), chan_desc := omit, pkt_chan_desc := { channel_Type_spare := 1, tn := 7, tsc := 7, presence := '0'B, zero := { hopping := '0'B, spare := '0'B, arfcn := 871, indirect := omit }, one := omit }, req_ref := { ra := '01111000'B, t1p := 1, t3 := 11, t2 := 11 }, timing_advance := 0, mobile_allocation := { len := 0, ma := ''B }, rest_octets := { presence := '11'B, ll := omit, lh := omit, hl := omit, hh := { pa_disc := '0'B, pa := { uldl := { ass_disc := '0'B, ass := { ul := { presence := '1'B, dynamic := { tfi_assignment := 0, polling := '0'B, spare := '0'B, usf := 0, usf_granularity := '0'B, p0_present := '0'B, p0 := omit, pr_mode := omit, ch_coding_cmd := CH_CODING_CS2 (1), tlli_block_chan_coding := '1'B, alpha_present := '0'B, alpha := omit, gamma := 0, ta_index_present := '0'B, ta_index := omit, tbf_starting_time_present := '0'B, tbf_starting_time := omit }, single := omit } } } } } } } } } MTC@b3f56d9298a2: Rx Uplink TBF GPRS assignment: { presence := '1'B, dynamic := { tfi_assignment := 0, polling := '0'B, spare := '0'B, usf := 0, usf_granularity := '0'B, p0_present := '0'B, p0 := omit, pr_mode := omit, ch_coding_cmd := CH_CODING_CS2 (1), tlli_block_chan_coding := '1'B, alpha_present := '0'B, alpha := omit, gamma := 0, ta_index_present := '0'B, ta_index := omit, tbf_starting_time_present := '0'B, tbf_starting_time := omit }, single := omit } BTS(35)@b3f56d9298a2: Warning: dec_RlcmacDlCtrlBlock(): Data remained at the end of the stream after successful decoding: '4B2B2B2B2B'O ("K++++") MTC@b3f56d9298a2: Test case "TC_ta_ul_ack_nack_first_block" aborted at "PCU_Tests.ttcn":561 BTS(35)@b3f56d9298a2: Shutting down virtual BTS #0... MTC@b3f56d9298a2: Test case TC_ta_ul_ack_nack_first_block finished. Verdict: fail reason: Timing Advance mismatch: expected 2, but received 0 MTC@b3f56d9298a2: Starting external command `../ttcn3-tcpdump-stop.sh PCU_Tests.TC_ta_ul_ack_nack_first_block fail'. Tue Jan 17 05:00:34 UTC 2023 [1;31m------ PCU_Tests.TC_ta_ul_ack_nack_first_block fail ------[0m Waiting for packet dumper to finish... 0 (prev_count=-1, count=7835) Waiting for packet dumper to finish... 1 (prev_count=7835, count=71972) + clean_up_common + set +e + set +x ### Clean up ### + trap - EXIT INT TERM 0 + type clean_up + network_clean + docker network inspect ttcn3-pcu-test + grep Name + cut -d : -f2 + awk -F" NR>1{print $2} + xargs -rn1 docker kill jenkins-ttcn3-pcu-test-latest-1440-pcu + network_remove + set +x Removing network ttcn3-pcu-test + docker network remove ttcn3-pcu-test Error: No such network: ttcn3-pcu-test + rm -rf <https://jenkins.osmocom.org/jenkins/job/ttcn3-pcu-test-latest/1440/artifact…> + fix_perms + set +x Fixing permissions + docker run --rm -v <https://jenkins.osmocom.org/jenkins/job/ttcn3-pcu-test-latest/ws/logs>:/data -v <https://jenkins.osmocom.org/jenkins/job/ttcn3-pcu-test-latest/ws/_cache>:/cache --name jenkins-ttcn3-pcu-test-latest-1440-cleaner debian:bullseye chmod -R a+rX /data/ /cache/ + collect_logs + cat <https://jenkins.osmocom.org/jenkins/job/ttcn3-pcu-test-latest/1440/artifact…> Build step 'Execute shell' marked build as failure Recording test results [Checks API] No suitable checks publisher found.

2 years, 5 months

Build failed in Jenkins: ttcn3-msc-test #1899

by jenkins＠lists.osmocom.org

See <https://jenkins.osmocom.org/jenkins/job/ttcn3-msc-test/1899/display/redirec…> Changes: [Oliver Smith] ttcn3-*/jenkins*.sh: use $SUBNET everywhere [Oliver Smith] network_create: find free subnet automatically ------------------------------------------ [...truncated 124.70 KB...] ---> 16f46c89e2e3 Step 11/14 : VOLUME /data ---> Running in 368fce14843d Removing intermediate container 368fce14843d ---> 83e8ae13f7f3 Step 12/14 : COPY osmo-msc.cfg /data/osmo-msc.cfg ---> 3b1a241b015e Step 13/14 : WORKDIR /data ---> Running in 6a83e509e6a9 Removing intermediate container 6a83e509e6a9 ---> c6fa48463da5 Step 14/14 : CMD ["/bin/sh", "-c", "/usr/local/bin/osmo-msc -c /data/osmo-msc.cfg >/data/osmo-msc.log 2>&1"] ---> Running in a11dd3fb6c6f Removing intermediate container a11dd3fb6c6f ---> 746b36806b81 [Warning] One or more build-args [OPEN5GS_BRANCH UID REGISTRY OSMOCOM_REPO_TESTSUITE_MIRROR OSMO_BTS_BRANCH OSMO_GGSN_BRANCH OSMO_GSM_TESTER_BRANCH OSMO_HNBGW_BRANCH OSMO_HNODEB_BRANCH OSMO_TTCN3_BRANCH OSMO_SIP_BRANCH OSMOCOM_REPO_MIRROR OSMO_CBC_BRANCH LIBOSMOCORE_BRANCH OSMO_PCU_BRANCH OSMO_SGSN_BRANCH OSMOCOM_REPO_PATH OSMO_BB_BRANCH OSMO_GBPROXY_BRANCH OSMO_IUH_BRANCH OSMO_MGW_BRANCH OSMO_UECUPS_BRANCH OSMOCOM_REPO_VERSION OSMO_BSC_BRANCH OSMO_HLR_BRANCH OSMO_NITB_BRANCH OSMO_STP_BRANCH UPSTREAM_DISTRO] were not consumed Successfully built 746b36806b81 Successfully tagged osmocom-build/osmo-msc-master:latest rm -rf .common make: Leaving directory '<https://jenkins.osmocom.org/jenkins/job/ttcn3-msc-test/ws/osmo-msc-master'> + docker_image_exists osmo-msc-master + docker images -q osmocom-build/osmo-msc-master + test -n 746b36806b81 + list_osmo_packages debian-bullseye osmo-msc-master + local distro=debian-bullseye + local image=osmo-msc-master + local docker_run_sh=docker run --rm --entrypoint=/bin/sh osmocom-build/osmo-msc-master -c + [ -n ] + set +x ### Installed Osmocom packages in: osmo-msc-master ### ii libosmo-abis-dev:amd64 1.3.0.24.864e.202301170007 amd64 Development headers for A-bis interface ii libosmo-gsup-client-dev:amd64 1.5.0.17.c27b.202301170007 amd64 Development headers of Osmocom GSUP client library ii libosmo-gsup-client0:amd64 1.5.0.17.c27b.202301170007 amd64 Osmocom GSUP (General Subscriber Update Protocol) client library ii libosmo-mgcp-client-dev:amd64 1.10.0.113.9c0d1.202301170007 amd64 libosmo-mgcp-client: Osmocom's Media Gateway Control Protocol client utilities ii libosmo-mgcp-client9:amd64 1.10.0.113.9c0d1.202301170007 amd64 libosmo-mgcp-client: Osmocom's Media Gateway Control Protocol client utilities ii libosmo-netif-dev:amd64 1.2.0.114.3804.202301170007 amd64 Development headers for Osmocom network interface ii libosmo-ranap-dev:amd64 1.3.0.16.a319.202301170007 amd64 Osmocom code for the Iuh interface (HNBAP, RUA, RANAP) ii libosmo-ranap5:amd64 1.3.0.16.a319.202301170007 amd64 Osmocom code for the Iuh interface (HNBAP, RUA, RANAP) ii libosmo-sccp-dev:amd64 1.6.0.20.8b67.202301170007 amd64 Development files for libsccp, libmtp and libxua ii libosmo-sigtran-dev:amd64 1.6.0.20.8b67.202301170007 amd64 Development headers for the Osmocom SIGTRAN library ii libosmo-sigtran7:amd64 1.6.0.20.8b67.202301170007 amd64 Osmocom SIGTRAN library (SCCP, SUA, M3UA and more) ii libosmoabis10:amd64 1.3.0.24.864e.202301170007 amd64 GSM A-bis handling ii libosmocodec0:amd64 1.7.0.96.a13d.202301170007 amd64 Osmo codec library ii libosmocoding0:amd64 1.7.0.96.a13d.202301170007 amd64 Osmo coding library ii libosmocore 1.7.0.96.a13d.202301170007 amd64 Open Source MObile COMmunications CORE library (metapackage) ii libosmocore-dev:amd64 1.7.0.96.a13d.202301170007 amd64 Development headers for Open Source MObile COMmunications CORE library ii libosmocore19:amd64 1.7.0.96.a13d.202301170007 amd64 Osmo Core library ii libosmoctrl0:amd64 1.7.0.96.a13d.202301170007 amd64 Osmo control library ii libosmogb14:amd64 1.7.0.96.a13d.202301170007 amd64 Osmo GPRS GB library ii libosmogsm18:amd64 1.7.0.96.a13d.202301170007 amd64 Osmo GSM utility library ii libosmonetif8:amd64 1.2.0.114.3804.202301170007 amd64 Common/shared code regarding network interface for OpenBSC ii libosmosim2:amd64 1.7.0.96.a13d.202301170007 amd64 Osmo SIM library ii libosmotrau2:amd64 1.3.0.24.864e.202301170007 amd64 GSM trau handling ii libosmousb0:amd64 1.7.0.96.a13d.202301170007 amd64 Osmo USB library ii libosmovty9:amd64 1.7.0.96.a13d.202301170007 amd64 Osmo VTY library ii osmocom-nightly 202301170007 amd64 Dummy package, conflicts with ['osmocom-2022q1', 'osmocom-2022q2', 'osmocom-2023q1', 'osmocom-latest', 'osmocom-master'] + [ registry.osmocom.org = registry.osmocom.org ] + docker_depends ttcn3-msc-test + local feed + echo debian-bullseye-titan + depends=debian-bullseye-titan + [ -n debian-bullseye-titan ] + docker_images_require debian-bullseye-titan + local i + local from_line + local pull_arg + local upstream_distro_arg + local distro_arg + local depends + local dir + [ registry.osmocom.org = registry.osmocom.org ] + docker pull registry.osmocom.org/osmocom-build/debian-bullseye-titan Using default tag: latest latest: Pulling from osmocom-build/debian-bullseye-titan Digest: sha256:33c02949d71e8f89cbe9ab1eed644c821e98199744d8b90820c9b324dff3d6d6 Status: Image is up to date for registry.osmocom.org/osmocom-build/debian-bullseye-titan:latest registry.osmocom.org/osmocom-build/debian-bullseye-titan:latest + continue + docker_distro_from_image_name ttcn3-msc-test + echo debian-bullseye + distro_arg=debian-bullseye + [ -z ] + docker_upstream_distro_from_image_name ttcn3-msc-test + echo debian:bullseye + upstream_distro_arg=debian:bullseye + docker_dir_from_image_name ttcn3-msc-test + echo ttcn3-msc-test + dir=ttcn3-msc-test + pull_arg=--pull + grep ^FROM ../ttcn3-msc-test/Dockerfile + from_line=FROM $REGISTRY/$USER/debian-bullseye-titan + echo FROM $REGISTRY/$USER/debian-bullseye-titan + grep -q $USER + pull_arg= + set +x Building image: ttcn3-msc-test (export NO_DOCKER_IMAGE_BUILD=1 to prevent this) + make -C ../ttcn3-msc-test BUILD_ARGS= UPSTREAM_DISTRO=debian:bullseye DISTRO=debian-bullseye IMAGE=osmocom-build/ttcn3-msc-test make: Entering directory '<https://jenkins.osmocom.org/jenkins/job/ttcn3-msc-test/ws/ttcn3-msc-test'> awk: fatal: cannot open file `.release' for reading: No such file or directory awk: fatal: cannot open file `.release' for reading: No such file or directory awk: fatal: cannot open file `.release' for reading: No such file or directory rm -rf .common cp -r <https://jenkins.osmocom.org/jenkins/job/ttcn3-msc-test/ws/common> .common INFO: .release created release=0.0.0 docker build \ --build-arg USER=osmocom-build \ --build-arg UID=1000 \ --build-arg REGISTRY=registry.osmocom.org \ --build-arg OSMO_TTCN3_BRANCH=master \ --build-arg UPSTREAM_DISTRO=debian:bullseye \ --build-arg DISTRO=debian-bullseye \ --build-arg OSMOCOM_REPO_MIRROR=https://downloads.osmocom.org \ --build-arg OSMOCOM_REPO_PATH=packages/osmocom: \ --build-arg OSMOCOM_REPO_VERSION=latest \ --build-arg OSMOCOM_REPO_TESTSUITE_MIRROR=https://downloads.osmocom.org \ --build-arg LIBOSMOCORE_BRANCH=master \ --build-arg OSMO_BB_BRANCH=master \ --build-arg OSMO_BSC_BRANCH=master \ --build-arg OSMO_BTS_BRANCH=master \ --build-arg OSMO_CBC_BRANCH=master \ --build-arg OSMO_GBPROXY_BRANCH=master \ --build-arg OSMO_GGSN_BRANCH=master \ --build-arg OSMO_GSM_TESTER_BRANCH=master \ --build-arg OSMO_HLR_BRANCH=master \ --build-arg OSMO_HNBGW_BRANCH=master \ --build-arg OSMO_HNODEB_BRANCH=master \ --build-arg OSMO_IUH_BRANCH=master \ --build-arg OSMO_MGW_BRANCH=master \ --build-arg OSMO_MSC_BRANCH=master \ --build-arg OSMO_NITB_BRANCH=master \ --build-arg OSMO_PCU_BRANCH=master \ --build-arg OSMO_SGSN_BRANCH=master \ --build-arg OSMO_SIP_BRANCH=master \ --build-arg OSMO_STP_BRANCH=master \ --build-arg OSMO_UECUPS_BRANCH=master \ --build-arg OPEN5GS_BRANCH=main \ -t osmocom-build/ttcn3-msc-test:latest . Sending build context to Docker daemon 32.77kB Step 1/9 : ARG REGISTRY Step 2/9 : ARG USER Step 3/9 : FROM $REGISTRY/$USER/debian-bullseye-titan ---> c52b3a0afa2b Step 4/9 : ARG OSMO_TTCN3_BRANCH="master" ---> Using cache ---> c97415d6789c Step 5/9 : ADD https://gerrit.osmocom.org/plugins/gitiles/osmo-ttcn3-hacks/+/$OSMO_TTCN3_B… /tmp/commit ---> Using cache ---> 9eadfbcc98b5 Step 6/9 : RUN ttcn3-docker-prepare "$OSMO_TTCN3_BRANCH" msc ---> Using cache ---> ec058dc347f3 Step 7/9 : VOLUME /data ---> Using cache ---> 66fa3dd0e892 Step 8/9 : COPY MSC_Tests.cfg /data/MSC_Tests.cfg ---> Using cache ---> aa65fc280ed8 Step 9/9 : CMD ttcn3-docker-run msc MSC_Tests ---> Using cache ---> fcbc2b1265a5 [Warning] One or more build-args [OSMOCOM_REPO_VERSION OSMO_GGSN_BRANCH OSMO_HNBGW_BRANCH OSMO_SIP_BRANCH OSMO_BSC_BRANCH OSMO_HNODEB_BRANCH OSMO_SGSN_BRANCH UID OSMO_BB_BRANCH OSMO_HLR_BRANCH OSMOCOM_REPO_MIRROR OSMOCOM_REPO_PATH OSMOCOM_REPO_TESTSUITE_MIRROR OSMO_CBC_BRANCH OSMO_GSM_TESTER_BRANCH OSMO_MGW_BRANCH OSMO_MSC_BRANCH OSMO_STP_BRANCH LIBOSMOCORE_BRANCH OPEN5GS_BRANCH OSMO_PCU_BRANCH OSMO_UECUPS_BRANCH DISTRO OSMO_BTS_BRANCH OSMO_GBPROXY_BRANCH OSMO_IUH_BRANCH OSMO_NITB_BRANCH UPSTREAM_DISTRO] were not consumed Successfully built fcbc2b1265a5 Successfully tagged osmocom-build/ttcn3-msc-test:latest rm -rf .common make: Leaving directory '<https://jenkins.osmocom.org/jenkins/job/ttcn3-msc-test/ws/ttcn3-msc-test'> + docker_image_exists ttcn3-msc-test + docker images -q osmocom-build/ttcn3-msc-test + test -n fcbc2b1265a5 + list_osmo_packages debian-bullseye ttcn3-msc-test + local distro=debian-bullseye + local image=ttcn3-msc-test + local docker_run_sh=docker run --rm --entrypoint=/bin/sh osmocom-build/ttcn3-msc-test -c + [ -n ] + return + set_clean_up_trap + trap clean_up_common EXIT INT TERM 0 + set -e + mkdir <https://jenkins.osmocom.org/jenkins/job/ttcn3-msc-test/1899/artifact/logs/m…> + mkdir <https://jenkins.osmocom.org/jenkins/job/ttcn3-msc-test/1899/artifact/logs/m…> + cp MSC_Tests.cfg <https://jenkins.osmocom.org/jenkins/job/ttcn3-msc-test/1899/artifact/logs/m…> + write_mp_osmo_repo <https://jenkins.osmocom.org/jenkins/job/ttcn3-msc-test/1899/artifact/logs/m…> + local repo=nightly + local config=<https://jenkins.osmocom.org/jenkins/job/ttcn3-msc-test/1899/artifact/logs/m…> + local line + [ -e <https://jenkins.osmocom.org/jenkins/job/ttcn3-msc-test/1899/artifact/logs/m…> ] + line=Misc_Helpers.mp_osmo_repo := "nightly" + sed -i s/\[MODULE_PARAMETERS\]/\[MODULE_PARAMETERS\]\nMisc_Helpers.mp_osmo_repo := "nightly"/g <https://jenkins.osmocom.org/jenkins/job/ttcn3-msc-test/1899/artifact/logs/m…> + mkdir <https://jenkins.osmocom.org/jenkins/job/ttcn3-msc-test/1899/artifact/logs/s…> + cp osmo-stp.cfg <https://jenkins.osmocom.org/jenkins/job/ttcn3-msc-test/1899/artifact/logs/s…> + mkdir <https://jenkins.osmocom.org/jenkins/job/ttcn3-msc-test/1899/artifact/logs/m…> + mkdir <https://jenkins.osmocom.org/jenkins/job/ttcn3-msc-test/1899/artifact/logs/m…> + cp osmo-msc.cfg <https://jenkins.osmocom.org/jenkins/job/ttcn3-msc-test/1899/artifact/logs/m…> + mkdir <https://jenkins.osmocom.org/jenkins/job/ttcn3-msc-test/1899/artifact/logs/u…> + network_create + docker network ls + grep -q ttcn3-msc-test + set +x Removing stale network and containers... + network_clean + docker network inspect ttcn3-msc-test + grep Name + cut -d : -f2 + awk -F" NR>1{print $2} + xargs -rn1 docker kill jenkins-TTCN3-centos-msc-test-975-msc jenkins-TTCN3-centos-msc-test-975-ttcn3-msc-test jenkins-TTCN3-centos-msc-test-975-stp + network_remove + set +x Removing network ttcn3-msc-test + docker network remove ttcn3-msc-test Error: No such network: ttcn3-msc-test + clean_up_common + set +e + set +x ### Clean up ### + trap - EXIT INT TERM 0 + type clean_up + network_clean + + docker network inspectgrep ttcn3-msc-test Name + cut -d : -f2 + awk -F" NR>1{print $2} + xargs -rn1 docker kill Error: No such network: ttcn3-msc-test + network_remove + set +x Removing network ttcn3-msc-test + docker network remove ttcn3-msc-test Error: No such network: ttcn3-msc-test + rm -rf <https://jenkins.osmocom.org/jenkins/job/ttcn3-msc-test/1899/artifact/logs/u…> + fix_perms + set +x Fixing permissions + docker run --rm -v <https://jenkins.osmocom.org/jenkins/job/ttcn3-msc-test/ws/logs>:/data -v <https://jenkins.osmocom.org/jenkins/job/ttcn3-msc-test/ws/_cache>:/cache --name jenkins-ttcn3-msc-test-1899-cleaner debian:bullseye chmod -R a+rX /data/ /cache/ + collect_logs + cat <https://jenkins.osmocom.org/jenkins/job/ttcn3-msc-test/1899/artifact/logs/*…> cat: '<https://jenkins.osmocom.org/jenkins/job/ttcn3-msc-test/1899/artifact/logs/*…'>: No such file or directory + true Build step 'Execute shell' marked build as failure Recording test results ERROR: Step ‘Publish JUnit test result report’ failed: No test report files were found. Configuration error?

2 years, 5 months

Build failed in Jenkins: TTCN3-centos-msc-test #975

by jenkins＠lists.osmocom.org

See <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-msc-test/975/display/r…> Changes: [Oliver Smith] ttcn3-*/jenkins*.sh: use $SUBNET everywhere [Oliver Smith] network_create: find free subnet automatically ------------------------------------------ [...truncated 1.41 MB...] MSC_Test_0-SCCP(854)@8d0921516074: DT1 data has been put to the reassembly buffer MSC_Test_0-SCCP(854)@8d0921516074: DT1/segmentingReassembl/more==0 received=> send ASP_SCCP_N_DATA comes MSC_Test_0-SCCP(854)@8d0921516074: Session index based on connection ID:0 MSC_Test_0-SCCP(854)@8d0921516074: vl_len:3 MSC_Test_0-SCCP(854)@8d0921516074: vl_from0 MSC_Test_0-SCCP(854)@8d0921516074: data sent by MTP3_SCCP_PORT: '000121'O MSC_Test_0-M3UA(856)@8d0921516074: MTP3_SP_PORT: ASP_MTP3_TRANSFERreq received -> message sent via SCTP MSC_Test_0-M3UA(856)@8d0921516074: Message received on association #8 MSC_Test_0-M3UA(856)@8d0921516074: MTP3_SP_PORT: Data received -> TRANSFERind sent MSC_Test_0-SCCP(854)@8d0921516074: Session index based on local reference:0 MSC_Test_0-RAN(855)@8d0921516074: Deleted conn table entry 0TC_lu_and_mo_ussd_mo_release0(862)13299034 MSC_Test_0-M3UA(856)@8d0921516074: MTP3_SP_PORT: ASP_MTP3_TRANSFERreq received -> message sent via SCTP TC_lu_and_mo_ussd_mo_release0(862)@8d0921516074: setverdict(pass): pass -> pass, component reason not changed TC_lu_and_mo_ussd_mo_release0(862)@8d0921516074: f_verify_vty_lac_ci(): VLR record matches "LAC / cell ID: 23 / 42" TC_lu_and_mo_ussd_mo_release0(862)@8d0921516074: setverdict(pass): pass -> pass, component reason not changed TC_lu_and_mo_ussd_mo_release0(862)@8d0921516074: Final verdict of PTC: pass MSC_Test-GSUP(860)@8d0921516074: Final verdict of PTC: none MSC_Test_0-M3UA(856)@8d0921516074: Final verdict of PTC: none MSC_Test-MNCC(857)@8d0921516074: Final verdict of PTC: none MSC_Test-SMPP(861)@8d0921516074: Final verdict of PTC: none MSC_Test_0-SCCP(854)@8d0921516074: Final verdict of PTC: none MSC_Test-MGCP(858)@8d0921516074: Final verdict of PTC: none IPA-CTRL-CLI-IPA(863)@8d0921516074: Final verdict of PTC: none IPA-CTRL-CLI-IPA(853)@8d0921516074: Final verdict of PTC: none MSC_Test-GSUP-IPA(859)@8d0921516074: Final verdict of PTC: none MSC_Test_0-RAN(855)@8d0921516074: Final verdict of PTC: none MTC@8d0921516074: Setting final verdict of the test case. MTC@8d0921516074: Local verdict of MTC: none MTC@8d0921516074: Local verdict of PTC IPA-CTRL-CLI-IPA(853): none (none -> none) MTC@8d0921516074: Local verdict of PTC MSC_Test_0-SCCP(854): none (none -> none) MTC@8d0921516074: Local verdict of PTC MSC_Test_0-RAN(855): none (none -> none) MTC@8d0921516074: Local verdict of PTC MSC_Test_0-M3UA(856): none (none -> none) MTC@8d0921516074: Local verdict of PTC MSC_Test-MNCC(857): none (none -> none) MTC@8d0921516074: Local verdict of PTC MSC_Test-MGCP(858): none (none -> none) MTC@8d0921516074: Local verdict of PTC MSC_Test-GSUP-IPA(859): none (none -> none) MTC@8d0921516074: Local verdict of PTC MSC_Test-GSUP(860): none (none -> none) MTC@8d0921516074: Local verdict of PTC MSC_Test-SMPP(861): none (none -> none) MTC@8d0921516074: Local verdict of PTC TC_lu_and_mo_ussd_mo_release0(862): pass (none -> pass) MTC@8d0921516074: Local verdict of PTC IPA-CTRL-CLI-IPA(863): none (pass -> pass) MTC@8d0921516074: Test case TC_lu_and_mo_ussd_mo_release finished. Verdict: pass MTC@8d0921516074: Starting external command `../ttcn3-tcpdump-stop.sh MSC_Tests.TC_lu_and_mo_ussd_mo_release pass'. Tue Jan 17 04:40:31 UTC 2023 [1;32m====== MSC_Tests.TC_lu_and_mo_ussd_mo_release pass ======[0m Saving talloc report from 172.18.186.10:4254 to MSC_Tests.TC_lu_and_mo_ussd_mo_release.talloc Waiting for packet dumper to finish... 0 (prev_count=-1, count=291188) Waiting for packet dumper to finish... 1 (prev_count=291188, count=292265) MTC@8d0921516074: External command `../ttcn3-tcpdump-stop.sh MSC_Tests.TC_lu_and_mo_ussd_mo_release pass' was executed successfully (exit status: 0). MTC@8d0921516074: Starting external command `../ttcn3-tcpdump-start.sh MSC_Tests.TC_lu_and_ss_session_timeout'. ------ MSC_Tests.TC_lu_and_ss_session_timeout ------ Tue Jan 17 04:40:34 UTC 2023 NOTE: unable to use dumpcap due to missing capabilities or suid bit Waiting for packet dumper to start... 0 MTC@8d0921516074: External command `../ttcn3-tcpdump-start.sh MSC_Tests.TC_lu_and_ss_session_timeout' was executed successfully (exit status: 0). MTC@8d0921516074: Test case TC_lu_and_ss_session_timeout started. MTC@8d0921516074: Connecting BSSAP RAN_Emulation to SCCP_SP_PORT MTC@8d0921516074: Starting RAN_Emulation MSC_Test_0-M3UA(867)@8d0921516074: ************************************************* MSC_Test_0-M3UA(867)@8d0921516074: M3UA emulation initiated, the test can be started MSC_Test_0-M3UA(867)@8d0921516074: ************************************************* MSC_Test_0-SCCP(865)@8d0921516074: v_sccp_pdu_maxlen:268 MSC_Test-MNCC(868)@8d0921516074: Ignoring MNCC { msg_type := MNCC_SOCKET_HELLO (1024), u := { hello := { version := 8, mncc_size := 1876, data_frame_size := 8, called_offset := 104, signal_offset := 796, emergency_offset := 812, lchan_type_offset := 832 } } } MSC_Test-GSUP-IPA(870)@8d0921516074: Established a new IPA connection (conn_id=2) MSC_Test-GSUP-IPA(870)@8d0921516074: CCM Tx:{ msg_type := IPAC_MSGT_ID_GET (4), u := { get := { { len := 1, tag := IPAC_IDTAG_UNITNAME (1) }, { len := 1, tag := IPAC_IDTAG_UNITID (8) } } } } MSC_Test-GSUP-IPA(870)@8d0921516074: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit } MSC_Test-GSUP-IPA(870)@8d0921516074: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit } MSC_Test-GSUP-IPA(870)@8d0921516074: CCM Rx:{ msg_type := IPAC_MSGT_ID_RESP (5), u := { resp := { { len := 23, tag := IPAC_IDTAG_UNITNAME (1), data := '4D53432D30302D30302D30302D30302D30302D303000'O }, { len := 7, tag := IPAC_IDTAG_UNITID (8), data := '302F302F3000'O } } } } MSC_Test-GSUP-IPA(870)@8d0921516074: IPA ID RESP: { { len := 23, tag := IPAC_IDTAG_UNITNAME (1), data := '4D53432D30302D30302D30302D30302D30302D303000'O }, { len := 7, tag := IPAC_IDTAG_UNITID (8), data := '302F302F3000'O } } MSC_Test-GSUP-IPA(870)@8d0921516074: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } MSC_Test-GSUP-IPA(870)@8d0921516074: CCM Rx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit } MSC_Test-GSUP(871)@8d0921516074: Created GsupExpect[0] for "262420000000051" to be handled at TC_lu_and_ss_session_timeout0(873) TC_lu_and_ss_session_timeout0(873)@8d0921516074: Sending COMPL L3: { discriminator := '0000'B, tiOrSkip := { skipIndicator := '0000'B }, msgs := { mm := { locationUpdateRequest := { messageType := '001000'B, nsd := '00'B, locationUpdatingType := { lut := '10'B, spare1_1 := '0'B, fop := '0'B }, cipheringKeySequenceNumber := { keySequence := '000'B, spare := '0'B }, locationAreaIdentification := { mcc_mnc := '62F220'O, lac := '9999'O }, mobileStationClassmark1 := { rf_PowerCapability := '010'B, a5_1 := '0'B, esind := '1'B, revisionLevel := '10'B, spare1_1 := '0'B }, mobileIdentityLV := { lengthIndicator := 0, mobileIdentityV := { typeOfIdentity := '001'B, oddEvenInd_identity := { imsi := { oddevenIndicator := '1'B, digits := '262420000000051'H, fillerDigit := omit } } } }, classmarkInformationType2_forUMTS := omit, additionalUpdateParameterTV := omit, deviceProperties := omit, mS_NetworkFeatureSupport := omit } } } } MSC_Test_0-M3UA(867)@8d0921516074: SCTP_ConnectResult -> connection established from: "172.18.186.103":23906 to server: "172.18.186.200":2905 association #8 MSC_Test_0-M3UA(867)@8d0921516074: Association #8 state changed to aSP_Down_sCTP_Associate_done (2) MSC_Test_0-M3UA(867)@8d0921516074: Association #8 state changed to: aSP_Down_commUP_Received (3) MSC_Test_0-M3UA(867)@8d0921516074: M3UA_ASPUP sent MSC_Test_0-M3UA(867)@8d0921516074: Association #8 state changed to: aSP_Down_ASPUP_Sent (4) MSC_Test_0-M3UA(867)@8d0921516074: Message received on association #8 MSC_Test_0-M3UA(867)@8d0921516074: M3UA_ASPUP_Ack received -> send M3UA_ASPAC MSC_Test_0-M3UA(867)@8d0921516074: Association #8 state changed to: aSP_Inactive (5) MSC_Test_0-M3UA(867)@8d0921516074: Association #8 state changed to: aSP_Inact_ASPAC_Sent (6) MSC_Test_0-M3UA(867)@8d0921516074: Message received on association #8 MSC_Test_0-M3UA(867)@8d0921516074: Received M3UA_NOTIFY -> discard MSC_Test_0-M3UA(867)@8d0921516074: Message received on association #8 MSC_Test_0-M3UA(867)@8d0921516074: ASPAC_Ack received for association #8 MSC_Test_0-M3UA(867)@8d0921516074: Association #8 state changed to: aSP_Active (7) MSC_Test_0-M3UA(867)@8d0921516074: Message received on association #8 MSC_Test_0-M3UA(867)@8d0921516074: Received M3UA_NOTIFY -> discard MSC_Test_0-M3UA(867)@8d0921516074: MTP3_SP_PORT: ASP_MTP3_TRANSFERreq received -> message sent via SCTP MSC_Test_0-M3UA(867)@8d0921516074: Message received on association #8 MSC_Test_0-M3UA(867)@8d0921516074: MTP3_SP_PORT: Data received -> TRANSFERind sent MSC_Test_0-RAN(866)@8d0921516074: BSSMAP: Received RESET-ACK in response to RESET, we're ready to go! MSC_Test_0-RAN(866)@8d0921516074: Added conn table entry 0TC_lu_and_ss_session_timeout0(873)14494755 MSC_Test_0-SCCP(865)@8d0921516074: First idle individual index:0 MSC_Test_0-RAN(866)@8d0921516074: patch: N(SD) for ConnIdx 0 set to 1 MSC_Test_0-M3UA(867)@8d0921516074: MTP3_SP_PORT: ASP_MTP3_TRANSFERreq received -> message sent via SCTP MSC_Test_0-M3UA(867)@8d0921516074: Message received on association #8 MSC_Test_0-M3UA(867)@8d0921516074: MTP3_SP_PORT: Data received -> TRANSFERind sent MSC_Test_0-M3UA(867)@8d0921516074: Message received on association #8 MSC_Test_0-M3UA(867)@8d0921516074: MTP3_SP_PORT: Data received -> TRANSFERind sent MSC_Test_0-SCCP(865)@8d0921516074: { messageType := 2, destLocRef := 'DD2C23'O, sourceLocRef := '00007D'O, protClass := { class := '0010'B, messageHandling := '0000'B }, pointer1 := 1, optionalPart := { credit := omit, calledPAddress := { paramName := 3, paramLength := 4, addr := '43B900FE'O }, data := omit, importance := omit }, eop := { paramName := 0 } } MSC_Test_0-SCCP(865)@8d0921516074: Session index based on local reference:0 MSC_Test-GSUP(871)@8d0921516074: Found GsupExpect[0] for "262420000000051" handled at TC_lu_and_ss_session_timeout0(873) MSC_Test-GSUP(871)@8d0921516074: Added IMSI table entry 0TC_lu_and_ss_session_timeout0(873)"262420000000051" MSC_Test_0-SCCP(865)@8d0921516074: Session index based on local reference:0 MSC_Test_0-SCCP(865)@8d0921516074: DT1 will be put to the reassembly buffer MSC_Test_0-SCCP(865)@8d0921516074: DT1 data has been put to the reassembly buffer MSC_Test_0-SCCP(865)@8d0921516074: DT1/segmentingReassembl/more==0 received=> send ASP_SCCP_N_DATA comes MSC_Test_0-SCCP(865)@8d0921516074: Session index based on connection ID:0 MSC_Test_0-SCCP(865)@8d0921516074: vl_len:8 MSC_Test_0-SCCP(865)@8d0921516074: vl_from0 MSC_Test_0-SCCP(865)@8d0921516074: data sent by MTP3_SCCP_PORT: '0006541203524902'O MSC_Test_0-M3UA(867)@8d0921516074: MTP3_SP_PORT: ASP_MTP3_TRANSFERreq received -> message sent via SCTP TC_lu_and_ss_session_timeout0(873)@8d0921516074: f_verify_vty_lac_ci(): Active connection matches "LAC / cell ID: 23 / 42" TC_lu_and_ss_session_timeout0(873)@8d0921516074: setverdict(pass): none -> pass MSC_Test_0-M3UA(867)@8d0921516074: Message received on association #8 MSC_Test_0-M3UA(867)@8d0921516074: MTP3_SP_PORT: Data received -> TRANSFERind sent MSC_Test_0-SCCP(865)@8d0921516074: Session index based on local reference:0 MSC_Test_0-SCCP(865)@8d0921516074: DT1 will be put to the reassembly buffer MSC_Test_0-SCCP(865)@8d0921516074: DT1 data has been put to the reassembly buffer MSC_Test_0-SCCP(865)@8d0921516074: DT1/segmentingReassembl/more==0 received=> send ASP_SCCP_N_DATA comes TC_lu_and_ss_session_timeout0(873)@8d0921516074: setverdict(pass): pass -> pass, component reason not changed MSC_Test_0-RAN(866)@8d0921516074: patching N(SD)=1 into dtap '051B'O MSC_Test_0-RAN(866)@8d0921516074: patched enc_l3: '055B'O MSC_Test_0-SCCP(865)@8d0921516074: Session index based on connection ID:0 MSC_Test_0-SCCP(865)@8d0921516074: vl_len:5 MSC_Test_0-SCCP(865)@8d0921516074: vl_from0 MSC_Test_0-SCCP(865)@8d0921516074: data sent by MTP3_SCCP_PORT: '010002055B'O MSC_Test_0-M3UA(867)@8d0921516074: MTP3_SP_PORT: ASP_MTP3_TRANSFERreq received -> message sent via SCTP MSC_Test_0-M3UA(867)@8d0921516074: Message received on association #8 MSC_Test_0-M3UA(867)@8d0921516074: MTP3_SP_PORT: Data received -> TRANSFERind sent MSC_Test_0-SCCP(865)@8d0921516074: Session index based on local reference:0 MSC_Test_0-SCCP(865)@8d0921516074: DT1 will be put to the reassembly buffer MSC_Test_0-SCCP(865)@8d0921516074: DT1 data has been put to the reassembly buffer MSC_Test_0-SCCP(865)@8d0921516074: DT1/segmentingReassembl/more==0 received=> send ASP_SCCP_N_DATA comes MSC_Test_0-SCCP(865)@8d0921516074: Session index based on connection ID:0 MSC_Test_0-SCCP(865)@8d0921516074: vl_len:3 MSC_Test_0-SCCP(865)@8d0921516074: vl_from0 MSC_Test_0-SCCP(865)@8d0921516074: data sent by MTP3_SCCP_PORT: '000121'O MSC_Test_0-M3UA(867)@8d0921516074: MTP3_SP_PORT: ASP_MTP3_TRANSFERreq received -> message sent via SCTP MSC_Test_0-M3UA(867)@8d0921516074: Message received on association #8 MSC_Test_0-M3UA(867)@8d0921516074: MTP3_SP_PORT: Data received -> TRANSFERind sent MSC_Test_0-SCCP(865)@8d0921516074: Session index based on local reference:0 MSC_Test_0-M3UA(867)@8d0921516074: MTP3_SP_PORT: ASP_MTP3_TRANSFERreq received -> message sent via SCTP MSC_Test_0-RAN(866)@8d0921516074: Deleted conn table entry 0TC_lu_and_ss_session_timeout0(873)14494755 TC_lu_and_ss_session_timeout0(873)@8d0921516074: setverdict(pass): pass -> pass, component reason not changed TC_lu_and_ss_session_timeout0(873)@8d0921516074: f_verify_vty_lac_ci(): VLR record matches "LAC / cell ID: 23 / 42" TC_lu_and_ss_session_timeout0(873)@8d0921516074: setverdict(pass): pass -> pass, component reason not changed TC_lu_and_ss_session_timeout0(873)@8d0921516074: setverdict(pass): pass -> pass, component reason not changed TC_lu_and_ss_session_timeout0(873)@8d0921516074: Sending COMPL L3: { discriminator := '0000'B, tiOrSkip := { skipIndicator := '0000'B }, msgs := { mm := { cMServiceRequest := { messageType := '000000'B, nsd := '00'B, cm_ServiceType := '1000'B, cipheringKeySequenceNumber := { keySequence := '000'B, spare := '0'B }, mobileStationClassmark2 := { lengthIndicator := 0, rf_PowerCapability := '000'B, a5_1 := '0'B, esind := '1'B, revisionLevel := '10'B, spare1_1 := '0'B, mobileStationClassmark2_oct4 := { fc := '1'B, vgcs := '0'B, vbs := '0'B, sm_Capability := '1'B, ss_ScreenIndicator := '01'B, ps_Capability := '1'B, spare2_1 := '0'B }, mobileStationClassmark2_oct5 := { a5_2 := '0'B, a5_3 := '1'B, cmsp := '0'B, solsa := '0'B, ucs2 := '0'B, lcsva_cap := '0'B, spare5_7 := '0'B, cm3 := '0'B } }, mobileIdentity := { lengthIndicator := 0, mobileIdentityV := { typeOfIdentity := '000'B, oddEvenInd_identity := { tmsi_ptmsi := { oddevenIndicator := '0'B, fillerDigit := '1111'B, octets := '219C1118'O } } } }, priorityLevel := omit, additionalUpdateParameterTV := omit, deviceProperties := omit } } } } MSC_Test_0-RAN(866)@8d0921516074: Added conn table entry 0TC_lu_and_ss_session_timeout0(873)8673647 MSC_Test_0-RAN(866)@8d0921516074: patch: N(SD) for ConnIdx 0 set to 1 MSC_Test_0-SCCP(865)@8d0921516074: First idle individual index:0 MSC_Test_0-M3UA(867)@8d0921516074: MTP3_SP_PORT: ASP_MTP3_TRANSFERreq received -> message sent via SCTP MSC_Test_0-M3UA(867)@8d0921516074: Message received on association #8 MSC_Test_0-M3UA(867)@8d0921516074: MTP3_SP_PORT: Data received -> TRANSFERind sent MSC_Test_0-SCCP(865)@8d0921516074: { messageType := 2, destLocRef := '84596F'O, sourceLocRef := '00007E'O, protClass := { class := '0010'B, messageHandling := '0000'B }, pointer1 := 1, optionalPart := { credit := omit, calledPAddress := { paramName := 3, paramLength := 4, addr := '43B900FE'O }, data := omit, importance := omit }, eop := { paramName := 0 } } MSC_Test_0-SCCP(865)@8d0921516074: Session index based on local reference:0 MSC_Test_0-M3UA(867)@8d0921516074: Message received on association #8 MSC_Test_0-M3UA(867)@8d0921516074: MTP3_SP_PORT: Data received -> TRANSFERind sent MSC_Test_0-M3UA(867)@8d0921516074: Message received on association #8 MSC_Test_0-SCCP(865)@8d0921516074: Session index based on local reference:0 MSC_Test_0-M3UA(867)@8d0921516074: MTP3_SP_PORT: Data received -> TRANSFERind sent MSC_Test_0-SCCP(865)@8d0921516074: DT1 will be put to the reassembly buffer MSC_Test_0-SCCP(865)@8d0921516074: DT1 data has been put to the reassembly buffer MSC_Test_0-SCCP(865)@8d0921516074: DT1/segmentingReassembl/more==0 received=> send ASP_SCCP_N_DATA comes MSC_Test_0-SCCP(865)@8d0921516074: Session index based on local reference:0 MSC_Test_0-SCCP(865)@8d0921516074: DT1 will be put to the reassembly buffer MSC_Test_0-SCCP(865)@8d0921516074: DT1 data has been put to the reassembly buffer MSC_Test_0-SCCP(865)@8d0921516074: DT1/segmentingReassembl/more==0 received=> send ASP_SCCP_N_DATA comes TC_lu_and_ss_session_timeout0(873)@8d0921516074: f_verify_vty_lac_ci(): Active connection matches "LAC / cell ID: 23 / 42" TC_lu_and_ss_session_timeout0(873)@8d0921516074: setverdict(pass): pass -> pass, component reason not changed MSC_Test-GSUP(871)@8d0921516074: Created GsupExpect[0] for "262420000000051" to be handled at TC_lu_and_ss_session_timeout0(873) MSC_Test_0-RAN(866)@8d0921516074: patching N(SD)=1 into dtap '1B3B1C19A11702010102013B300F04010F040A2379995D0ECFCB917619'O MSC_Test_0-RAN(866)@8d0921516074: patched enc_l3: '1B7B1C19A11702010102013B300F04010F040A2379995D0ECFCB917619'O MSC_Test_0-SCCP(865)@8d0921516074: Session index based on connection ID:0 MSC_Test_0-SCCP(865)@8d0921516074: vl_len:32 MSC_Test_0-SCCP(865)@8d0921516074: vl_from0 MSC_Test_0-SCCP(865)@8d0921516074: data sent by MTP3_SCCP_PORT: '01001D1B7B1C19A11702010102013B300F04010F040A2379995D0ECFCB917619'O MSC_Test_0-M3UA(867)@8d0921516074: MTP3_SP_PORT: ASP_MTP3_TRANSFERreq received -> message sent via SCTP TC_lu_and_ss_session_timeout0(873)@8d0921516074: setverdict(pass): pass -> pass, component reason not changed MSC_Test-GSUP-IPA(870)@8d0921516074: IPA: Closed MSC_Test-GSUP(871)@8d0921516074: setverdict(fail): none -> fail reason: "GSUP Connection Lost", new component reason: "GSUP Connection Lost" MC@8d0921516074: Test Component 871 has requested to stop MTC. Terminating current testcase execution. MSC_Test-SMPP(872)@8d0921516074: setverdict(fail): none -> fail reason: "Unexpected SMPP from peer", new component reason: "Unexpected SMPP from peer" MSC_Test-GSUP-IPA(870)@8d0921516074: Final verdict of PTC: none IPA-CTRL-CLI-IPA(864)@8d0921516074: IPA: Closed IPA-CTRL-CLI-IPA(864)@8d0921516074: Dynamic test case error: Sending data on the connection of port IPA_CTRL_PORT to 1:IPA_CTRL failed. (Broken pipe) IPA-CTRL-CLI-IPA(864)@8d0921516074: setverdict(error): none -> error MSC_Test-GSUP(871)@8d0921516074: Final verdict of PTC: fail reason: "GSUP Connection Lost" IPA-CTRL-CLI-IPA(874)@8d0921516074: IPA: Closed MSC_Test_0-M3UA(867)@8d0921516074: Final verdict of PTC: none MSC_Test_0-RAN(866)@8d0921516074: Final verdict of PTC: none MSC_Test-MGCP(869)@8d0921516074: Final verdict of PTC: none MSC_Test-SMPP(872)@8d0921516074: Final verdict of PTC: fail reason: "Unexpected SMPP from peer" IPA-CTRL-CLI-IPA(864)@8d0921516074: Final verdict of PTC: error IPA-CTRL-CLI-IPA(874)@8d0921516074: Final verdict of PTC: none TC_lu_and_ss_session_timeout0(873)@8d0921516074: Final verdict of PTC: pass MSC_Test-MNCC(868)@8d0921516074: Final verdict of PTC: none MSC_Test_0-SCCP(865)@8d0921516074: Final verdict of PTC: none MTC@8d0921516074: Setting final verdict of the test case. MTC@8d0921516074: Local verdict of MTC: none MTC@8d0921516074: Local verdict of PTC IPA-CTRL-CLI-IPA(864): error (none -> error) MTC@8d0921516074: Local verdict of PTC MSC_Test_0-SCCP(865): none (error -> error) MTC@8d0921516074: Local verdict of PTC MSC_Test_0-RAN(866): none (error -> error) MTC@8d0921516074: Local verdict of PTC MSC_Test_0-M3UA(867): none (error -> error) MTC@8d0921516074: Local verdict of PTC MSC_Test-MNCC(868): none (error -> error) MTC@8d0921516074: Local verdict of PTC MSC_Test-MGCP(869): none (error -> error) MTC@8d0921516074: Local verdict of PTC MSC_Test-GSUP-IPA(870): none (error -> error) MTC@8d0921516074: Local verdict of PTC MSC_Test-GSUP(871): fail (error -> error) reason: "GSUP Connection Lost" MTC@8d0921516074: Local verdict of PTC MSC_Test-SMPP(872): fail (error -> error) reason: "Unexpected SMPP from peer" MTC@8d0921516074: Local verdict of PTC TC_lu_and_ss_session_timeout0(873): pass (error -> error) MTC@8d0921516074: Local verdict of PTC IPA-CTRL-CLI-IPA(874): none (error -> error) MTC@8d0921516074: Test case TC_lu_and_ss_session_timeout finished. Verdict: error MTC@8d0921516074: Starting external command `../ttcn3-tcpdump-stop.sh MSC_Tests.TC_lu_and_ss_session_timeout error'. Tue Jan 17 04:40:37 UTC 2023 [1;31m------ MSC_Tests.TC_lu_and_ss_session_timeout error ------[0m Saving talloc report from 172.18.186.10:4254 to MSC_Tests.TC_lu_and_ss_session_timeout.talloc + clean_up_common + set +e + set +x ### Clean up ### + trap - EXIT INT TERM 0 + type clean_up + network_clean + docker network inspect ttcn3-msc-test + grep Name + cut -d : -f2 + awk -F" NR>1{print $2} + xargs -rn1 docker kill Error response from daemon: Cannot kill container: jenkins-TTCN3-centos-msc-test-975-stp: Container a2a1eeb1cf403164c0df18acd5cc88e787a8bff8c1cec37414ea165d28a6ab20 is not running + network_remove + set +x Removing network ttcn3-msc-test + docker network remove ttcn3-msc-test ttcn3-msc-test + rm -rf <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-msc-test/975/artifact/…> + fix_perms + set +x Fixing permissions + docker run --rm -v <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-msc-test/ws/logs>:/data -v <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-msc-test/ws/_cache>:/cache --name jenkins-TTCN3-centos-msc-test-975-cleaner debian:bullseye chmod -R a+rX /data/ /cache/ + collect_logs + cat <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-msc-test/975/artifact/…> Build step 'Execute shell' marked build as failure Recording test results [Checks API] No suitable checks publisher found.

2 years, 5 months

Build failed in Jenkins: ttcn3-ggsn-test-kernel-latest-net-next #694

by jenkins＠lists.osmocom.org

See <https://jenkins.osmocom.org/jenkins/job/ttcn3-ggsn-test-kernel-latest-net-n…> Changes: [Oliver Smith] ttcn3-*/jenkins*.sh: use $SUBNET everywhere [Oliver Smith] network_create: find free subnet automatically ------------------------------------------ [...truncated 58.63 KB...] Tue Jan 17 04:26:53 UTC 2023 [1;32m====== GGSN_Tests.TC_pdp4_act_deact_ipcp_pap_broken pass ======[0m Saving talloc report from 172.18.4.200:4260 to GGSN_Tests.TC_pdp4_act_deact_ipcp_pap_broken.talloc ERROR: failed to get talloc report via vty Waiting for packet dumper to finish... 0 (prev_count=-1, count=11102) Waiting for packet dumper to finish... 1 (prev_count=11102, count=11258) MTC@0205caf3e06b: External command `../ttcn3-tcpdump-stop.sh GGSN_Tests.TC_pdp4_act_deact_ipcp_pap_broken pass' was executed successfully (exit status: 0). MTC@0205caf3e06b: Starting external command `../ttcn3-tcpdump-start.sh GGSN_Tests.TC_pdp4_act_deact_pcodns'. ------ GGSN_Tests.TC_pdp4_act_deact_pcodns ------ Tue Jan 17 04:27:00 UTC 2023 NOTE: unable to use dumpcap due to missing capabilities or suid bit Waiting for packet dumper to start... 0 MTC@0205caf3e06b: External command `../ttcn3-tcpdump-start.sh GGSN_Tests.TC_pdp4_act_deact_pcodns' was executed successfully (exit status: 0). MTC@0205caf3e06b: Test case TC_pdp4_act_deact_pcodns started. MTC@0205caf3e06b: GTP1C ConnectionID: 1 MTC@0205caf3e06b: sending CreatePDP MTC@0205caf3e06b: setverdict(pass): none -> pass MTC@0205caf3e06b: setverdict(pass): pass -> pass, component reason not changed MTC@0205caf3e06b: setverdict(pass): pass -> pass, component reason not changed MTC@0205caf3e06b: Setting final verdict of the test case. MTC@0205caf3e06b: Local verdict of MTC: pass MTC@0205caf3e06b: No PTCs were created. MTC@0205caf3e06b: Test case TC_pdp4_act_deact_pcodns finished. Verdict: pass MTC@0205caf3e06b: Starting external command `../ttcn3-tcpdump-stop.sh GGSN_Tests.TC_pdp4_act_deact_pcodns pass'. Tue Jan 17 04:27:16 UTC 2023 [1;32m====== GGSN_Tests.TC_pdp4_act_deact_pcodns pass ======[0m Saving talloc report from 172.18.4.200:4260 to GGSN_Tests.TC_pdp4_act_deact_pcodns.talloc ERROR: failed to get talloc report via vty Waiting for packet dumper to finish... 0 (prev_count=-1, count=10713) Waiting for packet dumper to finish... 1 (prev_count=10713, count=10777) MTC@0205caf3e06b: External command `../ttcn3-tcpdump-stop.sh GGSN_Tests.TC_pdp4_act_deact_pcodns pass' was executed successfully (exit status: 0). MTC@0205caf3e06b: Starting external command `../ttcn3-tcpdump-start.sh GGSN_Tests.TC_pdp4_act_deact_gtpu_access'. ------ GGSN_Tests.TC_pdp4_act_deact_gtpu_access ------ Tue Jan 17 04:27:23 UTC 2023 NOTE: unable to use dumpcap due to missing capabilities or suid bit Waiting for packet dumper to start... 0 MTC@0205caf3e06b: External command `../ttcn3-tcpdump-start.sh GGSN_Tests.TC_pdp4_act_deact_gtpu_access' was executed successfully (exit status: 0). MTC@0205caf3e06b: Test case TC_pdp4_act_deact_gtpu_access started. MTC@0205caf3e06b: GTP1C ConnectionID: 1 MTC@0205caf3e06b: sending CreatePDP MTC@0205caf3e06b: setverdict(pass): none -> pass MTC@0205caf3e06b: setverdict(pass): pass -> pass, component reason not changed MTC@0205caf3e06b: setverdict(pass): pass -> pass, component reason not changed MTC@0205caf3e06b: Setting final verdict of the test case. MTC@0205caf3e06b: Local verdict of MTC: pass MTC@0205caf3e06b: No PTCs were created. MTC@0205caf3e06b: Test case TC_pdp4_act_deact_gtpu_access finished. Verdict: pass MTC@0205caf3e06b: Starting external command `../ttcn3-tcpdump-stop.sh GGSN_Tests.TC_pdp4_act_deact_gtpu_access pass'. Tue Jan 17 04:27:39 UTC 2023 [1;32m====== GGSN_Tests.TC_pdp4_act_deact_gtpu_access pass ======[0m Saving talloc report from 172.18.4.200:4260 to GGSN_Tests.TC_pdp4_act_deact_gtpu_access.talloc ERROR: failed to get talloc report via vty Waiting for packet dumper to finish... 0 (prev_count=-1, count=10667) Waiting for packet dumper to finish... 1 (prev_count=10667, count=10731) MTC@0205caf3e06b: External command `../ttcn3-tcpdump-stop.sh GGSN_Tests.TC_pdp4_act_deact_gtpu_access pass' was executed successfully (exit status: 0). MTC@0205caf3e06b: Starting external command `../ttcn3-tcpdump-start.sh GGSN_Tests.TC_pdp4_act_deact_gtpu_access_wrong_saddr'. ------ GGSN_Tests.TC_pdp4_act_deact_gtpu_access_wrong_saddr ------ Tue Jan 17 04:27:46 UTC 2023 NOTE: unable to use dumpcap due to missing capabilities or suid bit Waiting for packet dumper to start... 0 MTC@0205caf3e06b: External command `../ttcn3-tcpdump-start.sh GGSN_Tests.TC_pdp4_act_deact_gtpu_access_wrong_saddr' was executed successfully (exit status: 0). MTC@0205caf3e06b: Test case TC_pdp4_act_deact_gtpu_access_wrong_saddr started. MTC@0205caf3e06b: GTP1C ConnectionID: 1 MTC@0205caf3e06b: sending CreatePDP MTC@0205caf3e06b: setverdict(pass): none -> pass MTC@0205caf3e06b: Warning: Stopping inactive timer T_default. MTC@0205caf3e06b: setverdict(pass): pass -> pass, component reason not changed MTC@0205caf3e06b: setverdict(pass): pass -> pass, component reason not changed MTC@0205caf3e06b: Setting final verdict of the test case. MTC@0205caf3e06b: Local verdict of MTC: pass MTC@0205caf3e06b: No PTCs were created. MTC@0205caf3e06b: Test case TC_pdp4_act_deact_gtpu_access_wrong_saddr finished. Verdict: pass MTC@0205caf3e06b: Starting external command `../ttcn3-tcpdump-stop.sh GGSN_Tests.TC_pdp4_act_deact_gtpu_access_wrong_saddr pass'. Tue Jan 17 04:28:05 UTC 2023 [1;32m====== GGSN_Tests.TC_pdp4_act_deact_gtpu_access_wrong_saddr pass ======[0m Saving talloc report from 172.18.4.200:4260 to GGSN_Tests.TC_pdp4_act_deact_gtpu_access_wrong_saddr.talloc ERROR: failed to get talloc report via vty Waiting for packet dumper to finish... 0 (prev_count=-1, count=11153) Waiting for packet dumper to finish... 1 (prev_count=11153, count=11345) MTC@0205caf3e06b: External command `../ttcn3-tcpdump-stop.sh GGSN_Tests.TC_pdp4_act_deact_gtpu_access_wrong_saddr pass' was executed successfully (exit status: 0). MTC@0205caf3e06b: Starting external command `../ttcn3-tcpdump-start.sh GGSN_Tests.TC_pdp4_act_deact_gtpu_access_ipv6_apn4'. ------ GGSN_Tests.TC_pdp4_act_deact_gtpu_access_ipv6_apn4 ------ Tue Jan 17 04:28:12 UTC 2023 NOTE: unable to use dumpcap due to missing capabilities or suid bit Waiting for packet dumper to start... 0 MTC@0205caf3e06b: External command `../ttcn3-tcpdump-start.sh GGSN_Tests.TC_pdp4_act_deact_gtpu_access_ipv6_apn4' was executed successfully (exit status: 0). MTC@0205caf3e06b: Test case TC_pdp4_act_deact_gtpu_access_ipv6_apn4 started. MTC@0205caf3e06b: GTP1C ConnectionID: 1 MTC@0205caf3e06b: sending CreatePDP MTC@0205caf3e06b: setverdict(pass): none -> pass MTC@0205caf3e06b: Warning: Stopping inactive timer T_default. MTC@0205caf3e06b: setverdict(pass): pass -> pass, component reason not changed MTC@0205caf3e06b: setverdict(pass): pass -> pass, component reason not changed MTC@0205caf3e06b: Setting final verdict of the test case. MTC@0205caf3e06b: Local verdict of MTC: pass MTC@0205caf3e06b: No PTCs were created. MTC@0205caf3e06b: Test case TC_pdp4_act_deact_gtpu_access_ipv6_apn4 finished. Verdict: pass MTC@0205caf3e06b: Starting external command `../ttcn3-tcpdump-stop.sh GGSN_Tests.TC_pdp4_act_deact_gtpu_access_ipv6_apn4 pass'. Tue Jan 17 04:28:31 UTC 2023 [1;32m====== GGSN_Tests.TC_pdp4_act_deact_gtpu_access_ipv6_apn4 pass ======[0m Saving talloc report from 172.18.4.200:4260 to GGSN_Tests.TC_pdp4_act_deact_gtpu_access_ipv6_apn4.talloc ERROR: failed to get talloc report via vty Waiting for packet dumper to finish... 0 (prev_count=-1, count=11116) Waiting for packet dumper to finish... 1 (prev_count=11116, count=11308) MTC@0205caf3e06b: External command `../ttcn3-tcpdump-stop.sh GGSN_Tests.TC_pdp4_act_deact_gtpu_access_ipv6_apn4 pass' was executed successfully (exit status: 0). MTC@0205caf3e06b: Starting external command `../ttcn3-tcpdump-start.sh GGSN_Tests.TC_pdp4_clients_interact_with_txseq'. ------ GGSN_Tests.TC_pdp4_clients_interact_with_txseq ------ Tue Jan 17 04:28:38 UTC 2023 NOTE: unable to use dumpcap due to missing capabilities or suid bit Waiting for packet dumper to start... 0 MTC@0205caf3e06b: External command `../ttcn3-tcpdump-start.sh GGSN_Tests.TC_pdp4_clients_interact_with_txseq' was executed successfully (exit status: 0). MTC@0205caf3e06b: Test case TC_pdp4_clients_interact_with_txseq started. MTC@0205caf3e06b: GTP1C ConnectionID: 1 MTC@0205caf3e06b: sending CreatePDP MTC@0205caf3e06b: setverdict(pass): none -> pass MTC@0205caf3e06b: sending CreatePDP MTC@0205caf3e06b: setverdict(pass): pass -> pass, component reason not changed MTC@0205caf3e06b: setverdict(fail): pass -> fail MTC@0205caf3e06b: Warning: Stopping inactive timer T_default. MTC@0205caf3e06b: Setting final verdict of the test case. MTC@0205caf3e06b: Local verdict of MTC: fail MTC@0205caf3e06b: No PTCs were created. MTC@0205caf3e06b: Test case TC_pdp4_clients_interact_with_txseq finished. Verdict: fail MTC@0205caf3e06b: Starting external command `../ttcn3-tcpdump-stop.sh GGSN_Tests.TC_pdp4_clients_interact_with_txseq fail'. Tue Jan 17 04:28:57 UTC 2023 [1;31m------ GGSN_Tests.TC_pdp4_clients_interact_with_txseq fail ------[0m Saving talloc report from 172.18.4.200:4260 to GGSN_Tests.TC_pdp4_clients_interact_with_txseq.talloc ERROR: failed to get talloc report via vty Waiting for packet dumper to finish... 0 (prev_count=-1, count=13588) Waiting for packet dumper to finish... 1 (prev_count=13588, count=13780) MTC@0205caf3e06b: External command `../ttcn3-tcpdump-stop.sh GGSN_Tests.TC_pdp4_clients_interact_with_txseq fail' was executed successfully (exit status: 0). MTC@0205caf3e06b: Starting external command `../ttcn3-tcpdump-start.sh GGSN_Tests.TC_pdp4_clients_interact_without_txseq'. ------ GGSN_Tests.TC_pdp4_clients_interact_without_txseq ------ Tue Jan 17 04:29:04 UTC 2023 NOTE: unable to use dumpcap due to missing capabilities or suid bit Waiting for packet dumper to start... 0 MTC@0205caf3e06b: External command `../ttcn3-tcpdump-start.sh GGSN_Tests.TC_pdp4_clients_interact_without_txseq' was executed successfully (exit status: 0). MTC@0205caf3e06b: Test case TC_pdp4_clients_interact_without_txseq started. MTC@0205caf3e06b: GTP1C ConnectionID: 1 MTC@0205caf3e06b: sending CreatePDP MTC@0205caf3e06b: setverdict(pass): none -> pass MTC@0205caf3e06b: sending CreatePDP MTC@0205caf3e06b: setverdict(pass): pass -> pass, component reason not changed MTC@0205caf3e06b: setverdict(fail): pass -> fail MTC@0205caf3e06b: Warning: Stopping inactive timer T_default. MTC@0205caf3e06b: Setting final verdict of the test case. MTC@0205caf3e06b: Local verdict of MTC: fail MTC@0205caf3e06b: No PTCs were created. MTC@0205caf3e06b: Test case TC_pdp4_clients_interact_without_txseq finished. Verdict: fail MTC@0205caf3e06b: Starting external command `../ttcn3-tcpdump-stop.sh GGSN_Tests.TC_pdp4_clients_interact_without_txseq fail'. Tue Jan 17 04:29:23 UTC 2023 [1;31m------ GGSN_Tests.TC_pdp4_clients_interact_without_txseq fail ------[0m Saving talloc report from 172.18.4.200:4260 to GGSN_Tests.TC_pdp4_clients_interact_without_txseq.talloc ERROR: failed to get talloc report via vty Waiting for packet dumper to finish... 0 (prev_count=-1, count=13848) Waiting for packet dumper to finish... 1 (prev_count=13848, count=14040) MTC@0205caf3e06b: External command `../ttcn3-tcpdump-stop.sh GGSN_Tests.TC_pdp4_clients_interact_without_txseq fail' was executed successfully (exit status: 0). MTC@0205caf3e06b: Starting external command `../ttcn3-tcpdump-start.sh GGSN_Tests.TC_pdp4_act_deact_with_single_dns'. ------ GGSN_Tests.TC_pdp4_act_deact_with_single_dns ------ Tue Jan 17 04:29:30 UTC 2023 NOTE: unable to use dumpcap due to missing capabilities or suid bit Waiting for packet dumper to start... 0 MTC@0205caf3e06b: External command `../ttcn3-tcpdump-start.sh GGSN_Tests.TC_pdp4_act_deact_with_single_dns' was executed successfully (exit status: 0). MTC@0205caf3e06b: Test case TC_pdp4_act_deact_with_single_dns started. MTC@0205caf3e06b: GTP1C ConnectionID: 1 MTC@0205caf3e06b: sending CreatePDP MTC@0205caf3e06b: setverdict(pass): none -> pass MTC@0205caf3e06b: setverdict(pass): pass -> pass, component reason not changed MTC@0205caf3e06b: sending CreatePDP MTC@0205caf3e06b: setverdict(pass): pass -> pass, component reason not changed MTC@0205caf3e06b: setverdict(pass): pass -> pass, component reason not changed MTC@0205caf3e06b: setverdict(pass): pass -> pass, component reason not changed MTC@0205caf3e06b: Setting final verdict of the test case. MTC@0205caf3e06b: Local verdict of MTC: pass MTC@0205caf3e06b: No PTCs were created. MTC@0205caf3e06b: Test case TC_pdp4_act_deact_with_single_dns finished. Verdict: pass MTC@0205caf3e06b: Starting external command `../ttcn3-tcpdump-stop.sh GGSN_Tests.TC_pdp4_act_deact_with_single_dns pass'. Tue Jan 17 04:29:46 UTC 2023 [1;32m====== GGSN_Tests.TC_pdp4_act_deact_with_single_dns pass ======[0m Saving talloc report from 172.18.4.200:4260 to GGSN_Tests.TC_pdp4_act_deact_with_single_dns.talloc ERROR: failed to get talloc report via vty Waiting for packet dumper to finish... 0 (prev_count=-1, count=15889) Waiting for packet dumper to finish... 1 (prev_count=15889, count=15953) MTC@0205caf3e06b: External command `../ttcn3-tcpdump-stop.sh GGSN_Tests.TC_pdp4_act_deact_with_single_dns pass' was executed successfully (exit status: 0). MTC@0205caf3e06b: Starting external command `../ttcn3-tcpdump-start.sh GGSN_Tests.TC_pdp4_act_deact_with_separate_dns'. ------ GGSN_Tests.TC_pdp4_act_deact_with_separate_dns ------ Tue Jan 17 04:29:53 UTC 2023 NOTE: unable to use dumpcap due to missing capabilities or suid bit Waiting for packet dumper to start... 0 MTC@0205caf3e06b: External command `../ttcn3-tcpdump-start.sh GGSN_Tests.TC_pdp4_act_deact_with_separate_dns' was executed successfully (exit status: 0). MTC@0205caf3e06b: Test case TC_pdp4_act_deact_with_separate_dns started. MTC@0205caf3e06b: GTP1C ConnectionID: 1 MTC@0205caf3e06b: sending CreatePDP MTC@0205caf3e06b: setverdict(pass): none -> pass MTC@0205caf3e06b: setverdict(pass): pass -> pass, component reason not changed + clean_up_common + set +e + set +x ### Clean up ### + trap - EXIT INT TERM 0 + type clean_up + network_clean + docker network inspect ttcn3-ggsn-test + + cut -d : -f2 grep Name + awk -F" NR>1{print $2} + xargs -rn1 docker kill jenkins-ttcn3-ggsn-test-kernel-latest-net-next-694-ggsn + network_remove + set +x Removing network ttcn3-ggsn-test + docker network remove ttcn3-ggsn-test Error: No such network: ttcn3-ggsn-test + rm -rf <https://jenkins.osmocom.org/jenkins/job/ttcn3-ggsn-test-kernel-latest-net-n…> + fix_perms + set +x Fixing permissions + docker run --rm -v <https://jenkins.osmocom.org/jenkins/job/ttcn3-ggsn-test-kernel-latest-net-n…>:/data -v <https://jenkins.osmocom.org/jenkins/job/ttcn3-ggsn-test-kernel-latest-net-n…>:/cache --name jenkins-ttcn3-ggsn-test-kernel-latest-net-next-694-cleaner debian:bullseye chmod -R a+rX /data/ /cache/ + collect_logs + cat <https://jenkins.osmocom.org/jenkins/job/ttcn3-ggsn-test-kernel-latest-net-n…> Build step 'Execute shell' marked build as failure Recording test results [Checks API] No suitable checks publisher found.

2 years, 5 months

← Newer
1
...
5
6
7
8
9
10
11
Older →

2025

2024

2023

2022

2021

2020

2019

jenkins-notifications January 2023