Hi,
I was hoping that somebody could help me with something that I had working previously, but now seems to have stopped working? I’m assuming maybe an update has stopped this from functioning? I have my inbound route set with the destination as a dynamic route. In the dynamic route, I have a dynamic route lookup source:
Type: Asterisk Variable
Enable substitutions: Yes
Asterisk Variable: ${DB_EXISTS(cidname/${CALLERID(number)})}
I have this set to check to see if the inbound caller ID is in the Asterisk Phonebook or not. If they aren’t, the result should be ‘0’ and the call should go to an IVR “Unknown Caller”. If they are in the phonebook, the result should be ‘1’ and the call should go to the Ring Group I have.
What seems to be happening now is that if the inbound caller ID is not in the phonebook, the call just hangs up and the call shows in call history as “Duration: 0”. If the inbound caller ID is in the phonebook, the call reaches the Ring Group.
On the Inbound Route, I have “CID Lookup Source” set to “Phonebook”.
It’s really strange, this used to work perfectly but has randomly stopped working.
Log from incoming call (number redacted)
3359 [2025-02-14 17:53:01] VERBOSE[32450] res_srtp.c: Unsupported crypto suite: AEAD_AES_256_GCM
3360 [2025-02-14 17:53:01] VERBOSE[32450] res_srtp.c: Unsupported crypto suite: AES_CM_256_HMAC_SHA1_80
3361 [2025-02-14 17:53:01] VERBOSE[32450] res_srtp.c: Unsupported crypto suite: AES_CM_256_HMAC_SHA1_32
3362 [2025-02-14 17:53:01] VERBOSE[32450] res_srtp.c: Unsupported crypto suite: AES_256_CM_HMAC_SHA1_80
3363 [2025-02-14 17:53:01] VERBOSE[32450] res_srtp.c: Unsupported crypto suite: AES_256_CM_HMAC_SHA1_32
3364 [2025-02-14 17:53:01] VERBOSE[41837][C-00000077] pbx.c: Executing [0000@from-trunk:1] Set("PJSIP/0000-000000c9", "__DIRECTION=INBOUND") in new stack
3365 [2025-02-14 17:53:01] VERBOSE[41837][C-00000077] pbx.c: Executing [0000@from-trunk:2] Set("PJSIP/0000-000000c9", "CHANNEL(language)=en_GB") in new stack
3366 [2025-02-14 17:53:01] VERBOSE[41837][C-00000077] pbx.c: Executing [0000@from-trunk:3] Gosub("PJSIP/0000-000000c9", "sub-record-check,s,1(in,0000,force)") in new stack
3367 [2025-02-14 17:53:01] VERBOSE[41837][C-00000077] pbx.c: Executing [s@sub-record-check:1] GotoIf("PJSIP/0000-000000c9", "0?initialized") in new stack
3368 [2025-02-14 17:53:01] VERBOSE[41837][C-00000077] pbx.c: Executing [s@sub-record-check:2] Set("PJSIP/0000-000000c9", "__REC_STATUS=INITIALIZED") in new stack
3369 [2025-02-14 17:53:01] VERBOSE[41837][C-00000077] pbx.c: Executing [s@sub-record-check:3] Set("PJSIP/0000-000000c9", "NOW=1739555581") in new stack
3370 [2025-02-14 17:53:01] VERBOSE[41837][C-00000077] pbx.c: Executing [s@sub-record-check:4] Set("PJSIP/0000-000000c9", "__DAY=14") in new stack
3371 [2025-02-14 17:53:01] VERBOSE[41837][C-00000077] pbx.c: Executing [s@sub-record-check:5] Set("PJSIP/0000-000000c9", "__MONTH=02") in new stack
3372 [2025-02-14 17:53:01] VERBOSE[41837][C-00000077] pbx.c: Executing [s@sub-record-check:6] Set("PJSIP/0000-000000c9", "__YEAR=2025") in new stack
3373 [2025-02-14 17:53:01] VERBOSE[41837][C-00000077] pbx.c: Executing [s@sub-record-check:7] Set("PJSIP/0000-000000c9", "__TIMESTR=20250214-175301") in new stack
3374 [2025-02-14 17:53:01] VERBOSE[41837][C-00000077] pbx.c: Executing [s@sub-record-check:8] Set("PJSIP/0000-000000c9", "__FROMEXTEN=unknown") in new stack
3375 [2025-02-14 17:53:01] VERBOSE[41837][C-00000077] pbx.c: Executing [s@sub-record-check:9] Set("PJSIP/0000-000000c9", "__MON_FMT=wav") in new stack
3376 [2025-02-14 17:53:01] VERBOSE[41837][C-00000077] pbx.c: Executing [s@sub-record-check:10] NoOp("PJSIP/0000-000000c9", "Recordings initialized") in new stack
3377 [2025-02-14 17:53:01] VERBOSE[41837][C-00000077] pbx.c: Executing [s@sub-record-check:11] ExecIf("PJSIP/0000-000000c9", "0?Set(ARG3=dontcare)") in new stack
3378 [2025-02-14 17:53:01] VERBOSE[41837][C-00000077] pbx.c: Executing [s@sub-record-check:12] Set("PJSIP/0000-000000c9", "REC_POLICY_MODE_SAVE=") in new stack
3379 [2025-02-14 17:53:01] VERBOSE[41837][C-00000077] pbx.c: Executing [s@sub-record-check:13] ExecIf("PJSIP/0000-000000c9", "0?Set(REC_STATUS=NO)") in new stack
3380 [2025-02-14 17:53:01] VERBOSE[41837][C-00000077] pbx.c: Executing [s@sub-record-check:14] GotoIf("PJSIP/0000-000000c9", "2?checkaction") in new stack
3381 [2025-02-14 17:53:01] VERBOSE[41837][C-00000077] pbx_builtins.c: Goto (sub-record-check,s,17)
3382 [2025-02-14 17:53:01] VERBOSE[41837][C-00000077] pbx.c: Executing [s@sub-record-check:17] GotoIf("PJSIP/0000-000000c9", "1?sub-record-check,in,1") in new stack
3383 [2025-02-14 17:53:01] VERBOSE[41837][C-00000077] pbx_builtins.c: Goto (sub-record-check,in,1)
3384 [2025-02-14 17:53:01] VERBOSE[41837][C-00000077] pbx.c: Executing [in@sub-record-check:1] NoOp("PJSIP/0000-000000c9", "Inbound Recording Check to 0000") in new stack
3385 [2025-02-14 17:53:01] VERBOSE[41837][C-00000077] pbx.c: Executing [in@sub-record-check:2] Set("PJSIP/0000-000000c9", "FROMEXTEN=unknown") in new stack
3386 [2025-02-14 17:53:01] VERBOSE[41837][C-00000077] pbx.c: Executing [in@sub-record-check:3] ExecIf("PJSIP/0000-000000c9", "11?Set(FROMEXTEN=*REDACTED*)") in new stack
3387 [2025-02-14 17:53:01] VERBOSE[41837][C-00000077] pbx.c: Executing [in@sub-record-check:4] Gosub("PJSIP/0000-000000c9", "recordcheck,1(force,in,0000)") in new stack
3388 [2025-02-14 17:53:01] VERBOSE[41837][C-00000077] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("PJSIP/0000-000000c9", "Starting recording check against force") in new stack
3389 [2025-02-14 17:53:01] VERBOSE[41837][C-00000077] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("PJSIP/0000-000000c9", "force") in new stack
3390 [2025-02-14 17:53:01] VERBOSE[41837][C-00000077] pbx_builtins.c: Goto (sub-record-check,recordcheck,5)
3391 [2025-02-14 17:53:01] VERBOSE[41837][C-00000077] pbx.c: Executing [recordcheck@sub-record-check:5] Set("PJSIP/0000-000000c9", "__REC_POLICY_MODE=FORCE") in new stack
3392 [2025-02-14 17:53:01] VERBOSE[41837][C-00000077] pbx.c: Executing [recordcheck@sub-record-check:6] GotoIf("PJSIP/0000-000000c9", "1?startrec") in new stack
3393 [2025-02-14 17:53:01] VERBOSE[41837][C-00000077] pbx_builtins.c: Goto (sub-record-check,recordcheck,16)
3394 [2025-02-14 17:53:01] VERBOSE[41837][C-00000077] pbx.c: Executing [recordcheck@sub-record-check:16] NoOp("PJSIP/0000-000000c9", "Starting recording: in, 0000") in new stack
3395 [2025-02-14 17:53:01] VERBOSE[41837][C-00000077] pbx.c: Executing [recordcheck@sub-record-check:17] ExecIf("PJSIP/0000-000000c9", "0?Set(RECFROMEXTEN=)") in new stack
3396 [2025-02-14 17:53:01] VERBOSE[41837][C-00000077] pbx.c: Executing [recordcheck@sub-record-check:18] ExecIf("PJSIP/0000-000000c9", "1?Set(RECFROMEXTEN=*REDACTED*)") in new stack
3397 [2025-02-14 17:53:01] VERBOSE[41837][C-00000077] pbx.c: Executing [recordcheck@sub-record-check:19] Set("PJSIP/0000-000000c9", "__CALLFILENAME=in-0000-*REDACTED*-20250214-175301-1739555581.272") in new stack
3398 [2025-02-14 17:53:01] VERBOSE[41837][C-00000077] pbx.c: Executing [recordcheck@sub-record-check:20] Set("PJSIP/0000-000000c9", "_LOCAL_MIXMON_ID=20250214-175301-1739555581.272") in new stack
3399 [2025-02-14 17:53:01] VERBOSE[41837][C-00000077] pbx.c: Executing [recordcheck@sub-record-check:21] MixMonitor("PJSIP/0000-000000c9", "2025/02/14/in-0000-*REDACTED*-20250214-175301-1739555581.272.wav,ai(20250214-175301-1739555581.272),") in new stack
3400 [2025-02-14 17:53:01] VERBOSE[41837][C-00000077] pbx.c: Executing [recordcheck@sub-record-check:22] Set("PJSIP/0000-000000c9", "__MIXMON_ID=20250214-175301-1739555581.272") in new stack
3401 [2025-02-14 17:53:01] VERBOSE[41837][C-00000077] pbx.c: Executing [recordcheck@sub-record-check:23] Set("PJSIP/0000-000000c9", "__RECORD_ID=PJSIP/0000-000000c9") in new stack
3402 [2025-02-14 17:53:01] VERBOSE[41837][C-00000077] pbx.c: Executing [recordcheck@sub-record-check:24] Set("PJSIP/0000-000000c9", "__REC_STATUS=RECORDING") in new stack
3403 [2025-02-14 17:53:01] VERBOSE[41837][C-00000077] pbx.c: Executing [recordcheck@sub-record-check:25] Set("PJSIP/0000-000000c9", "CDR(recordingfile)=in-0000-*REDACTED*-20250214-175301-1739555581.272.wav") in new stack
3404 [2025-02-14 17:53:01] VERBOSE[41839][C-00000077] app_mixmonitor.c: Begin MixMonitor Recording PJSIP/0000-000000c9
3405 [2025-02-14 17:53:01] VERBOSE[41837][C-00000077] pbx.c: Executing [recordcheck@sub-record-check:26] Return("PJSIP/0000-000000c9", "") in new stack
3406 [2025-02-14 17:53:01] VERBOSE[41837][C-00000077] pbx.c: Executing [in@sub-record-check:5] Return("PJSIP/0000-000000c9", "") in new stack
3407 [2025-02-14 17:53:01] VERBOSE[41837][C-00000077] pbx.c: Executing [0000@from-trunk:4] Set("PJSIP/0000-000000c9", "CHANNEL(tonezone)=uk") in new stack
3408 [2025-02-14 17:53:01] VERBOSE[41837][C-00000077] pbx.c: Executing [0000@from-trunk:5] Set("PJSIP/0000-000000c9", "__FROM_DID=0000") in new stack
3409 [2025-02-14 17:53:01] VERBOSE[41837][C-00000077] pbx.c: Executing [0000@from-trunk:6] Set("PJSIP/0000-000000c9", "returnhere=1") in new stack
3410 [2025-02-14 17:53:01] VERBOSE[41837][C-00000077] pbx.c: Executing [0000@from-trunk:7] Gosub("PJSIP/0000-000000c9", "app-blacklist-check,s,1()") in new stack
3411 [2025-02-14 17:53:01] VERBOSE[41837][C-00000077] pbx.c: Executing [s@app-blacklist-check:1] GotoIf("PJSIP/0000-000000c9", "1?blacklisted") in new stack
3412 [2025-02-14 17:53:01] VERBOSE[41837][C-00000077] pbx_builtins.c: Goto (app-blacklist-check,s,4)
3413 [2025-02-14 17:53:01] VERBOSE[41837][C-00000077] pbx.c: Executing [s@app-blacklist-check:4] Answer("PJSIP/0000-000000c9", "") in new stack
3414 [2025-02-14 17:53:01] VERBOSE[30999] res_srtp.c: Unsupported crypto suite: AEAD_AES_256_GCM
3415 [2025-02-14 17:53:01] VERBOSE[30999] res_srtp.c: Unsupported crypto suite: AES_CM_256_HMAC_SHA1_80
3416 [2025-02-14 17:53:01] VERBOSE[30999] res_srtp.c: Unsupported crypto suite: AES_CM_256_HMAC_SHA1_32
3417 [2025-02-14 17:53:01] VERBOSE[30999] res_srtp.c: Unsupported crypto suite: AES_256_CM_HMAC_SHA1_80
3418 [2025-02-14 17:53:01] VERBOSE[30999] res_srtp.c: Unsupported crypto suite: AES_256_CM_HMAC_SHA1_32
3419 [2025-02-14 17:53:02] VERBOSE[41837][C-00000077] pbx.c: Executing [s@app-blacklist-check:5] Set("PJSIP/0000-000000c9", "BLDEST=app-blackhole,hangup,1") in new stack
3420 [2025-02-14 17:53:02] VERBOSE[41837][C-00000077] pbx.c: Executing [s@app-blacklist-check:6] ExecIf("PJSIP/0000-000000c9", "0?Set(BLDEST=app-blackhole,hangup,1)") in new stack
3421 [2025-02-14 17:53:02] VERBOSE[41837][C-00000077] pbx.c: Executing [s@app-blacklist-check:7] GotoIf("PJSIP/0000-000000c9", "1?returnto") in new stack
3422 [2025-02-14 17:53:02] VERBOSE[41837][C-00000077] pbx_builtins.c: Goto (app-blacklist-check,s,9)
3423 [2025-02-14 17:53:02] VERBOSE[41837][C-00000077] pbx.c: Executing [s@app-blacklist-check:9] Return("PJSIP/0000-000000c9", "") in new stack
3424 [2025-02-14 17:53:02] VERBOSE[41837][C-00000077] pbx.c: Executing [0000@from-trunk:8] Set("PJSIP/0000-000000c9", "CDR(did)=0000") in new stack
3425 [2025-02-14 17:53:02] VERBOSE[41837][C-00000077] pbx.c: Executing [0000@from-trunk:9] GotoIf("PJSIP/0000-000000c9", "22?app-blackhole,hangup,1") in new stack
3426 [2025-02-14 17:53:02] VERBOSE[41837][C-00000077] pbx_builtins.c: Goto (app-blackhole,hangup,1)
3427 [2025-02-14 17:53:02] VERBOSE[41837][C-00000077] pbx.c: Executing [hangup@app-blackhole:1] NoOp("PJSIP/0000-000000c9", "Blackhole Dest: Hangup") in new stack
3428 [2025-02-14 17:53:02] VERBOSE[41837][C-00000077] pbx.c: Executing [hangup@app-blackhole:2] Hangup("PJSIP/0000-000000c9", "") in new stack
3429 [2025-02-14 17:53:02] VERBOSE[41837][C-00000077] pbx.c: Spawn extension (app-blackhole, hangup, 2) exited non-zero on 'PJSIP/0000-000000c9'
3430 [2025-02-14 17:53:02] VERBOSE[41839][C-00000077] app_mixmonitor.c: MixMonitor close filestream (mixed)
3431 [2025-02-14 17:53:02] VERBOSE[41839][C-00000077] app_mixmonitor.c: End MixMonitor Recording PJSIP/0000-000000c9
Cheers,
Richie
