Someone

[Nov 11 19:32:33] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 216
[Nov 11 19:32:33] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 212
[Nov 11 19:32:33] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 208
[Nov 11 19:32:33] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 213
[Nov 11 19:32:33] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 223
[Nov 11 19:32:33] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 210
[Nov 11 19:32:33] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 230
[Nov 11 19:32:33] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 227
[Nov 11 19:32:33] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 215
[Nov 11 19:32:33] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 221
[Nov 11 19:32:38] DEBUG[32416] chan_zap.c: Set option AUDIO MODE, value: ON(1) on Zap/5-1
[Nov 11 19:32:38] DEBUG[32416] chan_zap.c: Not yet hungup... Calling hangup once with icause, and clearing call
[Nov 11 19:32:38] DEBUG[32416] chan_zap.c: Set option AUDIO MODE, value: OFF(0) on Zap/5-1
[Nov 11 19:32:38] VERBOSE[32416] logger.c: -- Hungup 'Zap/5-1'
[Nov 11 19:32:38] NOTICE[32416] cdr.c: CDR on channel 'Zap/5-1' not posted
[Nov 11 19:32:38] VERBOSE[32416] logger.c: == Spawn extension (macro-dialout-trunk, s, 23) exited non-zero on 'SIP/216-b7dc64d0' in macro 'dialout-trunk'
[Nov 11 19:32:38] VERBOSE[32416] logger.c: == Spawn extension (macro-dialout-trunk, s, 23) exited non-zero on 'SIP/216-b7dc64d0'
[Nov 11 19:32:38] VERBOSE[23428] logger.c: Extension Changed 216 new state Idle for Notify User 207
[Nov 11 19:32:38] VERBOSE[32416] logger.c: -- Executing [h@macro-dialout-trunk:1] Macro("SIP/216-b7dc64d0", "hangupcall") in new stack
[Nov 11 19:32:38] VERBOSE[32416] logger.c: -- Executing [s@macro-hangupcall:1] ResetCDR("SIP/216-b7dc64d0", "w") in new stack
[Nov 11 19:32:38] VERBOSE[23428] logger.c: Extension Changed 216 new state Idle for Notify User 224
[Nov 11 19:32:38] DEBUG[32416] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record.
[Nov 11 19:32:38] DEBUG[32416] 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 ('2007-11-11 19:32:30','\"Ext.216\" <216>','216','6518562','from-internal', 'SIP/216-b7dc64d0','Zap/5-1','Dial','ZAP/g0/6518562|300|Tt',8,0,'NO ANSWER',3,'','1194802350.1443')
[Nov 11 19:32:38] VERBOSE[23428] logger.c: Extension Changed 216 new state Idle for Notify User 214
[Nov 11 19:32:38] DEBUG[32416] app_macro.c: Executed application: ResetCDR
[Nov 11 19:32:38] VERBOSE[32416] logger.c: -- Executing [s@macro-hangupcall:2] NoCDR("SIP/216-b7dc64d0", "") in new stack
[Nov 11 19:32:38] VERBOSE[23428] logger.c: Extension Changed 216 new state Idle for Notify User 218
[Nov 11 19:32:38] DEBUG[32416] app_macro.c: Executed application: NoCDR
[Nov 11 19:32:38] VERBOSE[23428] logger.c: Extension Changed 216 new state Idle for Notify User 203
[Nov 11 19:32:38] VERBOSE[32416] logger.c: -- Executing [s@macro-hangupcall:3] GotoIf("SIP/216-b7dc64d0", "1?skiprg") in new stack
[Nov 11 19:32:38] VERBOSE[32416] logger.c: -- Goto (macro-hangupcall,s,6)
[Nov 11 19:32:38] DEBUG[32416] app_macro.c: Executed application: GotoIf
[Nov 11 19:32:38] VERBOSE[23428] logger.c: Extension Changed 216 new state Idle for Notify User 206
[Nov 11 19:32:38] VERBOSE[32416] logger.c: -- Executing [s@macro-hangupcall:6] GotoIf("SIP/216-b7dc64d0", "1?skipblkvm") in new stack
[Nov 11 19:32:38] VERBOSE[32416] logger.c: -- Goto (macro-hangupcall,s,9)
[Nov 11 19:32:38] DEBUG[32416] app_macro.c: Executed application: GotoIf
[Nov 11 19:32:38] VERBOSE[23428] logger.c: Extension Changed 216 new state Idle for Notify User 229
[Nov 11 19:32:38] VERBOSE[32416] logger.c: -- Executing [s@macro-hangupcall:9] GotoIf("SIP/216-b7dc64d0", "1?theend") in new stack
[Nov 11 19:32:38] VERBOSE[32416] logger.c: -- Goto (macro-hangupcall,s,11)
[Nov 11 19:32:38] DEBUG[32416] app_macro.c: Executed application: GotoIf
[Nov 11 19:32:38] VERBOSE[23428] logger.c: Extension Changed 216 new state Idle for Notify User 204
[Nov 11 19:32:38] VERBOSE[32416] logger.c: -- Executing [s@macro-hangupcall:11] Hangup("SIP/216-b7dc64d0", "") in new stack
[Nov 11 19:32:38] VERBOSE[32416] logger.c: == Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'SIP/216-b7dc64d0' in macro 'hangupcall'
[Nov 11 19:32:38] VERBOSE[32416] logger.c: == Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'SIP/216-b7dc64d0'
[Nov 11 19:32:38] VERBOSE[32419] logger.c: == End MixMonitor Recording SIP/216-b7dc64d0
[Nov 11 19:32:38] VERBOSE[23428] logger.c: Extension Changed 216 new state Idle for Notify User 225
[Nov 11 19:32:38] VERBOSE[23428] logger.c: Extension Changed 216 new state Idle for Notify User 228
[Nov 11 19:32:38] VERBOSE[23428] logger.c: Extension Changed 216 new state Idle for Notify User 226
[Nov 11 19:32:38] VERBOSE[23428] logger.c: Extension Changed 216 new state Idle for Notify User 201
[Nov 11 19:32:38] VERBOSE[23428] logger.c: Extension Changed 216 new state Idle for Notify User 220
[Nov 11 19:32:38] VERBOSE[23428] logger.c: Extension Changed 216 new state Idle for Notify User 219
[Nov 11 19:32:38] VERBOSE[23428] logger.c: Extension Changed 216 new state Idle for Notify User 211
[Nov 11 19:32:38] VERBOSE[23428] logger.c: Extension Changed 216 new state Idle for Notify User 216
[Nov 11 19:32:38] VERBOSE[23428] logger.c: Extension Changed 216 new state Idle for Notify User 212
[Nov 11 19:32:38] VERBOSE[23428] logger.c: Extension Changed 216 new state Idle for Notify User 208
[Nov 11 19:32:38] VERBOSE[23428] logger.c: Extension Changed 216 new state Idle for Notify User 213
[Nov 11 19:32:38] VERBOSE[23428] logger.c: Extension Changed 216 new state Idle for Notify User 223
[Nov 11 19:32:38] VERBOSE[23428] logger.c: Extension Changed 216 new state Idle for Notify User 210
[Nov 11 19:32:38] VERBOSE[23428] logger.c: Extension Changed 216 new state Idle for Notify User 230
[Nov 11 19:32:38] VERBOSE[23428] logger.c: Extension Changed 216 new state Idle for Notify User 227
[Nov 11 19:32:38] VERBOSE[23428] logger.c: Extension Changed 216 new state Idle for Notify User 215
[Nov 11 19:32:38] VERBOSE[23428] logger.c: Extension Changed 216 new state Idle for Notify User 221
[Nov 11 19:32:42] DEBUG[23454] chan_zap.c: Queuing frame from PRI_EVENT_PROGRESS on channel 0/2 span 1
[Nov 11 19:32:42] VERBOSE[32398] logger.c: -- Zap/2-1 is making progress passing it to SIP/212-b7d96730
[Nov 11 19:32:44] DEBUG[32398] chan_zap.c: Set option AUDIO MODE, value: ON(1) on Zap/2-1
[Nov 11 19:32:44] DEBUG[32398] chan_zap.c: Not yet hungup... Calling hangup once with icause, and clearing call
[Nov 11 19:32:44] DEBUG[32398] chan_zap.c: Set option AUDIO MODE, value: OFF(0) on Zap/2-1
[Nov 11 19:32:44] VERBOSE[32398] logger.c: -- Hungup 'Zap/2-1'
[Nov 11 19:32:44] NOTICE[32398] cdr.c: CDR on channel 'Zap/2-1' not posted
[Nov 11 19:32:44] VERBOSE[32398] logger.c: == Spawn extension (macro-dialout-trunk, s, 23) exited non-zero on 'SIP/212-b7d96730' in macro 'dialout-trunk'
[Nov 11 19:32:44] VERBOSE[23428] logger.c: Extension Changed 212 new state Idle for Notify User 207
[Nov 11 19:32:44] VERBOSE[32398] logger.c: == Spawn extension (macro-dialout-trunk, s, 23) exited non-zero on 'SIP/212-b7d96730'
[Nov 11 19:32:44] VERBOSE[32398] logger.c: -- Executing [h@macro-dialout-trunk:1] Macro("SIP/212-b7d96730", "hangupcall") in new stack
[Nov 11 19:32:44] VERBOSE[23428] logger.c: Extension Changed 212 new state Idle for Notify User 224
[Nov 11 19:32:44] VERBOSE[32398] logger.c: -- Executing [s@macro-hangupcall:1] ResetCDR("SIP/212-b7d96730", "w") in new stack
[Nov 11 19:32:44] VERBOSE[23428] logger.c: Extension Changed 212 new state Idle for Notify User 214
[Nov 11 19:32:44] DEBUG[32398] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record.
[Nov 11 19:32:44] DEBUG[32398] 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 ('2007-11-11 19:32:16','\"Ext.212\" <212>','212','036162234','from-internal', 'SIP/212-b7d96730','Zap/2-1','Dial','ZAP/g0/036162234|300|Tt',28,0,'NO ANSWER',3,'','1194802336.1439')
[Nov 11 19:32:44] VERBOSE[23428] logger.c: Extension Changed 212 new state Idle for Notify User 218
[Nov 11 19:32:44] DEBUG[32398] app_macro.c: Executed application: ResetCDR
[Nov 11 19:32:44] VERBOSE[23428] logger.c: Extension Changed 212 new state Idle for Notify User 203
[Nov 11 19:32:44] VERBOSE[32398] logger.c: -- Executing [s@macro-hangupcall:2] NoCDR("SIP/212-b7d96730", "") in new stack
[Nov 11 19:32:44] DEBUG[32398] app_macro.c: Executed application: NoCDR
[Nov 11 19:32:44] VERBOSE[23428] logger.c: Extension Changed 212 new state Idle for Notify User 206
[Nov 11 19:32:44] VERBOSE[32398] logger.c: -- Executing [s@macro-hangupcall:3] GotoIf("SIP/212-b7d96730", "1?skiprg") in new stack
[Nov 11 19:32:44] VERBOSE[32398] logger.c: -- Goto (macro-hangupcall,s,6)
[Nov 11 19:32:44] DEBUG[32398] app_macro.c: Executed application: GotoIf
[Nov 11 19:32:44] VERBOSE[23428] logger.c: Extension Changed 212 new state Idle for Notify User 229
[Nov 11 19:32:44] VERBOSE[32398] logger.c: -- Executing [s@macro-hangupcall:6] GotoIf("SIP/212-b7d96730", "1?skipblkvm") in new stack
[Nov 11 19:32:44] VERBOSE[32398] logger.c: -- Goto (macro-hangupcall,s,9)
[Nov 11 19:32:44] DEBUG[32398] app_macro.c: Executed application: GotoIf
[Nov 11 19:32:44] VERBOSE[23428] logger.c: Extension Changed 212 new state Idle for Notify User 204
[Nov 11 19:32:44] VERBOSE[32398] logger.c: -- Executing [s@macro-hangupcall:9] GotoIf("SIP/212-b7d96730", "1?theend") in new stack
[Nov 11 19:32:44] VERBOSE[32398] logger.c: -- Goto (macro-hangupcall,s,11)
[Nov 11 19:32:44] DEBUG[32398] app_macro.c: Executed application: GotoIf
[Nov 11 19:32:44] VERBOSE[23428] logger.c: Extension Changed 212 new state Idle for Notify User 225
[Nov 11 19:32:44] VERBOSE[32398] logger.c: -- Executing [s@macro-hangupcall:11] Hangup("SIP/212-b7d96730", "") in new stack
[Nov 11 19:32:44] VERBOSE[32398] logger.c: == Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'SIP/212-b7d96730' in macro 'hangupcall'
[Nov 11 19:32:44] VERBOSE[32398] logger.c: == Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'SIP/212-b7d96730'
[Nov 11 19:32:44] VERBOSE[32401] logger.c: == End MixMonitor Recording SIP/212-b7d96730
[Nov 11 19:32:44] VERBOSE[23428] logger.c: Extension Changed 212 new state Idle for Notify User 228
[Nov 11 19:32:44] VERBOSE[23428] logger.c: Extension Changed 212 new state Idle for Notify User 226
[Nov 11 19:32:44] VERBOSE[23428] logger.c: Extension Changed 212 new state Idle for Notify User 201
[Nov 11 19:32:44] VERBOSE[23428] logger.c: Extension Changed 212 new state Idle for Notify User 220
[Nov 11 19:32:44] VERBOSE[23428] logger.c: Extension Changed 212 new state Idle for Notify User 219
[Nov 11 19:32:44] VERBOSE[23428] logger.c: Extension Changed 212 new state Idle for Notify User 211
[Nov 11 19:32:44] VERBOSE[23428] logger.c: Extension Changed 212 new state Idle for Notify User 216
[Nov 11 19:32:44] VERBOSE[23428] logger.c: Extension Changed 212 new state Idle for Notify User 212
[Nov 11 19:32:44] VERBOSE[23428] logger.c: Extension Changed 212 new state Idle for Notify User 208
[Nov 11 19:32:44] VERBOSE[23428] logger.c: Extension Changed 212 new state Idle for Notify User 213
[Nov 11 19:32:44] VERBOSE[23428] logger.c: Extension Changed 212 new state Idle for Notify User 223
[Nov 11 19:32:44] VERBOSE[23428] logger.c: Extension Changed 212 new state Idle for Notify User 210
[Nov 11 19:32:44] VERBOSE[23428] logger.c: Extension Changed 212 new state Idle for Notify User 230
[Nov 11 19:32:44] VERBOSE[23428] logger.c: Extension Changed 212 new state Idle for Notify User 227
[Nov 11 19:32:44] VERBOSE[23428] logger.c: Extension Changed 212 new state Idle for Notify User 215
[Nov 11 19:32:44] VERBOSE[23428] logger.c: Extension Changed 212 new state Idle for Notify User 221
[Nov 11 19:32:46] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 207
[Nov 11 19:32:46] VERBOSE[32433] logger.c: -- Executing [035707732@from-internal:1] Macro("SIP/213-b7de1d70", "dialout-trunk|1|035707732||") in new stack
[Nov 11 19:32:46] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 224
[Nov 11 19:32:46] VERBOSE[32433] logger.c: -- Executing [s@macro-dialout-trunk:1] Set("SIP/213-b7de1d70", "DIAL_TRUNK=1") in new stack
[Nov 11 19:32:46] DEBUG[32433] app_macro.c: Executed application: Set
[Nov 11 19:32:46] VERBOSE[32433] logger.c: -- Executing [s@macro-dialout-trunk:2] Set("SIP/213-b7de1d70", "DIAL_NUMBER=035707732") in new stack
[Nov 11 19:32:46] DEBUG[32433] app_macro.c: Executed application: Set
[Nov 11 19:32:46] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 214
[Nov 11 19:32:46] VERBOSE[32433] logger.c: -- Executing [s@macro-dialout-trunk:3] Set("SIP/213-b7de1d70", "ROUTE_PASSWD=") in new stack
[Nov 11 19:32:46] DEBUG[32433] app_macro.c: Executed application: Set
[Nov 11 19:32:46] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 218
[Nov 11 19:32:46] VERBOSE[32433] logger.c: -- Executing [s@macro-dialout-trunk:4] GotoIf("SIP/213-b7de1d70", "1?noauth") in new stack
[Nov 11 19:32:46] VERBOSE[32433] logger.c: -- Goto (macro-dialout-trunk,s,6)
[Nov 11 19:32:46] DEBUG[32433] app_macro.c: Executed application: GotoIf
[Nov 11 19:32:46] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 203
[Nov 11 19:32:46] VERBOSE[32433] logger.c: -- Executing [s@macro-dialout-trunk:6] GotoIf("SIP/213-b7de1d70", "0?disabletrunk|1") in new stack
[Nov 11 19:32:46] DEBUG[32433] app_macro.c: Executed application: GotoIf
[Nov 11 19:32:46] VERBOSE[32433] logger.c: -- Executing [s@macro-dialout-trunk:7] Set("SIP/213-b7de1d70", "_NODEST=") in new stack
[Nov 11 19:32:46] DEBUG[32433] app_macro.c: Executed application: Set
[Nov 11 19:32:46] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 206
[Nov 11 19:32:46] VERBOSE[32433] logger.c: -- Executing [s@macro-dialout-trunk:8] Set("SIP/213-b7de1d70", "DIAL_TRUNK_OPTIONS=Tt") in new stack
[Nov 11 19:32:46] DEBUG[32433] app_macro.c: Executed application: Set
[Nov 11 19:32:46] VERBOSE[32433] logger.c: -- Executing [s@macro-dialout-trunk:9] Set("SIP/213-b7de1d70", "GROUP()=OUT_1") in new stack
[Nov 11 19:32:46] DEBUG[32433] app_macro.c: Executed application: Set
[Nov 11 19:32:46] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 229
[Nov 11 19:32:46] VERBOSE[32433] logger.c: -- Executing [s@macro-dialout-trunk:10] Macro("SIP/213-b7de1d70", "user-callerid|SKIPTTL") in new stack
[Nov 11 19:32:46] VERBOSE[32433] logger.c: -- Executing [s@macro-user-callerid:1] NoOp("SIP/213-b7de1d70", "user-callerid: device 213") in new stack
[Nov 11 19:32:46] DEBUG[32433] app_macro.c: Executed application: Noop
[Nov 11 19:32:46] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 204
[Nov 11 19:32:46] VERBOSE[32433] logger.c: -- Executing [s@macro-user-callerid:2] Set("SIP/213-b7de1d70", "AMPUSER=213") in new stack
[Nov 11 19:32:46] DEBUG[32433] app_macro.c: Executed application: Set
[Nov 11 19:32:46] VERBOSE[32433] logger.c: -- Executing [s@macro-user-callerid:3] GotoIf("SIP/213-b7de1d70", "0?report") in new stack
[Nov 11 19:32:46] DEBUG[32433] app_macro.c: Executed application: GotoIf
[Nov 11 19:32:46] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 225
[Nov 11 19:32:46] VERBOSE[32433] logger.c: -- Executing [s@macro-user-callerid:4] GotoIf("SIP/213-b7de1d70", "0?start") in new stack
[Nov 11 19:32:46] DEBUG[32433] app_macro.c: Executed application: GotoIf
[Nov 11 19:32:46] VERBOSE[32433] logger.c: -- Executing [s@macro-user-callerid:5] Set("SIP/213-b7de1d70", "REALCALLERIDNUM=213") in new stack
[Nov 11 19:32:46] DEBUG[32433] app_macro.c: Executed application: Set
[Nov 11 19:32:46] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 228
[Nov 11 19:32:46] VERBOSE[32433] logger.c: -- Executing [s@macro-user-callerid:6] NoOp("SIP/213-b7de1d70", "REALCALLERIDNUM is 213") in new stack
[Nov 11 19:32:46] DEBUG[32433] app_macro.c: Executed application: NoOp
[Nov 11 19:32:46] VERBOSE[32433] logger.c: -- Executing [s@macro-user-callerid:7] Set("SIP/213-b7de1d70", "AMPUSER=213") in new stack
[Nov 11 19:32:46] DEBUG[32433] app_macro.c: Executed application: Set
[Nov 11 19:32:46] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 226
[Nov 11 19:32:46] VERBOSE[32433] logger.c: -- Executing [s@macro-user-callerid:8] Set("SIP/213-b7de1d70", "AMPUSERCIDNAME=Ext.213") in new stack
[Nov 11 19:32:46] DEBUG[32433] app_macro.c: Executed application: Set
[Nov 11 19:32:46] VERBOSE[32433] logger.c: -- Executing [s@macro-user-callerid:9] GotoIf("SIP/213-b7de1d70", "0?report") in new stack
[Nov 11 19:32:46] DEBUG[32433] app_macro.c: Executed application: GotoIf
[Nov 11 19:32:46] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 201
[Nov 11 19:32:46] DEBUG[32433] db.c: Unable to find key '213/cidnum' in family 'AMPUSER'
[Nov 11 19:32:46] VERBOSE[32433] logger.c: -- Executing [s@macro-user-callerid:10] Set("SIP/213-b7de1d70", "AMPUSERCID=213") in new stack
[Nov 11 19:32:46] DEBUG[32433] app_macro.c: Executed application: Set
[Nov 11 19:32:46] VERBOSE[32433] logger.c: -- Executing [s@macro-user-callerid:11] Set("SIP/213-b7de1d70", "CALLERID(all)="Ext.213" <213>") in new stack
[Nov 11 19:32:46] DEBUG[32433] app_macro.c: Executed application: Set
[Nov 11 19:32:46] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 220
[Nov 11 19:32:46] VERBOSE[32433] logger.c: -- Executing [s@macro-user-callerid:12] Set("SIP/213-b7de1d70", "REALCALLERIDNUM=213") in new stack
[Nov 11 19:32:46] DEBUG[32433] app_macro.c: Executed application: Set
[Nov 11 19:32:46] VERBOSE[32433] logger.c: -- Executing [s@macro-user-callerid:13] NoOp("SIP/213-b7de1d70", "TTL: ARG1: SKIPTTL") in new stack
[Nov 11 19:32:46] DEBUG[32433] app_macro.c: Executed application: Noop
[Nov 11 19:32:46] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 219
[Nov 11 19:32:46] VERBOSE[32433] logger.c: -- Executing [s@macro-user-callerid:14] GotoIf("SIP/213-b7de1d70", "1?continue") in new stack
[Nov 11 19:32:46] VERBOSE[32433] logger.c: -- Goto (macro-user-callerid,s,23)
[Nov 11 19:32:46] DEBUG[32433] app_macro.c: Executed application: GotoIf
[Nov 11 19:32:46] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 211
[Nov 11 19:32:46] VERBOSE[32433] logger.c: -- Executing [s@macro-user-callerid:23] NoOp("SIP/213-b7de1d70", "Using CallerID "Ext.213" <213>") in new stack
[Nov 11 19:32:46] DEBUG[32433] app_macro.c: Executed application: NoOp
[Nov 11 19:32:46] DEBUG[32433] app_macro.c: Executed application: Macro
[Nov 11 19:32:46] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 216
[Nov 11 19:32:46] VERBOSE[32433] logger.c: -- Executing [s@macro-dialout-trunk:11] Macro("SIP/213-b7de1d70", "record-enable|213|OUT") in new stack
[Nov 11 19:32:46] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 212
[Nov 11 19:32:46] VERBOSE[32433] logger.c: -- Executing [s@macro-record-enable:1] GotoIf("SIP/213-b7de1d70", "0?2:4") in new stack
[Nov 11 19:32:46] VERBOSE[32433] logger.c: -- Goto (macro-record-enable,s,4)
[Nov 11 19:32:46] DEBUG[32433] app_macro.c: Executed application: GotoIf
[Nov 11 19:32:46] VERBOSE[32433] logger.c: -- Executing [s@macro-record-enable:4] AGI("SIP/213-b7de1d70", "recordingcheck|20071111-193246|1194802366.1445") in new stack
[Nov 11 19:32:46] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 208
[Nov 11 19:32:46] VERBOSE[32433] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
[Nov 11 19:32:46] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 213
[Nov 11 19:32:46] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 223
[Nov 11 19:32:46] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 210
[Nov 11 19:32:46] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 230
[Nov 11 19:32:46] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 227
[Nov 11 19:32:46] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 215
[Nov 11 19:32:46] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 221
[Nov 11 19:32:46] VERBOSE[32433] logger.c: recordingcheck|20071111-193246|1194802366.1445: Outbound recording enabled.
[Nov 11 19:32:46] VERBOSE[32433] logger.c: recordingcheck|20071111-193246|1194802366.1445: CALLFILENAME=OUT213-20071111-193246-1194802366.1445
[Nov 11 19:32:46] VERBOSE[32433] logger.c: -- AGI Script recordingcheck completed, returning 0
[Nov 11 19:32:46] DEBUG[32433] app_macro.c: Executed application: AGI
[Nov 11 19:32:46] VERBOSE[32433] logger.c: -- Executing [s@macro-record-enable:999] MixMonitor("SIP/213-b7de1d70", "OUT213-20071111-193246-1194802366.1445.WAV") in new stack
[Nov 11 19:32:46] DEBUG[32433] app_macro.c: Executed application: MixMonitor
[Nov 11 19:32:46] DEBUG[32433] app_macro.c: Executed application: Macro
[Nov 11 19:32:46] VERBOSE[32433] logger.c: -- Executing [s@macro-dialout-trunk:12] GotoIf("SIP/213-b7de1d70", "0?skipoutcid") in new stack
[Nov 11 19:32:46] DEBUG[32433] app_macro.c: Executed application: GotoIf
[Nov 11 19:32:46] VERBOSE[32436] logger.c: == Begin MixMonitor Recording SIP/213-b7de1d70
[Nov 11 19:32:46] VERBOSE[32433] logger.c: -- Executing [s@macro-dialout-trunk:13] Set("SIP/213-b7de1d70", "DIAL_TRUNK_OPTIONS=Tt") in new stack
[Nov 11 19:32:46] DEBUG[32433] app_macro.c: Executed application: Set
[Nov 11 19:32:46] VERBOSE[32433] logger.c: -- Executing [s@macro-dialout-trunk:14] Macro("SIP/213-b7de1d70", "outbound-callerid|1") in new stack
[Nov 11 19:32:46] VERBOSE[32433] logger.c: -- Executing [s@macro-outbound-callerid:1] GotoIf("SIP/213-b7de1d70", "1?start") in new stack
[Nov 11 19:32:46] VERBOSE[32433] logger.c: -- Goto (macro-outbound-callerid,s,3)
[Nov 11 19:32:46] DEBUG[32433] app_macro.c: Executed application: GotoIf
[Nov 11 19:32:46] VERBOSE[32433] logger.c: -- Executing [s@macro-outbound-callerid:3] NoOp("SIP/213-b7de1d70", "REALCALLERIDNUM is 213") in new stack
[Nov 11 19:32:46] DEBUG[32433] app_macro.c: Executed application: NoOp
[Nov 11 19:32:46] VERBOSE[32433] logger.c: -- Executing [s@macro-outbound-callerid:4] GotoIf("SIP/213-b7de1d70", "1?normcid") in new stack
[Nov 11 19:32:46] VERBOSE[32433] logger.c: -- Goto (macro-outbound-callerid,s,9)
[Nov 11 19:32:46] DEBUG[32433] app_macro.c: Executed application: GotoIf
[Nov 11 19:32:46] VERBOSE[32433] logger.c: -- Executing [s@macro-outbound-callerid:9] Set("SIP/213-b7de1d70", "USEROUTCID=") in new stack
[Nov 11 19:32:46] DEBUG[32433] app_macro.c: Executed application: Set
[Nov 11 19:32:46] DEBUG[32433] db.c: Unable to find key '213/emergency_cid' in family 'DEVICE'
[Nov 11 19:32:46] DEBUG[32433] func_db.c: DB: DEVICE/213/emergency_cid not found in database.
[Nov 11 19:32:46] VERBOSE[32433] logger.c: -- Executing [s@macro-outbound-callerid:10] Set("SIP/213-b7de1d70", "EMERGENCYCID=") in new stack
[Nov 11 19:32:46] DEBUG[32433] app_macro.c: Executed application: Set
[Nov 11 19:32:46] VERBOSE[32433] logger.c: -- Executing [s@macro-outbound-callerid:11] Set("SIP/213-b7de1d70", "TRUNKOUTCID=") in new stack
[Nov 11 19:32:46] DEBUG[32433] app_macro.c: Executed application: Set
[Nov 11 19:32:46] VERBOSE[32433] logger.c: -- Executing [s@macro-outbound-callerid:12] GotoIf("SIP/213-b7de1d70", "1?trunkcid") in new stack
[Nov 11 19:32:46] VERBOSE[32433] logger.c: -- Goto (macro-outbound-callerid,s,16)
[Nov 11 19:32:46] DEBUG[32433] app_macro.c: Executed application: GotoIf
[Nov 11 19:32:46] VERBOSE[32433] logger.c: -- Executing [s@macro-outbound-callerid:16] GotoIf("SIP/213-b7de1d70", "1?usercid") in new stack
[Nov 11 19:32:46] VERBOSE[32433] logger.c: -- Goto (macro-outbound-callerid,s,18)
[Nov 11 19:32:46] DEBUG[32433] app_macro.c: Executed application: GotoIf
[Nov 11 19:32:46] VERBOSE[32433] logger.c: -- Executing [s@macro-outbound-callerid:18] GotoIf("SIP/213-b7de1d70", "1?report") in new stack
[Nov 11 19:32:46] VERBOSE[32433] logger.c: -- Goto (macro-outbound-callerid,s,22)
[Nov 11 19:32:46] DEBUG[32433] app_macro.c: Executed application: GotoIf
[Nov 11 19:32:46] VERBOSE[32433] logger.c: -- Executing [s@macro-outbound-callerid:22] NoOp("SIP/213-b7de1d70", "CallerID set to "Ext.213" <213>") in new stack
[Nov 11 19:32:46] DEBUG[32433] app_macro.c: Executed application: NoOp
[Nov 11 19:32:46] DEBUG[32433] app_macro.c: Executed application: Macro
[Nov 11 19:32:46] VERBOSE[32433] logger.c: -- Executing [s@macro-dialout-trunk:15] GotoIf("SIP/213-b7de1d70", "1?nomax") in new stack
[Nov 11 19:32:46] VERBOSE[32433] logger.c: -- Goto (macro-dialout-trunk,s,17)
[Nov 11 19:32:46] DEBUG[32433] app_macro.c: Executed application: GotoIf
[Nov 11 19:32:46] VERBOSE[32433] logger.c: -- Executing [s@macro-dialout-trunk:17] AGI("SIP/213-b7de1d70", "fixlocalprefix") in new stack
[Nov 11 19:32:46] VERBOSE[32433] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/fixlocalprefix
[Nov 11 19:32:46] VERBOSE[32433] logger.c: > fixlocalprefix: Using pattern .
[Nov 11 19:32:46] VERBOSE[32433] logger.c: == fixlocalprefix: Dialpattern . matched. 035707732 -> 035707732
[Nov 11 19:32:46] VERBOSE[32433] logger.c: -- AGI Script fixlocalprefix completed, returning 0
[Nov 11 19:32:46] DEBUG[32433] app_macro.c: Executed application: AGI
[Nov 11 19:32:46] VERBOSE[32433] logger.c: -- Executing [s@macro-dialout-trunk:18] Set("SIP/213-b7de1d70", "OUTNUM=035707732") in new stack
[Nov 11 19:32:46] DEBUG[32433] app_macro.c: Executed application: Set
[Nov 11 19:32:46] VERBOSE[32433] logger.c: -- Executing [s@macro-dialout-trunk:19] Set("SIP/213-b7de1d70", "custom=ZAP/g0") in new stack
[Nov 11 19:32:46] DEBUG[32433] app_macro.c: Executed application: Set
[Nov 11 19:32:46] VERBOSE[32433] logger.c: -- Executing [s@macro-dialout-trunk:20] GotoIf("SIP/213-b7de1d70", "1?gocall") in new stack
[Nov 11 19:32:46] VERBOSE[32433] logger.c: -- Goto (macro-dialout-trunk,s,22)
[Nov 11 19:32:46] DEBUG[32433] app_macro.c: Executed application: GotoIf
[Nov 11 19:32:46] VERBOSE[32433] logger.c: -- Executing [s@macro-dialout-trunk:22] GotoIf("SIP/213-b7de1d70", "0?customtrunk") in new stack
[Nov 11 19:32:46] DEBUG[32433] app_macro.c: Executed application: GotoIf
[Nov 11 19:32:46] VERBOSE[32433] logger.c: -- Executing [s@macro-dialout-trunk:23] Dial("SIP/213-b7de1d70", "ZAP/g0/035707732|300|Tt") in new stack
[Nov 11 19:32:46] VERBOSE[32433] logger.c: -- Requested transfer capability: 0x00 - SPEECH
[Nov 11 19:32:46] VERBOSE[32433] logger.c: -- Called g0/035707732
[Nov 11 19:32:46] DEBUG[23454] chan_zap.c: Queuing frame from PRI_EVENT_PROCEEDING on channel 0/2 span 1
[Nov 11 19:32:46] VERBOSE[32433] logger.c: -- Zap/2-1 is proceeding passing it to SIP/213-b7de1d70
[Nov 11 19:32:47] VERBOSE[32433] logger.c: -- Zap/2-1 is ringing
[Nov 11 19:32:55] VERBOSE[23428] logger.c: Extension Changed 216 new state InUse for Notify User 207
[Nov 11 19:32:55] VERBOSE[32442] logger.c: -- Executing [9991492@from-internal:1] Macro("SIP/216-b7d96730", "dialout-trunk|1|9991492||") in new stack
[Nov 11 19:32:55] VERBOSE[23428] logger.c: Extension Changed 216 new state InUse for Notify User 224
[Nov 11 19:32:55] VERBOSE[32442] logger.c: -- Executing [s@macro-dialout-trunk:1] Set("SIP/216-b7d96730", "DIAL_TRUNK=1") in new stack
[Nov 11 19:32:55] DEBUG[32442] app_macro.c: Executed application: Set
[Nov 11 19:32:55] VERBOSE[32442] logger.c: -- Executing [s@macro-dialout-trunk:2] Set("SIP/216-b7d96730", "DIAL_NUMBER=9991492") in new stack
[Nov 11 19:32:55] VERBOSE[23428] logger.c: Extension Changed 216 new state InUse for Notify User 214
[Nov 11 19:32:55] DEBUG[32442] app_macro.c: Executed application: Set
[Nov 11 19:32:55] VERBOSE[32442] logger.c: -- Executing [s@macro-dialout-trunk:3] Set("SIP/216-b7d96730", "ROUTE_PASSWD=") in new stack
[Nov 11 19:32:55] DEBUG[32442] app_macro.c: Executed application: Set
[Nov 11 19:32:55] VERBOSE[23428] logger.c: Extension Changed 216 new state InUse for Notify User 218
[Nov 11 19:32:55] VERBOSE[32442] logger.c: -- Executing [s@macro-dialout-trunk:4] GotoIf("SIP/216-b7d96730", "1?noauth") in new stack
[Nov 11 19:32:55] VERBOSE[32442] logger.c: -- Goto (macro-dialout-trunk,s,6)
[Nov 11 19:32:55] DEBUG[32442] app_macro.c: Executed application: GotoIf
[Nov 11 19:32:55] VERBOSE[23428] logger.c: Extension Changed 216 new state InUse for Notify User 203
[Nov 11 19:32:55] VERBOSE[32442] logger.c: -- Executing [s@macro-dialout-trunk:6] GotoIf("SIP/216-b7d96730", "0?disabletrunk|1") in new stack
[Nov 11 19:32:55] DEBUG[32442] app_macro.c: Executed application: GotoIf
[Nov 11 19:32:55] VERBOSE[32442] logger.c: -- Executing [s@macro-dialout-trunk:7] Set("SIP/216-b7d96730", "_NODEST=") in new stack
[Nov 11 19:32:55] DEBUG[32442] app_macro.c: Executed application: Set
[Nov 11 19:32:55] VERBOSE[23428] logger.c: Extension Changed 216 new state InUse for Notify User 206
[Nov 11 19:32:55] VERBOSE[32442] logger.c: -- Executing [s@macro-dialout-trunk:8] Set("SIP/216-b7d96730", "DIAL_TRUNK_OPTIONS=Tt") in new stack
[Nov 11 19:32:55] DEBUG[32442] app_macro.c: Executed application: Set
[Nov 11 19:32:55] VERBOSE[32442] logger.c: -- Executing [s@macro-dialout-trunk:9] Set("SIP/216-b7d96730", "GROUP()=OUT_1") in new stack
[Nov 11 19:32:55] DEBUG[32442] app_macro.c: Executed application: Set
[Nov 11 19:32:55] VERBOSE[23428] logger.c: Extension Changed 216 new state InUse for Notify User 229
[Nov 11 19:32:55] VERBOSE[32442] logger.c: -- Executing [s@macro-dialout-trunk:10] Macro("SIP/216-b7d96730", "user-callerid|SKIPTTL") in new stack
[Nov 11 19:32:55] VERBOSE[32442] logger.c: -- Executing [s@macro-user-callerid:1] NoOp("SIP/216-b7d96730", "user-callerid: device 216") in new stack
[Nov 11 19:32:55] DEBUG[32442] app_macro.c: Executed application: Noop
[Nov 11 19:32:55] VERBOSE[23428] logger.c: Extension Changed 216 new state InUse for Notify User 204
[Nov 11 19:32:55] VERBOSE[32442] logger.c: -- Executing [s@macro-user-callerid:2] Set("SIP/216-b7d96730", "AMPUSER=216") in new stack
[Nov 11 19:32:55] DEBUG[32442] app_macro.c: Executed application: Set
[Nov 11 19:32:55] VERBOSE[32442] logger.c: -- Executing [s@macro-user-callerid:3] GotoIf("SIP/216-b7d96730", "0?report") in new stack
[Nov 11 19:32:55] DEBUG[32442] app_macro.c: Executed application: GotoIf
[Nov 11 19:32:55] VERBOSE[23428] logger.c: Extension Changed 216 new state InUse for Notify User 225
[Nov 11 19:32:55] VERBOSE[32442] logger.c: -- Executing [s@macro-user-callerid:4] GotoIf("SIP/216-b7d96730", "0?start") in new stack
[Nov 11 19:32:55] DEBUG[32442] app_macro.c: Executed application: GotoIf
[Nov 11 19:32:55] VERBOSE[32442] logger.c: -- Executing [s@macro-user-callerid:5] Set("SIP/216-b7d96730", "REALCALLERIDNUM=216") in new stack
[Nov 11 19:32:55] DEBUG[32442] app_macro.c: Executed application: Set
[Nov 11 19:32:55] VERBOSE[23428] logger.c: Extension Changed 216 new state InUse for Notify User 228
[Nov 11 19:32:55] VERBOSE[32442] logger.c: -- Executing [s@macro-user-callerid:6] NoOp("SIP/216-b7d96730", "REALCALLERIDNUM is 216") in new stack
[Nov 11 19:32:55] DEBUG[32442] app_macro.c: Executed application: NoOp
[Nov 11 19:32:55] VERBOSE[32442] logger.c: -- Executing [s@macro-user-callerid:7] Set("SIP/216-b7d96730", "AMPUSER=216") in new stack
[Nov 11 19:32:55] DEBUG[32442] app_macro.c: Executed application: Set
[Nov 11 19:32:55] VERBOSE[23428] logger.c: Extension Changed 216 new state InUse for Notify User 226
[Nov 11 19:32:55] VERBOSE[32442] logger.c: -- Executing [s@macro-user-callerid:8] Set("SIP/216-b7d96730", "AMPUSERCIDNAME=Ext.216") in new stack
[Nov 11 19:32:55] DEBUG[32442] app_macro.c: Executed application: Set
[Nov 11 19:32:55] VERBOSE[32442] logger.c: -- Executing [s@macro-user-callerid:9] GotoIf("SIP/216-b7d96730", "0?report") in new stack
[Nov 11 19:32:55] DEBUG[32442] app_macro.c: Executed application: GotoIf
[Nov 11 19:32:55] VERBOSE[23428] logger.c: Extension Changed 216 new state InUse for Notify User 201
[Nov 11 19:32:55] DEBUG[32442] db.c: Unable to find key '216/cidnum' in family 'AMPUSER'
[Nov 11 19:32:55] VERBOSE[32442] logger.c: -- Executing [s@macro-user-callerid:10] Set("SIP/216-b7d96730", "AMPUSERCID=216") in new stack
[Nov 11 19:32:55] DEBUG[32442] app_macro.c: Executed application: Set
[Nov 11 19:32:55] VERBOSE[23428] logger.c: Extension Changed 216 new state InUse for Notify User 220
[Nov 11 19:32:55] VERBOSE[32442] logger.c: -- Executing [s@macro-user-callerid:11] Set("SIP/216-b7d96730", "CALLERID(all)="Ext.216" <216>") in new stack
[Nov 11 19:32:55] DEBUG[32442] app_macro.c: Executed application: Set
[Nov 11 19:32:55] VERBOSE[32442] logger.c: -- Executing [s@macro-user-callerid:12] Set("SIP/216-b7d96730", "REALCALLERIDNUM=216") in new stack
[Nov 11 19:32:55] DEBUG[32442] app_macro.c: Executed application: Set
[Nov 11 19:32:55] VERBOSE[23428] logger.c: Extension Changed 216 new state InUse for Notify User 219
[Nov 11 19:32:55] VERBOSE[32442] logger.c: -- Executing [s@macro-user-callerid:13] NoOp("SIP/216-b7d96730", "TTL: ARG1: SKIPTTL") in new stack
[Nov 11 19:32:55] DEBUG[32442] app_macro.c: Executed application: Noop
[Nov 11 19:32:55] VERBOSE[32442] logger.c: -- Executing [s@macro-user-callerid:14] GotoIf("SIP/216-b7d96730", "1?continue") in new stack
[Nov 11 19:32:55] VERBOSE[23428] logger.c: Extension Changed 216 new state InUse for Notify User 211
[Nov 11 19:32:55] VERBOSE[32442] logger.c: -- Goto (macro-user-callerid,s,23)
[Nov 11 19:32:55] DEBUG[32442] app_macro.c: Executed application: GotoIf
[Nov 11 19:32:55] VERBOSE[32442] logger.c: -- Executing [s@macro-user-callerid:23] NoOp("SIP/216-b7d96730", "Using CallerID "Ext.216" <216>") in new stack
[Nov 11 19:32:55] DEBUG[32442] app_macro.c: Executed application: NoOp
[Nov 11 19:32:55] VERBOSE[23428] logger.c: Extension Changed 216 new state InUse for Notify User 216
[Nov 11 19:32:55] DEBUG[32442] app_macro.c: Executed application: Macro
[Nov 11 19:32:55] VERBOSE[32442] logger.c: -- Executing [s@macro-dialout-trunk:11] Macro("SIP/216-b7d96730", "record-enable|216|OUT") in new stack
[Nov 11 19:32:55] VERBOSE[23428] logger.c: Extension Changed 216 new state InUse for Notify User 212
[Nov 11 19:32:55] VERBOSE[32442] logger.c: -- Executing [s@macro-record-enable:1] GotoIf("SIP/216-b7d96730", "0?2:4") in new stack
[Nov 11 19:32:55] VERBOSE[32442] logger.c: -- Goto (macro-record-enable,s,4)
[Nov 11 19:32:55] DEBUG[32442] app_macro.c: Executed application: GotoIf
[Nov 11 19:32:55] VERBOSE[32442] logger.c: -- Executing [s@macro-record-enable:4] AGI("SIP/216-b7d96730", "recordingcheck|20071111-193255|1194802375.1447") in new stack
[Nov 11 19:32:55] VERBOSE[23428] logger.c: Extension Changed 216 new state InUse for Notify User 208
[Nov 11 19:32:55] VERBOSE[23428] logger.c: Extension Changed 216 new state InUse for Notify User 213
[Nov 11 19:32:55] VERBOSE[23428] logger.c: Extension Changed 216 new state InUse for Notify User 223
[Nov 11 19:32:55] VERBOSE[23428] logger.c: Extension Changed 216 new state InUse for Notify User 210
[Nov 11 19:32:55] VERBOSE[23428] logger.c: Extension Changed 216 new state InUse for Notify User 230
[Nov 11 19:32:55] VERBOSE[23428] logger.c: Extension Changed 216 new state InUse for Notify User 227
[Nov 11 19:32:55] VERBOSE[23428] logger.c: Extension Changed 216 new state InUse for Notify User 215
[Nov 11 19:32:55] VERBOSE[23428] logger.c: Extension Changed 216 new state InUse for Notify User 221
[Nov 11 19:32:55] VERBOSE[32442] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
[Nov 11 19:32:55] VERBOSE[32442] logger.c: recordingcheck|20071111-193255|1194802375.1447: Outbound recording enabled.
[Nov 11 19:32:55] VERBOSE[32442] logger.c: recordingcheck|20071111-193255|1194802375.1447: CALLFILENAME=OUT216-20071111-193255-1194802375.1447
[Nov 11 19:32:55] VERBOSE[32442] logger.c: -- AGI Script recordingcheck completed, returning 0
[Nov 11 19:32:55] DEBUG[32442] app_macro.c: Executed application: AGI
[Nov 11 19:32:55] VERBOSE[32442] logger.c: -- Executing [s@macro-record-enable:999] MixMonitor("SIP/216-b7d96730", "OUT216-20071111-193255-1194802375.1447.WAV") in new stack
[Nov 11 19:32:55] DEBUG[32442] app_macro.c: Executed application: MixMonitor
[Nov 11 19:32:55] DEBUG[32442] app_macro.c: Executed application: Macro
[Nov 11 19:32:55] VERBOSE[32442] logger.c: -- Executing [s@macro-dialout-trunk:12] GotoIf("SIP/216-b7d96730", "0?skipoutcid") in new stack
[Nov 11 19:32:55] DEBUG[32442] app_macro.c: Executed application: GotoIf
[Nov 11 19:32:55] VERBOSE[32442] logger.c: -- Executing [s@macro-dialout-trunk:13] Set("SIP/216-b7d96730", "DIAL_TRUNK_OPTIONS=Tt") in new stack
[Nov 11 19:32:55] DEBUG[32442] app_macro.c: Executed application: Set
[Nov 11 19:32:55] VERBOSE[32442] logger.c: -- Executing [s@macro-dialout-trunk:14] Macro("SIP/216-b7d96730", "outbound-callerid|1") in new stack
[Nov 11 19:32:55] VERBOSE[32442] logger.c: -- Executing [s@macro-outbound-callerid:1] GotoIf("SIP/216-b7d96730", "1?start") in new stack
[Nov 11 19:32:55] VERBOSE[32442] logger.c: -- Goto (macro-outbound-callerid,s,3)
[Nov 11 19:32:55] DEBUG[32442] app_macro.c: Executed application: GotoIf
[Nov 11 19:32:55] VERBOSE[32442] logger.c: -- Executing [s@macro-outbound-callerid:3] NoOp("SIP/216-b7d96730", "REALCALLERIDNUM is 216") in new stack
[Nov 11 19:32:55] DEBUG[32442] app_macro.c: Executed application: NoOp
[Nov 11 19:32:55] VERBOSE[32442] logger.c: -- Executing [s@macro-outbound-callerid:4] GotoIf("SIP/216-b7d96730", "1?normcid") in new stack
[Nov 11 19:32:55] VERBOSE[32442] logger.c: -- Goto (macro-outbound-callerid,s,9)
[Nov 11 19:32:55] DEBUG[32442] app_macro.c: Executed application: GotoIf
[Nov 11 19:32:55] VERBOSE[32442] logger.c: -- Executing [s@macro-outbound-callerid:9] Set("SIP/216-b7d96730", "USEROUTCID=") in new stack
[Nov 11 19:32:55] DEBUG[32442] app_macro.c: Executed application: Set
[Nov 11 19:32:55] DEBUG[32442] db.c: Unable to find key '216/emergency_cid' in family 'DEVICE'
[Nov 11 19:32:55] DEBUG[32442] func_db.c: DB: DEVICE/216/emergency_cid not found in database.
[Nov 11 19:32:55] VERBOSE[32442] logger.c: -- Executing [s@macro-outbound-callerid:10] Set("SIP/216-b7d96730", "EMERGENCYCID=") in new stack
[Nov 11 19:32:55] DEBUG[32442] app_macro.c: Executed application: Set
[Nov 11 19:32:55] VERBOSE[32442] logger.c: -- Executing [s@macro-outbound-callerid:11] Set("SIP/216-b7d96730", "TRUNKOUTCID=") in new stack
[Nov 11 19:32:55] DEBUG[32442] app_macro.c: Executed application: Set
[Nov 11 19:32:55] VERBOSE[32442] logger.c: -- Executing [s@macro-outbound-callerid:12] GotoIf("SIP/216-b7d96730", "1?trunkcid") in new stack
[Nov 11 19:32:55] VERBOSE[32442] logger.c: -- Goto (macro-outbound-callerid,s,16)
[Nov 11 19:32:55] DEBUG[32442] app_macro.c: Executed application: GotoIf
[Nov 11 19:32:55] VERBOSE[32442] logger.c: -- Executing [s@macro-outbound-callerid:16] GotoIf("SIP/216-b7d96730", "1?usercid") in new stack
[Nov 11 19:32:55] VERBOSE[32442] logger.c: -- Goto (macro-outbound-callerid,s,18)
[Nov 11 19:32:55] DEBUG[32442] app_macro.c: Executed application: GotoIf
[Nov 11 19:32:55] VERBOSE[32442] logger.c: -- Executing [s@macro-outbound-callerid:18] GotoIf("SIP/216-b7d96730", "1?report") in new stack
[Nov 11 19:32:55] VERBOSE[32442] logger.c: -- Goto (macro-outbound-callerid,s,22)
[Nov 11 19:32:55] DEBUG[32442] app_macro.c: Executed application: GotoIf
[Nov 11 19:32:55] VERBOSE[32442] logger.c: -- Executing [s@macro-outbound-callerid:22] NoOp("SIP/216-b7d96730", "CallerID set to "Ext.216" <216>") in new stack
[Nov 11 19:32:55] DEBUG[32442] app_macro.c: Executed application: NoOp
[Nov 11 19:32:55] DEBUG[32442] app_macro.c: Executed application: Macro
[Nov 11 19:32:55] VERBOSE[32442] logger.c: -- Executing [s@macro-dialout-trunk:15] GotoIf("SIP/216-b7d96730", "1?nomax") in new stack
[Nov 11 19:32:55] VERBOSE[32442] logger.c: -- Goto (macro-dialout-trunk,s,17)
[Nov 11 19:32:55] DEBUG[32442] app_macro.c: Executed application: GotoIf
[Nov 11 19:32:55] VERBOSE[32442] logger.c: -- Executing [s@macro-dialout-trunk:17] AGI("SIP/216-b7d96730", "fixlocalprefix") in new stack
[Nov 11 19:32:55] VERBOSE[32442] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/fixlocalprefix
[Nov 11 19:32:55] VERBOSE[32445] logger.c: == Begin MixMonitor Recording SIP/216-b7d96730
[Nov 11 19:32:55] VERBOSE[32442] logger.c: > fixlocalprefix: Using pattern .
[Nov 11 19:32:55] VERBOSE[32442] logger.c: == fixlocalprefix: Dialpattern . matched. 9991492 -> 9991492
[Nov 11 19:32:55] VERBOSE[32442] logger.c: -- AGI Script fixlocalprefix completed, returning 0
[Nov 11 19:32:55] DEBUG[32442] app_macro.c: Executed application: AGI
[Nov 11 19:32:55] VERBOSE[32442] logger.c: -- Executing [s@macro-dialout-trunk:18] Set("SIP/216-b7d96730", "OUTNUM=9991492") in new stack
[Nov 11 19:32:55] DEBUG[32442] app_macro.c: Executed application: Set
[Nov 11 19:32:55] VERBOSE[32442] logger.c: -- Executing [s@macro-dialout-trunk:19] Set("SIP/216-b7d96730", "custom=ZAP/g0") in new stack
[Nov 11 19:32:55] DEBUG[32442] app_macro.c: Executed application: Set
[Nov 11 19:32:55] VERBOSE[32442] logger.c: -- Executing [s@macro-dialout-trunk:20] GotoIf("SIP/216-b7d96730", "1?gocall") in new stack
[Nov 11 19:32:55] VERBOSE[32442] logger.c: -- Goto (macro-dialout-trunk,s,22)
[Nov 11 19:32:55] DEBUG[32442] app_macro.c: Executed application: GotoIf
[Nov 11 19:32:55] VERBOSE[32442] logger.c: -- Executing [s@macro-dialout-trunk:22] GotoIf("SIP/216-b7d96730", "0?customtrunk") in new stack
[Nov 11 19:32:55] DEBUG[32442] app_macro.c: Executed application: GotoIf
[Nov 11 19:32:55] VERBOSE[32442] logger.c: -- Executing [s@macro-dialout-trunk:23] Dial("SIP/216-b7d96730", "ZAP/g0/9991492|300|Tt") in new stack
[Nov 11 19:32:55] VERBOSE[32442] logger.c: -- Requested transfer capability: 0x00 - SPEECH
[Nov 11 19:32:55] VERBOSE[32442] logger.c: -- Called g0/9991492
[Nov 11 19:32:55] DEBUG[23454] chan_zap.c: Queuing frame from PRI_EVENT_PROCEEDING on channel 0/3 span 1
[Nov 11 19:32:55] VERBOSE[32442] logger.c: -- Zap/3-1 is proceeding passing it to SIP/216-b7d96730
[Nov 11 19:32:56] VERBOSE[23428] logger.c: Extension Changed 212 new state InUse for Notify User 207
[Nov 11 19:32:56] VERBOSE[32449] logger.c: -- Executing [036161415@from-internal:1] Macro("SIP/212-b7dc64d0", "dialout-trunk|1|036161415||") in new stack
[Nov 11 19:32:56] VERBOSE[23428] logger.c: Extension Changed 212 new state InUse for Notify User 224
[Nov 11 19:32:56] VERBOSE[32449] logger.c: -- Executing [s@macro-dialout-trunk:1] Set("SIP/212-b7dc64d0", "DIAL_TRUNK=1") in new stack
[Nov 11 19:32:56] DEBUG[32449] app_macro.c: Executed application: Set
[Nov 11 19:32:56] VERBOSE[32449] logger.c: -- Executing [s@macro-dialout-trunk:2] Set("SIP/212-b7dc64d0", "DIAL_NUMBER=036161415") in new stack
[Nov 11 19:32:56] VERBOSE[23428] logger.c: Extension Changed 212 new state InUse for Notify User 214
[Nov 11 19:32:56] DEBUG[32449] app_macro.c: Executed application: Set
[Nov 11 19:32:56] VERBOSE[32449] logger.c: -- Executing [s@macro-dialout-trunk:3] Set("SIP/212-b7dc64d0", "ROUTE_PASSWD=") in new stack
[Nov 11 19:32:56] VERBOSE[23428] logger.c: Extension Changed 212 new state InUse for Notify User 218
[Nov 11 19:32:56] DEBUG[32449] app_macro.c: Executed application: Set
[Nov 11 19:32:56] VERBOSE[32449] logger.c: -- Executing [s@macro-dialout-trunk:4] GotoIf("SIP/212-b7dc64d0", "1?noauth") in new stack
[Nov 11 19:32:56] VERBOSE[23428] logger.c: Extension Changed 212 new state InUse for Notify User 203
[Nov 11 19:32:56] VERBOSE[32449] logger.c: -- Goto (macro-dialout-trunk,s,6)
[Nov 11 19:32:56] DEBUG[32449] app_macro.c: Executed application: GotoIf
[Nov 11 19:32:56] VERBOSE[32449] logger.c: -- Executing [s@macro-dialout-trunk:6] GotoIf("SIP/212-b7dc64d0", "0?disabletrunk|1") in new stack
[Nov 11 19:32:56] VERBOSE[23428] logger.c: Extension Changed 212 new state InUse for Notify User 206
[Nov 11 19:32:56] DEBUG[32449] app_macro.c: Executed application: GotoIf
[Nov 11 19:32:56] VERBOSE[32449] logger.c: -- Executing [s@macro-dialout-trunk:7] Set("SIP/212-b7dc64d0", "_NODEST=") in new stack
[Nov 11 19:32:56] VERBOSE[23428] logger.c: Extension Changed 212 new state InUse for Notify User 229
[Nov 11 19:32:56] DEBUG[32449] app_macro.c: Executed application: Set
[Nov 11 19:32:56] VERBOSE[32449] logger.c: -- Executing [s@macro-dialout-trunk:8] Set("SIP/212-b7dc64d0", "DIAL_TRUNK_OPTIONS=Tt") in new stack
[Nov 11 19:32:56] VERBOSE[23428] logger.c: Extension Changed 212 new state InUse for Notify User 204
[Nov 11 19:32:56] DEBUG[32449] app_macro.c: Executed application: Set
[Nov 11 19:32:56] VERBOSE[32449] logger.c: -- Executing [s@macro-dialout-trunk:9] Set("SIP/212-b7dc64d0", "GROUP()=OUT_1") in new stack
[Nov 11 19:32:56] VERBOSE[23428] logger.c: Extension Changed 212 new state InUse for Notify User 225
[Nov 11 19:32:56] DEBUG[32449] app_macro.c: Executed application: Set
[Nov 11 19:32:56] VERBOSE[32449] logger.c: -- Executing [s@macro-dialout-trunk:10] Macro("SIP/212-b7dc64d0", "user-callerid|SKIPTTL") in new stack
[Nov 11 19:32:56] VERBOSE[23428] logger.c: Extension Changed 212 new state InUse for Notify User 228
[Nov 11 19:32:56] VERBOSE[32449] logger.c: -- Executing [s@macro-user-callerid:1] NoOp("SIP/212-b7dc64d0", "user-callerid: device 212") in new stack
[Nov 11 19:32:56] DEBUG[32449] app_macro.c: Executed application: Noop
[Nov 11 19:32:56] VERBOSE[23428] logger.c: Extension Changed 212 new state InUse for Notify User 226
[Nov 11 19:32:56] VERBOSE[32449] logger.c: -- Executing [s@macro-user-callerid:2] Set("SIP/212-b7dc64d0", "AMPUSER=212") in new stack
[Nov 11 19:32:56] DEBUG[32449] app_macro.c: Executed application: Set
[Nov 11 19:32:56] VERBOSE[32449] logger.c: -- Executing [s@macro-user-callerid:3] GotoIf("SIP/212-b7dc64d0", "0?report") in new stack
[Nov 11 19:32:56] DEBUG[32449] app_macro.c: Executed application: GotoIf
[Nov 11 19:32:56] VERBOSE[23428] logger.c: Extension Changed 212 new state InUse for Notify User 201
[Nov 11 19:32:56] VERBOSE[32449] logger.c: -- Executing [s@macro-user-callerid:4] GotoIf("SIP/212-b7dc64d0", "0?start") in new stack
[Nov 11 19:32:56] DEBUG[32449] app_macro.c: Executed application: GotoIf
[Nov 11 19:32:56] VERBOSE[32449] logger.c: -- Executing [s@macro-user-callerid:5] Set("SIP/212-b7dc64d0", "REALCALLERIDNUM=212") in new stack
[Nov 11 19:32:56] DEBUG[32449] app_macro.c: Executed application: Set
[Nov 11 19:32:56] VERBOSE[32449] logger.c: -- Executing [s@macro-user-callerid:6] NoOp("SIP/212-b7dc64d0", "REALCALLERIDNUM is 212") in new stack
[Nov 11 19:32:56] DEBUG[32449] app_macro.c: Executed application: NoOp
[Nov 11 19:32:56] VERBOSE[23428] logger.c: Extension Changed 212 new state InUse for Notify User 220
[Nov 11 19:32:56] VERBOSE[32449] logger.c: -- Executing [s@macro-user-callerid:7] Set("SIP/212-b7dc64d0", "AMPUSER=212") in new stack
[Nov 11 19:32:56] DEBUG[32449] app_macro.c: Executed application: Set
[Nov 11 19:32:56] VERBOSE[32449] logger.c: -- Executing [s@macro-user-callerid:8] Set("SIP/212-b7dc64d0", "AMPUSERCIDNAME=Ext.212") in new stack
[Nov 11 19:32:56] VERBOSE[23428] logger.c: Extension Changed 212 new state InUse for Notify User 219
[Nov 11 19:32:56] DEBUG[32449] app_macro.c: Executed application: Set
[Nov 11 19:32:56] VERBOSE[32449] logger.c: -- Executing [s@macro-user-callerid:9] GotoIf("SIP/212-b7dc64d0", "0?report") in new stack
[Nov 11 19:32:56] VERBOSE[23428] logger.c: Extension Changed 212 new state InUse for Notify User 211
[Nov 11 19:32:56] DEBUG[32449] app_macro.c: Executed application: GotoIf
[Nov 11 19:32:56] DEBUG[32449] db.c: Unable to find key '212/cidnum' in family 'AMPUSER'
[Nov 11 19:32:56] VERBOSE[32449] logger.c: -- Executing [s@macro-user-callerid:10] Set("SIP/212-b7dc64d0", "AMPUSERCID=212") in new stack
[Nov 11 19:32:56] DEBUG[32449] app_macro.c: Executed application: Set
[Nov 11 19:32:56] VERBOSE[32449] logger.c: -- Executing [s@macro-user-callerid:11] Set("SIP/212-b7dc64d0", "CALLERID(all)="Ext.212" <212>") in new stack
[Nov 11 19:32:56] DEBUG[32449] app_macro.c: Executed application: Set
[Nov 11 19:32:56] VERBOSE[23428] logger.c: Extension Changed 212 new state InUse for Notify User 216
[Nov 11 19:32:56] VERBOSE[32449] logger.c: -- Executing [s@macro-user-callerid:12] Set("SIP/212-b7dc64d0", "REALCALLERIDNUM=212") in new stack
[Nov 11 19:32:56] DEBUG[32449] app_macro.c: Executed application: Set
[Nov 11 19:32:56] VERBOSE[32449] logger.c: -- Executing [s@macro-user-callerid:13] NoOp("SIP/212-b7dc64d0", "TTL: ARG1: SKIPTTL") in new stack
[Nov 11 19:32:56] VERBOSE[23428] logger.c: Extension Changed 212 new state InUse for Notify User 212
[Nov 11 19:32:56] DEBUG[32449] app_macro.c: Executed application: Noop
[Nov 11 19:32:56] VERBOSE[32449] logger.c: -- Executing [s@macro-user-callerid:14] GotoIf("SIP/212-b7dc64d0", "1?continue") in new stack
[Nov 11 19:32:56] VERBOSE[23428] logger.c: Extension Changed 212 new state InUse for Notify User 208
[Nov 11 19:32:56] VERBOSE[32449] logger.c: -- Goto (macro-user-callerid,s,23)
[Nov 11 19:32:56] DEBUG[32449] app_macro.c: Executed application: GotoIf
[Nov 11 19:32:56] VERBOSE[32449] logger.c: -- Executing [s@macro-user-callerid:23] NoOp("SIP/212-b7dc64d0", "Using CallerID "Ext.212" <212>") in new stack
[Nov 11 19:32:56] DEBUG[32449] app_macro.c: Executed application: NoOp
[Nov 11 19:32:56] VERBOSE[23428] logger.c: Extension Changed 212 new state InUse for Notify User 213
[Nov 11 19:32:56] DEBUG[32449] app_macro.c: Executed application: Macro
[Nov 11 19:32:56] VERBOSE[32449] logger.c: -- Executing [s@macro-dialout-trunk:11] Macro("SIP/212-b7dc64d0", "record-enable|212|OUT") in new stack
[Nov 11 19:32:56] VERBOSE[23428] logger.c: Extension Changed 212 new state InUse for Notify User 223
[Nov 11 19:32:56] VERBOSE[32449] logger.c: -- Executing [s@macro-record-enable:1] GotoIf("SIP/212-b7dc64d0", "0?2:4") in new stack
[Nov 11 19:32:56] VERBOSE[32449] logger.c: -- Goto (macro-record-enable,s,4)
[Nov 11 19:32:56] DEBUG[32449] app_macro.c: Executed application: GotoIf
[Nov 11 19:32:56] VERBOSE[23428] logger.c: Extension Changed 212 new state InUse for Notify User 210
[Nov 11 19:32:56] VERBOSE[32449] logger.c: -- Executing [s@macro-record-enable:4] AGI("SIP/212-b7dc64d0", "recordingcheck|20071111-193256|1194802376.1449") in new stack
[Nov 11 19:32:56] VERBOSE[32449] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
[Nov 11 19:32:56] VERBOSE[23428] logger.c: Extension Changed 212 new state InUse for Notify User 230
[Nov 11 19:32:56] VERBOSE[23428] logger.c: Extension Changed 212 new state InUse for Notify User 227
[Nov 11 19:32:56] VERBOSE[23428] logger.c: Extension Changed 212 new state InUse for Notify User 215
[Nov 11 19:32:56] VERBOSE[23428] logger.c: Extension Changed 212 new state InUse for Notify User 221
[Nov 11 19:32:56] VERBOSE[32449] logger.c: recordingcheck|20071111-193256|1194802376.1449: Outbound recording enabled.
[Nov 11 19:32:56] VERBOSE[32449] logger.c: recordingcheck|20071111-193256|1194802376.1449: CALLFILENAME=OUT212-20071111-193256-1194802376.1449
[Nov 11 19:32:56] VERBOSE[32449] logger.c: -- AGI Script recordingcheck completed, returning 0
[Nov 11 19:32:56] DEBUG[32449] app_macro.c: Executed application: AGI
[Nov 11 19:32:56] VERBOSE[32449] logger.c: -- Executing [s@macro-record-enable:999] MixMonitor("SIP/212-b7dc64d0", "OUT212-20071111-193256-1194802376.1449.WAV") in new stack
[Nov 11 19:32:56] DEBUG[32449] app_macro.c: Executed application: MixMonitor
[Nov 11 19:32:56] DEBUG[32449] app_macro.c: Executed application: Macro
[Nov 11 19:32:56] VERBOSE[32449] logger.c: -- Executing [s@macro-dialout-trunk:12] GotoIf("SIP/212-b7dc64d0", "0?skipoutcid") in new stack
[Nov 11 19:32:56] DEBUG[32449] app_macro.c: Executed application: GotoIf
[Nov 11 19:32:56] VERBOSE[32449] logger.c: -- Executing [s@macro-dialout-trunk:13] Set("SIP/212-b7dc64d0", "DIAL_TRUNK_OPTIONS=Tt") in new stack
[Nov 11 19:32:56] VERBOSE[32452] logger.c: == Begin MixMonitor Recording SIP/212-b7dc64d0
[Nov 11 19:32:56] DEBUG[32449] app_macro.c: Executed application: Set
[Nov 11 19:32:56] VERBOSE[32449] logger.c: -- Executing [s@macro-dialout-trunk:14] Macro("SIP/212-b7dc64d0", "outbound-callerid|1") in new stack
[Nov 11 19:32:56] VERBOSE[32449] logger.c: -- Executing [s@macro-outbound-callerid:1] GotoIf("SIP/212-b7dc64d0", "1?start") in new stack
[Nov 11 19:32:56] VERBOSE[32449] logger.c: -- Goto (macro-outbound-callerid,s,3)
[Nov 11 19:32:56] DEBUG[32449] app_macro.c: Executed application: GotoIf
[Nov 11 19:32:56] VERBOSE[32449] logger.c: -- Executing [s@macro-outbound-callerid:3] NoOp("SIP/212-b7dc64d0", "REALCALLERIDNUM is 212") in new stack
[Nov 11 19:32:56] DEBUG[32449] app_macro.c: Executed application: NoOp
[Nov 11 19:32:56] VERBOSE[32449] logger.c: -- Executing [s@macro-outbound-callerid:4] GotoIf("SIP/212-b7dc64d0", "1?normcid") in new stack
[Nov 11 19:32:56] VERBOSE[32449] logger.c: -- Goto (macro-outbound-callerid,s,9)
[Nov 11 19:32:56] DEBUG[32449] app_macro.c: Executed application: GotoIf
[Nov 11 19:32:56] VERBOSE[32449] logger.c: -- Executing [s@macro-outbound-callerid:9] Set("SIP/212-b7dc64d0", "USEROUTCID=") in new stack
[Nov 11 19:32:56] DEBUG[32449] app_macro.c: Executed application: Set
[Nov 11 19:32:56] DEBUG[32449] db.c: Unable to find key '212/emergency_cid' in family 'DEVICE'
[Nov 11 19:32:56] DEBUG[32449] func_db.c: DB: DEVICE/212/emergency_cid not found in database.
[Nov 11 19:32:56] VERBOSE[32449] logger.c: -- Executing [s@macro-outbound-callerid:10] Set("SIP/212-b7dc64d0", "EMERGENCYCID=") in new stack
[Nov 11 19:32:56] DEBUG[32449] app_macro.c: Executed application: Set
[Nov 11 19:32:56] VERBOSE[32449] logger.c: -- Executing [s@macro-outbound-callerid:11] Set("SIP/212-b7dc64d0", "TRUNKOUTCID=") in new stack
[Nov 11 19:32:56] DEBUG[32449] app_macro.c: Executed application: Set
[Nov 11 19:32:56] VERBOSE[32449] logger.c: -- Executing [s@macro-outbound-callerid:12] GotoIf("SIP/212-b7dc64d0", "1?trunkcid") in new stack
[Nov 11 19:32:56] VERBOSE[32449] logger.c: -- Goto (macro-outbound-callerid,s,16)
[Nov 11 19:32:56] DEBUG[32449] app_macro.c: Executed application: GotoIf
[Nov 11 19:32:56] VERBOSE[32449] logger.c: -- Executing [s@macro-outbound-callerid:16] GotoIf("SIP/212-b7dc64d0", "1?usercid") in new stack
[Nov 11 19:32:56] VERBOSE[32449] logger.c: -- Goto (macro-outbound-callerid,s,18)
[Nov 11 19:32:56] DEBUG[32449] app_macro.c: Executed application: GotoIf
[Nov 11 19:32:56] VERBOSE[32449] logger.c: -- Executing [s@macro-outbound-callerid:18] GotoIf("SIP/212-b7dc64d0", "1?report") in new stack
[Nov 11 19:32:56] VERBOSE[32449] logger.c: -- Goto (macro-outbound-callerid,s,22)
[Nov 11 19:32:56] DEBUG[32449] app_macro.c: Executed application: GotoIf
[Nov 11 19:32:56] VERBOSE[32449] logger.c: -- Executing [s@macro-outbound-callerid:22] NoOp("SIP/212-b7dc64d0", "CallerID set to "Ext.212" <212>") in new stack
[Nov 11 19:32:56] DEBUG[32449] app_macro.c: Executed application: NoOp
[Nov 11 19:32:56] DEBUG[32449] app_macro.c: Executed application: Macro
[Nov 11 19:32:56] VERBOSE[32449] logger.c: -- Executing [s@macro-dialout-trunk:15] GotoIf("SIP/212-b7dc64d0", "1?nomax") in new stack
[Nov 11 19:32:56] VERBOSE[32449] logger.c: -- Goto (macro-dialout-trunk,s,17)
[Nov 11 19:32:56] DEBUG[32449] app_macro.c: Executed application: GotoIf
[Nov 11 19:32:56] VERBOSE[32449] logger.c: -- Executing [s@macro-dialout-trunk:17] AGI("SIP/212-b7dc64d0", "fixlocalprefix") in new stack
[Nov 11 19:32:56] VERBOSE[32449] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/fixlocalprefix
[Nov 11 19:32:56] VERBOSE[32449] logger.c: > fixlocalprefix: Using pattern .
[Nov 11 19:32:56] VERBOSE[32449] logger.c: == fixlocalprefix: Dialpattern . matched. 036161415 -> 036161415
[Nov 11 19:32:56] VERBOSE[32449] logger.c: -- AGI Script fixlocalprefix completed, returning 0
[Nov 11 19:32:56] DEBUG[32449] app_macro.c: Executed application: AGI
[Nov 11 19:32:56] VERBOSE[32449] logger.c: -- Executing [s@macro-dialout-trunk:18] Set("SIP/212-b7dc64d0", "OUTNUM=036161415") in new stack
[Nov 11 19:32:56] DEBUG[32449] app_macro.c: Executed application: Set
[Nov 11 19:32:56] VERBOSE[32449] logger.c: -- Executing [s@macro-dialout-trunk:19] Set("SIP/212-b7dc64d0", "custom=ZAP/g0") in new stack
[Nov 11 19:32:56] DEBUG[32449] app_macro.c: Executed application: Set
[Nov 11 19:32:56] VERBOSE[32449] logger.c: -- Executing [s@macro-dialout-trunk:20] GotoIf("SIP/212-b7dc64d0", "1?gocall") in new stack
[Nov 11 19:32:56] VERBOSE[32449] logger.c: -- Goto (macro-dialout-trunk,s,22)
[Nov 11 19:32:56] DEBUG[32449] app_macro.c: Executed application: GotoIf
[Nov 11 19:32:56] VERBOSE[32449] logger.c: -- Executing [s@macro-dialout-trunk:22] GotoIf("SIP/212-b7dc64d0", "0?customtrunk") in new stack
[Nov 11 19:32:56] DEBUG[32449] app_macro.c: Executed application: GotoIf
[Nov 11 19:32:56] VERBOSE[32449] logger.c: -- Executing [s@macro-dialout-trunk:23] Dial("SIP/212-b7dc64d0", "ZAP/g0/036161415|300|Tt") in new stack
[Nov 11 19:32:56] VERBOSE[32449] logger.c: -- Requested transfer capability: 0x00 - SPEECH
[Nov 11 19:32:56] VERBOSE[32449] logger.c: -- Called g0/036161415
[Nov 11 19:32:56] VERBOSE[32442] logger.c: -- Zap/3-1 is ringing
[Nov 11 19:32:56] DEBUG[23454] chan_zap.c: Queuing frame from PRI_EVENT_PROCEEDING on channel 0/5 span 1
[Nov 11 19:32:56] VERBOSE[32449] logger.c: -- Zap/5-1 is proceeding passing it to SIP/212-b7dc64d0
[Nov 11 19:32:57] VERBOSE[32449] logger.c: -- Zap/5-1 is ringing
[Nov 11 19:32:57] DEBUG[32258] chan_zap.c: Started VLDTMF digit '0'
[Nov 11 19:32:57] DEBUG[32258] chan_zap.c: Ending VLDTMF digit '0'
[Nov 11 19:32:58] DEBUG[32258] chan_zap.c: Started VLDTMF digit '3'
[Nov 11 19:32:58] DEBUG[32258] chan_zap.c: Ending VLDTMF digit '3'
[Nov 11 19:33:00] DEBUG[32258] chan_zap.c: Started VLDTMF digit '5'
[Nov 11 19:33:00] DEBUG[32258] chan_zap.c: Ending VLDTMF digit '5'
[Nov 11 19:33:01] DEBUG[23454] chan_zap.c: Echo cancellation already on
[Nov 11 19:33:01] VERBOSE[32442] logger.c: -- Zap/3-1 answered SIP/216-b7d96730
[Nov 11 19:33:01] DEBUG[32433] chan_zap.c: Set option AUDIO MODE, value: ON(1) on Zap/2-1
[Nov 11 19:33:01] DEBUG[32433] chan_zap.c: Not yet hungup... Calling hangup once with icause, and clearing call
[Nov 11 19:33:01] DEBUG[32433] chan_zap.c: Set option AUDIO MODE, value: OFF(0) on Zap/2-1
[Nov 11 19:33:01] VERBOSE[32433] logger.c: -- Hungup 'Zap/2-1'
[Nov 11 19:33:01] NOTICE[32433] cdr.c: CDR on channel 'Zap/2-1' not posted
[Nov 11 19:33:01] VERBOSE[32433] logger.c: == Spawn extension (macro-dialout-trunk, s, 23) exited non-zero on 'SIP/213-b7de1d70' in macro 'dialout-trunk'
[Nov 11 19:33:01] VERBOSE[32433] logger.c: == Spawn extension (macro-dialout-trunk, s, 23) exited non-zero on 'SIP/213-b7de1d70'
[Nov 11 19:33:01] VERBOSE[32433] logger.c: -- Executing [h@macro-dialout-trunk:1] Macro("SIP/213-b7de1d70", "hangupcall") in new stack
[Nov 11 19:33:01] VERBOSE[32433] logger.c: -- Executing [s@macro-hangupcall:1] ResetCDR("SIP/213-b7de1d70", "w") in new stack
[Nov 11 19:33:01] DEBUG[32433] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record.
[Nov 11 19:33:01] DEBUG[32433] 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 ('2007-11-11 19:32:46','\"Ext.213\" <213>','213','035707732','from-internal', 'SIP/213-b7de1d70','Zap/2-1','Dial','ZAP/g0/035707732|300|Tt',15,0,'NO ANSWER',3,'','1194802366.1445')
[Nov 11 19:33:01] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 207
[Nov 11 19:33:01] DEBUG[32433] app_macro.c: Executed application: ResetCDR
[Nov 11 19:33:01] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 224
[Nov 11 19:33:01] VERBOSE[32433] logger.c: -- Executing [s@macro-hangupcall:2] NoCDR("SIP/213-b7de1d70", "") in new stack
[Nov 11 19:33:01] DEBUG[32433] app_macro.c: Executed application: NoCDR
[Nov 11 19:33:01] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 214
[Nov 11 19:33:01] VERBOSE[32433] logger.c: -- Executing [s@macro-hangupcall:3] GotoIf("SIP/213-b7de1d70", "1?skiprg") in new stack
[Nov 11 19:33:01] VERBOSE[32433] logger.c: -- Goto (macro-hangupcall,s,6)
[Nov 11 19:33:01] DEBUG[32433] app_macro.c: Executed application: GotoIf
[Nov 11 19:33:01] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 218
[Nov 11 19:33:01] VERBOSE[32433] logger.c: -- Executing [s@macro-hangupcall:6] GotoIf("SIP/213-b7de1d70", "1?skipblkvm") in new stack
[Nov 11 19:33:01] VERBOSE[32433] logger.c: -- Goto (macro-hangupcall,s,9)
[Nov 11 19:33:01] DEBUG[32433] app_macro.c: Executed application: GotoIf
[Nov 11 19:33:01] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 203
[Nov 11 19:33:01] VERBOSE[32433] logger.c: -- Executing [s@macro-hangupcall:9] GotoIf("SIP/213-b7de1d70", "1?theend") in new stack
[Nov 11 19:33:01] VERBOSE[32433] logger.c: -- Goto (macro-hangupcall,s,11)
[Nov 11 19:33:01] DEBUG[32433] app_macro.c: Executed application: GotoIf
[Nov 11 19:33:01] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 206
[Nov 11 19:33:01] VERBOSE[32433] logger.c: -- Executing [s@macro-hangupcall:11] Hangup("SIP/213-b7de1d70", "") in new stack
[Nov 11 19:33:01] VERBOSE[32433] logger.c: == Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'SIP/213-b7de1d70' in macro 'hangupcall'
[Nov 11 19:33:01] VERBOSE[32433] logger.c: == Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'SIP/213-b7de1d70'
[Nov 11 19:33:01] VERBOSE[32436] logger.c: == End MixMonitor Recording SIP/213-b7de1d70
[Nov 11 19:33:01] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 229
[Nov 11 19:33:01] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 204
[Nov 11 19:33:01] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 225
[Nov 11 19:33:01] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 228
[Nov 11 19:33:01] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 226
[Nov 11 19:33:01] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 201
[Nov 11 19:33:01] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 220
[Nov 11 19:33:01] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 219
[Nov 11 19:33:01] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 211
[Nov 11 19:33:01] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 216
[Nov 11 19:33:01] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 212
[Nov 11 19:33:01] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 208
[Nov 11 19:33:01] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 213
[Nov 11 19:33:01] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 223
[Nov 11 19:33:01] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 210
[Nov 11 19:33:01] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 230
[Nov 11 19:33:01] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 227
[Nov 11 19:33:01] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 215
[Nov 11 19:33:01] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 221
[Nov 11 19:33:03] VERBOSE[31262] logger.c: == Done Spying on channel SIP/210-b7dc8fe0
[Nov 11 19:33:03] VERBOSE[31262] logger.c: -- Playing 'beep' (language 'he')
[Nov 11 19:33:09] VERBOSE[23426] logger.c: -- Remote UNIX connection
[Nov 11 19:33:09] VERBOSE[32464] logger.c: -- Remote UNIX connection disconnected
[Nov 11 19:33:09] VERBOSE[31262] logger.c: -- Playing 'spy-sip' (language 'he')
[Nov 11 19:33:10] VERBOSE[31262] logger.c: -- Playing 'digits/2' (language 'he')
[Nov 11 19:33:10] VERBOSE[31262] logger.c: -- Playing 'digits/1' (language 'he')
[Nov 11 19:33:11] VERBOSE[31262] logger.c: -- Playing 'digits/2' (language 'he')
[Nov 11 19:33:11] DEBUG[23454] chan_zap.c: Echo cancellation already on
[Nov 11 19:33:11] VERBOSE[32449] logger.c: -- Zap/5-1 answered SIP/212-b7dc64d0
[Nov 11 19:33:12] VERBOSE[31262] logger.c: == Spying on channel SIP/212-b7dc64d0
[Nov 11 19:33:12] NOTICE[31262] app_chanspy.c: Attaching Zap/6-1 to SIP/212-b7dc64d0
[Nov 11 19:33:14] VERBOSE[31262] logger.c: == Done Spying on channel SIP/212-b7dc64d0
[Nov 11 19:33:14] VERBOSE[31262] logger.c: -- Playing 'beep' (language 'he')
[Nov 11 19:33:14] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 207
[Nov 11 19:33:14] VERBOSE[32468] logger.c: -- Executing [036189216@from-internal:1] Macro("SIP/213-b7de1d70", "dialout-trunk|1|036189216||") in new stack
[Nov 11 19:33:14] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 224
[Nov 11 19:33:14] VERBOSE[32468] logger.c: -- Executing [s@macro-dialout-trunk:1] Set("SIP/213-b7de1d70", "DIAL_TRUNK=1") in new stack
[Nov 11 19:33:14] DEBUG[32468] app_macro.c: Executed application: Set
[Nov 11 19:33:14] VERBOSE[32468] logger.c: -- Executing [s@macro-dialout-trunk:2] Set("SIP/213-b7de1d70", "DIAL_NUMBER=036189216") in new stack
[Nov 11 19:33:14] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 214
[Nov 11 19:33:14] DEBUG[32468] app_macro.c: Executed application: Set
[Nov 11 19:33:14] VERBOSE[32468] logger.c: -- Executing [s@macro-dialout-trunk:3] Set("SIP/213-b7de1d70", "ROUTE_PASSWD=") in new stack
[Nov 11 19:33:14] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 218
[Nov 11 19:33:14] DEBUG[32468] app_macro.c: Executed application: Set
[Nov 11 19:33:14] VERBOSE[32468] logger.c: -- Executing [s@macro-dialout-trunk:4] GotoIf("SIP/213-b7de1d70", "1?noauth") in new stack
[Nov 11 19:33:14] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 203
[Nov 11 19:33:14] VERBOSE[32468] logger.c: -- Goto (macro-dialout-trunk,s,6)
[Nov 11 19:33:14] DEBUG[32468] app_macro.c: Executed application: GotoIf
[Nov 11 19:33:14] VERBOSE[32468] logger.c: -- Executing [s@macro-dialout-trunk:6] GotoIf("SIP/213-b7de1d70", "0?disabletrunk|1") in new stack
[Nov 11 19:33:14] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 206
[Nov 11 19:33:14] DEBUG[32468] app_macro.c: Executed application: GotoIf
[Nov 11 19:33:14] VERBOSE[32468] logger.c: -- Executing [s@macro-dialout-trunk:7] Set("SIP/213-b7de1d70", "_NODEST=") in new stack
[Nov 11 19:33:14] DEBUG[32468] app_macro.c: Executed application: Set
[Nov 11 19:33:14] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 229
[Nov 11 19:33:14] VERBOSE[32468] logger.c: -- Executing [s@macro-dialout-trunk:8] Set("SIP/213-b7de1d70", "DIAL_TRUNK_OPTIONS=Tt") in new stack
[Nov 11 19:33:14] DEBUG[32468] app_macro.c: Executed application: Set
[Nov 11 19:33:14] VERBOSE[32468] logger.c: -- Executing [s@macro-dialout-trunk:9] Set("SIP/213-b7de1d70", "GROUP()=OUT_1") in new stack
[Nov 11 19:33:14] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 204
[Nov 11 19:33:14] DEBUG[32468] app_macro.c: Executed application: Set
[Nov 11 19:33:14] VERBOSE[32468] logger.c: -- Executing [s@macro-dialout-trunk:10] Macro("SIP/213-b7de1d70", "user-callerid|SKIPTTL") in new stack
[Nov 11 19:33:14] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 225
[Nov 11 19:33:14] VERBOSE[32468] logger.c: -- Executing [s@macro-user-callerid:1] NoOp("SIP/213-b7de1d70", "user-callerid: device 213") in new stack
[Nov 11 19:33:14] DEBUG[32468] app_macro.c: Executed application: Noop
[Nov 11 19:33:14] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 228
[Nov 11 19:33:14] VERBOSE[32468] logger.c: -- Executing [s@macro-user-callerid:2] Set("SIP/213-b7de1d70", "AMPUSER=213") in new stack
[Nov 11 19:33:14] DEBUG[32468] app_macro.c: Executed application: Set
[Nov 11 19:33:14] VERBOSE[32468] logger.c: -- Executing [s@macro-user-callerid:3] GotoIf("SIP/213-b7de1d70", "0?report") in new stack
[Nov 11 19:33:14] DEBUG[32468] app_macro.c: Executed application: GotoIf
[Nov 11 19:33:14] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 226
[Nov 11 19:33:14] VERBOSE[32468] logger.c: -- Executing [s@macro-user-callerid:4] GotoIf("SIP/213-b7de1d70", "0?start") in new stack
[Nov 11 19:33:14] DEBUG[32468] app_macro.c: Executed application: GotoIf
[Nov 11 19:33:14] VERBOSE[32468] logger.c: -- Executing [s@macro-user-callerid:5] Set("SIP/213-b7de1d70", "REALCALLERIDNUM=213") in new stack
[Nov 11 19:33:14] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 201
[Nov 11 19:33:14] DEBUG[32468] app_macro.c: Executed application: Set
[Nov 11 19:33:14] VERBOSE[32468] logger.c: -- Executing [s@macro-user-callerid:6] NoOp("SIP/213-b7de1d70", "REALCALLERIDNUM is 213") in new stack
[Nov 11 19:33:14] DEBUG[32468] app_macro.c: Executed application: NoOp
[Nov 11 19:33:14] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 220
[Nov 11 19:33:14] VERBOSE[32468] logger.c: -- Executing [s@macro-user-callerid:7] Set("SIP/213-b7de1d70", "AMPUSER=213") in new stack
[Nov 11 19:33:14] DEBUG[32468] app_macro.c: Executed application: Set
[Nov 11 19:33:14] VERBOSE[32468] logger.c: -- Executing [s@macro-user-callerid:8] Set("SIP/213-b7de1d70", "AMPUSERCIDNAME=Ext.213") in new stack
[Nov 11 19:33:14] DEBUG[32468] app_macro.c: Executed application: Set
[Nov 11 19:33:14] VERBOSE[32468] logger.c: -- Executing [s@macro-user-callerid:9] GotoIf("SIP/213-b7de1d70", "0?report") in new stack
[Nov 11 19:33:14] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 219
[Nov 11 19:33:14] DEBUG[32468] app_macro.c: Executed application: GotoIf
[Nov 11 19:33:14] DEBUG[32468] db.c: Unable to find key '213/cidnum' in family 'AMPUSER'
[Nov 11 19:33:14] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 211
[Nov 11 19:33:14] VERBOSE[32468] logger.c: -- Executing [s@macro-user-callerid:10] Set("SIP/213-b7de1d70", "AMPUSERCID=213") in new stack
[Nov 11 19:33:14] DEBUG[32468] app_macro.c: Executed application: Set
[Nov 11 19:33:14] VERBOSE[32468] logger.c: -- Executing [s@macro-user-callerid:11] Set("SIP/213-b7de1d70", "CALLERID(all)="Ext.213" <213>") in new stack
[Nov 11 19:33:14] DEBUG[32468] app_macro.c: Executed application: Set
[Nov 11 19:33:14] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 216
[Nov 11 19:33:14] VERBOSE[32468] logger.c: -- Executing [s@macro-user-callerid:12] Set("SIP/213-b7de1d70", "REALCALLERIDNUM=213") in new stack
[Nov 11 19:33:14] DEBUG[32468] app_macro.c: Executed application: Set
[Nov 11 19:33:14] VERBOSE[32468] logger.c: -- Executing [s@macro-user-callerid:13] NoOp("SIP/213-b7de1d70", "TTL: ARG1: SKIPTTL") in new stack
[Nov 11 19:33:14] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 212
[Nov 11 19:33:14] DEBUG[32468] app_macro.c: Executed application: Noop
[Nov 11 19:33:14] VERBOSE[32468] logger.c: -- Executing [s@macro-user-callerid:14] GotoIf("SIP/213-b7de1d70", "1?continue") in new stack
[Nov 11 19:33:14] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 208
[Nov 11 19:33:14] VERBOSE[32468] logger.c: -- Goto (macro-user-callerid,s,23)
[Nov 11 19:33:14] DEBUG[32468] app_macro.c: Executed application: GotoIf
[Nov 11 19:33:14] VERBOSE[32468] logger.c: -- Executing [s@macro-user-callerid:23] NoOp("SIP/213-b7de1d70", "Using CallerID "Ext.213" <213>") in new stack
[Nov 11 19:33:14] DEBUG[32468] app_macro.c: Executed application: NoOp
[Nov 11 19:33:14] DEBUG[32468] app_macro.c: Executed application: Macro
[Nov 11 19:33:14] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 213
[Nov 11 19:33:14] VERBOSE[32468] logger.c: -- Executing [s@macro-dialout-trunk:11] Macro("SIP/213-b7de1d70", "record-enable|213|OUT") in new stack
[Nov 11 19:33:14] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 223
[Nov 11 19:33:14] VERBOSE[32468] logger.c: -- Executing [s@macro-record-enable:1] GotoIf("SIP/213-b7de1d70", "0?2:4") in new stack
[Nov 11 19:33:14] VERBOSE[32468] logger.c: -- Goto (macro-record-enable,s,4)
[Nov 11 19:33:14] DEBUG[32468] app_macro.c: Executed application: GotoIf
[Nov 11 19:33:14] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 210
[Nov 11 19:33:14] VERBOSE[32468] logger.c: -- Executing [s@macro-record-enable:4] AGI("SIP/213-b7de1d70", "recordingcheck|20071111-193314|1194802394.1451") in new stack
[Nov 11 19:33:14] VERBOSE[32468] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
[Nov 11 19:33:14] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 230
[Nov 11 19:33:14] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 227
[Nov 11 19:33:14] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 215
[Nov 11 19:33:14] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 221
[Nov 11 19:33:14] VERBOSE[32468] logger.c: recordingcheck|20071111-193314|1194802394.1451: Outbound recording enabled.
[Nov 11 19:33:14] VERBOSE[32468] logger.c: recordingcheck|20071111-193314|1194802394.1451: CALLFILENAME=OUT213-20071111-193314-1194802394.1451
[Nov 11 19:33:14] VERBOSE[32468] logger.c: -- AGI Script recordingcheck completed, returning 0
[Nov 11 19:33:14] DEBUG[32468] app_macro.c: Executed application: AGI
[Nov 11 19:33:14] VERBOSE[32468] logger.c: -- Executing [s@macro-record-enable:999] MixMonitor("SIP/213-b7de1d70", "OUT213-20071111-193314-1194802394.1451.WAV") in new stack
[Nov 11 19:33:14] DEBUG[32468] app_macro.c: Executed application: MixMonitor
[Nov 11 19:33:14] DEBUG[32468] app_macro.c: Executed application: Macro
[Nov 11 19:33:14] VERBOSE[32468] logger.c: -- Executing [s@macro-dialout-trunk:12] GotoIf("SIP/213-b7de1d70", "0?skipoutcid") in new stack
[Nov 11 19:33:14] DEBUG[32468] app_macro.c: Executed application: GotoIf
[Nov 11 19:33:14] VERBOSE[32468] logger.c: -- Executing [s@macro-dialout-trunk:13] Set("SIP/213-b7de1d70", "DIAL_TRUNK_OPTIONS=Tt") in new stack
[Nov 11 19:33:14] DEBUG[32468] app_macro.c: Executed application: Set
[Nov 11 19:33:14] VERBOSE[32468] logger.c: -- Executing [s@macro-dialout-trunk:14] Macro("SIP/213-b7de1d70", "outbound-callerid|1") in new stack
[Nov 11 19:33:14] VERBOSE[32468] logger.c: -- Executing [s@macro-outbound-callerid:1] GotoIf("SIP/213-b7de1d70", "1?start") in new stack
[Nov 11 19:33:14] VERBOSE[32468] logger.c: -- Goto (macro-outbound-callerid,s,3)
[Nov 11 19:33:14] DEBUG[32468] app_macro.c: Executed application: GotoIf
[Nov 11 19:33:14] VERBOSE[32468] logger.c: -- Executing [s@macro-outbound-callerid:3] NoOp("SIP/213-b7de1d70", "REALCALLERIDNUM is 213") in new stack
[Nov 11 19:33:14] DEBUG[32468] app_macro.c: Executed application: NoOp
[Nov 11 19:33:14] VERBOSE[32468] logger.c: -- Executing [s@macro-outbound-callerid:4] GotoIf("SIP/213-b7de1d70", "1?normcid") in new stack
[Nov 11 19:33:14] VERBOSE[32468] logger.c: -- Goto (macro-outbound-callerid,s,9)
[Nov 11 19:33:14] DEBUG[32468] app_macro.c: Executed application: GotoIf
[Nov 11 19:33:14] VERBOSE[32468] logger.c: -- Executing [s@macro-outbound-callerid:9] Set("SIP/213-b7de1d70", "USEROUTCID=") in new stack
[Nov 11 19:33:14] DEBUG[32468] app_macro.c: Executed application: Set
[Nov 11 19:33:14] DEBUG[32468] db.c: Unable to find key '213/emergency_cid' in family 'DEVICE'
[Nov 11 19:33:14] DEBUG[32468] func_db.c: DB: DEVICE/213/emergency_cid not found in database.
[Nov 11 19:33:14] VERBOSE[32468] logger.c: -- Executing [s@macro-outbound-callerid:10] Set("SIP/213-b7de1d70", "EMERGENCYCID=") in new stack
[Nov 11 19:33:14] DEBUG[32468] app_macro.c: Executed application: Set
[Nov 11 19:33:14] VERBOSE[32468] logger.c: -- Executing [s@macro-outbound-callerid:11] Set("SIP/213-b7de1d70", "TRUNKOUTCID=") in new stack
[Nov 11 19:33:14] DEBUG[32468] app_macro.c: Executed application: Set
[Nov 11 19:33:14] VERBOSE[32468] logger.c: -- Executing [s@macro-outbound-callerid:12] GotoIf("SIP/213-b7de1d70", "1?trunkcid") in new stack
[Nov 11 19:33:14] VERBOSE[32468] logger.c: -- Goto (macro-outbound-callerid,s,16)
[Nov 11 19:33:14] DEBUG[32468] app_macro.c: Executed application: GotoIf
[Nov 11 19:33:14] VERBOSE[32468] logger.c: -- Executing [s@macro-outbound-callerid:16] GotoIf("SIP/213-b7de1d70", "1?usercid") in new stack
[Nov 11 19:33:14] VERBOSE[32468] logger.c: -- Goto (macro-outbound-callerid,s,18)
[Nov 11 19:33:14] DEBUG[32468] app_macro.c: Executed application: GotoIf
[Nov 11 19:33:14] VERBOSE[32468] logger.c: -- Executing [s@macro-outbound-callerid:18] GotoIf("SIP/213-b7de1d70", "1?report") in new stack
[Nov 11 19:33:14] VERBOSE[32468] logger.c: -- Goto (macro-outbound-callerid,s,22)
[Nov 11 19:33:14] DEBUG[32468] app_macro.c: Executed application: GotoIf
[Nov 11 19:33:14] VERBOSE[32468] logger.c: -- Executing [s@macro-outbound-callerid:22] NoOp("SIP/213-b7de1d70", "CallerID set to "Ext.213" <213>") in new stack
[Nov 11 19:33:14] DEBUG[32468] app_macro.c: Executed application: NoOp
[Nov 11 19:33:14] DEBUG[32468] app_macro.c: Executed application: Macro
[Nov 11 19:33:14] VERBOSE[32468] logger.c: -- Executing [s@macro-dialout-trunk:15] GotoIf("SIP/213-b7de1d70", "1?nomax") in new stack
[Nov 11 19:33:14] VERBOSE[32468] logger.c: -- Goto (macro-dialout-trunk,s,17)
[Nov 11 19:33:14] DEBUG[32468] app_macro.c: Executed application: GotoIf
[Nov 11 19:33:14] VERBOSE[32468] logger.c: -- Executing [s@macro-dialout-trunk:17] AGI("SIP/213-b7de1d70", "fixlocalprefix") in new stack
[Nov 11 19:33:14] VERBOSE[32468] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/fixlocalprefix
[Nov 11 19:33:14] VERBOSE[32471] logger.c: == Begin MixMonitor Recording SIP/213-b7de1d70
[Nov 11 19:33:14] VERBOSE[32468] logger.c: > fixlocalprefix: Using pattern .
[Nov 11 19:33:14] VERBOSE[32468] logger.c: == fixlocalprefix: Dialpattern . matched. 036189216 -> 036189216
[Nov 11 19:33:14] VERBOSE[32468] logger.c: -- AGI Script fixlocalprefix completed, returning 0
[Nov 11 19:33:14] DEBUG[32468] app_macro.c: Executed application: AGI
[Nov 11 19:33:14] VERBOSE[32468] logger.c: -- Executing [s@macro-dialout-trunk:18] Set("SIP/213-b7de1d70", "OUTNUM=036189216") in new stack
[Nov 11 19:33:14] DEBUG[32468] app_macro.c: Executed application: Set
[Nov 11 19:33:14] VERBOSE[32468] logger.c: -- Executing [s@macro-dialout-trunk:19] Set("SIP/213-b7de1d70", "custom=ZAP/g0") in new stack
[Nov 11 19:33:14] DEBUG[32468] app_macro.c: Executed application: Set
[Nov 11 19:33:14] VERBOSE[32468] logger.c: -- Executing [s@macro-dialout-trunk:20] GotoIf("SIP/213-b7de1d70", "1?gocall") in new stack
[Nov 11 19:33:14] VERBOSE[32468] logger.c: -- Goto (macro-dialout-trunk,s,22)
[Nov 11 19:33:14] DEBUG[32468] app_macro.c: Executed application: GotoIf
[Nov 11 19:33:14] VERBOSE[32468] logger.c: -- Executing [s@macro-dialout-trunk:22] GotoIf("SIP/213-b7de1d70", "0?customtrunk") in new stack
[Nov 11 19:33:14] DEBUG[32468] app_macro.c: Executed application: GotoIf
[Nov 11 19:33:14] VERBOSE[32468] logger.c: -- Executing [s@macro-dialout-trunk:23] Dial("SIP/213-b7de1d70", "ZAP/g0/036189216|300|Tt") in new stack
[Nov 11 19:33:14] VERBOSE[32468] logger.c: -- Requested transfer capability: 0x00 - SPEECH
[Nov 11 19:33:14] VERBOSE[32468] logger.c: -- Called g0/036189216
[Nov 11 19:33:14] DEBUG[23454] chan_zap.c: Queuing frame from PRI_EVENT_PROCEEDING on channel 0/2 span 1
[Nov 11 19:33:14] VERBOSE[32468] logger.c: -- Zap/2-1 is proceeding passing it to SIP/213-b7de1d70
[Nov 11 19:33:15] VERBOSE[23426] logger.c: -- Remote UNIX connection
[Nov 11 19:33:15] DEBUG[23454] chan_zap.c: Queuing frame from PRI_EVENT_PROGRESS on channel 0/2 span 1
[Nov 11 19:33:15] VERBOSE[32468] logger.c: -- Zap/2-1 is making progress passing it to SIP/213-b7de1d70
[Nov 11 19:33:15] VERBOSE[32475] logger.c: -- Remote UNIX connection disconnected
[Nov 11 19:33:16] VERBOSE[32468] logger.c: -- Zap/2-1 is ringing
[Nov 11 19:33:19] VERBOSE[31262] logger.c: -- Playing 'spy-sip' (language 'he')
[Nov 11 19:33:19] DEBUG[32468] chan_zap.c: Set option AUDIO MODE, value: ON(1) on Zap/2-1
[Nov 11 19:33:19] DEBUG[32468] chan_zap.c: Not yet hungup... Calling hangup once with icause, and clearing call
[Nov 11 19:33:19] DEBUG[32468] chan_zap.c: Set option AUDIO MODE, value: OFF(0) on Zap/2-1
[Nov 11 19:33:19] VERBOSE[32468] logger.c: -- Hungup 'Zap/2-1'
[Nov 11 19:33:19] NOTICE[32468] cdr.c: CDR on channel 'Zap/2-1' not posted
[Nov 11 19:33:19] VERBOSE[32468] logger.c: == Spawn extension (macro-dialout-trunk, s, 23) exited non-zero on 'SIP/213-b7de1d70' in macro 'dialout-trunk'
[Nov 11 19:33:19] VERBOSE[32468] logger.c: == Spawn extension (macro-dialout-trunk, s, 23) exited non-zero on 'SIP/213-b7de1d70'
[Nov 11 19:33:19] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 207
[Nov 11 19:33:19] VERBOSE[32468] logger.c: -- Executing [h@macro-dialout-trunk:1] Macro("SIP/213-b7de1d70", "hangupcall") in new stack
[Nov 11 19:33:19] VERBOSE[32468] logger.c: -- Executing [s@macro-hangupcall:1] ResetCDR("SIP/213-b7de1d70", "w") in new stack
[Nov 11 19:33:19] DEBUG[32468] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record.
[Nov 11 19:33:19] DEBUG[32468] 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 ('2007-11-11 19:33:14','\"Ext.213\" <213>','213','036189216','from-internal', 'SIP/213-b7de1d70','Zap/2-1','Dial','ZAP/g0/036189216|300|Tt',5,0,'NO ANSWER',3,'','1194802394.1451')
[Nov 11 19:33:19] DEBUG[32468] app_macro.c: Executed application: ResetCDR
[Nov 11 19:33:19] VERBOSE[32468] logger.c: -- Executing [s@macro-hangupcall:2] NoCDR("SIP/213-b7de1d70", "") in new stack
[Nov 11 19:33:19] DEBUG[32468] app_macro.c: Executed application: NoCDR
[Nov 11 19:33:19] VERBOSE[32468] logger.c: -- Executing [s@macro-hangupcall:3] GotoIf("SIP/213-b7de1d70", "1?skiprg") in new stack
[Nov 11 19:33:19] VERBOSE[32468] logger.c: -- Goto (macro-hangupcall,s,6)
[Nov 11 19:33:19] DEBUG[32468] app_macro.c: Executed application: GotoIf
[Nov 11 19:33:19] VERBOSE[32468] logger.c: -- Executing [s@macro-hangupcall:6] GotoIf("SIP/213-b7de1d70", "1?skipblkvm") in new stack
[Nov 11 19:33:19] VERBOSE[32468] logger.c: -- Goto (macro-hangupcall,s,9)
[Nov 11 19:33:19] DEBUG[32468] app_macro.c: Executed application: GotoIf
[Nov 11 19:33:19] VERBOSE[32468] logger.c: -- Executing [s@macro-hangupcall:9] GotoIf("SIP/213-b7de1d70", "1?theend") in new stack
[Nov 11 19:33:19] VERBOSE[32468] logger.c: -- Goto (macro-hangupcall,s,11)
[Nov 11 19:33:19] DEBUG[32468] app_macro.c: Executed application: GotoIf
[Nov 11 19:33:19] VERBOSE[32468] logger.c: -- Executing [s@macro-hangupcall:11] Hangup("SIP/213-b7de1d70", "") in new stack
[Nov 11 19:33:19] VERBOSE[32468] logger.c: == Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'SIP/213-b7de1d70' in macro 'hangupcall'
[Nov 11 19:33:19] VERBOSE[32468] logger.c: == Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'SIP/213-b7de1d70'
[Nov 11 19:33:19] VERBOSE[32471] logger.c: == End MixMonitor Recording SIP/213-b7de1d70
[Nov 11 19:33:19] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 224
[Nov 11 19:33:19] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 214
[Nov 11 19:33:19] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 218
[Nov 11 19:33:19] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 203
[Nov 11 19:33:19] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 206
[Nov 11 19:33:19] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 229
[Nov 11 19:33:19] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 204
[Nov 11 19:33:19] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 225
[Nov 11 19:33:19] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 228
[Nov 11 19:33:19] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 226
[Nov 11 19:33:19] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 201
[Nov 11 19:33:19] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 220
[Nov 11 19:33:19] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 219
[Nov 11 19:33:19] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 211
[Nov 11 19:33:19] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 216
[Nov 11 19:33:19] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 212
[Nov 11 19:33:19] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 208
[Nov 11 19:33:19] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 213
[Nov 11 19:33:19] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 223
[Nov 11 19:33:19] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 210
[Nov 11 19:33:19] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 230
[Nov 11 19:33:19] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 227
[Nov 11 19:33:19] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 215
[Nov 11 19:33:19] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 221
[Nov 11 19:33:20] VERBOSE[31262] logger.c: -- Playing 'digits/2' (language 'he')
[Nov 11 19:33:21] VERBOSE[31262] logger.c: -- Playing 'digits/1' (language 'he')
[Nov 11 19:33:21] VERBOSE[31262] logger.c: -- Playing 'digits/3' (language 'he')
[Nov 11 19:33:22] DEBUG[32258] chan_zap.c: Started VLDTMF digit '0'
[Nov 11 19:33:22] VERBOSE[31262] logger.c: -- Playing 'beep' (language 'he')
[Nov 11 19:33:22] DEBUG[32258] chan_zap.c: Ending VLDTMF digit '0'
[Nov 11 19:33:24] VERBOSE[31262] logger.c: -- Playing 'spy-sip' (language 'he')
[Nov 11 19:33:24] VERBOSE[23426] logger.c: -- Remote UNIX connection
[Nov 11 19:33:24] VERBOSE[32481] logger.c: -- Remote UNIX connection disconnected
[Nov 11 19:33:24] VERBOSE[31262] logger.c: -- Playing 'digits/2' (language 'he')
[Nov 11 19:33:25] VERBOSE[31262] logger.c: -- Playing 'digits/1' (language 'he')
[Nov 11 19:33:26] VERBOSE[31262] logger.c: -- Playing 'digits/2' (language 'he')
[Nov 11 19:33:26] VERBOSE[31262] logger.c: == Spying on channel SIP/212-b7dc64d0
[Nov 11 19:33:26] NOTICE[31262] app_chanspy.c: Attaching Zap/6-1 to SIP/212-b7dc64d0
[Nov 11 19:33:27] VERBOSE[31262] logger.c: == Done Spying on channel SIP/212-b7dc64d0
[Nov 11 19:33:27] VERBOSE[31262] logger.c: -- Playing 'beep' (language 'he')
[Nov 11 19:33:28] DEBUG[32258] chan_zap.c: Set option AUDIO MODE, value: ON(1) on Zap/4-1
[Nov 11 19:33:28] DEBUG[32258] chan_zap.c: Not yet hungup... Calling hangup once with icause, and clearing call
[Nov 11 19:33:28] DEBUG[32258] chan_zap.c: Set option AUDIO MODE, value: OFF(0) on Zap/4-1
[Nov 11 19:33:28] VERBOSE[32258] logger.c: -- Hungup 'Zap/4-1'
[Nov 11 19:33:28] VERBOSE[32258] logger.c: == Spawn extension (macro-dialout-trunk, s, 23) exited non-zero on 'SIP/219-b7dd7120' in macro 'dialout-trunk'
[Nov 11 19:33:28] VERBOSE[32258] logger.c: == Spawn extension (macro-dialout-trunk, s, 23) exited non-zero on 'SIP/219-b7dd7120'
[Nov 11 19:33:28] VERBOSE[32258] logger.c: -- Executing [h@macro-dialout-trunk:1] Macro("SIP/219-b7dd7120", "hangupcall") in new stack
[Nov 11 19:33:28] VERBOSE[32258] logger.c: -- Executing [s@macro-hangupcall:1] ResetCDR("SIP/219-b7dd7120", "w") in new stack
[Nov 11 19:33:28] DEBUG[32258] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record.
[Nov 11 19:33:28] DEBUG[32258] 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 ('2007-11-11 19:30:51','\"Ext.219\" <219>','219','039320176','from-internal', 'SIP/219-b7dd7120','Zap/4-1','Dial','ZAP/g0/039320176|300|Tt',157,121,'ANSWERED',3,'','1194802251.1417')
[Nov 11 19:33:28] DEBUG[32258] app_macro.c: Executed application: ResetCDR
[Nov 11 19:33:28] VERBOSE[32258] logger.c: -- Executing [s@macro-hangupcall:2] NoCDR("SIP/219-b7dd7120", "") in new stack
[Nov 11 19:33:28] DEBUG[32258] app_macro.c: Executed application: NoCDR
[Nov 11 19:33:28] VERBOSE[32258] logger.c: -- Executing [s@macro-hangupcall:3] GotoIf("SIP/219-b7dd7120", "1?skiprg") in new stack
[Nov 11 19:33:28] VERBOSE[32258] logger.c: -- Goto (macro-hangupcall,s,6)
[Nov 11 19:33:28] DEBUG[32258] app_macro.c: Executed application: GotoIf
[Nov 11 19:33:28] VERBOSE[32258] logger.c: -- Executing [s@macro-hangupcall:6] GotoIf("SIP/219-b7dd7120", "1?skipblkvm") in new stack
[Nov 11 19:33:28] VERBOSE[32258] logger.c: -- Goto (macro-hangupcall,s,9)
[Nov 11 19:33:28] DEBUG[32258] app_macro.c: Executed application: GotoIf
[Nov 11 19:33:28] VERBOSE[32258] logger.c: -- Executing [s@macro-hangupcall:9] GotoIf("SIP/219-b7dd7120", "1?theend") in new stack
[Nov 11 19:33:28] VERBOSE[32258] logger.c: -- Goto (macro-hangupcall,s,11)
[Nov 11 19:33:28] DEBUG[32258] app_macro.c: Executed application: GotoIf
[Nov 11 19:33:28] VERBOSE[32258] logger.c: -- Executing [s@macro-hangupcall:11] Hangup("SIP/219-b7dd7120", "") in new stack
[Nov 11 19:33:28] VERBOSE[32258] logger.c: == Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'SIP/219-b7dd7120' in macro 'hangupcall'
[Nov 11 19:33:28] VERBOSE[32258] logger.c: == Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'SIP/219-b7dd7120'
[Nov 11 19:33:28] VERBOSE[32262] logger.c: == End MixMonitor Recording SIP/219-b7dd7120
[Nov 11 19:33:28] VERBOSE[23428] logger.c: Extension Changed 219 new state Idle for Notify User 207
[Nov 11 19:33:28] VERBOSE[23428] logger.c: Extension Changed 219 new state Idle for Notify User 224
[Nov 11 19:33:28] VERBOSE[23428] logger.c: Extension Changed 219 new state Idle for Notify User 214
[Nov 11 19:33:28] VERBOSE[23428] logger.c: Extension Changed 219 new state Idle for Notify User 218
[Nov 11 19:33:28] VERBOSE[23428] logger.c: Extension Changed 219 new state Idle for Notify User 203
[Nov 11 19:33:28] VERBOSE[23428] logger.c: Extension Changed 219 new state Idle for Notify User 206
[Nov 11 19:33:28] VERBOSE[23428] logger.c: Extension Changed 219 new state Idle for Notify User 229
[Nov 11 19:33:28] VERBOSE[23428] logger.c: Extension Changed 219 new state Idle for Notify User 204
[Nov 11 19:33:28] VERBOSE[23428] logger.c: Extension Changed 219 new state Idle for Notify User 225
[Nov 11 19:33:28] VERBOSE[23428] logger.c: Extension Changed 219 new state Idle for Notify User 228
[Nov 11 19:33:28] VERBOSE[23428] logger.c: Extension Changed 219 new state Idle for Notify User 226
[Nov 11 19:33:28] VERBOSE[23428] logger.c: Extension Changed 219 new state Idle for Notify User 201
[Nov 11 19:33:28] VERBOSE[23428] logger.c: Extension Changed 219 new state Idle for Notify User 220
[Nov 11 19:33:28] VERBOSE[23428] logger.c: Extension Changed 219 new state Idle for Notify User 219
[Nov 11 19:33:28] VERBOSE[23428] logger.c: Extension Changed 219 new state Idle for Notify User 211
[Nov 11 19:33:28] VERBOSE[23428] logger.c: Extension Changed 219 new state Idle for Notify User 216
[Nov 11 19:33:28] VERBOSE[23428] logger.c: Extension Changed 219 new state Idle for Notify User 212
[Nov 11 19:33:28] VERBOSE[23428] logger.c: Extension Changed 219 new state Idle for Notify User 208
[Nov 11 19:33:28] VERBOSE[23428] logger.c: Extension Changed 219 new state Idle for Notify User 213
[Nov 11 19:33:28] VERBOSE[23428] logger.c: Extension Changed 219 new state Idle for Notify User 223
[Nov 11 19:33:28] VERBOSE[23428] logger.c: Extension Changed 219 new state Idle for Notify User 210
[Nov 11 19:33:28] VERBOSE[23428] logger.c: Extension Changed 219 new state Idle for Notify User 230
[Nov 11 19:33:28] VERBOSE[23428] logger.c: Extension Changed 219 new state Idle for Notify User 227
[Nov 11 19:33:28] VERBOSE[23428] logger.c: Extension Changed 219 new state Idle for Notify User 215
[Nov 11 19:33:28] VERBOSE[23428] logger.c: Extension Changed 219 new state Idle for Notify User 221
[Nov 11 19:33:29] VERBOSE[23426] logger.c: -- Remote UNIX connection
[Nov 11 19:33:29] VERBOSE[32486] logger.c: -- Remote UNIX connection disconnected
[Nov 11 19:33:31] VERBOSE[23426] logger.c: -- Remote UNIX connection
[Nov 11 19:33:31] VERBOSE[32489] logger.c: -- Remote UNIX connection disconnected
[Nov 11 19:33:33] VERBOSE[31262] logger.c: -- Playing 'spy-sip' (language 'he')
[Nov 11 19:33:34] VERBOSE[31262] logger.c: -- Playing 'digits/2' (language 'he')
[Nov 11 19:33:34] VERBOSE[31262] logger.c: -- Playing 'digits/1' (language 'he')
[Nov 11 19:33:35] VERBOSE[23426] logger.c: -- Remote UNIX connection
[Nov 11 19:33:35] VERBOSE[32491] logger.c: -- Remote UNIX connection disconnected
[Nov 11 19:33:35] DEBUG[32449] chan_zap.c: Set option AUDIO MODE, value: ON(1) on Zap/5-1
[Nov 11 19:33:35] DEBUG[32449] chan_zap.c: Not yet hungup... Calling hangup once with icause, and clearing call
[Nov 11 19:33:35] DEBUG[32449] chan_zap.c: Set option AUDIO MODE, value: OFF(0) on Zap/5-1
[Nov 11 19:33:35] VERBOSE[32449] logger.c: -- Hungup 'Zap/5-1'
[Nov 11 19:33:35] VERBOSE[32449] logger.c: == Spawn extension (macro-dialout-trunk, s, 23) exited non-zero on 'SIP/212-b7dc64d0' in macro 'dialout-trunk'
[Nov 11 19:33:35] VERBOSE[32449] logger.c: == Spawn extension (macro-dialout-trunk, s, 23) exited non-zero on 'SIP/212-b7dc64d0'
[Nov 11 19:33:35] VERBOSE[32449] logger.c: -- Executing [h@macro-dialout-trunk:1] Macro("SIP/212-b7dc64d0", "hangupcall") in new stack
[Nov 11 19:33:35] VERBOSE[32449] logger.c: -- Executing [s@macro-hangupcall:1] ResetCDR("SIP/212-b7dc64d0", "w") in new stack
[Nov 11 19:33:35] DEBUG[32449] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record.
[Nov 11 19:33:35] DEBUG[32449] 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 ('2007-11-11 19:32:56','\"Ext.212\" <212>','212','036161415','from-internal', 'SIP/212-b7dc64d0','Zap/5-1','Dial','ZAP/g0/036161415|300|Tt',39,24,'ANSWERED',3,'','1194802376.1449')
[Nov 11 19:33:35] DEBUG[32449] app_macro.c: Executed application: ResetCDR
[Nov 11 19:33:35] VERBOSE[32449] logger.c: -- Executing [s@macro-hangupcall:2] NoCDR("SIP/212-b7dc64d0", "") in new stack
[Nov 11 19:33:35] DEBUG[32449] app_macro.c: Executed application: NoCDR
[Nov 11 19:33:35] VERBOSE[32449] logger.c: -- Executing [s@macro-hangupcall:3] GotoIf("SIP/212-b7dc64d0", "1?skiprg") in new stack
[Nov 11 19:33:35] VERBOSE[32449] logger.c: -- Goto (macro-hangupcall,s,6)
[Nov 11 19:33:35] DEBUG[32449] app_macro.c: Executed application: GotoIf
[Nov 11 19:33:35] VERBOSE[32449] logger.c: -- Executing [s@macro-hangupcall:6] GotoIf("SIP/212-b7dc64d0", "1?skipblkvm") in new stack
[Nov 11 19:33:35] VERBOSE[32449] logger.c: -- Goto (macro-hangupcall,s,9)
[Nov 11 19:33:35] DEBUG[32449] app_macro.c: Executed application: GotoIf
[Nov 11 19:33:35] VERBOSE[32449] logger.c: -- Executing [s@macro-hangupcall:9] GotoIf("SIP/212-b7dc64d0", "1?theend") in new stack
[Nov 11 19:33:35] VERBOSE[32449] logger.c: -- Goto (macro-hangupcall,s,11)
[Nov 11 19:33:35] DEBUG[32449] app_macro.c: Executed application: GotoIf
[Nov 11 19:33:35] VERBOSE[32449] logger.c: -- Executing [s@macro-hangupcall:11] Hangup("SIP/212-b7dc64d0", "") in new stack
[Nov 11 19:33:35] VERBOSE[32449] logger.c: == Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'SIP/212-b7dc64d0' in macro 'hangupcall'
[Nov 11 19:33:35] VERBOSE[32449] logger.c: == Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'SIP/212-b7dc64d0'
[Nov 11 19:33:35] VERBOSE[32452] logger.c: == End MixMonitor Recording SIP/212-b7dc64d0
[Nov 11 19:33:35] VERBOSE[23428] logger.c: Extension Changed 212 new state Idle for Notify User 207
[Nov 11 19:33:35] VERBOSE[23428] logger.c: Extension Changed 212 new state Idle for Notify User 224
[Nov 11 19:33:35] VERBOSE[23428] logger.c: Extension Changed 212 new state Idle for Notify User 214
[Nov 11 19:33:35] VERBOSE[23428] logger.c: Extension Changed 212 new state Idle for Notify User 218
[Nov 11 19:33:35] VERBOSE[23428] logger.c: Extension Changed 212 new state Idle for Notify User 203
[Nov 11 19:33:35] VERBOSE[23428] logger.c: Extension Changed 212 new state Idle for Notify User 206
[Nov 11 19:33:35] VERBOSE[23428] logger.c: Extension Changed 212 new state Idle for Notify User 229
[Nov 11 19:33:35] VERBOSE[23428] logger.c: Extension Changed 212 new state Idle for Notify User 204
[Nov 11 19:33:35] VERBOSE[23428] logger.c: Extension Changed 212 new state Idle for Notify User 225
[Nov 11 19:33:35] VERBOSE[23428] logger.c: Extension Changed 212 new state Idle for Notify User 228
[Nov 11 19:33:35] VERBOSE[23428] logger.c: Extension Changed 212 new state Idle for Notify User 226
[Nov 11 19:33:35] VERBOSE[23428] logger.c: Extension Changed 212 new state Idle for Notify User 201
[Nov 11 19:33:35] VERBOSE[23428] logger.c: Extension Changed 212 new state Idle for Notify User 220
[Nov 11 19:33:35] VERBOSE[23428] logger.c: Extension Changed 212 new state Idle for Notify User 219
[Nov 11 19:33:35] VERBOSE[23428] logger.c: Extension Changed 212 new state Idle for Notify User 211
[Nov 11 19:33:35] VERBOSE[23428] logger.c: Extension Changed 212 new state Idle for Notify User 216
[Nov 11 19:33:35] VERBOSE[23428] logger.c: Extension Changed 212 new state Idle for Notify User 212
[Nov 11 19:33:35] VERBOSE[23428] logger.c: Extension Changed 212 new state Idle for Notify User 208
[Nov 11 19:33:35] VERBOSE[23428] logger.c: Extension Changed 212 new state Idle for Notify User 213
[Nov 11 19:33:35] VERBOSE[23428] logger.c: Extension Changed 212 new state Idle for Notify User 223
[Nov 11 19:33:35] VERBOSE[23428] logger.c: Extension Changed 212 new state Idle for Notify User 210
[Nov 11 19:33:35] VERBOSE[23428] logger.c: Extension Changed 212 new state Idle for Notify User 230
[Nov 11 19:33:35] VERBOSE[23428] logger.c: Extension Changed 212 new state Idle for Notify User 227
[Nov 11 19:33:35] VERBOSE[23428] logger.c: Extension Changed 212 new state Idle for Notify User 215
[Nov 11 19:33:35] VERBOSE[23428] logger.c: Extension Changed 212 new state Idle for Notify User 221
[Nov 11 19:33:35] VERBOSE[31262] logger.c: -- Playing 'digits/2' (language 'he')
[Nov 11 19:33:36] VERBOSE[31262] logger.c: -- Playing 'beep' (language 'he')
[Nov 11 19:33:37] VERBOSE[31262] logger.c: -- Playing 'spy-sip' (language 'he')
[Nov 11 19:33:37] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 207
[Nov 11 19:33:37] VERBOSE[32496] logger.c: -- Executing [035783391@from-internal:1] Macro("SIP/213-b7de1d70", "dialout-trunk|1|035783391||") in new stack
[Nov 11 19:33:37] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 224
[Nov 11 19:33:37] VERBOSE[32496] logger.c: -- Executing [s@macro-dialout-trunk:1] Set("SIP/213-b7de1d70", "DIAL_TRUNK=1") in new stack
[Nov 11 19:33:37] DEBUG[32496] app_macro.c: Executed application: Set
[Nov 11 19:33:37] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 214
[Nov 11 19:33:37] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 218
[Nov 11 19:33:37] VERBOSE[32496] logger.c: -- Executing [s@macro-dialout-trunk:2] Set("SIP/213-b7de1d70", "DIAL_NUMBER=035783391") in new stack
[Nov 11 19:33:37] DEBUG[32496] app_macro.c: Executed application: Set
[Nov 11 19:33:37] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 203
[Nov 11 19:33:37] VERBOSE[32496] logger.c: -- Executing [s@macro-dialout-trunk:3] Set("SIP/213-b7de1d70", "ROUTE_PASSWD=") in new stack
[Nov 11 19:33:37] DEBUG[32496] app_macro.c: Executed application: Set
[Nov 11 19:33:37] VERBOSE[32496] logger.c: -- Executing [s@macro-dialout-trunk:4] GotoIf("SIP/213-b7de1d70", "1?noauth") in new stack
[Nov 11 19:33:37] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 206
[Nov 11 19:33:37] VERBOSE[32496] logger.c: -- Goto (macro-dialout-trunk,s,6)
[Nov 11 19:33:37] DEBUG[32496] app_macro.c: Executed application: GotoIf
[Nov 11 19:33:37] VERBOSE[32496] logger.c: -- Executing [s@macro-dialout-trunk:6] GotoIf("SIP/213-b7de1d70", "0?disabletrunk|1") in new stack
[Nov 11 19:33:37] DEBUG[32496] app_macro.c: Executed application: GotoIf
[Nov 11 19:33:37] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 229
[Nov 11 19:33:37] VERBOSE[32496] logger.c: -- Executing [s@macro-dialout-trunk:7] Set("SIP/213-b7de1d70", "_NODEST=") in new stack
[Nov 11 19:33:37] DEBUG[32496] app_macro.c: Executed application: Set
[Nov 11 19:33:37] VERBOSE[32496] logger.c: -- Executing [s@macro-dialout-trunk:8] Set("SIP/213-b7de1d70", "DIAL_TRUNK_OPTIONS=Tt") in new stack
[Nov 11 19:33:37] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 204
[Nov 11 19:33:37] DEBUG[32496] app_macro.c: Executed application: Set
[Nov 11 19:33:37] VERBOSE[32496] logger.c: -- Executing [s@macro-dialout-trunk:9] Set("SIP/213-b7de1d70", "GROUP()=OUT_1") in new stack
[Nov 11 19:33:37] DEBUG[32496] app_macro.c: Executed application: Set
[Nov 11 19:33:37] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 225
[Nov 11 19:33:37] VERBOSE[32496] logger.c: -- Executing [s@macro-dialout-trunk:10] Macro("SIP/213-b7de1d70", "user-callerid|SKIPTTL") in new stack
[Nov 11 19:33:37] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 228
[Nov 11 19:33:37] VERBOSE[32496] logger.c: -- Executing [s@macro-user-callerid:1] NoOp("SIP/213-b7de1d70", "user-callerid: device 213") in new stack
[Nov 11 19:33:37] DEBUG[32496] app_macro.c: Executed application: Noop
[Nov 11 19:33:37] VERBOSE[32496] logger.c: -- Executing [s@macro-user-callerid:2] Set("SIP/213-b7de1d70", "AMPUSER=213") in new stack
[Nov 11 19:33:37] DEBUG[32496] app_macro.c: Executed application: Set
[Nov 11 19:33:37] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 226
[Nov 11 19:33:37] VERBOSE[32496] logger.c: -- Executing [s@macro-user-callerid:3] GotoIf("SIP/213-b7de1d70", "0?report") in new stack
[Nov 11 19:33:37] DEBUG[32496] app_macro.c: Executed application: GotoIf
[Nov 11 19:33:37] VERBOSE[32496] logger.c: -- Executing [s@macro-user-callerid:4] GotoIf("SIP/213-b7de1d70", "0?start") in new stack
[Nov 11 19:33:37] DEBUG[32496] app_macro.c: Executed application: GotoIf
[Nov 11 19:33:37] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 201
[Nov 11 19:33:37] VERBOSE[32496] logger.c: -- Executing [s@macro-user-callerid:5] Set("SIP/213-b7de1d70", "REALCALLERIDNUM=213") in new stack
[Nov 11 19:33:37] DEBUG[32496] app_macro.c: Executed application: Set
[Nov 11 19:33:37] VERBOSE[32496] logger.c: -- Executing [s@macro-user-callerid:6] NoOp("SIP/213-b7de1d70", "REALCALLERIDNUM is 213") in new stack
[Nov 11 19:33:37] DEBUG[32496] app_macro.c: Executed application: NoOp
[Nov 11 19:33:37] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 220
[Nov 11 19:33:37] VERBOSE[32496] logger.c: -- Executing [s@macro-user-callerid:7] Set("SIP/213-b7de1d70", "AMPUSER=213") in new stack
[Nov 11 19:33:37] DEBUG[32496] app_macro.c: Executed application: Set
[Nov 11 19:33:37] VERBOSE[32496] logger.c: -- Executing [s@macro-user-callerid:8] Set("SIP/213-b7de1d70", "AMPUSERCIDNAME=Ext.213") in new stack
[Nov 11 19:33:37] DEBUG[32496] app_macro.c: Executed application: Set
[Nov 11 19:33:37] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 219
[Nov 11 19:33:37] VERBOSE[32496] logger.c: -- Executing [s@macro-user-callerid:9] GotoIf("SIP/213-b7de1d70", "0?report") in new stack
[Nov 11 19:33:37] DEBUG[32496] app_macro.c: Executed application: GotoIf
[Nov 11 19:33:37] DEBUG[32496] db.c: Unable to find key '213/cidnum' in family 'AMPUSER'
[Nov 11 19:33:37] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 211
[Nov 11 19:33:37] VERBOSE[32496] logger.c: -- Executing [s@macro-user-callerid:10] Set("SIP/213-b7de1d70", "AMPUSERCID=213") in new stack
[Nov 11 19:33:37] DEBUG[32496] app_macro.c: Executed application: Set
[Nov 11 19:33:37] VERBOSE[32496] logger.c: -- Executing [s@macro-user-callerid:11] Set("SIP/213-b7de1d70", "CALLERID(all)="Ext.213" <213>") in new stack
[Nov 11 19:33:37] DEBUG[32496] app_macro.c: Executed application: Set
[Nov 11 19:33:37] VERBOSE[32496] logger.c: -- Executing [s@macro-user-callerid:12] Set("SIP/213-b7de1d70", "REALCALLERIDNUM=213") in new stack
[Nov 11 19:33:37] DEBUG[32496] app_macro.c: Executed application: Set
[Nov 11 19:33:37] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 216
[Nov 11 19:33:37] VERBOSE[32496] logger.c: -- Executing [s@macro-user-callerid:13] NoOp("SIP/213-b7de1d70", "TTL: ARG1: SKIPTTL") in new stack
[Nov 11 19:33:37] DEBUG[32496] app_macro.c: Executed application: Noop
[Nov 11 19:33:37] VERBOSE[32496] logger.c: -- Executing [s@macro-user-callerid:14] GotoIf("SIP/213-b7de1d70", "1?continue") in new stack
[Nov 11 19:33:37] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 212
[Nov 11 19:33:37] VERBOSE[32496] logger.c: -- Goto (macro-user-callerid,s,23)
[Nov 11 19:33:37] DEBUG[32496] app_macro.c: Executed application: GotoIf
[Nov 11 19:33:37] VERBOSE[32496] logger.c: -- Executing [s@macro-user-callerid:23] NoOp("SIP/213-b7de1d70", "Using CallerID "Ext.213" <213>") in new stack
[Nov 11 19:33:37] DEBUG[32496] app_macro.c: Executed application: NoOp
[Nov 11 19:33:37] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 208
[Nov 11 19:33:37] DEBUG[32496] app_macro.c: Executed application: Macro
[Nov 11 19:33:37] VERBOSE[32496] logger.c: -- Executing [s@macro-dialout-trunk:11] Macro("SIP/213-b7de1d70", "record-enable|213|OUT") in new stack
[Nov 11 19:33:37] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 213
[Nov 11 19:33:37] VERBOSE[32496] logger.c: -- Executing [s@macro-record-enable:1] GotoIf("SIP/213-b7de1d70", "0?2:4") in new stack
[Nov 11 19:33:37] VERBOSE[32496] logger.c: -- Goto (macro-record-enable,s,4)
[Nov 11 19:33:37] DEBUG[32496] app_macro.c: Executed application: GotoIf
[Nov 11 19:33:37] VERBOSE[32496] logger.c: -- Executing [s@macro-record-enable:4] AGI("SIP/213-b7de1d70", "recordingcheck|20071111-193337|1194802417.1453") in new stack
[Nov 11 19:33:37] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 223
[Nov 11 19:33:37] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 210
[Nov 11 19:33:37] VERBOSE[32496] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
[Nov 11 19:33:37] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 230
[Nov 11 19:33:37] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 227
[Nov 11 19:33:37] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 215
[Nov 11 19:33:37] VERBOSE[23428] logger.c: Extension Changed 213 new state InUse for Notify User 221
[Nov 11 19:33:37] VERBOSE[32496] logger.c: recordingcheck|20071111-193337|1194802417.1453: Outbound recording enabled.
[Nov 11 19:33:37] VERBOSE[32496] logger.c: recordingcheck|20071111-193337|1194802417.1453: CALLFILENAME=OUT213-20071111-193337-1194802417.1453
[Nov 11 19:33:37] VERBOSE[32496] logger.c: -- AGI Script recordingcheck completed, returning 0
[Nov 11 19:33:37] DEBUG[32496] app_macro.c: Executed application: AGI
[Nov 11 19:33:37] VERBOSE[32496] logger.c: -- Executing [s@macro-record-enable:999] MixMonitor("SIP/213-b7de1d70", "OUT213-20071111-193337-1194802417.1453.WAV") in new stack
[Nov 11 19:33:37] DEBUG[32496] app_macro.c: Executed application: MixMonitor
[Nov 11 19:33:37] DEBUG[32496] app_macro.c: Executed application: Macro
[Nov 11 19:33:37] VERBOSE[32496] logger.c: -- Executing [s@macro-dialout-trunk:12] GotoIf("SIP/213-b7de1d70", "0?skipoutcid") in new stack
[Nov 11 19:33:37] DEBUG[32496] app_macro.c: Executed application: GotoIf
[Nov 11 19:33:37] VERBOSE[32496] logger.c: -- Executing [s@macro-dialout-trunk:13] Set("SIP/213-b7de1d70", "DIAL_TRUNK_OPTIONS=Tt") in new stack
[Nov 11 19:33:37] DEBUG[32496] app_macro.c: Executed application: Set
[Nov 11 19:33:37] VERBOSE[32496] logger.c: -- Executing [s@macro-dialout-trunk:14] Macro("SIP/213-b7de1d70", "outbound-callerid|1") in new stack
[Nov 11 19:33:37] VERBOSE[32496] logger.c: -- Executing [s@macro-outbound-callerid:1] GotoIf("SIP/213-b7de1d70", "1?start") in new stack
[Nov 11 19:33:37] VERBOSE[32496] logger.c: -- Goto (macro-outbound-callerid,s,3)
[Nov 11 19:33:37] DEBUG[32496] app_macro.c: Executed application: GotoIf
[Nov 11 19:33:37] VERBOSE[32496] logger.c: -- Executing [s@macro-outbound-callerid:3] NoOp("SIP/213-b7de1d70", "REALCALLERIDNUM is 213") in new stack
[Nov 11 19:33:37] DEBUG[32496] app_macro.c: Executed application: NoOp
[Nov 11 19:33:37] VERBOSE[32496] logger.c: -- Executing [s@macro-outbound-callerid:4] GotoIf("SIP/213-b7de1d70", "1?normcid") in new stack
[Nov 11 19:33:37] VERBOSE[32496] logger.c: -- Goto (macro-outbound-callerid,s,9)
[Nov 11 19:33:37] DEBUG[32496] app_macro.c: Executed application: GotoIf
[Nov 11 19:33:37] VERBOSE[32496] logger.c: -- Executing [s@macro-outbound-callerid:9] Set("SIP/213-b7de1d70", "USEROUTCID=") in new stack
[Nov 11 19:33:37] DEBUG[32496] app_macro.c: Executed application: Set
[Nov 11 19:33:37] DEBUG[32496] db.c: Unable to find key '213/emergency_cid' in family 'DEVICE'
[Nov 11 19:33:37] DEBUG[32496] func_db.c: DB: DEVICE/213/emergency_cid not found in database.
[Nov 11 19:33:37] VERBOSE[32496] logger.c: -- Executing [s@macro-outbound-callerid:10] Set("SIP/213-b7de1d70", "EMERGENCYCID=") in new stack
[Nov 11 19:33:37] DEBUG[32496] app_macro.c: Executed application: Set
[Nov 11 19:33:37] VERBOSE[32496] logger.c: -- Executing [s@macro-outbound-callerid:11] Set("SIP/213-b7de1d70", "TRUNKOUTCID=") in new stack
[Nov 11 19:33:37] DEBUG[32496] app_macro.c: Executed application: Set
[Nov 11 19:33:37] VERBOSE[32496] logger.c: -- Executing [s@macro-outbound-callerid:12] GotoIf("SIP/213-b7de1d70", "1?trunkcid") in new stack
[Nov 11 19:33:37] VERBOSE[32496] logger.c: -- Goto (macro-outbound-callerid,s,16)
[Nov 11 19:33:37] DEBUG[32496] app_macro.c: Executed application: GotoIf
[Nov 11 19:33:37] VERBOSE[32496] logger.c: -- Executing [s@macro-outbound-callerid:16] GotoIf("SIP/213-b7de1d70", "1?usercid") in new stack
[Nov 11 19:33:37] VERBOSE[32496] logger.c: -- Goto (macro-outbound-callerid,s,18)
[Nov 11 19:33:37] DEBUG[32496] app_macro.c: Executed application: GotoIf
[Nov 11 19:33:37] VERBOSE[32496] logger.c: -- Executing [s@macro-outbound-callerid:18] GotoIf("SIP/213-b7de1d70", "1?report") in new stack
[Nov 11 19:33:37] VERBOSE[32496] logger.c: -- Goto (macro-outbound-callerid,s,22)
[Nov 11 19:33:37] DEBUG[32496] app_macro.c: Executed application: GotoIf
[Nov 11 19:33:37] VERBOSE[32496] logger.c: -- Executing [s@macro-outbound-callerid:22] NoOp("SIP/213-b7de1d70", "CallerID set to "Ext.213" <213>") in new stack
[Nov 11 19:33:37] DEBUG[32496] app_macro.c: Executed application: NoOp
[Nov 11 19:33:37] DEBUG[32496] app_macro.c: Executed application: Macro
[Nov 11 19:33:37] VERBOSE[32496] logger.c: -- Executing [s@macro-dialout-trunk:15] GotoIf("SIP/213-b7de1d70", "1?nomax") in new stack
[Nov 11 19:33:37] VERBOSE[32496] logger.c: -- Goto (macro-dialout-trunk,s,17)
[Nov 11 19:33:37] DEBUG[32496] app_macro.c: Executed application: GotoIf
[Nov 11 19:33:37] VERBOSE[32499] logger.c: == Begin MixMonitor Recording SIP/213-b7de1d70
[Nov 11 19:33:37] VERBOSE[32496] logger.c: -- Executing [s@macro-dialout-trunk:17] AGI("SIP/213-b7de1d70", "fixlocalprefix") in new stack
[Nov 11 19:33:37] VERBOSE[32496] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/fixlocalprefix
[Nov 11 19:33:37] VERBOSE[32496] logger.c: > fixlocalprefix: Using pattern .
[Nov 11 19:33:37] VERBOSE[32496] logger.c: == fixlocalprefix: Dialpattern . matched. 035783391 -> 035783391
[Nov 11 19:33:37] VERBOSE[32496] logger.c: -- AGI Script fixlocalprefix completed, returning 0
[Nov 11 19:33:37] DEBUG[32496] app_macro.c: Executed application: AGI
[Nov 11 19:33:37] VERBOSE[32496] logger.c: -- Executing [s@macro-dialout-trunk:18] Set("SIP/213-b7de1d70", "OUTNUM=035783391") in new stack
[Nov 11 19:33:37] DEBUG[32496] app_macro.c: Executed application: Set
[Nov 11 19:33:37] VERBOSE[32496] logger.c: -- Executing [s@macro-dialout-trunk:19] Set("SIP/213-b7de1d70", "custom=ZAP/g0") in new stack
[Nov 11 19:33:37] DEBUG[32496] app_macro.c: Executed application: Set
[Nov 11 19:33:37] VERBOSE[32496] logger.c: -- Executing [s@macro-dialout-trunk:20] GotoIf("SIP/213-b7de1d70", "1?gocall") in new stack
[Nov 11 19:33:37] VERBOSE[32496] logger.c: -- Goto (macro-dialout-trunk,s,22)
[Nov 11 19:33:37] DEBUG[32496] app_macro.c: Executed application: GotoIf
[Nov 11 19:33:37] VERBOSE[32496] logger.c: -- Executing [s@macro-dialout-trunk:22] GotoIf("SIP/213-b7de1d70", "0?customtrunk") in new stack
[Nov 11 19:33:37] DEBUG[32496] app_macro.c: Executed application: GotoIf
[Nov 11 19:33:37] VERBOSE[32496] logger.c: -- Executing [s@macro-dialout-trunk:23] Dial("SIP/213-b7de1d70", "ZAP/g0/035783391|300|Tt") in new stack
[Nov 11 19:33:37] VERBOSE[32496] logger.c: -- Requested transfer capability: 0x00 - SPEECH
[Nov 11 19:33:37] VERBOSE[32496] logger.c: -- Called g0/035783391
[Nov 11 19:33:37] DEBUG[23454] chan_zap.c: Queuing frame from PRI_EVENT_PROCEEDING on channel 0/2 span 1
[Nov 11 19:33:37] VERBOSE[32496] logger.c: -- Zap/2-1 is proceeding passing it to SIP/213-b7de1d70
[Nov 11 19:33:37] VERBOSE[31262] logger.c: -- Playing 'digits/2' (language 'he')
[Nov 11 19:33:38] VERBOSE[23426] logger.c: -- Remote UNIX connection
[Nov 11 19:33:38] VERBOSE[32503] logger.c: -- Remote UNIX connection disconnected
[Nov 11 19:33:38] VERBOSE[32496] logger.c: -- Zap/2-1 is ringing
[Nov 11 19:33:38] VERBOSE[31262] logger.c: -- Playing 'digits/1' (language 'he')
[Nov 11 19:33:39] VERBOSE[31262] logger.c: -- Playing 'digits/6' (language 'he')
[Nov 11 19:33:39] VERBOSE[31262] logger.c: == Spying on channel SIP/216-b7d96730
[Nov 11 19:33:39] NOTICE[31262] app_chanspy.c: Attaching Zap/6-1 to SIP/216-b7d96730
[Nov 11 19:33:41] VERBOSE[23428] logger.c: Extension Changed 219 new state InUse for Notify User 207
[Nov 11 19:33:41] VERBOSE[32506] logger.c: -- Executing [035787390@from-internal:1] Macro("SIP/219-b7dd7120", "dialout-trunk|1|035787390||") in new stack
[Nov 11 19:33:41] VERBOSE[23428] logger.c: Extension Changed 219 new state InUse for Notify User 224
[Nov 11 19:33:41] VERBOSE[32506] logger.c: -- Executing [s@macro-dialout-trunk:1] Set("SIP/219-b7dd7120", "DIAL_TRUNK=1") in new stack
[Nov 11 19:33:41] DEBUG[32506] app_macro.c: Executed application: Set
[Nov 11 19:33:41] VERBOSE[32506] logger.c: -- Executing [s@macro-dialout-trunk:2] Set("SIP/219-b7dd7120", "DIAL_NUMBER=035787390") in new stack
[Nov 11 19:33:41] VERBOSE[23428] logger.c: Extension Changed 219 new state InUse for Notify User 214
[Nov 11 19:33:41] DEBUG[32506] app_macro.c: Executed application: Set
[Nov 11 19:33:41] VERBOSE[32506] logger.c: -- Executing [s@macro-dialout-trunk:3] Set("SIP/219-b7dd7120", "ROUTE_PASSWD=") in new stack
[Nov 11 19:33:41] DEBUG[32506] app_macro.c: Executed application: Set
[Nov 11 19:33:41] VERBOSE[23428] logger.c: Extension Changed 219 new state InUse for Notify User 218
[Nov 11 19:33:41] VERBOSE[32506] logger.c: -- Executing [s@macro-dialout-trunk:4] GotoIf("SIP/219-b7dd7120", "1?noauth") in new stack
[Nov 11 19:33:41] VERBOSE[32506] logger.c: -- Goto (macro-dialout-trunk,s,6)
[Nov 11 19:33:41] DEBUG[32506] app_macro.c: Executed application: GotoIf
[Nov 11 19:33:41] VERBOSE[23428] logger.c: Extension Changed 219 new state InUse for Notify User 203
[Nov 11 19:33:41] VERBOSE[32506] logger.c: -- Executing [s@macro-dialout-trunk:6] GotoIf("SIP/219-b7dd7120", "0?disabletrunk|1") in new stack
[Nov 11 19:33:41] DEBUG[32506] app_macro.c: Executed application: GotoIf
[Nov 11 19:33:41] VERBOSE[32506] logger.c: -- Executing [s@macro-dialout-trunk:7] Set("SIP/219-b7dd7120", "_NODEST=") in new stack
[Nov 11 19:33:41] DEBUG[32506] app_macro.c: Executed application: Set
[Nov 11 19:33:41] VERBOSE[23428] logger.c: Extension Changed 219 new state InUse for Notify User 206
[Nov 11 19:33:41] VERBOSE[32506] logger.c: -- Executing [s@macro-dialout-trunk:8] Set("SIP/219-b7dd7120", "DIAL_TRUNK_OPTIONS=Tt") in new stack
[Nov 11 19:33:41] DEBUG[32506] app_macro.c: Executed application: Set
[Nov 11 19:33:41] VERBOSE[32506] logger.c: -- Executing [s@macro-dialout-trunk:9] Set("SIP/219-b7dd7120", "GROUP()=OUT_1") in new stack
[Nov 11 19:33:41] VERBOSE[23428] logger.c: Extension Changed 219 new state InUse for Notify User 229
[Nov 11 19:33:41] DEBUG[32506] app_macro.c: Executed application: Set
[Nov 11 19:33:41] VERBOSE[32506] logger.c: -- Executing [s@macro-dialout-trunk:10] Macro("SIP/219-b7dd7120", "user-callerid|SKIPTTL") in new stack
[Nov 11 19:33:41] VERBOSE[23428] logger.c: Extension Changed 219 new state InUse for Notify User 204
[Nov 11 19:33:41] VERBOSE[32506] logger.c: -- Executing [s@macro-user-callerid:1] NoOp("SIP/219-b7dd7120", "user-callerid: device 219") in new stack
[Nov 11 19:33:41] DEBUG[32506] app_macro.c: Executed application: Noop
[Nov 11 19:33:41] VERBOSE[23428] logger.c: Extension Changed 219 new state InUse for Notify User 225
[Nov 11 19:33:41] VERBOSE[32506] logger.c: -- Executing [s@macro-user-callerid:2] Set("SIP/219-b7dd7120", "AMPUSER=219") in new stack
[Nov 11 19:33:41] DEBUG[32506] app_macro.c: Executed application: Set
[Nov 11 19:33:41] VERBOSE[32506] logger.c: -- Executing [s@macro-user-callerid:3] GotoIf("SIP/219-b7dd7120", "0?report") in new stack
[Nov 11 19:33:41] VERBOSE[23428] logger.c: Extension Changed 219 new state InUse for Notify User 228
[Nov 11 19:33:41] DEBUG[32506] app_macro.c: Executed application: GotoIf
[Nov 11 19:33:41] VERBOSE[32506] logger.c: -- Executing [s@macro-user-callerid:4] GotoIf("SIP/219-b7dd7120", "0?start") in new stack
[Nov 11 19:33:41] DEBUG[32506] app_macro.c: Executed application: GotoIf
[Nov 11 19:33:41] VERBOSE[23428] logger.c: Extension Changed 219 new state InUse for Notify User 226
[Nov 11 19:33:41] VERBOSE[32506] logger.c: -- Executing [s@macro-user-callerid:5] Set("SIP/219-b7dd7120", "REALCALLERIDNUM=219") in new stack
[Nov 11 19:33:41] DEBUG[32506] app_macro.c: Executed application: Set
[Nov 11 19:33:41] VERBOSE[32506] logger.c: -- Executing [s@macro-user-callerid:6] NoOp("SIP/219-b7dd7120", "REALCALLERIDNUM is 219") in new stack
[Nov 11 19:33:41] DEBUG[32506] app_macro.c: Executed application: NoOp
[Nov 11 19:33:41] VERBOSE[23428] logger.c: Extension Changed 219 new state InUse for Notify User 201
[Nov 11 19:33:41] VERBOSE[32506] logger.c: -- Executing [s@macro-user-callerid:7] Set("SIP/219-b7dd7120", "AMPUSER=219") in new stack
[Nov 11 19:33:41] DEBUG[32506] app_macro.c: Executed application: Set
[Nov 11 19:33:41] VERBOSE[23428] logger.c: Extension Changed 219 new state InUse for Notify User 220
[Nov 11 19:33:41] VERBOSE[32506] logger.c: -- Executing [s@macro-user-callerid:8] Set("SIP/219-b7dd7120", "AMPUSERCIDNAME=Ext.219") in new stack
[Nov 11 19:33:41] DEBUG[32506] app_macro.c: Executed application: Set
[Nov 11 19:33:41] VERBOSE[32506] logger.c: -- Executing [s@macro-user-callerid:9] GotoIf("SIP/219-b7dd7120", "0?report") in new stack
[Nov 11 19:33:41] DEBUG[32506] app_macro.c: Executed application: GotoIf
[Nov 11 19:33:41] VERBOSE[23428] logger.c: Extension Changed 219 new state InUse for Notify User 219
[Nov 11 19:33:41] DEBUG[32506] db.c: Unable to find key '219/cidnum' in family 'AMPUSER'
[Nov 11 19:33:41] VERBOSE[32506] logger.c: -- Executing [s@macro-user-callerid:10] Set("SIP/219-b7dd7120", "AMPUSERCID=219") in new stack
[Nov 11 19:33:41] DEBUG[32506] app_macro.c: Executed application: Set
[Nov 11 19:33:41] VERBOSE[32506] logger.c: -- Executing [s@macro-user-callerid:11] Set("SIP/219-b7dd7120", "CALLERID(all)="Ext.219" <219>") in new stack
[Nov 11 19:33:41] DEBUG[32506] app_macro.c: Executed application: Set
[Nov 11 19:33:41] VERBOSE[23428] logger.c: Extension Changed 219 new state InUse for Notify User 211
[Nov 11 19:33:41] VERBOSE[32506] logger.c: -- Executing [s@macro-user-callerid:12] Set("SIP/219-b7dd7120", "REALCALLERIDNUM=219") in new stack
[Nov 11 19:33:41] DEBUG[32506] app_macro.c: Executed application: Set
[Nov 11 19:33:41] VERBOSE[32506] logger.c: -- Executing [s@macro-user-callerid:13] NoOp("SIP/219-b7dd7120", "TTL: ARG1: SKIPTTL") in new stack
[Nov 11 19:33:41] VERBOSE[23428] logger.c: Extension Changed 219 new state InUse for Notify User 216
[Nov 11 19:33:41] DEBUG[32506] app_macro.c: Executed application: Noop
[Nov 11 19:33:41] VERBOSE[32506] logger.c: -- Executing [s@macro-user-callerid:14] GotoIf("SIP/219-b7dd7120", "1?continue") in new stack
[Nov 11 19:33:41] VERBOSE[23428] logger.c: Extension Changed 219 new state InUse for Notify User 212
[Nov 11 19:33:41] VERBOSE[32506] logger.c: -- Goto (macro-user-callerid,s,23)
[Nov 11 19:33:41] DEBUG[32506] app_macro.c: Executed application: GotoIf
[Nov 11 19:33:41] VERBOSE[32506] logger.c: -- Executing [s@macro-user-callerid:23] NoOp("SIP/219-b7dd7120", "Using CallerID "Ext.219" <219>") in new stack
[Nov 11 19:33:41] DEBUG[32506] app_macro.c: Executed application: NoOp
[Nov 11 19:33:41] VERBOSE[23428] logger.c: Extension Changed 219 new state InUse for Notify User 208
[Nov 11 19:33:41] DEBUG[32506] app_macro.c: Executed application: Macro
[Nov 11 19:33:41] VERBOSE[32506] logger.c: -- Executing [s@macro-dialout-trunk:11] Macro("SIP/219-b7dd7120", "record-enable|219|OUT") in new stack
[Nov 11 19:33:41] VERBOSE[23428] logger.c: Extension Changed 219 new state InUse for Notify User 213
[Nov 11 19:33:41] VERBOSE[32506] logger.c: -- Executing [s@macro-record-enable:1] GotoIf("SIP/219-b7dd7120", "0?2:4") in new stack
[Nov 11 19:33:41] VERBOSE[32506] logger.c: -- Goto (macro-record-enable,s,4)
[Nov 11 19:33:41] DEBUG[32506] app_macro.c: Executed application: GotoIf
[Nov 11 19:33:41] VERBOSE[32506] logger.c: -- Executing [s@macro-record-enable:4] AGI("SIP/219-b7dd7120", "recordingcheck|20071111-193341|1194802421.1455") in new stack
[Nov 11 19:33:41] VERBOSE[23428] logger.c: Extension Changed 219 new state InUse for Notify User 223
[Nov 11 19:33:41] VERBOSE[23428] logger.c: Extension Changed 219 new state InUse for Notify User 210
[Nov 11 19:33:41] VERBOSE[23428] logger.c: Extension Changed 219 new state InUse for Notify User 230
[Nov 11 19:33:41] VERBOSE[23428] logger.c: Extension Changed 219 new state InUse for Notify User 227
[Nov 11 19:33:41] VERBOSE[23428] logger.c: Extension Changed 219 new state InUse for Notify User 215
[Nov 11 19:33:41] VERBOSE[23428] logger.c: Extension Changed 219 new state InUse for Notify User 221
[Nov 11 19:33:41] VERBOSE[32506] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
[Nov 11 19:33:41] VERBOSE[32506] logger.c: recordingcheck|20071111-193341|1194802421.1455: Outbound recording enabled.
[Nov 11 19:33:41] VERBOSE[32506] logger.c: recordingcheck|20071111-193341|1194802421.1455: CALLFILENAME=OUT219-20071111-193341-1194802421.1455
[Nov 11 19:33:41] VERBOSE[32506] logger.c: -- AGI Script recordingcheck completed, returning 0
[Nov 11 19:33:41] DEBUG[32506] app_macro.c: Executed application: AGI
[Nov 11 19:33:41] VERBOSE[32506] logger.c: -- Executing [s@macro-record-enable:999] MixMonitor("SIP/219-b7dd7120", "OUT219-20071111-193341-1194802421.1455.WAV") in new stack
[Nov 11 19:33:41] DEBUG[32506] app_macro.c: Executed application: MixMonitor
[Nov 11 19:33:41] DEBUG[32506] app_macro.c: Executed application: Macro
[Nov 11 19:33:41] VERBOSE[32506] logger.c: -- Executing [s@macro-dialout-trunk:12] GotoIf("SIP/219-b7dd7120", "0?skipoutcid") in new stack
[Nov 11 19:33:41] DEBUG[32506] app_macro.c: Executed application: GotoIf
[Nov 11 19:33:41] VERBOSE[32506] logger.c: -- Executing [s@macro-dialout-trunk:13] Set("SIP/219-b7dd7120", "DIAL_TRUNK_OPTIONS=Tt") in new stack
[Nov 11 19:33:41] DEBUG[32506] app_macro.c: Executed application: Set
[Nov 11 19:33:41] VERBOSE[32506] logger.c: -- Executing [s@macro-dialout-trunk:14] Macro("SIP/219-b7dd7120", "outbound-callerid|1") in new stack
[Nov 11 19:33:41] VERBOSE[32506] logger.c: -- Executing [s@macro-outbound-callerid:1] GotoIf("SIP/219-b7dd7120", "1?start") in new stack
[Nov 11 19:33:41] VERBOSE[32506] logger.c: -- Goto (macro-outbound-callerid,s,3)
[Nov 11 19:33:41] DEBUG[32506] app_macro.c: Executed application: GotoIf
[Nov 11 19:33:41] VERBOSE[32506] logger.c: -- Executing [s@macro-outbound-callerid:3] NoOp("SIP/219-b7dd7120", "REALCALLERIDNUM is 219") in new stack
[Nov 11 19:33:41] DEBUG[32506] app_macro.c: Executed application: NoOp
[Nov 11 19:33:41] VERBOSE[32506] logger.c: -- Executing [s@macro-outbound-callerid:4] GotoIf("SIP/219-b7dd7120", "1?normcid") in new stack
[Nov 11 19:33:41] VERBOSE[32506] logger.c: -- Goto (macro-outbound-callerid,s,9)
[Nov 11 19:33:41] DEBUG[32506] app_macro.c: Executed application: GotoIf
[Nov 11 19:33:41] VERBOSE[32506] logger.c: -- Executing [s@macro-outbound-callerid:9] Set("SIP/219-b7dd7120", "USEROUTCID=") in new stack
[Nov 11 19:33:41] DEBUG[32506] app_macro.c: Executed application: Set
[Nov 11 19:33:41] DEBUG[32506] db.c: Unable to find key '219/emergency_cid' in family 'DEVICE'
[Nov 11 19:33:41] DEBUG[32506] func_db.c: DB: DEVICE/219/emergency_cid not found in database.
[Nov 11 19:33:41] VERBOSE[32506] logger.c: -- Executing [s@macro-outbound-callerid:10] Set("SIP/219-b7dd7120", "EMERGENCYCID=") in new stack
[Nov 11 19:33:41] DEBUG[32506] app_macro.c: Executed application: Set
[Nov 11 19:33:41] VERBOSE[32506] logger.c: -- Executing [s@macro-outbound-callerid:11] Set("SIP/219-b7dd7120", "TRUNKOUTCID=") in new stack
[Nov 11 19:33:41] DEBUG[32506] app_macro.c: Executed application: Set
[Nov 11 19:33:41] VERBOSE[32506] logger.c: -- Executing [s@macro-outbound-callerid:12] GotoIf("SIP/219-b7dd7120", "1?trunkcid") in new stack
[Nov 11 19:33:41] VERBOSE[32506] logger.c: -- Goto (macro-outbound-callerid,s,16)
[Nov 11 19:33:41] DEBUG[32506] app_macro.c: Executed application: GotoIf
[Nov 11 19:33:41] VERBOSE[32506] logger.c: -- Executing [s@macro-outbound-callerid:16] GotoIf("SIP/219-b7dd7120", "1?usercid") in new stack
[Nov 11 19:33:41] VERBOSE[32506] logger.c: -- Goto (macro-outbound-callerid,s,18)
[Nov 11 19:33:41] DEBUG[32506] app_macro.c: Executed application: GotoIf
[Nov 11 19:33:41] VERBOSE[32506] logger.c: -- Executing [s@macro-outbound-callerid:18] GotoIf("SIP/219-b7dd7120", "1?report") in new stack
[Nov 11 19:33:41] VERBOSE[32506] logger.c: -- Goto (macro-outbound-callerid,s,22)
[Nov 11 19:33:41] DEBUG[32506] app_macro.c: Executed application: GotoIf
[Nov 11 19:33:41] VERBOSE[32506] logger.c: -- Executing [s@macro-outbound-callerid:22] NoOp("SIP/219-b7dd7120", "CallerID set to "Ext.219" <219>") in new stack
[Nov 11 19:33:41] DEBUG[32506] app_macro.c: Executed application: NoOp
[Nov 11 19:33:41] DEBUG[32506] app_macro.c: Executed application: Macro
[Nov 11 19:33:41] VERBOSE[32506] logger.c: -- Executing [s@macro-dialout-trunk:15] GotoIf("SIP/219-b7dd7120", "1?nomax") in new stack
[Nov 11 19:33:41] VERBOSE[32506] logger.c: -- Goto (macro-dialout-trunk,s,17)
[Nov 11 19:33:41] DEBUG[32506] app_macro.c: Executed application: GotoIf
[Nov 11 19:33:41] VERBOSE[32506] logger.c: -- Executing [s@macro-dialout-trunk:17] AGI("SIP/219-b7dd7120", "fixlocalprefix") in new stack
[Nov 11 19:33:41] VERBOSE[32506] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/fixlocalprefix
[Nov 11 19:33:41] VERBOSE[32509] logger.c: == Begin MixMonitor Recording SIP/219-b7dd7120
[Nov 11 19:33:41] VERBOSE[32506] logger.c: > fixlocalprefix: Using pattern .
[Nov 11 19:33:41] VERBOSE[32506] logger.c: == fixlocalprefix: Dialpattern . matched. 035787390 -> 035787390
[Nov 11 19:33:41] VERBOSE[32506] logger.c: -- AGI Script fixlocalprefix completed, returning 0
[Nov 11 19:33:41] DEBUG[32506] app_macro.c: Executed application: AGI
[Nov 11 19:33:41] VERBOSE[32506] logger.c: -- Executing [s@macro-dialout-trunk:18] Set("SIP/219-b7dd7120", "OUTNUM=035787390") in new stack
[Nov 11 19:33:41] DEBUG[32506] app_macro.c: Executed application: Set
[Nov 11 19:33:41] VERBOSE[32506] logger.c: -- Executing [s@macro-dialout-trunk:19] Set("SIP/219-b7dd7120", "custom=ZAP/g0") in new stack
[Nov 11 19:33:41] DEBUG[32506] app_macro.c: Executed application: Set
[Nov 11 19:33:41] VERBOSE[32506] logger.c: -- Executing [s@macro-dialout-trunk:20] GotoIf("SIP/219-b7dd7120", "1?gocall") in new stack
[Nov 11 19:33:41] VERBOSE[32506] logger.c: -- Goto (macro-dialout-trunk,s,22)
[Nov 11 19:33:41] DEBUG[32506] app_macro.c: Executed application: GotoIf
[Nov 11 19:33:41] VERBOSE[32506] logger.c: -- Executing [s@macro-dialout-trunk:22] GotoIf("SIP/219-b7dd7120", "0?customtrunk") in new stack
[Nov 11 19:33:41] DEBUG[32506] app_macro.c: Executed application: GotoIf
[Nov 11 19:33:41] VERBOSE[32506] logger.c: -- Executing [s@macro-dialout-trunk:23] Dial("SIP/219-b7dd7120", "ZAP/g0/035787390|300|Tt") in new stack
[Nov 11 19:33:41] VERBOSE[32506] logger.c: -- Requested transfer capability: 0x00 - SPEECH
[Nov 11 19:33:41] VERBOSE[32506] logger.c: -- Called g0/035787390
[Nov 11 19:33:41] VERBOSE[31262] logger.c: == Done Spying on channel SIP/216-b7d96730
[Nov 11 19:33:41] VERBOSE[31262] logger.c: -- Playing 'beep' (language 'he')
[Nov 11 19:33:41] DEBUG[23454] chan_zap.c: Queuing frame from PRI_EVENT_PROCEEDING on channel 0/4 span 1
[Nov 11 19:33:41] VERBOSE[32506] logger.c: -- Zap/4-1 is proceeding passing it to SIP/219-b7dd7120
[Nov 11 19:33:41] VERBOSE[23426] logger.c: -- Remote UNIX connection
[Nov 11 19:33:41] VERBOSE[32513] logger.c: -- Remote UNIX connection disconnected
[Nov 11 19:33:42] VERBOSE[32506] logger.c: -- Zap/4-1 is ringing
[Nov 11 19:33:43] VERBOSE[23426] logger.c: -- Remote UNIX connection
[Nov 11 19:33:43] VERBOSE[32516] logger.c: -- Remote UNIX connection disconnected
[Nov 11 19:33:47] VERBOSE[31262] logger.c: -- Playing 'spy-sip' (language 'he')
[Nov 11 19:33:47] VERBOSE[31262] logger.c: -- Playing 'digits/2' (language 'he')
[Nov 11 19:33:48] VERBOSE[31262] logger.c: -- Playing 'digits/1' (language 'he')
[Nov 11 19:33:48] VERBOSE[31262] logger.c: -- Playing 'digits/9' (language 'he')
[Nov 11 19:33:49] VERBOSE[31262] logger.c: == Spying on channel SIP/219-b7dd7120
[Nov 11 19:33:49] NOTICE[31262] app_chanspy.c: Attaching Zap/6-1 to SIP/219-b7dd7120
[Nov 11 19:33:54] VERBOSE[23426] logger.c: -- Remote UNIX connection
[Nov 11 19:33:54] VERBOSE[32518] logger.c: -- Remote UNIX connection disconnected
[Nov 11 19:33:56] DEBUG[23454] chan_zap.c: Echo cancellation already on
[Nov 11 19:33:56] VERBOSE[32506] logger.c: -- Zap/4-1 answered SIP/219-b7dd7120
[Nov 11 19:33:56] VERBOSE[31262] logger.c: == Done Spying on channel SIP/219-b7dd7120
[Nov 11 19:33:56] VERBOSE[31262] logger.c: -- Playing 'beep' (language 'he')
[Nov 11 19:33:57] VERBOSE[23426] logger.c: -- Remote UNIX connection
[Nov 11 19:33:57] VERBOSE[32522] logger.c: -- Remote UNIX connection disconnected
[Nov 11 19:34:01] VERBOSE[23428] logger.c: Extension Changed 207 new state InUse for Notify User 207
[Nov 11 19:34:01] VERBOSE[32525] logger.c: -- Executing [0527617089@from-internal:1] Macro("SIP/207-b7dc64d0", "dialout-trunk|1|0527617089||") in new stack
[Nov 11 19:34:01] VERBOSE[23428] logger.c: Extension Changed 207 new state InUse for Notify User 224
[Nov 11 19:34:01] VERBOSE[32525] logger.c: -- Executing [s@macro-dialout-trunk:1] Set("SIP/207-b7dc64d0", "DIAL_TRUNK=1") in new stack
[Nov 11 19:34:01] DEBUG[32525] app_macro.c: Executed application: Set
[Nov 11 19:34:01] VERBOSE[32525] logger.c: -- Executing [s@macro-dialout-trunk:2] Set("SIP/207-b7dc64d0", "DIAL_NUMBER=0527617089") in new stack
[Nov 11 19:34:01] VERBOSE[23428] logger.c: Extension Changed 207 new state InUse for Notify User 214
[Nov 11 19:34:01] DEBUG[32525] app_macro.c: Executed application: Set
[Nov 11 19:34:01] VERBOSE[32525] logger.c: -- Executing [s@macro-dialout-trunk:3] Set("SIP/207-b7dc64d0", "ROUTE_PASSWD=") in new stack
[Nov 11 19:34:01] VERBOSE[23428] logger.c: Extension Changed 207 new state InUse for Notify User 218
[Nov 11 19:34:01] DEBUG[32525] app_macro.c: Executed application: Set
[Nov 11 19:34:01] VERBOSE[23428] logger.c: Extension Changed 207 new state InUse for Notify User 203
[Nov 11 19:34:01] VERBOSE[32525] logger.c: -- Executing [s@macro-dialout-trunk:4] GotoIf("SIP/207-b7dc64d0", "1?noauth") in new stack
[Nov 11 19:34:01] VERBOSE[32525] logger.c: -- Goto (macro-dialout-trunk,s,6)
[Nov 11 19:34:01] DEBUG[32525] app_macro.c: Executed application: GotoIf
[Nov 11 19:34:01] VERBOSE[23428] logger.c: Extension Changed 207 new state InUse for Notify User 206
[Nov 11 19:34:01] VERBOSE[32525] logger.c: -- Executing [s@macro-dialout-trunk:6] GotoIf("SIP/207-b7dc64d0", "0?disabletrunk|1") in new stack
[Nov 11 19:34:01] DEBUG[32525] app_macro.c: Executed application: GotoIf
[Nov 11 19:34:01] VERBOSE[32525] logger.c: -- Executing [s@macro-dialout-trunk:7] Set("SIP/207-b7dc64d0", "_NODEST=") in new stack
[Nov 11 19:34:01] DEBUG[32525] app_macro.c: Executed application: Set
[Nov 11 19:34:01] VERBOSE[23428] logger.c: Extension Changed 207 new state InUse for Notify User 229
[Nov 11 19:34:01] VERBOSE[32525] logger.c: -- Executing [s@macro-dialout-trunk:8] Set("SIP/207-b7dc64d0", "DIAL_TRUNK_OPTIONS=Tt") in new stack
[Nov 11 19:34:01] DEBUG[32525] app_macro.c: Executed application: Set
[Nov 11 19:34:01] VERBOSE[32525] logger.c: -- Executing [s@macro-dialout-trunk:9] Set("SIP/207-b7dc64d0", "GROUP()=OUT_1") in new stack
[Nov 11 19:34:01] VERBOSE[23428] logger.c: Extension Changed 207 new state InUse for Notify User 204
[Nov 11 19:34:01] DEBUG[32525] app_macro.c: Executed application: Set
[Nov 11 19:34:01] VERBOSE[32525] logger.c: -- Executing [s@macro-dialout-trunk:10] Macro("SIP/207-b7dc64d0", "user-callerid|SKIPTTL") in new stack
[Nov 11 19:34:01] VERBOSE[23428] logger.c: Extension Changed 207 new state InUse for Notify User 225
[Nov 11 19:34:01] VERBOSE[32525] logger.c: -- Executing [s@macro-user-callerid:1] NoOp("SIP/207-b7dc64d0", "user-callerid: device 207") in new stack
[Nov 11 19:34:01] DEBUG[32525] app_macro.c: Executed application: Noop
[Nov 11 19:34:01] VERBOSE[32525] logger.c: -- Executing [s@macro-user-callerid:2] Set("SIP/207-b7dc64d0", "AMPUSER=207") in new stack
[Nov 11 19:34:01] VERBOSE[23428] logger.c: Extension Changed 207 new state InUse for Notify User 228
[Nov 11 19:34:01] DEBUG[32525] app_macro.c: Executed application: Set
[Nov 11 19:34:01] VERBOSE[32525] logger.c: -- Executing [s@macro-user-callerid:3] GotoIf("SIP/207-b7dc64d0", "0?report") in new stack
[Nov 11 19:34:01] DEBUG[32525] app_macro.c: Executed application: GotoIf
[Nov 11 19:34:01] VERBOSE[23428] logger.c: Extension Changed 207 new state InUse for Notify User 226
[Nov 11 19:34:01] VERBOSE[32525] logger.c: -- Executing [s@macro-user-callerid:4] GotoIf("SIP/207-b7dc64d0", "0?start") in new stack
[Nov 11 19:34:01] DEBUG[32525] app_macro.c: Executed application: GotoIf
[Nov 11 19:34:01] VERBOSE[32525] logger.c: -- Executing [s@macro-user-callerid:5] Set("SIP/207-b7dc64d0", "REALCALLERIDNUM=207") in new stack
[Nov 11 19:34:01] DEBUG[32525] app_macro.c: Executed application: Set
[Nov 11 19:34:01] VERBOSE[32525] logger.c: -- Executing [s@macro-user-callerid:6] NoOp("SIP/207-b7dc64d0", "REALCALLERIDNUM is 207") in new stack
[Nov 11 19:34:01] VERBOSE[23428] logger.c: Extension Changed 207 new state InUse for Notify User 201
[Nov 11 19:34:01] DEBUG[32525] app_macro.c: Executed application: NoOp
[Nov 11 19:34:01] VERBOSE[23428] logger.c: Extension Changed 207 new state InUse for Notify User 220
[Nov 11 19:34:01] VERBOSE[32525] logger.c: -- Executing [s@macro-user-callerid:7] Set("SIP/207-b7dc64d0", "AMPUSER=207") in new stack
[Nov 11 19:34:01] DEBUG[32525] app_macro.c: Executed application: Set
[Nov 11 19:34:01] VERBOSE[23428] logger.c: Extension Changed 207 new state InUse for Notify User 219
[Nov 11 19:34:01] VERBOSE[32525] logger.c: -- Executing [s@macro-user-callerid:8] Set("SIP/207-b7dc64d0", "AMPUSERCIDNAME=Ext.207") in new stack
[Nov 11 19:34:01] DEBUG[32525] app_macro.c: Executed application: Set
[Nov 11 19:34:01] VERBOSE[32525] logger.c: -- Executing [s@macro-user-callerid:9] GotoIf("SIP/207-b7dc64d0", "0?report") in new stack
[Nov 11 19:34:01] VERBOSE[23428] logger.c: Extension Changed 207 new state InUse for Notify User 211
[Nov 11 19:34:01] DEBUG[32525] app_macro.c: Executed application: GotoIf
[Nov 11 19:34:01] DEBUG[32525] db.c: Unable to find key '207/cidnum' in family 'AMPUSER'
[Nov 11 19:34:01] VERBOSE[23428] logger.c: Extension Changed 207 new state InUse for Notify User 216
[Nov 11 19:34:01] VERBOSE[32525] logger.c: -- Executing [s@macro-user-callerid:10] Set("SIP/207-b7dc64d0", "AMPUSERCID=207") in new stack
[Nov 11 19:34:01] DEBUG[32525] app_macro.c: Executed application: Set
[Nov 11 19:34:01] VERBOSE[32525] logger.c: -- Executing [s@macro-user-callerid:11] Set("SIP/207-b7dc64d0", "CALLERID(all)="Ext.207" <207>") in new stack
[Nov 11 19:34:01] DEBUG[32525] app_macro.c: Executed application: Set
[Nov 11 19:34:01] VERBOSE[32525] logger.c: -- Executing [s@macro-user-callerid:12] Set("SIP/207-b7dc64d0", "REALCALLERIDNUM=207") in new stack
[Nov 11 19:34:01] VERBOSE[23428] logger.c: Extension Changed 207 new state InUse for Notify User 212
[Nov 11 19:34:01] DEBUG[32525] app_macro.c: Executed application: Set
[Nov 11 19:34:01] VERBOSE[32525] logger.c: -- Executing [s@macro-user-callerid:13] NoOp("SIP/207-b7dc64d0", "TTL: ARG1: SKIPTTL") in new stack
[Nov 11 19:34:01] DEBUG[32525] app_macro.c: Executed application: Noop
[Nov 11 19:34:01] VERBOSE[23428] logger.c: Extension Changed 207 new state InUse for Notify User 208
[Nov 11 19:34:01] VERBOSE[32525] logger.c: -- Executing [s@macro-user-callerid:14] GotoIf("SIP/207-b7dc64d0", "1?continue") in new stack
[Nov 11 19:34:01] VERBOSE[32525] logger.c: -- Goto (macro-user-callerid,s,23)
[Nov 11 19:34:01] DEBUG[32525] app_macro.c: Executed application: GotoIf
[Nov 11 19:34:01] VERBOSE[23428] logger.c: Extension Changed 207 new state InUse for Notify User 213
[Nov 11 19:34:01] VERBOSE[32525] logger.c: -- Executing [s@macro-user-callerid:23] NoOp("SIP/207-b7dc64d0", "Using CallerID "Ext.207" <207>") in new stack
[Nov 11 19:34:01] DEBUG[32525] app_macro.c: Executed application: NoOp
[Nov 11 19:34:01] DEBUG[32525] app_macro.c: Executed application: Macro
[Nov 11 19:34:01] VERBOSE[23428] logger.c: Extension Changed 207 new state InUse for Notify User 223
[Nov 11 19:34:01] VERBOSE[32525] logger.c: -- Executing [s@macro-dialout-trunk:11] Macro("SIP/207-b7dc64d0", "record-enable|207|OUT") in new stack
[Nov 11 19:34:01] VERBOSE[23428] logger.c: Extension Changed 207 new state InUse for Notify User 210
[Nov 11 19:34:01] VERBOSE[32525] logger.c: -- Executing [s@macro-record-enable:1] GotoIf("SIP/207-b7dc64d0", "0?2:4") in new stack
[Nov 11 19:34:01] VERBOSE[32525] logger.c: -- Goto (macro-record-enable,s,4)
[Nov 11 19:34:01] DEBUG[32525] app_macro.c: Executed application: GotoIf
[Nov 11 19:34:01] VERBOSE[32525] logger.c: -- Executing [s@macro-record-enable:4] AGI("SIP/207-b7dc64d0", "recordingcheck|20071111-193401|1194802441.1457") in new stack
[Nov 11 19:34:01] VERBOSE[23428] logger.c: Extension Changed 207 new state InUse for Notify User 230
[Nov 11 19:34:01] VERBOSE[32525] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
[Nov 11 19:34:01] VERBOSE[23428] logger.c: Extension Changed 207 new state InUse for Notify User 227
[Nov 11 19:34:01] VERBOSE[23428] logger.c: Extension Changed 207 new state InUse for Notify User 215
[Nov 11 19:34:01] VERBOSE[23428] logger.c: Extension Changed 207 new state InUse for Notify User 221
[Nov 11 19:34:01] VERBOSE[32525] logger.c: recordingcheck|20071111-193401|1194802441.1457: Outbound recording enabled.
[Nov 11 19:34:01] VERBOSE[32525] logger.c: recordingcheck|20071111-193401|1194802441.1457: CALLFILENAME=OUT207-20071111-193401-1194802441.1457
[Nov 11 19:34:01] VERBOSE[32525] logger.c: -- AGI Script recordingcheck completed, returning 0
[Nov 11 19:34:01] DEBUG[32525] app_macro.c: Executed application: AGI
[Nov 11 19:34:01] VERBOSE[32525] logger.c: -- Executing [s@macro-record-enable:999] MixMonitor("SIP/207-b7dc64d0", "OUT207-20071111-193401-1194802441.1457.WAV") in new stack
[Nov 11 19:34:01] DEBUG[32525] app_macro.c: Executed application: MixMonitor
[Nov 11 19:34:01] DEBUG[32525] app_macro.c: Executed application: Macro
[Nov 11 19:34:01] VERBOSE[32525] logger.c: -- Executing [s@macro-dialout-trunk:12] GotoIf("SIP/207-b7dc64d0", "0?skipoutcid") in new stack
[Nov 11 19:34:01] DEBUG[32525] app_macro.c: Executed application: GotoIf
[Nov 11 19:34:01] VERBOSE[32525] logger.c: -- Executing [s@macro-dialout-trunk:13] Set("SIP/207-b7dc64d0", "DIAL_TRUNK_OPTIONS=Tt") in new stack
[Nov 11 19:34:01] DEBUG[32525] app_macro.c: Executed application: Set
[Nov 11 19:34:01] VERBOSE[32525] logger.c: -- Executing [s@macro-dialout-trunk:14] Macro("SIP/207-b7dc64d0", "outbound-callerid|1") in new stack
[Nov 11 19:34:01] VERBOSE[32525] logger.c: -- Executing [s@macro-outbound-callerid:1] GotoIf("SIP/207-b7dc64d0", "1?start") in new stack
[Nov 11 19:34:01] VERBOSE[32525] logger.c: -- Goto (macro-outbound-callerid,s,3)
[Nov 11 19:34:01] DEBUG[32525] app_macro.c: Executed application: GotoIf
[Nov 11 19:34:01] VERBOSE[32525] logger.c: -- Executing [s@macro-outbound-callerid:3] NoOp("SIP/207-b7dc64d0", "REALCALLERIDNUM is 207") in new stack
[Nov 11 19:34:01] DEBUG[32525] app_macro.c: Executed application: NoOp
[Nov 11 19:34:01] VERBOSE[32525] logger.c: -- Executing [s@macro-outbound-callerid:4] GotoIf("SIP/207-b7dc64d0", "1?normcid") in new stack
[Nov 11 19:34:01] VERBOSE[32525] logger.c: -- Goto (macro-outbound-callerid,s,9)
[Nov 11 19:34:01] DEBUG[32525] app_macro.c: Executed application: GotoIf
[Nov 11 19:34:01] VERBOSE[32525] logger.c: -- Executing [s@macro-outbound-callerid:9] Set("SIP/207-b7dc64d0", "USEROUTCID=") in new stack
[Nov 11 19:34:01] DEBUG[32525] app_macro.c: Executed application: Set
[Nov 11 19:34:01] DEBUG[32525] db.c: Unable to find key '207/emergency_cid' in family 'DEVICE'
[Nov 11 19:34:01] VERBOSE[32528] logger.c: == Begin MixMonitor Recording SIP/207-b7dc64d0
[Nov 11 19:34:01] DEBUG[32525] func_db.c: DB: DEVICE/207/emergency_cid not found in database.
[Nov 11 19:34:01] VERBOSE[32525] logger.c: -- Executing [s@macro-outbound-callerid:10] Set("SIP/207-b7dc64d0", "EMERGENCYCID=") in new stack
[Nov 11 19:34:01] DEBUG[32525] app_macro.c: Executed application: Set
[Nov 11 19:34:01] VERBOSE[32525] logger.c: -- Executing [s@macro-outbound-callerid:11] Set("SIP/207-b7dc64d0", "TRUNKOUTCID=") in new stack
[Nov 11 19:34:01] DEBUG[32525] app_macro.c: Executed application: Set
[Nov 11 19:34:01] VERBOSE[32525] logger.c: -- Executing [s@macro-outbound-callerid:12] GotoIf("SIP/207-b7dc64d0", "1?trunkcid") in new stack
[Nov 11 19:34:01] VERBOSE[32525] logger.c: -- Goto (macro-outbound-callerid,s,16)
[Nov 11 19:34:01] DEBUG[32525] app_macro.c: Executed application: GotoIf
[Nov 11 19:34:01] VERBOSE[32525] logger.c: -- Executing [s@macro-outbound-callerid:16] GotoIf("SIP/207-b7dc64d0", "1?usercid") in new stack
[Nov 11 19:34:01] VERBOSE[32525] logger.c: -- Goto (macro-outbound-callerid,s,18)
[Nov 11 19:34:01] DEBUG[32525] app_macro.c: Executed application: GotoIf
[Nov 11 19:34:01] VERBOSE[32525] logger.c: -- Executing [s@macro-outbound-callerid:18] GotoIf("SIP/207-b7dc64d0", "1?report") in new stack
[Nov 11 19:34:01] VERBOSE[32525] logger.c: -- Goto (macro-outbound-callerid,s,22)
[Nov 11 19:34:01] DEBUG[32525] app_macro.c: Executed application: GotoIf
[Nov 11 19:34:01] VERBOSE[32525] logger.c: -- Executing [s@macro-outbound-callerid:22] NoOp("SIP/207-b7dc64d0", "CallerID set to "Ext.207" <207>") in new stack
[Nov 11 19:34:01] DEBUG[32525] app_macro.c: Executed application: NoOp
[Nov 11 19:34:01] DEBUG[32525] app_macro.c: Executed application: Macro
[Nov 11 19:34:01] VERBOSE[32525] logger.c: -- Executing [s@macro-dialout-trunk:15] GotoIf("SIP/207-b7dc64d0", "1?nomax") in new stack
[Nov 11 19:34:01] VERBOSE[32525] logger.c: -- Goto (macro-dialout-trunk,s,17)
[Nov 11 19:34:01] DEBUG[32525] app_macro.c: Executed application: GotoIf
[Nov 11 19:34:01] VERBOSE[32525] logger.c: -- Executing [s@macro-dialout-trunk:17] AGI("SIP/207-b7dc64d0", "fixlocalprefix") in new stack
[Nov 11 19:34:01] VERBOSE[32525] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/fixlocalprefix
[Nov 11 19:34:01] VERBOSE[32525] logger.c: > fixlocalprefix: Using pattern .
[Nov 11 19:34:01] VERBOSE[32525] logger.c: == fixlocalprefix: Dialpattern . matched. 0527617089 -> 0527617089
[Nov 11 19:34:01] VERBOSE[32525] logger.c: -- AGI Script fixlocalprefix completed, returning 0
[Nov 11 19:34:01] DEBUG[32525] app_macro.c: Executed application: AGI
[Nov 11 19:34:01] VERBOSE[32525] logger.c: -- Executing [s@macro-dialout-trunk:18] Set("SIP/207-b7dc64d0", "OUTNUM=0527617089") in new stack
[Nov 11 19:34:01] DEBUG[32525] app_macro.c: Executed application: Set
[Nov 11 19:34:01] VERBOSE[32525] logger.c: -- Executing [s@macro-dialout-trunk:19] Set("SIP/207-b7dc64d0", "custom=ZAP/g0") in new stack
[Nov 11 19:34:01] DEBUG[32525] app_macro.c: Executed application: Set
[Nov 11 19:34:01] VERBOSE[32525] logger.c: -- Executing [s@macro-dialout-trunk:20] GotoIf("SIP/207-b7dc64d0", "1?gocall") in new stack
[Nov 11 19:34:01] VERBOSE[32525] logger.c: -- Goto (macro-dialout-trunk,s,22)
[Nov 11 19:34:01] DEBUG[32525] app_macro.c: Executed application: GotoIf
[Nov 11 19:34:01] VERBOSE[32525] logger.c: -- Executing [s@macro-dialout-trunk:22] GotoIf("SIP/207-b7dc64d0", "0?customtrunk") in new stack
[Nov 11 19:34:01] DEBUG[32525] app_macro.c: Executed application: GotoIf
[Nov 11 19:34:01] VERBOSE[32525] logger.c: -- Executing [s@macro-dialout-trunk:23] Dial("SIP/207-b7dc64d0", "ZAP/g0/0527617089|300|Tt") in new stack
[Nov 11 19:34:01] VERBOSE[32525] logger.c: -- Requested transfer capability: 0x00 - SPEECH
[Nov 11 19:34:01] VERBOSE[32525] logger.c: -- Called g0/0527617089
[Nov 11 19:34:01] DEBUG[23454] chan_zap.c: Queuing frame from PRI_EVENT_PROCEEDING on channel 0/5 span 1
[Nov 11 19:34:01] VERBOSE[32525] logger.c: -- Zap/5-1 is proceeding passing it to SIP/207-b7dc64d0
[Nov 11 19:34:02] DEBUG[23454] chan_zap.c: Queuing frame from PRI_EVENT_PROGRESS on channel 0/5 span 1
[Nov 11 19:34:02] VERBOSE[32525] logger.c: -- Zap/5-1 is making progress passing it to SIP/207-b7dc64d0
[Nov 11 19:34:02] VERBOSE[31262] logger.c: -- Playing 'spy-sip' (language 'he')
[Nov 11 19:34:03] VERBOSE[31262] logger.c: -- Playing 'digits/2' (language 'he')
[Nov 11 19:34:03] VERBOSE[32531] logger.c: == Parsing '/etc/asterisk/manager.conf': [Nov 11 19:34:03] VERBOSE[32531] logger.c: Found
[Nov 11 19:34:03] VERBOSE[32531] logger.c: == Parsing '/etc/asterisk/manager_custom.conf': [Nov 11 19:34:03] VERBOSE[32531] logger.c: Found
[Nov 11 19:34:03] VERBOSE[32531] logger.c: == Manager 'admin' logged on from 127.0.0.1
[Nov 11 19:34:03] VERBOSE[31262] logger.c: -- Playing 'digits/0' (language 'he')
[Nov 11 19:34:03] VERBOSE[32531] logger.c: == Manager 'admin' logged off from 127.0.0.1
[Nov 11 19:34:04] VERBOSE[31262] logger.c: -- Playing 'digits/7' (language 'he')
[Nov 11 19:34:04] DEBUG[23454] chan_zap.c: Queuing frame from PRI_EVENT_PROGRESS on channel 0/5 span 1
[Nov 11 19:34:04] VERBOSE[32525] logger.c: -- Zap/5-1 is making progress passing it to SIP/207-b7dc64d0
[Nov 11 19:34:04] VERBOSE[32525] logger.c: -- Zap/5-1 is ringing
[Nov 11 19:34:05] VERBOSE[31262] logger.c: == Spying on channel SIP/207-b7dc64d0
[Nov 11 19:34:05] NOTICE[31262] app_chanspy.c: Attaching Zap/6-1 to SIP/207-b7dc64d0
[Nov 11 19:34:07] DEBUG[32377] chan_zap.c: Set option AUDIO MODE, value: ON(1) on Zap/7-1
[Nov 11 19:34:07] DEBUG[32377] chan_zap.c: Not yet hungup... Calling hangup once with icause, and clearing call
[Nov 11 19:34:07] DEBUG[32377] chan_zap.c: Set option AUDIO MODE, value: OFF(0) on Zap/7-1
[Nov 11 19:34:07] VERBOSE[32377] logger.c: -- Hungup 'Zap/7-1'
[Nov 11 19:34:07] VERBOSE[32377] logger.c: == Spawn extension (macro-dialout-trunk, s, 23) exited non-zero on 'SIP/210-b7dc8fe0' in macro 'dialout-trunk'
[Nov 11 19:34:07] VERBOSE[32377] logger.c: == Spawn extension (macro-dialout-trunk, s, 23) exited non-zero on 'SIP/210-b7dc8fe0'
[Nov 11 19:34:07] VERBOSE[32377] logger.c: -- Executing [h@macro-dialout-trunk:1] Macro("SIP/210-b7dc8fe0", "hangupcall") in new stack
[Nov 11 19:34:07] VERBOSE[32377] logger.c: -- Executing [s@macro-hangupcall:1] ResetCDR("SIP/210-b7dc8fe0", "w") in new stack
[Nov 11 19:34:07] DEBUG[32377] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record.
[Nov 11 19:34:07] DEBUG[32377] 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 ('2007-11-11 19:32:03','\"Ext.210\" <210>','210','6527416','from-internal', 'SIP/210-b7dc8fe0','Zap/7-1','Dial','ZAP/g0/6527416|300|Tt',124,107,'ANSWERED',3,'','1194802323.1437')
[Nov 11 19:34:07] DEBUG[32377] app_macro.c: Executed application: ResetCDR
[Nov 11 19:34:07] VERBOSE[32377] logger.c: -- Executing [s@macro-hangupcall:2] NoCDR("SIP/210-b7dc8fe0", "") in new stack
[Nov 11 19:34:07] DEBUG[32377] app_macro.c: Executed application: NoCDR
[Nov 11 19:34:07] VERBOSE[32377] logger.c: -- Executing [s@macro-hangupcall:3] GotoIf("SIP/210-b7dc8fe0", "1?skiprg") in new stack
[Nov 11 19:34:07] VERBOSE[32377] logger.c: -- Goto (macro-hangupcall,s,6)
[Nov 11 19:34:07] DEBUG[32377] app_macro.c: Executed application: GotoIf
[Nov 11 19:34:07] VERBOSE[32377] logger.c: -- Executing [s@macro-hangupcall:6] GotoIf("SIP/210-b7dc8fe0", "1?skipblkvm") in new stack
[Nov 11 19:34:07] VERBOSE[32377] logger.c: -- Goto (macro-hangupcall,s,9)
[Nov 11 19:34:07] DEBUG[32377] app_macro.c: Executed application: GotoIf
[Nov 11 19:34:07] VERBOSE[32377] logger.c: -- Executing [s@macro-hangupcall:9] GotoIf("SIP/210-b7dc8fe0", "1?theend") in new stack
[Nov 11 19:34:07] VERBOSE[32377] logger.c: -- Goto (macro-hangupcall,s,11)
[Nov 11 19:34:07] DEBUG[32377] app_macro.c: Executed application: GotoIf
[Nov 11 19:34:07] VERBOSE[32377] logger.c: -- Executing [s@macro-hangupcall:11] Hangup("SIP/210-b7dc8fe0", "") in new stack
[Nov 11 19:34:07] VERBOSE[32377] logger.c: == Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'SIP/210-b7dc8fe0' in macro 'hangupcall'
[Nov 11 19:34:07] VERBOSE[32377] logger.c: == Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'SIP/210-b7dc8fe0'
[Nov 11 19:34:07] VERBOSE[32380] logger.c: == End MixMonitor Recording SIP/210-b7dc8fe0
[Nov 11 19:34:07] VERBOSE[23428] logger.c: Extension Changed 210 new state Idle for Notify User 207
[Nov 11 19:34:07] VERBOSE[23428] logger.c: Extension Changed 210 new state Idle for Notify User 224
[Nov 11 19:34:07] VERBOSE[23428] logger.c: Extension Changed 210 new state Idle for Notify User 214
[Nov 11 19:34:07] VERBOSE[23428] logger.c: Extension Changed 210 new state Idle for Notify User 218
[Nov 11 19:34:07] VERBOSE[23428] logger.c: Extension Changed 210 new state Idle for Notify User 203
[Nov 11 19:34:07] VERBOSE[23428] logger.c: Extension Changed 210 new state Idle for Notify User 206
[Nov 11 19:34:07] VERBOSE[23428] logger.c: Extension Changed 210 new state Idle for Notify User 229
[Nov 11 19:34:07] VERBOSE[23428] logger.c: Extension Changed 210 new state Idle for Notify User 204
[Nov 11 19:34:07] VERBOSE[23428] logger.c: Extension Changed 210 new state Idle for Notify User 225
[Nov 11 19:34:07] VERBOSE[23428] logger.c: Extension Changed 210 new state Idle for Notify User 228
[Nov 11 19:34:07] VERBOSE[23428] logger.c: Extension Changed 210 new state Idle for Notify User 226
[Nov 11 19:34:07] VERBOSE[23428] logger.c: Extension Changed 210 new state Idle for Notify User 201
[Nov 11 19:34:07] VERBOSE[23428] logger.c: Extension Changed 210 new state Idle for Notify User 220
[Nov 11 19:34:07] VERBOSE[23428] logger.c: Extension Changed 210 new state Idle for Notify User 219
[Nov 11 19:34:07] VERBOSE[23428] logger.c: Extension Changed 210 new state Idle for Notify User 211
[Nov 11 19:34:07] VERBOSE[23428] logger.c: Extension Changed 210 new state Idle for Notify User 216
[Nov 11 19:34:07] VERBOSE[23428] logger.c: Extension Changed 210 new state Idle for Notify User 212
[Nov 11 19:34:07] VERBOSE[23428] logger.c: Extension Changed 210 new state Idle for Notify User 208
[Nov 11 19:34:07] VERBOSE[23428] logger.c: Extension Changed 210 new state Idle for Notify User 213
[Nov 11 19:34:07] VERBOSE[23428] logger.c: Extension Changed 210 new state Idle for Notify User 223
[Nov 11 19:34:07] VERBOSE[23428] logger.c: Extension Changed 210 new state Idle for Notify User 210
[Nov 11 19:34:07] VERBOSE[23428] logger.c: Extension Changed 210 new state Idle for Notify User 230
[Nov 11 19:34:07] VERBOSE[23428] logger.c: Extension Changed 210 new state Idle for Notify User 227
[Nov 11 19:34:07] VERBOSE[23428] logger.c: Extension Changed 210 new state Idle for Notify User 215
[Nov 11 19:34:07] VERBOSE[23428] logger.c: Extension Changed 210 new state Idle for Notify User 221
[Nov 11 19:34:11] DEBUG[32496] chan_zap.c: Set option AUDIO MODE, value: ON(1) on Zap/2-1
[Nov 11 19:34:11] DEBUG[32496] chan_zap.c: Not yet hungup... Calling hangup once with icause, and clearing call
[Nov 11 19:34:11] DEBUG[32496] chan_zap.c: Set option AUDIO MODE, value: OFF(0) on Zap/2-1
[Nov 11 19:34:11] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 207
[Nov 11 19:34:11] VERBOSE[32496] logger.c: -- Hungup 'Zap/2-1'
[Nov 11 19:34:11] NOTICE[32496] cdr.c: CDR on channel 'Zap/2-1' not posted
[Nov 11 19:34:11] VERBOSE[32496] logger.c: == Spawn extension (macro-dialout-trunk, s, 23) exited non-zero on 'SIP/213-b7de1d70' in macro 'dialout-trunk'
[Nov 11 19:34:11] VERBOSE[32496] logger.c: == Spawn extension (macro-dialout-trunk, s, 23) exited non-zero on 'SIP/213-b7de1d70'
[Nov 11 19:34:11] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 224
[Nov 11 19:34:11] VERBOSE[32496] logger.c: -- Executing [h@macro-dialout-trunk:1] Macro("SIP/213-b7de1d70", "hangupcall") in new stack
[Nov 11 19:34:11] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 214
[Nov 11 19:34:11] VERBOSE[32496] logger.c: -- Executing [s@macro-hangupcall:1] ResetCDR("SIP/213-b7de1d70", "w") in new stack
[Nov 11 19:34:11] DEBUG[32496] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record.
[Nov 11 19:34:11] DEBUG[32496] 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 ('2007-11-11 19:33:37','\"Ext.213\" <213>','213','035783391','from-internal', 'SIP/213-b7de1d70','Zap/2-1','Dial','ZAP/g0/035783391|300|Tt',34,0,'NO ANSWER',3,'','1194802417.1453')
[Nov 11 19:34:11] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 218
[Nov 11 19:34:11] DEBUG[32496] app_macro.c: Executed application: ResetCDR
[Nov 11 19:34:11] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 203
[Nov 11 19:34:11] VERBOSE[32496] logger.c: -- Executing [s@macro-hangupcall:2] NoCDR("SIP/213-b7de1d70", "") in new stack
[Nov 11 19:34:11] DEBUG[32496] app_macro.c: Executed application: NoCDR
[Nov 11 19:34:11] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 206
[Nov 11 19:34:11] VERBOSE[32496] logger.c: -- Executing [s@macro-hangupcall:3] GotoIf("SIP/213-b7de1d70", "1?skiprg") in new stack
[Nov 11 19:34:11] VERBOSE[32496] logger.c: -- Goto (macro-hangupcall,s,6)
[Nov 11 19:34:11] DEBUG[32496] app_macro.c: Executed application: GotoIf
[Nov 11 19:34:11] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 229
[Nov 11 19:34:11] VERBOSE[32496] logger.c: -- Executing [s@macro-hangupcall:6] GotoIf("SIP/213-b7de1d70", "1?skipblkvm") in new stack
[Nov 11 19:34:11] VERBOSE[32496] logger.c: -- Goto (macro-hangupcall,s,9)
[Nov 11 19:34:11] DEBUG[32496] app_macro.c: Executed application: GotoIf
[Nov 11 19:34:11] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 204
[Nov 11 19:34:11] VERBOSE[32496] logger.c: -- Executing [s@macro-hangupcall:9] GotoIf("SIP/213-b7de1d70", "1?theend") in new stack
[Nov 11 19:34:11] VERBOSE[32496] logger.c: -- Goto (macro-hangupcall,s,11)
[Nov 11 19:34:11] DEBUG[32496] app_macro.c: Executed application: GotoIf
[Nov 11 19:34:11] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 225
[Nov 11 19:34:11] VERBOSE[32496] logger.c: -- Executing [s@macro-hangupcall:11] Hangup("SIP/213-b7de1d70", "") in new stack
[Nov 11 19:34:11] VERBOSE[32496] logger.c: == Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'SIP/213-b7de1d70' in macro 'hangupcall'
[Nov 11 19:34:11] VERBOSE[32496] logger.c: == Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'SIP/213-b7de1d70'
[Nov 11 19:34:11] VERBOSE[32499] logger.c: == End MixMonitor Recording SIP/213-b7de1d70
[Nov 11 19:34:11] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 228
[Nov 11 19:34:11] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 226
[Nov 11 19:34:11] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 201
[Nov 11 19:34:11] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 220
[Nov 11 19:34:11] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 219
[Nov 11 19:34:11] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 211
[Nov 11 19:34:11] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 216
[Nov 11 19:34:11] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 212
[Nov 11 19:34:11] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 208
[Nov 11 19:34:11] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 213
[Nov 11 19:34:11] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 223
[Nov 11 19:34:11] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 210
[Nov 11 19:34:11] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 230
[Nov 11 19:34:11] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 227
[Nov 11 19:34:11] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 215
[Nov 11 19:34:11] VERBOSE[23428] logger.c: Extension Changed 213 new state Idle for Notify User 221
[Nov 11 19:34:12] VERBOSE[23428] logger.c: Extension Changed 212 new state InUse for Notify User 207
[Nov 11 19:34:12] VERBOSE[32546] logger.c: -- Executing [039320875@from-internal:1] Macro("SIP/212-b7de1d70", "dialout-trunk|1|039320875||") in new stack
[Nov 11 19:34:12] VERBOSE[32546] logger.c: -- Executing [s@macro-dialout-trunk:1] Set("SIP/212-b7de1d70", "DIAL_TRUNK=1") in new stack
[Nov 11 19:34:12] VERBOSE[23428] logger.c: Extension Changed 212 new state InUse for Notify User 224
[Nov 11 19:34:12] DEBUG[32546] app_macro.c: Executed application: Set
[Nov 11 19:34:12] VERBOSE[32546] logger.c: -- Executing [s@macro-dialout-trunk:2] Set("SIP/212-b7de1d70", "DIAL_NUMBER=039320875") in new stack
[Nov 11 19:34:12] VERBOSE[23428] logger.c: Extension Changed 212 new state InUse for Notify User 214
[Nov 11 19:34:12] DEBUG[32546] app_macro.c: Executed application: Set
[Nov 11 19:34:12] VERBOSE[32546] logger.c: -- Executing [s@macro-dialout-trunk:3] Set("SIP/212-b7de1d70", "ROUTE_PASSWD=") in new stack
[Nov 11 19:34:12] VERBOSE[23428] logger.c: Extension Changed 212 new state InUse for Notify User 218
[Nov 11 19:34:12] DEBUG[32546] app_macro.c: Executed application: Set
[Nov 11 19:34:12] VERBOSE[32546] logger.c: -- Executing [s@macro-dialout-trunk:4] GotoIf("SIP/212-b7de1d70", "1?noauth") in new stack
[Nov 11 19:34:12] VERBOSE[23428] logger.c: Extension Changed 212 new state InUse for Notify User 203
[Nov 11 19:34:12] VERBOSE[32546] logger.c: -- Goto (macro-dialout-trunk,s,6)
[Nov 11 19:34:12] DEBUG[32546] app_macro.c: Executed application: GotoIf
[Nov 11 19:34:12] VERBOSE[32546] logger.c: -- Executing [s@macro-dialout-trunk:6] GotoIf("SIP/212-b7de1d70", "0?disabletrunk|1") in new stack
[Nov 11 19:34:12] DEBUG[32546] app_macro.c: Executed application: GotoIf
[Nov 11 19:34:12] VERBOSE[23428] logger.c: Extension Changed 212 new state InUse for Notify User 206
[Nov 11 19:34:12] VERBOSE[32546] logger.c: -- Executing [s@macro-dialout-trunk:7] Set("SIP/212-b7de1d70", "_NODEST=") in new stack
[Nov 11 19:34:12] DEBUG[32546] app_macro.c: Executed application: Set
[Nov 11 19:34:12] VERBOSE[32546] logger.c: -- Executing [s@macro-dialout-trunk:8] Set("SIP/212-b7de1d70", "DIAL_TRUNK_OPTIONS=Tt") in new stack
[Nov 11 19:34:12] VERBOSE[23428] logger.c: Extension Changed 212 new state InUse for Notify User 229
[Nov 11 19:34:12] DEBUG[32546] app_macro.c: Executed application: Set
[Nov 11 19:34:12] VERBOSE[32546] logger.c: -- Executing [s@macro-dialout-trunk:9] Set("SIP/212-b7de1d70", "GROUP()=OUT_1") in new stack
[Nov 11 19:34:12] VERBOSE[23428] logger.c: Extension Changed 212 new state InUse for Notify User 204
[Nov 11 19:34:12] DEBUG[32546] app_macro.c: Executed application: Set
[Nov 11 19:34:12] VERBOSE[32546] logger.c: -- Executing [s@macro-dialout-trunk:10] Macro("SIP/212-b7de1d70", "user-callerid|SKIPTTL") in new stack
[Nov 11 19:34:12] VERBOSE[23428] logger.c: Extension Changed 212 new state InUse for Notify User 225
[Nov 11 19:34:12] VERBOSE[32546] logger.c: -- Executing [s@macro-user-callerid:1] NoOp("SIP/212-b7de1d70", "user-callerid: device 212") in new stack
[Nov 11 19:34:12] VERBOSE[23428] logger.c: Extension Changed 212 new state InUse for Notify User 228
[Nov 11 19:34:12] DEBUG[32546] app_macro.c: Executed application: Noop
[Nov 11 19:34:12] VERBOSE[23428] logger.c: Extension Changed 212 new state InUse for Notify User 226
[Nov 11 19:34:12] VERBOSE[32546] logger.c: -- Executing [s@macro-user-callerid:2] Set("SIP/212-b7de1d70", "AMPUSER=212") in new stack
[Nov 11 19:34:12] DEBUG[32546] app_macro.c: Executed application: Set
[Nov 11 19:34:12] VERBOSE[32546] logger.c: -- Executing [s@macro-user-callerid:3] GotoIf("SIP/212-b7de1d70", "0?report") in new stack
[Nov 11 19:34:12] DEBUG[32546] app_macro.c: Executed application: GotoIf
[Nov 11 19:34:12] VERBOSE[23428] logger.c: Extension Changed 212 new state InUse for Notify User 201
[Nov 11 19:34:12] VERBOSE[32546] logger.c: -- Executing [s@macro-user-callerid:4] GotoIf("SIP/212-b7de1d70", "0?start") in new stack
[Nov 11 19:34:12] DEBUG[32546] app_macro.c: Executed application: GotoIf
[Nov 11 19:34:12] VERBOSE[32546] logger.c: -- Executing [s@macro-user-callerid:5] Set("SIP/212-b7de1d70", "REALCALLERIDNUM=212") in new stack
[Nov 11 19:34:12] VERBOSE[23428] logger.c: Extension Changed 212 new state InUse for Notify User 220
[Nov 11 19:34:12] DEBUG[32546] app_macro.c: Executed application: Set
[Nov 11 19:34:12] VERBOSE[32546] logger.c: -- Executing [s@macro-user-callerid:6] NoOp("SIP/212-b7de1d70", "REALCALLERIDNUM is 212") in new stack
[Nov 11 19:34:12] VERBOSE[23428] logger.c: Extension Changed 212 new state InUse for Notify User 219
[Nov 11 19:34:12] DEBUG[32546] app_macro.c: Executed application: NoOp
[Nov 11 19:34:12] VERBOSE[32546] logger.c: -- Executing [s@macro-user-callerid:7] Set("SIP/212-b7de1d70", "AMPUSER=212") in new stack
[Nov 11 19:34:12] VERBOSE[23428] logger.c: Extension Changed 212 new state InUse for Notify User 211
[Nov 11 19:34:12] DEBUG[32546] app_macro.c: Executed application: Set
[Nov 11 19:34:12] VERBOSE[23428] logger.c: Extension Changed 212 new state InUse for Notify User 216
[Nov 11 19:34:12] VERBOSE[32546] logger.c: -- Executing [s@macro-user-callerid:8] Set("SIP/212-b7de1d70", "AMPUSERCIDNAME=Ext.212") in new stack
[Nov 11 19:34:12] DEBUG[32546] app_macro.c: Executed application: Set
[Nov 11 19:34:12] VERBOSE[32546] logger.c: -- Executing [s@macro-user-callerid:9] GotoIf("SIP/212-b7de1d70", "0?report") in new stack
[Nov 11 19:34:12] VERBOSE[23428] logger.c: Extension Changed 212 new state InUse for Notify User 212
[Nov 11 19:34:12] DEBUG[32546] app_macro.c: Executed application: GotoIf
[Nov 11 19:34:12] DEBUG[32546] db.c: Unable to find key '212/cidnum' in family 'AMPUSER'
[Nov 11 19:34:12] VERBOSE[23428] logger.c: Extension Changed 212 new state InUse for Notify User 208
[Nov 11 19:34:12] VERBOSE[32546] logger.c: -- Executing [s@macro-user-callerid:10] Set("SIP/212-b7de1d70", "AMPUSERCID=212") in new stack
[Nov 11 19:34:12] DEBUG[32546] app_macro.c: Executed application: Set
[Nov 11 19:34:12] VERBOSE[32546] logger.c: -- Executing [s@macro-user-callerid:11] Set("SIP/212-b7de1d70", "CALLERID(all)="Ext.212" <212>") in new stack
[Nov 11 19:34:12] VERBOSE[23428] logger.c: Extension Changed 212 new state InUse for Notify User 213
[Nov 11 19:34:12] DEBUG[32546] app_macro.c: Executed application: Set
[Nov 11 19:34:12] VERBOSE[32546] logger.c: -- Executing [s@macro-user-callerid:12] Set("SIP/212-b7de1d70", "REALCALLERIDNUM=212") in new stack
[Nov 11 19:34:12] DEBUG[32546] app_macro.c: Executed application: Set
[Nov 11 19:34:12] VERBOSE[23428] logger.c: Extension Changed 212 new state InUse for Notify User 223
[Nov 11 19:34:12] VERBOSE[32546] logger.c: -- Executing [s@macro-user-callerid:13] NoOp("SIP/212-b7de1d70", "TTL: ARG1: SKIPTTL") in new stack
[Nov 11 19:34:12] DEBUG[32546] app_macro.c: Executed application: Noop
[Nov 11 19:34:12] VERBOSE[32546] logger.c: -- Executing [s@macro-user-callerid:14] GotoIf("SIP/212-b7de1d70", "1?continue") in new stack
[Nov 11 19:34:12] VERBOSE[32546] logger.c: -- Goto (macro-user-callerid,s,23)
[Nov 11 19:34:12] DEBUG[32546] app_macro.c: Executed application: GotoIf
[Nov 11 19:34:12] VERBOSE[23428] logger.c: Extension Changed 212 new state InUse for Notify User 210
[Nov 11 19:34:12] VERBOSE[32546] logger.c: -- Executing [s@macro-user-callerid:23] NoOp("SIP/212-b7de1d70", "Using CallerID "Ext.212" <212>") in new stack
[Nov 11 19:34:12] DEBUG[32546] app_macro.c: Executed application: NoOp
[Nov 11 19:34:12] DEBUG[32546] app_macro.c: Executed application: Macro
[Nov 11 19:34:12] VERBOSE[23428] logger.c: Extension Changed 212 new state InUse for Notify User 230
[Nov 11 19:34:12] VERBOSE[32546] logger.c: -- Executing [s@macro-dialout-trunk:11] Macro("SIP/212-b7de1d70", "record-enable|212|OUT") in new stack
[Nov 11 19:34:12] VERBOSE[23428] logger.c: Extension Changed 212 new state InUse for Notify User 227
[Nov 11 19:34:12] VERBOSE[32546] logger.c: -- Executing [s@macro-record-enable:1] GotoIf("SIP/212-b7de1d70", "0?2:4") in new stack
[Nov 11 19:34:12] VERBOSE[32546] logger.c: -- Goto (macro-record-enable,s,4)
[Nov 11 19:34:12] DEBUG[32546] app_macro.c: Executed application: GotoIf
[Nov 11 19:34:12] VERBOSE[32546] logger.c: -- Executing [s@macro-record-enable:4] AGI("SIP/212-b7de1d70", "recordingcheck|20071111-193412|1194802452.1459") in new stack
[Nov 11 19:34:12] VERBOSE[23428] logger.c: Extension Changed 212 new state InUse for Notify User 215
[Nov 11 19:34:12] VERBOSE[32546] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
[Nov 11 19:34:12] VERBOSE[23428] logger.c: Extension Changed 212 new state InUse for Notify User 221
[Nov 11 19:34:12] VERBOSE[32546] logger.c: recordingcheck|20071111-193412|1194802452.1459: Outbound recording enabled.
[Nov 11 19:34:12] VERBOSE[32546] logger.c: recordingcheck|20071111-193412|1194802452.1459: CALLFILENAME=OUT212-20071111-193412-1194802452.1459
[Nov 11 19:34:12] VERBOSE[32546] logger.c: -- AGI Script recordingcheck completed, returning 0
[Nov 11 19:34:12] DEBUG[32546] app_macro.c: Executed application: AGI
[Nov 11 19:34:12] VERBOSE[32546] logger.c: -- Executing [s@macro-record-enable:999] MixMonitor("SIP/212-b7de1d70", "OUT212-20071111-193412-1194802452.1459.WAV") in new stack
[Nov 11 19:34:12] DEBUG[32546] app_macro.c: Executed application: MixMonitor
[Nov 11 19:34:12] DEBUG[32546] app_macro.c: Executed application: Macro
[Nov 11 19:34:12] VERBOSE[32546] logger.c: -- Executing [s@macro-dialout-trunk:12] GotoIf("SIP/212-b7de1d70", "0?skipoutcid") in new stack
[Nov 11 19:34:12] DEBUG[32546] app_macro.c: Executed application: GotoIf
[Nov 11 19:34:12] VERBOSE[32546] logger.c: -- Executing [s@macro-dialout-trunk:13] Set("SIP/212-b7de1d70", "DIAL_TRUNK_OPTIONS=Tt") in new stack
[Nov 11 19:34:12] DEBUG[32546] app_macro.c: Executed application: Set
[Nov 11 19:34:12] VERBOSE[32546] logger.c: -- Executing [s@macro-dialout-trunk:14] Macro("SIP/212-b7de1d70", "outbound-callerid|1") in new stack
[Nov 11 19:34:12] VERBOSE[32549] logger.c: == Begin MixMonitor Recording SIP/212-b7de1d70
[Nov 11 19:34:12] VERBOSE[32546] logger.c: -- Executing [s@macro-outbound-callerid:1] GotoIf("SIP/212-b7de1d70", "1?start") in new stack
[Nov 11 19:34:12] VERBOSE[32546] logger.c: -- Goto (macro-outbound-callerid,s,3)
[Nov 11 19:34:12] DEBUG[32546] app_macro.c: Executed application: GotoIf
[Nov 11 19:34:12] VERBOSE[32546] logger.c: -- Executing [s@macro-outbound-callerid:3] NoOp("SIP/212-b7de1d70", "REALCALLERIDNUM is 212") in new stack
[Nov 11 19:34:12] DEBUG[32546] app_macro.c: Executed application: NoOp
[Nov 11 19:34:12] VERBOSE[32546] logger.c: -- Executing [s@macro-outbound-callerid:4] GotoIf("SIP/212-b7de1d70", "1?normcid") in new stack
[Nov 11 19:34:12] VERBOSE[32546] logger.c: -- Goto (macro-outbound-callerid,s,9)
[Nov 11 19:34:12] DEBUG[32546] app_macro.c: Executed application: GotoIf
[Nov 11 19:34:12] VERBOSE[32546] logger.c: -- Executing [s@macro-outbound-callerid:9] Set("SIP/212-b7de1d70", "USEROUTCID=") in new stack
[Nov 11 19:34:12] DEBUG[32546] app_macro.c: Executed application: Set
[Nov 11 19:34:12] DEBUG[32546] db.c: Unable to find key '212/emergency_cid' in family 'DEVICE'
[Nov 11 19:34:12] DEBUG[32546] func_db.c: DB: DEVICE/212/emergency_cid not found in database.
[Nov 11 19:34:12] VERBOSE[32546] logger.c: -- Executing [s@macro-outbound-callerid:10] Set("SIP/212-b7de1d70", "EMERGENCYCID=") in new stack
[Nov 11 19:34:12] DEBUG[32546] app_macro.c: Executed application: Set
[Nov 11 19:34:12] VERBOSE[32546] logger.c: -- Executing [s@macro-outbound-callerid:11] Set("SIP/212-b7de1d70", "TRUNKOUTCID=") in new stack
[Nov 11 19:34:12] DEBUG[32546] app_macro.c: Executed application: Set
[Nov 11 19:34:12] VERBOSE[32546] logger.c: -- Executing [s@macro-outbound-callerid:12] GotoIf("SIP/212-b7de1d70", "1?trunkcid") in new stack
[Nov 11 19:34:12] VERBOSE[32546] logger.c: -- Goto (macro-outbound-callerid,s,16)
[Nov 11 19:34:12] DEBUG[32546] app_macro.c: Executed application: GotoIf
[Nov 11 19:34:12] VERBOSE[32546] logger.c: -- Executing [s@macro-outbound-callerid:16] GotoIf("SIP/212-b7de1d70", "1?usercid") in new stack
[Nov 11 19:34:12] VERBOSE[32546] logger.c: -- Goto (macro-outbound-callerid,s,18)
[Nov 11 19:34:12] DEBUG[32546] app_macro.c: Executed application: GotoIf
[Nov 11 19:34:12] VERBOSE[32546] logger.c: -- Executing [s@macro-outbound-callerid:18] GotoIf("SIP/212-b7de1d70", "1?report") in new stack
[Nov 11 19:34:12] VERBOSE[32546] logger.c: -- Goto (macro-outbound-callerid,s,22)
[Nov 11 19:34:12] DEBUG[32546] app_macro.c: Executed application: GotoIf
[Nov 11 19:34:12] VERBOSE[32546] logger.c: -- Executing [s@macro-outbound-callerid:22] NoOp("SIP/212-b7de1d70", "CallerID set to "Ext.212" <212>") in new stack
[Nov 11 19:34:12] DEBUG[32546] app_macro.c: Executed application: NoOp
[Nov 11 19:34:12] DEBUG[32546] app_macro.c: Executed application: Macro
[Nov 11 19:34:12] VERBOSE[32546] logger.c: -- Executing [s@macro-dialout-trunk:15] GotoIf("SIP/212-b7de1d70", "1?nomax") in new stack
[Nov 11 19:34:12] VERBOSE[32546] logger.c: -- Goto (macro-dialout-trunk,s,17)
[Nov 11 19:34:12] DEBUG[32546] app_macro.c: Executed application: GotoIf
[Nov 11 19:34:12] VERBOSE[32546] logger.c: -- Executing [s@macro-dialout-trunk:17] AGI("SIP/212-b7de1d70", "fixlocalprefix") in new stack
[Nov 11 19:34:12] VERBOSE[32546] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/fixlocalprefix
[Nov 11 19:34:12] VERBOSE[32546] logger.c: > fixlocalprefix: Using pattern .
[Nov 11 19:34:12] VERBOSE[32546] logger.c: == fixlocalprefix: Dialpattern . matched. 039320875 -> 039320875
[Nov 11 19:34:12] VERBOSE[32546] logger.c: -- AGI Script fixlocalprefix completed, returning 0
[Nov 11 19:34:12] DEBUG[32546] app_macro.c: Executed application: AGI
[Nov 11 19:34:12] VERBOSE[32546] logger.c: -- Executing [s@macro-dialout-trunk:18] Set("SIP/212-b7de1d70", "OUTNUM=039320875") in new stack
[Nov 11 19:34:12] DEBUG[32546] app_macro.c: Executed application: Set
[Nov 11 19:34:12] VERBOSE[32546] logger.c: -- Executing [s@macro-dialout-trunk:19] Set("SIP/212-b7de1d70", "custom=ZAP/g0") in new stack
[Nov 11 19:34:12] DEBUG[32546] app_macro.c: Executed application: Set
[Nov 11 19:34:12] VERBOSE[32546] logger.c: -- Executing [s@macro-dialout-trunk:20] GotoIf("SIP/212-b7de1d70", "1?gocall") in new stack
[Nov 11 19:34:12] VERBOSE[32546] logger.c: -- Goto (macro-dialout-trunk,s,22)
[Nov 11 19:34:12] DEBUG[32546] app_macro.c: Executed application: GotoIf
[Nov 11 19:34:12] VERBOSE[32546] logger.c: -- Executing [s@macro-dialout-trunk:22] GotoIf("SIP/212-b7de1d70", "0?customtrunk") in new stack
[Nov 11 19:34:12] DEBUG[32546] app_macro.c: Executed application: GotoIf
[Nov 11 19:34:12] VERBOSE[32546] logger.c: -- Executing [s@macro-dialout-trunk:23] Dial("SIP/212-b7de1d70", "ZAP/g0/039320875|300|Tt") in new stack
[Nov 11 19:34:12] VERBOSE[32546] logger.c: -- Requested transfer capability: 0x00 - SPEECH
[Nov 11 19:34:12] VERBOSE[32546] logger.c: -- Called g0/039320875
[Nov 11 19:34:13] DEBUG[23454] chan_zap.c: Queuing frame from PRI_EVENT_PROCEEDING on channel 0/2 span 1
[Nov 11 19:34:13] VERBOSE[32546] logger.c: -- Zap/2-1 is proceeding passing it to SIP/212-b7de1d70
[Nov 11 19:34:13] VERBOSE[32546] logger.c: -- Zap/2-1 is ringing
[Nov 11 19:34:24] VERBOSE[23426] logger.c: -- Remote UNIX connection
[Nov 11 19:34:24] VERBOSE[32554] logger.c: -- Remote UNIX connection disconnected
[Nov 11 19:34:27] VERBOSE[31262] logger.c: == Done Spying on channel SIP/207-b7dc64d0
[Nov 11 19:34:27] VERBOSE[31262] logger.c: -- Playing 'beep' (language 'he')
[Nov 11 19:34:28] VERBOSE[23426] logger.c: -- Remote UNIX connection
[Nov 11 19:34:28] VERBOSE[32556] logger.c: -- Remote UNIX connection disconnected
[Nov 11 19:34:28] DEBUG[32442] chan_zap.c: Set option AUDIO MODE, value: ON(1) on Zap/3-1
[Nov 11 19:34:28] DEBUG[32442] chan_zap.c: Not yet hungup... Calling hangup once with icause, and clearing call
[Nov 11 19:34:28] DEBUG[32442] chan_zap.c: Set option AUDIO MODE, value: OFF(0) on Zap/3-1
[Nov 11 19:34:28] VERBOSE[32442] logger.c: -- Hungup 'Zap/3-1'
[Nov 11 19:34:28] VERBOSE[32442] logger.c: == Spawn extension (macro-dialout-trunk, s, 23) exited non-zero on 'SIP/216-b7d96730' in macro 'dialout-trunk'
[Nov 11 19:34:28] VERBOSE[32442] logger.c: == Spawn extension (macro-dialout-trunk, s, 23) exited non-zero on 'SIP/216-b7d96730'
[Nov 11 19:34:28] VERBOSE[32442] logger.c: -- Executing [h@macro-dialout-trunk:1] Macro("SIP/216-b7d96730", "hangupcall") in new stack
[Nov 11 19:34:28] VERBOSE[32442] logger.c: -- Executing [s@macro-hangupcall:1] ResetCDR("SIP/216-b7d96730", "w") in new stack
[Nov 11 19:34:28] DEBUG[32442] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record.
[Nov 11 19:34:28] DEBUG[32442] 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 ('2007-11-11 19:32:55','\"Ext.216\" <216>','216','9991492','from-internal', 'SIP/216-b7d96730','Zap/3-1','Dial','ZAP/g0/9991492|300|Tt',93,87,'ANSWERED',3,'','1194802375.1447')
[Nov 11 19:34:28] DEBUG[32442] app_macro.c: Executed application: ResetCDR
[Nov 11 19:34:28] VERBOSE[32442] logger.c: -- Executing [s@macro-hangupcall:2] NoCDR("SIP/216-b7d96730", "") in new stack
[Nov 11 19:34:28] DEBUG[32442] app_macro.c: Executed application: NoCDR
[Nov 11 19:34:28] VERBOSE[32442] logger.c: -- Executing [s@macro-hangupcall:3] GotoIf("SIP/216-b7d96730", "1?skiprg") in new stack
[Nov 11 19:34:28] VERBOSE[32442] logger.c: -- Goto (macro-hangupcall,s,6)
[Nov 11 19:34:28] DEBUG[32442] app_macro.c: Executed application: GotoIf
[Nov 11 19:34:28] VERBOSE[32442] logger.c: -- Executing [s@macro-hangupcall:6] GotoIf("SIP/216-b7d96730", "1?skipblkvm") in new stack
[Nov 11 19:34:28] VERBOSE[32442] logger.c: -- Goto (macro-hangupcall,s,9)
[Nov 11 19:34:28] DEBUG[32442] app_macro.c: Executed application: GotoIf
[Nov 11 19:34:28] VERBOSE[32442] logger.c: -- Executing [s@macro-hangupcall:9] GotoIf("SIP/216-b7d96730", "1?theend") in new stack
[Nov 11 19:34:28] VERBOSE[32442] logger.c: -- Goto (macro-hangupcall,s,11)
[Nov 11 19:34:28] DEBUG[32442] app_macro.c: Executed application: GotoIf
[Nov 11 19:34:28] VERBOSE[32442] logger.c: -- Executing [s@macro-hangupcall:11] Hangup("SIP/216-b7d96730", "") in new stack
[Nov 11 19:34:28] VERBOSE[32442] logger.c: == Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'SIP/216-b7d96730' in macro 'hangupcall'
[Nov 11 19:34:28] VERBOSE[32442] logger.c: == Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'SIP/216-b7d96730'
[Nov 11 19:34:28] VERBOSE[32445] logger.c: == End MixMonitor Recording SIP/216-b7d96730
[Nov 11 19:34:28] VERBOSE[23428] logger.c: Extension Changed 216 new state Idle for Notify User 207
[Nov 11 19:34:28] VERBOSE[23428] logger.c: Extension Changed 216 new state Idle for Notify User 224
[Nov 11 19:34:28] VERBOSE[23428] logger.c: Extension Changed 216 new state Idle for Notify User 214
[Nov 11 19:34:28] VERBOSE[23428] logger.c: Extension Changed 216 new state Idle for Notify User 218
[Nov 11 19:34:28] VERBOSE[23428] logger.c: Extension Changed 216 new state Idle for Notify User 203
[Nov 11 19:34:28] VERBOSE[23428] logger.c: Extension Changed 216 new state Idle for Notify User 206
[Nov 11 19:34:28] VERBOSE[23428] logger.c: Extension Changed 216 new state Idle for Notify User 229
[Nov 11 19:34:28] VERBOSE[23428] logger.c: Extension Changed 216 new state Idle for Notify User 204
[Nov 11 19:34:28] VERBOSE[23428] logger.c: Extension Changed 216 new state Idle for Notify User 225
[Nov 11 19:34:28] VERBOSE[23428] logger.c: Extension Changed 216 new state Idle for Notify User 228
[Nov 11 19:34:28] VERBOSE[23428] logger.c: Extension Changed 216 new state Idle for Notify User 226
[Nov 11 19:34:28] VERBOSE[23428] logger.c: Extension Changed 216 new state Idle for Notify User 201
[Nov 11 19:34:28] VERBOSE[23428] logger.c: Extension Changed 216 new state Idle for Notify User 220
[Nov 11 19:34:28] VERBOSE[23428] logger.c: Extension Changed 216 new state Idle for Notify User 219
[Nov 11 19:34:28] VERBOSE[23428] logger.c: Extension Changed 216 new state Idle for Notify User 211
[Nov 11 19:34:28] VERBOSE[23428] logger.c: Extension Changed 216 new state Idle for Notify User 216
[Nov 11 19:34:28] VERBOSE[23428] logger.c: Extension Changed 216 new state Idle for Notify User 212
[Nov 11 19:34:28] VERBOSE[23428] logger.c: Extension Changed 216 new state Idle for Notify User 208
[Nov 11 19:34:28] VERBOSE[23428] logger.c: Extension Changed 216 new state Idle for Notify User 213
[Nov 11 19:34:28] VERBOSE[23428] logger.c: Extension Changed 216 new state Idle for Notify User 223
[Nov 11 19:34:28] VERBOSE[23428] logger.c: Extension Changed 216 new state Idle for Notify User 210
[Nov 11 19:34:28] VERBOSE[23428] logger.c: Extension Changed 216 new state Idle for Notify User 230
[Nov 11 19:34:28] VERBOSE[23428] logger.c: Extension Changed 216 new state Idle for Notify User 227
[Nov 11 19:34:28] VERBOSE[23428] logger.c: Extension Changed 216 new state Idle for Notify User 215
[Nov 11 19:34:28] VERBOSE[23428] logger.c: Extension Changed 216 new state Idle for Notify User 221
[Nov 11 19:34:32] VERBOSE[31262] logger.c: -- Playing 'spy-sip' (language 'he')
[Nov 11 19:34:33] VERBOSE[31262] logger.c: -- Playing 'digits/2' (language 'he')
[Nov 11 19:34:34] VERBOSE[31262] logger.c: -- Playing 'digits/1' (language 'he')
[Nov 11 19:34:34] VERBOSE[31262] logger.c: -- Playing 'digits/2' (language 'he')
[Nov 11 19:34:35] VERBOSE[31262] logger.c: == Spying on channel SIP/212-b7de1d70
[Nov 11 19:34:35] NOTICE[31262] app_chanspy.c: Attaching Zap/6-1 to SIP/212-b7de1d70
[Nov 11 19:34:35] DEBUG[32525] chan_zap.c: Set option AUDIO MODE, value: ON(1) on Zap/5-1
[Nov 11 19:34:35] DEBUG[32525] chan_zap.c: Not yet hungup... Calling hangup once with icause, and clearing call
[Nov 11 19:34:35] DEBUG[32525] chan_zap.c: Set option AUDIO MODE, value: OFF(0) on Zap/5-1
[Nov 11 19:34:35] VERBOSE[32525] logger.c: -- Hungup 'Zap/5-1'
[Nov 11 19:34:35] NOTICE[32525] cdr.c: CDR on channel 'Zap/5-1' not posted
[Nov 11 19:34:35] VERBOSE[23428] logger.c: Extension Changed 207 new state Idle for Notify User 207
[Nov 11 19:34:35] VERBOSE[32525] logger.c: == Spawn extension (macro-dialout-trunk, s, 23) exited non-zero on 'SIP/207-b7dc64d0' in macro 'dialout-trunk'
[Nov 11 19:34:35] VERBOSE[32525] logger.c: == Spawn extension (macro-dialout-trunk, s, 23) exited non-zero on 'SIP/207-b7dc64d0'
[Nov 11 19:34:35] VERBOSE[32525] logger.c: -- Executing [h@macro-dialout-trunk:1] Macro("SIP/207-b7dc64d0", "hangupcall") in new stack
[Nov 11 19:34:35] VERBOSE[23428] logger.c: Extension Changed 207 new state Idle for Notify User 224
[Nov 11 19:34:35] VERBOSE[32525] logger.c: -- Executing [s@macro-hangupcall:1] ResetCDR("SIP/207-b7dc64d0", "w") in new stack
[Nov 11 19:34:35] DEBUG[32525] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record.
[Nov 11 19:34:35] DEBUG[32525] 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 ('2007-11-11 19:34:01','\"Ext.207\" <207>','207','0527617089','from-internal', 'SIP/207-b7dc64d0','Zap/5-1','Dial','ZAP/g0/0527617089|300|Tt',34,0,'NO ANSWER',3,'','1194802441.1457')
[Nov 11 19:34:35] VERBOSE[23428] logger.c: Extension Changed 207 new state Idle for Notify User 214
[Nov 11 19:34:35] DEBUG[32525] app_macro.c: Executed application: ResetCDR
[Nov 11 19:34:35] VERBOSE[23428] logger.c: Extension Changed 207 new state Idle for Notify User 218
[Nov 11 19:34:35] VERBOSE[32525] logger.c: -- Executing [s@macro-hangupcall:2] NoCDR("SIP/207-b7dc64d0", "") in new stack
[Nov 11 19:34:35] DEBUG[32525] app_macro.c: Executed application: NoCDR
[Nov 11 19:34:35] VERBOSE[23428] logger.c: Extension Changed 207 new state Idle for Notify User 203
[Nov 11 19:34:35] VERBOSE[32525] logger.c: -- Executing [s@macro-hangupcall:3] GotoIf("SIP/207-b7dc64d0", "1?skiprg") in new stack
[Nov 11 19:34:35] VERBOSE[32525] logger.c: -- Goto (macro-hangupcall,s,6)
[Nov 11 19:34:35] DEBUG[32525] app_macro.c: Executed application: GotoIf
[Nov 11 19:34:35] VERBOSE[23428] logger.c: Extension Changed 207 new state Idle for Notify User 206
[Nov 11 19:34:35] VERBOSE[32525] logger.c: -- Executing [s@macro-hangupcall:6] GotoIf("SIP/207-b7dc64d0", "1?skipblkvm") in new stack
[Nov 11 19:34:35] VERBOSE[32525] logger.c: -- Goto (macro-hangupcall,s,9)
[Nov 11 19:34:35] DEBUG[32525] app_macro.c: Executed application: GotoIf
[Nov 11 19:34:35] VERBOSE[23428] logger.c: Extension Changed 207 new state Idle for Notify User 229
[Nov 11 19:34:35] VERBOSE[32525] logger.c: -- Executing [s@macro-hangupcall:9] GotoIf("SIP/207-b7dc64d0", "1?theend") in new stack
[Nov 11 19:34:35] VERBOSE[32525] logger.c: -- Goto (macro-hangupcall,s,11)
[Nov 11 19:34:35] DEBUG[32525] app_macro.c: Executed application: GotoIf
[Nov 11 19:34:35] VERBOSE[23428] logger.c: Extension Changed 207 new state Idle for Notify User 204
[Nov 11 19:34:35] VERBOSE[32525] logger.c: -- Executing [s@macro-hangupcall:11] Hangup("SIP/207-b7dc64d0", "") in new stack
[Nov 11 19:34:35] VERBOSE[32525] logger.c: == Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'SIP/207-b7dc64d0' in macro 'hangupcall'
[Nov 11 19:34:35] VERBOSE[32525] logger.c: == Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'SIP/207-b7dc64d0'
[Nov 11 19:34:35] VERBOSE[32528] logger.c: == End MixMonitor Recording SIP/207-b7dc64d0
[Nov 11 19:34:35] VERBOSE[23428] logger.c: Extension Changed 207 new state Idle for Notify User 225
[Nov 11 19:34:35] VERBOSE[23428] logger.c: Extension Changed 207 new state Idle for Notify User 228
[Nov 11 19:34:35] VERBOSE[23428] logger.c: Extension Changed 207 new state Idle for Notify User 226
[Nov 11 19:34:35] VERBOSE[23428] logger.c: Extension Changed 207 new state Idle for Notify User 201
[Nov 11 19:34:35] VERBOSE[23428] logger.c: Extension Changed 207 new state Idle for Notify User 220
[Nov 11 19:34:35] VERBOSE[23428] logger.c: Extension Changed 207 new state Idle for Notify User 219
[Nov 11 19:34:35] VERBOSE[23428] logger.c: Extension Changed 207 new state Idle for Notify User 211
[Nov 11 19:34:35] VERBOSE[23428] logger.c: Extension Changed 207 new state Idle for Notify User 216
[Nov 11 19:34:35] VERBOSE[23428] logger.c: Extension Changed 207 new state Idle for Notify User 212
[Nov 11 19:34:35] VERBOSE[23428] logger.c: Extension Changed 207 new state Idle for Notify User 208
[Nov 11 19:34:35] VERBOSE[23428] logger.c: Extension Changed 207 new state Idle for Notify User 213
[Nov 11 19:34:35] VERBOSE[23428] logger.c: Extension Changed 207 new state Idle for Notify User 223
[Nov 11 19:34:35] VERBOSE[23428] logger.c: Extension Changed 207 new state Idle for Notify User 210
[Nov 11 19:34:35] VERBOSE[23428] logger.c: Extension Changed 207 new state Idle for Notify User 230
[Nov 11 19:34:35] VERBOSE[23428] logger.c: Extension Changed 207 new state Idle for Notify User 227
[Nov 11 19:34:35] VERBOSE[23428] logger.c: Extension Changed 207 new state Idle for Notify User 215
[Nov 11 19:34:35] VERBOSE[23428] logger.c: Extension Changed 207 new state Idle for Notify User 221
[Nov 11 19:34:35] VERBOSE[23426] logger.c: -- Remote UNIX connection
[Nov 11 19:34:36] VERBOSE[32564] logger.c: -- Remote UNIX connection disconnected
[Nov 11 19:34:38] VERBOSE[31262] logger.c: == Done Spying on channel SIP/212-b7de1d70
[Nov 11 19:34:38] VERBOSE[31262] logger.c: -- Playing 'beep' (language 'he')
[Nov 11 19:34:39] VERBOSE[23426] logger.c: -- Remote UNIX connection
[Nov 11 19:34:39] VERBOSE[32568] logger.c: -- Remote UNIX connection disconnected
[Nov 11 19:34:40] VERBOSE[23426] logger.c: -- Remote UNIX connection
[Nov 11 19:34:40] VERBOSE[32570] logger.c: -- Remote UNIX connection disconnected
[Nov 11 19:34:41] VERBOSE[23426] logger.c: -- Remote UNIX connection
[Nov 11 19:34:41] VERBOSE[32572] logger.c: -- Remote UNIX connection disconnected
[Nov 11 19:34:41] VERBOSE[23426] logger.c: -- Remote UNIX connection
[Nov 11 19:34:42] VERBOSE[32574] logger.c: -- Remote UNIX connection disconnected
[Nov 11 19:34:42] VERBOSE[23426] logger.c: -- Remote UNIX connection
[Nov 11 19:34:42] VERBOSE[32576] logger.c: -- Remote UNIX connection disconnected
[Nov 11 19:34:43] VERBOSE[23426] logger.c: -- Remote UNIX connection
[Nov 11 19:34:43] VERBOSE[32578] logger.c: -- Remote UNIX connection disconnected
[Nov 11 19:34:43] VERBOSE[31262] logger.c: -- Playing 'spy-sip' (language 'he')
[Nov 11 19:34:44] VERBOSE[23426] logger.c: -- Remote UNIX connection
[Nov 11 19:34:44] VERBOSE[32580] logger.c: -- Remote UNIX connection disconnected
[Nov 11 19:34:44] VERBOSE[31262] logger.c: -- Playing 'digits/2' (language 'he')
[Nov 11 19:34:45] VERBOSE[23426] logger.c: -- Remote UNIX connection
[Nov 11 19:34:45] VERBOSE[32583] logger.c: -- Remote UNIX connection disconnected
[Nov 11 19:34:45] VERBOSE[31262] logger.c: -- Playing 'digits/1' (language 'he')
[Nov 11 19:34:45] VERBOSE[23426] logger.c: -- Remote UNIX connection
[Nov 11 19:34:45] VERBOSE[32585] logger.c: -- Remote UNIX connection disconnected
[Nov 11 19:34:45] VERBOSE[31262] logger.c: -- Playing 'digits/2' (language 'he')
[Nov 11 19:34:46] VERBOSE[23426] logger.c: -- Remote UNIX connection
[Nov 11 19:34:46] VERBOSE[31262] logger.c: == Spying on channel SIP/212-b7de1d70
[Nov 11 19:34:46] NOTICE[31262] app_chanspy.c: Attaching Zap/6-1 to SIP/212-b7de1d70
[Nov 11 19:34:46] VERBOSE[32587] logger.c: -- Remote UNIX connection disconnected
[Nov 11 19:34:47] VERBOSE[23428] logger.c: Extension Changed 207 new state InUse for Notify User 207
[Nov 11 19:34:47] VERBOSE[32589] logger.c: -- Executing [0777667089@from-internal:1] Macro("SIP/207-b7d96730", "dialout-trunk|1|0777667089||") in new stack
[Nov 11 19:34:47] VERBOSE[23428] logger.c: Extension Changed 207 new state InUse for Notify User 224
[Nov 11 19:34:47] VERBOSE[32589] logger.c: -- Executing [s@macro-dialout-trunk:1] Set("SIP/207-b7d96730", "DIAL_TRUNK=1") in new stack
[Nov 11 19:34:47] DEBUG[32589] app_macro.c: Executed application: Set
[Nov 11 19:34:47] VERBOSE[32589] logger.c: -- Executing [s@macro-dialout-trunk:2] Set("SIP/207-b7d96730", "DIAL_NUMBER=0777667089") in new stack
[Nov 11 19:34:47] DEBUG[32589] app_macro.c: Executed application: Set
[Nov 11 19:34:47] VERBOSE[23428] logger.c: Extension Changed 207 new state InUse for Notify User 214
[Nov 11 19:34:47] VERBOSE[32589] logger.c: -- Executing [s@macro-dialout-trunk:3] Set("SIP/207-b7d96730", "ROUTE_PASSWD=") in new stack
[Nov 11 19:34:47] DEBUG[32589] app_macro.c: Executed application: Set
[Nov 11 19:34:47] VERBOSE[23428] logger.c: Extension Changed 207 new state InUse for Notify User 218
[Nov 11 19:34:47] VERBOSE[32589] logger.c: -- Executing [s@macro-dialout-trunk:4] GotoIf("SIP/207-b7d96730", "1?noauth") in new stack
[Nov 11 19:34:47] VERBOSE[32589] logger.c: -- Goto (macro-dialout-trunk,s,6)
[Nov 11 19:34:47] VERBOSE[23428] logger.c: Extension Changed 207 new state InUse for Notify User 203
[Nov 11 19:34:47] DEBUG[32589] app_macro.c: Executed application: GotoIf
[Nov 11 19:34:47] VERBOSE[32589] logger.c: -- Executing [s@macro-dialout-trunk:6] GotoIf("SIP/207-b7d96730", "0?disabletrunk|1") in new stack
[Nov 11 19:34:47] VERBOSE[23428] logger.c: Extension Changed 207 new state InUse for Notify User 206
[Nov 11 19:34:47] DEBUG[32589] app_macro.c: Executed application: GotoIf
[Nov 11 19:34:47] VERBOSE[32589] logger.c: -- Executing [s@macro-dialout-trunk:7] Set("SIP/207-b7d96730", "_NODEST=") in new stack
[Nov 11 19:34:47] DEBUG[32589] app_macro.c: Executed application: Set
[Nov 11 19:34:47] VERBOSE[23428] logger.c: Extension Changed 207 new state InUse for Notify User 229
[Nov 11 19:34:47] VERBOSE[32589] logger.c: -- Executing [s@macro-dialout-trunk:8] Set("SIP/207-b7d96730", "DIAL_TRUNK_OPTIONS=Tt") in new stack
[Nov 11 19:34:47] VERBOSE[23428] logger.c: Extension Changed 207 new state InUse for Notify User 204
[Nov 11 19:34:47] DEBUG[32589] app_macro.c: Executed application: Set
[Nov 11 19:34:47] VERBOSE[32589] logger.c: -- Executing [s@macro-dialout-trunk:9] Set("SIP/207-b7d96730", "GROUP()=OUT_1") in new stack
[Nov 11 19:34:47] VERBOSE[23428] logger.c: Extension Changed 207 new state InUse for Notify User 225
[Nov 11 19:34:47] DEBUG[32589] app_macro.c: Executed application: Set
[Nov 11 19:34:47] VERBOSE[32589] logger.c: -- Executing [s@macro-dialout-trunk:10] Macro("SIP/207-b7d96730", "user-callerid|SKIPTTL") in new stack
[Nov 11 19:34:47] VERBOSE[23428] logger.c: Extension Changed 207 new state InUse for Notify User 228
[Nov 11 19:34:47] VERBOSE[32589] logger.c: -- Executing [s@macro-user-callerid:1] NoOp("SIP/207-b7d96730", "user-callerid: device 207") in new stack
[Nov 11 19:34:47] DEBUG[32589] app_macro.c: Executed application: Noop
[Nov 11 19:34:47] VERBOSE[23428] logger.c: Extension Changed 207 new state InUse for Notify User 226
[Nov 11 19:34:47] VERBOSE[32589] logger.c: -- Executing [s@macro-user-callerid:2] Set("SIP/207-b7d96730", "AMPUSER=207") in new stack
[Nov 11 19:34:47] DEBUG[32589] app_macro.c: Executed application: Set
[Nov 11 19:34:47] VERBOSE[32589] logger.c: -- Executing [s@macro-user-callerid:3] GotoIf("SIP/207-b7d96730", "0?report") in new stack
[Nov 11 19:34:47] VERBOSE[23428] logger.c: Extension Changed 207 new state InUse for Notify User 201
[Nov 11 19:34:47] DEBUG[32589] app_macro.c: Executed application: GotoIf
[Nov 11 19:34:47] VERBOSE[32589] logger.c: -- Executing [s@macro-user-callerid:4] GotoIf("SIP/207-b7d96730", "0?start") in new stack
[Nov 11 19:34:47] DEBUG[32589] app_macro.c: Executed application: GotoIf
[Nov 11 19:34:47] VERBOSE[23428] logger.c: Extension Changed 207 new state InUse for Notify User 220
[Nov 11 19:34:47] VERBOSE[32589] logger.c: -- Executing [s@macro-user-callerid:5] Set("SIP/207-b7d96730", "REALCALLERIDNUM=207") in new stack
[Nov 11 19:34:47] DEBUG[32589] app_macro.c: Executed application: Set
[Nov 11 19:34:47] VERBOSE[32589] logger.c: -- Executing [s@macro-user-callerid:6] NoOp("SIP/207-b7d96730", "REALCALLERIDNUM is 207") in new stack
[Nov 11 19:34:47] DEBUG[32589] app_macro.c: Executed application: NoOp
[Nov 11 19:34:47] VERBOSE[23428] logger.c: Extension Changed 207 new state InUse for Notify User 219
[Nov 11 19:34:47] VERBOSE[32589] logger.c: -- Executing [s@macro-user-callerid:7] Set("SIP/207-b7d96730", "AMPUSER=207") in new stack
[Nov 11 19:34:47] DEBUG[32589] app_macro.c: Executed application: Set
[Nov 11 19:34:47] VERBOSE[32589] logger.c: -- Executing [s@macro-user-callerid:8] Set("SIP/207-b7d96730", "AMPUSERCIDNAME=Ext.207") in new stack
[Nov 11 19:34:47] VERBOSE[23428] logger.c: Extension Changed 207 new state InUse for Notify User 211
[Nov 11 19:34:47] DEBUG[32589] app_macro.c: Executed application: Set
[Nov 11 19:34:47] VERBOSE[32589] logger.c: -- Executing [s@macro-user-callerid:9] GotoIf("SIP/207-b7d96730", "0?report") in new stack
[Nov 11 19:34:47] VERBOSE[23428] logger.c: Extension Changed 207 new state InUse for Notify User 216
[Nov 11 19:34:47] DEBUG[32589] app_macro.c: Executed application: GotoIf
[Nov 11 19:34:47] DEBUG[32589] db.c: Unable to find key '207/cidnum' in family 'AMPUSER'
[Nov 11 19:34:47] VERBOSE[23428] logger.c: Extension Changed 207 new state InUse for Notify User 212
[Nov 11 19:34:47] VERBOSE[32589] logger.c: -- Executing [s@macro-user-callerid:10] Set("SIP/207-b7d96730", "AMPUSERCID=207") in new stack
[Nov 11 19:34:47] DEBUG[32589] app_macro.c: Executed application: Set
[Nov 11 19:34:47] VERBOSE[32589] logger.c: -- Executing [s@macro-user-callerid:11] Set("SIP/207-b7d96730", "CALLERID(all)="Ext.207" <207>") in new stack
[Nov 11 19:34:47] VERBOSE[23428] logger.c: Extension Changed 207 new state InUse for Notify User 208
[Nov 11 19:34:47] DEBUG[32589] app_macro.c: Executed application: Set
[Nov 11 19:34:47] VERBOSE[32589] logger.c: -- Executing [s@macro-user-callerid:12] Set("SIP/207-b7d96730", "REALCALLERIDNUM=207") in new stack
[Nov 11 19:34:47] VERBOSE[23428] logger.c: Extension Changed 207 new state InUse for Notify User 213
[Nov 11 19:34:47] DEBUG[32589] app_macro.c: Executed application: Set
[Nov 11 19:34:47] VERBOSE[32589] logger.c: -- Executing [s@macro-user-callerid:13] NoOp("SIP/207-b7d96730", "TTL: ARG1: SKIPTTL") in new stack
[Nov 11 19:34:47] VERBOSE[23428] logger.c: Extension Changed 207 new state InUse for Notify User 223
[Nov 11 19:34:47] DEBUG[32589] app_macro.c: Executed application: Noop
[Nov 11 19:34:47] VERBOSE[32589] logger.c: -- Executing [s@macro-user-callerid:14] GotoIf("SIP/207-b7d96730", "1?continue") in new stack
[Nov 11 19:34:47] VERBOSE[23428] logger.c: Extension Changed 207 new state InUse for Notify User 210
[Nov 11 19:34:47] VERBOSE[32589] logger.c: -- Goto (macro-user-callerid,s,23)
[Nov 11 19:34:47] DEBUG[32589] app_macro.c: Executed application: GotoIf
[Nov 11 19:34:47] VERBOSE[32589] logger.c: -- Executing [s@macro-user-callerid:23] NoOp("SIP/207-b7d96730", "Using CallerID "Ext.207" <207>") in new stack
[Nov 11 19:34:47] VERBOSE[23428] logger.c: Extension Changed 207 new state InUse for Notify User 230
[Nov 11 19:34:47] DEBUG[32589] app_macro.c: Executed application: NoOp
[Nov 11 19:34:47] DEBUG[32589] app_macro.c: Executed application: Macro
[Nov 11 19:34:47] VERBOSE[23428] logger.c: Extension Changed 207 new state InUse for Notify User 227
[Nov 11 19:34:47] VERBOSE[32589] logger.c: -- Executing [s@macro-dialout-trunk:11] Macro("SIP/207-b7d96730", "record-enable|207|OUT") in new stack
[Nov 11 19:34:47] VERBOSE[23428] logger.c: Extension Changed 207 new state InUse for Notify User 215
[Nov 11 19:34:47] VERBOSE[32589] logger.c: -- Executing [s@macro-record-enable:1] GotoIf("SIP/207-b7d96730", "0?2:4") in new stack
[Nov 11 19:34:47] VERBOSE[32589] logger.c: -- Goto (macro-record-enable,s,4)
[Nov 11 19:34:47] DEBUG[32589] app_macro.c: Executed application: GotoIf
[Nov 11 19:34:47] VERBOSE[32589] logger.c: -- Executing [s@macro-record-enable:4] AGI("SIP/207-b7d96730", "recordingcheck|20071111-193447|1194802487.1461") in new stack
[Nov 11 19:34:47] VERBOSE[23428] logger.c: Extension Changed 207 new state InUse for Notify User 221
[Nov 11 19:34:47] VERBOSE[32589] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
[Nov 11 19:34:47] VERBOSE[32589] logger.c: recordingcheck|20071111-193447|1194802487.1461: Outbound recording enabled.
[Nov 11 19:34:47] VERBOSE[32589] logger.c: recordingcheck|20071111-193447|1194802487.1461: CALLFILENAME=OUT207-20071111-193447-1194802487.1461
[Nov 11 19:34:47] VERBOSE[32589] logger.c: -- AGI Script recordingcheck completed, returning 0
[Nov 11 19:34:47] DEBUG[32589] app_macro.c: Executed application: AGI
[Nov 11 19:34:47] VERBOSE[32589] logger.c: -- Executing [s@macro-record-enable:999] MixMonitor("SIP/207-b7d96730", "OUT207-20071111-193447-1194802487.1461.WAV") in new stack
[Nov 11 19:34:47] DEBUG[32589] app_macro.c: Executed application: MixMonitor
[Nov 11 19:34:47] DEBUG[32589] app_macro.c: Executed application: Macro
[Nov 11 19:34:47] VERBOSE[32589] logger.c: -- Executing [s@macro-dialout-trunk:12] GotoIf("SIP/207-b7d96730", "0?skipoutcid") in new stack
[Nov 11 19:34:47] DEBUG[32589] app_macro.c: Executed application: GotoIf
[Nov 11 19:34:47] VERBOSE[32589] logger.c: -- Executing [s@macro-dialout-trunk:13] Set("SIP/207-b7d96730", "DIAL_TRUNK_OPTIONS=Tt") in new stack
[Nov 11 19:34:47] DEBUG[32589] app_macro.c: Executed application: Set
[Nov 11 19:34:47] VERBOSE[32589] logger.c: -- Executing [s@macro-dialout-trunk:14] Macro("SIP/207-b7d96730", "outbound-callerid|1") in new stack
[Nov 11 19:34:47] VERBOSE[32589] logger.c: -- Executing [s@macro-outbound-callerid:1] GotoIf("SIP/207-b7d96730", "1?start") in new stack
[Nov 11 19:34:47] VERBOSE[32589] logger.c: -- Goto (macro-outbound-callerid,s,3)
[Nov 11 19:34:47] DEBUG[32589] app_macro.c: Executed application: GotoIf
[Nov 11 19:34:47] VERBOSE[32589] logger.c: -- Executing [s@macro-outbound-callerid:3] NoOp("SIP/207-b7d96730", "REALCALLERIDNUM is 207") in new stack
[Nov 11 19:34:47] DEBUG[32589] app_macro.c: Executed application: NoOp
[Nov 11 19:34:47] VERBOSE[32589] logger.c: -- Executing [s@macro-outbound-callerid:4] GotoIf("SIP/207-b7d96730", "1?normcid") in new stack
[Nov 11 19:34:47] VERBOSE[32589] logger.c: -- Goto (macro-outbound-callerid,s,9)
[Nov 11 19:34:47] DEBUG[32589] app_macro.c: Executed application: GotoIf
[Nov 11 19:34:47] VERBOSE[32589] logger.c: -- Executing [s@macro-outbound-callerid:9] Set("SIP/207-b7d96730", "USEROUTCID=") in new stack
[Nov 11 19:34:47] DEBUG[32589] app_macro.c: Executed application: Set
[Nov 11 19:34:47] DEBUG[32589] db.c: Unable to find key '207/emergency_cid' in family 'DEVICE'
[Nov 11 19:34:47] DEBUG[32589] func_db.c: DB: DEVICE/207/emergency_cid not found in database.
[Nov 11 19:34:47] VERBOSE[32589] logger.c: -- Executing [s@macro-outbound-callerid:10] Set("SIP/207-b7d96730", "EMERGENCYCID=") in new stack
[Nov 11 19:34:47] DEBUG[32589] app_macro.c: Executed application: Set
[Nov 11 19:34:47] VERBOSE[32589] logger.c: -- Executing [s@macro-outbound-callerid:11] Set("SIP/207-b7d96730", "TRUNKOUTCID=") in new stack
[Nov 11 19:34:47] DEBUG[32589] app_macro.c: Executed application: Set
[Nov 11 19:34:47] VERBOSE[32589] logger.c: -- Executing [s@macro-outbound-callerid:12] GotoIf("SIP/207-b7d96730", "1?trunkcid") in new stack
[Nov 11 19:34:47] VERBOSE[32589] logger.c: -- Goto (macro-outbound-callerid,s,16)
[Nov 11 19:34:47] DEBUG[32589] app_macro.c: Executed application: GotoIf
[Nov 11 19:34:47] VERBOSE[32589] logger.c: -- Executing [s@macro-outbound-callerid:16] GotoIf("SIP/207-b7d96730", "1?usercid") in new stack
[Nov 11 19:34:47] VERBOSE[32589] logger.c: -- Goto (macro-outbound-callerid,s,18)
[Nov 11 19:34:47] DEBUG[32589] app_macro.c: Executed application: GotoIf
[Nov 11 19:34:47] VERBOSE[32589] logger.c: -- Executing [s@macro-outbound-callerid:18] GotoIf("SIP/207-b7d96730", "1?report") in new stack
[Nov 11 19:34:47] VERBOSE[32589] logger.c: -- Goto (macro-outbound-callerid,s,22)
[Nov 11 19:34:47] DEBUG[32589] app_macro.c: Executed application: GotoIf
[Nov 11 19:34:47] VERBOSE[32589] logger.c: -- Executing [s@macro-outbound-callerid:22] NoOp("SIP/207-b7d96730", "CallerID set to "Ext.207" <207>") in new stack
[Nov 11 19:34:47] DEBUG[32589] app_macro.c: Executed application: NoOp
[Nov 11 19:34:47] DEBUG[32589] app_macro.c: Executed application: Macro
[Nov 11 19:34:47] VERBOSE[32589] logger.c: -- Executing [s@macro-dialout-trunk:15] GotoIf("SIP/207-b7d96730", "1?nomax") in new stack
[Nov 11 19:34:47] VERBOSE[32589] logger.c: -- Goto (macro-dialout-trunk,s,17)
[Nov 11 19:34:47] DEBUG[32589] app_macro.c: Executed application: GotoIf
[Nov 11 19:34:47] VERBOSE[32589] logger.c: -- Executing [s@macro-dialout-trunk:17] AGI("SIP/207-b7d96730", "fixlocalprefix") in new stack
[Nov 11 19:34:47] VERBOSE[32592] logger.c: == Begin MixMonitor Recording SIP/207-b7d96730
[Nov 11 19:34:47] VERBOSE[32589] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/fixlocalprefix
[Nov 11 19:34:47] VERBOSE[32589] logger.c: > fixlocalprefix: Using pattern .
[Nov 11 19:34:47] VERBOSE[32589] logger.c: == fixlocalprefix: Dialpattern . matched. 0777667089 -> 0777667089
[Nov 11 19:34:47] VERBOSE[32589] logger.c: -- AGI Script fixlocalprefix completed, returning 0
[Nov 11 19:34:47] DEBUG[32589] app_macro.c: Executed application: AGI
[Nov 11 19:34:47] VERBOSE[32589] logger.c: -- Executing [s@macro-dialout-trunk:18] Set("SIP/207-b7d96730", "OUTNUM=0777667089") in new stack
[Nov 11 19:34:47] DEBUG[32589] app_macro.c: Executed application: Set
[Nov 11 19:34:47] VERBOSE[32589] logger.c: -- Executing [s@macro-dialout-trunk:19] Set("SIP/207-b7d96730", "custom=ZAP/g0") in new stack
[Nov 11 19:34:47] DEBUG[32589] app_macro.c: Executed application: Set
[Nov 11 19:34:47] VERBOSE[32589] logger.c: -- Executing [s@macro-dialout-trunk:20] GotoIf("SIP/207-b7d96730", "1?gocall") in new stack
[Nov 11 19:34:47] VERBOSE[32589] logger.c: -- Goto (macro-dialout-trunk,s,22)
[Nov 11 19:34:47] DEBUG[32589] app_macro.c: Executed application: GotoIf
[Nov 11 19:34:47] VERBOSE[32589] logger.c: -- Executing [s@macro-dialout-trunk:22] GotoIf("SIP/207-b7d96730", "0?customtrunk") in new stack
[Nov 11 19:34:47] DEBUG[32589] app_macro.c: Executed application: GotoIf
[Nov 11 19:34:47] VERBOSE[32589] logger.c: -- Executing [s@macro-dialout-trunk:23] Dial("SIP/207-b7d96730", "ZAP/g0/0777667089|300|Tt") in new stack
[Nov 11 19:34:47] VERBOSE[32589] logger.c: -- Requested transfer capability: 0x00 - SPEECH
[Nov 11 19:34:47] VERBOSE[32589] logger.c: -- Called g0/0777667089
[Nov 11 19:34:48] DEBUG[23454] chan_zap.c: Queuing frame from PRI_EVENT_PROCEEDING on channel 0/3 span 1
[Nov 11 19:34:48] VERBOSE[32589] logger.c: -- Zap/3-1 is proceeding passing it to SIP/207-b7d96730
[Nov 11 19:34:48] VERBOSE[32595] logger.c: == Parsing '/etc/asterisk/manager.conf': [Nov 11 19:34:48] VERBOSE[32595] logger.c: Found
[Nov 11 19:34:48] VERBOSE[32595] logger.c: == Parsing '/etc/asterisk/manager_custom.conf': [Nov 11 19:34:48] VERBOSE[32595] logger.c: Found
[Nov 11 19:34:48] VERBOSE[32595] logger.c: == Manager 'admin' logged on from 127.0.0.1
[Nov 11 19:34:48] VERBOSE[32595] logger.c: == Manager 'admin' logged on from 127.0.0.1