Forum OpenACS Development: OpenACS 4.6.2 on Debian installation question

I'm setting up OpenACS 4.6.2 on a Debian (woody) machine using the installation guide. The installation of postgres and AOLserver went fine, and I could see the aolserver test page.

After making the appropriate changes to the config.tcl file, I invoked nsd-postgres like the installation guide suggested. Instead of seeing the "OpenACS Installation: Welcome" page, I see an empty page. The source of the page is:

<!DOCTYPE html PUBLIC "-//W3C//DTD HTML 4.01 Transitional//EN"><html><head><title></title></head><body></body></html>
The access log does not show anything at all. Can someone please tell me who is generating this empty page, and why I'm not getting the normal install page? I'm copying my error.log file below. It doesn't show anything obvious (at least to me).

Also, interestingly, if I enable the nsopenssl module (after generating the certificate and key files using the instructions in the install guide), and then use https to access the server, I do get the Installation page (after accepting the certificate). Why would that happen? I can post the error.log from when I have enabled nsopenssl, too, if that helps.

I couldn't find an answer to this question by doing searches, so apologies if it's answered elsewhere.

Thanks!

--------- error.log file -----------------------
[23/May/2003:22:05:09][11405.1024][-main-] Notice: nsmain: AOLserver/3.3.1+ad13 starting
[23/May/2003:22:05:09][11405.1024][-main-] Notice: nsmain: security info: uid=1009, euid=1009, gid=1002, egid=1002
[23/May/2003:22:05:09][11405.1024][-main-] Notice: nsmain: max files: FD_SETSIZE = 1024, rl_cur = 1024, rl_max = 1024
[23/May/2003:22:05:09][11405.1024][-main-] Notice: return: redirecting '404' to 'global/file-not-found.html'
[23/May/2003:22:05:09][11405.1024][-main-] Notice: return: redirecting '403' to 'global/forbidden.html'
[23/May/2003:22:05:09][11405.1024][-main-] Notice: modload: loading '/usr/local/aolserver/3.3oacs/bin/nspostgres.so'
[23/May/2003:22:05:09][11405.1024][-main-] Notice: PostgreSQL loaded.
[23/May/2003:22:05:09][11405.1024][-main-] Notice: adp: mapped /*.adp
[23/May/2003:22:05:09][11405.1024][-main-] Notice: modload: loading '/usr/local/aolserver/3.3oacs/bin/nssock.so'
[23/May/2003:22:05:09][11405.1024][-main-] Notice: modload: loading '/usr/local/aolserver/3.3oacs/bin/nslog.so'
[23/May/2003:22:05:09][11405.1024][-main-] Notice: nslog: opened '/home/testserver/testserver/log/testserver.log'
[23/May/2003:22:05:09][11405.1024][-main-] Notice: modload: loading '/usr/local/aolserver/3.3oacs/bin/nssha1.so'
[23/May/2003:22:05:09][11405.1024][-main-] Notice: modload: loading '/usr/local/aolserver/3.3oacs/bin/nscache.so'
[23/May/2003:22:05:09][11405.1024][-main-] Notice: nscache module version @VER@
[23/May/2003:22:05:09][11405.1024][-main-] Notice: modload: loading '/usr/local/aolserver/3.3oacs/bin/nsrewrite.so'
[23/May/2003:22:05:09][11405.1024][-main-] Notice: modload: loading '/usr/local/aolserver/3.3oacs/bin/nsxml.so'
[23/May/2003:22:05:09][11405.1024][-main-] Notice: nsxml module starting
[23/May/2003:22:05:09][11405.1024][-main-] Notice: modload: loading '/usr/local/aolserver/3.3oacs/bin/nsfts.so'
[23/May/2003:22:05:11][11405.1024][-main-] Notice: conf: [ns/server/testserver]enabletclpages = 1
[23/May/2003:22:05:11][11405.1024][-main-] Notice: tcl: enabling .tcl pages
[23/May/2003:22:05:11][11405.1024][-main-] Notice: Loading OpenACS, rooted at /home/testserver/testserver
[23/May/2003:22:05:11][11405.1024][-main-] Notice: Sourcing /home/testserver/testserver/packages/acs-bootstrap-installer/bootstrap.tcl
[23/May/2003:22:05:11][11405.1024][-main-] Notice: Bootstrap: sourcing /home/testserver/testserver/packages/acs-bootstrap-installer/tcl/00-proc-procs.tcl
[23/May/2003:22:05:11][11405.1024][-main-] Notice: Bootstrap: sourcing /home/testserver/testserver/packages/acs-bootstrap-installer/tcl/10-utilities-procs.tcl
[23/May/2003:22:05:11][11405.1024][-main-] Notice: Bootstrap: sourcing /home/testserver/testserver/packages/acs-bootstrap-installer/tcl/20-db-bootstrap-procs.tcl
[23/May/2003:22:05:11][11405.1024][-main-] Notice: Bootstrap: sourcing /home/testserver/testserver/packages/acs-bootstrap-installer/tcl/30-apm-load-procs.tcl
[23/May/2003:22:05:11][11405.1024][-main-] Notice: Bootstrap: sourcing /home/testserver/testserver/packages/acs-bootstrap-installer/tcl/40-db-query-dispatcher-procs.tcl
[23/May/2003:22:05:11][11405.1024][-main-] Notice: Database API: The following pools are available: pool2 pool3 pool1
[23/May/2003:22:05:11][11405.1024][-main-] Notice: dbdrv: opening database 'postgres:localhost::testserver'
[23/May/2003:22:05:11][11405.1024][-main-] Notice: Opening testserver on localhost
[23/May/2003:22:05:11][11405.1024][-main-] Notice: Ns_PgOpenDb(postgres):  Openned connection to localhost::testserver.
[23/May/2003:22:05:11][11405.1024][-main-] Notice: dbdrv: opening database 'postgres:localhost::testserver'
[23/May/2003:22:05:11][11405.1024][-main-] Notice: Opening testserver on localhost
[23/May/2003:22:05:12][11405.1024][-main-] Notice: Ns_PgOpenDb(postgres):  Openned connection to localhost::testserver.
[23/May/2003:22:05:12][11405.1024][-main-] Notice: dbdrv: opening database 'postgres:localhost::testserver'
[23/May/2003:22:05:12][11405.1024][-main-] Notice: Opening testserver on localhost
[23/May/2003:22:05:12][11405.1024][-main-] Notice: Ns_PgOpenDb(postgres):  Openned connection to localhost::testserver.
[23/May/2003:22:05:12][11405.1024][-main-] Notice: Loading acs-tcl
[23/May/2003:22:05:12][11405.1024][-main-] Notice: Loading packages/acs-tcl/tcl/00-database-procs-postgresql.tcl...
[23/May/2003:22:05:12][11405.1024][-main-] Notice: Loading packages/acs-tcl/tcl/00-database-procs.tcl...
[23/May/2003:22:05:12][11405.1024][-main-] Notice: Loading packages/acs-tcl/tcl/10-charset-compat-procs.tcl...
[23/May/2003:22:05:12][11405.1024][-main-] Notice: Loading packages/acs-tcl/tcl/30-xml-utils-procs.tcl...
[23/May/2003:22:05:12][11405.1024][-main-] Notice: Loading packages/acs-tcl/tcl/acs-kernel-procs.tcl...
[23/May/2003:22:05:12][11405.1024][-main-] Notice: Loading packages/acs-tcl/tcl/acs-permissions-procs.tcl...
[23/May/2003:22:05:13][11405.1024][-main-] Notice: Loading packages/acs-tcl/tcl/acs-private-data-procs.tcl...
[23/May/2003:22:05:13][11405.1024][-main-] Notice: Loading packages/acs-tcl/tcl/admin-procs.tcl...
[23/May/2003:22:05:13][11405.1024][-main-] Notice: Loading packages/acs-tcl/tcl/adp-parser-procs.tcl...
[23/May/2003:22:05:13][11405.1024][-main-] Notice: Loading packages/acs-tcl/tcl/aolserver-3-procs.tcl...
[23/May/2003:22:05:13][11405.1024][-main-] Notice: Loading packages/acs-tcl/tcl/apm-file-procs.tcl...
[23/May/2003:22:05:13][11405.1024][-main-] Notice: Loading packages/acs-tcl/tcl/apm-install-procs.tcl...
[23/May/2003:22:05:14][11405.1024][-main-] Notice: Loading packages/acs-tcl/tcl/apm-procs.tcl...
[23/May/2003:22:05:14][11405.1024][-main-] Notice: Loading packages/acs-tcl/tcl/apm-xml-procs.tcl...
[23/May/2003:22:05:14][11405.1024][-main-] Notice: Loading packages/acs-tcl/tcl/community-core-2-procs.tcl...
[23/May/2003:22:05:14][11405.1024][-main-] Notice: Loading packages/acs-tcl/tcl/community-core-procs.tcl...
[23/May/2003:22:05:15][11405.1024][-main-] Notice: Loading packages/acs-tcl/tcl/defs-procs.tcl...
[23/May/2003:22:05:15][11405.1024][-main-] Notice: Loading packages/acs-tcl/tcl/deprecated-utilities-procs.tcl...
[23/May/2003:22:05:15][11405.1024][-main-] Notice: Loading packages/acs-tcl/tcl/document-procs.tcl...
[23/May/2003:22:05:15][11405.1024][-main-] Notice: Loading packages/acs-tcl/tcl/exception-procs.tcl...
[23/May/2003:22:05:15][11405.1024][-main-] Notice: Loading packages/acs-tcl/tcl/form-processing-procs.tcl...
[23/May/2003:22:05:16][11405.1024][-main-] Notice: Loading packages/acs-tcl/tcl/membership-rel-procs.tcl...
[23/May/2003:22:05:16][11405.1024][-main-] Notice: Loading packages/acs-tcl/tcl/memoize-procs.tcl...
[23/May/2003:22:05:16][11405.1024][-main-] Notice: Loading packages/acs-tcl/tcl/navigation-procs.tcl...
[23/May/2003:22:05:16][11405.1024][-main-] Notice: Loading packages/acs-tcl/tcl/object-procs.tcl...
[23/May/2003:22:05:16][11405.1024][-main-] Notice: Loading packages/acs-tcl/tcl/object-type-procs.tcl...
[23/May/2003:22:05:16][11405.1024][-main-] Notice: Loading packages/acs-tcl/tcl/openacs-kernel-procs.tcl...
[23/May/2003:22:05:16][11405.1024][-main-] Notice: Loading packages/acs-tcl/tcl/parameter-procs.tcl...
[23/May/2003:22:05:16][11405.1024][-main-] Notice: Loading packages/acs-tcl/tcl/request-processor-procs.tcl...
[23/May/2003:22:05:16][11405.1024][-main-] Notice: Loading packages/acs-tcl/tcl/rollout-email-procs.tcl...
[23/May/2003:22:05:16][11405.1024][-main-] Notice: Loading packages/acs-tcl/tcl/security-procs.tcl...
[23/May/2003:22:05:17][11405.1024][-main-] Notice: Loading packages/acs-tcl/tcl/server-cluster-procs.tcl...
[23/May/2003:22:05:17][11405.1024][-main-] Notice: Loading packages/acs-tcl/tcl/set-operation-procs.tcl...
[23/May/2003:22:05:17][11405.1024][-main-] Notice: Loading packages/acs-tcl/tcl/site-node-apm-integration-procs.tcl...
[23/May/2003:22:05:17][11405.1024][-main-] Notice: Loading packages/acs-tcl/tcl/site-node-object-map-procs.tcl...
[23/May/2003:22:05:17][11405.1024][-main-] Notice: Loading packages/acs-tcl/tcl/site-nodes-procs.tcl...
[23/May/2003:22:05:18][11405.1024][-main-] Notice: Loading packages/acs-tcl/tcl/sql-statement-procs.tcl...
[23/May/2003:22:05:18][11405.1024][-main-] Notice: Loading packages/acs-tcl/tcl/table-display-procs.tcl...
[23/May/2003:22:05:18][11405.1024][-main-] Notice: Loading packages/acs-tcl/tcl/tcl-documentation-procs.tcl...
[23/May/2003:22:05:18][11405.1024][-main-] Notice: Loading packages/acs-tcl/tcl/text-html-procs.tcl...
[23/May/2003:22:05:19][11405.1024][-main-] Notice: Loading packages/acs-tcl/tcl/user-extensions-procs.tcl...
[23/May/2003:22:05:19][11405.1024][-main-] Notice: Loading packages/acs-tcl/tcl/utilities-procs.tcl...
[23/May/2003:22:05:20][11405.1024][-main-] Notice: Loading packages/acs-tcl/tcl/widgets-procs.tcl...
[23/May/2003:22:05:20][11405.1024][-main-] Notice: Loading packages/acs-tcl/tcl/xml-0-sgml-procs.tcl...
[23/May/2003:22:05:20][11405.1024][-main-] Notice: Loading packages/acs-tcl/tcl/xml-1-dom-procs.tcl...
[23/May/2003:22:05:20][11405.1024][-main-] Notice: Loading packages/acs-tcl/tcl/xml-2-procs.tcl...
[23/May/2003:22:05:23][11405.1024][-main-] Notice: Multiple definition of util_memoize in /home/testserver/testserver/packages/acs-tcl/tcl/memoize-procs.tcl and /home/testserver/testserver/packages/acs-bootstrap-installer/bootstrap.tcl
[23/May/2003:22:05:23][11405.1024][-main-] Notice: Installation is not complete - sourcing the installer.
[23/May/2003:22:05:23][11405.1024][-main-] Notice: Sourcing files for postload...
[23/May/2003:22:05:23][11405.1024][-main-] Notice: Done.
[23/May/2003:22:05:23][11405.1024][-main-] Notice: Executing initialization code blocks...
[23/May/2003:22:05:23][11405.1024][-main-] Warning: Query dbqd.www.index-oracle.site_nodes has rdbms info oracle 8.1.6 which is not compatible with system rdbms postgresql 7.3
[23/May/2003:22:05:23][11405.1024][-main-] Notice: tcl: generating interp init script
[23/May/2003:22:05:25][11405.1024][-main-] Notice: nsmain: AOLserver/3.3.1+ad13 running
[23/May/2003:22:05:25][11405.1024][-main-] Notice: nsmain: security info: uid=1009, euid=1009, gid=1002, egid=1002
[23/May/2003:22:05:25][11405.1024][-main-] Notice: serv: waiting for warmup
[23/May/2003:22:05:25][11405.2051][-sched-] Notice: sched: starting
[23/May/2003:22:05:33][11405.1024][-main-] Notice: serv: warmed up
[23/May/2003:22:05:33][11405.1024][-main-] Notice: socks: idle
[23/May/2003:22:05:33][11405.1024][-main-] Notice: sched: idle
[23/May/2003:22:05:33][11405.1024][-main-] Notice: binder: listen(18.42.2.64,80) = 15
[23/May/2003:22:05:33][11405.1024][-main-] Notice: nssock: listening on 18.42.2.64:80
[23/May/2003:22:05:33][11405.8201][-nssock-] Notice: nssock: starting
[23/May/2003:22:05:33][11405.8201][-nssock-] Notice: nssock: accepting connections

---------- end error.log -----------------------
Collapse
Posted by Jade Rubick on
My first guess would be that you have apache already installed, and it is listening on port 80. Try changing your config file to listen on port 8000, restart aolserver, and connect to your ip with :8000 at the end of it.
Collapse
Posted by Jade Rubick on
Closing your pre tag
Collapse
Posted by Naveen Agnihotri on
There is no apache process running, and AOLserver is indeed able to bind to port 80, as shown by the error log (although I don't know what a return value of 15 from the binder means). I've tried port 8000 also, with the same result. So it is AOLserver that is returning the empty page. I strongly suspect that the empty page is coming from somewhere within OpenACS, because when I searched for the string that is in the header of the source of the empty page, it produced lots of matches in the OpenACS tree. Of course, some other process could be using the identical header string, but it is unlikely that it would be able to respond to a request to port 80.

Also, I was initially using Postgres 7.3.2, and have now tried 7.2.3 (had to recompile AOLserver), but with the same result.

When I try to connect to the server from the command line by telnetting to port 80, I don't get the usual (empty) prompt, where the server waits for me to enter a GET request. Instead, the server throws me an error right away:

$ telnet 18.42.2.64 80
Trying 18.42.2.64...
Connected to 18.42.2.64.
Escape character is '^]'.
HTTP/1.0 400 Bad Request
Content-Type: text/html; charset=iso-8859-1
MIME-Version: 1.0
Date: Sat, 24 May 2003 17:58:55 GMT
Server: AOLserver/3.3.1+ad13
Content-Length: 541
Connection: close

<!DOCTYPE HTML PUBLIC "-//IETF//DTD HTML 2.0//EN">
<HTML>
<HEAD>
<TITLE>Invalid Request</TITLE>
</HEAD>
<BODY>
<H2>Invalid Request</H2>
The HTTP request presented by your browser is invalid.<P>
Invalid HTTP request
<P ALIGN=RIGHT><SMALL><I>AOLserver/3.3.1+ad13 on http://someaddr.mit.edu</I></SMALL></P>

<BODY><HTML>
Connection closed by foreign host.

Don't know if this helps.

Thanks!

Collapse
Posted by Don Baccus on
Very strange.  The log file in your first post is fine, it is listening on port 80 no problem.

The telnet result is very bizarre, it implies that a packet's been sent to the web server even though you've not typed anything in.  You're sure you don't have any proxy or other interfering software running?  What happens if you listen on localhost instead of your external IP?

Collapse
Posted by Naveen Agnihotri on
After some hunting, it is now clear that I'm suffering from an AOLserver issue. I'll be posting this on the AOLserver website, but thought I'd post this here as well.

In my first post, I'd said that the AOLserver installation went fine. Well, actually, it didn't. If I start AOLserver with the provided sample-config.tcl, it shows the normal "Welcome to AOLserver" page on port 8000. I can reload the page any number of times and it always shows the proper page. However, if I telnet to port 8000, it gives the same "Invalid Request" error I talked about above. From that point on, its behavior from a browser becomes erratic -- sometimes it shows the right page, and sometimes it gives the error. After a little while of reloading the page, it degenerates to always showing the error all the time. I've tried four different browsers, with varying but similar results.

I've tried AOlserver 3.3+ad13 and 3.4.2 (both from source), and also the debian package for 3.4.2 (which, incidentally, doesn't work at all). This machine is running debian on a PA-RISC processor. I would've thought that debian is debian is debian, but maybe not.

Boohoo.

Collapse
Posted by Naveen Agnihotri on
In response to Don above, it makes no difference which machine I try the telnet (or browser request) from: the results are the same when trying from the same machine that is running the AOLserver or from a different machine.