rendered paste body[2008-12-22 14:57:38] /opensrf/bin/opensrf-perl.pl [INTL:7750:PeerConnection.pm:63:] Built Peer with
[2008-12-22 14:57:38] opensrf.settings [INFO:7755:UnixServer.pm:183:] UnixServer: min=5, max=15, min_spare=3 max_spare=5, max_req=1000, log_file=/opensrf//var/log/opensrf.settings_unix.log, port=/opensrf//var/lock/opensrf.settings_unix.sock, pid_file=/opensrf//var/run/opensrf.settings_unix.pid
[2008-12-22 14:57:38] /opensrf/bin/opensrf-perl.pl [INTL:7756:Inbound.pm:51:] Inbound as opensrf, opensrf, opensrf.settings_listener_at_localhost, private.localhost, 5222
[2008-12-22 14:57:38] opensrf.settings [INTL:7755:PeerConnection.pm:63:] Built Peer with
[2008-12-22 14:57:38] opensrf.settings [DEBG:7755:UnixServer.pm:208:] Setting application implementation for opensrf.settings
[2008-12-22 14:57:38] /opensrf/bin/opensrf-perl.pl [INFO:7756:Inbound.pm:96:] loading router info ARRAY(0x95167b8)
[2008-12-22 14:57:38] /opensrf/bin/opensrf-perl.pl [WARN:7756:Inbound.pm:117:] opensrf.settings: No routers defined
[2008-12-22 14:57:38] /opensrf/bin/opensrf-perl.pl [INFO:7756:Inbound.pm:124:] opensrf.settings going into listen loop
[2008-12-22 14:57:38] /opensrf/bin/opensrf-perl.pl [DEBG:7756:Inbound.pm:131:] Inbound listener calling process()
[2008-12-22 14:57:38] opensrf.settings [INTL:7757:PeerConnection.pm:63:] Built Peer with
[2008-12-22 14:57:38] opensrf.settings [INTL:7758:PeerConnection.pm:63:] Built Peer with
[2008-12-22 14:57:38] opensrf.settings [INTL:7757:UnixServer.pm:250:] PeerHandle Created from UnixServer child_init_hook
[2008-12-22 14:57:38] opensrf.settings [INTL:7759:PeerConnection.pm:63:] Built Peer with
[2008-12-22 14:57:38] opensrf.settings [INTL:7758:UnixServer.pm:250:] PeerHandle Created from UnixServer child_init_hook
[2008-12-22 14:57:38] opensrf.settings [INTL:7760:PeerConnection.pm:63:] Built Peer with
[2008-12-22 14:57:38] opensrf.settings [INTL:7759:UnixServer.pm:250:] PeerHandle Created from UnixServer child_init_hook
[2008-12-22 14:57:38] opensrf.settings [INTL:7761:PeerConnection.pm:63:] Built Peer with
[2008-12-22 14:57:38] opensrf.settings [INTL:7760:UnixServer.pm:250:] PeerHandle Created from UnixServer child_init_hook
[2008-12-22 14:57:38] opensrf.settings [INTL:7761:UnixServer.pm:250:] PeerHandle Created from UnixServer child_init_hook
[2008-12-22 14:57:41] /opensrf/bin/opensrf-perl.pl [INTL:7765:Inbound.pm:51:] Inbound as opensrf, opensrf, opensrf.persist_listener_at_localhost, private.localhost, 5222
[2008-12-22 14:57:41] opensrf.persist [INFO:7764:UnixServer.pm:183:] UnixServer: min=5, max=25, min_spare=2 max_spare=5, max_req=1000, log_file=/opensrf//var/log/opensrf.persist_unix.log, port=/opensrf//var/lock/opensrf.persist_unix.sock, pid_file=/opensrf//var/run/opensrf.persist_unix.pid
[2008-12-22 14:57:41] opensrf.persist [INTL:7764:PeerConnection.pm:63:] Built Peer with
opensrf 2008-12-22 14:57:41 [INFO:7766:osrf_system.c:415:] Bootstrapping system with domain private.localhost, port 5222, and unixpath (none)
opensrf 2008-12-22 14:57:41 [INT :7766:socket_bundle.c:61:] Adding socket node with fd 3
opensrf 2008-12-22 14:57:41 [INT :7766:socket_bundle.c:548:] 1 active sockets after select()
opensrf 2008-12-22 14:57:41 [INT :7766:socket_bundle.c:727:] 7766 : Received data at 1229975861.813375
opensrf 2008-12-22 14:57:41 [INT :7766:socket_bundle.c:731:] Socket 3 Read 161 bytes and data: <?xml version='1.0'?><stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='2212544267' from='private.localhost' xml:lang='en'>
opensrf 2008-12-22 14:57:41 [INT :7766:socket_bundle.c:548:] 1 active sockets after select()
opensrf 2008-12-22 14:57:41 [INT :7766:socket_bundle.c:727:] 7766 : Received data at 1229975861.816809
opensrf 2008-12-22 14:57:41 [INT :7766:socket_bundle.c:731:] Socket 3 Read 34 bytes and data: <iq type='result' id='123456789'/>
opensrf 2008-12-22 14:57:41 [DEBG:7766:osrf_app_session.c:282:] opensrf.settings session is stateless
opensrf 2008-12-22 14:57:41 [DEBG:7766:osrf_app_session.c:293:] Building a new client session with id [opensrf.settings] [1229975861.817569.12299758617766]
opensrf 2008-12-22 14:57:41 [DEBG:7766:osrf_app_session.c:639:] AppSession in queue_wait with timeout 0
opensrf 2008-12-22 14:57:41 [INT :7766:socket_bundle.c:548:] 0 active sockets after select()
opensrf 2008-12-22 14:57:41 [INT :7766:socket_bundle.c:727:] 7766 : Received data at 1229975861.818047
opensrf 2008-12-22 14:57:41 [DEBG:7766:osrf_app_session.c:456:] App Session [opensrf.settings] [1229975861.817569.12299758617766] resetting remote id to router@private.localhost/opensrf.settings
opensrf 2008-12-22 14:57:41 [INFO:7766:osrf_app_session.c:608:] [opensrf.settings] sent 189 bytes of data to router@private.localhost/opensrf.settings
opensrf 2008-12-22 14:57:41 [DEBG:7766:osrf_app_session.c:611:] Sent: [{"__c":"osrfMessage","__p":{"threadTrace":"1","locale":"en-US","type":"REQUEST","payload":{"__c":"osrfMethod","__p":{"method":"opensrf.settings.host_config.get","params":["localhost"]}}}}]
opensrf 2008-12-22 14:57:41 [DEBG:7766:osrf_app_session.c:426:] Pushing [1] onto request queue for session [opensrf.settings] [1229975861.817569.12299758617766]
opensrf 2008-12-22 14:57:41 [DEBG:7766:osrf_app_session.c:109:] In app_request receive with remaining time [60]
opensrf 2008-12-22 14:57:41 [DEBG:7766:osrf_app_session.c:639:] AppSession in queue_wait with timeout 0
opensrf 2008-12-22 14:57:41 [INT :7766:socket_bundle.c:548:] 0 active sockets after select()
opensrf 2008-12-22 14:57:41 [INT :7766:socket_bundle.c:727:] 7766 : Received data at 1229975861.832135
opensrf 2008-12-22 14:57:41 [INT :7766:socket_bundle.c:731:] Socket 3 Read 765 bytes and data: <message from='router@private.localhost/opensrf.settings' to='opensrf@private.localhost/settings_grabber_debianlenny_1229975861.807759_7766' type='error' router_from='' router_to='' router_class='' router_command='' osrf_xid=''><thread>1229975861.817569.12299758617766</thread><body>[{"__c":"osrfMessage","__p":{"threadTrace":"1","locale":"en-US","type":"REQUEST","payload":{"__c":"osrfMethod","__p":{"method":"opensrf.settings.host_config.get","params":["localhost"]}}}}]</body><error code='503' type='cancel'><service-unavailable xmlns='urn:ietf:params:xml:ns:xmpp-stanzas'/></error></message>
opensrf 2008-12-22 14:57:41 [INFO:7766:transport_session.c:436:] Received <error> message with type cancel and code 503
opensrf 2008-12-22 14:57:41 [DEBG:7766:osrf_stack.c:24:] Received message from transport code from router@private.localhost/opensrf.settings
opensrf 2008-12-22 14:57:41 [DEBG:7766:osrf_stack.c:51:] Transport handler received new message
from router@private.localhost/opensrf.settings to opensrf@private.localhost/settings_grabber_debianlenny_1229975861.807759_7766 with body
[{"__c":"osrfMessage","__p":{"threadTrace":"1","locale":"en-US","type":"REQUEST","payload":{"__c":"osrfMethod","__p":{"method":"opensrf.settings.host_config.get","params":["localhost"]}}}}]
opensrf 2008-12-22 14:57:41 [DEBG:7766:osrf_stack.c:84:] We received 1 messages from router@private.localhost/opensrf.settings
opensrf 2008-12-22 14:57:41 [WARN:7766:osrf_stack.c:95:] !!! Received Jabber layer error message
opensrf 2008-12-22 14:57:41 [WARN:7766:osrf_stack.c:105:] * Jabber Error is for top level remote id [router@private.localhost/opensrf.settings], no one to send my message to! Cutting request short...
opensrf 2008-12-22 14:57:41 [INFO:7766:osrf_stack.c:116:] Message processing duration 0.000330
opensrf 2008-12-22 14:57:41 [DEBG:7766:osrf_stack.c:119:] after msg delete
opensrf 2008-12-22 14:57:41 [INT :7766:socket_bundle.c:548:] 0 active sockets after select()
opensrf 2008-12-22 14:57:41 [INT :7766:socket_bundle.c:727:] 7766 : Received data at 1229975861.834427
opensrf 2008-12-22 14:57:41 [ERR :7766:osrf_app_session.c:113:] Transport error in recv()
opensrf 2008-12-22 14:57:41 [ERR :7766:osrf_settings.c:48:] No osrfMessage received from host localhost (timeout?)
opensrf 2008-12-22 14:57:41 [DEBG:7766:osrf_app_session.c:649:] AppSession [opensrf.settings] [1229975861.817569.12299758617766] destroying self and deleting requests
opensrf 2008-12-22 14:57:41 [ERR :7766:osrf_settings.c:67:] Unable to load config for host localhost
opensrf 2008-12-22 14:57:41 [INT :7766:socket_bundle.c:501:] Closing socket 3
opensrf 2008-12-22 14:57:41 [DEBG:7766:socket_bundle.c:394:] removing socket 3
opensrf 2008-12-22 14:57:41 [ERR :7766:osrf_system.c:105:] Unable to retrieve settings for host localhost from configuration file /opensrf//etc/opensrf_core.xml
opensrf 2008-12-22 14:57:41 [ERR :7766:opensrf.c:23:] Server Loop returned an error condition, exiting with -1
[2008-12-22 14:57:41] opensrf.persist [DEBG:7764:UnixServer.pm:208:] Setting application implementation for opensrf.persist
[2008-12-22 14:57:41] /opensrf/bin/opensrf-perl.pl [INFO:7765:Inbound.pm:96:] loading router info ARRAY(0x95167b8)
[2008-12-22 14:57:41] /opensrf/bin/opensrf-perl.pl [WARN:7765:Inbound.pm:117:] opensrf.persist: No routers defined
[2008-12-22 14:57:41] /opensrf/bin/opensrf-perl.pl [INFO:7765:Inbound.pm:124:] opensrf.persist going into listen loop
[2008-12-22 14:57:41] /opensrf/bin/opensrf-perl.pl [DEBG:7765:Inbound.pm:131:] Inbound listener calling process()
[2008-12-22 14:57:41] opensrf.persist [INTL:7774:PeerConnection.pm:63:] Built Peer with
[2008-12-22 14:57:42] opensrf.persist [INTL:7775:PeerConnection.pm:63:] Built Peer with
[2008-12-22 14:57:42] opensrf.persist [INTL:7774:UnixServer.pm:250:] PeerHandle Created from UnixServer child_init_hook
[2008-12-22 14:57:42] opensrf.persist [INTL:7775:UnixServer.pm:250:] PeerHandle Created from UnixServer child_init_hook
[2008-12-22 14:57:42] opensrf.persist [INTL:7776:PeerConnection.pm:63:] Built Peer with
[2008-12-22 14:57:42] opensrf.persist [INTL:7777:PeerConnection.pm:63:] Built Peer with
[2008-12-22 14:57:42] opensrf.persist [INTL:7776:UnixServer.pm:250:] PeerHandle Created from UnixServer child_init_hook
[2008-12-22 14:57:42] opensrf.persist [INTL:7777:UnixServer.pm:250:] PeerHandle Created from UnixServer child_init_hook
[2008-12-22 14:57:42] opensrf.persist [INTL:7778:PeerConnection.pm:63:] Built Peer with
[2008-12-22 14:57:42] opensrf.persist [INTL:7778:UnixServer.pm:250:] PeerHandle Created from UnixServer child_init_hook
[2008-12-22 14:57:48] /opensrf/bin/opensrf-perl.pl [INTL:7784:PeerConnection.pm:63:] Built Peer with
[2008-12-22 14:57:48] opensrf.persist [DEBG:7774:UnixServer.pm:259:] attempting to call child exit handler...
[2008-12-22 14:57:48] opensrf.persist [DEBG:7775:UnixServer.pm:259:] attempting to call child exit handler...
[2008-12-22 14:57:48] opensrf.persist [DEBG:7776:UnixServer.pm:259:] attempting to call child exit handler...
[2008-12-22 14:57:48] opensrf.persist [DEBG:7777:UnixServer.pm:259:] attempting to call child exit handler...
[2008-12-22 14:57:48] opensrf.persist [DEBG:7778:UnixServer.pm:259:] attempting to call child exit handler...
[2008-12-22 14:57:48] opensrf.settings [DEBG:7757:UnixServer.pm:259:] attempting to call child exit handler...
[2008-12-22 14:57:48] opensrf.settings [DEBG:7757:Settings.pm:8:] settings server child exiting...7757
[2008-12-22 14:57:48] opensrf.settings [DEBG:7758:UnixServer.pm:259:] attempting to call child exit handler...
[2008-12-22 14:57:48] opensrf.settings [DEBG:7758:Settings.pm:8:] settings server child exiting...7758
[2008-12-22 14:57:48] opensrf.settings [DEBG:7759:UnixServer.pm:259:] attempting to call child exit handler...
[2008-12-22 14:57:48] opensrf.settings [DEBG:7759:Settings.pm:8:] settings server child exiting...7759
[2008-12-22 14:57:48] opensrf.settings [DEBG:7760:UnixServer.pm:259:] attempting to call child exit handler...
[2008-12-22 14:57:48] opensrf.settings [DEBG:7760:Settings.pm:8:] settings server child exiting...7760
[2008-12-22 14:57:48] opensrf.settings [DEBG:7761:UnixServer.pm:259:] attempting to call child exit handler...
[2008-12-22 14:57:48] opensrf.settings [DEBG:7761:Settings.pm:8:] settings server child exiting...7761
[2008-12-22 14:57:50] /opensrf/bin/opensrf-perl.pl [INTL:7790:PeerConnection.pm:63:] Built Peer with
[2008-12-22 14:57:50] opensrf.settings [INFO:7795:UnixServer.pm:183:] UnixServer: min=5, max=15, min_spare=3 max_spare=5, max_req=1000, log_file=/opensrf//var/log/opensrf.settings_unix.log, port=/opensrf//var/lock/opensrf.settings_unix.sock, pid_file=/opensrf//var/run/opensrf.settings_unix.pid
[2008-12-22 14:57:50] /opensrf/bin/opensrf-perl.pl [INTL:7796:Inbound.pm:51:] Inbound as opensrf, opensrf, opensrf.settings_listener_at_localhost, private.localhost, 5222
[2008-12-22 14:57:50] opensrf.settings [INTL:7795:PeerConnection.pm:63:] Built Peer with
[2008-12-22 14:57:50] opensrf.settings [DEBG:7795:UnixServer.pm:208:] Setting application implementation for opensrf.settings
[2008-12-22 14:57:50] /opensrf/bin/opensrf-perl.pl [INFO:7796:Inbound.pm:96:] loading router info ARRAY(0x92b57b0)
[2008-12-22 14:57:50] /opensrf/bin/opensrf-perl.pl [WARN:7796:Inbound.pm:117:] opensrf.settings: No routers defined
[2008-12-22 14:57:50] /opensrf/bin/opensrf-perl.pl [INFO:7796:Inbound.pm:124:] opensrf.settings going into listen loop
[2008-12-22 14:57:50] /opensrf/bin/opensrf-perl.pl [DEBG:7796:Inbound.pm:131:] Inbound listener calling process()
[2008-12-22 14:57:50] opensrf.settings [INTL:7797:PeerConnection.pm:63:] Built Peer with
[2008-12-22 14:57:50] opensrf.settings [INTL:7798:PeerConnection.pm:63:] Built Peer with
[2008-12-22 14:57:50] opensrf.settings [INTL:7797:UnixServer.pm:250:] PeerHandle Created from UnixServer child_init_hook
[2008-12-22 14:57:50] opensrf.settings [INTL:7798:UnixServer.pm:250:] PeerHandle Created from UnixServer child_init_hook
[2008-12-22 14:57:50] opensrf.settings [INTL:7800:PeerConnection.pm:63:] Built Peer with
[2008-12-22 14:57:50] opensrf.settings [INTL:7799:PeerConnection.pm:63:] Built Peer with
[2008-12-22 14:57:50] opensrf.settings [INTL:7799:UnixServer.pm:250:] PeerHandle Created from UnixServer child_init_hook
[2008-12-22 14:57:50] opensrf.settings [INTL:7801:PeerConnection.pm:63:] Built Peer with
[2008-12-22 14:57:50] opensrf.settings [INTL:7800:UnixServer.pm:250:] PeerHandle Created from UnixServer child_init_hook
[2008-12-22 14:57:50] opensrf.settings [INTL:7801:UnixServer.pm:250:] PeerHandle Created from UnixServer child_init_hook
[2008-12-22 14:57:53] opensrf.persist [INFO:7804:UnixServer.pm:183:] UnixServer: min=5, max=25, min_spare=2 max_spare=5, max_req=1000, log_file=/opensrf//var/log/opensrf.persist_unix.log, port=/opensrf//var/lock/opensrf.persist_unix.sock, pid_file=/opensrf//var/run/opensrf.persist_unix.pid
[2008-12-22 14:57:53] /opensrf/bin/opensrf-perl.pl [INTL:7805:Inbound.pm:51:] Inbound as opensrf, opensrf, opensrf.persist_listener_at_localhost, private.localhost, 5222
[2008-12-22 14:57:53] opensrf.persist [INTL:7804:PeerConnection.pm:63:] Built Peer with
[2008-12-22 14:57:53] /opensrf/bin/opensrf-perl.pl [INFO:7805:Inbound.pm:96:] loading router info ARRAY(0x92b57b0)
[2008-12-22 14:57:53] /opensrf/bin/opensrf-perl.pl [WARN:7805:Inbound.pm:117:] opensrf.persist: No routers defined
[2008-12-22 14:57:53] /opensrf/bin/opensrf-perl.pl [INFO:7805:Inbound.pm:124:] opensrf.persist going into listen loop
[2008-12-22 14:57:53] /opensrf/bin/opensrf-perl.pl [DEBG:7805:Inbound.pm:131:] Inbound listener calling process()
[2008-12-22 14:57:53] opensrf.persist [DEBG:7804:UnixServer.pm:208:] Setting application implementation for opensrf.persist
[2008-12-22 14:57:53] opensrf.persist [INTL:7808:PeerConnection.pm:63:] Built Peer with
[2008-12-22 14:57:53] opensrf.persist [INTL:7809:PeerConnection.pm:63:] Built Peer with
[2008-12-22 14:57:53] opensrf.persist [INTL:7808:UnixServer.pm:250:] PeerHandle Created from UnixServer child_init_hook
[2008-12-22 14:57:53] opensrf.persist [INTL:7809:UnixServer.pm:250:] PeerHandle Created from UnixServer child_init_hook
[2008-12-22 14:57:53] opensrf.persist [INTL:7810:PeerConnection.pm:63:] Built Peer with
[2008-12-22 14:57:53] opensrf.persist [INTL:7811:PeerConnection.pm:63:] Built Peer with
[2008-12-22 14:57:53] opensrf.persist [INTL:7810:UnixServer.pm:250:] PeerHandle Created from UnixServer child_init_hook
[2008-12-22 14:57:53] opensrf.persist [INTL:7812:PeerConnection.pm:63:] Built Peer with
[2008-12-22 14:57:53] opensrf.persist [INTL:7811:UnixServer.pm:250:] PeerHandle Created from UnixServer child_init_hook
[2008-12-22 14:57:53] opensrf.persist [INTL:7812:UnixServer.pm:250:] PeerHandle Created from UnixServer child_init_hook
opensrf 2008-12-22 14:58:01 [INFO:7821:osrf_system.c:415:] Bootstrapping system with domain private.localhost, port 5222, and unixpath (none)
opensrf 2008-12-22 14:58:01 [INT :7821:socket_bundle.c:61:] Adding socket node with fd 3
opensrf 2008-12-22 14:58:01 [INT :7821:socket_bundle.c:548:] 1 active sockets after select()
opensrf 2008-12-22 14:58:01 [INT :7821:socket_bundle.c:727:] 7821 : Received data at 1229975881.435148
opensrf 2008-12-22 14:58:01 [INT :7821:socket_bundle.c:731:] Socket 3 Read 161 bytes and data: <?xml version='1.0'?><stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='3721006818' from='private.localhost' xml:lang='en'>
opensrf 2008-12-22 14:58:01 [INT :7821:socket_bundle.c:548:] 1 active sockets after select()
opensrf 2008-12-22 14:58:01 [INT :7821:socket_bundle.c:727:] 7821 : Received data at 1229975881.441273
opensrf 2008-12-22 14:58:01 [INT :7821:socket_bundle.c:731:] Socket 3 Read 34 bytes and data: <iq type='result' id='123456789'/>
opensrf 2008-12-22 14:58:01 [DEBG:7821:osrf_app_session.c:282:] opensrf.settings session is stateless
opensrf 2008-12-22 14:58:01 [DEBG:7821:osrf_app_session.c:293:] Building a new client session with id [opensrf.settings] [1229975881.443137.12299758817821]
opensrf 2008-12-22 14:58:01 [DEBG:7821:osrf_app_session.c:639:] AppSession in queue_wait with timeout 0
opensrf 2008-12-22 14:58:01 [INT :7821:socket_bundle.c:548:] 0 active sockets after select()
opensrf 2008-12-22 14:58:01 [INT :7821:socket_bundle.c:727:] 7821 : Received data at 1229975881.443950
opensrf 2008-12-22 14:58:01 [DEBG:7821:osrf_app_session.c:456:] App Session [opensrf.settings] [1229975881.443137.12299758817821] resetting remote id to router@private.localhost/opensrf.settings
opensrf 2008-12-22 14:58:01 [INFO:7821:osrf_app_session.c:608:] [opensrf.settings] sent 189 bytes of data to router@private.localhost/opensrf.settings
opensrf 2008-12-22 14:58:01 [DEBG:7821:osrf_app_session.c:611:] Sent: [{"__c":"osrfMessage","__p":{"threadTrace":"1","locale":"en-US","type":"REQUEST","payload":{"__c":"osrfMethod","__p":{"method":"opensrf.settings.host_config.get","params":["localhost"]}}}}]
opensrf 2008-12-22 14:58:01 [DEBG:7821:osrf_app_session.c:426:] Pushing [1] onto request queue for session [opensrf.settings] [1229975881.443137.12299758817821]
opensrf 2008-12-22 14:58:01 [DEBG:7821:osrf_app_session.c:109:] In app_request receive with remaining time [60]
opensrf 2008-12-22 14:58:01 [DEBG:7821:osrf_app_session.c:639:] AppSession in queue_wait with timeout 0
opensrf 2008-12-22 14:58:01 [INT :7821:socket_bundle.c:548:] 0 active sockets after select()
opensrf 2008-12-22 14:58:01 [INT :7821:socket_bundle.c:727:] 7821 : Received data at 1229975881.463792
opensrf 2008-12-22 14:58:01 [INT :7821:socket_bundle.c:731:] Socket 3 Read 765 bytes and data: <message from='router@private.localhost/opensrf.settings' to='opensrf@private.localhost/settings_grabber_debianlenny_1229975881.424981_7821' type='error' router_from='' router_to='' router_class='' router_command='' osrf_xid=''><thread>1229975881.443137.12299758817821</thread><body>[{"__c":"osrfMessage","__p":{"threadTrace":"1","locale":"en-US","type":"REQUEST","payload":{"__c":"osrfMethod","__p":{"method":"opensrf.settings.host_config.get","params":["localhost"]}}}}]</body><error code='503' type='cancel'><service-unavailable xmlns='urn:ietf:params:xml:ns:xmpp-stanzas'/></error></message>
opensrf 2008-12-22 14:58:01 [INFO:7821:transport_session.c:436:] Received <error> message with type cancel and code 503
opensrf 2008-12-22 14:58:01 [DEBG:7821:osrf_stack.c:24:] Received message from transport code from router@private.localhost/opensrf.settings
opensrf 2008-12-22 14:58:01 [DEBG:7821:osrf_stack.c:51:] Transport handler received new message
from router@private.localhost/opensrf.settings to opensrf@private.localhost/settings_grabber_debianlenny_1229975881.424981_7821 with body
[{"__c":"osrfMessage","__p":{"threadTrace":"1","locale":"en-US","type":"REQUEST","payload":{"__c":"osrfMethod","__p":{"method":"opensrf.settings.host_config.get","params":["localhost"]}}}}]
opensrf 2008-12-22 14:58:01 [DEBG:7821:osrf_stack.c:84:] We received 1 messages from router@private.localhost/opensrf.settings
opensrf 2008-12-22 14:58:01 [WARN:7821:osrf_stack.c:95:] !!! Received Jabber layer error message
opensrf 2008-12-22 14:58:01 [WARN:7821:osrf_stack.c:105:] * Jabber Error is for top level remote id [router@private.localhost/opensrf.settings], no one to send my message to! Cutting request short...
opensrf 2008-12-22 14:58:01 [INFO:7821:osrf_stack.c:116:] Message processing duration 0.000484
opensrf 2008-12-22 14:58:01 [DEBG:7821:osrf_stack.c:119:] after msg delete
opensrf 2008-12-22 14:58:01 [INT :7821:socket_bundle.c:548:] 0 active sockets after select()
opensrf 2008-12-22 14:58:01 [INT :7821:socket_bundle.c:727:] 7821 : Received data at 1229975881.467017
opensrf 2008-12-22 14:58:01 [ERR :7821:osrf_app_session.c:113:] Transport error in recv()
opensrf 2008-12-22 14:58:01 [ERR :7821:osrf_settings.c:48:] No osrfMessage received from host localhost (timeout?)
opensrf 2008-12-22 14:58:01 [DEBG:7821:osrf_app_session.c:649:] AppSession [opensrf.settings] [1229975881.443137.12299758817821] destroying self and deleting requests
opensrf 2008-12-22 14:58:01 [ERR :7821:osrf_settings.c:67:] Unable to load config for host localhost
opensrf 2008-12-22 14:58:01 [INT :7821:socket_bundle.c:501:] Closing socket 3
opensrf 2008-12-22 14:58:01 [DEBG:7821:socket_bundle.c:394:] removing socket 3
opensrf 2008-12-22 14:58:01 [ERR :7821:osrf_system.c:105:] Unable to retrieve settings for host localhost from configuration file /opensrf//etc/opensrf_core.xml
opensrf 2008-12-22 14:58:01 [ERR :7821:opensrf.c:23:] Server Loop returned an error condition, exiting with -1
[2008-12-22 14:58:34] /opensrf/bin/opensrf-perl.pl [INTL:7835:PeerConnection.pm:63:] Built Peer with
[2008-12-22 14:58:34] opensrf.persist [DEBG:7808:UnixServer.pm:259:] attempting to call child exit handler...
[2008-12-22 14:58:34] opensrf.persist [DEBG:7809:UnixServer.pm:259:] attempting to call child exit handler...
[2008-12-22 14:58:34] opensrf.persist [DEBG:7810:UnixServer.pm:259:] attempting to call child exit handler...
[2008-12-22 14:58:35] opensrf.persist [DEBG:7811:UnixServer.pm:259:] attempting to call child exit handler...
[2008-12-22 14:58:35] opensrf.persist [DEBG:7812:UnixServer.pm:259:] attempting to call child exit handler...
[2008-12-22 14:58:35] opensrf.settings [DEBG:7797:UnixServer.pm:259:] attempting to call child exit handler...
[2008-12-22 14:58:35] opensrf.settings [DEBG:7797:Settings.pm:8:] settings server child exiting...7797
[2008-12-22 14:58:35] opensrf.settings [DEBG:7798:UnixServer.pm:259:] attempting to call child exit handler...
[2008-12-22 14:58:35] opensrf.settings [DEBG:7798:Settings.pm:8:] settings server child exiting...7798
[2008-12-22 14:58:35] opensrf.settings [DEBG:7799:UnixServer.pm:259:] attempting to call child exit handler...
[2008-12-22 14:58:35] opensrf.settings [DEBG:7799:Settings.pm:8:] settings server child exiting...7799
[2008-12-22 14:58:35] opensrf.settings [DEBG:7800:UnixServer.pm:259:] attempting to call child exit handler...
[2008-12-22 14:58:35] opensrf.settings [DEBG:7800:Settings.pm:8:] settings server child exiting...7800
[2008-12-22 14:58:35] opensrf.settings [DEBG:7801:UnixServer.pm:259:] attempting to call child exit handler...
[2008-12-22 14:58:35] opensrf.settings [DEBG:7801:Settings.pm:8:] settings server child exiting...7801
[2008-12-22 15:02:24] /opensrf/bin/opensrf-perl.pl [INTL:7875:PeerConnection.pm:63:] Built Peer with
[2008-12-22 15:02:24] opensrf.settings [INFO:7880:UnixServer.pm:183:] UnixServer: min=5, max=15, min_spare=3 max_spare=5, max_req=1000, log_file=/opensrf//var/log/opensrf.settings_unix.log, port=/opensrf//var/lock/opensrf.settings_unix.sock, pid_file=/opensrf//var/run/opensrf.settings_unix.pid
[2008-12-22 15:02:24] opensrf.settings [INTL:7880:PeerConnection.pm:63:] Built Peer with
[2008-12-22 15:02:24] /opensrf/bin/opensrf-perl.pl [INTL:7881:Inbound.pm:51:] Inbound as opensrf, opensrf, opensrf.settings_listener_at_localhost, private.localhost, 5222
[2008-12-22 15:02:24] opensrf.settings [DEBG:7880:UnixServer.pm:208:] Setting application implementation for opensrf.settings
[2008-12-22 15:02:24] /opensrf/bin/opensrf-perl.pl [INFO:7881:Inbound.pm:96:] loading router info ARRAY(0x8a967b8)
[2008-12-22 15:02:24] /opensrf/bin/opensrf-perl.pl [WARN:7881:Inbound.pm:117:] opensrf.settings: No routers defined
[2008-12-22 15:02:24] /opensrf/bin/opensrf-perl.pl [INFO:7881:Inbound.pm:124:] opensrf.settings going into listen loop
[2008-12-22 15:02:24] /opensrf/bin/opensrf-perl.pl [DEBG:7881:Inbound.pm:131:] Inbound listener calling process()
[2008-12-22 15:02:24] opensrf.settings [INTL:7882:PeerConnection.pm:63:] Built Peer with
[2008-12-22 15:02:24] opensrf.settings [INTL:7883:PeerConnection.pm:63:] Built Peer with
[2008-12-22 15:02:24] opensrf.settings [INTL:7882:UnixServer.pm:250:] PeerHandle Created from UnixServer child_init_hook
[2008-12-22 15:02:24] opensrf.settings [INTL:7884:PeerConnection.pm:63:] Built Peer with
[2008-12-22 15:02:24] opensrf.settings [INTL:7883:UnixServer.pm:250:] PeerHandle Created from UnixServer child_init_hook
[2008-12-22 15:02:24] opensrf.settings [INTL:7885:PeerConnection.pm:63:] Built Peer with
[2008-12-22 15:02:24] opensrf.settings [INTL:7884:UnixServer.pm:250:] PeerHandle Created from UnixServer child_init_hook
[2008-12-22 15:02:24] opensrf.settings [INTL:7886:PeerConnection.pm:63:] Built Peer with
[2008-12-22 15:02:24] opensrf.settings [INTL:7885:UnixServer.pm:250:] PeerHandle Created from UnixServer child_init_hook
[2008-12-22 15:02:24] opensrf.settings [INTL:7886:UnixServer.pm:250:] PeerHandle Created from UnixServer child_init_hook
[2008-12-22 15:02:27] opensrf.persist [INFO:7889:UnixServer.pm:183:] UnixServer: min=5, max=25, min_spare=2 max_spare=5, max_req=1000, log_file=/opensrf//var/log/opensrf.persist_unix.log, port=/opensrf//var/lock/opensrf.persist_unix.sock, pid_file=/opensrf//var/run/opensrf.persist_unix.pid
[2008-12-22 15:02:27] /opensrf/bin/opensrf-perl.pl [INTL:7890:Inbound.pm:51:] Inbound as opensrf, opensrf, opensrf.persist_listener_at_localhost, private.localhost, 5222
[2008-12-22 15:02:27] opensrf.persist [INTL:7889:PeerConnection.pm:63:] Built Peer with
[2008-12-22 15:02:27] opensrf.persist [DEBG:7889:UnixServer.pm:208:] Setting application implementation for opensrf.persist
[2008-12-22 15:02:27] /opensrf/bin/opensrf-perl.pl [INFO:7890:Inbound.pm:96:] loading router info ARRAY(0x8a967b8)
[2008-12-22 15:02:27] /opensrf/bin/opensrf-perl.pl [WARN:7890:Inbound.pm:117:] opensrf.persist: No routers defined
[2008-12-22 15:02:27] /opensrf/bin/opensrf-perl.pl [INFO:7890:Inbound.pm:124:] opensrf.persist going into listen loop
[2008-12-22 15:02:27] /opensrf/bin/opensrf-perl.pl [DEBG:7890:Inbound.pm:131:] Inbound listener calling process()
[2008-12-22 15:02:27] opensrf.persist [INTL:7893:PeerConnection.pm:63:] Built Peer with
[2008-12-22 15:02:27] opensrf.persist [INTL:7894:PeerConnection.pm:63:] Built Peer with
[2008-12-22 15:02:27] opensrf.persist [INTL:7893:UnixServer.pm:250:] PeerHandle Created from UnixServer child_init_hook
[2008-12-22 15:02:27] opensrf.persist [INTL:7894:UnixServer.pm:250:] PeerHandle Created from UnixServer child_init_hook
[2008-12-22 15:02:27] opensrf.persist [INTL:7895:PeerConnection.pm:63:] Built Peer with
[2008-12-22 15:02:27] opensrf.persist [INTL:7896:PeerConnection.pm:63:] Built Peer with
[2008-12-22 15:02:27] opensrf.persist [INTL:7895:UnixServer.pm:250:] PeerHandle Created from UnixServer child_init_hook
[2008-12-22 15:02:27] opensrf.persist [INTL:7897:PeerConnection.pm:63:] Built Peer with
[2008-12-22 15:02:27] opensrf.persist [INTL:7896:UnixServer.pm:250:] PeerHandle Created from UnixServer child_init_hook
[2008-12-22 15:02:27] opensrf.persist [INTL:7897:UnixServer.pm:250:] PeerHandle Created from UnixServer child_init_hook
opensrf 2008-12-22 15:02:39 [INFO:7903:osrf_system.c:415:] Bootstrapping system with domain private.localhost, port 5222, and unixpath (none)
opensrf 2008-12-22 15:02:39 [INT :7903:socket_bundle.c:61:] Adding socket node with fd 3
opensrf 2008-12-22 15:02:39 [INT :7903:socket_bundle.c:548:] 1 active sockets after select()
opensrf 2008-12-22 15:02:39 [INT :7903:socket_bundle.c:727:] 7903 : Received data at 1229976159.037500
opensrf 2008-12-22 15:02:39 [INT :7903:socket_bundle.c:731:] Socket 3 Read 160 bytes and data: <?xml version='1.0'?><stream:stream xmlns='jabber:client' xmlns:stream='http://etherx.jabber.org/streams' id='681647718' from='private.localhost' xml:lang='en'>
opensrf 2008-12-22 15:02:39 [INT :7903:socket_bundle.c:548:] 1 active sockets after select()
opensrf 2008-12-22 15:02:39 [INT :7903:socket_bundle.c:727:] 7903 : Received data at 1229976159.040969
opensrf 2008-12-22 15:02:39 [INT :7903:socket_bundle.c:731:] Socket 3 Read 34 bytes and data: <iq type='result' id='123456789'/>
opensrf 2008-12-22 15:02:39 [DEBG:7903:osrf_app_session.c:282:] opensrf.settings session is stateless
opensrf 2008-12-22 15:02:39 [DEBG:7903:osrf_app_session.c:293:] Building a new client session with id [opensrf.settings] [1229976159.042135.12299761597903]
opensrf 2008-12-22 15:02:39 [DEBG:7903:osrf_app_session.c:639:] AppSession in queue_wait with timeout 0
opensrf 2008-12-22 15:02:39 [INT :7903:socket_bundle.c:548:] 0 active sockets after select()
opensrf 2008-12-22 15:02:39 [INT :7903:socket_bundle.c:727:] 7903 : Received data at 1229976159.042621
opensrf 2008-12-22 15:02:39 [DEBG:7903:osrf_app_session.c:456:] App Session [opensrf.settings] [1229976159.042135.12299761597903] resetting remote id to router@private.localhost/opensrf.settings
opensrf 2008-12-22 15:02:39 [INFO:7903:osrf_app_session.c:608:] [opensrf.settings] sent 189 bytes of data to router@private.localhost/opensrf.settings
opensrf 2008-12-22 15:02:39 [DEBG:7903:osrf_app_session.c:611:] Sent: [{"__c":"osrfMessage","__p":{"threadTrace":"1","locale":"en-US","type":"REQUEST","payload":{"__c":"osrfMethod","__p":{"method":"opensrf.settings.host_config.get","params":["localhost"]}}}}]
opensrf 2008-12-22 15:02:39 [DEBG:7903:osrf_app_session.c:426:] Pushing [1] onto request queue for session [opensrf.settings] [1229976159.042135.12299761597903]
opensrf 2008-12-22 15:02:39 [DEBG:7903:osrf_app_session.c:109:] In app_request receive with remaining time [60]
opensrf 2008-12-22 15:02:39 [DEBG:7903:osrf_app_session.c:639:] AppSession in queue_wait with timeout 0
opensrf 2008-12-22 15:02:39 [INT :7903:socket_bundle.c:548:] 0 active sockets after select()
opensrf 2008-12-22 15:02:39 [INT :7903:socket_bundle.c:727:] 7903 : Received data at 1229976159.054953
opensrf 2008-12-22 15:02:39 [INT :7903:socket_bundle.c:731:] Socket 3 Read 765 bytes and data: <message from='router@private.localhost/opensrf.settings' to='opensrf@private.localhost/settings_grabber_debianlenny_1229976159.027636_7903' type='error' router_from='' router_to='' router_class='' router_command='' osrf_xid=''><thread>1229976159.042135.12299761597903</thread><body>[{"__c":"osrfMessage","__p":{"threadTrace":"1","locale":"en-US","type":"REQUEST","payload":{"__c":"osrfMethod","__p":{"method":"opensrf.settings.host_config.get","params":["localhost"]}}}}]</body><error code='503' type='cancel'><service-unavailable xmlns='urn:ietf:params:xml:ns:xmpp-stanzas'/></error></message>
opensrf 2008-12-22 15:02:39 [INFO:7903:transport_session.c:436:] Received <error> message with type cancel and code 503
opensrf 2008-12-22 15:02:39 [DEBG:7903:osrf_stack.c:24:] Received message from transport code from router@private.localhost/opensrf.settings
opensrf 2008-12-22 15:02:39 [DEBG:7903:osrf_stack.c:51:] Transport handler received new message
from router@private.localhost/opensrf.settings to opensrf@private.localhost/settings_grabber_debianlenny_1229976159.027636_7903 with body
[{"__c":"osrfMessage","__p":{"threadTrace":"1","locale":"en-US","type":"REQUEST","payload":{"__c":"osrfMethod","__p":{"method":"opensrf.settings.host_config.get","params":["localhost"]}}}}]
opensrf 2008-12-22 15:02:39 [DEBG:7903:osrf_stack.c:84:] We received 1 messages from router@private.localhost/opensrf.settings
opensrf 2008-12-22 15:02:39 [WARN:7903:osrf_stack.c:95:] !!! Received Jabber layer error message
opensrf 2008-12-22 15:02:39 [WARN:7903:osrf_stack.c:105:] * Jabber Error is for top level remote id [router@private.localhost/opensrf.settings], no one to send my message to! Cutting request short...
opensrf 2008-12-22 15:02:39 [INFO:7903:osrf_stack.c:116:] Message processing duration 0.000303
opensrf 2008-12-22 15:02:39 [DEBG:7903:osrf_stack.c:119:] after msg delete
opensrf 2008-12-22 15:02:39 [INT :7903:socket_bundle.c:548:] 0 active sockets after select()
opensrf 2008-12-22 15:02:39 [INT :7903:socket_bundle.c:727:] 7903 : Received data at 1229976159.056942
opensrf 2008-12-22 15:02:39 [ERR :7903:osrf_app_session.c:113:] Transport error in recv()
opensrf 2008-12-22 15:02:39 [ERR :7903:osrf_settings.c:48:] No osrfMessage received from host localhost (timeout?)
opensrf 2008-12-22 15:02:39 [DEBG:7903:osrf_app_session.c:649:] AppSession [opensrf.settings] [1229976159.042135.12299761597903] destroying self and deleting requests
opensrf 2008-12-22 15:02:39 [ERR :7903:osrf_settings.c:67:] Unable to load config for host localhost
opensrf 2008-12-22 15:02:39 [INT :7903:socket_bundle.c:501:] Closing socket 3
opensrf 2008-12-22 15:02:39 [DEBG:7903:socket_bundle.c:394:] removing socket 3
opensrf 2008-12-22 15:02:39 [ERR :7903:osrf_system.c:105:] Unable to retrieve settings for host localhost from configuration file /opensrf//etc/opensrf_core.xml
opensrf 2008-12-22 15:02:39 [ERR :7903:opensrf.c:23:] Server Loop returned an error condition, exiting with -1