Reply To: Firfely on MSS dies when Soundbridge tries to connect

FireFly Media Server Firefly Media Server Forums Firefly Media Server Setup Issues Firfely on MSS dies when Soundbridge tries to connect Reply To: Firfely on MSS dies when Soundbridge tries to connect

#12402
johan
Participant

I seem to have exactly the same problem with svn-1655

* mt-daapd/Firefly Version: svn-1655 (installed as described on http://wiki.fireflymediaserver.org/MSS_Installation)
* Server Operating System: 2.6.2-openmss1-rc2
* Server Hardware: Maxtor Shared Storage Plus
* Client Device: Roku Soundbridge (software version 3.0.44)
* Also tried using: iTunes 7.4.1.2 for Windows
* Connection of clients: wired ethernet 100 MBit
* FireFly shows up on soundbridge, but connection fails (see output from log file) and firefly aborts
* Web interface: shows up ok before trying to connect with soundbridge or iTunes

logfile after trying to connect with SoundBridge:
2007-09-11 12:32:51 (00013403): Thread 75:
2007-09-11 12:32:51 (00013403): Request: GET /rsp/info HTTP/1.0
2007-09-11 12:32:51 (00013403): Thread 75: Read: User-Agent: Roku SoundBridge/3.0
2007-09-11 12:32:51 (00013403): Thread 75: Adding header *User-Agent=Roku SoundBridge/3.0*
2007-09-11 12:32:51 (00013403): Added *User-Agent=Roku SoundBridge/3.0*
2007-09-11 12:32:51 (00013403): Thread 75: Read: Host: 192.168.1.101:3689
2007-09-11 12:32:51 (00013403): Thread 75: Adding header *Host=192.168.1.101:3689*
2007-09-11 12:32:51 (00013403): Added *Host=192.168.1.101:3689*
2007-09-11 12:32:51 (00013403): Thread 75: Read: Accept: */*
2007-09-11 12:32:51 (00013403): Thread 75: Adding header *Accept=*/**
2007-09-11 12:32:51 (00013403): Added *Accept=*/**
2007-09-11 12:32:51 (00013403): Thread 75: Read: Pragma: no-cache
2007-09-11 12:32:51 (00013403): Thread 75: Adding header *Pragma=no-cache*
2007-09-11 12:32:51 (00013403): Added *Pragma=no-cache*
2007-09-11 12:32:51 (00013403): Thread 75: Read: accept-encoding: gzip
2007-09-11 12:32:51 (00013403): Thread 75: Adding header *accept-encoding=gzip*
2007-09-11 12:32:51 (00013403): Added *accept-encoding=gzip*
2007-09-11 12:32:51 (00013403): Thread 75: Read: accept-codecs: wma,mpeg,wav,mp4a,alac
2007-09-11 12:32:51 (00013403): Thread 75: Adding header *accept-codecs=wma,mpeg,wav,mp4a,alac*
2007-09-11 12:32:51 (00013403): Added *accept-codecs=wma,mpeg,wav,mp4a,alac*
2007-09-11 12:32:51 (00013403): Thread 75: Read: rsp-version: 0.1
2007-09-11 12:32:51 (00013403): Thread 75: Adding header *rsp-version=0.1*
2007-09-11 12:32:51 (00013403): Added *rsp-version=0.1*
2007-09-11 12:32:51 (00013403): Thread 75: Read: transcode-codecs: wav,mp3
2007-09-11 12:32:51 (00013403): Thread 75: Adding header *transcode-codecs=wav,mp3*
2007-09-11 12:32:51 (00013403): Added *transcode-codecs=wav,mp3*
2007-09-11 12:32:51 (00013403): Thread 75: Read:
2007-09-11 12:32:51 (00013403): Thread 75: Headers parsed!
2007-09-11 12:32:51 (00013403): Checking to see if connection matches keep-alive
2007-09-11 12:32:51 (00013403): Nope!
2007-09-11 12:32:51 (00013403): Thread 75: Connection type HTTP/1.0
: Connection: non-persist
2007-09-11 12:32:51 (00013403): Thread 75: Original URI: /rsp/info
2007-09-11 12:32:51 (00013403): Thread 75: Translated URI: /rsp/info
2007-09-11 12:32:51 (00013403): Thread 75: Preparing to find handler
2007-09-11 12:32:51 (00013403): Checking /rsp/info against handler for /
2007-09-11 12:32:51 (00013403): Thread 75: URI Match!
2007-09-11 12:32:51 (00013403): Thread 75: Time is 1189539171 seconds after epoch
2007-09-11 12:32:51 (00013403): Thread 75: Setting time header
2007-09-11 12:32:51 (00013403): Added *Date=Tue, 11 Sep 2007 19:32:51 GMT*
2007-09-11 12:32:51 (00013403): Added *Connection=close*
2007-09-11 12:32:51 (00013403): Added *Server=mt-daapd/svn-1655*
2007-09-11 12:32:51 (00013403): Added *Content-Type=text/html*
2007-09-11 12:32:51 (00013403): Added *Content-Language=en_us*
2007-09-11 12:32:51 (00013403): Thread 75: Using non-default handler
2007-09-11 12:32:51 (00013403): in main_auth
2007-09-11 12:32:51 (00013403): Checking url /rsp/info
2007-09-11 12:32:51 (00013403): Dispatching auth for /rsp/info to plugin
2007-09-11 12:32:51 (00000400): Processing rendezvous message
2007-09-11 12:32:51 (00000400): Rendezvous socket closed (daap server crashed?) Aborting.
2007-09-11 12:32:51: Aborting

logging after trying to connect with iTunes:
2007-09-11 12:45:32 (00006404): Request: GET daap://192.168.1.101:3689/server-info HTTP/1.1
2007-09-11 12:45:32 (00006003): writing to socket 11
2007-09-11 12:45:32 (00006404): Thread 23: Read: Accept: */*
2007-09-11 12:45:32 (00006003): writing to socket 11
2007-09-11 12:45:32 (00006404): Thread 23: Adding header *Accept=*/**
2007-09-11 12:45:32 (00006003): writing to socket 11
2007-09-11 12:45:32 (00006404): Added *Accept=*/**
2007-09-11 12:45:32 (00006003): writing to socket 11
2007-09-11 12:45:32 (00006404): Thread 23: Read: User-Agent: iTunes/7.4.1 (Windows; N)
2007-09-11 12:45:32 (00006003): writing to socket 11
2007-09-11 12:45:32 (00006404): Thread 23: Adding header *User-Agent=iTunes/7.4.1 (Windows; N)*
2007-09-11 12:45:32 (00006003): writing to socket 11
2007-09-11 12:45:32 (00006404): Added *User-Agent=iTunes/7.4.1 (Windows; N)*
2007-09-11 12:45:32 (00006003): writing to socket 11
2007-09-11 12:45:32 (00006404): Thread 23: Read: Client-DAAP-Version: 3.5
2007-09-11 12:45:32 (00006003): writing to socket 11
2007-09-11 12:45:32 (00006404): Thread 23: Adding header *Client-DAAP-Version=3.5*
2007-09-11 12:45:32 (00006003): writing to socket 11
2007-09-11 12:45:32 (00006404): Added *Client-DAAP-Version=3.5*
2007-09-11 12:45:32 (00006003): writing to socket 11
2007-09-11 12:45:32 (00006404): Thread 23: Read: Client-DAAP-Access-Index: 2
2007-09-11 12:45:32 (00006003): writing to socket 11
2007-09-11 12:45:32 (00006404): Thread 23: Adding header *Client-DAAP-Access-Index=2*
2007-09-11 12:45:32 (00006003): writing to socket 11
2007-09-11 12:45:32 (00006404): Added *Client-DAAP-Access-Index=2*
2007-09-11 12:45:32 (00006003): writing to socket 11
2007-09-11 12:45:32 (00006404): Thread 23: Read: Accept-Encoding: gzip
2007-09-11 12:45:32 (00006003): writing to socket 11
2007-09-11 12:45:32 (00006404): Thread 23: Adding header *Accept-Encoding=gzip*
2007-09-11 12:45:32 (00006003): writing to socket 11
2007-09-11 12:45:32 (00006404): Added *Accept-Encoding=gzip*
2007-09-11 12:45:32 (00006003): writing to socket 11
2007-09-11 12:45:32 (00006404): Thread 23: Read:
2007-09-11 12:45:32 (00006003): writing to socket 11
2007-09-11 12:45:32 (00006404): Thread 23: Headers parsed!
2007-09-11 12:45:32 (00006003): writing to socket 11
2007-09-11 12:45:32 (00006404): Checking to see if connection matches close
2007-09-11 12:45:32 (00006003): writing to socket 11
2007-09-11 12:45:32 (00006404): Nope!
2007-09-11 12:45:32 (00006003): writing to socket 11
2007-09-11 12:45:32 (00006404): Thread 23: Connection type HTTP/1.1
: Connection: persist
2007-09-11 12:45:32 (00006003): writing to socket 11
2007-09-11 12:45:32 (00006404): Thread 23: Original URI: daap://192.168.1.101:3689/server-info
2007-09-11 12:45:32 (00006003): writing to socket 11
2007-09-11 12:45:32 (00006404): Thread 23: Translated URI: /server-info
2007-09-11 12:45:32 (00006003): writing to socket 11
2007-09-11 12:45:32 (00006404): Thread 23: Preparing to find handler
2007-09-11 12:45:32 (00006003): writing to socket 11
2007-09-11 12:45:32 (00006404): Checking /server-info against handler for /
2007-09-11 12:45:32 (00006003): writing to socket 11
2007-09-11 12:45:32 (00006404): Thread 23: URI Match!
2007-09-11 12:45:32 (00006003): writing to socket 11
2007-09-11 12:45:32 (00006404): Thread 23: Time is 1189539932 seconds after epoch
2007-09-11 12:45:32 (00006003): writing to socket 11
2007-09-11 12:45:32 (00006404): Thread 23: Setting time header
2007-09-11 12:45:32 (00006003): writing to socket 11
2007-09-11 12:45:32 (00006404): Added *Date=Tue, 11 Sep 2007 19:45:32 GMT*
2007-09-11 12:45:32 (00006003): writing to socket 11
2007-09-11 12:45:32 (00006404): Added *Connection=keep-alive*
2007-09-11 12:45:32 (00006003): writing to socket 11
2007-09-11 12:45:32 (00006404): Added *Server=mt-daapd/svn-1655*
2007-09-11 12:45:32 (00006003): writing to socket 11
2007-09-11 12:45:32 (00006404): Added *Content-Type=text/html*
2007-09-11 12:45:32 (00006003): writing to socket 11
2007-09-11 12:45:32 (00006404): Added *Content-Language=en_us*
2007-09-11 12:45:32 (00006003): writing to socket 11
2007-09-11 12:45:32 (00006404): Thread 23: Using non-default handler
2007-09-11 12:45:32 (00006003): writing to socket 11
2007-09-11 12:45:32 (00006404): in main_auth
2007-09-11 12:45:32 (00006003): writing to socket 11
2007-09-11 12:45:32 (00006404): Checking url /server-info
2007-09-11 12:45:32 (00006003): writing to socket 11
2007-09-11 12:45:32 (00006404): Checking url /server-info
2007-09-11 12:45:32 (00006003): writing to socket 11
2007-09-11 12:45:32 (00006404): Dispatching auth for /server-info to plugin
2007-09-11 12:45:32 (00006003): writing to socket 11
2007-09-11 12:45:32 (00000400): Processing rendezvous message
2007-09-11 12:45:32 (00000400): Rendezvous socket closed (daap server crashed?) Aborting.
2007-09-11 12:45:32: Aborting