Server crashes after long idle mode period

@Metzeler wrote:

Hi,

after my issue with the crash on startup is solved, I´ve got another problem. Firefly is crashing when the server is in idle mode for a longer timeperiod.
I´m running SVN-1671 on the NSLU with V2.3R63-uNSLUng-6.8-beta .

Here is an excerpt from my logfile:

2007-09-30 19:01:38 (00058803): Thread 352: Read: Accept: text/xml,application/xml,application/xhtml+xml,text/html;q=0.9,text/plain;q=0.8,image/png,*/*;q=0.5
2007-09-30 19:01:38 (00058803): Thread 352: Adding header *Accept=text/xml,application/xml,application/xhtml+xml,text/html;q=0.9,text/plain;q=0.8,image/png,*/*;q=0.5*
2007-09-30 19:01:38 (00058803): Added *Accept=text/xml,application/xml,application/xhtml+xml,text/html;q=0.9,text/plain;q=0.8,image/png,*/*;q=0.5*
2007-09-30 19:01:38 (00058803): Thread 352: Read: Accept-Language: de-de,de;q=0.8,en-us;q=0.5,en;q=0.3
2007-09-30 19:01:38 (00058803): Thread 352: Adding header *Accept-Language=de-de,de;q=0.8,en-us;q=0.5,en;q=0.3*
2007-09-30 19:01:38 (00058803): Added *Accept-Language=de-de,de;q=0.8,en-us;q=0.5,en;q=0.3*
2007-09-30 19:01:38 (00058803): Thread 352: Read: Accept-Encoding: gzip,deflate
2007-09-30 19:01:38 (00058803): Thread 352: Adding header *Accept-Encoding=gzip,deflate*
2007-09-30 19:01:38 (00058803): Added *Accept-Encoding=gzip,deflate*
2007-09-30 19:01:38 (00058803): Thread 352: Read: Accept-Charset: ISO-8859-1,utf-8;q=0.7,*;q=0.7
2007-09-30 19:01:38 (00058803): Thread 352: Adding header *Accept-Charset=ISO-8859-1,utf-8;q=0.7,*;q=0.7*
2007-09-30 19:01:38 (00058803): Added *Accept-Charset=ISO-8859-1,utf-8;q=0.7,*;q=0.7*
2007-09-30 19:01:38 (00058803): Thread 352: Read: Keep-Alive: 300
2007-09-30 19:01:38 (00058803): Thread 352: Adding header *Keep-Alive=300*
2007-09-30 19:01:38 (00058803): Added *Keep-Alive=300*
2007-09-30 19:01:38 (00058803): Thread 352: Read: Connection: keep-alive
2007-09-30 19:01:38 (00058803): Thread 352: Adding header *Connection=keep-alive*
2007-09-30 19:01:38 (00058803): Added *Connection=keep-alive*
2007-09-30 19:01:38 (00058803): Thread 352: Read: X-Requested-With: XMLHttpRequest
2007-09-30 19:01:38 (00058803): Thread 352: Adding header *X-Requested-With=XMLHttpRequest*
2007-09-30 19:01:38 (00058803): Added *X-Requested-With=XMLHttpRequest*
2007-09-30 19:01:38 (00058803): Thread 352: Read: X-Prototype-Version: 1.4.0
2007-09-30 19:01:38 (00058803): Thread 352: Adding header *X-Prototype-Version=1.4.0*
2007-09-30 19:01:38 (00058803): Added *X-Prototype-Version=1.4.0*
2007-09-30 19:01:38 (00058803): Thread 352: Read: Referer: http://192.168.2.32:3689/index.html
2007-09-30 19:01:38 (00058803): Thread 352: Adding header *Referer=http://192.168.2.32:3689/index.html*
2007-09-30 19:01:38 (00058803): Added *Referer=http://192.168.2.32:3689/index.html*
2007-09-30 19:01:38 (00058803): Thread 352: Read: Authorization: Basic Om10LWRhYXBk
2007-09-30 19:01:38 (00058803): Thread 352: Adding header *Authorization=Basic Om10LWRhYXBk*
2007-09-30 19:01:38 (00058803): Added *Authorization=Basic Om10LWRhYXBk*
2007-09-30 19:01:38 (00058803): Thread 352: Read:
2007-09-30 19:01:38 (00058803): Thread 352: Headers parsed!
2007-09-30 19:01:38 (00058803): Checking to see if connection matches close
2007-09-30 19:01:38 (00058803): And it does NOT
2007-09-30 19:01:38 (00058803): Thread 352: Connection type HTTP/1.1
: Connection: persist
2007-09-30 19:01:38 (00058803): Thread 352: parsing GET args
2007-09-30 19:01:38 (00058803): Thread 352: Adding arg method = stats
2007-09-30 19:01:38 (00058803): Added *method=stats*
2007-09-30 19:01:38 (00058803): Thread 352: Done parsing GET/POST args!
2007-09-30 19:01:38 (00058803): Thread 352: Original URI: /xml-rpc
2007-09-30 19:01:38 (00058803): Thread 352: Translated URI: /xml-rpc
2007-09-30 19:01:38 (00058803): Thread 352: Preparing to find handler
2007-09-30 19:01:38 (00058803): Checking /xml-rpc against handler for /
2007-09-30 19:01:38 (00058803): Thread 352: URI Match!
2007-09-30 19:01:38 (00058803): Thread 352: Time is 1191171698 seconds after epoch
2007-09-30 19:01:38 (00058803): Thread 352: Setting time header
2007-09-30 19:01:38 (00058803): Added *Date=Sun, 30 Sep 2007 17:01:38 GMT*
2007-09-30 19:01:38 (00058803): Added *Connection=keep-alive*
2007-09-30 19:01:38 (00058803): Added *Server=mt-daapd/svn-1671*
2007-09-30 19:01:38 (00058803): Added *Content-Type=text/html*
2007-09-30 19:01:38 (00058803): Added *Content-Language=en_us*
2007-09-30 19:01:38 (00058803): Thread 352: Using non-default handler
2007-09-30 19:01:38 (00058803): in main_auth
2007-09-30 19:01:38 (00058803): Checking url /xml-rpc
2007-09-30 19:01:38 (00058803): Checking url /xml-rpc
2007-09-30 19:01:38 (00058803): Dispatching auth for /xml-rpc to config auth
2007-09-30 19:01:38 (00058803): Checking if pw required for /xml-rpc as admin
2007-09-30 19:01:38 (00058803): Yep
2007-09-30 19:01:38 (00058803): Preparing to decode Om10LWRhYXBk
2007-09-30 19:01:38 (00058803): Decoded :mt-daapd
2007-09-30 19:01:38 (00058803): Decoded user=, pw=mt-daapd
2007-09-30 19:01:38 (00058803): in main_auth
2007-09-30 19:01:38 (00058803): Checking url /xml-rpc
2007-09-30 19:01:38 (00058803): Checking url /xml-rpc
2007-09-30 19:01:38 (00058803): Dispatching auth for /xml-rpc to config auth
2007-09-30 19:01:38 (00058803): Added *HTTP_USER=*
2007-09-30 19:01:38 (00058803): Added *HTTP_PASSWD=mt-daapd*
2007-09-30 19:01:38 (00058803): in main_handler
2007-09-30 19:01:38 (00058803): Checking url /xml-rpc
2007-09-30 19:01:38 (00058803): Checking url /xml-rpc
2007-09-30 19:01:38 (00058803): Dispatching /xml-rpc to config handler
2007-09-30 19:01:38 (00058803): Entering config_handler
2007-09-30 19:01:38 (00058803): Entering config_set_status
2007-09-30 19:01:38 (00058803): Exiting config_set_status
2007-09-30 19:01:38 (00058803): Updating Connection from keep-alive to close
2007-09-30 19:01:38 (00058803): Entering config_set_status
2007-09-30 19:01:38 (00058803): Exiting config_set_status
2007-09-30 19:01:38 (00058803): Added *Cache-Control=no-cache*
2007-09-30 19:01:38 (00058803): Added *Expires=-1*
2007-09-30 19:01:38 (00058803): Updating Content-Type from text/html to text/xml; charset=utf-8
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): Emitting reponse header Expires: -1
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): Emitting reponse header Cache-Control: no-cache
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): Emitting reponse header Content-Language: en_us
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): Emitting reponse header Content-Type: text/xml; charset=utf-8
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): Emitting reponse header Server: mt-daapd/svn-1671
2007-09-30 19:01:38 (00058803)2007-09-30 19:01:38 (00058803): writing to socket 12

2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): Status inquiry
2007-09-30 19:01:38 (00000400): Processing rendezvous message
2007-09-30 19:01:38 (00058803): Returning status 0
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): writing to socket 12
2007-09-30 19:01:38 (00058803): Executing: select count(*) FROM songs
2007-09-30 19:01:53 (00000400): Processing rendezvous message
2007-09-30 19:01:53 (00000400): Rendezvous socket closed (daap server crashed?) Aborting.
2007-09-30 19:01:53: Aborting

Thanks for your help!

Sven

Is your config set up for sqlite or sqlite3? If sqlite3, try sqlite.

— Ron

@Metzeler wrote:

Hi Ron,

my config is set up for sqlite 🙁

Do you have another idea?

Really? That looks *exactly* like the sqlite3 issue. Unless the sqlite3 thing is just a symptom.

@Metzeler wrote:

@rpedde wrote:

@Metzeler wrote:

Hi Ron,

my config is set up for sqlite 🙁

Do you have another idea?

Really? That looks *exactly* like the sqlite3 issue. Unless the sqlite3 thing is just a symptom.

Hi Ron,

I´ve checked my settings. The database type is currently set to “sqlite”. ❓

Hrmph. Probably the same issue, just less visible as sqlite is more tolerant of misuse.

Wish sqlite gave some debugging info. I’ll keep looking for it. I’ll have a workaround in the next nightly for sqlite3, but I still need to find the root cause.

— Ron