Bugzilla – Bug 12205
m4a playback - problems with faad.exe
Last modified: 2009-06-09 12:58:26 UTC
Created attachment 5289 [details] convert.conf and types.conf files I've had great fun with the duet, and I really like it (except for the charging nightmare). However, I found Squeezecenter susceptable to hanging. I finally isolated the symptoms: attempt to play an m4a, and the result is no sound, and a faad.exe process running at 100% of a processor on the server (my main PC - a Pentium D 2.8GHZ running XPsp3). As a side effect, the Squeezecentre server is lost by the controller (and indeed any attempt to browse to it via a PC on the network). Killing the faad.exe process rescutitates everything quite nicely, but I didn't get to listen to the track. I've attached dir listing for c:\windows\winsxs as this seemed to be one of the issues for other faad.exe problems. I hope someone can help! Here are a few bits and pieces (version, logs from player.source=INFO) Version: 7.4 - 25704 @ Fri Mar 27 08:59:49 PDT 2009 No edits to the convert.conf or types.conf files. They are attached. Slim::Player::StreamingController::_Stream (941) 00:04:20:17:0a:66: preparing to stream song index 1 [09-05-21 06:35:25.8454] Slim::Player::StreamingController::_Stream (957) Song queue is now 1 [09-05-21 06:35:25.8463] Slim::Player::Song::open (302) file:///F:/M4A%20Music/Suzanne%20Vega/99.9%20F%20[UK]/02%20Blood%20Makes%20Noise.m4a [09-05-21 06:35:26.0189] Slim::Player::TranscodingHelper::getConvertCommand 2 (455) Error: Didn't find any command matches for type: mp4 [09-05-21 06:35:26.0197] Slim::Player::Song::open (323) seek=false time=0 canSeek=0SEEK_ERROR_TYPE_NOT_SUPPORTEDmp4 [09-05-21 06:35:26.0218] Slim::Player::TranscodingHelper::getConvertCommand 2 (458) Matched: mp4->flc via: [faad] -q -w -f 1 $FILE$ | [flac] -cs --totally-silent --compression-level-0 --ignore-chunk-sizes - [09-05-21 06:35:26.0223] Slim::Player::Song::open (340) Transcoder: streamMode=F, streamformat=flc [09-05-21 06:35:26.2815] Slim::Player::Song::open (457) Tokenized command "C:\PROGRA~1\SQUEEZ~1\server\Bin\MSWin32-x86-multi-thread\faad.exe" -q -w -f 1 "F:\M4AMUS~1\SUZANN~1\99270D~1.9F_\02BLOO~1.M4 A" | "C:\PROGRA~1\SQUEEZ~1\server\Bin\MSWin32-x86-multi-thread\flac.exe" -cs --totally-silent --compression-level-0 --ignore-chunk-sizes - | [09-05-21 06:35:27.4854] Slim::Player::StreamingController::_Stream (1010) 00:04:20:17:0a:66: stream [09-05-21 06:35:27.7033] Slim::Player::StreamingController::_Stream (1040) Song queue is now 1 [09-05-21 06:35:27.7655] Slim::Player::StreamingController::_setPlayingStat e (1881) new playing state BUFFERING [09-05-21 06:35:27.7660] Slim::Player::StreamingController::_setStreamingSt ate (1894) new streaming state STREAMING [09-05-21 06:35:44.9218] Slim::Player::Source::_readNextChunk (499) end of file or error on socket, song pos: 0 [09-05-21 06:35:44.9286] Slim::Player::Source::_readNextChunk (506) Didn't stream any bytes for this song; mark it as failed [09-05-21 06:35:44.9290] Slim::Player::StreamingController::playerStreaming Failed (1758) 00:04:20:17:0a:66 [09-05-21 06:35:44.9355] Slim::Player::StreamingController::_playersMessage (710) Problem: Can't open file for:: file:///F:/M4A%20Music/Suzanne%20Vega/99.9%20F%20[UK]/02%20Blood%20Makes%20Noise.m4a [09-05-21 06:35:44.9398] Slim::Player::StreamingController::_Stop (548) Song queue is now 1 [09-05-21 06:35:44.9405] Slim::Player::StreamingController::_setPlayingStat e (1881) new playing state STOPPED [09-05-21 06:35:44.9409] Slim::Player::StreamingController::_setStreamingSt ate (1894) new streaming state IDLE [09-05-21 06:35:44.9423] Slim::Player::StreamingController::nextsong (775) The next song is number 2, was 1 [09-05-21 06:35:44.9477] Slim::Player::Song::new (64) index 2 -> file:///F:/M4A%20Music/Suzanne%20Vega/99.9%20F%20[UK]/03%20In%20Liverpool.m4a [09-05-21 06:35:44.9483] Slim::Player::StreamingController::_setStreamingSt ate (1894) new streaming state TRACKWAIT [09-05-21 06:35:44.9489] Slim::Player::Song::getNextSong (178) file:///F:/M4A%20Music/Suzanne%20Vega/99.9%20F%20[UK]/03%20In%20Liverpool.m4a [09-05-21 06:35:44.9494] Slim::Player::StreamingController::_nextTrackReady (667) 00:04:20:17:0a:66: nextTrack will be index 2 [09-05-21 06:35:44.9498] Slim::Player::StreamingController::_Stream (941) 00:04:20:17:0a:66: preparing to stream song index 2 [09-05-21 06:35:44.9502] Slim::Player::StreamingController::_Stream (957) Song queue is now 2 [09-05-21 06:35:44.9507] Slim::Player::Song::open (302) file:///F:/M4A%20Music/Suzanne%20Vega/99.9%20F%20[UK]/03%20In%20Liverpool.m4a [09-05-21 06:35:44.9533] Slim::Player::TranscodingHelper::getConvertCommand 2 (455) Error: Didn't find any command matches for type: mp4 [09-05-21 06:35:44.9538] Slim::Player::Song::open (323) seek=false time=0 canSeek=0SEEK_ERROR_TYPE_NOT_SUPPORTEDmp4 [09-05-21 06:35:44.9548] Slim::Player::TranscodingHelper::getConvertCommand 2 (458) Matched: mp4->flc via: [faad] -q -w -f 1 $FILE$ | [flac] -cs --totally-silent --compression-level-0 --ignore-chunk-sizes - [09-05-21 06:35:44.9553] Slim::Player::Song::open (340) Transcoder: streamMode=F, streamformat=flc [09-05-21 06:35:44.9603] Slim::Player::Song::open (457) Tokenized command "C:\PROGRA~1\SQUEEZ~1\server\Bin\MSWin32-x86-multi-thread\faad.exe" -q -w -f 1 "F:\M4AMUS~1\SUZANN~1\99270D~1.9F_\03INLI~1.M4 A" | "C:\PROGRA~1\SQUEEZ~1\server\Bin\MSWin32-x86-multi-thread\flac.exe" -cs --totally-silent --compression-level-0 --ignore-chunk-sizes - | [09-05-21 06:35:45.0043] Slim::Player::StreamingController::_Stream (1010) 00:04:20:17:0a:66: stream [09-05-21 06:35:45.0118] Slim::Player::StreamingController::_Stream (1040) Song queue is now 2 [09-05-21 06:35:45.0124] Slim::Player::StreamingController::_setPlayingStat e (1881) new playing state BUFFERING [09-05-21 06:35:45.0128] Slim::Player::StreamingController::_setStreamingSt ate (1894) new streaming state STREAMING [09-05-21 06:35:56.8440] Slim::Player::Source::_readNextChunk (499) end of file or error on socket, song pos: 0 [09-05-21 06:35:56.8444] Slim::Player::Source::_readNextChunk (506) Didn't stream any bytes for this song; mark it as failed [09-05-21 06:35:56.8449] Slim::Player::StreamingController::playerStreaming Failed (1758) 00:04:20:17:0a:66 [09-05-21 06:35:56.8455] Slim::Player::StreamingController::_playersMessage (710) Problem: Can't open file for:: file:///F:/M4A%20Music/Suzanne%20Vega/99.9%20F%20[UK]/03%20In%20Liverpool.m4a [09-05-21 06:35:56.8485] Slim::Player::StreamingController::_Stop (548) Song queue is now 2 [09-05-21 06:35:56.8492] Slim::Player::StreamingController::_setPlayingStat e (1881) new playing state STOPPED [09-05-21 06:35:56.8497] Slim::Player::StreamingController::_setStreamingSt ate (1894) new streaming state IDLE [09-05-21 06:35:56.8505] Slim::Player::StreamingController::nextsong (746) Giving up because of too many consecutive errors: 2 [09-05-21 06:35:56.9800] Slim::Player::TranscodingHelper::getConvertCommand 2 (455) Error: Didn't find any command matches for type: mp4 But in parallel I killed the faad.exe tasks (twice) so as not to leave squeezecentre "hanging". In order to see whether faad gives any information about why it cannot process the file... "C:\PROGRA~1\SQUEEZ~1\server\Bin\MSWin32-x86-multi-thread\faad.exe" -w -f 1 "F:\M4AMUS~1\SUZANN~1\99270D~1.9F_\03INLI~1.M4 A" from the command line. It just gives the short intro text and then just sits there (1 processor at 100%). Once again I kill it in task manager.
Created attachment 5293 [details] m4a file example Just one example of the m4a files that give me this problem.
I can reproduce this, faad is unable to handle the file. I will debug it some more and file a bug with faad if necessary. 7.4 scanner also reports an error parsing the file, but that may be a bug in the scanner.
I found the problem. This file has a bad size value for the "gnre" tag. faad may be failing to read past this. You should consider re-tagging the file, either removing or fixing the genre. That might fix your problem.
Certainly the solution seems to be to write the tag values correctly. I've made updates throughout this file set, and it seems to fix the issue. I had been mislead (by winamp) into thinking that I'd already done this, but it seems that winamp is not updating the files, but just filling the media library database. So I consider the bug report closed (no bug), although perhaps there should be some additional error handling so that we avoid this type of issue for other people?
Yeah, a bug needs to be filed with faad. Glad you got it fixed.