FreePBX | Register | Issues | Wiki | Portal | Support

Follow me not working


(Patrick Wilson) #1

I set up a ring group that when no one answers should go to a cell phone number. It seems like it’s trying to find the cell phone, but it fails. Here are the logs. Any ideas?

[2018-02-13 14:26:16] VERBOSE[10803][C-000000ac] pbx.c: Executing [705@ext-queues:34] Set("SIP/jaso_qsip3-00000094", "VQ_CONFIRMMSG=") in new stack
[2018-02-13 14:26:16] VERBOSE[10803][C-000000ac] pbx.c: Executing [705@ext-queues:35] ExecIf("SIP/jaso_qsip3-00000094", "0?Playback(, )") in new stack
[2018-02-13 14:26:16] VERBOSE[10803][C-000000ac] pbx.c: Executing [705@ext-queues:36] QueueLog("SIP/jaso_qsip3-00000094", "705,1518531964.345,NONE,DID,8884897169") in new stack
[2018-02-13 14:26:16] VERBOSE[10803][C-000000ac] pbx.c: Executing [705@ext-queues:37] Set("SIP/jaso_qsip3-00000094", "QAANNOUNCE=") in new stack
[2018-02-13 14:26:16] VERBOSE[10803][C-000000ac] pbx.c: Executing [705@ext-queues:38] Set("SIP/jaso_qsip3-00000094", "VQ_AANNOUNCE=") in new stack
[2018-02-13 14:26:16] VERBOSE[10803][C-000000ac] pbx.c: Executing [705@ext-queues:39] Set("SIP/jaso_qsip3-00000094", "QMOH=") in new stack
[2018-02-13 14:26:16] VERBOSE[10803][C-000000ac] pbx.c: Executing [705@ext-queues:40] Set("SIP/jaso_qsip3-00000094", "VQ_MOH=") in new stack
[2018-02-13 14:26:16] VERBOSE[10803][C-000000ac] pbx.c: Executing [705@ext-queues:41] ExecIf("SIP/jaso_qsip3-00000094", "0?Set(__MOHCLASS=)") in new stack
[2018-02-13 14:26:16] VERBOSE[10803][C-000000ac] pbx.c: Executing [705@ext-queues:42] ExecIf("SIP/jaso_qsip3-00000094", "0?Set(CHANNEL(musicclass)=)") in new stack
[2018-02-13 14:26:16] VERBOSE[10803][C-000000ac] pbx.c: Executing [705@ext-queues:43] Set("SIP/jaso_qsip3-00000094", "QMAXWAIT=") in new stack
[2018-02-13 14:26:16] VERBOSE[10803][C-000000ac] pbx.c: Executing [705@ext-queues:44] Set("SIP/jaso_qsip3-00000094", "VQ_MAXWAIT=") in new stack
[2018-02-13 14:26:16] VERBOSE[10803][C-000000ac] pbx.c: Executing [705@ext-queues:45] Set("SIP/jaso_qsip3-00000094", "QUEUENUM=705") in new stack
[2018-02-13 14:26:16] VERBOSE[10803][C-000000ac] pbx.c: Executing [705@ext-queues:46] Set("SIP/jaso_qsip3-00000094", "QUEUEJOINTIME=1518531976") in new stack
[2018-02-13 14:26:16] VERBOSE[10803][C-000000ac] pbx.c: Executing [705@ext-queues:47] Queue("SIP/jaso_qsip3-00000094", "705,t,,,,,,,,") in new stack
[2018-02-13 14:26:16] VERBOSE[10803][C-000000ac] res_musiconhold.c: Started music on hold, class 'default', on channel 'SIP/jaso_qsip3-00000094'
[2018-02-13 14:26:16] VERBOSE[10808][C-000000ac] res_agi.c: <Local/FMGL-3304402633#@from-internal-00000038;2>AGI Script attendedtransfer-rec-restart.php completed, returning 0
[2018-02-13 14:26:16] VERBOSE[10808][C-000000ac] pbx.c: Executing [s@macro-hangupcall:6] Hangup("Local/FMGL-3304402633#@from-internal-00000038;2", "") in new stack
[2018-02-13 14:26:16] VERBOSE[10808][C-000000ac] app_macro.c: Spawn extension (macro-hangupcall, s, 6) exited non-zero on 'Local/FMGL-3304402633#@from-internal-00000038;2' in macro 'hangupcall'
[2018-02-13 14:26:16] VERBOSE[10808][C-000000ac] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on 'Local/FMGL-3304402633#@from-internal-00000038;2'
[2018-02-13 14:26:45] VERBOSE[10803][C-000000ac] res_musiconhold.c: Stopped music on hold on SIP/jaso_qsip3-00000094
[2018-02-13 14:26:45] VERBOSE[10803][C-000000ac] pbx.c: Spawn extension (ext-queues, 705, 47) exited non-zero on 'SIP/jaso_qsip3-00000094'
[2018-02-13 14:26:45] VERBOSE[10803][C-000000ac] pbx.c: Executing [h@ext-queues:1] Macro("SIP/jaso_qsip3-00000094", "hangupcall,") in new stack
[2018-02-13 14:26:45] VERBOSE[10803][C-000000ac] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("SIP/jaso_qsip3-00000094", "1?theend") in new stack
[2018-02-13 14:26:45] VERBOSE[10803][C-000000ac] pbx_builtins.c: Goto (macro-hangupcall,s,3)
[2018-02-13 14:26:45] VERBOSE[10803][C-000000ac] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("SIP/jaso_qsip3-00000094", "0?Set(CDR(recordingfile)=)") in new stack
[2018-02-13 14:26:45] VERBOSE[10803][C-000000ac] pbx.c: Executing [s@macro-hangupcall:4] NoOp("SIP/jaso_qsip3-00000094", " monior file= ") in new stack
[2018-02-13 14:26:45] VERBOSE[10803][C-000000ac] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("SIP/jaso_qsip3-00000094", "0?Set(CDR(recordingfile)=)") in new stack
[2018-02-13 14:26:45] VERBOSE[10803][C-000000ac] pbx.c: Executing [s@macro-hangupcall:4] NoOp("SIP/jaso_qsip3-00000094", " monior file= ") in new stack
[2018-02-13 14:26:45] VERBOSE[10803][C-000000ac] pbx.c: Executing [s@macro-hangupcall:5] AGI("SIP/jaso_qsip3-00000094", "attendedtransfer-rec-restart.php,,") in new stack
[2018-02-13 14:26:45] VERBOSE[10803][C-000000ac] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/attendedtransfer-rec-restart.php
[2018-02-13 14:26:46] VERBOSE[10803][C-000000ac] res_agi.c: <SIP/jaso_qsip3-00000094>AGI Script attendedtransfer-rec-restart.php completed, returning 0
[2018-02-13 14:26:46] VERBOSE[10803][C-000000ac] pbx.c: Executing [s@macro-hangupcall:6] Hangup("SIP/jaso_qsip3-00000094", "") in new stack
[2018-02-13 14:26:46] VERBOSE[10803][C-000000ac] app_macro.c: Spawn extension (macro-hangupcall, s, 6) exited non-zero on 'SIP/jaso_qsip3-00000094' in macro 'hangupcall'
[2018-02-13 14:26:46] VERBOSE[10803][C-000000ac] pbx.c: Spawn extension (ext-queues, h, 1) exited non-zero on 'SIP/jaso_qsip3-00000094'
[2018-02-13 14:26:46] VERBOSE[10803][C-000000ac] app_stack.c: SIP/jaso_qsip3-00000094 Internal Gosub(crm-hangup,s,1) start
[2018-02-13 14:26:46] VERBOSE[10803][C-000000ac] pbx.c: Executing [s@crm-hangup:1] NoOp("SIP/jaso_qsip3-00000094", "Sending Hangup to CRM") in new stack
[2018-02-13 14:26:46] VERBOSE[10803][C-000000ac] pbx.c: Executing [s@crm-hangup:2] NoOp("SIP/jaso_qsip3-00000094", "HANGUP CAUSE: 16") in new stack
[2018-02-13 14:26:46] VERBOSE[10803][C-000000ac] pbx.c: Executing [s@crm-hangup:3] ExecIf("SIP/jaso_qsip3-00000094", "0?Set(__CRM_VOICEMAIL=)") in new stack
[2018-02-13 14:26:46] VERBOSE[10803][C-000000ac] pbx.c: Executing [s@crm-hangup:4] NoOp("SIP/jaso_qsip3-00000094", "MASTER CHANNEL: 1518531964.345 = 1518531964.345") in new stack
[2018-02-13 14:26:46] VERBOSE[10803][C-000000ac] pbx.c: Executing [s@crm-hangup:5] GotoIf("SIP/jaso_qsip3-00000094", "0?return") in new stack
[2018-02-13 14:26:46] VERBOSE[10803][C-000000ac] pbx.c: Executing [s@crm-hangup:6] Set("SIP/jaso_qsip3-00000094", "__CRM_HANGUP=1") in new stack
[2018-02-13 14:26:46] VERBOSE[10803][C-000000ac] pbx.c: Executing [s@crm-hangup:7] AGI("SIP/jaso_qsip3-00000094", "sangomacrm.agi") in new stack
[2018-02-13 14:26:46] VERBOSE[10803][C-000000ac] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/sangomacrm.agi
[2018-02-13 14:26:46] VERBOSE[10803][C-000000ac] res_agi.c: <SIP/jaso_qsip3-00000094>AGI Script sangomacrm.agi completed, returning 0
[2018-02-13 14:26:46] VERBOSE[10803][C-000000ac] pbx.c: Executing [s@crm-hangup:8] Return("SIP/jaso_qsip3-00000094", "") in new stack
[2018-02-13 14:26:46] VERBOSE[10803][C-000000ac] app_stack.c: Spawn extension (ext-queues, h, 1) exited non-zero on 'SIP/jaso_qsip3-00000094'
[2018-02-13 14:26:46] VERBOSE[10803][C-000000ac] app_stack.c: SIP/jaso_qsip3-00000094 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=

(Lorne Gaetz) #2

The log lines don’t bear even a vague resemblance to your description. It appears you are sending callers from a queue to ring extension 705, which in turn sends the call back to the same queue in some sort of loop. There is no ring group and there is no external call attempt.


(Patrick Wilson) #3

How about now? I removed Queue 705 from the equation:

 [2018-02-13 18:41:24] VERBOSE[11334][C-000000b4] pbx.c: Executing [s-NOANSWER@macro-simple-dial:1] NoOp("Local/FMPR-2224@from-internal-0000004c;2", "Extension is reporting s-NOANSWER") in new stack
[2018-02-13 18:41:24] VERBOSE[11334][C-000000b4] pbx.c: Executing [FMPR-2224@from-internal:6] ExecIf("Local/FMPR-2224@from-internal-0000004c;2", "0?Set(SHARED(FM_DND,SIP/jaso_qsip3-0000009b)=DND)") in new stack
[2018-02-13 18:41:24] VERBOSE[11334][C-000000b4] pbx.c: Executing [FMPR-2224@from-internal:7] Hangup("Local/FMPR-2224@from-internal-0000004c;2", "") in new stack
[2018-02-13 18:41:24] VERBOSE[11334][C-000000b4] pbx.c: Spawn extension (from-internal, FMPR-2224, 7) exited non-zero on 'Local/FMPR-2224@from-internal-0000004c;2'
[2018-02-13 18:41:24] VERBOSE[11334][C-000000b4] pbx.c: Executing [h@from-internal:1] Macro("Local/FMPR-2224@from-internal-0000004c;2", "hangupcall") in new stack
[2018-02-13 18:41:24] VERBOSE[11334][C-000000b4] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("Local/FMPR-2224@from-internal-0000004c;2", "1?theend") in new stack
[2018-02-13 18:41:24] VERBOSE[11334][C-000000b4] pbx_builtins.c: Goto (macro-hangupcall,s,3)
[2018-02-13 18:41:24] VERBOSE[11334][C-000000b4] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("Local/FMPR-2224@from-internal-0000004c;2", "0?Set(CDR(recordingfile)=)") in new stack
[2018-02-13 18:41:24] VERBOSE[11334][C-000000b4] pbx.c: Executing [s@macro-hangupcall:4] NoOp("Local/FMPR-2224@from-internal-0000004c;2", " monior file= ") in new stack
[2018-02-13 18:41:24] VERBOSE[11334][C-000000b4] pbx.c: Executing [s@macro-hangupcall:5] AGI("Local/FMPR-2224@from-internal-0000004c;2", "attendedtransfer-rec-restart.php,,") in new stack
[2018-02-13 18:41:24] VERBOSE[11334][C-000000b4] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/attendedtransfer-rec-restart.php
[2018-02-13 18:41:24] VERBOSE[11334][C-000000b4] res_agi.c: <Local/FMPR-2224@from-internal-0000004c;2>AGI Script attendedtransfer-rec-restart.php completed, returning 0
[2018-02-13 18:41:24] VERBOSE[11334][C-000000b4] pbx.c: Executing [s@macro-hangupcall:6] Hangup("Local/FMPR-2224@from-internal-0000004c;2", "") in new stack
[2018-02-13 18:41:24] VERBOSE[11334][C-000000b4] app_macro.c: Spawn extension (macro-hangupcall, s, 6) exited non-zero on 'Local/FMPR-2224@from-internal-0000004c;2' in macro 'hangupcall'
[2018-02-13 18:41:24] VERBOSE[11334][C-000000b4] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on 'Local/FMPR-2224@from-internal-0000004c;2'
[2018-02-13 18:41:24] VERBOSE[11331][C-000000b4] app_stack.c: Local/FMPR-2224@from-internal-0000004c;1 Internal Gosub(crm-hangup,s,1) start
[2018-02-13 18:41:24] VERBOSE[11331][C-000000b4] pbx.c: Executing [s@crm-hangup:1] NoOp("Local/FMPR-2224@from-internal-0000004c;1", "Sending Hangup to CRM") in new stack
[2018-02-13 18:41:24] VERBOSE[11331][C-000000b4] pbx.c: Executing [s@crm-hangup:2] NoOp("Local/FMPR-2224@from-internal-0000004c;1", "HANGUP CAUSE: 16") in new stack
[2018-02-13 18:41:24] VERBOSE[11331][C-000000b4] pbx.c: Executing [s@crm-hangup:3] ExecIf("Local/FMPR-2224@from-internal-0000004c;1", "0?Set(__CRM_VOICEMAIL=)") in new stack
[2018-02-13 18:41:24] VERBOSE[11331][C-000000b4] pbx.c: Executing [s@crm-hangup:4] NoOp("Local/FMPR-2224@from-internal-0000004c;1", "MASTER CHANNEL: 1518547284.411 = 1518547273.408") in new stack
[2018-02-13 18:41:24] VERBOSE[11331][C-000000b4] pbx.c: Executing [s@crm-hangup:5] GotoIf("Local/FMPR-2224@from-internal-0000004c;1", "1?return") in new stack
[2018-02-13 18:41:24] VERBOSE[11331][C-000000b4] pbx_builtins.c: Goto (crm-hangup,s,8)
[2018-02-13 18:41:24] VERBOSE[11331][C-000000b4] pbx.c: Executing [s@crm-hangup:8] Return("Local/FMPR-2224@from-internal-0000004c;1", "") in new stack
[2018-02-13 18:41:24] VERBOSE[11331][C-000000b4] app_stack.c: Spawn extension (from-internal, 2224, 1) exited non-zero on 'Local/FMPR-2224@from-internal-0000004c;1'
[2018-02-13 18:41:24] VERBOSE[11331][C-000000b4] app_stack.c: Local/FMPR-2224@from-internal-0000004c;1 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
[2018-02-13 18:41:25] VERBOSE[11335][C-000000b4] pbx.c: Executing [FMGL-3304402633#@from-internal:6] GotoIf("Local/FMGL-3304402633#@from-internal-0000004d;2", "1?start") in new stack
[2018-02-13 18:41:25] VERBOSE[11335][C-000000b4] pbx_builtins.c: Goto (from-internal,FMGL-3304402633#,4)
[2018-02-13 18:41:25] VERBOSE[11335][C-000000b4] pbx.c: Executing [FMGL-3304402633#@from-internal:4] GotoIf("Local/FMGL-3304402633#@from-internal-0000004d;2", "0?dodnd") in new stack
[2018-02-13 18:41:25] VERBOSE[11335][C-000000b4] pbx.c: Executing [FMGL-3304402633#@from-internal:5] Wait("Local/FMGL-3304402633#@from-internal-0000004d;2", "1") in new stack
[2018-02-13 18:41:26] VERBOSE[11331][C-000000b4] app_dial.c: Nobody picked up in 2000 ms
[2018-02-13 18:41:26] VERBOSE[11331][C-000000b4] app_stack.c: Local/FMGL-3304402633#@from-internal-0000004d;1 Internal Gosub(crm-hangup,s,1) start
[2018-02-13 18:41:26] VERBOSE[11331][C-000000b4] pbx.c: Executing [s@crm-hangup:1] NoOp("Local/FMGL-3304402633#@from-internal-0000004d;1", "Sending Hangup to CRM") in new stack
[2018-02-13 18:41:26] VERBOSE[11331][C-000000b4] pbx.c: Executing [s@crm-hangup:2] NoOp("Local/FMGL-3304402633#@from-internal-0000004d;1", "HANGUP CAUSE: 0") in new stack
[2018-02-13 18:41:26] VERBOSE[11331][C-000000b4] pbx.c: Executing [s@crm-hangup:3] ExecIf("Local/FMGL-3304402633#@from-internal-0000004d;1", "0?Set(__CRM_VOICEMAIL=)") in new stack
[2018-02-13 18:41:26] VERBOSE[11331][C-000000b4] pbx.c: Executing [s@crm-hangup:4] NoOp("Local/FMGL-3304402633#@from-internal-0000004d;1", "MASTER CHANNEL: 1518547284.413 = 1518547273.408") in new stack
[2018-02-13 18:41:26] VERBOSE[11331][C-000000b4] pbx.c: Executing [s@crm-hangup:5] GotoIf("Local/FMGL-3304402633#@from-internal-0000004d;1", "1?return") in new stack
[2018-02-13 18:41:26] VERBOSE[11331][C-000000b4] pbx_builtins.c: Goto (crm-hangup,s,8)
[2018-02-13 18:41:26] VERBOSE[11331][C-000000b4] pbx.c: Executing [s@crm-hangup:8] Return("Local/FMGL-3304402633#@from-internal-0000004d;1", "") in new stack
[2018-02-13 18:41:26] VERBOSE[11331][C-000000b4] app_stack.c: Spawn extension (from-internal, 2224, 1) exited non-zero on 'Local/FMGL-3304402633#@from-internal-0000004d;1'
[2018-02-13 18:41:26] VERBOSE[11331][C-000000b4] app_stack.c: Local/FMGL-3304402633#@from-internal-0000004d;1 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
[2018-02-13 18:41:26] VERBOSE[11331][C-000000b4] pbx.c: Executing [s@macro-dial:23] Set("SIP/jaso_qsip3-0000009b", "DIALSTATUS=NOANSWER") in new stack
[2018-02-13 18:41:26] VERBOSE[11331][C-000000b4] pbx.c: Executing [s@macro-dial:24] GosubIf("SIP/jaso_qsip3-0000009b", "0?NOANSWER,1()") in new stack
[2018-02-13 18:41:26] VERBOSE[11331][C-000000b4] pbx.c: Executing [s@macro-dial:25] NoOp("SIP/jaso_qsip3-0000009b", "Returning since nobody answered") in new stack
[2018-02-13 18:41:26] VERBOSE[11331][C-000000b4] pbx.c: Executing [s@macro-dial:26] MacroExit("SIP/jaso_qsip3-0000009b", "") in new stack
[2018-02-13 18:41:26] VERBOSE[11331][C-000000b4] pbx.c: Executing [2224@followme-sub:41] Goto("SIP/jaso_qsip3-0000009b", "nextstep") in new stack
[2018-02-13 18:41:26] VERBOSE[11331][C-000000b4] pbx_builtins.c: Goto (followme-sub,2224,46)
[2018-02-13 18:41:26] VERBOSE[11331][C-000000b4] pbx.c: Executing [2224@followme-sub:46] Set("SIP/jaso_qsip3-0000009b", "RingGroupMethod=") in new stack
[2018-02-13 18:41:26] VERBOSE[11331][C-000000b4] pbx.c: Executing [2224@followme-sub:47] GotoIf("SIP/jaso_qsip3-0000009b", "0?nodest") in new stack
[2018-02-13 18:41:26] VERBOSE[11331][C-000000b4] pbx.c: Executing [2224@followme-sub:48] Set("SIP/jaso_qsip3-0000009b", "__NODEST=") in new stack
[2018-02-13 18:41:26] VERBOSE[11331][C-000000b4] pbx.c: Executing [2224@followme-sub:49] Set("SIP/jaso_qsip3-0000009b", "__PICKUPMARK=") in new stack
[2018-02-13 18:41:26] VERBOSE[11331][C-000000b4] pbx.c: Executing [2224@followme-sub:50] Macro("SIP/jaso_qsip3-0000009b", "blkvm-clr,") in new stack
[2018-02-13 18:41:26] VERBOSE[11331][C-000000b4] pbx.c: Executing [s@macro-blkvm-clr:1] Set("SIP/jaso_qsip3-0000009b", "SHARED(BLKVM,SIP/jaso_qsip3-0000009b)=") in new stack
[2018-02-13 18:41:26] VERBOSE[11335][C-000000b4] pbx.c: Executing [h@from-internal:1] Macro("Local/FMGL-3304402633#@from-internal-0000004d;2", "hangupcall") in new stack
[2018-02-13 18:41:26] VERBOSE[11331][C-000000b4] pbx.c: Executing [s@macro-blkvm-clr:1] Set("SIP/jaso_qsip3-0000009b", "SHARED(BLKVM,SIP/jaso_qsip3-0000009b)=") in new stack
[2018-02-13 18:41:26] VERBOSE[11335][C-000000b4] pbx.c: Executing [h@from-internal:1] Macro("Local/FMGL-3304402633#@from-internal-0000004d;2", "hangupcall") in new stack
[2018-02-13 18:41:26] VERBOSE[11331][C-000000b4] pbx.c: Executing [s@macro-blkvm-clr:2] Set("SIP/jaso_qsip3-0000009b", "GOSUB_RETVAL=") in new stack
[2018-02-13 18:41:26] VERBOSE[11331][C-000000b4] pbx.c: Executing [s@macro-blkvm-clr:3] MacroExit("SIP/jaso_qsip3-0000009b", "") in new stack
[2018-02-13 18:41:26] VERBOSE[11335][C-000000b4] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("Local/FMGL-3304402633#@from-internal-0000004d;2", "1?theend") in new stack
[2018-02-13 18:41:26] VERBOSE[11331][C-000000b4] pbx.c: Executing [2224@followme-sub:51] Set("SIP/jaso_qsip3-0000009b", "DIALSTATUS=CHANUNAVAIL") in new stack
[2018-02-13 18:41:26] VERBOSE[11331][C-000000b4] pbx.c: Executing [2224@followme-sub:52] GotoIf("SIP/jaso_qsip3-0000009b", "0?dohangup") in new stack
[2018-02-13 18:41:26] VERBOSE[11331][C-000000b4] pbx.c: Executing [2224@followme-sub:53] Goto("SIP/jaso_qsip3-0000009b", "ext-local,2224,dest") in new stack
[2018-02-13 18:41:26] VERBOSE[11331][C-000000b4] pbx_builtins.c: Goto (ext-local,2224,3)
[2018-02-13 18:41:26] VERBOSE[11331][C-000000b4] pbx.c: Executing [2224@ext-local:3] Set("SIP/jaso_qsip3-0000009b", "__PICKUPMARK=") in new stack
[2018-02-13 18:41:26] VERBOSE[11331][C-000000b4] pbx.c: Executing [2224@ext-local:4] Goto("SIP/jaso_qsip3-0000009b", ",return,1") in new stack
[2018-02-13 18:41:26] VERBOSE[11331][C-000000b4] pbx_builtins.c: Goto (ext-local,return,1)
[2018-02-13 18:41:26] WARNING[11331][C-000000b4] pbx.c: Channel 'SIP/jaso_qsip3-0000009b' sent to invalid extension but no invalid handler: context,exten,priority=ext-local,return,1
[2018-02-13 18:41:26] VERBOSE[11335][C-000000b4] pbx_builtins.c: Goto (macro-hangupcall,s,3)
[2018-02-13 18:41:26] VERBOSE[11331][C-000000b4] pbx.c: Executing [h@ext-local:1] Macro("SIP/jaso_qsip3-0000009b", "hangupcall,") in new stack
[2018-02-13 18:41:26] VERBOSE[11331][C-000000b4] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("SIP/jaso_qsip3-0000009b", "1?theend") in new stack
[2018-02-13 18:41:26] VERBOSE[11331][C-000000b4] pbx_builtins.c: Goto (macro-hangupcall,s,3)
[2018-02-13 18:41:26] VERBOSE[11331][C-000000b4] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("SIP/jaso_qsip3-0000009b", "0?Set(CDR(recordingfile)=)") in new stack
[2018-02-13 18:41:26] VERBOSE[11335][C-000000b4] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("Local/FMGL-3304402633#@from-internal-0000004d;2", "0?Set(CDR(recordingfile)=)") in new stack
[2018-02-13 18:41:26] VERBOSE[11331][C-000000b4] pbx.c: Executing [s@macro-hangupcall:4] NoOp("SIP/jaso_qsip3-0000009b", " monior file= ") in new stack
[2018-02-13 18:41:26] VERBOSE[11331][C-000000b4] pbx.c: Executing [s@macro-hangupcall:5] AGI("SIP/jaso_qsip3-0000009b", "attendedtransfer-rec-restart.php,,") in new stack
[2018-02-13 18:41:26] VERBOSE[11331][C-000000b4] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/attendedtransfer-rec-restart.php
[2018-02-13 18:41:26] VERBOSE[11335][C-000000b4] pbx.c: Executing [s@macro-hangupcall:4] NoOp("Local/FMGL-3304402633#@from-internal-0000004d;2", " monior file= ") in new stack
[2018-02-13 18:41:26] VERBOSE[11335][C-000000b4] pbx.c: Executing [s@macro-hangupcall:5] AGI("Local/FMGL-3304402633#@from-internal-0000004d;2", "attendedtransfer-rec-restart.php,,") in new stack
[2018-02-13 18:41:26] VERBOSE[11335][C-000000b4] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/attendedtransfer-rec-restart.php
[2018-02-13 18:41:26] VERBOSE[11331][C-000000b4] res_agi.c: <SIP/jaso_qsip3-0000009b>AGI Script attendedtransfer-rec-restart.php completed, returning 0
[2018-02-13 18:41:26] VERBOSE[11331][C-000000b4] pbx.c: Executing [s@macro-hangupcall:6] Hangup("SIP/jaso_qsip3-0000009b", "") in new stack
[2018-02-13 18:41:26] VERBOSE[11331][C-000000b4] app_macro.c: Spawn extension (macro-hangupcall, s, 6) exited non-zero on 'SIP/jaso_qsip3-0000009b' in macro 'hangupcall'
[2018-02-13 18:41:26] VERBOSE[11331][C-000000b4] pbx.c: Spawn extension (ext-local, h, 1) exited non-zero on 'SIP/jaso_qsip3-0000009b'
[2018-02-13 18:41:26] VERBOSE[11331][C-000000b4] app_stack.c: SIP/jaso_qsip3-0000009b Internal Gosub(crm-hangup,s,1) start
[2018-02-13 18:41:26] VERBOSE[11331][C-000000b4] pbx.c: Executing [s@crm-hangup:1] NoOp("SIP/jaso_qsip3-0000009b", "Sending Hangup to CRM") in new stack
[2018-02-13 18:41:26] VERBOSE[11331][C-000000b4] pbx.c: Executing [s@crm-hangup:2] NoOp("SIP/jaso_qsip3-0000009b", "HANGUP CAUSE: 16") in new stack
[2018-02-13 18:41:26] VERBOSE[11331][C-000000b4] pbx.c: Executing [s@crm-hangup:3] ExecIf("SIP/jaso_qsip3-0000009b", "0?Set(__CRM_VOICEMAIL=)") in new stack
[2018-02-13 18:41:26] VERBOSE[11331][C-000000b4] pbx.c: Executing [s@crm-hangup:4] NoOp("SIP/jaso_qsip3-0000009b", "MASTER CHANNEL: 1518547273.408 = 1518547273.408") in new stack
[2018-02-13 18:41:26] VERBOSE[11331][C-000000b4] pbx.c: Executing [s@crm-hangup:5] GotoIf("SIP/jaso_qsip3-0000009b", "0?return") in new stack
[2018-02-13 18:41:26] VERBOSE[11331][C-000000b4] pbx.c: Executing [s@crm-hangup:6] Set("SIP/jaso_qsip3-0000009b", "__CRM_HANGUP=1") in new stack
[2018-02-13 18:41:26] VERBOSE[11331][C-000000b4] pbx.c: Executing [s@crm-hangup:7] AGI("SIP/jaso_qsip3-0000009b", "sangomacrm.agi") in new stack
[2018-02-13 18:41:26] VERBOSE[11331][C-000000b4] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/sangomacrm.agi
[2018-02-13 18:41:26] VERBOSE[11335][C-000000b4] res_agi.c: <Local/FMGL-3304402633#@from-internal-0000004d;2>AGI Script attendedtransfer-rec-restart.php completed, returning 0
[2018-02-13 18:41:26] VERBOSE[11335][C-000000b4] pbx.c: Executing [s@macro-hangupcall:6] Hangup("Local/FMGL-3304402633#@from-internal-0000004d;2", "") in new stack
[2018-02-13 18:41:26] VERBOSE[11335][C-000000b4] app_macro.c: Spawn extension (macro-hangupcall, s, 6) exited non-zero on 'Local/FMGL-3304402633#@from-internal-0000004d;2' in macro 'hangupcall'
[2018-02-13 18:41:26] VERBOSE[11335][C-000000b4] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on 'Local/FMGL-3304402633#@from-internal-0000004d;2'
[2018-02-13 18:41:26] VERBOSE[11331][C-000000b4] res_agi.c: <SIP/jaso_qsip3-0000009b>AGI Script sangomacrm.agi completed, returning 0
[2018-02-13 18:41:26] VERBOSE[11331][C-000000b4] pbx.c: Executing [s@crm-hangup:8] Return("SIP/jaso_qsip3-0000009b", "") in new stack
[2018-02-13 18:41:26] VERBOSE[11331][C-000000b4] app_stack.c: Spawn extension (ext-local, h, 1) exited non-zero on 'SIP/jaso_qsip3-0000009b'
[2018-02-13 18:41:26] VERBOSE[11331][C-000000b4] app_stack.c: SIP/jaso_qsip3-0000009b Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
[2018-02-13 18:41:27] VERBOSE[30603][C-000000b5] netsock2.c: Using SIP RTP TOS bits 184
[2018-02-13 18:41:27] VERBOSE[30603][C-000000b5] netsock2.c: Using SIP RTP CoS mark 5
[2018-02-13 18:41:27] VERBOSE[11350][C-000000b5] pbx.c: Executing [8884897169@from-pstn-toheader:1] NoOp("SIP/jaso_qsip3-0000009c", "Attempting to extract DID from SIP To header") in new stack
[2018-02-13 18:41:27] VERBOSE[11350][C-000000b5] pbx.c: Executing [8884897169@from-pstn-toheader:2] GotoIf("SIP/jaso_qsip3-0000009c", "1?SIP") in new stack
[2018-02-13 18:41:27] VERBOSE[11350][C-000000b5] pbx_builtins.c: Goto (from-pstn-toheader,8884897169,6)
[2018-02-13 18:41:27] VERBOSE[11350][C-000000b5] pbx.c: Executing [8884897169@from-pstn-toheader:6] Goto("SIP/jaso_qsip3-0000009c", "from-pstn,8884897169,1") in new stack
[2018-02-13 18:41:27] VERBOSE[11350][C-000000b5] pbx_builtins.c: Goto (from-pstn,8884897169,1)
[2018-02-13 18:41:27] VERBOSE[11350][C-000000b5] pbx.c: Executing [8884897169@from-pstn:1] Set("SIP/jaso_qsip3-0000009c", "__DIRECTION=INBOUND") in new stack
[2018-02-13 18:41:27] VERBOSE[11350][C-000000b5] pbx.c: Executing [8884897169@from-pstn:2] Gosub("SIP/jaso_qsip3-0000009c", "sub-record-check,s,1(in,8884897169,dontcare)") in new stack
[2018-02-13 18:41:27] VERBOSE[11350][C-000000b5] pbx.c: Executing [s@sub-record-check:1] GotoIf("SIP/jaso_qsip3-0000009c", "0?initialized") in new stack
[2018-02-13 18:41:27] VERBOSE[11350][C-000000b5] pbx.c: Executing [s@sub-record-check:2] Set("SIP/jaso_qsip3-0000009c", "__REC_STATUS=INITIALIZED") in new stack
[2018-02-13 18:41:27] VERBOSE[11350][C-000000b5] pbx.c: Executing [s@sub-record-check:3] Set("SIP/jaso_qsip3-0000009c", "NOW=1518547287") in new stack

(Patrick Wilson) #4

Upon no answer in the call group, the call should go to ext 2224 which is set to Follow me to the cell phone number.


(Dave Burgess) #5

This looks suspicious, but the log is really hard to read, since it still doesn’t seem to match your description of what you think is happening.


(Patrick Wilson) #6

I got it working. I just completely removed the ring group and the extension that was supposed to be forwarding to the cell phone, and rebuilt them both. Whatever the problem was is now fixed. Thanks!