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/7065@from-queue-00003959;1 answered SIP/AppiaSIP-000008d5
    [2019-06-27 14:04:43] VERBOSE[21198][C-00004406] file.c: <Local/7065@from-queue-00003959;1> Playing 'custom/AllAgents.slin' (language 'en')
    [2019-06-27 14:04:43] VERBOSE[21581][C-00004406] bridge_channel.c: Channel Local/907065@zulu-call-0000395a;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/7065@from-queue-00003959;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/907065@zulu-desktop-phone-0000395d;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/907065@zulu-call-0000395a;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/7065@from-queue-00003959;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/907065@zulu-call-0000395a;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/7065@from-queue-00003959;2 swapped with Local/907065@zulu-call-0000395a;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/907065@zulu-call-0000395a;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/907065@zulu-call-0000395a;1 Internal Gosub(crm-hangup,s,1) start
    [2019-06-27 14:04:43] VERBOSE[21581][C-00004406] pbx.c: Executing [s@crm-hangup:1] NoOp("Local/907065@zulu-call-0000395a;1", "Sending Hangup to CRM") in new stack
    [2019-06-27 14:04:43] VERBOSE[21581][C-00004406] pbx.c: Executing [s@crm-hangup:2] NoOp("Local/907065@zulu-call-0000395a;1", "HANGUP CAUSE: 16") in new stack
    [2019-06-27 14:04:43] VERBOSE[21581][C-00004406] pbx.c: Executing [s@crm-hangup:3] ExecIf("Local/907065@zulu-call-0000395a;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/907065@zulu-call-0000395a;2'
    [2019-06-27 14:04:43] VERBOSE[21581][C-00004406] pbx.c: Executing [s@crm-hangup:4] NoOp("Local/907065@zulu-call-0000395a;1", "MASTER CHANNEL: 1561662274.33433 = 1561662235.33386") in new stack
    [2019-06-27 14:04:43] VERBOSE[21581][C-00004406] pbx.c: Executing [s@crm-hangup:5] GotoIf("Local/907065@zulu-call-0000395a;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 [s@crm-hangup:8] Return("Local/907065@zulu-call-0000395a;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/907065@zulu-call-0000395a;1'
    [2019-06-27 14:04:43] VERBOSE[21581][C-00004406] app_stack.c: Local/907065@zulu-call-0000395a;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/7065@from-queue-00003959;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/907065@zulu-desktop-phone-0000395d;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/7065@from-queue-00003959;2 swapped with Local/907065@zulu-desktop-phone-0000395d;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/907065@zulu-desktop-phone-0000395d;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/907065@zulu-desktop-phone-0000395d;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/7065@from-queue-00003959;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 [s@crm-hangup: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 [s@crm-hangup: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/7065@from-queue-00003959;2' in macro 'dial-one'
    [2019-06-27 14:04:46] VERBOSE[21579][C-00004406] pbx.c: Executing [s@crm-hangup: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/7065@from-queue-00003959;2' in macro 'exten-vm'
    [2019-06-27 14:04:46] VERBOSE[21579][C-00004406] pbx.c: Executing [s@crm-hangup: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/7065@from-queue-00003959;2'
    [2019-06-27 14:04:46] VERBOSE[21579][C-00004406] pbx.c: Executing [s@crm-hangup:5] GotoIf("PJSIP/907065-00000687", "1?return") in new stack
    [2019-06-27 14:04:46] VERBOSE[21497][C-00004406] pbx.c: Executing [h@ext-local:1] Macro("Local/7065@from-queue-00003959;2", "hangupcall,") in new stack
    [2019-06-27 14:04:46] VERBOSE[21497][C-00004406] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("Local/7065@from-queue-00003959;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 [s@macro-hangupcall:3] ExecIf("Local/7065@from-queue-00003959;2", "0?Set(CDR(recordingfile)=)") in new stack
    [2019-06-27 14:04:46] VERBOSE[21497][C-00004406] pbx.c: Executing [s@macro-hangupcall:4] NoOp("Local/7065@from-queue-00003959;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 [s@macro-hangupcall:5] GotoIf("Local/7065@from-queue-00003959;2", "0?skipagi") in new stack
    [2019-06-27 14:04:46] VERBOSE[21497][C-00004406] pbx.c: Executing [s@macro-hangupcall:6] AGI("Local/7065@from-queue-00003959;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 [s@crm-hangup: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/7065@from-queue-00003959;2>AGI Script attendedtransfer-rec-restart.php completed, returning 0
    [2019-06-27 14:04:46] VERBOSE[21497][C-00004406] pbx.c: Executing [s@macro-hangupcall:7] Hangup("Local/7065@from-queue-00003959;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/7065@from-queue-00003959;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/7065@from-queue-00003959;2'
    [2019-06-27 14:04:46] ERROR[21198][C-00004406] app_queue.c: play_file failed for 'custom/AllAgents' on Local/7065@from-queue-00003959;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/7065@from-queue-00003959;2
    [2019-06-27 14:04:46] WARNING[21198][C-00004406] app_queue.c: Agent on Local/7065@from-queue-00003959;1 hungup on the customer.
    [2019-06-27 14:04:51] VERBOSE[21198][C-00004406] app_queue.c: Called Local/7205@from-queue/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 [7205@from-queue:1] Set("Local/7205@from-queue-0000395f;2", "QAGENT=7205") in new stack
    [2019-06-27 14:04:51] VERBOSE[21614][C-00004406] pbx.c: Executing [7205@from-queue:2] Set("Local/7205@from-queue-0000395f;2", "__FROMQ=true") in new stack
    [2019-06-27 14:04:51] VERBOSE[21614][C-00004406] pbx.c: Executing [7205@from-queue:3] GotoIf("Local/7205@from-queue-0000395f;2", "0?hangup") in new stack
    [2019-06-27 14:04:51] VERBOSE[21614][C-00004406] pbx.c: Executing [7205@from-queue:4] GotoIf("Local/7205@from-queue-0000395f;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 [303@from-queue:1] Goto("Local/7205@from-queue-0000395f;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 [7205@from-internal:1] GotoIf("Local/7205@from-queue-0000395f;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 [7205@ext-local:1] Set("Local/7205@from-queue-0000395f;2", "__RINGTIMER=30") in new stack
    [2019-06-27 14:04:51] VERBOSE[21614][C-00004406] pbx.c: Executing [7205@ext-local:2] Macro("Local/7205@from-queue-0000395f;2", "exten-vm,7205,7205,0,0,0") in new stack
    [2019-06-27 14:04:51] VERBOSE[21614][C-00004406] pbx.c: Executing [s@macro-exten-vm:1] Macro("Local/7205@from-queue-0000395f;2", "user-callerid,") in new stack
    [2019-06-27 14:04:51] VERBOSE[21614][C-00004406] pbx.c: Executing [s@macro-user-callerid:1] Set("Local/7205@from-queue-0000395f;2", "TOUCH_MONITOR=1561662291.33445") in new stack
    [2019-06-27 14:04:51] VERBOSE[21614][C-00004406] pbx.c: Executing [s@macro-user-callerid:2] Set("Local/7205@from-queue-0000395f;2", "AMPUSER=6302272957") in new stack
    [2019-06-27 14:04:51] VERBOSE[21614][C-00004406] pbx.c: Executing [s@macro-user-callerid:3] GotoIf("Local/7205@from-queue-0000395f;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.