Forum OpenACS Q&A: Errors are seen by everybody

Collapse
Posted by unuser u on
Coming from
https://openacs.org/forums/message-view?message_id=2893399

Aolserver 4.5.1
Tcl 8.5
Openacs 5.5.0

I have restarted the server and i have tried a lot of parameters. For example i tried the email send on error, It works, However the errors still can be seen by everybody.

The parameter is 'RestrictErrorsToAdminsP' in kernel config set to '1'

Collapse
Posted by unuser u on
I have seen other problem,

I cant set a error page, always appears the

'The requested URL cannot be accessed due to a system error on
this server. '

in the error.log i can see how the server is trying to reach the file i set in the config, however it fails so always shows the message above.

Someone has info about these issues? In the bug tracker they dont appear so i dont know why these problems.I think i have installed correctly the system...

Collapse
Posted by Brian Fenton on
Hi,

Maybe something is going wrong during startup. Are there are any errors in the AOLserver error log during start-up?

cheers
Brian

Collapse
Posted by unuser u on
Hi

In the log you can see the aolserver+openacs load process and the failed attempt trying to get the error file. I have edited some relevant things.

Whatever name the 404 has, it fails. It is not a production server, but it will be. If i cant find the error i will have to user 5.3.2.

[28/Aug/2009:08:07:56][5605.3083388592][-main-] Notice: nsmain: AOLserver/4.5.1 starting
[28/Aug/2009:08:07:56][5605.3083388592][-main-] Notice: nsmain: security info: uid=xxxx, euid=xxxx, gid=xxxx, egid=xxxx
[28/Aug/2009:08:07:56][5605.3083388592][-main-] Notice: nsmain: max files: FD_SETSIZE = 1024, rl_cur = 1024, rl_max = 1024
[28/Aug/2009:08:07:56][5605.3083388592][-main-] Notice: encoding: loaded: utf-8
[28/Aug/2009:08:07:56][5605.3083388592][-main-] Notice: fastpath[nmysite]: mapped GET /
[28/Aug/2009:08:07:56][5605.3083388592][-main-] Notice: fastpath[nmysite]: mapped HEAD /
[28/Aug/2009:08:07:56][5605.3083388592][-main-] Notice: fastpath[nmysite]: mapped POST /
[28/Aug/2009:08:07:56][5605.3083388592][-main-] Notice: adp[nmysite]: mapped GET /*.adp
[28/Aug/2009:08:07:56][5605.3083388592][-main-] Notice: adp[nmysite]: mapped HEAD /*.adp
[28/Aug/2009:08:07:56][5605.3083388592][-main-] Notice: adp[nmysite]: mapped POST /*.adp
[28/Aug/2009:08:07:56][5605.3083388592][-main-] Notice: modload: loading '/usr/lib/aolserver4/bin/nssock.so'
[28/Aug/2009:08:07:56][5605.3083388592][-main-] Notice: modload: loading '/usr/lib/aolserver4/bin/nslog.so'
[28/Aug/2009:08:07:56][5605.3083388592][-main-] Notice: nslog: opened '/var/lib/aolserver/nmysite/log/nmysite.log'
[28/Aug/2009:08:07:56][5605.3083388592][-main-] Notice: modload: loading '/usr/lib/aolserver4/bin/nssha1.so'
[28/Aug/2009:08:07:56][5605.3083388592][-main-] Notice: modload: loading '/usr/lib/aolserver4/bin/nsdb.so'
[28/Aug/2009:08:07:56][5605.3083388592][-main-] Notice: modload: loading '/usr/lib/aolserver4/bin/nspostgres.so'
[28/Aug/2009:08:07:56][5605.3083388592][-main-] Notice: PostgreSQL loaded.
[28/Aug/2009:08:07:56][5605.3083388592][-main-] Notice: modload: loading '/var/lib/aolserver/nmysite/test/libthread2.6.5-aolserver.so'
[28/Aug/2009:08:07:56][5605.3083388592][-main-] Notice: conf: [ns/server/nmysite]enabletclpages = 0
[28/Aug/2009:08:07:56][5605.3083388592][-main-] Notice: default thread pool: minthreads 5 maxthreads 100 idle 0 current 0 maxconns 150 queued 0 timeout 150 spread 20
[28/Aug/2009:08:07:56][5605.3083388592][-main-] Notice: Loading OpenACS, rooted at /var/lib/aolserver/nmysite
[28/Aug/2009:08:07:56][5605.3083388592][-main-] Notice: Sourcing /var/lib/aolserver/nmysite/packages/acs-bootstrap-installer/bootstrap.tcl
[28/Aug/2009:08:07:57][5605.3083388592][-main-] Notice: Bootstrap: sourcing /var/lib/aolserver/nmysite/packages/acs-bootstrap-installer/tcl/00-proc-procs.tcl
[28/Aug/2009:08:07:57][5605.3083388592][-main-] Notice: Bootstrap: sourcing /var/lib/aolserver/nmysite/packages/acs-bootstrap-installer/tcl/10-utilities-procs.tcl
[28/Aug/2009:08:07:57][5605.3083388592][-main-] Notice: Bootstrap: sourcing /var/lib/aolserver/nmysite/packages/acs-bootstrap-installer/tcl/20-db-bootstrap-procs.tcl
[28/Aug/2009:08:07:57][5605.3083388592][-main-] Notice: Bootstrap: sourcing /var/lib/aolserver/nmysite/packages/acs-bootstrap-installer/tcl/30-apm-load-procs.tcl
[28/Aug/2009:08:07:57][5605.3083388592][-main-] Notice: Bootstrap: sourcing /var/lib/aolserver/nmysite/packages/acs-bootstrap-installer/tcl/40-db-query-dispatcher-procs.tcl
[28/Aug/2009:08:07:57][5605.3083388592][-main-] Notice: Database API: For database 'main', the following pools are available: pool1 pool2 pool3
[28/Aug/2009:08:07:57][5605.3083388592][-main-] Notice: Database API: For database 'dbpool2', the following pools are available: db1 db2 db3
[28/Aug/2009:08:07:57][5605.3083388592][-main-] Notice: Database API: For database 'dbpool3', the following pools are available: db4 db5 db6
[28/Aug/2009:08:07:57][5605.3083388592][-main-] Notice: Database API: Default database (dbn) is: 'main'
[28/Aug/2009:08:07:57][5605.3083388592][-main-] Notice: Database API: The following pools are available for OpenACS: pool1 pool2 pool3
[28/Aug/2009:08:07:57][5605.3083388592][-main-] Notice: dbdrv: opening database 'db+ip'
[28/Aug/2009:08:07:57][5605.3083388592][-main-] Notice: Opening nmysite on ip, port
[28/Aug/2009:08:07:57][5605.3083388592][-main-] Notice: Ns_PgOpenDb(postgres): Openned connection to db+ip.
[28/Aug/2009:08:07:57][5605.3083388592][-main-] Notice: dbdrv: opening database 'db+ip'
[28/Aug/2009:08:07:57][5605.3083388592][-main-] Notice: Opening nmysite on ip, port
[28/Aug/2009:08:07:57][5605.3083388592][-main-] Notice: Ns_PgOpenDb(postgres): Openned connection to db+ip.
[28/Aug/2009:08:07:57][5605.3083388592][-main-] Notice: dbdrv: opening database 'postgres:ip::nmysite'
[28/Aug/2009:08:07:57][5605.3083388592][-main-] Notice: Opening nmysite on ip, port
[28/Aug/2009:08:07:57][5605.3083388592][-main-] Notice: Ns_PgOpenDb(postgres): Openned connection to db+ip.
[28/Aug/2009:08:07:57][5605.3083388592][-main-] Notice: Bootstrap: Loading acs-tcl
[28/Aug/2009:08:07:58][5605.3083388592][-main-] Notice: Loading acs-tcl/tcl/00-database-procs-postgresql.tcl

Loading a lot of packages....

[28/Aug/2009:08:07:58][5605.3083388592][-main-] Notice: Loading acs-tcl/tcl/pools-init.tcl
[28/Aug/2009:08:07:58][5605.3083388592][-main-] Notice: Default Pool: minthreads 5 maxthreads 100 idle 0 current 0 maxconns 150 queued 0 timeout 150 spread 20
[28/Aug/2009:08:07:58][5605.3083388592][-main-] Notice: Loading acs-tcl/tcl/request-processor-init.tcl
[28/Aug/2009:08:07:58][5605.3083388592][-main-] Notice: Loading acs-tcl/tcl/rollout-email-init.tcl
[28/Aug/2009:08:07:58][5605.3083388592][-main-] Notice: Loading acs-tcl/tcl/security-init.tcl
[28/Aug/2009:08:07:58][5605.3083388592][-main-] Notice: security-init.tcl: Creating secret_tokens ns_cache...
[28/Aug/2009:08:07:58][5605.3083388592][-main-] Notice: security-init.tcl: Populating secret_tokens ns_cache...
[28/Aug/2009:08:07:58][5605.3083388592][-main-] Notice: Loading acs-tcl/tcl/site-nodes-init.tcl
[28/Aug/2009:08:07:58][5605.3083388592][-main-] Notice: Loading acs-tcl/tcl/utilities-init.tcl
[28/Aug/2009:08:07:58][5605.3083388592][-main-] Notice: Loading acs-tcl/tcl/whos-online-init.tcl
[28/Aug/2009:08:07:58][5605.3083388592][-main-] Notice: Loading acs-automated-testing specially so other packages can define tests...
[28/Aug/2009:08:07:58][5605.3083388592][-main-] Notice: Loading acs-automated-testing/tcl/aa-test-procs.tcl
[28/Aug/2009:08:07:58][5605.3083388592][-main-] Notice: Loading acs-automated-testing/tcl/example-procs.tcl
[28/Aug/2009:08:07:58][5605.3083388592][-main-] Notice: Loading acs-automated-testing/tcl/filter-procs.tcl
[28/Aug/2009:08:07:58][5605.3083388592][-main-] Notice: Loading acs-automated-testing/tcl/tclwebtest-procs.tcl
[28/Aug/2009:08:08:00][5605.3083388592][-main-] Notice: Loading packages/acs-bootstrap-installer/tcl/00-proc-procs.tcl...

loading a lot of packages....

[28/Aug/2009:08:08:04][5605.3083388592][-main-] Notice: Loading packages/search/tcl/search-init.tcl...
[28/Aug/2009:08:08:04][5605.3083388592][-main-] Notice: Loaded packages/search/tcl/search-init.tcl.
[28/Aug/2009:08:08:04][5605.3083388592][-main-] Notice: Loading packages/acs-content-repository/tcl/acs-content-repository-init.tcl...
[28/Aug/2009:08:08:04][5605.3083388592][-main-] Notice: cr_scan_mime_types: inserting MIME TYPE - .pcd maps to image/x-photo-cd
[28/Aug/2009:08:08:04][5605.3083388592][-main-] Notice: cr_scan_mime_types: inserting MIME TYPE - .prc maps to application/x-pilot
[28/Aug/2009:08:08:04][5605.3083388592][-main-] Notice: cr_scan_mime_types: inserting MIME TYPE - .xls maps to application/vnd.ms-excel
[28/Aug/2009:08:08:04][5605.3083388592][-main-] Notice: cr_scan_mime_types: inserting MIME TYPE - .doc maps to application/vnd.ms-word
[28/Aug/2009:08:08:04][5605.3083388592][-main-] Notice: Loaded packages/acs-content-repository/tcl/acs-content-repository-init.tcl.
[28/Aug/2009:08:08:04][5605.3083388592][-main-] Notice: Loading packages/acs-messaging/tcl/acs-messaging-init.tcl...
[28/Aug/2009:08:08:04][5605.3083388592][-main-] Notice: Loaded packages/acs-messaging/tcl/acs-messaging-init.tcl.
[28/Aug/2009:08:08:04][5605.3083388592][-main-] Notice: Loading packages/acs-subsite/tcl/acs-subsite-init.tcl...
[28/Aug/2009:08:08:04][5605.3083388592][-main-] Notice: Loaded packages/acs-subsite/tcl/acs-subsite-init.tcl.
[28/Aug/2009:08:08:04][5605.3083388592][-main-] Notice: Loading packages/acs-subsite/tcl/approval-expiration-init.tcl...
[28/Aug/2009:08:08:04][5605.3083388592][-main-] Notice: Loaded packages/acs-subsite/tcl/approval-expiration-init.tcl.
[28/Aug/2009:08:08:04][5605.3083388592][-main-] Notice: Loading packages/acs-templating/tcl/acs-integration-init.tcl...
[28/Aug/2009:08:08:04][5605.3083388592][-main-] Notice: Loaded packages/acs-templating/tcl/acs-integration-init.tcl.
[28/Aug/2009:08:08:04][5605.3083388592][-main-] Notice: Loading packages/acs-templating/tcl/acs-templating-init.tcl...
[28/Aug/2009:08:08:04][5605.3083388592][-main-] Notice: Loaded packages/acs-templating/tcl/acs-templating-init.tcl.
[28/Aug/2009:08:08:04][5605.3083388592][-main-] Notice: Loading packages/acs-templating/tcl/date-init.tcl...
[28/Aug/2009:08:08:04][5605.3083388592][-main-] Notice: Loaded packages/acs-templating/tcl/date-init.tcl.
[28/Aug/2009:08:08:04][5605.3083388592][-main-] Notice: Loading packages/acs-templating/tcl/spellcheck-init.tcl...
[28/Aug/2009:08:08:04][5605.3083388592][-main-] Notice: Loaded packages/acs-templating/tcl/spellcheck-init.tcl.
[28/Aug/2009:08:08:04][5605.3083388592][-main-] Notice: Loading packages/acs-templating/tcl/tag-init.tcl...
[28/Aug/2009:08:08:04][5605.3083388592][-main-] Notice: Loaded packages/acs-templating/tcl/tag-init.tcl.
[28/Aug/2009:08:08:04][5605.3083388592][-main-] Notice: Loading packages/acs-templating/tcl/template-init.tcl...
[28/Aug/2009:08:08:04][5605.3083388592][-main-] Notice: Loaded packages/acs-templating/tcl/template-init.tcl.
[28/Aug/2009:08:08:05][5605.3083388592][-main-] Notice: Bootstrap: Done loading OpenACS.
[28/Aug/2009:08:08:05][5605.3083388592][-main-] Notice: Sourcing files for postload...
[28/Aug/2009:08:08:05][5605.3083388592][-main-] Notice: Done.
[28/Aug/2009:08:08:05][5605.3083388592][-main-] Notice: Executing initialization code blocks...
[28/Aug/2009:08:08:05][5605.3083388592][-main-] Notice: Executing initialization code block filters_register in /var/lib/aolserver/nmysite/packages/acs-tcl/tcl/request-processor-init.tcl
[28/Aug/2009:08:08:05][5605.3083388592][-main-] Notice: Executing initialization code block procs_register in /var/lib/aolserver/nmysite/packages/acs-tcl/tcl/request-processor-init.tcl
[28/Aug/2009:08:08:05][5605.3064634256][-default:0-] Notice: random: generating 1 seed
[28/Aug/2009:08:08:05][5605.3083388592][-main-] Notice: nsmain: AOLserver/4.5.1 running
[28/Aug/2009:08:08:05][5605.3083388592][-main-] Notice: nsmain: security info: uid=xxxx, euid=xxxx, gid=xxxx, egid=xxxx
[28/Aug/2009:08:08:05][5605.3083388592][-main-] Notice: driver: starting: nssock
[28/Aug/2009:08:08:05][5605.3072940944][-sched-] Notice: sched: starting
[28/Aug/2009:08:08:05][5605.3059350416][-nssock:driver-] Notice: starting
[28/Aug/2009:08:08:05][5605.3059350416][-nssock:driver-] Notice: nssock: listening on someip
[28/Aug/2009:08:08:05][5605.3083388592][-main-] Warning: prebind: closed unused: someip = 5
[28/Aug/2009:08:09:04][5605.3063577488][-sched:idle0-] Notice: starting
[28/Aug/2009:08:20:56][5605.3065691024][-default:1-] Notice: -- creating per thread sequence table
[28/Aug/2009:08:20:56][5605.3061463952][-default:3-] Notice: dbdrv: opening database 'somedbonsomeip'
[28/Aug/2009:08:20:56][5605.3061463952][-default:3-] Notice: Opening nmysite on someip, port
[28/Aug/2009:08:20:56][5605.3061463952][-default:3-] Notice: Ns_PgOpenDb(postgres): Openned connection to somedbonsomeip.
[28/Aug/2009:08:21:00][5605.3064634256][-default:0-] Error: return: failed to redirect 'GET /global/nfound.html': exceeded recursion limit of 3
[28/Aug/2009:08:21:13][5605.3061463952][-default:3-] Notice: dbdrv: opening database 'somedbonsomeip'
[28/Aug/2009:08:21:13][5605.3061463952][-default:3-] Notice: Opening db on someip, port port
[28/Aug/2009:08:21:13][5605.3061463952][-default:3-] Notice: Ns_PgOpenDb(postgres): Openned connection to somedbsomeip
[28/Aug/2009:08:21:13][5605.3061463952][-default:3-] Notice: dbdrv: opening database 'somedbonsomeip'
[28/Aug/2009:08:21:13][5605.3061463952][-default:3-] Notice: Opening db3 on someip, port port
[28/Aug/2009:08:21:14][5605.3061463952][-default:3-] Notice: Ns_PgOpenDb(postgres): Openned connection to somedbonsomeip
[28/Aug/2009:08:26:45][5605.3064634256][-default:0-] Error: return: failed to redirect 'GET /global/nfound.html': exceeded recursion limit of 3
[28/Aug/2009:08:26:47][5605.3061463952][-default:3-] Error: return: failed to redirect 'GET /global/nfound.html': exceeded recursion limit of 3
[28/Aug/2009:08:27:09][5605.3065691024][-default:1-] Error: return: failed to redirect 'GET /global/nfound.html': exceeded recursion limit of 3
[28/Aug/2009:10:12:53][5605.3060407184][-default:4-] Notice: -- creating per thread sequence table
[28/Aug/2009:10:13:07][5605.3064634256][-default:0-] Notice: -- creating per thread sequence table

Collapse
Posted by Brian Fenton on
Does /global/nfound.html exist and has it read permission?

I'm not sure I fully understand what your problem is - what error are people seeing? There probably shouldn't be errors coming up on a fresh install, unless you missed something during the installation.

Brian

Collapse
Posted by unuser u on
Yes, the file exists and has read permission.

The problems are two:

1.- 'RestrictErrorsToAdminsP' in kernel config set to '1'

Problem: When a query fails, whatever the reason, all the users can see the debug error. For example: 'db_0or1row' failed to... etc.

In the same server i have some other openacs working fine. When a error appears only the admins can see it. The normal users cant see it. They receive a generic programming error 'we are investigating it', etc .

2.- I think it can be related with the first one: When i set a error page, the system cant reach it, whatever the name i set, whatever the route to the file. Openacs always shows the system error problem.

Collapse
Posted by Brian Fenton on
Can you open that error page yourself in your own browser?
Collapse
Posted by unuser u on
Yes i can:

[31/Aug/2009:11:42:33 +0200] "GET /global/nfound.html HTTP/1.1" 200 15

Collapse
Posted by Torben Brosten on
Are you using the config.tcl that came with 5.5? It's significantly different than 5.3's config.tcl[1]. I'm not sure if it has relevance here with nscache changes.. but just to rule it out..
Collapse
Posted by unuser u on
Hi

I am using the config.tcl from the 5.5.0 (downloaded from the link in the frontpage) tarball with some pools added (but i saw the errors before i added the pools) and the path to the libthread customized.

Anyway i will check for differences the config.tcl

Thanks both for your help

Collapse
Posted by Brian Fenton on
By the way, which parameter did you set to /global/nfound.html? Was it NotFoundResponse or 404? You need to set both I think.

Brian

Collapse
Posted by unuser u on
Hi,

-The config.tcl is the one from 5.5.0
-The 404 parameters are the next one:

ns_param NotFoundResponse "global/nfound.html"
ns_param 404 "global/nfound.html"

- Thinking about it, maybe the 'hostname' parameter must be a real thing . At this moment i have a domain name in hostname. I can't test it now but i will :(

Thanks

Collapse
Posted by Brian Fenton on
Is "global/nfound.html" the same thing as "/global/nfound.html" (with the leading "/")?
Collapse
Posted by unuser u on
Yes it is, sorry for the delay

I have just installed again openacs 5.5.0 with the same problems as result.

Collapse
Posted by Torben Brosten on
If you have multiple aolservers installed, maybe the newer installation is corrupt by borrowing some of the code from the older installation. I had something like that happen with an install, and aolserver broke in an odd way kind of like this.
Collapse
Posted by unuser u on
At the start i thought like you say, but, actually, in the same server i have some other openacs transferred waiting for upgrade . They work fine. The errors are seen only by the administrator, and the not found pages are shown in a normal way.

In this moment i think maybe it is related with tcl 8.5 and the installation process. But i need investigate it.

sorry for the delay replying to you - something Dave Bauer mentioned in another thread (https://openacs.org/forums/message-view?message_id=2961711) got me thinking. What does the access log say?
Hi

wow! it looks like my problem. Here the logs a bit edited

Get Request with a simple firefox

source get ip - - [14/Sep/2009:16:15:02 +0200] "GET /ffdgsdfgsdfg HTTP/1.1" 500 545 "" "Mozilla/5.0 (Windows; U; Windows NT 5.1; es-AR; rv:1.9.1.3) Gecko/20090824 Firefox/3.5.3 (.NET CLR 3.5.30729)" 0.018631 "ad_session_id=randomletterandnumbers"

The server sends a 500 error as you see because...

[14/Sep/2009:16:15:02][20870.2996648848][-default:45-] Error: return: failed to redirect 'GET /tesst.html': exceeded recursion limit of 3

The weird file name is one of my numerous tests.

so project-manager... I have to check it in my installations.

Thanks for the new hint

Collapse
Posted by unuser u on
Hi

Finally i programmed some code to handle the error via error numbers and so. I could not find the true problem so i did it mannualy :(

It was a little change in the show-error code if anyone has this problem too. The errors are now stored in the database

Thanks to all for your previous responses.

The problem related with the 404 page still remains.