电话响铃后中断，过一会再响铃的故障

环境：elastix1.5.2 1200P卡
现象：电话振铃一至两声后，不振铃了，然后过几秒钟又振铃，此时接起话机，可正常接通（如果响第一声铃就接起的话，话机里没声音）。整个过程中，发起呼叫方不会感觉到异常。希望各位帮看看，这个问题困扰我很久了，elastix1.3的时候就有，换成1.5仍有，以前的一张sagoma的4口的卡好像也有这种故障。谢谢
日志：
[Sep 22 10:41:48] VERBOSE[11349] logger.c: -- Starting simple switch on 'DAHDI/1-1'
[Sep 22 10:41:48] ERROR[11349] callerid.c: No start bit found in fsk data.
[Sep 22 10:41:48] WARNING[11349] chan_dahdi.c: CallerID feed failed: Resource temporarily unavailable
[Sep 22 10:41:48] WARNING[11349] chan_dahdi.c: CallerID returned with error on channel 'DAHDI/1-1'
[Sep 22 10:41:48] VERBOSE[11349] logger.c: -- Executing [s@from-zaptel:1] NoOp("DAHDI/1-1", "Entering from-zaptel with DID == ") in new stack
[Sep 22 10:41:48] VERBOSE[11349] logger.c: -- Executing [s@from-zaptel:2] Ringing("DAHDI/1-1", "") in new stack
[Sep 22 10:41:48] VERBOSE[11349] logger.c: -- Executing [s@from-zaptel:3] Set("DAHDI/1-1", "DID=s") in new stack
[Sep 22 10:41:48] VERBOSE[11349] logger.c: -- Executing [s@from-zaptel:4] NoOp("DAHDI/1-1", "DID is now s") in new stack
[Sep 22 10:41:48] VERBOSE[11349] logger.c: -- Executing [s@from-zaptel:5] GotoIf("DAHDI/1-1", "1?zapok:notzap") in new stack
[Sep 22 10:41:48] VERBOSE[11349] logger.c: -- Goto (from-zaptel,s,
[Sep 22 10:41:48] VERBOSE[11349] logger.c: -- Executing [s@from-zaptel:8] NoOp("DAHDI/1-1", "Is a Zaptel Channel") in new stack
[Sep 22 10:41:48] VERBOSE[11349] logger.c: -- Executing [s@from-zaptel:9] Set("DAHDI/1-1", "CHAN=1-1") in new stack
[Sep 22 10:41:48] VERBOSE[11349] logger.c: -- Executing [s@from-zaptel:10] Set("DAHDI/1-1", "CHAN=1") in new stack
[Sep 22 10:41:48] VERBOSE[11349] logger.c: -- Executing [s@from-zaptel:11] Macro("DAHDI/1-1", "from-zaptel-1|s|1") in new stack
[Sep 22 10:41:48] VERBOSE[11349] logger.c: -- Executing [s@macro-from-zaptel-1:1] NoOp("DAHDI/1-1", "Entering macro-from-zaptel-1 with DID = s and setting to: 1") in new stack
[Sep 22 10:41:48] DEBUG[11349] app_macro.c: Executed application: Noop
[Sep 22 10:41:48] VERBOSE[11349] logger.c: -- Executing [s@macro-from-zaptel-1:2] Set("DAHDI/1-1", "__FROM_DID=1") in new stack
[Sep 22 10:41:48] DEBUG[11349] app_macro.c: Executed application: Set
[Sep 22 10:41:48] VERBOSE[11349] logger.c: -- Executing [s@macro-from-zaptel-1:3] Goto("DAHDI/1-1", "from-trunk|1|1") in new stack
[Sep 22 10:41:48] VERBOSE[11349] logger.c: -- Goto (from-trunk,1,1)
[Sep 22 10:41:48] DEBUG[11349] app_macro.c: Executed application: Goto
[Sep 22 10:41:48] VERBOSE[11349] logger.c: == Channel 'DAHDI/1-1' jumping out of macro 'from-zaptel-1'
[Sep 22 10:41:48] VERBOSE[11349] logger.c: -- Executing [1@from-trunk:1] Set("DAHDI/1-1", "__FROM_DID=1") in new stack
[Sep 22 10:41:48] VERBOSE[11349] logger.c: -- Executing [1@from-trunk:2] Gosub("DAHDI/1-1", "app-blacklist-check|s|1") in new stack
[Sep 22 10:41:48] VERBOSE[11349] logger.c: -- Executing [s@app-blacklist-check:1] LookupBlacklist("DAHDI/1-1", "") in new stack
[Sep 22 10:41:48] VERBOSE[11349] logger.c: -- Executing [s@app-blacklist-check:2] GotoIf("DAHDI/1-1", "0?blacklisted") in new stack
[Sep 22 10:41:48] VERBOSE[11349] logger.c: -- Executing [s@app-blacklist-check:3] Return("DAHDI/1-1", "") in new stack
[Sep 22 10:41:48] VERBOSE[11349] logger.c: -- Executing [1@from-trunk:3] ExecIf("DAHDI/1-1", "1 |Set|CALLERID(name)=") in new stack
[Sep 22 10:41:48] VERBOSE[11349] logger.c: -- Executing [1@from-trunk:4] Set("DAHDI/1-1", "__CALLINGPRES_SV=allowed_not_screened") in new stack
[Sep 22 10:41:48] VERBOSE[11349] logger.c: -- Executing [1@from-trunk:5] SetCallerPres("DAHDI/1-1", "allowed_not_screened") in new stack
[Sep 22 10:41:48] VERBOSE[11349] logger.c: -- Executing [1@from-trunk:6] Goto("DAHDI/1-1", "from-did-direct|9999|1") in new stack
[Sep 22 10:41:48] VERBOSE[11349] logger.c: -- Goto (from-did-direct,9999,1)
[Sep 22 10:41:48] VERBOSE[11349] logger.c: -- Executing [9999@from-did-direct:1] Macro("DAHDI/1-1", "exten-vm|novm|9999") in new stack
[Sep 22 10:41:48] VERBOSE[11349] logger.c: -- Executing [s@macro-exten-vm:1] Macro("DAHDI/1-1", "user-callerid") in new stack
[Sep 22 10:41:48] VERBOSE[11349] logger.c: -- Executing [s@macro-user-callerid:1] Set("DAHDI/1-1", "AMPUSER=") in new stack
[Sep 22 10:41:48] DEBUG[11349] app_macro.c: Executed application: Set
[Sep 22 10:41:48] VERBOSE[11349] logger.c: -- Executing [s@macro-user-callerid:2] GotoIf("DAHDI/1-1", "0?report") in new stack
[Sep 22 10:41:48] DEBUG[11349] app_macro.c: Executed application: GotoIf
[Sep 22 10:41:48] VERBOSE[11349] logger.c: -- Executing [s@macro-user-callerid:3] ExecIf("DAHDI/1-1", "1|Set|REALCALLERIDNUM=") in new stack
[Sep 22 10:41:48] DEBUG[11349] app_macro.c: Executed application: ExecIf
[Sep 22 10:41:48] DEBUG[11349] app_macro.c: Last app: Set|REALCALLERIDNUM=
[Sep 22 10:41:48] DEBUG[11349] func_db.c: DB: DEVICE//user not found in database.
[Sep 22 10:41:48] VERBOSE[11349] logger.c: -- Executing [s@macro-user-callerid:4] Set("DAHDI/1-1", "AMPUSER=") in new stack
[Sep 22 10:41:48] DEBUG[11349] app_macro.c: Executed application: Set
[Sep 22 10:41:48] DEBUG[11349] func_db.c: DB: AMPUSER//cidname not found in database.
[Sep 22 10:41:48] VERBOSE[11349] logger.c: -- Executing [s@macro-user-callerid:5] Set("DAHDI/1-1", "AMPUSERCIDNAME=") in new stack
[Sep 22 10:41:48] DEBUG[11349] app_macro.c: Executed application: Set
[Sep 22 10:41:48] VERBOSE[11349] logger.c: -- Executing [s@macro-user-callerid:6] GotoIf("DAHDI/1-1", "1?report") in new stack
[Sep 22 10:41:48] VERBOSE[11349] logger.c: -- Goto (macro-user-callerid,s,11)
[Sep 22 10:41:48] DEBUG[11349] app_macro.c: Executed application: GotoIf
[Sep 22 10:41:48] VERBOSE[11349] logger.c: -- Executing [s@macro-user-callerid:11] GotoIf("DAHDI/1-1", "0?continue") in new stack
[Sep 22 10:41:48] DEBUG[11349] app_macro.c: Executed application: GotoIf
[Sep 22 10:41:48] VERBOSE[11349] logger.c: -- Executing [s@macro-user-callerid:12] Set("DAHDI/1-1", "__TTL=64") in new stack
[Sep 22 10:41:48] DEBUG[11349] app_macro.c: Executed application: Set
[Sep 22 10:41:48] VERBOSE[11349] logger.c: -- Executing [s@macro-user-callerid:13] GotoIf("DAHDI/1-1", "1?continue") in new stack
[Sep 22 10:41:48] VERBOSE[11349] logger.c: -- Goto (macro-user-callerid,s,20)
[Sep 22 10:41:48] DEBUG[11349] app_macro.c: Executed application: GotoIf
[Sep 22 10:41:48] VERBOSE[11349] logger.c: -- Executing [s@macro-user-callerid:20] NoOp("DAHDI/1-1", "Using CallerID "" <>") in new stack
[Sep 22 10:41:48] DEBUG[11349] app_macro.c: Executed application: Noop
[Sep 22 10:41:48] DEBUG[11349] app_macro.c: Executed application: Macro
[Sep 22 10:41:48] VERBOSE[11349] logger.c: -- Executing [s@macro-exten-vm:2] Set("DAHDI/1-1", "RingGroupMethod=none") in new stack
[Sep 22 10:41:48] DEBUG[11349] app_macro.c: Executed application: Set
[Sep 22 10:41:48] VERBOSE[11349] logger.c: -- Executing [s@macro-exten-vm:3] Set("DAHDI/1-1", "VMBOX=novm") in new stack
[Sep 22 10:41:48] DEBUG[11349] app_macro.c: Executed application: Set
[Sep 22 10:41:48] VERBOSE[11349] logger.c: -- Executing [s@macro-exten-vm:4] Set("DAHDI/1-1", "EXTTOCALL=9999") in new stack
[Sep 22 10:41:48] DEBUG[11349] app_macro.c: Executed application: Set
[Sep 22 10:41:48] DEBUG[11349] func_db.c: DB: CFU/9999 not found in database.
[Sep 22 10:41:48] VERBOSE[11349] logger.c: -- Executing [s@macro-exten-vm:5] Set("DAHDI/1-1", "CFUEXT=") in new stack
[Sep 22 10:41:48] DEBUG[11349] app_macro.c: Executed application: Set
[Sep 22 10:41:48] DEBUG[11349] func_db.c: DB: CFB/9999 not found in database.
[Sep 22 10:41:48] VERBOSE[11349] logger.c: -- Executing [s@macro-exten-vm:6] Set("DAHDI/1-1", "CFBEXT=") in new stack
[Sep 22 10:41:48] DEBUG[11349] app_macro.c: Executed application: Set
[Sep 22 10:41:48] VERBOSE[11349] logger.c: -- Executing [s@macro-exten-vm:7] Set("DAHDI/1-1", "RT=""") in new stack
[Sep 22 10:41:48] DEBUG[11349] app_macro.c: Executed application: Set
[Sep 22 10:41:48] VERBOSE[11349] logger.c: -- Executing [s@macro-exten-vm:8] Macro("DAHDI/1-1", "record-enable|9999|IN") in new stack
[Sep 22 10:41:48] VERBOSE[11349] logger.c: -- Executing [s@macro-record-enable:1] GotoIf("DAHDI/1-1", "1?check") in new stack
[Sep 22 10:41:48] VERBOSE[11349] logger.c: -- Goto (macro-record-enable,s,4)
[Sep 22 10:41:48] DEBUG[11349] app_macro.c: Executed application: GotoIf
[Sep 22 10:41:48] VERBOSE[11349] logger.c: -- Executing [s@macro-record-enable:4] AGI("DAHDI/1-1", "recordingcheck|20090922-104148|1253587308.4446") in new stack
[Sep 22 10:41:48] VERBOSE[11349] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
[Sep 22 10:41:48] VERBOSE[11349] logger.c: recordingcheck|20090922-104148|1253587308.4446: Inbound recording not enabled
[Sep 22 10:41:48] VERBOSE[11349] logger.c: -- AGI Script recordingcheck completed, returning 0
[Sep 22 10:41:48] DEBUG[11349] app_macro.c: Executed application: AGI
[Sep 22 10:41:48] VERBOSE[11349] logger.c: -- Executing [s@macro-record-enable:5] MacroExit("DAHDI/1-1", "") in new stack
[Sep 22 10:41:48] DEBUG[11349] app_macro.c: Executed application: Macro
[Sep 22 10:41:48] VERBOSE[11349] logger.c: -- Executing [s@macro-exten-vm:9] Macro("DAHDI/1-1", "dial||Ttr|9999") in new stack
[Sep 22 10:41:48] VERBOSE[11349] logger.c: -- Executing [s@macro-dial:1] GotoIf("DAHDI/1-1", "1?dial") in new stack
[Sep 22 10:41:48] VERBOSE[11349] logger.c: -- Goto (macro-dial,s,3)
[Sep 22 10:41:48] DEBUG[11349] app_macro.c: Executed application: GotoIf
[Sep 22 10:41:48] VERBOSE[11349] logger.c: -- Executing [s@macro-dial:3] AGI("DAHDI/1-1", "dialparties.agi") in new stack
[Sep 22 10:41:48] VERBOSE[11349] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
[Sep 22 10:41:48] VERBOSE[11349] logger.c: dialparties.agi: Starting New Dialparties.agi
[Sep 22 10:41:48] VERBOSE[11352] logger.c: == Parsing '/etc/asterisk/manager.conf': [Sep 22 10:41:48] VERBOSE[11352] logger.c: Found
[Sep 22 10:41:48] VERBOSE[11352] logger.c: == Parsing '/etc/asterisk/manager_additional.conf': [Sep 22 10:41:48] VERBOSE[11352] logger.c: Found
[Sep 22 10:41:48] VERBOSE[11352] logger.c: == Parsing '/etc/asterisk/manager_custom.conf': [Sep 22 10:41:48] VERBOSE[11352] logger.c: Found
[Sep 22 10:41:48] VERBOSE[11352] logger.c: == Manager 'admin' logged on from 127.0.0.1
[Sep 22 10:41:48] VERBOSE[11349] logger.c: dialparties.agi: Caller ID name is 'unknown' number is 'unknown'
[Sep 22 10:41:48] VERBOSE[11349] logger.c: dialparties.agi: Methodology of ring is 'none'
[Sep 22 10:41:48] VERBOSE[11349] logger.c: -- dialparties.agi: Added extension 9999 to extension map
[Sep 22 10:41:48] VERBOSE[11349] logger.c: > dialparties.agi: Extension 9999 has call screening off
[Sep 22 10:41:48] VERBOSE[11349] logger.c: -- dialparties.agi: Extension 9999 cf is disabled
[Sep 22 10:41:48] VERBOSE[11349] logger.c: -- dialparties.agi: Extension 9999 do not disturb is disabled
[Sep 22 10:41:48] VERBOSE[11349] logger.c: > dialparties.agi: extnum 9999 has: cw: 0; hascfb: 0 [] hascfu: 0 []
[Sep 22 10:41:48] VERBOSE[11349] logger.c: > dialparties.agi: ExtensionState: 0
[Sep 22 10:41:48] VERBOSE[11349] logger.c: dialparties.agi: Extension 9999 has ExtensionState: 0
[Sep 22 10:41:48] VERBOSE[11349] logger.c: -- dialparties.agi: Checking CW and CFB status for extension 9999
[Sep 22 10:41:48] VERBOSE[11349] logger.c: -- dialparties.agi: DbDel CALLTRACE/9999 - Caller ID is not defined
[Sep 22 10:41:48] VERBOSE[11349] logger.c: -- dialparties.agi: Filtered ARG3: 9999
[Sep 22 10:41:48] VERBOSE[11352] logger.c: == Manager 'admin' logged off from 127.0.0.1

[Sep 22 10:41:48] VERBOSE[11349] logger.c: -- AGI Script dialparties.agi completed, returning 0
[Sep 22 10:41:48] DEBUG[11349] app_macro.c: Executed application: AGI
[Sep 22 10:41:48] VERBOSE[11349] logger.c: -- Executing [s@macro-dial:7] Dial("DAHDI/1-1", "SIP/9999||Ttr") in new stack
[Sep 22 10:41:48] NOTICE[11349] app_dial.c: Hey! chan DAHDI/1-1's context='macro-dial', and exten='s'
[Sep 22 10:41:48] VERBOSE[11349] logger.c: -- Called 9999
[Sep 22 10:41:48] VERBOSE[11349] logger.c: -- SIP/9999-b71032a0 is ringing
[Sep 22 10:41:49] DEBUG[11349] dsp.c: ast_dsp_busydetect detected busy, avgtone: 95, avgsilence 80
[Sep 22 10:41:49] VERBOSE[11349] logger.c: == Spawn extension (macro-dial, s, 7) exited non-zero on 'DAHDI/1-1' in macro 'dial'
[Sep 22 10:41:49] VERBOSE[11349] logger.c: == Spawn extension (macro-exten-vm, s, 9) exited non-zero on 'DAHDI/1-1' in macro 'exten-vm'
[Sep 22 10:41:49] VERBOSE[11349] logger.c: == Spawn extension (from-did-direct, 9999, 1) exited non-zero on 'DAHDI/1-1'
[Sep 22 10:41:49] VERBOSE[11349] logger.c: -- Executing [h@macro-dial:1] Macro("DAHDI/1-1", "hangupcall") in new stack
[Sep 22 10:41:49] VERBOSE[11349] logger.c: -- Executing [s@macro-hangupcall:1] ResetCDR("DAHDI/1-1", "vw") in new stack
[Sep 22 10:41:49] DEBUG[11349] app_macro.c: Executed application: ResetCDR
[Sep 22 10:41:49] VERBOSE[11349] logger.c: -- Executing [s@macro-hangupcall:2] NoCDR("DAHDI/1-1", "") in new stack
[Sep 22 10:41:49] DEBUG[11349] app_macro.c: Executed application: NoCDR
[Sep 22 10:41:49] VERBOSE[11349] logger.c: -- Executing [s@macro-hangupcall:3] GotoIf("DAHDI/1-1", "1?skiprg") in new stack
[Sep 22 10:41:49] VERBOSE[11349] logger.c: -- Goto (macro-hangupcall,s,6)
[Sep 22 10:41:49] DEBUG[11349] app_macro.c: Executed application: GotoIf
[Sep 22 10:41:49] VERBOSE[11349] logger.c: -- Executing [s@macro-hangupcall:6] GotoIf("DAHDI/1-1", "1?skipblkvm") in new stack
[Sep 22 10:41:49] VERBOSE[11349] logger.c: -- Goto (macro-hangupcall,s,9)
[Sep 22 10:41:49] DEBUG[11349] app_macro.c: Executed application: GotoIf
[Sep 22 10:41:49] VERBOSE[11349] logger.c: -- Executing [s@macro-hangupcall:9] GotoIf("DAHDI/1-1", "1?theend") in new stack
[Sep 22 10:41:49] VERBOSE[11349] logger.c: -- Goto (macro-hangupcall,s,11)
[Sep 22 10:41:49] DEBUG[11349] app_macro.c: Executed application: GotoIf
[Sep 22 10:41:49] VERBOSE[11349] logger.c: -- Executing [s@macro-hangupcall:11] Hangup("DAHDI/1-1", "") in new stack
[Sep 22 10:41:49] VERBOSE[11349] logger.c: == Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'DAHDI/1-1' in macro 'hangupcall'
[Sep 22 10:41:49] VERBOSE[11349] logger.c: == Spawn extension (macro-dial, h, 1) exited non-zero on 'DAHDI/1-1'
[Sep 22 10:41:49] VERBOSE[11349] logger.c: -- Hungup 'DAHDI/1-1'
[Sep 22 10:41:56] DEBUG[3153] dsp.c: dsp busy pattern set to 0,0
[Sep 22 10:41:56] VERBOSE[11359] logger.c: -- Starting simple switch on 'DAHDI/1-1'
[Sep 22 10:41:56] ERROR[11359] callerid.c: No start bit found in fsk data.
[Sep 22 10:41:56] WARNING[11359] chan_dahdi.c: CallerID feed failed: Resource temporarily unavailable
[Sep 22 10:41:56] WARNING[11359] chan_dahdi.c: CallerID returned with error on channel 'DAHDI/1-1'
[Sep 22 10:41:56] VERBOSE[11359] logger.c: -- Executing [s@from-zaptel:1] NoOp("DAHDI/1-1", "Entering from-zaptel with DID == ") in new stack
[Sep 22 10:41:56] VERBOSE[11359] logger.c: -- Executing [s@from-zaptel:2] Ringing("DAHDI/1-1", "") in new stack
[Sep 22 10:41:56] VERBOSE[11359] logger.c: -- Executing [s@from-zaptel:3] Set("DAHDI/1-1", "DID=s") in new stack
[Sep 22 10:41:56] VERBOSE[11359] logger.c: -- Executing [s@from-zaptel:4] NoOp("DAHDI/1-1", "DID is now s") in new stack
[Sep 22 10:41:56] VERBOSE[11359] logger.c: -- Executing [s@from-zaptel:5] GotoIf("DAHDI/1-1", "1?zapok:notzap") in new stack
[Sep 22 10:41:56] VERBOSE[11359] logger.c: -- Goto (from-zaptel,s,
[Sep 22 10:41:56] VERBOSE[11359] logger.c: -- Executing [s@from-zaptel:8] NoOp("DAHDI/1-1", "Is a Zaptel Channel") in new stack
[Sep 22 10:41:56] VERBOSE[11359] logger.c: -- Executing [s@from-zaptel:9] Set("DAHDI/1-1", "CHAN=1-1") in new stack
[Sep 22 10:41:56] VERBOSE[11359] logger.c: -- Executing [s@from-zaptel:10] Set("DAHDI/1-1", "CHAN=1") in new stack
[Sep 22 10:41:56] VERBOSE[11359] logger.c: -- Executing [s@from-zaptel:11] Macro("DAHDI/1-1", "from-zaptel-1|s|1") in new stack
[Sep 22 10:41:56] VERBOSE[11359] logger.c: -- Executing [s@macro-from-zaptel-1:1] NoOp("DAHDI/1-1", "Entering macro-from-zaptel-1 with DID = s and setting to: 1") in new stack
[Sep 22 10:41:56] DEBUG[11359] app_macro.c: Executed application: Noop
[Sep 22 10:41:56] VERBOSE[11359] logger.c: -- Executing [s@macro-from-zaptel-1:2] Set("DAHDI/1-1", "__FROM_DID=1") in new stack
[Sep 22 10:41:56] DEBUG[11359] app_macro.c: Executed application: Set
[Sep 22 10:41:56] VERBOSE[11359] logger.c: -- Executing [s@macro-from-zaptel-1:3] Goto("DAHDI/1-1", "from-trunk|1|1") in new stack
[Sep 22 10:41:56] VERBOSE[11359] logger.c: -- Goto (from-trunk,1,1)
[Sep 22 10:41:56] DEBUG[11359] app_macro.c: Executed application: Goto
[Sep 22 10:41:56] VERBOSE[11359] logger.c: == Channel 'DAHDI/1-1' jumping out of macro 'from-zaptel-1'
[Sep 22 10:41:56] VERBOSE[11359] logger.c: -- Executing [1@from-trunk:1] Set("DAHDI/1-1", "__FROM_DID=1") in new stack
[Sep 22 10:41:56] VERBOSE[11359] logger.c: -- Executing [1@from-trunk:2] Gosub("DAHDI/1-1", "app-blacklist-check|s|1") in new stack
[Sep 22 10:41:56] VERBOSE[11359] logger.c: -- Executing [s@app-blacklist-check:1] LookupBlacklist("DAHDI/1-1", "") in new stack
[Sep 22 10:41:56] VERBOSE[11359] logger.c: -- Executing [s@app-blacklist-check:2] GotoIf("DAHDI/1-1", "0?blacklisted") in new stack
[Sep 22 10:41:56] VERBOSE[11359] logger.c: -- Executing [s@app-blacklist-check:3] Return("DAHDI/1-1", "") in new stack
[Sep 22 10:41:56] VERBOSE[11359] logger.c: -- Executing [1@from-trunk:3] ExecIf("DAHDI/1-1", "1 |Set|CALLERID(name)=") in new stack
[Sep 22 10:41:56] VERBOSE[11359] logger.c: -- Executing [1@from-trunk:4] Set("DAHDI/1-1", "__CALLINGPRES_SV=allowed_not_screened") in new stack
[Sep 22 10:41:56] VERBOSE[11359] logger.c: -- Executing [1@from-trunk:5] SetCallerPres("DAHDI/1-1", "allowed_not_screened") in new stack
[Sep 22 10:41:56] VERBOSE[11359] logger.c: -- Executing [1@from-trunk:6] Goto("DAHDI/1-1", "from-did-direct|9999|1") in new stack
[Sep 22 10:41:56] VERBOSE[11359] logger.c: -- Goto (from-did-direct,9999,1)
[Sep 22 10:41:56] VERBOSE[11359] logger.c: -- Executing [9999@from-did-direct:1] Macro("DAHDI/1-1", "exten-vm|novm|9999") in new stack
[Sep 22 10:41:56] VERBOSE[11359] logger.c: -- Executing [s@macro-exten-vm:1] Macro("DAHDI/1-1", "user-callerid") in new stack
[Sep 22 10:41:56] VERBOSE[11359] logger.c: -- Executing [s@macro-user-callerid:1] Set("DAHDI/1-1", "AMPUSER=") in new stack
[Sep 22 10:41:56] DEBUG[11359] app_macro.c: Executed application: Set
[Sep 22 10:41:56] VERBOSE[11359] logger.c: -- Executing [s@macro-user-callerid:2] GotoIf("DAHDI/1-1", "0?report") in new stack
[Sep 22 10:41:56] DEBUG[11359] app_macro.c: Executed application: GotoIf
[Sep 22 10:41:56] VERBOSE[11359] logger.c: -- Executing [s@macro-user-callerid:3] ExecIf("DAHDI/1-1", "1|Set|REALCALLERIDNUM=") in new stack
[Sep 22 10:41:56] DEBUG[11359] app_macro.c: Executed application: ExecIf
[Sep 22 10:41:56] DEBUG[11359] app_macro.c: Last app: Set|REALCALLERIDNUM=
[Sep 22 10:41:56] DEBUG[11359] func_db.c: DB: DEVICE//user not found in database.
[Sep 22 10:41:56] VERBOSE[11359] logger.c: -- Executing [s@macro-user-callerid:4] Set("DAHDI/1-1", "AMPUSER=") in new stack
[Sep 22 10:41:56] DEBUG[11359] app_macro.c: Executed application: Set
[Sep 22 10:41:56] DEBUG[11359] func_db.c: DB: AMPUSER//cidname not found in database.
[Sep 22 10:41:56] VERBOSE[11359] logger.c: -- Executing [s@macro-user-callerid:5] Set("DAHDI/1-1", "AMPUSERCIDNAME=") in new stack
[Sep 22 10:41:56] DEBUG[11359] app_macro.c: Executed application: Set
[Sep 22 10:41:56] VERBOSE[11359] logger.c: -- Executing [s@macro-user-callerid:6] GotoIf("DAHDI/1-1", "1?report") in new stack
[Sep 22 10:41:56] VERBOSE[11359] logger.c: -- Goto (macro-user-callerid,s,11)
[Sep 22 10:41:56] DEBUG[11359] app_macro.c: Executed application: GotoIf
[Sep 22 10:41:56] VERBOSE[11359] logger.c: -- Executing [s@macro-user-callerid:11] GotoIf("DAHDI/1-1", "0?continue") in new stack
[Sep 22 10:41:56] DEBUG[11359] app_macro.c: Executed application: GotoIf
[Sep 22 10:41:56] VERBOSE[11359] logger.c: -- Executing [s@macro-user-callerid:12] Set("DAHDI/1-1", "__TTL=64") in new stack
[Sep 22 10:41:56] DEBUG[11359] app_macro.c: Executed application: Set
[Sep 22 10:41:56] VERBOSE[11359] logger.c: -- Executing [s@macro-user-callerid:13] GotoIf("DAHDI/1-1", "1?continue") in new stack
[Sep 22 10:41:56] VERBOSE[11359] logger.c: -- Goto (macro-user-callerid,s,20)
[Sep 22 10:41:56] DEBUG[11359] app_macro.c: Executed application: GotoIf
[Sep 22 10:41:56] VERBOSE[11359] logger.c: -- Executing [s@macro-user-callerid:20] NoOp("DAHDI/1-1", "Using CallerID "" <>") in new stack
[Sep 22 10:41:56] DEBUG[11359] app_macro.c: Executed application: Noop
[Sep 22 10:41:56] DEBUG[11359] app_macro.c: Executed application: Macro
[Sep 22 10:41:56] VERBOSE[11359] logger.c: -- Executing [s@macro-exten-vm:2] Set("DAHDI/1-1", "RingGroupMethod=none") in new stack
[Sep 22 10:41:56] DEBUG[11359] app_macro.c: Executed application: Set
[Sep 22 10:41:56] VERBOSE[11359] logger.c: -- Executing [s@macro-exten-vm:3] Set("DAHDI/1-1", "VMBOX=novm") in new stack
[Sep 22 10:41:56] DEBUG[11359] app_macro.c: Executed application: Set
[Sep 22 10:41:56] VERBOSE[11359] logger.c: -- Executing [s@macro-exten-vm:4] Set("DAHDI/1-1", "EXTTOCALL=9999") in new stack
[Sep 22 10:41:56] DEBUG[11359] app_macro.c: Executed application: Set
[Sep 22 10:41:56] DEBUG[11359] func_db.c: DB: CFU/9999 not found in database.
[Sep 22 10:41:56] VERBOSE[11359] logger.c: -- Executing [s@macro-exten-vm:5] Set("DAHDI/1-1", "CFUEXT=") in new stack
[Sep 22 10:41:56] DEBUG[11359] app_macro.c: Executed application: Set
[Sep 22 10:41:56] DEBUG[11359] func_db.c: DB: CFB/9999 not found in database.
[Sep 22 10:41:56] VERBOSE[11359] logger.c: -- Executing [s@macro-exten-vm:6] Set("DAHDI/1-1", "CFBEXT=") in new stack
[Sep 22 10:41:56] DEBUG[11359] app_macro.c: Executed application: Set
[Sep 22 10:41:56] VERBOSE[11359] logger.c: -- Executing [s@macro-exten-vm:7] Set("DAHDI/1-1", "RT=""") in new stack
[Sep 22 10:41:56] DEBUG[11359] app_macro.c: Executed application: Set
[Sep 22 10:41:56] VERBOSE[11359] logger.c: -- Executing [s@macro-exten-vm:8] Macro("DAHDI/1-1", "record-enable|9999|IN") in new stack
[Sep 22 10:41:56] VERBOSE[11359] logger.c: -- Executing [s@macro-record-enable:1] GotoIf("DAHDI/1-1", "1?check") in new stack
[Sep 22 10:41:56] VERBOSE[11359] logger.c: -- Goto (macro-record-enable,s,4)
[Sep 22 10:41:56] DEBUG[11359] app_macro.c: Executed application: GotoIf
[Sep 22 10:41:56] VERBOSE[11359] logger.c: -- Executing [s@macro-record-enable:4] AGI("DAHDI/1-1", "recordingcheck|20090922-104156|1253587316.4448") in new stack
[Sep 22 10:41:56] VERBOSE[11359] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
[Sep 22 10:41:56] VERBOSE[11359] logger.c: recordingcheck|20090922-104156|1253587316.4448: Inbound recording not enabled
[Sep 22 10:41:56] VERBOSE[11359] logger.c: -- AGI Script recordingcheck completed, returning 0
[Sep 22 10:41:56] DEBUG[11359] app_macro.c: Executed application: AGI
[Sep 22 10:41:56] VERBOSE[11359] logger.c: -- Executing [s@macro-record-enable:5] MacroExit("DAHDI/1-1", "") in new stack
[Sep 22 10:41:56] DEBUG[11359] app_macro.c: Executed application: Macro
[Sep 22 10:41:56] VERBOSE[11359] logger.c: -- Executing [s@macro-exten-vm:9] Macro("DAHDI/1-1", "dial||Ttr|9999") in new stack
[Sep 22 10:41:56] VERBOSE[11359] logger.c: -- Executing [s@macro-dial:1] GotoIf("DAHDI/1-1", "1?dial") in new stack
[Sep 22 10:41:56] VERBOSE[11359] logger.c: -- Goto (macro-dial,s,3)
[Sep 22 10:41:56] DEBUG[11359] app_macro.c: Executed application: GotoIf
[Sep 22 10:41:56] VERBOSE[11359] logger.c: -- Executing [s@macro-dial:3] AGI("DAHDI/1-1", "dialparties.agi") in new stack
[Sep 22 10:41:56] VERBOSE[11359] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
[Sep 22 10:41:56] VERBOSE[11359] logger.c: dialparties.agi: Starting New Dialparties.agi
[Sep 22 10:41:56] VERBOSE[11362] logger.c: == Parsing '/etc/asterisk/manager.conf': [Sep 22 10:41:56] VERBOSE[11362] logger.c: Found
[Sep 22 10:41:56] VERBOSE[11362] logger.c: == Parsing '/etc/asterisk/manager_additional.conf': [Sep 22 10:41:56] VERBOSE[11362] logger.c: Found
[Sep 22 10:41:56] VERBOSE[11362] logger.c: == Parsing '/etc/asterisk/manager_custom.conf': [Sep 22 10:41:56] VERBOSE[11362] logger.c: Found
[Sep 22 10:41:56] VERBOSE[11362] logger.c: == Manager 'admin' logged on from 127.0.0.1
[Sep 22 10:41:56] VERBOSE[11359] logger.c: dialparties.agi: Caller ID name is 'unknown' number is 'unknown'
[Sep 22 10:41:56] VERBOSE[11359] logger.c: dialparties.agi: Methodology of ring is 'none'
[Sep 22 10:41:56] VERBOSE[11359] logger.c: -- dialparties.agi: Added extension 9999 to extension map
[Sep 22 10:41:56] VERBOSE[11359] logger.c: > dialparties.agi: Extension 9999 has call screening off
[Sep 22 10:41:56] VERBOSE[11359] logger.c: -- dialparties.agi: Extension 9999 cf is disabled
[Sep 22 10:41:56] VERBOSE[11359] logger.c: -- dialparties.agi: Extension 9999 do not disturb is disabled
[Sep 22 10:41:56] VERBOSE[11359] logger.c: > dialparties.agi: extnum 9999 has: cw: 0; hascfb: 0 [] hascfu: 0 []
[Sep 22 10:41:56] VERBOSE[11359] logger.c: > dialparties.agi: ExtensionState: 0
[Sep 22 10:41:56] VERBOSE[11359] logger.c: dialparties.agi: Extension 9999 has ExtensionState: 0
[Sep 22 10:41:56] VERBOSE[11359] logger.c: -- dialparties.agi: Checking CW and CFB status for extension 9999
[Sep 22 10:41:56] VERBOSE[11359] logger.c: -- dialparties.agi: DbDel CALLTRACE/9999 - Caller ID is not defined
[Sep 22 10:41:56] VERBOSE[11359] logger.c: -- dialparties.agi: Filtered ARG3: 9999
[Sep 22 10:41:56] VERBOSE[11362] logger.c: == Manager 'admin' logged off from 127.0.0.1
[Sep 22 10:41:56] VERBOSE[11359] logger.c: -- AGI Script dialparties.agi completed, returning 0
[Sep 22 10:41:56] DEBUG[11359] app_macro.c: Executed application: AGI
[Sep 22 10:41:56] VERBOSE[11359] logger.c: -- Executing [s@macro-dial:7] Dial("DAHDI/1-1", "SIP/9999||Ttr") in new stack
[Sep 22 10:41:56] NOTICE[11359] app_dial.c: Hey! chan DAHDI/1-1's context='macro-dial', and exten='s'
[Sep 22 10:41:56] VERBOSE[11359] logger.c: -- Called 9999
[Sep 22 10:41:56] VERBOSE[11359] logger.c: -- SIP/9999-08dffb80 is ringing
[Sep 22 10:41:57] VERBOSE[11359] logger.c: -- SIP/9999-08dffb80 answered DAHDI/1-1
[Sep 22 10:41:57] DEBUG[11359] chan_dahdi.c: Took DAHDI/1-1 off hook
[Sep 22 10:41:57] DEBUG[11359] chan_dahdi.c: Engaged echo training on channel 1

你的debug 太多了，我只能把我看到的告诉你：
1） 你没有来电显示， 路由的时候可能出问题。
2） 振铃方式不对。
===========================
dialparties.agi: Caller ID name is 'unknown' number is 'unknown'
[Sep 22 10:41:48] VERBOSE[11349] logger.c: dialparties.agi: Methodology of ring is 'none'
==========================
最好的解决办法就是自己写一个简单的拨号规则，进来以后直接转分机。看看出现什么情况。elastix 的dialplan 太长，不容易看出来错误。
james.zhu

哦，这日志是我从服务下载下来的，只是我不太会分析日志。不是debug的。怎么debug？:L
1. 这来电显示的确异常，话机上显示为unknown，不过，应当路由没有问题，因为我很多时候都是正常，就是偶尔出现这种情况，让人难受。
2.振铃方式为什么会不对，会不会因为我选择了自定义铃音？


谢谢james.zhu，总能这么及时给以帮助。
:handshake


还有，我的卡都是托别人帮买的，也没有技术支持，请问从你们那买卡，能否提供远程技术支持？技术支持如果要收费，怎么收？

i] Last edited by dosimple at 2009-9-22 13:39 [/i

振铃组里面没有任何振铃方式。界面可以选择多种方式的，
我们提供技术支持，但是我们只负责我们卡方面的问题。其他的我们不负责。

振铃组里面没有任何振铃方式？我有好多dialparties.agi: Methodology of ring is 'none'记录，但好像都正常