Upgraded 2.11 modules this pm and now have restrictions on out going calls

mgpa2000 · January 3, 2014, 4:52pm

We are using FreePBX v2.11 and I updated 9 modules to the latest version at around 15:00 today.

Very quickly afterwards, users started complaining about getting the “all circuits are busy now - please try your call again later” message when trying to make an ongoing call. It appears that we are restricted to 1 or 2 external calls.

In desperation, I rebooted the server at 16:06, logs below are from 16:06.

It could be an issue with licensing. I do recall seeing something about “cron.php” as part of the upgrade blurb, but not the upgrade process. The upgrade did appear to be successful. I can post results if it will help.

Any assistance would be appreciated.

TIA
Marcus.

Tried to limit following to key information. If you require further details please ask but note, I am familiar with Swyx and not FreePBX/Asterisk, so a little guidance in commands would help me enormously!

Upgrades, installs, enables and disables at 15:05:
-Appointment Reminder 2.11.0.1 will be upgraded to online version 2.11.0.2
-Outbound Call Limit 2.11.0.5 will be upgraded to online version 2.11.0.6
-Call Recording 2.11.0.3 will be upgraded to online version 2.11.0.4
-EndPoint Manager 2.11.0.1.21 will be upgraded to online version 2.11.0.1.26
-Fax Configuration 2.11.0.3 will be upgraded to online version 2.11.0.5
-Fax Configuration Professional 2.11.10 will be upgraded to online version 2.11.14
-Wake Up Calls 2.11.0beta2 will be upgraded to online version 2.11.1
-Asterisk Logfiles 2.11.0.6 will be upgraded to online version 2.11.0.7
-System Admin 2.11.0.36 will be upgraded to online version 2.11.0.37

FreePBX_dbug now reports:
2014-Jan-03 16:09:51 /var/www/html/admin/modules/endpoint/functions.inc/functions_common.php:1091
[NOTICE]: Undefined index: action

2014-Jan-03 16:09:51 /var/www/html/admin/modules/sysadmin/page.sysadmin.php:77
[NOTICE]: Undefined index: sysadmin_exp

2014-Jan-03 16:09:51 /var/www/html/admin/modules/sysadmin/views/rnav.php:83
[NOTICE]: Undefined index: sysadmin_exp

2014-Jan-03 16:09:51 /var/www/html/admin/libraries/view.functions.php:370
[NOTICE]: Undefined variable: mod_version_tag

2014-Jan-03 16:10:01 /var/www/html/admin/modules/qxact_reports/functions.inc/functions.php:13
[NOTICE]: Undefined variable: lic_info

2014-Jan-03 16:15:01 /var/www/html/admin/modules/qxact_reports/functions.inc/functions.php:13
[NOTICE]: Undefined variable: lic_info

2014-Jan-03 16:20:01 /var/www/html/admin/modules/qxact_reports/functions.inc/functions.php:13
[NOTICE]: Undefined variable: lic_info

2014-Jan-03 16:25:02 /var/www/html/admin/modules/qxact_reports/functions.inc/functions.php:13
[NOTICE]: Undefined variable: lic_info

2014-Jan-03 16:30:01 /var/www/html/admin/modules/qxact_reports/functions.inc/functions.php:13
[NOTICE]: Undefined variable: lic_info

2014-Jan-03 16:35:01 /var/www/html/admin/modules/qxact_reports/functions.inc/functions.php:13
[NOTICE]: Undefined variable: lic_info

alan · January 3, 2014, 5:31pm

Looks like you upgraded some Pro modules? Do you have licenses for the Pro modules?

mgpa2000 · January 3, 2014, 6:12pm

Hi Alan

Thanks for your feedback.

I suspect not but not knowing what is and what is not licensed is not known to me. Either way, I only upgraded modules and did not add any new ones.

I have now uninstalled “qxact_reports” and will look at the others over the weekend but I am concerned that we cannot have more than a couple of external calls. Not being able to view the current calls in real time means that I cannot say whether that they are incoming, outgoing or both.

Thanks
Marcus.

The following is just one example of many:

[2014-01-03 14:41:29] VERBOSE[4790][C-0000cf0a] pbx.c: – Executing [s@macro-dialout-trunk:13] Set(“SIP/739-0000f41c”, “OUTNUM=0xxxxxxx726]”) in new stack
[2014-01-03 14:41:29] VERBOSE[4790][C-0000cf0a] pbx.c: – Executing [s@macro-dialout-trunk:14] Set(“SIP/739-0000f41c”, “custom=SIP/VOIPFONE-SIP214”) in new stack
[2014-01-03 14:41:29] VERBOSE[4790][C-0000cf0a] pbx.c: – Executing [s@macro-dialout-trunk:15] ExecIf(“SIP/739-0000f41c”, “0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)Tt)”) in new stack
[2014-01-03 14:41:29] VERBOSE[4790][C-0000cf0a] pbx.c: – Executing [s@macro-dialout-trunk:16] ExecIf(“SIP/739-0000f41c”, “0?Set(DIAL_TRUNK_OPTIONS=TtM(confirm))”) in new stack
[2014-01-03 14:41:29] VERBOSE[4790][C-0000cf0a] pbx.c: – Executing [s@macro-dialout-trunk:17] Macro(“SIP/739-0000f41c”, “dialout-trunk-predial-hook,”) in new stack
[2014-01-03 14:41:29] VERBOSE[4790][C-0000cf0a] pbx.c: – Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit(“SIP/739-0000f41c”, “”) in new stack
[2014-01-03 14:41:29] VERBOSE[4790][C-0000cf0a] pbx.c: – Executing [s@macro-dialout-trunk:18] GotoIf(“SIP/739-0000f41c”, “0?bypass,1”) in new stack
[2014-01-03 14:41:29] VERBOSE[4790][C-0000cf0a] pbx.c: – Executing [s@macro-dialout-trunk:19] ExecIf(“SIP/739-0000f41c”, “1?Set(CONNECTEDLINE(num,i)=0xxxxxxx726])”) in new stack
[2014-01-03 14:41:29] VERBOSE[4790][C-0000cf0a] pbx.c: – Executing [s@macro-dialout-trunk:20] ExecIf(“SIP/739-0000f41c”, “1?Set(CONNECTEDLINE(name,i)=CID:01xxxxxxx39)”) in new stack
[2014-01-03 14:41:29] VERBOSE[4790][C-0000cf0a] pbx.c: – Executing [s@macro-dialout-trunk:21] GotoIf(“SIP/739-0000f41c”, “0?customtrunk”) in new stack
[2014-01-03 14:41:29] VERBOSE[4790][C-0000cf0a] pbx.c: – Executing [s@macro-dialout-trunk:22] Dial(“SIP/739-0000f41c”, “SIP/VOIPFONE-SIP214/07831647726],300,Tt”) in new stack
[2014-01-03 14:41:29] VERBOSE[4790][C-0000cf0a] netsock2.c: == Using SIP RTP TOS bits 184
[2014-01-03 14:41:29] VERBOSE[4790][C-0000cf0a] netsock2.c: == Using SIP RTP CoS mark 5
[2014-01-03 14:41:29] VERBOSE[4790][C-0000cf0a] app_dial.c: – Called SIP/VOIPFONE-SIP214/07831647726]
[2014-01-03 14:41:29] VERBOSE[4791][C-0000cf0a] app_mixmonitor.c: == Begin MixMonitor Recording SIP/739-0000f41c
[2014-01-03 14:41:30] VERBOSE[2141][C-0000cf0a] chan_sip.c: – Got SIP response 480 “Number Unavailable” back from 195.189.173.27:5060
[2014-01-03 14:41:30] VERBOSE[4790][C-0000cf0a] app_dial.c: – SIP/VOIPFONE-SIP214-0000f41d is circuit-busy
[2014-01-03 14:41:30] VERBOSE[4790][C-0000cf0a] app_dial.c: == Everyone is busy/congested at this time (1:0/1/0)
[2014-01-03 14:41:30] VERBOSE[4790][C-0000cf0a] pbx.c: – Executing [s@macro-dialout-trunk:23] NoOp(“SIP/739-0000f41c”, “Dial failed for some reason with DIALSTATUS = CONGESTION and HANGUPCAUSE = 19”) in new stack
[2014-01-03 14:41:30] VERBOSE[4790][C-0000cf0a] pbx.c: – Executing [s@macro-dialout-trunk:24] GotoIf(“SIP/739-0000f41c”, “1?continue,1:s-CONGESTION,1”) in new stack
[2014-01-03 14:41:30] VERBOSE[4790][C-0000cf0a] pbx.c: – Goto (macro-dialout-trunk,continue,1)
[2014-01-03 14:41:30] VERBOSE[4790][C-0000cf0a] pbx.c: – Executing [continue@macro-dialout-trunk:1] NoOp(“SIP/739-0000f41c”, “TRUNK Dial failed due to CONGESTION HANGUPCAUSE: 19 - failing through to other trunks”) in new stack
[2014-01-03 14:41:30] VERBOSE[4790][C-0000cf0a] pbx.c: – Executing [continue@macro-dialout-trunk:2] Set(“SIP/739-0000f41c”, “CALLERID(number)=739”) in new stack
[2014-01-03 14:41:30] VERBOSE[4790][C-0000cf0a] pbx.c: – Executing [07831647726]@outbound-18:6] Macro(“SIP/739-0000f41c”, “outisbusy,”) in new stack
[2014-01-03 14:41:30] VERBOSE[4790][C-0000cf0a] pbx.c: – Executing [s@macro-outisbusy:1] Progress(“SIP/739-0000f41c”, “”) in new stack
[2014-01-03 14:41:30] VERBOSE[4790][C-0000cf0a] pbx.c: – Executing [s@macro-outisbusy:2] GotoIf(“SIP/739-0000f41c”, “0?emergency,1”) in new stack
[2014-01-03 14:41:30] VERBOSE[4790][C-0000cf0a] pbx.c: – Executing [s@macro-outisbusy:3] GotoIf(“SIP/739-0000f41c”, “0?intracompany,1”) in new stack
[2014-01-03 14:41:30] VERBOSE[4790][C-0000cf0a] pbx.c: – Executing [s@macro-outisbusy:4] Playback(“SIP/739-0000f41c”, “all-circuits-busy-now&pls-try-call-later, noanswer”) in new stack
[2014-01-03 14:41:30] VERBOSE[4790][C-0000cf0a] file.c: – <SIP/739-0000f41c> Playing ‘all-circuits-busy-now.ulaw’ (language ‘en’)
[2014-01-03 14:41:31] VERBOSE[4771][C-0000cf09] pbx.c: – Executing [h@macro-dialout-trunk:1] Macro(“SIP/734-0000f41a”, “hangupcall,”) in new stack
[2014-01-03 14:41:31] VERBOSE[4771][C-0000cf09] pbx.c: – Executing [s@macro-hangupcall:1] GotoIf(“SIP/734-0000f41a”, “1?theend”) in new stack
[2014-01-03 14:41:31] VERBOSE[4771][C-0000cf09] pbx.c: – Goto (macro-hangupcall,s,3)
[2014-01-03 14:41:31] VERBOSE[4771][C-0000cf09] pbx.c: – Executing [s@macro-hangupcall:3] ExecIf(“SIP/734-0000f41a”, “0?Set(CDR(recordingfile)=)”) in new stack
[2014-01-03 14:41:31] VERBOSE[4771][C-0000cf09] pbx.c: – Executing [s@macro-hangupcall:4] Hangup(“SIP/734-0000f41a”, “”) in new stack
[2014-01-03 14:41:31] VERBOSE[4771][C-0000cf09] app_macro.c: == Spawn extension (macro-hangupcall, s, 4) exited non-zero on ‘SIP/734-0000f41a’ in macro ‘hangupcall’
[2014-01-03 14:41:31] VERBOSE[4771][C-0000cf09] pbx.c: == Spawn extension (macro-dialout-trunk, h, 1) exited non-zero on ‘SIP/734-0000f41a’
[2014-01-03 14:41:31] VERBOSE[4771][C-0000cf09] app_macro.c: == Spawn extension (macro-dialout-trunk, s, 22) exited non-zero on ‘SIP/734-0000f41a’ in macro ‘dialout-trunk’
[2014-01-03 14:41:31] VERBOSE[4771][C-0000cf09] pbx.c: == Spawn extension (outbound-13, 441xxxxxx167, 5) exited non-zero on ‘SIP/734-0000f41a’

ashcortech · February 12, 2014, 12:16am

I’m experiencing this too, did you ever find a solution?

mgpa2000 · February 12, 2014, 4:21pm

To be honest, I hadn’t, so I had had another look at it. Thanks for the reminder.

I have disabled the unlicensed “Outbound Call Limit” and that appears to have done the trick. This was one of the original modules that I updated on 3 Jan 2014 and shortly afterwards users started complaining about an “all circuits busy” message.

Early days yet but we have just tried a load test and got over 50% of staff on the telephone at one go.

This was one of two messages being shown by the Reports / Asterisk Logfiles / “freepbx.log” report:

[2014-Feb-10 17:40:26] [ERROR] (bin/retrieve_conf:185) - /var/lib/asterisk/agi-bin/calllimit failed to copy from module directory

It appears to be more important than it looks.

Disabling the non-licensed module stopped the error message from occurring too.