Reply To: Connection loss and reboot issue reloaded

#4488
schiers
Participant

Hi Ron,

just managed to log -d10 the issue. Just in case it helps…

2006-05-27 12:53:03: Entering ws_lock_unsafe
2006-05-27 12:53:03: Exiting ws_lock_unsafe with retval of 0
2006-05-27 12:53:03: Entering ws_add_dispatch_thread
2006-05-27 12:53:03: Exiting ws_add_dispatch_thread
2006-05-27 12:53:03: Entering ws_unlock_unsafe
2006-05-27 12:53:03: Exiting ws_unlock_unsafe with a retval of 0
2006-05-27 12:53:03: Thread 0: Entering ws_dispatcher (Connection from 192.168.9.8)
2006-05-27 12:53:03: Entering ws_lock_unsafe
2006-05-27 12:53:03: Exiting ws_lock_unsafe with retval of 0
2006-05-27 12:53:03: Entering ws_unlock_unsafe
2006-05-27 12:53:03: Exiting ws_unlock_unsafe with a retval of 0
2006-05-27 12:53:03: Thread 0: got request
2006-05-27 12:53:03: Request: GET /rsp/info HTTP/1.0
2006-05-27 12:53:03: Entering ws_getheaders
2006-05-27 12:53:03: Thread 0: Read: User-Agent: Roku SoundBridge/2.5
2006-05-27 12:53:03: Thread 0: Adding header *User-Agent=Roku SoundBridge/2.5*
2006-05-27 12:53:03: Added *User-Agent=Roku SoundBridge/2.5*
2006-05-27 12:53:03: Thread 0: Read: Host: 192.168.9.9:3689
2006-05-27 12:53:03: Thread 0: Adding header *Host=192.168.9.9:3689*
2006-05-27 12:53:03: Added *Host=192.168.9.9:3689*
2006-05-27 12:53:03: Thread 0: Read: Accept: */*
2006-05-27 12:53:03: Thread 0: Adding header *Accept=*/**
2006-05-27 12:53:03: Added *Accept=*/**
2006-05-27 12:53:03: Thread 0: Read: Authorization: Basic cm9rdTpyb2t1
2006-05-27 12:53:03: Thread 0: Adding header *Authorization=Basic cm9rdTpyb2t1*
2006-05-27 12:53:03: Added *Authorization=Basic cm9rdTpyb2t1*
2006-05-27 12:53:03: Thread 0: Read: Pragma: no-cache
2006-05-27 12:53:03: Thread 0: Adding header *Pragma=no-cache*
2006-05-27 12:53:03: Added *Pragma=no-cache*
2006-05-27 12:53:03: Thread 0: Read: accept-encoding: gzip
2006-05-27 12:53:03: Thread 0: Adding header *accept-encoding=gzip*
2006-05-27 12:53:03: Added *accept-encoding=gzip*
2006-05-27 12:53:03: Thread 0: Read: accept-codecs: wma,mpeg,wav,mp4a
2006-05-27 12:53:03: Thread 0: Adding header *accept-codecs=wma,mpeg,wav,mp4a*
2006-05-27 12:53:03: Added *accept-codecs=wma,mpeg,wav,mp4a*
2006-05-27 12:53:03: Thread 0: Read: rsp-version: 0.1
2006-05-27 12:53:03: Thread 0: Adding header *rsp-version=0.1*
2006-05-27 12:53:03: Added *rsp-version=0.1*
2006-05-27 12:53:03: Thread 0: Read: transcode-codecs: wav,mp3
2006-05-27 12:53:03: Thread 0: Adding header *transcode-codecs=wav,mp3*
2006-05-27 12:53:03: Added *transcode-codecs=wav,mp3*
2006-05-27 12:53:03: Thread 0: Read:
2006-05-27 12:53:03: Thread 0: Headers parsed!
2006-05-27 12:53:03: Exiting ws_getheaders
2006-05-27 12:53:03: Checking to see if connection matches keep-alive
2006-05-27 12:53:03: Nope!
2006-05-27 12:53:03: Thread 0: Connection type HTTP/1.0
: Connection: non-persist
2006-05-27 12:53:03: Thread 0: Original URI: /rsp/info
2006-05-27 12:53:03: Thread 0: Translated URI: /rsp/info
2006-05-27 12:53:03: Entering ws_lock_unsafe
2006-05-27 12:53:03: Exiting ws_lock_unsafe with retval of 0
2006-05-27 12:53:03: Thread 0: Preparing to find handler
2006-05-27 12:53:03: Thread 0: URI Match!
2006-05-27 12:53:03: Entering ws_unlock_unsafe
2006-05-27 12:53:03: Exiting ws_unlock_unsafe with a retval of 0
2006-05-27 12:53:03: Thread 0: Time is 1148727183 seconds after epoch
2006-05-27 12:53:03: Thread 0: Setting time header
2006-05-27 12:53:03: Added *Date=Sat, 27 May 2006 10:53:03 GMT*
2006-05-27 12:53:03: Added *Connection=close*
2006-05-27 12:53:03: Added *Server=mt-daapd/svn-1082*
2006-05-27 12:53:03: Added *Content-Type=text/html*
2006-05-27 12:53:03: Added *Content-Language=en_us*
2006-05-27 12:53:03: Thread 0: Using non-default handler
2006-05-27 12:53:03: in main_auth
2006-05-27 12:53:03: Entering candispatch
2006-05-27 12:53:03: Current lock level: 0
2006-05-27 12:53:03: Dispatching auth for /rsp/info to plugin
2006-05-27 12:53:03: Current lock level: 0
2006-05-27 12:53:03: Dispatching /rsp/info to rsp/1.0
2006-05-27 12:53:03: in main_handler
2006-05-27 12:53:03: Entering candispatch
2006-05-27 12:53:03: Current lock level: 0
2006-05-27 12:53:03: Dispatching /rsp/info to plugin
2006-05-27 12:53:03: Current lock level: 0
2006-05-27 12:53:03: Dispatching /rsp/info to rsp/1.0
2006-05-27 12:53:03: Tokenizing url
2006-05-27 12:53:03: Found 5 elements
2006-05-27 12:53:03: Checking reponse 0
2006-05-27 12:53:03: Found it! Index: 0
2006-05-27 12:53:03: Starting rsp_info
2006-05-27 12:53:03: Gzipping output
2006-05-27 12:53:03: Added *Content-Encoding=gzip*
2006-05-27 12:53:03: Added *Vary=Accept-Encoding*
2006-05-27 12:53:03: Updating Connection from close to Close
2006-05-27 12:53:03: Added *Cache-Control=no-cache*
2006-05-27 12:53:03: Added *Expires=-1*
2006-05-27 12:53:03: Updating Content-Type from text/html to text/xml; charset=utf-8
2006-05-27 12:53:03: Entering ws_writefd
2006-05-27 12:53:03: Exiting ws_writefd
2006-05-27 12:53:03: Entering ws_emitheaders
2006-05-27 12:53:03: Emitting reponse header Expires: -1
2006-05-27 12:53:03: Entering ws_writefd
2006-05-27 12:53:03: Exiting ws_writefd
2006-05-27 12:53:03: Emitting reponse header Cache-Control: no-cache
2006-05-27 12:53:03: Entering ws_writefd
2006-05-27 12:53:03: Exiting ws_writefd
2006-05-27 12:53:03: Emitting reponse header Vary: Accept-Encoding
2006-05-27 12:53:03: Entering ws_writefd
2006-05-27 12:53:03: Exiting ws_writefd
2006-05-27 12:53:03: Emitting reponse header Content-Encoding: gzip
2006-05-27 12:53:03: Entering ws_writefd
2006-05-27 12:53:03: Exiting ws_writefd
2006-05-27 12:53:03: Emitting reponse header Content-Language: en_us
2006-05-27 12:53:03: Entering ws_writefd
2006-05-27 12:53:03: Exiting ws_writefd
2006-05-27 12:53:03: Emitting reponse header Content-Type: text/xml; charset=utf-8
2006-05-27 12:53:03: Entering ws_writefd
2006-05-27 12:53:03: Exiting ws_writefd
2006-05-27 12:53:03: Emitting reponse header Server: mt-daapd/svn-1082
2006-05-27 12:53:03: Entering ws_writefd
2006-05-27 12:53:03: Exiting ws_writefd
2006-05-27 12:53:03: Emitting reponse header Connection: Close
2006-05-27 12:53:03: Entering ws_writefd
2006-05-27 12:53:03: Exiting ws_writefd
2006-05-27 12:53:03: Emitting reponse header Date: Sat, 27 May 2006 10:53:03 GMT
2006-05-27 12:53:03: Entering ws_writefd
2006-05-27 12:53:03: Exiting ws_writefd
2006-05-27 12:53:03: Entering ws_writefd
2006-05-27 12:53:03: Exiting ws_writefd
2006-05-27 12:53:03: Exitin ws_emitheaders
2006-05-27 12:53:03: Executing: select count(*) FROM songs
2006-05-27 12:53:20: Done sending xml stream
2006-05-27 12:53:20: Entering ws_close
2006-05-27 12:53:20: Thread 0: Terminating
2006-05-27 12:53:20: Thread 0: Freeing request headers
2006-05-27 12:53:20: Entering ws_freearglist
2006-05-27 12:53:20: Exiting ws_freearglist
2006-05-27 12:53:20: Thread 0: Freeing response headers
2006-05-27 12:53:20: Entering ws_freearglist
2006-05-27 12:53:20: Exiting ws_freearglist
2006-05-27 12:53:20: Thread 0: Freeing request vars
2006-05-27 12:53:20: Entering ws_freearglist
2006-05-27 12:53:20: Exiting ws_freearglist
2006-05-27 12:53:20: Thread 0: Closing fd
2006-05-27 12:53:20: Entering ws_remove_dispatch_thread
2006-05-27 12:53:20: With thread 0 exiting, 0 are still running
2006-05-27 12:53:20: Exiting ws_remote_dispatch_thread
2006-05-27 12:53:20: Exiting ws_close (thread terminating)
2006-05-27 12:53:24: Entering ws_lock_unsafe
2006-05-27 12:53:24: Exiting ws_lock_unsafe with retval of 0
2006-05-27 12:53:24: Entering ws_add_dispatch_thread
2006-05-27 12:53:24: Exiting ws_add_dispatch_thread
2006-05-27 12:53:24: Entering ws_unlock_unsafe
2006-05-27 12:53:24: Exiting ws_unlock_unsafe with a retval of 0
2006-05-27 12:53:24: Thread 1: Entering ws_dispatcher (Connection from 192.168.9.8)
2006-05-27 12:53:24: Entering ws_lock_unsafe
2006-05-27 12:53:24: Exiting ws_lock_unsafe with retval of 0
2006-05-27 12:53:24: Entering ws_unlock_unsafe
2006-05-27 12:53:24: Exiting ws_unlock_unsafe with a retval of 0
2006-05-27 12:53:24: Thread 1: got request
2006-05-27 12:53:24: Request: GET /rsp/info HTTP/1.0
2006-05-27 12:53:24: Entering ws_getheaders
2006-05-27 12:53:24: Thread 1: Read: User-Agent: Roku SoundBridge/2.5
2006-05-27 12:53:24: Thread 1: Adding header *User-Agent=Roku SoundBridge/2.5*
2006-05-27 12:53:24: Added *User-Agent=Roku SoundBridge/2.5*
2006-05-27 12:53:24: Thread 1: Read: Host: 192.168.9.9:3689
2006-05-27 12:53:24: Thread 1: Adding header *Host=192.168.9.9:3689*
2006-05-27 12:53:24: Added *Host=192.168.9.9:3689*
2006-05-27 12:53:24: Thread 1: Read: Accept: */*
2006-05-27 12:53:24: Thread 1: Adding header *Accept=*/**
2006-05-27 12:53:24: Added *Accept=*/**
2006-05-27 12:53:24: Thread 1: Read: Authorization: Basic cm9rdTpyb2t1
2006-05-27 12:53:24: Thread 1: Adding header *Authorization=Basic cm9rdTpyb2t1*
2006-05-27 12:53:24: Added *Authorization=Basic cm9rdTpyb2t1*
2006-05-27 12:53:24: Thread 1: Read: Pragma: no-cache
2006-05-27 12:53:24: Thread 1: Adding header *Pragma=no-cache*
2006-05-27 12:53:24: Added *Pragma=no-cache*
2006-05-27 12:53:24: Thread 1: Read: accept-encoding: gzip
2006-05-27 12:53:24: Thread 1: Adding header *accept-encoding=gzip*
2006-05-27 12:53:24: Added *accept-encoding=gzip*
2006-05-27 12:53:24: Thread 1: Read: accept-codecs: wma,mpeg,wav,mp4a
2006-05-27 12:53:24: Thread 1: Adding header *accept-codecs=wma,mpeg,wav,mp4a*
2006-05-27 12:53:24: Added *accept-codecs=wma,mpeg,wav,mp4a*
2006-05-27 12:53:24: Thread 1: Read: rsp-version: 0.1
2006-05-27 12:53:24: Thread 1: Adding header *rsp-version=0.1*
2006-05-27 12:53:24: Added *rsp-version=0.1*
2006-05-27 12:53:24: Thread 1: Read: transcode-codecs: wav,mp3
2006-05-27 12:53:24: Thread 1: Adding header *transcode-codecs=wav,mp3*
2006-05-27 12:53:24: Added *transcode-codecs=wav,mp3*
2006-05-27 12:53:24: Thread 1: Read:
2006-05-27 12:53:24: Thread 1: Headers parsed!
2006-05-27 12:53:24: Exiting ws_getheaders
2006-05-27 12:53:24: Checking to see if connection matches keep-alive
2006-05-27 12:53:24: Nope!
2006-05-27 12:53:24: Thread 1: Connection type HTTP/1.0
: Connection: non-persist
2006-05-27 12:53:24: Thread 1: Original URI: /rsp/info
2006-05-27 12:53:24: Thread 1: Translated URI: /rsp/info
2006-05-27 12:53:24: Entering ws_lock_unsafe
2006-05-27 12:53:24: Exiting ws_lock_unsafe with retval of 0
2006-05-27 12:53:24: Thread 1: Preparing to find handler
2006-05-27 12:53:24: Thread 1: URI Match!
2006-05-27 12:53:24: Entering ws_unlock_unsafe
2006-05-27 12:53:24: Exiting ws_unlock_unsafe with a retval of 0
2006-05-27 12:53:24: Thread 1: Time is 1148727204 seconds after epoch
2006-05-27 12:53:24: Thread 1: Setting time header
2006-05-27 12:53:24: Added *Date=Sat, 27 May 2006 10:53:24 GMT*
2006-05-27 12:53:24: Added *Connection=close*
2006-05-27 12:53:24: Added *Server=mt-daapd/svn-1082*
2006-05-27 12:53:24: Added *Content-Type=text/html*
2006-05-27 12:53:24: Added *Content-Language=en_us*
2006-05-27 12:53:24: Thread 1: Using non-default handler
2006-05-27 12:53:24: in main_auth
2006-05-27 12:53:24: Entering candispatch
2006-05-27 12:53:24: Current lock level: 0
2006-05-27 12:53:24: Dispatching auth for /rsp/info to plugin
2006-05-27 12:53:24: Current lock level: 0
2006-05-27 12:53:24: Dispatching /rsp/info to rsp/1.0
2006-05-27 12:53:24: in main_handler
2006-05-27 12:53:24: Entering candispatch
2006-05-27 12:53:24: Current lock level: 0
2006-05-27 12:53:24: Dispatching /rsp/info to plugin
2006-05-27 12:53:24: Current lock level: 0
2006-05-27 12:53:24: Dispatching /rsp/info to rsp/1.0
2006-05-27 12:53:24: Tokenizing url
2006-05-27 12:53:24: Found 5 elements
2006-05-27 12:53:24: Checking reponse 0
2006-05-27 12:53:24: Found it! Index: 0
2006-05-27 12:53:24: Starting rsp_info
2006-05-27 12:53:24: Gzipping output
2006-05-27 12:53:24: Added *Content-Encoding=gzip*
2006-05-27 12:53:24: Added *Vary=Accept-Encoding*
2006-05-27 12:53:24: Updating Connection from close to Close
2006-05-27 12:53:24: Added *Cache-Control=no-cache*
2006-05-27 12:53:24: Added *Expires=-1*
2006-05-27 12:53:24: Updating Content-Type from text/html to text/xml; charset=utf-8
2006-05-27 12:53:24: Entering ws_writefd
2006-05-27 12:53:24: Exiting ws_writefd
2006-05-27 12:53:24: Entering ws_emitheaders
2006-05-27 12:53:24: Emitting reponse header Expires: -1
2006-05-27 12:53:24: Entering ws_writefd
2006-05-27 12:53:24: Exiting ws_writefd
2006-05-27 12:53:24: Emitting reponse header Cache-Control: no-cache
2006-05-27 12:53:24: Entering ws_writefd
2006-05-27 12:53:24: Exiting ws_writefd
2006-05-27 12:53:24: Emitting reponse header Vary: Accept-Encoding
2006-05-27 12:53:24: Entering ws_writefd
2006-05-27 12:53:24: Exiting ws_writefd
2006-05-27 12:53:24: Emitting reponse header Content-Encoding: gzip
2006-05-27 12:53:24: Entering ws_writefd
2006-05-27 12:53:24: Exiting ws_writefd
2006-05-27 12:53:24: Emitting reponse header Content-Language: en_us
2006-05-27 12:53:24: Entering ws_writefd
2006-05-27 12:53:24: Exiting ws_writefd
2006-05-27 12:53:24: Emitting reponse header Content-Type: text/xml; charset=utf-8
2006-05-27 12:53:24: Entering ws_writefd
2006-05-27 12:53:24: Exiting ws_writefd
2006-05-27 12:53:24: Emitting reponse header Server: mt-daapd/svn-1082
2006-05-27 12:53:24: Entering ws_writefd
2006-05-27 12:53:24: Exiting ws_writefd
2006-05-27 12:53:24: Emitting reponse header Connection: Close
2006-05-27 12:53:24: Entering ws_writefd
2006-05-27 12:53:24: Exiting ws_writefd
2006-05-27 12:53:24: Emitting reponse header Date: Sat, 27 May 2006 10:53:24 GMT
2006-05-27 12:53:24: Entering ws_writefd
2006-05-27 12:53:24: Exiting ws_writefd
2006-05-27 12:53:24: Entering ws_writefd
2006-05-27 12:53:24: Exiting ws_writefd
2006-05-27 12:53:24: Exitin ws_emitheaders
2006-05-27 12:53:24: Executing: select count(*) FROM songs
2006-05-27 12:53:24: Done sending xml stream
2006-05-27 12:53:24: Entering ws_close
2006-05-27 12:53:24: Thread 1: Terminating
2006-05-27 12:53:24: Thread 1: Freeing request headers
2006-05-27 12:53:24: Entering ws_freearglist
2006-05-27 12:53:24: Exiting ws_freearglist
2006-05-27 12:53:24: Thread 1: Freeing response headers
2006-05-27 12:53:24: Entering ws_freearglist
2006-05-27 12:53:24: Exiting ws_freearglist
2006-05-27 12:53:24: Thread 1: Freeing request vars
2006-05-27 12:53:24: Entering ws_freearglist
2006-05-27 12:53:24: Exiting ws_freearglist
2006-05-27 12:53:24: Thread 1: Closing fd
2006-05-27 12:53:24: Entering ws_remove_dispatch_thread
2006-05-27 12:53:24: With thread 1 exiting, 0 are still running
2006-05-27 12:53:24: Exiting ws_remote_dispatch_thread
2006-05-27 12:53:24: Exiting ws_close (thread terminating)
2006-05-27 12:53:57: Skipped bground scan... no users
2006-05-27 12:54:29: Got INT signal. Notifying daap server.
2006-05-27 12:54:29: Stopping gracefully
2006-05-27 12:54:29: Stopping rendezvous daemon
2006-05-27 12:54:29: Received a message from daap server
2006-05-27 12:54:29: Stopping mDNS
2006-05-27 12:54:29: Closing database
2006-05-27 12:54:29: Done!

BR,
Carsten.