у панаса главное регить в локалке, он NAT не умеет (кроме NS пожалуй).
еще там же лицензии на всё - нужно проверить подкормил ли японскую жадность
sip Транк между панасоником и астериском
Re: sip Транк между панасоником и астериском
Российские E1 шлюзы Alvis. Модернизация УПАТС с E1,Подключение к ИС "Антифрод" E1 PRI/SS#7 УВР Телестор, Грифин и др..
Re: sip Транк между панасоником и астериском
тут все нормуль , и подкормили , и контексты подправили 
Своими вопросами , вы загоняете меня в ГУГЛЬ.
Re: sip Транк между панасоником и астериском
Добрый день,
Подскажите пожалуйста. Вопрос так же связан с транком между Панасоником и Астериском.
Схема такая Астериск 1 (172.16.173.148) <== SIP ==> Астериск 2 (172.22.106.13) <== SIP ==> PANASONIC (172.22.106.10)
Пробовали транк и с паролем и без. Панасоник не под нашим контролем. Сейчас там якобы такая настройка.
https://disk.yandex.ru/d/J0z6NinzGf5VBw
Пробовали и ID аутентификации ставить: 123
У нас на астериске 2 в транке:
type=friend
sendrpid=no
qualify=yes
nat=no
username=123
host=172.22.106.10
dtmf=rfc2833
disallow=all
context=from-internal
canreinvite=no
allow=g729
При таких настройках звонок с панасоника на астер 1 проходит.
А звонок на панасоник нет.
Вот лог снятый с астера 2:
Как я понимаю что-то не так с авторизацией?
Подскажите пожалуйста. Вопрос так же связан с транком между Панасоником и Астериском.
Схема такая Астериск 1 (172.16.173.148) <== SIP ==> Астериск 2 (172.22.106.13) <== SIP ==> PANASONIC (172.22.106.10)
Пробовали транк и с паролем и без. Панасоник не под нашим контролем. Сейчас там якобы такая настройка.
https://disk.yandex.ru/d/J0z6NinzGf5VBw
Пробовали и ID аутентификации ставить: 123
У нас на астериске 2 в транке:
type=friend
sendrpid=no
qualify=yes
nat=no
username=123
host=172.22.106.10
dtmf=rfc2833
disallow=all
context=from-internal
canreinvite=no
allow=g729
При таких настройках звонок с панасоника на астер 1 проходит.
А звонок на панасоник нет.
Вот лог снятый с астера 2:
PRIME_BBCODE_SPOILER_SHOW PRIME_BBCODE_SPOILER:
<--- SIP read from UDP:172.22.106.10:5060 --->
1463186SIP/2.0 407 Proxy Authentication Required
1463187Via: SIP/2.0/UDP 172.22.106.13:5060;branch=z9hG4bK59abbd63
1463188To: sip:20608@172.22.106.10;tag=15912
1463189From: "Смирнов Test_1" <sip:9102999@172.22.106.13>;tag=as7eb35afa
1463190Call-ID: 5cd864b671c1e0503b9dc45a0ce9dccd@172.22.106.13:5060
1463191CSeq: 102 INVITE
1463192Allow: INVITE,ACK,CANCEL,BYE,REGISTER
1463193Proxy-Authenticate: Digest realm="Registered Users",nonce="20408000010204081122458a152b56ac",algorithm=MD5
1463194Content-Length: 0
1463260<--- SIP read from UDP:172.22.106.10:5060 --->
1463261SIP/2.0 404 Not Found
1463262Via: SIP/2.0/UDP 172.22.106.13:5060;branch=z9hG4bK634e0351
1463263To: sip:20608@172.22.106.10;tag=18994
1463264From: "Смирнов Test_1" <sip:9102999@172.22.106.13>;tag=as7eb35afa
1463265Call-ID: 5cd864b671c1e0503b9dc45a0ce9dccd@172.22.106.13:5060
1463266CSeq: 103 INVITE
1463267Allow: INVITE,ACK,CANCEL,BYE,REGISTER
1463268Content-Length: 0
SIP/2.0 487 Request Terminated
1482827Via: SIP/2.0/UDP 172.16.173.148:5060;branch=z9hG4bK70d07c17;received=172.16.173.148
1482828From: "Смирнов Test_1" <sip:9102999@172.16.173.148>;tag=as2983d628
1482829To: <sip:20608@172.22.106.13>;tag=as1d7a15a0
1482830Call-ID: 35a284c254b3773d6c82265650e40f60@172.16.173.148:5060
1482831CSeq: 101 INVITE
1482832Server: FPBX-15.0.17.34(16.17.0)
1482833Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
1482834Supported: replaces, timer
1482835Content-Length: 0
1482869<--- Transmitting (no NAT) to 172.16.173.148:5060 --->
1482870SIP/2.0 481 Call/Transaction Does Not Exist
1482871Via: SIP/2.0/UDP 172.16.173.148:5060;branch=z9hG4bK70d07c17;received=172.16.173.148
1482872From: "Смирнов Test_1" <sip:9102999@172.16.173.148>;tag=as2983d628
1482873To: <sip:20608@172.22.106.13>;tag=as1d7a15a0
1482874Call-ID: 35a284c254b3773d6c82265650e40f60@172.16.173.148:5060
1482875CSeq: 101 CANCEL
1482876Server: FPBX-15.0.17.34(16.17.0)
1482877Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
1482878Supported: replaces, timer
1482879Content-Length: 0
1463186SIP/2.0 407 Proxy Authentication Required
1463187Via: SIP/2.0/UDP 172.22.106.13:5060;branch=z9hG4bK59abbd63
1463188To: sip:20608@172.22.106.10;tag=15912
1463189From: "Смирнов Test_1" <sip:9102999@172.22.106.13>;tag=as7eb35afa
1463190Call-ID: 5cd864b671c1e0503b9dc45a0ce9dccd@172.22.106.13:5060
1463191CSeq: 102 INVITE
1463192Allow: INVITE,ACK,CANCEL,BYE,REGISTER
1463193Proxy-Authenticate: Digest realm="Registered Users",nonce="20408000010204081122458a152b56ac",algorithm=MD5
1463194Content-Length: 0
1463260<--- SIP read from UDP:172.22.106.10:5060 --->
1463261SIP/2.0 404 Not Found
1463262Via: SIP/2.0/UDP 172.22.106.13:5060;branch=z9hG4bK634e0351
1463263To: sip:20608@172.22.106.10;tag=18994
1463264From: "Смирнов Test_1" <sip:9102999@172.22.106.13>;tag=as7eb35afa
1463265Call-ID: 5cd864b671c1e0503b9dc45a0ce9dccd@172.22.106.13:5060
1463266CSeq: 103 INVITE
1463267Allow: INVITE,ACK,CANCEL,BYE,REGISTER
1463268Content-Length: 0
SIP/2.0 487 Request Terminated
1482827Via: SIP/2.0/UDP 172.16.173.148:5060;branch=z9hG4bK70d07c17;received=172.16.173.148
1482828From: "Смирнов Test_1" <sip:9102999@172.16.173.148>;tag=as2983d628
1482829To: <sip:20608@172.22.106.13>;tag=as1d7a15a0
1482830Call-ID: 35a284c254b3773d6c82265650e40f60@172.16.173.148:5060
1482831CSeq: 101 INVITE
1482832Server: FPBX-15.0.17.34(16.17.0)
1482833Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
1482834Supported: replaces, timer
1482835Content-Length: 0
1482869<--- Transmitting (no NAT) to 172.16.173.148:5060 --->
1482870SIP/2.0 481 Call/Transaction Does Not Exist
1482871Via: SIP/2.0/UDP 172.16.173.148:5060;branch=z9hG4bK70d07c17;received=172.16.173.148
1482872From: "Смирнов Test_1" <sip:9102999@172.16.173.148>;tag=as2983d628
1482873To: <sip:20608@172.22.106.13>;tag=as1d7a15a0
1482874Call-ID: 35a284c254b3773d6c82265650e40f60@172.16.173.148:5060
1482875CSeq: 101 CANCEL
1482876Server: FPBX-15.0.17.34(16.17.0)
1482877Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
1482878Supported: replaces, timer
1482879Content-Length: 0
Re: sip Транк между панасоником и астериском
1) Не надо регистрировать, ненужная процедура для этого случая
2) type=peer а не type=friend
3) insecure=invite снимает запрос авторизации входящего вызова от Panasonic на Астериск
4) Не надо использовать бессмысленный набор 123, ничего не объясняют эти цифры. Заведите пир [TDE100] или [Panasonic] или как там он называется, чтобы быдло понятно в логах.
5) qualify=yes не нужно, это для НАТ вспоможение, не ваш случай, лишние пакеты гонять только, allow=g729 для локальной сети совершенно неразумно, не скупитесь
allow=alaw
allow=ulaw
2) type=peer а не type=friend
3) insecure=invite снимает запрос авторизации входящего вызова от Panasonic на Астериск
4) Не надо использовать бессмысленный набор 123, ничего не объясняют эти цифры. Заведите пир [TDE100] или [Panasonic] или как там он называется, чтобы быдло понятно в логах.
5) qualify=yes не нужно, это для НАТ вспоможение, не ваш случай, лишние пакеты гонять только, allow=g729 для локальной сети совершенно неразумно, не скупитесь
allow=alaw
allow=ulaw
Re: sip Транк между панасоником и астериском
Ded, Спасибо за ответ.
"1) Не надо регистрировать, ненужная процедура для этого случая"
Так же считаю, но когда они в панасонике убирают логин. (в картинке что я присылал, я в панасониках слаб) пропадает связь. с Панасоника на астериск 1.
Они приводят это как аргумент.
После вашего ответа завтра уберём этот пункт и будем снимать логи вход/исход.
"2) type=peer а не type=friend"
Ок проверю.
"3) insecure=invite снимает запрос авторизации входящего вызова от Panasonic на Астериск"
Ок завтра пропишу и протестируем. Пробовал с "insecure=very" не работало.
"4) Не надо использовать бессмысленный набор 123, ничего не объясняют эти цифры. Заведите пир [TDE100] или [Panasonic] или как там он называется, чтобы быдло понятно в логах."
ок. сделаю
"5) qualify=yes не нужно, это для НАТ вспоможение, не ваш случай, лишние пакеты гонять только,"
Ок, спасибо за совет!
"allow=g729 для локальной сети совершенно неразумно, не скупитесь"
У нас там через спутниковый канал связь и в целях экономии 729
"1) Не надо регистрировать, ненужная процедура для этого случая"
Так же считаю, но когда они в панасонике убирают логин. (в картинке что я присылал, я в панасониках слаб) пропадает связь. с Панасоника на астериск 1.
Они приводят это как аргумент.
После вашего ответа завтра уберём этот пункт и будем снимать логи вход/исход.
"2) type=peer а не type=friend"
Ок проверю.
"3) insecure=invite снимает запрос авторизации входящего вызова от Panasonic на Астериск"
Ок завтра пропишу и протестируем. Пробовал с "insecure=very" не работало.
"4) Не надо использовать бессмысленный набор 123, ничего не объясняют эти цифры. Заведите пир [TDE100] или [Panasonic] или как там он называется, чтобы быдло понятно в логах."
ок. сделаю
"5) qualify=yes не нужно, это для НАТ вспоможение, не ваш случай, лишние пакеты гонять только,"
Ок, спасибо за совет!
"allow=g729 для локальной сети совершенно неразумно, не скупитесь"
У нас там через спутниковый канал связь и в целях экономии 729
Re: sip Транк между панасоником и астериском
very - старый синтаксис, Детально новыйAndy писал(а):Пробовал с "insecure=very" не работало.
insecure=invite,port
Картинки можно сюда заливать, зачем Яндекс? Или так -
Re: sip Транк между панасоником и астериском
username устарело, вместо него defaultuser.
Если insecure=port,invite то defaultuser и secret не нужны - авторизация по IP.
canreinvite устарел, юзайте directmedia.
Если insecure=port,invite то defaultuser и secret не нужны - авторизация по IP.
canreinvite устарел, юзайте directmedia.
Российские E1 шлюзы Alvis. Модернизация УПАТС с E1,Подключение к ИС "Антифрод" E1 PRI/SS#7 УВР Телестор, Грифин и др..
Re: sip Транк между панасоником и астериском
very - старый синтаксис, Детально новыйded писал(а):Andy писал(а):Пробовал с "insecure=very" не работало.
insecure=invite,port
Здравствуйте,
Сделал транк сейчас на 172.22.106.13 (Magadan-Out). Freepbx 2
type=peer
sendrpid=no
insecure==invite
nat=no
host=172.22.106.10
dtmf=rfc2833
disallow=all
context=from-internal
canreinvite=no
allow=g729
звоню с freepbx 1 (172.16.173.148) на freepbx 2
Далее звонок должен уходить на Panasonic (172.22.106.10)
Лог:
PRIME_BBCODE_SPOILER_SHOW PRIME_BBCODE_SPOILER:
1716132 <--- SIP read from UDP:172.16.173.148:5060 --->
1716133 INVITE sip:20608@172.22.106.13 SIP/2.0
1716134 Via: SIP/2.0/UDP 172.16.173.148:5060;branch=z9hG4bK70ad6c24
1716135 Max-Forwards: 70
1716136 From: "Смирнов Test_1" <sip:9102999@172.16.173.148>;tag=as04ba4c0f
1716137 To: <sip:20608@172.22.106.13>
1716138 Contact: <sip:9102999@172.16.173.148:5060>
1716139 Call-ID: 3e472f183fedfe8154f0f9230da009c5@172.16.173.148:5060
1716140 CSeq: 101 INVITE
1716141 User-Agent: FPBX-14.0.16(16.13.0)
1716142 Date: Thu, 30 Dec 2021 08:02:09 GMT
1716143 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
1716144 Supported: replaces,timer
1716145 Content-Type: application/sdp
1716146 Content-Length: 279
1716147
1716148 v=0
1716149 o=root 1652409066 1652409066 IN IP4 172.16.173.148
1716150 s=Asterisk PBX 16.13.0
1716151 c=IN IP4 172.16.173.148
1716152 t=0 0
1716153 m=audio 16276 RTP/AVP 18 101
1716154 a=rtpmap:18 G729/8000
1716155 a=fmtp:18 annexb=no
1716156 a=rtpmap:101 telephone-event/8000
1716157 a=fmtp:101 0-16
1716158 a=ptime:20
1716159 a=maxptime:230
1716160 a=sendrecv
1716161 <------------->
1716162 [2021-12-30 19:02:09] VERBOSE[2235] chan_sip.c: --- (14 headers 13 lines) ---
1716163 [2021-12-30 19:02:09] VERBOSE[2235] chan_sip.c: Sending to 172.16.173.148:5060 (no NAT)
1716164 [2021-12-30 19:02:09] VERBOSE[2235][C-00000029] chan_sip.c: Sending to 172.16.173.148:5060 (no NAT)
1716165 [2021-12-30 19:02:09] VERBOSE[2235][C-00000029] chan_sip.c: Using INVITE request as basis request - 3e472f183fedfe8154f0f9230da009c5@172.16.173.148:5060
1716166 [2021-12-30 19:02:09] VERBOSE[2235][C-00000029] chan_sip.c: Found peer '10-SPB_test_node_2 trunk_INC' for '9102999' from 172.16.173.148:5060
1716167 [2021-12-30 19:02:09] VERBOSE[2235][C-00000029] netsock2.c: Using SIP RTP TOS bits 184
1716168 [2021-12-30 19:02:09] VERBOSE[2235][C-00000029] netsock2.c: Using SIP RTP CoS mark 5
1716169 [2021-12-30 19:02:09] VERBOSE[2235][C-00000029] chan_sip.c: Got SDP version 1652409066 and unique parts [root 1652409066 IN IP4 172.16.173.148]
1716170 [2021-12-30 19:02:09] VERBOSE[2235][C-00000029] chan_sip.c: Found RTP audio format 18
1716171 [2021-12-30 19:02:09] VERBOSE[2235][C-00000029] chan_sip.c: Found RTP audio format 101
1716172 [2021-12-30 19:02:09] VERBOSE[2235][C-00000029] chan_sip.c: Found audio description format G729 for ID 18
1716173 [2021-12-30 19:02:09] VERBOSE[2235][C-00000029] chan_sip.c: Found audio description format telephone-event for ID 101
1716174 [2021-12-30 19:02:09] VERBOSE[2235][C-00000029] chan_sip.c: Capabilities: us - (g729), peer - audio=(g729)/video=(nothing)/text=(nothing), combined - (g729)
1716175 [2021-12-30 19:02:09] VERBOSE[2235][C-00000029] chan_sip.c: Non-codec capabilities (dtmf): us - 0x1 (telephone-event|), peer - 0x1 (telephone-event|), combined - 0x1 (telephone-event|)
1716176 [2021-12-30 19:02:09] VERBOSE[2235][C-00000029] chan_sip.c: Peer audio RTP is at port 172.16.173.148:16276
1716177 [2021-12-30 19:02:09] VERBOSE[2235][C-00000029] chan_sip.c: Looking for 20608 in from-internal (domain 172.22.106.13)
1716178 [2021-12-30 19:02:09] VERBOSE[2235][C-00000029] sip/route.c: sip_route_dump: route/path hop: <sip:9102999@172.16.173.148:5060>
1716179 [2021-12-30 19:02:09] VERBOSE[2235][C-00000029] chan_sip.c:
1716180 <--- Transmitting (no NAT) to 172.16.173.148:5060 --->
1716181 SIP/2.0 100 Trying
1716182 Via: SIP/2.0/UDP 172.16.173.148:5060;branch=z9hG4bK70ad6c24;received=172.16.173.148
1716183 From: "Смирнов Test_1" <sip:9102999@172.16.173.148>;tag=as04ba4c0f
1716184 To: <sip:20608@172.22.106.13>
1716185 Call-ID: 3e472f183fedfe8154f0f9230da009c5@172.16.173.148:5060
1716186 CSeq: 101 INVITE
1716187 Server: FPBX-15.0.17.34(16.17.0)
1716188 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
1716189 Supported: replaces, timer
1716190 Session-Expires: 1800;refresher=uas
1716191 Contact: <sip:20608@172.22.106.13:5060>
1716192 Content-Length: 0
1716193
1716194
1716195 <------------>
1716196 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [20608@from-internal:1] Macro("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "user-callerid,LIMIT,EXTERNAL,") in new stack
1716197 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-user-callerid:1] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "TOUCH_MONITOR=1640851329.74") in new stack
1716198 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-user-callerid:2] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "AMPUSER=9102999") in new stack
1716199 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-user-callerid:3] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "HOTDESCKCHAN=10-SPB_test_node_2 trunk_INC-0000004a") in new stack
1716200 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-user-callerid:4] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "HOTDESKEXTEN=10") in new stack
1716201 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-user-callerid:5] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "HOTDESKCALL=0") in new stack
1716202 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-user-callerid:6] ExecIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?Set(HOTDESKCALL=1)") in new stack
1716203 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-user-callerid:7] ExecIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?Set(CALLERID(name)=)") in new stack
1716204 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-user-callerid:8] GotoIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?report") in new stack
1716205 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-user-callerid:9] ExecIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "1?Set(REALCALLERIDNUM=9102999)") in new stack
1716206 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-user-callerid:10] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "AMPUSER=") in new stack
1716207 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-user-callerid:11] GotoIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?limit") in new stack
1716208 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-user-callerid:12] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "AMPUSERCIDNAME=") in new stack
1716209 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-user-callerid:13] ExecIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?Set(__CIDMASQUERADING=TRUE)") in new stack
1716210 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-user-callerid:14] GotoIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "1?report") in new stack
1716211 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx_builtins.c: Goto (macro-user-callerid,s,23)
1716212 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-user-callerid:23] NoOp("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "Macro Depth is 1") in new stack
1716213 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-user-callerid:24] GotoIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "1?report2:macroerror") in new stack
1716214 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx_builtins.c: Goto (macro-user-callerid,s,25)
1716215 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-user-callerid:25] GotoIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "1?continue") in new stack
1716216 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx_builtins.c: Goto (macro-user-callerid,s,44)
1716217 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-user-callerid:44] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "CALLERID(number)=9102999") in new stack
1716218 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-user-callerid:45] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "CALLERID(name)=Смирнов Test_1") in new stack
1716219 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-user-callerid:46] GotoIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?cnum") in new stack
1716220 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-user-callerid:47] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "CDR(cnam)=Смирнов Test_1") in new stack
1716221 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-user-callerid:48] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "CDR(cnum)=9102999") in new stack
1716222 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-user-callerid:49] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "CHANNEL(language)=en") in new stack
1716223 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [20608@from-internal:2] Gosub("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "sub-record-check,s,1(out,20608,dontcare)") in new stack
1716224 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@sub-record-check:1] GotoIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?initialized") in new stack
1716225 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@sub-record-check:2] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "__REC_STATUS=INITIALIZED") in new stack
1716226 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@sub-record-check:3] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "NOW=1640851329") in new stack
1716227 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@sub-record-check:4] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "__DAY=30") in new stack
1716228 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@sub-record-check:5] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "__MONTH=12") in new stack
1716229 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@sub-record-check:6] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "__YEAR=2021") in new stack
1716230 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@sub-record-check:7] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "__TIMESTR=20211230-190209") in new stack
1716231 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@sub-record-check:8] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "__FROMEXTEN=9102999") in new stack
1716232 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@sub-record-check:9] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "__MON_FMT=wav") in new stack
1716233 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@sub-record-check:10] NoOp("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "Recordings initialized") in new stack
1716234 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@sub-record-check:11] ExecIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?Set(ARG3=dontcare)") in new stack
1716235 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@sub-record-check:12] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "REC_POLICY_MODE_SAVE=") in new stack
1716236 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@sub-record-check:13] ExecIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?Set(REC_STATUS=NO)") in new stack
1716237 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@sub-record-check:14] GotoIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "3?checkaction") in new stack
1716238 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx_builtins.c: Goto (sub-record-check,s,17)
1716239 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@sub-record-check:17] GotoIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "1?sub-record-check,out,1") in new stack
1716240 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx_builtins.c: Goto (sub-record-check,out,1)
1716241 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [out@sub-record-check:1] NoOp("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "Outbound Recording Check from 9102999 to 20608") in new stack
1716242 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [out@sub-record-check:2] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "RECMODE=") in new stack
1716243 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [out@sub-record-check:3] ExecIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "1?Goto(routewins)") in new stack
1716244 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx_builtins.c: Goto (sub-record-check,out,7)
1716245 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [out@sub-record-check:7] Gosub("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "recordcheck,1(dontcare,out,20608)") in new stack
1716246 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "Starting recording check against dontcare") in new stack
1716247 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "dontcare") in new stack
1716248 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
1716249 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [recordcheck@sub-record-check:3] Return("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "") in new stack
1716250 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [out@sub-record-check:8] Return("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "") in new stack
1716251 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [20608@from-internal:3] ExecIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0 ?Set(CDR(accountcode)=)") in new stack
1716252 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [20608@from-internal:4] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "_ROUTEID=2") in new stack
1716253 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [20608@from-internal:5] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "_ROUTENAME=Na sebya 20 Magadan") in new stack
1716254 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [20608@from-internal:6] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "MOHCLASS=default") in new stack
1716255 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [20608@from-internal:7] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "_CALLERIDNAMEINTERNAL=Смирнов Test_1") in new stack
1716256 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [20608@from-internal:8] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "_CALLERIDNUMINTERNAL=9102999") in new stack
1716257 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [20608@from-internal:9] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "_EMAILNOTIFICATION=FALSE") in new stack
1716258 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [20608@from-internal:10] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "_NODEST=") in new stack
1716259 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [20608@from-internal:11] Macro("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "dialout-trunk,1,20608,,off") in new stack
1716260 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:1] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "DIAL_TRUNK=1") in new stack
1716261 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:2] ExecIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?Set(DIAL_OPTIONS=Hhtr)") in new stack
1716262 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:3] GosubIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?sub-pincheck,s,1()") in new stack
1716263 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:4] ExecIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?Set(CALLERID(num)=)") in new stack
1716264 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:5] GotoIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?disabletrunk,1") in new stack
1716265 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:6] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "DIAL_NUMBER=20608") in new stack
1716266 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:7] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "DIAL_TRUNK_OPTIONS=HhTtr") in new stack
1716267 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:8] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "OUTBOUND_GROUP=OUT_1") in new stack
1716268 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:9] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "DIAL_TRUNK_OPTIONS=T") in new stack
1716269 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:10] GotoIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "1?nomax") in new stack
1716270 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx_builtins.c: Goto (macro-dialout-trunk,s,12)
1716271 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:12] GotoIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?skipoutcid") in new stack
1716272 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:13] Macro("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "outbound-callerid,1") in new stack
1716273 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:1] NoOp("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "9102999") in new stack
1716274 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:2] NoOp("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "") in new stack
1716275 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:3] NoOp("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "off") in new stack
1716276 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:4] ExecIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?Set(CALLERPRES(name-pres)=)") in new stack
1716277 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:5] ExecIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?Set(CALLERPRES(num-pres)=)") in new stack
1716278 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:6] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "HOTDESCKCHAN=10-SPB_test_node_2 trunk_INC-0000004a") in new stack
1716279 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:7] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "HOTDESKEXTEN=10") in new stack
1716280 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:8] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "HOTDESKCALL=0") in new stack
1716281 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:9] ExecIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?Set(HOTDESKCALL=1)") in new stack
1716282 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:10] ExecIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?Set(CALLERID(name)=)") in new stack
1716283 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:11] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "ALLOWTHISROUTE=NO") in new stack
1716284 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:12] ExecIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?Set(ALLOWTHISROUTE=YES)") in new stack
1716285 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:13] ExecIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?Hangup()") in new stack
1716286 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:14] ExecIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?Set(REALCALLERIDNUM=9102999)") in new stack
1716287 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:15] ExecIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?Set(AMPUSER=9102999)") in new stack
1716288 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:16] GotoIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "1?normcid") in new stack
1716289 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx_builtins.c: Goto (macro-outbound-callerid,s,20)
1716290 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:20] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "USEROUTCID=") in new stack
1716291 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:21] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "EMERGENCYCID=") in new stack
1716292 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:22] ExecIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?Set(EMERGENCYCID=)") in new stack
1716293 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:23] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "TRUNKOUTCID=") in new stack
1716294 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:24] GotoIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "1?trunkcid") in new stack
1716295 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx_builtins.c: Goto (macro-outbound-callerid,s,30)
1716296 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:30] ExecIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?Set(CALLERID(all)=)") in new stack
1716297 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:31] ExecIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?Set(CALLERID(all)=)") in new stack
1716298 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:32] ExecIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?Set(CALLERID(all)=)") in new stack
1716299 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:33] ExecIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?Set(CALLERID(all)=9102999)") in new stack
1716300 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:34] ExecIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?Set(CALLERID(all)=9102999)") in new stack
1716301 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:35] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "TIOHIDE=no") in new stack
1716302 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:36] ExecIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?Set(CALLERPRES(name-pres)=prohib_passed_screen)") in new stack
1716303 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:37] ExecIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?Set(CALLERPRES(num-pres)=prohib_passed_screen)") in new stack
1716304 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:38] ExecIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?Set(CALLERPRES(name-pres)=prohib_passed_screen)") in new stack
1716305 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:39] ExecIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?Set(CALLERPRES(num-pres)=prohib_passed_screen)") in new stack
1716306 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:40] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "CDR(outbound_cnum)=9102999") in new stack
1716307 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:41] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "CDR(outbound_cnam)=Смирнов Test_1") in new stack
1716308 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:14] GosubIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?sub-flp-1,s,1()") in new stack
1716309 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:15] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "OUTNUM=20608") in new stack
1716310 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:16] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "custom=SIP/Magadan-Out") in new stack
1716311 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:17] ExecIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)T)") in new stack
1716312 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:18] ExecIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?Set(DIAL_TRUNK_OPTIONS=TM(confirm))") in new stack
1716313 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:19] Macro("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "dialout-trunk-predial-hook,") in new stack
1716314 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "") in new stack
1716315 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:20] GotoIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?skipcrm") in new stack
1716316 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:21] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "__CRM_DIRECTION=OUTBOUND") in new stack
1716317 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:22] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "__CRM_DESTINATION=20608") in new stack
1716318 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:23] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "__CRM_SOURCE=") in new stack
1716319 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:24] AGI("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "agi://127.0.0.1/sangomacrm.agi") in new stack
1716320 [2021-12-30 19:02:09] VERBOSE[2235] chan_sip.c:
1716321 <--- SIP read from UDP:172.16.173.148:5060 --->
1716322 INVITE sip:20608@172.22.106.13 SIP/2.0
1716323 Via: SIP/2.0/UDP 172.16.173.148:5060;branch=z9hG4bK70ad6c24
1716324 Max-Forwards: 70
1716325 From: "Смирнов Test_1" <sip:9102999@172.16.173.148>;tag=as04ba4c0f
1716326 To: <sip:20608@172.22.106.13>
1716327 Contact: <sip:9102999@172.16.173.148:5060>
1716328 Call-ID: 3e472f183fedfe8154f0f9230da009c5@172.16.173.148:5060
1716329 CSeq: 101 INVITE
1716330 User-Agent: FPBX-14.0.16(16.13.0)
1716331 Date: Thu, 30 Dec 2021 08:02:09 GMT
1716332 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
1716333 Supported: replaces,timer
1716334 Content-Type: application/sdp
1716335 Content-Length: 279
1716336
1716337 v=0
1716338 o=root 1652409066 1652409066 IN IP4 172.16.173.148
1716339 s=Asterisk PBX 16.13.0
1716340 c=IN IP4 172.16.173.148
1716341 t=0 0
1716342 m=audio 16276 RTP/AVP 18 101
1716343 a=rtpmap:18 G729/8000
1716344 a=fmtp:18 annexb=no
1716345 a=rtpmap:101 telephone-event/8000
1716346 a=fmtp:101 0-16
1716347 a=ptime:20
1716348 a=maxptime:230
1716349 a=sendrecv
1716350 <------------->
1716351 [2021-12-30 19:02:09] VERBOSE[2235] chan_sip.c: --- (14 headers 13 lines) ---
1716352 [2021-12-30 19:02:09] VERBOSE[2235][C-00000029] chan_sip.c: Ignoring this INVITE request
1716353 [2021-12-30 19:02:09] VERBOSE[2235][C-00000029] chan_sip.c:
1716354 <--- Transmitting (no NAT) to 172.16.173.148:5060 --->
1716355 SIP/2.0 100 Trying
1716356 Via: SIP/2.0/UDP 172.16.173.148:5060;branch=z9hG4bK70ad6c24;received=172.16.173.148
1716357 From: "Смирнов Test_1" <sip:9102999@172.16.173.148>;tag=as04ba4c0f
1716358 To: <sip:20608@172.22.106.13>
1716359 Call-ID: 3e472f183fedfe8154f0f9230da009c5@172.16.173.148:5060
1716360 CSeq: 101 INVITE
1716361 Server: FPBX-15.0.17.34(16.17.0)
1716362 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
1716363 Supported: replaces, timer
1716364 Session-Expires: 1800;refresher=uas
1716365 Contact: <sip:20608@172.22.106.13:5060>
1716366 Content-Length: 0
1716367
1716368
1716369 <------------>
1716370 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] res_agi.c: <SIP/10-SPB_test_node_2 trunk_INC-0000004a>AGI Script agi://127.0.0.1/sangomacrm.agi completed, returning 0
1716371 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:25] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "CHANNEL(hangup_handler_push)=crm-hangup,s,1") in new stack
1716372 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:26] NoOp("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "CRM Finished") in new stack
1716373 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:27] GotoIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?bypass,1") in new stack
1716374 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:28] ExecIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?Set(CONNECTEDLINE(num,i)=20608)") in new stack
1716375 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:29] ExecIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?Set(CONNECTEDLINE(name,i)=CID:9102999)") in new stack
1716376 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:30] ExecIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?Set(CONNECTEDLINE(name,i)=CID:(Hidden)9102999)") in new stack
1716377 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:31] GotoIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?customtrunk") in new stack
1716378 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:32] ExecIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?Set(DIAL_TRUNK_OPTIONS=)") in new stack
1716379 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:33] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "HASH(__SIPHEADERS,Alert-Info)=unset") in new stack
1716380 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:34] Dial("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "SIP/Magadan-Out/20608,300,Tb(func-apply-sipheaders^s^1,(1))U(sub-send-obroute-email^20608^20608^1^1640851329^Смирнов Test_1^9102999)") in new stack
1716381 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] netsock2.c: Using SIP RTP TOS bits 184
1716382 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] netsock2.c: Using SIP RTP CoS mark 5
1716383 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] app_stack.c: SIP/Magadan-Out-0000004b Internal Gosub(func-apply-sipheaders,s,1(1)) start
1716384 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf("SIP/Magadan-Out-0000004b", "0?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
1716385 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp("SIP/Magadan-Out-0000004b", "Applying SIP Headers to channel SIP/Magadan-Out-0000004b") in new stack
1716386 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@func-apply-sipheaders:3] Set("SIP/Magadan-Out-0000004b", "TECH=SIP") in new stack
1716387 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@func-apply-sipheaders:4] Set("SIP/Magadan-Out-0000004b", "SIPHEADERKEYS=Alert-Info") in new stack
1716388 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@func-apply-sipheaders:5] While("SIP/Magadan-Out-0000004b", "1") in new stack
1716389 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@func-apply-sipheaders:6] Set("SIP/Magadan-Out-0000004b", "sipheader=unset") in new stack
1716390 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@func-apply-sipheaders:7] ExecIf("SIP/Magadan-Out-0000004b", "1?SIPRemoveHeader(Alert-Info:)") in new stack
1716391 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@func-apply-sipheaders:8] ExecIf("SIP/Magadan-Out-0000004b", "0?Set(PJSIP_HEADER(remove,Alert-Info)=)") in new stack
1716392 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@func-apply-sipheaders:9] ExecIf("SIP/Magadan-Out-0000004b", "0?Set(sipheader=<http://127.0.0.1>;info=unset)") in new stack
1716393 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@func-apply-sipheaders:10] ExecIf("SIP/Magadan-Out-0000004b", "0?Set(sipheader=<http://127.0.0.1>unset)") in new stack
1716394 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@func-apply-sipheaders:11] ExecIf("SIP/Magadan-Out-0000004b", "0?SIPAddHeader(Alert-Info:unset)") in new stack
1716395 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@func-apply-sipheaders:12] ExecIf("SIP/Magadan-Out-0000004b", "0?Set(PJSIP_HEADER(add,Alert-Info)=unset)") in new stack
1716396 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@func-apply-sipheaders:13] EndWhile("SIP/Magadan-Out-0000004b", "") in new stack
1716397 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@func-apply-sipheaders:5] While("SIP/Magadan-Out-0000004b", "0") in new stack
1716398 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@func-apply-sipheaders:14] Return("SIP/Magadan-Out-0000004b", "") in new stack
1716399 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] app_stack.c: Spawn extension (from-internal, 20608, 1) exited non-zero on 'SIP/Magadan-Out-0000004b'
1716400 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] app_stack.c: SIP/Magadan-Out-0000004b Internal Gosub(func-apply-sipheaders,s,1(1)) complete GOSUB_RETVAL=
1716401 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] chan_sip.c: Audio is at 10004
1716402 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] chan_sip.c: Adding codec g729 to SDP
1716403 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] chan_sip.c: Adding non-codec 0x1 (telephone-event) to SDP
1716404 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] chan_sip.c: Reliably Transmitting (no NAT) to 172.22.106.10:5060:
1716405 INVITE sip:20608@172.22.106.10 SIP/2.0
1716406 Via: SIP/2.0/UDP 172.22.106.13:5060;branch=z9hG4bK2d43b9a4
1716407 Max-Forwards: 70
1716408 From: "Смирнов Test_1" <sip:9102999@172.22.106.13>;tag=as64a5262d
1716409 To: <sip:20608@172.22.106.10>
1716410 Contact: <sip:9102999@172.22.106.13:5060>
1716411 Call-ID: 5fc1a8574d62c1a427f8362c58c90601@172.22.106.13:5060
1716412 CSeq: 102 INVITE
1716413 User-Agent: FPBX-15.0.17.34(16.17.0)
1716414 Date: Thu, 30 Dec 2021 08:02:09 GMT
1716415 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
1716416 Supported: replaces, timer
1716417 Content-Type: application/sdp
1716418 Content-Length: 277
1716419
1716420 v=0
1716421 o=root 1064080542 1064080542 IN IP4 172.22.106.13
1716422 s=Asterisk PBX 16.17.0
1716423 c=IN IP4 172.22.106.13
1716424 t=0 0
1716425 m=audio 10004 RTP/AVP 18 101
1716426 a=rtpmap:18 G729/8000
1716427 a=fmtp:18 annexb=no
1716428 a=rtpmap:101 telephone-event/8000
1716429 a=fmtp:101 0-16
1716430 a=ptime:20
1716431 a=maxptime:230
1716432 a=sendrecv
1716433
1716434 ---
1716435 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] app_dial.c: Called SIP/Magadan-Out/20608
1716436 [2021-12-30 19:02:09] VERBOSE[2235] chan_sip.c:
1716437 <--- SIP read from UDP:172.22.106.10:5060 --->
1716438 SIP/2.0 100 Trying
1716439 Via: SIP/2.0/UDP 172.22.106.13:5060;branch=z9hG4bK2d43b9a4
1716440 To: sip:20608@172.22.106.10
1716441 From: "Смирнов Test_1" <sip:9102999@172.22.106.13>;tag=as64a5262d
1716442 Call-ID: 5fc1a8574d62c1a427f8362c58c90601@172.22.106.13:5060
1716443 CSeq: 102 INVITE
1716444 Content-Length: 0
1716445
1716446 <------------->
1716447 [2021-12-30 19:02:09] VERBOSE[2235] chan_sip.c: --- (7 headers 0 lines) ---
1716448 [2021-12-30 19:02:09] VERBOSE[2235] chan_sip.c:
1716449 <--- SIP read from UDP:172.22.106.10:5060 --->
1716450 SIP/2.0 407 Proxy Authentication Required
1716451 Via: SIP/2.0/UDP 172.22.106.13:5060;branch=z9hG4bK2d43b9a4
1716452 To: sip:20608@172.22.106.10;tag=7985
1716453 From: "Смирнов Test_1" <sip:9102999@172.22.106.13>;tag=as64a5262d
1716454 Call-ID: 5fc1a8574d62c1a427f8362c58c90601@172.22.106.13:5060
1716455 CSeq: 102 INVITE
1716456 Allow: INVITE,ACK,CANCEL,BYE,REGISTER
1716457 Proxy-Authenticate: Digest realm="Registered Users",nonce="458b162c58b163c68d1a3468d1a3468c",algorithm=MD5
1716458 Content-Length: 0
1716459
1716460 <------------->
1716461 [2021-12-30 19:02:09] VERBOSE[2235] chan_sip.c: --- (9 headers 0 lines) ---
1716462 [2021-12-30 19:02:09] VERBOSE[2235][C-00000029] chan_sip.c: Transmitting (no NAT) to 172.22.106.10:5060:
1716463 ACK sip:20608@172.22.106.10 SIP/2.0
1716464 Via: SIP/2.0/UDP 172.22.106.13:5060;branch=z9hG4bK2d43b9a4
1716465 Max-Forwards: 70
1716466 From: "Смирнов Test_1" <sip:9102999@172.22.106.13>;tag=as64a5262d
1716467 To: <sip:20608@172.22.106.10>;tag=7985
1716468 Contact: <sip:9102999@172.22.106.13:5060>
1716469 Call-ID: 5fc1a8574d62c1a427f8362c58c90601@172.22.106.13:5060
1716470 CSeq: 102 ACK
1716471 User-Agent: FPBX-15.0.17.34(16.17.0)
1716472 Content-Length: 0
1716473
1716474
1716475 ---
1716476 [2021-12-30 19:02:09] NOTICE[2235][C-00000029] chan_sip.c: Failed to authenticate on INVITE to '"Смирнов Test_1" <sip:9102999@172.22.106.13>;tag=as64a5262d'
1716477 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] app_dial.c: SIP/Magadan-Out-0000004b is circuit-busy
1716478 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] app_dial.c: Everyone is busy/congested at this time (1:0/1/0)
1716479 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:35] NoOp("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "Dial failed for some reason with DIALSTATUS = CONGESTION and HANGUPCAUSE = 21") in new stack
1716480 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:36] GotoIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?continue,1:s-CONGESTION,1") in new stack
1716481 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx_builtins.c: Goto (macro-dialout-trunk,s-CONGESTION,1)
1716482 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s-CONGESTION@macro-dialout-trunk:1] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "RC=21") in new stack
1716483 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s-CONGESTION@macro-dialout-trunk:2] Goto("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "21,1") in new stack
1716484 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx_builtins.c: Goto (macro-dialout-trunk,21,1)
1716485 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [21@macro-dialout-trunk:1] Goto("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "continue,1") in new stack
1716486 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx_builtins.c: Goto (macro-dialout-trunk,continue,1)
1716487 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [continue@macro-dialout-trunk:1] NoOp("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "TRUNK Dial failed due to CONGESTION HANGUPCAUSE: 21 - failing through to other trunks") in new stack
1716488 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [continue@macro-dialout-trunk:2] ExecIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?Set(CALLERID(number)=)") in new stack
1716489 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [20608@from-internal:12] Macro("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "outisbusy,") in new stack
1716490 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outisbusy:1] Progress("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "") in new stack
1716491 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] chan_sip.c: Audio is at 18978
1716492 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] chan_sip.c: Adding codec g729 to SDP
1716493 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] chan_sip.c: Adding non-codec 0x1 (telephone-event) to SDP
1716494 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] chan_sip.c:
1716495 <--- Transmitting (no NAT) to 172.16.173.148:5060 --->
1716496 SIP/2.0 183 Session Progress
1716497 Via: SIP/2.0/UDP 172.16.173.148:5060;branch=z9hG4bK70ad6c24;received=172.16.173.148
1716498 From: "Смирнов Test_1" <sip:9102999@172.16.173.148>;tag=as04ba4c0f
1716499 To: <sip:20608@172.22.106.13>;tag=as76ada22c
1716500 Call-ID: 3e472f183fedfe8154f0f9230da009c5@172.16.173.148:5060
1716501 CSeq: 101 INVITE
1716502 Server: FPBX-15.0.17.34(16.17.0)
1716503 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
1716504 Supported: replaces, timer
1716505 Session-Expires: 1800;refresher=uas
1716506 Contact: <sip:20608@172.22.106.13:5060>
1716507 Content-Type: application/sdp
1716508 Require: timer
1716509 Content-Length: 277
1716510
1716511 v=0
1716512 o=root 1253218799 1253218799 IN IP4 172.22.106.13
1716513 s=Asterisk PBX 16.17.0
1716514 c=IN IP4 172.22.106.13
1716515 t=0 0
1716516 m=audio 18978 RTP/AVP 18 101
1716517 a=rtpmap:18 G729/8000
1716518 a=fmtp:18 annexb=no
1716519 a=rtpmap:101 telephone-event/8000
1716520 a=fmtp:101 0-16
1716521 a=ptime:20
1716522 a=maxptime:230
1716523 a=sendrecv
1716524
1716525 <------------>
1716526 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outisbusy:2] GotoIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?emergency,1") in new stack
1716527 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outisbusy:3] GotoIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?intracompany,1") in new stack
1716528 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outisbusy:4] Playback("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "all-circuits-busy-now&please-try-call-later, noanswer") in new stack
1716529 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] file.c: <SIP/10-SPB_test_node_2 trunk_INC-0000004a> Playing 'all-circuits-busy-now.slin16' (language 'en')
1716530 [2021-12-30 19:02:09] VERBOSE[2235] chan_sip.c:
1716701 [2021-12-30 19:02:12] VERBOSE[2235] chan_sip.c: --- (13 headers 0 lines) ---
1716702 [2021-12-30 19:02:12] VERBOSE[2235] chan_sip.c: Sending to 172.24.29.13:5060 (no NAT)
1716703 [2021-12-30 19:02:12] VERBOSE[2235] chan_sip.c: Looking for s in from-sip-external (domain 172.22.106.13)
1716704 [2021-12-30 19:02:12] VERBOSE[2235] chan_sip.c:
1716705 <--- Transmitting (no NAT) to 172.24.29.13:5060 --->
1716706 SIP/2.0 200 OK
1716707 Via: SIP/2.0/UDP 172.24.29.13:5060;branch=z9hG4bK708bc6d7;received=172.24.29.13
1716708 From: "Unknown" <sip:Unknown@172.24.29.13>;tag=as083fbd5e
1716709 To: <sip:172.22.106.13>;tag=as21e24cc1
1716710 Call-ID: 5333d41543bfdd1e2ee0242c5f16bf0f@172.24.29.13:5060
1716711 CSeq: 102 OPTIONS
1716712 Server: FPBX-15.0.17.34(16.17.0)
1716713 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
1716714 Supported: replaces, timer
1716715 Contact: <sip:172.22.106.13:5060>
1716716 Accept: application/sdp
1716717 Content-Length: 0
1716718
1716719
1716720 <------------>
1716721 [2021-12-30 19:02:12] VERBOSE[2235] chan_sip.c: Scheduling destruction of SIP dialog '5333d41543bfdd1e2ee0242c5f16bf0f@172.24.29.13:5060' in 32000 ms (Method: OPTIONS)
1716722 [2021-12-30 19:02:12] VERBOSE[2235] chan_sip.c: Really destroying SIP dialog '7addd84456b61034197d40cb4dcd2cf2@172.26.11.100:5060' Method: OPTIONS
1716723 [2021-12-30 19:02:13] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outisbusy:5] Congestion("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "20") in new stack
1716724 [2021-12-30 19:02:13] VERBOSE[208279][C-00000029] chan_sip.c:
1716725 <--- Reliably Transmitting (no NAT) to 172.16.173.148:5060 --->
1716726 SIP/2.0 503 Service Unavailable
1716727 Via: SIP/2.0/UDP 172.16.173.148:5060;branch=z9hG4bK70ad6c24;received=172.16.173.148
1716728 From: "Смирнов Test_1" <sip:9102999@172.16.173.148>;tag=as04ba4c0f
1716729 To: <sip:20608@172.22.106.13>;tag=as76ada22c
1716730 Call-ID: 3e472f183fedfe8154f0f9230da009c5@172.16.173.148:5060
1716731 CSeq: 101 INVITE
1716732 Server: FPBX-15.0.17.34(16.17.0)
1716733 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
1716734 Supported: replaces, timer
1716735 Session-Expires: 1800;refresher=uas
1716736 X-Asterisk-HangupCause: Call Rejected
1716737 X-Asterisk-HangupCauseCode: 21
1716738 Content-Length: 0
1716739
1716740
1716741 <------------>
1716742 [2021-12-30 19:02:13] WARNING[208279][C-00000029] channel.c: Prodding channel 'SIP/10-SPB_test_node_2 trunk_INC-0000004a' failed
1716743 [2021-12-30 19:02:13] VERBOSE[208279][C-00000029] app_macro.c: Spawn extension (macro-outisbusy, s, 5) exited non-zero on 'SIP/10-SPB_test_node_2 trunk_INC-0000004a' in macro 'outisbusy'
1716744 [2021-12-30 19:02:13] VERBOSE[208279][C-00000029] pbx.c: Spawn extension (from-internal, 20608, 12) exited non-zero on 'SIP/10-SPB_test_node_2 trunk_INC-0000004a'
1716745 [2021-12-30 19:02:13] VERBOSE[208279][C-00000029] pbx.c: Executing [h@from-internal:1] Macro("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "hangupcall") in new stack
1716746 [2021-12-30 19:02:13] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "1?theend") in new stack
1716747 [2021-12-30 19:02:13] VERBOSE[208279][C-00000029] pbx_builtins.c: Goto (macro-hangupcall,s,3)
1716748 [2021-12-30 19:02:13] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?Set(CDR(recordingfile)=)") in new stack
1716749 [2021-12-30 19:02:13] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-hangupcall:4] NoOp("SIP/10-SPB_test_node_2 trunk_INC-0000004a", " montior file= ") in new stack
1716750 [2021-12-30 19:02:13] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-hangupcall:5] GotoIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "1?skipagi") in new stack
1716751 [2021-12-30 19:02:13] VERBOSE[208279][C-00000029] pbx_builtins.c: Goto (macro-hangupcall,s,7)
1716752 [2021-12-30 19:02:13] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-hangupcall:7] Hangup("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "") in new stack
1716753 [2021-12-30 19:02:13] VERBOSE[208279][C-00000029] app_macro.c: Spawn extension (macro-hangupcall, s, 7) exited non-zero on 'SIP/10-SPB_test_node_2 trunk_INC-0000004a' in macro 'hangupcall'
1716754 [2021-12-30 19:02:13] VERBOSE[208279][C-00000029] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/10-SPB_test_node_2 trunk_INC-0000004a'
1716755 [2021-12-30 19:02:13] VERBOSE[208279][C-00000029] app_stack.c: SIP/10-SPB_test_node_2 trunk_INC-0000004a Internal Gosub(crm-hangup,s,1) start
1716756 [2021-12-30 19:02:13] VERBOSE[208279][C-00000029] pbx.c: Executing [s@crm-hangup:1] NoOp("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "Sending Hangup to CRM") in new stack
1716757 [2021-12-30 19:02:13] VERBOSE[208279][C-00000029] pbx.c: Executing [s@crm-hangup:2] NoOp("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "HANGUP CAUSE: 34") in new stack
1716758 [2021-12-30 19:02:13] VERBOSE[208279][C-00000029] pbx.c: Executing [s@crm-hangup:3] ExecIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?Set(__CRM_VOICEMAIL=)") in new stack
1716759 [2021-12-30 19:02:13] VERBOSE[208279][C-00000029] pbx.c: Executing [s@crm-hangup:4] NoOp("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "MASTER CHANNEL: 1640851329.74 = 1640851329.74") in new stack
1716760 [2021-12-30 19:02:13] VERBOSE[208279][C-00000029] pbx.c: Executing [s@crm-hangup:5] GotoIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?return") in new stack
1716761 [2021-12-30 19:02:13] VERBOSE[208279][C-00000029] pbx.c: Executing [s@crm-hangup:6] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "__CRM_HANGUP=1") in new stack
1716762 [2021-12-30 19:02:13] VERBOSE[208279][C-00000029] pbx.c: Executing [s@crm-hangup:7] AGI("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "agi://127.0.0.1/sangomacrm.agi") in new stack
1716763 [2021-12-30 19:02:13] VERBOSE[2235] chan_sip.c: Retransmitting #1 (no NAT) to 172.16.173.148:5060:
1716764 SIP/2.0 503 Service Unavailable
1716765 Via: SIP/2.0/UDP 172.16.173.148:5060;branch=z9hG4bK70ad6c24;received=172.16.173.148
1716766 From: "Смирнов Test_1" <sip:9102999@172.16.173.148>;tag=as04ba4c0f
1716767 To: <sip:20608@172.22.106.13>;tag=as76ada22c
1716768 Call-ID: 3e472f183fedfe8154f0f9230da009c5@172.16.173.148:5060
1716769 CSeq: 101 INVITE
1716770 Server: FPBX-15.0.17.34(16.17.0)
1716771 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
1716772 Supported: replaces, timer
1716773 Session-Expires: 1800;refresher=uas
1716774 X-Asterisk-HangupCause: Call Rejected
1716775 X-Asterisk-HangupCauseCode: 21
1716776 Content-Length: 0
1716777
1716778
1716779 ---
1716780 [2021-12-30 19:02:13] VERBOSE[2235] chan_sip.c:
1716781 <--- SIP read from UDP:172.16.173.148:5060 --->
1716782 ACK sip:20608@172.22.106.13:5060 SIP/2.0
1716783 Via: SIP/2.0/UDP 172.16.173.148:5060;branch=z9hG4bK70ad6c24
1716784 Max-Forwards: 70
1716785 From: "Смирнов Test_1" <sip:9102999@172.16.173.148>;tag=as04ba4c0f
1716786 To: <sip:20608@172.22.106.13>;tag=as76ada22c
1716787 Contact: <sip:9102999@172.16.173.148:5060>
1716788 Call-ID: 3e472f183fedfe8154f0f9230da009c5@172.16.173.148:5060
1716789 CSeq: 101 ACK
1716790 User-Agent: FPBX-14.0.16(16.13.0)
1716791 Content-Length: 0
1716792
1716793 <------------->
1716794 [2021-12-30 19:02:13] VERBOSE[2235] chan_sip.c: --- (10 headers 0 lines) ---
1716795 [2021-12-30 19:02:13] VERBOSE[208279][C-00000029] res_agi.c: <SIP/10-SPB_test_node_2 trunk_INC-0000004a>AGI Script agi://127.0.0.1/sangomacrm.agi completed, returning 0
1716796 [2021-12-30 19:02:13] VERBOSE[208279][C-00000029] pbx.c: Executing [s@crm-hangup:8] Return("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "") in new stack
1716797 [2021-12-30 19:02:13] VERBOSE[208279][C-00000029] app_stack.c: Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/10-SPB_test_node_2 trunk_INC-0000004a'
1716798 [2021-12-30 19:02:13] VERBOSE[208279][C-00000029] app_stack.c: SIP/10-SPB_test_node_2 trunk_INC-0000004a Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
1716799 [2021-12-30 19:02:13] VERBOSE[2235] chan_sip.c: Really destroying SIP dialog '712e538d111ac2a579fcb39f29776259@172.26.130.13:5060' Method: OPTIONS
1716800 [2021-12-30 19:02:13] VERBOSE[2235] chan_sip.c: Really destroying SIP dialog '3e472f183fedfe8154f0f9230da009c5@172.16.173.148:5060' Method: ACK
1716801 [2021-12-30 19:02:14] VERBOSE[2235] chan_sip.c:
1716802 <--- SIP read from UDP:172.26.114.13:5060 --->
1716803 OPTIONS sip:172.22.106.13 SIP/2.0
1716804 Via: SIP/2.0/UDP 172.26.114.13:5060;branch=z9hG4bK135579cd
1716805 Max-Forwards: 70
1716806 From: "Unknown" <sip:Unknown@172.26.114.13>;tag=as788a0a8d
1716807 To: <sip:172.22.106.13>
1716808 Contact: <sip:Unknown@172.26.114.13:5060>
1716809 Call-ID: 790a66b9380f97e13cddfcdd05f02c08@172.26.114.13:5060
1716810 CSeq: 102 OPTIONS
1716811 User-Agent: FPBX-15.0.17.34(16.17.0)
1716812 Date: Thu, 30 Dec 2021 08:02:13 GMT
1716813 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
1716814 Supported: replaces, timer
1716815 Content-Length: 0
1716133 INVITE sip:20608@172.22.106.13 SIP/2.0
1716134 Via: SIP/2.0/UDP 172.16.173.148:5060;branch=z9hG4bK70ad6c24
1716135 Max-Forwards: 70
1716136 From: "Смирнов Test_1" <sip:9102999@172.16.173.148>;tag=as04ba4c0f
1716137 To: <sip:20608@172.22.106.13>
1716138 Contact: <sip:9102999@172.16.173.148:5060>
1716139 Call-ID: 3e472f183fedfe8154f0f9230da009c5@172.16.173.148:5060
1716140 CSeq: 101 INVITE
1716141 User-Agent: FPBX-14.0.16(16.13.0)
1716142 Date: Thu, 30 Dec 2021 08:02:09 GMT
1716143 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
1716144 Supported: replaces,timer
1716145 Content-Type: application/sdp
1716146 Content-Length: 279
1716147
1716148 v=0
1716149 o=root 1652409066 1652409066 IN IP4 172.16.173.148
1716150 s=Asterisk PBX 16.13.0
1716151 c=IN IP4 172.16.173.148
1716152 t=0 0
1716153 m=audio 16276 RTP/AVP 18 101
1716154 a=rtpmap:18 G729/8000
1716155 a=fmtp:18 annexb=no
1716156 a=rtpmap:101 telephone-event/8000
1716157 a=fmtp:101 0-16
1716158 a=ptime:20
1716159 a=maxptime:230
1716160 a=sendrecv
1716161 <------------->
1716162 [2021-12-30 19:02:09] VERBOSE[2235] chan_sip.c: --- (14 headers 13 lines) ---
1716163 [2021-12-30 19:02:09] VERBOSE[2235] chan_sip.c: Sending to 172.16.173.148:5060 (no NAT)
1716164 [2021-12-30 19:02:09] VERBOSE[2235][C-00000029] chan_sip.c: Sending to 172.16.173.148:5060 (no NAT)
1716165 [2021-12-30 19:02:09] VERBOSE[2235][C-00000029] chan_sip.c: Using INVITE request as basis request - 3e472f183fedfe8154f0f9230da009c5@172.16.173.148:5060
1716166 [2021-12-30 19:02:09] VERBOSE[2235][C-00000029] chan_sip.c: Found peer '10-SPB_test_node_2 trunk_INC' for '9102999' from 172.16.173.148:5060
1716167 [2021-12-30 19:02:09] VERBOSE[2235][C-00000029] netsock2.c: Using SIP RTP TOS bits 184
1716168 [2021-12-30 19:02:09] VERBOSE[2235][C-00000029] netsock2.c: Using SIP RTP CoS mark 5
1716169 [2021-12-30 19:02:09] VERBOSE[2235][C-00000029] chan_sip.c: Got SDP version 1652409066 and unique parts [root 1652409066 IN IP4 172.16.173.148]
1716170 [2021-12-30 19:02:09] VERBOSE[2235][C-00000029] chan_sip.c: Found RTP audio format 18
1716171 [2021-12-30 19:02:09] VERBOSE[2235][C-00000029] chan_sip.c: Found RTP audio format 101
1716172 [2021-12-30 19:02:09] VERBOSE[2235][C-00000029] chan_sip.c: Found audio description format G729 for ID 18
1716173 [2021-12-30 19:02:09] VERBOSE[2235][C-00000029] chan_sip.c: Found audio description format telephone-event for ID 101
1716174 [2021-12-30 19:02:09] VERBOSE[2235][C-00000029] chan_sip.c: Capabilities: us - (g729), peer - audio=(g729)/video=(nothing)/text=(nothing), combined - (g729)
1716175 [2021-12-30 19:02:09] VERBOSE[2235][C-00000029] chan_sip.c: Non-codec capabilities (dtmf): us - 0x1 (telephone-event|), peer - 0x1 (telephone-event|), combined - 0x1 (telephone-event|)
1716176 [2021-12-30 19:02:09] VERBOSE[2235][C-00000029] chan_sip.c: Peer audio RTP is at port 172.16.173.148:16276
1716177 [2021-12-30 19:02:09] VERBOSE[2235][C-00000029] chan_sip.c: Looking for 20608 in from-internal (domain 172.22.106.13)
1716178 [2021-12-30 19:02:09] VERBOSE[2235][C-00000029] sip/route.c: sip_route_dump: route/path hop: <sip:9102999@172.16.173.148:5060>
1716179 [2021-12-30 19:02:09] VERBOSE[2235][C-00000029] chan_sip.c:
1716180 <--- Transmitting (no NAT) to 172.16.173.148:5060 --->
1716181 SIP/2.0 100 Trying
1716182 Via: SIP/2.0/UDP 172.16.173.148:5060;branch=z9hG4bK70ad6c24;received=172.16.173.148
1716183 From: "Смирнов Test_1" <sip:9102999@172.16.173.148>;tag=as04ba4c0f
1716184 To: <sip:20608@172.22.106.13>
1716185 Call-ID: 3e472f183fedfe8154f0f9230da009c5@172.16.173.148:5060
1716186 CSeq: 101 INVITE
1716187 Server: FPBX-15.0.17.34(16.17.0)
1716188 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
1716189 Supported: replaces, timer
1716190 Session-Expires: 1800;refresher=uas
1716191 Contact: <sip:20608@172.22.106.13:5060>
1716192 Content-Length: 0
1716193
1716194
1716195 <------------>
1716196 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [20608@from-internal:1] Macro("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "user-callerid,LIMIT,EXTERNAL,") in new stack
1716197 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-user-callerid:1] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "TOUCH_MONITOR=1640851329.74") in new stack
1716198 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-user-callerid:2] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "AMPUSER=9102999") in new stack
1716199 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-user-callerid:3] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "HOTDESCKCHAN=10-SPB_test_node_2 trunk_INC-0000004a") in new stack
1716200 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-user-callerid:4] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "HOTDESKEXTEN=10") in new stack
1716201 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-user-callerid:5] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "HOTDESKCALL=0") in new stack
1716202 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-user-callerid:6] ExecIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?Set(HOTDESKCALL=1)") in new stack
1716203 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-user-callerid:7] ExecIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?Set(CALLERID(name)=)") in new stack
1716204 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-user-callerid:8] GotoIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?report") in new stack
1716205 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-user-callerid:9] ExecIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "1?Set(REALCALLERIDNUM=9102999)") in new stack
1716206 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-user-callerid:10] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "AMPUSER=") in new stack
1716207 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-user-callerid:11] GotoIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?limit") in new stack
1716208 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-user-callerid:12] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "AMPUSERCIDNAME=") in new stack
1716209 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-user-callerid:13] ExecIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?Set(__CIDMASQUERADING=TRUE)") in new stack
1716210 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-user-callerid:14] GotoIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "1?report") in new stack
1716211 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx_builtins.c: Goto (macro-user-callerid,s,23)
1716212 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-user-callerid:23] NoOp("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "Macro Depth is 1") in new stack
1716213 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-user-callerid:24] GotoIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "1?report2:macroerror") in new stack
1716214 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx_builtins.c: Goto (macro-user-callerid,s,25)
1716215 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-user-callerid:25] GotoIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "1?continue") in new stack
1716216 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx_builtins.c: Goto (macro-user-callerid,s,44)
1716217 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-user-callerid:44] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "CALLERID(number)=9102999") in new stack
1716218 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-user-callerid:45] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "CALLERID(name)=Смирнов Test_1") in new stack
1716219 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-user-callerid:46] GotoIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?cnum") in new stack
1716220 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-user-callerid:47] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "CDR(cnam)=Смирнов Test_1") in new stack
1716221 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-user-callerid:48] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "CDR(cnum)=9102999") in new stack
1716222 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-user-callerid:49] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "CHANNEL(language)=en") in new stack
1716223 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [20608@from-internal:2] Gosub("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "sub-record-check,s,1(out,20608,dontcare)") in new stack
1716224 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@sub-record-check:1] GotoIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?initialized") in new stack
1716225 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@sub-record-check:2] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "__REC_STATUS=INITIALIZED") in new stack
1716226 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@sub-record-check:3] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "NOW=1640851329") in new stack
1716227 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@sub-record-check:4] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "__DAY=30") in new stack
1716228 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@sub-record-check:5] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "__MONTH=12") in new stack
1716229 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@sub-record-check:6] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "__YEAR=2021") in new stack
1716230 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@sub-record-check:7] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "__TIMESTR=20211230-190209") in new stack
1716231 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@sub-record-check:8] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "__FROMEXTEN=9102999") in new stack
1716232 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@sub-record-check:9] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "__MON_FMT=wav") in new stack
1716233 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@sub-record-check:10] NoOp("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "Recordings initialized") in new stack
1716234 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@sub-record-check:11] ExecIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?Set(ARG3=dontcare)") in new stack
1716235 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@sub-record-check:12] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "REC_POLICY_MODE_SAVE=") in new stack
1716236 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@sub-record-check:13] ExecIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?Set(REC_STATUS=NO)") in new stack
1716237 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@sub-record-check:14] GotoIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "3?checkaction") in new stack
1716238 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx_builtins.c: Goto (sub-record-check,s,17)
1716239 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@sub-record-check:17] GotoIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "1?sub-record-check,out,1") in new stack
1716240 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx_builtins.c: Goto (sub-record-check,out,1)
1716241 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [out@sub-record-check:1] NoOp("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "Outbound Recording Check from 9102999 to 20608") in new stack
1716242 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [out@sub-record-check:2] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "RECMODE=") in new stack
1716243 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [out@sub-record-check:3] ExecIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "1?Goto(routewins)") in new stack
1716244 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx_builtins.c: Goto (sub-record-check,out,7)
1716245 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [out@sub-record-check:7] Gosub("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "recordcheck,1(dontcare,out,20608)") in new stack
1716246 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "Starting recording check against dontcare") in new stack
1716247 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "dontcare") in new stack
1716248 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
1716249 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [recordcheck@sub-record-check:3] Return("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "") in new stack
1716250 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [out@sub-record-check:8] Return("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "") in new stack
1716251 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [20608@from-internal:3] ExecIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0 ?Set(CDR(accountcode)=)") in new stack
1716252 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [20608@from-internal:4] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "_ROUTEID=2") in new stack
1716253 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [20608@from-internal:5] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "_ROUTENAME=Na sebya 20 Magadan") in new stack
1716254 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [20608@from-internal:6] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "MOHCLASS=default") in new stack
1716255 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [20608@from-internal:7] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "_CALLERIDNAMEINTERNAL=Смирнов Test_1") in new stack
1716256 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [20608@from-internal:8] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "_CALLERIDNUMINTERNAL=9102999") in new stack
1716257 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [20608@from-internal:9] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "_EMAILNOTIFICATION=FALSE") in new stack
1716258 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [20608@from-internal:10] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "_NODEST=") in new stack
1716259 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [20608@from-internal:11] Macro("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "dialout-trunk,1,20608,,off") in new stack
1716260 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:1] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "DIAL_TRUNK=1") in new stack
1716261 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:2] ExecIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?Set(DIAL_OPTIONS=Hhtr)") in new stack
1716262 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:3] GosubIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?sub-pincheck,s,1()") in new stack
1716263 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:4] ExecIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?Set(CALLERID(num)=)") in new stack
1716264 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:5] GotoIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?disabletrunk,1") in new stack
1716265 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:6] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "DIAL_NUMBER=20608") in new stack
1716266 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:7] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "DIAL_TRUNK_OPTIONS=HhTtr") in new stack
1716267 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:8] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "OUTBOUND_GROUP=OUT_1") in new stack
1716268 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:9] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "DIAL_TRUNK_OPTIONS=T") in new stack
1716269 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:10] GotoIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "1?nomax") in new stack
1716270 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx_builtins.c: Goto (macro-dialout-trunk,s,12)
1716271 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:12] GotoIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?skipoutcid") in new stack
1716272 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:13] Macro("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "outbound-callerid,1") in new stack
1716273 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:1] NoOp("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "9102999") in new stack
1716274 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:2] NoOp("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "") in new stack
1716275 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:3] NoOp("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "off") in new stack
1716276 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:4] ExecIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?Set(CALLERPRES(name-pres)=)") in new stack
1716277 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:5] ExecIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?Set(CALLERPRES(num-pres)=)") in new stack
1716278 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:6] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "HOTDESCKCHAN=10-SPB_test_node_2 trunk_INC-0000004a") in new stack
1716279 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:7] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "HOTDESKEXTEN=10") in new stack
1716280 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:8] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "HOTDESKCALL=0") in new stack
1716281 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:9] ExecIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?Set(HOTDESKCALL=1)") in new stack
1716282 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:10] ExecIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?Set(CALLERID(name)=)") in new stack
1716283 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:11] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "ALLOWTHISROUTE=NO") in new stack
1716284 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:12] ExecIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?Set(ALLOWTHISROUTE=YES)") in new stack
1716285 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:13] ExecIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?Hangup()") in new stack
1716286 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:14] ExecIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?Set(REALCALLERIDNUM=9102999)") in new stack
1716287 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:15] ExecIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?Set(AMPUSER=9102999)") in new stack
1716288 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:16] GotoIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "1?normcid") in new stack
1716289 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx_builtins.c: Goto (macro-outbound-callerid,s,20)
1716290 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:20] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "USEROUTCID=") in new stack
1716291 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:21] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "EMERGENCYCID=") in new stack
1716292 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:22] ExecIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?Set(EMERGENCYCID=)") in new stack
1716293 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:23] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "TRUNKOUTCID=") in new stack
1716294 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:24] GotoIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "1?trunkcid") in new stack
1716295 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx_builtins.c: Goto (macro-outbound-callerid,s,30)
1716296 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:30] ExecIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?Set(CALLERID(all)=)") in new stack
1716297 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:31] ExecIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?Set(CALLERID(all)=)") in new stack
1716298 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:32] ExecIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?Set(CALLERID(all)=)") in new stack
1716299 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:33] ExecIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?Set(CALLERID(all)=9102999)") in new stack
1716300 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:34] ExecIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?Set(CALLERID(all)=9102999)") in new stack
1716301 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:35] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "TIOHIDE=no") in new stack
1716302 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:36] ExecIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?Set(CALLERPRES(name-pres)=prohib_passed_screen)") in new stack
1716303 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:37] ExecIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?Set(CALLERPRES(num-pres)=prohib_passed_screen)") in new stack
1716304 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:38] ExecIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?Set(CALLERPRES(name-pres)=prohib_passed_screen)") in new stack
1716305 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:39] ExecIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?Set(CALLERPRES(num-pres)=prohib_passed_screen)") in new stack
1716306 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:40] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "CDR(outbound_cnum)=9102999") in new stack
1716307 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outbound-callerid:41] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "CDR(outbound_cnam)=Смирнов Test_1") in new stack
1716308 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:14] GosubIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?sub-flp-1,s,1()") in new stack
1716309 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:15] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "OUTNUM=20608") in new stack
1716310 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:16] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "custom=SIP/Magadan-Out") in new stack
1716311 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:17] ExecIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)T)") in new stack
1716312 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:18] ExecIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?Set(DIAL_TRUNK_OPTIONS=TM(confirm))") in new stack
1716313 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:19] Macro("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "dialout-trunk-predial-hook,") in new stack
1716314 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "") in new stack
1716315 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:20] GotoIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?skipcrm") in new stack
1716316 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:21] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "__CRM_DIRECTION=OUTBOUND") in new stack
1716317 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:22] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "__CRM_DESTINATION=20608") in new stack
1716318 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:23] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "__CRM_SOURCE=") in new stack
1716319 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:24] AGI("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "agi://127.0.0.1/sangomacrm.agi") in new stack
1716320 [2021-12-30 19:02:09] VERBOSE[2235] chan_sip.c:
1716321 <--- SIP read from UDP:172.16.173.148:5060 --->
1716322 INVITE sip:20608@172.22.106.13 SIP/2.0
1716323 Via: SIP/2.0/UDP 172.16.173.148:5060;branch=z9hG4bK70ad6c24
1716324 Max-Forwards: 70
1716325 From: "Смирнов Test_1" <sip:9102999@172.16.173.148>;tag=as04ba4c0f
1716326 To: <sip:20608@172.22.106.13>
1716327 Contact: <sip:9102999@172.16.173.148:5060>
1716328 Call-ID: 3e472f183fedfe8154f0f9230da009c5@172.16.173.148:5060
1716329 CSeq: 101 INVITE
1716330 User-Agent: FPBX-14.0.16(16.13.0)
1716331 Date: Thu, 30 Dec 2021 08:02:09 GMT
1716332 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
1716333 Supported: replaces,timer
1716334 Content-Type: application/sdp
1716335 Content-Length: 279
1716336
1716337 v=0
1716338 o=root 1652409066 1652409066 IN IP4 172.16.173.148
1716339 s=Asterisk PBX 16.13.0
1716340 c=IN IP4 172.16.173.148
1716341 t=0 0
1716342 m=audio 16276 RTP/AVP 18 101
1716343 a=rtpmap:18 G729/8000
1716344 a=fmtp:18 annexb=no
1716345 a=rtpmap:101 telephone-event/8000
1716346 a=fmtp:101 0-16
1716347 a=ptime:20
1716348 a=maxptime:230
1716349 a=sendrecv
1716350 <------------->
1716351 [2021-12-30 19:02:09] VERBOSE[2235] chan_sip.c: --- (14 headers 13 lines) ---
1716352 [2021-12-30 19:02:09] VERBOSE[2235][C-00000029] chan_sip.c: Ignoring this INVITE request
1716353 [2021-12-30 19:02:09] VERBOSE[2235][C-00000029] chan_sip.c:
1716354 <--- Transmitting (no NAT) to 172.16.173.148:5060 --->
1716355 SIP/2.0 100 Trying
1716356 Via: SIP/2.0/UDP 172.16.173.148:5060;branch=z9hG4bK70ad6c24;received=172.16.173.148
1716357 From: "Смирнов Test_1" <sip:9102999@172.16.173.148>;tag=as04ba4c0f
1716358 To: <sip:20608@172.22.106.13>
1716359 Call-ID: 3e472f183fedfe8154f0f9230da009c5@172.16.173.148:5060
1716360 CSeq: 101 INVITE
1716361 Server: FPBX-15.0.17.34(16.17.0)
1716362 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
1716363 Supported: replaces, timer
1716364 Session-Expires: 1800;refresher=uas
1716365 Contact: <sip:20608@172.22.106.13:5060>
1716366 Content-Length: 0
1716367
1716368
1716369 <------------>
1716370 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] res_agi.c: <SIP/10-SPB_test_node_2 trunk_INC-0000004a>AGI Script agi://127.0.0.1/sangomacrm.agi completed, returning 0
1716371 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:25] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "CHANNEL(hangup_handler_push)=crm-hangup,s,1") in new stack
1716372 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:26] NoOp("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "CRM Finished") in new stack
1716373 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:27] GotoIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?bypass,1") in new stack
1716374 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:28] ExecIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?Set(CONNECTEDLINE(num,i)=20608)") in new stack
1716375 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:29] ExecIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?Set(CONNECTEDLINE(name,i)=CID:9102999)") in new stack
1716376 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:30] ExecIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?Set(CONNECTEDLINE(name,i)=CID:(Hidden)9102999)") in new stack
1716377 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:31] GotoIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?customtrunk") in new stack
1716378 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:32] ExecIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?Set(DIAL_TRUNK_OPTIONS=)") in new stack
1716379 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:33] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "HASH(__SIPHEADERS,Alert-Info)=unset") in new stack
1716380 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:34] Dial("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "SIP/Magadan-Out/20608,300,Tb(func-apply-sipheaders^s^1,(1))U(sub-send-obroute-email^20608^20608^1^1640851329^Смирнов Test_1^9102999)") in new stack
1716381 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] netsock2.c: Using SIP RTP TOS bits 184
1716382 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] netsock2.c: Using SIP RTP CoS mark 5
1716383 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] app_stack.c: SIP/Magadan-Out-0000004b Internal Gosub(func-apply-sipheaders,s,1(1)) start
1716384 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf("SIP/Magadan-Out-0000004b", "0?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
1716385 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp("SIP/Magadan-Out-0000004b", "Applying SIP Headers to channel SIP/Magadan-Out-0000004b") in new stack
1716386 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@func-apply-sipheaders:3] Set("SIP/Magadan-Out-0000004b", "TECH=SIP") in new stack
1716387 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@func-apply-sipheaders:4] Set("SIP/Magadan-Out-0000004b", "SIPHEADERKEYS=Alert-Info") in new stack
1716388 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@func-apply-sipheaders:5] While("SIP/Magadan-Out-0000004b", "1") in new stack
1716389 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@func-apply-sipheaders:6] Set("SIP/Magadan-Out-0000004b", "sipheader=unset") in new stack
1716390 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@func-apply-sipheaders:7] ExecIf("SIP/Magadan-Out-0000004b", "1?SIPRemoveHeader(Alert-Info:)") in new stack
1716391 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@func-apply-sipheaders:8] ExecIf("SIP/Magadan-Out-0000004b", "0?Set(PJSIP_HEADER(remove,Alert-Info)=)") in new stack
1716392 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@func-apply-sipheaders:9] ExecIf("SIP/Magadan-Out-0000004b", "0?Set(sipheader=<http://127.0.0.1>;info=unset)") in new stack
1716393 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@func-apply-sipheaders:10] ExecIf("SIP/Magadan-Out-0000004b", "0?Set(sipheader=<http://127.0.0.1>unset)") in new stack
1716394 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@func-apply-sipheaders:11] ExecIf("SIP/Magadan-Out-0000004b", "0?SIPAddHeader(Alert-Info:unset)") in new stack
1716395 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@func-apply-sipheaders:12] ExecIf("SIP/Magadan-Out-0000004b", "0?Set(PJSIP_HEADER(add,Alert-Info)=unset)") in new stack
1716396 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@func-apply-sipheaders:13] EndWhile("SIP/Magadan-Out-0000004b", "") in new stack
1716397 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@func-apply-sipheaders:5] While("SIP/Magadan-Out-0000004b", "0") in new stack
1716398 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@func-apply-sipheaders:14] Return("SIP/Magadan-Out-0000004b", "") in new stack
1716399 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] app_stack.c: Spawn extension (from-internal, 20608, 1) exited non-zero on 'SIP/Magadan-Out-0000004b'
1716400 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] app_stack.c: SIP/Magadan-Out-0000004b Internal Gosub(func-apply-sipheaders,s,1(1)) complete GOSUB_RETVAL=
1716401 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] chan_sip.c: Audio is at 10004
1716402 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] chan_sip.c: Adding codec g729 to SDP
1716403 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] chan_sip.c: Adding non-codec 0x1 (telephone-event) to SDP
1716404 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] chan_sip.c: Reliably Transmitting (no NAT) to 172.22.106.10:5060:
1716405 INVITE sip:20608@172.22.106.10 SIP/2.0
1716406 Via: SIP/2.0/UDP 172.22.106.13:5060;branch=z9hG4bK2d43b9a4
1716407 Max-Forwards: 70
1716408 From: "Смирнов Test_1" <sip:9102999@172.22.106.13>;tag=as64a5262d
1716409 To: <sip:20608@172.22.106.10>
1716410 Contact: <sip:9102999@172.22.106.13:5060>
1716411 Call-ID: 5fc1a8574d62c1a427f8362c58c90601@172.22.106.13:5060
1716412 CSeq: 102 INVITE
1716413 User-Agent: FPBX-15.0.17.34(16.17.0)
1716414 Date: Thu, 30 Dec 2021 08:02:09 GMT
1716415 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
1716416 Supported: replaces, timer
1716417 Content-Type: application/sdp
1716418 Content-Length: 277
1716419
1716420 v=0
1716421 o=root 1064080542 1064080542 IN IP4 172.22.106.13
1716422 s=Asterisk PBX 16.17.0
1716423 c=IN IP4 172.22.106.13
1716424 t=0 0
1716425 m=audio 10004 RTP/AVP 18 101
1716426 a=rtpmap:18 G729/8000
1716427 a=fmtp:18 annexb=no
1716428 a=rtpmap:101 telephone-event/8000
1716429 a=fmtp:101 0-16
1716430 a=ptime:20
1716431 a=maxptime:230
1716432 a=sendrecv
1716433
1716434 ---
1716435 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] app_dial.c: Called SIP/Magadan-Out/20608
1716436 [2021-12-30 19:02:09] VERBOSE[2235] chan_sip.c:
1716437 <--- SIP read from UDP:172.22.106.10:5060 --->
1716438 SIP/2.0 100 Trying
1716439 Via: SIP/2.0/UDP 172.22.106.13:5060;branch=z9hG4bK2d43b9a4
1716440 To: sip:20608@172.22.106.10
1716441 From: "Смирнов Test_1" <sip:9102999@172.22.106.13>;tag=as64a5262d
1716442 Call-ID: 5fc1a8574d62c1a427f8362c58c90601@172.22.106.13:5060
1716443 CSeq: 102 INVITE
1716444 Content-Length: 0
1716445
1716446 <------------->
1716447 [2021-12-30 19:02:09] VERBOSE[2235] chan_sip.c: --- (7 headers 0 lines) ---
1716448 [2021-12-30 19:02:09] VERBOSE[2235] chan_sip.c:
1716449 <--- SIP read from UDP:172.22.106.10:5060 --->
1716450 SIP/2.0 407 Proxy Authentication Required
1716451 Via: SIP/2.0/UDP 172.22.106.13:5060;branch=z9hG4bK2d43b9a4
1716452 To: sip:20608@172.22.106.10;tag=7985
1716453 From: "Смирнов Test_1" <sip:9102999@172.22.106.13>;tag=as64a5262d
1716454 Call-ID: 5fc1a8574d62c1a427f8362c58c90601@172.22.106.13:5060
1716455 CSeq: 102 INVITE
1716456 Allow: INVITE,ACK,CANCEL,BYE,REGISTER
1716457 Proxy-Authenticate: Digest realm="Registered Users",nonce="458b162c58b163c68d1a3468d1a3468c",algorithm=MD5
1716458 Content-Length: 0
1716459
1716460 <------------->
1716461 [2021-12-30 19:02:09] VERBOSE[2235] chan_sip.c: --- (9 headers 0 lines) ---
1716462 [2021-12-30 19:02:09] VERBOSE[2235][C-00000029] chan_sip.c: Transmitting (no NAT) to 172.22.106.10:5060:
1716463 ACK sip:20608@172.22.106.10 SIP/2.0
1716464 Via: SIP/2.0/UDP 172.22.106.13:5060;branch=z9hG4bK2d43b9a4
1716465 Max-Forwards: 70
1716466 From: "Смирнов Test_1" <sip:9102999@172.22.106.13>;tag=as64a5262d
1716467 To: <sip:20608@172.22.106.10>;tag=7985
1716468 Contact: <sip:9102999@172.22.106.13:5060>
1716469 Call-ID: 5fc1a8574d62c1a427f8362c58c90601@172.22.106.13:5060
1716470 CSeq: 102 ACK
1716471 User-Agent: FPBX-15.0.17.34(16.17.0)
1716472 Content-Length: 0
1716473
1716474
1716475 ---
1716476 [2021-12-30 19:02:09] NOTICE[2235][C-00000029] chan_sip.c: Failed to authenticate on INVITE to '"Смирнов Test_1" <sip:9102999@172.22.106.13>;tag=as64a5262d'
1716477 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] app_dial.c: SIP/Magadan-Out-0000004b is circuit-busy
1716478 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] app_dial.c: Everyone is busy/congested at this time (1:0/1/0)
1716479 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:35] NoOp("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "Dial failed for some reason with DIALSTATUS = CONGESTION and HANGUPCAUSE = 21") in new stack
1716480 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-dialout-trunk:36] GotoIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?continue,1:s-CONGESTION,1") in new stack
1716481 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx_builtins.c: Goto (macro-dialout-trunk,s-CONGESTION,1)
1716482 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s-CONGESTION@macro-dialout-trunk:1] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "RC=21") in new stack
1716483 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s-CONGESTION@macro-dialout-trunk:2] Goto("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "21,1") in new stack
1716484 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx_builtins.c: Goto (macro-dialout-trunk,21,1)
1716485 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [21@macro-dialout-trunk:1] Goto("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "continue,1") in new stack
1716486 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx_builtins.c: Goto (macro-dialout-trunk,continue,1)
1716487 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [continue@macro-dialout-trunk:1] NoOp("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "TRUNK Dial failed due to CONGESTION HANGUPCAUSE: 21 - failing through to other trunks") in new stack
1716488 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [continue@macro-dialout-trunk:2] ExecIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?Set(CALLERID(number)=)") in new stack
1716489 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [20608@from-internal:12] Macro("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "outisbusy,") in new stack
1716490 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outisbusy:1] Progress("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "") in new stack
1716491 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] chan_sip.c: Audio is at 18978
1716492 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] chan_sip.c: Adding codec g729 to SDP
1716493 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] chan_sip.c: Adding non-codec 0x1 (telephone-event) to SDP
1716494 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] chan_sip.c:
1716495 <--- Transmitting (no NAT) to 172.16.173.148:5060 --->
1716496 SIP/2.0 183 Session Progress
1716497 Via: SIP/2.0/UDP 172.16.173.148:5060;branch=z9hG4bK70ad6c24;received=172.16.173.148
1716498 From: "Смирнов Test_1" <sip:9102999@172.16.173.148>;tag=as04ba4c0f
1716499 To: <sip:20608@172.22.106.13>;tag=as76ada22c
1716500 Call-ID: 3e472f183fedfe8154f0f9230da009c5@172.16.173.148:5060
1716501 CSeq: 101 INVITE
1716502 Server: FPBX-15.0.17.34(16.17.0)
1716503 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
1716504 Supported: replaces, timer
1716505 Session-Expires: 1800;refresher=uas
1716506 Contact: <sip:20608@172.22.106.13:5060>
1716507 Content-Type: application/sdp
1716508 Require: timer
1716509 Content-Length: 277
1716510
1716511 v=0
1716512 o=root 1253218799 1253218799 IN IP4 172.22.106.13
1716513 s=Asterisk PBX 16.17.0
1716514 c=IN IP4 172.22.106.13
1716515 t=0 0
1716516 m=audio 18978 RTP/AVP 18 101
1716517 a=rtpmap:18 G729/8000
1716518 a=fmtp:18 annexb=no
1716519 a=rtpmap:101 telephone-event/8000
1716520 a=fmtp:101 0-16
1716521 a=ptime:20
1716522 a=maxptime:230
1716523 a=sendrecv
1716524
1716525 <------------>
1716526 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outisbusy:2] GotoIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?emergency,1") in new stack
1716527 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outisbusy:3] GotoIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?intracompany,1") in new stack
1716528 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outisbusy:4] Playback("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "all-circuits-busy-now&please-try-call-later, noanswer") in new stack
1716529 [2021-12-30 19:02:09] VERBOSE[208279][C-00000029] file.c: <SIP/10-SPB_test_node_2 trunk_INC-0000004a> Playing 'all-circuits-busy-now.slin16' (language 'en')
1716530 [2021-12-30 19:02:09] VERBOSE[2235] chan_sip.c:
1716701 [2021-12-30 19:02:12] VERBOSE[2235] chan_sip.c: --- (13 headers 0 lines) ---
1716702 [2021-12-30 19:02:12] VERBOSE[2235] chan_sip.c: Sending to 172.24.29.13:5060 (no NAT)
1716703 [2021-12-30 19:02:12] VERBOSE[2235] chan_sip.c: Looking for s in from-sip-external (domain 172.22.106.13)
1716704 [2021-12-30 19:02:12] VERBOSE[2235] chan_sip.c:
1716705 <--- Transmitting (no NAT) to 172.24.29.13:5060 --->
1716706 SIP/2.0 200 OK
1716707 Via: SIP/2.0/UDP 172.24.29.13:5060;branch=z9hG4bK708bc6d7;received=172.24.29.13
1716708 From: "Unknown" <sip:Unknown@172.24.29.13>;tag=as083fbd5e
1716709 To: <sip:172.22.106.13>;tag=as21e24cc1
1716710 Call-ID: 5333d41543bfdd1e2ee0242c5f16bf0f@172.24.29.13:5060
1716711 CSeq: 102 OPTIONS
1716712 Server: FPBX-15.0.17.34(16.17.0)
1716713 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
1716714 Supported: replaces, timer
1716715 Contact: <sip:172.22.106.13:5060>
1716716 Accept: application/sdp
1716717 Content-Length: 0
1716718
1716719
1716720 <------------>
1716721 [2021-12-30 19:02:12] VERBOSE[2235] chan_sip.c: Scheduling destruction of SIP dialog '5333d41543bfdd1e2ee0242c5f16bf0f@172.24.29.13:5060' in 32000 ms (Method: OPTIONS)
1716722 [2021-12-30 19:02:12] VERBOSE[2235] chan_sip.c: Really destroying SIP dialog '7addd84456b61034197d40cb4dcd2cf2@172.26.11.100:5060' Method: OPTIONS
1716723 [2021-12-30 19:02:13] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-outisbusy:5] Congestion("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "20") in new stack
1716724 [2021-12-30 19:02:13] VERBOSE[208279][C-00000029] chan_sip.c:
1716725 <--- Reliably Transmitting (no NAT) to 172.16.173.148:5060 --->
1716726 SIP/2.0 503 Service Unavailable
1716727 Via: SIP/2.0/UDP 172.16.173.148:5060;branch=z9hG4bK70ad6c24;received=172.16.173.148
1716728 From: "Смирнов Test_1" <sip:9102999@172.16.173.148>;tag=as04ba4c0f
1716729 To: <sip:20608@172.22.106.13>;tag=as76ada22c
1716730 Call-ID: 3e472f183fedfe8154f0f9230da009c5@172.16.173.148:5060
1716731 CSeq: 101 INVITE
1716732 Server: FPBX-15.0.17.34(16.17.0)
1716733 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
1716734 Supported: replaces, timer
1716735 Session-Expires: 1800;refresher=uas
1716736 X-Asterisk-HangupCause: Call Rejected
1716737 X-Asterisk-HangupCauseCode: 21
1716738 Content-Length: 0
1716739
1716740
1716741 <------------>
1716742 [2021-12-30 19:02:13] WARNING[208279][C-00000029] channel.c: Prodding channel 'SIP/10-SPB_test_node_2 trunk_INC-0000004a' failed
1716743 [2021-12-30 19:02:13] VERBOSE[208279][C-00000029] app_macro.c: Spawn extension (macro-outisbusy, s, 5) exited non-zero on 'SIP/10-SPB_test_node_2 trunk_INC-0000004a' in macro 'outisbusy'
1716744 [2021-12-30 19:02:13] VERBOSE[208279][C-00000029] pbx.c: Spawn extension (from-internal, 20608, 12) exited non-zero on 'SIP/10-SPB_test_node_2 trunk_INC-0000004a'
1716745 [2021-12-30 19:02:13] VERBOSE[208279][C-00000029] pbx.c: Executing [h@from-internal:1] Macro("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "hangupcall") in new stack
1716746 [2021-12-30 19:02:13] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "1?theend") in new stack
1716747 [2021-12-30 19:02:13] VERBOSE[208279][C-00000029] pbx_builtins.c: Goto (macro-hangupcall,s,3)
1716748 [2021-12-30 19:02:13] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?Set(CDR(recordingfile)=)") in new stack
1716749 [2021-12-30 19:02:13] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-hangupcall:4] NoOp("SIP/10-SPB_test_node_2 trunk_INC-0000004a", " montior file= ") in new stack
1716750 [2021-12-30 19:02:13] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-hangupcall:5] GotoIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "1?skipagi") in new stack
1716751 [2021-12-30 19:02:13] VERBOSE[208279][C-00000029] pbx_builtins.c: Goto (macro-hangupcall,s,7)
1716752 [2021-12-30 19:02:13] VERBOSE[208279][C-00000029] pbx.c: Executing [s@macro-hangupcall:7] Hangup("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "") in new stack
1716753 [2021-12-30 19:02:13] VERBOSE[208279][C-00000029] app_macro.c: Spawn extension (macro-hangupcall, s, 7) exited non-zero on 'SIP/10-SPB_test_node_2 trunk_INC-0000004a' in macro 'hangupcall'
1716754 [2021-12-30 19:02:13] VERBOSE[208279][C-00000029] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/10-SPB_test_node_2 trunk_INC-0000004a'
1716755 [2021-12-30 19:02:13] VERBOSE[208279][C-00000029] app_stack.c: SIP/10-SPB_test_node_2 trunk_INC-0000004a Internal Gosub(crm-hangup,s,1) start
1716756 [2021-12-30 19:02:13] VERBOSE[208279][C-00000029] pbx.c: Executing [s@crm-hangup:1] NoOp("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "Sending Hangup to CRM") in new stack
1716757 [2021-12-30 19:02:13] VERBOSE[208279][C-00000029] pbx.c: Executing [s@crm-hangup:2] NoOp("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "HANGUP CAUSE: 34") in new stack
1716758 [2021-12-30 19:02:13] VERBOSE[208279][C-00000029] pbx.c: Executing [s@crm-hangup:3] ExecIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?Set(__CRM_VOICEMAIL=)") in new stack
1716759 [2021-12-30 19:02:13] VERBOSE[208279][C-00000029] pbx.c: Executing [s@crm-hangup:4] NoOp("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "MASTER CHANNEL: 1640851329.74 = 1640851329.74") in new stack
1716760 [2021-12-30 19:02:13] VERBOSE[208279][C-00000029] pbx.c: Executing [s@crm-hangup:5] GotoIf("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "0?return") in new stack
1716761 [2021-12-30 19:02:13] VERBOSE[208279][C-00000029] pbx.c: Executing [s@crm-hangup:6] Set("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "__CRM_HANGUP=1") in new stack
1716762 [2021-12-30 19:02:13] VERBOSE[208279][C-00000029] pbx.c: Executing [s@crm-hangup:7] AGI("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "agi://127.0.0.1/sangomacrm.agi") in new stack
1716763 [2021-12-30 19:02:13] VERBOSE[2235] chan_sip.c: Retransmitting #1 (no NAT) to 172.16.173.148:5060:
1716764 SIP/2.0 503 Service Unavailable
1716765 Via: SIP/2.0/UDP 172.16.173.148:5060;branch=z9hG4bK70ad6c24;received=172.16.173.148
1716766 From: "Смирнов Test_1" <sip:9102999@172.16.173.148>;tag=as04ba4c0f
1716767 To: <sip:20608@172.22.106.13>;tag=as76ada22c
1716768 Call-ID: 3e472f183fedfe8154f0f9230da009c5@172.16.173.148:5060
1716769 CSeq: 101 INVITE
1716770 Server: FPBX-15.0.17.34(16.17.0)
1716771 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
1716772 Supported: replaces, timer
1716773 Session-Expires: 1800;refresher=uas
1716774 X-Asterisk-HangupCause: Call Rejected
1716775 X-Asterisk-HangupCauseCode: 21
1716776 Content-Length: 0
1716777
1716778
1716779 ---
1716780 [2021-12-30 19:02:13] VERBOSE[2235] chan_sip.c:
1716781 <--- SIP read from UDP:172.16.173.148:5060 --->
1716782 ACK sip:20608@172.22.106.13:5060 SIP/2.0
1716783 Via: SIP/2.0/UDP 172.16.173.148:5060;branch=z9hG4bK70ad6c24
1716784 Max-Forwards: 70
1716785 From: "Смирнов Test_1" <sip:9102999@172.16.173.148>;tag=as04ba4c0f
1716786 To: <sip:20608@172.22.106.13>;tag=as76ada22c
1716787 Contact: <sip:9102999@172.16.173.148:5060>
1716788 Call-ID: 3e472f183fedfe8154f0f9230da009c5@172.16.173.148:5060
1716789 CSeq: 101 ACK
1716790 User-Agent: FPBX-14.0.16(16.13.0)
1716791 Content-Length: 0
1716792
1716793 <------------->
1716794 [2021-12-30 19:02:13] VERBOSE[2235] chan_sip.c: --- (10 headers 0 lines) ---
1716795 [2021-12-30 19:02:13] VERBOSE[208279][C-00000029] res_agi.c: <SIP/10-SPB_test_node_2 trunk_INC-0000004a>AGI Script agi://127.0.0.1/sangomacrm.agi completed, returning 0
1716796 [2021-12-30 19:02:13] VERBOSE[208279][C-00000029] pbx.c: Executing [s@crm-hangup:8] Return("SIP/10-SPB_test_node_2 trunk_INC-0000004a", "") in new stack
1716797 [2021-12-30 19:02:13] VERBOSE[208279][C-00000029] app_stack.c: Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/10-SPB_test_node_2 trunk_INC-0000004a'
1716798 [2021-12-30 19:02:13] VERBOSE[208279][C-00000029] app_stack.c: SIP/10-SPB_test_node_2 trunk_INC-0000004a Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
1716799 [2021-12-30 19:02:13] VERBOSE[2235] chan_sip.c: Really destroying SIP dialog '712e538d111ac2a579fcb39f29776259@172.26.130.13:5060' Method: OPTIONS
1716800 [2021-12-30 19:02:13] VERBOSE[2235] chan_sip.c: Really destroying SIP dialog '3e472f183fedfe8154f0f9230da009c5@172.16.173.148:5060' Method: ACK
1716801 [2021-12-30 19:02:14] VERBOSE[2235] chan_sip.c:
1716802 <--- SIP read from UDP:172.26.114.13:5060 --->
1716803 OPTIONS sip:172.22.106.13 SIP/2.0
1716804 Via: SIP/2.0/UDP 172.26.114.13:5060;branch=z9hG4bK135579cd
1716805 Max-Forwards: 70
1716806 From: "Unknown" <sip:Unknown@172.26.114.13>;tag=as788a0a8d
1716807 To: <sip:172.22.106.13>
1716808 Contact: <sip:Unknown@172.26.114.13:5060>
1716809 Call-ID: 790a66b9380f97e13cddfcdd05f02c08@172.26.114.13:5060
1716810 CSeq: 102 OPTIONS
1716811 User-Agent: FPBX-15.0.17.34(16.17.0)
1716812 Date: Thu, 30 Dec 2021 08:02:13 GMT
1716813 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
1716814 Supported: replaces, timer
1716815 Content-Length: 0
Failed to authenticate on INVITE to '"Смирнов Test_1" <sip:9102999@172.22.106.13>
На панасонике сейчас такие настройки:
Re: sip Транк между панасоником и астериском
Что-то картинки так и не пойму как добавить..
Re: sip Транк между панасоником и астериском
1716449 <--- SIP read from UDP:172.22.106.10:5060 --->
1716450 SIP/2.0 407 Proxy Authentication Required отвечает вам Panasonic на попытку INVITE
поэтому
Failed to authenticate on INVITE to '"Смирнов Test_1" <sip:9102999@172.22.106.13>
То есть Панасоник не хочет (не умеет?) принять INVITE без аутентификации username|secret.
Надо указать.
1716450 SIP/2.0 407 Proxy Authentication Required отвечает вам Panasonic на попытку INVITE
поэтому
Failed to authenticate on INVITE to '"Смирнов Test_1" <sip:9102999@172.22.106.13>
То есть Панасоник не хочет (не умеет?) принять INVITE без аутентификации username|secret.
Надо указать.
