<-- SIP read from 192.168.2.78:5060: 
INVITE sip:351@192.168.2.1 SIP/2.0
Via: SIP/2.0/UDP 192.168.2.78:5060;branch=z9hG4bK-oqy1t86s11zu;rport
From: "Wim Linge" <sip:owner8@192.168.2.1>;tag=3tf4ycnq8g
To: <sip:351@192.168.2.1>
Call-ID: 3c2cdbeede2b-uk2xcpvi3ozs@snom360-0004132388EC
CSeq: 1 INVITE
Max-Forwards: 70
Contact: <sip:owner8@192.168.2.78:5060;line=hl79r4jn>;flow-id=1
P-Key-Flags: resolution="31x13", keys="4"
User-Agent: snom360/6.5.10
Accept: application/sdp
Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE, PRACK, MESSAGE, INFO
Allow-Events: talk, hold, refer
Supported: timer, 100rel, replaces, callerid
Session-Expires: 3600;refresher=uas
Min-SE: 90
Content-Type: application/sdp
Content-Length: 475

v=0
o=root 1585851413 1585851413 IN IP4 192.168.2.78
s=call
c=IN IP4 192.168.2.78
t=0 0
m=audio 63852 RTP/AVP 0 8 9 2 3 18 4 101
a=crypto:1 AES_CM_128_HMAC_SHA1_32 inline:vbGYNUWZSqtSv33dLVydm6s6Ru4u9omMK8+BSVje
a=rtpmap:0 pcmu/8000
a=rtpmap:8 pcma/8000
a=rtpmap:9 g722/8000
a=rtpmap:2 g726-32/8000
a=rtpmap:3 gsm/8000
a=rtpmap:18 g729/8000
a=rtpmap:4 g723/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=encryption:optional
a=sendrecv

Jun 25 12:08:11 VERBOSE[1707] logger.c: --- (18 headers 19 lines) ---
Jun 25 12:08:11 DEBUG[1707] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE
Jun 25 12:08:11 DEBUG[1707] chan_sip.c: Begin: parsing SIP "Supported: timer, 100rel, replaces, callerid"
Jun 25 12:08:11 DEBUG[1707] chan_sip.c: Found SIP option: -timer-
Jun 25 12:08:11 DEBUG[1707] chan_sip.c: Matched SIP option: timer
Jun 25 12:08:11 DEBUG[1707] chan_sip.c: Found SIP option: -100rel-
Jun 25 12:08:11 DEBUG[1707] chan_sip.c: Matched SIP option: 100rel
Jun 25 12:08:11 DEBUG[1707] chan_sip.c: Found SIP option: -replaces-
Jun 25 12:08:11 DEBUG[1707] chan_sip.c: Matched SIP option: replaces
Jun 25 12:08:11 DEBUG[1707] chan_sip.c: Found SIP option: -callerid-
Jun 25 12:08:11 DEBUG[1707] chan_sip.c: Found no match for SIP option: callerid (Please file bug report!)
Jun 25 12:08:11 DEBUG[1707] chan_sip.c: * SIP extension value: 7 for call 3c2cdbeede2b-uk2xcpvi3ozs@snom360-0004132388EC
Jun 25 12:08:11 VERBOSE[1707] logger.c: Using INVITE request as basis request - 3c2cdbeede2b-uk2xcpvi3ozs@snom360-0004132388EC
Jun 25 12:08:11 VERBOSE[1707] logger.c: Sending to 192.168.2.78 : 5060 (NAT)
Jun 25 12:08:11 DEBUG[1707] chan_sip.c: Setting NAT on RTP to 0
Jun 25 12:08:11 DEBUG[1707] chan_sip.c: Setting NAT on VRTP to 0
Jun 25 12:08:11 VERBOSE[1707] logger.c: Reliably Transmitting (no NAT) to 192.168.2.78:5060:
SIP/2.0 407 Proxy Authentication Required
Via: SIP/2.0/UDP 192.168.2.78:5060;branch=z9hG4bK-oqy1t86s11zu;received=192.168.2.78;rport=5060
From: "Wim Linge" <sip:owner8@192.168.2.1>;tag=3tf4ycnq8g
To: <sip:351@192.168.2.1>;tag=as49340fec
Call-ID: 3c2cdbeede2b-uk2xcpvi3ozs@snom360-0004132388EC
CSeq: 1 INVITE
User-Agent: Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Proxy-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="559f40de"
Content-Length: 0


---
Jun 25 12:08:11 VERBOSE[1707] logger.c: Scheduling destruction of call '3c2cdbeede2b-uk2xcpvi3ozs@snom360-0004132388EC' in 15000 ms
Jun 25 12:08:11 VERBOSE[1707] logger.c: Found user 'owner8'
Jun 25 12:08:11 VERBOSE[1707] logger.c: 
<-- SIP read from 192.168.2.78:5060: 
ACK sip:351@192.168.2.1 SIP/2.0
Via: SIP/2.0/UDP 192.168.2.78:5060;branch=z9hG4bK-oqy1t86s11zu;rport
From: "Wim Linge" <sip:owner8@192.168.2.1>;tag=3tf4ycnq8g
To: <sip:351@192.168.2.1>;tag=as49340fec
Call-ID: 3c2cdbeede2b-uk2xcpvi3ozs@snom360-0004132388EC
CSeq: 1 ACK
Max-Forwards: 70
Contact: <sip:owner8@192.168.2.78:5060;line=hl79r4jn>;flow-id=1
Content-Length: 0


Jun 25 12:08:11 VERBOSE[1707] logger.c: --- (9 headers 0 lines) ---
Jun 25 12:08:11 DEBUG[1707] chan_sip.c: **** Received ACK (6) - Command in SIP ACK
Jun 25 12:08:11 VERBOSE[1707] logger.c: 
<-- SIP read from 192.168.2.78:5060: 
INVITE sip:351@192.168.2.1 SIP/2.0
Via: SIP/2.0/UDP 192.168.2.78:5060;branch=z9hG4bK-algt7glld8zo;rport
From: "Wim Linge" <sip:owner8@192.168.2.1>;tag=3tf4ycnq8g
To: <sip:351@192.168.2.1>
Call-ID: 3c2cdbeede2b-uk2xcpvi3ozs@snom360-0004132388EC
CSeq: 2 INVITE
Max-Forwards: 70
Contact: <sip:owner8@192.168.2.78:5060;line=hl79r4jn>;flow-id=1
P-Key-Flags: resolution="31x13", keys="4"
User-Agent: snom360/6.5.10
Accept: application/sdp
Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE, PRACK, MESSAGE, INFO
Allow-Events: talk, hold, refer
Supported: timer, 100rel, replaces, callerid
Session-Expires: 3600;refresher=uas
Min-SE: 90
Proxy-Authorization: Digest username="owner8",realm="asterisk",nonce="559f40de",uri="sip:351@192.168.2.1",response="ff29a4a8417220b11668a171ca9f739c",algorithm=MD5
Content-Type: application/sdp
Content-Length: 475

v=0
o=root 1585851413 1585851413 IN IP4 192.168.2.78
s=call
c=IN IP4 192.168.2.78
t=0 0
m=audio 63852 RTP/AVP 0 8 9 2 3 18 4 101
a=crypto:1 AES_CM_128_HMAC_SHA1_32 inline:vbGYNUWZSqtSv33dLVydm6s6Ru4u9omMK8+BSVje
a=rtpmap:0 pcmu/8000
a=rtpmap:8 pcma/8000
a=rtpmap:9 g722/8000
a=rtpmap:2 g726-32/8000
a=rtpmap:3 gsm/8000
a=rtpmap:18 g729/8000
a=rtpmap:4 g723/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=encryption:optional
a=sendrecv

Jun 25 12:08:11 VERBOSE[1707] logger.c: --- (19 headers 19 lines) ---
Jun 25 12:08:11 DEBUG[1707] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE
Jun 25 12:08:11 VERBOSE[1707] logger.c: Using INVITE request as basis request - 3c2cdbeede2b-uk2xcpvi3ozs@snom360-0004132388EC
Jun 25 12:08:11 VERBOSE[1707] logger.c: Sending to 192.168.2.78 : 5060 (NAT)
Jun 25 12:08:11 DEBUG[1707] chan_sip.c: Setting NAT on RTP to 0
Jun 25 12:08:11 DEBUG[1707] chan_sip.c: Setting NAT on VRTP to 0
Jun 25 12:08:11 VERBOSE[1707] logger.c: Found user 'owner8'
Jun 25 12:08:11 VERBOSE[1707] logger.c: Found RTP audio format 0
Jun 25 12:08:11 VERBOSE[1707] logger.c: Found RTP audio format 8
Jun 25 12:08:11 VERBOSE[1707] logger.c: Found RTP audio format 9
Jun 25 12:08:11 VERBOSE[1707] logger.c: Found RTP audio format 2
Jun 25 12:08:11 VERBOSE[1707] logger.c: Found RTP audio format 3
Jun 25 12:08:11 VERBOSE[1707] logger.c: Found RTP audio format 18
Jun 25 12:08:11 VERBOSE[1707] logger.c: Found RTP audio format 4
Jun 25 12:08:11 VERBOSE[1707] logger.c: Found RTP audio format 101
Jun 25 12:08:11 VERBOSE[1707] logger.c: Peer audio RTP is at port 192.168.2.78:63852
Jun 25 12:08:11 DEBUG[1707] chan_sip.c: Peer audio RTP is at port 192.168.2.78:63852
Jun 25 12:08:11 VERBOSE[1707] logger.c: Peer video RTP is at port 192.168.2.78:65535
Jun 25 12:08:11 DEBUG[1707] chan_sip.c: Peer video RTP is at port 192.168.2.78:65535
Jun 25 12:08:11 VERBOSE[1707] logger.c: Found description format pcmu
Jun 25 12:08:11 VERBOSE[1707] logger.c: Found description format pcma
Jun 25 12:08:11 VERBOSE[1707] logger.c: Found description format g722
Jun 25 12:08:11 VERBOSE[1707] logger.c: Found description format g726-32
Jun 25 12:08:11 VERBOSE[1707] logger.c: Found description format gsm
Jun 25 12:08:11 VERBOSE[1707] logger.c: Found description format g729
Jun 25 12:08:11 VERBOSE[1707] logger.c: Found description format g723
Jun 25 12:08:11 VERBOSE[1707] logger.c: Found description format telephone-event
Jun 25 12:08:11 VERBOSE[1707] logger.c: Capabilities: us - 0x4 (ulaw), peer - audio=0x11f (g723|gsm|ulaw|alaw|g726|g729)/video=0x0 (nothing), combined - 0x4 (ulaw)
Jun 25 12:08:11 VERBOSE[1707] logger.c: Non-codec capabilities: us - 0x1 (telephone-event), peer - 0x1 (telephone-event), combined - 0x1 (telephone-event)
Jun 25 12:08:11 DEBUG[1707] chan_sip.c: Checking SIP call limits for device owner8
Jun 25 12:08:11 DEBUG[1707] chan_sip.c: Updating call counter for incoming call
Jun 25 12:08:11 VERBOSE[1707] logger.c: Looking for 351 in phone (domain 192.168.2.1)
Jun 25 12:08:11 DEBUG[1707] chan_sip.c: build_route: Contact hop: <sip:owner8@192.168.2.78:5060;line=hl79r4jn>;flow-id=1
Jun 25 12:08:11 VERBOSE[1707] logger.c: list_route: hop: <sip:owner8@192.168.2.78:5060;line=hl79r4jn>
Jun 25 12:08:11 VERBOSE[1707] logger.c: Transmitting (no NAT) to 192.168.2.78:5060:
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 192.168.2.78:5060;branch=z9hG4bK-algt7glld8zo;received=192.168.2.78;rport=5060
From: "Wim Linge" <sip:owner8@192.168.2.1>;tag=3tf4ycnq8g
To: <sip:351@192.168.2.1>
Call-ID: 3c2cdbeede2b-uk2xcpvi3ozs@snom360-0004132388EC
CSeq: 2 INVITE
User-Agent: Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Contact: <sip:351@192.168.2.1>
Content-Length: 0


---
Jun 25 12:08:11 DEBUG[1692] chan_sip.c: Checking device state for peer owner8
Jun 25 12:08:11 DEBUG[1692] channel.c: Avoiding initial deadlock for 'SIP/owner8-097d6f80'
Jun 25 12:08:11 DEBUG[10405] pbx.c: Function result is '351'
Jun 25 12:08:11 DEBUG[10405] pbx.c: Launching 'Set'
Jun 25 12:08:11 VERBOSE[10405] logger.c:     -- Executing Set("SIP/owner8-097d6f80", "NEWEXTEN=351") in new stack
Jun 25 12:08:11 DEBUG[10405] pbx.c: Function result is ''
Jun 25 12:08:11 DEBUG[10405] pbx.c: Expression result is '1'
Jun 25 12:08:11 DEBUG[10405] pbx.c: Launching 'GotoIf'
Jun 25 12:08:11 VERBOSE[10405] logger.c:     -- Executing GotoIf("SIP/owner8-097d6f80", "1?Cont") in new stack
Jun 25 12:08:11 DEBUG[1692] devicestate.c: Changing state for SIP/owner8 - state 2 (In use)
Jun 25 12:08:11 DEBUG[1692] chan_sip.c: Checking device state for peer owner8
Jun 25 12:08:11 DEBUG[10406] app_queue.c: Device 'SIP/owner8' changed to state '2' (In use)
Jun 25 12:08:11 DEBUG[1692] chan_sip.c: Checking device state for peer owner8
Jun 25 12:08:11 VERBOSE[10405] logger.c:     -- Goto (phone,351,5)
Jun 25 12:08:11 DEBUG[10405] pbx.c: Function result is 'Wim Linge'
Jun 25 12:08:11 DEBUG[10405] pbx.c: Launching 'Macro'
Jun 25 12:08:11 VERBOSE[1692] logger.c: Reliably Transmitting (no NAT) to 192.168.2.78:5060:
NOTIFY sip:owner8@192.168.2.78:5060;line=hl79r4jn SIP/2.0
Via: SIP/2.0/UDP 192.168.2.1:5060;branch=z9hG4bK70def95c;rport
From: <sip:160@192.168.2.1>;tag=as1d833d23
To: <sip:owner8@192.168.2.1>;tag=aqe2b2srjz
Contact: <sip:160@192.168.2.1>
Call-ID: 3c26700ce57e-mvj46o05otkl@snom360-0004132388EC
CSeq: 686 NOTIFY
User-Agent: Asterisk PBX
Max-Forwards: 70
Event: dialog
Content-Type: application/dialog-info+xml
Subscription-State: active
Content-Length: 203

<?xml version="1.0"?>
<dialog-info xmlns="urn:ietf:params:xml:ns:dialog-info" version="584" state="full" entity="sip:160@192.168.2.1">
<dialog id="160">
<state>confirmed</state>
</dialog>
</dialog-info>

---
Jun 25 12:08:11 VERBOSE[10405] logger.c:     -- Executing Macro("SIP/owner8-097d6f80", "eventlog|Dialing: 160 (Wim Linge) -> 351") in new stack
Jun 25 12:08:11 VERBOSE[1692] logger.c:  Extension Changed 160 new state InUse for Notify User owner8
Jun 25 12:08:11 DEBUG[1692] chan_sip.c: Checking device state for peer owner8
Jun 25 12:08:11 DEBUG[10405] pbx.c: Expression result is '1'
Jun 25 12:08:11 DEBUG[10405] pbx.c: Launching 'GotoIf'
Jun 25 12:08:11 VERBOSE[1692] logger.c:  Extension Changed 160 new state InUse for Notify User owner2
Jun 25 12:08:11 DEBUG[1692] chan_sip.c: Checking device state for peer owner8
Jun 25 12:08:11 VERBOSE[1692] logger.c:  Extension Changed 160 new state InUse for Notify User owner4
Jun 25 12:08:11 DEBUG[1692] chan_sip.c: Checking device state for peer owner8
Jun 25 12:08:11 VERBOSE[10405] logger.c:     -- Executing GotoIf("SIP/owner8-097d6f80", "1?999") in new stack
Jun 25 12:08:11 VERBOSE[1692] logger.c:  Extension Changed 160 new state InUse for Notify User owner9
Jun 25 12:08:11 VERBOSE[10405] logger.c:     -- Goto (macro-eventlog,s,999)
Jun 25 12:08:11 DEBUG[10405] app_macro.c: Executed application: GotoIf
Jun 25 12:08:11 DEBUG[10405] pbx.c: Expression result is '1'
Jun 25 12:08:11 DEBUG[10405] pbx.c: Launching 'GotoIf'
Jun 25 12:08:11 VERBOSE[10405] logger.c:     -- Executing GotoIf("SIP/owner8-097d6f80", "1?Plus") in new stack
Jun 25 12:08:11 VERBOSE[10405] logger.c:     -- Goto (phone,351,9)

<-- snip: removed irrelevant dialplan trace -->

Jun 25 12:08:11 DEBUG[10405] chan_sip.c: Checking device state for peer owner26
Jun 25 12:08:11 DEBUG[10405] chan_sip.c: Setting NAT on RTP to 0
Jun 25 12:08:11 DEBUG[10405] chan_sip.c: Setting NAT on VRTP to 0
Jun 25 12:08:11 WARNING[10405] channel.c: Variable: SIPCALLID=0d84856c720c414e5584221d366621c3@192.168.2.1

Jun 25 12:08:11 VERBOSE[1707] logger.c: 
<-- SIP read from 192.168.2.78:5060: 
SIP/2.0 200 Ok
Via: SIP/2.0/UDP 192.168.2.1:5060;branch=z9hG4bK70def95c;rport=5060
From: <sip:160@192.168.2.1>;tag=as1d833d23
To: <sip:owner8@192.168.2.1>;tag=aqe2b2srjz
Call-ID: 3c26700ce57e-mvj46o05otkl@snom360-0004132388EC
CSeq: 686 NOTIFY
Content-Length: 0


Jun 25 12:08:11 VERBOSE[10405] logger.c:     -- Executing NoCDR("SIP/owner8-097d6f80", "") in new stack
Jun 25 12:08:11 DEBUG[10405] cdr.c: CDR on channel 'SIP/owner8-097d6f80' not posted
Jun 25 12:08:11 DEBUG[10405] cdr.c: CDR on channel 'SIP/owner8-097d6f80' lacks end
Jun 25 12:08:11 DEBUG[10405] app_macro.c: Executed application: NoCDR
Jun 25 12:08:11 DEBUG[10405] pbx.c: Launching 'SIPAddHeader'
Jun 25 12:08:11 VERBOSE[10405] logger.c:     -- Executing SIPAddHeader("SIP/owner8-097d6f80", "Call-Info:<sip:160@192.168.2.1>;answer-after=0") in new stack
Jun 25 12:08:11 DEBUG[10405] chan_sip.c: SIP Header added "Call-Info:<sip:160@192.168.2.1>;answer-after=0" as _SIPADDHEADER01
Jun 25 12:08:11 DEBUG[10405] app_macro.c: Executed application: SIPAddHeader
Jun 25 12:08:11 DEBUG[10405] pbx.c: Launching 'Goto'
Jun 25 12:08:11 VERBOSE[10405] logger.c:     -- Executing Goto("SIP/owner8-097d6f80", "s-Dial|1") in new stack
Jun 25 12:08:11 VERBOSE[10405] logger.c:     -- Goto (macro-page,s-Dial,1)
Jun 25 12:08:11 DEBUG[10405] app_macro.c: Executed application: Goto
Jun 25 12:08:11 DEBUG[10405] pbx.c: Launching 'Dial'
Jun 25 12:08:11 VERBOSE[10405] logger.c:     -- Executing Dial("SIP/owner8-097d6f80", "SIP/owner26||") in new stack
Jun 25 12:08:11 DEBUG[10405] chan_sip.c: Setting NAT on RTP to 0
Jun 25 12:08:11 DEBUG[10405] chan_sip.c: Setting NAT on VRTP to 0
Jun 25 12:08:11 WARNING[10405] channel.c: Variable: SIPCALLID=413adcd26c19192b625832de1c61de41@192.168.2.1

Jun 25 12:08:11 DEBUG[10405] chan_sip.c: Outgoing Call for owner26
Jun 25 12:08:11 DEBUG[10405] chan_sip.c: Updating call counter for outgoing call
Jun 25 12:08:11 DEBUG[10405] chan_sip.c: Adding SIP Header "Call-Info" with content :<sip:160@192.168.2.1>;answer-after=0: 
Jun 25 12:08:11 VERBOSE[10405] logger.c:     -- Called owner26
Jun 25 12:08:11 DEBUG[1692] chan_sip.c: Checking device state for peer owner26
Jun 25 12:08:11 DEBUG[1692] devicestate.c: Changing state for SIP/owner26 - state 6 (Ringing)
Jun 25 12:08:11 DEBUG[1692] chan_sip.c: Checking device state for peer owner26
Jun 25 12:08:11 DEBUG[1692] chan_sip.c: Checking device state for peer owner26
Jun 25 12:08:11 VERBOSE[10405] logger.c:     -- SIP/owner26-097a09d8 is ringing
Jun 25 12:08:11 DEBUG[10409] app_queue.c: Device 'SIP/owner26' changed to state '6' (Ringing)
Jun 25 12:08:11 VERBOSE[10405] logger.c: Transmitting (no NAT) to 192.168.2.78:5060:
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP 192.168.2.78:5060;branch=z9hG4bK-algt7glld8zo;received=192.168.2.78;rport=5060
From: "Wim Linge" <sip:owner8@192.168.2.1>;tag=3tf4ycnq8g
To: <sip:351@192.168.2.1>;tag=as598f0ee6
Call-ID: 3c2cdbeede2b-uk2xcpvi3ozs@snom360-0004132388EC
CSeq: 2 INVITE
User-Agent: Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Contact: <sip:351@192.168.2.1>
Content-Length: 0


---
Jun 25 12:08:11 VERBOSE[1692] logger.c:  Extension Changed 350 new state Ringing for Notify User owner2
Jun 25 12:08:11 DEBUG[1692] chan_sip.c: Checking device state for peer owner26
Jun 25 12:08:11 VERBOSE[1692] logger.c:  Extension Changed 350 new state Ringing for Notify User owner4
Jun 25 12:08:11 DEBUG[1692] chan_sip.c: Checking device state for peer owner26
Jun 25 12:08:11 VERBOSE[1692] logger.c:  Extension Changed 350 new state Ringing for Notify User owner9
Jun 25 12:08:11 DEBUG[1692] chan_sip.c: Checking device state for peer owner26
Jun 25 12:08:11 VERBOSE[1692] logger.c:  Extension Changed 350 new state Ringing for Notify User owner26
Jun 25 12:08:11 DEBUG[1692] chan_sip.c: Checking device state for peer owner26
Jun 25 12:08:11 VERBOSE[1692] logger.c: Reliably Transmitting (no NAT) to 192.168.2.78:5060:
NOTIFY sip:owner8@192.168.2.78:5060;line=hl79r4jn SIP/2.0
Via: SIP/2.0/UDP 192.168.2.1:5060;branch=z9hG4bK7f360a0b;rport
From: <sip:350@192.168.2.1>;tag=as5cf6528c
To: <sip:owner8@192.168.2.1>;tag=zxawooyw0m
Contact: <sip:350@192.168.2.1>
Call-ID: 3c26700d2981-o1ry3yibkgjj@snom360-0004132388EC
CSeq: 423 NOTIFY
User-Agent: Asterisk PBX
Max-Forwards: 70
Event: dialog
Content-Type: application/dialog-info+xml
Subscription-State: active
Content-Length: 221

<?xml version="1.0"?>
<dialog-info xmlns="urn:ietf:params:xml:ns:dialog-info" version="321" state="full" entity="sip:350@192.168.2.1">
<dialog id="350" direction="recipient">
<state>early</state>
</dialog>
</dialog-info>

---
Jun 25 12:08:11 VERBOSE[1692] logger.c:  Extension Changed 350 new state Ringing for Notify User owner8
Jun 25 12:08:11 DEBUG[10405] rtp.c: Got RTCP report of 52 bytes
Jun 25 12:08:11 DEBUG[10392] rtp.c: Got RTCP report of 52 bytes
Jun 25 12:08:11 DEBUG[1707] chan_sip.c: Acked pending invite 102
Jun 25 12:08:11 DEBUG[1707] chan_sip.c: build_route: Contact hop: <sip:owner26@192.168.2.83:5060;line=4gl2bzgn>;flow-id=1
Jun 25 12:08:11 DEBUG[1692] chan_sip.c: Checking device state for peer owner26
Jun 25 12:08:11 DEBUG[1692] devicestate.c: Changing state for SIP/owner26 - state 2 (In use)
Jun 25 12:08:11 VERBOSE[10405] logger.c:     -- SIP/owner26-097a09d8 answered SIP/owner8-097d6f80
Jun 25 12:08:11 DEBUG[1692] chan_sip.c: Checking device state for peer owner26
Jun 25 12:08:11 DEBUG[10410] app_queue.c: Device 'SIP/owner26' changed to state '2' (In use)
Jun 25 12:08:11 DEBUG[10405] chan_sip.c: sip_answer(SIP/owner8-097d6f80)
Jun 25 12:08:11 VERBOSE[10405] logger.c: We're at 192.168.2.1 port 12766
Jun 25 12:08:11 VERBOSE[10405] logger.c: Video is at 192.168.2.1 port 12480
Jun 25 12:08:11 VERBOSE[10405] logger.c: Adding codec 0x4 (ulaw) to SDP
Jun 25 12:08:11 DEBUG[1692] chan_sip.c: Checking device state for peer owner26
Jun 25 12:08:11 VERBOSE[10405] logger.c: Adding non-codec 0x1 (telephone-event) to SDP
Jun 25 12:08:11 VERBOSE[10405] logger.c: Reliably Transmitting (no NAT) to 192.168.2.78:5060:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.2.78:5060;branch=z9hG4bK-algt7glld8zo;received=192.168.2.78;rport=5060
From: "Wim Linge" <sip:owner8@192.168.2.1>;tag=3tf4ycnq8g
To: <sip:351@192.168.2.1>;tag=as598f0ee6
Call-ID: 3c2cdbeede2b-uk2xcpvi3ozs@snom360-0004132388EC
CSeq: 2 INVITE
User-Agent: Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Contact: <sip:351@192.168.2.1>
Content-Type: application/sdp
Content-Length: 212

v=0
o=root 1681 1681 IN IP4 192.168.2.1
s=session
c=IN IP4 192.168.2.1
t=0 0
m=audio 12766 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=silenceSupp:off - - - -

---
Jun 25 12:08:11 VERBOSE[10405] logger.c:     -- Attempting native bridge of SIP/owner8-097d6f80 and SIP/owner26-097a09d8
Jun 25 12:08:11 DEBUG[10405] rtp.c: Ooh, format changed from unknown to ulaw
Jun 25 12:08:11 VERBOSE[1692] logger.c:  Extension Changed 350 new state InUse for Notify User owner2
Jun 25 12:08:11 DEBUG[1692] chan_sip.c: Checking device state for peer owner26
Jun 25 12:08:11 VERBOSE[1692] logger.c:  Extension Changed 350 new state InUse for Notify User owner4
Jun 25 12:08:11 DEBUG[1692] chan_sip.c: Checking device state for peer owner26
Jun 25 12:08:11 VERBOSE[1692] logger.c:  Extension Changed 350 new state InUse for Notify User owner9
Jun 25 12:08:11 DEBUG[1692] chan_sip.c: Checking device state for peer owner26
Jun 25 12:08:11 VERBOSE[1692] logger.c:  Extension Changed 350 new state InUse for Notify User owner26
Jun 25 12:08:11 DEBUG[1692] chan_sip.c: Checking device state for peer owner26
Jun 25 12:08:11 VERBOSE[1692] logger.c: Reliably Transmitting (no NAT) to 192.168.2.78:5060:
NOTIFY sip:owner8@192.168.2.78:5060;line=hl79r4jn SIP/2.0
Via: SIP/2.0/UDP 192.168.2.1:5060;branch=z9hG4bK1b754010;rport
From: <sip:350@192.168.2.1>;tag=as5cf6528c
To: <sip:owner8@192.168.2.1>;tag=zxawooyw0m
Contact: <sip:350@192.168.2.1>
Call-ID: 3c26700d2981-o1ry3yibkgjj@snom360-0004132388EC
CSeq: 424 NOTIFY
User-Agent: Asterisk PBX
Max-Forwards: 70
Event: dialog
Content-Type: application/dialog-info+xml
Subscription-State: active
Content-Length: 203

<?xml version="1.0"?>
<dialog-info xmlns="urn:ietf:params:xml:ns:dialog-info" version="322" state="full" entity="sip:350@192.168.2.1">
<dialog id="350">
<state>confirmed</state>
</dialog>
</dialog-info>

---
Jun 25 12:08:11 VERBOSE[1692] logger.c:  Extension Changed 350 new state InUse for Notify User owner8
Jun 25 12:08:11 DEBUG[1692] chan_sip.c: Checking device state for peer owner8
Jun 25 12:08:11 DEBUG[1692] devicestate.c: Changing state for SIP/owner8 - state 2 (In use)
Jun 25 12:08:11 DEBUG[1692] chan_sip.c: Checking device state for peer owner8
Jun 25 12:08:11 DEBUG[10411] app_queue.c: Device 'SIP/owner8' changed to state '2' (In use)
Jun 25 12:08:12 VERBOSE[1707] logger.c: 
<-- SIP read from 192.168.2.78:5060: 
SIP/2.0 200 Ok
Via: SIP/2.0/UDP 192.168.2.1:5060;branch=z9hG4bK7f360a0b;rport=5060
From: <sip:350@192.168.2.1>;tag=as5cf6528c
To: <sip:owner8@192.168.2.1>;tag=zxawooyw0m
Call-ID: 3c26700d2981-o1ry3yibkgjj@snom360-0004132388EC
CSeq: 423 NOTIFY
Content-Length: 0


Jun 25 12:08:12 VERBOSE[1707] logger.c: --- (7 headers 0 lines) ---
Jun 25 12:08:12 VERBOSE[1707] logger.c: 
<-- SIP read from 192.168.2.78:5060: 
ACK sip:351@192.168.2.1 SIP/2.0
Via: SIP/2.0/UDP 192.168.2.78:5060;branch=z9hG4bK-pwz7s8vjqhwy;rport
From: "Wim Linge" <sip:owner8@192.168.2.1>;tag=3tf4ycnq8g
To: <sip:351@192.168.2.1>;tag=as598f0ee6
Call-ID: 3c2cdbeede2b-uk2xcpvi3ozs@snom360-0004132388EC
CSeq: 2 ACK
Max-Forwards: 70
Contact: <sip:owner8@192.168.2.78:5060;line=hl79r4jn>;flow-id=1
Content-Length: 0


Jun 25 12:08:12 VERBOSE[1707] logger.c: --- (9 headers 0 lines) ---
Jun 25 12:08:12 DEBUG[1707] chan_sip.c: **** Received ACK (6) - Command in SIP ACK
Jun 25 12:08:12 DEBUG[10405] rtp.c: Got RTCP report of 52 bytes
Jun 25 12:08:12 DEBUG[10363] rtp.c: Got RTCP report of 52 bytes
Jun 25 12:08:12 DEBUG[10392] rtp.c: Got RTCP report of 52 bytes
Jun 25 12:08:12 VERBOSE[1707] logger.c: 
<-- SIP read from 192.168.2.78:5060: 
SIP/2.0 200 Ok
Via: SIP/2.0/UDP 192.168.2.1:5060;branch=z9hG4bK1b754010;rport=5060
From: <sip:350@192.168.2.1>;tag=as5cf6528c
To: <sip:owner8@192.168.2.1>;tag=zxawooyw0m
Call-ID: 3c26700d2981-o1ry3yibkgjj@snom360-0004132388EC
CSeq: 424 NOTIFY
Content-Length: 0


Jun 25 12:08:12 VERBOSE[1707] logger.c: --- (7 headers 0 lines) ---
Jun 25 12:08:12 DEBUG[10405] rtp.c: Ooh, format changed from unknown to ulaw
Jun 25 12:08:12 VERBOSE[1707] logger.c: Retransmitting #1 (no NAT) to 192.168.2.78:5060:
NOTIFY sip:owner8@192.168.2.78:5060;line=hl79r4jn SIP/2.0
Via: SIP/2.0/UDP 192.168.2.1:5060;branch=z9hG4bK7f360a0b;rport
From: <sip:350@192.168.2.1>;tag=as5cf6528c
To: <sip:owner8@192.168.2.1>;tag=zxawooyw0m
Contact: <sip:350@192.168.2.1>
Call-ID: 3c26700d2981-o1ry3yibkgjj@snom360-0004132388EC
CSeq: 423 NOTIFY
User-Agent: Asterisk PBX
Max-Forwards: 70
Event: dialog
Content-Type: application/dialog-info+xml
Subscription-State: active
Content-Length: 221

<?xml version="1.0"?>
<dialog-info xmlns="urn:ietf:params:xml:ns:dialog-info" version="321" state="full" entity="sip:350@192.168.2.1">
<dialog id="350" direction="recipient">
<state>early</state>
</dialog>
</dialog-info>

---
Jun 25 12:08:12 VERBOSE[1707] logger.c: 
<-- SIP read from 192.168.2.78:5060: 
SIP/2.0 200 Ok
Via: SIP/2.0/UDP 192.168.2.1:5060;branch=z9hG4bK7f360a0b;rport=5060
From: <sip:350@192.168.2.1>;tag=as5cf6528c
To: <sip:owner8@192.168.2.1>;tag=zxawooyw0m
Call-ID: 3c26700d2981-o1ry3yibkgjj@snom360-0004132388EC
CSeq: 423 NOTIFY
Content-Length: 0


Jun 25 12:08:12 VERBOSE[1707] logger.c: --- (7 headers 0 lines) ---
Jun 25 12:08:13 VERBOSE[1707] logger.c: Retransmitting #2 (no NAT) to 192.168.2.78:5060:
NOTIFY sip:owner8@192.168.2.78:5060;line=hl79r4jn SIP/2.0
Via: SIP/2.0/UDP 192.168.2.1:5060;branch=z9hG4bK7f360a0b;rport
From: <sip:350@192.168.2.1>;tag=as5cf6528c
To: <sip:owner8@192.168.2.1>;tag=zxawooyw0m
Contact: <sip:350@192.168.2.1>
Call-ID: 3c26700d2981-o1ry3yibkgjj@snom360-0004132388EC
CSeq: 423 NOTIFY
User-Agent: Asterisk PBX
Max-Forwards: 70
Event: dialog
Content-Type: application/dialog-info+xml
Subscription-State: active
Content-Length: 221

<?xml version="1.0"?>
<dialog-info xmlns="urn:ietf:params:xml:ns:dialog-info" version="321" state="full" entity="sip:350@192.168.2.1">
<dialog id="350" direction="recipient">
<state>early</state>
</dialog>
</dialog-info>

---
Jun 25 12:08:13 VERBOSE[1707] logger.c: 
<-- SIP read from 192.168.2.78:5060: 
SIP/2.0 200 Ok
Via: SIP/2.0/UDP 192.168.2.1:5060;branch=z9hG4bK7f360a0b;rport=5060
From: <sip:350@192.168.2.1>;tag=as5cf6528c
To: <sip:owner8@192.168.2.1>;tag=zxawooyw0m
Call-ID: 3c26700d2981-o1ry3yibkgjj@snom360-0004132388EC
CSeq: 423 NOTIFY
Content-Length: 0


Jun 25 12:08:13 VERBOSE[1707] logger.c: --- (7 headers 0 lines) ---
Jun 25 12:08:14 DEBUG[10181] rtp.c: Got RTCP report of 52 bytes
Jun 25 12:08:15 DEBUG[8406] rtp.c: Got RTCP report of 52 bytes
Jun 25 12:08:15 DEBUG[10390] rtp.c: Got RTCP report of 52 bytes
Jun 25 12:08:15 DEBUG[1707] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER
Jun 25 12:08:15 DEBUG[1707] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER
Jun 25 12:08:15 DEBUG[1692] chan_sip.c: Checking device state for peer owner5
Jun 25 12:08:15 DEBUG[1692] devicestate.c: Changing state for SIP/owner5 - state 1 (Not in use)
Jun 25 12:08:15 DEBUG[1692] chan_sip.c: Checking device state for peer owner5
Jun 25 12:08:15 DEBUG[10412] app_queue.c: Device 'SIP/owner5' changed to state '1' (Not in use)
Jun 25 12:08:15 VERBOSE[1707] logger.c: Retransmitting #3 (no NAT) to 192.168.2.78:5060:
NOTIFY sip:owner8@192.168.2.78:5060;line=hl79r4jn SIP/2.0
Via: SIP/2.0/UDP 192.168.2.1:5060;branch=z9hG4bK7f360a0b;rport
From: <sip:350@192.168.2.1>;tag=as5cf6528c
To: <sip:owner8@192.168.2.1>;tag=zxawooyw0m
Contact: <sip:350@192.168.2.1>
Call-ID: 3c26700d2981-o1ry3yibkgjj@snom360-0004132388EC
CSeq: 423 NOTIFY
User-Agent: Asterisk PBX
Max-Forwards: 70
Event: dialog
Content-Type: application/dialog-info+xml
Subscription-State: active
Content-Length: 221

<?xml version="1.0"?>
<dialog-info xmlns="urn:ietf:params:xml:ns:dialog-info" version="321" state="full" entity="sip:350@192.168.2.1">
<dialog id="350" direction="recipient">
<state>early</state>
</dialog>
</dialog-info>

---
Jun 25 12:08:15 VERBOSE[1707] logger.c: 
<-- SIP read from 192.168.2.78:5060: 
SIP/2.0 200 Ok
Via: SIP/2.0/UDP 192.168.2.1:5060;branch=z9hG4bK7f360a0b;rport=5060
From: <sip:350@192.168.2.1>;tag=as5cf6528c
To: <sip:owner8@192.168.2.1>;tag=zxawooyw0m
Call-ID: 3c26700d2981-o1ry3yibkgjj@snom360-0004132388EC
CSeq: 423 NOTIFY
Content-Length: 0


Jun 25 12:08:15 VERBOSE[1707] logger.c: --- (7 headers 0 lines) ---
Jun 25 12:08:16 DEBUG[10405] rtp.c: Got RTCP report of 52 bytes
Jun 25 12:08:16 DEBUG[10392] rtp.c: Got RTCP report of 52 bytes
Jun 25 12:08:17 DEBUG[10405] rtp.c: Got RTCP report of 52 bytes
Jun 25 12:08:17 DEBUG[10363] rtp.c: Got RTCP report of 52 bytes
Jun 25 12:08:17 DEBUG[10392] rtp.c: Got RTCP report of 52 bytes
Jun 25 12:08:18 DEBUG[1707] chan_sip.c: Auto destroying call '46780cd67ebf0402757cbde476586b6c@81.17.52.108'
Jun 25 12:08:19 DEBUG[10405] rtp.c: Got RTCP report of 20 bytes
Jun 25 12:08:19 DEBUG[10181] rtp.c: Got RTCP report of 52 bytes
Jun 25 12:08:19 VERBOSE[1707] logger.c: Retransmitting #4 (no NAT) to 192.168.2.78:5060:
NOTIFY sip:owner8@192.168.2.78:5060;line=hl79r4jn SIP/2.0
Via: SIP/2.0/UDP 192.168.2.1:5060;branch=z9hG4bK7f360a0b;rport
From: <sip:350@192.168.2.1>;tag=as5cf6528c
To: <sip:owner8@192.168.2.1>;tag=zxawooyw0m
Contact: <sip:350@192.168.2.1>
Call-ID: 3c26700d2981-o1ry3yibkgjj@snom360-0004132388EC
CSeq: 423 NOTIFY
User-Agent: Asterisk PBX
Max-Forwards: 70
Event: dialog
Content-Type: application/dialog-info+xml
Subscription-State: active
Content-Length: 221

<?xml version="1.0"?>
<dialog-info xmlns="urn:ietf:params:xml:ns:dialog-info" version="321" state="full" entity="sip:350@192.168.2.1">
<dialog id="350" direction="recipient">
<state>early</state>
</dialog>
</dialog-info>

---
Jun 25 12:08:19 VERBOSE[1707] logger.c: 
<-- SIP read from 192.168.2.78:5060: 
SIP/2.0 200 Ok
Via: SIP/2.0/UDP 192.168.2.1:5060;branch=z9hG4bK7f360a0b;rport=5060
From: <sip:350@192.168.2.1>;tag=as5cf6528c
To: <sip:owner8@192.168.2.1>;tag=zxawooyw0m
Call-ID: 3c26700d2981-o1ry3yibkgjj@snom360-0004132388EC
CSeq: 423 NOTIFY
Content-Length: 0


Jun 25 12:08:19 VERBOSE[1707] logger.c: --- (7 headers 0 lines) ---
Jun 25 12:08:20 DEBUG[8406] rtp.c: Got RTCP report of 52 bytes
Jun 25 12:08:20 DEBUG[10390] rtp.c: Got RTCP report of 52 bytes
Jun 25 12:08:21 DEBUG[1707] chan_sip.c: **** Received SUBSCRIBE (10) - Command in SIP SUBSCRIBE
Jun 25 12:08:21 DEBUG[1707] chan_sip.c: Adding subscription for extension 120 context kantoor-subscribe for peer owner4
Jun 25 12:08:21 DEBUG[1707] chan_sip.c: Checking device state for peer owner4
Jun 25 12:08:21 DEBUG[1707] chan_sip.c: Checking device state for peer owner4
Jun 25 12:08:21 DEBUG[10405] rtp.c: Got RTCP report of 52 bytes
Jun 25 12:08:21 DEBUG[10392] rtp.c: Got RTCP report of 52 bytes
Jun 25 12:08:22 DEBUG[10363] rtp.c: Got RTCP report of 52 bytes
Jun 25 12:08:22 DEBUG[10392] rtp.c: Got RTCP report of 52 bytes
Jun 25 12:08:23 VERBOSE[1707] logger.c: Retransmitting #5 (no NAT) to 192.168.2.78:5060:
NOTIFY sip:owner8@192.168.2.78:5060;line=hl79r4jn SIP/2.0
Via: SIP/2.0/UDP 192.168.2.1:5060;branch=z9hG4bK7f360a0b;rport
From: <sip:350@192.168.2.1>;tag=as5cf6528c
To: <sip:owner8@192.168.2.1>;tag=zxawooyw0m
Contact: <sip:350@192.168.2.1>
Call-ID: 3c26700d2981-o1ry3yibkgjj@snom360-0004132388EC
CSeq: 423 NOTIFY
User-Agent: Asterisk PBX
Max-Forwards: 70
Event: dialog
Content-Type: application/dialog-info+xml
Subscription-State: active
Content-Length: 221

<?xml version="1.0"?>
<dialog-info xmlns="urn:ietf:params:xml:ns:dialog-info" version="321" state="full" entity="sip:350@192.168.2.1">
<dialog id="350" direction="recipient">
<state>early</state>
</dialog>
</dialog-info>

---
Jun 25 12:08:23 VERBOSE[1707] logger.c: 
<-- SIP read from 192.168.2.78:5060: 
SIP/2.0 200 Ok
Via: SIP/2.0/UDP 192.168.2.1:5060;branch=z9hG4bK7f360a0b;rport=5060
From: <sip:350@192.168.2.1>;tag=as5cf6528c
To: <sip:owner8@192.168.2.1>;tag=zxawooyw0m
Call-ID: 3c26700d2981-o1ry3yibkgjj@snom360-0004132388EC
CSeq: 423 NOTIFY
Content-Length: 0


Jun 25 12:08:23 VERBOSE[1707] logger.c: --- (7 headers 0 lines) ---
Jun 25 12:08:24 DEBUG[1707] chan_sip.c: **** Received BYE (8) - Command in SIP BYE
Jun 25 12:08:24 DEBUG[1707] chan_sip.c: Received bye, issuing owner hangup
Jun 25 12:08:24 DEBUG[10405] channel.c: Didn't get a frame from channel: SIP/owner26-097a09d8
Jun 25 12:08:24 DEBUG[10405] channel.c: Bridge stops bridging channels SIP/owner8-097d6f80 and SIP/owner26-097a09d8
Jun 25 12:08:24 DEBUG[10405] channel.c: Hanging up channel 'SIP/owner26-097a09d8'
Jun 25 12:08:24 DEBUG[10405] chan_sip.c: Hangup call SIP/owner26-097a09d8, SIP callid 413adcd26c19192b625832de1c61de41@192.168.2.1)
Jun 25 12:08:24 DEBUG[10405] chan_sip.c: update_call_counter(owner26) - decrement call limit counter
Jun 25 12:08:24 DEBUG[10405] chan_sip.c: Updating call counter for outgoing call
Jun 25 12:08:24 DEBUG[10405] app_dial.c: Exiting with DIALSTATUS=ANSWER.
Jun 25 12:08:24 DEBUG[10405] app_macro.c: Spawn extension (macro-page,s-Dial,1) exited non-zero on 'SIP/owner8-097d6f80' in macro 'page'
Jun 25 12:08:24 VERBOSE[10405] logger.c:   == Spawn extension (macro-page, s-Dial, 1) exited non-zero on 'SIP/owner8-097d6f80' in macro 'page'
Jun 25 12:08:24 DEBUG[10405] pbx.c: Spawn extension (macro-page,s-Dial,1) exited non-zero on 'SIP/owner8-097d6f80'
Jun 25 12:08:24 VERBOSE[10405] logger.c:   == Spawn extension (macro-page, s-Dial, 1) exited non-zero on 'SIP/owner8-097d6f80'
Jun 25 12:08:24 DEBUG[10405] channel.c: Hanging up channel 'SIP/owner8-097d6f80'
Jun 25 12:08:24 DEBUG[10405] chan_sip.c: Hangup call SIP/owner8-097d6f80, SIP callid 3c2cdbeede2b-uk2xcpvi3ozs@snom360-0004132388EC)
Jun 25 12:08:24 DEBUG[10405] chan_sip.c: update_call_counter(owner8) - decrement call limit counter
Jun 25 12:08:24 DEBUG[10405] chan_sip.c: Updating call counter for incoming call
Jun 25 12:08:24 VERBOSE[10405] logger.c: Scheduling destruction of call '3c2cdbeede2b-uk2xcpvi3ozs@snom360-0004132388EC' in 32000 ms
Jun 25 12:08:24 VERBOSE[10405] logger.c: set_destination: Parsing <sip:owner8@192.168.2.78:5060;line=hl79r4jn> for address/port to send to
Jun 25 12:08:24 VERBOSE[10405] logger.c: set_destination: set destination to 192.168.2.78, port 5060
Jun 25 12:08:24 VERBOSE[10405] logger.c: Reliably Transmitting (no NAT) to 192.168.2.78:5060:
BYE sip:owner8@192.168.2.78:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.2.1:5060;branch=z9hG4bK5589e83d;rport
From: <sip:351@192.168.2.1>;tag=as598f0ee6
To: "Wim Linge" <sip:owner8@192.168.2.1>;tag=3tf4ycnq8g
Call-ID: 3c2cdbeede2b-uk2xcpvi3ozs@snom360-0004132388EC
CSeq: 102 BYE
User-Agent: Asterisk PBX
Max-Forwards: 70
Content-Length: 0


---
Jun 25 12:08:24 DEBUG[1692] chan_sip.c: Checking device state for peer owner26
Jun 25 12:08:24 DEBUG[1692] devicestate.c: Changing state for SIP/owner26 - state 1 (Not in use)
Jun 25 12:08:24 DEBUG[1692] chan_sip.c: Checking device state for peer owner26
Jun 25 12:08:24 DEBUG[1692] chan_sip.c: Checking device state for peer owner26
Jun 25 12:08:24 VERBOSE[1692] logger.c:  Extension Changed 350 new state Idle for Notify User owner2
Jun 25 12:08:24 DEBUG[1692] chan_sip.c: Checking device state for peer owner26
Jun 25 12:08:24 VERBOSE[1692] logger.c:  Extension Changed 350 new state Idle for Notify User owner4
Jun 25 12:08:24 DEBUG[1692] chan_sip.c: Checking device state for peer owner26
Jun 25 12:08:24 VERBOSE[1692] logger.c:  Extension Changed 350 new state Idle for Notify User owner9
Jun 25 12:08:24 DEBUG[1692] chan_sip.c: Checking device state for peer owner26
Jun 25 12:08:24 VERBOSE[1692] logger.c:  Extension Changed 350 new state Idle for Notify User owner26
Jun 25 12:08:24 DEBUG[1692] chan_sip.c: Checking device state for peer owner26
Jun 25 12:08:24 VERBOSE[1692] logger.c: Reliably Transmitting (no NAT) to 192.168.2.78:5060:
NOTIFY sip:owner8@192.168.2.78:5060;line=hl79r4jn SIP/2.0
Via: SIP/2.0/UDP 192.168.2.1:5060;branch=z9hG4bK74214dfc;rport
From: <sip:350@192.168.2.1>;tag=as5cf6528c
To: <sip:owner8@192.168.2.1>;tag=zxawooyw0m
Contact: <sip:350@192.168.2.1>
Call-ID: 3c26700d2981-o1ry3yibkgjj@snom360-0004132388EC
CSeq: 425 NOTIFY
User-Agent: Asterisk PBX
Max-Forwards: 70
Event: dialog
Content-Type: application/dialog-info+xml
Subscription-State: active
Content-Length: 204

<?xml version="1.0"?>
<dialog-info xmlns="urn:ietf:params:xml:ns:dialog-info" version="323" state="full" entity="sip:350@192.168.2.1">
<dialog id="350">
<state>terminated</state>
</dialog>
</dialog-info>

---
Jun 25 12:08:24 VERBOSE[1692] logger.c:  Extension Changed 350 new state Idle for Notify User owner8
Jun 25 12:08:24 DEBUG[1692] chan_sip.c: Checking device state for peer owner8
Jun 25 12:08:24 DEBUG[1692] devicestate.c: Changing state for SIP/owner8 - state 1 (Not in use)
Jun 25 12:08:24 DEBUG[1692] chan_sip.c: Checking device state for peer owner8
Jun 25 12:08:24 DEBUG[1692] chan_sip.c: Checking device state for peer owner8
Jun 25 12:08:24 DEBUG[10413] app_queue.c: Device 'SIP/owner26' changed to state '1' (Not in use)
Jun 25 12:08:24 DEBUG[10414] app_queue.c: Device 'SIP/owner8' changed to state '1' (Not in use)
Jun 25 12:08:24 VERBOSE[1692] logger.c: Reliably Transmitting (no NAT) to 192.168.2.78:5060:
NOTIFY sip:owner8@192.168.2.78:5060;line=hl79r4jn SIP/2.0
Via: SIP/2.0/UDP 192.168.2.1:5060;branch=z9hG4bK6aa6536d;rport
From: <sip:160@192.168.2.1>;tag=as1d833d23
To: <sip:owner8@192.168.2.1>;tag=aqe2b2srjz
Contact: <sip:160@192.168.2.1>
Call-ID: 3c26700ce57e-mvj46o05otkl@snom360-0004132388EC
CSeq: 687 NOTIFY
User-Agent: Asterisk PBX
Max-Forwards: 70
Event: dialog
Content-Type: application/dialog-info+xml
Subscription-State: active
Content-Length: 204

<?xml version="1.0"?>
<dialog-info xmlns="urn:ietf:params:xml:ns:dialog-info" version="585" state="full" entity="sip:160@192.168.2.1">
<dialog id="160">
<state>terminated</state>
</dialog>
</dialog-info>

---
Jun 25 12:08:24 VERBOSE[1692] logger.c:  Extension Changed 160 new state Idle for Notify User owner8
Jun 25 12:08:24 DEBUG[1692] chan_sip.c: Checking device state for peer owner8
Jun 25 12:08:24 VERBOSE[1692] logger.c:  Extension Changed 160 new state Idle for Notify User owner2
Jun 25 12:08:24 DEBUG[1692] chan_sip.c: Checking device state for peer owner8
Jun 25 12:08:24 VERBOSE[1692] logger.c:  Extension Changed 160 new state Idle for Notify User owner4
Jun 25 12:08:24 DEBUG[1692] chan_sip.c: Checking device state for peer owner8
Jun 25 12:08:24 VERBOSE[1692] logger.c:  Extension Changed 160 new state Idle for Notify User owner9
Jun 25 12:08:24 VERBOSE[1707] logger.c: 
<-- SIP read from 192.168.2.78:5060: 
SIP/2.0 481 Call Leg/Transaction Does Not Exist
Via: SIP/2.0/UDP 192.168.2.1:5060;branch=z9hG4bK5589e83d;rport=5060
From: <sip:351@192.168.2.1>;tag=as598f0ee6
To: "Wim Linge" <sip:owner8@192.168.2.1>;tag=3tf4ycnq8g
Call-ID: 3c2cdbeede2b-uk2xcpvi3ozs@snom360-0004132388EC
CSeq: 102 BYE
Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE, PRACK, MESSAGE, INFO
Allow-Events: talk, hold, refer
Supported: timer, 100rel, replaces, callerid
Content-Length: 0


Jun 25 12:08:24 VERBOSE[1707] logger.c: --- (10 headers 0 lines) ---
Jun 25 12:08:24 VERBOSE[1707] logger.c: 
<-- SIP read from 192.168.2.78:5060: 
SIP/2.0 200 Ok
Via: SIP/2.0/UDP 192.168.2.1:5060;branch=z9hG4bK74214dfc;rport=5060
From: <sip:350@192.168.2.1>;tag=as5cf6528c
To: <sip:owner8@192.168.2.1>;tag=zxawooyw0m
Call-ID: 3c26700d2981-o1ry3yibkgjj@snom360-0004132388EC
CSeq: 425 NOTIFY
Content-Length: 0


Jun 25 12:08:24 VERBOSE[1707] logger.c: --- (7 headers 0 lines) ---
Jun 25 12:08:24 VERBOSE[1707] logger.c: 
<-- SIP read from 192.168.2.78:5060: 
SIP/2.0 200 Ok
Via: SIP/2.0/UDP 192.168.2.1:5060;branch=z9hG4bK6aa6536d;rport=5060
From: <sip:160@192.168.2.1>;tag=as1d833d23
To: <sip:owner8@192.168.2.1>;tag=aqe2b2srjz
Call-ID: 3c26700ce57e-mvj46o05otkl@snom360-0004132388EC
CSeq: 687 NOTIFY
Content-Length: 0