• Help needed, Naviserver 4.99.22 is starting and then shuts down.

    From ROLANDO LOPEZ GTR@21:1/5 to All on Mon Nov 22 08:07:29 2021
    Hi, I wanted to ask if anyone has had any problem with Naviserver 4.99.22 suddenly restarting. I have a fresh install & compile, and I'm trying to get up and running with OpenACS 5.9.1

    Previously I got advise to check the permissions on the /httpd directory, but they're set correctly (I checked against my current working install). Looks like there is another problem, or maybe a step missing during compile... (just guessing).

    Here's the log file (last lines): [15/Nov/2021:16:24:36][8574.7fef92960900][-main:openacs591-] Notice: Sourcing files for postload...
    [15/Nov/2021:16:24:36][8574.7fef92960900][-main:openacs591-] Notice: Done. [15/Nov/2021:16:24:36][8574.7fef92960900][-main:openacs591-] Notice: Executing initialization code blocks...
    [15/Nov/2021:16:24:36][8574.7fef92960900][-main:openacs591-] Notice: QD=Postload files to load from www:
    [15/Nov/2021:16:24:36][8574.7fef92960900][-main:openacs591-] Notice: QD=Postload files to load from tcl:
    [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:ucla-dgit-ctrl-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 ucla-dgit-ctrl-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 ucla-dgit-ctrl-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

    Thanks in advance !

    --- SoupGate-Win32 v1.05
    * Origin: fsxNet Usenet Gateway (21:1/5)
  • From stefan@21:1/5 to All on Thu Nov 25 23:31:28 2021
    Hi,

    [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)

    This seems to be related to some kernel hardening feature being active in your Amazon WS environment. See a related conversation at NaviServers mailing list:

    https://www.mail-archive.com/naviserver-devel@lists.sourceforge.net/msg03887.html

    What is your exact environment?
    Can you try another image?

    HTH, Stefan

    --- SoupGate-Win32 v1.05
    * Origin: fsxNet Usenet Gateway (21:1/5)