[OPEN-ILS-DEV] Trying out staff client with new install (long)

Tue May 23 15:04:37 EDT 2006

I think I am up to the same point that Don McMorris was at back at the 
end of March.  I am attempting to connect to the server with the staff 
client.  I setup the server to accept the beta.02 build id and the test 
server function of the staff client seems to work ok, Status is 200 : OK 
and Version is 200 : OK.

When I try and log in with admin/open-ils the staff client sends this to 
the server.

POST /gateway HTTP/1.1
service=open-ils.auth&method=open-ils.auth.authenticate.init&param=%22admin%22

And the server responds with
HTTP/1.1 200 OK
{"payload":["3f8c0fa178b1e77b93d1c77286cd69cc"], "status":200}

Then the client sends
service=open-ils.auth&method=open-ils.auth.authenticate.complete&param=%7B%22username%22%3A%22admin%22%2C%22password%22%3A%22a6f8d41ac7868a5cbad6ddffd6ddec6a%22%2C%22type%22%3A%22temp%22%7D

The server doesn't respond for about 60 seconds and then sends back
{"payload" : [], "status":200}

And the client hangs until it pops up a box with "Network failure. 
Please check your Internet connection to undefined and choose Retry 
Network, ... etc... give them this message 
"open-ils.auth.authenticate.complete".

I'm wondering how I can debug the auth service?  I can see that it is 
running,
debiantest1:~# ps aux |grep auth
opensrf  28533  0.0  0.5   3656  1472 pts/1    S    12:29   0:00 OpenSRF 
Listener [open-ils.auth]
opensrf  28542  0.0  0.6   3920  1704 pts/1    S    12:29   0:00 OpenSRF 
Drone [open-ils.auth]
....

But there is no log file in /openils/var/log/ for the auth service like 
there is for all the others.
And there is no pid file in /openils/var/pid like there is for
-rw-r--r-- 1 opensrf opensrf 6 2006-05-23 10:46 open-ils.actor_unix.pid
-rw-r--r-- 1 opensrf opensrf 6 2006-05-23 10:46 open-ils.cat_unix.pid
-rw-r--r-- 1 opensrf opensrf 6 2006-05-23 10:46 open-ils.circ_unix.pid
-rw-r--r-- 1 opensrf opensrf 6 2006-05-23 10:46 open-ils.penalty_unix.pid
-rw-r--r-- 1 opensrf opensrf 6 2006-05-23 10:46 open-ils.search_unix.pid
-rw-r--r-- 1 opensrf opensrf 6 2006-05-23 10:46 opoensrf.settings_unix.pid

And dito with the unix sockets.

I see these error messages in the terminal where I ran opensrv_all start.
[NOTICE at 1148408854.437340] mcm_server_connect():2302: Software caused 
connection abort
[NOTICE at 1148408854.438902] mcm_server_connect_next_avail():2333: 
Software caused connection abort
[WARN at 1148408854.438921] mcm_server_connect_next_avail():2338
[NOTICE at 1148409734.895610] mcm_server_connect_next_avail():2328

The posgres database is not being queried, could the problem be with 
open-ils.storage

 >>>> from osrfsys.log
open-ils.auth 2006-05-23 13:49:53 [DEBG:28545:osrf_app_session.c:554] 
AppSession in queue_wait with timeout 60
open-ils.auth 2006-05-23 13:49:53 [INT :28545:socket_bundle.c:472] 1 
active sockets after select()
open-ils.auth 2006-05-23 13:49:53 [INT :28545:socket_bundle.c:641] 28545 
: Received data at 1148410193.311210

open-ils.auth 2006-05-23 13:49:53 [INT :28545:socket_bundle.c:644] 
Socket 17 Read 486 bytes and data: <message 
to="client at 127.0.0.1/open-ils.auth_drone__28545" 
from="router at 127.0.0.1/open-ils.storage" router_from="" router_to="" 
router_class="" router_command=""><error type="cancel" 
code="501"/><thread>1148410193.296929.114841019328545</thread><body>[/*--S 
osrfMessage--*/{"threadTrace":"1","type":"REQUEST","payload":/*--S 
osrfMethod--*/{"method":"open-ils.storage.direct.actor.user.search.usrname.atomic","params":["admin"]}/*--E 
osrfMethod--*/}/*--E osrfMessage--*/]</body></message>
open-ils.auth 2006-05-23 13:49:53 [INFO:28545:transport_session.c:401] 
Received <error> message with type cancel and code 501
open-ils.auth 2006-05-23 13:49:53 [DEBG:28545:osrf_stack.c:15] Received 
message from transport code from router at 127.0.0.1/open-ils.storage
open-ils.auth 2006-05-23 13:49:53 [DEBG:28545:osrf_stack.c:32] Transport 
handler received new message
from router at 127.0.0.1/open-ils.storage to 
client at 127.0.0.1/open-ils.auth_drone__28545 with body

[/*--S 
osrfMessage--*/{"threadTrace":"1","type":"REQUEST","payload":/*--S 
osrfMethod--*/{"method":"open-ils.storage.direct.actor.user.search.usrname.atomic","params":["admin"]}/*--E 
osrfMethod--*/}/*--E osrfMessage--*/]

open-ils.auth 2006-05-23 13:49:53 [DEBG:28545:osrf_stack.c:62] We 
received 1 messages from router at 127.0.0.1/open-ils.storage
open-ils.auth 2006-05-23 13:49:53 [WARN:28545:osrf_stack.c:71]  !!! 
Received Jabber layer error message
open-ils.auth 2006-05-23 13:49:53 [WARN:28545:osrf_stack.c:81]  * Jabber 
Error is for top level remote id [router at 127.0.0.1/open-ils.storage], no 
one to send my message too!!!
open-ils.auth 2006-05-23 13:49:53 [DEBG:28545:osrf_stack.c:90] after msg 
delete
open-ils.auth 2006-05-23 13:49:53 [INT :28545:socket_bundle.c:472] 0 
active sockets after select()
open-ils.auth 2006-05-23 13:49:53 [INT :28545:socket_bundle.c:641] 28545 
: Received data at 1148410193.315189
 >>>>>>

Any ideas on what I should try next?
Thanks
Josh