Thanks, I didn’t know about those log files. Here is a call from ext. 101 to ext. 102. It returns a busy signal.
[Apr 2 09:04:42] VERBOSE[3376] netsock.c: == Using SIP RTP TOS bits 184
[Apr 2 09:04:42] VERBOSE[3376] netsock.c: == Using SIP RTP CoS mark 5
[Apr 2 09:04:42] VERBOSE[20040] pbx.c: – Executing [102@from-internal:1] Macro(“SIP/101-00000046”, “exten-vm,novm,102”) in new stack
[Apr 2 09:04:42] VERBOSE[20040] pbx.c: – Executing [s@macro-exten-vm:1] Macro(“SIP/101-00000046”, “user-callerid,”) in new stack
[Apr 2 09:04:42] VERBOSE[20040] pbx.c: – Executing [s@macro-user-callerid:1] Set(“SIP/101-00000046”, “AMPUSER=101”) in new stack
[Apr 2 09:04:42] VERBOSE[20040] pbx.c: – Executing [s@macro-user-callerid:2] GotoIf(“SIP/101-00000046”, “0?report”) in new stack
[Apr 2 09:04:42] VERBOSE[20040] pbx.c: – Executing [s@macro-user-callerid:3] ExecIf(“SIP/101-00000046”, “1?Set(REALCALLERIDNUM=101)”) in new stack
[Apr 2 09:04:42] VERBOSE[20040] pbx.c: – Executing [s@macro-user-callerid:4] Set(“SIP/101-00000046”, “AMPUSER=101”) in new stack
[Apr 2 09:04:42] VERBOSE[20040] pbx.c: – Executing [s@macro-user-callerid:5] Set(“SIP/101-00000046”, “AMPUSERCIDNAME=101”) in new stack
[Apr 2 09:04:42] VERBOSE[20040] pbx.c: – Executing [s@macro-user-callerid:6] GotoIf(“SIP/101-00000046”, “0?report”) in new stack
[Apr 2 09:04:42] VERBOSE[20040] pbx.c: – Executing [s@macro-user-callerid:7] Set(“SIP/101-00000046”, “AMPUSERCID=101”) in new stack
[Apr 2 09:04:42] VERBOSE[20040] pbx.c: – Executing [s@macro-user-callerid:8] Set(“SIP/101-00000046”, “CALLERID(all)=“101” <101>”) in new stack
[Apr 2 09:04:42] VERBOSE[20040] pbx.c: – Executing [s@macro-user-callerid:9] ExecIf(“SIP/101-00000046”, “0?Set(CHANNEL(language)=)”) in new stack
[Apr 2 09:04:42] VERBOSE[20040] pbx.c: – Executing [s@macro-user-callerid:10] GotoIf(“SIP/101-00000046”, “0?continue”) in new stack
[Apr 2 09:04:42] VERBOSE[20040] pbx.c: – Executing [s@macro-user-callerid:11] Set(“SIP/101-00000046”, “__TTL=64”) in new stack
[Apr 2 09:04:42] VERBOSE[20040] pbx.c: – Executing [s@macro-user-callerid:12] GotoIf(“SIP/101-00000046”, “1?continue”) in new stack
[Apr 2 09:04:42] VERBOSE[20040] pbx.c: – Goto (macro-user-callerid,s,19)
[Apr 2 09:04:42] VERBOSE[20040] pbx.c: – Executing [s@macro-user-callerid:19] NoOp(“SIP/101-00000046”, “Using CallerID “101” <101>”) in new stack
[Apr 2 09:04:42] VERBOSE[20040] pbx.c: – Executing [s@macro-exten-vm:2] Set(“SIP/101-00000046”, “RingGroupMethod=none”) in new stack
[Apr 2 09:04:42] VERBOSE[20040] pbx.c: – Executing [s@macro-exten-vm:3] Set(“SIP/101-00000046”, “VMBOX=novm”) in new stack
[Apr 2 09:04:42] VERBOSE[20040] pbx.c: – Executing [s@macro-exten-vm:4] Set(“SIP/101-00000046”, “EXTTOCALL=102”) in new stack
[Apr 2 09:04:42] VERBOSE[20040] pbx.c: – Executing [s@macro-exten-vm:5] Set(“SIP/101-00000046”, “CFUEXT=”) in new stack
[Apr 2 09:04:42] VERBOSE[20040] pbx.c: – Executing [s@macro-exten-vm:6] Set(“SIP/101-00000046”, “CFBEXT=”) in new stack
[Apr 2 09:04:42] VERBOSE[20040] pbx.c: – Executing [s@macro-exten-vm:7] Set(“SIP/101-00000046”, “RT=”"") in new stack
[Apr 2 09:04:42] VERBOSE[20040] pbx.c: – Executing [s@macro-exten-vm:8] Macro(“SIP/101-00000046”, “record-enable,102,IN”) in new stack
[Apr 2 09:04:42] VERBOSE[20040] pbx.c: – Executing [s@macro-record-enable:1] GotoIf(“SIP/101-00000046”, “1?check”) in new stack
[Apr 2 09:04:42] VERBOSE[20040] pbx.c: – Goto (macro-record-enable,s,4)
[Apr 2 09:04:42] VERBOSE[20040] pbx.c: – Executing [s@macro-record-enable:4] ExecIf(“SIP/101-00000046”, “0?MacroExit()”) in new stack
[Apr 2 09:04:42] VERBOSE[20040] pbx.c: – Executing [s@macro-record-enable:5] GotoIf(“SIP/101-00000046”, “0?Group:OUT”) in new stack
[Apr 2 09:04:42] VERBOSE[20040] pbx.c: – Goto (macro-record-enable,s,15)
[Apr 2 09:04:42] VERBOSE[20040] pbx.c: – Executing [s@macro-record-enable:15] GotoIf(“SIP/101-00000046”, “1?IN”) in new stack
[Apr 2 09:04:42] VERBOSE[20040] pbx.c: – Goto (macro-record-enable,s,20)
[Apr 2 09:04:42] VERBOSE[20040] pbx.c: – Executing [s@macro-record-enable:20] ExecIf(“SIP/101-00000046”, “0?MacroExit()”) in new stack
[Apr 2 09:04:42] VERBOSE[20040] pbx.c: – Executing [s@macro-record-enable:21] NoOp(“SIP/101-00000046”, “Recording enable for 102”) in new stack
[Apr 2 09:04:42] VERBOSE[20040] pbx.c: – Executing [s@macro-record-enable:22] Set(“SIP/101-00000046”, “CALLFILENAME=20130402-090442-1364911482.70”) in new stack
[Apr 2 09:04:42] VERBOSE[20040] pbx.c: – Executing [s@macro-record-enable:23] MixMonitor(“SIP/101-00000046”, “/var/spool/asterisk/monitor/20130402-090442-1364911482.70.wav,/root/recCleanUp”) in new stack
[Apr 2 09:04:42] VERBOSE[20040] pbx.c: – Executing [s@macro-record-enable:24] Set(“SIP/101-00000046”, “CDR(userfield)=audio:20130402-090442-1364911482.70.wav”) in new stack
[Apr 2 09:04:42] VERBOSE[20040] pbx.c: – Executing [s@macro-record-enable:25] MacroExit(“SIP/101-00000046”, “”) in new stack
[Apr 2 09:04:42] VERBOSE[20041] app_mixmonitor.c: == Begin MixMonitor Recording SIP/101-00000046
[Apr 2 09:04:42] VERBOSE[20040] pbx.c: – Executing [s@macro-exten-vm:9] Macro(“SIP/101-00000046”, “dial,tr,102”) in new stack
[Apr 2 09:04:42] VERBOSE[20040] pbx.c: – Executing [s@macro-dial:1] GotoIf(“SIP/101-00000046”, “1?dial”) in new stack
[Apr 2 09:04:42] VERBOSE[20040] pbx.c: – Goto (macro-dial,s,3)
[Apr 2 09:04:42] VERBOSE[20040] pbx.c: – Executing [s@macro-dial:3] AGI(“SIP/101-00000046”, “dialparties.agi”) in new stack
[Apr 2 09:04:42] VERBOSE[20040] res_agi.c: – Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
[Apr 2 09:04:42] VERBOSE[20040] res_agi.c: dialparties.agi: Starting New Dialparties.agi
[Apr 2 09:04:42] VERBOSE[20040] res_agi.c: dialparties.agi: Caller ID name is ‘101’ number is ‘101’
[Apr 2 09:04:42] VERBOSE[20040] res_agi.c: dialparties.agi: Methodology of ring is ‘none’
[Apr 2 09:04:42] VERBOSE[20040] res_agi.c: – dialparties.agi: Added extension 102 to extension map
[Apr 2 09:04:42] VERBOSE[20040] res_agi.c: – dialparties.agi: Extension 102 cf is disabled
[Apr 2 09:04:42] VERBOSE[20040] res_agi.c: – dialparties.agi: Extension 102 do not disturb is disabled
[Apr 2 09:04:42] VERBOSE[20040] res_agi.c: dialparties.agi: EXTENSION_STATE: 0 (NOT_INUSE)
[Apr 2 09:04:42] VERBOSE[20040] res_agi.c: dialparties.agi: Extension 102 has ExtensionState: 0
[Apr 2 09:04:42] VERBOSE[20040] res_agi.c: – dialparties.agi: Checking CW and CFB status for extension 102
[Apr 2 09:04:42] VERBOSE[20040] res_agi.c: – dialparties.agi: dbset CALLTRACE/102 to 101
[Apr 2 09:04:42] VERBOSE[20040] res_agi.c: – dialparties.agi: Filtered ARG3: 102
[Apr 2 09:04:42] VERBOSE[20040] res_agi.c: – <SIP/101-00000046>AGI Script dialparties.agi completed, returning 0
[Apr 2 09:04:42] VERBOSE[20040] pbx.c: – Executing [s@macro-dial:7] Dial(“SIP/101-00000046”, “SIP/102,tr”) in new stack
[Apr 2 09:04:42] VERBOSE[20040] netsock.c: == Using SIP RTP TOS bits 184
[Apr 2 09:04:42] VERBOSE[20040] netsock.c: == Using SIP RTP CoS mark 5
[Apr 2 09:04:42] VERBOSE[20040] app_dial.c: – Called 102
[Apr 2 09:04:42] VERBOSE[3376] chan_sip.c: – Got SIP response 486 “Busy Here” back from 192.168.47.199
[Apr 2 09:04:42] VERBOSE[20040] app_dial.c: – SIP/102-00000047 is busy
[Apr 2 09:04:42] VERBOSE[20040] app_dial.c: == Everyone is busy/congested at this time (1:1/0/0)
[Apr 2 09:04:42] VERBOSE[20040] pbx.c: – Executing [s@macro-dial:8] Set(“SIP/101-00000046”, “DIALSTATUS=BUSY”) in new stack
[Apr 2 09:04:42] VERBOSE[20040] pbx.c: – Executing [s@macro-dial:9] GosubIf(“SIP/101-00000046”, “0?BUSY,1”) in new stack
[Apr 2 09:04:42] VERBOSE[20040] pbx.c: – Executing [s@macro-exten-vm:10] GotoIf(“SIP/101-00000046”, “0?exit,return”) in new stack
[Apr 2 09:04:42] VERBOSE[20040] pbx.c: – Executing [s@macro-exten-vm:11] Set(“SIP/101-00000046”, “SV_DIALSTATUS=BUSY”) in new stack
[Apr 2 09:04:42] VERBOSE[20040] pbx.c: – Executing [s@macro-exten-vm:12] GosubIf(“SIP/101-00000046”, “0?docfu,1”) in new stack
[Apr 2 09:04:42] VERBOSE[20040] pbx.c: – Executing [s@macro-exten-vm:13] GosubIf(“SIP/101-00000046”, “0?docfb,1”) in new stack
[Apr 2 09:04:42] VERBOSE[20040] pbx.c: – Executing [s@macro-exten-vm:14] Set(“SIP/101-00000046”, “DIALSTATUS=BUSY”) in new stack
[Apr 2 09:04:42] VERBOSE[20040] pbx.c: – Executing [s@macro-exten-vm:15] NoOp(“SIP/101-00000046”, “Voicemail is ‘novm’”) in new stack
[Apr 2 09:04:42] VERBOSE[20040] pbx.c: – Executing [s@macro-exten-vm:16] GotoIf(“SIP/101-00000046”, “1?s-BUSY,1”) in new stack
[Apr 2 09:04:42] VERBOSE[20040] pbx.c: – Goto (macro-exten-vm,s-BUSY,1)
[Apr 2 09:04:42] VERBOSE[20040] pbx.c: – Executing [s-BUSY@macro-exten-vm:1] NoOp(“SIP/101-00000046”, “Extension is reporting BUSY and not passing to Voicemail”) in new stack
[Apr 2 09:04:42] VERBOSE[20040] pbx.c: – Executing [s-BUSY@macro-exten-vm:2] GotoIf(“SIP/101-00000046”, “0?exit,1”) in new stack
[Apr 2 09:04:42] VERBOSE[20040] pbx.c: – Executing [s-BUSY@macro-exten-vm:3] PlayTones(“SIP/101-00000046”, “busy”) in new stack
[Apr 2 09:04:42] WARNING[20040] translate.c: no samples for g729tolin
[Apr 2 09:04:42] VERBOSE[20040] pbx.c: – Executing [s-BUSY@macro-exten-vm:4] Busy(“SIP/101-00000046”, “20”) in new stack
[Apr 2 09:04:42] VERBOSE[20040] app_macro.c: == Spawn extension (macro-exten-vm, s-BUSY, 4) exited non-zero on ‘SIP/101-00000046’ in macro ‘exten-vm’
[Apr 2 09:04:42] VERBOSE[20040] pbx.c: == Spawn extension (from-internal, 102, 1) exited non-zero on ‘SIP/101-00000046’
[Apr 2 09:04:42] VERBOSE[20040] pbx.c: – Executing [h@from-internal:1] Macro(“SIP/101-00000046”, “hangupcall”) in new stack
[Apr 2 09:04:42] VERBOSE[20040] pbx.c: – Executing [s@macro-hangupcall:1] GotoIf(“SIP/101-00000046”, “1?noautomon”) in new stack
[Apr 2 09:04:42] VERBOSE[20040] pbx.c: – Goto (macro-hangupcall,s,3)
[Apr 2 09:04:42] VERBOSE[20040] pbx.c: – Executing [s@macro-hangupcall:3] NoOp(“SIP/101-00000046”, “TOUCH_MONITOR_OUTPUT=”) in new stack
[Apr 2 09:04:42] VERBOSE[20040] pbx.c: – Executing [s@macro-hangupcall:4] GotoIf(“SIP/101-00000046”, “1?skiprg”) in new stack
[Apr 2 09:04:42] VERBOSE[20040] pbx.c: – Goto (macro-hangupcall,s,7)
[Apr 2 09:04:42] VERBOSE[20040] pbx.c: – Executing [s@macro-hangupcall:7] GotoIf(“SIP/101-00000046”, “1?skipblkvm”) in new stack
[Apr 2 09:04:42] VERBOSE[20040] pbx.c: – Goto (macro-hangupcall,s,10)
[Apr 2 09:04:42] VERBOSE[20040] pbx.c: – Executing [s@macro-hangupcall:10] GotoIf(“SIP/101-00000046”, “1?theend”) in new stack
[Apr 2 09:04:42] VERBOSE[20040] pbx.c: – Goto (macro-hangupcall,s,12)
[Apr 2 09:04:42] VERBOSE[20040] pbx.c: – Executing [s@macro-hangupcall:12] Hangup(“SIP/101-00000046”, “”) in new stack
[Apr 2 09:04:42] VERBOSE[20040] app_macro.c: == Spawn extension (macro-hangupcall, s, 12) exited non-zero on ‘SIP/101-00000046’ in macro ‘hangupcall’
[Apr 2 09:04:42] VERBOSE[20040] pbx.c: == Spawn extension (from-internal, h, 1) exited non-zero on ‘SIP/101-00000046’
[Apr 2 09:04:42] VERBOSE[20041] app_mixmonitor.c: == Executing [/root/recCleanUp]
[Apr 2 09:04:42] VERBOSE[20041] app_mixmonitor.c: == End MixMonitor Recording SIP/101-00000046