04 February 2015, 6:08

Hello

Today I tried to open an institution to allow registration. But editing failed. Obviously connecting lasts too long. (But this happens only by editing institutions.) The error message tells me, that the site is unavailable. When refreshing the page sometimes the institution opens for editing.

Here is a snip of log

[Tue Feb 03 12:45:14 2015] [error] [client xx.xx.xx.xx] [WAR] 43 (lib/web.php:3847) Curl error: 7: couldn't connect to host, referer: http://www.xxx/admin/users/institutions.php
[Tue Feb 03 12:45:14 2015] [error] [client xx.xx.xx.xx] Call stack (most recent first):, referer: http://www.xxx/admin/users/institutions.php
[Tue Feb 03 12:45:14 2015] [error] [client xx.xx.xx.xx]   * log_message("Curl error: 7: couldn't connect to host", 8, true, true) at /home/.sites/xxx/site150/web/eportfolio/lib/errors.php:95, referer: http://www.xxx/admin/users/institutions.php
[Tue Feb 03 12:45:14 2015] [error] [client xx.xx.xx.xx]   * log_warn("Curl error: 7: couldn't connect to host") at /home/.sites/xxx/site150/web/eportfolio/lib/web.php:3847, referer: http://www.xxx/admin/users/institutions.php

I hope that someone knows/finds a fix

Thanks and Regards
Conrad

Mahara  1.9.1
PHP erstellt für    Linux
Datenbankversion    5.1.61  InnoDB
Datenbankzeichensatz    utf8_general_ci
PHP-Version    5.4.35
Webserver    Apache
PHP-Interface für den Webserver    cgi-fcgi

04 February 2015, 10:24

Hi Conrad,

The error occurs when mahara_http_request() is called. This can be called in a number of places. One of the places is when you click register of site to mahara. Another is when you want to set up external authentication for an institution.

Does the institution that you are trying to edit have any external authentication associated with it? Is the connection to the external authentication failing?

It's hard to work out exactly what is going wrong without seeing the backtrace before the errors you have posted. Is there more backtrace info that you can post?

Cheers

Robert

04 February 2015, 13:25

Hi Robert,

thanks for your support.
I checked all 7 institutions. There is only "Intern" Type of authentication.

This is a longer log snip. Too long for a forum post ;-)  I put it on a server for downloading:Here is the link:
https://www.dropbox.com/s/uv2kwrw6dqx8y1k/mahara_log_1150204.txt?dl=0

It is a 137KB txt file.

In case you won't download that file and prefer to see it in a posting, please tell me.

Thanks in advance and Regards
Conrad

07 February 2015, 23:00

Hi Robert,

to fix the problem I tried to delete institutions. But this was not possible. I get the message: "Site unavailable. A nonrecoverable error occurred. This probably means you have encountered a bug in the system". 
This message was shown immediately after I affirmed deleting the institution. (Editing sucks, because of time out).

May be that this notice may help to detect the bug and to fix it.

Greetings
Conrad

PS: Did  the download link to the Log snippet work?

01 March 2015, 21:07

Hello Conrad,

The "Site unavailable" message is a generic error message. Can you please check in your error log for any more information? Usually, that gives more details if the on-screen message doesn't already. If you have the same problem on your test server, you could simply change the config value to

$cfg->productionmode = false;

Error messages are then displayed directly on the screen.

Cheers

Kristina

02 March 2015, 4:10

Hello Kristina,

there is no such entry ($cfg->productionmode) in my config to set it to false. Should I add that line you mentioned?

Thanks & Regards

26 February 2015, 21:38

Hello Mahara Community,

Does anyone have a clue how to fix this bug?

Thanks, Conrad

02 March 2015, 11:29

Hello Conrad,

Please add it to your config but only on the test server. If you add it to your production server, no mails are sent etc. The config value is an easy way to disable a number of things on Mahara to prevent test servers sending messages to real users etc.

Cheers

Kristina

23 August 2015, 20:57

Hello,

I upgraded to the latest version 15.04.3 in hope that the problem not being able to edit an institution or to create a new institution will be solved. But the problem persists.

When calling these actions (new, edit) the call seems to stuck. After a minute or so I get the usual error message: "Site unavailable, A nonrecoverable error occurred. This probably means you have encountered a bug in the system".

May be this has something to do with BrowserID
It is installed, but within Authentication I cannot choose

Hope someone can help me, to fix this.

Thanks and regards, Conrad

Here is the log that points to this:

[Sun Aug 23 10:47:46 2015] [error] [client xx.xx.xx.xx] Call stack (most recent first):, referer: http://www.xxxx/eportfolio/admin/users/institutions.php
[Sun Aug 23 10:47:46 2015] [error] [client xx.xx.xx.xx]   * log_message("Curl error: 28: connect() timed out!", 8, true, true) at /home/.sites/383/site150/web/eportfolio/lib/errors.php:97, referer: http://www.xxxx/eportfolio/admin/users/institutions.php
[Sun Aug 23 10:47:46 2015] [error] [client xx.xx.xx.xx]   * log_warn("Curl error: 28: connect() timed out!") at /home/.sites/383/site150/web/eportfolio/lib/web.php:3970, referer: http://www.xxxx/eportfolio/admin/users/institutions.php
[Sun Aug 23 10:47:46 2015] [error] [client xx.xx.xx.xx]   * mahara_http_request(array(size 3)) at /home/.sites/383/site150/web/eportfolio/auth/browserid/lib.php:172, referer: http://www.xxxx/eportfolio/admin/users/institutions.php
[Sun Aug 23 10:47:46 2015] [error] [client xx.xx.xx.xx]   * PluginAuthBrowserid::is_available() at /home/.sites/383/site150/web/eportfolio/auth/browserid/lib.php:105, referer: http://www.xxxx/eportfolio/admin/users/institutions.php
[Sun Aug 23 10:47:46 2015] [error] [client xx.xx.xx.xx]   * PluginAuthBrowserid::is_usable() at Unknown:0, referer: http://www.xxxx/eportfolio/admin/users/institutions.php
[Sun Aug 23 10:47:46 2015] [error] [client xx.xx.xx.xx]   * call_user_func_array(array(size 2), array(size 0)) at /home/.sites/383/site150/web/eportfolio/lib/mahara.php:1625, referer: http://www.xxxx/eportfolio/admin/users/institutions.php
[Sun Aug 23 10:47:46 2015] [error] [client xx.xx.xx.xx]   * call_static_method("PluginAuthbrowserid", "is_usable") at /home/.sites/383/site150/web/eportfolio/auth/lib.php:718, referer: http://www.xxxx/eportfolio/admin/users/institutions.php
[Sun Aug 23 10:47:46 2015] [error] [client xx.xx.xx.xx]   * auth_get_available_auth_types() at /home/.sites/383/site150/web/eportfolio/admin/users/institutions.php:253, referer: http://www.xxxx/eportfolio/admin/users/institutions.php
[Sun Aug 23 10:47:46 2015] [error] [client xx.xx.xx.xx] , referer: http://www.xxxx/eportfolio/admin/users/institutions.php
[Sun Aug 23 10:48:06 2015] [error] [client xx.xx.xx.xx] [WAR] 08 (lib/web.php:3970) Curl error: 28: connect() timed out!, referer: http://www.xxxx/eportfolio/admin/users/institutions.php

[Sun Aug 23 10:48:06 2015] [error] [client xx.xx.xx.xx] Call stack (most recent first):, referer: http://www.xxxx/eportfolio/admin/users/institutions.php
[Sun Aug 23 10:48:06 2015] [error] [client xx.xx.xx.xx]   * log_message("Curl error: 28: connect() timed out!", 8, true, true) at /home/.sites/383/site150/web/eportfolio/lib/errors.php:97, referer: http://www.xxxx/eportfolio/admin/users/institutions.php
[Sun Aug 23 10:48:06 2015] [error] [client xx.xx.xx.xx]   * log_warn("Curl error: 28: connect() timed out!") at /home/.sites/383/site150/web/eportfolio/lib/web.php:3970, referer: http://www.xxxx/eportfolio/admin/users/institutions.php
[Sun Aug 23 10:48:06 2015] [error] [client xx.xx.xx.xx]   * mahara_http_request(array(size 3)) at /home/.sites/383/site150/web/eportfolio/auth/browserid/lib.php:172, referer: http://www.xxxx/eportfolio/admin/users/institutions.php
[Sun Aug 23 10:48:06 2015] [error] [client xx.xx.xx.xx]   * PluginAuthBrowserid::is_available() at /home/.sites/383/site150/web/eportfolio/auth/browserid/lib.php:105, referer: http://www.xxxx/eportfolio/admin/users/institutions.php
[Sun Aug 23 10:48:06 2015] [error] [client xx.xx.xx.xx]   * PluginAuthBrowserid::is_usable() at Unknown:0, referer: http://www.xxxx/eportfolio/admin/users/institutions.php
[Sun Aug 23 10:48:06 2015] [error] [client xx.xx.xx.xx]   * call_user_func_array(array(size 2), array(size 0)) at /home/.sites/383/site150/web/eportfolio/lib/mahara.php:1625, referer: http://www.xxxx/eportfolio/admin/users/institutions.php
[Sun Aug 23 10:48:06 2015] [error] [client xx.xx.xx.xx]   * call_static_method("PluginAuthbrowserid", "is_usable") at /home/.sites/383/site150/web/eportfolio/auth/lib.php:718, referer: http://www.xxxx/eportfolio/admin/users/institutions.php
[Sun Aug 23 10:48:06 2015] [error] [client xx.xx.xx.xx]   * auth_get_available_auth_types() at /home/.sites/383/site150/web/eportfolio/admin/users/institutions.php:253, referer: http://www.xxxx/eportfolio/admin/users/institutions.php
[Sun Aug 23 10:48:06 2015] [error] [client xx.xx.xx.xx] , referer: http://www.xxxx/eportfolio/admin/users/institutions.php
[Sun Aug 23 10:48:06 2015] [error] [client xx.xx.xx.xx] [WAR] 08 (lib/errors.php:747) Failed to get a recordset: mysqli error: [2006: MySQL server has gone away] in EXECUTE("SELECT * FROM "ep_artefact_config" WHERE "plugin" = 'file' ORDER BY field "), referer: http://www.xxxx/eportfolio/admin/users/institutions.php
[Sun Aug 23 10:48:06 2015] [error] [client xx.xx.xx.xx] [WAR] 08 (lib/errors.php:747) Command was: SELECT * FROM "ep_artefact_config" WHERE "plugin" = ? ORDER BY field  and values was (file), referer: http://www.xxxx/eportfolio/admin/users/institutions.php
[Sun Aug 23 10:48:06 2015] [error] [client xx.xx.xx.xx] Call stack (most recent first):, referer: http://www.xxxx/eportfolio/admin/users/institutions.php
[Sun Aug 23 10:48:06 2015] [error] [client xx.xx.xx.xx]   * log_message("Failed to get a recordset: mysqli error: [2006: My...", 8, true, true) at /home/.sites/383/site150/web/eportfolio/lib/errors.php:97, referer: http://www.xxxx/eportfolio/admin/users/institutions.php
[Sun Aug 23 10:48:06 2015] [error] [client xx.xx.xx.xx]   * log_warn("Failed to get a recordset: mysqli error: [2006: My...") at /home/.sites/383/site150/web/eportfolio/lib/errors.php:747, referer: http://www.xxxx/eportfolio/admin/users/institutions.php
[Sun Aug 23 10:48:06 2015] [error] [client xx.xx.xx.xx]   * SQLException->__construct("Failed to get a recordset: mysqli error: [2006: My...") at /home/.sites/383/site150/web/eportfolio/lib/dml.php:480, referer: http://www.xxxx/eportfolio/admin/users/institutions.php
[Sun Aug 23 10:48:06 2015] [error] [client xx.xx.xx.xx]   * get_recordset_sql("SELECT * FROM "ep_artefact_config" WHERE "plugin" ...", array(size 1)) at /home/.sites/383/site150/web/eportfolio/lib/dml.php:429, referer: http://www.xxxx/eportfolio/admin/users/institutions.php
[Sun Aug 23 10:48:06 2015] [error] [client xx.xx.xx.xx]   * get_recordset_select("artefact_config", ""plugin" = ?", array(size 1), "field", "*", "", "") at /home/.sites/383/site150/web/eportfolio/lib/dml.php:393, referer: http://www.xxxx/eportfolio/admin/users/institutions.php
[Sun Aug 23 10:48:06 2015] [error] [client xx.xx.xx.xx]   * get_recordset("artefact_config", "plugin", "file", "field", "*", "", "") at /home/.sites/383/site150/web/eportfolio/lib/dml.php:589, referer: http://www.xxxx/eportfolio/admin/users/institutions.php
[Sun Aug 23 10:48:06 2015] [error] [client xx.xx.xx.xx]   * get_records_array("artefact_config", "plugin", "file", "field") at /home/.sites/383/site150/web/eportfolio/lib/mahara.php:946, referer: http://www.xxxx/eportfolio/admin/users/institutions.php
[Sun Aug 23 10:48:06 2015] [error] [client xx.xx.xx.xx]   * get_config_plugin("artefact", "file", "defaultquota") at /home/.sites/383/site150/web/eportfolio/admin/users/institutions.php:491, referer: http://www.xxxx/eportfolio/admin/users/institutions.php
[Sun Aug 23 10:48:06 2015] [error] [client xx.xx.xx.xx] , referer: http://www.xxxx/eportfolio/admin/users/institutions.php
[Sun Aug 23 10:48:06 2015] [error] [client xx.xx.xx.xx] [WAR] 08 (lib/dml.php:480) Failed to get a recordset: mysqli error: [2006: MySQL server has gone away] in EXECUTE("SELECT * FROM "ep_artefact_config" WHERE "plugin" = 'file' ORDER BY field "), referer: http://www.xxxx/eportfolio/admin/users/institutions.php
[Sun Aug 23 10:48:06 2015] [error] [client xx.xx.xx.xx] [WAR] 08 (lib/dml.php:480) Command was: SELECT * FROM "ep_artefact_config" WHERE "plugin" = ? ORDER BY field  and values was (file), referer: http://www.xxxx/eportfolio/admin/users/institutions.php
[Sun Aug 23 10:48:06 2015] [error] [client xx.xx.xx.xx] Call stack (most recent first):, referer: http://www.xxxx/eportfolio/admin/users/institutions.php
[Sun Aug 23 10:48:06 2015] [error] [client xx.xx.xx.xx]   * get_recordset_sql("SELECT * FROM "ep_artefact_config" WHERE "plugin" ...", array(size 1)) at /home/.sites/383/site150/web/eportfolio/lib/dml.php:429, referer: http://www.xxxx/eportfolio/admin/users/institutions.php
[Sun Aug 23 10:48:06 2015] [error] [client xx.xx.xx.xx]   * get_recordset_select("artefact_config", ""plugin" = ?", array(size 1), "field", "*", "", "") at /home/.sites/383/site150/web/eportfolio/lib/dml.php:393, referer: http://www.xxxx/eportfolio/admin/users/institutions.php
[Sun Aug 23 10:48:06 2015] [error] [client xx.xx.xx.xx]   * get_recordset("artefact_config", "plugin", "file", "field", "*", "", "") at /home/.sites/383/site150/web/eportfolio/lib/dml.php:589, referer: http://www.xxxx/eportfolio/admin/users/institutions.php
[Sun Aug 23 10:48:06 2015] [error] [client xx.xx.xx.xx]   * get_records_array("artefact_config", "plugin", "file", "field") at /home/.sites/383/site150/web/eportfolio/lib/mahara.php:946, referer: http://www.xxxx/eportfolio/admin/users/institutions.php
[Sun Aug 23 10:48:06 2015] [error] [client xx.xx.xx.xx]   * get_config_plugin("artefact", "file", "defaultquota") at /home/.sites/383/site150/web/eportfolio/admin/users/institutions.php:491, referer: http://www.xxxx/eportfolio/admin/users/institutions.php
[Sun Aug 23 10:48:06 2015] [error] [client xx.xx.xx.xx] , referer: http://www.xxxx/eportfolio/admin/users/institutions.php
[Sun Aug 23 10:48:06 2015] [error] [client xx.xx.xx.xx] [WAR] 08 (lib/errors.php:747) Failed to get a recordset: mysqli error: [2006: MySQL server has gone away] in EXECUTE("SELECT * FROM "ep_artefact_installed" ORDER BY name "), referer: http://www.xxxx/eportfolio/admin/users/institutions.php
[Sun Aug 23 10:48:06 2015] [error] [client xx.xx.xx.xx] [WAR] 08 (lib/errors.php:747) Command was: SELECT * FROM "ep_artefact_installed" ORDER BY name , referer: http://www.xxxx/eportfolio/admin/users/institutions.php
[Sun Aug 23 10:48:06 2015] [error] [client xx.xx.xx.xx] Call stack (most recent first):, referer: http://www.xxxx/eportfolio/admin/users/institutions.php
[Sun Aug 23 10:48:06 2015] [error] [client xx.xx.xx.xx]   * log_message("Failed to get a recordset: mysqli error: [2006: My...", 8, true, true) at /home/.sites/383/site150/web/eportfolio/lib/errors.php:97, referer: http://www.xxxx/eportfolio/admin/users/institutions.php
[Sun Aug 23 10:48:06 2015] [error] [client xx.xx.xx.xx]   * log_warn("Failed to get a recordset: mysqli error: [2006: My...") at /home/.sites/383/site150/web/eportfolio/lib/errors.php:747, referer: http://www.xxxx/eportfolio/admin/users/institutions.php
[Sun Aug 23 10:48:06 2015] [error] [client xx.xx.xx.xx]   * SQLException->__construct("Failed to get a recordset: mysqli error: [2006: My...") at /home/.sites/383/site150/web/eportfolio/lib/dml.php:480, referer: http://www.xxxx/eportfolio/admin/users/institutions.php
[Sun Aug 23 10:48:06 2015] [error] [client xx.xx.xx.xx]   * get_recordset_sql("SELECT * FROM "ep_artefact_installed" ORDER BY nam...", null) at /home/.sites/383/site150/web/eportfolio/lib/dml.php:429, referer: http://www.xxxx/eportfolio/admin/users/institutions.php
[Sun Aug 23 10:48:06 2015] [error] [client xx.xx.xx.xx]   * get_recordset_select("artefact_installed", "", null, "name", "*", "", "") at /home/.sites/383/site150/web/eportfolio/lib/dml.php:393, referer: http://www.xxxx/eportfolio/admin/users/institutions.php
[Sun Aug 23 10:48:06 2015] [error] [client xx.xx.xx.xx]   * get_recordset("artefact_installed", "", "", "name", "*", "", "") at /home/.sites/383/site150/web/eportfolio/lib/dml.php:565, referer: http://www.xxxx/eportfolio/admin/users/institutions.php
[Sun Aug 23 10:48:06 2015] [error] [client xx.xx.xx.xx]   * get_records_assoc("artefact_installed", "", "", "name") at /home/.sites/383/site150/web/eportfolio/lib/mahara.php:1576, referer: http://www.xxxx/eportfolio/admin/users/institutions.php
[Sun Aug 23 10:48:06 2015] [error] [client xx.xx.xx.xx]   * plugins_installed("artefact") at /home/.sites/383/site150/web/eportfolio/lib/web.php:2372, referer: http://www.xxxx/eportfolio/admin/users/institutions.php
[Sun Aug 23 10:48:06 2015] [error] [client xx.xx.xx.xx]   * admin_nav() at /home/.sites/383/site150/web/eportfolio/lib/web.php:2777, referer: http://www.xxxx/eportfolio/admin/users/institutions.php
[Sun Aug 23 10:48:06 2015] [error] [client xx.xx.xx.xx]   * main_nav() at /home/.sites/383/site150/web/eportfolio/lib/web.php:563, referer: http://www.xxxx/eportfolio/admin/users/institutions.php
[Sun Aug 23 10:48:06 2015] [error] [client xx.xx.xx.xx]   * smarty(array(size 0), array(size 0), array(size 0), array(size 1)) at /home/.sites/383/site150/web/eportfolio/lib/errors.php:576, referer: http://www.xxxx/eportfolio/admin/users/institutions.php
[Sun Aug 23 10:48:06 2015] [error] [client xx.xx.xx.xx]   * MaharaException->handle_exception() at /home/.sites/383/site150/web/eportfolio/lib/errors.php:467, referer: http://www.xxxx/eportfolio/admin/users/institutions.php
[Sun Aug 23 10:48:06 2015] [error] [client xx.xx.xx.xx]   * exception(object(SQLException)) at Unknown:0, referer: http://www.xxxx/eportfolio/admin/users/institutions.php
[Sun Aug 23 10:48:06 2015] [error] [client xx.xx.xx.xx] , referer: http://www.xxxx/eportfolio/admin/users/institutions.php
[Sun Aug 23 10:48:06 2015] [error] [client xx.xx.xx.xx] [WAR] 08 (lib/errors.php:585) Exception thrown by smarty call while handling exception, referer: http://www.xxxx/eportfolio/admin/users/institutions.php
[Sun Aug 23 10:48:06 2015] [error] [client xx.xx.xx.xx] Call stack (most recent first):, referer: http://www.xxxx/eportfolio/admin/users/institutions.php
[Sun Aug 23 10:48:06 2015] [error] [client xx.xx.xx.xx]   * log_message("Exception thrown by smarty call while handling exc...", 8, true, true) at /home/.sites/383/site150/web/eportfolio/lib/errors.php:97, referer: http://www.xxxx/eportfolio/admin/users/institutions.php
[Sun Aug 23 10:48:06 2015] [error] [client xx.xx.xx.xx]   * log_warn("Exception thrown by smarty call while handling exc...") at /home/.sites/383/site150/web/eportfolio/lib/errors.php:585, referer: http://www.xxxx/eportfolio/admin/users/institutions.php
[Sun Aug 23 10:48:06 2015] [error] [client xx.xx.xx.xx]   * MaharaException->handle_exception() at /home/.sites/383/site150/web/eportfolio/lib/errors.php:467, referer: http://www.xxxx/eportfolio/admin/users/institutions.php
[Sun Aug 23 10:48:06 2015] [error] [client xx.xx.xx.xx]   * exception(object(SQLException)) at Unknown:0, referer: http://www.xxxx/eportfolio/admin/users/institutions.php
[Sun Aug 23 10:48:06 2015] [error] [client xx.xx.xx.xx] , referer: http://www.xxxx/eportfolio/admin/users/institutions.php 

Hope someone can help me, to fix this.

Thanks and regards, Conrad

24 August 2015, 7:39

Hello Conrad,

Is this server from which you pasted the error messages behind a firewall / not able to receive requests from the Internet? Usually, the Persona / BrowserID auth CURL times out when it can't receive any data back from Persona.

Cheers

Kristina