Подскажите, пожалуйста в следующей ситуации.
Есть такая схема:
АТС LG <--SIP--> FreePBX 1 <--SIP--> FreePBX 2
АТС LG:
172.26.218.10
FreePBX 1:
172.26.223.29
FreePBX 2:
172.16.173.148
С АТС LG звоним с номера 962191, на номер 10299.
На FreePBX 1 прописан входящий маршрут, при получении 10XXXX
Направить в сип транк смотрящий на FreePBX 2, "отрезав" 10ку.
на FreePBX 2, приходит вызов на абонента 2999 всё нормально разговор состоялся.
Вопрос с отображением имени, хотелось бы видеть ФИО звонящего.
С LG на FreePBX 1 ФИО приходит,
Но если я правильно понял при транзите с FreePBX 1 на FreePBX 2,
CALLERID меняется на номер.
Вот эта строчка
5256 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:9] ExecIf("SIP/172.26.218.10-0000001c", "1 ?Set(CALLERID(name)=962191)") in new stack
Соответственно на FreePBX 2 мы видим и номер 962191 и ФИО как: "962191"
Вот ниже весь лог с FreePBX 1:
PRIME_BBCODE_SPOILER_SHOW PRIME_BBCODE_SPOILER:
5152 <------------->
5153 [2021-04-20 10:55:11] VERBOSE[18872] chan_sip.c: --- (11 headers 0 lines) ---
5154 [2021-04-20 10:55:11] VERBOSE[18872] chan_sip.c: Really destroying SIP dialog '1697ecc52815df53215643067cbe5359@172.26.223.29:5060' Method: OPTIONS
5155 [2021-04-20 10:55:16] VERBOSE[18872] chan_sip.c:
5156 <--- SIP read from UDP:172.26.218.10:5060 --->
5157 INVITE sip:102999@172.26.223.29 SIP/2.0
5158 From: "Shkutov Pavel"<sip:962191@172.26.223.29>;tag=48a72418-ada1aac-13c4-65014-66182-4d59761a-66182
5159 To: <sip:102999@172.26.223.29>
5160 Call-ID: 48b1d928-ada1aac-13c4-65014-66182-40a8d8bc-66182
5161 CSeq: 1 INVITE
5162 Via: SIP/2.0/UDP 172.26.218.10:5060;rport;branch=z9hG4bK-66182-18ece55e-44127464-488f8630
5163 Max-Forwards: 70
5164 Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,REGISTER,REFER,SUBSCRIBE,NOTIFY,MESSAGE,INFO,PRACK,UPDATE
5165 Supported: replaces,UPDATE,INFO
5166 P-Asserted-Identity: "Shkutov Pavel"<sip:962191@172.26.223.29>
5167 Remote-Party-ID: <sip:962191@172.26.218.10>; party=calling; privacy=off; screen=yes
5168 Privacy: none
5169 User-Agent: Ericsson-LG Enterprise iPECS-eMG eMG800 2.1.11
5170 Contact: "Shkutov Pavel"<sip:962191@172.26.218.10:5060;transport=UDP>
5171 Content-Type: application/sdp
5172 Content-Length: 231
5173
5174 v=0
5175 o=iPECS-eMG 22 22 IN IP4 172.26.218.10
5176 s=iPECS-eMG SIP
5177 c=IN IP4 172.26.218.10
5178 t=0 0
5179 m=audio 20538 RTP/AVP 18 101
5180 a=rtpmap:18 G729/8000
5181 a=fmtp:18 annexb=no
5182 a=rtpmap:101 telephone-event/8000
5183 a=fmtp:101 0-15
5184 a=sendrecv
5185 <------------->
5186 [2021-04-20 10:55:16] VERBOSE[18872] chan_sip.c: --- (16 headers 11 lines) ---
5187 [2021-04-20 10:55:16] VERBOSE[18872] chan_sip.c: Sending to 172.26.218.10:5060 (no NAT)
5188 [2021-04-20 10:55:16] VERBOSE[18872][C-0000000f] chan_sip.c: Sending to 172.26.218.10:5060 (no NAT)
5189 [2021-04-20 10:55:16] VERBOSE[18872][C-0000000f] chan_sip.c: Using INVITE request as basis request - 48b1d928-ada1aac-13c4-65014-66182-40a8d8bc-66182
5190 [2021-04-20 10:55:16] VERBOSE[18872][C-0000000f] chan_sip.c: Found peer '172.26.218.10' for '962191' from 172.26.218.10:5060
5191 [2021-04-20 10:55:16] VERBOSE[18872][C-0000000f] netsock2.c: Using SIP RTP TOS bits 184
5192 [2021-04-20 10:55:16] VERBOSE[18872][C-0000000f] netsock2.c: Using SIP RTP CoS mark 5
5193 [2021-04-20 10:55:16] VERBOSE[18872][C-0000000f] chan_sip.c: Got SDP version 22 and unique parts [iPECS-eMG 22 IN IP4 172.26.218.10]
5194 [2021-04-20 10:55:16] VERBOSE[18872][C-0000000f] chan_sip.c: Found RTP audio format 18
5195 [2021-04-20 10:55:16] VERBOSE[18872][C-0000000f] chan_sip.c: Found RTP audio format 101
5196 [2021-04-20 10:55:16] VERBOSE[18872][C-0000000f] chan_sip.c: Found audio description format G729 for ID 18
5197 [2021-04-20 10:55:16] VERBOSE[18872][C-0000000f] chan_sip.c: Found audio description format telephone-event for ID 101
5198 [2021-04-20 10:55:16] VERBOSE[18872][C-0000000f] chan_sip.c: Capabilities: us - (g729), peer - audio=(g729)/video=(nothing)/text=(nothing), combined - (g729)
5199 [2021-04-20 10:55:16] VERBOSE[18872][C-0000000f] chan_sip.c: Non-codec capabilities (dtmf): us - 0x1 (telephone-event|), peer - 0x1 (telephone-event|), combined - 0x1 (telephone-event|)
5200 [2021-04-20 10:55:16] VERBOSE[18872][C-0000000f] chan_sip.c: Peer audio RTP is at port 172.26.218.10:20538
5201 [2021-04-20 10:55:16] VERBOSE[18872][C-0000000f] chan_sip.c: Looking for 102999 in from-trunk (domain 172.26.223.29)
5202 [2021-04-20 10:55:16] VERBOSE[18872][C-0000000f] sip/route.c: sip_route_dump: route/path hop: <sip:962191@172.26.218.10:5060;transport=UDP>
5203 [2021-04-20 10:55:16] VERBOSE[18872][C-0000000f] chan_sip.c:
5204 <--- Transmitting (no NAT) to 172.26.218.10:5060 --->
5205 SIP/2.0 100 Trying
5206 Via: SIP/2.0/UDP 172.26.218.10:5060;branch=z9hG4bK-66182-18ece55e-44127464-488f8630;received=172.26.218.10;rport=5060
5207 From: "Shkutov Pavel"<sip:962191@172.26.223.29>;tag=48a72418-ada1aac-13c4-65014-66182-4d59761a-66182
5208 To: <sip:102999@172.26.223.29>
5209 Call-ID: 48b1d928-ada1aac-13c4-65014-66182-40a8d8bc-66182
5210 CSeq: 1 INVITE
5211 Server: FPBX-15.0.16.81(16.13.0)
5212 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
5213 Supported: replaces, timer
5214 Contact: <sip:102999@172.26.223.29:5060>
5215 Content-Length: 0
5216
5217
5218 <------------>
5219 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:1] Set("SIP/172.26.218.10-0000001c", "__DIRECTION=INBOUND") in new stack
5220 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:2] Gosub("SIP/172.26.218.10-0000001c", "sub-record-check,s,1(in,102999,dontcare)") in new stack
5221 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-record-check:1] GotoIf("SIP/172.26.218.10-0000001c", "0?initialized") in new stack
5222 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-record-check:2] Set("SIP/172.26.218.10-0000001c", "__REC_STATUS=INITIALIZED") in new stack
5223 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-record-check:3] Set("SIP/172.26.218.10-0000001c", "NOW=1618916116") in new stack
5224 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-record-check:4] Set("SIP/172.26.218.10-0000001c", "__DAY=20") in new stack
5225 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-record-check:5] Set("SIP/172.26.218.10-0000001c", "__MONTH=04") in new stack
5226 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-record-check:6] Set("SIP/172.26.218.10-0000001c", "__YEAR=2021") in new stack
5227 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-record-check:7] Set("SIP/172.26.218.10-0000001c", "__TIMESTR=20210420-105516") in new stack
5228 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-record-check:8] Set("SIP/172.26.218.10-0000001c", "__FROMEXTEN=unknown") in new stack
5229 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-record-check:9] Set("SIP/172.26.218.10-0000001c", "__MON_FMT=wav") in new stack
5230 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-record-check:10] NoOp("SIP/172.26.218.10-0000001c", "Recordings initialized") in new stack
5231 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-record-check:11] ExecIf("SIP/172.26.218.10-0000001c", "0?Set(ARG3=dontcare)") in new stack
5232 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-record-check:12] Set("SIP/172.26.218.10-0000001c", "REC_POLICY_MODE_SAVE=") in new stack
5233 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-record-check:13] ExecIf("SIP/172.26.218.10-0000001c", "0?Set(REC_STATUS=NO)") in new stack
5234 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-record-check:14] GotoIf("SIP/172.26.218.10-0000001c", "2?checkaction") in new stack
5235 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx_builtins.c: Goto (sub-record-check,s,17)
5236 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-record-check:17] GotoIf("SIP/172.26.218.10-0000001c", "1?sub-record-check,in,1") in new stack
5237 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx_builtins.c: Goto (sub-record-check,in,1)
5238 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [in@sub-record-check:1] NoOp("SIP/172.26.218.10-0000001c", "Inbound Recording Check to 102999") in new stack
5239 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [in@sub-record-check:2] Set("SIP/172.26.218.10-0000001c", "FROMEXTEN=unknown") in new stack
5240 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [in@sub-record-check:3] ExecIf("SIP/172.26.218.10-0000001c", "6?Set(FROMEXTEN=962191)") in new stack
5241 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [in@sub-record-check:4] Gosub("SIP/172.26.218.10-0000001c", "recordcheck,1(dontcare,in,102999)") in new stack
5242 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("SIP/172.26.218.10-0000001c", "Starting recording check against dontcare") in new stack
5243 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("SIP/172.26.218.10-0000001c", "dontcare") in new stack
5244 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
5245 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [recordcheck@sub-record-check:3] Return("SIP/172.26.218.10-0000001c", "") in new stack
5246 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [in@sub-record-check:5] Return("SIP/172.26.218.10-0000001c", "") in new stack
5247 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:3] Set("SIP/172.26.218.10-0000001c", "CHANNEL(tonezone)=us") in new stack
5248 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:4] Set("SIP/172.26.218.10-0000001c", "__FROM_DID=102999") in new stack
5249 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:5] Set("SIP/172.26.218.10-0000001c", "returnhere=1") in new stack
5250 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:6] Gosub("SIP/172.26.218.10-0000001c", "app-blacklist-check,s,1()") in new stack
5251 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@app-blacklist-check:1] GotoIf("SIP/172.26.218.10-0000001c", "0?blacklisted") in new stack
5252 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@app-blacklist-check:2] Set("SIP/172.26.218.10-0000001c", "CALLED_BLACKLIST=1") in new stack
5253 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@app-blacklist-check:3] Return("SIP/172.26.218.10-0000001c", "") in new stack
5254 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:7] Set("SIP/172.26.218.10-0000001c", "CDR(did)=102999") in new stack
5255 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:8] GotoIf("SIP/172.26.218.10-0000001c", "0?") in new stack
5256 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:9] ExecIf("SIP/172.26.218.10-0000001c", "1 ?Set(CALLERID(name)=962191)") in new stack
5257 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:10] Set("SIP/172.26.218.10-0000001c", "__MOHCLASS=") in new stack
5258 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:11] Set("SIP/172.26.218.10-0000001c", "__REVERSAL_REJECT=FALSE") in new stack
5259 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:12] GotoIf("SIP/172.26.218.10-0000001c", "1?post-reverse-charge") in new stack
5260 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx_builtins.c: Goto (from-trunk,102999,14)
5261 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:14] NoOp("SIP/172.26.218.10-0000001c", "") in new stack
5262 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:15] Set("SIP/172.26.218.10-0000001c", "__CALLINGNAMEPRES_SV=allowed_not_screened") in new stack
5263 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:16] Set("SIP/172.26.218.10-0000001c", "__CALLINGNUMPRES_SV=allowed_not_screened") in new stack
5264 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:17] Set("SIP/172.26.218.10-0000001c", "CALLERID(name-pres)=allowed_not_screened") in new stack
5265 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:18] Set("SIP/172.26.218.10-0000001c", "CALLERID(num-pres)=allowed_not_screened") in new stack
5266 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:19] NoOp("SIP/172.26.218.10-0000001c", "CallerID Entry Point") in new stack
5267 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:20] Set("SIP/172.26.218.10-0000001c", "__CRM_DIRECTION=INBOUND") in new stack
5268 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:21] Set("SIP/172.26.218.10-0000001c", "__CRM_SOURCE=962191") in new stack
5269 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:22] Set("SIP/172.26.218.10-0000001c", "__CRM_LINKEDID=1618916116.28") in new stack
5270 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:23] AGI("SIP/172.26.218.10-0000001c", "agi://127.0.0.1/sangomacrm.agi,true") in new stack
5271 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] res_agi.c: <SIP/172.26.218.10-0000001c>AGI Script agi://127.0.0.1/sangomacrm.agi completed, returning 0
5272 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:24] ExecIf("SIP/172.26.218.10-0000001c", "1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
5273 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:25] Goto("SIP/172.26.218.10-0000001c", "ext-trunk,1,1") in new stack
5274 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx_builtins.c: Goto (ext-trunk,1,1)
5275 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [1@ext-trunk:1] Set("SIP/172.26.218.10-0000001c", "TDIAL_STRING=SIP/10-SPB_test_node_2 trunkOUT") in new stack
5276 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [1@ext-trunk:2] Set("SIP/172.26.218.10-0000001c", "DIAL_TRUNK=1") in new stack
5277 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [1@ext-trunk:3] Goto("SIP/172.26.218.10-0000001c", "ext-trunk,tdial,1") in new stack
5278 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx_builtins.c: Goto (ext-trunk,tdial,1)
5279 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [tdial@ext-trunk:1] Set("SIP/172.26.218.10-0000001c", "OUTBOUND_GROUP=OUT_1") in new stack
5280 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [tdial@ext-trunk:2] GotoIf("SIP/172.26.218.10-0000001c", "1?nomax") in new stack
5281 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx_builtins.c: Goto (ext-trunk,tdial,4)
5282 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [tdial@ext-trunk:4] ExecIf("SIP/172.26.218.10-0000001c", "1?Set(CALLERPRES(name-pres)=allowed_not_screened)") in new stack
5283 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [tdial@ext-trunk:5] ExecIf("SIP/172.26.218.10-0000001c", "1?Set(CALLERPRES(num-pres)=allowed_not_screened)") in new stack
5284 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [tdial@ext-trunk:6] Set("SIP/172.26.218.10-0000001c", "DIAL_NUMBER=102999") in new stack
5285 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [tdial@ext-trunk:7] GosubIf("SIP/172.26.218.10-0000001c", "1?sub-flp-1,s,1()") in new stack
5286 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-flp-1:1] ExecIf("SIP/172.26.218.10-0000001c", "1?Set(TARGET_FLP_1=2999)") in new stack
5287 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-flp-1:2] GotoIf("SIP/172.26.218.10-0000001c", "1?match") in new stack
5288 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx_builtins.c: Goto (sub-flp-1,s,4)
5289 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-flp-1:4] Set("SIP/172.26.218.10-0000001c", "DIAL_NUMBER=2999") in new stack
5290 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-flp-1:5] Return("SIP/172.26.218.10-0000001c", "") in new stack
5291 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [tdial@ext-trunk:8] Set("SIP/172.26.218.10-0000001c", "OUTNUM=2999") in new stack
5292 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [tdial@ext-trunk:9] Set("SIP/172.26.218.10-0000001c", "DIAL_TRUNK_OPTIONS=T") in new stack
5293 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [tdial@ext-trunk:10] Dial("SIP/172.26.218.10-0000001c", "SIP/10-SPB_test_node_2 trunkOUT/2999,300,T") in new stack
5294 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] netsock2.c: Using SIP RTP TOS bits 184
5295 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] netsock2.c: Using SIP RTP CoS mark 5
5296 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] chan_sip.c: Audio is at 17736
5297 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] chan_sip.c: Adding codec g729 to SDP
5298 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] chan_sip.c: Adding non-codec 0x1 (telephone-event) to SDP
5299 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] chan_sip.c: Reliably Transmitting (no NAT) to 172.16.173.148:5060:
5300 INVITE sip:2999@172.16.173.148 SIP/2.0
5301 Via: SIP/2.0/UDP 172.26.223.29:5060;branch=z9hG4bK54af0aec
5302 Max-Forwards: 70
5303 From: "962191" <sip:962191@172.26.223.29>;tag=as0cc0ee63
5304 To: <sip:2999@172.16.173.148>
5305 Contact: <sip:962191@172.26.223.29:5060>
5306 Call-ID: 0c06a95f125a5a07390cf3741e2e38ba@172.26.223.29:5060
5307 CSeq: 102 INVITE
5308 User-Agent: FPBX-15.0.16.81(16.13.0)
5309 Date: Tue, 20 Apr 2021 10:55:16 GMT
5310 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
5311 Supported: replaces, timer
5312 Remote-Party-ID: "962191" <sip:962191@172.26.223.29>;party=calling;privacy=off;screen=no
5313 Content-Type: application/sdp
5314 Content-Length: 277
5315
5316 v=0
5317 o=root 1979690048 1979690048 IN IP4 172.26.223.29
5318 s=Asterisk PBX 16.13.0
5319 c=IN IP4 172.26.223.29
5320 t=0 0
5321 m=audio 17736 RTP/AVP 18 101
5322 a=rtpmap:18 G729/8000
5323 a=fmtp:18 annexb=no
5324 a=rtpmap:101 telephone-event/8000
5325 a=fmtp:101 0-16
5326 a=ptime:20
5327 a=maxptime:230
5328 a=sendrecv
5329
5330 ---
5331 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] app_dial.c: Called SIP/10-SPB_test_node_2 trunkOUT/2999
5332 [2021-04-20 10:55:16] VERBOSE[18872] chan_sip.c:
5333 <--- SIP read from UDP:172.16.173.148:5060 --->
5334 SIP/2.0 100 Trying
5335 Via: SIP/2.0/UDP 172.26.223.29:5060;branch=z9hG4bK54af0aec;received=172.26.223.29
5336 From: "962191" <sip:962191@172.26.223.29>;tag=as0cc0ee63
5337 To: <sip:2999@172.16.173.148>
5338 Call-ID: 0c06a95f125a5a07390cf3741e2e38ba@172.26.223.29:5060
5339 CSeq: 102 INVITE
5340 Server: FPBX-14.0.16(16.13.0)
5341 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
5342 Supported: replaces,timer
5343 Session-Expires: 1800;refresher=uas
5344 Contact: <sip:2999@172.16.173.148:5060>
5345 Content-Length: 0
5346
5347 <------------->
5348 [2021-04-20 10:55:16] VERBOSE[18872] chan_sip.c: --- (12 headers 0 lines) ---
5349 [2021-04-20 10:55:16] VERBOSE[18872] chan_sip.c:
5350 <--- SIP read from UDP:172.16.173.148:5060 --->
5351 SIP/2.0 180 Ringing
5352 Via: SIP/2.0/UDP 172.26.223.29:5060;branch=z9hG4bK54af0aec;received=172.26.223.29
5353 From: "962191" <sip:962191@172.26.223.29>;tag=as0cc0ee63
5354 To: <sip:2999@172.16.173.148>;tag=as34bce41e
5355 Call-ID: 0c06a95f125a5a07390cf3741e2e38ba@172.26.223.29:5060
5356 CSeq: 102 INVITE
5357 Server: FPBX-14.0.16(16.13.0)
5358 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
5359 Supported: replaces,timer
5360 Session-Expires: 1800;refresher=uas
5361 Contact: <sip:2999@172.16.173.148:5060>
5362 Content-Length: 0
5363
5364 <------------->
5365 [2021-04-20 10:55:16] VERBOSE[18872] chan_sip.c: --- (12 headers 0 lines) ---
5366 [2021-04-20 10:55:16] VERBOSE[18872][C-0000000f] sip/route.c: sip_route_dump: route/path hop: <sip:2999@172.16.173.148:5060>
5367 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] app_dial.c: SIP/10-SPB_test_node_2 trunkOUT-0000001d is ringing
5368 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] chan_sip.c:
5369 <--- Transmitting (no NAT) to 172.26.218.10:5060 --->
5370 SIP/2.0 180 Ringing
5371 Via: SIP/2.0/UDP 172.26.218.10:5060;branch=z9hG4bK-66182-18ece55e-44127464-488f8630;received=172.26.218.10;rport=5060
5372 From: "Shkutov Pavel"<sip:962191@172.26.223.29>;tag=48a72418-ada1aac-13c4-65014-66182-4d59761a-66182
5373 To: <sip:102999@172.26.223.29>;tag=as4be79c79
5374 Call-ID: 48b1d928-ada1aac-13c4-65014-66182-40a8d8bc-66182
5375 CSeq: 1 INVITE
5376 Server: FPBX-15.0.16.81(16.13.0)
5377 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
5378 Supported: replaces, timer
5379 Contact: <sip:102999@172.26.223.29:5060>
5380 Content-Length: 0
5381
5382
5383 <------------>
5384 [2021-04-20 10:55:16] VERBOSE[18872] chan_sip.c:
5385 <--- SIP read from UDP:172.16.173.148:5060 --->
5386 SIP/2.0 180 Ringing
5387 Via: SIP/2.0/UDP 172.26.223.29:5060;branch=z9hG4bK54af0aec;received=172.26.223.29
5388 From: "962191" <sip:962191@172.26.223.29>;tag=as0cc0ee63
5389 To: <sip:2999@172.16.173.148>;tag=as34bce41e
5390 Call-ID: 0c06a95f125a5a07390cf3741e2e38ba@172.26.223.29:5060
5391 CSeq: 102 INVITE
5392 Server: FPBX-14.0.16(16.13.0)
5393 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
5394 Supported: replaces,timer
5395 Session-Expires: 1800;refresher=uas
5396 Contact: <sip:2999@172.16.173.148:5060>
5397 Remote-Party-ID: "Тест" <sip:2999@172.26.223.29>;party=called;privacy=off;screen=no
5398 Content-Length: 0
5399
5400 <------------->
5401 [2021-04-20 10:55:16] VERBOSE[18872] chan_sip.c: --- (13 headers 0 lines) ---
5402 [2021-04-20 10:55:16] VERBOSE[18872][C-0000000f] sip/route.c: sip_route_dump: route/path hop: <sip:2999@172.16.173.148:5060>
5403 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] app_dial.c: SIP/10-SPB_test_node_2 trunkOUT-0000001d is ringing
5404 [2021-04-20 10:55:17] VERBOSE[18872] chan_sip.c: Reliably Transmitting (no NAT) to 172.16.173.148:5060:
5405 OPTIONS sip:172.16.173.148 SIP/2.0
5406 Via: SIP/2.0/UDP 172.26.223.29:5060;branch=z9hG4bK09e2afa9
5407 Max-Forwards: 70
5408 From: "Unknown" <sip:Unknown@172.26.223.29>;tag=as197395bd
5409 To: <sip:172.16.173.148>
5410 Contact: <sip:Unknown@172.26.223.29:5060>
5411 Call-ID: 129151281eb63a6d2ac3b7f7513df78b@172.26.223.29:5060
5412 CSeq: 102 OPTIONS
5413 User-Agent: FPBX-15.0.16.81(16.13.0)
5414 Date: Tue, 20 Apr 2021 10:55:17 GMT
5415 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
5416 Supported: replaces, timer
5417 Content-Length: 0
5418
5419
5420 ---
5421 [2021-04-20 10:55:17] VERBOSE[18872] chan_sip.c: Reliably Transmitting (no NAT) to 172.16.173.148:5060:
5422 OPTIONS sip:172.16.173.148 SIP/2.0
5423 Via: SIP/2.0/UDP 172.26.223.29:5060;branch=z9hG4bK068b6016
5424 Max-Forwards: 70
5425 From: "Unknown" <sip:Unknown@172.26.223.29>;tag=as15509688
5426 To: <sip:172.16.173.148>
5427 Contact: <sip:Unknown@172.26.223.29:5060>
5428 Call-ID: 136b5fd5794034494c322c7b7900cb66@172.26.223.29:5060
5429 CSeq: 102 OPTIONS
5430 User-Agent: FPBX-15.0.16.81(16.13.0)
5431 Date: Tue, 20 Apr 2021 10:55:17 GMT
5432 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
5433 Supported: replaces, timer
5434 Content-Length: 0
5435
5436
5437 ---
5438 [2021-04-20 10:55:17] VERBOSE[18872] chan_sip.c:
5439 <--- SIP read from UDP:172.16.173.148:5060 --->
5440 SIP/2.0 200 OK
5441 Via: SIP/2.0/UDP 172.26.223.29:5060;branch=z9hG4bK09e2afa9;received=172.26.223.29
5442 From: "Unknown" <sip:Unknown@172.26.223.29>;tag=as197395bd
5443 To: <sip:172.16.173.148>;tag=as1e93ddfe
5444 Call-ID: 129151281eb63a6d2ac3b7f7513df78b@172.26.223.29:5060
5445 CSeq: 102 OPTIONS
5446 Server: FPBX-14.0.16(16.13.0)
5447 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
5448 Supported: replaces,timer
5449 Contact: <sip:172.16.173.148:5060>
5450 Accept: application/sdp
5451 Content-Length: 0
5452
5453 <------------->
5454 [2021-04-20 10:55:17] VERBOSE[18872] chan_sip.c: --- (12 headers 0 lines) ---
5455 [2021-04-20 10:55:17] VERBOSE[18872] chan_sip.c: Really destroying SIP dialog '129151281eb63a6d2ac3b7f7513df78b@172.26.223.29:5060' Method: OPTIONS
5456 [2021-04-20 10:55:17] VERBOSE[18872] chan_sip.c:
5457 <--- SIP read from UDP:172.16.173.148:5060 --->
5458 SIP/2.0 200 OK
5459 Via: SIP/2.0/UDP 172.26.223.29:5060;branch=z9hG4bK068b6016;received=172.26.223.29
5460 From: "Unknown" <sip:Unknown@172.26.223.29>;tag=as15509688
5461 To: <sip:172.16.173.148>;tag=as5d24ee77
5462 Call-ID: 136b5fd5794034494c322c7b7900cb66@172.26.223.29:5060
5463 CSeq: 102 OPTIONS
5464 Server: FPBX-14.0.16(16.13.0)
5465 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
5466 Supported: replaces,timer
5467 Contact: <sip:172.16.173.148:5060>
5468 Accept: application/sdp
5469 Content-Length: 0
5470
5471 <------------->
5472 [2021-04-20 10:55:17] VERBOSE[18872] chan_sip.c: --- (12 headers 0 lines) ---
5473 [2021-04-20 10:55:17] VERBOSE[18872] chan_sip.c: Really destroying SIP dialog '136b5fd5794034494c322c7b7900cb66@172.26.223.29:5060' Method: OPTIONS
5474 [2021-04-20 10:55:29] VERBOSE[18872] chan_sip.c:
5475 <--- SIP read from UDP:172.26.218.10:5060 --->
5476 CANCEL sip:102999@172.26.223.29 SIP/2.0
5477 From: "Shkutov Pavel"<sip:962191@172.26.223.29>;tag=48a72418-ada1aac-13c4-65014-66182-4d59761a-66182
5478 To: <sip:102999@172.26.223.29>
5479 Call-ID: 48b1d928-ada1aac-13c4-65014-66182-40a8d8bc-66182
5480 CSeq: 1 CANCEL
5481 Via: SIP/2.0/UDP 172.26.218.10:5060;rport;branch=z9hG4bK-66182-18ece55e-44127464-488f8630
5482 Max-Forwards: 70
5483 Supported: 100rel,timer,replaces,UPDATE,INFO
5484 User-Agent: Ericsson-LG Enterprise iPECS-eMG eMG800 2.1.11
5485 Content-Length: 0
5486
5487 <------------->
5488 [2021-04-20 10:55:29] VERBOSE[18872] chan_sip.c: --- (10 headers 0 lines) ---
5489 [2021-04-20 10:55:29] VERBOSE[18872][C-0000000f] chan_sip.c: Sending to 172.26.218.10:5060 (no NAT)
5490 [2021-04-20 10:55:29] VERBOSE[18872][C-0000000f] chan_sip.c:
5491 <--- Reliably Transmitting (no NAT) to 172.26.218.10:5060 --->
5492 SIP/2.0 487 Request Terminated
5493 Via: SIP/2.0/UDP 172.26.218.10:5060;branch=z9hG4bK-66182-18ece55e-44127464-488f8630;received=172.26.218.10;rport=5060
5494 From: "Shkutov Pavel"<sip:962191@172.26.223.29>;tag=48a72418-ada1aac-13c4-65014-66182-4d59761a-66182
5495 To: <sip:102999@172.26.223.29>;tag=as4be79c79
5496 Call-ID: 48b1d928-ada1aac-13c4-65014-66182-40a8d8bc-66182
5497 CSeq: 1 INVITE
5498 Server: FPBX-15.0.16.81(16.13.0)
5499 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
5500 Supported: replaces, timer
5501 Content-Length: 0
5502
5503
5504 <------------>
5505 [2021-04-20 10:55:29] VERBOSE[18872][C-0000000f] chan_sip.c:
5506 <--- Transmitting (no NAT) to 172.26.218.10:5060 --->
5507 SIP/2.0 200 OK
5508 Via: SIP/2.0/UDP 172.26.218.10:5060;branch=z9hG4bK-66182-18ece55e-44127464-488f8630;received=172.26.218.10;rport=5060
5509 From: "Shkutov Pavel"<sip:962191@172.26.223.29>;tag=48a72418-ada1aac-13c4-65014-66182-4d59761a-66182
5510 To: <sip:102999@172.26.223.29>;tag=as4be79c79
5511 Call-ID: 48b1d928-ada1aac-13c4-65014-66182-40a8d8bc-66182
5512 CSeq: 1 CANCEL
5513 Server: FPBX-15.0.16.81(16.13.0)
5514 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
5515 Supported: replaces, timer
5516 Content-Length: 0
5517
5518
5519 <------------>
5520 [2021-04-20 10:55:29] VERBOSE[34827][C-0000000f] chan_sip.c: Scheduling destruction of SIP dialog '0c06a95f125a5a07390cf3741e2e38ba@172.26.223.29:5060' in 6400 ms (Method: INVITE)
5521 [2021-04-20 10:55:29] VERBOSE[34827][C-0000000f] chan_sip.c: Reliably Transmitting (no NAT) to 172.16.173.148:5060:
5522 CANCEL sip:2999@172.16.173.148 SIP/2.0
5523 Via: SIP/2.0/UDP 172.26.223.29:5060;branch=z9hG4bK54af0aec
5524 Max-Forwards: 70
5525 From: "962191" <sip:962191@172.26.223.29>;tag=as0cc0ee63
5526 To: <sip:2999@172.16.173.148>
5527 Call-ID: 0c06a95f125a5a07390cf3741e2e38ba@172.26.223.29:5060
5528 CSeq: 102 CANCEL
5529 User-Agent: FPBX-15.0.16.81(16.13.0)
5530 Content-Length: 0
5531
5532
5533 ---
5534 [2021-04-20 10:55:29] VERBOSE[34827][C-0000000f] chan_sip.c: Scheduling destruction of SIP dialog '0c06a95f125a5a07390cf3741e2e38ba@172.26.223.29:5060' in 6400 ms (Method: INVITE)
5535 [2021-04-20 10:55:29] VERBOSE[34827][C-0000000f] pbx.c: Spawn extension (ext-trunk, tdial, 10) exited non-zero on 'SIP/172.26.218.10-0000001c'
5536 [2021-04-20 10:55:29] VERBOSE[34827][C-0000000f] app_stack.c: SIP/172.26.218.10-0000001c Internal Gosub(crm-hangup,s,1) start
5537 [2021-04-20 10:55:29] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@crm-hangup:1] NoOp("SIP/172.26.218.10-0000001c", "Sending Hangup to CRM") in new stack
5538 [2021-04-20 10:55:29] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@crm-hangup:2] NoOp("SIP/172.26.218.10-0000001c", "HANGUP CAUSE: 0") in new stack
5539 [2021-04-20 10:55:29] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@crm-hangup:3] ExecIf("SIP/172.26.218.10-0000001c", "0?Set(__CRM_VOICEMAIL=)") in new stack
5540 [2021-04-20 10:55:29] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@crm-hangup:4] NoOp("SIP/172.26.218.10-0000001c", "MASTER CHANNEL: 1618916116.28 = 1618916116.28") in new stack
5541 [2021-04-20 10:55:29] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@crm-hangup:5] GotoIf("SIP/172.26.218.10-0000001c", "0?return") in new stack
5542 [2021-04-20 10:55:29] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@crm-hangup:6] Set("SIP/172.26.218.10-0000001c", "__CRM_HANGUP=1") in new stack
5543 [2021-04-20 10:55:29] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@crm-hangup:7] AGI("SIP/172.26.218.10-0000001c", "agi://127.0.0.1/sangomacrm.agi") in new stack
5544 [2021-04-20 10:55:29] VERBOSE[18872] chan_sip.c:
5545 <--- SIP read from UDP:172.26.218.10:5060 --->
5546 ACK sip:102999@172.26.223.29 SIP/2.0
5547 From: "Shkutov Pavel"<sip:962191@172.26.223.29>;tag=48a72418-ada1aac-13c4-65014-66182-4d59761a-66182
5548 To: <sip:102999@172.26.223.29>;tag=as4be79c79
5549 Call-ID: 48b1d928-ada1aac-13c4-65014-66182-40a8d8bc-66182
5550 CSeq: 1 ACK
5551 Via: SIP/2.0/UDP 172.26.218.10:5060;rport;branch=z9hG4bK-66182-18ece55e-44127464-488f8630
5552 Max-Forwards: 70
5553 User-Agent: Ericsson-LG Enterprise iPECS-eMG eMG800 2.1.11
5554 Contact: <sip:962191@172.26.218.10:5060;transport=UDP>
5555 Content-Length: 0
5556
5557 <------------->
5558 [2021-04-20 10:55:29] VERBOSE[18872] chan_sip.c: --- (10 headers 0 lines) ---
5559 [2021-04-20 10:55:29] VERBOSE[18872] chan_sip.c:
5560 <--- SIP read from UDP:172.16.173.148:5060 --->
5561 SIP/2.0 487 Request Terminated
5562 Via: SIP/2.0/UDP 172.26.223.29:5060;branch=z9hG4bK54af0aec;received=172.26.223.29
5563 From: "962191" <sip:962191@172.26.223.29>;tag=as0cc0ee63
5564 To: <sip:2999@172.16.173.148>;tag=as34bce41e
5565 Call-ID: 0c06a95f125a5a07390cf3741e2e38ba@172.26.223.29:5060
5566 CSeq: 102 INVITE
5567 Server: FPBX-14.0.16(16.13.0)
5568 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
5569 Supported: replaces,timer
5570 Content-Length: 0
5571
5572 <------------->
5573 [2021-04-20 10:55:29] VERBOSE[18872] chan_sip.c: --- (10 headers 0 lines) ---
5574 [2021-04-20 10:55:29] VERBOSE[18872][C-0000000f] chan_sip.c: Transmitting (no NAT) to 172.16.173.148:5060:
5575 ACK sip:2999@172.16.173.148:5060 SIP/2.0
5576 Via: SIP/2.0/UDP 172.26.223.29:5060;branch=z9hG4bK54af0aec
5577 Max-Forwards: 70
5578 From: "962191" <sip:962191@172.26.223.29>;tag=as0cc0ee63
5579 To: <sip:2999@172.16.173.148>;tag=as34bce41e
5580 Contact: <sip:962191@172.26.223.29:5060>
5581 Call-ID: 0c06a95f125a5a07390cf3741e2e38ba@172.26.223.29:5060
5582 CSeq: 102 ACK
5583 User-Agent: FPBX-15.0.16.81(16.13.0)
5584 Content-Length: 0
5585
5586
5587 ---
5588 [2021-04-20 10:55:29] VERBOSE[18872][C-0000000f] chan_sip.c: Scheduling destruction of SIP dialog '0c06a95f125a5a07390cf3741e2e38ba@172.26.223.29:5060' in 6400 ms (Method: INVITE)
5589 [2021-04-20 10:55:29] VERBOSE[18872] chan_sip.c:
5590 <--- SIP read from UDP:172.16.173.148:5060 --->
5591 SIP/2.0 200 OK
5592 Via: SIP/2.0/UDP 172.26.223.29:5060;branch=z9hG4bK54af0aec;received=172.26.223.29
5593 From: "962191" <sip:962191@172.26.223.29>;tag=as0cc0ee63
5594 To: <sip:2999@172.16.173.148>;tag=as34bce41e
5595 Call-ID: 0c06a95f125a5a07390cf3741e2e38ba@172.26.223.29:5060
5596 CSeq: 102 CANCEL
5597 Server: FPBX-14.0.16(16.13.0)
5598 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
5599 Supported: replaces,timer
5600 Content-Length: 0
5601
5602 <------------->
5603 [2021-04-20 10:55:29] VERBOSE[18872] chan_sip.c: --- (10 headers 0 lines) ---
5604 [2021-04-20 10:55:29] VERBOSE[34827][C-0000000f] res_agi.c: <SIP/172.26.218.10-0000001c>AGI Script agi://127.0.0.1/sangomacrm.agi completed, returning 0
5605 [2021-04-20 10:55:29] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@crm-hangup:8] Return("SIP/172.26.218.10-0000001c", "") in new stack
5606 [2021-04-20 10:55:29] VERBOSE[34827][C-0000000f] app_stack.c: Spawn extension (ext-trunk, tdial, 10) exited non-zero on 'SIP/172.26.218.10-0000001c'
5607 [2021-04-20 10:55:29] VERBOSE[34827][C-0000000f] app_stack.c: SIP/172.26.218.10-0000001c Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
5608 [2021-04-20 10:55:30] VERBOSE[18872] chan_sip.c: Really destroying SIP dialog '48b1d928-ada1aac-13c4-65014-66182-40a8d8bc-66182' Method: ACK
5609 [2021-04-20 10:55:35] VERBOSE[18872] chan_sip.c: Really destroying SIP dialog '0c06a95f125a5a07390cf3741e2e38ba@172.26.223.29:5060' Method: INVITE
5151
5152 <------------->
5153 [2021-04-20 10:55:11] VERBOSE[18872] chan_sip.c: --- (11 headers 0 lines) ---
5154 [2021-04-20 10:55:11] VERBOSE[18872] chan_sip.c: Really destroying SIP dialog '1697ecc52815df53215643067cbe5359@172.26.223.29:5060' Method: OPTIONS
5155 [2021-04-20 10:55:16] VERBOSE[18872] chan_sip.c:
5156 <--- SIP read from UDP:172.26.218.10:5060 --->
5157 INVITE sip:102999@172.26.223.29 SIP/2.0
5158 From: "Shkutov Pavel"<sip:962191@172.26.223.29>;tag=48a72418-ada1aac-13c4-65014-66182-4d59761a-66182
5159 To: <sip:102999@172.26.223.29>
5160 Call-ID: 48b1d928-ada1aac-13c4-65014-66182-40a8d8bc-66182
5161 CSeq: 1 INVITE
5162 Via: SIP/2.0/UDP 172.26.218.10:5060;rport;branch=z9hG4bK-66182-18ece55e-44127464-488f8630
5163 Max-Forwards: 70
5164 Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,REGISTER,REFER,SUBSCRIBE,NOTIFY,MESSAGE,INFO,PRACK,UPDATE
5165 Supported: replaces,UPDATE,INFO
5166 P-Asserted-Identity: "Shkutov Pavel"<sip:962191@172.26.223.29>
5167 Remote-Party-ID: <sip:962191@172.26.218.10>; party=calling; privacy=off; screen=yes
5168 Privacy: none
5169 User-Agent: Ericsson-LG Enterprise iPECS-eMG eMG800 2.1.11
5170 Contact: "Shkutov Pavel"<sip:962191@172.26.218.10:5060;transport=UDP>
5171 Content-Type: application/sdp
5172 Content-Length: 231
5173
5174 v=0
5175 o=iPECS-eMG 22 22 IN IP4 172.26.218.10
5176 s=iPECS-eMG SIP
5177 c=IN IP4 172.26.218.10
5178 t=0 0
5179 m=audio 20538 RTP/AVP 18 101
5180 a=rtpmap:18 G729/8000
5181 a=fmtp:18 annexb=no
5182 a=rtpmap:101 telephone-event/8000
5183 a=fmtp:101 0-15
5184 a=sendrecv
5185 <------------->
5186 [2021-04-20 10:55:16] VERBOSE[18872] chan_sip.c: --- (16 headers 11 lines) ---
5187 [2021-04-20 10:55:16] VERBOSE[18872] chan_sip.c: Sending to 172.26.218.10:5060 (no NAT)
5188 [2021-04-20 10:55:16] VERBOSE[18872][C-0000000f] chan_sip.c: Sending to 172.26.218.10:5060 (no NAT)
5189 [2021-04-20 10:55:16] VERBOSE[18872][C-0000000f] chan_sip.c: Using INVITE request as basis request - 48b1d928-ada1aac-13c4-65014-66182-40a8d8bc-66182
5190 [2021-04-20 10:55:16] VERBOSE[18872][C-0000000f] chan_sip.c: Found peer '172.26.218.10' for '962191' from 172.26.218.10:5060
5191 [2021-04-20 10:55:16] VERBOSE[18872][C-0000000f] netsock2.c: Using SIP RTP TOS bits 184
5192 [2021-04-20 10:55:16] VERBOSE[18872][C-0000000f] netsock2.c: Using SIP RTP CoS mark 5
5193 [2021-04-20 10:55:16] VERBOSE[18872][C-0000000f] chan_sip.c: Got SDP version 22 and unique parts [iPECS-eMG 22 IN IP4 172.26.218.10]
5194 [2021-04-20 10:55:16] VERBOSE[18872][C-0000000f] chan_sip.c: Found RTP audio format 18
5195 [2021-04-20 10:55:16] VERBOSE[18872][C-0000000f] chan_sip.c: Found RTP audio format 101
5196 [2021-04-20 10:55:16] VERBOSE[18872][C-0000000f] chan_sip.c: Found audio description format G729 for ID 18
5197 [2021-04-20 10:55:16] VERBOSE[18872][C-0000000f] chan_sip.c: Found audio description format telephone-event for ID 101
5198 [2021-04-20 10:55:16] VERBOSE[18872][C-0000000f] chan_sip.c: Capabilities: us - (g729), peer - audio=(g729)/video=(nothing)/text=(nothing), combined - (g729)
5199 [2021-04-20 10:55:16] VERBOSE[18872][C-0000000f] chan_sip.c: Non-codec capabilities (dtmf): us - 0x1 (telephone-event|), peer - 0x1 (telephone-event|), combined - 0x1 (telephone-event|)
5200 [2021-04-20 10:55:16] VERBOSE[18872][C-0000000f] chan_sip.c: Peer audio RTP is at port 172.26.218.10:20538
5201 [2021-04-20 10:55:16] VERBOSE[18872][C-0000000f] chan_sip.c: Looking for 102999 in from-trunk (domain 172.26.223.29)
5202 [2021-04-20 10:55:16] VERBOSE[18872][C-0000000f] sip/route.c: sip_route_dump: route/path hop: <sip:962191@172.26.218.10:5060;transport=UDP>
5203 [2021-04-20 10:55:16] VERBOSE[18872][C-0000000f] chan_sip.c:
5204 <--- Transmitting (no NAT) to 172.26.218.10:5060 --->
5205 SIP/2.0 100 Trying
5206 Via: SIP/2.0/UDP 172.26.218.10:5060;branch=z9hG4bK-66182-18ece55e-44127464-488f8630;received=172.26.218.10;rport=5060
5207 From: "Shkutov Pavel"<sip:962191@172.26.223.29>;tag=48a72418-ada1aac-13c4-65014-66182-4d59761a-66182
5208 To: <sip:102999@172.26.223.29>
5209 Call-ID: 48b1d928-ada1aac-13c4-65014-66182-40a8d8bc-66182
5210 CSeq: 1 INVITE
5211 Server: FPBX-15.0.16.81(16.13.0)
5212 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
5213 Supported: replaces, timer
5214 Contact: <sip:102999@172.26.223.29:5060>
5215 Content-Length: 0
5216
5217
5218 <------------>
5219 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:1] Set("SIP/172.26.218.10-0000001c", "__DIRECTION=INBOUND") in new stack
5220 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:2] Gosub("SIP/172.26.218.10-0000001c", "sub-record-check,s,1(in,102999,dontcare)") in new stack
5221 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-record-check:1] GotoIf("SIP/172.26.218.10-0000001c", "0?initialized") in new stack
5222 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-record-check:2] Set("SIP/172.26.218.10-0000001c", "__REC_STATUS=INITIALIZED") in new stack
5223 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-record-check:3] Set("SIP/172.26.218.10-0000001c", "NOW=1618916116") in new stack
5224 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-record-check:4] Set("SIP/172.26.218.10-0000001c", "__DAY=20") in new stack
5225 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-record-check:5] Set("SIP/172.26.218.10-0000001c", "__MONTH=04") in new stack
5226 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-record-check:6] Set("SIP/172.26.218.10-0000001c", "__YEAR=2021") in new stack
5227 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-record-check:7] Set("SIP/172.26.218.10-0000001c", "__TIMESTR=20210420-105516") in new stack
5228 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-record-check:8] Set("SIP/172.26.218.10-0000001c", "__FROMEXTEN=unknown") in new stack
5229 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-record-check:9] Set("SIP/172.26.218.10-0000001c", "__MON_FMT=wav") in new stack
5230 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-record-check:10] NoOp("SIP/172.26.218.10-0000001c", "Recordings initialized") in new stack
5231 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-record-check:11] ExecIf("SIP/172.26.218.10-0000001c", "0?Set(ARG3=dontcare)") in new stack
5232 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-record-check:12] Set("SIP/172.26.218.10-0000001c", "REC_POLICY_MODE_SAVE=") in new stack
5233 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-record-check:13] ExecIf("SIP/172.26.218.10-0000001c", "0?Set(REC_STATUS=NO)") in new stack
5234 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-record-check:14] GotoIf("SIP/172.26.218.10-0000001c", "2?checkaction") in new stack
5235 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx_builtins.c: Goto (sub-record-check,s,17)
5236 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-record-check:17] GotoIf("SIP/172.26.218.10-0000001c", "1?sub-record-check,in,1") in new stack
5237 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx_builtins.c: Goto (sub-record-check,in,1)
5238 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [in@sub-record-check:1] NoOp("SIP/172.26.218.10-0000001c", "Inbound Recording Check to 102999") in new stack
5239 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [in@sub-record-check:2] Set("SIP/172.26.218.10-0000001c", "FROMEXTEN=unknown") in new stack
5240 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [in@sub-record-check:3] ExecIf("SIP/172.26.218.10-0000001c", "6?Set(FROMEXTEN=962191)") in new stack
5241 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [in@sub-record-check:4] Gosub("SIP/172.26.218.10-0000001c", "recordcheck,1(dontcare,in,102999)") in new stack
5242 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("SIP/172.26.218.10-0000001c", "Starting recording check against dontcare") in new stack
5243 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("SIP/172.26.218.10-0000001c", "dontcare") in new stack
5244 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
5245 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [recordcheck@sub-record-check:3] Return("SIP/172.26.218.10-0000001c", "") in new stack
5246 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [in@sub-record-check:5] Return("SIP/172.26.218.10-0000001c", "") in new stack
5247 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:3] Set("SIP/172.26.218.10-0000001c", "CHANNEL(tonezone)=us") in new stack
5248 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:4] Set("SIP/172.26.218.10-0000001c", "__FROM_DID=102999") in new stack
5249 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:5] Set("SIP/172.26.218.10-0000001c", "returnhere=1") in new stack
5250 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:6] Gosub("SIP/172.26.218.10-0000001c", "app-blacklist-check,s,1()") in new stack
5251 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@app-blacklist-check:1] GotoIf("SIP/172.26.218.10-0000001c", "0?blacklisted") in new stack
5252 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@app-blacklist-check:2] Set("SIP/172.26.218.10-0000001c", "CALLED_BLACKLIST=1") in new stack
5253 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@app-blacklist-check:3] Return("SIP/172.26.218.10-0000001c", "") in new stack
5254 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:7] Set("SIP/172.26.218.10-0000001c", "CDR(did)=102999") in new stack
5255 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:8] GotoIf("SIP/172.26.218.10-0000001c", "0?") in new stack
5256 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:9] ExecIf("SIP/172.26.218.10-0000001c", "1 ?Set(CALLERID(name)=962191)") in new stack
5257 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:10] Set("SIP/172.26.218.10-0000001c", "__MOHCLASS=") in new stack
5258 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:11] Set("SIP/172.26.218.10-0000001c", "__REVERSAL_REJECT=FALSE") in new stack
5259 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:12] GotoIf("SIP/172.26.218.10-0000001c", "1?post-reverse-charge") in new stack
5260 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx_builtins.c: Goto (from-trunk,102999,14)
5261 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:14] NoOp("SIP/172.26.218.10-0000001c", "") in new stack
5262 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:15] Set("SIP/172.26.218.10-0000001c", "__CALLINGNAMEPRES_SV=allowed_not_screened") in new stack
5263 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:16] Set("SIP/172.26.218.10-0000001c", "__CALLINGNUMPRES_SV=allowed_not_screened") in new stack
5264 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:17] Set("SIP/172.26.218.10-0000001c", "CALLERID(name-pres)=allowed_not_screened") in new stack
5265 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:18] Set("SIP/172.26.218.10-0000001c", "CALLERID(num-pres)=allowed_not_screened") in new stack
5266 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:19] NoOp("SIP/172.26.218.10-0000001c", "CallerID Entry Point") in new stack
5267 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:20] Set("SIP/172.26.218.10-0000001c", "__CRM_DIRECTION=INBOUND") in new stack
5268 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:21] Set("SIP/172.26.218.10-0000001c", "__CRM_SOURCE=962191") in new stack
5269 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:22] Set("SIP/172.26.218.10-0000001c", "__CRM_LINKEDID=1618916116.28") in new stack
5270 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:23] AGI("SIP/172.26.218.10-0000001c", "agi://127.0.0.1/sangomacrm.agi,true") in new stack
5271 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] res_agi.c: <SIP/172.26.218.10-0000001c>AGI Script agi://127.0.0.1/sangomacrm.agi completed, returning 0
5272 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:24] ExecIf("SIP/172.26.218.10-0000001c", "1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
5273 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:25] Goto("SIP/172.26.218.10-0000001c", "ext-trunk,1,1") in new stack
5274 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx_builtins.c: Goto (ext-trunk,1,1)
5275 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [1@ext-trunk:1] Set("SIP/172.26.218.10-0000001c", "TDIAL_STRING=SIP/10-SPB_test_node_2 trunkOUT") in new stack
5276 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [1@ext-trunk:2] Set("SIP/172.26.218.10-0000001c", "DIAL_TRUNK=1") in new stack
5277 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [1@ext-trunk:3] Goto("SIP/172.26.218.10-0000001c", "ext-trunk,tdial,1") in new stack
5278 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx_builtins.c: Goto (ext-trunk,tdial,1)
5279 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [tdial@ext-trunk:1] Set("SIP/172.26.218.10-0000001c", "OUTBOUND_GROUP=OUT_1") in new stack
5280 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [tdial@ext-trunk:2] GotoIf("SIP/172.26.218.10-0000001c", "1?nomax") in new stack
5281 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx_builtins.c: Goto (ext-trunk,tdial,4)
5282 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [tdial@ext-trunk:4] ExecIf("SIP/172.26.218.10-0000001c", "1?Set(CALLERPRES(name-pres)=allowed_not_screened)") in new stack
5283 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [tdial@ext-trunk:5] ExecIf("SIP/172.26.218.10-0000001c", "1?Set(CALLERPRES(num-pres)=allowed_not_screened)") in new stack
5284 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [tdial@ext-trunk:6] Set("SIP/172.26.218.10-0000001c", "DIAL_NUMBER=102999") in new stack
5285 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [tdial@ext-trunk:7] GosubIf("SIP/172.26.218.10-0000001c", "1?sub-flp-1,s,1()") in new stack
5286 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-flp-1:1] ExecIf("SIP/172.26.218.10-0000001c", "1?Set(TARGET_FLP_1=2999)") in new stack
5287 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-flp-1:2] GotoIf("SIP/172.26.218.10-0000001c", "1?match") in new stack
5288 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx_builtins.c: Goto (sub-flp-1,s,4)
5289 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-flp-1:4] Set("SIP/172.26.218.10-0000001c", "DIAL_NUMBER=2999") in new stack
5290 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-flp-1:5] Return("SIP/172.26.218.10-0000001c", "") in new stack
5291 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [tdial@ext-trunk:8] Set("SIP/172.26.218.10-0000001c", "OUTNUM=2999") in new stack
5292 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [tdial@ext-trunk:9] Set("SIP/172.26.218.10-0000001c", "DIAL_TRUNK_OPTIONS=T") in new stack
5293 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [tdial@ext-trunk:10] Dial("SIP/172.26.218.10-0000001c", "SIP/10-SPB_test_node_2 trunkOUT/2999,300,T") in new stack
5294 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] netsock2.c: Using SIP RTP TOS bits 184
5295 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] netsock2.c: Using SIP RTP CoS mark 5
5296 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] chan_sip.c: Audio is at 17736
5297 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] chan_sip.c: Adding codec g729 to SDP
5298 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] chan_sip.c: Adding non-codec 0x1 (telephone-event) to SDP
5299 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] chan_sip.c: Reliably Transmitting (no NAT) to 172.16.173.148:5060:
5300 INVITE sip:2999@172.16.173.148 SIP/2.0
5301 Via: SIP/2.0/UDP 172.26.223.29:5060;branch=z9hG4bK54af0aec
5302 Max-Forwards: 70
5303 From: "962191" <sip:962191@172.26.223.29>;tag=as0cc0ee63
5304 To: <sip:2999@172.16.173.148>
5305 Contact: <sip:962191@172.26.223.29:5060>
5306 Call-ID: 0c06a95f125a5a07390cf3741e2e38ba@172.26.223.29:5060
5307 CSeq: 102 INVITE
5308 User-Agent: FPBX-15.0.16.81(16.13.0)
5309 Date: Tue, 20 Apr 2021 10:55:16 GMT
5310 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
5311 Supported: replaces, timer
5312 Remote-Party-ID: "962191" <sip:962191@172.26.223.29>;party=calling;privacy=off;screen=no
5313 Content-Type: application/sdp
5314 Content-Length: 277
5315
5316 v=0
5317 o=root 1979690048 1979690048 IN IP4 172.26.223.29
5318 s=Asterisk PBX 16.13.0
5319 c=IN IP4 172.26.223.29
5320 t=0 0
5321 m=audio 17736 RTP/AVP 18 101
5322 a=rtpmap:18 G729/8000
5323 a=fmtp:18 annexb=no
5324 a=rtpmap:101 telephone-event/8000
5325 a=fmtp:101 0-16
5326 a=ptime:20
5327 a=maxptime:230
5328 a=sendrecv
5329
5330 ---
5331 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] app_dial.c: Called SIP/10-SPB_test_node_2 trunkOUT/2999
5332 [2021-04-20 10:55:16] VERBOSE[18872] chan_sip.c:
5333 <--- SIP read from UDP:172.16.173.148:5060 --->
5334 SIP/2.0 100 Trying
5335 Via: SIP/2.0/UDP 172.26.223.29:5060;branch=z9hG4bK54af0aec;received=172.26.223.29
5336 From: "962191" <sip:962191@172.26.223.29>;tag=as0cc0ee63
5337 To: <sip:2999@172.16.173.148>
5338 Call-ID: 0c06a95f125a5a07390cf3741e2e38ba@172.26.223.29:5060
5339 CSeq: 102 INVITE
5340 Server: FPBX-14.0.16(16.13.0)
5341 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
5342 Supported: replaces,timer
5343 Session-Expires: 1800;refresher=uas
5344 Contact: <sip:2999@172.16.173.148:5060>
5345 Content-Length: 0
5346
5347 <------------->
5348 [2021-04-20 10:55:16] VERBOSE[18872] chan_sip.c: --- (12 headers 0 lines) ---
5349 [2021-04-20 10:55:16] VERBOSE[18872] chan_sip.c:
5350 <--- SIP read from UDP:172.16.173.148:5060 --->
5351 SIP/2.0 180 Ringing
5352 Via: SIP/2.0/UDP 172.26.223.29:5060;branch=z9hG4bK54af0aec;received=172.26.223.29
5353 From: "962191" <sip:962191@172.26.223.29>;tag=as0cc0ee63
5354 To: <sip:2999@172.16.173.148>;tag=as34bce41e
5355 Call-ID: 0c06a95f125a5a07390cf3741e2e38ba@172.26.223.29:5060
5356 CSeq: 102 INVITE
5357 Server: FPBX-14.0.16(16.13.0)
5358 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
5359 Supported: replaces,timer
5360 Session-Expires: 1800;refresher=uas
5361 Contact: <sip:2999@172.16.173.148:5060>
5362 Content-Length: 0
5363
5364 <------------->
5365 [2021-04-20 10:55:16] VERBOSE[18872] chan_sip.c: --- (12 headers 0 lines) ---
5366 [2021-04-20 10:55:16] VERBOSE[18872][C-0000000f] sip/route.c: sip_route_dump: route/path hop: <sip:2999@172.16.173.148:5060>
5367 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] app_dial.c: SIP/10-SPB_test_node_2 trunkOUT-0000001d is ringing
5368 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] chan_sip.c:
5369 <--- Transmitting (no NAT) to 172.26.218.10:5060 --->
5370 SIP/2.0 180 Ringing
5371 Via: SIP/2.0/UDP 172.26.218.10:5060;branch=z9hG4bK-66182-18ece55e-44127464-488f8630;received=172.26.218.10;rport=5060
5372 From: "Shkutov Pavel"<sip:962191@172.26.223.29>;tag=48a72418-ada1aac-13c4-65014-66182-4d59761a-66182
5373 To: <sip:102999@172.26.223.29>;tag=as4be79c79
5374 Call-ID: 48b1d928-ada1aac-13c4-65014-66182-40a8d8bc-66182
5375 CSeq: 1 INVITE
5376 Server: FPBX-15.0.16.81(16.13.0)
5377 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
5378 Supported: replaces, timer
5379 Contact: <sip:102999@172.26.223.29:5060>
5380 Content-Length: 0
5153 [2021-04-20 10:55:11] VERBOSE[18872] chan_sip.c: --- (11 headers 0 lines) ---
5154 [2021-04-20 10:55:11] VERBOSE[18872] chan_sip.c: Really destroying SIP dialog '1697ecc52815df53215643067cbe5359@172.26.223.29:5060' Method: OPTIONS
5155 [2021-04-20 10:55:16] VERBOSE[18872] chan_sip.c:
5156 <--- SIP read from UDP:172.26.218.10:5060 --->
5157 INVITE sip:102999@172.26.223.29 SIP/2.0
5158 From: "Shkutov Pavel"<sip:962191@172.26.223.29>;tag=48a72418-ada1aac-13c4-65014-66182-4d59761a-66182
5159 To: <sip:102999@172.26.223.29>
5160 Call-ID: 48b1d928-ada1aac-13c4-65014-66182-40a8d8bc-66182
5161 CSeq: 1 INVITE
5162 Via: SIP/2.0/UDP 172.26.218.10:5060;rport;branch=z9hG4bK-66182-18ece55e-44127464-488f8630
5163 Max-Forwards: 70
5164 Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,REGISTER,REFER,SUBSCRIBE,NOTIFY,MESSAGE,INFO,PRACK,UPDATE
5165 Supported: replaces,UPDATE,INFO
5166 P-Asserted-Identity: "Shkutov Pavel"<sip:962191@172.26.223.29>
5167 Remote-Party-ID: <sip:962191@172.26.218.10>; party=calling; privacy=off; screen=yes
5168 Privacy: none
5169 User-Agent: Ericsson-LG Enterprise iPECS-eMG eMG800 2.1.11
5170 Contact: "Shkutov Pavel"<sip:962191@172.26.218.10:5060;transport=UDP>
5171 Content-Type: application/sdp
5172 Content-Length: 231
5173
5174 v=0
5175 o=iPECS-eMG 22 22 IN IP4 172.26.218.10
5176 s=iPECS-eMG SIP
5177 c=IN IP4 172.26.218.10
5178 t=0 0
5179 m=audio 20538 RTP/AVP 18 101
5180 a=rtpmap:18 G729/8000
5181 a=fmtp:18 annexb=no
5182 a=rtpmap:101 telephone-event/8000
5183 a=fmtp:101 0-15
5184 a=sendrecv
5185 <------------->
5186 [2021-04-20 10:55:16] VERBOSE[18872] chan_sip.c: --- (16 headers 11 lines) ---
5187 [2021-04-20 10:55:16] VERBOSE[18872] chan_sip.c: Sending to 172.26.218.10:5060 (no NAT)
5188 [2021-04-20 10:55:16] VERBOSE[18872][C-0000000f] chan_sip.c: Sending to 172.26.218.10:5060 (no NAT)
5189 [2021-04-20 10:55:16] VERBOSE[18872][C-0000000f] chan_sip.c: Using INVITE request as basis request - 48b1d928-ada1aac-13c4-65014-66182-40a8d8bc-66182
5190 [2021-04-20 10:55:16] VERBOSE[18872][C-0000000f] chan_sip.c: Found peer '172.26.218.10' for '962191' from 172.26.218.10:5060
5191 [2021-04-20 10:55:16] VERBOSE[18872][C-0000000f] netsock2.c: Using SIP RTP TOS bits 184
5192 [2021-04-20 10:55:16] VERBOSE[18872][C-0000000f] netsock2.c: Using SIP RTP CoS mark 5
5193 [2021-04-20 10:55:16] VERBOSE[18872][C-0000000f] chan_sip.c: Got SDP version 22 and unique parts [iPECS-eMG 22 IN IP4 172.26.218.10]
5194 [2021-04-20 10:55:16] VERBOSE[18872][C-0000000f] chan_sip.c: Found RTP audio format 18
5195 [2021-04-20 10:55:16] VERBOSE[18872][C-0000000f] chan_sip.c: Found RTP audio format 101
5196 [2021-04-20 10:55:16] VERBOSE[18872][C-0000000f] chan_sip.c: Found audio description format G729 for ID 18
5197 [2021-04-20 10:55:16] VERBOSE[18872][C-0000000f] chan_sip.c: Found audio description format telephone-event for ID 101
5198 [2021-04-20 10:55:16] VERBOSE[18872][C-0000000f] chan_sip.c: Capabilities: us - (g729), peer - audio=(g729)/video=(nothing)/text=(nothing), combined - (g729)
5199 [2021-04-20 10:55:16] VERBOSE[18872][C-0000000f] chan_sip.c: Non-codec capabilities (dtmf): us - 0x1 (telephone-event|), peer - 0x1 (telephone-event|), combined - 0x1 (telephone-event|)
5200 [2021-04-20 10:55:16] VERBOSE[18872][C-0000000f] chan_sip.c: Peer audio RTP is at port 172.26.218.10:20538
5201 [2021-04-20 10:55:16] VERBOSE[18872][C-0000000f] chan_sip.c: Looking for 102999 in from-trunk (domain 172.26.223.29)
5202 [2021-04-20 10:55:16] VERBOSE[18872][C-0000000f] sip/route.c: sip_route_dump: route/path hop: <sip:962191@172.26.218.10:5060;transport=UDP>
5203 [2021-04-20 10:55:16] VERBOSE[18872][C-0000000f] chan_sip.c:
5204 <--- Transmitting (no NAT) to 172.26.218.10:5060 --->
5205 SIP/2.0 100 Trying
5206 Via: SIP/2.0/UDP 172.26.218.10:5060;branch=z9hG4bK-66182-18ece55e-44127464-488f8630;received=172.26.218.10;rport=5060
5207 From: "Shkutov Pavel"<sip:962191@172.26.223.29>;tag=48a72418-ada1aac-13c4-65014-66182-4d59761a-66182
5208 To: <sip:102999@172.26.223.29>
5209 Call-ID: 48b1d928-ada1aac-13c4-65014-66182-40a8d8bc-66182
5210 CSeq: 1 INVITE
5211 Server: FPBX-15.0.16.81(16.13.0)
5212 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
5213 Supported: replaces, timer
5214 Contact: <sip:102999@172.26.223.29:5060>
5215 Content-Length: 0
5216
5217
5218 <------------>
5219 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:1] Set("SIP/172.26.218.10-0000001c", "__DIRECTION=INBOUND") in new stack
5220 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:2] Gosub("SIP/172.26.218.10-0000001c", "sub-record-check,s,1(in,102999,dontcare)") in new stack
5221 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-record-check:1] GotoIf("SIP/172.26.218.10-0000001c", "0?initialized") in new stack
5222 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-record-check:2] Set("SIP/172.26.218.10-0000001c", "__REC_STATUS=INITIALIZED") in new stack
5223 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-record-check:3] Set("SIP/172.26.218.10-0000001c", "NOW=1618916116") in new stack
5224 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-record-check:4] Set("SIP/172.26.218.10-0000001c", "__DAY=20") in new stack
5225 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-record-check:5] Set("SIP/172.26.218.10-0000001c", "__MONTH=04") in new stack
5226 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-record-check:6] Set("SIP/172.26.218.10-0000001c", "__YEAR=2021") in new stack
5227 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-record-check:7] Set("SIP/172.26.218.10-0000001c", "__TIMESTR=20210420-105516") in new stack
5228 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-record-check:8] Set("SIP/172.26.218.10-0000001c", "__FROMEXTEN=unknown") in new stack
5229 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-record-check:9] Set("SIP/172.26.218.10-0000001c", "__MON_FMT=wav") in new stack
5230 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-record-check:10] NoOp("SIP/172.26.218.10-0000001c", "Recordings initialized") in new stack
5231 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-record-check:11] ExecIf("SIP/172.26.218.10-0000001c", "0?Set(ARG3=dontcare)") in new stack
5232 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-record-check:12] Set("SIP/172.26.218.10-0000001c", "REC_POLICY_MODE_SAVE=") in new stack
5233 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-record-check:13] ExecIf("SIP/172.26.218.10-0000001c", "0?Set(REC_STATUS=NO)") in new stack
5234 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-record-check:14] GotoIf("SIP/172.26.218.10-0000001c", "2?checkaction") in new stack
5235 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx_builtins.c: Goto (sub-record-check,s,17)
5236 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-record-check:17] GotoIf("SIP/172.26.218.10-0000001c", "1?sub-record-check,in,1") in new stack
5237 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx_builtins.c: Goto (sub-record-check,in,1)
5238 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [in@sub-record-check:1] NoOp("SIP/172.26.218.10-0000001c", "Inbound Recording Check to 102999") in new stack
5239 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [in@sub-record-check:2] Set("SIP/172.26.218.10-0000001c", "FROMEXTEN=unknown") in new stack
5240 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [in@sub-record-check:3] ExecIf("SIP/172.26.218.10-0000001c", "6?Set(FROMEXTEN=962191)") in new stack
5241 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [in@sub-record-check:4] Gosub("SIP/172.26.218.10-0000001c", "recordcheck,1(dontcare,in,102999)") in new stack
5242 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("SIP/172.26.218.10-0000001c", "Starting recording check against dontcare") in new stack
5243 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("SIP/172.26.218.10-0000001c", "dontcare") in new stack
5244 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
5245 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [recordcheck@sub-record-check:3] Return("SIP/172.26.218.10-0000001c", "") in new stack
5246 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [in@sub-record-check:5] Return("SIP/172.26.218.10-0000001c", "") in new stack
5247 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:3] Set("SIP/172.26.218.10-0000001c", "CHANNEL(tonezone)=us") in new stack
5248 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:4] Set("SIP/172.26.218.10-0000001c", "__FROM_DID=102999") in new stack
5249 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:5] Set("SIP/172.26.218.10-0000001c", "returnhere=1") in new stack
5250 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:6] Gosub("SIP/172.26.218.10-0000001c", "app-blacklist-check,s,1()") in new stack
5251 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@app-blacklist-check:1] GotoIf("SIP/172.26.218.10-0000001c", "0?blacklisted") in new stack
5252 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@app-blacklist-check:2] Set("SIP/172.26.218.10-0000001c", "CALLED_BLACKLIST=1") in new stack
5253 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@app-blacklist-check:3] Return("SIP/172.26.218.10-0000001c", "") in new stack
5254 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:7] Set("SIP/172.26.218.10-0000001c", "CDR(did)=102999") in new stack
5255 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:8] GotoIf("SIP/172.26.218.10-0000001c", "0?") in new stack
5256 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:9] ExecIf("SIP/172.26.218.10-0000001c", "1 ?Set(CALLERID(name)=962191)") in new stack
5257 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:10] Set("SIP/172.26.218.10-0000001c", "__MOHCLASS=") in new stack
5258 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:11] Set("SIP/172.26.218.10-0000001c", "__REVERSAL_REJECT=FALSE") in new stack
5259 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:12] GotoIf("SIP/172.26.218.10-0000001c", "1?post-reverse-charge") in new stack
5260 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx_builtins.c: Goto (from-trunk,102999,14)
5261 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:14] NoOp("SIP/172.26.218.10-0000001c", "") in new stack
5262 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:15] Set("SIP/172.26.218.10-0000001c", "__CALLINGNAMEPRES_SV=allowed_not_screened") in new stack
5263 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:16] Set("SIP/172.26.218.10-0000001c", "__CALLINGNUMPRES_SV=allowed_not_screened") in new stack
5264 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:17] Set("SIP/172.26.218.10-0000001c", "CALLERID(name-pres)=allowed_not_screened") in new stack
5265 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:18] Set("SIP/172.26.218.10-0000001c", "CALLERID(num-pres)=allowed_not_screened") in new stack
5266 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:19] NoOp("SIP/172.26.218.10-0000001c", "CallerID Entry Point") in new stack
5267 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:20] Set("SIP/172.26.218.10-0000001c", "__CRM_DIRECTION=INBOUND") in new stack
5268 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:21] Set("SIP/172.26.218.10-0000001c", "__CRM_SOURCE=962191") in new stack
5269 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:22] Set("SIP/172.26.218.10-0000001c", "__CRM_LINKEDID=1618916116.28") in new stack
5270 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:23] AGI("SIP/172.26.218.10-0000001c", "agi://127.0.0.1/sangomacrm.agi,true") in new stack
5271 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] res_agi.c: <SIP/172.26.218.10-0000001c>AGI Script agi://127.0.0.1/sangomacrm.agi completed, returning 0
5272 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:24] ExecIf("SIP/172.26.218.10-0000001c", "1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
5273 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:25] Goto("SIP/172.26.218.10-0000001c", "ext-trunk,1,1") in new stack
5274 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx_builtins.c: Goto (ext-trunk,1,1)
5275 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [1@ext-trunk:1] Set("SIP/172.26.218.10-0000001c", "TDIAL_STRING=SIP/10-SPB_test_node_2 trunkOUT") in new stack
5276 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [1@ext-trunk:2] Set("SIP/172.26.218.10-0000001c", "DIAL_TRUNK=1") in new stack
5277 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [1@ext-trunk:3] Goto("SIP/172.26.218.10-0000001c", "ext-trunk,tdial,1") in new stack
5278 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx_builtins.c: Goto (ext-trunk,tdial,1)
5279 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [tdial@ext-trunk:1] Set("SIP/172.26.218.10-0000001c", "OUTBOUND_GROUP=OUT_1") in new stack
5280 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [tdial@ext-trunk:2] GotoIf("SIP/172.26.218.10-0000001c", "1?nomax") in new stack
5281 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx_builtins.c: Goto (ext-trunk,tdial,4)
5282 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [tdial@ext-trunk:4] ExecIf("SIP/172.26.218.10-0000001c", "1?Set(CALLERPRES(name-pres)=allowed_not_screened)") in new stack
5283 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [tdial@ext-trunk:5] ExecIf("SIP/172.26.218.10-0000001c", "1?Set(CALLERPRES(num-pres)=allowed_not_screened)") in new stack
5284 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [tdial@ext-trunk:6] Set("SIP/172.26.218.10-0000001c", "DIAL_NUMBER=102999") in new stack
5285 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [tdial@ext-trunk:7] GosubIf("SIP/172.26.218.10-0000001c", "1?sub-flp-1,s,1()") in new stack
5286 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-flp-1:1] ExecIf("SIP/172.26.218.10-0000001c", "1?Set(TARGET_FLP_1=2999)") in new stack
5287 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-flp-1:2] GotoIf("SIP/172.26.218.10-0000001c", "1?match") in new stack
5288 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx_builtins.c: Goto (sub-flp-1,s,4)
5289 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-flp-1:4] Set("SIP/172.26.218.10-0000001c", "DIAL_NUMBER=2999") in new stack
5290 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-flp-1:5] Return("SIP/172.26.218.10-0000001c", "") in new stack
5291 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [tdial@ext-trunk:8] Set("SIP/172.26.218.10-0000001c", "OUTNUM=2999") in new stack
5292 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [tdial@ext-trunk:9] Set("SIP/172.26.218.10-0000001c", "DIAL_TRUNK_OPTIONS=T") in new stack
5293 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [tdial@ext-trunk:10] Dial("SIP/172.26.218.10-0000001c", "SIP/10-SPB_test_node_2 trunkOUT/2999,300,T") in new stack
5294 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] netsock2.c: Using SIP RTP TOS bits 184
5295 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] netsock2.c: Using SIP RTP CoS mark 5
5296 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] chan_sip.c: Audio is at 17736
5297 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] chan_sip.c: Adding codec g729 to SDP
5298 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] chan_sip.c: Adding non-codec 0x1 (telephone-event) to SDP
5299 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] chan_sip.c: Reliably Transmitting (no NAT) to 172.16.173.148:5060:
5300 INVITE sip:2999@172.16.173.148 SIP/2.0
5301 Via: SIP/2.0/UDP 172.26.223.29:5060;branch=z9hG4bK54af0aec
5302 Max-Forwards: 70
5303 From: "962191" <sip:962191@172.26.223.29>;tag=as0cc0ee63
5304 To: <sip:2999@172.16.173.148>
5305 Contact: <sip:962191@172.26.223.29:5060>
5306 Call-ID: 0c06a95f125a5a07390cf3741e2e38ba@172.26.223.29:5060
5307 CSeq: 102 INVITE
5308 User-Agent: FPBX-15.0.16.81(16.13.0)
5309 Date: Tue, 20 Apr 2021 10:55:16 GMT
5310 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
5311 Supported: replaces, timer
5312 Remote-Party-ID: "962191" <sip:962191@172.26.223.29>;party=calling;privacy=off;screen=no
5313 Content-Type: application/sdp
5314 Content-Length: 277
5315
5316 v=0
5317 o=root 1979690048 1979690048 IN IP4 172.26.223.29
5318 s=Asterisk PBX 16.13.0
5319 c=IN IP4 172.26.223.29
5320 t=0 0
5321 m=audio 17736 RTP/AVP 18 101
5322 a=rtpmap:18 G729/8000
5323 a=fmtp:18 annexb=no
5324 a=rtpmap:101 telephone-event/8000
5325 a=fmtp:101 0-16
5326 a=ptime:20
5327 a=maxptime:230
5328 a=sendrecv
5329
5330 ---
5331 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] app_dial.c: Called SIP/10-SPB_test_node_2 trunkOUT/2999
5332 [2021-04-20 10:55:16] VERBOSE[18872] chan_sip.c:
5333 <--- SIP read from UDP:172.16.173.148:5060 --->
5334 SIP/2.0 100 Trying
5335 Via: SIP/2.0/UDP 172.26.223.29:5060;branch=z9hG4bK54af0aec;received=172.26.223.29
5336 From: "962191" <sip:962191@172.26.223.29>;tag=as0cc0ee63
5337 To: <sip:2999@172.16.173.148>
5338 Call-ID: 0c06a95f125a5a07390cf3741e2e38ba@172.26.223.29:5060
5339 CSeq: 102 INVITE
5340 Server: FPBX-14.0.16(16.13.0)
5341 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
5342 Supported: replaces,timer
5343 Session-Expires: 1800;refresher=uas
5344 Contact: <sip:2999@172.16.173.148:5060>
5345 Content-Length: 0
5346
5347 <------------->
5348 [2021-04-20 10:55:16] VERBOSE[18872] chan_sip.c: --- (12 headers 0 lines) ---
5349 [2021-04-20 10:55:16] VERBOSE[18872] chan_sip.c:
5350 <--- SIP read from UDP:172.16.173.148:5060 --->
5351 SIP/2.0 180 Ringing
5352 Via: SIP/2.0/UDP 172.26.223.29:5060;branch=z9hG4bK54af0aec;received=172.26.223.29
5353 From: "962191" <sip:962191@172.26.223.29>;tag=as0cc0ee63
5354 To: <sip:2999@172.16.173.148>;tag=as34bce41e
5355 Call-ID: 0c06a95f125a5a07390cf3741e2e38ba@172.26.223.29:5060
5356 CSeq: 102 INVITE
5357 Server: FPBX-14.0.16(16.13.0)
5358 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
5359 Supported: replaces,timer
5360 Session-Expires: 1800;refresher=uas
5361 Contact: <sip:2999@172.16.173.148:5060>
5362 Content-Length: 0
5363
5364 <------------->
5365 [2021-04-20 10:55:16] VERBOSE[18872] chan_sip.c: --- (12 headers 0 lines) ---
5366 [2021-04-20 10:55:16] VERBOSE[18872][C-0000000f] sip/route.c: sip_route_dump: route/path hop: <sip:2999@172.16.173.148:5060>
5367 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] app_dial.c: SIP/10-SPB_test_node_2 trunkOUT-0000001d is ringing
5368 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] chan_sip.c:
5369 <--- Transmitting (no NAT) to 172.26.218.10:5060 --->
5370 SIP/2.0 180 Ringing
5371 Via: SIP/2.0/UDP 172.26.218.10:5060;branch=z9hG4bK-66182-18ece55e-44127464-488f8630;received=172.26.218.10;rport=5060
5372 From: "Shkutov Pavel"<sip:962191@172.26.223.29>;tag=48a72418-ada1aac-13c4-65014-66182-4d59761a-66182
5373 To: <sip:102999@172.26.223.29>;tag=as4be79c79
5374 Call-ID: 48b1d928-ada1aac-13c4-65014-66182-40a8d8bc-66182
5375 CSeq: 1 INVITE
5376 Server: FPBX-15.0.16.81(16.13.0)
5377 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
5378 Supported: replaces, timer
5379 Contact: <sip:102999@172.26.223.29:5060>
5380 Content-Length: 0
5381
5382
5383 <------------>
5384 [2021-04-20 10:55:16] VERBOSE[18872] chan_sip.c:
5385 <--- SIP read from UDP:172.16.173.148:5060 --->
5386 SIP/2.0 180 Ringing
5387 Via: SIP/2.0/UDP 172.26.223.29:5060;branch=z9hG4bK54af0aec;received=172.26.223.29
5388 From: "962191" <sip:962191@172.26.223.29>;tag=as0cc0ee63
5389 To: <sip:2999@172.16.173.148>;tag=as34bce41e
5390 Call-ID: 0c06a95f125a5a07390cf3741e2e38ba@172.26.223.29:5060
5391 CSeq: 102 INVITE
5392 Server: FPBX-14.0.16(16.13.0)
5393 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
5394 Supported: replaces,timer
5395 Session-Expires: 1800;refresher=uas
5396 Contact: <sip:2999@172.16.173.148:5060>
5397 Remote-Party-ID: "Тест" <sip:2999@172.26.223.29>;party=called;privacy=off;screen=no
5398 Content-Length: 0
5399
5400 <------------->
5401 [2021-04-20 10:55:16] VERBOSE[18872] chan_sip.c: --- (13 headers 0 lines) ---
5402 [2021-04-20 10:55:16] VERBOSE[18872][C-0000000f] sip/route.c: sip_route_dump: route/path hop: <sip:2999@172.16.173.148:5060>
5403 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] app_dial.c: SIP/10-SPB_test_node_2 trunkOUT-0000001d is ringing
5404 [2021-04-20 10:55:17] VERBOSE[18872] chan_sip.c: Reliably Transmitting (no NAT) to 172.16.173.148:5060:
5405 OPTIONS sip:172.16.173.148 SIP/2.0
5406 Via: SIP/2.0/UDP 172.26.223.29:5060;branch=z9hG4bK09e2afa9
5407 Max-Forwards: 70
5408 From: "Unknown" <sip:Unknown@172.26.223.29>;tag=as197395bd
5409 To: <sip:172.16.173.148>
5410 Contact: <sip:Unknown@172.26.223.29:5060>
5411 Call-ID: 129151281eb63a6d2ac3b7f7513df78b@172.26.223.29:5060
5412 CSeq: 102 OPTIONS
5413 User-Agent: FPBX-15.0.16.81(16.13.0)
5414 Date: Tue, 20 Apr 2021 10:55:17 GMT
5415 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
5416 Supported: replaces, timer
5417 Content-Length: 0
5418
5419
5420 ---
5421 [2021-04-20 10:55:17] VERBOSE[18872] chan_sip.c: Reliably Transmitting (no NAT) to 172.16.173.148:5060:
5422 OPTIONS sip:172.16.173.148 SIP/2.0
5423 Via: SIP/2.0/UDP 172.26.223.29:5060;branch=z9hG4bK068b6016
5424 Max-Forwards: 70
5425 From: "Unknown" <sip:Unknown@172.26.223.29>;tag=as15509688
5426 To: <sip:172.16.173.148>
5427 Contact: <sip:Unknown@172.26.223.29:5060>
5428 Call-ID: 136b5fd5794034494c322c7b7900cb66@172.26.223.29:5060
5429 CSeq: 102 OPTIONS
5430 User-Agent: FPBX-15.0.16.81(16.13.0)
5431 Date: Tue, 20 Apr 2021 10:55:17 GMT
5432 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
5433 Supported: replaces, timer
5434 Content-Length: 0
5435
5436
5437 ---
5438 [2021-04-20 10:55:17] VERBOSE[18872] chan_sip.c:
5439 <--- SIP read from UDP:172.16.173.148:5060 --->
5440 SIP/2.0 200 OK
5441 Via: SIP/2.0/UDP 172.26.223.29:5060;branch=z9hG4bK09e2afa9;received=172.26.223.29
5442 From: "Unknown" <sip:Unknown@172.26.223.29>;tag=as197395bd
5443 To: <sip:172.16.173.148>;tag=as1e93ddfe
5444 Call-ID: 129151281eb63a6d2ac3b7f7513df78b@172.26.223.29:5060
5445 CSeq: 102 OPTIONS
5446 Server: FPBX-14.0.16(16.13.0)
5447 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
5448 Supported: replaces,timer
5449 Contact: <sip:172.16.173.148:5060>
5450 Accept: application/sdp
5451 Content-Length: 0
5452
5453 <------------->
5454 [2021-04-20 10:55:17] VERBOSE[18872] chan_sip.c: --- (12 headers 0 lines) ---
5455 [2021-04-20 10:55:17] VERBOSE[18872] chan_sip.c: Really destroying SIP dialog '129151281eb63a6d2ac3b7f7513df78b@172.26.223.29:5060' Method: OPTIONS
5456 [2021-04-20 10:55:17] VERBOSE[18872] chan_sip.c:
5457 <--- SIP read from UDP:172.16.173.148:5060 --->
5458 SIP/2.0 200 OK
5459 Via: SIP/2.0/UDP 172.26.223.29:5060;branch=z9hG4bK068b6016;received=172.26.223.29
5460 From: "Unknown" <sip:Unknown@172.26.223.29>;tag=as15509688
5461 To: <sip:172.16.173.148>;tag=as5d24ee77
5462 Call-ID: 136b5fd5794034494c322c7b7900cb66@172.26.223.29:5060
5463 CSeq: 102 OPTIONS
5464 Server: FPBX-14.0.16(16.13.0)
5465 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
5466 Supported: replaces,timer
5467 Contact: <sip:172.16.173.148:5060>
5468 Accept: application/sdp
5469 Content-Length: 0
5470
5471 <------------->
5472 [2021-04-20 10:55:17] VERBOSE[18872] chan_sip.c: --- (12 headers 0 lines) ---
5473 [2021-04-20 10:55:17] VERBOSE[18872] chan_sip.c: Really destroying SIP dialog '136b5fd5794034494c322c7b7900cb66@172.26.223.29:5060' Method: OPTIONS
5474 [2021-04-20 10:55:29] VERBOSE[18872] chan_sip.c:
5475 <--- SIP read from UDP:172.26.218.10:5060 --->
5476 CANCEL sip:102999@172.26.223.29 SIP/2.0
5477 From: "Shkutov Pavel"<sip:962191@172.26.223.29>;tag=48a72418-ada1aac-13c4-65014-66182-4d59761a-66182
5478 To: <sip:102999@172.26.223.29>
5479 Call-ID: 48b1d928-ada1aac-13c4-65014-66182-40a8d8bc-66182
5480 CSeq: 1 CANCEL
5481 Via: SIP/2.0/UDP 172.26.218.10:5060;rport;branch=z9hG4bK-66182-18ece55e-44127464-488f8630
5482 Max-Forwards: 70
5483 Supported: 100rel,timer,replaces,UPDATE,INFO
5484 User-Agent: Ericsson-LG Enterprise iPECS-eMG eMG800 2.1.11
5485 Content-Length: 0
5486
5487 <------------->
5488 [2021-04-20 10:55:29] VERBOSE[18872] chan_sip.c: --- (10 headers 0 lines) ---
5489 [2021-04-20 10:55:29] VERBOSE[18872][C-0000000f] chan_sip.c: Sending to 172.26.218.10:5060 (no NAT)
5490 [2021-04-20 10:55:29] VERBOSE[18872][C-0000000f] chan_sip.c:
5491 <--- Reliably Transmitting (no NAT) to 172.26.218.10:5060 --->
5492 SIP/2.0 487 Request Terminated
5493 Via: SIP/2.0/UDP 172.26.218.10:5060;branch=z9hG4bK-66182-18ece55e-44127464-488f8630;received=172.26.218.10;rport=5060
5494 From: "Shkutov Pavel"<sip:962191@172.26.223.29>;tag=48a72418-ada1aac-13c4-65014-66182-4d59761a-66182
5495 To: <sip:102999@172.26.223.29>;tag=as4be79c79
5496 Call-ID: 48b1d928-ada1aac-13c4-65014-66182-40a8d8bc-66182
5497 CSeq: 1 INVITE
5498 Server: FPBX-15.0.16.81(16.13.0)
5499 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
5500 Supported: replaces, timer
5501 Content-Length: 0
5502
5503
5504 <------------>
5505 [2021-04-20 10:55:29] VERBOSE[18872][C-0000000f] chan_sip.c:
5506 <--- Transmitting (no NAT) to 172.26.218.10:5060 --->
5507 SIP/2.0 200 OK
5508 Via: SIP/2.0/UDP 172.26.218.10:5060;branch=z9hG4bK-66182-18ece55e-44127464-488f8630;received=172.26.218.10;rport=5060
5509 From: "Shkutov Pavel"<sip:962191@172.26.223.29>;tag=48a72418-ada1aac-13c4-65014-66182-4d59761a-66182
5510 To: <sip:102999@172.26.223.29>;tag=as4be79c79
5511 Call-ID: 48b1d928-ada1aac-13c4-65014-66182-40a8d8bc-66182
5512 CSeq: 1 CANCEL
5513 Server: FPBX-15.0.16.81(16.13.0)
5514 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
5515 Supported: replaces, timer
5516 Content-Length: 0
5517
5518
5519 <------------>
5520 [2021-04-20 10:55:29] VERBOSE[34827][C-0000000f] chan_sip.c: Scheduling destruction of SIP dialog '0c06a95f125a5a07390cf3741e2e38ba@172.26.223.29:5060' in 6400 ms (Method: INVITE)
5521 [2021-04-20 10:55:29] VERBOSE[34827][C-0000000f] chan_sip.c: Reliably Transmitting (no NAT) to 172.16.173.148:5060:
5522 CANCEL sip:2999@172.16.173.148 SIP/2.0
5523 Via: SIP/2.0/UDP 172.26.223.29:5060;branch=z9hG4bK54af0aec
5524 Max-Forwards: 70
5525 From: "962191" <sip:962191@172.26.223.29>;tag=as0cc0ee63
5526 To: <sip:2999@172.16.173.148>
5527 Call-ID: 0c06a95f125a5a07390cf3741e2e38ba@172.26.223.29:5060
5528 CSeq: 102 CANCEL
5529 User-Agent: FPBX-15.0.16.81(16.13.0)
5530 Content-Length: 0
5531
5532
5533 ---
5534 [2021-04-20 10:55:29] VERBOSE[34827][C-0000000f] chan_sip.c: Scheduling destruction of SIP dialog '0c06a95f125a5a07390cf3741e2e38ba@172.26.223.29:5060' in 6400 ms (Method: INVITE)
5535 [2021-04-20 10:55:29] VERBOSE[34827][C-0000000f] pbx.c: Spawn extension (ext-trunk, tdial, 10) exited non-zero on 'SIP/172.26.218.10-0000001c'
5536 [2021-04-20 10:55:29] VERBOSE[34827][C-0000000f] app_stack.c: SIP/172.26.218.10-0000001c Internal Gosub(crm-hangup,s,1) start
5537 [2021-04-20 10:55:29] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@crm-hangup:1] NoOp("SIP/172.26.218.10-0000001c", "Sending Hangup to CRM") in new stack
5538 [2021-04-20 10:55:29] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@crm-hangup:2] NoOp("SIP/172.26.218.10-0000001c", "HANGUP CAUSE: 0") in new stack
5539 [2021-04-20 10:55:29] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@crm-hangup:3] ExecIf("SIP/172.26.218.10-0000001c", "0?Set(__CRM_VOICEMAIL=)") in new stack
5540 [2021-04-20 10:55:29] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@crm-hangup:4] NoOp("SIP/172.26.218.10-0000001c", "MASTER CHANNEL: 1618916116.28 = 1618916116.28") in new stack
5541 [2021-04-20 10:55:29] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@crm-hangup:5] GotoIf("SIP/172.26.218.10-0000001c", "0?return") in new stack
5542 [2021-04-20 10:55:29] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@crm-hangup:6] Set("SIP/172.26.218.10-0000001c", "__CRM_HANGUP=1") in new stack
5543 [2021-04-20 10:55:29] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@crm-hangup:7] AGI("SIP/172.26.218.10-0000001c", "agi://127.0.0.1/sangomacrm.agi") in new stack
5544 [2021-04-20 10:55:29] VERBOSE[18872] chan_sip.c:
5545 <--- SIP read from UDP:172.26.218.10:5060 --->
5546 ACK sip:102999@172.26.223.29 SIP/2.0
5547 From: "Shkutov Pavel"<sip:962191@172.26.223.29>;tag=48a72418-ada1aac-13c4-65014-66182-4d59761a-66182
5548 To: <sip:102999@172.26.223.29>;tag=as4be79c79
5549 Call-ID: 48b1d928-ada1aac-13c4-65014-66182-40a8d8bc-66182
5550 CSeq: 1 ACK
5551 Via: SIP/2.0/UDP 172.26.218.10:5060;rport;branch=z9hG4bK-66182-18ece55e-44127464-488f8630
5552 Max-Forwards: 70
5553 User-Agent: Ericsson-LG Enterprise iPECS-eMG eMG800 2.1.11
5554 Contact: <sip:962191@172.26.218.10:5060;transport=UDP>
5555 Content-Length: 0
5556
5557 <------------->
5558 [2021-04-20 10:55:29] VERBOSE[18872] chan_sip.c: --- (10 headers 0 lines) ---
5559 [2021-04-20 10:55:29] VERBOSE[18872] chan_sip.c:
5560 <--- SIP read from UDP:172.16.173.148:5060 --->
5561 SIP/2.0 487 Request Terminated
5562 Via: SIP/2.0/UDP 172.26.223.29:5060;branch=z9hG4bK54af0aec;received=172.26.223.29
5563 From: "962191" <sip:962191@172.26.223.29>;tag=as0cc0ee63
5564 To: <sip:2999@172.16.173.148>;tag=as34bce41e
5565 Call-ID: 0c06a95f125a5a07390cf3741e2e38ba@172.26.223.29:5060
5566 CSeq: 102 INVITE
5567 Server: FPBX-14.0.16(16.13.0)
5568 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
5569 Supported: replaces,timer
5570 Content-Length: 0
5571
5572 <------------->
5573 [2021-04-20 10:55:29] VERBOSE[18872] chan_sip.c: --- (10 headers 0 lines) ---
5574 [2021-04-20 10:55:29] VERBOSE[18872][C-0000000f] chan_sip.c: Transmitting (no NAT) to 172.16.173.148:5060:
5575 ACK sip:2999@172.16.173.148:5060 SIP/2.0
5576 Via: SIP/2.0/UDP 172.26.223.29:5060;branch=z9hG4bK54af0aec
5577 Max-Forwards: 70
5578 From: "962191" <sip:962191@172.26.223.29>;tag=as0cc0ee63
5579 To: <sip:2999@172.16.173.148>;tag=as34bce41e
5580 Contact: <sip:962191@172.26.223.29:5060>
5581 Call-ID: 0c06a95f125a5a07390cf3741e2e38ba@172.26.223.29:5060
5582 CSeq: 102 ACK
5583 User-Agent: FPBX-15.0.16.81(16.13.0)
5584 Content-Length: 0
5585
5586
5587 ---
5588 [2021-04-20 10:55:29] VERBOSE[18872][C-0000000f] chan_sip.c: Scheduling destruction of SIP dialog '0c06a95f125a5a07390cf3741e2e38ba@172.26.223.29:5060' in 6400 ms (Method: INVITE)
5589 [2021-04-20 10:55:29] VERBOSE[18872] chan_sip.c:
5590 <--- SIP read from UDP:172.16.173.148:5060 --->
5591 SIP/2.0 200 OK
5592 Via: SIP/2.0/UDP 172.26.223.29:5060;branch=z9hG4bK54af0aec;received=172.26.223.29
5593 From: "962191" <sip:962191@172.26.223.29>;tag=as0cc0ee63
5594 To: <sip:2999@172.16.173.148>;tag=as34bce41e
5595 Call-ID: 0c06a95f125a5a07390cf3741e2e38ba@172.26.223.29:5060
5596 CSeq: 102 CANCEL
5597 Server: FPBX-14.0.16(16.13.0)
5598 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
5599 Supported: replaces,timer
5600 Content-Length: 0
5601
5602 <------------->
5603 [2021-04-20 10:55:29] VERBOSE[18872] chan_sip.c: --- (10 headers 0 lines) ---
5604 [2021-04-20 10:55:29] VERBOSE[34827][C-0000000f] res_agi.c: <SIP/172.26.218.10-0000001c>AGI Script agi://127.0.0.1/sangomacrm.agi completed, returning 0
5605 [2021-04-20 10:55:29] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@crm-hangup:8] Return("SIP/172.26.218.10-0000001c", "") in new stack
5606 [2021-04-20 10:55:29] VERBOSE[34827][C-0000000f] app_stack.c: Spawn extension (ext-trunk, tdial, 10) exited non-zero on 'SIP/172.26.218.10-0000001c'
5607 [2021-04-20 10:55:29] VERBOSE[34827][C-0000000f] app_stack.c: SIP/172.26.218.10-0000001c Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
5608 [2021-04-20 10:55:30] VERBOSE[18872] chan_sip.c: Really destroying SIP dialog '48b1d928-ada1aac-13c4-65014-66182-40a8d8bc-66182' Method: ACK
5609 [2021-04-20 10:55:35] VERBOSE[18872] chan_sip.c: Really destroying SIP dialog '0c06a95f125a5a07390cf3741e2e38ba@172.26.223.29:5060' Method: INVITE
5151
5152 <------------->
5153 [2021-04-20 10:55:11] VERBOSE[18872] chan_sip.c: --- (11 headers 0 lines) ---
5154 [2021-04-20 10:55:11] VERBOSE[18872] chan_sip.c: Really destroying SIP dialog '1697ecc52815df53215643067cbe5359@172.26.223.29:5060' Method: OPTIONS
5155 [2021-04-20 10:55:16] VERBOSE[18872] chan_sip.c:
5156 <--- SIP read from UDP:172.26.218.10:5060 --->
5157 INVITE sip:102999@172.26.223.29 SIP/2.0
5158 From: "Shkutov Pavel"<sip:962191@172.26.223.29>;tag=48a72418-ada1aac-13c4-65014-66182-4d59761a-66182
5159 To: <sip:102999@172.26.223.29>
5160 Call-ID: 48b1d928-ada1aac-13c4-65014-66182-40a8d8bc-66182
5161 CSeq: 1 INVITE
5162 Via: SIP/2.0/UDP 172.26.218.10:5060;rport;branch=z9hG4bK-66182-18ece55e-44127464-488f8630
5163 Max-Forwards: 70
5164 Allow: INVITE,ACK,OPTIONS,BYE,CANCEL,REGISTER,REFER,SUBSCRIBE,NOTIFY,MESSAGE,INFO,PRACK,UPDATE
5165 Supported: replaces,UPDATE,INFO
5166 P-Asserted-Identity: "Shkutov Pavel"<sip:962191@172.26.223.29>
5167 Remote-Party-ID: <sip:962191@172.26.218.10>; party=calling; privacy=off; screen=yes
5168 Privacy: none
5169 User-Agent: Ericsson-LG Enterprise iPECS-eMG eMG800 2.1.11
5170 Contact: "Shkutov Pavel"<sip:962191@172.26.218.10:5060;transport=UDP>
5171 Content-Type: application/sdp
5172 Content-Length: 231
5173
5174 v=0
5175 o=iPECS-eMG 22 22 IN IP4 172.26.218.10
5176 s=iPECS-eMG SIP
5177 c=IN IP4 172.26.218.10
5178 t=0 0
5179 m=audio 20538 RTP/AVP 18 101
5180 a=rtpmap:18 G729/8000
5181 a=fmtp:18 annexb=no
5182 a=rtpmap:101 telephone-event/8000
5183 a=fmtp:101 0-15
5184 a=sendrecv
5185 <------------->
5186 [2021-04-20 10:55:16] VERBOSE[18872] chan_sip.c: --- (16 headers 11 lines) ---
5187 [2021-04-20 10:55:16] VERBOSE[18872] chan_sip.c: Sending to 172.26.218.10:5060 (no NAT)
5188 [2021-04-20 10:55:16] VERBOSE[18872][C-0000000f] chan_sip.c: Sending to 172.26.218.10:5060 (no NAT)
5189 [2021-04-20 10:55:16] VERBOSE[18872][C-0000000f] chan_sip.c: Using INVITE request as basis request - 48b1d928-ada1aac-13c4-65014-66182-40a8d8bc-66182
5190 [2021-04-20 10:55:16] VERBOSE[18872][C-0000000f] chan_sip.c: Found peer '172.26.218.10' for '962191' from 172.26.218.10:5060
5191 [2021-04-20 10:55:16] VERBOSE[18872][C-0000000f] netsock2.c: Using SIP RTP TOS bits 184
5192 [2021-04-20 10:55:16] VERBOSE[18872][C-0000000f] netsock2.c: Using SIP RTP CoS mark 5
5193 [2021-04-20 10:55:16] VERBOSE[18872][C-0000000f] chan_sip.c: Got SDP version 22 and unique parts [iPECS-eMG 22 IN IP4 172.26.218.10]
5194 [2021-04-20 10:55:16] VERBOSE[18872][C-0000000f] chan_sip.c: Found RTP audio format 18
5195 [2021-04-20 10:55:16] VERBOSE[18872][C-0000000f] chan_sip.c: Found RTP audio format 101
5196 [2021-04-20 10:55:16] VERBOSE[18872][C-0000000f] chan_sip.c: Found audio description format G729 for ID 18
5197 [2021-04-20 10:55:16] VERBOSE[18872][C-0000000f] chan_sip.c: Found audio description format telephone-event for ID 101
5198 [2021-04-20 10:55:16] VERBOSE[18872][C-0000000f] chan_sip.c: Capabilities: us - (g729), peer - audio=(g729)/video=(nothing)/text=(nothing), combined - (g729)
5199 [2021-04-20 10:55:16] VERBOSE[18872][C-0000000f] chan_sip.c: Non-codec capabilities (dtmf): us - 0x1 (telephone-event|), peer - 0x1 (telephone-event|), combined - 0x1 (telephone-event|)
5200 [2021-04-20 10:55:16] VERBOSE[18872][C-0000000f] chan_sip.c: Peer audio RTP is at port 172.26.218.10:20538
5201 [2021-04-20 10:55:16] VERBOSE[18872][C-0000000f] chan_sip.c: Looking for 102999 in from-trunk (domain 172.26.223.29)
5202 [2021-04-20 10:55:16] VERBOSE[18872][C-0000000f] sip/route.c: sip_route_dump: route/path hop: <sip:962191@172.26.218.10:5060;transport=UDP>
5203 [2021-04-20 10:55:16] VERBOSE[18872][C-0000000f] chan_sip.c:
5204 <--- Transmitting (no NAT) to 172.26.218.10:5060 --->
5205 SIP/2.0 100 Trying
5206 Via: SIP/2.0/UDP 172.26.218.10:5060;branch=z9hG4bK-66182-18ece55e-44127464-488f8630;received=172.26.218.10;rport=5060
5207 From: "Shkutov Pavel"<sip:962191@172.26.223.29>;tag=48a72418-ada1aac-13c4-65014-66182-4d59761a-66182
5208 To: <sip:102999@172.26.223.29>
5209 Call-ID: 48b1d928-ada1aac-13c4-65014-66182-40a8d8bc-66182
5210 CSeq: 1 INVITE
5211 Server: FPBX-15.0.16.81(16.13.0)
5212 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
5213 Supported: replaces, timer
5214 Contact: <sip:102999@172.26.223.29:5060>
5215 Content-Length: 0
5216
5217
5218 <------------>
5219 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:1] Set("SIP/172.26.218.10-0000001c", "__DIRECTION=INBOUND") in new stack
5220 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:2] Gosub("SIP/172.26.218.10-0000001c", "sub-record-check,s,1(in,102999,dontcare)") in new stack
5221 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-record-check:1] GotoIf("SIP/172.26.218.10-0000001c", "0?initialized") in new stack
5222 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-record-check:2] Set("SIP/172.26.218.10-0000001c", "__REC_STATUS=INITIALIZED") in new stack
5223 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-record-check:3] Set("SIP/172.26.218.10-0000001c", "NOW=1618916116") in new stack
5224 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-record-check:4] Set("SIP/172.26.218.10-0000001c", "__DAY=20") in new stack
5225 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-record-check:5] Set("SIP/172.26.218.10-0000001c", "__MONTH=04") in new stack
5226 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-record-check:6] Set("SIP/172.26.218.10-0000001c", "__YEAR=2021") in new stack
5227 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-record-check:7] Set("SIP/172.26.218.10-0000001c", "__TIMESTR=20210420-105516") in new stack
5228 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-record-check:8] Set("SIP/172.26.218.10-0000001c", "__FROMEXTEN=unknown") in new stack
5229 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-record-check:9] Set("SIP/172.26.218.10-0000001c", "__MON_FMT=wav") in new stack
5230 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-record-check:10] NoOp("SIP/172.26.218.10-0000001c", "Recordings initialized") in new stack
5231 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-record-check:11] ExecIf("SIP/172.26.218.10-0000001c", "0?Set(ARG3=dontcare)") in new stack
5232 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-record-check:12] Set("SIP/172.26.218.10-0000001c", "REC_POLICY_MODE_SAVE=") in new stack
5233 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-record-check:13] ExecIf("SIP/172.26.218.10-0000001c", "0?Set(REC_STATUS=NO)") in new stack
5234 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-record-check:14] GotoIf("SIP/172.26.218.10-0000001c", "2?checkaction") in new stack
5235 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx_builtins.c: Goto (sub-record-check,s,17)
5236 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-record-check:17] GotoIf("SIP/172.26.218.10-0000001c", "1?sub-record-check,in,1") in new stack
5237 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx_builtins.c: Goto (sub-record-check,in,1)
5238 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [in@sub-record-check:1] NoOp("SIP/172.26.218.10-0000001c", "Inbound Recording Check to 102999") in new stack
5239 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [in@sub-record-check:2] Set("SIP/172.26.218.10-0000001c", "FROMEXTEN=unknown") in new stack
5240 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [in@sub-record-check:3] ExecIf("SIP/172.26.218.10-0000001c", "6?Set(FROMEXTEN=962191)") in new stack
5241 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [in@sub-record-check:4] Gosub("SIP/172.26.218.10-0000001c", "recordcheck,1(dontcare,in,102999)") in new stack
5242 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("SIP/172.26.218.10-0000001c", "Starting recording check against dontcare") in new stack
5243 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("SIP/172.26.218.10-0000001c", "dontcare") in new stack
5244 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
5245 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [recordcheck@sub-record-check:3] Return("SIP/172.26.218.10-0000001c", "") in new stack
5246 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [in@sub-record-check:5] Return("SIP/172.26.218.10-0000001c", "") in new stack
5247 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:3] Set("SIP/172.26.218.10-0000001c", "CHANNEL(tonezone)=us") in new stack
5248 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:4] Set("SIP/172.26.218.10-0000001c", "__FROM_DID=102999") in new stack
5249 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:5] Set("SIP/172.26.218.10-0000001c", "returnhere=1") in new stack
5250 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:6] Gosub("SIP/172.26.218.10-0000001c", "app-blacklist-check,s,1()") in new stack
5251 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@app-blacklist-check:1] GotoIf("SIP/172.26.218.10-0000001c", "0?blacklisted") in new stack
5252 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@app-blacklist-check:2] Set("SIP/172.26.218.10-0000001c", "CALLED_BLACKLIST=1") in new stack
5253 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@app-blacklist-check:3] Return("SIP/172.26.218.10-0000001c", "") in new stack
5254 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:7] Set("SIP/172.26.218.10-0000001c", "CDR(did)=102999") in new stack
5255 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:8] GotoIf("SIP/172.26.218.10-0000001c", "0?") in new stack
5256 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:9] ExecIf("SIP/172.26.218.10-0000001c", "1 ?Set(CALLERID(name)=962191)") in new stack
5257 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:10] Set("SIP/172.26.218.10-0000001c", "__MOHCLASS=") in new stack
5258 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:11] Set("SIP/172.26.218.10-0000001c", "__REVERSAL_REJECT=FALSE") in new stack
5259 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:12] GotoIf("SIP/172.26.218.10-0000001c", "1?post-reverse-charge") in new stack
5260 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx_builtins.c: Goto (from-trunk,102999,14)
5261 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:14] NoOp("SIP/172.26.218.10-0000001c", "") in new stack
5262 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:15] Set("SIP/172.26.218.10-0000001c", "__CALLINGNAMEPRES_SV=allowed_not_screened") in new stack
5263 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:16] Set("SIP/172.26.218.10-0000001c", "__CALLINGNUMPRES_SV=allowed_not_screened") in new stack
5264 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:17] Set("SIP/172.26.218.10-0000001c", "CALLERID(name-pres)=allowed_not_screened") in new stack
5265 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:18] Set("SIP/172.26.218.10-0000001c", "CALLERID(num-pres)=allowed_not_screened") in new stack
5266 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:19] NoOp("SIP/172.26.218.10-0000001c", "CallerID Entry Point") in new stack
5267 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:20] Set("SIP/172.26.218.10-0000001c", "__CRM_DIRECTION=INBOUND") in new stack
5268 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:21] Set("SIP/172.26.218.10-0000001c", "__CRM_SOURCE=962191") in new stack
5269 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:22] Set("SIP/172.26.218.10-0000001c", "__CRM_LINKEDID=1618916116.28") in new stack
5270 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:23] AGI("SIP/172.26.218.10-0000001c", "agi://127.0.0.1/sangomacrm.agi,true") in new stack
5271 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] res_agi.c: <SIP/172.26.218.10-0000001c>AGI Script agi://127.0.0.1/sangomacrm.agi completed, returning 0
5272 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:24] ExecIf("SIP/172.26.218.10-0000001c", "1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
5273 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [102999@from-trunk:25] Goto("SIP/172.26.218.10-0000001c", "ext-trunk,1,1") in new stack
5274 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx_builtins.c: Goto (ext-trunk,1,1)
5275 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [1@ext-trunk:1] Set("SIP/172.26.218.10-0000001c", "TDIAL_STRING=SIP/10-SPB_test_node_2 trunkOUT") in new stack
5276 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [1@ext-trunk:2] Set("SIP/172.26.218.10-0000001c", "DIAL_TRUNK=1") in new stack
5277 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [1@ext-trunk:3] Goto("SIP/172.26.218.10-0000001c", "ext-trunk,tdial,1") in new stack
5278 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx_builtins.c: Goto (ext-trunk,tdial,1)
5279 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [tdial@ext-trunk:1] Set("SIP/172.26.218.10-0000001c", "OUTBOUND_GROUP=OUT_1") in new stack
5280 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [tdial@ext-trunk:2] GotoIf("SIP/172.26.218.10-0000001c", "1?nomax") in new stack
5281 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx_builtins.c: Goto (ext-trunk,tdial,4)
5282 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [tdial@ext-trunk:4] ExecIf("SIP/172.26.218.10-0000001c", "1?Set(CALLERPRES(name-pres)=allowed_not_screened)") in new stack
5283 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [tdial@ext-trunk:5] ExecIf("SIP/172.26.218.10-0000001c", "1?Set(CALLERPRES(num-pres)=allowed_not_screened)") in new stack
5284 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [tdial@ext-trunk:6] Set("SIP/172.26.218.10-0000001c", "DIAL_NUMBER=102999") in new stack
5285 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [tdial@ext-trunk:7] GosubIf("SIP/172.26.218.10-0000001c", "1?sub-flp-1,s,1()") in new stack
5286 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-flp-1:1] ExecIf("SIP/172.26.218.10-0000001c", "1?Set(TARGET_FLP_1=2999)") in new stack
5287 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-flp-1:2] GotoIf("SIP/172.26.218.10-0000001c", "1?match") in new stack
5288 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx_builtins.c: Goto (sub-flp-1,s,4)
5289 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-flp-1:4] Set("SIP/172.26.218.10-0000001c", "DIAL_NUMBER=2999") in new stack
5290 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [s@sub-flp-1:5] Return("SIP/172.26.218.10-0000001c", "") in new stack
5291 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [tdial@ext-trunk:8] Set("SIP/172.26.218.10-0000001c", "OUTNUM=2999") in new stack
5292 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [tdial@ext-trunk:9] Set("SIP/172.26.218.10-0000001c", "DIAL_TRUNK_OPTIONS=T") in new stack
5293 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] pbx.c: Executing [tdial@ext-trunk:10] Dial("SIP/172.26.218.10-0000001c", "SIP/10-SPB_test_node_2 trunkOUT/2999,300,T") in new stack
5294 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] netsock2.c: Using SIP RTP TOS bits 184
5295 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] netsock2.c: Using SIP RTP CoS mark 5
5296 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] chan_sip.c: Audio is at 17736
5297 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] chan_sip.c: Adding codec g729 to SDP
5298 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] chan_sip.c: Adding non-codec 0x1 (telephone-event) to SDP
5299 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] chan_sip.c: Reliably Transmitting (no NAT) to 172.16.173.148:5060:
5300 INVITE sip:2999@172.16.173.148 SIP/2.0
5301 Via: SIP/2.0/UDP 172.26.223.29:5060;branch=z9hG4bK54af0aec
5302 Max-Forwards: 70
5303 From: "962191" <sip:962191@172.26.223.29>;tag=as0cc0ee63
5304 To: <sip:2999@172.16.173.148>
5305 Contact: <sip:962191@172.26.223.29:5060>
5306 Call-ID: 0c06a95f125a5a07390cf3741e2e38ba@172.26.223.29:5060
5307 CSeq: 102 INVITE
5308 User-Agent: FPBX-15.0.16.81(16.13.0)
5309 Date: Tue, 20 Apr 2021 10:55:16 GMT
5310 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
5311 Supported: replaces, timer
5312 Remote-Party-ID: "962191" <sip:962191@172.26.223.29>;party=calling;privacy=off;screen=no
5313 Content-Type: application/sdp
5314 Content-Length: 277
5315
5316 v=0
5317 o=root 1979690048 1979690048 IN IP4 172.26.223.29
5318 s=Asterisk PBX 16.13.0
5319 c=IN IP4 172.26.223.29
5320 t=0 0
5321 m=audio 17736 RTP/AVP 18 101
5322 a=rtpmap:18 G729/8000
5323 a=fmtp:18 annexb=no
5324 a=rtpmap:101 telephone-event/8000
5325 a=fmtp:101 0-16
5326 a=ptime:20
5327 a=maxptime:230
5328 a=sendrecv
5329
5330 ---
5331 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] app_dial.c: Called SIP/10-SPB_test_node_2 trunkOUT/2999
5332 [2021-04-20 10:55:16] VERBOSE[18872] chan_sip.c:
5333 <--- SIP read from UDP:172.16.173.148:5060 --->
5334 SIP/2.0 100 Trying
5335 Via: SIP/2.0/UDP 172.26.223.29:5060;branch=z9hG4bK54af0aec;received=172.26.223.29
5336 From: "962191" <sip:962191@172.26.223.29>;tag=as0cc0ee63
5337 To: <sip:2999@172.16.173.148>
5338 Call-ID: 0c06a95f125a5a07390cf3741e2e38ba@172.26.223.29:5060
5339 CSeq: 102 INVITE
5340 Server: FPBX-14.0.16(16.13.0)
5341 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
5342 Supported: replaces,timer
5343 Session-Expires: 1800;refresher=uas
5344 Contact: <sip:2999@172.16.173.148:5060>
5345 Content-Length: 0
5346
5347 <------------->
5348 [2021-04-20 10:55:16] VERBOSE[18872] chan_sip.c: --- (12 headers 0 lines) ---
5349 [2021-04-20 10:55:16] VERBOSE[18872] chan_sip.c:
5350 <--- SIP read from UDP:172.16.173.148:5060 --->
5351 SIP/2.0 180 Ringing
5352 Via: SIP/2.0/UDP 172.26.223.29:5060;branch=z9hG4bK54af0aec;received=172.26.223.29
5353 From: "962191" <sip:962191@172.26.223.29>;tag=as0cc0ee63
5354 To: <sip:2999@172.16.173.148>;tag=as34bce41e
5355 Call-ID: 0c06a95f125a5a07390cf3741e2e38ba@172.26.223.29:5060
5356 CSeq: 102 INVITE
5357 Server: FPBX-14.0.16(16.13.0)
5358 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
5359 Supported: replaces,timer
5360 Session-Expires: 1800;refresher=uas
5361 Contact: <sip:2999@172.16.173.148:5060>
5362 Content-Length: 0
5363
5364 <------------->
5365 [2021-04-20 10:55:16] VERBOSE[18872] chan_sip.c: --- (12 headers 0 lines) ---
5366 [2021-04-20 10:55:16] VERBOSE[18872][C-0000000f] sip/route.c: sip_route_dump: route/path hop: <sip:2999@172.16.173.148:5060>
5367 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] app_dial.c: SIP/10-SPB_test_node_2 trunkOUT-0000001d is ringing
5368 [2021-04-20 10:55:16] VERBOSE[34827][C-0000000f] chan_sip.c:
5369 <--- Transmitting (no NAT) to 172.26.218.10:5060 --->
5370 SIP/2.0 180 Ringing
5371 Via: SIP/2.0/UDP 172.26.218.10:5060;branch=z9hG4bK-66182-18ece55e-44127464-488f8630;received=172.26.218.10;rport=5060
5372 From: "Shkutov Pavel"<sip:962191@172.26.223.29>;tag=48a72418-ada1aac-13c4-65014-66182-4d59761a-66182
5373 To: <sip:102999@172.26.223.29>;tag=as4be79c79
5374 Call-ID: 48b1d928-ada1aac-13c4-65014-66182-40a8d8bc-66182
5375 CSeq: 1 INVITE
5376 Server: FPBX-15.0.16.81(16.13.0)
5377 Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
5378 Supported: replaces, timer
5379 Contact: <sip:102999@172.26.223.29:5060>
5380 Content-Length: 0