svn-1659 crashes when SB tries to access

[thorstenhirsch]

Hi,

I just upgraded firefly from svn-1586 to svn-1659. Now when my SB tries to access the server, it just crashes. The SB tells me “connection with server failed”.

Here’s the logfile at debug level 9:

2007-09-15 12:20:00 (00004000): Checking daap/svn-1659

2007-09-15 12:20:00 (00004000): Registering _daap._tcp

2007-09-15 12:20:00 (00004000): Processing rendezvous message

2007-09-15 12:20:00 (00004000): Registering Firefly on tuxi._daap._tcp (3689)

2007-09-15 12:20:00 (00004000): Registered service 2, name 'Firefly on tuxi', type '_daap._tcp', port 3689

2007-09-15 12:20:00 (00004000): Executing: select count(*) FROM songs

2007-09-15 12:20:01 (00004000): Serving 8562 songs.  Startup complete in 8 seconds

2007-09-15 12:20:01 (00004000): Callback: Firefly on tuxi_http_tcplocal Name Registered

2007-09-15 12:20:01 (00004000): Callback: Firefly on tuxi_rsp_tcplocal Name Registered

2007-09-15 12:20:01 (00004000): Callback: Firefly on tuxi_daap_tcplocal Name Registered

2007-09-15 12:20:22 (00004002): Got listen socket 11

2007-09-15 12:20:22 (00008003): Thread 0:

2007-09-15 12:20:22 (00008003): Request: GET /rsp/info HTTP/1.0

2007-09-15 12:20:22 (00008003): Thread 0: Read: User-Agent: Roku SoundBridge/3.0

2007-09-15 12:20:22 (00008003): Thread 0: Adding header *User-Agent=Roku SoundBridge/3.0*

2007-09-15 12:20:22 (00008003): Added *User-Agent=Roku SoundBridge/3.0*

2007-09-15 12:20:22 (00008003): Thread 0: Read: Host: 192.168.0.7:3689

2007-09-15 12:20:22 (00008003): Thread 0: Adding header *Host=192.168.0.7:3689*

2007-09-15 12:20:22 (00008003): Added *Host=192.168.0.7:3689*

2007-09-15 12:20:22 (00008003): Thread 0: Read: Accept: */*

2007-09-15 12:20:22 (00008003): Thread 0: Adding header *Accept=*/**

2007-09-15 12:20:22 (00008003): Added *Accept=*/**

2007-09-15 12:20:22 (00008003): Thread 0: Read: Pragma: no-cache

2007-09-15 12:20:22 (00008003): Thread 0: Adding header *Pragma=no-cache*

2007-09-15 12:20:22 (00008003): Added *Pragma=no-cache*

2007-09-15 12:20:22 (00008003): Thread 0: Read: accept-encoding: gzip

2007-09-15 12:20:22 (00008003): Thread 0: Adding header *accept-encoding=gzip*

2007-09-15 12:20:22 (00008003): Added *accept-encoding=gzip*

2007-09-15 12:20:22 (00008003): Thread 0: Read: accept-codecs: wma,mpeg,wav,mp4a,alac

2007-09-15 12:20:22 (00008003): Thread 0: Adding header *accept-codecs=wma,mpeg,wav,mp4a,alac*

2007-09-15 12:20:22 (00008003): Added *accept-codecs=wma,mpeg,wav,mp4a,alac*

2007-09-15 12:20:22 (00008003): Thread 0: Read: rsp-version: 0.1

2007-09-15 12:20:22 (00008003): Thread 0: Adding header *rsp-version=0.1*

2007-09-15 12:20:22 (00008003): Added *rsp-version=0.1*

2007-09-15 12:20:22 (00008003): Thread 0: Read: transcode-codecs: wav,mp3

2007-09-15 12:20:22 (00008003): Thread 0: Adding header *transcode-codecs=wav,mp3*

2007-09-15 12:20:22 (00008003): Added *transcode-codecs=wav,mp3*

2007-09-15 12:20:22 (00008003): Thread 0: Read:

2007-09-15 12:20:22 (00008003): Thread 0: Headers parsed!

2007-09-15 12:20:22 (00008003): Checking to see if connection matches keep-alive

2007-09-15 12:20:22 (00008003): Nope!

2007-09-15 12:20:22 (00008003): Thread 0: Connection type HTTP/1.0

: Connection: non-persist

2007-09-15 12:20:22 (00008003): Thread 0: Original URI: /rsp/info

2007-09-15 12:20:22 (00008003): Thread 0: Translated URI: /rsp/info

2007-09-15 12:20:22 (00008003): Thread 0: Preparing to find handler

2007-09-15 12:20:22 (00008003): Checking /rsp/info against handler for /

2007-09-15 12:20:22 (00008003): Thread 0: URI Match!

2007-09-15 12:20:22 (00008003): Thread 0: Time is 1189876822 seconds after epoch

2007-09-15 12:20:22 (00008003): Thread 0: Setting time header

2007-09-15 12:20:22 (00008003): Added *Date=Sat, 15 Sep 2007 17:20:22 GMT*

2007-09-15 12:20:22 (00008003): Added *Connection=close*

2007-09-15 12:20:22 (00008003): Added *Server=mt-daapd/svn-1659*

2007-09-15 12:20:22 (00008003): Added *Content-Type=text/html*

2007-09-15 12:20:22 (00008003): Added *Content-Language=en_us*

2007-09-15 12:20:22 (00008003): Thread 0: Using non-default handler

2007-09-15 12:20:22 (00008003): in main_auth

2007-09-15 12:20:22 (00008003): Checking url /rsp/info

2007-09-15 12:20:22 (00008003): Dispatching auth for /rsp/info to plugin

2007-09-15 12:20:22 (00004000): Processing rendezvous message

2007-09-15 12:20:22 (00004000): Rendezvous socket closed (daap server crashed?) Aborting.

[dizziness]

I’m having the same error. See:

http://forums.fireflymediaserver.org/viewtopic.php?p=14573#14573

[rpedde]

@dizziness wrote:

I’m having the same error. See:

http://forums.fireflymediaserver.org/viewtopic.php?p=14573#14573

os, version, arch (including integer size) so I can repro this, please.

[thorstenhirsch]

$ uname -a

Linux TUXI 2.4.20_mipsel_linkstation #88 2005? 2?? 25?? ?????? 20:17:00 JST mips unknown

sizeOf(int) is 4
OS is difficult to describe …well, as you can see it has a Linux 2.4.20 kernel, the rest is a mixture of OpenLink 0.52b and some updated tools, including a new version of busybox: v 1.4.1.

[rpedde]

@thorstenhirsch wrote:

$ uname -a

Linux TUXI 2.4.20_mipsel_linkstation #88 2005? 2?? 25?? ?????? 20:17:00 JST mips unknown

sizeOf(int) is 4
OS is difficult to describe …well, as you can see it has a Linux 2.4.20 kernel, the rest is a mixture of OpenLink 0.52b and some updated tools, including a new version of busybox: v 1.4.1.

Seems like the reports on this are all mipsel.

Was this compiled by hand, or installed from a package?

[thorstenhirsch]

I compiled it by hand. And all the other libs (libogg, libvorbis, gdbm, sqlite3, …) have also been compiled by hand. And I did not upgrade libc or the kernel afterwards.

[rpedde]

@thorstenhirsch wrote:

I compiled it by hand. And all the other libs (libogg, libvorbis, gdbm, sqlite3, …) have also been compiled by hand. And I did not upgrade libc or the kernel afterwards.

I have a maybe fix in next nightlies, which will be as soon as I get the mac build chain working again. Another couple hours or so, I hope.

– Ron

[raveman]

got same problem with previous and 1668 nightly build. my system is RHEL4 server
Linux bigmac.XXX.net 2.6.9-55.EL.aspsmp #1 SMP Wed May 9 20:30:22 EEST 2007 i686 i686 i386 GNU/Linux
using with self compiled version sqlite3

2007-09-24 17:38:45 (b7fc2aa0): Executing: select count(*) FROM songs

2007-09-24 17:38:45 (b7fc2aa0): Scanned 21081 songs (was 21081) in 62 seconds

2007-09-24 17:42:05 (b7fc1ba0): Got listen socket 13

2007-09-24 17:42:05 (b75c0ba0): Thread 0:

2007-09-24 17:42:05 (b75c0ba0): Request: GET daap://10.0.20.20:3689/server-info HTTP/1.1

2007-09-24 17:42:05 (b75c0ba0): Thread 0: Read: Accept: */*

2007-09-24 17:42:05 (b75c0ba0): Thread 0: Adding header *Accept=*/**

2007-09-24 17:42:05 (b75c0ba0): Added *Accept=*/**

2007-09-24 17:42:05 (b75c0ba0): Thread 0: Read: User-Agent: iTunes/7.4.2 (Macintosh; N; Intel)

2007-09-24 17:42:05 (b75c0ba0): Thread 0: Adding header *User-Agent=iTunes/7.4.2 (Macintosh; N; Intel)*

2007-09-24 17:42:05 (b75c0ba0): Added *User-Agent=iTunes/7.4.2 (Macintosh; N; Intel)*

2007-09-24 17:42:05 (b75c0ba0): Thread 0: Read: Client-DAAP-Version: 3.5

2007-09-24 17:42:05 (b75c0ba0): Thread 0: Adding header *Client-DAAP-Version=3.5*

2007-09-24 17:42:05 (b75c0ba0): Added *Client-DAAP-Version=3.5*

2007-09-24 17:42:05 (b75c0ba0): Thread 0: Read: Client-DAAP-Access-Index: 2

2007-09-24 17:42:05 (b75c0ba0): Thread 0: Adding header *Client-DAAP-Access-Index=2*

2007-09-24 17:42:05 (b75c0ba0): Added *Client-DAAP-Access-Index=2*

2007-09-24 17:42:05 (b75c0ba0): Thread 0: Read: Accept-Encoding: gzip

2007-09-24 17:42:05 (b75c0ba0): Thread 0: Adding header *Accept-Encoding=gzip*

2007-09-24 17:42:05 (b75c0ba0): Added *Accept-Encoding=gzip*

2007-09-24 17:42:05 (b75c0ba0): Thread 0: Read:

2007-09-24 17:42:05 (b75c0ba0): Thread 0: Headers parsed!

2007-09-24 17:42:05 (b75c0ba0): Checking to see if connection matches close

2007-09-24 17:42:05 (b75c0ba0): Nope!

2007-09-24 17:42:05 (b75c0ba0): Thread 0: Connection type HTTP/1.1

: Connection: persist

2007-09-24 17:42:05 (b75c0ba0): Thread 0: Original URI: daap://10.0.20.20:3689/server-info

2007-09-24 17:42:05 (b75c0ba0): Thread 0: Translated URI: /server-info

2007-09-24 17:42:05 (b75c0ba0): Thread 0: Preparing to find handler

2007-09-24 17:42:05 (b75c0ba0): Checking /server-info against handler for /

2007-09-24 17:42:05 (b75c0ba0): Thread 0: URI Match!

2007-09-24 17:42:05 (b75c0ba0): Thread 0: Time is 1190641325 seconds after epoch

2007-09-24 17:42:05 (b75c0ba0): Thread 0: Setting time header

2007-09-24 17:42:05 (b75c0ba0): Added *Date=Mon, 24 Sep 2007 13:42:05 GMT*

2007-09-24 17:42:05 (b75c0ba0): Added *Connection=keep-alive*

2007-09-24 17:42:05 (b75c0ba0): Added *Server=mt-daapd/svn-1668*

2007-09-24 17:42:05 (b75c0ba0): Added *Content-Type=text/html*

2007-09-24 17:42:05 (b75c0ba0): Added *Content-Language=en_us*

2007-09-24 17:42:05 (b75c0ba0): Thread 0: Using non-default handler

2007-09-24 17:42:05 (b75c0ba0): in main_auth

2007-09-24 17:42:05 (b7fc2aa0): Processing rendezvous message

2007-09-24 17:42:05 (b7fc2aa0): Rendezvous socket closed (daap server crashed?) Aborting.

2007-09-24 17:42:05: Aborting

[fizze]

Well, it sure is working fine on my NSLU2 with my soundbridge.
I did do a full rescan though.

[thorstenhirsch]

Still crashing here with svn-1668. When my SB tries to connect, it crashes immediately. Using the web-interface, it doesn’t crash immediately – I can check my config settings and start a full rescan …but after some seconds, it also crashes. The log file seems to show the same lines as with 1659.

[Author]

Posts