SIP cliend behind NAT can't connect

Asterisk Asterisk Support
1

I have a following scheme:

          [asterisk server]          
                  |               
              [internet]
                  |
              [firewall]
               |     |
             [103] [104]

Client #104 can connect to asterisk, shown properly in ‘sip show status’

104/104 87.245.129.xx D N 14357 OK (1 ms)
Client #103 does this:

-- Unregistered SIP '103' -- Registered SIP '103' at 192.168.1.54:5060 -- Registered SIP '103' at 87.245.129.xx:5060 -- Unregistered SIP '103'
It can call #104, but can not be called as it gives this:

sip.conf for #103 and #104

[103] username=103 secret=secret type=friend host=dynamic directmedia=no context=psssh callgroup=1 pickupgroup=1 nat=yes qualify=yes [104] username=104 secret=secret type=friend host=dynamic directmedia=no context=psssh callgroup=1 pickupgroup=1 nat=yes qualify=yes

#104 is a MicroSIP softphone, #103 is a Konftel 300IP phone. Both live in same small network, no VLANs, nothing, they have same conditions, but one works when other does not :frowning:

Any ideas how to make it work? I’ve tried to look into sip debug, found out that it registers, but then something happens and i see

Tried googling that, found out possible codec problems, fixed them, but to no avail.

2

There are two SIP debug logs, both showing a client connect and register.

This one for 104, which works fine:

[code][Mar 15 15:59:12] VERBOSE[15255] chan_sip.c:
<— SIP read from UDP:$FIREWALL_IP:5060 —>
REGISTER sip:$ASTERISK_IP SIP/2.0
Via: SIP/2.0/UDP 192.168.1.200:5060;rport;branch=z9hG4bKPja2e51b8071484c84905a5a715cc02fd3
Max-Forwards: 70
From: “104” sip:104@$ASTERISK_IP;tag=df488cd5a2244656a86218d790a2f0cb
To: “104” sip:104@$ASTERISK_IP
Call-ID: dfdf1d529a3e419fa3a9a24356e893f3
CSeq: 49313 REGISTER
User-Agent: MicroSIP/3.3.27
Contact: “104” sip:104@192.168.1.200:5060;ob
Expires: 300
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Content-Length: 0

<------------->
[Mar 15 15:59:12] DEBUG[15255] chan_sip.c: Header 0 [ 34]: REGISTER sip:$ASTERISK_IP SIP/2.0
[Mar 15 15:59:12] DEBUG[15255] chan_sip.c: Header 1 [ 90]: Via: SIP/2.0/UDP 192.168.1.200:5060;rport;branch=z9hG4bKPja2e51b8071484c84905a5a715cc02fd3
[Mar 15 15:59:12] DEBUG[15255] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70
[Mar 15 15:59:12] DEBUG[15255] chan_sip.c: Header 3 [ 72]: From: “104” sip:104@$ASTERISK_IP;tag=df488cd5a2244656a86218d790a2f0cb
[Mar 15 15:59:12] DEBUG[15255] chan_sip.c: Header 4 [ 33]: To: “104” sip:104@$ASTERISK_IP
[Mar 15 15:59:12] DEBUG[15255] chan_sip.c: Header 5 [ 41]: Call-ID: dfdf1d529a3e419fa3a9a24356e893f3
[Mar 15 15:59:12] DEBUG[15255] chan_sip.c: Header 6 [ 20]: CSeq: 49313 REGISTER
[Mar 15 15:59:12] DEBUG[15255] chan_sip.c: Header 7 [ 27]: User-Agent: MicroSIP/3.3.27
[Mar 15 15:59:12] DEBUG[15255] chan_sip.c: Header 8 [ 46]: Contact: “104” sip:104@192.168.1.200:5060;ob
[Mar 15 15:59:12] DEBUG[15255] chan_sip.c: Header 9 [ 12]: Expires: 300
[Mar 15 15:59:12] DEBUG[15255] chan_sip.c: Header 10 [ 96]: Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
[Mar 15 15:59:12] DEBUG[15255] chan_sip.c: Header 11 [ 17]: Content-Length: 0
[Mar 15 15:59:12] VERBOSE[15255] chan_sip.c: — (12 headers 0 lines) —
[Mar 15 15:59:12] DEBUG[15255] chan_sip.c: = Looking for Call ID: dfdf1d529a3e419fa3a9a24356e893f3 (Checking From) --From tag df488cd5a2244656a86218d790a2f0cb --To-tag
[Mar 15 15:59:12] DEBUG[15255] acl.c: For destination ‘$FIREWALL_IP’, our source address is ‘$ASTERISK_IP’.
[Mar 15 15:59:12] DEBUG[15255] chan_sip.c: Setting SIP_TRANSPORT_UDP with address $ASTERISK_IP:5060
[Mar 15 15:59:12] DEBUG[15255] netsock2.c: Splitting ‘192.168.1.200:5060’ into…
[Mar 15 15:59:12] DEBUG[15255] netsock2.c: …host ‘192.168.1.200’ and port ‘5060’.
[Mar 15 15:59:12] DEBUG[15255] chan_sip.c: NAT detected for 192.168.1.200 / $FIREWALL_IP
[Mar 15 15:59:12] VERBOSE[15255] chan_sip.c: Sending to $FIREWALL_IP:5060 (NAT)
[Mar 15 15:59:12] DEBUG[15255] chan_sip.c: Allocating new SIP dialog for dfdf1d529a3e419fa3a9a24356e893f3 - REGISTER (No RTP)
[Mar 15 15:59:12] DEBUG[15255] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER
[Mar 15 15:59:12] DEBUG[15255] chan_sip.c: Initializing initreq for method REGISTER - callid dfdf1d529a3e419fa3a9a24356e893f3
[Mar 15 15:59:12] DEBUG[15255] netsock2.c: Splitting ‘192.168.1.200:5060’ into…
[Mar 15 15:59:12] DEBUG[15255] netsock2.c: …host ‘192.168.1.200’ and port ‘5060’.
[Mar 15 15:59:12] DEBUG[15255] chan_sip.c: NAT detected for 192.168.1.200 / $FIREWALL_IP
[Mar 15 15:59:12] VERBOSE[15255] chan_sip.c: Sending to $FIREWALL_IP:5060 (NAT)
[Mar 15 15:59:12] DEBUG[15255] netsock2.c: Splitting ‘$ASTERISK_IP’ into…
[Mar 15 15:59:12] DEBUG[15255] netsock2.c: …host ‘$ASTERISK_IP’ and port ‘’.
[Mar 15 15:59:12] VERBOSE[15255] chan_sip.c:
<— Transmitting (NAT) to $FIREWALL_IP:5060 —>
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 192.168.1.200:5060;branch=z9hG4bKPja2e51b8071484c84905a5a715cc02fd3;received=$FIREWALL_IP;rport=5060
From: “104” sip:104@$ASTERISK_IP;tag=df488cd5a2244656a86218d790a2f0cb
To: “104” sip:104@$ASTERISK_IP;tag=as3ac788dd
Call-ID: dfdf1d529a3e419fa3a9a24356e893f3
CSeq: 49313 REGISTER
Server: Asterisk PBX 11.8.1
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
WWW-Authenticate: Digest algorithm=MD5, realm=“asterisk”, nonce="418f3f01"
Content-Length: 0

<------------>
[Mar 15 15:59:12] DEBUG[15255] chan_sip.c: Header 0 [ 24]: SIP/2.0 401 Unauthorized
[Mar 15 15:59:12] DEBUG[15255] chan_sip.c: Header 1 [118]: Via: SIP/2.0/UDP 192.168.1.200:5060;branch=z9hG4bKPja2e51b8071484c84905a5a715cc02fd3;received=$FIREWALL_IP;rport=5060
[Mar 15 15:59:12] DEBUG[15255] chan_sip.c: Header 2 [ 72]: From: “104” sip:104@$ASTERISK_IP;tag=df488cd5a2244656a86218d790a2f0cb
[Mar 15 15:59:12] DEBUG[15255] chan_sip.c: Header 3 [ 48]: To: “104” sip:104@$ASTERISK_IP;tag=as3ac788dd
[Mar 15 15:59:12] DEBUG[15255] chan_sip.c: Header 4 [ 41]: Call-ID: dfdf1d529a3e419fa3a9a24356e893f3
[Mar 15 15:59:12] DEBUG[15255] chan_sip.c: Header 5 [ 20]: CSeq: 49313 REGISTER
[Mar 15 15:59:12] DEBUG[15255] chan_sip.c: Header 6 [ 27]: Server: Asterisk PBX 11.8.1
[Mar 15 15:59:12] DEBUG[15255] chan_sip.c: Header 7 [ 81]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
[Mar 15 15:59:12] DEBUG[15255] chan_sip.c: Header 8 [ 26]: Supported: replaces, timer
[Mar 15 15:59:12] DEBUG[15255] chan_sip.c: Header 9 [ 74]: WWW-Authenticate: Digest algorithm=MD5, realm=“asterisk”, nonce=“418f3f01”
[Mar 15 15:59:12] DEBUG[15255] chan_sip.c: Header 10 [ 17]: Content-Length: 0
[Mar 15 15:59:12] DEBUG[15255] chan_sip.c: Header 11 [ 0]:
[Mar 15 15:59:12] DEBUG[15255] chan_sip.c: Trying to put ‘SIP/2.0 401’ onto UDP socket destined for $FIREWALL_IP:5060
[Mar 15 15:59:12] VERBOSE[15255] chan_sip.c: Scheduling destruction of SIP dialog ‘dfdf1d529a3e419fa3a9a24356e893f3’ in 32000 ms (Method: REGISTER)
[Mar 15 15:59:12] VERBOSE[15255] chan_sip.c:
<— SIP read from UDP:$FIREWALL_IP:5060 —>
REGISTER sip:$ASTERISK_IP SIP/2.0
Via: SIP/2.0/UDP 192.168.1.200:5060;rport;branch=z9hG4bKPjdf7dbccb03554ed19bce79c7cd38791e
Max-Forwards: 70
From: “104” sip:104@$ASTERISK_IP;tag=df488cd5a2244656a86218d790a2f0cb
To: “104” sip:104@$ASTERISK_IP
Call-ID: dfdf1d529a3e419fa3a9a24356e893f3
CSeq: 49314 REGISTER
User-Agent: MicroSIP/3.3.27
Contact: “104” sip:104@192.168.1.200:5060;ob
Expires: 300
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Authorization: Digest username=“104”, realm=“asterisk”, nonce=“418f3f01”, uri=“sip:$ASTERISK_IP”, response=“ddc07d5cee4283803d4951fce4c76c0b”, algorithm=MD5
Content-Length: 0

<------------->
[Mar 15 15:59:12] DEBUG[15255] chan_sip.c: Header 0 [ 34]: REGISTER sip:$ASTERISK_IP SIP/2.0
[Mar 15 15:59:12] DEBUG[15255] chan_sip.c: Header 1 [ 90]: Via: SIP/2.0/UDP 192.168.1.200:5060;rport;branch=z9hG4bKPjdf7dbccb03554ed19bce79c7cd38791e
[Mar 15 15:59:12] DEBUG[15255] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70
[Mar 15 15:59:12] DEBUG[15255] chan_sip.c: Header 3 [ 72]: From: “104” sip:104@$ASTERISK_IP;tag=df488cd5a2244656a86218d790a2f0cb
[Mar 15 15:59:12] DEBUG[15255] chan_sip.c: Header 4 [ 33]: To: “104” sip:104@$ASTERISK_IP
[Mar 15 15:59:12] DEBUG[15255] chan_sip.c: Header 5 [ 41]: Call-ID: dfdf1d529a3e419fa3a9a24356e893f3
[Mar 15 15:59:12] DEBUG[15255] chan_sip.c: Header 6 [ 20]: CSeq: 49314 REGISTER
[Mar 15 15:59:12] DEBUG[15255] chan_sip.c: Header 7 [ 27]: User-Agent: MicroSIP/3.3.27
[Mar 15 15:59:12] DEBUG[15255] chan_sip.c: Header 8 [ 46]: Contact: “104” sip:104@192.168.1.200:5060;ob
[Mar 15 15:59:12] DEBUG[15255] chan_sip.c: Header 9 [ 12]: Expires: 300
[Mar 15 15:59:12] DEBUG[15255] chan_sip.c: Header 10 [ 96]: Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, INFO, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
[Mar 15 15:59:12] DEBUG[15255] chan_sip.c: Header 11 [157]: Authorization: Digest username=“104”, realm=“asterisk”, nonce=“418f3f01”, uri=“sip:$ASTERISK_IP”, response=“ddc07d5cee4283803d4951fce4c76c0b”, algorithm=MD5
[Mar 15 15:59:12] DEBUG[15255] chan_sip.c: Header 12 [ 17]: Content-Length: 0
[Mar 15 15:59:12] VERBOSE[15255] chan_sip.c: — (13 headers 0 lines) —
[Mar 15 15:59:12] DEBUG[15255] chan_sip.c: = Looking for Call ID: dfdf1d529a3e419fa3a9a24356e893f3 (Checking From) --From tag df488cd5a2244656a86218d790a2f0cb --To-tag
[Mar 15 15:59:12] DEBUG[15255] netsock2.c: Splitting ‘$ASTERISK_IP’ into…
[Mar 15 15:59:12] DEBUG[15255] netsock2.c: …host ‘$ASTERISK_IP’ and port ‘’.
[Mar 15 15:59:12] DEBUG[15255] netsock2.c: Splitting ‘$ASTERISK_IP’ into…
[Mar 15 15:59:12] DEBUG[15255] netsock2.c: …host ‘$ASTERISK_IP’ and port ‘’.
[Mar 15 15:59:12] DEBUG[15255] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER
[Mar 15 15:59:12] DEBUG[15255] chan_sip.c: Initializing initreq for method REGISTER - callid dfdf1d529a3e419fa3a9a24356e893f3
[Mar 15 15:59:12] DEBUG[15255] netsock2.c: Splitting ‘192.168.1.200:5060’ into…
[Mar 15 15:59:12] DEBUG[15255] netsock2.c: …host ‘192.168.1.200’ and port ‘5060’.
[Mar 15 15:59:12] DEBUG[15255] chan_sip.c: NAT detected for 192.168.1.200 / $FIREWALL_IP
[Mar 15 15:59:12] VERBOSE[15255] chan_sip.c: Sending to $FIREWALL_IP:5060 (NAT)
[Mar 15 15:59:12] DEBUG[15255] netsock2.c: Splitting ‘$ASTERISK_IP’ into…
[Mar 15 15:59:12] DEBUG[15255] netsock2.c: …host ‘$ASTERISK_IP’ and port ‘’.
[Mar 15 15:59:12] DEBUG[15255] chan_sip.c: Store REGISTER’s src-IP:port for call routing.
[Mar 15 15:59:12] VERBOSE[15255] chan_sip.c:
<— Transmitting (NAT) to $FIREWALL_IP:5060 —>
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.1.200:5060;branch=z9hG4bKPjdf7dbccb03554ed19bce79c7cd38791e;received=$FIREWALL_IP;rport=5060
From: “104” sip:104@$ASTERISK_IP;tag=df488cd5a2244656a86218d790a2f0cb
To: “104” sip:104@$ASTERISK_IP;tag=as3ac788dd
Call-ID: dfdf1d529a3e419fa3a9a24356e893f3
CSeq: 49314 REGISTER
Server: Asterisk PBX 11.8.1
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Expires: 300
Contact: sip:104@192.168.1.200:5060;ob;expires=300
Date: Sat, 15 Mar 2014 11:59:12 GMT
Content-Length: 0

<------------>
[Mar 15 15:59:12] DEBUG[15255] chan_sip.c: Header 0 [ 14]: SIP/2.0 200 OK
[Mar 15 15:59:12] DEBUG[15255] chan_sip.c: Header 1 [118]: Via: SIP/2.0/UDP 192.168.1.200:5060;branch=z9hG4bKPjdf7dbccb03554ed19bce79c7cd38791e;received=$FIREWALL_IP;rport=5060
[Mar 15 15:59:12] DEBUG[15255] chan_sip.c: Header 2 [ 72]: From: “104” sip:104@$ASTERISK_IP;tag=df488cd5a2244656a86218d790a2f0cb
[Mar 15 15:59:12] DEBUG[15255] chan_sip.c: Header 3 [ 48]: To: “104” sip:104@$ASTERISK_IP;tag=as3ac788dd
[Mar 15 15:59:12] DEBUG[15255] chan_sip.c: Header 4 [ 41]: Call-ID: dfdf1d529a3e419fa3a9a24356e893f3
[Mar 15 15:59:12] DEBUG[15255] chan_sip.c: Header 5 [ 20]: CSeq: 49314 REGISTER
[Mar 15 15:59:12] DEBUG[15255] chan_sip.c: Header 6 [ 27]: Server: Asterisk PBX 11.8.1
[Mar 15 15:59:12] DEBUG[15255] chan_sip.c: Header 7 [ 81]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
[Mar 15 15:59:12] DEBUG[15255] chan_sip.c: Header 8 [ 26]: Supported: replaces, timer
[Mar 15 15:59:12] DEBUG[15255] chan_sip.c: Header 9 [ 12]: Expires: 300
[Mar 15 15:59:12] DEBUG[15255] chan_sip.c: Header 10 [ 52]: Contact: sip:104@192.168.1.200:5060;ob;expires=300
[Mar 15 15:59:12] DEBUG[15255] chan_sip.c: Header 11 [ 35]: Date: Sat, 15 Mar 2014 11:59:12 GMT
[Mar 15 15:59:12] DEBUG[15255] chan_sip.c: Header 12 [ 17]: Content-Length: 0
[Mar 15 15:59:12] DEBUG[15255] chan_sip.c: Header 13 [ 0]:
[Mar 15 15:59:12] DEBUG[15255] chan_sip.c: Trying to put ‘SIP/2.0 200’ onto UDP socket destined for $FIREWALL_IP:5060
[Mar 15 15:59:12] VERBOSE[15255] chan_sip.c: Scheduling destruction of SIP dialog ‘dfdf1d529a3e419fa3a9a24356e893f3’ in 32000 ms (Method: REGISTER)
[Mar 15 15:59:12] DEBUG[15246] devicestate.c: No provider found, checking channel drivers for SIP - 104
[Mar 15 15:59:12] DEBUG[15246] chan_sip.c: Checking device state for peer 104
[Mar 15 15:59:12] DEBUG[15246] devicestate.c: Changing state for SIP/104 - state 1 (Not in use)
[Mar 15 15:59:12] DEBUG[15246] devicestate.c: device ‘SIP/104’ state ‘1’
[Mar 15 15:59:12] DEBUG[15280] app_queue.c: Device ‘SIP/104’ changed to state ‘1’ (Not in use) but we don’t care because they’re not a member of any queue.
[Mar 15 15:59:24] VERBOSE[15255] chan_sip.c:
<— SIP read from UDP:$FIREWALL_IP:5060 —>
REGISTER sip:$ASTERISK_IP SIP/2.0
Via: SIP/2.0/UDP 192.168.1.200:5060;rport;branch=z9hG4bKPj0251e91c0b3049e6985c2ee32945ffb2
Max-Forwards: 70
From: “104” sip:104@$ASTERISK_IP;tag=a55dff0a91dc4b7ca75782be1165d376
To: “104” sip:104@$ASTERISK_IP
Call-ID: dfdf1d529a3e419fa3a9a24356e893f3
CSeq: 49315 REGISTER
User-Agent: MicroSIP/3.3.27
Contact: “104” sip:104@192.168.1.200:5060;ob
Expires: 0
Content-Length: 0

<------------->
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Header 0 [ 34]: REGISTER sip:$ASTERISK_IP SIP/2.0
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Header 1 [ 90]: Via: SIP/2.0/UDP 192.168.1.200:5060;rport;branch=z9hG4bKPj0251e91c0b3049e6985c2ee32945ffb2
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Header 3 [ 72]: From: “104” sip:104@$ASTERISK_IP;tag=a55dff0a91dc4b7ca75782be1165d376
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Header 4 [ 33]: To: “104” sip:104@$ASTERISK_IP
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Header 5 [ 41]: Call-ID: dfdf1d529a3e419fa3a9a24356e893f3
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Header 6 [ 20]: CSeq: 49315 REGISTER
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Header 7 [ 27]: User-Agent: MicroSIP/3.3.27
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Header 8 [ 46]: Contact: “104” sip:104@192.168.1.200:5060;ob
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Header 9 [ 10]: Expires: 0
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Header 10 [ 17]: Content-Length: 0
[Mar 15 15:59:24] VERBOSE[15255] chan_sip.c: — (11 headers 0 lines) —
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: = Looking for Call ID: dfdf1d529a3e419fa3a9a24356e893f3 (Checking From) --From tag a55dff0a91dc4b7ca75782be1165d376 --To-tag
[Mar 15 15:59:24] DEBUG[15255] acl.c: For destination ‘$FIREWALL_IP’, our source address is ‘$ASTERISK_IP’.
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Setting SIP_TRANSPORT_UDP with address $ASTERISK_IP:5060
[Mar 15 15:59:24] DEBUG[15255] netsock2.c: Splitting ‘192.168.1.200:5060’ into…
[Mar 15 15:59:24] DEBUG[15255] netsock2.c: …host ‘192.168.1.200’ and port ‘5060’.
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: NAT detected for 192.168.1.200 / $FIREWALL_IP
[Mar 15 15:59:24] VERBOSE[15255] chan_sip.c: Sending to $FIREWALL_IP:5060 (NAT)
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Allocating new SIP dialog for dfdf1d529a3e419fa3a9a24356e893f3 - REGISTER (No RTP)
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Initializing initreq for method REGISTER - callid dfdf1d529a3e419fa3a9a24356e893f3
[Mar 15 15:59:24] DEBUG[15255] netsock2.c: Splitting ‘192.168.1.200:5060’ into…
[Mar 15 15:59:24] DEBUG[15255] netsock2.c: …host ‘192.168.1.200’ and port ‘5060’.
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: NAT detected for 192.168.1.200 / $FIREWALL_IP
[Mar 15 15:59:24] VERBOSE[15255] chan_sip.c: Sending to $FIREWALL_IP:5060 (NAT)
[Mar 15 15:59:24] DEBUG[15255] netsock2.c: Splitting ‘$ASTERISK_IP’ into…
[Mar 15 15:59:24] DEBUG[15255] netsock2.c: …host ‘$ASTERISK_IP’ and port ‘’.
[Mar 15 15:59:24] VERBOSE[15255] chan_sip.c:
<— Transmitting (NAT) to $FIREWALL_IP:5060 —>
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 192.168.1.200:5060;branch=z9hG4bKPj0251e91c0b3049e6985c2ee32945ffb2;received=$FIREWALL_IP;rport=5060
From: “104” sip:104@$ASTERISK_IP;tag=a55dff0a91dc4b7ca75782be1165d376
To: “104” sip:104@$ASTERISK_IP;tag=as4f9c93f6
Call-ID: dfdf1d529a3e419fa3a9a24356e893f3
CSeq: 49315 REGISTER
Server: Asterisk PBX 11.8.1
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
WWW-Authenticate: Digest algorithm=MD5, realm=“asterisk”, nonce="4f524dc6"
Content-Length: 0

<------------>
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Header 0 [ 24]: SIP/2.0 401 Unauthorized
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Header 1 [118]: Via: SIP/2.0/UDP 192.168.1.200:5060;branch=z9hG4bKPj0251e91c0b3049e6985c2ee32945ffb2;received=$FIREWALL_IP;rport=5060
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Header 2 [ 72]: From: “104” sip:104@$ASTERISK_IP;tag=a55dff0a91dc4b7ca75782be1165d376
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Header 3 [ 48]: To: “104” sip:104@$ASTERISK_IP;tag=as4f9c93f6
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Header 4 [ 41]: Call-ID: dfdf1d529a3e419fa3a9a24356e893f3
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Header 5 [ 20]: CSeq: 49315 REGISTER
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Header 6 [ 27]: Server: Asterisk PBX 11.8.1
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Header 7 [ 81]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Header 8 [ 26]: Supported: replaces, timer
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Header 9 [ 74]: WWW-Authenticate: Digest algorithm=MD5, realm=“asterisk”, nonce=“4f524dc6”
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Header 10 [ 17]: Content-Length: 0
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Header 11 [ 0]:
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Trying to put ‘SIP/2.0 401’ onto UDP socket destined for $FIREWALL_IP:5060
[Mar 15 15:59:24] VERBOSE[15255] chan_sip.c: Scheduling destruction of SIP dialog ‘dfdf1d529a3e419fa3a9a24356e893f3’ in 32000 ms (Method: REGISTER)
[Mar 15 15:59:24] VERBOSE[15255] chan_sip.c:
<— SIP read from UDP:$FIREWALL_IP:5060 —>
REGISTER sip:$ASTERISK_IP SIP/2.0
Via: SIP/2.0/UDP 192.168.1.200:5060;rport;branch=z9hG4bKPj2b2826a455ec4132b9c39845cbaadafd
Max-Forwards: 70
From: “104” sip:104@$ASTERISK_IP;tag=a55dff0a91dc4b7ca75782be1165d376
To: “104” sip:104@$ASTERISK_IP
Call-ID: dfdf1d529a3e419fa3a9a24356e893f3
CSeq: 49316 REGISTER
User-Agent: MicroSIP/3.3.27
Contact: “104” sip:104@192.168.1.200:5060;ob
Expires: 0
Authorization: Digest username=“104”, realm=“asterisk”, nonce=“4f524dc6”, uri=“sip:$ASTERISK_IP”, response=“a11bc644390bd4f4a3d62f5de5439f1a”, algorithm=MD5
Content-Length: 0

<------------->
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Header 0 [ 34]: REGISTER sip:$ASTERISK_IP SIP/2.0
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Header 1 [ 90]: Via: SIP/2.0/UDP 192.168.1.200:5060;rport;branch=z9hG4bKPj2b2826a455ec4132b9c39845cbaadafd
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Header 3 [ 72]: From: “104” sip:104@$ASTERISK_IP;tag=a55dff0a91dc4b7ca75782be1165d376
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Header 4 [ 33]: To: “104” sip:104@$ASTERISK_IP
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Header 5 [ 41]: Call-ID: dfdf1d529a3e419fa3a9a24356e893f3
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Header 6 [ 20]: CSeq: 49316 REGISTER
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Header 7 [ 27]: User-Agent: MicroSIP/3.3.27
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Header 8 [ 46]: Contact: “104” sip:104@192.168.1.200:5060;ob
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Header 9 [ 10]: Expires: 0
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Header 10 [157]: Authorization: Digest username=“104”, realm=“asterisk”, nonce=“4f524dc6”, uri=“sip:$ASTERISK_IP”, response=“a11bc644390bd4f4a3d62f5de5439f1a”, algorithm=MD5
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Header 11 [ 17]: Content-Length: 0
[Mar 15 15:59:24] VERBOSE[15255] chan_sip.c: — (12 headers 0 lines) —
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: = Looking for Call ID: dfdf1d529a3e419fa3a9a24356e893f3 (Checking From) --From tag a55dff0a91dc4b7ca75782be1165d376 --To-tag
[Mar 15 15:59:24] DEBUG[15255] netsock2.c: Splitting ‘$ASTERISK_IP’ into…
[Mar 15 15:59:24] DEBUG[15255] netsock2.c: …host ‘$ASTERISK_IP’ and port ‘’.
[Mar 15 15:59:24] DEBUG[15255] netsock2.c: Splitting ‘$ASTERISK_IP’ into…
[Mar 15 15:59:24] DEBUG[15255] netsock2.c: …host ‘$ASTERISK_IP’ and port ‘’.
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Initializing initreq for method REGISTER - callid dfdf1d529a3e419fa3a9a24356e893f3
[Mar 15 15:59:24] DEBUG[15255] netsock2.c: Splitting ‘192.168.1.200:5060’ into…
[Mar 15 15:59:24] DEBUG[15255] netsock2.c: …host ‘192.168.1.200’ and port ‘5060’.
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: NAT detected for 192.168.1.200 / $FIREWALL_IP
[Mar 15 15:59:24] VERBOSE[15255] chan_sip.c: Sending to $FIREWALL_IP:5060 (NAT)
[Mar 15 15:59:24] DEBUG[15255] netsock2.c: Splitting ‘$ASTERISK_IP’ into…
[Mar 15 15:59:24] DEBUG[15255] netsock2.c: …host ‘$ASTERISK_IP’ and port ‘’.
[Mar 15 15:59:24] NOTICE[15255] chan_sip.c: Correct auth, but based on stale nonce received from ‘“104” sip:104@$ASTERISK_IP;tag=a55dff0a91dc4b7ca75782be1165d376’
[Mar 15 15:59:24] VERBOSE[15255] chan_sip.c:
<— Transmitting (NAT) to $FIREWALL_IP:5060 —>
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 192.168.1.200:5060;branch=z9hG4bKPj2b2826a455ec4132b9c39845cbaadafd;received=$FIREWALL_IP;rport=5060
From: “104” sip:104@$ASTERISK_IP;tag=a55dff0a91dc4b7ca75782be1165d376
To: “104” sip:104@$ASTERISK_IP;tag=as3ac788dd
Call-ID: dfdf1d529a3e419fa3a9a24356e893f3
CSeq: 49316 REGISTER
Server: Asterisk PBX 11.8.1
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
WWW-Authenticate: Digest algorithm=MD5, realm=“asterisk”, nonce=“7ee251f3”, stale=true
Content-Length: 0

<------------>
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Header 0 [ 24]: SIP/2.0 401 Unauthorized
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Header 1 [118]: Via: SIP/2.0/UDP 192.168.1.200:5060;branch=z9hG4bKPj2b2826a455ec4132b9c39845cbaadafd;received=$FIREWALL_IP;rport=5060
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Header 2 [ 72]: From: “104” sip:104@$ASTERISK_IP;tag=a55dff0a91dc4b7ca75782be1165d376
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Header 3 [ 48]: To: “104” sip:104@$ASTERISK_IP;tag=as3ac788dd
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Header 4 [ 41]: Call-ID: dfdf1d529a3e419fa3a9a24356e893f3
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Header 5 [ 20]: CSeq: 49316 REGISTER
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Header 6 [ 27]: Server: Asterisk PBX 11.8.1
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Header 7 [ 81]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Header 8 [ 26]: Supported: replaces, timer
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Header 9 [ 86]: WWW-Authenticate: Digest algorithm=MD5, realm=“asterisk”, nonce=“7ee251f3”, stale=true
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Header 10 [ 17]: Content-Length: 0
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Header 11 [ 0]:
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Trying to put ‘SIP/2.0 401’ onto UDP socket destined for $FIREWALL_IP:5060
[Mar 15 15:59:24] VERBOSE[15255] chan_sip.c: Scheduling destruction of SIP dialog ‘dfdf1d529a3e419fa3a9a24356e893f3’ in 32000 ms (Method: REGISTER)
[Mar 15 15:59:24] VERBOSE[15255] chan_sip.c:
<— SIP read from UDP:$FIREWALL_IP:5060 —>
REGISTER sip:$ASTERISK_IP SIP/2.0
Via: SIP/2.0/UDP 192.168.1.200:5060;rport;branch=z9hG4bKPje371697f711649aa9b437ac3dd739b59
Max-Forwards: 70
From: “104” sip:104@$ASTERISK_IP;tag=a55dff0a91dc4b7ca75782be1165d376
To: “104” sip:104@$ASTERISK_IP
Call-ID: dfdf1d529a3e419fa3a9a24356e893f3
CSeq: 49317 REGISTER
User-Agent: MicroSIP/3.3.27
Contact: “104” sip:104@192.168.1.200:5060;ob
Expires: 0
Authorization: Digest username=“104”, realm=“asterisk”, nonce=“7ee251f3”, uri=“sip:$ASTERISK_IP”, response=“2b6b4f1f324072ee02defad77abdd3f4”, algorithm=MD5
Content-Length: 0

<------------->
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Header 0 [ 34]: REGISTER sip:$ASTERISK_IP SIP/2.0
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Header 1 [ 90]: Via: SIP/2.0/UDP 192.168.1.200:5060;rport;branch=z9hG4bKPje371697f711649aa9b437ac3dd739b59
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Header 3 [ 72]: From: “104” sip:104@$ASTERISK_IP;tag=a55dff0a91dc4b7ca75782be1165d376
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Header 4 [ 33]: To: “104” sip:104@$ASTERISK_IP
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Header 5 [ 41]: Call-ID: dfdf1d529a3e419fa3a9a24356e893f3
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Header 6 [ 20]: CSeq: 49317 REGISTER
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Header 7 [ 27]: User-Agent: MicroSIP/3.3.27
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Header 8 [ 46]: Contact: “104” sip:104@192.168.1.200:5060;ob
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Header 9 [ 10]: Expires: 0
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Header 10 [157]: Authorization: Digest username=“104”, realm=“asterisk”, nonce=“7ee251f3”, uri=“sip:$ASTERISK_IP”, response=“2b6b4f1f324072ee02defad77abdd3f4”, algorithm=MD5
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Header 11 [ 17]: Content-Length: 0
[Mar 15 15:59:24] VERBOSE[15255] chan_sip.c: — (12 headers 0 lines) —
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: = Looking for Call ID: dfdf1d529a3e419fa3a9a24356e893f3 (Checking From) --From tag a55dff0a91dc4b7ca75782be1165d376 --To-tag
[Mar 15 15:59:24] DEBUG[15255] netsock2.c: Splitting ‘$ASTERISK_IP’ into…
[Mar 15 15:59:24] DEBUG[15255] netsock2.c: …host ‘$ASTERISK_IP’ and port ‘’.
[Mar 15 15:59:24] DEBUG[15255] netsock2.c: Splitting ‘$ASTERISK_IP’ into…
[Mar 15 15:59:24] DEBUG[15255] netsock2.c: …host ‘$ASTERISK_IP’ and port ‘’.
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Initializing initreq for method REGISTER - callid dfdf1d529a3e419fa3a9a24356e893f3
[Mar 15 15:59:24] DEBUG[15255] netsock2.c: Splitting ‘192.168.1.200:5060’ into…
[Mar 15 15:59:24] DEBUG[15255] netsock2.c: …host ‘192.168.1.200’ and port ‘5060’.
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: NAT detected for 192.168.1.200 / $FIREWALL_IP
[Mar 15 15:59:24] VERBOSE[15255] chan_sip.c: Sending to $FIREWALL_IP:5060 (NAT)
[Mar 15 15:59:24] DEBUG[15255] netsock2.c: Splitting ‘$ASTERISK_IP’ into…
[Mar 15 15:59:24] DEBUG[15255] netsock2.c: …host ‘$ASTERISK_IP’ and port ‘’.
[Mar 15 15:59:24] VERBOSE[15255] chan_sip.c:
<— Transmitting (NAT) to $FIREWALL_IP:5060 —>
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.1.200:5060;branch=z9hG4bKPje371697f711649aa9b437ac3dd739b59;received=$FIREWALL_IP;rport=5060
From: “104” sip:104@$ASTERISK_IP;tag=a55dff0a91dc4b7ca75782be1165d376
To: “104” sip:104@$ASTERISK_IP;tag=as3ac788dd
Call-ID: dfdf1d529a3e419fa3a9a24356e893f3
CSeq: 49317 REGISTER
Server: Asterisk PBX 11.8.1
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Expires: 0
Date: Sat, 15 Mar 2014 11:59:24 GMT
Content-Length: 0

<------------>
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Header 0 [ 14]: SIP/2.0 200 OK
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Header 1 [118]: Via: SIP/2.0/UDP 192.168.1.200:5060;branch=z9hG4bKPje371697f711649aa9b437ac3dd739b59;received=$FIREWALL_IP;rport=5060
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Header 2 [ 72]: From: “104” sip:104@$ASTERISK_IP;tag=a55dff0a91dc4b7ca75782be1165d376
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Header 3 [ 48]: To: “104” sip:104@$ASTERISK_IP;tag=as3ac788dd
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Header 4 [ 41]: Call-ID: dfdf1d529a3e419fa3a9a24356e893f3
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Header 5 [ 20]: CSeq: 49317 REGISTER
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Header 6 [ 27]: Server: Asterisk PBX 11.8.1
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Header 7 [ 81]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Header 8 [ 26]: Supported: replaces, timer
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Header 9 [ 10]: Expires: 0
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Header 10 [ 35]: Date: Sat, 15 Mar 2014 11:59:24 GMT
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Header 11 [ 17]: Content-Length: 0
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Header 12 [ 0]:
[Mar 15 15:59:24] DEBUG[15255] chan_sip.c: Trying to put ‘SIP/2.0 200’ onto UDP socket destined for $FIREWALL_IP:5060
[Mar 15 15:59:24] DEBUG[15246] devicestate.c: No provider found, checking channel drivers for SIP - 104
[Mar 15 15:59:24] DEBUG[15246] chan_sip.c: Checking device state for peer 104
[Mar 15 15:59:24] VERBOSE[15255] chan_sip.c: Scheduling destruction of SIP dialog ‘dfdf1d529a3e419fa3a9a24356e893f3’ in 32000 ms (Method: REGISTER)
[Mar 15 15:59:24] DEBUG[15246] devicestate.c: Changing state for SIP/104 - state 5 (Unavailable)
[Mar 15 15:59:24] DEBUG[15246] devicestate.c: device ‘SIP/104’ state ‘5’
[Mar 15 15:59:24] DEBUG[15246] devicestate.c: No provider found, checking channel drivers for SIP - 104
[Mar 15 15:59:24] DEBUG[15246] chan_sip.c: Checking device state for peer 104
[Mar 15 15:59:24] DEBUG[15246] devicestate.c: Changing state for SIP/104 - state 5 (Unavailable)
[Mar 15 15:59:24] DEBUG[15246] devicestate.c: device ‘SIP/104’ state ‘5’
[Mar 15 15:59:24] DEBUG[15280] app_queue.c: Device ‘SIP/104’ changed to state ‘5’ (Unavailable) but we don’t care because they’re not a member of any queue.
[Mar 15 15:59:24] DEBUG[15280] app_queue.c: Device ‘SIP/104’ changed to state ‘5’ (Unavailable) but we don’t care because they’re not a member of any queue.
[Mar 15 15:59:29] DEBUG[15214] taskprocessor.c: destroying taskprocessor ‘ast_msg_queue’
[Mar 15 15:59:29] VERBOSE[15214] asterisk.c: Asterisk cleanly ending (0).
[Mar 15 15:59:29] VERBOSE[15214] asterisk.c: Executing last minute cleanups
[/code]

3

And this one for 103, which does not:

[code][Mar 15 15:54:21] VERBOSE[15103] chan_sip.c:
<— SIP read from UDP:$FIREWALL_IP:5066 —>
REGISTER sip:$ASTERISK_IP;transport=udp SIP/2.0
Via: SIP/2.0/UDP 192.168.1.54:5066;rport;branch=z9hG4bKPjMzUeOUrmchT42uKCxCHdnc75RwKf0PbU;alias
Max-Forwards: 70
From: sip:103@$ASTERISK_IP;tag=kZDteQEvs.iKt4rfbyvocIDYJc8SyadP
To: sip:103@$ASTERISK_IP
Call-ID: PCUiiBx8ga9Ni6aDgdDW6AgJ4.CwfoY6
CSeq: 59791 REGISTER
User-Agent: Konftel300IP 2.2.9
Contact: sip:103@192.168.1.54:5066;ob
Expires: 1800
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Content-Length: 0

<------------->
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 0 [ 48]: REGISTER sip:$ASTERISK_IP;transport=udp SIP/2.0
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 1 [ 95]: Via: SIP/2.0/UDP 192.168.1.54:5066;rport;branch=z9hG4bKPjMzUeOUrmchT42uKCxCHdnc75RwKf0PbU;alias
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 3 [ 66]: From: sip:103@$ASTERISK_IP;tag=kZDteQEvs.iKt4rfbyvocIDYJc8SyadP
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 4 [ 27]: To: sip:103@$ASTERISK_IP
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 5 [ 41]: Call-ID: PCUiiBx8ga9Ni6aDgdDW6AgJ4.CwfoY6
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 6 [ 20]: CSeq: 59791 REGISTER
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 7 [ 30]: User-Agent: Konftel300IP 2.2.9
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 8 [ 39]: Contact: sip:103@192.168.1.54:5066;ob
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 9 [ 13]: Expires: 1800
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 10 [ 90]: Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 11 [ 17]: Content-Length: 0
[Mar 15 15:54:21] VERBOSE[15103] chan_sip.c: — (12 headers 0 lines) —
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: = Looking for Call ID: PCUiiBx8ga9Ni6aDgdDW6AgJ4.CwfoY6 (Checking From) --From tag kZDteQEvs.iKt4rfbyvocIDYJc8SyadP --To-tag
[Mar 15 15:54:21] DEBUG[15103] acl.c: For destination ‘$FIREWALL_IP’, our source address is ‘$ASTERISK_IP’.
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Setting SIP_TRANSPORT_UDP with address $ASTERISK_IP:5060
[Mar 15 15:54:21] DEBUG[15103] netsock2.c: Splitting ‘192.168.1.54:5066’ into…
[Mar 15 15:54:21] DEBUG[15103] netsock2.c: …host ‘192.168.1.54’ and port ‘5066’.
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: NAT detected for 192.168.1.54 / $FIREWALL_IP
[Mar 15 15:54:21] VERBOSE[15103] chan_sip.c: Sending to $FIREWALL_IP:5066 (NAT)
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Allocating new SIP dialog for PCUiiBx8ga9Ni6aDgdDW6AgJ4.CwfoY6 - REGISTER (No RTP)
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Initializing initreq for method REGISTER - callid PCUiiBx8ga9Ni6aDgdDW6AgJ4.CwfoY6
[Mar 15 15:54:21] DEBUG[15103] netsock2.c: Splitting ‘192.168.1.54:5066’ into…
[Mar 15 15:54:21] DEBUG[15103] netsock2.c: …host ‘192.168.1.54’ and port ‘5066’.
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: NAT detected for 192.168.1.54 / $FIREWALL_IP
[Mar 15 15:54:21] VERBOSE[15103] chan_sip.c: Sending to $FIREWALL_IP:5066 (NAT)
[Mar 15 15:54:21] DEBUG[15103] netsock2.c: Splitting ‘$ASTERISK_IP’ into…
[Mar 15 15:54:21] DEBUG[15103] netsock2.c: …host ‘$ASTERISK_IP’ and port ‘’.
[Mar 15 15:54:21] VERBOSE[15103] chan_sip.c:
<— Transmitting (NAT) to $FIREWALL_IP:5066 —>
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 192.168.1.54:5066;branch=z9hG4bKPjMzUeOUrmchT42uKCxCHdnc75RwKf0PbU;alias;received=$FIREWALL_IP;rport=5066
From: sip:103@$ASTERISK_IP;tag=kZDteQEvs.iKt4rfbyvocIDYJc8SyadP
To: sip:103@$ASTERISK_IP;tag=as41535b92
Call-ID: PCUiiBx8ga9Ni6aDgdDW6AgJ4.CwfoY6
CSeq: 59791 REGISTER
Server: Asterisk PBX 11.8.1
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
WWW-Authenticate: Digest algorithm=MD5, realm=“asterisk”, nonce="2ff9e103"
Content-Length: 0

<------------>
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 0 [ 24]: SIP/2.0 401 Unauthorized
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 1 [123]: Via: SIP/2.0/UDP 192.168.1.54:5066;branch=z9hG4bKPjMzUeOUrmchT42uKCxCHdnc75RwKf0PbU;alias;received=$FIREWALL_IP;rport=5066
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 2 [ 66]: From: sip:103@$ASTERISK_IP;tag=kZDteQEvs.iKt4rfbyvocIDYJc8SyadP
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 3 [ 42]: To: sip:103@$ASTERISK_IP;tag=as41535b92
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 4 [ 41]: Call-ID: PCUiiBx8ga9Ni6aDgdDW6AgJ4.CwfoY6
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 5 [ 20]: CSeq: 59791 REGISTER
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 6 [ 27]: Server: Asterisk PBX 11.8.1
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 7 [ 81]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 8 [ 26]: Supported: replaces, timer
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 9 [ 74]: WWW-Authenticate: Digest algorithm=MD5, realm=“asterisk”, nonce=“2ff9e103”
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 10 [ 17]: Content-Length: 0
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 11 [ 0]:
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Trying to put ‘SIP/2.0 401’ onto UDP socket destined for $FIREWALL_IP:5066
[Mar 15 15:54:21] VERBOSE[15103] chan_sip.c: Scheduling destruction of SIP dialog ‘PCUiiBx8ga9Ni6aDgdDW6AgJ4.CwfoY6’ in 32000 ms (Method: REGISTER)
[Mar 15 15:54:21] VERBOSE[15103] chan_sip.c:
<— SIP read from UDP:$FIREWALL_IP:5066 —>
REGISTER sip:$ASTERISK_IP;transport=udp SIP/2.0
Via: SIP/2.0/UDP 192.168.1.54:5066;rport;branch=z9hG4bKPjt5EFKOK4RvdrAhys4eHvbPLLmM6Uua5K;alias
Max-Forwards: 70
From: sip:103@$ASTERISK_IP;tag=kZDteQEvs.iKt4rfbyvocIDYJc8SyadP
To: sip:103@$ASTERISK_IP
Call-ID: PCUiiBx8ga9Ni6aDgdDW6AgJ4.CwfoY6
CSeq: 59792 REGISTER
User-Agent: Konftel300IP 2.2.9
Contact: sip:103@192.168.1.54:5066;ob
Expires: 1800
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Authorization: Digest username=“103”, realm=“asterisk”, nonce=“2ff9e103”, uri=“sip:$ASTERISK_IP;transport=udp”, response=“83657684f17b92592ff00d24d3e68777”, algorithm=MD5
Content-Length: 0

<------------->
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 0 [ 48]: REGISTER sip:$ASTERISK_IP;transport=udp SIP/2.0
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 1 [ 95]: Via: SIP/2.0/UDP 192.168.1.54:5066;rport;branch=z9hG4bKPjt5EFKOK4RvdrAhys4eHvbPLLmM6Uua5K;alias
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 3 [ 66]: From: sip:103@$ASTERISK_IP;tag=kZDteQEvs.iKt4rfbyvocIDYJc8SyadP
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 4 [ 27]: To: sip:103@$ASTERISK_IP
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 5 [ 41]: Call-ID: PCUiiBx8ga9Ni6aDgdDW6AgJ4.CwfoY6
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 6 [ 20]: CSeq: 59792 REGISTER
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 7 [ 30]: User-Agent: Konftel300IP 2.2.9
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 8 [ 39]: Contact: sip:103@192.168.1.54:5066;ob
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 9 [ 13]: Expires: 1800
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 10 [ 90]: Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 11 [171]: Authorization: Digest username=“103”, realm=“asterisk”, nonce=“2ff9e103”, uri=“sip:$ASTERISK_IP;transport=udp”, response=“83657684f17b92592ff00d24d3e68777”, algorithm=MD5
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 12 [ 17]: Content-Length: 0
[Mar 15 15:54:21] VERBOSE[15103] chan_sip.c: — (13 headers 0 lines) —
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: = Looking for Call ID: PCUiiBx8ga9Ni6aDgdDW6AgJ4.CwfoY6 (Checking From) --From tag kZDteQEvs.iKt4rfbyvocIDYJc8SyadP --To-tag
[Mar 15 15:54:21] DEBUG[15103] netsock2.c: Splitting ‘$ASTERISK_IP’ into…
[Mar 15 15:54:21] DEBUG[15103] netsock2.c: …host ‘$ASTERISK_IP’ and port ‘’.
[Mar 15 15:54:21] DEBUG[15103] netsock2.c: Splitting ‘$ASTERISK_IP’ into…
[Mar 15 15:54:21] DEBUG[15103] netsock2.c: …host ‘$ASTERISK_IP’ and port ‘’.
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Initializing initreq for method REGISTER - callid PCUiiBx8ga9Ni6aDgdDW6AgJ4.CwfoY6
[Mar 15 15:54:21] DEBUG[15103] netsock2.c: Splitting ‘192.168.1.54:5066’ into…
[Mar 15 15:54:21] DEBUG[15103] netsock2.c: …host ‘192.168.1.54’ and port ‘5066’.
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: NAT detected for 192.168.1.54 / $FIREWALL_IP
[Mar 15 15:54:21] VERBOSE[15103] chan_sip.c: Sending to $FIREWALL_IP:5066 (NAT)
[Mar 15 15:54:21] DEBUG[15103] netsock2.c: Splitting ‘$ASTERISK_IP’ into…
[Mar 15 15:54:21] DEBUG[15103] netsock2.c: …host ‘$ASTERISK_IP’ and port ‘’.
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Store REGISTER’s src-IP:port for call routing.
[Mar 15 15:54:21] VERBOSE[15103] chan_sip.c:
<— Transmitting (NAT) to $FIREWALL_IP:5066 —>
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.1.54:5066;branch=z9hG4bKPjt5EFKOK4RvdrAhys4eHvbPLLmM6Uua5K;alias;received=$FIREWALL_IP;rport=5066
From: sip:103@$ASTERISK_IP;tag=kZDteQEvs.iKt4rfbyvocIDYJc8SyadP
To: sip:103@$ASTERISK_IP;tag=as41535b92
Call-ID: PCUiiBx8ga9Ni6aDgdDW6AgJ4.CwfoY6
CSeq: 59792 REGISTER
Server: Asterisk PBX 11.8.1
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Expires: 1800
Contact: sip:103@192.168.1.54:5066;ob;expires=1800
Date: Sat, 15 Mar 2014 11:54:21 GMT
Content-Length: 0

<------------>
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 0 [ 14]: SIP/2.0 200 OK
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 1 [123]: Via: SIP/2.0/UDP 192.168.1.54:5066;branch=z9hG4bKPjt5EFKOK4RvdrAhys4eHvbPLLmM6Uua5K;alias;received=$FIREWALL_IP;rport=5066
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 2 [ 66]: From: sip:103@$ASTERISK_IP;tag=kZDteQEvs.iKt4rfbyvocIDYJc8SyadP
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 3 [ 42]: To: sip:103@$ASTERISK_IP;tag=as41535b92
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 4 [ 41]: Call-ID: PCUiiBx8ga9Ni6aDgdDW6AgJ4.CwfoY6
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 5 [ 20]: CSeq: 59792 REGISTER
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 6 [ 27]: Server: Asterisk PBX 11.8.1
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 7 [ 81]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 8 [ 26]: Supported: replaces, timer
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 9 [ 13]: Expires: 1800
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 10 [ 52]: Contact: sip:103@192.168.1.54:5066;ob;expires=1800
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 11 [ 35]: Date: Sat, 15 Mar 2014 11:54:21 GMT
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 12 [ 17]: Content-Length: 0
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 13 [ 0]:
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Trying to put ‘SIP/2.0 200’ onto UDP socket destined for $FIREWALL_IP:5066
[Mar 15 15:54:21] VERBOSE[15103] chan_sip.c: Scheduling destruction of SIP dialog ‘PCUiiBx8ga9Ni6aDgdDW6AgJ4.CwfoY6’ in 32000 ms (Method: REGISTER)
[Mar 15 15:54:21] DEBUG[15094] devicestate.c: No provider found, checking channel drivers for SIP - 103
[Mar 15 15:54:21] DEBUG[15094] chan_sip.c: Checking device state for peer 103
[Mar 15 15:54:21] DEBUG[15094] devicestate.c: Changing state for SIP/103 - state 1 (Not in use)
[Mar 15 15:54:21] DEBUG[15094] devicestate.c: device ‘SIP/103’ state ‘1’
[Mar 15 15:54:21] DEBUG[15128] app_queue.c: Device ‘SIP/103’ changed to state ‘1’ (Not in use) but we don’t care because they’re not a member of any queue.
[Mar 15 15:54:21] VERBOSE[15103] chan_sip.c:
<— SIP read from UDP:$FIREWALL_IP:5066 —>
REGISTER sip:$ASTERISK_IP;transport=udp SIP/2.0
Via: SIP/2.0/UDP 192.168.1.54:5066;rport;branch=z9hG4bKPjKfOFYib8x17U6wzfLOEKkV3aiED.9PfH;alias
Max-Forwards: 70
From: sip:103@$ASTERISK_IP;tag=y6iZu18kb1VvOs4dc4AzAgzyQDA.8sjg
To: sip:103@$ASTERISK_IP
Call-ID: PCUiiBx8ga9Ni6aDgdDW6AgJ4.CwfoY6
CSeq: 59793 REGISTER
User-Agent: Konftel300IP 2.2.9
Contact: sip:103@192.168.1.54:5066;ob
Expires: 0
Content-Length: 0

<------------->
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 0 [ 48]: REGISTER sip:$ASTERISK_IP;transport=udp SIP/2.0
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 1 [ 95]: Via: SIP/2.0/UDP 192.168.1.54:5066;rport;branch=z9hG4bKPjKfOFYib8x17U6wzfLOEKkV3aiED.9PfH;alias
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 3 [ 66]: From: sip:103@$ASTERISK_IP;tag=y6iZu18kb1VvOs4dc4AzAgzyQDA.8sjg
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 4 [ 27]: To: sip:103@$ASTERISK_IP
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 5 [ 41]: Call-ID: PCUiiBx8ga9Ni6aDgdDW6AgJ4.CwfoY6
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 6 [ 20]: CSeq: 59793 REGISTER
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 7 [ 30]: User-Agent: Konftel300IP 2.2.9
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 8 [ 39]: Contact: sip:103@192.168.1.54:5066;ob
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 9 [ 10]: Expires: 0
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 10 [ 17]: Content-Length: 0
[Mar 15 15:54:21] VERBOSE[15103] chan_sip.c: — (11 headers 0 lines) —
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: = Looking for Call ID: PCUiiBx8ga9Ni6aDgdDW6AgJ4.CwfoY6 (Checking From) --From tag y6iZu18kb1VvOs4dc4AzAgzyQDA.8sjg --To-tag
[Mar 15 15:54:21] DEBUG[15103] acl.c: For destination ‘$FIREWALL_IP’, our source address is ‘$ASTERISK_IP’.
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Setting SIP_TRANSPORT_UDP with address $ASTERISK_IP:5060
[Mar 15 15:54:21] DEBUG[15103] netsock2.c: Splitting ‘192.168.1.54:5066’ into…
[Mar 15 15:54:21] DEBUG[15103] netsock2.c: …host ‘192.168.1.54’ and port ‘5066’.
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: NAT detected for 192.168.1.54 / $FIREWALL_IP
[Mar 15 15:54:21] VERBOSE[15103] chan_sip.c: Sending to $FIREWALL_IP:5066 (NAT)
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Allocating new SIP dialog for PCUiiBx8ga9Ni6aDgdDW6AgJ4.CwfoY6 - REGISTER (No RTP)
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Initializing initreq for method REGISTER - callid PCUiiBx8ga9Ni6aDgdDW6AgJ4.CwfoY6
[Mar 15 15:54:21] DEBUG[15103] netsock2.c: Splitting ‘192.168.1.54:5066’ into…
[Mar 15 15:54:21] DEBUG[15103] netsock2.c: …host ‘192.168.1.54’ and port ‘5066’.
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: NAT detected for 192.168.1.54 / $FIREWALL_IP
[Mar 15 15:54:21] VERBOSE[15103] chan_sip.c: Sending to $FIREWALL_IP:5066 (NAT)
[Mar 15 15:54:21] DEBUG[15103] netsock2.c: Splitting ‘$ASTERISK_IP’ into…
[Mar 15 15:54:21] DEBUG[15103] netsock2.c: …host ‘$ASTERISK_IP’ and port ‘’.
[Mar 15 15:54:21] VERBOSE[15103] chan_sip.c:
<— Transmitting (NAT) to $FIREWALL_IP:5066 —>
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 192.168.1.54:5066;branch=z9hG4bKPjKfOFYib8x17U6wzfLOEKkV3aiED.9PfH;alias;received=$FIREWALL_IP;rport=5066
From: sip:103@$ASTERISK_IP;tag=y6iZu18kb1VvOs4dc4AzAgzyQDA.8sjg
To: sip:103@$ASTERISK_IP;tag=as5c359725
Call-ID: PCUiiBx8ga9Ni6aDgdDW6AgJ4.CwfoY6
CSeq: 59793 REGISTER
Server: Asterisk PBX 11.8.1
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
WWW-Authenticate: Digest algorithm=MD5, realm=“asterisk”, nonce="69986e44"
Content-Length: 0

<------------>
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 0 [ 24]: SIP/2.0 401 Unauthorized
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 1 [123]: Via: SIP/2.0/UDP 192.168.1.54:5066;branch=z9hG4bKPjKfOFYib8x17U6wzfLOEKkV3aiED.9PfH;alias;received=$FIREWALL_IP;rport=5066
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 2 [ 66]: From: sip:103@$ASTERISK_IP;tag=y6iZu18kb1VvOs4dc4AzAgzyQDA.8sjg
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 3 [ 42]: To: sip:103@$ASTERISK_IP;tag=as5c359725
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 4 [ 41]: Call-ID: PCUiiBx8ga9Ni6aDgdDW6AgJ4.CwfoY6
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 5 [ 20]: CSeq: 59793 REGISTER
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 6 [ 27]: Server: Asterisk PBX 11.8.1
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 7 [ 81]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 8 [ 26]: Supported: replaces, timer
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 9 [ 74]: WWW-Authenticate: Digest algorithm=MD5, realm=“asterisk”, nonce=“69986e44”
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 10 [ 17]: Content-Length: 0
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 11 [ 0]:
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Trying to put ‘SIP/2.0 401’ onto UDP socket destined for $FIREWALL_IP:5066
[Mar 15 15:54:21] VERBOSE[15103] chan_sip.c: Scheduling destruction of SIP dialog ‘PCUiiBx8ga9Ni6aDgdDW6AgJ4.CwfoY6’ in 32000 ms (Method: REGISTER)
[Mar 15 15:54:21] VERBOSE[15103] chan_sip.c:
<— SIP read from UDP:$FIREWALL_IP:5066 —>
REGISTER sip:$ASTERISK_IP;transport=udp SIP/2.0
Via: SIP/2.0/UDP $FIREWALL_IP:5066;rport;branch=z9hG4bKPjNXvMWYqKtTWkz1xfUYxvbWW7dZ0Snpnl;alias
Max-Forwards: 70
From: sip:103@$ASTERISK_IP;tag=EM.0nmIg6wlwzZHg.Q07UPdq9T82zo5j
To: sip:103@$ASTERISK_IP
Call-ID: QzzQJWWZTK5NzB.MDm8MzJzUfM4Ah9UW
CSeq: 52501 REGISTER
User-Agent: Konftel300IP 2.2.9
Contact: sip:103@:5066$FIREWALL_IP;transport=UDP;ob
Expires: 1800
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Content-Length: 0

<------------->
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 0 [ 48]: REGISTER sip:$ASTERISK_IP;transport=udp SIP/2.0
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 1 [ 96]: Via: SIP/2.0/UDP $FIREWALL_IP:5066;rport;branch=z9hG4bKPjNXvMWYqKtTWkz1xfUYxvbWW7dZ0Snpnl;alias
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 3 [ 66]: From: sip:103@$ASTERISK_IP;tag=EM.0nmIg6wlwzZHg.Q07UPdq9T82zo5j
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 4 [ 27]: To: sip:103@$ASTERISK_IP
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 5 [ 41]: Call-ID: QzzQJWWZTK5NzB.MDm8MzJzUfM4Ah9UW
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 6 [ 20]: CSeq: 52501 REGISTER
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 7 [ 30]: User-Agent: Konftel300IP 2.2.9
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 8 [ 54]: Contact: sip:103@:5066$FIREWALL_IP;transport=UDP;ob
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 9 [ 13]: Expires: 1800
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 10 [ 90]: Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 11 [ 17]: Content-Length: 0
[Mar 15 15:54:21] VERBOSE[15103] chan_sip.c: — (12 headers 0 lines) —
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: = Looking for Call ID: QzzQJWWZTK5NzB.MDm8MzJzUfM4Ah9UW (Checking From) --From tag EM.0nmIg6wlwzZHg.Q07UPdq9T82zo5j --To-tag
[Mar 15 15:54:21] DEBUG[15103] acl.c: For destination ‘$FIREWALL_IP’, our source address is ‘$ASTERISK_IP’.
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Setting SIP_TRANSPORT_UDP with address $ASTERISK_IP:5060
[Mar 15 15:54:21] DEBUG[15103] netsock2.c: Splitting ‘$FIREWALL_IP:5066’ into…
[Mar 15 15:54:21] DEBUG[15103] netsock2.c: …host ‘$FIREWALL_IP’ and port ‘5066’.
[Mar 15 15:54:21] VERBOSE[15103] chan_sip.c: Sending to $FIREWALL_IP:5066 (no NAT)
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Allocating new SIP dialog for QzzQJWWZTK5NzB.MDm8MzJzUfM4Ah9UW - REGISTER (No RTP)
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Initializing initreq for method REGISTER - callid QzzQJWWZTK5NzB.MDm8MzJzUfM4Ah9UW
[Mar 15 15:54:21] DEBUG[15103] netsock2.c: Splitting ‘$FIREWALL_IP:5066’ into…
[Mar 15 15:54:21] DEBUG[15103] netsock2.c: …host ‘$FIREWALL_IP’ and port ‘5066’.
[Mar 15 15:54:21] VERBOSE[15103] chan_sip.c: Sending to $FIREWALL_IP:5066 (no NAT)
[Mar 15 15:54:21] DEBUG[15103] netsock2.c: Splitting ‘$ASTERISK_IP’ into…
[Mar 15 15:54:21] DEBUG[15103] netsock2.c: …host ‘$ASTERISK_IP’ and port ‘’.
[Mar 15 15:54:21] VERBOSE[15103] chan_sip.c:
<— Transmitting (NAT) to $FIREWALL_IP:5066 —>
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP $FIREWALL_IP:5066;branch=z9hG4bKPjNXvMWYqKtTWkz1xfUYxvbWW7dZ0Snpnl;alias;received=$FIREWALL_IP;rport=5066
From: sip:103@$ASTERISK_IP;tag=EM.0nmIg6wlwzZHg.Q07UPdq9T82zo5j
To: sip:103@$ASTERISK_IP;tag=as6489bd6a
Call-ID: QzzQJWWZTK5NzB.MDm8MzJzUfM4Ah9UW
CSeq: 52501 REGISTER
Server: Asterisk PBX 11.8.1
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
WWW-Authenticate: Digest algorithm=MD5, realm=“asterisk”, nonce="0ee33ba6"
Content-Length: 0

<------------>
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 0 [ 24]: SIP/2.0 401 Unauthorized
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 1 [124]: Via: SIP/2.0/UDP $FIREWALL_IP:5066;branch=z9hG4bKPjNXvMWYqKtTWkz1xfUYxvbWW7dZ0Snpnl;alias;received=$FIREWALL_IP;rport=5066
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 2 [ 66]: From: sip:103@$ASTERISK_IP;tag=EM.0nmIg6wlwzZHg.Q07UPdq9T82zo5j
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 3 [ 42]: To: sip:103@$ASTERISK_IP;tag=as6489bd6a
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 4 [ 41]: Call-ID: QzzQJWWZTK5NzB.MDm8MzJzUfM4Ah9UW
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 5 [ 20]: CSeq: 52501 REGISTER
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 6 [ 27]: Server: Asterisk PBX 11.8.1
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 7 [ 81]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 8 [ 26]: Supported: replaces, timer
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 9 [ 74]: WWW-Authenticate: Digest algorithm=MD5, realm=“asterisk”, nonce=“0ee33ba6”
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 10 [ 17]: Content-Length: 0
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 11 [ 0]:
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Trying to put ‘SIP/2.0 401’ onto UDP socket destined for $FIREWALL_IP:5066
[Mar 15 15:54:21] VERBOSE[15103] chan_sip.c: Scheduling destruction of SIP dialog ‘QzzQJWWZTK5NzB.MDm8MzJzUfM4Ah9UW’ in 32000 ms (Method: REGISTER)
[Mar 15 15:54:21] VERBOSE[15103] chan_sip.c:
<— SIP read from UDP:$FIREWALL_IP:5066 —>
REGISTER sip:$ASTERISK_IP;transport=udp SIP/2.0
Via: SIP/2.0/UDP $FIREWALL_IP:5066;rport;branch=z9hG4bKPj.S8a.W6NP1zvlYprzzfnXG5RjoVZA92l;alias
Max-Forwards: 70
From: sip:103@$ASTERISK_IP;tag=y6iZu18kb1VvOs4dc4AzAgzyQDA.8sjg
To: sip:103@$ASTERISK_IP
Call-ID: PCUiiBx8ga9Ni6aDgdDW6AgJ4.CwfoY6
CSeq: 59794 REGISTER
User-Agent: Konftel300IP 2.2.9
Contact: sip:103@192.168.1.54:5066;ob
Expires: 0
Authorization: Digest username=“103”, realm=“asterisk”, nonce=“69986e44”, uri=“sip:$ASTERISK_IP;transport=udp”, response=“b19ac8661fbef8a01b9cfe6a577f8bcb”, algorithm=MD5
Content-Length: 0

<------------->
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 0 [ 48]: REGISTER sip:$ASTERISK_IP;transport=udp SIP/2.0
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 1 [ 96]: Via: SIP/2.0/UDP $FIREWALL_IP:5066;rport;branch=z9hG4bKPj.S8a.W6NP1zvlYprzzfnXG5RjoVZA92l;alias
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 3 [ 66]: From: sip:103@$ASTERISK_IP;tag=y6iZu18kb1VvOs4dc4AzAgzyQDA.8sjg
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 4 [ 27]: To: sip:103@$ASTERISK_IP
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 5 [ 41]: Call-ID: PCUiiBx8ga9Ni6aDgdDW6AgJ4.CwfoY6
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 6 [ 20]: CSeq: 59794 REGISTER
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 7 [ 30]: User-Agent: Konftel300IP 2.2.9
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 8 [ 39]: Contact: sip:103@192.168.1.54:5066;ob
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 9 [ 10]: Expires: 0
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 10 [171]: Authorization: Digest username=“103”, realm=“asterisk”, nonce=“69986e44”, uri=“sip:$ASTERISK_IP;transport=udp”, response=“b19ac8661fbef8a01b9cfe6a577f8bcb”, algorithm=MD5
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 11 [ 17]: Content-Length: 0
[Mar 15 15:54:21] VERBOSE[15103] chan_sip.c: — (12 headers 0 lines) —
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: = Looking for Call ID: PCUiiBx8ga9Ni6aDgdDW6AgJ4.CwfoY6 (Checking From) --From tag y6iZu18kb1VvOs4dc4AzAgzyQDA.8sjg --To-tag
[Mar 15 15:54:21] DEBUG[15103] netsock2.c: Splitting ‘$ASTERISK_IP’ into…
[Mar 15 15:54:21] DEBUG[15103] netsock2.c: …host ‘$ASTERISK_IP’ and port ‘’.
[Mar 15 15:54:21] DEBUG[15103] netsock2.c: Splitting ‘$ASTERISK_IP’ into…
[Mar 15 15:54:21] DEBUG[15103] netsock2.c: …host ‘$ASTERISK_IP’ and port ‘’.
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Initializing initreq for method REGISTER - callid PCUiiBx8ga9Ni6aDgdDW6AgJ4.CwfoY6
[Mar 15 15:54:21] DEBUG[15103] netsock2.c: Splitting ‘$FIREWALL_IP:5066’ into…
[Mar 15 15:54:21] DEBUG[15103] netsock2.c: …host ‘$FIREWALL_IP’ and port ‘5066’.
[Mar 15 15:54:21] VERBOSE[15103] chan_sip.c: Sending to $FIREWALL_IP:5066 (no NAT)
[Mar 15 15:54:21] DEBUG[15103] netsock2.c: Splitting ‘$ASTERISK_IP’ into…
[Mar 15 15:54:21] DEBUG[15103] netsock2.c: …host ‘$ASTERISK_IP’ and port ‘’.
[Mar 15 15:54:21] NOTICE[15103] chan_sip.c: Correct auth, but based on stale nonce received from ‘sip:103@$ASTERISK_IP;tag=y6iZu18kb1VvOs4dc4AzAgzyQDA.8sjg’
[Mar 15 15:54:21] VERBOSE[15103] chan_sip.c:
<— Transmitting (NAT) to $FIREWALL_IP:5066 —>
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP $FIREWALL_IP:5066;branch=z9hG4bKPj.S8a.W6NP1zvlYprzzfnXG5RjoVZA92l;alias;received=$FIREWALL_IP;rport=5066
From: sip:103@$ASTERISK_IP;tag=y6iZu18kb1VvOs4dc4AzAgzyQDA.8sjg
To: sip:103@$ASTERISK_IP;tag=as41535b92
Call-ID: PCUiiBx8ga9Ni6aDgdDW6AgJ4.CwfoY6
CSeq: 59794 REGISTER
Server: Asterisk PBX 11.8.1
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
WWW-Authenticate: Digest algorithm=MD5, realm=“asterisk”, nonce=“42318b42”, stale=true
Content-Length: 0

<------------>
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 0 [ 24]: SIP/2.0 401 Unauthorized
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 1 [124]: Via: SIP/2.0/UDP $FIREWALL_IP:5066;branch=z9hG4bKPj.S8a.W6NP1zvlYprzzfnXG5RjoVZA92l;alias;received=$FIREWALL_IP;rport=5066
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 2 [ 66]: From: sip:103@$ASTERISK_IP;tag=y6iZu18kb1VvOs4dc4AzAgzyQDA.8sjg
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 3 [ 42]: To: sip:103@$ASTERISK_IP;tag=as41535b92
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 4 [ 41]: Call-ID: PCUiiBx8ga9Ni6aDgdDW6AgJ4.CwfoY6
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 5 [ 20]: CSeq: 59794 REGISTER
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 6 [ 27]: Server: Asterisk PBX 11.8.1
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 7 [ 81]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 8 [ 26]: Supported: replaces, timer
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 9 [ 86]: WWW-Authenticate: Digest algorithm=MD5, realm=“asterisk”, nonce=“42318b42”, stale=true
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 10 [ 17]: Content-Length: 0
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 11 [ 0]:
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Trying to put ‘SIP/2.0 401’ onto UDP socket destined for $FIREWALL_IP:5066
[Mar 15 15:54:21] VERBOSE[15103] chan_sip.c: Scheduling destruction of SIP dialog ‘PCUiiBx8ga9Ni6aDgdDW6AgJ4.CwfoY6’ in 32000 ms (Method: REGISTER)
[Mar 15 15:54:21] VERBOSE[15103] chan_sip.c:
<— SIP read from UDP:$FIREWALL_IP:5066 —>
REGISTER sip:$ASTERISK_IP;transport=udp SIP/2.0
Via: SIP/2.0/UDP $FIREWALL_IP:5066;rport;branch=z9hG4bKPjqL34R.IeymP0EoRJLvlkNnuTLB9bGDlz;alias
Max-Forwards: 70
From: sip:103@$ASTERISK_IP;tag=EM.0nmIg6wlwzZHg.Q07UPdq9T82zo5j
To: sip:103@$ASTERISK_IP
Call-ID: QzzQJWWZTK5NzB.MDm8MzJzUfM4Ah9UW
CSeq: 52502 REGISTER
User-Agent: Konftel300IP 2.2.9
Contact: sip:103@:5066$FIREWALL_IP;transport=UDP;ob
Expires: 1800
Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
Authorization: Digest username=“103”, realm=“asterisk”, nonce=“0ee33ba6”, uri=“sip:$ASTERISK_IP;transport=udp”, response=“abd0356a0d81dc690069ed883d1ec84b”, algorithm=MD5
Content-Length: 0

<------------->
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 0 [ 48]: REGISTER sip:$ASTERISK_IP;transport=udp SIP/2.0
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 1 [ 96]: Via: SIP/2.0/UDP $FIREWALL_IP:5066;rport;branch=z9hG4bKPjqL34R.IeymP0EoRJLvlkNnuTLB9bGDlz;alias
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 3 [ 66]: From: sip:103@$ASTERISK_IP;tag=EM.0nmIg6wlwzZHg.Q07UPdq9T82zo5j
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 4 [ 27]: To: sip:103@$ASTERISK_IP
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 5 [ 41]: Call-ID: QzzQJWWZTK5NzB.MDm8MzJzUfM4Ah9UW
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 6 [ 20]: CSeq: 52502 REGISTER
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 7 [ 30]: User-Agent: Konftel300IP 2.2.9
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 8 [ 54]: Contact: sip:103@:5066$FIREWALL_IP;transport=UDP;ob
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 9 [ 13]: Expires: 1800
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 10 [ 90]: Allow: PRACK, INVITE, ACK, BYE, CANCEL, UPDATE, SUBSCRIBE, NOTIFY, REFER, MESSAGE, OPTIONS
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 11 [171]: Authorization: Digest username=“103”, realm=“asterisk”, nonce=“0ee33ba6”, uri=“sip:$ASTERISK_IP;transport=udp”, response=“abd0356a0d81dc690069ed883d1ec84b”, algorithm=MD5
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Header 12 [ 17]: Content-Length: 0
[Mar 15 15:54:21] VERBOSE[15103] chan_sip.c: — (13 headers 0 lines) —
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: = Looking for Call ID: QzzQJWWZTK5NzB.MDm8MzJzUfM4Ah9UW (Checking From) --From tag EM.0nmIg6wlwzZHg.Q07UPdq9T82zo5j --To-tag
[Mar 15 15:54:21] DEBUG[15103] netsock2.c: Splitting ‘$ASTERISK_IP’ into…
[Mar 15 15:54:21] DEBUG[15103] netsock2.c: …host ‘$ASTERISK_IP’ and port ‘’.
[Mar 15 15:54:21] DEBUG[15103] netsock2.c: Splitting ‘$ASTERISK_IP’ into…
[Mar 15 15:54:21] DEBUG[15103] netsock2.c: …host ‘$ASTERISK_IP’ and port ‘’.
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Initializing initreq for method REGISTER - callid QzzQJWWZTK5NzB.MDm8MzJzUfM4Ah9UW
[Mar 15 15:54:21] DEBUG[15103] netsock2.c: Splitting ‘$FIREWALL_IP:5066’ into…
[Mar 15 15:54:21] DEBUG[15103] netsock2.c: …host ‘$FIREWALL_IP’ and port ‘5066’.
[Mar 15 15:54:21] VERBOSE[15103] chan_sip.c: Sending to $FIREWALL_IP:5066 (no NAT)
[Mar 15 15:54:21] DEBUG[15103] netsock2.c: Splitting ‘$ASTERISK_IP’ into…
[Mar 15 15:54:21] DEBUG[15103] netsock2.c: …host ‘$ASTERISK_IP’ and port ‘’.
[Mar 15 15:54:21] DEBUG[15103] chan_sip.c: Store REGISTER’s src-IP:port for call routing.
[Mar 15 15:54:22] VERBOSE[15103] chan_sip.c:
<— Transmitting (NAT) to $FIREWALL_IP:5066 —>
SIP/2.0 200 OK
Via: SIP/2.0/UDP $FIREWALL_IP:5066;branch=z9hG4bKPjqL34R.IeymP0EoRJLvlkNnuTLB9bGDlz;alias;received=$FIREWALL_IP;rport=5066
From: sip:103@$ASTERISK_IP;tag=EM.0nmIg6wlwzZHg.Q07UPdq9T82zo5j
To: sip:103@$ASTERISK_IP;tag=as6489bd6a
Call-ID: QzzQJWWZTK5NzB.MDm8MzJzUfM4Ah9UW
CSeq: 52502 REGISTER
Server: Asterisk PBX 11.8.1
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Expires: 1800
Contact: sip:103@:5066$FIREWALL_IP;transport=UDP;ob;expires=1800
Date: Sat, 15 Mar 2014 11:54:22 GMT
Content-Length: 0

<------------>
[Mar 15 15:54:22] DEBUG[15103] chan_sip.c: Header 0 [ 14]: SIP/2.0 200 OK
[Mar 15 15:54:22] DEBUG[15103] chan_sip.c: Header 1 [124]: Via: SIP/2.0/UDP $FIREWALL_IP:5066;branch=z9hG4bKPjqL34R.IeymP0EoRJLvlkNnuTLB9bGDlz;alias;received=$FIREWALL_IP;rport=5066
[Mar 15 15:54:22] DEBUG[15103] chan_sip.c: Header 2 [ 66]: From: sip:103@$ASTERISK_IP;tag=EM.0nmIg6wlwzZHg.Q07UPdq9T82zo5j
[Mar 15 15:54:22] DEBUG[15103] chan_sip.c: Header 3 [ 42]: To: sip:103@$ASTERISK_IP;tag=as6489bd6a
[Mar 15 15:54:22] DEBUG[15103] chan_sip.c: Header 4 [ 41]: Call-ID: QzzQJWWZTK5NzB.MDm8MzJzUfM4Ah9UW
[Mar 15 15:54:22] DEBUG[15103] chan_sip.c: Header 5 [ 20]: CSeq: 52502 REGISTER
[Mar 15 15:54:22] DEBUG[15103] chan_sip.c: Header 6 [ 27]: Server: Asterisk PBX 11.8.1
[Mar 15 15:54:22] DEBUG[15103] chan_sip.c: Header 7 [ 81]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
[Mar 15 15:54:22] DEBUG[15103] chan_sip.c: Header 8 [ 26]: Supported: replaces, timer
[Mar 15 15:54:22] DEBUG[15103] chan_sip.c: Header 9 [ 13]: Expires: 1800
[Mar 15 15:54:22] DEBUG[15103] chan_sip.c: Header 10 [ 67]: Contact: sip:103@:5066$FIREWALL_IP;transport=UDP;ob;expires=1800
[Mar 15 15:54:22] DEBUG[15103] chan_sip.c: Header 11 [ 35]: Date: Sat, 15 Mar 2014 11:54:22 GMT
[Mar 15 15:54:22] DEBUG[15103] chan_sip.c: Header 12 [ 17]: Content-Length: 0
[Mar 15 15:54:22] DEBUG[15103] chan_sip.c: Header 13 [ 0]:
[Mar 15 15:54:22] DEBUG[15103] chan_sip.c: Trying to put ‘SIP/2.0 200’ onto UDP socket destined for $FIREWALL_IP:5066
[Mar 15 15:54:22] DEBUG[15094] devicestate.c: No provider found, checking channel drivers for SIP - 103
[Mar 15 15:54:22] DEBUG[15094] chan_sip.c: Checking device state for peer 103
[Mar 15 15:54:22] DEBUG[15094] devicestate.c: Changing state for SIP/103 - state 1 (Not in use)
[Mar 15 15:54:22] DEBUG[15094] devicestate.c: device ‘SIP/103’ state ‘1’
[Mar 15 15:54:22] VERBOSE[15103] chan_sip.c: Scheduling destruction of SIP dialog ‘QzzQJWWZTK5NzB.MDm8MzJzUfM4Ah9UW’ in 32000 ms (Method: REGISTER)
[Mar 15 15:54:22] DEBUG[15128] app_queue.c: Device ‘SIP/103’ changed to state ‘1’ (Not in use) but we don’t care because they’re not a member of any queue.
[Mar 15 15:54:22] VERBOSE[15103] chan_sip.c:
<— SIP read from UDP:$FIREWALL_IP:5066 —>
REGISTER sip:$ASTERISK_IP;transport=udp SIP/2.0
Via: SIP/2.0/UDP $FIREWALL_IP:5066;rport;branch=z9hG4bKPjDGG8OLYhEZ8Jn4dXj1JIgoXpEHLSz8-y;alias
Max-Forwards: 70
From: sip:103@$ASTERISK_IP;tag=y6iZu18kb1VvOs4dc4AzAgzyQDA.8sjg
To: sip:103@$ASTERISK_IP
Call-ID: PCUiiBx8ga9Ni6aDgdDW6AgJ4.CwfoY6
CSeq: 59795 REGISTER
User-Agent: Konftel300IP 2.2.9
Contact: sip:103@192.168.1.54:5066;ob
Expires: 0
Authorization: Digest username=“103”, realm=“asterisk”, nonce=“42318b42”, uri=“sip:$ASTERISK_IP;transport=udp”, response=“2378fc64403ae7f6064035531544976e”, algorithm=MD5
Content-Length: 0

<------------->
[Mar 15 15:54:22] DEBUG[15103] chan_sip.c: Header 0 [ 48]: REGISTER sip:$ASTERISK_IP;transport=udp SIP/2.0
[Mar 15 15:54:22] DEBUG[15103] chan_sip.c: Header 1 [ 96]: Via: SIP/2.0/UDP $FIREWALL_IP:5066;rport;branch=z9hG4bKPjDGG8OLYhEZ8Jn4dXj1JIgoXpEHLSz8-y;alias
[Mar 15 15:54:22] DEBUG[15103] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70
[Mar 15 15:54:22] DEBUG[15103] chan_sip.c: Header 3 [ 66]: From: sip:103@$ASTERISK_IP;tag=y6iZu18kb1VvOs4dc4AzAgzyQDA.8sjg
[Mar 15 15:54:22] DEBUG[15103] chan_sip.c: Header 4 [ 27]: To: sip:103@$ASTERISK_IP
[Mar 15 15:54:22] DEBUG[15103] chan_sip.c: Header 5 [ 41]: Call-ID: PCUiiBx8ga9Ni6aDgdDW6AgJ4.CwfoY6
[Mar 15 15:54:22] DEBUG[15103] chan_sip.c: Header 6 [ 20]: CSeq: 59795 REGISTER
[Mar 15 15:54:22] DEBUG[15103] chan_sip.c: Header 7 [ 30]: User-Agent: Konftel300IP 2.2.9
[Mar 15 15:54:22] DEBUG[15103] chan_sip.c: Header 8 [ 39]: Contact: sip:103@192.168.1.54:5066;ob
[Mar 15 15:54:22] DEBUG[15103] chan_sip.c: Header 9 [ 10]: Expires: 0
[Mar 15 15:54:22] DEBUG[15103] chan_sip.c: Header 10 [171]: Authorization: Digest username=“103”, realm=“asterisk”, nonce=“42318b42”, uri=“sip:$ASTERISK_IP;transport=udp”, response=“2378fc64403ae7f6064035531544976e”, algorithm=MD5
[Mar 15 15:54:22] DEBUG[15103] chan_sip.c: Header 11 [ 17]: Content-Length: 0
[Mar 15 15:54:22] VERBOSE[15103] chan_sip.c: — (12 headers 0 lines) —
[Mar 15 15:54:22] DEBUG[15103] chan_sip.c: = Looking for Call ID: PCUiiBx8ga9Ni6aDgdDW6AgJ4.CwfoY6 (Checking From) --From tag y6iZu18kb1VvOs4dc4AzAgzyQDA.8sjg --To-tag
[Mar 15 15:54:22] DEBUG[15103] netsock2.c: Splitting ‘$ASTERISK_IP’ into…
[Mar 15 15:54:22] DEBUG[15103] netsock2.c: …host ‘$ASTERISK_IP’ and port ‘’.
[Mar 15 15:54:22] DEBUG[15103] netsock2.c: Splitting ‘$ASTERISK_IP’ into…
[Mar 15 15:54:22] DEBUG[15103] netsock2.c: …host ‘$ASTERISK_IP’ and port ‘’.
[Mar 15 15:54:22] DEBUG[15103] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER
[Mar 15 15:54:22] DEBUG[15103] chan_sip.c: Initializing initreq for method REGISTER - callid PCUiiBx8ga9Ni6aDgdDW6AgJ4.CwfoY6
[Mar 15 15:54:22] DEBUG[15103] netsock2.c: Splitting ‘$FIREWALL_IP:5066’ into…
[Mar 15 15:54:22] DEBUG[15103] netsock2.c: …host ‘$FIREWALL_IP’ and port ‘5066’.
[Mar 15 15:54:22] VERBOSE[15103] chan_sip.c: Sending to $FIREWALL_IP:5066 (no NAT)
[Mar 15 15:54:22] DEBUG[15103] netsock2.c: Splitting ‘$ASTERISK_IP’ into…
[Mar 15 15:54:22] DEBUG[15103] netsock2.c: …host ‘$ASTERISK_IP’ and port ‘’.
[Mar 15 15:54:22] VERBOSE[15103] chan_sip.c:
<— Transmitting (NAT) to $FIREWALL_IP:5066 —>
SIP/2.0 200 OK
Via: SIP/2.0/UDP $FIREWALL_IP:5066;branch=z9hG4bKPjDGG8OLYhEZ8Jn4dXj1JIgoXpEHLSz8-y;alias;received=$FIREWALL_IP;rport=5066
From: sip:103@$ASTERISK_IP;tag=y6iZu18kb1VvOs4dc4AzAgzyQDA.8sjg
To: sip:103@$ASTERISK_IP;tag=as41535b92
Call-ID: PCUiiBx8ga9Ni6aDgdDW6AgJ4.CwfoY6
CSeq: 59795 REGISTER
Server: Asterisk PBX 11.8.1
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Expires: 0
Date: Sat, 15 Mar 2014 11:54:22 GMT
Content-Length: 0

<------------>
[Mar 15 15:54:22] DEBUG[15103] chan_sip.c: Header 0 [ 14]: SIP/2.0 200 OK
[Mar 15 15:54:22] DEBUG[15103] chan_sip.c: Header 1 [124]: Via: SIP/2.0/UDP $FIREWALL_IP:5066;branch=z9hG4bKPjDGG8OLYhEZ8Jn4dXj1JIgoXpEHLSz8-y;alias;received=$FIREWALL_IP;rport=5066
[Mar 15 15:54:22] DEBUG[15094] devicestate.c: No provider found, checking channel drivers for SIP - 103
[Mar 15 15:54:22] DEBUG[15103] chan_sip.c: Header 2 [ 66]: From: sip:103@$ASTERISK_IP;tag=y6iZu18kb1VvOs4dc4AzAgzyQDA.8sjg
[Mar 15 15:54:22] DEBUG[15103] chan_sip.c: Header 3 [ 42]: To: sip:103@$ASTERISK_IP;tag=as41535b92
[Mar 15 15:54:22] DEBUG[15103] chan_sip.c: Header 4 [ 41]: Call-ID: PCUiiBx8ga9Ni6aDgdDW6AgJ4.CwfoY6
[Mar 15 15:54:22] DEBUG[15094] chan_sip.c: Checking device state for peer 103
[Mar 15 15:54:22] DEBUG[15103] chan_sip.c: Header 5 [ 20]: CSeq: 59795 REGISTER
[Mar 15 15:54:22] DEBUG[15103] chan_sip.c: Header 6 [ 27]: Server: Asterisk PBX 11.8.1
[Mar 15 15:54:22] DEBUG[15103] chan_sip.c: Header 7 [ 81]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
[Mar 15 15:54:22] DEBUG[15103] chan_sip.c: Header 8 [ 26]: Supported: replaces, timer
[Mar 15 15:54:22] DEBUG[15103] chan_sip.c: Header 9 [ 10]: Expires: 0
[Mar 15 15:54:22] DEBUG[15094] devicestate.c: Changing state for SIP/103 - state 5 (Unavailable)
[Mar 15 15:54:22] DEBUG[15094] devicestate.c: device ‘SIP/103’ state ‘5’
[Mar 15 15:54:22] DEBUG[15103] chan_sip.c: Header 10 [ 35]: Date: Sat, 15 Mar 2014 11:54:22 GMT
[Mar 15 15:54:22] DEBUG[15103] chan_sip.c: Header 11 [ 17]: Content-Length: 0
[Mar 15 15:54:22] DEBUG[15103] chan_sip.c: Header 12 [ 0]:
[Mar 15 15:54:22] DEBUG[15103] chan_sip.c: Trying to put ‘SIP/2.0 200’ onto UDP socket destined for $FIREWALL_IP:5066
[Mar 15 15:54:22] DEBUG[15128] app_queue.c: Device ‘SIP/103’ changed to state ‘5’ (Unavailable) but we don’t care because they’re not a member of any queue.
[Mar 15 15:54:22] DEBUG[15094] devicestate.c: No provider found, checking channel drivers for SIP - 103
[Mar 15 15:54:22] VERBOSE[15103] chan_sip.c: Scheduling destruction of SIP dialog ‘PCUiiBx8ga9Ni6aDgdDW6AgJ4.CwfoY6’ in 32000 ms (Method: REGISTER)
[Mar 15 15:54:22] DEBUG[15094] chan_sip.c: Checking device state for peer 103
[Mar 15 15:54:22] DEBUG[15094] devicestate.c: Changing state for SIP/103 - state 5 (Unavailable)
[Mar 15 15:54:22] DEBUG[15094] devicestate.c: device ‘SIP/103’ state ‘5’
[Mar 15 15:54:22] DEBUG[15128] app_queue.c: Device ‘SIP/103’ changed to state ‘5’ (Unavailable) but we don’t care because they’re not a member of any queue.
[Mar 15 15:54:37] VERBOSE[15103] chan_sip.c:
<— SIP read from UDP:$FIREWALL_IP:5066 —>

<------------->
[Mar 15 15:54:52] VERBOSE[15103] chan_sip.c:
<— SIP read from UDP:$FIREWALL_IP:5066 —>

<------------->
[Mar 15 15:54:53] DEBUG[15103] chan_sip.c: Auto destroying SIP dialog ‘PCUiiBx8ga9Ni6aDgdDW6AgJ4.CwfoY6’
[Mar 15 15:54:53] DEBUG[15103] chan_sip.c: Destroying SIP dialog PCUiiBx8ga9Ni6aDgdDW6AgJ4.CwfoY6
[Mar 15 15:54:53] VERBOSE[15103] chan_sip.c: Really destroying SIP dialog ‘PCUiiBx8ga9Ni6aDgdDW6AgJ4.CwfoY6’ Method: REGISTER
[Mar 15 15:54:54] DEBUG[15103] chan_sip.c: Auto destroying SIP dialog ‘QzzQJWWZTK5NzB.MDm8MzJzUfM4Ah9UW’
[Mar 15 15:54:54] DEBUG[15103] chan_sip.c: Destroying SIP dialog QzzQJWWZTK5NzB.MDm8MzJzUfM4Ah9UW
[Mar 15 15:54:54] VERBOSE[15103] chan_sip.c: Really destroying SIP dialog ‘QzzQJWWZTK5NzB.MDm8MzJzUfM4Ah9UW’ Method: REGISTER
[Mar 15 15:54:54] DEBUG[15103] chan_sip.c: Auto destroying SIP dialog ‘PCUiiBx8ga9Ni6aDgdDW6AgJ4.CwfoY6’
[Mar 15 15:54:54] DEBUG[15103] chan_sip.c: Destroying SIP dialog PCUiiBx8ga9Ni6aDgdDW6AgJ4.CwfoY6
[Mar 15 15:54:54] VERBOSE[15103] chan_sip.c: Really destroying SIP dialog ‘PCUiiBx8ga9Ni6aDgdDW6AgJ4.CwfoY6’ Method: REGISTER
[Mar 15 15:55:07] VERBOSE[15103] chan_sip.c:
<— SIP read from UDP:$FIREWALL_IP:5066 —>

<------------->
[Mar 15 15:55:21] DEBUG[15062] taskprocessor.c: destroying taskprocessor ‘ast_msg_queue’
[Mar 15 15:55:21] VERBOSE[15062] asterisk.c: Asterisk cleanly ending (0).
[Mar 15 15:55:21] VERBOSE[15062] asterisk.c: Executing last minute cleanups
[/code]

4

Disable the SIP support on the firewall and set it for simple port forwarding.