Build failed in Jenkins: TTCN3-centos-bts-test-2022q2 #160

28 Nov 2022


      See https://jenkins.osmocom.org/jenkins/job/TTCN3-centos-bts-test-2022q2/160/display/redirect
Changes:
------------------------------------------
[...truncated 4.01 MB...]
MTC@e4c06fca6070: Starting external command `../ttcn3-tcpdump-start.sh BTS_Tests.TC_pcu_socket_nsvc_ipv4'.
------ BTS_Tests.TC_pcu_socket_nsvc_ipv4 ------
Mon Nov 28 13:04:56 UTC 2022
NOTE: unable to use dumpcap due to missing capabilities or suid bit
Waiting for packet dumper to start... 0
MTC@e4c06fca6070: External command `../ttcn3-tcpdump-start.sh BTS_Tests.TC_pcu_socket_nsvc_ipv4' was executed successfully (exit status: 0).
MTC@e4c06fca6070: Test case TC_pcu_socket_nsvc_ipv4 started.
TC_pcu_socket_nsvc_ipv4-RSL-IPA(576)@e4c06fca6070: Established a new IPA connection (conn_id=2)
TC_pcu_socket_nsvc_ipv4-RSL(577)@e4c06fca6070: A new IPA/RSL connection has been established (conn_id=2), waiting for IDENTITY RESPONSE...
TC_pcu_socket_nsvc_ipv4-RSL-IPA(576)@e4c06fca6070: 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) } } } }
TC_pcu_socket_nsvc_ipv4-RSL-IPA(576)@e4c06fca6070: CCM Rx:{ msg_type := IPAC_MSGT_ID_RESP (5), u := { resp := { { len := 28, tag := IPAC_IDTAG_UNITNAME (1), data := '6F736D6F2D6274732D30322D34322D61632D31322D30392D313400'O }, { len := 10, tag := IPAC_IDTAG_UNITID (8), data := '313233342F302F3000'O } } } }
TC_pcu_socket_nsvc_ipv4-RSL-IPA(576)@e4c06fca6070: IPA ID RESP: { { len := 28, tag := IPAC_IDTAG_UNITNAME (1), data := '6F736D6F2D6274732D30322D34322D61632D31322D30392D313400'O }, { len := 10, tag := IPAC_IDTAG_UNITID (8), data := '313233342F302F3000'O } }
TC_pcu_socket_nsvc_ipv4-RSL-IPA(576)@e4c06fca6070: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit }
TC_pcu_socket_nsvc_ipv4-RSL(577)@e4c06fca6070: Got IDENTITY RESPONSE (conn_id=2): { { len := 28, tag := IPAC_IDTAG_UNITNAME (1), data := '6F736D6F2D6274732D30322D34322D61632D31322D30392D313400'O }, { len := 10, tag := IPAC_IDTAG_UNITID (8), data := '313233342F302F3000'O } }
TC_pcu_socket_nsvc_ipv4-RSL-IPA(576)@e4c06fca6070: CCM Rx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit }
TC_pcu_socket_nsvc_ipv4-RSL(577)@e4c06fca6070: Warning: The first argument (instr) of function regexp(), which is "1234/0/0" & char(0, 0, 0, 0), contains a character with zero character code at index 8. The rest of the string will be ignored during matching.
TC_pcu_socket_nsvc_ipv4-RSL(577)@e4c06fca6070: Mapped TRX#0 to TCP/IP conn_id=2
MTC@e4c06fca6070: 1/4 transceiver(s) connected
TC_pcu_socket_nsvc_ipv4-RSL-IPA(576)@e4c06fca6070: Established a new IPA connection (conn_id=3)
TC_pcu_socket_nsvc_ipv4-RSL-IPA(576)@e4c06fca6070: 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) } } } }
TC_pcu_socket_nsvc_ipv4-RSL(577)@e4c06fca6070: A new IPA/RSL connection has been established (conn_id=3), waiting for IDENTITY RESPONSE...
MTC@e4c06fca6070: 1/4 RF Resource Indication(s) received
TC_pcu_socket_nsvc_ipv4-RSL-IPA(576)@e4c06fca6070: CCM Rx:{ msg_type := IPAC_MSGT_ID_RESP (5), u := { resp := { { len := 28, tag := IPAC_IDTAG_UNITNAME (1), data := '6F736D6F2D6274732D30322D34322D61632D31322D30392D313400'O }, { len := 10, tag := IPAC_IDTAG_UNITID (8), data := '313233342F302F3100'O } } } }
TC_pcu_socket_nsvc_ipv4-RSL-IPA(576)@e4c06fca6070: IPA ID RESP: { { len := 28, tag := IPAC_IDTAG_UNITNAME (1), data := '6F736D6F2D6274732D30322D34322D61632D31322D30392D313400'O }, { len := 10, tag := IPAC_IDTAG_UNITID (8), data := '313233342F302F3100'O } }
TC_pcu_socket_nsvc_ipv4-RSL-IPA(576)@e4c06fca6070: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit }
TC_pcu_socket_nsvc_ipv4-RSL(577)@e4c06fca6070: Got IDENTITY RESPONSE (conn_id=3): { { len := 28, tag := IPAC_IDTAG_UNITNAME (1), data := '6F736D6F2D6274732D30322D34322D61632D31322D30392D313400'O }, { len := 10, tag := IPAC_IDTAG_UNITID (8), data := '313233342F302F3100'O } }
TC_pcu_socket_nsvc_ipv4-RSL(577)@e4c06fca6070: Warning: The first argument (instr) of function regexp(), which is "1234/0/1" & char(0, 0, 0, 0), contains a character with zero character code at index 8. The rest of the string will be ignored during matching.
TC_pcu_socket_nsvc_ipv4-RSL(577)@e4c06fca6070: Mapped TRX#1 to TCP/IP conn_id=3
TC_pcu_socket_nsvc_ipv4-RSL-IPA(576)@e4c06fca6070: CCM Rx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit }
MTC@e4c06fca6070: 2/4 transceiver(s) connected
MTC@e4c06fca6070: 2/4 RF Resource Indication(s) received
TC_pcu_socket_nsvc_ipv4-RSL-IPA(576)@e4c06fca6070: Established a new IPA connection (conn_id=4)
TC_pcu_socket_nsvc_ipv4-RSL(577)@e4c06fca6070: A new IPA/RSL connection has been established (conn_id=4), waiting for IDENTITY RESPONSE...
TC_pcu_socket_nsvc_ipv4-RSL-IPA(576)@e4c06fca6070: 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) } } } }
TC_pcu_socket_nsvc_ipv4-RSL-IPA(576)@e4c06fca6070: Established a new IPA connection (conn_id=5)
TC_pcu_socket_nsvc_ipv4-RSL(577)@e4c06fca6070: A new IPA/RSL connection has been established (conn_id=5), waiting for IDENTITY RESPONSE...
TC_pcu_socket_nsvc_ipv4-RSL-IPA(576)@e4c06fca6070: 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) } } } }
TC_pcu_socket_nsvc_ipv4-RSL-IPA(576)@e4c06fca6070: CCM Rx:{ msg_type := IPAC_MSGT_ID_RESP (5), u := { resp := { { len := 28, tag := IPAC_IDTAG_UNITNAME (1), data := '6F736D6F2D6274732D30322D34322D61632D31322D30392D313400'O }, { len := 10, tag := IPAC_IDTAG_UNITID (8), data := '313233342F302F3200'O } } } }
TC_pcu_socket_nsvc_ipv4-RSL-IPA(576)@e4c06fca6070: IPA ID RESP: { { len := 28, tag := IPAC_IDTAG_UNITNAME (1), data := '6F736D6F2D6274732D30322D34322D61632D31322D30392D313400'O }, { len := 10, tag := IPAC_IDTAG_UNITID (8), data := '313233342F302F3200'O } }
TC_pcu_socket_nsvc_ipv4-RSL(577)@e4c06fca6070: Got IDENTITY RESPONSE (conn_id=4): { { len := 28, tag := IPAC_IDTAG_UNITNAME (1), data := '6F736D6F2D6274732D30322D34322D61632D31322D30392D313400'O }, { len := 10, tag := IPAC_IDTAG_UNITID (8), data := '313233342F302F3200'O } }
TC_pcu_socket_nsvc_ipv4-RSL(577)@e4c06fca6070: Warning: The first argument (instr) of function regexp(), which is "1234/0/2" & char(0, 0, 0, 0), contains a character with zero character code at index 8. The rest of the string will be ignored during matching.
TC_pcu_socket_nsvc_ipv4-RSL(577)@e4c06fca6070: Mapped TRX#2 to TCP/IP conn_id=4
MTC@e4c06fca6070: 3/4 transceiver(s) connected
TC_pcu_socket_nsvc_ipv4-RSL-IPA(576)@e4c06fca6070: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit }
TC_pcu_socket_nsvc_ipv4-RSL-IPA(576)@e4c06fca6070: CCM Rx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit }
TC_pcu_socket_nsvc_ipv4-RSL-IPA(576)@e4c06fca6070: CCM Rx:{ msg_type := IPAC_MSGT_ID_RESP (5), u := { resp := { { len := 28, tag := IPAC_IDTAG_UNITNAME (1), data := '6F736D6F2D6274732D30322D34322D61632D31322D30392D313400'O }, { len := 10, tag := IPAC_IDTAG_UNITID (8), data := '313233342F302F3300'O } } } }
TC_pcu_socket_nsvc_ipv4-RSL-IPA(576)@e4c06fca6070: IPA ID RESP: { { len := 28, tag := IPAC_IDTAG_UNITNAME (1), data := '6F736D6F2D6274732D30322D34322D61632D31322D30392D313400'O }, { len := 10, tag := IPAC_IDTAG_UNITID (8), data := '313233342F302F3300'O } }
MTC@e4c06fca6070: 3/4 RF Resource Indication(s) received
TC_pcu_socket_nsvc_ipv4-RSL(577)@e4c06fca6070: Got IDENTITY RESPONSE (conn_id=5): { { len := 28, tag := IPAC_IDTAG_UNITNAME (1), data := '6F736D6F2D6274732D30322D34322D61632D31322D30392D313400'O }, { len := 10, tag := IPAC_IDTAG_UNITID (8), data := '313233342F302F3300'O } }
TC_pcu_socket_nsvc_ipv4-RSL(577)@e4c06fca6070: Warning: The first argument (instr) of function regexp(), which is "1234/0/3" & char(0, 0, 0, 0), contains a character with zero character code at index 8. The rest of the string will be ignored during matching.
TC_pcu_socket_nsvc_ipv4-RSL(577)@e4c06fca6070: Mapped TRX#3 to TCP/IP conn_id=5
MTC@e4c06fca6070: 4/4 transceiver(s) connected
TC_pcu_socket_nsvc_ipv4-RSL-IPA(576)@e4c06fca6070: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit }
TC_pcu_socket_nsvc_ipv4-RSL-IPA(576)@e4c06fca6070: CCM Rx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit }
MTC@e4c06fca6070: 4/4 RF Resource Indication(s) received
MTC@e4c06fca6070: Setting RSL_SYSTEM_INFO_3 (3): { header := { l2_plen := { l2_plen := 18, zero_one := '01'B }, skip_indicator := 0, rr_protocol_discriminator := 6, message_type := SYSTEM_INFORMATION_TYPE_3 (27) }, payload := { si3 := { cell_id := 23, lai := { mcc_mnc := '262F42'H, lac := 42 }, ctrl_chan_desc := { msc_r99 := true, att := true, bs_ag_blks_res := 1, ccch_conf := CCHAN_DESC_1CCCH_COMBINED (1), si22ind := false, cbq3 := CBQ3_IU_MODE_NOT_SUPPORTED (0), spare := '00'B, bs_pa_mfrms := 0, t3212 := 1 }, cell_options := { dn_ind := false, pwrc := false, dtx := MS_MAY_USE_UL_DTX (0), radio_link_tout_div4 := 7 }, cell_sel_par := { cell_resel_hyst_2dB := 2, ms_txpwr_max_cch := 7, acs := '0'B, neci := true, rxlev_access_min := 0 }, rach_control := { max_retrans := RACH_MAX_RETRANS_7 (3), tx_integer := '1001'B, cell_barr_access := false, re_not_allowed := true, acc := '0000010000000000'B }, rest_octets := { sel_params := { presence := '0'B, params := omit }, pwr_offset := { presence := '0'B, offset := omit }, si_2ter_ind := '0'B, early_cm_ind := '0'B, sched_where := { presence := '0'B, where := omit }, gprs_ind := { presence := '1'B, ind := { ra_colour := 0, si13_pos := '0'B } }, umts_early_cm_ind := '0'B, si2_quater_ind := { presence := '0'B, ind := omit }, iu_mode_ind := omit, si21_ind := { presence := '0'B, pos := omit } } } } }
MTC@e4c06fca6070: Setting RSL_SYSTEM_INFO_3 (3): '49061B001762F224002AC90001074740E504002C2B2B2B'O
MTC@e4c06fca6070: Setting RSL_SYSTEM_INFO_2 (2): { header := { l2_plen := { l2_plen := 22, zero_one := '01'B }, skip_indicator := 0, rr_protocol_discriminator := 6, message_type := SYSTEM_INFORMATION_TYPE_2 (26) }, payload := { si2 := { bcch_freq_list := '00000000000000000000000000000000'O, ncc_permitted := '11111111'B, rach_control := { max_retrans := RACH_MAX_RETRANS_7 (3), tx_integer := '1001'B, cell_barr_access := false, re_not_allowed := true, acc := '0000010000000000'B } } } }
MTC@e4c06fca6070: Setting RSL_SYSTEM_INFO_2 (2): '59061A00000000000000000000000000000000FFE50400'O
MTC@e4c06fca6070: Setting RSL_SYSTEM_INFO_4 (4): { header := { l2_plen := { l2_plen := 12, zero_one := '01'B }, skip_indicator := 0, rr_protocol_discriminator := 6, message_type := SYSTEM_INFORMATION_TYPE_4 (28) }, payload := { si4 := { lai := { mcc_mnc := '262F42'H, lac := 42 }, cell_sel_par := { cell_resel_hyst_2dB := 2, ms_txpwr_max_cch := 7, acs := '0'B, neci := true, rxlev_access_min := 0 }, rach_control := { max_retrans := RACH_MAX_RETRANS_7 (3), tx_integer := '1001'B, cell_barr_access := false, re_not_allowed := true, acc := '0000010000000000'B }, cbch_chan_desc := omit, cbch_mobile_alloc := omit, rest_octets := { sel_params := { presence := '0'B, params := omit }, pwr_offset := { presence := '0'B, offset := omit }, gprs_ind := { presence := '1'B, ind := { ra_colour := 0, si13_pos := '0'B } }, s_presence := '0'B, s := omit } } } }
MTC@e4c06fca6070: Setting RSL_SYSTEM_INFO_4 (4): '31061C62F224002A4740E50400012B2B2B2B2B2B2B2B2B'O
MTC@e4c06fca6070: Warning: dec_TrxcMessage(): Data remained at the end of the stream after successful decoding: '00'O
MTC@e4c06fca6070: Warning: dec_TrxcMessage(): Data remained at the end of the stream after successful decoding: '00'O
MTC@e4c06fca6070: Warning: dec_TrxcMessage(): Data remained at the end of the stream after successful decoding: '00'O
MTC@e4c06fca6070: setverdict(pass): none -> pass
TC_pcu_socket_nsvc_ipv4-RSL(577)@e4c06fca6070: Final verdict of PTC: none
TC_pcu_socket_nsvc_ipv4-RSL-IPA(576)@e4c06fca6070: Final verdict of PTC: none
IPA-CTRL-CLI-IPA(578)@e4c06fca6070: Final verdict of PTC: none
MTC@e4c06fca6070: Setting final verdict of the test case.
MTC@e4c06fca6070: Local verdict of MTC: pass
MTC@e4c06fca6070: Local verdict of PTC TC_pcu_socket_nsvc_ipv4-RSL-IPA(576): none (pass -> pass)
MTC@e4c06fca6070: Local verdict of PTC TC_pcu_socket_nsvc_ipv4-RSL(577): none (pass -> pass)
MTC@e4c06fca6070: Local verdict of PTC IPA-CTRL-CLI-IPA(578): none (pass -> pass)
MTC@e4c06fca6070: Test case TC_pcu_socket_nsvc_ipv4 finished. Verdict: pass
MTC@e4c06fca6070: Starting external command `../ttcn3-tcpdump-stop.sh BTS_Tests.TC_pcu_socket_nsvc_ipv4 pass'.
Mon Nov 28 13:05:03 UTC 2022
[1;32m====== BTS_Tests.TC_pcu_socket_nsvc_ipv4 pass ======[0m
Saving talloc report from 172.18.9.20:4241 to BTS_Tests.TC_pcu_socket_nsvc_ipv4.talloc
Waiting for packet dumper to finish... 0 (prev_count=-1, count=3250273)
Waiting for packet dumper to finish... 1 (prev_count=3250273, count=3255973)
MTC@e4c06fca6070: External command `../ttcn3-tcpdump-stop.sh BTS_Tests.TC_pcu_socket_nsvc_ipv4 pass' was executed successfully (exit status: 0).
MTC@e4c06fca6070: Starting external command `../ttcn3-tcpdump-start.sh BTS_Tests.TC_pcu_socket_nsvc_ipv6'.
------ BTS_Tests.TC_pcu_socket_nsvc_ipv6 ------
Mon Nov 28 13:05:08 UTC 2022
NOTE: unable to use dumpcap due to missing capabilities or suid bit
Waiting for packet dumper to start... 0
MTC@e4c06fca6070: External command `../ttcn3-tcpdump-start.sh BTS_Tests.TC_pcu_socket_nsvc_ipv6' was executed successfully (exit status: 0).
MTC@e4c06fca6070: Test case TC_pcu_socket_nsvc_ipv6 started.
TC_pcu_socket_nsvc_ipv6-RSL-IPA(579)@e4c06fca6070: Established a new IPA connection (conn_id=2)
TC_pcu_socket_nsvc_ipv6-RSL-IPA(579)@e4c06fca6070: 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) } } } }
TC_pcu_socket_nsvc_ipv6-RSL(580)@e4c06fca6070: A new IPA/RSL connection has been established (conn_id=2), waiting for IDENTITY RESPONSE...
TC_pcu_socket_nsvc_ipv6-RSL-IPA(579)@e4c06fca6070: CCM Rx:{ msg_type := IPAC_MSGT_ID_RESP (5), u := { resp := { { len := 28, tag := IPAC_IDTAG_UNITNAME (1), data := '6F736D6F2D6274732D30322D34322D61632D31322D30392D313400'O }, { len := 10, tag := IPAC_IDTAG_UNITID (8), data := '313233342F302F3000'O } } } }
TC_pcu_socket_nsvc_ipv6-RSL-IPA(579)@e4c06fca6070: IPA ID RESP: { { len := 28, tag := IPAC_IDTAG_UNITNAME (1), data := '6F736D6F2D6274732D30322D34322D61632D31322D30392D313400'O }, { len := 10, tag := IPAC_IDTAG_UNITID (8), data := '313233342F302F3000'O } }
TC_pcu_socket_nsvc_ipv6-RSL-IPA(579)@e4c06fca6070: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit }
TC_pcu_socket_nsvc_ipv6-RSL(580)@e4c06fca6070: Got IDENTITY RESPONSE (conn_id=2): { { len := 28, tag := IPAC_IDTAG_UNITNAME (1), data := '6F736D6F2D6274732D30322D34322D61632D31322D30392D313400'O }, { len := 10, tag := IPAC_IDTAG_UNITID (8), data := '313233342F302F3000'O } }
TC_pcu_socket_nsvc_ipv6-RSL-IPA(579)@e4c06fca6070: CCM Rx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit }
TC_pcu_socket_nsvc_ipv6-RSL(580)@e4c06fca6070: Warning: The first argument (instr) of function regexp(), which is "1234/0/0" & char(0, 0, 0, 0), contains a character with zero character code at index 8. The rest of the string will be ignored during matching.
TC_pcu_socket_nsvc_ipv6-RSL(580)@e4c06fca6070: Mapped TRX#0 to TCP/IP conn_id=2
MTC@e4c06fca6070: 1/4 transceiver(s) connected
TC_pcu_socket_nsvc_ipv6-RSL-IPA(579)@e4c06fca6070: Established a new IPA connection (conn_id=3)
TC_pcu_socket_nsvc_ipv6-RSL-IPA(579)@e4c06fca6070: 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) } } } }
TC_pcu_socket_nsvc_ipv6-RSL(580)@e4c06fca6070: A new IPA/RSL connection has been established (conn_id=3), waiting for IDENTITY RESPONSE...
MTC@e4c06fca6070: 1/4 RF Resource Indication(s) received
TC_pcu_socket_nsvc_ipv6-RSL-IPA(579)@e4c06fca6070: CCM Rx:{ msg_type := IPAC_MSGT_ID_RESP (5), u := { resp := { { len := 28, tag := IPAC_IDTAG_UNITNAME (1), data := '6F736D6F2D6274732D30322D34322D61632D31322D30392D313400'O }, { len := 10, tag := IPAC_IDTAG_UNITID (8), data := '313233342F302F3100'O } } } }
TC_pcu_socket_nsvc_ipv6-RSL-IPA(579)@e4c06fca6070: IPA ID RESP: { { len := 28, tag := IPAC_IDTAG_UNITNAME (1), data := '6F736D6F2D6274732D30322D34322D61632D31322D30392D313400'O }, { len := 10, tag := IPAC_IDTAG_UNITID (8), data := '313233342F302F3100'O } }
TC_pcu_socket_nsvc_ipv6-RSL-IPA(579)@e4c06fca6070: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit }
TC_pcu_socket_nsvc_ipv6-RSL(580)@e4c06fca6070: Got IDENTITY RESPONSE (conn_id=3): { { len := 28, tag := IPAC_IDTAG_UNITNAME (1), data := '6F736D6F2D6274732D30322D34322D61632D31322D30392D313400'O }, { len := 10, tag := IPAC_IDTAG_UNITID (8), data := '313233342F302F3100'O } }
TC_pcu_socket_nsvc_ipv6-RSL(580)@e4c06fca6070: Warning: The first argument (instr) of function regexp(), which is "1234/0/1" & char(0, 0, 0, 0), contains a character with zero character code at index 8. The rest of the string will be ignored during matching.
TC_pcu_socket_nsvc_ipv6-RSL(580)@e4c06fca6070: Mapped TRX#1 to TCP/IP conn_id=3
TC_pcu_socket_nsvc_ipv6-RSL-IPA(579)@e4c06fca6070: CCM Rx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit }
MTC@e4c06fca6070: 2/4 transceiver(s) connected
TC_pcu_socket_nsvc_ipv6-RSL-IPA(579)@e4c06fca6070: Established a new IPA connection (conn_id=4)
TC_pcu_socket_nsvc_ipv6-RSL-IPA(579)@e4c06fca6070: 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) } } } }
TC_pcu_socket_nsvc_ipv6-RSL(580)@e4c06fca6070: A new IPA/RSL connection has been established (conn_id=4), waiting for IDENTITY RESPONSE...
MTC@e4c06fca6070: 2/4 RF Resource Indication(s) received
TC_pcu_socket_nsvc_ipv6-RSL-IPA(579)@e4c06fca6070: Established a new IPA connection (conn_id=5)
TC_pcu_socket_nsvc_ipv6-RSL-IPA(579)@e4c06fca6070: 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) } } } }
TC_pcu_socket_nsvc_ipv6-RSL-IPA(579)@e4c06fca6070: CCM Rx:{ msg_type := IPAC_MSGT_ID_RESP (5), u := { resp := { { len := 28, tag := IPAC_IDTAG_UNITNAME (1), data := '6F736D6F2D6274732D30322D34322D61632D31322D30392D313400'O }, { len := 10, tag := IPAC_IDTAG_UNITID (8), data := '313233342F302F3200'O } } } }
TC_pcu_socket_nsvc_ipv6-RSL-IPA(579)@e4c06fca6070: IPA ID RESP: { { len := 28, tag := IPAC_IDTAG_UNITNAME (1), data := '6F736D6F2D6274732D30322D34322D61632D31322D30392D313400'O }, { len := 10, tag := IPAC_IDTAG_UNITID (8), data := '313233342F302F3200'O } }
TC_pcu_socket_nsvc_ipv6-RSL-IPA(579)@e4c06fca6070: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit }
TC_pcu_socket_nsvc_ipv6-RSL-IPA(579)@e4c06fca6070: CCM Rx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit }
TC_pcu_socket_nsvc_ipv6-RSL(580)@e4c06fca6070: A new IPA/RSL connection has been established (conn_id=5), waiting for IDENTITY RESPONSE...
TC_pcu_socket_nsvc_ipv6-RSL(580)@e4c06fca6070: Got IDENTITY RESPONSE (conn_id=4): { { len := 28, tag := IPAC_IDTAG_UNITNAME (1), data := '6F736D6F2D6274732D30322D34322D61632D31322D30392D313400'O }, { len := 10, tag := IPAC_IDTAG_UNITID (8), data := '313233342F302F3200'O } }
TC_pcu_socket_nsvc_ipv6-RSL(580)@e4c06fca6070: Warning: The first argument (instr) of function regexp(), which is "1234/0/2" & char(0, 0, 0, 0), contains a character with zero character code at index 8. The rest of the string will be ignored during matching.
TC_pcu_socket_nsvc_ipv6-RSL(580)@e4c06fca6070: Mapped TRX#2 to TCP/IP conn_id=4
MTC@e4c06fca6070: 3/4 transceiver(s) connected
TC_pcu_socket_nsvc_ipv6-RSL-IPA(579)@e4c06fca6070: CCM Rx:{ msg_type := IPAC_MSGT_ID_RESP (5), u := { resp := { { len := 28, tag := IPAC_IDTAG_UNITNAME (1), data := '6F736D6F2D6274732D30322D34322D61632D31322D30392D313400'O }, { len := 10, tag := IPAC_IDTAG_UNITID (8), data := '313233342F302F3300'O } } } }
MTC@e4c06fca6070: 3/4 RF Resource Indication(s) received
TC_pcu_socket_nsvc_ipv6-RSL-IPA(579)@e4c06fca6070: IPA ID RESP: { { len := 28, tag := IPAC_IDTAG_UNITNAME (1), data := '6F736D6F2D6274732D30322D34322D61632D31322D30392D313400'O }, { len := 10, tag := IPAC_IDTAG_UNITID (8), data := '313233342F302F3300'O } }
TC_pcu_socket_nsvc_ipv6-RSL(580)@e4c06fca6070: Got IDENTITY RESPONSE (conn_id=5): { { len := 28, tag := IPAC_IDTAG_UNITNAME (1), data := '6F736D6F2D6274732D30322D34322D61632D31322D30392D313400'O }, { len := 10, tag := IPAC_IDTAG_UNITID (8), data := '313233342F302F3300'O } }
TC_pcu_socket_nsvc_ipv6-RSL(580)@e4c06fca6070: Warning: The first argument (instr) of function regexp(), which is "1234/0/3" & char(0, 0, 0, 0), contains a character with zero character code at index 8. The rest of the string will be ignored during matching.
TC_pcu_socket_nsvc_ipv6-RSL(580)@e4c06fca6070: Mapped TRX#3 to TCP/IP conn_id=5
TC_pcu_socket_nsvc_ipv6-RSL-IPA(579)@e4c06fca6070: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit }
MTC@e4c06fca6070: 4/4 transceiver(s) connected
TC_pcu_socket_nsvc_ipv6-RSL-IPA(579)@e4c06fca6070: CCM Rx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit }
MTC@e4c06fca6070: 4/4 RF Resource Indication(s) received
MTC@e4c06fca6070: Setting RSL_SYSTEM_INFO_3 (3): { header := { l2_plen := { l2_plen := 18, zero_one := '01'B }, skip_indicator := 0, rr_protocol_discriminator := 6, message_type := SYSTEM_INFORMATION_TYPE_3 (27) }, payload := { si3 := { cell_id := 23, lai := { mcc_mnc := '262F42'H, lac := 42 }, ctrl_chan_desc := { msc_r99 := true, att := true, bs_ag_blks_res := 1, ccch_conf := CCHAN_DESC_1CCCH_COMBINED (1), si22ind := false, cbq3 := CBQ3_IU_MODE_NOT_SUPPORTED (0), spare := '00'B, bs_pa_mfrms := 0, t3212 := 1 }, cell_options := { dn_ind := false, pwrc := false, dtx := MS_MAY_USE_UL_DTX (0), radio_link_tout_div4 := 7 }, cell_sel_par := { cell_resel_hyst_2dB := 2, ms_txpwr_max_cch := 7, acs := '0'B, neci := true, rxlev_access_min := 0 }, rach_control := { max_retrans := RACH_MAX_RETRANS_7 (3), tx_integer := '1001'B, cell_barr_access := false, re_not_allowed := true, acc := '0000010000000000'B }, rest_octets := { sel_params := { presence := '0'B, params := omit }, pwr_offset := { presence := '0'B, offset := omit }, si_2ter_ind := '0'B, early_cm_ind := '0'B, sched_where := { presence := '0'B, where := omit }, gprs_ind := { presence := '1'B, ind := { ra_colour := 0, si13_pos := '0'B } }, umts_early_cm_ind := '0'B, si2_quater_ind := { presence := '0'B, ind := omit }, iu_mode_ind := omit, si21_ind := { presence := '0'B, pos := omit } } } } }
MTC@e4c06fca6070: Setting RSL_SYSTEM_INFO_3 (3): '49061B001762F224002AC90001074740E504002C2B2B2B'O
MTC@e4c06fca6070: Setting RSL_SYSTEM_INFO_2 (2): { header := { l2_plen := { l2_plen := 22, zero_one := '01'B }, skip_indicator := 0, rr_protocol_discriminator := 6, message_type := SYSTEM_INFORMATION_TYPE_2 (26) }, payload := { si2 := { bcch_freq_list := '00000000000000000000000000000000'O, ncc_permitted := '11111111'B, rach_control := { max_retrans := RACH_MAX_RETRANS_7 (3), tx_integer := '1001'B, cell_barr_access := false, re_not_allowed := true, acc := '0000010000000000'B } } } }
MTC@e4c06fca6070: Setting RSL_SYSTEM_INFO_2 (2): '59061A00000000000000000000000000000000FFE50400'O
MTC@e4c06fca6070: Setting RSL_SYSTEM_INFO_4 (4): { header := { l2_plen := { l2_plen := 12, zero_one := '01'B }, skip_indicator := 0, rr_protocol_discriminator := 6, message_type := SYSTEM_INFORMATION_TYPE_4 (28) }, payload := { si4 := { lai := { mcc_mnc := '262F42'H, lac := 42 }, cell_sel_par := { cell_resel_hyst_2dB := 2, ms_txpwr_max_cch := 7, acs := '0'B, neci := true, rxlev_access_min := 0 }, rach_control := { max_retrans := RACH_MAX_RETRANS_7 (3), tx_integer := '1001'B, cell_barr_access := false, re_not_allowed := true, acc := '0000010000000000'B }, cbch_chan_desc := omit, cbch_mobile_alloc := omit, rest_octets := { sel_params := { presence := '0'B, params := omit }, pwr_offset := { presence := '0'B, offset := omit }, gprs_ind := { presence := '1'B, ind := { ra_colour := 0, si13_pos := '0'B } }, s_presence := '0'B, s := omit } } } }
MTC@e4c06fca6070: Setting RSL_SYSTEM_INFO_4 (4): '31061C62F224002A4740E50400012B2B2B2B2B2B2B2B2B'O
MTC@e4c06fca6070: Warning: dec_TrxcMessage(): Data remained at the end of the stream after successful decoding: '00'O
MTC@e4c06fca6070: Warning: dec_TrxcMessage(): Data remained at the end of the stream after successful decoding: '00'O
MTC@e4c06fca6070: Warning: dec_TrxcMessage(): Data remained at the end of the stream after successful decoding: '00'O
MTC@e4c06fca6070: setverdict(pass): none -> pass
TC_pcu_socket_nsvc_ipv6-RSL(580)@e4c06fca6070: Final verdict of PTC: none
IPA-CTRL-CLI-IPA(581)@e4c06fca6070: Final verdict of PTC: none
TC_pcu_socket_nsvc_ipv6-RSL-IPA(579)@e4c06fca6070: Final verdict of PTC: none
MTC@e4c06fca6070: Setting final verdict of the test case.
MTC@e4c06fca6070: Local verdict of MTC: pass
MTC@e4c06fca6070: Local verdict of PTC TC_pcu_socket_nsvc_ipv6-RSL-IPA(579): none (pass -> pass)
MTC@e4c06fca6070: Local verdict of PTC TC_pcu_socket_nsvc_ipv6-RSL(580): none (pass -> pass)
MTC@e4c06fca6070: Local verdict of PTC IPA-CTRL-CLI-IPA(581): none (pass -> pass)
MTC@e4c06fca6070: Test case TC_pcu_socket_nsvc_ipv6 finished. Verdict: pass
MTC@e4c06fca6070: Starting external command `../ttcn3-tcpdump-stop.sh BTS_Tests.TC_pcu_socket_nsvc_ipv6 pass'.
Mon Nov 28 13:05:14 UTC 2022
[1;32m====== BTS_Tests.TC_pcu_socket_nsvc_ipv6 pass ======[0m
Saving talloc report from 172.18.9.20:4241 to BTS_Tests.TC_pcu_socket_nsvc_ipv6.talloc
ERROR: failed to get talloc report via vty
Waiting for packet dumper to finish... 0 (prev_count=-1, count=2757195)
gzip: /data/BTS_Tests.TC_pcu_socket_nsvc_ipv6.pcap.gz: No space left on device
Fatal error during logging: Writing to log file failed. (No space left on device) Exiting.
Error: Unexpected end of MTC connection from e4c06fca6070 [172.18.9.10].
MC@e4c06fca6070: The control connection to MTC is lost. Destroying all PTC connections.
MC@e4c06fca6070: MTC terminated.
ttcn3_start: error: the MTC terminated unexpectedly
exit
MC@e4c06fca6070: Shutting down session.
MC@e4c06fca6070: Shutdown complete.
Fatal error during logging: Writing to log file failed. (No space left on device) Exiting.
Comparing expected results '/osmo-ttcn3-hacks/bts/expected-results.xml' against results in 'junit-xml-21.log'
--------------------
Summary:
  [1;34mskip[0;m: 222
+ exit_code=0
+ /osmo-ttcn3-hacks/log_merge.sh BTS_Tests --rm
Generated BTS_Tests.TC_chan_act_react.merged
FATAL: command execution failed
java.io.EOFException
    at java.base/java.io.ObjectInputStream$PeekInputStream.readFully(ObjectInputStream.java:2905)
    at java.base/java.io.ObjectInputStream$BlockDataInputStream.readShort(ObjectInputStream.java:3400)
    at java.base/java.io.ObjectInputStream.readStreamHeader(ObjectInputStream.java:936)
    at java.base/java.io.ObjectInputStream.<init>(ObjectInputStream.java:379)
    at hudson.remoting.ObjectInputStreamEx.<init>(ObjectInputStreamEx.java:49)
    at hudson.remoting.Command.readFrom(Command.java:142)
    at hudson.remoting.Command.readFrom(Command.java:128)
    at hudson.remoting.AbstractSynchronousByteArrayCommandTransport.read(AbstractSynchronousByteArrayCommandTransport.java:35)
    at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:61)
Caused: java.io.IOException: Unexpected termination of the channel
    at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:75)
Caused: java.io.IOException: Backing channel 'gtp0-deb9build' is disconnected.
    at hudson.remoting.RemoteInvocationHandler.channelOrFail(RemoteInvocationHandler.java:215)
    at hudson.remoting.RemoteInvocationHandler.invoke(RemoteInvocationHandler.java:285)
    at com.sun.proxy.$Proxy77.isAlive(Unknown Source)
    at hudson.Launcher$RemoteLauncher$ProcImpl.isAlive(Launcher.java:1215)
    at hudson.Launcher$RemoteLauncher$ProcImpl.join(Launcher.java:1207)
    at hudson.tasks.CommandInterpreter.join(CommandInterpreter.java:195)
    at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:145)
    at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:92)
    at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
    at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:814)
    at hudson.model.Build$BuildExecution.build(Build.java:199)
    at hudson.model.Build$BuildExecution.doRun(Build.java:164)
    at hudson.model.AbstractBuild$AbstractBuildExecution.run(AbstractBuild.java:522)
    at hudson.model.Run.execute(Run.java:1896)
    at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:44)
    at hudson.model.ResourceController.execute(ResourceController.java:101)
    at hudson.model.Executor.run(Executor.java:442)
FATAL: Unable to delete script file /tmp/jenkins181056133916217001.sh
java.io.EOFException
    at java.base/java.io.ObjectInputStream$PeekInputStream.readFully(ObjectInputStream.java:2905)
    at java.base/java.io.ObjectInputStream$BlockDataInputStream.readShort(ObjectInputStream.java:3400)
    at java.base/java.io.ObjectInputStream.readStreamHeader(ObjectInputStream.java:936)
    at java.base/java.io.ObjectInputStream.<init>(ObjectInputStream.java:379)
    at hudson.remoting.ObjectInputStreamEx.<init>(ObjectInputStreamEx.java:49)
    at hudson.remoting.Command.readFrom(Command.java:142)
    at hudson.remoting.Command.readFrom(Command.java:128)
    at hudson.remoting.AbstractSynchronousByteArrayCommandTransport.read(AbstractSynchronousByteArrayCommandTransport.java:35)
    at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:61)
Caused: java.io.IOException: Unexpected termination of the channel
    at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:75)
Caused: hudson.remoting.ChannelClosedException: Channel "hudson.remoting.Channel@145206d7:gtp0-deb9build": Remote call on gtp0-deb9build failed. The channel is closing down or has closed down
    at hudson.remoting.Channel.call(Channel.java:993)
    at hudson.FilePath.act(FilePath.java:1194)
    at hudson.FilePath.act(FilePath.java:1183)
    at hudson.FilePath.delete(FilePath.java:1730)
    at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:163)
    at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:92)
    at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
    at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:814)
    at hudson.model.Build$BuildExecution.build(Build.java:199)
    at hudson.model.Build$BuildExecution.doRun(Build.java:164)
    at hudson.model.AbstractBuild$AbstractBuildExecution.run(AbstractBuild.java:522)
    at hudson.model.Run.execute(Run.java:1896)
    at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:44)
    at hudson.model.ResourceController.execute(ResourceController.java:101)
    at hudson.model.Executor.run(Executor.java:442)
Build step 'Execute shell' marked build as failure
ERROR: Step ‘Publish JUnit test result report’ failed: no workspace for TTCN3-centos-bts-test-2022q2 #160

2025

2024

2023

2022

2021

2020

2019

Build failed in Jenkins: TTCN3-centos-bts-test-2022q2 #160