Queques error after update core and framework 2.11

PBX Firmware: 3.211.63-7
Hi,
this morning i’ve update the module framework and core from 2.11.0RCX to 2.11.0.0.
Now i’ve a problem:
After the IVR the queque start 1 second and go in error and return to IVR. I’ve change queques with ring and go fine.
There is some bug in queques 2.11.0.6 after update the core and framework?

Thank you

Giuseppe

If you post a log of a call disconnecting someone might be able to help out.

[2013-05-16 12:27:38] VERBOSE[3350][C-00000014] pbx.c: – Executing [dstring@macro-dial-one:12] Set(“Local/222@from-queue-00000019;2”, “DSTRING=SIP/222”) in new stack
[2013-05-16 12:27:38] VERBOSE[3350][C-00000014] pbx.c: – Executing [dstring@macro-dial-one:12] Set(“Local/222@from-queue-00000019;2”, “DSTRING=SIP/222”) in new stack
[2013-05-16 12:27:38] VERBOSE[3352][C-00000014] pbx.c: – Executing [200@from-internal:4] Macro(“Local/200@from-queue-0000001b;2”, “vm,200,BUSY,”) in new stack
[2013-05-16 12:27:38] VERBOSE[3352][C-00000014] pbx.c: – Executing [200@from-internal:4] Macro(“Local/200@from-queue-0000001b;2”, “vm,200,BUSY,”) in new stack
[2013-05-16 12:27:38] WARNING[3351][C-00000014] app_dial.c: Unable to create channel of type ‘SIP’ (cause 20 - Subscriber absent)
[2013-05-16 12:27:38] WARNING[3351][C-00000014] app_dial.c: Unable to create channel of type ‘SIP’ (cause 20 - Subscriber absent)
[2013-05-16 12:27:38] VERBOSE[3351][C-00000014] app_dial.c: == Everyone is busy/congested at this time (1:0/0/1)
[2013-05-16 12:27:38] VERBOSE[3351][C-00000014] app_dial.c: == Everyone is busy/congested at this time (1:0/0/1)
[2013-05-16 12:27:38] VERBOSE[3350][C-00000014] pbx.c: – Executing [dstring@macro-dial-one:13] Return(“Local/222@from-queue-00000019;2”, “”) in new stack
[2013-05-16 12:27:38] VERBOSE[3350][C-00000014] pbx.c: – Executing [dstring@macro-dial-one:13] Return(“Local/222@from-queue-00000019;2”, “”) in new stack
[2013-05-16 12:27:38] VERBOSE[3352][C-00000014] pbx.c: – Executing [s@macro-vm:1] Macro(“Local/200@from-queue-0000001b;2”, “user-callerid,SKIPTTL”) in new stack
[2013-05-16 12:27:38] VERBOSE[3352][C-00000014] pbx.c: – Executing [s@macro-vm:1] Macro(“Local/200@from-queue-0000001b;2”, “user-callerid,SKIPTTL”) in new stack
[2013-05-16 12:27:38] VERBOSE[3351][C-00000014] pbx.c: – Executing [s@macro-dial-one:43] ExecIf(“Local/223@from-queue-0000001a;2”, “0?MacroExit()”) in new stack
[2013-05-16 12:27:38] VERBOSE[3351][C-00000014] pbx.c: – Executing [s@macro-dial-one:43] ExecIf(“Local/223@from-queue-0000001a;2”, “0?MacroExit()”) in new stack
[2013-05-16 12:27:38] VERBOSE[3351][C-00000014] pbx.c: – Executing [s@macro-dial-one:44] ExecIf(“Local/223@from-queue-0000001a;2”, “0?Set(DIALSTATUS=)”) in new stack
[2013-05-16 12:27:38] VERBOSE[3351][C-00000014] pbx.c: – Executing [s@macro-dial-one:44] ExecIf(“Local/223@from-queue-0000001a;2”, “0?Set(DIALSTATUS=)”) in new stack
[2013-05-16 12:27:38] VERBOSE[3351][C-00000014] pbx.c: – Executing [s@macro-dial-one:45] GosubIf(“Local/223@from-queue-0000001a;2”, “0?s-CHANUNAVAIL,1()”) in new stack
[2013-05-16 12:27:38] VERBOSE[3351][C-00000014] pbx.c: – Executing [s@macro-dial-one:45] GosubIf(“Local/223@from-queue-0000001a;2”, “0?s-CHANUNAVAIL,1()”) in new stack
[2013-05-16 12:27:38] VERBOSE[3352][C-00000014] pbx.c: – Executing [s@macro-user-callerid:1] Set(“Local/200@from-queue-0000001b;2”, “TOUCH_MONITOR=1368700058.102”) in new stack
[2013-05-16 12:27:38] VERBOSE[3352][C-00000014] pbx.c: – Executing [s@macro-user-callerid:1] Set(“Local/200@from-queue-0000001b;2”, “TOUCH_MONITOR=1368700058.102”) in new stack
[2013-05-16 12:27:38] VERBOSE[3351][C-00000014] pbx.c: – Executing [s@macro-dial-one:46] MacroExit(“Local/223@from-queue-0000001a;2”, “”) in new stack
[2013-05-16 12:27:38] VERBOSE[3351][C-00000014] pbx.c: – Executing [s@macro-dial-one:46] MacroExit(“Local/223@from-queue-0000001a;2”, “”) in new stack
[2013-05-16 12:27:38] VERBOSE[3351][C-00000014] pbx.c: – Executing [s@macro-exten-vm:15] Set(“Local/223@from-queue-0000001a;2”, “SV_DIALSTATUS=CHANUNAVAIL”) in new stack
[2013-05-16 12:27:38] VERBOSE[3351][C-00000014] pbx.c: – Executing [s@macro-exten-vm:15] Set(“Local/223@from-queue-0000001a;2”, “SV_DIALSTATUS=CHANUNAVAIL”) in new stack
[2013-05-16 12:27:38] VERBOSE[3351][C-00000014] pbx.c: – Executing [s@macro-exten-vm:16] GosubIf(“Local/223@from-queue-0000001a;2”, “0?docfu,1()”) in new stack
[2013-05-16 12:27:38] VERBOSE[3351][C-00000014] pbx.c: – Executing [s@macro-exten-vm:16] GosubIf(“Local/223@from-queue-0000001a;2”, “0?docfu,1()”) in new stack
[2013-05-16 12:27:38] VERBOSE[3352][C-00000014] pbx.c: – Executing [s@macro-user-callerid:2] Set(“Local/200@from-queue-0000001b;2”, “AMPUSER=33338”) in new stack
[2013-05-16 12:27:38] VERBOSE[3352][C-00000014] pbx.c: – Executing [s@macro-user-callerid:2] Set(“Local/200@from-queue-0000001b;2”, “AMPUSER=33338”) in new stack
[2013-05-16 12:27:38] VERBOSE[3349][C-00000014] app_dial.c: – Called SIP/211
[2013-05-16 12:27:38] VERBOSE[3349][C-00000014] app_dial.c: – Called SIP/211
[2013-05-16 12:27:38] VERBOSE[3323][C-00000014] app_queue.c: – Local/211@from-queue-00000018;1 is ringing
[2013-05-16 12:27:38] VERBOSE[3323][C-00000014] app_queue.c: – Local/211@from-queue-00000018;1 is ringing
[2013-05-16 12:27:38] VERBOSE[3352][C-00000014] pbx.c: – Executing [s@macro-user-callerid:3] GotoIf(“Local/200@from-queue-0000001b;2”, “1?report”) in new stack
[2013-05-16 12:27:38] VERBOSE[3352][C-00000014] pbx.c: – Executing [s@macro-user-callerid:3] GotoIf(“Local/200@from-queue-0000001b;2”, “1?report”) in new stack
[2013-05-16 12:27:38] VERBOSE[3351][C-00000014] pbx.c: – Executing [s@macro-exten-vm:17] GosubIf(“Local/223@from-queue-0000001a;2”, “0?docfb,1()”) in new stack
[2013-05-16 12:27:38] VERBOSE[3351][C-00000014] pbx.c: – Executing [s@macro-exten-vm:17] GosubIf(“Local/223@from-queue-0000001a;2”, “0?docfb,1()”) in new stack
[2013-05-16 12:27:38] VERBOSE[3352][C-00000014] pbx.c: – Goto (macro-user-callerid,s,15)
[2013-05-16 12:27:38] VERBOSE[3352][C-00000014] pbx.c: – Goto (macro-user-callerid,s,15)
[2013-05-16 12:27:38] VERBOSE[3352][C-00000014] pbx.c: – Executing [s@macro-user-callerid:15] GotoIf(“Local/200@from-queue-0000001b;2”, “1?continue”) in new stack
[2013-05-16 12:27:38] VERBOSE[3352][C-00000014] pbx.c: – Executing [s@macro-user-callerid:15] GotoIf(“Local/200@from-queue-0000001b;2”, “1?continue”) in new stack
[2013-05-16 12:27:38] VERBOSE[3323][C-00000014] app_queue.c: – Local/211@from-queue-00000018;1 connected line has changed. Saving it until answer for SIP/mginforma2-0000002e
[2013-05-16 12:27:38] VERBOSE[3323][C-00000014] app_queue.c: – Local/211@from-queue-00000018;1 connected line has changed. Saving it until answer for SIP/mginforma2-0000002e
[2013-05-16 12:27:38] VERBOSE[3351][C-00000014] pbx.c: – Executing [s@macro-exten-vm:18] Set(“Local/223@from-queue-0000001a;2”, “DIALSTATUS=CHANUNAVAIL”) in new stack
[2013-05-16 12:27:38] VERBOSE[3351][C-00000014] pbx.c: – Executing [s@macro-exten-vm:18] Set(“Local/223@from-queue-0000001a;2”, “DIALSTATUS=CHANUNAVAIL”) in new stack
[2013-05-16 12:27:38] VERBOSE[3351][C-00000014] pbx.c: – Executing [s@macro-exten-vm:19] ExecIf(“Local/223@from-queue-0000001a;2”, “1?MacroExit()”) in new stack
[2013-05-16 12:27:38] VERBOSE[3351][C-00000014] pbx.c: – Executing [s@macro-exten-vm:19] ExecIf(“Local/223@from-queue-0000001a;2”, “1?MacroExit()”) in new stack
[2013-05-16 12:27:38] VERBOSE[3323][C-00000014] app_queue.c: – Local/211@from-queue-00000018;1 connected line has changed. Saving it until answer for SIP/mginforma2-0000002e
[2013-05-16 12:27:38] VERBOSE[3323][C-00000014] app_queue.c: – Local/211@from-queue-00000018;1 connected line has changed. Saving it until answer for SIP/mginforma2-0000002e
[2013-05-16 12:27:38] VERBOSE[3352][C-00000014] pbx.c: – Goto (macro-user-callerid,s,28)
[2013-05-16 12:27:38] VERBOSE[3352][C-00000014] pbx.c: – Goto (macro-user-callerid,s,28)
[2013-05-16 12:27:38] VERBOSE[3352][C-00000014] pbx.c: – Executing [s@macro-user-callerid:28] Set(“Local/200@from-queue-0000001b;2”, “CALLERID(number)=33338”) in new stack
[2013-05-16 12:27:38] VERBOSE[3352][C-00000014] pbx.c: – Executing [s@macro-user-callerid:28] Set(“Local/200@from-queue-0000001b;2”, “CALLERID(number)=33338”) in new stack
[2013-05-16 12:27:38] VERBOSE[3351][C-00000014] pbx.c: – Executing [223@from-internal:3] Set(“Local/223@from-queue-0000001a;2”, “__PICKUPMARK=”) in new stack
[2013-05-16 12:27:38] VERBOSE[3351][C-00000014] pbx.c: – Executing [223@from-internal:3] Set(“Local/223@from-queue-0000001a;2”, “__PICKUPMARK=”) in new stack
[2013-05-16 12:27:38] VERBOSE[3352][C-00000014] pbx.c: – Executing [s@macro-user-callerid:29] Set(“Local/200@from-queue-0000001b;2”, “CALLERID(name)=33338”) in new stack
[2013-05-16 12:27:38] VERBOSE[3352][C-00000014] pbx.c: – Executing [s@macro-user-callerid:29] Set(“Local/200@from-queue-0000001b;2”, “CALLERID(name)=33338”) in new stack
[2013-05-16 12:27:38] VERBOSE[3348][C-00000014] app_dial.c: – SIP/210-0000002f is ringing
[2013-05-16 12:27:38] VERBOSE[3348][C-00000014] app_dial.c: – SIP/210-0000002f is ringing
[2013-05-16 12:27:38] VERBOSE[3323][C-00000014] app_queue.c: – Local/210@from-queue-00000017;1 is ringing
[2013-05-16 12:27:38] VERBOSE[3323][C-00000014] app_queue.c: – Local/210@from-queue-00000017;1 is ringing
[2013-05-16 12:27:38] VERBOSE[3351][C-00000014] pbx.c: – Executing [223@from-internal:4] Goto(“Local/223@from-queue-0000001a;2”, “ivr-1,return,1”) in new stack
[2013-05-16 12:27:38] VERBOSE[3351][C-00000014] pbx.c: – Executing [223@from-internal:4] Goto(“Local/223@from-queue-0000001a;2”, “ivr-1,return,1”) in new stack
[2013-05-16 12:27:38] VERBOSE[3352][C-00000014] pbx.c: – Executing [s@macro-user-callerid:30] Set(“Local/200@from-queue-0000001b;2”, “CDR(cnum)=33338”) in new stack
[2013-05-16 12:27:38] VERBOSE[3352][C-00000014] pbx.c: – Executing [s@macro-user-callerid:30] Set(“Local/200@from-queue-0000001b;2”, “CDR(cnum)=33338”) in new stack
[2013-05-16 12:27:38] VERBOSE[3351][C-00000014] pbx.c: – Goto (ivr-1,return,1)
[2013-05-16 12:27:38] VERBOSE[3351][C-00000014] pbx.c: – Goto (ivr-1,return,1)
[2013-05-16 12:27:38] VERBOSE[1552] chan_sip.c: == Extension Changed 210[ext-local] new state Ringing for Notify User 200 (queued)
[2013-05-16 12:27:38] VERBOSE[1552] chan_sip.c: == Extension Changed 210[ext-local] new state Ringing for Notify User 200 (queued)
[2013-05-16 12:27:38] VERBOSE[3351][C-00000014] pbx.c: – Executing [return@ivr-1:1] Set(“Local/223@from-queue-0000001a;2”, “_IVR_CONTEXT=ivr-1”) in new stack
[2013-05-16 12:27:38] VERBOSE[3351][C-00000014] pbx.c: – Executing [return@ivr-1:1] Set(“Local/223@from-queue-0000001a;2”, “_IVR_CONTEXT=ivr-1”) in new stack
[2013-05-16 12:27:38] VERBOSE[3352][C-00000014] pbx.c: – Executing [s@macro-user-callerid:31] Set(“Local/200@from-queue-0000001b;2”, “CDR(cnam)=33338”) in new stack
[2013-05-16 12:27:38] VERBOSE[3352][C-00000014] pbx.c: – Executing [s@macro-user-callerid:31] Set(“Local/200@from-queue-0000001b;2”, “CDR(cnam)=33338”) in new stack
[2013-05-16 12:27:38] VERBOSE[3351][C-00000014] pbx.c: – Executing [return@ivr-1:2] Set(“Local/223@from-queue-0000001a;2”, “_IVR_CONTEXT_ivr-1=”) in new stack
[2013-05-16 12:27:38] VERBOSE[3351][C-00000014] pbx.c: – Executing [return@ivr-1:2] Set(“Local/223@from-queue-0000001a;2”, “_IVR_CONTEXT_ivr-1=”) in new stack
[2013-05-16 12:27:38] VERBOSE[3351][C-00000014] pbx.c: – Executing [return@ivr-1:3] Set(“Local/223@from-queue-0000001a;2”, “IVR_MSG=custom/commerciale1&custom/amministrattivo2&custom/assistenza3”) in new stack
[2013-05-16 12:27:38] VERBOSE[3351][C-00000014] pbx.c: – Executing [return@ivr-1:3] Set(“Local/223@from-queue-0000001a;2”, “IVR_MSG=custom/commerciale1&custom/amministrattivo2&custom/assistenza3”) in new stack
[2013-05-16 12:27:38] VERBOSE[3351][C-00000014] pbx.c: – Executing [return@ivr-1:4] Goto(“Local/223@from-queue-0000001a;2”, “s,start”) in new stack
[2013-05-16 12:27:38] VERBOSE[3351][C-00000014] pbx.c: – Executing [return@ivr-1:4] Goto(“Local/223@from-queue-0000001a;2”, “s,start”) in new stack
[2013-05-16 12:27:38] VERBOSE[3351][C-00000014] pbx.c: – Goto (ivr-1,s,10)
[2013-05-16 12:27:38] VERBOSE[3351][C-00000014] pbx.c: – Goto (ivr-1,s,10)
[2013-05-16 12:27:38] VERBOSE[3351][C-00000014] pbx.c: – Executing [s@ivr-1:10] Set(“Local/223@from-queue-0000001a;2”, “TIMEOUT(digit)=3”) in new stack
[2013-05-16 12:27:38] VERBOSE[3351][C-00000014] pbx.c: – Executing [s@ivr-1:10] Set(“Local/223@from-queue-0000001a;2”, “TIMEOUT(digit)=3”) in new stack
[2013-05-16 12:27:38] VERBOSE[3352][C-00000014] pbx.c: – Executing [s@macro-user-callerid:32] Set(“Local/200@from-queue-0000001b;2”, “CHANNEL(language)=it”) in new stack
[2013-05-16 12:27:38] VERBOSE[3352][C-00000014] pbx.c: – Executing [s@macro-user-callerid:32] Set(“Local/200@from-queue-0000001b;2”, “CHANNEL(language)=it”) in new stack
[2013-05-16 12:27:38] VERBOSE[3350][C-00000014] pbx.c: – Executing [s@macro-dial-one:27] GotoIf(“Local/222@from-queue-00000019;2”, “0?nodial”) in new stack
[2013-05-16 12:27:38] VERBOSE[3350][C-00000014] pbx.c: – Executing [s@macro-dial-one:27] GotoIf(“Local/222@from-queue-00000019;2”, “0?nodial”) in new stack
[2013-05-16 12:27:38] VERBOSE[3351][C-00000014] func_timeout.c: – Digit timeout set to 3.000
[2013-05-16 12:27:38] VERBOSE[3351][C-00000014] func_timeout.c: – Digit timeout set to 3.000

I’ve see this 2 rows with a warning:
[2013-05-16 12:27:38] WARNING[3351][C-00000014] app_dial.c: Unable to create channel of type ‘SIP’ (cause 20 - Subscriber absent)
[2013-05-16 12:27:38] WARNING[3351][C-00000014] app_dial.c: Unable to create channel of type ‘SIP’ (cause 20 - Subscriber absent)

After the ivr call the queues i see in the log:

  • start music on hold
  • the phone start to ring (1 second only)
  • then there is this warning and queues recall ivr.

Hi,
We have the exact same issue. We Upgraded to Framework 2.11.0.1 yesterday, previous to teh upgrade we were all working fine.

We have an inbound DID which plays an IVR, option 1 goes to a Q. We play an initial message and if no agents are busy the Q works fine.

If any of the agents are busy or on DND the Q plays the initial welcome message for the Q and then goes straight back to the initial IVR message and keeps going round in a loop.

80% of all our internal extension are Aastra 6755i handsets. We have Firmware’ed them which make no difference and also put on an very old firmware of 2010 which again makes no difference.

However… what we have found is if i change the Q agents to be our Polycom handset users it all works fine !

We have factory reset the Aastra handsets and configured them manually ( as opposed to endpoint ) and we still have the same issue.

Looks like a Bug to me.

Hope this helps.

Is anyone else experiencing this, at the moment i am putting it down to a bug but if no one else can replicate it it has to be something else.
Thanks

We are having the same problem after this update. What’s strange is that we saw this happen to 4 out of 6 of our queues…the other two still worked. But when you create a new queue, we get this issue again. I found that if you dial the queue extension internally, it works just fine. It appears to only be affecting inbound calls. Our inbound routes go to a Time Condition, then an Announcement, then an IVR, then finally a queue. I’m a bit relieved to see that it’s an apparent bug and not something I did. I’m hoping this gets resolved before my PBX goes online next week…I already had to postpone the conversion to SIP with our phone company once because of this.

To confirm if we dial our Q’s internals they also work fine. ONLY when the are accessed via our IVR do they go into a loop.

Should sixths be logged as an actual Bug ?

Yes, it appears to be a bug with having an IVR send a call to a queue. I created a test Miscellaneous Application to assign an extension to the IVR. I dialed that extension internally, got the IVR message, pressed 1 to be transferred to the queue, and got sent back to the IVR after a few seconds. So it isn’t just happening on inbound calls…it happens when you reach a queue via IVR.

I glad it’s not just me… have spent most of the day trying different combinations to see where the problem lies.

I presume this just needs to be left in the capable hands of the developers. Do we leave as is now or does someone have to log a bug report ?

I’ve spent a couple days trying to figure this out, so I’m also glad to see it’s not just me. I will submit a bug ticket shortly. I’ll update this topic if/when I hear anything out of it.

Recently updated to 2.11 Final Release.
When calls goes to queue from IVR and first agent is busy, loops into queue.

No ticket at trac yet.

http://www.freepbx.org/trac/ticket/6576

Hi all,

Testing some options we find one that solves this issue: Agent Restrictions could be set to “Extensions only” an now when you go to a queue with first agent busy from an ivr, next agent rings.

I think that is a temporal solution but its working for us.

this fixed our problem thank you for posting this info.

I’m having this issue too.

This does not work for us. Had to revert back to older version till sorted.

Agent Restrictions could be set to “Extensions only” an now when you go to a queue with first agent busy from an ivr, next agent rings.

I will be commenting on the ticket so please see the ticket but as of right now, the crux is I can’t repro this and the log provided isn’t much help. See more in the ticket.

this has been fixed in the latest core details are in the ticket