Hi,
I did a new installation of FreePBX (after failing to upgrade my old system).
I’m trying to make outbound call over a trunk through provider Poivy (a Dellmont / Betmax clone). In the old days I used chan_sip for this, now trying to get it to work with pjsip, but getting immediatly “The number is not answerring”, wihtout obvious errors in the log..
Using another trunk/provider on the outbound route works fine, so it seems to be a problem associated with poivy trunk.
I would appreciate your help.
Thanks.
Here’s the log. Hopefully you can give me some tips on how to resolve the problem.
I masked the reaal CID and DID number.
-- Executing [0032470123456@from-internal:1] Gosub("PJSIP/901-00000032", "macro-user-callerid,s,1(LIMIT,EXTERNAL)") in new stack
-- Executing [s@macro-user-callerid:1] Set("PJSIP/901-00000032", "TOUCH_MONITOR=1744877739.88") in new stack
-- Executing [s@macro-user-callerid:2] Set("PJSIP/901-00000032", "CHANCONTEXT=") in new stack
-- Executing [s@macro-user-callerid:3] Set("PJSIP/901-00000032", "CHANCONTEXT=") in new stack
-- Executing [s@macro-user-callerid:4] Set("PJSIP/901-00000032", "CHANEXTENCONTEXT=901-00000032") in new stack
-- Executing [s@macro-user-callerid:5] Set("PJSIP/901-00000032", "CHANEXTEN=901-00000032") in new stack
-- Executing [s@macro-user-callerid:6] Set("PJSIP/901-00000032", "CALLERID(number)=901") in new stack
-- Executing [s@macro-user-callerid:7] Set("PJSIP/901-00000032", "AMPUSER=901") in new stack
-- Executing [s@macro-user-callerid:8] Set("PJSIP/901-00000032", "HOTDESCKCHAN=901-00000032") in new stack
-- Executing [s@macro-user-callerid:9] Set("PJSIP/901-00000032", "HOTDESKEXTEN=901") in new stack
-- Executing [s@macro-user-callerid:10] Set("PJSIP/901-00000032", "HOTDESKCALL=0") in new stack
-- Executing [s@macro-user-callerid:11] ExecIf("PJSIP/901-00000032", "0?Set(HOTDESKCALL=1)") in new stack
-- Executing [s@macro-user-callerid:12] ExecIf("PJSIP/901-00000032", "0?Set(CALLERID(name)=)") in new stack
-- Executing [s@macro-user-callerid:13] GotoIf("PJSIP/901-00000032", "0?report") in new stack
-- Executing [s@macro-user-callerid:14] ExecIf("PJSIP/901-00000032", "1?Set(REALCALLERIDNUM=901)") in new stack
-- Executing [s@macro-user-callerid:15] Set("PJSIP/901-00000032", "AMPUSER=901") in new stack
-- Executing [s@macro-user-callerid:16] GotoIf("PJSIP/901-00000032", "0?limit") in new stack
-- Executing [s@macro-user-callerid:17] Set("PJSIP/901-00000032", "AMPUSERCIDNAME=901") in new stack
-- Executing [s@macro-user-callerid:18] ExecIf("PJSIP/901-00000032", "0?Set(__CIDMASQUERADING=TRUE)") in new stack
-- Executing [s@macro-user-callerid:19] GotoIf("PJSIP/901-00000032", "0?report") in new stack
-- Executing [s@macro-user-callerid:20] Set("PJSIP/901-00000032", "AMPUSERCID=901") in new stack
-- Executing [s@macro-user-callerid:21] Set("PJSIP/901-00000032", "__DIAL_OPTIONS=HhTtr") in new stack
-- Executing [s@macro-user-callerid:22] Set("PJSIP/901-00000032", "CALLERID(all)="901" <901>") in new stack
-- Executing [s@macro-user-callerid:23] ExecIf("PJSIP/901-00000032", "0?Set(CUSDIAL=)") in new stack
-- Executing [s@macro-user-callerid:24] ExecIf("PJSIP/901-00000032", "0?Set(CALLERID(all)="901" <901>)") in new stack
-- Executing [s@macro-user-callerid:25] GotoIf("PJSIP/901-00000032", "0?limit") in new stack
-- Executing [s@macro-user-callerid:26] ExecIf("PJSIP/901-00000032", "1?Set(GROUP(concurrency_limit)=901)") in new stack
-- Executing [s@macro-user-callerid:27] ExecIf("PJSIP/901-00000032", "1?Set(CHANNEL(language)=en)") in new stack
-- Executing [s@macro-user-callerid:28] NoOp("PJSIP/901-00000032", "Macro depricated!! To keep the same line numbers") in new stack
-- Executing [s@macro-user-callerid:29] NoOp("PJSIP/901-00000032", "Macro depricated !! To keep the same line numbers") in new stack
-- Executing [s@macro-user-callerid:30] GotoIf("PJSIP/901-00000032", "1?continue") in new stack
-- Goto (macro-user-callerid,s,49)
-- Executing [s@macro-user-callerid:49] Set("PJSIP/901-00000032", "CALLERID(number)=901") in new stack
-- Executing [s@macro-user-callerid:50] Set("PJSIP/901-00000032", "CALLERID(name)=901") in new stack
-- Executing [s@macro-user-callerid:51] GotoIf("PJSIP/901-00000032", "0?cnum") in new stack
-- Executing [s@macro-user-callerid:52] Set("PJSIP/901-00000032", "__MCNUM=901") in new stack
-- Executing [s@macro-user-callerid:53] Set("PJSIP/901-00000032", "__MCNAME=901") in new stack
-- Executing [s@macro-user-callerid:54] Set("PJSIP/901-00000032", "__MCEXTEN=901") in new stack
-- Executing [s@macro-user-callerid:55] Set("PJSIP/901-00000032", "__MCORGCHAN=PJSIP/901-00000032") in new stack
[2025-04-17 10:15:39] WARNING[269431][C-00000023]: taskprocessor.c:1225 taskprocessor_push: The 'stasis/pool-control' task processor queue reached 500 scheduled tasks again.
-- Executing [s@macro-user-callerid:56] Set("PJSIP/901-00000032", "CDR(cnam)=901") in new stack
-- Executing [s@macro-user-callerid:57] Set("PJSIP/901-00000032", "CDR(cnum)=901") in new stack
-- Executing [s@macro-user-callerid:58] Return("PJSIP/901-00000032", "") in new stack
[2025-04-17 10:15:39] WARNING[269431][C-00000023]: taskprocessor.c:1225 taskprocessor_push: The 'stasis/pool-control' task processor queue reached 500 scheduled tasks again.
-- Executing [0032470123456@from-internal:2] Gosub("PJSIP/901-00000032", "sub-record-check,s,1(out,0032470123456,dontcare)") in new stack
[2025-04-17 10:15:39] WARNING[269421]: taskprocessor.c:1225 taskprocessor_push: The 'stasis/pool-control' task processor queue reached 500 scheduled tasks again.
-- Executing [s@sub-record-check:1] GotoIf("PJSIP/901-00000032", "0?initialized") in new stack
-- Executing [s@sub-record-check:2] Set("PJSIP/901-00000032", "__REC_STATUS=INITIALIZED") in new stack
-- Executing [s@sub-record-check:3] Set("PJSIP/901-00000032", "NOW=1744877739") in new stack
-- Executing [s@sub-record-check:4] Set("PJSIP/901-00000032", "__DAY=17") in new stack
-- Executing [s@sub-record-check:5] Set("PJSIP/901-00000032", "__MONTH=04") in new stack
-- Executing [s@sub-record-check:6] Set("PJSIP/901-00000032", "__YEAR=2025") in new stack
-- Executing [s@sub-record-check:7] Set("PJSIP/901-00000032", "__TIMESTR=20250417-101539") in new stack
[2025-04-17 10:15:39] WARNING[269431][C-00000023]: taskprocessor.c:1225 taskprocessor_push: The 'stasis/pool-control' task processor queue reached 500 scheduled tasks again.
-- Executing [s@sub-record-check:8] Set("PJSIP/901-00000032", "__FROMEXTEN=901") in new stack
-- Executing [s@sub-record-check:9] Set("PJSIP/901-00000032", "__MON_FMT=wav") in new stack
-- Executing [s@sub-record-check:10] NoOp("PJSIP/901-00000032", "Recordings initialized") in new stack
-- Executing [s@sub-record-check:11] ExecIf("PJSIP/901-00000032", "0?Set(ARG3=dontcare)") in new stack
-- Executing [s@sub-record-check:12] Set("PJSIP/901-00000032", "REC_POLICY_MODE_SAVE=") in new stack
-- Executing [s@sub-record-check:13] ExecIf("PJSIP/901-00000032", "0?Set(REC_STATUS=NO)") in new stack
-- Executing [s@sub-record-check:14] GotoIf("PJSIP/901-00000032", "3?checkaction") in new stack
-- Goto (sub-record-check,s,17)
-- Executing [s@sub-record-check:17] GotoIf("PJSIP/901-00000032", "1?sub-record-check,out,1") in new stack
-- Goto (sub-record-check,out,1)
-- Executing [out@sub-record-check:1] NoOp("PJSIP/901-00000032", "Outbound Recording Check from 901 to 0032470123456") in new stack
-- Executing [out@sub-record-check:2] Set("PJSIP/901-00000032", "RECMODE=dontcare") in new stack
-- Executing [out@sub-record-check:3] ExecIf("PJSIP/901-00000032", "1?Goto(routewins)") in new stack
-- Goto (sub-record-check,out,7)
-- Executing [out@sub-record-check:7] Gosub("PJSIP/901-00000032", "recordcheck,1(dontcare,out,0032470123456)") in new stack
-- Executing [recordcheck@sub-record-check:1] NoOp("PJSIP/901-00000032", "Starting recording check against dontcare") in new stack
-- Executing [recordcheck@sub-record-check:2] Goto("PJSIP/901-00000032", "dontcare") in new stack
-- Goto (sub-record-check,recordcheck,3)
-- Executing [recordcheck@sub-record-check:3] Return("PJSIP/901-00000032", "") in new stack
[2025-04-17 10:15:39] WARNING[269431][C-00000023]: taskprocessor.c:1225 taskprocessor_push: The 'stasis/pool-control' task processor queue reached 500 scheduled tasks again.
-- Executing [out@sub-record-check:8] Return("PJSIP/901-00000032", "") in new stack
-- Executing [0032470123456@from-internal:3] ExecIf("PJSIP/901-00000032", "0 ?Set(CHANNEL(accountcode)=)") in new stack
-- Executing [0032470123456@from-internal:4] Set("PJSIP/901-00000032", "_ROUTEID=1") in new stack
-- Executing [0032470123456@from-internal:5] Set("PJSIP/901-00000032", "_ROUTENAME=BE-mobile") in new stack
-- Executing [0032470123456@from-internal:6] Set("PJSIP/901-00000032", "MOHCLASS=default") in new stack
-- Executing [0032470123456@from-internal:7] ExecIf("PJSIP/901-00000032", "1?Set(TRUNKCIDOVERRIDE=003227654321)") in new stack
-- Executing [0032470123456@from-internal:8] Set("PJSIP/901-00000032", "_CALLERIDNAMEINTERNAL=901") in new stack
-- Executing [0032470123456@from-internal:9] Set("PJSIP/901-00000032", "_CALLERIDNUMINTERNAL=901") in new stack
-- Executing [0032470123456@from-internal:10] Set("PJSIP/901-00000032", "_EMAILNOTIFICATION=FALSE") in new stack
-- Executing [0032470123456@from-internal:11] Set("PJSIP/901-00000032", "_NODEST=") in new stack
-- Executing [0032470123456@from-internal:12] Gosub("PJSIP/901-00000032", "macro-dialout-trunk,s,1(3,0032470123456,,off)") in new stack
-- Executing [s@macro-dialout-trunk:1] Set("PJSIP/901-00000032", "DIAL_TRUNK=3") in new stack
-- Executing [s@macro-dialout-trunk:2] ExecIf("PJSIP/901-00000032", "0?Set(DIAL_OPTIONS=Hhtr)") in new stack
-- Executing [s@macro-dialout-trunk:3] ExecIf("PJSIP/901-00000032", "0?Set(DIAL_OPTIONS=HhTr)") in new stack
-- Executing [s@macro-dialout-trunk:4] ExecIf("PJSIP/901-00000032", "0?Set(DIAL_OPTIONS=Hhtr)") in new stack
-- Executing [s@macro-dialout-trunk:5] GosubIf("PJSIP/901-00000032", "0?sub-pincheck,s,1()") in new stack
-- Executing [s@macro-dialout-trunk:6] ExecIf("PJSIP/901-00000032", "0?Set(CALLERID(num)=901)") in new stack
-- Executing [s@macro-dialout-trunk:7] GotoIf("PJSIP/901-00000032", "0?disabletrunk,1") in new stack
-- Executing [s@macro-dialout-trunk:8] Set("PJSIP/901-00000032", "DIAL_NUMBER=0032470123456") in new stack
-- Executing [s@macro-dialout-trunk:9] Set("PJSIP/901-00000032", "DIAL_TRUNK_OPTIONS=HhTtr") in new stack
-- Executing [s@macro-dialout-trunk:10] Set("PJSIP/901-00000032", "OUTBOUND_GROUP=OUT_3") in new stack
-- Executing [s@macro-dialout-trunk:11] Set("PJSIP/901-00000032", "DIAL_TRUNK_OPTIONS=T") in new stack
-- Executing [s@macro-dialout-trunk:12] ExecIf("PJSIP/901-00000032", "0?Set(DIAL_TRUNK_OPTIONS=)") in new stack
-- Executing [s@macro-dialout-trunk:13] GotoIf("PJSIP/901-00000032", "1?nomax") in new stack
-- Goto (macro-dialout-trunk,s,15)
-- Executing [s@macro-dialout-trunk:15] GotoIf("PJSIP/901-00000032", "0?skipoutcid") in new stack
-- Executing [s@macro-dialout-trunk:16] Gosub("PJSIP/901-00000032", "macro-outbound-callerid,s,1(3)") in new stack
[2025-04-17 10:15:39] WARNING[269431][C-00000023]: taskprocessor.c:1225 taskprocessor_push: The 'stasis/pool-control' task processor queue reached 500 scheduled tasks again.
-- Executing [s@macro-outbound-callerid:1] NoOp("PJSIP/901-00000032", "901") in new stack
-- Executing [s@macro-outbound-callerid:2] NoOp("PJSIP/901-00000032", "") in new stack
[2025-04-17 10:15:39] WARNING[269431][C-00000023]: taskprocessor.c:1225 taskprocessor_push: The 'stasis/pool-control' task processor queue reached 500 scheduled tasks again.
-- Executing [s@macro-outbound-callerid:3] NoOp("PJSIP/901-00000032", "off") in new stack
-- Executing [s@macro-outbound-callerid:4] ExecIf("PJSIP/901-00000032", "0?Set(CALLERID(name-pres)=)") in new stack
-- Executing [s@macro-outbound-callerid:5] ExecIf("PJSIP/901-00000032", "0?Set(CALLERID(num-pres)=)") in new stack
-- Executing [s@macro-outbound-callerid:6] Set("PJSIP/901-00000032", "HOTDESCKCHAN=901-00000032") in new stack
-- Executing [s@macro-outbound-callerid:7] Set("PJSIP/901-00000032", "HOTDESKEXTEN=901") in new stack
-- Executing [s@macro-outbound-callerid:8] Set("PJSIP/901-00000032", "HOTDESKCALL=0") in new stack
-- Executing [s@macro-outbound-callerid:9] ExecIf("PJSIP/901-00000032", "0?Set(HOTDESKCALL=1)") in new stack
-- Executing [s@macro-outbound-callerid:10] ExecIf("PJSIP/901-00000032", "0?Set(CALLERID(name)=)") in new stack
-- Executing [s@macro-outbound-callerid:11] Set("PJSIP/901-00000032", "ALLOWTHISROUTE=NO") in new stack
-- Executing [s@macro-outbound-callerid:12] ExecIf("PJSIP/901-00000032", "0?Set(ALLOWTHISROUTE=YES)") in new stack
-- Executing [s@macro-outbound-callerid:13] ExecIf("PJSIP/901-00000032", "0?Hangup()") in new stack
-- Executing [s@macro-outbound-callerid:14] ExecIf("PJSIP/901-00000032", "0?Set(REALCALLERIDNUM=901)") in new stack
-- Executing [s@macro-outbound-callerid:15] ExecIf("PJSIP/901-00000032", "0?Set(AMPUSER=901)") in new stack
-- Executing [s@macro-outbound-callerid:16] GotoIf("PJSIP/901-00000032", "1?normcid") in new stack
-- Goto (macro-outbound-callerid,s,20)
-- Executing [s@macro-outbound-callerid:20] Set("PJSIP/901-00000032", "USEROUTCID=") in new stack
-- Executing [s@macro-outbound-callerid:21] Set("PJSIP/901-00000032", "EMERGENCYCID=") in new stack
-- Executing [s@macro-outbound-callerid:22] ExecIf("PJSIP/901-00000032", "0?Set(EMERGENCYCID=)") in new stack
-- Executing [s@macro-outbound-callerid:23] Set("PJSIP/901-00000032", "TRUNKOUTCID=") in new stack
-- Executing [s@macro-outbound-callerid:24] GotoIf("PJSIP/901-00000032", "1?trunkcid") in new stack
-- Goto (macro-outbound-callerid,s,30)
-- Executing [s@macro-outbound-callerid:30] ExecIf("PJSIP/901-00000032", "0?Set(CALLERID(all)=)") in new stack
-- Executing [s@macro-outbound-callerid:31] ExecIf("PJSIP/901-00000032", "0?Set(CALLERID(all)=)") in new stack
-- Executing [s@macro-outbound-callerid:32] ExecIf("PJSIP/901-00000032", "1?Set(CALLERID(all)=003227654321)") in new stack
-- Executing [s@macro-outbound-callerid:33] ExecIf("PJSIP/901-00000032", "0?Set(CALLERID(all)=901)") in new stack
-- Executing [s@macro-outbound-callerid:34] ExecIf("PJSIP/901-00000032", "0?Set(CALLERID(all)=901)") in new stack
-- Executing [s@macro-outbound-callerid:35] Set("PJSIP/901-00000032", "TIOHIDE=no") in new stack
-- Executing [s@macro-outbound-callerid:36] ExecIf("PJSIP/901-00000032", "0?Set(CALLERID(name-pres)=prohib_passed_screen)") in new stack
-- Executing [s@macro-outbound-callerid:37] ExecIf("PJSIP/901-00000032", "0?Set(CALLERID(num-pres)=prohib_passed_screen)") in new stack
-- Executing [s@macro-outbound-callerid:38] ExecIf("PJSIP/901-00000032", "0?Set(CALLERID(name-pres)=prohib_passed_screen)") in new stack
-- Executing [s@macro-outbound-callerid:39] ExecIf("PJSIP/901-00000032", "0?Set(CALLERID(num-pres)=prohib_passed_screen)") in new stack
-- Executing [s@macro-outbound-callerid:40] Set("PJSIP/901-00000032", "CDR(outbound_cnum)=003227654321") in new stack
-- Executing [s@macro-outbound-callerid:41] Set("PJSIP/901-00000032", "CDR(outbound_cnam)=") in new stack
-- Executing [s@macro-outbound-callerid:42] Return("PJSIP/901-00000032", "") in new stack
-- Executing [s@macro-dialout-trunk:17] GosubIf("PJSIP/901-00000032", "0?sub-flp-3,s,1()") in new stack
-- Executing [s@macro-dialout-trunk:18] Set("PJSIP/901-00000032", "OUTNUM=0032470123456") in new stack
-- Executing [s@macro-dialout-trunk:19] Set("PJSIP/901-00000032", "custom=PJSIP") in new stack
-- Executing [s@macro-dialout-trunk:20] ExecIf("PJSIP/901-00000032", "0?Set(DIAL_TRUNK_MOH=default)") in new stack
-- Executing [s@macro-dialout-trunk:21] ExecIf("PJSIP/901-00000032", "0?Set(DIAL_TRUNK_OPTIONS=TU(macro-confirm))") in new stack
-- Executing [s@macro-dialout-trunk:22] ExecIf("PJSIP/901-00000032", "0?AGI(allowlist-autoadd.agi,)") in new stack
-- Executing [s@macro-dialout-trunk:23] Gosub("PJSIP/901-00000032", "macro-dialout-trunk-predial-hook,s,1()") in new stack
-- Executing [s@macro-dialout-trunk-predial-hook:1] Return("PJSIP/901-00000032", "") in new stack
[2025-04-17 10:15:39] WARNING[269431][C-00000023]: taskprocessor.c:1225 taskprocessor_push: The 'stasis/pool-control' task processor queue reached 500 scheduled tasks again.
-- Executing [s@macro-dialout-trunk:24] GotoIf("PJSIP/901-00000032", "0?skipcrm") in new stack
-- Executing [s@macro-dialout-trunk:25] Set("PJSIP/901-00000032", "__CRM_DIRECTION=OUTBOUND") in new stack
-- Executing [s@macro-dialout-trunk:26] Set("PJSIP/901-00000032", "__CRM_DESTINATION=0032470123456") in new stack
-- Executing [s@macro-dialout-trunk:27] Set("PJSIP/901-00000032", "__CRM_SOURCE=901") in new stack
-- Executing [s@macro-dialout-trunk:28] AGI("PJSIP/901-00000032", "agi://127.0.0.1/sangomacrm.agi") in new stack
-- <PJSIP/901-00000032>AGI Script agi://127.0.0.1/sangomacrm.agi completed, returning 0
-- Executing [s@macro-dialout-trunk:29] Set("PJSIP/901-00000032", "CHANNEL(hangup_handler_push)=crm-hangup,s,1") in new stack
-- Executing [s@macro-dialout-trunk:30] NoOp("PJSIP/901-00000032", "CRM Finished") in new stack
-- Executing [s@macro-dialout-trunk:31] GotoIf("PJSIP/901-00000032", "0?bypass,1") in new stack
-- Executing [s@macro-dialout-trunk:32] ExecIf("PJSIP/901-00000032", "1?Set(CONNECTEDLINE(num,i)=0032470123456)") in new stack
-- Executing [s@macro-dialout-trunk:33] ExecIf("PJSIP/901-00000032", "1?Set(CONNECTEDLINE(name,i)=CID:003227654321)") in new stack
-- Executing [s@macro-dialout-trunk:34] ExecIf("PJSIP/901-00000032", "0?Set(CONNECTEDLINE(name,i)=CID:(Hidden)003227654321)") in new stack
-- Executing [s@macro-dialout-trunk:35] GotoIf("PJSIP/901-00000032", "0?customtrunk") in new stack
-- Executing [s@macro-dialout-trunk:36] ExecIf("PJSIP/901-00000032", "0?Set(DIAL_TRUNK_OPTIONS=)") in new stack
-- Executing [s@macro-dialout-trunk:37] Set("PJSIP/901-00000032", "HASH(__SIPHEADERS,Alert-Info)=unset") in new stack
-- Executing [s@macro-dialout-trunk:38] Gosub("PJSIP/901-00000032", "trunk-dial-with-exten,0032470123456,1()") in new stack
-- Executing [0032470123456@trunk-dial-with-exten:1] Dial("PJSIP/901-00000032", "PJSIP/0032470123456@poivy,300,Tb(func-apply-sipheaders^s^1,(3))U(sub-send-obroute-email^0032470123456^^3^1744877739^^003227654321,^)") in new stack
[2025-04-17 10:15:40] WARNING[269431][C-00000023]: taskprocessor.c:1225 taskprocessor_push: The 'stasis/pool-control' task processor queue reached 500 scheduled tasks again.
-- PJSIP/poivy-00000033 Internal Gosub(func-apply-sipheaders,s,1(3)) start
-- Executing [s@func-apply-sipheaders:1] ExecIf("PJSIP/poivy-00000033", "0?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
-- Executing [s@func-apply-sipheaders:2] NoOp("PJSIP/poivy-00000033", "Applying SIP Headers to channel PJSIP/poivy-00000033") in new stack
-- Executing [s@func-apply-sipheaders:3] Set("PJSIP/poivy-00000033", "localchan=poivy-00000033") in new stack
-- Executing [s@func-apply-sipheaders:4] Set("PJSIP/poivy-00000033", "DialMCEXT=poivy") in new stack
-- Executing [s@func-apply-sipheaders:5] Set("PJSIP/poivy-00000033", "CHANNEL(hangup_handler_push)=app-missedcall-hangup,poivy,1") in new stack
[2025-04-17 10:15:40] WARNING[269431][C-00000023]: taskprocessor.c:1225 taskprocessor_push: The 'stasis/pool-control' task processor queue reached 500 scheduled tasks again.
-- Executing [s@func-apply-sipheaders:6] Set("PJSIP/poivy-00000033", "Dchan=poivy-00000033") in new stack
-- Executing [s@func-apply-sipheaders:7] Set("PJSIP/poivy-00000033", "TECH=PJSIP") in new stack
-- Executing [s@func-apply-sipheaders:8] Set("PJSIP/poivy-00000033", "SIPHEADERKEYS=Alert-Info") in new stack
-- Executing [s@func-apply-sipheaders:9] While("PJSIP/poivy-00000033", "1") in new stack
[2025-04-17 10:15:40] WARNING[269431][C-00000023]: taskprocessor.c:1225 taskprocessor_push: The 'stasis/pool-control' task processor queue reached 500 scheduled tasks again.
-- Executing [s@func-apply-sipheaders:10] Set("PJSIP/poivy-00000033", "sipheader=unset") in new stack
[2025-04-17 10:15:40] WARNING[269431][C-00000023]: taskprocessor.c:1225 taskprocessor_push: The 'stasis/pool-control' task processor queue reached 500 scheduled tasks again.
-- Executing [s@func-apply-sipheaders:11] ExecIf("PJSIP/poivy-00000033", "1?Set(PJSIP_HEADER(remove,Alert-Info)=)") in new stack
[2025-04-17 10:15:40] ERROR[95287]: res_pjsip_header_funcs.c:726 remove_header: No headers had been previously added to this session.
-- Executing [s@func-apply-sipheaders:12] ExecIf("PJSIP/poivy-00000033", "0?Set(sipheader=<http://127.0.0.1>;info=unset)") in new stack
-- Executing [s@func-apply-sipheaders:13] ExecIf("PJSIP/poivy-00000033", "0?Set(sipheader=<http://127.0.0.1>unset)") in new stack
-- Executing [s@func-apply-sipheaders:14] ExecIf("PJSIP/poivy-00000033", "0?Set(PJSIP_HEADER(add,Alert-Info)=unset)") in new stack
-- Executing [s@func-apply-sipheaders:15] EndWhile("PJSIP/poivy-00000033", "") in new stack
-- Executing [s@func-apply-sipheaders:9] While("PJSIP/poivy-00000033", "0") in new stack
-- Executing [s@func-apply-sipheaders:16] Return("PJSIP/poivy-00000033", "") in new stack
[2025-04-17 10:15:40] WARNING[269431][C-00000023]: taskprocessor.c:1225 taskprocessor_push: The 'stasis/pool-control' task processor queue reached 500 scheduled tasks again.
== Spawn extension (from-pstn, 0032470123456, 1) exited non-zero on 'PJSIP/poivy-00000033'
-- PJSIP/poivy-00000033 Internal Gosub(func-apply-sipheaders,s,1(3)) complete GOSUB_RETVAL=
-- Called PJSIP/0032470123456@poivy
-- PJSIP/poivy-00000033 is making progress passing it to PJSIP/901-00000032
-- PJSIP/poivy-00000033 Internal Gosub(app-missedcall-hangup,poivy,1) start
-- Executing [poivy@app-missedcall-hangup:1] NoOp("PJSIP/poivy-00000033", "Dialed: poivy") in new stack
-- Executing [poivy@app-missedcall-hangup:2] NoOp("PJSIP/poivy-00000033", "Caller: 901") in new stack
-- Executing [poivy@app-missedcall-hangup:3] GotoIf("PJSIP/poivy-00000033", "0?exit") in new stack
-- Executing [poivy@app-missedcall-hangup:4] Set("PJSIP/poivy-00000033", "EXTENNUM=poivy") in new stack
-- Executing [poivy@app-missedcall-hangup:5] Set("PJSIP/poivy-00000033", "FEXTENNUM=poivy") in new stack
-- Executing [poivy@app-missedcall-hangup:6] GotoIf("PJSIP/poivy-00000033", "0?exit") in new stack
-- Executing [poivy@app-missedcall-hangup:7] AGI("PJSIP/poivy-00000033", "agi://127.0.0.1/missedcallnotify.php,poivy,,poivy,0,,PJSIP/poivy-00000033,,,,") in new stack
-- <PJSIP/poivy-00000033>AGI Script agi://127.0.0.1/missedcallnotify.php completed, returning 0
-- Executing [poivy@app-missedcall-hangup:8] Return("PJSIP/poivy-00000033", "") in new stack
== Spawn extension (from-pstn, 0032470123456, 1) exited non-zero on 'PJSIP/poivy-00000033'
-- PJSIP/poivy-00000033 Internal Gosub(app-missedcall-hangup,poivy,1) complete GOSUB_RETVAL=
-- No one is available to answer at this time (1:0/0/0)
-- Executing [0032470123456@trunk-dial-with-exten:2] Return("PJSIP/901-00000032", "") in new stack
-- Executing [s@macro-dialout-trunk:39] NoOp("PJSIP/901-00000032", "Dial failed for some reason with DIALSTATUS = NOANSWER and HANGUPCAUSE = 19") in new stack
-- Executing [s@macro-dialout-trunk:40] GotoIf("PJSIP/901-00000032", "0?continue,1:s-NOANSWER,1") in new stack
-- Goto (macro-dialout-trunk,s-NOANSWER,1)
-- Executing [s-NOANSWER@macro-dialout-trunk:1] NoOp("PJSIP/901-00000032", "Dial failed due to trunk reporting NOANSWER - giving up") in new stack
-- Executing [s-NOANSWER@macro-dialout-trunk:2] Progress("PJSIP/901-00000032", "") in new stack
-- Executing [s-NOANSWER@macro-dialout-trunk:3] Playback("PJSIP/901-00000032", "number-not-answering,noanswer") in new stack
-- <PJSIP/901-00000032> Playing 'number-not-answering.ulaw' (language 'en')
== Spawn extension (macro-dialout-trunk, s-NOANSWER, 3) exited non-zero on 'PJSIP/901-00000032'
-- Executing [h@macro-dialout-trunk:1] Gosub("PJSIP/901-00000032", "macro-hangupcall,s,1()") in new stack
-- Executing [s@macro-hangupcall:1] Set("PJSIP/901-00000032", "__MCVMSTATUS=") in new stack
-- Executing [s@macro-hangupcall:2] Gosub("PJSIP/901-00000032", "app-missedcall-hangup,s,1()") in new stack
-- Executing [s@app-missedcall-hangup:1] NoOp("PJSIP/901-00000032", "Dialed: s") in new stack
-- Executing [s@app-missedcall-hangup:2] NoOp("PJSIP/901-00000032", "Caller: 901") in new stack
-- Executing [s@app-missedcall-hangup:3] GotoIf("PJSIP/901-00000032", "0?exit") in new stack
-- Executing [s@app-missedcall-hangup:4] Set("PJSIP/901-00000032", "EXTENNUM=s") in new stack
-- Executing [s@app-missedcall-hangup:5] Set("PJSIP/901-00000032", "FEXTENNUM=s") in new stack
-- Executing [s@app-missedcall-hangup:6] GotoIf("PJSIP/901-00000032", "0?exit") in new stack
-- Executing [s@app-missedcall-hangup:7] AGI("PJSIP/901-00000032", "agi://127.0.0.1/missedcallnotify.php,s,,s,0,,PJSIP/901-00000032,NOANSWER,,,") in new stack
-- <PJSIP/901-00000032>AGI Script agi://127.0.0.1/missedcallnotify.php completed, returning 0
-- Executing [s@app-missedcall-hangup:8] Return("PJSIP/901-00000032", "") in new stack
-- Executing [s@macro-hangupcall:3] UserEvent("PJSIP/901-00000032", "MES,RTPAUDIOQOSMESBRIDGED:,RTPAUDIOQOSMES: minrxmes=000.000000; maxrxmes=000.000000; avgrxmes=000.000000; stdevrxmes=000.011986; mintxmes=000.000000; maxtxmes=000.000000; avgtxmes=000.000000; stdevtxmes=000.000000;") in new stack
-- Executing [s@macro-hangupcall:4] GotoIf("PJSIP/901-00000032", "1?theend") in new stack
-- Goto (macro-hangupcall,s,6)
-- Executing [s@macro-hangupcall:6] ExecIf("PJSIP/901-00000032", "0?Set(CDR(recordingfile)=)") in new stack
-- Executing [s@macro-hangupcall:7] Hangup("PJSIP/901-00000032", "") in new stack
== Spawn extension (macro-hangupcall, s, 7) exited non-zero on 'PJSIP/901-00000032'
-- PJSIP/901-00000032 Internal Gosub(crm-hangup,s,1) start
-- Executing [s@crm-hangup:1] NoOp("PJSIP/901-00000032", "Sending Hangup to CRM") in new stack
-- Executing [s@crm-hangup:2] NoOp("PJSIP/901-00000032", "HANGUP CAUSE: 19") in new stack
-- Executing [s@crm-hangup:3] ExecIf("PJSIP/901-00000032", "0?Set(__CRM_VOICEMAIL=)") in new stack
-- Executing [s@crm-hangup:4] NoOp("PJSIP/901-00000032", "MASTER CHANNEL: 1744877739.88 = 1744877739.88") in new stack
-- Executing [s@crm-hangup:5] GotoIf("PJSIP/901-00000032", "0?return") in new stack
-- Executing [s@crm-hangup:6] Set("PJSIP/901-00000032", "__CRM_HANGUP=1") in new stack
-- Executing [s@crm-hangup:7] AGI("PJSIP/901-00000032", "agi://127.0.0.1/sangomacrm.agi") in new stack
-- <PJSIP/901-00000032>AGI Script agi://127.0.0.1/sangomacrm.agi completed, returning 0
-- Executing [s@crm-hangup:8] Return("PJSIP/901-00000032", "") in new stack
== Spawn extension (macro-hangupcall, s, 7) exited non-zero on 'PJSIP/901-00000032'
-- PJSIP/901-00000032 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
You really need to have “pjsip set logger on” in effect, and it would help to use the log file, not a screen scrape, as the former contains time information, and this was terminated because a time limit expired.
However, you got Progress from them, but no final response, so it looks like they simply didn’t answer.
Thanks for your reply. Maybe this already helps. I will prepare a log-file
<--- Transmitting SIP request (420 bytes) to UDP:77.72.169.131:5060 --->
OPTIONS sip:[myuserid]@sip.poivy.com SIP/2.0
Via: SIP/2.0/UDP [myipaddr]:5060;rport;branch=z9hG4bKPj97099dfe-ecbb-44a0-b739-ddd31de329bc
From: <sip:[myuserid]@poivy.com>;tag=59e59ad0-95af-4cdf-9c53-b401db7d273c
To: <sip:[myuserid]@sip.poivy.com>
Contact: <sip:[myuserid]@[myipaddr]:5060>
Call-ID: 1af2084f-d48b-4020-9701-7f73791603f7
CSeq: 64295 OPTIONS
Max-Forwards: 70
User-Agent: FPBX-17.0.19.25(22.2.0)
Content-Length: 0
<--- Received SIP response (467 bytes) from UDP:77.72.169.131:5060 --->
SIP/2.0 200 Ok
Via: SIP/2.0/UDP [myipaddr]:5060;rport;branch=z9hG4bKPj97099dfe-ecbb-44a0-b739-ddd31de329bc
From: <sip:[myuserid]@poivy.com>;tag=59e59ad0-95af-4cdf-9c53-b401db7d273c
To: <sip:[myuserid]@sip.poivy.com>
Contact: sip:172.19.13.229:5060
Call-ID: 1af2084f-d48b-4020-9701-7f73791603f7
CSeq: 64295 OPTIONS
Supported: foo
User-Agent: (Very nice Sip Registrar/Proxy Server)
Allow: ACK,BYE,CANCEL,INVITE,REGISTER,OPTIONS,INFO,MESSAGE
Accept: application/sdp
Here’s a log. It seems it is not possible to atach it as a file, hope this works. I probably included way too much information, but wanted to avoid leaving important information out.
``
They responded with the following:
[2025-04-17 14:27:16] VERBOSE[2149] res_pjsip_logger.c: <--- Received SIP response (510 bytes) from UDP:77.72.169.131:5060 --->
SIP/2.0 480 Temporarily not available
Via: SIP/2.0/UDP [myipaddr]:5060;rport;branch=z9hG4bKPja43b9aca-290e-4f78-88a1-93547a4570ac
From: <sip:[myuserid]@poivy.com>;tag=299662a3-7f5d-42cb-a30f-1a76a8f26f34
To: <sip:[email protected]>;tag=e50d13ac67d79e2e14664
Contact: sip:[email protected]:5060
Call-ID: 35700d66-7919-4733-a587-76e3a9d1499a
CSeq: 8375 INVITE
Server: (Very nice Sip Registrar/Proxy Server)
Allow: ACK,BYE,CANCEL,INVITE,REGISTER,OPTIONS,INFO,MESSAGE
Content-Length: 0
Not sure what they mean by 480.
Also, You seem to have a couple of codecs (including video) which they don’t support, try adding only supported codecs.
Codecs
G.711
Hello,
In Report → Asterik info → Registries → PJSIP
What are the statut of your trunk, if it is not registered there is a problem.
Can you also show me your outbound route, one time I also got a similar problem and resolved it by correcting the routes.
Under peers I see:
Endpoint: poivy Not in use 0 of inf
OutAuth: poivy/arncr
Aor: poivy 0
Contact: poivy/sip:[email protected] dd3c3f38b6 Avail 31.961
Transport: 0.0.0.0-udp udp 3 96 0.0.0.0:5060
Identify: poivy/poivy
Match: 77.72.169.134/32
Match: 77.72.169.131/32
And under Registries:
<Registration/ServerURI..............................> <Auth....................> <Status.......>
==========================================================================================
poivy/sip:sip.poivy.com poivy Registered (exp. 3350s)
Objects found: 1
The outbound route is very basic.
It’s a normal response. They could just be passing back what they got from the other side but 480 generally is a response from phones in DND mode. It can also be used to indicate the destination was tried, they responded with 1XX provisional responses but then never picked up or the call was not answered by voicemail, etc. The user could also be offline.
@Belgier There’s nothing wrong with this call technical wise. I’d asked the provider why you are getting back a 480 from them.
I found the cause of the problem. With the various Dellmont providers you can register multiple numbers (you need to verify you “own” them) to use as CID numbers. In my outbound routes I was setting one of those verified numbers as “source” of my outgoing calls. The test I performed was an outbound call to a number I also had registered and verified as CID number. While this was possible in the past, it is apparently no longer allowed.
Calling any other number works fine.
Thanks for all the support I received. It helped me to find the root-cause of the problem.
This topic was automatically closed 7 days after the last reply. New replies are no longer allowed.