Jan 22 23:27:27 DEBUG[2266] chan_sip.c: Hangup call SIP/22-b051, SIP callid 002608555d52e59d7bd09a191d07b2b2@85.115.115.120)
Jan 22 23:27:27 DEBUG[2266] chan_sip.c: update_call_counter(22) - decrement call limit counter
Jan 22 23:27:27 DEBUG[2266] chan_sip.c: Updating call counter for outgoing call
Jan 22 23:27:27 DEBUG[2266] chan_sip.c: Acked pending invite 102
Jan 22 23:27:27 DEBUG[2266] chan_sip.c: Stopping retransmission on '002608555d52e59d7bd09a191d07b2b2@85.115.115.120' of Request 102: Match Found
Jan 22 23:27:27 DEBUG[2266] chan_sip.c: Stopping retransmission on '002608555d52e59d7bd09a191d07b2b2@85.115.115.120' of Request 102: Match Found
Jan 22 23:27:27 DEBUG[2266] app_dial.c: Exiting with DIALSTATUS=CANCEL.
Jan 22 23:27:27 DEBUG[2266] pbx.c: Spawn extension (sip_local,12,1) exited non-zero on 'SIP/14-b973'
Jan 22 23:27:27 DEBUG[2266] channel.c: Hanging up channel 'SIP/14-b973'
Jan 22 23:27:27 DEBUG[2266] chan_sip.c: Hangup call SIP/14-b973, SIP callid de7d913256035f24@eC10YWJsZXQ.)
Jan 22 23:27:27 DEBUG[2266] chan_sip.c: update_call_counter(14) - decrement call limit counter
Jan 22 23:27:27 DEBUG[2266] chan_sip.c: Updating call counter for incoming call
Jan 22 23:27:27 DEBUG[1252] chan_sip.c: Checking device state for peer 22
Jan 22 23:27:27 DEBUG[1252] devicestate.c: Changing state for SIP/22 - state 1 (Not in use)
Jan 22 23:27:27 DEBUG[1252] chan_sip.c: Checking device state for peer 22
Jan 22 23:27:27 DEBUG[1252] chan_sip.c: Checking device state for peer 22
Jan 22 23:27:27 VERBOSE[1252] logger.c:  Extension Changed 12 new state Idle for Notify User 16
Jan 22 23:27:27 DEBUG[1252] chan_sip.c: Checking device state for peer 14
Jan 22 23:27:27 DEBUG[1252] devicestate.c: Changing state for SIP/14 - state 1 (Not in use)
Jan 22 23:27:27 DEBUG[1252] chan_sip.c: Checking device state for peer 14
Jan 22 23:27:27 DEBUG[1252] chan_sip.c: Checking device state for peer 14
Jan 22 23:27:27 VERBOSE[1252] logger.c:  Extension Changed 14 new state Idle for Notify User 16
Jan 22 23:27:27 DEBUG[2270] app_queue.c: Device 'SIP/22' changed to state '1' (Not in use)
Jan 22 23:27:27 DEBUG[2271] app_queue.c: Device 'SIP/14' changed to state '1' (Not in use)
Jan 22 23:27:27 DEBUG[1259] chan_sip.c: = No match Their Call ID: 002608555d52e59d7bd09a191d07b2b2@85.115.115.120 Their Tag 5573737d-13c4-3a53b4b7-5032a6e-7d45 Our tag: as4cc1f5db
Jan 22 23:27:27 DEBUG[1259] chan_sip.c: = Found Their Call ID: de7d913256035f24@eC10YWJsZXQ. Their Tag c6313454 Our tag: as48a7cedf
Jan 22 23:27:27 DEBUG[1259] chan_sip.c: **** Received ACK (6) - Command in SIP ACK
Jan 22 23:27:27 DEBUG[1259] chan_sip.c: Stopping retransmission on 'de7d913256035f24@eC10YWJsZXQ.' of Response 2: Match Found
Jan 22 23:27:27 DEBUG[1259] chan_sip.c: = Found Their Call ID: 002608555d52e59d7bd09a191d07b2b2@85.115.115.120 Their Tag 5573737d-13c4-3a53b4b7-5032a6e-7d45 Our tag: as4cc1f5db
Jan 22 23:27:27 DEBUG[1259] chan_sip.c: Stopping retransmission on '002608555d52e59d7bd09a191d07b2b2@85.115.115.120' of Request 102: Match Not Found
Jan 22 23:27:27 DEBUG[1259] chan_sip.c: = No match Their Call ID: 002608555d52e59d7bd09a191d07b2b2@85.115.115.120 Their Tag 5573737d-13c4-3a53b4b7-5032a6e-7d45 Our tag: as4cc1f5db
Jan 22 23:27:27 DEBUG[1259] chan_sip.c: = No match Their Call ID: fc34a85de64ccc43@eC1jZjQxZmQyMTYyYTU0 Their Tag 5554a510 Our tag: as2ed931b1
Jan 22 23:27:27 DEBUG[1259] chan_sip.c: = No match Their Call ID: 6a2d912c9c79243e@eC1jZjQxZmQyMTYyYTU0 Their Tag a311ea09 Our tag: as411deffe
Jan 22 23:27:27 DEBUG[1259] chan_sip.c: = No match Their Call ID: 6a0c3275726b993a@eC1jZjQxZmQyMTYyYTU0 Their Tag 2d41f126 Our tag: as37fb3f83
Jan 22 23:27:27 DEBUG[1259] chan_sip.c: = Found Their Call ID: ef245f3ce45e0464@eC1jZjQxZmQyMTYyYTU0 Their Tag f51adc09 Our tag: as05c66172
Jan 22 23:27:27 DEBUG[1259] chan_sip.c: Stopping retransmission on 'ef245f3ce45e0464@eC1jZjQxZmQyMTYyYTU0' of Request 104: Match Found
Jan 22 23:27:27 DEBUG[1259] chan_sip.c: = Found Their Call ID: 002608555d52e59d7bd09a191d07b2b2@85.115.115.120 Their Tag 5573737d-13c4-3a53b4b7-5032a6e-7d45 Our tag: as4cc1f5db
Jan 22 23:27:27 DEBUG[1259] chan_sip.c: Stopping retransmission on '002608555d52e59d7bd09a191d07b2b2@85.115.115.120' of Request 102: Match Not Found
Jan 22 23:27:27 DEBUG[1259] chan_sip.c: Updating call counter for outgoing call
Jan 22 23:27:27 DEBUG[1259] chan_sip.c: = No match Their Call ID: fc34a85de64ccc43@eC1jZjQxZmQyMTYyYTU0 Their Tag 5554a510 Our tag: as2ed931b1
Jan 22 23:27:27 DEBUG[1259] chan_sip.c: = Found Their Call ID: 6a2d912c9c79243e@eC1jZjQxZmQyMTYyYTU0 Their Tag a311ea09 Our tag: as411deffe
Jan 22 23:27:27 DEBUG[1259] chan_sip.c: Stopping retransmission on '6a2d912c9c79243e@eC1jZjQxZmQyMTYyYTU0' of Request 110: Match Found
Jan 22 23:27:37 DEBUG[1259] chan_sip.c: Allocating new SIP dialog for 75a7171528998262665262d23ede086a@217.199.102.230 - REGISTER (No RTP)
Jan 22 23:27:37 DEBUG[1259] chan_sip.c: Scheduled a registration timeout for 85.115.115.29 id  #644 
Jan 22 23:27:37 VERBOSE[1259] logger.c: REGISTER attempt 1 to xhome@85.115.115.29
Jan 22 23:27:37 DEBUG[1259] chan_sip.c: = Found Their Call ID: 75a7171528998262665262d23ede086a@217.199.102.230 Their Tag  Our tag: as0892ad7a
Jan 22 23:27:37 DEBUG[1259] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '75a7171528998262665262d23ede086a@217.199.102.230' Request 146: Found
Jan 22 23:27:37 DEBUG[1259] chan_sip.c: = Found Their Call ID: 75a7171528998262665262d23ede086a@217.199.102.230 Their Tag  Our tag: as0892ad7a
Jan 22 23:27:37 DEBUG[1259] chan_sip.c: Stopping retransmission on '75a7171528998262665262d23ede086a@217.199.102.230' of Request 146: Match Found
Jan 22 23:27:37 DEBUG[1259] chan_sip.c: Registration successful
Jan 22 23:27:37 DEBUG[1259] chan_sip.c: Cancelling timeout 644
Jan 22 23:27:39 DEBUG[1259] chan_sip.c: = No match Their Call ID: 75a7171528998262665262d23ede086a@217.199.102.230 Their Tag as376f9816 Our tag: as0892ad7a
Jan 22 23:27:39 DEBUG[1259] chan_sip.c: = No match Their Call ID: fc34a85de64ccc43@eC1jZjQxZmQyMTYyYTU0 Their Tag 5554a510 Our tag: as2ed931b1
Jan 22 23:27:39 DEBUG[1259] chan_sip.c: = No match Their Call ID: 6a2d912c9c79243e@eC1jZjQxZmQyMTYyYTU0 Their Tag a311ea09 Our tag: as411deffe
Jan 22 23:27:39 DEBUG[1259] chan_sip.c: = No match Their Call ID: 6a0c3275726b993a@eC1jZjQxZmQyMTYyYTU0 Their Tag 2d41f126 Our tag: as37fb3f83
Jan 22 23:27:39 DEBUG[1259] chan_sip.c: = No match Their Call ID: ef245f3ce45e0464@eC1jZjQxZmQyMTYyYTU0 Their Tag f51adc09 Our tag: as05c66172
Jan 22 23:27:39 DEBUG[1259] chan_sip.c: = No match Their Call ID: f946ef361e21dc06@eC1jZjQxZmQyMTYyYTU0 Their Tag c2136303 Our tag: as0f3e8f09
Jan 22 23:27:39 DEBUG[1259] chan_sip.c: = No match Their Call ID: 9a682238076c9b39@eC10YWJsZXQ. Their Tag 82326652 Our tag: as60cb4f26
Jan 22 23:27:39 DEBUG[1259] chan_sip.c: = No match Their Call ID: a23db80850416406@eC10YWJsZXQ. Their Tag cf1c2a24 Our tag: as0b3ae19f
Jan 22 23:27:39 DEBUG[1259] chan_sip.c: = No match Their Call ID: 75206263367b9c0e@eA.. Their Tag 583df86f Our tag: as44a5c203
Jan 22 23:27:39 DEBUG[1259] chan_sip.c: = No match Their Call ID: de7dcb1f88458a63@eA.. Their Tag c2251a24 Our tag: as56ef1190
Jan 22 23:27:39 DEBUG[1259] chan_sip.c: Allocating new SIP dialog for fa1c5255dd702836@YWNlci1mN2tlNWhtZGUw - INVITE (With RTP)
Jan 22 23:27:39 DEBUG[1259] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE
Jan 22 23:27:39 DEBUG[1259] chan_sip.c: * SIP extension value: 2048 for call fa1c5255dd702836@YWNlci1mN2tlNWhtZGUw
Jan 22 23:27:39 DEBUG[1259] chan_sip.c: Setting NAT on RTP to 0
Jan 22 23:27:39 DEBUG[1259] chan_sip.c: Setting NAT on VRTP to 0
Jan 22 23:27:39 DEBUG[1259] chan_sip.c: = Found Their Call ID: fa1c5255dd702836@YWNlci1mN2tlNWhtZGUw Their Tag 9e41b211 Our tag: as3d8d66ed
Jan 22 23:27:39 DEBUG[1259] chan_sip.c: **** Received ACK (6) - Command in SIP ACK
Jan 22 23:27:39 DEBUG[1259] chan_sip.c: Stopping retransmission on 'fa1c5255dd702836@YWNlci1mN2tlNWhtZGUw' of Response 1: Match Found
Jan 22 23:27:39 DEBUG[1259] chan_sip.c: = Found Their Call ID: fa1c5255dd702836@YWNlci1mN2tlNWhtZGUw Their Tag 9e41b211 Our tag: as3d8d66ed
Jan 22 23:27:39 DEBUG[1259] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE
Jan 22 23:27:39 DEBUG[1259] chan_sip.c: Setting NAT on RTP to 0
Jan 22 23:27:39 DEBUG[1259] chan_sip.c: Setting NAT on VRTP to 0
Jan 22 23:27:39 DEBUG[1259] chan_sip.c: Checking SIP call limits for device 15
Jan 22 23:27:39 DEBUG[1259] chan_sip.c: Updating call counter for incoming call
Jan 22 23:27:39 DEBUG[1259] chan_sip.c: build_route: Contact hop: <sip:15@85.115.115.117:7098>
Jan 22 23:27:39 DEBUG[1252] chan_sip.c: Checking device state for peer 15
Jan 22 23:27:39 DEBUG[1252] channel.c: Avoiding initial deadlock for 'SIP/15-dd67'
Jan 22 23:27:39 DEBUG[2272] pbx.c: Launching 'Dial'
Jan 22 23:27:39 VERBOSE[2272] logger.c:     -- Executing Dial("SIP/15-dd67", "SIP/13") in new stack
Jan 22 23:27:39 DEBUG[2272] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - INVITE (With RTP)
Jan 22 23:27:39 DEBUG[2272] chan_sip.c: Setting NAT on RTP to 0
Jan 22 23:27:39 DEBUG[2272] chan_sip.c: Setting NAT on VRTP to 0
Jan 22 23:27:39 DEBUG[1252] devicestate.c: Changing state for SIP/15 - state 2 (In use)
Jan 22 23:27:39 DEBUG[1252] chan_sip.c: Checking device state for peer 15
Jan 22 23:27:39 DEBUG[1252] chan_sip.c: Checking device state for peer 15
Jan 22 23:27:39 VERBOSE[1252] logger.c:  Extension Changed 15 new state InUse for Notify User 16
Jan 22 23:27:39 DEBUG[2272] channel.c: Not copying variable STACK-sip_local-13-1.
Jan 22 23:27:39 DEBUG[2272] channel.c: Not copying variable SIPCALLID.
Jan 22 23:27:39 DEBUG[2272] channel.c: Not copying variable SIPUSERAGENT.
Jan 22 23:27:39 DEBUG[2272] channel.c: Not copying variable SIPDOMAIN.
Jan 22 23:27:39 DEBUG[2272] channel.c: Not copying variable SIPURI.
Jan 22 23:27:39 DEBUG[2272] chan_sip.c: Outgoing Call for 13
Jan 22 23:27:39 DEBUG[2272] chan_sip.c: Updating call counter for outgoing call
Jan 22 23:27:39 VERBOSE[2272] logger.c:     -- Called 13
Jan 22 23:27:39 DEBUG[2272] channel.c: Set channel SIP/13-b8eb to read format ulaw
Jan 22 23:27:39 DEBUG[2272] channel.c: Set channel SIP/15-dd67 to write format ulaw
Jan 22 23:27:39 DEBUG[2272] channel.c: Set channel SIP/15-dd67 to read format ulaw
Jan 22 23:27:39 DEBUG[2272] channel.c: Set channel SIP/13-b8eb to write format ulaw
Jan 22 23:27:39 DEBUG[2273] app_queue.c: Device 'SIP/15' changed to state '2' (In use)
Jan 22 23:27:39 DEBUG[2272] rtp.c: Got RTCP report of 72 bytes
Jan 22 23:27:39 DEBUG[2272] rtp.c: Got RTCP report of 72 bytes
Jan 22 23:27:39 DEBUG[1259] chan_sip.c: = Found Their Call ID: 6974949a09dc1cde3d3743801baf5a1e@85.115.115.120 Their Tag  Our tag: as5fee7988
Jan 22 23:27:39 DEBUG[1259] chan_sip.c: Acked pending invite 102
Jan 22 23:27:39 DEBUG[1259] chan_sip.c: Stopping retransmission on '6974949a09dc1cde3d3743801baf5a1e@85.115.115.120' of Request 102: Match Found
Jan 22 23:27:39 DEBUG[1259] chan_sip.c: SIP response 200 to standard invite
Jan 22 23:27:39 DEBUG[1259] chan_sip.c: build_route: Contact hop: <sip:13@85.115.115.115:7080>
Jan 22 23:27:39 VERBOSE[2272] logger.c:     -- SIP/13-b8eb answered SIP/15-dd67
Jan 22 23:27:39 DEBUG[2272] channel.c: Set channel SIP/15-dd67 to read format ulaw
Jan 22 23:27:39 DEBUG[2272] channel.c: Set channel SIP/13-b8eb to write format ulaw
Jan 22 23:27:39 DEBUG[2272] channel.c: Set channel SIP/13-b8eb to read format ulaw
Jan 22 23:27:39 DEBUG[2272] channel.c: Set channel SIP/15-dd67 to write format ulaw
Jan 22 23:27:39 DEBUG[2272] chan_sip.c: sip_answer(SIP/15-dd67)
Jan 22 23:27:39 VERBOSE[2272] logger.c:     -- Attempting native bridge of SIP/15-dd67 and SIP/13-b8eb
Jan 22 23:27:39 DEBUG[1252] chan_sip.c: Checking device state for peer 13
Jan 22 23:27:39 DEBUG[1252] devicestate.c: Changing state for SIP/13 - state 2 (In use)
Jan 22 23:27:39 DEBUG[1252] chan_sip.c: Checking device state for peer 13
Jan 22 23:27:39 DEBUG[1252] chan_sip.c: Checking device state for peer 13
Jan 22 23:27:39 VERBOSE[1252] logger.c:  Extension Changed 13 new state InUse for Notify User 16
Jan 22 23:27:39 DEBUG[1252] chan_sip.c: Checking device state for peer 13
Jan 22 23:27:39 VERBOSE[1252] logger.c:  Extension Changed 13 new state InUse for Notify User 14
Jan 22 23:27:39 DEBUG[1252] chan_sip.c: Checking device state for peer 15
Jan 22 23:27:39 DEBUG[1252] devicestate.c: Changing state for SIP/15 - state 2 (In use)
Jan 22 23:27:39 DEBUG[1252] chan_sip.c: Checking device state for peer 15
Jan 22 23:27:39 DEBUG[2274] app_queue.c: Device 'SIP/13' changed to state '2' (In use)
Jan 22 23:27:39 DEBUG[2275] app_queue.c: Device 'SIP/15' changed to state '2' (In use)
Jan 22 23:27:39 DEBUG[1259] chan_sip.c: = No match Their Call ID: 6974949a09dc1cde3d3743801baf5a1e@85.115.115.120 Their Tag eb18c800 Our tag: as5fee7988
Jan 22 23:27:39 DEBUG[1259] chan_sip.c: = No match Their Call ID: fa1c5255dd702836@YWNlci1mN2tlNWhtZGUw Their Tag 9e41b211 Our tag: as4d80d2b8
Jan 22 23:27:39 DEBUG[1259] chan_sip.c: = No match Their Call ID: 75a7171528998262665262d23ede086a@217.199.102.230 Their Tag as376f9816 Our tag: as0892ad7a
Jan 22 23:27:39 DEBUG[1259] chan_sip.c: = Found Their Call ID: fc34a85de64ccc43@eC1jZjQxZmQyMTYyYTU0 Their Tag 5554a510 Our tag: as2ed931b1
Jan 22 23:27:39 DEBUG[1259] chan_sip.c: Stopping retransmission on 'fc34a85de64ccc43@eC1jZjQxZmQyMTYyYTU0' of Request 105: Match Found
Jan 22 23:27:39 DEBUG[2272] rtp.c: Got RTCP report of 72 bytes
Jan 22 23:27:40 DEBUG[1259] chan_sip.c: = No match Their Call ID: 6974949a09dc1cde3d3743801baf5a1e@85.115.115.120 Their Tag eb18c800 Our tag: as5fee7988
Jan 22 23:27:40 DEBUG[1259] chan_sip.c: = No match Their Call ID: fa1c5255dd702836@YWNlci1mN2tlNWhtZGUw Their Tag 9e41b211 Our tag: as4d80d2b8
Jan 22 23:27:40 DEBUG[1259] chan_sip.c: = No match Their Call ID: 75a7171528998262665262d23ede086a@217.199.102.230 Their Tag as376f9816 Our tag: as0892ad7a
Jan 22 23:27:40 DEBUG[1259] chan_sip.c: = No match Their Call ID: fc34a85de64ccc43@eC1jZjQxZmQyMTYyYTU0 Their Tag 5554a510 Our tag: as2ed931b1
Jan 22 23:27:40 DEBUG[1259] chan_sip.c: = No match Their Call ID: 6a2d912c9c79243e@eC1jZjQxZmQyMTYyYTU0 Their Tag a311ea09 Our tag: as411deffe
Jan 22 23:27:40 DEBUG[1259] chan_sip.c: = No match Their Call ID: 6a0c3275726b993a@eC1jZjQxZmQyMTYyYTU0 Their Tag 2d41f126 Our tag: as37fb3f83
Jan 22 23:27:40 DEBUG[1259] chan_sip.c: = No match Their Call ID: ef245f3ce45e0464@eC1jZjQxZmQyMTYyYTU0 Their Tag f51adc09 Our tag: as05c66172
Jan 22 23:27:40 DEBUG[1259] chan_sip.c: = No match Their Call ID: f946ef361e21dc06@eC1jZjQxZmQyMTYyYTU0 Their Tag c2136303 Our tag: as0f3e8f09
Jan 22 23:27:40 DEBUG[1259] chan_sip.c: = Found Their Call ID: 9a682238076c9b39@eC10YWJsZXQ. Their Tag 82326652 Our tag: as60cb4f26
Jan 22 23:27:40 DEBUG[1259] chan_sip.c: Stopping retransmission on '9a682238076c9b39@eC10YWJsZXQ.' of Request 104: Match Found
Jan 22 23:27:40 DEBUG[1259] chan_sip.c: = No match Their Call ID: 6974949a09dc1cde3d3743801baf5a1e@85.115.115.120 Their Tag eb18c800 Our tag: as5fee7988
Jan 22 23:27:40 DEBUG[1259] chan_sip.c: = No match Their Call ID: fa1c5255dd702836@YWNlci1mN2tlNWhtZGUw Their Tag 9e41b211 Our tag: as4d80d2b8
Jan 22 23:27:40 DEBUG[1259] chan_sip.c: = No match Their Call ID: 75a7171528998262665262d23ede086a@217.199.102.230 Their Tag as376f9816 Our tag: as0892ad7a
Jan 22 23:27:40 DEBUG[1259] chan_sip.c: = No match Their Call ID: fc34a85de64ccc43@eC1jZjQxZmQyMTYyYTU0 Their Tag 5554a510 Our tag: as2ed931b1
Jan 22 23:27:40 DEBUG[1259] chan_sip.c: = No match Their Call ID: 6a2d912c9c79243e@eC1jZjQxZmQyMTYyYTU0 Their Tag a311ea09 Our tag: as411deffe
Jan 22 23:27:40 DEBUG[1259] chan_sip.c: = No match Their Call ID: 6a0c3275726b993a@eC1jZjQxZmQyMTYyYTU0 Their Tag 2d41f126 Our tag: as37fb3f83
Jan 22 23:27:40 DEBUG[1259] chan_sip.c: = No match Their Call ID: ef245f3ce45e0464@eC1jZjQxZmQyMTYyYTU0 Their Tag f51adc09 Our tag: as05c66172
Jan 22 23:27:40 DEBUG[1259] chan_sip.c: = No match Their Call ID: f946ef361e21dc06@eC1jZjQxZmQyMTYyYTU0 Their Tag c2136303 Our tag: as0f3e8f09
Jan 22 23:27:40 DEBUG[1259] chan_sip.c: = Found Their Call ID: 9a682238076c9b39@eC10YWJsZXQ. Their Tag 82326652 Our tag: as60cb4f26
Jan 22 23:27:40 DEBUG[1259] chan_sip.c: **** Received SUBSCRIBE (10) - Command in SIP SUBSCRIBE
Jan 22 23:27:40 DEBUG[1259] chan_sip.c: Adding subscription for extension 13 context sip_local for peer 14
Jan 22 23:27:40 DEBUG[1259] chan_sip.c: Checking device state for peer 13
Jan 22 23:27:40 DEBUG[1259] chan_sip.c: Checking device state for peer 13
Jan 22 23:27:40 DEBUG[1259] chan_sip.c: = No match Their Call ID: 6974949a09dc1cde3d3743801baf5a1e@85.115.115.120 Their Tag eb18c800 Our tag: as5fee7988
Jan 22 23:27:40 DEBUG[1259] chan_sip.c: = Found Their Call ID: fa1c5255dd702836@YWNlci1mN2tlNWhtZGUw Their Tag 9e41b211 Our tag: as4d80d2b8
Jan 22 23:27:40 DEBUG[1259] chan_sip.c: **** Received ACK (6) - Command in SIP ACK
Jan 22 23:27:40 DEBUG[1259] chan_sip.c: Stopping retransmission on 'fa1c5255dd702836@YWNlci1mN2tlNWhtZGUw' of Response 2: Match Found
Jan 22 23:27:40 DEBUG[1259] chan_sip.c: = No match Their Call ID: 6974949a09dc1cde3d3743801baf5a1e@85.115.115.120 Their Tag eb18c800 Our tag: as5fee7988
Jan 22 23:27:40 DEBUG[1259] chan_sip.c: = No match Their Call ID: fa1c5255dd702836@YWNlci1mN2tlNWhtZGUw Their Tag 9e41b211 Our tag: as4d80d2b8
Jan 22 23:27:40 DEBUG[1259] chan_sip.c: = No match Their Call ID: 75a7171528998262665262d23ede086a@217.199.102.230 Their Tag as376f9816 Our tag: as0892ad7a
Jan 22 23:27:40 DEBUG[1259] chan_sip.c: = No match Their Call ID: fc34a85de64ccc43@eC1jZjQxZmQyMTYyYTU0 Their Tag 5554a510 Our tag: as2ed931b1
Jan 22 23:27:40 DEBUG[1259] chan_sip.c: = No match Their Call ID: 6a2d912c9c79243e@eC1jZjQxZmQyMTYyYTU0 Their Tag a311ea09 Our tag: as411deffe
Jan 22 23:27:40 DEBUG[1259] chan_sip.c: = Found Their Call ID: 6a0c3275726b993a@eC1jZjQxZmQyMTYyYTU0 Their Tag 2d41f126 Our tag: as37fb3f83
Jan 22 23:27:40 DEBUG[1259] chan_sip.c: Stopping retransmission on '6a0c3275726b993a@eC1jZjQxZmQyMTYyYTU0' of Request 103: Match Found
Jan 22 23:27:40 DEBUG[2272] rtp.c: Ooh, format changed from unknown to ulaw
Jan 22 23:27:40 DEBUG[2272] rtp.c: Ooh, format changed from unknown to ulaw
Jan 22 23:27:40 DEBUG[1259] chan_sip.c: = No match Their Call ID: 6974949a09dc1cde3d3743801baf5a1e@85.115.115.120 Their Tag eb18c800 Our tag: as5fee7988
Jan 22 23:27:40 DEBUG[1259] chan_sip.c: = No match Their Call ID: fa1c5255dd702836@YWNlci1mN2tlNWhtZGUw Their Tag 9e41b211 Our tag: as4d80d2b8
Jan 22 23:27:40 DEBUG[1259] chan_sip.c: = No match Their Call ID: 75a7171528998262665262d23ede086a@217.199.102.230 Their Tag as376f9816 Our tag: as0892ad7a
Jan 22 23:27:40 DEBUG[1259] chan_sip.c: = No match Their Call ID: fc34a85de64ccc43@eC1jZjQxZmQyMTYyYTU0 Their Tag 5554a510 Our tag: as2ed931b1
Jan 22 23:27:40 DEBUG[1259] chan_sip.c: = No match Their Call ID: 6a2d912c9c79243e@eC1jZjQxZmQyMTYyYTU0 Their Tag a311ea09 Our tag: as411deffe
Jan 22 23:27:40 DEBUG[1259] chan_sip.c: = No match Their Call ID: 6a0c3275726b993a@eC1jZjQxZmQyMTYyYTU0 Their Tag 2d41f126 Our tag: as37fb3f83
Jan 22 23:27:40 DEBUG[1259] chan_sip.c: = No match Their Call ID: ef245f3ce45e0464@eC1jZjQxZmQyMTYyYTU0 Their Tag f51adc09 Our tag: as05c66172
Jan 22 23:27:40 DEBUG[1259] chan_sip.c: = No match Their Call ID: f946ef361e21dc06@eC1jZjQxZmQyMTYyYTU0 Their Tag c2136303 Our tag: as0f3e8f09
Jan 22 23:27:40 DEBUG[1259] chan_sip.c: = Found Their Call ID: 9a682238076c9b39@eC10YWJsZXQ. Their Tag 82326652 Our tag: as60cb4f26
Jan 22 23:27:40 DEBUG[1259] chan_sip.c: Stopping retransmission on '9a682238076c9b39@eC10YWJsZXQ.' of Request 105: Match Found
Jan 22 23:27:44 DEBUG[1259] chan_sip.c: = No match Their Call ID: 6974949a09dc1cde3d3743801baf5a1e@85.115.115.120 Their Tag eb18c800 Our tag: as5fee7988
Jan 22 23:27:44 DEBUG[1259] chan_sip.c: = No match Their Call ID: fa1c5255dd702836@YWNlci1mN2tlNWhtZGUw Their Tag 9e41b211 Our tag: as4d80d2b8
Jan 22 23:27:44 DEBUG[1259] chan_sip.c: = No match Their Call ID: 75a7171528998262665262d23ede086a@217.199.102.230 Their Tag as376f9816 Our tag: as0892ad7a
Jan 22 23:27:44 DEBUG[1259] chan_sip.c: = No match Their Call ID: fc34a85de64ccc43@eC1jZjQxZmQyMTYyYTU0 Their Tag 5554a510 Our tag: as2ed931b1
Jan 22 23:27:44 DEBUG[1259] chan_sip.c: = No match Their Call ID: 6a2d912c9c79243e@eC1jZjQxZmQyMTYyYTU0 Their Tag a311ea09 Our tag: as411deffe
Jan 22 23:27:44 DEBUG[1259] chan_sip.c: = No match Their Call ID: 6a0c3275726b993a@eC1jZjQxZmQyMTYyYTU0 Their Tag 2d41f126 Our tag: as37fb3f83
Jan 22 23:27:44 DEBUG[1259] chan_sip.c: = No match Their Call ID: ef245f3ce45e0464@eC1jZjQxZmQyMTYyYTU0 Their Tag f51adc09 Our tag: as05c66172
Jan 22 23:27:44 DEBUG[1259] chan_sip.c: = No match Their Call ID: f946ef361e21dc06@eC1jZjQxZmQyMTYyYTU0 Their Tag c2136303 Our tag: as0f3e8f09
Jan 22 23:27:44 DEBUG[1259] chan_sip.c: = No match Their Call ID: a23db80850416406@eC10YWJsZXQ. Their Tag cf1c2a24 Our tag: as0b3ae19f
Jan 22 23:27:44 DEBUG[1259] chan_sip.c: = No match Their Call ID: 75206263367b9c0e@eA.. Their Tag 583df86f Our tag: as44a5c203
Jan 22 23:27:44 DEBUG[1259] chan_sip.c: = No match Their Call ID: de7dcb1f88458a63@eA.. Their Tag c2251a24 Our tag: as56ef1190
Jan 22 23:27:44 DEBUG[1259] chan_sip.c: Allocating new SIP dialog for 2a6c5da225250fdd5ee9ec55671af693@217.199.98.33 - REGISTER (No RTP)
Jan 22 23:27:44 DEBUG[1259] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER
Jan 22 23:27:44 DEBUG[1252] chan_sip.c: Checking device state for peer xoffice
Jan 22 23:27:44 DEBUG[1252] devicestate.c: Changing state for SIP/xoffice - state 1 (Not in use)
Jan 22 23:27:44 DEBUG[2277] app_queue.c: Device 'SIP/xoffice' changed to state '1' (Not in use)
Jan 22 23:27:45 DEBUG[2272] rtp.c: Got RTCP report of 52 bytes
Jan 22 23:27:45 DEBUG[2272] rtp.c: Got RTCP report of 72 bytes
Jan 22 23:27:45 DEBUG[2272] rtp.c: Got RTCP report of 52 bytes
Jan 22 23:27:45 DEBUG[2272] rtp.c: Got RTCP report of 8 bytes
Jan 22 23:27:45 DEBUG[2272] rtp.c: Got RTCP report of 72 bytes
Jan 22 23:27:45 DEBUG[2272] rtp.c: Got RTCP report of 72 bytes
Jan 22 23:27:52 DEBUG[2272] rtp.c: Got RTCP report of 12 bytes
Jan 22 23:27:52 DEBUG[2272] rtp.c: Got RTCP report of 12 bytes
Jan 22 23:27:52 DEBUG[1259] chan_sip.c: = No match Their Call ID: 2a6c5da225250fdd5ee9ec55671af693@217.199.98.33 Their Tag as5ca0cdd0 Our tag: as7a7ea642
Jan 22 23:27:52 DEBUG[1259] chan_sip.c: = Found Their Call ID: 6974949a09dc1cde3d3743801baf5a1e@85.115.115.120 Their Tag eb18c800 Our tag: as5fee7988
Jan 22 23:27:52 DEBUG[1259] chan_sip.c: **** Received BYE (8) - Command in SIP BYE
Jan 22 23:27:52 DEBUG[2272] channel.c: Didn't get a frame from channel: SIP/13-b8eb
Jan 22 23:27:52 DEBUG[2272] channel.c: Bridge stops bridging channels SIP/15-dd67 and SIP/13-b8eb
Jan 22 23:27:52 DEBUG[2272] channel.c: Hanging up channel 'SIP/13-b8eb'
Jan 22 23:27:52 DEBUG[2272] chan_sip.c: Hangup call SIP/13-b8eb, SIP callid 6974949a09dc1cde3d3743801baf5a1e@85.115.115.120)
Jan 22 23:27:52 DEBUG[2272] chan_sip.c: update_call_counter(13) - decrement call limit counter
Jan 22 23:27:52 DEBUG[2272] chan_sip.c: Updating call counter for outgoing call
Jan 22 23:27:52 DEBUG[2272] app_dial.c: Exiting with DIALSTATUS=ANSWER.
Jan 22 23:27:52 DEBUG[2272] pbx.c: Spawn extension (sip_local,13,1) exited non-zero on 'SIP/15-dd67'
Jan 22 23:27:52 DEBUG[2272] channel.c: Hanging up channel 'SIP/15-dd67'
Jan 22 23:27:52 DEBUG[2272] chan_sip.c: Hangup call SIP/15-dd67, SIP callid fa1c5255dd702836@YWNlci1mN2tlNWhtZGUw)
Jan 22 23:27:52 DEBUG[2272] chan_sip.c: update_call_counter(15) - decrement call limit counter
Jan 22 23:27:52 DEBUG[2272] chan_sip.c: Updating call counter for incoming call
Jan 22 23:27:52 DEBUG[1252] chan_sip.c: Checking device state for peer 13
Jan 22 23:27:52 DEBUG[1252] devicestate.c: Changing state for SIP/13 - state 1 (Not in use)
Jan 22 23:27:52 DEBUG[1252] chan_sip.c: Checking device state for peer 13
Jan 22 23:27:52 DEBUG[1252] chan_sip.c: Checking device state for peer 13
Jan 22 23:27:52 VERBOSE[1252] logger.c:  Extension Changed 13 new state Idle for Notify User 16
Jan 22 23:27:52 DEBUG[1252] chan_sip.c: Checking device state for peer 15
Jan 22 23:27:52 DEBUG[1252] devicestate.c: Changing state for SIP/15 - state 1 (Not in use)
Jan 22 23:27:52 DEBUG[1252] chan_sip.c: Checking device state for peer 15
Jan 22 23:27:52 DEBUG[1252] chan_sip.c: Checking device state for peer 15
Jan 22 23:27:52 VERBOSE[1252] logger.c:  Extension Changed 15 new state Idle for Notify User 16
Jan 22 23:27:52 DEBUG[2278] app_queue.c: Device 'SIP/13' changed to state '1' (Not in use)
Jan 22 23:27:52 DEBUG[2279] app_queue.c: Device 'SIP/15' changed to state '1' (Not in use)
Jan 22 23:27:52 DEBUG[1259] chan_sip.c: = No match Their Call ID: 2a6c5da225250fdd5ee9ec55671af693@217.199.98.33 Their Tag as5ca0cdd0 Our tag: as7a7ea642
Jan 22 23:27:52 DEBUG[1259] chan_sip.c: = No match Their Call ID: 6974949a09dc1cde3d3743801baf5a1e@85.115.115.120 Their Tag eb18c800 Our tag: as5fee7988
Jan 22 23:27:52 DEBUG[1259] chan_sip.c: = No match Their Call ID: fa1c5255dd702836@YWNlci1mN2tlNWhtZGUw Their Tag 9e41b211 Our tag: as4d80d2b8
Jan 22 23:27:52 DEBUG[1259] chan_sip.c: = No match Their Call ID: 75a7171528998262665262d23ede086a@217.199.102.230 Their Tag as376f9816 Our tag: as0892ad7a
Jan 22 23:27:52 DEBUG[1259] chan_sip.c: = No match Their Call ID: fc34a85de64ccc43@eC1jZjQxZmQyMTYyYTU0 Their Tag 5554a510 Our tag: as2ed931b1
Jan 22 23:27:52 DEBUG[1259] chan_sip.c: = No match Their Call ID: 6a2d912c9c79243e@eC1jZjQxZmQyMTYyYTU0 Their Tag a311ea09 Our tag: as411deffe
Jan 22 23:27:52 DEBUG[1259] chan_sip.c: = Found Their Call ID: 6a0c3275726b993a@eC1jZjQxZmQyMTYyYTU0 Their Tag 2d41f126 Our tag: as37fb3f83
Jan 22 23:27:52 DEBUG[1259] chan_sip.c: Stopping retransmission on '6a0c3275726b993a@eC1jZjQxZmQyMTYyYTU0' of Request 104: Match Found
Jan 22 23:27:52 DEBUG[1259] chan_sip.c: = No match Their Call ID: 2a6c5da225250fdd5ee9ec55671af693@217.199.98.33 Their Tag as5ca0cdd0 Our tag: as7a7ea642
Jan 22 23:27:52 DEBUG[1259] chan_sip.c: = No match Their Call ID: fa1c5255dd702836@YWNlci1mN2tlNWhtZGUw Their Tag 9e41b211 Our tag: as4d80d2b8
Jan 22 23:27:52 DEBUG[1259] chan_sip.c: = No match Their Call ID: 75a7171528998262665262d23ede086a@217.199.102.230 Their Tag as376f9816 Our tag: as0892ad7a
Jan 22 23:27:52 DEBUG[1259] chan_sip.c: = Found Their Call ID: fc34a85de64ccc43@eC1jZjQxZmQyMTYyYTU0 Their Tag 5554a510 Our tag: as2ed931b1
Jan 22 23:27:52 DEBUG[1259] chan_sip.c: Stopping retransmission on 'fc34a85de64ccc43@eC1jZjQxZmQyMTYyYTU0' of Request 106: Match Found
Jan 22 23:27:52 DEBUG[1259] chan_sip.c: = No match Their Call ID: 2a6c5da225250fdd5ee9ec55671af693@217.199.98.33 Their Tag as5ca0cdd0 Our tag: as7a7ea642
Jan 22 23:27:52 DEBUG[1259] chan_sip.c: = Found Their Call ID: fa1c5255dd702836@YWNlci1mN2tlNWhtZGUw Their Tag 9e41b211 Our tag: as4d80d2b8
Jan 22 23:27:52 DEBUG[1259] chan_sip.c: Stopping retransmission on 'fa1c5255dd702836@YWNlci1mN2tlNWhtZGUw' of Request 102: Match Found
Jan 22 23:27:54 DEBUG[1259] chan_sip.c: = No match Their Call ID: 2a6c5da225250fdd5ee9ec55671af693@217.199.98.33 Their Tag as5ca0cdd0 Our tag: as7a7ea642
Jan 22 23:27:54 DEBUG[1259] chan_sip.c: = No match Their Call ID: 75a7171528998262665262d23ede086a@217.199.102.230 Their Tag as376f9816 Our tag: as0892ad7a
Jan 22 23:27:54 DEBUG[1259] chan_sip.c: = No match Their Call ID: fc34a85de64ccc43@eC1jZjQxZmQyMTYyYTU0 Their Tag 5554a510 Our tag: as2ed931b1
Jan 22 23:27:54 DEBUG[1259] chan_sip.c: = No match Their Call ID: 6a2d912c9c79243e@eC1jZjQxZmQyMTYyYTU0 Their Tag a311ea09 Our tag: as411deffe
Jan 22 23:27:54 DEBUG[1259] chan_sip.c: = No match Their Call ID: 6a0c3275726b993a@eC1jZjQxZmQyMTYyYTU0 Their Tag 2d41f126 Our tag: as37fb3f83
Jan 22 23:27:54 DEBUG[1259] chan_sip.c: = No match Their Call ID: ef245f3ce45e0464@eC1jZjQxZmQyMTYyYTU0 Their Tag f51adc09 Our tag: as05c66172
Jan 22 23:27:54 DEBUG[1259] chan_sip.c: = No match Their Call ID: f946ef361e21dc06@eC1jZjQxZmQyMTYyYTU0 Their Tag c2136303 Our tag: as0f3e8f09
Jan 22 23:27:54 DEBUG[1259] chan_sip.c: = No match Their Call ID: a23db80850416406@eC10YWJsZXQ. Their Tag cf1c2a24 Our tag: as0b3ae19f
Jan 22 23:27:54 DEBUG[1259] chan_sip.c: = No match Their Call ID: 75206263367b9c0e@eA.. Their Tag 583df86f Our tag: as44a5c203
Jan 22 23:27:54 DEBUG[1259] chan_sip.c: = No match Their Call ID: de7dcb1f88458a63@eA.. Their Tag c2251a24 Our tag: as56ef1190
Jan 22 23:27:54 DEBUG[1259] chan_sip.c: Allocating new SIP dialog for 440964367f7eae13@YWNlci1mN2tlNWhtZGUw - INVITE (With RTP)
Jan 22 23:27:54 DEBUG[1259] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE
Jan 22 23:27:54 DEBUG[1259] chan_sip.c: * SIP extension value: 2048 for call 440964367f7eae13@YWNlci1mN2tlNWhtZGUw
Jan 22 23:27:54 DEBUG[1259] chan_sip.c: Setting NAT on RTP to 0
Jan 22 23:27:54 DEBUG[1259] chan_sip.c: Setting NAT on VRTP to 0
Jan 22 23:27:54 DEBUG[1259] chan_sip.c: = Found Their Call ID: 440964367f7eae13@YWNlci1mN2tlNWhtZGUw Their Tag b1508359 Our tag: as10a55bc7
Jan 22 23:27:54 DEBUG[1259] chan_sip.c: **** Received ACK (6) - Command in SIP ACK
Jan 22 23:27:54 DEBUG[1259] chan_sip.c: Stopping retransmission on '440964367f7eae13@YWNlci1mN2tlNWhtZGUw' of Response 1: Match Found
Jan 22 23:27:54 DEBUG[1259] chan_sip.c: = Found Their Call ID: 440964367f7eae13@YWNlci1mN2tlNWhtZGUw Their Tag b1508359 Our tag: as10a55bc7
Jan 22 23:27:54 DEBUG[1259] chan_sip.c: **** Received INVITE (5) - Command in SIP INVITE
Jan 22 23:27:54 DEBUG[1259] chan_sip.c: Setting NAT on RTP to 0
Jan 22 23:27:54 DEBUG[1259] chan_sip.c: Setting NAT on VRTP to 0
Jan 22 23:27:54 DEBUG[1259] chan_sip.c: Checking SIP call limits for device 15
Jan 22 23:27:54 DEBUG[1259] chan_sip.c: Updating call counter for incoming call
Jan 22 23:27:54 DEBUG[1259] chan_sip.c: build_route: Contact hop: <sip:15@85.115.115.117:7098>
Jan 22 23:27:54 DEBUG[1252] chan_sip.c: Checking device state for peer 15
Jan 22 23:27:54 DEBUG[1252] channel.c: Avoiding initial deadlock for 'SIP/15-43ed'
Jan 22 23:27:54 DEBUG[2280] pbx.c: Launching 'Dial'
Jan 22 23:27:54 VERBOSE[2280] logger.c:     -- Executing Dial("SIP/15-43ed", "SIP/14") in new stack
Jan 22 23:27:54 DEBUG[2280] chan_sip.c: Allocating new SIP dialog for (No Call-ID) - INVITE (With RTP)
Jan 22 23:27:54 DEBUG[2280] chan_sip.c: Setting NAT on RTP to 0
Jan 22 23:27:54 DEBUG[2280] chan_sip.c: Setting NAT on VRTP to 0
Jan 22 23:27:54 DEBUG[1252] devicestate.c: Changing state for SIP/15 - state 2 (In use)
Jan 22 23:27:54 DEBUG[1252] chan_sip.c: Checking device state for peer 15
Jan 22 23:27:54 DEBUG[1252] chan_sip.c: Checking device state for peer 15
Jan 22 23:27:54 VERBOSE[1252] logger.c:  Extension Changed 15 new state InUse for Notify User 16
Jan 22 23:27:54 DEBUG[2280] channel.c: Not copying variable STACK-sip_local-14-1.
Jan 22 23:27:54 DEBUG[2280] channel.c: Not copying variable SIPCALLID.
Jan 22 23:27:54 DEBUG[2280] channel.c: Not copying variable SIPUSERAGENT.
Jan 22 23:27:54 DEBUG[2280] channel.c: Not copying variable SIPDOMAIN.
Jan 22 23:27:54 DEBUG[2280] channel.c: Not copying variable SIPURI.
Jan 22 23:27:54 DEBUG[2280] chan_sip.c: Outgoing Call for 14
Jan 22 23:27:54 DEBUG[2280] chan_sip.c: Updating call counter for outgoing call
Jan 22 23:27:54 VERBOSE[2280] logger.c:     -- Called 14
Jan 22 23:27:54 DEBUG[2280] channel.c: Set channel SIP/14-cc19 to read format ulaw
Jan 22 23:27:54 DEBUG[2280] channel.c: Set channel SIP/15-43ed to write format ulaw
Jan 22 23:27:54 DEBUG[2280] channel.c: Set channel SIP/15-43ed to read format ulaw
Jan 22 23:27:54 DEBUG[2280] channel.c: Set channel SIP/14-cc19 to write format ulaw
Jan 22 23:27:54 DEBUG[2281] app_queue.c: Device 'SIP/15' changed to state '2' (In use)
Jan 22 23:27:54 DEBUG[1259] chan_sip.c: = No match Their Call ID: 1441c04971c7bde23a476f6869361984@85.115.115.120 Their Tag  Our tag: as6692590d
Jan 22 23:27:54 DEBUG[1259] chan_sip.c: = No match Their Call ID: 440964367f7eae13@YWNlci1mN2tlNWhtZGUw Their Tag b1508359 Our tag: as5367b360
Jan 22 23:27:54 DEBUG[1259] chan_sip.c: = No match Their Call ID: 2a6c5da225250fdd5ee9ec55671af693@217.199.98.33 Their Tag as5ca0cdd0 Our tag: as7a7ea642
Jan 22 23:27:54 DEBUG[1259] chan_sip.c: = No match Their Call ID: 75a7171528998262665262d23ede086a@217.199.102.230 Their Tag as376f9816 Our tag: as0892ad7a
Jan 22 23:27:54 DEBUG[1259] chan_sip.c: = Found Their Call ID: fc34a85de64ccc43@eC1jZjQxZmQyMTYyYTU0 Their Tag 5554a510 Our tag: as2ed931b1
Jan 22 23:27:54 DEBUG[1259] chan_sip.c: Stopping retransmission on 'fc34a85de64ccc43@eC1jZjQxZmQyMTYyYTU0' of Request 107: Match Found
Jan 22 23:27:54 DEBUG[1259] chan_sip.c: = Found Their Call ID: 1441c04971c7bde23a476f6869361984@85.115.115.120 Their Tag  Our tag: as6692590d
Jan 22 23:27:54 DEBUG[1259] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '1441c04971c7bde23a476f6869361984@85.115.115.120' Request 102: Found
Jan 22 23:27:54 DEBUG[1259] chan_sip.c: SIP response 180 to standard invite
Jan 22 23:27:54 VERBOSE[2280] logger.c:     -- SIP/14-cc19 is ringing
Jan 22 23:27:54 DEBUG[1252] chan_sip.c: Checking device state for peer 14
Jan 22 23:27:54 DEBUG[1252] devicestate.c: Changing state for SIP/14 - state 6 (Ringing)
Jan 22 23:27:54 DEBUG[1252] chan_sip.c: Checking device state for peer 14
Jan 22 23:27:54 DEBUG[1252] chan_sip.c: Checking device state for peer 14
Jan 22 23:27:54 VERBOSE[1252] logger.c:  Extension Changed 14 new state Ringing for Notify User 16
Jan 22 23:27:54 DEBUG[2282] app_queue.c: Device 'SIP/14' changed to state '6' (Ringing)
Jan 22 23:27:54 DEBUG[1259] chan_sip.c: = No match Their Call ID: 1441c04971c7bde23a476f6869361984@85.115.115.120 Their Tag 01328e39 Our tag: as6692590d
Jan 22 23:27:54 DEBUG[1259] chan_sip.c: = No match Their Call ID: 440964367f7eae13@YWNlci1mN2tlNWhtZGUw Their Tag b1508359 Our tag: as5367b360
Jan 22 23:27:54 DEBUG[1259] chan_sip.c: = No match Their Call ID: 2a6c5da225250fdd5ee9ec55671af693@217.199.98.33 Their Tag as5ca0cdd0 Our tag: as7a7ea642
Jan 22 23:27:54 DEBUG[1259] chan_sip.c: = No match Their Call ID: 75a7171528998262665262d23ede086a@217.199.102.230 Their Tag as376f9816 Our tag: as0892ad7a
Jan 22 23:27:54 DEBUG[1259] chan_sip.c: = No match Their Call ID: fc34a85de64ccc43@eC1jZjQxZmQyMTYyYTU0 Their Tag 5554a510 Our tag: as2ed931b1
Jan 22 23:27:54 DEBUG[1259] chan_sip.c: = Found Their Call ID: 6a2d912c9c79243e@eC1jZjQxZmQyMTYyYTU0 Their Tag a311ea09 Our tag: as411deffe
Jan 22 23:27:54 DEBUG[1259] chan_sip.c: Stopping retransmission on '6a2d912c9c79243e@eC1jZjQxZmQyMTYyYTU0' of Request 111: Match Found
Jan 22 23:27:57 DEBUG[2280] rtp.c: Got RTCP report of 72 bytes
Jan 22 23:27:57 DEBUG[2280] rtp.c: Got RTCP report of 72 bytes
Jan 22 23:27:57 DEBUG[1259] chan_sip.c: = Found Their Call ID: 1441c04971c7bde23a476f6869361984@85.115.115.120 Their Tag 01328e39 Our tag: as6692590d
Jan 22 23:27:57 DEBUG[1259] chan_sip.c: Acked pending invite 102
Jan 22 23:27:57 DEBUG[1259] chan_sip.c: Stopping retransmission on '1441c04971c7bde23a476f6869361984@85.115.115.120' of Request 102: Match Found
Jan 22 23:27:57 DEBUG[1259] chan_sip.c: SIP response 200 to standard invite
Jan 22 23:27:57 DEBUG[1259] chan_sip.c: build_route: Contact hop: <sip:14@85.115.115.114:9348>
Jan 22 23:27:57 VERBOSE[2280] logger.c:     -- SIP/14-cc19 answered SIP/15-43ed
Jan 22 23:27:57 DEBUG[2280] channel.c: Set channel SIP/15-43ed to read format ulaw
Jan 22 23:27:57 DEBUG[2280] channel.c: Set channel SIP/14-cc19 to write format ulaw
Jan 22 23:27:57 DEBUG[2280] channel.c: Set channel SIP/14-cc19 to read format ulaw
Jan 22 23:27:57 DEBUG[2280] channel.c: Set channel SIP/15-43ed to write format ulaw
Jan 22 23:27:57 DEBUG[2280] chan_sip.c: sip_answer(SIP/15-43ed)
Jan 22 23:27:57 VERBOSE[2280] logger.c:     -- Attempting native bridge of SIP/15-43ed and SIP/14-cc19
Jan 22 23:27:57 DEBUG[1252] chan_sip.c: Checking device state for peer 14
Jan 22 23:27:57 DEBUG[1252] devicestate.c: Changing state for SIP/14 - state 2 (In use)
Jan 22 23:27:57 DEBUG[1252] chan_sip.c: Checking device state for peer 14
Jan 22 23:27:57 DEBUG[1252] chan_sip.c: Checking device state for peer 14
Jan 22 23:27:57 VERBOSE[1252] logger.c:  Extension Changed 14 new state InUse for Notify User 16
Jan 22 23:27:57 DEBUG[1252] chan_sip.c: Checking device state for peer 15
Jan 22 23:27:57 DEBUG[1252] devicestate.c: Changing state for SIP/15 - state 2 (In use)
Jan 22 23:27:57 DEBUG[1252] chan_sip.c: Checking device state for peer 15
Jan 22 23:27:57 DEBUG[2283] app_queue.c: Device 'SIP/14' changed to state '2' (In use)
Jan 22 23:27:57 DEBUG[2284] app_queue.c: Device 'SIP/15' changed to sta DEBUG[2280] rtp.c: Ooh, format changed from unknown to ulaw
Jan 22 23:27:57 DEBUG[1259] chan_sip.c: = No match Their Call ID: 1441c04971c7bde23a476f6869361984@85.115.115.120 Their Tag 01328e39 Our tag: as6692590d
Jan 22 23:27:57 DEBUG[1259] chan_sip.c: = No match Their Call ID: 440964367f7eae13@YWNlci1mN2tlNWhtZGUw Their Tag b1508359 Our tag: as5367b360
Jan 22 23:27:57 DEBUG[1259] chan_sip.c: = No match Their Call ID: 2a6c5da225250fdd5ee9ec55671af693@217.199.98.33 Their Tag as5ca0cdd0 Our tag: as7a7ea642
Jan 22 23:27:57 DEBUG[1259] chan_sip.c: = No match Their Call ID: 75a7171528998262665262d23ede086a@217.199.102.230 Their Tag as376f9816 Our tag: as0892ad7a
Jan 22 23:27:57 DEBUG[1259] chan_sip.c: = No match Their Call ID: fc34a85de64ccc43@eC1jZjQxZmQyMTYyYTU0 Their Tag 5554a510 Our tag: as2ed931b1
Jan 22 23:27:57 DEBUG[1259] chan_sip.c: = Found Their Call ID: 6a2d912c9c79243e@eC1jZjQxZmQyMTYyYTU0 Their Tag a311ea09 Our tag: as411deffe
Jan 22 23:27:57 DEBUG[1259] chan_sip.c: Stopping retransmission on '6a2d912c9c79243e@eC1jZjQxZmQyMTYyYTU0' of Request 112: Match Found
Jan 22 23:27:57 DEBUG[2280] rtp.c: Ooh, format changed from unknown to ulaw
Jan 22 23:27:57 DEBUG[1259] chan_sip.c: = No match Their Call ID: 1441c04971c7bde23a476f6869361984@85.115.115.120 Their Tag 01328e39 Our tag: as6692590d
Jan 22 23:27:57 DEBUG[1259] chan_sip.c: = Found Their Call ID: 440964367f7eae13@YWNlci1mN2tlNWhtZGUw Their Tag b1508359 Our tag: as5367b360
Jan 22 23:27:57 DEBUG[1259] chan_sip.c: **** Received ACK (6) - Command in SIP ACK
Jan 22 23:27:57 DEBUG[1259] chan_sip.c: Stopping retransmission on '440964367f7eae13@YWNlci1mN2tlNWhtZGUw' of Response 2: Match Found
Jan 22 23:27:59 DEBUG[1259] chan_sip.c: Auto destroying call 'de7dcb1f88458a63@eA..'
Jan 22 23:27:59 DEBUG[1259] chan_sip.c: Auto destroying call ''
Jan 22 23:27:59 NOTICE[1259] sched.c: Attempted to delete nonexistent schedule entry 0!
Jan 22 23:27:59 WARNING[1259] chan_sip.c: Trying to destroy "", not found in dialog list?!?! 
Jan 22 23:27:59 DEBUG[1259] chan_sip.c: Auto destroying call ''
Jan 22 23:27:59 NOTICE[1259] sched.c: Attempted to delete nonexistent schedule entry 0!
Jan 22 23:27:59 WARNING[1259] chan_sip.c: Trying to destroy "", not found in dialog list?!?! 
Jan 22 23:27:59 DEBUG[1259] chan_sip.c: Auto destroying call '1441c04971c7bde23a476f6869361984@85.115.115.120'
Jan 22 23:27:59 WARNING[1259] chan_sip.c: Autodestruct on call '1441c04971c7bde23a476f6869361984@85.115.115.120' with owner in place
Jan 22 23:27:59 DEBUG[1259] chan_sip.c: Auto destroying call 'L'
Jan 22 23:27:59 NOTICE[1259] sched.c: Attempted to delete nonexistent schedule entry 0!
Jan 22 23:27:59 DEBUG[2280] channel.c: Didn't get a frame from channel: SIP/14-cc19
Jan 22 23:27:59 DEBUG[2280] channel.c: Bridge stops bridging channels SIP/15-43ed and SIP/14-cc19
Jan 22 23:27:59 DEBUG[2280] channel.c: Hanging up channel 'SIP/14-cc19'
Jan 22 23:27:59 DEBUG[2280] chan_sip.c: Hangup call SIP/14-cc19, SIP callid 1441c04971c7bde23a476f6869361984@85.115.115.120)
Jan 22 23:27:59 DEBUG[2280] chan_sip.c: update_call_counter(14) - decrement call limit counter
Jan 22 23:27:59 DEBUG[2280] chan_sip.c: Updating call counter for outgoing call
Jan 22 23:27:59 DEBUG[2280] app_dial.c: Exiting with DIALSTATUS=ANSWER.