Forum OpenACS Q&A: Trouble while bo

Collapse
1: Trouble while bo
Posted by Kevin Lange on 05/29/02 02:03 AM
Hi All,

I was hoping somebody could help me out.  I received an error message during the bootstrapping and I'm not sure what is causing it or what to do it.  I've included my log file below and would really appreciate it if any of you could help me figure out the problem.  The errors show up in the second and fifth "paragraph".

Thanks,
Kevin

p.s. I was trying to install dotlrn stuff so the version of openacs is from 5/27.

[27/May/2002:16:34:18][8756.1024][-main-] Notice: nsmain: AOLserver/3.3.1+ad13 stopping
[27/May/2002:16:34:18][8756.1024][-main-] Notice: nssock: triggering shutdown
[27/May/2002:16:34:18][8756.1024][-main-] Notice: serv: stopping connection threads
[27/May/2002:16:34:18][8756.1024][-main-] Notice: serv: connection threads stopped
[27/May/2002:16:34:18][8756.1024][-main-] Notice: sched: shutdown pending
[27/May/2002:16:34:18][8756.2051][-sched-] Notice: sched: shutdown started
[27/May/2002:16:34:18][8756.2051][-sched-] Notice: sched: shutdown complete
[27/May/2002:16:34:18][8756.8201][-nssock-] Notice: exiting
[27/May/2002:16:34:18][8756.9219][-shutdown-] Notice: nslog: closing '/usr/local/aolserver/log/catapult.log'
[27/May/2002:16:34:18][8756.9219][-shutdown-] Notice: nssock: shutdown complete
[27/May/2002:16:34:18][8756.1024][-main-] Notice: nsmain: AOLserver/3.3.1+ad13 exiting
[27/May/2002:16:34:45][8773.1024][-main-] Notice: nsmain: AOLserver/3.3.1+ad13 starting
[27/May/2002:16:34:45][8773.1024][-main-] Notice: nsmain: security info: uid=502, euid=502, gid=504, egid=504
[27/May/2002:16:34:45][8773.1024][-main-] Notice: nsmain: max files: FD_SETSIZE = 1024, rl_cur = 1024, rl_max = 1024
[27/May/2002:16:34:45][8773.1024][-main-] Notice: return: redirecting '404' to 'global/file-not-found.html'
[27/May/2002:16:34:45][8773.1024][-main-] Notice: return: redirecting '403' to 'global/forbidden.html'
[27/May/2002:16:34:45][8773.1024][-main-] Notice: modload: loading '/usr/local/aolserver/bin/ora8.so'
[27/May/2002:16:34:45][8773.1024][-main-] Notice: ora8 driver LobBufferSize = 16384
[27/May/2002:16:34:45][8773.1024][-main-] Notice: ora8 driver PrefetchRows = 0
[27/May/2002:16:34:45][8773.1024][-main-] Notice: ora8 driver PrefetchMemory = 0
[27/May/2002:16:34:45][8773.1024][-main-] Notice: Loaded ArsDigita Oracle Driver version 2.6, built on 10:14:25/May 27 2002
[27/May/2002:16:34:45][8773.1024][-main-] Notice: adp: mapped /*.adp
[27/May/2002:16:34:45][8773.1024][-main-] Notice: modload: loading '/usr/local/aolserver/bin/nssock.so'
[27/May/2002:16:34:45][8773.1024][-main-] Debug: modload: initializing module 'nssock'
[27/May/2002:16:34:45][8773.1024][-main-] Notice: modload: loading '/usr/local/aolserver/bin/nslog.so'
[27/May/2002:16:34:45][8773.1024][-main-] Notice: nslog: opened '/usr/local/aolserver/log/catapult.log'
[27/May/2002:16:34:45][8773.1024][-main-] Debug: modload: initializing module 'nslog'
[27/May/2002:16:34:45][8773.1024][-main-] Notice: modload: loading '/usr/local/aolserver/bin/nssha1.so'
[27/May/2002:16:34:45][8773.1024][-main-] Debug: modload: initializing module 'nssha1'
[27/May/2002:16:34:45][8773.1024][-main-] Notice: modload: loading '/usr/local/aolserver/bin/nscache.so'
[27/May/2002:16:34:45][8773.1024][-main-] Notice: nscache module version @VER@
[27/May/2002:16:34:45][8773.1024][-main-] Debug: modload: initializing module 'nscache'
[27/May/2002:16:34:45][8773.1024][-main-] Notice: modload: loading '/usr/local/aolserver/bin/nsrewrite.so'
[27/May/2002:16:34:45][8773.1024][-main-] Debug: modload: initializing module 'nsrewrite'
[27/May/2002:16:34:45][8773.1024][-main-] Notice: modload: loading '/usr/local/aolserver/bin/nsxml.so'
[27/May/2002:16:34:45][8773.1024][-main-] Notice: nsxml module starting
[27/May/2002:16:34:45][8773.1024][-main-] Debug: modload: initializing module 'nsxml'
[27/May/2002:16:34:46][8773.1024][-main-] Notice: conf: [ns/server/catapult]enabletclpages = 0
[27/May/2002:16:34:46][8773.1024][-main-] Notice: Loading OpenACS, rooted at /web/catapult
[27/May/2002:16:34:46][8773.1024][-main-] Notice: Sourcing /web/catapult/packages/acs-bootstrap-installer/bootstrap.tcl
[27/May/2002:16:34:46][8773.1024][-main-] Notice: Bootstrap: sourcing /web/catapult/packages/acs-bootstrap-installer/tcl/00-proc-procs.tcl

[27/May/2002:16:34:46][8773.1024][-main-] Error: Server startup failed: Error during bootstrapping
syntax error in expression " [nsv_exists proc_source_file $proc_name]
    && [string"
    ("if" test expression)
    while compiling
"if { [nsv_exists proc_source_file $proc_name]
    && [string compare [nsv_get proc_source_file $proc_name] [info script]] != 0 } {
        ns_log..."
    (compiling body of proc "ad_proc", line 221)
    invoked from within
"ad_proc -public -deprecated proc_doc { args } {

    A synonym for <code>ad_proc</code> (to support legacy code).

} {
    eval ad_proc $args..."
    (file "/web/catapult/packages/acs-bootstrap-installer/tcl/00-proc-procs.tcl" line 364)
    invoked from within
"source $file
"
    ("foreach" body line 3)
    invoked from within
"foreach file [lsort $files] {
ns_log Notice "Bootstrap: sourcing $file"
source $file
    }
"

[27/May/2002:16:34:46][8773.1024][-main-] Notice: Sourcing files for postload...
[27/May/2002:16:34:46][8773.1024][-main-] Notice: Done.
[27/May/2002:16:34:46][8773.1024][-main-] Notice: Executing initialization code blocks...
[27/May/2002:16:34:46][8773.1024][-main-] Notice: QD=Postload files to load: /web/catapult/www/index-oracle.xql /web/catapult/www/index-postgresql.xql /web/catapult/www/index.xql

[27/May/2002:16:34:46][8773.1024][-main-] Error:
invalid command name "db_qd_load_query_file"
    while executing
"db_qd_load_query_file $file
"
    ("foreach" body line 2)
    invoked from within
"foreach file $files {
db_qd_load_query_file $file
    }
"
    ("foreach" body line 6)
    invoked from within
"foreach dir $dirs {
    set files [glob -nocomplain "${oacs_root}/$dir/*.xql"]

    ns_log Notice "QD=Postload files to load: $files"

    fo..."
    (file "/web/catapult/tcl/zz-postload.tcl" line 37)

[27/May/2002:16:34:46][8773.1024][-main-] Notice: tcl: generating interp init script
[27/May/2002:16:34:46][8773.1024][-main-] Warning: keepalive: insufficient maxkeepalive 0: keepalive disabled
[27/May/2002:16:34:46][8773.1024][-main-] Notice: nsmain: AOLserver/3.3.1+ad13 running
[27/May/2002:16:34:46][8773.1024][-main-] Notice: nsmain: security info: uid=502, euid=502, gid=504, egid=504
[27/May/2002:16:34:46][8773.2051][-sched-] Notice: sched: starting
[27/May/2002:16:34:46][8773.1024][-main-] Notice: serv: waiting for warmup
[27/May/2002:16:34:46][8773.1024][-main-] Notice: serv: warmed up
[27/May/2002:16:34:46][8773.1024][-main-] Notice: socks: idle
[27/May/2002:16:34:46][8773.1024][-main-] Notice: sched: idle
[27/May/2002:16:34:46][8773.1024][-main-] Notice: nssock: listening on 127.0.0.1:8000
[27/May/2002:16:34:46][8773.8201][-nssock-] Notice: nssock: starting
[27/May/2002:16:34:46][8773.8201][-nssock-] Notice: nssock: accepting connections

Collapse
2: Response to Trouble while bo (response to 1)
Posted by Jade Rubick on 05/29/02 07:10 PM
Kevin, that's rather difficult to read. Could you put pre tags around your log text? 
like
 this
Collapse
3: Response to Trouble while booting (response to 1)
Posted by Kevin Lange on 05/29/02 11:56 PM
Thanks for showing me how to do that! This should be much more clear... 
[27/May/2002:16:34:18][8756.1024][-main-] Notice: nsmain: AOLserver/3.3.1+ad13 stopping
[27/May/2002:16:34:18][8756.1024][-main-] Notice: nssock: triggering shutdown
[27/May/2002:16:34:18][8756.1024][-main-] Notice: serv: stopping connection threads
[27/May/2002:16:34:18][8756.1024][-main-] Notice: serv: connection threads stopped
[27/May/2002:16:34:18][8756.1024][-main-] Notice: sched: shutdown pending
[27/May/2002:16:34:18][8756.2051][-sched-] Notice: sched: shutdown started
[27/May/2002:16:34:18][8756.2051][-sched-] Notice: sched: shutdown complete
[27/May/2002:16:34:18][8756.8201][-nssock-] Notice: exiting
[27/May/2002:16:34:18][8756.9219][-shutdown-] Notice: nslog: closing '/usr/local/aolserver/log/catapult.log'
[27/May/2002:16:34:18][8756.9219][-shutdown-] Notice: nssock: shutdown complete
[27/May/2002:16:34:18][8756.1024][-main-] Notice: nsmain: AOLserver/3.3.1+ad13 exiting
[27/May/2002:16:34:45][8773.1024][-main-] Notice: nsmain: AOLserver/3.3.1+ad13 starting
[27/May/2002:16:34:45][8773.1024][-main-] Notice: nsmain: security info: uid=502, euid=502, gid=504, egid=504
[27/May/2002:16:34:45][8773.1024][-main-] Notice: nsmain: max files: FD_SETSIZE = 1024, rl_cur = 1024, rl_max = 1024
[27/May/2002:16:34:45][8773.1024][-main-] Notice: return: redirecting '404' to 'global/file-not-found.html'
[27/May/2002:16:34:45][8773.1024][-main-] Notice: return: redirecting '403' to 'global/forbidden.html'
[27/May/2002:16:34:45][8773.1024][-main-] Notice: modload: loading '/usr/local/aolserver/bin/ora8.so'
[27/May/2002:16:34:45][8773.1024][-main-] Notice: ora8 driver LobBufferSize = 16384
[27/May/2002:16:34:45][8773.1024][-main-] Notice: ora8 driver PrefetchRows = 0
[27/May/2002:16:34:45][8773.1024][-main-] Notice: ora8 driver PrefetchMemory = 0
[27/May/2002:16:34:45][8773.1024][-main-] Notice: Loaded ArsDigita Oracle Driver version 2.6, built on 10:14:25/May 27 2002
[27/May/2002:16:34:45][8773.1024][-main-] Notice: adp: mapped /*.adp
[27/May/2002:16:34:45][8773.1024][-main-] Notice: modload: loading '/usr/local/aolserver/bin/nssock.so'
[27/May/2002:16:34:45][8773.1024][-main-] Debug: modload: initializing module 'nssock'
[27/May/2002:16:34:45][8773.1024][-main-] Notice: modload: loading '/usr/local/aolserver/bin/nslog.so'
[27/May/2002:16:34:45][8773.1024][-main-] Notice: nslog: opened '/usr/local/aolserver/log/catapult.log'
[27/May/2002:16:34:45][8773.1024][-main-] Debug: modload: initializing module 'nslog'
[27/May/2002:16:34:45][8773.1024][-main-] Notice: modload: loading '/usr/local/aolserver/bin/nssha1.so'
[27/May/2002:16:34:45][8773.1024][-main-] Debug: modload: initializing module 'nssha1'
[27/May/2002:16:34:45][8773.1024][-main-] Notice: modload: loading '/usr/local/aolserver/bin/nscache.so'
[27/May/2002:16:34:45][8773.1024][-main-] Notice: nscache module version @VER@
[27/May/2002:16:34:45][8773.1024][-main-] Debug: modload: initializing module 'nscache'
[27/May/2002:16:34:45][8773.1024][-main-] Notice: modload: loading '/usr/local/aolserver/bin/nsrewrite.so'
[27/May/2002:16:34:45][8773.1024][-main-] Debug: modload: initializing module 'nsrewrite'
[27/May/2002:16:34:45][8773.1024][-main-] Notice: modload: loading '/usr/local/aolserver/bin/nsxml.so'
[27/May/2002:16:34:45][8773.1024][-main-] Notice: nsxml module starting
[27/May/2002:16:34:45][8773.1024][-main-] Debug: modload: initializing module 'nsxml'
[27/May/2002:16:34:46][8773.1024][-main-] Notice: conf: [ns/server/catapult]enabletclpages = 0
[27/May/2002:16:34:46][8773.1024][-main-] Notice: Loading OpenACS, rooted at /web/catapult
[27/May/2002:16:34:46][8773.1024][-main-] Notice: Sourcing /web/catapult/packages/acs-bootstrap-installer/bootstrap.tcl
[27/May/2002:16:34:46][8773.1024][-main-] Notice: Bootstrap: sourcing /web/catapult/packages/acs-bootstrap-installer/tcl/00-proc-procs.tcl
[27/May/2002:16:34:46][8773.1024][-main-] Error: Server startup failed: Error during bootstrapping
syntax error in expression " [nsv_exists proc_source_file $proc_name] 
	    && [string"
    ("if" test expression)
    while compiling
"if { [nsv_exists proc_source_file $proc_name] 
	    && [string compare [nsv_get proc_source_file $proc_name] [info script]] != 0 } {
        ns_log..."
    (compiling body of proc "ad_proc", line 221)
    invoked from within
"ad_proc -public -deprecated proc_doc { args } {

    A synonym for ad_proc (to support legacy code).
    
} {
    eval ad_proc $args..."
    (file "/web/catapult/packages/acs-bootstrap-installer/tcl/00-proc-procs.tcl" line 364)
    invoked from within
"source $file
"
    ("foreach" body line 3)
    invoked from within
"foreach file [lsort $files] {
	ns_log Notice "Bootstrap: sourcing $file"
	source $file
    }
"
[27/May/2002:16:34:46][8773.1024][-main-] Notice: Sourcing files for postload...
[27/May/2002:16:34:46][8773.1024][-main-] Notice: Done.
[27/May/2002:16:34:46][8773.1024][-main-] Notice: Executing initialization code blocks...
[27/May/2002:16:34:46][8773.1024][-main-] Notice: QD=Postload files to load: /web/catapult/www/index-oracle.xql /web/catapult/www/index-postgresql.xql /web/catapult/www/index.xql
[27/May/2002:16:34:46][8773.1024][-main-] Error: 
invalid command name "db_qd_load_query_file"
    while executing
"db_qd_load_query_file $file
"
    ("foreach" body line 2)
    invoked from within
"foreach file $files {
	db_qd_load_query_file $file
    }
"
    ("foreach" body line 6)
    invoked from within
"foreach dir $dirs {
    set files [glob -nocomplain "${oacs_root}/$dir/*.xql"]
    
    ns_log Notice "QD=Postload files to load: $files"

    fo..."
    (file "/web/catapult/tcl/zz-postload.tcl" line 37)
[27/May/2002:16:34:46][8773.1024][-main-] Notice: tcl: generating interp init script
[27/May/2002:16:34:46][8773.1024][-main-] Warning: keepalive: insufficient maxkeepalive 0: keepalive disabled
[27/May/2002:16:34:46][8773.1024][-main-] Notice: nsmain: AOLserver/3.3.1+ad13 running
[27/May/2002:16:34:46][8773.1024][-main-] Notice: nsmain: security info: uid=502, euid=502, gid=504, egid=504
[27/May/2002:16:34:46][8773.2051][-sched-] Notice: sched: starting
[27/May/2002:16:34:46][8773.1024][-main-] Notice: serv: waiting for warmup
[27/May/2002:16:34:46][8773.1024][-main-] Notice: serv: warmed up
[27/May/2002:16:34:46][8773.1024][-main-] Notice: socks: idle
[27/May/2002:16:34:46][8773.1024][-main-] Notice: sched: idle
[27/May/2002:16:34:46][8773.1024][-main-] Notice: nssock: listening on 127.0.0.1:8000
[27/May/2002:16:34:46][8773.8201][-nssock-] Notice: nssock: starting
[27/May/2002:16:34:46][8773.8201][-nssock-] Notice: nssock: accepting connections
[27/May/2002:21:38:20][8773.1024][-main-] Notice: nsmain: AOLserver/3.3.1+ad13 stopping
[27/May/2002:21:38:20][8773.1024][-main-] Notice: nssock: triggering shutdown
[27/May/2002:21:38:20][8773.1024][-main-] Notice: serv: stopping connection threads
[27/May/2002:21:38:20][8773.1024][-main-] Notice: serv: connection threads stopped
[27/May/2002:21:38:20][8773.1024][-main-] Notice: sched: shutdown pending
[27/May/2002:21:38:20][8773.2051][-sched-] Notice: sched: shutdown started
[27/May/2002:21:38:20][8773.2051][-sched-] Notice: sched: shutdown complete
[27/May/2002:21:38:20][8773.8201][-nssock-] Notice: exiting
[27/May/2002:21:38:20][8773.9219][-shutdown-] Notice: nslog: closing '/usr/local/aolserver/log/catapult.log'
[27/May/2002:21:38:20][8773.9219][-shutdown-] Notice: nssock: shutdown complete
[27/May/2002:21:38:20][8773.1024][-main-] Notice: nsmain: AOLserver/3.3.1+ad13 exiting
Collapse
4: Response to Trouble while bo (response to 1)
Posted by Jade Rubick on 05/30/02 12:52 AM
I guess it's still unclear. Tell us about your setup, what versions
of everything you're using, what you've modified and what you
haven't, etc...
Collapse
5: Response to Trouble while bo (response to 1)
Posted by Kevin Lange on 05/30/02 05:48 PM
Okay, I'll put everything that I can think of.  I'm at work right now so I don't have access to the system right now.

Installed Red Hat 7.2, Oracle 8.1.7, and Aolserver really without much incident.  I used the instructions at http://www.idevelopment.info/data/DBA_tips/DBATIP_54.shtml in conjunction with the installation instructions provided here.  The idevelopment instructions were the clearest ones I could find regarding some of the patches, rpm, etc. that need to be done to get oracle to properly install.

I then followed the instructions regarding dotlrn about getting the latest cvs build for acs and dotlrn.  So, for my first installation the code was dated about 5/20.  I then retried it with code 5/24ish but had the exact same error message.

This is my first time to really dig into this stuff and I'm still unfamiliar with many of the details and so I really tried not to divert away from the instructions at all.  I could find no syntax error in the line above.  If you think it is worthwhile I could include my 00-proc-procs.tcl and bootstrap.tcl when I get back home.

After being unable to figure out what syntax error it was indicating I did try commenting out the line just to see how far it would go.  It got a lot farther but ended up getting an error in, I think it was called, 40-proc-procs.tcl but I'd have to retry it if the error message associated with that will help any.

I'm sorry if this is a bit vague but I'm not sure what information will be the most helpful.  If you can tell me what will help I'll do what I can to get it.

Collapse
6: Response to Trouble while bo (response to 1)
Posted by Jade Rubick on 05/30/02 06:59 PM
I don't know very much about dotLRN, so perhaps someone
else can help you out here...