Apr 5 08:55:17 DEBUG[2735] chan_sip.c: Stopping retransmission on '4adc91797@192.168.5.138' of Response 1: Match Found Apr 5 08:55:17 DEBUG[2735] chan_sip.c: Setting NAT on RTP to 0 Apr 5 08:55:17 DEBUG[2735] chan_sip.c: Checking SIP call limits for device 538 Apr 5 08:55:17 DEBUG[2735] chan_sip.c: build_route: Contact hop: Apr 5 08:55:17 DEBUG[2721] channel.c: Avoiding initial deadlock for 'SIP/538-452a' Apr 5 08:55:17 VERBOSE[9346] logger.c: -- Executing Answer("SIP/538-452a", "") in new stack Apr 5 08:55:17 VERBOSE[9346] logger.c: -- Executing Wait("SIP/538-452a", "1") in new stack Apr 5 08:55:17 DEBUG[2735] chan_sip.c: Stopping retransmission on '4adc91797@192.168.5.138' of Response 2: Match Found Apr 5 08:55:18 VERBOSE[9346] logger.c: -- Executing AGI("SIP/538-452a", "directory|general|ext-local|b") in new stack Apr 5 08:55:18 VERBOSE[9346] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/directory Apr 5 08:55:18 DEBUG[9346] channel.c: Scheduling timer at 160 sample intervals Apr 5 08:55:18 VERBOSE[9346] logger.c: -- Playing 'dir-intro-fnln' (language 'en') Apr 5 08:55:19 NOTICE[9341] rtp.c: Comfort noise support incomplete in Asterisk (RFC 3389). Please turn off on client if possible. Client IP: 192.168.5.16 Apr 5 08:55:20 DEBUG[9346] channel.c: Scheduling timer at 0 sample intervals Apr 5 08:55:21 DEBUG[9346] channel.c: Scheduling timer at 160 sample intervals Apr 5 08:55:24 DEBUG[9346] channel.c: Scheduling timer at 0 sample intervals Apr 5 08:55:24 DEBUG[9346] channel.c: Scheduling timer at 0 sample intervals Apr 5 08:55:24 DEBUG[9346] channel.c: Scheduling timer at 160 sample intervals Apr 5 08:55:24 VERBOSE[9346] logger.c: -- Playing 'dir-intro-fnln' (language 'en') Apr 5 08:55:25 DEBUG[9346] channel.c: Scheduling timer at 0 sample intervals Apr 5 08:55:25 VERBOSE[9346] logger.c: -- AGI Script directory completed, returning 0 Apr 5 08:55:25 VERBOSE[9346] logger.c: -- Executing Playback("SIP/538-452a", "vm-goodbye") in new stack Apr 5 08:55:25 DEBUG[9346] channel.c: Scheduling timer at 160 sample intervals Apr 5 08:55:25 VERBOSE[9346] logger.c: -- Playing 'vm-goodbye' (language 'en') Apr 5 08:55:26 DEBUG[9346] channel.c: Scheduling timer at 0 sample intervals Apr 5 08:55:26 DEBUG[9346] channel.c: Scheduling timer at 0 sample intervals Apr 5 08:55:26 VERBOSE[9346] logger.c: -- Executing Hangup("SIP/538-452a", "") in new stack Apr 5 08:55:26 VERBOSE[9346] logger.c: == Spawn extension (from-internal, *411, 5) exited non-zero on 'SIP/538-452a' Apr 5 08:55:26 VERBOSE[9346] logger.c: -- Executing Macro("SIP/538-452a", "hangupcall") in new stack Apr 5 08:55:26 VERBOSE[9346] logger.c: -- Executing ResetCDR("SIP/538-452a", "w") in new stack Apr 5 08:55:26 DEBUG[9346] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record. Apr 5 08:55:26 DEBUG[9346] 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,uniqueid) VALUES ('2006-04-05 08:55:17','\"device\" <538>','538','*411','from-internal', 'SIP/538-452a','','ResetCDR','w',9,9,'ANSWERED',3,'','1144220117.3133') Apr 5 08:55:26 VERBOSE[9346] logger.c: -- Executing NoCDR("SIP/538-452a", "") in new stack Apr 5 08:55:26 WARNING[9346] cdr.c: CDR on channel 'SIP/538-452a' not posted Apr 5 08:55:26 WARNING[9346] cdr.c: CDR on channel 'SIP/538-452a' lacks end Apr 5 08:55:26 VERBOSE[9346] logger.c: -- Executing Wait("SIP/538-452a", "5") in new stack Apr 5 08:55:26 VERBOSE[9346] logger.c: == Spawn extension (macro-hangupcall, s, 3) exited non-zero on 'SIP/538-452a' in macro 'hangupcall' Apr 5 08:55:26 VERBOSE[9346] logger.c: == Spawn extension (macro-hangupcall, s, 3) exited non-zero on 'SIP/538-452a' Apr 5 08:55:26 DEBUG[9346] chan_sip.c: update_call_counter(538) - decrement call limit counter