[2007-09-25 09:57:26] DEBUG[16128]: chan_iax2.c:7826 iax2_do_register: Registration created on call 19
[2007-09-25 09:57:39] 
<-- SIP read from 10.1.1.171:5060: 
REGISTER sip:10.1.1.249:5060 SIP/2.0
Via: SIP/2.0/UDP 10.1.1.171;branch=z9hG4bK1b9a63ab962028DA
From: "NOC Phone 2" <sip:7094@10.1.1.249>;tag=23039101-488E485E
To: <sip:7094@10.1.1.249>
CSeq: 1 REGISTER
Call-ID: 97b58c8d-e03ae37f-21a3b7b4@10.1.1.171
Contact: <sip:7094@10.1.1.171>;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER"
User-Agent: PolycomSoundPointIP-SPIP_301-UA/2.0.1.0291
Max-Forwards: 70
Expires: 60
Content-Length: 0

[2007-09-25 09:57:39] DEBUG[16126]: chan_sip.c:3463 parse_request: Header 0: REGISTER sip:10.1.1.249:5060 SIP/2.0 (36)
[2007-09-25 09:57:39] DEBUG[16126]: chan_sip.c:3463 parse_request: Header 1: Via: SIP/2.0/UDP 10.1.1.171;branch=z9hG4bK1b9a63ab962028DA (58)
[2007-09-25 09:57:39] DEBUG[16126]: chan_sip.c:3463 parse_request: Header 2: From: "NOC Phone 2" <sip:7094@10.1.1.249>;tag=23039101-488E485E (63)
[2007-09-25 09:57:39] DEBUG[16126]: chan_sip.c:3463 parse_request: Header 3: To: <sip:7094@10.1.1.249> (25)
[2007-09-25 09:57:39] DEBUG[16126]: chan_sip.c:3463 parse_request: Header 4: CSeq: 1 REGISTER (16)
[2007-09-25 09:57:39] DEBUG[16126]: chan_sip.c:3463 parse_request: Header 5: Call-ID: 97b58c8d-e03ae37f-21a3b7b4@10.1.1.171 (46)
[2007-09-25 09:57:39] DEBUG[16126]: chan_sip.c:3463 parse_request: Header 6: Contact: <sip:7094@10.1.1.171>;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER" (130)
[2007-09-25 09:57:39] DEBUG[16126]: chan_sip.c:3463 parse_request: Header 7: User-Agent: PolycomSoundPointIP-SPIP_301-UA/2.0.1.0291 (54)
[2007-09-25 09:57:39] DEBUG[16126]: chan_sip.c:3463 parse_request: Header 8: Max-Forwards: 70 (16)
[2007-09-25 09:57:39] DEBUG[16126]: chan_sip.c:3463 parse_request: Header 9: Expires: 60 (11)
[2007-09-25 09:57:39] DEBUG[16126]: chan_sip.c:3463 parse_request: Header 10: Content-Length: 0 (17)
[2007-09-25 09:57:39] --- (11 headers 0 lines) ---
[2007-09-25 09:57:39] DEBUG[16126]: chan_sip.c:3246 find_call: = Looking for  Call ID: 97b58c8d-e03ae37f-21a3b7b4@10.1.1.171 (Checking From) --From tag 23039101-488E485E --To-tag   
[2007-09-25 09:57:39] DEBUG[16126]: acl.c:211 ast_apply_ha: ##### Testing 10.1.1.171 with 192.168.0.0
[2007-09-25 09:57:39] DEBUG[16126]: acl.c:211 ast_apply_ha: ##### Testing 10.1.1.171 with 10.0.0.0
[2007-09-25 09:57:39] DEBUG[16126]: acl.c:211 ast_apply_ha: ##### Testing 10.1.1.171 with 172.16.0.0
[2007-09-25 09:57:39] DEBUG[16126]: acl.c:211 ast_apply_ha: ##### Testing 10.1.1.171 with 169.254.0.0
[2007-09-25 09:57:39] DEBUG[16126]: chan_sip.c:3198 sip_alloc: Allocating new SIP dialog for 97b58c8d-e03ae37f-21a3b7b4@10.1.1.171 - REGISTER (No RTP)
[2007-09-25 09:57:39] DEBUG[16126]: chan_sip.c:11666 handle_request: **** Received REGISTER (2) - Command in SIP REGISTER
[2007-09-25 09:57:39] Using latest REGISTER request as basis request
[2007-09-25 09:57:39] Sending to 10.1.1.171 : 5060 (NAT)
[2007-09-25 09:57:39] Transmitting (NAT) to 10.1.1.171:5060:
SIP/2.0 404 Not found
v: SIP/2.0/UDP 10.1.1.171;branch=z9hG4bK1b9a63ab962028DA;received=10.1.1.171
f: "NOC Phone 2" <sip:7094@10.1.1.249>;tag=23039101-488E485E
t: <sip:7094@10.1.1.249>;tag=as441587d0
i: 97b58c8d-e03ae37f-21a3b7b4@10.1.1.171
CSeq: 1 REGISTER
User-Agent: Royal Publishing
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
l: 0


---
[2007-09-25 09:57:39] DEBUG[16126]: chan_sip.c:3463 parse_request: Header 0: SIP/2.0 404 Not found (21)
[2007-09-25 09:57:39] DEBUG[16126]: chan_sip.c:3463 parse_request: Header 1: v: SIP/2.0/UDP 10.1.1.171;branch=z9hG4bK1b9a63ab962028DA;received=10.1.1.171 (76)
[2007-09-25 09:57:39] DEBUG[16126]: chan_sip.c:3463 parse_request: Header 2: f: "NOC Phone 2" <sip:7094@10.1.1.249>;tag=23039101-488E485E (60)
[2007-09-25 09:57:39] DEBUG[16126]: chan_sip.c:3463 parse_request: Header 3: t: <sip:7094@10.1.1.249>;tag=as441587d0 (39)
[2007-09-25 09:57:39] DEBUG[16126]: chan_sip.c:3463 parse_request: Header 4: i: 97b58c8d-e03ae37f-21a3b7b4@10.1.1.171 (40)
[2007-09-25 09:57:39] DEBUG[16126]: chan_sip.c:3463 parse_request: Header 5: CSeq: 1 REGISTER (16)
[2007-09-25 09:57:39] DEBUG[16126]: chan_sip.c:3463 parse_request: Header 6: User-Agent: Royal Publishing (28)
[2007-09-25 09:57:39] DEBUG[16126]: chan_sip.c:3463 parse_request: Header 7: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66)
[2007-09-25 09:57:39] DEBUG[16126]: chan_sip.c:3463 parse_request: Header 8: l: 0 (4)
[2007-09-25 09:57:39] DEBUG[16126]: chan_sip.c:3463 parse_request: Header 9:  (0)
[2007-09-25 09:57:39] NOTICE[16126]: chan_sip.c:11575 handle_request_register: Registration from '<sip:7094@10.1.1.249>' failed for '10.1.1.171' - ACL error (permit/deny)
[2007-09-25 09:57:39] Scheduling destruction of call '97b58c8d-e03ae37f-21a3b7b4@10.1.1.171' in 15000 ms
[2007-09-25 09:57:46] 
<-- SIP read from 10.1.1.183:5060: 
REGISTER sip:10.1.1.249:5060 SIP/2.0
Via: SIP/2.0/UDP 10.1.1.183;branch=z9hG4bKe3b36e7d9C027830
From: "NOC Phone 1" <sip:7095@10.1.1.249>;tag=1AD31553-5DC9BAB4
To: <sip:7095@10.1.1.249>
CSeq: 1 REGISTER
Call-ID: 656bceff-b907b671-ba17839a@10.1.1.183
Contact: <sip:7095@10.1.1.183>;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER"
User-Agent: PolycomSoundPointIP-SPIP_301-UA/2.0.1.0291
Max-Forwards: 70
Expires: 60
Content-Length: 0

[2007-09-25 09:57:46] DEBUG[16126]: chan_sip.c:3463 parse_request: Header 0: REGISTER sip:10.1.1.249:5060 SIP/2.0 (36)
[2007-09-25 09:57:46] DEBUG[16126]: chan_sip.c:3463 parse_request: Header 1: Via: SIP/2.0/UDP 10.1.1.183;branch=z9hG4bKe3b36e7d9C027830 (58)
[2007-09-25 09:57:46] DEBUG[16126]: chan_sip.c:3463 parse_request: Header 2: From: "NOC Phone 1" <sip:7095@10.1.1.249>;tag=1AD31553-5DC9BAB4 (63)
[2007-09-25 09:57:46] DEBUG[16126]: chan_sip.c:3463 parse_request: Header 3: To: <sip:7095@10.1.1.249> (25)
[2007-09-25 09:57:46] DEBUG[16126]: chan_sip.c:3463 parse_request: Header 4: CSeq: 1 REGISTER (16)
[2007-09-25 09:57:46] DEBUG[16126]: chan_sip.c:3463 parse_request: Header 5: Call-ID: 656bceff-b907b671-ba17839a@10.1.1.183 (46)
[2007-09-25 09:57:46] DEBUG[16126]: chan_sip.c:3463 parse_request: Header 6: Contact: <sip:7095@10.1.1.183>;methods="INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER" (130)
[2007-09-25 09:57:46] DEBUG[16126]: chan_sip.c:3463 parse_request: Header 7: User-Agent: PolycomSoundPointIP-SPIP_301-UA/2.0.1.0291 (54)
[2007-09-25 09:57:46] DEBUG[16126]: chan_sip.c:3463 parse_request: Header 8: Max-Forwards: 70 (16)
[2007-09-25 09:57:46] DEBUG[16126]: chan_sip.c:3463 parse_request: Header 9: Expires: 60 (11)
[2007-09-25 09:57:46] DEBUG[16126]: chan_sip.c:3463 parse_request: Header 10: Content-Length: 0 (17)
[2007-09-25 09:57:46] --- (11 headers 0 lines) ---
[2007-09-25 09:57:46] DEBUG[16126]: chan_sip.c:3246 find_call: = Looking for  Call ID: 656bceff-b907b671-ba17839a@10.1.1.183 (Checking From) --From tag 1AD31553-5DC9BAB4 --To-tag   
[2007-09-25 09:57:46] DEBUG[16126]: chan_sip.c:3260 find_call: = No match Their Call ID: 97b58c8d-e03ae37f-21a3b7b4@10.1.1.171 Their Tag 23039101-488E485E Our tag: as441587d0
[2007-09-25 09:57:46] DEBUG[16126]: acl.c:211 ast_apply_ha: ##### Testing 10.1.1.183 with 192.168.0.0
[2007-09-25 09:57:46] DEBUG[16126]: acl.c:211 ast_apply_ha: ##### Testing 10.1.1.183 with 10.0.0.0
[2007-09-25 09:57:46] DEBUG[16126]: acl.c:211 ast_apply_ha: ##### Testing 10.1.1.183 with 172.16.0.0
[2007-09-25 09:57:46] DEBUG[16126]: acl.c:211 ast_apply_ha: ##### Testing 10.1.1.183 with 169.254.0.0
[2007-09-25 09:57:46] DEBUG[16126]: chan_sip.c:3198 sip_alloc: Allocating new SIP dialog for 656bceff-b907b671-ba17839a@10.1.1.183 - REGISTER (No RTP)
[2007-09-25 09:57:46] DEBUG[16126]: chan_sip.c:11666 handle_request: **** Received REGISTER (2) - Command in SIP REGISTER
[2007-09-25 09:57:46] Using latest REGISTER request as basis request
[2007-09-25 09:57:46] Sending to 10.1.1.183 : 5060 (NAT)
[2007-09-25 09:57:46] Transmitting (NAT) to 10.1.1.183:5060:
SIP/2.0 404 Not found
v: SIP/2.0/UDP 10.1.1.183;branch=z9hG4bKe3b36e7d9C027830;received=10.1.1.183
f: "NOC Phone 1" <sip:7095@10.1.1.249>;tag=1AD31553-5DC9BAB4
t: <sip:7095@10.1.1.249>;tag=as3fc65aa4
i: 656bceff-b907b671-ba17839a@10.1.1.183
CSeq: 1 REGISTER
User-Agent: Royal Publishing
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
l: 0


---
[2007-09-25 09:57:46] DEBUG[16126]: chan_sip.c:3463 parse_request: Header 0: SIP/2.0 404 Not found (21)
[2007-09-25 09:57:46] DEBUG[16126]: chan_sip.c:3463 parse_request: Header 1: v: SIP/2.0/UDP 10.1.1.183;branch=z9hG4bKe3b36e7d9C027830;received=10.1.1.183 (76)
[2007-09-25 09:57:46] DEBUG[16126]: chan_sip.c:3463 parse_request: Header 2: f: "NOC Phone 1" <sip:7095@10.1.1.249>;tag=1AD31553-5DC9BAB4 (60)
[2007-09-25 09:57:46] DEBUG[16126]: chan_sip.c:3463 parse_request: Header 3: t: <sip:7095@10.1.1.249>;tag=as3fc65aa4 (39)
[2007-09-25 09:57:46] DEBUG[16126]: chan_sip.c:3463 parse_request: Header 4: i: 656bceff-b907b671-ba17839a@10.1.1.183 (40)
[2007-09-25 09:57:46] DEBUG[16126]: chan_sip.c:3463 parse_request: Header 5: CSeq: 1 REGISTER (16)
[2007-09-25 09:57:46] DEBUG[16126]: chan_sip.c:3463 parse_request: Header 6: User-Agent: Royal Publishing (28)
[2007-09-25 09:57:46] DEBUG[16126]: chan_sip.c:3463 parse_request: Header 7: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY (66)
[2007-09-25 09:57:46] DEBUG[16126]: chan_sip.c:3463 parse_request: Header 8: l: 0 (4)
[2007-09-25 09:57:46] DEBUG[16126]: chan_sip.c:3463 parse_request: Header 9:  (0)
[2007-09-25 09:57:46] NOTICE[16126]: chan_sip.c:11575 handle_request_register: Registration from '<sip:7095@10.1.1.249>' failed for '10.1.1.183' - ACL error (permit/deny)
[2007-09-25 09:57:46] Scheduling destruction of call '656bceff-b907b671-ba17839a@10.1.1.183' in 15000 ms
[2007-09-25 09:57:50] DEBUG[16117]: chan_iax2.c:9566 iax2_devicestate: Checking device state for device rpc-pbx-peo
[2007-09-25 09:57:50] DEBUG[16117]: devicestate.c:189 do_state_change: Changing state for IAX2/rpc-pbx-peo - state 4 (Invalid)
[2007-09-25 09:57:50] DEBUG[16271]: app_queue.c:500 changethread: Device 'IAX2/rpc-pbx-peo' changed to state '4' (Invalid) but we don't care because they're not a member of any queue.
[2007-09-25 09:57:50] DEBUG[16117]: chan_iax2.c:9566 iax2_devicestate: Checking device state for device rpc-pbx-peo
[2007-09-25 09:57:50] DEBUG[16117]: devicestate.c:189 do_state_change: Changing state for IAX2/rpc-pbx-peo - state 4 (Invalid)
[2007-09-25 09:57:50] DEBUG[16272]: app_queue.c:500 changethread: Device 'IAX2/rpc-pbx-peo' changed to state '4' (Invalid) but we don't care because they're not a member of any queue.