Well, the issue is that within the 20-second period somebody did answer the phone. I have an eye witness and the call history on the phone indicates the call was answered.
It’s as if the phone is not telling freepbx the call was answered.
Or that freepbx didn’t get the message, or ignored the message.
The call ended up going to voicemail - which is how it’s programmed for when nobody answers. That part worked OK.
[2020-03-02 12:50:08] VERBOSE[25163][C-000024de] app_dial.c: Nobody picked up in 20000 ms
[2020-03-02 12:50:08] VERBOSE[25163][C-000024de] app_stack.c: PJSIP/1600-00002e05 Internal Gosub(crm-hangup,s,1) start
[2020-03-02 12:50:08] VERBOSE[25163][C-000024de] pbx.c: Executing [s@crm-hangup:1] NoOp("PJSIP/1600-00002e05", "Sending Hangup to CRM") in new stack
[2020-03-02 12:50:08] VERBOSE[25163][C-000024de] pbx.c: Executing [s@crm-hangup:2] NoOp("PJSIP/1600-00002e05", "HANGUP CAUSE: 0") in new stack
[2020-03-02 12:50:08] VERBOSE[25163][C-000024de] pbx.c: Executing [s@crm-hangup:3] ExecIf("PJSIP/1600-00002e05", "0?Set(__CRM_VOICEMAIL=)") in new stack
[2020-03-02 12:50:08] VERBOSE[25163][C-000024de] pbx.c: Executing [s@crm-hangup:4] NoOp("PJSIP/1600-00002e05", "MASTER CHANNEL: 1583171388.18452 = 1583171354.18451") in new stack
[2020-03-02 12:50:08] VERBOSE[25163][C-000024de] pbx.c: Executing [s@crm-hangup:5] GotoIf("PJSIP/1600-00002e05", "1?return") in new stack
[2020-03-02 12:50:08] VERBOSE[25163][C-000024de] pbx_builtins.c: Goto (crm-hangup,s,8)
[2020-03-02 12:50:08] VERBOSE[25163][C-000024de] pbx.c: Executing [s@crm-hangup:8] Return("PJSIP/1600-00002e05", "") in new stack
[2020-03-02 12:50:08] VERBOSE[25163][C-000024de] app_stack.c: Spawn extension (from-internal, 0, 1) exited non-zero on 'PJSIP/1600-00002e05'
[2020-03-02 12:50:08] VERBOSE[25163][C-000024de] app_stack.c: PJSIP/1600-00002e05 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
[2020-03-02 12:50:08] VERBOSE[25163][C-000024de] app_stack.c: PJSIP/1601-00002e06 Internal Gosub(crm-hangup,s,1) start
[2020-03-02 12:50:08] VERBOSE[25163][C-000024de] pbx.c: Executing [s@crm-hangup:1] NoOp("PJSIP/1601-00002e06", "Sending Hangup to CRM") in new stack
[2020-03-02 12:50:08] VERBOSE[25163][C-000024de] pbx.c: Executing [s@crm-hangup:2] NoOp("PJSIP/1601-00002e06", "HANGUP CAUSE: 0") in new stack
[2020-03-02 12:50:08] VERBOSE[25163][C-000024de] pbx.c: Executing [s@crm-hangup:3] ExecIf("PJSIP/1601-00002e06", "0?Set(__CRM_VOICEMAIL=)") in new stack
[2020-03-02 12:50:08] VERBOSE[25163][C-000024de] pbx.c: Executing [s@crm-hangup:4] NoOp("PJSIP/1601-00002e06", "MASTER CHANNEL: 1583171388.18453 = 1583171354.18451") in new stack
[2020-03-02 12:50:08] VERBOSE[25163][C-000024de] pbx.c: Executing [s@crm-hangup:5] GotoIf("PJSIP/1601-00002e06", "1?return") in new stack
[2020-03-02 12:50:08] VERBOSE[25163][C-000024de] pbx_builtins.c: Goto (crm-hangup,s,8)
[2020-03-02 12:50:08] VERBOSE[25163][C-000024de] pbx.c: Executing [s@crm-hangup:8] Return("PJSIP/1601-00002e06", "") in new stack
[2020-03-02 12:50:08] VERBOSE[25163][C-000024de] app_stack.c: Spawn extension (from-internal, 0, 1) exited non-zero on 'PJSIP/1601-00002e06'
[2020-03-02 12:50:08] VERBOSE[25163][C-000024de] app_stack.c: PJSIP/1601-00002e06 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
[2020-03-02 12:50:08] VERBOSE[25163][C-000024de] pbx.c: Executing [s@macro-dial:24] Set("DAHDI/1-1", "DIALSTATUS=NOANSWER") in new stack
[2020-03-02 12:50:08] VERBOSE[25163][C-000024de] pbx.c: Executing [s@macro-dial:25] GosubIf("DAHDI/1-1", "0?NOANSWER,1()") in new stack
[2020-03-02 12:50:08] VERBOSE[25163][C-000024de] pbx.c: Executing [s@macro-dial:26] NoOp("DAHDI/1-1", "Returning since nobody answered") in new stack
[2020-03-02 12:50:08] VERBOSE[25163][C-000024de] pbx.c: Executing [s@macro-dial:27] MacroExit("DAHDI/1-1", "") in new stack
[2020-03-02 12:50:08] VERBOSE[25163][C-000024de] pbx.c: Executing [0@ext-group:19] Gosub("DAHDI/1-1", "sub-record-cancel,s,1()") in new stack
[2020-03-02 12:50:08] VERBOSE[25163][C-000024de] pbx.c: Executing [s@sub-record-cancel:1] Return("DAHDI/1-1", "") in new stack
[2020-03-02 12:50:08] VERBOSE[25163][C-000024de] pbx.c: Executing [0@ext-group:20] Set("DAHDI/1-1", "RingGroupMethod=") in new stack
[2020-03-02 12:50:08] VERBOSE[25163][C-000024de] pbx.c: Executing [0@ext-group:21] GotoIf("DAHDI/1-1", "0?nodest") in new stack
[2020-03-02 12:50:08] VERBOSE[25163][C-000024de] pbx.c: Executing [0@ext-group:22] Set("DAHDI/1-1", "__NODEST=") in new stack
[2020-03-02 12:50:08] VERBOSE[25163][C-000024de] pbx.c: Executing [0@ext-group:23] Macro("DAHDI/1-1", "blkvm-clr,") in new stack
[2020-03-02 12:50:08] VERBOSE[25163][C-000024de] pbx.c: Executing [s@macro-blkvm-clr:1] Set("DAHDI/1-1", "SHARED(BLKVM,DAHDI/1-1)=") in new stack
[2020-03-02 12:50:08] VERBOSE[25163][C-000024de] pbx.c: Executing [s@macro-blkvm-clr:2] Set("DAHDI/1-1", "GOSUB_RETVAL=") in new stack
[2020-03-02 12:50:08] VERBOSE[25163][C-000024de] pbx.c: Executing [s@macro-blkvm-clr:3] MacroExit("DAHDI/1-1", "") in new stack
[2020-03-02 12:50:08] VERBOSE[25163][C-000024de] pbx.c: Executing [0@ext-group:24] Goto("DAHDI/1-1", "ext-local,vmu990,1") in new stack
[2020-03-02 12:50:08] VERBOSE[25163][C-000024de] pbx_builtins.c: Goto (ext-local,vmu990,1)