[OPEN-ILS-DEV] Fieldmapper problems

[Bob Wicksall]

Can anyone see a reason why the following doesn't work on my test server (1.6.X):

  http://evergreen-test.owwl.org/osrf-gateway-v1?service=open-ils.actor&method=opensrf.open-ils.system.fieldmapper

When run in a browser it times out after 60 seconds and returns empty results.  I also tried the following from srfsh:

  request open-ils.actor opensrf.open-ils.system.fieldmapper 

This one times out after 120 seconds.

This all works find on our live server and my 2.1 test server.

Here is some debug info:

2011-12-07 17:09:35 s234 gateway: [DEBG:3066:./osrf_json_gateway.c:121:] osrf gateway: entered request handler
2011-12-07 17:09:35 s234 osrf_json_gw: [DEBG:3066:./osrf_json_gateway.c:146:] osrf gateway: parsing URL params
2011-12-07 17:09:35 s234 osrf_json_gw: [DEBG:3066:apachetools.c:75:] parsing URL params from post/get request data: service=open-ils.actor&method=opensrf.open-ils.system.fieldmapper&locale=en-US
2011-12-07 17:09:35 s234 osrf_json_gw: [DEBG:3066:apachetools.c:95:] parsed URL params service=open-ils.actor
2011-12-07 17:09:35 s234 osrf_json_gw: [DEBG:3066:apachetools.c:95:] parsed URL params method=opensrf.open-ils.system.fieldmapper
2011-12-07 17:09:35 s234 osrf_json_gw: [DEBG:3066:apachetools.c:95:] parsed URL params locale=en-US
2011-12-07 17:09:35 s234 osrf_json_gw: [DEBG:3066:apachetools.c:109:] Apache tools parsed 3 params key/values
2011-12-07 17:09:35 s234 osrf_json_gw: [DEBG:3066:apachetools.c:147:] Fetching first URL value for key locale
2011-12-07 17:09:35 s234 osrf_json_gw: [DEBG:3066:apachetools.c:147:] Fetching first URL value for key service
2011-12-07 17:09:35 s234 osrf_json_gw: [DEBG:3066:apachetools.c:147:] Fetching first URL value for key method
2011-12-07 17:09:35 s234 osrf_json_gw: [DEBG:3066:apachetools.c:147:] Fetching first URL value for key format
2011-12-07 17:09:35 s234 osrf_json_gw: [DEBG:3066:apachetools.c:147:] Fetching first URL value for key input_format
2011-12-07 17:09:35 s234 osrf_json_gw: [DEBG:3066:apachetools.c:147:] Fetching first URL value for key api_level
2011-12-07 17:09:35 s234 osrf_json_gw: [DEBG:3066:apachetools.c:132:] Fetching URL values for key param
2011-12-07 17:09:35 s234 osrf_json_gw: [DEBG:3066:apachetools.c:147:] Fetching first URL value for key timeout
2011-12-07 17:09:35 s234 osrf_json_gw: [DEBG:3066:osrf_json_object.c:307:] Objects malloc()'d: 141, Reusable objects captured: 2000, Objects reused: 1969, Current List Length: 31
2011-12-07 17:09:35 s234 osrf_json_gw: [DEBG:3066:osrf_app_session.c:486:] open-ils.actor session is stateless
2011-12-07 17:09:35 s234 osrf_json_gw: [DEBG:3066:osrf_app_session.c:497:] Building a new client session with id [open-ils.actor] [1323295775.011243.13232957753066]
2011-12-07 17:09:35 s234 osrf_json_gw: [DEBG:3066:osrf_message.c:170:132329451830660] passing non-array to osrf_message_set_params(), fixing...
2011-12-07 17:09:35 s234 osrf_json_gw: [DEBG:3066:osrf_app_session.c:1044:132329451830660] AppSession in queue_wait with timeout 0
2011-12-07 17:09:35 s234 osrf_json_gw: [DEBG:3066:osrf_app_session.c:759:132329451830660] App Session [open-ils.actor] [1323295775.011243.13232957753066] resetting remote id to router at public.localhost/open-ils.actor
2011-12-07 17:09:35 s234 osrf_json_gw: [INFO:3066:osrf_app_session.c:991:132329451830660] [open-ils.actor] sent 185 bytes of data to router at public.localhost/open-ils.actor
2011-12-07 17:09:35 s234 osrf_json_gw: [DEBG:3066:osrf_app_session.c:994:132329451830660] Sent: [{"__c":"osrfMessage","__p":{"threadTrace":"1","locale":"en-US","type":"REQUEST","payload":{"__c":"osrfMethod","__p":{"method":"opensrf.open-ils.system.fieldmapper","params":[null]}}}}]
2011-12-07 17:09:35 s234 osrf_json_gw: [DEBG:3066:osrf_app_session.c:714:132329451830660] Pushing [1] onto request queue for session [open-ils.actor] [1323295775.011243.13232957753066]
2011-12-07 17:09:35 s234 osrf_json_gw: [INFO:3066:./osrf_json_gateway.c:302:132329451830660] [10.199.30.161] [] [en-US] open-ils.actor opensrf.open-ils.system.fieldmapper
2011-12-07 17:09:35 s234 osrf_json_gw: [DEBG:3066:osrf_app_session.c:275:132329451830660] In app_request receive with remaining time [60]
2011-12-07 17:09:35 s234 osrf_json_gw: [DEBG:3066:osrf_app_session.c:1044:132329451830660] AppSession in queue_wait with timeout 0
2011-12-07 17:09:35 s234 osrf_json_gw: [DEBG:3066:osrf_app_session.c:1044:132329451830660] AppSession in queue_wait with timeout 60
2011-12-07 17:10:35 s234 osrf_json_gw: [DEBG:3066:osrf_app_session.c:275:132329451830660] In app_request receive with remaining time [0]
2011-12-07 17:10:35 s234 osrf_json_gw: [DEBG:3066:osrf_app_session.c:1044:132329451830660] AppSession in queue_wait with timeout 0
2011-12-07 17:10:35 s234 osrf_json_gw: [DEBG:3066:osrf_app_session.c:1044:132329451830660] AppSession in queue_wait with timeout 0
2011-12-07 17:10:35 s234 osrf_json_gw: [INFO:3066:osrf_app_session.c:336:132329451830660] Returning NULL from app_request_recv after timeout: opensrf.open-ils.system.fieldmapper [null]
2011-12-07 17:10:35 s234 osrf_json_gw: [DEBG:3066:./osrf_json_gateway.c:356:132329451830660] gateway request took 60.018291 seconds
2011-12-07 17:10:35 s234 osrf_json_gw: [DEBG:3066:osrf_app_session.c:1063:132329451830660] AppSession [open-ils.actor] [1323295775.011243.13232957753066] destroying self and deleting requests
2011-12-07 17:10:35 s234 osrf_json_gw: [INFO:3066:./osrf_json_gateway.c:409:132329451830660] Completed processing service=open-ils.actor, method=opensrf.open-ils.system.fieldmapper
2011-12-07 17:10:35 s234 osrf_json_gw: [DEBG:3066:./osrf_json_gateway.c:417:132329451830660] Gateway served 1 requests


Bob Wicksall
Systems Administrator

Pioneer Library System
2557 State Rt. 21
Canandaigua, New York  14424

Voice: (585) 394-8260
Fax: (585) 394-1935