Sep 24 09:36:51 DEBUG[6773] chan_sip.c: Setting NAT on RTP to 524288 Sep 24 09:36:51 DEBUG[6773] chan_sip.c: Setting NAT on VRTP to 524288 Sep 24 09:36:51 DEBUG[6773] chan_sip.c: Stopping retransmission on 'NTczNTVhZDAxZGJhNDBhNWMxM2JlMTVhYWU5ZTdjZjk.' of Response 1: Match Found Sep 24 09:36:51 DEBUG[6773] chan_sip.c: Setting NAT on RTP to 524288 Sep 24 09:36:51 DEBUG[6773] chan_sip.c: Setting NAT on VRTP to 524288 Sep 24 09:36:51 DEBUG[6773] chan_sip.c: Checking SIP call limits for device test Sep 24 09:36:51 DEBUG[6773] chan_sip.c: build_route: Contact hop: Sep 24 09:36:52 DEBUG[6773] chan_sip.c: Stopping retransmission on 'NTczNTVhZDAxZGJhNDBhNWMxM2JlMTVhYWU5ZTdjZjk.' of Response 2: Match Found Sep 24 09:36:57 DEBUG[10539] pbx.c: Oooh, got something to jump out with ('1')! Sep 24 09:37:05 DEBUG[10539] chan_sip.c: Setting NAT on RTP to 0 Sep 24 09:37:05 DEBUG[10539] chan_sip.c: Setting NAT on VRTP to 0 Sep 24 09:37:05 DEBUG[10539] chan_sip.c: Outgoing Call for 18005558355 Sep 24 09:37:05 DEBUG[6773] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '415a546c444c99287ca1d28b73d11f15@216.143.130.36' Request 102: Found Sep 24 09:37:06 DEBUG[6773] chan_sip.c: Acked pending invite 102 Sep 24 09:37:06 DEBUG[6773] chan_sip.c: Stopping retransmission on '415a546c444c99287ca1d28b73d11f15@216.143.130.36' of Request 102: Match Found Sep 24 09:37:06 DEBUG[6773] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '415a546c444c99287ca1d28b73d11f15@216.143.130.36' Request 103: Found Sep 24 09:37:06 DEBUG[6773] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '415a546c444c99287ca1d28b73d11f15@216.143.130.36' Request 103: Found Sep 24 09:37:07 DEBUG[6773] chan_sip.c: Acked pending invite 103 Sep 24 09:37:07 DEBUG[6773] chan_sip.c: Stopping retransmission on '415a546c444c99287ca1d28b73d11f15@216.143.130.36' of Request 103: Match Found Sep 24 09:37:07 DEBUG[6773] chan_sip.c: build_route: Record-Route hop: Sep 24 09:37:07 DEBUG[6773] chan_sip.c: build_route: Record-Route hop: Sep 24 09:37:09 DEBUG[6773] chan_sip.c: Auto destroying call '518775db4a3536f5272f3de24a6b01e7@127.0.0.1' Sep 24 09:37:09 DEBUG[6773] chan_sip.c: Auto destroying call '0af1549402f244bc38553963772f4c8a@127.0.0.1' Sep 24 09:37:09 DEBUG[6773] chan_sip.c: Auto destroying call '778fe547249bb290786fe4e365915177@127.0.0.1' Sep 24 09:37:09 DEBUG[6773] chan_sip.c: Auto destroying call '436aa2d767ccd37104fd94812d2d11c6@127.0.0.1' Sep 24 09:37:09 DEBUG[6773] chan_sip.c: Auto destroying call '6effdbba01632d93051e43c227ed0f91@127.0.0.1' Sep 24 09:37:09 DEBUG[6773] chan_sip.c: Auto destroying call '766f11d82c8ac9903b94f87a792216f1@127.0.0.1' Sep 24 09:37:11 DEBUG[10539] channel.c: Got DTMF on channel (SIP/test-08cb4290) Sep 24 09:37:11 DEBUG[10539] channel.c: Bridge stops bridging channels SIP/test-08cb4290 and SIP/dovid_cool-08cf47d8 Sep 24 09:37:11 DEBUG[10539] res_features.c: Feature interpret: chan=SIP/test-08cb4290, peer=SIP/dovid_cool-08cf47d8, sense=1, features=4 Sep 24 09:37:11 DEBUG[10539] res_features.c: Set time limit to 500 Sep 24 09:37:11 DEBUG[10539] channel.c: Got DTMF on channel (SIP/test-08cb4290) Sep 24 09:37:11 DEBUG[10539] channel.c: Bridge stops bridging channels SIP/test-08cb4290 and SIP/dovid_cool-08cf47d8 Sep 24 09:37:11 DEBUG[10539] res_features.c: Feature interpret: chan=SIP/test-08cb4290, peer=SIP/dovid_cool-08cf47d8, sense=1, features=4 Sep 24 09:37:11 DEBUG[10539] chan_sip.c: update_call_counter(18005558355) - decrement call limit counter Sep 24 09:37:11 DEBUG[10539] app_dial.c: Exiting with DIALSTATUS=ANSWER. Sep 24 09:37:11 DEBUG[6773] chan_sip.c: Stopping retransmission on '415a546c444c99287ca1d28b73d11f15@216.143.130.36' of Request 104: Match Found Sep 24 09:37:17 DEBUG[10539] chan_sip.c: update_call_counter(test) - decrement call limit counter Sep 24 09:37:17 DEBUG[10539] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record. Sep 24 09:37:17 DEBUG[10539] cdr_addon_mysql.c: cdr_mysql: SQL command as follows: INSERT INTO cdr (calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duration,billsec,disposition,amaflags,accountcode) VALUES ('2007-09-24 09:36:51','\"Bender, Dovid\" <9146002000>','9146002000','123','test', 'SIP/test-08cb4290','SIP/dovid_cool-08cf47d8','BackGround','dovid/4711',26,26,'ANSWERED',3,'') Sep 24 09:37:17 DEBUG[10539] pbx.c: Function result is '"SMith, John" <9146002222>' Sep 24 09:37:17 DEBUG[10539] pbx.c: Function result is '91460022222' Sep 24 09:37:17 DEBUG[10539] pbx.c: Function result is '123' Sep 24 09:37:17 DEBUG[10539] pbx.c: Function result is 'test' Sep 24 09:37:17 DEBUG[10539] pbx.c: Function result is 'SIP/test-08cb4290' Sep 24 09:37:17 DEBUG[10539] pbx.c: Function result is 'SIP/dovid_cool-08cf47d8' Sep 24 09:37:17 DEBUG[10539] pbx.c: Function result is 'BackGround' Sep 24 09:37:17 DEBUG[10539] pbx.c: Function result is 'dovid/4711' Sep 24 09:37:17 DEBUG[10539] pbx.c: Function result is '2007-09-24 09:36:51' Sep 24 09:37:17 DEBUG[10539] pbx.c: Function result is '2007-09-24 09:36:51' Sep 24 09:37:17 DEBUG[10539] pbx.c: Function result is '2007-09-24 09:37:17' Sep 24 09:37:17 DEBUG[10539] pbx.c: Function result is '26' Sep 24 09:37:17 DEBUG[10539] pbx.c: Function result is '26' Sep 24 09:37:17 DEBUG[10539] pbx.c: Function result is 'ANSWERED' Sep 24 09:37:17 DEBUG[10539] pbx.c: Function result is 'DOCUMENTATION' Sep 24 09:37:17 DEBUG[10539] pbx.c: Function result is '(null)' Sep 24 09:37:17 DEBUG[10539] pbx.c: Function result is '1190641011.1859' Sep 24 09:37:17 DEBUG[10539] pbx.c: Function result is '(null)' Sep 24 09:37:17 DEBUG[6773] chan_sip.c: Stopping retransmission on 'NTczNTVhZDAxZGJhNDBhNWMxM2JlMTVhYWU5ZTdjZjk.' of Request 102: Match Found