Dialed a ZoomMeeting number but can't find in logs

configuration
Tags: #<Tag:0x00007f4f424812f8>

(FAK) #1

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.


(Andrew) #2

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


(FAK) #3

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


(Andrew) #4

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


(FAK) #5

yes there are 1100 plus calls in CDRs

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


(Andrew) #6
grep UID /var/log/asterisk/full | pastebin

(FAK) #8
[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

(Andrew) #9

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

asterisk -x "cdr show status"

(FAK) #10

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

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

(FAK) #12

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


(Lorne Gaetz) #13

This is not FreePBX

 123456789@Deny:1

What is context “Deny”?


(FAK) #14

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

whereas 123456789 is a replacement of a dumy number