[Feb 6 21:45:41] VERBOSE[3352] logger.c: -- Starting simple switch on 'Zap/1-1'
[Feb 6 21:45:45] NOTICE[3352] chan_zap.c: Got event 18 (Ring Begin)...
[Feb 6 21:45:45] VERBOSE[3352] logger.c: -- Executing [s@from-pstn:1] Set("Zap/1-1", "__FROM_DID=s") in new stack
[Feb 6 21:45:45] VERBOSE[3352] logger.c: -- Executing [s@from-pstn:2] Gosub("Zap/1-1", "app-blacklist-check|s|1") in new stack
[Feb 6 21:45:45] VERBOSE[3352] logger.c: -- Executing [s@app-blacklist-check:1] LookupBlacklist("Zap/1-1", "") in new stack
[Feb 6 21:45:45] VERBOSE[3352] logger.c: -- Executing [s@app-blacklist-check:2] GotoIf("Zap/1-1", "0?blacklisted") in new stack
[Feb 6 21:45:45] VERBOSE[3352] logger.c: -- Executing [s@app-blacklist-check:3] Return("Zap/1-1", "") in new stack
[Feb 6 21:45:45] VERBOSE[3352] logger.c: -- Executing [s@from-pstn:3] GotoIf("Zap/1-1", "0 ?cidok") in new stack
[Feb 6 21:45:45] VERBOSE[3352] logger.c: -- Executing [s@from-pstn:4] Set("Zap/1-1", "CALLERID(name)=") in new stack
[Feb 6 21:45:45] VERBOSE[3352] logger.c: -- Executing [s@from-pstn:5] NoOp("Zap/1-1", "CallerID is "" <>") in new stack
[Feb 6 21:45:45] VERBOSE[3352] logger.c: -- Executing [s@from-pstn:6] Goto("Zap/1-1", "from-did-direct|200|1") in new stack
[Feb 6 21:45:45] VERBOSE[3352] logger.c: -- Goto (from-did-direct,200,1)
[Feb 6 21:45:45] VERBOSE[3352] logger.c: -- Executing [200@from-did-direct:1] Macro("Zap/1-1", "exten-vm|200|200") in new stack
[Feb 6 21:45:45] VERBOSE[3352] logger.c: -- Executing [s@macro-exten-vm:1] Macro("Zap/1-1", "user-callerid") in new stack
[Feb 6 21:45:45] VERBOSE[3352] logger.c: -- Executing [s@macro-user-callerid:1] NoOp("Zap/1-1", "user-callerid: ") in new stack
[Feb 6 21:45:45] DEBUG[3352] app_macro.c: Executed application: Noop
[Feb 6 21:45:45] VERBOSE[3352] logger.c: -- Executing [s@macro-user-callerid:2] Set("Zap/1-1", "AMPUSER=") in new stack
[Feb 6 21:45:45] DEBUG[3352] app_macro.c: Executed application: Set
[Feb 6 21:45:45] VERBOSE[3352] logger.c: -- Executing [s@macro-user-callerid:3] GotoIf("Zap/1-1", "0?report") in new stack
[Feb 6 21:45:45] DEBUG[3352] app_macro.c: Executed application: GotoIf
[Feb 6 21:45:45] VERBOSE[3352] logger.c: -- Executing [s@macro-user-callerid:4] GotoIf("Zap/1-1", "0?start") in new stack
[Feb 6 21:45:45] DEBUG[3352] app_macro.c: Executed application: GotoIf
[Feb 6 21:45:45] VERBOSE[3352] logger.c: -- Executing [s@macro-user-callerid:5] Set("Zap/1-1", "REALCALLERIDNUM=") in new stack
[Feb 6 21:45:45] DEBUG[3352] app_macro.c: Executed application: Set
[Feb 6 21:45:45] VERBOSE[3352] logger.c: -- Executing [s@macro-user-callerid:6] NoOp("Zap/1-1", "REALCALLERIDNUM is ") in new stack
[Feb 6 21:45:45] DEBUG[3352] app_macro.c: Executed application: NoOp
[Feb 6 21:45:45] DEBUG[3352] func_db.c: DB: DEVICE//user not found in database.
[Feb 6 21:45:45] VERBOSE[3352] logger.c: -- Executing [s@macro-user-callerid:7] Set("Zap/1-1", "AMPUSER=") in new stack
[Feb 6 21:45:45] DEBUG[3352] app_macro.c: Executed application: Set
[Feb 6 21:45:45] DEBUG[3352] func_db.c: DB: AMPUSER//cidname not found in database.
[Feb 6 21:45:45] VERBOSE[3352] logger.c: -- Executing [s@macro-user-callerid:8] Set("Zap/1-1", "AMPUSERCIDNAME=") in new stack
[Feb 6 21:45:45] DEBUG[3352] app_macro.c: Executed application: Set
[Feb 6 21:45:45] VERBOSE[3352] logger.c: -- Executing [s@macro-user-callerid:9] GotoIf("Zap/1-1", "1?report") in new stack
[Feb 6 21:45:45] VERBOSE[3352] logger.c: -- Goto (macro-user-callerid,s,13)
[Feb 6 21:45:45] DEBUG[3352] app_macro.c: Executed application: GotoIf
[Feb 6 21:45:45] VERBOSE[3352] logger.c: -- Executing [s@macro-user-callerid:13] NoOp("Zap/1-1", "TTL: ARG1: 200") in new stack
[Feb 6 21:45:45] DEBUG[3352] app_macro.c: Executed application: Noop
[Feb 6 21:45:45] VERBOSE[3352] logger.c: -- Executing [s@macro-user-callerid:14] GotoIf("Zap/1-1", "0?continue") in new stack
[Feb 6 21:45:45] DEBUG[3352] app_macro.c: Executed application: GotoIf
[Feb 6 21:45:45] VERBOSE[3352] logger.c: -- Executing [s@macro-user-callerid:15] Set("Zap/1-1", "__TTL=64") in new stack
[Feb 6 21:45:45] DEBUG[3352] app_macro.c: Executed application: Set
[Feb 6 21:45:45] VERBOSE[3352] logger.c: -- Executing [s@macro-user-callerid:16] GotoIf("Zap/1-1", "1?continue") in new stack
[Feb 6 21:45:45] VERBOSE[3352] logger.c: -- Goto (macro-user-callerid,s,23)
[Feb 6 21:45:45] DEBUG[3352] app_macro.c: Executed application: GotoIf
[Feb 6 21:45:45] VERBOSE[3352] logger.c: -- Executing [s@macro-user-callerid:23] NoOp("Zap/1-1", "Using CallerID "" <>") in new stack
[Feb 6 21:45:45] DEBUG[3352] app_macro.c: Executed application: NoOp
[Feb 6 21:45:45] DEBUG[3352] app_macro.c: Executed application: Macro
[Feb 6 21:45:45] VERBOSE[3352] logger.c: -- Executing [s@macro-exten-vm:2] Set("Zap/1-1", "FROMCONTEXT=exten-vm") in new stack
[Feb 6 21:45:45] DEBUG[3352] app_macro.c: Executed application: Set
[Feb 6 21:45:45] VERBOSE[3352] logger.c: -- Executing [s@macro-exten-vm:3] Set("Zap/1-1", "VMBOX=200") in new stack
[Feb 6 21:45:45] DEBUG[3352] app_macro.c: Executed application: Set
[Feb 6 21:45:45] VERBOSE[3352] logger.c: -- Executing [s@macro-exten-vm:4] Set("Zap/1-1", "EXTTOCALL=200") in new stack
[Feb 6 21:45:45] DEBUG[3352] app_macro.c: Executed application: Set
[Feb 6 21:45:45] DEBUG[3352] func_db.c: DB: CFU/200 not found in database.
[Feb 6 21:45:45] VERBOSE[3352] logger.c: -- Executing [s@macro-exten-vm:5] Set("Zap/1-1", "CFUEXT=") in new stack
[Feb 6 21:45:45] DEBUG[3352] app_macro.c: Executed application: Set
[Feb 6 21:45:45] DEBUG[3352] func_db.c: DB: CFB/200 not found in database.
[Feb 6 21:45:45] VERBOSE[3352] logger.c: -- Executing [s@macro-exten-vm:6] Set("Zap/1-1", "CFBEXT=") in new stack
[Feb 6 21:45:45] DEBUG[3352] app_macro.c: Executed application: Set
[Feb 6 21:45:45] VERBOSE[3352] logger.c: -- Executing [s@macro-exten-vm:7] Set("Zap/1-1", "RT=15") in new stack
[Feb 6 21:45:45] DEBUG[3352] app_macro.c: Executed application: Set
[Feb 6 21:45:45] VERBOSE[3352] logger.c: -- Executing [s@macro-exten-vm:8] Macro("Zap/1-1", "record-enable|200|IN") in new stack
[Feb 6 21:45:45] VERBOSE[3352] logger.c: -- Executing [s@macro-record-enable:1] GotoIf("Zap/1-1", "0?2:4") in new stack
[Feb 6 21:45:45] VERBOSE[3352] logger.c: -- Goto (macro-record-enable,s,4)
[Feb 6 21:45:45] DEBUG[3352] app_macro.c: Executed application: GotoIf
[Feb 6 21:45:45] VERBOSE[3352] logger.c: -- Executing [s@macro-record-enable:4] AGI("Zap/1-1", "recordingcheck|20080206-214545|1202363141.2") in new stack
[Feb 6 21:45:45] VERBOSE[3352] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
[Feb 6 21:45:45] VERBOSE[3352] logger.c: recordingcheck|20080206-214545|1202363141.2: Inbound recording not enabled
[Feb 6 21:45:45] VERBOSE[3352] logger.c: -- AGI Script recordingcheck completed, returning 0
[Feb 6 21:45:45] DEBUG[3352] app_macro.c: Executed application: AGI
[Feb 6 21:45:45] VERBOSE[3352] logger.c: -- Executing [s@macro-record-enable:5] NoOp("Zap/1-1", "No recording needed") in new stack
[Feb 6 21:45:45] DEBUG[3352] app_macro.c: Executed application: Noop
[Feb 6 21:45:45] DEBUG[3352] app_macro.c: Executed application: Macro
[Feb 6 21:45:45] VERBOSE[3352] logger.c: -- Executing [s@macro-exten-vm:9] Macro("Zap/1-1", "dial|15|tr|200") in new stack
[Feb 6 21:45:45] VERBOSE[3352] logger.c: -- Executing [s@macro-dial:1] GotoIf("Zap/1-1", "1?dial") in new stack
[Feb 6 21:45:45] VERBOSE[3352] logger.c: -- Goto (macro-dial,s,3)
[Feb 6 21:45:45] DEBUG[3352] app_macro.c: Executed application: GotoIf
[Feb 6 21:45:45] VERBOSE[3352] logger.c: -- Executing [s@macro-dial:3] AGI("Zap/1-1", "dialparties.agi") in new stack
[Feb 6 21:45:45] VERBOSE[3352] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
[Feb 6 21:45:46] VERBOSE[3352] logger.c: dialparties.agi: Starting New Dialparties.agi
[Feb 6 21:45:46] VERBOSE[3355] logger.c: == Parsing '/etc/asterisk/manager.conf': [Feb 6 21:45:46] VERBOSE[3355] logger.c: Found
[Feb 6 21:45:46] ERROR[3355] config.c: *********************************************************
[Feb 6 21:45:46] ERROR[3355] config.c: *********** YOU SHOULD REALLY READ THIS ERROR ***********
[Feb 6 21:45:46] ERROR[3355] config.c: Future versions of Asterisk will treat a #include of a file that does not exist as an error, and will fail to load that configuration file. Please ensure that the file 'manager_additional.conf' exists, even if it is empty.
[Feb 6 21:45:46] ERROR[3355] config.c: *********** YOU SHOULD REALLY READ THIS ERROR ***********
[Feb 6 21:45:46] ERROR[3355] config.c: *********************************************************
[Feb 6 21:45:46] VERBOSE[3355] logger.c: == Parsing '/etc/asterisk/manager_custom.conf': [Feb 6 21:45:46] VERBOSE[3355] logger.c: Found
[Feb 6 21:45:46] VERBOSE[3355] logger.c: == Manager 'admin' logged on from 127.0.0.1
[Feb 6 21:45:46] VERBOSE[3352] logger.c: dialparties.agi: Caller ID name is 'unknown' number is 'unknown'
[Feb 6 21:45:46] VERBOSE[3352] logger.c: dialparties.agi: USE_CONFIRMATION: 'FALSE'
[Feb 6 21:45:46] VERBOSE[3352] logger.c: dialparties.agi: RINGGROUP_INDEX: ''
[Feb 6 21:45:46] VERBOSE[3352] logger.c: dialparties.agi: Methodology of ring is 'none'
[Feb 6 21:45:46] VERBOSE[3352] logger.c: -- dialparties.agi: Added extension 200 to extension map
[Feb 6 21:45:46] VERBOSE[3352] logger.c: -- dialparties.agi: Extension 200 cf is disabled
[Feb 6 21:45:46] VERBOSE[3352] logger.c: -- dialparties.agi: Extension 200 do not disturb is disabled
[Feb 6 21:45:46] VERBOSE[3352] logger.c: > dialparties.agi: extnum 200 has: cw: 1; hascfb: 0 [] hascfu: 0 []
[Feb 6 21:45:46] VERBOSE[3352] logger.c: > dialparties.agi: ExtensionState: 0
[Feb 6 21:45:46] VERBOSE[3352] logger.c: -- dialparties.agi: DbDel CALLTRACE/200 - Caller ID is not defined
[Feb 6 21:45:46] VERBOSE[3355] logger.c: == Manager 'admin' logged off from 127.0.0.1
[Feb 6 21:45:46] VERBOSE[3352] logger.c: -- AGI Script dialparties.agi completed, returning 0
[Feb 6 21:45:46] DEBUG[3352] app_macro.c: Executed application: AGI
[Feb 6 21:45:46] VERBOSE[3352] logger.c: -- Executing [s@macro-dial:10] Dial("Zap/1-1", "SIP/200|15|tr") in new stack
[Feb 6 21:45:46] VERBOSE[3352] logger.c: -- Called 200