проблема со входящими звонками

[diblack79]

добрый день. астериск стоял. никаких изменений не было. с определенного дня начались проблемы с частью номеров (внешних номеров с десяток) настроены одинаково , через правила по времени
и вот 3 номера при звонке на них с 18.05 по 09.00 "номер не доступен" остальные работают. в правилах по времени в районе 18.05 вообще не фигурирует даже близко. переадресация внутри астериска на рабочий номер ничего не дает (на одном поставили переадресацию у провайдера. тогда заработал). изменение времени в не рабочее время тоже. все так же не доступны. если в рабочее время поменять то срабатывает ночная временная группа. в группы заведомо рабочие внутренние номера подставляли. (уж думали может кто тел выключает)
провайдер тоже все проверил. ничего не нашел. в астериске логи есть.
не работали бы все. или все время было бы как то понятно. а так... куда копать?
лог звонка на один и тот же номер. до 09.00 и после.

1864[2023-11-08 08:54:42] VERBOSE[28732][C-0000219e] pbx.c: Executing [*****@from-trunk:1] Set("SIP/MTS-00001741", "__DIRECTION=INBOUND") in new stack
1865[2023-11-08 08:54:42] VERBOSE[28732][C-0000219e] pbx.c: Executing [*****@from-trunk:2] Gosub("SIP/MTS-00001741", "sub-record-check,s,1(in,*****,force)") in new stack
1883[2023-11-08 08:54:42] VERBOSE[28732][C-0000219e] pbx.c: Executing [in@sub-record-check:1] NoOp("SIP/MTS-00001741", "Inbound Recording Check to *****") in new stack
1886[2023-11-08 08:54:42] VERBOSE[28732][C-0000219e] pbx.c: Executing [in@sub-record-check:4] Gosub("SIP/MTS-00001741", "recordcheck,1(force,in,*****)") in new stack
1893[2023-11-08 08:54:42] VERBOSE[28732][C-0000219e] pbx.c: Executing [recordcheck@sub-record-check:16] NoOp("SIP/MTS-00001741", "Starting recording: in, *****") in new stack
1896[2023-11-08 08:54:42] VERBOSE[28732][C-0000219e] pbx.c: Executing [recordcheck@sub-record-check:19] Set("SIP/MTS-00001741", "__CALLFILENAME=in-*****-*****-20231108-085442-1699415682.36748") in new stack
1897[2023-11-08 08:54:42] VERBOSE[28732][C-0000219e] pbx.c: Executing [recordcheck@sub-record-check:20] MixMonitor("SIP/MTS-00001741", "2023/11/08/in-*****-*****-20231108-085442-1699415682.36748.wav,abi(),") in new stack
1901[2023-11-08 08:54:42] VERBOSE[28732][C-0000219e] pbx.c: Executing [recordcheck@sub-record-check:24] Set("SIP/MTS-00001741", "CDR(recordingfile)=in-*****-*****-20231108-085442-1699415682.36748.wav") in new stack
1905[2023-11-08 08:54:42] VERBOSE[28732][C-0000219e] pbx.c: Executing [*****@from-trunk:3] Set("SIP/MTS-00001741", "CHANNEL(tonezone)=us") in new stack
1906[2023-11-08 08:54:42] VERBOSE[28732][C-0000219e] pbx.c: Executing [*****@from-trunk:4] Set("SIP/MTS-00001741", "__FROM_DID=*****") in new stack
1907[2023-11-08 08:54:42] VERBOSE[28732][C-0000219e] pbx.c: Executing [*****@from-trunk:5] Set("SIP/MTS-00001741", "returnhere=1") in new stack
1908[2023-11-08 08:54:42] VERBOSE[28732][C-0000219e] pbx.c: Executing [*****@from-trunk:6] Gosub("SIP/MTS-00001741", "app-blacklist-check,s,1()") in new stack
1912[2023-11-08 08:54:42] VERBOSE[28732][C-0000219e] pbx.c: Executing [*****@from-trunk:7] Set("SIP/MTS-00001741", "CDR(did)=*****") in new stack
1913[2023-11-08 08:54:42] VERBOSE[28732][C-0000219e] pbx.c: Executing [*****@from-trunk:8] GotoIf("SIP/MTS-00001741", "0?") in new stack
1914[2023-11-08 08:54:42] VERBOSE[28732][C-0000219e] pbx.c: Executing [*****@from-trunk:9] ExecIf("SIP/MTS-00001741", "0 ?Set(CALLERID(name)=*****)") in new stack
1915[2023-11-08 08:54:42] VERBOSE[28732][C-0000219e] pbx.c: Executing [*****@from-trunk:10] Set("SIP/MTS-00001741", "CHANNEL(musicclass)=al-every-day") in new stack
1916[2023-11-08 08:54:42] VERBOSE[28732][C-0000219e] pbx.c: Executing [*****@from-trunk:11] Set("SIP/MTS-00001741", "__MOHCLASS=al-every-day") in new stack
1917[2023-11-08 08:54:42] VERBOSE[28732][C-0000219e] pbx.c: Executing [*****@from-trunk:12] Set("SIP/MTS-00001741", "__REVERSAL_REJECT=FALSE") in new stack
1918[2023-11-08 08:54:42] VERBOSE[28732][C-0000219e] pbx.c: Executing [*****@from-trunk:13] GotoIf("SIP/MTS-00001741", "1?post-reverse-charge") in new stack
1919[2023-11-08 08:54:42] VERBOSE[28732][C-0000219e] pbx_builtins.c: Goto (from-trunk,*****,15)
1920[2023-11-08 08:54:42] VERBOSE[28732][C-0000219e] pbx.c: Executing [*****@from-trunk:15] NoOp("SIP/MTS-00001741", "") in new stack
1921[2023-11-08 08:54:42] VERBOSE[28732][C-0000219e] pbx.c: Executing [*****@from-trunk:16] Set("SIP/MTS-00001741", "__CALLINGNAMEPRES_SV=allowed_not_screened") in new stack
1922[2023-11-08 08:54:42] VERBOSE[28732][C-0000219e] pbx.c: Executing [*****@from-trunk:17] Set("SIP/MTS-00001741", "__CALLINGNUMPRES_SV=allowed_not_screened") in new stack
1923[2023-11-08 08:54:42] VERBOSE[28732][C-0000219e] pbx.c: Executing [*****@from-trunk:18] Set("SIP/MTS-00001741", "CALLERID(name-pres)=allowed_not_screened") in new stack
1924[2023-11-08 08:54:42] VERBOSE[28732][C-0000219e] pbx.c: Executing [*****@from-trunk:19] Set("SIP/MTS-00001741", "CALLERID(num-pres)=allowed_not_screened") in new stack
1925[2023-11-08 08:54:42] VERBOSE[28732][C-0000219e] pbx.c: Executing [*****@from-trunk:20] NoOp("SIP/MTS-00001741", "CallerID Entry Point") in new stack
1926[2023-11-08 08:54:42] VERBOSE[28732][C-0000219e] pbx.c: Executing [*****@from-trunk:21] UserEvent("SIP/MTS-00001741", "InterceptionCTI2,CALLERID: *****,chan1c: SIP/MTS-00001741") in new stack
1927[2023-11-08 08:54:42] VERBOSE[28732][C-0000219e] pbx.c: Executing [*****@from-trunk:22] AGI("SIP/MTS-00001741", "agi://127.0.0.1/set-caller-id-with-transliteration.agi") in new stack
1929[2023-11-08 08:54:47] VERBOSE[28732][C-0000219e] pbx.c: Spawn extension (from-trunk, *****, 22) exited non-zero on 'SIP/MTS-00001741'


3904[2023-11-08 09:11:00] VERBOSE[31204][C-000021aa] pbx.c: Executing [*****@from-trunk:1] Set("SIP/MTS-00001749", "__DIRECTION=INBOUND") in new stack
3905[2023-11-08 09:11:00] VERBOSE[31204][C-000021aa] pbx.c: Executing [*****@from-trunk:2] Gosub("SIP/MTS-00001749", "sub-record-check,s,1(in,*****,force)") in new stack
3923[2023-11-08 09:11:00] VERBOSE[31204][C-000021aa] pbx.c: Executing [in@sub-record-check:1] NoOp("SIP/MTS-00001749", "Inbound Recording Check to *****") in new stack
3926[2023-11-08 09:11:00] VERBOSE[31204][C-000021aa] pbx.c: Executing [in@sub-record-check:4] Gosub("SIP/MTS-00001749", "recordcheck,1(force,in,*****)") in new stack
3933[2023-11-08 09:11:00] VERBOSE[31204][C-000021aa] pbx.c: Executing [recordcheck@sub-record-check:16] NoOp("SIP/MTS-00001749", "Starting recording: in, *****") in new stack
3936[2023-11-08 09:11:00] VERBOSE[31204][C-000021aa] pbx.c: Executing [recordcheck@sub-record-check:19] Set("SIP/MTS-00001749", "__CALLFILENAME=in-*****-*****-20231108-091100-1699416660.36774") in new stack
3937[2023-11-08 09:11:00] VERBOSE[31204][C-000021aa] pbx.c: Executing [recordcheck@sub-record-check:20] MixMonitor("SIP/MTS-00001749", "2023/11/08/in-*****-*****-20231108-091100-1699416660.36774.wav,abi(),") in new stack
3942[2023-11-08 09:11:00] VERBOSE[31204][C-000021aa] pbx.c: Executing [recordcheck@sub-record-check:24] Set("SIP/MTS-00001749", "CDR(recordingfile)=in-*****-*****-20231108-091100-1699416660.36774.wav") in new stack
3945[2023-11-08 09:11:00] VERBOSE[31204][C-000021aa] pbx.c: Executing [*****@from-trunk:3] Set("SIP/MTS-00001749", "CHANNEL(tonezone)=us") in new stack
3946[2023-11-08 09:11:00] VERBOSE[31204][C-000021aa] pbx.c: Executing [*****@from-trunk:4] Set("SIP/MTS-00001749", "__FROM_DID=*****") in new stack
3947[2023-11-08 09:11:00] VERBOSE[31204][C-000021aa] pbx.c: Executing [*****@from-trunk:5] Set("SIP/MTS-00001749", "returnhere=1") in new stack
3948[2023-11-08 09:11:00] VERBOSE[31204][C-000021aa] pbx.c: Executing [*****@from-trunk:6] Gosub("SIP/MTS-00001749", "app-blacklist-check,s,1()") in new stack
3952[2023-11-08 09:11:00] VERBOSE[31204][C-000021aa] pbx.c: Executing [*****@from-trunk:7] Set("SIP/MTS-00001749", "CDR(did)=*****") in new stack
3953[2023-11-08 09:11:00] VERBOSE[31204][C-000021aa] pbx.c: Executing [*****@from-trunk:8] GotoIf("SIP/MTS-00001749", "0?") in new stack
3954[2023-11-08 09:11:00] VERBOSE[31204][C-000021aa] pbx.c: Executing [*****@from-trunk:9] ExecIf("SIP/MTS-00001749", "0 ?Set(CALLERID(name)=*****)") in new stack
3955[2023-11-08 09:11:00] VERBOSE[31204][C-000021aa] pbx.c: Executing [*****@from-trunk:10] Set("SIP/MTS-00001749", "CHANNEL(musicclass)=al-every-day") in new stack
3956[2023-11-08 09:11:00] VERBOSE[31204][C-000021aa] pbx.c: Executing [*****@from-trunk:11] Set("SIP/MTS-00001749", "__MOHCLASS=al-every-day") in new stack
3957[2023-11-08 09:11:00] VERBOSE[31204][C-000021aa] pbx.c: Executing [*****@from-trunk:12] Set("SIP/MTS-00001749", "__REVERSAL_REJECT=FALSE") in new stack
3958[2023-11-08 09:11:00] VERBOSE[31204][C-000021aa] pbx.c: Executing [*****@from-trunk:13] GotoIf("SIP/MTS-00001749", "1?post-reverse-charge") in new stack
3959[2023-11-08 09:11:00] VERBOSE[31204][C-000021aa] pbx_builtins.c: Goto (from-trunk,*****,15)
3960[2023-11-08 09:11:00] VERBOSE[31204][C-000021aa] pbx.c: Executing [*****@from-trunk:15] NoOp("SIP/MTS-00001749", "") in new stack
3961[2023-11-08 09:11:00] VERBOSE[31204][C-000021aa] pbx.c: Executing [*****@from-trunk:16] Set("SIP/MTS-00001749", "__CALLINGNAMEPRES_SV=allowed_not_screened") in new stack
3962[2023-11-08 09:11:00] VERBOSE[31204][C-000021aa] pbx.c: Executing [*****@from-trunk:17] Set("SIP/MTS-00001749", "__CALLINGNUMPRES_SV=allowed_not_screened") in new stack
3963[2023-11-08 09:11:00] VERBOSE[31204][C-000021aa] pbx.c: Executing [*****@from-trunk:18] Set("SIP/MTS-00001749", "CALLERID(name-pres)=allowed_not_screened") in new stack
3964[2023-11-08 09:11:00] VERBOSE[31204][C-000021aa] pbx.c: Executing [*****@from-trunk:19] Set("SIP/MTS-00001749", "CALLERID(num-pres)=allowed_not_screened") in new stack
3965[2023-11-08 09:11:00] VERBOSE[31204][C-000021aa] pbx.c: Executing [*****@from-trunk:20] NoOp("SIP/MTS-00001749", "CallerID Entry Point") in new stack
3966[2023-11-08 09:11:00] VERBOSE[31204][C-000021aa] pbx.c: Executing [*****@from-trunk:21] UserEvent("SIP/MTS-00001749", "InterceptionCTI2,CALLERID: *****,chan1c: SIP/MTS-00001749") in new stack
3967[2023-11-08 09:11:00] VERBOSE[31204][C-000021aa] pbx.c: Executing [*****@from-trunk:22] AGI("SIP/MTS-00001749", "agi://127.0.0.1/set-caller-id-with-transliteration.agi") in new stack
3970[2023-11-08 09:11:01] VERBOSE[31204][C-000021aa] pbx.c: Executing [*****@from-trunk:23] Macro("SIP/MTS-00001749", "prepend-cid,Восток-") in new stack
3975[2023-11-08 09:11:01] VERBOSE[31204][C-000021aa] pbx.c: Executing [*****@from-trunk:24] Set("SIP/MTS-00001749", "__CRM_DIRECTION=INBOUND") in new stack
3976[2023-11-08 09:11:01] VERBOSE[31204][C-000021aa] pbx.c: Executing [*****@from-trunk:25] Set("SIP/MTS-00001749", "__CRM_SOURCE=*****") in new stack
3977[2023-11-08 09:11:01] VERBOSE[31204][C-000021aa] pbx.c: Executing [*****@from-trunk:26] Set("SIP/MTS-00001749", "__CRM_LINKEDID=1699416660.36774") in new stack
3978[2023-11-08 09:11:01] VERBOSE[31204][C-000021aa] pbx.c: Executing [*****@from-trunk:27] AGI("SIP/MTS-00001749", "agi://127.0.0.1/sangomacrm.agi,true") in new stack
3980[2023-11-08 09:11:01] VERBOSE[31204][C-000021aa] pbx.c: Executing [*****@from-trunk:28] ExecIf("SIP/MTS-00001749", "1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
3981[2023-11-08 09:11:01] VERBOSE[31204][C-000021aa] pbx.c: Executing [*****@from-trunk:29] Goto("SIP/MTS-00001749", "timeconditions,17,1") in new stack

[ded]

1) У вас стоит FreePBX, которую ставили и настраивали не вы (мопед не мой, я только объяву разместил).
2) У вас там работают Правила по времени на входящей маршрутизации - timeconditions
3) Вы не хотите читать документацию (asterisk.ru => База знаний => Книги => FreePBX - руководство администратора),
4) Надеетесь на волшебный пинок или волшебный порошок, типа - Открой вкладку №22 и сними/поставь птичку на опции. Так не получится, обращайтесь в платную поддержку тогда.
5) Страдаете невнимательностью, когда публикуете пост, не видите опечаток в заглавии, не видите что система на предмодерации, и пост не публикуется сразу, а вы запиливаете её упорно ещё раз.

[diblack79]

то что на модерации увидел только при дубле. (потому что запись об этом мигнула я ее просто не видел) (а последний вход модератора был еще в июне по данным)
ставил я. но на основе старой. и ставил уже давно. а перестало работать не давно.
2) У вас там работают Правила по времени на входящей маршрутизации - timeconditions
так я так и написал. но они для всех номеров единые. (или идентичные) а не работает на части. и по времени настроек никаких совпадений.
при том изменение времени в астериске ничего не дает. (точнее дает но когда уже в городе 09.00 наступило) в 08.40 например выставить время на час вперед. ничего не меняет.

3) Вы не хотите читать документацию (asterisk.ru => База знаний => Книги => FreePBX - руководство администратора)
поиск по такой симптоматике мне ничего не дал.

[ded]

Разбор последних строк лога (вы сами могли это сделать), с 20-го шага в контексте from-trunk:
3965[2023-11-08 09:11:00] VERBOSE[31204][C-000021aa] pbx.c: Executing [*****@from-trunk:20] NoOp("SIP/MTS-00001749", "CallerID Entry Point") in new stack - вход вызова с анализом CallerID
3966[2023-11-08 09:11:00] VERBOSE[31204][C-000021aa] pbx.c: Executing [*****@from-trunk:21] UserEvent("SIP/MTS-00001749", "InterceptionCTI2,CALLERID: *****,chan1c: SIP/MTS-00001749") in new stack - перехват CALLERID: *****
3967[2023-11-08 09:11:00] VERBOSE[31204][C-000021aa] pbx.c: Executing [*****@from-trunk:22] AGI("SIP/MTS-00001749", "agi://127.0.0.1/set-caller-id-with-transliteration.agi") in new stack - внешний скрипт set-caller-id-with-transliteration.agi подставляет к номеру имя
3970[2023-11-08 09:11:01] VERBOSE[31204][C-000021aa] pbx.c: Executing [*****@from-trunk:23] Macro("SIP/MTS-00001749", "prepend-cid,Восток-") in new stack - отрабатывает макрос prepend-cid, подставляя к номеру имя Восток
3975[2023-11-08 09:11:01] VERBOSE[31204][C-000021aa] pbx.c: Executing [*****@from-trunk:24] Set("SIP/MTS-00001749", "__CRM_DIRECTION=INBOUND") in new stack - в переменную CRM_DIRECTION записывается значение INBOUND
3976[2023-11-08 09:11:01] VERBOSE[31204][C-000021aa] pbx.c: Executing [*****@from-trunk:25] Set("SIP/MTS-00001749", "__CRM_SOURCE=*****") in new stack - в переменную CRM_SOURCE записываются ***** (номер. Вместо ***** лучше бы настоюящий номер тут оставили, изменив только пару цифр)
3977[2023-11-08 09:11:01] VERBOSE[31204][C-000021aa] pbx.c: Executing [*****@from-trunk:26] Set("SIP/MTS-00001749", "__CRM_LINKEDID=1699416660.36774") in new stack - в переменную CRM_LINKEDID записывается уникальный ID 1699416660.36774
3978[2023-11-08 09:11:01] VERBOSE[31204][C-000021aa] pbx.c: Executing [*****@from-trunk:27] AGI("SIP/MTS-00001749", "agi://127.0.0.1/sangomacrm.agi,true") in new stack - отрабатывает скрипт-коннектор в CRM: sangomacrm.agi, передаёт? возвращает? значение true
3980[2023-11-08 09:11:01] VERBOSE[31204][C-000021aa] pbx.c: Executing [*****@from-trunk:28] ExecIf("SIP/MTS-00001749", "1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack - тут выполняется проверка
ExecIf($["${CRM_DIRECTION}"="INBOUND"]?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)), у вас она возвратил 1, поэтому записала в переменную CHANNEL(hangup_handler_push)=crm-hangup,s,1
Всё это выполняется вроде по метке [did-cid-hook], так что разбирайтесь дальше самостоятельно. Или - в платный суппорт.

[diblack79]

спасибо. пока достаточно и направления.

[diblack79]

лог я смотрел. разницу увидел только в последней строке (когда не дозвонился) просто все же настройки транков и линий не содержат времени. а тут именно в определенное время суток. а не вообще не работают какие то номера.

[diblack79]

в итоге провайдер у себя там что то сделал (сами не знают что. или просто так говорят) и все заработало.

[ded]

Если бы вы не шифровались звёздочками ***** и мы бы в логе видели что-то конкретное - было бы и нам понятно.
Раньше вам провайдер, например, присылал номер в формате 10-знак 4991234567 и он обрабатывался во входящей маршрутизации как было указано - 4991234567@from-trunk
А потом вдруг стал присылать 11-знак, в виде 84991234567@from-trunk, и потекло не так, как ожидали. Но вы это не увидели даже.
А потом вернул назад - всё и восстановилось.
Это предположение, а не утваерждение.

[diblack79]

ну такое я тоже рассматривал. а все что под * было одинаковым. автозаменой делал. и для проверки не представлялось интересным

[ded]

На будущее: надо было исследовать SIP Invite и ответ на него -
http://asterisk.ru/knowledgebase/debug ручной захват.