Forum OpenACS Q&A: Server startup failed : Error during bootstrapping
The following is the log file:
[23/Jul/2002:20:02:16][3410.1024][-main-] Notice: nsmain:
AOLserver/3.3.1+ad13 exiting
[23/Jul/2002:20:02:20][3454.1024][-main-] Notice: nsmain:
AOLserver/3.3.1+ad13 starting
[23/Jul/2002:20:02:20][3454.1024][-main-] Notice: nsmain: security
info: uid=504, euid=504, gid=503, egid=503
[23/Jul/2002:20:02:20][3454.1024][-main-] Notice: nsmain: max files:
FD_SETSIZE = 1024, rl_cur = 1024, rl_max = 1024
[23/Jul/2002:20:02:20][3454.1024][-main-] Notice: return: redirecting
'404' to 'global/file-not-found.html'
[23/Jul/2002:20:02:20][3454.1024][-main-] Notice: return: redirecting
'403' to 'global/forbidden.html'
[23/Jul/2002:20:02:20][3454.1024][-main-] Error: dbdrv: no such driver
'ora8'
[23/Jul/2002:20:02:20][3454.1024][-main-] Error: dbinit: no such
default pool 'pool1'
[23/Jul/2002:20:02:20][3454.1024][-main-] Debug: dbinit: no configured
pools
[23/Jul/2002:20:02:20][3454.1024][-main-] Notice: adp: mapped /*.adp
[23/Jul/2002:20:02:20][3454.1024][-main-] Notice: modload: loading
'/usr/local/aolserver/bin/nssock.so'
[23/Jul/2002:20:02:20][3454.1024][-main-] Debug: modload: initializing
module 'nssock'
[23/Jul/2002:20:02:20][3454.1024][-main-] Notice: modload: loading
'/usr/local/aolserver/bin/nslog.so'
[23/Jul/2002:20:02:20][3454.1024][-main-] Notice: nslog: opened
'/usr/local/aolserver/log/birdnote.log'
[23/Jul/2002:20:02:20][3454.1024][-main-] Debug: modload: initializing
module 'nslog'
[23/Jul/2002:20:02:20][3454.1024][-main-] Notice: modload: loading
'/usr/local/aolserver/bin/nssha1.so'
[23/Jul/2002:20:02:20][3454.1024][-main-] Debug: modload: initializing
module 'nssha1'
[23/Jul/2002:20:02:20][3454.1024][-main-] Notice: modload: loading
'/usr/local/aolserver/bin/nscache.so'
[23/Jul/2002:20:02:20][3454.1024][-main-] Notice: nscache module
version @VER@
[23/Jul/2002:20:02:20][3454.1024][-main-] Debug: modload: initializing
module 'nscache'
[23/Jul/2002:20:02:20][3454.1024][-main-] Notice: modload: loading
'/usr/local/aolserver/bin/nsrewrite.so'
[23/Jul/2002:20:02:20][3454.1024][-main-] Debug: modload: initializing
module 'nsrewrite'
[23/Jul/2002:20:02:20][3454.1024][-main-] Notice: modload: loading
'/usr/local/aolserver/bin/nsxml.so'
[23/Jul/2002:20:02:20][3454.1024][-main-] Notice: nsxml module starting
[23/Jul/2002:20:02:20][3454.1024][-main-] Debug: modload: initializing
module 'nsxml'
[23/Jul/2002:20:02:20][3454.1024][-main-] Notice: conf:
[ns/server/birdnote]enabletclpages = 0
[23/Jul/2002:20:02:20][3454.1024][-main-] Notice: Loading OpenACS,
rooted at /web/birdnote
[23/Jul/2002:20:02:20][3454.1024][-main-] Notice: Sourcing
/web/birdnote/packages/acs-bootstrap-installer/bootstrap.tcl
[23/Jul/2002:20:02:20][3454.1024][-main-] Notice: Bootstrap: sourcing
/web/birdnote/packages/acs-bootstrap-installer/tcl/00-proc-procs.tcl
[23/Jul/2002:20:02:20][3454.1024][-main-] Notice: Bootstrap: sourcing
/web/birdnote/packages/acs-bootstrap-installer/tcl/10-utilities-procs.tcl
[23/Jul/2002:20:02:20][3454.1024][-main-] Notice: Bootstrap: sourcing
/web/birdnote/packages/acs-bootstrap-installer/tcl/20-db-bootstrap-procs.tcl
[23/Jul/2002:20:02:20][3454.1024][-main-] Notice: Bootstrap: sourcing
/web/birdnote/packages/acs-bootstrap-installer/tcl/30-apm-load-procs.tcl
[23/Jul/2002:20:02:20][3454.1024][-main-] Notice: Bootstrap: sourcing
/web/birdnote/packages/acs-bootstrap-installer/tcl/40-db-query-dispatcher-procs.tcl
[23/Jul/2002:20:02:20][3454.1024][-main-] Error: Server startup
failed: Error during bootstrapping
command "ns_db" is not enabled
while executing
"ns_db pools"
(procedure "db_bootstrap_set_db_type" line 72)
invoked from within
"db_bootstrap_set_db_type database_problem"
[23/Jul/2002:20:02:20][3454.1024][-main-] Notice: Sourcing files for
postload...
[23/Jul/2002:20:02:20][3454.1024][-main-] Notice: Done.
[23/Jul/2002:20:02:20][3454.1024][-main-] Notice: Executing
initialization code blocks...
[23/Jul/2002:20:02:20][3454.1024][-main-] Notice: QD=Postload files to
load: /web/birdnote/www/index-oracle.xql
/web/birdnote/www/index-postgresql.xql /web/birdnote/www/index.xql
[23/Jul/2002:20:02:20][3454.1024][-main-] Debug: QD_LOGGER = Loading
/web/birdnote/www/index-oracle.xql
[23/Jul/2002:20:02:20][3454.1024][-main-] Debug: QD_LOGGER = new
massaged file content:
oracle
8.1.6
select site_node.url(node_id) as url, acs_object.name(object_id) as name
from site_nodes
where parent_id = site_node.node_id('/')
and object_id is not null
and acs_permission.permission_p(
object_id,
nvl(:user_id, acs.magic_object_id('the_public')),
'read') = 't'
[23/Jul/2002:20:02:20][3454.1024][-main-] Error: QD_LOGGER = Error
parsing queryfile /web/birdnote/www/index-oracle.xql:
invalid command name "xml_parse"
[23/Jul/2002:20:02:20][3454.1024][-main-] Debug: QD_LOGGER = Loading
/web/birdnote/www/index-postgresql.xql
[23/Jul/2002:20:02:20][3454.1024][-main-] Debug: QD_LOGGER = new
massaged file content:
postgresql
7.1
select site_node__url(node_id) as url, acs_object__name(object_id)
as name
from site_nodes
where parent_id = site_node__node_id('/',NULL)
and object_id is not null
and acs_permission__permission_p(
object_id,
coalesce(:user_id, acs__magic_object_id('the_public')),
'read') = 't'
[23/Jul/2002:20:02:20][3454.1024][-main-] Error: QD_LOGGER = Error
parsing queryfile /web/birdnote/www/index-postgresql.xql:
invalid command name "xml_parse"
[23/Jul/2002:20:02:20][3454.1024][-main-] Debug: QD_LOGGER = Loading
/web/birdnote/www/index.xql
[23/Jul/2002:20:02:20][3454.1024][-main-] Debug: QD_LOGGER = new
massaged file content:
select first_names || ' ' || last_name as name, email
from persons, parties
where person_id = :user_id
and person_id = party_id
[23/Jul/2002:20:02:20][3454.1024][-main-] Error: QD_LOGGER = Error
parsing queryfile /web/birdnote/www/index.xql:
invalid command name "xml_parse"
[23/Jul/2002:20:02:20][3454.1024][-main-] Notice: QD=Postload files to
load:
[23/Jul/2002:20:02:20][3454.1024][-main-] Notice: tcl: generating
interp init script
[23/Jul/2002:20:02:20][3454.1024][-main-] Warning: keepalive:
insufficient maxkeepalive 0: keepalive disabled
[23/Jul/2002:20:02:20][3454.1024][-main-] Notice: nsmain:
AOLserver/3.3.1+ad13 running
[23/Jul/2002:20:02:20][3454.1024][-main-] Notice: nsmain: security
info: uid=504, euid=504, gid=503, egid=503
[23/Jul/2002:20:02:20][3454.2051][-sched-] Notice: sched: starting
[23/Jul/2002:20:02:20][3454.1024][-main-] Notice: serv: waiting for warmup
[23/Jul/2002:20:02:21][3454.1024][-main-] Notice: serv: warmed up
[23/Jul/2002:20:02:21][3454.1024][-main-] Notice: socks: idle
[23/Jul/2002:20:02:21][3454.1024][-main-] Notice: sched: idle
[23/Jul/2002:20:02:21][3454.1024][-main-] Notice: nssock: listening on
127.0.0.1:8000
[23/Jul/2002:20:02:21][3454.8201][-nssock-] Notice: nssock: starting
[23/Jul/2002:20:02:21][3454.8201][-nssock-] Notice: nssock: accepting
connections
[23/Jul/2002:20:38:41][3454.1024][-main-] Notice: nsmain:
AOLserver/3.3.1+ad13 stopping
[23/Jul/2002:20:38:41][3454.1024][-main-] Notice: nssock: triggering
shutdown
[23/Jul/2002:20:38:41][3454.1024][-main-] Notice: serv: stopping
connection threads
[23/Jul/2002:20:38:41][3454.1024][-main-] Notice: serv: connection
threads stopped
[23/Jul/2002:20:38:41][3454.1024][-main-] Notice: sched: shutdown pending
[23/Jul/2002:20:38:41][3454.2051][-sched-] Notice: sched: shutdown started
[23/Jul/2002:20:38:41][3454.2051][-sched-] Notice: sched: shutdown
complete
[23/Jul/2002:20:38:41][3454.8201][-nssock-] Notice: exiting
[23/Jul/2002:20:38:41][3454.9219][-shutdown-] Notice: nslog: closing
'/usr/local/aolserver/log/birdnote.log'
[23/Jul/2002:20:38:41][3454.9219][-shutdown-] Notice: nssock: shutdown
complete
[23/Jul/2002:20:38:41][3454.1024][-main-] Notice: nsmain:
AOLserver/3.3.1+ad13 exiting
[23/Jul/2002:20:39:03][3588.1024][-main-] Notice: nsmain:
AOLserver/3.3.1+ad13 starting
[23/Jul/2002:20:39:03][3588.1024][-main-] Notice: nsmain: security
info: uid=504, euid=504, gid=503, egid=503
[23/Jul/2002:20:39:03][3588.1024][-main-] Notice: nsmain: max files:
FD_SETSIZE = 1024, rl_cur = 1024, rl_max = 1024
[23/Jul/2002:20:39:03][3588.1024][-main-] Notice: return: redirecting
'404' to 'global/file-not-found.html'
[23/Jul/2002:20:39:03][3588.1024][-main-] Notice: return: redirecting
'403' to 'global/forbidden.html'
[23/Jul/2002:20:39:03][3588.1024][-main-] Error: dbdrv: no such driver
'ora8'
[23/Jul/2002:20:39:03][3588.1024][-main-] Error: dbinit: no such
default pool 'pool1'
[23/Jul/2002:20:39:03][3588.1024][-main-] Debug: dbinit: no configured
pools
[23/Jul/2002:20:39:03][3588.1024][-main-] Notice: adp: mapped /*.adp
[23/Jul/2002:20:39:03][3588.1024][-main-] Notice: modload: loading
'/usr/local/aolserver/bin/nssock.so'
[23/Jul/2002:20:39:03][3588.1024][-main-] Debug: modload: initializing
module 'nssock'
[23/Jul/2002:20:39:03][3588.1024][-main-] Notice: modload: loading
'/usr/local/aolserver/bin/nslog.so'
[23/Jul/2002:20:39:03][3588.1024][-main-] Notice: nslog: opened
'/usr/local/aolserver/log/birdnote.log'
[23/Jul/2002:20:39:03][3588.1024][-main-] Debug: modload: initializing
module 'nslog'
[23/Jul/2002:20:39:03][3588.1024][-main-] Notice: modload: loading
'/usr/local/aolserver/bin/nssha1.so'
[23/Jul/2002:20:39:03][3588.1024][-main-] Debug: modload: initializing
module 'nssha1'
[23/Jul/2002:20:39:03][3588.1024][-main-] Notice: modload: loading
'/usr/local/aolserver/bin/nscache.so'
[23/Jul/2002:20:39:03][3588.1024][-main-] Notice: nscache module
version @VER@
[23/Jul/2002:20:39:03][3588.1024][-main-] Debug: modload: initializing
module 'nscache'
[23/Jul/2002:20:39:03][3588.1024][-main-] Notice: modload: loading
'/usr/local/aolserver/bin/nsrewrite.so'
[23/Jul/2002:20:39:03][3588.1024][-main-] Debug: modload: initializing
module 'nsrewrite'
[23/Jul/2002:20:39:03][3588.1024][-main-] Notice: modload: loading
'/usr/local/aolserver/bin/nsxml.so'
[23/Jul/2002:20:39:04][3588.1024][-main-] Notice: nsxml module starting
[23/Jul/2002:20:39:04][3588.1024][-main-] Debug: modload: initializing
module 'nsxml'
[23/Jul/2002:20:39:04][3588.1024][-main-] Notice: conf:
[ns/server/birdnote]enabletclpages = 0
[23/Jul/2002:20:39:04][3588.1024][-main-] Notice: Loading OpenACS,
rooted at /web/birdnote
[23/Jul/2002:20:39:04][3588.1024][-main-] Notice: Sourcing
/web/birdnote/packages/acs-bootstrap-installer/bootstrap.tcl
[23/Jul/2002:20:39:04][3588.1024][-main-] Notice: Bootstrap: sourcing
/web/birdnote/packages/acs-bootstrap-installer/tcl/00-proc-procs.tcl
[23/Jul/2002:20:39:04][3588.1024][-main-] Notice: Bootstrap: sourcing
/web/birdnote/packages/acs-bootstrap-installer/tcl/10-utilities-procs.tcl
[23/Jul/2002:20:39:04][3588.1024][-main-] Notice: Bootstrap: sourcing
/web/birdnote/packages/acs-bootstrap-installer/tcl/20-db-bootstrap-procs.tcl
[23/Jul/2002:20:39:04][3588.1024][-main-] Notice: Bootstrap: sourcing
/web/birdnote/packages/acs-bootstrap-installer/tcl/30-apm-load-procs.tcl
[23/Jul/2002:20:39:04][3588.1024][-main-] Notice: Bootstrap: sourcing
/web/birdnote/packages/acs-bootstrap-installer/tcl/40-db-query-dispatcher-procs.tcl
[23/Jul/2002:20:39:04][3588.1024][-main-] Error: Server startup
failed: Error during bootstrapping
command "ns_db" is not enabled
while executing
"ns_db pools"
(procedure "db_bootstrap_set_db_type" line 72)
invoked from within
"db_bootstrap_set_db_type database_problem"
[23/Jul/2002:20:39:04][3588.1024][-main-] Notice: Sourcing files for
postload...
[23/Jul/2002:20:39:04][3588.1024][-main-] Notice: Done.
[23/Jul/2002:20:39:04][3588.1024][-main-] Notice: Executing
initialization code blocks...
[23/Jul/2002:20:39:04][3588.1024][-main-] Notice: QD=Postload files to
load: /web/birdnote/www/index-oracle.xql
/web/birdnote/www/index-postgresql.xql /web/birdnote/www/index.xql
[23/Jul/2002:20:39:04][3588.1024][-main-] Debug: QD_LOGGER = Loading
/web/birdnote/www/index-oracle.xql
[23/Jul/2002:20:39:04][3588.1024][-main-] Debug: QD_LOGGER = new
massaged file content:
oracle
8.1.6
select site_node.url(node_id) as url, acs_object.name(object_id) as name
from site_nodes
where parent_id = site_node.node_id('/')
and object_id is not null
and acs_permission.permission_p(
object_id,
nvl(:user_id, acs.magic_object_id('the_public')),
'read') = 't'
[23/Jul/2002:20:39:04][3588.1024][-main-] Error: QD_LOGGER = Error
parsing queryfile /web/birdnote/www/index-oracle.xql:
invalid command name "xml_parse"
[23/Jul/2002:20:39:04][3588.1024][-main-] Debug: QD_LOGGER = Loading
/web/birdnote/www/index-postgresql.xql
[23/Jul/2002:20:39:04][3588.1024][-main-] Debug: QD_LOGGER = new
massaged file content:
postgresql
7.1
select site_node__url(node_id) as url, acs_object__name(object_id)
as name
from site_nodes
where parent_id = site_node__node_id('/',NULL)
and object_id is not null
and acs_permission__permission_p(
object_id,
coalesce(:user_id, acs__magic_object_id('the_public')),
'read') = 't'
[23/Jul/2002:20:39:04][3588.1024][-main-] Error: QD_LOGGER = Error
parsing queryfile /web/birdnote/www/index-postgresql.xql:
invalid command name "xml_parse"
[23/Jul/2002:20:39:04][3588.1024][-main-] Debug: QD_LOGGER = Loading
/web/birdnote/www/index.xql
[23/Jul/2002:20:39:04][3588.1024][-main-] Debug: QD_LOGGER = new
massaged file content:
select first_names || ' ' || last_name as name, email
from persons, parties
where person_id = :user_id
and person_id = party_id
[23/Jul/2002:20:39:04][3588.1024][-main-] Error: QD_LOGGER = Error
parsing queryfile /web/birdnote/www/index.xql:
invalid command name "xml_parse"
[23/Jul/2002:20:39:04][3588.1024][-main-] Notice: QD=Postload files to
load:
[23/Jul/2002:20:39:04][3588.1024][-main-] Notice: tcl: generating
interp init script
[23/Jul/2002:20:39:04][3588.1024][-main-] Warning: keepalive:
insufficient maxkeepalive 0: keepalive disabled
[23/Jul/2002:20:39:04][3588.1024][-main-] Notice: nsmain:
AOLserver/3.3.1+ad13 running
[23/Jul/2002:20:39:04][3588.1024][-main-] Notice: nsmain: security
info: uid=504, euid=504, gid=503, egid=503
[23/Jul/2002:20:39:04][3588.2051][-sched-] Notice: sched: starting
[23/Jul/2002:20:39:04][3588.1024][-main-] Notice: serv: waiting for warmup
[23/Jul/2002:20:39:04][3588.1024][-main-] Notice: serv: warmed up
[23/Jul/2002:20:39:04][3588.1024][-main-] Notice: socks: idle
[23/Jul/2002:20:39:04][3588.1024][-main-] Notice: sched: idle
[23/Jul/2002:20:39:04][3588.1024][-main-] Notice: nssock: listening on
127.0.0.1:8000
[23/Jul/2002:20:39:04][3588.8201][-nssock-] Notice: nssock: starting
[23/Jul/2002:20:39:04][3588.8201][-nssock-] Notice: nssock: accepting
connections
Please help
In the future, please wrap these things in <pre></pre> tags and change the posting type to HTML. FAR more readable that way.
The first problem I see is:
Error: dbdrv: no such driver 'ora8'
Are you using oracle? Is it installed?
If you haven't already, check out https://openacs.org/doc/openacs-4/unix-install.html for installation guidelines. I'm guessing you incorrectly specified which DB you're using? If so, check out conf-db, and recompile aolserver if incorrectly set. Also check that you used the nsd-postgres script, not nsd-oracle.
Of course, if you're actually using Oracle, hopefully someone else will chime in and help. It might help if you could post your version of /usr/local/aolserver/birdnotes.tcl also.
Good luck!
