jenkins-notifications
August 2024
jenkins-notifications@lists.osmocom.org
- 1 participants
- 131 discussions
See <https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/2565/display/redirec…>
Changes:
[Pau Espin Pedrol] sgsn: Fix running with testenv.py podman setup
[Pau Espin Pedrol] sgsn: Introduce test TC_attach_rau_a_b_wrong_old_ra
[Pau Espin Pedrol] deps/Makefile: Use upstream titan.TestPorts.SIPmsg
------------------------------------------
[...truncated 186.75 KB...]
env CCACHE_SLOPPINESS=time_macros ccache g++ -shared -o StatsD_CodecPort_CtrlFunct_part_4.so StatsD_CodecPort_CtrlFunct_part_4.o
env CCACHE_SLOPPINESS=time_macros ccache g++ -shared -o StatsD_CodecPort_CtrlFunct_part_5.so StatsD_CodecPort_CtrlFunct_part_5.o
env CCACHE_SLOPPINESS=time_macros ccache g++ -shared -o StatsD_CodecPort_CtrlFunct_part_6.so StatsD_CodecPort_CtrlFunct_part_6.o
env CCACHE_SLOPPINESS=time_macros ccache g++ -shared -o StatsD_CodecPort_CtrlFunct_part_7.so StatsD_CodecPort_CtrlFunct_part_7.o
env CCACHE_SLOPPINESS=time_macros ccache g++ -shared -o StatsD_Types_part_1.so StatsD_Types_part_1.o
env CCACHE_SLOPPINESS=time_macros ccache g++ -shared -o StatsD_Types_part_2.so StatsD_Types_part_2.o
env CCACHE_SLOPPINESS=time_macros ccache g++ -shared -o StatsD_Types_part_3.so StatsD_Types_part_3.o
env CCACHE_SLOPPINESS=time_macros ccache g++ -shared -o StatsD_Types_part_4.so StatsD_Types_part_4.o
env CCACHE_SLOPPINESS=time_macros ccache g++ -shared -o StatsD_Types_part_5.so StatsD_Types_part_5.o
env CCACHE_SLOPPINESS=time_macros ccache g++ -shared -o StatsD_Types_part_6.so StatsD_Types_part_6.o
env CCACHE_SLOPPINESS=time_macros ccache g++ -shared -o StatsD_Types_part_7.so StatsD_Types_part_7.o
env CCACHE_SLOPPINESS=time_macros ccache g++ -shared -o TCCConversion_Functions_part_1.so TCCConversion_Functions_part_1.o
env CCACHE_SLOPPINESS=time_macros ccache g++ -shared -o TCCConversion_Functions_part_2.so TCCConversion_Functions_part_2.o
env CCACHE_SLOPPINESS=time_macros ccache g++ -shared -o TCCConversion_Functions_part_3.so TCCConversion_Functions_part_3.o
env CCACHE_SLOPPINESS=time_macros ccache g++ -shared -o TCCConversion_Functions_part_4.so TCCConversion_Functions_part_4.o
env CCACHE_SLOPPINESS=time_macros ccache g++ -shared -o TCCConversion_Functions_part_5.so TCCConversion_Functions_part_5.o
env CCACHE_SLOPPINESS=time_macros ccache g++ -shared -o TCCConversion_Functions_part_6.so TCCConversion_Functions_part_6.o
env CCACHE_SLOPPINESS=time_macros ccache g++ -shared -o TCCConversion_Functions_part_7.so TCCConversion_Functions_part_7.o
env CCACHE_SLOPPINESS=time_macros ccache g++ -shared -o TCCInterface_Functions_part_1.so TCCInterface_Functions_part_1.o
env CCACHE_SLOPPINESS=time_macros ccache g++ -shared -o TCCInterface_Functions_part_2.so TCCInterface_Functions_part_2.o
env CCACHE_SLOPPINESS=time_macros ccache g++ -shared -o TCCInterface_Functions_part_3.so TCCInterface_Functions_part_3.o
env CCACHE_SLOPPINESS=time_macros ccache g++ -shared -o TCCInterface_Functions_part_4.so TCCInterface_Functions_part_4.o
env CCACHE_SLOPPINESS=time_macros ccache g++ -shared -o TCCInterface_Functions_part_5.so TCCInterface_Functions_part_5.o
env CCACHE_SLOPPINESS=time_macros ccache g++ -shared -o TCCInterface_Functions_part_6.so TCCInterface_Functions_part_6.o
env CCACHE_SLOPPINESS=time_macros ccache g++ -shared -o TCCInterface_Functions_part_7.so TCCInterface_Functions_part_7.o
env CCACHE_SLOPPINESS=time_macros ccache g++ -shared -o TELNETasp_PortType_part_1.so TELNETasp_PortType_part_1.o
env CCACHE_SLOPPINESS=time_macros ccache g++ -shared -o TELNETasp_PortType_part_2.so TELNETasp_PortType_part_2.o
env CCACHE_SLOPPINESS=time_macros ccache g++ -shared -o TELNETasp_PortType_part_3.so TELNETasp_PortType_part_3.o
env CCACHE_SLOPPINESS=time_macros ccache g++ -shared -o TELNETasp_PortType_part_4.so TELNETasp_PortType_part_4.o
env CCACHE_SLOPPINESS=time_macros ccache g++ -shared -o TELNETasp_PortType_part_5.so TELNETasp_PortType_part_5.o
env CCACHE_SLOPPINESS=time_macros ccache g++ -shared -o TELNETasp_PortType_part_6.so TELNETasp_PortType_part_6.o
env CCACHE_SLOPPINESS=time_macros ccache g++ -shared -o TELNETasp_PortType_part_7.so TELNETasp_PortType_part_7.o
env CCACHE_SLOPPINESS=time_macros ccache g++ -shared -o IPA_Emulation.so IPA_Emulation.o
env CCACHE_SLOPPINESS=time_macros ccache g++ -shared -o IPA_Emulation_part_1.so IPA_Emulation_part_1.o
env CCACHE_SLOPPINESS=time_macros ccache g++ -shared -o IPA_Emulation_part_2.so IPA_Emulation_part_2.o
env CCACHE_SLOPPINESS=time_macros ccache g++ -shared -o IPA_Emulation_part_3.so IPA_Emulation_part_3.o
env CCACHE_SLOPPINESS=time_macros ccache g++ -shared -o IPA_Emulation_part_4.so IPA_Emulation_part_4.o
env CCACHE_SLOPPINESS=time_macros ccache g++ -shared -o IPA_Emulation_part_5.so IPA_Emulation_part_5.o
env CCACHE_SLOPPINESS=time_macros ccache g++ -shared -o IPA_Emulation_part_6.so IPA_Emulation_part_6.o
env CCACHE_SLOPPINESS=time_macros ccache g++ -shared -o IPA_Emulation_part_7.so IPA_Emulation_part_7.o
env CCACHE_SLOPPINESS=time_macros ccache g++ -shared -o SDP_parse_.tab.so SDP_parse_.tab.o
env CCACHE_SLOPPINESS=time_macros ccache g++ -shared -o lex.SDP_parse_.so lex.SDP_parse_.o
env CCACHE_SLOPPINESS=time_macros ccache g++ -shared -o IPA_CodecPort_CtrlFunctDef.so IPA_CodecPort_CtrlFunctDef.o
env CCACHE_SLOPPINESS=time_macros ccache g++ -shared -o IPL4asp_PT.so IPL4asp_PT.o
env CCACHE_SLOPPINESS=time_macros ccache g++ -shared -o IPL4asp_discovery.so IPL4asp_discovery.o
env CCACHE_SLOPPINESS=time_macros ccache g++ -shared -o IP_EncDec.so IP_EncDec.o
env CCACHE_SLOPPINESS=time_macros ccache g++ -shared -o IuUP_EncDec.so IuUP_EncDec.o
env CCACHE_SLOPPINESS=time_macros ccache g++ -shared -o MGCP_CodecPort_CtrlFunctDef.so MGCP_CodecPort_CtrlFunctDef.o
env CCACHE_SLOPPINESS=time_macros ccache g++ -shared -o Native_FunctionDefs.so Native_FunctionDefs.o
env CCACHE_SLOPPINESS=time_macros ccache g++ -shared -o OSMUX_CodecPort_CtrlFunctDef.so OSMUX_CodecPort_CtrlFunctDef.o
env CCACHE_SLOPPINESS=time_macros ccache g++ -shared -o RTP_CodecPort_CtrlFunctDef.so RTP_CodecPort_CtrlFunctDef.o
env CCACHE_SLOPPINESS=time_macros ccache g++ -shared -o RTP_EncDec.so RTP_EncDec.o
env CCACHE_SLOPPINESS=time_macros ccache g++ -shared -o SDP_EncDec.so SDP_EncDec.o
env CCACHE_SLOPPINESS=time_macros ccache g++ -shared -o StatsD_CodecPort_CtrlFunctdef.so StatsD_CodecPort_CtrlFunctdef.o
env CCACHE_SLOPPINESS=time_macros ccache g++ -shared -o TCCConversion.so TCCConversion.o
env CCACHE_SLOPPINESS=time_macros ccache g++ -shared -o TCCInterface.so TCCInterface.o
env CCACHE_SLOPPINESS=time_macros ccache g++ -shared -o TELNETasp_PT.so TELNETasp_PT.o
if env CCACHE_SLOPPINESS=time_macros ccache g++ -L /usr/lib/titan-fPIC -o MGCP_Test -Wl,--no-as-needed AMR_Types.so General_Types.so IPA_CodecPort.so IPA_CodecPort_CtrlFunct.so IPA_Types.so IPCP_Types.so IPL4asp_Functions.so IPL4asp_PortType.so IPL4asp_Types.so IP_Types.so IuUP_Emulation.so IuUP_Types.so MGCP_CodecPort.so MGCP_CodecPort_CtrlFunct.so MGCP_Templates.so MGCP_Test.so MGCP_Types.so Misc_Helpers.so Native_Functions.so OSMUX_CodecPort.so OSMUX_CodecPort_CtrlFunct.so OSMUX_Emulation.so OSMUX_Types.so Osmocom_CTRL_Adapter.so Osmocom_CTRL_Functions.so Osmocom_CTRL_Types.so Osmocom_Types.so Osmocom_VTY_Functions.so RTP_CodecPort.so RTP_CodecPort_CtrlFunct.so RTP_Emulation.so RTP_Endpoint.so RTP_Types.so SDP_Templates.so SDP_Types.so Socket_API_Definitions.so StatsD_Checker.so StatsD_CodecPort.so StatsD_CodecPort_CtrlFunct.so StatsD_Types.so TCCConversion_Functions.so TCCInterface_Functions.so TELNETasp_PortType.so AMR_Types_part_1.so AMR_Types_part_2.so AMR_Types_part_3.so AMR_Types_part_4.so AMR_Types_part_5.so AMR_Types_part_6.so AMR_Types_part_7.so General_Types_part_1.so General_Types_part_2.so General_Types_part_3.so General_Types_part_4.so General_Types_part_5.so General_Types_part_6.so General_Types_part_7.so IPA_CodecPort_part_1.so IPA_CodecPort_part_2.so IPA_CodecPort_part_3.so IPA_CodecPort_part_4.so IPA_CodecPort_part_5.so IPA_CodecPort_part_6.so IPA_CodecPort_part_7.so IPA_CodecPort_CtrlFunct_part_1.so IPA_CodecPort_CtrlFunct_part_2.so IPA_CodecPort_CtrlFunct_part_3.so IPA_CodecPort_CtrlFunct_part_4.so IPA_CodecPort_CtrlFunct_part_5.so IPA_CodecPort_CtrlFunct_part_6.so IPA_CodecPort_CtrlFunct_part_7.so IPA_Types_part_1.so IPA_Types_part_2.so IPA_Types_part_3.so IPA_Types_part_4.so IPA_Types_part_5.so IPA_Types_part_6.so IPA_Types_part_7.so IPCP_Types_part_1.so IPCP_Types_part_2.so IPCP_Types_part_3.so IPCP_Types_part_4.so IPCP_Types_part_5.so IPCP_Types_part_6.so IPCP_Types_part_7.so IPL4asp_Functions_part_1.so IPL4asp_Functions_part_2.so IPL4asp_Functions_part_3.so IPL4asp_Functions_part_4.so IPL4asp_Functions_part_5.so IPL4asp_Functions_part_6.so IPL4asp_Functions_part_7.so IPL4asp_PortType_part_1.so IPL4asp_PortType_part_2.so IPL4asp_PortType_part_3.so IPL4asp_PortType_part_4.so IPL4asp_PortType_part_5.so IPL4asp_PortType_part_6.so IPL4asp_PortType_part_7.so IPL4asp_Types_part_1.so IPL4asp_Types_part_2.so IPL4asp_Types_part_3.so IPL4asp_Types_part_4.so IPL4asp_Types_part_5.so IPL4asp_Types_part_6.so IPL4asp_Types_part_7.so IP_Types_part_1.so IP_Types_part_2.so IP_Types_part_3.so IP_Types_part_4.so IP_Types_part_5.so IP_Types_part_6.so IP_Types_part_7.so IuUP_Emulation_part_1.so IuUP_Emulation_part_2.so IuUP_Emulation_part_3.so IuUP_Emulation_part_4.so IuUP_Emulation_part_5.so IuUP_Emulation_part_6.so IuUP_Emulation_part_7.so IuUP_Types_part_1.so IuUP_Types_part_2.so IuUP_Types_part_3.so IuUP_Types_part_4.so IuUP_Types_part_5.so IuUP_Types_part_6.so IuUP_Types_part_7.so MGCP_CodecPort_part_1.so MGCP_CodecPort_part_2.so MGCP_CodecPort_part_3.so MGCP_CodecPort_part_4.so MGCP_CodecPort_part_5.so MGCP_CodecPort_part_6.so MGCP_CodecPort_part_7.so MGCP_CodecPort_CtrlFunct_part_1.so MGCP_CodecPort_CtrlFunct_part_2.so MGCP_CodecPort_CtrlFunct_part_3.so MGCP_CodecPort_CtrlFunct_part_4.so MGCP_CodecPort_CtrlFunct_part_5.so MGCP_CodecPort_CtrlFunct_part_6.so MGCP_CodecPort_CtrlFunct_part_7.so MGCP_Templates_part_1.so MGCP_Templates_part_2.so MGCP_Templates_part_3.so MGCP_Templates_part_4.so MGCP_Templates_part_5.so MGCP_Templates_part_6.so MGCP_Templates_part_7.so MGCP_Test_part_1.so MGCP_Test_part_2.so MGCP_Test_part_3.so MGCP_Test_part_4.so MGCP_Test_part_5.so MGCP_Test_part_6.so MGCP_Test_part_7.so MGCP_Types_part_1.so MGCP_Types_part_2.so MGCP_Types_part_3.so MGCP_Types_part_4.so MGCP_Types_part_5.so MGCP_Types_part_6.so MGCP_Types_part_7.so Misc_Helpers_part_1.so Misc_Helpers_part_2.so Misc_Helpers_part_3.so Misc_Helpers_part_4.so Misc_Helpers_part_5.so Misc_Helpers_part_6.so Misc_Helpers_part_7.so Native_Functions_part_1.so Native_Functions_part_2.so Native_Functions_part_3.so Native_Functions_part_4.so Native_Functions_part_5.so Native_Functions_part_6.so Native_Functions_part_7.so OSMUX_CodecPort_part_1.so OSMUX_CodecPort_part_2.so OSMUX_CodecPort_part_3.so OSMUX_CodecPort_part_4.so OSMUX_CodecPort_part_5.so OSMUX_CodecPort_part_6.so OSMUX_CodecPort_part_7.so OSMUX_CodecPort_CtrlFunct_part_1.so OSMUX_CodecPort_CtrlFunct_part_2.so OSMUX_CodecPort_CtrlFunct_part_3.so OSMUX_CodecPort_CtrlFunct_part_4.so OSMUX_CodecPort_CtrlFunct_part_5.so OSMUX_CodecPort_CtrlFunct_part_6.so OSMUX_CodecPort_CtrlFunct_part_7.so OSMUX_Emulation_part_1.so OSMUX_Emulation_part_2.so OSMUX_Emulation_part_3.so OSMUX_Emulation_part_4.so OSMUX_Emulation_part_5.so OSMUX_Emulation_part_6.so OSMUX_Emulation_part_7.so OSMUX_Types_part_1.so OSMUX_Types_part_2.so OSMUX_Types_part_3.so OSMUX_Types_part_4.so OSMUX_Types_part_5.so OSMUX_Types_part_6.so OSMUX_Types_part_7.so Osmocom_CTRL_Adapter_part_1.so Osmocom_CTRL_Adapter_part_2.so Osmocom_CTRL_Adapter_part_3.so Osmocom_CTRL_Adapter_part_4.so Osmocom_CTRL_Adapter_part_5.so Osmocom_CTRL_Adapter_part_6.so Osmocom_CTRL_Adapter_part_7.so Osmocom_CTRL_Functions_part_1.so Osmocom_CTRL_Functions_part_2.so Osmocom_CTRL_Functions_part_3.so Osmocom_CTRL_Functions_part_4.so Osmocom_CTRL_Functions_part_5.so Osmocom_CTRL_Functions_part_6.so Osmocom_CTRL_Functions_part_7.so Osmocom_CTRL_Types_part_1.so Osmocom_CTRL_Types_part_2.so Osmocom_CTRL_Types_part_3.so Osmocom_CTRL_Types_part_4.so Osmocom_CTRL_Types_part_5.so Osmocom_CTRL_Types_part_6.so Osmocom_CTRL_Types_part_7.so Osmocom_Types_part_1.so Osmocom_Types_part_2.so Osmocom_Types_part_3.so Osmocom_Types_part_4.so Osmocom_Types_part_5.so Osmocom_Types_part_6.so Osmocom_Types_part_7.so Osmocom_VTY_Functions_part_1.so Osmocom_VTY_Functions_part_2.so Osmocom_VTY_Functions_part_3.so Osmocom_VTY_Functions_part_4.so Osmocom_VTY_Functions_part_5.so Osmocom_VTY_Functions_part_6.so Osmocom_VTY_Functions_part_7.so RTP_CodecPort_part_1.so RTP_CodecPort_part_2.so RTP_CodecPort_part_3.so RTP_CodecPort_part_4.so RTP_CodecPort_part_5.so RTP_CodecPort_part_6.so RTP_CodecPort_part_7.so RTP_CodecPort_CtrlFunct_part_1.so RTP_CodecPort_CtrlFunct_part_2.so RTP_CodecPort_CtrlFunct_part_3.so RTP_CodecPort_CtrlFunct_part_4.so RTP_CodecPort_CtrlFunct_part_5.so RTP_CodecPort_CtrlFunct_part_6.so RTP_CodecPort_CtrlFunct_part_7.so RTP_Emulation_part_1.so RTP_Emulation_part_2.so RTP_Emulation_part_3.so RTP_Emulation_part_4.so RTP_Emulation_part_5.so RTP_Emulation_part_6.so RTP_Emulation_part_7.so RTP_Endpoint_part_1.so RTP_Endpoint_part_2.so RTP_Endpoint_part_3.so RTP_Endpoint_part_4.so RTP_Endpoint_part_5.so RTP_Endpoint_part_6.so RTP_Endpoint_part_7.so RTP_Types_part_1.so RTP_Types_part_2.so RTP_Types_part_3.so RTP_Types_part_4.so RTP_Types_part_5.so RTP_Types_part_6.so RTP_Types_part_7.so SDP_Templates_part_1.so SDP_Templates_part_2.so SDP_Templates_part_3.so SDP_Templates_part_4.so SDP_Templates_part_5.so SDP_Templates_part_6.so SDP_Templates_part_7.so SDP_Types_part_1.so SDP_Types_part_2.so SDP_Types_part_3.so SDP_Types_part_4.so SDP_Types_part_5.so SDP_Types_part_6.so SDP_Types_part_7.so Socket_API_Definitions_part_1.so Socket_API_Definitions_part_2.so Socket_API_Definitions_part_3.so Socket_API_Definitions_part_4.so Socket_API_Definitions_part_5.so Socket_API_Definitions_part_6.so Socket_API_Definitions_part_7.so StatsD_Checker_part_1.so StatsD_Checker_part_2.so StatsD_Checker_part_3.so StatsD_Checker_part_4.so StatsD_Checker_part_5.so StatsD_Checker_part_6.so StatsD_Checker_part_7.so StatsD_CodecPort_part_1.so StatsD_CodecPort_part_2.so StatsD_CodecPort_part_3.so StatsD_CodecPort_part_4.so StatsD_CodecPort_part_5.so StatsD_CodecPort_part_6.so StatsD_CodecPort_part_7.so StatsD_CodecPort_CtrlFunct_part_1.so StatsD_CodecPort_CtrlFunct_part_2.so StatsD_CodecPort_CtrlFunct_part_3.so StatsD_CodecPort_CtrlFunct_part_4.so StatsD_CodecPort_CtrlFunct_part_5.so StatsD_CodecPort_CtrlFunct_part_6.so StatsD_CodecPort_CtrlFunct_part_7.so StatsD_Types_part_1.so StatsD_Types_part_2.so StatsD_Types_part_3.so StatsD_Types_part_4.so StatsD_Types_part_5.so StatsD_Types_part_6.so StatsD_Types_part_7.so TCCConversion_Functions_part_1.so TCCConversion_Functions_part_2.so TCCConversion_Functions_part_3.so TCCConversion_Functions_part_4.so TCCConversion_Functions_part_5.so TCCConversion_Functions_part_6.so TCCConversion_Functions_part_7.so TCCInterface_Functions_part_1.so TCCInterface_Functions_part_2.so TCCInterface_Functions_part_3.so TCCInterface_Functions_part_4.so TCCInterface_Functions_part_5.so TCCInterface_Functions_part_6.so TCCInterface_Functions_part_7.so TELNETasp_PortType_part_1.so TELNETasp_PortType_part_2.so TELNETasp_PortType_part_3.so TELNETasp_PortType_part_4.so TELNETasp_PortType_part_5.so TELNETasp_PortType_part_6.so TELNETasp_PortType_part_7.so IPA_Emulation.so IPA_Emulation_part_1.so IPA_Emulation_part_2.so IPA_Emulation_part_3.so IPA_Emulation_part_4.so IPA_Emulation_part_5.so IPA_Emulation_part_6.so IPA_Emulation_part_7.so SDP_parse_.tab.so lex.SDP_parse_.so IPA_CodecPort_CtrlFunctDef.so IPL4asp_PT.so IPL4asp_discovery.so IP_EncDec.so IuUP_EncDec.so MGCP_CodecPort_CtrlFunctDef.so Native_FunctionDefs.so OSMUX_CodecPort_CtrlFunctDef.so RTP_CodecPort_CtrlFunctDef.so RTP_EncDec.so SDP_EncDec.so StatsD_CodecPort_CtrlFunctdef.so TCCConversion.so TCCInterface.so TELNETasp_PT.so \
-L/usr/lib/titan -lttcn3-parallel-dynamic \
-L/lib -lcrypto \
-L/usr/lib -lxml2 -lsctp -lssl -lpthread; \
then : ; else /usr/bin/titanver AMR_Types.o General_Types.o IPA_CodecPort.o IPA_CodecPort_CtrlFunct.o IPA_Types.o IPCP_Types.o IPL4asp_Functions.o IPL4asp_PortType.o IPL4asp_Types.o IP_Types.o IuUP_Emulation.o IuUP_Types.o MGCP_CodecPort.o MGCP_CodecPort_CtrlFunct.o MGCP_Templates.o MGCP_Test.o MGCP_Types.o Misc_Helpers.o Native_Functions.o OSMUX_CodecPort.o OSMUX_CodecPort_CtrlFunct.o OSMUX_Emulation.o OSMUX_Types.o Osmocom_CTRL_Adapter.o Osmocom_CTRL_Functions.o Osmocom_CTRL_Types.o Osmocom_Types.o Osmocom_VTY_Functions.o RTP_CodecPort.o RTP_CodecPort_CtrlFunct.o RTP_Emulation.o RTP_Endpoint.o RTP_Types.o SDP_Templates.o SDP_Types.o Socket_API_Definitions.o StatsD_Checker.o StatsD_CodecPort.o StatsD_CodecPort_CtrlFunct.o StatsD_Types.o TCCConversion_Functions.o TCCInterface_Functions.o TELNETasp_PortType.o AMR_Types_part_1.o AMR_Types_part_2.o AMR_Types_part_3.o AMR_Types_part_4.o AMR_Types_part_5.o AMR_Types_part_6.o AMR_Types_part_7.o General_Types_part_1.o General_Types_part_2.o General_Types_part_3.o General_Types_part_4.o General_Types_part_5.o General_Types_part_6.o General_Types_part_7.o IPA_CodecPort_part_1.o IPA_CodecPort_part_2.o IPA_CodecPort_part_3.o IPA_CodecPort_part_4.o IPA_CodecPort_part_5.o IPA_CodecPort_part_6.o IPA_CodecPort_part_7.o IPA_CodecPort_CtrlFunct_part_1.o IPA_CodecPort_CtrlFunct_part_2.o IPA_CodecPort_CtrlFunct_part_3.o IPA_CodecPort_CtrlFunct_part_4.o IPA_CodecPort_CtrlFunct_part_5.o IPA_CodecPort_CtrlFunct_part_6.o IPA_CodecPort_CtrlFunct_part_7.o IPA_Types_part_1.o IPA_Types_part_2.o IPA_Types_part_3.o IPA_Types_part_4.o IPA_Types_part_5.o IPA_Types_part_6.o IPA_Types_part_7.o IPCP_Types_part_1.o IPCP_Types_part_2.o IPCP_Types_part_3.o IPCP_Types_part_4.o IPCP_Types_part_5.o IPCP_Types_part_6.o IPCP_Types_part_7.o IPL4asp_Functions_part_1.o IPL4asp_Functions_part_2.o IPL4asp_Functions_part_3.o IPL4asp_Functions_part_4.o IPL4asp_Functions_part_5.o IPL4asp_Functions_part_6.o IPL4asp_Functions_part_7.o IPL4asp_PortType_part_1.o IPL4asp_PortType_part_2.o IPL4asp_PortType_part_3.o IPL4asp_PortType_part_4.o IPL4asp_PortType_part_5.o IPL4asp_PortType_part_6.o IPL4asp_PortType_part_7.o IPL4asp_Types_part_1.o IPL4asp_Types_part_2.o IPL4asp_Types_part_3.o IPL4asp_Types_part_4.o IPL4asp_Types_part_5.o IPL4asp_Types_part_6.o IPL4asp_Types_part_7.o IP_Types_part_1.o IP_Types_part_2.o IP_Types_part_3.o IP_Types_part_4.o IP_Types_part_5.o IP_Types_part_6.o IP_Types_part_7.o IuUP_Emulation_part_1.o IuUP_Emulation_part_2.o IuUP_Emulation_part_3.o IuUP_Emulation_part_4.o IuUP_Emulation_part_5.o IuUP_Emulation_part_6.o IuUP_Emulation_part_7.o IuUP_Types_part_1.o IuUP_Types_part_2.o IuUP_Types_part_3.o IuUP_Types_part_4.o IuUP_Types_part_5.o IuUP_Types_part_6.o IuUP_Types_part_7.o MGCP_CodecPort_part_1.o MGCP_CodecPort_part_2.o MGCP_CodecPort_part_3.o MGCP_CodecPort_part_4.o MGCP_CodecPort_part_5.o MGCP_CodecPort_part_6.o MGCP_CodecPort_part_7.o MGCP_CodecPort_CtrlFunct_part_1.o MGCP_CodecPort_CtrlFunct_part_2.o MGCP_CodecPort_CtrlFunct_part_3.o MGCP_CodecPort_CtrlFunct_part_4.o MGCP_CodecPort_CtrlFunct_part_5.o MGCP_CodecPort_CtrlFunct_part_6.o MGCP_CodecPort_CtrlFunct_part_7.o MGCP_Templates_part_1.o MGCP_Templates_part_2.o MGCP_Templates_part_3.o MGCP_Templates_part_4.o MGCP_Templates_part_5.o MGCP_Templates_part_6.o MGCP_Templates_part_7.o MGCP_Test_part_1.o MGCP_Test_part_2.o MGCP_Test_part_3.o MGCP_Test_part_4.o MGCP_Test_part_5.o MGCP_Test_part_6.o MGCP_Test_part_7.o MGCP_Types_part_1.o MGCP_Types_part_2.o MGCP_Types_part_3.o MGCP_Types_part_4.o MGCP_Types_part_5.o MGCP_Types_part_6.o MGCP_Types_part_7.o Misc_Helpers_part_1.o Misc_Helpers_part_2.o Misc_Helpers_part_3.o Misc_Helpers_part_4.o Misc_Helpers_part_5.o Misc_Helpers_part_6.o Misc_Helpers_part_7.o Native_Functions_part_1.o Native_Functions_part_2.o Native_Functions_part_3.o Native_Functions_part_4.o Native_Functions_part_5.o Native_Functions_part_6.o Native_Functions_part_7.o OSMUX_CodecPort_part_1.o OSMUX_CodecPort_part_2.o OSMUX_CodecPort_part_3.o OSMUX_CodecPort_part_4.o OSMUX_CodecPort_part_5.o OSMUX_CodecPort_part_6.o OSMUX_CodecPort_part_7.o OSMUX_CodecPort_CtrlFunct_part_1.o OSMUX_CodecPort_CtrlFunct_part_2.o OSMUX_CodecPort_CtrlFunct_part_3.o OSMUX_CodecPort_CtrlFunct_part_4.o OSMUX_CodecPort_CtrlFunct_part_5.o OSMUX_CodecPort_CtrlFunct_part_6.o OSMUX_CodecPort_CtrlFunct_part_7.o OSMUX_Emulation_part_1.o OSMUX_Emulation_part_2.o OSMUX_Emulation_part_3.o OSMUX_Emulation_part_4.o OSMUX_Emulation_part_5.o OSMUX_Emulation_part_6.o OSMUX_Emulation_part_7.o OSMUX_Types_part_1.o OSMUX_Types_part_2.o OSMUX_Types_part_3.o OSMUX_Types_part_4.o OSMUX_Types_part_5.o OSMUX_Types_part_6.o OSMUX_Types_part_7.o Osmocom_CTRL_Adapter_part_1.o Osmocom_CTRL_Adapter_part_2.o Osmocom_CTRL_Adapter_part_3.o Osmocom_CTRL_Adapter_part_4.o Osmocom_CTRL_Adapter_part_5.o Osmocom_CTRL_Adapter_part_6.o Osmocom_CTRL_Adapter_part_7.o Osmocom_CTRL_Functions_part_1.o Osmocom_CTRL_Functions_part_2.o Osmocom_CTRL_Functions_part_3.o Osmocom_CTRL_Functions_part_4.o Osmocom_CTRL_Functions_part_5.o Osmocom_CTRL_Functions_part_6.o Osmocom_CTRL_Functions_part_7.o Osmocom_CTRL_Types_part_1.o Osmocom_CTRL_Types_part_2.o Osmocom_CTRL_Types_part_3.o Osmocom_CTRL_Types_part_4.o Osmocom_CTRL_Types_part_5.o Osmocom_CTRL_Types_part_6.o Osmocom_CTRL_Types_part_7.o Osmocom_Types_part_1.o Osmocom_Types_part_2.o Osmocom_Types_part_3.o Osmocom_Types_part_4.o Osmocom_Types_part_5.o Osmocom_Types_part_6.o Osmocom_Types_part_7.o Osmocom_VTY_Functions_part_1.o Osmocom_VTY_Functions_part_2.o Osmocom_VTY_Functions_part_3.o Osmocom_VTY_Functions_part_4.o Osmocom_VTY_Functions_part_5.o Osmocom_VTY_Functions_part_6.o Osmocom_VTY_Functions_part_7.o RTP_CodecPort_part_1.o RTP_CodecPort_part_2.o RTP_CodecPort_part_3.o RTP_CodecPort_part_4.o RTP_CodecPort_part_5.o RTP_CodecPort_part_6.o RTP_CodecPort_part_7.o RTP_CodecPort_CtrlFunct_part_1.o RTP_CodecPort_CtrlFunct_part_2.o RTP_CodecPort_CtrlFunct_part_3.o RTP_CodecPort_CtrlFunct_part_4.o RTP_CodecPort_CtrlFunct_part_5.o RTP_CodecPort_CtrlFunct_part_6.o RTP_CodecPort_CtrlFunct_part_7.o RTP_Emulation_part_1.o RTP_Emulation_part_2.o RTP_Emulation_part_3.o RTP_Emulation_part_4.o RTP_Emulation_part_5.o RTP_Emulation_part_6.o RTP_Emulation_part_7.o RTP_Endpoint_part_1.o RTP_Endpoint_part_2.o RTP_Endpoint_part_3.o RTP_Endpoint_part_4.o RTP_Endpoint_part_5.o RTP_Endpoint_part_6.o RTP_Endpoint_part_7.o RTP_Types_part_1.o RTP_Types_part_2.o RTP_Types_part_3.o RTP_Types_part_4.o RTP_Types_part_5.o RTP_Types_part_6.o RTP_Types_part_7.o SDP_Templates_part_1.o SDP_Templates_part_2.o SDP_Templates_part_3.o SDP_Templates_part_4.o SDP_Templates_part_5.o SDP_Templates_part_6.o SDP_Templates_part_7.o SDP_Types_part_1.o SDP_Types_part_2.o SDP_Types_part_3.o SDP_Types_part_4.o SDP_Types_part_5.o SDP_Types_part_6.o SDP_Types_part_7.o Socket_API_Definitions_part_1.o Socket_API_Definitions_part_2.o Socket_API_Definitions_part_3.o Socket_API_Definitions_part_4.o Socket_API_Definitions_part_5.o Socket_API_Definitions_part_6.o Socket_API_Definitions_part_7.o StatsD_Checker_part_1.o StatsD_Checker_part_2.o StatsD_Checker_part_3.o StatsD_Checker_part_4.o StatsD_Checker_part_5.o StatsD_Checker_part_6.o StatsD_Checker_part_7.o StatsD_CodecPort_part_1.o StatsD_CodecPort_part_2.o StatsD_CodecPort_part_3.o StatsD_CodecPort_part_4.o StatsD_CodecPort_part_5.o StatsD_CodecPort_part_6.o StatsD_CodecPort_part_7.o StatsD_CodecPort_CtrlFunct_part_1.o StatsD_CodecPort_CtrlFunct_part_2.o StatsD_CodecPort_CtrlFunct_part_3.o StatsD_CodecPort_CtrlFunct_part_4.o StatsD_CodecPort_CtrlFunct_part_5.o StatsD_CodecPort_CtrlFunct_part_6.o StatsD_CodecPort_CtrlFunct_part_7.o StatsD_Types_part_1.o StatsD_Types_part_2.o StatsD_Types_part_3.o StatsD_Types_part_4.o StatsD_Types_part_5.o StatsD_Types_part_6.o StatsD_Types_part_7.o TCCConversion_Functions_part_1.o TCCConversion_Functions_part_2.o TCCConversion_Functions_part_3.o TCCConversion_Functions_part_4.o TCCConversion_Functions_part_5.o TCCConversion_Functions_part_6.o TCCConversion_Functions_part_7.o TCCInterface_Functions_part_1.o TCCInterface_Functions_part_2.o TCCInterface_Functions_part_3.o TCCInterface_Functions_part_4.o TCCInterface_Functions_part_5.o TCCInterface_Functions_part_6.o TCCInterface_Functions_part_7.o TELNETasp_PortType_part_1.o TELNETasp_PortType_part_2.o TELNETasp_PortType_part_3.o TELNETasp_PortType_part_4.o TELNETasp_PortType_part_5.o TELNETasp_PortType_part_6.o TELNETasp_PortType_part_7.o IPA_Emulation.o IPA_Emulation_part_1.o IPA_Emulation_part_2.o IPA_Emulation_part_3.o IPA_Emulation_part_4.o IPA_Emulation_part_5.o IPA_Emulation_part_6.o IPA_Emulation_part_7.o SDP_parse_.tab.o lex.SDP_parse_.o IPA_CodecPort_CtrlFunctDef.o IPL4asp_PT.o IPL4asp_discovery.o IP_EncDec.o IuUP_EncDec.o MGCP_CodecPort_CtrlFunctDef.o Native_FunctionDefs.o OSMUX_CodecPort_CtrlFunctDef.o RTP_CodecPort_CtrlFunctDef.o RTP_EncDec.o SDP_EncDec.o StatsD_CodecPort_CtrlFunctdef.o TCCConversion.o TCCInterface.o TELNETasp_PT.o; exit 1; fi
[0;94m[testenv] + ['podman', 'exec', '-e', 'CCACHE_DIR=/home/osmocom-build/ccache/testenv', '-e', 'TESTENV_CACHE_DIR=<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_cache',> '-e', 'TESTENV_SRC_DIR=/home/osmocom-build/jenkins/workspace', '-e', 'TERM=dumb', '-e', 'TESTENV_GIT_DIR=<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_cache/git',> '-e', 'PATH=<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_testenv/data/scr…>:<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_cache/podman/osm…>:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin', '-e', 'HOME=/home/osmocom-build', '-t', 'testenv-mgw-osmocom-nightly-20240821-0550-d3f47e39', 'sh', '-c', "echo 'deb [signed-by=/obs.key] https://downloads.osmocom.org/packages/osmocom:/nightly/Debian_12/ ./' > /etc/apt/sources.list.d/osmocom.list"][0m
[0;94m[testenv] + ['podman', 'exec', '-e', 'CCACHE_DIR=/home/osmocom-build/ccache/testenv', '-e', 'TESTENV_CACHE_DIR=<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_cache',> '-e', 'TESTENV_SRC_DIR=/home/osmocom-build/jenkins/workspace', '-e', 'TERM=dumb', '-e', 'TESTENV_GIT_DIR=<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_cache/git',> '-e', 'PATH=<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_testenv/data/scr…>:<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_cache/podman/osm…>:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin', '-e', 'HOME=/home/osmocom-build', '-t', 'testenv-mgw-osmocom-nightly-20240821-0550-d3f47e39', 'apt-get', '-q', 'update'][0m
Hit:1 http://deb.debian.org/debian bookworm InRelease
Hit:2 http://deb.debian.org/debian bookworm-updates InRelease
Hit:3 http://deb.debian.org/debian-security bookworm-security InRelease
Get:4 https://downloads.osmocom.org/packages/osmocom:/nightly/Debian_12 ./ InRelease [1579 B]
Ign:5 http://repo.mongodb.org/apt/debian bullseye/mongodb-org/5.0 InRelease
Hit:6 http://repo.mongodb.org/apt/debian bullseye/mongodb-org/5.0 Release
Get:7 https://downloads.osmocom.org/packages/osmocom:/nightly/Debian_12 ./ Packages [187 kB]
Fetched 189 kB in 1s (373 kB/s)
Reading package lists...
[1;34m[testenv] Installing packages: osmo-mgw[0m
[0;94m[testenv] + ['podman', 'exec', '-e', 'CCACHE_DIR=/home/osmocom-build/ccache/testenv', '-e', 'TESTENV_CACHE_DIR=<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_cache',> '-e', 'TESTENV_SRC_DIR=/home/osmocom-build/jenkins/workspace', '-e', 'TERM=dumb', '-e', 'TESTENV_GIT_DIR=<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_cache/git',> '-e', 'PATH=<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_testenv/data/scr…>:<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_cache/podman/osm…>:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin', '-e', 'HOME=/home/osmocom-build', '-t', 'testenv-mgw-osmocom-nightly-20240821-0550-d3f47e39', 'apt-get', '-q', 'install', '-y', '--no-install-recommends', 'osmo-mgw'][0m
Reading package lists...
Building dependency tree...
The following additional packages will be installed:
libosmoabis13 libosmocodec4 libosmocore22 libosmoctrl0 libosmogsm20
libosmoisdn0 libosmonetif11 libosmotrau10 libosmovty13 osmocom-nightly
The following NEW packages will be installed:
libosmoabis13 libosmocodec4 libosmocore22 libosmoctrl0 libosmogsm20
libosmoisdn0 libosmonetif11 libosmotrau10 libosmovty13 osmo-mgw
osmocom-nightly
0 upgraded, 11 newly installed, 0 to remove and 11 not upgraded.
Need to get 931 kB of archives.
After this operation, 2376 kB of additional disk space will be used.
Get:1 https://downloads.osmocom.org/packages/osmocom:/nightly/Debian_12 ./ osmocom-nightly 202408202026 [1176 B]
Get:2 https://downloads.osmocom.org/packages/osmocom:/nightly/Debian_12 ./ libosmocore22 1.10.0.7.1243.202408202026 [168 kB]
Get:3 https://downloads.osmocom.org/packages/osmocom:/nightly/Debian_12 ./ libosmoisdn0 1.10.0.7.1243.202408202026 [69.7 kB]
Get:4 https://downloads.osmocom.org/packages/osmocom:/nightly/Debian_12 ./ libosmogsm20 1.10.0.7.1243.202408202026 [227 kB]
Get:5 https://downloads.osmocom.org/packages/osmocom:/nightly/Debian_12 ./ libosmovty13 1.10.0.7.1243.202408202026 [103 kB]
Get:6 https://downloads.osmocom.org/packages/osmocom:/nightly/Debian_12 ./ libosmoabis13 1.6.0.202408202026 [73.2 kB]
Get:7 https://downloads.osmocom.org/packages/osmocom:/nightly/Debian_12 ./ libosmocodec4 1.10.0.7.1243.202408202026 [50.6 kB]
Get:8 https://downloads.osmocom.org/packages/osmocom:/nightly/Debian_12 ./ libosmoctrl0 1.10.0.7.1243.202408202026 [58.8 kB]
Get:9 https://downloads.osmocom.org/packages/osmocom:/nightly/Debian_12 ./ libosmonetif11 1.5.1.202408202026 [53.6 kB]
Get:10 https://downloads.osmocom.org/packages/osmocom:/nightly/Debian_12 ./ libosmotrau10 1.6.0.202408202026 [30.5 kB]
Get:11 https://downloads.osmocom.org/packages/osmocom:/nightly/Debian_12 ./ osmo-mgw 1.13.0.2.24ca.202408202026 [96.2 kB]
Fetched 931 kB in 0s (13.6 MB/s)
debconf: delaying package configuration, since apt-utils is not installed
Selecting previously unselected package osmocom-nightly.
(Reading database ... (Reading database ... 5%(Reading database ... 10%(Reading database ... 15%(Reading database ... 20%(Reading database ... 25%(Reading database ... 30%(Reading database ... 35%(Reading database ... 40%(Reading database ... 45%(Reading database ... 50%(Reading database ... 55%(Reading database ... 60%(Reading database ... 65%(Reading database ... 70%(Reading database ... 75%(Reading database ... 80%(Reading database ... 85%(Reading database ... 90%(Reading database ... 95%(Reading database ... 100%(Reading database ... 44218 files and directories currently installed.)
Preparing to unpack .../00-osmocom-nightly_202408202026_amd64.deb ...
Unpacking osmocom-nightly (202408202026) ...
Selecting previously unselected package libosmocore22:amd64.
Preparing to unpack .../01-libosmocore22_1.10.0.7.1243.202408202026_amd64.deb ...
Unpacking libosmocore22:amd64 (1.10.0.7.1243.202408202026) ...
Selecting previously unselected package libosmoisdn0:amd64.
Preparing to unpack .../02-libosmoisdn0_1.10.0.7.1243.202408202026_amd64.deb ...
Unpacking libosmoisdn0:amd64 (1.10.0.7.1243.202408202026) ...
Selecting previously unselected package libosmogsm20:amd64.
Preparing to unpack .../03-libosmogsm20_1.10.0.7.1243.202408202026_amd64.deb ...
Unpacking libosmogsm20:amd64 (1.10.0.7.1243.202408202026) ...
Selecting previously unselected package libosmovty13:amd64.
Preparing to unpack .../04-libosmovty13_1.10.0.7.1243.202408202026_amd64.deb ...
Unpacking libosmovty13:amd64 (1.10.0.7.1243.202408202026) ...
Selecting previously unselected package libosmoabis13:amd64.
Preparing to unpack .../05-libosmoabis13_1.6.0.202408202026_amd64.deb ...
Unpacking libosmoabis13:amd64 (1.6.0.202408202026) ...
Selecting previously unselected package libosmocodec4:amd64.
Preparing to unpack .../06-libosmocodec4_1.10.0.7.1243.202408202026_amd64.deb ...
Unpacking libosmocodec4:amd64 (1.10.0.7.1243.202408202026) ...
Selecting previously unselected package libosmoctrl0:amd64.
Preparing to unpack .../07-libosmoctrl0_1.10.0.7.1243.202408202026_amd64.deb ...
Unpacking libosmoctrl0:amd64 (1.10.0.7.1243.202408202026) ...
Selecting previously unselected package libosmonetif11:amd64.
Preparing to unpack .../08-libosmonetif11_1.5.1.202408202026_amd64.deb ...
Unpacking libosmonetif11:amd64 (1.5.1.202408202026) ...
Selecting previously unselected package libosmotrau10:amd64.
Preparing to unpack .../09-libosmotrau10_1.6.0.202408202026_amd64.deb ...
Unpacking libosmotrau10:amd64 (1.6.0.202408202026) ...
Selecting previously unselected package osmo-mgw.
Preparing to unpack .../10-osmo-mgw_1.13.0.2.24ca.202408202026_amd64.deb ...
Unpacking osmo-mgw (1.13.0.2.24ca.202408202026) ...
Setting up osmocom-nightly (202408202026) ...
Setting up libosmocore22:amd64 (1.10.0.7.1243.202408202026) ...
Setting up libosmocodec4:amd64 (1.10.0.7.1243.202408202026) ...
Setting up libosmovty13:amd64 (1.10.0.7.1243.202408202026) ...
Setting up libosmoisdn0:amd64 (1.10.0.7.1243.202408202026) ...
Setting up libosmotrau10:amd64 (1.6.0.202408202026) ...
Setting up libosmogsm20:amd64 (1.10.0.7.1243.202408202026) ...
Setting up libosmoabis13:amd64 (1.6.0.202408202026) ...
Setting up libosmoctrl0:amd64 (1.10.0.7.1243.202408202026) ...
Setting up libosmonetif11:amd64 (1.5.1.202408202026) ...
Setting up osmo-mgw (1.13.0.2.24ca.202408202026) ...
changed ownership of '/etc/osmocom/osmo-mgw.cfg' from root:root to osmocom:osmocom
mode of '/etc/osmocom/osmo-mgw.cfg' changed from 0644 (rw-r--r--) to 0660 (rw-rw----)
changed ownership of '/etc/osmocom' from root:root to root:osmocom
mode of '/etc/osmocom' changed from 0755 (rwxr-xr-x) to 2775 (rwxrwsr-x)
changed ownership of '/var/lib/osmocom' from root:root to osmocom:osmocom
Created symlink /etc/systemd/system/multi-user.target.wants/osmo-mgw.service → /lib/systemd/system/osmo-mgw.service.
Processing triggers for libc-bin (2.36-9+deb12u7) ...
[1;34m[testenv] Preparing testdir: <https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/logs[0m>
[0;94m[testenv] + ['podman', 'exec', '-e', 'CCACHE_DIR=/home/osmocom-build/ccache/testenv', '-e', 'TESTENV_CACHE_DIR=<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_cache',> '-e', 'TESTENV_SRC_DIR=/home/osmocom-build/jenkins/workspace', '-e', 'TERM=dumb', '-e', 'TESTENV_GIT_DIR=<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_cache/git',> '-e', 'PATH=<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_testenv/data/scr…>:<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_cache/podman/osm…>:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin', '-e', 'HOME=/home/osmocom-build', '-t', 'testenv-mgw-osmocom-nightly-20240821-0550-d3f47e39', 'install', '-Dm644', '<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_cache/podman/osm…',> '<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/2565/artifact/logs/t…'][0m>
[0;94m[testenv] + ['podman', 'exec', '-e', 'CCACHE_DIR=/home/osmocom-build/ccache/testenv', '-e', 'TESTENV_CACHE_DIR=<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_cache',> '-e', 'TESTENV_SRC_DIR=/home/osmocom-build/jenkins/workspace', '-e', 'TERM=dumb', '-e', 'TESTENV_GIT_DIR=<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_cache/git',> '-e', 'PATH=<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_testenv/data/scr…>:<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_cache/podman/osm…>:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin', '-e', 'HOME=/home/osmocom-build', '-t', 'testenv-mgw-osmocom-nightly-20240821-0550-d3f47e39', 'install', '-Dm644', '<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_cache/podman/osm…',> '<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/2565/artifact/logs/m…'][0m>
[0;94m[testenv] + ['podman', 'exec', '-e', 'CCACHE_DIR=/home/osmocom-build/ccache/testenv', '-e', 'TESTENV_CACHE_DIR=<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_cache',> '-e', 'TESTENV_SRC_DIR=/home/osmocom-build/jenkins/workspace', '-e', 'TERM=dumb', '-e', 'TESTENV_GIT_DIR=<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_cache/git',> '-e', 'PATH=<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_testenv/data/scr…>:<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_cache/podman/osm…>:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin', '-e', 'HOME=/home/osmocom-build', '-t', 'testenv-mgw-osmocom-nightly-20240821-0550-d3f47e39', 'install', '-Dm644', '<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_cache/podman/osm…',> '<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/2565/artifact/logs/t…'][0m>
[0;94m[testenv] + ['podman', 'exec', '-e', 'CCACHE_DIR=/home/osmocom-build/ccache/testenv', '-e', 'TESTENV_CACHE_DIR=<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_cache',> '-e', 'TESTENV_SRC_DIR=/home/osmocom-build/jenkins/workspace', '-e', 'TERM=dumb', '-e', 'TESTENV_GIT_DIR=<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_cache/git',> '-e', 'PATH=<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_testenv/data/scr…>:<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_cache/podman/osm…>:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin', '-e', 'HOME=/home/osmocom-build', '-t', 'testenv-mgw-osmocom-nightly-20240821-0550-d3f47e39', 'install', '-Dm644', '<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_cache/podman/osm…',> '<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/2565/artifact/logs/t…'][0m>
[0;94m[testenv] + ['podman', 'exec', '-e', 'CCACHE_DIR=/home/osmocom-build/ccache/testenv', '-e', 'TESTENV_CACHE_DIR=<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_cache',> '-e', 'TESTENV_SRC_DIR=/home/osmocom-build/jenkins/workspace', '-e', 'TERM=dumb', '-e', 'TESTENV_GIT_DIR=<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_cache/git',> '-e', 'PATH=<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_testenv/data/scr…>:<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_cache/podman/osm…>:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin', '-e', 'HOME=/home/osmocom-build', '-t', 'testenv-mgw-osmocom-nightly-20240821-0550-d3f47e39', 'sed', '-i', 's#TTCN3_HACKS_PATH := .*#TTCN3_HACKS_PATH := "<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_cache/podman/osm…">#', '<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/2565/artifact/logs/t…'][0m>
[0;94m[testenv] Adjusting testsuite config: <https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/2565/artifact/logs/t…>
[0;94m[testenv] + ['podman', 'exec', '-e', 'CCACHE_DIR=/home/osmocom-build/ccache/testenv', '-e', 'TESTENV_CACHE_DIR=<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_cache',> '-e', 'TESTENV_SRC_DIR=/home/osmocom-build/jenkins/workspace', '-e', 'TERM=dumb', '-e', 'TESTENV_GIT_DIR=<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_cache/git',> '-e', 'PATH=<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_testenv/data/scr…>:<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_cache/podman/osm…>:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin', '-e', 'HOME=/home/osmocom-build', '-t', 'testenv-mgw-osmocom-nightly-20240821-0550-d3f47e39', 'sed', '-i', '-e', 's/\\[MODULE_PARAMETERS\\]/\\[MODULE_PARAMETERS\\]\\nMisc_Helpers.mp_osmo_repo := "nightly"/g', '-e', 's#../Common.cfg#Common.cfg#', '<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/2565/artifact/logs/t…'][0m>
[0;94m[testenv] Adjusting testsuite config: <https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/2565/artifact/logs/t…>
[0;94m[testenv] + ['podman', 'exec', '-e', 'CCACHE_DIR=/home/osmocom-build/ccache/testenv', '-e', 'TESTENV_CACHE_DIR=<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_cache',> '-e', 'TESTENV_SRC_DIR=/home/osmocom-build/jenkins/workspace', '-e', 'TERM=dumb', '-e', 'TESTENV_GIT_DIR=<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_cache/git',> '-e', 'PATH=<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_testenv/data/scr…>:<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_cache/podman/osm…>:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin', '-e', 'HOME=/home/osmocom-build', '-t', 'testenv-mgw-osmocom-nightly-20240821-0550-d3f47e39', 'sed', '-i', '-e', 's/\\[MODULE_PARAMETERS\\]/\\[MODULE_PARAMETERS\\]\\nMisc_Helpers.mp_osmo_repo := "nightly"/g', '-e', 's#../Common.cfg#Common.cfg#', '<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/2565/artifact/logs/t…'][0m>
[0;94m[testenv] Adjusting testsuite config: <https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/2565/artifact/logs/t…>
[0;94m[testenv] + ['podman', 'exec', '-e', 'CCACHE_DIR=/home/osmocom-build/ccache/testenv', '-e', 'TESTENV_CACHE_DIR=<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_cache',> '-e', 'TESTENV_SRC_DIR=/home/osmocom-build/jenkins/workspace', '-e', 'TERM=dumb', '-e', 'TESTENV_GIT_DIR=<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_cache/git',> '-e', 'PATH=<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_testenv/data/scr…>:<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_cache/podman/osm…>:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin', '-e', 'HOME=/home/osmocom-build', '-t', 'testenv-mgw-osmocom-nightly-20240821-0550-d3f47e39', 'sed', '-i', '-e', 's/\\[MODULE_PARAMETERS\\]/\\[MODULE_PARAMETERS\\]\\nMisc_Helpers.mp_osmo_repo := "nightly"/g', '-e', 's#../Common.cfg#Common.cfg#', '<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/2565/artifact/logs/t…'][0m>
[1;34m[testenv] Running mgw[0m
[0;94m[testenv] + ['podman', 'exec', '-e', 'CCACHE_DIR=/home/osmocom-build/ccache/testenv', '-e', 'TESTENV_CACHE_DIR=<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_cache',> '-e', 'TESTENV_SRC_DIR=/home/osmocom-build/jenkins/workspace', '-e', 'TERM=dumb', '-e', 'TESTENV_GIT_DIR=<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_cache/git',> '-e', 'PATH=<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_testenv/data/scr…>:<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_cache/podman/osm…>:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin', '-e', 'HOME=/home/osmocom-build', '-w', '<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/2565/artifact/logs/m…',> 'testenv-mgw-osmocom-nightly-20240821-0550-d3f47e39', 'sh', '-c', 'osmo-mgw 2>&1 2>&1 | tee <https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/2565/artifact/logs/m…'][0m>
range must end at an odd port number, autocorrecting port (16000) to: 16001
% Deprecated 'sdp audio-payload number <0-255>' config no longer has any effect
% Deprecated 'sdp audio-payload name NAME' config no longer has any effect
% Deprecated 'loop (0|1)' config no longer has any effect
% Deprecated 'allow-transcoding' config no longer has any effect
% Deprecated 'loop (0|1)' config no longer has any effect
% Deprecated 'allow-transcoding' config no longer has any effect
20240821055033700 [1;33mDLGLOBAL[0;m [1;33mNOTICE[0;m <0003> Available via telnet 127.0.0.1 4243 (telnet_interface.c:88)
[38;5;33m20240821055033700 [1;33mDLCTRL[0;m[38;5;33m [1;33mNOTICE[0;m[38;5;33m <000a> CTRL at 127.0.0.1 4267 (control_if.c:1024)[0;m
[38;5;51m20240821055033700 [1;33mDLMGCP[0;m[38;5;51m [1;33mNOTICE[0;m[38;5;51m <0013> Configured for MGCP, listen on 127.0.0.1:2427 (mgw_main.c:424)[0;m
[1;34m[testenv] Running testsuite[0m
[0;94m[testenv] + ['podman', 'exec', '-e', 'CCACHE_DIR=/home/osmocom-build/ccache/testenv', '-e', 'TESTENV_CACHE_DIR=<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_cache',> '-e', 'TESTENV_SRC_DIR=/home/osmocom-build/jenkins/workspace', '-e', 'TERM=dumb', '-e', 'TESTENV_GIT_DIR=<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_cache/git',> '-e', 'PATH=<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_testenv/data/scr…>:<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_cache/podman/osm…>:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin', '-e', 'HOME=/home/osmocom-build', '-e', 'TTCN3_PCAP_PATH=<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/2565/artifact/logs/t…',> '-w', '<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/2565/artifact/logs/t…',> 'testenv-mgw-osmocom-nightly-20240821-0550-d3f47e39', '<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_cache/podman/osm…',> '<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_cache/podman/osm…',> 'MGCP_Test.cfg'][0m
ttcn3_start: Starting the test suite
ttcn3_start: warning: TTCN3_DIR environment variable is not set
spawn mctr_cli MGCP_Test.cfg
*************************************************************************
* TTCN-3 Test Executor - Main Controller 2 *
* Version: 9.0.0 *
* Copyright (c) 2000-2023 Ericsson Telecom AB *
* All rights reserved. This program and the accompanying materials *
* are made available under the terms of the Eclipse Public License v2.0 *
* which accompanies this distribution, and is available at *
* https://www.eclipse.org/org/documents/epl-2.0/EPL-2.0.html *
*************************************************************************
Using configuration file: MGCP_Test.cfg
MC@07ea47a26d26: Unix server socket created successfully.
MC@07ea47a26d26: Listening on TCP port 41581.
MC2> 07ea47a26d26 is the default
spawn <https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_cache/podman/osm…> 07ea47a26d26 41581
TTCN-3 Host Controller (parallel mode), version 9.0.0
MC@07ea47a26d26: New HC connected from 10.0.2.100 [10.0.2.100]. 07ea47a26d26: Linux 6.1.0-13-amd64 on x86_64.
cmtc
MC@07ea47a26d26: Downloading configuration file to all HCs.
construct junitlogger
Initializing `JUnitLogger' (v2.0): JUnitLogger writes JUnit-compatible XML
HC@07ea47a26d26: Warning: Option `FileMask' was given more than once in section [LOGGING] of the configuration file.
MC@07ea47a26d26: Configuration file was processed on all HCs.
MC@07ea47a26d26: Creating MTC on host 10.0.2.100.
MC@07ea47a26d26: MTC is created.
MC2> smtc
Executing all items of [EXECUTE] section.
MC2> MTC@07ea47a26d26: Starting external command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_cache/podman/osm…> MGCP_Test.TC_selftest'.
------ MGCP_Test.TC_selftest ------
Wed Aug 21 05:50:34 UTC 2024
/usr/bin/dumpcap -q -s 1520 -n -i any -w "<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/2565/artifact/logs/t…"> ><https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/2565/artifact/logs/t…> 2>/tmp/cmderr &
Waiting for packet dumper to start... 0
[0;94m[testenv] Testsuite is done[0m
[1;34m[testenv] Stopping testsuite (3173209)[0m
[1;34m[testenv] Merging log files[0m
[0;94m[testenv] + ['podman', 'exec', '-e', 'CCACHE_DIR=/home/osmocom-build/ccache/testenv', '-e', 'TESTENV_CACHE_DIR=<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_cache',> '-e', 'TESTENV_SRC_DIR=/home/osmocom-build/jenkins/workspace', '-e', 'TERM=dumb', '-e', 'TESTENV_GIT_DIR=<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_cache/git',> '-e', 'PATH=<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_testenv/data/scr…>:<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_cache/podman/osm…>:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin', '-e', 'HOME=/home/osmocom-build', '-t', '-w', '<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/2565/artifact/logs/t…',> 'testenv-mgw-osmocom-nightly-20240821-0550-d3f47e39', 'sh', '-c', '<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_cache/podman/osm…> MGCP_Test --rm >/dev/null'][0m
Error: no container with name or ID "testenv-mgw-osmocom-nightly-20240821-0550-d3f47e39" found: no such container
[1;91m[testenv] Command failed unexpectedly[0m
[0;94m[testenv] Command: ['podman', 'exec', '-e', 'CCACHE_DIR=/home/osmocom-build/ccache/testenv', '-e', 'TESTENV_CACHE_DIR=<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_cache',> '-e', 'TESTENV_SRC_DIR=/home/osmocom-build/jenkins/workspace', '-e', 'TERM=dumb', '-e', 'TESTENV_GIT_DIR=<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_cache/git',> '-e', 'PATH=<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_testenv/data/scr…>:<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_cache/podman/osm…>:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin', '-e', 'HOME=/home/osmocom-build', '-t', '-w', '<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/2565/artifact/logs/t…',> 'testenv-mgw-osmocom-nightly-20240821-0550-d3f47e39', 'sh', '-c', '<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_cache/podman/osm…> MGCP_Test --rm >/dev/null'][0m
[0;94m[testenv] Returncode: 125[0m
Traceback (most recent call last):
File "<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/./testenv.py",> line 115, in <module>
main()
File "<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/./testenv.py",> line 104, in main
run()
File "<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/./testenv.py",> line 63, in run
testenv.testsuite.run(cfg)
File "<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_testenv/testenv/…",> line 199, in run
merge_log_files(cfg)
File "<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_testenv/testenv/…",> line 131, in merge_log_files
testenv.cmd.run(cmd, cwd=cwd)
File "<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_testenv/testenv/…",> line 86, in run
return testenv.podman.exec_cmd(cmd, check=check, env=env, *args, **kwargs)
^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
File "<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_testenv/testenv/…",> line 133, in exec_cmd
testenv.cmd.run(
File "<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_testenv/testenv/…",> line 103, in run
exit_error_cmd(p, "Command failed unexpectedly")
File "<https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/ws/_testenv/testenv/…",> line 59, in exit_error_cmd
raise RuntimeError("shell command related error, find details right above this python trace")
RuntimeError: shell command related error, find details right above this python trace
Error: no container with name or ID "testenv-mgw-osmocom-nightly-20240821-0550-d3f47e39" found: no such container
[1;91m[testenv] podman container crashed![0m
[1;34m[testenv] Logs saved to: https://jenkins.osmocom.org/jenkins/job/ttcn3-mgw-test/2565/artifact/logs/ [0m
Build step 'Execute shell' marked build as failure
Recording test results
[Checks API] No suitable checks publisher found.
1
0
Build failed in Jenkins: master-openbsc » --disable-iu,--enable-mgcp-transcoding,--enable-smpp,default,osmocom-master #17440
by jenkins＠lists.osmocom.org 20 Aug '24
by jenkins＠lists.osmocom.org 20 Aug '24
20 Aug '24
See <https://jenkins.osmocom.org/jenkins/job/master-openbsc/IU=--disable-iu,MGCP…>
Changes:
------------------------------------------
[...truncated 463.34 KB...]
> ss -tln
State Recv-Q Send-Q Local Address:Port Peer Address:PortProcess
LISTEN 0 1 0.0.0.0:5001 0.0.0.0:*
LISTEN 0 10 127.0.0.1:4244 0.0.0.0:*
LISTEN 0 10 127.0.0.1:4250 0.0.0.0:*
LISTEN 0 1 127.0.0.1:5000 0.0.0.0:*
> ps xua | grep osmo
build 53453 0.0 0.0 13452 7440 ? S 15:11 0:00 ./src/osmo-bsc_nat/osmo-bsc_nat -l 127.0.0.1 -c /build/openbsc/tests/../doc/examples/osmo-bsc_nat/osmo-bsc-nat.cfg
build 53460 0.0 0.0 2576 892 ? S 15:11 0:00 /bin/sh -c ps xua | grep osmo
build 53462 0.0 0.0 3700 1760 ? S 15:11 0:00 grep osmo
Socket: in 1 tries, connected to 127.0.0.1:4244 <socket.socket fd=4, family=2, type=1, proto=0, laddr=('127.0.0.1', 54302), raddr=('127.0.0.1', 4244)> (1 sockets open)
MSC is ready at 127.0.0.1
'show msc connection' says: 'MSC is connected: 1'
ERROR
Socket: closing 127.0.0.1:4244 <socket.socket fd=4, family=2, type=1, proto=0, laddr=('127.0.0.1', 54302), raddr=('127.0.0.1', 4244)> (0 sockets open)
Process returned code: -15
testEnsureNoEnsureModeSet (__main__.TestVTYNAT.testEnsureNoEnsureModeSet) ... Launching: PWD=/build/openbsc './src/osmo-bsc_nat/osmo-bsc_nat' '-l' '127.0.0.1' '-c' '/build/openbsc/tests/../doc/examples/osmo-bsc_nat/osmo-bsc-nat.cfg'
> ls -l /proc/53192/fd
total 0
lr-x------ 1 build build 64 Aug 20 15:10 0 -> pipe:[867752857]
l-wx------ 1 build build 64 Aug 20 15:10 1 -> pipe:[867752858]
l-wx------ 1 build build 64 Aug 20 15:10 2 -> pipe:[867752859]
l-wx------ 1 build build 64 Aug 20 15:10 3 -> /dev/null
> ss -tn
State Recv-Q Send-Q Local Address:Port Peer Address:PortProcess
> ss -tln
State Recv-Q Send-Q Local Address:Port Peer Address:PortProcess
LISTEN 0 1 0.0.0.0:5001 0.0.0.0:*
LISTEN 0 10 127.0.0.1:4244 0.0.0.0:*
LISTEN 0 10 127.0.0.1:4250 0.0.0.0:*
LISTEN 0 1 127.0.0.1:5000 0.0.0.0:*
> ps xua | grep osmo
build 53463 0.0 0.0 13452 7428 ? S 15:11 0:00 ./src/osmo-bsc_nat/osmo-bsc_nat -l 127.0.0.1 -c /build/openbsc/tests/../doc/examples/osmo-bsc_nat/osmo-bsc-nat.cfg
build 53470 0.0 0.0 2576 948 ? S 15:11 0:00 /bin/sh -c ps xua | grep osmo
build 53472 0.0 0.0 3700 1796 ? S 15:11 0:00 grep osmo
Socket: in 1 tries, connected to 127.0.0.1:4244 <socket.socket fd=4, family=2, type=1, proto=0, laddr=('127.0.0.1', 54310), raddr=('127.0.0.1', 4244)> (1 sockets open)
Socket: closing 127.0.0.1:4244 <socket.socket fd=4, family=2, type=1, proto=0, laddr=('127.0.0.1', 54310), raddr=('127.0.0.1', 4244)> (0 sockets open)
Process returned code: -15
ok
testPrefixTreeLoading (__main__.TestVTYNAT.testPrefixTreeLoading) ... Launching: PWD=/build/openbsc './src/osmo-bsc_nat/osmo-bsc_nat' '-l' '127.0.0.1' '-c' '/build/openbsc/tests/../doc/examples/osmo-bsc_nat/osmo-bsc-nat.cfg'
> ls -l /proc/53192/fd
total 0
lr-x------ 1 build build 64 Aug 20 15:10 0 -> pipe:[867752857]
l-wx------ 1 build build 64 Aug 20 15:10 1 -> pipe:[867752858]
l-wx------ 1 build build 64 Aug 20 15:10 2 -> pipe:[867752859]
l-wx------ 1 build build 64 Aug 20 15:10 3 -> /dev/null
> ss -tn
State Recv-Q Send-Q Local Address:Port Peer Address:PortProcess
> ss -tln
State Recv-Q Send-Q Local Address:Port Peer Address:PortProcess
LISTEN 0 1 0.0.0.0:5001 0.0.0.0:*
LISTEN 0 10 127.0.0.1:4244 0.0.0.0:*
LISTEN 0 10 127.0.0.1:4250 0.0.0.0:*
LISTEN 0 1 127.0.0.1:5000 0.0.0.0:*
> ps xua | grep osmo
build 53473 0.0 0.0 13452 7540 ? S 15:11 0:00 ./src/osmo-bsc_nat/osmo-bsc_nat -l 127.0.0.1 -c /build/openbsc/tests/../doc/examples/osmo-bsc_nat/osmo-bsc-nat.cfg
build 53480 0.0 0.0 2576 900 ? S 15:11 0:00 /bin/sh -c ps xua | grep osmo
build 53482 0.0 0.0 3700 1800 ? S 15:11 0:00 grep osmo
Socket: in 1 tries, connected to 127.0.0.1:4244 <socket.socket fd=4, family=2, type=1, proto=0, laddr=('127.0.0.1', 54322), raddr=('127.0.0.1', 4244)> (1 sockets open)
Socket: closing 127.0.0.1:4244 <socket.socket fd=4, family=2, type=1, proto=0, laddr=('127.0.0.1', 54322), raddr=('127.0.0.1', 4244)> (0 sockets open)
Process returned code: -15
ok
testRewriteNoRewrite (__main__.TestVTYNAT.testRewriteNoRewrite) ... Launching: PWD=/build/openbsc './src/osmo-bsc_nat/osmo-bsc_nat' '-l' '127.0.0.1' '-c' '/build/openbsc/tests/../doc/examples/osmo-bsc_nat/osmo-bsc-nat.cfg'
> ls -l /proc/53192/fd
total 0
lr-x------ 1 build build 64 Aug 20 15:10 0 -> pipe:[867752857]
l-wx------ 1 build build 64 Aug 20 15:10 1 -> pipe:[867752858]
l-wx------ 1 build build 64 Aug 20 15:10 2 -> pipe:[867752859]
l-wx------ 1 build build 64 Aug 20 15:10 3 -> /dev/null
> ss -tn
State Recv-Q Send-Q Local Address:Port Peer Address:PortProcess
> ss -tln
State Recv-Q Send-Q Local Address:Port Peer Address:PortProcess
LISTEN 0 1 0.0.0.0:5001 0.0.0.0:*
LISTEN 0 10 127.0.0.1:4244 0.0.0.0:*
LISTEN 0 10 127.0.0.1:4250 0.0.0.0:*
LISTEN 0 1 127.0.0.1:5000 0.0.0.0:*
> ps xua | grep osmo
build 53483 0.0 0.0 13452 6860 ? S 15:11 0:00 ./src/osmo-bsc_nat/osmo-bsc_nat -l 127.0.0.1 -c /build/openbsc/tests/../doc/examples/osmo-bsc_nat/osmo-bsc-nat.cfg
build 53490 0.0 0.0 2576 896 ? S 15:11 0:00 /bin/sh -c ps xua | grep osmo
build 53492 0.0 0.0 3700 1744 ? S 15:11 0:00 grep osmo
Socket: in 1 tries, connected to 127.0.0.1:4244 <socket.socket fd=4, family=2, type=1, proto=0, laddr=('127.0.0.1', 54330), raddr=('127.0.0.1', 4244)> (1 sockets open)
Socket: closing 127.0.0.1:4244 <socket.socket fd=4, family=2, type=1, proto=0, laddr=('127.0.0.1', 54330), raddr=('127.0.0.1', 4244)> (0 sockets open)
Process returned code: -15
ok
testRewritePostNoRewrite (__main__.TestVTYNAT.testRewritePostNoRewrite) ... Launching: PWD=/build/openbsc './src/osmo-bsc_nat/osmo-bsc_nat' '-l' '127.0.0.1' '-c' '/build/openbsc/tests/../doc/examples/osmo-bsc_nat/osmo-bsc-nat.cfg'
> ls -l /proc/53192/fd
total 0
lr-x------ 1 build build 64 Aug 20 15:10 0 -> pipe:[867752857]
l-wx------ 1 build build 64 Aug 20 15:10 1 -> pipe:[867752858]
l-wx------ 1 build build 64 Aug 20 15:10 2 -> pipe:[867752859]
l-wx------ 1 build build 64 Aug 20 15:10 3 -> /dev/null
> ss -tn
State Recv-Q Send-Q Local Address:Port Peer Address:PortProcess
> ss -tln
State Recv-Q Send-Q Local Address:Port Peer Address:PortProcess
LISTEN 0 1 0.0.0.0:5001 0.0.0.0:*
LISTEN 0 10 127.0.0.1:4244 0.0.0.0:*
LISTEN 0 10 127.0.0.1:4250 0.0.0.0:*
LISTEN 0 1 127.0.0.1:5000 0.0.0.0:*
> ps xua | grep osmo
build 53493 0.0 0.0 13452 7376 ? S 15:11 0:00 ./src/osmo-bsc_nat/osmo-bsc_nat -l 127.0.0.1 -c /build/openbsc/tests/../doc/examples/osmo-bsc_nat/osmo-bsc-nat.cfg
build 53500 0.0 0.0 2576 956 ? S 15:11 0:00 /bin/sh -c ps xua | grep osmo
build 53502 0.0 0.0 3700 1832 ? S 15:11 0:00 grep osmo
Socket: in 1 tries, connected to 127.0.0.1:4244 <socket.socket fd=4, family=2, type=1, proto=0, laddr=('127.0.0.1', 54338), raddr=('127.0.0.1', 4244)> (1 sockets open)
Socket: closing 127.0.0.1:4244 <socket.socket fd=4, family=2, type=1, proto=0, laddr=('127.0.0.1', 54338), raddr=('127.0.0.1', 4244)> (0 sockets open)
Process returned code: -15
ok
testUssdSideChannelProvider (__main__.TestVTYNAT.testUssdSideChannelProvider) ... Launching: PWD=/build/openbsc './src/osmo-bsc_nat/osmo-bsc_nat' '-l' '127.0.0.1' '-c' '/build/openbsc/tests/../doc/examples/osmo-bsc_nat/osmo-bsc-nat.cfg'
> ls -l /proc/53192/fd
total 0
lr-x------ 1 build build 64 Aug 20 15:10 0 -> pipe:[867752857]
l-wx------ 1 build build 64 Aug 20 15:10 1 -> pipe:[867752858]
l-wx------ 1 build build 64 Aug 20 15:10 2 -> pipe:[867752859]
l-wx------ 1 build build 64 Aug 20 15:10 3 -> /dev/null
> ss -tn
State Recv-Q Send-Q Local Address:Port Peer Address:PortProcess
> ss -tln
State Recv-Q Send-Q Local Address:Port Peer Address:PortProcess
LISTEN 0 1 0.0.0.0:5001 0.0.0.0:*
LISTEN 0 10 127.0.0.1:4244 0.0.0.0:*
LISTEN 0 10 127.0.0.1:4250 0.0.0.0:*
LISTEN 0 1 127.0.0.1:5000 0.0.0.0:*
> ps xua | grep osmo
build 53503 0.0 0.0 13452 7440 ? S 15:11 0:00 ./src/osmo-bsc_nat/osmo-bsc_nat -l 127.0.0.1 -c /build/openbsc/tests/../doc/examples/osmo-bsc_nat/osmo-bsc-nat.cfg
build 53510 0.0 0.0 2576 948 ? S 15:11 0:00 /bin/sh -c ps xua | grep osmo
build 53512 0.0 0.0 3700 1896 ? S 15:11 0:00 grep osmo
Socket: in 1 tries, connected to 127.0.0.1:4244 <socket.socket fd=4, family=2, type=1, proto=0, laddr=('127.0.0.1', 48568), raddr=('127.0.0.1', 4244)> (1 sockets open)
Connected to 127.0.0.1:5001
Expecting ID_GET request
Going to send ID_RESP response
Going to send PING request
Expecting PONG response
Going to shut down connection
Expecting EOF
Socket: closing 127.0.0.1:4244 <socket.socket fd=4, family=2, type=1, proto=0, laddr=('127.0.0.1', 48568), raddr=('127.0.0.1', 4244)> (0 sockets open)
Process returned code: -15
ok
testVtyTree (__main__.TestVTYNAT.testVtyTree) ... Launching: PWD=/build/openbsc './src/osmo-bsc_nat/osmo-bsc_nat' '-l' '127.0.0.1' '-c' '/build/openbsc/tests/../doc/examples/osmo-bsc_nat/osmo-bsc-nat.cfg'
> ls -l /proc/53192/fd
total 0
lr-x------ 1 build build 64 Aug 20 15:10 0 -> pipe:[867752857]
l-wx------ 1 build build 64 Aug 20 15:10 1 -> pipe:[867752858]
l-wx------ 1 build build 64 Aug 20 15:10 2 -> pipe:[867752859]
l-wx------ 1 build build 64 Aug 20 15:10 3 -> /dev/null
> ss -tn
State Recv-Q Send-Q Local Address:Port Peer Address:PortProcess
> ss -tln
State Recv-Q Send-Q Local Address:Port Peer Address:PortProcess
LISTEN 0 1 0.0.0.0:5001 0.0.0.0:*
LISTEN 0 10 127.0.0.1:4244 0.0.0.0:*
LISTEN 0 10 127.0.0.1:4250 0.0.0.0:*
LISTEN 0 1 127.0.0.1:5000 0.0.0.0:*
> ps xua | grep osmo
build 53513 0.0 0.0 13452 7332 ? S 15:11 0:00 ./src/osmo-bsc_nat/osmo-bsc_nat -l 127.0.0.1 -c /build/openbsc/tests/../doc/examples/osmo-bsc_nat/osmo-bsc-nat.cfg
build 53520 0.0 0.0 2576 904 ? S 15:11 0:00 /bin/sh -c ps xua | grep osmo
build 53522 0.0 0.0 3700 1784 ? S 15:11 0:00 grep osmo
Socket: in 1 tries, connected to 127.0.0.1:4244 <socket.socket fd=4, family=2, type=1, proto=0, laddr=('127.0.0.1', 48574), raddr=('127.0.0.1', 4244)> (1 sockets open)
Socket: closing 127.0.0.1:4244 <socket.socket fd=4, family=2, type=1, proto=0, laddr=('127.0.0.1', 48574), raddr=('127.0.0.1', 4244)> (0 sockets open)
Process returned code: -15
ok
======================================================================
ERROR: testBSCreload (__main__.TestVTYNAT.testBSCreload)
----------------------------------------------------------------------
Traceback (most recent call last):
File "/build/openbsc/tests/./vty_test_runner.py", line 803, in testBSCreload
msc_socket, msc = nat_msc_test(self, ip, port, verbose=True)
^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
File "/build/openbsc/tests/./vty_test_runner.py", line 1047, in nat_msc_test
raise Exception("VTY reports MSC is connected, but I haven't"
Exception: VTY reports MSC is connected, but I haven't connected yet: '127.0.0.1' 5522
----------------------------------------------------------------------
Ran 33 tests in 80.018s
FAILED (errors=1)
make[5]: *** [Makefile:717: python-tests] Error 1
make[5]: Leaving directory '/build/openbsc/tests'
make[4]: *** [Makefile:726: check-local] Error 2
make[4]: Leaving directory '/build/openbsc/tests'
make[3]: *** [Makefile:575: check-am] Error 2
make[3]: Leaving directory '/build/openbsc/tests'
make[2]: *** [Makefile:425: check-recursive] Error 1
make[2]: Leaving directory '/build/openbsc/tests'
make[1]: *** [Makefile:457: check-recursive] Error 1
make[1]: Leaving directory '/build/openbsc'
make: *** [Makefile:756: check] Error 2
+ cat-testlogs.sh
Build step 'Execute shell' marked build as failure
$ ssh-agent -k
unset SSH_AUTH_SOCK;
unset SSH_AGENT_PID;
echo Agent pid 4098414 killed;
[ssh-agent] Stopped.
Archiving artifacts
1
1
20 Aug '24
See <https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-asan/78/display/red…>
Changes:
[Pau Espin Pedrol] ggsn: Split test and relax expectations testing gtpu access over ipv6
[Pau Espin Pedrol] sgsn: Fix running with testenv.py podman setup
[Pau Espin Pedrol] sgsn: Introduce test TC_attach_rau_a_b_wrong_old_ra
[Pau Espin Pedrol] deps/Makefile: Use upstream titan.TestPorts.SIPmsg
------------------------------------------
[...truncated 1.50 MB...]
[1;33m20240820124814125 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM(262420000000039/cd638218) Updating authorization (authenticate -> accepted) (sgsn_auth.c:217)[0;m
[1;33m20240820124814125 [1;32mDMM[0;m[1;33m [1;32mINFO[0;m[1;33m MM(262420000000039/cd638218) Got authorization update: state authenticate -> accepted (sgsn_auth.c:246)[0;m
[1;33m20240820124814125 [1;33mDMM[0;m[1;33m [1;33mNOTICE[0;m[1;33m MM(262420000000039/cd638218) Authorized, continuing procedure, IMSI=262420000000039 (gprs_gmm.c:1000)[0;m
[1;33m20240820124814125 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x612000012e20]{WaitAttachComplete}: Received Event E_VLR_ANSWERED (gprs_gmm.c:1005)[0;m
[1;33m20240820124814125 [1;33mDMM[0;m[1;33m [1;33mNOTICE[0;m[1;33m MM(262420000000039/cd638218) Unusual event: if MS got no data connection, check that it has APN configured. (gprs_gmm_attach.c:217)[0;m
20240820124814125 [1;32mDGPRS[0;m [1;32mINFO[0;m SUBSCR(262420000000039) Sending GSUP, will send: 12 01 08 62 42 02 00 00 00 30 f9 28 01 01 (gprs_subscriber.c:210)
20240820124814126 [1;32mDGPRS[0;m [1;32mINFO[0;m SUBSCR(262420000000039) Received GSUP message OSMO_GSUP_MSGT_UPDATE_LOCATION_RESULT (gprs_subscriber.c:723)
20240820124814126 [1;34mDGPRS[0;m [1;34mDEBUG[0;m SUBSCR(262420000000039) Updating subscriber data (gprs_subscriber.c:839)
[1;33m20240820124814126 [1;32mDMM[0;m[1;33m [1;32mINFO[0;m[1;33m MM(262420000000039/cd638218) Subscriber data update (mmctx.c:430)[0;m
[1;33m20240820124814126 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM(262420000000039/cd638218) Updating authorization (accepted -> accepted) (sgsn_auth.c:217)[0;m
[38;5;57m20240820124814130 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00096-NSVC00097-127_0_0_10:23000-127_0_0_103:21010)[0x612000004ba0]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
20240820124814130 [1;34mDLLC[0;m [1;34mDEBUG[0;m LLC SAPI=1 C U GEA0 IOV-UI=0x000000 FCS=0xffae8e CMD=UI DATA (gprs_llc_parse.c:89)
[1;33m20240820124814130 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM_STATE_Gb(0)[0x612000012fa0]{Idle}: Received Event E_MM_PDU_RECEPTION (gprs_gmm.c:2333)[0;m
[1;33m20240820124814130 [1;32mDMM[0;m[1;33m [1;32mINFO[0;m[1;33m MM(262420000000039/cd638218) -> GMM ATTACH COMPLETE (gprs_gmm.c:1397)[0;m
[1;33m20240820124814130 [1;33mDMM[0;m[1;33m [1;33mNOTICE[0;m[1;33m MM(262420000000039/cd638218) Stopping *inactive* MM timer 3350 (gprs_gmm.c:115)[0;m
[1;33m20240820124814130 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM(gmm_fsm)[0x612000012ca0]{CommonProcedureInitiated}: Received Event E_GMM_ATTACH_SUCCESS (gprs_gmm.c:1409)[0;m
[1;33m20240820124814130 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM(gmm_fsm)[0x612000012ca0]{CommonProcedureInitiated}: state_chg to Registered.NORMAL (gprs_gmm_fsm.c:72)[0;m
20240820124814130 [1;33mDLLC[0;m [1;33mNOTICE[0;m LLME(c3923305/cd638218){ASSIGNED} LLGM Assign pre (ffffffff => cd638218) (gprs_llc.c:1079)
20240820124814130 [1;33mDLLC[0;m [1;33mNOTICE[0;m LLME(ffffffff/cd638218){ASSIGNED} LLGM Assign post (ffffffff => cd638218) (gprs_llc.c:1125)
[1;33m20240820124814130 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM_STATE_Gb(0)[0x612000012fa0]{Idle}: Received Event E_MM_GPRS_ATTACH (gprs_gmm.c:1420)[0;m
[1;33m20240820124814130 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM_STATE_Gb(0)[0x612000012fa0]{Idle}: state_chg to Ready (gprs_mm_state_gb_fsm.c:60)[0;m
[1;33m20240820124814130 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x612000012e20]{WaitAttachComplete}: Received Event E_ATTACH_COMPLETE_RECV (gprs_gmm.c:1424)[0;m
[1;33m20240820124814130 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x612000012e20]{WaitAttachComplete}: state_chg to Init (gprs_gmm_attach.c:212)[0;m
TC_attach_rau_a_b_wrong_old_ra(535)@204f4a9cdd34: setverdict(pass): none -> pass
TC_attach_rau_a_b_wrong_old_ra(535)@204f4a9cdd34: attach complete sending rau
[38;5;57m20240820124814181 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00096-NSVC00097-127_0_0_10:23000-127_0_0_103:21010)[0x612000004ba0]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
20240820124814182 [1;34mDLLC[0;m [1;34mDEBUG[0;m LLC SAPI=1 C U GEA0 IOV-UI=0x000000 FCS=0xbcf888 CMD=UI DATA (gprs_llc_parse.c:89)
[1;33m20240820124814182 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM_STATE_Gb(0)[0x612000012fa0]{Ready}: Received Event E_MM_PDU_RECEPTION (gprs_gmm.c:2333)[0;m
[1;33m20240820124814182 [1;32mDMM[0;m[1;33m [1;32mINFO[0;m[1;33m MM(262420000000039/cd638218) -> GMM RA UPDATE REQUEST type="RA updating" (gprs_gmm.c:1641)[0;m
[1;33m20240820124814182 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM(gmm_fsm)[0x612000012ca0]{Registered.NORMAL}: Received Event E_GMM_COMMON_PROC_INIT_REQ (gprs_gmm.c:1144)[0;m
[1;33m20240820124814182 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM(gmm_fsm)[0x612000012ca0]{Registered.NORMAL}: state_chg to CommonProcedureInitiated (gprs_gmm_fsm.c:81)[0;m
20240820124814182 [1;33mDLLC[0;m [1;33mNOTICE[0;m LLME(ffffffff/cd638218){ASSIGNED} LLGM Assign pre (cd638218 => e15a1c05) (gprs_llc.c:1079)
20240820124814182 [1;33mDLLC[0;m [1;33mNOTICE[0;m LLME(cd638218/e15a1c05){ASSIGNED} LLGM Assign post (cd638218 => e15a1c05) (gprs_llc.c:1125)
[1;33m20240820124814182 [1;32mDMM[0;m[1;33m [1;32mINFO[0;m[1;33m MM(262420000000039/e15a1c05) <- GMM ROUTING AREA UPDATE ACCEPT (gprs_gmm.c:1504)[0;m
TC_attach_rau_a_b_wrong_old_ra(535)@204f4a9cdd34: setverdict(pass): pass -> pass, component reason not changed
TC_attach_rau_a_b_wrong_old_ra(535)@204f4a9cdd34: setverdict(pass): pass -> pass, component reason not changed
TC_attach_rau_a_b_wrong_old_ra(535)@204f4a9cdd34: rau complete unregistering
TC_attach_rau_a_b_wrong_old_ra-BVCI196(520)@204f4a9cdd34: Removing Client IMSI='262420000000039'H, index=0
[38;5;57m20240820124814187 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00096-NSVC00097-127_0_0_10:23000-127_0_0_103:21010)[0x612000004ba0]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
20240820124814187 [1;34mDLLC[0;m [1;34mDEBUG[0;m LLC SAPI=1 C U GEA0 IOV-UI=0x000000 FCS=0xb0b0ba CMD=UI DATA (gprs_llc_parse.c:89)
[1;33m20240820124814187 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM_STATE_Gb(0)[0x612000012fa0]{Ready}: Received Event E_MM_PDU_RECEPTION (gprs_gmm.c:2333)[0;m
[1;33m20240820124814187 [1;32mDMM[0;m[1;33m [1;32mINFO[0;m[1;33m MM(262420000000039/e15a1c05) -> ROUTING AREA UPDATE COMPLETE (gprs_gmm.c:1847)[0;m
[1;33m20240820124814187 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM(gmm_fsm)[0x612000012ca0]{CommonProcedureInitiated}: Received Event E_GMM_COMMON_PROC_SUCCESS (gprs_gmm.c:1852)[0;m
[1;33m20240820124814187 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM(gmm_fsm)[0x612000012ca0]{CommonProcedureInitiated}: state_chg to Registered.NORMAL (gprs_gmm_fsm.c:72)[0;m
20240820124814188 [1;33mDLLC[0;m [1;33mNOTICE[0;m LLME(cd638218/e15a1c05){ASSIGNED} LLGM Assign pre (ffffffff => e15a1c05) (gprs_llc.c:1079)
20240820124814188 [1;33mDLLC[0;m [1;33mNOTICE[0;m LLME(ffffffff/e15a1c05){ASSIGNED} LLGM Assign post (ffffffff => e15a1c05) (gprs_llc.c:1125)
[1;33m20240820124814188 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM_STATE_Gb(0)[0x612000012fa0]{Ready}: Received Event E_MM_RA_UPDATE (gprs_gmm.c:1862)[0;m
TC_attach_rau_a_b_wrong_old_ra-BVCI210(525)@204f4a9cdd34: Adding Client=TC_attach_rau_a_b_wrong_old_ra(535), IMSI='262420000000039'H, TLLI='E15A1C05'O, index=0
TC_attach_rau_a_b_wrong_old_ra(535)@204f4a9cdd34: sending second RAU via different RA with wrong Old RA
[38;5;57m20240820124814191 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00097-NSVC00098-127_0_0_10:23000-127_0_0_103:21011)[0x612000004d20]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
20240820124814191 [1;34mDLLC[0;m [1;34mDEBUG[0;m LLC SAPI=1 C U GEA0 IOV-UI=0x000000 FCS=0xfedba3 CMD=UI DATA (gprs_llc_parse.c:89)
[1;33m20240820124814191 [1;32mDMM[0;m[1;33m [1;32mINFO[0;m[1;33m MM(---/ffffffff) -> GMM RA UPDATE REQUEST type="RA updating" (gprs_gmm.c:1641)[0;m
[1;33m20240820124814191 [1;33mDMM[0;m[1;33m [1;33mNOTICE[0;m[1;33m LLME(ffffffff/e15a1c05){ASSIGNED} LLC XID RESET (gprs_gmm.c:1749)[0;m
20240820124814191 [1;33mDLLC[0;m [1;33mNOTICE[0;m LLME(ffffffff/e15a1c05){ASSIGNED} LLGM Reset (SAPI=1) (gprs_llc.c:1186)
[1;33m20240820124814192 [1;31mDMM[0;m[1;33m [1;31mERROR[0;m[1;33m LLME(ffffffff/e15a1c05){ASSIGNED} Rejecting GMM RA Update Request: MS should GMM Attach first (gprs_gmm.c:1755)[0;m
[1;33m20240820124814192 [1;33mDMM[0;m[1;33m [1;33mNOTICE[0;m[1;33m MM(---/ffffffff) Rejecting RA Update Request with cause 'Implicitly detached' (10) (gprs_gmm.c:1824)[0;m
[1;33m20240820124814192 [1;33mDMM[0;m[1;33m [1;33mNOTICE[0;m[1;33m <- ROUTING AREA UPDATE REJECT (gprs_gmm.c:1558)[0;m
20240820124814192 [1;33mDLLC[0;m [1;33mNOTICE[0;m LLME(ffffffff/e15a1c05){ASSIGNED} LLGM Assign pre (e15a1c05 => ffffffff) (gprs_llc.c:1079)
20240820124814192 [1;33mDLLC[0;m [1;33mNOTICE[0;m LLME(00000000/00000000){UNASSIGNED} LLGM Assign post (e15a1c05 => ffffffff) (gprs_llc.c:1125)
[38;5;57m20240820124814202 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00097-NSVC00098-127_0_0_10:23000-127_0_0_103:21011)[0x612000004d20]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
20240820124814202 [1;33mDLLC[0;m [1;33mNOTICE[0;m LLME(ffffffff/e15a1c05){UNASSIGNED} LLC RX: unknown TLLI 0xe15a1c05, creating LLME on the fly (gprs_llc.c:552)
20240820124814202 [1;34mDLLC[0;m [1;34mDEBUG[0;m LLC SAPI=1 C U GEA0 IOV-UI=0x000000 FCS=0x912c5c CMD=UI DATA (gprs_llc_parse.c:89)
[1;33m20240820124814202 [1;33mDMM[0;m[1;33m [1;33mNOTICE[0;m[1;33m LLME(ffffffff/e15a1c05){UNASSIGNED} Cannot handle GMM for unknown MM CTX (gprs_gmm.c:2035)[0;m
20240820124818471 [1;34mDGPRS[0;m [1;34mDEBUG[0;m Checking for inactive LLMEs, time = 5968832 (sgsn.c:131)
[38;5;39m20240820124833053 [1;32mDLGSUP[0;m[38;5;39m [1;32mINFO[0;m[38;5;39m GSUP ping callback (connected, got PONG) (gsup_client.c:266)[0;m
[38;5;39m20240820124833053 [1;34mDLGSUP[0;m[38;5;39m [1;34mDEBUG[0;m[38;5;39m GSUP sending PING (gsup_client.c:288)[0;m
SGSN_Test-GSUP-IPA(532)@204f4a9cdd34: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit }
SGSN_Test-GSUP-IPA(532)@204f4a9cdd34: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit }
[38;5;39m20240820124833053 [1;34mDLGSUP[0;m[38;5;39m [1;34mDEBUG[0;m[38;5;39m GSUP receiving PONG (gsup_client.c:225)[0;m
[38;5;63m20240820124842219 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00096)-NSVC(00097) Tx NS-ALIVE (gprs_ns2_message.c:193)[0;m
[38;5;63m20240820124842220 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00096)-NSVC(00097) Rx NS-ALIVE-ACK (gprs_ns2.c:1309)[0;m
[38;5;57m20240820124842220 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00096-NSVC00097-127_0_0_10:23000-127_0_0_103:21010)[0x612000004ba0]{UNBLOCKED}: Received Event RX-ALIVE_ACK (gprs_ns2_vc_fsm.c:960)[0;m
[38;5;63m20240820124842255 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00097)-NSVC(00098) Tx NS-ALIVE (gprs_ns2_message.c:193)[0;m
[38;5;63m20240820124842256 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00097)-NSVC(00098) Rx NS-ALIVE-ACK (gprs_ns2.c:1309)[0;m
[38;5;57m20240820124842256 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00097-NSVC00098-127_0_0_10:23000-127_0_0_103:21011)[0x612000004d20]{UNBLOCKED}: Received Event RX-ALIVE_ACK (gprs_ns2_vc_fsm.c:960)[0;m
[38;5;63m20240820124842289 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00098)-NSVC(00099) Tx NS-ALIVE (gprs_ns2_message.c:193)[0;m
[38;5;63m20240820124842290 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00098)-NSVC(00099) Rx NS-ALIVE-ACK (gprs_ns2.c:1309)[0;m
[38;5;57m20240820124842290 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00098-NSVC00099-127_0_0_10:23000-127_0_0_103:21012)[0x612000004ea0]{UNBLOCKED}: Received Event RX-ALIVE_ACK (gprs_ns2_vc_fsm.c:960)[0;m
TC_attach_rau_a_b_wrong_old_ra(535)@204f4a9cdd34: setverdict(fail): pass -> fail reason: "Tguard timeout", new component reason: "Tguard timeout"
MC@204f4a9cdd34: Test Component 535 has requested to stop MTC. Terminating current testcase execution.
SGSN_Test-Gb0-BSSGP0(518)@204f4a9cdd34: Final verdict of PTC: none
TC_attach_rau_a_b_wrong_old_ra-BVCI210(525)@204f4a9cdd34: Final verdict of PTC: none
TC_attach_rau_a_b_wrong_old_ra-BVCI196(520)@204f4a9cdd34: Final verdict of PTC: none
SGSN_Test-Gb0-NS0(517)@204f4a9cdd34: Final verdict of PTC: none
SGSN_Test-Gb1-NS1(522)@204f4a9cdd34: Final verdict of PTC: none
20240820124844119 [1;32mDLGLOBAL[0;m [1;32mINFO[0;m Closing telnet connection r=127.0.0.1:45628<->l=127.0.0.10:4245 (telnet_interface.c:138)
-NSVCI97(521)@204f4a9cdd34: Final verdict of PTC: none
-NSVCI98(526)@204f4a9cdd34: Final verdict of PTC: none
-NSVCI99(531)@204f4a9cdd34: Final verdict of PTC: none
SGSN_Test-Gb2-NS2(527)@204f4a9cdd34: Final verdict of PTC: none
TC_attach_rau_a_b_wrong_old_ra(535)@204f4a9cdd34: Final verdict of PTC: fail reason: "Tguard timeout"
[38;5;23m20240820124844120 [1;31mDLINP[0;m[38;5;23m [1;31mERROR[0;m[38;5;23m 127.0.0.103:4222 connection closed with server (ipa.c:68)[0;m
[38;5;39m20240820124844121 [1;32mDLGSUP[0;m[38;5;39m [1;32mINFO[0;m[38;5;39m GSUP link to 127.0.0.103:4222 DOWN (gsup_client.c:151)[0;m
SGSN_Test-GGSN_GTP(534)@204f4a9cdd34: Final verdict of PTC: none
SGSN_Test-Gb2-BSSGP2(528)@204f4a9cdd34: Final verdict of PTC: none
-NSVCI98-provIP(524)@204f4a9cdd34: Final verdict of PTC: none
-NSVCI97-provIP(519)@204f4a9cdd34: Final verdict of PTC: none
TC_attach_rau_a_b_wrong_old_ra-BVCI220(530)@204f4a9cdd34: Final verdict of PTC: none
SGSN_Test-GSUP(533)@204f4a9cdd34: Final verdict of PTC: none
SGSN_Test-Gb1-BSSGP1(523)@204f4a9cdd34: Final verdict of PTC: none
-NSVCI99-provIP(529)@204f4a9cdd34: Final verdict of PTC: none
SGSN_Test-GSUP-IPA(532)@204f4a9cdd34: Final verdict of PTC: none
MTC@204f4a9cdd34: Setting final verdict of the test case.
MTC@204f4a9cdd34: Local verdict of MTC: none
MTC@204f4a9cdd34: Local verdict of PTC SGSN_Test-Gb0-NS0(517): none (none -> none)
MTC@204f4a9cdd34: Local verdict of PTC SGSN_Test-Gb0-BSSGP0(518): none (none -> none)
MTC@204f4a9cdd34: Local verdict of PTC -NSVCI97-provIP(519): none (none -> none)
MTC@204f4a9cdd34: Local verdict of PTC TC_attach_rau_a_b_wrong_old_ra-BVCI196(520): none (none -> none)
MTC@204f4a9cdd34: Local verdict of PTC -NSVCI97(521): none (none -> none)
MTC@204f4a9cdd34: Local verdict of PTC SGSN_Test-Gb1-NS1(522): none (none -> none)
MTC@204f4a9cdd34: Local verdict of PTC SGSN_Test-Gb1-BSSGP1(523): none (none -> none)
MTC@204f4a9cdd34: Local verdict of PTC -NSVCI98-provIP(524): none (none -> none)
MTC@204f4a9cdd34: Local verdict of PTC TC_attach_rau_a_b_wrong_old_ra-BVCI210(525): none (none -> none)
MTC@204f4a9cdd34: Local verdict of PTC -NSVCI98(526): none (none -> none)
MTC@204f4a9cdd34: Local verdict of PTC SGSN_Test-Gb2-NS2(527): none (none -> none)
MTC@204f4a9cdd34: Local verdict of PTC SGSN_Test-Gb2-BSSGP2(528): none (none -> none)
MTC@204f4a9cdd34: Local verdict of PTC -NSVCI99-provIP(529): none (none -> none)
MTC@204f4a9cdd34: Local verdict of PTC TC_attach_rau_a_b_wrong_old_ra-BVCI220(530): none (none -> none)
MTC@204f4a9cdd34: Local verdict of PTC -NSVCI99(531): none (none -> none)
MTC@204f4a9cdd34: Local verdict of PTC SGSN_Test-GSUP-IPA(532): none (none -> none)
MTC@204f4a9cdd34: Local verdict of PTC SGSN_Test-GSUP(533): none (none -> none)
MTC@204f4a9cdd34: Local verdict of PTC SGSN_Test-GGSN_GTP(534): none (none -> none)
MTC@204f4a9cdd34: Local verdict of PTC TC_attach_rau_a_b_wrong_old_ra(535): fail (none -> fail) reason: "Tguard timeout"
MTC@204f4a9cdd34: Test case TC_attach_rau_a_b_wrong_old_ra finished. Verdict: fail reason: Tguard timeout
MTC@204f4a9cdd34: Starting external command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-asan/ws/_cache/podm…> SGSN_Tests.TC_attach_rau_a_b_wrong_old_ra fail'.
Tue Aug 20 12:48:44 UTC 2024
[1;31m------ SGSN_Tests.TC_attach_rau_a_b_wrong_old_ra fail ------[0m
Waiting for packet dumper to finish... 0 (prev_count=-1, count=99872)
[38;5;39m20240820124845122 [1;33mDLGSUP[0;m[38;5;39m [1;33mNOTICE[0;m[38;5;39m GSUP connecting to 127.0.0.103:4222 (gsup_client.c:74)[0;m
[38;5;39m20240820124845122 [1;32mDLGSUP[0;m[38;5;39m [1;32mINFO[0;m[38;5;39m GSUP link to 127.0.0.103:4222 DOWN (gsup_client.c:151)[0;m
Waiting for packet dumper to finish... 1 (prev_count=99872, count=101568)
[38;5;39m20240820124846123 [1;33mDLGSUP[0;m[38;5;39m [1;33mNOTICE[0;m[38;5;39m GSUP connecting to 127.0.0.103:4222 (gsup_client.c:74)[0;m
[38;5;39m20240820124846123 [1;32mDLGSUP[0;m[38;5;39m [1;32mINFO[0;m[38;5;39m GSUP link to 127.0.0.103:4222 DOWN (gsup_client.c:151)[0;m
MTC@204f4a9cdd34: External command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-asan/ws/_cache/podm…> SGSN_Tests.TC_attach_rau_a_b_wrong_old_ra fail' was executed successfully (exit status: 0).
MTC@204f4a9cdd34: Starting external command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-asan/ws/_cache/podm…> SGSN_Tests.TC_attach_usim_resync'.
------ SGSN_Tests.TC_attach_usim_resync ------
Tue Aug 20 12:48:46 UTC 2024
/usr/bin/dumpcap -q -s 1520 -n -i any -w "<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-asan/78/artifact/lo…"> ><https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-asan/78/artifact/lo…> 2>/tmp/cmderr &
Waiting for packet dumper to start... 0
[38;5;39m20240820124847124 [1;33mDLGSUP[0;m[38;5;39m [1;33mNOTICE[0;m[38;5;39m GSUP connecting to 127.0.0.103:4222 (gsup_client.c:74)[0;m
[38;5;39m20240820124847124 [1;32mDLGSUP[0;m[38;5;39m [1;32mINFO[0;m[38;5;39m GSUP link to 127.0.0.103:4222 DOWN (gsup_client.c:151)[0;m
MTC@204f4a9cdd34: External command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-asan/ws/_cache/podm…> SGSN_Tests.TC_attach_usim_resync' was executed successfully (exit status: 0).
MTC@204f4a9cdd34: Test case TC_attach_usim_resync started.
20240820124847256 [1;32mDLGLOBAL[0;m [1;32mINFO[0;m Accept()ed new telnet connection r=127.0.0.1:49860<->l=127.0.0.10:4245 (telnet_interface.c:192)
[1;33m20240820124847257 [1;32mDMM[0;m[1;33m [1;32mINFO[0;m[1;33m MM(262420000000039/e15a1c05) Cancelled, deleting context silently (gprs_gmm.c:1061)[0;m
[1;33m20240820124847257 [1;32mDMM[0;m[1;33m [1;32mINFO[0;m[1;33m MM(262420000000039/e15a1c05) Cleaning MM context due to access cancelled (gprs_gmm.c:200)[0;m
[1;33m20240820124847257 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM(gmm_fsm)[0x612000012ca0]{Registered.NORMAL}: Received Event E_GMM_CLEANUP (gprs_gmm.c:203)[0;m
[1;33m20240820124847257 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM(gmm_fsm)[0x612000012ca0]{Registered.NORMAL}: state_chg to Deregistered (gprs_gmm_fsm.c:223)[0;m
[1;33m20240820124847257 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM_STATE_Gb(0)[0x612000012fa0]{Ready}: Received Event E_MM_GPRS_DETACH (gprs_gmm.c:210)[0;m
[1;33m20240820124847257 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM_STATE_Gb(0)[0x612000012fa0]{Ready}: state_chg to Idle (gprs_mm_state_gb_fsm.c:76)[0;m
=================================================================
==7814==ERROR: AddressSanitizer: heap-use-after-free on address 0x621002a45574 at pc 0x564385c1ee5b bp 0x7fffc57395d0 sp 0x7fffc57395c8
READ of size 4 at 0x621002a45574 thread T0
#0 0x564385c1ee5a (/usr/bin/osmo-sgsn+0x221e5a)
#1 0x564385ba5f4c (/usr/bin/osmo-sgsn+0x1a8f4c)
#2 0x7f366417c9af (/lib/x86_64-linux-gnu/libosmocore.so.22+0x17c9af)
#3 0x7f366417f0fb in _osmo_fsm_inst_state_chg (/lib/x86_64-linux-gnu/libosmocore.so.22+0x17f0fb)
#4 0x564385ba5ce7 (/usr/bin/osmo-sgsn+0x1a8ce7)
#5 0x7f366417f7db in _osmo_fsm_inst_dispatch (/lib/x86_64-linux-gnu/libosmocore.so.22+0x17f7db)
#6 0x564385b7a73d (/usr/bin/osmo-sgsn+0x17d73d)
#7 0x564385bf13ef (/usr/bin/osmo-sgsn+0x1f43ef)
#8 0x7f3664f0198c (/lib/x86_64-linux-gnu/libosmovty.so.13+0xae98c)
#9 0x7f3664f121e4 in vty_read (/lib/x86_64-linux-gnu/libosmovty.so.13+0xbf1e4)
#10 0x7f3664f23a00 (/lib/x86_64-linux-gnu/libosmovty.so.13+0xd0a00)
#11 0x7f36641d1245 (/lib/x86_64-linux-gnu/libosmocore.so.22+0x1d1245)
#12 0x7f36641d16e8 in osmo_select_main (/lib/x86_64-linux-gnu/libosmocore.so.22+0x1d16e8)
#13 0x564385b7399c (/usr/bin/osmo-sgsn+0x17699c)
#14 0x7f3660e46249 (/lib/x86_64-linux-gnu/libc.so.6+0x27249)
#15 0x7f3660e46304 in __libc_start_main (/lib/x86_64-linux-gnu/libc.so.6+0x27304)
#16 0x564385b74630 (/usr/bin/osmo-sgsn+0x177630)
0x621002a45574 is located 116 bytes inside of 4408-byte region [0x621002a45500,0x621002a46638)
freed by thread T0 here:
#0 0x7f36656b76a8 in __interceptor_free ../../../../src/libsanitizer/asan/asan_malloc_linux.cpp:52
#1 0x7f3665cab5b1 (/lib/x86_64-linux-gnu/libtalloc.so.2+0x45b1)
previously allocated by thread T0 here:
#0 0x7f36656b89cf in __interceptor_malloc ../../../../src/libsanitizer/asan/asan_malloc_linux.cpp:69
#1 0x7f3665cacd53 (/lib/x86_64-linux-gnu/libtalloc.so.2+0x5d53)
SUMMARY: AddressSanitizer: heap-use-after-free (/usr/bin/osmo-sgsn+0x221e5a)
Shadow bytes around the buggy address:
0x0c4280540a50: fa fa fa fa fa fa fa fa fa fa fa fa fa fa fa fa
0x0c4280540a60: fa fa fa fa fa fa fa fa fa fa fa fa fa fa fa fa
0x0c4280540a70: fa fa fa fa fa fa fa fa fa fa fa fa fa fa fa fa
0x0c4280540a80: fa fa fa fa fa fa fa fa fa fa fa fa fa fa fa fa
0x0c4280540a90: fa fa fa fa fa fa fa fa fa fa fa fa fa fa fa fa
=>0x0c4280540aa0: fd fd fd fd fd fd fd fd fd fd fd fd fd fd[fd]fd
0x0c4280540ab0: fd fd fd fd fd fd fd fd fd fd fd fd fd fd fd fd
0x0c4280540ac0: fd fd fd fd fd fd fd fd fd fd fd fd fd fd fd fd
0x0c4280540ad0: fd fd fd fd fd fd fd fd fd fd fd fd fd fd fd fd
0x0c4280540ae0: fd fd fd fd fd fd fd fd fd fd fd fd fd fd fd fd
0x0c4280540af0: fd fd fd fd fd fd fd fd fd fd fd fd fd fd fd fd
Shadow byte legend (one shadow byte represents 8 application bytes):
Addressable: 00
Partially addressable: 01 02 03 04 05 06 07
Heap left redzone: fa
Freed heap region: fd
Stack left redzone: f1
Stack mid redzone: f2
Stack right redzone: f3
Stack after return: f5
Stack use after scope: f8
Global redzone: f9
Global init order: f6
Poisoned by user: f7
Container overflow: fc
Array cookie: ac
Intra object redzone: bb
ASan internal: fe
Left alloca redzone: ca
Right alloca redzone: cb
==7814==ABORTING
[38;5;23m20240820124847295 [1;33mDLINP[0;m[38;5;23m [1;33mNOTICE[0;m[38;5;23m asp-dyn-1 NOTIFICATION SHUTDOWN_EVENT flags=0x0 (stream.c:265)[0;m
[38;5;43m20240820124847295 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: asp-asp-dyn-1: ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x80) (osmo_ss7_asp.c:831)[0;m
[38;5;43m20240820124847295 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m 0: asp-asp-dyn-1: xUA SRV SCTP NOTIFICATION 32773 flags=0x0 (osmo_ss7_asp.c:769)[0;m
[38;5;43m20240820124847295 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m 0: asp-asp-dyn-1: xUA SRV SHUTDOWN_EVENT (osmo_ss7_asp.c:792)[0;m
[38;5;23m20240820124847295 [1;33mDLINP[0;m[38;5;23m [1;33mNOTICE[0;m[38;5;23m asp-dyn-1 NOTIFICATION ASSOC_CHANGE flags=0x0 SHUTDOWN_COMP (stream.c:265)[0;m
[38;5;43m20240820124847295 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: asp-asp-dyn-1: ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x80) (osmo_ss7_asp.c:831)[0;m
[38;5;43m20240820124847295 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m 0: asp-asp-dyn-1: xUA SRV SCTP NOTIFICATION 32769 flags=0x0 (osmo_ss7_asp.c:769)[0;m
[38;5;43m20240820124847295 [1;33mDLSS7[0;m[38;5;43m [1;33mNOTICE[0;m[38;5;43m 0: asp-asp-dyn-1: xUA SRV SCTP_ASSOC_CHANGE: SHUTDOWN_COMP (osmo_ss7_asp.c:777)[0;m
[38;5;23m20240820124847295 [1;33mDLINP[0;m[38;5;23m [1;33mNOTICE[0;m[38;5;23m SRVCONN(asp-dyn-1,r=127.0.0.1:41752<->l=127.0.0.200:2905) connection closed with client (stream_srv.c:673)[0;m
[38;5;43m20240820124847295 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m 0: asp-asp-dyn-1: ss7_asp_xua_srv_conn_rx_cb(): sctp_recvmsg() returned 0 (flags=0x0) (osmo_ss7_asp.c:831)[0;m
[38;5;43m20240820124847295 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m asp-dyn-1: connection closed (osmo_ss7_asp.c:1079)[0;m
[38;5;43m20240820124847295 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_ASP(asp-dyn-1){ASP_ACTIVE}: Received Event SCTP-COMM_DOWN.ind (osmo_ss7_asp.c:1085)[0;m
[38;5;43m20240820124847295 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_ASP(asp-dyn-1){ASP_ACTIVE}: state_chg to ASP_DOWN (xua_asp_fsm.c:683)[0;m
[38;5;43m20240820124847295 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(as-rkm-3){AS_ACTIVE}: Received Event ASPAS-ASP_DOWN.ind (xua_asp_fsm.c:364)[0;m
[38;5;43m20240820124847295 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(as-rkm-3){AS_ACTIVE}: state_chg to AS_PENDING (xua_as_fsm.c:467)[0;m
[38;5;43m20240820124847295 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_ASP(asp-dyn-1){ASP_DOWN}: No Layer Manager, dropping M-ASP_DOWN.indication (xua_asp_fsm.c:115)[0;m
[38;5;43m20240820124847295 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m 0: as-as-rkm-3: Destroying AS (osmo_ss7_as.c:159)[0;m
[38;5;43m20240820124847295 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(as-rkm-3){AS_PENDING}: Terminating (cause = OSMO_FSM_TERM_REQUEST) (osmo_ss7_as.c:162)[0;m
[38;5;43m20240820124847295 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(as-rkm-3){AS_PENDING}: Freeing instance (osmo_ss7_as.c:162)[0;m
[38;5;43m20240820124847295 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_AS(as-rkm-3){AS_PENDING}: Deallocated (fsm.c:568)[0;m
[38;5;43m20240820124847295 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m 0: Destroying route: pc=188=0.23.4 mask=0x3fff via linkset/ASP 'as-rkm-3' (osmo_ss7.c:797)[0;m
[38;5;43m20240820124847295 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_ASP(asp-dyn-1){ASP_DOWN}: No Layer Manager, dropping M-SCTP_RELEASE.indication (xua_asp_fsm.c:115)[0;m
[38;5;43m20240820124847295 [1;32mDLSS7[0;m[38;5;43m [1;32mINFO[0;m[38;5;43m 0: asp-asp-dyn-1: Destroying ASP (osmo_ss7_asp.c:578)[0;m
[38;5;43m20240820124847296 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_ASP(asp-dyn-1){ASP_DOWN}: Terminating (cause = OSMO_FSM_TERM_REQUEST) (osmo_ss7_asp.c:585)[0;m
[38;5;43m20240820124847296 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_ASP(asp-dyn-1){ASP_DOWN}: Freeing instance (osmo_ss7_asp.c:585)[0;m
[38;5;43m20240820124847296 [1;34mDLSS7[0;m[38;5;43m [1;34mDEBUG[0;m[38;5;43m XUA_ASP(asp-dyn-1){ASP_DOWN}: Deallocated (fsm.c:568)[0;m
[1;91m[testenv][generic] sgsn crashed![0m
[1;34m[testenv][generic] Stopping podman container[0m
[0;94m[testenv][generic] + ['podman', 'kill', 'testenv-sgsn-generic-osmocom-nightly-asan-20240820-1242-b8f3e599'][0m
204f4a9cdd34d6d11ae79dd00b8abbad30f3d80d65701070f7621831dd9fd413
[1;34m[testenv][generic] Stopping testsuite (3822254)[0m
[1;34m[testenv][generic] Logs saved to: https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-asan/78/artifact/lo… [0m
Build step 'Execute shell' marked build as failure
Recording test results
[Checks API] No suitable checks publisher found.
1
0
20 Aug '24
See <https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/169/displa…>
Changes:
[Pau Espin Pedrol] ggsn: Split test and relax expectations testing gtpu access over ipv6
[Pau Espin Pedrol] sgsn: Fix running with testenv.py podman setup
[Pau Espin Pedrol] sgsn: Introduce test TC_attach_rau_a_b_wrong_old_ra
------------------------------------------
[...truncated 985.96 KB...]
[1;33m20240820120147445 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x558fdf4b4580]{CheckIdentity}: Timeout of T3370 (fsm.c:317)[0;m
[1;33m20240820120147445 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM(262420000000032/f6935c18) <- GMM IDENTITY REQUEST: mi_type=IMEI (gprs_gmm.c:431)[0;m
[38;5;63m20240820120151520 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00096)-NSVC(00097) Tx NS-ALIVE (gprs_ns2_message.c:193)[0;m
[38;5;63m20240820120151521 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00096)-NSVC(00097) Rx NS-ALIVE-ACK (gprs_ns2.c:1309)[0;m
[38;5;57m20240820120151521 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00096-NSVC00097-127_0_0_10:23000-127_0_0_103:21010)[0x558fdf4aee10]{UNBLOCKED}: Received Event RX-ALIVE_ACK (gprs_ns2_vc_fsm.c:960)[0;m
[38;5;63m20240820120151538 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00097)-NSVC(00098) Tx NS-ALIVE (gprs_ns2_message.c:193)[0;m
[38;5;63m20240820120151539 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00097)-NSVC(00098) Rx NS-ALIVE-ACK (gprs_ns2.c:1309)[0;m
[38;5;57m20240820120151539 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00097-NSVC00098-127_0_0_10:23000-127_0_0_103:21011)[0x558fdf4b12a0]{UNBLOCKED}: Received Event RX-ALIVE_ACK (gprs_ns2_vc_fsm.c:960)[0;m
[38;5;63m20240820120151554 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00098)-NSVC(00099) Tx NS-ALIVE (gprs_ns2_message.c:193)[0;m
[38;5;63m20240820120151556 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00098)-NSVC(00099) Rx NS-ALIVE-ACK (gprs_ns2.c:1309)[0;m
[38;5;57m20240820120151556 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00098-NSVC00099-127_0_0_10:23000-127_0_0_103:21012)[0x558fdf4b2d00]{UNBLOCKED}: Received Event RX-ALIVE_ACK (gprs_ns2_vc_fsm.c:960)[0;m
[1;33m20240820120153446 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x558fdf4b4580]{CheckIdentity}: Timeout of T3370 (fsm.c:317)[0;m
[1;33m20240820120153446 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x558fdf4b4580]{CheckIdentity}: Received Event E_REJECT (gprs_gmm_attach.c:422)[0;m
[1;33m20240820120153446 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x558fdf4b4580]{CheckIdentity}: state_chg to Reject (gprs_gmm_attach.c:397)[0;m
[1;33m20240820120153446 [1;33mDMM[0;m[1;33m [1;33mNOTICE[0;m[1;33m MM(262420000000032/f6935c18) <- GMM ATTACH REJECT: MS identity cannot be derived by the network (gprs_gmm.c:364)[0;m
[1;33m20240820120153446 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x558fdf4b4580]{Reject}: Deallocated (fsm.c:568)[0;m
[1;33m20240820120153446 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM_STATE_Gb(0)[0x558fdf4b46b0]{Idle}: Deallocated (fsm.c:568)[0;m
[1;33m20240820120153446 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM_STATE_Iu[0x558fdf4b47e0]{Detached}: Deallocated (fsm.c:568)[0;m
[1;33m20240820120153446 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM(gmm_fsm)[0x558fdf4b4450]{CommonProcedureInitiated}: Deallocated (fsm.c:568)[0;m
20240820120153446 [1;33mDLLC[0;m [1;33mNOTICE[0;m LLME(f5445ec1/f6935c18){ASSIGNED} LLGM Assign pre (f6935c18 => ffffffff) (gprs_llc.c:1079)
20240820120153446 [1;33mDLLC[0;m [1;33mNOTICE[0;m LLME(00000000/00000000){UNASSIGNED} LLGM Assign post (f6935c18 => ffffffff) (gprs_llc.c:1125)
TC_attach_no_imei_response(269)@1c739be12bc2: setverdict(pass): none -> pass
TC_attach_no_imei_response-BVCI196(254)@1c739be12bc2: Removing Client IMSI='262420000000032'H, index=0
TC_attach_no_imei_response(269)@1c739be12bc2: Final verdict of PTC: pass
20240820120153453 [1;32mDLGLOBAL[0;m [1;32mINFO[0;m Closing telnet connection r=127.0.0.1:59770<->l=127.0.0.10:4245 (telnet_interface.c:138)
SGSN_Test-Gb2-NS2(261)@1c739be12bc2: Final verdict of PTC: none
SGSN_Test-Gb0-NS0(251)@1c739be12bc2: Final verdict of PTC: none
TC_attach_no_imei_response-BVCI220(264)@1c739be12bc2: Final verdict of PTC: none
-NSVCI98(260)@1c739be12bc2: Final verdict of PTC: none
SGSN_Test-Gb1-BSSGP1(257)@1c739be12bc2: Final verdict of PTC: none
SGSN_Test-Gb0-BSSGP0(252)@1c739be12bc2: Final verdict of PTC: none
-NSVCI99(265)@1c739be12bc2: Final verdict of PTC: none
SGSN_Test-GSUP(267)@1c739be12bc2: Final verdict of PTC: none
[38;5;23m20240820120153456 [1;31mDLINP[0;m[38;5;23m [1;31mERROR[0;m[38;5;23m 127.0.0.103:4222 connection closed with server (ipa.c:68)[0;m
[38;5;39m20240820120153456 [1;32mDLGSUP[0;m[38;5;39m [1;32mINFO[0;m[38;5;39m GSUP link to 127.0.0.103:4222 DOWN (gsup_client.c:151)[0;m
SGSN_Test-Gb1-NS1(256)@1c739be12bc2: Final verdict of PTC: none
-NSVCI98-provIP(258)@1c739be12bc2: Final verdict of PTC: none
-NSVCI97-provIP(253)@1c739be12bc2: Final verdict of PTC: none
TC_attach_no_imei_response-BVCI210(259)@1c739be12bc2: Final verdict of PTC: none
TC_attach_no_imei_response-BVCI196(254)@1c739be12bc2: Final verdict of PTC: none
-NSVCI99-provIP(263)@1c739be12bc2: Final verdict of PTC: none
-NSVCI97(255)@1c739be12bc2: Final verdict of PTC: none
SGSN_Test-GGSN_GTP(268)@1c739be12bc2: Final verdict of PTC: none
SGSN_Test-GSUP-IPA(266)@1c739be12bc2: Final verdict of PTC: none
SGSN_Test-Gb2-BSSGP2(262)@1c739be12bc2: Final verdict of PTC: none
MTC@1c739be12bc2: Setting final verdict of the test case.
MTC@1c739be12bc2: Local verdict of MTC: none
MTC@1c739be12bc2: Local verdict of PTC SGSN_Test-Gb0-NS0(251): none (none -> none)
MTC@1c739be12bc2: Local verdict of PTC SGSN_Test-Gb0-BSSGP0(252): none (none -> none)
MTC@1c739be12bc2: Local verdict of PTC -NSVCI97-provIP(253): none (none -> none)
MTC@1c739be12bc2: Local verdict of PTC TC_attach_no_imei_response-BVCI196(254): none (none -> none)
MTC@1c739be12bc2: Local verdict of PTC -NSVCI97(255): none (none -> none)
MTC@1c739be12bc2: Local verdict of PTC SGSN_Test-Gb1-NS1(256): none (none -> none)
MTC@1c739be12bc2: Local verdict of PTC SGSN_Test-Gb1-BSSGP1(257): none (none -> none)
MTC@1c739be12bc2: Local verdict of PTC -NSVCI98-provIP(258): none (none -> none)
MTC@1c739be12bc2: Local verdict of PTC TC_attach_no_imei_response-BVCI210(259): none (none -> none)
MTC@1c739be12bc2: Local verdict of PTC -NSVCI98(260): none (none -> none)
MTC@1c739be12bc2: Local verdict of PTC SGSN_Test-Gb2-NS2(261): none (none -> none)
MTC@1c739be12bc2: Local verdict of PTC SGSN_Test-Gb2-BSSGP2(262): none (none -> none)
MTC@1c739be12bc2: Local verdict of PTC -NSVCI99-provIP(263): none (none -> none)
MTC@1c739be12bc2: Local verdict of PTC TC_attach_no_imei_response-BVCI220(264): none (none -> none)
MTC@1c739be12bc2: Local verdict of PTC -NSVCI99(265): none (none -> none)
MTC@1c739be12bc2: Local verdict of PTC SGSN_Test-GSUP-IPA(266): none (none -> none)
MTC@1c739be12bc2: Local verdict of PTC SGSN_Test-GSUP(267): none (none -> none)
MTC@1c739be12bc2: Local verdict of PTC SGSN_Test-GGSN_GTP(268): none (none -> none)
MTC@1c739be12bc2: Local verdict of PTC TC_attach_no_imei_response(269): pass (none -> pass)
MTC@1c739be12bc2: Test case TC_attach_no_imei_response finished. Verdict: pass
MTC@1c739be12bc2: Starting external command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/ws/_cache/…> SGSN_Tests.TC_attach_no_imei_response pass'.
Tue Aug 20 12:01:53 UTC 2024
[1;32m====== SGSN_Tests.TC_attach_no_imei_response pass ======[0m
Waiting for packet dumper to finish... 0 (prev_count=-1, count=62632)
[38;5;39m20240820120154457 [1;33mDLGSUP[0;m[38;5;39m [1;33mNOTICE[0;m[38;5;39m GSUP connecting to 127.0.0.103:4222 (gsup_client.c:74)[0;m
[38;5;39m20240820120154457 [1;32mDLGSUP[0;m[38;5;39m [1;32mINFO[0;m[38;5;39m GSUP link to 127.0.0.103:4222 DOWN (gsup_client.c:151)[0;m
Waiting for packet dumper to finish... 1 (prev_count=62632, count=67152)
[38;5;39m20240820120155458 [1;33mDLGSUP[0;m[38;5;39m [1;33mNOTICE[0;m[38;5;39m GSUP connecting to 127.0.0.103:4222 (gsup_client.c:74)[0;m
[38;5;39m20240820120155458 [1;32mDLGSUP[0;m[38;5;39m [1;32mINFO[0;m[38;5;39m GSUP link to 127.0.0.103:4222 DOWN (gsup_client.c:151)[0;m
MTC@1c739be12bc2: External command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/ws/_cache/…> SGSN_Tests.TC_attach_no_imei_response pass' was executed successfully (exit status: 0).
MTC@1c739be12bc2: Starting external command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/ws/_cache/…> SGSN_Tests.TC_attach_no_imsi_response'.
------ SGSN_Tests.TC_attach_no_imsi_response ------
Tue Aug 20 12:01:55 UTC 2024
/usr/bin/dumpcap -q -s 1520 -n -i any -w "<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/169/artifa…"> ><https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/169/artifa…> 2>/tmp/cmderr &
Waiting for packet dumper to start... 0
[38;5;39m20240820120156459 [1;33mDLGSUP[0;m[38;5;39m [1;33mNOTICE[0;m[38;5;39m GSUP connecting to 127.0.0.103:4222 (gsup_client.c:74)[0;m
[38;5;39m20240820120156459 [1;32mDLGSUP[0;m[38;5;39m [1;32mINFO[0;m[38;5;39m GSUP link to 127.0.0.103:4222 DOWN (gsup_client.c:151)[0;m
MTC@1c739be12bc2: External command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/ws/_cache/…> SGSN_Tests.TC_attach_no_imsi_response' was executed successfully (exit status: 0).
MTC@1c739be12bc2: Test case TC_attach_no_imsi_response started.
20240820120156667 [1;32mDLGLOBAL[0;m [1;32mINFO[0;m Accept()ed new telnet connection r=127.0.0.1:49296<->l=127.0.0.10:4245 (telnet_interface.c:192)
[38;5;35m20240820120156668 [1;32mDLGTP[0;m[38;5;35m [1;32mINFO[0;m[38;5;35m Clearing req & resp retransmit queues (gsn.c:365)[0;m
SGSN_Test-Gb0-NS0(270)@1c739be12bc2: Creating NSIP provider for "127.0.0.103":21010
SGSN_Test-Gb0-BSSGP0(271)@1c739be12bc2: BVCI(0) State Transition: BVC_S_WAIT_RESET (0) -> BVC_S_WAIT_NS_ALIVE_UNBLOCKED (1)
SGSN_Test-Gb0-NS0(270)@1c739be12bc2: Starting NSVC component for { provider := { ip := { address_family := AF_INET (2), local_udp_port := 21010, local_ip := "127.0.0.103", remote_udp_port := 23000, remote_ip := "127.0.0.10", data_weight := 1, signalling_weight := 1 } }, nsvci := 97 }
-NSVCI97(275)@1c739be12bc2: NSVC 97 State Transition: NSVC_S_DEAD_BLOCKED (1) -> NSVC_S_DEAD_BLOCKED (1)
-NSVCI97(275)@1c739be12bc2: Provider Link came up: sending NS-RESET
SGSN_Test-Gb1-NS1(274)@1c739be12bc2: Creating NSIP provider for "127.0.0.103":21011
[38;5;63m20240820120156699 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00096)-NSVC(00097) Rx NS-RESET (gprs_ns2.c:1309)[0;m
[38;5;57m20240820120156699 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00096-NSVC00097-127_0_0_10:23000-127_0_0_103:21010)[0x558fdf4aee10]{UNBLOCKED}: Received Event RX-RESET (gprs_ns2_vc_fsm.c:934)[0;m
[38;5;57m20240820120156699 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00096-NSVC00097-127_0_0_10:23000-127_0_0_103:21010)[0x558fdf4aee10]{UNBLOCKED}: state_chg to RESET (gprs_ns2_vc_fsm.c:657)[0;m
[38;5;57m20240820120156699 [1;33mDLNS[0;m[38;5;57m [1;33mNOTICE[0;m[38;5;57m NSE(00096) NS-STATUS.ind(bvci=00000): cause=NSE failure, transfer=0, first=0, mtu=65523 (gprs_ns2.c:575)[0;m
20240820120156699 [1;33mDGPRS[0;m [1;33mNOTICE[0;m NS-E 96 became unavailable (gprs_ns.c:54)
[38;5;63m20240820120156699 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00096)-NSVC(00097) Tx NS-RESET-ACK (gprs_ns2_message.c:331)[0;m
[38;5;57m20240820120156699 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00096-NSVC00097-127_0_0_10:23000-127_0_0_103:21010)[0x558fdf4aee10]{RESET}: state_chg to BLOCKED (gprs_ns2_vc_fsm.c:329)[0;m
[38;5;63m20240820120156699 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00096)-NSVC(00097) Tx NS-ALIVE (gprs_ns2_message.c:193)[0;m
[38;5;57m20240820120156699 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00096-NSVC00097-127_0_0_10:23000-127_0_0_103:21010)[0x558fdf4aee10]{BLOCKED}: Starting Tns-alive of 3 seconds (gprs_ns2_vc_fsm.c:177)[0;m
-NSVCI97(275)@1c739be12bc2: NSVC 97 State Transition: NSVC_S_WAIT_RESET (2) -> NSVC_S_ALIVE_BLOCKED (3)
[38;5;63m20240820120156701 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00096)-NSVC(00097) Rx NS-ALIVE (gprs_ns2.c:1309)[0;m
[38;5;57m20240820120156701 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00096-NSVC00097-127_0_0_10:23000-127_0_0_103:21010)[0x558fdf4aee10]{BLOCKED}: Received Event RX-ALIVE (gprs_ns2_vc_fsm.c:957)[0;m
[38;5;63m20240820120156701 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00096)-NSVC(00097) Tx NS-ALIVE-ACK (gprs_ns2_message.c:193)[0;m
[38;5;63m20240820120156701 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00096)-NSVC(00097) Rx NS-UNBLOCK (gprs_ns2.c:1309)[0;m
[38;5;57m20240820120156701 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00096-NSVC00097-127_0_0_10:23000-127_0_0_103:21010)[0x558fdf4aee10]{BLOCKED}: Received Event RX-UNBLOCK (gprs_ns2_vc_fsm.c:951)[0;m
[38;5;63m20240820120156701 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00096)-NSVC(00097) Tx NS-UNBLOCK-ACK (gprs_ns2_message.c:193)[0;m
[38;5;57m20240820120156701 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00096-NSVC00097-127_0_0_10:23000-127_0_0_103:21010)[0x558fdf4aee10]{BLOCKED}: state_chg to UNBLOCKED (gprs_ns2_vc_fsm.c:414)[0;m
[38;5;57m20240820120156701 [1;33mDLNS[0;m[38;5;57m [1;33mNOTICE[0;m[38;5;57m NSE(00096) NS-STATUS.ind(bvci=00000): cause=NSE recovery, transfer=100, first=0, mtu=65523 (gprs_ns2.c:575)[0;m
20240820120156701 [1;33mDGPRS[0;m [1;33mNOTICE[0;m NS-E 96 became available (gprs_ns.c:43)
[38;5;57m20240820120156701 [1;33mDLNS[0;m[38;5;57m [1;33mNOTICE[0;m[38;5;57m NSE(00096)-NSVC(00097) NS-STATUS.ind(bvci=00000): cause=NSVC recovery, transfer=100, first=0, mtu=65523 (gprs_ns2.c:571)[0;m
20240820120156701 [1;33mDGPRS[0;m [1;33mNOTICE[0;m NS: indication Unknown prim 2 from NS (gprs_ns.c:57)
-NSVCI97(275)@1c739be12bc2: Rx NS-ALIVE-ACK: stopping Tns-alive; starting Tns-test
[38;5;63m20240820120156701 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00096)-NSVC(00097) Rx NS-ALIVE-ACK (gprs_ns2.c:1309)[0;m
[38;5;57m20240820120156701 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00096-NSVC00097-127_0_0_10:23000-127_0_0_103:21010)[0x558fdf4aee10]{UNBLOCKED}: Received Event RX-ALIVE_ACK (gprs_ns2_vc_fsm.c:960)[0;m
-NSVCI97(275)@1c739be12bc2: NSVC 97 State Transition: NSVC_S_ALIVE_BLOCKED (3) -> NSVC_S_ALIVE_UNBLOCKED (4)
[38;5;57m20240820120156704 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00096-NSVC00097-127_0_0_10:23000-127_0_0_103:21010)[0x558fdf4aee10]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
SGSN_Test-Gb0-BSSGP0(271)@1c739be12bc2: BVCI(0) Rx BVC-RESET-ACK
SGSN_Test-Gb0-BSSGP0(271)@1c739be12bc2: BVCI(0) State Transition: BVC_S_WAIT_NS_ALIVE_UNBLOCKED (1) -> BVC_S_UNBLOCKED (3)
SGSN_Test-Gb1-BSSGP1(276)@1c739be12bc2: BVCI(0) State Transition: BVC_S_WAIT_RESET (0) -> BVC_S_WAIT_NS_ALIVE_UNBLOCKED (1)
SGSN_Test-Gb1-NS1(274)@1c739be12bc2: Starting NSVC component for { provider := { ip := { address_family := AF_INET (2), local_udp_port := 21011, local_ip := "127.0.0.103", remote_udp_port := 23000, remote_ip := "127.0.0.10", data_weight := 1, signalling_weight := 1 } }, nsvci := 98 }
[38;5;57m20240820120156712 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00096-NSVC00097-127_0_0_10:23000-127_0_0_103:21010)[0x558fdf4aee10]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
[38;5;59m20240820120156712 [1;33mDLBSSGP[0;m[38;5;59m [1;33mNOTICE[0;m[38;5;59m Cell 262-42-13135-0 CI 20960 on BVCI 196 (gprs_bssgp.c:400)[0;m
-NSVCI98(279)@1c739be12bc2: NSVC 98 State Transition: NSVC_S_DEAD_BLOCKED (1) -> NSVC_S_DEAD_BLOCKED (1)
TC_attach_no_imsi_response-BVCI196(272)@1c739be12bc2: BVCI(196) State Transition: BVC_S_BLOCKED (2) -> BVC_S_UNBLOCKED (3)
-NSVCI98(279)@1c739be12bc2: Provider Link came up: sending NS-RESET
[38;5;63m20240820120156716 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00097)-NSVC(00098) Rx NS-RESET (gprs_ns2.c:1309)[0;m
[38;5;57m20240820120156716 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00097-NSVC00098-127_0_0_10:23000-127_0_0_103:21011)[0x558fdf4b12a0]{UNBLOCKED}: Received Event RX-RESET (gprs_ns2_vc_fsm.c:934)[0;m
[38;5;57m20240820120156716 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00097-NSVC00098-127_0_0_10:23000-127_0_0_103:21011)[0x558fdf4b12a0]{UNBLOCKED}: state_chg to RESET (gprs_ns2_vc_fsm.c:657)[0;m
[38;5;57m20240820120156716 [1;33mDLNS[0;m[38;5;57m [1;33mNOTICE[0;m[38;5;57m NSE(00097) NS-STATUS.ind(bvci=00000): cause=NSE failure, transfer=0, first=0, mtu=65523 (gprs_ns2.c:575)[0;m
20240820120156716 [1;33mDGPRS[0;m [1;33mNOTICE[0;m NS-E 97 became unavailable (gprs_ns.c:54)
[38;5;63m20240820120156716 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00097)-NSVC(00098) Tx NS-RESET-ACK (gprs_ns2_message.c:331)[0;m
[38;5;57m20240820120156716 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00097-NSVC00098-127_0_0_10:23000-127_0_0_103:21011)[0x558fdf4b12a0]{RESET}: state_chg to BLOCKED (gprs_ns2_vc_fsm.c:329)[0;m
[38;5;63m20240820120156716 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00097)-NSVC(00098) Tx NS-ALIVE (gprs_ns2_message.c:193)[0;m
[38;5;57m20240820120156716 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00097-NSVC00098-127_0_0_10:23000-127_0_0_103:21011)[0x558fdf4b12a0]{BLOCKED}: Starting Tns-alive of 3 seconds (gprs_ns2_vc_fsm.c:177)[0;m
-NSVCI98(279)@1c739be12bc2: NSVC 98 State Transition: NSVC_S_WAIT_RESET (2) -> NSVC_S_ALIVE_BLOCKED (3)
[38;5;63m20240820120156717 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00097)-NSVC(00098) Rx NS-ALIVE (gprs_ns2.c:1309)[0;m
[38;5;57m20240820120156717 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00097-NSVC00098-127_0_0_10:23000-127_0_0_103:21011)[0x558fdf4b12a0]{BLOCKED}: Received Event RX-ALIVE (gprs_ns2_vc_fsm.c:957)[0;m
[38;5;63m20240820120156718 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00097)-NSVC(00098) Tx NS-ALIVE-ACK (gprs_ns2_message.c:193)[0;m
[38;5;63m20240820120156718 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00097)-NSVC(00098) Rx NS-UNBLOCK (gprs_ns2.c:1309)[0;m
[38;5;57m20240820120156718 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00097-NSVC00098-127_0_0_10:23000-127_0_0_103:21011)[0x558fdf4b12a0]{BLOCKED}: Received Event RX-UNBLOCK (gprs_ns2_vc_fsm.c:951)[0;m
[38;5;63m20240820120156718 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00097)-NSVC(00098) Tx NS-UNBLOCK-ACK (gprs_ns2_message.c:193)[0;m
[38;5;57m20240820120156718 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00097-NSVC00098-127_0_0_10:23000-127_0_0_103:21011)[0x558fdf4b12a0]{BLOCKED}: state_chg to UNBLOCKED (gprs_ns2_vc_fsm.c:414)[0;m
[38;5;57m20240820120156718 [1;33mDLNS[0;m[38;5;57m [1;33mNOTICE[0;m[38;5;57m NSE(00097) NS-STATUS.ind(bvci=00000): cause=NSE recovery, transfer=100, first=0, mtu=65523 (gprs_ns2.c:575)[0;m
20240820120156718 [1;33mDGPRS[0;m [1;33mNOTICE[0;m NS-E 97 became available (gprs_ns.c:43)
[38;5;57m20240820120156718 [1;33mDLNS[0;m[38;5;57m [1;33mNOTICE[0;m[38;5;57m NSE(00097)-NSVC(00098) NS-STATUS.ind(bvci=00000): cause=NSVC recovery, transfer=100, first=0, mtu=65523 (gprs_ns2.c:571)[0;m
20240820120156718 [1;33mDGPRS[0;m [1;33mNOTICE[0;m NS: indication Unknown prim 2 from NS (gprs_ns.c:57)
[38;5;63m20240820120156718 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00097)-NSVC(00098) Rx NS-ALIVE-ACK (gprs_ns2.c:1309)[0;m
[38;5;57m20240820120156718 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00097-NSVC00098-127_0_0_10:23000-127_0_0_103:21011)[0x558fdf4b12a0]{UNBLOCKED}: Received Event RX-ALIVE_ACK (gprs_ns2_vc_fsm.c:960)[0;m
-NSVCI98(279)@1c739be12bc2: Rx NS-ALIVE-ACK: stopping Tns-alive; starting Tns-test
-NSVCI98(279)@1c739be12bc2: NSVC 98 State Transition: NSVC_S_ALIVE_BLOCKED (3) -> NSVC_S_ALIVE_UNBLOCKED (4)
[38;5;57m20240820120156721 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00097-NSVC00098-127_0_0_10:23000-127_0_0_103:21011)[0x558fdf4b12a0]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
SGSN_Test-Gb2-NS2(280)@1c739be12bc2: Creating NSIP provider for "127.0.0.103":21012
SGSN_Test-Gb1-BSSGP1(276)@1c739be12bc2: BVCI(0) Rx BVC-RESET-ACK
SGSN_Test-Gb1-BSSGP1(276)@1c739be12bc2: BVCI(0) State Transition: BVC_S_WAIT_NS_ALIVE_UNBLOCKED (1) -> BVC_S_UNBLOCKED (3)
[38;5;57m20240820120156729 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00097-NSVC00098-127_0_0_10:23000-127_0_0_103:21011)[0x558fdf4b12a0]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
[38;5;59m20240820120156729 [1;33mDLBSSGP[0;m[38;5;59m [1;33mNOTICE[0;m[38;5;59m Cell 262-42-13200-0 CI 20961 on BVCI 210 (gprs_bssgp.c:400)[0;m
TC_attach_no_imsi_response-BVCI210(278)@1c739be12bc2: BVCI(210) State Transition: BVC_S_BLOCKED (2) -> BVC_S_UNBLOCKED (3)
SGSN_Test-Gb2-BSSGP2(281)@1c739be12bc2: BVCI(0) State Transition: BVC_S_WAIT_RESET (0) -> BVC_S_WAIT_NS_ALIVE_UNBLOCKED (1)
SGSN_Test-Gb2-NS2(280)@1c739be12bc2: Starting NSVC component for { provider := { ip := { address_family := AF_INET (2), local_udp_port := 21012, local_ip := "127.0.0.103", remote_udp_port := 23000, remote_ip := "127.0.0.10", data_weight := 1, signalling_weight := 1 } }, nsvci := 99 }
-NSVCI99(284)@1c739be12bc2: NSVC 99 State Transition: NSVC_S_DEAD_BLOCKED (1) -> NSVC_S_DEAD_BLOCKED (1)
-NSVCI99(284)@1c739be12bc2: Provider Link came up: sending NS-RESET
[38;5;63m20240820120156738 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00098)-NSVC(00099) Rx NS-RESET (gprs_ns2.c:1309)[0;m
[38;5;57m20240820120156738 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00098-NSVC00099-127_0_0_10:23000-127_0_0_103:21012)[0x558fdf4b2d00]{UNBLOCKED}: Received Event RX-RESET (gprs_ns2_vc_fsm.c:934)[0;m
[38;5;57m20240820120156738 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00098-NSVC00099-127_0_0_10:23000-127_0_0_103:21012)[0x558fdf4b2d00]{UNBLOCKED}: state_chg to RESET (gprs_ns2_vc_fsm.c:657)[0;m
[38;5;57m20240820120156738 [1;33mDLNS[0;m[38;5;57m [1;33mNOTICE[0;m[38;5;57m NSE(00098) NS-STATUS.ind(bvci=00000): cause=NSE failure, transfer=0, first=0, mtu=65523 (gprs_ns2.c:575)[0;m
20240820120156738 [1;33mDGPRS[0;m [1;33mNOTICE[0;m NS-E 98 became unavailable (gprs_ns.c:54)
[38;5;63m20240820120156738 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00098)-NSVC(00099) Tx NS-RESET-ACK (gprs_ns2_message.c:331)[0;m
[38;5;57m20240820120156738 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00098-NSVC00099-127_0_0_10:23000-127_0_0_103:21012)[0x558fdf4b2d00]{RESET}: state_chg to BLOCKED (gprs_ns2_vc_fsm.c:329)[0;m
[38;5;63m20240820120156738 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00098)-NSVC(00099) Tx NS-ALIVE (gprs_ns2_message.c:193)[0;m
[38;5;57m20240820120156738 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00098-NSVC00099-127_0_0_10:23000-127_0_0_103:21012)[0x558fdf4b2d00]{BLOCKED}: Starting Tns-alive of 3 seconds (gprs_ns2_vc_fsm.c:177)[0;m
-NSVCI99(284)@1c739be12bc2: NSVC 99 State Transition: NSVC_S_WAIT_RESET (2) -> NSVC_S_ALIVE_BLOCKED (3)
[38;5;63m20240820120156739 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00098)-NSVC(00099) Rx NS-ALIVE (gprs_ns2.c:1309)[0;m
[38;5;57m20240820120156739 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00098-NSVC00099-127_0_0_10:23000-127_0_0_103:21012)[0x558fdf4b2d00]{BLOCKED}: Received Event RX-ALIVE (gprs_ns2_vc_fsm.c:957)[0;m
[38;5;63m20240820120156739 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00098)-NSVC(00099) Tx NS-ALIVE-ACK (gprs_ns2_message.c:193)[0;m
[38;5;63m20240820120156739 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00098)-NSVC(00099) Rx NS-UNBLOCK (gprs_ns2.c:1309)[0;m
[38;5;57m20240820120156739 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00098-NSVC00099-127_0_0_10:23000-127_0_0_103:21012)[0x558fdf4b2d00]{BLOCKED}: Received Event RX-UNBLOCK (gprs_ns2_vc_fsm.c:951)[0;m
[38;5;63m20240820120156739 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00098)-NSVC(00099) Tx NS-UNBLOCK-ACK (gprs_ns2_message.c:193)[0;m
[38;5;57m20240820120156740 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00098-NSVC00099-127_0_0_10:23000-127_0_0_103:21012)[0x558fdf4b2d00]{BLOCKED}: state_chg to UNBLOCKED (gprs_ns2_vc_fsm.c:414)[0;m
[38;5;57m20240820120156740 [1;33mDLNS[0;m[38;5;57m [1;33mNOTICE[0;m[38;5;57m NSE(00098) NS-STATUS.ind(bvci=00000): cause=NSE recovery, transfer=100, first=0, mtu=65523 (gprs_ns2.c:575)[0;m
20240820120156740 [1;33mDGPRS[0;m [1;33mNOTICE[0;m NS-E 98 became available (gprs_ns.c:43)
[38;5;57m20240820120156740 [1;33mDLNS[0;m[38;5;57m [1;33mNOTICE[0;m[38;5;57m NSE(00098)-NSVC(00099) NS-STATUS.ind(bvci=00000): cause=NSVC recovery, transfer=100, first=0, mtu=65523 (gprs_ns2.c:571)[0;m
20240820120156740 [1;33mDGPRS[0;m [1;33mNOTICE[0;m NS: indication Unknown prim 2 from NS (gprs_ns.c:57)
[38;5;63m20240820120156740 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00098)-NSVC(00099) Rx NS-ALIVE-ACK (gprs_ns2.c:1309)[0;m
[38;5;57m20240820120156740 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00098-NSVC00099-127_0_0_10:23000-127_0_0_103:21012)[0x558fdf4b2d00]{UNBLOCKED}: Received Event RX-ALIVE_ACK (gprs_ns2_vc_fsm.c:960)[0;m
-NSVCI99(284)@1c739be12bc2: Rx NS-ALIVE-ACK: stopping Tns-alive; starting Tns-test
-NSVCI99(284)@1c739be12bc2: NSVC 99 State Transition: NSVC_S_ALIVE_BLOCKED (3) -> NSVC_S_ALIVE_UNBLOCKED (4)
[38;5;57m20240820120156743 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00098-NSVC00099-127_0_0_10:23000-127_0_0_103:21012)[0x558fdf4b2d00]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
SGSN_Test-Gb2-BSSGP2(281)@1c739be12bc2: BVCI(0) Rx BVC-RESET-ACK
SGSN_Test-Gb2-BSSGP2(281)@1c739be12bc2: BVCI(0) State Transition: BVC_S_WAIT_NS_ALIVE_UNBLOCKED (1) -> BVC_S_UNBLOCKED (3)
[38;5;57m20240820120156749 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00098-NSVC00099-127_0_0_10:23000-127_0_0_103:21012)[0x558fdf4b2d00]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
[38;5;59m20240820120156749 [1;33mDLBSSGP[0;m[38;5;59m [1;33mNOTICE[0;m[38;5;59m Cell 262-42-13200-0 CI 20962 on BVCI 220 (gprs_bssgp.c:400)[0;m
TC_attach_no_imsi_response-BVCI220(283)@1c739be12bc2: BVCI(220) State Transition: BVC_S_BLOCKED (2) -> BVC_S_UNBLOCKED (3)
[38;5;39m20240820120157459 [1;33mDLGSUP[0;m[38;5;39m [1;33mNOTICE[0;m[38;5;39m GSUP connecting to 127.0.0.103:4222 (gsup_client.c:74)[0;m
[38;5;23m20240820120157459 [1;33mDLINP[0;m[38;5;23m [1;33mNOTICE[0;m[38;5;23m 127.0.0.103:4222 connection done (ipa.c:143)[0;m
[38;5;39m20240820120157459 [1;32mDLGSUP[0;m[38;5;39m [1;32mINFO[0;m[38;5;39m GSUP link to 127.0.0.103:4222 UP (gsup_client.c:151)[0;m
[38;5;39m20240820120157459 [1;34mDLGSUP[0;m[38;5;39m [1;34mDEBUG[0;m[38;5;39m GSUP sending PING (gsup_client.c:288)[0;m
SGSN_Test-GSUP-IPA(285)@1c739be12bc2: Established a new IPA connection (conn_id=2)
SGSN_Test-GSUP-IPA(285)@1c739be12bc2: 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) } } } }
[38;5;23m20240820120157460 [1;33mDLINP[0;m[38;5;23m [1;33mNOTICE[0;m[38;5;23m received ID_GET for unit ID 0/0/0 (ipaccess.c:897)[0;m
[38;5;39m20240820120157461 [1;34mDLGSUP[0;m[38;5;39m [1;34mDEBUG[0;m[38;5;39m GSUP receiving PONG (gsup_client.c:225)[0;m
SGSN_Test-GSUP-IPA(285)@1c739be12bc2: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit }
SGSN_Test-GSUP-IPA(285)@1c739be12bc2: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit }
SGSN_Test-GSUP-IPA(285)@1c739be12bc2: CCM Rx:{ msg_type := IPAC_MSGT_ID_RESP (5), u := { resp := { { len := 24, tag := IPAC_IDTAG_UNITNAME (1), data := '5347534E2D30302D30302D30302D30302D30302D303000'O }, { len := 7, tag := IPAC_IDTAG_UNITID (8), data := '302F302F3000'O } } } }
SGSN_Test-GSUP-IPA(285)@1c739be12bc2: IPA ID RESP: { { len := 24, tag := IPAC_IDTAG_UNITNAME (1), data := '5347534E2D30302D30302D30302D30302D30302D303000'O }, { len := 7, tag := IPAC_IDTAG_UNITID (8), data := '302F302F3000'O } }
SGSN_Test-GSUP-IPA(285)@1c739be12bc2: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit }
SGSN_Test-GSUP-IPA(285)@1c739be12bc2: CCM Rx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit }
TC_attach_no_imsi_response-BVCI196(272)@1c739be12bc2: Adding Client=TC_attach_no_imsi_response(288), IMSI='262420000000035'H, TLLI='D923B82A'O, index=0
SGSN_Test-GSUP(286)@1c739be12bc2: Created GsupExpect[0] for "262420000000035" to be handled at TC_attach_no_imsi_response(288)
[38;5;57m20240820120158482 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00096-NSVC00097-127_0_0_10:23000-127_0_0_103:21010)[0x558fdf4aee10]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
20240820120158482 [1;33mDLLC[0;m [1;33mNOTICE[0;m LLME(ffffffff/d923b82a){UNASSIGNED} LLC RX: unknown TLLI 0xd923b82a, creating LLME on the fly (gprs_llc.c:552)
20240820120158482 [1;34mDLLC[0;m [1;34mDEBUG[0;m LLC SAPI=1 C U GEA0 IOV-UI=0x000000 FCS=0x24bf94 CMD=UI DATA (gprs_llc_parse.c:89)
[1;33m20240820120158482 [1;32mDMM[0;m[1;33m [1;32mINFO[0;m[1;33m MM(---/ffffffff) -> GMM ATTACH REQUEST [0;m[1;33mMI(TMSI-0xC0000035) type="Combined GPRS/IMSI attach" [0;m[1;33m (gprs_gmm.c:1266)[0;m
[1;33m20240820120158482 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM(gmm_fsm)[0x558fdf4b4450]{Deregistered}: Allocated (fsm.c:456)[0;m
[1;33m20240820120158482 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x558fdf4b47e0]{Init}: Allocated (fsm.c:456)[0;m
[1;33m20240820120158482 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM_STATE_Gb[0x558fdf4b46b0]{Idle}: Allocated (fsm.c:456)[0;m
[1;33m20240820120158482 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM_STATE_Iu[0x558fdf4b4580]{Detached}: Allocated (fsm.c:456)[0;m
[1;33m20240820120158482 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM(gmm_fsm)[0x558fdf4b4450]{Deregistered}: Received Event E_GMM_COMMON_PROC_INIT_REQ (gprs_gmm.c:1144)[0;m
[1;33m20240820120158482 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM(gmm_fsm)[0x558fdf4b4450]{Deregistered}: state_chg to CommonProcedureInitiated (gprs_gmm_fsm.c:49)[0;m
20240820120158482 [1;33mDLLC[0;m [1;33mNOTICE[0;m LLME(ffffffff/d923b82a){UNASSIGNED} LLGM Assign pre (d923b82a => d64c93cc) (gprs_llc.c:1079)
20240820120158482 [1;33mDLLC[0;m [1;33mNOTICE[0;m LLME(d923b82a/d64c93cc){ASSIGNED} LLGM Assign post (d923b82a => d64c93cc) (gprs_llc.c:1125)
[1;33m20240820120158482 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x558fdf4b47e0]{Init}: Received Event E_ATTACH_REQ_RECV (gprs_gmm.c:1370)[0;m
[1;33m20240820120158482 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x558fdf4b47e0]{Init}: state_chg to CheckIdentity (gprs_gmm_attach.c:52)[0;m
[1;33m20240820120158482 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM(/d64c93cc) <- GMM IDENTITY REQUEST: mi_type=IMEI (gprs_gmm.c:431)[0;m
[38;5;57m20240820120158490 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00096-NSVC00097-127_0_0_10:23000-127_0_0_103:21010)[0x558fdf4aee10]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
20240820120158490 [1;34mDLLC[0;m [1;34mDEBUG[0;m LLC SAPI=1 C U GEA0 IOV-UI=0x000000 FCS=0x612fca CMD=UI DATA (gprs_llc_parse.c:89)
[1;33m20240820120158490 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM_STATE_Gb(3642996778)[0x558fdf4b46b0]{Idle}: Received Event E_MM_PDU_RECEPTION (gprs_gmm.c:2333)[0;m
[1;33m20240820120158490 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM(/d64c93cc) -> GMM IDENTITY RESPONSE: MI=IMEI-499990000000350 (gprs_gmm.c:1084)[0;m
[1;33m20240820120158490 [1;33mDMM[0;m[1;33m [1;33mNOTICE[0;m[1;33m MM(/d64c93cc) Stopping *inactive* MM timer 3370 (gprs_gmm.c:115)[0;m
[1;33m20240820120158490 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x558fdf4b47e0]{CheckIdentity}: Received Event E_IDEN_RESP_RECV (gprs_gmm.c:1128)[0;m
[1;33m20240820120158490 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x558fdf4b47e0]{CheckIdentity}: state_chg to CheckIdentity (gprs_gmm_attach.c:113)[0;m
[1;33m20240820120158491 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM(/d64c93cc) <- GMM IDENTITY REQUEST: mi_type=IMSI (gprs_gmm.c:431)[0;m
20240820120202437 [1;34mDGPRS[0;m [1;34mDEBUG[0;m Checking for inactive LLMEs, time = 26683521 (sgsn.c:131)
[1;33m20240820120204491 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x558fdf4b47e0]{CheckIdentity}: Timeout of T3370 (fsm.c:317)[0;m
[1;33m20240820120204491 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM(/d64c93cc) <- GMM IDENTITY REQUEST: mi_type=IMSI (gprs_gmm.c:431)[0;m
[1;33m20240820120210492 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x558fdf4b47e0]{CheckIdentity}: Timeout of T3370 (fsm.c:317)[0;m
[1;33m20240820120210492 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM(/d64c93cc) <- GMM IDENTITY REQUEST: mi_type=IMSI (gprs_gmm.c:431)[0;m
[1;91m[testenv][generic] stp crashed![0m
Error: container 1c739be12bc2f8a482dd66c05d277611429f392a20a4bb4a23b7f966ba5b93d9 does not exist in database: no such container
[1;91m[testenv] podman container crashed![0m
[1;34m[testenv][generic] Stopping testsuite (590140)[0m
[1;34m[testenv][generic] Logs saved to: https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-io_uring/169/artifa… [0m
Build step 'Execute shell' marked build as failure
Recording test results
[Checks API] No suitable checks publisher found.
1
0
19 Aug '24
Build failed in Jenkins: master-osmo-msc » --disable-iu,0,a3=default,a4=default,osmocom-master #45069
by jenkins＠lists.osmocom.org 19 Aug '24
by jenkins＠lists.osmocom.org 19 Aug '24
19 Aug '24
See <https://jenkins.osmocom.org/jenkins/job/master-osmo-msc/IU=--disable-iu,WIT…>
Changes:
------------------------------------------
[...truncated 636.60 KB...]
logging level asci notice
logging level lglobal notice
logging level llapd notice
logging level linp notice
logging level lmux notice
logging level lmi notice
logging level lmib notice
logging level lsms notice
logging level lctrl notice
logging level lgtp notice
logging level lstats notice
logging level lgsup notice
logging level loap notice
logging level lss7 notice
logging level lsccp notice
logging level lsua notice
logging level lm3ua notice
logging level lmgcp notice
logging level ljibuf notice
logging level lrspro notice
logging level lns notice
logging level lbssgp notice
logging level lnsdata notice
logging level lnssignal notice
logging level liuup notice
logging level lpfcp notice
logging level lcsn1 notice
logging level lio notice
!
stats interval 5
!
line vty
no login
!
cs7 instance 0
point-code 0.23.1
network
network country code 001
mobile network code 01
short name OsmoMSC
long name OsmoMSC
encryption a5 0
encryption uea 1 2
authentication optional
rrlp mode none
mm info 1
mgw 0
local-port 2728
remote-ip 127.0.0.1
remote-port 2427
msc
mncc internal
mncc guard-timeout 180
ncss guard-timeout 30
assign-tmsi
cs7-instance-a 0
auth-tuple-max-reuse-count 3
auth-tuple-reuse-on-error 1
mncc-int
default-codec tch-f fr
default-codec tch-h hr
smpp
local-tcp-port 2775
policy closed
no smpp-first
hlr
remote-ip 127.0.0.1
remote-port 4222
ipa-name unnamed-MSC
sgs
local-port 29118
local-ip 0.0.0.0
vlr-name vlr.example.net
smsc
queue max-pending 20
queue max-failure 1
database delete-delivered 1
database delete-expired 1
validity-period minimum 1
validity-period default 10080
asci
disable
gcr
end
[1;33mDMSC[0;m [1;33mNOTICE[0;m Terminating due to signal 15 (msc_main.c:286)
full talloc report on 'osmo_msc' (total 19638 bytes in 94 blocks)
struct osmo_ss7_instance contains 5113 bytes in 38 blocks (ref 0) 0x562b809c4970
struct osmo_sccp_instance contains 858 bytes in 6 blocks (ref 0) 0x562b80b0bed0
struct osmo_sccp_user contains 90 bytes in 2 blocks (ref 0) 0x562b80b14ae0
OsmoMSC-A contains 10 bytes in 1 blocks (ref 0) 0x562b80b23160
struct osmo_sccp_user contains 96 bytes in 2 blocks (ref 0) 0x562b80b07b60
SCCP Management contains 16 bytes in 1 blocks (ref 0) 0x562b80b230e0
sccp_user.c:241 contains 560 bytes in 1 blocks (ref 0) 0x562b80b22e40
struct osmo_ss7_asp contains 2958 bytes in 19 blocks (ref 0) 0x562b809c28d0
struct osmo_fsm_inst contains 367 bytes in 4 blocks (ref 0) 0x562b80b22bf0
struct xua_asp_fsm_priv contains 104 bytes in 1 blocks (ref 0) 0x562b80b07040
XUA_ASP(asp-clnt-OsmoMSC-A)[0x562b80b22bf0] contains 44 bytes in 1 blocks (ref 0) 0x562b80b22da0
asp-clnt-OsmoMSC-A contains 19 bytes in 1 blocks (ref 0) 0x562b80b22d20
struct osmo_stream_cli contains 951 bytes in 4 blocks (ref 0) 0x562b80b227f0
localhost contains 10 bytes in 1 blocks (ref 0) 0x562b80b1cef0
localhost contains 10 bytes in 1 blocks (ref 0) 0x562b80b18c20
asp-clnt-OsmoMSC-A contains 19 bytes in 1 blocks (ref 0) 0x562b80b14e30
struct osmo_fsm_inst contains 278 bytes in 4 blocks (ref 0) 0x562b80b225a0
struct lm_fsm_priv contains 8 bytes in 1 blocks (ref 0) 0x562b80b075c0
xua_default_lm(asp-clnt-OsmoMSC-A)[0x562b80b225a0] contains 51 bytes in 1 blocks (ref 0) 0x562b80b22750
asp-clnt-OsmoMSC-A contains 19 bytes in 1 blocks (ref 0) 0x562b80b226d0
localhost contains 10 bytes in 1 blocks (ref 0) 0x562b80aea860
localhost contains 10 bytes in 1 blocks (ref 0) 0x562b80b18a80
osmo_ss7_asp.c:561 contains 280 bytes in 1 blocks (ref 0) 0x562b80aea6e0
asp-clnt-OsmoMSC-A contains 19 bytes in 1 blocks (ref 0) 0x562b80aea660
rate_ctr.c:233 contains 299 bytes in 2 blocks (ref 0) 0x562b80aea4e0
asp-clnt-OsmoMSC-A contains 19 bytes in 1 blocks (ref 0) 0x562b80b14c00
struct osmo_ss7_as contains 856 bytes in 8 blocks (ref 0) 0x562b8099efa0
struct osmo_fsm_inst contains 372 bytes in 4 blocks (ref 0) 0x562b80af07c0
struct xua_as_fsm_priv contains 112 bytes in 1 blocks (ref 0) 0x562b80b10f70
XUA_AS(as-clnt-OsmoMSC-A)[0x562b80af07c0] contains 42 bytes in 1 blocks (ref 0) 0x562b80af08f0
as-clnt-OsmoMSC-A contains 18 bytes in 1 blocks (ref 0) 0x562b80b0c2d0
as-clnt-OsmoMSC-A contains 18 bytes in 1 blocks (ref 0) 0x562b80b07860
rate_ctr.c:233 contains 218 bytes in 2 blocks (ref 0) 0x562b80af0690
as-clnt-OsmoMSC-A contains 18 bytes in 1 blocks (ref 0) 0x562b80b119f0
struct osmo_ss7_route_table contains 145 bytes in 4 blocks (ref 0) 0x562b80b096a0
struct osmo_ss7_route contains 82 bytes in 2 blocks (ref 0) 0x562b80b06f60
as-clnt-OsmoMSC-A contains 18 bytes in 1 blocks (ref 0) 0x562b80b07920
system contains 7 bytes in 1 blocks (ref 0) 0x562b80b0c430
telnet_connection contains 1 bytes in 1 blocks (ref 0) 0x562b80aeb920
struct osmo_stream_srv_link contains 1920 bytes in 2 blocks (ref 0) 0x562b80ae9a00
0.0.0.0 contains 8 bytes in 1 blocks (ref 0) 0x562b80aea1e0
struct sgs_state contains 376 bytes in 1 blocks (ref 0) 0x562b80ae9820
struct smsc contains 152 bytes in 1 blocks (ref 0) 0x562b8099c980
sms.db contains 7 bytes in 1 blocks (ref 0) 0x562b80a04620
struct sms_queue_config contains 32 bytes in 1 blocks (ref 0) 0x562b80a04590
struct gsm_network contains 11227 bytes in 31 blocks (ref 0) 0x562b8099f1a0
struct sccp_ran_inst contains 152 bytes in 1 blocks (ref 0) 0x562b80b07c20
struct gsm_sms_queue contains 776 bytes in 4 blocks (ref 0) 0x562b809c41f0
rate_ctr.c:233 contains 440 bytes in 1 blocks (ref 0) 0x562b80aec770
stat_item.c:195 contains 104 bytes in 2 blocks (ref 0) 0x562b80aeb290
struct osmo_stat_item contains 56 bytes in 1 blocks (ref 0) 0x562b80aeb330
struct gsup_client_mux contains 682 bytes in 6 blocks (ref 0) 0x562b80aeb050
struct osmo_gsup_client contains 514 bytes in 4 blocks (ref 0) 0x562b809e7660
struct osmo_fd contains 48 bytes in 1 blocks (ref 0) 0x562b80aeb120
struct ipa_client_conn contains 202 bytes in 2 blocks (ref 0) 0x562b809a1510
127.0.0.1 contains 10 bytes in 1 blocks (ref 0) 0x562b80aeb1c0
struct ipaccess_unit contains 64 bytes in 1 blocks (ref 0) 0x562b80aebc40
struct ctrl_handle contains 88 bytes in 1 blocks (ref 0) 0x562b80aeae30
127.0.0.1 contains 10 bytes in 1 blocks (ref 0) 0x562b80aebe60
struct vlr_instance contains 2496 bytes in 5 blocks (ref 0) 0x562b8099da50
rate_ctr.c:233 contains 2040 bytes in 1 blocks (ref 0) 0x562b80ad3d80
stat_item.c:195 contains 168 bytes in 2 blocks (ref 0) 0x562b80ad3c00
struct osmo_stat_item contains 112 bytes in 1 blocks (ref 0) 0x562b80ad3ca0
struct osmo_nri_ranges contains 16 bytes in 1 blocks (ref 0) 0x562b80ad3b80
struct mgcp_client_conf contains 1088 bytes in 1 blocks (ref 0) 0x562b80a040e0
struct mgcp_client_pool contains 2877 bytes in 7 blocks (ref 0) 0x562b80a04050
struct mgcp_client_pool_member contains 2716 bytes in 4 blocks (ref 0) 0x562b80aec070
struct mgcp_client contains 1580 bytes in 3 blocks (ref 0) 0x562b80af0110
struct osmo_io_fd contains 284 bytes in 2 blocks (ref 0) 0x562b809a1ba0
r=127.0.0.1:2427<->l=127.0.0.1:2728 contains 36 bytes in 1 blocks (ref 0) 0x562b80b07d60
struct cmd_node contains 121 bytes in 2 blocks (ref 0) 0x562b8099f970
%s(config-mgw)# contains 17 bytes in 1 blocks (ref 0) 0x562b80a26260
127.0.0.1 contains 10 bytes in 1 blocks (ref 0) 0x562b80a03180
stat_item.c:195 contains 168 bytes in 2 blocks (ref 0) 0x562b80a03ed0
struct osmo_stat_item contains 112 bytes in 1 blocks (ref 0) 0x562b8099fd10
rate_ctr.c:233 contains 2520 bytes in 1 blocks (ref 0) 0x562b80a03490
struct sched_vty_opts contains 72 bytes in 1 blocks (ref 0) 0x562b809f0e30
utils.c:386 contains 203 bytes in 1 blocks (ref 0) 0x562b80928a10
utils.c:386 contains 46 bytes in 1 blocks (ref 0) 0x562b80928970
utils.c:386 contains 165 bytes in 1 blocks (ref 0) 0x562b80928860
utils.c:386 contains 43 bytes in 1 blocks (ref 0) 0x562b809287c0
transaction contains 0 bytes in 1 blocks (ref 0) 0x562b80904a70
gsm_call contains 0 bytes in 1 blocks (ref 0) 0x562b80904a00
sms contains 0 bytes in 1 blocks (ref 0) 0x562b80904990
osmo_signal contains 280 bytes in 8 blocks (ref 0) 0x562b80904920
struct signal_handler contains 40 bytes in 1 blocks (ref 0) 0x562b80b0c4a0
struct signal_handler contains 40 bytes in 1 blocks (ref 0) 0x562b80af0d70
struct signal_handler contains 40 bytes in 1 blocks (ref 0) 0x562b80aeb890
struct signal_handler contains 40 bytes in 1 blocks (ref 0) 0x562b80aeb990
struct signal_handler contains 40 bytes in 1 blocks (ref 0) 0x562b80aebdd0
struct signal_handler contains 40 bytes in 1 blocks (ref 0) 0x562b80aebf60
struct signal_handler contains 40 bytes in 1 blocks (ref 0) 0x562b80a9ab40
msgb contains 0 bytes in 1 blocks (ref 0) 0x562b809048b0
full talloc report on 'null_context' (total 24 bytes in 2 blocks)
struct lookup_helper contains 24 bytes in 1 blocks (ref 0) 0x562b80aeb700
signal 15 received
Terminating took 0.897s
RESULTS:
pass: ../../../tests/test_neighbor_ident.vty
pass: ../../../tests/test_nodes.vty
rm -f ./sms.db*
make[8]: Leaving directory '/build/osmo-msc-1.12.0/_build/sub/tests'
make[7]: Leaving directory '/build/osmo-msc-1.12.0/_build/sub/tests'
make ctrl-test
make[7]: Entering directory '/build/osmo-msc-1.12.0/_build/sub/tests'
make ctrl-python-test
make[8]: Entering directory '/build/osmo-msc-1.12.0/_build/sub/tests'
../../../tests/ctrl_test_runner.py -w /build/osmo-msc-1.12.0/_build/sub -v
testRateCounters (__main__.TestCtrlMSC.testRateCounters) ... ERROR
======================================================================
ERROR: testRateCounters (__main__.TestCtrlMSC.testRateCounters)
----------------------------------------------------------------------
Traceback (most recent call last):
File "/build/osmo-msc-1.12.0/_build/sub/tests/../../../tests/ctrl_test_runner.py", line 69, in tearDown
raise Exception("Process returned %d" % rc)
Exception: Process returned -6
----------------------------------------------------------------------
Ran 1 test in 2.169s
FAILED (errors=1)
confpath /build/osmo-msc-1.12.0/tests/.., workdir /build/osmo-msc-1.12.0/_build/sub
Running tests for specific control commands
Opening /dev/null
Launching: PWD=/build/osmo-msc-1.12.0/_build/sub './src/osmo-msc/osmo-msc' '-c' '/build/osmo-msc-1.12.0/tests/../doc/examples/osmo-msc/osmo-msc.cfg'
Connecting to host 127.0.0.1:4255
Sending "GET 1000 rate_ctr.*"
Got message: b'ERROR 1000 GET variable contains invalid characters'
Decoded replies: {1000: {'mtype': b'ERROR', 'id': 1000, 'var': b'GET', 'value': b'variable contains invalid characters'}}
Process returned code: -6
make[8]: *** [Makefile:749: ctrl-python-test] Error 1
make[8]: Leaving directory '/build/osmo-msc-1.12.0/_build/sub/tests'
make[7]: *** [Makefile:760: ctrl-test] Error 2
make[7]: Leaving directory '/build/osmo-msc-1.12.0/_build/sub/tests'
make[6]: Leaving directory '/build/osmo-msc-1.12.0/_build/sub/tests'
make[6]: *** [Makefile:716: python-tests] Error 2
make[5]: *** [Makefile:769: check-local] Error 2
make[5]: Leaving directory '/build/osmo-msc-1.12.0/_build/sub/tests'
make[4]: *** [Makefile:574: check-am] Error 2
make[4]: Leaving directory '/build/osmo-msc-1.12.0/_build/sub/tests'
make[3]: *** [Makefile:424: check-recursive] Error 1
make[3]: Leaving directory '/build/osmo-msc-1.12.0/_build/sub/tests'
make[2]: Leaving directory '/build/osmo-msc-1.12.0/_build/sub'
make[2]: *** [Makefile:473: check-recursive] Error 1
make[1]: Leaving directory '/build/osmo-msc-1.12.0/_build/sub'
make[1]: *** [Makefile:772: check] Error 2
make: *** [Makefile:692: distcheck] Error 1
+ exit_tar_workspace
+ '[' 1 = 1 ']'
+ tar -cJf /tmp/workspace.tar.xz /build
tar: Removing leading `/' from member names
+ mv /tmp/workspace.tar.xz /build
+ cat-testlogs.sh
Build step 'Execute shell' marked build as failure
$ ssh-agent -k
unset SSH_AUTH_SOCK;
unset SSH_AGENT_PID;
echo Agent pid 2913252 killed;
[ssh-agent] Stopped.
Archiving artifacts
1
1
18 Aug '24
Jenkins build became unstable: ttcn3-sgsn-test-sns-latest #116
by jenkins＠lists.osmocom.org 17 Aug '24
by jenkins＠lists.osmocom.org 17 Aug '24
17 Aug '24
17 Aug '24
16 Aug '24
See <https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-asan/74/display/red…>
Changes:
[lynxis] SGSN_Tests: add paging a whole routing area with multiple cells
[lynxis] SGSN_Tests: remove unused variable d
[lynxis] SGSN_Tests_NS: NS related tests: use the shutdown helper
[lynxis] SGSN_Tests_NS: TC_NS_connect_alive: set missing verdict
------------------------------------------
[...truncated 1.13 MB...]
[38;5;63m20240816124634370 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00096)-NSVC(00097) Tx NS-ALIVE-ACK (gprs_ns2_message.c:193)[0;m
[38;5;63m20240816124634370 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00096)-NSVC(00097) Rx NS-UNBLOCK (gprs_ns2.c:1309)[0;m
[38;5;57m20240816124634370 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00096-NSVC00097-127_0_0_10:23000-127_0_0_103:21010)[0x612000004ba0]{BLOCKED}: Received Event RX-UNBLOCK (gprs_ns2_vc_fsm.c:951)[0;m
[38;5;63m20240816124634370 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00096)-NSVC(00097) Tx NS-UNBLOCK-ACK (gprs_ns2_message.c:193)[0;m
[38;5;57m20240816124634370 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00096-NSVC00097-127_0_0_10:23000-127_0_0_103:21010)[0x612000004ba0]{BLOCKED}: state_chg to UNBLOCKED (gprs_ns2_vc_fsm.c:414)[0;m
[38;5;57m20240816124634370 [1;33mDLNS[0;m[38;5;57m [1;33mNOTICE[0;m[38;5;57m NSE(00096) NS-STATUS.ind(bvci=00000): cause=NSE recovery, transfer=100, first=0, mtu=65523 (gprs_ns2.c:575)[0;m
20240816124634370 [1;33mDGPRS[0;m [1;33mNOTICE[0;m NS-E 96 became available (gprs_ns.c:43)
[38;5;57m20240816124634370 [1;33mDLNS[0;m[38;5;57m [1;33mNOTICE[0;m[38;5;57m NSE(00096)-NSVC(00097) NS-STATUS.ind(bvci=00000): cause=NSVC recovery, transfer=100, first=0, mtu=65523 (gprs_ns2.c:571)[0;m
20240816124634370 [1;33mDGPRS[0;m [1;33mNOTICE[0;m NS: indication Unknown prim 2 from NS (gprs_ns.c:57)
[38;5;63m20240816124634370 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00096)-NSVC(00097) Rx NS-ALIVE-ACK (gprs_ns2.c:1309)[0;m
-NSVCI97(350)@bb9044106d99: Rx NS-ALIVE-ACK: stopping Tns-alive; starting Tns-test
[38;5;57m20240816124634370 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00096-NSVC00097-127_0_0_10:23000-127_0_0_103:21010)[0x612000004ba0]{UNBLOCKED}: Received Event RX-ALIVE_ACK (gprs_ns2_vc_fsm.c:960)[0;m
-NSVCI97(350)@bb9044106d99: NSVC 97 State Transition: NSVC_S_ALIVE_BLOCKED (3) -> NSVC_S_ALIVE_UNBLOCKED (4)
[38;5;57m20240816124634373 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00096-NSVC00097-127_0_0_10:23000-127_0_0_103:21010)[0x612000004ba0]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
SGSN_Test-Gb0-BSSGP0(347)@bb9044106d99: BVCI(0) Rx BVC-RESET-ACK
SGSN_Test-Gb0-BSSGP0(347)@bb9044106d99: BVCI(0) State Transition: BVC_S_WAIT_NS_ALIVE_UNBLOCKED (1) -> BVC_S_UNBLOCKED (3)
SGSN_Test-Gb1-NS1(351)@bb9044106d99: Creating NSIP provider for "127.0.0.103":21011
[38;5;57m20240816124634380 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00096-NSVC00097-127_0_0_10:23000-127_0_0_103:21010)[0x612000004ba0]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
[38;5;59m20240816124634380 [1;33mDLBSSGP[0;m[38;5;59m [1;33mNOTICE[0;m[38;5;59m Cell 262-42-13135-0 CI 20960 on BVCI 196 (gprs_bssgp.c:400)[0;m
TC_attach_check_complete_resend-BVCI196(349)@bb9044106d99: BVCI(196) State Transition: BVC_S_BLOCKED (2) -> BVC_S_UNBLOCKED (3)
SGSN_Test-Gb1-BSSGP1(352)@bb9044106d99: BVCI(0) State Transition: BVC_S_WAIT_RESET (0) -> BVC_S_WAIT_NS_ALIVE_UNBLOCKED (1)
SGSN_Test-Gb1-NS1(351)@bb9044106d99: Starting NSVC component for { provider := { ip := { address_family := AF_INET (2), local_udp_port := 21011, local_ip := "127.0.0.103", remote_udp_port := 23000, remote_ip := "127.0.0.10", data_weight := 1, signalling_weight := 1 } }, nsvci := 98 }
-NSVCI98(355)@bb9044106d99: NSVC 98 State Transition: NSVC_S_DEAD_BLOCKED (1) -> NSVC_S_DEAD_BLOCKED (1)
-NSVCI98(355)@bb9044106d99: Provider Link came up: sending NS-RESET
[38;5;63m20240816124634402 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00097)-NSVC(00098) Rx NS-RESET (gprs_ns2.c:1309)[0;m
[38;5;57m20240816124634402 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00097-NSVC00098-127_0_0_10:23000-127_0_0_103:21011)[0x612000004d20]{UNBLOCKED}: Received Event RX-RESET (gprs_ns2_vc_fsm.c:934)[0;m
[38;5;57m20240816124634402 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00097-NSVC00098-127_0_0_10:23000-127_0_0_103:21011)[0x612000004d20]{UNBLOCKED}: state_chg to RESET (gprs_ns2_vc_fsm.c:657)[0;m
[38;5;57m20240816124634402 [1;33mDLNS[0;m[38;5;57m [1;33mNOTICE[0;m[38;5;57m NSE(00097) NS-STATUS.ind(bvci=00000): cause=NSE failure, transfer=0, first=0, mtu=65523 (gprs_ns2.c:575)[0;m
20240816124634402 [1;33mDGPRS[0;m [1;33mNOTICE[0;m NS-E 97 became unavailable (gprs_ns.c:54)
[38;5;63m20240816124634402 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00097)-NSVC(00098) Tx NS-RESET-ACK (gprs_ns2_message.c:331)[0;m
[38;5;57m20240816124634403 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00097-NSVC00098-127_0_0_10:23000-127_0_0_103:21011)[0x612000004d20]{RESET}: state_chg to BLOCKED (gprs_ns2_vc_fsm.c:329)[0;m
[38;5;63m20240816124634403 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00097)-NSVC(00098) Tx NS-ALIVE (gprs_ns2_message.c:193)[0;m
[38;5;57m20240816124634403 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00097-NSVC00098-127_0_0_10:23000-127_0_0_103:21011)[0x612000004d20]{BLOCKED}: Starting Tns-alive of 3 seconds (gprs_ns2_vc_fsm.c:177)[0;m
-NSVCI98(355)@bb9044106d99: NSVC 98 State Transition: NSVC_S_WAIT_RESET (2) -> NSVC_S_ALIVE_BLOCKED (3)
[38;5;63m20240816124634404 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00097)-NSVC(00098) Rx NS-ALIVE (gprs_ns2.c:1309)[0;m
[38;5;57m20240816124634404 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00097-NSVC00098-127_0_0_10:23000-127_0_0_103:21011)[0x612000004d20]{BLOCKED}: Received Event RX-ALIVE (gprs_ns2_vc_fsm.c:957)[0;m
[38;5;63m20240816124634404 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00097)-NSVC(00098) Tx NS-ALIVE-ACK (gprs_ns2_message.c:193)[0;m
[38;5;63m20240816124634404 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00097)-NSVC(00098) Rx NS-UNBLOCK (gprs_ns2.c:1309)[0;m
[38;5;57m20240816124634404 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00097-NSVC00098-127_0_0_10:23000-127_0_0_103:21011)[0x612000004d20]{BLOCKED}: Received Event RX-UNBLOCK (gprs_ns2_vc_fsm.c:951)[0;m
[38;5;63m20240816124634404 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00097)-NSVC(00098) Tx NS-UNBLOCK-ACK (gprs_ns2_message.c:193)[0;m
[38;5;57m20240816124634404 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00097-NSVC00098-127_0_0_10:23000-127_0_0_103:21011)[0x612000004d20]{BLOCKED}: state_chg to UNBLOCKED (gprs_ns2_vc_fsm.c:414)[0;m
[38;5;57m20240816124634404 [1;33mDLNS[0;m[38;5;57m [1;33mNOTICE[0;m[38;5;57m NSE(00097) NS-STATUS.ind(bvci=00000): cause=NSE recovery, transfer=100, first=0, mtu=65523 (gprs_ns2.c:575)[0;m
20240816124634404 [1;33mDGPRS[0;m [1;33mNOTICE[0;m NS-E 97 became available (gprs_ns.c:43)
[38;5;57m20240816124634404 [1;33mDLNS[0;m[38;5;57m [1;33mNOTICE[0;m[38;5;57m NSE(00097)-NSVC(00098) NS-STATUS.ind(bvci=00000): cause=NSVC recovery, transfer=100, first=0, mtu=65523 (gprs_ns2.c:571)[0;m
20240816124634404 [1;33mDGPRS[0;m [1;33mNOTICE[0;m NS: indication Unknown prim 2 from NS (gprs_ns.c:57)
[38;5;63m20240816124634404 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00097)-NSVC(00098) Rx NS-ALIVE-ACK (gprs_ns2.c:1309)[0;m
[38;5;57m20240816124634404 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00097-NSVC00098-127_0_0_10:23000-127_0_0_103:21011)[0x612000004d20]{UNBLOCKED}: Received Event RX-ALIVE_ACK (gprs_ns2_vc_fsm.c:960)[0;m
-NSVCI98(355)@bb9044106d99: Rx NS-ALIVE-ACK: stopping Tns-alive; starting Tns-test
-NSVCI98(355)@bb9044106d99: NSVC 98 State Transition: NSVC_S_ALIVE_BLOCKED (3) -> NSVC_S_ALIVE_UNBLOCKED (4)
[38;5;57m20240816124634407 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00097-NSVC00098-127_0_0_10:23000-127_0_0_103:21011)[0x612000004d20]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
SGSN_Test-Gb1-BSSGP1(352)@bb9044106d99: BVCI(0) Rx BVC-RESET-ACK
SGSN_Test-Gb1-BSSGP1(352)@bb9044106d99: BVCI(0) State Transition: BVC_S_WAIT_NS_ALIVE_UNBLOCKED (1) -> BVC_S_UNBLOCKED (3)
SGSN_Test-Gb2-NS2(356)@bb9044106d99: Creating NSIP provider for "127.0.0.103":21012
[38;5;57m20240816124634414 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00097-NSVC00098-127_0_0_10:23000-127_0_0_103:21011)[0x612000004d20]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
[38;5;59m20240816124634414 [1;33mDLBSSGP[0;m[38;5;59m [1;33mNOTICE[0;m[38;5;59m Cell 262-42-13200-0 CI 20961 on BVCI 210 (gprs_bssgp.c:400)[0;m
TC_attach_check_complete_resend-BVCI210(354)@bb9044106d99: BVCI(210) State Transition: BVC_S_BLOCKED (2) -> BVC_S_UNBLOCKED (3)
SGSN_Test-Gb2-BSSGP2(357)@bb9044106d99: BVCI(0) State Transition: BVC_S_WAIT_RESET (0) -> BVC_S_WAIT_NS_ALIVE_UNBLOCKED (1)
SGSN_Test-Gb2-NS2(356)@bb9044106d99: Starting NSVC component for { provider := { ip := { address_family := AF_INET (2), local_udp_port := 21012, local_ip := "127.0.0.103", remote_udp_port := 23000, remote_ip := "127.0.0.10", data_weight := 1, signalling_weight := 1 } }, nsvci := 99 }
-NSVCI99(360)@bb9044106d99: NSVC 99 State Transition: NSVC_S_DEAD_BLOCKED (1) -> NSVC_S_DEAD_BLOCKED (1)
-NSVCI99(360)@bb9044106d99: Provider Link came up: sending NS-RESET
[38;5;63m20240816124634439 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00098)-NSVC(00099) Rx NS-RESET (gprs_ns2.c:1309)[0;m
[38;5;57m20240816124634439 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00098-NSVC00099-127_0_0_10:23000-127_0_0_103:21012)[0x612000004ea0]{UNBLOCKED}: Received Event RX-RESET (gprs_ns2_vc_fsm.c:934)[0;m
[38;5;57m20240816124634439 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00098-NSVC00099-127_0_0_10:23000-127_0_0_103:21012)[0x612000004ea0]{UNBLOCKED}: state_chg to RESET (gprs_ns2_vc_fsm.c:657)[0;m
[38;5;57m20240816124634440 [1;33mDLNS[0;m[38;5;57m [1;33mNOTICE[0;m[38;5;57m NSE(00098) NS-STATUS.ind(bvci=00000): cause=NSE failure, transfer=0, first=0, mtu=65523 (gprs_ns2.c:575)[0;m
20240816124634440 [1;33mDGPRS[0;m [1;33mNOTICE[0;m NS-E 98 became unavailable (gprs_ns.c:54)
[38;5;63m20240816124634440 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00098)-NSVC(00099) Tx NS-RESET-ACK (gprs_ns2_message.c:331)[0;m
[38;5;57m20240816124634440 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00098-NSVC00099-127_0_0_10:23000-127_0_0_103:21012)[0x612000004ea0]{RESET}: state_chg to BLOCKED (gprs_ns2_vc_fsm.c:329)[0;m
[38;5;63m20240816124634440 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00098)-NSVC(00099) Tx NS-ALIVE (gprs_ns2_message.c:193)[0;m
[38;5;57m20240816124634440 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00098-NSVC00099-127_0_0_10:23000-127_0_0_103:21012)[0x612000004ea0]{BLOCKED}: Starting Tns-alive of 3 seconds (gprs_ns2_vc_fsm.c:177)[0;m
-NSVCI99(360)@bb9044106d99: NSVC 99 State Transition: NSVC_S_WAIT_RESET (2) -> NSVC_S_ALIVE_BLOCKED (3)
[38;5;63m20240816124634441 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00098)-NSVC(00099) Rx NS-ALIVE (gprs_ns2.c:1309)[0;m
[38;5;57m20240816124634441 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00098-NSVC00099-127_0_0_10:23000-127_0_0_103:21012)[0x612000004ea0]{BLOCKED}: Received Event RX-ALIVE (gprs_ns2_vc_fsm.c:957)[0;m
[38;5;63m20240816124634441 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00098)-NSVC(00099) Tx NS-ALIVE-ACK (gprs_ns2_message.c:193)[0;m
[38;5;63m20240816124634441 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00098)-NSVC(00099) Rx NS-UNBLOCK (gprs_ns2.c:1309)[0;m
[38;5;57m20240816124634441 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00098-NSVC00099-127_0_0_10:23000-127_0_0_103:21012)[0x612000004ea0]{BLOCKED}: Received Event RX-UNBLOCK (gprs_ns2_vc_fsm.c:951)[0;m
[38;5;63m20240816124634441 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00098)-NSVC(00099) Tx NS-UNBLOCK-ACK (gprs_ns2_message.c:193)[0;m
[38;5;57m20240816124634441 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00098-NSVC00099-127_0_0_10:23000-127_0_0_103:21012)[0x612000004ea0]{BLOCKED}: state_chg to UNBLOCKED (gprs_ns2_vc_fsm.c:414)[0;m
[38;5;57m20240816124634441 [1;33mDLNS[0;m[38;5;57m [1;33mNOTICE[0;m[38;5;57m NSE(00098) NS-STATUS.ind(bvci=00000): cause=NSE recovery, transfer=100, first=0, mtu=65523 (gprs_ns2.c:575)[0;m
20240816124634441 [1;33mDGPRS[0;m [1;33mNOTICE[0;m NS-E 98 became available (gprs_ns.c:43)
[38;5;57m20240816124634441 [1;33mDLNS[0;m[38;5;57m [1;33mNOTICE[0;m[38;5;57m NSE(00098)-NSVC(00099) NS-STATUS.ind(bvci=00000): cause=NSVC recovery, transfer=100, first=0, mtu=65523 (gprs_ns2.c:571)[0;m
20240816124634441 [1;33mDGPRS[0;m [1;33mNOTICE[0;m NS: indication Unknown prim 2 from NS (gprs_ns.c:57)
[38;5;63m20240816124634442 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00098)-NSVC(00099) Rx NS-ALIVE-ACK (gprs_ns2.c:1309)[0;m
[38;5;57m20240816124634442 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00098-NSVC00099-127_0_0_10:23000-127_0_0_103:21012)[0x612000004ea0]{UNBLOCKED}: Received Event RX-ALIVE_ACK (gprs_ns2_vc_fsm.c:960)[0;m
-NSVCI99(360)@bb9044106d99: Rx NS-ALIVE-ACK: stopping Tns-alive; starting Tns-test
-NSVCI99(360)@bb9044106d99: NSVC 99 State Transition: NSVC_S_ALIVE_BLOCKED (3) -> NSVC_S_ALIVE_UNBLOCKED (4)
[38;5;57m20240816124634444 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00098-NSVC00099-127_0_0_10:23000-127_0_0_103:21012)[0x612000004ea0]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
SGSN_Test-Gb2-BSSGP2(357)@bb9044106d99: BVCI(0) Rx BVC-RESET-ACK
SGSN_Test-Gb2-BSSGP2(357)@bb9044106d99: BVCI(0) State Transition: BVC_S_WAIT_NS_ALIVE_UNBLOCKED (1) -> BVC_S_UNBLOCKED (3)
[38;5;57m20240816124634451 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00098-NSVC00099-127_0_0_10:23000-127_0_0_103:21012)[0x612000004ea0]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
[38;5;59m20240816124634452 [1;33mDLBSSGP[0;m[38;5;59m [1;33mNOTICE[0;m[38;5;59m Cell 262-42-13200-0 CI 20962 on BVCI 220 (gprs_bssgp.c:400)[0;m
TC_attach_check_complete_resend-BVCI220(359)@bb9044106d99: BVCI(220) State Transition: BVC_S_BLOCKED (2) -> BVC_S_UNBLOCKED (3)
[38;5;39m20240816124635195 [1;33mDLGSUP[0;m[38;5;39m [1;33mNOTICE[0;m[38;5;39m GSUP connecting to 127.0.0.103:4222 (gsup_client.c:74)[0;m
[38;5;23m20240816124635195 [1;33mDLINP[0;m[38;5;23m [1;33mNOTICE[0;m[38;5;23m 127.0.0.103:4222 connection done (ipa.c:143)[0;m
[38;5;39m20240816124635195 [1;32mDLGSUP[0;m[38;5;39m [1;32mINFO[0;m[38;5;39m GSUP link to 127.0.0.103:4222 UP (gsup_client.c:151)[0;m
[38;5;39m20240816124635195 [1;34mDLGSUP[0;m[38;5;39m [1;34mDEBUG[0;m[38;5;39m GSUP sending PING (gsup_client.c:288)[0;m
SGSN_Test-GSUP-IPA(361)@bb9044106d99: Established a new IPA connection (conn_id=2)
SGSN_Test-GSUP-IPA(361)@bb9044106d99: 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) } } } }
[38;5;23m20240816124635198 [1;33mDLINP[0;m[38;5;23m [1;33mNOTICE[0;m[38;5;23m received ID_GET for unit ID 0/0/0 (ipaccess.c:897)[0;m
SGSN_Test-GSUP-IPA(361)@bb9044106d99: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit }
SGSN_Test-GSUP-IPA(361)@bb9044106d99: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit }
[38;5;39m20240816124635198 [1;34mDLGSUP[0;m[38;5;39m [1;34mDEBUG[0;m[38;5;39m GSUP receiving PONG (gsup_client.c:225)[0;m
SGSN_Test-GSUP-IPA(361)@bb9044106d99: CCM Rx:{ msg_type := IPAC_MSGT_ID_RESP (5), u := { resp := { { len := 24, tag := IPAC_IDTAG_UNITNAME (1), data := '5347534E2D30302D30302D30302D30302D30302D303000'O }, { len := 7, tag := IPAC_IDTAG_UNITID (8), data := '302F302F3000'O } } } }
SGSN_Test-GSUP-IPA(361)@bb9044106d99: IPA ID RESP: { { len := 24, tag := IPAC_IDTAG_UNITNAME (1), data := '5347534E2D30302D30302D30302D30302D30302D303000'O }, { len := 7, tag := IPAC_IDTAG_UNITID (8), data := '302F302F3000'O } }
SGSN_Test-GSUP-IPA(361)@bb9044106d99: CCM Tx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit }
SGSN_Test-GSUP-IPA(361)@bb9044106d99: CCM Rx:{ msg_type := IPAC_MSGT_ID_ACK (6), u := omit }
TC_attach_check_complete_resend-BVCI196(349)@bb9044106d99: Adding Client=TC_attach_check_complete_resend(364), IMSI='262420000000036'H, TLLI='CFF8BD92'O, index=0
SGSN_Test-GSUP(362)@bb9044106d99: Created GsupExpect[0] for "262420000000036" to be handled at TC_attach_check_complete_resend(364)
TC_attach_check_complete_resend(364)@bb9044106d99: GSUP sends only 2G auth tuple{ tag := OSMO_GSUP_AUTH_TUPLE_IE (3), len := 0, val := { auth_tuple := { { tag := OSMO_GSUP_RAND_IE (32), len := 0, val := { rand := 'E8E74F960D6B1406DCC79399E1578473'O } }, { tag := OSMO_GSUP_SRES_IE (33), len := 0, val := { sres := 'FDB364BD'O } }, { tag := OSMO_GSUP_KC_IE (34), len := 0, val := { kc := '980F0C14A1B7E70D'O } } } } }
[38;5;57m20240816124636244 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00096-NSVC00097-127_0_0_10:23000-127_0_0_103:21010)[0x612000004ba0]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
20240816124636244 [1;33mDLLC[0;m [1;33mNOTICE[0;m LLME(ffffffff/cff8bd92){UNASSIGNED} LLC RX: unknown TLLI 0xcff8bd92, creating LLME on the fly (gprs_llc.c:552)
20240816124636244 [1;34mDLLC[0;m [1;34mDEBUG[0;m LLC SAPI=1 C U GEA0 IOV-UI=0x000000 FCS=0x24bf94 CMD=UI DATA (gprs_llc_parse.c:89)
[1;33m20240816124636244 [1;32mDMM[0;m[1;33m [1;32mINFO[0;m[1;33m MM(---/ffffffff) -> GMM ATTACH REQUEST [0;m[1;33mMI(IMSI-262420000000036) type="Combined GPRS/IMSI attach" [0;m[1;33m (gprs_gmm.c:1266)[0;m
[1;33m20240816124636244 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM(gmm_fsm)[0x6120000108a0]{Deregistered}: Allocated (fsm.c:456)[0;m
[1;33m20240816124636244 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x612000010a20]{Init}: Allocated (fsm.c:456)[0;m
[1;33m20240816124636244 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM_STATE_Gb[0x612000010ba0]{Idle}: Allocated (fsm.c:456)[0;m
[1;33m20240816124636244 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM_STATE_Iu[0x612000010d20]{Detached}: Allocated (fsm.c:456)[0;m
[1;33m20240816124636244 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM(gmm_fsm)[0x6120000108a0]{Deregistered}: Received Event E_GMM_COMMON_PROC_INIT_REQ (gprs_gmm.c:1144)[0;m
[1;33m20240816124636244 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM(gmm_fsm)[0x6120000108a0]{Deregistered}: state_chg to CommonProcedureInitiated (gprs_gmm_fsm.c:49)[0;m
20240816124636244 [1;33mDLLC[0;m [1;33mNOTICE[0;m LLME(ffffffff/cff8bd92){UNASSIGNED} LLGM Assign pre (cff8bd92 => e7e27a3f) (gprs_llc.c:1079)
20240816124636244 [1;33mDLLC[0;m [1;33mNOTICE[0;m LLME(cff8bd92/e7e27a3f){ASSIGNED} LLGM Assign post (cff8bd92 => e7e27a3f) (gprs_llc.c:1125)
[1;33m20240816124636244 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x612000010a20]{Init}: Received Event E_ATTACH_REQ_RECV (gprs_gmm.c:1370)[0;m
[1;33m20240816124636244 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x612000010a20]{Init}: state_chg to CheckIdentity (gprs_gmm_attach.c:52)[0;m
[1;33m20240816124636245 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM(262420000000036/e7e27a3f) <- GMM IDENTITY REQUEST: mi_type=IMEI (gprs_gmm.c:431)[0;m
[38;5;57m20240816124636255 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00096-NSVC00097-127_0_0_10:23000-127_0_0_103:21010)[0x612000004ba0]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
20240816124636255 [1;34mDLLC[0;m [1;34mDEBUG[0;m LLC SAPI=1 C U GEA0 IOV-UI=0x000000 FCS=0x612fca CMD=UI DATA (gprs_llc_parse.c:89)
[1;33m20240816124636255 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM_STATE_Gb(0)[0x612000010ba0]{Idle}: Received Event E_MM_PDU_RECEPTION (gprs_gmm.c:2333)[0;m
[1;33m20240816124636255 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM(262420000000036/e7e27a3f) -> GMM IDENTITY RESPONSE: MI=IMEI-499990000000360 (gprs_gmm.c:1084)[0;m
[1;33m20240816124636255 [1;33mDMM[0;m[1;33m [1;33mNOTICE[0;m[1;33m MM(262420000000036/e7e27a3f) Stopping *inactive* MM timer 3370 (gprs_gmm.c:115)[0;m
[1;33m20240816124636255 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x612000010a20]{CheckIdentity}: Received Event E_IDEN_RESP_RECV (gprs_gmm.c:1128)[0;m
[1;33m20240816124636255 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x612000010a20]{CheckIdentity}: state_chg to Authenticate (gprs_gmm_attach.c:115)[0;m
[1;33m20240816124636255 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x612000010a20]{Authenticate}: state_chg to AskVLR (gprs_gmm_attach.c:143)[0;m
[1;33m20240816124636255 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM(262420000000036/e7e27a3f) Requesting authorization (sgsn_auth.c:160)[0;m
[1;33m20240816124636256 [1;32mDMM[0;m[1;33m [1;32mINFO[0;m[1;33m MM(262420000000036/e7e27a3f) Requesting authentication tuples (sgsn_auth.c:184)[0;m
[1;33m20240816124636256 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM(262420000000036/e7e27a3f) Requesting subscriber authentication info (gprs_subscriber.c:921)[0;m
20240816124636256 [1;32mDGPRS[0;m [1;32mINFO[0;m SUBSCR(262420000000036) requesting auth info (gprs_subscriber.c:805)
20240816124636256 [1;32mDGPRS[0;m [1;32mINFO[0;m SUBSCR(262420000000036) Sending GSUP, will send: 08 01 08 62 42 02 00 00 00 30 f6 28 01 01 (gprs_subscriber.c:210)
SGSN_Test-GSUP(362)@bb9044106d99: Found GsupExpect[0] for "262420000000036" handled at TC_attach_check_complete_resend(364)
SGSN_Test-GSUP(362)@bb9044106d99: Added IMSI table entry 0TC_attach_check_complete_resend(364)"262420000000036"
20240816124636258 [1;32mDGPRS[0;m [1;32mINFO[0;m SUBSCR(262420000000036) Received GSUP message OSMO_GSUP_MSGT_SEND_AUTH_INFO_RESULT (gprs_subscriber.c:723)
20240816124636258 [1;32mDGPRS[0;m [1;32mINFO[0;m SUBSCR(262420000000036) Got SendAuthenticationInfoResult, num_auth_vectors = 1 (gprs_subscriber.c:242)
20240816124636258 [1;34mDGPRS[0;m [1;34mDEBUG[0;m SUBSCR(262420000000036) Adding auth tuple, cksn = 0 (gprs_subscriber.c:255)
20240816124636258 [1;34mDGPRS[0;m [1;34mDEBUG[0;m SUBSCR(262420000000036) Updating subscriber authentication info (gprs_subscriber.c:850)
[1;33m20240816124636258 [1;32mDMM[0;m[1;33m [1;32mINFO[0;m[1;33m MM(262420000000036/e7e27a3f) Subscriber data update (mmctx.c:430)[0;m
[1;33m20240816124636258 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM(262420000000036/e7e27a3f) Updating authorization (unknown -> authenticate) (sgsn_auth.c:217)[0;m
[1;33m20240816124636259 [1;32mDMM[0;m[1;33m [1;32mINFO[0;m[1;33m MM(262420000000036/e7e27a3f) Got authorization update: state unknown -> authenticate (sgsn_auth.c:246)[0;m
[1;33m20240816124636259 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x612000010a20]{AskVLR}: Received Event E_VLR_ANSWERED (gprs_gmm.c:991)[0;m
[1;33m20240816124636259 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x612000010a20]{AskVLR}: state_chg to Authenticate (gprs_gmm_attach.c:253)[0;m
[1;33m20240816124636259 [1;32mDMM[0;m[1;33m [1;32mINFO[0;m[1;33m MM(262420000000036/e7e27a3f) <- GMM AUTH AND CIPHERING REQ (rand = e8 e7 4f 96 0d 6b 14 06 dc c7 93 99 e1 57 84 73 , mmctx_is_r99=0, vec->auth_types=0x1[0;m[1;33m) (gprs_gmm.c:486)[0;m
[38;5;57m20240816124636264 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00096-NSVC00097-127_0_0_10:23000-127_0_0_103:21010)[0x612000004ba0]{UNBLOCKED}: Received Event RX-UNITDATA (gprs_ns2_vc_fsm.c:964)[0;m
20240816124636264 [1;34mDLLC[0;m [1;34mDEBUG[0;m LLC SAPI=1 C U GEA0 IOV-UI=0x000000 FCS=0xe44d18 CMD=UI DATA (gprs_llc_parse.c:89)
[1;33m20240816124636264 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM_STATE_Gb(0)[0x612000010ba0]{Idle}: Received Event E_MM_PDU_RECEPTION (gprs_gmm.c:2333)[0;m
[1;33m20240816124636264 [1;32mDMM[0;m[1;33m [1;32mINFO[0;m[1;33m MM(262420000000036/e7e27a3f) -> GMM AUTH AND CIPH RESPONSE (gprs_gmm.c:620)[0;m
[1;33m20240816124636264 [1;33mDMM[0;m[1;33m [1;33mNOTICE[0;m[1;33m MM(262420000000036/e7e27a3f) Stopping *inactive* MM timer 3360 (gprs_gmm.c:115)[0;m
[1;33m20240816124636264 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM(262420000000036/e7e27a3f) checking auth: received GSM SRES = fd b3 64 bd (gprs_gmm.c:666)[0;m
[1;33m20240816124636264 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x612000010a20]{Authenticate}: Received Event E_AUTH_RESP_RECV_SUCCESS (gprs_gmm.c:681)[0;m
[1;33m20240816124636264 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM(262420000000036/e7e27a3f) Requesting authorization (sgsn_auth.c:160)[0;m
[1;33m20240816124636264 [1;32mDMM[0;m[1;33m [1;32mINFO[0;m[1;33m MM(262420000000036/e7e27a3f) Missing information, requesting subscriber data (sgsn_auth.c:195)[0;m
[1;33m20240816124636264 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM(262420000000036/e7e27a3f) Requesting subscriber data update (gprs_subscriber.c:894)[0;m
20240816124636264 [1;32mDGPRS[0;m [1;32mINFO[0;m SUBSCR(262420000000036) subscriber data is not available (gprs_subscriber.c:818)
20240816124636264 [1;32mDGPRS[0;m [1;32mINFO[0;m SUBSCR(262420000000036) Sending GSUP, will send: 04 01 08 62 42 02 00 00 00 30 f6 28 01 01 2a 01 01 (gprs_subscriber.c:210)
[1;33m20240816124636265 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x612000010a20]{Authenticate}: state_chg to WaitAttachComplete (gprs_gmm_attach.c:181)[0;m
[1;33m20240816124636265 [1;32mDMM[0;m[1;33m [1;32mINFO[0;m[1;33m MM(262420000000036/e7e27a3f) <- GMM ATTACH ACCEPT (new P-TMSI=0xe7e27a3f) (gprs_gmm.c:300)[0;m
20240816124636266 [1;32mDGPRS[0;m [1;32mINFO[0;m SUBSCR(262420000000036) Received GSUP message OSMO_GSUP_MSGT_INSERT_DATA_REQUEST (gprs_subscriber.c:723)
20240816124636266 [1;32mDGPRS[0;m [1;32mINFO[0;m SUBSCR(262420000000036) Will set PDP info, context id = 0, APN = 2a (gprs_subscriber.c:367)
20240816124636266 [1;34mDGPRS[0;m [1;34mDEBUG[0;m SUBSCR(262420000000036) Updating subscriber data (gprs_subscriber.c:839)
[1;33m20240816124636266 [1;32mDMM[0;m[1;33m [1;32mINFO[0;m[1;33m MM(262420000000036/e7e27a3f) Subscriber data update (mmctx.c:430)[0;m
[1;33m20240816124636266 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM(262420000000036/e7e27a3f) Updating authorization (authenticate -> accepted) (sgsn_auth.c:217)[0;m
[1;33m20240816124636266 [1;32mDMM[0;m[1;33m [1;32mINFO[0;m[1;33m MM(262420000000036/e7e27a3f) Got authorization update: state authenticate -> accepted (sgsn_auth.c:246)[0;m
[1;33m20240816124636266 [1;33mDMM[0;m[1;33m [1;33mNOTICE[0;m[1;33m MM(262420000000036/e7e27a3f) Authorized, continuing procedure, IMSI=262420000000036 (gprs_gmm.c:1000)[0;m
[1;33m20240816124636266 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x612000010a20]{WaitAttachComplete}: Received Event E_VLR_ANSWERED (gprs_gmm.c:1005)[0;m
[1;33m20240816124636266 [1;33mDMM[0;m[1;33m [1;33mNOTICE[0;m[1;33m MM(262420000000036/e7e27a3f) Unusual event: if MS got no data connection, check that it has APN configured. (gprs_gmm_attach.c:217)[0;m
20240816124636266 [1;32mDGPRS[0;m [1;32mINFO[0;m SUBSCR(262420000000036) Sending GSUP, will send: 12 01 08 62 42 02 00 00 00 30 f6 28 01 01 (gprs_subscriber.c:210)
20240816124636266 [1;32mDGPRS[0;m [1;32mINFO[0;m SUBSCR(262420000000036) Received GSUP message OSMO_GSUP_MSGT_UPDATE_LOCATION_RESULT (gprs_subscriber.c:723)
20240816124636266 [1;34mDGPRS[0;m [1;34mDEBUG[0;m SUBSCR(262420000000036) Updating subscriber data (gprs_subscriber.c:839)
[1;33m20240816124636266 [1;32mDMM[0;m[1;33m [1;32mINFO[0;m[1;33m MM(262420000000036/e7e27a3f) Subscriber data update (mmctx.c:430)[0;m
[1;33m20240816124636266 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM(262420000000036/e7e27a3f) Updating authorization (accepted -> accepted) (sgsn_auth.c:217)[0;m
TC_attach_check_complete_resend(364)@bb9044106d99: Warning: Re-starting timer T, which is already active (running or expired).
[1;33m20240816124642265 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x612000010a20]{WaitAttachComplete}: Timeout of T3350 (fsm.c:317)[0;m
[1;33m20240816124642265 [1;32mDMM[0;m[1;33m [1;32mINFO[0;m[1;33m MM(262420000000036/e7e27a3f) <- GMM ATTACH ACCEPT (new P-TMSI=0xe7e27a3f) (gprs_gmm.c:300)[0;m
TC_attach_check_complete_resend(364)@bb9044106d99: Warning: Re-starting timer T, which is already active (running or expired).
20240816124645596 [1;34mDGPRS[0;m [1;34mDEBUG[0;m Checking for inactive LLMEs, time = 5623139 (sgsn.c:131)
[1;33m20240816124648266 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x612000010a20]{WaitAttachComplete}: Timeout of T3350 (fsm.c:317)[0;m
[1;33m20240816124648266 [1;32mDMM[0;m[1;33m [1;32mINFO[0;m[1;33m MM(262420000000036/e7e27a3f) <- GMM ATTACH ACCEPT (new P-TMSI=0xe7e27a3f) (gprs_gmm.c:300)[0;m
TC_attach_check_complete_resend(364)@bb9044106d99: Warning: Re-starting timer T, which is already active (running or expired).
[1;33m20240816124654267 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x612000010a20]{WaitAttachComplete}: Timeout of T3350 (fsm.c:317)[0;m
[1;33m20240816124654267 [1;32mDMM[0;m[1;33m [1;32mINFO[0;m[1;33m MM(262420000000036/e7e27a3f) <- GMM ATTACH ACCEPT (new P-TMSI=0xe7e27a3f) (gprs_gmm.c:300)[0;m
TC_attach_check_complete_resend(364)@bb9044106d99: Warning: Re-starting timer T, which is already active (running or expired).
[38;5;39m20240816124655196 [1;32mDLGSUP[0;m[38;5;39m [1;32mINFO[0;m[38;5;39m GSUP ping callback (connected, got PONG) (gsup_client.c:266)[0;m
[38;5;39m20240816124655196 [1;34mDLGSUP[0;m[38;5;39m [1;34mDEBUG[0;m[38;5;39m GSUP sending PING (gsup_client.c:288)[0;m
SGSN_Test-GSUP-IPA(361)@bb9044106d99: CCM Rx:{ msg_type := IPAC_MSGT_PING (0), u := omit }
SGSN_Test-GSUP-IPA(361)@bb9044106d99: CCM Tx:{ msg_type := IPAC_MSGT_PONG (1), u := omit }
[38;5;39m20240816124655197 [1;34mDLGSUP[0;m[38;5;39m [1;34mDEBUG[0;m[38;5;39m GSUP receiving PONG (gsup_client.c:225)[0;m
[1;33m20240816124700268 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x612000010a20]{WaitAttachComplete}: Timeout of T3350 (fsm.c:317)[0;m
[1;33m20240816124700268 [1;32mDMM[0;m[1;33m [1;32mINFO[0;m[1;33m MM(262420000000036/e7e27a3f) <- GMM ATTACH ACCEPT (new P-TMSI=0xe7e27a3f) (gprs_gmm.c:300)[0;m
TC_attach_check_complete_resend(364)@bb9044106d99: Warning: Re-starting timer T, which is already active (running or expired).
[38;5;63m20240816124704371 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00096)-NSVC(00097) Tx NS-ALIVE (gprs_ns2_message.c:193)[0;m
[38;5;63m20240816124704373 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00096)-NSVC(00097) Rx NS-ALIVE-ACK (gprs_ns2.c:1309)[0;m
[38;5;57m20240816124704373 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00096-NSVC00097-127_0_0_10:23000-127_0_0_103:21010)[0x612000004ba0]{UNBLOCKED}: Received Event RX-ALIVE_ACK (gprs_ns2_vc_fsm.c:960)[0;m
[38;5;63m20240816124704405 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00097)-NSVC(00098) Tx NS-ALIVE (gprs_ns2_message.c:193)[0;m
[38;5;63m20240816124704406 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00097)-NSVC(00098) Rx NS-ALIVE-ACK (gprs_ns2.c:1309)[0;m
[38;5;57m20240816124704406 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00097-NSVC00098-127_0_0_10:23000-127_0_0_103:21011)[0x612000004d20]{UNBLOCKED}: Received Event RX-ALIVE_ACK (gprs_ns2_vc_fsm.c:960)[0;m
[38;5;63m20240816124704442 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00098)-NSVC(00099) Tx NS-ALIVE (gprs_ns2_message.c:193)[0;m
[38;5;63m20240816124704443 [1;32mDLNSSIGNAL[0;m[38;5;63m [1;32mINFO[0;m[38;5;63m NSE(00098)-NSVC(00099) Rx NS-ALIVE-ACK (gprs_ns2.c:1309)[0;m
[38;5;57m20240816124704443 [1;34mDLNS[0;m[38;5;57m [1;34mDEBUG[0;m[38;5;57m GPRS-NS2-VC(UDP-NSE00098-NSVC00099-127_0_0_10:23000-127_0_0_103:21012)[0x612000004ea0]{UNBLOCKED}: Received Event RX-ALIVE_ACK (gprs_ns2_vc_fsm.c:960)[0;m
[1;33m20240816124706269 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x612000010a20]{WaitAttachComplete}: Timeout of T3350 (fsm.c:317)[0;m
[1;33m20240816124706269 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x612000010a20]{WaitAttachComplete}: Received Event E_REJECT (gprs_gmm_attach.c:444)[0;m
[1;33m20240816124706269 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x612000010a20]{WaitAttachComplete}: state_chg to Reject (gprs_gmm_attach.c:397)[0;m
20240816124706269 [1;32mDGPRS[0;m [1;32mINFO[0;m SUBSCR(262420000000036) purging MS subscriber (gprs_subscriber.c:785)
20240816124706269 [1;32mDGPRS[0;m [1;32mINFO[0;m SUBSCR(262420000000036) Sending GSUP, will send: 0c 01 08 62 42 02 00 00 00 30 f6 09 00 28 01 01 (gprs_subscriber.c:210)
[1;33m20240816124706269 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM_ATTACH_REQ_FSM(gb_gmm_req)[0x612000010a20]{Reject}: Deallocated (fsm.c:568)[0;m
[1;33m20240816124706269 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM_STATE_Gb(0)[0x612000010ba0]{Idle}: Deallocated (fsm.c:568)[0;m
[1;33m20240816124706270 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m MM_STATE_Iu[0x612000010d20]{Detached}: Deallocated (fsm.c:568)[0;m
[1;33m20240816124706270 [1;34mDMM[0;m[1;33m [1;34mDEBUG[0;m[1;33m GMM(gmm_fsm)[0x6120000108a0]{CommonProcedureInitiated}: Deallocated (fsm.c:568)[0;m
20240816124706270 [1;33mDLLC[0;m [1;33mNOTICE[0;m LLME(cff8bd92/e7e27a3f){ASSIGNED} LLGM Assign pre (e7e27a3f => ffffffff) (gprs_llc.c:1079)
20240816124706270 [1;33mDLLC[0;m [1;33mNOTICE[0;m LLME(00000000/00000000){UNASSIGNED} LLGM Assign post (e7e27a3f => ffffffff) (gprs_llc.c:1125)
[0;94m[testenv][generic] Testsuite is done[0m
[1;34m[testenv][generic] Stopping testsuite (180725)[0m
[1;34m[testenv][generic] Merging log files[0m
[0;94m[testenv][generic] + ['podman', 'exec', '-e', 'CCACHE_DIR=/home/osmocom-build/ccache/testenv', '-e', 'TESTENV_CACHE_DIR=<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-asan/ws/_cache',> '-e', 'TESTENV_SRC_DIR=/home/osmocom-build/jenkins/workspace', '-e', 'TERM=dumb', '-e', 'TESTENV_GIT_DIR=<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-asan/ws/_cache/git',> '-e', 'PATH=<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-asan/ws/_testenv/da…>:<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-asan/ws/_cache/podm…>:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin', '-e', 'HOME=/home/osmocom-build', '-t', '-w', '<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-asan/74/artifact/lo…',> 'testenv-sgsn-generic-osmocom-nightly-asan-20240816-1242-e766b8ba', 'sh', '-c', '<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-asan/ws/_cache/podm…> SGSN_Tests --rm >/dev/null'][0m
Error: container bb9044106d99740e0b73386982751f86cd521fbd5e9223776ed9026150a64a39 does not exist in database: no such container
[1;91m[testenv] podman container crashed![0m
Error: no container with name or ID testenv-sgsn-generic-osmocom-nightly-asan-20240816-1242-e766b8ba found: no such container
[1;91m[testenv][generic] Command failed unexpectedly[0m
[0;94m[testenv][generic] Command: ['podman', 'exec', '-e', 'CCACHE_DIR=/home/osmocom-build/ccache/testenv', '-e', 'TESTENV_CACHE_DIR=<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-asan/ws/_cache',> '-e', 'TESTENV_SRC_DIR=/home/osmocom-build/jenkins/workspace', '-e', 'TERM=dumb', '-e', 'TESTENV_GIT_DIR=<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-asan/ws/_cache/git',> '-e', 'PATH=<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-asan/ws/_testenv/da…>:<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-asan/ws/_cache/podm…>:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin', '-e', 'HOME=/home/osmocom-build', '-t', '-w', '<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-asan/74/artifact/lo…',> 'testenv-sgsn-generic-osmocom-nightly-asan-20240816-1242-e766b8ba', 'sh', '-c', '<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-asan/ws/_cache/podm…> SGSN_Tests --rm >/dev/null'][0m
[0;94m[testenv][generic] Returncode: 125[0m
Traceback (most recent call last):
File "<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-asan/ws/./testenv.py",> line 115, in <module>
main()
File "<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-asan/ws/./testenv.py",> line 104, in main
run()
File "<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-asan/ws/./testenv.py",> line 63, in run
testenv.testsuite.run(cfg)
File "<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-asan/ws/_testenv/te…",> line 199, in run
merge_log_files(cfg)
File "<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-asan/ws/_testenv/te…",> line 131, in merge_log_files
testenv.cmd.run(cmd, cwd=cwd)
File "<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-asan/ws/_testenv/te…",> line 86, in run
return testenv.podman.exec_cmd(cmd, check=check, env=env, *args, **kwargs)
File "<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-asan/ws/_testenv/te…",> line 133, in exec_cmd
testenv.cmd.run(
File "<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-asan/ws/_testenv/te…",> line 103, in run
exit_error_cmd(p, "Command failed unexpectedly")
File "<https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-asan/ws/_testenv/te…",> line 59, in exit_error_cmd
raise RuntimeError("shell command related error, find details right above this python trace")
RuntimeError: shell command related error, find details right above this python trace
[1;34m[testenv][generic] Logs saved to: https://jenkins.osmocom.org/jenkins/job/ttcn3-sgsn-test-asan/74/artifact/lo… [0m
Build step 'Execute shell' marked build as failure
Recording test results
[Checks API] No suitable checks publisher found.
1
0