Hi everyone,
I’m working on migrating a small office from FreePBX 13 to FreePBX 15 (Asterisk 16). The office has 4 MiTel IP phones with two POTS lines from the PSTN provider (each assigned a DID). The current system runs on a Dell OptiPlex 755 tower with a single TDM400P+/TDM410 PCI card, with 4 x X100M FXO modules for PSTN connectivity. (This is an older tower, hence the PCI support; the replacement hardware/tower is a Lenovo M83 desktop.) Calls inbound can be handled by either POTS line (depending on the called number) but calls outbound use only a single of these POTS lines.
I’ve generated a full backup on the version 13 system and restored it on version 15 system. Because of the newer Lenovo hardware I’m moving to, I had to use a PCIe-to-PCI bridge to permit the transfer of the TDM400P+ POTS card. This appears to work fine, but without successful calls in/out, it’s hard to say for sure (more on that shortly). With the new system up, backup restored, and the same IP address used, the existing IP phones can register and place/receive calls between them on the LAN. Unfortunately, calls in/out with the PSTN are not working.
Calls placed outbound result in one of two behaviors: (1) I immediately hear a message stating “all circuits are busy now,” or (2) as soon as the call rings the called device (mobile phone, for instance), the call disconnects when attempting to answer. This is true regardless of number type (landline/mobile/toll-free, etc.).
Calls placed inbound to either of the DIDs ring the desk phones (as configured), but when the call is answered, I only hear one-way audio: I can hear the external/PSTN caller, but they cannot hear me. When the desk phone (called party) hangs up, the caller stays connected to the call until manually hanging up as well.
Both issues seem codec-related to me (e.g., failure to negotiate codec when RTP stream attempts to nail up/one-way audio), but I can’t determine how that could be. We’re in the United States, so I have configured ulaw
under the module settings for the TDM card (under Applications > DAHDI Config).
Rolling the environment back to the old system (FreePBX 13, Dell hardware, physically moving the TDM card once again) results in everything working flawlessly, so there would seem to be a configuration issue somewhere that I’m missing.
I’m very new to Asterisk/FreePBX, so please excuse any obvious oversights or missing information. (My vocational background is in Cisco VoIP.)
I’m attaching outputs from the Asterisk CLI, captured while I was troubleshooting today. Thank you very much in advance for your help on this.
–
Call from extension 102 (using 717XXX2700 as outbound caller ID) to 717XXX8007 (mobile); call disconnects as soon as it’s answered (note the SIP retransmit messages toward the end):
[root@pbx ~]# asterisk -rx "sip show peers"
Name/username Host Dyn Forcerport Comedia ACL Port Status Description
101/101 192.168.1.154 D No No A 5060 OK (31 ms)
102/102 192.168.1.247 D No No A 5060 OK (9 ms)
103/103 192.168.1.115 D No No A 5060 OK (30 ms)
104/104 192.168.1.116 D No No A 5060 OK (31 ms)
199/199 192.168.1.6 D No No A 5060 OK (14 ms)
5 sip peers [Monitored: 5 online, 0 offline Unmonitored: 0 online, 0 offline]
pbx*CLI>
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
> 0x7f43c005a460 -- Strict RTP learning after remote address set to: 192.168.1.247:3000
== Extension Changed 102[ext-local] new state InUse for Notify User 102
== Extension Changed 102[ext-local] new state InUse for Notify User 103
== Extension Changed 102[ext-local] new state InUse for Notify User 104
-- Executing [717XXX8007@from-internal:1] Macro("SIP/102-00000000", "user-callerid,LIMIT,EXTERNAL,") in new stack
[2020-12-31 14:42:09] WARNING[4330][C-00000001]: app_macro.c:274 _macro_exec: Macro() is deprecated and will be removed from a future version of Asterisk.
[2020-12-31 14:42:09] WARNING[4330][C-00000001]: app_macro.c:275 _macro_exec: Dialplan should be updated to use Gosub instead.
== Extension Changed 102[ext-local] new state InUse for Notify User 101
-- Executing [s@macro-user-callerid:1] Set("SIP/102-00000000", "TOUCH_MONITOR=1609443729.0") in new stack
-- Executing [s@macro-user-callerid:2] Set("SIP/102-00000000", "AMPUSER=102") in new stack
-- Executing [s@macro-user-callerid:3] Set("SIP/102-00000000", "HOTDESCKCHAN=102-00000000") in new stack
-- Executing [s@macro-user-callerid:4] Set("SIP/102-00000000", "HOTDESKEXTEN=102") in new stack
-- Executing [s@macro-user-callerid:5] Set("SIP/102-00000000", "HOTDESKCALL=0") in new stack
-- Executing [s@macro-user-callerid:6] ExecIf("SIP/102-00000000", "0?Set(HOTDESKCALL=1)") in new stack
-- Executing [s@macro-user-callerid:7] ExecIf("SIP/102-00000000", "0?Set(CALLERID(name)=)") in new stack
-- Executing [s@macro-user-callerid:8] GotoIf("SIP/102-00000000", "0?report") in new stack
-- Executing [s@macro-user-callerid:9] ExecIf("SIP/102-00000000", "1?Set(REALCALLERIDNUM=102)") in new stack
-- Executing [s@macro-user-callerid:10] Set("SIP/102-00000000", "AMPUSER=102") in new stack
-- Executing [s@macro-user-callerid:11] GotoIf("SIP/102-00000000", "0?limit") in new stack
-- Executing [s@macro-user-callerid:12] Set("SIP/102-00000000", "AMPUSERCIDNAME=Emily") in new stack
-- Executing [s@macro-user-callerid:13] ExecIf("SIP/102-00000000", "0?Set(__CIDMASQUERADING=TRUE)") in new stack
-- Executing [s@macro-user-callerid:14] GotoIf("SIP/102-00000000", "0?report") in new stack
-- Executing [s@macro-user-callerid:15] Set("SIP/102-00000000", "AMPUSERCID=102") in new stack
-- Executing [s@macro-user-callerid:16] Set("SIP/102-00000000", "__DIAL_OPTIONS=Ttr") in new stack
-- Executing [s@macro-user-callerid:17] Set("SIP/102-00000000", "CALLERID(all)="Emily" <102>") in new stack
-- Executing [s@macro-user-callerid:18] ExecIf("SIP/102-00000000", "0?Set(CUSDIAL=)") in new stack
-- Executing [s@macro-user-callerid:19] ExecIf("SIP/102-00000000", "0?Set(CALLERID(all)="Emily" <102>)") in new stack
-- Executing [s@macro-user-callerid:20] GotoIf("SIP/102-00000000", "0?limit") in new stack
-- Executing [s@macro-user-callerid:21] ExecIf("SIP/102-00000000", "1?Set(GROUP(concurrency_limit)=102)") in new stack
-- Executing [s@macro-user-callerid:22] ExecIf("SIP/102-00000000", "0?Set(CHANNEL(language)=)") in new stack
-- Executing [s@macro-user-callerid:23] NoOp("SIP/102-00000000", "Macro Depth is 1") in new stack
-- Executing [s@macro-user-callerid:24] GotoIf("SIP/102-00000000", "1?report2:macroerror") in new stack
-- Goto (macro-user-callerid,s,25)
-- Executing [s@macro-user-callerid:25] GotoIf("SIP/102-00000000", "1?continue") in new stack
-- Goto (macro-user-callerid,s,44)
-- Executing [s@macro-user-callerid:44] Set("SIP/102-00000000", "CALLERID(number)=102") in new stack
-- Executing [s@macro-user-callerid:45] Set("SIP/102-00000000", "CALLERID(name)=Emily") in new stack
-- Executing [s@macro-user-callerid:46] GotoIf("SIP/102-00000000", "0?cnum") in new stack
-- Executing [s@macro-user-callerid:47] Set("SIP/102-00000000", "CDR(cnam)=Emily") in new stack
-- Executing [s@macro-user-callerid:48] Set("SIP/102-00000000", "CDR(cnum)=102") in new stack
-- Executing [s@macro-user-callerid:49] Set("SIP/102-00000000", "CHANNEL(language)=en") in new stack
-- Executing [717XXX8007@from-internal:2] Gosub("SIP/102-00000000", "sub-record-check,s,1(out,717XXX8007,dontcare)") in new stack
-- Executing [s@sub-record-check:1] GotoIf("SIP/102-00000000", "0?initialized") in new stack
-- Executing [s@sub-record-check:2] Set("SIP/102-00000000", "__REC_STATUS=INITIALIZED") in new stack
-- Executing [s@sub-record-check:3] Set("SIP/102-00000000", "NOW=1609443729") in new stack
-- Executing [s@sub-record-check:4] Set("SIP/102-00000000", "__DAY=31") in new stack
-- Executing [s@sub-record-check:5] Set("SIP/102-00000000", "__MONTH=12") in new stack
-- Executing [s@sub-record-check:6] Set("SIP/102-00000000", "__YEAR=2020") in new stack
-- Executing [s@sub-record-check:7] Set("SIP/102-00000000", "__TIMESTR=20201231-144209") in new stack
-- Executing [s@sub-record-check:8] Set("SIP/102-00000000", "__FROMEXTEN=102") in new stack
-- Executing [s@sub-record-check:9] Set("SIP/102-00000000", "__MON_FMT=wav") in new stack
-- Executing [s@sub-record-check:10] NoOp("SIP/102-00000000", "Recordings initialized") in new stack
-- Executing [s@sub-record-check:11] ExecIf("SIP/102-00000000", "0?Set(ARG3=dontcare)") in new stack
-- Executing [s@sub-record-check:12] Set("SIP/102-00000000", "REC_POLICY_MODE_SAVE=") in new stack
-- Executing [s@sub-record-check:13] ExecIf("SIP/102-00000000", "0?Set(REC_STATUS=NO)") in new stack
-- Executing [s@sub-record-check:14] GotoIf("SIP/102-00000000", "3?checkaction") in new stack
-- Goto (sub-record-check,s,17)
-- Executing [s@sub-record-check:17] GotoIf("SIP/102-00000000", "1?sub-record-check,out,1") in new stack
-- Goto (sub-record-check,out,1)
-- Executing [out@sub-record-check:1] NoOp("SIP/102-00000000", "Outbound Recording Check from 102 to 717XXX8007") in new stack
-- Executing [out@sub-record-check:2] Set("SIP/102-00000000", "RECMODE=dontcare") in new stack
-- Executing [out@sub-record-check:3] ExecIf("SIP/102-00000000", "1?Goto(routewins)") in new stack
-- Goto (sub-record-check,out,7)
-- Executing [out@sub-record-check:7] Gosub("SIP/102-00000000", "recordcheck,1(dontcare,out,717XXX8007)") in new stack
-- Executing [recordcheck@sub-record-check:1] NoOp("SIP/102-00000000", "Starting recording check against dontcare") in new stack
-- Executing [recordcheck@sub-record-check:2] Goto("SIP/102-00000000", "dontcare") in new stack
-- Goto (sub-record-check,recordcheck,3)
-- Executing [recordcheck@sub-record-check:3] Return("SIP/102-00000000", "") in new stack
-- Executing [out@sub-record-check:8] Return("SIP/102-00000000", "") in new stack
-- Executing [717XXX8007@from-internal:3] ExecIf("SIP/102-00000000", "0 ?Set(CDR(accountcode)=)") in new stack
-- Executing [717XXX8007@from-internal:4] Set("SIP/102-00000000", "_ROUTEID=1") in new stack
-- Executing [717XXX8007@from-internal:5] Set("SIP/102-00000000", "_ROUTENAME=Outgoing") in new stack
-- Executing [717XXX8007@from-internal:6] Set("SIP/102-00000000", "MOHCLASS=default") in new stack
-- Executing [717XXX8007@from-internal:7] Set("SIP/102-00000000", "_CALLERIDNAMEINTERNAL=Emily") in new stack
-- Executing [717XXX8007@from-internal:8] Set("SIP/102-00000000", "_CALLERIDNUMINTERNAL=102") in new stack
-- Executing [717XXX8007@from-internal:9] Set("SIP/102-00000000", "_EMAILNOTIFICATION=FALSE") in new stack
-- Executing [717XXX8007@from-internal:10] Set("SIP/102-00000000", "_NODEST=") in new stack
-- Executing [717XXX8007@from-internal:11] Macro("SIP/102-00000000", "dialout-trunk,1,717XXX8007,,off") in new stack
-- Executing [s@macro-dialout-trunk:1] Set("SIP/102-00000000", "DIAL_TRUNK=1") in new stack
-- Executing [s@macro-dialout-trunk:2] ExecIf("SIP/102-00000000", "0?Set(DIAL_OPTIONS=tr)") in new stack
-- Executing [s@macro-dialout-trunk:3] GosubIf("SIP/102-00000000", "0?sub-pincheck,s,1()") in new stack
-- Executing [s@macro-dialout-trunk:4] ExecIf("SIP/102-00000000", "0?Set(CALLERID(num)=102)") in new stack
-- Executing [s@macro-dialout-trunk:5] GotoIf("SIP/102-00000000", "0?disabletrunk,1") in new stack
-- Executing [s@macro-dialout-trunk:6] Set("SIP/102-00000000", "DIAL_NUMBER=717XXX8007") in new stack
-- Executing [s@macro-dialout-trunk:7] Set("SIP/102-00000000", "DIAL_TRUNK_OPTIONS=Ttr") in new stack
-- Executing [s@macro-dialout-trunk:8] Set("SIP/102-00000000", "OUTBOUND_GROUP=OUT_1") in new stack
-- Executing [s@macro-dialout-trunk:9] Set("SIP/102-00000000", "DIAL_TRUNK_OPTIONS=T") in new stack
-- Executing [s@macro-dialout-trunk:10] GotoIf("SIP/102-00000000", "1?nomax") in new stack
-- Goto (macro-dialout-trunk,s,12)
-- Executing [s@macro-dialout-trunk:12] GotoIf("SIP/102-00000000", "0?skipoutcid") in new stack
-- Executing [s@macro-dialout-trunk:13] Macro("SIP/102-00000000", "outbound-callerid,1") in new stack
-- Executing [s@macro-outbound-callerid:1] NoOp("SIP/102-00000000", "102") in new stack
-- Executing [s@macro-outbound-callerid:2] NoOp("SIP/102-00000000", "") in new stack
-- Executing [s@macro-outbound-callerid:3] NoOp("SIP/102-00000000", "") in new stack
-- Executing [s@macro-outbound-callerid:4] ExecIf("SIP/102-00000000", "0?Set(CALLERPRES(name-pres)=)") in new stack
-- Executing [s@macro-outbound-callerid:5] ExecIf("SIP/102-00000000", "0?Set(CALLERPRES(num-pres)=)") in new stack
-- Executing [s@macro-outbound-callerid:6] Set("SIP/102-00000000", "HOTDESCKCHAN=102-00000000") in new stack
-- Executing [s@macro-outbound-callerid:7] Set("SIP/102-00000000", "HOTDESKEXTEN=102") in new stack
-- Executing [s@macro-outbound-callerid:8] Set("SIP/102-00000000", "HOTDESKCALL=0") in new stack
-- Executing [s@macro-outbound-callerid:9] ExecIf("SIP/102-00000000", "0?Set(HOTDESKCALL=1)") in new stack
-- Executing [s@macro-outbound-callerid:10] ExecIf("SIP/102-00000000", "0?Set(CALLERID(name)=)") in new stack
-- Executing [s@macro-outbound-callerid:11] Set("SIP/102-00000000", "ALLOWTHISROUTE=NO") in new stack
-- Executing [s@macro-outbound-callerid:12] ExecIf("SIP/102-00000000", "0?Set(ALLOWTHISROUTE=YES)") in new stack
-- Executing [s@macro-outbound-callerid:13] ExecIf("SIP/102-00000000", "0?Hangup()") in new stack
-- Executing [s@macro-outbound-callerid:14] ExecIf("SIP/102-00000000", "0?Set(REALCALLERIDNUM=102)") in new stack
-- Executing [s@macro-outbound-callerid:15] ExecIf("SIP/102-00000000", "0?Set(AMPUSER=102)") in new stack
-- Executing [s@macro-outbound-callerid:16] GotoIf("SIP/102-00000000", "1?normcid") in new stack
-- Goto (macro-outbound-callerid,s,20)
-- Executing [s@macro-outbound-callerid:20] Set("SIP/102-00000000", "USEROUTCID=") in new stack
-- Executing [s@macro-outbound-callerid:21] Set("SIP/102-00000000", "EMERGENCYCID=") in new stack
-- Executing [s@macro-outbound-callerid:22] ExecIf("SIP/102-00000000", "0?Set(EMERGENCYCID=)") in new stack
-- Executing [s@macro-outbound-callerid:23] Set("SIP/102-00000000", "TRUNKOUTCID=") in new stack
-- Executing [s@macro-outbound-callerid:24] GotoIf("SIP/102-00000000", "1?trunkcid") in new stack
-- Goto (macro-outbound-callerid,s,30)
-- Executing [s@macro-outbound-callerid:30] ExecIf("SIP/102-00000000", "0?Set(CALLERID(all)=)") in new stack
-- Executing [s@macro-outbound-callerid:31] ExecIf("SIP/102-00000000", "0?Set(CALLERID(all)=)") in new stack
-- Executing [s@macro-outbound-callerid:32] ExecIf("SIP/102-00000000", "0?Set(CALLERID(all)=)") in new stack
-- Executing [s@macro-outbound-callerid:33] ExecIf("SIP/102-00000000", "0?Set(CALLERID(all)=102)") in new stack
-- Executing [s@macro-outbound-callerid:34] Set("SIP/102-00000000", "TIOHIDE=no") in new stack
-- Executing [s@macro-outbound-callerid:35] ExecIf("SIP/102-00000000", "0?Set(CALLERPRES(name-pres)=prohib_passed_screen)") in new stack
-- Executing [s@macro-outbound-callerid:36] ExecIf("SIP/102-00000000", "0?Set(CALLERPRES(num-pres)=prohib_passed_screen)") in new stack
-- Executing [s@macro-outbound-callerid:37] ExecIf("SIP/102-00000000", "0?Set(CALLERPRES(name-pres)=prohib_passed_screen)") in new stack
-- Executing [s@macro-outbound-callerid:38] ExecIf("SIP/102-00000000", "0?Set(CALLERPRES(num-pres)=prohib_passed_screen)") in new stack
-- Executing [s@macro-outbound-callerid:39] Set("SIP/102-00000000", "CDR(outbound_cnum)=102") in new stack
-- Executing [s@macro-outbound-callerid:40] Set("SIP/102-00000000", "CDR(outbound_cnam)=Emily") in new stack
-- Executing [s@macro-dialout-trunk:14] GosubIf("SIP/102-00000000", "0?sub-flp-1,s,1()") in new stack
-- Executing [s@macro-dialout-trunk:15] Set("SIP/102-00000000", "OUTNUM=717XXX8007") in new stack
-- Executing [s@macro-dialout-trunk:16] Set("SIP/102-00000000", "custom=DAHDI/g0") in new stack
-- Executing [s@macro-dialout-trunk:17] ExecIf("SIP/102-00000000", "0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)T)") in new stack
-- Executing [s@macro-dialout-trunk:18] ExecIf("SIP/102-00000000", "0?Set(DIAL_TRUNK_OPTIONS=TM(confirm))") in new stack
-- Executing [s@macro-dialout-trunk:19] Macro("SIP/102-00000000", "dialout-trunk-predial-hook,") in new stack
-- Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("SIP/102-00000000", "") in new stack
-- Executing [s@macro-dialout-trunk:20] GotoIf("SIP/102-00000000", "0?skipcrm") in new stack
-- Executing [s@macro-dialout-trunk:21] Set("SIP/102-00000000", "__CRM_DIRECTION=OUTBOUND") in new stack
-- Executing [s@macro-dialout-trunk:22] Set("SIP/102-00000000", "__CRM_DESTINATION=717XXX8007") in new stack
-- Executing [s@macro-dialout-trunk:23] Set("SIP/102-00000000", "__CRM_SOURCE=102") in new stack
-- Executing [s@macro-dialout-trunk:24] AGI("SIP/102-00000000", "agi://127.0.0.1/sangomacrm.agi") in new stack
-- <SIP/102-00000000>AGI Script agi://127.0.0.1/sangomacrm.agi completed, returning 0
-- Executing [s@macro-dialout-trunk:25] Set("SIP/102-00000000", "CHANNEL(hangup_handler_push)=crm-hangup,s,1") in new stack
-- Executing [s@macro-dialout-trunk:26] NoOp("SIP/102-00000000", "CRM Finished") in new stack
-- Executing [s@macro-dialout-trunk:27] GotoIf("SIP/102-00000000", "0?bypass,1") in new stack
-- Executing [s@macro-dialout-trunk:28] ExecIf("SIP/102-00000000", "1?Set(CONNECTEDLINE(num,i)=717XXX8007)") in new stack
-- Executing [s@macro-dialout-trunk:29] ExecIf("SIP/102-00000000", "1?Set(CONNECTEDLINE(name,i)=CID:102)") in new stack
-- Executing [s@macro-dialout-trunk:30] ExecIf("SIP/102-00000000", "0?Set(CONNECTEDLINE(name,i)=CID:(Hidden)102)") in new stack
-- Executing [s@macro-dialout-trunk:31] GotoIf("SIP/102-00000000", "0?customtrunk") in new stack
-- Executing [s@macro-dialout-trunk:32] ExecIf("SIP/102-00000000", "0?Set(DIAL_TRUNK_OPTIONS=)") in new stack
-- Executing [s@macro-dialout-trunk:33] Set("SIP/102-00000000", "HASH(__SIPHEADERS,Alert-Info)=unset") in new stack
-- Executing [s@macro-dialout-trunk:34] Dial("SIP/102-00000000", "DAHDI/g0/717XXX8007,300,Tb(func-apply-sipheaders^s^1,(1))U(sub-send-obroute-email^717XXX8007^717XXX8007^1^1609443729^Emily^102)") in new stack
-- DAHDI/1-1 Internal Gosub(func-apply-sipheaders,s,1(1)) start
-- Executing [s@func-apply-sipheaders:1] ExecIf("DAHDI/1-1", "0?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
-- Executing [s@func-apply-sipheaders:2] NoOp("DAHDI/1-1", "Applying SIP Headers to channel DAHDI/1-1") in new stack
-- Executing [s@func-apply-sipheaders:3] Set("DAHDI/1-1", "TECH=DAHDI") in new stack
-- Executing [s@func-apply-sipheaders:4] Set("DAHDI/1-1", "SIPHEADERKEYS=Alert-Info") in new stack
-- Executing [s@func-apply-sipheaders:5] While("DAHDI/1-1", "1") in new stack
-- Executing [s@func-apply-sipheaders:6] Set("DAHDI/1-1", "sipheader=unset") in new stack
-- Executing [s@func-apply-sipheaders:7] ExecIf("DAHDI/1-1", "0?SIPRemoveHeader(Alert-Info:)") in new stack
-- Executing [s@func-apply-sipheaders:8] ExecIf("DAHDI/1-1", "0?Set(sipheader=<http://127.0.0.1>;info=unset)") in new stack
-- Executing [s@func-apply-sipheaders:9] ExecIf("DAHDI/1-1", "0?Set(sipheader=<http://127.0.0.1>unset)") in new stack
-- Executing [s@func-apply-sipheaders:10] ExecIf("DAHDI/1-1", "0?SIPAddHeader(Alert-Info:unset)") in new stack
-- Executing [s@func-apply-sipheaders:11] EndWhile("DAHDI/1-1", "") in new stack
-- Executing [s@func-apply-sipheaders:5] While("DAHDI/1-1", "0") in new stack
-- Executing [s@func-apply-sipheaders:12] Return("DAHDI/1-1", "") in new stack
== Spawn extension (from-analog, 717XXX8007, 1) exited non-zero on 'DAHDI/1-1'
-- DAHDI/1-1 Internal Gosub(func-apply-sipheaders,s,1(1)) complete GOSUB_RETVAL=
-- Called DAHDI/g0/717XXX8007
-- DAHDI/1-1 answered SIP/102-00000000
-- DAHDI/1-1 Internal Gosub(sub-send-obroute-email,s,1(717XXX8007,717XXX8007,1,1609443729,Emily,102)) start
-- Executing [s@sub-send-obroute-email:1] GotoIf("DAHDI/1-1", "0?sendEmail") in new stack
-- Executing [s@sub-send-obroute-email:2] NoOp("DAHDI/1-1", "email notifications disabled..exiting.") in new stack
-- Executing [s@sub-send-obroute-email:3] Return("DAHDI/1-1", "") in new stack
== Spawn extension (from-analog, , 1) exited non-zero on 'DAHDI/1-1'
-- DAHDI/1-1 Internal Gosub(sub-send-obroute-email,s,1(717XXX8007,717XXX8007,1,1609443729,Emily,102)) complete GOSUB_RETVAL=
-- Channel DAHDI/1-1 joined 'simple_bridge' basic-bridge <0e62d4f8-b422-4142-ac8d-7434280b3df0>
-- Channel SIP/102-00000000 joined 'simple_bridge' basic-bridge <0e62d4f8-b422-4142-ac8d-7434280b3df0>
[2020-12-31 14:42:19] WARNING[2783]: chan_sip.c:4142 retrans_pkt: Retransmission timeout reached on transmission 1ef35e1a7f719b85 for seqno 28841 (Critical Response) -- See https://wiki.asterisk.org/wiki/display/AST/SIP+Retransmissions
Packet timed out after 6399ms with no response
[2020-12-31 14:42:19] WARNING[2783]: chan_sip.c:4166 retrans_pkt: Hanging up call 1ef35e1a7f719b85 - no reply to our critical packet (see https://wiki.asterisk.org/wiki/display/AST/SIP+Retransmissions).
-- Channel SIP/102-00000000 left 'simple_bridge' basic-bridge <0e62d4f8-b422-4142-ac8d-7434280b3df0>
-- Channel DAHDI/1-1 left 'simple_bridge' basic-bridge <0e62d4f8-b422-4142-ac8d-7434280b3df0>
== Spawn extension (macro-dialout-trunk, s, 34) exited non-zero on 'SIP/102-00000000' in macro 'dialout-trunk'
-- Hanging up on 'DAHDI/1-1'
-- Hungup 'DAHDI/1-1'
== Spawn extension (from-internal, 717XXX8007, 11) exited non-zero on 'SIP/102-00000000'
-- Executing [h@from-internal:1] Macro("SIP/102-00000000", "hangupcall") in new stack
-- Executing [s@macro-hangupcall:1] GotoIf("SIP/102-00000000", "1?theend") in new stack
-- Goto (macro-hangupcall,s,3)
-- Executing [s@macro-hangupcall:3] ExecIf("SIP/102-00000000", "0?Set(CDR(recordingfile)=)") in new stack
-- Executing [s@macro-hangupcall:4] NoOp("SIP/102-00000000", "DAHDI/1-1 montior file= ") in new stack
-- Executing [s@macro-hangupcall:5] GotoIf("SIP/102-00000000", "1?skipagi") in new stack
-- Goto (macro-hangupcall,s,7)
-- Executing [s@macro-hangupcall:7] Hangup("SIP/102-00000000", "") in new stack
== Spawn extension (macro-hangupcall, s, 7) exited non-zero on 'SIP/102-00000000' in macro 'hangupcall'
== Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/102-00000000'
-- SIP/102-00000000 Internal Gosub(crm-hangup,s,1) start
-- Executing [s@crm-hangup:1] NoOp("SIP/102-00000000", "Sending Hangup to CRM") in new stack
-- Executing [s@crm-hangup:2] NoOp("SIP/102-00000000", "HANGUP CAUSE: 18") in new stack
-- Executing [s@crm-hangup:3] ExecIf("SIP/102-00000000", "0?Set(__CRM_VOICEMAIL=)") in new stack
-- Executing [s@crm-hangup:4] NoOp("SIP/102-00000000", "MASTER CHANNEL: 1609443729.0 = 1609443729.0") in new stack
-- Executing [s@crm-hangup:5] GotoIf("SIP/102-00000000", "0?return") in new stack
-- Executing [s@crm-hangup:6] Set("SIP/102-00000000", "__CRM_HANGUP=1") in new stack
-- Executing [s@crm-hangup:7] AGI("SIP/102-00000000", "agi://127.0.0.1/sangomacrm.agi") in new stack
-- <SIP/102-00000000>AGI Script agi://127.0.0.1/sangomacrm.agi completed, returning 0
-- Executing [s@crm-hangup:8] Return("SIP/102-00000000", "") in new stack
== Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/102-00000000'
-- SIP/102-00000000 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
== Extension Changed 102[ext-local] new state Idle for Notify User 102
== Extension Changed 102[ext-local] new state Idle for Notify User 103
== Extension Changed 102[ext-local] new state Idle for Notify User 104
== Extension Changed 102[ext-local] new state Idle for Notify User 101
pbx*CLI>