[BUG] Incorrect BIND Status for given command after reconnecting
Mohammed Saleem <[email protected]>
| Newsgroups | gmane.comp.mobile.kannel.devel |
|---|---|
| Message-ID | <[email protected]> |
Dear Kannelers <<Log Attached>> I am using svn kannel, I had an issue with SMPP, kannel doesn't reconnect well when the connection is lost (reset) it reconnects and reports that the connection is online, but it is not. all sent PDUs gets a reply with generic_nack because the connection is not bound Do we have a bug here? I think kannel should monitor the last valid enquire_link_resp received and reconnects at certain timeout? Thanks Best Regards, Mohammed M I Sleem http://www.abusleem.net - Personal blog http://www.freakle.com - The Search Freak <http://www.colorle.com>
smsc_smpp_error_log.txt
(text/plain, 11.2 KB)
2011-01-13 11:46:22 [27081] [45] DEBUG: type_name: deliver_sm_resp 2011-01-13 11:46:22 [27081] [45] DEBUG: command_id: 2147483653 = 0x80000005 2011-01-13 11:46:22 [27081] [45] DEBUG: command_status: 0 = 0x00000000 2011-01-13 11:46:22 [27081] [45] DEBUG: sequence_number: 4374570 = 0x0042c02a 2011-01-13 11:46:22 [27081] [45] DEBUG: message_id: NULL 2011-01-13 11:46:22 [27081] [45] DEBUG: SMPP PDU dump ends. 2011-01-13 11:46:43 [27081] [45] DEBUG: SMPP[XYZ]: Sending enquire link: 2011-01-13 11:46:43 [27081] [45] DEBUG: SMPP PDU 0xa4ae070 dump: 2011-01-13 11:46:43 [27081] [45] DEBUG: type_name: enquire_link 2011-01-13 11:46:43 [27081] [45] DEBUG: command_id: 21 = 0x00000015 2011-01-13 11:46:43 [27081] [45] DEBUG: command_status: 0 = 0x00000000 2011-01-13 11:46:43 [27081] [45] DEBUG: sequence_number: 353601 = 0x00056541 2011-01-13 11:46:43 [27081] [45] DEBUG: SMPP PDU dump ends. 2011-01-13 11:46:43 [27081] [44] DEBUG: SMPP[XYZ]: Sending enquire link: 2011-01-13 11:46:43 [27081] [44] DEBUG: SMPP PDU 0x2aaab20cdb50 dump: 2011-01-13 11:46:43 [27081] [44] DEBUG: type_name: enquire_link 2011-01-13 11:46:43 [27081] [44] DEBUG: command_id: 21 = 0x00000015 2011-01-13 11:46:43 [27081] [44] DEBUG: command_status: 0 = 0x00000000 2011-01-13 11:46:43 [27081] [44] DEBUG: sequence_number: 353602 = 0x00056542 2011-01-13 11:46:43 [27081] [44] DEBUG: SMPP PDU dump ends. 2011-01-13 11:46:43 [27081] [44] DEBUG: SMPP[XYZ]: throughput (0.00,19.00) 2011-01-13 11:46:43 [27081] [45] ERROR: Error reading from fd 65: 2011-01-13 11:46:43 [27081] [45] ERROR: System error 104: Connection reset by peer 2011-01-13 11:46:43 [27081] [45] ERROR: SMPP[XYZ]: Couldn't connect to SMS center (retrying in 5 seconds). 2011-01-13 11:46:44 [27081] [44] ERROR: Error reading from fd 39: 2011-01-13 11:46:44 [27081] [44] ERROR: System error 104: Connection reset by peer 2011-01-13 11:46:44 [27081] [44] ERROR: SMPP[XYZ]: Couldn't connect to SMS center (retrying in 5 seconds). 2011-01-13 11:46:48 [27081] [45] DEBUG: Connecting to <213.139.63.193> 2011-01-13 11:46:49 [27081] [44] DEBUG: Connecting to <213.139.63.193> 2011-01-13 11:46:49 [27081] [45] DEBUG: SMPP[XYZ]: Sending PDU: 2011-01-13 11:46:49 [27081] [45] DEBUG: SMPP PDU 0xa3374c0 dump: 2011-01-13 11:46:49 [27081] [45] DEBUG: type_name: bind_receiver 2011-01-13 11:46:49 [27081] [45] DEBUG: command_id: 1 = 0x00000001 2011-01-13 11:46:49 [27081] [45] DEBUG: command_status: 0 = 0x00000000 2011-01-13 11:46:49 [27081] [45] DEBUG: sequence_number: 353603 = 0x00056543 2011-01-13 11:46:49 [27081] [45] DEBUG: system_id: "xxx" 2011-01-13 11:46:49 [27081] [45] DEBUG: password: "xxx" 2011-01-13 11:46:49 [27081] [45] DEBUG: system_type: "SMPP" 2011-01-13 11:46:49 [27081] [45] DEBUG: interface_version: 52 = 0x00000034 2011-01-13 11:46:49 [27081] [45] DEBUG: addr_ton: 0 = 0x00000000 2011-01-13 11:46:49 [27081] [45] DEBUG: addr_npi: 1 = 0x00000001 2011-01-13 11:46:49 [27081] [45] DEBUG: address_range: NULL 2011-01-13 11:46:49 [27081] [45] DEBUG: SMPP PDU dump ends. 2011-01-13 11:46:49 [27081] [44] DEBUG: SMPP[XYZ]: Sending PDU: 2011-01-13 11:46:49 [27081] [44] DEBUG: SMPP PDU 0x2aaab20cdb50 dump: 2011-01-13 11:46:49 [27081] [44] DEBUG: type_name: bind_transmitter 2011-01-13 11:46:49 [27081] [44] DEBUG: command_id: 2 = 0x00000002 2011-01-13 11:46:49 [27081] [44] DEBUG: command_status: 0 = 0x00000000 2011-01-13 11:46:49 [27081] [44] DEBUG: sequence_number: 353604 = 0x00056544 2011-01-13 11:46:49 [27081] [44] DEBUG: system_id: "xxx" 2011-01-13 11:46:49 [27081] [44] DEBUG: password: "xxx" 2011-01-13 11:46:49 [27081] [44] DEBUG: system_type: "SMPP" 2011-01-13 11:46:49 [27081] [44] DEBUG: interface_version: 52 = 0x00000034 2011-01-13 11:46:49 [27081] [44] DEBUG: addr_ton: 0 = 0x00000000 2011-01-13 11:46:49 [27081] [44] DEBUG: addr_npi: 1 = 0x00000001 2011-01-13 11:46:49 [27081] [44] DEBUG: address_range: NULL 2011-01-13 11:46:49 [27081] [44] DEBUG: SMPP PDU dump ends. 2011-01-13 11:46:49 [27081] [45] DEBUG: SMPP[XYZ]: Got PDU: 2011-01-13 11:46:49 [27081] [45] DEBUG: SMPP PDU 0xa50b0b0 dump: 2011-01-13 11:46:49 [27081] [45] DEBUG: type_name: bind_receiver_resp 2011-01-13 11:46:49 [27081] [45] DEBUG: command_id: 2147483649 = 0x80000001 2011-01-13 11:46:49 [27081] [45] DEBUG: command_status: 5 = 0x00000005 2011-01-13 11:46:49 [27081] [45] DEBUG: sequence_number: 353603 = 0x00056543 2011-01-13 11:46:49 [27081] [45] DEBUG: system_id: "smpp1a" 2011-01-13 11:46:49 [27081] [45] DEBUG: SMPP PDU dump ends. 2011-01-13 11:46:49 [27081] [44] DEBUG: SMPP[XYZ]: Got PDU: 2011-01-13 11:46:49 [27081] [44] DEBUG: SMPP PDU 0x2aaab20c5dc0 dump: 2011-01-13 11:46:49 [27081] [44] DEBUG: type_name: bind_transmitter_resp 2011-01-13 11:46:49 [27081] [44] DEBUG: command_id: 2147483650 = 0x80000002 2011-01-13 11:46:49 [27081] [44] DEBUG: command_status: 5 = 0x00000005 2011-01-13 11:46:49 [27081] [44] DEBUG: sequence_number: 353604 = 0x00056544 2011-01-13 11:46:49 [27081] [44] DEBUG: system_id: "smpp1a" 2011-01-13 11:46:49 [27081] [44] DEBUG: SMPP PDU dump ends. 2011-01-13 11:46:49 [27081] [44] DEBUG: SMPP[XYZ]: throughput (0.00,19.00) 2011-01-13 11:47:19 [27081] [45] DEBUG: SMPP[XYZ]: Sending enquire link: 2011-01-13 11:47:19 [27081] [45] DEBUG: SMPP PDU 0xa4ae070 dump: 2011-01-13 11:47:19 [27081] [45] DEBUG: type_name: enquire_link 2011-01-13 11:47:19 [27081] [45] DEBUG: command_id: 21 = 0x00000015 2011-01-13 11:47:19 [27081] [45] DEBUG: command_status: 0 = 0x00000000 2011-01-13 11:47:19 [27081] [45] DEBUG: sequence_number: 353605 = 0x00056545 2011-01-13 11:47:19 [27081] [45] DEBUG: SMPP PDU 0xa1d85b0 dump: 2011-01-13 11:47:19 [27081] [45] DEBUG: type_name: generic_nack 2011-01-13 11:47:19 [27081] [45] DEBUG: command_id: 2147483648 = 0x80000000 2011-01-13 11:47:19 [27081] [45] DEBUG: command_status: 4 = 0x00000004 2011-01-13 11:47:19 [27081] [45] DEBUG: sequence_number: 353603 = 0x00056543 2011-01-13 11:47:19 [27081] [45] DEBUG: SMPP PDU dump ends. 2011-01-13 11:47:19 [27081] [45] ERROR: SMPP[XYZ]: SMSC rejected last command, code 0x00000004 (Incorrect BIND Status for given command). 2011-01-13 11:47:19 [27081] [44] DEBUG: SMPP[XYZ]: Sending enquire link: 2011-01-13 11:47:19 [27081] [44] DEBUG: SMPP PDU 0x2aaab20c5dc0 dump: 2011-01-13 11:47:19 [27081] [44] DEBUG: type_name: enquire_link 2011-01-13 11:47:19 [27081] [44] DEBUG: command_id: 21 = 0x00000015 2011-01-13 11:47:19 [27081] [44] DEBUG: command_status: 0 = 0x00000000 2011-01-13 11:47:19 [27081] [44] DEBUG: sequence_number: 353606 = 0x00056546 2011-01-13 11:47:19 [27081] [44] DEBUG: SMPP PDU dump ends. 2011-01-13 11:47:19 [27081] [44] DEBUG: SMPP[XYZ]: throughput (0.00,19.00) 2011-01-13 11:47:19 [27081] [44] DEBUG: SMPP[XYZ]: throughput (0.00,19.00) 2011-01-13 11:47:19 [27081] [44] DEBUG: SMPP[XYZ]: Got PDU: 2011-01-13 11:47:19 [27081] [44] DEBUG: SMPP PDU 0x2aaab20cdb50 dump: 2011-01-13 11:47:19 [27081] [44] DEBUG: type_name: generic_nack 2011-01-13 11:47:19 [27081] [44] DEBUG: command_id: 2147483648 = 0x80000000 2011-01-13 11:47:19 [27081] [44] DEBUG: command_status: 4 = 0x00000004 2011-01-13 11:47:19 [27081] [44] DEBUG: sequence_number: 353604 = 0x00056544 2011-01-13 11:47:19 [27081] [44] DEBUG: SMPP PDU dump ends. 2011-01-13 11:47:19 [27081] [44] ERROR: SMPP[XYZ]: SMSC rejected last command, code 0x00000004 (Incorrect BIND Status for given command). 2011-01-13 11:47:19 [27081] [44] DEBUG: SMPP[XYZ]: throughput (0.00,19.00) 2011-01-13 11:47:49 [27081] [45] DEBUG: SMPP[XYZ]: Sending enquire link: 2011-01-13 11:47:49 [27081] [45] DEBUG: SMPP PDU 0x9e9b5a0 dump: 2011-01-13 11:47:49 [27081] [45] DEBUG: type_name: enquire_link 2011-01-13 11:47:49 [27081] [45] DEBUG: command_id: 21 = 0x00000015 2011-01-13 11:47:49 [27081] [45] DEBUG: command_status: 0 = 0x00000000 2011-01-13 11:47:49 [27081] [45] DEBUG: sequence_number: 353607 = 0x00056547 2011-01-13 11:47:49 [27081] [45] DEBUG: SMPP PDU dump ends. 2011-01-13 11:47:49 [27081] [45] DEBUG: SMPP[XYZ]: Got PDU: 2011-01-13 11:47:49 [27081] [45] DEBUG: SMPP PDU 0xa3374c0 dump: 2011-01-13 11:47:49 [27081] [45] DEBUG: type_name: generic_nack 2011-01-13 11:47:49 [27081] [45] DEBUG: command_id: 2147483648 = 0x80000000 2011-01-13 11:47:49 [27081] [45] DEBUG: command_status: 4 = 0x00000004 2011-01-13 11:47:49 [27081] [45] DEBUG: sequence_number: 353603 = 0x00056543 2011-01-13 11:47:49 [27081] [45] DEBUG: SMPP PDU dump ends. 2011-01-13 11:47:49 [27081] [45] ERROR: SMPP[XYZ]: SMSC rejected last command, code 0x00000004 (Incorrect BIND Status for given command). 2011-01-13 11:47:49 [27081] [44] DEBUG: SMPP[XYZ]: Sending enquire link: 2011-01-13 11:47:49 [27081] [44] DEBUG: SMPP PDU 0x2aaab20c5dc0 dump: 2011-01-13 11:47:49 [27081] [44] DEBUG: type_name: enquire_link 2011-01-13 11:47:49 [27081] [44] DEBUG: command_id: 21 = 0x00000015 2011-01-13 11:47:49 [27081] [44] DEBUG: command_status: 0 = 0x00000000 2011-01-13 11:47:49 [27081] [44] DEBUG: sequence_number: 353608 = 0x00056548 2011-01-13 11:47:49 [27081] [44] DEBUG: SMPP PDU dump ends. 2011-01-13 11:47:49 [27081] [44] DEBUG: SMPP[XYZ]: throughput (0.00,19.00) 2011-01-13 11:47:49 [27081] [44] DEBUG: SMPP[XYZ]: throughput (0.00,19.00) 2011-01-13 11:47:49 [27081] [44] DEBUG: SMPP[XYZ]: Got PDU: 2011-01-13 11:47:49 [27081] [44] DEBUG: SMPP PDU 0x2aaab20cdb50 dump: 2011-01-13 11:47:49 [27081] [44] DEBUG: type_name: generic_nack 2011-01-13 11:47:49 [27081] [44] DEBUG: command_id: 2147483648 = 0x80000000 2011-01-13 11:47:49 [27081] [44] DEBUG: command_status: 4 = 0x00000004 2011-01-13 11:47:49 [27081] [44] DEBUG: sequence_number: 353604 = 0x00056544 2011-01-13 11:47:49 [27081] [44] DEBUG: SMPP PDU dump ends. 2011-01-13 11:47:49 [27081] [44] ERROR: SMPP[XYZ]: SMSC rejected last command, code 0x00000004 (Incorrect BIND Status for given command). 2011-01-13 11:47:49 [27081] [44] DEBUG: SMPP[XYZ]: throughput (0.00,19.00) 2011-01-13 11:48:19 [27081] [45] DEBUG: SMPP[XYZ]: Sending enquire link: 2011-01-13 11:48:19 [27081] [45] DEBUG: SMPP PDU 0xa55bf10 dump: 2011-01-13 11:48:19 [27081] [45] DEBUG: type_name: enquire_link 2011-01-13 11:48:19 [27081] [45] DEBUG: command_id: 21 = 0x00000015 2011-01-13 11:48:19 [27081] [45] DEBUG: command_status: 0 = 0x00000000 2011-01-13 11:48:19 [27081] [45] DEBUG: sequence_number: 353609 = 0x00056549 2011-01-13 11:48:19 [27081] [45] DEBUG: SMPP PDU dump ends. 2011-01-13 11:48:19 [27081] [44] DEBUG: SMPP[XYZ]: Sending enquire link: 2011-01-13 11:48:19 [27081] [44] DEBUG: SMPP PDU 0x2aaab20cdb50 dump: 2011-01-13 11:48:19 [27081] [44] DEBUG: type_name: enquire_link 2011-01-13 11:48:19 [27081] [44] DEBUG: command_id: 21 = 0x00000015 2011-01-13 11:48:19 [27081] [44] DEBUG: command_status: 0 = 0x00000000 2011-01-13 11:48:19 [27081] [44] DEBUG: sequence_number: 353610 = 0x0005654a 2011-01-13 11:48:19 [27081] [44] DEBUG: SMPP PDU dump ends. 2011-01-13 11:48:19 [27081] [44] DEBUG: SMPP[XYZ]: throughput (0.00,19.00) 2011-01-13 11:48:19 [27081] [45] DEBUG: SMPP[XYZ]: Got PDU: 2011-01-13 11:48:19 [27081] [45] DEBUG: SMPP PDU 0xa4ae070 dump: 2011-01-13 11:48:19 [27081] [45] DEBUG: type_name: generic_nack 2011-01-13 11:48:19 [27081] [45] DEBUG: command_id: 2147483648 = 0x80000000 2011-01-13 11:48:19 [27081] [45] DEBUG: command_status: 4 = 0x00000004