Hello everyone,
I'm trying to debug a pretty annoying issue with maintaining accurate subscriber-to-IP associations for PPPoE customers in our SCE SM database. p3radiussniff shows lots of accounting-stop RDRs and as many failed logouts (zero successful logouts, not even a single one), umlog shows "events" like the one quoted below and the subscriber database gets full of subscribers without any IP mappings and subscribers with multiple IP mappings - I'll try to think of a way to track the "movement" of IP mappings among subscribers between two successive database exports, but I'm really puzzled by the absolute zero successful logouts in p3radiussniff --show-statistics.
Many thanks in advance for any hints you may have on this.
"27-Mar 2020", "14:45:49.316 EET","[rdrconn/S.S.S.S/47988]",WARN ,"com.pcube.management.common.threads.LockedObjects","unlock of a non-locked object 1150481301196190205"
"27-Mar 2020", "14:45:49.316 EET","[rdrconn/S.S.S.S/47988]",ERROR,"com.pcube.management.leg.RdrRadiusListener","could not logout subscriber"
javax.management.RuntimeMBeanException: java.lang.RuntimeException: unlock of a non-locked object 1150481301196190205
at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.rethrow(Unknown Source)
at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.rethrowMaybeMBeanException(Unknown Source)
at com.sun.jmx.interceptor.DefaultMBeanServerInterceptor.invoke(Unknown Source)
at com.sun.jmx.mbeanserver.JmxMBeanServer.invoke(Unknown Source)
at com.sun.jdmk.MBeanServerForwarder.invoke(MBeanServerForwarder.java:247)
at com.pcube.management.framework.agent.info.MBeanServerTraceInfo.invoke(MBeanServerTraceInfo.java:93)
at com.pcube.management.leg.RdrRadiusListener.logoutByMappings(RdrRadiusListener.java:1901)
at com.pcube.management.leg.RdrRadiusListener.handleAccountingStop(RdrRadiusListener.java:561)
at com.pcube.management.leg.RdrRadiusListener.handleAccountingPacket(RdrRadiusListener.java:389)
at com.pcube.management.leg.RdrRadiusListener.handle(RdrRadiusListener.java:228)
at com.pcube.management.framework.rdr.mbean.RdrDispatcher.handle(RdrDispatcher.java:156)
at com.pcube.management.framework.rdr.server.RDRServer.handleRDR(RDRServer.java:509)
at com.pcube.management.framework.rdr.server.RDRConnection.getRdrFromStream(RDRConnection.java:163)
at com.pcube.management.framework.rdr.server.RDRConnection.run(RDRConnection.java:110)
Caused by: java.lang.RuntimeException: unlock of a non-locked object 1150481301196190205
at com.pcube.management.common.threads.LockedObjects.unlock(LockedObjects.java:219)
at com.pcube.management.smm.database.DataRepositoryTimesTenImpl.deleteMappingsByMappings(DataRepositoryTimesTenImpl.java:10155)
at com.pcube.management.smm.database.DataRepositoryTimesTenImpl.deleteMappingsAndGetSEList(DataRepositoryTimesTenImpl.java:10654)
at com.pcube.management.smm.SmmMbean.internalLogoutByMapping(SmmMbean.java:4862)
at com.pcube.management.smm.SmmMbean.logoutByMapping(SmmMbean.java:9109)
at sun.reflect.GeneratedMethodAccessor10.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(Unknown Source)
at java.lang.reflect.Method.invoke(Unknown Source)
at com.pcube.management.framework.jdmk.ExtendedMBean.invokeMethod(ExtendedMBean.java:834)
at com.pcube.management.framework.jdmk.ExtendedMBean.invoke(ExtendedMBean.java:623)
at com.pcube.management.smm.SmmMbean.invoke(SmmMbean.java:1503)
... 12 more
"27-Mar 2020", "14:45:49.316 EET","[rdrconn/S.S.S.S/47988]",INFO ,"com.pcube.logger.userlog.UserLog","RADIUS: Failed to logout mapping X.X.X.X/32 (unlock of a non-locked object 1150481301196190205)"
