kneodev / ksmppd

Kurt's SMPP Daemon (SMPP Server for Kannel)
Other
56 stars 38 forks source link

PHP SMPP library with KSMPPD Issue #120

Closed voicecomms closed 5 years ago

voicecomms commented 5 years ago

Hello,

I am trying to send SMS using php-smpp library (https://github.com/onlinecity/php-smpp)and getting some issues when sending SMS, I am getting error for which log is pasted below, need some insight on why the issue is occurring. =========================debug logs========================== 2019-07-21 09:46:01 [3744] [7] INFO: Current SMPP load is 0.000000/sec inbound 0.000000/sec outbound 2019-07-21 09:46:01 [3744] [7] INFO: SMPP[cx] currently has 1 binds connected 2019-07-21 09:46:01 [3744] [7] INFO: -- cx:2 (3)-- openacks:0 inbound:0.000000,outbound:0.000000,inbound-queue:0,outbound-queue:0,inbound-processed:6,outbound-processed:6 2019-07-21 09:46:01 [3744] [7] INFO: SMPP[smppusera] currently has 1 binds connected 2019-07-21 09:46:01 [3744] [7] INFO: -- smppusera:0 (3)-- openacks:0 inbound:0.000000,outbound:0.000000,inbound-queue:0,outbound-queue:0,inbound-processed:6,outbound-processed:6 2019-07-21 09:46:01 [3744] [7] INFO: SMPP[smppuserb] currently has 1 binds connected 2019-07-21 09:46:01 [3744] [7] INFO: -- smppuserb:1 (3)-- openacks:0 inbound:0.000000,outbound:0.000000,inbound-queue:0,outbound-queue:0,inbound-processed:6,outbound-processed:6 2019-07-21 09:46:01 [3744] [7] INFO: SMPP[smppuserc] currently has 1 binds connected 2019-07-21 09:46:01 [3744] [7] INFO: -- smppuserc:3 (3)-- openacks:0 inbound:0.000000,outbound:0.000000,inbound-queue:0,outbound-queue:0,inbound-processed:6,outbound-processed:6 2019-07-21 09:46:31 [3744] [7] INFO: Current SMPP load is 0.000000/sec inbound 0.000000/sec outbound 2019-07-21 09:46:31 [3744] [7] INFO: SMPP[cx] currently has 1 binds connected 2019-07-21 09:46:31 [3744] [7] INFO: -- cx:2 (3)-- openacks:0 inbound:0.000000,outbound:0.000000,inbound-queue:0,outbound-queue:0,inbound-processed:6,outbound-processed:6 2019-07-21 09:46:31 [3744] [7] INFO: SMPP[smppusera] currently has 1 binds connected 2019-07-21 09:46:31 [3744] [7] INFO: -- smppusera:0 (3)-- openacks:0 inbound:0.000000,outbound:0.000000,inbound-queue:0,outbound-queue:0,inbound-processed:6,outbound-processed:6 2019-07-21 09:46:31 [3744] [7] INFO: SMPP[smppuserb] currently has 1 binds connected 2019-07-21 09:46:31 [3744] [7] INFO: -- smppuserb:1 (3)-- openacks:0 inbound:0.000000,outbound:0.000000,inbound-queue:0,outbound-queue:0,inbound-processed:6,outbound-processed:6 2019-07-21 09:46:31 [3744] [7] INFO: SMPP[smppuserc] currently has 1 binds connected 2019-07-21 09:46:31 [3744] [7] INFO: -- smppuserc:3 (3)-- openacks:0 inbound:0.000000,outbound:0.000000,inbound-queue:0,outbound-queue:0,inbound-processed:6,outbound-processed:6 2019-07-21 09:46:40 [3744] [4] ERROR: Connection closed by the bearerbox. 2019-07-21 09:46:40 [3744] [4] ERROR: Connection to bearerbox broke! 2019-07-21 09:46:40 [3744] [4] ERROR: connect to <192.168.1.9> failed 2019-07-21 09:46:40 [3744] [4] ERROR: System error 111: Connection refused 2019-07-21 09:46:40 [3744] [4] ERROR: error connecting to server 192.168.1.9' at port14000' 2019-07-21 09:46:41 [3744] [4] ERROR: connect to <192.168.1.9> failed 2019-07-21 09:46:41 [3744] [4] ERROR: System error 111: Connection refused 2019-07-21 09:46:41 [3744] [4] ERROR: error connecting to server 192.168.1.9' at port14000' 2019-07-21 09:46:42 [3744] [6] WARNING: No bearerboxes active, waiting to try again 2019-07-21 09:46:42 [3744] [4] ERROR: connect to <192.168.1.9> failed 2019-07-21 09:46:42 [3744] [4] ERROR: System error 111: Connection refused 2019-07-21 09:46:42 [3744] [4] ERROR: error connecting to server 192.168.1.9' at port14000' 2019-07-21 09:46:43 [3744] [0] ERROR: SIGINT received, aborting program... 2019-07-21 09:46:43 [3744] [4] INFO: Shutting down bearerbox reader thread 2019-07-21 09:46:43 [3744] [6] INFO: Shutting down requeue thread 2019-07-21 09:46:44 [3744] [0] ERROR: conn_destroy: error on close 2019-07-21 09:46:44 [3744] [0] ERROR: System error 9: Bad file descriptor 2019-07-21 09:46:44 [3744] [0] ERROR: conn_destroy: error on close 2019-07-21 09:46:44 [3744] [0] ERROR: System error 9: Bad file descriptor 2019-07-21 09:46:44 [3744] [0] ERROR: conn_destroy: error on close 2019-07-21 09:46:44 [3744] [0] ERROR: System error 9: Bad file descriptor 2019-07-21 09:46:44 [3744] [0] ERROR: conn_destroy: error on close 2019-07-21 09:46:44 [3744] [0] ERROR: System error 9: Bad file descriptor 2019-07-21 09:46:44 [3744] [3] INFO: Shutting down HTTP Server thread on port 14010 2019-07-21 09:46:44 [3744] [0] INFO: Shutting down MySQL connections 2019-07-21 09:46:51 [4067] [0] INFO: Added logfile /var/log/kannel/ksmppd.log' with level1'. 2019-07-21 09:46:51 [4067] [0] INFO: SMPP ESME Default Max Open Acks set to 500 2019-07-21 09:46:51 [4067] [0] INFO: SMPP Wait ack behaviour 0 2019-07-21 09:46:51 [4067] [0] INFO: MYSQL: Connected to server at localhost. 2019-07-21 09:46:51 [4067] [0] INFO: MYSQL: server version 5.5.49, client version 5.5.62. 2019-07-21 09:46:51 [4067] [0] INFO: MYSQL: Connected to server at localhost. 2019-07-21 09:46:51 [4067] [0] INFO: MYSQL: server version 5.5.49, client version 5.5.62. 2019-07-21 09:46:51 [4067] [0] INFO: MYSQL: Connected to server at localhost. 2019-07-21 09:46:51 [4067] [0] INFO: MYSQL: server version 5.5.49, client version 5.5.62. 2019-07-21 09:46:51 [4067] [0] INFO: MYSQL: Connected to server at localhost. 2019-07-21 09:46:51 [4067] [0] INFO: MYSQL: server version 5.5.49, client version 5.5.62. 2019-07-21 09:46:51 [4067] [0] INFO: MYSQL: Connected to server at localhost. 2019-07-21 09:46:51 [4067] [0] INFO: MYSQL: server version 5.5.49, client version 5.5.62. 2019-07-21 09:46:51 [4067] [0] WARNING: No 'database-version-table' specified, using default 'smpp_version' 2019-07-21 09:46:51 [4067] [0] INFO: HTTP: Opening server at port 14010. 2019-07-21 09:46:51 [4067] [0] INFO: Initializing database based routing 2019-07-21 09:46:51 [4067] [0] INFO: Rebuilding database routes 2019-07-21 09:46:51 [4067] [3] INFO: Starting HTTP Server thread on port 14010 2019-07-21 09:46:51 [4067] [0] INFO: ---------------------------------------------- 2019-07-21 09:46:51 [4067] [0] INFO: KSMPPD kmppd version starting 2019-07-21 09:46:51 [4067] [0] INFO: KSMPPD system platform
2019-07-21 09:46:51 [4067] [0] INFO: ---------------------------------------------- 2019-07-21 09:46:51 [4067] [0] INFO: Starting SMPP server on port 2345 2019-07-21 09:46:51 [4067] [0] INFO: Successfully configured bearerbox 192.168.1.9:14000 KSMPPD 2019-07-21 09:46:51 [4067] [0] INFO: Starting simulation queue threads 2019-07-21 09:46:51 [4067] [7] INFO: Current SMPP load is 0.000000/sec inbound 0.000000/sec outbound 2019-07-21 09:46:51 [4067] [6] INFO: Starting bearerbox requeue thread 2019-07-21 09:46:51 [4067] [4] INFO: Connected to bearerbox at 192.168.1.9 port 14000. 2019-07-21 09:46:51 [4067] [4] INFO: Successfully connected and identified. 2019-07-21 09:46:57 [4067] [11] INFO: SMPP[cx] Successfully authenticated 2019-07-21 09:46:57 [4067] [11] INFO: SMPP[cx] has prepaid billing enabled. 2019-07-21 09:46:57 [4067] [9] INFO: SMPP[cx] Successfully routed message for +919930573730 to voicecomms for cost 0.010000 2019-07-21 09:46:57 [4067] [4] ERROR: Connection closed by the bearerbox. 2019-07-21 09:46:57 [4067] [4] ERROR: Connection to bearerbox broke! 2019-07-21 09:46:57 [4067] [4] INFO: Connected to bearerbox at 192.168.1.9 port 14000. 2019-07-21 09:46:57 [4067] [4] INFO: Successfully connected and identified. 2019-07-21 09:46:57 [4067] [0] ERROR: Could not read PDU from cx status was -1 2019-07-21 09:47:06 [4067] [10] INFO: SMPP[cx] bind limit still ok (1/5), allowing 2019-07-21 09:47:06 [4067] [10] INFO: SMPP[cx] Successfully authenticated 2019-07-21 09:47:06 [4067] [10] INFO: SMPP[cx] has prepaid billing enabled. 2019-07-21 09:47:06 [4067] [12] INFO: SMPP[cx] Successfully routed message for +919930573730 to voicecomms for cost 0.010000 2019-07-21 09:47:06 [4067] [4] ERROR: Connection closed by the bearerbox. 2019-07-21 09:47:06 [4067] [4] ERROR: Connection to bearerbox broke! 2019-07-21 09:47:06 [4067] [4] INFO: Connected to bearerbox at 192.168.1.9 port 14000. 2019-07-21 09:47:06 [4067] [4] INFO: Successfully connected and identified. 2019-07-21 09:47:06 [4067] [0] ERROR: Could not read PDU from cx status was -1 2019-07-21 09:47:14 [4067] [11] INFO: SMPP[cx] bind limit still ok (2/5), allowing 2019-07-21 09:47:14 [4067] [11] INFO: SMPP[cx] Successfully authenticated 2019-07-21 09:47:14 [4067] [11] INFO: SMPP[cx] has prepaid billing enabled. 2019-07-21 09:47:14 [4067] [9] INFO: SMPP[cx] Successfully routed message for +919930573730 to voicecomms for cost 0.010000 2019-07-21 09:47:14 [4067] [4] ERROR: Connection closed by the bearerbox. 2019-07-21 09:47:14 [4067] [4] ERROR: Connection to bearerbox broke! 2019-07-21 09:47:14 [4067] [4] INFO: Connected to bearerbox at 192.168.1.9 port 14000. 2019-07-21 09:47:14 [4067] [4] INFO: Successfully connected and identified. 2019-07-21 09:47:14 [4067] [0] ERROR: Could not read PDU from cx status was -1 2019-07-21 09:47:20 [4067] [10] INFO: SMPP[cx] bind limit still ok (3/5), allowing 2019-07-21 09:47:20 [4067] [10] INFO: SMPP[cx] Successfully authenticated 2019-07-21 09:47:20 [4067] [10] INFO: SMPP[cx] has prepaid billing enabled. 2019-07-21 09:47:20 [4067] [12] INFO: SMPP[cx] Successfully routed message for +919930573730 to voicecomms for cost 0.010000 2019-07-21 09:47:20 [4067] [4] ERROR: Connection closed by the bearerbox. 2019-07-21 09:47:20 [4067] [4] ERROR: Connection to bearerbox broke! 2019-07-21 09:47:20 [4067] [4] INFO: Connected to bearerbox at 192.168.1.9 port 14000. 2019-07-21 09:47:20 [4067] [4] INFO: Successfully connected and identified. 2019-07-21 09:47:20 [4067] [0] ERROR: Could not read PDU from cx status was -1 2019-07-21 09:47:21 [4067] [7] INFO: Current SMPP load is 0.000000/sec inbound 0.000000/sec outbound 2019-07-21 09:47:21 [4067] [7] INFO: SMPP[cx] currently has 4 binds connected 2019-07-21 09:47:21 [4067] [7] INFO: -- cx:0 (1)-- openacks:0 inbound:0.000000,outbound:0.000000,inbound-queue:0,outbound-queue:0,inbound-processed:2,outbound-processed:2 2019-07-21 09:47:21 [4067] [7] INFO: -- cx:1 (1)-- openacks:0 inbound:0.000000,outbound:0.000000,inbound-queue:0,outbound-queue:0,inbound-processed:2,outbound-processed:2 2019-07-21 09:47:21 [4067] [7] INFO: -- cx:2 (1)-- openacks:0 inbound:0.000000,outbound:0.000000,inbound-queue:0,outbound-queue:0,inbound-processed:2,outbound-processed:2 2019-07-21 09:47:21 [4067] [7] INFO: -- cx:3 (1)-- openacks:0 inbound:0.000000,outbound:0.000000,inbound-queue:0,outbound-queue:0,inbound-processed:2,outbound-processed:2 2019-07-21 09:47:31 [4067] [11] INFO: SMPP[cx] bind limit still ok (0/5), allowing 2019-07-21 09:47:31 [4067] [11] INFO: SMPP[cx] Successfully authenticated 2019-07-21 09:47:31 [4067] [11] INFO: SMPP[cx] has prepaid billing enabled. 2019-07-21 09:47:31 [4067] [9] INFO: SMPP[cx] Successfully routed message for +919930573730 to voicecomms for cost 0.010000 2019-07-21 09:47:31 [4067] [4] ERROR: Connection closed by the bearerbox. 2019-07-21 09:47:31 [4067] [4] ERROR: Connection to bearerbox broke! 2019-07-21 09:47:31 [4067] [4] INFO: Connected to bearerbox at 192.168.1.9 port 14000. 2019-07-21 09:47:31 [4067] [4] INFO: Successfully connected and identified. 2019-07-21 09:47:31 [4067] [0] ERROR: Could not read PDU from cx status was -1 2019-07-21 09:47:41 [4067] [10] INFO: SMPP[cx] bind limit still ok (1/5), allowing 2019-07-21 09:47:41 [4067] [10] INFO: SMPP[cx] Successfully authenticated 2019-07-21 09:47:41 [4067] [10] INFO: SMPP[cx] has prepaid billing enabled. 2019-07-21 09:47:41 [4067] [12] INFO: SMPP[cx] Successfully routed message for +919930573730 to voicecomms for cost 0.010000 2019-07-21 09:47:41 [4067] [4] ERROR: Connection closed by the bearerbox. 2019-07-21 09:47:41 [4067] [4] ERROR: Connection to bearerbox broke! 2019-07-21 09:47:41 [4067] [4] INFO: Connected to bearerbox at 192.168.1.9 port 14000. 2019-07-21 09:47:41 [4067] [4] INFO: Successfully connected and identified. 2019-07-21 09:47:41 [4067] [0] ERROR: Could not read PDU from cx status was -1 2019-07-21 09:47:45 [4067] [11] INFO: SMPP[smppuserc] Successfully authenticated 2019-07-21 09:47:45 [4067] [11] WARNING: SMPP[smppuserc] Bind has simulation enabled, messages WILL NOT be delivered. 2019-07-21 09:47:45 [4067] [11] WARNING: SMPP[smppuserc] Permanent failures every 0 messages, Temporary failures every 0 messages 2019-07-21 09:47:45 [4067] [11] WARNING: SMPP[smppuserc] Delivery simulated every 1 messages, MO every 0 messages Max open acks 500 2019-07-21 09:47:45 [4067] [11] INFO: SMPP[smppuserc] has prepaid billing enabled. 2019-07-21 09:47:45 [4067] [10] INFO: SMPP[smppuserb] Successfully authenticated 2019-07-21 09:47:45 [4067] [10] WARNING: SMPP[smppuserb] Bind has simulation enabled, messages WILL NOT be delivered. 2019-07-21 09:47:45 [4067] [10] WARNING: SMPP[smppuserb] Permanent failures every 0 messages, Temporary failures every 0 messages 2019-07-21 09:47:45 [4067] [10] WARNING: SMPP[smppuserb] Delivery simulated every 1 messages, MO every 0 messages Max open acks 500 2019-07-21 09:47:45 [4067] [10] INFO: SMPP[smppuserb] has prepaid billing enabled. 2019-07-21 09:47:45 [4067] [11] INFO: SMPP[smppusera] Successfully authenticated 2019-07-21 09:47:45 [4067] [11] WARNING: SMPP[smppusera] Bind has simulation enabled, messages WILL NOT be delivered. 2019-07-21 09:47:45 [4067] [11] WARNING: SMPP[smppusera] Permanent failures every 0 messages, Temporary failures every 0 messages 2019-07-21 09:47:45 [4067] [11] WARNING: SMPP[smppusera] Delivery simulated every 1 messages, MO every 0 messages Max open acks 500 2019-07-21 09:47:45 [4067] [11] INFO: SMPP[smppusera] has prepaid billing enabled. 2019-07-21 09:47:45 [4067] [9] INFO: SMPP[cx] bind limit still ok (2/5), allowing 2019-07-21 09:47:45 [4067] [9] INFO: SMPP[cx] Successfully authenticated 2019-07-21 09:47:45 [4067] [9] INFO: SMPP[cx] has prepaid billing enabled. 2019-07-21 09:47:51 [4067] [12] INFO: SMPP[cx] bind limit still ok (3/5), allowing 2019-07-21 09:47:51 [4067] [12] INFO: SMPP[cx] Successfully authenticated 2019-07-21 09:47:51 [4067] [12] INFO: SMPP[cx] has prepaid billing enabled. 2019-07-21 09:47:51 [4067] [10] INFO: SMPP[cx] Successfully routed message for +919930573730 to voicecomms for cost 0.010000 2019-07-21 09:47:51 [4067] [4] ERROR: Connection closed by the bearerbox. 2019-07-21 09:47:51 [4067] [4] ERROR: Connection to bearerbox broke! 2019-07-21 09:47:51 [4067] [4] INFO: Connected to bearerbox at 192.168.1.9 port 14000. 2019-07-21 09:47:51 [4067] [4] INFO: Successfully connected and identified. 2019-07-21 09:47:51 [4067] [0] ERROR: Could not read PDU from cx status was -1 2019-07-21 09:47:51 [4067] [7] INFO: New maximum inbound load: 1.000000/sec 2019-07-21 09:47:51 [4067] [7] INFO: Current SMPP load is 1.000000/sec inbound 0.000000/sec outbound 2019-07-21 09:47:51 [4067] [7] INFO: SMPP[cx] currently has 4 binds connected 2019-07-21 09:47:51 [4067] [7] INFO: -- cx:4 (1)-- openacks:0 inbound:0.000000,outbound:0.000000,inbound-queue:0,outbound-queue:0,inbound-processed:2,outbound-processed:2 2019-07-21 09:47:51 [4067] [7] INFO: -- cx:5 (1)-- openacks:0 inbound:0.000000,outbound:0.000000,inbound-queue:0,outbound-queue:0,inbound-processed:2,outbound-processed:2 2019-07-21 09:47:51 [4067] [7] INFO: -- cx:8 (3)-- openacks:0 inbound:0.000000,outbound:0.000000,inbound-queue:0,outbound-queue:0,inbound-processed:1,outbound-processed:1 2019-07-21 09:47:51 [4067] [7] INFO: -- cx:10 (1)-- openacks:0 inbound:1.000000,outbound:0.000000,inbound-queue:0,outbound-queue:0,inbound-processed:2,outbound-processed:2 2019-07-21 09:47:51 [4067] [7] INFO: SMPP[smppusera] currently has 1 binds connected 2019-07-21 09:47:51 [4067] [7] INFO: -- smppusera:6 (3)-- openacks:0 inbound:0.000000,outbound:0.000000,inbound-queue:0,outbound-queue:0,inbound-processed:1,outbound-processed:1 2019-07-21 09:47:51 [4067] [7] INFO: SMPP[smppuserb] currently has 1 binds connected 2019-07-21 09:47:51 [4067] [7] INFO: -- smppuserb:7 (3)-- openacks:0 inbound:0.000000,outbound:0.000000,inbound-queue:0,outbound-queue:0,inbound-processed:1,outbound-processed:1 2019-07-21 09:47:51 [4067] [7] INFO: SMPP[smppuserc] currently has 1 binds connected 2019-07-21 09:47:51 [4067] [7] INFO: -- smppuserc:9 (3)-- openacks:0 inbound:0.000000,outbound:0.000000,inbound-queue:0,outbound-queue:0,inbound-processed:1,outbound-processed:1

========================================================== Thanks in advance

CJ +91-9930573730

voicecomms commented 5 years ago

This is fixed and working fine

voicecomms commented 5 years ago

Fixed and working fine