a1*CLI> show agents online
217          () available at '25@my-queue' (musiconhold is 'default')
001          () available at '91@my-queue' (musiconhold is 'default')
010          () available at '92@my-queue' (musiconhold is 'default')
3 agents online




full
[Dec  6 09:03:13] VERBOSE[14654] logger.c:     -- Accepting call from '510049' to '98' on channel 0/3, span 1
[Dec  6 09:03:13] DEBUG[14654] chan_zap.c: Enabled echo cancellation on channel 3
[Dec  6 09:03:13] DEBUG[15125] pbx.c: Launching 'GotoIfTime'
[Dec  6 09:03:13] VERBOSE[15125] logger.c:     -- Executing [98@default:1] GotoIfTime("Zap/3-1", "00:00-09:59|sun|*|*?my-queue-00:00-09:59-sun-*-*|98|1") i
n new stack
[Dec  6 09:03:13] DEBUG[15125] pbx.c: Launching 'GotoIfTime'
[Dec  6 09:03:13] VERBOSE[15125] logger.c:     -- Executing [98@default:2] GotoIfTime("Zap/3-1", "17:30-23:59|sun|*|*?my-queue-17:30-23:59-sun-*-*|98|1") i
n new stack
[Dec  6 09:03:13] DEBUG[15125] pbx.c: Launching 'GotoIfTime'
[Dec  6 09:03:13] VERBOSE[15125] logger.c:     -- Executing [98@default:3] GotoIfTime("Zap/3-1", "22:00-07:59|mon-sat|*|*?my-queue-22:00-07:59-mon-sat-*-*|
98|1") in new stack
[Dec  6 09:03:13] DEBUG[15125] pbx.c: Launching 'GotoIfTime'
[Dec  6 09:03:13] VERBOSE[15125] logger.c:     -- Executing [98@default:4] GotoIfTime("Zap/3-1", "00:00-09:59|mon|18|jun?my-queue-00:00-09:59-mon-18-jun|98
|1") in new stack
[Dec  6 09:03:13] DEBUG[15125] pbx.c: Launching 'GotoIfTime'
[Dec  6 09:03:13] VERBOSE[15125] logger.c:     -- Executing [98@default:5] GotoIfTime("Zap/3-1", "17:30-23:59|mon|18|jun?my-queue-17:30-23:59-mon-18-jun|98
|1") in new stack
[Dec  6 09:03:13] DEBUG[15125] pbx.c: Launching 'Answer'
[Dec  6 09:03:13] VERBOSE[15125] logger.c:     -- Executing [98@default:6] Answer("Zap/3-1", "") in new stack
[Dec  6 09:03:13] DEBUG[15125] devicestate.c: Notification of state change to be queued on device/channel Zap/3-1
[Dec  6 09:03:13] DEBUG[12746] channel.c: Avoiding initial deadlock for channel '0x82105d8'
[Dec  6 09:03:13] DEBUG[15125] pbx.c: Launching 'Playback'
[Dec  6 09:03:13] VERBOSE[15125] logger.c:     -- Executing [98@default:7] Playback("Zap/3-1", "bienvenidos") in new stack
[Dec  6 09:03:13] DEBUG[12746] devicestate.c: Changing state for Zap/3 - state 2 (In use)
[Dec  6 09:03:13] DEBUG[14660] app_queue.c: Device 'Zap/3' changed to state '2' (In use) but we don't care because they're not a member of any queue.
[Dec  6 09:03:13] DEBUG[15125] channel.c: Set channel Zap/3-1 to write format slin
[Dec  6 09:03:13] DEBUG[15125] channel.c: Scheduling timer at 160 sample intervals
[Dec  6 09:03:13] VERBOSE[15125] logger.c:     -- <Zap/3-1> Playing 'bienvenidos' (language 'en')
[Dec  6 09:03:13] VERBOSE[14654] logger.c:     -- Accepting call from '492131' to '98' on channel 0/9, span 1
[Dec  6 09:03:13] DEBUG[14654] chan_zap.c: Enabled echo cancellation on channel 9
[Dec  6 09:03:13] DEBUG[15132] pbx.c: Launching 'GotoIfTime'
[Dec  6 09:03:13] VERBOSE[15132] logger.c:     -- Executing [98@default:1] GotoIfTime("Zap/9-1", "00:00-09:59|sun|*|*?my-queue-00:00-09:59-sun-*-*|98|1") i
n new stack
[Dec  6 09:03:13] DEBUG[15132] pbx.c: Launching 'GotoIfTime'
[Dec  6 09:03:13] VERBOSE[15132] logger.c:     -- Executing [98@default:2] GotoIfTime("Zap/9-1", "17:30-23:59|sun|*|*?my-queue-17:30-23:59-sun-*-*|98|1") i
n new stack
[Dec  6 09:03:13] DEBUG[15132] pbx.c: Launching 'GotoIfTime'
[Dec  6 09:03:13] VERBOSE[15132] logger.c:     -- Executing [98@default:3] GotoIfTime("Zap/9-1", "22:00-07:59|mon-sat|*|*?my-queue-22:00-07:59-mon-sat-*-*|
98|1") in new stack
[Dec  6 09:03:13] DEBUG[15132] pbx.c: Launching 'GotoIfTime'
[Dec  6 09:03:13] VERBOSE[15132] logger.c:     -- Executing [98@default:4] GotoIfTime("Zap/9-1", "00:00-09:59|mon|18|jun?my-queue-00:00-09:59-mon-18-jun|98
|1") in new stack
[Dec  6 09:03:13] DEBUG[15132] pbx.c: Launching 'GotoIfTime'
[Dec  6 09:03:13] VERBOSE[15132] logger.c:     -- Executing [98@default:5] GotoIfTime("Zap/9-1", "17:30-23:59|mon|18|jun?my-queue-17:30-23:59-mon-18-jun|98
|1") in new stack
[Dec  6 09:03:13] DEBUG[15132] pbx.c: Launching 'Answer'
[Dec  6 09:03:13] VERBOSE[15132] logger.c:     -- Executing [98@default:6] Answer("Zap/9-1", "") in new stack
[Dec  6 09:03:13] DEBUG[15132] devicestate.c: Notification of state change to be queued on device/channel Zap/9-1
[Dec  6 09:03:13] DEBUG[12746] channel.c: Avoiding initial deadlock for channel '0x8202568'
[Dec  6 09:03:13] DEBUG[15132] pbx.c: Launching 'Playback'
[Dec  6 09:03:13] VERBOSE[15132] logger.c:     -- Executing [98@default:7] Playback("Zap/9-1", "bienvenidos") in new stack
[Dec  6 09:03:13] DEBUG[15132] channel.c: Set channel Zap/9-1 to write format slin
[Dec  6 09:03:13] DEBUG[15132] channel.c: Scheduling timer at 160 sample intervals
[Dec  6 09:03:13] VERBOSE[15132] logger.c:     -- <Zap/9-1> Playing 'bienvenidos' (language 'en')
[Dec  6 09:03:13] DEBUG[12746] devicestate.c: Changing state for Zap/9 - state 2 (In use)
[Dec  6 09:03:13] DEBUG[14660] app_queue.c: Device 'Zap/9' changed to state '2' (In use) but we don't care because they're not a member of any queue.
[Dec  6 09:03:16] VERBOSE[14654] logger.c:     -- Accepting call from '456524' to '98' on channel 0/10, span 1
[Dec  6 09:03:16] DEBUG[14654] chan_zap.c: Enabled echo cancellation on channel 10
[Dec  6 09:03:16] DEBUG[15163] pbx.c: Launching 'GotoIfTime'
[Dec  6 09:03:16] VERBOSE[15163] logger.c:     -- Executing [98@default:1] GotoIfTime("Zap/10-1", "00:00-09:59|sun|*|*?my-queue-00:00-09:59-sun-*-*|98|1")
in new stack
[Dec  6 09:03:16] DEBUG[15163] pbx.c: Launching 'GotoIfTime'
[Dec  6 09:03:16] VERBOSE[15163] logger.c:     -- Executing [98@default:2] GotoIfTime("Zap/10-1", "17:30-23:59|sun|*|*?my-queue-17:30-23:59-sun-*-*|98|1")
in new stack
[Dec  6 09:03:16] DEBUG[15163] pbx.c: Launching 'GotoIfTime'
[Dec  6 09:03:16] VERBOSE[15163] logger.c:     -- Executing [98@default:3] GotoIfTime("Zap/10-1", "22:00-07:59|mon-sat|*|*?my-queue-22:00-07:59-mon-sat-*-*
|98|1") in new stack
[Dec  6 09:03:16] DEBUG[15163] pbx.c: Launching 'GotoIfTime'
[Dec  6 09:03:16] VERBOSE[15163] logger.c:     -- Executing [98@default:4] GotoIfTime("Zap/10-1", "00:00-09:59|mon|18|jun?my-queue-00:00-09:59-mon-18-jun|9
8|1") in new stack
[Dec  6 09:03:16] DEBUG[15163] pbx.c: Launching 'GotoIfTime'
[Dec  6 09:03:16] VERBOSE[15163] logger.c:     -- Executing [98@default:5] GotoIfTime("Zap/10-1", "17:30-23:59|mon|18|jun?my-queue-17:30-23:59-mon-18-jun|9
8|1") in new stack
[Dec  6 09:03:16] DEBUG[15163] pbx.c: Launching 'Answer'
[Dec  6 09:03:16] VERBOSE[15163] logger.c:     -- Executing [98@default:6] Answer("Zap/10-1", "") in new stack
[Dec  6 09:03:16] DEBUG[15163] devicestate.c: Notification of state change to be queued on device/channel Zap/10-1
[Dec  6 09:03:16] DEBUG[12746] channel.c: Avoiding initial deadlock for channel '0x81f1570'
[Dec  6 09:03:16] DEBUG[15163] pbx.c: Launching 'Playback'
[Dec  6 09:03:16] VERBOSE[15163] logger.c:     -- Executing [98@default:7] Playback("Zap/10-1", "bienvenidos") in new stack
[Dec  6 09:03:16] DEBUG[12746] devicestate.c: Changing state for Zap/10 - state 2 (In use)
[Dec  6 09:03:16] DEBUG[14660] app_queue.c: Device 'Zap/10' changed to state '2' (In use) but we don't care because they're not a member of any queue.
[Dec  6 09:03:16] DEBUG[15163] channel.c: Set channel Zap/10-1 to write format slin
[Dec  6 09:03:16] DEBUG[15163] channel.c: Scheduling timer at 160 sample intervals
[Dec  6 09:03:16] VERBOSE[15163] logger.c:     -- <Zap/10-1> Playing 'bienvenidos' (language 'en')
[Dec  6 09:03:18] DEBUG[15125] channel.c: Scheduling timer at 23 sample intervals
[Dec  6 09:03:18] DEBUG[15125] channel.c: Scheduling timer at 0 sample intervals
[Dec  6 09:03:18] DEBUG[15125] channel.c: Scheduling timer at 0 sample intervals
[Dec  6 09:03:18] DEBUG[15125] channel.c: Set channel Zap/3-1 to write format alaw
[Dec  6 09:03:18] DEBUG[15125] pbx.c: Launching 'Ringing'
[Dec  6 09:03:18] VERBOSE[15125] logger.c:     -- Executing [98@default:8] Ringing("Zap/3-1", "") in new stack
[Dec  6 09:03:18] DEBUG[15125] chan_zap.c: Requested indication 3 on channel Zap/3-1
[Dec  6 09:03:18] DEBUG[15125] pbx.c: Launching 'Queue'
[Dec  6 09:03:18] VERBOSE[15125] logger.c:     -- Executing [98@default:9] Queue("Zap/3-1", "my-queue|t|||300") in new stack
[Dec  6 09:03:18] DEBUG[15125] app_queue.c: NO QUEUE_PRIO variable found. Using default.
[Dec  6 09:03:18] DEBUG[15125] app_queue.c: queue: my-queue, options: t, url: , announce: , expires: 1196942898, priority: 0
[Dec  6 09:03:18] DEBUG[15125] app_queue.c: Queue my-queue has no realtime members defined. No need for update
[Dec  6 09:03:18] DEBUG[15125] app_queue.c: Queue 'my-queue' Join, Channel 'Zap/3-1', Position '1'
[Dec  6 09:03:18] DEBUG[15125] channel.c: Set channel Zap/3-1 to write format slin
[Dec  6 09:03:18] VERBOSE[15125] logger.c:     -- Started music on hold, class 'default', on channel 'Zap/3-1'
[Dec  6 09:03:18] DEBUG[15125] channel.c: Scheduling timer at 160 sample intervals
[Dec  6 09:03:18] DEBUG[15125] app_queue.c: There are 3 available members.
[Dec  6 09:03:18] DEBUG[15125] app_queue.c: It's our turn (Zap/3-1).
[Dec  6 09:03:18] DEBUG[15125] app_queue.c: Zap/3-1 is trying to call a queue member.
[Dec  6 09:03:18] DEBUG[15125] app_queue.c: Trying 'Agent/588' with metric 0
[Dec  6 09:03:18] DEBUG[15125] app_queue.c: Trying 'Agent/307' with metric 0
[Dec  6 09:03:18] DEBUG[15125] app_queue.c: Trying 'Agent/987' with metric 0
[Dec  6 09:03:18] DEBUG[15125] app_queue.c: Trying 'Agent/001' with metric 0
[Dec  6 09:03:18] DEBUG[15125] channel.c: Not copying variable PLAYBACKSTATUS.
[Dec  6 09:03:18] DEBUG[15125] channel.c: Not copying variable CALLEDTON.
[Dec  6 09:03:18] DEBUG[15125] channel.c: Not copying variable ANI2.
[Dec  6 09:03:18] DEBUG[15125] channel.c: Not copying variable TRANSFERCAPABILITY.
[Dec  6 09:03:18] VERBOSE[15125] logger.c:     -- outgoing agentcall, to agent '001', on 'Local/91@my-queue-a427,1'
[Dec  6 09:03:18] VERBOSE[15125] logger.c:     -- Called Agent/001
[Dec  6 09:03:18] DEBUG[15217] pbx.c: Function result is '510049'
[Dec  6 09:03:18] DEBUG[15217] pbx.c: Launching 'QueueLog'
[Dec  6 09:03:18] VERBOSE[15217] logger.c:     -- Executing [91@my-queue:1] QueueLog("Local/91@my-queue-a427,2", "NONE|NONE|NONE|RINGAGENT||510049|Local/
91@my-queue-a427,2") in new stack
[Dec  6 09:03:18] DEBUG[15125] channel.c: Generator got voice, switching to phase locked mode
[Dec  6 09:03:18] DEBUG[15125] channel.c: Scheduling timer at 0 sample intervals
[Dec  6 09:03:18] DEBUG[15217] pbx.c: Launching 'Dial'
[Dec  6 09:03:18] VERBOSE[15217] logger.c:     -- Executing [91@my-queue:2] Dial("Local/91@my-queue-a427,2", "Zap/g1/492191") in new stack
[Dec  6 09:03:18] DEBUG[15217] chan_zap.c: Using channel 1
[Dec  6 09:03:18] DEBUG[15217] rtp.c: Channel 'Zap/1-1' has no RTP, not doing anything
[Dec  6 09:03:18] VERBOSE[15217] logger.c:     -- Requested transfer capability: 0x00 - SPEECH
[Dec  6 09:03:18] DEBUG[15217] devicestate.c: Notification of state change to be queued on device/channel Zap/1-1
[Dec  6 09:03:18] DEBUG[12746] devicestate.c: Changing state for Zap/1 - state 2 (In use)
[Dec  6 09:03:18] VERBOSE[15217] logger.c:     -- Called g1/492191
[Dec  6 09:03:18] DEBUG[14660] app_queue.c: Device 'Zap/1' changed to state '2' (In use) but we don't care because they're not a member of any queue.
[Dec  6 09:03:18] DEBUG[14654] chan_zap.c: Queuing frame from PRI_EVENT_PROCEEDING on channel 0/1 span 1
[Dec  6 09:03:18] VERBOSE[15217] logger.c:     -- Zap/1-1 is proceeding passing it to Local/91@my-queue-a427,2
[Dec  6 09:03:18] DEBUG[15217] rtp.c: Channel 'Local/91@my-queue-a427,2' has no RTP, not doing anything
[Dec  6 09:03:18] DEBUG[15125] app_queue.c: Dunno what to do with control type 15
[Dec  6 09:03:19] DEBUG[15132] channel.c: Scheduling timer at 23 sample intervals
[Dec  6 09:03:19] DEBUG[15132] channel.c: Scheduling timer at 0 sample intervals
[Dec  6 09:03:19] DEBUG[15132] channel.c: Scheduling timer at 0 sample intervals
[Dec  6 09:03:19] DEBUG[15132] channel.c: Set channel Zap/9-1 to write format alaw
[Dec  6 09:03:19] DEBUG[15132] pbx.c: Launching 'Ringing'
[Dec  6 09:03:19] VERBOSE[15132] logger.c:     -- Executing [98@default:8] Ringing("Zap/9-1", "") in new stack
[Dec  6 09:03:19] DEBUG[15132] chan_zap.c: Requested indication 3 on channel Zap/9-1
[Dec  6 09:03:19] DEBUG[15132] pbx.c: Launching 'Queue'
[Dec  6 09:03:19] VERBOSE[15132] logger.c:     -- Executing [98@default:9] Queue("Zap/9-1", "my-queue|t|||300") in new stack
[Dec  6 09:03:19] DEBUG[15132] app_queue.c: NO QUEUE_PRIO variable found. Using default.
[Dec  6 09:03:19] DEBUG[15132] app_queue.c: queue: my-queue, options: t, url: , announce: , expires: 1196942899, priority: 0
[Dec  6 09:03:19] DEBUG[15132] app_queue.c: Queue my-queue has no realtime members defined. No need for update
[Dec  6 09:03:19] DEBUG[15132] app_queue.c: Queue 'my-queue' Join, Channel 'Zap/9-1', Position '2'
[Dec  6 09:03:19] DEBUG[15132] channel.c: Set channel Zap/9-1 to write format slin
[Dec  6 09:03:19] VERBOSE[15132] logger.c:     -- Started music on hold, class 'default', on channel 'Zap/9-1'
[Dec  6 09:03:19] DEBUG[15132] channel.c: Scheduling timer at 160 sample intervals
[Dec  6 09:03:19] DEBUG[15132] app_queue.c: There are 2 available members.
[Dec  6 09:03:19] DEBUG[15132] app_queue.c: It's our turn (Zap/9-1).
[Dec  6 09:03:19] DEBUG[15132] app_queue.c: Zap/9-1 is trying to call a queue member.
[Dec  6 09:03:19] DEBUG[15132] app_queue.c: Trying 'Agent/588' with metric 0
[Dec  6 09:03:19] DEBUG[15132] app_queue.c: Trying 'Agent/307' with metric 0
[Dec  6 09:03:19] DEBUG[15132] app_queue.c: Trying 'Agent/987' with metric 0
[Dec  6 09:03:19] DEBUG[15132] app_queue.c: Trying 'Agent/001' with metric 0
[Dec  6 09:03:19] DEBUG[15132] app_queue.c: Trying 'Agent/217' with metric 0
[Dec  6 09:03:19] DEBUG[15132] channel.c: Not copying variable PLAYBACKSTATUS.
[Dec  6 09:03:19] DEBUG[15132] channel.c: Not copying variable CALLEDTON.
[Dec  6 09:03:19] DEBUG[15132] channel.c: Not copying variable ANI2.
[Dec  6 09:03:19] DEBUG[15132] channel.c: Not copying variable TRANSFERCAPABILITY.
[Dec  6 09:03:19] VERBOSE[15132] logger.c:     -- outgoing agentcall, to agent '217', on 'Local/25@my-queue-56d6,1'
[Dec  6 09:03:19] VERBOSE[15132] logger.c:     -- Called Agent/217
[Dec  6 09:03:19] DEBUG[15132] channel.c: Generator got voice, switching to phase locked mode
[Dec  6 09:03:19] DEBUG[15132] channel.c: Scheduling timer at 0 sample intervals
[Dec  6 09:03:19] DEBUG[14654] chan_zap.c: Enabled echo cancellation on channel 1
[Dec  6 09:03:19] DEBUG[15218] pbx.c: Function result is '492131'
[Dec  6 09:03:19] DEBUG[15218] pbx.c: Launching 'QueueLog'
[Dec  6 09:03:19] VERBOSE[15218] logger.c:     -- Executing [25@my-queue:1] QueueLog("Local/25@my-queue-56d6,2", "NONE|NONE|NONE|RINGAGENT||492131|Local/
25@my-queue-56d6,2") in new stack
[Dec  6 09:03:19] DEBUG[15218] pbx.c: Launching 'Dial'
[Dec  6 09:03:19] VERBOSE[15218] logger.c:     -- Executing [25@my-queue:2] Dial("Local/25@my-queue-56d6,2", "Zap/g1/492125") in new stack
[Dec  6 09:03:19] DEBUG[15218] chan_zap.c: Using channel 2
[Dec  6 09:03:19] DEBUG[15218] rtp.c: Channel 'Zap/2-1' has no RTP, not doing anything
[Dec  6 09:03:19] VERBOSE[15218] logger.c:     -- Requested transfer capability: 0x00 - SPEECH
[Dec  6 09:03:19] DEBUG[15218] devicestate.c: Notification of state change to be queued on device/channel Zap/2-1
[Dec  6 09:03:19] DEBUG[12746] devicestate.c: Changing state for Zap/2 - state 2 (In use)
[Dec  6 09:03:19] VERBOSE[15218] logger.c:     -- Called g1/492125
[Dec  6 09:03:19] DEBUG[14660] app_queue.c: Device 'Zap/2' changed to state '2' (In use) but we don't care because they're not a member of any queue.
[Dec  6 09:03:19] DEBUG[15217] devicestate.c: Notification of state change to be queued on device/channel Zap/1-1
[Dec  6 09:03:19] DEBUG[12746] devicestate.c: Changing state for Zap/1 - state 6 (Ringing)
[Dec  6 09:03:19] DEBUG[14660] app_queue.c: Device 'Zap/1' changed to state '6' (Ringing) but we don't care because they're not a member of any queue.
[Dec  6 09:03:19] VERBOSE[15217] logger.c:     -- Zap/1-1 is ringing
[Dec  6 09:03:19] DEBUG[15217] rtp.c: Channel 'Local/91@my-queue-a427,2' has no RTP, not doing anything
[Dec  6 09:03:19] DEBUG[14654] chan_zap.c: Queuing frame from PRI_EVENT_PROCEEDING on channel 0/2 span 1
[Dec  6 09:03:19] VERBOSE[15218] logger.c:     -- Zap/2-1 is proceeding passing it to Local/25@my-queue-56d6,2
[Dec  6 09:03:19] DEBUG[15218] rtp.c: Channel 'Local/25@my-queue-56d6,2' has no RTP, not doing anything
[Dec  6 09:03:19] VERBOSE[15125] logger.c:     -- Agent/001 is ringing
[Dec  6 09:03:19] DEBUG[15132] app_queue.c: Dunno what to do with control type 15
[Dec  6 09:03:19] DEBUG[14654] chan_zap.c: Enabled echo cancellation on channel 2
[Dec  6 09:03:19] DEBUG[15218] devicestate.c: Notification of state change to be queued on device/channel Zap/2-1
[Dec  6 09:03:19] DEBUG[12746] devicestate.c: Changing state for Zap/2 - state 6 (Ringing)
[Dec  6 09:03:19] VERBOSE[15218] logger.c:     -- Zap/2-1 is ringing
[Dec  6 09:03:19] DEBUG[14660] app_queue.c: Device 'Zap/2' changed to state '6' (Ringing) but we don't care because they're not a member of any queue.
[Dec  6 09:03:19] DEBUG[15218] rtp.c: Channel 'Local/25@my-queue-56d6,2' has no RTP, not doing anything
[Dec  6 09:03:19] VERBOSE[15132] logger.c:     -- Agent/217 is ringing
[Dec  6 09:03:22] DEBUG[15163] channel.c: Scheduling timer at 23 sample intervals
[Dec  6 09:03:22] DEBUG[15163] channel.c: Scheduling timer at 0 sample intervals
[Dec  6 09:03:22] DEBUG[15163] channel.c: Scheduling timer at 0 sample intervals
[Dec  6 09:03:22] DEBUG[15163] channel.c: Set channel Zap/10-1 to write format alaw
[Dec  6 09:03:22] DEBUG[15163] pbx.c: Launching 'Ringing'
[Dec  6 09:03:22] VERBOSE[15163] logger.c:     -- Executing [98@default:8] Ringing("Zap/10-1", "") in new stack
[Dec  6 09:03:22] DEBUG[15163] chan_zap.c: Requested indication 3 on channel Zap/10-1
[Dec  6 09:03:22] DEBUG[15163] pbx.c: Launching 'Queue'
[Dec  6 09:03:22] VERBOSE[15163] logger.c:     -- Executing [98@default:9] Queue("Zap/10-1", "my-queue|t|||300") in new stack
[Dec  6 09:03:22] DEBUG[15163] app_queue.c: NO QUEUE_PRIO variable found. Using default.
[Dec  6 09:03:22] DEBUG[15163] app_queue.c: queue: my-queue, options: t, url: , announce: , expires: 1196942902, priority: 0
[Dec  6 09:03:22] DEBUG[15163] app_queue.c: Queue my-queue has no realtime members defined. No need for update
[Dec  6 09:03:22] DEBUG[15163] app_queue.c: Queue 'my-queue' Join, Channel 'Zap/10-1', Position '3'
[Dec  6 09:03:22] DEBUG[15163] channel.c: Set channel Zap/10-1 to write format slin
[Dec  6 09:03:22] VERBOSE[15163] logger.c:     -- Started music on hold, class 'default', on channel 'Zap/10-1'
[Dec  6 09:03:22] DEBUG[15163] channel.c: Scheduling timer at 160 sample intervals
[Dec  6 09:03:22] DEBUG[15163] app_queue.c: There are 1 available members.
[Dec  6 09:03:22] DEBUG[15163] app_queue.c: It's not our turn (Zap/10-1).
[Dec  6 09:03:22] DEBUG[15163] channel.c: Generator got voice, switching to phase locked mode
[Dec  6 09:03:22] DEBUG[15163] channel.c: Scheduling timer at 0 sample intervals
[Dec  6 09:03:23] DEBUG[15163] app_queue.c: There are 1 available members.
[Dec  6 09:03:23] DEBUG[15163] app_queue.c: It's not our turn (Zap/10-1).
[Dec  6 09:03:24] DEBUG[15163] app_queue.c: There are 1 available members.
[Dec  6 09:03:24] DEBUG[15163] app_queue.c: It's not our turn (Zap/10-1).
[Dec  6 09:03:25] DEBUG[15163] app_queue.c: There are 1 available members.
[Dec  6 09:03:25] DEBUG[15163] app_queue.c: It's not our turn (Zap/10-1).
[Dec  6 09:03:26] DEBUG[15163] app_queue.c: There are 1 available members.
[Dec  6 09:03:26] DEBUG[15163] app_queue.c: It's not our turn (Zap/10-1).
[Dec  6 09:03:27] DEBUG[15163] app_queue.c: There are 1 available members.
[Dec  6 09:03:27] DEBUG[15163] app_queue.c: It's not our turn (Zap/10-1).
[Dec  6 09:03:28] DEBUG[15163] app_queue.c: There are 1 available members.
[Dec  6 09:03:28] DEBUG[15163] app_queue.c: It's not our turn (Zap/10-1).
[Dec  6 09:03:29] DEBUG[15163] app_queue.c: There are 1 available members.
[Dec  6 09:03:29] DEBUG[15163] app_queue.c: It's not our turn (Zap/10-1).
[Dec  6 09:03:30] DEBUG[15163] app_queue.c: There are 1 available members.
[Dec  6 09:03:30] DEBUG[15163] app_queue.c: It's not our turn (Zap/10-1).
[Dec  6 09:03:31] DEBUG[15163] app_queue.c: There are 1 available members.
[Dec  6 09:03:31] DEBUG[15163] app_queue.c: It's not our turn (Zap/10-1).
[Dec  6 09:03:32] DEBUG[15163] app_queue.c: There are 1 available members.
[Dec  6 09:03:32] DEBUG[15163] app_queue.c: It's not our turn (Zap/10-1).
[Dec  6 09:03:33] DEBUG[15163] app_queue.c: There are 1 available members.
[Dec  6 09:03:33] DEBUG[15163] app_queue.c: It's not our turn (Zap/10-1).
[Dec  6 09:03:34] DEBUG[15163] app_queue.c: There are 1 available members.
[Dec  6 09:03:34] DEBUG[15163] app_queue.c: It's not our turn (Zap/10-1).
[Dec  6 09:03:35] DEBUG[15163] app_queue.c: There are 1 available members.
[Dec  6 09:03:35] DEBUG[15163] app_queue.c: It's not our turn (Zap/10-1).
[Dec  6 09:03:36] DEBUG[15163] app_queue.c: There are 1 available members.
[Dec  6 09:03:36] DEBUG[15163] app_queue.c: It's not our turn (Zap/10-1).
[Dec  6 09:03:37] DEBUG[15163] app_queue.c: There are 1 available members.
[Dec  6 09:03:37] DEBUG[15163] app_queue.c: It's not our turn (Zap/10-1).
[Dec  6 09:03:38] DEBUG[15163] app_queue.c: There are 1 available members.
[Dec  6 09:03:38] DEBUG[15163] app_queue.c: It's not our turn (Zap/10-1).
[Dec  6 09:03:39] DEBUG[15163] app_queue.c: There are 1 available members.
[Dec  6 09:03:39] DEBUG[15163] app_queue.c: It's not our turn (Zap/10-1).
[Dec  6 09:03:40] DEBUG[15163] app_queue.c: There are 1 available members.
[Dec  6 09:03:40] DEBUG[15163] app_queue.c: It's not our turn (Zap/10-1).
[Dec  6 09:03:41] DEBUG[15163] app_queue.c: There are 1 available members.
[Dec  6 09:03:41] DEBUG[15163] app_queue.c: It's not our turn (Zap/10-1).
[Dec  6 09:03:42] DEBUG[14654] chan_zap.c: Queuing frame from PRI_EVENT_PROGRESS on channel 0/2 span 1
[Dec  6 09:03:42] VERBOSE[15218] logger.c:     -- Zap/2-1 is making progress passing it to Local/25@my-queue-56d6,2
[Dec  6 09:03:42] DEBUG[15218] rtp.c: Channel 'Local/25@my-queue-56d6,2' has no RTP, not doing anything
[Dec  6 09:03:42] DEBUG[15132] app_queue.c: Dunno what to do with control type 14
[Dec  6 09:03:42] DEBUG[15163] app_queue.c: There are 1 available members.
[Dec  6 09:03:42] DEBUG[15163] app_queue.c: It's not our turn (Zap/10-1).
[Dec  6 09:03:43] DEBUG[15163] app_queue.c: There are 1 available members.
[Dec  6 09:03:43] DEBUG[15163] app_queue.c: It's not our turn (Zap/10-1).
[Dec  6 09:03:44] DEBUG[15163] app_queue.c: There are 1 available members.
[Dec  6 09:03:44] DEBUG[15163] app_queue.c: It's not our turn (Zap/10-1).
[Dec  6 09:03:46] DEBUG[15163] app_queue.c: There are 1 available members.
[Dec  6 09:03:46] DEBUG[15163] app_queue.c: It's not our turn (Zap/10-1).
[Dec  6 09:03:47] DEBUG[15163] app_queue.c: There are 1 available members.
[Dec  6 09:03:47] DEBUG[15163] app_queue.c: It's not our turn (Zap/10-1).
[Dec  6 09:03:47] DEBUG[14654] chan_zap.c: Queuing frame from PRI_EVENT_PROGRESS on channel 0/2 span 1
[Dec  6 09:03:47] VERBOSE[15218] logger.c:     -- Zap/2-1 is making progress passing it to Local/25@my-queue-56d6,2
[Dec  6 09:03:47] DEBUG[15218] rtp.c: Channel 'Local/25@my-queue-56d6,2' has no RTP, not doing anything
[Dec  6 09:03:47] DEBUG[15132] app_queue.c: Dunno what to do with control type 14
[Dec  6 09:03:48] DEBUG[15163] app_queue.c: There are 1 available members.
[Dec  6 09:03:48] DEBUG[15163] app_queue.c: It's not our turn (Zap/10-1).
[Dec  6 09:03:49] DEBUG[15163] app_queue.c: There are 1 available members.
[Dec  6 09:03:49] DEBUG[15163] app_queue.c: It's not our turn (Zap/10-1).
[Dec  6 09:03:50] VERBOSE[14654] logger.c:     -- Channel 0/3, span 1 got hangup request, cause 16
[Dec  6 09:03:50] DEBUG[15125] channel.c: Set channel Zap/3-1 to write format alaw
[Dec  6 09:03:50] VERBOSE[15125] logger.c:     -- Stopped music on hold on Zap/3-1
[Dec  6 09:03:50] DEBUG[15125] channel.c: Scheduling timer at 0 sample intervals
[Dec  6 09:03:50] DEBUG[15125] app_queue.c: Zap/3-1: Nobody answered.
[Dec  6 09:03:50] DEBUG[15125] channel.c: Hanging up channel 'Agent/001'
[Dec  6 09:03:50] DEBUG[15125] chan_agent.c: Hangup called for state Down
[Dec  6 09:03:50] DEBUG[15125] channel.c: Hanging up channel 'Local/91@my-queue-a427,1'
[Dec  6 09:03:50] DEBUG[15217] rtp.c: Channel 'Local/91@my-queue-a427,2' has no RTP, not doing anything
[Dec  6 09:03:50] DEBUG[15217] channel.c: Hanging up channel 'Zap/1-1'
[Dec  6 09:03:50] DEBUG[15217] chan_zap.c: zt_hangup(Zap/1-1)
[Dec  6 09:03:50] DEBUG[15217] chan_zap.c: Set option AUDIO MODE, value: ON(1) on Zap/1-1
[Dec  6 09:03:50] DEBUG[15217] chan_zap.c: Hangup: channel: 1 index = 0, normal = 26, callwait = -1, thirdcall = -1
[Dec  6 09:03:50] DEBUG[15217] chan_zap.c: Not yet hungup...  Calling hangup once with icause, and clearing call
[Dec  6 09:03:50] DEBUG[15217] chan_zap.c: disabled echo cancellation on channel 1
[Dec  6 09:03:50] DEBUG[15217] chan_zap.c: Set option TDD MODE, value: OFF(0) on Zap/1-1
[Dec  6 09:03:50] DEBUG[15217] chan_zap.c: Updated conferencing on 1, with 0 conference users
[Dec  6 09:03:50] DEBUG[15217] chan_zap.c: Set option AUDIO MODE, value: OFF(0) on Zap/1-1
[Dec  6 09:03:50] DEBUG[15217] chan_zap.c: disabled echo cancellation on channel 1
[Dec  6 09:03:50] VERBOSE[15217] logger.c:     -- Hungup 'Zap/1-1'
[Dec  6 09:03:50] DEBUG[15217] devicestate.c: Notification of state change to be queued on device/channel Zap/1-1
[Dec  6 09:03:50] DEBUG[15217] pbx.c: Function result is '91'
[Dec  6 09:03:50] DEBUG[15217] pbx.c: Function result is '91'
[Dec  6 09:03:50] DEBUG[15217] pbx.c: Function result is 's'
[Dec  6 09:03:50] DEBUG[15217] pbx.c: Function result is 'default'
[Dec  6 09:03:50] DEBUG[15217] pbx.c: Function result is 'Zap/1-1'
[Dec  6 09:03:50] DEBUG[12746] devicestate.c: Changing state for Zap/1 - state 0 (Unknown)
[Dec  6 09:03:50] DEBUG[15125] devicestate.c: Notification of state change to be queued on device/channel Local/91@my-queue-a427,1
[Dec  6 09:03:50] DEBUG[15217] pbx.c: Function result is ''
[Dec  6 09:03:50] DEBUG[14660] app_queue.c: Device 'Zap/1' changed to state '0' (Unknown) but we don't care because they're not a member of any queue.
[Dec  6 09:03:50] DEBUG[12746] chan_local.c: Checking if extension 91@my-queue exists (devicestate)
[Dec  6 09:03:50] DEBUG[15217] pbx.c: Function result is ''
[Dec  6 09:03:50] DEBUG[15217] pbx.c: Function result is ''
[Dec  6 09:03:50] DEBUG[12746] devicestate.c: Changing state for Local/91@my-queue - state 2 (In use)
[Dec  6 09:03:50] DEBUG[15217] pbx.c: Function result is '2007-12-06 09:03:18'
[Dec  6 09:03:50] DEBUG[14660] app_queue.c: Device 'Local/91@my-queue' changed to state '2' (In use) but we don't care because they're not a member of any
queue.
[Dec  6 09:03:50] DEBUG[15217] pbx.c: Function result is ''
[Dec  6 09:03:50] DEBUG[15217] pbx.c: Function result is '2007-12-06 09:03:50'
[Dec  6 09:03:50] DEBUG[15217] pbx.c: Function result is '32'
[Dec  6 09:03:50] DEBUG[15217] pbx.c: Function result is '0'
[Dec  6 09:03:50] DEBUG[15217] pbx.c: Function result is 'NO ANSWER'
[Dec  6 09:03:50] DEBUG[15217] pbx.c: Function result is 'DOCUMENTATION'
[Dec  6 09:03:50] DEBUG[15217] pbx.c: Function result is ''
[Dec  6 09:03:50] DEBUG[15217] pbx.c: Function result is '1196942598.71'
[Dec  6 09:03:50] DEBUG[15217] pbx.c: Function result is ''
[Dec  6 09:03:50] DEBUG[15125] pbx.c: Function result is '510049'
[Dec  6 09:03:50] DEBUG[15217] app_dial.c: Exiting with DIALSTATUS=CANCEL.
[Dec  6 09:03:50] DEBUG[15125] pbx.c: Function result is '510049'
[Dec  6 09:03:50] DEBUG[15125] pbx.c: Function result is '91'
[Dec  6 09:03:50] DEBUG[15217] pbx.c: Spawn extension (my-queue,91,2) exited non-zero on 'Local/91@my-queue-a427,2'
[Dec  6 09:03:50] DEBUG[15125] pbx.c: Function result is 'my-queue'
[Dec  6 09:03:50] DEBUG[15125] pbx.c: Function result is 'Local/91@my-queue-a427,1'
[Dec  6 09:03:50] VERBOSE[15217] logger.c:   == Spawn extension (my-queue, 91, 2) exited non-zero on 'Local/91@my-queue-a427,2'
[Dec  6 09:03:50] DEBUG[15217] channel.c: Soft-Hanging up channel 'Local/91@my-queue-a427,2'
[Dec  6 09:03:50] DEBUG[15217] channel.c: Hanging up channel 'Local/91@my-queue-a427,2'
[Dec  6 09:03:50] DEBUG[15217] devicestate.c: Notification of state change to be queued on device/channel Local/91@my-queue-a427,2
[Dec  6 09:03:50] DEBUG[15125] pbx.c: Function result is ''
[Dec  6 09:03:50] DEBUG[15125] pbx.c: Function result is ''
[Dec  6 09:03:50] DEBUG[15125] pbx.c: Function result is ''
[Dec  6 09:03:50] DEBUG[15125] pbx.c: Function result is '2007-12-06 09:03:18'
[Dec  6 09:03:50] DEBUG[15125] pbx.c: Function result is ''
[Dec  6 09:03:50] DEBUG[15125] pbx.c: Function result is '2007-12-06 09:03:50'
[Dec  6 09:03:50] DEBUG[15125] pbx.c: Function result is '32'
[Dec  6 09:03:50] DEBUG[15125] pbx.c: Function result is '0'
[Dec  6 09:03:50] DEBUG[15125] pbx.c: Function result is 'NO ANSWER'
[Dec  6 09:03:50] DEBUG[15125] pbx.c: Function result is 'DOCUMENTATION'
[Dec  6 09:03:50] DEBUG[15125] pbx.c: Function result is ''
[Dec  6 09:03:50] DEBUG[15125] pbx.c: Function result is '1196942598.68'
[Dec  6 09:03:50] DEBUG[15125] pbx.c: Function result is ''
[Dec  6 09:03:50] DEBUG[15125] chan_agent.c: Hungup, howlong is 32, autologoff is 0
[Dec  6 09:03:50] DEBUG[12746] chan_local.c: Checking if extension 91@my-queue exists (devicestate)
[Dec  6 09:03:50] DEBUG[15217] pbx.c: Function result is '510049'
[Dec  6 09:03:50] DEBUG[15217] pbx.c: Function result is '510049'
[Dec  6 09:03:50] DEBUG[15217] pbx.c: Function result is '91'
[Dec  6 09:03:50] DEBUG[12746] devicestate.c: Changing state for Local/91@my-queue - state 1 (Not in use)
[Dec  6 09:03:50] DEBUG[15217] pbx.c: Function result is 'my-queue'
[Dec  6 09:03:50] DEBUG[14660] app_queue.c: Device 'Local/91@my-queue' changed to state '1' (Not in use) but we don't care because they're not a member of
any queue.
[Dec  6 09:03:50] DEBUG[15217] pbx.c: Function result is 'Local/91@my-queue-a427,2'
[Dec  6 09:03:50] DEBUG[15217] pbx.c: Function result is 'Zap/1-1'
[Dec  6 09:03:50] DEBUG[15217] pbx.c: Function result is 'Dial'
[Dec  6 09:03:50] DEBUG[15217] pbx.c: Function result is 'Zap/g1/492191'
[Dec  6 09:03:50] DEBUG[15217] pbx.c: Function result is '2007-12-06 09:03:18'
[Dec  6 09:03:50] DEBUG[15217] pbx.c: Function result is ''
[Dec  6 09:03:50] DEBUG[15217] pbx.c: Function result is '2007-12-06 09:03:50'
[Dec  6 09:03:50] DEBUG[15217] pbx.c: Function result is '32'
[Dec  6 09:03:50] DEBUG[15217] pbx.c: Function result is '0'
[Dec  6 09:03:50] DEBUG[15217] pbx.c: Function result is 'NO ANSWER'
[Dec  6 09:03:50] DEBUG[15217] pbx.c: Function result is 'DOCUMENTATION'
[Dec  6 09:03:50] DEBUG[15217] pbx.c: Function result is ''
[Dec  6 09:03:50] DEBUG[15217] pbx.c: Function result is '1196942598.69'
[Dec  6 09:03:50] DEBUG[15217] pbx.c: Function result is ''
[Dec  6 09:03:50] DEBUG[15125] devicestate.c: Notification of state change to be queued on device/channel Agent/001
[Dec  6 09:03:50] DEBUG[15125] devicestate.c: Notification of state change to be queued on device/channel Agent/001
[Dec  6 09:03:50] DEBUG[12746] devicestate.c: Changing state for Agent/001 - state 1 (Not in use)
[Dec  6 09:03:50] DEBUG[15125] pbx.c: Function result is ''
[Dec  6 09:03:50] DEBUG[12746] devicestate.c: Changing state for Agent/001 - state 1 (Not in use)
[Dec  6 09:03:50] DEBUG[15125] pbx.c: Function result is ''
[Dec  6 09:03:50] DEBUG[15125] pbx.c: Function result is '91'
[Dec  6 09:03:50] DEBUG[14660] app_queue.c: Device 'Agent/001' changed to state '1' (Not in use)
[Dec  6 09:03:50] DEBUG[15125] pbx.c: Function result is 'my-queue'
[Dec  6 09:03:50] DEBUG[14660] app_queue.c: Device 'Agent/001' changed to state '1' (Not in use)
[Dec  6 09:03:50] DEBUG[15125] pbx.c: Function result is 'Agent/001'
[Dec  6 09:03:50] DEBUG[15125] pbx.c: Function result is ''
[Dec  6 09:03:50] DEBUG[15125] pbx.c: Function result is ''
[Dec  6 09:03:50] DEBUG[15125] pbx.c: Function result is ''
[Dec  6 09:03:50] DEBUG[15125] pbx.c: Function result is '2007-12-06 09:03:18'
[Dec  6 09:03:50] DEBUG[15125] pbx.c: Function result is ''
[Dec  6 09:03:50] DEBUG[15125] pbx.c: Function result is '2007-12-06 09:03:50'
[Dec  6 09:03:50] DEBUG[15125] pbx.c: Function result is '32'
[Dec  6 09:03:50] DEBUG[15125] pbx.c: Function result is '0'
[Dec  6 09:03:50] DEBUG[15125] pbx.c: Function result is 'NO ANSWER'
[Dec  6 09:03:50] DEBUG[15125] pbx.c: Function result is 'DOCUMENTATION'
[Dec  6 09:03:50] DEBUG[15125] pbx.c: Function result is ''
[Dec  6 09:03:50] DEBUG[15125] pbx.c: Function result is '1196942598.70'
[Dec  6 09:03:50] DEBUG[15125] pbx.c: Function result is ''
[Dec  6 09:03:50] DEBUG[15125] app_queue.c: Queue 'my-queue' Leave, Channel 'Zap/3-1'
[Dec  6 09:03:50] DEBUG[15125] pbx.c: Spawn extension (default,98,9) exited non-zero on 'Zap/3-1'
[Dec  6 09:03:50] VERBOSE[15125] logger.c:   == Spawn extension (default, 98, 9) exited non-zero on 'Zap/3-1'
[Dec  6 09:03:50] DEBUG[15125] channel.c: Soft-Hanging up channel 'Zap/3-1'
[Dec  6 09:03:50] DEBUG[15125] channel.c: Hanging up channel 'Zap/3-1'
[Dec  6 09:03:50] DEBUG[15125] chan_zap.c: zt_hangup(Zap/3-1)
[Dec  6 09:03:50] DEBUG[15125] chan_zap.c: Set option AUDIO MODE, value: ON(1) on Zap/3-1
[Dec  6 09:03:50] DEBUG[15125] chan_zap.c: Hangup: channel: 3 index = 0, normal = 28, callwait = -1, thirdcall = -1
[Dec  6 09:03:50] DEBUG[15125] chan_zap.c: Not yet hungup...  Calling hangup once with icause, and clearing call
[Dec  6 09:03:50] DEBUG[15125] chan_zap.c: disabled echo cancellation on channel 3
[Dec  6 09:03:50] DEBUG[15125] chan_zap.c: Set option TDD MODE, value: OFF(0) on Zap/3-1
[Dec  6 09:03:50] DEBUG[15125] chan_zap.c: Updated conferencing on 3, with 0 conference users
[Dec  6 09:03:50] DEBUG[15125] chan_zap.c: Set option AUDIO MODE, value: OFF(0) on Zap/3-1
[Dec  6 09:03:50] DEBUG[15125] chan_zap.c: disabled echo cancellation on channel 3
[Dec  6 09:03:50] VERBOSE[15125] logger.c:     -- Hungup 'Zap/3-1'
[Dec  6 09:03:50] DEBUG[15125] devicestate.c: Notification of state change to be queued on device/channel Zap/3-1
[Dec  6 09:03:50] DEBUG[15125] pbx.c: Function result is '510049'
[Dec  6 09:03:50] DEBUG[15125] pbx.c: Function result is '510049'
[Dec  6 09:03:50] DEBUG[15125] pbx.c: Function result is '98'
[Dec  6 09:03:50] DEBUG[15125] pbx.c: Function result is 'default'
[Dec  6 09:03:50] DEBUG[15125] pbx.c: Function result is 'Zap/3-1'
[Dec  6 09:03:50] DEBUG[15125] pbx.c: Function result is ''
[Dec  6 09:03:50] DEBUG[15125] pbx.c: Function result is 'Queue'
[Dec  6 09:03:50] DEBUG[15125] pbx.c: Function result is 'my-queue|t|||300'
[Dec  6 09:03:50] DEBUG[15125] pbx.c: Function result is '2007-12-06 09:03:13'
[Dec  6 09:03:50] DEBUG[15125] pbx.c: Function result is '2007-12-06 09:03:13'
[Dec  6 09:03:50] DEBUG[15125] pbx.c: Function result is '2007-12-06 09:03:50'
[Dec  6 09:03:50] DEBUG[15125] pbx.c: Function result is '37'
[Dec  6 09:03:50] DEBUG[15125] pbx.c: Function result is '37'
[Dec  6 09:03:50] DEBUG[15125] pbx.c: Function result is 'ANSWERED'
[Dec  6 09:03:50] DEBUG[15125] pbx.c: Function result is 'DOCUMENTATION'
[Dec  6 09:03:50] DEBUG[15125] pbx.c: Function result is ''
[Dec  6 09:03:50] DEBUG[15125] pbx.c: Function result is '1196942593.65'
[Dec  6 09:03:50] DEBUG[15125] pbx.c: Function result is ''
[Dec  6 09:03:50] DEBUG[12746] devicestate.c: Changing state for Zap/3 - state 0 (Unknown)
[Dec  6 09:03:50] DEBUG[14660] app_queue.c: Device 'Zap/3' changed to state '0' (Unknown) but we don't care because they're not a member of any queue.
[Dec  6 09:03:50] DEBUG[15163] app_queue.c: There are 2 available members.
[Dec  6 09:03:50] DEBUG[15163] app_queue.c: It's our turn (Zap/10-1).
[Dec  6 09:03:50] DEBUG[15163] app_queue.c: Zap/10-1 is trying to call a queue member.
[Dec  6 09:03:50] DEBUG[15163] app_queue.c: Trying 'Agent/588' with metric 0
[Dec  6 09:03:50] DEBUG[15163] app_queue.c: Trying 'Agent/307' with metric 0
[Dec  6 09:03:50] DEBUG[15163] app_queue.c: Trying 'Agent/987' with metric 0
[Dec  6 09:03:50] DEBUG[15163] app_queue.c: Trying 'Agent/001' with metric 0
[Dec  6 09:03:50] DEBUG[15163] channel.c: Not copying variable PLAYBACKSTATUS.
[Dec  6 09:03:50] DEBUG[15163] channel.c: Not copying variable CALLEDTON.
[Dec  6 09:03:50] DEBUG[15163] channel.c: Not copying variable ANI2.
[Dec  6 09:03:50] DEBUG[15163] channel.c: Not copying variable TRANSFERCAPABILITY.
[Dec  6 09:03:50] VERBOSE[15163] logger.c:     -- outgoing agentcall, to agent '001', on 'Local/91@my-queue-69a3,1'
[Dec  6 09:03:50] DEBUG[15486] pbx.c: Function result is '456524'
[Dec  6 09:03:50] DEBUG[15486] pbx.c: Launching 'QueueLog'
[Dec  6 09:03:50] VERBOSE[15163] logger.c:     -- Called Agent/001
[Dec  6 09:03:50] VERBOSE[15486] logger.c:     -- Executing [91@my-queue:1] QueueLog("Local/91@my-queue-69a3,2", "NONE|NONE|NONE|RINGAGENT||456524|Local/
91@my-queue-69a3,2") in new stack
[Dec  6 09:03:50] DEBUG[15486] pbx.c: Launching 'Dial'
[Dec  6 09:03:50] VERBOSE[15486] logger.c:     -- Executing [91@my-queue:2] Dial("Local/91@my-queue-69a3,2", "Zap/g1/492191") in new stack
[Dec  6 09:03:50] DEBUG[15486] chan_zap.c: Using channel 1
[Dec  6 09:03:50] DEBUG[15486] rtp.c: Channel 'Zap/1-1' has no RTP, not doing anything
[Dec  6 09:03:50] VERBOSE[15486] logger.c:     -- Requested transfer capability: 0x00 - SPEECH
[Dec  6 09:03:50] DEBUG[15486] devicestate.c: Notification of state change to be queued on device/channel Zap/1-1
[Dec  6 09:03:50] DEBUG[12746] channel.c: Avoiding initial deadlock for channel '0x8203e10'
[Dec  6 09:03:50] VERBOSE[15486] logger.c:     -- Called g1/492191
[Dec  6 09:03:50] DEBUG[12746] devicestate.c: Changing state for Zap/1 - state 2 (In use)
[Dec  6 09:03:50] DEBUG[14660] app_queue.c: Device 'Zap/1' changed to state '2' (In use) but we don't care because they're not a member of any queue.
[Dec  6 09:03:50] DEBUG[14654] chan_zap.c: Queuing frame from PRI_EVENT_PROCEEDING on channel 0/1 span 1
[Dec  6 09:03:50] VERBOSE[15486] logger.c:     -- Zap/1-1 is proceeding passing it to Local/91@my-queue-69a3,2
[Dec  6 09:03:50] DEBUG[15486] rtp.c: Channel 'Local/91@my-queue-69a3,2' has no RTP, not doing anything
[Dec  6 09:03:50] DEBUG[15163] app_queue.c: Dunno what to do with control type 15
[Dec  6 09:03:50] DEBUG[14654] chan_zap.c: Enabled echo cancellation on channel 1
[Dec  6 09:03:50] DEBUG[15486] devicestate.c: Notification of state change to be queued on device/channel Zap/1-1
[Dec  6 09:03:50] DEBUG[12746] devicestate.c: Changing state for Zap/1 - state 6 (Ringing)
[Dec  6 09:03:50] VERBOSE[15486] logger.c:     -- Zap/1-1 is ringing
[Dec  6 09:03:50] DEBUG[15486] rtp.c: Channel 'Local/91@my-queue-69a3,2' has no RTP, not doing anything
[Dec  6 09:03:50] DEBUG[14660] app_queue.c: Device 'Zap/1' changed to state '6' (Ringing) but we don't care because they're not a member of any queue.
[Dec  6 09:03:50] VERBOSE[15163] logger.c:     -- Agent/001 is ringing
[Dec  6 09:03:50] VERBOSE[14654] logger.c:     -- Channel 0/10, span 1 got hangup request, cause 16
[Dec  6 09:03:50] DEBUG[15163] channel.c: Set channel Zap/10-1 to write format alaw
[Dec  6 09:03:50] VERBOSE[15163] logger.c:     -- Stopped music on hold on Zap/10-1
[Dec  6 09:03:50] DEBUG[15163] channel.c: Scheduling timer at 0 sample intervals
[Dec  6 09:03:50] DEBUG[15163] app_queue.c: Zap/10-1: Nobody answered.
[Dec  6 09:03:50] DEBUG[15163] channel.c: Hanging up channel 'Agent/001'
[Dec  6 09:03:50] DEBUG[15163] chan_agent.c: Hangup called for state Down
[Dec  6 09:03:50] DEBUG[15163] channel.c: Hanging up channel 'Local/91@my-queue-69a3,1'
[Dec  6 09:03:50] DEBUG[15163] devicestate.c: Notification of state change to be queued on device/channel Local/91@my-queue-69a3,1
[Dec  6 09:03:50] DEBUG[15486] rtp.c: Channel 'Local/91@my-queue-69a3,2' has no RTP, not doing anything
[Dec  6 09:03:50] DEBUG[15163] pbx.c: Function result is '456524'
[Dec  6 09:03:50] DEBUG[12746] chan_local.c: Checking if extension 91@my-queue exists (devicestate)
[Dec  6 09:03:50] DEBUG[12746] devicestate.c: Changing state for Local/91@my-queue - state 2 (In use)
[Dec  6 09:03:50] DEBUG[14660] app_queue.c: Device 'Local/91@my-queue' changed to state '2' (In use) but we don't care because they're not a member of any
queue.
[Dec  6 09:03:50] DEBUG[15486] channel.c: Hanging up channel 'Zap/1-1'
[Dec  6 09:03:50] DEBUG[15486] chan_zap.c: zt_hangup(Zap/1-1)
[Dec  6 09:03:50] DEBUG[15486] chan_zap.c: Set option AUDIO MODE, value: ON(1) on Zap/1-1
[Dec  6 09:03:50] DEBUG[15486] chan_zap.c: Hangup: channel: 1 index = 0, normal = 26, callwait = -1, thirdcall = -1
[Dec  6 09:03:50] DEBUG[15486] chan_zap.c: Not yet hungup...  Calling hangup once with icause, and clearing call
[Dec  6 09:03:50] DEBUG[15486] chan_zap.c: disabled echo cancellation on channel 1
[Dec  6 09:03:50] DEBUG[15486] chan_zap.c: Set option TDD MODE, value: OFF(0) on Zap/1-1
[Dec  6 09:03:50] DEBUG[15486] chan_zap.c: Updated conferencing on 1, with 0 conference users
[Dec  6 09:03:50] DEBUG[15486] chan_zap.c: Set option AUDIO MODE, value: OFF(0) on Zap/1-1
[Dec  6 09:03:50] DEBUG[15486] chan_zap.c: disabled echo cancellation on channel 1
[Dec  6 09:03:50] VERBOSE[15486] logger.c:     -- Hungup 'Zap/1-1'
[Dec  6 09:03:50] DEBUG[15486] devicestate.c: Notification of state change to be queued on device/channel Zap/1-1
[Dec  6 09:03:50] DEBUG[12746] devicestate.c: Changing state for Zap/1 - state 0 (Unknown)
[Dec  6 09:03:50] DEBUG[14660] app_queue.c: Device 'Zap/1' changed to state '0' (Unknown) but we don't care because they're not a member of any queue.
[Dec  6 09:03:50] DEBUG[15163] pbx.c: Function result is '456524'
[Dec  6 09:03:50] DEBUG[15163] pbx.c: Function result is '91'
[Dec  6 09:03:50] DEBUG[15163] pbx.c: Function result is 'my-queue'
[Dec  6 09:03:50] DEBUG[15163] pbx.c: Function result is 'Local/91@my-queue-69a3,1'
[Dec  6 09:03:50] DEBUG[15163] pbx.c: Function result is ''
[Dec  6 09:03:50] DEBUG[15163] pbx.c: Function result is ''
[Dec  6 09:03:50] DEBUG[15163] pbx.c: Function result is ''
[Dec  6 09:03:50] DEBUG[15163] pbx.c: Function result is '2007-12-06 09:03:50'
[Dec  6 09:03:50] DEBUG[15163] pbx.c: Function result is ''
[Dec  6 09:03:50] DEBUG[15163] pbx.c: Function result is '2007-12-06 09:03:50'
[Dec  6 09:03:50] DEBUG[15163] pbx.c: Function result is '0'
[Dec  6 09:03:50] DEBUG[15163] pbx.c: Function result is '0'
[Dec  6 09:03:50] DEBUG[15163] pbx.c: Function result is 'NO ANSWER'
[Dec  6 09:03:50] DEBUG[15163] pbx.c: Function result is 'DOCUMENTATION'
[Dec  6 09:03:50] DEBUG[15163] pbx.c: Function result is ''
[Dec  6 09:03:50] DEBUG[15163] pbx.c: Function result is '1196942630.76'
[Dec  6 09:03:50] DEBUG[15163] pbx.c: Function result is ''
[Dec  6 09:03:50] DEBUG[15163] chan_agent.c: Hungup, howlong is 0, autologoff is 0
[Dec  6 09:03:50] DEBUG[15486] pbx.c: Function result is '91'
[Dec  6 09:03:50] DEBUG[15163] devicestate.c: Notification of state change to be queued on device/channel Agent/001
[Dec  6 09:03:50] DEBUG[15486] pbx.c: Function result is '91'
[Dec  6 09:03:50] DEBUG[12746] devicestate.c: Changing state for Agent/001 - state 1 (Not in use)
[Dec  6 09:03:50] DEBUG[15163] devicestate.c: Notification of state change to be queued on device/channel Agent/001
[Dec  6 09:03:50] DEBUG[14660] app_queue.c: Device 'Agent/001' changed to state '1' (Not in use)
[Dec  6 09:03:50] DEBUG[12746] devicestate.c: Changing state for Agent/001 - state 1 (Not in use)
[Dec  6 09:03:50] DEBUG[15486] pbx.c: Function result is 's'
[Dec  6 09:03:50] DEBUG[14660] app_queue.c: Device 'Agent/001' changed to state '1' (Not in use)
[Dec  6 09:03:50] DEBUG[15486] pbx.c: Function result is 'default'
[Dec  6 09:03:50] DEBUG[15486] pbx.c: Function result is 'Zap/1-1'
[Dec  6 09:03:50] DEBUG[15486] pbx.c: Function result is ''
[Dec  6 09:03:50] DEBUG[15486] pbx.c: Function result is ''
[Dec  6 09:03:50] DEBUG[15486] pbx.c: Function result is ''
[Dec  6 09:03:50] DEBUG[15486] pbx.c: Function result is '2007-12-06 09:03:50'
[Dec  6 09:03:50] DEBUG[15486] pbx.c: Function result is ''
[Dec  6 09:03:50] DEBUG[15486] pbx.c: Function result is '2007-12-06 09:03:50'
[Dec  6 09:03:50] DEBUG[15486] pbx.c: Function result is '0'
[Dec  6 09:03:50] DEBUG[15486] pbx.c: Function result is '0'
[Dec  6 09:03:50] DEBUG[15486] pbx.c: Function result is 'NO ANSWER'
[Dec  6 09:03:50] DEBUG[15486] pbx.c: Function result is 'DOCUMENTATION'
[Dec  6 09:03:50] DEBUG[15486] pbx.c: Function result is ''
[Dec  6 09:03:50] DEBUG[15486] pbx.c: Function result is '1196942630.79'
[Dec  6 09:03:50] DEBUG[15486] pbx.c: Function result is ''
[Dec  6 09:03:50] DEBUG[15163] pbx.c: Function result is ''
[Dec  6 09:03:50] DEBUG[15486] app_dial.c: Exiting with DIALSTATUS=CANCEL.
[Dec  6 09:03:50] DEBUG[15163] pbx.c: Function result is ''
[Dec  6 09:03:50] DEBUG[15163] pbx.c: Function result is '91'
[Dec  6 09:03:50] DEBUG[15486] pbx.c: Spawn extension (my-queue,91,2) exited non-zero on 'Local/91@my-queue-69a3,2'
[Dec  6 09:03:50] DEBUG[15163] pbx.c: Function result is 'my-queue'
[Dec  6 09:03:50] DEBUG[15163] pbx.c: Function result is 'Agent/001'
[Dec  6 09:03:50] DEBUG[15163] pbx.c: Function result is ''
[Dec  6 09:03:50] DEBUG[15163] pbx.c: Function result is ''
[Dec  6 09:03:50] DEBUG[15163] pbx.c: Function result is ''
[Dec  6 09:03:50] DEBUG[15163] pbx.c: Function result is '2007-12-06 09:03:50'
[Dec  6 09:03:50] VERBOSE[15486] logger.c:   == Spawn extension (my-queue, 91, 2) exited non-zero on 'Local/91@my-queue-69a3,2'
[Dec  6 09:03:50] DEBUG[15486] channel.c: Soft-Hanging up channel 'Local/91@my-queue-69a3,2'
[Dec  6 09:03:50] DEBUG[15486] channel.c: Hanging up channel 'Local/91@my-queue-69a3,2'
[Dec  6 09:03:50] DEBUG[15486] devicestate.c: Notification of state change to be queued on device/channel Local/91@my-queue-69a3,2
[Dec  6 09:03:50] DEBUG[12746] chan_local.c: Checking if extension 91@my-queue exists (devicestate)
[Dec  6 09:03:50] DEBUG[15163] pbx.c: Function result is ''
[Dec  6 09:03:50] DEBUG[12746] devicestate.c: Changing state for Local/91@my-queue - state 1 (Not in use)
[Dec  6 09:03:50] DEBUG[15163] pbx.c: Function result is '2007-12-06 09:03:50'
[Dec  6 09:03:50] DEBUG[14660] app_queue.c: Device 'Local/91@my-queue' changed to state '1' (Not in use) but we don't care because they're not a member of
any queue.
[Dec  6 09:03:50] DEBUG[15163] pbx.c: Function result is '0'
[Dec  6 09:03:50] DEBUG[15163] pbx.c: Function result is '0'
[Dec  6 09:03:50] DEBUG[15163] pbx.c: Function result is 'NO ANSWER'
[Dec  6 09:03:50] DEBUG[15163] pbx.c: Function result is 'DOCUMENTATION'
[Dec  6 09:03:50] DEBUG[15163] pbx.c: Function result is ''
[Dec  6 09:03:50] DEBUG[15163] pbx.c: Function result is '1196942630.78'
[Dec  6 09:03:50] DEBUG[15163] pbx.c: Function result is ''
[Dec  6 09:03:50] DEBUG[15163] app_queue.c: Queue 'my-queue' Leave, Channel 'Zap/10-1'
[Dec  6 09:03:50] DEBUG[15163] pbx.c: Spawn extension (default,98,9) exited non-zero on 'Zap/10-1'
[Dec  6 09:03:50] VERBOSE[15163] logger.c:   == Spawn extension (default, 98, 9) exited non-zero on 'Zap/10-1'
[Dec  6 09:03:50] DEBUG[15163] channel.c: Soft-Hanging up channel 'Zap/10-1'
[Dec  6 09:03:50] DEBUG[15163] channel.c: Hanging up channel 'Zap/10-1'
[Dec  6 09:03:50] DEBUG[15163] chan_zap.c: zt_hangup(Zap/10-1)
[Dec  6 09:03:50] DEBUG[15163] chan_zap.c: Set option AUDIO MODE, value: ON(1) on Zap/10-1
[Dec  6 09:03:50] DEBUG[15163] chan_zap.c: Hangup: channel: 10 index = 0, normal = 35, callwait = -1, thirdcall = -1
[Dec  6 09:03:50] DEBUG[15163] chan_zap.c: Not yet hungup...  Calling hangup once with icause, and clearing call
[Dec  6 09:03:50] DEBUG[15163] chan_zap.c: disabled echo cancellation on channel 10
[Dec  6 09:03:50] DEBUG[15163] chan_zap.c: Set option TDD MODE, value: OFF(0) on Zap/10-1
[Dec  6 09:03:50] DEBUG[15163] chan_zap.c: Updated conferencing on 10, with 0 conference users
[Dec  6 09:03:50] DEBUG[15163] chan_zap.c: Set option AUDIO MODE, value: OFF(0) on Zap/10-1
[Dec  6 09:03:50] DEBUG[15163] chan_zap.c: disabled echo cancellation on channel 10
[Dec  6 09:03:50] VERBOSE[15163] logger.c:     -- Hungup 'Zap/10-1'
[Dec  6 09:03:50] DEBUG[15163] devicestate.c: Notification of state change to be queued on device/channel Zap/10-1
[Dec  6 09:03:50] DEBUG[15486] pbx.c: Function result is '456524'
[Dec  6 09:03:50] DEBUG[12746] devicestate.c: Changing state for Zap/10 - state 0 (Unknown)
[Dec  6 09:03:50] DEBUG[15486] pbx.c: Function result is '456524'
[Dec  6 09:03:50] DEBUG[14660] app_queue.c: Device 'Zap/10' changed to state '0' (Unknown) but we don't care because they're not a member of any queue.
[Dec  6 09:03:50] DEBUG[15486] pbx.c: Function result is '91'
[Dec  6 09:03:50] DEBUG[15486] pbx.c: Function result is 'my-queue'
[Dec  6 09:03:50] DEBUG[15486] pbx.c: Function result is 'Local/91@my-queue-69a3,2'
[Dec  6 09:03:50] DEBUG[15486] pbx.c: Function result is 'Zap/1-1'
[Dec  6 09:03:50] DEBUG[15486] pbx.c: Function result is 'Dial'
[Dec  6 09:03:50] DEBUG[15486] pbx.c: Function result is 'Zap/g1/492191'
[Dec  6 09:03:50] DEBUG[15486] pbx.c: Function result is '2007-12-06 09:03:50'
[Dec  6 09:03:50] DEBUG[15486] pbx.c: Function result is ''
[Dec  6 09:03:50] DEBUG[15486] pbx.c: Function result is '2007-12-06 09:03:50'
[Dec  6 09:03:50] DEBUG[15486] pbx.c: Function result is '0'
[Dec  6 09:03:50] DEBUG[15486] pbx.c: Function result is '0'
[Dec  6 09:03:50] DEBUG[15486] pbx.c: Function result is 'NO ANSWER'
[Dec  6 09:03:50] DEBUG[15486] pbx.c: Function result is 'DOCUMENTATION'
[Dec  6 09:03:50] DEBUG[15486] pbx.c: Function result is ''
[Dec  6 09:03:50] DEBUG[15486] pbx.c: Function result is '1196942630.77'
[Dec  6 09:03:50] DEBUG[15486] pbx.c: Function result is ''
[Dec  6 09:03:50] DEBUG[15163] pbx.c: Function result is '456524'
[Dec  6 09:03:50] DEBUG[15163] pbx.c: Function result is '456524'
[Dec  6 09:03:50] DEBUG[15163] pbx.c: Function result is '98'
[Dec  6 09:03:50] DEBUG[15163] pbx.c: Function result is 'default'
[Dec  6 09:03:50] DEBUG[15163] pbx.c: Function result is 'Zap/10-1'
[Dec  6 09:03:50] DEBUG[15163] pbx.c: Function result is ''
[Dec  6 09:03:50] DEBUG[15163] pbx.c: Function result is 'Queue'
[Dec  6 09:03:50] DEBUG[15163] pbx.c: Function result is 'my-queue|t|||300'
[Dec  6 09:03:50] DEBUG[15163] pbx.c: Function result is '2007-12-06 09:03:16'
[Dec  6 09:03:50] DEBUG[15163] pbx.c: Function result is '2007-12-06 09:03:16'
[Dec  6 09:03:50] DEBUG[15163] pbx.c: Function result is '2007-12-06 09:03:50'
[Dec  6 09:03:50] DEBUG[15163] pbx.c: Function result is '34'
[Dec  6 09:03:50] DEBUG[15163] pbx.c: Function result is '34'
[Dec  6 09:03:50] DEBUG[15163] pbx.c: Function result is 'ANSWERED'
[Dec  6 09:03:50] DEBUG[15163] pbx.c: Function result is 'DOCUMENTATION'
[Dec  6 09:03:50] DEBUG[15163] pbx.c: Function result is ''
[Dec  6 09:03:50] DEBUG[15163] pbx.c: Function result is '1196942596.67'
[Dec  6 09:03:50] DEBUG[15163] pbx.c: Function result is ''
[Dec  6 09:03:52] DEBUG[14654] chan_zap.c: Echo cancellation already on
[Dec  6 09:03:52] DEBUG[15218] devicestate.c: Notification of state change to be queued on device/channel Zap/2-1
[Dec  6 09:03:52] DEBUG[12746] devicestate.c: Changing state for Zap/2 - state 2 (In use)
[Dec  6 09:03:52] VERBOSE[15218] logger.c:     -- Zap/2-1 answered Local/25@my-queue-56d6,2
[Dec  6 09:03:52] DEBUG[14660] app_queue.c: Device 'Zap/2' changed to state '2' (In use) but we don't care because they're not a member of any queue.
[Dec  6 09:03:52] DEBUG[15218] rtp.c: Channel 'Local/25@my-queue-56d6,2' has no RTP, not doing anything
[Dec  6 09:03:52] DEBUG[15218] devicestate.c: Notification of state change to be queued on device/channel Local/25@my-queue-56d6,2
[Dec  6 09:03:52] DEBUG[12746] chan_local.c: Checking if extension 25@my-queue exists (devicestate)
[Dec  6 09:03:52] DEBUG[12746] devicestate.c: Changing state for Local/25@my-queue - state 2 (In use)
[Dec  6 09:03:52] DEBUG[14660] app_queue.c: Device 'Local/25@my-queue' changed to state '2' (In use) but we don't care because they're not a member of any
queue.
[Dec  6 09:03:52] DEBUG[15132] app_queue.c: Dunno what to do with control type -1
[Dec  6 09:03:52] DEBUG[15132] devicestate.c: Notification of state change to be queued on device/channel Local/25@my-queue-56d6,1
[Dec  6 09:03:52] DEBUG[15132] devicestate.c: Notification of state change to be queued on device/channel Agent/217
[Dec  6 09:03:52] DEBUG[12746] chan_local.c: Checking if extension 25@my-queue exists (devicestate)
[Dec  6 09:03:52] VERBOSE[15132] logger.c:     -- Agent/217 answered Zap/9-1
[Dec  6 09:03:52] DEBUG[12746] devicestate.c: Changing state for Local/25@my-queue - state 2 (In use)
[Dec  6 09:03:52] DEBUG[15132] chan_zap.c: Set option TONE VERIFY, mode: MUTECONF(1) on Zap/9-1
[Dec  6 09:03:52] DEBUG[12746] devicestate.c: Changing state for Agent/217 - state 3 (Busy)
[Dec  6 09:03:52] DEBUG[15132] channel.c: Set channel Zap/9-1 to write format alaw
[Dec  6 09:03:52] VERBOSE[15132] logger.c:     -- Stopped music on hold on Zap/9-1
[Dec  6 09:03:52] DEBUG[14660] app_queue.c: Device 'Local/25@my-queue' changed to state '2' (In use) but we don't care because they're not a member of any
queue.
[Dec  6 09:03:52] DEBUG[14660] app_queue.c: Device 'Agent/217' changed to state '3' (Busy)
[Dec  6 09:03:52] DEBUG[15132] channel.c: Scheduling timer at 0 sample intervals
[Dec  6 09:03:52] DEBUG[15132] app_queue.c: Queue 'my-queue' Leave, Channel 'Zap/9-1'
[Dec  6 09:03:52] DEBUG[15218] channel.c: Planning to masquerade channel Zap/2-1 into the structure of Local/25@my-queue-56d6,1
[Dec  6 09:03:52] DEBUG[15218] channel.c: Done planning to masquerade channel Zap/2-1 into the structure of Local/25@my-queue-56d6,1
[Dec  6 09:03:52] DEBUG[15218] chan_local.c: Not posting to queue since already masked on 'Local/25@my-queue-56d6,2'
[Dec  6 09:03:52] DEBUG[15132] channel.c: Got clone lock for masquerade on 'Zap/2-1' at 0x8228138
[Dec  6 09:03:52] DEBUG[15132] channel.c: Set channel Zap/2-1 to write format alaw
[Dec  6 09:03:52] DEBUG[15132] channel.c: Set channel Zap/2-1 to read format alaw
[Dec  6 09:03:52] DEBUG[15132] channel.c: Putting channel Zap/2-1 in 8/8 formats
[Dec  6 09:03:52] DEBUG[15132] chan_zap.c: New owner for channel 2 is Zap/2-1
[Dec  6 09:03:52] DEBUG[15132] chan_zap.c: Updated conferencing on 2, with 0 conference users
[Dec  6 09:03:52] DEBUG[15132] chan_zap.c: Updated conferencing on 2, with 0 conference users
[Dec  6 09:03:52] DEBUG[15132] channel.c: Released clone lock on 'Local/25@my-queue-56d6,1<ZOMBIE>'
[Dec  6 09:03:52] DEBUG[15132] channel.c: Done Masquerading Zap/2-1 (6)
[Dec  6 09:03:52] DEBUG[15132] chan_agent.c: Bridge on 'Zap/2-1' being set to 'Agent/217' (3)
[Dec  6 09:03:52] DEBUG[15132] chan_agent.c: Native formats changing from 8 to 72
[Dec  6 09:03:52] DEBUG[15132] chan_agent.c: Resetting read to 8 and write to 8
[Dec  6 09:03:52] DEBUG[15218] channel.c: Didn't get a frame from channel: Local/25@my-queue-56d6,2
[Dec  6 09:03:52] DEBUG[15218] channel.c: Bridge stops bridging channels Local/25@my-queue-56d6,2 and Local/25@my-queue-56d6,1<ZOMBIE>
[Dec  6 09:03:52] DEBUG[15218] channel.c: Hanging up zombie 'Local/25@my-queue-56d6,1<ZOMBIE>'
[Dec  6 09:03:52] DEBUG[15218] devicestate.c: Notification of state change to be queued on device/channel Local/25@my-queue-56d6,1<ZOMBIE>
[Dec  6 09:03:52] DEBUG[15218] rtp.c: Channel 'Local/25@my-queue-56d6,2' has no RTP, not doing anything
[Dec  6 09:03:52] DEBUG[12746] chan_local.c: Checking if extension 25@my-queue exists (devicestate)
[Dec  6 09:03:52] DEBUG[15218] app_dial.c: Exiting with DIALSTATUS=ANSWER.
[Dec  6 09:03:52] DEBUG[15218] pbx.c: Spawn extension (my-queue,25,2) exited non-zero on 'Local/25@my-queue-56d6,2'
[Dec  6 09:03:52] DEBUG[12746] devicestate.c: Changing state for Local/25@my-queue - state 2 (In use)
[Dec  6 09:03:52] DEBUG[14660] app_queue.c: Device 'Local/25@my-queue' changed to state '2' (In use) but we don't care because they're not a member of any
queue.
[Dec  6 09:03:52] VERBOSE[15218] logger.c:   == Spawn extension (my-queue, 25, 2) exited non-zero on 'Local/25@my-queue-56d6,2'
[Dec  6 09:03:52] DEBUG[15218] channel.c: Soft-Hanging up channel 'Local/25@my-queue-56d6,2'
[Dec  6 09:03:52] DEBUG[15218] channel.c: Hanging up channel 'Local/25@my-queue-56d6,2'
[Dec  6 09:03:52] DEBUG[15218] devicestate.c: Notification of state change to be queued on device/channel Local/25@my-queue-56d6,2
[Dec  6 09:03:52] DEBUG[15218] pbx.c: Function result is '492131'
[Dec  6 09:03:52] DEBUG[15218] pbx.c: Function result is '492131'
[Dec  6 09:03:52] DEBUG[15218] pbx.c: Function result is '25'
[Dec  6 09:03:52] DEBUG[15218] pbx.c: Function result is 'my-queue'
[Dec  6 09:03:52] DEBUG[15218] pbx.c: Function result is 'Local/25@my-queue-56d6,2'
[Dec  6 09:03:52] DEBUG[15218] pbx.c: Function result is 'Zap/2-1'
[Dec  6 09:03:52] DEBUG[15218] pbx.c: Function result is 'Dial'
[Dec  6 09:03:52] DEBUG[15218] pbx.c: Function result is 'Zap/g1/492125'
[Dec  6 09:03:52] DEBUG[15218] pbx.c: Function result is '2007-12-06 09:03:19'
[Dec  6 09:03:52] DEBUG[15218] pbx.c: Function result is '2007-12-06 09:03:52'
[Dec  6 09:03:52] DEBUG[15218] pbx.c: Function result is '2007-12-06 09:03:52'
[Dec  6 09:03:52] DEBUG[15218] pbx.c: Function result is '33'
[Dec  6 09:03:52] DEBUG[15218] pbx.c: Function result is '0'
[Dec  6 09:03:52] DEBUG[15218] pbx.c: Function result is 'ANSWERED'
[Dec  6 09:03:52] DEBUG[15218] pbx.c: Function result is 'DOCUMENTATION'
[Dec  6 09:03:52] DEBUG[15218] pbx.c: Function result is ''
[Dec  6 09:03:52] DEBUG[15218] pbx.c: Function result is '1196942599.73'
[Dec  6 09:03:52] DEBUG[15218] pbx.c: Function result is ''
[Dec  6 09:03:52] DEBUG[12746] chan_local.c: Checking if extension 25@my-queue exists (devicestate)
[Dec  6 09:03:52] DEBUG[12746] devicestate.c: Changing state for Local/25@my-queue - state 1 (Not in use)
[Dec  6 09:03:52] DEBUG[14660] app_queue.c: Device 'Local/25@my-queue' changed to state '1' (Not in use) but we don't care because they're not a member of
any queue.
[Dec  6 09:03:58] VERBOSE[14654] logger.c:     -- Channel 0/9, span 1 got hangup request, cause 16
[Dec  6 09:03:58] DEBUG[15132] channel.c: Didn't get a frame from channel: Zap/9-1
[Dec  6 09:03:58] DEBUG[15132] channel.c: Bridge stops bridging channels Zap/9-1 and Agent/217
[Dec  6 09:03:58] DEBUG[15132] channel.c: Hanging up channel 'Agent/217'
[Dec  6 09:03:58] DEBUG[15132] chan_agent.c: Hangup called for state Up
[Dec  6 09:03:58] DEBUG[15132] channel.c: Hanging up channel 'Zap/2-1'
[Dec  6 09:03:58] DEBUG[15132] chan_zap.c: zt_hangup(Zap/2-1)
[Dec  6 09:03:58] DEBUG[15132] chan_zap.c: Set option AUDIO MODE, value: ON(1) on Zap/2-1
[Dec  6 09:03:58] DEBUG[15132] chan_zap.c: Hangup: channel: 2 index = 0, normal = 27, callwait = -1, thirdcall = -1
[Dec  6 09:03:58] DEBUG[15132] chan_zap.c: Not yet hungup...  Calling hangup once with icause, and clearing call
[Dec  6 09:03:58] DEBUG[15132] chan_zap.c: disabled echo cancellation on channel 2
[Dec  6 09:03:58] DEBUG[15132] chan_zap.c: Set option TDD MODE, value: OFF(0) on Zap/2-1
[Dec  6 09:03:58] DEBUG[15132] chan_zap.c: Updated conferencing on 2, with 0 conference users
[Dec  6 09:03:58] DEBUG[15132] chan_zap.c: Set option AUDIO MODE, value: OFF(0) on Zap/2-1
[Dec  6 09:03:58] DEBUG[15132] chan_zap.c: disabled echo cancellation on channel 2
[Dec  6 09:03:58] VERBOSE[15132] logger.c:     -- Hungup 'Zap/2-1'
[Dec  6 09:03:58] DEBUG[15132] devicestate.c: Notification of state change to be queued on device/channel Zap/2-1
[Dec  6 09:03:58] DEBUG[15132] pbx.c: Function result is '25'
[Dec  6 09:03:58] DEBUG[15132] pbx.c: Function result is '25'
[Dec  6 09:03:58] DEBUG[15132] pbx.c: Function result is 's'
[Dec  6 09:03:58] DEBUG[15132] pbx.c: Function result is 'default'
[Dec  6 09:03:58] DEBUG[15132] pbx.c: Function result is 'Zap/2-1'
[Dec  6 09:03:58] DEBUG[15132] pbx.c: Function result is ''
[Dec  6 09:03:58] DEBUG[15132] pbx.c: Function result is ''
[Dec  6 09:03:58] DEBUG[15132] pbx.c: Function result is ''
[Dec  6 09:03:58] DEBUG[15132] pbx.c: Function result is '2007-12-06 09:03:19'
[Dec  6 09:03:58] DEBUG[15132] pbx.c: Function result is '2007-12-06 09:03:52'
[Dec  6 09:03:58] DEBUG[15132] pbx.c: Function result is '2007-12-06 09:03:58'
[Dec  6 09:03:58] DEBUG[15132] pbx.c: Function result is '39'
[Dec  6 09:03:58] DEBUG[15132] pbx.c: Function result is '6'
[Dec  6 09:03:58] DEBUG[15132] pbx.c: Function result is 'ANSWERED'
[Dec  6 09:03:58] DEBUG[15132] pbx.c: Function result is 'DOCUMENTATION'
[Dec  6 09:03:58] DEBUG[15132] pbx.c: Function result is ''
[Dec  6 09:03:58] DEBUG[15132] pbx.c: Function result is '1196942599.75'
[Dec  6 09:03:58] DEBUG[15132] pbx.c: Function result is ''
[Dec  6 09:03:58] DEBUG[15132] chan_agent.c: Hungup, howlong is 0, autologoff is 0
[Dec  6 09:03:58] DEBUG[15132] devicestate.c: Notification of state change to be queued on device/channel Agent/217
[Dec  6 09:03:58] DEBUG[15132] devicestate.c: Notification of state change to be queued on device/channel Agent/217
[Dec  6 09:03:58] DEBUG[15132] pbx.c: Spawn extension (default,98,9) exited non-zero on 'Zap/9-1'
[Dec  6 09:03:58] VERBOSE[15132] logger.c:   == Spawn extension (default, 98, 9) exited non-zero on 'Zap/9-1'
[Dec  6 09:03:58] DEBUG[15132] channel.c: Soft-Hanging up channel 'Zap/9-1'
[Dec  6 09:03:58] DEBUG[15132] channel.c: Hanging up channel 'Zap/9-1'
[Dec  6 09:03:58] DEBUG[15132] chan_zap.c: zt_hangup(Zap/9-1)
[Dec  6 09:03:58] DEBUG[15132] chan_zap.c: Set option AUDIO MODE, value: ON(1) on Zap/9-1
[Dec  6 09:03:58] DEBUG[15132] chan_zap.c: Hangup: channel: 9 index = 0, normal = 34, callwait = -1, thirdcall = -1
[Dec  6 09:03:58] DEBUG[15132] chan_zap.c: Not yet hungup...  Calling hangup once with icause, and clearing call
[Dec  6 09:03:58] DEBUG[15132] chan_zap.c: disabled echo cancellation on channel 9
[Dec  6 09:03:58] DEBUG[15132] chan_zap.c: Set option TDD MODE, value: OFF(0) on Zap/9-1
[Dec  6 09:03:58] DEBUG[15132] chan_zap.c: Updated conferencing on 9, with 0 conference users
[Dec  6 09:03:58] DEBUG[15132] chan_zap.c: Set option AUDIO MODE, value: OFF(0) on Zap/9-1
[Dec  6 09:03:58] DEBUG[15132] chan_zap.c: disabled echo cancellation on channel 9
[Dec  6 09:03:58] VERBOSE[15132] logger.c:     -- Hungup 'Zap/9-1'
[Dec  6 09:03:58] DEBUG[15132] devicestate.c: Notification of state change to be queued on device/channel Zap/9-1
[Dec  6 09:03:58] DEBUG[15132] pbx.c: Function result is '492131'
[Dec  6 09:03:58] DEBUG[15132] pbx.c: Function result is '492131'
[Dec  6 09:03:58] DEBUG[15132] pbx.c: Function result is '98'
[Dec  6 09:03:58] DEBUG[15132] pbx.c: Function result is 'default'
[Dec  6 09:03:58] DEBUG[15132] pbx.c: Function result is 'Agent/217'
[Dec  6 09:03:58] DEBUG[15132] pbx.c: Function result is 'Agent/217'
[Dec  6 09:03:58] DEBUG[15132] pbx.c: Function result is 'Queue'
[Dec  6 09:03:58] DEBUG[15132] pbx.c: Function result is 'my-queue|t|||300'
[Dec  6 09:03:58] DEBUG[15132] pbx.c: Function result is '2007-12-06 09:03:13'
[Dec  6 09:03:58] DEBUG[15132] pbx.c: Function result is '2007-12-06 09:03:13'
[Dec  6 09:03:58] DEBUG[15132] pbx.c: Function result is '2007-12-06 09:03:58'
[Dec  6 09:03:58] DEBUG[15132] pbx.c: Function result is '45'
[Dec  6 09:03:58] DEBUG[15132] pbx.c: Function result is '45'
[Dec  6 09:03:58] DEBUG[15132] pbx.c: Function result is 'ANSWERED'
[Dec  6 09:03:58] DEBUG[15132] pbx.c: Function result is 'DOCUMENTATION'
[Dec  6 09:03:58] DEBUG[15132] pbx.c: Function result is ''
[Dec  6 09:03:58] DEBUG[15132] pbx.c: Function result is '1196942593.66'
[Dec  6 09:03:58] DEBUG[15132] pbx.c: Function result is ''
[Dec  6 09:03:58] DEBUG[12746] devicestate.c: Changing state for Zap/2 - state 0 (Unknown)
[Dec  6 09:03:58] DEBUG[12746] devicestate.c: Changing state for Agent/217 - state 1 (Not in use)
[Dec  6 09:03:58] DEBUG[12746] devicestate.c: Changing state for Agent/217 - state 1 (Not in use)
[Dec  6 09:03:58] DEBUG[12746] devicestate.c: Changing state for Zap/9 - state 0 (Unknown)
[Dec  6 09:03:58] DEBUG[14660] app_queue.c: Device 'Zap/2' changed to state '0' (Unknown) but we don't care because they're not a member of any queue.
[Dec  6 09:03:58] DEBUG[14660] app_queue.c: Device 'Agent/217' changed to state '1' (Not in use)
[Dec  6 09:03:58] DEBUG[14660] app_queue.c: Device 'Agent/217' changed to state '1' (Not in use)
[Dec  6 09:03:58] DEBUG[14660] app_queue.c: Device 'Zap/9' changed to state '0' (Unknown) but we don't care because they're not a member of any queue.