-- SIP/220-085bd8a0 is ringing
-- Agent/220 is ringing
Nov 30 13:20:05 DEBUG[3591]: chan_sip.c:1390 __sip_ack: Acked pending invite 102
Nov 30 13:20:05 DEBUG[3591]: chan_sip.c:1412 __sip_ack: Stopping retransmission on '20ce8be2285bd9ee314614ae4ff2684a@192.168.53.9' of Request 102: Match Found
Nov 30 13:20:05 DEBUG[3591]: chan_sip.c:3726 process_sdp: Oooh, we need to change our formats since our peer supports only 0xc (ulaw|alaw) and not 0x2 (gsm)
Nov 30 13:20:05 DEBUG[3591]: chan_sip.c:6194 build_route: build_route: Contact hop: <sip:220@192.168.53.53>
-- SIP/220-085bd8a0 answered Local/220@ext-torrent-e27f,2
Nov 30 13:20:05 DEBUG[10364]: app_queue.c:1953 wait_for_answer: Dunno what to do with control type -1
-- Agent/220 answered Zap/11-1
Nov 30 13:20:05 DEBUG[10364]: chan_zap.c:2764 zt_setoption: Set option TONE VERIFY, mode: MUTECONF(1) on Zap/11-1
Nov 30 13:20:05 DEBUG[10368]: channel.c:2834 ast_channel_masquerade: Planning to masquerade channel SIP/220-085bd8a0 into the structure of Local/220@ext-torrent-e27f,1
Nov 30 13:20:05 DEBUG[10368]: channel.c:2847 ast_channel_masquerade: Done planning to masquerade channel SIP/220-085bd8a0 into the structure of Local/220@ext-torrent-e27f,1
Nov 30 13:20:05 DEBUG[10368]: chan_local.c:256 local_write: Not posting to queue since already masked on 'Local/220@ext-torrent-e27f,2'
Nov 30 13:20:05 DEBUG[10364]: channel.c:2972 ast_do_masquerade: Got clone lock for masquerade on 'SIP/220-085bd8a0' at 0x85c058c
Nov 30 13:20:05 DEBUG[10364]: channel.c:3154 ast_do_masquerade: Putting channel SIP/220-085bd8a0 in 64/64 formats
Nov 30 13:20:05 DEBUG[10364]: channel.c:3189 ast_do_masquerade: Released clone lock on 'Local/220@ext-torrent-e27f,1<ZOMBIE>'
Nov 30 13:20:05 DEBUG[10364]: channel.c:3198 ast_do_masquerade: Done Masquerading SIP/220-085bd8a0 (6)
Nov 30 13:20:05 DEBUG[10364]: chan_agent.c:547 agent_read: Bridge on 'SIP/220-085bd8a0' being set to 'Agent/220' (3)
Nov 30 13:20:05 DEBUG[10364]: chan_agent.c:470 agent_read: Native formats changing from 64 to 4
Nov 30 13:20:05 DEBUG[10364]: chan_agent.c:470 agent_read: Resetting read to 64 and write to 64
Nov 30 13:20:05 DEBUG[10368]: channel.c:3377 ast_generic_bridge: Didn't get a frame from channel: Local/220@ext-torrent-e27f,2
Nov 30 13:20:05 DEBUG[10368]: channel.c:3662 ast_channel_bridge: Bridge stops bridging channels Local/220@ext-torrent-e27f,2 and Local/220@ext-torrent-e27f,1<ZOMBIE>
Nov 30 13:20:05 DEBUG[10368]: app_dial.c:1635 dial_exec_full: Exiting with DIALSTATUS=ANSWER.
== Spawn extension (ext-torrent, 220, 1) exited non-zero on 'Local/220@ext-torrent-e27f,2'
Nov 30 13:20:05 DEBUG[10368]: cdr_addon_mysql.c:206 mysql_log: cdr_mysql: inserting a CDR record.
Nov 30 13:20:05 DEBUG[10368]: cdr_addon_mysql.c:222 mysql_log: cdr_mysql: SQL command as follows: INSERT INTO cdr (calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duration,billsec,disposition,amaflags,accountcode) VALUES ('2006-11-30 13:20:03','\"Q.Alexis Torrent\" <5148897649>','5148897649','220','ext-torrent', 'Local/220@ext-torrent-e27f,2','SIP/220-085bd8a0','Dial','Sip/220|20|tT',2,0,'ANSWERED',3,'')
Nov 30 13:20:05 DEBUG[10368]: pbx.c:1522 pbx_substitute_variables_helper_full: Function result is '"Q.Alexis Torrent" <5148897649>'
Nov 30 13:20:05 DEBUG[10368]: pbx.c:1522 pbx_substitute_variables_helper_full: Function result is '5148897649'
Nov 30 13:20:05 DEBUG[10368]: pbx.c:1522 pbx_substitute_variables_helper_full: Function result is '220'
Nov 30 13:20:05 DEBUG[10368]: pbx.c:1522 pbx_substitute_variables_helper_full: Function result is 'ext-torrent'
Nov 30 13:20:05 DEBUG[10368]: pbx.c:1522 pbx_substitute_variables_helper_full: Function result is 'Local/220@ext-torrent-e27f,2'
Nov 30 13:20:05 DEBUG[10368]: pbx.c:1522 pbx_substitute_variables_helper_full: Function result is 'SIP/220-085bd8a0'
Nov 30 13:20:05 DEBUG[10368]: pbx.c:1522 pbx_substitute_variables_helper_full: Function result is 'Dial'
Nov 30 13:20:05 DEBUG[10368]: pbx.c:1522 pbx_substitute_variables_helper_full: Function result is 'Sip/220|20|tT'
Nov 30 13:20:05 DEBUG[10368]: pbx.c:1522 pbx_substitute_variables_helper_full: Function result is '2006-11-30 13:20:03'
Nov 30 13:20:05 DEBUG[10368]: pbx.c:1522 pbx_substitute_variables_helper_full: Function result is '2006-11-30 13:20:05'
Nov 30 13:20:05 DEBUG[10368]: pbx.c:1522 pbx_substitute_variables_helper_full: Function result is '2006-11-30 13:20:05'
Nov 30 13:20:05 DEBUG[10368]: pbx.c:1522 pbx_substitute_variables_helper_full: Function result is '2'
Nov 30 13:20:05 DEBUG[10368]: pbx.c:1522 pbx_substitute_variables_helper_full: Function result is '0'
Nov 30 13:20:05 DEBUG[10368]: pbx.c:1522 pbx_substitute_variables_helper_full: Function result is 'ANSWERED'
Nov 30 13:20:05 DEBUG[10368]: pbx.c:1522 pbx_substitute_variables_helper_full: Function result is 'DOCUMENTATION'
Nov 30 13:20:05 DEBUG[10368]: pbx.c:1522 pbx_substitute_variables_helper_full: Function result is '(null)'
Nov 30 13:20:05 DEBUG[10368]: pbx.c:1522 pbx_substitute_variables_helper_full: Function result is '1164910803.248'
Nov 30 13:20:05 DEBUG[10368]: pbx.c:1522 pbx_substitute_variables_helper_full: Function result is '(null)'
-- Started music on hold, class 'default', on channel 'Zap/11-1'
Nov 30 13:20:05 DEBUG[3591]: channel.c:1761 ast_settimeout: Scheduling timer at 160 sample intervals
Nov 30 13:20:05 DEBUG[10364]: channel.c:2027 ast_read: Generator got voice, switching to phase locked mode
Nov 30 13:20:05 DEBUG[10364]: channel.c:1761 ast_settimeout: Scheduling timer at 0 sample intervals
Nov 30 13:20:06 DEBUG[3591]: chan_sip.c:1412 __sip_ack: Stopping retransmission on '20ce8be2285bd9ee314614ae4ff2684a@192.168.53.9' of Response 1656203908: Match Found
Nov 30 13:20:07 DEBUG[3591]: chan_sip.c:7215 check_user_full: Setting NAT on RTP to 524288
Nov 30 13:20:07 DEBUG[3591]: chan_sip.c:1412 __sip_ack: Stopping retransmission on 'e25b1049e962bbbaf31a6b84d2cdffa0@192.168.53.53' of Response 1565433942: Match Found
Nov 30 13:20:07 DEBUG[3591]: chan_sip.c:7215 check_user_full: Setting NAT on RTP to 524288
Nov 30 13:20:07 DEBUG[3591]: chan_sip.c:10571 handle_request_invite: Checking SIP call limits for device 220
Nov 30 13:20:07 DEBUG[3591]: chan_sip.c:6194 build_route: build_route: Contact hop: Alexis Gingras <sip:220@192.168.53.53>
-- Executing Dial("SIP/220-086077e8", "Sip/260|20|tT") in new stack
Nov 30 13:20:07 DEBUG[10377]: chan_sip.c:1885 create_addr_from_peer: Setting NAT on RTP to 524288
Nov 30 13:20:07 DEBUG[10377]: chan_sip.c:2079 sip_call: Outgoing Call for 260
-- Called 260
Nov 30 13:20:07 DEBUG[3591]: chan_sip.c:1465 __sip_semi_ack: (Provisional) Stopping retransmission (but retaining packet) on '190d048a29f40ebd54df8c3515ad4bb7@192.168.53.9' Request 102: Found
Nov 30 13:20:07 DEBUG[3591]: chan_sip.c:1465 __sip_semi_ack: (Provisional) Stopping retransmission (but retaining packet) on '190d048a29f40ebd54df8c3515ad4bb7@192.168.53.9' Request 102: Found
-- SIP/260-08611af8 is ringing
Nov 30 13:20:11 DEBUG[3591]: chan_sip.c:1390 __sip_ack: Acked pending invite 102
Nov 30 13:20:11 DEBUG[3591]: chan_sip.c:1412 __sip_ack: Stopping retransmission on '190d048a29f40ebd54df8c3515ad4bb7@192.168.53.9' of Request 102: Match Found
Nov 30 13:20:11 DEBUG[3591]: chan_sip.c:3726 process_sdp: Oooh, we need to change our formats since our peer supports only 0xc (ulaw|alaw) and not 0x2 (gsm)
Nov 30 13:20:11 DEBUG[3591]: chan_sip.c:6194 build_route: build_route: Contact hop: <sip:260@192.168.53.113>
-- SIP/260-08611af8 answered SIP/220-086077e8
Nov 30 13:20:11 DEBUG[3591]: chan_sip.c:1412 __sip_ack: Stopping retransmission on 'e25b1049e962bbbaf31a6b84d2cdffa0@192.168.53.53' of Response 1565433943: Match Found
Nov 30 13:20:17 DEBUG[3591]: chan_sip.c:6916 get_refer_info: Assigning Replace-Call-ID Info e25b1049e962bbbaf31a6b84d2cdffa0@192.168.53.53 to REPLACE_CALL_ID
Nov 30 13:20:17 DEBUG[3591]: chan_sip.c:10752 handle_request_refer: 202 Accepted (supervised)
-- Stopped music on hold on Zap/11-1
Nov 30 13:20:17 DEBUG[3591]: channel.c:1761 ast_settimeout: Scheduling timer at 0 sample intervals
Nov 30 13:20:17 DEBUG[3591]: channel.c:2834 ast_channel_masquerade: Planning to masquerade channel Agent/220 into the structure of SIP/220-086077e8
Nov 30 13:20:17 DEBUG[3591]: channel.c:2847 ast_channel_masquerade: Done planning to masquerade channel Agent/220 into the structure of SIP/220-086077e8
Nov 30 13:20:17 DEBUG[10377]: channel.c:2972 ast_do_masquerade: Got clone lock for masquerade on 'Agent/220' at 0x85bd16c
Nov 30 13:20:17 DEBUG[10377]: chan_sip.c:2435 sip_hangup: update_call_counter(220) - decrement call limit counter
Nov 30 13:20:17 DEBUG[10377]: channel.c:3154 ast_do_masquerade: Putting channel Agent/220 in 4/4 formats
Nov 30 13:20:17 DEBUG[10377]: channel.c:3189 ast_do_masquerade: Released clone lock on 'SIP/220-086077e8<ZOMBIE>'
Nov 30 13:20:17 DEBUG[10377]: channel.c:3198 ast_do_masquerade: Done Masquerading Agent/220 (6)
Nov 30 13:20:17 DEBUG[10364]: channel.c:3377 ast_generic_bridge: Didn't get a frame from channel: SIP/220-086077e8<ZOMBIE>
Nov 30 13:20:17 DEBUG[10364]: channel.c:3662 ast_channel_bridge: Bridge stops bridging channels Zap/11-1 and SIP/220-086077e8<ZOMBIE>
== Spawn extension (Incomming-Torrent, 220, 3) exited non-zero on 'Zap/11-1'
Nov 30 13:20:17 DEBUG[10364]: chan_zap.c:2861 zt_setoption: Set option AUDIO MODE, value: ON(1) on Zap/11-1
Nov 30 13:20:17 DEBUG[10364]: chan_zap.c:2342 zt_hangup: Hangup: channel: 11 index = 0, normal = 27, callwait = -1, thirdcall = -1
Nov 30 13:20:17 DEBUG[10364]: chan_zap.c:2491 zt_hangup: Not yet hungup... Calling hangup once with icause, and clearing call
Nov 30 13:20:17 DEBUG[10364]: chan_zap.c:1435 zt_disable_ec: disabled echo cancellation on channel 11
Nov 30 13:20:17 DEBUG[10364]: chan_zap.c:2782 zt_setoption: Set option TDD MODE, value: OFF(0) on Zap/11-1
Nov 30 13:20:17 DEBUG[10364]: chan_zap.c:1372 update_conf: Updated conferencing on 11, with 0 conference users
Nov 30 13:20:17 DEBUG[10364]: chan_zap.c:2857 zt_setoption: Set option AUDIO MODE, value: OFF(0) on Zap/11-1
Nov 30 13:20:17 DEBUG[10364]: chan_zap.c:1435 zt_disable_ec: disabled echo cancellation on channel 11
-- Hungup 'Zap/11-1'