Hi Elton,
Kindly find the attachment for the logs. Note that this was done manually since
the application are not functioning yet since I need to get the MO through
first.
Thank you,
Arif Noor.
From: Elton Hoxha [mailto:[email protected]]
Sent: Wednesday, April 13, 2016 8:30 PM
To: Arif Noor
Cc: Stipe Tolj; [email protected]
Subject: Re: USSD with Kannel
Also please debug logs of submit SM after you are receiving initial PSSR.
PSSR
2016-04-14 09:02:30 [12379] [6] DEBUG: command_id: 2147483669 = 0x80000015
2016-04-14 09:02:30 [12379] [6] DEBUG: command_status: 0 = 0x00000000
2016-04-14 09:02:30 [12379] [6] DEBUG: sequence_number: 2045 = 0x000007fd
2016-04-14 09:02:30 [12379] [6] DEBUG: SMPP PDU dump ends.
2016-04-14 09:02:30 [12379] [6] DEBUG: SMPP[smppUSSD]: throughput (0.00,15.00)
2016-04-14 09:02:33 [12379] [7] DEBUG: SMPP[smppUSSD2]: throughput (0.00,15.00)
2016-04-14 09:02:33 [12379] [7] DEBUG: Optional parameter tag (0x0501)
2016-04-14 09:02:33 [12379] [7] DEBUG: Optional parameter length read as 1
2016-04-14 09:02:33 [12379] [7] DEBUG: Found configured optional parameter
`ussd_srv_op'
2016-04-14 09:02:33 [12379] [7] DEBUG: Optional parameter tag (0x4001)
2016-04-14 09:02:33 [12379] [7] DEBUG: Optional parameter length read as 15
2016-04-14 09:02:33 [12379] [7] DEBUG: Found configured optional parameter
`ussd_imsi'
2016-04-14 09:02:33 [12379] [7] DEBUG: Optional parameter tag (0x4002)
2016-04-14 09:02:33 [12379] [7] DEBUG: Optional parameter length read as 11
2016-04-14 09:02:33 [12379] [7] DEBUG: Found configured optional parameter
`ussd_vlr'
2016-04-14 09:02:33 [12379] [7] DEBUG: Optional parameter tag (0x4006)
2016-04-14 09:02:33 [12379] [7] DEBUG: Optional parameter length read as 11
2016-04-14 09:02:33 [12379] [7] DEBUG: Found configured optional parameter
`ussd_hlr'
2016-04-14 09:02:33 [12379] [7] DEBUG: Optional parameter tag (0x400c)
2016-04-14 09:02:33 [12379] [7] DEBUG: Optional parameter length read as 16
2016-04-14 09:02:33 [12379] [7] DEBUG: Found configured optional parameter
`ussd_optional'
2016-04-14 09:02:33 [12379] [7] DEBUG: SMPP[smppUSSD2]: Got PDU:
2016-04-14 09:02:33 [12379] [7] DEBUG: SMPP PDU 0x7f90f400c270 dump:
2016-04-14 09:02:33 [12379] [7] DEBUG: type_name: deliver_sm
2016-04-14 09:02:33 [12379] [7] DEBUG: command_id: 5 = 0x00000005
2016-04-14 09:02:33 [12379] [7] DEBUG: command_status: 0 = 0x00000000
2016-04-14 09:02:33 [12379] [7] DEBUG: sequence_number: 8767 = 0x0000223f
2016-04-14 09:02:33 [12379] [7] DEBUG: service_type: "USSD"
2016-04-14 09:02:33 [12379] [7] DEBUG: source_addr_ton: 1 = 0x00000001
2016-04-14 09:02:33 [12379] [7] DEBUG: source_addr_npi: 1 = 0x00000001
2016-04-14 09:02:33 [12379] [7] DEBUG: source_addr: "60103064822"
2016-04-14 09:02:33 [12379] [7] DEBUG: dest_addr_ton: 3 = 0x00000003
2016-04-14 09:02:33 [12379] [7] DEBUG: dest_addr_npi: 9 = 0x00000009
2016-04-14 09:02:33 [12379] [7] DEBUG: destination_addr: "126"
2016-04-14 09:02:33 [12379] [7] DEBUG: esm_class: 0 = 0x00000000
2016-04-14 09:02:33 [12379] [7] DEBUG: protocol_id: 0 = 0x00000000
2016-04-14 09:02:33 [12379] [7] DEBUG: priority_flag: 0 = 0x00000000
2016-04-14 09:02:33 [12379] [7] DEBUG: schedule_delivery_time: NULL
2016-04-14 09:02:33 [12379] [7] DEBUG: validity_period: NULL
2016-04-14 09:02:33 [12379] [7] DEBUG: registered_delivery: 0 = 0x00000000
2016-04-14 09:02:33 [12379] [7] DEBUG: replace_if_present_flag: 0 = 0x00000000
2016-04-14 09:02:33 [12379] [7] DEBUG: data_coding: 15 = 0x0000000f
2016-04-14 09:02:33 [12379] [7] DEBUG: sm_default_msg_id: 0 = 0x00000000
2016-04-14 09:02:33 [12379] [7] DEBUG: sm_length: 5 = 0x00000005
2016-04-14 09:02:33 [12379] [7] DEBUG: short_message: "*126#"
2016-04-14 09:02:33 [12379] [7] DEBUG: ussd_service_op:
2016-04-14 09:02:33 [12379] [7] DEBUG: Octet string at 0x7f90f400d870:
2016-04-14 09:02:33 [12379] [7] DEBUG: len: 1
2016-04-14 09:02:33 [12379] [7] DEBUG: size: 2
2016-04-14 09:02:33 [12379] [7] DEBUG: immutable: 0
2016-04-14 09:02:33 [12379] [7] DEBUG: data: 01
.
2016-04-14 09:02:33 [12379] [7] DEBUG: Octet string dump ends.
2016-04-14 09:02:33 [12379] [7] DEBUG: ussd_hlr: "60192040152"
2016-04-14 09:02:33 [12379] [7] DEBUG: ussd_imsi: "502195700771324"
2016-04-14 09:02:33 [12379] [7] DEBUG: ussd_optional: "0010022C570EEBEB"
2016-04-14 09:02:33 [12379] [7] DEBUG: ussd_srv_op:
2016-04-14 09:02:33 [12379] [7] DEBUG: Octet string at 0x7f90f400c040:
2016-04-14 09:02:33 [12379] [7] DEBUG: len: 1
2016-04-14 09:02:33 [12379] [7] DEBUG: size: 2
2016-04-14 09:02:33 [12379] [7] DEBUG: immutable: 0
2016-04-14 09:02:33 [12379] [7] DEBUG: data: 01
.
2016-04-14 09:02:33 [12379] [7] DEBUG: Octet string dump ends.
2016-04-14 09:02:33 [12379] [7] DEBUG: ussd_vlr: "60194030063"
2016-04-14 09:02:33 [12379] [7] DEBUG: SMPP PDU dump ends.
USSR
2016-04-14 09:02:43 [12379] [7] DEBUG: SMPP[smppUSSD2]: throughput (0.00,15.00)
2016-04-14 09:02:47 [12379] [10] DEBUG: boxc_receiver: sms received
2016-04-14 09:02:47 [12379] [10] DEBUG: send_msg: sending msg to box:
<127.0.0.1>
2016-04-14 09:02:47 [12379] [7] DEBUG: SMPP[smppUSSD2]: throughput (0.00,15.00)
2016-04-14 09:02:47 [12379] [7] DEBUG: SMPP[smppUSSD2]: Manually forced source
addr ton = 0, source add npi = 1
2016-04-14 09:02:47 [12379] [7] DEBUG: new group created `smpp'
2016-04-14 09:02:47 [12379] [7] DEBUG: group=`smpp' key=`ussd_srv_op' value=`2'
2016-04-14 09:02:47 [12379] [7] DEBUG: new group created `smpp'
2016-04-14 09:02:47 [12379] [7] DEBUG: group=`smpp' key=`ussd_srv_op' value=`2'
2016-04-14 09:02:47 [12379] [7] DEBUG: SMPP[smppUSSD2]: Manually forced dest
addr ton = 1, dest add npi = 1
2016-04-14 09:02:47 [12379] [7] DEBUG: new group created `smpp'
2016-04-14 09:02:47 [12379] [7] DEBUG: group=`smpp' key=`ussd_srv_op' value=`2'
2016-04-14 09:02:47 [12379] [7] DEBUG: new group created `smpp'
2016-04-14 09:02:47 [12379] [7] DEBUG: group=`smpp' key=`ussd_srv_op' value=`2'
2016-04-14 09:02:47 [12379] [7] DEBUG: new group created `smpp'
2016-04-14 09:02:47 [12379] [7] DEBUG: group=`smpp' key=`ussd_srv_op' value=`2'
2016-04-14 09:02:47 [12379] [7] DEBUG: new group created `smpp'
2016-04-14 09:02:47 [12379] [7] DEBUG: group=`smpp' key=`ussd_srv_op' value=`2'
2016-04-14 09:02:47 [12379] [7] DEBUG: SMPP[smppUSSD2]: Sending PDU:
2016-04-14 09:02:47 [12379] [7] DEBUG: SMPP PDU 0x7f90f400c270 dump:
2016-04-14 09:02:47 [12379] [7] DEBUG: type_name: submit_sm
2016-04-14 09:02:47 [12379] [7] DEBUG: command_id: 4 = 0x00000004
2016-04-14 09:02:47 [12379] [7] DEBUG: command_status: 0 = 0x00000000
2016-04-14 09:02:47 [12379] [7] DEBUG: sequence_number: 2048 = 0x00000800
2016-04-14 09:02:47 [12379] [7] DEBUG: service_type: "USSD"
2016-04-14 09:02:47 [12379] [7] DEBUG: source_addr_ton: 0 = 0x00000000
2016-04-14 09:02:47 [12379] [7] DEBUG: source_addr_npi: 1 = 0x00000001
2016-04-14 09:02:47 [12379] [7] DEBUG: source_addr: "126"
2016-04-14 09:02:47 [12379] [7] DEBUG: dest_addr_ton: 1 = 0x00000001
2016-04-14 09:02:47 [12379] [7] DEBUG: dest_addr_npi: 1 = 0x00000001
2016-04-14 09:02:47 [12379] [7] DEBUG: destination_addr: "60103064822"
2016-04-14 09:02:47 [12379] [7] DEBUG: esm_class: 3 = 0x00000003
2016-04-14 09:02:47 [12379] [7] DEBUG: protocol_id: 0 = 0x00000000
2016-04-14 09:02:47 [12379] [7] DEBUG: priority_flag: 0 = 0x00000000
2016-04-14 09:02:47 [12379] [7] DEBUG: schedule_delivery_time: NULL
2016-04-14 09:02:47 [12379] [7] DEBUG: validity_period: NULL
2016-04-14 09:02:47 [12379] [7] DEBUG: registered_delivery: 0 = 0x00000000
2016-04-14 09:02:47 [12379] [7] DEBUG: replace_if_present_flag: 0 = 0x00000000
2016-04-14 09:02:47 [12379] [7] DEBUG: data_coding: 0 = 0x00000000
2016-04-14 09:02:47 [12379] [7] DEBUG: sm_default_msg_id: 0 = 0x00000000
2016-04-14 09:02:47 [12379] [7] DEBUG: sm_length: 14 = 0x0000000e
2016-04-14 09:02:47 [12379] [7] DEBUG: short_message: "1.TEST\n2.TEST2"
2016-04-14 09:02:47 [12379] [7] DEBUG: ussd_srv_op: "2"
2016-04-14 09:02:47 [12379] [7] DEBUG: SMPP PDU dump ends.
2016-04-14 09:02:47 [12379] [7] DEBUG: SMPP[smppUSSD2]: throughput (1.00,15.00)
2016-04-14 09:02:47 [12379] [7] DEBUG: SMPP[smppUSSD2]: throughput (1.00,15.00)
2016-04-14 09:02:47 [12379] [7] DEBUG: SMPP[smppUSSD2]: Got PDU:
2016-04-14 09:02:47 [12379] [7] DEBUG: SMPP PDU 0x7f90f400c270 dump:
2016-04-14 09:02:47 [12379] [7] DEBUG: type_name: submit_sm_resp
2016-04-14 09:02:47 [12379] [7] DEBUG: command_id: 2147483652 = 0x80000004
2016-04-14 09:02:47 [12379] [7] DEBUG: command_status: 0 = 0x00000000
2016-04-14 09:02:47 [12379] [7] DEBUG: sequence_number: 2048 = 0x00000800
2016-04-14 09:02:47 [12379] [7] DEBUG: message_id: NULL
2016-04-14 09:02:47 [12379] [7] DEBUG: SMPP PDU dump ends.
2016-04-14 09:02:47 [12379] [7] DEBUG: new group created `smpp'
2016-04-14 09:02:47 [12379] [7] DEBUG: group=`smpp' key=`ussd_srv_op' value=`2'
2016-04-14 09:02:47 [12379] [7] ERROR: SMPP[smppUSSD2]: SMSC returned error
code 0x00000000 (OK) in response to submit_sm PDU, but no `message_id' value!
2016-04-14 09:02:47 [12379] [7] DEBUG: SMPP[smppUSSD2]: throughput (1.00,15.00)
Replying to USSR via test phone
2016-04-14 09:02:52 [12379] [7] DEBUG: Optional parameter tag (0x0501)
2016-04-14 09:02:52 [12379] [7] DEBUG: Optional parameter length read as 1
2016-04-14 09:02:52 [12379] [7] DEBUG: Found configured optional parameter
`ussd_srv_op'
2016-04-14 09:02:52 [12379] [7] DEBUG: Optional parameter tag (0x4001)
2016-04-14 09:02:52 [12379] [7] DEBUG: Optional parameter length read as 15
2016-04-14 09:02:52 [12379] [7] DEBUG: Found configured optional parameter
`ussd_imsi'
2016-04-14 09:02:52 [12379] [7] DEBUG: Optional parameter tag (0x4002)
2016-04-14 09:02:52 [12379] [7] DEBUG: Optional parameter length read as 11
2016-04-14 09:02:52 [12379] [7] DEBUG: Found configured optional parameter
`ussd_vlr'
2016-04-14 09:02:52 [12379] [7] DEBUG: Optional parameter tag (0x4006)
2016-04-14 09:02:52 [12379] [7] DEBUG: Optional parameter length read as 11
2016-04-14 09:02:52 [12379] [7] DEBUG: Found configured optional parameter
`ussd_hlr'
2016-04-14 09:02:52 [12379] [7] DEBUG: Optional parameter tag (0x400c)
2016-04-14 09:02:52 [12379] [7] DEBUG: Optional parameter length read as 16
2016-04-14 09:02:52 [12379] [7] DEBUG: Found configured optional parameter
`ussd_optional'
2016-04-14 09:02:52 [12379] [7] DEBUG: SMPP[smppUSSD2]: Got PDU:
2016-04-14 09:02:52 [12379] [7] DEBUG: SMPP PDU 0x7f90f400c270 dump:
2016-04-14 09:02:52 [12379] [7] DEBUG: type_name: deliver_sm
2016-04-14 09:02:52 [12379] [7] DEBUG: command_id: 5 = 0x00000005
2016-04-14 09:02:52 [12379] [7] DEBUG: command_status: 0 = 0x00000000
2016-04-14 09:02:52 [12379] [7] DEBUG: sequence_number: 8771 = 0x00002243
2016-04-14 09:02:52 [12379] [7] DEBUG: service_type: "USSD"
2016-04-14 09:02:52 [12379] [7] DEBUG: source_addr_ton: 1 = 0x00000001
2016-04-14 09:02:52 [12379] [7] DEBUG: source_addr_npi: 1 = 0x00000001
2016-04-14 09:02:52 [12379] [7] DEBUG: source_addr: "60103064822"
2016-04-14 09:02:52 [12379] [7] DEBUG: dest_addr_ton: 0 = 0x00000000
2016-04-14 09:02:52 [12379] [7] DEBUG: dest_addr_npi: 0 = 0x00000000
2016-04-14 09:02:52 [12379] [7] DEBUG: destination_addr: NULL
2016-04-14 09:02:52 [12379] [7] DEBUG: esm_class: 0 = 0x00000000
2016-04-14 09:02:52 [12379] [7] DEBUG: protocol_id: 0 = 0x00000000
2016-04-14 09:02:52 [12379] [7] DEBUG: priority_flag: 0 = 0x00000000
2016-04-14 09:02:52 [12379] [7] DEBUG: schedule_delivery_time: NULL
2016-04-14 09:02:52 [12379] [7] DEBUG: validity_period: NULL
2016-04-14 09:02:52 [12379] [7] DEBUG: registered_delivery: 0 = 0x00000000
2016-04-14 09:02:52 [12379] [7] DEBUG: replace_if_present_flag: 0 = 0x00000000
2016-04-14 09:02:52 [12379] [7] DEBUG: data_coding: 15 = 0x0000000f
2016-04-14 09:02:52 [12379] [7] DEBUG: sm_default_msg_id: 0 = 0x00000000
2016-04-14 09:02:52 [12379] [7] DEBUG: sm_length: 1 = 0x00000001
2016-04-14 09:02:52 [12379] [7] DEBUG: short_message: "1"
2016-04-14 09:02:52 [12379] [7] DEBUG: ussd_service_op:
2016-04-14 09:02:52 [12379] [7] DEBUG: Octet string at 0x7f90f400b4d0:
2016-04-14 09:02:52 [12379] [7] DEBUG: len: 1
2016-04-14 09:02:52 [12379] [7] DEBUG: size: 2
2016-04-14 09:02:52 [12379] [7] DEBUG: immutable: 0
2016-04-14 09:02:52 [12379] [7] DEBUG: data: 12
.
2016-04-14 09:02:52 [12379] [7] DEBUG: Octet string dump ends.
2016-04-14 09:02:52 [12379] [7] DEBUG: ussd_hlr: "60192040152"
2016-04-14 09:02:52 [12379] [7] DEBUG: ussd_imsi: "502195700771324"
2016-04-14 09:02:52 [12379] [7] DEBUG: ussd_optional: "0010022C570EEBEB"
2016-04-14 09:02:52 [12379] [7] DEBUG: ussd_srv_op:
2016-04-14 09:02:52 [12379] [7] DEBUG: Octet string at 0x7f90f400bf20:
2016-04-14 09:02:52 [12379] [7] DEBUG: len: 1
2016-04-14 09:02:52 [12379] [7] DEBUG: size: 2
2016-04-14 09:02:52 [12379] [7] DEBUG: immutable: 0
2016-04-14 09:02:52 [12379] [7] DEBUG: data: 12
.
2016-04-14 09:02:52 [12379] [7] DEBUG: Octet string dump ends.
2016-04-14 09:02:52 [12379] [7] DEBUG: ussd_vlr: "60194030063"
2016-04-14 09:02:52 [12379] [7] DEBUG: SMPP PDU dump ends.
PSSR
2016-04-14 09:02:33 [12396] [5] INFO: Starting to service <*126#> from
<+60103064822> to <126>
2016-04-14 09:02:33 [12396] [10] DEBUG: Queue contains 0 pending requests.
2016-04-14 09:02:33 [12396] [10] DEBUG: Parsing URL
`http://17x.16x.5x.2xx:8080/USSDMO/mo.aspx?msisdn=%2B60103064822&Msg=*126%23&shortcode=126&metadata=%3Fsmpp%3Fussd_hlr%3D60192040152%26ussd_imsi%3D502195700771324%26ussd_optional%3D0010022C570EEBEB%26ussd_srv_op%3D%2501%26ussd_vlr%3D60194030063%26&ussdID=c53e73e6-cc7c-4a33-b43b-54841112af79':
2016-04-14 09:02:33 [12396] [10] DEBUG: Scheme: http://
2016-04-14 09:02:33 [12396] [10] DEBUG: Host: 17x.16x.5x.2xx
2016-04-14 09:02:33 [12396] [10] DEBUG: Port: 8080
2016-04-14 09:02:33 [12396] [10] DEBUG: Username: (null)
2016-04-14 09:02:33 [12396] [10] DEBUG: Password: (null)
2016-04-14 09:02:33 [12396] [10] DEBUG: Path: /USSDMO/mo.aspx
2016-04-14 09:02:33 [12396] [10] DEBUG: Query:
msisdn=%2B60103064822&Msg=*126%23&shortcode=126&metadata=%3Fsmpp%3Fussd_hlr%3D60192040152%26ussd_imsi%3D502195700771324%26ussd_optional%3D0010022C570EEBEB%26ussd_srv_op%3D%2501%26ussd_vlr%3D60194030063%26&ussdID=c53e73e6-cc7c-4a33-b43b-54841112af79
2016-04-14 09:02:33 [12396] [10] DEBUG: Fragment: (null)
2016-04-14 09:02:33 [12396] [10] DEBUG: Connecting nonblocking to
<17x.16x.5x.2xx>
2016-04-14 09:02:33 [12396] [10] DEBUG: HTTP: Opening connection to
`17x.16x.5x.2xx:8080' (fd=35).
2016-04-14 09:02:33 [12396] [10] DEBUG: Socket connecting
2016-04-14 09:02:33 [12396] [9] DEBUG: Get info about connecting socket
2016-04-14 09:02:33 [12396] [9] DEBUG: HTTP: Sending request:
2016-04-14 09:02:33 [12396] [9] DEBUG: Octet string at 0x7fa2ac001210:
2016-04-14 09:02:33 [12396] [9] DEBUG: len: 363
2016-04-14 09:02:33 [12396] [9] DEBUG: size: 1024
2016-04-14 09:02:33 [12396] [9] DEBUG: immutable: 0
2016-04-14 09:02:33 [12396] [9] DEBUG: data: 47 45 54 20 2f 55 53 53 44 4d 4f
2f 6d 6f 2e 61 GET /USSDMO/mo.a
2016-04-14 09:02:33 [12396] [9] DEBUG: data: 73 70 78 3f 6d 73 69 73 64 6e 3d
25 32 42 36 30 spx?msisdn=%2B60
2016-04-14 09:02:33 [12396] [9] DEBUG: data: 31 30 33 30 36 34 38 32 32 26 4d
73 67 3d 2a 31 103064822&Msg=*1
2016-04-14 09:02:33 [12396] [9] DEBUG: data: 32 36 25 32 33 26 73 68 6f 72 74
63 6f 64 65 3d 26%23&shortcode=
2016-04-14 09:02:33 [12396] [9] DEBUG: data: 31 32 36 26 6d 65 74 61 64 61 74
61 3d 25 33 46 126&metadata=%3F
2016-04-14 09:02:33 [12396] [9] DEBUG: data: 73 6d 70 70 25 33 46 75 73 73 64
5f 68 6c 72 25 smpp%3Fussd_hlr%
2016-04-14 09:02:33 [12396] [9] DEBUG: data: 33 44 36 30 31 39 32 30 34 30 31
35 32 25 32 36 3D60192040152%26
2016-04-14 09:02:33 [12396] [9] DEBUG: data: 75 73 73 64 5f 69 6d 73 69 25 33
44 35 30 32 31 ussd_imsi%3D5021
2016-04-14 09:02:33 [12396] [9] DEBUG: data: 39 35 37 30 30 37 37 31 33 32 34
25 32 36 75 73 95700771324%26us
2016-04-14 09:02:33 [12396] [9] DEBUG: data: 73 64 5f 6f 70 74 69 6f 6e 61 6c
25 33 44 30 30 sd_optional%3D00
2016-04-14 09:02:33 [12396] [9] DEBUG: data: 31 30 30 32 32 43 35 37 30 45 45
42 45 42 25 32 10022C570EEBEB%2
2016-04-14 09:02:33 [12396] [9] DEBUG: data: 36 75 73 73 64 5f 73 72 76 5f 6f
70 25 33 44 25 6ussd_srv_op%3D%
2016-04-14 09:02:33 [12396] [9] DEBUG: data: 32 35 30 31 25 32 36 75 73 73 64
5f 76 6c 72 25 2501%26ussd_vlr%
2016-04-14 09:02:33 [12396] [9] DEBUG: data: 33 44 36 30 31 39 34 30 33 30 30
36 33 25 32 36 3D60194030063%26
2016-04-14 09:02:33 [12396] [9] DEBUG: data: 26 75 73 73 64 49 44 3d 63 35 33
65 37 33 65 36 &ussdID=c53e73e6
2016-04-14 09:02:33 [12396] [9] DEBUG: data: 2d 63 63 37 63 2d 34 61 33 33 2d
62 34 33 62 2d -cc7c-4a33-b43b-
2016-04-14 09:02:33 [12396] [9] DEBUG: data: 35 34 38 34 31 31 31 32 61 66 37
39 20 48 54 54 54841112af79 HTT
2016-04-14 09:02:33 [12396] [9] DEBUG: data: 50 2f 31 2e 31 0d 0a 43 6f 6e 6e
65 63 74 69 6f P/1.1..Connectio
2016-04-14 09:02:33 [12396] [9] DEBUG: data: 6e 3a 20 6b 65 65 70 2d 61 6c 69
76 65 0d 0a 55 n: keep-alive..U
2016-04-14 09:02:33 [12396] [9] DEBUG: data: 73 65 72 2d 41 67 65 6e 74 3a 20
4b 61 6e 6e 65 ser-Agent: Kanne
2016-04-14 09:02:33 [12396] [9] DEBUG: data: 6c 2f 73 76 6e 2d 72 35 31 35 34
4d 0d 0a 48 6f l/svn-r5154M..Ho
2016-04-14 09:02:33 [12396] [9] DEBUG: data: 73 74 3a 20 31 37 3x 2e 31 36 3x
2e 35 3x 2e 32 st: 17x.16x.5x.2
2016-04-14 09:02:33 [12396] [9] DEBUG: data: 3x 3x 3x 38 30 38 30 0d 0a 0d 0a
xx:8080....
2016-04-14 09:02:33 [12396] [9] DEBUG: Octet string dump ends.
2016-04-14 09:02:33 [12396] [9] DEBUG: HTTP: Status line: <HTTP/1.1 200 OK>
2016-04-14 09:02:33 [12396] [9] DEBUG: HTTP: Received response:
2016-04-14 09:02:33 [12396] [9] DEBUG: Octet string at 0x7fa2ac001210:
2016-04-14 09:02:33 [12396] [9] DEBUG: len: 204
2016-04-14 09:02:33 [12396] [9] DEBUG: size: 1024
2016-04-14 09:02:33 [12396] [9] DEBUG: immutable: 0
2016-04-14 09:02:33 [12396] [9] DEBUG: data: 43 61 63 68 65 2d 43 6f 6e 74 72
6f 6c 3a 20 70 Cache-Control: p
2016-04-14 09:02:33 [12396] [9] DEBUG: data: 72 69 76 61 74 65 0d 0a 43 6f 6e
74 65 6e 74 2d rivate..Content-
2016-04-14 09:02:33 [12396] [9] DEBUG: data: 54 79 70 65 3a 20 74 65 78 74 2f
68 74 6d 6c 3b Type: text/html;
2016-04-14 09:02:33 [12396] [9] DEBUG: data: 20 63 68 61 72 73 65 74 3d 75 74
66 2d 38 0d 0a charset=utf-8..
2016-04-14 09:02:33 [12396] [9] DEBUG: data: 53 65 72 76 65 72 3a 20 4d 69 63
72 6f 73 6f 66 Server: Microsof
2016-04-14 09:02:33 [12396] [9] DEBUG: data: 74 2d 49 49 53 2f 38 2e 35 0d 0a
58 2d 41 73 70 t-IIS/8.5..X-Asp
2016-04-14 09:02:33 [12396] [9] DEBUG: data: 4e 65 74 2d 56 65 72 73 69 6f 6e
3a 20 34 2e 30 Net-Version: 4.0
2016-04-14 09:02:33 [12396] [9] DEBUG: data: 2e 33 30 33 31 39 0d 0a 58 2d 50
6f 77 65 72 65 .30319..X-Powere
2016-04-14 09:02:33 [12396] [9] DEBUG: data: 64 2d 42 79 3a 20 41 53 50 2e 4e
45 54 0d 0a 44 d-By: ASP.NET..D
2016-04-14 09:02:33 [12396] [9] DEBUG: data: 61 74 65 3a 20 54 68 75 2c 20 31
34 20 41 70 72 ate: Thu, 14 Apr
2016-04-14 09:02:33 [12396] [9] DEBUG: data: 20 32 30 31 36 20 30 31 3a 30 31
3a 32 39 20 47 2016 01:01:29 G
2016-04-14 09:02:33 [12396] [9] DEBUG: data: 4d 54 0d 0a 43 6f 6e 74 65 6e 74
2d 4c 65 6e 67 MT..Content-Leng
2016-04-14 09:02:33 [12396] [9] DEBUG: data: 74 68 3a 20 33 0d 0a 0d 0a 32 30
30 th: 3....200
2016-04-14 09:02:33 [12396] [9] DEBUG: Octet string dump ends.
2016-04-14 09:02:33 [12396] [6] WARNING: Tried to set Coding field, denied.
2016-04-14 09:02:33 [12396] [6] INFO: No reply sent, denied.
2016-04-14 09:02:43 [12396] [9] DEBUG: HTTP: Server closed connection,
destroying it <17x.16x.5x.2xx:8080:0::><0x7fa2a40009d0><fd:35>.
USSR
2016-04-14 09:02:47 [12396] [2] DEBUG: HTTP: Creating HTTPClient for
`17x.16x.5x.2xx'.
2016-04-14 09:02:47 [12396] [2] DEBUG: HTTP: Created HTTPClient area
0x7fa2c0000ac0.
2016-04-14 09:02:47 [12396] [3] INFO: smsbox: Got HTTP request
</cgi-bin/sendsms> from <17x.16x.5x.2xx>
2016-04-14 09:02:47 [12396] [3] INFO: sendsms used by <smsSMPP2>
2016-04-14 09:02:47 [12396] [3] INFO: sendsms sender:<smsSMPP2:126>
(17x.16x.5x.2xx) to:< 60103064822> msg:<1.TEST
2.TEST2>
2016-04-14 09:02:47 [12396] [3] DEBUG: Stored UUID
7f92796f-f60f-43df-be78-0a8672c9049c
2016-04-14 09:02:47 [12396] [3] DEBUG: message length 14, sending 1 messages
2016-04-14 09:02:47 [12396] [3] DEBUG: Status: 202 Answer: <Sent.>
2016-04-14 09:02:47 [12396] [3] DEBUG: Delayed reply - wait for bearerbox
2016-04-14 09:02:47 [12396] [0] DEBUG: Got ACK (0) of
7f92796f-f60f-43df-be78-0a8672c9049c
2016-04-14 09:02:47 [12396] [0] DEBUG: HTTP: Destroying HTTPClient area
0x7fa2c0000ac0.
2016-04-14 09:02:47 [12396] [0] DEBUG: HTTP: Destroying HTTPClient for
`17x.16x.5x.2xx'.
Replying to USSR via test phone
2016-04-14 09:02:52 [12396] [5] ERROR: smsbox_req_thread: no sender/receiver,
dump follows:
2016-04-14 09:02:52 [12396] [5] DEBUG: Msg object at 0x1c9b020:
2016-04-14 09:02:52 [12396] [5] DEBUG: type: sms
2016-04-14 09:02:52 [12396] [5] DEBUG: sms.sender:
2016-04-14 09:02:52 [12396] [5] DEBUG: Octet string at 0x1c9aff0:
2016-04-14 09:02:52 [12396] [5] DEBUG: len: 12
2016-04-14 09:02:52 [12396] [5] DEBUG: size: 13
2016-04-14 09:02:52 [12396] [5] DEBUG: immutable: 0
2016-04-14 09:02:52 [12396] [5] DEBUG: data: 2b 36 30 31 30 33 30 36 34 38
32 32 +60103064822
2016-04-14 09:02:52 [12396] [5] DEBUG: Octet string dump ends.
2016-04-14 09:02:52 [12396] [5] DEBUG: sms.receiver:
2016-04-14 09:02:52 [12396] [5] DEBUG: sms.udhdata:
2016-04-14 09:02:52 [12396] [5] DEBUG: sms.msgdata:
2016-04-14 09:02:52 [12396] [5] DEBUG: Octet string at 0x1c9ae70:
2016-04-14 09:02:52 [12396] [5] DEBUG: len: 1
2016-04-14 09:02:52 [12396] [5] DEBUG: size: 2
2016-04-14 09:02:52 [12396] [5] DEBUG: immutable: 0
2016-04-14 09:02:52 [12396] [5] DEBUG: data: 31
1
2016-04-14 09:02:52 [12396] [5] DEBUG: Octet string dump ends.
2016-04-14 09:02:52 [12396] [5] DEBUG: sms.time: 1460595772
2016-04-14 09:02:52 [12396] [5] DEBUG: sms.smsc_id:
2016-04-14 09:02:52 [12396] [5] DEBUG: Octet string at 0x1c98630:
2016-04-14 09:02:52 [12396] [5] DEBUG: len: 9
2016-04-14 09:02:52 [12396] [5] DEBUG: size: 10
2016-04-14 09:02:52 [12396] [5] DEBUG: immutable: 0
2016-04-14 09:02:52 [12396] [5] DEBUG: data: 73 6d 70 70 55 53 53 44 32
smppUSSD2
2016-04-14 09:02:52 [12396] [5] DEBUG: Octet string dump ends.
2016-04-14 09:02:52 [12396] [5] DEBUG: sms.smsc_number:
2016-04-14 09:02:52 [12396] [5] DEBUG: sms.foreign_id:
2016-04-14 09:02:52 [12396] [5] DEBUG: sms.service:
2016-04-14 09:02:52 [12396] [5] DEBUG: sms.account:
2016-04-14 09:02:52 [12396] [5] DEBUG: Octet string at 0x1c987b0:
2016-04-14 09:02:52 [12396] [5] DEBUG: len: 6
2016-04-14 09:02:52 [12396] [5] DEBUG: size: 7
2016-04-14 09:02:52 [12396] [5] DEBUG: immutable: 0
2016-04-14 09:02:52 [12396] [5] DEBUG: data: 7x 6x 7x 31 32 36
xxx126
2016-04-14 09:02:52 [12396] [5] DEBUG: Octet string dump ends.
2016-04-14 09:02:52 [12396] [5] DEBUG: sms.id:
ccd6d061-a771-4cc2-84ce-050a6f31a533
2016-04-14 09:02:52 [12396] [5] DEBUG: sms.sms_type: 0
2016-04-14 09:02:52 [12396] [5] DEBUG: sms.mclass: -1
2016-04-14 09:02:52 [12396] [5] DEBUG: sms.mwi: -1
2016-04-14 09:02:52 [12396] [5] DEBUG: sms.coding: 3
2016-04-14 09:02:52 [12396] [5] DEBUG: sms.compress: 0
2016-04-14 09:02:52 [12396] [5] DEBUG: sms.validity: -1
2016-04-14 09:02:52 [12396] [5] DEBUG: sms.deferred: -1
2016-04-14 09:02:52 [12396] [5] DEBUG: sms.dlr_mask: -1
2016-04-14 09:02:52 [12396] [5] DEBUG: sms.dlr_url:
2016-04-14 09:02:52 [12396] [5] DEBUG: sms.pid: 0
2016-04-14 09:02:52 [12396] [5] DEBUG: sms.alt_dcs: 0
2016-04-14 09:02:52 [12396] [5] DEBUG: sms.rpi: -1
2016-04-14 09:02:52 [12396] [5] DEBUG: sms.charset:
2016-04-14 09:02:52 [12396] [5] DEBUG: sms.boxc_id:
2016-04-14 09:02:52 [12396] [5] DEBUG: sms.binfo:
2016-04-14 09:02:52 [12396] [5] DEBUG: Octet string at 0x1c9b220:
2016-04-14 09:02:52 [12396] [5] DEBUG: len: 4
2016-04-14 09:02:52 [12396] [5] DEBUG: size: 5
2016-04-14 09:02:52 [12396] [5] DEBUG: immutable: 0
2016-04-14 09:02:52 [12396] [5] DEBUG: data: 55 53 53 44
USSD
2016-04-14 09:02:52 [12396] [5] DEBUG: Octet string dump ends.
2016-04-14 09:02:52 [12396] [5] DEBUG: sms.msg_left: -1
2016-04-14 09:02:52 [12396] [5] DEBUG: sms.split_parts: (nil)
2016-04-14 09:02:52 [12396] [5] DEBUG: sms.priority: 0
2016-04-14 09:02:52 [12396] [5] DEBUG: sms.resend_try: -1
2016-04-14 09:02:52 [12396] [5] DEBUG: sms.resend_time: -1
2016-04-14 09:02:52 [12396] [5] DEBUG: sms.meta_data:
2016-04-14 09:02:52 [12396] [5] DEBUG: Octet string at 0x1c9b250:
2016-04-14 09:02:52 [12396] [5] DEBUG: len: 121
2016-04-14 09:02:52 [12396] [5] DEBUG: size: 122
2016-04-14 09:02:52 [12396] [5] DEBUG: immutable: 0
2016-04-14 09:02:52 [12396] [5] DEBUG: data: 3f 73 6d 70 70 3f 75 73 73 64
5f 68 6c 72 3d 36 ?smpp?ussd_hlr=6
2016-04-14 09:02:52 [12396] [5] DEBUG: data: 30 31 39 32 30 34 30 31 35 32
26 75 73 73 64 5f 0192040152&ussd_
2016-04-14 09:02:52 [12396] [5] DEBUG: data: 69 6d 73 69 3d 35 30 32 31 39
35 37 30 30 37 37 imsi=50219570077
2016-04-14 09:02:52 [12396] [5] DEBUG: data: 31 33 32 34 26 75 73 73 64 5f
6f 70 74 69 6f 6e 1324&ussd_option
2016-04-14 09:02:52 [12396] [5] DEBUG: data: 61 6c 3d 30 30 31 30 30 32 32
43 35 37 30 45 45 al=0010022C570EE
2016-04-14 09:02:52 [12396] [5] DEBUG: data: 42 45 42 26 75 73 73 64 5f 73
72 76 5f 6f 70 3d BEB&ussd_srv_op=
2016-04-14 09:02:52 [12396] [5] DEBUG: data: 25 31 32 26 75 73 73 64 5f 76
6c 72 3d 36 30 31 %12&ussd_vlr=601
2016-04-14 09:02:52 [12396] [5] DEBUG: data: 39 34 30 33 30 30 36 33 26
94030063&
2016-04-14 09:02:52 [12396] [5] DEBUG: Octet string dump ends.
2016-04-14 09:02:52 [12396] [5] DEBUG: Msg object ends.
