Forum OpenACS Q&A: Naviserver 4.99.22 suddenly stops and tries to restart

Collapse
1: Naviserver 4.99.22 suddenly stops and tries to restart
Posted by Rolando Lopez on 11/20/21 04:33 PM
Hi everybody, I'm having a problem with my install of Naviserver 4.99.22, it starts, but suddenly stops and tries to restart. Has anyone had this error, or can guide me to troubleshoot it ? Thanks !! These are the last lines of the error log:
(I did a fresh install & compile of NS, no critical errors during installation).

[15/Nov/2021:16:24:36][8574.7fef92960900][-main:openacs591-] Notice: sharedlib /web/dev/openacs591/httpd/tcl/nslog is not a readable directory (ignored)
[15/Nov/2021:16:24:36][8574.7fef92960900][-main:openacs591-] Notice: privatelib /web/dev/openacs591/tcl/nslog is not a readable directory (ignored)
[15/Nov/2021:16:24:36][8574.7fef92960900][-main:openacs591-] Notice: privatelib /web/dev/openacs591/tcl/nsdb is not a readable directory (ignored)
[15/Nov/2021:16:24:36][8574.7fef92960900][-main:openacs591-] Notice: sharedlib /web/dev/openacs591/httpd/tcl/nscache is not a readable directory (ignored)
[15/Nov/2021:16:24:36][8574.7fef92960900][-main:openacs591-] Notice: privatelib /web/dev/openacs591/tcl/nscache is not a readable directory (ignored)
[15/Nov/2021:16:24:36][8574.7fef92960900][-main:openacs591-] Notice: sharedlib /web/dev/openacs591/httpd/tcl/nsssl is not a readable directory (ignored)
[15/Nov/2021:16:24:36][8574.7fef92960900][-main:openacs591-] Notice: privatelib /web/dev/openacs591/tcl/nsssl is not a readable directory (ignored)
[15/Nov/2021:16:24:36][8574.7fef92960900][-main:openacs591-] Notice: sharedlib /web/dev/openacs591/httpd/tcl/nssock is not a readable directory (ignored)
[15/Nov/2021:16:24:36][8574.7fef92960900][-main:openacs591-] Notice: privatelib /web/dev/openacs591/tcl/nssock is not a readable directory (ignored)
[15/Nov/2021:16:24:36][8574.7fef92960900][-main:openacs591-] Notice: update interpreter openacs591 to epoch 1, trace deallocate, time 0.070940 secs concurrent 1
[15/Nov/2021:16:24:36][8574.7fef92960900][-main:openacs591-] Notice: update interpreter openacs591 to epoch 1, trace none, time 0.142450 secs concurrent 1
[15/Nov/2021:16:24:36][8574.7fef77fff700][-driver:nssock:0-] Notice: starting
[15/Nov/2021:16:24:36][8574.7fef77fff700][-driver:nssock:0-] Notice: nssock:0: listening on [10.34.203.236]:80
[15/Nov/2021:16:24:36][8574.7fef77fff700][-driver:nssock:0-] Notice: driver: accepting connections
[15/Nov/2021:16:24:36][8574.7fef777fe700][-driver:nsssl:0-] Notice: starting
[15/Nov/2021:16:24:36][8574.7fef777fe700][-driver:nsssl:0-] Notice: nsssl:0: listening on [10.34.203.236]:443
[15/Nov/2021:16:24:36][8574.7fef777fe700][-driver:nsssl:0-] Notice: driver: accepting connections
[15/Nov/2021:16:24:36][8574.7fef92960900][-main:openacs591-] Notice: nsmain: NaviServer/4.99.22 (tar-4.99.22) running
[15/Nov/2021:16:24:36][8574.7fef92960900][-main:openacs591-] Notice: nsmain: security info: uid=501, euid=501, gid=511, egid=511
[15/Nov/2021:16:24:36][8574.7fef92960900][-main:openacs591-] Fatal: nsmain: can't communicate with parent process, nwrite -1, error: Broken pipe (parent process was probably killed)
[15/Nov/2021:16:34:33][8574.7fef87e6c700][-sched-] Notice: nsdb: closing idle handle in pool 'oacs1'
[15/Nov/2021:16:34:33][8574.7fef87e6c700][-sched-] Notice: nsdb: closing idle handle in pool 'oacs0'
[15/Nov/2021:16:34:33][8574.7fef87e6c700][-sched-] Notice: nsdb: closing idle handle in pool 'oacs2'
[15/Nov/2021:17:37:30][8574.7fef8594a700][-conn:openacs591:default:1:3-] Notice: dbdrv: opening database 'postgres:xxxx-xxxx-xxxx-aurora-pg-1-resource-auroracluster-1avxr94trsgp8.cluster-cs4ytygqjbgh.us-west-2.rds.amazonaws.com:5432:openacs591'
[15/Nov/2021:17:37:30][8574.7fef8594a700][-conn:openacs591:default:1:3-] Notice: nsdbpg(oacs2): opening connection to db openacs591 on xxxx-xxxx-xxxx-aurora-pg-1-resource-auroracluster-1avxr94trsgp8.cluster-cs4ytygqjbgh.us-west-2.rds.amazonaws.com, port 5432
[15/Nov/2021:17:37:30][8574.7fef8594a700][-conn:openacs591:default:1:3-] Notice: nsdbpg(oacs2): opened connection to xxxx-xxxx-xxxx-aurora-pg-1-resource-auroracluster-1avxr94trsgp8.cluster-cs4ytygqjbgh.us-west-2.rds.amazonaws.com:5432:openacs591.
[15/Nov/2021:17:41:15][8574.7fef92960900][-main:openacs591-] Notice: nsmain: NaviServer/4.99.22 (tar-4.99.22) stopping
[15/Nov/2021:17:41:15][8574.7fef92960900][-main:openacs591-] Notice: [driver:nssock:0]: stopping
[15/Nov/2021:17:41:15][8574.7fef92960900][-main:openacs591-] Notice: [driver:nsssl:0]: stopping
[15/Nov/2021:17:41:15][8574.7fef92960900][-main:openacs591-] Notice: server [openacs591]: stopping
[15/Nov/2021:17:41:15][8574.7fef8694c700][-conn:openacs591:default:0:0-] Notice: exiting: shutdown pending
[15/Nov/2021:17:41:15][8574.7fef73fff700][-driver:nssock:0-] Notice: exiting
[15/Nov/2021:17:41:15][8574.7fef737fe700][-driver:nsssl:0-] Notice: exiting
[15/Nov/2021:17:41:15][8574.7fef84948700][-conn:openacs591:default:4:0-] Notice: exiting: shutdown pending
[15/Nov/2021:17:41:15][8574.7fef8614b700][-conn:openacs591:default:2:0-] Notice: exiting: shutdown pending
[15/Nov/2021:17:41:15][8574.7fef8594a700][-conn:openacs591:default:1:5-] Notice: exiting: shutdown pending
[15/Nov/2021:17:41:15][8574.7fef85149700][-conn:openacs591:default:3:0-] Notice: exiting: shutdown pending
[15/Nov/2021:17:41:15][8574.7fef92960900][-main:openacs591-] Notice: server [openacs591]: connection threads stopped
[15/Nov/2021:17:41:15][8574.7fef92960900][-main:openacs591-] Notice: driver: stopping writer and spooler threads
[15/Nov/2021:17:41:15][8574.7fef92960900][-main:openacs591-] Notice: sched: shutdown pending
[15/Nov/2021:17:41:15][8574.7fef87e6c700][-sched-] Notice: sched: shutdown started
[15/Nov/2021:17:41:15][8574.7fef87e6c700][-sched-] Notice: sched: shutdown complete
[15/Nov/2021:17:41:15][8574.7fef92960900][-main:openacs591-] Notice: [driver:nssock:0]: stopped
[15/Nov/2021:17:41:15][8574.7fef92960900][-main:openacs591-] Notice: [driver:nsssl:0]: stopped
[15/Nov/2021:17:41:15][8574.7fef85149700][-shutdown-] Notice: nslog: closed '/web/dev/openacs591/log/access/ip-10-34-203-16.openacs591-access.log'
[15/Nov/2021:17:41:15][8574.7fef92960900][-main:openacs591-] Notice: dbi[test:nscache]: handlegets 0 handlemisses 0 handleopens 0 handlefailures 0 queries 0 agedcloses 0 idlecloses 0 oppscloses 0 bounces 0
[15/Nov/2021:17:41:15][8574.7fef92960900][-main:openacs591-] Notice: nsmain: NaviServer/4.99.22 (tar-4.99.22) exiting

Collapse
2: Re: Naviserver 4.99.22 suddenly stops and tries to restart (response to 1)
Posted by Iuri Sampaio on 11/21/21 05:49 PM
Hi there,
It seems httpd/ directory has not read permission assigned.

Documentation is available at:

Automated scripts: https://openacs.org/xowiki/naviserver-openacs

Manual installation (that one has detailed information about users, permissions and etc.
https://openacs.org/xowiki/aolserver-install

hope it helps.

Best wishes,
I

Collapse
3: Re: Naviserver 4.99.22 suddenly stops and tries to restart (response to 1)
Posted by Rolando Lopez on 11/22/21 12:18 AM
Thank you very much Iuri, I'll go through the docs, I'll let you know when I got it fixed.
Best,
R
Collapse
4: Re: Naviserver 4.99.22 suddenly stops and tries to restart (response to 1)
Posted by Rolando Lopez on 11/22/21 04:53 PM
Hi, I checked permissions on the httpd directory (compared to a previous install of OpenACS) and they're set correctly, looks like there is another issue, maybe in the compile process. I'm still trying to figure out. Any help is welcome ! Thanks !
Collapse
5: Re: Naviserver 4.99.22 suddenly stops and tries to restart (response to 1)
Posted by Gustaf Neumann on 11/22/21 05:27 PM
checked permissions on the httpd directory
in normal NaviServer installation, there is no httpd directory involved. A typical permission setup involves e.g. "chown -R nsadmin:nsadmin /usr/local/ns/logs", but this certainly depends on your setup, since one can change almost everything via configuration files in the server, including directories.

Have you tried an install with "install-ns.sh" and "install-oacs.sh"? This sets up everything needed (including systemd service files) and makes it easier for you figure out what might be wrong.

The usual question in such situations:
- what is the OS you are using
- how do you start the server (command line options)
- in which respect does the used configuration file differ from the recommended one (e.g. included in the naviserver tar file)

Collapse
6: Re: Naviserver 4.99.22 suddenly stops and tries to restart (response to 1)
Posted by Iuri Sampaio on 11/23/21 01:53 AM
Rolando,
You should try installation scripts as per Gustaf's recommendation: "install-ns.sh" and "install-oacs.sh"

Plus, you may even edit those scripts, in case your OS is different (Ubuntu and Debian are the standards) , or if you'd like to customize your on automated installation, such as "httpd" directory and so on.

Do not hesitate to ask if you get stuck somehow.

Best wishes,
I