Odd Queue Behavior - Logfile help

Customer reports that a call came into a queue and someone ended up in a queue members VM box (7205). That agent though was set to DND. Looking at the logfiles the queue doesn’t even call 7205. My only thought is that another extension transferred the call there. I’ve attached a brief portion of the logfiles for that call. Any help would be greatly appreciated!

 [2019-06-27 14:04:43] VERBOSE[21198][C-00004406] app_queue.c: Local/[email protected];1 answered SIP/AppiaSIP-000008d5
    [2019-06-27 14:04:43] VERBOSE[21198][C-00004406] file.c: <Local/[email protected];1> Playing 'custom/AllAgents.slin' (language 'en')
    [2019-06-27 14:04:43] VERBOSE[21581][C-00004406] bridge_channel.c: Channel Local/[email protected];1 joined 'simple_bridge' basic-bridge <0cdad956-75df-4d4e-87b0-4f656caced3f>
    [2019-06-27 14:04:43] VERBOSE[21497][C-00004406] bridge_channel.c: Channel Local/[email protected];2 joined 'simple_bridge' basic-bridge <0cdad956-75df-4d4e-87b0-4f656caced3f>
    [2019-06-27 14:04:43] VERBOSE[21580][C-00004406] bridge_channel.c: Channel Local/[email protected];1 joined 'simple_bridge' basic-bridge <7635fe73-f09d-42f3-9234-1ba88dd8140d>
    [2019-06-27 14:04:43] VERBOSE[21499][C-00004406] bridge_channel.c: Channel Local/[email protected];2 joined 'simple_bridge' basic-bridge <7635fe73-f09d-42f3-9234-1ba88dd8140d>
    [2019-06-27 14:04:43] VERBOSE[21581][C-00004406] bridge_channel.c: Channel Local/[email protected];2 left 'simple_bridge' basic-bridge <0cdad956-75df-4d4e-87b0-4f656caced3f>
    [2019-06-27 14:04:43] VERBOSE[21581][C-00004406] bridge_channel.c: Channel Local/[email protected];2 left 'simple_bridge' basic-bridge <7635fe73-f09d-42f3-9234-1ba88dd8140d>
    [2019-06-27 14:04:43] VERBOSE[21581][C-00004406] bridge_channel.c: Channel Local/[email protected];2 swapped with Local/[email protected];2 into 'simple_bridge' basic-bridge <7635fe73-f09d-42f3-9234-1ba88dd8140d>
    [2019-06-27 14:04:43] VERBOSE[21581][C-00004406] bridge_channel.c: Channel Local/[email protected];1 left 'simple_bridge' basic-bridge <0cdad956-75df-4d4e-87b0-4f656caced3f>
    [2019-06-27 14:04:43] VERBOSE[21581][C-00004406] app_stack.c: Local/[email protected];1 Internal Gosub(crm-hangup,s,1) start
    [2019-06-27 14:04:43] VERBOSE[21581][C-00004406] pbx.c: Executing [[email protected]:1] NoOp("Local/[email protected];1", "Sending Hangup to CRM") in new stack
    [2019-06-27 14:04:43] VERBOSE[21581][C-00004406] pbx.c: Executing [[email protected]:2] NoOp("Local/[email protected];1", "HANGUP CAUSE: 16") in new stack
    [2019-06-27 14:04:43] VERBOSE[21581][C-00004406] pbx.c: Executing [[email protected]:3] ExecIf("Local/[email protected];1", "0?Set(__CRM_VOICEMAIL=)") in new stack
    [2019-06-27 14:04:43] VERBOSE[21499][C-00004406] pbx.c: Spawn extension (zulu-call, 907065, 5) exited non-zero on 'Local/[email protected];2'
    [2019-06-27 14:04:43] VERBOSE[21581][C-00004406] pbx.c: Executing [[email protected]:4] NoOp("Local/[email protected];1", "MASTER CHANNEL: 1561662274.33433 = 1561662235.33386") in new stack
    [2019-06-27 14:04:43] VERBOSE[21581][C-00004406] pbx.c: Executing [[email protected]:5] GotoIf("Local/[email protected];1", "1?return") in new stack
    [2019-06-27 14:04:43] VERBOSE[21581][C-00004406] pbx_builtins.c: Goto (crm-hangup,s,8)
    [2019-06-27 14:04:43] VERBOSE[21581][C-00004406] pbx.c: Executing [[email protected]:8] Return("Local/[email protected];1", "") in new stack
    [2019-06-27 14:04:43] VERBOSE[21581][C-00004406] app_stack.c: Spawn extension (macro-dial-one, s, 1) exited non-zero on 'Local/[email protected];1'
    [2019-06-27 14:04:43] VERBOSE[21581][C-00004406] app_stack.c: Local/[email protected];1 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
    [2019-06-27 14:04:43] VERBOSE[21580][C-00004406] bridge_channel.c: Channel Local/[email protected];2 left 'simple_bridge' basic-bridge <7635fe73-f09d-42f3-9234-1ba88dd8140d>
    [2019-06-27 14:04:43] VERBOSE[21580][C-00004406] bridge_channel.c: Channel Local/[email protected];2 left 'simple_bridge' basic-bridge <e65b1047-c513-432f-916f-2c6c7caf3049>
    [2019-06-27 14:04:43] VERBOSE[21580][C-00004406] bridge_channel.c: Channel Local/[email protected];2 swapped with Local/[email protected];2 into 'simple_bridge' basic-bridge <e65b1047-c513-432f-916f-2c6c7caf3049>
    [2019-06-27 14:04:43] VERBOSE[21580][C-00004406] bridge_channel.c: Channel Local/[email protected];1 left 'simple_bridge' basic-bridge <7635fe73-f09d-42f3-9234-1ba88dd8140d>
    [2019-06-27 14:04:43] VERBOSE[21502][C-00004406] pbx.c: Spawn extension (zulu-desktop-phone, 907065, 5) exited non-zero on 'Local/[email protected];2'
    [2019-06-27 14:04:46] VERBOSE[21579][C-00004406] bridge_channel.c: Channel PJSIP/907065-00000687 left 'simple_bridge' basic-bridge <e65b1047-c513-432f-916f-2c6c7caf3049>
    [2019-06-27 14:04:46] VERBOSE[21497][C-00004406] bridge_channel.c: Channel Local/[email protected];2 left 'simple_bridge' basic-bridge <e65b1047-c513-432f-916f-2c6c7caf3049>
    [2019-06-27 14:04:46] VERBOSE[21579][C-00004406] app_stack.c: PJSIP/907065-00000687 Internal Gosub(crm-hangup,s,1) start
    [2019-06-27 14:04:46] VERBOSE[21579][C-00004406] pbx.c: Executing [[email protected]:1] NoOp("PJSIP/907065-00000687", "Sending Hangup to CRM") in new stack
    [2019-06-27 14:04:46] VERBOSE[21579][C-00004406] pbx.c: Executing [[email protected]:2] NoOp("PJSIP/907065-00000687", "HANGUP CAUSE: 16") in new stack
    [2019-06-27 14:04:46] VERBOSE[21497][C-00004406] app_macro.c: Spawn extension (macro-dial-one, s, 60) exited non-zero on 'Local/[email protected];2' in macro 'dial-one'
    [2019-06-27 14:04:46] VERBOSE[21579][C-00004406] pbx.c: Executing [[email protected]:3] ExecIf("PJSIP/907065-00000687", "0?Set(__CRM_VOICEMAIL=)") in new stack
    [2019-06-27 14:04:46] VERBOSE[21497][C-00004406] app_macro.c: Spawn extension (macro-exten-vm, s, 20) exited non-zero on 'Local/[email protected];2' in macro 'exten-vm'
    [2019-06-27 14:04:46] VERBOSE[21579][C-00004406] pbx.c: Executing [[email protected]:4] NoOp("PJSIP/907065-00000687", "MASTER CHANNEL: 1561662275.33443 = 1561662235.33386") in new stack
    [2019-06-27 14:04:46] VERBOSE[21497][C-00004406] pbx.c: Spawn extension (ext-local, 7065, 2) exited non-zero on 'Local/[email protected];2'
    [2019-06-27 14:04:46] VERBOSE[21579][C-00004406] pbx.c: Executing [[email protected]:5] GotoIf("PJSIP/907065-00000687", "1?return") in new stack
    [2019-06-27 14:04:46] VERBOSE[21497][C-00004406] pbx.c: Executing [[email protected]:1] Macro("Local/[email protected];2", "hangupcall,") in new stack
    [2019-06-27 14:04:46] VERBOSE[21497][C-00004406] pbx.c: Executing [[email protected]:1] GotoIf("Local/[email protected];2", "1?theend") in new stack
    [2019-06-27 14:04:46] VERBOSE[21497][C-00004406] pbx_builtins.c: Goto (macro-hangupcall,s,3)
    [2019-06-27 14:04:46] VERBOSE[21497][C-00004406] pbx.c: Executing [[email protected]:3] ExecIf("Local/[email protected];2", "0?Set(CDR(recordingfile)=)") in new stack
    [2019-06-27 14:04:46] VERBOSE[21497][C-00004406] pbx.c: Executing [[email protected]:4] NoOp("Local/[email protected];2", "PJSIP/907065-00000687 montior file= /var/spool/asterisk/monitor/2019/06/27/external-7065-6302272957-20190627-140434-1561662274.33432.wav") in new stack
    [2019-06-27 14:04:46] VERBOSE[21497][C-00004406] pbx.c: Executing [[email protected]:5] GotoIf("Local/[email protected];2", "0?skipagi") in new stack
    [2019-06-27 14:04:46] VERBOSE[21497][C-00004406] pbx.c: Executing [[email protected]:6] AGI("Local/[email protected];2", "attendedtransfer-rec-restart.php,PJSIP/907065-00000687,/var/spool/asterisk/monitor/2019/06/27/external-7065-6302272957-20190627-140434-1561662274.33432.wav") in new stack
    [2019-06-27 14:04:46] VERBOSE[21497][C-00004406] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/attendedtransfer-rec-restart.php
    [2019-06-27 14:04:46] VERBOSE[21579][C-00004406] pbx_builtins.c: Goto (crm-hangup,s,8)
    [2019-06-27 14:04:46] VERBOSE[21579][C-00004406] pbx.c: Executing [[email protected]:8] Return("PJSIP/907065-00000687", "") in new stack
    [2019-06-27 14:04:46] VERBOSE[21579][C-00004406] app_stack.c: Spawn extension (zulu-desktop-phone, 907065, 1) exited non-zero on 'PJSIP/907065-00000687'
    [2019-06-27 14:04:46] VERBOSE[21579][C-00004406] app_stack.c: PJSIP/907065-00000687 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
    [2019-06-27 14:04:46] VERBOSE[21497][C-00004406] res_agi.c: <Local/[email protected];2>AGI Script attendedtransfer-rec-restart.php completed, returning 0
    [2019-06-27 14:04:46] VERBOSE[21497][C-00004406] pbx.c: Executing [[email protected]:7] Hangup("Local/[email protected];2", "") in new stack
    [2019-06-27 14:04:46] VERBOSE[21497][C-00004406] app_macro.c: Spawn extension (macro-hangupcall, s, 7) exited non-zero on 'Local/[email protected];2' in macro 'hangupcall'
    [2019-06-27 14:04:46] VERBOSE[21497][C-00004406] pbx.c: Spawn extension (ext-local, h, 1) exited non-zero on 'Local/[email protected];2'
    [2019-06-27 14:04:46] ERROR[21198][C-00004406] app_queue.c: play_file failed for 'custom/AllAgents' on Local/[email protected];1
    [2019-06-27 14:04:46] VERBOSE[21498][C-00004406] app_mixmonitor.c: MixMonitor close filestream (mixed)
    [2019-06-27 14:04:46] VERBOSE[21498][C-00004406] app_mixmonitor.c: End MixMonitor Recording Local/[email protected];2
    [2019-06-27 14:04:46] WARNING[21198][C-00004406] app_queue.c: Agent on Local/[email protected];1 hungup on the customer.
    [2019-06-27 14:04:51] VERBOSE[21198][C-00004406] app_queue.c: Called Local/[email protected]/n
    [2019-06-27 14:04:51] VERBOSE[21198][C-00004406] app_queue.c: -- LazyMembers debugging - Numbusies: 0, Nummems: 6
    [2019-06-27 14:04:51] VERBOSE[21614][C-00004406] pbx.c: Executing [[email protected]:1] Set("Local/[email protected];2", "QAGENT=7205") in new stack
    [2019-06-27 14:04:51] VERBOSE[21614][C-00004406] pbx.c: Executing [[email protected]:2] Set("Local/[email protected];2", "__FROMQ=true") in new stack
    [2019-06-27 14:04:51] VERBOSE[21614][C-00004406] pbx.c: Executing [[email protected]:3] GotoIf("Local/[email protected];2", "0?hangup") in new stack
    [2019-06-27 14:04:51] VERBOSE[21614][C-00004406] pbx.c: Executing [[email protected]:4] GotoIf("Local/[email protected];2", "1?303,1:hangup") in new stack
    [2019-06-27 14:04:51] VERBOSE[21614][C-00004406] pbx_builtins.c: Goto (from-queue,303,1)
    [2019-06-27 14:04:51] VERBOSE[21614][C-00004406] pbx.c: Executing [[email protected]:1] Goto("Local/[email protected];2", "from-internal,7205,1") in new stack
    [2019-06-27 14:04:51] VERBOSE[21614][C-00004406] pbx_builtins.c: Goto (from-internal,7205,1)
    [2019-06-27 14:04:51] VERBOSE[21614][C-00004406] pbx.c: Executing [[email protected]:1] GotoIf("Local/[email protected];2", "1?ext-local,7205,1:followme-check,7205,1") in new stack
    [2019-06-27 14:04:51] VERBOSE[21614][C-00004406] pbx_builtins.c: Goto (ext-local,7205,1)
    [2019-06-27 14:04:51] VERBOSE[21614][C-00004406] pbx.c: Executing [[email protected]:1] Set("Local/[email protected];2", "__RINGTIMER=30") in new stack
    [2019-06-27 14:04:51] VERBOSE[21614][C-00004406] pbx.c: Executing [[email protected]:2] Macro("Local/[email protected];2", "exten-vm,7205,7205,0,0,0") in new stack
    [2019-06-27 14:04:51] VERBOSE[21614][C-00004406] pbx.c: Executing [[email protected]:1] Macro("Local/[email protected];2", "user-callerid,") in new stack
    [2019-06-27 14:04:51] VERBOSE[21614][C-00004406] pbx.c: Executing [[email protected]:1] Set("Local/[email protected];2", "TOUCH_MONITOR=1561662291.33445") in new stack
    [2019-06-27 14:04:51] VERBOSE[21614][C-00004406] pbx.c: Executing [[email protected]:2] Set("Local/[email protected];2", "AMPUSER=6302272957") in new stack
    [2019-06-27 14:04:51] VERBOSE[21614][C-00004406] pbx.c: Executing [[email protected]cro-user-callerid:3] GotoIf("Local/[email protected];2", "1?report") in new stack

Here’s where the call got transferred to the extension in question. I don’t think there’s anything in here that would tell us exactly how, but I’d say you’re probably pretty close in your “CSR Transferred” theory.

1 Like

Thanks Dave, I appreciate you taking a look

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