openacs-5.4.3 on freebsd 6.3 AMD configuration problem SOLVED

Sun Nov 2 06:49:18 PST 2008

        Friday, October 31, 2008 11:24 PM

            From: 

                "Dino Vliet" <dino_vliet at yahoo.com>

            To:         

            mm at FreeBSD.org        

            Cc:         

            freebsd-questions at freebsd.org                        

Hi
I'm a new user of openacs-5.4.3 and want to try it out on my amd64
system running Freebsd 6.3 and having postgresql-server-8.2.9
installed. The port installs fine and I have set openacs_enable="YES"
in /etc/rc.conf to have it start at boot time.

The first time configuration does not give the expected results as my browser indicates this:

OpenACS Installation

    Installing the OpenACS kernel data model...

But nothing happens anymore. 

The last part of the file  /usr/local/openacs/log/error.log:

[31/Oct/2008:22:33:14][1073.5292032][-main-] Notice: nsmain: AOLserver/4.5.0 starting
[31/Oct/2008:22:33:14][1073.5292032][-main-] Notice: nsmain: security info: uid=1005, euid=1005, gid=80, egid=80
[31/Oct/2008:22:33:14][1073.5292032][-main-] Notice: nsmain: max files: FD_SETSIZE = 1024, rl_cur = 11095, rl_max = 11095
[31/Oct/2008:22:33:14][1073.5292032][-main-] Warning: nsmain: rl_max > FD_SETSIZE
[31/Oct/2008:22:33:14][1073.5292032][-main-]
Error: pidfile: failed to open pid file
'/usr/local/aolserver/log/nspid.openacs': 'Permission denied'
[31/Oct/2008:22:33:14][1073.5292032][-main-] Notice: encoding: loaded: utf-8
[31/Oct/2008:22:33:14][1073.5292032][-main-] Notice: fastpath[openacs]: mapped GET /
[31/Oct/2008:22:33:14][1073.5292032][-main-] Notice: fastpath[openacs]: mapped HEAD /
[31/Oct/2008:22:33:14][1073.5292032][-main-] Notice: fastpath[openacs]: mapped POST /
[31/Oct/2008:22:33:14][1073.5292032][-main-] Notice: adp[openacs]: mapped GET /*.adp
[31/Oct/2008:22:33:14][1073.5292032][-main-] Notice: adp[openacs]: mapped HEAD /*.adp
[31/Oct/2008:22:33:14][1073.5292032][-main-] Notice: adp[openacs]: mapped POST /*.adp
[31/Oct/2008:22:33:14][1073.5292032][-main-] Notice: modload: loading '/usr/local/aolserver/bin/nssock.so'
[31/Oct/2008:22:33:14][1073.5292032][-main-] Notice: modload: loading '/usr/local/aolserver/bin/nslog.so'
[31/Oct/2008:22:33:14][1073.5292032][-main-] Notice: nslog: opened '/usr/local/openacs/log/openacs.log'
[31/Oct/2008:22:33:14][1073.5292032][-main-] Notice: modload: loading '/usr/local/aolserver/bin/nssha1.so'
[31/Oct/2008:22:33:14][1073.5292032][-main-] Notice: modload: loading '/usr/local/aolserver/bin/nscache.so'
[31/Oct/2008:22:33:14][1073.5292032][-main-] Notice: nscache module version 1.5 server: openacs
[31/Oct/2008:22:33:14][1073.5292032][-main-] Notice: modload: loading '/usr/local/aolserver/bin/nsdb.so'
[31/Oct/2008:22:33:14][1073.5292032][-main-] Notice: modload: loading '/usr/local/aolserver/bin/nspostgres.so'
[31/Oct/2008:22:33:14][1073.5292032][-main-] Notice: PostgreSQL loaded.
[31/Oct/2008:22:33:14][1073.5292032][-main-] Notice: conf: [ns/server/openacs]enabletclpages = 0
[31/Oct/2008:22:33:20][1073.5292032][-main-] Notice: XOTcl version 1.6.1 loaded
[31/Oct/2008:22:33:20][1073.5292032][-main-] Notice: Loading OpenACS, rooted at /usr/local/openacs
[31/Oct/2008:22:33:20][1073.5292032][-main-] Notice: Sourcing /usr/local/openacs/packages/acs-bootstrap-installer/bootstrap.tcl
[31/Oct/2008:22:33:20][1073.5292032][-main-]
Notice: Bootstrap: sourcing
/usr/local/openacs/packages/acs-bootstrap-installer/tcl/00-proc-procs.tcl
[31/Oct/2008:22:33:20][1073.5292032][-main-]
Notice: Bootstrap: sourcing
/usr/local/openacs/packages/acs-bootstrap-installer/tcl/10-utilities-procs.tcl
[31/Oct/2008:22:33:20][1073.5292032][-main-]
Notice: Bootstrap: sourcing
/usr/local/openacs/packages/acs-bootstrap-installer/tcl/20-db-bootstrap-procs.tcl
[31/Oct/2008:22:33:20][1073.5292032][-main-]
Notice: Bootstrap: sourcing
/usr/local/openacs/packages/acs-bootstrap-installer/tcl/30-apm-load-procs.tcl
[31/Oct/2008:22:33:20][1073.5292032][-main-]
Notice: Bootstrap: sourcing
/usr/local/openacs/packages/acs-bootstrap-installer/tcl/40-db-query-dispatcher-procs.tcl
[31/Oct/2008:22:33:20][1073.5292032][-main-] Notice: Database API: Default database (dbn) is: 'default'
[31/Oct/2008:22:33:20][1073.5292032][-main-] Notice: Database API: Using ALL database pools for OpenACS.
[31/Oct/2008:22:33:20][1073.5292032][-main-]
Notice: Database API: The following pools are available for OpenACS:
pool2 pool3 pool1
[31/Oct/2008:22:33:20][1073.5292032][-main-] Notice: dbdrv: opening database 'postgres:localhost::openacs'
[31/Oct/2008:22:33:20][1073.5292032][-main-] Notice: Opening openacs on localhost
[31/Oct/2008:22:33:20][1073.5292032][-main-] Notice: Ns_PgOpenDb(postgres):  Openned connection to localhost::openacs.
[31/Oct/2008:22:33:20][1073.5292032][-main-] Notice: dbdrv: opening database 'postgres:localhost::openacs'
[31/Oct/2008:22:33:20][1073.5292032][-main-] Notice: Opening openacs on localhost
[31/Oct/2008:22:33:20][1073.5292032][-main-] Notice: Ns_PgOpenDb(postgres):  Openned connection to localhost::openacs.
[31/Oct/2008:22:33:20][1073.5292032][-main-] Notice: dbdrv: opening database 'postgres:localhost::openacs'
[31/Oct/2008:22:33:20][1073.5292032][-main-] Notice: Opening openacs on localhost
[31/Oct/2008:22:33:20][1073.5292032][-main-] Notice: Ns_PgOpenDb(postgres):  Openned connection to localhost::openacs.
[31/Oct/2008:22:33:20][1073.5292032][-main-] Notice: Bootstrap: Loading acs-tcl
[31/Oct/2008:22:33:20][1073.5292032][-main-] Notice: Loading acs-tcl/tcl/00-database-procs-postgresql.tcl
[31/Oct/2008:22:33:20][1073.5292032][-main-] Notice: Loading acs-tcl/tcl/00-database-procs.tcl
[31/Oct/2008:22:33:20][1073.5292032][-main-] Notice: Loading acs-tcl/tcl/10-charset-compat-procs.tcl
[31/Oct/2008:22:33:20][1073.5292032][-main-] Notice: Loading acs-tcl/tcl/30-xml-utils-procs.tcl
[31/Oct/2008:22:33:20][1073.5292032][-main-] Notice: Loading acs-tcl/tcl/acs-kernel-procs.tcl
[31/Oct/2008:22:33:20][1073.5292032][-main-] Notice: Loading acs-tcl/tcl/acs-permissions-procs.tcl
[31/Oct/2008:22:33:21][1073.5292032][-main-] Notice: Loading acs-tcl/tcl/acs-private-data-procs.tcl
[31/Oct/2008:22:33:21][1073.5292032][-main-] Notice: Loading acs-tcl/tcl/ad-functional-procs.tcl
[31/Oct/2008:22:33:21][1073.5292032][-main-] Notice: Loading acs-tcl/tcl/admin-procs.tcl
[31/Oct/2008:22:33:21][1073.5292032][-main-] Notice: Loading acs-tcl/tcl/adp-parser-procs.tcl
[31/Oct/2008:22:33:21][1073.5292032][-main-] Notice: Loading acs-tcl/tcl/aolserver-3-procs.tcl
[31/Oct/2008:22:33:21][1073.5292032][-main-] Notice: Loading acs-tcl/tcl/apm-file-procs.tcl
[31/Oct/2008:22:33:21][1073.5292032][-main-] Notice: Loading acs-tcl/tcl/apm-install-procs.tcl
[31/Oct/2008:22:33:21][1073.5292032][-main-] Notice: Loading acs-tcl/tcl/apm-procs.tcl
[31/Oct/2008:22:33:21][1073.5292032][-main-] Notice: Loading acs-tcl/tcl/apm-xml-procs.tcl
[31/Oct/2008:22:33:21][1073.5292032][-main-] Notice: Loading acs-tcl/tcl/application-data-link-procs.tcl
[31/Oct/2008:22:33:21][1073.5292032][-main-] Notice: Loading acs-tcl/tcl/application-link-procs.tcl
[31/Oct/2008:22:33:21][1073.5292032][-main-] Notice: Loading acs-tcl/tcl/callback-procs.tcl
[31/Oct/2008:22:33:21][1073.5292032][-main-] Notice: Loading acs-tcl/tcl/community-core-2-procs.tcl
[31/Oct/2008:22:33:21][1073.5292032][-main-] Notice: Loading acs-tcl/tcl/community-core-procs.tcl
[31/Oct/2008:22:33:21][1073.5292032][-main-] Notice: Loading acs-tcl/tcl/defs-procs.tcl
[31/Oct/2008:22:33:21][1073.5292032][-main-] Notice: Loading acs-tcl/tcl/deprecated-utilities-procs.tcl
[31/Oct/2008:22:33:21][1073.5292032][-main-] Notice: Loading acs-tcl/tcl/document-procs.tcl
[31/Oct/2008:22:33:21][1073.5292032][-main-] Notice: Loading acs-tcl/tcl/ds-stub-procs.tcl
[31/Oct/2008:22:33:21][1073.5292032][-main-] Notice: Loading acs-tcl/tcl/exception-procs.tcl
[31/Oct/2008:22:33:21][1073.5292032][-main-] Notice: Loading acs-tcl/tcl/form-processing-procs.tcl
[31/Oct/2008:22:33:21][1073.5292032][-main-] Notice: Loading acs-tcl/tcl/html-email-procs.tcl
[31/Oct/2008:22:33:21][1073.5292032][-main-] Notice: Loading acs-tcl/tcl/image-procs.tcl
[31/Oct/2008:22:33:21][1073.5292032][-main-] Notice: Loading acs-tcl/tcl/install-procs.tcl
[31/Oct/2008:22:33:21][1073.5292032][-main-] Notice: Loading acs-tcl/tcl/membership-rel-procs.tcl
[31/Oct/2008:22:33:21][1073.5292032][-main-] Notice: Loading acs-tcl/tcl/memoize-procs.tcl
[31/Oct/2008:22:33:21][1073.5292032][-main-] Notice: Loading acs-tcl/tcl/navigation-callback-procs.tcl
[31/Oct/2008:22:33:21][1073.5292032][-main-] Notice: Loading acs-tcl/tcl/navigation-procs.tcl
[31/Oct/2008:22:33:21][1073.5292032][-main-] Notice: Loading acs-tcl/tcl/object-procs.tcl
[31/Oct/2008:22:33:21][1073.5292032][-main-] Notice: Loading acs-tcl/tcl/object-type-procs.tcl
[31/Oct/2008:22:33:21][1073.5292032][-main-] Notice: Loading acs-tcl/tcl/openacs-kernel-procs.tcl
[31/Oct/2008:22:33:21][1073.5292032][-main-] Notice: Loading acs-tcl/tcl/parameter-procs.tcl
[31/Oct/2008:22:33:21][1073.5292032][-main-] Notice: Loading acs-tcl/tcl/pdf-procs.tcl
[31/Oct/2008:22:33:21][1073.5292032][-main-] Notice: Loading acs-tcl/tcl/proxy-procs.tcl
[31/Oct/2008:22:33:21][1073.5292032][-main-] Notice: Loading acs-tcl/tcl/request-processor-procs.tcl
[31/Oct/2008:22:33:21][1073.5292032][-main-] Notice: Loading acs-tcl/tcl/rollout-email-procs.tcl
[31/Oct/2008:22:33:21][1073.5292032][-main-] Notice: Loading acs-tcl/tcl/security-procs.tcl
[31/Oct/2008:22:33:21][1073.5292032][-main-] Notice: Loading acs-tcl/tcl/server-cluster-procs.tcl
[31/Oct/2008:22:33:21][1073.5292032][-main-] Notice: Loading acs-tcl/tcl/set-operation-procs.tcl
[31/Oct/2008:22:33:21][1073.5292032][-main-] Notice: Loading acs-tcl/tcl/site-node-apm-integration-procs.tcl
[31/Oct/2008:22:33:21][1073.5292032][-main-] Notice: Loading acs-tcl/tcl/site-node-object-map-procs.tcl
[31/Oct/2008:22:33:21][1073.5292032][-main-] Notice: Loading acs-tcl/tcl/site-nodes-procs.tcl
[31/Oct/2008:22:33:21][1073.5292032][-main-] Notice: Loading acs-tcl/tcl/sql-statement-procs.tcl
[31/Oct/2008:22:33:21][1073.5292032][-main-] Notice: Loading acs-tcl/tcl/stack-trace-procs.tcl
[31/Oct/2008:22:33:21][1073.5292032][-main-] Notice: Loading acs-tcl/tcl/table-display-procs.tcl
[31/Oct/2008:22:33:21][1073.5292032][-main-] Notice: Loading acs-tcl/tcl/tcl-documentation-procs.tcl
[31/Oct/2008:22:33:21][1073.5292032][-main-] Notice: Loading acs-tcl/tcl/tdom-procs.tcl
[31/Oct/2008:22:33:21][1073.5292032][-main-] Notice: Loading acs-tcl/tcl/text-html-procs.tcl
[31/Oct/2008:22:33:21][1073.5292032][-main-] Notice: Loading acs-tcl/tcl/user-extensions-procs.tcl
[31/Oct/2008:22:33:21][1073.5292032][-main-] Notice: Loading acs-tcl/tcl/utilities-procs.tcl
[31/Oct/2008:22:33:21][1073.5292032][-main-] Notice: Loading acs-tcl/tcl/whos-online-procs.tcl
[31/Oct/2008:22:33:21][1073.5292032][-main-] Notice: Loading acs-tcl/tcl/widgets-procs.tcl
[31/Oct/2008:22:33:21][1073.5292032][-main-] Notice: Loading acs-tcl/tcl/xml-0-sgml-procs.tcl
[31/Oct/2008:22:33:21][1073.5292032][-main-] Notice: Loading acs-tcl/tcl/xml-1-dom-procs.tcl
[31/Oct/2008:22:33:21][1073.5292032][-main-] Notice: Loading acs-tcl/tcl/xml-2-procs.tcl
[31/Oct/2008:22:33:21][1073.5292032][-main-] Notice: Bootstrap: Installation is not complete - sourcing the installer.
[31/Oct/2008:22:33:22][1073.5292032][-main-] Notice: Sourcing files for postload...
[31/Oct/2008:22:33:22][1073.5292032][-main-] Notice: Done.
[31/Oct/2008:22:33:22][1073.5292032][-main-] Notice: Executing initialization code blocks...
[31/Oct/2008:22:33:22][1073.5292032][-main-] Notice: nsmain: AOLserver/4.5.0 running
[31/Oct/2008:22:33:22][1073.5292032][-main-] Notice: nsmain: security info: uid=1005, euid=1005, gid=80, egid=80
[31/Oct/2008:22:33:22][1073.5437440][-sched-] Notice: sched: starting
[31/Oct/2008:22:33:22][1073.5292032][-main-] Notice: driver: starting: nssock
[31/Oct/2008:22:33:22][1073.5434368][-nssock:driver-] Notice: starting
[31/Oct/2008:22:33:22][1073.5434368][-nssock:driver-] Notice: nssock: listening on 0.0.0.0:8000
[31/Oct/2008:22:34:50][1073.6005760][-conn:0-] Warning: tclfilter: install_handler return invalid result: 
[31/Oct/2008:22:36:50][1073.6005760][-conn:0-] Notice: exiting: timeout waiting for connection
[31/Oct/2008:22:37:03][1073.6006784][-conn:1-] Notice: 
[31/Oct/2008:22:37:03][1073.6006784][-conn:1-]
Error: Error sourcing
/usr/local/openacs/packages/acs-bootstrap-installer/installer/install.tcl:
[31/Oct/2008:22:39:03][1073.6006784][-conn:1-] Notice: exiting: timeout waiting for connection
[31/Oct/2008:22:52:34][1073.5292032][-main-] Notice: nsmain: AOLserver/4.5.0 stopping
[31/Oct/2008:22:52:34][1073.5292032][-main-] Notice: driver: stopping: nssock
[31/Oct/2008:22:52:34][1073.5292032][-main-] Notice: sched: shutdown pending
[31/Oct/2008:22:52:34][1073.5434368][-nssock:driver-] Notice: exiting
[31/Oct/2008:22:52:34][1073.5437440][-sched-] Notice: sched: shutdown started
[31/Oct/2008:22:52:34][1073.5437440][-sched-] Notice: sched: shutdown complete
[31/Oct/2008:22:52:34][1073.5292032][-main-] Notice: driver: stopped: nssock
[31/Oct/2008:22:52:34][1073.5438464][-shutdown-] Notice: nslog: closing '/usr/local/openacs/log/openacs.log'
[31/Oct/2008:22:52:34][1073.5292032][-main-]
Error: pidfile: failed to remove
'/usr/local/aolserver/log/nspid.openacs': 'Permission denied'
[31/Oct/2008:22:52:34][1073.5292032][-main-] Notice: nsmain: AOLserver/4.5.0 exiting
[31/Oct/2008:22:54:03][1075.5292032][-main-] Notice: nsmain: AOLserver/4.5.0 starting
[31/Oct/2008:22:54:03][1075.5292032][-main-] Notice: nsmain: security info: uid=1005, euid=1005, gid=80, egid=80
[31/Oct/2008:22:54:03][1075.5292032][-main-] Notice: nsmain: max files: FD_SETSIZE = 1024, rl_cur = 11095, rl_max = 11095
[31/Oct/2008:22:54:03][1075.5292032][-main-] Warning: nsmain: rl_max > FD_SETSIZE
[31/Oct/2008:22:54:03][1075.5292032][-main-]
Error: pidfile: failed to open pid file
'/usr/local/aolserver/log/nspid.openacs': 'Permission denied'
[31/Oct/2008:22:54:03][1075.5292032][-main-] Notice: encoding: loaded: utf-8
[31/Oct/2008:22:54:03][1075.5292032][-main-] Notice: fastpath[openacs]: mapped GET /
[31/Oct/2008:22:54:03][1075.5292032][-main-] Notice: fastpath[openacs]: mapped HEAD /
[31/Oct/2008:22:54:03][1075.5292032][-main-] Notice: fastpath[openacs]: mapped POST /
[31/Oct/2008:22:54:03][1075.5292032][-main-] Notice: adp[openacs]: mapped GET /*.adp
[31/Oct/2008:22:54:03][1075.5292032][-main-] Notice: adp[openacs]: mapped HEAD /*.adp
[31/Oct/2008:22:54:03][1075.5292032][-main-] Notice: adp[openacs]: mapped POST /*.adp
[31/Oct/2008:22:54:03][1075.5292032][-main-] Notice: modload: loading '/usr/local/aolserver/bin/nssock.so'
[31/Oct/2008:22:54:03][1075.5292032][-main-] Notice: modload: loading '/usr/local/aolserver/bin/nslog.so'
[31/Oct/2008:22:54:03][1075.5292032][-main-] Notice: nslog: opened '/usr/local/openacs/log/openacs.log'
[31/Oct/2008:22:54:03][1075.5292032][-main-] Notice: modload: loading '/usr/local/aolserver/bin/nssha1.so'
[31/Oct/2008:22:54:03][1075.5292032][-main-] Notice: modload: loading '/usr/local/aolserver/bin/nscache.so'
[31/Oct/2008:22:54:03][1075.5292032][-main-] Notice: nscache module version 1.5 server: openacs
[31/Oct/2008:22:54:03][1075.5292032][-main-] Notice: modload: loading '/usr/local/aolserver/bin/nsdb.so'
[31/Oct/2008:22:54:03][1075.5292032][-main-] Notice: modload: loading '/usr/local/aolserver/bin/nspostgres.so'
[31/Oct/2008:22:54:03][1075.5292032][-main-] Notice: PostgreSQL loaded.
[31/Oct/2008:22:54:04][1075.5292032][-main-] Notice: conf: [ns/server/openacs]enabletclpages = 0
[31/Oct/2008:22:54:11][1075.5292032][-main-] Notice: XOTcl version 1.6.1 loaded
[31/Oct/2008:22:54:11][1075.5292032][-main-] Notice: Loading OpenACS, rooted at /usr/local/openacs
[31/Oct/2008:22:54:11][1075.5292032][-main-] Notice: Sourcing /usr/local/openacs/packages/acs-bootstrap-installer/bootstrap.tcl
[31/Oct/2008:22:54:11][1075.5292032][-main-]
Notice: Bootstrap: sourcing
/usr/local/openacs/packages/acs-bootstrap-installer/tcl/00-proc-procs.tcl
[31/Oct/2008:22:54:11][1075.5292032][-main-]
Notice: Bootstrap: sourcing
/usr/local/openacs/packages/acs-bootstrap-installer/tcl/10-utilities-procs.tcl
[31/Oct/2008:22:54:11][1075.5292032][-main-]
Notice: Bootstrap: sourcing
/usr/local/openacs/packages/acs-bootstrap-installer/tcl/20-db-bootstrap-procs.tcl
[31/Oct/2008:22:54:11][1075.5292032][-main-]
Notice: Bootstrap: sourcing
/usr/local/openacs/packages/acs-bootstrap-installer/tcl/30-apm-load-procs.tcl
[31/Oct/2008:22:54:11][1075.5292032][-main-]
Notice: Bootstrap: sourcing
/usr/local/openacs/packages/acs-bootstrap-installer/tcl/40-db-query-dispatcher-procs.tcl
[31/Oct/2008:22:54:11][1075.5292032][-main-] Notice: Database API: Default database (dbn) is: 'default'
[31/Oct/2008:22:54:11][1075.5292032][-main-] Notice: Database API: Using ALL database pools for OpenACS.
[31/Oct/2008:22:54:11][1075.5292032][-main-]
Notice: Database API: The following pools are available for OpenACS:
pool2 pool3 pool1
[31/Oct/2008:22:54:11][1075.5292032][-main-] Notice: dbdrv: opening database 'postgres:localhost::openacs'
[31/Oct/2008:22:54:11][1075.5292032][-main-] Notice: Opening openacs on localhost
[31/Oct/2008:22:54:11][1075.5292032][-main-] Notice: Ns_PgOpenDb(postgres):  Openned connection to localhost::openacs.
[31/Oct/2008:22:54:11][1075.5292032][-main-] Notice: dbdrv: opening database 'postgres:localhost::openacs'
[31/Oct/2008:22:54:11][1075.5292032][-main-] Notice: Opening openacs on localhost
[31/Oct/2008:22:54:11][1075.5292032][-main-] Notice: Ns_PgOpenDb(postgres):  Openned connection to localhost::openacs.
[31/Oct/2008:22:54:11][1075.5292032][-main-] Notice: dbdrv: opening database 'postgres:localhost::openacs'
[31/Oct/2008:22:54:11][1075.5292032][-main-] Notice: Opening openacs on localhost
[31/Oct/2008:22:54:11][1075.5292032][-main-] Notice: Ns_PgOpenDb(postgres):  Openned connection to localhost::openacs.
[31/Oct/2008:22:54:12][1075.5292032][-main-] Notice: Bootstrap: Loading acs-tcl
[31/Oct/2008:22:54:12][1075.5292032][-main-] Notice: Loading acs-tcl/tcl/00-database-procs-postgresql.tcl
[31/Oct/2008:22:54:12][1075.5292032][-main-] Notice: Loading acs-tcl/tcl/00-database-procs.tcl
[31/Oct/2008:22:54:12][1075.5292032][-main-] Notice: Loading acs-tcl/tcl/10-charset-compat-procs.tcl
[31/Oct/2008:22:54:12][1075.5292032][-main-] Notice: Loading acs-tcl/tcl/30-xml-utils-procs.tcl
[31/Oct/2008:22:54:12][1075.5292032][-main-] Notice: Loading acs-tcl/tcl/acs-kernel-procs.tcl
[31/Oct/2008:22:54:12][1075.5292032][-main-] Notice: Loading acs-tcl/tcl/acs-permissions-procs.tcl
[31/Oct/2008:22:54:12][1075.5292032][-main-] Notice: Loading acs-tcl/tcl/acs-private-data-procs.tcl
[31/Oct/2008:22:54:12][1075.5292032][-main-] Notice: Loading acs-tcl/tcl/ad-functional-procs.tcl
[31/Oct/2008:22:54:12][1075.5292032][-main-] Notice: Loading acs-tcl/tcl/admin-procs.tcl
[31/Oct/2008:22:54:12][1075.5292032][-main-] Notice: Loading acs-tcl/tcl/adp-parser-procs.tcl
[31/Oct/2008:22:54:12][1075.5292032][-main-] Notice: Loading acs-tcl/tcl/aolserver-3-procs.tcl
[31/Oct/2008:22:54:12][1075.5292032][-main-] Notice: Loading acs-tcl/tcl/apm-file-procs.tcl
[31/Oct/2008:22:54:12][1075.5292032][-main-] Notice: Loading acs-tcl/tcl/apm-install-procs.tcl
[31/Oct/2008:22:54:12][1075.5292032][-main-] Notice: Loading acs-tcl/tcl/apm-procs.tcl
[31/Oct/2008:22:54:12][1075.5292032][-main-] Notice: Loading acs-tcl/tcl/apm-xml-procs.tcl
[31/Oct/2008:22:54:12][1075.5292032][-main-] Notice: Loading acs-tcl/tcl/application-data-link-procs.tcl
[31/Oct/2008:22:54:12][1075.5292032][-main-] Notice: Loading acs-tcl/tcl/application-link-procs.tcl
[31/Oct/2008:22:54:12][1075.5292032][-main-] Notice: Loading acs-tcl/tcl/callback-procs.tcl
[31/Oct/2008:22:54:12][1075.5292032][-main-] Notice: Loading acs-tcl/tcl/community-core-2-procs.tcl
[31/Oct/2008:22:54:12][1075.5292032][-main-] Notice: Loading acs-tcl/tcl/community-core-procs.tcl
[31/Oct/2008:22:54:12][1075.5292032][-main-] Notice: Loading acs-tcl/tcl/defs-procs.tcl
[31/Oct/2008:22:54:12][1075.5292032][-main-] Notice: Loading acs-tcl/tcl/deprecated-utilities-procs.tcl
[31/Oct/2008:22:54:12][1075.5292032][-main-] Notice: Loading acs-tcl/tcl/document-procs.tcl
[31/Oct/2008:22:54:12][1075.5292032][-main-] Notice: Loading acs-tcl/tcl/ds-stub-procs.tcl
[31/Oct/2008:22:54:13][1075.5292032][-main-] Notice: Loading acs-tcl/tcl/exception-procs.tcl
[31/Oct/2008:22:54:13][1075.5292032][-main-] Notice: Loading acs-tcl/tcl/form-processing-procs.tcl
[31/Oct/2008:22:54:13][1075.5292032][-main-] Notice: Loading acs-tcl/tcl/html-email-procs.tcl
[31/Oct/2008:22:54:13][1075.5292032][-main-] Notice: Loading acs-tcl/tcl/image-procs.tcl
[31/Oct/2008:22:54:13][1075.5292032][-main-] Notice: Loading acs-tcl/tcl/install-procs.tcl
[31/Oct/2008:22:54:13][1075.5292032][-main-] Notice: Loading acs-tcl/tcl/membership-rel-procs.tcl
[31/Oct/2008:22:54:13][1075.5292032][-main-] Notice: Loading acs-tcl/tcl/memoize-procs.tcl
[31/Oct/2008:22:54:13][1075.5292032][-main-] Notice: Loading acs-tcl/tcl/navigation-callback-procs.tcl
[31/Oct/2008:22:54:13][1075.5292032][-main-] Notice: Loading acs-tcl/tcl/navigation-procs.tcl
[31/Oct/2008:22:54:13][1075.5292032][-main-] Notice: Loading acs-tcl/tcl/object-procs.tcl
[31/Oct/2008:22:54:13][1075.5292032][-main-] Notice: Loading acs-tcl/tcl/object-type-procs.tcl
[31/Oct/2008:22:54:13][1075.5292032][-main-] Notice: Loading acs-tcl/tcl/openacs-kernel-procs.tcl
[31/Oct/2008:22:54:13][1075.5292032][-main-] Notice: Loading acs-tcl/tcl/parameter-procs.tcl
[31/Oct/2008:22:54:13][1075.5292032][-main-] Notice: Loading acs-tcl/tcl/pdf-procs.tcl
[31/Oct/2008:22:54:13][1075.5292032][-main-] Notice: Loading acs-tcl/tcl/proxy-procs.tcl
[31/Oct/2008:22:54:13][1075.5292032][-main-] Notice: Loading acs-tcl/tcl/request-processor-procs.tcl
[31/Oct/2008:22:54:13][1075.5292032][-main-] Notice: Loading acs-tcl/tcl/rollout-email-procs.tcl
[31/Oct/2008:22:54:13][1075.5292032][-main-] Notice: Loading acs-tcl/tcl/security-procs.tcl
[31/Oct/2008:22:54:13][1075.5292032][-main-] Notice: Loading acs-tcl/tcl/server-cluster-procs.tcl
[31/Oct/2008:22:54:13][1075.5292032][-main-] Notice: Loading acs-tcl/tcl/set-operation-procs.tcl
[31/Oct/2008:22:54:13][1075.5292032][-main-] Notice: Loading acs-tcl/tcl/site-node-apm-integration-procs.tcl
[31/Oct/2008:22:54:13][1075.5292032][-main-] Notice: Loading acs-tcl/tcl/site-node-object-map-procs.tcl
[31/Oct/2008:22:54:13][1075.5292032][-main-] Notice: Loading acs-tcl/tcl/site-nodes-procs.tcl
[31/Oct/2008:22:54:13][1075.5292032][-main-] Notice: Loading acs-tcl/tcl/sql-statement-procs.tcl
[31/Oct/2008:22:54:13][1075.5292032][-main-] Notice: Loading acs-tcl/tcl/stack-trace-procs.tcl
[31/Oct/2008:22:54:13][1075.5292032][-main-] Notice: Loading acs-tcl/tcl/table-display-procs.tcl
[31/Oct/2008:22:54:13][1075.5292032][-main-] Notice: Loading acs-tcl/tcl/tcl-documentation-procs.tcl
[31/Oct/2008:22:54:13][1075.5292032][-main-] Notice: Loading acs-tcl/tcl/tdom-procs.tcl
[31/Oct/2008:22:54:13][1075.5292032][-main-] Notice: Loading acs-tcl/tcl/text-html-procs.tcl
[31/Oct/2008:22:54:13][1075.5292032][-main-] Notice: Loading acs-tcl/tcl/user-extensions-procs.tcl
[31/Oct/2008:22:54:13][1075.5292032][-main-] Notice: Loading acs-tcl/tcl/utilities-procs.tcl
[31/Oct/2008:22:54:13][1075.5292032][-main-] Notice: Loading acs-tcl/tcl/whos-online-procs.tcl
[31/Oct/2008:22:54:13][1075.5292032][-main-] Notice: Loading acs-tcl/tcl/widgets-procs.tcl
[31/Oct/2008:22:54:13][1075.5292032][-main-] Notice: Loading acs-tcl/tcl/xml-0-sgml-procs.tcl
[31/Oct/2008:22:54:13][1075.5292032][-main-] Notice: Loading acs-tcl/tcl/xml-1-dom-procs.tcl
[31/Oct/2008:22:54:13][1075.5292032][-main-] Notice: Loading acs-tcl/tcl/xml-2-procs.tcl
[31/Oct/2008:22:54:14][1075.5292032][-main-] Notice: Bootstrap: Installation is not complete - sourcing the installer.
[31/Oct/2008:22:54:14][1075.5292032][-main-] Notice: Sourcing files for postload...
[31/Oct/2008:22:54:14][1075.5292032][-main-] Notice: Done.
[31/Oct/2008:22:54:14][1075.5292032][-main-] Notice: Executing initialization code blocks...
[31/Oct/2008:22:54:14][1075.5292032][-main-] Notice: nsmain: AOLserver/4.5.0 running
[31/Oct/2008:22:54:14][1075.5292032][-main-] Notice: nsmain: security info: uid=1005, euid=1005, gid=80, egid=80
[31/Oct/2008:22:54:14][1075.5437440][-sched-] Notice: sched: starting
[31/Oct/2008:22:54:14][1075.5292032][-main-] Notice: driver: starting: nssock
[31/Oct/2008:22:54:14][1075.5434368][-nssock:driver-] Notice: starting
[31/Oct/2008:22:54:14][1075.5434368][-nssock:driver-] Notice: nssock: listening on 0.0.0.0:8000
[31/Oct/2008:22:56:51][1075.6005760][-conn:0-] Notice: 
[31/Oct/2008:22:56:51][1075.6005760][-conn:0-]
Error: Error sourcing
/usr/local/openacs/packages/acs-bootstrap-installer/installer/install.tcl:
[31/Oct/2008:22:58:51][1075.6005760][-conn:0-] Notice: exiting: timeout waiting for connection
[31/Oct/2008:23:11:30][1075.6006784][-conn:1-] Notice: 
[31/Oct/2008:23:11:30][1075.6006784][-conn:1-]
Error: Error sourcing
/usr/local/openacs/packages/acs-bootstrap-installer/installer/install.tcl:
[31/Oct/2008:23:13:30][1075.6006784][-conn:1-] Notice: exiting: timeout waiting for connection

What's wrong here?
How can I solve it?

The last file is present on my system:

ls -l /usr/local/openacs/packages/acs-bootstrap-installer/installer/install.tcl 
-rwxrwx---  1 openacs  openacs  4455 Jan 10  2007 /usr/local/openacs/packages/acs-bootstrap-installer/installer/install.tcl

And it's contents are:

##############
#
# Get configuration parameters
#
#############

install_page_contract [install_mandatory_params] [install_optional_params]

# Default all system emails to the administrators email
foreach var_name {system_owner admin_owner host_administrator outgoing_sender new_registrations} {
    if { [empty_string_p [set $var_name]] } {
        set $var_name $email
    }
}

##############
#
# System setting validation
#
#############

if {$password ne $password_confirmation  } {
    install_return 200 "Passwords Don't Match" "
The passwords you've entered don't match. Please <a href=\"javascript:history.back()\">try again</a>.
"
    return
}

##############
#
# Install data model
#
#############

ns_write [install_header 200 ""]

if { ![install_good_data_model_p] } {
    install_do_data_model_install
} else {
    ns_write "Kernel data model already installed."
    # If kernel is installed it probably means this page has already been requested,
    # let's exit
    return
}

##############
#
# Install packages
#
#############

install_do_packages_install

if { $username eq "" } {
    set username $email
}

if { ![db_string user_exists {
    select count(*) from parties where email = lower(:email)
}] } {

  db_transaction {

    set user_id [ad_user_new \
                     $email \
                     $first_names \
                     $last_name \
                     $password \
                     "" \
                     "" \
                     "" \
                     "t" \
                     "approved" \
                     "" \
                     $username]
    if { !$user_id } {

    global errorInfo    
    install_return 200 "Unable to Create Administrator" "

Unable to create the site-wide administrator:

<blockquote><pre>[ns_quotehtml $errorInfo]</pre></blockquote>

Please <a href=\"javascript:history.back()\">try again</a>.

"
        return
    }

    # stub util_memoize_flush...
    rename util_memoize_flush util_memoize_flush_saved
    proc util_memoize_flush {args} {}
    permission::grant -party_id $user_id -object_id [acs_lookup_magic_object security_context_root] -privilege "admin"
    # nuke stub 
    rename util_memoize_flush {}
    rename util_memoize_flush_saved util_memoize_flush
  }
  ad_conn -set user_id $user_id
}

# Now process the application bundle if an install.xml file was found.

if { [file exists "[acs_root_dir]/install.xml"] } {
    set output [apm::process_install_xml "/install.xml" {}]
    ns_write "<p>[join $output "</p><p>"]</p>"
}

##############
#
# Load message catalogs
#
#############

# Doing this before restart so that keys are available in init files on startup
ns_write "<p>Loading message catalogs..."
lang::catalog::import -initialize
ns_write "  <p>Done.<p>"

##############
#
# Secret tokens
#
#############

ns_write "<p>Generating secret tokens..."
populate_secret_tokens_db
ns_write "  <p>Done.<p>"

##############
#
# System settings
#
#############

set kernel_id [db_string acs_kernel_id_get {
    select package_id from apm_packages
    where package_key = 'acs-kernel'
}]

foreach { var param } {
    system_url SystemURL
    system_name SystemName
    publisher_name PublisherName
    system_owner SystemOwner
    admin_owner AdminOwner
    host_administrator HostAdministrator
    outgoing_sender OutgoingSender
} {
    ad_parameter -set [set $var] -package_id $kernel_id $param
}

# set the Main Site RestrictToSSL parameter

set main_site_id [subsite::main_site_id]

ad_parameter -set "acs-admin/*" -package_id $main_site_id RestrictToSSL
ad_parameter -set $new_registrations -package_id $main_site_id NewRegistrationEmailAddress

# We're done - kill the server (will restart if server is setup properly)
ad_schedule_proc -thread t -once t 1 ns_shutdown

set post_installation_message \
    [parameter::get_from_package_key -package_key acs-bootstrap-installer \
     -parameter post_installation_message \
     -default ""]

ns_write "<b>Installation finished</b>

<p> The server has been shut down. Normally, it should come back up by itself after a minute or so. </p>

<p> If not, please check your server error log, or contact your system administrator. </p>"

if { $post_installation_message ne "" } {
    ns_write $post_installation_message
} else {
    ns_write "
<p>
When the server is back up you can visit <a
href=\"/acs-admin/\">the site-wide administration pages</a>
</p>"
}

Thanks in advanced
Dino

ns_write [install_footer]

*************************************** end of original message *******************

Hi,
This was solved by adding the path to the psql binary into my openacs configuration file:

 ns_param   pgbin          /usr/local/bin/

My openacs-config.tcl now has this:

ns_section "ns/db/drivers"
if { $database eq "oracle" } {
    ns_param   ora8           ${bindir}/ora8.so
} else {
    ns_param   postgres       ${bindir}/nspostgres.so  ;# Load PostgreSQL driver
    ns_param   pgbin          /usr/local/bin/
}

Thanks.
Dino