Некорректно работает трансфер звонка по таймауту в IVR

[JackB]

Настроил IVR. По таймауту звонок должен уходить на секретаря и далее по цепочке, если секретаря нету. Проблема такова: секретарь или кто-либо другой, кто отвечают на звонок, не слышат звонящего, а звонящий в это время слышит гудки. Кто виноват и что делать?

[Aven]

Вы виноваты. В том, что как минимум не предоставили лога вызова.

[JackB]

Если подскажете, как вычленить из логов elastix отдельный звонок - с радостью предоставлю.

[jugatsu]

Но для приличия хоть конфигурацию опиши, как приходит звонок - SIP,pri и т.д. Выложи скрины настройки, да, правильно скрины, а что не так?

[JackB]

Используется SIP транк. Внешний номер куплен у эртелекома.
Цепочка для входящих звонков такова: DID->Time Condition -> IVR -> секретарь и далее через followme.

[jugatsu]

NAT что ли?

rtp set debug on

трафик ходит?

[JackB]

Nat нету, все в локальной сети. Хотелось бы так же уточнить, что проблема проявляется ТОЛЬКО со включенным IVR.
И где я смогу увидеть вывод этого rtp debug?

[JackB]

Выкладываю лог одного из тестовых звонков. Кстати, иногда соединение все-таки устанавливается, но всего на пару секунд. Затем у звонящего снова идут гудки ожидания, а принимающий звонок слышит звонящего.

Код: Выделить всё

Apr 16 16:15:24	VERBOSE	[3295] netsock2.c:	
== Using SIP RTP TOS bits 224
Apr 16 16:15:24	VERBOSE	[3295] netsock2.c:	
== Using SIP RTP CoS mark 5
Apr 16 16:15:24	VERBOSE	[25430] pbx.c:	
-- Executing [78XXXXXXXXX@from-trunk-sip-rtel4_out:1] Set("SIP/rtel4_out-0000096f", "GROUP()=OUT_7") in new stack
Apr 16 16:15:24	VERBOSE	[25430] pbx.c:	
-- Executing [78XXXXXXXXX@from-trunk-sip-rtel4_out:2] Goto("SIP/rtel4_out-0000096f", "from-trunk,78XXXXXXXXX,1") in new stack
Apr 16 16:15:24	VERBOSE	[25430] pbx.c:	
-- Goto (from-trunk,78XXXXXXXXX,1)
Apr 16 16:15:24	VERBOSE	[25430] pbx.c:	
-- Executing [78XXXXXXXXX@from-trunk:1] Set("SIP/rtel4_out-0000096f", "__FROM_DID=78XXXXXXXXX") in new stack
Apr 16 16:15:24	VERBOSE	[25430] pbx.c:	
-- Executing [78XXXXXXXXX@from-trunk:2] Gosub("SIP/rtel4_out-0000096f", "app-blacklist-check,s,1") in new stack
Apr 16 16:15:24	VERBOSE	[25430] pbx.c:	
-- Executing [s@app-blacklist-check:1] GotoIf("SIP/rtel4_out-0000096f", "0?blacklisted") in new stack
Apr 16 16:15:24	VERBOSE	[25430] pbx.c:	
-- Executing [s@app-blacklist-check:2] Set("SIP/rtel4_out-0000096f", "CALLED_BLACKLIST=1") in new stack
Apr 16 16:15:24	VERBOSE	[25430] pbx.c:	
-- Executing [s@app-blacklist-check:3] Return("SIP/rtel4_out-0000096f", "") in new stack
Apr 16 16:15:24	VERBOSE	[25430] pbx.c:	
-- Executing [78XXXXXXXXX@from-trunk:3] ExecIf("SIP/rtel4_out-0000096f", "0 ?Set(CALLERID(name)=7XXXXXXXXXX)") in new stack
Apr 16 16:15:24	VERBOSE	[25430] pbx.c:	
-- Executing [78XXXXXXXXX@from-trunk:4] Ringing("SIP/rtel4_out-0000096f", "") in new stack
Apr 16 16:15:24	VERBOSE	[25430] pbx.c:	
-- Executing [78XXXXXXXXX@from-trunk:5] Set("SIP/rtel4_out-0000096f", "__CALLINGPRES_SV=allowed_not_screened") in new stack
Apr 16 16:15:24	VERBOSE	[25430] pbx.c:	
-- Executing [78XXXXXXXXX@from-trunk:6] Set("SIP/rtel4_out-0000096f", "CALLERPRES()=allowed_not_screened") in new stack
Apr 16 16:15:24	VERBOSE	[25430] pbx.c:	
-- Executing [78XXXXXXXXX@from-trunk:7] Goto("SIP/rtel4_out-0000096f", "ivr-5,s,1") in new stack
Apr 16 16:15:24	VERBOSE	[25430] pbx.c:	
-- Goto (ivr-5,s,1)
Apr 16 16:15:24	VERBOSE	[25430] pbx.c:	
-- Executing [s@ivr-5:1] Set("SIP/rtel4_out-0000096f", "MSG=") in new stack
Apr 16 16:15:24	VERBOSE	[25430] pbx.c:	
-- Executing [s@ivr-5:2] Set("SIP/rtel4_out-0000096f", "LOOPCOUNT=0") in new stack
Apr 16 16:15:24	VERBOSE	[25430] pbx.c:	
-- Executing [s@ivr-5:3] Set("SIP/rtel4_out-0000096f", "__DIR-CONTEXT=") in new stack
Apr 16 16:15:24	VERBOSE	[25430] pbx.c:	
-- Executing [s@ivr-5:4] Set("SIP/rtel4_out-0000096f", "_IVR_CONTEXT_ivr-5=") in new stack
Apr 16 16:15:24	VERBOSE	[25430] pbx.c:	
-- Executing [s@ivr-5:5] Set("SIP/rtel4_out-0000096f", "_IVR_CONTEXT=ivr-5") in new stack
Apr 16 16:15:24	VERBOSE	[25430] pbx.c:	
-- Executing [s@ivr-5:6] GotoIf("SIP/rtel4_out-0000096f", "0?begin") in new stack
Apr 16 16:15:24	VERBOSE	[25430] pbx.c:	
-- Executing [s@ivr-5:7] Answer("SIP/rtel4_out-0000096f", "") in new stack
Apr 16 16:15:24	VERBOSE	[25430] pbx.c:	
-- Executing [s@ivr-5:8] Wait("SIP/rtel4_out-0000096f", "1") in new stack
Apr 16 16:15:26	VERBOSE	[25430] pbx.c:	
-- Executing [s@ivr-5:9] Set("SIP/rtel4_out-0000096f", "TIMEOUT(digit)=3") in new stack
Apr 16 16:15:26	VERBOSE	[25430] func_timeout.c:	
-- Digit timeout set to 3.000
Apr 16 16:15:26	VERBOSE	[25430] pbx.c:	
-- Executing [s@ivr-5:10] Set("SIP/rtel4_out-0000096f", "TIMEOUT(response)=7") in new stack
Apr 16 16:15:26	VERBOSE	[25430] func_timeout.c:	
-- Response timeout set to 7.000
Apr 16 16:15:26	VERBOSE	[25430] pbx.c:	
-- Executing [s@ivr-5:11] Set("SIP/rtel4_out-0000096f", "__IVR_RETVM=") in new stack
Apr 16 16:15:26	VERBOSE	[25430] pbx.c:	
-- Executing [s@ivr-5:12] ExecIf("SIP/rtel4_out-0000096f", "0?Background()") in new stack
Apr 16 16:15:26	VERBOSE	[25430] pbx.c:	
-- Executing [s@ivr-5:13] WaitExten("SIP/rtel4_out-0000096f", ",") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Timeout on SIP/rtel4_out-0000096f, going to 't'
Apr 16 16:15:33	WARNING	[25430] func_db.c:	
DB_DELETE requires an argument, DB_DELETE(<family>/<key>)
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [t@ivr-5:1] NoOp("SIP/rtel4_out-0000096f", "Deleting:  ") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [t@ivr-5:2] Set("SIP/rtel4_out-0000096f", "__NODEST=") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [t@ivr-5:3] Goto("SIP/rtel4_out-0000096f", "from-did-direct,149,1") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Goto (from-did-direct,149,1)
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [149@from-did-direct:1] Macro("SIP/rtel4_out-0000096f", "exten-vm,novm,149") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [s@macro-exten-vm:1] Macro("SIP/rtel4_out-0000096f", "user-callerid,") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [s@macro-user-callerid:1] Set("SIP/rtel4_out-0000096f", "AMPUSER=7XXXXXXXXXX") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [s@macro-user-callerid:2] GotoIf("SIP/rtel4_out-0000096f", "0?report") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [s@macro-user-callerid:3] ExecIf("SIP/rtel4_out-0000096f", "1?Set(REALCALLERIDNUM=7XXXXXXXXXX)") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [s@macro-user-callerid:4] Set("SIP/rtel4_out-0000096f", "AMPUSER=") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [s@macro-user-callerid:5] Set("SIP/rtel4_out-0000096f", "AMPUSERCIDNAME=") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [s@macro-user-callerid:6] GotoIf("SIP/rtel4_out-0000096f", "1?report") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Goto (macro-user-callerid,s,10)
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [s@macro-user-callerid:10] GotoIf("SIP/rtel4_out-0000096f", "0?continue") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [s@macro-user-callerid:11] Set("SIP/rtel4_out-0000096f", "__TTL=64") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [s@macro-user-callerid:12] GotoIf("SIP/rtel4_out-0000096f", "1?continue") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Goto (macro-user-callerid,s,19)
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [s@macro-user-callerid:19] Set("SIP/rtel4_out-0000096f", "CALLERID(number)=7XXXXXXXXXX") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [s@macro-user-callerid:20] Set("SIP/rtel4_out-0000096f", "CALLERID(name)=7XXXXXXXXXX") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [s@macro-user-callerid:21] NoOp("SIP/rtel4_out-0000096f", "Using CallerID "7XXXXXXXXXX" <7XXXXXXXXXX>") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [s@macro-exten-vm:2] Set("SIP/rtel4_out-0000096f", "RingGroupMethod=none") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [s@macro-exten-vm:3] Set("SIP/rtel4_out-0000096f", "VMBOX=novm") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [s@macro-exten-vm:4] Set("SIP/rtel4_out-0000096f", "__EXTTOCALL=149") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [s@macro-exten-vm:5] Set("SIP/rtel4_out-0000096f", "CFUEXT=") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [s@macro-exten-vm:6] Set("SIP/rtel4_out-0000096f", "CFBEXT=") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [s@macro-exten-vm:7] Set("SIP/rtel4_out-0000096f", "RT=""") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [s@macro-exten-vm:8] Macro("SIP/rtel4_out-0000096f", "record-enable,149,IN") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [s@macro-record-enable:1] GotoIf("SIP/rtel4_out-0000096f", "1?check") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Goto (macro-record-enable,s,4)
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [s@macro-record-enable:4] ExecIf("SIP/rtel4_out-0000096f", "0?MacroExit()") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [s@macro-record-enable:5] GotoIf("SIP/rtel4_out-0000096f", "0?Group:OUT") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Goto (macro-record-enable,s,15)
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [s@macro-record-enable:15] GotoIf("SIP/rtel4_out-0000096f", "1?IN") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Goto (macro-record-enable,s,20)
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [s@macro-record-enable:20] ExecIf("SIP/rtel4_out-0000096f", "0?MacroExit()") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [s@macro-record-enable:21] NoOp("SIP/rtel4_out-0000096f", "Recording enable for 149") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [s@macro-record-enable:22] Set("SIP/rtel4_out-0000096f", "CALLFILENAME=20120416-161533-1334578524.2698") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [s@macro-record-enable:23] MixMonitor("SIP/rtel4_out-0000096f", "20120416-161533-1334578524.2698.WAV,,") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [s@macro-record-enable:24] Set("SIP/rtel4_out-0000096f", "CDR(userfield)=audio:20120416-161533-1334578524.2698.WAV") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [s@macro-record-enable:25] MacroExit("SIP/rtel4_out-0000096f", "") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [s@macro-exten-vm:9] Macro("SIP/rtel4_out-0000096f", "dial-one,"",Ttr,149") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [s@macro-dial-one:1] Set("SIP/rtel4_out-0000096f", "DEXTEN=149") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [s@macro-dial-one:2] Set("SIP/rtel4_out-0000096f", "DIALSTATUS_CW=") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [s@macro-dial-one:3] GosubIf("SIP/rtel4_out-0000096f", "0?screen,1") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [s@macro-dial-one:4] GosubIf("SIP/rtel4_out-0000096f", "0?cf,1") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [s@macro-dial-one:5] GotoIf("SIP/rtel4_out-0000096f", "1?skip1") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Goto (macro-dial-one,s,8)
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [s@macro-dial-one:8] GotoIf("SIP/rtel4_out-0000096f", "0?nodial") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [s@macro-dial-one:9] GotoIf("SIP/rtel4_out-0000096f", "0?continue") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [s@macro-dial-one:10] Set("SIP/rtel4_out-0000096f", "EXTHASCW=") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [s@macro-dial-one:11] GotoIf("SIP/rtel4_out-0000096f", "1?next1:cwinusebusy") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Goto (macro-dial-one,s,12)
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [s@macro-dial-one:12] GotoIf("SIP/rtel4_out-0000096f", "0?docfu:skip3") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Goto (macro-dial-one,s,16)
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [s@macro-dial-one:16] GotoIf("SIP/rtel4_out-0000096f", "1?next2:continue") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Goto (macro-dial-one,s,17)
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [s@macro-dial-one:17] GotoIf("SIP/rtel4_out-0000096f", "1?continue") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Goto (macro-dial-one,s,25)
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [s@macro-dial-one:25] GotoIf("SIP/rtel4_out-0000096f", "0?nodial") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [s@macro-dial-one:26] GosubIf("SIP/rtel4_out-0000096f", "1?dstring,1:dlocal,1") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [dstring@macro-dial-one:1] Set("SIP/rtel4_out-0000096f", "DSTRING=") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [dstring@macro-dial-one:2] Set("SIP/rtel4_out-0000096f", "DEVICES=149") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [dstring@macro-dial-one:3] ExecIf("SIP/rtel4_out-0000096f", "0?Return()") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [dstring@macro-dial-one:4] ExecIf("SIP/rtel4_out-0000096f", "0?Set(DEVICES=49)") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [dstring@macro-dial-one:5] Set("SIP/rtel4_out-0000096f", "LOOPCNT=1") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [dstring@macro-dial-one:6] Set("SIP/rtel4_out-0000096f", "ITER=1") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [dstring@macro-dial-one:7] Set("SIP/rtel4_out-0000096f", "THISDIAL=SIP/149") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [dstring@macro-dial-one:8] GosubIf("SIP/rtel4_out-0000096f", "1?zap2dahdi,1") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [zap2dahdi@macro-dial-one:1] ExecIf("SIP/rtel4_out-0000096f", "0?Return()") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [zap2dahdi@macro-dial-one:2] Set("SIP/rtel4_out-0000096f", "NEWDIAL=") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [zap2dahdi@macro-dial-one:3] Set("SIP/rtel4_out-0000096f", "LOOPCNT2=1") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [zap2dahdi@macro-dial-one:4] Set("SIP/rtel4_out-0000096f", "ITER2=1") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [zap2dahdi@macro-dial-one:5] Set("SIP/rtel4_out-0000096f", "THISPART2=SIP/149") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [zap2dahdi@macro-dial-one:6] ExecIf("SIP/rtel4_out-0000096f", "0?Set(THISPART2=DAHDI/149)") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [zap2dahdi@macro-dial-one:7] Set("SIP/rtel4_out-0000096f", "NEWDIAL=SIP/149&") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [zap2dahdi@macro-dial-one:8] Set("SIP/rtel4_out-0000096f", "ITER2=2") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [zap2dahdi@macro-dial-one:9] GotoIf("SIP/rtel4_out-0000096f", "0?begin2") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [zap2dahdi@macro-dial-one:10] Set("SIP/rtel4_out-0000096f", "THISDIAL=SIP/149") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [zap2dahdi@macro-dial-one:11] Return("SIP/rtel4_out-0000096f", "") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [dstring@macro-dial-one:9] Set("SIP/rtel4_out-0000096f", "DSTRING=SIP/149&") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [dstring@macro-dial-one:10] Set("SIP/rtel4_out-0000096f", "ITER=2") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [dstring@macro-dial-one:11] GotoIf("SIP/rtel4_out-0000096f", "0?begin") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [dstring@macro-dial-one:12] Set("SIP/rtel4_out-0000096f", "DSTRING=SIP/149") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [dstring@macro-dial-one:13] Return("SIP/rtel4_out-0000096f", "") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [s@macro-dial-one:27] GotoIf("SIP/rtel4_out-0000096f", "0?nodial") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [s@macro-dial-one:28] GotoIf("SIP/rtel4_out-0000096f", "1?skiptrace") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Goto (macro-dial-one,s,30)
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [s@macro-dial-one:30] Set("SIP/rtel4_out-0000096f", "D_OPTIONS=Ttr") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [s@macro-dial-one:31] ExecIf("SIP/rtel4_out-0000096f", "0?SIPAddHeader(Alert-Info: )") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [s@macro-dial-one:32] ExecIf("SIP/rtel4_out-0000096f", "0?SIPAddHeader()") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [s@macro-dial-one:33] ExecIf("SIP/rtel4_out-0000096f", "0?Set(CHANNEL(musicclass)=)") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [s@macro-dial-one:34] GosubIf("SIP/rtel4_out-0000096f", "0?qwait,1") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [s@macro-dial-one:35] Set("SIP/rtel4_out-0000096f", "__CWIGNORE=") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [s@macro-dial-one:36] Set("SIP/rtel4_out-0000096f", "__KEEPCID=TRUE") in new stack
Apr 16 16:15:33	VERBOSE	[25430] pbx.c:	
-- Executing [s@macro-dial-one:37] Dial("SIP/rtel4_out-0000096f", "SIP/149,"",Ttr") in new stack
Apr 16 16:15:33	VERBOSE	[25430] netsock2.c:	
== Using SIP RTP TOS bits 224
Apr 16 16:15:33	VERBOSE	[25430] netsock2.c:	
== Using SIP RTP CoS mark 5
Apr 16 16:15:33	VERBOSE	[25430] app_dial.c:	
-- Called SIP/149
Apr 16 16:15:33	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state Ringing for Notify User 106 
Apr 16 16:15:33	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state Ringing for Notify User 151 
Apr 16 16:15:33	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state Ringing for Notify User 115 
Apr 16 16:15:33	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state Ringing for Notify User 108 
Apr 16 16:15:33	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state Ringing for Notify User 110 
Apr 16 16:15:33	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state Ringing for Notify User 141 
Apr 16 16:15:33	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state Ringing for Notify User 138 
Apr 16 16:15:33	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state Ringing for Notify User 131 
Apr 16 16:15:33	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state Ringing for Notify User 104 
Apr 16 16:15:33	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state Ringing for Notify User 102 
Apr 16 16:15:33	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state Ringing for Notify User 113 
Apr 16 16:15:33	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state Ringing for Notify User 142 
Apr 16 16:15:33	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state Ringing for Notify User 159 
Apr 16 16:15:33	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state Ringing for Notify User 123 
Apr 16 16:15:33	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state Ringing for Notify User 154 
Apr 16 16:15:33	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state Ringing for Notify User 112 
Apr 16 16:15:33	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state Ringing for Notify User 148 
Apr 16 16:15:33	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state Ringing for Notify User 127 
Apr 16 16:15:33	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state Ringing for Notify User 143 
Apr 16 16:15:33	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state Ringing for Notify User 153 
Apr 16 16:15:33	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state Ringing for Notify User 121 
Apr 16 16:15:33	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state Ringing for Notify User 124 
Apr 16 16:15:33	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state Ringing for Notify User 130 
Apr 16 16:15:33	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state Ringing for Notify User 100 
Apr 16 16:15:33	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state Ringing for Notify User 149 
Apr 16 16:15:33	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state Ringing for Notify User 101 
Apr 16 16:15:33	VERBOSE	[25431] app_mixmonitor.c:	
== Begin MixMonitor Recording SIP/rtel4_out-0000096f
Apr 16 16:15:33	VERBOSE	[25430] app_dial.c:	
-- SIP/149-00000970 is ringing
Apr 16 16:15:36	VERBOSE	[25430] app_dial.c:	
-- SIP/149-00000970 answered SIP/rtel4_out-0000096f
Apr 16 16:15:36	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state InUse for Notify User 106 
Apr 16 16:15:36	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state InUse for Notify User 151 
Apr 16 16:15:36	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state InUse for Notify User 115 
Apr 16 16:15:36	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state InUse for Notify User 108 
Apr 16 16:15:36	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state InUse for Notify User 110 
Apr 16 16:15:36	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state InUse for Notify User 141 
Apr 16 16:15:36	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state InUse for Notify User 138 
Apr 16 16:15:36	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state InUse for Notify User 131 
Apr 16 16:15:36	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state InUse for Notify User 104 
Apr 16 16:15:36	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state InUse for Notify User 102 
Apr 16 16:15:36	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state InUse for Notify User 113 
Apr 16 16:15:36	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state InUse for Notify User 142 
Apr 16 16:15:36	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state InUse for Notify User 159 
Apr 16 16:15:36	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state InUse for Notify User 123 
Apr 16 16:15:36	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state InUse for Notify User 154 
Apr 16 16:15:36	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state InUse for Notify User 112 
Apr 16 16:15:36	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state InUse for Notify User 148 
Apr 16 16:15:36	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state InUse for Notify User 127 
Apr 16 16:15:36	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state InUse for Notify User 143 
Apr 16 16:15:36	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state InUse for Notify User 153 
Apr 16 16:15:36	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state InUse for Notify User 121 
Apr 16 16:15:36	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state InUse for Notify User 124 
Apr 16 16:15:36	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state InUse for Notify User 130 
Apr 16 16:15:36	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state InUse for Notify User 100 
Apr 16 16:15:36	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state InUse for Notify User 149 
Apr 16 16:15:36	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state InUse for Notify User 101 
Apr 16 16:15:36	VERBOSE	[25430] abstract_jb.c:	
-- fixed jitterbuffer created on channel SIP/149-00000970
Apr 16 16:15:37	VERBOSE	[25430] abstract_jb.c:	
-- fixed jitterbuffer created on channel SIP/rtel4_out-0000096f
Apr 16 16:15:50	VERBOSE	[25430] pbx.c:	
-- Executing [h@macro-dial-one:1] Macro("SIP/rtel4_out-0000096f", "hangupcall,") in new stack
Apr 16 16:15:50	VERBOSE	[25430] pbx.c:	
-- Executing [s@macro-hangupcall:1] GotoIf("SIP/rtel4_out-0000096f", "0?endmixmoncheck") in new stack
Apr 16 16:15:50	VERBOSE	[25430] pbx.c:	
-- Executing [s@macro-hangupcall:2] Set("SIP/rtel4_out-0000096f", "MIXMON_CALLFILENAME=/var/spool/asterisk/monitor/20120416-161533-1334578524.2698.WAV") in new stack
Apr 16 16:15:50	VERBOSE	[25430] pbx.c:	
-- Executing [s@macro-hangupcall:3] GotoIf("SIP/rtel4_out-0000096f", "1?defaultmixmondir") in new stack
Apr 16 16:15:50	VERBOSE	[25430] pbx.c:	
-- Goto (macro-hangupcall,s,5)
Apr 16 16:15:50	VERBOSE	[25430] pbx.c:	
-- Executing [s@macro-hangupcall:5] System("SIP/rtel4_out-0000096f", "test -e /var/spool/asterisk/monitor/20120416-161533-1334578524.2698.WAV") in new stack
Apr 16 16:15:50	VERBOSE	[25430] pbx.c:	
-- Executing [s@macro-hangupcall:6] NoOp("SIP/rtel4_out-0000096f", "SYSTEMSTATUS = SUCCESS") in new stack
Apr 16 16:15:50	VERBOSE	[25430] pbx.c:	
-- Executing [s@macro-hangupcall:7] GotoIf("SIP/rtel4_out-0000096f", "1?endmixmoncheck") in new stack
Apr 16 16:15:50	VERBOSE	[25430] pbx.c:	
-- Goto (macro-hangupcall,s,9)
Apr 16 16:15:50	VERBOSE	[25430] pbx.c:	
-- Executing [s@macro-hangupcall:9] NoOp("SIP/rtel4_out-0000096f", "End of MIXMON check") in new stack
Apr 16 16:15:50	VERBOSE	[25430] pbx.c:	
-- Executing [s@macro-hangupcall:10] GotoIf("SIP/rtel4_out-0000096f", "1?nomeetmemon") in new stack
Apr 16 16:15:50	VERBOSE	[25430] pbx.c:	
-- Goto (macro-hangupcall,s,15)
Apr 16 16:15:50	VERBOSE	[25430] pbx.c:	
-- Executing [s@macro-hangupcall:15] NoOp("SIP/rtel4_out-0000096f", "MEETME_RECORDINGFILE=") in new stack
Apr 16 16:15:50	VERBOSE	[25430] pbx.c:	
-- Executing [s@macro-hangupcall:16] GotoIf("SIP/rtel4_out-0000096f", "1?noautomon") in new stack
Apr 16 16:15:50	VERBOSE	[25430] pbx.c:	
-- Goto (macro-hangupcall,s,18)
Apr 16 16:15:50	VERBOSE	[25430] pbx.c:	
-- Executing [s@macro-hangupcall:18] NoOp("SIP/rtel4_out-0000096f", "TOUCH_MONITOR_OUTPUT=") in new stack
Apr 16 16:15:50	VERBOSE	[25430] pbx.c:	
-- Executing [s@macro-hangupcall:19] GotoIf("SIP/rtel4_out-0000096f", "1?noautomon2") in new stack
Apr 16 16:15:50	VERBOSE	[25430] pbx.c:	
-- Goto (macro-hangupcall,s,25)
Apr 16 16:15:50	VERBOSE	[25430] pbx.c:	
-- Executing [s@macro-hangupcall:25] NoOp("SIP/rtel4_out-0000096f", "MONITOR_FILENAME=") in new stack
Apr 16 16:15:50	VERBOSE	[25430] pbx.c:	
-- Executing [s@macro-hangupcall:26] GotoIf("SIP/rtel4_out-0000096f", "1?skiprg") in new stack
Apr 16 16:15:50	VERBOSE	[25430] pbx.c:	
-- Goto (macro-hangupcall,s,29)
Apr 16 16:15:50	VERBOSE	[25430] pbx.c:	
-- Executing [s@macro-hangupcall:29] GotoIf("SIP/rtel4_out-0000096f", "1?skipblkvm") in new stack
Apr 16 16:15:50	VERBOSE	[25430] pbx.c:	
-- Goto (macro-hangupcall,s,32)
Apr 16 16:15:50	VERBOSE	[25430] pbx.c:	
-- Executing [s@macro-hangupcall:32] GotoIf("SIP/rtel4_out-0000096f", "1?theend") in new stack
Apr 16 16:15:50	VERBOSE	[25430] pbx.c:	
-- Goto (macro-hangupcall,s,34)
Apr 16 16:15:50	VERBOSE	[25430] pbx.c:	
-- Executing [s@macro-hangupcall:34] Hangup("SIP/rtel4_out-0000096f", "") in new stack
Apr 16 16:15:50	VERBOSE	[25430] app_macro.c:	
== Spawn extension (macro-hangupcall, s, 34) exited non-zero on 'SIP/rtel4_out-0000096f' in macro 'hangupcall'
Apr 16 16:15:50	VERBOSE	[25430] features.c:	
== Spawn extension (macro-dial-one, h, 1) exited non-zero on 'SIP/rtel4_out-0000096f'
Apr 16 16:15:50	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state Idle for Notify User 106 
Apr 16 16:15:50	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state Idle for Notify User 151 
Apr 16 16:15:50	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state Idle for Notify User 115 
Apr 16 16:15:50	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state Idle for Notify User 108 
Apr 16 16:15:50	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state Idle for Notify User 110 
Apr 16 16:15:50	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state Idle for Notify User 141 
Apr 16 16:15:50	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state Idle for Notify User 138 
Apr 16 16:15:50	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state Idle for Notify User 131 
Apr 16 16:15:50	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state Idle for Notify User 104 
Apr 16 16:15:50	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state Idle for Notify User 102 
Apr 16 16:15:50	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state Idle for Notify User 113 
Apr 16 16:15:50	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state Idle for Notify User 142 
Apr 16 16:15:50	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state Idle for Notify User 159 
Apr 16 16:15:50	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state Idle for Notify User 123 
Apr 16 16:15:50	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state Idle for Notify User 154 
Apr 16 16:15:50	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state Idle for Notify User 112 
Apr 16 16:15:50	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state Idle for Notify User 148 
Apr 16 16:15:50	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state Idle for Notify User 127 
Apr 16 16:15:50	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state Idle for Notify User 143 
Apr 16 16:15:50	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state Idle for Notify User 153 
Apr 16 16:15:50	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state Idle for Notify User 121 
Apr 16 16:15:50	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state Idle for Notify User 124 
Apr 16 16:15:50	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state Idle for Notify User 130 
Apr 16 16:15:50	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state Idle for Notify User 100 
Apr 16 16:15:50	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state Idle for Notify User 149 
Apr 16 16:15:50	VERBOSE	[3066] chan_sip.c:	
== Extension Changed 149[ext-local] new state Idle for Notify User 101 
Apr 16 16:15:50	VERBOSE	[25430] abstract_jb.c:	
-- fixed jitterbuffer destroyed on channel SIP/149-00000970
Apr 16 16:15:50	VERBOSE	[25430] app_macro.c:	
== Spawn extension (macro-dial-one, s, 37) exited non-zero on 'SIP/rtel4_out-0000096f' in macro 'dial-one'
Apr 16 16:15:50	VERBOSE	[25430] app_macro.c:	
== Spawn extension (macro-exten-vm, s, 9) exited non-zero on 'SIP/rtel4_out-0000096f' in macro 'exten-vm'
Apr 16 16:15:50	VERBOSE	[25430] pbx.c:	
== Spawn extension (from-did-direct, 149, 1) exited non-zero on 'SIP/rtel4_out-0000096f'
Apr 16 16:15:50	VERBOSE	[25431] app_mixmonitor.c:	
== MixMonitor close filestream
Apr 16 16:15:50	VERBOSE	[25431] app_mixmonitor.c:	
== End MixMonitor Recording SIP/rtel4_out-0000096f
Apr 16 16:15:50	VERBOSE	[25430] abstract_jb.c:	
-- fixed jitterbuffer destroyed on channel SIP/rtel4_out-0000096f

[JackB]

Выявил, что проблема возникает только при входящем звонке через основного sip провайдера. Через мультифон все ок. Настройки транков не различаются ничем кроме callbackextension. Может есть какие-то предположения?

[Vlad1983]

сменить администратора атс.