jenkins-notifications September 2026

jenkins-notifications@lists.osmocom.org

1 participants
59 discussions

Build failed in Jenkins: ttcn3-gbproxy-test-fr-latest #1528
by jenkins＠lists.osmocom.org 16 Sep '26

16 Sep '26

See <https://jenkins.osmocom.org/jenkins/job/ttcn3-gbproxy-test-fr-latest/1528/d…> Changes: ------------------------------------------ [...truncated 2.96 MiB...] at hudson.remoting.PingThread.run(PingThread.java:87) Caused: hudson.remoting.Channel$OrderlyShutdown at hudson.remoting.Channel$CloseCommand.execute(Channel.java:1431) at hudson.remoting.Channel$1.handle(Channel.java:664) at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:86) Caused: hudson.remoting.ChannelClosedException: Channel "hudson.remoting.Channel@2e75aa:gtp0-deb10fr": Remote call on gtp0-deb10fr failed. The channel is closing down or has closed down at hudson.remoting.Channel.call(Channel.java:1101) at hudson.FilePath.act(FilePath.java:1220) at hudson.FilePath.act(FilePath.java:1209) at hudson.FilePath.delete(FilePath.java:1756) at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:163) at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:92) at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20) at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:817) at hudson.model.Build$BuildExecution.build(Build.java:199) at hudson.model.Build$BuildExecution.doRun(Build.java:164) at hudson.model.AbstractBuild$AbstractBuildExecution.run(AbstractBuild.java:526) at hudson.model.Run.execute(Run.java:1849) at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:44) at hudson.model.ResourceController.execute(ResourceController.java:97) at hudson.model.Executor.run(Executor.java:456) Build step 'Execute shell' marked build as failure GbProxy_Test-NS(PCU[2])-NSVCI7-NSVCemu7-FRemu(4079)@97156dfe9099: AF_PACKET Test port (FR): Using AF_PACKET netdev `hdlc7' GbProxy_Test-NS(PCU[2])-NSVCI8-NSVCemu8-provFR(4080)@97156dfe9099: Warning: Test port parameter sleep_on_enobufs is not supported on port FR. GbProxy_Test-NS(PCU[2])-NSVCI8(4077)@97156dfe9099: NSVC 8 State Transition: NSVC_S_DEAD_BLOCKED (1) -> NSVC_S_DEAD_BLOCKED (1) ERROR: Step ‘Publish JUnit test result report’ failed: no workspace for ttcn3-gbproxy-test-fr-latest #1528 GbProxy_Test-NS(PCU[2])-NSVCI8-NSVCemu8-FRemu(4081)@97156dfe9099: AF_PACKET Test port (FR): user_start GbProxy_Test-NS(PCU[2])-NSVCI8-NSVCemu8-FRemu(4081)@97156dfe9099: AF_PACKET Test port (FR): user_map GbProxy_Test-NS(PCU[2])-NSVCI8-NSVCemu8-FRemu(4081)@97156dfe9099: AF_PACKET Test port (FR): Using AF_PACKET netdev `hdlc8' GbProxy_Test-NS(PCU[0])-NSVCI1-NSVCemu1-FRemu(4054)@97156dfe9099: Dropping Rx FR frame while service affecting condition exists GbProxy_Test-NS(PCU[0])-NSVCI2-NSVCemu2-FRemu(4058)@97156dfe9099: Dropping Rx FR frame while service affecting condition exists GbProxy_Test-NS(PCU[0])-NSVCI3-NSVCemu3-FRemu(4061)@97156dfe9099: Dropping Rx FR frame while service affecting condition exists GbProxy_Test-NS(PCU[0])-NSVCI4-NSVCemu4-FRemu(4064)@97156dfe9099: Dropping Rx FR frame while service affecting condition exists GbProxy_Test-NS(PCU[1])-NSVCI6-NSVCemu6-FRemu(4070)@97156dfe9099: Dropping Rx FR frame while service affecting condition exists GbProxy_Test-NS(PCU[1])-NSVCI5-NSVCemu5-FRemu(4068)@97156dfe9099: Dropping Rx FR frame while service affecting condition exists GbProxy_Test-NS(PCU[2])-NSVCI7-NSVCemu7-FRemu(4079)@97156dfe9099: Dropping Rx FR frame while service affecting condition exists GbProxy_Test-NS(PCU[2])-NSVCI8-NSVCemu8-FRemu(4081)@97156dfe9099: Dropping Rx FR frame while service affecting condition exists GbProxy_Test-NS(PCU[0])-NSVCI1-NSVCemu1-FRemu(4054)@97156dfe9099: Detecting no more service affecting condition after N392 consecutive good cycles GbProxy_Test-NS(PCU[0])-NSVCI2-NSVCemu2-FRemu(4058)@97156dfe9099: Detecting no more service affecting condition after N392 consecutive good cycles GbProxy_Test-NS(PCU[0])-NSVCI3-NSVCemu3-FRemu(4061)@97156dfe9099: Detecting no more service affecting condition after N392 consecutive good cycles GbProxy_Test-NS(PCU[0])-NSVCI4-NSVCemu4-FRemu(4064)@97156dfe9099: Detecting no more service affecting condition after N392 consecutive good cycles GbProxy_Test-NS(PCU[1])-NSVCI5-NSVCemu5-FRemu(4068)@97156dfe9099: Detecting no more service affecting condition after N392 consecutive good cycles GbProxy_Test-NS(PCU[1])-NSVCI6-NSVCemu6-FRemu(4070)@97156dfe9099: Detecting no more service affecting condition after N392 consecutive good cycles GbProxy_Test-NS(PCU[2])-NSVCI7-NSVCemu7-FRemu(4079)@97156dfe9099: Detecting no more service affecting condition after N392 consecutive good cycles GbProxy_Test-NS(PCU[2])-NSVCI8-NSVCemu8-FRemu(4081)@97156dfe9099: Detecting no more service affecting condition after N392 consecutive good cycles GbProxy_Test-NS(PCU[0])-NSVCI1-NSVCemu1-provFR(4051)@97156dfe9099: Dropping Rx Msg because FR DLC not yet available{ hdr := { dlci := 16, c_r := false, fecn := false, becn := false, de := false }, payload := '0200810101820001048207D1'O } GbProxy_Test-NS(PCU[0])-NSVCI2-NSVCemu2-provFR(4053)@97156dfe9099: Dropping Rx Msg because FR DLC not yet available{ hdr := { dlci := 17, c_r := false, fecn := false, becn := false, de := false }, payload := '0200810101820002048207D1'O } GbProxy_Test-NS(PCU[0])-NSVCI3-NSVCemu3-provFR(4057)@97156dfe9099: Dropping Rx Msg because FR DLC not yet available{ hdr := { dlci := 18, c_r := false, fecn := false, becn := false, de := false }, payload := '0200810101820003048207D1'O } GbProxy_Test-NS(PCU[0])-NSVCI4-NSVCemu4-provFR(4060)@97156dfe9099: Dropping Rx Msg because FR DLC not yet available{ hdr := { dlci := 19, c_r := false, fecn := false, becn := false, de := false }, payload := '0200810101820004048207D1'O } GbProxy_Test-NS(PCU[1])-NSVCI5-NSVCemu5-provFR(4066)@97156dfe9099: Dropping Rx Msg because FR DLC not yet available{ hdr := { dlci := 20, c_r := false, fecn := false, becn := false, de := false }, payload := '0200810101820005048207D2'O } GbProxy_Test-NS(PCU[1])-NSVCI6-NSVCemu6-provFR(4069)@97156dfe9099: Dropping Rx Msg because FR DLC not yet available{ hdr := { dlci := 21, c_r := false, fecn := false, becn := false, de := false }, payload := '0200810101820006048207D2'O } GbProxy_Test-NS(PCU[2])-NSVCI7-NSVCemu7-provFR(4076)@97156dfe9099: Dropping Rx Msg because FR DLC not yet available{ hdr := { dlci := 22, c_r := false, fecn := false, becn := false, de := false }, payload := '0200810101820007048207D3'O } GbProxy_Test-NS(PCU[2])-NSVCI8-NSVCemu8-provFR(4080)@97156dfe9099: Dropping Rx Msg because FR DLC not yet available{ hdr := { dlci := 23, c_r := false, fecn := false, becn := false, de := false }, payload := '0200810101820008048207D3'O } GbProxy_Test-NS(PCU[0])-NSVCI1(4048)@97156dfe9099: Provider Link came up: sending NS-RESET GbProxy_Test-NS(PCU[0])-NSVCI1(4048)@97156dfe9099: NSVC 1 State Transition: NSVC_S_WAIT_RESET (2) -> NSVC_S_ALIVE_BLOCKED (3) GbProxy_Test-NS(PCU[0])-NSVCI1(4048)@97156dfe9099: NSVC 1 State Transition: NSVC_S_ALIVE_BLOCKED (3) -> NSVC_S_ALIVE_UNBLOCKED (4) GbProxy_Test-NS(PCU[0])-NSVCI2(4050)@97156dfe9099: Provider Link came up: sending NS-RESET GbProxy_Test-NS(PCU[0])-NSVCI3(4052)@97156dfe9099: Provider Link came up: sending NS-RESET GbProxy_Test-NS(PCU[0])-NSVCI1(4048)@97156dfe9099: Rx NS-ALIVE-ACK: stopping Tns-alive; starting Tns-test GbProxy_Test-NS(PCU[0])-NSVCI2(4050)@97156dfe9099: NSVC 2 State Transition: NSVC_S_WAIT_RESET (2) -> NSVC_S_ALIVE_BLOCKED (3) GbProxy_Test-NS(PCU[0])-NSVCI2(4050)@97156dfe9099: NSVC 2 State Transition: NSVC_S_ALIVE_BLOCKED (3) -> NSVC_S_ALIVE_UNBLOCKED (4) GbProxy_Test-NS(PCU[0])-NSVCI4(4056)@97156dfe9099: Provider Link came up: sending NS-RESET GbProxy_Test-NS(PCU[0])-NSVCI2(4050)@97156dfe9099: Rx NS-ALIVE-ACK: stopping Tns-alive; starting Tns-test GbProxy_Test-NS(PCU[0])-NSVCI3(4052)@97156dfe9099: NSVC 3 State Transition: NSVC_S_WAIT_RESET (2) -> NSVC_S_ALIVE_BLOCKED (3) GbProxy_Test-NS(PCU[0])-NSVCI3(4052)@97156dfe9099: NSVC 3 State Transition: NSVC_S_ALIVE_BLOCKED (3) -> NSVC_S_ALIVE_UNBLOCKED (4) GbProxy_Test-NS(PCU[0])-NSVCI4(4056)@97156dfe9099: NSVC 4 State Transition: NSVC_S_WAIT_RESET (2) -> NSVC_S_ALIVE_BLOCKED (3) GbProxy_Test-NS(PCU[0])-NSVCI4(4056)@97156dfe9099: NSVC 4 State Transition: NSVC_S_ALIVE_BLOCKED (3) -> NSVC_S_ALIVE_UNBLOCKED (4) GbProxy_Test-BSSGP(PCU[0])(4047)@97156dfe9099: BVCI(0) Rx BVC-RESET-ACK GbProxy_Test-BSSGP(PCU[0])(4047)@97156dfe9099: BVCI(0) State Transition: BVC_S_WAIT_NS_ALIVE_UNBLOCKED (1) -> BVC_S_UNBLOCKED (3) GbProxy_Test-NS(PCU[0])-NSVCI3(4052)@97156dfe9099: Rx NS-ALIVE-ACK: stopping Tns-alive; starting Tns-test GbProxy_Test-NS(PCU[1])-NSVCI6(4067)@97156dfe9099: Provider Link came up: sending NS-RESET GbProxy_Test-NS(PCU[1])-NSVCI5(4062)@97156dfe9099: Provider Link came up: sending NS-RESET GbProxy_Test-NS(PCU[0])-NSVCI4(4056)@97156dfe9099: Rx NS-ALIVE-ACK: stopping Tns-alive; starting Tns-test GbProxy_Test-NS(PCU[1])-NSVCI6(4067)@97156dfe9099: NSVC 6 State Transition: NSVC_S_WAIT_RESET (2) -> NSVC_S_ALIVE_BLOCKED (3) GbProxy_Test-NS(PCU[1])-NSVCI6(4067)@97156dfe9099: NSVC 6 State Transition: NSVC_S_ALIVE_BLOCKED (3) -> NSVC_S_ALIVE_UNBLOCKED (4) GbProxy_Test-BSSGP(SGSN[0])-BVCI20011(4025)@97156dfe9099: Rx BVC-RESET from BVCI=20011 GbProxy_Test-NS(PCU[1])-NSVCI5(4062)@97156dfe9099: NSVC 5 State Transition: NSVC_S_WAIT_RESET (2) -> NSVC_S_ALIVE_BLOCKED (3) GbProxy_Test-BSSGP(SGSN[0])-BVCI20011(4025)@97156dfe9099: BVCI(20011) State Transition: BVC_S_BLOCKED (2) -> BVC_S_UNBLOCKED (3) GbProxy_Test-NS(PCU[1])-NSVCI5(4062)@97156dfe9099: NSVC 5 State Transition: NSVC_S_ALIVE_BLOCKED (3) -> NSVC_S_ALIVE_UNBLOCKED (4) GbProxy_Test-BSSGP(SGSN[1])-BVCI20011(4037)@97156dfe9099: Rx BVC-RESET from BVCI=20011 GbProxy_Test-NS(PCU[1])-NSVCI6(4067)@97156dfe9099: Rx NS-ALIVE-ACK: stopping Tns-alive; starting Tns-test GbProxy_Test-BSSGP(SGSN[1])-BVCI20011(4037)@97156dfe9099: BVCI(20011) State Transition: BVC_S_BLOCKED (2) -> BVC_S_UNBLOCKED (3) GbProxy_Test-BSSGP(PCU[0])-BVCI20011(4049)@97156dfe9099: BVCI(20011) State Transition: BVC_S_BLOCKED (2) -> BVC_S_UNBLOCKED (3) GbProxy_Test-NS(PCU[2])-NSVCI7(4073)@97156dfe9099: Provider Link came up: sending NS-RESET GbProxy_Test-NS(PCU[1])-NSVCI5(4062)@97156dfe9099: Rx NS-ALIVE-ACK: stopping Tns-alive; starting Tns-test GbProxy_Test-NS(PCU[2])-NSVCI8(4077)@97156dfe9099: Provider Link came up: sending NS-RESET GbProxy_Test-BSSGP(PCU[1])(4059)@97156dfe9099: BVCI(0) Rx BVC-RESET-ACK GbProxy_Test-BSSGP(PCU[1])(4059)@97156dfe9099: BVCI(0) State Transition: BVC_S_WAIT_NS_ALIVE_UNBLOCKED (1) -> BVC_S_UNBLOCKED (3) GbProxy_Test-NS(PCU[2])-NSVCI7(4073)@97156dfe9099: NSVC 7 State Transition: NSVC_S_WAIT_RESET (2) -> NSVC_S_ALIVE_BLOCKED (3) GbProxy_Test-NS(PCU[2])-NSVCI7(4073)@97156dfe9099: NSVC 7 State Transition: NSVC_S_ALIVE_BLOCKED (3) -> NSVC_S_ALIVE_UNBLOCKED (4) GbProxy_Test-NS(PCU[2])-NSVCI8(4077)@97156dfe9099: NSVC 8 State Transition: NSVC_S_WAIT_RESET (2) -> NSVC_S_ALIVE_BLOCKED (3) GbProxy_Test-NS(PCU[2])-NSVCI8(4077)@97156dfe9099: NSVC 8 State Transition: NSVC_S_ALIVE_BLOCKED (3) -> NSVC_S_ALIVE_UNBLOCKED (4) GbProxy_Test-BSSGP(SGSN[0])-BVCI20021(4027)@97156dfe9099: Rx BVC-RESET from BVCI=20021 GbProxy_Test-BSSGP(SGSN[1])-BVCI20021(4039)@97156dfe9099: Rx BVC-RESET from BVCI=20021 GbProxy_Test-BSSGP(SGSN[1])-BVCI20021(4039)@97156dfe9099: BVCI(20021) State Transition: BVC_S_BLOCKED (2) -> BVC_S_UNBLOCKED (3) GbProxy_Test-BSSGP(SGSN[1])-BVCI20022(4040)@97156dfe9099: Rx BVC-RESET from BVCI=20022 GbProxy_Test-BSSGP(SGSN[0])-BVCI20021(4027)@97156dfe9099: BVCI(20021) State Transition: BVC_S_BLOCKED (2) -> BVC_S_UNBLOCKED (3) GbProxy_Test-BSSGP(SGSN[1])-BVCI20022(4040)@97156dfe9099: BVCI(20022) State Transition: BVC_S_BLOCKED (2) -> BVC_S_UNBLOCKED (3) GbProxy_Test-BSSGP(SGSN[0])-BVCI20022(4028)@97156dfe9099: Rx BVC-RESET from BVCI=20022 GbProxy_Test-NS(PCU[2])-NSVCI8(4077)@97156dfe9099: Rx NS-ALIVE-ACK: stopping Tns-alive; starting Tns-test GbProxy_Test-NS(PCU[2])-NSVCI7(4073)@97156dfe9099: Rx NS-ALIVE-ACK: stopping Tns-alive; starting Tns-test GbProxy_Test-BSSGP(SGSN[0])-BVCI20022(4028)@97156dfe9099: BVCI(20022) State Transition: BVC_S_BLOCKED (2) -> BVC_S_UNBLOCKED (3) GbProxy_Test-BSSGP(PCU[1])-BVCI20021(4063)@97156dfe9099: BVCI(20021) State Transition: BVC_S_BLOCKED (2) -> BVC_S_UNBLOCKED (3) GbProxy_Test-BSSGP(PCU[1])-BVCI20022(4065)@97156dfe9099: BVCI(20022) State Transition: BVC_S_BLOCKED (2) -> BVC_S_UNBLOCKED (3) GbProxy_Test-BSSGP(PCU[2])(4072)@97156dfe9099: BVCI(0) Rx BVC-RESET-ACK GbProxy_Test-BSSGP(PCU[2])(4072)@97156dfe9099: BVCI(0) State Transition: BVC_S_WAIT_NS_ALIVE_UNBLOCKED (1) -> BVC_S_UNBLOCKED (3) GbProxy_Test-BSSGP(SGSN[0])-BVCI20032(4032)@97156dfe9099: Rx BVC-RESET from BVCI=20032 GbProxy_Test-BSSGP(SGSN[1])-BVCI20031(4042)@97156dfe9099: Rx BVC-RESET from BVCI=20031 GbProxy_Test-BSSGP(SGSN[1])-BVCI20032(4044)@97156dfe9099: Rx BVC-RESET from BVCI=20032 GbProxy_Test-BSSGP(SGSN[0])-BVCI20032(4032)@97156dfe9099: BVCI(20032) State Transition: BVC_S_BLOCKED (2) -> BVC_S_UNBLOCKED (3) GbProxy_Test-BSSGP(SGSN[1])-BVCI20031(4042)@97156dfe9099: BVCI(20031) State Transition: BVC_S_BLOCKED (2) -> BVC_S_UNBLOCKED (3) GbProxy_Test-BSSGP(SGSN[1])-BVCI20032(4044)@97156dfe9099: BVCI(20032) State Transition: BVC_S_BLOCKED (2) -> BVC_S_UNBLOCKED (3) GbProxy_Test-BSSGP(SGSN[1])-BVCI20033(4045)@97156dfe9099: Rx BVC-RESET from BVCI=20033 GbProxy_Test-BSSGP(SGSN[0])-BVCI20031(4030)@97156dfe9099: Rx BVC-RESET from BVCI=20031 GbProxy_Test-BSSGP(PCU[2])-BVCI20032(4075)@97156dfe9099: BVCI(20032) State Transition: BVC_S_BLOCKED (2) -> BVC_S_UNBLOCKED (3) GbProxy_Test-BSSGP(SGSN[0])-BVCI20033(4033)@97156dfe9099: Rx BVC-RESET from BVCI=20033 GbProxy_Test-BSSGP(SGSN[0])-BVCI20031(4030)@97156dfe9099: BVCI(20031) State Transition: BVC_S_BLOCKED (2) -> BVC_S_UNBLOCKED (3) GbProxy_Test-BSSGP(SGSN[0])-BVCI20033(4033)@97156dfe9099: BVCI(20033) State Transition: BVC_S_BLOCKED (2) -> BVC_S_UNBLOCKED (3) GbProxy_Test-BSSGP(SGSN[1])-BVCI20033(4045)@97156dfe9099: BVCI(20033) State Transition: BVC_S_BLOCKED (2) -> BVC_S_UNBLOCKED (3) GbProxy_Test-BSSGP(PCU[2])-BVCI20033(4078)@97156dfe9099: BVCI(20033) State Transition: BVC_S_BLOCKED (2) -> BVC_S_UNBLOCKED (3) GbProxy_Test-BSSGP(PCU[2])-BVCI20031(4074)@97156dfe9099: BVCI(20031) State Transition: BVC_S_BLOCKED (2) -> BVC_S_UNBLOCKED (3) MTC@97156dfe9099: Warning: Re-starting timer g_Tguard, which is already active (running or expired). MTC@97156dfe9099: Testing RIM PCU2PCU from PCU[0][0] to PCU[1][0] MTC@97156dfe9099: setverdict(pass): none -> pass MTC@97156dfe9099: Testing RIM PCU2PCU from PCU[0][0] to PCU[1][1] MTC@97156dfe9099: setverdict(pass): pass -> pass, component reason not changed MTC@97156dfe9099: Testing RIM PCU2PCU from PCU[0][0] to PCU[2][0] MTC@97156dfe9099: setverdict(pass): pass -> pass, component reason not changed MTC@97156dfe9099: Testing RIM PCU2PCU from PCU[0][0] to PCU[2][1] MTC@97156dfe9099: setverdict(pass): pass -> pass, component reason not changed MTC@97156dfe9099: Testing RIM PCU2PCU from PCU[0][0] to PCU[2][2] MTC@97156dfe9099: setverdict(pass): pass -> pass, component reason not changed MTC@97156dfe9099: Testing RIM PCU2PCU from PCU[1][0] to PCU[0][0] MTC@97156dfe9099: setverdict(pass): pass -> pass, component reason not changed MTC@97156dfe9099: Testing RIM PCU2PCU from PCU[1][0] to PCU[2][0] MTC@97156dfe9099: setverdict(pass): pass -> pass, component reason not changed MTC@97156dfe9099: Testing RIM PCU2PCU from PCU[1][0] to PCU[2][1] MTC@97156dfe9099: setverdict(pass): pass -> pass, component reason not changed MTC@97156dfe9099: Testing RIM PCU2PCU from PCU[1][0] to PCU[2][2] MTC@97156dfe9099: setverdict(pass): pass -> pass, component reason not changed MTC@97156dfe9099: Testing RIM PCU2PCU from PCU[1][1] to PCU[0][0] MTC@97156dfe9099: setverdict(pass): pass -> pass, component reason not changed MTC@97156dfe9099: Testing RIM PCU2PCU from PCU[1][1] to PCU[2][0] MTC@97156dfe9099: setverdict(pass): pass -> pass, component reason not changed MTC@97156dfe9099: Testing RIM PCU2PCU from PCU[1][1] to PCU[2][1] MTC@97156dfe9099: setverdict(pass): pass -> pass, component reason not changed MTC@97156dfe9099: Testing RIM PCU2PCU from PCU[1][1] to PCU[2][2] MTC@97156dfe9099: setverdict(pass): pass -> pass, component reason not changed MTC@97156dfe9099: Testing RIM PCU2PCU from PCU[2][0] to PCU[0][0] MTC@97156dfe9099: setverdict(pass): pass -> pass, component reason not changed MTC@97156dfe9099: Testing RIM PCU2PCU from PCU[2][0] to PCU[1][0] MTC@97156dfe9099: setverdict(pass): pass -> pass, component reason not changed MTC@97156dfe9099: Testing RIM PCU2PCU from PCU[2][0] to PCU[1][1] MTC@97156dfe9099: setverdict(pass): pass -> pass, component reason not changed MTC@97156dfe9099: Testing RIM PCU2PCU from PCU[2][1] to PCU[0][0] MTC@97156dfe9099: setverdict(pass): pass -> pass, component reason not changed MTC@97156dfe9099: Testing RIM PCU2PCU from PCU[2][1] to PCU[1][0] MTC@97156dfe9099: setverdict(pass): pass -> pass, component reason not changed MTC@97156dfe9099: Testing RIM PCU2PCU from PCU[2][1] to PCU[1][1] MTC@97156dfe9099: setverdict(pass): pass -> pass, component reason not changed MTC@97156dfe9099: Testing RIM PCU2PCU from PCU[2][2] to PCU[0][0] MTC@97156dfe9099: setverdict(pass): pass -> pass, component reason not changed MTC@97156dfe9099: Testing RIM PCU2PCU from PCU[2][2] to PCU[1][0] MTC@97156dfe9099: setverdict(pass): pass -> pass, component reason not changed MTC@97156dfe9099: Testing RIM PCU2PCU from PCU[2][2] to PCU[1][1] MTC@97156dfe9099: setverdict(pass): pass -> pass, component reason not changed MTC@97156dfe9099: setverdict(pass): pass -> pass reason: ""GBProxy_Tests.ttcn:674 : "", component reason not changed MTC@97156dfe9099: Stopping testcase execution from "GBProxy_Tests.ttcn":674 IPA-CTRL-CLI-IPA(4021)@97156dfe9099: Final verdict of PTC: none GbProxy_Test-NS(SGSN[0])(4022)@97156dfe9099: Final verdict of PTC: none GbProxy_Test-BSSGP(SGSN[0])-BVCI20032(4032)@97156dfe9099: Final verdict of PTC: none GbProxy_Test-NS(SGSN[0])-NSVCI101-provIP(4024)@97156dfe9099: Final verdict of PTC: none GbProxy_Test-NS(SGSN[1])-NSVCI102-provIP(4036)@97156dfe9099: Final verdict of PTC: none GbProxy_Test-NS(SGSN[1])-NSVCI202-provIP(4041)@97156dfe9099: Final verdict of PTC: none GbProxy_Test-BSSGP(SGSN[1])-BVCI20032(4044)@97156dfe9099: Final verdict of PTC: none GbProxy_Test-BSSGP(SGSN[0])-BVCI20011(4025)@97156dfe9099: Final verdict of PTC: none GbProxy_Test-BSSGP(SGSN[0])-BVCI20031(4030)@97156dfe9099: Final verdict of PTC: none GbProxy_Test-NS(SGSN[0])-NSVCI101(4026)@97156dfe9099: Final verdict of PTC: none GbProxy_Test-BSSGP(SGSN[1])-BVCI20011(4037)@97156dfe9099: Final verdict of PTC: none GbProxy_Test-BSSGP(SGSN[0])-BVCI20022(4028)@97156dfe9099: Final verdict of PTC: none GbProxy_Test-BSSGP(SGSN[1])-BVCI20021(4039)@97156dfe9099: Final verdict of PTC: none GbProxy_Test-NS(SGSN[0])-NSVCI201-provIP(4029)@97156dfe9099: Final verdict of PTC: none GbProxy_Test-NS(PCU[0])-NSVCI4(4056)@97156dfe9099: Final verdict of PTC: none GbProxy_Test-NS(SGSN[1])-NSVCI202(4043)@97156dfe9099: Final verdict of PTC: none GbProxy_Test-BSSGP(PCU[0])(4047)@97156dfe9099: Final verdict of PTC: none GbProxy_Test-NS(PCU[1])(4055)@97156dfe9099: Final verdict of PTC: none GbProxy_Test-BSSGP(SGSN[0])-BVCI20033(4033)@97156dfe9099: Final verdict of PTC: none GbProxy_Test-NS(SGSN[1])(4034)@97156dfe9099: Final verdict of PTC: none GbProxy_Test-NS(PCU[2])-NSVCI8(4077)@97156dfe9099: Final verdict of PTC: none GbProxy_Test-NS(PCU[0])-NSVCI3-NSVCemu3-provFR(4057)@97156dfe9099: Final verdict of PTC: none GbProxy_Test-BSSGP(PCU[1])-BVCI20022(4065)@97156dfe9099: Final verdict of PTC: none GbProxy_Test-BSSGP(SGSN[1])(4035)@97156dfe9099: Final verdict of PTC: none GbProxy_Test-BSSGP(SGSN[0])-BVCI20021(4027)@97156dfe9099: Final verdict of PTC: none GbProxy_Test-BSSGP(SGSN[0])(4023)@97156dfe9099: Final verdict of PTC: none GbProxy_Test-NS(PCU[1])-NSVCI5-NSVCemu5-provFR(4066)@97156dfe9099: Final verdict of PTC: none GbProxy_Test-BSSGP(SGSN[1])-BVCI20033(4045)@97156dfe9099: Final verdict of PTC: none GbProxy_Test-NS(PCU[0])(4046)@97156dfe9099: Final verdict of PTC: none GbProxy_Test-NS(SGSN[0])-NSVCI201(4031)@97156dfe9099: Final verdict of PTC: none GbProxy_Test-NS(PCU[1])-NSVCI6(4067)@97156dfe9099: Final verdict of PTC: none GbProxy_Test-NS(PCU[0])-NSVCI3(4052)@97156dfe9099: Final verdict of PTC: none GbProxy_Test-BSSGP(SGSN[1])-BVCI20031(4042)@97156dfe9099: Final verdict of PTC: none GbProxy_Test-NS(PCU[2])-NSVCI7-NSVCemu7-provFR(4076)@97156dfe9099: Final verdict of PTC: none GbProxy_Test-BSSGP(PCU[2])-BVCI20032(4075)@97156dfe9099: Final verdict of PTC: none GbProxy_Test-NS(PCU[0])-NSVCI4-NSVCemu4-provFR(4060)@97156dfe9099: Final verdict of PTC: none GbProxy_Test-BSSGP(PCU[0])-BVCI20011(4049)@97156dfe9099: Final verdict of PTC: none GbProxy_Test-BSSGP(PCU[1])-BVCI20021(4063)@97156dfe9099: Final verdict of PTC: none GbProxy_Test-BSSGP(PCU[2])-BVCI20033(4078)@97156dfe9099: Final verdict of PTC: none GbProxy_Test-NS(PCU[1])-NSVCI6-NSVCemu6-provFR(4069)@97156dfe9099: Final verdict of PTC: none GbProxy_Test-BSSGP(PCU[2])-BVCI20031(4074)@97156dfe9099: Final verdict of PTC: none GbProxy_Test-NS(PCU[2])-NSVCI8-NSVCemu8-provFR(4080)@97156dfe9099: Final verdict of PTC: none GbProxy_Test-BSSGP(SGSN[1])-BVCI20022(4040)@97156dfe9099: Final verdict of PTC: none GbProxy_Test-NS(PCU[2])(4071)@97156dfe9099: Final verdict of PTC: none GbProxy_Test-NS(PCU[2])-NSVCI7(4073)@97156dfe9099: Final verdict of PTC: none GbProxy_Test-NS(PCU[0])-NSVCI2(4050)@97156dfe9099: Final verdict of PTC: none GbProxy_Test-NS(SGSN[1])-NSVCI102(4038)@97156dfe9099: Final verdict of PTC: none GbProxy_Test-BSSGP(PCU[2])(4072)@97156dfe9099: Final verdict of PTC: none GbProxy_Test-NS(PCU[0])-NSVCI4-NSVCemu4-FRemu(4064)@97156dfe9099: AF_PACKET Test port (FR): user_stop GbProxy_Test-NS(PCU[0])-NSVCI4-NSVCemu4-FRemu(4064)@97156dfe9099: Final verdict of PTC: none GbProxy_Test-NS(PCU[0])-NSVCI2-NSVCemu2-FRemu(4058)@97156dfe9099: AF_PACKET Test port (FR): user_stop GbProxy_Test-NS(PCU[0])-NSVCI2-NSVCemu2-FRemu(4058)@97156dfe9099: Final verdict of PTC: none GbProxy_Test-NS(PCU[0])-NSVCI1-NSVCemu1-FRemu(4054)@97156dfe9099: AF_PACKET Test port (FR): user_stop GbProxy_Test-NS(PCU[0])-NSVCI1-NSVCemu1-FRemu(4054)@97156dfe9099: Final verdict of PTC: none GbProxy_Test-NS(PCU[2])-NSVCI8-NSVCemu8-FRemu(4081)@97156dfe9099: AF_PACKET Test port (FR): user_stop GbProxy_Test-NS(PCU[2])-NSVCI8-NSVCemu8-FRemu(4081)@97156dfe9099: Final verdict of PTC: none GbProxy_Test-NS(PCU[2])-NSVCI7-NSVCemu7-FRemu(4079)@97156dfe9099: AF_PACKET Test port (FR): user_stop GbProxy_Test-NS(PCU[2])-NSVCI7-NSVCemu7-FRemu(4079)@97156dfe9099: Final verdict of PTC: none GbProxy_Test-NS(PCU[0])-NSVCI3-NSVCemu3-FRemu(4061)@97156dfe9099: AF_PACKET Test port (FR): user_stop GbProxy_Test-NS(PCU[0])-NSVCI3-NSVCemu3-FRemu(4061)@97156dfe9099: Final verdict of PTC: none GbProxy_Test-NS(PCU[1])-NSVCI6-NSVCemu6-FRemu(4070)@97156dfe9099: AF_PACKET Test port (FR): user_stop GbProxy_Test-NS(PCU[1])-NSVCI6-NSVCemu6-FRemu(4070)@97156dfe9099: Final verdict of PTC: none GbProxy_Test-NS(PCU[0])-NSVCI1(4048)@97156dfe9099: Final verdict of PTC: none GbProxy_Test-NS(PCU[1])-NSVCI5-NSVCemu5-FRemu(4068)@97156dfe9099: AF_PACKET Test port (FR): user_stop GbProxy_Test-NS(PCU[1])-NSVCI5-NSVCemu5-FRemu(4068)@97156dfe9099: Final verdict of PTC: none GbProxy_Test-BSSGP(PCU[1])(4059)@97156dfe9099: Final verdict of PTC: none GbProxy_Test-NS(PCU[0])-NSVCI2-NSVCemu2-provFR(4053)@97156dfe9099: Final verdict of PTC: none GbProxy_Test-NS(PCU[0])-NSVCI1-NSVCemu1-provFR(4051)@97156dfe9099: Final verdict of PTC: none GbProxy_Test-NS(PCU[1])-NSVCI5(4062)@97156dfe9099: Final verdict of PTC: none MTC@97156dfe9099: Setting final verdict of the test case. MTC@97156dfe9099: Local verdict of MTC: pass MTC@97156dfe9099: Local verdict of PTC IPA-CTRL-CLI-IPA(4021): none (pass -> pass) MTC@97156dfe9099: Local verdict of PTC GbProxy_Test-NS(SGSN[0])(4022): none (pass -> pass) MTC@97156dfe9099: Local verdict of PTC GbProxy_Test-BSSGP(SGSN[0])(4023): none (pass -> pass) MTC@97156dfe9099: Local verdict of PTC GbProxy_Test-NS(SGSN[0])-NSVCI101-provIP(4024): none (pass -> pass) MTC@97156dfe9099: Local verdict of PTC GbProxy_Test-BSSGP(SGSN[0])-BVCI20011(4025): none (pass -> pass) MTC@97156dfe9099: Local verdict of PTC GbProxy_Test-NS(SGSN[0])-NSVCI101(4026): none (pass -> pass) MTC@97156dfe9099: Local verdict of PTC GbProxy_Test-BSSGP(SGSN[0])-BVCI20021(4027): none (pass -> pass) MTC@97156dfe9099: Local verdict of PTC GbProxy_Test-BSSGP(SGSN[0])-BVCI20022(4028): none (pass -> pass) MTC@97156dfe9099: Local verdict of PTC GbProxy_Test-NS(SGSN[0])-NSVCI201-provIP(4029): none (pass -> pass) MTC@97156dfe9099: Local verdict of PTC GbProxy_Test-BSSGP(SGSN[0])-BVCI20031(4030): none (pass -> pass)

1 1

Build failed in Jenkins: master-osmo-pcu » master,0,raspbian,none,False #8393
by jenkins＠lists.osmocom.org 16 Sep '26

16 Sep '26

See <https://jenkins.osmocom.org/jenkins/job/master-osmo-pcu/FIRMWARE_VERSION=ma…> Changes: ------------------------------------------ Started by upstream project "master-osmo-pcu" build number 8393 originally caused by: Started by timer Running as SYSTEM Building remotely on rpi4-raspbian12build-ansible-3 (raspbian) in workspace /home/osmocom-build/jenkins/workspace/master-osmo-pcu/FIRMWARE_VERSION/master/WITH_MANUALS/0/label/raspbian/with_dsp/none/with_vty/False [ssh-agent] Looking for ssh-agent implementation... $ ssh-agent SSH_AUTH_SOCK=/tmp/ssh-NbjGZifslefy/agent.1034746 SSH_AGENT_PID=1034750 [ssh-agent] Started. Running ssh-add (command line suppressed) [ssh-agent] Using credentials docs (OS#5798) The recommended git tool is: NONE No credentials specified Cloning the remote Git repository Cloning repository https://gerrit.osmocom.org/osmo-pcu > git init /home/osmocom-build/jenkins/workspace/master-osmo-pcu/FIRMWARE_VERSION/master/WITH_MANUALS/0/label/raspbian/with_dsp/none/with_vty/False # timeout=10 Fetching upstream changes from https://gerrit.osmocom.org/osmo-pcu > git --version # timeout=10 > git --version # 'git version 2.47.3' > git fetch --tags --force --progress -- https://gerrit.osmocom.org/osmo-pcu +refs/heads/*:refs/remotes/origin/* # timeout=10 > git config remote.origin.url https://gerrit.osmocom.org/osmo-pcu # timeout=10 > git config --add remote.origin.fetch +refs/heads/*:refs/remotes/origin/* # timeout=10 Avoid second fetch Checking out Revision 379d74689e1a93b20be554a76f8db4db37801980 (origin/master) > git config core.sparsecheckout # timeout=10 > git checkout -f 379d74689e1a93b20be554a76f8db4db37801980 # timeout=10 Commit message: "llc: fix misaligned MetaInfo access in msgb headroom" > git rev-list --no-walk 379d74689e1a93b20be554a76f8db4db37801980 # timeout=10 [False] $ /bin/sh -xe /tmp/jenkins17193394244198597469.sh + DOCKER_IMG=osmocom-build/debian-trixie-build + arch + DOCKER_IMG=osmocom-build/debian-trixie-build-arm + SETARCH_CMD=setarch linux32 + CCACHE_DIR=/home/osmocom-build/ccache/master-builds + mkdir -p /home/osmocom-build/ccache/master-builds + chown osmocom-build:osmocom-build /home/osmocom-build/ccache/master-builds + readlink -f /tmp/ssh-NbjGZifslefy/agent.1034746 + docker run --rm=true --cap-add SYS_PTRACE --security-opt seccomp=/home/osmocom-build/osmo-ci/_docker_playground/seccomp_profile.json --ulimit fsize=1000000000 -e ASCIIDOC_WARNINGS_CHECK=1 -e CFLAGS=-g -e HOME=/build -e IS_MASTER_BUILD=1 -e JOB_NAME=master-osmo-pcu/FIRMWARE_VERSION=master,WITH_MANUALS=0,label=raspbian,with_dsp=none,with_vty=False -e MAKE=make -e OSMOPY_DEBUG_TCP_SOCKETS=1 -e OSMO_GSM_MANUALS_DIR=/opt/osmo-gsm-manuals -e PARALLEL_MAKE=-j 4 -e PUBLISH=1 -e SSH_AUTH_SOCK=/ssh-agent -e WITH_MANUALS=1 -w /build -i -u build -v /tmp/ssh-NbjGZifslefy/agent.1034746:/ssh-agent -v /home/osmocom-build/osmo-ci:/osmo-ci:ro -v /home/osmocom-build/jenkins/workspace/master-osmo-pcu/FIRMWARE_VERSION/master/WITH_MANUALS/0/label/raspbian/with_dsp/none/with_vty/False:/build -v /home/osmocom-build/.ssh:/home/build/.ssh:ro -e CCACHE_DIR=/ccache -e PATH=/usr/lib/ccache:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin -v /home/osmocom-build/ccache/master-builds:/ccache -e FIRMWARE_VERSION=master -e with_vty=False -e with_dsp=none -e WITH_MANUALS=0 osmocom-build/debian-trixie-build-arm /usr/bin/timeout 30m /osmo-ci/scripts/wrapper_core_bt_on_error.sh setarch linux32 /build/contrib/jenkins.sh + [ -z make ] + base=/build + deps=/build/deps + inst=/build/deps/install + export deps inst + osmo-clean-workspace.sh + chmod -R +w . + git checkout -f HEAD + git clean -dxf + [ -d /build/deps ] + [ -d layer1-headers ] + mkdir /build/deps + PCU_CONFIG=Ericsson E1 CCU support enabled, sanitizer enabled VTY tests disabled + [ none = sysmo ] + [ none = lc15 ] + [ none = oc2g ] + [ -z none -o none = none ] + echo Ericsson E1 CCU support enabled, sanitizer enabled + PCU_CONFIG= --enable-werror --enable-sanitize --enable-er-e1-ccu + [ False = True ] + [ -z False -o False = False ] + echo VTY tests disabled + find . -name *.[hc] + verify_value_string_arrays_are_terminated.py ./include/osmocom/pcu/pcuif_proto.h ./src/pcuif_sock.c ./src/csn1_dec.c ./src/gsm_rlcmac.c ./src/tbf_dl.h ./src/pcu_vty.h ./src/decoding.h ./src/csn1_enc.c ./src/encoding.h ./src/pcu_vty.c ./src/tbf.h ./src/tbf_ul_fsm.c ./src/pdch_ul_controller.c ./src/gprs_bssgp_pcu.h ./src/csn1.c ./src/mslot_class.c ./src/mslot_class.h ./src/tbf_ul_ass_fsm.c ./src/sba.c ./src/gsm_rlcmac.h ./src/llc.h ./src/csn1.h ./src/gprs_codel.c ./src/pcu_utils.h ./src/rlc.h ./src/gprs_debug.h ./src/tbf_ul_ass_fsm.h ./src/rlc_window.h ./src/tbf_ul.h ./src/gprs_bssgp_pcu.c ./src/osmo-bts-sysmo/sysmo_l1_if.c ./src/osmo-bts-sysmo/femtobts.c ./src/osmo-bts-sysmo/sysmo_l1_fwd.c ./src/osmo-bts-sysmo/sysmo_l1_if.h ./src/osmo-bts-sysmo/femtobts.h ./src/osmo-bts-sysmo/sysmo_l1_hw.c ./src/pcu_l1_if.h ./src/gprs_ms.c ./src/neigh_cache.h ./src/bts_pch_timer.c ./src/gprs_ms.h ./src/nacc_fsm.c ./src/alloc_algo.h ./src/gprs_bssgp_rim.c ./src/gprs_codel.h ./src/rlc_window_ul.h ./src/egprs_rlc_compression.h ./src/pcu_l1_if_phy.h ./src/wireshark_compat.h ./src/tbf_dl_ass_fsm.h ./src/rlc_window_dl.h ./src/tbf_ul_ack_fsm.h ./src/gprs_debug.c ./src/coding_scheme.c ./src/cxx_linuxlist.h ./src/tbf_fsm.c ./src/gprs_rlcmac.c ./src/pcu_vty_functions.h ./src/neigh_cache.c ./src/tbf_fsm.h ./src/llc.c ./src/ericsson-rbs/er_ccu_if.h ./src/ericsson-rbs/er_ccu_if.c ./src/ericsson-rbs/er_ccu_descr.h ./src/ericsson-rbs/er_ccu_l1_if.c ./src/gprs_bssgp_rim.h ./src/coding_scheme.h ./src/osmo-bts-oc2g/oc2g_l1_if.c ./src/osmo-bts-oc2g/oc2g_l1_if.h ./src/osmo-bts-oc2g/oc2g_l1_hw.c ./src/osmo-bts-oc2g/oc2gbts.c ./src/osmo-bts-oc2g/oc2gbts.h ./src/gprs_pcu.h ./src/tbf_dl_fsm.c ./src/tbf_ul_ack_fsm.c ./src/tbf_dl_ass_fsm.c ./src/gprs_rlcmac.h ./src/bts.h ./src/pdch.h ./src/nacc_fsm.h ./src/sba.h ./src/bts_pch_timer.h ./src/pdch_ul_controller.h ./src/gprs_pcu.c ./src/osmo-bts-litecell15/lc15bts.c ./src/osmo-bts-litecell15/lc15bts.h ./src/osmo-bts-litecell15/lc15_l1_if.h ./src/osmo-bts-litecell15/lc15_l1_hw.c ./src/osmo-bts-litecell15/lc15_l1_if.c ./tests/codel/codel_test.c ./tests/emu/openbsc_clone.c ./tests/emu/gprs_tests.h ./tests/emu/openbsc_clone.h + osmo-build-dep.sh libosmocore --disable-doxygen =============================== libosmocore =============================== + mkdir -p /build/deps + cd /build/deps + osmo-deps.sh libosmocore + project=libosmocore + branch=master + git branch -a + grep -c remotes/origin/master$ + [ x1 != x0 ] + branch=origin/master + test -d libosmocore + osmo_git_clone_url libosmocore + echo https://gerrit.osmocom.org/libosmocore + git clone https://gerrit.osmocom.org/libosmocore libosmocore Cloning into 'libosmocore'... error: RPC failed; curl 56 Recv failure: Connection reset by peer error: 31422 bytes of body are still expected fetch-pack: unexpected disconnect while reading sideband packet fatal: early EOF fatal: fetch-pack: invalid index-pack output FATAL: command execution failed hudson.remoting.ProxyException: java.util.concurrent.TimeoutException: Ping started at 1789457536326 hasn't completed by 1789457776326 at hudson.remoting.PingThread.ping(PingThread.java:135) Caused: Command Close created at at hudson.remoting.Command.<init>(Command.java:69) at hudson.remoting.Channel$CloseCommand.<init>(Channel.java:1424) at hudson.remoting.Channel.close(Channel.java:1603) at Jenkins v2.568.3//hudson.slaves.ChannelPinger$1.onDead(ChannelPinger.java:206) at hudson.remoting.PingThread.ping(PingThread.java:134) at hudson.remoting.PingThread.run(PingThread.java:87) Caused: hudson.remoting.Channel$OrderlyShutdown at hudson.remoting.Channel$CloseCommand.execute(Channel.java:1431) at hudson.remoting.Channel$1.handle(Channel.java:664) at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:86) Caused: java.io.IOException: Backing channel 'rpi4-raspbian12build-ansible-3' is disconnected. at hudson.remoting.RemoteInvocationHandler.channelOrFail(RemoteInvocationHandler.java:227) at hudson.remoting.RemoteInvocationHandler.invoke(RemoteInvocationHandler.java:306) at jdk.proxy2/jdk.proxy2.$Proxy129.isAlive(Unknown Source) at hudson.Launcher$RemoteLauncher$ProcImpl.isAlive(Launcher.java:1227) at hudson.Launcher$RemoteLauncher$ProcImpl.join(Launcher.java:1219) at hudson.tasks.CommandInterpreter.join(CommandInterpreter.java:195) at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:145) at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:92) at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20) at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:817) at hudson.model.Build$BuildExecution.build(Build.java:199) at hudson.model.Build$BuildExecution.doRun(Build.java:164) at hudson.model.AbstractBuild$AbstractBuildExecution.run(AbstractBuild.java:526) at hudson.model.Run.execute(Run.java:1849) at PluginClassLoader for matrix-project//hudson.matrix.MatrixRun.run(MatrixRun.java:153) at hudson.model.ResourceController.execute(ResourceController.java:97) at hudson.model.Executor.run(Executor.java:456) FATAL: Unable to delete script file /tmp/jenkins17193394244198597469.sh hudson.remoting.ProxyException: java.util.concurrent.TimeoutException: Ping started at 1789457536326 hasn't completed by 1789457776326 at hudson.remoting.PingThread.ping(PingThread.java:135) Caused: Command Close created at at hudson.remoting.Command.<init>(Command.java:69) at hudson.remoting.Channel$CloseCommand.<init>(Channel.java:1424) at hudson.remoting.Channel.close(Channel.java:1603) at Jenkins v2.568.3//hudson.slaves.ChannelPinger$1.onDead(ChannelPinger.java:206) at hudson.remoting.PingThread.ping(PingThread.java:134) at hudson.remoting.PingThread.run(PingThread.java:87) Caused: hudson.remoting.Channel$OrderlyShutdown at hudson.remoting.Channel$CloseCommand.execute(Channel.java:1431) at hudson.remoting.Channel$1.handle(Channel.java:664) at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:86) Caused: hudson.remoting.ChannelClosedException: Channel "hudson.remoting.Channel@5b1d8197:rpi4-raspbian12build-ansible-3": Remote call on rpi4-raspbian12build-ansible-3 failed. The channel is closing down or has closed down at hudson.remoting.Channel.call(Channel.java:1101) at hudson.FilePath.act(FilePath.java:1220) at hudson.FilePath.act(FilePath.java:1209) at hudson.FilePath.delete(FilePath.java:1756) at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:163) at hudson.tasks.CommandInterpreter.perform(CommandInterpreter.java:92) at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20) at hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:817) at hudson.model.Build$BuildExecution.build(Build.java:199) at hudson.model.Build$BuildExecution.doRun(Build.java:164) at hudson.model.AbstractBuild$AbstractBuildExecution.run(AbstractBuild.java:526) at hudson.model.Run.execute(Run.java:1849) at PluginClassLoader for matrix-project//hudson.matrix.MatrixRun.run(MatrixRun.java:153) at hudson.model.ResourceController.execute(ResourceController.java:97) at hudson.model.Executor.run(Executor.java:456) Build step 'Execute shell' marked build as failure ERROR: Unable to tear down: Channel "hudson.remoting.Channel@5b1d8197:rpi4-raspbian12build-ansible-3": Remote call on rpi4-raspbian12build-ansible-3 failed. The channel is closing down or has closed down hudson.remoting.ProxyException: java.util.concurrent.TimeoutException: Ping started at 1789457536326 hasn't completed by 1789457776326 at hudson.remoting.PingThread.ping(PingThread.java:135) Caused: Command Close created at at hudson.remoting.Command.<init>(Command.java:69) at hudson.remoting.Channel$CloseCommand.<init>(Channel.java:1424) at hudson.remoting.Channel.close(Channel.java:1603) at Jenkins v2.568.3//hudson.slaves.ChannelPinger$1.onDead(ChannelPinger.java:206) at hudson.remoting.PingThread.ping(PingThread.java:134) at hudson.remoting.PingThread.run(PingThread.java:87) Caused: hudson.remoting.Channel$OrderlyShutdown at hudson.remoting.Channel$CloseCommand.execute(Channel.java:1431) at hudson.remoting.Channel$1.handle(Channel.java:664) at hudson.remoting.SynchronousCommandTransport$ReaderThread.run(SynchronousCommandTransport.java:86) Caused: hudson.remoting.ChannelClosedException: Channel "hudson.remoting.Channel@5b1d8197:rpi4-raspbian12build-ansible-3": Remote call on rpi4-raspbian12build-ansible-3 failed. The channel is closing down or has closed down at hudson.remoting.Channel.call(Channel.java:1101) at hudson.Launcher$RemoteLauncher.launch(Launcher.java:1136) at hudson.Launcher$ProcStarter.start(Launcher.java:507) at PluginClassLoader for ssh-agent//com.cloudbees.jenkins.plugins.sshagent.exec.ExecRemoteAgent.executeCommand(ExecRemoteAgent.java:161) at PluginClassLoader for ssh-agent//com.cloudbees.jenkins.plugins.sshagent.exec.ExecRemoteAgent.stop(ExecRemoteAgent.java:253) at PluginClassLoader for ssh-agent//com.cloudbees.jenkins.plugins.sshagent.SSHAgentBuildWrapper$SSHAgentEnvironment.tearDown(SSHAgentBuildWrapper.java:411) at hudson.model.AbstractBuild$AbstractBuildExecution.tearDownBuildEnvironments(AbstractBuild.java:566) at hudson.model.AbstractBuild$AbstractBuildExecution.run(AbstractBuild.java:530) at hudson.model.Run.execute(Run.java:1849) at PluginClassLoader for matrix-project//hudson.matrix.MatrixRun.run(MatrixRun.java:153) at hudson.model.ResourceController.execute(ResourceController.java:97) at hudson.model.Executor.run(Executor.java:456) ERROR: Step ‘Archive the artifacts’ failed: no workspace for master-osmo-pcu/FIRMWARE_VERSION=master,WITH_MANUALS=0,label=raspbian,with_dsp=none,with_vty=False #8393

1 1

Build failed in Jenkins: ttcn3-5gc-test-ogs-latest #307
by jenkins＠lists.osmocom.org 15 Sep '26

15 Sep '26

See <https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs-latest/307/displ…> Changes: ------------------------------------------ [...truncated 163.46 MiB...] [32m09/15 13:45:59.078[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED[0] (../lib/sbi/client.c:755) [32m09/15 13:45:59.078[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): OGS_EVENT_NAME_SBI_CLIENT (../src/amf/amf-sm.c:83) [32m09/15 13:45:59.078[0m: [[33msbi[0m] [1;37mDEBUG[0m: ogs_sbi_nf_state_registered(): OGS_EVENT_NAME_SBI_CLIENT (../lib/sbi/nf-sm.c:358) Waiting for packet dumper to start... 0 MTC@70c03bd1b648: External command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs-latest/ws/src/os…> C5G_Tests.TC_ue_service_request_cm_idle_inact_sess' was executed successfully (exit status: 0). MTC@70c03bd1b648: Test case TC_ue_service_request_cm_idle_inact_sess started. GTP1U_EM(578)@70c03bd1b648: Warning: sizes of 'struct sctp_event_subscribe': compile-time 14, kernel: 14 GTP1U_EM(578)@70c03bd1b648: setverdict(fail): none -> fail reason: "Could not connect UECUPS socket, check your configuration", new component reason: "Could not connect UECUPS socket, check your configuration" GTP1U_EM(578)@70c03bd1b648: Dynamic test case error: testcase.stop GTP1U_EM(578)@70c03bd1b648: setverdict(error): fail -> error GTP1U_EM(578)@70c03bd1b648: Final verdict of PTC: error TC_ue_service_request_cm_idle_inact_sess-NGAP0(579)@70c03bd1b648: Warning: sizes of 'struct sctp_event_subscribe': compile-time 14, kernel: 14 [32m09/15 13:46:00.125[0m: [[33mamf[0m] [1;32mINFO[0m: gNB-N2 accepted[127.0.0.202]:50000 in ng-path module (../src/amf/ngap-sctp.c:113) [32m09/15 13:46:00.125[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): AMF_EVENT_NGAP_LO_ACCEPT (../src/amf/amf-sm.c:83) [32m09/15 13:46:00.125[0m: [[33mamf[0m] [1;32mINFO[0m: gNB-N2 accepted[127.0.0.202] in master_sm module (../src/amf/amf-sm.c:818) [32m09/15 13:46:00.132[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_initial(): ENTRY (../src/amf/ngap-sm.c:28) [32m09/15 13:46:00.132[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_operational(): ENTRY (../src/amf/ngap-sm.c:55) [32m09/15 13:46:00.132[0m: [[33mamf[0m] [1;32mINFO[0m: [Added] Number of gNBs is now 1 (../src/amf/context.c:1277) [32m09/15 13:46:00.133[0m: [[33mamf[0m] [1;37mDEBUG[0m: SCTP_ASSOC_CHANGE:[T:32769, F:0x0, S:0, I/O:64/30] (../src/amf/ngap-sctp.c:152) [32m09/15 13:46:00.133[0m: [[33mamf[0m] [1;37mDEBUG[0m: SCTP_COMM_UP (../src/amf/ngap-sctp.c:161) [32m09/15 13:46:00.133[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): AMF_EVENT_NGAP_LO_SCTP_COMM_UP (../src/amf/amf-sm.c:83) [32m09/15 13:46:00.133[0m: [[33mamf[0m] [1;32mINFO[0m: gNB-N2[127.0.0.202] max_num_of_ostreams : 30 (../src/amf/amf-sm.c:865) [32m09/15 13:46:00.138[0m: [[33mamf[0m] [1;32mINFO[0m: gNB-N2 accepted[127.0.0.202]:50001 in ng-path module (../src/amf/ngap-sctp.c:113) [32m09/15 13:46:00.138[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): AMF_EVENT_NGAP_LO_ACCEPT (../src/amf/amf-sm.c:83) [32m09/15 13:46:00.138[0m: [[33mamf[0m] [1;32mINFO[0m: gNB-N2 accepted[127.0.0.202] in master_sm module (../src/amf/amf-sm.c:818) TC_ue_service_request_cm_idle_inact_sess-NGAP1(580)@70c03bd1b648: Warning: sizes of 'struct sctp_event_subscribe': compile-time 14, kernel: 14 [32m09/15 13:46:00.142[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_initial(): ENTRY (../src/amf/ngap-sm.c:28) [32m09/15 13:46:00.142[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_operational(): ENTRY (../src/amf/ngap-sm.c:55) [32m09/15 13:46:00.142[0m: [[33mamf[0m] [1;32mINFO[0m: [Added] Number of gNBs is now 2 (../src/amf/context.c:1277) [32m09/15 13:46:00.142[0m: [[33mamf[0m] [1;37mDEBUG[0m: SCTP_ASSOC_CHANGE:[T:32769, F:0x0, S:0, I/O:64/30] (../src/amf/ngap-sctp.c:152) [32m09/15 13:46:00.142[0m: [[33mamf[0m] [1;37mDEBUG[0m: SCTP_COMM_UP (../src/amf/ngap-sctp.c:161) [32m09/15 13:46:00.142[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): AMF_EVENT_NGAP_LO_SCTP_COMM_UP (../src/amf/amf-sm.c:83) [32m09/15 13:46:00.142[0m: [[33mamf[0m] [1;32mINFO[0m: gNB-N2[127.0.0.202] max_num_of_ostreams : 30 (../src/amf/amf-sm.c:865) [32m09/15 13:46:00.145[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): AMF_EVENT_NGAP_MESSAGE (../src/amf/amf-sm.c:83) [32m09/15 13:46:00.145[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_operational(): AMF_EVENT_NGAP_MESSAGE (../src/amf/ngap-sm.c:55) [32m09/15 13:46:00.145[0m: [[33mamf[0m] [1;37mDEBUG[0m: NGSetupRequest (../src/amf/ngap-handler.c:288) [32m09/15 13:46:00.145[0m: [[33mamf[0m] [1;37mDEBUG[0m: IP[127.0.0.202] GNB_ID[0x0] GNB_ID_LENGTH[22] (../src/amf/ngap-handler.c:386) [32m09/15 13:46:00.145[0m: [[33mamf[0m] [1;37mDEBUG[0m: PLMN_ID[MCC:999 MNC:70] (../src/amf/ngap-handler.c:391) [32m09/15 13:46:00.145[0m: [[33mamf[0m] [1;37mDEBUG[0m: PagingDRX[0] (../src/amf/ngap-handler.c:395) [32m09/15 13:46:00.145[0m: [[33mamf[0m] [1;37mDEBUG[0m: TAC[1] (../src/amf/ngap-handler.c:439) [32m09/15 13:46:00.145[0m: [[33mamf[0m] [1;37mDEBUG[0m: PLMN_ID[MCC:999 MNC:70] (../src/amf/ngap-handler.c:501) [32m09/15 13:46:00.145[0m: [[33mamf[0m] [1;37mDEBUG[0m: S_NSSAI[SST:1 SD:0xffffff] (../src/amf/ngap-handler.c:562) [32m09/15 13:46:00.145[0m: [[33mamf[0m] [1;37mDEBUG[0m: TAC[1] (../src/amf/ngap-handler.c:72) [32m09/15 13:46:00.145[0m: [[33mamf[0m] [1;37mDEBUG[0m: PLMN_ID[MCC:999 MNC:70] (../src/amf/ngap-handler.c:73) [32m09/15 13:46:00.145[0m: [[33mamf[0m] [1;37mDEBUG[0m: SERVED_TAI_INDEX[0] (../src/amf/ngap-handler.c:78) [32m09/15 13:46:00.145[0m: [[33mamf[0m] [1;37mDEBUG[0m: PLMN_ID[MCC:999 MNC:70] (../src/amf/ngap-handler.c:100) [32m09/15 13:46:00.145[0m: [[33mamf[0m] [1;37mDEBUG[0m: S_NSSAI[SST:1 SD:0xffffff] (../src/amf/ngap-handler.c:105) [32m09/15 13:46:00.145[0m: [[33mamf[0m] [1;37mDEBUG[0m: NG-Setup response (../src/amf/ngap-path.c:356) [32m09/15 13:46:00.145[0m: [[33mamf[0m] [1;37mDEBUG[0m: NGSetupResponse (../src/amf/ngap-build.c:91) [32m09/15 13:46:00.145[0m: [[33mamf[0m] [1;37mDEBUG[0m: IP[127.0.0.202] RAN_ID[0] (../src/amf/ngap-path.c:64) MTC@70c03bd1b648: setverdict(pass): none -> pass MTC@70c03bd1b648: Dynamic test case error: Error message was received from MC: The connect operation refers to test component with component reference 578, which has already terminated. MTC@70c03bd1b648: setverdict(error): pass -> error TC_ue_service_request_cm_idle_inact_sess0(581)@70c03bd1b648: Final verdict of PTC: none [32m09/15 13:46:00.152[0m: [[33mamf[0m] [1;37mDEBUG[0m: SCTP_SHUTDOWN_EVENT:[T:32773, F:0x0, L:12] (../src/amf/ngap-sctp.c:193) [32m09/15 13:46:00.152[0m: [[33mamf[0m] [1;37mDEBUG[0m: SCTP_SHUTDOWN_EVENT:[T:32773, F:0x0, L:12] (../src/amf/ngap-sctp.c:193) [32m09/15 13:46:00.152[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): AMF_EVENT_NGAP_LO_CONNREFUSED (../src/amf/amf-sm.c:83) [32m09/15 13:46:00.152[0m: [[33mamf[0m] [1;32mINFO[0m: gNB-N2[127.0.0.202] connection refused!!! (../src/amf/amf-sm.c:878) [32m09/15 13:46:00.152[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_operational(): EXIT (../src/amf/ngap-sm.c:55) [32m09/15 13:46:00.152[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_final(): EXIT (../src/amf/ngap-sm.c:37) [32m09/15 13:46:00.152[0m: [[33mamf[0m] [1;32mINFO[0m: [Removed] Number of gNBs is now 1 (../src/amf/context.c:1305) [32m09/15 13:46:00.152[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): AMF_EVENT_NGAP_LO_CONNREFUSED (../src/amf/amf-sm.c:83) [32m09/15 13:46:00.152[0m: [[33mamf[0m] [1;32mINFO[0m: gNB-N2[127.0.0.202] connection refused!!! (../src/amf/amf-sm.c:878) [32m09/15 13:46:00.152[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_operational(): EXIT (../src/amf/ngap-sm.c:55) [32m09/15 13:46:00.152[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_final(): EXIT (../src/amf/ngap-sm.c:37) [32m09/15 13:46:00.152[0m: [[33mamf[0m] [1;32mINFO[0m: [Removed] Number of gNBs is now 0 (../src/amf/context.c:1305) TC_ue_service_request_cm_idle_inact_sess-NGAP1(580)@70c03bd1b648: Final verdict of PTC: none TC_ue_service_request_cm_idle_inact_sess-NGAP0(579)@70c03bd1b648: Final verdict of PTC: none MTC@70c03bd1b648: Setting final verdict of the test case. MTC@70c03bd1b648: Local verdict of MTC: error MTC@70c03bd1b648: Local verdict of PTC GTP1U_EM(578): error (error -> error) MTC@70c03bd1b648: Local verdict of PTC TC_ue_service_request_cm_idle_inact_sess-NGAP0(579): none (error -> error) MTC@70c03bd1b648: Local verdict of PTC TC_ue_service_request_cm_idle_inact_sess-NGAP1(580): none (error -> error) MTC@70c03bd1b648: Local verdict of PTC TC_ue_service_request_cm_idle_inact_sess0(581): none (error -> error) MTC@70c03bd1b648: Test case TC_ue_service_request_cm_idle_inact_sess finished. Verdict: error MTC@70c03bd1b648: Starting external command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs-latest/ws/src/os…> C5G_Tests.TC_ue_service_request_cm_idle_inact_sess error'. (13:46:00) load average: 3.61, 3.82, 2.89 [1;31m------ C5G_Tests.TC_ue_service_request_cm_idle_inact_sess error ------[0m Waiting for packet dumper to finish... 0 (prev_count=-1, count=456) [32m09/15 13:46:00.812[0m: [[33mdiam[0m] [1;37mDEBUG[0m: pid:ConnTo:pcrf.localdomain in md_hook_cb_tree@dbg_msg_dumps.c:150: CONNECT FAILED to pcrf.localdomain: All connection attempts failed, will retry later ((null):0) Waiting for packet dumper to finish... 1 (prev_count=456, count=5172) [32m09/15 13:46:01.385[0m: [[33msmf[0m] [1;37mDEBUG[0m: smf_state_operational(): SMF_EVT_N4_TIMER (../src/smf/smf-sm.c:94) [32m09/15 13:46:01.385[0m: [[33msmf[0m] [1;37mDEBUG[0m: smf_pfcp_state_associated(): SMF_EVT_N4_TIMER (../src/smf/pfcp-sm.c:182) [32m09/15 13:46:01.385[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [1571] LOCAL Create peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:130) [32m09/15 13:46:01.385[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: Heartbeat Request (../lib/pfcp/build.c:28) [32m09/15 13:46:01.385[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [1571] LOCAL UPD TX-1 peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:229) [32m09/15 13:46:01.385[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [1571] LOCAL Commit peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:498) [32m09/15 13:46:01.385[0m: [[33mupf[0m] [1;37mDEBUG[0m: ogs_pfcp_extract_node_id() type [1] pfcp_status [1] node_id [NULL] from [127.0.0.4]:8805 (../src/upf/pfcp-path.c:101) [32m09/15 13:46:01.385[0m: [[33mupf[0m] [1;37mDEBUG[0m: Found PFCP-Node: addr_list [127.0.0.4]:8805 (../src/upf/pfcp-path.c:155) [32m09/15 13:46:01.385[0m: [[33mupf[0m] [1;37mDEBUG[0m: Merged PFCP-Node: addr_list [127.0.0.4]:8805 (../src/upf/pfcp-path.c:161) [32m09/15 13:46:01.385[0m: [[33mupf[0m] [1;37mDEBUG[0m: upf_state_operational(): UPF_EVT_N4_MESSAGE (../src/upf/upf-sm.c:51) [32m09/15 13:46:01.385[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [1571] Cannot find new type 1 from PFCP peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:765) [32m09/15 13:46:01.385[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [1571] REMOTE Create peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:194) [32m09/15 13:46:01.385[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [1571] REMOTE Receive peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:771) [32m09/15 13:46:01.385[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [1571] REMOTE UPD RX-1 peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:327) [32m09/15 13:46:01.385[0m: [[33mupf[0m] [1;37mDEBUG[0m: upf_pfcp_state_associated(): UPF_EVT_N4_MESSAGE (../src/upf/pfcp-sm.c:161) [32m09/15 13:46:01.385[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: Heartbeat Response (../lib/pfcp/build.c:56) [32m09/15 13:46:01.385[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [1571] REMOTE UPD TX-2 peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:229) [32m09/15 13:46:01.385[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [1571] REMOTE Commit peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:498) [32m09/15 13:46:01.385[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [1571] REMOTE Delete peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:829) [32m09/15 13:46:01.385[0m: [[33mupf[0m] [1;37mDEBUG[0m: upf_state_operational(): UPF_EVT_N4_TIMER (../src/upf/upf-sm.c:51) [32m09/15 13:46:01.385[0m: [[33mupf[0m] [1;37mDEBUG[0m: upf_pfcp_state_associated(): UPF_EVT_N4_TIMER (../src/upf/pfcp-sm.c:161) [32m09/15 13:46:01.385[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [126] LOCAL Create peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:130) [32m09/15 13:46:01.385[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: Heartbeat Request (../lib/pfcp/build.c:28) [32m09/15 13:46:01.385[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [126] LOCAL UPD TX-1 peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:229) [32m09/15 13:46:01.385[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [126] LOCAL Commit peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:498) [32m09/15 13:46:01.385[0m: [[33msmf[0m] [1;37mDEBUG[0m: ogs_pfcp_extract_node_id() type [2] pfcp_status [1] node_id [NULL] from [127.0.0.7]:8805 (../src/smf/pfcp-path.c:138) [32m09/15 13:46:01.385[0m: [[33msmf[0m] [1;37mDEBUG[0m: Found PFCP-Node: addr_list [127.0.0.7]:8805 (../src/smf/pfcp-path.c:192) [32m09/15 13:46:01.385[0m: [[33msmf[0m] [1;37mDEBUG[0m: Merged PFCP-Node: addr_list [127.0.0.7]:8805 (../src/smf/pfcp-path.c:198) [32m09/15 13:46:01.385[0m: [[33msmf[0m] [1;37mDEBUG[0m: smf_state_operational(): SMF_EVT_N4_MESSAGE (../src/smf/smf-sm.c:94) [32m09/15 13:46:01.385[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [1571] LOCAL Find peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:756) [32m09/15 13:46:01.385[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [1571] LOCAL Receive peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:771) [32m09/15 13:46:01.385[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [1571] LOCAL UPD RX-2 peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:327) [32m09/15 13:46:01.385[0m: [[33msmf[0m] [1;37mDEBUG[0m: smf_pfcp_state_associated(): SMF_EVT_N4_MESSAGE (../src/smf/pfcp-sm.c:182) [32m09/15 13:46:01.385[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [1571] LOCAL Commit peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:498) [32m09/15 13:46:01.385[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [1571] LOCAL Delete peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:829) [32m09/15 13:46:01.385[0m: [[33msmf[0m] [1;37mDEBUG[0m: ogs_pfcp_extract_node_id() type [1] pfcp_status [1] node_id [NULL] from [127.0.0.7]:8805 (../src/smf/pfcp-path.c:138) [32m09/15 13:46:01.385[0m: [[33msmf[0m] [1;37mDEBUG[0m: Found PFCP-Node: addr_list [127.0.0.7]:8805 (../src/smf/pfcp-path.c:192) [32m09/15 13:46:01.385[0m: [[33msmf[0m] [1;37mDEBUG[0m: Merged PFCP-Node: addr_list [127.0.0.7]:8805 (../src/smf/pfcp-path.c:198) [32m09/15 13:46:01.385[0m: [[33msmf[0m] [1;37mDEBUG[0m: smf_state_operational(): SMF_EVT_N4_MESSAGE (../src/smf/smf-sm.c:94) [32m09/15 13:46:01.385[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [126] Cannot find new type 1 from PFCP peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:765) [32m09/15 13:46:01.385[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [126] REMOTE Create peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:194) [32m09/15 13:46:01.385[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [126] REMOTE Receive peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:771) [32m09/15 13:46:01.385[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [126] REMOTE UPD RX-1 peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:327) [32m09/15 13:46:01.385[0m: [[33msmf[0m] [1;37mDEBUG[0m: smf_pfcp_state_associated(): SMF_EVT_N4_MESSAGE (../src/smf/pfcp-sm.c:182) [32m09/15 13:46:01.385[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: Heartbeat Response (../lib/pfcp/build.c:56) [32m09/15 13:46:01.385[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [126] REMOTE UPD TX-2 peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:229) [32m09/15 13:46:01.385[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [126] REMOTE Commit peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:498) [32m09/15 13:46:01.385[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [126] REMOTE Delete peer [127.0.0.7]:8805 (../lib/pfcp/xact.c:829) [32m09/15 13:46:01.385[0m: [[33mupf[0m] [1;37mDEBUG[0m: ogs_pfcp_extract_node_id() type [2] pfcp_status [1] node_id [NULL] from [127.0.0.4]:8805 (../src/upf/pfcp-path.c:101) [32m09/15 13:46:01.385[0m: [[33mupf[0m] [1;37mDEBUG[0m: Found PFCP-Node: addr_list [127.0.0.4]:8805 (../src/upf/pfcp-path.c:155) [32m09/15 13:46:01.385[0m: [[33mupf[0m] [1;37mDEBUG[0m: Merged PFCP-Node: addr_list [127.0.0.4]:8805 (../src/upf/pfcp-path.c:161) [32m09/15 13:46:01.385[0m: [[33mupf[0m] [1;37mDEBUG[0m: upf_state_operational(): UPF_EVT_N4_MESSAGE (../src/upf/upf-sm.c:51) [32m09/15 13:46:01.385[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [126] LOCAL Find peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:756) [32m09/15 13:46:01.385[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [126] LOCAL Receive peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:771) [32m09/15 13:46:01.385[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [126] LOCAL UPD RX-2 peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:327) [32m09/15 13:46:01.385[0m: [[33mupf[0m] [1;37mDEBUG[0m: upf_pfcp_state_associated(): UPF_EVT_N4_MESSAGE (../src/upf/pfcp-sm.c:161) [32m09/15 13:46:01.385[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [126] LOCAL Commit peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:498) [32m09/15 13:46:01.385[0m: [[33mpfcp[0m] [1;37mDEBUG[0m: [126] LOCAL Delete peer [127.0.0.4]:8805 (../lib/pfcp/xact.c:829) MTC@70c03bd1b648: External command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs-latest/ws/src/os…> C5G_Tests.TC_ue_service_request_cm_idle_inact_sess error' was executed successfully (exit status: 0). MTC@70c03bd1b648: Starting external command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs-latest/ws/src/os…> C5G_Tests.TC_ue_service_request_cm_idle_unknown_sess_active'. ------ C5G_Tests.TC_ue_service_request_cm_idle_unknown_sess_active ------ (13:46:02) load average: 3.61, 3.82, 2.89 /usr/bin/dumpcap -q -s 1520 -n -i any -w "<https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs-latest/307/artif…"> ><https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs-latest/307/artif…> 2>/tmp/cmderr & Waiting for packet dumper to start... 0 MTC@70c03bd1b648: External command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs-latest/ws/src/os…> C5G_Tests.TC_ue_service_request_cm_idle_unknown_sess_active' was executed successfully (exit status: 0). MTC@70c03bd1b648: Test case TC_ue_service_request_cm_idle_unknown_sess_active started. GTP1U_EM(582)@70c03bd1b648: Warning: sizes of 'struct sctp_event_subscribe': compile-time 14, kernel: 14 GTP1U_EM(582)@70c03bd1b648: setverdict(fail): none -> fail reason: "Could not connect UECUPS socket, check your configuration", new component reason: "Could not connect UECUPS socket, check your configuration" GTP1U_EM(582)@70c03bd1b648: Dynamic test case error: testcase.stop GTP1U_EM(582)@70c03bd1b648: setverdict(error): fail -> error GTP1U_EM(582)@70c03bd1b648: Final verdict of PTC: error TC_ue_service_request_cm_idle_unknown_sess_active-NGAP0(583)@70c03bd1b648: Warning: sizes of 'struct sctp_event_subscribe': compile-time 14, kernel: 14 [32m09/15 13:46:03.317[0m: [[33mamf[0m] [1;32mINFO[0m: gNB-N2 accepted[127.0.0.202]:50000 in ng-path module (../src/amf/ngap-sctp.c:113) [32m09/15 13:46:03.317[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): AMF_EVENT_NGAP_LO_ACCEPT (../src/amf/amf-sm.c:83) [32m09/15 13:46:03.317[0m: [[33mamf[0m] [1;32mINFO[0m: gNB-N2 accepted[127.0.0.202] in master_sm module (../src/amf/amf-sm.c:818) [32m09/15 13:46:03.324[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_initial(): ENTRY (../src/amf/ngap-sm.c:28) [32m09/15 13:46:03.324[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_operational(): ENTRY (../src/amf/ngap-sm.c:55) [32m09/15 13:46:03.324[0m: [[33mamf[0m] [1;32mINFO[0m: [Added] Number of gNBs is now 1 (../src/amf/context.c:1277) [32m09/15 13:46:03.324[0m: [[33mamf[0m] [1;37mDEBUG[0m: SCTP_ASSOC_CHANGE:[T:32769, F:0x0, S:0, I/O:64/30] (../src/amf/ngap-sctp.c:152) [32m09/15 13:46:03.324[0m: [[33mamf[0m] [1;37mDEBUG[0m: SCTP_COMM_UP (../src/amf/ngap-sctp.c:161) [32m09/15 13:46:03.324[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): AMF_EVENT_NGAP_LO_SCTP_COMM_UP (../src/amf/amf-sm.c:83) [32m09/15 13:46:03.324[0m: [[33mamf[0m] [1;32mINFO[0m: gNB-N2[127.0.0.202] max_num_of_ostreams : 30 (../src/amf/amf-sm.c:865) [32m09/15 13:46:03.331[0m: [[33mamf[0m] [1;32mINFO[0m: gNB-N2 accepted[127.0.0.202]:50001 in ng-path module (../src/amf/ngap-sctp.c:113) [32m09/15 13:46:03.331[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): AMF_EVENT_NGAP_LO_ACCEPT (../src/amf/amf-sm.c:83) [32m09/15 13:46:03.331[0m: [[33mamf[0m] [1;32mINFO[0m: gNB-N2 accepted[127.0.0.202] in master_sm module (../src/amf/amf-sm.c:818) TC_ue_service_request_cm_idle_unknown_sess_active-NGAP1(584)@70c03bd1b648: Warning: sizes of 'struct sctp_event_subscribe': compile-time 14, kernel: 14 [32m09/15 13:46:03.334[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_initial(): ENTRY (../src/amf/ngap-sm.c:28) [32m09/15 13:46:03.334[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_operational(): ENTRY (../src/amf/ngap-sm.c:55) [32m09/15 13:46:03.334[0m: [[33mamf[0m] [1;32mINFO[0m: [Added] Number of gNBs is now 2 (../src/amf/context.c:1277) [32m09/15 13:46:03.334[0m: [[33mamf[0m] [1;37mDEBUG[0m: SCTP_ASSOC_CHANGE:[T:32769, F:0x0, S:0, I/O:64/30] (../src/amf/ngap-sctp.c:152) [32m09/15 13:46:03.334[0m: [[33mamf[0m] [1;37mDEBUG[0m: SCTP_COMM_UP (../src/amf/ngap-sctp.c:161) [32m09/15 13:46:03.334[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): AMF_EVENT_NGAP_LO_SCTP_COMM_UP (../src/amf/amf-sm.c:83) [32m09/15 13:46:03.334[0m: [[33mamf[0m] [1;32mINFO[0m: gNB-N2[127.0.0.202] max_num_of_ostreams : 30 (../src/amf/amf-sm.c:865) [32m09/15 13:46:03.338[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): AMF_EVENT_NGAP_MESSAGE (../src/amf/amf-sm.c:83) [32m09/15 13:46:03.338[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_operational(): AMF_EVENT_NGAP_MESSAGE (../src/amf/ngap-sm.c:55) [32m09/15 13:46:03.338[0m: [[33mamf[0m] [1;37mDEBUG[0m: NGSetupRequest (../src/amf/ngap-handler.c:288) [32m09/15 13:46:03.338[0m: [[33mamf[0m] [1;37mDEBUG[0m: IP[127.0.0.202] GNB_ID[0x0] GNB_ID_LENGTH[22] (../src/amf/ngap-handler.c:386) [32m09/15 13:46:03.338[0m: [[33mamf[0m] [1;37mDEBUG[0m: PLMN_ID[MCC:999 MNC:70] (../src/amf/ngap-handler.c:391) [32m09/15 13:46:03.338[0m: [[33mamf[0m] [1;37mDEBUG[0m: PagingDRX[0] (../src/amf/ngap-handler.c:395) [32m09/15 13:46:03.338[0m: [[33mamf[0m] [1;37mDEBUG[0m: TAC[1] (../src/amf/ngap-handler.c:439) [32m09/15 13:46:03.338[0m: [[33mamf[0m] [1;37mDEBUG[0m: PLMN_ID[MCC:999 MNC:70] (../src/amf/ngap-handler.c:501) [32m09/15 13:46:03.338[0m: [[33mamf[0m] [1;37mDEBUG[0m: S_NSSAI[SST:1 SD:0xffffff] (../src/amf/ngap-handler.c:562) [32m09/15 13:46:03.338[0m: [[33mamf[0m] [1;37mDEBUG[0m: TAC[1] (../src/amf/ngap-handler.c:72) [32m09/15 13:46:03.338[0m: [[33mamf[0m] [1;37mDEBUG[0m: PLMN_ID[MCC:999 MNC:70] (../src/amf/ngap-handler.c:73) [32m09/15 13:46:03.338[0m: [[33mamf[0m] [1;37mDEBUG[0m: SERVED_TAI_INDEX[0] (../src/amf/ngap-handler.c:78) [32m09/15 13:46:03.338[0m: [[33mamf[0m] [1;37mDEBUG[0m: PLMN_ID[MCC:999 MNC:70] (../src/amf/ngap-handler.c:100) [32m09/15 13:46:03.338[0m: [[33mamf[0m] [1;37mDEBUG[0m: S_NSSAI[SST:1 SD:0xffffff] (../src/amf/ngap-handler.c:105) [32m09/15 13:46:03.338[0m: [[33mamf[0m] [1;37mDEBUG[0m: NG-Setup response (../src/amf/ngap-path.c:356) [32m09/15 13:46:03.338[0m: [[33mamf[0m] [1;37mDEBUG[0m: NGSetupResponse (../src/amf/ngap-build.c:91) [32m09/15 13:46:03.338[0m: [[33mamf[0m] [1;37mDEBUG[0m: IP[127.0.0.202] RAN_ID[0] (../src/amf/ngap-path.c:64) MTC@70c03bd1b648: setverdict(pass): none -> pass MTC@70c03bd1b648: Dynamic test case error: Error message was received from MC: The connect operation refers to test component with component reference 582, which has already terminated. MTC@70c03bd1b648: setverdict(error): pass -> error TC_ue_service_request_cm_idle_unknown_sess_active0(585)@70c03bd1b648: Final verdict of PTC: none [32m09/15 13:46:03.346[0m: [[33mamf[0m] [1;37mDEBUG[0m: SCTP_SHUTDOWN_EVENT:[T:32773, F:0x0, L:12] (../src/amf/ngap-sctp.c:193) [32m09/15 13:46:03.346[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): AMF_EVENT_NGAP_LO_CONNREFUSED (../src/amf/amf-sm.c:83) [32m09/15 13:46:03.346[0m: [[33mamf[0m] [1;32mINFO[0m: gNB-N2[127.0.0.202] connection refused!!! (../src/amf/amf-sm.c:878) [32m09/15 13:46:03.346[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_operational(): EXIT (../src/amf/ngap-sm.c:55) [32m09/15 13:46:03.346[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_final(): EXIT (../src/amf/ngap-sm.c:37) [32m09/15 13:46:03.346[0m: [[33mamf[0m] [1;32mINFO[0m: [Removed] Number of gNBs is now 1 (../src/amf/context.c:1305) TC_ue_service_request_cm_idle_unknown_sess_active-NGAP0(583)@70c03bd1b648: Final verdict of PTC: none [32m09/15 13:46:03.346[0m: [[33mamf[0m] [1;37mDEBUG[0m: SCTP_SHUTDOWN_EVENT:[T:32773, F:0x0, L:12] (../src/amf/ngap-sctp.c:193) [32m09/15 13:46:03.346[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): AMF_EVENT_NGAP_LO_CONNREFUSED (../src/amf/amf-sm.c:83) [32m09/15 13:46:03.346[0m: [[33mamf[0m] [1;32mINFO[0m: gNB-N2[127.0.0.202] connection refused!!! (../src/amf/amf-sm.c:878) [32m09/15 13:46:03.346[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_operational(): EXIT (../src/amf/ngap-sm.c:55) [32m09/15 13:46:03.346[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_final(): EXIT (../src/amf/ngap-sm.c:37) [32m09/15 13:46:03.346[0m: [[33mamf[0m] [1;32mINFO[0m: [Removed] Number of gNBs is now 0 (../src/amf/context.c:1305) TC_ue_service_request_cm_idle_unknown_sess_active-NGAP1(584)@70c03bd1b648: Final verdict of PTC: none MTC@70c03bd1b648: Setting final verdict of the test case. MTC@70c03bd1b648: Local verdict of MTC: error MTC@70c03bd1b648: Local verdict of PTC GTP1U_EM(582): error (error -> error) MTC@70c03bd1b648: Local verdict of PTC TC_ue_service_request_cm_idle_unknown_sess_active-NGAP0(583): none (error -> error) MTC@70c03bd1b648: Local verdict of PTC TC_ue_service_request_cm_idle_unknown_sess_active-NGAP1(584): none (error -> error) MTC@70c03bd1b648: Local verdict of PTC TC_ue_service_request_cm_idle_unknown_sess_active0(585): none (error -> error) MTC@70c03bd1b648: Test case TC_ue_service_request_cm_idle_unknown_sess_active finished. Verdict: error MTC@70c03bd1b648: Starting external command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs-latest/ws/src/os…> C5G_Tests.TC_ue_service_request_cm_idle_unknown_sess_active error'. (13:46:03) load average: 3.32, 3.76, 2.87 [1;31m------ C5G_Tests.TC_ue_service_request_cm_idle_unknown_sess_active error ------[0m Waiting for packet dumper to finish... 0 (prev_count=-1, count=224) Waiting for packet dumper to finish... 1 (prev_count=224, count=4844) MTC@70c03bd1b648: External command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs-latest/ws/src/os…> C5G_Tests.TC_ue_service_request_cm_idle_unknown_sess_active error' was executed successfully (exit status: 0). MTC@70c03bd1b648: Starting external command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs-latest/ws/src/os…> C5G_Tests.TC_ue_service_request_cm_connected'. ------ C5G_Tests.TC_ue_service_request_cm_connected ------ (13:46:05) load average: 3.32, 3.76, 2.87 /usr/bin/dumpcap -q -s 1520 -n -i any -w "<https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs-latest/307/artif…"> ><https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs-latest/307/artif…> 2>/tmp/cmderr & Waiting for packet dumper to start... 0 [1;34m[testenv] Stopping podman container[0m [0;94m[testenv] + ['podman', 'kill', 'testenv-5gc-osmocom-latest-20260915-1343-3ffc8ee5-1'][0m testenv-5gc-osmocom-latest-20260915-1343-3ffc8ee5-1 [0;94m[testenv] Skipping clean up scripts, podman container has already stopped[0m [1;34m[testenv] Stopping testsuite (1122421)[0m Error: container has already been removed Error: container has already been removed Error: container has already been removed [0;94m[testenv] feed_watchdog_loop: podman container has stopped[0m [1;34m[testenv] Logs saved to: https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs-latest/307/artif… [0m + RC=1 + [ 1 = 0 ] + uptime + grep --color=always -o load.* [01;31m[Kload average: 3.32, 3.76, 2.87[m[K + exit 1 Build step 'Execute shell' marked build as failure Recording test results [Checks API] No suitable checks publisher found.

1 0

Build failed in Jenkins: ttcn3-5gc-test-ogs #479
by jenkins＠lists.osmocom.org 14 Sep '26

14 Sep '26

See <https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs/479/display/redi…> Changes: ------------------------------------------ [...truncated 246.13 MiB...] [32m09/14 09:33:17.503[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_initial(): ENTRY (../src/amf/ngap-sm.c:28) [32m09/14 09:33:17.503[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_operational(): ENTRY (../src/amf/ngap-sm.c:55) [32m09/14 09:33:17.503[0m: [[33mamf[0m] [1;32mINFO[0m: [Added] Number of gNBs is now 1 (../src/amf/context.c:1349) [32m09/14 09:33:17.503[0m: [[33mamf[0m] [1;37mDEBUG[0m: SCTP_ASSOC_CHANGE:[T:32769, F:0x0, S:0, I/O:64/30] (../src/amf/ngap-sctp.c:152) [32m09/14 09:33:17.503[0m: [[33mamf[0m] [1;37mDEBUG[0m: SCTP_COMM_UP (../src/amf/ngap-sctp.c:161) [32m09/14 09:33:17.503[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): AMF_EVENT_NGAP_LO_SCTP_COMM_UP (../src/amf/amf-sm.c:84) [32m09/14 09:33:17.503[0m: [[33mamf[0m] [1;32mINFO[0m: gNB-N2[127.0.0.202] max_num_of_ostreams : 30 (../src/amf/amf-sm.c:870) TC_secondary_rat_data_usage_report-NGAP1(887)@a379fdf1b308: Warning: sizes of 'struct sctp_event_subscribe': compile-time 14, kernel: 14 [32m09/14 09:33:17.508[0m: [[33mamf[0m] [1;32mINFO[0m: gNB-N2 accepted[127.0.0.202]:50001 in ng-path module (../src/amf/ngap-sctp.c:113) [32m09/14 09:33:17.508[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): AMF_EVENT_NGAP_LO_ACCEPT (../src/amf/amf-sm.c:84) [32m09/14 09:33:17.508[0m: [[33mamf[0m] [1;32mINFO[0m: gNB-N2 accepted[127.0.0.202] in master_sm module (../src/amf/amf-sm.c:823) [32m09/14 09:33:17.511[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_initial(): ENTRY (../src/amf/ngap-sm.c:28) [32m09/14 09:33:17.511[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_operational(): ENTRY (../src/amf/ngap-sm.c:55) [32m09/14 09:33:17.511[0m: [[33mamf[0m] [1;32mINFO[0m: [Added] Number of gNBs is now 2 (../src/amf/context.c:1349) [32m09/14 09:33:17.511[0m: [[33mamf[0m] [1;37mDEBUG[0m: SCTP_ASSOC_CHANGE:[T:32769, F:0x0, S:0, I/O:64/30] (../src/amf/ngap-sctp.c:152) [32m09/14 09:33:17.511[0m: [[33mamf[0m] [1;37mDEBUG[0m: SCTP_COMM_UP (../src/amf/ngap-sctp.c:161) [32m09/14 09:33:17.511[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): AMF_EVENT_NGAP_LO_SCTP_COMM_UP (../src/amf/amf-sm.c:84) [32m09/14 09:33:17.511[0m: [[33mamf[0m] [1;32mINFO[0m: gNB-N2[127.0.0.202] max_num_of_ostreams : 30 (../src/amf/amf-sm.c:870) [32m09/14 09:33:17.514[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): AMF_EVENT_NGAP_MESSAGE (../src/amf/amf-sm.c:84) [32m09/14 09:33:17.514[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_operational(): AMF_EVENT_NGAP_MESSAGE (../src/amf/ngap-sm.c:55) [32m09/14 09:33:17.514[0m: [[33mamf[0m] [1;37mDEBUG[0m: NGSetupRequest (../src/amf/ngap-handler.c:288) [32m09/14 09:33:17.514[0m: [[33mamf[0m] [1;37mDEBUG[0m: IP[127.0.0.202] GNB_ID[0x0] GNB_ID_LENGTH[22] (../src/amf/ngap-handler.c:386) [32m09/14 09:33:17.514[0m: [[33mamf[0m] [1;37mDEBUG[0m: PLMN_ID[MCC:999 MNC:70] (../src/amf/ngap-handler.c:391) [32m09/14 09:33:17.514[0m: [[33mamf[0m] [1;37mDEBUG[0m: PagingDRX[0] (../src/amf/ngap-handler.c:395) [32m09/14 09:33:17.514[0m: [[33mamf[0m] [1;37mDEBUG[0m: TAC[1] (../src/amf/ngap-handler.c:439) [32m09/14 09:33:17.514[0m: [[33mamf[0m] [1;37mDEBUG[0m: PLMN_ID[MCC:999 MNC:70] (../src/amf/ngap-handler.c:501) [32m09/14 09:33:17.514[0m: [[33mamf[0m] [1;37mDEBUG[0m: S_NSSAI[SST:1 SD:0xffffff] (../src/amf/ngap-handler.c:562) [32m09/14 09:33:17.514[0m: [[33mamf[0m] [1;37mDEBUG[0m: TAC[1] (../src/amf/ngap-handler.c:72) [32m09/14 09:33:17.514[0m: [[33mamf[0m] [1;37mDEBUG[0m: PLMN_ID[MCC:999 MNC:70] (../src/amf/ngap-handler.c:73) [32m09/14 09:33:17.514[0m: [[33mamf[0m] [1;37mDEBUG[0m: SERVED_TAI_INDEX[0] (../src/amf/ngap-handler.c:78) [32m09/14 09:33:17.514[0m: [[33mamf[0m] [1;37mDEBUG[0m: PLMN_ID[MCC:999 MNC:70] (../src/amf/ngap-handler.c:100) [32m09/14 09:33:17.514[0m: [[33mamf[0m] [1;37mDEBUG[0m: S_NSSAI[SST:1 SD:0xffffff] (../src/amf/ngap-handler.c:105) [32m09/14 09:33:17.514[0m: [[33mamf[0m] [1;37mDEBUG[0m: NG-Setup response (../src/amf/ngap-path.c:356) [32m09/14 09:33:17.514[0m: [[33mamf[0m] [1;37mDEBUG[0m: NGSetupResponse (../src/amf/ngap-build.c:91) [32m09/14 09:33:17.514[0m: [[33mamf[0m] [1;37mDEBUG[0m: IP[127.0.0.202] RAN_ID[0] (../src/amf/ngap-path.c:64) MTC@a379fdf1b308: setverdict(pass): none -> pass MTC@a379fdf1b308: Dynamic test case error: Error message was received from MC: The connect operation refers to test component with component reference 885, which has already terminated. MTC@a379fdf1b308: setverdict(error): pass -> error TC_secondary_rat_data_usage_report0(888)@a379fdf1b308: Final verdict of PTC: none [32m09/14 09:33:17.523[0m: [[33mamf[0m] [1;37mDEBUG[0m: SCTP_SHUTDOWN_EVENT:[T:32773, F:0x0, L:12] (../src/amf/ngap-sctp.c:193) [32m09/14 09:33:17.523[0m: [[33mamf[0m] [1;37mDEBUG[0m: SCTP_SHUTDOWN_EVENT:[T:32773, F:0x0, L:12] (../src/amf/ngap-sctp.c:193) [32m09/14 09:33:17.523[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): AMF_EVENT_NGAP_LO_CONNREFUSED (../src/amf/amf-sm.c:84) [32m09/14 09:33:17.523[0m: [[33mamf[0m] [1;32mINFO[0m: gNB-N2[127.0.0.202] connection refused!!! (../src/amf/amf-sm.c:883) [32m09/14 09:33:17.523[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_operational(): EXIT (../src/amf/ngap-sm.c:55) [32m09/14 09:33:17.523[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_final(): EXIT (../src/amf/ngap-sm.c:37) [32m09/14 09:33:17.523[0m: [[33mamf[0m] [1;32mINFO[0m: [Removed] Number of gNBs is now 1 (../src/amf/context.c:1377) [32m09/14 09:33:17.523[0m: [[33mamf[0m] [1;37mDEBUG[0m: amf_state_operational(): AMF_EVENT_NGAP_LO_CONNREFUSED (../src/amf/amf-sm.c:84) [32m09/14 09:33:17.523[0m: [[33mamf[0m] [1;32mINFO[0m: gNB-N2[127.0.0.202] connection refused!!! (../src/amf/amf-sm.c:883) [32m09/14 09:33:17.523[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_operational(): EXIT (../src/amf/ngap-sm.c:55) [32m09/14 09:33:17.523[0m: [[33mamf[0m] [1;37mDEBUG[0m: ngap_state_final(): EXIT (../src/amf/ngap-sm.c:37) [32m09/14 09:33:17.523[0m: [[33mamf[0m] [1;32mINFO[0m: [Removed] Number of gNBs is now 0 (../src/amf/context.c:1377) TC_secondary_rat_data_usage_report-NGAP1(887)@a379fdf1b308: Final verdict of PTC: none TC_secondary_rat_data_usage_report-NGAP0(886)@a379fdf1b308: Final verdict of PTC: none MTC@a379fdf1b308: Setting final verdict of the test case. MTC@a379fdf1b308: Local verdict of MTC: error MTC@a379fdf1b308: Local verdict of PTC GTP1U_EM(885): error (error -> error) MTC@a379fdf1b308: Local verdict of PTC TC_secondary_rat_data_usage_report-NGAP0(886): none (error -> error) MTC@a379fdf1b308: Local verdict of PTC TC_secondary_rat_data_usage_report-NGAP1(887): none (error -> error) MTC@a379fdf1b308: Local verdict of PTC TC_secondary_rat_data_usage_report0(888): none (error -> error) MTC@a379fdf1b308: Test case TC_secondary_rat_data_usage_report finished. Verdict: error MTC@a379fdf1b308: Starting external command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs/ws/src/osmo-ttcn…> C5G_Tests.TC_secondary_rat_data_usage_report error'. (09:33:17) load average: 1.16, 1.80, 3.40 [1;31m------ C5G_Tests.TC_secondary_rat_data_usage_report error ------[0m Waiting for packet dumper to finish... 0 (prev_count=-1, count=224) Waiting for packet dumper to finish... 1 (prev_count=224, count=4732) [32m09/14 09:33:19.184[0m: [[33mscp[0m] [1;37mDEBUG[0m: scp_state_operational(): OGS_EVENT_NAME_SBI_TIMER (../src/scp/scp-sm.c:54) [32m09/14 09:33:19.184[0m: [[33msbi[0m] [1;37mDEBUG[0m: ogs_sbi_nf_state_registered(): OGS_EVENT_NAME_SBI_TIMER (../lib/sbi/nf-sm.c:312) [32m09/14 09:33:19.185[0m: [[33msbi[0m] [1;37mDEBUG[0m: [PATCH] http://127.0.0.10:7777/nnrf-nfm/v1/nf-instances/d4c7db16-b01e-41f1-981e-9d8… (../lib/sbi/client.c:797) [32m09/14 09:33:19.185[0m: [[33msbi[0m] [1;37mDEBUG[0m: SENDING...[100] (../lib/sbi/client.c:546) [32m09/14 09:33:19.185[0m: [[33msbi[0m] [1;37mDEBUG[0m: [{"op":"replace","path":"/nfStatus","value":"REGISTERED"},{"op":"replace","path":"/load","value":0}] (../lib/sbi/client.c:548) [32m09/14 09:33:19.185[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM added [631] (../lib/sbi/nghttp2-server.c:1749) [32m09/14 09:33:19.185[0m: [[33msbi[0m] [1;37mDEBUG[0m: [PATCH] /nnrf-nfm/v1/nf-instances/d4c7db16-b01e-41f1-981e-9d8f137f857f (../lib/sbi/nghttp2-server.c:1308) [32m09/14 09:33:19.185[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED: 100 (../lib/sbi/nghttp2-server.c:1312) [32m09/14 09:33:19.185[0m: [[33msbi[0m] [1;37mDEBUG[0m: [{"op":"replace","path":"/nfStatus","value":"REGISTERED"},{"op":"replace","path":"/load","value":0}] (../lib/sbi/nghttp2-server.c:1314) [32m09/14 09:33:19.185[0m: [[33mnrf[0m] [1;37mDEBUG[0m: nrf_state_operational(): OGS_EVENT_NAME_SBI_SERVER (../src/nrf/nrf-sm.c:54) [32m09/14 09:33:19.185[0m: [[33mnrf[0m] [1;37mDEBUG[0m: nrf_nf_state_registered(): OGS_EVENT_NAME_SBI_SERVER (../src/nrf/nf-sm.c:201) [32m09/14 09:33:19.185[0m: [[33msbi[0m] [1;37mDEBUG[0m: STATUS [204] (../lib/sbi/nghttp2-server.c:702) [32m09/14 09:33:19.185[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM closed [631] (../lib/sbi/nghttp2-server.c:1431) 0000: 00001a01 05000002 7789c061 96d07abe ........w..a..z. 0010: 940b4a6e 2d6a0802 71403f71 966e05f5 ..Jn-j..q(a)?q.n.. 0020: 3168df 1h. [32m09/14 09:33:19.186[0m: [[33msbi[0m] [1;37mDEBUG[0m: [204:PATCH] http://127.0.0.10:7777/nnrf-nfm/v1/nf-instances/d4c7db16-b01e-41f1-981e-9d8… (../lib/sbi/client.c:744) [32m09/14 09:33:19.186[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED[0] (../lib/sbi/client.c:755) [32m09/14 09:33:19.186[0m: [[33mscp[0m] [1;37mDEBUG[0m: scp_state_operational(): OGS_EVENT_NAME_SBI_CLIENT (../src/scp/scp-sm.c:54) [32m09/14 09:33:19.186[0m: [[33msbi[0m] [1;37mDEBUG[0m: ogs_sbi_nf_state_registered(): OGS_EVENT_NAME_SBI_CLIENT (../lib/sbi/nf-sm.c:312) [32m09/14 09:33:19.469[0m: [[33msepp[0m] [1;37mDEBUG[0m: sepp_state_operational(): OGS_EVENT_NAME_SBI_TIMER (../src/sepp/sepp-sm.c:55) [32m09/14 09:33:19.469[0m: [[33msbi[0m] [1;37mDEBUG[0m: ogs_sbi_nf_state_registered(): OGS_EVENT_NAME_SBI_TIMER (../lib/sbi/nf-sm.c:312) [32m09/14 09:33:19.469[0m: [[33msbi[0m] [1;37mDEBUG[0m: [PATCH] http://127.0.0.200:7777/nnrf-nfm/v1/nf-instances/d4e60e42-b01e-41f1-91e9-67… (../lib/sbi/client.c:797) [32m09/14 09:33:19.469[0m: [[33msbi[0m] [1;37mDEBUG[0m: SENDING...[100] (../lib/sbi/client.c:546) [32m09/14 09:33:19.469[0m: [[33msbi[0m] [1;37mDEBUG[0m: [{"op":"replace","path":"/nfStatus","value":"REGISTERED"},{"op":"replace","path":"/load","value":0}] (../lib/sbi/client.c:548) [32m09/14 09:33:19.469[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM added [51] (../lib/sbi/nghttp2-server.c:1749) [32m09/14 09:33:19.469[0m: [[33msbi[0m] [1;37mDEBUG[0m: [PATCH] /nnrf-nfm/v1/nf-instances/d4e60e42-b01e-41f1-91e9-67fbdaf3737b (../lib/sbi/nghttp2-server.c:1308) [32m09/14 09:33:19.469[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED: 100 (../lib/sbi/nghttp2-server.c:1312) [32m09/14 09:33:19.469[0m: [[33msbi[0m] [1;37mDEBUG[0m: [{"op":"replace","path":"/nfStatus","value":"REGISTERED"},{"op":"replace","path":"/load","value":0}] (../lib/sbi/nghttp2-server.c:1314) [32m09/14 09:33:19.469[0m: [[33msbi[0m] [1;37mDEBUG[0m: [PATCH] http://127.0.0.10:7777/nnrf-nfm/v1/nf-instances/d4e60e42-b01e-41f1-91e9-67f… (../lib/sbi/client.c:797) [32m09/14 09:33:19.469[0m: [[33msbi[0m] [1;37mDEBUG[0m: SENDING...[100] (../lib/sbi/client.c:546) [32m09/14 09:33:19.469[0m: [[33msbi[0m] [1;37mDEBUG[0m: [{"op":"replace","path":"/nfStatus","value":"REGISTERED"},{"op":"replace","path":"/load","value":0}] (../lib/sbi/client.c:548) [32m09/14 09:33:19.470[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM added [633] (../lib/sbi/nghttp2-server.c:1749) [32m09/14 09:33:19.470[0m: [[33msbi[0m] [1;37mDEBUG[0m: [PATCH] /nnrf-nfm/v1/nf-instances/d4e60e42-b01e-41f1-91e9-67fbdaf3737b (../lib/sbi/nghttp2-server.c:1308) [32m09/14 09:33:19.470[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED: 100 (../lib/sbi/nghttp2-server.c:1312) [32m09/14 09:33:19.470[0m: [[33msbi[0m] [1;37mDEBUG[0m: [{"op":"replace","path":"/nfStatus","value":"REGISTERED"},{"op":"replace","path":"/load","value":0}] (../lib/sbi/nghttp2-server.c:1314) [32m09/14 09:33:19.470[0m: [[33mnrf[0m] [1;37mDEBUG[0m: nrf_state_operational(): OGS_EVENT_NAME_SBI_SERVER (../src/nrf/nrf-sm.c:54) [32m09/14 09:33:19.470[0m: [[33mnrf[0m] [1;37mDEBUG[0m: nrf_nf_state_registered(): OGS_EVENT_NAME_SBI_SERVER (../src/nrf/nf-sm.c:201) [32m09/14 09:33:19.470[0m: [[33msbi[0m] [1;37mDEBUG[0m: STATUS [204] (../lib/sbi/nghttp2-server.c:702) [32m09/14 09:33:19.470[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM closed [633] (../lib/sbi/nghttp2-server.c:1431) 0000: 00000301 05000002 7989c1be ........y... [32m09/14 09:33:19.470[0m: [[33msbi[0m] [1;37mDEBUG[0m: [204:PATCH] http://127.0.0.10:7777/nnrf-nfm/v1/nf-instances/d4e60e42-b01e-41f1-91e9-67f… (../lib/sbi/client.c:744) [32m09/14 09:33:19.470[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED[0] (../lib/sbi/client.c:755) [32m09/14 09:33:19.470[0m: [[33msbi[0m] [1;37mDEBUG[0m: STATUS [204] (../lib/sbi/nghttp2-server.c:702) [32m09/14 09:33:19.470[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM closed [51] (../lib/sbi/nghttp2-server.c:1431) 0000: 00001a01 05000000 3389d561 96d07abe ........3..a..z. 0010: 940b4a6e 2d6a0802 71403f71 966e05f5 ..Jn-j..q(a)?q.n.. 0020: 3168df 1h. [32m09/14 09:33:19.471[0m: [[33msbi[0m] [1;37mDEBUG[0m: [204:PATCH] http://127.0.0.200:7777/nnrf-nfm/v1/nf-instances/d4e60e42-b01e-41f1-91e9-67… (../lib/sbi/client.c:744) [32m09/14 09:33:19.471[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED[0] (../lib/sbi/client.c:755) [32m09/14 09:33:19.471[0m: [[33msepp[0m] [1;37mDEBUG[0m: sepp_state_operational(): OGS_EVENT_NAME_SBI_CLIENT (../src/sepp/sepp-sm.c:55) [32m09/14 09:33:19.471[0m: [[33msbi[0m] [1;37mDEBUG[0m: ogs_sbi_nf_state_registered(): OGS_EVENT_NAME_SBI_CLIENT (../lib/sbi/nf-sm.c:312) MTC@a379fdf1b308: External command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs/ws/src/osmo-ttcn…> C5G_Tests.TC_secondary_rat_data_usage_report error' was executed successfully (exit status: 0). MC@a379fdf1b308: Test execution finished. Execution of [EXECUTE] section finished. emtc MC@a379fdf1b308: Terminating MTC. MC@a379fdf1b308: MTC terminated. MC2> exit MC@a379fdf1b308: Shutting down session. MC@a379fdf1b308: Shutdown complete. [32m09/14 09:33:19.664[0m: [[33msepp[0m] [1;37mDEBUG[0m: sepp_state_operational(): OGS_EVENT_NAME_SBI_TIMER (../src/sepp/sepp-sm.c:55) [32m09/14 09:33:19.664[0m: [[33msbi[0m] [1;37mDEBUG[0m: ogs_sbi_nf_state_registered(): OGS_EVENT_NAME_SBI_TIMER (../lib/sbi/nf-sm.c:312) [32m09/14 09:33:19.664[0m: [[33msbi[0m] [1;37mDEBUG[0m: [PATCH] http://127.0.0.200:7777/nnrf-nfm/v1/nf-instances/d4ffd156-b01e-41f1-a982-39… (../lib/sbi/client.c:797) [32m09/14 09:33:19.664[0m: [[33msbi[0m] [1;37mDEBUG[0m: SENDING...[100] (../lib/sbi/client.c:546) [32m09/14 09:33:19.664[0m: [[33msbi[0m] [1;37mDEBUG[0m: [{"op":"replace","path":"/nfStatus","value":"REGISTERED"},{"op":"replace","path":"/load","value":0}] (../lib/sbi/client.c:548) [32m09/14 09:33:19.665[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM added [53] (../lib/sbi/nghttp2-server.c:1749) [32m09/14 09:33:19.665[0m: [[33msbi[0m] [1;37mDEBUG[0m: [PATCH] /nnrf-nfm/v1/nf-instances/d4ffd156-b01e-41f1-a982-394619cecdd4 (../lib/sbi/nghttp2-server.c:1308) [32m09/14 09:33:19.665[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED: 100 (../lib/sbi/nghttp2-server.c:1312) [32m09/14 09:33:19.665[0m: [[33msbi[0m] [1;37mDEBUG[0m: [{"op":"replace","path":"/nfStatus","value":"REGISTERED"},{"op":"replace","path":"/load","value":0}] (../lib/sbi/nghttp2-server.c:1314) [32m09/14 09:33:19.665[0m: [[33msbi[0m] [1;37mDEBUG[0m: [PATCH] http://127.0.0.10:7777/nnrf-nfm/v1/nf-instances/d4ffd156-b01e-41f1-a982-394… (../lib/sbi/client.c:797) [32m09/14 09:33:19.665[0m: [[33msbi[0m] [1;37mDEBUG[0m: SENDING...[100] (../lib/sbi/client.c:546) [32m09/14 09:33:19.665[0m: [[33msbi[0m] [1;37mDEBUG[0m: [{"op":"replace","path":"/nfStatus","value":"REGISTERED"},{"op":"replace","path":"/load","value":0}] (../lib/sbi/client.c:548) [32m09/14 09:33:19.665[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM added [635] (../lib/sbi/nghttp2-server.c:1749) [32m09/14 09:33:19.665[0m: [[33msbi[0m] [1;37mDEBUG[0m: [PATCH] /nnrf-nfm/v1/nf-instances/d4ffd156-b01e-41f1-a982-394619cecdd4 (../lib/sbi/nghttp2-server.c:1308) [32m09/14 09:33:19.665[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED: 100 (../lib/sbi/nghttp2-server.c:1312) [32m09/14 09:33:19.665[0m: [[33msbi[0m] [1;37mDEBUG[0m: [{"op":"replace","path":"/nfStatus","value":"REGISTERED"},{"op":"replace","path":"/load","value":0}] (../lib/sbi/nghttp2-server.c:1314) [32m09/14 09:33:19.665[0m: [[33mnrf[0m] [1;37mDEBUG[0m: nrf_state_operational(): OGS_EVENT_NAME_SBI_SERVER (../src/nrf/nrf-sm.c:54) [32m09/14 09:33:19.665[0m: [[33mnrf[0m] [1;37mDEBUG[0m: nrf_nf_state_registered(): OGS_EVENT_NAME_SBI_SERVER (../src/nrf/nf-sm.c:201) [32m09/14 09:33:19.665[0m: [[33msbi[0m] [1;37mDEBUG[0m: STATUS [204] (../lib/sbi/nghttp2-server.c:702) [32m09/14 09:33:19.665[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM closed [635] (../lib/sbi/nghttp2-server.c:1431) 0000: 00000301 05000002 7b89c1be ........{... [32m09/14 09:33:19.665[0m: [[33msbi[0m] [1;37mDEBUG[0m: [204:PATCH] http://127.0.0.10:7777/nnrf-nfm/v1/nf-instances/d4ffd156-b01e-41f1-a982-394… (../lib/sbi/client.c:744) [32m09/14 09:33:19.665[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED[0] (../lib/sbi/client.c:755) [32m09/14 09:33:19.665[0m: [[33msbi[0m] [1;37mDEBUG[0m: STATUS [204] (../lib/sbi/nghttp2-server.c:702) [32m09/14 09:33:19.665[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM closed [53] (../lib/sbi/nghttp2-server.c:1431) 0000: 00001a01 05000000 3589d561 96d07abe ........5..a..z. 0010: 940b4a6e 2d6a0802 71403f71 966e05f5 ..Jn-j..q(a)?q.n.. 0020: 3168df 1h. [32m09/14 09:33:19.665[0m: [[33msbi[0m] [1;37mDEBUG[0m: [204:PATCH] http://127.0.0.200:7777/nnrf-nfm/v1/nf-instances/d4ffd156-b01e-41f1-a982-39… (../lib/sbi/client.c:744) [32m09/14 09:33:19.665[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED[0] (../lib/sbi/client.c:755) [32m09/14 09:33:19.665[0m: [[33msepp[0m] [1;37mDEBUG[0m: sepp_state_operational(): OGS_EVENT_NAME_SBI_CLIENT (../src/sepp/sepp-sm.c:55) [32m09/14 09:33:19.665[0m: [[33msbi[0m] [1;37mDEBUG[0m: ogs_sbi_nf_state_registered(): OGS_EVENT_NAME_SBI_CLIENT (../lib/sbi/nf-sm.c:312) Comparing expected results '<https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs/ws/src/osmo-ttcn…'> against results in 'junit-xml-6861.log' -------------------- [1;32mpass[0;m C5G_Tests.TC_ng_setup_unknown_global_gnb_id_plmn [1;32mpass[0;m C5G_Tests.TC_ng_setup_wrong_tac [1;32mpass[0;m C5G_Tests.TC_ng_setup [1;32mpass[0;m C5G_Tests.TC_ng_register [1;32mpass[0;m C5G_Tests.TC_periodic_registration_updating [1;32mpass[0;m C5G_Tests.TC_ue_context_release_no_pdu_session [1;32mpass[0;m C5G_Tests.TC_ue_context_release_with_pdu_session [1;32mpass[0;m C5G_Tests.TC_pdu_sess_modification [1;33mxfail[0;m C5G_Tests.TC_pdu_sess_est_always_on [1;32mpass[0;m C5G_Tests.TC_pdu_sess_est_pco_pcscf [1;32mpass[0;m C5G_Tests.TC_ng_register_ping4 [1;32mpass[0;m C5G_Tests.TC_ng_register_ping4_256 [1;32mpass[0;m C5G_Tests.TC_ng_register_ping6 [1;32mpass[0;m C5G_Tests.TC_ng_register_ping6_256 [1;32mpass[0;m C5G_Tests.TC_ng_register_ping46 [1;31mpass->FAIL[0;m C5G_Tests.TC_ng_register_ping46_256 [1;31mpass->FAIL[0;m C5G_Tests.TC_ue_service_request_cm_idle_ul_data [1;31mpass->FAIL[0;m C5G_Tests.TC_ue_service_request_cm_idle_inact_sess [1;31mpass->FAIL[0;m C5G_Tests.TC_ue_service_request_cm_idle_unknown_sess_active [1;31mpass->FAIL[0;m C5G_Tests.TC_ue_service_request_cm_connected [1;31mpass->FAIL[0;m C5G_Tests.TC_net_triggered_service_req [1;33mxfail[0;m C5G_Tests.TC_connection_inactive [1;33mxfail[0;m C5G_Tests.TC_connection_suspend [1;33mxfail[0;m C5G_Tests.TC_normal_reg_emergency_services [1;33mxfail[0;m C5G_Tests.TC_normal_reg_emergency_services_fallback [1;33mxfail[0;m C5G_Tests.TC_emergency_registration [1;31mpass->FAIL[0;m C5G_Tests.TC_handover_inter_ngran_xn [1;31mpass->FAIL[0;m C5G_Tests.TC_handover_inter_ngran_n2 [1;31mpass->FAIL[0;m C5G_Tests.TC_handover_inter_ngran_n2_cancel [1;33mxfail[0;m C5G_Tests.TC_ran_initiated_qos_flow_mobility [1;31mpass->FAIL[0;m C5G_Tests.TC_secondary_rat_data_usage_report Summary: [1;31mpass->FAIL[0;m: 10 [1;33mxfail[0;m: 7 [1;32mpass[0;m: 14 [32m09/14 09:33:19.780[0m: [[33mbsf[0m] [1;37mDEBUG[0m: bsf_state_operational(): OGS_EVENT_NAME_SBI_TIMER (../src/bsf/bsf-sm.c:59) [32m09/14 09:33:19.781[0m: [[33msbi[0m] [1;37mDEBUG[0m: ogs_sbi_nf_state_registered(): OGS_EVENT_NAME_SBI_TIMER (../lib/sbi/nf-sm.c:312) [32m09/14 09:33:19.781[0m: [[33msbi[0m] [1;37mDEBUG[0m: [PATCH] http://127.0.0.200:7777/nnrf-nfm/v1/nf-instances/d51e5338-b01e-41f1-ad06-13… (../lib/sbi/client.c:797) [32m09/14 09:33:19.781[0m: [[33msbi[0m] [1;37mDEBUG[0m: SENDING...[100] (../lib/sbi/client.c:546) [32m09/14 09:33:19.781[0m: [[33msbi[0m] [1;37mDEBUG[0m: [{"op":"replace","path":"/nfStatus","value":"REGISTERED"},{"op":"replace","path":"/load","value":6}] (../lib/sbi/client.c:548) [32m09/14 09:33:19.781[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM added [57] (../lib/sbi/nghttp2-server.c:1749) [32m09/14 09:33:19.781[0m: [[33msbi[0m] [1;37mDEBUG[0m: [PATCH] /nnrf-nfm/v1/nf-instances/d51e5338-b01e-41f1-ad06-1374fe33161f (../lib/sbi/nghttp2-server.c:1308) [32m09/14 09:33:19.781[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED: 100 (../lib/sbi/nghttp2-server.c:1312) [32m09/14 09:33:19.781[0m: [[33msbi[0m] [1;37mDEBUG[0m: [{"op":"replace","path":"/nfStatus","value":"REGISTERED"},{"op":"replace","path":"/load","value":6}] (../lib/sbi/nghttp2-server.c:1314) [32m09/14 09:33:19.781[0m: [[33msbi[0m] [1;37mDEBUG[0m: [PATCH] http://127.0.0.10:7777/nnrf-nfm/v1/nf-instances/d51e5338-b01e-41f1-ad06-137… (../lib/sbi/client.c:797) [32m09/14 09:33:19.781[0m: [[33msbi[0m] [1;37mDEBUG[0m: SENDING...[100] (../lib/sbi/client.c:546) [32m09/14 09:33:19.781[0m: [[33msbi[0m] [1;37mDEBUG[0m: [{"op":"replace","path":"/nfStatus","value":"REGISTERED"},{"op":"replace","path":"/load","value":6}] (../lib/sbi/client.c:548) [32m09/14 09:33:19.782[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM added [637] (../lib/sbi/nghttp2-server.c:1749) [32m09/14 09:33:19.782[0m: [[33msbi[0m] [1;37mDEBUG[0m: [PATCH] /nnrf-nfm/v1/nf-instances/d51e5338-b01e-41f1-ad06-1374fe33161f (../lib/sbi/nghttp2-server.c:1308) [32m09/14 09:33:19.782[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED: 100 (../lib/sbi/nghttp2-server.c:1312) [32m09/14 09:33:19.782[0m: [[33msbi[0m] [1;37mDEBUG[0m: [{"op":"replace","path":"/nfStatus","value":"REGISTERED"},{"op":"replace","path":"/load","value":6}] (../lib/sbi/nghttp2-server.c:1314) [32m09/14 09:33:19.782[0m: [[33mnrf[0m] [1;37mDEBUG[0m: nrf_state_operational(): OGS_EVENT_NAME_SBI_SERVER (../src/nrf/nrf-sm.c:54) [32m09/14 09:33:19.782[0m: [[33mnrf[0m] [1;37mDEBUG[0m: nrf_nf_state_registered(): OGS_EVENT_NAME_SBI_SERVER (../src/nrf/nf-sm.c:201) [32m09/14 09:33:19.782[0m: [[33msbi[0m] [1;37mDEBUG[0m: STATUS [204] (../lib/sbi/nghttp2-server.c:702) [32m09/14 09:33:19.782[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM closed [637] (../lib/sbi/nghttp2-server.c:1431) 0000: 00000301 05000002 7d89c1be ........}... [32m09/14 09:33:19.782[0m: [[33msbi[0m] [1;37mDEBUG[0m: [204:PATCH] http://127.0.0.10:7777/nnrf-nfm/v1/nf-instances/d51e5338-b01e-41f1-ad06-137… (../lib/sbi/client.c:744) [32m09/14 09:33:19.782[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED[0] (../lib/sbi/client.c:755) [32m09/14 09:33:19.782[0m: [[33msbi[0m] [1;37mDEBUG[0m: STATUS [204] (../lib/sbi/nghttp2-server.c:702) [32m09/14 09:33:19.782[0m: [[33msbi[0m] [1;37mDEBUG[0m: STREAM closed [57] (../lib/sbi/nghttp2-server.c:1431) 0000: 00001a01 05000000 3989d561 96d07abe ........9..a..z. 0010: 940b4a6e 2d6a0802 71403f71 966e05f5 ..Jn-j..q(a)?q.n.. 0020: 3168df 1h. [32m09/14 09:33:19.783[0m: [[33msbi[0m] [1;37mDEBUG[0m: [204:PATCH] http://127.0.0.200:7777/nnrf-nfm/v1/nf-instances/d51e5338-b01e-41f1-ad06-13… (../lib/sbi/client.c:744) [32m09/14 09:33:19.783[0m: [[33msbi[0m] [1;37mDEBUG[0m: RECEIVED[0] (../lib/sbi/client.c:755) [32m09/14 09:33:19.783[0m: [[33mbsf[0m] [1;37mDEBUG[0m: bsf_state_operational(): OGS_EVENT_NAME_SBI_CLIENT (../src/bsf/bsf-sm.c:59) [32m09/14 09:33:19.783[0m: [[33msbi[0m] [1;37mDEBUG[0m: ogs_sbi_nf_state_registered(): OGS_EVENT_NAME_SBI_CLIENT (../lib/sbi/nf-sm.c:312) [1;34m[testenv] Stopping podman container[0m [0;94m[testenv] + ['podman', 'kill', 'testenv-5gc-osmocom-nightly-20260914-0929-f348db2d-1'][0m testenv-5gc-osmocom-nightly-20260914-0929-f348db2d-1 [0;94m[testenv] Skipping clean up scripts, podman container has already stopped[0m [1;34m[testenv] Stopping testsuite (3872542)[0m Error: container has already been removed [0;94m[testenv] feed_watchdog_loop: podman container has stopped[0m [1;34m[testenv] Logs saved to: https://jenkins.osmocom.org/jenkins/job/ttcn3-5gc-test-ogs/479/artifact/log… [0m + RC=1 + [ 1 = 0 ] + uptime + grep --color=always -o load.* [01;31m[Kload average: 1.07, 1.77, 3.39[m[K + exit 1 Build step 'Execute shell' marked build as failure Recording test results [Checks API] No suitable checks publisher found.

1 0

Build failed in Jenkins: ttcn3-bts-test #3263
by jenkins＠lists.osmocom.org 14 Sep '26

14 Sep '26

See <https://jenkins.osmocom.org/jenkins/job/ttcn3-bts-test/3263/display/redirect> Changes: ------------------------------------------ [...truncated 12.62 MiB...] <testcase classname='BTS_Tests:hopping' name='TC_chan_act_a52' time='2.723557'/> <testcase classname='BTS_Tests:hopping' name='TC_chan_act_a53' time='2.700870'/> <testcase classname='BTS_Tests:hopping' name='TC_chan_act_a54' time='2.729426'/> <testcase classname='BTS_Tests:hopping' name='TC_encr_cmd_a51' time='5.243514'/> <testcase classname='BTS_Tests:hopping' name='TC_encr_cmd_a52' time='5.220784'/> <testcase classname='BTS_Tests:hopping' name='TC_encr_cmd_a53' time='5.219503'/> <testcase classname='BTS_Tests:hopping' name='TC_encr_cmd_a54' time='5.214209'/> <testcase classname='BTS_Tests:hopping' name='TC_err_rep_wrong_mdisc' time='1.225686'/> <testcase classname='BTS_Tests:hopping' name='TC_err_rep_wrong_msg_type' time='1.239884'/> <testcase classname='BTS_Tests:hopping' name='TC_err_rep_wrong_sequence' time='1.250528'/> <testcase classname='BTS_Tests:hopping' name='TC_lapdm_selftest' time='0.004425'/> <testcase classname='BTS_Tests:hopping' name='TC_tch_sign_l2_fill_frame' time='18.428792'/> <testcase classname='BTS_Tests:hopping' name='TC_tch_sign_l2_fill_frame_dtxd' time='2.136831'> <failure type='fail-verdict'>Received DTX TCH fill frame with bad frame number: 175 (mod 104: 71) (mod 26: 19) BTS_Tests.ttcn:9579 BTS_Tests control part BTS_Tests.ttcn:8110 TC_tch_sign_l2_fill_frame_dtxd testcase </failure> </testcase> <testcase classname='BTS_Tests:hopping' name='TC_chopped_ipa_ping' time='24.675705'/> <testcase classname='BTS_Tests:hopping' name='TC_chopped_ipa_payload' time='42.606982'/> <testcase classname='BTS_Tests:hopping' name='TC_ms_pwr_ctrl_constant' time='16.900445'/> <testcase classname='BTS_Tests:hopping' name='TC_ms_pwr_ctrl_pf_ewma' time='32.007421'/> <testcase classname='BTS_Tests:hopping' name='TC_speech_no_rtp_tchf' time='7.394324'/> <testcase classname='BTS_Tests:hopping' name='TC_speech_no_rtp_tchh' time='5.462394'/> <testcase classname='BTS_Tests:hopping' name='TC_speech_rtp_tchf' time='7.576089'/> <testcase classname='BTS_Tests:hopping' name='TC_speech_rtp_tchh' time='5.503080'/> <testcase classname='BTS_Tests:hopping' name='TC_speech_osmux_tchf' time='3.508232'/> <testcase classname='BTS_Tests:hopping' name='TC_speech_osmux_tchh' time='3.481087'/> <testcase classname='BTS_Tests:hopping' name='TC_data_rtp_tchf144' time='5.779337'> <failure type='fail-verdict'>Timeout waiting for matching Downlink data frame BTS_Tests.ttcn:9594 BTS_Tests control part BTS_Tests.ttcn:8781 TC_data_rtp_tchf144 testcase </failure> </testcase> <testcase classname='BTS_Tests:hopping' name='TC_data_rtp_tchf96' time='2.152025'/> <testcase classname='BTS_Tests:hopping' name='TC_data_rtp_tchf48' time='2.134629'/> <testcase classname='BTS_Tests:hopping' name='TC_data_rtp_tchh48' time='5.316943'> <failure type='fail-verdict'>Timeout waiting for matching Downlink data frame BTS_Tests.ttcn:9597 BTS_Tests control part BTS_Tests.ttcn:8880 TC_data_rtp_tchh48 testcase </failure> </testcase> <testcase classname='BTS_Tests:hopping' name='TC_data_rtp_tchf24' time='1.805095'/> <testcase classname='BTS_Tests:hopping' name='TC_data_rtp_tchh24' time='5.363697'> <failure type='fail-verdict'>Timeout waiting for matching Downlink data frame BTS_Tests.ttcn:9599 BTS_Tests control part BTS_Tests.ttcn:8949 TC_data_rtp_tchh24 testcase </failure> </testcase> <testcase classname='BTS_Tests:hopping' name='TC_data_facch_tchf144' time='2.017878'/> <testcase classname='BTS_Tests:hopping' name='TC_data_facch_tchf96' time='2.026323'/> <testcase classname='BTS_Tests:hopping' name='TC_data_facch_tchf48' time='2.049606'/> <testcase classname='BTS_Tests:hopping' name='TC_data_facch_tchh48' time='2.148481'/> <testcase classname='BTS_Tests:hopping' name='TC_data_facch_tchf24' time='2.108748'/> <testcase classname='BTS_Tests:hopping' name='TC_data_facch_tchh24' time='2.180226'/> <testcase classname='BTS_Tests:hopping' name='TC_early_immediate_assignment' time='3.565445'/> <testcase classname='BTS_Tests:hopping' name='TC_acch_overpower_rxqual_thresh' time='9.079111'> <failure type='fail-verdict'>Timeout waiting for L1CTL DATA.ind BTS_Tests.ttcn:9610 BTS_Tests control part BTS_Tests.ttcn:9244 TC_acch_overpower_rxqual_thresh testcase </failure> </testcase> <testcase classname='BTS_Tests:hopping' name='TC_acch_overpower_rxqual_thresh_dtx' time='9.039922'> <failure type='fail-verdict'>Timeout waiting for L1CTL DATA.ind BTS_Tests.ttcn:9611 BTS_Tests control part BTS_Tests.ttcn:9285 TC_acch_overpower_rxqual_thresh_dtx testcase </failure> </testcase> <testcase classname='BTS_Tests:hopping' name='TC_acch_overpower_always_on_facch' time='9.026437'> <failure type='fail-verdict'>Timeout waiting for L1CTL DATA.ind BTS_Tests.ttcn:9612 BTS_Tests control part BTS_Tests.ttcn:9317 TC_acch_overpower_always_on_facch testcase </failure> </testcase> <testcase classname='BTS_Tests:hopping' name='TC_acch_overpower_always_on_sacch' time='9.015236'> <failure type='fail-verdict'>Timeout waiting for L1CTL DATA.ind BTS_Tests.ttcn:9613 BTS_Tests control part BTS_Tests.ttcn:9349 TC_acch_overpower_always_on_sacch testcase </failure> </testcase> <testcase classname='BTS_Tests:hopping' name='TC_acch_overpower_limit' time='9.024395'> <failure type='fail-verdict'>Timeout waiting for L1CTL DATA.ind BTS_Tests.ttcn:9614 BTS_Tests control part BTS_Tests.ttcn:9380 TC_acch_overpower_limit testcase </failure> </testcase> <testcase classname='BTS_Tests_SMSCB:hopping' name='TC_cbch_load_idle_no_cbch' time='11.190192'/> <testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch4_1block' time='14.259658'/> <testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch4_2block' time='14.187769'/> <testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch4_3block' time='14.198502'/> <testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch4_4block' time='14.172921'/> <testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch4_multi' time='20.202346'/> <testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch4_schedule' time='14.112418'/> <testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch4_default_only' time='16.179351'/> <testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch4_default_and_normal' time='17.181709'/> <testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch4_default_then_null' time='26.189720'/> <testcase classname='BTS_Tests_SMSCB:hopping' name='TC_cbc_sdcch4_load_idle' time='16.070744'/> <testcase classname='BTS_Tests_SMSCB:hopping' name='TC_cbc_sdcch4_load_overload' time='18.197366'/> <testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch8_1block' time='14.195525'/> <testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch8_2block' time='14.224404'/> <testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch8_3block' time='14.192284'/> <testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch8_4block' time='14.226541'/> <testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch8_multi' time='20.211261'/> <testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch8_schedule' time='14.128247'/> <testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch8_default_only' time='16.243630'/> <testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch8_default_and_normal' time='17.175690'/> <testcase classname='BTS_Tests_SMSCB:hopping' name='TC_cbc_sdcch8_load_idle' time='16.172973'/> <testcase classname='BTS_Tests_SMSCB:hopping' name='TC_cbc_sdcch8_load_overload' time='18.174852'/> <testcase classname='BTS_Tests_SMSCB:hopping' name='TC_etws_p1ro' time='12.184870'/> <testcase classname='BTS_Tests_SMSCB:hopping' name='TC_etws_p1ro_end' time='16.153449'/> <testcase classname='BTS_Tests_SMSCB:hopping' name='TC_etws_pcu' time='1.161566'/> <testcase classname='BTS_Tests_LAPDm:hopping' name='TC_sabm_ua_dcch_sapi0' time='2.907012'/> <testcase classname='BTS_Tests_LAPDm:hopping' name='TC_sabm_ua_dcch_sapi0_nopayload' time='21.568065'/> <testcase classname='BTS_Tests_LAPDm:hopping' name='TC_sabm_ua_dcch_sapi3' time='21.362301'/> <testcase classname='BTS_Tests_LAPDm:hopping' name='TC_sabm_ua_dcch_sapi4' time='21.364738'/> <testcase classname='BTS_Tests_LAPDm:hopping' name='TC_sabm_contention' time='21.303822'/> <testcase classname='BTS_Tests_LAPDm:hopping' name='TC_sabm_retransmit' time='3.165568'/> <testcase classname='BTS_Tests_LAPDm:hopping' name='TC_sabm_retransmit_bts' time='81.443678'/> <testcase classname='BTS_Tests_LAPDm:hopping' name='TC_sabm_invalid_resp' time='23.388963'/> <testcase classname='BTS_Tests_LAPDm:hopping' name='TC_sabm_dm' time='14.017617'/> <testcase classname='BTS_Tests_LAPDm:hopping' name='TC_establish_ign_first_sabm' time='7.947219'/> <testcase classname='BTS_Tests_LAPDm:hopping' name='TC_iframe_seq_and_ack' time='29.576281'> <failure type='fail-verdict'>Received RSL ERROR IND { msg_disc := { msg_group := RSL_MDISC_RLL (1), transparent := false }, msg_type := RSL_MT_ERROR_IND (3), ies := { { iei := RSL_IE_CHAN_NR (1), body := { chan_nr := { u := { sdcch8 := { tag := '01'B, sub_chan := 4 } }, tn := 6 } } }, { iei := RSL_IE_LINK_IDENT (2), body := { link_id := { c := FACCH_SDCCH (0), na := false, prio := SAPI0_PRIO_NORMAL (0), sapi := 0 } } }, { iei := RSL_IE_RLM_CAUSE (22), body := { rlm_cause := { len := 1, payload := '01'O } } } } } BTS_Tests_LAPDm.ttcn:1484 BTS_Tests_LAPDm control part BTS_Tests_LAPDm.ttcn:579 TC_iframe_seq_and_ack testcase </failure> </testcase> <testcase classname='BTS_Tests_LAPDm:hopping' name='TC_iframe_timer_recovery' time='24.586806'> <failure type='fail-verdict'>Received RSL ERROR IND { msg_disc := { msg_group := RSL_MDISC_RLL (1), transparent := false }, msg_type := RSL_MT_ERROR_IND (3), ies := { { iei := RSL_IE_CHAN_NR (1), body := { chan_nr := { u := { sdcch8 := { tag := '01'B, sub_chan := 4 } }, tn := 6 } } }, { iei := RSL_IE_LINK_IDENT (2), body := { link_id := { c := FACCH_SDCCH (0), na := false, prio := SAPI0_PRIO_NORMAL (0), sapi := 0 } } }, { iei := RSL_IE_RLM_CAUSE (22), body := { rlm_cause := { len := 1, payload := '01'O } } } } } BTS_Tests_LAPDm.ttcn:1485 BTS_Tests_LAPDm control part BTS_Tests_LAPDm.ttcn:685 TC_iframe_timer_recovery testcase </failure> </testcase> <testcase classname='BTS_Tests_LAPDm:hopping' name='TC_ns_seq_error' time='29.621045'> <failure type='fail-verdict'>"BTS_Tests.ttcn:732 : Tguard timeout" BTS_Tests_LAPDm.ttcn:1486 BTS_Tests_LAPDm control part BTS_Tests_LAPDm.ttcn:768 TC_ns_seq_error testcase </failure> </testcase> <testcase classname='BTS_Tests_LAPDm:hopping' name='TC_nr_seq_error' time='2.906095'/> <testcase classname='BTS_Tests_LAPDm:hopping' name='TC_rec_invalid_frame' time='119.169867'> <failure type='fail-verdict'>"BTS_Tests_LAPDm.ttcn:909 : Missing LAPDm_RR RSP" BTS_Tests_LAPDm.ttcn:1488 BTS_Tests_LAPDm control part BTS_Tests_LAPDm.ttcn:961 TC_rec_invalid_frame testcase </failure> </testcase> <testcase classname='BTS_Tests_LAPDm:hopping' name='TC_segm_concat_dcch' time='13.539977'> <failure type='fail-verdict'>"BTS_Tests_LAPDm.ttcn:1023 : received unexpected LAPDm { sacch := false, sapi := 0, lapdm := { ab := { addr := { spare := '0'B, lpd := 0, sapi := 0, c_r := false, ea := true }, ctrl := { s := { n_r := 1, p_f := false, s := '10'B, spare := '01'B } }, len := 0, m := false, el := 1, payload := ''O, padding := '2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B'O (\"++++++++++++++++++++\") } } }" BTS_Tests_LAPDm.ttcn:1489 BTS_Tests_LAPDm control part BTS_Tests_LAPDm.ttcn:1088 TC_segm_concat_dcch testcase </failure> </testcase> <testcase classname='BTS_Tests_LAPDm:hopping' name='TC_segm_concat_sacch' time='48.541748'> <failure type='fail-verdict'>"BTS_Tests_LAPDm.ttcn:1023 : received unexpected LAPDm { sacch := true, sapi := 0, lapdm := { ab := { addr := { spare := '0'B, lpd := 0, sapi := 0, c_r := false, ea := true }, ctrl := { u := { u := '011'B, p_f := true, u2 := '00'B, spare := '11'B } }, len := 0, m := false, el := 1, payload := ''O, padding := '2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B'O (\"++++++++++++++++++\") } } }" BTS_Tests_LAPDm.ttcn:1490 BTS_Tests_LAPDm control part BTS_Tests_LAPDm.ttcn:1093 TC_segm_concat_sacch testcase </failure> </testcase> <testcase classname='BTS_Tests_LAPDm:hopping' name='TC_t200_n200' time='39.804502'> <failure type='fail-verdict'>Missing RSL RLL ERROR INDICATION BTS_Tests_LAPDm.ttcn:1491 BTS_Tests_LAPDm control part BTS_Tests_LAPDm.ttcn:1182 TC_t200_n200 testcase </failure> </testcase> <testcase classname='BTS_Tests_LAPDm:hopping' name='TC_rr_response_frame_loss' time='6.665469'> <failure type='fail-verdict'>Timeout waiting for RR or REJ BTS_Tests_LAPDm.ttcn:1492 BTS_Tests_LAPDm control part BTS_Tests_LAPDm.ttcn:1239 TC_rr_response_frame_loss testcase </failure> </testcase> <testcase classname='BTS_Tests_LAPDm:hopping' name='TC_incorrect_cr' time='24.482814'> <failure type='fail-verdict'>"BTS_Tests.ttcn:732 : Tguard timeout" BTS_Tests_LAPDm.ttcn:1493 BTS_Tests_LAPDm control part BTS_Tests_LAPDm.ttcn:1293 TC_incorrect_cr testcase </failure> </testcase> <testcase classname='BTS_Tests_LAPDm:hopping' name='TC_sabm_incorrect_c' time='23.221346'> <failure type='fail-verdict'>"BTS_Tests.ttcn:732 : Tguard timeout" BTS_Tests_LAPDm.ttcn:1494 BTS_Tests_LAPDm control part BTS_Tests_LAPDm.ttcn:1341 TC_sabm_incorrect_c testcase </failure> </testcase> <testcase classname='BTS_Tests_LAPDm:hopping' name='TC_normal_reestablishment' time='16.110573'> <failure type='fail-verdict'>"BTS_Tests_LAPDm.ttcn:1023 : received unexpected LAPDm { sacch := false, sapi := 0, lapdm := { ab := { addr := { spare := '0'B, lpd := 0, sapi := 0, c_r := false, ea := true }, ctrl := { u := { u := '011'B, p_f := true, u2 := '00'B, spare := '11'B } }, len := 5, m := false, el := 1, payload := '828FD4C2D3'O, padding := '2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B'O (\"+++++++++++++++\") } } }" BTS_Tests_LAPDm.ttcn:1495 BTS_Tests_LAPDm control part BTS_Tests_LAPDm.ttcn:1403 TC_normal_reestablishment testcase </failure> </testcase> <testcase classname='BTS_Tests_LAPDm:hopping' name='TC_normal_reestablishment_state_unacked' time='12.385532'> <failure type='fail-verdict'>"BTS_Tests_LAPDm.ttcn:1023 : received unexpected LAPDm { sacch := false, sapi := 0, lapdm := { ab := { addr := { spare := '0'B, lpd := 0, sapi := 0, c_r := false, ea := true }, ctrl := { s := { n_r := 1, p_f := false, s := '10'B, spare := '01'B } }, len := 0, m := false, el := 1, payload := ''O, padding := '2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B'O (\"++++++++++++++++++++\") } } }" BTS_Tests_LAPDm.ttcn:1496 BTS_Tests_LAPDm control part BTS_Tests_LAPDm.ttcn:1470 TC_normal_reestablishment_state_unacked testcase </failure> </testcase> <testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_act_vff' time='2.433425'/> <testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_act_vhh' time='2.578732'/> <testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_act_hvhh' time='2.590143'/> <testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_act_dyn_ipa_vff' time='2.524930'/> <testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_act_dyn_osmo_vff' time='2.569483'/> <testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_act_dyn_osmo_vhh' time='2.590514'/> <testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_act_dyn_osmo_hvhh' time='2.588064'/> <testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_mode_modify_vff' time='1.303354'/> <testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_mode_modify_vhh' time='1.370379'/> <testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_mode_modify_hvhh' time='1.356280'/> <testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_mode_modify_dyn_ipa_vff' time='1.341490'/> <testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_mode_modify_dyn_osmo_vff' time='1.339755'/> <testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_mode_modify_dyn_osmo_vhh' time='1.402120'/> <testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_mode_modify_dyn_osmo_hvhh' time='1.365678'/> <testcase classname='BTS_Tests_ASCI:hopping' name='TC_vbs_notification' time='4.663694'/> <testcase classname='BTS_Tests_ASCI:hopping' name='TC_vgcs_uplink_free_and_busy' time='1.826568'/> <testcase classname='BTS_Tests_ASCI:hopping' name='TC_vgcs_talker_fail' time='17.009040'/> <testcase classname='BTS_Tests_ASCI:hopping' name='TC_vgcs_talker_est_rel' time='1.448237'/> <testcase classname='BTS_Tests_ASCI:hopping' name='TC_vgcs_listener_det' time='3.388861'/> </testsuite> <?xml version="1.0"?> <testsuite name='Titan' tests='17' failures='0' errors='0' skipped='0' inconc='0' time='96.00'> <testcase classname='BTS_Tests_OML' name='TC_wrong_mdisc' time='10.616577'/> <testcase classname='BTS_Tests_OML' name='TC_wrong_msgtype' time='0.703864'/> <testcase classname='BTS_Tests_OML' name='TC_short_length' time='0.718241'/> <testcase classname='BTS_Tests_OML' name='TC_long_length' time='0.691461'/> <testcase classname='BTS_Tests_OML' name='TC_wrong_placement' time='0.702266'/> <testcase classname='BTS_Tests_OML' name='TC_wrong_seq' time='0.683919'/> <testcase classname='BTS_Tests_OML' name='TC_wrong_obj_class' time='0.677186'/> <testcase classname='BTS_Tests_OML' name='TC_wrong_bts_nr' time='0.667177'/> <testcase classname='BTS_Tests_OML' name='TC_wrong_trx_nr' time='0.669756'/> <testcase classname='BTS_Tests_OML' name='TC_radio_carrier_opstart_noattr' time='0.677981'/> <testcase classname='BTS_Tests_OML' name='TC_bts_opstart' time='0.695797'/> <testcase classname='BTS_Tests_OML' name='TC_bts_opstart_noattr' time='0.666016'/> <testcase classname='BTS_Tests_OML' name='TC_ts_opstart' time='0.659369'/> <testcase classname='BTS_Tests_OML' name='TC_ts_opstart_noattr' time='0.682439'/> <testcase classname='BTS_Tests_OML' name='TC_initial_state_reports' time='0.671297'/> <testcase classname='BTS_Tests_OML' name='TC_ipa_osmo_pcu_anr_fwd' time='0.696470'/> <testcase classname='BTS_Tests_OML' name='TC_ipa_rsl_connect_nack' time='0.681792'/> </testsuite> + check_respawn_count + set +x [0;31m ================================================================ WARNING: osmo-bts was respawned 4 times (expected: 3)! (OS#6794) ================================================================ [0m+ RC=1 + uptime + grep --color=always -o load.* [01;31m[Kload average: 0.81, 0.98, 0.88[m[K + exit 1 Build step 'Execute shell' marked build as failure Recording test results [Checks API] No suitable checks publisher found.

1 0

Jenkins build became unstable: ttcn3-epdg-test #1076
by jenkins＠lists.osmocom.org 13 Sep '26

13 Sep '26

See <https://jenkins.osmocom.org/jenkins/job/ttcn3-epdg-test/1076/display/redire…>

1 1

Jenkins build became unstable: ttcn3-epdg-test-latest-net-next #480
by jenkins＠lists.osmocom.org 11 Sep '26

11 Sep '26

See <https://jenkins.osmocom.org/jenkins/job/ttcn3-epdg-test-latest-net-next/480…>

1 1

Build failed in Jenkins: master-osmo-pcu » master,0,raspbian,none,False #8384
by jenkins＠lists.osmocom.org 11 Sep '26

11 Sep '26

See <https://jenkins.osmocom.org/jenkins/job/master-osmo-pcu/FIRMWARE_VERSION=ma…> Changes: ------------------------------------------ [...truncated 139.92 KiB...] make[3]: Nothing to be done for 'dvi-am'. make[3]: Leaving directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/doc' make[2]: Leaving directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/doc' Making dvi in tests make[2]: Entering directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/tests' make[2]: Nothing to be done for 'dvi'. make[2]: Leaving directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/tests' Making dvi in contrib make[2]: Entering directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/contrib' Making dvi in systemd make[3]: Entering directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/contrib/systemd' make[3]: Nothing to be done for 'dvi'. make[3]: Leaving directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/contrib/systemd' make[3]: Entering directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/contrib' make[3]: Nothing to be done for 'dvi-am'. make[3]: Leaving directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/contrib' make[2]: Leaving directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/contrib' make[2]: Entering directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub' make[2]: Nothing to be done for 'dvi-am'. make[2]: Leaving directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub' make[1]: Leaving directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub' make[1]: Entering directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub' make check-recursive make[2]: Entering directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub' Making check in include make[3]: Entering directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/include' make[3]: Nothing to be done for 'check'. make[3]: Leaving directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/include' Making check in src make[3]: Entering directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/src' make[3]: Nothing to be done for 'check'. make[3]: Leaving directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/src' Making check in doc make[3]: Entering directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/doc' Making check in examples make[4]: Entering directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/doc/examples' make[4]: Nothing to be done for 'check'. make[4]: Leaving directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/doc/examples' Making check in manuals make[4]: Entering directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/doc/manuals' make[4]: Nothing to be done for 'check'. make[4]: Leaving directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/doc/manuals' make[4]: Entering directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/doc' make[4]: Nothing to be done for 'check-am'. make[4]: Leaving directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/doc' make[3]: Leaving directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/doc' Making check in tests make[3]: Entering directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/tests' make rlcmac/RLCMACTest alloc/AllocTest alloc/MslotTest tbf/TbfTest types/TypesTest ms/MsTest llist/LListTest llc/LlcTest codel/codel_test edge/EdgeTest bitcomp/BitcompTest fn/FnTest app_info/AppInfoTest ulc/PdchUlcTest make[4]: Entering directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/tests' CXX RLCMACTest.o CXX AllocTest.o CXX MslotTest.o CXX TbfTest.o CXX TypesTest.o CXX MsTest.o CXX LListTest.o CXX LlcTest.o CC codel_test.o CXX EdgeTest.o CXX BitcompTest.o CXX egprs_rlc_compression.o CXX FnTest.o CXX AppInfoTest.o CXX PdchUlcTest.o CXXLD rlcmac/RLCMACTest CXXLD alloc/AllocTest CXXLD alloc/MslotTest CXXLD tbf/TbfTest CXXLD types/TypesTest CXXLD ms/MsTest CXXLD llist/LListTest CXXLD llc/LlcTest CCLD codel/codel_test CXXLD edge/EdgeTest CXXLD bitcomp/BitcompTest CXXLD fn/FnTest CXXLD app_info/AppInfoTest CXXLD ulc/PdchUlcTest make[4]: Leaving directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/tests' make check-local make[4]: Entering directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/tests' /bin/bash '../../../tests/testsuite' ## ---------------------------------- ## ## osmo-pcu 1.5.3.1-379d7 test suite. ## ## ---------------------------------- ## Regression tests 1: rlcmac ok 2: multi_slot ok 3: ts_alloc ok 4: tbf ok 5: bitcomp ok 6: edge ok 7: types ok 8: ms ok 9: llc ok 10: llist ok 11: codel ok 12: fn ok 13: app_info ok 14: ulc ok ## ------------- ## ## Test results. ## ## ------------- ## All 14 tests were successful. make python-tests make[5]: Entering directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/tests' Not running python-based tests (determined at configure-time) make[5]: Leaving directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/tests' make[4]: Leaving directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/tests' make[3]: Leaving directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/tests' Making check in contrib make[3]: Entering directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/contrib' Making check in systemd make[4]: Entering directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/contrib/systemd' make[4]: Nothing to be done for 'check'. make[4]: Leaving directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/contrib/systemd' make[4]: Entering directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/contrib' make[4]: Nothing to be done for 'check-am'. make[4]: Leaving directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/contrib' make[3]: Leaving directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/contrib' make[3]: Entering directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub' make[3]: Nothing to be done for 'check-am'. make[3]: Leaving directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub' make[2]: Leaving directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub' make[1]: Leaving directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub' make[1]: Entering directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub' make install-recursive make[2]: Entering directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub' Making install in include make[3]: Entering directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/include' make[4]: Entering directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/include' make[4]: Nothing to be done for 'install-exec-am'. make[4]: Nothing to be done for 'install-data-am'. make[4]: Leaving directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/include' make[3]: Leaving directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/include' Making install in src make[3]: Entering directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/src' make[4]: Entering directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/src' make[4]: Nothing to be done for 'install-data-am'. /usr/bin/mkdir -p '/build/osmo-pcu-1.5.3.1-379d7/_inst/bin' /bin/bash ../libtool --mode=install /usr/bin/install -c osmo-pcu '/build/osmo-pcu-1.5.3.1-379d7/_inst/bin' libtool: install: /usr/bin/install -c osmo-pcu /build/osmo-pcu-1.5.3.1-379d7/_inst/bin/osmo-pcu make[4]: Leaving directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/src' make[3]: Leaving directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/src' Making install in doc make[3]: Entering directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/doc' Making install in examples make[4]: Entering directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/doc/examples' make[5]: Entering directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/doc/examples' make[5]: Nothing to be done for 'install-exec-am'. /usr/bin/mkdir -p '/build/osmo-pcu-1.5.3.1-379d7/_inst/share/doc/osmo-pcu/examples/osmo-pcu' /usr/bin/mkdir -p '/build/osmo-pcu-1.5.3.1-379d7/_inst/etc/osmocom' /usr/bin/install -c -m 644 ../../../../doc/examples/osmo-pcu.cfg '/build/osmo-pcu-1.5.3.1-379d7/_inst/share/doc/osmo-pcu/examples/osmo-pcu' /usr/bin/install -c -m 644 ../../../../doc/examples/osmo-pcu.cfg '/build/osmo-pcu-1.5.3.1-379d7/_inst/etc/osmocom' make[5]: Leaving directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/doc/examples' make[4]: Leaving directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/doc/examples' Making install in manuals make[4]: Entering directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/doc/manuals' make[5]: Entering directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/doc/manuals' make[5]: Nothing to be done for 'install-exec-am'. make[5]: Nothing to be done for 'install-data-am'. make[5]: Leaving directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/doc/manuals' make[4]: Leaving directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/doc/manuals' make[4]: Entering directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/doc' make[5]: Entering directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/doc' make[5]: Nothing to be done for 'install-exec-am'. make[5]: Nothing to be done for 'install-data-am'. make[5]: Leaving directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/doc' make[4]: Leaving directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/doc' make[3]: Leaving directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/doc' Making install in tests make[3]: Entering directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/tests' make[4]: Entering directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/tests' make[4]: Nothing to be done for 'install-exec-am'. make[4]: Nothing to be done for 'install-data-am'. make[4]: Leaving directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/tests' make[3]: Leaving directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/tests' Making install in contrib make[3]: Entering directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/contrib' Making install in systemd make[4]: Entering directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/contrib/systemd' make[5]: Entering directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/contrib/systemd' make[5]: Nothing to be done for 'install-exec-am'. /usr/bin/mkdir -p '/build/osmo-pcu-1.5.3.1-379d7/_inst/' /usr/bin/install -c -m 644 ../../../../contrib/systemd/osmo-pcu.service '/build/osmo-pcu-1.5.3.1-379d7/_inst/' make[5]: Leaving directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/contrib/systemd' make[4]: Leaving directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/contrib/systemd' make[4]: Entering directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/contrib' make[5]: Entering directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/contrib' make[5]: Nothing to be done for 'install-exec-am'. make[5]: Nothing to be done for 'install-data-am'. make[5]: Leaving directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/contrib' make[4]: Leaving directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/contrib' make[3]: Leaving directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/contrib' make[3]: Entering directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub' make[4]: Entering directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub' make[4]: Nothing to be done for 'install-exec-am'. make[4]: Nothing to be done for 'install-data-am'. make[4]: Leaving directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub' make[3]: Leaving directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub' make[2]: Leaving directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub' make[1]: Leaving directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub' make[1]: Entering directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub' Making installcheck in include make[2]: Entering directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/include' make[2]: Nothing to be done for 'installcheck'. make[2]: Leaving directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/include' Making installcheck in src make[2]: Entering directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/src' make[2]: Nothing to be done for 'installcheck'. make[2]: Leaving directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/src' Making installcheck in doc make[2]: Entering directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/doc' Making installcheck in examples make[3]: Entering directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/doc/examples' make[3]: Nothing to be done for 'installcheck'. make[3]: Leaving directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/doc/examples' Making installcheck in manuals make[3]: Entering directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/doc/manuals' make[3]: Nothing to be done for 'installcheck'. make[3]: Leaving directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/doc/manuals' make[3]: Entering directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/doc' make[3]: Nothing to be done for 'installcheck-am'. make[3]: Leaving directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/doc' make[2]: Leaving directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/doc' Making installcheck in tests make[2]: Entering directory '/build/osmo-pcu-1.5.3.1-379d7/_build/sub/tests' /bin/bash '../../../tests/testsuite' AUTOTEST_PATH='/build/osmo-pcu-1.5.3.1-379d7/_inst/bin' \ ## ---------------------------------- ## ## osmo-pcu 1.5.3.1-379d7 test suite. ## ## ---------------------------------- ## Regression tests 1: rlcmac ok 2: multi_slot ok 3: ts_alloc Build step 'Execute shell' marked build as failure $ ssh-agent -k unset SSH_AUTH_SOCK; unset SSH_AGENT_PID; echo Agent pid 1826270 killed; [ssh-agent] Stopped. Archiving artifacts

1 1

Build failed in Jenkins: ttcn3-smf-test-ogs-latest #1
by jenkins＠lists.osmocom.org 08 Sep '26

08 Sep '26

See <https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/1/display…> Changes: ------------------------------------------ [...truncated 109.52 KiB...] cmtc MC@29c9d80cf285: Downloading configuration file to all HCs. construct junitlogger Initializing `JUnitLogger' (v2.0): JUnitLogger writes JUnit-compatible XML MC@29c9d80cf285: Configuration file was processed on all HCs. MC@29c9d80cf285: Creating MTC on host 10.0.2.100. MC@29c9d80cf285: MTC is created. MC2> smtc Executing all items of [EXECUTE] section. MC2> MTC@29c9d80cf285: Starting external command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/ws/ttcn3-…> SMF_Tests.TC_tx_echo'. ------ SMF_Tests.TC_tx_echo ------ (13:08:30) load average: 2.37, 0.88, 0.83 /usr/bin/dumpcap -q -s 1520 -n -i any -w "<https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/1/artifac…"> ><https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/1/artifac…> 2>/tmp/cmderr & Waiting for packet dumper to start... 0 MTC@29c9d80cf285: External command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/ws/ttcn3-…> SMF_Tests.TC_tx_echo' was executed successfully (exit status: 0). MTC@29c9d80cf285: Test case TC_tx_echo started. TC_tx_echo-PFCP(6)@29c9d80cf285: f_PFCPEM_conns_bcast_add(): vc_conn mtc subscribed for broadcast TC_tx_echo-PFCP(6)@29c9d80cf285: PFCP_Emulation main() CLIENT.receive from mtc: { s_flag := '0'B, mp := '0'B, spare := '000'B, version := 1, message_type := 0, lengthIndicator := 0, seid := omit, sequence_number := 0, spare2 := '0000'B, mp_or_spare := '0000'B, message_body := { heartbeat_request := { recovery_time_stamp := { elementIdentifier := 96, lengthIndicator := 0, time_value := 3997170511 } } } } TC_tx_echo-PFCP(6)@29c9d80cf285: PFCP_Emulation main() PFCP.receive: { peer := { conn_id := 1, remote_name := "127.0.0.4", remote_port := 8805 }, pdu := { s_flag := '0'B, mp := '0'B, spare := '000'B, version := 1, message_type := 2, lengthIndicator := 12, seid := omit, sequence_number := 460001, spare2 := '0000'B, mp_or_spare := '0000'B, message_body := { heartbeat_response := { recovery_time_stamp := { elementIdentifier := 96, lengthIndicator := 4, time_value := 3997170509 } } } } } TC_tx_echo-PFCP(6)@29c9d80cf285: found destination mtc TC_tx_echo-PFCP(6)@29c9d80cf285: PFCP_Emulation main() PFCP.receive: { peer := { conn_id := 1, remote_name := "127.0.0.4", remote_port := 8805 }, pdu := { s_flag := '0'B, mp := '0'B, spare := '000'B, version := 1, message_type := 5, lengthIndicator := 26, seid := omit, sequence_number := 1, spare2 := '0000'B, mp_or_spare := '0000'B, message_body := { pfcp_association_setup_request := { node_id := { elementIdentifier := 60, lengthIndicator := 5, node_id_type := 0, spare := '0000'B, node_id_value := '7F000004'O }, time_stamp := { elementIdentifier := 96, lengthIndicator := 4, time_value := 3997170509 }, up_function_features := omit, cp_function_features := { elementIdentifier := 89, lengthIndicator := 1, load := '0'B, ovrl := '0'B, epfar := '0'B, sset := '0'B, bundl := '0'B, mpas := '0'B, ardr := '0'B, uiaur := '0'B, psucc := omit, rpgur := omit, papfd := omit, spare6_4 := omit, spare6_5 := omit, spare6_6 := omit, spare6_7 := omit, spare6_8 := omit }, UP_IP_resource_list := omit } } } } TC_tx_echo-PFCP(6)@29c9d80cf285: broadcasting to mtc MTC@29c9d80cf285: setverdict(pass): none -> pass TC_tx_echo-PFCP(6)@29c9d80cf285: PFCP_Emulation main() CLIENT.receive from mtc: { s_flag := '0'B, mp := '0'B, spare := '000'B, version := 1, message_type := 0, lengthIndicator := 0, seid := omit, sequence_number := 1, spare2 := '0000'B, mp_or_spare := '0000'B, message_body := { pfcp_association_setup_response := { node_id := { elementIdentifier := 60, lengthIndicator := 0, node_id_type := 0, spare := '0000'B, node_id_value := '7F000007'O }, cause := { elementIdentifier := 19, lengthIndicator := 0, causeValue := '01'O }, time_stamp := { elementIdentifier := 96, lengthIndicator := 0, time_value := 3997170511 }, up_function_features := { elementIdentifier := 43, lengthIndicator := 0, bucp := '0'B, ddnd := '0'B, dlbd := '0'B, trst := '0'B, ftup := '1'B, pfdm := '0'B, heeu := '0'B, treu := '0'B, empu := '0'B, pdiu := '0'B, udbc := '0'B, quoac := '0'B, trace := '0'B, frrt := '0'B, pfde := '0'B, epfar := '0'B, dpdra := '0'B, adpdp := '0'B, ueip := '0'B, sset := '0'B, mnop := '0'B, mte := '0'B, bundl := '0'B, gcom := '0'B, mpas := '0'B, rttl := '0'B, vtime := '0'B, norp := '0'B, iptv := '0'B, ip6pl := '0'B, tsn := '0'B, mptcp := '0'B, atsss_ll := '0'B, qfqm := '0'B, gpqm := '0'B, mt_edt := '0'B, ciot := '0'B, ethar := '0'B, ddds := '0'B, rds := '0'B, rttwp := '0'B, quasf := '0'B, nspoc := '0'B, l2tp := '0'B, upber := '0'B, resps := '0'B, iprep := '0'B, dnsts := '0'B, drqos := '0'B, mbsn4 := '0'B, psuprm := '0'B, epppi := '0'B, ratp := '0'B, upidp := '0'B, afsfc := '0'B, mquic_udp := '0'B, redsm := '0'B, dbdm := '0'B, tscts := '0'B, drtsc := '0'B, n6jedb := '0'B, qmcon := '0'B, detnet := '0'B, eml4s := '0'B, pdusm := '0'B, cn_tl := '0'B, qmdrm := '0'B, edbnc := '0'B, mt_sdt := '0'B, upsbies := '0'B, umn6ip := '0'B, un6tu := '0'B, mbsch := '0'B, un6dm := '0'B, natpub := '0'B, ushph := '0'B, mpquic_ip := '0'B, mpquic_e := '0'B, dyntr := '0'B, muxmf := '0'B, conudp := '0'B, moq := '0'B, ulm := '0'B, psitlm := '0'B, udpopt := '0'B, qmabr := '0'B, papfd := '0'B, spare := '0'B }, cp_function_features := omit, UP_IP_resource_list := omit } } } [32m08/31 13:08:32.251[0m: [[33msmf[0m] [1;32mINFO[0m: PFCP associated [127.0.0.7]:8805 (../src/smf/pfcp-sm.c:189) TC_tx_echo-PFCP(6)@29c9d80cf285: f_PFCPEM_conns_bcast_del(): vc_conn mtc unsubscribed from broadcast TC_tx_echo-PFCP(6)@29c9d80cf285: Rx Heartbeat Req: { s_flag := '0'B, mp := '0'B, spare := '000'B, version := 1, message_type := 1, lengthIndicator := 12, seid := omit, sequence_number := 2, spare2 := '0000'B, mp_or_spare := '0000'B, message_body := { heartbeat_request := { recovery_time_stamp := { elementIdentifier := 96, lengthIndicator := 4, time_value := 3997170509 } } } } TC_tx_echo(7)@29c9d80cf285: Warning: sizes of 'struct sctp_event_subscribe': compile-time 14, kernel: 14 TC_tx_echo(8)@29c9d80cf285: Warning: sizes of 'struct sctp_event_subscribe': compile-time 14, kernel: 14 TC_tx_echo(9)@29c9d80cf285: Warning: sizes of 'struct sctp_event_subscribe': compile-time 14, kernel: 14 [32m08/31 13:08:32.432[0m: [[33mdiam[0m] [1;32mINFO[0m: CONNECTED TO 'aaa.localdomain' (SCTP,soc#17): (../lib/diameter/common/logger.c:81) [32m08/31 13:08:33.466[0m: [[33mdiam[0m] [1;32mINFO[0m: CONNECTED TO 'ocs.localdomain' (SCTP,soc#18): (../lib/diameter/common/logger.c:81) [32m08/31 13:08:33.710[0m: [[33mdiam[0m] [1;32mINFO[0m: CONNECTED TO 'pcrf.localdomain' (SCTP,soc#19): (../lib/diameter/common/logger.c:81) MTC@29c9d80cf285: setverdict(pass): pass -> pass, component reason not changed GTP1U_EM(4)@29c9d80cf285: Final verdict of PTC: none GTP2C_EM(3)@29c9d80cf285: Final verdict of PTC: none MutexDispCT-TC_tx_echo(5)@29c9d80cf285: Final verdict of PTC: none [32m08/31 13:08:34.736[0m: [[33mdiam[0m] [1;33mERROR[0m: pid:PSM/aaa.localdomain in fd_psm_change_state@p_psm.c:284: 'STATE_OPEN' -> 'STATE_CLOSED' 'aaa.localdomain' ((null):0) [32m08/31 13:08:34.736[0m: [[33mdiam[0m] [1;33mERROR[0m: pid:PSM/pcrf.localdomain in fd_psm_change_state@p_psm.c:284: 'STATE_OPEN' -> 'STATE_CLOSED' 'pcrf.localdomain' ((null):0) TC_tx_echo-PFCP(6)@29c9d80cf285: Final verdict of PTC: none [32m08/31 13:08:34.736[0m: [[33mdiam[0m] [1;33mERROR[0m: pid:PSM/ocs.localdomain in fd_psm_change_state@p_psm.c:284: 'STATE_OPEN' -> 'STATE_CLOSED' 'ocs.localdomain' ((null):0) TC_tx_echo(8)@29c9d80cf285: Final verdict of PTC: none TC_tx_echo(7)@29c9d80cf285: Final verdict of PTC: none TC_tx_echo(9)@29c9d80cf285: Final verdict of PTC: none MTC@29c9d80cf285: Setting final verdict of the test case. MTC@29c9d80cf285: Local verdict of MTC: pass MTC@29c9d80cf285: Local verdict of PTC GTP2C_EM(3): none (pass -> pass) MTC@29c9d80cf285: Local verdict of PTC GTP1U_EM(4): none (pass -> pass) MTC@29c9d80cf285: Local verdict of PTC MutexDispCT-TC_tx_echo(5): none (pass -> pass) MTC@29c9d80cf285: Local verdict of PTC TC_tx_echo-PFCP(6): none (pass -> pass) MTC@29c9d80cf285: Local verdict of PTC TC_tx_echo(7): none (pass -> pass) MTC@29c9d80cf285: Local verdict of PTC TC_tx_echo(8): none (pass -> pass) MTC@29c9d80cf285: Local verdict of PTC TC_tx_echo(9): none (pass -> pass) MTC@29c9d80cf285: Test case TC_tx_echo finished. Verdict: pass MTC@29c9d80cf285: Starting external command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/ws/ttcn3-…> SMF_Tests.TC_tx_echo pass'. (13:08:34) load average: 2.26, 0.89, 0.83 [1;32m====== SMF_Tests.TC_tx_echo pass ======[0m Waiting for packet dumper to finish... 0 (prev_count=-1, count=8896) Waiting for packet dumper to finish... 1 (prev_count=8896, count=9852) MTC@29c9d80cf285: External command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/ws/ttcn3-…> SMF_Tests.TC_tx_echo pass' was executed successfully (exit status: 0). MTC@29c9d80cf285: Starting external command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/ws/ttcn3-…> SMF_Tests.TC_createSession_deleteSession'. ------ SMF_Tests.TC_createSession_deleteSession ------ (13:08:36) load average: 2.08, 0.87, 0.82 /usr/bin/dumpcap -q -s 1520 -n -i any -w "<https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/1/artifac…"> ><https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/1/artifac…> 2>/tmp/cmderr & Waiting for packet dumper to start... 0 MTC@29c9d80cf285: External command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/ws/ttcn3-…> SMF_Tests.TC_createSession_deleteSession' was executed successfully (exit status: 0). MTC@29c9d80cf285: Test case TC_createSession_deleteSession started. TC_createSession_deleteSession-PFCP(13)@29c9d80cf285: f_PFCPEM_conns_bcast_add(): vc_conn mtc subscribed for broadcast TC_createSession_deleteSession-PFCP(13)@29c9d80cf285: PFCP_Emulation main() CLIENT.receive from mtc: { s_flag := '0'B, mp := '0'B, spare := '000'B, version := 1, message_type := 0, lengthIndicator := 0, seid := omit, sequence_number := 0, spare2 := '0000'B, mp_or_spare := '0000'B, message_body := { heartbeat_request := { recovery_time_stamp := { elementIdentifier := 96, lengthIndicator := 0, time_value := 3997170517 } } } } [32m08/31 13:08:37.889[0m: [[33mpfcp[0m] [1;33mERROR[0m: Remote PFCP restarted [3997170511<3997170517] in Heartbeat REQ (../lib/pfcp/handler.c:39) [32m08/31 13:08:37.889[0m: [[33msmf[0m] [1;32mINFO[0m: PFCP de-associated [127.0.0.7]:8805 (../src/smf/pfcp-sm.c:205) TC_createSession_deleteSession-PFCP(13)@29c9d80cf285: PFCP_Emulation main() PFCP.receive: { peer := { conn_id := 1, remote_name := "127.0.0.4", remote_port := 8805 }, pdu := { s_flag := '0'B, mp := '0'B, spare := '000'B, version := 1, message_type := 2, lengthIndicator := 12, seid := omit, sequence_number := 6510001, spare2 := '0000'B, mp_or_spare := '0000'B, message_body := { heartbeat_response := { recovery_time_stamp := { elementIdentifier := 96, lengthIndicator := 4, time_value := 3997170509 } } } } } TC_createSession_deleteSession-PFCP(13)@29c9d80cf285: found destination mtc TC_createSession_deleteSession-PFCP(13)@29c9d80cf285: PFCP_Emulation main() PFCP.receive: { peer := { conn_id := 1, remote_name := "127.0.0.4", remote_port := 8805 }, pdu := { s_flag := '0'B, mp := '0'B, spare := '000'B, version := 1, message_type := 5, lengthIndicator := 26, seid := omit, sequence_number := 3, spare2 := '0000'B, mp_or_spare := '0000'B, message_body := { pfcp_association_setup_request := { node_id := { elementIdentifier := 60, lengthIndicator := 5, node_id_type := 0, spare := '0000'B, node_id_value := '7F000004'O }, time_stamp := { elementIdentifier := 96, lengthIndicator := 4, time_value := 3997170509 }, up_function_features := omit, cp_function_features := { elementIdentifier := 89, lengthIndicator := 1, load := '0'B, ovrl := '0'B, epfar := '0'B, sset := '0'B, bundl := '0'B, mpas := '0'B, ardr := '0'B, uiaur := '0'B, psucc := omit, rpgur := omit, papfd := omit, spare6_4 := omit, spare6_5 := omit, spare6_6 := omit, spare6_7 := omit, spare6_8 := omit }, UP_IP_resource_list := omit } } } } TC_createSession_deleteSession-PFCP(13)@29c9d80cf285: broadcasting to mtc MTC@29c9d80cf285: setverdict(pass): none -> pass TC_createSession_deleteSession-PFCP(13)@29c9d80cf285: PFCP_Emulation main() CLIENT.receive from mtc: { s_flag := '0'B, mp := '0'B, spare := '000'B, version := 1, message_type := 0, lengthIndicator := 0, seid := omit, sequence_number := 3, spare2 := '0000'B, mp_or_spare := '0000'B, message_body := { pfcp_association_setup_response := { node_id := { elementIdentifier := 60, lengthIndicator := 0, node_id_type := 0, spare := '0000'B, node_id_value := '7F000007'O }, cause := { elementIdentifier := 19, lengthIndicator := 0, causeValue := '01'O }, time_stamp := { elementIdentifier := 96, lengthIndicator := 0, time_value := 3997170517 }, up_function_features := { elementIdentifier := 43, lengthIndicator := 0, bucp := '0'B, ddnd := '0'B, dlbd := '0'B, trst := '0'B, ftup := '1'B, pfdm := '0'B, heeu := '0'B, treu := '0'B, empu := '0'B, pdiu := '0'B, udbc := '0'B, quoac := '0'B, trace := '0'B, frrt := '0'B, pfde := '0'B, epfar := '0'B, dpdra := '0'B, adpdp := '0'B, ueip := '0'B, sset := '0'B, mnop := '0'B, mte := '0'B, bundl := '0'B, gcom := '0'B, mpas := '0'B, rttl := '0'B, vtime := '0'B, norp := '0'B, iptv := '0'B, ip6pl := '0'B, tsn := '0'B, mptcp := '0'B, atsss_ll := '0'B, qfqm := '0'B, gpqm := '0'B, mt_edt := '0'B, ciot := '0'B, ethar := '0'B, ddds := '0'B, rds := '0'B, rttwp := '0'B, quasf := '0'B, nspoc := '0'B, l2tp := '0'B, upber := '0'B, resps := '0'B, iprep := '0'B, dnsts := '0'B, drqos := '0'B, mbsn4 := '0'B, psuprm := '0'B, epppi := '0'B, ratp := '0'B, upidp := '0'B, afsfc := '0'B, mquic_udp := '0'B, redsm := '0'B, dbdm := '0'B, tscts := '0'B, drtsc := '0'B, n6jedb := '0'B, qmcon := '0'B, detnet := '0'B, eml4s := '0'B, pdusm := '0'B, cn_tl := '0'B, qmdrm := '0'B, edbnc := '0'B, mt_sdt := '0'B, upsbies := '0'B, umn6ip := '0'B, un6tu := '0'B, mbsch := '0'B, un6dm := '0'B, natpub := '0'B, ushph := '0'B, mpquic_ip := '0'B, mpquic_e := '0'B, dyntr := '0'B, muxmf := '0'B, conudp := '0'B, moq := '0'B, ulm := '0'B, psitlm := '0'B, udpopt := '0'B, qmabr := '0'B, papfd := '0'B, spare := '0'B }, cp_function_features := omit, UP_IP_resource_list := omit } } } [32m08/31 13:08:37.891[0m: [[33msmf[0m] [1;32mINFO[0m: PFCP associated [127.0.0.7]:8805 (../src/smf/pfcp-sm.c:189) [32m08/31 13:08:37.891[0m: [[33msmf[0m] [1;33mERROR[0m: PFCP restoration (../src/smf/pfcp-sm.c:199) TC_createSession_deleteSession-PFCP(13)@29c9d80cf285: f_PFCPEM_conns_bcast_del(): vc_conn mtc unsubscribed from broadcast TC_createSession_deleteSession-PFCP(13)@29c9d80cf285: Rx Heartbeat Req: { s_flag := '0'B, mp := '0'B, spare := '000'B, version := 1, message_type := 1, lengthIndicator := 12, seid := omit, sequence_number := 4, spare2 := '0000'B, mp_or_spare := '0000'B, message_body := { heartbeat_request := { recovery_time_stamp := { elementIdentifier := 96, lengthIndicator := 4, time_value := 3997170509 } } } } TC_createSession_deleteSession(14)@29c9d80cf285: Warning: sizes of 'struct sctp_event_subscribe': compile-time 14, kernel: 14 TC_createSession_deleteSession(15)@29c9d80cf285: Warning: sizes of 'struct sctp_event_subscribe': compile-time 14, kernel: 14 TC_createSession_deleteSession(16)@29c9d80cf285: Warning: sizes of 'struct sctp_event_subscribe': compile-time 14, kernel: 14 [32m08/31 13:08:38.457[0m: [[33mdiam[0m] [1;32mINFO[0m: CONNECTED TO 'aaa.localdomain' (SCTP,soc#17): (../lib/diameter/common/logger.c:81) [32m08/31 13:08:39.672[0m: [[33mdiam[0m] [1;32mINFO[0m: CONNECTED TO 'ocs.localdomain' (SCTP,soc#18): (../lib/diameter/common/logger.c:81) [32m08/31 13:08:40.739[0m: [[33msbi[0m] [1;36mWARNING[0m: [0f859ec4-a53d-41f1-a76c-dda2e2791897] Retry registration with NRF (../lib/sbi/nf-sm.c:282) [32m08/31 13:08:40.739[0m: [[33msbi[0m] [1;36mWARNING[0m: Could not connect to server (7): Failed to connect to 127.0.1.10 port 7777 after 0 ms: Could not connect to server (../lib/sbi/client.c:767) [32m08/31 13:08:40.739[0m: [[33msbi[0m] [1;36mWARNING[0m: ogs_sbi_client_handler() failed [-1] (../lib/sbi/path.c:62) [32m08/31 13:08:40.944[0m: [[33mdiam[0m] [1;32mINFO[0m: CONNECTED TO 'pcrf.localdomain' (SCTP,soc#19): (../lib/diameter/common/logger.c:81) TC_createSession_deleteSession(14)@29c9d80cf285: Created Expect[0] for '001010123456789'H to be handled at TC_createSession_deleteSession(17) TC_createSession_deleteSession(15)@29c9d80cf285: Created Expect[0] for '001010123456789'H to be handled at TC_createSession_deleteSession(17) TC_createSession_deleteSession(16)@29c9d80cf285: Created Expect[0] for '001010123456789'H to be handled at TC_createSession_deleteSession(17) TC_createSession_deleteSession(17)@29c9d80cf285: f_Mutex_lock(): mutex acquired @ "SMF_Session_CT.ttcn":402 [32m08/31 13:08:42.037[0m: [[33msmf[0m] [1;32mINFO[0m: [Added] Number of SMF-UEs is now 1 (../src/smf/context.c:1069) [32m08/31 13:08:42.037[0m: [[33msmf[0m] [1;32mINFO[0m: [Added] Number of SMF-Sessions is now 1 (../src/smf/context.c:3612) [32m08/31 13:08:42.037[0m: [[33msmf[0m] [1;32mINFO[0m: UE IMSI[001010123456789] APN[internet] IPv4[10.45.0.2] IPv6[] (../src/smf/s5c-handler.c:312) TC_createSession_deleteSession(15)@29c9d80cf285: Found Expect[0] for { version := 1, message_length := 712, RPETxxxx := '11000000'B, command_code := Credit_Control (272), application_id := '00000004'O, hop_by_hop_id := '10760088'O, end_to_end_id := 'CCDEDA22'O, avps := { { avp := { avp_header := { avp_code := { vendor_id_NONE := avp_code_BASE_NONE_Session_Id (263) }, VMPxxxxx := '01000000'B, avp_length := 43, vendor_id := omit }, avp_data := { avp_BASE_NONE_Session_Id := '736D662E6C6F63616C646F6D61696E3B313738383138313730393B323B6170705F6779'O ("smf.localdomain;1788181709;2;app_gy") } } }, { avp := { avp_header := { avp_code := { vendor_id_NONE := avp_code_BASE_NONE_Origin_Host (264) }, VMPxxxxx := '01000000'B, avp_length := 23, vendor_id := omit }, avp_data := { avp_BASE_NONE_Origin_Host := "smf.localdomain" } } }, { avp := { avp_header := { avp_code := { vendor_id_NONE := avp_code_BASE_NONE_Origin_Realm (296) }, VMPxxxxx := '01000000'B, avp_length := 19, vendor_id := omit }, avp_data := { avp_BASE_NONE_Origin_Realm := "localdomain" } } }, { avp := { avp_header := { avp_code := { vendor_id_NONE := avp_code_BASE_NONE_Destination_Realm (283) }, VMPxxxxx := '01000000'B, avp_length := 19, vendor_id := omit }, avp_data := { avp_BASE_NONE_Destination_Realm := "localdomain" } } }, { avp := { avp_header := { avp_code := { vendor_id_NONE := avp_code_BASE_NONE_Auth_Application_Id (258) }, VMPxxxxx := '01000000'B, avp_length := 12, vendor_id := omit }, avp_data := { avp_BASE_NONE_Auth_Application_Id := '00000004'O } } }, { avp := { avp_header := { avp_code := { vendor_id_NONE := avp_code_DCC_NONE_Service_Context_Id (461) }, VMPxxxxx := '01000000'B, avp_length := 22, vendor_id := omit }, avp_data := { avp_DCC_NONE_Service_Context_Id := '333232353140336770702E6F7267'O ("32251(a)3gpp.org") } } }, { avp := { avp_header := { avp_code := { vendor_id_NONE := avp_code_DCC_NONE_CC_Request_Type (416) }, VMPxxxxx := '01000000'B, avp_length := 12, vendor_id := omit }, avp_data := { avp_DCC_NONE_CC_Request_Type := INITIAL_REQUEST (1) } } }, { avp := { avp_header := { avp_code := { vendor_id_NONE := avp_code_DCC_NONE_CC_Request_Number (415) }, VMPxxxxx := '01000000'B, avp_length := 12, vendor_id := omit }, avp_data := { avp_DCC_NONE_CC_Request_Number := '00000000'O } } }, { avp := { avp_header := { avp_code := { vendor_id_NONE := avp_code_BASE_NONE_Event_Timestamp (55) }, VMPxxxxx := '01000000'B, avp_length := 12, vendor_id := omit }, avp_data := { avp_BASE_NONE_Event_Timestamp := 'EE3FFB5A'O } } }, { avp := { avp_header := { avp_code := { vendor_id_NONE := avp_code_DCC_NONE_Subscription_Id (443) }, VMPxxxxx := '01000000'B, avp_length := 44, vendor_id := omit }, avp_data := { avp_DCC_NONE_Subscription_Id := { { avp := { avp_header := { avp_code := { vendor_id_NONE := avp_code_DCC_NONE_Subscription_Id_Type (450) }, VMPxxxxx := '01000000'B, avp_length := 12, vendor_id := omit }, avp_data := { avp_DCC_NONE_Subscription_Id_Type := END_USER_IMSI (1) } } }, { avp := { avp_header := { avp_code := { vendor_id_NONE := avp_code_DCC_NONE_Subscription_Id_Data (444) }, VMPxxxxx := '01000000'B, avp_length := 23, vendor_id := omit }, avp_data := { avp_DCC_NONE_Subscription_Id_Data := '303031303130313233343536373839'O ("001010123456789") } } } } } } }, { avp := { avp_header := { avp_code := { vendor_id_NONE := avp_code_DCC_NONE_Subscription_Id (443) }, VMPxxxxx := '01000000'B, avp_length := 32, vendor_id := omit }, avp_data := { avp_DCC_NONE_Subscription_Id := { { avp := { avp_header := { avp_code := { vendor_id_NONE := avp_code_DCC_NONE_Subscription_Id_Type (450) }, VMPxxxxx := '01000000'B, avp_length := 12, vendor_id := omit }, avp_data := { avp_DCC_NONE_Subscription_Id_Type := END_USER_E164 (0) } } }, { avp := { avp_header := { avp_code := { vendor_id_NONE := avp_code_DCC_NONE_Subscription_Id_Data (444) }, VMPxxxxx := '01000000'B, avp_length := 12, vendor_id := omit }, avp_data := { avp_DCC_NONE_Subscription_Id_Data := '31323334'O ("1234") } } } } } } }, { avp := { avp_header := { avp_code := { vendor_id_NONE := avp_code_DCC_NONE_Requested_Action (436) }, VMPxxxxx := '01000000'B, avp_length := 12, vendor_id := omit }, avp_data := { avp_DCC_NONE_Requested_Action := DIRECT_DEBITING (0) } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_DCA_3GPP_AoC_Request_Type (2055) }, VMPxxxxx := '11000000'B, avp_length := 16, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_DCA_3GPP_AoC_Request_Type := AoC_FULL (1) } } }, { avp := { avp_header := { avp_code := { vendor_id_NONE := avp_code_DCC_NONE_Multiple_Services_Indicator (455) }, VMPxxxxx := '01000000'B, avp_length := 12, vendor_id := omit }, avp_data := { avp_DCC_NONE_Multiple_Services_Indicator := MULTIPLE_SERVICES_NOT_SUPPORTED (0) } } }, { avp := { avp_header := { avp_code := { vendor_id_NONE := avp_code_DCC_NONE_Multiple_Services_Credit_Control (456) }, VMPxxxxx := '01000000'B, avp_length := 152, vendor_id := omit }, avp_data := { avp_DCC_NONE_Multiple_Services_Credit_Control := { { avp := { avp_header := { avp_code := { vendor_id_NONE := avp_code_DCC_NONE_Requested_Service_Unit (437) }, VMPxxxxx := '01000000'B, avp_length := 24, vendor_id := omit }, avp_data := { avp_DCC_NONE_Requested_Service_Unit := { { avp := { avp_header := { avp_code := { vendor_id_NONE := avp_code_DCC_NONE_CC_Total_Octets (421) }, VMPxxxxx := '01000000'B, avp_length := 16, vendor_id := omit }, avp_data := { avp_DCC_NONE_CC_Total_Octets := '00000000000F4240'O } } } } } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_PCC_3GPP_QoS_Information (1016) }, VMPxxxxx := '11000000'B, avp_length := 120, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_PCC_3GPP_QoS_Information := { { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_PCC_3GPP_QoS_Class_Identifier (1028) }, VMPxxxxx := '11000000'B, avp_length := 16, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_PCC_3GPP_QoS_Class_Identifier := '00000009'O } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_PCC_3GPP_Allocation_Retention_Priority (1034) }, VMPxxxxx := '10000000'B, avp_length := 60, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_PCC_3GPP_Allocation_Retention_Priority := { { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_PCC_3GPP_Priority_Level (1046) }, VMPxxxxx := '10000000'B, avp_length := 16, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_PCC_3GPP_Priority_Level := '00000009'O } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_PCC_3GPP_Pre_emption_Capability (1047) }, VMPxxxxx := '10000000'B, avp_length := 16, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_PCC_3GPP_Pre_emption_Capability := PRE_EMPTION_CAPABILITY_ENABLED (0) } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_PCC_3GPP_Pre_emption_Vulnerability (1048) }, VMPxxxxx := '10000000'B, avp_length := 16, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_PCC_3GPP_Pre_emption_Vulnerability := PRE_EMPTION_VULNERABILITY_ENABLED (0) } } } } } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_PCC_3GPP_APN_Aggregate_Max_Bitrate_UL (1041) }, VMPxxxxx := '10000000'B, avp_length := 16, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_PCC_3GPP_APN_Aggregate_Max_Bitrate_UL := '061A8000'O } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_PCC_3GPP_APN_Aggregate_Max_Bitrate_DL (1040) }, VMPxxxxx := '10000000'B, avp_length := 16, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_PCC_3GPP_APN_Aggregate_Max_Bitrate_DL := '061A8000'O } } } } } } } } } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_DCA_3GPP_Service_Information (873) }, VMPxxxxx := '11000000'B, avp_length := 244, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_DCA_3GPP_Service_Information := { { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_DCA_3GPP_PS_Information (874) }, VMPxxxxx := '11000000'B, avp_length := 232, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_DCA_3GPP_PS_Information := { { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_GI_3GPP_3GPP_Charging_Id (2) }, VMPxxxxx := '11000000'B, avp_length := 16, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_GI_3GPP_3GPP_Charging_Id := '00000001'O } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_GI_3GPP_3GPP_PDP_Type (3) }, VMPxxxxx := '11000000'B, avp_length := 16, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_GI_3GPP_3GPP_PDP_Type := IPv4 (0) } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_DCA_3GPP_PDP_Address (1227) }, VMPxxxxx := '11000000'B, avp_length := 18, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_DCA_3GPP_PDP_Address := { address_type := IP (1), address_data := '0A2D0002'O } } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_DCA_3GPP_SGSN_Address (1228) }, VMPxxxxx := '11000000'B, avp_length := 18, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_DCA_3GPP_SGSN_Address := { address_type := IP (1), address_data := '7F0000CA'O } } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_DCA_3GPP_GGSN_Address (847) }, VMPxxxxx := '11000000'B, avp_length := 18, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_DCA_3GPP_GGSN_Address := { address_type := IP (1), address_data := '7F000004'O } } } }, { avp := { avp_header := { avp_code := { vendor_id_NONE := avp_code_DNAS_NONE_Called_Station_Id (30) }, VMPxxxxx := '01000000'B, avp_length := 16, vendor_id := omit }, avp_data := { avp_DNAS_NONE_Called_Station_Id := '696E7465726E6574'O ("internet") } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_GI_3GPP_3GPP_Selection_Mode (12) }, VMPxxxxx := '11000000'B, avp_length := 13, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_GI_3GPP_3GPP_Selection_Mode := '30'O ("0") } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_GI_3GPP_3GPP_Charging_Characteristics (13) }, VMPxxxxx := '11000000'B, avp_length := 16, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_GI_3GPP_3GPP_Charging_Characteristics := '30303030'O ("0000") } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_GI_3GPP_3GPP_SGSN_MCC_MNC (18) }, VMPxxxxx := '11000000'B, avp_length := 17, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_GI_3GPP_3GPP_SGSN_MCC_MNC := '3030313031'O ("00101") } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_GI_3GPP_3GPP_NSAPI (10) }, VMPxxxxx := '11000000'B, avp_length := 13, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_GI_3GPP_3GPP_NSAPI := '05'O } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_GI_3GPP_3GPP_User_Location_Info (22) }, VMPxxxxx := '11000000'B, avp_length := 25, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_GI_3GPP_3GPP_User_Location_Info := '8200F110000100F11000000017'O } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_GI_3GPP_3GPP_RAT_Type (21) }, VMPxxxxx := '11000000'B, avp_length := 13, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_GI_3GPP_3GPP_RAT_Type := '06'O } } } } } } } } } } } } } handled at TC_createSession_deleteSession(17) TC_createSession_deleteSession(14)@29c9d80cf285: Found Expect[0] for { version := 1, message_length := 668, RPETxxxx := '11000000'B, command_code := Credit_Control (272), application_id := '01000016'O, hop_by_hop_id := '1F84E2D0'O, end_to_end_id := 'CCDEDA21'O, avps := { { avp := { avp_header := { avp_code := { vendor_id_NONE := avp_code_BASE_NONE_Session_Id (263) }, VMPxxxxx := '01000000'B, avp_length := 43, vendor_id := omit }, avp_data := { avp_BASE_NONE_Session_Id := '736D662E6C6F63616C646F6D61696E3B313738383138313730393B313B6170705F6778'O ("smf.localdomain;1788181709;1;app_gx") } } }, { avp := { avp_header := { avp_code := { vendor_id_NONE := avp_code_BASE_NONE_Origin_Host (264) }, VMPxxxxx := '01000000'B, avp_length := 23, vendor_id := omit }, avp_data := { avp_BASE_NONE_Origin_Host := "smf.localdomain" } } }, { avp := { avp_header := { avp_code := { vendor_id_NONE := avp_code_BASE_NONE_Origin_Realm (296) }, VMPxxxxx := '01000000'B, avp_length := 19, vendor_id := omit }, avp_data := { avp_BASE_NONE_Origin_Realm := "localdomain" } } }, { avp := { avp_header := { avp_code := { vendor_id_NONE := avp_code_BASE_NONE_Destination_Realm (283) }, VMPxxxxx := '01000000'B, avp_length := 19, vendor_id := omit }, avp_data := { avp_BASE_NONE_Destination_Realm := "localdomain" } } }, { avp := { avp_header := { avp_code := { vendor_id_NONE := avp_code_BASE_NONE_Auth_Application_Id (258) }, VMPxxxxx := '01000000'B, avp_length := 12, vendor_id := omit }, avp_data := { avp_BASE_NONE_Auth_Application_Id := '01000016'O } } }, { avp := { avp_header := { avp_code := { vendor_id_NONE := avp_code_DCC_NONE_CC_Request_Type (416) }, VMPxxxxx := '01000000'B, avp_length := 12, vendor_id := omit }, avp_data := { avp_DCC_NONE_CC_Request_Type := INITIAL_REQUEST (1) } } }, { avp := { avp_header := { avp_code := { vendor_id_NONE := avp_code_DCC_NONE_CC_Request_Number (415) }, VMPxxxxx := '01000000'B, avp_length := 12, vendor_id := omit }, avp_data := { avp_DCC_NONE_CC_Request_Number := '00000000'O } } }, { avp := { avp_header := { avp_code := { vendor_id_NONE := avp_code_DCC_NONE_Subscription_Id (443) }, VMPxxxxx := '01000000'B, avp_length := 44, vendor_id := omit }, avp_data := { avp_DCC_NONE_Subscription_Id := { { avp := { avp_header := { avp_code := { vendor_id_NONE := avp_code_DCC_NONE_Subscription_Id_Type (450) }, VMPxxxxx := '01000000'B, avp_length := 12, vendor_id := omit }, avp_data := { avp_DCC_NONE_Subscription_Id_Type := END_USER_IMSI (1) } } }, { avp := { avp_header := { avp_code := { vendor_id_NONE := avp_code_DCC_NONE_Subscription_Id_Data (444) }, VMPxxxxx := '01000000'B, avp_length := 23, vendor_id := omit }, avp_data := { avp_DCC_NONE_Subscription_Id_Data := '303031303130313233343536373839'O ("001010123456789") } } } } } } }, { avp := { avp_header := { avp_code := { vendor_id_NONE := avp_code_DCC_NONE_Subscription_Id (443) }, VMPxxxxx := '01000000'B, avp_length := 32, vendor_id := omit }, avp_data := { avp_DCC_NONE_Subscription_Id := { { avp := { avp_header := { avp_code := { vendor_id_NONE := avp_code_DCC_NONE_Subscription_Id_Type (450) }, VMPxxxxx := '01000000'B, avp_length := 12, vendor_id := omit }, avp_data := { avp_DCC_NONE_Subscription_Id_Type := END_USER_E164 (0) } } }, { avp := { avp_header := { avp_code := { vendor_id_NONE := avp_code_DCC_NONE_Subscription_Id_Data (444) }, VMPxxxxx := '01000000'B, avp_length := 12, vendor_id := omit }, avp_data := { avp_DCC_NONE_Subscription_Id_Data := '31323334'O ("1234") } } } } } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_CxDx_3GPP_Supported_Features (628) }, VMPxxxxx := '10000000'B, avp_length := 56, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_CxDx_3GPP_Supported_Features := { { avp := { avp_header := { avp_code := { vendor_id_NONE := avp_code_BASE_NONE_Vendor_Id (266) }, VMPxxxxx := '01000000'B, avp_length := 12, vendor_id := omit }, avp_data := { avp_BASE_NONE_Vendor_Id := '000028AF'O } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_CxDx_3GPP_Feature_List_ID (629) }, VMPxxxxx := '10000000'B, avp_length := 16, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_CxDx_3GPP_Feature_List_ID := '00000001'O } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_CxDx_3GPP_Feature_List (630) }, VMPxxxxx := '10000000'B, avp_length := 16, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_CxDx_3GPP_Feature_List := '0000000B'O } } } } } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_PCC_3GPP_Network_Request_Support (1024) }, VMPxxxxx := '11000000'B, avp_length := 16, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_PCC_3GPP_Network_Request_Support := NETWORK_REQUEST_SUPPORTED (1) } } }, { avp := { avp_header := { avp_code := { vendor_id_NONE := avp_code_DNAS_NONE_Framed_IP_Address (8) }, VMPxxxxx := '01000000'B, avp_length := 12, vendor_id := omit }, avp_data := { avp_DNAS_NONE_Framed_IP_Address := '0A2D0002'O } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_PCC_3GPP_IP_CAN_Type (1027) }, VMPxxxxx := '11000000'B, avp_length := 16, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_PCC_3GPP_IP_CAN_Type := threeGPP_EPS (5) } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_PCC_3GPP_RAT_Type (1032) }, VMPxxxxx := '10000000'B, avp_length := 16, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_PCC_3GPP_RAT_Type := EUTRAN (1004) } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_PCC_3GPP_QoS_Information (1016) }, VMPxxxxx := '11000000'B, avp_length := 44, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_PCC_3GPP_QoS_Information := { { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_PCC_3GPP_APN_Aggregate_Max_Bitrate_UL (1041) }, VMPxxxxx := '10000000'B, avp_length := 16, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_PCC_3GPP_APN_Aggregate_Max_Bitrate_UL := '061A8000'O } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_PCC_3GPP_APN_Aggregate_Max_Bitrate_DL (1040) }, VMPxxxxx := '10000000'B, avp_length := 16, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_PCC_3GPP_APN_Aggregate_Max_Bitrate_DL := '061A8000'O } } } } } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_PCC_3GPP_Default_EPS_Bearer_QoS (1049) }, VMPxxxxx := '10000000'B, avp_length := 88, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_PCC_3GPP_Default_EPS_Bearer_QoS := { { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_PCC_3GPP_QoS_Class_Identifier (1028) }, VMPxxxxx := '11000000'B, avp_length := 16, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_PCC_3GPP_QoS_Class_Identifier := '00000009'O } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_PCC_3GPP_Allocation_Retention_Priority (1034) }, VMPxxxxx := '10000000'B, avp_length := 60, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_PCC_3GPP_Allocation_Retention_Priority := { { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_PCC_3GPP_Priority_Level (1046) }, VMPxxxxx := '10000000'B, avp_length := 16, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_PCC_3GPP_Priority_Level := '00000009'O } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_PCC_3GPP_Pre_emption_Capability (1047) }, VMPxxxxx := '10000000'B, avp_length := 16, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_PCC_3GPP_Pre_emption_Capability := PRE_EMPTION_CAPABILITY_ENABLED (0) } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_PCC_3GPP_Pre_emption_Vulnerability (1048) }, VMPxxxxx := '10000000'B, avp_length := 16, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_PCC_3GPP_Pre_emption_Vulnerability := PRE_EMPTION_VULNERABILITY_ENABLED (0) } } } } } } } } } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_GI_3GPP_3GPP_User_Location_Info (22) }, VMPxxxxx := '11000000'B, avp_length := 25, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_GI_3GPP_3GPP_User_Location_Info := '8200F110000100F11000000017'O } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_GI_3GPP_3GPP_SGSN_MCC_MNC (18) }, VMPxxxxx := '11000000'B, avp_length := 17, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_GI_3GPP_3GPP_SGSN_MCC_MNC := '3030313031'O ("00101") } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_PCC_3GPP_AN_GW_Address (1050) }, VMPxxxxx := '10000000'B, avp_length := 18, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_PCC_3GPP_AN_GW_Address := { address_type := IP (1), address_data := '7F0000CA'O } } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_GI_3GPP_3GPP_Charging_Characteristics (13) }, VMPxxxxx := '11000000'B, avp_length := 16, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_GI_3GPP_3GPP_Charging_Characteristics := '30303030'O ("0000") } } }, { avp := { avp_header := { avp_code := { vendor_id_NONE := avp_code_DNAS_NONE_Called_Station_Id (30) }, VMPxxxxx := '01000000'B, avp_length := 16, vendor_id := omit }, avp_data := { avp_DNAS_NONE_Called_Station_Id := '696E7465726E6574'O ("internet") } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_PCC_3GPP_Online (1009) }, VMPxxxxx := '11000000'B, avp_length := 16, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_PCC_3GPP_Online := DISABLE_ONLINE (0) } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_PCC_3GPP_Offline (1008) }, VMPxxxxx := '11000000'B, avp_length := 16, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_PCC_3GPP_Offline := ENABLE_OFFLINE (1) } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_RX_3GPP_Access_Network_Charging_Address (501) }, VMPxxxxx := '11000000'B, avp_length := 18, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_RX_3GPP_Access_Network_Charging_Address := { address_type := IP (1), address_data := '7F000004'O } } } }, { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_PCC_3GPP_Access_Network_Charging_Identifier_Gx (1022) }, VMPxxxxx := '11000000'B, avp_length := 28, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_PCC_3GPP_Access_Network_Charging_Identifier_Gx := { { avp := { avp_header := { avp_code := { vendor_id_3GPP := avp_code_RX_3GPP_Access_Network_Charging_Identifier_Value (503) }, VMPxxxxx := '11000000'B, avp_length := 16, vendor_id := vendor_id_3GPP (10415) }, avp_data := { avp_RX_3GPP_Access_Network_Charging_Identifier_Value := '00000001'O } } } } } } } } } handled at TC_createSession_deleteSession(17) TC_createSession_deleteSession-PFCP(13)@29c9d80cf285: PFCP_Emulation main() PFCP.receive: { peer := { conn_id := 1, remote_name := "127.0.0.4", remote_port := 8805 }, pdu := { s_flag := '1'B, mp := '0'B, spare := '000'B, version := 1, message_type := 50, lengthIndicator := 608, seid := '0000000000000000'O, sequence_number := 5, spare2 := '0000'B, mp_or_spare := '0000'B, message_body := { pfcp_session_establishment_request := { node_id := { elementIdentifier := 60, lengthIndicator := 5, node_id_type := 0, spare := '0000'B, node_id_value := '7F000004'O }, CP_F_SEID := { elementIdentifier := 57, lengthIndicator := 13, v6 := '0'B, v4 := '1'B, spare := '000000'B, seid := '0000000000000FF8'O, ipv4_address := '7F000004'O, ipv6_address := omit }, create_PDR_list := { { elementIdentifier := 1, lengthIndicator := 74, grouped_ie := { pdr_id := { elementIdentifier := 56, lengthIndicator := 2, rule_id := '0001'O }, precedence := { elementIdentifier := 29, lengthIndicator := 4, precedence_value := 65535 }, pdi := { elementIdentifier := 2, lengthIndicator := 32, grouped_ie := { source_interface := { elementIdentifier := 20, lengthIndicator := 1, interfacevalue := 1, spare := '0000'B }, local_F_TEID := omit, pdn_instance := { elementIdentifier := 22, lengthIndicator := 9, pdn_instance := '08696E7465726E6574'O ("\binternet") }, ue_ip_address := { elementIdentifier := 93, lengthIndicator := 5, v6 := '0'B, v4 := '1'B, sd := '1'B, spare := '00000'B, ipv4_address := '0A2D0002'O, ipv6_address := omit }, traffic_endpoint_id := omit, sdf_filter_list := omit, application_id := omit, ethernet_packet_filter_list := omit, qfi_list := omit, source_interface_type := { elementIdentifier := 160, lengthIndicator := 1, interfacetypevalue := 17, spare := '00'B } } }, outer_header_removal := omit, fAR_ID_list := { { elementIdentifier := 108, lengthIndicator := 4, id_value := 1 } }, uRR_ID_list := { { elementIdentifier := 81, lengthIndicator := 4, id_value := 1 } }, qER_ID_list := { { elementIdentifier := 109, lengthIndicator := 4, id_value := 1 } }, activate_predefined_rules := omit } }, { elementIdentifier := 1, lengthIndicator := 85, grouped_ie := { pdr_id := { elementIdentifier := 56, lengthIndicator := 2, rule_id := '0002'O }, precedence := { elementIdentifier := 29, lengthIndicator := 4, precedence_value := 65535 }, pdi := { elementIdentifier := 2, lengthIndicator := 38, grouped_ie := { source_interface := { elementIdentifier := 20, lengthIndicator := 1, interfacevalue := 0, spare := '0000'B }, local_F_TEID := { elementIdentifier := 21, lengthIndicator := 2, v4 := '1'B, v6 := '1'B, ch := '1'B, chid := '1'B, spare := '0000'B, teid := omit, ipv4_address := omit, ipv6_address := omit, choose_id := '05'O }, pdn_instance := { elementIdentifier := 22, lengthIndicator := 9, pdn_instance := '08696E7465726E6574'O ("\binternet") }, ue_ip_address := { elementIdentifier := 93, lengthIndicator := 5, v6 := '0'B, v4 := '1'B, sd := '0'B, spare := '00000'B, ipv4_address := '0A2D0002'O, ipv6_address := omit }, traffic_endpoint_id := omit, sdf_filter_list := omit, application_id := omit, ethernet_packet_filter_list := omit, qfi_list := omit, source_interface_type := { elementIdentifier := 160, lengthIndicator := 1, interfacetypevalue := 11, spare := '00'B } } }, outer_header_removal := { elementIdentifier := 95, lengthIndicator := 1, ohc_description := 6 }, fAR_ID_list := { { elementIdentifier := 108, lengthIndicator := 4, id_value := 2 } }, uRR_ID_list := { { elementIdentifier := 81, lengthIndicator := 4, id_value := 1 } }, qER_ID_list := { { elementIdentifier := 109, lengthIndicator := 4, id_value := 1 } }, activate_predefined_rules := omit } }, { elementIdentifier := 1, lengthIndicator := 41, grouped_ie := { pdr_id := { elementIdentifier := 56, lengthIndicator := 2, rule_id := '0003'O }, precedence := { elementIdentifier := 29, lengthIndicator := 4, precedence_value := 255 }, pdi := { elementIdentifier := 2, lengthIndicator := 10, grouped_ie := { source_interface := { elementIdentifier := 20, lengthIndicator := 1, interfacevalue := 3, spare := '0000'B }, local_F_TEID := { elementIdentifier := 21, lengthIndicator := 1, v4 := '1'B, v6 := '1'B, ch := '1'B, chid := '0'B, spare := '0000'B, teid := omit, ipv4_address := omit, ipv6_address := omit, choose_id := omit }, pdn_instance := omit, ue_ip_address := omit, traffic_endpoint_id := omit, sdf_filter_list := omit, application_id := omit, ethernet_packet_filter_list := omit, qfi_list := omit, source_interface_type := omit } }, outer_header_removal := { elementIdentifier := 95, lengthIndicator := 1, ohc_description := 6 }, fAR_ID_list := { { elementIdentifier := 108, lengthIndicator := 4, id_value := 1 } }, uRR_ID_list := omit, qER_ID_list := omit, activate_predefined_rules := omit } }, { elementIdentifier := 1, lengthIndicator := 110, grouped_ie := { pdr_id := { elementIdentifier := 56, lengthIndicator := 2, rule_id := '0004'O }, precedence := { elementIdentifier := 29, lengthIndicator := 4, precedence_value := 255 }, pdi := { elementIdentifier := 2, lengthIndicator := 79, grouped_ie := { source_interface := { elementIdentifier := 20, lengthIndicator := 1, interfacevalue := 0, spare := '0000'B }, local_F_TEID := { elementIdentifier := 21, lengthIndicator := 2, v4 := '1'B, v6 := '1'B, ch := '1'B, chid := '1'B, spare := '0000'B, teid := omit, ipv4_address := omit, ipv6_address := omit, choose_id := '05'O }, pdn_instance := { elementIdentifier := 22, lengthIndicator := 9, pdn_instance := '08696E7465726E6574'O ("\binternet") }, ue_ip_address := omit, traffic_endpoint_id := omit, sdf_filter_list := { { elementIdentifier := 23, lengthIndicator := 46, fd := '1'B, ttc := '0'B, spi := '0'B, fl := '0'B, bid := '0'B, spare := '00000000000'B, lengthFlowDesc := 42, flow_description := '7065726D6974206F75742035382066726F6D20666630323A3A322F31323820746F2061737369676E6564'O ("permit out 58 from ff02::2/128 to assigned"), ToS_Traffic_Class := omit, security_parameter_index := omit, flow_label := omit, SDF_filter_id := omit } }, application_id := omit, ethernet_packet_filter_list := omit, qfi_list := omit, source_interface_type := { elementIdentifier := 160, lengthIndicator := 1, interfacetypevalue := 11, spare := '00'B } } }, outer_header_removal := { elementIdentifier := 95, lengthIndicator := 1, ohc_description := 6 }, fAR_ID_list := { { elementIdentifier := 108, lengthIndicator := 4, id_value := 3 } }, uRR_ID_list := omit, qER_ID_list := omit, activate_predefined_rules := omit } } }, create_FAR_list := { { elementIdentifier := 3, lengthIndicator := 55, grouped_ie := { far_id := { elementIdentifier := 108, lengthIndicator := 4, id_value := 1 }, apply_action := { elementIdentifier := 44, lengthIndicator := 2, drop := '0'B, forw := '1'B, buff := '0'B, nocp := '0'B, dupl := '0'B, ipma := '0'B, ipmd := '0'B, dfrt := '0'B, edrt := '0'B, bdpn := '0'B, ddpn := '0'B, fssm := '0'B, mbsu := '0'B, spare := '000'B }, forwarding_parameters := { elementIdentifier := 4, lengthIndicator := 37, grouped_ie := { destination_interface := { elementIdentifier := 42, lengthIndicator := 1, interface_value := 0, spare := '0000'B }, pdn_Instance := { elementIdentifier := 22, lengthIndicator := 9, pdn_instance := '08696E7465726E6574'O ("\binternet") }, redirect_information := omit, outer_header_creation := { elementIdentifier := 84, lengthIndicator := 10, ohc_description_oct5 := '00000001'B, ohc_description_oct6 := '00000000'B, teid := '2D95F3A5'O, ipv4 := '7F000014'O, ipv6 := omit, udp_port := omit }, transport_level_marking := omit, forwarding_policy := omit, header_enrichment := omit, traffic_endpoint_ID := omit, destination_interface_type := { elementIdentifier := 160, lengthIndicator := 1, interfacetypevalue := 11, spare := '00'B } } }, duplicating_parameters := omit, bar_id := omit } }, { elementIdentifier := 3, lengthIndicator := 41, grouped_ie := { far_id := { elementIdentifier := 108, lengthIndicator := 4, id_value := 2 }, apply_action := { elementIdentifier := 44, lengthIndicator := 2, drop := '0'B, forw := '1'B, buff := '0'B, nocp := '0'B, dupl := '0'B, ipma := '0'B, ipmd := '0'B, dfrt := '0'B, edrt := '0'B, bdpn := '0'B, ddpn := '0'B, fssm := '0'B, mbsu := '0'B, spare := '000'B }, forwarding_parameters := { elementIdentifier := 4, lengthIndicator := 23, grouped_ie := { destination_interface := { elementIdentifier := 42, lengthIndicator := 1, interface_value := 1, spare := '0000'B }, pdn_Instance := { elementIdentifier := 22, lengthIndicator := 9, pdn_instance := '08696E7465726E6574'O ("\binternet") }, redirect_information := omit, outer_header_creation := omit, transport_level_marking := omit, forwarding_policy := omit, header_enrichment := omit, traffic_endpoint_ID := omit, destination_interface_type := { elementIdentifier := 160, lengthIndicator := 1, interfacetypevalue := 17, spare := '00'B } } }, duplicating_parameters := omit, bar_id := omit } }, { elementIdentifier := 3, lengthIndicator := 37, grouped_ie := { far_id := { elementIdentifier := 108, lengthIndicator := 4, id_value := 3 }, apply_action := { elementIdentifier := 44, lengthIndicator := 2, drop := '0'B, forw := '1'B, buff := '0'B, nocp := '0'B, dupl := '0'B, ipma := '0'B, ipmd := '0'B, dfrt := '0'B, edrt := '0'B, bdpn := '0'B, ddpn := '0'B, fssm := '0'B, mbsu := '0'B, spare := '000'B }, forwarding_parameters := { elementIdentifier := 4, lengthIndicator := 19, grouped_ie := { destination_interface := { elementIdentifier := 42, lengthIndicator := 1, interface_value := 3, spare := '0000'B }, pdn_Instance := omit, redirect_information := omit, outer_header_creation := { elementIdentifier := 84, lengthIndicator := 10, ohc_description_oct5 := '00000001'B, ohc_description_oct6 := '00000000'B, teid := '00000001'O, ipv4 := '7F000004'O, ipv6 := omit, udp_port := omit }, transport_level_marking := omit, forwarding_policy := omit, header_enrichment := omit, traffic_endpoint_ID := omit, destination_interface_type := omit } }, duplicating_parameters := omit, bar_id := omit } } }, create_URR_list := { { elementIdentifier := 6, lengthIndicator := 20, grouped_ie := { urr_id := { elementIdentifier := 81, lengthIndicator := 4, id_value := 1 }, measurement_method := { elementIdentifier := 62, lengthIndicator := 1, durat := '0'B, volum := '0'B, event := '0'B, spare := '00000'B }, reporting_triggers := { elementIdentifier := 37, lengthIndicator := 3, perio := '0'B, volth := '0'B, timth := '0'B, quhti := '0'B, start_ := '0'B, stop_ := '0'B, droth := '0'B, liusa := '0'B, volqu := '0'B, timqu := '0'B, envcl := '0'B, macar := '0'B, eveth := '0'B, evequ := '0'B, ipmjl := '0'B, quvti := '0'B, upint := '0'B, reemr := '0'B, spare := '000000'B }, measurement_period := omit, volume_threshold := omit, volume_quota := omit, time_threshold := omit, time_quota := omit, quota_holding_time := omit, dropped_dl_traffic_threshold := omit, quota_validity_time := omit, monitoring_time := omit, subsequent_volume_threshold := omit, subsequent_time_threshold := omit, subsequent_volume_quota := omit, subsequent_time_quota := omit, inactivity_detection_time := omit, linked_urr_id := omit, measurement_information := omit, time_qouta_mechanism := omit, aggregated_urrs := omit } } }, create_QER_list := { { elementIdentifier := 7, lengthIndicator := 27, grouped_ie := { qer_id := { elementIdentifier := 109, lengthIndicator := 4, id_value := 1 }, qer_correlation_id := omit, gate_status := { elementIdentifier := 25, lengthIndicator := 1, dl_gate := 0, ul_gate := 0, spare := '0000'B }, mbr := { elementIdentifier := 26, lengthIndicator := 10, ul_mbr := '0000008313'O, dl_mbr := '000000CCCD'O }, gbr := omit, packet_rate := omit, dl_transport_level_marking := omit, qfi := omit, rqi := omit } } }, create_BAR := { elementIdentifier := 85, lengthIndicator := 5, grouped_ie := { bar_id := { elementIdentifier := 88, lengthIndicator := 1, id_value := '01'O }, downlink_data_notification_delay := omit } }, create_traffic_endpoint_list := omit, pdn_type := { elementIdentifier := 113, lengthIndicator := 1, pdn_type := 1, spare := '00000'B }, node_list := omit, up_inactivity_timer := omit, user_id := { elementIdentifier := 141, lengthIndicator := 13, imsif := '1'B, imeif := '0'B, msisdnf := '1'B, naif := '0'B, supif := '0'B, gpsif := '0'B, peif := '0'B, spare := '0'B, imsi_length := 8, imsi := '00010121436587F9'O, imei_length := omit, imei := omit, msisdn_length := 2, msisdn := '2143'O ("!C"), nai_length := omit, nai := omit, supi_length := omit, supi := omit, gpsi_length := omit, gpsi := omit, pei_length := omit, pei := omit }, apn_dnn := { elementIdentifier := 159, lengthIndicator := 9, apn_dnn := '08696E7465726E6574'O ("\binternet") } } } } } TC_createSession_deleteSession-PFCP(13)@29c9d80cf285: found destination TC_createSession_deleteSession(17) TC_createSession_deleteSession-PFCP(13)@29c9d80cf285: PFCP_Emulation main() CLIENT.receive from TC_createSession_deleteSession(17): { s_flag := '0'B, mp := '0'B, spare := '000'B, version := 1, message_type := 0, lengthIndicator := 0, seid := '0000000000000FF8'O, sequence_number := 5, spare2 := '0000'B, mp_or_spare := '0000'B, message_body := { pfcp_session_establishment_response := { node_id := { elementIdentifier := 60, lengthIndicator := 0, node_id_type := 0, spare := '0000'B, node_id_value := '7F000007'O }, cause := { elementIdentifier := 19, lengthIndicator := 0, causeValue := '01'O }, offending_ie := omit, UP_F_SEID := { elementIdentifier := 57, lengthIndicator := 0, v6 := '0'B, v4 := '1'B, spare := '000000'B, seid := '0000000000000001'O, ipv4_address := '7F000007'O, ipv6_address := omit }, created_PDR_list := { { elementIdentifier := 8, lengthIndicator := 0, grouped_ie := { pdr_id := { elementIdentifier := 56, lengthIndicator := 2, rule_id := '0001'O }, local_F_TEID := { elementIdentifier := 21, lengthIndicator := 0, v4 := '1'B, v6 := '0'B, ch := '0'B, chid := '0'B, spare := '0000'B, teid := '00002001'O, ipv4_address := '7F000007'O, ipv6_address := omit, choose_id := omit } } }, { elementIdentifier := 8, lengthIndicator := 0, grouped_ie := { pdr_id := { elementIdentifier := 56, lengthIndicator := 2, rule_id := '0002'O }, local_F_TEID := { elementIdentifier := 21, lengthIndicator := 0, v4 := '1'B, v6 := '0'B, ch := '0'B, chid := '0'B, spare := '0000'B, teid := '00000001'O, ipv4_address := '7F000007'O, ipv6_address := omit, choose_id := omit } } }, { elementIdentifier := 8, lengthIndicator := 0, grouped_ie := { pdr_id := { elementIdentifier := 56, lengthIndicator := 2, rule_id := '0003'O }, local_F_TEID := { elementIdentifier := 21, lengthIndicator := 0, v4 := '1'B, v6 := '0'B, ch := '0'B, chid := '0'B, spare := '0000'B, teid := '00002001'O, ipv4_address := '7F000007'O, ipv6_address := omit, choose_id := omit } } }, { elementIdentifier := 8, lengthIndicator := 0, grouped_ie := { pdr_id := { elementIdentifier := 56, lengthIndicator := 2, rule_id := '0004'O }, local_F_TEID := { elementIdentifier := 21, lengthIndicator := 0, v4 := '1'B, v6 := '0'B, ch := '0'B, chid := '0'B, spare := '0000'B, teid := '00000001'O, ipv4_address := '7F000007'O, ipv6_address := omit, choose_id := omit } } } }, load_control_information := omit, overload_control_information := omit, node_list := omit, failed_rule_id := omit, created_traffic_endpoint_list := omit } } } [32m08/31 13:08:42.081[0m: [[33mgtp[0m] [1;32mINFO[0m: gtp_connect() [127.0.0.7]:2152 (../lib/gtp/path.c:60) TC_createSession_deleteSession(17)@29c9d80cf285: f_Mutex_unlock(): mutex released @ "SMF_Session_CT.ttcn":427 TC_createSession_deleteSession(17)@29c9d80cf285: SMF assigned UE IPv4 address: "10.45.0.2" TC_createSession_deleteSession-PFCP(13)@29c9d80cf285: PFCP_Emulation main() PFCP.receive: { peer := { conn_id := 1, remote_name := "127.0.0.4", remote_port := 8805 }, pdu := { s_flag := '1'B, mp := '0'B, spare := '000'B, version := 1, message_type := 54, lengthIndicator := 12, seid := '0000000000000001'O, sequence_number := 6, spare2 := '0000'B, mp_or_spare := '0000'B, message_body := { pfcp_session_deletion_request := { } } } } TC_createSession_deleteSession-PFCP(13)@29c9d80cf285: found destination TC_createSession_deleteSession(17) TC_createSession_deleteSession-PFCP(13)@29c9d80cf285: PFCP_Emulation main() CLIENT.receive from TC_createSession_deleteSession(17): { s_flag := '0'B, mp := '0'B, spare := '000'B, version := 1, message_type := 0, lengthIndicator := 0, seid := '0000000000000FF8'O, sequence_number := 6, spare2 := '0000'B, mp_or_spare := '0000'B, message_body := { pfcp_session_deletion_response := { cause := { elementIdentifier := 19, lengthIndicator := 0, causeValue := '01'O }, offending_IE := omit, load_control_information := omit, overload_control_information := omit, usage_report := { { elementIdentifier := 79, lengthIndicator := 0, grouped_ie := { urr_id := { elementIdentifier := 81, lengthIndicator := 4, id_value := 1 }, ur_seqn := { elementIdentifier := 104, lengthIndicator := 0, ur_seqn := 0 }, usage_report_trigger := { elementIdentifier := 63, lengthIndicator := 0, perio := '0'B, volth := '0'B, timth := '0'B, quhti := '0'B, start_ := '0'B, stop_ := '0'B, droth := '0'B, immer := '0'B, volqu := '0'B, timqu := '0'B, liusa := '0'B, termr := '1'B, monit := '0'B, envcl := '0'B, macar := '0'B, eveth := '0'B, evequ := '0'B, tebur := '0'B, ipmjl := '0'B, quvti := '0'B, emrre := '0'B, upint := '0'B, spare7_7 := '0'B, spare7_8 := '0'B }, start_time := { elementIdentifier := 75, lengthIndicator := 0, start_time := 'EE3FFB5A'O }, end_time := { elementIdentifier := 76, lengthIndicator := 0, end_time := 'EE3FFB5A'O }, volume_measurement := omit, duration_measurement := omit, time_of_first_packet := omit, time_of_last_packet := omit, usage_information := omit } } } } } } Segmentation fault (core dumped) [1;34m[testenv] Looking for a coredump file[0m [0;94m[testenv] + ['file', '<https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/1/artifac…'][0m> [0;94m[testenv] Coredump file and execfn found[0m [1;91m[testenv] smf unexpected exit during SMF_Tests.TC_createSession_deleteSession! rc=0[0m [0;94m[testenv] Waiting until test has stopped...[0m MTC@29c9d80cf285: setverdict(fail): pass -> fail reason: "Timeout of T_guard", new component reason: "Timeout of T_guard" MutexDispCT-TC_createSession_deleteSession(12)@29c9d80cf285: Final verdict of PTC: none TC_createSession_deleteSession(17)@29c9d80cf285: Final verdict of PTC: none GTP1U_EM(11)@29c9d80cf285: Final verdict of PTC: none TC_createSession_deleteSession(15)@29c9d80cf285: Final verdict of PTC: none TC_createSession_deleteSession(14)@29c9d80cf285: Final verdict of PTC: none TC_createSession_deleteSession(16)@29c9d80cf285: Final verdict of PTC: none TC_createSession_deleteSession-PFCP(13)@29c9d80cf285: Final verdict of PTC: none GTP2C_EM(10)@29c9d80cf285: Final verdict of PTC: none MTC@29c9d80cf285: Setting final verdict of the test case. MTC@29c9d80cf285: Local verdict of MTC: fail reason: "Timeout of T_guard" MTC@29c9d80cf285: Local verdict of PTC GTP2C_EM(10): none (fail -> fail) MTC@29c9d80cf285: Local verdict of PTC GTP1U_EM(11): none (fail -> fail) MTC@29c9d80cf285: Local verdict of PTC MutexDispCT-TC_createSession_deleteSession(12): none (fail -> fail) MTC@29c9d80cf285: Local verdict of PTC TC_createSession_deleteSession-PFCP(13): none (fail -> fail) MTC@29c9d80cf285: Local verdict of PTC TC_createSession_deleteSession(14): none (fail -> fail) MTC@29c9d80cf285: Local verdict of PTC TC_createSession_deleteSession(15): none (fail -> fail) MTC@29c9d80cf285: Local verdict of PTC TC_createSession_deleteSession(16): none (fail -> fail) MTC@29c9d80cf285: Local verdict of PTC TC_createSession_deleteSession(17): none (fail -> fail) MTC@29c9d80cf285: Test case TC_createSession_deleteSession finished. Verdict: fail reason: Timeout of T_guard MTC@29c9d80cf285: Starting external command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/ws/ttcn3-…> SMF_Tests.TC_createSession_deleteSession fail'. (13:09:37) load average: 1.52, 0.91, 0.84 [1;31m------ SMF_Tests.TC_createSession_deleteSession fail ------[0m Waiting for packet dumper to finish... 0 (prev_count=-1, count=21240) MTC@29c9d80cf285: External command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/ws/ttcn3-…> SMF_Tests.TC_createSession_deleteSession fail' was executed successfully (exit status: 0). MTC@29c9d80cf285: Starting external command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/ws/ttcn3-…> SMF_Tests.TC_deleteSession_unknown'. ------ SMF_Tests.TC_deleteSession_unknown ------ (13:09:38) load average: 1.52, 0.91, 0.84 /usr/bin/dumpcap -q -s 1520 -n -i any -w "<https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/1/artifac…"> ><https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/1/artifac…> 2>/tmp/cmderr & Waiting for packet dumper to start... 0 MTC@29c9d80cf285: External command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/ws/ttcn3-…> SMF_Tests.TC_deleteSession_unknown' was executed successfully (exit status: 0). MTC@29c9d80cf285: Test case TC_deleteSession_unknown started. TC_deleteSession_unknown-PFCP(21)@29c9d80cf285: f_PFCPEM_conns_bcast_add(): vc_conn mtc subscribed for broadcast TC_deleteSession_unknown-PFCP(21)@29c9d80cf285: PFCP_Emulation main() CLIENT.receive from mtc: { s_flag := '0'B, mp := '0'B, spare := '000'B, version := 1, message_type := 0, lengthIndicator := 0, seid := omit, sequence_number := 0, spare2 := '0000'B, mp_or_spare := '0000'B, message_body := { heartbeat_request := { recovery_time_stamp := { elementIdentifier := 96, lengthIndicator := 0, time_value := 3997170580 } } } } MTC@29c9d80cf285: setverdict(fail): none -> fail reason: ""PFCP_Emulation.ttcn:504 : Timeout waiting for PFCP { s_flag := ?, mp := ?, spare := ?, version := 1, message_type := ?, lengthIndicator := ?, seid := *, sequence_number := ?, spare2 := ?, mp_or_spare := ?, message_body := { pfcp_association_setup_request := { node_id := ?, time_stamp := ?, up_function_features := *, cp_function_features := *, UP_IP_resource_list := * } } }"", new component reason: ""PFCP_Emulation.ttcn:504 : Timeout waiting for PFCP { s_flag := ?, mp := ?, spare := ?, version := 1, message_type := ?, lengthIndicator := ?, seid := *, sequence_number := ?, spare2 := ?, mp_or_spare := ?, message_body := { pfcp_association_setup_request := { node_id := ?, time_stamp := ?, up_function_features := *, cp_function_features := *, UP_IP_resource_list := * } } }"" MTC@29c9d80cf285: Stopping testcase execution from "PFCP_Emulation.ttcn":504 GTP1U_EM(19)@29c9d80cf285: Final verdict of PTC: none GTP2C_EM(18)@29c9d80cf285: Final verdict of PTC: none MutexDispCT-TC_deleteSession_unknown(20)@29c9d80cf285: Final verdict of PTC: none TC_deleteSession_unknown-PFCP(21)@29c9d80cf285: Final verdict of PTC: none MTC@29c9d80cf285: Setting final verdict of the test case. MTC@29c9d80cf285: Local verdict of MTC: fail reason: ""PFCP_Emulation.ttcn:504 : Timeout waiting for PFCP { s_flag := ?, mp := ?, spare := ?, version := 1, message_type := ?, lengthIndicator := ?, seid := *, sequence_number := ?, spare2 := ?, mp_or_spare := ?, message_body := { pfcp_association_setup_request := { node_id := ?, time_stamp := ?, up_function_features := *, cp_function_features := *, UP_IP_resource_list := * } } }"" MTC@29c9d80cf285: Local verdict of PTC GTP2C_EM(18): none (fail -> fail) MTC@29c9d80cf285: Local verdict of PTC GTP1U_EM(19): none (fail -> fail) MTC@29c9d80cf285: Local verdict of PTC MutexDispCT-TC_deleteSession_unknown(20): none (fail -> fail) MTC@29c9d80cf285: Local verdict of PTC TC_deleteSession_unknown-PFCP(21): none (fail -> fail) MTC@29c9d80cf285: Test case TC_deleteSession_unknown finished. Verdict: fail reason: "PFCP_Emulation.ttcn:504 : Timeout waiting for PFCP { s_flag := ?, mp := ?, spare := ?, version := 1, message_type := ?, lengthIndicator := ?, seid := *, sequence_number := ?, spare2 := ?, mp_or_spare := ?, message_body := { pfcp_association_setup_request := { node_id := ?, time_stamp := ?, up_function_features := *, cp_function_features := *, UP_IP_resource_list := * } } }" MTC@29c9d80cf285: Starting external command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/ws/ttcn3-…> SMF_Tests.TC_deleteSession_unknown fail'. (13:09:56) load average: 1.33, 0.90, 0.84 [1;31m------ SMF_Tests.TC_deleteSession_unknown fail ------[0m Waiting for packet dumper to finish... 0 (prev_count=-1, count=1736) MTC@29c9d80cf285: External command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/ws/ttcn3-…> SMF_Tests.TC_deleteSession_unknown fail' was executed successfully (exit status: 0). MTC@29c9d80cf285: Starting external command `<https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/ws/ttcn3-…> SMF_Tests.TC_gy_ccr_update_rejected'. ------ SMF_Tests.TC_gy_ccr_update_rejected ------ (13:09:57) load average: 1.23, 0.89, 0.83 /usr/bin/dumpcap -q -s 1520 -n -i any -w "<https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/1/artifac…"> ><https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/1/artifac…> 2>/tmp/cmderr & Waiting for packet dumper to start... 0 [1;34m[testenv] Running gdb to get a backtrace[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-smf-test-ogs-latest/ws/_cache',> '-e', 'TESTENV_SRC_DIR=/home/osmocom-build/jenkins/workspace', '-e', 'TESTENV_INSTALL_DIR=/', '-e', 'TERM=dumb', '-e', 'PATH=<https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/ws/_teste…>:<https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/ws/_teste…>:<https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/ws/smf>:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin', '-e', 'HOME=/home/osmocom-build', '-e', 'PYTHONUNBUFFERED=1', '-t', 'testenv-smf-osmocom-latest-20260831-1308-5a804244-1', 'sh', '-c', 'echo; gdb --batch /usr/bin/open5gs-smfd <https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/1/artifac…> -ex bt | tee <https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/1/artifac…;> echo'][0m [New LWP 209] [New LWP 185] [New LWP 188] [New LWP 186] [New LWP 193] [New LWP 194] [New LWP 195] [New LWP 197] [New LWP 198] [New LWP 200] [New LWP 199] [New LWP 184] [New LWP 189] [New LWP 187] [New LWP 191] [New LWP 201] [New LWP 190] [New LWP 192] [New LWP 196] [New LWP 182] [New LWP 348] [New LWP 205] [New LWP 202] [New LWP 208] [New LWP 203] [New LWP 358] [New LWP 353] [New LWP 204] [New LWP 357] [New LWP 207] [New LWP 206] [New LWP 352] [New LWP 349] [Thread debugging using libthread_db enabled] Using host libthread_db library "/lib/x86_64-linux-gnu/libthread_db.so.1". Core was generated by `/usr/bin/open5gs-smfd -c open5gs-smf.yaml'. Program terminated with signal SIGSEGV, Segmentation fault. #0 0x00007fede2c86f69 in ogs_pfcp_parse_volume_measurement (volume=volume@entry=0x7fed80ff69b0, warning: 591 ../lib/pfcp/types.c: No such file or directory octet=octet@entry=0x7fed5400c7d0) at ../lib/pfcp/types.c:591 [Current thread is 1 (Thread 0x7fed80ff96c0 (LWP 209))] #0 0x00007fede2c86f69 in ogs_pfcp_parse_volume_measurement (volume=volume@entry=0x7fed80ff69b0, octet=octet@entry=0x7fed5400c7d0) at ../lib/pfcp/types.c:591 #1 0x000055be55802886 in smf_epc_n4_handle_session_deletion_response (sess=sess@entry=0x7fedcb7a3010, xact=xact@entry=0x7fedcaf3e748, rsp=rsp@entry=0x7fed5400c6b8) at ../src/smf/n4-handler.c:1548 #2 0x000055be557d4738 in smf_gsm_state_wait_pfcp_deletion (s=s@entry=0x7fedcb7a3850, e=e@entry=0x7fed9c000e30) at ../src/smf/gsm-sm.c:2313 #3 0x00007fede3863e24 in ogs_fsm_dispatch (fsm=0x7fedcb7a3850, event=0x7fed9c000e30) at ../lib/core/ogs-fsm.c:127 #4 0x00007fede3863e24 in ogs_fsm_dispatch (fsm=0x55be5d830258, event=0x7fed9c000e30) at ../lib/core/ogs-fsm.c:127 #5 0x000055be557c9dc6 in smf_state_operational (s=s@entry=0x7fed80ff8c20, e=e@entry=0x7fed9c000e30) at ../src/smf/smf-sm.c:441 #6 0x00007fede3863e24 in ogs_fsm_dispatch (fsm=0x7fed80ff8c20, event=0x7fed9c000e30) at ../lib/core/ogs-fsm.c:127 #7 0x000055be557bb859 in smf_main (data=<optimized out>) at ../src/smf/init.c:194 #8 0x00007fede3859005 in thread_worker (arg=0x55be5da6b190) at ../lib/core/ogs-thread.c:67 #9 0x00007fede25beb7b in start_thread (arg=<optimized out>) at ./nptl/pthread_create.c:448 #10 0x00007fede263c7f8 in __GI___clone3 () at ../sysdeps/unix/sysv/linux/x86_64/clone3.S:78 [1;34m[testenv] Stopping podman container[0m [0;94m[testenv] + ['podman', 'kill', 'testenv-smf-osmocom-latest-20260831-1308-5a804244-1'][0m testenv-smf-osmocom-latest-20260831-1308-5a804244-1 <https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/1/artifac…>: ELF 64-bit LSB core file, x86-64, version 1 (SYSV), SVR4-style, from 'open5gs-smfd -c open5gs-smf.yaml', real uid: 0, effective uid: 0, real gid: 0, effective gid: 0, execfn: '/usr/bin/open5gs-smfd', platform: 'x86_64' [1;34m[testenv] Stopping testsuite (1851773)[0m [0;94m[testenv] feed_watchdog_loop: podman container has stopped[0m [1;34m[testenv] Logs saved to: https://jenkins.osmocom.org/jenkins/job/ttcn3-smf-test-ogs-latest/1/artifac… [0m + RC=1 + [ 1 = 0 ] + + grep --color=always -o load.* uptime [01;31m[Kload average: 1.23, 0.89, 0.83[m[K + exit 1 Build step 'Execute shell' marked build as failure Recording test results [Checks API] No suitable checks publisher found.

1 8

Build failed in Jenkins: ttcn3-bts-test-io_uring #849
by jenkins＠lists.osmocom.org 08 Sep '26

08 Sep '26

See <https://jenkins.osmocom.org/jenkins/job/ttcn3-bts-test-io_uring/849/display…> Changes: ------------------------------------------ [...truncated 12.58 MiB...] <failure type='fail-verdict'>"BTS_Tests.ttcn:732 : Tguard timeout" BTS_Tests.ttcn:9557 BTS_Tests control part BTS_Tests.ttcn:7525 TC_rll_rel_req testcase </failure> </testcase> <testcase classname='BTS_Tests:hopping' name='TC_rll_unit_data_req_DCCH' time='2.342918'/> <testcase classname='BTS_Tests:hopping' name='TC_rll_unit_data_req_ACCH' time='5.292034'/> <testcase classname='BTS_Tests:hopping' name='TC_rll_unit_data_ind_DCCH' time='2.918450'/> <testcase classname='BTS_Tests:hopping' name='TC_rll_unit_data_ind_ACCH' time='11.511367'/> <testcase classname='BTS_Tests:hopping' name='TC_chan_act_a51' time='2.650276'/> <testcase classname='BTS_Tests:hopping' name='TC_chan_act_a52' time='2.699148'/> <testcase classname='BTS_Tests:hopping' name='TC_chan_act_a53' time='2.710186'/> <testcase classname='BTS_Tests:hopping' name='TC_chan_act_a54' time='2.717119'/> <testcase classname='BTS_Tests:hopping' name='TC_encr_cmd_a51' time='5.242888'/> <testcase classname='BTS_Tests:hopping' name='TC_encr_cmd_a52' time='5.208244'/> <testcase classname='BTS_Tests:hopping' name='TC_encr_cmd_a53' time='5.206919'/> <testcase classname='BTS_Tests:hopping' name='TC_encr_cmd_a54' time='5.215719'/> <testcase classname='BTS_Tests:hopping' name='TC_err_rep_wrong_mdisc' time='1.208878'/> <testcase classname='BTS_Tests:hopping' name='TC_err_rep_wrong_msg_type' time='1.305426'/> <testcase classname='BTS_Tests:hopping' name='TC_err_rep_wrong_sequence' time='1.268411'/> <testcase classname='BTS_Tests:hopping' name='TC_lapdm_selftest' time='0.005557'/> <testcase classname='BTS_Tests:hopping' name='TC_tch_sign_l2_fill_frame' time='18.463654'/> <testcase classname='BTS_Tests:hopping' name='TC_tch_sign_l2_fill_frame_dtxd' time='2.106338'> <failure type='fail-verdict'>Received DTX TCH fill frame with bad frame number: 175 (mod 104: 71) (mod 26: 19) BTS_Tests.ttcn:9579 BTS_Tests control part BTS_Tests.ttcn:8110 TC_tch_sign_l2_fill_frame_dtxd testcase </failure> </testcase> <testcase classname='BTS_Tests:hopping' name='TC_chopped_ipa_ping' time='24.694319'/> <testcase classname='BTS_Tests:hopping' name='TC_chopped_ipa_payload' time='42.615309'/> <testcase classname='BTS_Tests:hopping' name='TC_ms_pwr_ctrl_constant' time='16.897724'/> <testcase classname='BTS_Tests:hopping' name='TC_ms_pwr_ctrl_pf_ewma' time='31.998358'/> <testcase classname='BTS_Tests:hopping' name='TC_speech_no_rtp_tchf' time='7.438991'/> <testcase classname='BTS_Tests:hopping' name='TC_speech_no_rtp_tchh' time='5.462630'/> <testcase classname='BTS_Tests:hopping' name='TC_speech_rtp_tchf' time='7.706848'/> <testcase classname='BTS_Tests:hopping' name='TC_speech_rtp_tchh' time='5.481234'/> <testcase classname='BTS_Tests:hopping' name='TC_speech_osmux_tchf' time='3.453746'/> <testcase classname='BTS_Tests:hopping' name='TC_speech_osmux_tchh' time='3.502667'/> <testcase classname='BTS_Tests:hopping' name='TC_data_rtp_tchf144' time='2.094024'/> <testcase classname='BTS_Tests:hopping' name='TC_data_rtp_tchf96' time='2.142364'/> <testcase classname='BTS_Tests:hopping' name='TC_data_rtp_tchf48' time='2.200510'/> <testcase classname='BTS_Tests:hopping' name='TC_data_rtp_tchh48' time='5.307154'> <failure type='fail-verdict'>Timeout waiting for matching Downlink data frame BTS_Tests.ttcn:9597 BTS_Tests control part BTS_Tests.ttcn:8880 TC_data_rtp_tchh48 testcase </failure> </testcase> <testcase classname='BTS_Tests:hopping' name='TC_data_rtp_tchf24' time='1.783627'/> <testcase classname='BTS_Tests:hopping' name='TC_data_rtp_tchh24' time='5.307066'> <failure type='fail-verdict'>Timeout waiting for matching Downlink data frame BTS_Tests.ttcn:9599 BTS_Tests control part BTS_Tests.ttcn:8949 TC_data_rtp_tchh24 testcase </failure> </testcase> <testcase classname='BTS_Tests:hopping' name='TC_data_facch_tchf144' time='2.083183'/> <testcase classname='BTS_Tests:hopping' name='TC_data_facch_tchf96' time='2.027918'/> <testcase classname='BTS_Tests:hopping' name='TC_data_facch_tchf48' time='1.985915'/> <testcase classname='BTS_Tests:hopping' name='TC_data_facch_tchh48' time='2.128729'/> <testcase classname='BTS_Tests:hopping' name='TC_data_facch_tchf24' time='2.025097'/> <testcase classname='BTS_Tests:hopping' name='TC_data_facch_tchh24' time='2.166861'/> <testcase classname='BTS_Tests:hopping' name='TC_early_immediate_assignment' time='3.545980'/> <testcase classname='BTS_Tests:hopping' name='TC_acch_overpower_rxqual_thresh' time='9.086858'> <failure type='fail-verdict'>Timeout waiting for L1CTL DATA.ind BTS_Tests.ttcn:9610 BTS_Tests control part BTS_Tests.ttcn:9244 TC_acch_overpower_rxqual_thresh testcase </failure> </testcase> <testcase classname='BTS_Tests:hopping' name='TC_acch_overpower_rxqual_thresh_dtx' time='9.043845'> <failure type='fail-verdict'>Timeout waiting for L1CTL DATA.ind BTS_Tests.ttcn:9611 BTS_Tests control part BTS_Tests.ttcn:9285 TC_acch_overpower_rxqual_thresh_dtx testcase </failure> </testcase> <testcase classname='BTS_Tests:hopping' name='TC_acch_overpower_always_on_facch' time='9.020579'> <failure type='fail-verdict'>Timeout waiting for L1CTL DATA.ind BTS_Tests.ttcn:9612 BTS_Tests control part BTS_Tests.ttcn:9317 TC_acch_overpower_always_on_facch testcase </failure> </testcase> <testcase classname='BTS_Tests:hopping' name='TC_acch_overpower_always_on_sacch' time='9.030794'> <failure type='fail-verdict'>Timeout waiting for L1CTL DATA.ind BTS_Tests.ttcn:9613 BTS_Tests control part BTS_Tests.ttcn:9349 TC_acch_overpower_always_on_sacch testcase </failure> </testcase> <testcase classname='BTS_Tests:hopping' name='TC_acch_overpower_limit' time='9.025674'> <failure type='fail-verdict'>Timeout waiting for L1CTL DATA.ind BTS_Tests.ttcn:9614 BTS_Tests control part BTS_Tests.ttcn:9380 TC_acch_overpower_limit testcase </failure> </testcase> <testcase classname='BTS_Tests_SMSCB:hopping' name='TC_cbch_load_idle_no_cbch' time='11.163746'/> <testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch4_1block' time='14.242062'/> <testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch4_2block' time='14.189691'/> <testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch4_3block' time='14.203221'/> <testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch4_4block' time='14.198932'/> <testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch4_multi' time='20.199785'/> <testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch4_schedule' time='14.138432'/> <testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch4_default_only' time='16.197110'/> <testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch4_default_and_normal' time='17.193498'/> <testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch4_default_then_null' time='26.213650'/> <testcase classname='BTS_Tests_SMSCB:hopping' name='TC_cbc_sdcch4_load_idle' time='16.062186'/> <testcase classname='BTS_Tests_SMSCB:hopping' name='TC_cbc_sdcch4_load_overload' time='18.174633'/> <testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch8_1block' time='14.207873'/> <testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch8_2block' time='14.223714'/> <testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch8_3block' time='14.207990'/> <testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch8_4block' time='14.205683'/> <testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch8_multi' time='20.227691'/> <testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch8_schedule' time='14.166550'/> <testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch8_default_only' time='16.237712'/> <testcase classname='BTS_Tests_SMSCB:hopping' name='TC_sms_cb_cmd_sdcch8_default_and_normal' time='17.205582'/> <testcase classname='BTS_Tests_SMSCB:hopping' name='TC_cbc_sdcch8_load_idle' time='16.147247'/> <testcase classname='BTS_Tests_SMSCB:hopping' name='TC_cbc_sdcch8_load_overload' time='18.250367'/> <testcase classname='BTS_Tests_SMSCB:hopping' name='TC_etws_p1ro' time='12.195901'/> <testcase classname='BTS_Tests_SMSCB:hopping' name='TC_etws_p1ro_end' time='16.143973'/> <testcase classname='BTS_Tests_SMSCB:hopping' name='TC_etws_pcu' time='1.085141'/> <testcase classname='BTS_Tests_LAPDm:hopping' name='TC_sabm_ua_dcch_sapi0' time='2.909533'/> <testcase classname='BTS_Tests_LAPDm:hopping' name='TC_sabm_ua_dcch_sapi0_nopayload' time='21.563093'/> <testcase classname='BTS_Tests_LAPDm:hopping' name='TC_sabm_ua_dcch_sapi3' time='21.273371'/> <testcase classname='BTS_Tests_LAPDm:hopping' name='TC_sabm_ua_dcch_sapi4' time='21.339864'/> <testcase classname='BTS_Tests_LAPDm:hopping' name='TC_sabm_contention' time='21.289912'/> <testcase classname='BTS_Tests_LAPDm:hopping' name='TC_sabm_retransmit' time='3.163055'/> <testcase classname='BTS_Tests_LAPDm:hopping' name='TC_sabm_retransmit_bts' time='81.460943'/> <testcase classname='BTS_Tests_LAPDm:hopping' name='TC_sabm_invalid_resp' time='23.348972'/> <testcase classname='BTS_Tests_LAPDm:hopping' name='TC_sabm_dm' time='14.000164'/> <testcase classname='BTS_Tests_LAPDm:hopping' name='TC_establish_ign_first_sabm' time='7.947384'/> <testcase classname='BTS_Tests_LAPDm:hopping' name='TC_iframe_seq_and_ack' time='29.568167'> <failure type='fail-verdict'>Received RSL ERROR IND { msg_disc := { msg_group := RSL_MDISC_RLL (1), transparent := false }, msg_type := RSL_MT_ERROR_IND (3), ies := { { iei := RSL_IE_CHAN_NR (1), body := { chan_nr := { u := { sdcch8 := { tag := '01'B, sub_chan := 4 } }, tn := 6 } } }, { iei := RSL_IE_LINK_IDENT (2), body := { link_id := { c := FACCH_SDCCH (0), na := false, prio := SAPI0_PRIO_NORMAL (0), sapi := 0 } } }, { iei := RSL_IE_RLM_CAUSE (22), body := { rlm_cause := { len := 1, payload := '01'O } } } } } BTS_Tests_LAPDm.ttcn:1484 BTS_Tests_LAPDm control part BTS_Tests_LAPDm.ttcn:579 TC_iframe_seq_and_ack testcase </failure> </testcase> <testcase classname='BTS_Tests_LAPDm:hopping' name='TC_iframe_timer_recovery' time='24.616309'> <failure type='fail-verdict'>Received RSL ERROR IND { msg_disc := { msg_group := RSL_MDISC_RLL (1), transparent := false }, msg_type := RSL_MT_ERROR_IND (3), ies := { { iei := RSL_IE_CHAN_NR (1), body := { chan_nr := { u := { sdcch8 := { tag := '01'B, sub_chan := 4 } }, tn := 6 } } }, { iei := RSL_IE_LINK_IDENT (2), body := { link_id := { c := FACCH_SDCCH (0), na := false, prio := SAPI0_PRIO_NORMAL (0), sapi := 0 } } }, { iei := RSL_IE_RLM_CAUSE (22), body := { rlm_cause := { len := 1, payload := '01'O } } } } } BTS_Tests_LAPDm.ttcn:1485 BTS_Tests_LAPDm control part BTS_Tests_LAPDm.ttcn:685 TC_iframe_timer_recovery testcase </failure> </testcase> <testcase classname='BTS_Tests_LAPDm:hopping' name='TC_ns_seq_error' time='29.603420'> <failure type='fail-verdict'>"BTS_Tests.ttcn:732 : Tguard timeout" BTS_Tests_LAPDm.ttcn:1486 BTS_Tests_LAPDm control part BTS_Tests_LAPDm.ttcn:768 TC_ns_seq_error testcase </failure> </testcase> <testcase classname='BTS_Tests_LAPDm:hopping' name='TC_nr_seq_error' time='2.901309'/> <testcase classname='BTS_Tests_LAPDm:hopping' name='TC_rec_invalid_frame' time='119.108605'> <failure type='fail-verdict'>"BTS_Tests_LAPDm.ttcn:909 : Missing LAPDm_RR RSP" BTS_Tests_LAPDm.ttcn:1488 BTS_Tests_LAPDm control part BTS_Tests_LAPDm.ttcn:961 TC_rec_invalid_frame testcase </failure> </testcase> <testcase classname='BTS_Tests_LAPDm:hopping' name='TC_segm_concat_dcch' time='13.548481'> <failure type='fail-verdict'>"BTS_Tests_LAPDm.ttcn:1023 : received unexpected LAPDm { sacch := false, sapi := 0, lapdm := { ab := { addr := { spare := '0'B, lpd := 0, sapi := 0, c_r := false, ea := true }, ctrl := { s := { n_r := 1, p_f := false, s := '10'B, spare := '01'B } }, len := 0, m := false, el := 1, payload := ''O, padding := '2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B'O (\"++++++++++++++++++++\") } } }" BTS_Tests_LAPDm.ttcn:1489 BTS_Tests_LAPDm control part BTS_Tests_LAPDm.ttcn:1088 TC_segm_concat_dcch testcase </failure> </testcase> <testcase classname='BTS_Tests_LAPDm:hopping' name='TC_segm_concat_sacch' time='48.518003'> <failure type='fail-verdict'>"BTS_Tests_LAPDm.ttcn:1023 : received unexpected LAPDm { sacch := true, sapi := 0, lapdm := { ab := { addr := { spare := '0'B, lpd := 0, sapi := 0, c_r := false, ea := true }, ctrl := { u := { u := '011'B, p_f := true, u2 := '00'B, spare := '11'B } }, len := 0, m := false, el := 1, payload := ''O, padding := '2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B'O (\"++++++++++++++++++\") } } }" BTS_Tests_LAPDm.ttcn:1490 BTS_Tests_LAPDm control part BTS_Tests_LAPDm.ttcn:1093 TC_segm_concat_sacch testcase </failure> </testcase> <testcase classname='BTS_Tests_LAPDm:hopping' name='TC_t200_n200' time='39.841267'> <failure type='fail-verdict'>Missing RSL RLL ERROR INDICATION BTS_Tests_LAPDm.ttcn:1491 BTS_Tests_LAPDm control part BTS_Tests_LAPDm.ttcn:1182 TC_t200_n200 testcase </failure> </testcase> <testcase classname='BTS_Tests_LAPDm:hopping' name='TC_rr_response_frame_loss' time='6.683598'> <failure type='fail-verdict'>Timeout waiting for RR or REJ BTS_Tests_LAPDm.ttcn:1492 BTS_Tests_LAPDm control part BTS_Tests_LAPDm.ttcn:1239 TC_rr_response_frame_loss testcase </failure> </testcase> <testcase classname='BTS_Tests_LAPDm:hopping' name='TC_incorrect_cr' time='15.825487'/> <testcase classname='BTS_Tests_LAPDm:hopping' name='TC_sabm_incorrect_c' time='23.302386'> <failure type='fail-verdict'>"BTS_Tests.ttcn:732 : Tguard timeout" BTS_Tests_LAPDm.ttcn:1494 BTS_Tests_LAPDm control part BTS_Tests_LAPDm.ttcn:1341 TC_sabm_incorrect_c testcase </failure> </testcase> <testcase classname='BTS_Tests_LAPDm:hopping' name='TC_normal_reestablishment' time='16.132537'> <failure type='fail-verdict'>"BTS_Tests_LAPDm.ttcn:1023 : received unexpected LAPDm { sacch := false, sapi := 0, lapdm := { ab := { addr := { spare := '0'B, lpd := 0, sapi := 0, c_r := false, ea := true }, ctrl := { u := { u := '011'B, p_f := true, u2 := '00'B, spare := '11'B } }, len := 5, m := false, el := 1, payload := '384B4BA0DD'O, padding := '2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B'O (\"+++++++++++++++\") } } }" BTS_Tests_LAPDm.ttcn:1495 BTS_Tests_LAPDm control part BTS_Tests_LAPDm.ttcn:1403 TC_normal_reestablishment testcase </failure> </testcase> <testcase classname='BTS_Tests_LAPDm:hopping' name='TC_normal_reestablishment_state_unacked' time='12.408751'> <failure type='fail-verdict'>"BTS_Tests_LAPDm.ttcn:1023 : received unexpected LAPDm { sacch := false, sapi := 0, lapdm := { ab := { addr := { spare := '0'B, lpd := 0, sapi := 0, c_r := false, ea := true }, ctrl := { s := { n_r := 1, p_f := false, s := '10'B, spare := '01'B } }, len := 0, m := false, el := 1, payload := ''O, padding := '2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B2B'O (\"++++++++++++++++++++\") } } }" BTS_Tests_LAPDm.ttcn:1496 BTS_Tests_LAPDm control part BTS_Tests_LAPDm.ttcn:1470 TC_normal_reestablishment_state_unacked testcase </failure> </testcase> <testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_act_vff' time='2.473522'/> <testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_act_vhh' time='2.587559'/> <testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_act_hvhh' time='2.549683'/> <testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_act_dyn_ipa_vff' time='2.542742'/> <testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_act_dyn_osmo_vff' time='2.528251'/> <testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_act_dyn_osmo_vhh' time='2.577991'/> <testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_act_dyn_osmo_hvhh' time='2.566651'/> <testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_mode_modify_vff' time='1.303282'/> <testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_mode_modify_vhh' time='1.375615'/> <testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_mode_modify_hvhh' time='1.325253'/> <testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_mode_modify_dyn_ipa_vff' time='1.340647'/> <testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_mode_modify_dyn_osmo_vff' time='1.341694'/> <testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_mode_modify_dyn_osmo_vhh' time='1.334550'/> <testcase classname='BTS_Tests_VAMOS:hopping' name='TC_vamos_chan_mode_modify_dyn_osmo_hvhh' time='1.385772'/> <testcase classname='BTS_Tests_ASCI:hopping' name='TC_vbs_notification' time='4.663183'/> <testcase classname='BTS_Tests_ASCI:hopping' name='TC_vgcs_uplink_free_and_busy' time='1.835520'/> <testcase classname='BTS_Tests_ASCI:hopping' name='TC_vgcs_talker_fail' time='16.554889'/> <testcase classname='BTS_Tests_ASCI:hopping' name='TC_vgcs_talker_est_rel' time='1.439544'/> <testcase classname='BTS_Tests_ASCI:hopping' name='TC_vgcs_listener_det' time='3.308056'/> </testsuite> <?xml version="1.0"?> <testsuite name='Titan' tests='17' failures='0' errors='0' skipped='0' inconc='0' time='96.00'> <testcase classname='BTS_Tests_OML' name='TC_wrong_mdisc' time='10.855609'/> <testcase classname='BTS_Tests_OML' name='TC_wrong_msgtype' time='0.712234'/> <testcase classname='BTS_Tests_OML' name='TC_short_length' time='0.711826'/> <testcase classname='BTS_Tests_OML' name='TC_long_length' time='0.706585'/> <testcase classname='BTS_Tests_OML' name='TC_wrong_placement' time='0.712584'/> <testcase classname='BTS_Tests_OML' name='TC_wrong_seq' time='0.669659'/> <testcase classname='BTS_Tests_OML' name='TC_wrong_obj_class' time='0.679135'/> <testcase classname='BTS_Tests_OML' name='TC_wrong_bts_nr' time='0.717946'/> <testcase classname='BTS_Tests_OML' name='TC_wrong_trx_nr' time='0.695812'/> <testcase classname='BTS_Tests_OML' name='TC_radio_carrier_opstart_noattr' time='0.677893'/> <testcase classname='BTS_Tests_OML' name='TC_bts_opstart' time='0.695455'/> <testcase classname='BTS_Tests_OML' name='TC_bts_opstart_noattr' time='0.679089'/> <testcase classname='BTS_Tests_OML' name='TC_ts_opstart' time='0.680640'/> <testcase classname='BTS_Tests_OML' name='TC_ts_opstart_noattr' time='0.676068'/> <testcase classname='BTS_Tests_OML' name='TC_initial_state_reports' time='0.671526'/> <testcase classname='BTS_Tests_OML' name='TC_ipa_osmo_pcu_anr_fwd' time='0.695731'/> <testcase classname='BTS_Tests_OML' name='TC_ipa_rsl_connect_nack' time='0.674729'/> </testsuite> + check_respawn_count + set +x [0;31m ================================================================ WARNING: osmo-bts was respawned 4 times (expected: 3)! (OS#6794) ================================================================ [0m+ RC=1 + uptime + grep --color=always -o load.* [01;31m[Kload average: 1.10, 1.05, 0.79[m[K + exit 1 Build step 'Execute shell' marked build as failure Recording test results [Checks API] No suitable checks publisher found.

1 0

2026

2025

2024

2023

2022

2021

2020

2019

jenkins-notifications September 2026