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