Hi all, it’s been a few days that i’m on a problem with my freepbx, i’ve discovered that in my queues some user/agent don’t ring.
So i put myself in a test:
queue number:500 → 420: me the only agent at the moment (i’m not in dnd, busy or other)
I call with my cell-phone my pbx → ivr → queue 500 and i don’t ring…
I’m a static agent
In attachments the configuration of my queue (more easy )
Version of my freepbx: FreePBX 15.0.17.43
i’dont understand why my agent don’t ring…i’ve try to put me busy and to go out, close and re-open my client, look my user on UCP panel for see something (no forwarding)…
any suggestions?
thanks all
ENTIRE LOG OF CALL, PART 1
8031 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [in@sub-record-check:3] ExecIf("SIP/02[numberofpbx]-00000021", "10?Set(FROMEXTEN=329[mynumber]5)") in new stack
8032 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [in@sub-record-check:4] Gosub("SIP/02[numberofpbx]-00000021", "recordcheck,1(dontcare,in,s)") in new stack
8033 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("SIP/02[numberofpbx]-00000021", "Starting recording check against dontcare") in new stack
8034 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("SIP/02[numberofpbx]-00000021", "dontcare") in new stack
8035 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
8036 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [recordcheck@sub-record-check:3] Return("SIP/02[numberofpbx]-00000021", "") in new stack
8037 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [in@sub-record-check:5] Return("SIP/02[numberofpbx]-00000021", "") in new stack
8038 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [s@ext-did:3] Set("SIP/02[numberofpbx]-00000021", "CHANNEL(tonezone)=it") in new stack
8039 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [s@ext-did:4] ExecIf("SIP/02[numberofpbx]-00000021", "0?Set(__FROM_DID=s)") in new stack
8040 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [s@ext-did:5] Set("SIP/02[numberofpbx]-00000021", "returnhere=1") in new stack
8041 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [s@ext-did:6] Gosub("SIP/02[numberofpbx]-00000021", "app-blacklist-check,s,1()") in new stack
8042 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [s@app-blacklist-check:1] GotoIf("SIP/02[numberofpbx]-00000021", "0?blacklisted") in new stack
8043 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [s@app-blacklist-check:2] Set("SIP/02[numberofpbx]-00000021", "CALLED_BLACKLIST=1") in new stack
8044 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [s@app-blacklist-check:3] Return("SIP/02[numberofpbx]-00000021", "") in new stack
8045 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [s@ext-did:7] Set("SIP/02[numberofpbx]-00000021", "CDR(did)=from-trunk") in new stack
8046 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [s@ext-did:8] GotoIf("SIP/02[numberofpbx]-00000021", "0?") in new stack
8047 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [s@ext-did:9] ExecIf("SIP/02[numberofpbx]-00000021", "0 ?Set(CALLERID(name)=329[mynumber]5)") in new stack
8048 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [s@ext-did:10] Set("SIP/02[numberofpbx]-00000021", "CHANNEL(musicclass)=Canzoni") in new stack
8049 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [s@ext-did:11] Set("SIP/02[numberofpbx]-00000021", "__MOHCLASS=Canzoni") in new stack
8050 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [s@ext-did:12] Ringing("SIP/02[numberofpbx]-00000021", "") in new stack
8051 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [s@ext-did:13] Set("SIP/02[numberofpbx]-00000021", "__RINGINGSENT=TRUE") in new stack
8052 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [s@ext-did:14] Set("SIP/02[numberofpbx]-00000021", "__REVERSAL_REJECT=FALSE") in new stack
8053 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [s@ext-did:15] GotoIf("SIP/02[numberofpbx]-00000021", "1?post-reverse-charge") in new stack
8054 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx_builtins.c: Goto (ext-did,s,17)
8055 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [s@ext-did:17] NoOp("SIP/02[numberofpbx]-00000021", "") in new stack
8056 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [s@ext-did:18] Set("SIP/02[numberofpbx]-00000021", "__CALLINGNAMEPRES_SV=allowed_not_screened") in new stack
8057 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [s@ext-did:19] Set("SIP/02[numberofpbx]-00000021", "__CALLINGNUMPRES_SV=allowed_not_screened") in new stack
8058 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [s@ext-did:20] Set("SIP/02[numberofpbx]-00000021", "CALLERID(name-pres)=allowed_not_screened") in new stack
8059 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [s@ext-did:21] Set("SIP/02[numberofpbx]-00000021", "CALLERID(num-pres)=allowed_not_screened") in new stack
8060 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [s@ext-did:22] NoOp("SIP/02[numberofpbx]-00000021", "CallerID Entry Point") in new stack
8061 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [s@ext-did:23] Set("SIP/02[numberofpbx]-00000021", "__CRM_DIRECTION=INBOUND") in new stack
8062 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [s@ext-did:24] Set("SIP/02[numberofpbx]-00000021", "__CRM_SOURCE=329[mynumber]5") in new stack
8063 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [s@ext-did:25] Set("SIP/02[numberofpbx]-00000021", "__CRM_LINKEDID=1626244556.622") in new stack
8064 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [s@ext-did:26] AGI("SIP/02[numberofpbx]-00000021", "agi://127.0.0.1/sangomacrm.agi,true") in new stack
8065 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] res_agi.c: <SIP/02[numberofpbx]-00000021>AGI Script agi://127.0.0.1/sangomacrm.agi completed, returning 0
8066 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [s@ext-did:27] ExecIf("SIP/02[numberofpbx]-00000021", "1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
8067 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [s@ext-did:28] Goto("SIP/02[numberofpbx]-00000021", "app-daynight,0,1") in new stack
8068 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx_builtins.c: Goto (app-daynight,0,1)
8069 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [0@app-daynight:1] GotoIf("SIP/02[numberofpbx]-00000021", "0?app-announcement-1,s,1:app-daynight,3,1") in new stack
8070 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx_builtins.c: Goto (app-daynight,3,1)
8071 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [3@app-daynight:1] GotoIf("SIP/02[numberofpbx]-00000021", "0?app-announcement-3,s,1:timeconditions,6,1") in new stack
8072 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx_builtins.c: Goto (timeconditions,6,1)
8073 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [6@timeconditions:1] Set("SIP/02[numberofpbx]-00000021", "DB(TC/6/INUSESTATE)=INUSE") in new stack
8074 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [6@timeconditions:2] Set("SIP/02[numberofpbx]-00000021", "DB(TC/6/NOT_INUSESTATE)=NOT_INUSE") in new stack
8075 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [6@timeconditions:3] NoOp("SIP/02[numberofpbx]-00000021", "TIMENOW: 08:35,Wed,14,Jul") in new stack
8076 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [6@timeconditions:4] NoOp("SIP/02[numberofpbx]-00000021", "TIMEMATCHED: FALSE") in new stack
8077 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [6@timeconditions:5] GotoIfTime("SIP/02[numberofpbx]-00000021", "10:00-11:00,*,11,jan?truestate") in new stack
8078 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [6@timeconditions:6] GotoIf("SIP/02[numberofpbx]-00000021", "0?truegoto") in new stack
8079 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [6@timeconditions:7] ExecIf("SIP/02[numberofpbx]-00000021", "0?Set(DB(TC/6)=)") in new stack
8080 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [6@timeconditions:8] Set("SIP/02[numberofpbx]-00000021", "DEVICE_STATE(Custom:TC6)=INUSE") in new stack
8081 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [6@timeconditions:9] ExecIf("SIP/02[numberofpbx]-00000021", "0?Set(DEVICE_STATE(Custom:TCSTICKY)=INUSE)") in new stack
8082 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [6@timeconditions:10] GotoIf("SIP/02[numberofpbx]-00000021", "1?timeconditions,5,1") in new stack
8083 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx_builtins.c: Goto (timeconditions,5,1)
8084 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [5@timeconditions:1] Set("SIP/02[numberofpbx]-00000021", "DB(TC/5/INUSESTATE)=INUSE") in new stack
8085 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [5@timeconditions:2] Set("SIP/02[numberofpbx]-00000021", "DB(TC/5/NOT_INUSESTATE)=NOT_INUSE") in new stack
8086 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [5@timeconditions:3] NoOp("SIP/02[numberofpbx]-00000021", "TIMENOW: 08:35,Wed,14,Jul") in new stack
8087 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [5@timeconditions:4] NoOp("SIP/02[numberofpbx]-00000021", "TIMEMATCHED: FALSE") in new stack
8088 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [5@timeconditions:5] GotoIfTime("SIP/02[numberofpbx]-00000021", "08:59-17:59,*,6,jan?truestate") in new stack
8089 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [5@timeconditions:6] GotoIf("SIP/02[numberofpbx]-00000021", "0?truegoto") in new stack
8090 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [5@timeconditions:7] ExecIf("SIP/02[numberofpbx]-00000021", "0?Set(DB(TC/5)=)") in new stack
8091 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [5@timeconditions:8] Set("SIP/02[numberofpbx]-00000021", "DEVICE_STATE(Custom:TC5)=INUSE") in new stack
8092 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [5@timeconditions:9] ExecIf("SIP/02[numberofpbx]-00000021", "0?Set(DEVICE_STATE(Custom:TCSTICKY)=INUSE)") in new stack
8093 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [5@timeconditions:10] GotoIf("SIP/02[numberofpbx]-00000021", "1?timeconditions,4,1") in new stack
8094 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx_builtins.c: Goto (timeconditions,4,1)
8095 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [4@timeconditions:1] Set("SIP/02[numberofpbx]-00000021", "DB(TC/4/INUSESTATE)=INUSE") in new stack
8096 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [4@timeconditions:2] Set("SIP/02[numberofpbx]-00000021", "DB(TC/4/NOT_INUSESTATE)=NOT_INUSE") in new stack
8097 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [4@timeconditions:3] NoOp("SIP/02[numberofpbx]-00000021", "TIMENOW: 08:35,Wed,14,Jul") in new stack
8098 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [4@timeconditions:4] NoOp("SIP/02[numberofpbx]-00000021", "TIMEMATCHED: FALSE") in new stack
8099 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [4@timeconditions:5] GotoIfTime("SIP/02[numberofpbx]-00000021", "00:00-23:59,*,20-30,aug?truestate") in new stack
8100 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [4@timeconditions:6] GotoIf("SIP/02[numberofpbx]-00000021", "0?truegoto") in new stack
8101 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [4@timeconditions:7] ExecIf("SIP/02[numberofpbx]-00000021", "0?Set(DB(TC/4)=)") in new stack
8102 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [4@timeconditions:8] Set("SIP/02[numberofpbx]-00000021", "DEVICE_STATE(Custom:TC4)=INUSE") in new stack
8103 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [4@timeconditions:9] ExecIf("SIP/02[numberofpbx]-00000021", "0?Set(DEVICE_STATE(Custom:TCSTICKY)=INUSE)") in new stack
8104 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [4@timeconditions:10] GotoIf("SIP/02[numberofpbx]-00000021", "1?timeconditions,3,1") in new stack
8105 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx_builtins.c: Goto (timeconditions,3,1)
8106 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [3@timeconditions:1] Set("SIP/02[numberofpbx]-00000021", "DB(TC/3/INUSESTATE)=INUSE") in new stack
8107 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [3@timeconditions:2] Set("SIP/02[numberofpbx]-00000021", "DB(TC/3/NOT_INUSESTATE)=NOT_INUSE") in new stack
8108 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [3@timeconditions:3] NoOp("SIP/02[numberofpbx]-00000021", "TIMENOW: 08:35,Wed,14,Jul") in new stack
8109 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [3@timeconditions:4] NoOp("SIP/02[numberofpbx]-00000021", "TIMEMATCHED: FALSE") in new stack
8110 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [3@timeconditions:5] GotoIfTime("SIP/02[numberofpbx]-00000021", "00:01-23:59,*,25,dec?truestate") in new stack
8111 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [3@timeconditions:6] GotoIf("SIP/02[numberofpbx]-00000021", "0?truegoto") in new stack
8112 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [3@timeconditions:7] ExecIf("SIP/02[numberofpbx]-00000021", "0?Set(DB(TC/3)=)") in new stack
8113 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [3@timeconditions:8] Set("SIP/02[numberofpbx]-00000021", "DEVICE_STATE(Custom:TC3)=INUSE") in new stack
8114 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [3@timeconditions:9] ExecIf("SIP/02[numberofpbx]-00000021", "0?Set(DEVICE_STATE(Custom:TCSTICKY)=INUSE)") in new stack
8115 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [3@timeconditions:10] GotoIf("SIP/02[numberofpbx]-00000021", "1?timeconditions,2,1") in new stack
8116 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx_builtins.c: Goto (timeconditions,2,1)
8117 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [2@timeconditions:1] Set("SIP/02[numberofpbx]-00000021", "DB(TC/2/INUSESTATE)=INUSE") in new stack
8118 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [2@timeconditions:2] Set("SIP/02[numberofpbx]-00000021", "DB(TC/2/NOT_INUSESTATE)=NOT_INUSE") in new stack
8119 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [2@timeconditions:3] NoOp("SIP/02[numberofpbx]-00000021", "TIMENOW: 08:35,Wed,14,Jul") in new stack
8120 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [2@timeconditions:4] NoOp("SIP/02[numberofpbx]-00000021", "TIMEMATCHED: TRUE") in new stack
8121 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [2@timeconditions:5] GotoIfTime("SIP/02[numberofpbx]-00000021", "07:29-17:29,mon-sat,*,*?truestate") in new stack
8122 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx_builtins.c: Goto (timeconditions,2,14)
8123 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [2@timeconditions:14] GotoIf("SIP/02[numberofpbx]-00000021", "0?falsegoto") in new stack
8124 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [2@timeconditions:15] ExecIf("SIP/02[numberofpbx]-00000021", "0?Set(DB(TC/2)=)") in new stack
8125 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [2@timeconditions:16] Set("SIP/02[numberofpbx]-00000021", "DEVICE_STATE(Custom:TC2)=NOT_INUSE") in new stack
8126 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [2@timeconditions:17] ExecIf("SIP/02[numberofpbx]-00000021", "0?Set(DEVICE_STATE(Custom:TCSTICKY)=INUSE)") in new stack
8127 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [2@timeconditions:18] GotoIf("SIP/02[numberofpbx]-00000021", "1?app-announcement-2,s,1") in new stack
8128 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx_builtins.c: Goto (app-announcement-2,s,1)
8129 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [s@app-announcement-2:1] GotoIf("SIP/02[numberofpbx]-00000021", "0?begin") in new stack
8130 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [s@app-announcement-2:2] Answer("SIP/02[numberofpbx]-00000021", "") in new stack
8131 [2021-07-14 08:35:56] VERBOSE[7946][C-00000030] pbx.c: Executing [s@app-announcement-2:3] Wait("SIP/02[numberofpbx]-00000021", "1") in new stack
8132 [2021-07-14 08:35:57] VERBOSE[7946][C-00000030] pbx.c: Executing [s@app-announcement-2:4] NoOp("SIP/02[numberofpbx]-00000021", "Playing announcement DGLINE-MSG-BENVENUTO") in new stack
8133 [2021-07-14 08:35:57] VERBOSE[7946][C-00000030] pbx.c: Executing [s@app-announcement-2:5] Playback("SIP/02[numberofpbx]-00000021", "custom/questa_e_l-assistenza_dgline,noanswer") in new stack
8134 [2021-07-14 08:35:57] VERBOSE[7946][C-00000030] file.c: <SIP/02[numberofpbx]-00000021> Playing 'custom/questa_e_l-assistenza_dgline.slin' (language 'it')
8135 [2021-07-14 08:36:00] VERBOSE[7946][C-00000030] pbx.c: Executing [s@app-announcement-2:6] Goto("SIP/02[numberofpbx]-00000021", "ivr-1,s,1") in new stack
8136 [2021-07-14 08:36:00] VERBOSE[7946][C-00000030] pbx_builtins.c: Goto (ivr-1,s,1)
8137 [2021-07-14 08:36:00] VERBOSE[7946][C-00000030] pbx.c: Executing [s@ivr-1:1] Set("SIP/02[numberofpbx]-00000021", "TIMEOUT_LOOPCOUNT=0") in new stack
8138 [2021-07-14 08:36:00] VERBOSE[7946][C-00000030] pbx.c: Executing [s@ivr-1:2] Set("SIP/02[numberofpbx]-00000021", "INVALID_LOOPCOUNT=0") in new stack
8139 [2021-07-14 08:36:00] VERBOSE[7946][C-00000030] pbx.c: Executing [s@ivr-1:3] Set("SIP/02[numberofpbx]-00000021", "_IVR_CONTEXT_ivr-1=") in new stack
8140 [2021-07-14 08:36:00] VERBOSE[7946][C-00000030] pbx.c: Executing [s@ivr-1:4] Set("SIP/02[numberofpbx]-00000021", "_IVR_CONTEXT=ivr-1") in new stack
8141 [2021-07-14 08:36:00] VERBOSE[7946][C-00000030] pbx.c: Executing [s@ivr-1:5] Set("SIP/02[numberofpbx]-00000021", "__IVR_RETVM=") in new stack
8142 [2021-07-14 08:36:00] VERBOSE[7946][C-00000030] pbx.c: Executing [s@ivr-1:6] GotoIf("SIP/02[numberofpbx]-00000021", "1?skip") in new stack
8143 [2021-07-14 08:36:00] VERBOSE[7946][C-00000030] pbx_builtins.c: Goto (ivr-1,s,8)
8144 [2021-07-14 08:36:00] VERBOSE[7946][C-00000030] pbx.c: Executing [s@ivr-1:8] Set("SIP/02[numberofpbx]-00000021", "IVR_MSG=custom/ivr1_premete_1_o_2") in new stack
8145 [2021-07-14 08:36:00] VERBOSE[7946][C-00000030] pbx.c: Executing [s@ivr-1:9] Set("SIP/02[numberofpbx]-00000021", "TIMEOUT(digit)=3") in new stack
8146 [2021-07-14 08:36:00] VERBOSE[7946][C-00000030] func_timeout.c: Digit timeout set to 3.000
8147 [2021-07-14 08:36:00] VERBOSE[7946][C-00000030] pbx.c: Executing [s@ivr-1:10] ExecIf("SIP/02[numberofpbx]-00000021", "1?Background(custom/ivr1_premete_1_o_2)") in new stack
8148 [2021-07-14 08:36:00] VERBOSE[7946][C-00000030] file.c: <SIP/02[numberofpbx]-00000021> Playing 'custom/ivr1_premete_1_o_2.ulaw' (language 'it')
8149 [2021-07-14 08:36:08] VERBOSE[7946][C-00000030] pbx.c: Executing [s@ivr-1:11] WaitExten("SIP/02[numberofpbx]-00000021", "5,") in new stack
8150 [2021-07-14 08:36:13] VERBOSE[7946][C-00000030] pbx_builtins.c: Timeout on SIP/02[numberofpbx]-00000021, going to 't'
8151 [2021-07-14 08:36:13] VERBOSE[7946][C-00000030] pbx.c: Executing [t@ivr-1:1] Set("SIP/02[numberofpbx]-00000021", "TIMEOUT_LOOPCOUNT=1") in new stack
8152 [2021-07-14 08:36:13] VERBOSE[7946][C-00000030] pbx.c: Executing [t@ivr-1:2] GotoIf("SIP/02[numberofpbx]-00000021", "0?final") in new stack
8153 [2021-07-14 08:36:13] VERBOSE[7946][C-00000030] pbx.c: Executing [t@ivr-1:3] Set("SIP/02[numberofpbx]-00000021", "IVR_MSG=custom/ivr1_premete_1_o_2") in new stack
8154 [2021-07-14 08:36:13] VERBOSE[7946][C-00000030] pbx.c: Executing [t@ivr-1:4] Goto("SIP/02[numberofpbx]-00000021", "s,start") in new stack
8155 [2021-07-14 08:36:13] VERBOSE[7946][C-00000030] pbx_builtins.c: Goto (ivr-1,s,9)
8156 [2021-07-14 08:36:13] VERBOSE[7946][C-00000030] pbx.c: Executing [s@ivr-1:9] Set("SIP/02[numberofpbx]-00000021", "TIMEOUT(digit)=3") in new stack
8157 [2021-07-14 08:36:13] VERBOSE[7946][C-00000030] func_timeout.c: Digit timeout set to 3.000
8158 [2021-07-14 08:36:13] VERBOSE[7946][C-00000030] pbx.c: Executing [s@ivr-1:10] ExecIf("SIP/02[numberofpbx]-00000021", "1?Background(custom/ivr1_premete_1_o_2)") in new stack
8159 [2021-07-14 08:36:13] VERBOSE[7946][C-00000030] file.c: <SIP/02[numberofpbx]-00000021> Playing 'custom/ivr1_premete_1_o_2.ulaw' (language 'it')
8160 [2021-07-14 08:36:20] VERBOSE[7946][C-00000030] pbx.c: Executing [s@ivr-1:11] WaitExten("SIP/02[numberofpbx]-00000021", "5,") in new stack
8161 [2021-07-14 08:36:20] VERBOSE[7946][C-00000030] pbx.c: Spawn extension (ivr-1, s, 11) exited non-zero on 'SIP/02[numberofpbx]-00000021'
8162 [2021-07-14 08:36:20] VERBOSE[7946][C-00000030] pbx.c: Executing [h@ivr-1:1] Hangup("SIP/02[numberofpbx]-00000021", "") in new stack
8163 [2021-07-14 08:36:20] VERBOSE[7946][C-00000030] pbx.c: Spawn extension (ivr-1, h, 1) exited non-zero on 'SIP/02[numberofpbx]-00000021'
8164 [2021-07-14 08:36:20] VERBOSE[7946][C-00000030] app_stack.c: SIP/02[numberofpbx]-00000021 Internal Gosub(crm-hangup,s,1) start
8165 [2021-07-14 08:36:20] VERBOSE[7946][C-00000030] pbx.c: Executing [s@crm-hangup:1] NoOp("SIP/02[numberofpbx]-00000021", "Sending Hangup to CRM") in new stack
8166 [2021-07-14 08:36:20] VERBOSE[7946][C-00000030] pbx.c: Executing [s@crm-hangup:2] NoOp("SIP/02[numberofpbx]-00000021", "HANGUP CAUSE: 16") in new stack
8167 [2021-07-14 08:36:20] VERBOSE[7946][C-00000030] pbx.c: Executing [s@crm-hangup:3] ExecIf("SIP/02[numberofpbx]-00000021", "0?Set(__CRM_VOICEMAIL=)") in new stack
8168 [2021-07-14 08:36:20] VERBOSE[7946][C-00000030] pbx.c: Executing [s@crm-hangup:4] NoOp("SIP/02[numberofpbx]-00000021", "MASTER CHANNEL: 1626244556.622 = 1626244556.622") in new stack
8169 [2021-07-14 08:36:20] VERBOSE[7946][C-00000030] pbx.c: Executing [s@crm-hangup:5] GotoIf("SIP/02[numberofpbx]-00000021", "0?return") in new stack