Dialed a ZoomMeeting number but can't find in logs

faisalkhan · September 13, 2021, 8:11pm

HI Team,

one of our agent dialed a number of ZoomMeeting Session from IP Phone registered with Freepbx. but when we looked at the records, no record found.

There is no log for that call and this happens for various zoommeeting numbers.

adell4444 · September 13, 2021, 8:17pm

If an extension registered to your FreePBX made an outbound call the logs for that call would be in /var/log/asterisk/full

faisalkhan · September 13, 2021, 8:21pm

thanks. I found the call in the /var/log/asterisk/full but why it’s not appearing in the CDRs

adell4444 · September 13, 2021, 8:22pm

Are there any outbound calls in the CDRs? Put a call example into pastebin and post the link here.

faisalkhan · September 13, 2021, 8:27pm

yes there are 1100 plus calls in CDRs

ExecIf("SIP/400-00001c9e", "0 ?Set(CDR(accountcode)=)") in new stack

adell4444 · September 13, 2021, 8:30pm

grep UID /var/log/asterisk/full | pastebin

faisalkhan · September 13, 2021, 8:39pm

[2021-09-13 13:01:04] VERBOSE[1906][C-00000f66] netsock2.c:   == Using SIP VIDEO TOS bits 136
[2021-09-13 13:01:04] VERBOSE[1906][C-00000f66] netsock2.c:   == Using SIP VIDEO CoS mark 6
[2021-09-13 13:01:04] VERBOSE[1906][C-00000f66] netsock2.c:   == Using SIP RTP TOS bits 184
[2021-09-13 13:01:04] VERBOSE[1906][C-00000f66] netsock2.c:   == Using SIP RTP CoS mark 5
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [123456789@Deny:1] Macro("SIP/400-00001c9e", "user-callerid,LIMIT,EXTERNAL,") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@macro-user-callerid:1] Set("SIP/400-00001c9e", "TOUCH_MONITOR=1631552464.14428") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@macro-user-callerid:2] Set("SIP/400-00001c9e", "AMPUSER=400") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@macro-user-callerid:3] GotoIf("SIP/400-00001c9e", "0?report") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@macro-user-callerid:4] ExecIf("SIP/400-00001c9e", "1?Set(REALCALLERIDNUM=400)") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@macro-user-callerid:5] Set("SIP/400-00001c9e", "AMPUSER=400") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@macro-user-callerid:6] GotoIf("SIP/400-00001c9e", "0?limit") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@macro-user-callerid:7] Set("SIP/400-00001c9e", "AMPUSERCIDNAME=ABC DEF") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@macro-user-callerid:8] GotoIf("SIP/400-00001c9e", "0?report") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@macro-user-callerid:9] Set("SIP/400-00001c9e", "AMPUSERCID=400") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@macro-user-callerid:10] Set("SIP/400-00001c9e", "__DIAL_OPTIONS=Ttr") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@macro-user-callerid:11] Set("SIP/400-00001c9e", "CALLERID(all)="ABC DEF" <400>") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@macro-user-callerid:12] GotoIf("SIP/400-00001c9e", "0?limit") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@macro-user-callerid:13] ExecIf("SIP/400-00001c9e", "1?Set(GROUP(concurrency_limit)=400)") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@macro-user-callerid:14] ExecIf("SIP/400-00001c9e", "0?Set(CHANNEL(language)=)") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@macro-user-callerid:15] GosubIf("SIP/400-00001c9e", "7?sub-ccss,s,1(Deny,123456789)") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@sub-ccss:1] ExecIf("SIP/400-00001c9e", "0?Return()") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@sub-ccss:2] Set("SIP/400-00001c9e", "CCSS_SETUP=TRUE") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@sub-ccss:3] GosubIf("SIP/400-00001c9e", "0?monitor_config,1(Deny,123456789):monitor_default,1(Deny,123456789)") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [monitor_default@sub-ccss:1] GotoIf("SIP/400-00001c9e", "0?is_exten") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [monitor_default@sub-ccss:2] StackPop("SIP/400-00001c9e", "") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [monitor_default@sub-ccss:3] Return("SIP/400-00001c9e", "FALSE") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@macro-user-callerid:16] GotoIf("SIP/400-00001c9e", "1?continue") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Goto (macro-user-callerid,s,30)
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@macro-user-callerid:30] Set("SIP/400-00001c9e", "CALLERID(number)=400") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@macro-user-callerid:31] Set("SIP/400-00001c9e", "CALLERID(name)=ABC DEF") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@macro-user-callerid:32] Set("SIP/400-00001c9e", "CDR(cnum)=400") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@macro-user-callerid:33] Set("SIP/400-00001c9e", "CDR(cnam)=ABC DEF") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@macro-user-callerid:34] Set("SIP/400-00001c9e", "CHANNEL(language)=en") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [123456789@Deny:2] ExecIf("SIP/400-00001c9e", "0 ?Set(CDR(accountcode)=)") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [123456789@Deny:3] Set("SIP/400-00001c9e", "EMERGENCYROUTE=YES") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [123456789@Deny:4] Set("SIP/400-00001c9e", "MOHCLASS=default") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [123456789@Deny:5] ExecIf("SIP/400-00001c9e", "1?Set(TRUNKCIDOVERRIDE=987654321)") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [123456789@Deny:6] Set("SIP/400-00001c9e", "_NODEST=") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [123456789@Deny:7] Gosub("SIP/400-00001c9e", "sub-record-check,s,1(out,123456789,)") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@sub-record-check:1] Set("SIP/400-00001c9e", "REC_POLICY_MODE_SAVE=") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@sub-record-check:2] GotoIf("SIP/400-00001c9e", "1?check") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Goto (sub-record-check,s,7)
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@sub-record-check:7] Set("SIP/400-00001c9e", "__MON_FMT=wav") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@sub-record-check:8] GotoIf("SIP/400-00001c9e", "1?next") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Goto (sub-record-check,s,11)
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@sub-record-check:11] ExecIf("SIP/400-00001c9e", "0?Return()") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@sub-record-check:12] ExecIf("SIP/400-00001c9e", "0?Set(__REC_POLICY_MODE=)") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@sub-record-check:13] GotoIf("SIP/400-00001c9e", "0?out,1") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@sub-record-check:14] Set("SIP/400-00001c9e", "__REC_STATUS=INITIALIZED") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@sub-record-check:15] Set("SIP/400-00001c9e", "NOW=1631552464") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@sub-record-check:16] Set("SIP/400-00001c9e", "__DAY=13") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@sub-record-check:17] Set("SIP/400-00001c9e", "__MONTH=09") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@sub-record-check:18] Set("SIP/400-00001c9e", "__YEAR=2021") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@sub-record-check:19] Set("SIP/400-00001c9e", "__TIMESTR=20210913-130104") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@sub-record-check:20] Set("SIP/400-00001c9e", "__FROMEXTEN=400") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@sub-record-check:21] Set("SIP/400-00001c9e", "__CALLFILENAME=out-123456789-400-20210913-130104-1631552464.14428") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@sub-record-check:22] Goto("SIP/400-00001c9e", "out,1") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Goto (sub-record-check,out,1)
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [out@sub-record-check:1] ExecIf("SIP/400-00001c9e", "1?Set(__REC_POLICY_MODE=always)") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [out@sub-record-check:2] GosubIf("SIP/400-00001c9e", "1?record,1(exten,123456789,400)") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [record@sub-record-check:1] Set("SIP/400-00001c9e", "AUDIOHOOK_INHERIT(MixMonitor)=yes") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [record@sub-record-check:2] MixMonitor("SIP/400-00001c9e", "2021/09/13/out-123456789-400-20210913-130104-1631552464.14428.wav,,") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [record@sub-record-check:3] Set("SIP/400-00001c9e", "__REC_STATUS=RECORDING") in new stack
[2021-09-13 13:01:04] VERBOSE[55223][C-00000f66] app_mixmonitor.c:   == Begin MixMonitor Recording SIP/400-00001c9e
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [record@sub-record-check:4] Set("SIP/400-00001c9e", "CDR(recordingfile)=out-123456789-400-20210913-130104-1631552464.14428.wav") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [record@sub-record-check:5] Return("SIP/400-00001c9e", "") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [out@sub-record-check:3] Return("SIP/400-00001c9e", "") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [123456789@Deny:8] Macro("SIP/400-00001c9e", "dialout-trunk,2,1123456789,,off") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@macro-dialout-trunk:1] Set("SIP/400-00001c9e", "DIAL_TRUNK=2") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@macro-dialout-trunk:2] GosubIf("SIP/400-00001c9e", "0?sub-pincheck,s,1()") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@macro-dialout-trunk:3] GotoIf("SIP/400-00001c9e", "0?disabletrunk,1") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@macro-dialout-trunk:4] Set("SIP/400-00001c9e", "DIAL_NUMBER=1123456789") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@macro-dialout-trunk:5] Set("SIP/400-00001c9e", "DIAL_TRUNK_OPTIONS=Ttr") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@macro-dialout-trunk:6] Set("SIP/400-00001c9e", "OUTBOUND_GROUP=OUT_2") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@macro-dialout-trunk:7] GotoIf("SIP/400-00001c9e", "1?nomax") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Goto (macro-dialout-trunk,s,9)
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@macro-dialout-trunk:9] GotoIf("SIP/400-00001c9e", "0?skipoutcid") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@macro-dialout-trunk:10] Set("SIP/400-00001c9e", "DIAL_TRUNK_OPTIONS=Tt") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@macro-dialout-trunk:11] Macro("SIP/400-00001c9e", "outbound-callerid,2") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@macro-outbound-callerid:1] ExecIf("SIP/400-00001c9e", "0?Set(CALLERPRES()=)") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@macro-outbound-callerid:2] ExecIf("SIP/400-00001c9e", "0?Set(REALCALLERIDNUM=400)") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@macro-outbound-callerid:3] GotoIf("SIP/400-00001c9e", "1?normcid") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Goto (macro-outbound-callerid,s,6)
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@macro-outbound-callerid:6] Set("SIP/400-00001c9e", "USEROUTCID=ABC DEF<400>") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@macro-outbound-callerid:7] Set("SIP/400-00001c9e", "EMERGENCYCID=") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@macro-outbound-callerid:8] Set("SIP/400-00001c9e", "TRUNKOUTCID=") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@macro-outbound-callerid:9] GotoIf("SIP/400-00001c9e", "1?trunkcid") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Goto (macro-outbound-callerid,s,14)
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@macro-outbound-callerid:14] ExecIf("SIP/400-00001c9e", "0?Set(CALLERID(all)=)") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@macro-outbound-callerid:15] ExecIf("SIP/400-00001c9e", "1?Set(CALLERID(all)=ABC DEF<400>)") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@macro-outbound-callerid:16] ExecIf("SIP/400-00001c9e", "1?Set(CALLERID(all)=987654321)") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@macro-outbound-callerid:17] ExecIf("SIP/400-00001c9e", "0?Set(CALLERPRES()=prohib_passed_screen)") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@macro-outbound-callerid:18] Set("SIP/400-00001c9e", "CDR(outbound_cnum)=987654321") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@macro-outbound-callerid:19] Set("SIP/400-00001c9e", "CDR(outbound_cnam)=") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@macro-dialout-trunk:12] GosubIf("SIP/400-00001c9e", "0?sub-flp-2,s,1()") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@macro-dialout-trunk:13] Set("SIP/400-00001c9e", "OUTNUM=1123456789") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@macro-dialout-trunk:14] Set("SIP/400-00001c9e", "custom=SIP/TRUNKPROVIDER") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@macro-dialout-trunk:15] ExecIf("SIP/400-00001c9e", "0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)Tt)") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@macro-dialout-trunk:16] ExecIf("SIP/400-00001c9e", "0?Set(DIAL_TRUNK_OPTIONS=TtM(confirm))") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@macro-dialout-trunk:17] Macro("SIP/400-00001c9e", "dialout-trunk-predial-hook,") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("SIP/400-00001c9e", "") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@macro-dialout-trunk:18] GotoIf("SIP/400-00001c9e", "0?bypass,1") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@macro-dialout-trunk:19] ExecIf("SIP/400-00001c9e", "1?Set(CONNECTEDLINE(num,i)=1123456789)") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@macro-dialout-trunk:20] ExecIf("SIP/400-00001c9e", "1?Set(CONNECTEDLINE(name,i)=CID:987654321)") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@macro-dialout-trunk:21] GotoIf("SIP/400-00001c9e", "0?customtrunk") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@macro-dialout-trunk:22] Dial("SIP/400-00001c9e", "SIP/TRUNKPROVIDER/1123456789,300,Tt") in new stack
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] netsock2.c:   == Using SIP RTP TOS bits 184
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] netsock2.c:   == Using SIP RTP CoS mark 5
[2021-09-13 13:01:04] VERBOSE[55222][C-00000f66] app_dial.c:     -- Called SIP/TRUNKPROVIDER/1123456789
[2021-09-13 13:01:05] VERBOSE[55222][C-00000f66] app_dial.c:     -- SIP/TRUNKPROVIDER-00001c9f is ringing
[2021-09-13 13:01:05] VERBOSE[55222][C-00000f66] app_dial.c:     -- SIP/TRUNKPROVIDER-00001c9f is making progress passing it to SIP/400-00001c9e
[2021-09-13 13:01:05] VERBOSE[55222][C-00000f66] app_dial.c:     -- SIP/TRUNKPROVIDER-00001c9f answered SIP/400-00001c9e
[2021-09-13 13:01:12] DTMF[55222][C-00000f66] channel.c: DTMF begin '9' received on SIP/400-00001c9e
[2021-09-13 13:01:12] DTMF[55222][C-00000f66] channel.c: DTMF begin passthrough '9' on SIP/400-00001c9e
[2021-09-13 13:01:12] DTMF[55222][C-00000f66] channel.c: DTMF end '9' received on SIP/400-00001c9e, duration 100 ms
[2021-09-13 13:01:12] DTMF[55222][C-00000f66] channel.c: DTMF end accepted with begin '9' on SIP/400-00001c9e
[2021-09-13 13:01:12] DTMF[55222][C-00000f66] channel.c: DTMF end passthrough '9' on SIP/400-00001c9e
[2021-09-13 13:01:12] DTMF[55222][C-00000f66] channel.c: DTMF begin '9' received on SIP/400-00001c9e
[2021-09-13 13:01:12] DTMF[55222][C-00000f66] channel.c: DTMF begin passthrough '9' on SIP/400-00001c9e
[2021-09-13 13:01:12] DTMF[55222][C-00000f66] channel.c: DTMF end '9' received on SIP/400-00001c9e, duration 60 ms
[2021-09-13 13:01:12] DTMF[55222][C-00000f66] channel.c: DTMF end accepted with begin '9' on SIP/400-00001c9e
[2021-09-13 13:01:12] DTMF[55222][C-00000f66] channel.c: DTMF end '9' detected to have actual duration 59 on the wire, emulation will be triggered on SIP/400-00001c9e
[2021-09-13 13:01:12] DTMF[55222][C-00000f66] channel.c: DTMF end '9' has duration 59 but want minimum 80, emulating on SIP/400-00001c9e
[2021-09-13 13:01:12] DTMF[55222][C-00000f66] channel.c: DTMF end emulation of '9' queued on SIP/400-00001c9e
[2021-09-13 13:01:13] DTMF[55222][C-00000f66] channel.c: DTMF begin '2' received on SIP/400-00001c9e
[2021-09-13 13:01:13] DTMF[55222][C-00000f66] channel.c: DTMF begin passthrough '2' on SIP/400-00001c9e
[2021-09-13 13:01:13] DTMF[55222][C-00000f66] channel.c: DTMF end '2' received on SIP/400-00001c9e, duration 110 ms
[2021-09-13 13:01:13] DTMF[55222][C-00000f66] channel.c: DTMF end accepted with begin '2' on SIP/400-00001c9e
[2021-09-13 13:01:13] DTMF[55222][C-00000f66] channel.c: DTMF end passthrough '2' on SIP/400-00001c9e
[2021-09-13 13:01:15] DTMF[55222][C-00000f66] channel.c: DTMF begin '0' received on SIP/400-00001c9e
[2021-09-13 13:01:15] DTMF[55222][C-00000f66] channel.c: DTMF begin passthrough '0' on SIP/400-00001c9e
[2021-09-13 13:01:15] DTMF[55222][C-00000f66] channel.c: DTMF end '0' received on SIP/400-00001c9e, duration 100 ms
[2021-09-13 13:01:15] DTMF[55222][C-00000f66] channel.c: DTMF end accepted with begin '0' on SIP/400-00001c9e
[2021-09-13 13:01:15] DTMF[55222][C-00000f66] channel.c: DTMF end passthrough '0' on SIP/400-00001c9e
[2021-09-13 13:01:15] DTMF[55222][C-00000f66] channel.c: DTMF begin '6' received on SIP/400-00001c9e
[2021-09-13 13:01:15] DTMF[55222][C-00000f66] channel.c: DTMF begin passthrough '6' on SIP/400-00001c9e
[2021-09-13 13:01:15] DTMF[55222][C-00000f66] channel.c: DTMF end '6' received on SIP/400-00001c9e, duration 90 ms
[2021-09-13 13:01:15] DTMF[55222][C-00000f66] channel.c: DTMF end accepted with begin '6' on SIP/400-00001c9e
[2021-09-13 13:01:15] DTMF[55222][C-00000f66] channel.c: DTMF end passthrough '6' on SIP/400-00001c9e
[2021-09-13 13:01:15] DTMF[55222][C-00000f66] channel.c: DTMF begin '1' received on SIP/400-00001c9e
[2021-09-13 13:01:15] DTMF[55222][C-00000f66] channel.c: DTMF begin passthrough '1' on SIP/400-00001c9e
[2021-09-13 13:01:15] DTMF[55222][C-00000f66] channel.c: DTMF end '1' received on SIP/400-00001c9e, duration 90 ms
[2021-09-13 13:01:15] DTMF[55222][C-00000f66] channel.c: DTMF end accepted with begin '1' on SIP/400-00001c9e
[2021-09-13 13:01:15] DTMF[55222][C-00000f66] channel.c: DTMF end passthrough '1' on SIP/400-00001c9e
[2021-09-13 13:01:16] DTMF[55222][C-00000f66] channel.c: DTMF begin '1' received on SIP/400-00001c9e
[2021-09-13 13:01:16] DTMF[55222][C-00000f66] channel.c: DTMF begin passthrough '1' on SIP/400-00001c9e
[2021-09-13 13:01:16] DTMF[55222][C-00000f66] channel.c: DTMF end '1' received on SIP/400-00001c9e, duration 100 ms
[2021-09-13 13:01:16] DTMF[55222][C-00000f66] channel.c: DTMF end accepted with begin '1' on SIP/400-00001c9e
[2021-09-13 13:01:16] DTMF[55222][C-00000f66] channel.c: DTMF end passthrough '1' on SIP/400-00001c9e
[2021-09-13 13:01:17] DTMF[55222][C-00000f66] channel.c: DTMF begin '2' received on SIP/400-00001c9e
[2021-09-13 13:01:17] DTMF[55222][C-00000f66] channel.c: DTMF begin passthrough '2' on SIP/400-00001c9e
[2021-09-13 13:01:17] DTMF[55222][C-00000f66] channel.c: DTMF end '2' received on SIP/400-00001c9e, duration 80 ms
[2021-09-13 13:01:17] DTMF[55222][C-00000f66] channel.c: DTMF end accepted with begin '2' on SIP/400-00001c9e
[2021-09-13 13:01:17] DTMF[55222][C-00000f66] channel.c: DTMF end passthrough '2' on SIP/400-00001c9e
[2021-09-13 13:01:17] DTMF[55222][C-00000f66] channel.c: DTMF begin '1' received on SIP/400-00001c9e
[2021-09-13 13:01:17] DTMF[55222][C-00000f66] channel.c: DTMF begin passthrough '1' on SIP/400-00001c9e
[2021-09-13 13:01:18] DTMF[55222][C-00000f66] channel.c: DTMF end '1' received on SIP/400-00001c9e, duration 90 ms
[2021-09-13 13:01:18] DTMF[55222][C-00000f66] channel.c: DTMF end accepted with begin '1' on SIP/400-00001c9e
[2021-09-13 13:01:18] DTMF[55222][C-00000f66] channel.c: DTMF end passthrough '1' on SIP/400-00001c9e
[2021-09-13 13:01:18] DTMF[55222][C-00000f66] channel.c: DTMF begin '1' received on SIP/400-00001c9e
[2021-09-13 13:01:18] DTMF[55222][C-00000f66] channel.c: DTMF begin passthrough '1' on SIP/400-00001c9e
[2021-09-13 13:01:18] DTMF[55222][C-00000f66] channel.c: DTMF end '1' received on SIP/400-00001c9e, duration 70 ms
[2021-09-13 13:01:18] DTMF[55222][C-00000f66] channel.c: DTMF end accepted with begin '1' on SIP/400-00001c9e
[2021-09-13 13:01:18] DTMF[55222][C-00000f66] channel.c: DTMF end '1' detected to have actual duration 69 on the wire, emulation will be triggered on SIP/400-00001c9e
[2021-09-13 13:01:18] DTMF[55222][C-00000f66] channel.c: DTMF end '1' has duration 69 but want minimum 80, emulating on SIP/400-00001c9e
[2021-09-13 13:01:18] DTMF[55222][C-00000f66] channel.c: DTMF end emulation of '1' queued on SIP/400-00001c9e
[2021-09-13 13:01:18] DTMF[55222][C-00000f66] channel.c: DTMF begin '1' received on SIP/400-00001c9e
[2021-09-13 13:01:18] DTMF[55222][C-00000f66] channel.c: DTMF begin passthrough '1' on SIP/400-00001c9e
[2021-09-13 13:01:18] DTMF[55222][C-00000f66] channel.c: DTMF end '1' received on SIP/400-00001c9e, duration 100 ms
[2021-09-13 13:01:18] DTMF[55222][C-00000f66] channel.c: DTMF end accepted with begin '1' on SIP/400-00001c9e
[2021-09-13 13:01:18] DTMF[55222][C-00000f66] channel.c: DTMF end passthrough '1' on SIP/400-00001c9e
[2021-09-13 13:01:19] DTMF[55222][C-00000f66] channel.c: DTMF begin '#' received on SIP/400-00001c9e
[2021-09-13 13:01:19] DTMF[55222][C-00000f66] channel.c: DTMF begin passthrough '#' on SIP/400-00001c9e
[2021-09-13 13:01:19] DTMF[55222][C-00000f66] channel.c: DTMF end '#' received on SIP/400-00001c9e, duration 70 ms
[2021-09-13 13:01:19] DTMF[55222][C-00000f66] channel.c: DTMF end accepted with begin '#' on SIP/400-00001c9e
[2021-09-13 13:01:19] DTMF[55222][C-00000f66] channel.c: DTMF end '#' detected to have actual duration 69 on the wire, emulation will be triggered on SIP/400-00001c9e
[2021-09-13 13:01:19] DTMF[55222][C-00000f66] channel.c: DTMF end '#' has duration 69 but want minimum 80, emulating on SIP/400-00001c9e
[2021-09-13 13:01:19] DTMF[55222][C-00000f66] channel.c: DTMF end emulation of '#' queued on SIP/400-00001c9e
[2021-09-13 13:01:27] DTMF[55222][C-00000f66] channel.c: DTMF begin '2' received on SIP/400-00001c9e
[2021-09-13 13:01:27] DTMF[55222][C-00000f66] channel.c: DTMF begin passthrough '2' on SIP/400-00001c9e
[2021-09-13 13:01:27] DTMF[55222][C-00000f66] channel.c: DTMF end '2' received on SIP/400-00001c9e, duration 180 ms
[2021-09-13 13:01:27] DTMF[55222][C-00000f66] channel.c: DTMF end accepted with begin '2' on SIP/400-00001c9e
[2021-09-13 13:01:27] DTMF[55222][C-00000f66] channel.c: DTMF end passthrough '2' on SIP/400-00001c9e
[2021-09-13 13:01:27] DTMF[55222][C-00000f66] channel.c: DTMF begin '2' received on SIP/400-00001c9e
[2021-09-13 13:01:27] DTMF[55222][C-00000f66] channel.c: DTMF begin passthrough '2' on SIP/400-00001c9e
[2021-09-13 13:01:27] DTMF[55222][C-00000f66] channel.c: DTMF end '2' received on SIP/400-00001c9e, duration 120 ms
[2021-09-13 13:01:27] DTMF[55222][C-00000f66] channel.c: DTMF end accepted with begin '2' on SIP/400-00001c9e
[2021-09-13 13:01:27] DTMF[55222][C-00000f66] channel.c: DTMF end passthrough '2' on SIP/400-00001c9e
[2021-09-13 13:01:28] DTMF[55222][C-00000f66] channel.c: DTMF begin '6' received on SIP/400-00001c9e
[2021-09-13 13:01:28] DTMF[55222][C-00000f66] channel.c: DTMF begin passthrough '6' on SIP/400-00001c9e
[2021-09-13 13:01:28] DTMF[55222][C-00000f66] channel.c: DTMF end '6' received on SIP/400-00001c9e, duration 120 ms
[2021-09-13 13:01:28] DTMF[55222][C-00000f66] channel.c: DTMF end accepted with begin '6' on SIP/400-00001c9e
[2021-09-13 13:01:28] DTMF[55222][C-00000f66] channel.c: DTMF end passthrough '6' on SIP/400-00001c9e
[2021-09-13 13:01:31] DTMF[55222][C-00000f66] channel.c: DTMF begin '2' received on SIP/400-00001c9e
[2021-09-13 13:01:31] DTMF[55222][C-00000f66] channel.c: DTMF begin passthrough '2' on SIP/400-00001c9e
[2021-09-13 13:01:31] DTMF[55222][C-00000f66] channel.c: DTMF end '2' received on SIP/400-00001c9e, duration 120 ms
[2021-09-13 13:01:31] DTMF[55222][C-00000f66] channel.c: DTMF end accepted with begin '2' on SIP/400-00001c9e
[2021-09-13 13:01:31] DTMF[55222][C-00000f66] channel.c: DTMF end passthrough '2' on SIP/400-00001c9e
[2021-09-13 13:01:32] DTMF[55222][C-00000f66] channel.c: DTMF begin '5' received on SIP/400-00001c9e
[2021-09-13 13:01:32] DTMF[55222][C-00000f66] channel.c: DTMF begin passthrough '5' on SIP/400-00001c9e
[2021-09-13 13:01:32] DTMF[55222][C-00000f66] channel.c: DTMF end '5' received on SIP/400-00001c9e, duration 100 ms
[2021-09-13 13:01:32] DTMF[55222][C-00000f66] channel.c: DTMF end accepted with begin '5' on SIP/400-00001c9e
[2021-09-13 13:01:32] DTMF[55222][C-00000f66] channel.c: DTMF end passthrough '5' on SIP/400-00001c9e
[2021-09-13 13:01:32] DTMF[55222][C-00000f66] channel.c: DTMF begin '5' received on SIP/400-00001c9e
[2021-09-13 13:01:32] DTMF[55222][C-00000f66] channel.c: DTMF begin passthrough '5' on SIP/400-00001c9e
[2021-09-13 13:01:32] DTMF[55222][C-00000f66] channel.c: DTMF end '5' received on SIP/400-00001c9e, duration 110 ms
[2021-09-13 13:01:32] DTMF[55222][C-00000f66] channel.c: DTMF end accepted with begin '5' on SIP/400-00001c9e
[2021-09-13 13:01:32] DTMF[55222][C-00000f66] channel.c: DTMF end passthrough '5' on SIP/400-00001c9e
[2021-09-13 13:01:32] DTMF[55222][C-00000f66] channel.c: DTMF begin '#' received on SIP/400-00001c9e
[2021-09-13 13:01:32] DTMF[55222][C-00000f66] channel.c: DTMF begin passthrough '#' on SIP/400-00001c9e
[2021-09-13 13:01:33] DTMF[55222][C-00000f66] channel.c: DTMF end '#' received on SIP/400-00001c9e, duration 140 ms
[2021-09-13 13:01:33] DTMF[55222][C-00000f66] channel.c: DTMF end accepted with begin '#' on SIP/400-00001c9e
[2021-09-13 13:01:33] DTMF[55222][C-00000f66] channel.c: DTMF end passthrough '#' on SIP/400-00001c9e
[2021-09-13 14:08:53] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [h@macro-dialout-trunk:1] Macro("SIP/400-00001c9e", "hangupcall,") in new stack
[2021-09-13 14:08:53] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@macro-hangupcall:1] GotoIf("SIP/400-00001c9e", "1?theend") in new stack
[2021-09-13 14:08:53] VERBOSE[55222][C-00000f66] pbx.c:     -- Goto (macro-hangupcall,s,3)
[2021-09-13 14:08:53] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@macro-hangupcall:3] ExecIf("SIP/400-00001c9e", "0?Set(CDR(recordingfile)=)") in new stack
[2021-09-13 14:08:53] VERBOSE[55222][C-00000f66] pbx.c:     -- Executing [s@macro-hangupcall:4] Hangup("SIP/400-00001c9e", "") in new stack
[2021-09-13 14:08:53] VERBOSE[55222][C-00000f66] app_macro.c:   == Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'SIP/400-00001c9e' in macro 'hangupcall'
[2021-09-13 14:08:53] VERBOSE[55222][C-00000f66] pbx.c:   == Spawn extension (macro-dialout-trunk, h, 1) exited non-zero on 'SIP/400-00001c9e'
[2021-09-13 14:08:53] VERBOSE[55222][C-00000f66] app_macro.c:   == Spawn extension (macro-dialout-trunk, s, 22) exited non-zero on 'SIP/400-00001c9e' in macro 'dialout-trunk'
[2021-09-13 14:08:53] VERBOSE[55222][C-00000f66] pbx.c:   == Spawn extension (Deny, 123456789, 8) exited non-zero on 'SIP/400-00001c9e'
[2021-09-13 14:08:53] VERBOSE[55223][C-00000f66] app_mixmonitor.c:   == MixMonitor close filestream (mixed)
[2021-09-13 14:08:53] VERBOSE[55223][C-00000f66] app_mixmonitor.c:   == End MixMonitor Recording SIP/400-00001c9e

adell4444 · September 13, 2021, 8:53pm

I don’t see any issues in the log. What is the output of

asterisk -x "cdr show status"

faisalkhan · September 13, 2021, 9:26pm

Call Detail Record (CDR) settings

Logging: Enabled
Mode: Simple
Log unanswered calls: No
Log congestion: No

Registered Backends
mysql
Adaptive ODBC
csv
radius
cdr-custom

adell4444 · September 13, 2021, 9:36pm

mysql -u root -D asteriskcdrdb -e "select * from cdr where channel like 'SIP/400-00001c9e';"

faisalkhan · September 14, 2021, 2:01pm

Can’t find any record for this call in the DB

lgaetz · September 14, 2021, 2:02pm

This is not FreePBX

 123456789@Deny:1

What is context “Deny”?

faisalkhan · September 14, 2021, 2:03pm

Context Deny is a custom context to limit some calls to local Destinations.

whereas 123456789 is a replacement of a dumy number

system · October 15, 2021, 2:03pm

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

Dialed a ZoomMeeting number but can't find in logs

Call Detail Record (CDR) settings

Registered Backends