Greetings everyone. I have a weird problem that I’m sure has a simple fix. I am using contact manager for speed dials. When users dial *30x or *30xxx the speeddial completes just fine. However, when they dial *30xx the call comes back and says it cannot be completed. I have checked asterisk, and it is seeing all 3 variations incoming on the console (i.e. *301, *3022, *30555) so I don’t believe its a phone digit map issue.
On the calls that are not completing I am getting a restrictedroute bad-number message. I found a lot of posts pointing to Extension Routing as a problem so I tried setting an allow-all CoS group to my test phone to override the extension route module, but the behavior remains.
Here’s a failed call:
[2019-09-26 11:27:34] VERBOSE[12914][C-00000381] pbx.c: Executing [*3051@from-internal:1] Set("SIP/235-000003eb", "__COS_DEST=app-contactmanager-sd") in new stack
[2019-09-26 11:27:34] VERBOSE[12914][C-00000381] pbx.c: Executing [*3051@from-internal:2] Set("SIP/235-000003eb", "__COS_TYPE=FC") in new stack
[2019-09-26 11:27:34] VERBOSE[12914][C-00000381] pbx.c: Executing [*3051@from-internal:3] Answer("SIP/235-000003eb", "") in new stack
[2019-09-26 11:27:34] VERBOSE[12914][C-00000381] pbx.c: Executing [*3051@from-internal:4] Macro("SIP/235-000003eb", "user-callerid,") in new stack
[2019-09-26 11:27:34] VERBOSE[12914][C-00000381] pbx.c: Executing [s@macro-user-callerid:1] Set("SIP/235-000003eb", "TOUCH_MONITOR=1569511654.2157") in new stack
[2019-09-26 11:27:34] VERBOSE[12914][C-00000381] pbx.c: Executing [s@macro-user-callerid:2] Set("SIP/235-000003eb", "AMPUSER=235") in new stack
[2019-09-26 11:27:34] VERBOSE[12914][C-00000381] pbx.c: Executing [s@macro-user-callerid:3] GotoIf("SIP/235-000003eb", "0?report") in new stack
[2019-09-26 11:27:34] VERBOSE[12914][C-00000381] pbx.c: Executing [s@macro-user-callerid:4] ExecIf("SIP/235-000003eb", "1?Set(REALCALLERIDNUM=235)") in new stack
[2019-09-26 11:27:34] VERBOSE[12914][C-00000381] pbx.c: Executing [s@macro-user-callerid:5] Set("SIP/235-000003eb", "AMPUSER=235") in new stack
[2019-09-26 11:27:34] VERBOSE[12914][C-00000381] pbx.c: Executing [s@macro-user-callerid:6] GotoIf("SIP/235-000003eb", "0?limit") in new stack
[2019-09-26 11:27:34] VERBOSE[12914][C-00000381] pbx.c: Executing [s@macro-user-callerid:7] Set("SIP/235-000003eb", "AMPUSERCIDNAME=ITO") in new stack
[2019-09-26 11:27:34] VERBOSE[12914][C-00000381] pbx.c: Executing [s@macro-user-callerid:8] ExecIf("SIP/235-000003eb", "0?Set(__CIDMASQUERADING=TRUE)") in new stack
[2019-09-26 11:27:34] VERBOSE[12914][C-00000381] pbx.c: Executing [s@macro-user-callerid:9] GotoIf("SIP/235-000003eb", "0?report") in new stack
[2019-09-26 11:27:34] VERBOSE[12914][C-00000381] pbx.c: Executing [s@macro-user-callerid:10] Set("SIP/235-000003eb", "AMPUSERCID=235") in new stack
[2019-09-26 11:27:34] VERBOSE[12914][C-00000381] pbx.c: Executing [s@macro-user-callerid:11] Set("SIP/235-000003eb", "__DIAL_OPTIONS=HhTtr") in new stack
[2019-09-26 11:27:34] VERBOSE[12914][C-00000381] pbx.c: Executing [s@macro-user-callerid:12] Set("SIP/235-000003eb", "CALLERID(all)="ITO" <235>") in new stack
[2019-09-26 11:27:34] VERBOSE[12914][C-00000381] pbx.c: Executing [s@macro-user-callerid:13] GotoIf("SIP/235-000003eb", "0?limit") in new stack
[2019-09-26 11:27:34] VERBOSE[12914][C-00000381] pbx.c: Executing [s@macro-user-callerid:14] ExecIf("SIP/235-000003eb", "0?Set(GROUP(concurrency_limit)=235)") in new stack
[2019-09-26 11:27:34] VERBOSE[12914][C-00000381] pbx.c: Executing [s@macro-user-callerid:15] ExecIf("SIP/235-000003eb", "0?Set(CHANNEL(language)=)") in new stack
[2019-09-26 11:27:34] VERBOSE[12914][C-00000381] pbx.c: Executing [s@macro-user-callerid:16] NoOp("SIP/235-000003eb", "Macro Depth is 1") in new stack
[2019-09-26 11:27:34] VERBOSE[12914][C-00000381] pbx.c: Executing [s@macro-user-callerid:17] GotoIf("SIP/235-000003eb", "1?report2:macroerror") in new stack
[2019-09-26 11:27:34] VERBOSE[12914][C-00000381] pbx_builtins.c: Goto (macro-user-callerid,s,18)
[2019-09-26 11:27:34] VERBOSE[12914][C-00000381] pbx.c: Executing [s@macro-user-callerid:18] GotoIf("SIP/235-000003eb", "0?continue") in new stack
[2019-09-26 11:27:34] VERBOSE[12914][C-00000381] pbx.c: Executing [s@macro-user-callerid:19] ExecIf("SIP/235-000003eb", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack
[2019-09-26 11:27:34] VERBOSE[12914][C-00000381] pbx.c: Executing [s@macro-user-callerid:20] Set("SIP/235-000003eb", "__TTL=64") in new stack
[2019-09-26 11:27:34] VERBOSE[12914][C-00000381] pbx.c: Executing [s@macro-user-callerid:21] GotoIf("SIP/235-000003eb", "1?continue") in new stack
[2019-09-26 11:27:34] VERBOSE[12914][C-00000381] pbx_builtins.c: Goto (macro-user-callerid,s,37)
[2019-09-26 11:27:34] VERBOSE[12914][C-00000381] pbx.c: Executing [s@macro-user-callerid:37] Set("SIP/235-000003eb", "CALLERID(number)=235") in new stack
[2019-09-26 11:27:34] VERBOSE[12914][C-00000381] pbx.c: Executing [s@macro-user-callerid:38] Set("SIP/235-000003eb", "CALLERID(name)=ITO") in new stack
[2019-09-26 11:27:34] VERBOSE[12914][C-00000381] pbx.c: Executing [s@macro-user-callerid:39] GotoIf("SIP/235-000003eb", "0?cnum") in new stack
[2019-09-26 11:27:34] VERBOSE[12914][C-00000381] pbx.c: Executing [s@macro-user-callerid:40] Set("SIP/235-000003eb", "CDR(cnam)=ITO") in new stack
[2019-09-26 11:27:34] VERBOSE[12914][C-00000381] pbx.c: Executing [s@macro-user-callerid:41] Set("SIP/235-000003eb", "CDR(cnum)=235") in new stack
[2019-09-26 11:27:34] VERBOSE[12914][C-00000381] pbx.c: Executing [s@macro-user-callerid:42] Set("SIP/235-000003eb", "CHANNEL(language)=en") in new stack
[2019-09-26 11:27:34] VERBOSE[12914][C-00000381] pbx.c: Executing [s@macro-user-callerid:43] GosubIf("SIP/235-000003eb", "1?app-check-classofservce,s,1()") in new stack
[2019-09-26 11:27:34] VERBOSE[12914][C-00000381] pbx.c: Executing [s@app-check-classofservce:1] NoOp("SIP/235-000003eb", "Starting COS Check") in new stack
[2019-09-26 11:27:34] VERBOSE[12914][C-00000381] pbx.c: Executing [s@app-check-classofservce:2] AGI("SIP/235-000003eb", "cos.agi") in new stack
[2019-09-26 11:27:34] VERBOSE[12914][C-00000381] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/cos.agi
[2019-09-26 11:27:34] VERBOSE[12914][C-00000381] res_agi.c: cos.agi: Starting Class Of Service checks
[2019-09-26 11:27:34] VERBOSE[12914][C-00000381] res_agi.c: cos.agi: CoS Test allow found for user 235.
[2019-09-26 11:27:34] VERBOSE[12914][C-00000381] res_agi.c: cos.agi: 235 is a member of Test allow, and Test allow is allowed to use app-contactmanager-sd
[2019-09-26 11:27:34] VERBOSE[12914][C-00000381] res_agi.c: cos.agi:
[2019-09-26 11:27:34] VERBOSE[12914][C-00000381] res_agi.c: <SIP/235-000003eb>AGI Script cos.agi completed, returning 0
[2019-09-26 11:27:34] VERBOSE[12914][C-00000381] pbx.c: Executing [s@app-check-classofservce:3] Return("SIP/235-000003eb", "") in new stack
[2019-09-26 11:27:34] VERBOSE[12914][C-00000381] pbx.c: Executing [*3051@from-internal:5] GotoIf("SIP/235-000003eb", "1?from-internal,12162416904 ,1") in new stack
[2019-09-26 11:27:34] VERBOSE[12914][C-00000381] pbx_builtins.c: Goto (from-internal,12162416904 ,1)
[2019-09-26 11:27:34] VERBOSE[12914][C-00000381] pbx.c: Executing [12162416904 @from-internal:1] Macro("SIP/235-000003eb", "user-callerid,LIMIT") in new stack
[2019-09-26 11:27:34] VERBOSE[12914][C-00000381] pbx.c: Executing [s@macro-user-callerid:1] Set("SIP/235-000003eb", "TOUCH_MONITOR=1569511654.2157") in new stack
[2019-09-26 11:27:34] VERBOSE[12914][C-00000381] pbx.c: Executing [s@macro-user-callerid:2] Set("SIP/235-000003eb", "AMPUSER=235") in new stack
[2019-09-26 11:27:34] VERBOSE[12914][C-00000381] pbx.c: Executing [s@macro-user-callerid:3] GotoIf("SIP/235-000003eb", "3?report") in new stack
[2019-09-26 11:27:34] VERBOSE[12914][C-00000381] pbx_builtins.c: Goto (macro-user-callerid,s,16)
[2019-09-26 11:27:34] VERBOSE[12914][C-00000381] pbx.c: Executing [s@macro-user-callerid:16] NoOp("SIP/235-000003eb", "Macro Depth is 1") in new stack
[2019-09-26 11:27:34] VERBOSE[12914][C-00000381] pbx.c: Executing [s@macro-user-callerid:17] GotoIf("SIP/235-000003eb", "1?report2:macroerror") in new stack
[2019-09-26 11:27:34] VERBOSE[12914][C-00000381] pbx_builtins.c: Goto (macro-user-callerid,s,18)
[2019-09-26 11:27:34] VERBOSE[12914][C-00000381] pbx.c: Executing [s@macro-user-callerid:18] GotoIf("SIP/235-000003eb", "1?continue") in new stack
[2019-09-26 11:27:34] VERBOSE[12914][C-00000381] pbx_builtins.c: Goto (macro-user-callerid,s,37)
[2019-09-26 11:27:34] VERBOSE[12914][C-00000381] pbx.c: Executing [s@macro-user-callerid:37] Set("SIP/235-000003eb", "CALLERID(number)=235") in new stack
[2019-09-26 11:27:34] VERBOSE[12914][C-00000381] pbx.c: Executing [s@macro-user-callerid:38] Set("SIP/235-000003eb", "CALLERID(name)=ITO") in new stack
[2019-09-26 11:27:34] VERBOSE[12914][C-00000381] pbx.c: Executing [s@macro-user-callerid:39] GotoIf("SIP/235-000003eb", "0?cnum") in new stack
[2019-09-26 11:27:34] VERBOSE[12914][C-00000381] pbx.c: Executing [s@macro-user-callerid:40] Set("SIP/235-000003eb", "CDR(cnam)=ITO") in new stack
[2019-09-26 11:27:34] VERBOSE[12914][C-00000381] pbx.c: Executing [s@macro-user-callerid:41] Set("SIP/235-000003eb", "CDR(cnum)=235") in new stack
[2019-09-26 11:27:34] VERBOSE[12914][C-00000381] pbx.c: Executing [s@macro-user-callerid:42] Set("SIP/235-000003eb", "CHANNEL(language)=en") in new stack
[2019-09-26 11:27:34] VERBOSE[12914][C-00000381] pbx.c: Executing [s@macro-user-callerid:43] GosubIf("SIP/235-000003eb", "1?app-check-classofservce,s,1()") in new stack
[2019-09-26 11:27:34] VERBOSE[12914][C-00000381] pbx.c: Executing [s@app-check-classofservce:1] NoOp("SIP/235-000003eb", "Starting COS Check") in new stack
[2019-09-26 11:27:34] VERBOSE[12914][C-00000381] pbx.c: Executing [s@app-check-classofservce:2] AGI("SIP/235-000003eb", "cos.agi") in new stack
[2019-09-26 11:27:34] VERBOSE[12914][C-00000381] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/cos.agi
[2019-09-26 11:27:35] VERBOSE[12914][C-00000381] res_agi.c: cos.agi: Starting Class Of Service checks
[2019-09-26 11:27:35] VERBOSE[12914][C-00000381] res_agi.c: cos.agi: CoS Test allow found for user 235.
[2019-09-26 11:27:35] VERBOSE[12914][C-00000381] res_agi.c: cos.agi: 235 is a member of Test allow, and Test allow is allowed to use app-contactmanager-sd
[2019-09-26 11:27:35] VERBOSE[12914][C-00000381] res_agi.c: cos.agi:
[2019-09-26 11:27:35] VERBOSE[12914][C-00000381] res_agi.c: <SIP/235-000003eb>AGI Script cos.agi completed, returning 0
[2019-09-26 11:27:35] VERBOSE[12914][C-00000381] pbx.c: Executing [s@app-check-classofservce:3] Return("SIP/235-000003eb", "") in new stack
[2019-09-26 11:27:35] VERBOSE[12914][C-00000381] pbx.c: Executing [12162416904 @from-internal:2] Set("SIP/235-000003eb", "ROUTEUSER=235") in new stack
[2019-09-26 11:27:35] VERBOSE[12914][C-00000381] pbx.c: Executing [12162416904 @from-internal:3] Set("SIP/235-000003eb", "ROUTEUSER=235") in new stack
[2019-09-26 11:27:35] VERBOSE[12914][C-00000381] pbx.c: Executing [12162416904 @from-internal:4] GotoIf("SIP/235-000003eb", "1?notblind") in new stack
[2019-09-26 11:27:35] VERBOSE[12914][C-00000381] pbx_builtins.c: Goto (from-internal,12162416904 ,7)
[2019-09-26 11:27:35] VERBOSE[12914][C-00000381] pbx.c: Executing [12162416904 @from-internal:7] GotoIf("SIP/235-000003eb", "1?restrictedroute-c4ca4238a0b923820dcc509a6f75849b,12162416904 ,2:outbound-allroutes,12162416904 ,2") in new stack
[2019-09-26 11:27:35] VERBOSE[12914][C-00000381] pbx_builtins.c: Goto (restrictedroute-c4ca4238a0b923820dcc509a6f75849b,12162416904 ,2)
[2019-09-26 11:27:35] VERBOSE[12914][C-00000381] pbx.c: Channel 'SIP/235-000003eb' sent to invalid extension: context,exten,priority=restrictedroute-c4ca4238a0b923820dcc509a6f75849b,12162416904 ,2
[2019-09-26 11:27:35] VERBOSE[12914][C-00000381] pbx.c: Executing [i@restrictedroute-c4ca4238a0b923820dcc509a6f75849b:1] Goto("SIP/235-000003eb", "bad-number,s,1") in new stack
[2019-09-26 11:27:35] VERBOSE[12914][C-00000381] pbx_builtins.c: Goto (bad-number,s,1)
[2019-09-26 11:27:35] VERBOSE[12914][C-00000381] pbx.c: Executing [s@bad-number:1] Goto("SIP/235-000003eb", "11,1") in new stack
[2019-09-26 11:27:35] VERBOSE[12914][C-00000381] pbx_builtins.c: Goto (bad-number,11,1)
[2019-09-26 11:27:35] VERBOSE[12914][C-00000381] pbx.c: Executing [11@bad-number:1] ResetCDR("SIP/235-000003eb", "") in new stack
[2019-09-26 11:27:35] VERBOSE[12914][C-00000381] pbx.c: Executing [11@bad-number:2] NoCDR("SIP/235-000003eb", "") in new stack
[2019-09-26 11:27:35] VERBOSE[12914][C-00000381] pbx.c: Executing [11@bad-number:3] Progress("SIP/235-000003eb", "") in new stack
[2019-09-26 11:27:35] VERBOSE[12914][C-00000381] pbx.c: Executing [11@bad-number:4] Wait("SIP/235-000003eb", "1") in new stack
[2019-09-26 11:27:36] VERBOSE[12914][C-00000381] pbx.c: Executing [11@bad-number:5] Playback("SIP/235-000003eb", "silence/1&cannot-complete-as-dialed&check-number-dial-again,noanswer") in new stack
[2019-09-26 11:27:36] VERBOSE[12914][C-00000381] file.c: <SIP/235-000003eb> Playing 'silence/1.ulaw' (language 'en')
[2019-09-26 11:27:37] VERBOSE[12914][C-00000381] file.c: <SIP/235-000003eb> Playing 'cannot-complete-as-dialed.ulaw' (language 'en')
[2019-09-26 11:27:39] VERBOSE[12914][C-00000381] file.c: <SIP/235-000003eb> Playing 'check-number-dial-again.ulaw' (language 'en')