Hello,
Thanks for your quick response to our issue. Please find attached the pcap pcaps.tgz (11.9 KB)
files you mentioned (one from the Zoiper softphone which works fine, and three more captured from 8821 with the issue) . Also below there is the debug output from asterisk with the registration witch becoming unreachable. Please take a look into them and let me know if you can notice something weird. My 8821 ip is: 192.168.4.2 Ext: 231 and Freepbx is: 192.168.0.78
P/S: Softphone and/or Cisco SPA525g2 are working fine through 192.168.4.0/24 subnet :(((
Once again thanks for your time
<--- SIP read from UDP:192.168.4.2:5060 --->
REGISTER sip:192.168.0.78 SIP/2.0
Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK61d5819b
From: <sip:[email protected]>;tag=7018a717c7460e380143e597-0e915b76
To: <sip:[email protected]>
Call-ID: [email protected]
Max-Forwards: 70
Session-ID: a1dc650e00105000a0007018a717c746;remote=00000000000000000000000000000000
Date: Tue, 31 Mar 2020 11:22:27 GMT
CSeq: 1238 REGISTER
User-Agent: Cisco-CP8821/11.0.5
Contact: <sip:[email protected]:5060;user=phone;transport=udp>;+sip.instance="<urn:uuid:00000000-0000-0000-0000-7018a717c746>";+u.sip!devicename.ccm.cisco.com="SEP7018A717C746";+u.sip!model.ccm.cisco.com="36216"
Supported: replaces,join,sdp-anat,norefersub,resource-priority,extended-refer,X-cisco-callinfo,X-cisco-serviceuri,X-cisco-escapecodes,X-cisco-service-control,X-cisco-srtp-fallback,X-cisco-monrec,X-cisco-config,X-cisco-sis-7.0.0,X-cisco-xsi-8.5.1
Content-Length: 0
Reason: SIP;cause=200;text="cisco-alarm:14 Name=SEP7018A717C746 ActiveLoad=sip8821.11-0-5SR2-2.loads InactiveLoad=sip8821.11-0-4-14.loads Last=cm-closed-tcp"
Expires: 3600
<------------->
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Header 0 [ 33]: REGISTER sip:192.168.0.78 SIP/2.0
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Header 1 [ 56]: Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK61d5819b
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Header 2 [ 66]: From: <sip:[email protected]>;tag=7018a717c7460e380143e597-0e915b76
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Header 3 [ 26]: To: <sip:[email protected]>
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Header 4 [ 56]: Call-ID: [email protected]
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Header 5 [ 16]: Max-Forwards: 70
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Header 6 [ 84]: Session-ID: a1dc650e00105000a0007018a717c746;remote=00000000000000000000000000000000
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Header 7 [ 35]: Date: Tue, 31 Mar 2020 11:22:27 GMT
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Header 8 [ 19]: CSeq: 1238 REGISTER
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Header 9 [ 31]: User-Agent: Cisco-CP8821/11.0.5
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Header 10 [209]: Contact: <sip:[email protected]:5060;user=phone;transport=udp>;+sip.instance="<urn:uuid:00000000-0000-0000-0000-7018a717c746>";+u.sip!devicename.ccm.cisco.com="SEP7018A717C746";+u.sip!model.ccm.cisco.com="36216"
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Header 11 [245]: Supported: replaces,join,sdp-anat,norefersub,resource-priority,extended-refer,X-cisco-callinfo,X-cisco-serviceuri,X-cisco-escapecodes,X-cisco-service-control,X-cisco-srtp-fallback,X-cisco-monrec,X-cisco-config,X-cisco-sis-7.0.0,X-cisco-xsi-8.5.1
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Header 12 [ 17]: Content-Length: 0
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Header 13 [157]: Reason: SIP;cause=200;text="cisco-alarm:14 Name=SEP7018A717C746 ActiveLoad=sip8821.11-0-5SR2-2.loads InactiveLoad=sip8821.11-0-4-14.loads Last=cm-closed-tcp"
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Header 14 [ 13]: Expires: 3600
[2020-03-31 14:22:25] VERBOSE[1660] chan_sip.c: --- (15 headers 0 lines) ---
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: = Looking for Call ID: [email protected] (Checking From) --From tag 7018a717c7460e380143e597-0e915b76 --To-tag
[2020-03-31 14:22:25] DEBUG[1660] acl.c: For destination '192.168.4.2', our source address is '192.168.0.78'.
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Setting AST_TRANSPORT_UDP with address 192.168.0.78:5060
[2020-03-31 14:22:25] DEBUG[1660] netsock2.c: Splitting '192.168.4.2:5060' into...
[2020-03-31 14:22:25] DEBUG[1660] netsock2.c: ...host '192.168.4.2' and port '5060'.
[2020-03-31 14:22:25] VERBOSE[1660] chan_sip.c: Sending to 192.168.4.2:5060 (NAT)
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Allocating new SIP dialog for [email protected] - REGISTER (No RTP)
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Initializing initreq for method REGISTER - callid [email protected]
[2020-03-31 14:22:25] DEBUG[1660] netsock2.c: Splitting '192.168.4.2:5060' into...
[2020-03-31 14:22:25] DEBUG[1660] netsock2.c: ...host '192.168.4.2' and port '5060'.
[2020-03-31 14:22:25] VERBOSE[1660] chan_sip.c: Sending to 192.168.4.2:5060 (NAT)
[2020-03-31 14:22:25] DEBUG[1660] netsock2.c: Splitting '192.168.0.78' into...
[2020-03-31 14:22:25] DEBUG[1660] netsock2.c: ...host '192.168.0.78' and port ''.
[2020-03-31 14:22:25] VERBOSE[1660] chan_sip.c:
<--- Transmitting (NAT) to 192.168.4.2:5060 --->
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK61d5819b;received=192.168.4.2;rport=5060
From: <sip:[email protected]>;tag=7018a717c7460e380143e597-0e915b76
To: <sip:[email protected]>;tag=as60c39ffb
Call-ID: [email protected]
CSeq: 1238 REGISTER
Server: FPBX-14.0.13.26(13.29.2)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces
WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="5ab2eece"
Content-Length: 0
<------------>
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Trying to put 'SIP/2.0 401' onto UDP socket destined for 192.168.4.2:5060
[2020-03-31 14:22:25] VERBOSE[1660] chan_sip.c: Scheduling destruction of SIP dialog '[email protected]' in 32000 ms (Method: REGISTER)
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Destroying SIP dialog [email protected]
[2020-03-31 14:22:25] VERBOSE[1660] chan_sip.c: Really destroying SIP dialog '[email protected]' Method: REFER
[2020-03-31 14:22:25] VERBOSE[1660] chan_sip.c:
<--- SIP read from UDP:192.168.4.2:5060 --->
REFER sip:192.168.0.78 SIP/2.0
Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK4c77981f
From: "231" <sip:[email protected]>;tag=7018a717c7460e37068afc71-4f49bbd5
To: <sip:192.168.0.78>
Call-ID: [email protected]
Session-ID: a1dc650e00105000a0007018a717c746;remote=00000000000000000000000000000000
Date: Tue, 31 Mar 2020 11:22:27 GMT
CSeq: 1000 REFER
User-Agent: Cisco-CP8821/11.0.5
Expires: 10
Max-Forwards: 70
Contact: <sip:[email protected]:5060;transport=udp>;+u.sip!devicename.ccm.cisco.com="SEP7018A717C746"
Require: norefersub
Referred-By: "231" <sip:[email protected]>
Refer-To: cid:[email protected]
Content-Id: <[email protected]>
Allow: ACK,BYE,CANCEL,INVITE,NOTIFY,OPTIONS,REFER,REGISTER,UPDATE,SUBSCRIBE
Content-Length: 506
Content-Type: application/x-cisco-alarm+xml
Content-Disposition: session;handling=required
<?xml version="1.0" encoding="UTF-8"?>
<x-cisco-alarm>
<Alarm Name="DeviceTLInfo">
<ParameterList>
<String name="DeviceName">SEP7018A717C746</String>
<String name="IPv4Address">192.168.1.5</String>
<String name="IPv6Address"></String>
<String name="CTL_Signature">Not Installed</String>
<String name="CTL_TFTP_Server">N/A</String>
<String name="ITL_Signature">Not Installed</String>
<String name="ITL_TFTP_Server">N/A</String>
<String name="StatusCode">6</String>
</ParameterList>
</Alarm>
</x-cisco-alarm>
<------------->
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Header 0 [ 30]: REFER sip:192.168.0.78 SIP/2.0
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Header 1 [ 56]: Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK4c77981f
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Header 2 [ 71]: From: "231" <sip:[email protected]>;tag=7018a717c7460e37068afc71-4f49bbd5
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Header 3 [ 22]: To: <sip:192.168.0.78>
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Header 4 [ 56]: Call-ID: [email protected]
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Header 5 [ 84]: Session-ID: a1dc650e00105000a0007018a717c746;remote=00000000000000000000000000000000
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Header 6 [ 35]: Date: Tue, 31 Mar 2020 11:22:27 GMT
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Header 7 [ 16]: CSeq: 1000 REFER
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Header 8 [ 31]: User-Agent: Cisco-CP8821/11.0.5
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Header 9 [ 11]: Expires: 10
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Header 10 [ 16]: Max-Forwards: 70
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Header 11 [ 99]: Contact: <sip:[email protected]:5060;transport=udp>;+u.sip!devicename.ccm.cisco.com="SEP7018A717C746"
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Header 12 [ 19]: Require: norefersub
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Header 13 [ 40]: Referred-By: "231" <sip:[email protected]>
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Header 14 [ 34]: Refer-To: cid:[email protected]
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Header 15 [ 34]: Content-Id: <[email protected]>
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Header 16 [ 75]: Allow: ACK,BYE,CANCEL,INVITE,NOTIFY,OPTIONS,REFER,REGISTER,UPDATE,SUBSCRIBE
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Header 17 [ 19]: Content-Length: 506
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Header 18 [ 43]: Content-Type: application/x-cisco-alarm+xml
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Header 19 [ 46]: Content-Disposition: session;handling=required
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Header 20 [ 0]:
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Body 0 [ 38]: <?xml version="1.0" encoding="UTF-8"?>
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Body 1 [ 15]: <x-cisco-alarm>
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Body 2 [ 27]: <Alarm Name="DeviceTLInfo">
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Body 3 [ 15]: <ParameterList>
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Body 4 [ 50]: <String name="DeviceName">SEP7018A717C746</String>
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Body 5 [ 47]: <String name="IPv4Address">192.168.1.5</String>
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Body 6 [ 36]: <String name="IPv6Address"></String>
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Body 7 [ 51]: <String name="CTL_Signature">Not Installed</String>
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Body 8 [ 43]: <String name="CTL_TFTP_Server">N/A</String>
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Body 9 [ 51]: <String name="ITL_Signature">Not Installed</String>
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Body 10 [ 43]: <String name="ITL_TFTP_Server">N/A</String>
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Body 11 [ 36]: <String name="StatusCode">6</String>
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Body 12 [ 16]: </ParameterList>
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Body 13 [ 8]: </Alarm>
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Body 14 [ 16]: </x-cisco-alarm>
[2020-03-31 14:22:25] VERBOSE[1660] chan_sip.c: --- (20 headers 15 lines) ---
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: = Looking for Call ID: [email protected] (Checking From) --From tag 7018a717c7460e37068afc71-4f49bbd5 --To-tag
[2020-03-31 14:22:25] DEBUG[1660] acl.c: For destination '192.168.4.2', our source address is '192.168.0.78'.
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Setting AST_TRANSPORT_UDP with address 192.168.0.78:5060
[2020-03-31 14:22:25] DEBUG[1660] netsock2.c: Splitting '192.168.4.2:5060' into...
[2020-03-31 14:22:25] DEBUG[1660] netsock2.c: ...host '192.168.4.2' and port '5060'.
[2020-03-31 14:22:25] VERBOSE[1660] chan_sip.c: Sending to 192.168.4.2:5060 (NAT)
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Allocating new SIP dialog for [email protected] - REFER (No RTP)
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: **** Received REFER (9) - Command in SIP REFER
[2020-03-31 14:22:25] VERBOSE[1660] chan_sip.c: Call [email protected] got a SIP call transfer from caller: (REFER)!
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Call [email protected]: Declined REFER, outside of dialog...
[2020-03-31 14:22:25] VERBOSE[1660] chan_sip.c:
<--- Transmitting (NAT) to 192.168.4.2:5060 --->
SIP/2.0 603 Declined (No dialog)
Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK4c77981f;received=192.168.4.2;rport=5060
From: "231" <sip:[email protected]>;tag=7018a717c7460e37068afc71-4f49bbd5
To: <sip:192.168.0.78>;tag=as6c71e095
Call-ID: [email protected]
CSeq: 1000 REFER
Server: FPBX-14.0.13.26(13.29.2)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces
Content-Length: 0
<------------>
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Trying to put 'SIP/2.0 603' onto UDP socket destined for 192.168.4.2:5060
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Setting SIP_ALREADYGONE on dialog [email protected]
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Destroying SIP dialog [email protected]
[2020-03-31 14:22:25] VERBOSE[1660] chan_sip.c: Really destroying SIP dialog '[email protected]' Method: REFER
[2020-03-31 14:22:25] VERBOSE[1660] chan_sip.c:
<--- SIP read from UDP:192.168.4.2:5060 --->
REGISTER sip:192.168.0.78 SIP/2.0
Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK24de7a54
From: <sip:[email protected]>;tag=7018a717c7460e380143e597-0e915b76
To: <sip:[email protected]>
Call-ID: [email protected]
Max-Forwards: 70
Session-ID: a1dc650e00105000a0007018a717c746;remote=00000000000000000000000000000000
Date: Tue, 31 Mar 2020 11:22:27 GMT
CSeq: 1239 REGISTER
User-Agent: Cisco-CP8821/11.0.5
Contact: <sip:[email protected]:5060;user=phone;transport=udp>;+sip.instance="<urn:uuid:00000000-0000-0000-0000-7018a717c746>";+u.sip!devicename.ccm.cisco.com="SEP7018A717C746";+u.sip!model.ccm.cisco.com="36216"
Authorization: Digest username="231",realm="asterisk",uri="sip:192.168.0.78",response="f765b896027a0911580bfbeaef77331a",nonce="5ab2eece",algorithm=MD5
Supported: replaces,join,sdp-anat,norefersub,resource-priority,extended-refer,X-cisco-callinfo,X-cisco-serviceuri,X-cisco-escapecodes,X-cisco-service-control,X-cisco-srtp-fallback,X-cisco-monrec,X-cisco-config,X-cisco-sis-7.0.0,X-cisco-xsi-8.5.1
Content-Length: 0
Reason: SIP;cause=200;text="cisco-alarm:14 Name=SEP7018A717C746 ActiveLoad=sip8821.11-0-5SR2-2.loads InactiveLoad=sip8821.11-0-4-14.loads Last=cm-closed-tcp"
Expires: 3600
<------------->
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Header 0 [ 33]: REGISTER sip:192.168.0.78 SIP/2.0
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Header 1 [ 56]: Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK24de7a54
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Header 2 [ 66]: From: <sip:[email protected]>;tag=7018a717c7460e380143e597-0e915b76
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Header 3 [ 26]: To: <sip:[email protected]>
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Header 4 [ 56]: Call-ID: [email protected]
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Header 5 [ 16]: Max-Forwards: 70
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Header 6 [ 84]: Session-ID: a1dc650e00105000a0007018a717c746;remote=00000000000000000000000000000000
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Header 7 [ 35]: Date: Tue, 31 Mar 2020 11:22:27 GMT
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Header 8 [ 19]: CSeq: 1239 REGISTER
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Header 9 [ 31]: User-Agent: Cisco-CP8821/11.0.5
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Header 10 [209]: Contact: <sip:[email protected]:5060;user=phone;transport=udp>;+sip.instance="<urn:uuid:00000000-0000-0000-0000-7018a717c746>";+u.sip!devicename.ccm.cisco.com="SEP7018A717C746";+u.sip!model.ccm.cisco.com="36216"
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Header 11 [151]: Authorization: Digest username="231",realm="asterisk",uri="sip:192.168.0.78",response="f765b896027a0911580bfbeaef77331a",nonce="5ab2eece",algorithm=MD5
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Header 12 [245]: Supported: replaces,join,sdp-anat,norefersub,resource-priority,extended-refer,X-cisco-callinfo,X-cisco-serviceuri,X-cisco-escapecodes,X-cisco-service-control,X-cisco-srtp-fallback,X-cisco-monrec,X-cisco-config,X-cisco-sis-7.0.0,X-cisco-xsi-8.5.1
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Header 13 [ 17]: Content-Length: 0
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Header 14 [157]: Reason: SIP;cause=200;text="cisco-alarm:14 Name=SEP7018A717C746 ActiveLoad=sip8821.11-0-5SR2-2.loads InactiveLoad=sip8821.11-0-4-14.loads Last=cm-closed-tcp"
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Header 15 [ 13]: Expires: 3600
[2020-03-31 14:22:25] VERBOSE[1660] chan_sip.c: --- (16 headers 0 lines) ---
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: = Looking for Call ID: [email protected] (Checking From) --From tag 7018a717c7460e380143e597-0e915b76 --To-tag
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Initializing initreq for method REGISTER - callid [email protected]
[2020-03-31 14:22:25] DEBUG[1660] netsock2.c: Splitting '192.168.4.2:5060' into...
[2020-03-31 14:22:25] DEBUG[1660] netsock2.c: ...host '192.168.4.2' and port '5060'.
[2020-03-31 14:22:25] VERBOSE[1660] chan_sip.c: Sending to 192.168.4.2:5060 (NAT)
[2020-03-31 14:22:25] DEBUG[1660] netsock2.c: Splitting '192.168.0.78' into...
[2020-03-31 14:22:25] DEBUG[1660] netsock2.c: ...host '192.168.0.78' and port ''.
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Store REGISTER's src-IP:port for call routing.
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: build_path: do not use Path headers
[2020-03-31 14:22:25] VERBOSE[1660] chan_sip.c: Registered SIP '231' at 192.168.4.2:5060
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Allocating new SIP dialog for [email protected]:5060 - OPTIONS (No RTP)
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: OBPROXY: Not applying OBproxy to this call
[2020-03-31 14:22:25] DEBUG[1660] acl.c: For destination '192.168.4.2', our source address is '192.168.0.78'.
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Setting AST_TRANSPORT_UDP with address 192.168.0.78:5060
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: SIP call-id changed from '[email protected]:5060' to '[email protected]:5060'
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Initializing initreq for method OPTIONS - callid [email protected]:5060
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Header 0 [ 65]: OPTIONS sip:[email protected]:5060;user=phone;transport=udp SIP/2.0
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Header 1 [ 63]: Via: SIP/2.0/UDP 192.168.0.78:5060;branch=z9hG4bK3aed04a5;rport
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Header 3 [ 57]: From: "Unknown" <sip:[email protected]>;tag=as6f8b8f89
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Header 4 [ 55]: To: <sip:[email protected]:5060;user=phone;transport=udp>
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Header 5 [ 40]: Contact: <sip:[email protected]:5060>
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Header 6 [ 59]: Call-ID: [email protected]:5060
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Header 7 [ 17]: CSeq: 102 OPTIONS
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Header 8 [ 36]: User-Agent: FPBX-14.0.13.26(13.29.2)
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Header 9 [ 35]: Date: Tue, 31 Mar 2020 11:22:25 GMT
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Header 10 [ 90]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Header 11 [ 26]: Supported: replaces, timer
[2020-03-31 14:22:25] VERBOSE[1660] chan_sip.c: Reliably Transmitting (NAT) to 192.168.4.2:5060:
OPTIONS sip:[email protected]:5060;user=phone;transport=udp SIP/2.0
Via: SIP/2.0/UDP 192.168.0.78:5060;branch=z9hG4bK3aed04a5;rport
Max-Forwards: 70
From: "Unknown" <sip:[email protected]>;tag=as6f8b8f89
To: <sip:[email protected]:5060;user=phone;transport=udp>
Contact: <sip:[email protected]:5060>
Call-ID: [email protected]:5060
CSeq: 102 OPTIONS
User-Agent: FPBX-14.0.13.26(13.29.2)
Date: Tue, 31 Mar 2020 11:22:25 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Content-Length: 0
---
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #84
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Trying to put 'OPTIONS sip' onto UDP socket destined for 192.168.4.2:5060
[2020-03-31 14:22:25] VERBOSE[1660] chan_sip.c:
<--- Transmitting (NAT) to 192.168.4.2:5060 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.4.2:5060;branch=z9hG4bK24de7a54;received=192.168.4.2;rport=5060
From: <sip:[email protected]>;tag=7018a717c7460e380143e597-0e915b76
To: <sip:[email protected]>;tag=as60c39ffb
Call-ID: [email protected]
CSeq: 1239 REGISTER
Server: FPBX-14.0.13.26(13.29.2)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces
Expires: 300
Contact: <sip:[email protected]:5060;user=phone;transport=udp>;expires=300
Date: Tue, 31 Mar 2020 11:22:25 GMT
Content-Length: 0
<------------>
[2020-03-31 14:22:25] DEBUG[1660] chan_sip.c: Trying to put 'SIP/2.0 200' onto UDP socket destined for 192.168.4.2:5060
[2020-03-31 14:22:25] DEBUG[1576] devicestate.c: No provider found, checking channel drivers for SIP - 231
[2020-03-31 14:22:25] DEBUG[1576] chan_sip.c: Checking device state for peer 231
[2020-03-31 14:22:25] DEBUG[1576] devicestate.c: Changing state for SIP/231 - state 1 (Not in use)
[2020-03-31 14:22:25] DEBUG[1685] app_queue.c: Device 'SIP/231' changed to state '1' (Not in use) but we don't care because they're not a member of any queue.
[2020-03-31 14:22:25] VERBOSE[1660] chan_sip.c: Scheduling destruction of SIP dialog '[email protected]' in 32000 ms (Method: REGISTER)
[2020-03-31 14:22:26] DEBUG[1660] chan_sip.c: SIP TIMER: Not rescheduling id #84:OPTIONS (Method 3) (No timer T1)
[2020-03-31 14:22:26] VERBOSE[1660] chan_sip.c: Retransmitting #1 (NAT) to 192.168.4.2:5060:
OPTIONS sip:[email protected]:5060;user=phone;transport=udp SIP/2.0
Via: SIP/2.0/UDP 192.168.0.78:5060;branch=z9hG4bK3aed04a5;rport
Max-Forwards: 70
From: "Unknown" <sip:[email protected]>;tag=as6f8b8f89
To: <sip:[email protected]:5060;user=phone;transport=udp>
Contact: <sip:[email protected]:5060>
Call-ID: [email protected]:5060
CSeq: 102 OPTIONS
User-Agent: FPBX-14.0.13.26(13.29.2)
Date: Tue, 31 Mar 2020 11:22:25 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Content-Length: 0
---
[2020-03-31 14:22:26] DEBUG[1660] chan_sip.c: Trying to put 'OPTIONS sip' onto UDP socket destined for 192.168.4.2:5060
[2020-03-31 14:22:27] DEBUG[1660] chan_sip.c: SIP TIMER: Not rescheduling id #84:OPTIONS (Method 3) (No timer T1)
[2020-03-31 14:22:27] VERBOSE[1660] chan_sip.c: Retransmitting #2 (NAT) to 192.168.4.2:5060:
OPTIONS sip:[email protected]:5060;user=phone;transport=udp SIP/2.0
Via: SIP/2.0/UDP 192.168.0.78:5060;branch=z9hG4bK3aed04a5;rport
Max-Forwards: 70
From: "Unknown" <sip:[email protected]>;tag=as6f8b8f89
To: <sip:[email protected]:5060;user=phone;transport=udp>
Contact: <sip:[email protected]:5060>
Call-ID: [email protected]:5060
CSeq: 102 OPTIONS
User-Agent: FPBX-14.0.13.26(13.29.2)
Date: Tue, 31 Mar 2020 11:22:25 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Content-Length: 0
---
[2020-03-31 14:22:27] DEBUG[1660] chan_sip.c: Trying to put 'OPTIONS sip' onto UDP socket destined for 192.168.4.2:5060
[2020-03-31 14:22:28] DEBUG[1660] chan_sip.c: SIP TIMER: Not rescheduling id #84:OPTIONS (Method 3) (No timer T1)
[2020-03-31 14:22:28] VERBOSE[1660] chan_sip.c: Retransmitting #3 (NAT) to 192.168.4.2:5060:
OPTIONS sip:[email protected]:5060;user=phone;transport=udp SIP/2.0
Via: SIP/2.0/UDP 192.168.0.78:5060;branch=z9hG4bK3aed04a5;rport
Max-Forwards: 70
From: "Unknown" <sip:[email protected]>;tag=as6f8b8f89
To: <sip:[email protected]:5060;user=phone;transport=udp>
Contact: <sip:[email protected]:5060>
Call-ID: [email protected]:5060
CSeq: 102 OPTIONS
User-Agent: FPBX-14.0.13.26(13.29.2)
Date: Tue, 31 Mar 2020 11:22:25 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Content-Length: 0
---
[2020-03-31 14:22:28] DEBUG[1660] chan_sip.c: Trying to put 'OPTIONS sip' onto UDP socket destined for 192.168.4.2:5060
[2020-03-31 14:22:29] VERBOSE[1660] chan_sip.c: Retransmitting #4 (NAT) to 192.168.4.2:5060:
OPTIONS sip:[email protected]:5060;user=phone;transport=udp SIP/2.0
Via: SIP/2.0/UDP 192.168.0.78:5060;branch=z9hG4bK3aed04a5;rport
Max-Forwards: 70
From: "Unknown" <sip:[email protected]>;tag=as6f8b8f89
To: <sip:[email protected]:5060;user=phone;transport=udp>
Contact: <sip:[email protected]:5060>
Call-ID: [email protected]:5060
CSeq: 102 OPTIONS
User-Agent: FPBX-14.0.13.26(13.29.2)
Date: Tue, 31 Mar 2020 11:22:25 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Content-Length: 0
---
[2020-03-31 14:22:29] DEBUG[1660] chan_sip.c: Trying to put 'OPTIONS sip' onto UDP socket destined for 192.168.4.2:5060
[2020-03-31 14:22:29] NOTICE[1660] chan_sip.c: Peer '231' is now UNREACHABLE! Last qualify: 0
[2020-03-31 14:22:29] DEBUG[1576] devicestate.c: No provider found, checking channel drivers for SIP - 231
[2020-03-31 14:22:29] DEBUG[1576] chan_sip.c: Checking device state for peer 231
[2020-03-31 14:22:29] DEBUG[1576] devicestate.c: Changing state for SIP/231 - state 5 (Unavailable)
[2020-03-31 14:22:29] DEBUG[1685] app_queue.c: Device 'SIP/231' changed to state '5' (Unavailable) but we don't care because they're not a member of any queue.
[2020-03-31 14:22:29] DEBUG[1660] chan_sip.c: Destroying SIP dialog [email protected]:5060
[2020-03-31 14:22:29] VERBOSE[1660] chan_sip.c: Really destroying SIP dialog '[email protected]:5060' Method: OPTIONS