See <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-bsc-test/1355/display/…>
Changes:
------------------------------------------
[...truncated 4.11 MB...]
VirtMGW-MGCP-0(2882)@c70eb02a91ac: Final verdict of PTC: none
MTC@c70eb02a91ac: Setting final verdict of the test case.
MTC@c70eb02a91ac: Local verdict of MTC: pass
MTC@c70eb02a91ac: Local verdict of PTC VirtMSC-STATS(2874): none (pass -> pass)
MTC@c70eb02a91ac: Local verdict of PTC VirtMSC-SCCP(2875): none (pass -> pass)
MTC@c70eb02a91ac: Local verdict of PTC VirtMSC-RAN(2876): none (pass -> pass)
MTC@c70eb02a91ac: Local verdict of PTC VirtMSC-M3UA(2877): none (pass -> pass)
MTC@c70eb02a91ac: Local verdict of PTC VirtSMLC-SCCP(2878): none (pass -> pass)
MTC@c70eb02a91ac: Local verdict of PTC VirtSMLC-BSSAP_LE(2879): none (pass -> pass)
MTC@c70eb02a91ac: Local verdict of PTC VirtSMLC-M3UA(2880): none (pass -> pass)
MTC@c70eb02a91ac: Local verdict of PTC IPA-CTRL-CLI-IPA(2881): none (pass -> pass)
MTC@c70eb02a91ac: Local verdict of PTC VirtMGW-MGCP-0(2882): none (pass -> pass)
MTC@c70eb02a91ac: Local verdict of PTC IPA-BTS0-TRX0-RSL-IPA(2883): none (pass -> pass)
MTC@c70eb02a91ac: Local verdict of PTC IPA-BTS0-TRX0-RSL-RSL(2884): none (pass -> pass)
MTC@c70eb02a91ac: Local verdict of PTC TC_lcs_loc_req_for_active_ms_le_timeout(2885): pass (pass -> pass)
MTC@c70eb02a91ac: Test case TC_lcs_loc_req_for_active_ms_le_timeout finished. Verdict: pass
MTC@c70eb02a91ac: Starting external command `../ttcn3-tcpdump-stop.sh BSC_Tests.TC_lcs_loc_req_for_active_ms_le_timeout pass'.
Wed Jan 31 12:34:08 UTC 2024
[1;32m====== BSC_Tests.TC_lcs_loc_req_for_active_ms_le_timeout pass ======[0m
Saving talloc report from 172.18.213.20:4242 to BSC_Tests.TC_lcs_loc_req_for_active_ms_le_timeout.talloc
Waiting for packet dumper to finish... 0 (prev_count=-1, count=370618)
Waiting for packet dumper to finish... 1 (prev_count=370618, count=566263)
MTC@c70eb02a91ac: External command `../ttcn3-tcpdump-stop.sh BSC_Tests.TC_lcs_loc_req_for_active_ms_le_timeout pass' was executed successfully (exit status: 0).
MTC@c70eb02a91ac: Starting external command `../ttcn3-tcpdump-start.sh BSC_Tests.TC_lcs_loc_req_for_active_ms_le_timeout2'.
------ BSC_Tests.TC_lcs_loc_req_for_active_ms_le_timeout2 ------
Wed Jan 31 12:34:13 UTC 2024
NOTE: unable to use dumpcap due to missing capabilities or suid bit
Waiting for packet dumper to start... 0
MTC@c70eb02a91ac: External command `../ttcn3-tcpdump-start.sh BSC_Tests.TC_lcs_loc_req_for_active_ms_le_timeout2' was executed successfully (exit status: 0).
MTC@c70eb02a91ac: Test case TC_lcs_loc_req_for_active_ms_le_timeout2 started.
MTC@c70eb02a91ac: "TC_lcs_loc_req_for_active_ms_le_timeout2": using IMSI '001018956781284'H
MTC@c70eb02a91ac: Connecting BSSAP RAN_Emulation to SCCP_SP_PORT
MTC@c70eb02a91ac: Starting RAN_Emulation
VirtMSC-M3UA(2889)@c70eb02a91ac: *************************************************
VirtMSC-M3UA(2889)@c70eb02a91ac: M3UA emulation initiated, the test can be started
VirtMSC-M3UA(2889)@c70eb02a91ac: *************************************************
VirtMSC-SCCP(2887)@c70eb02a91ac: v_sccp_pdu_maxlen:268
MTC@c70eb02a91ac: Connecting BSSAP_LE_Emulation to SCCP_SP_PORT
MTC@c70eb02a91ac: Starting BSSAP_LE_Emulation
VirtSMLC-M3UA(2892)@c70eb02a91ac: *************************************************
VirtSMLC-M3UA(2892)@c70eb02a91ac: M3UA emulation initiated, the test can be started
VirtSMLC-M3UA(2892)@c70eb02a91ac: *************************************************
VirtSMLC-SCCP(2890)@c70eb02a91ac: v_sccp_pdu_maxlen:268
VirtMSC-M3UA(2889)@c70eb02a91ac: SCTP_ConnectResult -> connection established from: "172.18.213.203":23905 to server: "172.18.213.200":2905 association #8
VirtSMLC-M3UA(2892)@c70eb02a91ac: SCTP_ConnectResult -> connection established from: "172.18.213.203":23908 to server: "172.18.213.200":2905 association #8
VirtMSC-RAN(2888)@c70eb02a91ac: BSSMAP: Responding to inbound RESET with RESET-ACK
VirtMSC-RAN(2888)@c70eb02a91ac: BSSMAP: Received RESET-ACK in response to RESET, we're ready to go!
VirtSMLC-BSSAP_LE(2891)@c70eb02a91ac: BSSMAP-LE: Received RESET-ACK in response to RESET, we're ready to go!
IPA-BTS0-TRX0-RSL-IPA(2895)@c70eb02a91ac: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit }
IPA-BTS0-TRX0-RSL-IPA(2895)@c70eb02a91ac: CCM Rx:{ msg_type := IPAC_MSGT_ID_GET (4), u := { get := { { len := 1, tag := IPAC_IDTAG_UNITID (8) }, { len := 1, tag := IPAC_IDTAG_MACADDR (7) }, { len := 1, tag := IPAC_IDTAG_LOCATION1 (2) }, { len := 1, tag := IPAC_IDTAG_LOCATION2 (3) }, { len := 1, tag := IPAC_IDTAG_EQUIPVERS (4) }, { len := 1, tag := IPAC_IDTAG_SWVERSION (5) }, { len := 1, tag := IPAC_IDTAG_UNITNAME (1) }, { len := 1, tag := IPAC_IDTAG_SERNR (0) } } } }
IPA-BTS0-TRX0-RSL-IPA(2895)@c70eb02a91ac: CCM Tx:{ msg_type := IPAC_MSGT_ID_RESP (5), u := { resp := { { len := 0, tag := IPAC_IDTAG_UNITID (8), data := '313233342F302F3000'O }, { len := 0, tag := IPAC_IDTAG_MACADDR (7), data := '00'O }, { len := 0, tag := IPAC_IDTAG_LOCATION1 (2), data := '00'O }, { len := 0, tag := IPAC_IDTAG_LOCATION2 (3), data := '00'O }, { len := 0, tag := IPAC_IDTAG_EQUIPVERS (4), data := '00'O }, { len := 0, tag := IPAC_IDTAG_SWVERSION (5), data := '00'O }, { len := 0, tag := IPAC_IDTAG_UNITNAME (1), data := '4F736D6F636F6D205454434E2D33204254532053696D756C61746F7200'O }, { len := 0, tag := IPAC_IDTAG_SERNR (0), data := '00'O } } } }
IPA-BTS0-TRX0-RSL-IPA(2895)@c70eb02a91ac: CCM Rx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit }
MTC@c70eb02a91ac: "TC_lcs_loc_req_for_active_ms_le_timeout2() start"
VirtMSC-RAN(2888)@c70eb02a91ac: f_create_expect(l3 := '05240103505902080910109865872148'O, n_connectPointCode := omit
VirtMSC-RAN(2888)@c70eb02a91ac: Created Expect[0] for '05240103505902080910109865872148'O to be handled at TC_lcs_loc_req_for_active_ms_le_timeout2(2897)
VirtMSC-SCCP(2887)@c70eb02a91ac: First idle individual index:0
VirtMSC-SCCP(2887)@c70eb02a91ac: Warning: Stopping inactive timer T_ias[0].
VirtMSC-RAN(2888)@c70eb02a91ac: ExpectedCreateCallback completeLayer3Information
VirtMSC-RAN(2888)@c70eb02a91ac: Found Expect[0] for '05240103505902080910109865872148'O handled at TC_lcs_loc_req_for_active_ms_le_timeout2(2897)
VirtMSC-RAN(2888)@c70eb02a91ac: Added conn table entry 0TC_lcs_loc_req_for_active_ms_le_timeout2(2897)14126383
VirtMSC-SCCP(2887)@c70eb02a91ac: Session index based on connection ID:0
VirtMSC-SCCP(2887)@c70eb02a91ac: Warning: Re-starting timer T_ias[0], which is already active (running or expired).
VirtSMLC-BSSAP_LE(2891)@c70eb02a91ac: Created IMSI[0] for '001018956781284'H'FFFFFFFF'O to be handled at TC_lcs_loc_req_for_active_ms_le_timeout2(2897)
VirtMSC-SCCP(2887)@c70eb02a91ac: Session index based on connection ID:0
VirtMSC-SCCP(2887)@c70eb02a91ac: vl_len:38
VirtMSC-SCCP(2887)@c70eb02a91ac: vl_from0
VirtMSC-SCCP(2887)@c70eb02a91ac: data sent by MTP3_SCCP_PORT: '00242B44010005080062F2240017002A4801204301003E040092000008080910109865872148'O
VirtSMLC-SCCP(2890)@c70eb02a91ac: First idle individual index:0
VirtSMLC-SCCP(2890)@c70eb02a91ac: Warning: Stopping inactive timer T_ias[0].
VirtSMLC-BSSAP_LE(2891)@c70eb02a91ac: ExpectedCreateCallback: Found ConnHdlr TC_lcs_loc_req_for_active_ms_le_timeout2(2897) for IMSI '001018956781284'H
VirtSMLC-BSSAP_LE(2891)@c70eb02a91ac: Added conn table entry 0TC_lcs_loc_req_for_active_ms_le_timeout2(2897)6043494
VirtSMLC-SCCP(2890)@c70eb02a91ac: Session index based on connection ID:0
VirtSMLC-SCCP(2890)@c70eb02a91ac: Warning: Re-starting timer T_ias[0], which is already active (running or expired).
VirtSMLC-SCCP(2890)@c70eb02a91ac: Session index based on connection ID:0
VirtSMLC-SCCP(2890)@c70eb02a91ac: vl_len:8
VirtSMLC-SCCP(2890)@c70eb02a91ac: vl_from0
VirtSMLC-SCCP(2890)@c70eb02a91ac: data sent by MTP3_SCCP_PORT: '00062A4900020101'O
VirtSMLC-SCCP(2890)@c70eb02a91ac: Session index based on local reference:0
VirtSMLC-SCCP(2890)@c70eb02a91ac: DT1 will be put to the reassembly buffer
VirtSMLC-SCCP(2890)@c70eb02a91ac: DT1 data has been put to the reassembly buffer
VirtSMLC-SCCP(2890)@c70eb02a91ac: DT1/segmentingReassembl/more==0 received=> send ASP_SCCP_N_DATA comes
TC_lcs_loc_req_for_active_ms_le_timeout2(2897)@c70eb02a91ac: setverdict(pass): none -> pass
VirtMSC-SCCP(2887)@c70eb02a91ac: Session index based on local reference:0
VirtMSC-SCCP(2887)@c70eb02a91ac: DT1 will be put to the reassembly buffer
VirtMSC-SCCP(2887)@c70eb02a91ac: DT1 data has been put to the reassembly buffer
VirtMSC-SCCP(2887)@c70eb02a91ac: DT1/segmentingReassembl/more==0 received=> send ASP_SCCP_N_DATA comes
VirtSMLC-SCCP(2890)@c70eb02a91ac: Session index based on local reference:0
VirtSMLC-SCCP(2890)@c70eb02a91ac: DT1 will be put to the reassembly buffer
VirtSMLC-SCCP(2890)@c70eb02a91ac: DT1 data has been put to the reassembly buffer
VirtSMLC-SCCP(2890)@c70eb02a91ac: DT1/segmentingReassembl/more==0 received=> send ASP_SCCP_N_DATA comes
VirtSMLC-SCCP(2890)@c70eb02a91ac: Session index based on local reference:0
VirtSMLC-BSSAP_LE(2891)@c70eb02a91ac: Deleted conn table entry 0TC_lcs_loc_req_for_active_ms_le_timeout2(2897)6043494
TC_lcs_loc_req_for_active_ms_le_timeout2(2897)@c70eb02a91ac: "f_verify_active_A_conn_and_clear: test A link, then clear"
VirtMSC-SCCP(2887)@c70eb02a91ac: Session index based on connection ID:0
VirtMSC-SCCP(2887)@c70eb02a91ac: vl_len:38
VirtMSC-SCCP(2887)@c70eb02a91ac: vl_from0
VirtMSC-SCCP(2887)@c70eb02a91ac: data sent by MTP3_SCCP_PORT: '00242B44010005080062F2240017002A4801204301003E040092000008080910109865872148'O
VirtSMLC-SCCP(2890)@c70eb02a91ac: First idle individual index:0
VirtSMLC-SCCP(2890)@c70eb02a91ac: Warning: Re-starting timer T_guard[0], which is already active (running or expired).
VirtSMLC-SCCP(2890)@c70eb02a91ac: Warning: Stopping inactive timer T_ias[0].
VirtSMLC-BSSAP_LE(2891)@c70eb02a91ac: ExpectedCreateCallback: Found ConnHdlr TC_lcs_loc_req_for_active_ms_le_timeout2(2897) for IMSI '001018956781284'H
VirtSMLC-BSSAP_LE(2891)@c70eb02a91ac: Added conn table entry 0TC_lcs_loc_req_for_active_ms_le_timeout2(2897)4501989
VirtSMLC-SCCP(2890)@c70eb02a91ac: Session index based on connection ID:0
VirtSMLC-SCCP(2890)@c70eb02a91ac: Warning: Re-starting timer T_ias[0], which is already active (running or expired).
VirtMSC-SCCP(2887)@c70eb02a91ac: Session index based on connection ID:0
VirtMSC-SCCP(2887)@c70eb02a91ac: vl_len:6
VirtMSC-SCCP(2887)@c70eb02a91ac: vl_from0
VirtMSC-SCCP(2887)@c70eb02a91ac: data sent by MTP3_SCCP_PORT: '000420040100'O
VirtMSC-SCCP(2887)@c70eb02a91ac: Session index based on local reference:0
VirtMSC-SCCP(2887)@c70eb02a91ac: DT1 will be put to the reassembly buffer
VirtMSC-SCCP(2887)@c70eb02a91ac: DT1 data has been put to the reassembly buffer
VirtMSC-SCCP(2887)@c70eb02a91ac: DT1/segmentingReassembl/more==0 received=> send ASP_SCCP_N_DATA comes
VirtSMLC-SCCP(2890)@c70eb02a91ac: Session index based on local reference:0
VirtSMLC-SCCP(2890)@c70eb02a91ac: DT1 will be put to the reassembly buffer
VirtSMLC-SCCP(2890)@c70eb02a91ac: DT1 data has been put to the reassembly buffer
VirtSMLC-SCCP(2890)@c70eb02a91ac: DT1/segmentingReassembl/more==0 received=> send ASP_SCCP_N_DATA comes
VirtMSC-RAN(2888)@c70eb02a91ac: Deleted conn table entry 0TC_lcs_loc_req_for_active_ms_le_timeout2(2897)14126383
VirtMSC-SCCP(2887)@c70eb02a91ac: Session index based on connection ID:0
VirtMSC-SCCP(2887)@c70eb02a91ac: Session index based on local reference:0
VirtMSC-SCCP(2887)@c70eb02a91ac: RLC received in state:disconnect_pending
VirtSMLC-SCCP(2890)@c70eb02a91ac: Session index based on local reference:0
VirtSMLC-BSSAP_LE(2891)@c70eb02a91ac: Deleted conn table entry 0TC_lcs_loc_req_for_active_ms_le_timeout2(2897)4501989
TC_lcs_loc_req_for_active_ms_le_timeout2(2897)@c70eb02a91ac: setverdict(pass): pass -> pass, component reason not changed
TC_lcs_loc_req_for_active_ms_le_timeout2(2897)@c70eb02a91ac: setverdict(pass): pass -> pass, component reason not changed
TC_lcs_loc_req_for_active_ms_le_timeout2(2897)@c70eb02a91ac: Final verdict of PTC: pass
MTC@c70eb02a91ac: talloc reports "struct bsc_subscr" x 0, expecting 0
MTC@c70eb02a91ac: talloc reports "struct gsm_subscriber_connection" x 0, expecting 0
VirtMSC-RAN(2888)@c70eb02a91ac: Final verdict of PTC: none
VirtMSC-SCCP(2887)@c70eb02a91ac: Final verdict of PTC: none
VirtMSC-M3UA(2889)@c70eb02a91ac: Final verdict of PTC: none
IPA-CTRL-CLI-IPA(2893)@c70eb02a91ac: Final verdict of PTC: none
VirtMSC-STATS(2886)@c70eb02a91ac: Final verdict of PTC: none
MTC@c70eb02a91ac: setverdict(pass): none -> pass
VirtSMLC-BSSAP_LE(2891)@c70eb02a91ac: Final verdict of PTC: none
VirtSMLC-M3UA(2892)@c70eb02a91ac: Final verdict of PTC: none
VirtSMLC-SCCP(2890)@c70eb02a91ac: Final verdict of PTC: none
IPA-BTS0-TRX0-RSL-IPA(2895)@c70eb02a91ac: Final verdict of PTC: none
IPA-BTS0-TRX0-RSL-RSL(2896)@c70eb02a91ac: Final verdict of PTC: none
VirtMGW-MGCP-0(2894)@c70eb02a91ac: Final verdict of PTC: none
MTC@c70eb02a91ac: Setting final verdict of the test case.
MTC@c70eb02a91ac: Local verdict of MTC: pass
MTC@c70eb02a91ac: Local verdict of PTC VirtMSC-STATS(2886): none (pass -> pass)
MTC@c70eb02a91ac: Local verdict of PTC VirtMSC-SCCP(2887): none (pass -> pass)
MTC@c70eb02a91ac: Local verdict of PTC VirtMSC-RAN(2888): none (pass -> pass)
MTC@c70eb02a91ac: Local verdict of PTC VirtMSC-M3UA(2889): none (pass -> pass)
MTC@c70eb02a91ac: Local verdict of PTC VirtSMLC-SCCP(2890): none (pass -> pass)
MTC@c70eb02a91ac: Local verdict of PTC VirtSMLC-BSSAP_LE(2891): none (pass -> pass)
MTC@c70eb02a91ac: Local verdict of PTC VirtSMLC-M3UA(2892): none (pass -> pass)
MTC@c70eb02a91ac: Local verdict of PTC IPA-CTRL-CLI-IPA(2893): none (pass -> pass)
MTC@c70eb02a91ac: Local verdict of PTC VirtMGW-MGCP-0(2894): none (pass -> pass)
MTC@c70eb02a91ac: Local verdict of PTC IPA-BTS0-TRX0-RSL-IPA(2895): none (pass -> pass)
MTC@c70eb02a91ac: Local verdict of PTC IPA-BTS0-TRX0-RSL-RSL(2896): none (pass -> pass)
MTC@c70eb02a91ac: Local verdict of PTC TC_lcs_loc_req_for_active_ms_le_timeout2(2897): pass (pass -> pass)
MTC@c70eb02a91ac: Test case TC_lcs_loc_req_for_active_ms_le_timeout2 finished. Verdict: pass
MTC@c70eb02a91ac: Starting external command `../ttcn3-tcpdump-stop.sh BSC_Tests.TC_lcs_loc_req_for_active_ms_le_timeout2 pass'.
Wed Jan 31 12:34:31 UTC 2024
[1;32m====== BSC_Tests.TC_lcs_loc_req_for_active_ms_le_timeout2 pass ======[0m
Saving talloc report from 172.18.213.20:4242 to BSC_Tests.TC_lcs_loc_req_for_active_ms_le_timeout2.talloc
Waiting for packet dumper to finish... 0 (prev_count=-1, count=380104)
Waiting for packet dumper to finish... 1 (prev_count=380104, count=575336)
MTC@c70eb02a91ac: External command `../ttcn3-tcpdump-stop.sh BSC_Tests.TC_lcs_loc_req_for_active_ms_le_timeout2 pass' was executed successfully (exit status: 0).
MTC@c70eb02a91ac: Starting external command `../ttcn3-tcpdump-start.sh BSC_Tests.TC_lcs_loc_req_for_idle_ms_no_pag_resp'.
------ BSC_Tests.TC_lcs_loc_req_for_idle_ms_no_pag_resp ------
Wed Jan 31 12:34:36 UTC 2024
NOTE: unable to use dumpcap due to missing capabilities or suid bit
Waiting for packet dumper to start... 0
MTC@c70eb02a91ac: External command `../ttcn3-tcpdump-start.sh BSC_Tests.TC_lcs_loc_req_for_idle_ms_no_pag_resp' was executed successfully (exit status: 0).
MTC@c70eb02a91ac: Test case TC_lcs_loc_req_for_idle_ms_no_pag_resp started.
MTC@c70eb02a91ac: "TC_lcs_loc_req_for_idle_ms_no_pag_resp": using IMSI '001012809903609'H
MTC@c70eb02a91ac: Connecting BSSAP RAN_Emulation to SCCP_SP_PORT
MTC@c70eb02a91ac: Starting RAN_Emulation
VirtMSC-M3UA(2901)@c70eb02a91ac: *************************************************
VirtMSC-M3UA(2901)@c70eb02a91ac: M3UA emulation initiated, the test can be started
VirtMSC-M3UA(2901)@c70eb02a91ac: *************************************************
VirtMSC-SCCP(2899)@c70eb02a91ac: v_sccp_pdu_maxlen:268
MTC@c70eb02a91ac: Connecting BSSAP_LE_Emulation to SCCP_SP_PORT
MTC@c70eb02a91ac: Starting BSSAP_LE_Emulation
VirtSMLC-M3UA(2904)@c70eb02a91ac: *************************************************
VirtSMLC-M3UA(2904)@c70eb02a91ac: M3UA emulation initiated, the test can be started
VirtSMLC-M3UA(2904)@c70eb02a91ac: *************************************************
VirtSMLC-SCCP(2902)@c70eb02a91ac: v_sccp_pdu_maxlen:268
VirtMSC-M3UA(2901)@c70eb02a91ac: SCTP_ConnectResult -> connection established from: "172.18.213.203":23905 to server: "172.18.213.200":2905 association #8
VirtSMLC-M3UA(2904)@c70eb02a91ac: SCTP_ConnectResult -> connection established from: "172.18.213.203":23908 to server: "172.18.213.200":2905 association #8
VirtMSC-RAN(2900)@c70eb02a91ac: BSSMAP: Responding to inbound RESET with RESET-ACK
VirtMSC-RAN(2900)@c70eb02a91ac: BSSMAP: Received RESET-ACK in response to RESET, we're ready to go!
VirtSMLC-BSSAP_LE(2903)@c70eb02a91ac: BSSMAP-LE: Received RESET-ACK in response to RESET, we're ready to go!
IPA-BTS0-TRX0-RSL-IPA(2907)@c70eb02a91ac: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit }
IPA-BTS0-TRX0-RSL-IPA(2907)@c70eb02a91ac: CCM Rx:{ msg_type := IPAC_MSGT_ID_GET (4), u := { get := { { len := 1, tag := IPAC_IDTAG_UNITID (8) }, { len := 1, tag := IPAC_IDTAG_MACADDR (7) }, { len := 1, tag := IPAC_IDTAG_LOCATION1 (2) }, { len := 1, tag := IPAC_IDTAG_LOCATION2 (3) }, { len := 1, tag := IPAC_IDTAG_EQUIPVERS (4) }, { len := 1, tag := IPAC_IDTAG_SWVERSION (5) }, { len := 1, tag := IPAC_IDTAG_UNITNAME (1) }, { len := 1, tag := IPAC_IDTAG_SERNR (0) } } } }
IPA-BTS0-TRX0-RSL-IPA(2907)@c70eb02a91ac: CCM Tx:{ msg_type := IPAC_MSGT_ID_RESP (5), u := { resp := { { len := 0, tag := IPAC_IDTAG_UNITID (8), data := '313233342F302F3000'O }, { len := 0, tag := IPAC_IDTAG_MACADDR (7), data := '00'O }, { len := 0, tag := IPAC_IDTAG_LOCATION1 (2), data := '00'O }, { len := 0, tag := IPAC_IDTAG_LOCATION2 (3), data := '00'O }, { len := 0, tag := IPAC_IDTAG_EQUIPVERS (4), data := '00'O }, { len := 0, tag := IPAC_IDTAG_SWVERSION (5), data := '00'O }, { len := 0, tag := IPAC_IDTAG_UNITNAME (1), data := '4F736D6F636F6D205454434E2D33204254532053696D756C61746F7200'O }, { len := 0, tag := IPAC_IDTAG_SERNR (0), data := '00'O } } } }
IPA-BTS0-TRX0-RSL-IPA(2907)@c70eb02a91ac: CCM Rx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit }
MTC@c70eb02a91ac: "TC_lcs_loc_req_for_idle_ms_no_pag_resp() start"
VirtSMLC-BSSAP_LE(2903)@c70eb02a91ac: Created IMSI[0] for '001012809903609'H'FFFFFFFF'O to be handled at TC_lcs_loc_req_for_idle_ms_no_pag_resp(2909)
VirtMSC-RAN(2900)@c70eb02a91ac: Added conn table entry 0TC_lcs_loc_req_for_idle_ms_no_pag_resp(2909)9494158
VirtMSC-SCCP(2899)@c70eb02a91ac: First idle individual index:0
VirtMSC-SCCP(2899)@c70eb02a91ac: { messageType := 2, destLocRef := '90DE8E'O, sourceLocRef := '00001A'O, protClass := { class := '0010'B, messageHandling := '0000'B }, pointer1 := 1, optionalPart := { credit := omit, calledPAddress := { paramName := 3, paramLength := 4, addr := '43BB00FE'O }, data := omit, importance := omit }, eop := { paramName := 0 } }
VirtMSC-SCCP(2899)@c70eb02a91ac: Session index based on local reference:0
VirtSMLC-SCCP(2902)@c70eb02a91ac: First idle individual index:0
VirtSMLC-SCCP(2902)@c70eb02a91ac: Warning: Stopping inactive timer T_ias[0].
VirtSMLC-BSSAP_LE(2903)@c70eb02a91ac: ExpectedCreateCallback: Found ConnHdlr TC_lcs_loc_req_for_idle_ms_no_pag_resp(2909) for IMSI '001012809903609'H
VirtSMLC-BSSAP_LE(2903)@c70eb02a91ac: Added conn table entry 0TC_lcs_loc_req_for_idle_ms_no_pag_resp(2909)1453184
VirtSMLC-SCCP(2902)@c70eb02a91ac: Session index based on connection ID:0
VirtSMLC-SCCP(2902)@c70eb02a91ac: Warning: Re-starting timer T_ias[0], which is already active (running or expired).
VirtSMLC-SCCP(2902)@c70eb02a91ac: Session index based on connection ID:0
VirtSMLC-SCCP(2902)@c70eb02a91ac: vl_len:8
VirtSMLC-SCCP(2902)@c70eb02a91ac: vl_from0
VirtSMLC-SCCP(2902)@c70eb02a91ac: data sent by MTP3_SCCP_PORT: '00062A4900020101'O
TC_lcs_loc_req_for_idle_ms_no_pag_resp(2909)@c70eb02a91ac: "got Paging Command"
TC_lcs_loc_req_for_idle_ms_no_pag_resp(2909)@c70eb02a91ac: "got Paging Command"
TC_lcs_loc_req_for_idle_ms_no_pag_resp(2909)@c70eb02a91ac: "got Paging Command"
TC_lcs_loc_req_for_idle_ms_no_pag_resp(2909)@c70eb02a91ac: "got Paging Command"
TC_lcs_loc_req_for_idle_ms_no_pag_resp(2909)@c70eb02a91ac: "got Paging Command"
TC_lcs_loc_req_for_idle_ms_no_pag_resp(2909)@c70eb02a91ac: "got Paging Command"
TC_lcs_loc_req_for_idle_ms_no_pag_resp(2909)@c70eb02a91ac: "got Paging Command"
TC_lcs_loc_req_for_idle_ms_no_pag_resp(2909)@c70eb02a91ac: "got Paging Command"
TC_lcs_loc_req_for_idle_ms_no_pag_resp(2909)@c70eb02a91ac: "got Paging Command"
TC_lcs_loc_req_for_idle_ms_no_pag_resp(2909)@c70eb02a91ac: "got Paging Command"
+ clean_up_common
+ set +e
+ set +x
### Clean up ###
+ trap - EXIT INT TERM 0
+ type clean_up
+ network_clean
+ docker network inspect ttcn3-bsc-test-213
+ grep Name
+ cut -d : -f2
+ awk -F" NR>1{print $2}
Cannot connect to the Docker daemon at unix:///var/run/docker.sock. Is the docker daemon running?
+ local containers=
+ [ -n ]
+ network_remove
+ set +x
Removing network ttcn3-bsc-test-213
+ docker network remove ttcn3-bsc-test-213
ttcn3-bsc-test-213
+ rm -rf <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-bsc-test/1355/artifact…>
+ fix_perms
+ set +x
Fixing permissions
+ docker run --rm -v <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-bsc-test/ws/logs>:/data -v <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-bsc-test/ws/_cache>:/cache --name jenkins-TTCN3-centos-bsc-test-1355-cleaner debian:bookworm chmod -R a+rX /data/ /cache/
+ collect_logs
+ cat <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-bsc-test/1355/artifact…>
Build step 'Execute shell' marked build as failure
Recording test results
[Checks API] No suitable checks publisher found.
See <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-bsc-test-sccplite/1350…>
Changes:
------------------------------------------
[...truncated 3.20 MB...]
NOTE: unable to use dumpcap due to missing capabilities or suid bit
Waiting for packet dumper to start... 0
MTC@bebf6fbd4118: External command `../ttcn3-tcpdump-start.sh BSC_Tests.TC_imm_ass_post_chan_ack' was executed successfully (exit status: 0).
MTC@bebf6fbd4118: Test case TC_imm_ass_post_chan_ack started.
VirtMSC-IPA(1881)@bebf6fbd4118: Established a new IPA connection (conn_id=2)
VirtMSC-IPA(1881)@bebf6fbd4118: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit }
VirtMSC-IPA(1881)@bebf6fbd4118: CCM Rx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit }
VirtMSC-IPA-WAIT(1882)@bebf6fbd4118: setverdict(pass): none -> pass
VirtMSC-IPA-WAIT(1882)@bebf6fbd4118: Final verdict of PTC: pass
VirtMSC-SCCP(1880)@bebf6fbd4118: v_sccp_pdu_maxlen:268
MTC@bebf6fbd4118: BSSMAP: Received RESET-ACK in response to RESET, we're ready to go!
MTC@bebf6fbd4118: msc 1 is not configured, skipping
MTC@bebf6fbd4118: msc 2 is not configured, skipping
IPA-BTS0-TRX0-RSL-IPA(1885)@bebf6fbd4118: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit }
IPA-BTS0-TRX0-RSL-IPA(1885)@bebf6fbd4118: CCM Rx:{ msg_type := IPAC_MSGT_ID_GET (4), u := { get := { { len := 1, tag := IPAC_IDTAG_UNITID (8) }, { len := 1, tag := IPAC_IDTAG_MACADDR (7) }, { len := 1, tag := IPAC_IDTAG_LOCATION1 (2) }, { len := 1, tag := IPAC_IDTAG_LOCATION2 (3) }, { len := 1, tag := IPAC_IDTAG_EQUIPVERS (4) }, { len := 1, tag := IPAC_IDTAG_SWVERSION (5) }, { len := 1, tag := IPAC_IDTAG_UNITNAME (1) }, { len := 1, tag := IPAC_IDTAG_SERNR (0) } } } }
IPA-BTS0-TRX0-RSL-IPA(1885)@bebf6fbd4118: CCM Tx:{ msg_type := IPAC_MSGT_ID_RESP (5), u := { resp := { { len := 0, tag := IPAC_IDTAG_UNITID (8), data := '313233342F302F3000'O }, { len := 0, tag := IPAC_IDTAG_MACADDR (7), data := '00'O }, { len := 0, tag := IPAC_IDTAG_LOCATION1 (2), data := '00'O }, { len := 0, tag := IPAC_IDTAG_LOCATION2 (3), data := '00'O }, { len := 0, tag := IPAC_IDTAG_EQUIPVERS (4), data := '00'O }, { len := 0, tag := IPAC_IDTAG_SWVERSION (5), data := '00'O }, { len := 0, tag := IPAC_IDTAG_UNITNAME (1), data := '4F736D6F636F6D205454434E2D33204254532053696D756C61746F7200'O }, { len := 0, tag := IPAC_IDTAG_SERNR (0), data := '00'O } } } }
IPA-BTS0-TRX0-RSL-IPA(1885)@bebf6fbd4118: CCM Rx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit }
MTC@bebf6fbd4118: "TC_imm_ass_post_chan_ack() start"
VirtMSC-SCCP(1880)@bebf6fbd4118: First idle individual index:0
VirtMSC-SCCP(1880)@bebf6fbd4118: Warning: Stopping inactive timer T_ias[0].
VirtMSC-SCCP(1880)@bebf6fbd4118: Session index based on connection ID:0
VirtMSC-SCCP(1880)@bebf6fbd4118: Warning: Re-starting timer T_ias[0], which is already active (running or expired).
VirtMSC-SCCP(1880)@bebf6fbd4118: Session index based on connection ID:0
VirtMSC-SCCP(1880)@bebf6fbd4118: vl_len:6
VirtMSC-SCCP(1880)@bebf6fbd4118: vl_from0
VirtMSC-SCCP(1880)@bebf6fbd4118: data sent by MTP3_SCCP_PORT: '000420040100'O
VirtMSC-SCCP(1880)@bebf6fbd4118: Session index based on local reference:0
VirtMSC-SCCP(1880)@bebf6fbd4118: DT1 will be put to the reassembly buffer
VirtMSC-SCCP(1880)@bebf6fbd4118: DT1 data has been put to the reassembly buffer
VirtMSC-SCCP(1880)@bebf6fbd4118: DT1/segmentingReassembl/more==0 received=> send ASP_SCCP_N_DATA comes
VirtMSC-SCCP(1880)@bebf6fbd4118: Session index based on connection ID:0
MTC@bebf6fbd4118: talloc reports "struct bsc_subscr" x 1, expecting 0
MTC@bebf6fbd4118: talloc reports "struct gsm_subscriber_connection" x 1, expecting 0
MTC@bebf6fbd4118: count mismatch, retrying in 3.000000
VirtMSC-SCCP(1880)@bebf6fbd4118: Session index based on local reference:0
VirtMSC-SCCP(1880)@bebf6fbd4118: RLC received in state:disconnect_pending
MTC@bebf6fbd4118: talloc reports "struct bsc_subscr" x 0, expecting 0
MTC@bebf6fbd4118: talloc reports "struct gsm_subscriber_connection" x 0, expecting 0
VirtMSC-SCCP(1880)@bebf6fbd4118: Final verdict of PTC: none
IPA-CTRL-CLI-IPA(1883)@bebf6fbd4118: Final verdict of PTC: none
VirtMSC-STATS(1879)@bebf6fbd4118: Final verdict of PTC: none
VirtMSC-IPA(1881)@bebf6fbd4118: Final verdict of PTC: none
MTC@bebf6fbd4118: setverdict(pass): none -> pass
IPA-BTS0-TRX0-RSL-IPA(1885)@bebf6fbd4118: Final verdict of PTC: none
VirtMGW-MGCP-0(1884)@bebf6fbd4118: Final verdict of PTC: none
MTC@bebf6fbd4118: Setting final verdict of the test case.
MTC@bebf6fbd4118: Local verdict of MTC: pass
MTC@bebf6fbd4118: Local verdict of PTC VirtMSC-STATS(1879): none (pass -> pass)
MTC@bebf6fbd4118: Local verdict of PTC VirtMSC-SCCP(1880): none (pass -> pass)
MTC@bebf6fbd4118: Local verdict of PTC VirtMSC-IPA(1881): none (pass -> pass)
MTC@bebf6fbd4118: Local verdict of PTC VirtMSC-IPA-WAIT(1882): pass (pass -> pass)
MTC@bebf6fbd4118: Local verdict of PTC IPA-CTRL-CLI-IPA(1883): none (pass -> pass)
MTC@bebf6fbd4118: Local verdict of PTC VirtMGW-MGCP-0(1884): none (pass -> pass)
MTC@bebf6fbd4118: Local verdict of PTC IPA-BTS0-TRX0-RSL-IPA(1885): none (pass -> pass)
MTC@bebf6fbd4118: Test case TC_imm_ass_post_chan_ack finished. Verdict: pass
MTC@bebf6fbd4118: Starting external command `../ttcn3-tcpdump-stop.sh BSC_Tests.TC_imm_ass_post_chan_ack pass'.
Wed Jan 31 12:33:56 UTC 2024
[1;32m====== BSC_Tests.TC_imm_ass_post_chan_ack pass ======[0m
Saving talloc report from 172.18.245.20:4242 to BSC_Tests.TC_imm_ass_post_chan_ack.talloc
Waiting for packet dumper to finish... 0 (prev_count=-1, count=218692)
Waiting for packet dumper to finish... 1 (prev_count=218692, count=402526)
MTC@bebf6fbd4118: External command `../ttcn3-tcpdump-stop.sh BSC_Tests.TC_imm_ass_post_chan_ack pass' was executed successfully (exit status: 0).
MTC@bebf6fbd4118: Starting external command `../ttcn3-tcpdump-start.sh BSC_Tests.TC_imm_ass_pre_chan_ack'.
------ BSC_Tests.TC_imm_ass_pre_chan_ack ------
Wed Jan 31 12:34:01 UTC 2024
NOTE: unable to use dumpcap due to missing capabilities or suid bit
Waiting for packet dumper to start... 0
MTC@bebf6fbd4118: External command `../ttcn3-tcpdump-start.sh BSC_Tests.TC_imm_ass_pre_chan_ack' was executed successfully (exit status: 0).
MTC@bebf6fbd4118: Test case TC_imm_ass_pre_chan_ack started.
VirtMSC-IPA(1888)@bebf6fbd4118: Established a new IPA connection (conn_id=2)
VirtMSC-IPA(1888)@bebf6fbd4118: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit }
VirtMSC-IPA(1888)@bebf6fbd4118: CCM Rx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit }
VirtMSC-IPA-WAIT(1889)@bebf6fbd4118: setverdict(pass): none -> pass
VirtMSC-IPA-WAIT(1889)@bebf6fbd4118: Final verdict of PTC: pass
VirtMSC-SCCP(1887)@bebf6fbd4118: v_sccp_pdu_maxlen:268
MTC@bebf6fbd4118: BSSMAP: Received RESET-ACK in response to RESET, we're ready to go!
MTC@bebf6fbd4118: msc 1 is not configured, skipping
MTC@bebf6fbd4118: msc 2 is not configured, skipping
IPA-BTS0-TRX0-RSL-IPA(1892)@bebf6fbd4118: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit }
IPA-BTS0-TRX0-RSL-IPA(1892)@bebf6fbd4118: CCM Rx:{ msg_type := IPAC_MSGT_ID_GET (4), u := { get := { { len := 1, tag := IPAC_IDTAG_UNITID (8) }, { len := 1, tag := IPAC_IDTAG_MACADDR (7) }, { len := 1, tag := IPAC_IDTAG_LOCATION1 (2) }, { len := 1, tag := IPAC_IDTAG_LOCATION2 (3) }, { len := 1, tag := IPAC_IDTAG_EQUIPVERS (4) }, { len := 1, tag := IPAC_IDTAG_SWVERSION (5) }, { len := 1, tag := IPAC_IDTAG_UNITNAME (1) }, { len := 1, tag := IPAC_IDTAG_SERNR (0) } } } }
IPA-BTS0-TRX0-RSL-IPA(1892)@bebf6fbd4118: CCM Tx:{ msg_type := IPAC_MSGT_ID_RESP (5), u := { resp := { { len := 0, tag := IPAC_IDTAG_UNITID (8), data := '313233342F302F3000'O }, { len := 0, tag := IPAC_IDTAG_MACADDR (7), data := '00'O }, { len := 0, tag := IPAC_IDTAG_LOCATION1 (2), data := '00'O }, { len := 0, tag := IPAC_IDTAG_LOCATION2 (3), data := '00'O }, { len := 0, tag := IPAC_IDTAG_EQUIPVERS (4), data := '00'O }, { len := 0, tag := IPAC_IDTAG_SWVERSION (5), data := '00'O }, { len := 0, tag := IPAC_IDTAG_UNITNAME (1), data := '4F736D6F636F6D205454434E2D33204254532053696D756C61746F7200'O }, { len := 0, tag := IPAC_IDTAG_SERNR (0), data := '00'O } } } }
IPA-BTS0-TRX0-RSL-IPA(1892)@bebf6fbd4118: CCM Rx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit }
MTC@bebf6fbd4118: "TC_imm_ass_pre_chan_ack() start"
VirtMSC-SCCP(1887)@bebf6fbd4118: First idle individual index:0
VirtMSC-SCCP(1887)@bebf6fbd4118: Warning: Stopping inactive timer T_ias[0].
VirtMSC-SCCP(1887)@bebf6fbd4118: Session index based on connection ID:0
VirtMSC-SCCP(1887)@bebf6fbd4118: Warning: Re-starting timer T_ias[0], which is already active (running or expired).
VirtMSC-SCCP(1887)@bebf6fbd4118: Session index based on connection ID:0
VirtMSC-SCCP(1887)@bebf6fbd4118: vl_len:6
VirtMSC-SCCP(1887)@bebf6fbd4118: vl_from0
VirtMSC-SCCP(1887)@bebf6fbd4118: data sent by MTP3_SCCP_PORT: '000420040100'O
VirtMSC-SCCP(1887)@bebf6fbd4118: Session index based on local reference:0
VirtMSC-SCCP(1887)@bebf6fbd4118: DT1 will be put to the reassembly buffer
VirtMSC-SCCP(1887)@bebf6fbd4118: DT1 data has been put to the reassembly buffer
VirtMSC-SCCP(1887)@bebf6fbd4118: DT1/segmentingReassembl/more==0 received=> send ASP_SCCP_N_DATA comes
VirtMSC-SCCP(1887)@bebf6fbd4118: Session index based on connection ID:0
MTC@bebf6fbd4118: talloc reports "struct bsc_subscr" x 1, expecting 0
MTC@bebf6fbd4118: talloc reports "struct gsm_subscriber_connection" x 1, expecting 0
MTC@bebf6fbd4118: count mismatch, retrying in 3.000000
VirtMSC-SCCP(1887)@bebf6fbd4118: Session index based on local reference:0
VirtMSC-SCCP(1887)@bebf6fbd4118: RLC received in state:disconnect_pending
MTC@bebf6fbd4118: talloc reports "struct bsc_subscr" x 0, expecting 0
MTC@bebf6fbd4118: talloc reports "struct gsm_subscriber_connection" x 0, expecting 0
VirtMSC-SCCP(1887)@bebf6fbd4118: Final verdict of PTC: none
IPA-CTRL-CLI-IPA(1890)@bebf6fbd4118: Final verdict of PTC: none
VirtMSC-IPA(1888)@bebf6fbd4118: Final verdict of PTC: none
VirtMSC-STATS(1886)@bebf6fbd4118: Final verdict of PTC: none
MTC@bebf6fbd4118: setverdict(pass): none -> pass
IPA-BTS0-TRX0-RSL-IPA(1892)@bebf6fbd4118: Final verdict of PTC: none
VirtMGW-MGCP-0(1891)@bebf6fbd4118: Final verdict of PTC: none
MTC@bebf6fbd4118: Setting final verdict of the test case.
MTC@bebf6fbd4118: Local verdict of MTC: pass
MTC@bebf6fbd4118: Local verdict of PTC VirtMSC-STATS(1886): none (pass -> pass)
MTC@bebf6fbd4118: Local verdict of PTC VirtMSC-SCCP(1887): none (pass -> pass)
MTC@bebf6fbd4118: Local verdict of PTC VirtMSC-IPA(1888): none (pass -> pass)
MTC@bebf6fbd4118: Local verdict of PTC VirtMSC-IPA-WAIT(1889): pass (pass -> pass)
MTC@bebf6fbd4118: Local verdict of PTC IPA-CTRL-CLI-IPA(1890): none (pass -> pass)
MTC@bebf6fbd4118: Local verdict of PTC VirtMGW-MGCP-0(1891): none (pass -> pass)
MTC@bebf6fbd4118: Local verdict of PTC IPA-BTS0-TRX0-RSL-IPA(1892): none (pass -> pass)
MTC@bebf6fbd4118: Test case TC_imm_ass_pre_chan_ack finished. Verdict: pass
MTC@bebf6fbd4118: Starting external command `../ttcn3-tcpdump-stop.sh BSC_Tests.TC_imm_ass_pre_chan_ack pass'.
Wed Jan 31 12:34:15 UTC 2024
[1;32m====== BSC_Tests.TC_imm_ass_pre_chan_ack pass ======[0m
Saving talloc report from 172.18.245.20:4242 to BSC_Tests.TC_imm_ass_pre_chan_ack.talloc
Waiting for packet dumper to finish... 0 (prev_count=-1, count=219862)
Waiting for packet dumper to finish... 1 (prev_count=219862, count=405111)
MTC@bebf6fbd4118: External command `../ttcn3-tcpdump-stop.sh BSC_Tests.TC_imm_ass_pre_chan_ack pass' was executed successfully (exit status: 0).
MTC@bebf6fbd4118: Starting external command `../ttcn3-tcpdump-start.sh BSC_Tests.TC_imm_ass_pre_ts_ack'.
------ BSC_Tests.TC_imm_ass_pre_ts_ack ------
Wed Jan 31 12:34:20 UTC 2024
NOTE: unable to use dumpcap due to missing capabilities or suid bit
Waiting for packet dumper to start... 0
MTC@bebf6fbd4118: External command `../ttcn3-tcpdump-start.sh BSC_Tests.TC_imm_ass_pre_ts_ack' was executed successfully (exit status: 0).
MTC@bebf6fbd4118: Test case TC_imm_ass_pre_ts_ack started.
VirtMSC-IPA(1895)@bebf6fbd4118: Established a new IPA connection (conn_id=2)
VirtMSC-IPA(1895)@bebf6fbd4118: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit }
VirtMSC-IPA(1895)@bebf6fbd4118: CCM Rx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit }
VirtMSC-IPA-WAIT(1896)@bebf6fbd4118: setverdict(pass): none -> pass
VirtMSC-IPA-WAIT(1896)@bebf6fbd4118: Final verdict of PTC: pass
VirtMSC-SCCP(1894)@bebf6fbd4118: v_sccp_pdu_maxlen:268
MTC@bebf6fbd4118: BSSMAP: Received RESET-ACK in response to RESET, we're ready to go!
MTC@bebf6fbd4118: msc 1 is not configured, skipping
MTC@bebf6fbd4118: msc 2 is not configured, skipping
IPA-BTS0-TRX0-RSL-IPA(1899)@bebf6fbd4118: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit }
IPA-BTS0-TRX0-RSL-IPA(1899)@bebf6fbd4118: CCM Rx:{ msg_type := IPAC_MSGT_ID_GET (4), u := { get := { { len := 1, tag := IPAC_IDTAG_UNITID (8) }, { len := 1, tag := IPAC_IDTAG_MACADDR (7) }, { len := 1, tag := IPAC_IDTAG_LOCATION1 (2) }, { len := 1, tag := IPAC_IDTAG_LOCATION2 (3) }, { len := 1, tag := IPAC_IDTAG_EQUIPVERS (4) }, { len := 1, tag := IPAC_IDTAG_SWVERSION (5) }, { len := 1, tag := IPAC_IDTAG_UNITNAME (1) }, { len := 1, tag := IPAC_IDTAG_SERNR (0) } } } }
IPA-BTS0-TRX0-RSL-IPA(1899)@bebf6fbd4118: CCM Tx:{ msg_type := IPAC_MSGT_ID_RESP (5), u := { resp := { { len := 0, tag := IPAC_IDTAG_UNITID (8), data := '313233342F302F3000'O }, { len := 0, tag := IPAC_IDTAG_MACADDR (7), data := '00'O }, { len := 0, tag := IPAC_IDTAG_LOCATION1 (2), data := '00'O }, { len := 0, tag := IPAC_IDTAG_LOCATION2 (3), data := '00'O }, { len := 0, tag := IPAC_IDTAG_EQUIPVERS (4), data := '00'O }, { len := 0, tag := IPAC_IDTAG_SWVERSION (5), data := '00'O }, { len := 0, tag := IPAC_IDTAG_UNITNAME (1), data := '4F736D6F636F6D205454434E2D33204254532053696D756C61746F7200'O }, { len := 0, tag := IPAC_IDTAG_SERNR (0), data := '00'O } } } }
IPA-BTS0-TRX0-RSL-IPA(1899)@bebf6fbd4118: CCM Rx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit }
MTC@bebf6fbd4118: "TC_imm_ass_pre_ts_ack() start"
VirtMSC-SCCP(1894)@bebf6fbd4118: First idle individual index:0
VirtMSC-SCCP(1894)@bebf6fbd4118: Warning: Stopping inactive timer T_ias[0].
VirtMSC-SCCP(1894)@bebf6fbd4118: Session index based on connection ID:0
VirtMSC-SCCP(1894)@bebf6fbd4118: Warning: Re-starting timer T_ias[0], which is already active (running or expired).
VirtMSC-SCCP(1894)@bebf6fbd4118: Session index based on connection ID:0
VirtMSC-SCCP(1894)@bebf6fbd4118: vl_len:6
VirtMSC-SCCP(1894)@bebf6fbd4118: vl_from0
VirtMSC-SCCP(1894)@bebf6fbd4118: data sent by MTP3_SCCP_PORT: '000420040100'O
VirtMSC-SCCP(1894)@bebf6fbd4118: Session index based on local reference:0
VirtMSC-SCCP(1894)@bebf6fbd4118: DT1 will be put to the reassembly buffer
VirtMSC-SCCP(1894)@bebf6fbd4118: DT1 data has been put to the reassembly buffer
VirtMSC-SCCP(1894)@bebf6fbd4118: DT1/segmentingReassembl/more==0 received=> send ASP_SCCP_N_DATA comes
VirtMSC-SCCP(1894)@bebf6fbd4118: Session index based on connection ID:0
MTC@bebf6fbd4118: talloc reports "struct bsc_subscr" x 1, expecting 0
MTC@bebf6fbd4118: talloc reports "struct gsm_subscriber_connection" x 1, expecting 0
MTC@bebf6fbd4118: count mismatch, retrying in 3.000000
VirtMSC-SCCP(1894)@bebf6fbd4118: Session index based on local reference:0
VirtMSC-SCCP(1894)@bebf6fbd4118: RLC received in state:disconnect_pending
MTC@bebf6fbd4118: talloc reports "struct bsc_subscr" x 0, expecting 0
MTC@bebf6fbd4118: talloc reports "struct gsm_subscriber_connection" x 0, expecting 0
VirtMSC-SCCP(1894)@bebf6fbd4118: Final verdict of PTC: none
IPA-CTRL-CLI-IPA(1897)@bebf6fbd4118: Final verdict of PTC: none
VirtMSC-STATS(1893)@bebf6fbd4118: Final verdict of PTC: none
VirtMSC-IPA(1895)@bebf6fbd4118: Final verdict of PTC: none
MTC@bebf6fbd4118: setverdict(pass): none -> pass
IPA-BTS0-TRX0-RSL-IPA(1899)@bebf6fbd4118: Final verdict of PTC: none
VirtMGW-MGCP-0(1898)@bebf6fbd4118: Final verdict of PTC: none
MTC@bebf6fbd4118: Setting final verdict of the test case.
MTC@bebf6fbd4118: Local verdict of MTC: pass
MTC@bebf6fbd4118: Local verdict of PTC VirtMSC-STATS(1893): none (pass -> pass)
MTC@bebf6fbd4118: Local verdict of PTC VirtMSC-SCCP(1894): none (pass -> pass)
MTC@bebf6fbd4118: Local verdict of PTC VirtMSC-IPA(1895): none (pass -> pass)
MTC@bebf6fbd4118: Local verdict of PTC VirtMSC-IPA-WAIT(1896): pass (pass -> pass)
MTC@bebf6fbd4118: Local verdict of PTC IPA-CTRL-CLI-IPA(1897): none (pass -> pass)
MTC@bebf6fbd4118: Local verdict of PTC VirtMGW-MGCP-0(1898): none (pass -> pass)
MTC@bebf6fbd4118: Local verdict of PTC IPA-BTS0-TRX0-RSL-IPA(1899): none (pass -> pass)
MTC@bebf6fbd4118: Test case TC_imm_ass_pre_ts_ack finished. Verdict: pass
MTC@bebf6fbd4118: Starting external command `../ttcn3-tcpdump-stop.sh BSC_Tests.TC_imm_ass_pre_ts_ack pass'.
Wed Jan 31 12:34:34 UTC 2024
[1;32m====== BSC_Tests.TC_imm_ass_pre_ts_ack pass ======[0m
Saving talloc report from 172.18.245.20:4242 to BSC_Tests.TC_imm_ass_pre_ts_ack.talloc
Waiting for packet dumper to finish... 0 (prev_count=-1, count=221764)
Waiting for packet dumper to finish... 1 (prev_count=221764, count=406010)
MTC@bebf6fbd4118: External command `../ttcn3-tcpdump-stop.sh BSC_Tests.TC_imm_ass_pre_ts_ack pass' was executed successfully (exit status: 0).
MTC@bebf6fbd4118: Starting external command `../ttcn3-tcpdump-start.sh BSC_Tests.TC_imm_ass_pre_chan_ack_dyn_ts'.
------ BSC_Tests.TC_imm_ass_pre_chan_ack_dyn_ts ------
Wed Jan 31 12:34:39 UTC 2024
NOTE: unable to use dumpcap due to missing capabilities or suid bit
Waiting for packet dumper to start... 0
MTC@bebf6fbd4118: External command `../ttcn3-tcpdump-start.sh BSC_Tests.TC_imm_ass_pre_chan_ack_dyn_ts' was executed successfully (exit status: 0).
MTC@bebf6fbd4118: Test case TC_imm_ass_pre_chan_ack_dyn_ts started.
VirtMSC-IPA(1902)@bebf6fbd4118: Established a new IPA connection (conn_id=2)
VirtMSC-IPA(1902)@bebf6fbd4118: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit }
VirtMSC-IPA(1902)@bebf6fbd4118: CCM Rx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit }
VirtMSC-IPA-WAIT(1903)@bebf6fbd4118: setverdict(pass): none -> pass
VirtMSC-IPA-WAIT(1903)@bebf6fbd4118: Final verdict of PTC: pass
VirtMSC-SCCP(1901)@bebf6fbd4118: v_sccp_pdu_maxlen:268
MTC@bebf6fbd4118: BSSMAP: Received RESET-ACK in response to RESET, we're ready to go!
MTC@bebf6fbd4118: msc 1 is not configured, skipping
MTC@bebf6fbd4118: msc 2 is not configured, skipping
IPA-BTS0-TRX0-RSL-IPA(1906)@bebf6fbd4118: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit }
IPA-BTS0-TRX0-RSL-IPA(1906)@bebf6fbd4118: CCM Rx:{ msg_type := IPAC_MSGT_ID_GET (4), u := { get := { { len := 1, tag := IPAC_IDTAG_UNITID (8) }, { len := 1, tag := IPAC_IDTAG_MACADDR (7) }, { len := 1, tag := IPAC_IDTAG_LOCATION1 (2) }, { len := 1, tag := IPAC_IDTAG_LOCATION2 (3) }, { len := 1, tag := IPAC_IDTAG_EQUIPVERS (4) }, { len := 1, tag := IPAC_IDTAG_SWVERSION (5) }, { len := 1, tag := IPAC_IDTAG_UNITNAME (1) }, { len := 1, tag := IPAC_IDTAG_SERNR (0) } } } }
IPA-BTS0-TRX0-RSL-IPA(1906)@bebf6fbd4118: CCM Tx:{ msg_type := IPAC_MSGT_ID_RESP (5), u := { resp := { { len := 0, tag := IPAC_IDTAG_UNITID (8), data := '313233342F302F3000'O }, { len := 0, tag := IPAC_IDTAG_MACADDR (7), data := '00'O }, { len := 0, tag := IPAC_IDTAG_LOCATION1 (2), data := '00'O }, { len := 0, tag := IPAC_IDTAG_LOCATION2 (3), data := '00'O }, { len := 0, tag := IPAC_IDTAG_EQUIPVERS (4), data := '00'O }, { len := 0, tag := IPAC_IDTAG_SWVERSION (5), data := '00'O }, { len := 0, tag := IPAC_IDTAG_UNITNAME (1), data := '4F736D6F636F6D205454434E2D33204254532053696D756C61746F7200'O }, { len := 0, tag := IPAC_IDTAG_SERNR (0), data := '00'O } } } }
IPA-BTS0-TRX0-RSL-IPA(1906)@bebf6fbd4118: CCM Rx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit }
MTC@bebf6fbd4118: "TC_imm_ass_pre_chan_ack_dyn_ts() start"
+ clean_up_common
+ set +e
+ set +x
### Clean up ###
+ trap - EXIT INT TERM 0
+ type clean_up
+ network_clean
+ docker network inspect ttcn3-bsc-test-sccplite-245
+ grep Name
+ cut -d : -f2
+ awk -F" NR>1{print $2}
Cannot connect to the Docker daemon at unix:///var/run/docker.sock. Is the docker daemon running?
+ local containers=
+ [ -n ]
+ network_remove
+ set +x
Removing network ttcn3-bsc-test-sccplite-245
+ docker network remove ttcn3-bsc-test-sccplite-245
permission denied while trying to connect to the Docker daemon socket at unix:///var/run/docker.sock: Delete "http://%2Fvar%2Frun%2Fdocker.sock/v1.24/networks/ttcn3-bsc-test-sccplite-245": dial unix /var/run/docker.sock: connect: permission denied
+ rm -rf <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-bsc-test-sccplite/1350…>
+ fix_perms
+ set +x
Fixing permissions
+ docker run --rm -v <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-bsc-test-sccplite/ws/l…>:/data -v <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-bsc-test-sccplite/ws/_…>:/cache --name jenkins-TTCN3-centos-bsc-test-sccplite-1350-cleaner debian:bookworm chmod -R a+rX /data/ /cache/
docker: permission denied while trying to connect to the Docker daemon socket at unix:///var/run/docker.sock: Post "http://%2Fvar%2Frun%2Fdocker.sock/v1.24/containers/create?name=jenkins-TTCN3-centos-bsc-test-sccplite-1350-cleaner": dial unix /var/run/docker.sock: connect: permission denied.
See 'docker run --help'.
+ collect_logs
+ cat <https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-bsc-test-sccplite/1350…>
Build step 'Execute shell' marked build as failure
Recording test results
[Checks API] No suitable checks publisher found.