Enquire time not respected?

[javadevmtl]

Hi using 2.3.10

On bind I call serverSession.setEnquireLinkTimer(30000);

But the client says they get an enquire link every 5 seconds.

[pmoerenhout]

The enquire_link can be send from both sides. When you call setEnquireLinkTimer, it controls the timer on your side. It's the remote side, which sends you every 5 seconds a enquire_link PDU. You could ask the remote party to increase this timer. On a reliable connection, I would say that 5 seconds is a little too much. 60 seconds should be fine.

From the SMPP 5 specification:

_This PDU can be originated by either the ESME or MC and is used to provide a confidence- check of the communication path between an ESME and a MC. On receipt of this request the receiving party should respond with an enquire_linkresp, thus verifying that the application level connection between the MC and the ESME is functioning. The ESME may also respond by sending any valid SMPP primitive.

[javadevmtl]

Ok well the client side informed me that it's happening every 5 seconds unless they are unaware of a default setting.

Also which setting gets priority if both sides set it?

Let me get back to you.

[javadevmtl]

Ok I looked at the client side. It's Kannel and it has the following setting: enquire-link-interval = 60

Btw I don't see the enquire events on the server side is there a way to log them?

[pmoerenhout]

You need to set the log level on the server to debug to see the all messages: if (logger.isDebugEnabled()) { logger.debug("Received PDU {}", HexUtil.convertBytesToHexString(pdu, 0, pdu.length)); }

Each side can send enquiry_link requests. Each side has it's on timer. So the sending by the server if independent on the client. I tried with serverSession.setEnquireLinkTimer(60000) on the server and the timing was then 60 seconds as expected. The enquiry timer is using the underlying socket SoTimeout. On which platform are you using the server ?

[javadevmtl]

Ok going to check this out now.

[javadevmtl]

Ok I have to get back to you. I embedded JSMPP in a http://quarkus.io application and they are using Jboss logging. So I need to see which adapter to use for it to make the JSMPP log4j logs print.

[javadevmtl]

Ok here it is... I'm running in Docker, Java 11 Image is: registry.access.redhat.com/ubi8/ubi-minimal:8.1

I'm looking at the code though I'm confused... How does this tell the enquirer to fire every 60 seconds?

 connection().setSoTimeout(enquireLinkTimer);

2020-11-25 15:04:42,934 INFO  [com.xxx.SmppServerCommand] (main) New state bound: true, Old state bound: false, Source: 7fb64c0b
2020-11-25 15:04:42,935 DEBUG [org.jsm.DefaultPDUSender] (main) Sending SMPP message 00 00 00 1e 80 00 00 09 00 00 00 00 00 00 42 0a 73 6d 70 70 5f 64 65 76 00 02 10 00 01 34 
2020-11-25 15:04:42,934 DEBUG [org.jsm.ses.AbstractSession] (EnquireLinkSender-7fb64c0b) Starting EnquireLinkSender for session 7fb64c0b
2020-11-25 15:04:42,939 INFO  [com.xxx.SmppServerCommand] (main) The session is now in state BOUND_TRX
2020-11-25 15:04:46,505 DEBUG [org.jsm.ses.SMPPServerSession] (PDUReaderWorker-ff858e53) No activity notified, sending enquireLink
2020-11-25 15:04:46,510 DEBUG [org.jsm.DefaultPDUSender] (EnquireLinkSender-ff858e53) Sending SMPP message 00 00 00 10 00 00 00 15 00 00 00 00 00 00 00 01 
2020-11-25 15:04:46,515 DEBUG [org.jsm.ses.PDUProcessServerTask] (pool-7-thread-2) Received SMPP message PDUHeader(16, 80000015, 00000000, 1) 00 00 00 10 80 00 00 15 00 00 00 00 00 00 00 01 
2020-11-25 15:04:46,516 DEBUG [org.jsm.ses.AbstractSession] (EnquireLinkSender-ff858e53) enquire_link response with sequence_number 1 received for session ff858e53
2020-11-25 15:04:47,504 DEBUG [org.jsm.ses.SMPPServerSession] (PDUReaderWorker-7fb64c0b) No activity notified, sending enquireLink
2020-11-25 15:04:47,505 DEBUG [org.jsm.DefaultPDUSender] (EnquireLinkSender-7fb64c0b) Sending SMPP message 00 00 00 10 00 00 00 15 00 00 00 00 00 00 00 01 
2020-11-25 15:04:47,509 DEBUG [org.jsm.ses.PDUProcessServerTask] (pool-9-thread-2) Received SMPP message PDUHeader(16, 80000015, 00000000, 1) 00 00 00 10 80 00 00 15 00 00 00 00 00 00 00 01 
2020-11-25 15:04:47,510 DEBUG [org.jsm.ses.AbstractSession] (EnquireLinkSender-7fb64c0b) enquire_link response with sequence_number 1 received for session 7fb64c0b
2020-11-25 15:04:51,517 DEBUG [org.jsm.ses.SMPPServerSession] (PDUReaderWorker-ff858e53) No activity notified, sending enquireLink
2020-11-25 15:04:51,518 DEBUG [org.jsm.DefaultPDUSender] (EnquireLinkSender-ff858e53) Sending SMPP message 00 00 00 10 00 00 00 15 00 00 00 00 00 00 00 02 
2020-11-25 15:04:51,522 DEBUG [org.jsm.ses.PDUProcessServerTask] (pool-7-thread-3) Received SMPP message PDUHeader(16, 80000015, 00000000, 2) 00 00 00 10 80 00 00 15 00 00 00 00 00 00 00 02 
2020-11-25 15:04:51,522 DEBUG [org.jsm.ses.AbstractSession] (EnquireLinkSender-ff858e53) enquire_link response with sequence_number 2 received for session ff858e53
2020-11-25 15:04:52,514 DEBUG [org.jsm.ses.SMPPServerSession] (PDUReaderWorker-7fb64c0b) No activity notified, sending enquireLink
2020-11-25 15:04:52,515 DEBUG [org.jsm.DefaultPDUSender] (EnquireLinkSender-7fb64c0b) Sending SMPP message 00 00 00 10 00 00 00 15 00 00 00 00 00 00 00 02 
2020-11-25 15:04:52,519 DEBUG [org.jsm.ses.PDUProcessServerTask] (pool-9-thread-3) Received SMPP message PDUHeader(16, 80000015, 00000000, 2) 00 00 00 10 80 00 00 15 00 00 00 00 00 00 00 02 
2020-11-25 15:04:52,520 DEBUG [org.jsm.ses.AbstractSession] (EnquireLinkSender-7fb64c0b) enquire_link response with sequence_number 2 received for session 7fb64c0b
2020-11-25 15:04:56,524 DEBUG [org.jsm.ses.SMPPServerSession] (PDUReaderWorker-ff858e53) No activity notified, sending enquireLink
2020-11-25 15:04:56,525 DEBUG [org.jsm.DefaultPDUSender] (EnquireLinkSender-ff858e53) Sending SMPP message 00 00 00 10 00 00 00 15 00 00 00 00 00 00 00 03 
2020-11-25 15:04:56,527 DEBUG [org.jsm.ses.PDUProcessServerTask] (pool-7-thread-1) Received SMPP message PDUHeader(16, 80000015, 00000000, 3) 00 00 00 10 80 00 00 15 00 00 00 00 00 00 00 03 
2020-11-25 15:04:56,527 DEBUG [org.jsm.ses.AbstractSession] (EnquireLinkSender-ff858e53) enquire_link response with sequence_number 3 received for session ff858e53

[javadevmtl]

I also see this... Which explains the every 500ms

while (!sendingEnquireLink.compareAndSet(true, false) && !Thread.currentThread().isInterrupted() && isReadPdu()) {
                    synchronized (sendingEnquireLink) {
                        try {
                            sendingEnquireLink.wait(500);
                        } catch (InterruptedException e) {
                            Thread.currentThread().interrupt();
                            break;
                        }
                    }
                }

[pmoerenhout]

Not exactly. The log shows a sending every 5 seconds on link 7fb64c0b. The setSoTimeout() is implemented with socket.setSoTimeout(timeout); The underlying socket will notify the OS when no data is read/written for a while (the timeout). On the SMPP server the socket timeout is first used to get a bind in a certain time: conn.setSoTimeout(initiationTimer) in SMPPServerSessionListener. Maybe the changing of the socket timeout (for the usage of the enquiry) doesn't work. Could you try to set the initial timer (which default to 5 seconds) to a higher value? setInitiationTimer(60000) (and use of SMPPServerSessionListener(int port), sessionListener.accept(), etc)

I will try if I can reproduce this behaviour...

[javadevmtl]

Ooops read the time on the logs wrong. Ok let me try.

[javadevmtl]

Ok that seems to work...

SMPPServerSessionListener sessionListener = useSsl ?
    new SMPPServerSessionListener(port, new KeyStoreSSLServerSocketConnectionFactory()) : new SMPPServerSessionListener(port);

LOG.info("Listening on port {}{}", port, useSsl ? " (SSL)" : "");

 sessionListener.setInitiationTimer(60000);

while(true) {
....
}

2020-11-25 17:04:49,708 DEBUG [org.jsm.ses.AbstractSession] (EnquireLinkSender-f0994669) Starting EnquireLinkSender for session f0994669
2020-11-25 17:04:49,709 DEBUG [org.jsm.DefaultPDUSender] (main) Sending SMPP message 00 00 00 1e 80 00 00 09 00 00 00 00 00 00 42 11 73 6d 70 70 5f 64 65 76 00 02 10 00 01 34 
2020-11-25 17:04:49,712 INFO  [com.xxx.SmppServerCommand] (main) The session is now in state BOUND_TRX
2020-11-25 17:05:47,509 DEBUG [org.jsm.ses.PDUProcessServerTask] (pool-7-thread-2) Received SMPP message PDUHeader(16, 00000015, 00000000, 16915) 00 00 00 10 00 00 00 15 00 00 00 00 00 00 42 13 
2020-11-25 17:05:47,510 DEBUG [org.jsm.ses.SMPPServerSession] (pool-7-thread-2) Sending enquire_link_resp
2020-11-25 17:05:47,511 DEBUG [org.jsm.DefaultPDUSender] (pool-7-thread-2) Sending SMPP message 00 00 00 10 80 00 00 15 00 00 00 00 00 00 42 13 
2020-11-25 17:05:47,769 DEBUG [org.jsm.ses.PDUProcessServerTask] (pool-9-thread-2) Received SMPP message PDUHeader(16, 00000015, 00000000, 16914) 00 00 00 10 00 00 00 15 00 00 00 00 00 00 42 12 
2020-11-25 17:05:47,773 DEBUG [org.jsm.ses.SMPPServerSession] (pool-9-thread-2) Sending enquire_link_resp
2020-11-25 17:05:47,774 DEBUG [org.jsm.DefaultPDUSender] (pool-9-thread-2) Sending SMPP message 00 00 00 10 80 00 00 15 00 00 00 00 00 00 42 12 
2020-11-25 17:06:47,538 DEBUG [org.jsm.ses.PDUProcessServerTask] (pool-7-thread-3) Received SMPP message PDUHeader(16, 00000015, 00000000, 16916) 00 00 00 10 00 00 00 15 00 00 00 00 00 00 42 14 
2020-11-25 17:06:47,540 DEBUG [org.jsm.ses.SMPPServerSession] (pool-7-thread-3) Sending enquire_link_resp
2020-11-25 17:06:47,540 DEBUG [org.jsm.DefaultPDUSender] (pool-7-thread-3) Sending SMPP message 00 00 00 10 80 00 00 15 00 00 00 00 00 00 42 14 
2020-11-25 17:06:47,803 DEBUG [org.jsm.ses.SMPPServerSession] (PDUReaderWorker-f0994669) No activity notified, sending enquireLink
2020-11-25 17:06:47,807 DEBUG [org.jsm.DefaultPDUSender] (EnquireLinkSender-f0994669) Sending SMPP message 00 00 00 10 00 00 00 15 00 00 00 00 00 00 00 01 
2020-11-25 17:06:47,811 DEBUG [org.jsm.ses.PDUProcessServerTask] (pool-9-thread-3) Received SMPP message PDUHeader(16, 00000015, 00000000, 16915) 00 00 00 10 00 00 00 15 00 00 00 00 00 00 42 13 
2020-11-25 17:06:47,811 DEBUG [org.jsm.ses.SMPPServerSession] (pool-9-thread-3) Sending enquire_link_resp
2020-11-25 17:06:47,811 DEBUG [org.jsm.DefaultPDUSender] (pool-9-thread-3) Sending SMPP message 00 00 00 10 80 00 00 15 00 00 00 00 00 00 42 13 
2020-11-25 17:06:47,813 DEBUG [org.jsm.ses.PDUProcessServerTask] (pool-9-thread-1) Received SMPP message PDUHeader(16, 80000015, 00000000, 1) 00 00 00 10 80 00 00 15 00 00 00 00 00 00 00 01 
2020-11-25 17:06:47,814 DEBUG [org.jsm.ses.AbstractSession] (EnquireLinkSender-f0994669) enquire_link response with sequence_number 1 received for session f0994669

[pmoerenhout]

I see now where the problem is. When the setEnquiryTimer is set on the server session before the bound took place, the socket SoTimeout was not set. I will make a fix that the socket timeout is also set when the enquiry thread is starting when the bound state took place. In your code, you could set the setEnquireTimer after the bound took place: In WaitBindTask after the bindRequest.accept(...); I'll make the fix available in Maven in the coming days.

[javadevmtl]

Ok thanks. Your suggestion of doing after bindRequest.accept(...); works as well.

[pmoerenhout]

Release 2.3.11 is now in Maven Central. Can you try if this solves it as well ?

[javadevmtl]

Ok started using it. I let you know...

[javadevmtl]

Ok it doesn't seem to work? I removed the code, so it should default to 60 seconds?

bindRequest.accept(bindRequest.getSystemId(), InterfaceVersion.IF_34);
//serverSession.setEnquireLinkTimer(60000);

2020-12-01 19:24:50,319 DEBUG [org.jsm.DefaultPDUSender] (EnquireLinkSender-2061c3c0) Sending PDU 00000010000000150000000000000001
2020-12-01 19:24:50,323 DEBUG [org.jsm.ses.PDUProcessServerTask] (pool-7-thread-2) Received PDU 00000010800000150000000000000001
2020-12-01 19:24:50,325 DEBUG [org.jsm.ses.AbstractSession] (EnquireLinkSender-2061c3c0) enquire_link response with sequence_number 1 received for session 2061c3c0
2020-12-01 19:24:53,491 DEBUG [org.jsm.ses.SMPPServerSession] (PDUReaderWorker-f60107ca) No activity notified, sending enquireLink
2020-12-01 19:24:53,493 DEBUG [org.jsm.DefaultPDUSender] (EnquireLinkSender-f60107ca) Sending PDU 00000010000000150000000000000001
2020-12-01 19:24:53,502 DEBUG [org.jsm.ses.PDUProcessServerTask] (pool-11-thread-2) Received PDU 00000010800000150000000000000001
2020-12-01 19:24:53,504 DEBUG [org.jsm.ses.AbstractSession] (EnquireLinkSender-f60107ca) enquire_link response with sequence_number 1 received for session f60107ca
2020-12-01 19:24:55,329 DEBUG [org.jsm.ses.SMPPServerSession] (PDUReaderWorker-2061c3c0) No activity notified, sending enquireLink
2020-12-01 19:24:55,330 DEBUG [org.jsm.DefaultPDUSender] (EnquireLinkSender-2061c3c0) Sending PDU 00000010000000150000000000000002
2020-12-01 19:24:55,333 DEBUG [org.jsm.ses.PDUProcessServerTask] (pool-7-thread-3) Received PDU 00000010800000150000000000000002
2020-12-01 19:24:55,334 DEBUG [org.jsm.ses.AbstractSession] (EnquireLinkSender-2061c3c0) enquire_link response with sequence_number 2 received for session 2061c3c0
2020-12-01 19:24:58,507 DEBUG [org.jsm.ses.SMPPServerSession] (PDUReaderWorker-f60107ca) No activity notified, sending enquireLink
2020-12-01 19:24:58,508 DEBUG [org.jsm.DefaultPDUSender] (EnquireLinkSender-f60107ca) Sending PDU 00000010000000150000000000000002
2020-12-01 19:24:58,516 DEBUG [org.jsm.ses.PDUProcessServerTask] (pool-11-thread-3) Received PDU 00000010800000150000000000000002
2020-12-01 19:24:58,517 DEBUG [org.jsm.ses.AbstractSession] (EnquireLinkSender-f60107ca) enquire_link response with sequence_number 2 received for session f60107ca
2020-12-01 19:25:00,341 DEBUG [org.jsm.ses.SMPPServerSession] (PDUReaderWorker-2061c3c0) No activity notified, sending enquireLink
2020-12-01 19:25:00,343 DEBUG [org.jsm.DefaultPDUSender] (EnquireLinkSender-2061c3c0) Sending PDU 00000010000000150000000000000003
2020-12-01 19:25:00,350 DEBUG [org.jsm.ses.PDUProcessServerTask] (pool-7-thread-1) Received PDU 00000010800000150000000000000003
2020-12-01 19:25:00,358 DEBUG [org.jsm.ses.AbstractSession] (EnquireLinkSender-2061c3c0) enquire_link response with sequence_number 3 received for session 2061c3c0
2020-12-01 19:25:03,522 DEBUG [org.jsm.ses.SMPPServerSession] (PDUReaderWorker-f60107ca) No activity notified, sending enquireLink
2020-12-01 19:25:03,523 DEBUG [org.jsm.DefaultPDUSender] (EnquireLinkSender-f60107ca) Sending PDU 00000010000000150000000000000003
2020-12-01 19:25:03,528 DEBUG [org.jsm.ses.PDUProcessServerTask] (pool-11-thread-1) Received PDU 00000010800000150000000000000003
2020-12-01 19:25:03,528 DEBUG [org.jsm.ses.AbstractSession] (EnquireLinkSender-f60107ca) enquire_link response with sequence_number 3 received for session f60107ca
2020-12-01 19:25:05,355 DEBUG [org.jsm.ses.SMPPServerSession] (PDUReaderWorker-2061c3c0) No activity notified, sending enquireLink
2020-12-01 19:25:05,356 DEBUG [org.jsm.DefaultPDUSender] (EnquireLinkSender-2061c3c0) Sending PDU 00000010000000150000000000000004
2020-12-01 19:25:05,361 DEBUG [org.jsm.ses.PDUProcessServerTask] (pool-7-thread-2) Received PDU 00000010800000150000000000000004
2020-12-01 19:25:05,361 DEBUG [org.jsm.ses.AbstractSession] (EnquireLinkSender-2061c3c0) enquire_link response with sequence_number 4 received for session 2061c3c0
2020-12-01 19:25:08,536 DEBUG [org.jsm.ses.SMPPServerSession] (PDUReaderWorker-f60107ca) No activity notified, sending enquireLink
2020-12-01 19:25:08,537 DEBUG [org.jsm.DefaultPDUSender] (EnquireLinkSender-f60107ca) Sending PDU 00000010000000150000000000000004
2020-12-01 19:25:08,539 DEBUG [org.jsm.ses.PDUProcessServerTask] (pool-11-thread-2) Received PDU 00000010800000150000000000000004
2020-12-01 19:25:08,539 DEBUG [org.jsm.ses.AbstractSession] (EnquireLinkSender-f60107ca) enquire_link response with sequence_number 4 received for session f60107ca
2020-12-01 19:25:10,369 DEBUG [org.jsm.ses.SMPPServerSession] (PDUReaderWorker-2061c3c0) No activity notified, sending enquireLink
2020-12-01 19:25:10,371 DEBUG [org.jsm.DefaultPDUSender] (EnquireLinkSender-2061c3c0) Sending PDU 00000010000000150000000000000005
2020-12-01 19:25:10,373 DEBUG [org.jsm.ses.PDUProcessServerTask] (pool-7-thread-3) Received PDU 00000010800000150000000000000005
2020-12-01 19:25:10,373 DEBUG [org.jsm.ses.AbstractSession] (EnquireLinkSender-2061c3c0) enquire_link response with sequence_number 5 received for session 2061c3c0
2020-12-01 19:25:13,544 DEBUG [org.jsm.ses.SMPPServerSession] (PDUReaderWorker-f60107ca) No activity notified, sending enquireLink
2020-12-01 19:25:13,545 DEBUG [org.jsm.DefaultPDUSender] (EnquireLinkSender-f60107ca) Sending PDU 00000010000000150000000000000005
2020-12-01 19:25:13,548 DEBUG [org.jsm.ses.PDUProcessServerTask] (pool-11-thread-3) Received PDU 00000010800000150000000000000005
2020-12-01 19:25:13,548 DEBUG [org.jsm.ses.AbstractSession] (EnquireLinkSender-f60107ca) enquire_link response with sequence_number 5 received for session f60107ca
2020-12-01 19:25:15,378 DEBUG [org.jsm.ses.SMPPServerSession] (PDUReaderWorker-2061c3c0) No activity notified, sending enquireLink
2020-12-01 19:25:15,379 DEBUG [org.jsm.DefaultPDUSender] (EnquireLinkSender-2061c3c0) Sending PDU 00000010000000150000000000000006
2020-12-01 19:25:15,383 DEBUG [org.jsm.ses.PDUProcessServerTask] (pool-7-thread-1) Received PDU 00000010800000150000000000000006
2020-12-01 19:25:15,384 DEBUG [org.jsm.ses.AbstractSession] (EnquireLinkSender-2061c3c0) enquire_link response with sequence_number 6 received for session 2061c3c0
2020-12-01 19:25:18,554 DEBUG [org.jsm.ses.SMPPServerSession] (PDUReaderWorker-f60107ca) No activity notified, sending enquireLink
2020-12-01 19:25:18,556 DEBUG [org.jsm.DefaultPDUSender] (EnquireLinkSender-f60107ca) Sending PDU 00000010000000150000000000000006
2020-12-01 19:25:18,559 DEBUG [org.jsm.ses.PDUProcessServerTask] (pool-11-thread-1) Received PDU 00000010800000150000000000000006
2020-12-01 19:25:18,559 DEBUG [org.jsm.ses.AbstractSession] (EnquireLinkSender-f60107ca) enquire_link response with sequence_number 6 received for session f60107ca
2020-12-01 19:25:20,388 DEBUG [org.jsm.ses.SMPPServerSession] (PDUReaderWorker-2061c3c0) No activity notified, sending enquireLink
2020-12-01 19:25:20,390 DEBUG [org.jsm.DefaultPDUSender] (EnquireLinkSender-2061c3c0) Sending PDU 00000010000000150000000000000007
2020-12-01 19:25:20,394 DEBUG [org.jsm.ses.PDUProcessServerTask] (pool-7-thread-2) Received PDU 00000010800000150000000000000007
2020-12-01 19:25:20,394 DEBUG [org.jsm.ses.AbstractSession] (EnquireLinkSender-2061c3c0) enquire_link response with sequence_number 7 received for session 2061c3c0
2020-12-01 19:25:23,563 DEBUG [org.jsm.ses.SMPPServerSession] (PDUReaderWorker-f60107ca) No activity notified, sending enquireLink
2020-12-01 19:25:23,564 DEBUG [org.jsm.DefaultPDUSender] (EnquireLinkSender-f60107ca) Sending PDU 00000010000000150000000000000007
2020-12-01 19:25:23,567 DEBUG [org.jsm.ses.PDUProcessServerTask] (pool-11-thread-2) Received PDU 00000010800000150000000000000007
2020-12-01 19:25:23,567 DEBUG [org.jsm.ses.AbstractSession] (EnquireLinkSender-f60107ca) enquire_link response with sequence_number 7 received for session f60107ca
2020-12-01 19:25:25,398 DEBUG [org.jsm.ses.SMPPServerSession] (PDUReaderWorker-2061c3c0) No activity notified, sending enquireLink
2020-12-01 19:25:25,411 DEBUG [org.jsm.DefaultPDUSender] (EnquireLinkSender-2061c3c0) Sending PDU 00000010000000150000000000000008
2020-12-01 19:25:25,413 DEBUG [org.jsm.ses.PDUProcessServerTask] (pool-7-thread-3) Received PDU 00000010800000150000000000000008
2020-12-01 19:25:25,413 DEBUG [org.jsm.ses.AbstractSession] (EnquireLinkSender-2061c3c0) enquire_link response with sequence_number 8 received for session 2061c3c0
2020-12-01 19:25:28,570 DEBUG [org.jsm.ses.SMPPServerSession] (PDUReaderWorker-f60107ca) No activity notified, sending enquireLink
2020-12-01 19:25:28,572 DEBUG [org.jsm.DefaultPDUSender] (EnquireLinkSender-f60107ca) Sending PDU 00000010000000150000000000000008
2020-12-01 19:25:28,579 DEBUG [org.jsm.ses.PDUProcessServerTask] (pool-11-thread-3) Received PDU 00000010800000150000000000000008
2020-12-01 19:25:28,579 DEBUG [org.jsm.ses.AbstractSession] (EnquireLinkSender-f60107ca) enquire_link response with sequence_number 8 received for session f60107ca
2020-12-01 19:25:30,416 DEBUG [org.jsm.ses.SMPPServerSession] (PDUReaderWorker-2061c3c0) No activity notified, sending enquireLink
2020-12-01 19:25:30,417 DEBUG [org.jsm.DefaultPDUSender] (EnquireLinkSender-2061c3c0) Sending PDU 00000010000000150000000000000009
2020-12-01 19:25:30,419 DEBUG [org.jsm.ses.PDUProcessServerTask] (pool-7-thread-1) Received PDU 00000010800000150000000000000009
2020-12-01 19:25:30,419 DEBUG [org.jsm.ses.AbstractSession] (EnquireLinkSender-2061c3c0) enquire_link response with sequence_number 9 received for session 2061c3c0
2020-12-01 19:25:33,580 DEBUG [org.jsm.ses.SMPPServerSession] (PDUReaderWorker-f60107ca) No activity notified, sending enquireLink
2020-12-01 19:25:33,581 DEBUG [org.jsm.DefaultPDUSender] (EnquireLinkSender-f60107ca) Sending PDU 00000010000000150000000000000009
2020-12-01 19:25:33,584 DEBUG [org.jsm.ses.PDUProcessServerTask] (pool-11-thread-1) Received PDU 00000010800000150000000000000009
2020-12-01 19:25:33,584 DEBUG [org.jsm.ses.AbstractSession] (EnquireLinkSender-f60107ca) enquire_link response with sequence_number 9 received for session f60107ca
2020-12-01 19:25:35,424 DEBUG [org.jsm.ses.SMPPServerSession] (PDUReaderWorker-2061c3c0) No activity notified, sending enquireLink
2020-12-01 19:25:35,425 DEBUG [org.jsm.DefaultPDUSender] (EnquireLinkSender-2061c3c0) Sending PDU 0000001000000015000000000000000a
2020-12-01 19:25:35,429 DEBUG [org.jsm.ses.PDUProcessServerTask] (pool-7-thread-2) Received PDU 0000001080000015000000000000000a
2020-12-01 19:25:35,431 DEBUG [org.jsm.ses.AbstractSession] (EnquireLinkSender-2061c3c0) enquire_link response with sequence_number 10 received for session 2061c3c0
2020-12-01 19:25:38,586 DEBUG [org.jsm.ses.SMPPServerSession] (PDUReaderWorker-f60107ca) No activity notified, sending enquireLink
2020-12-01 19:25:38,589 DEBUG [org.jsm.DefaultPDUSender] (EnquireLinkSender-f60107ca) Sending PDU 0000001000000015000000000000000a
2020-12-01 19:25:38,595 DEBUG [org.jsm.ses.PDUProcessServerTask] (pool-11-thread-2) Received PDU 0000001080000015000000000000000a
2020-12-01 19:25:38,595 DEBUG [org.jsm.ses.AbstractSession] (EnquireLinkSender-f60107ca) enquire_link response with sequence_number 10 received for session f60107ca
2020-12-01 19:25:40,435 DEBUG [org.jsm.ses.SMPPServerSession] (PDUReaderWorker-2061c3c0) No activity notified, sending enquireLink
2020-12-01 19:25:40,436 DEBUG [org.jsm.DefaultPDUSender] (EnquireLinkSender-2061c3c0) Sending PDU 0000001000000015000000000000000b
2020-12-01 19:25:40,440 DEBUG [org.jsm.ses.PDUProcessServerTask] (pool-7-thread-3) Received PDU 0000001080000015000000000000000b
2020-12-01 19:25:40,440 DEBUG [org.jsm.ses.AbstractSession] (EnquireLinkSender-2061c3c0) enquire_link response with sequence_number 11 received for session 2061c3c0
2020-12-01 19:25:43,600 DEBUG [org.jsm.ses.SMPPServerSession] (PDUReaderWorker-f60107ca) No activity notified, sending enquireLink
2020-12-01 19:25:43,606 DEBUG [org.jsm.DefaultPDUSender] (EnquireLinkSender-f60107ca) Sending PDU 0000001000000015000000000000000b
2020-12-01 19:25:43,613 DEBUG [org.jsm.ses.PDUProcessServerTask] (pool-11-thread-3) Received PDU 0000001080000015000000000000000b