FÓRUM DigiVoice

Olá nirdo!

Você precisa pegar os logs para que possamos analisar o momento do travamento (cole aqui os trechos finais do /var/log/messages).

[]'s
Muriel Arneiro

Boa tarde

Eu postei que o meu servidor tinha para de travar mais ele voltou a dar o mesmo problema, esto postando aqui o meu log.
Jan 9 15:08:15 VERBOSE[5441] logger.c: -- Executing Goto("SIP/2094-af50de38", "s-BUSY|1") in new stack
Jan 9 15:08:15 VERBOSE[5441] logger.c: -- Goto (macro-dialout-trunk,s-BUSY,1)
Jan 9 15:08:15 DEBUG[5441] app_macro.c: Executed application: Goto
Jan 9 15:08:15 VERBOSE[5441] logger.c: -- Executing NoOp("SIP/2094-af50de38", "Trunk is reporting BUSY") in new stack
Jan 9 15:08:15 DEBUG[5441] app_macro.c: Executed application: NoOp
Jan 9 15:08:15 VERBOSE[5441] logger.c: -- Executing Busy("SIP/2094-af50de38", "") in new stack
Jan 9 15:08:15 VERBOSE[5441] logger.c: == Spawn extension (macro-dialout-trunk, s-BUSY, 2) exited non-zero on 'SIP/2094-af50de38' in macro 'dialout-trunk'
Jan 9 15:08:15 VERBOSE[5441] logger.c: == Spawn extension (macro-dialout-trunk, s-BUSY, 2) exited non-zero on 'SIP/2094-af50de38'
Jan 9 15:08:15 DEBUG[5441] chan_sip.c: update_call_counter(2094) - decrement call limit counter
Jan 9 15:08:15 DEBUG[5441] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record.
Jan 9 15:08:15 DEBUG[5441] 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 ('2008-01-09 15:07:13','\"VIVIANE\" <2094>','2094','0001721368067','from-internal', 'SIP/2094-af50de38','DGV/1','Busy','',62,0,'BUSY',3,'','1199898433.160')
Jan 9 15:08:15 DEBUG[5441] pbx.c: Function result is '"VIVIANE" <2094>'
Jan 9 15:08:15 DEBUG[5441] pbx.c: Function result is '2094'
Jan 9 15:08:15 DEBUG[5441] pbx.c: Function result is '0001721368067'
Jan 9 15:08:15 DEBUG[5441] pbx.c: Function result is 'from-internal'
Jan 9 15:08:15 DEBUG[5441] pbx.c: Function result is 'SIP/2094-af50de38'
Jan 9 15:08:15 DEBUG[5441] pbx.c: Function result is 'DGV/1'
Jan 9 15:08:15 DEBUG[5441] pbx.c: Function result is 'Busy'
Jan 9 15:08:15 DEBUG[5441] pbx.c: Function result is '(null)'
Jan 9 15:08:15 DEBUG[5441] pbx.c: Function result is '2008-01-09 15:07:13'
Jan 9 15:08:15 DEBUG[5441] pbx.c: Function result is '(null)'
Jan 9 15:08:15 DEBUG[5441] pbx.c: Function result is '2008-01-09 15:08:15'
Jan 9 15:08:15 DEBUG[5441] pbx.c: Function result is '62'
Jan 9 15:08:15 DEBUG[5441] pbx.c: Function result is '0'
Jan 9 15:08:15 DEBUG[5441] pbx.c: Function result is 'BUSY'
Jan 9 15:08:15 DEBUG[5441] pbx.c: Function result is 'DOCUMENTATION'
Jan 9 15:08:15 DEBUG[5441] pbx.c: Function result is '(null)'
Jan 9 15:08:15 DEBUG[5441] pbx.c: Function result is '1199898433.160'
Jan 9 15:08:15 DEBUG[5441] pbx.c: Function result is '(null)'
Jan 9 15:08:15 DEBUG[3993] chan_sip.c: Stopping retransmission on 'Mjg2MGFkYzFhOTZlODk2ZjNhNTI0MTY4MGIzMGE2NTY.' of Response 2: Match Not Found
Jan 9 15:08:44 NOTICE[4279] chan_iax2.c: No registration for peer '2010' (from 192.168.0.109)
Jan 9 15:08:53 NOTICE[4279] chan_iax2.c: No registration for peer '2006' (from 201.28.146.163)
Jan 9 15:09:11 DEBUG[4727] manager.c: Manager received command 'Command'
Jan 9 15:09:11 DEBUG[4727] manager.c: Manager received command 'Command'
Jan 9 15:09:16 NOTICE[3993] chan_sip.c: Got SUBSCRIBE for extension 039024204@from-internal from 192.168.0.148, but there is no hint for that extension
Jan 9 15:09:44 NOTICE[4279] chan_iax2.c: No registration for peer '2010' (from 192.168.0.109)
Jan 9 15:09:47 DEBUG[3993] chan_sip.c: Setting NAT on RTP to 0
Jan 9 15:09:47 DEBUG[3993] chan_sip.c: Stopping retransmission on 'NTViNWU2ZGM5NWNlNmY3ZmMxMjkwMGFlYTY4ZjZiODU.' of Response 1: Match Found
Jan 9 15:09:47 DEBUG[3993] chan_sip.c: Setting NAT on RTP to 0
Jan 9 15:09:47 DEBUG[3993] chan_sip.c: Checking SIP call limits for device 2094
Jan 9 15:09:47 DEBUG[3993] chan_sip.c: build_route: Contact hop:
Jan 9 15:09:47 DEBUG[3986] channel.c: Avoiding initial deadlock for 'SIP/2094-af510a58'
Jan 9 15:09:47 VERBOSE[5453] logger.c: -- Executing Macro("SIP/2094-af510a58", "dialout-trunk|2|001721368067||") in new stack
Jan 9 15:09:47 VERBOSE[5453] logger.c: -- Executing Set("SIP/2094-af510a58", "DIAL_TRUNK=2") in new stack
Jan 9 15:09:47 DEBUG[5453] app_macro.c: Executed application: Set
Jan 9 15:09:47 VERBOSE[5453] logger.c: -- Executing Set("SIP/2094-af510a58", "DIAL_NUMBER=001721368067") in new stack
Jan 9 15:09:47 DEBUG[5453] app_macro.c: Executed application: Set
Jan 9 15:09:47 VERBOSE[5453] logger.c: -- Executing Set("SIP/2094-af510a58", "ROUTE_PASSWD=") in new stack
Jan 9 15:09:47 DEBUG[5453] app_macro.c: Executed application: Set
Jan 9 15:09:47 DEBUG[5453] pbx.c: Expression result is '1'
Jan 9 15:09:47 VERBOSE[5453] logger.c: -- Executing GotoIf("SIP/2094-af510a58", "1?noauth") in new stack
Jan 9 15:09:47 VERBOSE[5453] logger.c: -- Goto (macro-dialout-trunk,s,6)
Jan 9 15:09:47 DEBUG[5453] app_macro.c: Executed application: GotoIf
Jan 9 15:09:47 VERBOSE[5453] logger.c: -- Executing Set("SIP/2094-af510a58", "GROUP()=OUT_2") in new stack
Jan 9 15:09:47 DEBUG[5453] app_macro.c: Executed application: Set
Jan 9 15:09:47 VERBOSE[5453] logger.c: -- Executing Macro("SIP/2094-af510a58", "user-callerid") in new stack
Jan 9 15:09:47 DEBUG[5453] pbx.c: Expression result is '0'
Jan 9 15:09:47 VERBOSE[5453] logger.c: -- Executing GotoIf("SIP/2094-af510a58", "0?report") in new stack
Jan 9 15:09:47 DEBUG[5453] pbx.c: Not taking any branch
Jan 9 15:09:47 DEBUG[5453] app_macro.c: Executed application: GotoIf
Jan 9 15:09:47 DEBUG[5453] pbx.c: Expression result is '0'
Jan 9 15:09:47 VERBOSE[5453] logger.c: -- Executing GotoIf("SIP/2094-af510a58", "0?start") in new stack
Jan 9 15:09:47 DEBUG[5453] pbx.c: Not taking any branch
Jan 9 15:09:47 DEBUG[5453] app_macro.c: Executed application: GotoIf
Jan 9 15:09:47 DEBUG[5453] pbx.c: Function result is '2094'
Jan 9 15:09:47 VERBOSE[5453] logger.c: -- Executing Set("SIP/2094-af510a58", "REALCALLERIDNUM=2094") in new stack
Jan 9 15:09:47 DEBUG[5453] app_macro.c: Executed application: Set
Jan 9 15:09:47 VERBOSE[5453] logger.c: -- Executing NoOp("SIP/2094-af510a58", "REALCALLERIDNUM is 2094") in new stack
Jan 9 15:09:47 DEBUG[5453] app_macro.c: Executed application: NoOp
Jan 9 15:09:47 DEBUG[5453] pbx.c: Function result is '2094'
Jan 9 15:09:47 VERBOSE[5453] logger.c: -- Executing Set("SIP/2094-af510a58", "AMPUSER=2094") in new stack
Jan 9 15:09:47 DEBUG[5453] app_macro.c: Executed application: Set
Jan 9 15:09:47 DEBUG[5453] pbx.c: Function result is 'VIVIANE'
Jan 9 15:09:47 VERBOSE[5453] logger.c: -- Executing Set("SIP/2094-af510a58", "AMPUSERCIDNAME=VIVIANE") in new stack
Jan 9 15:09:47 DEBUG[5453] app_macro.c: Executed application: Set
Jan 9 15:09:47 DEBUG[5453] pbx.c: Expression result is '0'
Jan 9 15:09:47 VERBOSE[5453] logger.c: -- Executing GotoIf("SIP/2094-af510a58", "0?report") in new stack
Jan 9 15:09:47 DEBUG[5453] pbx.c: Not taking any branch
Jan 9 15:09:47 DEBUG[5453] app_macro.c: Executed application: GotoIf
Jan 9 15:09:47 VERBOSE[5453] logger.c: -- Executing Set("SIP/2094-af510a58", "CALLERID(all)=VIVIANE <2094>") in new stack
Jan 9 15:09:47 DEBUG[5453] app_macro.c: Executed application: Set
Jan 9 15:09:47 DEBUG[5453] pbx.c: Function result is '"VIVIANE" <2094>'
Jan 9 15:09:47 VERBOSE[5453] logger.c: -- Executing NoOp("SIP/2094-af510a58", "Using CallerID "VIVIANE" <2094>") in new stack
Jan 9 15:09:47 DEBUG[5453] app_macro.c: Executed application: NoOp
Jan 9 15:09:47 DEBUG[5453] app_macro.c: Executed application: Macro
Jan 9 15:09:47 DEBUG[5453] pbx.c: Function result is '2094'
Jan 9 15:09:47 VERBOSE[5453] logger.c: -- Executing Macro("SIP/2094-af510a58", "record-enable|2094|OUT") in new stack
Jan 9 15:09:47 DEBUG[5453] pbx.c: Function result is '0'
Jan 9 15:09:47 VERBOSE[5453] logger.c: -- Executing GotoIf("SIP/2094-af510a58", "0 > 0?2:4") in new stack
Jan 9 15:09:47 VERBOSE[5453] logger.c: -- Goto (macro-record-enable,s,4)
Jan 9 15:09:47 DEBUG[5453] app_macro.c: Executed application: GotoIf
Jan 9 15:09:47 VERBOSE[5453] logger.c: -- Executing AGI("SIP/2094-af510a58", "recordingcheck|20080109-150947|1199898587.162") in new stack
Jan 9 15:09:47 VERBOSE[5453] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
Jan 9 15:09:47 VERBOSE[5453] logger.c: recordingcheck|20080109-150947|1199898587.162: Outbound recording not enabled
Jan 9 15:09:47 DEBUG[3993] chan_sip.c: Stopping retransmission on '5c767a56291ed0911f180ad66040ca81@192.168.0.158' of Request 102: Match Found
Jan 9 15:09:47 VERBOSE[5453] logger.c: -- AGI Script recordingcheck completed, returning 0
Jan 9 15:09:47 DEBUG[5453] app_macro.c: Executed application: AGI
Jan 9 15:09:47 VERBOSE[5453] logger.c: -- Executing NoOp("SIP/2094-af510a58", "No recording needed") in new stack
Jan 9 15:09:47 DEBUG[5453] app_macro.c: Executed application: Noop
Jan 9 15:09:47 DEBUG[5453] app_macro.c: Executed application: Macro
Jan 9 15:09:47 VERBOSE[5453] logger.c: -- Executing Macro("SIP/2094-af510a58", "outbound-callerid|2") in new stack
Jan 9 15:09:47 DEBUG[5453] pbx.c: Expression result is '1'
Jan 9 15:09:47 VERBOSE[5453] logger.c: -- Executing GotoIf("SIP/2094-af510a58", "1?start") in new stack
Jan 9 15:09:47 VERBOSE[5453] logger.c: -- Goto (macro-outbound-callerid,s,3)
Jan 9 15:09:47 DEBUG[5453] app_macro.c: Executed application: GotoIf
Jan 9 15:09:47 VERBOSE[5453] logger.c: -- Executing NoOp("SIP/2094-af510a58", "REALCALLERIDNUM is 2094") in new stack
Jan 9 15:09:47 DEBUG[5453] app_macro.c: Executed application: NoOp
Jan 9 15:09:47 DEBUG[5453] pbx.c: Function result is ''
Jan 9 15:09:47 VERBOSE[5453] logger.c: -- Executing Set("SIP/2094-af510a58", "USEROUTCID=") in new stack
Jan 9 15:09:47 DEBUG[5453] app_macro.c: Executed application: Set
Jan 9 15:09:47 DEBUG[5453] db.c: Unable to find key '2094/emergency_cid' in family 'DEVICE'
Jan 9 15:09:47 DEBUG[5453] func_db.c: DB: DEVICE/2094/emergency_cid not found in database.
Jan 9 15:09:47 DEBUG[5453] pbx.c: Function result is ''
Jan 9 15:09:47 VERBOSE[5453] logger.c: -- Executing Set("SIP/2094-af510a58", "EMERGENCYCID=") in new stack
Jan 9 15:09:47 DEBUG[5453] app_macro.c: Executed application: Set
Jan 9 15:09:47 VERBOSE[5453] logger.c: -- Executing Set("SIP/2094-af510a58", "TRUNKOUTCID=") in new stack
Jan 9 15:09:47 DEBUG[5453] app_macro.c: Executed application: Set
Jan 9 15:09:47 DEBUG[5453] pbx.c: Expression result is '1'
Jan 9 15:09:47 VERBOSE[5453] logger.c: -- Executing GotoIf("SIP/2094-af510a58", "1?trunkcid") in new stack
Jan 9 15:09:47 VERBOSE[5453] logger.c: -- Goto (macro-outbound-callerid,s,11)
Jan 9 15:09:47 DEBUG[5453] app_macro.c: Executed application: GotoIf
Jan 9 15:09:47 DEBUG[5453] pbx.c: Expression result is '1'
Jan 9 15:09:47 VERBOSE[5453] logger.c: -- Executing GotoIf("SIP/2094-af510a58", "1?usercid") in new stack
Jan 9 15:09:47 VERBOSE[5453] logger.c: -- Goto (macro-outbound-callerid,s,13)
Jan 9 15:09:47 DEBUG[5453] app_macro.c: Executed application: GotoIf
Jan 9 15:09:47 DEBUG[5453] pbx.c: Expression result is '1'
Jan 9 15:09:47 VERBOSE[5453] logger.c: -- Executing GotoIf("SIP/2094-af510a58", "1?report") in new stack
Jan 9 15:09:47 VERBOSE[5453] logger.c: -- Goto (macro-outbound-callerid,s,15)
Jan 9 15:09:47 DEBUG[5453] app_macro.c: Executed application: GotoIf
Jan 9 15:09:47 DEBUG[5453] pbx.c: Function result is '"VIVIANE" <2094>'
Jan 9 15:09:47 VERBOSE[5453] logger.c: -- Executing NoOp("SIP/2094-af510a58", "CallerID set to "VIVIANE" <2094>") in new stack
Jan 9 15:09:47 DEBUG[5453] app_macro.c: Executed application: NoOp
Jan 9 15:09:47 DEBUG[5453] app_macro.c: Executed application: Macro
Jan 9 15:09:47 DEBUG[5453] pbx.c: Expression result is '1'
Jan 9 15:09:47 VERBOSE[5453] logger.c: -- Executing GotoIf("SIP/2094-af510a58", "1?nomax") in new stack
Jan 9 15:09:47 VERBOSE[5453] logger.c: -- Goto (macro-dialout-trunk,s,12)
Jan 9 15:09:47 DEBUG[5453] app_macro.c: Executed application: GotoIf
Jan 9 15:09:47 VERBOSE[5453] logger.c: -- Executing AGI("SIP/2094-af510a58", "fixlocalprefix") in new stack
Jan 9 15:09:47 VERBOSE[5453] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/fixlocalprefix
Jan 9 15:09:47 VERBOSE[5453] logger.c: fixlocalprefix: Could not parse /etc/asterisk/localprefixes.conf
Jan 9 15:09:47 VERBOSE[5453] logger.c: -- AGI Script fixlocalprefix completed, returning 0
Jan 9 15:09:47 DEBUG[5453] app_macro.c: Executed application: AGI
Jan 9 15:09:47 VERBOSE[5453] logger.c: -- Executing Set("SIP/2094-af510a58", "OUTNUM=001721368067") in new stack
Jan 9 15:09:47 DEBUG[5453] app_macro.c: Executed application: Set
Jan 9 15:09:47 DEBUG[5453] pbx.c: Function result is 'DGV/g1'
Jan 9 15:09:47 VERBOSE[5453] logger.c: -- Executing Set("SIP/2094-af510a58", "custom=DGV/g1") in new stack
Jan 9 15:09:47 DEBUG[5453] app_macro.c: Executed application: Set
Jan 9 15:09:47 DEBUG[5453] pbx.c: Expression result is '0'
Jan 9 15:09:47 VERBOSE[5453] logger.c: -- Executing GotoIf("SIP/2094-af510a58", "0?customtrunk") in new stack
Jan 9 15:09:47 DEBUG[5453] pbx.c: Not taking any branch
Jan 9 15:09:47 DEBUG[5453] app_macro.c: Executed application: GotoIf
Jan 9 15:09:47 VERBOSE[5453] logger.c: -- Executing Dial("SIP/2094-af510a58", "DGV/g1/001721368067|120|r") in new stack
Jan 9 15:09:47 NOTICE[5453] chan_dgv.c: Asked to get a channel DGV/g1/001721368067 using format AST_FORMAT_ULAW:ffWaveULaw
Jan 9 15:09:47 NOTICE[5453] chan_dgv.c: Group g1
Jan 9 15:09:47 VERBOSE[5453] logger.c: -- Called g1/001721368067
Jan 9 15:09:48 NOTICE[3993] chan_sip.c: Got SUBSCRIBE for extension 04239@from-internal from 192.168.0.188, but there is no hint for that extension
Jan 9 15:09:48 NOTICE[3993] chan_sip.c: Got SUBSCRIBE for extension 0032291000@from-internal from 192.168.0.188, but there is no hint for that extension
Jan 9 15:09:48 NOTICE[3993] chan_sip.c: Got SUBSCRIBE for extension 0004333713700@from-internal from 192.168.0.188, but there is no hint for that extension
Jan 9 15:09:51 DEBUG[3993] chan_sip.c: Auto destroying call 'N2Q2YzA5Mjk5OGYzODE4MjQxZjcwZmUzOGZlNGNjYmY.'
Jan 9 15:09:53 NOTICE[4279] chan_iax2.c: No registration for peer '2006' (from 201.28.146.163)
Jan 9 15:10:04 VERBOSE[5453] logger.c: -- DGV/1 is ringing
Jan 9 15:10:09 VERBOSE[5453] logger.c: -- DGV/1 is ringing
Jan 9 15:10:14 VERBOSE[5453] logger.c: -- DGV/1 is ringing
Jan 9 15:10:19 VERBOSE[5453] logger.c: -- DGV/1 is ringing
Jan 9 15:10:24 VERBOSE[5453] logger.c: -- DGV/1 is ringing
Jan 9 15:10:29 VERBOSE[5453] logger.c: -- DGV/1 is ringing
Jan 9 15:10:34 VERBOSE[5453] logger.c: -- DGV/1 is ringing
Jan 9 15:10:38 VERBOSE[5453] logger.c: -- DGV/1 is ringing
Jan 9 15:10:43 VERBOSE[5453] logger.c: -- DGV/1 is ringing
Jan 9 15:10:44 NOTICE[4279] chan_iax2.c: No registration for peer '2010' (from 192.168.0.109)
Jan 9 15:10:48 VERBOSE[5453] logger.c: -- DGV/1 is ringing
Jan 9 15:10:50 VERBOSE[5453] logger.c: -- DGV/1 is busy
Jan 9 15:10:50 NOTICE[5453] chan_dgv.c: Calling dg_HangUp on port 1
Jan 9 15:10:50 VERBOSE[5453] logger.c: == Everyone is busy/congested at this time (1:1/0/0)
Jan 9 15:10:50 DEBUG[5453] app_dial.c: Exiting with DIALSTATUS=BUSY.
Jan 9 15:10:50 DEBUG[5453] app_macro.c: Executed application: Dial
Jan 9 15:10:50 VERBOSE[5453] logger.c: -- Executing Goto("SIP/2094-af510a58", "s-BUSY|1") in new stack
Jan 9 15:10:50 VERBOSE[5453] logger.c: -- Goto (macro-dialout-trunk,s-BUSY,1)
Jan 9 15:10:50 DEBUG[5453] app_macro.c: Executed application: Goto
Jan 9 15:10:50 VERBOSE[5453] logger.c: -- Executing NoOp("SIP/2094-af510a58", "Trunk is reporting BUSY") in new stack
Jan 9 15:10:50 DEBUG[5453] app_macro.c: Executed application: NoOp
Jan 9 15:10:50 VERBOSE[5453] logger.c: -- Executing Busy("SIP/2094-af510a58", "") in new stack
Jan 9 15:10:50 VERBOSE[5453] logger.c: == Spawn extension (macro-dialout-trunk, s-BUSY, 2) exited non-zero on 'SIP/2094-af510a58' in macro 'dialout-trunk'
Jan 9 15:10:50 VERBOSE[5453] logger.c: == Spawn extension (macro-dialout-trunk, s-BUSY, 2) exited non-zero on 'SIP/2094-af510a58'
Jan 9 15:10:50 DEBUG[5453] chan_sip.c: update_call_counter(2094) - decrement call limit counter
Jan 9 15:10:50 DEBUG[5453] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record.
Jan 9 15:10:50 DEBUG[5453] 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 ('2008-01-09 15:09:47','\"VIVIANE\" <2094>','2094','0001721368067','from-internal', 'SIP/2094-af510a58','DGV/1','Busy','',63,0,'BUSY',3,'','1199898587.162')
Jan 9 15:10:50 DEBUG[5453] pbx.c: Function result is '"VIVIANE" <2094>'
Jan 9 15:10:50 DEBUG[5453] pbx.c: Function result is '2094'
Jan 9 15:10:50 DEBUG[5453] pbx.c: Function result is '0001721368067'
Jan 9 15:10:50 DEBUG[5453] pbx.c: Function result is 'from-internal'
Jan 9 15:10:50 DEBUG[5453] pbx.c: Function result is 'SIP/2094-af510a58'
Jan 9 15:10:50 DEBUG[5453] pbx.c: Function result is 'DGV/1'
Jan 9 15:10:50 DEBUG[5453] pbx.c: Function result is 'Busy'
Jan 9 15:10:50 DEBUG[5453] pbx.c: Function result is '(null)'
Jan 9 15:10:50 DEBUG[5453] pbx.c: Function result is '2008-01-09 15:09:47'
Jan 9 15:10:50 DEBUG[5453] pbx.c: Function result is '(null)'
Jan 9 15:10:50 DEBUG[5453] pbx.c: Function result is '2008-01-09 15:10:50'
Jan 9 15:10:50 DEBUG[5453] pbx.c: Function result is '63'
Jan 9 15:10:50 DEBUG[5453] pbx.c: Function result is '0'
Jan 9 15:10:50 DEBUG[5453] pbx.c: Function result is 'BUSY'
Jan 9 15:10:50 DEBUG[5453] pbx.c: Function result is 'DOCUMENTATION'
Jan 9 15:10:50 DEBUG[5453] pbx.c: Function result is '(null)'
Jan 9 15:10:50 DEBUG[5453] pbx.c: Function result is '1199898587.162'
Jan 9 15:10:50 DEBUG[5453] pbx.c: Function result is '(null)'
Jan 9 15:10:50 DEBUG[3993] chan_sip.c: Stopping retransmission on 'NTViNWU2ZGM5NWNlNmY3ZmMxMjkwMGFlYTY4ZjZiODU.' of Response 2: Match Not Found
Jan 9 15:10:53 NOTICE[4279] chan_iax2.c: No registration for peer '2006' (from 201.28.146.163)
Jan 9 15:11:11 DEBUG[4727] manager.c: Manager received command 'Command'
Jan 9 15:11:11 DEBUG[4727] manager.c: Manager received command 'Command'
Jan 9 15:11:41 NOTICE[3993] chan_sip.c: Got SUBSCRIBE for extension 2005@from-sip-external from 201.34.201.190, but there is no hint for that extension
Jan 9 15:11:44 NOTICE[4279] chan_iax2.c: No registration for peer '2010' (from 192.168.0.109)
Jan 9 15:11:53 NOTICE[4279] chan_iax2.c: No registration for peer '2006' (from 201.28.146.163)
Jan 9 15:12:05 DEBUG[3993] chan_sip.c: Setting NAT on RTP to 0
Jan 9 15:12:05 DEBUG[3993] chan_sip.c: Stopping retransmission on 'NDY5YjZiNmM2OGM4NDk1OGMzY2U1NGEzYzZjMTVlMDQ.' of Response 1: Match Found
Jan 9 15:12:05 DEBUG[3993] chan_sip.c: Setting NAT on RTP to 0
Jan 9 15:12:05 DEBUG[3993] chan_sip.c: Checking SIP call limits for device 2094
Jan 9 15:12:05 DEBUG[3993] chan_sip.c: build_route: Contact hop:
Jan 9 15:12:05 DEBUG[3986] channel.c: Avoiding initial deadlock for 'SIP/2094-af50de38'
Jan 9 15:12:05 VERBOSE[5464] logger.c: -- Executing Macro("SIP/2094-af50de38", "dialout-trunk|2|00172138073||") in new stack
Jan 9 15:12:05 VERBOSE[5464] logger.c: -- Executing Set("SIP/2094-af50de38", "DIAL_TRUNK=2") in new stack
Jan 9 15:12:05 DEBUG[5464] app_macro.c: Executed application: Set
Jan 9 15:12:05 VERBOSE[5464] logger.c: -- Executing Set("SIP/2094-af50de38", "DIAL_NUMBER=00172138073") in new stack
Jan 9 15:12:05 DEBUG[5464] app_macro.c: Executed application: Set
Jan 9 15:12:05 VERBOSE[5464] logger.c: -- Executing Set("SIP/2094-af50de38", "ROUTE_PASSWD=") in new stack
Jan 9 15:12:05 DEBUG[5464] app_macro.c: Executed application: Set
Jan 9 15:12:05 DEBUG[5464] pbx.c: Expression result is '1'
Jan 9 15:12:05 VERBOSE[5464] logger.c: -- Executing GotoIf("SIP/2094-af50de38", "1?noauth") in new stack
Jan 9 15:12:05 VERBOSE[5464] logger.c: -- Goto (macro-dialout-trunk,s,6)
Jan 9 15:12:05 DEBUG[5464] app_macro.c: Executed application: GotoIf
Jan 9 15:12:05 VERBOSE[5464] logger.c: -- Executing Set("SIP/2094-af50de38", "GROUP()=OUT_2") in new stack
Jan 9 15:12:05 DEBUG[5464] app_macro.c: Executed application: Set
Jan 9 15:12:05 VERBOSE[5464] logger.c: -- Executing Macro("SIP/2094-af50de38", "user-callerid") in new stack
Jan 9 15:12:05 DEBUG[5464] pbx.c: Expression result is '0'
Jan 9 15:12:05 VERBOSE[5464] logger.c: -- Executing GotoIf("SIP/2094-af50de38", "0?report") in new stack
Jan 9 15:12:05 DEBUG[5464] pbx.c: Not taking any branch
Jan 9 15:12:05 DEBUG[5464] app_macro.c: Executed application: GotoIf
Jan 9 15:12:05 DEBUG[5464] pbx.c: Expression result is '0'
Jan 9 15:12:05 VERBOSE[5464] logger.c: -- Executing GotoIf("SIP/2094-af50de38", "0?start") in new stack
Jan 9 15:12:05 DEBUG[5464] pbx.c: Not taking any branch
Jan 9 15:12:05 DEBUG[5464] app_macro.c: Executed application: GotoIf
Jan 9 15:12:05 DEBUG[5464] pbx.c: Function result is '2094'
Jan 9 15:12:05 VERBOSE[5464] logger.c: -- Executing Set("SIP/2094-af50de38", "REALCALLERIDNUM=2094") in new stack
Jan 9 15:12:05 DEBUG[5464] app_macro.c: Executed application: Set
Jan 9 15:12:05 VERBOSE[5464] logger.c: -- Executing NoOp("SIP/2094-af50de38", "REALCALLERIDNUM is 2094") in new stack
Jan 9 15:12:05 DEBUG[5464] app_macro.c: Executed application: NoOp
Jan 9 15:12:05 DEBUG[5464] pbx.c: Function result is '2094'
Jan 9 15:12:05 VERBOSE[5464] logger.c: -- Executing Set("SIP/2094-af50de38", "AMPUSER=2094") in new stack
Jan 9 15:12:05 DEBUG[5464] app_macro.c: Executed application: Set
Jan 9 15:12:05 DEBUG[5464] pbx.c: Function result is 'VIVIANE'
Jan 9 15:12:05 VERBOSE[5464] logger.c: -- Executing Set("SIP/2094-af50de38", "AMPUSERCIDNAME=VIVIANE") in new stack
Jan 9 15:12:05 DEBUG[5464] app_macro.c: Executed application: Set
Jan 9 15:12:05 DEBUG[5464] pbx.c: Expression result is '0'
Jan 9 15:12:05 VERBOSE[5464] logger.c: -- Executing GotoIf("SIP/2094-af50de38", "0?report") in new stack
Jan 9 15:12:05 DEBUG[5464] pbx.c: Not taking any branch
Jan 9 15:12:05 DEBUG[5464] app_macro.c: Executed application: GotoIf
Jan 9 15:12:05 VERBOSE[5464] logger.c: -- Executing Set("SIP/2094-af50de38", "CALLERID(all)=VIVIANE <2094>") in new stack
Jan 9 15:12:05 DEBUG[5464] app_macro.c: Executed application: Set
Jan 9 15:12:05 DEBUG[5464] pbx.c: Function result is '"VIVIANE" <2094>'
Jan 9 15:12:05 VERBOSE[5464] logger.c: -- Executing NoOp("SIP/2094-af50de38", "Using CallerID "VIVIANE" <2094>") in new stack
Jan 9 15:12:05 DEBUG[5464] app_macro.c: Executed application: NoOp
Jan 9 15:12:05 DEBUG[5464] app_macro.c: Executed application: Macro
Jan 9 15:12:05 DEBUG[5464] pbx.c: Function result is '2094'
Jan 9 15:12:05 VERBOSE[5464] logger.c: -- Executing Macro("SIP/2094-af50de38", "record-enable|2094|OUT") in new stack
Jan 9 15:12:05 DEBUG[5464] pbx.c: Function result is '0'
Jan 9 15:12:05 VERBOSE[5464] logger.c: -- Executing GotoIf("SIP/2094-af50de38", "0 > 0?2:4") in new stack
Jan 9 15:12:05 VERBOSE[5464] logger.c: -- Goto (macro-record-enable,s,4)
Jan 9 15:12:05 DEBUG[5464] app_macro.c: Executed application: GotoIf
Jan 9 15:12:05 VERBOSE[5464] logger.c: -- Executing AGI("SIP/2094-af50de38", "recordingcheck|20080109-151205|1199898725.164") in new stack
Jan 9 15:12:05 VERBOSE[5464] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
Jan 9 15:12:05 VERBOSE[5464] logger.c: recordingcheck|20080109-151205|1199898725.164: Outbound recording not enabled
Jan 9 15:12:05 VERBOSE[5464] logger.c: -- AGI Script recordingcheck completed, returning 0
Jan 9 15:12:05 DEBUG[5464] app_macro.c: Executed application: AGI
Jan 9 15:12:05 VERBOSE[5464] logger.c: -- Executing NoOp("SIP/2094-af50de38", "No recording needed") in new stack
Jan 9 15:12:05 DEBUG[5464] app_macro.c: Executed application: Noop
Jan 9 15:12:05 DEBUG[5464] app_macro.c: Executed application: Macro
Jan 9 15:12:05 VERBOSE[5464] logger.c: -- Executing Macro("SIP/2094-af50de38", "outbound-callerid|2") in new stack
Jan 9 15:12:05 DEBUG[5464] pbx.c: Expression result is '1'
Jan 9 15:12:05 VERBOSE[5464] logger.c: -- Executing GotoIf("SIP/2094-af50de38", "1?start") in new stack
Jan 9 15:12:05 VERBOSE[5464] logger.c: -- Goto (macro-outbound-callerid,s,3)
Jan 9 15:12:05 DEBUG[5464] app_macro.c: Executed application: GotoIf
Jan 9 15:12:05 VERBOSE[5464] logger.c: -- Executing NoOp("SIP/2094-af50de38", "REALCALLERIDNUM is 2094") in new stack
Jan 9 15:12:05 DEBUG[5464] app_macro.c: Executed application: NoOp
Jan 9 15:12:05 DEBUG[5464] pbx.c: Function result is ''
Jan 9 15:12:05 VERBOSE[5464] logger.c: -- Executing Set("SIP/2094-af50de38", "USEROUTCID=") in new stack
Jan 9 15:12:05 DEBUG[5464] app_macro.c: Executed application: Set
Jan 9 15:12:05 DEBUG[5464] db.c: Unable to find key '2094/emergency_cid' in family 'DEVICE'
Jan 9 15:12:05 DEBUG[5464] func_db.c: DB: DEVICE/2094/emergency_cid not found in database.
Jan 9 15:12:05 DEBUG[5464] pbx.c: Function result is ''
Jan 9 15:12:05 VERBOSE[5464] logger.c: -- Executing Set("SIP/2094-af50de38", "EMERGENCYCID=") in new stack
Jan 9 15:12:05 DEBUG[5464] app_macro.c: Executed application: Set
Jan 9 15:12:05 VERBOSE[5464] logger.c: -- Executing Set("SIP/2094-af50de38", "TRUNKOUTCID=") in new stack
Jan 9 15:12:05 DEBUG[5464] app_macro.c: Executed application: Set
Jan 9 15:12:05 DEBUG[5464] pbx.c: Expression result is '1'
Jan 9 15:12:05 VERBOSE[5464] logger.c: -- Executing GotoIf("SIP/2094-af50de38", "1?trunkcid") in new stack
Jan 9 15:12:05 VERBOSE[5464] logger.c: -- Goto (macro-outbound-callerid,s,11)
Jan 9 15:12:05 DEBUG[5464] app_macro.c: Executed application: GotoIf
Jan 9 15:12:05 DEBUG[5464] pbx.c: Expression result is '1'
Jan 9 15:12:05 VERBOSE[5464] logger.c: -- Executing GotoIf("SIP/2094-af50de38", "1?usercid") in new stack
Jan 9 15:12:05 VERBOSE[5464] logger.c: -- Goto (macro-outbound-callerid,s,13)
Jan 9 15:12:05 DEBUG[5464] app_macro.c: Executed application: GotoIf
Jan 9 15:12:05 DEBUG[5464] pbx.c: Expression result is '1'
Jan 9 15:12:05 VERBOSE[5464] logger.c: -- Executing GotoIf("SIP/2094-af50de38", "1?report") in new stack
Jan 9 15:12:05 VERBOSE[5464] logger.c: -- Goto (macro-outbound-callerid,s,15)
Jan 9 15:12:05 DEBUG[5464] app_macro.c: Executed application: GotoIf
Jan 9 15:12:05 DEBUG[5464] pbx.c: Function result is '"VIVIANE" <2094>'
Jan 9 15:12:05 VERBOSE[5464] logger.c: -- Executing NoOp("SIP/2094-af50de38", "CallerID set to "VIVIANE" <2094>") in new stack
Jan 9 15:12:05 DEBUG[5464] app_macro.c: Executed application: NoOp
Jan 9 15:12:05 DEBUG[5464] app_macro.c: Executed application: Macro
Jan 9 15:12:05 DEBUG[5464] pbx.c: Expression result is '1'
Jan 9 15:12:05 VERBOSE[5464] logger.c: -- Executing GotoIf("SIP/2094-af50de38", "1?nomax") in new stack
Jan 9 15:12:05 VERBOSE[5464] logger.c: -- Goto (macro-dialout-trunk,s,12)
Jan 9 15:12:05 DEBUG[5464] app_macro.c: Executed application: GotoIf
Jan 9 15:12:05 VERBOSE[5464] logger.c: -- Executing AGI("SIP/2094-af50de38", "fixlocalprefix") in new stack
Jan 9 15:12:05 VERBOSE[5464] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/fixlocalprefix
Jan 9 15:12:05 VERBOSE[5464] logger.c: fixlocalprefix: Could not parse /etc/asterisk/localprefixes.conf
Jan 9 15:12:05 VERBOSE[5464] logger.c: -- AGI Script fixlocalprefix completed, returning 0
Jan 9 15:12:05 DEBUG[5464] app_macro.c: Executed application: AGI
Jan 9 15:12:05 VERBOSE[5464] logger.c: -- Executing Set("SIP/2094-af50de38", "OUTNUM=00172138073") in new stack
Jan 9 15:12:05 DEBUG[5464] app_macro.c: Executed application: Set
Jan 9 15:12:05 DEBUG[5464] pbx.c: Function result is 'DGV/g1'
Jan 9 15:12:05 VERBOSE[5464] logger.c: -- Executing Set("SIP/2094-af50de38", "custom=DGV/g1") in new stack
Jan 9 15:12:05 DEBUG[5464] app_macro.c: Executed application: Set
Jan 9 15:12:05 DEBUG[5464] pbx.c: Expression result is '0'
Jan 9 15:12:05 VERBOSE[5464] logger.c: -- Executing GotoIf("SIP/2094-af50de38", "0?customtrunk") in new stack
Jan 9 15:12:05 DEBUG[5464] pbx.c: Not taking any branch
Jan 9 15:12:05 DEBUG[5464] app_macro.c: Executed application: GotoIf
Jan 9 15:12:05 VERBOSE[5464] logger.c: -- Executing Dial("SIP/2094-af50de38", "DGV/g1/00172138073|120|r") in new stack
Jan 9 15:12:05 NOTICE[5464] chan_dgv.c: Asked to get a channel DGV/g1/00172138073 using format AST_FORMAT_ULAW:ffWaveULaw
Jan 9 15:12:05 NOTICE[5464] chan_dgv.c: Group g1
Jan 9 15:12:05 VERBOSE[5464] logger.c: -- Called g1/00172138073
Jan 9 15:12:28 VERBOSE[5464] logger.c: -- DGV/1 answered SIP/2094-af50de38
Jan 9 15:12:28 DEBUG[3986] channel.c: Avoiding initial deadlock for 'SIP/2094-af50de38'
Jan 9 15:12:28 DEBUG[5464] channel.c: Dropping duplicate answer!
Jan 9 15:12:28 DEBUG[3993] chan_sip.c: Stopping retransmission on 'NDY5YjZiNmM2OGM4NDk1OGMzY2U1NGEzYzZjMTVlMDQ.' of Response 2: Match Found
Jan 9 15:12:37 DEBUG[5464] channel.c: Didn't get a frame from channel: SIP/2094-af50de38
Jan 9 15:12:37 DEBUG[5464] channel.c: Bridge stops bridging channels SIP/2094-af50de38 and DGV/1
Jan 9 15:12:37 NOTICE[5464] chan_dgv.c: Calling dg_HangUp on port 1
Jan 9 15:12:37 DEBUG[5464] app_dial.c: Exiting with DIALSTATUS=ANSWER.
Jan 9 15:12:37 VERBOSE[5464] logger.c: == Spawn extension (macro-dialout-trunk, s, 16) exited non-zero on 'SIP/2094-af50de38' in macro 'dialout-trunk'
Jan 9 15:12:37 VERBOSE[5464] logger.c: == Spawn extension (macro-dialout-trunk, s, 16) exited non-zero on 'SIP/2094-af50de38'
Jan 9 15:12:37 DEBUG[5464] chan_sip.c: update_call_counter(2094) - decrement call limit counter
Jan 9 15:12:37 DEBUG[5464] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record.
Jan 9 15:12:37 DEBUG[5464] 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 ('2008-01-09 15:12:05','\"VIVIANE\" <2094>','2094','000172138073','from-internal', 'SIP/2094-af50de38','DGV/1','Dial','DGV/g1/00172138073|120|r',32,9,'ANSWERED',3,'','1199898725.164')
Jan 9 15:12:37 DEBUG[5464] pbx.c: Function result is '"VIVIANE" <2094>'
Jan 9 15:12:37 DEBUG[5464] pbx.c: Function result is '2094'
Jan 9 15:12:37 DEBUG[5464] pbx.c: Function result is '000172138073'
Jan 9 15:12:37 DEBUG[5464] pbx.c: Function result is 'from-internal'
Jan 9 15:12:37 DEBUG[5464] pbx.c: Function result is 'SIP/2094-af50de38'
Jan 9 15:12:37 DEBUG[5464] pbx.c: Function result is 'DGV/1'
Jan 9 15:12:37 DEBUG[5464] pbx.c: Function result is 'Dial'
Jan 9 15:12:37 DEBUG[5464] pbx.c: Function result is 'DGV/g1/00172138073|120|r'
Jan 9 15:12:37 DEBUG[5464] pbx.c: Function result is '2008-01-09 15:12:05'
Jan 9 15:12:37 DEBUG[5464] pbx.c: Function result is '2008-01-09 15:12:28'
Jan 9 15:12:37 DEBUG[5464] pbx.c: Function result is '2008-01-09 15:12:37'
Jan 9 15:12:37 DEBUG[5464] pbx.c: Function result is '32'
Jan 9 15:12:37 DEBUG[5464] pbx.c: Function result is '9'
Jan 9 15:12:37 DEBUG[5464] pbx.c: Function result is 'ANSWERED'
Jan 9 15:12:37 DEBUG[5464] pbx.c: Function result is 'DOCUMENTATION'
Jan 9 15:12:37 DEBUG[5464] pbx.c: Function result is '(null)'
Jan 9 15:12:37 DEBUG[5464] pbx.c: Function result is '1199898725.164'
Jan 9 15:12:37 DEBUG[5464] pbx.c: Function result is '(null)'
Jan 9 15:12:44 NOTICE[4279] chan_iax2.c: No registration for peer '2010' (from 192.168.0.109)
Jan 9 15:12:53 NOTICE[4279] chan_iax2.c: No registration for peer '2006' (from 201.28.146.163)
Jan 9 15:13:11 DEBUG[4727] manager.c: Manager received command 'Command'
Jan 9 15:13:11 DEBUG[4727] manager.c: Manager received command 'Command'
Jan 9 15:13:44 NOTICE[4279] chan_iax2.c: No registration for peer '2010' (from 192.168.0.109)
Jan 9 15:13:53 NOTICE[4279] chan_iax2.c: No registration for peer '2006' (from 201.28.146.163)
Jan 9 15:14:16 NOTICE[3993] chan_sip.c: Got SUBSCRIBE for extension 039024204@from-internal from 192.168.0.148, but there is no hint for that extension
Jan 9 15:14:44 NOTICE[4279] chan_iax2.c: No registration for peer '2010' (from 192.168.0.109)
Jan 9 15:14:48 NOTICE[3993] chan_sip.c: Got SUBSCRIBE for extension 04239@from-internal from 192.168.0.188, but there is no hint for that extension
Jan 9 15:14:48 NOTICE[3993] chan_sip.c: Got SUBSCRIBE for extension 0032291000@from-internal from 192.168.0.188, but there is no hint for that extension
Jan 9 15:14:48 NOTICE[3993] chan_sip.c: Got SUBSCRIBE for extension 0004333713700@from-internal from 192.168.0.188, but there is no hint for that extension
Jan 9 15:14:53 NOTICE[4279] chan_iax2.c: No registration for peer '2006' (from 201.28.146.163)
Jan 9 15:15:11 DEBUG[4727] manager.c: Manager received command 'Command'
Jan 9 15:15:11 DEBUG[4727] manager.c: Manager received command 'Command'
Jan 9 15:15:44 NOTICE[4279] chan_iax2.c: No registration for peer '2010' (from 192.168.0.109)
Jan 9 15:15:53 NOTICE[4279] chan_iax2.c: No registration for peer '2006' (from 201.28.146.163)
Jan 9 15:16:41 NOTICE[3993] chan_sip.c: Got SUBSCRIBE for extension 2005@from-sip-external from 201.34.201.190, but there is no hint for that extension
Jan 9 15:16:44 NOTICE[4279] chan_iax2.c: No registration for peer '2010' (from 192.168.0.109)
Jan 9 15:16:53 NOTICE[4279] chan_iax2.c: No registration for peer '2006' (from 201.28.146.163)
Jan 9 15:17:11 DEBUG[4727] manager.c: Manager received command 'Command'
Jan 9 15:17:11 DEBUG[4727] manager.c: Manager received command 'Command'
Jan 9 15:17:44 NOTICE[4279] chan_iax2.c: No registration for peer '2010' (from 192.168.0.109)
Jan 9 15:17:53 NOTICE[4279] chan_iax2.c: No registration for peer '2006' (from 201.28.146.163)
Jan 9 15:18:44 NOTICE[4279] chan_iax2.c: No registration for peer '2010' (from 192.168.0.109)
Jan 9 15:18:53 NOTICE[4279] chan_iax2.c: No registration for peer '2006' (from 201.28.146.163)
Jan 9 15:19:11 DEBUG[4727] manager.c: Manager received command 'Command'
Jan 9 15:19:11 DEBUG[4727] manager.c: Manager received command 'Command'
Jan 9 15:19:16 NOTICE[3993] chan_sip.c: Got SUBSCRIBE for extension 039024204@from-internal from 192.168.0.148, but there is no hint for that extension
Jan 9 15:19:44 NOTICE[4279] chan_iax2.c: No registration for peer '2010' (from 192.168.0.109)
Jan 9 15:19:48 NOTICE[3993] chan_sip.c: Got SUBSCRIBE for extension 04239@from-internal from 192.168.0.188, but there is no hint for that extension
Jan 9 15:19:48 NOTICE[3993] chan_sip.c: Got SUBSCRIBE for extension 0032291000@from-internal from 192.168.0.188, but there is no hint for that extension
Jan 9 15:19:48 NOTICE[3993] chan_sip.c: Got SUBSCRIBE for extension 0004333713700@from-internal from 192.168.0.188, but there is no hint for that extension
Jan 9 15:19:53 NOTICE[4279] chan_iax2.c: No registration for peer '2006' (from 201.28.146.163)
Jan 9 15:20:44 NOTICE[4279] chan_iax2.c: No registration for peer '2010' (from 192.168.0.109)
Jan 9 15:20:53 NOTICE[4279] chan_iax2.c: No registration for peer '2006' (from 201.28.146.163)
Jan 9 15:21:11 DEBUG[4727] manager.c: Manager received command 'Command'
Jan 9 15:21:11 DEBUG[4727] manager.c: Manager received command 'Command'
Jan 9 15:21:41 NOTICE[3993] chan_sip.c: Got SUBSCRIBE for extension 2005@from-sip-external from 201.34.201.190, but there is no hint for that extension
Jan 9 15:21:44 NOTICE[4279] chan_iax2.c: No registration for peer '2010' (from 192.168.0.109)
Jan 9 15:21:53 NOTICE[4279] chan_iax2.c: No registration for peer '2006' (from 201.28.146.163)
Jan 9 15:22:43 NOTICE[4279] chan_iax2.c: No registration for peer '2010' (from 192.168.0.109)
Jan 9 15:22:53 NOTICE[4279] chan_iax2.c: No registration for peer '2006' (from 201.28.146.163)
Jan 9 15:23:11 DEBUG[4727] manager.c: Manager received command 'Command'
Jan 9 15:23:11 DEBUG[4727] manager.c: Manager received command 'Command'
Jan 9 15:23:44 NOTICE[4279] chan_iax2.c: No registration for peer '2010' (from 192.168.0.109)
Jan 9 15:23:53 NOTICE[4279] chan_iax2.c: No registration for peer '2006' (from 201.28.146.163)
Jan 9 15:24:16 NOTICE[3993] chan_sip.c: Got SUBSCRIBE for extension 039024204@from-internal from 192.168.0.148, but there is no hint for that extension
Jan 9 15:24:44 NOTICE[4279] chan_iax2.c: No registration for peer '2010' (from 192.168.0.109)
Jan 9 15:24:48 NOTICE[3993] chan_sip.c: Got SUBSCRIBE for extension 04239@from-internal from 192.168.0.188, but there is no hint for that extension
Jan 9 15:24:48 NOTICE[3993] chan_sip.c: Got SUBSCRIBE for extension 0032291000@from-internal from 192.168.0.188, but there is no hint for that extension
Jan 9 15:24:48 NOTICE[3993] chan_sip.c: Got SUBSCRIBE for extension 0004333713700@from-internal from 192.168.0.188, but there is no hint for that extension
Jan 9 15:24:53 NOTICE[4279] chan_iax2.c: No registration for peer '2006' (from 201.28.146.163)
Jan 9 15:25:11 DEBUG[4727] manager.c: Manager received command 'Command'
Jan 9 15:25:11 DEBUG[4727] manager.c: Manager received command 'Command'
Jan 9 15:25:44 NOTICE[4279] chan_iax2.c: No registration for peer '2010' (from 192.168.0.109)
Jan 9 15:25:53 NOTICE[4279] chan_iax2.c: No registration for peer '2006' (from 201.28.146.163)
Jan 9 15:26:42 NOTICE[3993] chan_sip.c: Got SUBSCRIBE for extension 2005@from-sip-external from 201.34.201.190, but there is no hint for that extension
Jan 9 15:26:44 NOTICE[4279] chan_iax2.c: No registration for peer '2010' (from 192.168.0.109)
Jan 9 15:26:53 NOTICE[4279] chan_iax2.c: No registration for peer '2006' (from 201.28.146.163)
Jan 9 15:27:11 DEBUG[4727] manager.c: Manager received command 'Command'
Jan 9 15:27:11 DEBUG[4727] manager.c: Manager received command 'Command'
Jan 9 15:27:44 NOTICE[4279] chan_iax2.c: No registration for peer '2010' (from 192.168.0.109)
Jan 9 15:27:53 NOTICE[4279] chan_iax2.c: No registration for peer '2006' (from 201.28.146.163)
Jan 9 15:28:44 NOTICE[4279] chan_iax2.c: No registration for peer '2010' (from 192.168.0.109)
Jan 9 15:28:53 NOTICE[4279] chan_iax2.c: No registration for peer '2006' (from 201.28.146.163)
Jan 9 15:29:11 DEBUG[4727] manager.c: Manager received command 'Command'
Jan 9 15:29:11 DEBUG[4727] manager.c: Manager received command 'Command'
Jan 9 15:29:17 NOTICE[3993] chan_sip.c: Got SUBSCRIBE for extension 039024204@from-internal from 192.168.0.148, but there is no hint for that extension
Jan 9 15:29:32 VERBOSE[4107] logger.c: -- Ringing on port 22 - callstate=0
Jan 9 15:29:32 VERBOSE[4107] logger.c: -- Answering port 22
Jan 9 15:29:32 VERBOSE[5507] logger.c: -- Executing Set("DGV/22", "FROM_DID=2099") in new stack
Jan 9 15:29:32 VERBOSE[5507] logger.c: -- Executing Goto("DGV/22", "from-did-direct|2099|1") in new stack
Jan 9 15:29:32 VERBOSE[5507] logger.c: -- Goto (from-did-direct,2099,1)
Jan 9 15:29:32 VERBOSE[5507] logger.c: -- Executing Macro("DGV/22", "exten-vm|2099|2099") in new stack
Jan 9 15:29:32 VERBOSE[5507] logger.c: -- Executing Macro("DGV/22", "user-callerid") in new stack
Jan 9 15:29:32 DEBUG[5507] pbx.c: Expression result is '0'
Jan 9 15:29:32 VERBOSE[5507] logger.c: -- Executing GotoIf("DGV/22", "0?report") in new stack
Jan 9 15:29:32 DEBUG[5507] pbx.c: Not taking any branch
Jan 9 15:29:32 DEBUG[5507] app_macro.c: Executed application: GotoIf
Jan 9 15:29:32 DEBUG[5507] pbx.c: Expression result is '0'
Jan 9 15:29:32 VERBOSE[5507] logger.c: -- Executing GotoIf("DGV/22", "0?start") in new stack
Jan 9 15:29:32 DEBUG[5507] pbx.c: Not taking any branch
Jan 9 15:29:32 DEBUG[5507] app_macro.c: Executed application: GotoIf
Jan 9 15:29:32 DEBUG[5507] pbx.c: Function result is '39024238'
Jan 9 15:29:32 VERBOSE[5507] logger.c: -- Executing Set("DGV/22", "REALCALLERIDNUM=39024238") in new stack
Jan 9 15:29:32 DEBUG[5507] app_macro.c: Executed application: Set
Jan 9 15:29:32 VERBOSE[5507] logger.c: -- Executing NoOp("DGV/22", "REALCALLERIDNUM is 39024238") in new stack
Jan 9 15:29:32 DEBUG[5507] app_macro.c: Executed application: NoOp
Jan 9 15:29:32 DEBUG[5507] db.c: Unable to find key '39024238/user' in family 'DEVICE'
Jan 9 15:29:32 DEBUG[5507] func_db.c: DB: DEVICE/39024238/user not found in database.
Jan 9 15:29:32 DEBUG[5507] pbx.c: Function result is ''
Jan 9 15:29:32 VERBOSE[5507] logger.c: -- Executing Set("DGV/22", "AMPUSER=") in new stack
Jan 9 15:29:32 DEBUG[5507] app_macro.c: Executed application: Set
Jan 9 15:29:32 DEBUG[5507] db.c: Unable to find key '/cidname' in family 'AMPUSER'
Jan 9 15:29:32 DEBUG[5507] func_db.c: DB: AMPUSER//cidname not found in database.
Jan 9 15:29:32 DEBUG[5507] pbx.c: Function result is ''
Jan 9 15:29:32 VERBOSE[5507] logger.c: -- Executing Set("DGV/22", "AMPUSERCIDNAME=") in new stack
Jan 9 15:29:32 DEBUG[5507] app_macro.c: Executed application: Set
Jan 9 15:29:32 DEBUG[5507] pbx.c: Expression result is '1'
Jan 9 15:29:32 VERBOSE[5507] logger.c: -- Executing GotoIf("DGV/22", "1?report") in new stack
Jan 9 15:29:32 VERBOSE[5507] logger.c: -- Goto (macro-user-callerid,s,9)
Jan 9 15:29:32 DEBUG[5507] app_macro.c: Executed application: GotoIf
Jan 9 15:29:32 DEBUG[5507] pbx.c: Function result is '"" <39024238>'
Jan 9 15:29:32 VERBOSE[5507] logger.c: -- Executing NoOp("DGV/22", "Using CallerID "" <39024238>") in new stack
Jan 9 15:29:32 DEBUG[5507] app_macro.c: Executed application: NoOp
Jan 9 15:29:32 DEBUG[5507] app_macro.c: Executed application: Macro
Jan 9 15:29:32 VERBOSE[5507] logger.c: -- Executing Set("DGV/22", "FROMCONTEXT=exten-vm") in new stack
Jan 9 15:29:32 DEBUG[5507] app_macro.c: Executed application: Set
Jan 9 15:29:32 VERBOSE[5507] logger.c: -- Executing Set("DGV/22", "VMBOX=2099") in new stack
Jan 9 15:29:32 DEBUG[5507] app_macro.c: Executed application: Set
Jan 9 15:29:32 VERBOSE[5507] logger.c: -- Executing Set("DGV/22", "EXTTOCALL=2099") in new stack
Jan 9 15:29:32 DEBUG[5507] app_macro.c: Executed application: Set
Jan 9 15:29:32 DEBUG[5507] db.c: Unable to find key '2099' in family 'CFU'
Jan 9 15:29:32 DEBUG[5507] func_db.c: DB: CFU/2099 not found in database.
Jan 9 15:29:32 DEBUG[5507] pbx.c: Function result is ''
Jan 9 15:29:32 VERBOSE[5507] logger.c: -- Executing Set("DGV/22", "CFUEXT=") in new stack
Jan 9 15:29:32 DEBUG[5507] app_macro.c: Executed application: Set
Jan 9 15:29:32 DEBUG[5507] pbx.c: Expression result is '1'
Jan 9 15:29:32 DEBUG[5507] pbx.c: Expression result is '0'
Jan 9 15:29:32 DEBUG[5507] pbx.c: Expression result is '1'
Jan 9 15:29:32 DEBUG[5507] pbx.c: Function result is '15'
Jan 9 15:29:32 VERBOSE[5507] logger.c: -- Executing Set("DGV/22", "RT=15") in new stack
Jan 9 15:29:32 DEBUG[5507] app_macro.c: Executed application: Set
Jan 9 15:29:32 VERBOSE[5507] logger.c: -- Executing Macro("DGV/22", "record-enable|2099|IN") in new stack
Jan 9 15:29:32 DEBUG[5507] pbx.c: Function result is '0'
Jan 9 15:29:32 VERBOSE[5507] logger.c: -- Executing GotoIf("DGV/22", "0 > 0?2:4") in new stack
Jan 9 15:29:32 VERBOSE[5507] logger.c: -- Goto (macro-record-enable,s,4)
Jan 9 15:29:32 DEBUG[5507] app_macro.c: Executed application: GotoIf
Jan 9 15:29:32 VERBOSE[5507] logger.c: -- Executing AGI("DGV/22", "recordingcheck|20080109-152932|1199899772.166") in new stack
Jan 9 15:29:32 VERBOSE[5507] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
Jan 9 15:29:32 VERBOSE[5507] logger.c: recordingcheck|20080109-152932|1199899772.166: Inbound recording not enabled
Jan 9 15:29:33 VERBOSE[5507] logger.c: -- AGI Script recordingcheck completed, returning 0
Jan 9 15:29:33 DEBUG[5507] app_macro.c: Executed application: AGI
Jan 9 15:29:33 VERBOSE[5507] logger.c: -- Executing NoOp("DGV/22", "No recording needed") in new stack
Jan 9 15:29:33 DEBUG[5507] app_macro.c: Executed application: Noop
Jan 9 15:29:33 DEBUG[5507] app_macro.c: Executed application: Macro
Jan 9 15:29:33 DEBUG[5507] pbx.c: Expression result is '0'
Jan 9 15:29:33 VERBOSE[5507] logger.c: -- Executing GotoIf("DGV/22", "0?dolocaldial|1") in new stack
Jan 9 15:29:33 DEBUG[5507] pbx.c: Not taking any branch
Jan 9 15:29:33 DEBUG[5507] app_macro.c: Executed application: GotoIf
Jan 9 15:29:33 VERBOSE[5507] logger.c: -- Executing Macro("DGV/22", "dial|15|tTrm|2099") in new stack
Jan 9 15:29:33 VERBOSE[5507] logger.c: -- Executing AGI("DGV/22", "dialparties.agi") in new stack
Jan 9 15:29:33 VERBOSE[5507] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
Jan 9 15:29:33 VERBOSE[5507] logger.c: dialparties.agi: Starting New Dialparties.agi
Jan 9 15:29:33 VERBOSE[5507] logger.c: -- dialparties.agi: priority is 1
Jan 9 15:29:33 VERBOSE[5507] logger.c: dialparties.agi: Caller ID name is 'unknown' number is '39024238'
Jan 9 15:29:33 VERBOSE[5507] logger.c: dialparties.agi: Methodology of ring is 'none'
Jan 9 15:29:33 VERBOSE[5507] logger.c: -- dialparties.agi: Added extension 2099 to extension map
Jan 9 15:29:33 DEBUG[5507] db.c: Unable to find key '2099' in family 'CF'
Jan 9 15:29:33 VERBOSE[5507] logger.c: -- dialparties.agi: Extension 2099 cf is disabled
Jan 9 15:29:33 DEBUG[5507] db.c: Unable to find key '2099' in family 'DND'
Jan 9 15:29:33 VERBOSE[5507] logger.c: -- dialparties.agi: Extension 2099 do not disturb is disabled
Jan 9 15:29:33 DEBUG[5507] db.c: Unable to find key '2099' in family 'CW'
Jan 9 15:29:33 DEBUG[5507] db.c: Unable to find key '2099' in family 'CFB'
Jan 9 15:29:33 DEBUG[5507] db.c: Unable to find key '2099' in family 'CFU'
Jan 9 15:29:33 DEBUG[5510] manager.c: Manager received command 'login'
Jan 9 15:29:33 VERBOSE[5510] logger.c: == Parsing '/etc/asterisk/manager.conf': Jan 9 15:29:33 VERBOSE[5510] logger.c: == Parsing '/etc/asterisk/manager.conf': Found
Jan 9 15:29:33 VERBOSE[5510] logger.c: == Parsing '/etc/asterisk/manager_custom.conf': Jan 9 15:29:33 VERBOSE[5510] logger.c: == Parsing '/etc/asterisk/manager_custom.conf': Found
Jan 9 15:29:33 DEBUG[5510] acl.c: 0.0.0.0/0.0.0.0/0.0.0.0 appended to acl for peer
Jan 9 15:29:33 DEBUG[5510] acl.c: 127.0.0.1/255.255.255.0/255.255.255.0 appended to acl for peer
Jan 9 15:29:33 DEBUG[5510] acl.c: ##### Testing 127.0.0.1 with 0.0.0.0
Jan 9 15:29:33 DEBUG[5510] acl.c: ##### Testing 127.0.0.1 with 127.0.0.0
Jan 9 15:29:33 VERBOSE[5510] logger.c: == Manager 'admin' logged on from 127.0.0.1
Jan 9 15:29:33 DEBUG[5510] manager.c: Manager received command 'ExtensionState'
Jan 9 15:29:33 DEBUG[5510] manager.c: Manager received command 'Logoff'
Jan 9 15:29:33 VERBOSE[5510] logger.c: == Manager 'admin' logged off from 127.0.0.1
Jan 9 15:29:33 VERBOSE[5507] logger.c: -- dialparties.agi: Checking CW and CFB status for extension 2099
Jan 9 15:29:33 VERBOSE[5507] logger.c: -- dialparties.agi: DbSet CALLTRACE/2099 to 39024238
Jan 9 15:29:33 VERBOSE[5507] logger.c: -- AGI Script dialparties.agi completed, returning 0
Jan 9 15:29:33 DEBUG[5507] app_macro.c: Executed application: AGI
Jan 9 15:29:33 VERBOSE[5507] logger.c: -- Executing Dial("DGV/22", "SIP/2099|15|tTrm") in new stack
Jan 9 15:29:33 DEBUG[5507] chan_sip.c: Setting NAT on RTP to 0
Jan 9 15:29:33 DEBUG[5507] chan_sip.c: Outgoing Call for 2099
Jan 9 15:29:33 VERBOSE[5507] logger.c: -- Called 2099
Jan 9 15:29:33 DEBUG[5507] channel.c: Prodding channel 'DGV/22'
Jan 9 15:29:33 VERBOSE[5507] logger.c: -- Started music on hold, class 'default', on DGV/22
Jan 9 15:29:33 DEBUG[5507] channel.c: Scheduling timer at 160 sample intervals
Jan 9 15:29:34 DEBUG[3993] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '751cc20605dd20eb6883a975501e65fe@192.168.0.158' Request 102: Found
Jan 9 15:29:36 DEBUG[3993] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '751cc20605dd20eb6883a975501e65fe@192.168.0.158' Request 102: Found
Jan 9 15:29:36 DEBUG[3986] channel.c: Avoiding initial deadlock for 'SIP/2099-081d4658'
Jan 9 15:29:36 VERBOSE[5507] logger.c: -- SIP/2099-081d4658 is ringing
Jan 9 15:29:37 VERBOSE[4107] logger.c: -- Ringing on port 22 - callstate=1
Jan 9 15:29:39 DEBUG[3993] chan_sip.c: Acked pending invite 102
Jan 9 15:29:39 DEBUG[3993] chan_sip.c: Stopping retransmission on '751cc20605dd20eb6883a975501e65fe@192.168.0.158' of Request 102: Match Found
Jan 9 15:29:39 DEBUG[3993] chan_sip.c: build_route: Contact hop:
Jan 9 15:29:39 VERBOSE[5507] logger.c: -- SIP/2099-081d4658 answered DGV/22
Jan 9 15:29:39 VERBOSE[5507] logger.c: -- Stopped music on hold on DGV/22
Jan 9 15:29:39 DEBUG[5507] channel.c: Scheduling timer at 0 sample intervals
Jan 9 15:29:44 NOTICE[4279] chan_iax2.c: No registration for peer '2010' (from 192.168.0.109)
Jan 9 15:29:48 NOTICE[3993] chan_sip.c: Got SUBSCRIBE for extension 04239@from-internal from 192.168.0.188, but there is no hint for that extension
Jan 9 15:29:48 NOTICE[3993] chan_sip.c: Got SUBSCRIBE for extension 0032291000@from-internal from 192.168.0.188, but there is no hint for that extension
Jan 9 15:29:48 NOTICE[3993] chan_sip.c: Got SUBSCRIBE for extension 0004333713700@from-internal from 192.168.0.188, but there is no hint for that extension
Jan 9 15:29:53 NOTICE[4279] chan_iax2.c: No registration for peer '2006' (from 201.28.146.163)
Jan 9 15:30:06 DEBUG[5507] channel.c: Didn't get a frame from channel: SIP/2099-081d4658
Jan 9 15:30:06 DEBUG[5507] channel.c: Bridge stops bridging channels DGV/22 and SIP/2099-081d4658
Jan 9 15:30:06 DEBUG[5507] chan_sip.c: update_call_counter(2099) - decrement call limit counter
Jan 9 15:30:06 DEBUG[5507] app_dial.c: Exiting with DIALSTATUS=ANSWER.
Jan 9 15:30:06 VERBOSE[5507] logger.c: == Spawn extension (macro-dial, s, 10) exited non-zero on 'DGV/22' in macro 'dial'
Jan 9 15:30:06 VERBOSE[5507] logger.c: == Spawn extension (macro-dial, s, 10) exited non-zero on 'DGV/22' in macro 'exten-vm'
Jan 9 15:30:06 VERBOSE[5507] logger.c: == Spawn extension (macro-dial, s, 10) exited non-zero on 'DGV/22'
Jan 9 15:30:06 NOTICE[5507] chan_dgv.c: Calling dg_HangUp on port 22
Jan 9 15:30:06 DEBUG[5507] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record.
Jan 9 15:30:06 DEBUG[5507] 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 ('2008-01-09 15:29:32','39024238','39024238','2099','from-did-direct', 'DGV/22','SIP/2099-081d4658','Dial','SIP/2099|15|tTrm',34,27,'ANSWERED',3,'e1','1199899772.166')
Jan 9 15:30:06 DEBUG[5507] pbx.c: Function result is '39024238'
Jan 9 15:30:06 DEBUG[5507] pbx.c: Function result is '39024238'
Jan 9 15:30:06 DEBUG[5507] pbx.c: Function result is '2099'
Jan 9 15:30:06 DEBUG[5507] pbx.c: Function result is 'from-did-direct'
Jan 9 15:30:06 DEBUG[5507] pbx.c: Function result is 'DGV/22'
Jan 9 15:30:06 DEBUG[5507] pbx.c: Function result is 'SIP/2099-081d4658'
Jan 9 15:30:06 DEBUG[5507] pbx.c: Function result is 'Dial'
Jan 9 15:30:06 DEBUG[5507] pbx.c: Function result is 'SIP/2099|15|tTrm'
Jan 9 15:30:06 DEBUG[5507] pbx.c: Function result is '2008-01-09 15:29:32'
Jan 9 15:30:06 DEBUG[5507] pbx.c: Function result is '2008-01-09 15:29:39'
Jan 9 15:30:06 DEBUG[5507] pbx.c: Function result is '2008-01-09 15:30:06'
Jan 9 15:30:06 DEBUG[5507] pbx.c: Function result is '34'
Jan 9 15:30:06 DEBUG[5507] pbx.c: Function result is '27'
Jan 9 15:30:06 DEBUG[5507] pbx.c: Function result is 'ANSWERED'
Jan 9 15:30:06 DEBUG[5507] pbx.c: Function result is 'DOCUMENTATION'
Jan 9 15:30:06 DEBUG[5507] pbx.c: Function result is 'e1'
Jan 9 15:30:06 DEBUG[5507] pbx.c: Function result is '1199899772.166'
Jan 9 15:30:06 DEBUG[5507] pbx.c: Function result is '(null)'
Jan 9 15:30:36 VERBOSE[4110] logger.c: -- Ringing on port 23 - callstate=0
Jan 9 15:30:36 VERBOSE[4110] logger.c: -- Answering port 23
Jan 9 15:30:36 VERBOSE[5539] logger.c: == Starting DGV/23 at from-pstn,2111,1 failed so falling back to exten 's'
Jan 9 15:30:36 VERBOSE[5539] logger.c: -- Executing NoOp("DGV/23", "No DID or CID Match") in new stack
Jan 9 15:30:36 VERBOSE[5539] logger.c: -- Executing Answer("DGV/23", "") in new stack
Jan 9 15:30:36 VERBOSE[5539] logger.c: -- Executing Wait("DGV/23", "2") in new stack
Jan 9 15:30:38 VERBOSE[5539] logger.c: -- Executing Playback("DGV/23", "ss-noservice") in new stack
Jan 9 15:30:38 DEBUG[5539] channel.c: Scheduling timer at 160 sample intervals
Jan 9 15:30:38 VERBOSE[5539] logger.c: -- Playing 'ss-noservice' (language 'pt_BR')
Jan 9 15:30:38 DEBUG[5539] channel.c: Scheduling timer at 0 sample intervals
Jan 9 15:30:38 VERBOSE[5539] logger.c: == Spawn extension (from-pstn, s, 4) exited non-zero on 'DGV/23'
Jan 9 15:30:38 NOTICE[5539] chan_dgv.c: Calling dg_HangUp on port 23
Jan 9 15:30:38 DEBUG[5539] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record.
Jan 9 15:30:38 DEBUG[5539] 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 ('2008-01-09 15:30:36','39024217','39024217','2111','from-pstn', 'DGV/23','','Playback','ss-noservice',2,2,'ANSWERED',3,'e1','1199899836.168')
Jan 9 15:30:38 DEBUG[5539] pbx.c: Function result is '39024217'
Jan 9 15:30:38 DEBUG[5539] pbx.c: Function result is '39024217'
Jan 9 15:30:38 DEBUG[5539] pbx.c: Function result is '2111'
Jan 9 15:30:38 DEBUG[5539] pbx.c: Function result is 'from-pstn'
Jan 9 15:30:38 DEBUG[5539] pbx.c: Function result is 'DGV/23'
Jan 9 15:30:38 DEBUG[5539] pbx.c: Function result is '(null)'
Jan 9 15:30:38 DEBUG[5539] pbx.c: Function result is 'Playback'
Jan 9 15:30:38 DEBUG[5539] pbx.c: Function result is 'ss-noservice'
Jan 9 15:30:38 DEBUG[5539] pbx.c: Function result is '2008-01-09 15:30:36'
Jan 9 15:30:38 DEBUG[5539] pbx.c: Function result is '2008-01-09 15:30:36'
Jan 9 15:30:38 DEBUG[5539] pbx.c: Function result is '2008-01-09 15:30:38'
Jan 9 15:30:38 DEBUG[5539] pbx.c: Function result is '2'
Jan 9 15:30:38 DEBUG[5539] pbx.c: Function result is '2'
Jan 9 15:30:38 DEBUG[5539] pbx.c: Function result is 'ANSWERED'
Jan 9 15:30:38 DEBUG[5539] pbx.c: Function result is 'DOCUMENTATION'
Jan 9 15:30:38 DEBUG[5539] pbx.c: Function result is 'e1'
Jan 9 15:30:38 DEBUG[5539] pbx.c: Function result is '1199899836.168'
Jan 9 15:30:38 DEBUG[5539] pbx.c: Function result is '(null)'
Jan 9 15:30:38 DEBUG[5539] pbx.c: Function result is '(null)'