Incoming Calls are Dropped on Answer, Outgoing Works Fine

Hi all. Using a WellGate 2540, I originally had calls working at the beginning until I started tweaking to get caller ID to work. I am currently being faced with incoming calls being dropped.

I have sectioned off this log output, it is separated by initial call, then what happens when I answer the call.

I see the warnings for codecs, I’ve tried enabling & disabling yes still no change.


    -- Executing [500@from-sip-external:1] NoOp("PJSIP/anonymous-0000004d", "Received incoming SIP connection from unknown peer to 500") in new stack
    -- Executing [500@from-sip-external:2] Set("PJSIP/anonymous-0000004d", "DID=500") in new stack
    -- Executing [500@from-sip-external:3] Goto("PJSIP/anonymous-0000004d", "s,1") in new stack
    -- Goto (from-sip-external,s,1)
    -- Executing [s@from-sip-external:1] GotoIf("PJSIP/anonymous-0000004d", "1?setlanguage:checkanon") in new stack
    -- Goto (from-sip-external,s,2)
    -- Executing [s@from-sip-external:2] Set("PJSIP/anonymous-0000004d", "CHANNEL(language)=en") in new stack
    -- Executing [s@from-sip-external:3] GotoIf("PJSIP/anonymous-0000004d", "0?noanonymous") in new stack
    -- Executing [s@from-sip-external:4] Goto("PJSIP/anonymous-0000004d", "from-trunk,500,1") in new stack
    -- Goto (from-trunk,500,1)
    -- Executing [500@from-trunk:1] NoOp("PJSIP/anonymous-0000004d", "Catch-All DID Match - Found 500 - You probably want a DID for this.") in new stack
    -- Executing [500@from-trunk:2] Set("PJSIP/anonymous-0000004d", "__FROM_DID=500") in new stack
    -- Executing [500@from-trunk:3] Goto("PJSIP/anonymous-0000004d", "ext-did,s,1") in new stack
    -- Goto (ext-did,s,1)
    -- Executing [s@ext-did:1] Set("PJSIP/anonymous-0000004d", "__DIRECTION=INBOUND") in new stack
    -- Executing [s@ext-did:2] Set("PJSIP/anonymous-0000004d", "CHANNEL(tonezone)=us") in new stack
    -- Executing [s@ext-did:3] ExecIf("PJSIP/anonymous-0000004d", "0?Set(__FROM_DID=s)") in new stack
    -- Executing [s@ext-did:4] Set("PJSIP/anonymous-0000004d", "returnhere=1") in new stack
    -- Executing [s@ext-did:5] Gosub("PJSIP/anonymous-0000004d", "app-blacklist-check,s,1()") in new stack
    -- Executing [s@app-blacklist-check:1] GotoIf("PJSIP/anonymous-0000004d", "0?blacklisted") in new stack
    -- Executing [s@app-blacklist-check:2] Set("PJSIP/anonymous-0000004d", "CALLED_BLACKLIST=1") in new stack
    -- Executing [s@app-blacklist-check:3] Return("PJSIP/anonymous-0000004d", "") in new stack
    -- Executing [s@ext-did:6] Set("PJSIP/anonymous-0000004d", "CDR(did)=500") in new stack
    -- Executing [s@ext-did:7] GotoIf("PJSIP/anonymous-0000004d", "0?") in new stack
    -- Executing [s@ext-did:8] ExecIf("PJSIP/anonymous-0000004d", "0 ?Set(CALLERID(name)=16316371511)") in new stack
    -- Executing [s@ext-did:9] Set("PJSIP/anonymous-0000004d", "__MOHCLASS=") in new stack
    -- Executing [s@ext-did:10] Set("PJSIP/anonymous-0000004d", "__REVERSAL_REJECT=FALSE") in new stack
    -- Executing [s@ext-did:11] GotoIf("PJSIP/anonymous-0000004d", "1?post-reverse-charge") in new stack
    -- Goto (ext-did,s,13)
    -- Executing [s@ext-did:13] NoOp("PJSIP/anonymous-0000004d", "") in new stack
    -- Executing [s@ext-did:14] Set("PJSIP/anonymous-0000004d", "__CALLINGNAMEPRES_SV=allowed_not_screened") in new stack
    -- Executing [s@ext-did:15] Set("PJSIP/anonymous-0000004d", "__CALLINGNUMPRES_SV=allowed_not_screened") in new stack
    -- Executing [s@ext-did:16] Set("PJSIP/anonymous-0000004d", "CALLERID(name-pres)=allowed_not_screened") in new stack
    -- Executing [s@ext-did:17] Set("PJSIP/anonymous-0000004d", "CALLERID(num-pres)=allowed_not_screened") in new stack
    -- Executing [s@ext-did:18] NoOp("PJSIP/anonymous-0000004d", "CallerID Entry Point") in new stack
    -- Executing [s@ext-did:19] Set("PJSIP/anonymous-0000004d", "__CRM_DIRECTION=INBOUND") in new stack
    -- Executing [s@ext-did:20] Set("PJSIP/anonymous-0000004d", "__CRM_SOURCE=16316371511") in new stack
    -- Executing [s@ext-did:21] Set("PJSIP/anonymous-0000004d", "__CRM_LINKEDID=1643720285.77") in new stack
    -- Executing [s@ext-did:22] AGI("PJSIP/anonymous-0000004d", "agi://127.0.0.1/sangomacrm.agi,true") in new stack
    -- <PJSIP/anonymous-0000004d>AGI Script agi://127.0.0.1/sangomacrm.agi completed, returning 0
    -- Executing [s@ext-did:23] ExecIf("PJSIP/anonymous-0000004d", "1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
    -- Executing [s@ext-did:24] Goto("PJSIP/anonymous-0000004d", "ext-group,500,1") in new stack
    -- Goto (ext-group,500,1)
    -- Executing [500@ext-group:1] GotoIf("PJSIP/anonymous-0000004d", "0?cid") in new stack
    -- Executing [500@ext-group:2] PlayTones("PJSIP/anonymous-0000004d", "ring") in new stack
    -- Executing [500@ext-group:3] Progress("PJSIP/anonymous-0000004d", "") in new stack
    -- Executing [500@ext-group:4] Macro("PJSIP/anonymous-0000004d", "user-callerid,") in new stack
    -- Executing [s@macro-user-callerid:1] Set("PJSIP/anonymous-0000004d", "TOUCH_MONITOR=1643720285.77") in new stack
    -- Executing [s@macro-user-callerid:2] Set("PJSIP/anonymous-0000004d", "CHANCONTEXT=") in new stack
    -- Executing [s@macro-user-callerid:3] Set("PJSIP/anonymous-0000004d", "CHANCONTEXT=") in new stack
    -- Executing [s@macro-user-callerid:4] Set("PJSIP/anonymous-0000004d", "CHANEXTENCONTEXT=anonymous-0000004d") in new stack
    -- Executing [s@macro-user-callerid:5] Set("PJSIP/anonymous-0000004d", "CHANEXTEN=anonymous-0000004d") in new stack
    -- Executing [s@macro-user-callerid:6] Set("PJSIP/anonymous-0000004d", "CALLERID(number)=16316371511") in new stack
    -- Executing [s@macro-user-callerid:7] Set("PJSIP/anonymous-0000004d", "AMPUSER=16316371511") in new stack
    -- Executing [s@macro-user-callerid:8] Set("PJSIP/anonymous-0000004d", "HOTDESCKCHAN=anonymous-0000004d") in new stack
    -- Executing [s@macro-user-callerid:9] Set("PJSIP/anonymous-0000004d", "HOTDESKEXTEN=anonymous") in new stack
    -- Executing [s@macro-user-callerid:10] Set("PJSIP/anonymous-0000004d", "HOTDESKCALL=0") in new stack
    -- Executing [s@macro-user-callerid:11] ExecIf("PJSIP/anonymous-0000004d", "0?Set(HOTDESKCALL=1)") in new stack
    -- Executing [s@macro-user-callerid:12] ExecIf("PJSIP/anonymous-0000004d", "0?Set(CALLERID(name)=)") in new stack
    -- Executing [s@macro-user-callerid:13] GotoIf("PJSIP/anonymous-0000004d", "0?report") in new stack
    -- Executing [s@macro-user-callerid:14] ExecIf("PJSIP/anonymous-0000004d", "1?Set(REALCALLERIDNUM=16316371511)") in new stack
    -- Executing [s@macro-user-callerid:15] Set("PJSIP/anonymous-0000004d", "AMPUSER=") in new stack
    -- Executing [s@macro-user-callerid:16] GotoIf("PJSIP/anonymous-0000004d", "0?limit") in new stack
    -- Executing [s@macro-user-callerid:17] Set("PJSIP/anonymous-0000004d", "AMPUSERCIDNAME=") in new stack
    -- Executing [s@macro-user-callerid:18] ExecIf("PJSIP/anonymous-0000004d", "0?Set(__CIDMASQUERADING=TRUE)") in new stack
    -- Executing [s@macro-user-callerid:19] GotoIf("PJSIP/anonymous-0000004d", "1?report") in new stack
    -- Goto (macro-user-callerid,s,28)
    -- Executing [s@macro-user-callerid:28] NoOp("PJSIP/anonymous-0000004d", "Macro Depth is 1") in new stack
    -- Executing [s@macro-user-callerid:29] GotoIf("PJSIP/anonymous-0000004d", "1?report2:macroerror") in new stack
    -- Goto (macro-user-callerid,s,30)
    -- Executing [s@macro-user-callerid:30] GotoIf("PJSIP/anonymous-0000004d", "0?continue") in new stack
    -- Executing [s@macro-user-callerid:31] ExecIf("PJSIP/anonymous-0000004d", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack
    -- Executing [s@macro-user-callerid:32] Set("PJSIP/anonymous-0000004d", "__TTL=64") in new stack
    -- Executing [s@macro-user-callerid:33] GotoIf("PJSIP/anonymous-0000004d", "1?continue") in new stack
    -- Goto (macro-user-callerid,s,49)
    -- Executing [s@macro-user-callerid:49] Set("PJSIP/anonymous-0000004d", "CALLERID(number)=16316371511") in new stack
    -- Executing [s@macro-user-callerid:50] Set("PJSIP/anonymous-0000004d", "CALLERID(name)=16316371511") in new stack
    -- Executing [s@macro-user-callerid:51] GotoIf("PJSIP/anonymous-0000004d", "0?cnum") in new stack
    -- Executing [s@macro-user-callerid:52] Set("PJSIP/anonymous-0000004d", "CDR(cnam)=16316371511") in new stack
    -- Executing [s@macro-user-callerid:53] Set("PJSIP/anonymous-0000004d", "CDR(cnum)=16316371511") in new stack
    -- Executing [s@macro-user-callerid:54] Set("PJSIP/anonymous-0000004d", "CHANNEL(language)=en") in new stack
    -- Executing [500@ext-group:5] Macro("PJSIP/anonymous-0000004d", "blkvm-setifempty,") in new stack
    -- Executing [s@macro-blkvm-setifempty:1] GotoIf("PJSIP/anonymous-0000004d", "1?init") in new stack
    -- Goto (macro-blkvm-setifempty,s,4)
    -- Executing [s@macro-blkvm-setifempty:4] Set("PJSIP/anonymous-0000004d", "__BLKVM_CHANNEL=PJSIP/anonymous-0000004d") in new stack
    -- Executing [s@macro-blkvm-setifempty:5] Set("PJSIP/anonymous-0000004d", "SHARED(BLKVM,PJSIP/anonymous-0000004d)=TRUE") in new stack
    -- Executing [s@macro-blkvm-setifempty:6] Set("PJSIP/anonymous-0000004d", "GOSUB_RETVAL=TRUE") in new stack
    -- Executing [s@macro-blkvm-setifempty:7] MacroExit("PJSIP/anonymous-0000004d", "") in new stack
    -- Executing [500@ext-group:6] GotoIf("PJSIP/anonymous-0000004d", "1?skipov") in new stack
    -- Goto (ext-group,500,9)
    -- Executing [500@ext-group:9] Set("PJSIP/anonymous-0000004d", "RRNODEST=") in new stack
    -- Executing [500@ext-group:10] Set("PJSIP/anonymous-0000004d", "__NODEST=500") in new stack
    -- Executing [500@ext-group:11] GosubIf("PJSIP/anonymous-0000004d", "0?sub-rgsetcid,s,1()") in new stack
    -- Executing [500@ext-group:12] Gosub("PJSIP/anonymous-0000004d", "sub-record-check,s,1(rg,500,dontcare)") in new stack
    -- Executing [s@sub-record-check:1] GotoIf("PJSIP/anonymous-0000004d", "0?initialized") in new stack
    -- Executing [s@sub-record-check:2] Set("PJSIP/anonymous-0000004d", "__REC_STATUS=INITIALIZED") in new stack
    -- Executing [s@sub-record-check:3] Set("PJSIP/anonymous-0000004d", "NOW=1643720285") in new stack
    -- Executing [s@sub-record-check:4] Set("PJSIP/anonymous-0000004d", "__DAY=01") in new stack
    -- Executing [s@sub-record-check:5] Set("PJSIP/anonymous-0000004d", "__MONTH=02") in new stack
    -- Executing [s@sub-record-check:6] Set("PJSIP/anonymous-0000004d", "__YEAR=2022") in new stack
    -- Executing [s@sub-record-check:7] Set("PJSIP/anonymous-0000004d", "__TIMESTR=20220201-125805") in new stack
    -- Executing [s@sub-record-check:8] Set("PJSIP/anonymous-0000004d", "__FROMEXTEN=16316371511") in new stack
    -- Executing [s@sub-record-check:9] Set("PJSIP/anonymous-0000004d", "__MON_FMT=wav") in new stack
    -- Executing [s@sub-record-check:10] NoOp("PJSIP/anonymous-0000004d", "Recordings initialized") in new stack
    -- Executing [s@sub-record-check:11] ExecIf("PJSIP/anonymous-0000004d", "0?Set(ARG3=dontcare)") in new stack
    -- Executing [s@sub-record-check:12] Set("PJSIP/anonymous-0000004d", "REC_POLICY_MODE_SAVE=") in new stack
    -- Executing [s@sub-record-check:13] ExecIf("PJSIP/anonymous-0000004d", "0?Set(REC_STATUS=NO)") in new stack
    -- Executing [s@sub-record-check:14] GotoIf("PJSIP/anonymous-0000004d", "2?checkaction") in new stack
    -- Goto (sub-record-check,s,17)
    -- Executing [s@sub-record-check:17] GotoIf("PJSIP/anonymous-0000004d", "0?sub-record-check,rg,1") in new stack
    -- Executing [s@sub-record-check:18] NoOp("PJSIP/anonymous-0000004d", "Generic rg Recording Check - 16316371511 500") in new stack
    -- Executing [s@sub-record-check:19] Gosub("PJSIP/anonymous-0000004d", "recordcheck,1(dontcare,rg,500)") in new stack
    -- Executing [recordcheck@sub-record-check:1] NoOp("PJSIP/anonymous-0000004d", "Starting recording check against dontcare") in new stack
    -- Executing [recordcheck@sub-record-check:2] Goto("PJSIP/anonymous-0000004d", "dontcare") in new stack
    -- Goto (sub-record-check,recordcheck,3)
    -- Executing [recordcheck@sub-record-check:3] Return("PJSIP/anonymous-0000004d", "") in new stack
    -- Executing [s@sub-record-check:20] Return("PJSIP/anonymous-0000004d", "") in new stack
    -- Executing [500@ext-group:13] Set("PJSIP/anonymous-0000004d", "RingGroupMethod=ringall") in new stack
    -- Executing [500@ext-group:14] Set("PJSIP/anonymous-0000004d", "__ALT_CONFIRM_MSG=") in new stack
    -- Executing [500@ext-group:15] GotoIf("PJSIP/anonymous-0000004d", "0?RGVQANNOUNCE:NORGVQANNOUNCE") in new stack
    -- Goto (ext-group,500,18)
    -- Executing [500@ext-group:18] Macro("PJSIP/anonymous-0000004d", "dial,20,HhTtrQ(NO_ANSWER),101-102-103-104-100") in new stack
    -- Executing [s@macro-dial:1] NoOp("PJSIP/anonymous-0000004d", "Blind Transfer: , Attended Transfer: , User: , Alert Info: ") in new stack
    -- Executing [s@macro-dial:2] Set("PJSIP/anonymous-0000004d", "__CRM_SOURCE=16316371511") in new stack
    -- Executing [s@macro-dial:3] ExecIf("PJSIP/anonymous-0000004d", "0?Set(ALERT_INFO=)") in new stack
    -- Executing [s@macro-dial:4] ExecIf("PJSIP/anonymous-0000004d", "0?Set(ALERT_INFO=)") in new stack
    -- Executing [s@macro-dial:5] ExecIf("PJSIP/anonymous-0000004d", "0?Set(ALERT_INFO=)") in new stack
    -- Executing [s@macro-dial:6] ExecIf("PJSIP/anonymous-0000004d", "0?Set(CHANNEL(musicclass)=)") in new stack
    -- Executing [s@macro-dial:7] AGI("PJSIP/anonymous-0000004d", "agi://127.0.0.1/dialparties.agi") in new stack
       > 0x7f7fa80529d0 -- Strict RTP learning after remote address set to: 10.10.3.240:16459
[2022-02-01 12:58:05] WARNING[12537]: channel.c:5704 set_format: Unable to find a codec translation path: (slin) -> (g723)
[2022-02-01 12:58:05] WARNING[18470][C-00000028]: chan_pjsip.c:957 chan_pjsip_write_stream: Channel PJSIP/anonymous-0000004d asked to send ulaw frame when native formats are (g723) (rd:g723->g723; wr:slin->ulaw;(slin@8000)->(ulaw@8000))
[2022-02-01 12:58:05] WARNING[18470][C-00000028]: chan_pjsip.c:957 chan_pjsip_write_stream: Channel PJSIP/anonymous-0000004d asked to send ulaw frame when native formats are (g723) (rd:g723->g723; wr:slin->ulaw;(slin@8000)->(ulaw@8000))
[2022-02-01 12:58:05] WARNING[18470][C-00000028]: chan_pjsip.c:957 chan_pjsip_write_stream: Channel PJSIP/anonymous-0000004d asked to send ulaw frame when native formats are (g723) (rd:g723->g723; wr:slin->ulaw;(slin@8000)->(ulaw@8000))
[2022-02-01 12:58:05] WARNING[18470][C-00000028]: chan_pjsip.c:957 chan_pjsip_write_stream: Channel PJSIP/anonymous-0000004d asked to send ulaw frame when native formats are (g723) (rd:g723->g723; wr:slin->ulaw;(slin@8000)->(ulaw@8000))
[2022-02-01 12:58:05] WARNING[18470][C-00000028]: chan_pjsip.c:957 chan_pjsip_write_stream: Channel PJSIP/anonymous-0000004d asked to send ulaw frame when native formats are (g723) (rd:g723->g723; wr:slin->ulaw;(slin@8000)->(ulaw@8000))
 agi://127.0.0.1/dialparties.agi: Starting New Dialparties.agi
 agi://127.0.0.1/dialparties.agi: Caller ID name is '16316371511' number is '16316371511'
 agi://127.0.0.1/dialparties.agi: CW Ignore is:
 agi://127.0.0.1/dialparties.agi: CF Ignore is:
 agi://127.0.0.1/dialparties.agi: CW IN_USE/BUSY is: 1
 agi://127.0.0.1/dialparties.agi: Ringgroup confirm is  :
[2022-02-01 12:58:05] WARNING[18470][C-00000028]: chan_pjsip.c:957 chan_pjsip_write_stream: Channel PJSIP/anonymous-0000004d asked to send ulaw frame when native formats are (g723) (rd:g723->g723; wr:slin->ulaw;(slin@8000)->(ulaw@8000))
       > agi://127.0.0.1/dialparties.agi: USE_CONFIRMATION:  'FALSE'
       > agi://127.0.0.1/dialparties.agi: RINGGROUP_INDEX:   ''
 agi://127.0.0.1/dialparties.agi: Methodology of ring is  'ringall'
    -- agi://127.0.0.1/dialparties.agi: Added extension 101 to extension map
    -- agi://127.0.0.1/dialparties.agi: Added extension 102 to extension map
    -- agi://127.0.0.1/dialparties.agi: Added extension 103 to extension map
    -- agi://127.0.0.1/dialparties.agi: Added extension 104 to extension map
    -- agi://127.0.0.1/dialparties.agi: Added extension 100 to extension map
[2022-02-01 12:58:05] WARNING[18470][C-00000028]: chan_pjsip.c:957 chan_pjsip_write_stream: Channel PJSIP/anonymous-0000004d asked to send ulaw frame when native formats are (g723) (rd:g723->g723; wr:slin->ulaw;(slin@8000)->(ulaw@8000))
    -- agi://127.0.0.1/dialparties.agi: Extension 101 cf is disabled
    -- agi://127.0.0.1/dialparties.agi: Extension 102 cf is disabled
    -- agi://127.0.0.1/dialparties.agi: Extension 103 cf is disabled
    -- agi://127.0.0.1/dialparties.agi: Extension 104 cf is disabled
    -- agi://127.0.0.1/dialparties.agi: Extension 100 cf is disabled
    -- agi://127.0.0.1/dialparties.agi: Extension 101 do not disturb is disabled
    -- agi://127.0.0.1/dialparties.agi: Extension 102 do not disturb is disabled
    -- agi://127.0.0.1/dialparties.agi: Extension 103 do not disturb is disabled
    -- agi://127.0.0.1/dialparties.agi: Extension 104 do not disturb is disabled
[2022-02-01 12:58:05] WARNING[18470][C-00000028]: chan_pjsip.c:957 chan_pjsip_write_stream: Channel PJSIP/anonymous-0000004d asked to send ulaw frame when native formats are (g723) (rd:g723->g723; wr:slin->ulaw;(slin@8000)->(ulaw@8000))
    -- agi://127.0.0.1/dialparties.agi: Extension 100 do not disturb is disabled
       > agi://127.0.0.1/dialparties.agi: extnum 101 has:  cw: 1; hascfb: 0 [] hascfu: 0 []
  == agi://127.0.0.1/dialparties.agi: Discovered PJSIP Endpoint PJSIP/101
    -- agi://127.0.0.1/dialparties.agi: Ended up with no PJSIP contacts
    -- agi://127.0.0.1/dialparties.agi: dbset CALLTRACE/101 to 16316371511
       > agi://127.0.0.1/dialparties.agi: extnum 102 has:  cw: 1; hascfb: 0 [] hascfu: 0 []
[2022-02-01 12:58:05] WARNING[18470][C-00000028]: chan_pjsip.c:957 chan_pjsip_write_stream: Channel PJSIP/anonymous-0000004d asked to send ulaw frame when native formats are (g723) (rd:g723->g723; wr:slin->ulaw;(slin@8000)->(ulaw@8000))
  == agi://127.0.0.1/dialparties.agi: Discovered PJSIP Endpoint PJSIP/102
    -- agi://127.0.0.1/dialparties.agi: Ended up with no PJSIP contacts
    -- agi://127.0.0.1/dialparties.agi: dbset CALLTRACE/102 to 16316371511
       > agi://127.0.0.1/dialparties.agi: extnum 103 has:  cw: 1; hascfb: 0 [] hascfu: 0 []
  == agi://127.0.0.1/dialparties.agi: Discovered PJSIP Endpoint PJSIP/103
    -- agi://127.0.0.1/dialparties.agi: Ended up with no PJSIP contacts
    -- agi://127.0.0.1/dialparties.agi: dbset CALLTRACE/103 to 16316371511
[2022-02-01 12:58:05] WARNING[18470][C-00000028]: chan_pjsip.c:957 chan_pjsip_write_stream: Channel PJSIP/anonymous-0000004d asked to send ulaw frame when native formats are (g723) (rd:g723->g723; wr:slin->ulaw;(slin@8000)->(ulaw@8000))
       > agi://127.0.0.1/dialparties.agi: extnum 104 has:  cw: 1; hascfb: 0 [] hascfu: 0 []
  == agi://127.0.0.1/dialparties.agi: Discovered PJSIP Endpoint PJSIP/104
    -- agi://127.0.0.1/dialparties.agi: Ended up with no PJSIP contacts
    -- agi://127.0.0.1/dialparties.agi: dbset CALLTRACE/104 to 16316371511
       > agi://127.0.0.1/dialparties.agi: extnum 100 has:  cw: 1; hascfb: 0 [] hascfu: 0 []
  == agi://127.0.0.1/dialparties.agi: Discovered PJSIP Endpoint PJSIP/100
    -- agi://127.0.0.1/dialparties.agi: Ended up with real PJSIP Dial string PJSIP/100/sip:[email protected]:51072;rinstance=aa4f438e9b36745d
    -- agi://127.0.0.1/dialparties.agi: dbset CALLTRACE/100 to 16316371511
[2022-02-01 12:58:05] WARNING[18470][C-00000028]: chan_pjsip.c:957 chan_pjsip_write_stream: Channel PJSIP/anonymous-0000004d asked to send ulaw frame when native formats are (g723) (rd:g723->g723; wr:slin->ulaw;(slin@8000)->(ulaw@8000))
    -- agi://127.0.0.1/dialparties.agi: Filtered ARG3: 101-102-103-104-100
       > agi://127.0.0.1/dialparties.agi: NODEST: 500 adding M(auto-blkvm) to dialopts: HhTtrQ(NO_ANSWER)M(auto-blkvm)
       > agi://127.0.0.1/dialparties.agi: NODEST: 500 blkvm enabled macro already in dialopts: HhTtrQ(NO_ANSWER)M(auto-blkvm)
 agi://127.0.0.1/dialparties.agi: RVOL_MODE ''
 agi://127.0.0.1/dialparties.agi: RVOL is:
 agi://127.0.0.1/dialparties.agi: RVOLPARENT is:
[2022-02-01 12:58:05] WARNING[18470][C-00000028]: chan_pjsip.c:957 chan_pjsip_write_stream: Channel PJSIP/anonymous-0000004d asked to send ulaw frame when native formats are (g723) (rd:g723->g723; wr:slin->ulaw;(slin@8000)->(ulaw@8000))
    -- <PJSIP/anonymous-0000004d>AGI Script agi://127.0.0.1/dialparties.agi completed, returning 0
    -- Executing [s@macro-dial:8] GotoIf("PJSIP/anonymous-0000004d", "1?normdial") in new stack
    -- Goto (macro-dial,s,11)
    -- Executing [s@macro-dial:11] NoOp("PJSIP/anonymous-0000004d", "Returned from dialparties with groups to dial") in new stack
    -- Executing [s@macro-dial:12] NoOp("PJSIP/anonymous-0000004d", "ringall array  ") in new stack
    -- Executing [s@macro-dial:13] Set("PJSIP/anonymous-0000004d", "__FMGL_DIAL=") in new stack
    -- Executing [s@macro-dial:14] Set("PJSIP/anonymous-0000004d", "LOOPCNT=5") in new stack
    -- Executing [s@macro-dial:15] Set("PJSIP/anonymous-0000004d", "ITER=1") in new stack
    -- Executing [s@macro-dial:16] Set("PJSIP/anonymous-0000004d", "__EXTTOCALL=101") in new stack
    -- Executing [s@macro-dial:17] NoOp("PJSIP/anonymous-0000004d", "Working with 101") in new stack
    -- Executing [s@macro-dial:18] ExecIf("PJSIP/anonymous-0000004d", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
    -- Executing [s@macro-dial:19] ExecIf("PJSIP/anonymous-0000004d", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
    -- Executing [s@macro-dial:20] Set("PJSIP/anonymous-0000004d", "ITER=2") in new stack
    -- Executing [s@macro-dial:21] GotoIf("PJSIP/anonymous-0000004d", "1?ndloopbegin") in new stack
    -- Goto (macro-dial,s,16)
    -- Executing [s@macro-dial:16] Set("PJSIP/anonymous-0000004d", "__EXTTOCALL=102") in new stack
    -- Executing [s@macro-dial:17] NoOp("PJSIP/anonymous-0000004d", "Working with 102") in new stack
    -- Executing [s@macro-dial:18] ExecIf("PJSIP/anonymous-0000004d", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
    -- Executing [s@macro-dial:19] ExecIf("PJSIP/anonymous-0000004d", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
    -- Executing [s@macro-dial:20] Set("PJSIP/anonymous-0000004d", "ITER=3") in new stack
    -- Executing [s@macro-dial:21] GotoIf("PJSIP/anonymous-0000004d", "1?ndloopbegin") in new stack
    -- Goto (macro-dial,s,16)
    -- Executing [s@macro-dial:16] Set("PJSIP/anonymous-0000004d", "__EXTTOCALL=103") in new stack
    -- Executing [s@macro-dial:17] NoOp("PJSIP/anonymous-0000004d", "Working with 103") in new stack
    -- Executing [s@macro-dial:18] ExecIf("PJSIP/anonymous-0000004d", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
    -- Executing [s@macro-dial:19] ExecIf("PJSIP/anonymous-0000004d", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
    -- Executing [s@macro-dial:20] Set("PJSIP/anonymous-0000004d", "ITER=4") in new stack
    -- Executing [s@macro-dial:21] GotoIf("PJSIP/anonymous-0000004d", "1?ndloopbegin") in new stack
    -- Goto (macro-dial,s,16)
    -- Executing [s@macro-dial:16] Set("PJSIP/anonymous-0000004d", "__EXTTOCALL=104") in new stack
    -- Executing [s@macro-dial:17] NoOp("PJSIP/anonymous-0000004d", "Working with 104") in new stack
    -- Executing [s@macro-dial:18] ExecIf("PJSIP/anonymous-0000004d", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
    -- Executing [s@macro-dial:19] ExecIf("PJSIP/anonymous-0000004d", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
    -- Executing [s@macro-dial:20] Set("PJSIP/anonymous-0000004d", "ITER=5") in new stack
    -- Executing [s@macro-dial:21] GotoIf("PJSIP/anonymous-0000004d", "1?ndloopbegin") in new stack
    -- Goto (macro-dial,s,16)
    -- Executing [s@macro-dial:16] Set("PJSIP/anonymous-0000004d", "__EXTTOCALL=100") in new stack
    -- Executing [s@macro-dial:17] NoOp("PJSIP/anonymous-0000004d", "Working with 100") in new stack
    -- Executing [s@macro-dial:18] ExecIf("PJSIP/anonymous-0000004d", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
    -- Executing [s@macro-dial:19] ExecIf("PJSIP/anonymous-0000004d", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
    -- Executing [s@macro-dial:20] Set("PJSIP/anonymous-0000004d", "ITER=6") in new stack
    -- Executing [s@macro-dial:21] GotoIf("PJSIP/anonymous-0000004d", "0?ndloopbegin") in new stack
    -- Executing [s@macro-dial:22] Macro("PJSIP/anonymous-0000004d", "dial-ringall-predial-hook,") in new stack
    -- Executing [s@macro-dial-ringall-predial-hook:1] MacroExit("PJSIP/anonymous-0000004d", "") in new stack
    -- Executing [s@macro-dial:23] ExecIf("PJSIP/anonymous-0000004d", "0?Set(CWRING=r(callwaiting)):Set(CWRING=)") in new stack
    -- Executing [s@macro-dial:24] ExecIf("PJSIP/anonymous-0000004d", "0?Set(ds=PJSIP/100/sip:[email protected]:51072;rinstance=aa4f438e9b36745d,20,HhtrQ(NO_ANSWER)M(auto-blkvm)g)") in new stack
    -- Executing [s@macro-dial:25] Dial("PJSIP/anonymous-0000004d", "PJSIP/100/sip:[email protected]:51072;rinstance=aa4f438e9b36745d,20,HhtrQ(NO_ANSWER)M(auto-blkvm)b(func-apply-sipheaders^s^1),") in new stack
    -- PJSIP/100-0000004e Internal Gosub(func-apply-sipheaders,s,1) start
[2022-02-01 12:58:05] WARNING[3531][C-00000028]: chan_pjsip.c:957 chan_pjsip_write_stream: Channel PJSIP/anonymous-0000004d asked to send ulaw frame when native formats are (g723) (rd:g723->g723; wr:slin->ulaw;(slin@8000)->(ulaw@8000))
    -- Executing [s@func-apply-sipheaders:1] ExecIf("PJSIP/100-0000004e", "1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
    -- Executing [s@func-apply-sipheaders:2] NoOp("PJSIP/100-0000004e", "Applying SIP Headers to channel PJSIP/100-0000004e") in new stack
    -- Executing [s@func-apply-sipheaders:3] Set("PJSIP/100-0000004e", "TECH=PJSIP") in new stack
    -- Executing [s@func-apply-sipheaders:4] Set("PJSIP/100-0000004e", "SIPHEADERKEYS=") in new stack
    -- Executing [s@func-apply-sipheaders:5] While("PJSIP/100-0000004e", "0") in new stack
    -- Jumping to priority 11
    -- Executing [s@func-apply-sipheaders:12] Return("PJSIP/100-0000004e", "") in new stack
  == Spawn extension (from-internal, 500, 1) exited non-zero on 'PJSIP/100-0000004e'
    -- PJSIP/100-0000004e Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
    -- Called PJSIP/100/sip:[email protected]:51072;rinstance=aa4f438e9b36745d
[2022-02-01 12:58:05] WARNING[18470][C-00000028]: channel.c:5704 set_format: Unable to find a codec translation path: (slin) -> (g723)
[2022-02-01 12:58:05] WARNING[18470][C-00000028]: indications.c:140 playtones_alloc: Unable to set 'PJSIP/anonymous-0000004d' to signed linear format (write)
[2022-02-01 12:58:05] WARNING[18470][C-00000028]: channel.c:4691 indicate_data_internal: Unable to handle indication 3 for 'PJSIP/anonymous-0000004d'
  == Using SIP RTP Audio TOS bits 184
  == Using SIP RTP Audio TOS bits 184 in TCLASS field.
  == Using SIP RTP Audio CoS mark 5
    -- PJSIP/100-0000004e is ringing
[2022-02-01 12:58:06] WARNING[18470][C-00000028]: channel.c:5704 set_format: Unable to find a codec translation path: (slin) -> (g723)
[2022-02-01 12:58:06] WARNING[18470][C-00000028]: indications.c:140 playtones_alloc: Unable to set 'PJSIP/anonymous-0000004d' to signed linear format (write)
[2022-02-01 12:58:06] WARNING[18470][C-00000028]: channel.c:4691 indicate_data_internal: Unable to handle indication 3 for 'PJSIP/anonymous-0000004d'

Here is what happens when you answer the call:

       > 0x7f7fc40573f0 -- Strict RTP learning after remote address set to: 10.10.3.204:50438
[2022-02-01 12:58:09] WARNING[12440]: channel.c:5704 set_format: Unable to find a codec translation path: (alaw) -> (g723)
[2022-02-01 12:58:09] WARNING[12440]: channel.c:5704 set_format: Unable to find a codec translation path: (g723) -> (alaw)
    -- PJSIP/100-0000004e answered PJSIP/anonymous-0000004d
    -- Executing [s@macro-auto-blkvm:1] Set("PJSIP/100-0000004e", "__MACRO_RESULT=") in new stack
    -- Executing [s@macro-auto-blkvm:2] Set("PJSIP/100-0000004e", "CFIGNORE=") in new stack
    -- Executing [s@macro-auto-blkvm:3] Set("PJSIP/100-0000004e", "MASTER_CHANNEL(CFIGNORE)=") in new stack
    -- Executing [s@macro-auto-blkvm:4] Set("PJSIP/100-0000004e", "FORWARD_CONTEXT=from-internal") in new stack
    -- Executing [s@macro-auto-blkvm:5] Set("PJSIP/100-0000004e", "MASTER_CHANNEL(FORWARD_CONTEXT)=from-internal") in new stack
    -- Executing [s@macro-auto-blkvm:6] Macro("PJSIP/100-0000004e", "blkvm-clr,") in new stack
    -- Executing [s@macro-blkvm-clr:1] Set("PJSIP/100-0000004e", "SHARED(BLKVM,PJSIP/anonymous-0000004d)=") in new stack
    -- Executing [s@macro-blkvm-clr:2] Set("PJSIP/100-0000004e", "GOSUB_RETVAL=") in new stack
    -- Executing [s@macro-blkvm-clr:3] MacroExit("PJSIP/100-0000004e", "") in new stack
    -- Executing [s@macro-auto-blkvm:7] ExecIf("PJSIP/100-0000004e", "0?Set(MASTER_CHANNEL(CONNECTEDLINE(num))=100/sip:[email protected]:51072;rinstance=aa4f438e9b36745d)") in new stack
    -- Executing [s@macro-auto-blkvm:8] ExecIf("PJSIP/100-0000004e", "0?Set(MASTER_CHANNEL(CONNECTEDLINE(name))=)") in new stack
[2022-02-01 12:58:09] WARNING[18470][C-00000028]: channel.c:6704 ast_channel_make_compatible_helper: No path to translate from PJSIP/100-0000004e to PJSIP/anonymous-0000004d
[2022-02-01 12:58:09] WARNING[18470][C-00000028]: app_dial.c:3300 dial_exec_full: Had to drop call because I couldn't make PJSIP/anonymous-0000004d compatible with PJSIP/100-0000004e
    -- PJSIP/100-0000004e Internal Gosub(crm-hangup,s,1) start
    -- Executing [s@crm-hangup:1] NoOp("PJSIP/100-0000004e", "Sending Hangup to CRM") in new stack
    -- Executing [s@crm-hangup:2] NoOp("PJSIP/100-0000004e", "HANGUP CAUSE: 16") in new stack
    -- Executing [s@crm-hangup:3] ExecIf("PJSIP/100-0000004e", "0?Set(__CRM_VOICEMAIL=)") in new stack
    -- Executing [s@crm-hangup:4] NoOp("PJSIP/100-0000004e", "MASTER CHANNEL: 1643720285.78 = 1643720285.77") in new stack
    -- Executing [s@crm-hangup:5] GotoIf("PJSIP/100-0000004e", "1?return") in new stack
    -- Goto (crm-hangup,s,8)
    -- Executing [s@crm-hangup:8] Return("PJSIP/100-0000004e", "") in new stack
  == Spawn extension (macro-dial, s, 1) exited non-zero on 'PJSIP/100-0000004e'
    -- PJSIP/100-0000004e Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
  == Spawn extension (macro-dial, s, 25) exited non-zero on 'PJSIP/anonymous-0000004d' in macro 'dial'
  == Spawn extension (ext-group, 500, 18) exited non-zero on 'PJSIP/anonymous-0000004d'
    -- Executing [h@ext-group:1] Macro("PJSIP/anonymous-0000004d", "hangupcall,") in new stack
    -- Executing [s@macro-hangupcall:1] GotoIf("PJSIP/anonymous-0000004d", "1?theend") in new stack
    -- Goto (macro-hangupcall,s,3)
    -- Executing [s@macro-hangupcall:3] ExecIf("PJSIP/anonymous-0000004d", "0?Set(CDR(recordingfile)=)") in new stack
    -- Executing [s@macro-hangupcall:4] NoOp("PJSIP/anonymous-0000004d", "PJSIP/100-0000004e montior file= ") in new stack
    -- Executing [s@macro-hangupcall:5] GotoIf("PJSIP/anonymous-0000004d", "1?skipagi") in new stack
    -- Goto (macro-hangupcall,s,7)
    -- Executing [s@macro-hangupcall:7] Hangup("PJSIP/anonymous-0000004d", "") in new stack
  == Spawn extension (macro-hangupcall, s, 7) exited non-zero on 'PJSIP/anonymous-0000004d' in macro 'hangupcall'
  == Spawn extension (ext-group, h, 1) exited non-zero on 'PJSIP/anonymous-0000004d'
    -- PJSIP/anonymous-0000004d Internal Gosub(crm-hangup,s,1) start
    -- Executing [s@crm-hangup:1] NoOp("PJSIP/anonymous-0000004d", "Sending Hangup to CRM") in new stack
    -- Executing [s@crm-hangup:2] NoOp("PJSIP/anonymous-0000004d", "HANGUP CAUSE: 16") in new stack
    -- Executing [s@crm-hangup:3] ExecIf("PJSIP/anonymous-0000004d", "0?Set(__CRM_VOICEMAIL=)") in new stack
    -- Executing [s@crm-hangup:4] NoOp("PJSIP/anonymous-0000004d", "MASTER CHANNEL: 1643720285.77 = 1643720285.77") in new stack
    -- Executing [s@crm-hangup:5] GotoIf("PJSIP/anonymous-0000004d", "0?return") in new stack
    -- Executing [s@crm-hangup:6] Set("PJSIP/anonymous-0000004d", "__CRM_HANGUP=1") in new stack
    -- Executing [s@crm-hangup:7] AGI("PJSIP/anonymous-0000004d", "agi://127.0.0.1/sangomacrm.agi") in new stack
    -- <PJSIP/anonymous-0000004d>AGI Script agi://127.0.0.1/sangomacrm.agi completed, returning 0
    -- Executing [s@crm-hangup:8] Return("PJSIP/anonymous-0000004d", "") in new stack
  == Spawn extension (ext-group, h, 1) exited non-zero on 'PJSIP/anonymous-0000004d'
    -- PJSIP/anonymous-0000004d Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=

It’s not matching any normal endpoint. Was that intentional? Otherwise your type=identify section is wrong.

Likely was not intentional. I’m not very well versed in FreePBX so my terminology is definitely off.

Do you have a quick walkthrough on correcting that?

I can’t find relevant information in the user guide, but this thread describes the options you might need:

However, in general, you need to ask yourself: “what in the incoming request would tell Asterisk that this request is coming from the trunk in question?”.

The head posting for the thread contains the basic answer; the rest of the thread is about complications that can arise.

Thank you, I’ll definitely look into that.

I continued searching, and stumbled across this thread:

In my case in the logs it was was kicking back g723, not g729. I went over to the site listed (http://asterisk.hosting.lv) and performed the steps with the g723 codec, restarted asterisk, calls work now. :slight_smile:

It’s unable to find a codec path because it is using the codecs for the anonymous endpoint, rather than those for intended endpoint.

It might still fail with the write endpoint, but nothing you do to configure the codecs for that endpoint will make any difference until it actually matches the call to the endpoint.

This topic was automatically closed 31 days after the last reply. New replies are no longer allowed.