Reply To: 1018 Connecting Issue

#4300
Carsten Schiers
Guest

Hi,

maybe it helps: I now have a debug level 9 log of a hang with a restart. I hope it fits in here:

2006-05-08 22:51:31: SocketDataReady got a packet from 192.168.9.8 to 224.0.0.251 on interface 192.168.9.9/eth0/2
2006-05-08 22:51:31: Received Query from 192.168.9.8 :5353 to 224.0.0.251 :5353 on 0x08096C48 with 4 Questions, 0 Answers, 0 Authorities, 0 Additionals
2006-05-08 22:51:31: AddRecordToResponseList: _daap._tcp.local. (PTR) already in list
2006-05-08 22:51:31: AddRecordToResponseList: _rsp._tcp.local. (PTR) already in list
2006-05-08 22:51:31: AddRecordToResponseList: mt-daapd._daap._tcp.local. (SRV) already in list
2006-05-08 22:51:31: AddRecordToResponseList: mt-daapd._daap._tcp.local. (TXT) already in list
2006-05-08 22:51:31: AddRecordToResponseList: mt-daapd (rsp/1.0)._rsp._tcp.local. (SRV) already in list
2006-05-08 22:51:31: AddRecordToResponseList: mt-daapd (rsp/1.0)._rsp._tcp.local. (TXT) already in list
2006-05-08 22:51:31: AddRecordToResponseList: data.local. (Addr) already in list
2006-05-08 22:51:31: AddRecordToResponseList: data.local. (Addr) already in list
2006-05-08 22:51:31: select(6, 0.003906)
2006-05-08 22:51:31: mDNS_Execute
2006-05-08 22:51:31: SendResponses: Sending 0 Deregistrations, 0 Announcements, 2 Answers, 5 Additionals on 08096C48
2006-05-08 22:51:31: SendResponses: Next in 2013265920 ticks
2006-05-08 22:51:31: select(6, 0.099609)
2006-05-08 22:51:31: SocketDataReady got a packet from 192.168.9.9 to 224.0.0.251 on interface 192.168.9.9/eth0/2
2006-05-08 22:51:31: Received Response from 192.168.9.9 addressed to 224.0.0.251 on 08096C48 TTL 255 with 0 Questions, 2 Answers, 0 Authorities, 5 Additionals
2006-05-08 22:51:31: select(6, 0.099609)
2006-05-08 22:51:31: mDNS_Execute
2006-05-08 22:51:31: select(6, 1868965.983398)
2006-05-08 22:57:54: SocketDataReady got a packet from 192.168.9.8 to 224.0.0.251 on interface 192.168.9.9/eth0/2
2006-05-08 22:57:54: Received Query from 192.168.9.8 :5353 to 224.0.0.251 :5353 on 0x08096C48 with 2 Questions, 0 Answers, 0 Authorities, 0 Additionals
2006-05-08 22:57:54: AddRecordToResponseList: mt-daapd (rsp/1.0)._rsp._tcp.local. (SRV) already in list
2006-05-08 22:57:54: AddRecordToResponseList: mt-daapd (rsp/1.0)._rsp._tcp.local. (TXT) already in list
2006-05-08 22:57:54: AddRecordToResponseList: data.local. (Addr) already in list
2006-05-08 22:57:54: Unicast Response: 0 Questions, 2 Answers, 1 Additional to 192.168.9.8 :5353 on 08096C48/4
2006-05-08 22:57:54: mDNS_Execute
2006-05-08 22:57:54: SendResponses: Sending 0 Deregistrations, 0 Announcements, 3 Answers, 0 Additionals on 08096C48
2006-05-08 22:57:54: SendResponses: Next in 2013265920 ticks
2006-05-08 22:57:54: select(6, 0.083007)
2006-05-08 22:57:54: SocketDataReady got a packet from 192.168.9.9 to 224.0.0.251 on interface 192.168.9.9/eth0/2
2006-05-08 22:57:54: Received Response from 192.168.9.9 addressed to 224.0.0.251 on 08096C48 TTL 255 with 0 Questions, 3 Answers, 0 Authorities, 0 Additionals
2006-05-08 22:57:54: select(6, 0.083007)
2006-05-08 22:57:54: SocketDataReady got a packet from 192.168.9.8 to 224.0.0.251 on interface 192.168.9.9/eth0/2
2006-05-08 22:57:54: Received Query from 192.168.9.8 :5353 to 224.0.0.251 :5353 on 0x08096C48 with 1 Question, 1 Answer, 0 Authorities, 0 Additionals
2006-05-08 22:57:54: AddRecordToResponseList: data.local. (Addr) already in list
2006-05-08 22:57:54: mDNS_Execute
2006-05-08 22:57:54: select(6, 1868583.018554)
2006-05-08 22:57:54: Thread 633: Entering ws_dispatcher (Connection from 192.168.9.8)
2006-05-08 22:57:54: Thread 633: got request
2006-05-08 22:57:54: Request: GET /rsp/info HTTP/1.0
2006-05-08 22:57:54: Thread 633: Read: User-Agent: Roku SoundBridge/2.5
2006-05-08 22:57:54: Thread 633: Adding header *User-Agent=Roku SoundBridge/2.5*
2006-05-08 22:57:54: Added *User-Agent=Roku SoundBridge/2.5*
2006-05-08 22:57:54: Thread 633: Read: Host: 192.168.9.9:3689
2006-05-08 22:57:54: Thread 633: Adding header *Host=192.168.9.9:3689*
2006-05-08 22:57:54: Added *Host=192.168.9.9:3689*
2006-05-08 22:57:54: Thread 633: Read: Accept: */*
2006-05-08 22:57:54: Thread 633: Adding header *Accept=*/**
2006-05-08 22:57:54: Added *Accept=*/**
2006-05-08 22:57:54: Thread 633: Read: Authorization: Basic cm9rdTpyb2t1
2006-05-08 22:57:54: Thread 633: Adding header *Authorization=Basic cm9rdTpyb2t1*
2006-05-08 22:57:54: Added *Authorization=Basic cm9rdTpyb2t1*
2006-05-08 22:57:54: Thread 633: Read: Pragma: no-cache
2006-05-08 22:57:54: Thread 633: Adding header *Pragma=no-cache*
2006-05-08 22:57:54: Added *Pragma=no-cache*
2006-05-08 22:57:54: Thread 633: Read: accept-encoding: gzip
2006-05-08 22:57:54: Thread 633: Adding header *accept-encoding=gzip*
2006-05-08 22:57:54: Added *accept-encoding=gzip*
2006-05-08 22:57:54: Thread 633: Read: accept-codecs: wma,mpeg,wav,mp4a
2006-05-08 22:57:54: Thread 633: Adding header *accept-codecs=wma,mpeg,wav,mp4a*
2006-05-08 22:57:54: Added *accept-codecs=wma,mpeg,wav,mp4a*
2006-05-08 22:57:54: Thread 633: Read: rsp-version: 0.1
2006-05-08 22:57:54: Thread 633: Adding header *rsp-version=0.1*
2006-05-08 22:57:54: Added *rsp-version=0.1*
2006-05-08 22:57:54: Thread 633: Read: transcode-codecs: wav,mp3
2006-05-08 22:57:54: Thread 633: Adding header *transcode-codecs=wav,mp3*
2006-05-08 22:57:54: Added *transcode-codecs=wav,mp3*
2006-05-08 22:57:54: Thread 633: Read:
2006-05-08 22:57:54: Thread 633: Headers parsed!
2006-05-08 22:57:54: Checking to see if connection matches keep-alive
2006-05-08 22:57:54: Nope!
2006-05-08 22:57:54: Thread 633: Connection type HTTP/1.0
: Connection: non-persist
2006-05-08 22:57:54: Thread 633: Original URI: /rsp/info
2006-05-08 22:57:54: Thread 633: Translated URI: /rsp/info
2006-05-08 22:57:54: Thread 633: Preparing to find handler
2006-05-08 22:57:54: Thread 633: URI Match!
2006-05-08 22:57:54: Thread 633: Time is 1147121874 seconds after epoch
2006-05-08 22:57:54: Thread 633: Setting time header
2006-05-08 22:57:54: Added *Date=Mon, 8 May 2006 20:57:54 GMT*
2006-05-08 22:57:54: Added *Connection=close*
2006-05-08 22:57:54: Added *Server=mt-daapd/svn-1018*
2006-05-08 22:57:54: Added *Content-Type=text/html*
2006-05-08 22:57:54: Added *Content-Language=en_us*
2006-05-08 22:57:54: Thread 633: Using non-default handler
2006-05-08 22:57:54: in main_auth
2006-05-08 22:57:54: Entering candispatch
2006-05-08 22:57:54: Current lock level: 0
2006-05-08 22:57:54: Dispatching auth for /rsp/info to plugin
2006-05-08 22:57:54: Current lock level: 0
2006-05-08 22:57:54: Dispatching /rsp/info to rsp/1.0
2006-05-08 22:57:54: in main_handler
2006-05-08 22:57:54: Entering candispatch
2006-05-08 22:57:54: Current lock level: 0
2006-05-08 22:57:54: Dispatching /rsp/info to plugin
2006-05-08 22:57:54: Current lock level: 0
2006-05-08 22:57:54: Dispatching /rsp/info to rsp/1.0
2006-05-08 22:57:54: Tokenizing url
2006-05-08 22:57:54: Found 5 elements
2006-05-08 22:57:54: Checking reponse 0
2006-05-08 22:57:54: Found it! Index: 0
2006-05-08 22:57:54: Starting rsp_info
2006-05-08 22:57:54: Gzipping output
2006-05-08 22:57:54: Added *Content-Encoding=gzip*
2006-05-08 22:57:54: Added *Vary=Accept-Encoding*
2006-05-08 22:57:54: Updating Connection from close to Close
2006-05-08 22:57:54: Added *Cache-Control=no-cache*
2006-05-08 22:57:54: Added *Expires=-1*
2006-05-08 22:57:54: Updating Content-Type from text/html to text/xml; charset=utf-8
2006-05-08 22:57:54: Emitting reponse header Expires: -1
2006-05-08 22:57:54: Emitting reponse header Cache-Control: no-cache
2006-05-08 22:57:54: Emitting reponse header Vary: Accept-Encoding
2006-05-08 22:57:54: Emitting reponse header Content-Encoding: gzip
2006-05-08 22:57:54: Emitting reponse header Content-Language: en_us
2006-05-08 22:57:54: Emitting reponse header Content-Type: text/xml; charset=utf-8
2006-05-08 22:57:54: Emitting reponse header Server: mt-daapd/svn-1018
2006-05-08 22:57:54: Emitting reponse header Connection: Close
2006-05-08 22:57:54: Emitting reponse header Date: Mon, 8 May 2006 20:57:54 GMT
2006-05-08 22:57:54: Executing: select count(*) FROM songs
2006-05-08 22:58:08: Done sending xml stream
2006-05-08 22:58:08: Thread 633: Terminating
2006-05-08 22:58:08: Thread 633: Freeing request headers
2006-05-08 22:58:08: Thread 633: Freeing response headers
2006-05-08 22:58:08: Thread 633: Freeing request vars
2006-05-08 22:58:08: Thread 633: Closing fd
2006-05-08 22:58:08: With thread 633 exiting, 0 are still running
2006-05-08 22:58:14: SocketDataReady got a packet from 192.168.9.8 to 224.0.0.251 on interface 192.168.9.9/eth0/2
2006-05-08 22:58:14: Received Query from 192.168.9.8 :5353 to 224.0.0.251 :5353 on 0x08096C48 with 8 Questions, 0 Answers, 4 Authorities, 0 Additionals
2006-05-08 22:58:14: AddRecordToResponseList: _daap._tcp.local. (PTR) already in list
2006-05-08 22:58:14: AddRecordToResponseList: _rsp._tcp.local. (PTR) already in list
2006-05-08 22:58:14: AddRecordToResponseList: mt-daapd._daap._tcp.local. (SRV) already in list
2006-05-08 22:58:14: AddRecordToResponseList: mt-daapd._daap._tcp.local. (TXT) already in list
2006-05-08 22:58:14: AddRecordToResponseList: mt-daapd (rsp/1.0)._rsp._tcp.local. (SRV) already in list
2006-05-08 22:58:14: AddRecordToResponseList: mt-daapd (rsp/1.0)._rsp._tcp.local. (TXT) already in list
2006-05-08 22:58:14: AddRecordToResponseList: data.local. (Addr) already in list
2006-05-08 22:58:14: AddRecordToResponseList: data.local. (Addr) already in list
2006-05-08 22:58:14: Unicast Response: 0 Questions, 2 Answers, 5 Additionals to 192.168.9.8 :5353 on 08096C48/4
2006-05-08 22:58:14: select(6, 0.083984)
2006-05-08 22:58:14: mDNS_Execute
2006-05-08 22:58:14: select(6, 1868562.761718)
2006-05-08 22:58:14: SocketDataReady got a packet from 192.168.9.8 to 224.0.0.251 on interface 192.168.9.9/eth0/2
2006-05-08 22:58:14: Received Query from 192.168.9.8 :5353 to 224.0.0.251 :5353 on 0x08096C48 with 4 Questions, 0 Answers, 4 Authorities, 0 Additionals
2006-05-08 22:58:14: select(6, 0.073242)
2006-05-08 22:58:15: mDNS_Execute
2006-05-08 22:58:15: select(6, 1868562.472656)
2006-05-08 22:58:15: SocketDataReady got a packet from 192.168.9.8 to 224.0.0.251 on interface 192.168.9.9/eth0/2
2006-05-08 22:58:15: Received Query from 192.168.9.8 :5353 to 224.0.0.251 :5353 on 0x08096C48 with 4 Questions, 0 Answers, 4 Authorities, 0 Additionals
2006-05-08 22:58:15: select(6, 0.025390)
2006-05-08 22:58:15: mDNS_Execute
2006-05-08 22:58:15: select(6, 1868562.220703)
2006-05-08 22:58:15: SocketDataReady got a packet from 192.168.9.8 to 224.0.0.251 on interface 192.168.9.9/eth0/2
2006-05-08 22:58:15: Received Response from 192.168.9.8 addressed to 224.0.0.251 on 08096C48 TTL 255 with 0 Questions, 4 Answers, 0 Authorities, 0 Additionals
2006-05-08 22:58:15: select(6, 1868562.045898)
2006-05-08 22:58:15: SocketDataReady got a packet from 192.168.9.8 to 224.0.0.251 on interface 192.168.9.9/eth0/2
2006-05-08 22:58:15: Received Query from 192.168.9.8 :5353 to 224.0.0.251 :5353 on 0x08096C48 with 4 Questions, 2 Answers, 0 Authorities, 0 Additionals
2006-05-08 22:58:15: AddRecordToResponseList: _daap._tcp.local. (PTR) already in list
2006-05-08 22:58:15: AddRecordToResponseList: _rsp._tcp.local. (PTR) already in list
2006-05-08 22:58:15: AddRecordToResponseList: mt-daapd._daap._tcp.local. (SRV) already in list
2006-05-08 22:58:15: AddRecordToResponseList: mt-daapd._daap._tcp.local. (TXT) already in list
2006-05-08 22:58:15: AddRecordToResponseList: mt-daapd (rsp/1.0)._rsp._tcp.local. (SRV) already in list
2006-05-08 22:58:15: AddRecordToResponseList: mt-daapd (rsp/1.0)._rsp._tcp.local. (TXT) already in list
2006-05-08 22:58:15: AddRecordToResponseList: data.local. (Addr) already in list
2006-05-08 22:58:15: AddRecordToResponseList: data.local. (Addr) already in list
2006-05-08 22:58:15: select(6, 0.117187)
2006-05-08 22:58:15: SocketDataReady got a packet from 192.168.9.8 to 224.0.0.251 on interface 192.168.9.9/eth0/2
2006-05-08 22:58:15: Received Response from 192.168.9.8 addressed to 224.0.0.251 on 08096C48 TTL 255 with 0 Questions, 11 Answers, 0 Authorities, 0 Additionals
2006-05-08 22:58:15: select(6, 0.117187)
2006-05-08 22:58:15: mDNS_Execute
2006-05-08 22:58:15: select(6, 1868561.727539)
2006-05-08 22:58:16: SocketDataReady got a packet from 192.168.9.8 to 224.0.0.251 on interface 192.168.9.9/eth0/2
2006-05-08 22:58:16: Received Response from 192.168.9.8 addressed to 224.0.0.251 on 08096C48 TTL 255 with 0 Questions, 15 Answers, 0 Authorities, 0 Additionals
2006-05-08 22:58:16: select(6, 1868560.945312)
2006-05-08 22:58:16: SocketDataReady got a packet from 192.168.9.8 to 224.0.0.251 on interface 192.168.9.9/eth0/2
2006-05-08 22:58:16: Received Query from 192.168.9.8 :5353 to 224.0.0.251 :5353 on 0x08096C48 with 3 Questions, 3 Answers, 0 Authorities, 0 Additionals
2006-05-08 22:58:16: AddRecordToResponseList: data.local. (Addr) already in list
2006-05-08 22:58:16: AddRecordToResponseList: mt-daapd (rsp/1.0)._rsp._tcp.local. (SRV) already in list
2006-05-08 22:58:16: AddRecordToResponseList: mt-daapd (rsp/1.0)._rsp._tcp.local. (TXT) already in list
2006-05-08 22:58:16: AddRecordToResponseList: data.local. (Addr) already in list
2006-05-08 22:58:16: select(6, 1868560.845703)
2006-05-08 22:58:16: Thread 634: Entering ws_dispatcher (Connection from 192.168.9.8)
2006-05-08 22:58:16: Thread 634: got request
2006-05-08 22:58:16: Request: GET /rsp/info HTTP/1.0
2006-05-08 22:58:16: Thread 634: Read: User-Agent: Roku SoundBridge/2.5
2006-05-08 22:58:16: Thread 634: Adding header *User-Agent=Roku SoundBridge/2.5*
2006-05-08 22:58:16: Added *User-Agent=Roku SoundBridge/2.5*
2006-05-08 22:58:16: Thread 634: Read: Host: 192.168.9.9:3689
2006-05-08 22:58:16: Thread 634: Adding header *Host=192.168.9.9:3689*
2006-05-08 22:58:16: Added *Host=192.168.9.9:3689*
2006-05-08 22:58:16: Thread 634: Read: Accept: */*
2006-05-08 22:58:16: Thread 634: Adding header *Accept=*/**
2006-05-08 22:58:16: Added *Accept=*/**
2006-05-08 22:58:16: Thread 634: Read: Authorization: Basic cm9rdTpyb2t1
2006-05-08 22:58:16: Thread 634: Adding header *Authorization=Basic cm9rdTpyb2t1*
2006-05-08 22:58:16: Added *Authorization=Basic cm9rdTpyb2t1*
2006-05-08 22:58:16: Thread 634: Read: Pragma: no-cache
2006-05-08 22:58:16: Thread 634: Adding header *Pragma=no-cache*
2006-05-08 22:58:16: Added *Pragma=no-cache*
2006-05-08 22:58:16: Thread 634: Read: accept-encoding: gzip
2006-05-08 22:58:16: Thread 634: Adding header *accept-encoding=gzip*
2006-05-08 22:58:16: Added *accept-encoding=gzip*
2006-05-08 22:58:16: Thread 634: Read: accept-codecs: wma,mpeg,wav,mp4a
2006-05-08 22:58:16: Thread 634: Adding header *accept-codecs=wma,mpeg,wav,mp4a*
2006-05-08 22:58:16: Added *accept-codecs=wma,mpeg,wav,mp4a*
2006-05-08 22:58:16: Thread 634: Read: rsp-version: 0.1
2006-05-08 22:58:16: Thread 634: Adding header *rsp-version=0.1*
2006-05-08 22:58:16: Added *rsp-version=0.1*
2006-05-08 22:58:16: Thread 634: Read: transcode-codecs: wav,mp3
2006-05-08 22:58:16: Thread 634: Adding header *transcode-codecs=wav,mp3*
2006-05-08 22:58:16: Added *transcode-codecs=wav,mp3*
2006-05-08 22:58:16: Thread 634: Read:
2006-05-08 22:58:16: Thread 634: Headers parsed!
2006-05-08 22:58:16: Checking to see if connection matches keep-alive
2006-05-08 22:58:16: Nope!
2006-05-08 22:58:16: Thread 634: Connection type HTTP/1.0
: Connection: non-persist
2006-05-08 22:58:16: Thread 634: Original URI: /rsp/info
2006-05-08 22:58:16: Thread 634: Translated URI: /rsp/info
2006-05-08 22:58:16: Thread 634: Preparing to find handler
2006-05-08 22:58:16: Thread 634: URI Match!
2006-05-08 22:58:16: Thread 634: Time is 1147121896 seconds after epoch
2006-05-08 22:58:16: Thread 634: Setting time header
2006-05-08 22:58:16: Added *Date=Mon, 8 May 2006 20:58:16 GMT*
2006-05-08 22:58:16: Added *Connection=close*
2006-05-08 22:58:16: Added *Server=mt-daapd/svn-1018*
2006-05-08 22:58:16: Added *Content-Type=text/html*
2006-05-08 22:58:16: Added *Content-Language=en_us*
2006-05-08 22:58:16: Thread 634: Using non-default handler
2006-05-08 22:58:16: in main_auth
2006-05-08 22:58:16: Entering candispatch
2006-05-08 22:58:16: Current lock level: 0
2006-05-08 22:58:16: Dispatching auth for /rsp/info to plugin
2006-05-08 22:58:16: Current lock level: 0
2006-05-08 22:58:16: Dispatching /rsp/info to rsp/1.0
2006-05-08 22:58:16: in main_handler
2006-05-08 22:58:16: Entering candispatch
2006-05-08 22:58:16: Current lock level: 0
2006-05-08 22:58:16: Dispatching /rsp/info to plugin
2006-05-08 22:58:16: Current lock level: 0
2006-05-08 22:58:16: Dispatching /rsp/info to rsp/1.0
2006-05-08 22:58:16: Tokenizing url
2006-05-08 22:58:16: Found 5 elements
2006-05-08 22:58:16: Checking reponse 0
2006-05-08 22:58:16: Found it! Index: 0
2006-05-08 22:58:16: Starting rsp_info
2006-05-08 22:58:16: Gzipping output
2006-05-08 22:58:16: Added *Content-Encoding=gzip*
2006-05-08 22:58:16: Added *Vary=Accept-Encoding*
2006-05-08 22:58:16: Updating Connection from close to Close
2006-05-08 22:58:16: Added *Cache-Control=no-cache*
2006-05-08 22:58:16: Added *Expires=-1*
2006-05-08 22:58:16: Updating Content-Type from text/html to text/xml; charset=utf-8
2006-05-08 22:58:16: Emitting reponse header Expires: -1
2006-05-08 22:58:16: Emitting reponse header Cache-Control: no-cache
2006-05-08 22:58:16: Emitting reponse header Vary: Accept-Encoding
2006-05-08 22:58:16: Emitting reponse header Content-Encoding: gzip
2006-05-08 22:58:16: Emitting reponse header Content-Language: en_us
2006-05-08 22:58:16: Emitting reponse header Content-Type: text/xml; charset=utf-8
2006-05-08 22:58:16: Emitting reponse header Server: mt-daapd/svn-1018
2006-05-08 22:58:16: Emitting reponse header Connection: Close
2006-05-08 22:58:16: Emitting reponse header Date: Mon, 8 May 2006 20:58:16 GMT
2006-05-08 22:58:16: Executing: select count(*) FROM songs
2006-05-08 22:58:16: Done sending xml stream
2006-05-08 22:58:16: Thread 634: Terminating
2006-05-08 22:58:16: Thread 634: Freeing request headers
2006-05-08 22:58:16: Thread 634: Freeing response headers
2006-05-08 22:58:16: Thread 634: Freeing request vars
2006-05-08 22:58:16: Thread 634: Closing fd
2006-05-08 22:58:16: With thread 634 exiting, 0 are still running
2006-05-08 22:58:17: SocketDataReady got a packet from 192.168.9.8 to 224.0.0.251 on interface 192.168.9.9/eth0/2
2006-05-08 22:58:17: Received Query from 192.168.9.8 :5353 to 224.0.0.251 :5353 on 0x08096C48 with 4 Questions, 2 Answers, 0 Authorities, 0 Additionals
2006-05-08 22:58:17: AddRecordToResponseList: _daap._tcp.local. (PTR) already in list
2006-05-08 22:58:17: AddRecordToResponseList: _rsp._tcp.local. (PTR) already in list
2006-05-08 22:58:17: AddRecordToResponseList: mt-daapd._daap._tcp.local. (SRV) already in list
2006-05-08 22:58:17: AddRecordToResponseList: mt-daapd._daap._tcp.local. (TXT) already in list
2006-05-08 22:58:17: AddRecordToResponseList: mt-daapd (rsp/1.0)._rsp._tcp.local. (SRV) already in list
2006-05-08 22:58:17: AddRecordToResponseList: mt-daapd (rsp/1.0)._rsp._tcp.local. (TXT) already in list
2006-05-08 22:58:17: AddRecordToResponseList: data.local. (Addr) already in list
2006-05-08 22:58:17: AddRecordToResponseList: data.local. (Addr) already in list
2006-05-08 22:58:17: select(6, 0.041015)
2006-05-08 22:58:17: mDNS_Execute
2006-05-08 22:58:17: select(6, 1868559.704101)
2006-05-08 22:58:18: SocketDataReady got a packet from 192.168.9.8 to 224.0.0.251 on interface 192.168.9.9/eth0/2
2006-05-08 22:58:18: Received Response from 192.168.9.8 addressed to 224.0.0.251 on 08096C48 TTL 255 with 0 Questions, 15 Answers, 0 Authorities, 0 Additionals
2006-05-08 22:58:18: select(6, 1868558.844726)
2006-05-08 22:58:21: SocketDataReady got a packet from 192.168.9.8 to 224.0.0.251 on interface 192.168.9.9/eth0/2
2006-05-08 22:58:21: Received Query from 192.168.9.8 :5353 to 224.0.0.251 :5353 on 0x08096C48 with 4 Questions, 2 Answers, 0 Authorities, 0 Additionals
2006-05-08 22:58:21: AddRecordToResponseList: _daap._tcp.local. (PTR) already in list
2006-05-08 22:58:21: AddRecordToResponseList: _rsp._tcp.local. (PTR) already in list
2006-05-08 22:58:21: AddRecordToResponseList: mt-daapd._daap._tcp.local. (SRV) already in list
2006-05-08 22:58:21: AddRecordToResponseList: mt-daapd._daap._tcp.local. (TXT) already in list
2006-05-08 22:58:21: AddRecordToResponseList: mt-daapd (rsp/1.0)._rsp._tcp.local. (SRV) already in list
2006-05-08 22:58:21: AddRecordToResponseList: mt-daapd (rsp/1.0)._rsp._tcp.local. (TXT) already in list
2006-05-08 22:58:21: AddRecordToResponseList: data.local. (Addr) already in list
2006-05-08 22:58:21: AddRecordToResponseList: data.local. (Addr) already in list
2006-05-08 22:58:21: select(6, 0.029296)
2006-05-08 22:58:21: mDNS_Execute
2006-05-08 22:58:21: select(6, 1868555.615234)
2006-05-08 22:58:22: SocketDataReady got a packet from 192.168.9.8 to 224.0.0.251 on interface 192.168.9.9/eth0/2
2006-05-08 22:58:22: Received Response from 192.168.9.8 addressed to 224.0.0.251 on 08096C48 TTL 255 with 0 Questions, 15 Answers, 0 Authorities, 0 Additionals
2006-05-08 22:58:22: select(6, 1868554.745117)
2006-05-08 22:58:23: Thread 635: Entering ws_dispatcher (Connection from 192.168.9.8)
2006-05-08 22:58:23: Thread 635: got request
2006-05-08 22:58:23: Request: GET /rsp/db/0/artist?type=browse HTTP/1.0
2006-05-08 22:58:23: Thread 635: Read: User-Agent: Roku SoundBridge/2.5
2006-05-08 22:58:23: Thread 635: Adding header *User-Agent=Roku SoundBridge/2.5*
2006-05-08 22:58:23: Added *User-Agent=Roku SoundBridge/2.5*
2006-05-08 22:58:23: Thread 635: Read: Host: 192.168.9.9:3689
2006-05-08 22:58:23: Thread 635: Adding header *Host=192.168.9.9:3689*
2006-05-08 22:58:23: Added *Host=192.168.9.9:3689*
2006-05-08 22:58:23: Thread 635: Read: Accept: */*
2006-05-08 22:58:23: Thread 635: Adding header *Accept=*/**
2006-05-08 22:58:23: Added *Accept=*/**
2006-05-08 22:58:23: Thread 635: Read: Authorization: Basic cm9rdTpyb2t1
2006-05-08 22:58:23: Thread 635: Adding header *Authorization=Basic cm9rdTpyb2t1*
2006-05-08 22:58:23: Added *Authorization=Basic cm9rdTpyb2t1*
2006-05-08 22:58:23: Thread 635: Read: Pragma: no-cache
2006-05-08 22:58:23: Thread 635: Adding header *Pragma=no-cache*
2006-05-08 22:58:23: Added *Pragma=no-cache*
2006-05-08 22:58:23: Thread 635: Read: accept-encoding: gzip
2006-05-08 22:58:23: Thread 635: Adding header *accept-encoding=gzip*
2006-05-08 22:58:23: Added *accept-encoding=gzip*
2006-05-08 22:58:23: Thread 635: Read: accept-codecs: wma,mpeg,wav,mp4a
2006-05-08 22:58:23: Thread 635: Adding header *accept-codecs=wma,mpeg,wav,mp4a*
2006-05-08 22:58:23: Added *accept-codecs=wma,mpeg,wav,mp4a*
2006-05-08 22:58:23: Thread 635: Read: rsp-version: 0.1
2006-05-08 22:58:23: Thread 635: Adding header *rsp-version=0.1*
2006-05-08 22:58:23: Added *rsp-version=0.1*
2006-05-08 22:58:23: Thread 635: Read: transcode-codecs: wav,mp3
2006-05-08 22:58:23: Thread 635: Adding header *transcode-codecs=wav,mp3*
2006-05-08 22:58:23: Added *transcode-codecs=wav,mp3*
2006-05-08 22:58:23: Thread 635: Read:
2006-05-08 22:58:23: Thread 635: Headers parsed!
2006-05-08 22:58:23: Checking to see if connection matches keep-alive
2006-05-08 22:58:23: Nope!
2006-05-08 22:58:23: Thread 635: Connection type HTTP/1.0
: Connection: non-persist
2006-05-08 22:58:23: Thread 635: parsing GET args
2006-05-08 22:58:23: Thread 635: Entering ws_getgetvars (type=browse)
2006-05-08 22:58:23: Thread 635: Adding arg type = browse
2006-05-08 22:58:23: Added *type=browse*
2006-05-08 22:58:23: Thread 635: Done parsing GET/POST args!
2006-05-08 22:58:23: Thread 635: Original URI: /rsp/db/0/artist
2006-05-08 22:58:23: Thread 635: Translated URI: /rsp/db/0/artist
2006-05-08 22:58:23: Thread 635: Preparing to find handler
2006-05-08 22:58:23: Thread 635: URI Match!
2006-05-08 22:58:23: Thread 635: Time is 1147121903 seconds after epoch
2006-05-08 22:58:23: Thread 635: Setting time header
2006-05-08 22:58:23: Added *Date=Mon, 8 May 2006 20:58:23 GMT*
2006-05-08 22:58:23: Added *Connection=close*
2006-05-08 22:58:23: Added *Server=mt-daapd/svn-1018*
2006-05-08 22:58:23: Added *Content-Type=text/html*
2006-05-08 22:58:23: Added *Content-Language=en_us*
2006-05-08 22:58:23: Thread 635: Using non-default handler
2006-05-08 22:58:23: in main_auth
2006-05-08 22:58:23: Entering candispatch
2006-05-08 22:58:23: Current lock level: 0
2006-05-08 22:58:23: Dispatching auth for /rsp/db/0/artist to plugin
2006-05-08 22:58:23: Current lock level: 0
2006-05-08 22:58:23: Dispatching /rsp/db/0/artist to rsp/1.0
2006-05-08 22:58:23: in main_handler
2006-05-08 22:58:23: Entering candispatch
2006-05-08 22:58:23: Current lock level: 0
2006-05-08 22:58:23: Dispatching /rsp/db/0/artist to plugin
2006-05-08 22:58:23: Current lock level: 0
2006-05-08 22:58:23: Dispatching /rsp/db/0/artist to rsp/1.0
2006-05-08 22:58:23: Tokenizing url
2006-05-08 22:58:23: Found 5 elements
2006-05-08 22:58:23: Checking reponse 0
2006-05-08 22:58:23: Checking reponse 1
2006-05-08 22:58:23: Checking reponse 2
2006-05-08 22:58:23: Checking reponse 3
2006-05-08 22:58:23: Found it! Index: 3
2006-05-08 22:58:23: No query/filter
2006-05-08 22:58:23: Executing: select count(artist) from (select distinct artist from songs )
2006-05-08 22:58:24: Number of results: 163
2006-05-08 22:58:24: Executing: select distinct artist from songs limit 1000000 offset 0
2006-05-08 22:58:24: Gzipping output
2006-05-08 22:58:24: Added *Content-Encoding=gzip*
2006-05-08 22:58:24: Added *Vary=Accept-Encoding*
2006-05-08 22:58:24: Updating Connection from close to Close
2006-05-08 22:58:24: Added *Cache-Control=no-cache*
2006-05-08 22:58:24: Added *Expires=-1*
2006-05-08 22:58:24: Updating Content-Type from text/html to text/xml; charset=utf-8
2006-05-08 22:58:24: Emitting reponse header Expires: -1
2006-05-08 22:58:24: Emitting reponse header Cache-Control: no-cache
2006-05-08 22:58:24: Emitting reponse header Vary: Accept-Encoding
2006-05-08 22:58:24: Emitting reponse header Content-Encoding: gzip
2006-05-08 22:58:24: Emitting reponse header Content-Language: en_us
2006-05-08 22:58:24: Emitting reponse header Content-Type: text/xml; charset=utf-8
2006-05-08 22:58:24: Emitting reponse header Server: mt-daapd/svn-1018
2006-05-08 22:58:24: Emitting reponse header Connection: Close
2006-05-08 22:58:24: Emitting reponse header Date: Mon, 8 May 2006 20:58:23 GMT
2006-05-08 22:58:24: Done sending xml stream
2006-05-08 22:58:24: Thread 635: Terminating
2006-05-08 22:58:24: Thread 635: Freeing request headers
2006-05-08 22:58:24: Thread 635: Freeing response headers
2006-05-08 22:58:24: Thread 635: Freeing request vars
2006-05-08 22:58:24: Thread 635: Closing fd
2006-05-08 22:58:24: With thread 635 exiting, 0 are still running
2006-05-08 22:58:29: SocketDataReady got a packet from 192.168.9.8 to 224.0.0.251 on interface 192.168.9.9/eth0/2
2006-05-08 22:58:29: Received Query from 192.168.9.8 :5353 to 224.0.0.251 :5353 on 0x08096C48 with 4 Questions, 2 Answers, 0 Authorities, 0 Additionals
2006-05-08 22:58:29: AddRecordToResponseList: _daap._tcp.local. (PTR) already in list
2006-05-08 22:58:29: AddRecordToResponseList: _rsp._tcp.local. (PTR) already in list
2006-05-08 22:58:29: AddRecordToResponseList: mt-daapd._daap._tcp.local. (SRV) already in list
2006-05-08 22:58:29: AddRecordToResponseList: mt-daapd._daap._tcp.local. (TXT) already in list
2006-05-08 22:58:29: AddRecordToResponseList: mt-daapd (rsp/1.0)._rsp._tcp.local. (SRV) already in list
2006-05-08 22:58:29: AddRecordToResponseList: mt-daapd (rsp/1.0)._rsp._tcp.local. (TXT) already in list
2006-05-08 22:58:29: AddRecordToResponseList: data.local. (Addr) already in list
2006-05-08 22:58:29: AddRecordToResponseList: data.local. (Addr) already in list
2006-05-08 22:58:29: select(6, 0.053710)
2006-05-08 22:58:30: mDNS_Execute
2006-05-08 22:58:30: select(6, 1868547.492187)
2006-05-08 22:58:30: SocketDataReady got a packet from 192.168.9.8 to 224.0.0.251 on interface 192.168.9.9/eth0/2
2006-05-08 22:58:30: Received Response from 192.168.9.8 addressed to 224.0.0.251 on 08096C48 TTL 255 with 0 Questions, 15 Answers, 0 Authorities, 0 Additionals
2006-05-08 22:58:30: select(6, 1868546.644531)
2006-05-08 22:58:44: Thread 636: Entering ws_dispatcher (Connection from 192.168.9.8)
2006-05-08 22:58:44: Thread 636: got request
2006-05-08 22:58:44: Request: GET /rsp/db/0/album?query=artist%3D%22Herbert%20Von%20Karajan%20Und%20Die%20Berliner%20Philharmoniker%22&type=browse HTTP/1.0
2006-05-08 22:58:44: Thread 636: Read: User-Agent: Roku SoundBridge/2.5
2006-05-08 22:58:44: Thread 636: Adding header *User-Agent=Roku SoundBridge/2.5*
2006-05-08 22:58:44: Added *User-Agent=Roku SoundBridge/2.5*
2006-05-08 22:58:44: Thread 636: Read: Host: 192.168.9.9:3689
2006-05-08 22:58:44: Thread 636: Adding header *Host=192.168.9.9:3689*
2006-05-08 22:58:44: Added *Host=192.168.9.9:3689*
2006-05-08 22:58:44: Thread 636: Read: Accept: */*
2006-05-08 22:58:44: Thread 636: Adding header *Accept=*/**
2006-05-08 22:58:44: Added *Accept=*/**
2006-05-08 22:58:44: Thread 636: Read: Authorization: Basic cm9rdTpyb2t1
2006-05-08 22:58:44: Thread 636: Adding header *Authorization=Basic cm9rdTpyb2t1*
2006-05-08 22:58:44: Added *Authorization=Basic cm9rdTpyb2t1*
2006-05-08 22:58:44: Thread 636: Read: Pragma: no-cache
2006-05-08 22:58:44: Thread 636: Adding header *Pragma=no-cache*
2006-05-08 22:58:44: Added *Pragma=no-cache*
2006-05-08 22:58:44: Thread 636: Read: accept-encoding: gzip
2006-05-08 22:58:44: Thread 636: Adding header *accept-encoding=gzip*
2006-05-08 22:58:44: Added *accept-encoding=gzip*
2006-05-08 22:58:44: Thread 636: Read: accept-codecs: wma,mpeg,wav,mp4a
2006-05-08 22:58:44: Thread 636: Adding header *accept-codecs=wma,mpeg,wav,mp4a*
2006-05-08 22:58:44: Added *accept-codecs=wma,mpeg,wav,mp4a*
2006-05-08 22:58:44: Thread 636: Read: rsp-version: 0.1
2006-05-08 22:58:44: Thread 636: Adding header *rsp-version=0.1*
2006-05-08 22:58:44: Added *rsp-version=0.1*
2006-05-08 22:58:44: Thread 636: Read: transcode-codecs: wav,mp3
2006-05-08 22:58:44: Thread 636: Adding header *transcode-codecs=wav,mp3*
2006-05-08 22:58:44: Added *transcode-codecs=wav,mp3*
2006-05-08 22:58:44: Thread 636: Read:
2006-05-08 22:58:44: Thread 636: Headers parsed!
2006-05-08 22:58:44: Checking to see if connection matches keep-alive
2006-05-08 22:58:44: Nope!
2006-05-08 22:58:44: Thread 636: Connection type HTTP/1.0
: Connection: non-persist
2006-05-08 22:58:44: Thread 636: parsing GET args
2006-05-08 22:58:44: Thread 636: Entering ws_getgetvars (query=artist%3D%22Herbert%20Von%20Karajan%20Und%20Die%20Berliner%20Philharmoniker%22&type=browse)
2006-05-08 22:58:44: Thread 636: Adding arg query = artist="Herbert Von Karajan Und Die Berliner Philharmoniker"
2006-05-08 22:58:44: Added *query=artist="Herbert Von Karajan Und Die Berliner Philharmoniker"*
2006-05-08 22:58:44: Thread 636: Adding arg type = browse
2006-05-08 22:58:44: Added *type=browse*
2006-05-08 22:58:44: Thread 636: Done parsing GET/POST args!
2006-05-08 22:58:44: Thread 636: Original URI: /rsp/db/0/album
2006-05-08 22:58:44: Thread 636: Translated URI: /rsp/db/0/album
2006-05-08 22:58:44: Thread 636: Preparing to find handler
2006-05-08 22:58:44: Thread 636: URI Match!
2006-05-08 22:58:44: Thread 636: Time is 1147121924 seconds after epoch
2006-05-08 22:58:44: Thread 636: Setting time header
2006-05-08 22:58:44: Added *Date=Mon, 8 May 2006 20:58:44 GMT*
2006-05-08 22:58:44: Added *Connection=close*
2006-05-08 22:58:44: Added *Server=mt-daapd/svn-1018*
2006-05-08 22:58:44: Added *Content-Type=text/html*
2006-05-08 22:58:44: Added *Content-Language=en_us*
2006-05-08 22:58:44: Thread 636: Using non-default handler
2006-05-08 22:58:44: in main_auth
2006-05-08 22:58:44: Entering candispatch
2006-05-08 22:58:44: Current lock level: 0
2006-05-08 22:58:44: Dispatching auth for /rsp/db/0/album to plugin
2006-05-08 22:58:44: Current lock level: 0
2006-05-08 22:58:44: Dispatching /rsp/db/0/album to rsp/1.0
2006-05-08 22:58:44: in main_handler
2006-05-08 22:58:44: Entering candispatch
2006-05-08 22:58:44: Current lock level: 0
2006-05-08 22:58:44: Dispatching /rsp/db/0/album to plugin
2006-05-08 22:58:44: Current lock level: 0
2006-05-08 22:58:44: Dispatching /rsp/db/0/album to rsp/1.0
2006-05-08 22:58:44: Tokenizing url
2006-05-08 22:58:44: Found 5 elements
2006-05-08 22:58:44: Checking reponse 0
2006-05-08 22:58:44: Checking reponse 1
2006-05-08 22:58:44: Checking reponse 2
2006-05-08 22:58:44: Checking reponse 3
2006-05-08 22:58:44: Found it! Index: 3
2006-05-08 22:58:44: Returning token 2003
2006-05-08 22:58:44: Entering sp_parse_phrase
2006-05-08 22:58:44: Entering sp_parse_oexpr
2006-05-08 22:58:44: Entering sp_parse_aexpr
2006-05-08 22:58:44: Entering sp_parse_expr
2006-05-08 22:58:44: Entering sp_parse_criterion
2006-05-08 22:58:44: Entering sp_parse_string_criterion
2006-05-08 22:58:44: Returning token 2001
2006-05-08 22:58:44: Returning token 00fd
2006-05-08 22:58:44: Exiting sp_parse_string_criterion (success)
2006-05-08 22:58:44: Exiting sp_parse_criterion (success)
2006-05-08 22:58:44: Exiting sp_parse_expr (success)
2006-05-08 22:58:44: Exiting sp_parse_aexpr (success)
2006-05-08 22:58:44: Exiting sp_parse_oexpr (success)
2006-05-08 22:58:44: Exiting sp_parse_phrase (success)
2006-05-08 22:58:44: Got query/filter
2006-05-08 22:58:44: Fetching sql statement size
2006-05-08 22:58:44: Size: 65
2006-05-08 22:58:44: Serialized to : (artist = 'Herbert Von Karajan Und Die Berliner Philharmoniker')
2006-05-08 22:58:44: Executing: select count(album) from (select distinct album from songs where ((artist = 'Herbert Von Karajan Und Die Berliner Philharmoniker')))
2006-05-08 22:58:44: Number of results: 9
2006-05-08 22:58:44: Executing: select distinct album from songs where ((artist = 'Herbert Von Karajan Und Die Berliner Philharmoniker')) limit 1000000 offset 0
2006-05-08 22:58:44: Gzipping output
2006-05-08 22:58:44: Added *Content-Encoding=gzip*
2006-05-08 22:58:44: Added *Vary=Accept-Encoding*
2006-05-08 22:58:44: Updating Connection from close to Close
2006-05-08 22:58:44: Added *Cache-Control=no-cache*
2006-05-08 22:58:44: Added *Expires=-1*
2006-05-08 22:58:44: Updating Content-Type from text/html to text/xml; charset=utf-8
2006-05-08 22:58:44: Emitting reponse header Expires: -1
2006-05-08 22:58:44: Emitting reponse header Cache-Control: no-cache
2006-05-08 22:58:44: Emitting reponse header Vary: Accept-Encoding
2006-05-08 22:58:44: Emitting reponse header Content-Encoding: gzip
2006-05-08 22:58:44: Emitting reponse header Content-Language: en_us
2006-05-08 22:58:44: Emitting reponse header Content-Type: text/xml; charset=utf-8
2006-05-08 22:58:44: Emitting reponse header Server: mt-daapd/svn-1018
2006-05-08 22:58:44: Emitting reponse header Connection: Close
2006-05-08 22:58:44: Emitting reponse header Date: Mon, 8 May 2006 20:58:44 GMT
2006-05-08 22:58:44: Done sending xml stream
2006-05-08 22:58:44: Thread 636: Terminating
2006-05-08 22:58:44: Thread 636: Freeing request headers
2006-05-08 22:58:44: Thread 636: Freeing response headers
2006-05-08 22:58:44: Thread 636: Freeing request vars
2006-05-08 22:58:44: Thread 636: Closing fd
2006-05-08 22:58:44: With thread 636 exiting, 0 are still running

I will look into it tomorrow. I have to go to bed now.

Best Regards,
Carsten.