chan_sip.c: Peer 'XXX' is now UNREACHABLE

system · May 6, 2010, 7:57pm

Hello,

I work with asterisk 1.6.1.18 (from fedora packages) behind a nat and hardphones (linksys SPA922-942 and siemens C470IP) which are behind different nats too. For testing, I also have a SPA942 on my LAN. Moreover, public IP are dynamic (because our unique ISP does not provide fixed IP addresses) but this is not the purpose for that issue because all worked very well for months.

Since several days, I have “unreachable” messages on the CLI that should not be due to NAT timeouts because I also have them with my LAN peer. Nothing has changed on my server and the problem appeared suddenly. For information, the problem occured twice this year with earlier asterisk versions and I don’t know what resolved it.

Of course, during the time the peer is unreachable, it cannot be called which is a problem.

This time, I have debug information. Here are my confs & logs.

Regards

###########################################
sip config :
CLI> sip show settings
Global Settings:

UDP SIP Port: 5060
UDP Bindaddress: 0.0.0.0
TCP SIP Port: Disabled
TLS SIP Port: Disabled
Videosupport: No
Textsupport: No
AutoCreate Peer: No
Ignore SDP sess. ver.: No
Match Auth Username: No
Allow unknown access: Yes
Allow subscriptions: Yes
Allow overlap dialing: No
Allow promsic. redir: No
Enable call counters: No
SIP domain support: No
Realm. auth: No
Our auth realm asterisk
Call to non-local dom.: Yes
URI user is phone no: No
Always auth rejects: No
Direct RTP setup: No
User Agent: QWERTY (not shown for security reasons)
SDP Session Name: Asterisk PBX 1.6.1.18
SDP Owner Name: root
Reg. context: (not set)
Regexten on Qualify: No
Caller ID: asterisk
From: Domain:
Record SIP history: Off
Call Events: Off
Auth. Failure Events: Off
T.38 support: No
T.38 EC mode: Unknown
T.38 MaxDtgrm: -1
SIP realtime: Disabled
Qualify Freq : 60000 ms

Network Settings:

SIP address remapping: Enabled using externhost
Externhost: myserver.mydomain.net (not shown for security reasons)
Externip: XXX.XXX.XXX.XXX:5060 (not shown for security reasons)
Externrefresh: 10
Internal IP: 127.0.0.1:5060
Localnet: 192.168.0.0/255.255.255.0
STUN server: 0.0.0.0:0
Global Signalling Settings:

Codecs: 0x8000e (gsm|ulaw|alaw|h263)
Codec Order: none
Relax DTMF: No
RFC2833 Compensation: No
Compact SIP headers: No
RTP Keepalive: 0 (Disabled)
RTP Timeout: 0 (Disabled)
RTP Hold Timeout: 0 (Disabled)
MWI NOTIFY mime type: application/simple-message-summary
DNS SRV lookup: Yes
Pedantic SIP support: No
Reg. min duration 60 secs
Reg. max duration: 3600 secs
Reg. default duration: 120 secs
Outbound reg. timeout: 20 secs
Outbound reg. attempts: 0
Notify ringing state: Yes
Notify hold state: No
SIP Transfer mode: open
Max Call Bitrate: 384 kbps
Auto-Framing: No
Outb. proxy:
Session Timers: Accept
Session Refresher: uas
Session Expires: 1800 secs
Session Min-SE: 90 secs
Timer T1: 500
Timer T1 minimum: 100
Timer B: 32000
No premature media: No

Default Settings:

Context: default
Nat: Always
DTMF: rfc2833
Qualify: 0
Use ClientCode: No
Progress inband: Never
Language:
MOH Interpret: default
MOH Suggest:
Voice Mail Extension: asterisk

####################
peer configuration (for behind nat peer) :

CLI> sip show peer 60070

Name : 60070
Secret :
MD5Secret :
Context : qwerty (not shown for security reasons)
Subscr.Cont. :
Language : fr
Accountcode : 123456789 (not shown for security reasons)
AMA flags : QWERTY (not shown for security reasons)
Transfer mode: open
CallingPres : Presentation Allowed, Not Screened
Callgroup :
Pickupgroup :
Mailbox : 60070
VM Extension : asterisk
LastMsgsSent : 32767/65535
Call limit : 0
Dynamic : Yes
Callerid : “” <60070>
MaxCallBR : 384 kbps
Expire : 177
Insecure : no
Nat : Always
ACL : No
T.38 support : No
T.38 EC mode : Unknown
T.38 MaxDtgrm: -1
CanReinvite : No
PromiscRedir : No
User=Phone : No
Video Support: No
Text Support : No
Ign SDP ver : No
Trust RPID : No
Send RPID : No
Subscriptions: Yes
Overlap dial : No
DTMFmode : rfc2833
Timer T1 : 500
Timer B : 32000
ToHost :
Addr->IP : YYY.YYY.YYY.YYY Port 5918 (not shown for security reasons)
Defaddr->IP : 0.0.0.0 Port 5060
Transport : UDP
Def. Username: 60070
SIP Options : (none)
Codecs : 0x100 (g729)
Codec Order : (g729:20)
Auto-Framing : No
100 on REG : No
Status : OK (165 ms)
Useragent : C470IP021910000000
Reg. Contact : sip:60070@YYY.YYY.YYY.YYY:5918 (not shown for security reasons)
Qualify Freq : 60000 ms
Sess-Timers : Accept
Sess-Refresh : uas
Sess-Expires : 1800 secs
Min-Sess : 90 secs
Parkinglot :

#############################
sip configuration (for LAN peer) :

CLI> sip show peer 60025

Name : 60025
Secret :
MD5Secret :
Context : qwerty (not shown for security reasons)
Subscr.Cont. :
Language : fr
Accountcode : 2468013579 (not shown for security reasons)
AMA flags : QWERTY (not shown for security reasons)
Transfer mode: open
CallingPres : Presentation Allowed, Not Screened
Callgroup : 20
Pickupgroup : 20
Mailbox : 60025
VM Extension : asterisk
LastMsgsSent : 32767/65535
Call limit : 0
Dynamic : Yes
Callerid : “” <60025>
MaxCallBR : 384 kbps
Expire : 184
Insecure : no
Nat : Always
ACL : No
T.38 support : No
T.38 EC mode : Unknown
T.38 MaxDtgrm: -1
CanReinvite : No
PromiscRedir : No
User=Phone : No
Video Support: No
Text Support : No
Ign SDP ver : No
Trust RPID : No
Send RPID : No
Subscriptions: Yes
Overlap dial : No
DTMFmode : rfc2833
Timer T1 : 500
Timer B : 32000
ToHost :
Addr->IP : 192.168.0.106 Port 5063
Defaddr->IP : 0.0.0.0 Port 5060
Transport : UDP
Def. Username: 60025
SIP Options : (none)
Codecs : 0x100 (g729)
Codec Order : (g729:20)
Auto-Framing : No
100 on REG : No
Status : OK (8 ms)
Useragent : Linksys/SPA942-5.1.15(a)
Reg. Contact : sip:60025@192.168.0.106:5063
Qualify Freq : 60000 ms
Sess-Timers : Accept
Sess-Refresh : uas
Sess-Expires : 1800 secs
Min-Sess : 90 secs
Parkinglot :
#########################################
sip logs (for behind nat peer) :

[May 5 23:20:45] NOTICE[1847] chan_sip.c: Peer ‘60070’ is now UNREACHABLE! Last qualify: 122
[May 5 23:23:10] NOTICE[1847] chan_sip.c: Peer ‘60070’ is now Reachable. (154ms / 2000ms)
[May 5 23:23:11] NOTICE[1847] chan_sip.c: Correct auth, but based on stale nonce received from ‘“John Doe” sip:60070@mydomain.net’
[May 5 23:40:54] NOTICE[1847] chan_sip.c: Peer ‘60070’ is now UNREACHABLE! Last qualify: 141
[May 5 23:40:54] NOTICE[1847] chan_sip.c: Correct auth, but based on stale nonce received from ‘“John Doe” sip:60070@mydomain.net’
[May 5 23:40:54] NOTICE[1847] chan_sip.c: Correct auth, but based on stale nonce received from ‘“John Doe” sip:60070@mydomain.net’
[May 5 23:40:54] NOTICE[1847] chan_sip.c: Correct auth, but based on stale nonce received from ‘“John Doe” sip:60070@mydomain.net’
[May 5 23:40:54] NOTICE[1847] chan_sip.c: Peer ‘60070’ is now Reachable. (474ms / 2000ms)
[May 5 23:47:14] NOTICE[1847] chan_sip.c: Peer ‘60070’ is now UNREACHABLE! Last qualify: 1834
[May 5 23:47:50] NOTICE[1847] chan_sip.c: Peer ‘60070’ is now Reachable. (124ms / 2000ms)
[May 5 23:53:09] NOTICE[1847] chan_sip.c: Correct auth, but based on stale nonce received from ‘“John Doe” sip:60070@mydomain.net’
[May 5 23:53:09] NOTICE[1847] chan_sip.c: Correct auth, but based on stale nonce received from ‘“John Doe” sip:60070@mydomain.net’
[May 5 23:53:09] NOTICE[1847] chan_sip.c: Correct auth, but based on stale nonce received from ‘“John Doe” sip:60070@mydomain.net’
[May 5 23:54:39] NOTICE[1847] chan_sip.c: Peer ‘60070’ is now UNREACHABLE! Last qualify: 191
[May 5 23:55:28] NOTICE[1847] chan_sip.c: Peer ‘60070’ is now Reachable. (121ms / 2000ms)
[May 6 00:15:38] NOTICE[1847] chan_sip.c: Peer ‘60070’ is now UNREACHABLE! Last qualify: 262
[May 6 00:15:51] NOTICE[1847] chan_sip.c: Peer ‘60070’ is now Reachable. (313ms / 2000ms)
[May 6 00:20:00] NOTICE[1847] chan_sip.c: Peer ‘60070’ is now UNREACHABLE! Last qualify: 1915
[May 6 00:23:23] NOTICE[1847] chan_sip.c: Correct auth, but based on stale nonce received from ‘“John Doe” sip:60070@mydomain.net’
[May 6 00:23:23] NOTICE[1847] chan_sip.c: Correct auth, but based on stale nonce received from ‘“John Doe” sip:60070@mydomain.net’
[May 6 00:23:23] NOTICE[1847] chan_sip.c: Correct auth, but based on stale nonce received from ‘“John Doe” sip:60070@mydomain.net’
[May 6 00:23:23] NOTICE[1847] chan_sip.c: Correct auth, but based on stale nonce received from ‘“John Doe” sip:60070@mydomain.net’
[May 6 00:23:23] NOTICE[1847] chan_sip.c: Correct auth, but based on stale nonce received from ‘“John Doe” sip:60070@mydomain.net’
[May 6 00:24:17] NOTICE[1847] chan_sip.c: Peer ‘60070’ is now Reachable. (262ms / 2000ms)
##########################################
sip logs (for LAN peer):

[May 5 23:15:04] NOTICE[1847] chan_sip.c: Peer ‘60025’ is now UNREACHABLE! Last qualify: 6
[May 5 23:16:17] NOTICE[1847] chan_sip.c: Peer ‘60025’ is now Reachable. (10ms / 2000ms)
[May 5 23:19:19] NOTICE[1847] chan_sip.c: Correct auth, but based on stale nonce received from ‘“60025” sip:60025@192.168.0.204’
[May 5 23:19:19] NOTICE[1847] chan_sip.c: Correct auth, but based on stale nonce received from ‘“60025” sip:60025@192.168.0.204’
[May 5 23:19:19] NOTICE[1847] chan_sip.c: Correct auth, but based on stale nonce received from ‘“60025” sip:60025@192.168.0.204’
[May 5 23:20:38] NOTICE[1847] chan_sip.c: Peer ‘60025’ is now UNREACHABLE! Last qualify: 13
[May 5 23:20:53] NOTICE[1847] chan_sip.c: Peer ‘60025’ is now Reachable. (9ms / 2000ms)
[May 5 23:25:25] NOTICE[1847] chan_sip.c: Correct auth, but based on stale nonce received from ‘“60025” sip:60025@192.168.0.204’
[May 5 23:25:25] NOTICE[1847] chan_sip.c: Correct auth, but based on stale nonce received from ‘“60025” sip:60025@192.168.0.204’
[May 5 23:25:25] NOTICE[1847] chan_sip.c: Correct auth, but based on stale nonce received from ‘“60025” sip:60025@192.168.0.204’
[May 5 23:25:25] NOTICE[1847] chan_sip.c: Correct auth, but based on stale nonce received from ‘“60025” sip:60025@192.168.0.204’
[May 5 23:25:25] NOTICE[1847] chan_sip.c: Correct auth, but based on stale nonce received from ‘“60025” sip:60025@192.168.0.204’
[May 5 23:31:24] NOTICE[1847] chan_sip.c: Correct auth, but based on stale nonce received from ‘“60025” sip:60025@192.168.0.204’
[May 5 23:31:24] NOTICE[1847] chan_sip.c: Correct auth, but based on stale nonce received from ‘“60025” sip:60025@192.168.0.204’
[May 5 23:31:24] NOTICE[1847] chan_sip.c: Correct auth, but based on stale nonce received from ‘“60025” sip:60025@192.168.0.204’
[May 5 23:34:24] NOTICE[1847] chan_sip.c: Correct auth, but based on stale nonce received from ‘“60025” sip:60025@192.168.0.204’
[May 5 23:34:24] NOTICE[1847] chan_sip.c: Correct auth, but based on stale nonce received from ‘“60025” sip:60025@192.168.0.204’
[May 5 23:37:25] NOTICE[1847] chan_sip.c: Correct auth, but based on stale nonce received from ‘“60025” sip:60025@192.168.0.204’
[May 5 23:37:25] NOTICE[1847] chan_sip.c: Correct auth, but based on stale nonce received from ‘“60025” sip:60025@192.168.0.204’
[May 5 23:37:25] NOTICE[1847] chan_sip.c: Correct auth, but based on stale nonce received from ‘“60025” sip:60025@192.168.0.204’
[May 5 23:39:47] NOTICE[1847] chan_sip.c: Peer ‘60025’ is now UNREACHABLE! Last qualify: 7
[May 5 23:40:22] NOTICE[1847] chan_sip.c: Peer ‘60025’ is now Reachable. (12ms / 2000ms)
[May 5 23:47:05] NOTICE[1847] chan_sip.c: Peer ‘60025’ is now UNREACHABLE! Last qualify: 9
[May 5 23:47:07] NOTICE[1847] chan_sip.c: Correct auth, but based on stale nonce received from ‘“60025” sip:60025@192.168.0.204’
[May 5 23:47:07] NOTICE[1847] chan_sip.c: Correct auth, but based on stale nonce received from ‘“60025” sip:60025@192.168.0.204’
[May 5 23:47:07] NOTICE[1847] chan_sip.c: Correct auth, but based on stale nonce received from ‘“60025” sip:60025@192.168.0.204’
[May 5 23:47:07] NOTICE[1847] chan_sip.c: Correct auth, but based on stale nonce received from ‘“60025” sip:60025@192.168.0.204’
[May 5 23:47:07] NOTICE[1847] chan_sip.c: Correct auth, but based on stale nonce received from ‘“60025” sip:60025@192.168.0.204’
[May 5 23:47:07] NOTICE[1847] chan_sip.c: Correct auth, but based on stale nonce received from ‘“60025” sip:60025@192.168.0.204’
[May 5 23:47:50] NOTICE[1847] chan_sip.c: Peer ‘60025’ is now Reachable. (12ms / 2000ms)
[May 5 23:53:51] NOTICE[1847] chan_sip.c: Correct auth, but based on stale nonce received from ‘“60025” sip:60025@192.168.0.204’
[May 5 23:53:51] NOTICE[1847] chan_sip.c: Correct auth, but based on stale nonce received from ‘“60025” sip:60025@192.168.0.204’
[May 5 23:53:51] NOTICE[1847] chan_sip.c: Correct auth, but based on stale nonce received from ‘“60025” sip:60025@192.168.0.204’
[May 5 23:55:13] NOTICE[1847] chan_sip.c: Peer ‘60025’ is now UNREACHABLE! Last qualify: 13
[May 5 23:55:28] NOTICE[1847] chan_sip.c: Peer ‘60025’ is now Reachable. (9ms / 2000ms)
[May 5 23:59:52] NOTICE[1847] chan_sip.c: Correct auth, but based on stale nonce received from ‘“60025” sip:60025@192.168.0.204’
[May 5 23:59:52] NOTICE[1847] chan_sip.c: Correct auth, but based on stale nonce received from ‘“60025” sip:60025@192.168.0.204’
[May 5 23:59:52] NOTICE[1847] chan_sip.c: Correct auth, but based on stale nonce received from ‘“60025” sip:60025@192.168.0.204’
[May 6 00:18:18] NOTICE[1847] chan_sip.c: Peer ‘60025’ is now UNREACHABLE! Last qualify: 6
[May 6 00:18:25] NOTICE[1847] chan_sip.c: Correct auth, but based on stale nonce received from ‘“60025” sip:60025@192.168.0.204’
[May 6 00:18:25] NOTICE[1847] chan_sip.c: Correct auth, but based on stale nonce received from ‘“60025” sip:60025@192.168.0.204’
[May 6 00:18:25] NOTICE[1847] chan_sip.c: Correct auth, but based on stale nonce received from ‘“60025” sip:60025@192.168.0.204’
[May 6 00:18:25] NOTICE[1847] chan_sip.c: Correct auth, but based on stale nonce received from ‘“60025” sip:60025@192.168.0.204’
[May 6 00:18:25] NOTICE[1847] chan_sip.c: Correct auth, but based on stale nonce received from ‘“60025” sip:60025@192.168.0.204’
[May 6 00:18:25] NOTICE[1847] chan_sip.c: Correct auth, but based on stale nonce received from ‘“60025” sip:60025@192.168.0.204’
[May 6 00:18:36] NOTICE[1847] chan_sip.c: Peer ‘60025’ is now Reachable. (5ms / 2000ms)
##################
See more debug logs in attachment

system · May 6, 2010, 8:03pm

I don’t find how to post attachments. If somebody would like to help, I can send debug log files.

navaismo · May 6, 2010, 10:18pm

The message “Correct auth, but based on stale nonce received from” maybe its a problem.

I found this googling mail-archive.com/asterisk-us … 22636.html

system · May 14, 2010, 11:50pm

Hello
Thanks for replying.
I think the problem is that there are some SIP packet problems.
Do you think the reason can be something with network ? or chan_sip problem ?
Here are my debug logs (sorry, I did not find how to attach files in this forum) :

<— SIP read from UDP://YYY.YYY.YYY.YYY:5688 —>
REGISTER sip:mydomain.net SIP/2.0
Via: SIP/2.0/UDP YYY.YYY.YYY.YYY:5688;branch=z9hG4bKf4f59cd7216de3ca28412ebdb80d039b;rport
From: “John Doe” sip:60070@mydomain.net;tag=1738044141
To: “John Doe” sip:60070@mydomain.net
Call-ID: 3602491317@192_168_1_86
CSeq: 2146 REGISTER
Contact: sip:60070@YYY.YYY.YYY.YYY:5688
Max-Forwards: 70
User-Agent: C470IP021910000000
Expires: 180
Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY
Content-Length: 0

<------------->
[May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 0 [ 32]: REGISTER sip:mydomain.net SIP/2.0
[May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 1 [ 88]: Via: SIP/2.0/UDP YYY.YYY.YYY.YYY:5688;branch=z9hG4bKf4f59cd7216de3ca28412ebdb80d039b;rport
[May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 2 [ 57]: From: “John Doe” sip:60070@mydomain.net;tag=1738044141
[May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 3 [ 40]: To: “John Doe” sip:60070@mydomain.net
[May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 4 [ 32]: Call-ID: 3602491317@192_168_1_86
[May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 5 [ 19]: CSeq: 2146 REGISTER
[May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 6 [ 39]: Contact: sip:60070@YYY.YYY.YYY.YYY:5688
[May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 7 [ 16]: Max-Forwards: 70
[May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 8 [ 30]: User-Agent: C470IP021910000000
[May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 9 [ 12]: Expires: 180
[May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 10 [ 72]: Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY
[May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 11 [ 17]: Content-Length: 0
[May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 12 [ 0]:
[May 5 20:15:44] VERBOSE[1776] chan_sip.c: — (12 headers 0 lines) —
[May 5 20:15:44] DEBUG[1776] acl.c: Found IP address for this socket
[May 5 20:15:44] DEBUG[1776] chan_sip.c: Target address YYY.YYY.YYY.YYY is not local, substituting externip
[May 5 20:15:44] DEBUG[1776] chan_sip.c: Setting SIP_TRANSPORT_UDP with address XXX.XXX.XXX.XXX:5060
[May 5 20:15:44] DEBUG[1776] chan_sip.c: Allocating new SIP dialog for 3602491317@192_168_1_86 - REGISTER (No RTP)
[May 5 20:15:44] DEBUG[1776] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER
[May 5 20:15:44] DEBUG[1776] chan_sip.c: Initializing initreq for method REGISTER - callid 3602491317@192_168_1_86
[May 5 20:15:44] VERBOSE[1776] chan_sip.c: Sending to YYY.YYY.YYY.YYY : 5688 (NAT)
[May 5 20:15:44] VERBOSE[1776] chan_sip.c:
<— Transmitting (NAT) to YYY.YYY.YYY.YYY:5688 —>
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP YYY.YYY.YYY.YYY:5688;branch=z9hG4bKf4f59cd7216de3ca28412ebdb80d039b;received=YYY.YYY.YYY.YYY;rport=5688
From: “John Doe” sip:60070@mydomain.net;tag=1738044141
To: “John Doe” sip:60070@mydomain.net;tag=as7cef0651
Call-ID: 3602491317@192_168_1_86
CSeq: 2146 REGISTER
Server: EasyNUM
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO
Supported: replaces, timer
WWW-Authenticate: Digest algorithm=MD5, realm=“asterisk”, nonce="4d3eb05c"
Content-Length: 0

<------------>
[May 5 20:15:44] DEBUG[1776] chan_sip.c: Trying to put ‘SIP/2.0 401’ onto UDP socket destined for YYY.YYY.YYY.YYY:5688
[May 5 20:15:44] VERBOSE[1776] chan_sip.c: Scheduling destruction of SIP dialog ‘3602491317@192_168_1_86’ in 32000 ms (Method: REGISTER)
[May 5 20:15:44] VERBOSE[1776] chan_sip.c:

<— SIP read from UDP://YYY.YYY.YYY.YYY:5688 —>
REGISTER sip:mydomain.net SIP/2.0
Via: SIP/2.0/UDP YYY.YYY.YYY.YYY:5688;branch=z9hG4bK3f334386f3fcea0f3be53b7f783d88c;rport
From: “John Doe” sip:60070@mydomain.net;tag=1738044141
To: “John Doe” sip:60070@mydomain.net
Call-ID: 3602491317@192_168_1_86
CSeq: 2147 REGISTER
Contact: sip:60070@YYY.YYY.YYY.YYY:5688
Authorization: Digest username=“60070”, realm=“asterisk”, algorithm=MD5, uri=“sip:mydomain.net”, nonce=“4d3eb05c”, response="f17ad882b021bd517e58a509b47ff3f1"
Max-Forwards: 70
User-Agent: C470IP021910000000
Expires: 180
Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY
Content-Length: 0

<------------->
[May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 0 [ 32]: REGISTER sip:mydomain.net SIP/2.0
[May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 1 [ 87]: Via: SIP/2.0/UDP YYY.YYY.YYY.YYY:5688;branch=z9hG4bK3f334386f3fcea0f3be53b7f783d88c;rport
[May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 2 [ 57]: From: “John Doe” sip:60070@mydomain.net;tag=1738044141
[May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 3 [ 40]: To: “John Doe” sip:60070@mydomain.net
[May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 4 [ 32]: Call-ID: 3602491317@192_168_1_86
[May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 5 [ 19]: CSeq: 2147 REGISTER
[May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 6 [ 39]: Contact: sip:60070@YYY.YYY.YYY.YYY:5688
[May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 7 [157]: Authorization: Digest username=“60070”, realm=“asterisk”, algorithm=MD5, uri=“sip:mydomain.net”, nonce=“4d3eb05c”, response=“f17ad882b021bd517e58a509b47ff3f1”
[May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 8 [ 16]: Max-Forwards: 70
[May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 9 [ 30]: User-Agent: C470IP021910000000
[May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 10 [ 12]: Expires: 180
[May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 11 [ 72]: Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY
[May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 12 [ 17]: Content-Length: 0
[May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 13 [ 0]:
[May 5 20:15:44] VERBOSE[1776] chan_sip.c: — (13 headers 0 lines) —
[May 5 20:15:44] DEBUG[1776] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER
[May 5 20:15:44] DEBUG[1776] chan_sip.c: Initializing initreq for method REGISTER - callid 3602491317@192_168_1_86
[May 5 20:15:44] VERBOSE[1776] chan_sip.c: Sending to YYY.YYY.YYY.YYY : 5688 (NAT)
[May 5 20:15:44] DEBUG[1776] chan_sip.c: Allocating new SIP dialog for 15baa4a421fe50fe39bf0efd1b1e2913@127.0.0.1 - OPTIONS (No RTP)
[May 5 20:15:44] DEBUG[1776] acl.c: Found IP address for this socket
[May 5 20:15:44] DEBUG[1776] chan_sip.c: Target address YYY.YYY.YYY.YYY is not local, substituting externip
[May 5 20:15:44] DEBUG[1776] chan_sip.c: Setting SIP_TRANSPORT_UDP with address XXX.XXX.XXX.XXX:5060
[May 5 20:15:44] DEBUG[1776] chan_sip.c: Initializing initreq for method OPTIONS - callid 6d5cd6e4614786c666f8f89a7ee396ae@XXX.XXX.XXX.XXX
[May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 0 [ 44]: OPTIONS sip:60070@YYY.YYY.YYY.YYY:5688 SIP/2.0
[May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 1 [ 66]: Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5060;branch=z9hG4bK38fd7a31;rport
[May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70
[May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 3 [ 62]: From: “asterisk” sip:asterisk@XXX.XXX.XXX.XXX;tag=as5751f653
[May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 4 [ 34]: To: sip:60070@YYY.YYY.YYY.YYY:5688
[May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 5 [ 39]: Contact: sip:asterisk@XXX.XXX.XXX.XXX
[May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 6 [ 57]: Call-ID: 6d5cd6e4614786c666f8f89a7ee396ae@XXX.XXX.XXX.XXX
[May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 7 [ 17]: CSeq: 102 OPTIONS
[May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 8 [ 19]: User-Agent: EasyNUM
[May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 9 [ 35]: Date: Thu, 06 May 2010 06:15:44 GMT
[May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 10 [ 72]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO
[May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 11 [ 26]: Supported: replaces, timer
[May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 12 [ 17]: Content-Length: 0
[May 5 20:15:44] VERBOSE[1776] chan_sip.c: Reliably Transmitting (NAT) to YYY.YYY.YYY.YYY:5688:
OPTIONS sip:60070@YYY.YYY.YYY.YYY:5688 SIP/2.0
Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5060;branch=z9hG4bK38fd7a31;rport
Max-Forwards: 70
From: “asterisk” sip:asterisk@XXX.XXX.XXX.XXX;tag=as5751f653
To: sip:60070@YYY.YYY.YYY.YYY:5688
Contact: sip:asterisk@XXX.XXX.XXX.XXX
Call-ID: 6d5cd6e4614786c666f8f89a7ee396ae@XXX.XXX.XXX.XXX
CSeq: 102 OPTIONS
User-Agent: EasyNUM
Date: Thu, 06 May 2010 06:15:44 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO
Supported: replaces, timer
Content-Length: 0

[May 5 20:15:44] DEBUG[1776] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #2917
[May 5 20:15:44] DEBUG[1776] chan_sip.c: Trying to put ‘OPTIONS sip’ onto UDP socket destined for YYY.YYY.YYY.YYY:5688
[May 5 20:15:44] VERBOSE[1776] chan_sip.c:
<— Transmitting (NAT) to YYY.YYY.YYY.YYY:5688 —>
SIP/2.0 200 OK
Via: SIP/2.0/UDP YYY.YYY.YYY.YYY:5688;branch=z9hG4bK3f334386f3fcea0f3be53b7f783d88c;received=YYY.YYY.YYY.YYY;rport=5688
From: “John Doe” sip:60070@mydomain.net;tag=1738044141
To: “John Doe” sip:60070@mydomain.net;tag=as7cef0651
Call-ID: 3602491317@192_168_1_86
CSeq: 2147 REGISTER
Server: EasyNUM
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO
Supported: replaces, timer
Expires: 180
Contact: sip:60070@YYY.YYY.YYY.YYY:5688;expires=180
Date: Thu, 06 May 2010 06:15:44 GMT
Content-Length: 0

<------------>
[May 5 20:15:44] DEBUG[1776] chan_sip.c: Trying to put ‘SIP/2.0 200’ onto UDP socket destined for YYY.YYY.YYY.YYY:5688
[May 5 20:15:44] VERBOSE[1776] chan_sip.c: Scheduling destruction of SIP dialog ‘3602491317@192_168_1_86’ in 32000 ms (Method: REGISTER)
[May 5 20:15:44] DEBUG[1680] devicestate.c: No provider found, checking channel drivers for SIP - 60070
[May 5 20:15:44] DEBUG[1680] chan_sip.c: Checking device state for peer 60070
[May 5 20:15:44] DEBUG[1680] devicestate.c: Changing state for SIP/60070 - state 1 (Not in use)
[May 5 20:15:44] DEBUG[1680] devicestate.c: device ‘SIP/60070’ state ‘1’
[May 5 20:15:44] DEBUG[1761] app_queue.c: Device ‘SIP/60070’ changed to state ‘1’ (Not in use) but we don’t care because they’re not a member of any queue.
[May 5 20:15:44] VERBOSE[1776] chan_sip.c:

<— SIP read from UDP://YYY.YYY.YYY.YYY:5688 —>
SIP/2.0 200 OK
Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5060;branch=z9hG4bK38fd7a31;rport=5060
From: “asterisk” sip:asterisk@XXX.XXX.XXX.XXX;tag=as5751f653
To: sip:60070@YYY.YYY.YYY.YYY:5688;tag=4105476699
Call-ID: 6d5cd6e4614786c666f8f89a7ee396ae@XXX.XXX.XXX.XXX
CSeq: 102 OPTIONS
Contact: sip:60070@YYY.YYY.YYY.YYY:5688
Supported: replaces
Allow-Events: refer
Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY
Accept: application/sdp,application/dtmf-relay,application/simple-message-summary,message/sipfrag
Accept-Encoding: identity
Accept-Language: en
Content-Length: 0

<------------->
[May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 0 [ 14]: SIP/2.0 200 OK
[May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 1 [ 71]: Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5060;branch=z9hG4bK38fd7a31;rport=5060
[May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 2 [ 62]: From: “asterisk” sip:asterisk@XXX.XXX.XXX.XXX;tag=as5751f653
[May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 3 [ 49]: To: sip:60070@YYY.YYY.YYY.YYY:5688;tag=4105476699
[May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 4 [ 57]: Call-ID: 6d5cd6e4614786c666f8f89a7ee396ae@XXX.XXX.XXX.XXX
[May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 5 [ 17]: CSeq: 102 OPTIONS
[May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 6 [ 39]: Contact: sip:60070@YYY.YYY.YYY.YYY:5688
[May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 7 [ 19]: Supported: replaces
[May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 8 [ 19]: Allow-Events: refer
[May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 9 [ 72]: Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY
[May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 10 [ 97]: Accept: application/sdp,application/dtmf-relay,application/simple-message-summary,message/sipfrag
[May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 11 [ 25]: Accept-Encoding: identity
[May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 12 [ 19]: Accept-Language: en
[May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 13 [ 17]: Content-Length: 0
[May 5 20:15:44] DEBUG[1776] chan_sip.c: Header 14 [ 0]:
[May 5 20:15:44] VERBOSE[1776] chan_sip.c: — (14 headers 0 lines) —
[May 5 20:15:44] DEBUG[1776] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #2917
[May 5 20:15:44] DEBUG[1776] chan_sip.c: Stopping retransmission on '6d5cd6e4614786c666f8f89a7ee396ae@XXX.XXX.XXX.XXX’ of Request 102: Match Found
[May 5 20:15:44] DEBUG[1776] chan_sip.c: Destroying SIP dialog 6d5cd6e4614786c666f8f89a7ee396ae@XXX.XXX.XXX.XXX
[May 5 20:15:44] VERBOSE[1776] chan_sip.c: Really destroying SIP dialog '6d5cd6e4614786c666f8f89a7ee396ae@XXX.XXX.XXX.XXX’ Method: OPTIONS
[May 5 20:15:44] VERBOSE[1776] chan_sip.c:

[May 5 20:16:44] DEBUG[1776] chan_sip.c: Header 0 [ 44]: OPTIONS sip:60070@YYY.YYY.YYY.YYY:5688 SIP/2.0
[May 5 20:16:44] DEBUG[1776] chan_sip.c: Header 1 [ 66]: Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5060;branch=z9hG4bK408e5281;rport
[May 5 20:16:44] DEBUG[1776] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70
[May 5 20:16:44] DEBUG[1776] chan_sip.c: Header 3 [ 62]: From: “asterisk” sip:asterisk@XXX.XXX.XXX.XXX;tag=as2e2eba50
[May 5 20:16:44] DEBUG[1776] chan_sip.c: Header 4 [ 34]: To: sip:60070@YYY.YYY.YYY.YYY:5688
[May 5 20:16:44] DEBUG[1776] chan_sip.c: Header 5 [ 39]: Contact: sip:asterisk@XXX.XXX.XXX.XXX
[May 5 20:16:44] DEBUG[1776] chan_sip.c: Header 6 [ 57]: Call-ID: 30262435108f6e1d1a13b2ca076a0785@XXX.XXX.XXX.XXX
[May 5 20:16:44] DEBUG[1776] chan_sip.c: Header 7 [ 17]: CSeq: 102 OPTIONS
[May 5 20:16:44] DEBUG[1776] chan_sip.c: Header 8 [ 19]: User-Agent: EasyNUM
[May 5 20:16:44] DEBUG[1776] chan_sip.c: Header 9 [ 35]: Date: Thu, 06 May 2010 06:16:44 GMT
[May 5 20:16:44] DEBUG[1776] chan_sip.c: Header 10 [ 72]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO
[May 5 20:16:44] DEBUG[1776] chan_sip.c: Header 11 [ 26]: Supported: replaces, timer
[May 5 20:16:44] DEBUG[1776] chan_sip.c: Header 12 [ 17]: Content-Length: 0
[May 5 20:16:44] VERBOSE[1776] chan_sip.c: Reliably Transmitting (NAT) to YYY.YYY.YYY.YYY:5688:
OPTIONS sip:60070@YYY.YYY.YYY.YYY:5688 SIP/2.0
Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5060;branch=z9hG4bK408e5281;rport
Max-Forwards: 70
From: “asterisk” sip:asterisk@XXX.XXX.XXX.XXX;tag=as2e2eba50
To: sip:60070@YYY.YYY.YYY.YYY:5688
Contact: sip:asterisk@XXX.XXX.XXX.XXX
Call-ID: 30262435108f6e1d1a13b2ca076a0785@XXX.XXX.XXX.XXX
CSeq: 102 OPTIONS
User-Agent: EasyNUM
Date: Thu, 06 May 2010 06:16:44 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO
Supported: replaces, timer
Content-Length: 0

<— SIP read from UDP://YYY.YYY.YYY.YYY:5688 —>
SIP/2.0 200 OK
Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5060;branch=z9hG4bK408e5281;rport=5060
From: “asterisk” sip:asterisk@XXX.XXX.XXX.XXX;tag=as2e2eba50
To: sip:60070@YYY.YYY.YYY.YYY:5688;tag=865471299
Call-ID: 30262435108f6e1d1a13b2ca076a0785@XXX.XXX.XXX.XXX
CSeq: 102 OPTIONS
Contact: sip:60070@YYY.YYY.YYY.YYY:5688
Supported: replaces
Allow-Events: refer
Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY
Accept: application/sdp,application/dtmf-relay,application/simple-message-summary,message/sipfrag
Accept-Encoding: identity
Accept-Language: en
Content-Length: 0

<------------->
[May 5 20:16:44] DEBUG[1776] chan_sip.c: Header 0 [ 14]: SIP/2.0 200 OK
[May 5 20:16:44] DEBUG[1776] chan_sip.c: Header 1 [ 71]: Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5060;branch=z9hG4bK408e5281;rport=5060
[May 5 20:16:44] DEBUG[1776] chan_sip.c: Header 2 [ 62]: From: “asterisk” sip:asterisk@XXX.XXX.XXX.XXX;tag=as2e2eba50
[May 5 20:16:44] DEBUG[1776] chan_sip.c: Header 3 [ 48]: To: sip:60070@YYY.YYY.YYY.YYY:5688;tag=865471299
[May 5 20:16:44] DEBUG[1776] chan_sip.c: Header 4 [ 57]: Call-ID: 30262435108f6e1d1a13b2ca076a0785@XXX.XXX.XXX.XXX
[May 5 20:16:44] DEBUG[1776] chan_sip.c: Header 5 [ 17]: CSeq: 102 OPTIONS
[May 5 20:16:44] DEBUG[1776] chan_sip.c: Header 6 [ 39]: Contact: sip:60070@YYY.YYY.YYY.YYY:5688
[May 5 20:16:44] DEBUG[1776] chan_sip.c: Header 7 [ 19]: Supported: replaces
[May 5 20:16:44] DEBUG[1776] chan_sip.c: Header 8 [ 19]: Allow-Events: refer
[May 5 20:16:44] DEBUG[1776] chan_sip.c: Header 9 [ 72]: Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY
[May 5 20:16:44] DEBUG[1776] chan_sip.c: Header 10 [ 97]: Accept: application/sdp,application/dtmf-relay,application/simple-message-summary,message/sipfrag
[May 5 20:16:44] DEBUG[1776] chan_sip.c: Header 11 [ 25]: Accept-Encoding: identity
[May 5 20:16:44] DEBUG[1776] chan_sip.c: Header 12 [ 19]: Accept-Language: en
[May 5 20:16:44] DEBUG[1776] chan_sip.c: Header 13 [ 17]: Content-Length: 0
[May 5 20:16:44] DEBUG[1776] chan_sip.c: Header 14 [ 0]:
[May 5 20:16:44] VERBOSE[1776] chan_sip.c: — (14 headers 0 lines) —
[May 5 20:16:44] DEBUG[1776] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #2998
[May 5 20:16:44] DEBUG[1776] chan_sip.c: Stopping retransmission on '30262435108f6e1d1a13b2ca076a0785@XXX.XXX.XXX.XXX’ of Request 102: Match Found
[May 5 20:16:44] DEBUG[1776] chan_sip.c: Destroying SIP dialog 30262435108f6e1d1a13b2ca076a0785@XXX.XXX.XXX.XXX
[May 5 20:16:44] VERBOSE[1776] chan_sip.c: Really destroying SIP dialog '30262435108f6e1d1a13b2ca076a0785@XXX.XXX.XXX.XXX’ Method: OPTIONS
[May 5 20:16:45] VERBOSE[1776] chan_sip.c:

[May 5 20:17:44] DEBUG[1776] chan_sip.c: Allocating new SIP dialog for 6ecf70765da6a4d63e0ab140610b0a4e@127.0.0.1 - OPTIONS (No RTP)
[May 5 20:17:44] DEBUG[1776] acl.c: Found IP address for this socket
[May 5 20:17:44] DEBUG[1776] chan_sip.c: Target address YYY.YYY.YYY.YYY is not local, substituting externip
[May 5 20:17:44] DEBUG[1776] chan_sip.c: Setting SIP_TRANSPORT_UDP with address XXX.XXX.XXX.XXX:5060
[May 5 20:17:44] DEBUG[1776] chan_sip.c: Initializing initreq for method OPTIONS - callid 41605cf27ec2fd163f28dadf41cb9d78@XXX.XXX.XXX.XXX
[May 5 20:17:44] DEBUG[1776] chan_sip.c: Header 0 [ 44]: OPTIONS sip:60070@YYY.YYY.YYY.YYY:5688 SIP/2.0
[May 5 20:17:44] DEBUG[1776] chan_sip.c: Header 1 [ 66]: Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5060;branch=z9hG4bK28a4c2b8;rport
[May 5 20:17:44] DEBUG[1776] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70
[May 5 20:17:44] DEBUG[1776] chan_sip.c: Header 3 [ 62]: From: “asterisk” sip:asterisk@XXX.XXX.XXX.XXX;tag=as02ca55e2
[May 5 20:17:44] DEBUG[1776] chan_sip.c: Header 4 [ 34]: To: sip:60070@YYY.YYY.YYY.YYY:5688
[May 5 20:17:44] DEBUG[1776] chan_sip.c: Header 5 [ 39]: Contact: sip:asterisk@XXX.XXX.XXX.XXX
[May 5 20:17:44] DEBUG[1776] chan_sip.c: Header 6 [ 57]: Call-ID: 41605cf27ec2fd163f28dadf41cb9d78@XXX.XXX.XXX.XXX
[May 5 20:17:44] DEBUG[1776] chan_sip.c: Header 7 [ 17]: CSeq: 102 OPTIONS
[May 5 20:17:44] DEBUG[1776] chan_sip.c: Header 8 [ 19]: User-Agent: EasyNUM
[May 5 20:17:44] DEBUG[1776] chan_sip.c: Header 9 [ 35]: Date: Thu, 06 May 2010 06:17:44 GMT
[May 5 20:17:44] DEBUG[1776] chan_sip.c: Header 10 [ 72]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO
[May 5 20:17:44] DEBUG[1776] chan_sip.c: Header 11 [ 26]: Supported: replaces, timer
[May 5 20:17:44] DEBUG[1776] chan_sip.c: Header 12 [ 17]: Content-Length: 0
[May 5 20:17:44] VERBOSE[1776] chan_sip.c: Reliably Transmitting (NAT) to YYY.YYY.YYY.YYY:5688:
OPTIONS sip:60070@YYY.YYY.YYY.YYY:5688 SIP/2.0
Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5060;branch=z9hG4bK28a4c2b8;rport
Max-Forwards: 70
From: “asterisk” sip:asterisk@XXX.XXX.XXX.XXX;tag=as02ca55e2
To: sip:60070@YYY.YYY.YYY.YYY:5688
Contact: sip:asterisk@XXX.XXX.XXX.XXX
Call-ID: 41605cf27ec2fd163f28dadf41cb9d78@XXX.XXX.XXX.XXX
CSeq: 102 OPTIONS
User-Agent: EasyNUM
Date: Thu, 06 May 2010 06:17:44 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO
Supported: replaces, timer
Content-Length: 0

[May 5 20:17:44] DEBUG[1776] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #3051
[May 5 20:17:44] DEBUG[1776] chan_sip.c: Trying to put ‘OPTIONS sip’ onto UDP socket destined for YYY.YYY.YYY.YYY:5688
[May 5 20:17:45] VERBOSE[1776] chan_sip.c:

<— SIP read from UDP://YYY.YYY.YYY.YYY:5688 —>
SIP/2.0 200 OK
Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5060;branch=z9hG4bK28a4c2b8;rport=5060
From: “asterisk” sip:asterisk@XXX.XXX.XXX.XXX;tag=as02ca55e2
To: sip:60070@YYY.YYY.YYY.YYY:5688;tag=2926259639
Call-ID: 41605cf27ec2fd163f28dadf41cb9d78@XXX.XXX.XXX.XXX
CSeq: 102 OPTIONS
Contact: sip:60070@YYY.YYY.YYY.YYY:5688
Supported: replaces
Allow-Events: refer
Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY
Accept: application/sdp,application/dtmf-relay,application/simple-message-summary,message/sipfrag
Accept-Encoding: identity
Accept-Language: en
Content-Length: 0

<------------->
[May 5 20:17:45] DEBUG[1776] chan_sip.c: Header 0 [ 14]: SIP/2.0 200 OK
[May 5 20:17:45] DEBUG[1776] chan_sip.c: Header 1 [ 71]: Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5060;branch=z9hG4bK28a4c2b8;rport=5060
[May 5 20:17:45] DEBUG[1776] chan_sip.c: Header 2 [ 62]: From: “asterisk” sip:asterisk@XXX.XXX.XXX.XXX;tag=as02ca55e2
[May 5 20:17:45] DEBUG[1776] chan_sip.c: Header 3 [ 49]: To: sip:60070@YYY.YYY.YYY.YYY:5688;tag=2926259639
[May 5 20:17:45] DEBUG[1776] chan_sip.c: Header 4 [ 57]: Call-ID: 41605cf27ec2fd163f28dadf41cb9d78@XXX.XXX.XXX.XXX
[May 5 20:17:45] DEBUG[1776] chan_sip.c: Header 5 [ 17]: CSeq: 102 OPTIONS
[May 5 20:17:45] DEBUG[1776] chan_sip.c: Header 6 [ 39]: Contact: sip:60070@YYY.YYY.YYY.YYY:5688
[May 5 20:17:45] DEBUG[1776] chan_sip.c: Header 7 [ 19]: Supported: replaces
[May 5 20:17:45] DEBUG[1776] chan_sip.c: Header 8 [ 19]: Allow-Events: refer
[May 5 20:17:45] DEBUG[1776] chan_sip.c: Header 9 [ 72]: Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY
[May 5 20:17:45] DEBUG[1776] chan_sip.c: Header 10 [ 97]: Accept: application/sdp,application/dtmf-relay,application/simple-message-summary,message/sipfrag
[May 5 20:17:45] DEBUG[1776] chan_sip.c: Header 11 [ 25]: Accept-Encoding: identity
[May 5 20:17:45] DEBUG[1776] chan_sip.c: Header 12 [ 19]: Accept-Language: en
[May 5 20:17:45] DEBUG[1776] chan_sip.c: Header 13 [ 17]: Content-Length: 0
[May 5 20:17:45] DEBUG[1776] chan_sip.c: Header 14 [ 0]:
[May 5 20:17:45] VERBOSE[1776] chan_sip.c: — (14 headers 0 lines) —
[May 5 20:17:45] DEBUG[1776] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #3051
[May 5 20:17:45] DEBUG[1776] chan_sip.c: Stopping retransmission on '41605cf27ec2fd163f28dadf41cb9d78@XXX.XXX.XXX.XXX’ of Request 102: Match Found
[May 5 20:17:45] DEBUG[1776] chan_sip.c: Destroying SIP dialog 41605cf27ec2fd163f28dadf41cb9d78@XXX.XXX.XXX.XXX
[May 5 20:17:45] VERBOSE[1776] chan_sip.c: Really destroying SIP dialog '41605cf27ec2fd163f28dadf41cb9d78@XXX.XXX.XXX.XXX’ Method: OPTIONS
[May 5 20:17:45] VERBOSE[1776] chan_sip.c:

[May 5 20:18:59] DEBUG[1776] chan_sip.c: Setting SIP_TRANSPORT_UDP with address XXX.XXX.XXX.XXX:5060
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Initializing initreq for method OPTIONS - callid 59e59e6958364d436fa475d34ea916a8@XXX.XXX.XXX.XXX
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 0 [ 44]: OPTIONS sip:60070@YYY.YYY.YYY.YYY:5688 SIP/2.0
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 1 [ 66]: Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5060;branch=z9hG4bK53abb295;rport
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 3 [ 62]: From: “asterisk” sip:asterisk@XXX.XXX.XXX.XXX;tag=as2c10d6b0
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 4 [ 34]: To: sip:60070@YYY.YYY.YYY.YYY:5688
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 5 [ 39]: Contact: sip:asterisk@XXX.XXX.XXX.XXX
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 6 [ 57]: Call-ID: 59e59e6958364d436fa475d34ea916a8@XXX.XXX.XXX.XXX
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 7 [ 17]: CSeq: 102 OPTIONS
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 8 [ 19]: User-Agent: EasyNUM
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 9 [ 35]: Date: Thu, 06 May 2010 06:18:59 GMT
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 10 [ 72]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 11 [ 26]: Supported: replaces, timer
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 12 [ 17]: Content-Length: 0
[May 5 20:18:59] VERBOSE[1776] chan_sip.c: Reliably Transmitting (NAT) to YYY.YYY.YYY.YYY:5688:
OPTIONS sip:60070@YYY.YYY.YYY.YYY:5688 SIP/2.0
Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5060;branch=z9hG4bK53abb295;rport
Max-Forwards: 70
From: “asterisk” sip:asterisk@XXX.XXX.XXX.XXX;tag=as2c10d6b0
To: sip:60070@YYY.YYY.YYY.YYY:5688
Contact: sip:asterisk@XXX.XXX.XXX.XXX
Call-ID: 59e59e6958364d436fa475d34ea916a8@XXX.XXX.XXX.XXX
CSeq: 102 OPTIONS
User-Agent: EasyNUM
Date: Thu, 06 May 2010 06:18:59 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO
Supported: replaces, timer
Content-Length: 0

[May 5 20:18:59] DEBUG[1680] devicestate.c: No provider found, checking channel drivers for SIP - 60070
[May 5 20:18:59] DEBUG[1680] chan_sip.c: Checking device state for peer 60070
[May 5 20:18:59] DEBUG[1680] devicestate.c: Changing state for SIP/60070 - state 5 (Unavailable)
[May 5 20:18:59] DEBUG[1680] devicestate.c: device ‘SIP/60070’ state ‘5’
[May 5 20:18:59] DEBUG[1761] app_queue.c: Device ‘SIP/60070’ changed to state ‘5’ (Unavailable) but we don’t care because they’re not a member of any queue.

[May 5 20:18:59] VERBOSE[1776] chan_sip.c:
<— SIP read from UDP://YYY.YYY.YYY.YYY:5688 —>
REGISTER sip:mydomain.net SIP/2.0
Via: SIP/2.0/UDP YYY.YYY.YYY.YYY:5688;branch=z9hG4bK8fc6e30b9b2f77d89e8f2811e58c72c7;rport
From: “John Doe” sip:60070@mydomain.net;tag=1973517905
To: “John Doe” sip:60070@mydomain.net
Call-ID: 3602491317@192_168_1_86
CSeq: 2148 REGISTER
Contact: sip:60070@YYY.YYY.YYY.YYY:5688
Max-Forwards: 70
User-Agent: C470IP021910000000
Expires: 180
Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY
Content-Length: 0

<------------->
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 0 [ 32]: REGISTER sip:mydomain.net SIP/2.0
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 1 [ 88]: Via: SIP/2.0/UDP YYY.YYY.YYY.YYY:5688;branch=z9hG4bK8fc6e30b9b2f77d89e8f2811e58c72c7;rport
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 2 [ 57]: From: “John Doe” sip:60070@mydomain.net;tag=1973517905
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 3 [ 40]: To: “John Doe” sip:60070@mydomain.net
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 4 [ 32]: Call-ID: 3602491317@192_168_1_86
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 5 [ 19]: CSeq: 2148 REGISTER
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 6 [ 39]: Contact: sip:60070@YYY.YYY.YYY.YYY:5688
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 7 [ 16]: Max-Forwards: 70
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 8 [ 30]: User-Agent: C470IP021910000000
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 9 [ 12]: Expires: 180
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 10 [ 72]: Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 11 [ 17]: Content-Length: 0
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 12 [ 0]:
[May 5 20:18:59] VERBOSE[1776] chan_sip.c: — (12 headers 0 lines) —
[May 5 20:18:59] DEBUG[1776] acl.c: Found IP address for this socket
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Target address YYY.YYY.YYY.YYY is not local, substituting externip
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Setting SIP_TRANSPORT_UDP with address XXX.XXX.XXX.XXX:5060
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Allocating new SIP dialog for 3602491317@192_168_1_86 - REGISTER (No RTP)
[May 5 20:18:59] DEBUG[1776] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Initializing initreq for method REGISTER - callid 3602491317@192_168_1_86
[May 5 20:18:59] VERBOSE[1776] chan_sip.c: Sending to YYY.YYY.YYY.YYY : 5688 (NAT)
[May 5 20:18:59] VERBOSE[1776] chan_sip.c:
<— Transmitting (NAT) to YYY.YYY.YYY.YYY:5688 —>
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP YYY.YYY.YYY.YYY:5688;branch=z9hG4bK8fc6e30b9b2f77d89e8f2811e58c72c7;received=YYY.YYY.YYY.YYY;rport=5688
From: “John Doe” sip:60070@mydomain.net;tag=1973517905
To: “John Doe” sip:60070@mydomain.net;tag=as7a152f8c
Call-ID: 3602491317@192_168_1_86
CSeq: 2148 REGISTER
Server: EasyNUM
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO
Supported: replaces, timer
WWW-Authenticate: Digest algorithm=MD5, realm=“asterisk”, nonce="410049f4"
Content-Length: 0

<------------>
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Trying to put ‘SIP/2.0 401’ onto UDP socket destined for YYY.YYY.YYY.YYY:5688
[May 5 20:18:59] VERBOSE[1776] chan_sip.c: Scheduling destruction of SIP dialog ‘3602491317@192_168_1_86’ in 32000 ms (Method: REGISTER)
[May 5 20:18:59] VERBOSE[1776] chan_sip.c:

[May 5 20:18:59] VERBOSE[1776] chan_sip.c:
<— SIP read from UDP://YYY.YYY.YYY.YYY:5688 —>
REGISTER sip:mydomain.net SIP/2.0
Via: SIP/2.0/UDP YYY.YYY.YYY.YYY:5688;branch=z9hG4bK8fc6e30b9b2f77d89e8f2811e58c72c7;rport
From: “John Doe” sip:60070@mydomain.net;tag=1973517905
To: “John Doe” sip:60070@mydomain.net
Call-ID: 3602491317@192_168_1_86
CSeq: 2148 REGISTER
Contact: sip:60070@YYY.YYY.YYY.YYY:5688
Max-Forwards: 70
User-Agent: C470IP021910000000
Expires: 180
Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY
Content-Length: 0

<------------->
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 0 [ 32]: REGISTER sip:mydomain.net SIP/2.0
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 1 [ 88]: Via: SIP/2.0/UDP YYY.YYY.YYY.YYY:5688;branch=z9hG4bK8fc6e30b9b2f77d89e8f2811e58c72c7;rport
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 2 [ 57]: From: “John Doe” sip:60070@mydomain.net;tag=1973517905
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 3 [ 40]: To: “John Doe” sip:60070@mydomain.net
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 4 [ 32]: Call-ID: 3602491317@192_168_1_86
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 5 [ 19]: CSeq: 2148 REGISTER
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 6 [ 39]: Contact: sip:60070@YYY.YYY.YYY.YYY:5688
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 7 [ 16]: Max-Forwards: 70
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 8 [ 30]: User-Agent: C470IP021910000000
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 9 [ 12]: Expires: 180
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 10 [ 72]: Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 11 [ 17]: Content-Length: 0
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 12 [ 0]:
[May 5 20:18:59] VERBOSE[1776] chan_sip.c: — (12 headers 0 lines) —
[May 5 20:18:59] DEBUG[1776] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Ignoring SIP message because of retransmit (REGISTER Seqno 2148, ours 2148)
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Initializing initreq for method REGISTER - callid 3602491317@192_168_1_86
[May 5 20:18:59] VERBOSE[1776] chan_sip.c: Sending to YYY.YYY.YYY.YYY : 5688 (NAT)
[May 5 20:18:59] VERBOSE[1776] chan_sip.c:
<— Transmitting (NAT) to YYY.YYY.YYY.YYY:5688 —>
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP YYY.YYY.YYY.YYY:5688;branch=z9hG4bK8fc6e30b9b2f77d89e8f2811e58c72c7;received=YYY.YYY.YYY.YYY;rport=5688
From: “John Doe” sip:60070@mydomain.net;tag=1973517905
To: “John Doe” sip:60070@mydomain.net;tag=as7a152f8c
Call-ID: 3602491317@192_168_1_86
CSeq: 2148 REGISTER
Server: EasyNUM

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO
Supported: replaces, timer
WWW-Authenticate: Digest algorithm=MD5, realm=“asterisk”, nonce="410049f4"
Content-Length: 0

<------------>
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Trying to put ‘SIP/2.0 401’ onto UDP socket destined for YYY.YYY.YYY.YYY:5688
[May 5 20:18:59] VERBOSE[1776] chan_sip.c: Scheduling destruction of SIP dialog ‘3602491317@192_168_1_86’ in 32000 ms (Method: REGISTER)
[May 5 20:18:59] VERBOSE[1776] chan_sip.c:

[May 5 20:18:59] VERBOSE[1776] chan_sip.c:
<— SIP read from UDP://YYY.YYY.YYY.YYY:5688 —>
REGISTER sip:mydomain.net SIP/2.0
Via: SIP/2.0/UDP YYY.YYY.YYY.YYY:5688;branch=z9hG4bK8fc6e30b9b2f77d89e8f2811e58c72c7;rport
From: “John Doe” sip:60070@mydomain.net;tag=1973517905
To: “John Doe” sip:60070@mydomain.net
Call-ID: 3602491317@192_168_1_86
CSeq: 2148 REGISTER
Contact: sip:60070@YYY.YYY.YYY.YYY:5688
Max-Forwards: 70
User-Agent: C470IP021910000000
Expires: 180
Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY
Content-Length: 0

<------------->
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 0 [ 32]: REGISTER sip:mydomain.net SIP/2.0
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 1 [ 88]: Via: SIP/2.0/UDP YYY.YYY.YYY.YYY:5688;branch=z9hG4bK8fc6e30b9b2f77d89e8f2811e58c72c7;rport
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 2 [ 57]: From: “John Doe” sip:60070@mydomain.net;tag=1973517905
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 3 [ 40]: To: “John Doe” sip:60070@mydomain.net
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 4 [ 32]: Call-ID: 3602491317@192_168_1_86
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 5 [ 19]: CSeq: 2148 REGISTER
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 6 [ 39]: Contact: sip:60070@YYY.YYY.YYY.YYY:5688
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 7 [ 16]: Max-Forwards: 70
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 8 [ 30]: User-Agent: C470IP021910000000
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 9 [ 12]: Expires: 180
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 10 [ 72]: Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 11 [ 17]: Content-Length: 0
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 12 [ 0]:
[May 5 20:18:59] VERBOSE[1776] chan_sip.c: — (12 headers 0 lines) —
[May 5 20:18:59] DEBUG[1776] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Ignoring SIP message because of retransmit (REGISTER Seqno 2148, ours 2148)
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Initializing initreq for method REGISTER - callid 3602491317@192_168_1_86
[May 5 20:18:59] VERBOSE[1776] chan_sip.c: Sending to YYY.YYY.YYY.YYY : 5688 (NAT)
[May 5 20:18:59] VERBOSE[1776] chan_sip.c:
<— Transmitting (NAT) to YYY.YYY.YYY.YYY:5688 —>
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP YYY.YYY.YYY.YYY:5688;branch=z9hG4bK8fc6e30b9b2f77d89e8f2811e58c72c7;received=YYY.YYY.YYY.YYY;rport=5688
From: “John Doe” sip:60070@mydomain.net;tag=1973517905
To: “John Doe” sip:60070@mydomain.net;tag=as7a152f8c

Call-ID: 3602491317@192_168_1_86
CSeq: 2148 REGISTER
Server: EasyNUM
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO
Supported: replaces, timer
WWW-Authenticate: Digest algorithm=MD5, realm=“asterisk”, nonce="410049f4"
Content-Length: 0

<------------>
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Trying to put ‘SIP/2.0 401’ onto UDP socket destined for YYY.YYY.YYY.YYY:5688
[May 5 20:18:59] VERBOSE[1776] chan_sip.c: Scheduling destruction of SIP dialog ‘3602491317@192_168_1_86’ in 32000 ms (Method: REGISTER)

[May 5 20:18:59] VERBOSE[1776] chan_sip.c:
<— SIP read from UDP://YYY.YYY.YYY.YYY:5688 —>
REGISTER sip:mydomain.net SIP/2.0
Via: SIP/2.0/UDP YYY.YYY.YYY.YYY:5688;branch=z9hG4bK8fc6e30b9b2f77d89e8f2811e58c72c7;rport
From: “John Doe” sip:60070@mydomain.net;tag=1973517905
To: “John Doe” sip:60070@mydomain.net
Call-ID: 3602491317@192_168_1_86
CSeq: 2148 REGISTER
Contact: sip:60070@YYY.YYY.YYY.YYY:5688
Max-Forwards: 70
User-Agent: C470IP021910000000
Expires: 180
Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY
Content-Length: 0

<------------->
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 0 [ 32]: REGISTER sip:mydomain.net SIP/2.0
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 1 [ 88]: Via: SIP/2.0/UDP YYY.YYY.YYY.YYY:5688;branch=z9hG4bK8fc6e30b9b2f77d89e8f2811e58c72c7;rport
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 2 [ 57]: From: “John Doe” sip:60070@mydomain.net;tag=1973517905
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 3 [ 40]: To: “John Doe” sip:60070@mydomain.net
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 4 [ 32]: Call-ID: 3602491317@192_168_1_86
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 5 [ 19]: CSeq: 2148 REGISTER
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 6 [ 39]: Contact: sip:60070@YYY.YYY.YYY.YYY:5688
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 7 [ 16]: Max-Forwards: 70
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 8 [ 30]: User-Agent: C470IP021910000000
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 9 [ 12]: Expires: 180
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 10 [ 72]: Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 11 [ 17]: Content-Length: 0
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Header 12 [ 0]:
[May 5 20:18:59] VERBOSE[1776] chan_sip.c: — (12 headers 0 lines) —
[May 5 20:18:59] DEBUG[1776] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Ignoring SIP message because of retransmit (REGISTER Seqno 2148, ours 2148)
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Initializing initreq for method REGISTER - callid 3602491317@192_168_1_86
[May 5 20:18:59] VERBOSE[1776] chan_sip.c: Sending to YYY.YYY.YYY.YYY : 5688 (NAT)
[May 5 20:18:59] VERBOSE[1776] chan_sip.c:
<— Transmitting (NAT) to YYY.YYY.YYY.YYY:5688 —>
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP YYY.YYY.YYY.YYY:5688;branch=z9hG4bK8fc6e30b9b2f77d89e8f2811e58c72c7;received=YYY.YYY.YYY.YYY;rport=5688
From: “John Doe” sip:60070@mydomain.net;tag=1973517905
To: “John Doe” sip:60070@mydomain.net;tag=as7a152f8c
Call-ID: 3602491317@192_168_1_86
CSeq: 2148 REGISTER
Server: EasyNUM
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO
Supported: replaces, timer
WWW-Authenticate: Digest algorithm=MD5, realm=“asterisk”, nonce="410049f4"
Content-Length: 0

<------------>
[May 5 20:18:59] DEBUG[1776] chan_sip.c: Trying to put ‘SIP/2.0 401’ onto UDP socket destined for YYY.YYY.YYY.YYY:5688
[May 5 20:18:59] VERBOSE[1776] chan_sip.c: Scheduling destruction of SIP dialog ‘3602491317@192_168_1_86’ in 32000 ms (Method: REGISTER)

[May 5 20:19:05] DEBUG[1776] chan_sip.c: SIP TIMER: Not rescheduling id #3089:OPTIONS (Method 3) (No timer T1)
[May 5 20:19:05] VERBOSE[1776] chan_sip.c: Retransmitting #1 (NAT) to YYY.YYY.YYY.YYY:5688:
OPTIONS sip:60070@YYY.YYY.YYY.YYY:5688 SIP/2.0
Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5060;branch=z9hG4bK53abb295;rport
Max-Forwards: 70
From: “asterisk” sip:asterisk@XXX.XXX.XXX.XXX;tag=as2c10d6b0
To: sip:60070@YYY.YYY.YYY.YYY:5688
Contact: sip:asterisk@XXX.XXX.XXX.XXX
Call-ID: 59e59e6958364d436fa475d34ea916a8@XXX.XXX.XXX.XXX
CSeq: 102 OPTIONS
User-Agent: EasyNUM
Date: Thu, 06 May 2010 06:18:59 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO
Supported: replaces, timer
Content-Length: 0

[May 5 20:19:05] DEBUG[1776] chan_sip.c: Trying to put ‘OPTIONS sip’ onto UDP socket destined for YYY.YYY.YYY.YYY:5688
[May 5 20:19:05] DEBUG[1776] chan_sip.c: SIP TIMER: Not rescheduling id #3091:OPTIONS (Method 3) (No timer T1)

[May 5 20:19:05] NOTICE[1776] chan_sip.c: Peer ‘60070’ is now UNREACHABLE! Last qualify: 126

[May 5 20:19:05] DEBUG[1680] devicestate.c: No provider found, checking channel drivers for SIP - 60070
[May 5 20:19:05] DEBUG[1680] chan_sip.c: Checking device state for peer 60070
[May 5 20:19:05] DEBUG[1680] devicestate.c: Changing state for SIP/60070 - state 5 (Unavailable)
[May 5 20:19:05] DEBUG[1680] devicestate.c: device ‘SIP/60070’ state ‘5’

[May 5 20:19:05] DEBUG[1761] app_queue.c: Device ‘SIP/60070’ changed to state ‘5’ (Unavailable) but we don’t care because they’re not a member of any queue.

[May 5 20:19:05] VERBOSE[1776] chan_sip.c:
<— SIP read from UDP://YYY.YYY.YYY.YYY:5688 —>
REGISTER sip:mydomain.net SIP/2.0
Via: SIP/2.0/UDP YYY.YYY.YYY.YYY:5688;branch=z9hG4bK8fc6e30b9b2f77d89e8f2811e58c72c7;rport
From: “John Doe” sip:60070@mydomain.net;tag=1973517905
To: “John Doe” sip:60070@mydomain.net
Call-ID: 3602491317@192_168_1_86
CSeq: 2148 REGISTER
Contact: sip:60070@YYY.YYY.YYY.YYY:5688
Max-Forwards: 70
User-Agent: C470IP021910000000
Expires: 180
Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY
Content-Length: 0

<------------->
[May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 0 [ 32]: REGISTER sip:mydomain.net SIP/2.0
[May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 1 [ 88]: Via: SIP/2.0/UDP YYY.YYY.YYY.YYY:5688;branch=z9hG4bK8fc6e30b9b2f77d89e8f2811e58c72c7;rport
[May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 2 [ 57]: From: “John Doe” sip:60070@mydomain.net;tag=1973517905
[May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 3 [ 40]: To: “John Doe” sip:60070@mydomain.net
[May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 4 [ 32]: Call-ID: 3602491317@192_168_1_86
[May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 5 [ 19]: CSeq: 2148 REGISTER
[May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 6 [ 39]: Contact: sip:60070@YYY.YYY.YYY.YYY:5688
[May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 7 [ 16]: Max-Forwards: 70
[May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 8 [ 30]: User-Agent: C470IP021910000000
[May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 9 [ 12]: Expires: 180
[May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 10 [ 72]: Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY
[May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 11 [ 17]: Content-Length: 0
[May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 12 [ 0]:
[May 5 20:19:05] VERBOSE[1776] chan_sip.c: — (12 headers 0 lines) —
[May 5 20:19:05] DEBUG[1776] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER
[May 5 20:19:05] DEBUG[1776] chan_sip.c: Ignoring SIP message because of retransmit (REGISTER Seqno 2148, ours 2148)
[May 5 20:19:05] DEBUG[1776] chan_sip.c: Initializing initreq for method REGISTER - callid 3602491317@192_168_1_86
[May 5 20:19:05] VERBOSE[1776] chan_sip.c: Sending to YYY.YYY.YYY.YYY : 5688 (NAT)
[May 5 20:19:05] VERBOSE[1776] chan_sip.c:
<— Transmitting (NAT) to YYY.YYY.YYY.YYY:5688 —>
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP YYY.YYY.YYY.YYY:5688;branch=z9hG4bK8fc6e30b9b2f77d89e8f2811e58c72c7;received=YYY.YYY.YYY.YYY;rport=5688
From: “John Doe” sip:60070@mydomain.net;tag=1973517905
To: “John Doe” sip:60070@mydomain.net;tag=as7a152f8c
Call-ID: 3602491317@192_168_1_86
CSeq: 2148 REGISTER
Server: EasyNUM
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO
Supported: replaces, timer
WWW-Authenticate: Digest algorithm=MD5, realm=“asterisk”, nonce="410049f4"
Content-Length: 0

<------------>
[May 5 20:19:05] DEBUG[1776] chan_sip.c: Trying to put ‘SIP/2.0 401’ onto UDP socket destined for YYY.YYY.YYY.YYY:5688
[May 5 20:19:05] VERBOSE[1776] chan_sip.c: Scheduling destruction of SIP dialog ‘3602491317@192_168_1_86’ in 32000 ms (Method: REGISTER)

[May 5 20:19:05] VERBOSE[1776] chan_sip.c:
<— SIP read from UDP://YYY.YYY.YYY.YYY:5688 —>
SIP/2.0 200 OK
Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5060;branch=z9hG4bK53abb295;rport=5060
From: “asterisk” sip:asterisk@XXX.XXX.XXX.XXX;tag=as2c10d6b0
To: sip:60070@YYY.YYY.YYY.YYY:5688;tag=1881441311
Call-ID: 59e59e6958364d436fa475d34ea916a8@XXX.XXX.XXX.XXX
CSeq: 102 OPTIONS
Contact: sip:60070@YYY.YYY.YYY.YYY:5688
Supported: replaces
Allow-Events: refer
Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY
Accept: application/sdp,application/dtmf-relay,application/simple-message-summary,message/sipfrag
Accept-Encoding: identity
Accept-Language: en
Content-Length: 0

<------------->
[May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 0 [ 14]: SIP/2.0 200 OK
[May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 1 [ 71]: Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5060;branch=z9hG4bK53abb295;rport=5060
[May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 2 [ 62]: From: “asterisk” sip:asterisk@XXX.XXX.XXX.XXX;tag=as2c10d6b0
[May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 3 [ 49]: To: sip:60070@YYY.YYY.YYY.YYY:5688;tag=1881441311
[May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 4 [ 57]: Call-ID: 59e59e6958364d436fa475d34ea916a8@XXX.XXX.XXX.XXX
[May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 5 [ 17]: CSeq: 102 OPTIONS
[May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 6 [ 39]: Contact: sip:60070@YYY.YYY.YYY.YYY:5688
[May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 7 [ 19]: Supported: replaces
[May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 8 [ 19]: Allow-Events: refer
[May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 9 [ 72]: Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY
[May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 10 [ 97]: Accept: application/sdp,application/dtmf-relay,application/simple-message-summary,message/sipfrag
[May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 11 [ 25]: Accept-Encoding: identity
[May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 12 [ 19]: Accept-Language: en
[May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 13 [ 17]: Content-Length: 0
[May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 14 [ 0]:
[May 5 20:19:05] VERBOSE[1776] chan_sip.c: — (14 headers 0 lines) —
[May 5 20:19:05] DEBUG[1776] chan_sip.c: That’s odd… Got a response on a call we dont know about. Callid 59e59e6958364d436fa475d34ea916a8@XXX.XXX.XXX.XXX
[May 5 20:19:05] DEBUG[1776] chan_sip.c: Invalid SIP message - rejected , no callid, len 607

[May 5 20:19:05] VERBOSE[1776] chan_sip.c:
<— SIP read from UDP://YYY.YYY.YYY.YYY:5688 —>
REGISTER sip:mydomain.net SIP/2.0
Via: SIP/2.0/UDP YYY.YYY.YYY.YYY:5688;branch=z9hG4bK644ab656a3d8864ec7e7bb84d072556e;rport
From: “John Doe” sip:60070@mydomain.net;tag=1973517905
To: “John Doe” sip:60070@mydomain.net
Call-ID: 3602491317@192_168_1_86
CSeq: 2149 REGISTER
Contact: sip:60070@YYY.YYY.YYY.YYY:5688
Authorization: Digest username=“60070”, realm=“asterisk”, algorithm=MD5, uri=“sip:mydomain.net”, nonce=“410049f4”, response="537735e7f7b26b4e712654a84fea42f7"
Max-Forwards: 70
User-Agent: C470IP021910000000
Expires: 180
Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY
Content-Length: 0

…

system · May 14, 2010, 11:51pm

<------------->
[May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 0 [ 32]: REGISTER sip:mydomain.net SIP/2.0
[May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 1 [ 88]: Via: SIP/2.0/UDP YYY.YYY.YYY.YYY:5688;branch=z9hG4bK644ab656a3d8864ec7e7bb84d072556e;rport
[May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 2 [ 57]: From: “John Doe” sip:60070@mydomain.net;tag=1973517905
[May 5 20:19:05] DEBUG[1761] app_queue.c: Device ‘SIP/60078’ changed to state ‘5’ (Unavailable) but we don’t care because they’re not a member of any queue.
[May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 3 [ 40]: To: “John Doe” sip:60070@mydomain.net
[May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 4 [ 32]: Call-ID: 3602491317@192_168_1_86
[May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 5 [ 19]: CSeq: 2149 REGISTER
[May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 6 [ 39]: Contact: sip:60070@YYY.YYY.YYY.YYY:5688
[May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 7 [157]: Authorization: Digest username=“60070”, realm=“asterisk”, algorithm=MD5, uri=“sip:mydomain.net”, nonce=“410049f4”, response=“537735e7f7b26b4e712654a84fea42f7”
[May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 8 [ 16]: Max-Forwards: 70
[May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 9 [ 30]: User-Agent: C470IP021910000000
[May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 10 [ 12]: Expires: 180
[May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 11 [ 72]: Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY
[May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 12 [ 17]: Content-Length: 0
[May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 13 [ 0]:
[May 5 20:19:05] VERBOSE[1776] chan_sip.c: — (13 headers 0 lines) —
[May 5 20:19:05] DEBUG[1776] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER
[May 5 20:19:05] DEBUG[1776] chan_sip.c: Initializing initreq for method REGISTER - callid 3602491317@192_168_1_86
[May 5 20:19:05] VERBOSE[1776] chan_sip.c: Sending to YYY.YYY.YYY.YYY : 5688 (NAT)
[May 5 20:19:05] VERBOSE[1776] chan_sip.c: – Registered SIP ‘60070’ at YYY.YYY.YYY.YYY port 5688
[May 5 20:19:05] DEBUG[1776] chan_sip.c: Allocating new SIP dialog for 67356ab041d35c6818cc76f466b3bbc2@127.0.0.1 - OPTIONS (No RTP)
[May 5 20:19:05] DEBUG[1776] acl.c: Found IP address for this socket
[May 5 20:19:05] DEBUG[1776] chan_sip.c: Target address YYY.YYY.YYY.YYY is not local, substituting externip
[May 5 20:19:05] DEBUG[1776] chan_sip.c: Setting SIP_TRANSPORT_UDP with address XXX.XXX.XXX.XXX:5060
[May 5 20:19:05] DEBUG[1776] chan_sip.c: Initializing initreq for method OPTIONS - callid 638cfdc20bd6f99b39cb77c0524a8337@XXX.XXX.XXX.XXX
[May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 0 [ 44]: OPTIONS sip:60070@YYY.YYY.YYY.YYY:5688 SIP/2.0

[May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 1 [ 66]: Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5060;branch=z9hG4bK0811af14;rport
[May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 2 [ 16]: Max-Forwards: 70
[May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 3 [ 62]: From: “asterisk” sip:asterisk@XXX.XXX.XXX.XXX;tag=as60d28455
[May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 4 [ 34]: To: sip:60070@YYY.YYY.YYY.YYY:5688
[May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 5 [ 39]: Contact: sip:asterisk@XXX.XXX.XXX.XXX
[May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 6 [ 57]: Call-ID: 638cfdc20bd6f99b39cb77c0524a8337@XXX.XXX.XXX.XXX
[May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 7 [ 17]: CSeq: 102 OPTIONS
[May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 8 [ 19]: User-Agent: EasyNUM
[May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 9 [ 35]: Date: Thu, 06 May 2010 06:19:05 GMT
[May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 10 [ 72]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO
[May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 11 [ 26]: Supported: replaces, timer
[May 5 20:19:05] DEBUG[1776] chan_sip.c: Header 12 [ 17]: Content-Length: 0
[May 5 20:19:05] VERBOSE[1776] chan_sip.c: Reliably Transmitting (NAT) to YYY.YYY.YYY.YYY:5688:
OPTIONS sip:60070@YYY.YYY.YYY.YYY:5688 SIP/2.0
Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5060;branch=z9hG4bK0811af14;rport
Max-Forwards: 70
From: “asterisk” sip:asterisk@XXX.XXX.XXX.XXX;tag=as60d28455
To: sip:60070@YYY.YYY.YYY.YYY:5688
Contact: sip:asterisk@XXX.XXX.XXX.XXX
Call-ID: 638cfdc20bd6f99b39cb77c0524a8337@XXX.XXX.XXX.XXX
CSeq: 102 OPTIONS
User-Agent: EasyNUM
Date: Thu, 06 May 2010 06:19:05 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO
Supported: replaces, timer
Content-Length: 0

[May 5 20:19:05] DEBUG[1776] chan_sip.c: *** SIP TIMER: Initializing retransmit timer on packet: Id #3183
[May 5 20:19:05] DEBUG[1776] chan_sip.c: Trying to put ‘OPTIONS sip’ onto UDP socket destined for YYY.YYY.YYY.YYY:5688
[May 5 20:19:05] VERBOSE[1776] chan_sip.c:
<— Transmitting (NAT) to YYY.YYY.YYY.YYY:5688 —>
SIP/2.0 200 OK
Via: SIP/2.0/UDP YYY.YYY.YYY.YYY:5688;branch=z9hG4bK644ab656a3d8864ec7e7bb84d072556e;received=YYY.YYY.YYY.YYY;rport=5688
From: “John Doe” sip:60070@mydomain.net;tag=1973517905
To: “John Doe” sip:60070@mydomain.net;tag=as7a152f8c
Call-ID: 3602491317@192_168_1_86
CSeq: 2149 REGISTER
Server: EasyNUM
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO
Supported: replaces, timer
Expires: 180
Contact: sip:60070@YYY.YYY.YYY.YYY:5688;expires=180
Date: Thu, 06 May 2010 06:19:05 GMT
Content-Length: 0

<------------>
[May 5 20:19:05] DEBUG[1776] chan_sip.c: Trying to put ‘SIP/2.0 200’ onto UDP socket destined for YYY.YYY.YYY.YYY:5688
[May 5 20:19:05] VERBOSE[1776] chan_sip.c: Scheduling destruction of SIP dialog ‘3602491317@192_168_1_86’ in 32000 ms (Method: REGISTER)
[May 5 20:19:05] DEBUG[1680] devicestate.c: No provider found, checking channel drivers for SIP - 60070
[May 5 20:19:05] DEBUG[1680] chan_sip.c: Checking device state for peer 60070
[May 5 20:19:05] DEBUG[1680] devicestate.c: Changing state for SIP/60070 - state 5 (Unavailable)
[May 5 20:19:05] DEBUG[1680] devicestate.c: device ‘SIP/60070’ state ‘5’

[May 5 20:19:05] DEBUG[1761] app_queue.c: Device ‘SIP/60070’ changed to state ‘5’ (Unavailable) but we don’t care because they’re not a member of any queue.

[May 5 20:19:06] VERBOSE[1776] chan_sip.c:
<— SIP read from UDP://YYY.YYY.YYY.YYY:5688 —>
REGISTER sip:mydomain.net SIP/2.0
Via: SIP/2.0/UDP YYY.YYY.YYY.YYY:5688;branch=z9hG4bK644ab656a3d8864ec7e7bb84d072556e;rport
From: “John Doe” sip:60070@mydomain.net;tag=1973517905
To: “John Doe” sip:60070@mydomain.net
Call-ID: 3602491317@192_168_1_86
CSeq: 2149 REGISTER
Contact: sip:60070@YYY.YYY.YYY.YYY:5688
Authorization: Digest username=“60070”, realm=“asterisk”, algorithm=MD5, uri=“sip:mydomain.net”, nonce=“410049f4”, response="537735e7f7b26b4e712654a84fea42f7"
Max-Forwards: 70
User-Agent: C470IP021910000000
Expires: 180
Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY
Content-Length: 0

<------------->
[May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 0 [ 32]: REGISTER sip:mydomain.net SIP/2.0
[May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 1 [ 88]: Via: SIP/2.0/UDP YYY.YYY.YYY.YYY:5688;branch=z9hG4bK644ab656a3d8864ec7e7bb84d072556e;rport
[May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 2 [ 57]: From: “John Doe” sip:60070@mydomain.net;tag=1973517905
[May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 3 [ 40]: To: “John Doe” sip:60070@mydomain.net
[May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 4 [ 32]: Call-ID: 3602491317@192_168_1_86
[May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 5 [ 19]: CSeq: 2149 REGISTER
[May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 6 [ 39]: Contact: sip:60070@YYY.YYY.YYY.YYY:5688
[May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 7 [157]: Authorization: Digest username=“60070”, realm=“asterisk”, algorithm=MD5, uri=“sip:mydomain.net”, nonce=“410049f4”, response=“537735e7f7b26b4e712654a84fea42f7”
[May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 8 [ 16]: Max-Forwards: 70
[May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 9 [ 30]: User-Agent: C470IP021910000000
[May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 10 [ 12]: Expires: 180
[May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 11 [ 72]: Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY
[May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 12 [ 17]: Content-Length: 0
[May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 13 [ 0]:
[May 5 20:19:06] VERBOSE[1776] chan_sip.c: — (13 headers 0 lines) —
[May 5 20:19:06] DEBUG[1776] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER
[May 5 20:19:06] DEBUG[1776] chan_sip.c: Ignoring SIP message because of retransmit (REGISTER Seqno 2149, ours 2149)
[May 5 20:19:06] DEBUG[1776] chan_sip.c: Initializing initreq for method REGISTER - callid 3602491317@192_168_1_86
[May 5 20:19:06] VERBOSE[1776] chan_sip.c: Sending to YYY.YYY.YYY.YYY : 5688 (NAT)
[May 5 20:19:06] NOTICE[1776] chan_sip.c: Correct auth, but based on stale nonce received from ‘“John Doe” sip:60070@mydomain.net’
[May 5 20:19:06] VERBOSE[1776] chan_sip.c:
<— Transmitting (NAT) to YYY.YYY.YYY.YYY:5688 —>
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP YYY.YYY.YYY.YYY:5688;branch=z9hG4bK644ab656a3d8864ec7e7bb84d072556e;received=YYY.YYY.YYY.YYY;rport=5688
From: “John Doe” sip:60070@mydomain.net;tag=1973517905
To: “John Doe” sip:60070@mydomain.net;tag=as7a152f8c
Call-ID: 3602491317@192_168_1_86
CSeq: 2149 REGISTER
Server: EasyNUM
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO
Supported: replaces, timer
WWW-Authenticate: Digest algorithm=MD5, realm=“asterisk”, nonce=“4e71df6a”, stale=true
Content-Length: 0

<------------>
[May 5 20:19:06] DEBUG[1776] chan_sip.c: Trying to put ‘SIP/2.0 401’ onto UDP socket destined for YYY.YYY.YYY.YYY:5688
[May 5 20:19:06] VERBOSE[1776] chan_sip.c: Scheduling destruction of SIP dialog ‘3602491317@192_168_1_86’ in 32000 ms (Method: REGISTER)

[May 5 20:19:06] VERBOSE[1776] chan_sip.c:
<— SIP read from UDP://YYY.YYY.YYY.YYY:5688 —>
REGISTER sip:mydomain.net SIP/2.0
Via: SIP/2.0/UDP YYY.YYY.YYY.YYY:5688;branch=z9hG4bK644ab656a3d8864ec7e7bb84d072556e;rport
From: “John Doe” sip:60070@mydomain.net;tag=1973517905
To: “John Doe” sip:60070@mydomain.net
Call-ID: 3602491317@192_168_1_86
CSeq: 2149 REGISTER
Contact: sip:60070@YYY.YYY.YYY.YYY:5688
Authorization: Digest username=“60070”, realm=“asterisk”, algorithm=MD5, uri=“sip:mydomain.net”, nonce=“410049f4”, response="537735e7f7b26b4e712654a84fea42f7"
Max-Forwards: 70
User-Agent: C470IP021910000000
Expires: 180
Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY
Content-Length: 0

<------------->
[May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 0 [ 32]: REGISTER sip:mydomain.net SIP/2.0
[May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 1 [ 88]: Via: SIP/2.0/UDP YYY.YYY.YYY.YYY:5688;branch=z9hG4bK644ab656a3d8864ec7e7bb84d072556e;rport
[May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 2 [ 57]: From: “John Doe” sip:60070@mydomain.net;tag=1973517905
[May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 3 [ 40]: To: “John Doe” sip:60070@mydomain.net
[May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 4 [ 32]: Call-ID: 3602491317@192_168_1_86
[May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 5 [ 19]: CSeq: 2149 REGISTER
[May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 6 [ 39]: Contact: sip:60070@YYY.YYY.YYY.YYY:5688
[May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 7 [157]: Authorization: Digest username=“60070”, realm=“asterisk”, algorithm=MD5, uri=“sip:mydomain.net”, nonce=“410049f4”, response=“537735e7f7b26b4e712654a84fea42f7”
[May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 8 [ 16]: Max-Forwards: 70
[May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 9 [ 30]: User-Agent: C470IP021910000000
[May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 10 [ 12]: Expires: 180
[May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 11 [ 72]: Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY
[May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 12 [ 17]: Content-Length: 0
[May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 13 [ 0]:
[May 5 20:19:06] VERBOSE[1776] chan_sip.c: — (13 headers 0 lines) —
[May 5 20:19:06] DEBUG[1776] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER
[May 5 20:19:06] DEBUG[1776] chan_sip.c: Ignoring SIP message because of retransmit (REGISTER Seqno 2149, ours 2149)
[May 5 20:19:06] DEBUG[1776] chan_sip.c: Initializing initreq for method REGISTER - callid 3602491317@192_168_1_86
[May 5 20:19:06] VERBOSE[1776] chan_sip.c: Sending to YYY.YYY.YYY.YYY : 5688 (NAT)
[May 5 20:19:06] NOTICE[1776] chan_sip.c: Correct auth, but based on stale nonce received from ‘“John Doe” sip:60070@mydomain.net’
[May 5 20:19:06] VERBOSE[1776] chan_sip.c:
<— Transmitting (NAT) to YYY.YYY.YYY.YYY:5688 —>
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP YYY.YYY.YYY.YYY:5688;branch=z9hG4bK644ab656a3d8864ec7e7bb84d072556e;received=YYY.YYY.YYY.YYY;rport=5688
From: “John Doe” sip:60070@mydomain.net;tag=1973517905
To: “John Doe” sip:60070@mydomain.net;tag=as7a152f8c
Call-ID: 3602491317@192_168_1_86
CSeq: 2149 REGISTER
Server: EasyNUM
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO
Supported: replaces, timer
WWW-Authenticate: Digest algorithm=MD5, realm=“asterisk”, nonce=“4e71df6a”, stale=true
Content-Length: 0

<------------>
[May 5 20:19:06] DEBUG[1776] chan_sip.c: Trying to put ‘SIP/2.0 401’ onto UDP socket destined for YYY.YYY.YYY.YYY:5688
[May 5 20:19:06] VERBOSE[1776] chan_sip.c: Scheduling destruction of SIP dialog ‘3602491317@192_168_1_86’ in 32000 ms (Method: REGISTER)

[May 5 20:19:06] VERBOSE[1776] chan_sip.c:
<— SIP read from UDP://YYY.YYY.YYY.YYY:5688 —>
SIP/2.0 200 OK
Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5060;branch=z9hG4bK53abb295;rport=5060
From: “asterisk” sip:asterisk@XXX.XXX.XXX.XXX;tag=as2c10d6b0
To: sip:60070@YYY.YYY.YYY.YYY:5688;tag=1881441311
Call-ID: 59e59e6958364d436fa475d34ea916a8@XXX.XXX.XXX.XXX
CSeq: 102 OPTIONS
Contact: sip:60070@YYY.YYY.YYY.YYY:5688
Supported: replaces
Allow-Events: refer
Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY
Accept: application/sdp,application/dtmf-relay,application/simple-message-summary,message/sipfrag
Accept-Encoding: identity
Accept-Language: en
Content-Length: 0

<------------->
[May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 0 [ 14]: SIP/2.0 200 OK
[May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 1 [ 71]: Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5060;branch=z9hG4bK53abb295;rport=5060
[May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 2 [ 62]: From: “asterisk” sip:asterisk@XXX.XXX.XXX.XXX;tag=as2c10d6b0
[May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 3 [ 49]: To: sip:60070@YYY.YYY.YYY.YYY:5688;tag=1881441311
[May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 4 [ 57]: Call-ID: 59e59e6958364d436fa475d34ea916a8@XXX.XXX.XXX.XXX
[May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 5 [ 17]: CSeq: 102 OPTIONS
[May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 6 [ 39]: Contact: sip:60070@YYY.YYY.YYY.YYY:5688
[May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 7 [ 19]: Supported: replaces
[May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 8 [ 19]: Allow-Events: refer
[May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 9 [ 72]: Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY
[May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 10 [ 97]: Accept: application/sdp,application/dtmf-relay,application/simple-message-summary,message/sipfrag
[May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 11 [ 25]: Accept-Encoding: identity
[May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 12 [ 19]: Accept-Language: en
[May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 13 [ 17]: Content-Length: 0
[May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 14 [ 0]:
[May 5 20:19:06] VERBOSE[1776] chan_sip.c: — (14 headers 0 lines) —
[May 5 20:19:06] DEBUG[1776] chan_sip.c: That’s odd… Got a response on a call we dont know about. Callid 59e59e6958364d436fa475d34ea916a8@XXX.XXX.XXX.XXX
[May 5 20:19:06] DEBUG[1776] chan_sip.c: Invalid SIP message - rejected , no callid, len 607

[May 5 20:19:06] VERBOSE[1776] chan_sip.c:
<— SIP read from UDP://YYY.YYY.YYY.YYY:5688 —>
SIP/2.0 200 OK
Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5060;branch=z9hG4bK0811af14;rport=5060
From: “asterisk” sip:asterisk@XXX.XXX.XXX.XXX;tag=as60d28455
To: sip:60070@YYY.YYY.YYY.YYY:5688;tag=4034954609
Call-ID: 638cfdc20bd6f99b39cb77c0524a8337@XXX.XXX.XXX.XXX
CSeq: 102 OPTIONS
Contact: sip:60070@YYY.YYY.YYY.YYY:5688
Supported: replaces
Allow-Events: refer
Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY
Accept: application/sdp,application/dtmf-relay,application/simple-message-summary,message/sipfrag
Accept-Encoding: identity
Accept-Language: en
Content-Length: 0

<------------->
[May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 0 [ 14]: SIP/2.0 200 OK
[May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 1 [ 71]: Via: SIP/2.0/UDP XXX.XXX.XXX.XXX:5060;branch=z9hG4bK0811af14;rport=5060
[May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 2 [ 62]: From: “asterisk” sip:asterisk@XXX.XXX.XXX.XXX;tag=as60d28455
[May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 3 [ 49]: To: sip:60070@YYY.YYY.YYY.YYY:5688;tag=4034954609
[May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 4 [ 57]: Call-ID: 638cfdc20bd6f99b39cb77c0524a8337@XXX.XXX.XXX.XXX
[May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 5 [ 17]: CSeq: 102 OPTIONS
[May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 6 [ 39]: Contact: sip:60070@YYY.YYY.YYY.YYY:5688
[May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 7 [ 19]: Supported: replaces
[May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 8 [ 19]: Allow-Events: refer
[May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 9 [ 72]: Allow: INVITE, ACK, CANCEL, BYE, OPTIONS, INFO, REFER, SUBSCRIBE, NOTIFY
[May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 10 [ 97]: Accept: application/sdp,application/dtmf-relay,application/simple-message-summary,message/sipfrag
[May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 11 [ 25]: Accept-Encoding: identity
[May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 12 [ 19]: Accept-Language: en
[May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 13 [ 17]: Content-Length: 0
[May 5 20:19:06] DEBUG[1776] chan_sip.c: Header 14 [ 0]:
[May 5 20:19:06] VERBOSE[1776] chan_sip.c: — (14 headers 0 lines) —
[May 5 20:19:06] DEBUG[1776] chan_sip.c: ** SIP TIMER: Cancelling retransmit of packet (reply received) Retransid #3183
[May 5 20:19:06] DEBUG[1776] chan_sip.c: Stopping retransmission on '638cfdc20bd6f99b39cb77c0524a8337@XXX.XXX.XXX.XXX’ of Request 102: Match Found
[May 5 20:19:06] NOTICE[1776] chan_sip.c: Peer ‘60070’ is now Reachable. (789ms / 2000ms)
[May 5 20:19:06] DEBUG[1776] chan_sip.c: Destroying SIP dialog 638cfdc20bd6f99b39cb77c0524a8337@XXX.XXX.XXX.XXX
[May 5 20:19:06] DEBUG[1680] devicestate.c: No provider found, checking channel drivers for SIP - 60070
[May 5 20:19:06] DEBUG[1680] chan_sip.c: Checking device state for peer 60070
[May 5 20:19:06] DEBUG[1680] devicestate.c: Changing state for SIP/60070 - state 1 (Not in use)
[May 5 20:19:06] VERBOSE[1776] chan_sip.c: Really destroying SIP dialog '638cfdc20bd6f99b39cb77c0524a8337@XXX.XXX.XXX.XXX’ Method: OPTIONS
[May 5 20:19:06] DEBUG[1680] devicestate.c: device ‘SIP/60070’ state ‘1’

[May 5 20:19:06] DEBUG[1761] app_queue.c: Device ‘SIP/60070’ changed to state ‘1’ (Not in use) but we don’t care because they’re not a member of any queue.

Topic		Replies	Views
SIP peer "UNREACHABLE" Asterisk SIP	11	2841	April 24, 2023
SIP Peer Unreachable Asterisk Support	8	1880	February 27, 2007
External sip peers unreachable Asterisk Support	2	2818	June 20, 2017
Sip/reachable-unreachable Asterisk Support	3	755	July 11, 2010
sip_poke_noanswer: Peer 'XXX' is now UNREACHABLE! Asterisk Support	7	14909	March 16, 2016

chan_sip.c: Peer 'XXX' is now UNREACHABLE

########################################### sip config : CLI> sip show settings Global Settings:

Network QoS Settings:

Network Settings:

Default Settings:

Related topics

###########################################
sip config :
CLI> sip show settings
Global Settings: