Thread x: could not read: Unknown error

[hrym]

Hello

I have recently upgraded my ubuntu server’s hardware (CPU, MB, RAM). Since this my mt-daap acts strange. When I try to listen to some songs, sometimes connection aborts and the daap server isn’t any longer listed in my itunes until I restart it. The server even acts more wired when I try to use it with my windows pc. Most times the server only shows up in itunes when i restart it.

I have tried many things. First i thought the new NIC (onboard on the new MB) confuses something. But it doesn’t seem so. Furthermore I can’t tell exactly if the problems occurred with the recent hw upgrade.

After some research in the log (and reinstalling mt-daapd manually and via apt-get and using it with and without avahi) i found this entries when itunes stops playing, but to be honest, i have no clue why this happens 🙁

2009-08-25 02:39:46 (b74a7b90): Thread 1:  could not read: Unknown error

2009-08-25 02:39:46 (b74a7b90): Thread 1: Terminating

2009-08-25 02:39:46 (b74a7b90): Thread 1: Freeing request headers

2009-08-25 02:39:46 (b74a7b90): Thread 1: Freeing response headers

2009-08-25 02:39:46 (b74a7b90): Thread 1: Freeing request vars

2009-08-25 02:39:46 (b74a7b90): Thread 1: Closing fd

2009-08-25 02:39:46 (b74a7b90): With thread 1 exiting, 1 are still running

2009-08-25 02:39:46 (b64a5b90): Write error: Broken pipe

2009-08-25 02:39:46 (b64a5b90): Error copying file to remote...

2009-08-25 02:39:46 (b64a5b90): Entering config_set_status

2009-08-25 02:39:46 (b64a5b90): Exiting config_set_status

2009-08-25 02:39:46 (b64a5b90): Thread 4: Terminating

2009-08-25 02:39:46 (b64a5b90): Thread 4: Freeing request headers

2009-08-25 02:39:46 (b64a5b90): Thread 4: Freeing response headers

2009-08-25 02:39:46 (b64a5b90): Thread 4: Freeing request vars

2009-08-25 02:39:46 (b64a5b90): Thread 4: Closing fd

2009-08-25 02:39:46 (b64a5b90): With thread 4 exiting, 0 are still running

the older entries are:

2009-08-25 02:38:12 (b64a5b90): Session 0: Streaming file '04 Buried Alive.mp3' to 192.168.10.100 (offset 0)

2009-08-25 02:38:16 (b64a5b90): Write error: Broken pipe

2009-08-25 02:38:16 (b64a5b90): Error copying file to remote...

2009-08-25 02:38:16 (b64a5b90): Entering config_set_status

2009-08-25 02:38:16 (b64a5b90): Exiting config_set_status

2009-08-25 02:38:16 (b64a5b90): Thread 3: Terminating

2009-08-25 02:38:16 (b64a5b90): Thread 3: Freeing request headers

2009-08-25 02:38:16 (b64a5b90): Thread 3: Freeing response headers

2009-08-25 02:38:16 (b64a5b90): Thread 3: Freeing request vars

2009-08-25 02:38:16 (b64a5b90): Thread 3: Closing fd

2009-08-25 02:38:16 (b64a5b90): With thread 3 exiting, 1 are still running

2009-08-25 02:38:16 (b6ca6b90): Got listen socket 7

2009-08-25 02:38:16 (b64a5b90): Thread 4:

2009-08-25 02:38:16 (b64a5b90): Request: GET daap://192.168.10.90:3689/databases/1/items/9083.mp3 HTTP/1.1

2009-08-25 02:38:16 (b64a5b90): Thread 4: Read: Accept: */*

2009-08-25 02:38:16 (b64a5b90): Thread 4: Adding header *Accept=*/**

2009-08-25 02:38:16 (b64a5b90): Added *Accept=*/**

2009-08-25 02:38:16 (b64a5b90): Thread 4: Read: Cache-Control: no-cache

2009-08-25 02:38:16 (b64a5b90): Thread 4: Adding header *Cache-Control=no-cache*

2009-08-25 02:38:16 (b64a5b90): Added *Cache-Control=no-cache*

2009-08-25 02:38:16 (b64a5b90): Thread 4: Read: User-Agent: iTunes/8.2.1 (Macintosh; N; Intel)

2009-08-25 02:38:16 (b64a5b90): Thread 4: Adding header *User-Agent=iTunes/8.2.1 (Macintosh; N; Intel)*

2009-08-25 02:38:16 (b64a5b90): Added *User-Agent=iTunes/8.2.1 (Macintosh; N; Intel)*

2009-08-25 02:38:16 (b64a5b90): Thread 4: Read: Client-DAAP-Access-Index: 2

2009-08-25 02:38:16 (b64a5b90): Thread 4: Adding header *Client-DAAP-Access-Index=2*

2009-08-25 02:38:16 (b64a5b90): Added *Client-DAAP-Access-Index=2*

2009-08-25 02:38:16 (b64a5b90): Thread 4: Read: Client-DAAP-Validation:

2009-08-25 02:38:16 (b64a5b90): Thread 4: Adding header *Client-DAAP-Validation=*

2009-08-25 02:38:16 (b64a5b90): Added *Client-DAAP-Validation=*

2009-08-25 02:38:16 (b64a5b90): Thread 4: Read: Client-DAAP-Request-ID: 3

2009-08-25 02:38:16 (b64a5b90): Thread 4: Adding header *Client-DAAP-Request-ID=3*

2009-08-25 02:38:16 (b64a5b90): Added *Client-DAAP-Request-ID=3*

2009-08-25 02:38:16 (b64a5b90): Thread 4: Read: x-audiocast-udpport: 53906

2009-08-25 02:38:16 (b64a5b90): Thread 4: Adding header *x-audiocast-udpport=53906*

2009-08-25 02:38:16 (b64a5b90): Added *x-audiocast-udpport=53906*

2009-08-25 02:38:16 (b64a5b90): Thread 4: Read: icy-metadata: 1

2009-08-25 02:38:16 (b64a5b90): Thread 4: Adding header *icy-metadata=1*

2009-08-25 02:38:16 (b64a5b90): Added *icy-metadata=1*

2009-08-25 02:38:16 (b64a5b90): Thread 4: Read: Connection: close

2009-08-25 02:38:16 (b64a5b90): Thread 4: Adding header *Connection=close*

2009-08-25 02:38:16 (b64a5b90): Added *Connection=close*

2009-08-25 02:38:16 (b64a5b90): Thread 4: Read:

2009-08-25 02:38:16 (b64a5b90): Thread 4: Headers parsed!

2009-08-25 02:38:16 (b64a5b90): Checking to see if connection matches close

2009-08-25 02:38:16 (b64a5b90): And it DOES!

2009-08-25 02:38:16 (b64a5b90): Thread 4: Connection type HTTP/1.1

: Connection: non-persist

2009-08-25 02:38:16 (b64a5b90): Thread 4: Original URI: daap://192.168.10.90:3689/databases/1/items/9083.mp3

2009-08-25 02:38:16 (b64a5b90): Thread 4: Translated URI: /databases/1/items/9083.mp3

2009-08-25 02:38:16 (b64a5b90): Thread 4: Preparing to find handler

2009-08-25 02:38:16 (b64a5b90): Checking /databases/1/items/9083.mp3 against handler for /

2009-08-25 02:38:16 (b64a5b90): Thread 4: URI Match!

2009-08-25 02:38:16 (b64a5b90): Thread 4: Time is 1251160696 seconds after epoch

2009-08-25 02:38:16 (b64a5b90): Thread 4: Setting time header

2009-08-25 02:38:16 (b64a5b90): Added *Date=Tue, 25 Aug 2009 00:38:16 GMT*

2009-08-25 02:38:16 (b64a5b90): Added *Connection=close*

2009-08-25 02:38:16 (b64a5b90): Added *Server=mt-daapd/svn-1696*

2009-08-25 02:38:16 (b64a5b90): Added *Content-Type=text/html*

2009-08-25 02:38:16 (b64a5b90): Added *Content-Language=en_us*

2009-08-25 02:38:16 (b64a5b90): Thread 4: Using non-default handler

2009-08-25 02:38:16 (b64a5b90): in main_auth

2009-08-25 02:38:16 (b64a5b90): Checking url /databases/1/items/9083.mp3

2009-08-25 02:38:16 (b64a5b90): Checking url /databases/1/items/9083.mp3

2009-08-25 02:38:16 (b64a5b90): Dispatching auth for /databases/1/items/9083.mp3 to plugin

2009-08-25 02:38:16 (b64a5b90): Checking url /databases/1/items/9083.mp3

2009-08-25 02:38:16 (b64a5b90): Checking url /databases/1/items/9083.mp3

2009-08-25 02:38:16 (b64a5b90): Dispatching /databases/1/items/9083.mp3 to daap/svn-1696

2009-08-25 02:38:16 (b64a5b90): in main_handler

2009-08-25 02:38:16 (b64a5b90): Checking url /databases/1/items/9083.mp3

2009-08-25 02:38:16 (b64a5b90): Checking url /databases/1/items/9083.mp3

2009-08-25 02:38:16 (b64a5b90): Dispatching /databases/1/items/9083.mp3 to plugin

2009-08-25 02:38:16 (b64a5b90): Checking url /databases/1/items/9083.mp3

2009-08-25 02:38:16 (b64a5b90): Checking url /databases/1/items/9083.mp3

2009-08-25 02:38:16 (b64a5b90): Dispatching /databases/1/items/9083.mp3 to daap/svn-1696

2009-08-25 02:38:16 (b64a5b90): Getting uri...

2009-08-25 02:38:16 (b64a5b90): Mallocing privinfo...

2009-08-25 02:38:16 (b64a5b90): Added *Accept-Ranges=bytes*

2009-08-25 02:38:16 (b64a5b90): Added *DAAP-Server=firefly/svn-1696*

2009-08-25 02:38:16 (b64a5b90): Updating Content-Type from text/html to application/x-dmap-tagged

2009-08-25 02:38:16 (b64a5b90): Added *Cache-Control=no-cache*

2009-08-25 02:38:16 (b64a5b90): Added *Expires=-1*

2009-08-25 02:38:16 (b64a5b90): Tokenizing url

2009-08-25 02:38:16 (b64a5b90): Found 17 elements

2009-08-25 02:38:16 (b64a5b90): Checking reponse 0

2009-08-25 02:38:16 (b64a5b90): Checking reponse 1

2009-08-25 02:38:16 (b64a5b90): Checking reponse 2

2009-08-25 02:38:16 (b64a5b90): Checking reponse 3

2009-08-25 02:38:16 (b64a5b90): Checking reponse 4

2009-08-25 02:38:16 (b64a5b90): Checking reponse 5

2009-08-25 02:38:16 (b64a5b90): Checking reponse 6

2009-08-25 02:38:16 (b64a5b90): Checking reponse 7

2009-08-25 02:38:16 (b64a5b90): Checking reponse 8

2009-08-25 02:38:16 (b64a5b90): Checking reponse 9

2009-08-25 02:38:16 (b64a5b90): Found it! Index: 9

2009-08-25 02:38:16 (b64a5b90): Executing: select * from songs where id=9083

2009-08-25 02:38:16 (b64a5b90): Creating new db handle

2009-08-25 02:38:16 (b64a5b90): Opening file:///mnt/rd0/musik/Alter+Bridge/One+Day+Remains/05+Metalingus.mp3

2009-08-25 02:38:16 (b64a5b90): Checking handler for file

2009-08-25 02:38:16 (b64a5b90): opening /mnt/rd0/musik/Alter Bridge/One Day Remains/05 Metalingus.mp3

2009-08-25 02:38:16 (b64a5b90): Thread 4: Length of file (remaining): 6259144

2009-08-25 02:38:16 (b64a5b90): Updating Content-Type from application/x-dmap-tagged to audio/mp3

2009-08-25 02:38:16 (b64a5b90): Added *Content-Length=6259144*

2009-08-25 02:38:16 (b64a5b90): Updating Connection from close to Close

2009-08-25 02:38:16 (b64a5b90): Emitting reponse header Content-Length: 6259144

2009-08-25 02:38:16 (b64a5b90): Emitting reponse header Expires: -1

2009-08-25 02:38:16 (b64a5b90): Emitting reponse header Cache-Control: no-cache

2009-08-25 02:38:16 (b64a5b90): Emitting reponse header DAAP-Server: firefly/svn-1696

2009-08-25 02:38:16 (b64a5b90): Emitting reponse header Accept-Ranges: bytes

2009-08-25 02:38:16 (b64a5b90): Emitting reponse header Content-Language: en_us

2009-08-25 02:38:16 (b64a5b90): Emitting reponse header Content-Type: audio/mp3

2009-08-25 02:38:16 (b64a5b90): Emitting reponse header Server: mt-daapd/svn-1696

2009-08-25 02:38:16 (b64a5b90): Emitting reponse header Connection: Close

2009-08-25 02:38:16 (b64a5b90): Emitting reponse header Date: Tue, 25 Aug 2009 00:38:16 GMT

2009-08-25 02:38:16 (b64a5b90): Entering config_set_status

2009-08-25 02:38:16 (b64a5b90): Exiting config_set_status

2009-08-25 02:38:16 (b64a5b90): Session 0: Streaming file '05 Metalingus.mp3' to 192.168.10.100 (offset 0)

[Anonymous]

I think I found the problem….

Theres a bug in my ubuntu version (8.04) and I think it isn’t fixed yet because I updated & upgraded my system but the problem still was there.
This bug causes the kernel to use a wrong driver for my NIC (Ethernet controller: Realtek Semiconductor Co., Ltd. RTL8111/8168B PCI Express Gigabit Ethernet controller (rev 02)).

I have solved the problem with the help of this script: http://acm.cse.lehigh.edu:8080/~jhw204/hardy-r8168
After finding it mentioned here: https://bugs.launchpad.net/ubuntu/+source/linux-source-2.6.22/+bug/141343

[Author]

Posts