Got sip response 500 server internal error back from - Исправление ошибок и поиск оптимальных решений проблем

Господа !
Последний раз Астериск ставил 3 года назад, так что не ругайте сильно.

Имеется FreePBX 14.0.13.4 , один транк ( еще 2 транка выключены)
Входящие звонки поступают, а вот исходящих нет, говорит астер мол все линии заняты.
Исходящий маршрут прописан X ( иксами )

В логах увидел вот такую строчку, response 500 «Server Internal Error» back from ip_провайдера:5060 ( лог прикреплен )

Подскажите что не так?
2ой день борюсь , домой хочу.

P.S.: Книгу читаю на 36 странице и все еще читаю…

Код: выделить все: [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [890********@from-internal:1] Macro("SIP/100-00000028", "user-callerid,LIMIT,EXTERNAL,") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-user-callerid:1] Set("SIP/100-00000028", "TOUCH_MONITOR=1568300584.40") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-user-callerid:2] Set("SIP/100-00000028", "AMPUSER=100") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-user-callerid:3] GotoIf("SIP/100-00000028", "0?report") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-user-callerid:4] ExecIf("SIP/100-00000028", "1?Set(REALCALLERIDNUM=100)") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-user-callerid:5] Set("SIP/100-00000028", "AMPUSER=100") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-user-callerid:6] GotoIf("SIP/100-00000028", "0?limit") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-user-callerid:7] Set("SIP/100-00000028", "AMPUSERCIDNAME=100") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-user-callerid:8] ExecIf("SIP/100-00000028", "0?Set(__CIDMASQUERADING=TRUE)") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-user-callerid:9] GotoIf("SIP/100-00000028", "0?report") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-user-callerid:10] Set("SIP/100-00000028", "AMPUSERCID=100") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-user-callerid:11] Set("SIP/100-00000028", "__DIAL_OPTIONS=HhTtr") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-user-callerid:12] Set("SIP/100-00000028", "CALLERID(all)="100" <100>") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-user-callerid:13] ExecIf("SIP/100-00000028", "0?Set(CALLERID(all)=EXTERNAL)") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-user-callerid:14] GotoIf("SIP/100-00000028", "0?limit") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-user-callerid:15] ExecIf("SIP/100-00000028", "1?Set(GROUP(concurrency_limit)=100)") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-user-callerid:16] ExecIf("SIP/100-00000028", "1?Set(CHANNEL(language)=ru)") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-user-callerid:17] NoOp("SIP/100-00000028", "Macro Depth is 1") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-user-callerid:18] GotoIf("SIP/100-00000028", "1?report2:macroerror") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx_builtins.c: Goto (macro-user-callerid,s,19) [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-user-callerid:19] GotoIf("SIP/100-00000028", "1?continue") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx_builtins.c: Goto (macro-user-callerid,s,38) [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-user-callerid:38] Set("SIP/100-00000028", "CALLERID(number)=100") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-user-callerid:39] Set("SIP/100-00000028", "CALLERID(name)=100") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-user-callerid:40] GotoIf("SIP/100-00000028", "0?cnum") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-user-callerid:41] Set("SIP/100-00000028", "CDR(cnam)=100") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-user-callerid:42] Set("SIP/100-00000028", "CDR(cnum)=100") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-user-callerid:43] Set("SIP/100-00000028", "CHANNEL(language)=ru") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [890********@from-internal:2] Gosub("SIP/100-00000028", "sub-record-check,s,1(out,890********,force)") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@sub-record-check:1] GotoIf("SIP/100-00000028", "0?initialized") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@sub-record-check:2] Set("SIP/100-00000028", "__REC_STATUS=INITIALIZED") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@sub-record-check:3] Set("SIP/100-00000028", "NOW=1568300584") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@sub-record-check:4] Set("SIP/100-00000028", "__DAY=12") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@sub-record-check:5] Set("SIP/100-00000028", "__MONTH=09") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@sub-record-check:6] Set("SIP/100-00000028", "__YEAR=2019") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@sub-record-check:7] Set("SIP/100-00000028", "__TIMESTR=20190912-200304") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@sub-record-check:8] Set("SIP/100-00000028", "__FROMEXTEN=100") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@sub-record-check:9] Set("SIP/100-00000028", "__MON_FMT=wav") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@sub-record-check:10] NoOp("SIP/100-00000028", "Recordings initialized") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@sub-record-check:11] ExecIf("SIP/100-00000028", "0?Set(ARG3=dontcare)") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@sub-record-check:12] Set("SIP/100-00000028", "REC_POLICY_MODE_SAVE=") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@sub-record-check:13] ExecIf("SIP/100-00000028", "0?Set(REC_STATUS=NO)") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@sub-record-check:14] GotoIf("SIP/100-00000028", "3?checkaction") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx_builtins.c: Goto (sub-record-check,s,17) [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@sub-record-check:17] GotoIf("SIP/100-00000028", "1?sub-record-check,out,1") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx_builtins.c: Goto (sub-record-check,out,1) [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [out@sub-record-check:1] NoOp("SIP/100-00000028", "Outbound Recording Check from 100 to 890********") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [out@sub-record-check:2] Set("SIP/100-00000028", "RECMODE=force") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [out@sub-record-check:3] ExecIf("SIP/100-00000028", "0?Goto(routewins)") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [out@sub-record-check:4] ExecIf("SIP/100-00000028", "1?Goto(routewins)") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx_builtins.c: Goto (sub-record-check,out,7) [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [out@sub-record-check:7] Gosub("SIP/100-00000028", "recordcheck,1(force,out,890********)") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("SIP/100-00000028", "Starting recording check against force") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("SIP/100-00000028", "force") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx_builtins.c: Goto (sub-record-check,recordcheck,5) [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [recordcheck@sub-record-check:5] Set("SIP/100-00000028", "__REC_POLICY_MODE=FORCE") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [recordcheck@sub-record-check:6] GotoIf("SIP/100-00000028", "1?startrec") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx_builtins.c: Goto (sub-record-check,recordcheck,16) [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [recordcheck@sub-record-check:16] NoOp("SIP/100-00000028", "Starting recording: out, 890********") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [recordcheck@sub-record-check:17] Set("SIP/100-00000028", "__CALLFILENAME=out-890********-100-20190912-200304-1568300584.40") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [recordcheck@sub-record-check:18] MixMonitor("SIP/100-00000028", "2019/09/12/out-890********-100-20190912-200304-1568300584.40.wav,abi(LOCAL_MIXMON_ID),") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [recordcheck@sub-record-check:19] Set("SIP/100-00000028", "__MIXMON_ID=0x7f6e3400b270") in new stack [2019-09-12 20:03:04] VERBOSE[25430][C-00000015] app_mixmonitor.c: Begin MixMonitor Recording SIP/100-00000028 [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [recordcheck@sub-record-check:20] Set("SIP/100-00000028", "__RECORD_ID=SIP/100-00000028") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [recordcheck@sub-record-check:21] Set("SIP/100-00000028", "__REC_STATUS=RECORDING") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [recordcheck@sub-record-check:22] Set("SIP/100-00000028", "CDR(recordingfile)=out-890********-100-20190912-200304-1568300584.40.wav") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [recordcheck@sub-record-check:23] Return("SIP/100-00000028", "") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [out@sub-record-check:8] Return("SIP/100-00000028", "") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [890********@from-internal:3] ExecIf("SIP/100-00000028", "0 ?Set(CDR(accountcode)=)") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [890********@from-internal:4] Set("SIP/100-00000028", "MOHCLASS=default") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [890********@from-internal:5] ExecIf("SIP/100-00000028", "0?Set(TRUNKCIDOVERRIDE=34********)") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [890********@from-internal:6] Set("SIP/100-00000028", "_NODEST=") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [890********@from-internal:7] Macro("SIP/100-00000028", "dialout-trunk,3,90********,,off") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-dialout-trunk:1] Set("SIP/100-00000028", "DIAL_TRUNK=3") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-dialout-trunk:2] ExecIf("SIP/100-00000028", "0?Set(DIAL_OPTIONS=Hhtr)") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-dialout-trunk:3] GosubIf("SIP/100-00000028", "0?sub-pincheck,s,1()") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-dialout-trunk:4] ExecIf("SIP/100-00000028", "0?Set(CALLERID(num)=100)") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-dialout-trunk:5] GotoIf("SIP/100-00000028", "0?disabletrunk,1") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-dialout-trunk:6] Set("SIP/100-00000028", "DIAL_NUMBER=90********") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-dialout-trunk:7] Set("SIP/100-00000028", "DIAL_TRUNK_OPTIONS=HhTtr") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-dialout-trunk:8] Set("SIP/100-00000028", "OUTBOUND_GROUP=OUT_3") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-dialout-trunk:9] Set("SIP/100-00000028", "DIAL_TRUNK_OPTIONS=T") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-dialout-trunk:10] GotoIf("SIP/100-00000028", "0?nomax") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-dialout-trunk:11] GotoIf("SIP/100-00000028", "0?chanfull") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-dialout-trunk:12] GotoIf("SIP/100-00000028", "0?skipoutcid") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-dialout-trunk:13] Macro("SIP/100-00000028", "outbound-callerid,3") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-outbound-callerid:1] NoOp("SIP/100-00000028", "100") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-outbound-callerid:2] NoOp("SIP/100-00000028", "") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-outbound-callerid:3] NoOp("SIP/100-00000028", "off") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-outbound-callerid:4] ExecIf("SIP/100-00000028", "0?Set(CALLERPRES(name-pres)=)") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-outbound-callerid:5] ExecIf("SIP/100-00000028", "0?Set(CALLERPRES(num-pres)=)") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-outbound-callerid:6] ExecIf("SIP/100-00000028", "0?Set(REALCALLERIDNUM=100)") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-outbound-callerid:7] ExecIf("SIP/100-00000028", "0?Set(AMPUSER=100)") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-outbound-callerid:8] GotoIf("SIP/100-00000028", "1?normcid") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx_builtins.c: Goto (macro-outbound-callerid,s,12) [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-outbound-callerid:12] Set("SIP/100-00000028", "USEROUTCID=100") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-outbound-callerid:13] Set("SIP/100-00000028", "EMERGENCYCID=") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-outbound-callerid:14] Set("SIP/100-00000028", "TRUNKOUTCID=34********") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-outbound-callerid:15] GotoIf("SIP/100-00000028", "1?trunkcid") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx_builtins.c: Goto (macro-outbound-callerid,s,21) [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-outbound-callerid:21] ExecIf("SIP/100-00000028", "1?Set(CALLERID(all)=34********)") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-outbound-callerid:22] ExecIf("SIP/100-00000028", "1?Set(CALLERID(all)=100)") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-outbound-callerid:23] ExecIf("SIP/100-00000028", "0?Set(CALLERID(all)=)") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-outbound-callerid:24] ExecIf("SIP/100-00000028", "0?Set(CALLERPRES(name-pres)=prohib_passed_screen)") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-outbound-callerid:25] ExecIf("SIP/100-00000028", "0?Set(CALLERPRES(num-pres)=prohib_passed_screen)") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-outbound-callerid:26] Set("SIP/100-00000028", "CDR(outbound_cnum)=100") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-outbound-callerid:27] Set("SIP/100-00000028", "CDR(outbound_cnam)=") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-dialout-trunk:14] GosubIf("SIP/100-00000028", "1?sub-flp-3,s,1()") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@sub-flp-3:1] ExecIf("SIP/100-00000028", "1?Return()") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-dialout-trunk:15] Set("SIP/100-00000028", "OUTNUM=90********") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-dialout-trunk:16] Set("SIP/100-00000028", "custom=SIP/tr-34********") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-dialout-trunk:17] ExecIf("SIP/100-00000028", "0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)T)") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-dialout-trunk:18] ExecIf("SIP/100-00000028", "0?Set(DIAL_TRUNK_OPTIONS=TM(confirm))") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-dialout-trunk:19] Macro("SIP/100-00000028", "dialout-trunk-predial-hook,") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("SIP/100-00000028", "") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-dialout-trunk:20] GotoIf("SIP/100-00000028", "0?skipcrm") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-dialout-trunk:21] Set("SIP/100-00000028", "__CRM_DIRECTION=OUTBOUND") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-dialout-trunk:22] Set("SIP/100-00000028", "__CRM_DESTINATION=90********") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-dialout-trunk:23] Set("SIP/100-00000028", "__CRM_SOURCE=100") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-dialout-trunk:24] AGI("SIP/100-00000028", "sangomacrm.agi") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/sangomacrm.agi [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] res_agi.c: <SIP/100-00000028>AGI Script sangomacrm.agi completed, returning 0 [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-dialout-trunk:25] Set("SIP/100-00000028", "CHANNEL(hangup_handler_push)=crm-hangup,s,1") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-dialout-trunk:26] NoOp("SIP/100-00000028", "CRM Finished") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-dialout-trunk:27] GotoIf("SIP/100-00000028", "0?bypass,1") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-dialout-trunk:28] ExecIf("SIP/100-00000028", "1?Set(CONNECTEDLINE(num,i)=90********)") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-dialout-trunk:29] ExecIf("SIP/100-00000028", "1?Set(CONNECTEDLINE(name,i)=CID:100)") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-dialout-trunk:30] ExecIf("SIP/100-00000028", "0?Set(CONNECTEDLINE(name,i)=CID:(Hidden)100)") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-dialout-trunk:31] GotoIf("SIP/100-00000028", "0?customtrunk") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-dialout-trunk:32] Dial("SIP/100-00000028", "SIP/tr-34********/90********,300,Tb(func-apply-sipheaders^s^1,(3))") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] netsock2.c: Using SIP RTP TOS bits 184 [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] netsock2.c: Using SIP RTP CoS mark 5 [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] app_stack.c: SIP/tr-34********-00000029 Internal Gosub(func-apply-sipheaders,s,1(3)) start [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf("SIP/tr-34********-00000029", "0?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp("SIP/tr-34********-00000029", "Applying SIP Headers to channel SIP/tr-34********-00000029") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@func-apply-sipheaders:3] Set("SIP/tr-34********-00000029", "TECH=SIP") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@func-apply-sipheaders:4] Set("SIP/tr-34********-00000029", "SIPHEADERKEYS=") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@func-apply-sipheaders:5] While("SIP/tr-34********-00000029", "0") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] app_while.c: Jumping to priority 11 [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@func-apply-sipheaders:12] Return("SIP/tr-34********-00000029", "") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] app_stack.c: Spawn extension (from-trunk-sip-tr-34********, 890********, 1) exited non-zero on 'SIP/tr-34********-00000029' [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] app_stack.c: SIP/tr-34********-00000029 Internal Gosub(func-apply-sipheaders,s,1(3)) complete GOSUB_RETVAL= [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] app_dial.c: Called SIP/tr-34********/90******** [2019-09-12 20:03:04] VERBOSE[2682][C-00000015] chan_sip.c: Got SIP response 500 "Server Internal Error" back from 10.193.0.1:5060 [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] app_dial.c: SIP/tr-34********-00000029 is circuit-busy [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] app_dial.c: Everyone is busy/congested at this time (1:0/1/0) [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-dialout-trunk:33] NoOp("SIP/100-00000028", "Dial failed for some reason with DIALSTATUS = CONGESTION and HANGUPCAUSE = 38") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-dialout-trunk:34] GotoIf("SIP/100-00000028", "0?continue,1:s-CONGESTION,1") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx_builtins.c: Goto (macro-dialout-trunk,s-CONGESTION,1) [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s-CONGESTION@macro-dialout-trunk:1] Set("SIP/100-00000028", "RC=38") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s-CONGESTION@macro-dialout-trunk:2] Goto("SIP/100-00000028", "38,1") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx_builtins.c: Goto (macro-dialout-trunk,38,1) [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [38@macro-dialout-trunk:1] Goto("SIP/100-00000028", "continue,1") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx_builtins.c: Goto (macro-dialout-trunk,continue,1) [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [continue@macro-dialout-trunk:1] NoOp("SIP/100-00000028", "TRUNK Dial failed due to CONGESTION HANGUPCAUSE: 38 - failing through to other trunks") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [continue@macro-dialout-trunk:2] ExecIf("SIP/100-00000028", "1?Set(CALLERID(number)=100)") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [890********@from-internal:8] Macro("SIP/100-00000028", "outisbusy,") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-outisbusy:1] Progress("SIP/100-00000028", "") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-outisbusy:2] GotoIf("SIP/100-00000028", "0?emergency,1") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-outisbusy:3] GotoIf("SIP/100-00000028", "0?intracompany,1") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-outisbusy:4] Playback("SIP/100-00000028", "all-circuits-busy-now&please-try-call-later, noanswer") in new stack [2019-09-12 20:03:04] VERBOSE[25429][C-00000015] file.c: <SIP/100-00000028> Playing 'all-circuits-busy-now.ulaw' (language 'ru') [2019-09-12 20:03:06] VERBOSE[25429][C-00000015] file.c: <SIP/100-00000028> Playing 'please-try-call-later.ulaw' (language 'ru') [2019-09-12 20:03:06] VERBOSE[25429][C-00000015] pbx.c: Executing [h@from-internal:1] Macro("SIP/100-00000028", "hangupcall") in new stack [2019-09-12 20:03:06] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("SIP/100-00000028", "1?theend") in new stack [2019-09-12 20:03:06] VERBOSE[25429][C-00000015] pbx_builtins.c: Goto (macro-hangupcall,s,3) [2019-09-12 20:03:06] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("SIP/100-00000028", "0?Set(CDR(recordingfile)=)") in new stack [2019-09-12 20:03:06] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-hangupcall:4] NoOp("SIP/100-00000028", " montior file= /var/spool/asterisk/monitor/2019/09/12/out-890********-100-20190912-200304-1568300584.40.wav") in new stack [2019-09-12 20:03:06] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-hangupcall:5] GotoIf("SIP/100-00000028", "1?skipagi") in new stack [2019-09-12 20:03:06] VERBOSE[25429][C-00000015] pbx_builtins.c: Goto (macro-hangupcall,s,7) [2019-09-12 20:03:06] VERBOSE[25429][C-00000015] pbx.c: Executing [s@macro-hangupcall:7] Hangup("SIP/100-00000028", "") in new stack [2019-09-12 20:03:06] VERBOSE[25429][C-00000015] app_macro.c: Spawn extension (macro-hangupcall, s, 7) exited non-zero on 'SIP/100-00000028' in macro 'hangupcall' [2019-09-12 20:03:06] VERBOSE[25429][C-00000015] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/100-00000028' [2019-09-12 20:03:06] VERBOSE[25429][C-00000015] app_stack.c: SIP/100-00000028 Internal Gosub(crm-hangup,s,1) start [2019-09-12 20:03:06] VERBOSE[25429][C-00000015] pbx.c: Executing [s@crm-hangup:1] NoOp("SIP/100-00000028", "Sending Hangup to CRM") in new stack [2019-09-12 20:03:06] VERBOSE[25429][C-00000015] pbx.c: Executing [s@crm-hangup:2] NoOp("SIP/100-00000028", "HANGUP CAUSE: 38") in new stack [2019-09-12 20:03:06] VERBOSE[25429][C-00000015] pbx.c: Executing [s@crm-hangup:3] ExecIf("SIP/100-00000028", "0?Set(__CRM_VOICEMAIL=)") in new stack [2019-09-12 20:03:06] VERBOSE[25429][C-00000015] pbx.c: Executing [s@crm-hangup:4] NoOp("SIP/100-00000028", "MASTER CHANNEL: 1568300584.40 = 1568300584.40") in new stack [2019-09-12 20:03:06] VERBOSE[25429][C-00000015] pbx.c: Executing [s@crm-hangup:5] GotoIf("SIP/100-00000028", "0?return") in new stack [2019-09-12 20:03:06] VERBOSE[25429][C-00000015] pbx.c: Executing [s@crm-hangup:6] Set("SIP/100-00000028", "__CRM_HANGUP=1") in new stack [2019-09-12 20:03:06] VERBOSE[25429][C-00000015] pbx.c: Executing [s@crm-hangup:7] AGI("SIP/100-00000028", "sangomacrm.agi") in new stack [2019-09-12 20:03:06] VERBOSE[25429][C-00000015] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/sangomacrm.agi [2019-09-12 20:03:06] VERBOSE[25429][C-00000015] res_agi.c: <SIP/100-00000028>AGI Script sangomacrm.agi completed, returning 0 [2019-09-12 20:03:06] VERBOSE[25429][C-00000015] pbx.c: Executing [s@crm-hangup:8] Return("SIP/100-00000028", "") in new stack [2019-09-12 20:03:06] VERBOSE[25429][C-00000015] app_stack.c: Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/100-00000028' [2019-09-12 20:03:06] VERBOSE[25429][C-00000015] app_stack.c: SIP/100-00000028 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL= [2019-09-12 20:03:06] VERBOSE[25430][C-00000015] app_mixmonitor.c: MixMonitor close filestream (mixed) [2019-09-12 20:03:06] VERBOSE[25430][C-00000015] app_mixmonitor.c: End MixMonitor Recording SIP/100-00000028 [2019-09-12 20:03:22] VERBOSE[2682][C-00000016] netsock2.c: Using SIP RTP TOS bits 184 [2019-09-12 20:03:22] VERBOSE[2682][C-00000016] netsock2.c: Using SIP RTP CoS mark

Источник

Содержание

Asterisk как SIP-клиент: то 500, то 504
Asterisk как SIP-клиент: то 500, то 504
Re: Asterisk как SIP-клиент: то 500, то 504
Re: Asterisk как SIP-клиент: то 500, то 504
Re: Asterisk как SIP-клиент: то 500, то 504
Re: Asterisk как SIP-клиент: то 500, то 504
SIP ответы и их значения
1xx – информационные ответы
2xx – ответы о завершении запроса
3xx – сообщения о переадресации
4xx – невозможность обработать запрос
5xx – ошибки сервера
6xx – глобальная ошибка

Asterisk как SIP-клиент: то 500, то 504

Подключаю Asterisk как SIP-клиент к станции Huawei SoftX3000, но почему-то не получается.
Про silenceSupp читал уже, закомментировал строчку в исходниках и пересобрал. Больше silenceSupp в пакете нет, но лучше не стало.
NAT-а нет между Asterisk (x.x.x.21) и SoftX3000 (x.x.x.5), как сказал админ тамошний.
Насчёт debug: если честно, то не совсем понимаю его: слишком много всего. Надеюсь, что удастся разобраться с вашей помощью. Вот я отключил register, указал sipdebug=yes, выгнал с Asterisk всех пиров. Перезапустил Asterisk. Вижу сразу такие сообщения:

Дальше это всё повторяется. Куча вопросов в связи с этим небольшим кусочком дебагом:
Это нормально, что он отправляет пакеты даже когда я никуда не звоню с него?
Почему сразу OPTIONS, а где же INVITE? Этот Asterisk умеет «помнить» между перезапусками? Это можно как-то сбросить?
Откуда можно почерпнуть подробности о причине Server Internal Error? Мне говорят, что на SoftX вроде как ничего нету. Есть повод этому не верить?
Как я понимаю, дело отнюдь не в регистрации. Если бы мешало именно её отстуствие, то SoftX как-то иначе бы ответил, 4xx, например Unauthorized или Forbidden. Не?

—
Заранее спасибо! Уже второй день гуглю и читаю, но что-то всё на другие проблемы натыкаюсь вроде NAT. Очень надеюсь на вашу помощь!

Re: Asterisk как SIP-клиент: то 500, то 504

Да вы просто ужасно вежливы. Ну да ладно, будем считать, что все устали.

Нет, за два дня гугления не удалось понять, почему он с хуавеем не хочет общаться и в какую сторону копать. Вообще Asterisk я настроил, диалплан кое-какой написал, прикрутил app_konference для конференций. Так что уже месяц примерно ковыряюсь. Теперь осталось только прицепить Asterisk к SIP-станции провайдера, а вот тут что-то никак не получается.

К сожалению, нету такой возможности. Вообще говоря, я занимаюсь не своим делом: у меня есть опыт администрирования под UNIX, но не Asterisk. Тем не менее так уж оно вышло.

Ага, спасибо. Т.е. это нормально что SoftX отвечает 500 на неожиданные запросы?

ОК, выключил qualify и попробовал позвонить с Asterisk:

Может быть, подскажете, что я делаю не так и где искать проблему?

Re: Asterisk как SIP-клиент: то 500, то 504

Проблему искать везде.
Звоните с номера 270084 на Астериске на sip:999999@x.x.x.5 — то есть точно знаете, что на Х..вэе (плохо у них с названием) есть такой номер — 999999?

И он, Х..вэй, отвечает Вам SIP/2.0 500 Server Internal Error
Спросите об этом админа, что в Х.. доме живёт
Он с этим ответом ложится,
Он с этим ответом встаёт.

Re: Asterisk как SIP-клиент: то 500, то 504

Да, номер такой есть. Если я подключаюсь к 270084 с софтфона и звоню на 999999, то всё работает.

Админ не в состоянии ответить на вопрос «почему 500». Я от него не могу добиться даже ответа, есть ли на SoftX логи, в которых было бы описано, почему 500. А они есть там на самом деле?

Админ также настаивает, что должен быть сначала пакет REGISTER, а уж потом всё остальное. ОК, включил регистрацию и вижу вот такое общение Asterisk и SoftX:
======================================================================
conf*CLI> sip reload
[Aug 26 20:54:00] NOTICE[40947]: chan_sip.c:10016 sip_reregister: — Re-registration for 270084@x.x.x.5
REGISTER 12 headers, 0 lines
Reliably Transmitting (no NAT) to x.x.x.5:5060:
REGISTER sip:x.x.x.5 SIP/2.0
Via: SIP/2.0/UDP x.x.x.21:5060;branch=z9hG4bK6481ec21;rport
Max-Forwards: 70
From: ;tag=as0f80a293
To:
Call-ID: 5fc2292821632b9a4e0cabf94325e140@x.x.x.21
CSeq: 102 REGISTER
User-Agent: Asterisk PBX 1.6.0.21
Expires: 120
Contact:
Event: registration
Content-Length: 0

—
Retransmitting #1 (no NAT) to x.x.x.5:5060:
REGISTER sip:x.x.x.5 SIP/2.0
Via: SIP/2.0/UDP x.x.x.21:5060;branch=z9hG4bK6481ec21;rport
Max-Forwards: 70
From: ;tag=as0f80a293
To:
Call-ID: 5fc2292821632b9a4e0cabf94325e140@x.x.x.21
CSeq: 102 REGISTER
User-Agent: Asterisk PBX 1.6.0.21
Expires: 120
Contact:
Event: registration
Content-Length: 0

—

hello

Retransmitting #2 (no NAT) to x.x.x.5:5060:
REGISTER sip:x.x.x.5 SIP/2.0
Via: SIP/2.0/UDP x.x.x.21:5060;branch=z9hG4bK6481ec21;rport
Max-Forwards: 70
From: ;tag=as0f80a293
To:
Call-ID: 5fc2292821632b9a4e0cabf94325e140@x.x.x.21
CSeq: 102 REGISTER
User-Agent: Asterisk PBX 1.6.0.21
Expires: 120
Contact:
Event: registration
Content-Length: 0

—
Retransmitting #3 (no NAT) to x.x.x.5:5060:
REGISTER sip:x.x.x.5 SIP/2.0
Via: SIP/2.0/UDP x.x.x.21:5060;branch=z9hG4bK6481ec21;rport
Max-Forwards: 70
From: ;tag=as0f80a293
To:
Call-ID: 5fc2292821632b9a4e0cabf94325e140@x.x.x.21
CSeq: 102 REGISTER
User-Agent: Asterisk PBX 1.6.0.21
Expires: 120
Contact:
Event: registration
Content-Length: 0

—
Retransmitting #4 (no NAT) to x.x.x.5:5060:
REGISTER sip:x.x.x.5 SIP/2.0
Via: SIP/2.0/UDP x.x.x.21:5060;branch=z9hG4bK6481ec21;rport
Max-Forwards: 70
From: ;tag=as0f80a293
To:
Call-ID: 5fc2292821632b9a4e0cabf94325e140@x.x.x.21
CSeq: 102 REGISTER
User-Agent: Asterisk PBX 1.6.0.21
Expires: 120
Contact:
Event: registration
Content-Length: 0

—
Retransmitting #5 (no NAT) to x.x.x.5:5060:
REGISTER sip:x.x.x.5 SIP/2.0
Via: SIP/2.0/UDP x.x.x.21:5060;branch=z9hG4bK6481ec21;rport
Max-Forwards: 70
From: ;tag=as0f80a293
To:
Call-ID: 5fc2292821632b9a4e0cabf94325e140@x.x.x.21
CSeq: 102 REGISTER
User-Agent: Asterisk PBX 1.6.0.21
Expires: 120
Contact:
Event: registration
Content-Length: 0

—
Retransmitting #6 (no NAT) to x.x.x.5:5060:
REGISTER sip:x.x.x.5 SIP/2.0
Via: SIP/2.0/UDP x.x.x.21:5060;branch=z9hG4bK6481ec21;rport
Max-Forwards: 70
From: ;tag=as0f80a293
To:
Call-ID: 5fc2292821632b9a4e0cabf94325e140@x.x.x.21
CSeq: 102 REGISTER
User-Agent: Asterisk PBX 1.6.0.21
Expires: 120
Contact:
Event: registration
Content-Length: 0

hello

SIP/2.0 504 Server Time-out
Via: SIP/2.0/UDP x.x.x.21:5060;branch=z9hG4bK6481ec21;rport=5060
Call-ID: 5fc2292821632b9a4e0cabf94325e140@x.x.x.21
From: ;tag=as0f80a293
To: ;tag=6adc00e0
CSeq: 102 REGISTER
Content-Length: 0

Я выделил жирным те места, которые меня заинтересовали.
Первое: что это за ответ «hello»? В RFC я такого не нашёл.
Второе: весьма подозрительное предупреждение. В указанной доке идёт речь лишь о firewall. Но раз и SoftX, и Asterisk по отдельности работают без проблем, то дело не в нём, ведь так?
Третье: т.е. Asterisk должен был как-то среагировать на hello и ответить? но он не ответил и SoftX выдал 504?

Я тут уже раньше натыкался на такую проблему на этом форуме (Huawei softx3000 + asterisk (504 Server timeout)), но там проблема решилась авторизацией по IP (вот). А есть другие варианты?

Источник

SIP ответы и их значения

1xx – информационные ответы

SIP/2.0 100 Trying
Запрос обрабатывается.

SIP/2.0 180 Ringing
Местоположение вызываемого пользователя определено. Выдан сигнал о входящем вызове.

SIP/2.0 181 Call is Being Forwarded
Прокси-сервер переадресует вызов к другому пользователю.

SIP/2.0 182 Call is Queued
Вызываемый абонент временно не доступен. вызов поставлен в очередь.

SIP/2.0 183 Session Progress
Используется для того, чтобы заранее получить описание сеанса информационного обмена от шлюзов на пути к вызываемому пользователю.

2xx – ответы о завершении запроса

SIP/2.0 200 OK
Успешное завершение.

SIP/2.0 202 Accepted
Запрос принят для обработки. Используется для справки о состоянии обработки.

3xx – сообщения о переадресации

SIP/2.0 300 Multiple Choices
Указывает несколько SIP-адресов, по которым можно найти вызываемого пользователя.

SIP/2.0 301 Moved Permanently
Вызываемый пользователь больше не находится по адресу, указанному в запросе.

SIP/2.0 302 Moved Temporarily
Пользователь временно сменил местоположение. (Настроена переадресация по SIPUA в т.ч. с VOIP-телефона)

SIP/2.0 305 Use Proxy
Вызываемый пользователь не доступен непосредственно. Входящий вызов должен пройти через прокси-сервер.

SIP/2.0 380 Alternative Service
Запрошенная услуга недоступна, но доступны альтернативные услуги.

4xx – невозможность обработать запрос

SIP/2.0 400 Bad Request
Запрос не распознан из-за синтаксических ошибок или ошибок в сигнализации.

SIP/2.0 401 Unauthorized
Нормальный ответ сервера о том, что пользователь еще не авторизовался. Обычно после этого абонентское оборудование отправляет на сервер новый запрос, содержащий логин и пароль.

SIP/2.0 401 AUTH Error: Stall nonce
1.Разные данные в поле NONCE (шифр пароля), проверить дату/время или проблема с протоколом шифрования
2. П роверить на клиентской стороне не заблокирован ли sipnet.ru (212.53.40.40)
3. Проверить в ВАТС статус присутствия. Должен быть «нет».

SIP/2.0 401 Expired Authorization
Время регистрации истекло.

SIP/2.0 402 Payment Required
Требуется оплата (зарезервирован для использования в будущем).

SIP/2.0 403 No Such User
Нет такого пользователя. Ошибка в номере, логине или пароле.

SIP/2.0 403 No license available
Кончились лицензия на SIP

SIP/2.0 403 You
Нет такого пользователя. Ошибка в номере, логине или пароле.

SIP/2.0 403 User Disabled
Пользователь отключен.

SIP/2.0 403 You do not have the required right
Не верный логин в поле FROM

SIP/2.0 403 Wrong Guess
Ошибка в пароле.

SIP/2.0 403 Conflict
Такой SIP-номер уже используется.

SIP/2.0 403 Forbidden
Абонент не зарегистрирован.

SIP/2.0 403 Empty Route Set
Нет ни одного шлюза в роутинге.

SIP/2.0 403 Caller Not Registered
Нет такого пользователя.

SIP/2.0 403 Out of Look-Ahead Retries
Перебор узлов закончен.

SIP/2.0 403 Invalid Phone Number
Нет такого направления.

SIP/2.0 403 No Money Left on RFC Account
На счету не достаточно денежных средств для совершения вызова.

SIP/2.0 404 Not found
Вызываемый абонент не найден, нет такого SIP-номера.

SIP/2.0 404 Undefined Reason
Неопределенное направление.

SIP/2.0 404 Unknown user account
Логин и пароль не найдены.

SIP/2.0 404 Out of Order
В заявке на маршрутизацию по этому направлению нет принимающих шлюзов.

SIP/2.0 405 Method Not Allowed
Метод не поддерживается. Может возникать если пользователь пытается отправлять голосовую почту и т.п.

SIP/2.0 406 No codecs match
Неправильная конфигурация кодеков.

SIP/2.0 406 Not Acceptable
Пользователь не доступен.

SIP/2.0 407 Proxy Authentication Required
Необходима аутентификация на прокси-сервере.

SIP/2.0 407 User not found
Проверить ID на CGP

SIP/2.0 408 Request Timeout
Время обработки запроса истекло. Абонента не удалось найти за отведенное время. (Проблема с firewall, нет ответ на Invite от сервера)

SIP/2.0 408 Login timed out
За отведенное время не получен ответ от сервера на запрос авторизации.

SIP/2.0 410 No Route
Вариант «SIP/2.0 403 Empty Route Set». Нет доступа к ресурсу или ресурс по указанному адресу больше не существует.

SIP/2.0 413 Request Entity Too Large
Размер запроса слишком велик для обработки на сервере.

SIP/2.0 415 No Media
Звонок совершается неподдерживаемым кодеком.

SIP/2.0 416 Unsupported Scheme
Сервер не может обработать запрос из-за того, что схема адреса не распознана.

SIP/2.0 420 Bad extension
Неизвестное расширение. Сервер не распознал расширение протокола SIP.

SIP/2.0 421 Extension Required
В заголовке запроса не указано, какое расширение сервер должен применить для его обработки.

SIP/2.0 423 Interval Too Brief
Сервер отклоняет запрос, так как время действия ресурса короткое.

SIP/2.0 480 Invalid Phone Number
Неправильный номер телефона, не соответствует количество цифр или неправильный код страны или города.

SIP/2.0 480 Destination Not Found In Client Plan
Направления нет в тарифном плане абонента.

SIP/2.0 480 Wrong DB Response
Проблемы с центральной базой данных.

SIP/2.0 480 DB Timeout
Проблемы с центральной базой данных.

SIP/2.0 480 Database Error
Проблемы с центральной базой данных.

SIP/2.0 480 Codec Mismatch
Несоответствие кодеков.

SIP/2.0 480 No Money Left on RFC Account
Не достаточно денежных средств на счету.

SIP/2.0 480 Empty Route Set
Пустое направление. Нет принимающих шлюзов.

SIP/2.0 480 No money left
Не достаточно денежных средств на счету.

SIP/2.0 480 Temporarily Unavailable
Временно недоступное направление. (Возможно статус DND)

SIP/2.0 481 Call Leg/Transaction Does Not Exist
Действие не выполнено. Нормальный ответ при поступлении дублирующего пакета.

SIP/2.0 482 Loop Detected
Обнаружен замкнутый маршрут передачи запроса.

SIP/2.0 483 Too Many Hops
Запрос на своем пути прошел через большее число прокси-серверов, чем разрешено.

SIP/2.0 484 Address Incomplete
Принят запрос с неполным адресом.

SIP/2.0 485 Ambiguous
Адрес вызываемого пользователя не однозначен.

SIP/2.0 486 Busy Here
Абонент занят.

SIP/2.0 487 Request Terminated
Запрос отменен. Обычно приходит при отмене вызова.

SIP/2.0 488 Codec Mismatch
Нет шлюзов с поддержкой заказанного кодека.

SIP/2.0 488 Private IP Address
Адрес RTP media из сетей RFC1918.

SIP/2.0 488 Not acceptable here
Не совпадают кодеки

SIP/2.0 491 Request Pending
Запрос поступил в то время, когда сервер еще не закончил обработку другого запроса, относящегося к тому же диалогу.

SIP/2.0 493 Undeciperable
Сервер не в состоянии подобрать ключ дешифрования. Невозможно декодировать тело S/MIME сообщения.

SIP/2.0 499 Codec Mismatch
Отсутствует кодек.

5xx – ошибки сервера

SIP/2.0 500 Internal Server Error
Внутренняя ошибка сервера.

SIP/2.0 500 DB Timeout
Нет ответа от базы данных.

SIP/2.0 500 Database Error
То же самое, но в другой момент.

SIP/2.0 500 Wrong DB Response
Неправильный ответ базы данных.

SIP/2.0 500 Undefined Reason
Неопределенная причина.

SIP/2.0 500 account has been moved to a remote system
Аккаунт перенесен в удаленную систему (дословно).

SIP/2.0 500 Call placing quota exceeded
Превышен CPS.

SIP/2.0 501 Method Not Supported Here
В сервере не реализованы какие-либо функции, необходимые для обслуживания запроса. Метод запроса SIP не поддерживается.

SIP/2.0 502 Bad Gateway
Сервер, функционирующий в качестве шлюза или прокси-сервера, принимает некорректный ответ от сервера, к которому он направил запрос.

SIP/2.0 503 Service Unavailable
Сервер не может в данный момент обслужить вызов вследствие перегрузки или проведения технического обслуживания.

SIP/2.0 504 Server time-out
Сервер не получил ответа в течение установленного промежутка времени от сервера, к которому он обратился для завершения вызова.

SIP/2.0 505 SIP Version not supported
Версия не поддерживается. Сервер не поддерживает эту версию протокола SIP.

SIP/2.0 513 Message too big
Сервер не в состоянии обработать запрос из-за большой длины сообщения.

6xx – глобальная ошибка

SIP/2.0 600 Busy everywhere
Вызываемый пользователь занят и не желает принимать вызов в данный момент.

SIP/2.0 603 Decline
Вызываемый пользователь не желает принимать входящие вызовы, не указывая причину отказа.

SIP/2.0 604 Does Not Exist Anywhere
Вызываемого пользователя не существует.

SIP/2.0 606 Not Acceptable
Соединение с сервером было установлено. Отдельные параметры, такие как тип запрашиваемой информации, полоса пропускания, вид адресации не доступны.

Источник

Главная

Новости

Форум

Платы Digium

Русский IVR

Asterisk как SIP-клиент у SoftX3000: ошибки с сервера то 500, то 504

Откуда: Россия, Ульяновск

Сообщений: 5

Asterisk как SIP-клиент: то 500, то 504

Подключаю Asterisk как SIP-клиент к станции Huawei SoftX3000, но почему-то не получается.

Про silenceSupp читал уже, закомментировал строчку в исходниках и пересобрал. Больше silenceSupp в пакете нет, но лучше не стало.

NAT-а нет между Asterisk (x.x.x.21) и SoftX3000 (x.x.x.5), как сказал админ тамошний.

Насчёт debug: если честно, то не совсем понимаю его: слишком много всего. Надеюсь, что удастся разобраться с вашей помощью. Вот я отключил register, указал sipdebug=yes, выгнал с Asterisk всех пиров. Перезапустил Asterisk. Вижу сразу такие сообщения:



Reliably Transmitting (no NAT) to x.x.x.5:5060:

OPTIONS sip:x.x.x.5 SIP/2.0

Via: SIP/2.0/UDP x.x.x.21:5060;branch=z9hG4bK7dbebe3d;rport

Max-Forwards: 70

From: "Unknown" <sip:Unknown@x.x.x.21>;tag=as667d9eef

To: <sip:x.x.x.5>

Contact: <sip:Unknown@x.x.x.21>

Call-ID: 7aa9dda8060a901731de7b277ce6583c@x.x.x.21

CSeq: 102 OPTIONS

User-Agent: Asterisk PBX 1.6.0.21

Date: Thu, 26 Aug 2010 11:38:54 GMT

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO

Supported: replaces, timer

Content-Length: 0

<--- SIP read from UDP://x.x.x.5:5060 --->

SIP/2.0 500 Server Internal Error

Via: SIP/2.0/UDP x.x.x.21:5060;branch=z9hG4bK7dbebe3d;rport=5060

Call-ID: 7aa9dda8060a901731de7b277ce6583c@x.x.x.21

From: "Unknown"<sip:Unknown@x.x.x.21>;tag=as667d9eef

To: <sip:x.x.x.5>;tag=f0ab7116

CSeq: 102 OPTIONS

Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,REFER,SUBSCRIBE,NOTIFY,INFO

Date: Thu, 26 Aug 2010 11:38:54 GMT

User-Agent: Asterisk PBX 1.6.0.21

Content-Length: 0

Дальше это всё повторяется… Куча вопросов в связи с этим небольшим кусочком дебагом:

Это нормально, что он отправляет пакеты даже когда я никуда не звоню с него?

Почему сразу OPTIONS, а где же INVITE? Этот Asterisk умеет «помнить» между перезапусками? Это можно как-то сбросить?

Откуда можно почерпнуть подробности о причине Server Internal Error? Мне говорят, что на SoftX вроде как ничего нету. Есть повод этому не верить?

Как я понимаю, дело отнюдь не в регистрации. Если бы мешало именно её отстуствие, то SoftX как-то иначе бы ответил, 4xx, например Unauthorized или Forbidden. Не?

—

Заранее спасибо! Уже второй день гуглю и читаю, но что-то всё на другие проблемы натыкаюсь вроде NAT. Очень надеюсь на вашу помощь!

Сообщений: 6521

Re: Asterisk как SIP-клиент: то 500, то 504

kirikaza:

Заранее спасибо! Уже второй день гуглю и читаю

Ну надо же! За два дня гугления не удалось настроить Астериск?

Не пройден тест на профпригодность.

Пакеты OPTIONS посылаются для поддержания NAT соединений, которых у Вас нет, а посылаются потому что указано qualify=yes.

Лучше обратитесь к интеграторам, с таким минимальным багажом знания это будет похоже на индивидуальные курсы для даунов, решивших получить квалификацию VoIP administrator.

Откуда: Россия, Ульяновск

Сообщений: 5

Re: Asterisk как SIP-клиент: то 500, то 504

Да вы просто ужасно вежливы. Ну да ладно, будем считать, что все устали.

За два дня гугления не удалось настроить Астериск?

Лучше обратитесь к интеграторам

Пакеты OPTIONS посылаются для поддержания NAT соединений … qualify=yes.

Ага, спасибо. Т.е. это нормально что SoftX отвечает 500 на неожиданные запросы?

ОК, выключил qualify и попробовал позвонить с Asterisk:



conf*CLI> originate SIP/999999@huawei-270084 extension 501

Audio is at x.x.x.21 port 19138

Video is at x.x.x.21 port 11314

Adding codec 0x4 (ulaw) to SDP

Adding codec 0x8 (alaw) to SDP

Adding codec 0x2 (gsm) to SDP

Adding codec 0x200 (speex) to SDP

Adding codec 0x400 (ilbc) to SDP

Adding video codec 0x40000 (h261) to SDP

Adding video codec 0x80000 (h263) to SDP

Adding video codec 0x100000 (h263p) to SDP

Adding video codec 0x200000 (h264) to SDP

Reliably Transmitting (no NAT) to x.x.x.5:5060:

INVITE sip:999999@x.x.x.5 SIP/2.0

Via: SIP/2.0/UDP x.x.x.21:5060;branch=z9hG4bK48c14bd4;rport

Max-Forwards: 70

From: "Unknown" <sip:270084@x.x.x.21>;tag=as1382b2d2

To: <sip:999999@x.x.x.5>

Contact: <sip:270084@x.x.x.21>

Call-ID: 379919eb3ced7354667c65481a32d070@x.x.x.21

CSeq: 102 INVITE

User-Agent: Asterisk PBX 1.6.0.21

Date: Thu, 26 Aug 2010 12:38:57 GMT

Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO

Supported: replaces, timer

Content-Type: application/sdp

Content-Length: 464

v=0

o=root 1575691621 1575691621 IN IP4 x.x.x.21

s=Asterisk PBX 1.6.0.21

c=IN IP4 x.x.x.21

b=CT:384

t=0 0

m=audio 19138 RTP/AVP 0 8 3 110 97

a=rtpmap:0 PCMU/8000

a=rtpmap:8 PCMA/8000

a=rtpmap:3 GSM/8000

a=rtpmap:110 speex/8000

a=rtpmap:97 iLBC/8000

a=fmtp:97 mode=30

a=ptime:20

a=sendrecv

m=video 11314 RTP/AVP 31 34 98 99

a=rtpmap:31 H261/90000

a=rtpmap:34 H263/90000

a=rtpmap:98 h263-1998/90000

a=rtpmap:99 H264/90000

a=sendrecv

---

<--- SIP read from UDP://x.x.x.5:5060 --->

SIP/2.0 100 Trying

Via: SIP/2.0/UDP x.x.x.21:5060;branch=z9hG4bK48c14bd4;rport=5060

Call-ID: 379919eb3ced7354667c65481a32d070@x.x.x.21

From: "Unknown"<sip:270084@x.x.x.21>;tag=as1382b2d2

To: <sip:999999@x.x.x.5>

CSeq: 102 INVITE

Content-Length: 0




  


                       
  





<------------->

--- (7 headers 0 lines) ---

<--- SIP read from UDP://x.x.x.5:5060 --->

SIP/2.0 500 Server Internal Error

Via: SIP/2.0/UDP x.x.x.21:5060;branch=z9hG4bK48c14bd4;rport=5060

Call-ID: 379919eb3ced7354667c65481a32d070@x.x.x.21

From: "Unknown"<sip:270084@x.x.x.21>;tag=as1382b2d2

To: <sip:999999@x.x.x.5>;tag=efb0270d

CSeq: 102 INVITE

Content-Length: 0


<------------->

--- (7 headers 0 lines) ---

Transmitting (no NAT) to x.x.x.5:5060:

ACK sip:999999@x.x.x.5 SIP/2.0

Via: SIP/2.0/UDP x.x.x.21:5060;branch=z9hG4bK48c14bd4;rport

Max-Forwards: 70

From: "Unknown" <sip:270084@x.x.x.21>;tag=as1382b2d2

To: <sip:999999@x.x.x.5>;tag=efb0270d

Contact: <sip:270084@x.x.x.21>

Call-ID: 379919eb3ced7354667c65481a32d070@x.x.x.21

CSeq: 102 ACK

User-Agent: Asterisk PBX 1.6.0.21

Content-Length: 0


---

Really destroying SIP dialog '379919eb3ced7354667c65481a32d070@x.x.x.21' Method: INVITE

Может быть, подскажете, что я делаю не так и где искать проблему?

Сообщений: 6521

Re: Asterisk как SIP-клиент: то 500, то 504

Проблему искать везде.

Звоните с номера 270084 на Астериске на sip:999999@x.x.x.5 — то есть точно знаете, что на Х..вэе (плохо у них с названием) есть такой номер — 999999?

И он, Х..вэй, отвечает Вам SIP/2.0 500 Server Internal Error

Спросите об этом админа, что в Х.. доме живёт

Он с этим ответом ложится,

Он с этим ответом встаёт.

Откуда: Россия, Ульяновск

Сообщений: 5

Re: Asterisk как SIP-клиент: то 500, то 504

есть такой номер — 999999?

Да, номер такой есть. Если я подключаюсь к 270084 с софтфона и звоню на 999999, то всё работает.

И он, Х..вэй, отвечает Вам SIP/2.0 500 Server Internal Error

Спросите об этом админа, что в Х.. доме живёт

Админ также настаивает, что должен быть сначала пакет REGISTER, а уж потом всё остальное. ОК, включил регистрацию и вижу вот такое общение Asterisk и SoftX:

======================================================================

conf*CLI> sip reload

[Aug 26 20:54:00] NOTICE[40947]: chan_sip.c:10016 sip_reregister: — Re-registration for 270084@x.x.x.5

REGISTER 12 headers, 0 lines

Reliably Transmitting (no NAT) to x.x.x.5:5060:

REGISTER sip:x.x.x.5 SIP/2.0

Via: SIP/2.0/UDP x.x.x.21:5060;branch=z9hG4bK6481ec21;rport

Max-Forwards: 70

From: <sip:270084@x.x.x.5>;tag=as0f80a293

To: <sip:270084@x.x.x.5>

Call-ID: 5fc2292821632b9a4e0cabf94325e140@x.x.x.21

CSeq: 102 REGISTER

User-Agent: Asterisk PBX 1.6.0.21

Expires: 120

Contact: <sip:0084@x.x.x.21>

Event: registration

Content-Length: 0

—

Retransmitting #1 (no NAT) to x.x.x.5:5060:

REGISTER sip:x.x.x.5 SIP/2.0

Via: SIP/2.0/UDP x.x.x.21:5060;branch=z9hG4bK6481ec21;rport

Max-Forwards: 70

From: <sip:270084@x.x.x.5>;tag=as0f80a293

To: <sip:270084@x.x.x.5>

Call-ID: 5fc2292821632b9a4e0cabf94325e140@x.x.x.21

CSeq: 102 REGISTER

User-Agent: Asterisk PBX 1.6.0.21

Expires: 120

Contact: <sip:0084@x.x.x.21>

Event: registration

Content-Length: 0

—

<— SIP read from UDP://x.x.x.5:5060 —>

hello

<————->

Retransmitting #2 (no NAT) to x.x.x.5:5060:

REGISTER sip:x.x.x.5 SIP/2.0

Via: SIP/2.0/UDP x.x.x.21:5060;branch=z9hG4bK6481ec21;rport

Max-Forwards: 70

From: <sip:270084@x.x.x.5>;tag=as0f80a293

To: <sip:270084@x.x.x.5>

Call-ID: 5fc2292821632b9a4e0cabf94325e140@x.x.x.21

CSeq: 102 REGISTER

User-Agent: Asterisk PBX 1.6.0.21

Expires: 120

Contact: <sip:0084@x.x.x.21>

Event: registration

Content-Length: 0

—

Retransmitting #3 (no NAT) to x.x.x.5:5060:

REGISTER sip:x.x.x.5 SIP/2.0

Via: SIP/2.0/UDP x.x.x.21:5060;branch=z9hG4bK6481ec21;rport

Max-Forwards: 70

From: <sip:270084@x.x.x.5>;tag=as0f80a293

To: <sip:270084@x.x.x.5>

Call-ID: 5fc2292821632b9a4e0cabf94325e140@x.x.x.21

CSeq: 102 REGISTER

User-Agent: Asterisk PBX 1.6.0.21

Expires: 120

Contact: <sip:0084@x.x.x.21>

Event: registration

Content-Length: 0

—

Retransmitting #4 (no NAT) to x.x.x.5:5060:

REGISTER sip:x.x.x.5 SIP/2.0

Via: SIP/2.0/UDP x.x.x.21:5060;branch=z9hG4bK6481ec21;rport

Max-Forwards: 70

From: <sip:270084@x.x.x.5>;tag=as0f80a293

To: <sip:270084@x.x.x.5>

Call-ID: 5fc2292821632b9a4e0cabf94325e140@x.x.x.21

CSeq: 102 REGISTER

User-Agent: Asterisk PBX 1.6.0.21

Expires: 120

Contact: <sip:0084@x.x.x.21>

Event: registration

Content-Length: 0

—

Retransmitting #5 (no NAT) to x.x.x.5:5060:

REGISTER sip:x.x.x.5 SIP/2.0

Via: SIP/2.0/UDP x.x.x.21:5060;branch=z9hG4bK6481ec21;rport

Max-Forwards: 70

From: <sip:270084@x.x.x.5>;tag=as0f80a293

To: <sip:270084@x.x.x.5>

Call-ID: 5fc2292821632b9a4e0cabf94325e140@x.x.x.21

CSeq: 102 REGISTER

User-Agent: Asterisk PBX 1.6.0.21

Expires: 120

Contact: <sip:0084@x.x.x.21>

Event: registration

Content-Length: 0

—

Retransmitting #6 (no NAT) to x.x.x.5:5060:

REGISTER sip:x.x.x.5 SIP/2.0

Via: SIP/2.0/UDP x.x.x.21:5060;branch=z9hG4bK6481ec21;rport

Max-Forwards: 70

From: <sip:270084@x.x.x.5>;tag=as0f80a293

To: <sip:270084@x.x.x.5>

Call-ID: 5fc2292821632b9a4e0cabf94325e140@x.x.x.21

CSeq: 102 REGISTER

User-Agent: Asterisk PBX 1.6.0.21

Expires: 120

Contact: <sip:0084@x.x.x.21>

Event: registration

Content-Length: 0

—

[Aug 26 20:54:20] WARNING[40947]: chan_sip.c:2959 retrans_pkt: Maximum retries exceeded on transmission 5fc2292821632b9a4e0cabf94325e140@x.x.x.21 for seqno 102 (Critical Request) — See doc/sip-retransmit.txt.

<— SIP read from UDP://x.x.x.5:5060 —>

hello

<————->

<— SIP read from UDP://x.x.x.5:5060 —>

hello

<————->

<— SIP read from UDP://x.x.x.5:5060 —>

SIP/2.0 504 Server Time-out

Via: SIP/2.0/UDP x.x.x.21:5060;branch=z9hG4bK6481ec21;rport=5060

Call-ID: 5fc2292821632b9a4e0cabf94325e140@x.x.x.21

From: <sip:270084@x.x.x.5>;tag=as0f80a293

To: <sip:270084@x.x.x.5>;tag=6adc00e0

CSeq: 102 REGISTER

Content-Length: 0

<————->

— (7 headers 0 lines) —

<— SIP read from UDP://x.x.x.5:5060 —>

hello

затем так много раз

==========================================================================

Я выделил жирным те места, которые меня заинтересовали.

Первое: что это за ответ «hello»? В RFC я такого не нашёл.

Второе: весьма подозрительное предупреждение. В указанной доке идёт речь лишь о firewall. Но раз и SoftX, и Asterisk по отдельности работают без проблем, то дело не в нём, ведь так?

Третье: т.е. Asterisk должен был как-то среагировать на hello и ответить? но он не ответил и SoftX выдал 504?

Спасибо за внимание!!

Сообщений: 6521

Re: Asterisk как SIP-клиент: то 500, то 504

Asterisk посылает REGISTER, и не дождавшись ответа перепосылает REGISTER

Retransmitting #1

потом ещё раз

Retransmitting #2

и ещё

Retransmitting #3

и так 6 раз. После чего говорит Maximum retries exceeded.

Это может быть вызвано разными причинами. Как Вы регистрируете телефон на Huawei softx3000? Поточнее пожалуйста!

Если есть возможность — выцепить REGISTER и ответ если регистрировать софтфон с 270084 и mysuperpassword

Должно быть что-то типа user — 270084 & secret — mysuperpassword

И тогда строка регистрации на Астериске должна быть

register => 270084:mysuperpassword@10.11.12.5/270084

Expires: 120 — очень мало, дефолтное значение порядка 3600.

И почему

Contact: <sip:0084@x.x.x.21>

если должно быть Contact: <sip:270084@x.x.x.21> ??

Передайте админу, что для исходящих регистрация не нужна.

Админ не прошёл тест на профпригодность. Лог есть однозначно. Но он не знает где его искать.

Откуда: Россия, Ульяновск

Сообщений: 5

Re: Asterisk как SIP-клиент: то 500, то 504

Как Вы регистрируете телефон на Huawei softx3000? Поточнее пожалуйста!

Админ мне выцепил регистрацию телефона (мне всё прилетело одной строкой, поэтому форматировал я вручную — может, где слепил две строчки):

=====================================================================

REGISTER sip:y.y.y.4:5060;user=phone SIP/2.0

Via: SIP/2.0/UDP y.y.y.254:28203;branch=z9hG4bK1789d5f02dd1a157cbd968bfd

Call-ID: SBCtsKqv_PZoIcP-0efVrrTaiPIj788RU

From: ;tag=4bcc64b0

To:

CSeq: 1079 REGISTER

Max-Forwards: 70

User-Agent: Nokia RM-159 V 11.0.026

Contact: ;expires=3600

Allow: INVITE,ACK,CANCEL,OPTIONS,BYE,REGISTER,PRACK,INFO,UPDATE,SUBSCRIBE,NOTIFY,MESSAGE,REFER,PUBLISH

Accept: application/sdp

Content-Length: 0

SIP/2.0 200 OK

Via: SIP/2.0/UDP y.y.y.254:28203;branch=z9hG4bK1789d5f02dd1a157cbd968bfd

Call-ID: SBCtsKqv_PZoIcP-0efVrrTaiPIj788RU

From: ;tag=4bcc64b0

To: ;tag=bce8eccf

CSeq: 1079 REGISTER

Expires: 3600

Contact: ;expires=3600

Server: Huawei SoftX3000 V300R601

Content-Length: 0

=====================================================================

Expires: 120 — очень мало, дефолтное значение порядка 3600.

Да, я тоже обратил на это внимание, но тогда ещё не нашёл настройку defaultexpiry, ибо искал её среди peer specific options. Правда, я подумал, что 120 секунд это более чем достаточно для общения сервера на FreeBSD и Huawei-ной станции, находящихся в одном сегменте сети… Оказалось, мои догадки были неверны. Сейчас поставил 3600 — ура! Ответ сменился на Unauthorized! Спасибо!!

А это удалось решить буквально первой же ссылкой в гугле: нужен pedantic=yes. О, чудо! Авторизация удалась! `sip show registry` говорит «Registered». Удалось позвонить с Asterisk на 999999! О, как я счастлив!

И почему
Contact: <sip:0084@x.x.x.21>
если должно быть
Contact: <sip:270084@x.x.x.21>

Ну это я так задал правило регистрации:

register => 270084:password@x.x.x.5/0084

Я понял из доков, что таким образом смогу сделать extension для входящих вызовов равный 0084. Но фокус не прошёл. Запрос REGISTER уходит c 0084 в заголовке Contact, а ответ OK приходит с 270084 в Contact. Видимо, SoftX игнорирует предложение использовать 0084. Но это не столь важно, extensions.ael я знаю как править

ded, огромное спасибо за время и терпение!! Я уже был в отчаянии пытаясь добиться от трёх работников провайдера хоть чего-то. Для меня форум был уже последней надеждой и Вы меня очень выручили!! Ещё раз спасибо!!

Сообщений: 6521

Re: Asterisk как SIP-клиент: то 500, то 504

Это потому что я крут невыразимо! Кто такие безвестные работники провайдера? Кто их знает в мире VoIP? И кто я?

Можно было вместо pedantic=yes поставить на пире usereqphone=yes

Я так думаю!

Откуда: SPb

Сообщений: 1307

Re: Asterisk как SIP-клиент: то 500, то 504

Почему мне так кажется , что это разговор про Арктел?

Сообщений: 6521

Re: Asterisk как SIP-клиент: то 500, то 504

zzuz, думаю что нет. То, как Арктел подаёт DIDs клиентам и какими методами ограничивает канальность входящих и исходящих — просто книга Necronomicon.

Добавить страницу в закладки:

Google

Yahoo

Digg

Smi2.ru

Vaau.ru

Источник

The configuration is the same as the previously showed.

Asterisk: 190.228.46.85:5060
MCUWEB: 190.228.46.85:5070
Softphone: 200.43.166.254

Next, I will show you packet sniff, thanks in advance:

######### 1. I atach you a .pcap generated with tcpdump in the server:

http://www.4shared.com/file/x0ZQRw5z/mcu.html

Is the output for: tcpdump -vv -s 1600 -w prueba.pcap host 190.228.46.85

######### 2. Asterisk cli — sip packets

intercity-VirtualBox*CLI> sip set debug ip 190.228.46.85
Audio is at 190.228.46.85 port 15894
Video is at 190.228.46.85 port 11476
Adding codec 0x4 (ulaw) to SDP
Adding codec 0x8 (alaw) to SDP
Adding video codec 0x100000 (h263p) to SDP
Adding non-codec 0x1 (telephone-event) to SDP
Reliably Transmitting (no NAT) to 190.228.46.85:5070:
INVITE sip:333@190.228.46.85:5070 SIP/2.0
Via: SIP/2.0/UDP 190.228.46.85:5060;branch=z9hG4bK6a87b29c;rport
Max-Forwards: 70
From: «6000» sip:6000@190.228.46.85;tag=as633d502e
To: sip:333@190.228.46.85:5070
Contact: sip:6000@190.228.46.85
Call-ID: 5999fad01f49f20b0ed05efd1f3c51c8@190.228.46.85
CSeq: 102 INVITE
User-Agent: Asterisk PBX 1.6.2.9-2ubuntu2.1
Date: Fri, 17 Feb 2012 20:51:27 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO
Supported: replaces, timer
Content-Type: application/sdp
Content-Length: 351

v=0
o=root 1605813520 1605813520 IN IP4 190.228.46.85
s=Asterisk PBX 1.6.2.9-2ubuntu2.1
c=IN IP4 190.228.46.85
b=CT:2048
t=0 0
m=audio 15894 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
m=video 11476 RTP/AVP 98
a=rtpmap:98 h263-1998/90000
a=sendrecv

<— SIP read from UDP:190.228.46.85:5070 —>
SIP/2.0 100 Trying
Content-Length: 0
To: sip:333@190.228.46.85:5070
Cseq: 102 INVITE
Via: SIP/2.0/UDP
190.228.46.85:5060;branch=z9hG4bK6a87b29c;rport=5060;received=190.228.46.85
Server: Glassfish_SIP_2.0.0
Call-Id: 5999fad01f49f20b0ed05efd1f3c51c8@190.228.46.85
From: «6000» sip:6000@190.228.46.85;tag=as633d502e

<————->
— (8 headers 0 lines) —

<— SIP read from UDP:190.228.46.85:5070 —>
SIP/2.0 180 Ringing
Content-Length: 0
To: sip:333@190.228.46.85:5070;tag=gyrp1tro-2
Contact: <sip:190.228.46.85:5060;fid=server_1>
Cseq: 102 INVITE
Via: SIP/2.0/UDP
190.228.46.85:5060;branch=z9hG4bK6a87b29c;rport=5060;received=190.228.46.85
From: «6000»sip:6000@190.228.46.85;tag=as633d502e
Call-Id: 5999fad01f49f20b0ed05efd1f3c51c8@190.228.46.85
Server: Glassfish_SIP_2.0.0

<————->
— (9 headers 0 lines) —

<— SIP read from UDP:190.228.46.85:5070 —>
SIP/2.0 200 Ok
Content-Length: 205
To: sip:333@190.228.46.85:5070;tag=gyrp1tro-2
Contact: <sip:190.228.46.85:5060;fid=server_1>
Cseq: 102 INVITE
Via: SIP/2.0/UDP
190.228.46.85:5060;branch=z9hG4bK6a87b29c;rport=5060;received=190.228.46.85
Content-Type: application/sdp
From: «6000»sip:6000@190.228.46.85;tag=as633d502e
Call-Id: 5999fad01f49f20b0ed05efd1f3c51c8@190.228.46.85
Server: Glassfish_SIP_2.0.0

v=0
o=- 0 0 IN IP4 127.0.0.1
s=MediaMixerSession
c=IN IP4 127.0.0.1
t=0 0
m=audio 49882 RTP/AVP 0 8
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
m=video 39946 RTP/AVP 98
a=rtpmap:98 H263-1998/90000

<————->
— (10 headers 10 lines) —
Found RTP audio format 0
Found RTP audio format 8
Found audio description format PCMU for ID 0
Found audio description format PCMA for ID 8
Found RTP video format 98
Found video description format H263-1998 for ID 98
Capabilities: us — 0x10000c (ulaw|alaw|h263p), peer — audio=0xc
(ulaw|alaw)/video=0x100000 (h263p)/text=0x0 (nothing), combined — 0x10000c
(ulaw|alaw|h263p)
Non-codec capabilities (dtmf): us — 0x1 (telephone-event), peer — 0x0
(nothing), combined — 0x0 (nothing)
Peer audio RTP is at port 127.0.0.1:49882
Peer video RTP is at port 127.0.0.1:39946
list_route: hop: <sip:190.228.46.85:5060;fid=server_1>
set_destination: Parsing <sip:190.228.46.85:5060;fid=server_1> for
address/port to send to
set_destination: set destination to 190.228.46.85, port 5060
Transmitting (no NAT) to 190.228.46.85:5060:
ACK sip:190.228.46.85:5060;fid=server_1 SIP/2.0
Via: SIP/2.0/UDP 190.228.46.85:5060;branch=z9hG4bK7f89f6af;rport
Max-Forwards: 70
From: «6000» sip:6000@190.228.46.85;tag=as633d502e
To: sip:333@190.228.46.85:5070;tag=gyrp1tro-2
Contact: sip:6000@190.228.46.85
Call-ID: 5999fad01f49f20b0ed05efd1f3c51c8@190.228.46.85
CSeq: 102 ACK
User-Agent: Asterisk PBX 1.6.2.9-2ubuntu2.1
Content-Length: 0

<— SIP read from UDP:190.228.46.85:5060 —>
ACK sip:190.228.46.85:5060;fid=server_1 SIP/2.0
Via: SIP/2.0/UDP 190.228.46.85:5060;branch=z9hG4bK7f89f6af;rport
Max-Forwards: 70
From: «6000» sip:6000@190.228.46.85;tag=as633d502e
To: sip:333@190.228.46.85:5070;tag=gyrp1tro-2
Contact: sip:6000@190.228.46.85
Call-ID: 5999fad01f49f20b0ed05efd1f3c51c8@190.228.46.85
CSeq: 102 ACK
User-Agent: Asterisk PBX 1.6.2.9-2ubuntu2.1
Content-Length: 0

<————->
— (10 headers 0 lines) —

v=0
o=- 0 0 IN IP4 127.0.0.1
s=MediaMixerSession
c=IN IP4 127.0.0.1
t=0 0
m=audio 49882 RTP/AVP 0 8
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
m=video 39946 RTP/AVP 98
a=rtpmap:98 H263-1998/90000

<————->
— (10 headers 10 lines) —
intercity-VirtualBox*CLI>

############### 2. Asterisk cli — rtp packets

intercity-VirtualBox*CLI> rtp set debug on
RTP Debugging Enabled
Got RTP packet from 200.43.166.254:38384 (type 115, seq 007315, ts 1403912,
len 000048)
Sent RTP packet to 127.0.0.1:54084 (type 98, seq 017522, ts 1390950, len
000048)
Got RTP packet from 200.43.166.254:19152 (type 00, seq 002023, ts 1421640, len
000160)
Sent RTP packet to 127.0.0.1:54300 (type 00, seq 009307, ts 1421640, len
000160)
Got RTP packet from 200.43.166.254:19152 (type 00, seq 002024, ts 1421800, len
000160)
Sent RTP packet to 127.0.0.1:54300 (type 00, seq 009308, ts 1421800, len
000160)
Got RTP packet from 200.43.166.254:38384 (type 115, seq 007316, ts 1409312,
len 000048)
Sent RTP packet to 127.0.0.1:54084 (type 98, seq 017523, ts 1396350, len
000048)
Got RTP packet from 200.43.166.254:19152 (type 00, seq 002025, ts 1421960, len
000160)
Sent RTP packet to 127.0.0.1:54300 (type 00, seq 009309, ts 1421960, len
000160)
Got RTP packet from 200.43.166.254:38384 (type 115, seq 007317, ts 1412912,
len 000048)
Sent RTP packet to 127.0.0.1:54084 (type 98, seq 017524, ts 1399950, len
000048)
Got RTP packet from 200.43.166.254:19152 (type 00, seq 002026, ts 1422120, len
000160)
Sent RTP packet to 127.0.0.1:54300 (type 00, seq 009310, ts 1422120, len
000160)
Got RTP packet from 200.43.166.254:19152 (type 00, seq 002027, ts 1422280, len
000160)
Sent RTP packet to 127.0.0.1:54300 (type 00, seq 009311, ts 1422280, len
000160)
Got RTP packet from 200.43.166.254:38384 (type 115, seq 007318, ts 1415612,
len 000048)
Sent RTP packet to 127.0.0.1:54084 (type 98, seq 017525, ts 1402650, len
000048)
Got RTP packet from 200.43.166.254:19152 (type 00, seq 002028, ts 1422440, len
000160)
Sent RTP packet to 127.0.0.1:54300 (type 00, seq 009312, ts 1422440, len
000160)
Got RTP packet from 200.43.166.254:19152 (type 00, seq 002029, ts 1422600, len
000160)
Sent RTP packet to 127.0.0.1:54300 (type 00, seq 009313, ts 1422600, len
000160)
Got RTP packet from 200.43.166.254:38384 (type 115, seq 007319, ts 1418312,
len 000109)
Sent RTP packet to 127.0.0.1:54084 (type 98, seq 017526, ts 1405350, len
000109)
Got RTP packet from 200.43.166.254:19152 (type 00, seq 002030, ts 1422760, len
000160)
Sent RTP packet to 127.0.0.1:54300 (type 00, seq 009314, ts 1422760, len
000160)
Got RTP packet from 200.43.166.254:19152 (type 00, seq 002031, ts 1422920, len
000160)
Sent RTP packet to 127.0.0.1:54300 (type 00, seq 009315, ts 1422920, len
000160)
Got RTP packet from 200.43.166.254:38384 (type 115, seq 007320, ts 1421912,
len 000052)
Sent RTP packet to 127.0.0.1:54084 (type 98, seq 017527, ts 1408950, len
000052)
Got RTP packet from 200.43.166.254:19152 (type 00, seq 002032, ts 1423080, len
000160)
Sent RTP packet to 127.0.0.1:54300 (type 00, seq 009316, ts 1423080, len
000160)
Got RTP packet from 200.43.166.254:19152 (type 00, seq 002033, ts 1423240, len
000160)
Sent RTP packet to 127.0.0.1:54300 (type 00, seq 009317, ts 1423240, len
000160)
Got RTP packet from 200.43.166.254:19152 (type 00, seq 002034, ts 1423400, len
000160)
Sent RTP packet to 127.0.0.1:54300 (type 00, seq 009318, ts 1423400, len
000160)
Got RTP packet from 200.43.166.254:38384 (type 115, seq 007321, ts 1424612,
len 000048)
Sent RTP packet to 127.0.0.1:54084 (type 98, seq 017528, ts 1411650, len
000048)
Got RTP packet from 200.43.166.254:19152 (type 00, seq 002035, ts 1423560, len
000160)
Sent RTP packet to 127.0.0.1:54300 (type 00, seq 009319, ts 1423560, len
000160)
Got RTP packet from 200.43.166.254:19152 (type 00, seq 002036, ts 1423720, len
000160)
Sent RTP packet to 127.0.0.1:54300 (type 00, seq 009320, ts 1423720, len
000160)
Got RTP packet from 200.43.166.254:38384 (type 115, seq 007322, ts 1431002,
len 000048)
Sent RTP packet to 127.0.0.1:54084 (type 98, seq 017529, ts 1418040, len
000048)
Got RTP packet from 200.43.166.254:19152 (type 00, seq 002037, ts 1423880, len
000160)
Sent RTP packet to 127.0.0.1:54300 (type 00, seq 009321, ts 1423880, len
000160)
Got RTP packet from 200.43.166.254:19152 (type 00, seq 002038, ts 1424040, len
000160)
Sent RTP packet to 127.0.0.1:54300 (type 00, seq 009322, ts 1424040, len
000160)
Got RTP packet from 200.43.166.254:38384 (type 115, seq 007323, ts 1433612,
len 000048)
Sent RTP packet to 127.0.0.1:54084 (type 98, seq 017530, ts 1420650, len
000048)
Got RTP packet from 200.43.166.254:19152 (type 00, seq 002039, ts 1424200, len
000160)
Sent RTP packet to 127.0.0.1:54300 (type 00, seq 009323, ts 1424200, len
000160)
Got RTP packet from 200.43.166.254:19152 (type 00, seq 002040, ts 1424360, len
000160)
Sent RTP packet to 127.0.0.1:54300 (type 00, seq 009324, ts 1424360, len
000160)
Got RTP packet from 200.43.166.254:19152 (type 00, seq 002041, ts 1424520, len
000160)
Sent RTP packet to 127.0.0.1:54300 (type 00, seq 009325, ts 1424520, len
000160)
Got RTP packet from 200.43.166.254:38384 (type 115, seq 007324, ts 1436312,
len 000102)
Sent RTP packet to 127.0.0.1:54084 (type 98, seq 017531, ts 1423350, len
000102)
Got RTP packet from 200.43.166.254:19152 (type 00, seq 002042, ts 1424680, len
000160)
Sent RTP packet to 127.0.0.1:54300 (type 00, seq 009326, ts 1424680, len
000160)
Got RTP packet from 200.43.166.254:19152 (type 00, seq 002043, ts 1424840, len
000160)
Sent RTP packet to 127.0.0.1:54300 (type 00, seq 009327, ts 1424840, len
000160)
Got RTP packet from 200.43.166.254:19152 (type 00, seq 002044, ts 1425000, len
000160)
Sent RTP packet to 127.0.0.1:54300 (type 00, seq 009328, ts 1425000, len
000160)
Got RTP packet from 200.43.166.254:38384 (type 115, seq 007325, ts 1442612,
len 000050)
Sent RTP packet to 127.0.0.1:54084 (type 98, seq 017532, ts 1429650, len
000050)
Got RTP packet from 200.43.166.254:19152 (type 00, seq 002045, ts 1425160, len
000160)
Sent RTP packet to 127.0.0.1:54300 (type 00, seq 009329, ts 1425160, len
000160)
Got RTP packet from 200.43.166.254:19152 (type 00, seq 002046, ts 1425320, len
000160)
Sent RTP packet to 127.0.0.1:54300 (type 00, seq 009330, ts 1425320, len
000160)
Got RTP packet from 200.43.166.254:38384 (type 115, seq 007326, ts 1448012,
len 000048)
Sent RTP packet to 127.0.0.1:54084 (type 98, seq 017533, ts 1435050, len
000048)
Got RTP packet from 200.43.166.254:19152 (type 00, seq 002047, ts 1425480, len
000160)
Sent RTP packet to 127.0.0.1:54300 (type 00, seq 009331, ts 1425480, len
000160)
Got RTP packet from 200.43.166.254:19152 (type 00, seq 002048, ts 1425640, len
000160)
Sent RTP packet to 127.0.0.1:54300 (type 00, seq 009332, ts 1425640, len
000160)
Got RTP packet from 200.43.166.254:38384 (type 115, seq 007327, ts 1451612,
len 000087)
Sent RTP packet to 127.0.0.1:54084 (type 98, seq 017534, ts 1438650, len
000087)
Got RTP packet from 200.43.166.254:19152 (type 00, seq 002049, ts 1425800, len
000160)
Sent RTP packet to 127.0.0.1:54300 (type 00, seq 009333, ts 1425800, len
000160)

Источник