Feb 14 08:59:38 VERBOSE[1904] logger.c: -- Executing Macro("UniCall/57-1", "dialout-trunk|13|38454890|") in new stack Feb 14 08:59:38 DEBUG[1904] pbx.c: Expression result is '1' Feb 14 08:59:38 VERBOSE[1904] logger.c: -- Executing GotoIf("UniCall/57-1", "1?3:2)") in new stack Feb 14 08:59:38 VERBOSE[1904] logger.c: -- Goto (macro-dialout-trunk,s,3) Feb 14 08:59:38 VERBOSE[1904] logger.c: -- Executing Macro("UniCall/57-1", "user-callerid") in new stack Feb 14 08:59:38 VERBOSE[1904] logger.c: -- Executing DBget("UniCall/57-1", "AMPUSER=DEVICE/31564126/user") in new stack Feb 14 08:59:38 VERBOSE[1904] logger.c: -- DBget: varname=AMPUSER, family=DEVICE, key=31564126/user Feb 14 08:59:38 DEBUG[1904] db.c: Unable to find key '31564126/user' in family 'DEVICE' Feb 14 08:59:38 VERBOSE[1904] logger.c: -- DBget: Value not found in database. Feb 14 08:59:38 VERBOSE[1904] logger.c: -- Executing DBget("UniCall/57-1", "AMPUSERCIDNAME=AMPUSER//cidname") in new stack Feb 14 08:59:38 VERBOSE[1904] logger.c: -- DBget: varname=AMPUSERCIDNAME, family=AMPUSER, key=/cidname Feb 14 08:59:38 DEBUG[1904] db.c: Unable to find key '/cidname' in family 'AMPUSER' Feb 14 08:59:38 VERBOSE[1904] logger.c: -- DBget: Value not found in database. Feb 14 08:59:38 DEBUG[1904] pbx.c: Expression result is '1' Feb 14 08:59:38 VERBOSE[1904] logger.c: -- Executing GotoIf("UniCall/57-1", "1?5") in new stack Feb 14 08:59:38 VERBOSE[1904] logger.c: -- Goto (macro-user-callerid,s,5) Feb 14 08:59:38 VERBOSE[1904] logger.c: -- Executing NoOp("UniCall/57-1", "Using CallerID 31564126") in new stack Feb 14 08:59:38 VERBOSE[1904] logger.c: -- Executing Macro("UniCall/57-1", "record-enable|31564126|OUT") in new stack Feb 14 08:59:38 DEBUG[1904] pbx.c: Function result is '0' Feb 14 08:59:38 VERBOSE[1904] logger.c: -- Executing GotoIf("UniCall/57-1", "0 > 0?2:4") in new stack Feb 14 08:59:38 VERBOSE[1904] logger.c: -- Goto (macro-record-enable,s,4) Feb 14 08:59:38 VERBOSE[1904] logger.c: -- Executing Macro("UniCall/57-1", "outbound-callerid|13") in new stack Feb 14 08:59:38 VERBOSE[1904] logger.c: -- Executing DBget("UniCall/57-1", "USEROUTCID=AMPUSER/31564126/outboundcid") in new stack Feb 14 08:59:38 VERBOSE[1904] logger.c: -- DBget: varname=USEROUTCID, family=AMPUSER, key=31564126/outboundcid Feb 14 08:59:38 DEBUG[1904] db.c: Unable to find key '31564126/outboundcid' in family 'AMPUSER' Feb 14 08:59:38 VERBOSE[1904] logger.c: -- DBget: Value not found in database. Feb 14 08:59:38 DEBUG[1904] pbx.c: Expression result is '1' Feb 14 08:59:38 VERBOSE[1904] logger.c: -- Executing GotoIf("UniCall/57-1", "1?4") in new stack Feb 14 08:59:38 VERBOSE[1904] logger.c: -- Goto (macro-outbound-callerid,s,4) Feb 14 08:59:38 DEBUG[1904] pbx.c: Expression result is '1' Feb 14 08:59:38 VERBOSE[1904] logger.c: -- Executing GotoIf("UniCall/57-1", "1?6") in new stack Feb 14 08:59:38 VERBOSE[1904] logger.c: -- Goto (macro-outbound-callerid,s,6) Feb 14 08:59:38 VERBOSE[1904] logger.c: -- Executing NoOp("UniCall/57-1", "CallerID set to 31564126") in new stack Feb 14 08:59:38 VERBOSE[1904] logger.c: -- Executing SetGroup("UniCall/57-1", "OUT_13") in new stack Feb 14 08:59:38 VERBOSE[1904] logger.c: -- Executing CheckGroup("UniCall/57-1", "") in new stack Feb 14 08:59:38 WARNING[1904] app_groupcount.c: CheckGroup requires an argument(max[@category][|options]) Feb 14 08:59:38 VERBOSE[1904] logger.c: -- Executing SetVar("UniCall/57-1", "DIAL_NUMBER=38454890") in new stack Feb 14 08:59:38 VERBOSE[1904] logger.c: -- Executing SetVar("UniCall/57-1", "DIAL_TRUNK=13") in new stack Feb 14 08:59:38 VERBOSE[1904] logger.c: -- Executing AGI("UniCall/57-1", "fixlocalprefix") in new stack Feb 14 08:59:38 VERBOSE[1904] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/fixlocalprefix Feb 14 08:59:38 VERBOSE[1904] logger.c: -- AGI Script fixlocalprefix completed, returning 0 Feb 14 08:59:38 VERBOSE[1904] logger.c: -- Executing SetVar("UniCall/57-1", "OUTNUM=38454890") in new stack Feb 14 08:59:38 VERBOSE[1904] logger.c: -- Executing Cut("UniCall/57-1", "custom=OUT_13|:|1") in new stack Feb 14 08:59:38 DEBUG[1904] pbx.c: Expression result is '1' Feb 14 08:59:38 VERBOSE[1904] logger.c: -- Executing GotoIf("UniCall/57-1", "1?17") in new stack Feb 14 08:59:38 VERBOSE[1904] logger.c: -- Goto (macro-dialout-trunk,s,17) Feb 14 08:59:38 VERBOSE[1904] logger.c: -- Executing Cut("UniCall/57-1", "pre_num=OUT_13|$|1") in new stack Feb 14 08:59:38 VERBOSE[1904] logger.c: -- Executing Cut("UniCall/57-1", "the_num=OUT_13|$|2") in new stack Feb 14 08:59:38 VERBOSE[1904] logger.c: -- Executing Cut("UniCall/57-1", "post_num=OUT_13|$|3") in new stack Feb 14 08:59:38 DEBUG[1904] pbx.c: Expression result is '1' Feb 14 08:59:38 VERBOSE[1904] logger.c: -- Executing GotoIf("UniCall/57-1", "1?21:22") in new stack Feb 14 08:59:38 VERBOSE[1904] logger.c: -- Goto (macro-dialout-trunk,s,21) Feb 14 08:59:38 VERBOSE[1904] logger.c: -- Executing SetVar("UniCall/57-1", "the_num=38454890") in new stack Feb 14 08:59:38 VERBOSE[1904] logger.c: -- Executing Dial("UniCall/57-1", "UniCall/g2/38454890") in new stack Feb 14 08:59:38 DEBUG[1904] chan_unicall.c: unicall_call called - 'g2/38454890' Feb 14 08:59:38 DEBUG[1904] chan_unicall.c: unicall_call caller id - '31564126' Feb 14 08:59:38 WARNING[1904] chan_unicall.c: MFC/R2 UniCall/63 Call control(1) Feb 14 08:59:38 WARNING[1904] chan_unicall.c: MFC/R2 UniCall/63 Make call Feb 14 08:59:38 WARNING[1904] chan_unicall.c: MFC/R2 UniCall/63 Making a new call with CRN 33027 Feb 14 08:59:38 WARNING[1904] chan_unicall.c: MFC/R2 UniCall/63 0001 -> [1/ 1/Idle /Idle ] Feb 14 08:59:38 VERBOSE[1904] logger.c: -- Called g2/38454890 Feb 14 08:59:38 WARNING[1904] chan_unicall.c: Unicall/63 event Dialing Feb 14 08:59:38 DEBUG[1904] chan_unicall.c: Exception on 78, channel 63 Feb 14 08:59:38 WARNING[1904] chan_unicall.c: MFC/R2 UniCall/63 <- 1101 [1/ 40/Seize /Idle ] Feb 14 08:59:38 WARNING[1904] chan_unicall.c: MFC/R2 UniCall/63 3 on -> [2/ 40/Group I /Idle ] Feb 14 08:59:38 WARNING[1904] chan_unicall.c: MFC/R2 UniCall/63 <- 1 on [2/ 40/Group I /DNIS ] Feb 14 08:59:38 WARNING[1904] chan_unicall.c: MFC/R2 UniCall/63 3 off -> [2/ 40/Group I /DNIS ] Feb 14 08:59:39 WARNING[1904] chan_unicall.c: MFC/R2 UniCall/63 <- 1 off [2/ 40/Group I /DNIS ] Feb 14 08:59:39 WARNING[1904] chan_unicall.c: MFC/R2 UniCall/63 8 on -> [2/ 40/Group I /DNIS ] Feb 14 08:59:39 WARNING[1904] chan_unicall.c: MFC/R2 UniCall/63 <- 1 on [2/ 40/Group I /DNIS ] Feb 14 08:59:39 WARNING[1904] chan_unicall.c: MFC/R2 UniCall/63 8 off -> [2/ 40/Group I /DNIS ] Feb 14 08:59:39 WARNING[1904] chan_unicall.c: MFC/R2 UniCall/63 <- 1 off [2/ 40/Group I /DNIS ] Feb 14 08:59:39 WARNING[1904] chan_unicall.c: MFC/R2 UniCall/63 4 on -> [2/ 40/Group I /DNIS ] Feb 14 08:59:39 WARNING[1904] chan_unicall.c: MFC/R2 UniCall/63 <- 1 on [2/ 40/Group I /DNIS ] Feb 14 08:59:39 WARNING[1904] chan_unicall.c: MFC/R2 UniCall/63 4 off -> [2/ 40/Group I /DNIS ] Feb 14 08:59:39 WARNING[1904] chan_unicall.c: MFC/R2 UniCall/63 <- 1 off [2/ 40/Group I /DNIS ] Feb 14 08:59:39 WARNING[1904] chan_unicall.c: MFC/R2 UniCall/63 5 on -> [2/ 40/Group I /DNIS ] Feb 14 08:59:39 WARNING[1904] chan_unicall.c: MFC/R2 UniCall/63 <- 1 on [2/ 40/Group I /DNIS ] Feb 14 08:59:39 WARNING[1904] chan_unicall.c: MFC/R2 UniCall/63 5 off -> [2/ 40/Group I /DNIS ] Feb 14 08:59:39 WARNING[1904] chan_unicall.c: MFC/R2 UniCall/63 <- 1 off [2/ 40/Group I /DNIS ] Feb 14 08:59:39 WARNING[1904] chan_unicall.c: MFC/R2 UniCall/63 4 on -> [2/ 40/Group I /DNIS ] Feb 14 08:59:39 WARNING[1904] chan_unicall.c: MFC/R2 UniCall/63 <- 1 on [2/ 40/Group I /DNIS ] Feb 14 08:59:39 WARNING[1904] chan_unicall.c: MFC/R2 UniCall/63 4 off -> [2/ 40/Group I /DNIS ] Feb 14 08:59:39 WARNING[1904] chan_unicall.c: MFC/R2 UniCall/63 <- 1 off [2/ 40/Group I /DNIS ] Feb 14 08:59:39 WARNING[1904] chan_unicall.c: MFC/R2 UniCall/63 8 on -> [2/ 40/Group I /DNIS ] Feb 14 08:59:39 WARNING[1904] chan_unicall.c: MFC/R2 UniCall/63 <- 1 on [2/ 40/Group I /DNIS ] Feb 14 08:59:39 WARNING[1904] chan_unicall.c: MFC/R2 UniCall/63 8 off -> [2/ 40/Group I /DNIS ] Feb 14 08:59:39 WARNING[1904] chan_unicall.c: MFC/R2 UniCall/63 <- 1 off [2/ 40/Group I /DNIS ] Feb 14 08:59:39 WARNING[1904] chan_unicall.c: MFC/R2 UniCall/63 9 on -> [2/ 40/Group I /DNIS ] Feb 14 08:59:39 WARNING[1904] chan_unicall.c: MFC/R2 UniCall/63 <- 1 on [2/ 40/Group I /DNIS ] Feb 14 08:59:39 WARNING[1904] chan_unicall.c: MFC/R2 UniCall/63 9 off -> [2/ 40/Group I /DNIS ] Feb 14 08:59:39 WARNING[1904] chan_unicall.c: MFC/R2 UniCall/63 <- 1 off [2/ 40/Group I /DNIS ] Feb 14 08:59:39 WARNING[1904] chan_unicall.c: MFC/R2 UniCall/63 0 on -> [2/ 40/Group I /DNIS ] Feb 14 08:59:39 WARNING[1904] chan_unicall.c: MFC/R2 UniCall/63 <- 3 on [2/ 40/Group I /DNIS ] Feb 14 08:59:39 WARNING[1904] chan_unicall.c: MFC/R2 UniCall/63 0 off -> [2/ 40/Group I /DNIS ] Feb 14 08:59:40 WARNING[1904] chan_unicall.c: MFC/R2 UniCall/63 <- 3 off [2/ 40/Group I /DNIS ] Feb 14 08:59:40 WARNING[1904] chan_unicall.c: MFC/R2 UniCall/63 1 on -> [2/ 40/Group I /DNIS ] Feb 14 08:59:40 WARNING[1904] chan_unicall.c: MFC/R2 UniCall/63 <- 1 on [2/ 40/Group II /Category ] Feb 14 08:59:40 WARNING[1904] chan_unicall.c: MFC/R2 UniCall/63 1 off -> [2/ 40/Group II /Category ] Feb 14 08:59:40 WARNING[1904] chan_unicall.c: MFC/R2 UniCall/63 <- 1 off [2/ 40/Group II /Category ] Feb 14 08:59:40 WARNING[1904] chan_unicall.c: Unicall/63 event Alerting Feb 14 08:59:44 DEBUG[1904] chan_unicall.c: Exception on 78, channel 63 Feb 14 08:59:44 WARNING[1904] chan_unicall.c: MFC/R2 UniCall/63 <- 0101 [1/ 200/Await answer /Category ] Feb 14 08:59:44 WARNING[1904] chan_unicall.c: Unicall/63 event Connected Feb 14 08:59:44 WARNING[1904] chan_unicall.c: MFC/R2 UniCall/63 Channel echo cancel Feb 14 08:59:44 DEBUG[1904] chan_unicall.c: Enabled echo cancellation on channel 63 Feb 14 08:59:44 DEBUG[1904] chan_unicall.c: needanswer Feb 14 08:59:44 VERBOSE[1904] logger.c: -- UniCall/63-1 answered UniCall/57-1 Feb 14 08:59:44 WARNING[1904] chan_unicall.c: Answer Call Feb 14 08:59:44 WARNING[1904] chan_unicall.c: MFC/R2 UniCall/57 Call control(5) Feb 14 08:59:44 WARNING[1904] chan_unicall.c: MFC/R2 UniCall/57 Answer call Feb 14 08:59:44 WARNING[1904] chan_unicall.c: MFC/R2 UniCall/57 0101 -> [1/ 20/Group B /Accepted Paid] Feb 14 08:59:44 VERBOSE[1904] logger.c: -- Attempting native bridge of UniCall/57-1 and UniCall/63-1 Feb 14 08:59:44 WARNING[1904] chan_unicall.c: unicall_bridge called Feb 14 08:59:44 DEBUG[1904] chan_unicall.c: Stoping tones on 57/0 talking to 63/0 Feb 14 08:59:44 DEBUG[1904] chan_unicall.c: Stoping tones on 63/0 talking to 57/0 Feb 14 08:59:44 DEBUG[1904] chan_unicall.c: Making 63 slave to master 57 Feb 14 08:59:44 DEBUG[1904] chan_unicall.c: Added 78 to conference 9/57 Feb 14 08:59:44 DEBUG[1904] chan_unicall.c: Added 72 to conference 9/63 Feb 14 08:59:44 DEBUG[1904] chan_unicall.c: Updated conferencing on 57, with 0 conference users Feb 14 08:59:44 DEBUG[1904] chan_unicall.c: Updated conferencing on 63, with 0 conference users Feb 14 08:59:44 WARNING[1904] chan_unicall.c: Unicall/57 event Answered Feb 14 08:59:44 WARNING[1904] chan_unicall.c: MFC/R2 UniCall/57 Channel echo cancel Feb 14 08:59:44 DEBUG[1904] chan_unicall.c: Enabled echo cancellation on channel 57 Feb 14 08:59:45 DEBUG[1904] chan_unicall.c: Exception on 78, channel 63 Feb 14 08:59:45 WARNING[1904] chan_unicall.c: MFC/R2 UniCall/63 <- 1101 [1/ 400/Answered /Category ] Feb 14 08:59:45 WARNING[1904] chan_unicall.c: MFC/R2 UniCall/63 Far end disconnected(cause=Normal Clearing [16]) - state 0x400 Feb 14 08:59:45 WARNING[1904] chan_unicall.c: Unicall/63 event Far end disconnected Feb 14 08:59:45 WARNING[1904] chan_unicall.c: CRN 33027 - far disconnected cause=Normal Clearing [16] Feb 14 08:59:45 VERBOSE[1904] logger.c: -- Channel 0 got hangup Feb 14 08:59:45 DEBUG[1904] chan_unicall.c: Unlinking slave 63 from 57 Feb 14 08:59:45 DEBUG[1904] chan_unicall.c: Removed 78 from conference 9/57 Feb 14 08:59:45 DEBUG[1904] chan_unicall.c: Removed 72 from conference 9/63 Feb 14 08:59:45 DEBUG[1904] chan_unicall.c: Updated conferencing on 57, with 0 conference users Feb 14 08:59:45 DEBUG[1904] channel.c: Returning from native bridge, channels: UniCall/57-1, UniCall/63-1 Feb 14 08:59:45 WARNING[1904] chan_unicall.c: MFC/R2 UniCall/63 Channel gains Feb 14 08:59:45 WARNING[1904] chan_unicall.c: MFC/R2 UniCall/63 Channel switching Feb 14 08:59:45 DEBUG[1904] chan_unicall.c: Hangup: channel: 63 index = 0, normal = 78, callwait = -1, thirdcall = -1 Feb 14 08:59:45 WARNING[1904] chan_unicall.c: MFC/R2 UniCall/63 Call control(6) Feb 14 08:59:45 WARNING[1904] chan_unicall.c: MFC/R2 UniCall/63 Drop call(cause=Normal Clearing [16]) Feb 14 08:59:45 WARNING[1904] chan_unicall.c: MFC/R2 UniCall/63 Clearing fwd Feb 14 08:59:45 WARNING[1904] chan_unicall.c: MFC/R2 UniCall/63 1001 -> [1/ 800/Clear back /Category ] Feb 14 08:59:45 DEBUG[1904] chan_unicall.c: Updated conferencing on 63, with 0 conference users Feb 14 08:59:45 VERBOSE[1904] logger.c: -- Hungup 'UniCall/63-1' Feb 14 08:59:45 DEBUG[1904] app_dial.c: Exiting with DIALSTATUS=ANSWER. Feb 14 08:59:45 VERBOSE[1904] logger.c: == Spawn extension (macro-dialout-trunk, s, 22) exited non-zero on 'UniCall/57-1' in macro 'dialout-trunk' Feb 14 08:59:45 VERBOSE[1904] logger.c: == Spawn extension (from-internal, 38454890, 1) exited non-zero on 'UniCall/57-1' Feb 14 08:59:45 VERBOSE[1904] logger.c: -- Executing Macro("UniCall/57-1", "hangupcall") in new stack Feb 14 08:59:45 VERBOSE[1904] logger.c: -- Executing ResetCDR("UniCall/57-1", "w") in new stack Feb 14 08:59:45 DEBUG[1904] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record. Feb 14 08:59:45 DEBUG[1904] 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,userfield) VALUES ('2006-02-14 08:59:38','31564126','31564126','38454890','from-internal', 'UniCall/57-1','UniCall/63-1','ResetCDR','w',7,1,'ANSWERED',3,'','1139925578.10411','') Feb 14 08:59:45 VERBOSE[1904] logger.c: -- Executing NoCDR("UniCall/57-1", "") in new stack Feb 14 08:59:45 WARNING[1904] cdr.c: CDR on channel 'UniCall/57-1' not posted Feb 14 08:59:45 WARNING[1904] cdr.c: CDR on channel 'UniCall/57-1' lacks end Feb 14 08:59:45 VERBOSE[1904] logger.c: -- Executing Wait("UniCall/57-1", "5") in new stack Feb 14 08:59:49 DEBUG[1904] chan_unicall.c: Exception on 72, channel 57 Feb 14 08:59:49 WARNING[1904] chan_unicall.c: MFC/R2 UniCall/57 <- 1001 [1/ 400/Answer /Accepted Paid] Feb 14 08:59:49 WARNING[1904] chan_unicall.c: MFC/R2 UniCall/57 Far end disconnected(cause=Normal Clearing [16]) - state 0x400 Feb 14 08:59:49 WARNING[1904] chan_unicall.c: Unicall/57 event Far end disconnected Feb 14 08:59:49 WARNING[1904] chan_unicall.c: CRN 33165 - far disconnected cause=Normal Clearing [16] Feb 14 08:59:49 VERBOSE[1904] logger.c: -- Channel 0 got hangup Feb 14 08:59:49 VERBOSE[1904] logger.c: == Spawn extension (macro-hangupcall, s, 3) exited non-zero on 'UniCall/57-1' in macro 'hangupcall' Feb 14 08:59:49 VERBOSE[1904] logger.c: == Spawn extension (from-internal, h, 1) exited non-zero on 'UniCall/57-1' Feb 14 08:59:49 WARNING[1904] chan_unicall.c: MFC/R2 UniCall/57 Channel gains Feb 14 08:59:49 WARNING[1904] chan_unicall.c: MFC/R2 UniCall/57 Channel switching Feb 14 08:59:49 DEBUG[1904] chan_unicall.c: Hangup: channel: 57 index = 0, normal = 72, callwait = -1, thirdcall = -1 Feb 14 08:59:49 WARNING[1904] chan_unicall.c: MFC/R2 UniCall/57 Call control(6) Feb 14 08:59:49 WARNING[1904] chan_unicall.c: MFC/R2 UniCall/57 Drop call(cause=Normal Clearing [16]) Feb 14 08:59:49 WARNING[1904] chan_unicall.c: MFC/R2 UniCall/57 Call disconnected(cause=Normal Clearing [16]) - state 0x800 Feb 14 08:59:49 DEBUG[1904] chan_unicall.c: Updated conferencing on 57, with 0 conference users Feb 14 08:59:49 VERBOSE[1904] logger.c: -- Hungup 'UniCall/57-1'