here is a incoming call on the system. which progresses along great but the connection is never established. the caller is still ringing while all this is going on. when we pick up the phone there is nothing there but clicks. and the caller has long since hung up. I do not know if I mentioned it but I have a sangoma a102 card in my server and it is attached to a Rhino CB-24 via T1 cable. when I mention watching the Rhino I can see the action on the Rhino screen but it is like the caller is never sent a off-hook command.
[May 19 12:57:57] VERBOSE[9753] logger.c: – Executing [[email protected]:1] Set(“DAHDI/7-1”, “LOOPCOUNT=2”) in new stack
[May 19 12:57:57] VERBOSE[9753] logger.c: – Executing [[email protected]:2] GotoIf(“DAHDI/7-1”, “0?s|begin”) in new stack
[May 19 12:57:57] VERBOSE[9753] logger.c: – Executing [[email protected]:3] DBdel(“DAHDI/7-1”, “”) in new stack
[May 19 12:57:57] WARNING[9753] app_db.c: The DBdel application has been deprecated in favor of the DB_DELETE dialplan function!
[May 19 12:57:57] DEBUG[9753] app_db.c: Ignoring, no parameters
[May 19 12:57:57] VERBOSE[9753] logger.c: – Executing [[email protected]:4] Set(“DAHDI/7-1”, “__NODEST=”) in new stack
[May 19 12:57:57] VERBOSE[9753] logger.c: – Executing [[email protected]:5] Goto(“DAHDI/7-1”, “timeconditions|1|1”) in new stack
[May 19 12:57:57] VERBOSE[9753] logger.c: – Goto (timeconditions,1,1)
[May 19 12:57:57] VERBOSE[9753] logger.c: – Executing [[email protected]:1] GotoIfTime(“DAHDI/7-1”, “08:00-17:00|mon-fri||?ext-group|600|1”) in new stack
[May 19 12:57:57] VERBOSE[9753] logger.c: – Goto (ext-group,600,1)
[May 19 12:57:57] VERBOSE[9753] logger.c: – Executing [[email protected]:1] Macro(“DAHDI/7-1”, “user-callerid|”) in new stack
[May 19 12:57:57] VERBOSE[9753] logger.c: – Executing [[email protected]:1] Set(“DAHDI/7-1”, “AMPUSER=”) in new stack
[May 19 12:57:57] DEBUG[9753] app_macro.c: Executed application: Set
[May 19 12:57:57] VERBOSE[9753] logger.c: – Executing [[email protected]:2] GotoIf(“DAHDI/7-1”, “0?report”) in new stack
[May 19 12:57:57] DEBUG[9753] app_macro.c: Executed application: GotoIf
[May 19 12:57:57] VERBOSE[9753] logger.c: – Executing [[email protected]:3] ExecIf(“DAHDI/7-1”, “1|Set|REALCALLERIDNUM=”) in new stack
[May 19 12:57:57] DEBUG[9753] app_macro.c: Executed application: ExecIf
[May 19 12:57:57] DEBUG[9753] app_macro.c: Last app: Set|REALCALLERIDNUM=
[May 19 12:57:57] DEBUG[9753] func_db.c: DB: DEVICE//user not found in database.
[May 19 12:57:57] VERBOSE[9753] logger.c: – Executing [[email protected]:4] Set(“DAHDI/7-1”, “AMPUSER=”) in new stack
[May 19 12:57:57] DEBUG[9753] app_macro.c: Executed application: Set
[May 19 12:57:57] DEBUG[9753] func_db.c: DB: AMPUSER//cidname not found in database.
[May 19 12:57:57] VERBOSE[9753] logger.c: – Executing [[email protected]:5] Set(“DAHDI/7-1”, “AMPUSERCIDNAME=”) in new stack
[May 19 12:57:57] DEBUG[9753] app_macro.c: Executed application: Set
[May 19 12:57:57] VERBOSE[9753] logger.c: – Executing [[email protected]:6] GotoIf(“DAHDI/7-1”, “1?report”) in new stack
[May 19 12:57:57] VERBOSE[9753] logger.c: – Goto (macro-user-callerid,s,10)
[May 19 12:57:57] DEBUG[9753] app_macro.c: Executed application: GotoIf
[May 19 12:57:57] VERBOSE[9753] logger.c: – Executing [[email protected]:10] GotoIf(“DAHDI/7-1”, “0?continue”) in new stack
[May 19 12:57:57] DEBUG[9753] app_macro.c: Executed application: GotoIf
[May 19 12:57:57] VERBOSE[9753] logger.c: – Executing [[email protected]:11] Set(“DAHDI/7-1”, “__TTL=64”) in new stack
[May 19 12:57:57] DEBUG[9753] app_macro.c: Executed application: Set
[May 19 12:57:57] VERBOSE[9753] logger.c: – Executing [[email protected]:12] GotoIf(“DAHDI/7-1”, “1?continue”) in new stack
[May 19 12:57:57] VERBOSE[9753] logger.c: – Goto (macro-user-callerid,s,19)
[May 19 12:57:57] DEBUG[9753] app_macro.c: Executed application: GotoIf
[May 19 12:57:57] VERBOSE[9753] logger.c: – Executing [[email protected]:19] NoOp(“DAHDI/7-1”, “Using CallerID “” <>”) in new stack
[May 19 12:57:57] DEBUG[9753] app_macro.c: Executed application: Noop
[May 19 12:57:57] VERBOSE[9753] logger.c: – Executing [[email protected]:2] GotoIf(“DAHDI/7-1”, “1?skipdb”) in new stack
[May 19 12:57:57] VERBOSE[9753] logger.c: – Goto (ext-group,600,4)
[May 19 12:57:57] VERBOSE[9753] logger.c: – Executing [[email protected]:4] Set(“DAHDI/7-1”, “__NODEST=”) in new stack
[May 19 12:57:57] VERBOSE[9753] logger.c: – Executing [[email protected]:5] Set(“DAHDI/7-1”, “__BLKVM_OVERRIDE=BLKVM/600/DAHDI/7-1”) in new stack
[May 19 12:57:57] VERBOSE[9753] logger.c: – Executing [[email protected]:6] Set(“DAHDI/7-1”, “__BLKVM_BASE=600”) in new stack
[May 19 12:57:57] VERBOSE[9753] logger.c: – Executing [[email protected]:7] Set(“DAHDI/7-1”, “DB(BLKVM/600/DAHDI/7-1)=TRUE”) in new stack
[May 19 12:57:57] VERBOSE[9753] logger.c: – Executing [[email protected]:8] Set(“DAHDI/7-1”, “RRNODEST=”) in new stack
[May 19 12:57:57] VERBOSE[9753] logger.c: – Executing [[email protected]:9] Set(“DAHDI/7-1”, “__NODEST=600”) in new stack
[May 19 12:57:57] VERBOSE[9753] logger.c: – Executing [[email protected]:10] GosubIf(“DAHDI/7-1”, “0?sub-rgsetcid|s|1”) in new stack
[May 19 12:57:57] VERBOSE[9753] logger.c: – Executing [[email protected]:11] Set(“DAHDI/7-1”, “RecordMethod=Group”) in new stack
[May 19 12:57:57] VERBOSE[9753] logger.c: – Executing [[email protected]:12] Macro(“DAHDI/7-1”, “record-enable|7000-7003-7005-7007|Group”) in new stack
[May 19 12:57:57] VERBOSE[9753] logger.c: – Executing [[email protected]nable:1] GotoIf(“DAHDI/7-1”, “1?check”) in new stack
[May 19 12:57:57] VERBOSE[9753] logger.c: – Goto (macro-record-enable,s,4)
[May 19 12:57:57] DEBUG[9753] app_macro.c: Executed application: GotoIf
[May 19 12:57:57] VERBOSE[9753] logger.c: – Executing [[email protected]:4] ExecIf(“DAHDI/7-1”, “0|MacroExit|”) in new stack
[May 19 12:57:57] DEBUG[9753] app_macro.c: Executed application: ExecIf
[May 19 12:57:57] VERBOSE[9753] logger.c: – Executing [[email protected]:5] GotoIf(“DAHDI/7-1”, “1?Group:OUT”) in new stack
[May 19 12:57:57] VERBOSE[9753] logger.c: – Goto (macro-record-enable,s,6)
[May 19 12:57:57] DEBUG[9753] app_macro.c: Executed application: GotoIf
[May 19 12:57:57] VERBOSE[9753] logger.c: – Executing [[email protected]:6] Set(“DAHDI/7-1”, “LOOPCNT=4”) in new stack
[May 19 12:57:57] DEBUG[9753] app_macro.c: Executed application: Set
[May 19 12:57:57] VERBOSE[9753] logger.c: – Executing [[email protected]:7] Set(“DAHDI/7-1”, “ITER=1”) in new stack
[May 19 12:57:57] DEBUG[9753] app_macro.c: Executed application: Set
[May 19 12:57:57] VERBOSE[9753] logger.c: – Executing [[email protected]:8] GotoIf(“DAHDI/7-1”, “1?continue”) in new stack
[May 19 12:57:57] VERBOSE[9753] logger.c: – Goto (macro-record-enable,s,13)
[May 19 12:57:57] DEBUG[9753] app_macro.c: Executed application: GotoIf
[May 19 12:57:57] VERBOSE[9753] logger.c: – Executing [[email protected]:13] Set(“DAHDI/7-1”, “ITER=2”) in new stack
[May 19 12:57:57] DEBUG[9753] app_macro.c: Executed application: Set
[May 19 12:57:57] VERBOSE[9753] logger.c: – Executing [[email protected]:14] GotoIf(“DAHDI/7-1”, “1?begin”) in new stack
[May 19 12:57:57] VERBOSE[9753] logger.c: – Goto (macro-record-enable,s,8)
[May 19 12:57:57] DEBUG[9753] app_macro.c: Executed application: GotoIf
[May 19 12:57:57] VERBOSE[9753] logger.c: – Executing [[email protected]:8] GotoIf(“DAHDI/7-1”, “1?continue”) in new stack
[May 19 12:57:57] VERBOSE[9753] logger.c: – Goto (macro-record-enable,s,13)
[May 19 12:57:57] DEBUG[9753] app_macro.c: Executed application: GotoIf
[May 19 12:57:57] VERBOSE[9753] logger.c: – Executing [[email protected]:13] Set(“DAHDI/7-1”, “ITER=3”) in new stack
[May 19 12:57:57] DEBUG[9753] app_macro.c: Executed application: Set
[May 19 12:57:57] VERBOSE[9753] logger.c: – Executing [[email protected]:14] GotoIf(“DAHDI/7-1”, “1?begin”) in new stack
[May 19 12:57:57] VERBOSE[9753] logger.c: – Goto (macro-record-enable,s,8)
[May 19 12:57:57] DEBUG[9753] app_macro.c: Executed application: GotoIf
[May 19 12:57:57] VERBOSE[9753] logger.c: – Executing [[email protected]:8] GotoIf(“DAHDI/7-1”, “1?continue”) in new stack
[May 19 12:57:57] VERBOSE[9753] logger.c: – Goto (macro-record-enable,s,13)
[May 19 12:57:57] DEBUG[9753] app_macro.c: Executed application: GotoIf
[May 19 12:57:57] VERBOSE[9753] logger.c: – Executing [[email protected]:13] Set(“DAHDI/7-1”, “ITER=4”) in new stack
[May 19 12:57:57] DEBUG[9753] app_macro.c: Executed application: Set
[May 19 12:57:57] VERBOSE[9753] logger.c: – Executing [[email protected]:14] GotoIf(“DAHDI/7-1”, “1?begin”) in new stack
[May 19 12:57:57] VERBOSE[9753] logger.c: – Goto (macro-record-enable,s,8)
[May 19 12:57:57] DEBUG[9753] app_macro.c: Executed application: GotoIf
[May 19 12:57:57] VERBOSE[9753] logger.c: – Executing [[email protected]:8] GotoIf(“DAHDI/7-1”, “1?continue”) in new stack
[May 19 12:57:57] VERBOSE[9753] logger.c: – Goto (macro-record-enable,s,13)
[May 19 12:57:57] DEBUG[9753] app_macro.c: Executed application: GotoIf
[May 19 12:57:57] VERBOSE[9753] logger.c: – Executing [[email protected]:13] Set(“DAHDI/7-1”, “ITER=5”) in new stack
[May 19 12:57:57] DEBUG[9753] app_macro.c: Executed application: Set
[May 19 12:57:57] VERBOSE[9753] logger.c: – Executing [[email protected]:14] GotoIf(“DAHDI/7-1”, “0?begin”) in new stack
[May 19 12:57:57] DEBUG[9753] app_macro.c: Executed application: GotoIf
[May 19 12:57:57] VERBOSE[9753] logger.c: – Executing [[email protected]:15] GotoIf(“DAHDI/7-1”, “0?IN”) in new stack
[May 19 12:57:57] DEBUG[9753] app_macro.c: Executed application: GotoIf
[May 19 12:57:57] DEBUG[9753] func_db.c: DB: AMPUSER/7000-7003-7005-7007/recording not found in database.
[May 19 12:57:57] VERBOSE[9753] logger.c: – Executing [[email protected]:16] ExecIf(“DAHDI/7-1”, “1|MacroExit|”) in new stack
[May 19 12:57:57] VERBOSE[9753] logger.c: – Executing [[email protected]:13] Set(“DAHDI/7-1”, “RingGroupMethod=ringall”) in new stack
[May 19 12:57:57] VERBOSE[9753] logger.c: – Executing [[email protected]:14] Macro(“DAHDI/7-1”, “dial|20|tr|7000-7003-7005-7007”) in new stack
[May 19 12:57:57] VERBOSE[9753] logger.c: – Executing [[email protected]:1] GotoIf(“DAHDI/7-1”, “0?dial”) in new stack
[May 19 12:57:57] DEBUG[9753] app_macro.c: Executed application: GotoIf
[May 19 12:57:57] VERBOSE[9753] logger.c: – Executing [[email protected]:2] SetMusicOnHold(“DAHDI/7-1”, “moh”) in new stack
[May 19 12:57:57] DEBUG[9753] app_macro.c: Executed application: SetMusicOnHold
[May 19 12:57:57] VERBOSE[9753] logger.c: – Executing [[email protected]:3] AGI(“DAHDI/7-1”, “dialparties.agi”) in new stack
[May 19 12:57:57] VERBOSE[9753] logger.c: – Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
[May 19 12:57:57] VERBOSE[9753] logger.c: dialparties.agi: Starting New Dialparties.agi
[May 19 12:57:57] VERBOSE[9816] logger.c: == Parsing ‘/etc/asterisk/manager.conf’: [May 19 12:57:57] VERBOSE[9816] logger.c: Found
[May 19 12:57:57] VERBOSE[9816] logger.c: == Parsing ‘/etc/asterisk/manager_additional.conf’: [May 19 12:57:57] VERBOSE[9816] logger.c: Found
[May 19 12:57:57] VERBOSE[9816] logger.c: == Parsing ‘/etc/asterisk/manager_custom.conf’: [May 19 12:57:57] VERBOSE[9816] logger.c: Found
[May 19 12:57:57] VERBOSE[9816] logger.c: == Manager ‘admin’ logged on from 127.0.0.1
[May 19 12:57:57] VERBOSE[9753] logger.c: dialparties.agi: Caller ID name is ‘unknown’ number is ‘unknown’
[May 19 12:57:57] VERBOSE[9753] logger.c: dialparties.agi: USE_CONFIRMATION: ‘FALSE’
[May 19 12:57:57] VERBOSE[9753] logger.c: dialparties.agi: RINGGROUP_INDEX: ‘’
[May 19 12:57:57] VERBOSE[9753] logger.c: dialparties.agi: Methodology of ring is ‘ringall’
[May 19 12:57:57] VERBOSE[9753] logger.c: – dialparties.agi: Added extension 7000 to extension map
[May 19 12:57:57] VERBOSE[9753] logger.c: – dialparties.agi: Added extension 7003 to extension map
[May 19 12:57:57] VERBOSE[9753] logger.c: – dialparties.agi: Added extension 7005 to extension map
[May 19 12:57:57] VERBOSE[9753] logger.c: – dialparties.agi: Added extension 7007 to extension map
[May 19 12:57:57] VERBOSE[9753] logger.c: – dialparties.agi: Extension 7000 cf is disabled
[May 19 12:57:57] VERBOSE[9753] logger.c: – dialparties.agi: Extension 7003 cf is disabled
[May 19 12:57:57] VERBOSE[9753] logger.c: – dialparties.agi: Extension 7005 cf is disabled
[May 19 12:57:57] VERBOSE[9753] logger.c: – dialparties.agi: Extension 7007 cf is disabled
[May 19 12:57:57] VERBOSE[9753] logger.c: – dialparties.agi: Extension 7000 do not disturb is disabled
[May 19 12:57:57] VERBOSE[9753] logger.c: – dialparties.agi: Extension 7003 do not disturb is disabled
[May 19 12:57:57] VERBOSE[9753] logger.c: – dialparties.agi: Extension 7005 do not disturb is disabled
[May 19 12:57:57] VERBOSE[9753] logger.c: – dialparties.agi: Extension 7007 do not disturb is disabled
[May 19 12:57:57] VERBOSE[9753] logger.c: > dialparties.agi: extnum 7000 has: cw: 0; hascfb: 0 [] hascfu: 0 []
[May 19 12:57:57] VERBOSE[9753] logger.c: dialparties.agi: ExtensionState: 0
[May 19 12:57:57] VERBOSE[9753] logger.c: dialparties.agi: Extension 7000 has ExtensionState: 0
[May 19 12:57:57] VERBOSE[9753] logger.c: – dialparties.agi: Checking CW and CFB status for extension 7000
[May 19 12:57:57] VERBOSE[9753] logger.c: – dialparties.agi: DbDel CALLTRACE/7000 - Caller ID is not defined
[May 19 12:57:57] VERBOSE[9753] logger.c: > dialparties.agi: extnum 7003 has: cw: 0; hascfb: 0 [] hascfu: 0 []
[May 19 12:57:57] VERBOSE[9753] logger.c: dialparties.agi: ExtensionState: 0
[May 19 12:57:57] VERBOSE[9753] logger.c: dialparties.agi: Extension 7003 has ExtensionState: 0
[May 19 12:57:57] VERBOSE[9753] logger.c: – dialparties.agi: Checking CW and CFB status for extension 7003
[May 19 12:57:57] VERBOSE[9753] logger.c: – dialparties.agi: DbDel CALLTRACE/7003 - Caller ID is not defined
[May 19 12:57:57] VERBOSE[9753] logger.c: > dialparties.agi: extnum 7005 has: cw: 0; hascfb: 0 [] hascfu: 0 []
[May 19 12:57:57] VERBOSE[9753] logger.c: dialparties.agi: ExtensionState: 0
[May 19 12:57:57] VERBOSE[9753] logger.c: dialparties.agi: Extension 7005 has ExtensionState: 0
[May 19 12:57:57] VERBOSE[9753] logger.c: – dialparties.agi: Checking CW and CFB status for extension 7005
[May 19 12:57:57] VERBOSE[9753] logger.c: – dialparties.agi: DbDel CALLTRACE/7005 - Caller ID is not defined
[May 19 12:57:57] VERBOSE[9753] logger.c: > dialparties.agi: extnum 7007 has: cw: 0; hascfb: 0 [] hascfu: 0 []
[May 19 12:57:57] VERBOSE[9753] logger.c: dialparties.agi: ExtensionState: 0
[May 19 12:57:57] VERBOSE[9753] logger.c: dialparties.agi: Extension 7007 has ExtensionState: 0
[May 19 12:57:57] VERBOSE[9753] logger.c: – dialparties.agi: Checking CW and CFB status for extension 7007
[May 19 12:57:57] VERBOSE[9753] logger.c: – dialparties.agi: DbDel CALLTRACE/7007 - Caller ID is not defined
[May 19 12:57:57] VERBOSE[9753] logger.c: – dialparties.agi: Filtered ARG3: 7000-7003-7005-7007
[May 19 12:57:57] VERBOSE[9753] logger.c: > dialparties.agi: NODEST: 600 adding M(auto-blkvm) to dialopts: trM(auto-blkvm)
[May 19 12:57:57] VERBOSE[9753] logger.c: > dialparties.agi: NODEST: 600 blkvm enabled macro already in dialopts: trM(auto-blkvm)
[May 19 12:57:57] VERBOSE[9816] logger.c: == Manager ‘admin’ logged off from 127.0.0.1
[May 19 12:57:57] VERBOSE[9753] logger.c: – AGI Script dialparties.agi completed, returning 0
[May 19 12:57:57] DEBUG[9753] app_macro.c: Executed application: AGI
[May 19 12:57:57] VERBOSE[9753] logger.c: – Executing [[email protected]:7] Dial(“DAHDI/7-1”, “SIP/7000&SIP/7003&SIP/7005&SIP/7007|20|trM(auto-blkvm)”) in new stack
[May 19 12:57:57] VERBOSE[9753] logger.c: – Called 7000
[May 19 12:57:57] VERBOSE[9753] logger.c: – Called 7003
[May 19 12:57:57] VERBOSE[9753] logger.c: – Called 7005
[May 19 12:57:57] VERBOSE[9753] logger.c: – Called 7007
[May 19 12:57:57] VERBOSE[9753] logger.c: – SIP/7007-00000005 is ringing
[May 19 12:57:57] VERBOSE[9753] logger.c: – SIP/7000-00000002 is ringing
[May 19 12:57:57] VERBOSE[9753] logger.c: – SIP/7003-00000003 is ringing
[May 19 12:57:57] VERBOSE[9753] logger.c: – SIP/7005-00000004 is ringing
[May 19 12:57:59] VERBOSE[9753] logger.c: – SIP/7003-00000003 answered DAHDI/7-1
[May 19 12:57:59] VERBOSE[9753] logger.c: – Executing [[email protected]:1] Set(“SIP/7003-00000003”, “__MACRO_RESULT=”) in new stack
[May 19 12:57:59] DEBUG[9753] app_macro.c: Executed application: Set
[May 19 12:57:59] VERBOSE[9753] logger.c: – Executing [[email protected]:2] DBdel(“SIP/7003-00000003”, “BLKVM/600/DAHDI/7-1”) in new stack
[May 19 12:57:59] VERBOSE[9753] logger.c: – DBdel: family=BLKVM, key=600/DAHDI/7-1
[May 19 12:57:59] DEBUG[9753] app_macro.c: Executed application: dbDel
[May 19 12:57:59] DEBUG[9753] app_dial.c: Macro exited with status 0