Lots of Error - 3E4 - in SMSDEBUG.LOG and slow sms

Lots of Error - 3E4 - in SMSDEBUG.LOG and slow sms SearchSearch
Author Message
Christoffer
New member
Username: Chx

Post Number: 1
Registered: 10-2020
Posted on Tuesday, October 13, 2020 - 01:29 pm:   

Hi

I am in the process of moving from a server with Now SMS/MMS Gateway v2010.02.09 to a new server with Now SMS/MMS Gateway v2020.01.15

After a test move of 1 modem (COM8), i notice it is slow when sending sms messages and i see lots of errors (timouts?) in the SMSDEBUG.LOG file.

Everything seems to work, but all the errors is worrisome.

Below is a snipppit of the errors, and they repeat forever.

How can i find the cause of this? I don't get it on the old server.

Ps. the new server is running trial license, so 30 sms/min limit, but i only get about 5sms/min.
I have bought the new license, but the bigger limit is great for finding the limit of the system, so it is not entered yet.


12:42:47:017 [20] ModemTest: COM8:
12:42:50:870 [20] ModemTest: OK
12:42:52:359 [20] ReceiveModemCommand: Error - 3E4 -
12:42:52:359 [20] ReceiveModemCommand: CancelIo
12:42:52:359 [20] ReceiveModemCommand: After CancelIo #1
12:42:52:359 [20] ReceiveModemCommand: After CancelIo #1a
12:43:07:360 [20] ReceiveModemCommand: After CancelIo #2
12:43:07:360 [20] ReceiveModemCommand: After CancelIo #3
12:43:13:112 [20] ReceiveModemCommand: Error - 3E4 -
12:43:13:112 [20] ReceiveModemCommand: CancelIo
12:43:13:112 [20] ReceiveModemCommand: After CancelIo #1
12:43:13:112 [20] ReceiveModemCommand: After CancelIo #1a
12:43:28:113 [20] ReceiveModemCommand: After CancelIo #2
12:43:28:113 [20] ReceiveModemCommand: After CancelIo #3
12:43:33:865 [20] ReceiveModemCommand: Error - 3E4 -
12:43:33:865 [20] ReceiveModemCommand: CancelIo
12:43:33:865 [20] ReceiveModemCommand: After CancelIo #1
12:43:33:865 [20] ReceiveModemCommand: After CancelIo #1a
12:43:48:866 [20] ReceiveModemCommand: After CancelIo #2
12:43:48:866 [20] ReceiveModemCommand: After CancelIo #3
12:43:54:627 [20] ReceiveModemCommand: Error - 3E4 -
12:43:54:627 [20] ReceiveModemCommand: CancelIo
12:43:54:627 [20] ReceiveModemCommand: After CancelIo #1
12:43:54:627 [20] ReceiveModemCommand: After CancelIo #1a
12:44:09:627 [20] ReceiveModemCommand: After CancelIo #2
12:44:09:627 [20] ReceiveModemCommand: After CancelIo #3
12:44:15:396 [20] ReceiveModemCommand: Error - 3E4 -
12:44:15:396 [20] ReceiveModemCommand: CancelIo
12:44:15:396 [20] ReceiveModemCommand: After CancelIo #1
12:44:15:396 [20] ReceiveModemCommand: After CancelIo #1a
12:44:30:397 [20] ReceiveModemCommand: After CancelIo #2
12:44:30:397 [20] ReceiveModemCommand: After CancelIo #3
12:44:36:157 [20] ReceiveModemCommand: Error - 3E4 -
12:44:36:157 [20] ReceiveModemCommand: CancelIo
12:44:36:157 [20] ReceiveModemCommand: After CancelIo #1
12:44:36:157 [20] ReceiveModemCommand: After CancelIo #1a
12:44:51:157 [20] ReceiveModemCommand: After CancelIo #2
12:44:51:157 [20] ReceiveModemCommand: After CancelIo #3
12:44:56:918 [20] ReceiveModemCommand: Error - 3E4 -
12:44:56:918 [20] ReceiveModemCommand: CancelIo
12:44:56:918 [20] ReceiveModemCommand: After CancelIo #1
12:44:56:918 [20] ReceiveModemCommand: After CancelIo #1a
12:45:11:919 [20] ReceiveModemCommand: After CancelIo #2
12:45:11:919 [20] ReceiveModemCommand: After CancelIo #3
12:45:17:673 [20] ReceiveModemCommand: Error - 3E4 -
12:45:17:673 [20] ReceiveModemCommand: CancelIo
12:45:17:673 [20] ReceiveModemCommand: After CancelIo #1
12:45:17:673 [20] ReceiveModemCommand: After CancelIo #1a
12:45:32:673 [20] ReceiveModemCommand: After CancelIo #2
12:45:32:673 [20] ReceiveModemCommand: After CancelIo #3
12:45:38:479 [20] ReceiveModemCommand: Error - 3E4 -
12:45:38:479 [20] ReceiveModemCommand: CancelIo
12:45:38:479 [20] ReceiveModemCommand: After CancelIo #1
12:45:38:479 [20] ReceiveModemCommand: After CancelIo #1a
12:45:53:480 [20] ReceiveModemCommand: After CancelIo #2
12:45:53:480 [20] ReceiveModemCommand: After CancelIo #3
12:45:57:745 [20] ModemTest: COM8:
12:46:01:355 [20] ModemTest: OK
12:46:02:850 [20] ReceiveModemCommand: Error - 3E4 -
12:46:02:850 [20] ReceiveModemCommand: CancelIo
12:46:02:850 [20] ReceiveModemCommand: After CancelIo #1
12:46:02:850 [20] ReceiveModemCommand: After CancelIo #1a
12:46:17:850 [20] ReceiveModemCommand: After CancelIo #2
12:46:17:850 [20] ReceiveModemCommand: After CancelIo #3
Bryce Norwood - NowSMS Support
Board Administrator
Username: Bryce

Post Number: 8357
Registered: 10-2002
Posted on Wednesday, October 14, 2020 - 08:23 pm:   

Hi,

This is very strange.

We have seen this error before, but not with this type of repeating pattern.

I'd like to get a better idea of what is going on between NowSMS and the modem. Please edit SMSGW.INI, and under the [SMSGW] header, add DebugModemPoll=Yes

This will add extra debug information to the log. Every 2 or 3 seconds, NowSMS is polling the modem, looking for received messages, and something about this activity is generating these errors. I'd like to see the SMSDEBUG.LOG with that setting.

Either post a log snippet in reply here or e-mail to nowsms@nowsms.com, and put Attention: Bryce in the subject line of the email.

Regards,

Bryce Norwood
NowSMS Support
Christoffer
New member
Username: Chx

Post Number: 2
Registered: 10-2020
Posted on Thursday, October 15, 2020 - 09:10 am:   

08:59:48:707 [0] UseRouteCache: Yes
08:59:50:754 [0] main: Before InitStatCounters
08:59:50:754 [0] main: After InitStatCounters
08:59:50:754 [0] main: IPV6 Disabled
08:59:58:317 [0] main: Before CheckDatabaseIntegrity
08:59:58:317 [0] main: After CheckDatabaseIntegrity
09:00:03:333 [0] main: Now SMS/MMS Gateway v2020.01.15 Web server started on port number 8800
09:00:03:336 [13] ThreadScanAlerts: Before CheckDatabaseIntegrity
09:00:03:336 [13] ThreadScanAlerts: After CheckDatabaseIntegrity
09:00:03:337 [0] main: 17 Days remaining in trial version
09:00:03:341 [20] ThreadProcessModem: COM8:
09:00:03:341 [20] ThreadProcessModem: Before ModemAllocate - COM8:
09:00:03:342 [20] ThreadProcessModem: After ModemAllocate - COM8: - OK
09:00:03:342 [20] ThreadProcessModem: Re-initializing modem: COM8: ...
09:00:04:346 [0] UseRouteCache: Yes
09:00:04:823 [20] ReceiveModemCommand: Error - 3E4 -
09:00:04:823 [20] ReceiveModemCommand: CancelIo
09:00:04:823 [20] ReceiveModemCommand: After CancelIo #1
09:00:04:823 [20] ReceiveModemCommand: After CancelIo #1a
09:00:19:824 [20] ReceiveModemCommand: After CancelIo #2
09:00:19:824 [20] ReceiveModemCommand: After CancelIo #3
09:00:20:412 [20] TestModemSpeed: Initialised modem at 115200 bps
09:00:22:396 [20] ThreadProcessModem: ATI0
09:00:22:396 [20] ThreadProcessModem:
Sierra Wireless

WMP50 Product

OK

09:00:22:781 [20] ThreadProcessModem: ATI1
09:00:22:781 [20] ThreadProcessModem:
OK

09:00:23:164 [20] ThreadProcessModem: ATI2
09:00:23:164 [20] ThreadProcessModem:
OK

09:00:23:544 [20] ThreadProcessModem: ATI3
09:00:23:544 [20] ThreadProcessModem:
R7.45.1.201105250600.WMP50 2203572 052511 06:00

OK

09:00:23:927 [20] ThreadProcessModem: ATI4
09:00:23:927 [20] ThreadProcessModem:
Q:0 V:1 S0:000 S2:043 S3:013 S4:010 S5:008
+CR:0 +CRC:0 +CMEE:1 +CBST:0,0,1
+SPEAKER:1 +ECHO:0 &C:1 &D:2 %C:0
+IPR:0 +ICF:3,4 +IFC:2,2

OK

09:00:24:310 [20] ThreadProcessModem: ATI5
09:00:24:310 [20] ThreadProcessModem:
Q:0 V:1 S0:000 S2:043 S3:013 S4:010 S5:008
+CR:0 +CRC:0 +CMEE:0 +CBST:0,0,1
+SPEAKER:1 +ECHO:0 &C:1 &D:2 %C:0
+IPR:0 +ICF:3,4 +IFC:2,2

OK

09:00:26:229 [20] ModemTest: COM8:
09:00:30:311 [20] ModemTest: OK
09:00:30:311 [20] ThreadProcessModem: AT+CPMS?
09:00:30:917 [20] ThreadProcessModem:
+CPMS: "ME",0,99,"SM",0,20,"SM",0,20

OK

09:00:30:917 [20] ThreadProcessModem: AT+CNMI=2,2,2,1,0
09:00:32:011 [20] ThreadProcessModem:
OK

09:00:32:011 [20] ThreadProcessModem: AT+CNMI?
09:00:32:616 [20] ThreadProcessModem:
+CNMI: 2,2,2,1,0

OK

09:00:32:616 [20] ThreadProcessModem: AT+CNMI?
09:00:33:221 [20] ThreadProcessModem:
+CNMI: 2,2,2,1,0

OK

09:00:33:221 [20] ThreadProcessModem: AT+CPMS=?
09:00:33:880 [20] ThreadProcessModem:
+CPMS: (("SM","ME","BM","SR"),("SM","ME"),("SM","ME"))

OK

09:00:33:880 [20] ThreadProcessModem: AT+CPMS="SM","SM","SM"
09:00:35:266 [20] ThreadProcessModem:
+CPMS: 0,20,0,20,0,20

OK

09:00:35:266 [20] ThreadProcessModem: AT+CPMS?
09:00:35:870 [20] ThreadProcessModem:
+CPMS: "SM",0,20,"SM",0,20,"SM",0,20

OK

09:00:36:525 [20] ThreadProcessModem: AT+CNMI?
09:00:37:126 [20] ThreadProcessModem:
+CNMI: 2,2,2,1,0

OK

09:00:37:126 [20] ThreadProcessModem: AT+CNMI=?
09:00:37:784 [20] ThreadProcessModem:
+CNMI: (0-3),(0-3),(0-3),(0-2),(0,1)

OK

09:00:37:784 [20] ThreadProcessModem: AT+COPS?
09:00:38:390 [20] ThreadProcessModem:
+COPS: 0,2,24001

OK

09:00:38:390 [20] ThreadProcessModem: AT+CSQ
09:00:38:879 [20] ThreadProcessModem:
+CSQ: 21,0

OK

09:00:38:879 [20] ThreadProcessModem: AT+CNUM
09:00:39:425 [20] ThreadProcessModem:
OK

09:00:39:425 [20] ThreadProcessModem: Modem initialization complete: COM8:
09:00:39:425 [20] ModemReceiveMessages: AT+CPMS="SM"

09:00:40:249 [20] ModemReceiveMessages:
+CPMS: 0,20,0,20,0,20

OK

09:00:40:249 [20] ModemReceiveMessages: AT+CMGL=0

09:00:40:907 [20] ModemReceiveMessages:
OK

09:00:40:907 [20] ModemReceiveMessages: AT+CMGL=1

09:00:41:565 [20] ModemReceiveMessages:
OK

09:00:41:565 [20] ModemReceiveMessages: AT+CPMS="ME"

09:00:42:395 [20] ModemReceiveMessages:
+CPMS: 0,99,0,20,0,20

OK

09:00:42:395 [20] ModemReceiveMessages: AT+CMGL=0

09:00:43:052 [20] ModemReceiveMessages:
OK

09:00:43:052 [20] ModemReceiveMessages: AT+CMGL=1

09:00:43:712 [20] ModemReceiveMessages:
OK

09:00:45:202 [20] ReceiveModemCommand: Error - 3E4 -
09:00:45:202 [20] ReceiveModemCommand: CancelIo
09:00:45:202 [20] ReceiveModemCommand: After CancelIo #1
09:00:45:202 [20] ReceiveModemCommand: After CancelIo #1a
09:01:00:202 [20] ReceiveModemCommand: After CancelIo #2
09:01:00:202 [20] ReceiveModemCommand: After CancelIo #3
09:01:00:202 [20] ModemReceiveMessages: AT+CPMS="SM"

09:01:01:025 [20] ModemReceiveMessages:
+CPMS: 0,20,0,20,0,20

OK

09:01:01:025 [20] ModemReceiveMessages: AT+CMGL=0

09:01:01:680 [20] ModemReceiveMessages:
OK

09:01:01:680 [20] ModemReceiveMessages: AT+CMGL=1

09:01:02:340 [20] ModemReceiveMessages:
OK

09:01:02:340 [20] ModemReceiveMessages: AT+CPMS="ME"

09:01:03:162 [20] ModemReceiveMessages:
+CPMS: 0,99,0,20,0,20

OK

09:01:03:162 [20] ModemReceiveMessages: AT+CMGL=0

09:01:03:817 [20] ModemReceiveMessages:
OK

09:01:03:817 [20] ModemReceiveMessages: AT+CMGL=1

09:01:04:476 [20] ModemReceiveMessages:
OK

09:01:05:971 [20] ReceiveModemCommand: Error - 3E4 -
09:01:05:971 [20] ReceiveModemCommand: CancelIo
09:01:05:971 [20] ReceiveModemCommand: After CancelIo #1
09:01:05:971 [20] ReceiveModemCommand: After CancelIo #1a
09:01:20:971 [20] ReceiveModemCommand: After CancelIo #2
09:01:20:971 [20] ReceiveModemCommand: After CancelIo #3
09:01:20:971 [20] ModemReceiveMessages: AT+CPMS="SM"

09:01:21:795 [20] ModemReceiveMessages:
+CPMS: 0,20,0,20,0,20

OK

09:01:21:795 [20] ModemReceiveMessages: AT+CMGL=0

09:01:22:477 [20] ModemReceiveMessages:
OK

09:01:22:477 [20] ModemReceiveMessages: AT+CMGL=1

09:01:23:136 [20] ModemReceiveMessages:
OK

09:01:23:136 [20] ModemReceiveMessages: AT+CPMS="ME"

09:01:23:958 [20] ModemReceiveMessages:
+CPMS: 0,99,0,20,0,20

OK

09:01:23:958 [20] ModemReceiveMessages: AT+CMGL=0

09:01:24:615 [20] ModemReceiveMessages:
OK

09:01:24:615 [20] ModemReceiveMessages: AT+CMGL=1

09:01:25:272 [20] ModemReceiveMessages:
OK

09:01:26:766 [20] ReceiveModemCommand: Error - 3E4 -
09:01:26:766 [20] ReceiveModemCommand: CancelIo
09:01:26:766 [20] ReceiveModemCommand: After CancelIo #1
09:01:26:766 [20] ReceiveModemCommand: After CancelIo #1a
09:01:41:766 [20] ReceiveModemCommand: After CancelIo #2
09:01:41:766 [20] ReceiveModemCommand: After CancelIo #3
09:01:41:766 [20] ModemReceiveMessages: AT+CPMS="SM"

09:01:42:612 [20] ModemReceiveMessages:
+CPMS: 0,20,0,20,0,20

OK

09:01:42:612 [20] ModemReceiveMessages: AT+CMGL=0

09:01:43:272 [20] ModemReceiveMessages:
OK

09:01:43:272 [20] ModemReceiveMessages: AT+CMGL=1

09:01:43:930 [20] ModemReceiveMessages:
OK

09:01:43:930 [20] ModemReceiveMessages: AT+CPMS="ME"

09:01:44:753 [20] ModemReceiveMessages:
+CPMS: 0,99,0,20,0,20

OK

09:01:44:753 [20] ModemReceiveMessages: AT+CMGL=0

09:01:45:408 [20] ModemReceiveMessages:
OK

09:01:45:408 [20] ModemReceiveMessages: AT+CMGL=1

09:01:46:065 [20] ModemReceiveMessages:
OK

09:01:47:558 [20] ReceiveModemCommand: Error - 3E4 -
09:01:47:558 [20] ReceiveModemCommand: CancelIo
09:01:47:558 [20] ReceiveModemCommand: After CancelIo #1
09:01:47:558 [20] ReceiveModemCommand: After CancelIo #1a
09:02:02:558 [20] ReceiveModemCommand: After CancelIo #2
09:02:02:558 [20] ReceiveModemCommand: After CancelIo #3
09:02:02:558 [20] ModemReceiveMessages: AT+CPMS="SM"

09:02:03:382 [20] ModemReceiveMessages:
+CPMS: 0,20,0,20,0,20

OK
Bryce Norwood - NowSMS Support
Board Administrator
Username: Bryce

Post Number: 8359
Registered: 10-2002
Posted on Thursday, October 15, 2020 - 05:48 pm:   

Very interesting. This is not what I expected.

There is actually no real error occurring...just a normal situation being treated as a minor communication error, and introducing a 15 second delay.

We will have an update shortly that avoids this delay.

Out of curiosity, what is the Windows version?

Regards,

Bryce Norwood
NowSMS Support
Christoffer
New member
Username: Chx

Post Number: 3
Registered: 10-2020
Posted on Friday, October 16, 2020 - 05:47 am:   

Sounds great :)

The OS is Windows Server 2019
Des - NowSMS Support
Board Administrator
Username: Desosms

Post Number: 6183
Registered: 08-2008
Posted on Friday, October 16, 2020 - 06:46 pm:   

We've posted an update to fIx how this is handled. The download link is https://www.nowsms.com/download/nowsms20201015.zip

--
Des
NowSMS Support

Add Your Message Here, or click here to start a new topic.
Post:
Bold text Italics Underline Create a hyperlink Insert a clipart image
Options: Automatically activate URLs in message
Action: