[OPEN-ILS-DEV] Stuck on Evergreen client authentication

Dale Arntson arnt at uchicago.edu
Mon Jun 23 02:45:10 EDT 2008 

Hi all,

I am still having problems with the xul client authenticating to my 
Redhat installation. Memcached seems to be functioning ok. At least, I 
can telnet to it. I turned up the loglevel to 'debug' in 
opensrf_core.xml to generate better logs. However, I am still not sure 
where else to look. I could still use some help in interpreting them.

I am attaching the console output of the client, and the tail of the 
osrfsys.log.

Thanks,

-dale
-------------- next part --------------

-----------------------------------------
entering util/file.js
exiting util/file.js
file: /home/arnt/.openils/open_ils_staff_client/v6atmkym.default/chrome/ws_info
-----------------------------------------
1214197211246	delta = 0	D_ERROR
/home/arnt/.openils/open_ils_staff_client/v6atmkym.default/chrome/ws_info
util.file.get_content(): File does not exist.
-----------------------------------------
-----------------------------------------
1214197211246	delta = 0	D_ERROR
/home/arnt/.openils/open_ils_staff_client/v6atmkym.default/chrome/ws_info
util.file.get_object(): File does not exist.
-----------------------------------------
-----------------------------------------
1214197211247	delta = 5	D_DATA_STASH
stashing ws_info : [object Object] = {}

-----------------------------------------
entering util/controller.js
exiting util/controller.js
entering auth/session.js
exiting auth/session.js
entering util/widgets.js
exiting util/widgets.js
file: /home/arnt/.openils/open_ils_staff_client/v6atmkym.default/chrome/last_ws_server
file: /opt/openils/src/evergreen/Evergreen-ILS-1.2.2.0/Open-ILS/xul/staff_client/build/chrome/open_ils_staff_client/content/conf/ws_info
exiting main_init()
server url = http://libopen
version url = http://libopen/xul/rel_1_2_2_0/server/
-----------------------------------------
1214197241011	delta = 29812	D_AUTH
login with admin and open-ils at libopen

-----------------------------------------
-----------------------------------------
1214197241011	delta = 0	D_AUTH
auth.controller.session.on_init = auth.controller.on_login

-----------------------------------------
-----------------------------------------
1214197241011	delta = 29759	D_SES
_request open-ils.auth open-ils.auth.authenticate.init "admin"
override_params = undefined
_params = undefined
Result #1 synced
-----------------------------------------
-----------------------------------------
1214197241020	delta = 9	D_SES_RESULT
synced result #1

"09c08f9b4e24ec5b73a70652915d24bb"

Original Request:

request open-ils.auth open-ils.auth.authenticate.init "admin"
-----------------------------------------
-----------------------------------------
1214197241030	delta = 10	D_SES
_request open-ils.auth open-ils.auth.authenticate.complete 
{
	"username":"admin",
	"password":"85e5c15ab3450908beaf51f73a3cbd25",
	"type":"temp"
}

override_params = undefined
_params = [object Object]
Result #2 synced
-----------------------------------------
! NETWORK FAILURE.  HTTP STATUS = <unknown>
service=open-ils.auth&method=open-ils.auth.authenticate.complete&param=%7B%22username%22%3A%22admin%22%2C%22password%22%3A%2285e5c15ab3450908beaf51f73a3cbd25%22%2C%22type%22%3A%22temp%22%7D
-----------------------------------------
1214197244713	delta = 3683	D_SES_RESULT
synced result #2

null

Original Request:

request open-ils.auth open-ils.auth.authenticate.complete {"username":"admin","password":"85e5c15ab3450908beaf51f73a3cbd25","type":"temp"}
-----------------------------------------
! NETWORK FAILURE.  HTTP STATUS = <unknown>
service=open-ils.auth&method=open-ils.auth.authenticate.complete&param=%7B%22username%22%3A%22admin%22%2C%22password%22%3A%2285e5c15ab3450908beaf51f73a3cbd25%22%2C%22type%22%3A%22temp%22%7D
! NETWORK FAILURE.  HTTP STATUS = <unknown>
service=open-ils.auth&method=open-ils.auth.authenticate.complete&param=%7B%22username%22%3A%22admin%22%2C%22password%22%3A%2285e5c15ab3450908beaf51f73a3cbd25%22%2C%22type%22%3A%22temp%22%7D
! NETWORK FAILURE.  HTTP STATUS = <unknown>
service=open-ils.auth&method=open-ils.auth.authenticate.complete&param=%7B%22username%22%3A%22admin%22%2C%22password%22%3A%2285e5c15ab3450908beaf51f73a3cbd25%22%2C%22type%22%3A%22temp%22%7D
-----------------------------------------
1214197244714	delta = 1	D_SES_ERROR
Network/server failure.  Please check your Internet connection to undefined and choose Retry Network.  If you need to enter Offline Mode, choose Ignore Errors in this and subsequent dialogs.  If you believe this error is due to a bug in Evergreen and not network problems, please contact your helpdesk or friendly Evergreen admins, and give them this information:
method=open-ils.auth.authenticate.complete
params=[{"username":"admin","password":"85e5c15ab3450908beaf51f73a3cbd25","type":"temp"}]
THROWN:
Network Failure: status = <unknown>
service=open-ils.auth&method=open-ils.auth.authenticate.complete&param=%7B%22username%22%3A%22admin%22%2C%22password%22%3A%2285e5c15ab3450908beaf51f73a3cbd25%22%2C%22type%22%3A%22temp%22%7D
STATUS:
<unknown>
-----------------------------------------
yns_alert:
	s = Network/server failure.  Please check your Internet connection to undefined and choose Retry Network.  If you need to enter Offline Mode, choose Ignore Errors in this and subsequent dialogs.  If you believe this error is due to a bug in Evergreen and not network problems, please contact your helpdesk or friendly Evergreen admins, and give them this information:
method=open-ils.auth.authenticate.complete
params=[{"username":"admin","password":"85e5c15ab3450908beaf51f73a3cbd25","type":"temp"}]
THROWN:
Network Failure: status = <unknown>
service=open-ils.auth&method=open-ils.auth.authenticate.complete&param=%7B%22username%22%3A%22admin%22%2C%22password%22%3A%2285e5c15ab3450908beaf51f73a3cbd25%22%2C%22type%22%3A%22temp%22%7D
STATUS:
<unknown>
	title = Network Failure
	b1 = Retry Network
	b2 = Ignore Errors
	b3 = null
	c = Check here to confirm this message
url = [xpconnect wrapped nsIURL]
ALSA lib confmisc.c:769:(parse_card) cannot find card ''
ALSA lib conf.c:3510:(_snd_config_evaluate) function snd_func_card_driver returned error: No such device
ALSA lib confmisc.c:392:(snd_func_concat) error evaluating strings
ALSA lib conf.c:3510:(_snd_config_evaluate) function snd_func_concat returned error: No such device
ALSA lib confmisc.c:1251:(snd_func_refer) error evaluating name
ALSA lib conf.c:3510:(_snd_config_evaluate) function snd_func_refer returned error: No such device
ALSA lib conf.c:3982:(snd_config_expand) Evaluate error: No such device
ALSA lib pcm.c:2144:(snd_pcm_open_noupdate) Unknown PCM default
play_url(): [Exception... "Component returned failure code: 0x804b0012 (NS_ERROR_UNKNOWN_PROTOCOL) [nsISound.play]"  nsresult: "0x804b0012 (NS_ERROR_UNKNOWN_PROTOCOL)"  location: "JS frame :: chrome://open_ils_staff_client/content/main/JSAN.js :: anonymous :: line 249"  data: no]

-------------- next part --------------
/opt/openils/bin$ tail -200 ../var/log/osrfsys.log 
[2008-6-22 23:57:43] -e [INFO:29271:Inbound.pm:141:] open-ils.circ going into listen loop
[2008-6-22 23:57:43] -e [DEBG:29271:Inbound.pm:148:] Inbound listener calling process()
[2008-6-22 23:57:43] -e [INFO:29273:Inbound.pm:121:] open-ils.storage connecting to router router at libopen/router
[2008-6-22 23:57:43] -e [DEBG:29272:Client.pm:530:]  * evergreen at libopen/open-ils.actor_listener_at_libopen.uchicago.edu: Jabber authenticated and connected
[2008-6-22 23:57:43] -e [INFO:29272:Inbound.pm:121:] open-ils.actor connecting to router router at libopen/router
[2008-6-22 23:57:43] -e [INFO:29273:Inbound.pm:125:] open-ils.storage :routers connected
[2008-6-22 23:57:43] -e [INFO:29273:Inbound.pm:141:] open-ils.storage going into listen loop
[2008-6-22 23:57:43] -e [DEBG:29273:Inbound.pm:148:] Inbound listener calling process()
[2008-6-22 23:57:43] -e [INFO:29211:System.pm:446:]  * Starting Listener for open-ils.collections...
[2008-6-22 23:57:43] -e [INFO:29272:Inbound.pm:125:] open-ils.actor :routers connected
[2008-6-22 23:57:43] -e [INFO:29272:Inbound.pm:141:] open-ils.actor going into listen loop
[2008-6-22 23:57:43] -e [DEBG:29272:Inbound.pm:148:] Inbound listener calling process()
[2008-6-22 23:57:43] -e [INFO:29211:System.pm:446:]  * Starting Listener for open-ils.ingest...
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::actor::user_address
[2008-6-22 23:57:43] -e [INFO:29275:Client.pm:84:] Creating Client instance: libopen:5222, evergreen, open-ils.collections_listener_at_libopen.uchicago.edu
[2008-6-22 23:57:43] -e [INFO:29211:System.pm:446:]  * Starting Listener for open-ils.reporter...
[2008-6-22 23:57:43] -e [DEBG:29275:Client.pm:490:] evergreen at libopen/open-ils.collections_listener_at_libopen.uchicago.edu: 1 connect attempt to libopen:5222
[2008-6-22 23:57:43] -e [DEBG:29275:Client.pm:500:] Logging into jabber as evergreen at libopen/open-ils.collections_listener_at_libopen.uchicago.edu from OpenSRF::Transport::Listener
[2008-6-22 23:57:43] -e [INFO:29276:Client.pm:84:] Creating Client instance: libopen:5222, evergreen, open-ils.ingest_listener_at_libopen.uchicago.edu
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::authority::record_note
[2008-6-22 23:57:43] -e [INFO:29275:Client.pm:275:] timed_read() read 28 bytes of data
[2008-6-22 23:57:43] -e [DEBG:29275:Client.pm:530:]  * evergreen at libopen/open-ils.collections_listener_at_libopen.uchicago.edu: Jabber authenticated and connected
[2008-6-22 23:57:43] -e [INFO:29277:Client.pm:84:] Creating Client instance: libopen:5222, evergreen, open-ils.reporter_listener_at_libopen.uchicago.edu
[2008-6-22 23:57:43] -e [DEBG:29276:Client.pm:490:] evergreen at libopen/open-ils.ingest_listener_at_libopen.uchicago.edu: 1 connect attempt to libopen:5222
[2008-6-22 23:57:43] -e [INFO:29275:Inbound.pm:121:] open-ils.collections connecting to router router at libopen/router
[2008-6-22 23:57:43] -e [DEBG:29276:Client.pm:500:] Logging into jabber as evergreen at libopen/open-ils.ingest_listener_at_libopen.uchicago.edu from OpenSRF::Transport::Listener
[2008-6-22 23:57:43] -e [DEBG:29277:Client.pm:490:] evergreen at libopen/open-ils.reporter_listener_at_libopen.uchicago.edu: 1 connect attempt to libopen:5222
[2008-6-22 23:57:43] -e [DEBG:29277:Client.pm:500:] Logging into jabber as evergreen at libopen/open-ils.reporter_listener_at_libopen.uchicago.edu from OpenSRF::Transport::Listener
[2008-6-22 23:57:43] -e [INFO:29276:Client.pm:275:] timed_read() read 28 bytes of data
[2008-6-22 23:57:43] -e [DEBG:29276:Client.pm:530:]  * evergreen at libopen/open-ils.ingest_listener_at_libopen.uchicago.edu: Jabber authenticated and connected
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::container::call_number_bucket_item
[2008-6-22 23:57:43] -e [INFO:29274:Client.pm:84:] Creating Client instance: libopen:5222, evergreen, open-ils.penalty_listener_at_libopen.uchicago.edu
[2008-6-22 23:57:43] -e [INFO:29276:Inbound.pm:121:] open-ils.ingest connecting to router router at libopen/router
[2008-6-22 23:57:43] -e [INFO:29275:Inbound.pm:125:] open-ils.collections :routers connected
[2008-6-22 23:57:43] -e [INFO:29275:Inbound.pm:141:] open-ils.collections going into listen loop
[2008-6-22 23:57:43] -e [DEBG:29275:Inbound.pm:148:] Inbound listener calling process()
[2008-6-22 23:57:43] -e [DEBG:29274:Client.pm:490:] evergreen at libopen/open-ils.penalty_listener_at_libopen.uchicago.edu: 1 connect attempt to libopen:5222
[2008-6-22 23:57:43] -e [DEBG:29274:Client.pm:500:] Logging into jabber as evergreen at libopen/open-ils.penalty_listener_at_libopen.uchicago.edu from OpenSRF::Transport::Listener
[2008-6-22 23:57:43] -e [INFO:29276:Inbound.pm:125:] open-ils.ingest :routers connected
[2008-6-22 23:57:43] -e [INFO:29276:Inbound.pm:141:] open-ils.ingest going into listen loop
[2008-6-22 23:57:43] -e [DEBG:29276:Inbound.pm:148:] Inbound listener calling process()
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::action::hold_copy_map
[2008-6-22 23:57:43] -e [INFO:29277:Client.pm:275:] timed_read() read 28 bytes of data
[2008-6-22 23:57:43] -e [DEBG:29277:Client.pm:530:]  * evergreen at libopen/open-ils.reporter_listener_at_libopen.uchicago.edu: Jabber authenticated and connected
[2008-6-22 23:57:43] -e [INFO:29277:Inbound.pm:121:] open-ils.reporter connecting to router router at libopen/router
[2008-6-22 23:57:43] -e [INFO:29277:Inbound.pm:125:] open-ils.reporter :routers connected
[2008-6-22 23:57:43] -e [INFO:29277:Inbound.pm:141:] open-ils.reporter going into listen loop
[2008-6-22 23:57:43] -e [DEBG:29277:Inbound.pm:148:] Inbound listener calling process()
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::money::bnm_payment
[2008-6-22 23:57:43] -e [INFO:29274:Client.pm:275:] timed_read() read 28 bytes of data
[2008-6-22 23:57:43] -e [DEBG:29274:Client.pm:530:]  * evergreen at libopen/open-ils.penalty_listener_at_libopen.uchicago.edu: Jabber authenticated and connected
[2008-6-22 23:57:43] -e [INFO:29274:Inbound.pm:121:] open-ils.penalty connecting to router router at libopen/router
[2008-6-22 23:57:43] -e [INFO:29274:Inbound.pm:125:] open-ils.penalty :routers connected
[2008-6-22 23:57:43] -e [INFO:29274:Inbound.pm:141:] open-ils.penalty going into listen loop
[2008-6-22 23:57:43] -e [DEBG:29274:Inbound.pm:148:] Inbound listener calling process()
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::config::item_type_map
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::action::survey
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::action::hold_request
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::container::call_number_bucket
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::reporter::template
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::asset::stat_cat
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::actor::card
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::reporter::legacy_cat2
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::actor::stat_cat
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::reporter::legacy_cat1
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::reporter::circulation_type
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::money::credit_payment
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::actor::org_unit_proximity
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::reporter::classic_current_circ
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::money::user_circulation_summary
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::biblio::record_note
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::config::bib_source
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::money::grocery
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::reporter::money::open_balance_by_circ_and_owning_lib
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::actor::stat_cat_entry
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::money::non_drawer_payment
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::money::work_payment
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::config::lit_form_map
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::reporter::money::open_circ_balance_by_usr_home_and_owning_lib
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::config::rules::recuring_fine
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::money::check_payment
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::asset::copy
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::reporter::user_demographic
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::reporter::report
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::action::survey_answer
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::actor::stat_cat_entry_user_map
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::metabib::title_field_entry
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::permission::grp_perm_map
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::container::copy_bucket
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::biblio::record_node
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::reporter::classic_item_list
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::reporter::classic_current_billing_summary
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::money::payment
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::money::collections_tracker
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::permission::usr_perm_map
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::reporter::money::open_circ_balance_by_owning_lib
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::reporter::simple_record
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::permission::usr_work_ou_map
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::action::hold_transit_copy
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::money::billing
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::config::rules::age_hold_protect
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::actor::workstation
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::config::identification_type
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::money::billable_transaction_summary
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::actor::user_setting
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::metabib::author_field_entry
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::action::in_house_use
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::action::survey_response
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::authority::record_entry
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::reporter::super_simple_record
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::config::audience_map
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::perm_ex
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::config::standing
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::money::open_user_summary
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::actor::org_unit::hours_of_operation
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::actor::org_unit::closed_date
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::money::open_billable_transaction_summary
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::config::rules::circ_duration
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::money::desk_payment
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::config::copy_status
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::actor::user_standing_penalty
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::asset::call_number_note
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::action::circulation
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::reporter::money::open_balance_by_usr_home_and_owning_lib
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::reporter::hold_request_record
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::container::biblio_record_entry_bucket
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::ex
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::asset::copy_location
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::action::hold_notification
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::metabib::virtual_record
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::asset::stat_cat_entry_copy_map
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::actor::org_address
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::actor::org_unit
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::actor::usr_org_unit_opt_in
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::money::goods_payment
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::container::user_bucket
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::metabib::series_field_entry
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::authority::full_rec
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::config::non_cataloged_type
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::actor::org_unit_type
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::money::billable_transaction
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::container::user_bucket_item
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::money::user_summary
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::action::open_circulation
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::reporter::template_folder
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::metabib::subject_field_entry
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::config::rules::max_fine
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::permission::grp_tree
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::reporter::money::open_circ_balance_by_circ_and_owning_lib
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::money::open_user_circulation_summary
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::action::non_cataloged_circulation
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::action::unfulfilled_hold_list
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::money::user_payment_summary
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::reporter::xact_paid_totals
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::container::biblio_record_entry_bucket_item
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::asset::stat_cat_entry
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::action::non_cat_in_house_use
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::reporter::money::open_balance_by_owning_lib
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::action::open_circ_count_by_circ_mod
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Publisher.pm:431:] Generating methods for Fieldmapper class Fieldmapper::permission::usr_grp_map
[2008-6-22 23:57:43] open-ils.storage [DEBG:29226:Storage.pm:51:] We seem to be OK...
[2008-6-22 23:57:43] open-ils.storage [INFO:29278:Client.pm:84:] Creating Client instance: libopen:5222, evergreen, open-ils.storage_drone_at_libopen.uchicago.edu
[2008-6-22 23:57:43] open-ils.storage [DEBG:29278:Client.pm:490:] evergreen at libopen/open-ils.storage_drone_at_libopen.uchicago.edu: 1 connect attempt to libopen:5222
[2008-6-22 23:57:43] open-ils.storage [DEBG:29278:Client.pm:500:] Logging into jabber as evergreen at libopen/open-ils.storage_drone_at_libopen.uchicago.edu from OpenSRF::Transport::PeerHandle
[2008-6-22 23:57:43] open-ils.storage [INFO:29278:Client.pm:275:] timed_read() read 28 bytes of data
[2008-6-22 23:57:43] open-ils.storage [DEBG:29278:Client.pm:530:]  * evergreen at libopen/open-ils.storage_drone_at_libopen.uchicago.edu: Jabber authenticated and connected
[2008-6-22 23:57:43] open-ils.storage [DEBG:29278:Storage.pm:56:] Running child_init for OpenILS::Application::Storage...
[2008-6-22 23:57:43] open-ils.storage [DEBG:29278:Storage.pm:60:] Calling the Driver child_init
[2008-6-22 23:57:43] open-ils.storage [DEBG:29278:CDBI.pm:27:] Creating ImaDBI Querys
[2008-6-22 23:57:43] open-ils.storage [DEBG:29278:CDBI.pm:48:] Calling Driver child_init
[2008-6-22 23:57:43] open-ils.storage [INFO:29278:Pg.pm:64:] Attempting to connect to evergreen at libopen.uchicago.edu
[2008-6-22 23:57:43] open-ils.storage [INFO:29278:Pg.pm:94:] Connected to MASTER db evergreen at libopen.uchicago.edu
[2008-6-22 23:57:43] open-ils.storage [DEBG:29278:Storage.pm:66:] Success initializing driver!
[2008-6-22 23:57:44] -e [INFO:29211:System.pm:446:]  * System is ready...
open-ils.auth 2008-06-23 00:00:41 [DEBG:29253:osrf_prefork.c:389:] Server received inbound data
open-ils.auth 2008-06-23 00:00:41 [DEBG:29253:osrf_prefork.c:400:] forker sending data to 29263
open-ils.auth 2008-06-23 00:00:41 [DEBG:29263:osrf_prefork.c:578:] Prefork child read 567 bytes of data
open-ils.auth 2008-06-23 00:00:41 [DEBG:29263:osrf_prefork.c:598:] Prefork child got a request.. processing..
open-ils.auth 2008-06-23 00:00:41 [DEBG:29263:osrf_stack.c:43:1214125323225140] Transport handler received new message 
from evergreen at libopen/1214125323_libopen.uchicago.edu_1214125323.485687_22514 to evergreen at libopen/open-ils.auth_listener_libopen.uchicago.edu_1214197063.039385_29253 with body 

[/*--S osrfMessage--*/{"threadTrace":"1","type":"REQUEST","payload":/*--S osrfMethod--*/{"method":"open-ils.auth.authenticate.init","params":["admin"]}/*--E osrfMethod--*/}/*--E osrfMessage--*/]

open-ils.auth 2008-06-23 00:00:41 [DEBG:29263:osrf_app_session.c:248:1214125323225140] Initing server session with session id 1214197241.014437.121419724122514, service open-ils.auth, and remote_id evergreen at libopen/1214125323_libopen.uchicago.edu_1214125323.485687_22514
open-ils.auth 2008-06-23 00:00:41 [DEBG:29263:osrf_stack.c:66:1214125323225140] Session [1214197241.014437.121419724122514] found or built
open-ils.auth 2008-06-23 00:00:41 [DEBG:29263:osrf_stack.c:73:1214125323225140] We received 1 messages from evergreen at libopen/1214125323_libopen.uchicago.edu_1214125323.485687_22514
open-ils.auth 2008-06-23 00:00:41 [DEBG:29263:osrf_stack.c:208:1214125323225140] Server received message of type 1
open-ils.auth 2008-06-23 00:00:41 [DEBG:29263:osrf_stack.c:229:1214125323225140] server passing message 1 to application handler for session 1214197241.014437.121419724122514
open-ils.auth 2008-06-23 00:00:41 [DEBG:29263:osrf_stack.c:123:1214125323225140] passing message 1 / session 1214197241.014437.121419724122514 to app handler
open-ils.auth 2008-06-23 00:00:41 [INFO:29263:oils_auth.c:85:1214125323225140] CALL:	open-ils.auth open-ils.auth.authenticate.init - ["admin"]
open-ils.auth 2008-06-23 00:00:41 [DEBG:29253:osrf_prefork.c:374:] Forker going into wait for data...
open-ils.auth 2008-06-23 00:00:41 [DEBG:29263:oils_auth.c:110:1214125323225140] oilsAuthInit(): has seed 09c08f9b4e24ec5b73a70652915d24bb and key oils_auth_admin
open-ils.auth 2008-06-23 00:00:41 [DEBG:29263:osrf_app_session.c:554:1214125323225140] AppSession in queue_wait with timeout 0
open-ils.auth 2008-06-23 00:00:41 [DEBG:29263:osrf_app_session.c:374:1214125323225140] App Session [open-ils.auth] [1214197241.014437.121419724122514] resetting remote id to evergreen at libopen/1214125323_libopen.uchicago.edu_1214125323.485687_22514
open-ils.auth 2008-06-23 00:00:41 [INFO:29263:osrf_app_session.c:522:1214125323225140] [open-ils.auth] sent 400 bytes of data to evergreen at libopen/1214125323_libopen.uchicago.edu_1214125323.485687_22514
open-ils.auth 2008-06-23 00:00:41 [DEBG:29263:osrf_app_session.c:525:1214125323225140] Sent: [/*--S osrfMessage--*/{"threadTrace":"1","type":"RESULT","payload":/*--S osrfResult--*/{"status":"OK","statusCode":"200","content":"09c08f9b4e24ec5b73a70652915d24bb"}/*--E osrfResult--*/}/*--E osrfMessage--*/,/*--S osrfMessage--*/{"threadTrace":"1","type":"STATUS","payload":/*--S osrfConnectStatus--*/{"status":"Request Complete","statusCode":"205"}/*--E osrfConnectStatus--*/}/*--E osrfMessage--*/]
open-ils.auth 2008-06-23 00:00:41 [DEBG:29263:osrf_application.c:335:1214125323225140] Postprocessing method open-ils.auth.authenticate.init with retcode 0
open-ils.auth 2008-06-23 00:00:41 [INFO:29263:osrf_stack.c:103:1214125323225140] Message processing duration 0.000748
open-ils.auth 2008-06-23 00:00:41 [DEBG:29263:osrf_stack.c:106:1214125323225140] after msg delete
open-ils.auth 2008-06-23 00:00:41 [DEBG:29263:osrf_app_session.c:570:1214125323225140] AppSession [open-ils.auth] [1214197241.014437.121419724122514] destroying self and deleting requests

More information about the Open-ils-dev mailing list