Calls only to my cell phone failing

braden · February 2, 2016, 2:59am

Hi guys - weird situation here.

My FreePBX is up and running perfectly, with one small exception. I can receive calls and place outbound calls to just about any number (including other cell phone #'s) - except my own personal cellphone (on the Rogers network in Canada).

There are 3 scenarios that happen:

The call goes through perfectly
The call kinda goes through - rings once on my cell (shows as missed call), then rings again (shows as a second missed call), then my cell phone voicemail picks up
The call rings maybe once on my cell (shows as missed call), then asterisk kicks in and says “The number is not answering”

I have taken the logs and literally compared each of those scenarios line by line. 1 and 2 are identical. 3 obviously differs slightly at the end. The 3 logs are shown below.

There are 2 different errors/warnings I am seeing, not sure if they’re affecting this situation. Seems very odd to me.
error 1 - shown within the call always as
`WARNING[5206] func_cdr.c: CDR requires a value (CDR(variable)=value)

error 2 - non-critical invite transaction timeouts in between calls, for example
eg. Timeout on 4c5d42746791a5694d3b7b7c09248a30 on non-critical invite transaction.

(logs edited slightly to remove my #'s and the timestamps so I could compare line by line in Excel.

1 - Successful call to my cell and 2 - partial ringing, “missed calls”, then answered by my cellphone voicemail
pbx.c: Executing [mycell#@from-internal:1] Macro(“SIP/203”, “user-callerid,LIMIT,EXTERNAL,”) in new stack pbx.c: Executing [s@macro-user-callerid:1] Set(“SIP/203”, “TOUCH_MONITOR=1454376918.12”) in new stack pbx.c: Executing [s@macro-user-callerid:2] Set(“SIP/203”, “AMPUSER=203”) in new stack pbx.c: Executing [s@macro-user-callerid:3] GotoIf(“SIP/203”, “0?report”) in new stack pbx.c: Executing [s@macro-user-callerid:4] ExecIf(“SIP/203”, “1?Set(REALCALLERIDNUM=203)”) in new stack pbx.c: Executing [s@macro-user-callerid:5] Set(“SIP/203”, “AMPUSER=203”) in new stack pbx.c: Executing [s@macro-user-callerid:6] GotoIf(“SIP/203”, “0?limit”) in new stack pbx.c: Executing [s@macro-user-callerid:7] Set(“SIP/203”, “AMPUSERCIDNAME=BradenMBP”) in new stack pbx.c: Executing [s@macro-user-callerid:8] GotoIf(“SIP/203”, “0?report”) in new stack pbx.c: Executing [s@macro-user-callerid:9] Set(“SIP/203”, “AMPUSERCID=203”) in new stack pbx.c: Executing [s@macro-user-callerid:10] Set(“SIP/203”, “__DIAL_OPTIONS=Ttr”) in new stack pbx.c: Executing [s@macro-user-callerid:11] Set(“SIP/203”, “CALLERID(all)=“BradenMBP” <203>”) in new stack pbx.c: Executing [s@macro-user-callerid:12] GotoIf(“SIP/203”, “0?limit”) in new stack pbx.c: Executing [s@macro-user-callerid:13] ExecIf(“SIP/203”, “1?Set(GROUP(concurrency_limit)=203)”) in new stack pbx.c: Executing [s@macro-user-callerid:14] GotoIf(“SIP/203”, “1?continue”) in new stack pbx.c: Goto (macro-user-callerid,s,27) pbx.c: Executing [s@macro-user-callerid:27] Set(“SIP/203”, “CALLERID(number)=203”) in new stack pbx.c: Executing [s@macro-user-callerid:28] Set(“SIP/203”, “CALLERID(name)=BradenMBP”) in new stack pbx.c: Executing [s@macro-user-callerid:29] Set(“SIP/203”, “CDR(cnum)=203”) in new stack pbx.c: Executing [s@macro-user-callerid:30] Set(“SIP/203”, “CDR(cnam)=BradenMBP”) in new stack pbx.c: Executing [s@macro-user-callerid:31] Set(“SIP/203”, “CHANNEL(language)=en”) in new stack pbx.c: Executing [mycell#@from-internal:2] Gosub(“SIP/203”, “sub-record-check,s,1(out,mycell#,dontcare)”) in new stack pbx.c: Executing [s@sub-record-check:1] GotoIf(“SIP/203”, “0?initialized”) in new stack pbx.c: Executing [s@sub-record-check:2] Set(“SIP/203”, “__REC_STATUS=INITIALIZED”) in new stack pbx.c: Executing [s@sub-record-check:3] Set(“SIP/203”, “NOW=1454376918”) in new stack pbx.c: Executing [s@sub-record-check:4] Set(“SIP/203”, “__DAY=01”) in new stack pbx.c: Executing [s@sub-record-check:5] Set(“SIP/203”, “__MONTH=02”) in new stack pbx.c: Executing [s@sub-record-check:6] Set(“SIP/203”, “__YEAR=2016”) in new stack pbx.c: Executing [s@sub-record-check:7] Set(“SIP/203”, “__TIMESTR=20160201-203518”) in new stack pbx.c: Executing [s@sub-record-check:8] Set(“SIP/203”, “__FROMEXTEN=203”) in new stack pbx.c: Executing [s@sub-record-check:9] Set(“SIP/203”, “__MON_FMT=wav”) in new stack pbx.c: Executing [s@sub-record-check:10] NoOp(“SIP/203”, “Recordings initialized”) in new stack pbx.c: Executing [s@sub-record-check:11] ExecIf(“SIP/203”, “0?Set(ARG3=dontcare)”) in new stack pbx.c: Executing [s@sub-record-check:12] Set(“SIP/203”, “REC_POLICY_MODE_SAVE=”) in new stack pbx.c: Executing [s@sub-record-check:13] ExecIf(“SIP/203”, “0?Set(REC_STATUS=NO)”) in new stack pbx.c: Executing [s@sub-record-check:14] GotoIf(“SIP/203”, “3?checkaction”) in new stack pbx.c: Goto (sub-record-check,s,17) pbx.c: Executing [s@sub-record-check:17] GotoIf(“SIP/203”, “1?sub-record-check,out,1”) in new stack pbx.c: Goto (sub-record-check,out,1) pbx.c: Executing [out@sub-record-check:1] NoOp(“SIP/203”, “Outbound Recording Check from 203 to mycell#”) in new stack pbx.c: Executing [out@sub-record-check:2] Set(“SIP/203”, “RECMODE=dontcare”) in new stack pbx.c: Executing [out@sub-record-check:3] ExecIf(“SIP/203”, “1?Goto(routewins)”) in new stack pbx.c: Goto (sub-record-check,out,7) pbx.c: Executing [out@sub-record-check:7] Gosub(“SIP/203”, “recordcheck,1(dontcare,out,mycell#)”) in new stack pbx.c: Executing [recordcheck@sub-record-check:1] NoOp(“SIP/203”, “Starting recording check against dontcare”) in new stack pbx.c: Executing [recordcheck@sub-record-check:2] Goto(“SIP/203”, “dontcare”) in new stack pbx.c: Goto (sub-record-check,recordcheck,3) pbx.c: Executing [recordcheck@sub-record-check:3] Return(“SIP/203”, “”) in new stack pbx.c: Executing [out@sub-record-check:8] Return(“SIP/203”, “”) in new stack pbx.c: Executing [mycell#@from-internal:3] Set(“SIP/203”, “MOHCLASS=default”) in new stack pbx.c: Executing [mycell#@from-internal:4] ExecIf(“SIP/203”, “1?Set(TRUNKCIDOVERRIDE=myvoip#)”) in new stack pbx.c: Executing [mycell#@from-internal:5] Set(“SIP/203”, “_NODEST=”) in new stack pbx.c: Executing [mycell#@from-internal:6] Macro(“SIP/203”, “dialout-trunk,2,mycell#,off”) in new stack pbx.c: Executing [s@macro-dialout-trunk:1] Set(“SIP/203”, “DIAL_TRUNK=2”) in new stack pbx.c: Executing [s@macro-dialout-trunk:2] GosubIf(“SIP/203”, “0?sub-pincheck,s,1()”) in new stack pbx.c: Executing [s@macro-dialout-trunk:3] GotoIf(“SIP/203”, “0?disabletrunk,1”) in new stack pbx.c: Executing [s@macro-dialout-trunk:4] Set(“SIP/203”, “DIAL_NUMBER=mycell#”) in new stack pbx.c: Executing [s@macro-dialout-trunk:5] Set(“SIP/203”, “DIAL_TRUNK_OPTIONS=Ttr”) in new stack pbx.c: Executing [s@macro-dialout-trunk:6] Set(“SIP/203”, “OUTBOUND_GROUP=OUT_2”) in new stack pbx.c: Executing [s@macro-dialout-trunk:7] GotoIf(“SIP/203”, “1?nomax”) in new stack pbx.c: Goto (macro-dialout-trunk,s,9) pbx.c: Executing [s@macro-dialout-trunk:9] GotoIf(“SIP/203”, “0?skipoutcid”) in new stack pbx.c: Executing [s@macro-dialout-trunk:10] Set(“SIP/203”, “DIAL_TRUNK_OPTIONS=Tt”) in new stack pbx.c: Executing [s@macro-dialout-trunk:11] Macro(“SIP/203”, “outbound-callerid,2”) in new stack pbx.c: Executing [s@macro-outbound-callerid:1] ExecIf(“SIP/203”, “0?Set(CALLERPRES(name-pres)=)”) in new stack pbx.c: Executing [s@macro-outbound-callerid:2] ExecIf(“SIP/203”, “0?Set(CALLERPRES(num-pres)=)”) in new stack pbx.c: Executing [s@macro-outbound-callerid:3] ExecIf(“SIP/203”, “0?Set(REALCALLERIDNUM=203)”) in new stack pbx.c: Executing [s@macro-outbound-callerid:4] GotoIf(“SIP/203”, “1?normcid”) in new stack pbx.c: Goto (macro-outbound-callerid,s,7) pbx.c: Executing [s@macro-outbound-callerid:7] Set(“SIP/203”, “USEROUTCID=”) in new stack pbx.c: Executing [s@macro-outbound-callerid:8] Set(“SIP/203”, “EMERGENCYCID=myvoip#”) in new stack pbx.c: Executing [s@macro-outbound-callerid:9] Set(“SIP/203”, “TRUNKOUTCID=myvoip#”) in new stack pbx.c: Executing [s@macro-outbound-callerid:10] GotoIf(“SIP/203”, “1?trunkcid”) in new stack pbx.c: Goto (macro-outbound-callerid,s,15) pbx.c: Executing [s@macro-outbound-callerid:15] ExecIf(“SIP/203”, “1?Set(CALLERID(all)=myvoip#)”) in new stack pbx.c: Executing [s@macro-outbound-callerid:16] ExecIf(“SIP/203”, “0?Set(CALLERID(all)=)”) in new stack pbx.c: Executing [s@macro-outbound-callerid:17] ExecIf(“SIP/203”, “1?Set(CALLERID(all)=myvoip#)”) in new stack pbx.c: Executing [s@macro-outbound-callerid:18] ExecIf(“SIP/203”, “0?Set(CALLERPRES(name-pres)=prohib_passed_screen)”) in new stack pbx.c: Executing [s@macro-outbound-callerid:19] ExecIf(“SIP/203”, “0?Set(CALLERPRES(num-pres)=prohib_passed_screen)”) in new stack pbx.c: Executing [s@macro-outbound-callerid:20] Set(“SIP/203”, “CDR(outbound_cnum)=myvoip#”) in new stack pbx.c: Executing [s@macro-outbound-callerid:21] Set(“SIP/203”, “CDR(outbound_cnam)=”) in new stack CDR requires a value (CDR(variable)=value) pbx.c: Executing [s@macro-dialout-trunk:12] GosubIf(“SIP/203”, “0?sub-flp-2,s,1()”) in new stack pbx.c: Executing [s@macro-dialout-trunk:13] Set(“SIP/203”, “OUTNUM=mycell#”) in new stack pbx.c: Executing [s@macro-dialout-trunk:14] Set(“SIP/203”, “custom=SIP/OutboundPPTrunk”) in new stack pbx.c: Executing [s@macro-dialout-trunk:15] ExecIf(“SIP/203”, “0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)Tt)”) in new stack pbx.c: Executing [s@macro-dialout-trunk:16] ExecIf(“SIP/203”, “0?Set(DIAL_TRUNK_OPTIONS=TtM(confirm))”) in new stack pbx.c: Executing [s@macro-dialout-trunk:17] Macro(“SIP/203”, “dialout-trunk-predial-hook,”) in new stack pbx.c: Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit(“SIP/203”, “”) in new stack pbx.c: Executing [s@macro-dialout-trunk:18] GotoIf(“SIP/203”, “0?bypass,1”) in new stack pbx.c: Executing [s@macro-dialout-trunk:19] ExecIf(“SIP/203”, “1?Set(CONNECTEDLINE(num,i)=mycell#)”) in new stack pbx.c: Executing [s@macro-dialout-trunk:20] ExecIf(“SIP/203”, “1?Set(CONNECTEDLINE(name,i)=CID:myvoip#)”) in new stack pbx.c: Executing [s@macro-dialout-trunk:21] ExecIf(“SIP/203”, “0?Set(CONNECTEDLINE(name,i)=CID:(Hidden)myvoip#)”) in new stack pbx.c: Executing [s@macro-dialout-trunk:22] GotoIf(“SIP/203”, “0?customtrunk”) in new stack pbx.c: Executing [s@macro-dialout-trunk:23] Dial(“SIP/203”, “SIP/OutboundPPTrunk/mycell#,300,Tt”) in new stack netsock2.c: Using SIP RTP TOS bits 184 netsock2.c: Using SIP RTP CoS mark 5 app_dial.c: Called SIP/OutboundPPTrunk/mycell# app_dial.c: SIP/OutboundPPTrunk-0000000d is making progress passing it to SIP/203 app_dial.c: SIP/OutboundPPTrunk-0000000d answered SIP/203 bridge_channel.c: Channel SIP/OutboundPPTrunk-0000000d joined ‘simple_bridge’ basic-bridge <9ed69219-624e-4eb6-b5a6-e7c7798ffe68> bridge_channel.c: Channel SIP/203 joined ‘simple_bridge’ basic-bridge <9ed69219-624e-4eb6-b5a6-e7c7798ffe68> bridge_channel.c: Channel SIP/OutboundPPTrunk-0000000d left ‘simple_bridge’ basic-bridge <9ed69219-624e-4eb6-b5a6-e7c7798ffe68> bridge_channel.c: Channel SIP/203 left ‘simple_bridge’ basic-bridge <9ed69219-624e-4eb6-b5a6-e7c7798ffe68> app_macro.c: Spawn extension (macro-dialout-trunk, s, 23) exited non-zero on ‘SIP/203’ in macro 'dialout-trunk’ pbx.c: Spawn extension (from-internal, mycell#, 6) exited non-zero on 'SIP/203’ pbx.c: Executing [h@from-internal:1] Macro(“SIP/203”, “hangupcall”) in new stack pbx.c: Executing [s@macro-hangupcall:1] GotoIf(“SIP/203”, “1?theend”) in new stack pbx.c: Goto (macro-hangupcall,s,3) pbx.c: Executing [s@macro-hangupcall:3] ExecIf(“SIP/203”, “0?Set(CDR(recordingfile)=)”) in new stack pbx.c: Executing [s@macro-hangupcall:4] Hangup(“SIP/203”, “”) in new stack app_macro.c: Spawn extension (macro-hangupcall, s, 4) exited non-zero on ‘SIP/203’ in macro 'hangupcall’ pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on ‘SIP/203’

3 - partial ring, asterisk says call not answered
pbx.c: Executing [mycell#@from-internal:1] Macro(“SIP/203”, “user-callerid,LIMIT,EXTERNAL,”) in new stack pbx.c: Executing [s@macro-user-callerid:1] Set(“SIP/203”, “TOUCH_MONITOR=1454378465.16”) in new stack pbx.c: Executing [s@macro-user-callerid:2] Set(“SIP/203”, “AMPUSER=203”) in new stack pbx.c: Executing [s@macro-user-callerid:3] GotoIf(“SIP/203”, “0?report”) in new stack pbx.c: Executing [s@macro-user-callerid:4] ExecIf(“SIP/203”, “1?Set(REALCALLERIDNUM=203)”) in new stack pbx.c: Executing [s@macro-user-callerid:5] Set(“SIP/203”, “AMPUSER=203”) in new stack pbx.c: Executing [s@macro-user-callerid:6] GotoIf(“SIP/203”, “0?limit”) in new stack pbx.c: Executing [s@macro-user-callerid:7] Set(“SIP/203”, “AMPUSERCIDNAME=BradenMBP”) in new stack pbx.c: Executing [s@macro-user-callerid:8] GotoIf(“SIP/203”, “0?report”) in new stack pbx.c: Executing [s@macro-user-callerid:9] Set(“SIP/203”, “AMPUSERCID=203”) in new stack pbx.c: Executing [s@macro-user-callerid:10] Set(“SIP/203”, “__DIAL_OPTIONS=Ttr”) in new stack pbx.c: Executing [s@macro-user-callerid:11] Set(“SIP/203”, “CALLERID(all)=“BradenMBP” <203>”) in new stack pbx.c: Executing [s@macro-user-callerid:12] GotoIf(“SIP/203”, “0?limit”) in new stack pbx.c: Executing [s@macro-user-callerid:13] ExecIf(“SIP/203”, “1?Set(GROUP(concurrency_limit)=203)”) in new stack pbx.c: Executing [s@macro-user-callerid:14] GotoIf(“SIP/203”, “1?continue”) in new stack pbx.c: Goto (macro-user-callerid,s,27) pbx.c: Executing [s@macro-user-callerid:27] Set(“SIP/203”, “CALLERID(number)=203”) in new stack pbx.c: Executing [s@macro-user-callerid:28] Set(“SIP/203”, “CALLERID(name)=BradenMBP”) in new stack pbx.c: Executing [s@macro-user-callerid:29] Set(“SIP/203”, “CDR(cnum)=203”) in new stack pbx.c: Executing [s@macro-user-callerid:30] Set(“SIP/203”, “CDR(cnam)=BradenMBP”) in new stack pbx.c: Executing [s@macro-user-callerid:31] Set(“SIP/203”, “CHANNEL(language)=en”) in new stack pbx.c: Executing [mycell#@from-internal:2] Gosub(“SIP/203”, “sub-record-check,s,1(out,mycell#,dontcare)”) in new stack pbx.c: Executing [s@sub-record-check:1] GotoIf(“SIP/203”, “0?initialized”) in new stack pbx.c: Executing [s@sub-record-check:2] Set(“SIP/203”, “__REC_STATUS=INITIALIZED”) in new stack pbx.c: Executing [s@sub-record-check:3] Set(“SIP/203”, “NOW=1454378465”) in new stack pbx.c: Executing [s@sub-record-check:4] Set(“SIP/203”, “__DAY=01”) in new stack pbx.c: Executing [s@sub-record-check:5] Set(“SIP/203”, “__MONTH=02”) in new stack pbx.c: Executing [s@sub-record-check:6] Set(“SIP/203”, “__YEAR=2016”) in new stack pbx.c: Executing [s@sub-record-check:7] Set(“SIP/203”, “__TIMESTR=20160201-210105”) in new stack pbx.c: Executing [s@sub-record-check:8] Set(“SIP/203”, “__FROMEXTEN=203”) in new stack pbx.c: Executing [s@sub-record-check:9] Set(“SIP/203”, “__MON_FMT=wav”) in new stack pbx.c: Executing [s@sub-record-check:10] NoOp(“SIP/203”, “Recordings initialized”) in new stack pbx.c: Executing [s@sub-record-check:11] ExecIf(“SIP/203”, “0?Set(ARG3=dontcare)”) in new stack pbx.c: Executing [s@sub-record-check:12] Set(“SIP/203”, “REC_POLICY_MODE_SAVE=”) in new stack pbx.c: Executing [s@sub-record-check:13] ExecIf(“SIP/203”, “0?Set(REC_STATUS=NO)”) in new stack pbx.c: Executing [s@sub-record-check:14] GotoIf(“SIP/203”, “3?checkaction”) in new stack pbx.c: Goto (sub-record-check,s,17) pbx.c: Executing [s@sub-record-check:17] GotoIf(“SIP/203”, “1?sub-record-check,out,1”) in new stack pbx.c: Goto (sub-record-check,out,1) pbx.c: Executing [out@sub-record-check:1] NoOp(“SIP/203”, “Outbound Recording Check from 203 to mycell#”) in new stack pbx.c: Executing [out@sub-record-check:2] Set(“SIP/203”, “RECMODE=dontcare”) in new stack pbx.c: Executing [out@sub-record-check:3] ExecIf(“SIP/203”, “1?Goto(routewins)”) in new stack pbx.c: Goto (sub-record-check,out,7) pbx.c: Executing [out@sub-record-check:7] Gosub(“SIP/203”, “recordcheck,1(dontcare,out,mycell#)”) in new stack pbx.c: Executing [recordcheck@sub-record-check:1] NoOp(“SIP/203”, “Starting recording check against dontcare”) in new stack pbx.c: Executing [recordcheck@sub-record-check:2] Goto(“SIP/203”, “dontcare”) in new stack pbx.c: Goto (sub-record-check,recordcheck,3) pbx.c: Executing [recordcheck@sub-record-check:3] Return(“SIP/203”, “”) in new stack pbx.c: Executing [out@sub-record-check:8] Return(“SIP/203”, “”) in new stack pbx.c: Executing [mycell#@from-internal:3] Set(“SIP/203”, “MOHCLASS=default”) in new stack pbx.c: Executing [mycell#@from-internal:4] ExecIf(“SIP/203”, “1?Set(TRUNKCIDOVERRIDE=myvoip#)”) in new stack pbx.c: Executing [mycell#@from-internal:5] Set(“SIP/203”, “_NODEST=”) in new stack pbx.c: Executing [mycell#@from-internal:6] Macro(“SIP/203”, “dialout-trunk,2,mycell#,off”) in new stack pbx.c: Executing [s@macro-dialout-trunk:1] Set(“SIP/203”, “DIAL_TRUNK=2”) in new stack pbx.c: Executing [s@macro-dialout-trunk:2] GosubIf(“SIP/203”, “0?sub-pincheck,s,1()”) in new stack pbx.c: Executing [s@macro-dialout-trunk:3] GotoIf(“SIP/203”, “0?disabletrunk,1”) in new stack pbx.c: Executing [s@macro-dialout-trunk:4] Set(“SIP/203”, “DIAL_NUMBER=mycell#”) in new stack pbx.c: Executing [s@macro-dialout-trunk:5] Set(“SIP/203”, “DIAL_TRUNK_OPTIONS=Ttr”) in new stack pbx.c: Executing [s@macro-dialout-trunk:6] Set(“SIP/203”, “OUTBOUND_GROUP=OUT_2”) in new stack pbx.c: Executing [s@macro-dialout-trunk:7] GotoIf(“SIP/203”, “1?nomax”) in new stack pbx.c: Goto (macro-dialout-trunk,s,9) pbx.c: Executing [s@macro-dialout-trunk:9] GotoIf(“SIP/203”, “0?skipoutcid”) in new stack pbx.c: Executing [s@macro-dialout-trunk:10] Set(“SIP/203”, “DIAL_TRUNK_OPTIONS=Tt”) in new stack pbx.c: Executing [s@macro-dialout-trunk:11] Macro(“SIP/203”, “outbound-callerid,2”) in new stack pbx.c: Executing [s@macro-outbound-callerid:1] ExecIf(“SIP/203”, “0?Set(CALLERPRES(name-pres)=)”) in new stack pbx.c: Executing [s@macro-outbound-callerid:2] ExecIf(“SIP/203”, “0?Set(CALLERPRES(num-pres)=)”) in new stack pbx.c: Executing [s@macro-outbound-callerid:3] ExecIf(“SIP/203”, “0?Set(REALCALLERIDNUM=203)”) in new stack pbx.c: Executing [s@macro-outbound-callerid:4] GotoIf(“SIP/203”, “1?normcid”) in new stack pbx.c: Goto (macro-outbound-callerid,s,7) pbx.c: Executing [s@macro-outbound-callerid:7] Set(“SIP/203”, “USEROUTCID=”) in new stack pbx.c: Executing [s@macro-outbound-callerid:8] Set(“SIP/203”, “EMERGENCYCID=myvoip#”) in new stack pbx.c: Executing [s@macro-outbound-callerid:9] Set(“SIP/203”, “TRUNKOUTCID=myvoip#”) in new stack pbx.c: Executing [s@macro-outbound-callerid:10] GotoIf(“SIP/203”, “1?trunkcid”) in new stack pbx.c: Goto (macro-outbound-callerid,s,15) pbx.c: Executing [s@macro-outbound-callerid:15] ExecIf(“SIP/203”, “1?Set(CALLERID(all)=myvoip#)”) in new stack pbx.c: Executing [s@macro-outbound-callerid:16] ExecIf(“SIP/203”, “0?Set(CALLERID(all)=)”) in new stack pbx.c: Executing [s@macro-outbound-callerid:17] ExecIf(“SIP/203”, “1?Set(CALLERID(all)=myvoip#)”) in new stack pbx.c: Executing [s@macro-outbound-callerid:18] ExecIf(“SIP/203”, “0?Set(CALLERPRES(name-pres)=prohib_passed_screen)”) in new stack pbx.c: Executing [s@macro-outbound-callerid:19] ExecIf(“SIP/203”, “0?Set(CALLERPRES(num-pres)=prohib_passed_screen)”) in new stack pbx.c: Executing [s@macro-outbound-callerid:20] Set(“SIP/203”, “CDR(outbound_cnum)=myvoip#”) in new stack pbx.c: Executing [s@macro-outbound-callerid:21] Set(“SIP/203”, “CDR(outbound_cnam)=”) in new stack CDR requires a value (CDR(variable)=value) pbx.c: Executing [s@macro-dialout-trunk:12] GosubIf(“SIP/203”, “0?sub-flp-2,s,1()”) in new stack pbx.c: Executing [s@macro-dialout-trunk:13] Set(“SIP/203”, “OUTNUM=mycell#”) in new stack pbx.c: Executing [s@macro-dialout-trunk:14] Set(“SIP/203”, “custom=SIP/OutboundPPTrunk”) in new stack pbx.c: Executing [s@macro-dialout-trunk:15] ExecIf(“SIP/203”, “0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)Tt)”) in new stack pbx.c: Executing [s@macro-dialout-trunk:16] ExecIf(“SIP/203”, “0?Set(DIAL_TRUNK_OPTIONS=TtM(confirm))”) in new stack pbx.c: Executing [s@macro-dialout-trunk:17] Macro(“SIP/203”, “dialout-trunk-predial-hook,”) in new stack pbx.c: Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit(“SIP/203”, “”) in new stack pbx.c: Executing [s@macro-dialout-trunk:18] GotoIf(“SIP/203”, “0?bypass,1”) in new stack pbx.c: Executing [s@macro-dialout-trunk:19] ExecIf(“SIP/203”, “1?Set(CONNECTEDLINE(num,i)=mycell#)”) in new stack pbx.c: Executing [s@macro-dialout-trunk:20] ExecIf(“SIP/203”, “1?Set(CONNECTEDLINE(name,i)=CID:myvoip#)”) in new stack pbx.c: Executing [s@macro-dialout-trunk:21] ExecIf(“SIP/203”, “0?Set(CONNECTEDLINE(name,i)=CID:(Hidden)myvoip#)”) in new stack pbx.c: Executing [s@macro-dialout-trunk:22] GotoIf(“SIP/203”, “0?customtrunk”) in new stack pbx.c: Executing [s@macro-dialout-trunk:23] Dial(“SIP/203”, “SIP/OutboundPPTrunk/mycell#,300,Tt”) in new stack netsock2.c: Using SIP RTP TOS bits 184 netsock2.c: Using SIP RTP CoS mark 5 app_dial.c: Called SIP/OutboundPPTrunk/mycell# app_dial.c: No one is available to answer at this time (1:0/0/0) pbx.c: Executing [s@macro-dialout-trunk:24] NoOp(“SIP/203”, “Dial failed for some reason with DIALSTATUS = NOANSWER and HANGUPCAUSE = 16”) in new stack pbx.c: Executing [s@macro-dialout-trunk:25] GotoIf(“SIP/203”, “0?continue,1:s-NOANSWER,1”) in new stack pbx.c: Goto (macro-dialout-trunk,s-NOANSWER,1) pbx.c: Executing [s-NOANSWER@macro-dialout-trunk:1] NoOp(“SIP/203”, “Dial failed due to trunk reporting NOANSWER - giving up”) in new stack pbx.c: Executing [s-NOANSWER@macro-dialout-trunk:2] Progress(“SIP/203”, “”) in new stack pbx.c: Executing [s-NOANSWER@macro-dialout-trunk:3] Playback(“SIP/203”, “number-not-answering,noanswer”) in new stack file.c: <SIP/203> Playing ‘number-not-answering.slin’ (language ‘en’) pbx.c: Executing [s-NOANSWER@macro-dialout-trunk:4] Congestion(“SIP/203”, “20”) in new stack channel.c: Prodding channel ‘SIP/203’ failed app_macro.c: Spawn extension (macro-dialout-trunk, s-NOANSWER, 4) exited non-zero on ‘SIP/203’ in macro 'dialout-trunk’ pbx.c: Spawn extension (from-internal, mycell#, 6) exited non-zero on 'SIP/203’ pbx.c: Executing [h@from-internal:1] Macro(“SIP/203”, “hangupcall”) in new stack pbx.c: Executing [s@macro-hangupcall:1] GotoIf(“SIP/203”, “1?theend”) in new stack pbx.c: Goto (macro-hangupcall,s,3) pbx.c: Executing [s@macro-hangupcall:3] ExecIf(“SIP/203-00000010”, “0?Set(CDR(recordingfile)=)”) in new stack pbx.c: Executing [s@macro-hangupcall:4] Hangup(“SIP/203-00000010”, “”) in new stack app_macro.c: Spawn extension (macro-hangupcall, s, 4) exited non-zero on ‘SIP/203-00000010’ in macro 'hangupcall’ pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/203-00000010’ Timeout on 4c5d42746791a5694d3b7b7c09248a30 on non-critical invite transaction.

To me this sounds like something is wrong with my cell phone, but I have no other issues placing or receiving calls with my cell.

Anyone out there with a bright idea???

Thanks!

mulderlr · February 2, 2016, 3:58am

Open a ticket with your sip trunk provider and provide your call examples with times and your to/from numbers. The would be in a better position to trace this and determine where the problem could be in a one off situation like this.

braden · February 15, 2016, 7:54pm

Thanks - yes the provider said there were “routing delays” and they tried to play around with them, but said it was the best they could do.

Coincidentally, I noticed this in the log:

[2016-02-15 15:10:49] VERBOSE[8885][C-00000000] app_dial.c: Called SIP/OutboundPPTrunk/7055551234
[2016-02-15 15:11:13] VERBOSE[8885][C-00000000] app_dial.c: SIP/OutboundPPTrunk-00000001 is making progress passing it to SIP/401-00000000
[2016-02-15 15:11:19] VERBOSE[8885][C-00000000] app_dial.c: SIP/OutboundPPTrunk-00000001 answered SIP/401-00000000

note the 24 second delay from when the call is made to when it’s connected… do you think there is something on my end that could be causing the delay or it’s all on their side?

mulderlr · February 18, 2016, 3:42am

I would say it is on their side unless you have a bad STUN server configured or something.

jlinkcom · February 19, 2016, 5:09am

If you have a static IP you just need to define that in SIP Settings correctly and not use STUN, correct?