Forum OpenACS Q&A: Response to VMWare + W2K + ARGHHH!!
Hi Reuven:
It seems the postgres database is accessible since I can log in as system/changeme and change the system user password, But I can not
register new user.
I set Verbose to on and this is the log file
Thank you
Thank you
[28/Jul/2002:00:47:43][1084.2051][-sched-] Notice: Running scheduled proc search_indexer...
[28/Jul/2002:00:47:43][1084.2051][-sched-] Notice: Querying '
select package_id from apm_packages where package_key = 'search';'
[28/Jul/2002:00:47:43][1084.2051][-sched-] Notice: dbinit: sql(localhost::openacs4): '
select package_id from apm_packages where package_key = 'search'
'
[28/Jul/2002:00:47:43][1084.2051][-sched-] Warning: APM: FtsEngineDriver does not exist
[28/Jul/2002:00:47:43][1084.2051][-sched-] Notice: Querying '
select object_id, date, event
from search_observer_queue
order by date asc;'
[28/Jul/2002:00:47:43][1084.2051][-sched-] Notice: dbinit: sql(localhost::openacs4): '
select object_id, date, event
from search_observer_queue
order by date asc
'
[28/Jul/2002:00:47:43][1084.2051][-sched-] Notice: Done running scheduled proc search_indexer.
[28/Jul/2002:00:48:02][1084.4101][-conn0-] Notice: Querying '
select node_id
from host_node_map
where host = '192.168.111.134';'
[28/Jul/2002:00:48:02][1084.4101][-conn0-] Notice: dbinit: sql(localhost::openacs4): '
select node_id
from host_node_map
where host = '192.168.111.134'
'
[28/Jul/2002:00:48:02][1084.4101][-conn0-] Notice: Querying 'select sec_id_seq.nextval;'
[28/Jul/2002:00:48:02][1084.4101][-conn0-] Notice: dbinit: sql(localhost::openacs4): 'select sec_id_seq.nextval'
[28/Jul/2002:00:48:02][1084.4101][-conn0-] Notice: Security: 1027831682 sec_generate_session_id_cookie setting 30001, 0.
[28/Jul/2002:00:48:02][1084.4101][-conn0-] Notice: random: generating 1 seeds
[28/Jul/2002:00:48:02][1084.4101][-conn0-] Notice: Querying '
select count(*)
from dual
where 't' = acs_permission__permission_p('2403', '0', 'read');'
[28/Jul/2002:00:48:02][1084.4101][-conn0-] Notice: dbinit: sql(localhost::openacs4): '
select count(*)
from dual
where 't' = acs_permission__permission_p('2403', '0', 'read')
'
[28/Jul/2002:00:48:03][1084.4101][-conn0-] Warning: APM: PersistentLoginDefaultP does not exist
[28/Jul/2002:00:48:03][1084.4101][-conn0-] Notice: Querying '
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(NULL, acs__magic_object_id('the_public')),
'read') = 't';'
[28/Jul/2002:00:48:03][1084.4101][-conn0-] Notice: dbinit: sql(localhost::openacs4): '
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(NULL, acs__magic_object_id('the_public')),
'read') = 't'
'
[28/Jul/2002:00:48:03][1084.6150][-keepalive-] Notice: keepalive: keepalive thread starting
192.168.111.1 - - [28/Jul/2002:00:48:03 -0400] "GET / HTTP/1.1" 200 8568 "" "Mozilla/5.0 (Windows; U; Windows NT 5.0; en-US; rv:1.0.0) Gecko/20020530"
[28/Jul/2002:00:48:13][1084.2051][-sched-] Notice: Running scheduled proc search_indexer...
[28/Jul/2002:00:48:13][1084.2051][-sched-] Warning: APM: FtsEngineDriver does not exist
[28/Jul/2002:00:48:13][1084.2051][-sched-] Notice: Querying '
select object_id, date, event
from search_observer_queue
order by date asc;'
[28/Jul/2002:00:48:13][1084.2051][-sched-] Notice: dbinit: sql(localhost::openacs4): '
select object_id, date, event
from search_observer_queue
order by date asc
'
[28/Jul/2002:00:48:13][1084.2051][-sched-] Notice: Done running scheduled proc search_indexer.
[28/Jul/2002:00:48:43][1084.2051][-sched-] Notice: Running scheduled proc search_indexer...
[28/Jul/2002:00:48:43][1084.2051][-sched-] Warning: APM: FtsEngineDriver does not exist
[28/Jul/2002:00:48:43][1084.2051][-sched-] Notice: Querying '
select object_id, date, event
from search_observer_queue
order by date asc;'
[28/Jul/2002:00:48:43][1084.2051][-sched-] Notice: dbinit: sql(localhost::openacs4): '
select object_id, date, event
from search_observer_queue
order by date asc
'
[28/Jul/2002:00:48:43][1084.2051][-sched-] Notice: Done running scheduled proc search_indexer.
[28/Jul/2002:00:48:54][1084.4101][-conn0-] Notice: Querying '
select node_id
from host_node_map
where host = '192.168.111.134';'
[28/Jul/2002:00:48:54][1084.4101][-conn0-] Notice: dbinit: sql(localhost::openacs4): '
select node_id
from host_node_map
where host = '192.168.111.134'
'
[28/Jul/2002:00:48:54][1084.4101][-conn0-] Notice: Querying '
select count(*)
from dual
where 't' = acs_permission__permission_p('2403', '0', 'read');'
[28/Jul/2002:00:48:54][1084.4101][-conn0-] Notice: dbinit: sql(localhost::openacs4): '
select count(*)
from dual
where 't' = acs_permission__permission_p('2403', '0', 'read')
'
[28/Jul/2002:00:48:54][1084.4101][-conn0-] Notice: ns_getform using encoding iso8859-1 for charset iso-8859-1
[28/Jul/2002:00:48:54][1084.4101][-conn0-] Notice: Querying '
select user_id, member_state, email_verified_p
from cc_users
where email = lower('hh@hknet.com');'
[28/Jul/2002:00:48:54][1084.4101][-conn0-] Notice: dbinit: sql(localhost::openacs4): '
select user_id, member_state, email_verified_p
from cc_users
where email = lower('hh@hknet.com')
'
192.168.111.1 - - [28/Jul/2002:00:48:54 -0400] "POST /register/user-login HTTP/1.1" 302 386 "http://192.168.111.134:8000/" "Mozilla/5.0 (Windows; U; Windows NT 5.0; en-US; rv:1.0.0) Gecko/20020530"
[28/Jul/2002:00:48:54][1084.4101][-conn0-] Notice: Querying '
select node_id
from host_node_map
where host = '192.168.111.134';'
[28/Jul/2002:00:48:54][1084.4101][-conn0-] Notice: dbinit: sql(localhost::openacs4): '
select node_id
from host_node_map
where host = '192.168.111.134'
'
[28/Jul/2002:00:48:54][1084.4101][-conn0-] Notice: Querying '
select count(*)
from dual
where 't' = acs_permission__permission_p('2403', '0', 'read');'
[28/Jul/2002:00:48:55][1084.4101][-conn0-] Notice: dbinit: sql(localhost::openacs4): '
select count(*)
from dual
where 't' = acs_permission__permission_p('2403', '0', 'read')
'
[28/Jul/2002:00:48:55][1084.4101][-conn0-] Notice: ns_getform using encoding iso8859-1 for charset iso-8859-1
[28/Jul/2002:00:48:55][1084.4101][-conn0-] Notice: Querying ''
[28/Jul/2002:00:48:55][1084.4101][-conn0-] Error: Ns_PgExec: result status: 0 message:
[28/Jul/2002:00:48:55][1084.4101][-conn0-] Notice: dbinit: sql(localhost::openacs4): ''
[28/Jul/2002:00:48:55][1084.4101][-conn0-] Notice: RP (295.183 ms): error in rp_handler: serving GET /register/user-new?email=hh%40hknet%2ecom&persistent%5fcookie%5fp=1&return%5furl=%2f
ad_url "/register/user-new" maps to file "/var/lib/aolserver/servers/openacs4/packages/acs-subsite/www/register/user-new.adp"
errmsg is Database operation "0or1row" failed (exception NSDB, "Query was not a statement returning rows.")
[28/Jul/2002:00:48:55][1084.4101][-conn0-] Notice: Querying '
select count(*)
from dual
where 't' = acs_permission__permission_p('2403', '0', 'admin');'
[28/Jul/2002:00:48:55][1084.4101][-conn0-] Notice: dbinit: sql(localhost::openacs4): '
select count(*)
from dual
where 't' = acs_permission__permission_p('2403', '0', 'admin')
'
[28/Jul/2002:00:48:55][1084.4101][-conn0-] Error: GET /register/user-new email=hh%40hknet%2ecom&persistent%5fcookie%5fp=1&return%5furl=%2f Database operation "0or1row" failed (exception NSDB, "Query was not a statement returning rows.")
while executing
"ns_pg_bind 0or1row nsdb0 {}"
("uplevel" body line 1)
invoked from within
"uplevel $ulevel [list ns_pg_bind $type $db $sql"
invoked from within
"db_exec 0or1row $db $full_statement_name $sql"
invoked from within
"set selection [db_exec 0or1row $db $full_statement_name $sql]"
("uplevel" body line 2)
invoked from within
"uplevel 1 $code_block "
invoked from within
"db_with_handle db {
set selection [db_exec 0or1row $db $full_statement_name $sql]
}"
(procedure "db_0or1row" line 22)
invoked from within
"db_0or1row find_person "" "
("uplevel" body line 1)
(procedure "code::tcl::/var/lib/aolserver/servers/openacs4/packages/acs-..." line 1)
invoked from within
"code::tcl::$__adp_stub"
invoked from within
"if { [file exists $__adp_stub.tcl] } {
# ensure that data source preparation procedure exists and is up-to-date
adp_init tcl $__adp_stub
..."
("uplevel" body line 3)
invoked from within
"uplevel {
if { [file exists $__adp_stub.tcl] } {
# ensure that data source preparation procedure exists and is up-to-date
adp_init t..."
(procedure "adp_prepare" line 3)
invoked from within
"adp_prepare "
(procedure "template::adp_parse" line 30)
invoked from within
"template::adp_parse [file root [ad_conn file]] {}"
(procedure "adp_parse_ad_conn_file" line 7)
invoked from within
"$handler"
("uplevel" body line 2)
invoked from within
"uplevel $code"
invoked from within
"ad_try {
$handler
} ad_script_abort val {
# do nothing
}"
invoked from within
"rp_serve_concrete_file [ad_conn file]"
(procedure "rp_serve_abstract_file" line 60)
invoked from within
"rp_serve_abstract_file "$root/$path""
("uplevel" body line 2)
invoked from within
"uplevel $code"
invoked from within
"ad_try {
rp_serve_abstract_file "$root/$path"
set tcl_url2file([ad_conn url]) [ad_conn file]
set tcl_url2path_info([ad_conn url]) [ad_conn path_inf..."
192.168.111.1 - - [28/Jul/2002:00:48:55 -0400] "GET /register/user-new?email=hh%40hknet%2ecom&persistent%5fcookie%5fp=1&return%5furl=%2f HTTP/1.1" 200 387 "http://192.168.111.134:8000/" "Mozilla/5.0 (Windows; U; Windows NT 5.0; en-US; rv:1.0.0) Gecko/20020530"
[28/Jul/2002:00:49:13][1084.2051][-sched-] Notice: Running scheduled proc search_indexer...
[28/Jul/2002:00:49:13][1084.2051][-sched-] Warning: APM: FtsEngineDriver does not exist
[28/Jul/2002:00:49:13][1084.2051][-sched-] Notice: Querying '
select object_id, date, event
from search_observer_queue
order by date asc;'
[28/Jul/2002:00:49:13][1084.2051][-sched-] Notice: dbinit: sql(localhost::openacs4): '
select object_id, date, event
from search_observer_queue
order by date asc
'
[28/Jul/2002:00:49:13][1084.2051][-sched-] Notice: Done running scheduled proc search_indexer.
[28/Jul/2002:00:49:43][1084.2051][-sched-] Notice: Running scheduled proc search_indexer...
[28/Jul/2002:00:49:43][1084.2051][-sched-] Warning: APM: FtsEngineDriver does not exist
[28/Jul/2002:00:49:43][1084.2051][-sched-] Notice: Querying '
select object_id, date, event
from search_observer_queue
order by date asc;'
[28/Jul/2002:00:49:43][1084.2051][-sched-] Notice: dbinit: sql(localhost::openacs4): '
select object_id, date, event
from search_observer_queue
order by date asc
'
[28/Jul/2002:00:49:43][1084.2051][-sched-] Notice: Done running scheduled proc search_indexer.
[28/Jul/2002:00:50:13][1084.2051][-sched-] Notice: Running scheduled proc search_indexer...
[28/Jul/2002:00:50:13][1084.2051][-sched-] Warning: APM: FtsEngineDriver does not exist
[28/Jul/2002:00:50:13][1084.2051][-sched-] Notice: Querying '
select object_id, date, event
from search_observer_queue
order by date asc;'
[28/Jul/2002:00:50:13][1084.2051][-sched-] Notice: dbinit: sql(localhost::openacs4): '
select object_id, date, event
from search_observer_queue
order by date asc
'
[28/Jul/2002:00:50:13][1084.2051][-sched-] Notice: Done running scheduled proc search_indexer.
[28/Jul/2002:00:50:43][1084.2051][-sched-] Notice: Running scheduled proc search_indexer...
[28/Jul/2002:00:50:43][1084.2051][-sched-] Warning: APM: FtsEngineDriver does not exist
[28/Jul/2002:00:50:43][1084.2051][-sched-] Notice: Querying '
select object_id, date, event
from search_observer_queue
order by date asc;'
[28/Jul/2002:00:50:43][1084.2051][-sched-] Notice: dbinit: sql(localhost::openacs4): '
select object_id, date, event
from search_observer_queue
order by date asc
'
[28/Jul/2002:00:50:43][1084.2051][-sched-] Notice: Done running scheduled proc search_indexer.
[28/Jul/2002:00:51:13][1084.2051][-sched-] Notice: Running scheduled proc search_indexer...
[28/Jul/2002:00:51:13][1084.2051][-sched-] Warning: APM: FtsEngineDriver does not exist
[28/Jul/2002:00:51:13][1084.2051][-sched-] Notice: Querying '
select object_id, date, event
from search_observer_queue
order by date asc;'
[28/Jul/2002:00:51:13][1084.2051][-sched-] Notice: dbinit: sql(localhost::openacs4): '
select object_id, date, event
from search_observer_queue
order by date asc
'
[28/Jul/2002:00:51:13][1084.2051][-sched-] Notice: Done running scheduled proc search_indexer.
Hi Damon et all,
I am following the steps of Damon - I learned from his problems and passed the first barrier through the change in postgresql.conf - and I am now stuck with the 'Query was not a statement returning rows' error, as described by Damon.
Don't know if he solved it - but it would be very helpful if anyone has an idea about how to overcome this and get the OpenACS over VMware finally working...
Cheers