No ringback tone on dahdi outgoing call

hi dear
i have problem in outgoing call from sip to pstn with ss7 dahdi chanell . incoming call not any problem, but in outgoing call from sip to pstn no ringback tone.
Thanking you in advanced and appreciate for your urgent reply.
Kind Regards
<— SIP read from UDP:192.168.2.150:5555 —>
INVITE sip:09126397436@sepco SIP/2.0
Via: SIP/2.0/UDP 192.168.2.150:5555;branch=z9hG4bK310zm24sn83j3k22msklzml1p
Route: sip:192.168.2.156:5060;transport=udp;lr
Call-ID: zzcil2k88mc48mslp88s4i8n84zcp0cp@192.168.2.150
From: sip:31589000@sepco;tag=ci0m031n
To: sip:09126397436@sepco
CSeq: 1 INVITE
Contact: sip:31589000@192.168.2.150:5555;transport=udp
Supported: 100rel,replaces,timer,privacy,in-dialog
Session-Expires: 1800
Accept: application/sdp
Min-SE: 90
Accept-Encoding: identity
Accept-Language: en
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,REGISTER,INFO,PRACK,SUBSCRIBE,NOTIFY,UPDATE,MESSAGE,REFER
Max-Forwards: 70
Session-ID: s4l2c30kpmkkps31np3nlzszjspnnn2l
Content-Length: 258
Content-Type: application/sdp

v=0
o=huawei 18 1 IN IP4 192.168.2.150
s=-
c=IN IP4 192.168.2.150
t=0 0
m=audio 28600 RTP/AVP 8 0 18 4
a=rtpmap:8 PCMA/8000
a=ptime:20
a=rtpmap:0 PCMU/8000
a=ptime:20
a=rtpmap:18 G729/8000
a=ptime:10
a=rtpmap:4 G723/8000
a=ptime:30
a=sendrecv
<------------->
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Header 0 [ 36]: INVITE sip:09126397436@sepco SIP/2.0
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Header 1 [ 75]: Via: SIP/2.0/UDP 192.168.2.150:5555;branch=z9hG4bK310zm24sn83j3k22msklzml1p
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Header 2 [ 48]: Route: sip:192.168.2.156:5060;transport=udp;lr
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Header 3 [ 55]: Call-ID: zzcil2k88mc48mslp88s4i8n84zcp0cp@192.168.2.150
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Header 4 [ 39]: From: sip:31589000@sepco;tag=ci0m031n
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Header 5 [ 27]: To: sip:09126397436@sepco
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Header 6 [ 14]: CSeq: 1 INVITE
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Header 7 [ 56]: Contact: sip:31589000@192.168.2.150:5555;transport=udp
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Header 8 [ 50]: Supported: 100rel,replaces,timer,privacy,in-dialog
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Header 9 [ 21]: Session-Expires: 1800
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Header 10 [ 23]: Accept: application/sdp
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Header 11 [ 10]: Min-SE: 90
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Header 12 [ 25]: Accept-Encoding: identity
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Header 13 [ 19]: Accept-Language: en
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Header 14 [ 94]: Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,REGISTER,INFO,PRACK,SUBSCRIBE,NOTIFY,UPDATE,MESSAGE,REFER
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Header 15 [ 16]: Max-Forwards: 70
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Header 16 [ 44]: Session-ID: s4l2c30kpmkkps31np3nlzszjspnnn2l
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Header 17 [ 19]: Content-Length: 258
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Header 18 [ 29]: Content-Type: application/sdp
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Header 19 [ 0]:
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Body 0 [ 3]: v=0
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Body 1 [ 34]: o=huawei 18 1 IN IP4 192.168.2.150
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Body 2 [ 3]: s=-
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Body 3 [ 22]: c=IN IP4 192.168.2.150
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Body 4 [ 5]: t=0 0
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Body 5 [ 30]: m=audio 28600 RTP/AVP 8 0 18 4
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Body 6 [ 20]: a=rtpmap:8 PCMA/8000
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Body 7 [ 10]: a=ptime:20
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Body 8 [ 20]: a=rtpmap:0 PCMU/8000
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Body 9 [ 10]: a=ptime:20
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Body 10 [ 21]: a=rtpmap:18 G729/8000
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Body 11 [ 10]: a=ptime:10
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Body 12 [ 20]: a=rtpmap:4 G723/8000
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Body 13 [ 10]: a=ptime:30
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Body 14 [ 10]: a=sendrecv
[2002-06-16 08:37:28] VERBOSE[2278] chan_sip.c: — (19 headers 15 lines) —
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: = Looking for Call ID: zzcil2k88mc48mslp88s4i8n84zcp0cp@192.168.2.150 (Checking From) --From tag ci0m031n --To-tag
[2002-06-16 08:37:28] DEBUG[2278] acl.c: For destination ‘192.168.2.150’, our source address is ‘192.168.2.156’.
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Setting SIP_TRANSPORT_UDP with address 192.168.2.156:5060
[2002-06-16 08:37:28] DEBUG[2278] netsock2.c: Splitting ‘192.168.2.150:5555’ into…
[2002-06-16 08:37:28] DEBUG[2278] netsock2.c: …host ‘192.168.2.150’ and port ‘5555’.
[2002-06-16 08:37:28] VERBOSE[2278] chan_sip.c: Sending to 192.168.2.150:5555 (no NAT)
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Allocating new SIP dialog for zzcil2k88mc48mslp88s4i8n84zcp0cp@192.168.2.150 - INVITE (No RTP)
[2002-06-16 08:37:28] DEBUG[2278][C-00000027] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE
[2002-06-16 08:37:28] DEBUG[2278][C-00000027] sip/reqresp_parser.c: Begin: parsing SIP “Supported: 100rel,replaces,timer,privacy,in-dialog”
[2002-06-16 08:37:28] DEBUG[2278][C-00000027] sip/reqresp_parser.c: Found SIP option: -100rel-
[2002-06-16 08:37:28] DEBUG[2278][C-00000027] sip/reqresp_parser.c: Matched SIP option: 100rel
[2002-06-16 08:37:28] DEBUG[2278][C-00000027] sip/reqresp_parser.c: Found SIP option: -replaces-
[2002-06-16 08:37:28] DEBUG[2278][C-00000027] sip/reqresp_parser.c: Matched SIP option: replaces
[2002-06-16 08:37:28] DEBUG[2278][C-00000027] sip/reqresp_parser.c: Found SIP option: -timer-
[2002-06-16 08:37:28] DEBUG[2278][C-00000027] sip/reqresp_parser.c: Matched SIP option: timer
[2002-06-16 08:37:28] DEBUG[2278][C-00000027] sip/reqresp_parser.c: Found SIP option: -privacy-
[2002-06-16 08:37:28] DEBUG[2278][C-00000027] sip/reqresp_parser.c: Matched SIP option: privacy
[2002-06-16 08:37:28] DEBUG[2278][C-00000027] sip/reqresp_parser.c: Found SIP option: -in-dialog-
[2002-06-16 08:37:28] DEBUG[2278][C-00000027] sip/reqresp_parser.c: Found no match for SIP option: in-dialog (Please file bug report!)
[2002-06-16 08:37:28] DEBUG[2278][C-00000027] netsock2.c: Splitting ‘192.168.2.150:5555’ into…
[2002-06-16 08:37:28] DEBUG[2278][C-00000027] netsock2.c: …host ‘192.168.2.150’ and port ‘5555’.
[2002-06-16 08:37:28] VERBOSE[2278][C-00000027] chan_sip.c: Sending to 192.168.2.150:5555 (no NAT)
[2002-06-16 08:37:28] DEBUG[2278][C-00000027] chan_sip.c: Initializing initreq for method INVITE - callid zzcil2k88mc48mslp88s4i8n84zcp0cp@192.168.2.150
[2002-06-16 08:37:28] VERBOSE[2278][C-00000027] chan_sip.c: Using INVITE request as basis request - zzcil2k88mc48mslp88s4i8n84zcp0cp@192.168.2.150
[2002-06-16 08:37:28] DEBUG[2278][C-00000027] netsock2.c: Splitting ‘sepco’ into…
[2002-06-16 08:37:28] DEBUG[2278][C-00000027] netsock2.c: …host ‘sepco’ and port ‘’.
[2002-06-16 08:37:28] VERBOSE[2278][C-00000027] chan_sip.c: Found peer ‘31589000’ for ‘31589000’ from 192.168.2.150:5555
[2002-06-16 08:37:28] VERBOSE[2278][C-00000027] chan_sip.c:
<— Reliably Transmitting (no NAT) to 192.168.2.150:5555 —>
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 192.168.2.150:5555;branch=z9hG4bK310zm24sn83j3k22msklzml1p;received=192.168.2.150
From: sip:31589000@sepco;tag=ci0m031n
To: sip:09126397436@sepco;tag=as0a510d33
Call-ID: zzcil2k88mc48mslp88s4i8n84zcp0cp@192.168.2.150
CSeq: 1 INVITE
Server: FPBX-2.11.0(11.20.0)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
WWW-Authenticate: Digest algorithm=MD5, realm=“asterisk”, nonce="0fb4e75e"
Content-Length: 0

<------------>
[2002-06-16 08:37:28] DEBUG[2278][C-00000027] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #7
[2002-06-16 08:37:28] DEBUG[2278][C-00000027] chan_sip.c: Trying to put ‘SIP/2.0 401’ onto UDP socket destined for 192.168.2.150:5555
[2002-06-16 08:37:28] VERBOSE[2278][C-00000027] chan_sip.c: Scheduling destruction of SIP dialog ‘zzcil2k88mc48mslp88s4i8n84zcp0cp@192.168.2.150’ in 6400 ms (Method: INVITE)
[2002-06-16 08:37:28] VERBOSE[2278] chan_sip.c:
<— SIP read from UDP:192.168.2.150:5555 —>
ACK sip:09126397436@sepco SIP/2.0
Via: SIP/2.0/UDP 192.168.2.150:5555;branch=z9hG4bK310zm24sn83j3k22msklzml1p
Route: sip:192.168.2.156:5060;transport=udp;lr
Call-ID: zzcil2k88mc48mslp88s4i8n84zcp0cp@192.168.2.150
From: sip:31589000@sepco;tag=ci0m031n
To: sip:09126397436@sepco;tag=as0a510d33
CSeq: 1 ACK
Max-Forwards: 70
Content-Length: 0

<------------->
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Header 0 [ 33]: ACK sip:09126397436@sepco SIP/2.0
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Header 1 [ 75]: Via: SIP/2.0/UDP 192.168.2.150:5555;branch=z9hG4bK310zm24sn83j3k22msklzml1p
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Header 2 [ 48]: Route: sip:192.168.2.156:5060;transport=udp;lr
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Header 3 [ 55]: Call-ID: zzcil2k88mc48mslp88s4i8n84zcp0cp@192.168.2.150
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Header 4 [ 39]: From: sip:31589000@sepco;tag=ci0m031n
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Header 5 [ 42]: To: sip:09126397436@sepco;tag=as0a510d33
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Header 6 [ 11]: CSeq: 1 ACK
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Header 7 [ 16]: Max-Forwards: 70
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Header 8 [ 17]: Content-Length: 0
[2002-06-16 08:37:28] VERBOSE[2278] chan_sip.c: — (9 headers 0 lines) —
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: = Looking for Call ID: zzcil2k88mc48mslp88s4i8n84zcp0cp@192.168.2.150 (Checking From) --From tag ci0m031n --To-tag as0a510d33
[2002-06-16 08:37:28] DEBUG[2278][C-00000027] chan_sip.c: **** Received ACK (6) - Command in SIP ACK
[2002-06-16 08:37:28] DEBUG[2278][C-00000027] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #7
[2002-06-16 08:37:28] DEBUG[2278][C-00000027] chan_sip.c: Stopping retransmission on ‘zzcil2k88mc48mslp88s4i8n84zcp0cp@192.168.2.150’ of Response 1: Match Found
[2002-06-16 08:37:28] VERBOSE[2278] chan_sip.c:
<— SIP read from UDP:192.168.2.150:5555 —>
INVITE sip:09126397436@sepco SIP/2.0
Via: SIP/2.0/UDP 192.168.2.150:5555;branch=z9hG4bK2l01213sjl2cmn31pn1km33j8
Route: sip:192.168.2.156:5060;transport=udp;lr
Call-ID: zzcil2k88mc48mslp88s4i8n84zcp0cp@192.168.2.150
From: sip:31589000@sepco;tag=ci0m031n
To: sip:09126397436@sepco
CSeq: 2 INVITE
Contact: sip:31589000@192.168.2.150:5555;transport=udp
Supported: 100rel,replaces,timer,privacy,in-dialog
Session-Expires: 1800
Accept: application/sdp
Min-SE: 90
Authorization: Digest username=“31589000”,realm=“asterisk”,nonce=“0fb4e75e”,uri=“sip:09126397436@sepco”,response=“912f57389f876db3a9b5864bce063be9”,algorithm=MD5
Accept-Encoding: identity
Accept-Language: en
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,REGISTER,INFO,PRACK,SUBSCRIBE,NOTIFY,UPDATE,MESSAGE,REFER
Max-Forwards: 70
Session-ID: s4l2c30kpmkkps31np3nlzszjspnnn2l
Content-Length: 258
Content-Type: application/sdp

v=0
o=huawei 18 2 IN IP4 192.168.2.150
s=-
c=IN IP4 192.168.2.150
t=0 0
m=audio 28600 RTP/AVP 8 0 18 4
a=rtpmap:8 PCMA/8000
a=ptime:20
a=rtpmap:0 PCMU/8000
a=ptime:20
a=rtpmap:18 G729/8000
a=ptime:10
a=rtpmap:4 G723/8000
a=ptime:30
a=sendrecv
<------------->
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Header 0 [ 36]: INVITE sip:09126397436@sepco SIP/2.0
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Header 1 [ 75]: Via: SIP/2.0/UDP 192.168.2.150:5555;branch=z9hG4bK2l01213sjl2cmn31pn1km33j8
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Header 2 [ 48]: Route: sip:192.168.2.156:5060;transport=udp;lr
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Header 3 [ 55]: Call-ID: zzcil2k88mc48mslp88s4i8n84zcp0cp@192.168.2.150
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Header 4 [ 39]: From: sip:31589000@sepco;tag=ci0m031n
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Header 5 [ 27]: To: sip:09126397436@sepco
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Header 6 [ 14]: CSeq: 2 INVITE
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Header 7 [ 56]: Contact: sip:31589000@192.168.2.150:5555;transport=udp
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Header 8 [ 50]: Supported: 100rel,replaces,timer,privacy,in-dialog
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Header 9 [ 21]: Session-Expires: 1800
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Header 10 [ 23]: Accept: application/sdp
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Header 11 [ 10]: Min-SE: 90
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Header 12 [161]: Authorization: Digest username=“31589000”,realm=“asterisk”,nonce=“0fb4e75e”,uri=“sip:09126397436@sepco”,response=“912f57389f876db3a9b5864bce063be9”,algorithm=MD5
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Header 13 [ 25]: Accept-Encoding: identity
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Header 14 [ 19]: Accept-Language: en
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Header 15 [ 94]: Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,REGISTER,INFO,PRACK,SUBSCRIBE,NOTIFY,UPDATE,MESSAGE,REFER
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Header 16 [ 16]: Max-Forwards: 70
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Header 17 [ 44]: Session-ID: s4l2c30kpmkkps31np3nlzszjspnnn2l
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Header 18 [ 19]: Content-Length: 258
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Header 19 [ 29]: Content-Type: application/sdp
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Header 20 [ 0]:
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Body 0 [ 3]: v=0
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Body 1 [ 34]: o=huawei 18 2 IN IP4 192.168.2.150
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Body 2 [ 3]: s=-
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Body 3 [ 22]: c=IN IP4 192.168.2.150
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Body 4 [ 5]: t=0 0
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Body 5 [ 30]: m=audio 28600 RTP/AVP 8 0 18 4
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Body 6 [ 20]: a=rtpmap:8 PCMA/8000
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Body 7 [ 10]: a=ptime:20
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Body 8 [ 20]: a=rtpmap:0 PCMU/8000
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Body 9 [ 10]: a=ptime:20
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Body 10 [ 21]: a=rtpmap:18 G729/8000
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Body 11 [ 10]: a=ptime:10
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Body 12 [ 20]: a=rtpmap:4 G723/8000
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Body 13 [ 10]: a=ptime:30
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: Body 14 [ 10]: a=sendrecv
[2002-06-16 08:37:28] VERBOSE[2278] chan_sip.c: — (20 headers 15 lines) —
[2002-06-16 08:37:28] DEBUG[2278] chan_sip.c: = Looking for Call ID: zzcil2k88mc48mslp88s4i8n84zcp0cp@192.168.2.150 (Checking From) --From tag ci0m031n --To-tag
[2002-06-16 08:37:28] DEBUG[2278] netsock2.c: Splitting ‘sepco’ into…
[2002-06-16 08:37:28] DEBUG[2278] netsock2.c: …host ‘sepco’ and port ‘’.
[2002-06-16 08:37:28] DEBUG[2278] netsock2.c: Splitting ‘sepco’ into…
[2002-06-16 08:37:28] DEBUG[2278] netsock2.c: …host ‘sepco’ and port ‘’.
[2002-06-16 08:37:28] DEBUG[2278][C-00000027] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE
[2002-06-16 08:37:28] DEBUG[2278][C-00000027] netsock2.c: Splitting ‘192.168.2.150:5555’ into…
[2002-06-16 08:37:28] DEBUG[2278][C-00000027] netsock2.c: …host ‘192.168.2.150’ and port ‘5555’.
[2002-06-16 08:37:28] VERBOSE[2278][C-00000027] chan_sip.c: Sending to 192.168.2.150:5555 (no NAT)
[2002-06-16 08:37:28] DEBUG[2278][C-00000027] chan_sip.c: Initializing initreq for method INVITE - callid zzcil2k88mc48mslp88s4i8n84zcp0cp@192.168.2.150
[2002-06-16 08:37:28] VERBOSE[2278][C-00000027] chan_sip.c: Using INVITE request as basis request - zzcil2k88mc48mslp88s4i8n84zcp0cp@192.168.2.150
[2002-06-16 08:37:28] DEBUG[2278][C-00000027] netsock2.c: Splitting ‘sepco’ into…
[2002-06-16 08:37:28] DEBUG[2278][C-00000027] netsock2.c: …host ‘sepco’ and port ‘’.
[2002-06-16 08:37:28] VERBOSE[2278][C-00000027] chan_sip.c: Found peer ‘31589000’ for ‘31589000’ from 192.168.2.150:5555
[2002-06-16 08:37:28] DEBUG[2278][C-00000027] rtp_engine.c: Using engine ‘asterisk’ for RTP instance ‘0x7ff83801e5e8’
[2002-06-16 08:37:28] DEBUG[2278][C-00000027] res_rtp_asterisk.c: Allocated port 11258 for RTP instance ‘0x7ff83801e5e8’
[2002-06-16 08:37:28] DEBUG[2278][C-00000027] res_rtp_asterisk.c: Creating ICE session 0.0.0.0:11258 (11258) for RTP instance ‘0x7ff83801e5e8’
[2002-06-16 08:37:28] DEBUG[2278][C-00000027] netsock2.c: Splitting ‘192.168.2.156’ into…
[2002-06-16 08:37:28] DEBUG[2278][C-00000027] netsock2.c: …host ‘192.168.2.156’ and port ‘’.
[2002-06-16 08:37:28] DEBUG[2278][C-00000027] rtp_engine.c: RTP instance ‘0x7ff83801e5e8’ is setup and ready to go
[2002-06-16 08:37:28] DEBUG[2278][C-00000027] res_rtp_asterisk.c: Setup RTCP on RTP instance ‘0x7ff83801e5e8’
[2002-06-16 08:37:28] DEBUG[2278][C-00000027] chan_sip.c: Setting NAT on RTP to Off
[2002-06-16 08:37:28] DEBUG[2278][C-00000027] chan_sip.c: Processing session-level SDP v=0… UNSUPPORTED OR FAILED.
[2002-06-16 08:37:28] DEBUG[2278][C-00000027] chan_sip.c: Processing session-level SDP o=huawei 18 2 IN IP4 192.168.2.150… OK.
[2002-06-16 08:37:28] DEBUG[2278][C-00000027] chan_sip.c: Processing session-level SDP s=-… UNSUPPORTED OR FAILED.
[2002-06-16 08:37:28] DEBUG[2278][C-00000027] netsock2.c: Splitting ‘192.168.2.150’ into…
[2002-06-16 08:37:28] DEBUG[2278][C-00000027] netsock2.c: …host ‘192.168.2.150’ and port ‘’.
[2002-06-16 08:37:28] DEBUG[2278][C-00000027] chan_sip.c: Processing session-level SDP c=IN IP4 192.168.2.150… OK.
[2002-06-16 08:37:28] DEBUG[2278][C-00000027] chan_sip.c: Processing session-level SDP t=0 0… UNSUPPORTED OR FAILED.
[2002-06-16 08:37:28] VERBOSE[2278][C-00000027] chan_sip.c: Found RTP audio format 8
[2002-06-16 08:37:28] DEBUG[2278][C-00000027] rtp_engine.c: Setting payload 8 based on m type on 0x7ff854170b70
[2002-06-16 08:37:28] VERBOSE[2278][C-00000027] chan_sip.c: Found RTP audio format 0
[2002-06-16 08:37:28] DEBUG[2278][C-00000027] rtp_engine.c: Setting payload 0 based on m type on 0x7ff854170b70
[2002-06-16 08:37:28] VERBOSE[2278][C-00000027] chan_sip.c: Found RTP audio format 18
[2002-06-16 08:37:28] DEBUG[2278][C-00000027] rtp_engine.c: Setting payload 18 based on m type on 0x7ff854170b70
[2002-06-16 08:37:28] VERBOSE[2278][C-00000027] chan_sip.c: Found RTP audio format 4
[2002-06-16 08:37:28] DEBUG[2278][C-00000027] rtp_engine.c: Setting payload 4 based on m type on 0x7ff854170b70
[2002-06-16 08:37:28] VERBOSE[2278][C-00000027] chan_sip.c: Found audio description format PCMA for ID 8
[2002-06-16 08:37:28] DEBUG[2278][C-00000027] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:8 PCMA/8000… OK.
[2002-06-16 08:37:28] DEBUG[2278][C-00000027] chan_sip.c: Processing media-level (audio) SDP a=ptime:20… OK.
[2002-06-16 08:37:28] VERBOSE[2278][C-00000027] chan_sip.c: Found audio description format PCMU for ID 0
[2002-06-16 08:37:28] DEBUG[2278][C-00000027] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:0 PCMU/8000… OK.
[2002-06-16 08:37:28] DEBUG[2278][C-00000027] chan_sip.c: Processing media-level (audio) SDP a=ptime:20… OK.
[2002-06-16 08:37:28] VERBOSE[2278][C-00000027] chan_sip.c: Found audio description format G729 for ID 18
[2002-06-16 08:37:28] DEBUG[2278][C-00000027] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:18 G729/8000… OK.
[2002-06-16 08:37:28] DEBUG[2278][C-00000027] chan_sip.c: Processing media-level (audio) SDP a=ptime:10… OK.
[2002-06-16 08:37:28] VERBOSE[2278][C-00000027] chan_sip.c: Found audio description format G723 for ID 4
[2002-06-16 08:37:28] DEBUG[2278][C-00000027] chan_sip.c: Processing media-level (audio) SDP a=rtpmap:4 G723/8000… OK.
[2002-06-16 08:37:28] DEBUG[2278][C-00000027] chan_sip.c: Processing media-level (audio) SDP a=ptime:30… OK.
[2002-06-16 08:37:28] DEBUG[2278][C-00000027] chan_sip.c: Processing media-level (audio) SDP a=sendrecv… OK.
[2002-06-16 08:37:28] VERBOSE[2278][C-00000027] chan_sip.c: Capabilities: us - (ulaw|alaw), peer - audio=(g723|ulaw|alaw|g729)/video=(nothing)/text=(nothing), combined - (ulaw|alaw)
[2002-06-16 08:37:28] VERBOSE[2278][C-00000027] chan_sip.c: Non-codec capabilities (dtmf): us - 0x0 (nothing), peer - 0x0 (nothing), combined - 0x0 (nothing)
[2002-06-16 08:37:28] DEBUG[2278][C-00000027] res_rtp_asterisk.c: Set role to CONTROLLED (0x7ff83801e5e8)
[2002-06-16 08:37:28] DEBUG[2278][C-00000027] res_rtp_asterisk.c: Set role failed; no ice instance (0x7ff83801e5e8)
[2002-06-16 08:37:28] DEBUG[2278][C-00000027] res_rtp_asterisk.c: Setting RTCP address on RTP instance ‘0x7ff83801e5e8’
[2002-06-16 08:37:28] VERBOSE[2278][C-00000027] chan_sip.c: Peer audio RTP is at port 192.168.2.150:28600
[2002-06-16 08:37:28] DEBUG[2278][C-00000027] rtp_engine.c: Copying payload 0 from 0x7ff854170b70 to 0x7ff83801e7b0
[2002-06-16 08:37:28] DEBUG[2278][C-00000027] rtp_engine.c: Copying payload 4 from 0x7ff854170b70 to 0x7ff83801e7b0
[2002-06-16 08:37:28] DEBUG[2278][C-00000027] rtp_engine.c: Copying payload 8 from 0x7ff854170b70 to 0x7ff83801e7b0
[2002-06-16 08:37:28] DEBUG[2278][C-00000027] rtp_engine.c: Copying payload 18 from 0x7ff854170b70 to 0x7ff83801e7b0
[2002-06-16 08:37:28] DEBUG[2278][C-00000027] res_rtp_asterisk.c: Ignoring duplicate RTCP property on RTP instance ‘0x7ff83801e5e8’
[2002-06-16 08:37:28] DEBUG[2278][C-00000027] chan_sip.c: We’re settling with these formats: (ulaw|alaw)
[2002-06-16 08:37:28] DEBUG[2278][C-00000027] chan_sip.c: Checking SIP call limits for device 31589000
[2002-06-16 08:37:28] DEBUG[2278][C-00000027] chan_sip.c: Updating call counter for incoming call
[2002-06-16 08:37:28] DEBUG[2278][C-00000027] chan_sip.c: Call from peer ‘31589000’ is 1 out of 2147483647
[2002-06-16 08:37:28] DEBUG[2233] devicestate.c: No provider found, checking channel drivers for SIP - 31589000
[2002-06-16 08:37:28] DEBUG[2233] chan_sip.c: Checking device state for peer 31589000
[2002-06-16 08:37:28] DEBUG[2233] devicestate.c: Changing state for SIP/31589000 - state 2 (In use)
[2002-06-16 08:37:28] DEBUG[2233] devicestate.c: device ‘SIP/31589000’ state ‘2’
[2002-06-16 08:37:28] DEBUG[2313] app_queue.c: Device ‘SIP/31589000’ changed to state ‘2’ (In use) but we don’t care because they’re not a member of any queue.
[2002-06-16 08:37:28] DEBUG[2278][C-00000027] netsock2.c: Splitting ‘sepco’ into…
[2002-06-16 08:37:28] DEBUG[2278][C-00000027] netsock2.c: …host ‘sepco’ and port ‘’.
[2002-06-16 08:37:28] DEBUG[2278][C-00000027] netsock2.c: Splitting ‘sepco’ into…
[2002-06-16 08:37:28] DEBUG[2235] app_queue.c: Extension ‘31589000@ext-local’ changed to state ‘2’ (In use) but we don’t care because they’re not a member of any queue.
[2002-06-16 08:37:28] DEBUG[2343] manager.c: Examining event:
Event: ExtensionStatus
Privilege: call,all
Exten: 31589000
Context: ext-local
Hint: SIP/31589000,CustomPresence:31589000
Status: 1

Your log is either incomplete or the device has not been provided with any password with which to authenticate itself.

(Note early media may require the use of Progress(), but you are not getting that far in your logs.

how to fix this problem?

Configure a password on the device or remove the secret on Asterisk.

Correction: Provide a complete log. You have the INVITE, the 401 telling it it needs to authenticate, the ACK, and the re-send of the INVITE with authentication, but you don’t have the bit that follows, where it goes wrong for you.

With so much noise, I missed that it had sent the INVITE with authentication.

Thank you for reply.

Also, it’s a bit strange that the To:, From: and Route: headers are empty. Perhaps secondarily related to the authentication problem, but strange nonetheless.

Matt

That’s an artefact of the forum software. It is totally unsuitable for reporting SIP problems because it treats angle brackets as HTML markup. The OP needs to use </> to stop that happening.

1 Like

new debug
[2016-10-25 10:35:52] VERBOSE[2279] chan_dahdi.c: [1] Got message smaller than the minimum SS7 SU length. Dropping
[2016-10-25 10:35:56] VERBOSE[2279] chan_dahdi.c: [1] Got message smaller than the minimum SS7 SU length. Dropping
[2016-10-25 10:37:11] VERBOSE[2278] chan_sip.c:
<— SIP read from UDP:192.168.2.150:5060 —>
OPTIONS sip:192.168.2.156:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP 192.168.2.150:5060;branch=z9hG4bKccsn23cn02c3plic8kp8n88nn
Call-ID: s1s4kj1czj244ppp0sllj1k1sz3zccm4@192.168.2.150
From: sip:192.168.2.150:5060;transport=udp;tag=zl140jn0
To: sip:192.168.2.156;transport=udp
CSeq: 1 OPTIONS
Contact: sip:192.168.2.150:5060;transport=udp
Accept-Encoding: identity
Accept-Language: en
Max-Forwards: 70
Session-ID: jmjp1zksskj1s2nz01l303kn1z2clm41
Content-Length: 0

<------------->
[2016-10-25 10:37:11] VERBOSE[2278] chan_sip.c: — (12 headers 0 lines) —
[2016-10-25 10:37:11] VERBOSE[2278] chan_sip.c: Sending to 192.168.2.150:5060 (no NAT)
[2016-10-25 10:37:11] VERBOSE[2278] chan_sip.c: Looking for s in from-sip-external (domain 192.168.2.156)
[2016-10-25 10:37:11] VERBOSE[2278] chan_sip.c:
<— Transmitting (no NAT) to 192.168.2.150:5060 —>
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.2.150:5060;branch=z9hG4bKccsn23cn02c3plic8kp8n88nn;received=192.168.2.150
From: sip:192.168.2.150:5060;transport=udp;tag=zl140jn0
To: sip:192.168.2.156;transport=udp;tag=as54d8e418
Call-ID: s1s4kj1czj244ppp0sllj1k1sz3zccm4@192.168.2.150
CSeq: 1 OPTIONS
Server: FPBX-2.11.0(11.20.0)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Contact: sip:192.168.2.156:5060
Accept: application/sdp
Content-Length: 0

<------------>
[2016-10-25 10:37:11] VERBOSE[2278] chan_sip.c: Scheduling destruction of SIP dialog ‘s1s4kj1czj244ppp0sllj1k1sz3zccm4@192.168.2.150’ in 32000 ms (Method: OPTIONS)
[2016-10-25 10:37:12] VERBOSE[2278] chan_sip.c: Really destroying SIP dialog ‘siklkslmspkm04ijmzc3z0il8sk3sjcz@192.168.2.150’ Method: OPTIONS
[2016-10-25 10:37:16] VERBOSE[2278] chan_sip.c:
<— SIP read from UDP:192.168.2.150:5060 —>
OPTIONS sip:192.168.2.156:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP 192.168.2.150:5060;branch=z9hG4bK1j14j10nj4ms32ll12p4k13jk
Call-ID: cpn4n22z0psjjmz1jkssslnm33jc1smp@192.168.2.150
From: sip:192.168.2.150:5060;transport=udp;tag=lic3z2mi
To: sip:192.168.2.156;transport=udp
CSeq: 1 OPTIONS
Contact: sip:192.168.2.150:5060;transport=udp
Accept-Encoding: identity
Accept-Language: en
Max-Forwards: 70
Session-ID: 0j1cp1ciz3c4zkinl2lkjc84840418zc
Content-Length: 0

<------------->
[2016-10-25 10:37:16] VERBOSE[2278] chan_sip.c: — (12 headers 0 lines) —
[2016-10-25 10:37:16] VERBOSE[2278] chan_sip.c: Sending to 192.168.2.150:5060 (no NAT)
[2016-10-25 10:37:16] VERBOSE[2278] chan_sip.c: Looking for s in from-sip-external (domain 192.168.2.156)
[2016-10-25 10:37:16] VERBOSE[2278] chan_sip.c:
<— Transmitting (no NAT) to 192.168.2.150:5060 —>
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.2.150:5060;branch=z9hG4bK1j14j10nj4ms32ll12p4k13jk;received=192.168.2.150
From: sip:192.168.2.150:5060;transport=udp;tag=lic3z2mi
To: sip:192.168.2.156;transport=udp;tag=as774084eb
Call-ID: cpn4n22z0psjjmz1jkssslnm33jc1smp@192.168.2.150
CSeq: 1 OPTIONS
Server: FPBX-2.11.0(11.20.0)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Contact: sip:192.168.2.156:5060
Accept: application/sdp
Content-Length: 0

<------------>
[2016-10-25 10:37:16] VERBOSE[2278] chan_sip.c: Scheduling destruction of SIP dialog ‘cpn4n22z0psjjmz1jkssslnm33jc1smp@192.168.2.150’ in 32000 ms (Method: OPTIONS)
[2016-10-25 10:37:17] VERBOSE[2278] chan_sip.c: Really destroying SIP dialog ‘j3zsz32jc23iml03iknjppn1sjmn424k@192.168.2.150’ Method: OPTIONS
[2016-10-25 10:37:21] VERBOSE[2278] chan_sip.c: Reliably Transmitting (no NAT) to 192.168.2.150:5060:
OPTIONS sip:31589000@192.168.2.150:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP 192.168.2.156:5060;branch=z9hG4bK039bc034
Max-Forwards: 70
From: “Unknown” sip:Unknown@192.168.2.156;tag=as659704e5
To: sip:31589000@192.168.2.150:5060;transport=udp
Contact: sip:Unknown@192.168.2.156:5060
Call-ID: 7fc452f52ee182073ddec9841a65c623@192.168.2.156:5060
CSeq: 102 OPTIONS
User-Agent: FPBX-2.11.0(11.20.0)
Date: Tue, 25 Oct 2016 07:07:21 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Content-Length: 0


[2016-10-25 10:37:21] VERBOSE[2278] chan_sip.c:
<— SIP read from UDP:192.168.2.150:5060 —>
SIP/2.0 486 Busy Here
Via: SIP/2.0/UDP 192.168.2.156:5060;branch=z9hG4bK039bc034
Call-ID: 7fc452f52ee182073ddec9841a65c623@192.168.2.156:5060
From: "Unknown"sip:Unknown@192.168.2.156;tag=as659704e5
To: sip:31589000@192.168.2.150:5060;transport=udp;tag=s44li4n4
CSeq: 102 OPTIONS
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,REGISTER,INFO,PRACK,SUBSCRIBE,NOTIFY,UPDATE,MESSAGE,REFER
Session-ID: 242sjpc3jmkii22224n034pjij1kmjjs
Content-Length: 0

<------------->
[2016-10-25 10:37:21] VERBOSE[2278] chan_sip.c: — (9 headers 0 lines) —
[2016-10-25 10:37:21] VERBOSE[2278] chan_sip.c: Really destroying SIP dialog ‘7fc452f52ee182073ddec9841a65c623@192.168.2.156:5060’ Method: OPTIONS
[2016-10-25 10:37:21] VERBOSE[2278] chan_sip.c:
<— SIP read from UDP:192.168.2.150:5060 —>
OPTIONS sip:192.168.2.156:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP 192.168.2.150:5060;branch=z9hG4bKjmsj3li0znn2smnn4zzpi13p4
Call-ID: p20lzpilsskllns0jjknl03lk1ipkmjc@192.168.2.150
From: sip:192.168.2.150:5060;transport=udp;tag=k8szimzn
To: sip:192.168.2.156;transport=udp
CSeq: 1 OPTIONS
Contact: sip:192.168.2.150:5060;transport=udp
Accept-Encoding: identity
Accept-Language: en
Max-Forwards: 70
Session-ID: ps80cl812jk4zkk244cksps4cjp2ici1
Content-Length: 0

<------------->
[2016-10-25 10:37:21] VERBOSE[2278] chan_sip.c: — (12 headers 0 lines) —
[2016-10-25 10:37:21] VERBOSE[2278] chan_sip.c: Sending to 192.168.2.150:5060 (no NAT)
[2016-10-25 10:37:21] VERBOSE[2278] chan_sip.c: Looking for s in from-sip-external (domain 192.168.2.156)
[2016-10-25 10:37:21] VERBOSE[2278] chan_sip.c:
<— Transmitting (no NAT) to 192.168.2.150:5060 —>
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.2.150:5060;branch=z9hG4bKjmsj3li0znn2smnn4zzpi13p4;received=192.168.2.150
From: sip:192.168.2.150:5060;transport=udp;tag=k8szimzn
To: sip:192.168.2.156;transport=udp;tag=as6572b3fb
Call-ID: p20lzpilsskllns0jjknl03lk1ipkmjc@192.168.2.150
CSeq: 1 OPTIONS
Server: FPBX-2.11.0(11.20.0)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Contact: sip:192.168.2.156:5060
Accept: application/sdp
Content-Length: 0

<------------>
[2016-10-25 10:37:21] VERBOSE[2278] chan_sip.c: Scheduling destruction of SIP dialog ‘p20lzpilsskllns0jjknl03lk1ipkmjc@192.168.2.150’ in 32000 ms (Method: OPTIONS)
[2016-10-25 10:37:22] VERBOSE[2278] chan_sip.c: Really destroying SIP dialog ‘msj2m4mc2lzzkn1lczmmm44im0cn8k2c@192.168.2.150’ Method: OPTIONS
[2016-10-25 10:37:23] VERBOSE[2278] chan_sip.c:
<— SIP read from UDP:192.168.2.150:5060 —>
REGISTER sip:192.168.2.156 SIP/2.0
Via: SIP/2.0/UDP 192.168.2.150:5060;branch=z9hG4bK0ksiksms384i3mps2382841m0
Route: sip:192.168.2.156:5060;transport=udp;lr
Call-ID: l0zi8l4ln0cjm4mkm2kp4l303pp41c31@192.168.2.150
From: sip:31589000@192.168.2.156;tag=1clzpk48
To: sip:31589000@192.168.2.156
CSeq: 623 REGISTER
Contact: sip:31589000@192.168.2.150:5060;transport=udp
Supported: 100rel,replaces,timer,privacy,in-dialog
Expires: 600
Accept: application/sdp
Accept-Encoding: identity
Accept-Language: en
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,REGISTER,INFO,PRACK,SUBSCRIBE,NOTIFY,UPDATE,MESSAGE,REFER
Max-Forwards: 70
Session-ID: 084l04i012zippnjn8884jczjsi20pcl
Content-Length: 0

<------------->
[2016-10-25 10:37:23] VERBOSE[2278] chan_sip.c: — (17 headers 0 lines) —
[2016-10-25 10:37:23] VERBOSE[2278] chan_sip.c: Sending to 192.168.2.150:5060 (no NAT)
[2016-10-25 10:37:23] VERBOSE[2278] chan_sip.c: Sending to 192.168.2.150:5060 (no NAT)
[2016-10-25 10:37:23] VERBOSE[2278] chan_sip.c:
<— Transmitting (no NAT) to 192.168.2.150:5060 —>
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 192.168.2.150:5060;branch=z9hG4bK0ksiksms384i3mps2382841m0;received=192.168.2.150
From: sip:31589000@192.168.2.156;tag=1clzpk48
To: sip:31589000@192.168.2.156;tag=as6e6d1c34
Call-ID: l0zi8l4ln0cjm4mkm2kp4l303pp41c31@192.168.2.150
CSeq: 623 REGISTER
Server: FPBX-2.11.0(11.20.0)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
WWW-Authenticate: Digest algorithm=MD5, realm=“asterisk”, nonce="396e7373"
Content-Length: 0

<------------>
[2016-10-25 10:37:23] VERBOSE[2278] chan_sip.c: Scheduling destruction of SIP dialog ‘l0zi8l4ln0cjm4mkm2kp4l303pp41c31@192.168.2.150’ in 32000 ms (Method: REGISTER)
[2016-10-25 10:37:23] VERBOSE[2278] chan_sip.c:
<— SIP read from UDP:192.168.2.150:5060 —>
REGISTER sip:192.168.2.156 SIP/2.0
Via: SIP/2.0/UDP 192.168.2.150:5060;branch=z9hG4bKilli3c1jj13sjsk0ni2k1p40l
Route: sip:192.168.2.156:5060;transport=udp;lr
Call-ID: l0zi8l4ln0cjm4mkm2kp4l303pp41c31@192.168.2.150
From: sip:31589000@192.168.2.156;tag=1clzpk48
To: sip:31589000@192.168.2.156
CSeq: 624 REGISTER
Contact: sip:31589000@192.168.2.150:5060;transport=udp
Supported: 100rel,replaces,timer,privacy,in-dialog
Expires: 600
Accept: application/sdp
Authorization: Digest username=“31589000”,realm=“asterisk”,nonce=“396e7373”,uri=“sip:192.168.2.156”,response=“b1d4019fff8db943ec051c0092cb9864”,algorithm=MD5
Accept-Encoding: identity
Accept-Language: en
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,REGISTER,INFO,PRACK,SUBSCRIBE,NOTIFY,UPDATE,MESSAGE,REFER
Max-Forwards: 70
Session-ID: 084l04i012zippnjn8884jczjsi20pcl
Content-Length: 0

<------------->
[2016-10-25 10:37:23] VERBOSE[2278] chan_sip.c: — (18 headers 0 lines) —
[2016-10-25 10:37:23] VERBOSE[2278] chan_sip.c: Sending to 192.168.2.150:5060 (no NAT)
[2016-10-25 10:37:23] VERBOSE[2278] chan_sip.c: Reliably Transmitting (no NAT) to 192.168.2.150:5060:
OPTIONS sip:31589000@192.168.2.150:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP 192.168.2.156:5060;branch=z9hG4bK7583c22c
Max-Forwards: 70
From: “Unknown” sip:Unknown@192.168.2.156;tag=as6fcd6972
To: sip:31589000@192.168.2.150:5060;transport=udp
Contact: sip:Unknown@192.168.2.156:5060
Call-ID: 70455db12de9eef5402c9fd05f5b9489@192.168.2.156:5060
CSeq: 102 OPTIONS
User-Agent: FPBX-2.11.0(11.20.0)
Date: Tue, 25 Oct 2016 07:07:23 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Content-Length: 0


[2016-10-25 10:37:23] VERBOSE[2278] chan_sip.c:
<— Transmitting (no NAT) to 192.168.2.150:5060 —>
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.2.150:5060;branch=z9hG4bKilli3c1jj13sjsk0ni2k1p40l;received=192.168.2.150
From: sip:31589000@192.168.2.156;tag=1clzpk48
To: sip:31589000@192.168.2.156;tag=as6e6d1c34
Call-ID: l0zi8l4ln0cjm4mkm2kp4l303pp41c31@192.168.2.150
CSeq: 624 REGISTER
Server: FPBX-2.11.0(11.20.0)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Expires: 600
Contact: sip:31589000@192.168.2.150:5060;transport=udp;expires=600
Date: Tue, 25 Oct 2016 07:07:23 GMT
Content-Length: 0

<------------>
[2016-10-25 10:37:23] VERBOSE[2278] chan_sip.c: Scheduling destruction of SIP dialog ‘56847f5f4f571ef77e6ed6f856055568@192.168.2.156:5060’ in 6400 ms (Method: NOTIFY)
[2016-10-25 10:37:23] VERBOSE[2278] chan_sip.c: Reliably Transmitting (no NAT) to 192.168.2.150:5060:
NOTIFY sip:31589000@192.168.2.150:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP 192.168.2.156:5060;branch=z9hG4bK0b644f00
Max-Forwards: 70
From: “Unknown” sip:Unknown@192.168.2.156;tag=as280f43b2
To: sip:31589000@192.168.2.150:5060;transport=udp
Contact: sip:Unknown@192.168.2.156:5060
Call-ID: 56847f5f4f571ef77e6ed6f856055568@192.168.2.156:5060
CSeq: 102 NOTIFY
User-Agent: FPBX-2.11.0(11.20.0)
Event: message-summary
Content-Type: application/simple-message-summary
Content-Length: 88

Messages-Waiting: no
Message-Account: sip:*97@192.168.2.156
Voice-Message: 0/0 (0/0)


[2016-10-25 10:37:23] VERBOSE[2278] chan_sip.c: Scheduling destruction of SIP dialog ‘l0zi8l4ln0cjm4mkm2kp4l303pp41c31@192.168.2.150’ in 32000 ms (Method: REGISTER)
[2016-10-25 10:37:23] VERBOSE[2278] chan_sip.c:
<— SIP read from UDP:192.168.2.150:5060 —>
SIP/2.0 486 Busy Here
Via: SIP/2.0/UDP 192.168.2.156:5060;branch=z9hG4bK7583c22c
Call-ID: 70455db12de9eef5402c9fd05f5b9489@192.168.2.156:5060
From: "Unknown"sip:Unknown@192.168.2.156;tag=as6fcd6972
To: sip:31589000@192.168.2.150:5060;transport=udp;tag=l1n82isp
CSeq: 102 OPTIONS
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,REGISTER,INFO,PRACK,SUBSCRIBE,NOTIFY,UPDATE,MESSAGE,REFER
Session-ID: 3l02mj8l10kikcsz2nz0020icks8i1l1
Content-Length: 0

<------------->
[2016-10-25 10:37:23] VERBOSE[2278] chan_sip.c: — (9 headers 0 lines) —
[2016-10-25 10:37:23] VERBOSE[2278] chan_sip.c: Really destroying SIP dialog ‘70455db12de9eef5402c9fd05f5b9489@192.168.2.156:5060’ Method: OPTIONS
[2016-10-25 10:37:23] VERBOSE[2278] chan_sip.c:
<— SIP read from UDP:192.168.2.150:5060 —>
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.2.156:5060;branch=z9hG4bK0b644f00
Call-ID: 56847f5f4f571ef77e6ed6f856055568@192.168.2.156:5060
From: "Unknown"sip:Unknown@192.168.2.156;tag=as280f43b2
To: sip:31589000@192.168.2.150:5060;transport=udp;tag=ksplpmli
CSeq: 102 NOTIFY
Session-ID: 8mzi0jkpkspimic2c48iknc8jnm4n0lk
Content-Length: 0

<------------->
[2016-10-25 10:37:23] VERBOSE[2278] chan_sip.c: — (8 headers 0 lines) —
[2016-10-25 10:37:23] VERBOSE[2278] chan_sip.c: Really destroying SIP dialog ‘56847f5f4f571ef77e6ed6f856055568@192.168.2.156:5060’ Method: NOTIFY
[2016-10-25 10:37:26] VERBOSE[2278] chan_sip.c:
<— SIP read from UDP:192.168.2.150:5060 —>
OPTIONS sip:192.168.2.156:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP 192.168.2.150:5060;branch=z9hG4bKm4sl140is4lkz41cn28jkps12
Call-ID: k0snnlp1p3mklpp3s34c40cpc1nnz81c@192.168.2.150
From: sip:192.168.2.150:5060;transport=udp;tag=nsp2nni2
To: sip:192.168.2.156;transport=udp
CSeq: 1 OPTIONS
Contact: sip:192.168.2.150:5060;transport=udp
Accept-Encoding: identity
Accept-Language: en
Max-Forwards: 70
Session-ID: pspn0l0l00ll80ip20cicppj0l242801
Content-Length: 0

<------------->
[2016-10-25 10:37:26] VERBOSE[2278] chan_sip.c: — (12 headers 0 lines) —
[2016-10-25 10:37:26] VERBOSE[2278] chan_sip.c: Sending to 192.168.2.150:5060 (no NAT)
[2016-10-25 10:37:26] VERBOSE[2278] chan_sip.c: Looking for s in from-sip-external (domain 192.168.2.156)
[2016-10-25 10:37:26] VERBOSE[2278] chan_sip.c:
<— Transmitting (no NAT) to 192.168.2.150:5060 —>
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.2.150:5060;branch=z9hG4bKm4sl140is4lkz41cn28jkps12;received=192.168.2.150
From: sip:192.168.2.150:5060;transport=udp;tag=nsp2nni2
To: sip:192.168.2.156;transport=udp;tag=as1c222bf8
Call-ID: k0snnlp1p3mklpp3s34c40cpc1nnz81c@192.168.2.150
CSeq: 1 OPTIONS
Server: FPBX-2.11.0(11.20.0)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Contact: sip:192.168.2.156:5060
Accept: application/sdp
Content-Length: 0

<------------>
[2016-10-25 10:37:26] VERBOSE[2278] chan_sip.c: Scheduling destruction of SIP dialog ‘k0snnlp1p3mklpp3s34c40cpc1nnz81c@192.168.2.150’ in 32000 ms (Method: OPTIONS)
[2016-10-25 10:37:27] VERBOSE[2278] chan_sip.c: Really destroying SIP dialog ‘3c4pj8810ns8n4lk42431jpszmm444l0@192.168.2.150’ Method: OPTIONS
[2016-10-25 10:37:31] VERBOSE[2278] chan_sip.c:
<— SIP read from UDP:192.168.2.150:5060 —>
INVITE sip:09126397436@192.168.2.156 SIP/2.0
Via: SIP/2.0/UDP 192.168.2.150:5060;branch=z9hG4bKjs80mznipclm0024m2288ck2l
Route: sip:192.168.2.156:5060;transport=udp;lr
Call-ID: lmp48n8m13s0c41l8zp8k8ssk1lj3mcp@192.168.2.150
From: sip:31589000@192.168.2.156;tag=s00812sk
To: sip:09126397436@192.168.2.156
CSeq: 1 INVITE
Contact: sip:31589000@192.168.2.150:5060;transport=udp
Supported: 100rel,replaces,timer,privacy,in-dialog
Session-Expires: 1800
Accept: application/sdp
Min-SE: 90
Accept-Encoding: identity
Accept-Language: en
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,REGISTER,INFO,PRACK,SUBSCRIBE,NOTIFY,UPDATE,MESSAGE,REFER
Max-Forwards: 70
Session-ID: zcp2z814cksi4n0n3mkpizkck38c10li
Content-Length: 258
Content-Type: application/sdp

v=0
o=huawei 55 1 IN IP4 192.168.2.150
s=-
c=IN IP4 192.168.2.150
t=0 0
m=audio 28452 RTP/AVP 8 0 18 4
a=rtpmap:8 PCMA/8000
a=ptime:20
a=rtpmap:0 PCMU/8000
a=ptime:20
a=rtpmap:18 G729/8000
a=ptime:10
a=rtpmap:4 G723/8000
a=ptime:30
a=sendrecv
<------------->
[2016-10-25 10:37:31] VERBOSE[2278] chan_sip.c: — (19 headers 15 lines) —
[2016-10-25 10:37:31] VERBOSE[2278] chan_sip.c: Sending to 192.168.2.150:5060 (no NAT)
[2016-10-25 10:37:31] VERBOSE[2278][C-0000004f] chan_sip.c: Sending to 192.168.2.150:5060 (no NAT)
[2016-10-25 10:37:31] VERBOSE[2278][C-0000004f] chan_sip.c: Using INVITE request as basis request - lmp48n8m13s0c41l8zp8k8ssk1lj3mcp@192.168.2.150
[2016-10-25 10:37:31] VERBOSE[2278][C-0000004f] chan_sip.c: Found peer ‘31589000’ for ‘31589000’ from 192.168.2.150:5060
[2016-10-25 10:37:31] VERBOSE[2278][C-0000004f] chan_sip.c:
<— Reliably Transmitting (no NAT) to 192.168.2.150:5060 —>
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 192.168.2.150:5060;branch=z9hG4bKjs80mznipclm0024m2288ck2l;received=192.168.2.150
From: sip:31589000@192.168.2.156;tag=s00812sk
To: sip:09126397436@192.168.2.156;tag=as3f007176
Call-ID: lmp48n8m13s0c41l8zp8k8ssk1lj3mcp@192.168.2.150
CSeq: 1 INVITE
Server: FPBX-2.11.0(11.20.0)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
WWW-Authenticate: Digest algorithm=MD5, realm=“asterisk”, nonce="25eeb2a7"
Content-Length: 0

<------------>
[2016-10-25 10:37:31] VERBOSE[2278][C-0000004f] chan_sip.c: Scheduling destruction of SIP dialog ‘lmp48n8m13s0c41l8zp8k8ssk1lj3mcp@192.168.2.150’ in 6400 ms (Method: INVITE)
[2016-10-25 10:37:31] VERBOSE[2278] chan_sip.c:
<— SIP read from UDP:192.168.2.150:5060 —>
ACK sip:09126397436@192.168.2.156 SIP/2.0
Via: SIP/2.0/UDP 192.168.2.150:5060;branch=z9hG4bKjs80mznipclm0024m2288ck2l
Route: sip:192.168.2.156:5060;transport=udp;lr
Call-ID: lmp48n8m13s0c41l8zp8k8ssk1lj3mcp@192.168.2.150
From: sip:31589000@192.168.2.156;tag=s00812sk
To: sip:09126397436@192.168.2.156;tag=as3f007176
CSeq: 1 ACK
Max-Forwards: 70
Content-Length: 0

<------------->
[2016-10-25 10:37:31] VERBOSE[2278] chan_sip.c: — (9 headers 0 lines) —
[2016-10-25 10:37:31] VERBOSE[2278] chan_sip.c:
<— SIP read from UDP:192.168.2.150:5060 —>
INVITE sip:09126397436@192.168.2.156 SIP/2.0
Via: SIP/2.0/UDP 192.168.2.150:5060;branch=z9hG4bK3k2n32scss0jjkis41pj3psk2
Route: sip:192.168.2.156:5060;transport=udp;lr
Call-ID: lmp48n8m13s0c41l8zp8k8ssk1lj3mcp@192.168.2.150
From: sip:31589000@192.168.2.156;tag=s00812sk
To: sip:09126397436@192.168.2.156
CSeq: 2 INVITE
Contact: sip:31589000@192.168.2.150:5060;transport=udp
Supported: 100rel,replaces,timer,privacy,in-dialog
Session-Expires: 1800
Accept: application/sdp
Min-SE: 90
Authorization: Digest username=“31589000”,realm=“asterisk”,nonce=“25eeb2a7”,uri="sip:09126397436@192.168.2.156",response=“e5bb090347d8141f2c4b4885c3108f44”,algorithm=MD5
Accept-Encoding: identity
Accept-Language: en
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,REGISTER,INFO,PRACK,SUBSCRIBE,NOTIFY,UPDATE,MESSAGE,REFER
Max-Forwards: 70
Session-ID: zcp2z814cksi4n0n3mkpizkck38c10li
Content-Length: 258
Content-Type: application/sdp

v=0
o=huawei 55 2 IN IP4 192.168.2.150
s=-
c=IN IP4 192.168.2.150
t=0 0
m=audio 28452 RTP/AVP 8 0 18 4
a=rtpmap:8 PCMA/8000
a=ptime:20
a=rtpmap:0 PCMU/8000
a=ptime:20
a=rtpmap:18 G729/8000
a=ptime:10
a=rtpmap:4 G723/8000
a=ptime:30
a=sendrecv
<------------->
[2016-10-25 10:37:31] VERBOSE[2278] chan_sip.c: — (20 headers 15 lines) —
[2016-10-25 10:37:31] VERBOSE[2278][C-0000004f] chan_sip.c: Sending to 192.168.2.150:5060 (no NAT)
[2016-10-25 10:37:31] VERBOSE[2278][C-0000004f] chan_sip.c: Using INVITE request as basis request - lmp48n8m13s0c41l8zp8k8ssk1lj3mcp@192.168.2.150
[2016-10-25 10:37:31] VERBOSE[2278][C-0000004f] chan_sip.c: Found peer ‘31589000’ for ‘31589000’ from 192.168.2.150:5060
[2016-10-25 10:37:31] VERBOSE[2278][C-0000004f] chan_sip.c: Found RTP audio format 8
[2016-10-25 10:37:31] VERBOSE[2278][C-0000004f] chan_sip.c: Found RTP audio format 0
[2016-10-25 10:37:31] VERBOSE[2278][C-0000004f] chan_sip.c: Found RTP audio format 18
[2016-10-25 10:37:31] VERBOSE[2278][C-0000004f] chan_sip.c: Found RTP audio format 4
[2016-10-25 10:37:31] VERBOSE[2278][C-0000004f] chan_sip.c: Found audio description format PCMA for ID 8
[2016-10-25 10:37:31] VERBOSE[2278][C-0000004f] chan_sip.c: Found audio description format PCMU for ID 0
[2016-10-25 10:37:31] VERBOSE[2278][C-0000004f] chan_sip.c: Found audio description format G729 for ID 18
[2016-10-25 10:37:31] VERBOSE[2278][C-0000004f] chan_sip.c: Found audio description format G723 for ID 4
[2016-10-25 10:37:31] VERBOSE[2278][C-0000004f] chan_sip.c: Capabilities: us - (ulaw|alaw), peer - audio=(g723|ulaw|alaw|g729)/video=(nothing)/text=(nothing), combined - (ulaw|alaw)
[2016-10-25 10:37:31] VERBOSE[2278][C-0000004f] chan_sip.c: Non-codec capabilities (dtmf): us - 0x1 (telephone-event|), peer - 0x0 (nothing), combined - 0x0 (nothing)
[2016-10-25 10:37:31] VERBOSE[2278][C-0000004f] chan_sip.c: Peer audio RTP is at port 192.168.2.150:28452
[2016-10-25 10:37:31] VERBOSE[2278][C-0000004f] chan_sip.c: Looking for 09126397436 in from-internal (domain 192.168.2.156)
[2016-10-25 10:37:31] VERBOSE[2278][C-0000004f] chan_sip.c: list_route: hop: sip:31589000@192.168.2.150:5060;transport=udp
[2016-10-25 10:37:31] VERBOSE[2278][C-0000004f] chan_sip.c:
<— Transmitting (no NAT) to 192.168.2.150:5060 —>
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 192.168.2.150:5060;branch=z9hG4bK3k2n32scss0jjkis41pj3psk2;received=192.168.2.150
From: sip:31589000@192.168.2.156;tag=s00812sk
To: sip:09126397436@192.168.2.156
Call-ID: lmp48n8m13s0c41l8zp8k8ssk1lj3mcp@192.168.2.150
CSeq: 2 INVITE
Server: FPBX-2.11.0(11.20.0)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Session-Expires: 1800;refresher=uas
Contact: sip:09126397436@192.168.2.156:5060
Content-Length: 0

<------------>
[2016-10-25 10:37:31] VERBOSE[2278] chan_sip.c:
<— SIP read from UDP:192.168.2.150:5060 —>
OPTIONS sip:192.168.2.156:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP 192.168.2.150:5060;branch=z9hG4bK8841341j14n3li00lp4k828nn
Call-ID: mn2j4cnz481imlm810z8nizii34kzllk@192.168.2.150
From: sip:192.168.2.150:5060;transport=udp;tag=31mkl88k
To: sip:192.168.2.156;transport=udp
CSeq: 1 OPTIONS
Contact: sip:192.168.2.150:5060;transport=udp
Accept-Encoding: identity
Accept-Language: en
Max-Forwards: 70
Session-ID: pzil32mnl003m34i0jmlpnk0ks2mli2m
Content-Length: 0

<------------->
[2016-10-25 10:37:31] VERBOSE[2278] chan_sip.c: — (12 headers 0 lines) —
[2016-10-25 10:37:31] VERBOSE[2278] chan_sip.c: Sending to 192.168.2.150:5060 (no NAT)
[2016-10-25 10:37:31] VERBOSE[2278] chan_sip.c: Looking for s in from-sip-external (domain 192.168.2.156)
[2016-10-25 10:37:31] VERBOSE[2278] chan_sip.c:
<— Transmitting (no NAT) to 192.168.2.150:5060 —>
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.2.150:5060;branch=z9hG4bK8841341j14n3li00lp4k828nn;received=192.168.2.150
From: sip:192.168.2.150:5060;transport=udp;tag=31mkl88k
To: sip:192.168.2.156;transport=udp;tag=as55772c31
Call-ID: mn2j4cnz481imlm810z8nizii34kzllk@192.168.2.150
CSeq: 1 OPTIONS
Server: FPBX-2.11.0(11.20.0)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Contact: sip:192.168.2.156:5060
Accept: application/sdp
Content-Length: 0

<------------>
[2016-10-25 10:37:31] VERBOSE[2278] chan_sip.c: Scheduling destruction of SIP dialog ‘mn2j4cnz481imlm810z8nizii34kzllk@192.168.2.150’ in 32000 ms (Method: OPTIONS)
[2016-10-25 10:37:32] VERBOSE[2278] chan_sip.c: Really destroying SIP dialog ‘pi00sii4lmnj4nss2cz4m0nz3z48mk1s@192.168.2.150’ Method: OPTIONS
[2016-10-25 10:37:36] VERBOSE[2278] chan_sip.c:
<— SIP read from UDP:192.168.2.150:5060 —>
OPTIONS sip:192.168.2.156:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP 192.168.2.150:5060;branch=z9hG4bKnnl8lm4izz048n10ns44p2288
Call-ID: sz0k42k848c0c31kl2kjc4mlp2icnpp0@192.168.2.150
From: sip:192.168.2.150:5060;transport=udp;tag=s2lpzkz0
To: sip:192.168.2.156;transport=udp
CSeq: 1 OPTIONS
Contact: sip:192.168.2.150:5060;transport=udp
Accept-Encoding: identity
Accept-Language: en
Max-Forwards: 70
Session-ID: sjj210ljk332lcm2k1mknl1821ncnkc2
Content-Length: 0

<------------->
[2016-10-25 10:37:36] VERBOSE[2278] chan_sip.c: — (12 headers 0 lines) —
[2016-10-25 10:37:36] VERBOSE[2278] chan_sip.c: Sending to 192.168.2.150:5060 (no NAT)
[2016-10-25 10:37:36] VERBOSE[2278] chan_sip.c: Looking for s in from-sip-external (domain 192.168.2.156)
[2016-10-25 10:37:36] VERBOSE[2278] chan_sip.c:
<— Transmitting (no NAT) to 192.168.2.150:5060 —>
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.2.150:5060;branch=z9hG4bKnnl8lm4izz048n10ns44p2288;received=192.168.2.150
From: sip:192.168.2.150:5060;transport=udp;tag=s2lpzkz0
To: sip:192.168.2.156;transport=udp;tag=as11c400c4
Call-ID: sz0k42k848c0c31kl2kjc4mlp2icnpp0@192.168.2.150
CSeq: 1 OPTIONS
Server: FPBX-2.11.0(11.20.0)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Contact: sip:192.168.2.156:5060
Accept: application/sdp
Content-Length: 0

<------------>
[2016-10-25 10:37:36] VERBOSE[2278] chan_sip.c: Scheduling destruction of SIP dialog ‘sz0k42k848c0c31kl2kjc4mlp2icnpp0@192.168.2.150’ in 32000 ms (Method: OPTIONS)
[2016-10-25 10:37:36] ERROR[2279] chan_dahdi.c: [1] !! Unable to handle message of type 0x31 on CIC 2
[2016-10-25 10:37:38] VERBOSE[2278] chan_sip.c: Really destroying SIP dialog ‘8msiiz08lm2likjjz4slnk323c88j41n@192.168.2.150’ Method: OPTIONS
[2016-10-25 10:37:40] VERBOSE[10891][C-0000004f] chan_sip.c:
<— Transmitting (no NAT) to 192.168.2.150:5060 —>
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 192.168.2.150:5060;branch=z9hG4bK3k2n32scss0jjkis41pj3psk2;received=192.168.2.150
From: sip:31589000@192.168.2.156;tag=s00812sk
To: sip:09126397436@192.168.2.156
Call-ID: lmp48n8m13s0c41l8zp8k8ssk1lj3mcp@192.168.2.150
CSeq: 2 INVITE
Server: FPBX-2.11.0(11.20.0)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Session-Expires: 1800;refresher=uas
Contact: sip:09126397436@192.168.2.156:5060
Content-Length: 0

<------------>
[2016-10-25 10:37:41] VERBOSE[2278] chan_sip.c:
<— SIP read from UDP:192.168.2.150:5060 —>
OPTIONS sip:192.168.2.156:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP 192.168.2.150:5060;branch=z9hG4bKki8m4c8pl3lljsc2isk014k0j
Call-ID: 424ml04m828icknlii830zk8jpc1pm0k@192.168.2.150
From: sip:192.168.2.150:5060;transport=udp;tag=n2pk3mj1
To: sip:192.168.2.156;transport=udp
CSeq: 1 OPTIONS
Contact: sip:192.168.2.150:5060;transport=udp
Accept-Encoding: identity
Accept-Language: en
Max-Forwards: 70
Session-ID: 4lpsl88pk23ppmm1ckkkm1zs3zp10n0z
Content-Length: 0

<------------->
[2016-10-25 10:37:41] VERBOSE[2278] chan_sip.c: — (12 headers 0 lines) —
[2016-10-25 10:37:41] VERBOSE[2278] chan_sip.c: Sending to 192.168.2.150:5060 (no NAT)
[2016-10-25 10:37:41] VERBOSE[2278] chan_sip.c: Looking for s in from-sip-external (domain 192.168.2.156)
[2016-10-25 10:37:41] VERBOSE[2278] chan_sip.c:
<— Transmitting (no NAT) to 192.168.2.150:5060 —>
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.2.150:5060;branch=z9hG4bKki8m4c8pl3lljsc2isk014k0j;received=192.168.2.150
From: sip:192.168.2.150:5060;transport=udp;tag=n2pk3mj1
To: sip:192.168.2.156;transport=udp;tag=as4b4100ea
Call-ID: 424ml04m828icknlii830zk8jpc1pm0k@192.168.2.150
CSeq: 1 OPTIONS
Server: FPBX-2.11.0(11.20.0)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Contact: sip:192.168.2.156:5060
Accept: application/sdp
Content-Length: 0

i call from sip (31589000) to pstn cell number(09126397436) no ringback tone

From your trace, it looks like you’re never getting an ACK back in response to your 200 OK. If you’re 200 OK is never accepted or reaching the remote end (due to nat, or some other reason) you need to figure out why that is happening. Maybe check the SIP client and see if it’s actually receiving on the network the 200 OK, and if it is, why it’s not sending an ACK back.

Matt

ACK sip:09126397436@192.168.2.156 SIP/2.0
Via: SIP/2.0/UDP 192.168.2.150:5060;branch=z9hG4bK24zl8njj2iljmmscjl30kmn0j
Route: sip:192.168.2.156:5060;transport=udp;lr
Call-ID: ksps400180320nzl32lj3i0s08psz310@192.168.2.150
From: sip:31589000@192.168.2.156;tag=i0izpnzz
To: sip:09126397436@192.168.2.156;tag=as501fd93d
CSeq: 1 ACK
Max-Forwards: 70
Content-Length: 0

[2016-10-25 17:12:16] VERBOSE[2278] chan_sip.c:
<— SIP read from UDP:192.168.2.150:5060 —>
ACK sip:09126397436@192.168.2.156 SIP/2.0
Via: SIP/2.0/UDP 192.168.2.150:5060;branch=z9hG4bK24zl8njj2iljmmscjl30kmn0j
Route: sip:192.168.2.156:5060;transport=udp;lr
Call-ID: ksps400180320nzl32lj3i0s08psz310@192.168.2.150
From: sip:31589000@192.168.2.156;tag=i0izpnzz
To: sip:09126397436@192.168.2.156;tag=as501fd93d
CSeq: 1 ACK
Max-Forwards: 70
Content-Length: 0

<------------->
[2016-10-25 17:12:16] VERBOSE[2278] chan_sip.c: — (9 headers 0 lines) —
[2016-10-25 17:12:16] VERBOSE[2278] chan_sip.c:
<— SIP read from UDP:192.168.2.150:5060 —>
INVITE sip:09126397436@192.168.2.156 SIP/2.0
Via: SIP/2.0/UDP 192.168.2.150:5060;branch=z9hG4bKsc33ckcckz40i8kn3lkmp1jmc
Route: sip:192.168.2.156:5060;transport=udp;lr
Call-ID: ksps400180320nzl32lj3i0s08psz310@192.168.2.150
From: sip:31589000@192.168.2.156;tag=i0izpnzz
To: sip:09126397436@192.168.2.156
CSeq: 2 INVITE
Contact: sip:31589000@192.168.2.150:5060;transport=udp
Supported: 100rel,replaces,timer,privacy,in-dialog
Session-Expires: 1800
Accept: application/sdp
Min-SE: 90
Authorization: Digest username=“31589000”,realm=“asterisk”,nonce=“3b6225f4”,uri="sip:09126397436@192.168.2.156",response=“fcc98b3380b4cda4249cdaf73411bfd1”,algorithm=MD5
Accept-Encoding: identity
Accept-Language: en
Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,REGISTER,INFO,PRACK,SUBSCRIBE,NOTIFY,UPDATE,MESSAGE,REFER
Max-Forwards: 70
Session-ID: i0kjim32l14k410clp3443zj0cp8pnmm
Content-Length: 258
Content-Type: application/sdp

v=0
o=huawei 10 2 IN IP4 192.168.2.150
s=-
c=IN IP4 192.168.2.150
t=0 0
m=audio 28632 RTP/AVP 8 0 18 4
a=rtpmap:8 PCMA/8000
a=ptime:20
a=rtpmap:0 PCMU/8000
a=ptime:20
a=rtpmap:18 G729/8000
a=ptime:10
a=rtpmap:4 G723/8000
a=ptime:30
a=sendrecv
<------------->
[2016-10-25 17:12:16] VERBOSE[2278] chan_sip.c: — (20 headers 15 lines) —
[2016-10-25 17:12:16] VERBOSE[2278][C-0000006b] chan_sip.c: Sending to 192.168.2.150:5060 (no NAT)
[2016-10-25 17:12:16] VERBOSE[2278][C-0000006b] chan_sip.c: Using INVITE request as basis request - ksps400180320nzl32lj3i0s08psz310@192.168.2.150
[2016-10-25 17:12:16] VERBOSE[2278][C-0000006b] chan_sip.c: Found peer ‘31589000’ for ‘31589000’ from 192.168.2.150:5060
[2016-10-25 17:12:16] VERBOSE[2278][C-0000006b] chan_sip.c: Found RTP audio format 8
[2016-10-25 17:12:16] VERBOSE[2278][C-0000006b] chan_sip.c: Found RTP audio format 0
[2016-10-25 17:12:16] VERBOSE[2278][C-0000006b] chan_sip.c: Found RTP audio format 18
[2016-10-25 17:12:16] VERBOSE[2278][C-0000006b] chan_sip.c: Found RTP audio format 4
[2016-10-25 17:12:16] VERBOSE[2278][C-0000006b] chan_sip.c: Found audio description format PCMA for ID 8
[2016-10-25 17:12:16] VERBOSE[2278][C-0000006b] chan_sip.c: Found audio description format PCMU for ID 0
[2016-10-25 17:12:16] VERBOSE[2278][C-0000006b] chan_sip.c: Found audio description format G729 for ID 18
[2016-10-25 17:12:16] VERBOSE[2278][C-0000006b] chan_sip.c: Found audio description format G723 for ID 4
[2016-10-25 17:12:16] VERBOSE[2278][C-0000006b] chan_sip.c: Capabilities: us - (ulaw|alaw), peer - audio=(g723|ulaw|alaw|g729)/video=(nothing)/text=(nothing), combined - (ulaw|alaw)
[2016-10-25 17:12:16] VERBOSE[2278][C-0000006b] chan_sip.c: Non-codec capabilities (dtmf): us - 0x1 (telephone-event|), peer - 0x0 (nothing), combined - 0x0 (nothing)
[2016-10-25 17:12:16] VERBOSE[2278][C-0000006b] chan_sip.c: Peer audio RTP is at port 192.168.2.150:28632
[2016-10-25 17:12:16] VERBOSE[2278][C-0000006b] chan_sip.c: Looking for 09126397436 in from-internal (domain 192.168.2.156)
[2016-10-25 17:12:16] VERBOSE[2278][C-0000006b] chan_sip.c: list_route: hop: sip:31589000@192.168.2.150:5060;transport=udp
[2016-10-25 17:12:16] VERBOSE[2278][C-0000006b] chan_sip.c:
<— Transmitting (no NAT) to 192.168.2.150:5060 —>
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 192.168.2.150:5060;branch=z9hG4bKsc33ckcckz40i8kn3lkmp1jmc;received=192.168.2.150
From: sip:31589000@192.168.2.156;tag=i0izpnzz
To: sip:09126397436@192.168.2.156
Call-ID: ksps400180320nzl32lj3i0s08psz310@192.168.2.150
CSeq: 2 INVITE
Server: FPBX-2.11.0(11.20.0)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Session-Expires: 1800;refresher=uas
Contact: sip:09126397436@192.168.2.156:5060
Content-Length: 0

<------------>
[2016-10-25 17:12:18] VERBOSE[2278] chan_sip.c:
<— SIP read from UDP:192.168.2.150:5060 —>
OPTIONS sip:192.168.2.156:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP 192.168.2.150:5060;branch=z9hG4bKic0p3s34kc0388cz3lmi1zk24
Call-ID: ljss3lk20m0ps0pl8l43110z1p31mnls@192.168.2.150
From: sip:192.168.2.150:5060;transport=udp;tag=2jss1izn
To: sip:192.168.2.156;transport=udp
CSeq: 1 OPTIONS
Contact: sip:192.168.2.150:5060;transport=udp
Accept-Encoding: identity
Accept-Language: en
Max-Forwards: 70
Session-ID: j4nkzz4l1i4s208ccp28cjniinc4injn
Content-Length: 0

<------------->
[2016-10-25 17:12:18] VERBOSE[2278] chan_sip.c: — (12 headers 0 lines) —
[2016-10-25 17:12:18] VERBOSE[2278] chan_sip.c: Sending to 192.168.2.150:5060 (no NAT)
[2016-10-25 17:12:18] VERBOSE[2278] chan_sip.c: Looking for s in from-sip-external (domain 192.168.2.156)
[2016-10-25 17:12:18] VERBOSE[2278] chan_sip.c:
<— Transmitting (no NAT) to 192.168.2.150:5060 —>
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.2.150:5060;branch=z9hG4bKic0p3s34kc0388cz3lmi1zk24;received=192.168.2.150
From: sip:192.168.2.150:5060;transport=udp;tag=2jss1izn
To: sip:192.168.2.156;transport=udp;tag=as2915d05f
Call-ID: ljss3lk20m0ps0pl8l43110z1p31mnls@192.168.2.150
CSeq: 1 OPTIONS
Server: FPBX-2.11.0(11.20.0)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Contact: sip:192.168.2.156:5060
Accept: application/sdp
Content-Length: 0

<------------>
[2016-10-25 17:12:18] VERBOSE[2278] chan_sip.c: Scheduling destruction of SIP dialog ‘ljss3lk20m0ps0pl8l43110z1p31mnls@192.168.2.150’ in 32000 ms (Method: OPTIONS)
[2016-10-25 17:12:20] VERBOSE[2278] chan_sip.c: Really destroying SIP dialog ‘0ns4kz3kkm1k42l834lj4s30ml0icnz0@192.168.2.150’ Method: OPTIONS
[2016-10-25 17:12:21] ERROR[2279] chan_dahdi.c: [1] !! Unable to handle message of type 0x31 on CIC 2
[2016-10-25 17:12:23] VERBOSE[2278] chan_sip.c:
<— SIP read from UDP:192.168.2.150:5060 —>
OPTIONS sip:192.168.2.156:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP 192.168.2.150:5060;branch=z9hG4bKcn2cijkscj0cnjm4ns3pn02j0
Call-ID: 2s1lcpp3np38ml118403sc4n022zn83k@192.168.2.150
From: sip:192.168.2.150:5060;transport=udp;tag=m04mszl2
To: sip:192.168.2.156;transport=udp
CSeq: 1 OPTIONS
Contact: sip:192.168.2.150:5060;transport=udp
Accept-Encoding: identity
Accept-Language: en
Max-Forwards: 70
Session-ID: 0pi132nplj2481sppk3skp3034022828
Content-Length: 0

<------------->
[2016-10-25 17:12:23] VERBOSE[2278] chan_sip.c: — (12 headers 0 lines) —
[2016-10-25 17:12:23] VERBOSE[2278] chan_sip.c: Sending to 192.168.2.150:5060 (no NAT)
[2016-10-25 17:12:23] VERBOSE[2278] chan_sip.c: Looking for s in from-sip-external (domain 192.168.2.156)
[2016-10-25 17:12:23] VERBOSE[2278] chan_sip.c:
<— Transmitting (no NAT) to 192.168.2.150:5060 —>
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.2.150:5060;branch=z9hG4bKcn2cijkscj0cnjm4ns3pn02j0;received=192.168.2.150
From: sip:192.168.2.150:5060;transport=udp;tag=m04mszl2
To: sip:192.168.2.156;transport=udp;tag=as2f5a4509
Call-ID: 2s1lcpp3np38ml118403sc4n022zn83k@192.168.2.150
CSeq: 1 OPTIONS
Server: FPBX-2.11.0(11.20.0)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Contact: sip:192.168.2.156:5060
Accept: application/sdp
Content-Length: 0

<------------>
[2016-10-25 17:12:23] VERBOSE[2278] chan_sip.c: Scheduling destruction of SIP dialog ‘2s1lcpp3np38ml118403sc4n022zn83k@192.168.2.150’ in 32000 ms (Method: OPTIONS)
[2016-10-25 17:12:25] VERBOSE[2278] chan_sip.c: Really destroying SIP dialog ‘ljzm2pnmk4cp81pzpzm2m2lc10l148i0@192.168.2.150’ Method: OPTIONS
[2016-10-25 17:12:25] VERBOSE[11992][C-0000006b] chan_sip.c:
<— Transmitting (no NAT) to 192.168.2.150:5060 —>
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 192.168.2.150:5060;branch=z9hG4bKsc33ckcckz40i8kn3lkmp1jmc;received=192.168.2.150
From: sip:31589000@192.168.2.156;tag=i0izpnzz
To: sip:09126397436@192.168.2.156
Call-ID: ksps400180320nzl32lj3i0s08psz310@192.168.2.150
CSeq: 2 INVITE
Server: FPBX-2.11.0(11.20.0)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Session-Expires: 1800;refresher=uas
Contact: sip:09126397436@192.168.2.156:5060
Content-Length: 0

<------------>
[2016-10-25 17:12:28] VERBOSE[2278] chan_sip.c:
<— SIP read from UDP:192.168.2.150:5060 —>
OPTIONS sip:192.168.2.156:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP 192.168.2.150:5060;branch=z9hG4bKz01nnnszkp4zm0lk3c4mpmsis
Call-ID: plj884isl3ln1sic34n3ics2mjplm4ii@192.168.2.150
From: sip:192.168.2.150:5060;transport=udp;tag=mil12nim
To: sip:192.168.2.156;transport=udp
CSeq: 1 OPTIONS
Contact: sip:192.168.2.150:5060;transport=udp
Accept-Encoding: identity
Accept-Language: en
Max-Forwards: 70
Session-ID: slcz8s4icic4lc4zkcnk4ki8n2imck4n
Content-Length: 0

<------------->
[2016-10-25 17:12:28] VERBOSE[2278] chan_sip.c: — (12 headers 0 lines) —
[2016-10-25 17:12:28] VERBOSE[2278] chan_sip.c: Sending to 192.168.2.150:5060 (no NAT)
[2016-10-25 17:12:28] VERBOSE[2278] chan_sip.c: Looking for s in from-sip-external (domain 192.168.2.156)
[2016-10-25 17:12:28] VERBOSE[2278] chan_sip.c:
<— Transmitting (no NAT) to 192.168.2.150:5060 —>
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.2.150:5060;branch=z9hG4bKz01nnnszkp4zm0lk3c4mpmsis;received=192.168.2.150
From: sip:192.168.2.150:5060;transport=udp;tag=mil12nim
To: sip:192.168.2.156;transport=udp;tag=as6d861dfe
Call-ID: plj884isl3ln1sic34n3ics2mjplm4ii@192.168.2.150
CSeq: 1 OPTIONS
Server: FPBX-2.11.0(11.20.0)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Contact: sip:192.168.2.156:5060
Accept: application/sdp
Content-Length: 0

<------------>
[2016-10-25 17:12:28] VERBOSE[2278] chan_sip.c: Scheduling destruction of SIP dialog ‘plj884isl3ln1sic34n3ics2mjplm4ii@192.168.2.150’ in 32000 ms (Method: OPTIONS)
[2016-10-25 17:12:28] VERBOSE[11992][C-0000006b] chan_sip.c: Audio is at 18604
[2016-10-25 17:12:28] VERBOSE[11992][C-0000006b] chan_sip.c: Adding codec 100003 (ulaw) to SDP
[2016-10-25 17:12:28] VERBOSE[11992][C-0000006b] chan_sip.c: Adding codec 100004 (alaw) to SDP
[2016-10-25 17:12:28] VERBOSE[11992][C-0000006b] chan_sip.c:
<— Reliably Transmitting (no NAT) to 192.168.2.150:5060 —>
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.2.150:5060;branch=z9hG4bKsc33ckcckz40i8kn3lkmp1jmc;received=192.168.2.150
From: sip:31589000@192.168.2.156;tag=i0izpnzz
To: sip:09126397436@192.168.2.156;tag=as0fb927ac
Call-ID: ksps400180320nzl32lj3i0s08psz310@192.168.2.150
CSeq: 2 INVITE
Server: FPBX-2.11.0(11.20.0)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Session-Expires: 1800;refresher=uas
Contact: sip:09126397436@192.168.2.156:5060
Content-Type: application/sdp
Require: timer
Content-Length: 204

v=0
o=root 142501458 142501458 IN IP4 192.168.2.156
s=Asterisk PBX 11.20.0
c=IN IP4 192.168.2.156
t=0 0
m=audio 18604 RTP/AVP 0 8
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=ptime:20
a=sendrecv

<------------>
[2016-10-25 17:12:28] VERBOSE[2278] chan_sip.c:
<— SIP read from UDP:192.168.2.150:5060 —>
ACK sip:09126397436@192.168.2.156:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.2.150:5060;branch=z9hG4bKc3cjmsi1nim0l1c30pz1knjk8
Call-ID: ksps400180320nzl32lj3i0s08psz310@192.168.2.150
From: sip:31589000@192.168.2.156;tag=i0izpnzz
To: sip:09126397436@192.168.2.156;tag=as0fb927ac
CSeq: 2 ACK
Contact: sip:31589000@192.168.2.150:5060;transport=udp
Max-Forwards: 70
Session-ID: i0kjim32l14k410clp3443zj0cp8pnmm
Content-Length: 0

<------------->
[2016-10-25 17:12:28] VERBOSE[2278] chan_sip.c: — (10 headers 0 lines) —
[2016-10-25 17:12:30] VERBOSE[2278] chan_sip.c: Really destroying SIP dialog ‘kccsi8iknpjzl01ms2jm33lzpnkicjjj@192.168.2.150’ Method: OPTIONS
[2016-10-25 17:12:33] VERBOSE[2278] chan_sip.c:
<— SIP read from UDP:192.168.2.150:5060 —>
OPTIONS sip:192.168.2.156:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP 192.168.2.150:5060;branch=z9hG4bKji3mm28p8i420ps42zpc0m2cn
Call-ID: pkjs81lmp304pspj40nscnz0k1l12pm0@192.168.2.150
From: sip:192.168.2.150:5060;transport=udp;tag=sp22l830
To: sip:192.168.2.156;transport=udp
CSeq: 1 OPTIONS
Contact: sip:192.168.2.150:5060;transport=udp
Accept-Encoding: identity
Accept-Language: en
Max-Forwards: 70
Session-ID: 02k313z3c4pm3l0mcpp8s03s33442i8s
Content-Length: 0

<------------->
[2016-10-25 17:12:33] VERBOSE[2278] chan_sip.c: — (12 headers 0 lines) —
[2016-10-25 17:12:33] VERBOSE[2278] chan_sip.c: Sending to 192.168.2.150:5060 (no NAT)
[2016-10-25 17:12:33] VERBOSE[2278] chan_sip.c: Looking for s in from-sip-external (domain 192.168.2.156)
[2016-10-25 17:12:33] VERBOSE[2278] chan_sip.c:
<— Transmitting (no NAT) to 192.168.2.150:5060 —>
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.2.150:5060;branch=z9hG4bKji3mm28p8i420ps42zpc0m2cn;received=192.168.2.150
From: sip:192.168.2.150:5060;transport=udp;tag=sp22l830
To: sip:192.168.2.156;transport=udp;tag=as19b6bca3
Call-ID: pkjs81lmp304pspj40nscnz0k1l12pm0@192.168.2.150
CSeq: 1 OPTIONS
Server: FPBX-2.11.0(11.20.0)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Contact: sip:192.168.2.156:5060
Accept: application/sdp
Content-Length: 0

<------------>
[2016-10-25 17:12:33] VERBOSE[2278] chan_sip.c: Scheduling destruction of SIP dialog ‘pkjs81lmp304pspj40nscnz0k1l12pm0@192.168.2.150’ in 32000 ms (Method: OPTIONS)
[2016-10-25 17:12:33] VERBOSE[11992][C-0000006b] chan_sip.c: Scheduling destruction of SIP dialog ‘ksps400180320nzl32lj3i0s08psz310@192.168.2.150’ in 6400 ms (Method: ACK)
[2016-10-25 17:12:33] VERBOSE[11992][C-0000006b] chan_sip.c: set_destination: Parsing sip:31589000@192.168.2.150:5060;transport=udp for address/port to send to
[2016-10-25 17:12:33] VERBOSE[11992][C-0000006b] chan_sip.c: set_destination: set destination to 192.168.2.150:5060
[2016-10-25 17:12:33] VERBOSE[11992][C-0000006b] chan_sip.c: Reliably Transmitting (no NAT) to 192.168.2.150:5060:
BYE sip:31589000@192.168.2.150:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP 192.168.2.156:5060;branch=z9hG4bK7c6e2b5e
Max-Forwards: 70
From: sip:09126397436@192.168.2.156;tag=as0fb927ac
To: sip:31589000@192.168.2.156;tag=i0izpnzz
Call-ID: ksps400180320nzl32lj3i0s08psz310@192.168.2.150
CSeq: 102 BYE
User-Agent: FPBX-2.11.0(11.20.0)
Proxy-Authorization: Digest username=“31589000”, realm=“asterisk”, algorithm=MD5, uri=“sip:192.168.2.156”, nonce=“3b6225f4”, response="e2bd03a6125606bf55ed7616229e0f3f"
X-Asterisk-HangupCause: Normal Clearing
X-Asterisk-HangupCauseCode: 16
Content-Length: 0


[2016-10-25 17:12:33] VERBOSE[2278] chan_sip.c:
<— SIP read from UDP:192.168.2.150:5060 —>
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.2.156:5060;branch=z9hG4bK7c6e2b5e
Call-ID: ksps400180320nzl32lj3i0s08psz310@192.168.2.150
From: sip:09126397436@192.168.2.156;tag=as0fb927ac
To: sip:31589000@192.168.2.156;tag=i0izpnzz
CSeq: 102 BYE
Session-ID: i0kjim32l14k410clp3443zj0cp8pnmm
Content-Length: 0

<------------->
[2016-10-25 17:12:33] VERBOSE[2278] chan_sip.c: — (8 headers 0 lines) —
[2016-10-25 17:12:33] VERBOSE[2278][C-0000006b] chan_sip.c: SIP Response message for INCOMING dialog BYE arrived
[2016-10-25 17:12:33] VERBOSE[2278] chan_sip.c: Really destroying SIP dialog ‘ksps400180320nzl32lj3i0s08psz310@192.168.2.150’ Method: ACK
[2016-10-25 17:12:35] VERBOSE[2278] chan_sip.c: Really destroying SIP dialog ‘8imilnni18zmpncsnp224lsnkp2znlck@192.168.2.150’ Method: OPTIONS
[2016-10-25 17:12:38] VERBOSE[2278] chan_sip.c:
<— SIP read from UDP:192.168.2.150:5060 —>
OPTIONS sip:192.168.2.156:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP 192.168.2.150:5060;branch=z9hG4bK81c20ic4pmpcmc302pl3881kc
Call-ID: 0p1ijkzcl20c80klmmcmpjk31kkkn0l4@192.168.2.150
From: sip:192.168.2.150:5060;transport=udp;tag=j02lz4jk
To: sip:192.168.2.156;transport=udp
CSeq: 1 OPTIONS
Contact: sip:192.168.2.150:5060;transport=udp
Accept-Encoding: identity
Accept-Language: en
Max-Forwards: 70
Session-ID: 2si2kn002jnj3s4zjs433s8p8sjp8p01
Content-Length: 0

ss7 debug:
[2016-10-25 17:13:45] VERBOSE[2279] chan_dahdi.c: [1] Len = 37 [ bc c2 22 85 3b dd 69 27 02 00 01 00 60 01 0a 00 02 0a 08 03 10 90 21 36 79 34 f6 0a 07 03 10 77 13 85 09 00 00 ]
[2016-10-25 17:13:45] VERBOSE[2279] chan_dahdi.c: [1] FSN: 66 FIB 1
[2016-10-25 17:13:45] VERBOSE[2279] chan_dahdi.c: [1] BSN: 60 BIB 1
[2016-10-25 17:13:45] VERBOSE[2279] chan_dahdi.c: [1] >[0] MSU
[2016-10-25 17:13:45] VERBOSE[2279] chan_dahdi.c: [1] [ bc c2 22 ]
[2016-10-25 17:13:45] VERBOSE[2279] chan_dahdi.c: [1] Network Indicator: 2 Priority: 0 User Part: ISUP (5)
[2016-10-25 17:13:45] VERBOSE[2279] chan_dahdi.c: [1] [ 85 ]
[2016-10-25 17:13:45] VERBOSE[2279] chan_dahdi.c: [1] OPC 7591 DPC 7483 SLS 2
[2016-10-25 17:13:45] VERBOSE[2279] chan_dahdi.c: [1] [ 3b dd 69 27 ]
[2016-10-25 17:13:45] VERBOSE[2279] chan_dahdi.c: [1] CIC: 2
[2016-10-25 17:13:45] VERBOSE[2279] chan_dahdi.c: [1] [ 02 00 ]
[2016-10-25 17:13:45] VERBOSE[2279] chan_dahdi.c: [1] Message Type: IAM
[2016-10-25 17:13:45] VERBOSE[2279] chan_dahdi.c: [1] [ 01 ]
[2016-10-25 17:13:45] VERBOSE[2279] chan_dahdi.c: [1] --FIXED LENGTH PARMS[4]–
[2016-10-25 17:13:45] VERBOSE[2279] chan_dahdi.c: [1] Nature of Connection Indicator:
[2016-10-25 17:13:45] VERBOSE[2279] chan_dahdi.c: [1] Satellites in connection: 0
[2016-10-25 17:13:45] VERBOSE[2279] chan_dahdi.c: [1] Continuity Check: Check not required (0)
[2016-10-25 17:13:45] VERBOSE[2279] chan_dahdi.c: [1] Outgoing half echo control device: not included (0)
[2016-10-25 17:13:45] VERBOSE[2279] chan_dahdi.c: [1] [ 00 ]
[2016-10-25 17:13:45] VERBOSE[2279] chan_dahdi.c: [1] Forward Call Indicators:
[2016-10-25 17:13:45] VERBOSE[2279] chan_dahdi.c: [1] Nat/Intl Call Ind: call to be treated as a national call (0)
[2016-10-25 17:13:45] VERBOSE[2279] chan_dahdi.c: [1] End to End Method Ind: no end-to-end method(s) available (0)
[2016-10-25 17:13:45] VERBOSE[2279] chan_dahdi.c: [1] Interworking Ind: no interworking encountered (0)
[2016-10-25 17:13:45] VERBOSE[2279] chan_dahdi.c: [1] End to End Info Ind: no end-to-end information available (0)
[2016-10-25 17:13:45] VERBOSE[2279] chan_dahdi.c: [1] ISDN User Part Ind: ISDN user part used all the way (1)
[2016-10-25 17:13:45] VERBOSE[2279] chan_dahdi.c: [1] ISDN User Part Pref Ind: ISDN user part not preferred all the way (1)
[2016-10-25 17:13:45] VERBOSE[2279] chan_dahdi.c: [1] ISDN Access Ind: originating access ISDN (1)
[2016-10-25 17:13:45] VERBOSE[2279] chan_dahdi.c: [1] SCCP Method Ind: no indication (0)
[2016-10-25 17:13:45] VERBOSE[2279] chan_dahdi.c: [1] [ 60 01 ]
[2016-10-25 17:13:45] VERBOSE[2279] chan_dahdi.c: [1] Calling Party’s Category:
[2016-10-25 17:13:45] VERBOSE[2279] chan_dahdi.c: [1] Category: Ordinary calling subscriber (10)
[2016-10-25 17:13:45] VERBOSE[2279] chan_dahdi.c: [1] [ 0a ]
[2016-10-25 17:13:45] VERBOSE[2279] chan_dahdi.c: [1] Transmission Medium Requirements:
[2016-10-25 17:13:45] VERBOSE[2279] chan_dahdi.c: [1] Speech (0)
[2016-10-25 17:13:45] VERBOSE[2279] chan_dahdi.c: [1] [ 00 ]
[2016-10-25 17:13:45] VERBOSE[2279] chan_dahdi.c: [1] --VARIABLE LENGTH PARMS[1]–
[2016-10-25 17:13:45] VERBOSE[2279] chan_dahdi.c: [1] Called Party Number:
[2016-10-25 17:13:45] VERBOSE[2279] chan_dahdi.c: [1] Nature of address: 3
[2016-10-25 17:13:45] VERBOSE[2279] chan_dahdi.c: [1] NI: 0
[2016-10-25 17:13:45] VERBOSE[2279] chan_dahdi.c: [1] Numbering plan: 1
[2016-10-25 17:13:45] VERBOSE[2279] chan_dahdi.c: [1] Address signals: 09126397436#
[2016-10-25 17:13:45] VERBOSE[2279] chan_dahdi.c: [1] [ 08 03 10 90 21 36 79 34 f6 ]
[2016-10-25 17:13:45] VERBOSE[2279] chan_dahdi.c: [1] --OPTIONAL PARMS–
[2016-10-25 17:13:45] VERBOSE[2279] chan_dahdi.c: [1] Calling Party Number:
[2016-10-25 17:13:45] VERBOSE[2279] chan_dahdi.c: [1] Nature of address: 3
[2016-10-25 17:13:45] VERBOSE[2279] chan_dahdi.c: [1] NI: 0
[2016-10-25 17:13:45] VERBOSE[2279] chan_dahdi.c: [1] Numbering plan: 1
[2016-10-25 17:13:45] VERBOSE[2279] chan_dahdi.c: [1] Presentation: 0
[2016-10-25 17:13:45] VERBOSE[2279] chan_dahdi.c: [1] Screening: 0
[2016-10-25 17:13:45] VERBOSE[2279] chan_dahdi.c: [1] Address signals: 7731589000
[2016-10-25 17:13:45] VERBOSE[2279] chan_dahdi.c: [1] [ 0a 07 03 10 77 13 85 09 00 ]
[2016-10-25 17:13:45] VERBOSE[2279] chan_dahdi.c: [1]
[2016-10-25 17:13:51] VERBOSE[2279] chan_dahdi.c: [1] Len = 16 [ c2 bd 0d 85 a7 dd 4e 27 02 00 31 01 ff 01 11 00 ]
[2016-10-25 17:13:51] VERBOSE[2279] chan_dahdi.c: [1] FSN: 61 FIB 1
[2016-10-25 17:13:51] VERBOSE[2279] chan_dahdi.c: [1] BSN: 66 BIB 1
[2016-10-25 17:13:51] VERBOSE[2279] chan_dahdi.c: [1] <[0] MSU
[2016-10-25 17:13:51] VERBOSE[2279] chan_dahdi.c: [1] [ c2 bd 0d ]
[2016-10-25 17:13:51] VERBOSE[2279] chan_dahdi.c: [1] Network Indicator: 2 Priority: 0 User Part: ISUP (5)
[2016-10-25 17:13:51] VERBOSE[2279] chan_dahdi.c: [1] [ 85 ]
[2016-10-25 17:13:51] VERBOSE[2279] chan_dahdi.c: [1] OPC 7483 DPC 7591 SLS 2
[2016-10-25 17:13:51] VERBOSE[2279] chan_dahdi.c: [1] [ a7 dd 4e 27 ]
[2016-10-25 17:13:51] VERBOSE[2279] chan_dahdi.c: [1] CIC: 2
[2016-10-25 17:13:51] VERBOSE[2279] chan_dahdi.c: [1] [ 02 00 ]
[2016-10-25 17:13:51] VERBOSE[2279] chan_dahdi.c: [1] Message Type: Unknown
[2016-10-25 17:13:51] VERBOSE[2279] chan_dahdi.c: [1] [ 31 ]
[2016-10-25 17:13:51] ERROR[2279] chan_dahdi.c: [1] !! Unable to handle message of type 0x31
[2016-10-25 17:13:51] VERBOSE[2279] chan_dahdi.c: [1]
[2016-10-25 17:13:51] ERROR[2279] chan_dahdi.c: [1] !! Unable to handle message of type 0x31 on CIC 2
[2016-10-25 17:13:55] VERBOSE[2279] chan_dahdi.c: [1] Len = 18 [ c2 be 0f 85 a7 dd 4e 27 02 00 06 12 04 01 29 01 01 00 ]
[2016-10-25 17:13:55] VERBOSE[2279] chan_dahdi.c: [1] FSN: 62 FIB 1
[2016-10-25 17:13:55] VERBOSE[2279] chan_dahdi.c: [1] BSN: 66 BIB 1
[2016-10-25 17:13:55] VERBOSE[2279] chan_dahdi.c: [1] <[0] MSU
[2016-10-25 17:13:55] VERBOSE[2279] chan_dahdi.c: [1] [ c2 be 0f ]
[2016-10-25 17:13:55] VERBOSE[2279] chan_dahdi.c: [1] Network Indicator: 2 Priority: 0 User Part: ISUP (5)
[2016-10-25 17:13:55] VERBOSE[2279] chan_dahdi.c: [1] [ 85 ]
[2016-10-25 17:13:55] VERBOSE[2279] chan_dahdi.c: [1] OPC 7483 DPC 7591 SLS 2
[2016-10-25 17:13:55] VERBOSE[2279] chan_dahdi.c: [1] [ a7 dd 4e 27 ]
[2016-10-25 17:13:55] VERBOSE[2279] chan_dahdi.c: [1] CIC: 2
[2016-10-25 17:13:55] VERBOSE[2279] chan_dahdi.c: [1] [ 02 00 ]
[2016-10-25 17:13:55] VERBOSE[2279] chan_dahdi.c: [1] Message Type: ACM
[2016-10-25 17:13:55] VERBOSE[2279] chan_dahdi.c: [1] [ 06 ]
[2016-10-25 17:13:55] VERBOSE[2279] chan_dahdi.c: [1] --FIXED LENGTH PARMS[1]–
[2016-10-25 17:13:55] VERBOSE[2279] chan_dahdi.c: [1] Backward Call Indicator:
[2016-10-25 17:13:55] VERBOSE[2279] chan_dahdi.c: [1] Charge indicator: 2
[2016-10-25 17:13:55] VERBOSE[2279] chan_dahdi.c: [1] Called party’s status indicator: 0
[2016-10-25 17:13:55] VERBOSE[2279] chan_dahdi.c: [1] Called party’s category indicator: 1
[2016-10-25 17:13:55] VERBOSE[2279] chan_dahdi.c: [1] End to End method indicator: 0
[2016-10-25 17:13:55] VERBOSE[2279] chan_dahdi.c: [1] Interworking indicator: 0
[2016-10-25 17:13:55] VERBOSE[2279] chan_dahdi.c: [1] End to End information indicator: 0
[2016-10-25 17:13:55] VERBOSE[2279] chan_dahdi.c: [1] ISDN user part indicator: 1
[2016-10-25 17:13:55] VERBOSE[2279] chan_dahdi.c: [1] Holding indicator: 0
[2016-10-25 17:13:55] VERBOSE[2279] chan_dahdi.c: [1] ISDN access indicator: 0
[2016-10-25 17:13:55] VERBOSE[2279] chan_dahdi.c: [1] Echo control device indicator: 0
[2016-10-25 17:13:55] VERBOSE[2279] chan_dahdi.c: [1] SCCP method indicator: 0
[2016-10-25 17:13:55] VERBOSE[2279] chan_dahdi.c: [1] [ 12 04 ]
[2016-10-25 17:13:55] VERBOSE[2279] chan_dahdi.c: [1] --OPTIONAL PARMS–
[2016-10-25 17:13:55] VERBOSE[2279] chan_dahdi.c: [1] Optional Backward Call Indicator:
[2016-10-25 17:13:55] VERBOSE[2279] chan_dahdi.c: [1] In-band information indicator: 1
[2016-10-25 17:13:55] VERBOSE[2279] chan_dahdi.c: [1] Call diversion may occur indicator: 0
[2016-10-25 17:13:55] VERBOSE[2279] chan_dahdi.c: [1] Simple segmentation indicator: 0
[2016-10-25 17:13:55] VERBOSE[2279] chan_dahdi.c: [1] MLPP user indicator: 0
[2016-10-25 17:13:55] VERBOSE[2279] chan_dahdi.c: [1] [ 29 01 01 ]
[2016-10-25 17:13:55] VERBOSE[2279] chan_dahdi.c: [1]
[2016-10-25 17:13:55] VERBOSE[2279] sig_ss7.c: Linkset 1: Processing event: ISUP_EVENT_ACM
[2016-10-25 17:13:59] VERBOSE[2279] chan_dahdi.c: [1] Len = 12 [ c2 bf 09 85 a7 dd 4e 27 02 00 09 00 ]
[2016-10-25 17:13:59] VERBOSE[2279] chan_dahdi.c: [1] FSN: 63 FIB 1
[2016-10-25 17:13:59] VERBOSE[2279] chan_dahdi.c: [1] BSN: 66 BIB 1
[2016-10-25 17:13:59] VERBOSE[2279] chan_dahdi.c: [1] <[0] MSU
[2016-10-25 17:13:59] VERBOSE[2279] chan_dahdi.c: [1] [ c2 bf 09 ]
[2016-10-25 17:13:59] VERBOSE[2279] chan_dahdi.c: [1] Network Indicator: 2 Priority: 0 User Part: ISUP (5)
[2016-10-25 17:13:59] VERBOSE[2279] chan_dahdi.c: [1] [ 85 ]
[2016-10-25 17:13:59] VERBOSE[2279] chan_dahdi.c: [1] OPC 7483 DPC 7591 SLS 2
[2016-10-25 17:13:59] VERBOSE[2279] chan_dahdi.c: [1] [ a7 dd 4e 27 ]
[2016-10-25 17:13:59] VERBOSE[2279] chan_dahdi.c: [1] CIC: 2
[2016-10-25 17:13:59] VERBOSE[2279] chan_dahdi.c: [1] [ 02 00 ]
[2016-10-25 17:13:59] VERBOSE[2279] chan_dahdi.c: [1] Message Type: ANM
[2016-10-25 17:13:59] VERBOSE[2279] chan_dahdi.c: [1] [ 09 ]
[2016-10-25 17:13:59] VERBOSE[2279] chan_dahdi.c: [1]
[2016-10-25 17:13:59] VERBOSE[2279] sig_ss7.c: Linkset 1: Processing event: ISUP_EVENT_ANM
[2016-10-25 17:14:02] VERBOSE[2279] chan_dahdi.c: [1] Len = 16 [ c2 c0 0d 85 a7 dd 4e 27 02 00 0c 02 00 02 80 90 ]
[2016-10-25 17:14:02] VERBOSE[2279] chan_dahdi.c: [1] FSN: 64 FIB 1
[2016-10-25 17:14:02] VERBOSE[2279] chan_dahdi.c: [1] BSN: 66 BIB 1
[2016-10-25 17:14:02] VERBOSE[2279] chan_dahdi.c: [1] <[0] MSU
[2016-10-25 17:14:02] VERBOSE[2279] chan_dahdi.c: [1] [ c2 c0 0d ]
[2016-10-25 17:14:02] VERBOSE[2279] chan_dahdi.c: [1] Network Indicator: 2 Priority: 0 User Part: ISUP (5)
[2016-10-25 17:14:02] VERBOSE[2279] chan_dahdi.c: [1] [ 85 ]
[2016-10-25 17:14:02] VERBOSE[2279] chan_dahdi.c: [1] OPC 7483 DPC 7591 SLS 2
[2016-10-25 17:14:02] VERBOSE[2279] chan_dahdi.c: [1] [ a7 dd 4e 27 ]
[2016-10-25 17:14:02] VERBOSE[2279] chan_dahdi.c: [1] CIC: 2
[2016-10-25 17:14:02] VERBOSE[2279] chan_dahdi.c: [1] [ 02 00 ]
[2016-10-25 17:14:02] VERBOSE[2279] chan_dahdi.c: [1] Message Type: REL
[2016-10-25 17:14:02] VERBOSE[2279] chan_dahdi.c: [1] [ 0c ]
[2016-10-25 17:14:02] VERBOSE[2279] chan_dahdi.c: [1] --VARIABLE LENGTH PARMS[1]–
[2016-10-25 17:14:02] VERBOSE[2279] chan_dahdi.c: [1] Cause Indicator:
[2016-10-25 17:14:02] VERBOSE[2279] chan_dahdi.c: [1] Coding Standard: 0
[2016-10-25 17:14:02] VERBOSE[2279] chan_dahdi.c: [1] Location: 0
[2016-10-25 17:14:02] VERBOSE[2279] chan_dahdi.c: [1] Cause Class: 1
[2016-10-25 17:14:02] VERBOSE[2279] chan_dahdi.c: [1] Cause Subclass: 0
[2016-10-25 17:14:02] VERBOSE[2279] chan_dahdi.c: [1] Cause: Normal call clearing (16)
[2016-10-25 17:14:02] VERBOSE[2279] chan_dahdi.c: [1] [ 02 80 90 ]
[2016-10-25 17:14:02] VERBOSE[2279] chan_dahdi.c: [1]
[2016-10-25 17:14:02] VERBOSE[2279] sig_ss7.c: Linkset 1: Processing event: ISUP_EVENT_REL
[2016-10-25 17:14:02] VERBOSE[2279] chan_dahdi.c: [1] Len = 12 [ c0 c3 09 85 3b dd 69 27 02 00 10 00 ]
[2016-10-25 17:14:02] VERBOSE[2279] chan_dahdi.c: [1] FSN: 67 FIB 1
[2016-10-25 17:14:02] VERBOSE[2279] chan_dahdi.c: [1] BSN: 64 BIB 1
[2016-10-25 17:14:02] VERBOSE[2279] chan_dahdi.c: [1] >[0] MSU
[2016-10-25 17:14:02] VERBOSE[2279] chan_dahdi.c: [1] [ c0 c3 09 ]
[2016-10-25 17:14:02] VERBOSE[2279] chan_dahdi.c: [1] Network Indicator: 2 Priority: 0 User Part: ISUP (5)
[2016-10-25 17:14:02] VERBOSE[2279] chan_dahdi.c: [1] [ 85 ]
[2016-10-25 17:14:02] VERBOSE[2279] chan_dahdi.c: [1] OPC 7591 DPC 7483 SLS 2
[2016-10-25 17:14:02] VERBOSE[2279] chan_dahdi.c: [1] [ 3b dd 69 27 ]
[2016-10-25 17:14:02] VERBOSE[2279] chan_dahdi.c: [1] CIC: 2
[2016-10-25 17:14:02] VERBOSE[2279] chan_dahdi.c: [1] [ 02 00 ]
[2016-10-25 17:14:02] VERBOSE[2279] chan_dahdi.c: [1] Message Type: RLC
[2016-10-25 17:14:02] VERBOSE[2279] chan_dahdi.c: [1] [ 10 ]
[2016-10-25 17:14:02] VERBOSE[2279] chan_dahdi.c: [1]

ndle message of type 0x31

hi dears
How to Solve this Problem ?
please help me

Is the only problem that you don’t get ringback, or do you get no media at all on the call?

Have you taken a pcap on the network side to verify that you actually have ringback audio present in the packets being sent back?

only don’t ringback tone on outgoing call. no media problem.
in ss7 the pstn side no send cpg packet to asterisk