- jenkins-notifications - lists.osmocom.org

Build failed in Jenkins: ttcn3-pcu-test-latest #1440
by jenkins＠lists.osmocom.org 17 Jan '23

17 Jan '23

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.

1 0

Build failed in Jenkins: ttcn3-msc-test #1899
by jenkins＠lists.osmocom.org 17 Jan '23

17 Jan '23

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?

1 0

Build failed in Jenkins: TTCN3-centos-msc-test #975
by jenkins＠lists.osmocom.org 17 Jan '23

17 Jan '23

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.

1 0

Build failed in Jenkins: ttcn3-ggsn-test-kernel-latest-net-next #694
by jenkins＠lists.osmocom.org 17 Jan '23

17 Jan '23

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.

1 0

Build failed in Jenkins: ttcn3-ggsn-test-kernel #695
by jenkins＠lists.osmocom.org 17 Jan '23

17 Jan '23

See <https://jenkins.osmocom.org/jenkins/job/ttcn3-ggsn-test-kernel/695/display/…> Changes: [Oliver Smith] ttcn3-*/jenkins*.sh: use $SUBNET everywhere [Oliver Smith] network_create: find free subnet automatically ------------------------------------------ [...truncated 146.98 KB...] MTC@daebdff8a770: Local verdict of MTC: fail reason: ""GGSN_Tests.ttcn:395 : CreatePDPContextResp: cause expectancies didn't match"" MTC@daebdff8a770: No PTCs were created. MTC@daebdff8a770: Test case TC_pdp46_act_deact_pcodns6 finished. Verdict: fail reason: "GGSN_Tests.ttcn:395 : CreatePDPContextResp: cause expectancies didn't match" MTC@daebdff8a770: Starting external command `../ttcn3-tcpdump-stop.sh GGSN_Tests.TC_pdp46_act_deact_pcodns6 fail'. Tue Jan 17 04:18:11 UTC 2023 [1;31m------ GGSN_Tests.TC_pdp46_act_deact_pcodns6 fail ------[0m Saving talloc report from 172.18.244.200:4260 to GGSN_Tests.TC_pdp46_act_deact_pcodns6.talloc ERROR: failed to get talloc report via vty Waiting for packet dumper to finish... 0 (prev_count=-1, count=9094) Waiting for packet dumper to finish... 1 (prev_count=9094, count=9222) MTC@daebdff8a770: External command `../ttcn3-tcpdump-stop.sh GGSN_Tests.TC_pdp46_act_deact_pcodns6 fail' was executed successfully (exit status: 0). MTC@daebdff8a770: Starting external command `../ttcn3-tcpdump-start.sh GGSN_Tests.TC_pdp46_act_deact_gtpu_access'. ------ GGSN_Tests.TC_pdp46_act_deact_gtpu_access ------ Tue Jan 17 04:18:18 UTC 2023 NOTE: unable to use dumpcap due to missing capabilities or suid bit Waiting for packet dumper to start... 0 MTC@daebdff8a770: External command `../ttcn3-tcpdump-start.sh GGSN_Tests.TC_pdp46_act_deact_gtpu_access' was executed successfully (exit status: 0). MTC@daebdff8a770: Test case TC_pdp46_act_deact_gtpu_access started. MTC@daebdff8a770: GTP1C ConnectionID: 1 MTC@daebdff8a770: sending CreatePDP MTC@daebdff8a770: setverdict(fail): none -> fail reason: ""GGSN_Tests.ttcn:395 : CreatePDPContextResp: cause expectancies didn't match"", new component reason: ""GGSN_Tests.ttcn:395 : CreatePDPContextResp: cause expectancies didn't match"" MTC@daebdff8a770: Stopping testcase execution from "GGSN_Tests.ttcn":395 MTC@daebdff8a770: Setting final verdict of the test case. MTC@daebdff8a770: Local verdict of MTC: fail reason: ""GGSN_Tests.ttcn:395 : CreatePDPContextResp: cause expectancies didn't match"" MTC@daebdff8a770: No PTCs were created. MTC@daebdff8a770: Test case TC_pdp46_act_deact_gtpu_access finished. Verdict: fail reason: "GGSN_Tests.ttcn:395 : CreatePDPContextResp: cause expectancies didn't match" MTC@daebdff8a770: Starting external command `../ttcn3-tcpdump-stop.sh GGSN_Tests.TC_pdp46_act_deact_gtpu_access fail'. Tue Jan 17 04:18:19 UTC 2023 [1;31m------ GGSN_Tests.TC_pdp46_act_deact_gtpu_access fail ------[0m Saving talloc report from 172.18.244.200:4260 to GGSN_Tests.TC_pdp46_act_deact_gtpu_access.talloc ERROR: failed to get talloc report via vty Waiting for packet dumper to finish... 0 (prev_count=-1, count=8842) Waiting for packet dumper to finish... 1 (prev_count=8842, count=8906) MTC@daebdff8a770: External command `../ttcn3-tcpdump-stop.sh GGSN_Tests.TC_pdp46_act_deact_gtpu_access fail' was executed successfully (exit status: 0). MTC@daebdff8a770: Starting external command `../ttcn3-tcpdump-start.sh GGSN_Tests.TC_pdp46_act_deact_gtpu_access_wrong_saddr_ipv4'. ------ GGSN_Tests.TC_pdp46_act_deact_gtpu_access_wrong_saddr_ipv4 ------ Tue Jan 17 04:18:26 UTC 2023 NOTE: unable to use dumpcap due to missing capabilities or suid bit Waiting for packet dumper to start... 0 MTC@daebdff8a770: External command `../ttcn3-tcpdump-start.sh GGSN_Tests.TC_pdp46_act_deact_gtpu_access_wrong_saddr_ipv4' was executed successfully (exit status: 0). MTC@daebdff8a770: Test case TC_pdp46_act_deact_gtpu_access_wrong_saddr_ipv4 started. MTC@daebdff8a770: GTP1C ConnectionID: 1 MTC@daebdff8a770: sending CreatePDP MTC@daebdff8a770: setverdict(fail): none -> fail reason: ""GGSN_Tests.ttcn:395 : CreatePDPContextResp: cause expectancies didn't match"", new component reason: ""GGSN_Tests.ttcn:395 : CreatePDPContextResp: cause expectancies didn't match"" MTC@daebdff8a770: Stopping testcase execution from "GGSN_Tests.ttcn":395 MTC@daebdff8a770: Setting final verdict of the test case. MTC@daebdff8a770: Local verdict of MTC: fail reason: ""GGSN_Tests.ttcn:395 : CreatePDPContextResp: cause expectancies didn't match"" MTC@daebdff8a770: No PTCs were created. MTC@daebdff8a770: Test case TC_pdp46_act_deact_gtpu_access_wrong_saddr_ipv4 finished. Verdict: fail reason: "GGSN_Tests.ttcn:395 : CreatePDPContextResp: cause expectancies didn't match" MTC@daebdff8a770: Starting external command `../ttcn3-tcpdump-stop.sh GGSN_Tests.TC_pdp46_act_deact_gtpu_access_wrong_saddr_ipv4 fail'. Tue Jan 17 04:18:27 UTC 2023 [1;31m------ GGSN_Tests.TC_pdp46_act_deact_gtpu_access_wrong_saddr_ipv4 fail ------[0m Saving talloc report from 172.18.244.200:4260 to GGSN_Tests.TC_pdp46_act_deact_gtpu_access_wrong_saddr_ipv4.talloc ERROR: failed to get talloc report via vty Waiting for packet dumper to finish... 0 (prev_count=-1, count=9087) Waiting for packet dumper to finish... 1 (prev_count=9087, count=9151) MTC@daebdff8a770: External command `../ttcn3-tcpdump-stop.sh GGSN_Tests.TC_pdp46_act_deact_gtpu_access_wrong_saddr_ipv4 fail' was executed successfully (exit status: 0). MTC@daebdff8a770: Starting external command `../ttcn3-tcpdump-start.sh GGSN_Tests.TC_pdp46_act_deact_gtpu_access_wrong_ll_saddr_ipv6'. ------ GGSN_Tests.TC_pdp46_act_deact_gtpu_access_wrong_ll_saddr_ipv6 ------ Tue Jan 17 04:18:34 UTC 2023 NOTE: unable to use dumpcap due to missing capabilities or suid bit Waiting for packet dumper to start... 0 MTC@daebdff8a770: External command `../ttcn3-tcpdump-start.sh GGSN_Tests.TC_pdp46_act_deact_gtpu_access_wrong_ll_saddr_ipv6' was executed successfully (exit status: 0). MTC@daebdff8a770: Test case TC_pdp46_act_deact_gtpu_access_wrong_ll_saddr_ipv6 started. MTC@daebdff8a770: GTP1C ConnectionID: 1 MTC@daebdff8a770: sending CreatePDP MTC@daebdff8a770: setverdict(fail): none -> fail reason: ""GGSN_Tests.ttcn:395 : CreatePDPContextResp: cause expectancies didn't match"", new component reason: ""GGSN_Tests.ttcn:395 : CreatePDPContextResp: cause expectancies didn't match"" MTC@daebdff8a770: Stopping testcase execution from "GGSN_Tests.ttcn":395 MTC@daebdff8a770: Setting final verdict of the test case. MTC@daebdff8a770: Local verdict of MTC: fail reason: ""GGSN_Tests.ttcn:395 : CreatePDPContextResp: cause expectancies didn't match"" MTC@daebdff8a770: No PTCs were created. MTC@daebdff8a770: Test case TC_pdp46_act_deact_gtpu_access_wrong_ll_saddr_ipv6 finished. Verdict: fail reason: "GGSN_Tests.ttcn:395 : CreatePDPContextResp: cause expectancies didn't match" MTC@daebdff8a770: Starting external command `../ttcn3-tcpdump-stop.sh GGSN_Tests.TC_pdp46_act_deact_gtpu_access_wrong_ll_saddr_ipv6 fail'. Tue Jan 17 04:18:35 UTC 2023 [1;31m------ GGSN_Tests.TC_pdp46_act_deact_gtpu_access_wrong_ll_saddr_ipv6 fail ------[0m Saving talloc report from 172.18.244.200:4260 to GGSN_Tests.TC_pdp46_act_deact_gtpu_access_wrong_ll_saddr_ipv6.talloc ERROR: failed to get talloc report via vty Waiting for packet dumper to finish... 0 (prev_count=-1, count=8842) Waiting for packet dumper to finish... 1 (prev_count=8842, count=8906) MTC@daebdff8a770: External command `../ttcn3-tcpdump-stop.sh GGSN_Tests.TC_pdp46_act_deact_gtpu_access_wrong_ll_saddr_ipv6 fail' was executed successfully (exit status: 0). MTC@daebdff8a770: Starting external command `../ttcn3-tcpdump-start.sh GGSN_Tests.TC_pdp46_act_deact_gtpu_access_wrong_global_saddr_ipv6'. ------ GGSN_Tests.TC_pdp46_act_deact_gtpu_access_wrong_global_saddr_ipv6 ------ Tue Jan 17 04:18:42 UTC 2023 NOTE: unable to use dumpcap due to missing capabilities or suid bit Waiting for packet dumper to start... 0 MTC@daebdff8a770: External command `../ttcn3-tcpdump-start.sh GGSN_Tests.TC_pdp46_act_deact_gtpu_access_wrong_global_saddr_ipv6' was executed successfully (exit status: 0). MTC@daebdff8a770: Test case TC_pdp46_act_deact_gtpu_access_wrong_global_saddr_ipv6 started. MTC@daebdff8a770: GTP1C ConnectionID: 1 MTC@daebdff8a770: sending CreatePDP MTC@daebdff8a770: setverdict(fail): none -> fail reason: ""GGSN_Tests.ttcn:395 : CreatePDPContextResp: cause expectancies didn't match"", new component reason: ""GGSN_Tests.ttcn:395 : CreatePDPContextResp: cause expectancies didn't match"" MTC@daebdff8a770: Stopping testcase execution from "GGSN_Tests.ttcn":395 MTC@daebdff8a770: Setting final verdict of the test case. MTC@daebdff8a770: Local verdict of MTC: fail reason: ""GGSN_Tests.ttcn:395 : CreatePDPContextResp: cause expectancies didn't match"" MTC@daebdff8a770: No PTCs were created. MTC@daebdff8a770: Test case TC_pdp46_act_deact_gtpu_access_wrong_global_saddr_ipv6 finished. Verdict: fail reason: "GGSN_Tests.ttcn:395 : CreatePDPContextResp: cause expectancies didn't match" MTC@daebdff8a770: Starting external command `../ttcn3-tcpdump-stop.sh GGSN_Tests.TC_pdp46_act_deact_gtpu_access_wrong_global_saddr_ipv6 fail'. Tue Jan 17 04:18:43 UTC 2023 [1;31m------ GGSN_Tests.TC_pdp46_act_deact_gtpu_access_wrong_global_saddr_ipv6 fail ------[0m Saving talloc report from 172.18.244.200:4260 to GGSN_Tests.TC_pdp46_act_deact_gtpu_access_wrong_global_saddr_ipv6.talloc ERROR: failed to get talloc report via vty Waiting for packet dumper to finish... 0 (prev_count=-1, count=9087) Waiting for packet dumper to finish... 1 (prev_count=9087, count=9151) MTC@daebdff8a770: External command `../ttcn3-tcpdump-stop.sh GGSN_Tests.TC_pdp46_act_deact_gtpu_access_wrong_global_saddr_ipv6 fail' was executed successfully (exit status: 0). MTC@daebdff8a770: Starting external command `../ttcn3-tcpdump-start.sh GGSN_Tests.TC_pdp46_clients_interact'. ------ GGSN_Tests.TC_pdp46_clients_interact ------ Tue Jan 17 04:18:50 UTC 2023 NOTE: unable to use dumpcap due to missing capabilities or suid bit Waiting for packet dumper to start... 0 MTC@daebdff8a770: External command `../ttcn3-tcpdump-start.sh GGSN_Tests.TC_pdp46_clients_interact' was executed successfully (exit status: 0). MTC@daebdff8a770: Test case TC_pdp46_clients_interact started. MTC@daebdff8a770: GTP1C ConnectionID: 1 MTC@daebdff8a770: sending CreatePDP MTC@daebdff8a770: setverdict(fail): none -> fail reason: ""GGSN_Tests.ttcn:395 : CreatePDPContextResp: cause expectancies didn't match"", new component reason: ""GGSN_Tests.ttcn:395 : CreatePDPContextResp: cause expectancies didn't match"" MTC@daebdff8a770: Stopping testcase execution from "GGSN_Tests.ttcn":395 MTC@daebdff8a770: Setting final verdict of the test case. MTC@daebdff8a770: Local verdict of MTC: fail reason: ""GGSN_Tests.ttcn:395 : CreatePDPContextResp: cause expectancies didn't match"" MTC@daebdff8a770: No PTCs were created. MTC@daebdff8a770: Test case TC_pdp46_clients_interact finished. Verdict: fail reason: "GGSN_Tests.ttcn:395 : CreatePDPContextResp: cause expectancies didn't match" MTC@daebdff8a770: Starting external command `../ttcn3-tcpdump-stop.sh GGSN_Tests.TC_pdp46_clients_interact fail'. Tue Jan 17 04:18:51 UTC 2023 [1;31m------ GGSN_Tests.TC_pdp46_clients_interact fail ------[0m Saving talloc report from 172.18.244.200:4260 to GGSN_Tests.TC_pdp46_clients_interact.talloc ERROR: failed to get talloc report via vty Waiting for packet dumper to finish... 0 (prev_count=-1, count=8840) Waiting for packet dumper to finish... 1 (prev_count=8840, count=8904) MTC@daebdff8a770: External command `../ttcn3-tcpdump-stop.sh GGSN_Tests.TC_pdp46_clients_interact fail' was executed successfully (exit status: 0). MTC@daebdff8a770: Starting external command `../ttcn3-tcpdump-start.sh GGSN_Tests.TC_pdp46_act_deact_apn4'. ------ GGSN_Tests.TC_pdp46_act_deact_apn4 ------ Tue Jan 17 04:18:58 UTC 2023 NOTE: unable to use dumpcap due to missing capabilities or suid bit Waiting for packet dumper to start... 0 MTC@daebdff8a770: External command `../ttcn3-tcpdump-start.sh GGSN_Tests.TC_pdp46_act_deact_apn4' was executed successfully (exit status: 0). MTC@daebdff8a770: Test case TC_pdp46_act_deact_apn4 started. MTC@daebdff8a770: GTP1C ConnectionID: 1 MTC@daebdff8a770: sending CreatePDP MTC@daebdff8a770: setverdict(pass): none -> pass MTC@daebdff8a770: sending CreatePDP MTC@daebdff8a770: setverdict(pass): pass -> pass, component reason not changed MTC@daebdff8a770: sending CreatePDP MTC@daebdff8a770: setverdict(pass): pass -> pass, component reason not changed MTC@daebdff8a770: setverdict(pass): pass -> pass, component reason not changed MTC@daebdff8a770: setverdict(pass): pass -> pass, component reason not changed MTC@daebdff8a770: Setting final verdict of the test case. MTC@daebdff8a770: Local verdict of MTC: pass MTC@daebdff8a770: No PTCs were created. MTC@daebdff8a770: Test case TC_pdp46_act_deact_apn4 finished. Verdict: pass MTC@daebdff8a770: Starting external command `../ttcn3-tcpdump-stop.sh GGSN_Tests.TC_pdp46_act_deact_apn4 pass'. Tue Jan 17 04:19:14 UTC 2023 [1;32m====== GGSN_Tests.TC_pdp46_act_deact_apn4 pass ======[0m Saving talloc report from 172.18.244.200:4260 to GGSN_Tests.TC_pdp46_act_deact_apn4.talloc ERROR: failed to get talloc report via vty Waiting for packet dumper to finish... 0 (prev_count=-1, count=16776) Waiting for packet dumper to finish... 1 (prev_count=16776, count=16840) MTC@daebdff8a770: External command `../ttcn3-tcpdump-stop.sh GGSN_Tests.TC_pdp46_act_deact_apn4 pass' was executed successfully (exit status: 0). MTC@daebdff8a770: Starting external command `../ttcn3-tcpdump-start.sh GGSN_Tests.TC_echo_req_resp'. ------ GGSN_Tests.TC_echo_req_resp ------ Tue Jan 17 04:19:21 UTC 2023 NOTE: unable to use dumpcap due to missing capabilities or suid bit Waiting for packet dumper to start... 0 MTC@daebdff8a770: External command `../ttcn3-tcpdump-start.sh GGSN_Tests.TC_echo_req_resp' was executed successfully (exit status: 0). MTC@daebdff8a770: Test case TC_echo_req_resp started. MTC@daebdff8a770: GTP1C ConnectionID: 1 MTC@daebdff8a770: setverdict(pass): none -> pass MTC@daebdff8a770: setverdict(pass): pass -> pass, component reason not changed MTC@daebdff8a770: Setting final verdict of the test case. MTC@daebdff8a770: Local verdict of MTC: pass MTC@daebdff8a770: No PTCs were created. MTC@daebdff8a770: Test case TC_echo_req_resp finished. Verdict: pass MTC@daebdff8a770: Starting external command `../ttcn3-tcpdump-stop.sh GGSN_Tests.TC_echo_req_resp pass'. Tue Jan 17 04:19:37 UTC 2023 [1;32m====== GGSN_Tests.TC_echo_req_resp pass ======[0m Saving talloc report from 172.18.244.200:4260 to GGSN_Tests.TC_echo_req_resp.talloc ERROR: failed to get talloc report via vty Waiting for packet dumper to finish... 0 (prev_count=-1, count=6632) Waiting for packet dumper to finish... 1 (prev_count=6632, count=6696) MTC@daebdff8a770: External command `../ttcn3-tcpdump-stop.sh GGSN_Tests.TC_echo_req_resp pass' was executed successfully (exit status: 0). MTC@daebdff8a770: Starting external command `../ttcn3-tcpdump-start.sh GGSN_Tests.TC_echo_req_resp_gtpu'. ------ GGSN_Tests.TC_echo_req_resp_gtpu ------ Tue Jan 17 04:19:44 UTC 2023 NOTE: unable to use dumpcap due to missing capabilities or suid bit Waiting for packet dumper to start... 0 MTC@daebdff8a770: External command `../ttcn3-tcpdump-start.sh GGSN_Tests.TC_echo_req_resp_gtpu' was executed successfully (exit status: 0). MTC@daebdff8a770: Test case TC_echo_req_resp_gtpu started. MTC@daebdff8a770: GTP1C ConnectionID: 1 MTC@daebdff8a770: setverdict(pass): none -> pass MTC@daebdff8a770: setverdict(pass): pass -> pass, component reason not changed MTC@daebdff8a770: Setting final verdict of the test case. MTC@daebdff8a770: Local verdict of MTC: pass MTC@daebdff8a770: No PTCs were created. MTC@daebdff8a770: Test case TC_echo_req_resp_gtpu finished. Verdict: pass MTC@daebdff8a770: Starting external command `../ttcn3-tcpdump-stop.sh GGSN_Tests.TC_echo_req_resp_gtpu pass'. Tue Jan 17 04:20:00 UTC 2023 [1;32m====== GGSN_Tests.TC_echo_req_resp_gtpu pass ======[0m Saving talloc report from 172.18.244.200:4260 to GGSN_Tests.TC_echo_req_resp_gtpu.talloc + 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 + grep Name + cut -d : -f2 + awk -F" NR>1{print $2} + xargs -rn1 docker kill jenkins-ttcn3-ggsn-test-kernel-695-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/695/artifact…> + fix_perms + set +x Fixing permissions + docker run --rm -v <https://jenkins.osmocom.org/jenkins/job/ttcn3-ggsn-test-kernel/ws/logs>:/data -v <https://jenkins.osmocom.org/jenkins/job/ttcn3-ggsn-test-kernel/ws/_cache>:/cache --name jenkins-ttcn3-ggsn-test-kernel-695-cleaner debian:bullseye chmod -R a+rX /data/ /cache/ + collect_logs + cat <https://jenkins.osmocom.org/jenkins/job/ttcn3-ggsn-test-kernel/695/artifact…> Build step 'Execute shell' marked build as failure Recording test results [Checks API] No suitable checks publisher found.

1 0

Build failed in Jenkins: TTCN3-centos-ggsn-test #971
by jenkins＠lists.osmocom.org 17 Jan '23

17 Jan '23

See <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-ggsn-test/971/display/…> Changes: [Oliver Smith] ttcn3-*/jenkins*.sh: use $SUBNET everywhere [Oliver Smith] network_create: find free subnet automatically ------------------------------------------ [...truncated 41.85 KB...] + [ -z ] + docker_upstream_distro_from_image_name ttcn3-ggsn-test + echo debian:bullseye + upstream_distro_arg=debian:bullseye + docker_dir_from_image_name ttcn3-ggsn-test + echo ttcn3-ggsn-test + dir=ttcn3-ggsn-test + pull_arg=--pull + grep ^FROM ../ttcn3-ggsn-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-ggsn-test (export NO_DOCKER_IMAGE_BUILD=1 to prevent this) + make -C ../ttcn3-ggsn-test BUILD_ARGS= UPSTREAM_DISTRO=debian:bullseye DISTRO=debian-bullseye IMAGE=osmocom-build/ttcn3-ggsn-test make: Entering directory '<https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-ggsn-test/ws/ttcn3-ggs…'> 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-ggsn-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-ggsn-test:latest . Sending build context to Docker daemon 82.94kB 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" ggsn_tests ---> Using cache ---> 79e6a863a572 Step 7/9 : VOLUME /data ---> Using cache ---> 6f3eaf610018 Step 8/9 : COPY GGSN_Tests.cfg /data/GGSN_Tests.cfg ---> Using cache ---> 0003d99458f2 Step 9/9 : CMD ttcn3-docker-run ggsn_tests GGSN_Tests ---> Using cache ---> 1ded3b2d7fb3 [Warning] One or more build-args [OSMO_HLR_BRANCH OSMO_SIP_BRANCH OPEN5GS_BRANCH OSMO_BTS_BRANCH OSMO_BSC_BRANCH OSMOCOM_REPO_PATH OSMOCOM_REPO_VERSION OSMO_BB_BRANCH OSMO_GSM_TESTER_BRANCH OSMO_SGSN_BRANCH LIBOSMOCORE_BRANCH OSMO_HNBGW_BRANCH OSMO_IUH_BRANCH OSMO_MGW_BRANCH OSMO_MSC_BRANCH UID OSMOCOM_REPO_TESTSUITE_MIRROR OSMO_NITB_BRANCH OSMO_UECUPS_BRANCH DISTRO OSMO_GBPROXY_BRANCH OSMO_GGSN_BRANCH OSMO_PCU_BRANCH OSMO_CBC_BRANCH OSMO_HNODEB_BRANCH OSMO_STP_BRANCH UPSTREAM_DISTRO OSMOCOM_REPO_MIRROR] were not consumed Successfully built 1ded3b2d7fb3 Successfully tagged osmocom-build/ttcn3-ggsn-test:latest rm -rf .common make: Leaving directory '<https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-ggsn-test/ws/ttcn3-ggs…'> + docker_image_exists ttcn3-ggsn-test + docker images -q osmocom-build/ttcn3-ggsn-test + test -n 1ded3b2d7fb3 + list_osmo_packages debian-bullseye ttcn3-ggsn-test + local distro=debian-bullseye + local image=ttcn3-ggsn-test + local docker_run_sh=docker run --rm --entrypoint=/bin/sh osmocom-build/ttcn3-ggsn-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-ggsn-test/971/artifact…> + cp GGSN_Tests.cfg <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-ggsn-test/971/artifact…> + write_mp_osmo_repo <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-ggsn-test/971/artifact…> + local repo=nightly + local config=<https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-ggsn-test/971/artifact…> + local line + [ -e <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-ggsn-test/971/artifact…> ] + 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-ggsn-test/971/artifact…> + mkdir <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-ggsn-test/971/artifact…> + network_create + docker network ls + grep -q ttcn3-ggsn-test + SUBNET=801493 + seq 1 30 + echo (801493 + 1) % 256 + bc + SUBNET=214 + SUB4=172.18.214.0/24 + SUB6=fd02:db8:214::/64 + set +x Creating network ttcn3-ggsn-test, trying SUBNET=214... + docker network create --internal --subnet 172.18.214.0/24 --ipv6 --subnet fd02:db8:214::/64 ttcn3-ggsn-test e0ba3318c0f71add14c328fcedbef396e3df000656ff6d87231c6a005ee9f23e + set +x ### Network ttcn3-ggsn-test created (SUBNET=214) ### + return + GGSN_CMD=osmo-ggsn -c /data/osmo-ggsn.cfg + GGSN_DOCKER_ARGS= + [ 0 = 1 ] + cp osmo-ggsn.cfg <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-ggsn-test/971/artifact…> + network_replace_subnet_in_configs + set +x Applying SUBNET=214 to: <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-ggsn-test/971/artifact…> Applying SUBNET=214 to: <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-ggsn-test/971/artifact…> + docker_network_params 214 201 + NET=214 + ADDR_SUFIX=201 + echo --network ttcn3-ggsn-test --ip 172.18.214.201 --ip6 fd02:db8:214::201 + GGSN_DOCKER_ARGS= --network ttcn3-ggsn-test --ip 172.18.214.201 --ip6 fd02:db8:214::201 + OSMO_SUT_HOST=172.18.214.201 + docker run --rm --cap-add=NET_ADMIN --device /dev/net/tun:/dev/net/tun --sysctl net.ipv6.conf.all.disable_ipv6=0 --ulimit core=-1 -v <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-ggsn-test/971/artifact…>:/data --name jenkins-TTCN3-centos-ggsn-test-971-ggsn -d --network ttcn3-ggsn-test --ip 172.18.214.201 --ip6 fd02:db8:214::201 osmocom-build/osmo-ggsn-master-centos8 /bin/sh -c osmo-ggsn -c /data/osmo-ggsn.cfg >/data/osmo-ggsn.log 2>&1 53d25146235c3f850a766f6f3c55d2ac365476e5a0b74acdb116d0800fbf006c + kernel_test_wait_for_vm <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-ggsn-test/971/artifact…> + local log=<https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-ggsn-test/971/artifact…> + local i + [ 0 != 1 ] + return + docker_network_params 214 202 + NET=214 + ADDR_SUFIX=202 + echo --network ttcn3-ggsn-test --ip 172.18.214.202 --ip6 fd02:db8:214::202 + docker run --rm --sysctl net.ipv6.conf.all.disable_ipv6=0 --network ttcn3-ggsn-test --ip 172.18.214.202 --ip6 fd02:db8:214::202 --ulimit core=-1 -v <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-ggsn-test/971/artifact…>:/data -e TTCN3_PCAP_PATH=/data -e OSMO_SUT_HOST=172.18.214.201 -e OSMO_SUT_PORT=4260 --name jenkins-TTCN3-centos-ggsn-test-971-ggsn-test osmocom-build/ttcn3-ggsn-test + SUBDIR=ggsn_tests + SUITE=GGSN_Tests + '[' -n '' ']' + cd /data + /osmo-ttcn3-hacks/start-testsuite.sh /osmo-ttcn3-hacks/ggsn_tests/GGSN_Tests ttcn3_start: Starting the test suite ttcn3_start: warning: TTCN3_DIR environment variable is not set spawn mctr_cli GGSN_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: GGSN_Tests.cfg MC@80664ae6d54f: Unix server socket created successfully. MC@80664ae6d54f: Listening on TCP port 41803. MC2> 80664ae6d54f is the default spawn /osmo-ttcn3-hacks/ggsn_tests/GGSN_Tests 80664ae6d54f 41803 TTCN-3 Host Controller (parallel mode), version 8.0.0 MC@80664ae6d54f: New HC connected from 80664ae6d54f [172.18.214.202]. 80664ae6d54f: Linux 5.10.0-20-amd64 on x86_64. cmtc MC@80664ae6d54f: Downloading configuration file to all HCs. construct junitlogger Initializing `JUnitLogger' (v2.0): JUnitLogger writes JUnit-compatible XML MC@80664ae6d54f: Configuration file was processed on all HCs. MC@80664ae6d54f: Creating MTC on host 80664ae6d54f. MC@80664ae6d54f: MTC is created. MC2> smtc Executing all items of [EXECUTE] section. MC2> MTC@80664ae6d54f: Starting external command `../ttcn3-tcpdump-start.sh GGSN_Tests.TC_pdp4_act_deact'. ------ GGSN_Tests.TC_pdp4_act_deact ------ Tue Jan 17 04:00:37 UTC 2023 NOTE: unable to use dumpcap due to missing capabilities or suid bit Waiting for packet dumper to start... 0 MTC@80664ae6d54f: External command `../ttcn3-tcpdump-start.sh GGSN_Tests.TC_pdp4_act_deact' was executed successfully (exit status: 0). MTC@80664ae6d54f: Test case TC_pdp4_act_deact started. MTC@80664ae6d54f: GTP1C ConnectionID: 1 MTC@80664ae6d54f: sending CreatePDP MTC@80664ae6d54f: setverdict(pass): none -> pass MTC@80664ae6d54f: setverdict(pass): pass -> pass, component reason not changed MTC@80664ae6d54f: setverdict(pass): pass -> pass, component reason not changed MTC@80664ae6d54f: Setting final verdict of the test case. MTC@80664ae6d54f: Local verdict of MTC: pass MTC@80664ae6d54f: No PTCs were created. MTC@80664ae6d54f: Test case TC_pdp4_act_deact finished. Verdict: pass MTC@80664ae6d54f: Starting external command `../ttcn3-tcpdump-stop.sh GGSN_Tests.TC_pdp4_act_deact pass'. Tue Jan 17 04:00:53 UTC 2023 [1;32m====== GGSN_Tests.TC_pdp4_act_deact pass ======[0m Saving talloc report from 172.18.214.201:4260 to GGSN_Tests.TC_pdp4_act_deact.talloc Waiting for packet dumper to finish... 0 (prev_count=-1, count=22821) Waiting for packet dumper to finish... 1 (prev_count=22821, count=27017) MTC@80664ae6d54f: External command `../ttcn3-tcpdump-stop.sh GGSN_Tests.TC_pdp4_act_deact pass' was executed successfully (exit status: 0). MTC@80664ae6d54f: Starting external command `../ttcn3-tcpdump-start.sh GGSN_Tests.TC_pdp4_act_deact_ipcp'. ------ GGSN_Tests.TC_pdp4_act_deact_ipcp ------ Tue Jan 17 04:00:55 UTC 2023 NOTE: unable to use dumpcap due to missing capabilities or suid bit Waiting for packet dumper to start... 0 + 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 + grep Name + cut -d : -f2 + awk -F" NR>1{print $2} + xargs -rn1 docker kill Error: No such network: ttcn3-ggsn-test + 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-centos-ggsn-test/971/artifact…> + fix_perms + set +x Fixing permissions + docker run --rm -v <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-ggsn-test/ws/logs>:/data -v <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-ggsn-test/ws/_cache>:/cache --name jenkins-TTCN3-centos-ggsn-test-971-cleaner debian:bullseye chmod -R a+rX /data/ /cache/ + collect_logs + cat <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-ggsn-test/971/artifact…> Build step 'Execute shell' marked build as failure Recording test results [Checks API] No suitable checks publisher found.

1 0

Jenkins build became unstable: nplab-sua-test #1994
by jenkins＠lists.osmocom.org 17 Jan '23

17 Jan '23

See <https://jenkins.osmocom.org/jenkins/job/nplab-sua-test/1994/display/redirec…>

1 0

Jenkins build became unstable: nplab-sua-test-latest #1554
by jenkins＠lists.osmocom.org 17 Jan '23

17 Jan '23

See <https://jenkins.osmocom.org/jenkins/job/nplab-sua-test-latest/1554/display/…>

1 0

Jenkins build became unstable: nplab-m3ua-test #2025
by jenkins＠lists.osmocom.org 17 Jan '23

17 Jan '23

See <https://jenkins.osmocom.org/jenkins/job/nplab-m3ua-test/2025/display/redire…>

1 0

Jenkins build became unstable: nplab-m3ua-test-latest #1561
by jenkins＠lists.osmocom.org 17 Jan '23

17 Jan '23

See <https://jenkins.osmocom.org/jenkins/job/nplab-m3ua-test-latest/1561/display…>

1 0

2026

2025

2024

2023

2022

2021

2020

2019

jenkins-notifications