VIDEOCHAT  ::   FAQ  ::   Поиск  ::   Регистрация  ::   Вход

freepbx distro, таймаут исх. звонка

Обо всем касательно FreePBX, MetPBX, TrixBox, Elastix, AstPBX и всех других дистрибутивов

Модераторы: april22, Zavr2008

Ответить
aka_daemon
Сообщения: 5
Зарегистрирован: 28 май 2015, 12:27

freepbx distro, таймаут исх. звонка

Сообщение aka_daemon »

Доброго дня.
Установлен freepbx distro stable-6.12.65 Release Date-2015 FreePBX 12, Linux 6.5 Asterisk 11, настроен транк на sipnet, добавлены внутренние номера и входящие/исходящие маршруты.
Подскажите как увеличить время дозвона до внешнего абонента, к примеру на сот. телефон, в данный момент время 30 сек. и звонок сбрасывается. Покопался в настройках, нашёл параметр trunk_ring_timer, более подходящий по описанию к моей проблеме, но он выставлен в 300 сек. Подскажите в каком направлении искать причину?

Прилагаю лог при исходящем звонке:

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

[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [79209191078@from-internal:1] Macro("SIP/222-00000000", "user-callerid,LIMIT,EXTERNAL,") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@macro-user-callerid:1] Set("SIP/222-00000000", "TOUCH_MONITOR=1433419174.0") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@macro-user-callerid:2] Set("SIP/222-00000000", "AMPUSER=222") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@macro-user-callerid:3] GotoIf("SIP/222-00000000", "0?report") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@macro-user-callerid:4] ExecIf("SIP/222-00000000", "1?Set(REALCALLERIDNUM=222)") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@macro-user-callerid:5] Set("SIP/222-00000000", "AMPUSER=222") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@macro-user-callerid:6] GotoIf("SIP/222-00000000", "0?limit") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@macro-user-callerid:7] Set("SIP/222-00000000", "AMPUSERCIDNAME=222") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@macro-user-callerid:8] GotoIf("SIP/222-00000000", "0?report") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@macro-user-callerid:9] Set("SIP/222-00000000", "AMPUSERCID=222") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@macro-user-callerid:10] Set("SIP/222-00000000", "__DIAL_OPTIONS=Ttr") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@macro-user-callerid:11] Set("SIP/222-00000000", "CALLERID(all)="222" <222>") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@macro-user-callerid:12] GotoIf("SIP/222-00000000", "0?limit") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@macro-user-callerid:13] ExecIf("SIP/222-00000000", "1?Set(GROUP(concurrency_limit)=222)") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@macro-user-callerid:14] GosubIf("SIP/222-00000000", "7?sub-ccss,s,1(from-internal,79209191078)") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@sub-ccss:1] ExecIf("SIP/222-00000000", "0?Return()") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@sub-ccss:2] Set("SIP/222-00000000", "CCSS_SETUP=TRUE") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@sub-ccss:3] GosubIf("SIP/222-00000000", "0?monitor_config,1(from-internal,79209191078):monitor_default,1(from-internal,79209191078)") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [monitor_default@sub-ccss:1] GotoIf("SIP/222-00000000", "0?is_exten") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [monitor_default@sub-ccss:2] StackPop("SIP/222-00000000", "") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [monitor_default@sub-ccss:3] Return("SIP/222-00000000", "FALSE") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@macro-user-callerid:15] ExecIf("SIP/222-00000000", "1?Set(CHANNEL(language)=ru)") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@macro-user-callerid:16] GotoIf("SIP/222-00000000", "1?continue") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Goto (macro-user-callerid,s,30)
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@macro-user-callerid:30] Set("SIP/222-00000000", "CALLERID(number)=222") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@macro-user-callerid:31] Set("SIP/222-00000000", "CALLERID(name)=222") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@macro-user-callerid:32] Set("SIP/222-00000000", "CDR(cnum)=222") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@macro-user-callerid:33] Set("SIP/222-00000000", "CDR(cnam)=222") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@macro-user-callerid:34] Set("SIP/222-00000000", "CHANNEL(language)=ru") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [79209191078@from-internal:2] Gosub("SIP/222-00000000", "sub-record-check,s,1(out,79209191078,dontcare)") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@sub-record-check:1] GotoIf("SIP/222-00000000", "0?initialized") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@sub-record-check:2] Set("SIP/222-00000000", "__REC_STATUS=INITIALIZED") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@sub-record-check:3] Set("SIP/222-00000000", "NOW=1433419174") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@sub-record-check:4] Set("SIP/222-00000000", "__DAY=04") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@sub-record-check:5] Set("SIP/222-00000000", "__MONTH=06") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@sub-record-check:6] Set("SIP/222-00000000", "__YEAR=2015") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@sub-record-check:7] Set("SIP/222-00000000", "__TIMESTR=20150604-145934") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@sub-record-check:8] Set("SIP/222-00000000", "__FROMEXTEN=222") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@sub-record-check:9] Set("SIP/222-00000000", "__MON_FMT=wav") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@sub-record-check:10] NoOp("SIP/222-00000000", "Recordings initialized") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@sub-record-check:11] ExecIf("SIP/222-00000000", "0?Set(ARG3=dontcare)") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@sub-record-check:12] Set("SIP/222-00000000", "REC_POLICY_MODE_SAVE=") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@sub-record-check:13] ExecIf("SIP/222-00000000", "0?Set(REC_STATUS=NO)") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@sub-record-check:14] GotoIf("SIP/222-00000000", "3?checkaction") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Goto (sub-record-check,s,17)
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@sub-record-check:17] GotoIf("SIP/222-00000000", "1?sub-record-check,out,1") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Goto (sub-record-check,out,1)
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [out@sub-record-check:1] NoOp("SIP/222-00000000", "Outbound Recording Check from 222 to 79209191078") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [out@sub-record-check:2] Set("SIP/222-00000000", "RECMODE=yes") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [out@sub-record-check:3] ExecIf("SIP/222-00000000", "0?Goto(routewins)") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [out@sub-record-check:4] ExecIf("SIP/222-00000000", "0?Goto(routewins)") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [out@sub-record-check:5] Gosub("SIP/222-00000000", "recordcheck,1(yes,out,79209191078)") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [recordcheck@sub-record-check:1] NoOp("SIP/222-00000000", "Starting recording check against yes") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [recordcheck@sub-record-check:2] Goto("SIP/222-00000000", "yes") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Goto (sub-record-check,recordcheck,9)
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [recordcheck@sub-record-check:9] ExecIf("SIP/222-00000000", "0?Return()") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [recordcheck@sub-record-check:10] Set("SIP/222-00000000", "__REC_POLICY_MODE=YES") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [recordcheck@sub-record-check:11] Goto("SIP/222-00000000", "startrec") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Goto (sub-record-check,recordcheck,16)
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [recordcheck@sub-record-check:16] NoOp("SIP/222-00000000", "Starting recording: out, 79209191078") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [recordcheck@sub-record-check:17] Set("SIP/222-00000000", "AUDIOHOOK_INHERIT(MixMonitor)=yes") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [recordcheck@sub-record-check:18] Set("SIP/222-00000000", "__CALLFILENAME=out-79209191078-222-20150604-145934-1433419174.0") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [recordcheck@sub-record-check:19] MixMonitor("SIP/222-00000000", "2015/06/04/out-79209191078-222-20150604-145934-1433419174.0.wav,ai(LOCAL_MIXMON_ID),") in new stack
[2015-06-04 14:59:34] VERBOSE[2401][C-00000000] app_mixmonitor.c: == Begin MixMonitor Recording SIP/222-00000000
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [recordcheck@sub-record-check:20] Set("SIP/222-00000000", "__MIXMON_ID=0xb7008830") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [recordcheck@sub-record-check:21] Set("SIP/222-00000000", "__RECORD_ID=SIP/222-00000000") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [recordcheck@sub-record-check:22] Set("SIP/222-00000000", "__REC_STATUS=RECORDING") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [recordcheck@sub-record-check:23] Set("SIP/222-00000000", "CDR(recordingfile)=out-79209191078-222-20150604-145934-1433419174.0.wav") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [recordcheck@sub-record-check:24] Return("SIP/222-00000000", "") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [out@sub-record-check:6] Return("SIP/222-00000000", "") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [79209191078@from-internal:3] ExecIf("SIP/222-00000000", "0 ?Set(CDR(accountcode)=)") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [79209191078@from-internal:4] Set("SIP/222-00000000", "MOHCLASS=default") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [79209191078@from-internal:5] Set("SIP/222-00000000", "_NODEST=") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [79209191078@from-internal:6] Macro("SIP/222-00000000", "dialout-trunk,2,79209191078,,off") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@macro-dialout-trunk:1] Set("SIP/222-00000000", "DIAL_TRUNK=2") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@macro-dialout-trunk:2] GosubIf("SIP/222-00000000", "0?sub-pincheck,s,1()") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@macro-dialout-trunk:3] GotoIf("SIP/222-00000000", "0?disabletrunk,1") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@macro-dialout-trunk:4] Set("SIP/222-00000000", "DIAL_NUMBER=79209191078") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@macro-dialout-trunk:5] Set("SIP/222-00000000", "DIAL_TRUNK_OPTIONS=Ttr") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@macro-dialout-trunk:6] Set("SIP/222-00000000", "OUTBOUND_GROUP=OUT_2") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@macro-dialout-trunk:7] GotoIf("SIP/222-00000000", "1?nomax") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Goto (macro-dialout-trunk,s,9)
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@macro-dialout-trunk:9] GotoIf("SIP/222-00000000", "0?skipoutcid") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@macro-dialout-trunk:10] Set("SIP/222-00000000", "DIAL_TRUNK_OPTIONS=Tt") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@macro-dialout-trunk:11] Macro("SIP/222-00000000", "outbound-callerid,2") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@macro-outbound-callerid:1] ExecIf("SIP/222-00000000", "0?Set(CALLERPRES()=)") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@macro-outbound-callerid:2] ExecIf("SIP/222-00000000", "0?Set(REALCALLERIDNUM=222)") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@macro-outbound-callerid:3] GotoIf("SIP/222-00000000", "1?normcid") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Goto (macro-outbound-callerid,s,6)
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@macro-outbound-callerid:6] Set("SIP/222-00000000", "USEROUTCID=") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@macro-outbound-callerid:7] Set("SIP/222-00000000", "EMERGENCYCID=") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@macro-outbound-callerid:8] Set("SIP/222-00000000", "TRUNKOUTCID=0042070919") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@macro-outbound-callerid:9] GotoIf("SIP/222-00000000", "1?trunkcid") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Goto (macro-outbound-callerid,s,14)
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@macro-outbound-callerid:14] ExecIf("SIP/222-00000000", "1?Set(CALLERID(all)=0042070919)") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@macro-outbound-callerid:15] ExecIf("SIP/222-00000000", "0?Set(CALLERID(all)=)") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@macro-outbound-callerid:16] ExecIf("SIP/222-00000000", "0?Set(CALLERID(all)=)") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@macro-outbound-callerid:17] ExecIf("SIP/222-00000000", "0?Set(CALLERPRES()=prohib_passed_screen)") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@macro-outbound-callerid:18] Set("SIP/222-00000000", "CDR(outbound_cnum)=0042070919") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@macro-outbound-callerid:19] Set("SIP/222-00000000", "CDR(outbound_cnam)=") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@macro-dialout-trunk:12] GosubIf("SIP/222-00000000", "0?sub-flp-2,s,1()") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@macro-dialout-trunk:13] Set("SIP/222-00000000", "OUTNUM=79209191078") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@macro-dialout-trunk:14] Set("SIP/222-00000000", "custom=SIP/sipnet") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@macro-dialout-trunk:15] ExecIf("SIP/222-00000000", "0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)Tt)") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@macro-dialout-trunk:16] ExecIf("SIP/222-00000000", "0?Set(DIAL_TRUNK_OPTIONS=TtM(confirm))") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@macro-dialout-trunk:17] Macro("SIP/222-00000000", "dialout-trunk-predial-hook,") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("SIP/222-00000000", "") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@macro-dialout-trunk:18] GotoIf("SIP/222-00000000", "0?bypass,1") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@macro-dialout-trunk:19] ExecIf("SIP/222-00000000", "1?Set(CONNECTEDLINE(num,i)=79209191078)") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@macro-dialout-trunk:20] ExecIf("SIP/222-00000000", "1?Set(CONNECTEDLINE(name,i)=CID:0042070919)") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@macro-dialout-trunk:21] GotoIf("SIP/222-00000000", "0?customtrunk") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@macro-dialout-trunk:22] Dial("SIP/222-00000000", "SIP/sipnet/79209191078,300,Tt") in new stack
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] netsock2.c: == Using SIP RTP TOS bits 184
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] netsock2.c: == Using SIP RTP CoS mark 5
[2015-06-04 14:59:34] VERBOSE[2400][C-00000000] app_dial.c: -- Called SIP/sipnet/79209191078
[2015-06-04 14:59:38] VERBOSE[2400][C-00000000] app_dial.c: -- SIP/sipnet-00000001 is making progress passing it to SIP/222-00000000
[2015-06-04 15:00:06] VERBOSE[2400][C-00000000] app_dial.c: -- No one is available to answer at this time (1:0/0/0)
[2015-06-04 15:00:06] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@macro-dialout-trunk:23] NoOp("SIP/222-00000000", "Dial failed for some reason with DIALSTATUS = NOANSWER and HANGUPCAUSE = 16") in new stack
[2015-06-04 15:00:06] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s@macro-dialout-trunk:24] GotoIf("SIP/222-00000000", "0?continue,1:s-NOANSWER,1") in new stack
[2015-06-04 15:00:06] VERBOSE[2400][C-00000000] pbx.c: -- Goto (macro-dialout-trunk,s-NOANSWER,1)
[2015-06-04 15:00:06] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s-NOANSWER@macro-dialout-trunk:1] NoOp("SIP/222-00000000", "Dial failed due to trunk reporting NOANSWER - giving up") in new stack
[2015-06-04 15:00:06] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s-NOANSWER@macro-dialout-trunk:2] Progress("SIP/222-00000000", "") in new stack
[2015-06-04 15:00:06] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s-NOANSWER@macro-dialout-trunk:3] Playback("SIP/222-00000000", "number-not-answering,noanswer") in new stack
[2015-06-04 15:00:06] VERBOSE[2400][C-00000000] file.c: -- <SIP/222-00000000> Playing 'number-not-answering.ulaw' (language 'ru')
[2015-06-04 15:00:08] VERBOSE[2400][C-00000000] pbx.c: -- Executing [s-NOANSWER@macro-dialout-trunk:4] Congestion("SIP/222-00000000", "20") in new stack
[2015-06-04 15:00:08] WARNING[2400][C-00000000] channel.c: Prodding channel 'SIP/222-00000000' failed
[2015-06-04 15:00:08] VERBOSE[2400][C-00000000] app_macro.c: == Spawn extension (macro-dialout-trunk, s-NOANSWER, 4) exited non-zero on 'SIP/222-00000000' in macro 'dialout-trunk'
[2015-06-04 15:00:08] VERBOSE[2400][C-00000000] pbx.c: == Spawn extension (from-internal, 79209191078, 6) exited non-zero on 'SIP/222-00000000'
[2015-06-04 15:00:08] VERBOSE[2400][C-00000000] pbx.c: -- Executing [h@from-internal:1] Hangup("SIP/222-00000000", "") in new stack
[2015-06-04 15:00:08] VERBOSE[2400][C-00000000] pbx.c: == Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/222-00000000'
[2015-06-04 15:00:08] VERBOSE[2401][C-00000000] app_mixmonitor.c: == MixMonitor close filestream (mixed)
[2015-06-04 15:00:08] VERBOSE[2401][C-00000000] app_mixmonitor.c: == End MixMonitor Recording SIP/222-00000000
еще отладка командой sip set debug peer 222(внутренний номер с кот. звонил на сот. телефон)

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

SIP/2.0 183 Session Progress
Via: SIP/2.0/UDP 192.168.0.15:55804;branch=z9hG4bK-d8754z-5b148564045fe924-1---d8754z-;received=192.168.0.15
From: <sip:222@192.168.0.10;transport=UDP>;tag=bd15786c
To: <sip:79209191078@192.168.0.10;transport=UDP>;tag=as43f8090f
Call-ID: MGNkY2QyNDBhM2UwNDhiODY2Y2U3YzIzMzYzZGMwMTQ.
CSeq: 2 INVITE
Server: FPBX-12.0.65(11.17.1)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Session-Expires: 1800;refresher=uas
Contact: <sip:79209191078@192.168.0.10:5060>
Content-Type: application/sdp
Require: timer
Content-Length: 283

v=0
o=root 2006663916 2006663916 IN IP4 192.168.0.10
s=Asterisk PBX 11.17.1
c=IN IP4 192.168.0.10
t=0 0
m=audio 12046 RTP/AVP 0 8 3 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:3 GSM/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=sendrecv

<------------>
[2015-06-04 15:06:04] VERBOSE[2607][C-00000001] app_dial.c: -- No one is available to answer at this time (1:0/0/0)
[2015-06-04 15:06:04] VERBOSE[2607][C-00000001] pbx.c: -- Executing [s@macro-dialout-trunk:23] NoOp("SIP/222-00000002", "Dial failed for some reason with DIALSTATUS = NOANSWER and HANGUPCAUSE = 16") in new stack
[2015-06-04 15:06:04] VERBOSE[2607][C-00000001] pbx.c: -- Executing [s@macro-dialout-trunk:24] GotoIf("SIP/222-00000002", "0?continue,1:s-NOANSWER,1") in new stack
[2015-06-04 15:06:04] VERBOSE[2607][C-00000001] pbx.c: -- Goto (macro-dialout-trunk,s-NOANSWER,1)
[2015-06-04 15:06:04] VERBOSE[2607][C-00000001] pbx.c: -- Executing [s-NOANSWER@macro-dialout-trunk:1] NoOp("SIP/222-00000002", "Dial failed due to trunk reporting NOANSWER - giving up") in new stack
[2015-06-04 15:06:04] VERBOSE[2607][C-00000001] pbx.c: -- Executing [s-NOANSWER@macro-dialout-trunk:2] Progress("SIP/222-00000002", "") in new stack
[2015-06-04 15:06:04] VERBOSE[2607][C-00000001] pbx.c: -- Executing [s-NOANSWER@macro-dialout-trunk:3] Playback("SIP/222-00000002", "number-not-answering,noanswer") in new stack
[2015-06-04 15:06:04] VERBOSE[2607][C-00000001] file.c: -- <SIP/222-00000002> Playing 'number-not-answering.ulaw' (language 'ru')
[2015-06-04 15:06:05] VERBOSE[2607][C-00000001] pbx.c: -- Executing [s-NOANSWER@macro-dialout-trunk:4] Congestion("SIP/222-00000002", "20") in new stack
[2015-06-04 15:06:05] VERBOSE[2607][C-00000001] chan_sip.c:
<--- Reliably Transmitting (no NAT) to 192.168.0.15:55804 --->
SIP/2.0 503 Service Unavailable
Via: SIP/2.0/UDP 192.168.0.15:55804;branch=z9hG4bK-d8754z-5b148564045fe924-1---d8754z-;received=192.168.0.15
From: <sip:222@192.168.0.10;transport=UDP>;tag=bd15786c
To: <sip:79209191078@192.168.0.10;transport=UDP>;tag=as43f8090f
Call-ID: MGNkY2QyNDBhM2UwNDhiODY2Y2U3YzIzMzYzZGMwMTQ.
CSeq: 2 INVITE
Server: FPBX-12.0.65(11.17.1)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Session-Expires: 1800;refresher=uas
X-Asterisk-HangupCause: Normal Clearing
X-Asterisk-HangupCauseCode: 16
Content-Length: 0


<------------>
[2015-06-04 15:06:05] WARNING[2607][C-00000001] channel.c: Prodding channel 'SIP/222-00000002' failed
[2015-06-04 15:06:05] VERBOSE[2607][C-00000001] app_macro.c: == Spawn extension (macro-dialout-trunk, s-NOANSWER, 4) exited non-zero on 'SIP/222-00000002' in macro 'dialout-trunk'
[2015-06-04 15:06:05] VERBOSE[2607][C-00000001] pbx.c: == Spawn extension (from-internal, 79209191078, 6) exited non-zero on 'SIP/222-00000002'
[2015-06-04 15:06:05] VERBOSE[2607][C-00000001] pbx.c: -- Executing [h@from-internal:1] Hangup("SIP/222-00000002", "") in new stack
[2015-06-04 15:06:05] VERBOSE[2607][C-00000001] pbx.c: == Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/222-00000002'
[2015-06-04 15:06:05] VERBOSE[2608][C-00000001] app_mixmonitor.c: == MixMonitor close filestream (mixed)
[2015-06-04 15:06:05] VERBOSE[2608][C-00000001] app_mixmonitor.c: == End MixMonitor Recording SIP/222-00000002
[2015-06-04 15:06:05] VERBOSE[1787] chan_sip.c: Retransmitting #1 (no NAT) to 192.168.0.15:55804:
SIP/2.0 503 Service Unavailable
Via: SIP/2.0/UDP 192.168.0.15:55804;branch=z9hG4bK-d8754z-5b148564045fe924-1---d8754z-;received=192.168.0.15
From: <sip:222@192.168.0.10;transport=UDP>;tag=bd15786c
To: <sip:79209191078@192.168.0.10;transport=UDP>;tag=as43f8090f
Call-ID: MGNkY2QyNDBhM2UwNDhiODY2Y2U3YzIzMzYzZGMwMTQ.
CSeq: 2 INVITE
Server: FPBX-12.0.65(11.17.1)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Session-Expires: 1800;refresher=uas
X-Asterisk-HangupCause: Normal Clearing
X-Asterisk-HangupCauseCode: 16
Content-Length: 0


---
[2015-06-04 15:06:05] VERBOSE[1787] chan_sip.c:
<--- SIP read from UDP:192.168.0.15:55804 --->
ACK sip:79209191078@192.168.0.10;transport=UDP SIP/2.0
Via: SIP/2.0/UDP 192.168.0.15:55804;branch=z9hG4bK-d8754z-5b148564045fe924-1---d8754z-
Max-Forwards: 70
To: <sip:79209191078@192.168.0.10;transport=UDP>;tag=as43f8090f
From: <sip:222@192.168.0.10;transport=UDP>;tag=bd15786c
Call-ID: MGNkY2QyNDBhM2UwNDhiODY2Y2U3YzIzMzYzZGMwMTQ.
CSeq: 2 ACK
Content-Length: 0

<------------->
[2015-06-04 15:06:05] VERBOSE[1787] chan_sip.c: --- (8 headers 0 lines) ---
[2015-06-04 15:06:05] VERBOSE[1787] chan_sip.c: Really destroying SIP dialog 'MGNkY2QyNDBhM2UwNDhiODY2Y2U3YzIzMzYzZGMwMTQ.' Method: ACK
[2015-06-04 15:06:05] VERBOSE[1787] chan_sip.c:
<--- SIP read from UDP:192.168.0.15:55804 --->
ACK sip:79209191078@192.168.0.10;transport=UDP SIP/2.0
Via: SIP/2.0/UDP 192.168.0.15:55804;branch=z9hG4bK-d8754z-5b148564045fe924-1---d8754z-
Max-Forwards: 70
To: <sip:79209191078@192.168.0.10;transport=UDP>;tag=as43f8090f
From: <sip:222@192.168.0.10;transport=UDP>;tag=bd15786c
Call-ID: MGNkY2QyNDBhM2UwNDhiODY2Y2U3YzIzMzYzZGMwMTQ.
CSeq: 2 ACK
Content-Length: 0

<------------->
[2015-06-04 15:06:05] VERBOSE[1787] chan_sip.c: --- (8 headers 0 lines) ---
[2015-06-04 15:06:06] VERBOSE[1787] chan_sip.c:
<--- SIP read from UDP:192.168.0.15:55804 --->
PUBLISH sip:222@192.168.0.10;transport=UDP SIP/2.0
Via: SIP/2.0/UDP 192.168.0.15:55804;branch=z9hG4bK-d8754z-f8e3da12998058d1-1---d8754z-
Max-Forwards: 70
Contact: <sip:222@192.168.0.15:55804;transport=UDP>
To: <sip:222@192.168.0.10;transport=UDP>
From: <sip:222@192.168.0.10;transport=UDP>;tag=c735d31d
Call-ID: ZTY1NTQzYmE5MjYxNDY5OWI3YWE2ZTYwYzA0ZDk5MDE.
CSeq: 1 PUBLISH
Expires: 600
Allow: INVITE, ACK, CANCEL, BYE, NOTIFY, REFER, MESSAGE, OPTIONS, INFO, SUBSCRIBE
Content-Type: application/pidf+xml
Supported: replaces, norefersub, extended-refer, timer, X-cisco-serviceuri
User-Agent: Z 3.6.25251 r25476
Event: presence
Allow-Events: presence, kpml
Content-Length: 258

<?xml version="1.0" encoding="UTF-8"?>
<presence xmlns="urn:ietf:params:xml:ns:pidf" entity="sip:222@192.168.0.10;transport=UDP"> <tuple id="222" > <status><basic>open</basic></status> <note>Online</note> </tuple>
</presence>
<------------->
[2015-06-04 15:06:06] VERBOSE[1787] chan_sip.c: --- (16 headers 3 lines) ---
[2015-06-04 15:06:06] VERBOSE[1787] chan_sip.c: Sending to 192.168.0.15:55804 (no NAT)
[2015-06-04 15:06:06] VERBOSE[1787] chan_sip.c:
<--- Transmitting (no NAT) to 192.168.0.15:55804 --->
SIP/2.0 489 Bad Event
Via: SIP/2.0/UDP 192.168.0.15:55804;branch=z9hG4bK-d8754z-f8e3da12998058d1-1---d8754z-;received=192.168.0.15
From: <sip:222@192.168.0.10;transport=UDP>;tag=c735d31d
To: <sip:222@192.168.0.10;transport=UDP>;tag=as54e7ea33
Call-ID: ZTY1NTQzYmE5MjYxNDY5OWI3YWE2ZTYwYzA0ZDk5MDE.
CSeq: 1 PUBLISH
Server: FPBX-12.0.65(11.17.1)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Content-Length: 0


<------------>
[2015-06-04 15:06:06] VERBOSE[1787] chan_sip.c: Really destroying SIP dialog 'ZTY1NTQzYmE5MjYxNDY5OWI3YWE2ZTYwYzA0ZDk5MDE.' Method: PUBLISH
[2015-06-04 15:06:06] VERBOSE[1787] chan_sip.c:
<--- SIP read from UDP:192.168.0.15:55804 --->
SUBSCRIBE sip:222@192.168.0.10;transport=UDP SIP/2.0
Via: SIP/2.0/UDP 192.168.0.15:55804;branch=z9hG4bK-d8754z-3947ab30df6c5d29-1---d8754z-
Max-Forwards: 70
Contact: <sip:222@192.168.0.15:55804;transport=UDP>
To: <sip:222@192.168.0.10;transport=UDP>
From: <sip:222@192.168.0.10;transport=UDP>;tag=4a2ccc48
Call-ID: ZTkyY2QwYjAwOTRhN2JhM2I3YjM5NjQ4M2E1MGU1ZTg.
CSeq: 1 SUBSCRIBE
Expires: 600
Accept: application/watcherinfo+xml
Allow: INVITE, ACK, CANCEL, BYE, NOTIFY, REFER, MESSAGE, OPTIONS, INFO, SUBSCRIBE
Supported: replaces, norefersub, extended-refer, timer, X-cisco-serviceuri
User-Agent: Z 3.6.25251 r25476
Event: presence.winfo
Allow-Events: presence, kpml
Content-Length: 0

<------------->
[2015-06-04 15:06:06] VERBOSE[1787] chan_sip.c: --- (16 headers 0 lines) ---
[2015-06-04 15:06:06] VERBOSE[1787] chan_sip.c: Sending to 192.168.0.15:55804 (no NAT)
[2015-06-04 15:06:06] VERBOSE[1787] chan_sip.c: Creating new subscription
[2015-06-04 15:06:06] VERBOSE[1787] chan_sip.c: Sending to 192.168.0.15:55804 (no NAT)
[2015-06-04 15:06:06] VERBOSE[1787] chan_sip.c: list_route: hop: <sip:222@192.168.0.15:55804;transport=UDP>
[2015-06-04 15:06:06] VERBOSE[1787] chan_sip.c: Found peer '222' for '222' from 192.168.0.15:55804
[2015-06-04 15:06:06] VERBOSE[1787] chan_sip.c:
<--- Transmitting (no NAT) to 192.168.0.15:55804 --->
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 192.168.0.15:55804;branch=z9hG4bK-d8754z-3947ab30df6c5d29-1---d8754z-;received=192.168.0.15
From: <sip:222@192.168.0.10;transport=UDP>;tag=4a2ccc48
To: <sip:222@192.168.0.10;transport=UDP>;tag=as72f65d92
Call-ID: ZTkyY2QwYjAwOTRhN2JhM2I3YjM5NjQ4M2E1MGU1ZTg.
CSeq: 1 SUBSCRIBE
Server: FPBX-12.0.65(11.17.1)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="4185b674"
Content-Length: 0


<------------>
[2015-06-04 15:06:06] VERBOSE[1787] chan_sip.c: Scheduling destruction of SIP dialog 'ZTkyY2QwYjAwOTRhN2JhM2I3YjM5NjQ4M2E1MGU1ZTg.' in 6400 ms (Method: SUBSCRIBE)
[2015-06-04 15:06:06] VERBOSE[1787] chan_sip.c:
<--- SIP read from UDP:192.168.0.15:55804 --->
SUBSCRIBE sip:222@192.168.0.10;transport=UDP SIP/2.0
Via: SIP/2.0/UDP 192.168.0.15:55804;branch=z9hG4bK-d8754z-1f831ba4f3f18395-1---d8754z-
Max-Forwards: 70
Contact: <sip:222@192.168.0.15:55804;transport=UDP>
To: <sip:222@192.168.0.10;transport=UDP>
From: <sip:222@192.168.0.10;transport=UDP>;tag=4a2ccc48
Call-ID: ZTkyY2QwYjAwOTRhN2JhM2I3YjM5NjQ4M2E1MGU1ZTg.
CSeq: 2 SUBSCRIBE
Expires: 600
Accept: application/watcherinfo+xml
Allow: INVITE, ACK, CANCEL, BYE, NOTIFY, REFER, MESSAGE, OPTIONS, INFO, SUBSCRIBE
Supported: replaces, norefersub, extended-refer, timer, X-cisco-serviceuri
User-Agent: Z 3.6.25251 r25476
Authorization: Digest username="222",realm="asterisk",nonce="4185b674",uri="sip:222@192.168.0.10;transport=UDP",response="bd8521deeb17058ff41a6414093a67f8",algorithm=MD5
Event: presence.winfo
Allow-Events: presence, kpml
Content-Length: 0

<------------->
[2015-06-04 15:06:06] VERBOSE[1787] chan_sip.c: --- (17 headers 0 lines) ---
[2015-06-04 15:06:06] VERBOSE[1787] chan_sip.c: Creating new subscription
[2015-06-04 15:06:06] VERBOSE[1787] chan_sip.c: Sending to 192.168.0.15:55804 (no NAT)
[2015-06-04 15:06:06] VERBOSE[1787] chan_sip.c: Found peer '222' for '222' from 192.168.0.15:55804
[2015-06-04 15:06:06] VERBOSE[1787] chan_sip.c:
<--- Transmitting (no NAT) to 192.168.0.15:55804 --->
SIP/2.0 489 Bad Event
Via: SIP/2.0/UDP 192.168.0.15:55804;branch=z9hG4bK-d8754z-1f831ba4f3f18395-1---d8754z-;received=192.168.0.15
From: <sip:222@192.168.0.10;transport=UDP>;tag=4a2ccc48
To: <sip:222@192.168.0.10;transport=UDP>;tag=as72f65d92
Call-ID: ZTkyY2QwYjAwOTRhN2JhM2I3YjM5NjQ4M2E1MGU1ZTg.
CSeq: 2 SUBSCRIBE
Server: FPBX-12.0.65(11.17.1)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Content-Length: 0


<------------>
[2015-06-04 15:06:06] VERBOSE[1787] chan_sip.c: Really destroying SIP dialog 'ZTkyY2QwYjAwOTRhN2JhM2I3YjM5NjQ4M2E1MGU1ZTg.' Method: SUBSCRIBE
[2015-06-04 15:06:10] VERBOSE[1787] chan_sip.c: Reliably Transmitting (no NAT) to 192.168.0.15:55804:
OPTIONS sip:222@192.168.0.15:55804;rinstance=14b65107394a701e;transport=UDP SIP/2.0
Via: SIP/2.0/UDP 192.168.0.10:5060;branch=z9hG4bK42dd8f43
Max-Forwards: 70
From: "Unknown" <sip:Unknown@192.168.0.10>;tag=as2a7f7194
To: <sip:222@192.168.0.15:55804;rinstance=14b65107394a701e;transport=UDP>
Contact: <sip:Unknown@192.168.0.10:5060>
Call-ID: 261196a93943a4a52ad6ee417f1e5718@192.168.0.10:5060
CSeq: 102 OPTIONS
User-Agent: FPBX-12.0.65(11.17.1)
Date: Thu, 04 Jun 2015 12:06:10 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Content-Length: 0


---
[2015-06-04 15:06:10] VERBOSE[1787] chan_sip.c:
<--- SIP read from UDP:192.168.0.15:55804 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.0.10:5060;branch=z9hG4bK42dd8f43
Contact: <sip:192.168.0.15:55804>
To: <sip:222@192.168.0.15:55804;rinstance=14b65107394a701e;transport=UDP>;tag=6348ea5b
From: "Unknown"<sip:Unknown@192.168.0.10>;tag=as2a7f7194
Call-ID: 261196a93943a4a52ad6ee417f1e5718@192.168.0.10:5060
CSeq: 102 OPTIONS
Accept: application/sdp, application/sdp
Accept-Language: en
Allow: INVITE, ACK, CANCEL, BYE, NOTIFY, REFER, MESSAGE, OPTIONS, INFO, SUBSCRIBE
Supported: replaces, norefersub, extended-refer, timer, X-cisco-serviceuri
User-Agent: Z 3.6.25251 r25476
Allow-Events: presence, kpml
Content-Length: 0

<------------->
[2015-06-04 15:06:10] VERBOSE[1787] chan_sip.c: --- (14 headers 0 lines) ---
[2015-06-04 15:06:10] VERBOSE[1787] chan_sip.c: Really destroying SIP dialog '261196a93943a4a52ad6ee417f1e5718@192.168.0.10:5060' Method: OPTIONS
[2015-06-04 15:06:21] NOTICE[1787] chan_sip.c: -- Re-registration for 0042070919@sipnet.ru
[2015-06-04 15:06:21] NOTICE[1787] chan_sip.c: Outbound Registration: Expiry for sipnet.ru is 120 sec (Scheduling reregistration in 105 s)
[2015-06-04 15:07:10] VERBOSE[1787] chan_sip.c: Reliably Transmitting (no NAT) to 192.168.0.15:55804:
OPTIONS sip:222@192.168.0.15:55804;rinstance=14b65107394a701e;transport=UDP SIP/2.0
Via: SIP/2.0/UDP 192.168.0.10:5060;branch=z9hG4bK1e1065a0
Max-Forwards: 70
From: "Unknown" <sip:Unknown@192.168.0.10>;tag=as7a1ebaa4
To: <sip:222@192.168.0.15:55804;rinstance=14b65107394a701e;transport=UDP>
Contact: <sip:Unknown@192.168.0.10:5060>
Call-ID: 6f4fb6887c112e05286510657b64d6ed@192.168.0.10:5060
CSeq: 102 OPTIONS
User-Agent: FPBX-12.0.65(11.17.1)
Date: Thu, 04 Jun 2015 12:07:10 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Content-Length: 0


---
[2015-06-04 15:07:10] VERBOSE[1787] chan_sip.c:
<--- SIP read from UDP:192.168.0.15:55804 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.0.10:5060;branch=z9hG4bK1e1065a0
Contact: <sip:192.168.0.15:55804>
To: <sip:222@192.168.0.15:55804;rinstance=14b65107394a701e;transport=UDP>;tag=8268ca64
From: "Unknown"<sip:Unknown@192.168.0.10>;tag=as7a1ebaa4
Call-ID: 6f4fb6887c112e05286510657b64d6ed@192.168.0.10:5060
CSeq: 102 OPTIONS
Accept: application/sdp, application/sdp
Accept-Language: en
Allow: INVITE, ACK, CANCEL, BYE, NOTIFY, REFER, MESSAGE, OPTIONS, INFO, SUBSCRIBE
Supported: replaces, norefersub, extended-refer, timer, X-cisco-serviceuri
User-Agent: Z 3.6.25251 r25476
Allow-Events: presence, kpml
Content-Length: 0

<------------->
[2015-06-04 15:07:10] VERBOSE[1787] chan_sip.c: --- (14 headers 0 lines) ---
[2015-06-04 15:07:10] VERBOSE[1787] chan_sip.c: Really destroying SIP dialog '6f4fb6887c112e05286510657b64d6ed@192.168.0.10:5060' Method: OPTIONS
[2015-06-04 15:08:06] NOTICE[1787] chan_sip.c: -- Re-registration for 0042070919@sipnet.ru
[2015-06-04 15:08:06] NOTICE[1787] chan_sip.c: Outbound Registration: Expiry for sipnet.ru is 120 sec (Scheduling reregistration in 105 s)
[2015-06-04 15:08:10] VERBOSE[1787] chan_sip.c: Reliably Transmitting (no NAT) to 192.168.0.15:55804:
OPTIONS sip:222@192.168.0.15:55804;rinstance=14b65107394a701e;transport=UDP SIP/2.0
Via: SIP/2.0/UDP 192.168.0.10:5060;branch=z9hG4bK7708daea
Max-Forwards: 70
From: "Unknown" <sip:Unknown@192.168.0.10>;tag=as07f2871d
To: <sip:222@192.168.0.15:55804;rinstance=14b65107394a701e;transport=UDP>
Contact: <sip:Unknown@192.168.0.10:5060>
Call-ID: 115d29994e0beb8a0aba3780380a7592@192.168.0.10:5060
CSeq: 102 OPTIONS
User-Agent: FPBX-12.0.65(11.17.1)
Date: Thu, 04 Jun 2015 12:08:10 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Content-Length: 0


---
[2015-06-04 15:08:10] VERBOSE[1787] chan_sip.c:
<--- SIP read from UDP:192.168.0.15:55804 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.0.10:5060;branch=z9hG4bK7708daea
Contact: <sip:192.168.0.15:55804>
To: <sip:222@192.168.0.15:55804;rinstance=14b65107394a701e;transport=UDP>;tag=aa5e122d
From: "Unknown"<sip:Unknown@192.168.0.10>;tag=as07f2871d
Call-ID: 115d29994e0beb8a0aba3780380a7592@192.168.0.10:5060
CSeq: 102 OPTIONS
Accept: application/sdp, application/sdp
Accept-Language: en
Allow: INVITE, ACK, CANCEL, BYE, NOTIFY, REFER, MESSAGE, OPTIONS, INFO, SUBSCRIBE
Supported: replaces, norefersub, extended-refer, timer, X-cisco-serviceuri
User-Agent: Z 3.6.25251 r25476
Allow-Events: presence, kpml
Content-Length: 0

<------------->
[2015-06-04 15:08:10] VERBOSE[1787] chan_sip.c: --- (14 headers 0 lines) ---
[2015-06-04 15:08:10] VERBOSE[1787] chan_sip.c: Really destroying SIP dialog '115d29994e0beb8a0aba3780380a7592@192.168.0.10:5060' Method: OPTIONS
[2015-06-04 15:09:10] VERBOSE[1787] chan_sip.c: Reliably Transmitting (no NAT) to 192.168.0.15:55804:
OPTIONS sip:222@192.168.0.15:55804;rinstance=14b65107394a701e;transport=UDP SIP/2.0
Via: SIP/2.0/UDP 192.168.0.10:5060;branch=z9hG4bK2ab50e71
Max-Forwards: 70
From: "Unknown" <sip:Unknown@192.168.0.10>;tag=as0287b2a1
To: <sip:222@192.168.0.15:55804;rinstance=14b65107394a701e;transport=UDP>
Contact: <sip:Unknown@192.168.0.10:5060>
Call-ID: 24e0aa5f3adddb6d1b475ede3e4428f7@192.168.0.10:5060
CSeq: 102 OPTIONS
User-Agent: FPBX-12.0.65(11.17.1)
Date: Thu, 04 Jun 2015 12:09:10 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Content-Length: 0


---
[2015-06-04 15:09:10] VERBOSE[1787] chan_sip.c:
<--- SIP read from UDP:192.168.0.15:55804 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.0.10:5060;branch=z9hG4bK2ab50e71
Contact: <sip:192.168.0.15:55804>
To: <sip:222@192.168.0.15:55804;rinstance=14b65107394a701e;transport=UDP>;tag=32120069
From: "Unknown"<sip:Unknown@192.168.0.10>;tag=as0287b2a1
Call-ID: 24e0aa5f3adddb6d1b475ede3e4428f7@192.168.0.10:5060
CSeq: 102 OPTIONS
Accept: application/sdp, application/sdp
Accept-Language: en
Allow: INVITE, ACK, CANCEL, BYE, NOTIFY, REFER, MESSAGE, OPTIONS, INFO, SUBSCRIBE
Supported: replaces, norefersub, extended-refer, timer, X-cisco-serviceuri
User-Agent: Z 3.6.25251 r25476
Allow-Events: presence, kpml
Content-Length: 0

<------------->
[2015-06-04 15:09:10] VERBOSE[1787] chan_sip.c: --- (14 headers 0 lines) ---
[2015-06-04 15:09:10] VERBOSE[1787] chan_sip.c: Really destroying SIP dialog '24e0aa5f3adddb6d1b475ede3e4428f7@192.168.0.10:5060' Method: OPTIONS
[2015-06-04 15:09:51] NOTICE[1787] chan_sip.c: -- Re-registration for 0042070919@sipnet.ru
[2015-06-04 15:09:51] NOTICE[1787] chan_sip.c: Outbound Registration: Expiry for sipnet.ru is 117 sec (Scheduling reregistration in 102 s)
[2015-06-04 15:10:10] VERBOSE[1787] chan_sip.c: Reliably Transmitting (no NAT) to 192.168.0.15:55804:
OPTIONS sip:222@192.168.0.15:55804;rinstance=14b65107394a701e;transport=UDP SIP/2.0
Via: SIP/2.0/UDP 192.168.0.10:5060;branch=z9hG4bK0d119c33
Max-Forwards: 70
From: "Unknown" <sip:Unknown@192.168.0.10>;tag=as64cf4ef6
To: <sip:222@192.168.0.15:55804;rinstance=14b65107394a701e;transport=UDP>
Contact: <sip:Unknown@192.168.0.10:5060>
Call-ID: 664efc4f1b3c6be1558db2a942a6bdee@192.168.0.10:5060
CSeq: 102 OPTIONS
User-Agent: FPBX-12.0.65(11.17.1)
Date: Thu, 04 Jun 2015 12:10:10 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Content-Length: 0


---
[2015-06-04 15:10:10] VERBOSE[1787] chan_sip.c:
<--- SIP read from UDP:192.168.0.15:55804 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.0.10:5060;branch=z9hG4bK0d119c33
Contact: <sip:192.168.0.15:55804>
To: <sip:222@192.168.0.15:55804;rinstance=14b65107394a701e;transport=UDP>;tag=5e1a474c
From: "Unknown"<sip:Unknown@192.168.0.10>;tag=as64cf4ef6
Call-ID: 664efc4f1b3c6be1558db2a942a6bdee@192.168.0.10:5060
CSeq: 102 OPTIONS
Accept: application/sdp, application/sdp
Accept-Language: en
Allow: INVITE, ACK, CANCEL, BYE, NOTIFY, REFER, MESSAGE, OPTIONS, INFO, SUBSCRIBE
Supported: replaces, norefersub, extended-refer, timer, X-cisco-serviceuri
User-Agent: Z 3.6.25251 r25476
Allow-Events: presence, kpml
Content-Length: 0

<------------->
[2015-06-04 15:10:10] VERBOSE[1787] chan_sip.c: --- (14 headers 0 lines) ---
[2015-06-04 15:10:10] VERBOSE[1787] chan_sip.c: Really destroying SIP dialog '664efc4f1b3c6be1558db2a942a6bdee@192.168.0.10:5060' Method: OPTIONS
[2015-06-04 15:11:10] VERBOSE[1787] chan_sip.c: Reliably Transmitting (no NAT) to 192.168.0.15:55804:
OPTIONS sip:222@192.168.0.15:55804;rinstance=14b65107394a701e;transport=UDP SIP/2.0
Via: SIP/2.0/UDP 192.168.0.10:5060;branch=z9hG4bK034b8b19
Max-Forwards: 70
From: "Unknown" <sip:Unknown@192.168.0.10>;tag=as237845f5
To: <sip:222@192.168.0.15:55804;rinstance=14b65107394a701e;transport=UDP>
Contact: <sip:Unknown@192.168.0.10:5060>
Call-ID: 51128d383468591a3cf031323da41c81@192.168.0.10:5060
CSeq: 102 OPTIONS
User-Agent: FPBX-12.0.65(11.17.1)
Date: Thu, 04 Jun 2015 12:11:10 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Content-Length: 0


---
[2015-06-04 15:11:10] VERBOSE[1787] chan_sip.c:
<--- SIP read from UDP:192.168.0.15:55804 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.0.10:5060;branch=z9hG4bK034b8b19
Contact: <sip:192.168.0.15:55804>
To: <sip:222@192.168.0.15:55804;rinstance=14b65107394a701e;transport=UDP>;tag=8a5b8738
From: "Unknown"<sip:Unknown@192.168.0.10>;tag=as237845f5
Call-ID: 51128d383468591a3cf031323da41c81@192.168.0.10:5060
CSeq: 102 OPTIONS
Accept: application/sdp, application/sdp
Accept-Language: en
Allow: INVITE, ACK, CANCEL, BYE, NOTIFY, REFER, MESSAGE, OPTIONS, INFO, SUBSCRIBE
Supported: replaces, norefersub, extended-refer, timer, X-cisco-serviceuri
User-Agent: Z 3.6.25251 r25476
Allow-Events: presence, kpml
Content-Length: 0

<------------->
[2015-06-04 15:11:10] VERBOSE[1787] chan_sip.c: --- (14 headers 0 lines) ---
[2015-06-04 15:11:10] VERBOSE[1787] chan_sip.c: Really destroying SIP dialog '51128d383468591a3cf031323da41c81@192.168.0.10:5060' Method: OPTIONS
[2015-06-04 15:11:24] VERBOSE[2532] asterisk.c: -- Remote UNIX connection disconnected
Вернуться к началу
Аватара пользователя
Paguk
Сообщения: 78
Зарегистрирован: 27 июл 2011, 18:23
Откуда: Ульяновск

Re: freepbx distro, таймаут исх. звонка

Сообщение Paguk »

Попробуйте "Caller Timeout to Request Default"
Там по умолчанию 30 сек.
в расширенных настройках с веб-панели.
Вернуться к началу
aka_daemon
Сообщения: 5
Зарегистрирован: 28 май 2015, 12:27

Re: freepbx distro, таймаут исх. звонка

Сообщение aka_daemon »

выставил на 120 сек., не помог данный параметр
Вернуться к началу
ded
Сообщения: 15631
Зарегистрирован: 26 авг 2010, 19:00

Re: freepbx distro, таймаут исх. звонка

Сообщение ded »

Там без вэб-параметрв видно
Dial("SIP/222-00000000", "SIP/sipnet/79209191078,300,Tt") то есть 300 сек, но по логу хронометраж - через 30 сек делает no answeer - normal hangup (16)
Увеличить до 3000?
Вернуться к началу
aka_daemon
Сообщения: 5
Зарегистрирован: 28 май 2015, 12:27

Re: freepbx distro, таймаут исх. звонка

Сообщение aka_daemon »

Доброго дня.
Увеличил параметр trunk_ring_timer до 3000, проблема осталась. Менял на другие значения, все так же 30 сек.

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

 [2015-06-05 10:49:23] VERBOSE[31400][C-00000003] pbx.c: -- Executing [s@macro-dialout-trunk:22] Dial("SIP/222-00000006", "SIP/sipnet/79209191078,3000,Tt") in new stack
Как решить неприятную проблему?
Вернуться к началу
ded
Сообщения: 15631
Зарегистрирован: 26 авг 2010, 19:00

Re: freepbx distro, таймаут исх. звонка

Сообщение ded »

CLI> sip set debug peer sipnet
и смотрите с какой стороны прибегает Hangup.
Моё предположение - со стороны sipnet, и тогда только с ними бороться.
Вернуться к началу
aka_daemon
Сообщения: 5
Зарегистрирован: 28 май 2015, 12:27

Re: freepbx distro, таймаут исх. звонка

Сообщение aka_daemon »

вот лог с sip set debug peer sipnet:

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

[2015-06-08 11:49:51] VERBOSE[1769] asterisk.c: -- Remote UNIX connection
[2015-06-08 11:50:45] VERBOSE[1785][C-00000000] netsock2.c: == Using SIP RTP TOS bits 184
[2015-06-08 11:50:45] VERBOSE[1785][C-00000000] netsock2.c: == Using SIP RTP CoS mark 5
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [79209191078@from-internal:1] Macro("SIP/222-00000000", "user-callerid,LIMIT,EXTERNAL,") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@macro-user-callerid:1] Set("SIP/222-00000000", "TOUCH_MONITOR=1433753445.0") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@macro-user-callerid:2] Set("SIP/222-00000000", "AMPUSER=222") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@macro-user-callerid:3] GotoIf("SIP/222-00000000", "0?report") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@macro-user-callerid:4] ExecIf("SIP/222-00000000", "1?Set(REALCALLERIDNUM=222)") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@macro-user-callerid:5] Set("SIP/222-00000000", "AMPUSER=222") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@macro-user-callerid:6] GotoIf("SIP/222-00000000", "0?limit") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@macro-user-callerid:7] Set("SIP/222-00000000", "AMPUSERCIDNAME=222") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@macro-user-callerid:8] GotoIf("SIP/222-00000000", "0?report") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@macro-user-callerid:9] Set("SIP/222-00000000", "AMPUSERCID=222") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@macro-user-callerid:10] Set("SIP/222-00000000", "__DIAL_OPTIONS=Ttr") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@macro-user-callerid:11] Set("SIP/222-00000000", "CALLERID(all)="222" <222>") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@macro-user-callerid:12] GotoIf("SIP/222-00000000", "0?limit") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@macro-user-callerid:13] ExecIf("SIP/222-00000000", "1?Set(GROUP(concurrency_limit)=222)") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@macro-user-callerid:14] GosubIf("SIP/222-00000000", "7?sub-ccss,s,1(from-internal,79209191078)") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@sub-ccss:1] ExecIf("SIP/222-00000000", "0?Return()") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@sub-ccss:2] Set("SIP/222-00000000", "CCSS_SETUP=TRUE") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@sub-ccss:3] GosubIf("SIP/222-00000000", "0?monitor_config,1(from-internal,79209191078):monitor_default,1(from-internal,79209191078)") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [monitor_default@sub-ccss:1] GotoIf("SIP/222-00000000", "0?is_exten") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [monitor_default@sub-ccss:2] StackPop("SIP/222-00000000", "") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [monitor_default@sub-ccss:3] Return("SIP/222-00000000", "FALSE") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@macro-user-callerid:15] ExecIf("SIP/222-00000000", "1?Set(CHANNEL(language)=ru)") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@macro-user-callerid:16] GotoIf("SIP/222-00000000", "1?continue") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Goto (macro-user-callerid,s,30)
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@macro-user-callerid:30] Set("SIP/222-00000000", "CALLERID(number)=222") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@macro-user-callerid:31] Set("SIP/222-00000000", "CALLERID(name)=222") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@macro-user-callerid:32] Set("SIP/222-00000000", "CDR(cnum)=222") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@macro-user-callerid:33] Set("SIP/222-00000000", "CDR(cnam)=222") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@macro-user-callerid:34] Set("SIP/222-00000000", "CHANNEL(language)=ru") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [79209191078@from-internal:2] Gosub("SIP/222-00000000", "sub-record-check,s,1(out,79209191078,dontcare)") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@sub-record-check:1] GotoIf("SIP/222-00000000", "0?initialized") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@sub-record-check:2] Set("SIP/222-00000000", "__REC_STATUS=INITIALIZED") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@sub-record-check:3] Set("SIP/222-00000000", "NOW=1433753445") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@sub-record-check:4] Set("SIP/222-00000000", "__DAY=08") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@sub-record-check:5] Set("SIP/222-00000000", "__MONTH=06") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@sub-record-check:6] Set("SIP/222-00000000", "__YEAR=2015") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@sub-record-check:7] Set("SIP/222-00000000", "__TIMESTR=20150608-115045") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@sub-record-check:8] Set("SIP/222-00000000", "__FROMEXTEN=222") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@sub-record-check:9] Set("SIP/222-00000000", "__MON_FMT=wav") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@sub-record-check:10] NoOp("SIP/222-00000000", "Recordings initialized") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@sub-record-check:11] ExecIf("SIP/222-00000000", "0?Set(ARG3=dontcare)") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@sub-record-check:12] Set("SIP/222-00000000", "REC_POLICY_MODE_SAVE=") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@sub-record-check:13] ExecIf("SIP/222-00000000", "0?Set(REC_STATUS=NO)") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@sub-record-check:14] GotoIf("SIP/222-00000000", "3?checkaction") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Goto (sub-record-check,s,17)
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@sub-record-check:17] GotoIf("SIP/222-00000000", "1?sub-record-check,out,1") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Goto (sub-record-check,out,1)
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [out@sub-record-check:1] NoOp("SIP/222-00000000", "Outbound Recording Check from 222 to 79209191078") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [out@sub-record-check:2] Set("SIP/222-00000000", "RECMODE=yes") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [out@sub-record-check:3] ExecIf("SIP/222-00000000", "0?Goto(routewins)") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [out@sub-record-check:4] ExecIf("SIP/222-00000000", "0?Goto(routewins)") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [out@sub-record-check:5] Gosub("SIP/222-00000000", "recordcheck,1(yes,out,79209191078)") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [recordcheck@sub-record-check:1] NoOp("SIP/222-00000000", "Starting recording check against yes") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [recordcheck@sub-record-check:2] Goto("SIP/222-00000000", "yes") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Goto (sub-record-check,recordcheck,9)
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [recordcheck@sub-record-check:9] ExecIf("SIP/222-00000000", "0?Return()") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [recordcheck@sub-record-check:10] Set("SIP/222-00000000", "__REC_POLICY_MODE=YES") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [recordcheck@sub-record-check:11] Goto("SIP/222-00000000", "startrec") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Goto (sub-record-check,recordcheck,16)
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [recordcheck@sub-record-check:16] NoOp("SIP/222-00000000", "Starting recording: out, 79209191078") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [recordcheck@sub-record-check:17] Set("SIP/222-00000000", "AUDIOHOOK_INHERIT(MixMonitor)=yes") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [recordcheck@sub-record-check:18] Set("SIP/222-00000000", "__CALLFILENAME=out-79209191078-222-20150608-115045-1433753445.0") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [recordcheck@sub-record-check:19] MixMonitor("SIP/222-00000000", "2015/06/08/out-79209191078-222-20150608-115045-1433753445.0.wav,ai(LOCAL_MIXMON_ID),") in new stack
[2015-06-08 11:50:45] VERBOSE[2557][C-00000000] app_mixmonitor.c: == Begin MixMonitor Recording SIP/222-00000000
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [recordcheck@sub-record-check:20] Set("SIP/222-00000000", "__MIXMON_ID=0xb6e0a438") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [recordcheck@sub-record-check:21] Set("SIP/222-00000000", "__RECORD_ID=SIP/222-00000000") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [recordcheck@sub-record-check:22] Set("SIP/222-00000000", "__REC_STATUS=RECORDING") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [recordcheck@sub-record-check:23] Set("SIP/222-00000000", "CDR(recordingfile)=out-79209191078-222-20150608-115045-1433753445.0.wav") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [recordcheck@sub-record-check:24] Return("SIP/222-00000000", "") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [out@sub-record-check:6] Return("SIP/222-00000000", "") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [79209191078@from-internal:3] ExecIf("SIP/222-00000000", "0 ?Set(CDR(accountcode)=)") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [79209191078@from-internal:4] Set("SIP/222-00000000", "MOHCLASS=default") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [79209191078@from-internal:5] Set("SIP/222-00000000", "_NODEST=") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [79209191078@from-internal:6] Macro("SIP/222-00000000", "dialout-trunk,2,79209191078,,off") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@macro-dialout-trunk:1] Set("SIP/222-00000000", "DIAL_TRUNK=2") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@macro-dialout-trunk:2] GosubIf("SIP/222-00000000", "0?sub-pincheck,s,1()") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@macro-dialout-trunk:3] GotoIf("SIP/222-00000000", "0?disabletrunk,1") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@macro-dialout-trunk:4] Set("SIP/222-00000000", "DIAL_NUMBER=79209191078") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@macro-dialout-trunk:5] Set("SIP/222-00000000", "DIAL_TRUNK_OPTIONS=Ttr") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@macro-dialout-trunk:6] Set("SIP/222-00000000", "OUTBOUND_GROUP=OUT_2") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@macro-dialout-trunk:7] GotoIf("SIP/222-00000000", "1?nomax") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Goto (macro-dialout-trunk,s,9)
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@macro-dialout-trunk:9] GotoIf("SIP/222-00000000", "0?skipoutcid") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@macro-dialout-trunk:10] Set("SIP/222-00000000", "DIAL_TRUNK_OPTIONS=Tt") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@macro-dialout-trunk:11] Macro("SIP/222-00000000", "outbound-callerid,2") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@macro-outbound-callerid:1] ExecIf("SIP/222-00000000", "0?Set(CALLERPRES()=)") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@macro-outbound-callerid:2] ExecIf("SIP/222-00000000", "0?Set(REALCALLERIDNUM=222)") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@macro-outbound-callerid:3] GotoIf("SIP/222-00000000", "1?normcid") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Goto (macro-outbound-callerid,s,6)
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@macro-outbound-callerid:6] Set("SIP/222-00000000", "USEROUTCID=") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@macro-outbound-callerid:7] Set("SIP/222-00000000", "EMERGENCYCID=") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@macro-outbound-callerid:8] Set("SIP/222-00000000", "TRUNKOUTCID=0042070919") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@macro-outbound-callerid:9] GotoIf("SIP/222-00000000", "1?trunkcid") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Goto (macro-outbound-callerid,s,14)
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@macro-outbound-callerid:14] ExecIf("SIP/222-00000000", "1?Set(CALLERID(all)=0042070919)") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@macro-outbound-callerid:15] ExecIf("SIP/222-00000000", "0?Set(CALLERID(all)=)") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@macro-outbound-callerid:16] ExecIf("SIP/222-00000000", "0?Set(CALLERID(all)=)") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@macro-outbound-callerid:17] ExecIf("SIP/222-00000000", "0?Set(CALLERPRES()=prohib_passed_screen)") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@macro-outbound-callerid:18] Set("SIP/222-00000000", "CDR(outbound_cnum)=0042070919") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@macro-outbound-callerid:19] Set("SIP/222-00000000", "CDR(outbound_cnam)=") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@macro-dialout-trunk:12] GosubIf("SIP/222-00000000", "0?sub-flp-2,s,1()") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@macro-dialout-trunk:13] Set("SIP/222-00000000", "OUTNUM=79209191078") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@macro-dialout-trunk:14] Set("SIP/222-00000000", "custom=SIP/sipnet") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@macro-dialout-trunk:15] ExecIf("SIP/222-00000000", "0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)Tt)") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@macro-dialout-trunk:16] ExecIf("SIP/222-00000000", "0?Set(DIAL_TRUNK_OPTIONS=TtM(confirm))") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@macro-dialout-trunk:17] Macro("SIP/222-00000000", "dialout-trunk-predial-hook,") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("SIP/222-00000000", "") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@macro-dialout-trunk:18] GotoIf("SIP/222-00000000", "0?bypass,1") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@macro-dialout-trunk:19] ExecIf("SIP/222-00000000", "1?Set(CONNECTEDLINE(num,i)=79209191078)") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@macro-dialout-trunk:20] ExecIf("SIP/222-00000000", "1?Set(CONNECTEDLINE(name,i)=CID:0042070919)") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@macro-dialout-trunk:21] GotoIf("SIP/222-00000000", "0?customtrunk") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@macro-dialout-trunk:22] Dial("SIP/222-00000000", "SIP/sipnet/79209191078,300,Tt") in new stack
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] netsock2.c: == Using SIP RTP TOS bits 184
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] netsock2.c: == Using SIP RTP CoS mark 5
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] chan_sip.c: Audio is at 19700
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] chan_sip.c: Adding codec 100003 (ulaw) to SDP
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] chan_sip.c: Adding codec 100004 (alaw) to SDP
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] chan_sip.c: Adding non-codec 0x1 (telephone-event) to SDP
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] chan_sip.c: Reliably Transmitting (no NAT) to 212.53.40.40:5060:
INVITE sip:79209191078@sipnet.ru SIP/2.0
Via: SIP/2.0/UDP 192.168.0.10:5060;branch=z9hG4bK2d3dd107
Max-Forwards: 70
From: <sip:0042070919@sipnet.ru>;tag=as367c75e7
To: <sip:79209191078@sipnet.ru>
Contact: <sip:0042070919@192.168.0.10:5060>
Call-ID: 10afe24e6ce9962b47afee2d7ae5efd4@sipnet.ru
CSeq: 102 INVITE
User-Agent: FPBX-12.0.65(11.17.1)
Date: Mon, 08 Jun 2015 08:50:45 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Content-Type: application/sdp
Content-Length: 260

v=0
o=root 1237018195 1237018195 IN IP4 192.168.0.10
s=Asterisk PBX 11.17.1
c=IN IP4 192.168.0.10
t=0 0
m=audio 19700 RTP/AVP 0 8 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=sendrecv

---
[2015-06-08 11:50:45] VERBOSE[2556][C-00000000] app_dial.c: -- Called SIP/sipnet/79209191078
[2015-06-08 11:50:45] VERBOSE[1785] chan_sip.c:
<--- SIP read from UDP:212.53.40.40:5060 --->
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 192.168.0.10:5060;branch=z9hG4bK2d3dd107;received=95.66.153.45
From: <sip:0042070919@sipnet.ru>;tag=as367c75e7
To: <sip:79209191078@sipnet.ru>
Call-ID: 10afe24e6ce9962b47afee2d7ae5efd4@sipnet.ru
CSeq: 102 INVITE
Server: CommuniGatePro/6.1.3f
Content-Length: 0

<------------->
[2015-06-08 11:50:45] VERBOSE[1785] chan_sip.c: --- (8 headers 0 lines) ---
[2015-06-08 11:50:45] VERBOSE[1785] chan_sip.c:
<--- SIP read from UDP:212.53.40.40:5060 --->
SIP/2.0 401 Authentication required
Via: SIP/2.0/UDP 192.168.0.10:5060;branch=z9hG4bK2d3dd107;received=95.66.153.45
From: <sip:0042070919@sipnet.ru>;tag=as367c75e7
To: <sip:79209191078@sipnet.ru>;tag=21ECAEF7
Call-ID: 10afe24e6ce9962b47afee2d7ae5efd4@sipnet.ru
CSeq: 102 INVITE
WWW-Authenticate: Digest realm="etc.tario.ru",nonce="3D38E218A2113C7D9264",opaque="opaq",qop="auth",algorithm=MD5
Server: CommuniGatePro/6.1.3f
Content-Length: 0

<------------->
[2015-06-08 11:50:45] VERBOSE[1785] chan_sip.c: --- (9 headers 0 lines) ---
[2015-06-08 11:50:45] VERBOSE[1785][C-00000000] chan_sip.c: Transmitting (no NAT) to 212.53.40.40:5060:
ACK sip:79209191078@sipnet.ru SIP/2.0
Via: SIP/2.0/UDP 192.168.0.10:5060;branch=z9hG4bK2d3dd107
Max-Forwards: 70
From: <sip:0042070919@sipnet.ru>;tag=as367c75e7
To: <sip:79209191078@sipnet.ru>;tag=21ECAEF7
Contact: <sip:0042070919@192.168.0.10:5060>
Call-ID: 10afe24e6ce9962b47afee2d7ae5efd4@sipnet.ru
CSeq: 102 ACK
User-Agent: FPBX-12.0.65(11.17.1)
Content-Length: 0


---
[2015-06-08 11:50:45] VERBOSE[1785][C-00000000] chan_sip.c: Audio is at 19700
[2015-06-08 11:50:45] VERBOSE[1785][C-00000000] chan_sip.c: Adding codec 100003 (ulaw) to SDP
[2015-06-08 11:50:45] VERBOSE[1785][C-00000000] chan_sip.c: Adding codec 100004 (alaw) to SDP
[2015-06-08 11:50:45] VERBOSE[1785][C-00000000] chan_sip.c: Adding non-codec 0x1 (telephone-event) to SDP
[2015-06-08 11:50:45] VERBOSE[1785][C-00000000] chan_sip.c: Reliably Transmitting (no NAT) to 212.53.40.40:5060:
INVITE sip:79209191078@sipnet.ru SIP/2.0
Via: SIP/2.0/UDP 192.168.0.10:5060;branch=z9hG4bK5028cc0d
Max-Forwards: 70
From: <sip:0042070919@sipnet.ru>;tag=as367c75e7
To: <sip:79209191078@sipnet.ru>
Contact: <sip:0042070919@192.168.0.10:5060>
Call-ID: 10afe24e6ce9962b47afee2d7ae5efd4@sipnet.ru
CSeq: 103 INVITE
User-Agent: FPBX-12.0.65(11.17.1)
Authorization: Digest username="0042070919", realm="etc.tario.ru", algorithm=MD5, uri="sip:79209191078@sipnet.ru", nonce="3D38E218A2113C7D9264", response="cdda647ac76b89aee671e1fca15b188c", opaque="opaq", qop=auth, cnonce="3b3722fd", nc=00000001
Date: Mon, 08 Jun 2015 08:50:45 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Content-Type: application/sdp
Content-Length: 260

v=0
o=root 1237018195 1237018196 IN IP4 192.168.0.10
s=Asterisk PBX 11.17.1
c=IN IP4 192.168.0.10
t=0 0
m=audio 19700 RTP/AVP 0 8 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=sendrecv

---
[2015-06-08 11:50:45] VERBOSE[1785] chan_sip.c:
<--- SIP read from UDP:212.53.40.40:5060 --->
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 192.168.0.10:5060;branch=z9hG4bK5028cc0d;received=95.66.153.45
From: <sip:0042070919@sipnet.ru>;tag=as367c75e7
To: <sip:79209191078@sipnet.ru>
Call-ID: 10afe24e6ce9962b47afee2d7ae5efd4@sipnet.ru
CSeq: 103 INVITE
Server: CommuniGatePro/6.1.3f
Content-Length: 0

<------------->
[2015-06-08 11:50:45] VERBOSE[1785] chan_sip.c: --- (8 headers 0 lines) ---
[2015-06-08 11:50:48] VERBOSE[1785] chan_sip.c:
<--- SIP read from UDP:212.53.40.40:5060 --->
SIP/2.0 183 Call progress
Via: SIP/2.0/UDP 192.168.0.10:5060;branch=z9hG4bK5028cc0d;received=95.66.153.45
Record-Route: <sip:c192.168.40.71.1756896.call.cgatepro;lr>
Record-Route: <sip:212.53.40.40:5060;lr>
From: <sip:0042070919@sipnet.ru>;tag=as367c75e7
To: <sip:79209191078@sipnet.ru>;tag=8B820141-104466-491B149A_kmbdctj-44B1
Call-ID: 10afe24e6ce9962b47afee2d7ae5efd4@sipnet.ru
CSeq: 103 INVITE
Contact: <sip:signode-104466-491B149A_kmbdctj-44B1@212.53.40.40>
Supported: 100rel,timer,replaces,histinfo,precondition
Allow: INVITE,OPTIONS,INFO,MESSAGE,PRACK,UPDATE,REFER
User-Agent: CommuniGatePro-callLeg/6.1.3f
Content-Type: application/sdp
Content-Length: 223

v=0
o=CGPLeg104466 322992086 161496044 IN IP4 212.53.40.71
s=-
c=IN IP4 212.53.40.71
t=0 0
m=audio 37568 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
a=sendrecv
a=ptime:20
<------------->
[2015-06-08 11:50:48] VERBOSE[1785] chan_sip.c: --- (14 headers 11 lines) ---
[2015-06-08 11:50:48] VERBOSE[1785][C-00000000] chan_sip.c: list_route: hop: <sip:212.53.40.40:5060;lr>
[2015-06-08 11:50:48] VERBOSE[1785][C-00000000] chan_sip.c: list_route: hop: <sip:c192.168.40.71.1756896.call.cgatepro;lr>
[2015-06-08 11:50:48] VERBOSE[1785][C-00000000] chan_sip.c: Found RTP audio format 0
[2015-06-08 11:50:48] VERBOSE[1785][C-00000000] chan_sip.c: Found RTP audio format 101
[2015-06-08 11:50:48] VERBOSE[1785][C-00000000] chan_sip.c: Found audio description format PCMU for ID 0
[2015-06-08 11:50:48] VERBOSE[1785][C-00000000] chan_sip.c: Found audio description format telephone-event for ID 101
[2015-06-08 11:50:48] VERBOSE[1785][C-00000000] chan_sip.c: Capabilities: us - (ulaw|alaw), peer - audio=(ulaw)/video=(nothing)/text=(nothing), combined - (ulaw)
[2015-06-08 11:50:48] VERBOSE[1785][C-00000000] chan_sip.c: Non-codec capabilities (dtmf): us - 0x1 (telephone-event|), peer - 0x1 (telephone-event|), combined - 0x1 (telephone-event|)
[2015-06-08 11:50:48] VERBOSE[1785][C-00000000] chan_sip.c: Peer audio RTP is at port 212.53.40.71:37568
[2015-06-08 11:50:48] VERBOSE[2556][C-00000000] app_dial.c: -- SIP/sipnet-00000001 is making progress passing it to SIP/222-00000000
[2015-06-08 11:51:17] VERBOSE[1785] chan_sip.c:
<--- SIP read from UDP:212.53.40.40:5060 --->
SIP/2.0 487 Request cancelled
Via: SIP/2.0/UDP 192.168.0.10:5060;branch=z9hG4bK5028cc0d;received=95.66.153.45
From: <sip:0042070919@sipnet.ru>;tag=as367c75e7
To: <sip:79209191078@sipnet.ru>;tag=8B820141-104466-491B149A_kmbdctj-44B1
Call-ID: 10afe24e6ce9962b47afee2d7ae5efd4@sipnet.ru
CSeq: 103 INVITE
Server: CommuniGatePro/6.1.3f
Content-Length: 0

<------------->
[2015-06-08 11:51:17] VERBOSE[1785] chan_sip.c: --- (8 headers 0 lines) ---
[2015-06-08 11:51:17] VERBOSE[1785][C-00000000] chan_sip.c: Transmitting (no NAT) to 212.53.40.40:5060:
ACK sip:signode-104466-491B149A_kmbdctj-44B1@212.53.40.40 SIP/2.0
Via: SIP/2.0/UDP 192.168.0.10:5060;branch=z9hG4bK5028cc0d
Max-Forwards: 70
From: <sip:0042070919@sipnet.ru>;tag=as367c75e7
To: <sip:79209191078@sipnet.ru>;tag=8B820141-104466-491B149A_kmbdctj-44B1
Contact: <sip:0042070919@192.168.0.10:5060>
Call-ID: 10afe24e6ce9962b47afee2d7ae5efd4@sipnet.ru
CSeq: 103 ACK
User-Agent: FPBX-12.0.65(11.17.1)
Content-Length: 0


---
[2015-06-08 11:51:17] VERBOSE[1785][C-00000000] chan_sip.c: Scheduling destruction of SIP dialog '10afe24e6ce9962b47afee2d7ae5efd4@sipnet.ru' in 32000 ms (Method: INVITE)
[2015-06-08 11:51:17] VERBOSE[2556][C-00000000] chan_sip.c: Scheduling destruction of SIP dialog '10afe24e6ce9962b47afee2d7ae5efd4@sipnet.ru' in 32000 ms (Method: INVITE)
[2015-06-08 11:51:17] VERBOSE[2556][C-00000000] app_dial.c: -- No one is available to answer at this time (1:0/0/0)
[2015-06-08 11:51:17] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@macro-dialout-trunk:23] NoOp("SIP/222-00000000", "Dial failed for some reason with DIALSTATUS = NOANSWER and HANGUPCAUSE = 16") in new stack
[2015-06-08 11:51:17] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@macro-dialout-trunk:24] GotoIf("SIP/222-00000000", "0?continue,1:s-NOANSWER,1") in new stack
[2015-06-08 11:51:17] VERBOSE[2556][C-00000000] pbx.c: -- Goto (macro-dialout-trunk,s-NOANSWER,1)
[2015-06-08 11:51:17] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s-NOANSWER@macro-dialout-trunk:1] NoOp("SIP/222-00000000", "Dial failed due to trunk reporting NOANSWER - giving up") in new stack
[2015-06-08 11:51:17] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s-NOANSWER@macro-dialout-trunk:2] Progress("SIP/222-00000000", "") in new stack
[2015-06-08 11:51:17] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s-NOANSWER@macro-dialout-trunk:3] Playback("SIP/222-00000000", "number-not-answering,noanswer") in new stack
[2015-06-08 11:51:17] VERBOSE[2556][C-00000000] file.c: -- <SIP/222-00000000> Playing 'number-not-answering.ulaw' (language 'ru')
[2015-06-08 11:51:18] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s-NOANSWER@macro-dialout-trunk:4] Congestion("SIP/222-00000000", "20") in new stack
[2015-06-08 11:51:18] WARNING[2556][C-00000000] channel.c: Prodding channel 'SIP/222-00000000' failed
[2015-06-08 11:51:18] VERBOSE[2556][C-00000000] app_macro.c: == Spawn extension (macro-dialout-trunk, s-NOANSWER, 4) exited non-zero on 'SIP/222-00000000' in macro 'dialout-trunk'
[2015-06-08 11:51:18] VERBOSE[2556][C-00000000] pbx.c: == Spawn extension (from-internal, 79209191078, 6) exited non-zero on 'SIP/222-00000000'
[2015-06-08 11:51:18] VERBOSE[2556][C-00000000] pbx.c: -- Executing [h@from-internal:1] Hangup("SIP/222-00000000", "") in new stack
[2015-06-08 11:51:18] VERBOSE[2556][C-00000000] pbx.c: == Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/222-00000000'
[2015-06-08 11:51:18] VERBOSE[2557][C-00000000] app_mixmonitor.c: == MixMonitor close filestream (mixed)
[2015-06-08 11:51:18] VERBOSE[2557][C-00000000] app_mixmonitor.c: == End MixMonitor Recording SIP/222-00000000
[2015-06-08 11:51:49] VERBOSE[1785] chan_sip.c: Really destroying SIP dialog '10afe24e6ce9962b47afee2d7ae5efd4@sipnet.ru' Method: INVITE
[2015-06-08 11:51:52] NOTICE[1785] chan_sip.c: -- Re-registration for 0042070919@sipnet.ru
[2015-06-08 11:51:52] VERBOSE[1785] chan_sip.c: REGISTER 11 headers, 0 lines
[2015-06-08 11:51:52] VERBOSE[1785] chan_sip.c: Reliably Transmitting (no NAT) to 212.53.40.40:5060:
REGISTER sip:sipnet.ru SIP/2.0
Via: SIP/2.0/UDP 192.168.0.10:5060;branch=z9hG4bK2147f0e6
Max-Forwards: 70
From: <sip:0042070919@sipnet.ru>;tag=as211aa912
To: <sip:0042070919@sipnet.ru>
Call-ID: 480e07944a28454d7714fede20f25dda@sipnet.ru
CSeq: 113 REGISTER
User-Agent: FPBX-12.0.65(11.17.1)
Authorization: Digest username="0042070919", realm="etc.tario.ru", algorithm=MD5, uri="sip:sipnet.ru", nonce="E2921C776C7E86173E47", response="78ba9851193a26108f2457d0ceb4d391", opaque="opaq", qop=auth, cnonce="62993eb2", nc=0000000b
Expires: 120
Contact: <sip:0042070919@192.168.0.10:5060>
Content-Length: 0

---
[2015-06-08 11:51:52] VERBOSE[1785] chan_sip.c:
<--- SIP read from UDP:212.53.40.40:5060 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.0.10:5060;branch=z9hG4bK2147f0e6;received=95.66.153.45
Path: <sip:nudp-t95.66.153.45-21883s212.53.40.40-5060.u.cgatepro;lr>
From: <sip:0042070919@sipnet.ru>;tag=as211aa912
To: <sip:0042070919@sipnet.ru>;tag=2AF932BE
Call-ID: 480e07944a28454d7714fede20f25dda@sipnet.ru
CSeq: 113 REGISTER
Expires: 117
Contact: <sip:0042070919@192.168.0.10:5060>;expires=117
Contact: <sip:0042070919@192.168.0.10:5060>;expires=15
Event: registration
Date: Mon, 08 Jun 2015 08:51:52 GMT
Allow: PUBLISH,SUBSCRIBE
Supported: path,gruu
Allow-Events: presence,message-summary,reg,dialog,line-seize,keep-alive,refer
Server: CommuniGatePro/6.1.3f
Content-Length: 0

<------------->
[2015-06-08 11:51:52] VERBOSE[1785] chan_sip.c: --- (17 headers 0 lines) ---
[2015-06-08 11:51:52] NOTICE[1785] chan_sip.c: Outbound Registration: Expiry for sipnet.ru is 117 sec (Scheduling reregistration in 102 s)
[2015-06-08 11:51:52] VERBOSE[1785] chan_sip.c: Really destroying SIP dialog '480e07944a28454d7714fede20f25dda@sipnet.ru' Method: REGISTER
[2015-06-08 11:53:34] NOTICE[1785] chan_sip.c: -- Re-registration for 0042070919@sipnet.ru
[2015-06-08 11:53:34] VERBOSE[1785] chan_sip.c: REGISTER 11 headers, 0 lines
[2015-06-08 11:53:34] VERBOSE[1785] chan_sip.c: Reliably Transmitting (no NAT) to 212.53.40.40:5060:
REGISTER sip:sipnet.ru SIP/2.0
Via: SIP/2.0/UDP 192.168.0.10:5060;branch=z9hG4bK47156649
Max-Forwards: 70
From: <sip:0042070919@sipnet.ru>;tag=as211aa912
To: <sip:0042070919@sipnet.ru>
Call-ID: 480e07944a28454d7714fede20f25dda@sipnet.ru
CSeq: 114 REGISTER
User-Agent: FPBX-12.0.65(11.17.1)
Authorization: Digest username="0042070919", realm="etc.tario.ru", algorithm=MD5, uri="sip:sipnet.ru", nonce="E2921C776C7E86173E47", response="1fe20efd10bf7425be92f92b8448a774", opaque="opaq", qop=auth, cnonce="0119eac5", nc=0000000c
Expires: 120
Contact: <sip:0042070919@192.168.0.10:5060>
Content-Length: 0


---
[2015-06-08 11:53:34] VERBOSE[1785] chan_sip.c:
<--- SIP read from UDP:212.53.40.40:5060 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.0.10:5060;branch=z9hG4bK47156649;received=95.66.153.45
Path: <sip:nudp-t95.66.153.45-36778s212.53.40.40-5060.u.cgatepro;lr>
From: <sip:0042070919@sipnet.ru>;tag=as211aa912
To: <sip:0042070919@sipnet.ru>;tag=4FF806A5
Call-ID: 480e07944a28454d7714fede20f25dda@sipnet.ru
CSeq: 114 REGISTER
Expires: 118
Contact: <sip:0042070919@192.168.0.10:5060>;expires=118
Contact: <sip:0042070919@192.168.0.10:5060>;expires=15
Event: registration
Date: Mon, 08 Jun 2015 08:53:34 GMT
Allow: PUBLISH,SUBSCRIBE
Supported: path,gruu
Allow-Events: presence,message-summary,reg,dialog,line-seize,keep-alive,refer
Server: CommuniGatePro/6.1.3f
Content-Length: 0

<------------->
[2015-06-08 11:53:34] VERBOSE[1785] chan_sip.c: --- (17 headers 0 lines) ---
[2015-06-08 11:53:34] NOTICE[1785] chan_sip.c: Outbound Registration: Expiry for sipnet.ru is 118 sec (Scheduling reregistration in 103 s)
[2015-06-08 11:53:34] VERBOSE[1785] chan_sip.c: Really destroying SIP dialog '480e07944a28454d7714fede20f25dda@sipnet.ru' Method: REGISTER
и как я понял из вот этого куска лога, ограничение со стороны оператора?

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

---
[2015-06-08 11:51:17] VERBOSE[1785][C-00000000] chan_sip.c: Scheduling destruction of SIP dialog '10afe24e6ce9962b47afee2d7ae5efd4@sipnet.ru' in 32000 ms (Method: INVITE)
[2015-06-08 11:51:17] VERBOSE[2556][C-00000000] chan_sip.c: Scheduling destruction of SIP dialog '10afe24e6ce9962b47afee2d7ae5efd4@sipnet.ru' in 32000 ms (Method: INVITE)
[2015-06-08 11:51:17] VERBOSE[2556][C-00000000] app_dial.c: -- No one is available to answer at this time (1:0/0/0)
[2015-06-08 11:51:17] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@macro-dialout-trunk:23] NoOp("SIP/222-00000000", "Dial failed for some reason with DIALSTATUS = NOANSWER and HANGUPCAUSE = 16") in new stack
[2015-06-08 11:51:17] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s@macro-dialout-trunk:24] GotoIf("SIP/222-00000000", "0?continue,1:s-NOANSWER,1") in new stack
[2015-06-08 11:51:17] VERBOSE[2556][C-00000000] pbx.c: -- Goto (macro-dialout-trunk,s-NOANSWER,1)
[2015-06-08 11:51:17] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s-NOANSWER@macro-dialout-trunk:1] NoOp("SIP/222-00000000", "Dial failed due to trunk reporting NOANSWER - giving up") in new stack
[2015-06-08 11:51:17] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s-NOANSWER@macro-dialout-trunk:2] Progress("SIP/222-00000000", "") in new stack
[2015-06-08 11:51:17] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s-NOANSWER@macro-dialout-trunk:3] Playback("SIP/222-00000000", "number-not-answering,noanswer") in new stack
[2015-06-08 11:51:17] VERBOSE[2556][C-00000000] file.c: -- <SIP/222-00000000> Playing 'number-not-answering.ulaw' (language 'ru')
[2015-06-08 11:51:18] VERBOSE[2556][C-00000000] pbx.c: -- Executing [s-NOANSWER@macro-dialout-trunk:4] Congestion("SIP/222-00000000", "20") in new stack
[2015-06-08 11:51:18] WARNING[2556][C-00000000] channel.c: Prodding channel 'SIP/222-00000000' failed
[2015-06-08 11:51:18] VERBOSE[2556][C-00000000] app_macro.c: == Spawn extension (macro-dialout-trunk, s-NOANSWER, 4) exited non-zero on 'SIP/222-00000000' in macro 'dialout-trunk'
[2015-06-08 11:51:18] VERBOSE[2556][C-00000000] pbx.c: == Spawn extension (from-internal, 79209191078, 6) exited non-zero on 'SIP/222-00000000'
[2015-06-08 11:51:18] VERBOSE[2556][C-00000000] pbx.c: -- Executing [h@from-internal:1] Hangup("SIP/222-00000000", "") in new stack
[2015-06-08 11:51:18] VERBOSE[2556][C-00000000] pbx.c: == Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/222-00000000'
[2015-06-08 11:51:18] VERBOSE[2557][C-00000000] app_mixmonitor.c: == MixMonitor close filestream (mixed)
[2015-06-08 11:51:18] VERBOSE[2557][C-00000000] app_mixmonitor.c: == End MixMonitor Recording SIP/222-00000000
[2015-06-08 11:51:49] VERBOSE[1785] chan_sip.c: Really destroying SIP dialog '10afe24e6ce9962b47afee2d7ae5efd4@sipnet.ru' Method: INVITE
[2015-06-08 11:51:52] NOTICE[1785] chan_sip.c: -- Re-registration for 0042070919@sipnet.ru
[2015-06-08 11:51:52] VERBOSE[1785] chan_sip.c: REGISTER 11 headers, 0 lines
[2015-06-08 11:51:52] VERBOSE[1785] chan_sip.c: Reliably Transmitting (no NAT) to 212.53.40.40:5060:
REGISTER sip:sipnet.ru SIP/2.0
Via: SIP/2.0/UDP 192.168.0.10:5060;branch=z9hG4bK2147f0e6
Max-Forwards: 70
From: <sip:0042070919@sipnet.ru>;tag=as211aa912
To: <sip:0042070919@sipnet.ru>
Call-ID: 480e07944a28454d7714fede20f25dda@sipnet.ru
CSeq: 113 REGISTER
User-Agent: FPBX-12.0.65(11.17.1)
Authorization: Digest username="0042070919", realm="etc.tario.ru", algorithm=MD5, uri="sip:sipnet.ru", nonce="E2921C776C7E86173E47", response="78ba9851193a26108f2457d0ceb4d391", opaque="opaq", qop=auth, cnonce="62993eb2", nc=0000000b
Expires: 120
Contact: <sip:0042070919@192.168.0.10:5060>
Content-Length: 0
Вернуться к началу
ded
Сообщения: 15631
Зарегистрирован: 26 авг 2010, 19:00

Re: freepbx distro, таймаут исх. звонка

Сообщение ded »

Много зелёных букв и цифр.
Ну вот это более красноречиво:

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

<--- SIP read from UDP:212.53.40.40:5060 --->
SIP/2.0 487 Request cancelled
ded писал(а):Моё предположение - со стороны sipnet, и тогда только с ними бороться.
Предположение оправдалось.
Вернуться к началу
aka_daemon
Сообщения: 5
Зарегистрирован: 28 май 2015, 12:27

Re: freepbx distro, таймаут исх. звонка

Сообщение aka_daemon »

Благодарю за ответы, очень помогли. Тему можно закрывать
Вернуться к началу
Ответить

Вернуться в «Готовые VoIP дистрибутивы»

© 2008 — 2025 Asterisk.ru
Digium, Asterisk and AsteriskNOW are registered trademarks of Digium, Inc.
Design and development by PostMet-Netzwerk GmbH