See <https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-sccplite-latest/2365…>
Changes:
[Pau Espin Pedrol] bsc: generic: Introduce test TC_stat_msc_sctp_disconnected
[Pau Espin Pedrol] stp: Support expect RFC466 4.5.1 DUNA after ASP ACT
------------------------------------------
[...truncated 5.79 MiB...]
[1;31mDABIS[0;m abis.c:429 E1L(0) Signalling link down
[1;33mDABIS[0;m abis.c:170 A-bis connection establishment to BSC (127.0.0.1) in progress...
[38;5;23m[1;33mDLINP[0;m[38;5;23m input/ipaccess.c:1020 E1TS(0:1) enabling ipaccess BTS mode, OML connecting to 127.0.0.1:3002[0;m
[38;5;23m[1;31mDLINP[0;m[38;5;23m stream_cli.c:423 CLICONN(ts-0-1-oml,){CONNECTING} connect failed (-111)[0;m
[1;31mDABIS[0;m abis.c:429 E1L(0) Signalling link down
[1;33mDABIS[0;m abis.c:170 A-bis connection establishment to BSC (127.0.0.1) in progress...
[38;5;23m[1;33mDLINP[0;m[38;5;23m input/ipaccess.c:1020 E1TS(0:1) enabling ipaccess BTS mode, OML connecting to 127.0.0.1:3002[0;m
[38;5;23m[1;31mDLINP[0;m[38;5;23m stream_cli.c:423 CLICONN(ts-0-1-oml,){CONNECTING} connect failed (-111)[0;m
[1;31mDABIS[0;m abis.c:429 E1L(0) Signalling link down
[1;33mDABIS[0;m abis.c:170 A-bis connection establishment to BSC (127.0.0.1) in progress...
[38;5;23m[1;33mDLINP[0;m[38;5;23m input/ipaccess.c:1020 E1TS(0:1) enabling ipaccess BTS mode, OML connecting to 127.0.0.1:3002[0;m
[38;5;23m[1;31mDLINP[0;m[38;5;23m stream_cli.c:423 CLICONN(ts-0-1-oml,){CONNECTING} connect failed (-111)[0;m
[1;31mDABIS[0;m abis.c:429 E1L(0) Signalling link down
[1;33mDABIS[0;m abis.c:170 A-bis connection establishment to BSC (127.0.0.1) in progress...
[38;5;23m[1;33mDLINP[0;m[38;5;23m input/ipaccess.c:1020 E1TS(0:1) enabling ipaccess BTS mode, OML connecting to 127.0.0.1:3002[0;m
[38;5;23m[1;31mDLINP[0;m[38;5;23m stream_cli.c:423 CLICONN(ts-0-1-oml,){CONNECTING} connect failed (-111)[0;m
[1;31mDABIS[0;m abis.c:429 E1L(0) Signalling link down
[1;33mDABIS[0;m abis.c:170 A-bis connection establishment to BSC (127.0.0.1) in progress...
[38;5;23m[1;33mDLINP[0;m[38;5;23m input/ipaccess.c:1020 E1TS(0:1) enabling ipaccess BTS mode, OML connecting to 127.0.0.1:3002[0;m
[38;5;23m[1;31mDLINP[0;m[38;5;23m stream_cli.c:423 CLICONN(ts-0-1-oml,){CONNECTING} connect failed (-111)[0;m
[1;31mDABIS[0;m abis.c:429 E1L(0) Signalling link down
[1;33mDABIS[0;m abis.c:170 A-bis connection establishment to BSC (127.0.0.1) in progress...
[38;5;23m[1;33mDLINP[0;m[38;5;23m input/ipaccess.c:1020 E1TS(0:1) enabling ipaccess BTS mode, OML connecting to 127.0.0.1:3002[0;m
[38;5;23m[1;31mDLINP[0;m[38;5;23m stream_cli.c:423 CLICONN(ts-0-1-oml,){CONNECTING} connect failed (-111)[0;m
[1;31mDABIS[0;m abis.c:429 E1L(0) Signalling link down
MTC@5dafcdb8a73d: Timeout T_guard: 30 s
MTC@5dafcdb8a73d: setverdict(fail): pass -> fail reason: "Timeout of T_guard", new component reason: "Timeout of T_guard"
MTC@5dafcdb8a73d: Stopping test component execution.
MTC@5dafcdb8a73d: Test case TC_si2quater_3_earfcns was stopped.
MTC@5dafcdb8a73d: Terminating component type BSC_Tests.test_CT.
MTC@5dafcdb8a73d: Default with id 1 (altstep as_Tguard) was deactivated.
MTC@5dafcdb8a73d: Message with id 4 was extracted from the queue of IPA_CTRL.
MTC@5dafcdb8a73d: Port IPA_CTRL was stopped.
MTC@5dafcdb8a73d: Port RSL_CCHAN[0] was stopped.
MTC@5dafcdb8a73d: Port RSL_CCHAN[1] was stopped.
MTC@5dafcdb8a73d: Port RSL_CCHAN[2] was stopped.
MTC@5dafcdb8a73d: Removing unterminated connection between port IPA_RSL[0][0] and IPA-BTS0-TRX0-RSL-IPA(104):IPA_RSL_PORT.
MTC@5dafcdb8a73d: Message with id 18 was extracted from the queue of IPA_RSL[0][0].
IPA-BTS0-TRX0-RSL-IPA(104)@5dafcdb8a73d: Connection of port IPA_RSL_PORT to mtc:IPA_RSL[0][0] was closed unexpectedly by the peer.
IPA-BTS0-TRX0-RSL-IPA(104)@5dafcdb8a73d: Port IPA_RSL_PORT was disconnected from mtc:IPA_RSL[0][0].
MTC@5dafcdb8a73d: Port IPA_RSL[0][0] was stopped.
MTC@5dafcdb8a73d: Port IPA_RSL[0][1] was stopped.
MTC@5dafcdb8a73d: Port IPA_RSL[0][2] was stopped.
MTC@5dafcdb8a73d: Port IPA_RSL[0][3] was stopped.
MTC@5dafcdb8a73d: Port IPA_RSL[1][0] was stopped.
MTC@5dafcdb8a73d: Port IPA_RSL[1][1] was stopped.
MTC@5dafcdb8a73d: Port IPA_RSL[1][2] was stopped.
MTC@5dafcdb8a73d: Port IPA_RSL[1][3] was stopped.
MTC@5dafcdb8a73d: Port IPA_RSL[2][0] was stopped.
MTC@5dafcdb8a73d: Port IPA_RSL[2][1] was stopped.
MTC@5dafcdb8a73d: Port IPA_RSL[2][2] was stopped.
MTC@5dafcdb8a73d: Port IPA_RSL[2][3] was stopped.
MTC@5dafcdb8a73d: Port IPA was stopped.
MTC@5dafcdb8a73d: Port SCCPLITE_IPA_CTRL was stopped.
MTC@5dafcdb8a73d: Removing unterminated connection between port IPA_CFG_PORT[0][0] and IPA-BTS0-TRX0-RSL-IPA(104):CFG_PORT.
MTC@5dafcdb8a73d: Port IPA_CFG_PORT[0][0] was stopped.
IPA-BTS0-TRX0-RSL-IPA(104)@5dafcdb8a73d: Connection of port CFG_PORT to mtc:IPA_CFG_PORT[0][0] was closed unexpectedly by the peer.
MTC@5dafcdb8a73d: Port IPA_CFG_PORT[0][1] was stopped.
IPA-BTS0-TRX0-RSL-IPA(104)@5dafcdb8a73d: Port CFG_PORT was disconnected from mtc:IPA_CFG_PORT[0][0].
MTC@5dafcdb8a73d: Port IPA_CFG_PORT[0][2] was stopped.
MTC@5dafcdb8a73d: Port IPA_CFG_PORT[0][3] was stopped.
MTC@5dafcdb8a73d: Port IPA_CFG_PORT[1][0] was stopped.
MTC@5dafcdb8a73d: Port IPA_CFG_PORT[1][1] was stopped.
MTC@5dafcdb8a73d: Port IPA_CFG_PORT[1][2] was stopped.
MTC@5dafcdb8a73d: Port IPA_CFG_PORT[1][3] was stopped.
MTC@5dafcdb8a73d: Port IPA_CFG_PORT[2][0] was stopped.
MTC@5dafcdb8a73d: Port IPA_CFG_PORT[2][1] was stopped.
MTC@5dafcdb8a73d: Port IPA_CFG_PORT[2][2] was stopped.
MTC@5dafcdb8a73d: Port IPA_CFG_PORT[2][3] was stopped.
MTC@5dafcdb8a73d: Removing unterminated mapping between port BSCVTY and system:BSCVTY.
MTC@5dafcdb8a73d: Port BSCVTY was unmapped from system:BSCVTY.
MTC@5dafcdb8a73d: Message with id 41 was extracted from the queue of BSCVTY.
MTC@5dafcdb8a73d: Port BSCVTY was stopped.
MTC@5dafcdb8a73d: Port STPVTY was stopped.
MTC@5dafcdb8a73d: Removing unterminated connection between port SCCPLITE_IPA_CFG_PORT[0] and VirtMSC-IPA(100):CFG_PORT.
MTC@5dafcdb8a73d: Port SCCPLITE_IPA_CFG_PORT[0] was stopped.
VirtMSC-IPA(100)@5dafcdb8a73d: Connection of port CFG_PORT to mtc:SCCPLITE_IPA_CFG_PORT[0] was closed unexpectedly by the peer.
MTC@5dafcdb8a73d: Port SCCPLITE_IPA_CFG_PORT[1] was stopped.
VirtMSC-IPA(100)@5dafcdb8a73d: Port CFG_PORT was disconnected from mtc:SCCPLITE_IPA_CFG_PORT[0].
MTC@5dafcdb8a73d: Port SCCPLITE_IPA_CFG_PORT[2] was stopped.
MTC@5dafcdb8a73d: Removing unterminated connection between port BSSAP and VirtMSC-SCCP(99):SCCP_SP_PORT.
MTC@5dafcdb8a73d: Port BSSAP was stopped.
VirtMSC-SCCP(99)@5dafcdb8a73d: Connection of port SCCP_SP_PORT to mtc:BSSAP was closed unexpectedly by the peer.
MTC@5dafcdb8a73d: Port BSSAP_LE was stopped.
VirtMSC-SCCP(99)@5dafcdb8a73d: Port SCCP_SP_PORT was disconnected from mtc:BSSAP.
MTC@5dafcdb8a73d: Component type BSC_Tests.test_CT was shut down inside testcase TC_si2quater_3_earfcns.
MTC@5dafcdb8a73d: Waiting for PTCs to finish.
VirtMSC-SCCP(99)@5dafcdb8a73d: Kill was requested from MC.
VirtMSC-IPA-WAIT(101)@5dafcdb8a73d: Kill was requested from MC. Terminating idle PTC.
VirtMSC-IPA(100)@5dafcdb8a73d: Kill was requested from MC. Terminating idle PTC.
VirtMSC-STATS(98)@5dafcdb8a73d: Kill was requested from MC.
VirtMSC-IPA-WAIT(101)@5dafcdb8a73d: Terminating component type IPA_Emulation.IPA_EventWaiter_CT.
VirtMSC-IPA-WAIT(101)@5dafcdb8a73d: Port IPA_SP_PORT was stopped.
VirtMSC-SCCP(99)@5dafcdb8a73d: Terminating test component execution.
VirtMSC-IPA(100)@5dafcdb8a73d: Terminating component type IPA_Emulation.IPA_Emulation_CT.
VirtMGW-MGCP-0(103)@5dafcdb8a73d: Kill was requested from MC.
VirtMSC-IPA-WAIT(101)@5dafcdb8a73d: Component type IPA_Emulation.IPA_EventWaiter_CT was shut down inside testcase TC_si2quater_3_earfcns.
VirtMSC-STATS(98)@5dafcdb8a73d: Terminating test component execution.
VirtMSC-IPA(100)@5dafcdb8a73d: Removing unterminated mapping between port IPA_PORT and system:IPA_CODEC_PT.
VirtMSC-IPA-WAIT(101)@5dafcdb8a73d: Final verdict of PTC: pass
VirtMGW-MGCP-0(103)@5dafcdb8a73d: Terminating test component execution.
VirtMSC-IPA(100)@5dafcdb8a73d: Port IPA_PORT was unmapped from system:IPA_CODEC_PT.
VirtMSC-IPA(100)@5dafcdb8a73d: Port IPA_PORT was stopped.
VirtMSC-IPA(100)@5dafcdb8a73d: Port CFG_PORT was stopped.
VirtMSC-IPA(100)@5dafcdb8a73d: Removing unterminated connection between port MTP3_SP_PORT and VirtMSC-SCCP(99):MTP3_SCCP_PORT.
VirtMSC-IPA(100)@5dafcdb8a73d: Port MTP3_SP_PORT was stopped.
VirtMSC-IPA(100)@5dafcdb8a73d: Port IPA_MGCP_PORT was stopped.
VirtMSC-SCCP(99)@5dafcdb8a73d: Function SCCPStart was stopped. PTC terminates.
IPA-BTS0-TRX0-RSL-IPA(104)@5dafcdb8a73d: Kill was requested from MC. Terminating idle PTC.
VirtMSC-SCCP(99)@5dafcdb8a73d: Terminating component type SCCP_Emulation.SCCP_CT.
VirtMSC-IPA(100)@5dafcdb8a73d: Port IPA_RSL_PORT was stopped.
VirtMSC-SCCP(99)@5dafcdb8a73d: Port SCCP_SP_PORT was stopped.
VirtMGW-MGCP-0(103)@5dafcdb8a73d: Function main was stopped. PTC terminates.
VirtMSC-STATS(98)@5dafcdb8a73d: Function main was stopped. PTC terminates.
VirtMSC-SCCP(99)@5dafcdb8a73d: Removing unterminated connection between port MTP3_SCCP_PORT and VirtMSC-IPA(100):MTP3_SP_PORT.
VirtMSC-IPA(100)@5dafcdb8a73d: Port IPA_OML_PORT was stopped.
VirtMSC-IPA(100)@5dafcdb8a73d: Port IPA_CTRL_PORT was stopped.
IPA-BTS0-TRX0-RSL-IPA(104)@5dafcdb8a73d: Terminating component type IPA_Emulation.IPA_Emulation_CT.
VirtMGW-MGCP-0(103)@5dafcdb8a73d: Terminating component type MGCP_Emulation.MGCP_Emulation_CT.
VirtMSC-IPA(100)@5dafcdb8a73d: Port IPA_SP_PORT was stopped.
VirtMSC-SCCP(99)@5dafcdb8a73d: Port MTP3_SCCP_PORT was stopped.
VirtMGW-MGCP-0(103)@5dafcdb8a73d: Removing unterminated mapping between port MGCP and system:MGCP_CODEC_PT.
IPA-BTS0-TRX0-RSL-IPA(104)@5dafcdb8a73d: Removing unterminated mapping between port IPA_PORT and system:IPA_CODEC_PT.
VirtMSC-IPA(100)@5dafcdb8a73d: Component type IPA_Emulation.IPA_Emulation_CT was shut down inside testcase TC_si2quater_3_earfcns.
VirtMSC-STATS(98)@5dafcdb8a73d: Terminating component type StatsD_Checker.StatsD_Checker_CT.
VirtMSC-SCCP(99)@5dafcdb8a73d: Component type SCCP_Emulation.SCCP_CT was shut down inside testcase TC_si2quater_3_earfcns.
VirtMSC-STATS(98)@5dafcdb8a73d: Removing unterminated mapping between port STATSVTY and system:STATSVTY.
VirtMSC-IPA(100)@5dafcdb8a73d: Final verdict of PTC: none
VirtMSC-SCCP(99)@5dafcdb8a73d: Final verdict of PTC: none
VirtMSC-STATS(98)@5dafcdb8a73d: Port STATSVTY was unmapped from system:STATSVTY.
IPA-BTS0-TRX0-RSL-IPA(104)@5dafcdb8a73d: Port IPA_PORT was unmapped from system:IPA_CODEC_PT.
VirtMSC-STATS(98)@5dafcdb8a73d: Message with id 4 was extracted from the queue of STATSVTY.
IPA-BTS0-TRX0-RSL-IPA(104)@5dafcdb8a73d: Port IPA_PORT was stopped.
VirtMSC-STATS(98)@5dafcdb8a73d: Port STATSVTY was stopped.
VirtMSC-STATS(98)@5dafcdb8a73d: Port STATSD_PROC was stopped.
IPA-BTS0-TRX0-RSL-IPA(104)@5dafcdb8a73d: Port CFG_PORT was stopped.
VirtMSC-STATS(98)@5dafcdb8a73d: Removing unterminated mapping between port STATS and system:STATS.
IPA-BTS0-TRX0-RSL-IPA(104)@5dafcdb8a73d: Port MTP3_SP_PORT was stopped.
IPA-BTS0-TRX0-RSL-IPA(104)@5dafcdb8a73d: Port IPA_MGCP_PORT was stopped.
IPA-BTS0-TRX0-RSL-IPA(104)@5dafcdb8a73d: Port IPA_RSL_PORT was stopped.
VirtMGW-MGCP-0(103)@5dafcdb8a73d: Port MGCP was unmapped from system:MGCP_CODEC_PT.
IPA-BTS0-TRX0-RSL-IPA(104)@5dafcdb8a73d: Port IPA_OML_PORT was stopped.
VirtMGW-MGCP-0(103)@5dafcdb8a73d: Port MGCP was stopped.
IPA-BTS0-TRX0-RSL-IPA(104)@5dafcdb8a73d: Port IPA_CTRL_PORT was stopped.
VirtMGW-MGCP-0(103)@5dafcdb8a73d: Port MGCP_CLIENT was stopped.
VirtMGW-MGCP-0(103)@5dafcdb8a73d: Port MGCP_CLIENT_MULTI was stopped.
IPA-BTS0-TRX0-RSL-IPA(104)@5dafcdb8a73d: Port IPA_SP_PORT was stopped.
VirtMGW-MGCP-0(103)@5dafcdb8a73d: Port MGCP_PROC was stopped.
IPA-BTS0-TRX0-RSL-IPA(104)@5dafcdb8a73d: Component type IPA_Emulation.IPA_Emulation_CT was shut down inside testcase TC_si2quater_3_earfcns.
VirtMGW-MGCP-0(103)@5dafcdb8a73d: Component type MGCP_Emulation.MGCP_Emulation_CT was shut down inside testcase TC_si2quater_3_earfcns.
VirtMGW-MGCP-0(103)@5dafcdb8a73d: Final verdict of PTC: none
IPA-BTS0-TRX0-RSL-IPA(104)@5dafcdb8a73d: Final verdict of PTC: none
VirtMSC-STATS(98)@5dafcdb8a73d: Port STATS was unmapped from system:STATS.
VirtMSC-STATS(98)@5dafcdb8a73d: Port STATS was stopped.
VirtMSC-STATS(98)@5dafcdb8a73d: Component type StatsD_Checker.StatsD_Checker_CT was shut down inside testcase TC_si2quater_3_earfcns.
VirtMSC-STATS(98)@5dafcdb8a73d: Final verdict of PTC: none
MTC@5dafcdb8a73d: Setting final verdict of the test case.
MTC@5dafcdb8a73d: Local verdict of MTC: fail reason: "Timeout of T_guard"
MTC@5dafcdb8a73d: Local verdict of PTC VirtMSC-STATS(98): none (fail -> fail)
MTC@5dafcdb8a73d: Local verdict of PTC VirtMSC-SCCP(99): none (fail -> fail)
MTC@5dafcdb8a73d: Local verdict of PTC VirtMSC-IPA(100): none (fail -> fail)
MTC@5dafcdb8a73d: Local verdict of PTC VirtMSC-IPA-WAIT(101): pass (fail -> fail)
MTC@5dafcdb8a73d: Local verdict of PTC IPA-CTRL-CLI-IPA(102): none (fail -> fail)
MTC@5dafcdb8a73d: Local verdict of PTC VirtMGW-MGCP-0(103): none (fail -> fail)
MTC@5dafcdb8a73d: Local verdict of PTC IPA-BTS0-TRX0-RSL-IPA(104): none (fail -> fail)
MTC@5dafcdb8a73d: Test case TC_si2quater_3_earfcns finished. Verdict: fail reason: Timeout of T_guard
MTC@5dafcdb8a73d: Starting external command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-sccplite-latest/ws/t…> BSC_Tests.TC_si2quater_3_earfcns fail'.
Fri Jul 4 13:43:42 UTC 2025
[1;31m------ BSC_Tests.TC_si2quater_3_earfcns fail ------[0m
Saving talloc report from 127.0.0.1:4242 to BSC_Tests.TC_si2quater_3_earfcns.talloc
Traceback (most recent call last):
File "/usr/local/lib/python3.11/dist-packages/osmopy/osmo_interact/common.py", line 420, in main_run_commands
interact.connect()
File "/usr/local/lib/python3.11/dist-packages/osmopy/osmo_interact/vty.py", line 84, in connect
super().connect()
File "/usr/local/lib/python3.11/dist-packages/osmopy/osmo_interact/common.py", line 88, in connect
self.socket.connect((self.host, int(self.port)))
ConnectionRefusedError: [Errno 111] Connection refused
Waiting for packet dumper to finish... 0 (prev_count=-1, count=145152)
Waiting for packet dumper to finish... 1 (prev_count=145152, count=146328)
[1;33mDABIS[0;m abis.c:170 A-bis connection establishment to BSC (127.0.0.1) in progress...
[38;5;23m[1;33mDLINP[0;m[38;5;23m input/ipaccess.c:1020 E1TS(0:1) enabling ipaccess BTS mode, OML connecting to 127.0.0.1:3002[0;m
[1;33mDABIS[0;m abis.c:170 A-bis connection establishment to BSC (127.0.0.1) in progress...
[38;5;23m[1;33mDLINP[0;m[38;5;23m input/ipaccess.c:1020 E1TS(0:1) enabling ipaccess BTS mode, OML connecting to 127.0.0.1:3002[0;m
[38;5;23m[1;31mDLINP[0;m[38;5;23m stream_cli.c:423 CLICONN(ts-0-1-oml,){CONNECTING} connect failed (-111)[0;m
[1;31mDABIS[0;m abis.c:429 E1L(0) Signalling link down
[38;5;23m[1;31mDLINP[0;m[38;5;23m stream_cli.c:423 CLICONN(ts-0-1-oml,){CONNECTING} connect failed (-111)[0;m
[1;31mDABIS[0;m abis.c:429 E1L(0) Signalling link down
[1;33mDABIS[0;m abis.c:170 A-bis connection establishment to BSC (127.0.0.1) in progress...
[38;5;23m[1;33mDLINP[0;m[38;5;23m input/ipaccess.c:1020 E1TS(0:1) enabling ipaccess BTS mode, OML connecting to 127.0.0.1:3002[0;m
[38;5;23m[1;31mDLINP[0;m[38;5;23m stream_cli.c:423 CLICONN(ts-0-1-oml,){CONNECTING} connect failed (-111)[0;m
[1;31mDABIS[0;m abis.c:429 E1L(0) Signalling link down
MTC@5dafcdb8a73d: External command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-sccplite-latest/ws/t…> BSC_Tests.TC_si2quater_3_earfcns fail' was executed successfully (exit status: 0).
MTC@5dafcdb8a73d: Starting external command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-sccplite-latest/ws/t…> BSC_Tests.TC_si2quater_4_earfcns'.
------ BSC_Tests.TC_si2quater_4_earfcns ------
Fri Jul 4 13:43:47 UTC 2025
/usr/bin/dumpcap -q -s 1520 -n -i any -w "<https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-sccplite-latest/2365…"> ><https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-sccplite-latest/2365…> 2>/tmp/cmderr &
Waiting for packet dumper to start... 0
[1;34m[testenv][sccplite] Running gdb to get a backtrace[0m
[0;94m[testenv][sccplite] + ['podman', 'exec', '-e', 'CCACHE_DIR=/home/osmocom-build/ccache/testenv', '-e', 'TESTENV_CACHE_DIR=<https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-sccplite-latest/ws/_…',> '-e', 'TESTENV_SRC_DIR=/home/osmocom-build/jenkins/workspace', '-e', 'TESTENV_INSTALL_DIR=/', '-e', 'TERM=dumb', '-e', 'PATH=<https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-sccplite-latest/ws/_…>:<https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-sccplite-latest/ws/_…>:<https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-sccplite-latest/ws/b…>:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin', '-e', 'HOME=/home/osmocom-build', '-t', 'testenv-bsc-sccplite-osmocom-latest-20250704-1340-263fd302-0', 'sh', '-c', 'gdb --batch /usr/bin/osmo-bsc <https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-sccplite-latest/2365…> -ex bt | tee <https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-sccplite-latest/2365…'][0m>
[New LWP 9899]
77 ./src/core/rbtree.c: No such file or directory.
[Thread debugging using libthread_db enabled]
Using host libthread_db library "/lib/x86_64-linux-gnu/libthread_db.so.1".
Core was generated by `osmo-bsc -c osmo-bsc-sccplite.cfg'.
Program terminated with signal SIGSEGV, Segmentation fault.
#0 rb_insert_color (node=node@entry=0x55beebd20520, root=0x7f2a69175c40) at ./src/core/rbtree.c:77
#0 rb_insert_color (node=node@entry=0x55beebd20520, root=0x7f2a69175c40) at ./src/core/rbtree.c:77
#1 0x00007f2a69e1a7e1 in __add_timer (timer=0x55beebd20520) at ./src/core/timer.c:62
#2 0x00007f2a69e1a856 in osmo_timer_schedule (timer=timer@entry=0x55beebd20520, seconds=60, microseconds=microseconds@entry=0) at ./src/core/timer.c:107
#3 0x00007f2a69da6014 in sccp_timer_schedule (conn=0x55beebd20430, timer_name=OSMO_SCCP_TIMER_CONN_EST, timer=0x55beebd20520) at ./src/sccp_scoc.c:286
#4 conn_start_connect_timer (conn=0x55beebd20430) at ./src/sccp_scoc.c:386
#5 scoc_fsm_idle (fi=0x55beebd23530, event=<optimized out>, data=<optimized out>) at ./src/sccp_scoc.c:974
#6 0x00007f2a69dff1b0 in _osmo_fsm_inst_dispatch (fi=0x55beebd23530, event=0, data=0x55beebd278e8, file=file@entry=0x7f2a69dbe844 "sccp_scoc.c", line=line@entry=1932) at ./src/core/fsm.c:875
#7 0x00007f2a69da75e7 in osmo_sccp_user_sap_down_nofree (scu=scu@entry=0x2, oph=<optimized out>) at ./src/sccp_scoc.c:1932
#8 0x00007f2a69da797f in osmo_sccp_user_sap_down (scu=scu@entry=0x2, oph=<optimized out>) at ./src/sccp_scoc.c:1944
#9 0x00007f2a69da09a7 in osmo_sccp_tx_conn_req (scu=0x2, scu@entry=0x55beebceea90, conn_id=conn_id@entry=2, calling_addr=calling_addr@entry=0x55beebb72024, called_addr=called_addr@entry=0x55beebb72078, data=<optimized out>, len=<optimized out>) at ./src/sccp_helpers.c:137
#10 0x00007f2a69da0a9b in osmo_sccp_tx_conn_req_msg (scu=0x55beebceea90, conn_id=conn_id@entry=2, calling_addr=calling_addr@entry=0x55beebb72024, called_addr=called_addr@entry=0x55beebb72078, msg=msg@entry=0x55beebd27170) at ./src/sccp_helpers.c:147
#11 0x000055bebfe556c1 in osmo_bsc_sigtran_open_conn (conn=conn@entry=0x55beebd29700, msg=msg@entry=0x55beebd27170) at ./src/osmo-bsc/osmo_bsc_sigtran.c:453
#12 0x000055bebfdb5cc0 in gscon_fsm_init (fi=0x55beebd23790, event=<optimized out>, data=0x55beebd27170) at ./src/osmo-bsc/bsc_subscr_conn_fsm.c:430
#13 0x00007f2a69dff1b0 in _osmo_fsm_inst_dispatch (fi=0x55beebd23790, event=event@entry=2, data=0x55beebd27170, file=file@entry=0x55bebfec6b01 "gsm_08_08.c", line=line@entry=544) at ./src/core/fsm.c:875
#14 0x000055bebfe4383a in bsc_compl_l3 (lchan=0x55beebbc1730, msg=msg@entry=0x55beebd26c00, chosen_channel=chosen_channel@entry=0) at ./src/osmo-bsc/gsm_08_08.c:544
#15 0x000055bebfde4487 in gsm0408_rcvmsg (msg=msg@entry=0x55beebd26c00, link_id=<optimized out>) at ./src/osmo-bsc/gsm_04_08_rr.c:1272
#16 0x000055bebfda6264 in abis_rsl_rx_rll (msg=0x55beebd26c00) at ./src/osmo-bsc/abis_rsl.c:2740
#17 abis_rsl_rcvmsg (msg=0x55beebd26c00) at ./src/osmo-bsc/abis_rsl.c:3411
#18 0x00007f2a69e4bdcf in ?? () from /lib/x86_64-linux-gnu/libosmoabis.so.15
#19 0x00007f2a69e0b40e in iofd_handle_segmented_read (iofd=0x55beebd09770, msg=0x55beebd26c00, rc=30) at ./src/core/osmo_io.c:350
#20 0x00007f2a69e0c951 in iofd_poll_ofd_cb_recvmsg_sendmsg (ofd=0x55beebd09820, what=1) at ./src/core/osmo_io_poll.c:64
#21 0x00007f2a69e0cbce in iofd_poll_ofd_cb_dispatch (ofd=<optimized out>, what=<optimized out>) at ./src/core/osmo_io_poll.c:136
#22 0x00007f2a69e0ed04 in poll_disp_fds (n_fd=<optimized out>) at ./src/core/select.c:419
#23 _osmo_select_main (polling=polling@entry=0) at ./src/core/select.c:457
#24 0x00007f2a69e0ee16 in osmo_select_main_ctx (polling=polling@entry=0) at ./src/core/select.c:513
#25 0x000055bebfd8a9b7 in main (argc=3, argv=<optimized out>) at ./src/osmo-bsc/osmo_bsc_main.c:1076
[1;34m[testenv][sccplite] Stopping podman container[0m
[0;94m[testenv][sccplite] + ['podman', 'kill', 'testenv-bsc-sccplite-osmocom-latest-20250704-1340-263fd302-0'][0m
testenv-bsc-sccplite-osmocom-latest-20250704-1340-263fd302-0
[1;34m[testenv][sccplite] Stopping testsuite (613046)[0m
[0;94m[testenv] feed_watchdog_loop: podman container has stopped[0m
[1;34m[testenv][sccplite] Logs saved to: https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-sccplite-latest/2365… [0m
+ RC=1
+ [ 1 = 0 ]
+ uptime
+ grep --color=always -o load.*
[01;31m[Kload average: 0.62, 1.65, 2.39[m[K
+ exit 1
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-bsc-test-sccplite/2637/displa…>
Changes:
[Pau Espin Pedrol] bsc: generic: Introduce test TC_stat_msc_sctp_disconnected
[Pau Espin Pedrol] stp: Support expect RFC466 4.5.1 DUNA after ASP ACT
------------------------------------------
[...truncated 5.84 MiB...]
[1;31mDABIS[0;m abis.c:429 E1L(0) Signalling link down
[1;33mDABIS[0;m abis.c:170 A-bis connection establishment to BSC (127.0.0.1) in progress...
[38;5;23m[1;33mDLINP[0;m[38;5;23m input/ipaccess.c:1078 E1TS(0:1) enabling ipaccess BTS mode, OML connecting to 127.0.0.1:3002[0;m
[38;5;23m[1;31mDLINP[0;m[38;5;23m stream_cli.c:424 CLICONN(ts-0-1-oml,){CONNECTING} connect failed (-111)[0;m
[1;31mDABIS[0;m abis.c:429 E1L(0) Signalling link down
[1;33mDABIS[0;m abis.c:170 A-bis connection establishment to BSC (127.0.0.1) in progress...
[38;5;23m[1;33mDLINP[0;m[38;5;23m input/ipaccess.c:1078 E1TS(0:1) enabling ipaccess BTS mode, OML connecting to 127.0.0.1:3002[0;m
[38;5;23m[1;31mDLINP[0;m[38;5;23m stream_cli.c:424 CLICONN(ts-0-1-oml,){CONNECTING} connect failed (-111)[0;m
[1;31mDABIS[0;m abis.c:429 E1L(0) Signalling link down
[1;33mDABIS[0;m abis.c:170 A-bis connection establishment to BSC (127.0.0.1) in progress...
[38;5;23m[1;33mDLINP[0;m[38;5;23m input/ipaccess.c:1078 E1TS(0:1) enabling ipaccess BTS mode, OML connecting to 127.0.0.1:3002[0;m
[38;5;23m[1;31mDLINP[0;m[38;5;23m stream_cli.c:424 CLICONN(ts-0-1-oml,){CONNECTING} connect failed (-111)[0;m
[1;31mDABIS[0;m abis.c:429 E1L(0) Signalling link down
[1;33mDABIS[0;m abis.c:170 A-bis connection establishment to BSC (127.0.0.1) in progress...
[38;5;23m[1;33mDLINP[0;m[38;5;23m input/ipaccess.c:1078 E1TS(0:1) enabling ipaccess BTS mode, OML connecting to 127.0.0.1:3002[0;m
[38;5;23m[1;31mDLINP[0;m[38;5;23m stream_cli.c:424 CLICONN(ts-0-1-oml,){CONNECTING} connect failed (-111)[0;m
[1;31mDABIS[0;m abis.c:429 E1L(0) Signalling link down
[1;33mDABIS[0;m abis.c:170 A-bis connection establishment to BSC (127.0.0.1) in progress...
[38;5;23m[1;33mDLINP[0;m[38;5;23m input/ipaccess.c:1078 E1TS(0:1) enabling ipaccess BTS mode, OML connecting to 127.0.0.1:3002[0;m
[38;5;23m[1;31mDLINP[0;m[38;5;23m stream_cli.c:424 CLICONN(ts-0-1-oml,){CONNECTING} connect failed (-111)[0;m
[1;31mDABIS[0;m abis.c:429 E1L(0) Signalling link down
[1;33mDABIS[0;m abis.c:170 A-bis connection establishment to BSC (127.0.0.1) in progress...
[38;5;23m[1;33mDLINP[0;m[38;5;23m input/ipaccess.c:1078 E1TS(0:1) enabling ipaccess BTS mode, OML connecting to 127.0.0.1:3002[0;m
[38;5;23m[1;31mDLINP[0;m[38;5;23m stream_cli.c:424 CLICONN(ts-0-1-oml,){CONNECTING} connect failed (-111)[0;m
[1;31mDABIS[0;m abis.c:429 E1L(0) Signalling link down
MTC@fb3ee1a7b60f: Timeout T_guard: 30 s
MTC@fb3ee1a7b60f: setverdict(fail): pass -> fail reason: "Timeout of T_guard", new component reason: "Timeout of T_guard"
MTC@fb3ee1a7b60f: Stopping test component execution.
MTC@fb3ee1a7b60f: Test case TC_si2quater_3_earfcns was stopped.
MTC@fb3ee1a7b60f: Terminating component type BSC_Tests.test_CT.
MTC@fb3ee1a7b60f: Default with id 1 (altstep as_Tguard) was deactivated.
MTC@fb3ee1a7b60f: Message with id 4 was extracted from the queue of IPA_CTRL.
MTC@fb3ee1a7b60f: Port IPA_CTRL was stopped.
MTC@fb3ee1a7b60f: Port RSL_CCHAN[0] was stopped.
MTC@fb3ee1a7b60f: Port RSL_CCHAN[1] was stopped.
MTC@fb3ee1a7b60f: Port RSL_CCHAN[2] was stopped.
MTC@fb3ee1a7b60f: Removing unterminated connection between port IPA_RSL[0][0] and IPA-BTS0-TRX0-RSL-IPA(104):IPA_RSL_PORT.
MTC@fb3ee1a7b60f: Message with id 18 was extracted from the queue of IPA_RSL[0][0].
MTC@fb3ee1a7b60f: Port IPA_RSL[0][0] was stopped.
MTC@fb3ee1a7b60f: Port IPA_RSL[0][1] was stopped.
MTC@fb3ee1a7b60f: Port IPA_RSL[0][2] was stopped.
IPA-BTS0-TRX0-RSL-IPA(104)@fb3ee1a7b60f: Connection of port IPA_RSL_PORT to mtc:IPA_RSL[0][0] was closed unexpectedly by the peer.
IPA-BTS0-TRX0-RSL-IPA(104)@fb3ee1a7b60f: Port IPA_RSL_PORT was disconnected from mtc:IPA_RSL[0][0].
MTC@fb3ee1a7b60f: Port IPA_RSL[0][3] was stopped.
MTC@fb3ee1a7b60f: Port IPA_RSL[1][0] was stopped.
MTC@fb3ee1a7b60f: Port IPA_RSL[1][1] was stopped.
MTC@fb3ee1a7b60f: Port IPA_RSL[1][2] was stopped.
MTC@fb3ee1a7b60f: Port IPA_RSL[1][3] was stopped.
MTC@fb3ee1a7b60f: Port IPA_RSL[2][0] was stopped.
MTC@fb3ee1a7b60f: Port IPA_RSL[2][1] was stopped.
MTC@fb3ee1a7b60f: Port IPA_RSL[2][2] was stopped.
MTC@fb3ee1a7b60f: Port IPA_RSL[2][3] was stopped.
MTC@fb3ee1a7b60f: Port IPA was stopped.
MTC@fb3ee1a7b60f: Port SCCPLITE_IPA_CTRL was stopped.
MTC@fb3ee1a7b60f: Removing unterminated connection between port IPA_CFG_PORT[0][0] and IPA-BTS0-TRX0-RSL-IPA(104):CFG_PORT.
MTC@fb3ee1a7b60f: Port IPA_CFG_PORT[0][0] was stopped.
MTC@fb3ee1a7b60f: Port IPA_CFG_PORT[0][1] was stopped.
MTC@fb3ee1a7b60f: Port IPA_CFG_PORT[0][2] was stopped.
IPA-BTS0-TRX0-RSL-IPA(104)@fb3ee1a7b60f: Connection of port CFG_PORT to mtc:IPA_CFG_PORT[0][0] was closed unexpectedly by the peer.
IPA-BTS0-TRX0-RSL-IPA(104)@fb3ee1a7b60f: Port CFG_PORT was disconnected from mtc:IPA_CFG_PORT[0][0].
MTC@fb3ee1a7b60f: Port IPA_CFG_PORT[0][3] was stopped.
MTC@fb3ee1a7b60f: Port IPA_CFG_PORT[1][0] was stopped.
MTC@fb3ee1a7b60f: Port IPA_CFG_PORT[1][1] was stopped.
MTC@fb3ee1a7b60f: Port IPA_CFG_PORT[1][2] was stopped.
MTC@fb3ee1a7b60f: Port IPA_CFG_PORT[1][3] was stopped.
MTC@fb3ee1a7b60f: Port IPA_CFG_PORT[2][0] was stopped.
MTC@fb3ee1a7b60f: Port IPA_CFG_PORT[2][1] was stopped.
MTC@fb3ee1a7b60f: Port IPA_CFG_PORT[2][2] was stopped.
MTC@fb3ee1a7b60f: Port IPA_CFG_PORT[2][3] was stopped.
MTC@fb3ee1a7b60f: Removing unterminated mapping between port BSCVTY and system:BSCVTY.
MTC@fb3ee1a7b60f: Port BSCVTY was unmapped from system:BSCVTY.
MTC@fb3ee1a7b60f: Message with id 41 was extracted from the queue of BSCVTY.
MTC@fb3ee1a7b60f: Port BSCVTY was stopped.
MTC@fb3ee1a7b60f: Port STPVTY was stopped.
MTC@fb3ee1a7b60f: Removing unterminated connection between port SCCPLITE_IPA_CFG_PORT[0] and VirtMSC-IPA(100):CFG_PORT.
MTC@fb3ee1a7b60f: Port SCCPLITE_IPA_CFG_PORT[0] was stopped.
MTC@fb3ee1a7b60f: Port SCCPLITE_IPA_CFG_PORT[1] was stopped.
VirtMSC-IPA(100)@fb3ee1a7b60f: Connection of port CFG_PORT to mtc:SCCPLITE_IPA_CFG_PORT[0] was closed unexpectedly by the peer.
MTC@fb3ee1a7b60f: Port SCCPLITE_IPA_CFG_PORT[2] was stopped.
VirtMSC-IPA(100)@fb3ee1a7b60f: Port CFG_PORT was disconnected from mtc:SCCPLITE_IPA_CFG_PORT[0].
MTC@fb3ee1a7b60f: Removing unterminated connection between port BSSAP and VirtMSC-SCCP(99):SCCP_SP_PORT.
MTC@fb3ee1a7b60f: Port BSSAP was stopped.
MTC@fb3ee1a7b60f: Port BSSAP_LE was stopped.
MTC@fb3ee1a7b60f: Component type BSC_Tests.test_CT was shut down inside testcase TC_si2quater_3_earfcns.
VirtMSC-SCCP(99)@fb3ee1a7b60f: Connection of port SCCP_SP_PORT to mtc:BSSAP was closed unexpectedly by the peer.
MTC@fb3ee1a7b60f: Waiting for PTCs to finish.
VirtMSC-SCCP(99)@fb3ee1a7b60f: Port SCCP_SP_PORT was disconnected from mtc:BSSAP.
VirtMSC-IPA-WAIT(101)@fb3ee1a7b60f: Kill was requested from MC. Terminating idle PTC.
VirtMSC-IPA-WAIT(101)@fb3ee1a7b60f: Terminating component type IPA_Emulation.IPA_EventWaiter_CT.
VirtMSC-STATS(98)@fb3ee1a7b60f: Kill was requested from MC.
IPA-BTS0-TRX0-RSL-IPA(104)@fb3ee1a7b60f: Kill was requested from MC. Terminating idle PTC.
IPA-BTS0-TRX0-RSL-IPA(104)@fb3ee1a7b60f: Terminating component type IPA_Emulation.IPA_Emulation_CT.
VirtMGW-MGCP-0(103)@fb3ee1a7b60f: Kill was requested from MC.
VirtMSC-SCCP(99)@fb3ee1a7b60f: Kill was requested from MC.
VirtMSC-IPA(100)@fb3ee1a7b60f: Kill was requested from MC. Terminating idle PTC.
VirtMGW-MGCP-0(103)@fb3ee1a7b60f: Terminating test component execution.
VirtMSC-STATS(98)@fb3ee1a7b60f: Terminating test component execution.
VirtMSC-IPA-WAIT(101)@fb3ee1a7b60f: Port IPA_SP_PORT was stopped.
VirtMSC-SCCP(99)@fb3ee1a7b60f: Terminating test component execution.
IPA-BTS0-TRX0-RSL-IPA(104)@fb3ee1a7b60f: Removing unterminated mapping between port IPA_PORT and system:IPA_CODEC_PT.
VirtMSC-IPA-WAIT(101)@fb3ee1a7b60f: Component type IPA_Emulation.IPA_EventWaiter_CT was shut down inside testcase TC_si2quater_3_earfcns.
VirtMSC-IPA(100)@fb3ee1a7b60f: Terminating component type IPA_Emulation.IPA_Emulation_CT.
VirtMSC-IPA-WAIT(101)@fb3ee1a7b60f: Final verdict of PTC: pass
VirtMSC-IPA(100)@fb3ee1a7b60f: Removing unterminated mapping between port IPA_PORT and system:IPA_CODEC_PT.
IPA-BTS0-TRX0-RSL-IPA(104)@fb3ee1a7b60f: Port IPA_PORT was unmapped from system:IPA_CODEC_PT.
VirtMSC-IPA(100)@fb3ee1a7b60f: Port IPA_PORT was unmapped from system:IPA_CODEC_PT.
IPA-BTS0-TRX0-RSL-IPA(104)@fb3ee1a7b60f: Port IPA_PORT was stopped.
VirtMSC-IPA(100)@fb3ee1a7b60f: Port IPA_PORT was stopped.
VirtMSC-IPA(100)@fb3ee1a7b60f: Port CFG_PORT was stopped.
IPA-BTS0-TRX0-RSL-IPA(104)@fb3ee1a7b60f: Port CFG_PORT was stopped.
IPA-BTS0-TRX0-RSL-IPA(104)@fb3ee1a7b60f: Port MTP3_SP_PORT was stopped.
VirtMSC-IPA(100)@fb3ee1a7b60f: Removing unterminated connection between port MTP3_SP_PORT and VirtMSC-SCCP(99):MTP3_SCCP_PORT.
IPA-BTS0-TRX0-RSL-IPA(104)@fb3ee1a7b60f: Port IPA_MGCP_PORT was stopped.
VirtMSC-SCCP(99)@fb3ee1a7b60f: Function SCCPStart was stopped. PTC terminates.
VirtMSC-IPA(100)@fb3ee1a7b60f: Port MTP3_SP_PORT was stopped.
VirtMSC-IPA(100)@fb3ee1a7b60f: Port IPA_MGCP_PORT was stopped.
VirtMSC-SCCP(99)@fb3ee1a7b60f: Terminating component type SCCP_Emulation.SCCP_CT.
VirtMSC-STATS(98)@fb3ee1a7b60f: Function main was stopped. PTC terminates.
VirtMSC-SCCP(99)@fb3ee1a7b60f: Port SCCP_SP_PORT was stopped.
VirtMSC-IPA(100)@fb3ee1a7b60f: Port IPA_RSL_PORT was stopped.
VirtMSC-SCCP(99)@fb3ee1a7b60f: Removing unterminated connection between port MTP3_SCCP_PORT and VirtMSC-IPA(100):MTP3_SP_PORT.
VirtMSC-STATS(98)@fb3ee1a7b60f: Terminating component type StatsD_Checker.StatsD_Checker_CT.
VirtMSC-IPA(100)@fb3ee1a7b60f: Port IPA_OML_PORT was stopped.
VirtMSC-SCCP(99)@fb3ee1a7b60f: Port MTP3_SCCP_PORT was stopped.
VirtMGW-MGCP-0(103)@fb3ee1a7b60f: Function main was stopped. PTC terminates.
VirtMSC-STATS(98)@fb3ee1a7b60f: Removing unterminated mapping between port STATSVTY and system:STATSVTY.
VirtMSC-IPA(100)@fb3ee1a7b60f: Port IPA_CTRL_PORT was stopped.
VirtMSC-SCCP(99)@fb3ee1a7b60f: Component type SCCP_Emulation.SCCP_CT was shut down inside testcase TC_si2quater_3_earfcns.
VirtMSC-IPA(100)@fb3ee1a7b60f: Port IPA_SP_PORT was stopped.
VirtMSC-STATS(98)@fb3ee1a7b60f: Port STATSVTY was unmapped from system:STATSVTY.
IPA-BTS0-TRX0-RSL-IPA(104)@fb3ee1a7b60f: Port IPA_RSL_PORT was stopped.
VirtMSC-IPA(100)@fb3ee1a7b60f: Component type IPA_Emulation.IPA_Emulation_CT was shut down inside testcase TC_si2quater_3_earfcns.
VirtMGW-MGCP-0(103)@fb3ee1a7b60f: Terminating component type MGCP_Emulation.MGCP_Emulation_CT.
VirtMSC-SCCP(99)@fb3ee1a7b60f: Final verdict of PTC: none
VirtMSC-STATS(98)@fb3ee1a7b60f: Message with id 4 was extracted from the queue of STATSVTY.
VirtMSC-IPA(100)@fb3ee1a7b60f: Final verdict of PTC: none
IPA-BTS0-TRX0-RSL-IPA(104)@fb3ee1a7b60f: Port IPA_OML_PORT was stopped.
VirtMGW-MGCP-0(103)@fb3ee1a7b60f: Removing unterminated mapping between port MGCP and system:MGCP_CODEC_PT.
IPA-BTS0-TRX0-RSL-IPA(104)@fb3ee1a7b60f: Port IPA_CTRL_PORT was stopped.
VirtMSC-STATS(98)@fb3ee1a7b60f: Port STATSVTY was stopped.
IPA-BTS0-TRX0-RSL-IPA(104)@fb3ee1a7b60f: Port IPA_SP_PORT was stopped.
VirtMSC-STATS(98)@fb3ee1a7b60f: Port STATSD_PROC was stopped.
IPA-BTS0-TRX0-RSL-IPA(104)@fb3ee1a7b60f: Component type IPA_Emulation.IPA_Emulation_CT was shut down inside testcase TC_si2quater_3_earfcns.
VirtMSC-STATS(98)@fb3ee1a7b60f: Removing unterminated mapping between port STATS and system:STATS.
IPA-BTS0-TRX0-RSL-IPA(104)@fb3ee1a7b60f: Final verdict of PTC: none
VirtMGW-MGCP-0(103)@fb3ee1a7b60f: Port MGCP was unmapped from system:MGCP_CODEC_PT.
VirtMGW-MGCP-0(103)@fb3ee1a7b60f: Port MGCP was stopped.
VirtMGW-MGCP-0(103)@fb3ee1a7b60f: Port MGCP_CLIENT was stopped.
VirtMSC-STATS(98)@fb3ee1a7b60f: Port STATS was unmapped from system:STATS.
VirtMSC-STATS(98)@fb3ee1a7b60f: Port STATS was stopped.
VirtMGW-MGCP-0(103)@fb3ee1a7b60f: Port MGCP_CLIENT_MULTI was stopped.
VirtMSC-STATS(98)@fb3ee1a7b60f: Component type StatsD_Checker.StatsD_Checker_CT was shut down inside testcase TC_si2quater_3_earfcns.
VirtMGW-MGCP-0(103)@fb3ee1a7b60f: Port MGCP_PROC was stopped.
VirtMGW-MGCP-0(103)@fb3ee1a7b60f: Component type MGCP_Emulation.MGCP_Emulation_CT was shut down inside testcase TC_si2quater_3_earfcns.
VirtMSC-STATS(98)@fb3ee1a7b60f: Final verdict of PTC: none
VirtMGW-MGCP-0(103)@fb3ee1a7b60f: Final verdict of PTC: none
MTC@fb3ee1a7b60f: Setting final verdict of the test case.
MTC@fb3ee1a7b60f: Local verdict of MTC: fail reason: "Timeout of T_guard"
MTC@fb3ee1a7b60f: Local verdict of PTC VirtMSC-STATS(98): none (fail -> fail)
MTC@fb3ee1a7b60f: Local verdict of PTC VirtMSC-SCCP(99): none (fail -> fail)
MTC@fb3ee1a7b60f: Local verdict of PTC VirtMSC-IPA(100): none (fail -> fail)
MTC@fb3ee1a7b60f: Local verdict of PTC VirtMSC-IPA-WAIT(101): pass (fail -> fail)
MTC@fb3ee1a7b60f: Local verdict of PTC IPA-CTRL-CLI-IPA(102): none (fail -> fail)
MTC@fb3ee1a7b60f: Local verdict of PTC VirtMGW-MGCP-0(103): none (fail -> fail)
MTC@fb3ee1a7b60f: Local verdict of PTC IPA-BTS0-TRX0-RSL-IPA(104): none (fail -> fail)
MTC@fb3ee1a7b60f: Test case TC_si2quater_3_earfcns finished. Verdict: fail reason: Timeout of T_guard
MTC@fb3ee1a7b60f: Starting external command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-sccplite/ws/ttcn3-tc…> BSC_Tests.TC_si2quater_3_earfcns fail'.
Fri Jul 4 08:51:42 UTC 2025
[1;31m------ BSC_Tests.TC_si2quater_3_earfcns fail ------[0m
Saving talloc report from 127.0.0.1:4242 to BSC_Tests.TC_si2quater_3_earfcns.talloc
Traceback (most recent call last):
File "/usr/local/lib/python3.11/dist-packages/osmopy/osmo_interact/common.py", line 420, in main_run_commands
interact.connect()
File "/usr/local/lib/python3.11/dist-packages/osmopy/osmo_interact/vty.py", line 84, in connect
super().connect()
File "/usr/local/lib/python3.11/dist-packages/osmopy/osmo_interact/common.py", line 88, in connect
self.socket.connect((self.host, int(self.port)))
ConnectionRefusedError: [Errno 111] Connection refused
Waiting for packet dumper to finish... 0 (prev_count=-1, count=150256)
Waiting for packet dumper to finish... 1 (prev_count=150256, count=151628)
[1;33mDABIS[0;m abis.c:170 A-bis connection establishment to BSC (127.0.0.1) in progress...
[38;5;23m[1;33mDLINP[0;m[38;5;23m input/ipaccess.c:1078 E1TS(0:1) enabling ipaccess BTS mode, OML connecting to 127.0.0.1:3002[0;m
[1;33mDABIS[0;m abis.c:170 A-bis connection establishment to BSC (127.0.0.1) in progress...
[38;5;23m[1;33mDLINP[0;m[38;5;23m input/ipaccess.c:1078 E1TS(0:1) enabling ipaccess BTS mode, OML connecting to 127.0.0.1:3002[0;m
[38;5;23m[1;31mDLINP[0;m[38;5;23m stream_cli.c:424 CLICONN(ts-0-1-oml,){CONNECTING} connect failed (-111)[0;m
[1;31mDABIS[0;m abis.c:429 E1L(0) Signalling link down
[38;5;23m[1;31mDLINP[0;m[38;5;23m stream_cli.c:424 CLICONN(ts-0-1-oml,){CONNECTING} connect failed (-111)[0;m
[1;31mDABIS[0;m abis.c:429 E1L(0) Signalling link down
[1;33mDABIS[0;m abis.c:170 A-bis connection establishment to BSC (127.0.0.1) in progress...
[38;5;23m[1;33mDLINP[0;m[38;5;23m input/ipaccess.c:1078 E1TS(0:1) enabling ipaccess BTS mode, OML connecting to 127.0.0.1:3002[0;m
[38;5;23m[1;31mDLINP[0;m[38;5;23m stream_cli.c:424 CLICONN(ts-0-1-oml,){CONNECTING} connect failed (-111)[0;m
[1;31mDABIS[0;m abis.c:429 E1L(0) Signalling link down
MTC@fb3ee1a7b60f: External command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-sccplite/ws/ttcn3-tc…> BSC_Tests.TC_si2quater_3_earfcns fail' was executed successfully (exit status: 0).
MTC@fb3ee1a7b60f: Starting external command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-sccplite/ws/ttcn3-tc…> BSC_Tests.TC_si2quater_4_earfcns'.
------ BSC_Tests.TC_si2quater_4_earfcns ------
Fri Jul 4 08:51:47 UTC 2025
/usr/bin/dumpcap -q -s 1520 -n -i any -w "<https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-sccplite/2637/artifa…"> ><https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-sccplite/2637/artifa…> 2>/tmp/cmderr &
Waiting for packet dumper to start... 0
[1;34m[testenv][sccplite] Running gdb to get a backtrace[0m
[0;94m[testenv][sccplite] + ['podman', 'exec', '-e', 'CCACHE_DIR=/home/osmocom-build/ccache/testenv', '-e', 'TESTENV_CACHE_DIR=<https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-sccplite/ws/_cache',> '-e', 'TESTENV_SRC_DIR=/home/osmocom-build/jenkins/workspace', '-e', 'TESTENV_INSTALL_DIR=/', '-e', 'TERM=dumb', '-e', 'PATH=<https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-sccplite/ws/_testenv…>:<https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-sccplite/ws/_testenv…>:<https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-sccplite/ws/bsc>:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin', '-e', 'HOME=/home/osmocom-build', '-t', 'testenv-bsc-sccplite-osmocom-nightly-20250704-0848-7d796c8a-0', 'sh', '-c', 'gdb --batch /usr/bin/osmo-bsc <https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-sccplite/2637/artifa…> -ex bt | tee <https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-sccplite/2637/artifa…'][0m>
[New LWP 9899]
77 ./src/core/rbtree.c: [Thread debugging using libthread_db enabled]
Using host libthread_db library "/lib/x86_64-linux-gnu/libthread_db.so.1".
Core was generated by `osmo-bsc -c osmo-bsc-sccplite.cfg'.
Program terminated with signal SIGSEGV, Segmentation fault.
#0 rb_insert_color (node=node@entry=0x559f3a5abe18, root=0x7f9f1911bc40) at ./src/core/rbtree.c:77
No such file or directory.
#0 rb_insert_color (node=node@entry=0x559f3a5abe18, root=0x7f9f1911bc40) at ./src/core/rbtree.c:77
#1 0x00007f9f19dd5831 in __add_timer (timer=0x559f3a5abe18) at ./src/core/timer.c:62
#2 0x00007f9f19dd58a6 in osmo_timer_schedule (timer=timer@entry=0x559f3a5abe18, seconds=60, microseconds=microseconds@entry=0) at ./src/core/timer.c:107
#3 0x00007f9f19d44cf6 in sccp_timer_schedule (conn=0x559f3a5abd20, timer_name=OSMO_SCCP_TIMER_CONN_EST, timer=0x559f3a5abe18) at ./src/sccp_scoc.c:297
#4 conn_start_connect_timer (conn=0x559f3a5abd20) at ./src/sccp_scoc.c:397
#5 scoc_fsm_idle (fi=0x559f3a5a69a0, event=<optimized out>, data=<optimized out>) at ./src/sccp_scoc.c:1018
#6 0x00007f9f19dba1b0 in _osmo_fsm_inst_dispatch (fi=0x559f3a5a69a0, event=0, data=0x559f3a5b4888, file=file@entry=0x7f9f19d6efc4 "sccp_scoc.c", line=line@entry=1994) at ./src/core/fsm.c:875
#7 0x00007f9f19d465f3 in osmo_sccp_user_sap_down_nofree (scu=scu@entry=0x2, oph=<optimized out>) at ./src/sccp_scoc.c:1994
#8 0x00007f9f19d46aef in osmo_sccp_user_sap_down (scu=scu@entry=0x2, oph=<optimized out>) at ./src/sccp_scoc.c:2006
#9 0x00007f9f19d3dc87 in osmo_sccp_tx_conn_req (scu=0x2, scu@entry=0x559f3a576e40, conn_id=conn_id@entry=2, calling_addr=calling_addr@entry=0x559f3a3ea944, called_addr=called_addr@entry=0x559f3a3ea998, data=<optimized out>, len=<optimized out>) at ./src/sccp_helpers.c:139
#10 0x00007f9f19d3dd7b in osmo_sccp_tx_conn_req_msg (scu=0x559f3a576e40, conn_id=conn_id@entry=2, calling_addr=calling_addr@entry=0x559f3a3ea944, called_addr=called_addr@entry=0x559f3a3ea998, msg=msg@entry=0x559f3a5b39a0) at ./src/sccp_helpers.c:149
#11 0x0000559f24a542b1 in osmo_bsc_sigtran_open_conn (conn=conn@entry=0x559f3a5b0580, msg=msg@entry=0x559f3a5b39a0) at ./src/osmo-bsc/osmo_bsc_sigtran.c:453
#12 0x0000559f249b3c80 in gscon_fsm_init (fi=0x559f3a5b0180, event=<optimized out>, data=0x559f3a5b39a0) at ./src/osmo-bsc/bsc_subscr_conn_fsm.c:430
#13 0x00007f9f19dba1b0 in _osmo_fsm_inst_dispatch (fi=0x559f3a5b0180, event=event@entry=2, data=0x559f3a5b39a0, file=file@entry=0x559f24ac6d21 "gsm_08_08.c", line=line@entry=544) at ./src/core/fsm.c:875
#14 0x0000559f24a4243a in bsc_compl_l3 (lchan=0x559f3a44b500, msg=msg@entry=0x559f3a5b3430, chosen_channel=chosen_channel@entry=0) at ./src/osmo-bsc/gsm_08_08.c:544
#15 0x0000559f249e3057 in gsm0408_rcvmsg (msg=msg@entry=0x559f3a5b3430, link_id=<optimized out>) at ./src/osmo-bsc/gsm_04_08_rr.c:1272
#16 0x0000559f249a4224 in abis_rsl_rx_rll (msg=0x559f3a5b3430) at ./src/osmo-bsc/abis_rsl.c:2740
#17 abis_rsl_rcvmsg (msg=0x559f3a5b3430) at ./src/osmo-bsc/abis_rsl.c:3411
#18 0x00007f9f19e0713f in ?? () from /lib/x86_64-linux-gnu/libosmoabis.so.15
#19 0x00007f9f19dc6446 in iofd_handle_segmented_read (iofd=0x559f3a5b2530, msg=0x559f3a5b3430, rc=30) at ./src/core/osmo_io.c:359
#20 0x00007f9f19dc79a1 in iofd_poll_ofd_cb_recvmsg_sendmsg (ofd=0x559f3a5b25e0, what=1) at ./src/core/osmo_io_poll.c:64
#21 0x00007f9f19dc7c1e in iofd_poll_ofd_cb_dispatch (ofd=<optimized out>, what=<optimized out>) at ./src/core/osmo_io_poll.c:136
#22 0x00007f9f19dc9d54 in poll_disp_fds (n_fd=<optimized out>) at ./src/core/select.c:419
#23 _osmo_select_main (polling=polling@entry=0) at ./src/core/select.c:457
#24 0x00007f9f19dc9e66 in osmo_select_main_ctx (polling=polling@entry=0) at ./src/core/select.c:513
#25 0x0000559f249889b7 in main (argc=3, argv=<optimized out>) at ./src/osmo-bsc/osmo_bsc_main.c:1056
[1;34m[testenv][sccplite] Stopping podman container[0m
[0;94m[testenv][sccplite] + ['podman', 'kill', 'testenv-bsc-sccplite-osmocom-nightly-20250704-0848-7d796c8a-0'][0m
testenv-bsc-sccplite-osmocom-nightly-20250704-0848-7d796c8a-0
[1;34m[testenv][sccplite] Stopping testsuite (1017281)[0m
[0;94m[testenv] feed_watchdog_loop: podman container has stopped[0m
[1;34m[testenv][sccplite] Logs saved to: https://jenkins.osmocom.org/jenkins/job/ttcn3-bsc-test-sccplite/2637/artifa… [0m
+ RC=1
+ [ 1 = 0 ]
+ uptime
+ grep --color=always -o load.*
[01;31m[Kload average: 1.18, 1.10, 0.99[m[K
+ exit 1
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/master-pyosmocom/JOB_TYPE=pysim,a1=…>
Changes:
------------------------------------------
[...truncated 31.59 KiB...]
ok
test_03_gen_ext_auth_apdu (test_globalplatform.SCP03_Test_AES192_03.test_03_gen_ext_auth_apdu) ... DEBUG:pySim.global_platform.scp:wrap_cmd_apdu(808203000865df4f1a356a8879)
ok
test_04_wrap_cmd_apdu_get_eid (test_globalplatform.SCP03_Test_AES192_03.test_04_wrap_cmd_apdu_get_eid) ... DEBUG:pySim.global_platform.scp:wrap_cmd_apdu(80e2910006bf3e035c015a00)
DEBUG:pySim.global_platform.scp:_get_icv(data=00000000000000000000000000000001, is_resp=False) -> icv=295afe76882e5ab0ee690befcf2ef778
ok
test_05_unwrap_rsp_apdu_get_eid (test_globalplatform.SCP03_Test_AES192_03.test_05_unwrap_rsp_apdu_get_eid) ... DEBUG:pySim.global_platform.scp:unwrap_rsp_apdu(sw=bytearray(b'\x90\x00'), rsp_apdu=bytearray(b'\xbf>\x12Z\x10\x89\x88!\x19\x90\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x05'))
ok
test_06_mac_command (test_globalplatform.SCP03_Test_AES192_03.test_06_mac_command) ... DEBUG:pySim.global_platform.scp:wrap_cmd_apdu(80f22002)
DEBUG:pySim.global_platform.scp:wrap_cmd_apdu(80ca006600)
DEBUG:pySim.global_platform.scp:wrap_cmd_apdu(80f220020a4f0212345c054f9f70c5)
DEBUG:pySim.global_platform.scp:_get_icv(data=00000000000000000000000000000004, is_resp=False) -> icv=2c5b3d54201922790a8fbefc7d7542e8
DEBUG:pySim.global_platform.scp:wrap_cmd_apdu(80f28002024f0000)
DEBUG:pySim.global_platform.scp:_get_icv(data=00000000000000000000000000000005, is_resp=False) -> icv=f1642ddaede08d7f4e16a839c8875fed
ok
test_01_initialize_update (test_globalplatform.SCP03_Test_AES192_11.test_01_initialize_update) ... ok
test_02_parse_init_upd_resp (test_globalplatform.SCP03_Test_AES192_11.test_02_parse_init_upd_resp) ... DEBUG:pySim.global_platform.scp:scp03_kdf(constant=04, context=7396430b768b085b8cfc23522ffdbf1e, base_key=000102030405060708090a0b0c0d0e0f0001020304050607, l=192)
DEBUG:pySim.global_platform.scp:scp03_kdf(constant=06, context=7396430b768b085b8cfc23522ffdbf1e, base_key=101112131415161718191a1b1c1d1e1f1011121314151617, l=192)
DEBUG:pySim.global_platform.scp:scp03_kdf(constant=07, context=7396430b768b085b8cfc23522ffdbf1e, base_key=101112131415161718191a1b1c1d1e1f1011121314151617, l=192)
DEBUG:pySim.global_platform.scp:<pySim.global_platform.scp.Scp03SessionKeys object at 0x7ff2f205f910>
DEBUG:pySim.global_platform.scp:host_challenge(7396430b768b085b), card_challenge(8cfc23522ffdbf1e)
DEBUG:pySim.global_platform.scp:scp03_kdf(constant=00, context=7396430b768b085b8cfc23522ffdbf1e, base_key=15e4303d3157d8d1dd283b7321434ae83fc80ac9b12e3daf, l=64)
DEBUG:pySim.global_platform.scp:scp03_kdf(constant=01, context=7396430b768b085b8cfc23522ffdbf1e, base_key=15e4303d3157d8d1dd283b7321434ae83fc80ac9b12e3daf, l=64)
DEBUG:pySim.global_platform.scp:host_cryptogram(2145ed30b146f5db), card_cryptogram(5df5542cac8fd866)
ok
test_03_gen_ext_auth_apdu (test_globalplatform.SCP03_Test_AES192_11.test_03_gen_ext_auth_apdu) ... DEBUG:pySim.global_platform.scp:wrap_cmd_apdu(80821100082145ed30b146f5db)
ok
test_04_wrap_cmd_apdu_get_eid (test_globalplatform.SCP03_Test_AES192_11.test_04_wrap_cmd_apdu_get_eid) ... DEBUG:pySim.global_platform.scp:wrap_cmd_apdu(80e2910006bf3e035c015a00)
ok
test_05_unwrap_rsp_apdu_get_eid (test_globalplatform.SCP03_Test_AES192_11.test_05_unwrap_rsp_apdu_get_eid) ... DEBUG:pySim.global_platform.scp:unwrap_rsp_apdu(sw=bytearray(b'\x90\x00'), rsp_apdu=bytearray(b'\xbf>\x12Z\x10\x89\x88!\x19\x90\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x05\x16/\xbd3\xe0I@\xa9'))
ok
test_06_mac_command (test_globalplatform.SCP03_Test_AES192_11.test_06_mac_command) ... DEBUG:pySim.global_platform.scp:wrap_cmd_apdu(80f22002)
DEBUG:pySim.global_platform.scp:wrap_cmd_apdu(80ca006600)
DEBUG:pySim.global_platform.scp:wrap_cmd_apdu(80f220020a4f0212345c054f9f70c5)
DEBUG:pySim.global_platform.scp:wrap_cmd_apdu(80f28002024f0000)
ok
test_01_initialize_update (test_globalplatform.SCP03_Test_AES192_33.test_01_initialize_update) ... ok
test_02_parse_init_upd_resp (test_globalplatform.SCP03_Test_AES192_33.test_02_parse_init_upd_resp) ... DEBUG:pySim.global_platform.scp:scp03_kdf(constant=04, context=9b3f2eef0e8c9374f6bb305a15bae1a6, base_key=000102030405060708090a0b0c0d0e0f0001020304050607, l=192)
DEBUG:pySim.global_platform.scp:scp03_kdf(constant=06, context=9b3f2eef0e8c9374f6bb305a15bae1a6, base_key=101112131415161718191a1b1c1d1e1f1011121314151617, l=192)
DEBUG:pySim.global_platform.scp:scp03_kdf(constant=07, context=9b3f2eef0e8c9374f6bb305a15bae1a6, base_key=101112131415161718191a1b1c1d1e1f1011121314151617, l=192)
DEBUG:pySim.global_platform.scp:<pySim.global_platform.scp.Scp03SessionKeys object at 0x7ff2f2285590>
DEBUG:pySim.global_platform.scp:host_challenge(9b3f2eef0e8c9374), card_challenge(f6bb305a15bae1a6)
DEBUG:pySim.global_platform.scp:scp03_kdf(constant=00, context=9b3f2eef0e8c9374f6bb305a15bae1a6, base_key=436682192ffc13c9399ca38c1adac01498ec77fe1fb560a9, l=64)
DEBUG:pySim.global_platform.scp:scp03_kdf(constant=01, context=9b3f2eef0e8c9374f6bb305a15bae1a6, base_key=436682192ffc13c9399ca38c1adac01498ec77fe1fb560a9, l=64)
DEBUG:pySim.global_platform.scp:host_cryptogram(9100bc22d58b45b8), card_cryptogram(8f79fb08212fbed7)
ok
test_03_gen_ext_auth_apdu (test_globalplatform.SCP03_Test_AES192_33.test_03_gen_ext_auth_apdu) ... DEBUG:pySim.global_platform.scp:wrap_cmd_apdu(80823300089100bc22d58b45b8)
ok
test_04_wrap_cmd_apdu_get_eid (test_globalplatform.SCP03_Test_AES192_33.test_04_wrap_cmd_apdu_get_eid) ... DEBUG:pySim.global_platform.scp:wrap_cmd_apdu(80e2910006bf3e035c015a00)
DEBUG:pySim.global_platform.scp:_get_icv(data=00000000000000000000000000000001, is_resp=False) -> icv=8dfc8944578cf519b96fa2f3d0540929
ok
test_05_unwrap_rsp_apdu_get_eid (test_globalplatform.SCP03_Test_AES192_33.test_05_unwrap_rsp_apdu_get_eid) ... DEBUG:pySim.global_platform.scp:unwrap_rsp_apdu(sw=bytearray(b'\x90\x00'), rsp_apdu=bytearray(b'\xc0Qv\xc1\xb6\xf7*\xaeP\xc3,\xbe\xe6;\x0e\x95\x99\x89(\xfdM\xfb+\xe9\xf2\x7f\xfd\xe8\xc8GoY\t\xb4\x80\\\xc4\x03\x95\x99'))
DEBUG:pySim.global_platform.scp:_get_icv(data=80000000000000000000000000000001, is_resp=True) -> icv=dffa9a2aa40de538b5919545a699f2f6
DEBUG:pySim.global_platform.scp:decrypted: bf3e125a10898821199000000000000000000000058000000000000000000000
DEBUG:pySim.global_platform.scp:response_data: bf3e125a1089882119900000000000000000000005
ok
test_06_mac_command (test_globalplatform.SCP03_Test_AES192_33.test_06_mac_command) ... DEBUG:pySim.global_platform.scp:wrap_cmd_apdu(80f22002)
DEBUG:pySim.global_platform.scp:wrap_cmd_apdu(80ca006600)
DEBUG:pySim.global_platform.scp:wrap_cmd_apdu(80f220020a4f0212345c054f9f70c5)
DEBUG:pySim.global_platform.scp:_get_icv(data=00000000000000000000000000000004, is_resp=False) -> icv=66915264ac25c6c48618558e45b6c2d3
DEBUG:pySim.global_platform.scp:wrap_cmd_apdu(80f28002024f0000)
DEBUG:pySim.global_platform.scp:_get_icv(data=00000000000000000000000000000005, is_resp=False) -> icv=100fc7e3d717452ba03a1823f396f260
ok
test_01_initialize_update (test_globalplatform.SCP03_Test_AES256_03.test_01_initialize_update) ... ok
test_02_parse_init_upd_resp (test_globalplatform.SCP03_Test_AES256_03.test_02_parse_init_upd_resp) ... DEBUG:pySim.global_platform.scp:scp03_kdf(constant=04, context=c6066990fc426e1d8682cd81bbd8919f, base_key=000102030405060708090a0b0c0d0e0f000102030405060708090a0b0c0d0e0f, l=256)
DEBUG:pySim.global_platform.scp:scp03_kdf(constant=06, context=c6066990fc426e1d8682cd81bbd8919f, base_key=101112131415161718191a1b1c1d1e1f101112131415161718191a1b1c1d1e1f, l=256)
DEBUG:pySim.global_platform.scp:scp03_kdf(constant=07, context=c6066990fc426e1d8682cd81bbd8919f, base_key=101112131415161718191a1b1c1d1e1f101112131415161718191a1b1c1d1e1f, l=256)
DEBUG:pySim.global_platform.scp:<pySim.global_platform.scp.Scp03SessionKeys object at 0x7ff2f2285790>
DEBUG:pySim.global_platform.scp:host_challenge(c6066990fc426e1d), card_challenge(8682cd81bbd8919f)
DEBUG:pySim.global_platform.scp:scp03_kdf(constant=00, context=c6066990fc426e1d8682cd81bbd8919f, base_key=309b7e14a014a190dd01e21756a8010d01568b058971f803f7d77be42c6cb45b, l=64)
DEBUG:pySim.global_platform.scp:scp03_kdf(constant=01, context=c6066990fc426e1d8682cd81bbd8919f, base_key=309b7e14a014a190dd01e21756a8010d01568b058971f803f7d77be42c6cb45b, l=64)
DEBUG:pySim.global_platform.scp:host_cryptogram(77c493b632edadaf), card_cryptogram(2de3f2664581f118)
ok
test_03_gen_ext_auth_apdu (test_globalplatform.SCP03_Test_AES256_03.test_03_gen_ext_auth_apdu) ... DEBUG:pySim.global_platform.scp:wrap_cmd_apdu(808203000877c493b632edadaf)
ok
test_04_wrap_cmd_apdu_get_eid (test_globalplatform.SCP03_Test_AES256_03.test_04_wrap_cmd_apdu_get_eid) ... DEBUG:pySim.global_platform.scp:wrap_cmd_apdu(80e2910006bf3e035c015a00)
DEBUG:pySim.global_platform.scp:_get_icv(data=00000000000000000000000000000001, is_resp=False) -> icv=f23382fb921338b0d02abf13bcddb773
ok
test_05_unwrap_rsp_apdu_get_eid (test_globalplatform.SCP03_Test_AES256_03.test_05_unwrap_rsp_apdu_get_eid) ... DEBUG:pySim.global_platform.scp:unwrap_rsp_apdu(sw=bytearray(b'\x90\x00'), rsp_apdu=bytearray(b'\xbf>\x12Z\x10\x89\x88!\x19\x90\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x05'))
ok
test_06_mac_command (test_globalplatform.SCP03_Test_AES256_03.test_06_mac_command) ... DEBUG:pySim.global_platform.scp:wrap_cmd_apdu(80f22002)
DEBUG:pySim.global_platform.scp:wrap_cmd_apdu(80ca006600)
DEBUG:pySim.global_platform.scp:wrap_cmd_apdu(80f220020a4f0212345c054f9f70c5)
DEBUG:pySim.global_platform.scp:_get_icv(data=00000000000000000000000000000004, is_resp=False) -> icv=93af6a0412e8d59a29fe7298fc1352d8
DEBUG:pySim.global_platform.scp:wrap_cmd_apdu(80f28002024f0000)
DEBUG:pySim.global_platform.scp:_get_icv(data=00000000000000000000000000000005, is_resp=False) -> icv=4e54b6ff8f32aa99d447d977d3d30e7a
ok
test_01_initialize_update (test_globalplatform.SCP03_Test_AES256_11.test_01_initialize_update) ... ok
test_02_parse_init_upd_resp (test_globalplatform.SCP03_Test_AES256_11.test_02_parse_init_upd_resp) ... DEBUG:pySim.global_platform.scp:scp03_kdf(constant=04, context=11666d57866c6f5453ea8847efa7674e, base_key=000102030405060708090a0b0c0d0e0f000102030405060708090a0b0c0d0e0f, l=256)
DEBUG:pySim.global_platform.scp:scp03_kdf(constant=06, context=11666d57866c6f5453ea8847efa7674e, base_key=101112131415161718191a1b1c1d1e1f101112131415161718191a1b1c1d1e1f, l=256)
DEBUG:pySim.global_platform.scp:scp03_kdf(constant=07, context=11666d57866c6f5453ea8847efa7674e, base_key=101112131415161718191a1b1c1d1e1f101112131415161718191a1b1c1d1e1f, l=256)
DEBUG:pySim.global_platform.scp:<pySim.global_platform.scp.Scp03SessionKeys object at 0x7ff2f2183210>
DEBUG:pySim.global_platform.scp:host_challenge(11666d57866c6f54), card_challenge(53ea8847efa7674e)
DEBUG:pySim.global_platform.scp:scp03_kdf(constant=00, context=11666d57866c6f5453ea8847efa7674e, base_key=80546fe2b8e72587efa3f1be7df30b0fb0146b795f8f131d8b32ac5200f5dbec, l=64)
DEBUG:pySim.global_platform.scp:scp03_kdf(constant=01, context=11666d57866c6f5453ea8847efa7674e, base_key=80546fe2b8e72587efa3f1be7df30b0fb0146b795f8f131d8b32ac5200f5dbec, l=64)
DEBUG:pySim.global_platform.scp:host_cryptogram(2f2ad190eff2fafc), card_cryptogram(41498a4d66cf0dee)
ok
test_03_gen_ext_auth_apdu (test_globalplatform.SCP03_Test_AES256_11.test_03_gen_ext_auth_apdu) ... DEBUG:pySim.global_platform.scp:wrap_cmd_apdu(80821100082f2ad190eff2fafc)
ok
test_04_wrap_cmd_apdu_get_eid (test_globalplatform.SCP03_Test_AES256_11.test_04_wrap_cmd_apdu_get_eid) ... DEBUG:pySim.global_platform.scp:wrap_cmd_apdu(80e2910006bf3e035c015a00)
ok
test_05_unwrap_rsp_apdu_get_eid (test_globalplatform.SCP03_Test_AES256_11.test_05_unwrap_rsp_apdu_get_eid) ... DEBUG:pySim.global_platform.scp:unwrap_rsp_apdu(sw=bytearray(b'\x90\x00'), rsp_apdu=bytearray(b'\xbf>\x12Z\x10\x89\x88!\x19\x90\x00\x00\x00\x00\x00\x00\x00\x00\x00\x00\x05\x80\x12\xdd\x7f\x01\xf1\xc4\xc1'))
ok
test_06_mac_command (test_globalplatform.SCP03_Test_AES256_11.test_06_mac_command) ... DEBUG:pySim.global_platform.scp:wrap_cmd_apdu(80f22002)
DEBUG:pySim.global_platform.scp:wrap_cmd_apdu(80ca006600)
DEBUG:pySim.global_platform.scp:wrap_cmd_apdu(80f220020a4f0212345c054f9f70c5)
DEBUG:pySim.global_platform.scp:wrap_cmd_apdu(80f28002024f0000)
ok
test_01_initialize_update (test_globalplatform.SCP03_Test_AES256_33.test_01_initialize_update) ... ok
test_02_parse_init_upd_resp (test_globalplatform.SCP03_Test_AES256_33.test_02_parse_init_upd_resp) ... DEBUG:pySim.global_platform.scp:scp03_kdf(constant=04, context=97b2055fe58599fda8439a22cedf045f, base_key=000102030405060708090a0b0c0d0e0f000102030405060708090a0b0c0d0e0f, l=256)
DEBUG:pySim.global_platform.scp:scp03_kdf(constant=06, context=97b2055fe58599fda8439a22cedf045f, base_key=101112131415161718191a1b1c1d1e1f101112131415161718191a1b1c1d1e1f, l=256)
DEBUG:pySim.global_platform.scp:scp03_kdf(constant=07, context=97b2055fe58599fda8439a22cedf045f, base_key=101112131415161718191a1b1c1d1e1f101112131415161718191a1b1c1d1e1f, l=256)
DEBUG:pySim.global_platform.scp:<pySim.global_platform.scp.Scp03SessionKeys object at 0x7ff2f218f0d0>
DEBUG:pySim.global_platform.scp:host_challenge(97b2055fe58599fd), card_challenge(a8439a22cedf045f)
DEBUG:pySim.global_platform.scp:scp03_kdf(constant=00, context=97b2055fe58599fda8439a22cedf045f, base_key=0607c530eb8e7b6e781a70b349370db8728c4b6ed29a24e5461864c142f98fec, l=64)
DEBUG:pySim.global_platform.scp:scp03_kdf(constant=01, context=97b2055fe58599fda8439a22cedf045f, base_key=0607c530eb8e7b6e781a70b349370db8728c4b6ed29a24e5461864c142f98fec, l=64)
DEBUG:pySim.global_platform.scp:host_cryptogram(508a0fd959d2e547), card_cryptogram(a9f1903b2834f26e)
ok
test_03_gen_ext_auth_apdu (test_globalplatform.SCP03_Test_AES256_33.test_03_gen_ext_auth_apdu) ... DEBUG:pySim.global_platform.scp:wrap_cmd_apdu(8082330008508a0fd959d2e547)
ok
test_04_wrap_cmd_apdu_get_eid (test_globalplatform.SCP03_Test_AES256_33.test_04_wrap_cmd_apdu_get_eid) ... DEBUG:pySim.global_platform.scp:wrap_cmd_apdu(80e2910006bf3e035c015a00)
DEBUG:pySim.global_platform.scp:_get_icv(data=00000000000000000000000000000001, is_resp=False) -> icv=bcaf73883e649908dcad6ebcaf5a3c41
ok
test_05_unwrap_rsp_apdu_get_eid (test_globalplatform.SCP03_Test_AES256_33.test_05_unwrap_rsp_apdu_get_eid) ... DEBUG:pySim.global_platform.scp:unwrap_rsp_apdu(sw=bytearray(b'\x90\x00'), rsp_apdu=bytearray(b'\xea\x90\xdb\xfad\x8ag\xc5\xebj\xbcW\xf8S\x0b\x97\xd0\xcdVG\xc5\xe8s \x16\xb5R\x03\xb0x\xdd*\xce\x7f\x8b\xc5\xd1\xc1\xcd\x99'))
DEBUG:pySim.global_platform.scp:_get_icv(data=80000000000000000000000000000001, is_resp=True) -> icv=3dc50f8cecdf2361db2de3d475e8c3e7
DEBUG:pySim.global_platform.scp:decrypted: bf3e125a10898821199000000000000000000000058000000000000000000000
DEBUG:pySim.global_platform.scp:response_data: bf3e125a1089882119900000000000000000000005
ok
test_06_mac_command (test_globalplatform.SCP03_Test_AES256_33.test_06_mac_command) ... DEBUG:pySim.global_platform.scp:wrap_cmd_apdu(80f22002)
DEBUG:pySim.global_platform.scp:wrap_cmd_apdu(80ca006600)
DEBUG:pySim.global_platform.scp:wrap_cmd_apdu(80f220020a4f0212345c054f9f70c5)
DEBUG:pySim.global_platform.scp:_get_icv(data=00000000000000000000000000000004, is_resp=False) -> icv=707aab208193fcaf550f8a0198a6a9f7
DEBUG:pySim.global_platform.scp:wrap_cmd_apdu(80f28002024f0000)
DEBUG:pySim.global_platform.scp:_get_icv(data=00000000000000000000000000000005, is_resp=False) -> icv=8207743b0fe3c82e9096d5c5c6299c7a
ok
test_CapFile (test_javacard.TestJavacard.test_CapFile) ... ok
test_decode_resp (test_ota.SmsOtaTestCase.test_decode_resp) ... ok
test_encode_cmd (test_ota.SmsOtaTestCase.test_encode_cmd) ... ok
test_cmd_3des_ciphered (test_ota.Test_SMS_3DES.test_cmd_3des_ciphered) ... ok
test_cmd_3des_none (test_ota.Test_SMS_3DES.test_cmd_3des_none) ... ok
test_cmd_3des_signed (test_ota.Test_SMS_3DES.test_cmd_3des_signed) ... ok
test_resp_3des_ciphered (test_ota.Test_SMS_3DES.test_resp_3des_ciphered) ... ok
test_resp_3des_none (test_ota.Test_SMS_3DES.test_resp_3des_none) ... ok
test_resp_3des_signed (test_ota.Test_SMS_3DES.test_resp_3des_signed) ... ok
test_resp_3des_signed_err (test_ota.Test_SMS_3DES.test_resp_3des_signed_err)
Expect an OtaCheckError exception if the computed CC != received CC ... ok
test_cmd_aes128_ciphered (test_ota.Test_SMS_AES128.test_cmd_aes128_ciphered) ... ok
test_resp_aes128_ciphered (test_ota.Test_SMS_AES128.test_resp_aes128_ciphered) ... ok
test_from_bytes (test_sms.Test_AddressField.test_from_bytes) ... ok
test_from_bytes_odd (test_sms.Test_AddressField.test_from_bytes_odd) ... ok
test_to_bytes (test_sms.Test_AddressField.test_to_bytes) ... ok
test_to_bytes_odd (test_sms.Test_AddressField.test_to_bytes_odd) ... ok
test_from_bytes (test_sms.Test_DELIVER.test_from_bytes) ... ok
test_single_ie (test_sms.Test_SMS_UDH.test_single_ie) ... ok
test_single_ie_tail (test_sms.Test_SMS_UDH.test_single_ie_tail) ... ok
test_single_ie_value (test_sms.Test_SMS_UDH.test_single_ie_value) ... ok
test_to_bytes (test_sms.Test_SMS_UDH.test_to_bytes) ... ok
test_two_ie_data_tail (test_sms.Test_SMS_UDH.test_two_ie_data_tail) ... ok
test_from_bytes (test_sms.Test_SUBMIT.test_from_bytes) ... ok
test_de_encode_tlv (test_tlvs.TLV_IE_Test.test_de_encode_tlv)
Test the decoder and encoder for a TLV_IE. Performs first a decoder ... ok
test_decode_tlv (test_tlvs.TLV_IE_Test.test_decode_tlv)
Test the decoder for a TLV_IE. Requires the given TLV_IE subclass ... ok
test_encode_tlv (test_tlvs.TLV_IE_Test.test_encode_tlv)
Test the encoder for a TLV_IE. Requires the given TLV_IE subclass ... ok
testDecAct_allSet (test_utils.DecTestCase.testDecAct_allSet) ... ok
testDecAct_noneSet (test_utils.DecTestCase.testDecAct_noneSet) ... ok
testDecAct_onlyCdma20001xRTT (test_utils.DecTestCase.testDecAct_onlyCdma20001xRTT) ... ok
testDecAct_onlyCdma2000HRPD (test_utils.DecTestCase.testDecAct_onlyCdma2000HRPD) ... ok
testDecAct_onlyEUtran (test_utils.DecTestCase.testDecAct_onlyEUtran) ... ok
testDecAct_onlyGsm (test_utils.DecTestCase.testDecAct_onlyGsm) ... ok
testDecAct_onlyGsmCompact (test_utils.DecTestCase.testDecAct_onlyGsmCompact) ... ok
testDecAct_onlyNgRan (test_utils.DecTestCase.testDecAct_onlyNgRan) ... ok
testDecAct_onlyUtran (test_utils.DecTestCase.testDecAct_onlyUtran) ... ok
testDecMCCfromPLMN (test_utils.DecTestCase.testDecMCCfromPLMN) ... ok
testDecMCCfromPLMN_str (test_utils.DecTestCase.testDecMCCfromPLMN_str) ... ok
testDecMCCfromPLMN_unused (test_utils.DecTestCase.testDecMCCfromPLMN_unused) ... ok
testDecMCCfromPLMN_unused_str (test_utils.DecTestCase.testDecMCCfromPLMN_unused_str) ... ok
testDecMNCfromPLMN_threeDigitMNC (test_utils.DecTestCase.testDecMNCfromPLMN_threeDigitMNC) ... ok
testDecMNCfromPLMN_threeDigitMNC_str (test_utils.DecTestCase.testDecMNCfromPLMN_threeDigitMNC_str) ... ok
testDecMNCfromPLMN_twoDigitMNC (test_utils.DecTestCase.testDecMNCfromPLMN_twoDigitMNC) ... ok
testDecMNCfromPLMN_twoDigitMNC_str (test_utils.DecTestCase.testDecMNCfromPLMN_twoDigitMNC_str) ... ok
testDecMNCfromPLMN_unused (test_utils.DecTestCase.testDecMNCfromPLMN_unused) ... ok
testDecMNCfromPLMN_unused_str (test_utils.DecTestCase.testDecMNCfromPLMN_unused_str) ... ok
testDec_msisdn (test_utils.DecTestCase.testDec_msisdn) ... ok
testDecodeSuciCalcInfo (test_utils.DecTestCase.testDecodeSuciCalcInfo) ... ok
testDecxPlmn_w_act (test_utils.DecTestCase.testDecxPlmn_w_act) ... ok
testEnc_msisdn (test_utils.DecTestCase.testEnc_msisdn) ... ok
testEncodeSuciCalcInfo (test_utils.DecTestCase.testEncodeSuciCalcInfo) ... ok
testFormatxPlmn_w_act (test_utils.DecTestCase.testFormatxPlmn_w_act) ... ok
testSplitHexStringToListOf5ByteEntries (test_utils.DecTestCase.testSplitHexStringToListOf5ByteEntries) ... ok
test_enc_plmn (test_utils.DecTestCase.test_enc_plmn) ... ok
testSeqOfChoices (test_utils.DoTestCase.testSeqOfChoices)
A sequence of two choices with each a variety of DO/TLVs ... ok
test_encode (test_utils.TestLuhn.test_encode) ... ok
test_sanitize_iccid (test_utils.TestLuhn.test_sanitize_iccid) ... ok
test_verify (test_utils.TestLuhn.test_verify) ... ok
test_parse_command_apdu (test_utils.TestUtils.test_parse_command_apdu) ... ok
----------------------------------------------------------------------
Ran 152 tests in 1.554s
OK
+ cd tests/pySim-prog_test/
+ ./pySim-prog_test.sh
pySim-prog_test - a test program to test pySim-prog.py
======================================================
Expecting to see the following cards:
Fairwaves-SIM
fakemagicsim
sysmoISIM-SJA2
sysmoISIM-SJA5
sysmosim-gr1
sysmoUSIM-SJS1
Wavemobile-SIM
Number of card terminals installed: 6
== EXECUTING TEST ==
Probing card in terminal #0
Card is of type: Insert card now (or CTRL-C to cancel)
Warning: no .data file for this card, skipping...
== EXECUTING TEST ==
Probing card in terminal #1
Card is of type: Insert card now (or CTRL-C to cancel)
Warning: no .data file for this card, skipping...
== EXECUTING TEST ==
Probing card in terminal #2
Card is of type: Insert card now (or CTRL-C to cancel)
Warning: no .data file for this card, skipping...
== EXECUTING TEST ==
Probing card in terminal #3
Card is of type: Insert card now (or CTRL-C to cancel)
Warning: no .data file for this card, skipping...
== EXECUTING TEST ==
Probing card in terminal #4
Card is of type: Insert card now (or CTRL-C to cancel)
Warning: no .data file for this card, skipping...
== EXECUTING TEST ==
Probing card in terminal #5
Card is of type: Insert card now (or CTRL-C to cancel)
Warning: no .data file for this card, skipping...
Error: Card Fairwaves-SIM seen 0 times!
Build step 'Execute shell' marked build as failure
$ ssh-agent -k
unset SSH_AUTH_SOCK;
unset SSH_AGENT_PID;
echo Agent pid 8234 killed;
[ssh-agent] Stopped.
Archiving artifacts