DAAP server crashes when accessed by iTunes

[Anonymous]

It seems that as soon as iTunes tries to read from the DAAP server, it crashes. I turned on verbose logging hoping to find the problem, and I found something interesting, but I don’t know what to make of it.

1999-12-02 11:02:17: Thread 3: Entering ws_dispatcher (Connection from 192.168.1.108)
1999-12-02 11:02:17: select(6, 0.052734)
1999-12-02 11:02:17: Thread 3: got request
1999-12-02 11:02:17: Request: GET daap://192.168.1.77:3689/server-info HTTP/1.1
1999-12-02 11:02:17: Thread 3: Read: Accept: */*
1999-12-02 11:02:17: Thread 3: Adding header *Accept=*/**
1999-12-02 11:02:17: Added *Accept=*/**
1999-12-02 11:02:17: Thread 3: Read: User-Agent: iTunes/7.6.1 (Macintosh; N; Intel)
1999-12-02 11:02:17: Thread 3: Adding header *User-Agent=iTunes/7.6.1 (Macintosh; N; Intel)*
1999-12-02 11:02:17: Added *User-Agent=iTunes/7.6.1 (Macintosh; N; Intel)*
1999-12-02 11:02:17: Thread 3: Read: Client-DAAP-Version: 3.6
1999-12-02 11:02:17: Thread 3: Adding header *Client-DAAP-Version=3.6*
1999-12-02 11:02:17: Added *Client-DAAP-Version=3.6*
1999-12-02 11:02:17: Thread 3: Read: Client-DAAP-Access-Index: 2
1999-12-02 11:02:17: Thread 3: Adding header *Client-DAAP-Access-Index=2*
1999-12-02 11:02:17: Added *Client-DAAP-Access-Index=2*
1999-12-02 11:02:17: Thread 3: Read: Accept-Encoding: gzip
1999-12-02 11:02:17: Thread 3: Adding header *Accept-Encoding=gzip*
1999-12-02 11:02:17: Added *Accept-Encoding=gzip*
1999-12-02 11:02:17: Thread 3: Read:
1999-12-02 11:02:17: Thread 3: Headers parsed!
1999-12-02 11:02:17: Checking to see if connection matches close
1999-12-02 11:02:17: Thread 3: Connection type HTTP/1.1
: Connection: persist
1999-12-02 11:02:17: Thread 3: Original URI: daap://192.168.1.77:3689/server-info
1999-12-02 11:02:17: Thread 3: Translated URI: /server-info
1999-12-02 11:02:17: Thread 3: Preparing to find handler
1999-12-02 11:02:17: Thread 3: URI Match!
1999-12-02 11:02:17: Thread 3: Time is 944132537 seconds after epoch
1999-12-02 11:02:17: Thread 3: Setting time header
1999-12-02 11:02:17: Added *Date=Thu, 2 Dec 1999 11:02:17 GMT*
1999-12-02 11:02:17: Thread 3: Using non-default handler
1999-12-02 11:02:17: Added *Accept-Ranges=bytes*
1999-12-02 11:02:17: Added *DAAP-Server=mt-daapd/0.2.3*
1999-12-02 11:02:17: Added *Content-Type=application/x-dmap-tagged*
1999-12-02 11:02:17: Entering config_set_status
1999-12-02 11:02:17: Exiting config_set_status
1999-12-02 11:02:17: Preparing to send server-info for client ver 3.6
1999-12-02 11:02:17: Satisfying request
1999-12-02 11:02:17: Serializing
1999-12-02 11:02:17: Added *Content-Length=141*
1999-12-02 11:02:17: Emitting headers
1999-12-02 11:02:17: Emitting reponse header Content-Length: 141
1999-12-02 11:02:17: Emitting reponse header Content-Type: application/x-dmap-tagged
1999-12-02 11:02:17: Emitting reponse header DAAP-Server: mt-daapd/0.2.3
1999-12-02 11:02:17: Emitting reponse header Accept-Ranges: bytes
1999-12-02 11:02:17: Emitting reponse header Date: Thu, 2 Dec 1999 11:02:17 GMT
1999-12-02 11:02:17: Sent 141 bytes in 0 seconds
1999-12-02 11:02:17: Done, freeing
1999-12-02 11:02:17: Finished serving DAAP response
1999-12-02 11:02:17: Entering config_set_status
1999-12-02 11:02:17: Exiting config_set_status
1999-12-02 11:02:17: Thread 3: Terminating
1999-12-02 11:02:17: Thread 3: Freeing request headers
1999-12-02 11:02:17: Thread 3: Freeing response headers
1999-12-02 11:02:17: Thread 3: Freeing request vars
1999-12-02 11:02:17: Thread 3: got request
1999-12-02 11:02:17: Request: GET /content-codes HTTP/1.1
1999-12-02 11:02:17: Thread 3: Read: Host: 192.168.1.77
1999-12-02 11:02:17: Thread 3: Adding header *Host=192.168.1.77*
1999-12-02 11:02:17: Added *Host=192.168.1.77*
1999-12-02 11:02:17: Thread 3: Read: Client-DAAP-Version: 3.6
1999-12-02 11:02:17: Thread 3: Adding header *Client-DAAP-Version=3.6*
1999-12-02 11:02:17: Added *Client-DAAP-Version=3.6*
1999-12-02 11:02:17: Thread 3: Read: User-Agent: iTunes/7.6.1 (Macintosh; N; Intel)
1999-12-02 11:02:17: Thread 3: Adding header *User-Agent=iTunes/7.6.1 (Macintosh; N; Intel)*
1999-12-02 11:02:17: Added *User-Agent=iTunes/7.6.1 (Macintosh; N; Intel)*
1999-12-02 11:02:17: Thread 3: Read: Accept-Language: en-us, en;q=0.50
1999-12-02 11:02:17: Thread 3: Adding header *Accept-Language=en-us, en;q=0.50*
1999-12-02 11:02:17: Added *Accept-Language=en-us, en;q=0.50*
1999-12-02 11:02:17: Thread 3: Read: Accept-Encoding: gzip
1999-12-02 11:02:17: Thread 3: Adding header *Accept-Encoding=gzip*
1999-12-02 11:02:17: Added *Accept-Encoding=gzip*
1999-12-02 11:02:17: Thread 3: Read:
1999-12-02 11:02:17: Thread 3: Headers parsed!
1999-12-02 11:02:17: Checking to see if connection matches close
1999-12-02 11:02:17: Thread 3: Connection type HTTP/1.1
: Connection: persist
1999-12-02 11:02:17: Thread 3: Original URI: /content-codes
1999-12-02 11:02:17: Thread 3: Translated URI: /content-codes
1999-12-02 11:02:17: Thread 3: Preparing to find handler
1999-12-02 11:02:17: select(6, 0.100585)
1999-12-02 11:02:17: select(6, 0.099609)
1999-12-02 11:02:17: Thread 3: URI Match!
1999-12-02 11:02:17: Thread 3: Time is 944132537 seconds after epoch
1999-12-02 11:02:17: Thread 3: Setting time header
1999-12-02 11:02:17: Added *Date=Thu, 2 Dec 1999 11:02:17 GMT*
1999-12-02 11:02:17: Thread 3: Using non-default handler
1999-12-02 11:02:17: Added *Accept-Ranges=bytes*
1999-12-02 11:02:17: Added *DAAP-Server=mt-daapd/0.2.3*
1999-12-02 11:02:17: Added *Content-Type=application/x-dmap-tagged*
1999-12-02 11:02:17: Entering config_set_status
1999-12-02 11:02:17: Exiting config_set_status
1999-12-02 11:02:17: Preparing to get content codes
1999-12-02 11:02:17: Satisfying request
1999-12-02 11:02:17: Serializing
1999-12-02 11:02:17: Added *Content-Length=4767*
1999-12-02 11:02:17: Emitting headers
1999-12-02 11:02:17: Emitting reponse header Content-Length: 4767
1999-12-02 11:02:17: Emitting reponse header Content-Type: application/x-dmap-tagged
1999-12-02 11:02:17: Emitting reponse header DAAP-Server: mt-daapd/0.2.3
1999-12-02 11:02:17: Emitting reponse header Accept-Ranges: bytes
1999-12-02 11:02:17: Emitting reponse header Date: Thu, 2 Dec 1999 11:02:17 GMT
1999-12-02 11:02:17: Sent 4767 bytes in 0 seconds
1999-12-02 11:02:17: Done, freeing
1999-12-02 11:02:17: Finished serving DAAP response
1999-12-02 11:02:17: Entering config_set_status
1999-12-02 11:02:17: Exiting config_set_status
1999-12-02 11:02:17: Thread 3: Terminating
1999-12-02 11:02:17: Thread 3: Freeing request headers
1999-12-02 11:02:17: Thread 3: Freeing response headers
1999-12-02 11:02:17: Thread 3: Freeing request vars
1999-12-02 11:02:17: Thread 3: got request
1999-12-02 11:02:17: Request: GET /update?revision-number=1 HTTP/1.1
1999-12-02 11:02:17: Thread 3: Read: Host: 192.168.1.77
1999-12-02 11:02:17: Thread 3: Adding header *Host=192.168.1.77*
1999-12-02 11:02:17: Added *Host=192.168.1.77*
1999-12-02 11:02:17: Thread 3: Read: Client-DAAP-Version: 3.6
1999-12-02 11:02:17: Thread 3: Adding header *Client-DAAP-Version=3.6*
1999-12-02 11:02:17: Added *Client-DAAP-Version=3.6*
1999-12-02 11:02:17: Thread 3: Read: User-Agent: iTunes/7.6.1 (Macintosh; N; Intel)
1999-12-02 11:02:17: Thread 3: Adding header *User-Agent=iTunes/7.6.1 (Macintosh; N; Intel)*
1999-12-02 11:02:17: Added *User-Agent=iTunes/7.6.1 (Macintosh; N; Intel)*
1999-12-02 11:02:17: Thread 3: Read: Accept-Language: en-us, en;q=0.50
1999-12-02 11:02:17: Thread 3: Adding header *Accept-Language=en-us, en;q=0.50*
1999-12-02 11:02:17: Added *Accept-Language=en-us, en;q=0.50*
1999-12-02 11:02:17: Thread 3: Read: Client-DAAP-Access-Index: 2
1999-12-02 11:02:17: Thread 3: Adding header *Client-DAAP-Access-Index=2*
1999-12-02 11:02:17: Added *Client-DAAP-Access-Index=2*
1999-12-02 11:02:17: Thread 3: Read: Client-DAAP-Validation: 7034F4ECD67CE872545B0A5E1924FD63
1999-12-02 11:02:17: Thread 3: Adding header *Client-DAAP-Validation=7034F4ECD67CE872545B0A5E1924FD63*
1999-12-02 11:02:17: Added *Client-DAAP-Validation=7034F4ECD67CE872545B0A5E1924FD63*
1999-12-02 11:02:17: Thread 3: Read: Accept-Encoding: gzip
1999-12-02 11:02:17: Thread 3: Adding header *Accept-Encoding=gzip*
1999-12-02 11:02:17: Added *Accept-Encoding=gzip*
1999-12-02 11:02:17: Thread 3: Read:
1999-12-02 11:02:17: Thread 3: Headers parsed!
1999-12-02 11:02:17: Checking to see if connection matches close
1999-12-02 11:02:17: Thread 3: Connection type HTTP/1.1
: Connection: persist
1999-12-02 11:02:17: Thread 3: parsing GET args
1999-12-02 11:02:17: Thread 3: Entering ws_getgetvars (revision-number=1)
1999-12-02 11:02:17: Thread 3: Adding arg revision-number = 1
1999-12-02 11:02:17: Added *revision-number=1*
1999-12-02 11:02:17: Thread 3: Done parsing GET/POST args!
1999-12-02 11:02:17: Thread 3: Original URI: /update
1999-12-02 11:02:17: Thread 3: Translated URI: /update
1999-12-02 11:02:17: Thread 3: Preparing to find handler
1999-12-02 11:02:17: Thread 3: URI Match!
1999-12-02 11:02:17: Thread 3: Time is 944132537 seconds after epoch
1999-12-02 11:02:17: Thread 3: Setting time header
1999-12-02 11:02:17: Added *Date=Thu, 2 Dec 1999 11:02:17 GMT*
1999-12-02 11:02:17: Thread 3: Using non-default handler
1999-12-02 11:02:17: Added *Accept-Ranges=bytes*
1999-12-02 11:02:17: Added *DAAP-Server=mt-daapd/0.2.3*
1999-12-02 11:02:17: Added *Content-Type=application/x-dmap-tagged*
1999-12-02 11:02:17: Entering config_set_status
1999-12-02 11:02:17: Exiting config_set_status
1999-12-02 11:02:17: Preparing to send update response
1999-12-02 11:02:17: Satisfying request
1999-12-02 11:02:17: Serializing
1999-12-02 11:02:17: Added *Content-Length=32*
1999-12-02 11:02:17: Emitting headers
1999-12-02 11:02:17: Emitting reponse header Content-Length: 32
1999-12-02 11:02:17: Emitting reponse header Content-Type: application/x-dmap-tagged
1999-12-02 11:02:17: Emitting reponse header DAAP-Server: mt-daapd/0.2.3
1999-12-02 11:02:17: Emitting reponse header Accept-Ranges: bytes
1999-12-02 11:02:17: Emitting reponse header Date: Thu, 2 Dec 1999 11:02:17 GMT
1999-12-02 11:02:17: Sent 32 bytes in 0 seconds
1999-12-02 11:02:17: Done, freeing
1999-12-02 11:02:17: Finished serving DAAP response
1999-12-02 11:02:17: Entering config_set_status
1999-12-02 11:02:17: Exiting config_set_status
1999-12-02 11:02:17: Thread 3: Terminating
1999-12-02 11:02:17: Thread 3: Freeing request headers
1999-12-02 11:02:17: Thread 3: Freeing response headers
1999-12-02 11:02:17: Thread 3: Freeing request vars
1999-12-02 11:02:17: Thread 3: got request
1999-12-02 11:02:17: Request: GET /databases?revision-number=1 HTTP/1.1
1999-12-02 11:02:17: Thread 3: Read: Host: 192.168.1.77
1999-12-02 11:02:17: Thread 3: Adding header *Host=192.168.1.77*
1999-12-02 11:02:17: Added *Host=192.168.1.77*
1999-12-02 11:02:17: Thread 3: Read: Client-DAAP-Version: 3.6
1999-12-02 11:02:17: Thread 3: Adding header *Client-DAAP-Version=3.6*
1999-12-02 11:02:17: Added *Client-DAAP-Version=3.6*
1999-12-02 11:02:17: Thread 3: Read: User-Agent: iTunes/7.6.1 (Macintosh; N; Intel)
1999-12-02 11:02:17: Thread 3: Adding header *User-Agent=iTunes/7.6.1 (Macintosh; N; Intel)*
1999-12-02 11:02:17: Added *User-Agent=iTunes/7.6.1 (Macintosh; N; Intel)*
1999-12-02 11:02:17: Thread 3: Read: Accept-Language: en-us, en;q=0.50
1999-12-02 11:02:17: Thread 3: Adding header *Accept-Language=en-us, en;q=0.50*
1999-12-02 11:02:17: Added *Accept-Language=en-us, en;q=0.50*
1999-12-02 11:02:17: Thread 3: Read: Client-DAAP-Access-Index: 2
1999-12-02 11:02:17: Thread 3: Adding header *Client-DAAP-Access-Index=2*
1999-12-02 11:02:17: Added *Client-DAAP-Access-Index=2*
1999-12-02 11:02:17: Thread 3: Read: Client-DAAP-Validation: B2503BDFE977E20454D1C53E936B64A3
1999-12-02 11:02:17: Thread 3: Adding header *Client-DAAP-Validation=B2503BDFE977E20454D1C53E936B64A3*
1999-12-02 11:02:17: Added *Client-DAAP-Validation=B2503BDFE977E20454D1C53E936B64A3*
1999-12-02 11:02:17: Thread 3: Read: Accept-Encoding: gzip
1999-12-02 11:02:17: Thread 3: Adding header *Accept-Encoding=gzip*
1999-12-02 11:02:17: Added *Accept-Encoding=gzip*
1999-12-02 11:02:17: Thread 3: Read:
1999-12-02 11:02:17: Thread 3: Headers parsed!
1999-12-02 11:02:17: Checking to see if connection matches close
1999-12-02 11:02:17: Thread 3: Connection type HTTP/1.1
: Connection: persist
1999-12-02 11:02:17: Thread 3: parsing GET args
1999-12-02 11:02:17: Thread 3: Entering ws_getgetvars (revision-number=1)
1999-12-02 11:02:17: Thread 3: Adding arg revision-number = 1
1999-12-02 11:02:17: Added *revision-number=1*
1999-12-02 11:02:17: Thread 3: Done parsing GET/POST args!
1999-12-02 11:02:17: Thread 3: Original URI: /databases
1999-12-02 11:02:17: Thread 3: Translated URI: /databases
1999-12-02 11:02:17: Thread 3: Preparing to find handler
1999-12-02 11:02:17: Thread 3: URI Match!
1999-12-02 11:02:17: Thread 3: Time is 944132537 seconds after epoch
1999-12-02 11:02:17: Thread 3: Setting time header
1999-12-02 11:02:17: Added *Date=Thu, 2 Dec 1999 11:02:17 GMT*
1999-12-02 11:02:17: Thread 3: Using non-default handler
1999-12-02 11:02:17: Added *Accept-Ranges=bytes*
1999-12-02 11:02:17: Added *DAAP-Server=mt-daapd/0.2.3*
1999-12-02 11:02:17: Added *Content-Type=application/x-dmap-tagged*
1999-12-02 11:02:17: Entering config_set_status
1999-12-02 11:02:17: Exiting config_set_status
1999-12-02 11:02:17: Preparing to send db info
1999-12-02 11:02:17: Sent db info… 4965 songs, 0 playlists
1999-12-02 11:02:17: Satisfying request
1999-12-02 11:02:17: Serializing
1999-12-02 11:02:17: Added *Content-Length=140*
1999-12-02 11:02:17: Emitting headers
1999-12-02 11:02:17: Emitting reponse header Content-Length: 140
1999-12-02 11:02:17: Emitting reponse header Content-Type: application/x-dmap-tagged
1999-12-02 11:02:17: Emitting reponse header DAAP-Server: mt-daapd/0.2.3
1999-12-02 11:02:17: Emitting reponse header Accept-Ranges: bytes
1999-12-02 11:02:17: Emitting reponse header Date: Thu, 2 Dec 1999 11:02:17 GMT
1999-12-02 11:02:17: Sent 140 bytes in 0 seconds
1999-12-02 11:02:17: Done, freeing
1999-12-02 11:02:17: Finished serving DAAP response
1999-12-02 11:02:17: Entering config_set_status
1999-12-02 11:02:17: Exiting config_set_status
1999-12-02 11:02:17: Thread 3: Terminating
1999-12-02 11:02:17: Thread 3: Freeing request headers
1999-12-02 11:02:17: Thread 3: Freeing response headers
1999-12-02 11:02:17: Thread 3: Freeing request vars
1999-12-02 11:02:17: select(6, 12.818359)
1999-12-02 11:02:17: Thread 3: could not read: Invalid argument
1999-12-02 11:02:17: Entering config_set_status
1999-12-02 11:02:17: Exiting config_set_status
1999-12-02 11:02:17: Thread 3: Terminating
1999-12-02 11:02:17: Thread 3: Freeing request headers
1999-12-02 11:02:17: Thread 3: Freeing response headers
1999-12-02 11:02:17: Thread 3: Freeing request vars
1999-12-02 11:02:17: Thread 3: Closing fd
1999-12-02 11:02:17: With thread 3 exiting, 0 are still running
1999-12-02 11:02:19: select(6, 0.065429)
1999-12-02 11:02:19: select(6, 10.899414)
1999-12-02 11:02:26: select(6, 0.113281)
1999-12-02 11:02:26: select(6, 3.838867)
1999-12-02 11:02:30: select(6, 0.099609)
1999-12-02 11:02:30: select(6, 0.098632)
1999-12-02 11:02:30: select(6, 18.544921)

To summarize that, right at the end, it looks like thread 3 crashes (“Thread 3: could not read: Invalid argument”). Any ideas what could cause this? The log doesn’t seem to say what can’t be read 🙁

Thanks!
Kevin

[Author]

Posts