Bug 7765 - Scanning MP3 NEVER completes, regression bug in 7.01
: Scanning MP3 NEVER completes, regression bug in 7.01
Status: CLOSED FIXED
Product: Logitech Media Server
Classification: Unclassified
Component: Scanner
: 7.0.1
: PC Windows Server 2003
: P1 critical (vote)
: 7.x
Assigned To: Andy Grundman
:
Depends on:
Blocks:
  Show dependency treegraph
 
Reported: 2008-04-07 17:29 UTC by Alexander Holy
Modified: 2009-07-31 10:19 UTC (History)
1 user (show)

See Also:
Category: ---


Attachments
MP3 likely to cause the problem (96.48 MB, application/octet-stream)
2008-04-09 10:23 UTC, Alexander Holy
Details
scan log indicating problems and mp3 file (14.41 MB, application/octet-stream)
2008-04-09 18:22 UTC, Alexander Holy
Details

Note You need to log in before you can comment on or make changes to this bug.
Description Alexander Holy 2008-04-07 17:29:21 UTC
I did a clean upgarde from 7.0 to 7.01 (uninstall, reboot, reinstall, full rescan)

However, non of the full rescans comple, after approx 660 disks /2200 MP3s scanning stops.
Non of the 14000 MP3s has been changed, scanning worked flawless in 7.0
All of my MP3s have been tagged the same way with a C# program of mine (using taglib# 2.0.3.0). I've take crucial step to ensure all my tags are correct, son unless there is a bug in taglib# 2.0.3.0 tagging should be perfect.

The scanner log ends like this:

C:\PROGRA~1\SQUEEZ~1\server\lib/MPEG/Audio/Frame.pm line 619, <$mpeg> chunk 8.

[08-04-08 02:05:38.8853] MPEG::Audio::Frame::broken (620) Warning: Use of uninitialized value in right bitshift (>>) at C:\PROGRA~1\SQUEEZ~1\server\lib/MPEG/Audio/Frame.pm line 620, <$mpeg> chunk 8.

[08-04-08 02:05:38.8856] MPEG::Audio::Frame::broken (621) Warning: Use of uninitialized value in right bitshift (>>) at C:\PROGRA~1\SQUEEZ~1\server\lib/MPEG/Audio/Frame.pm line 621, <$mpeg> chunk 8.

[08-04-08 02:05:38.8858] MPEG::Audio::Frame::broken (616) Warning: substr outside of string at C:\PROGRA~1\SQUEEZ~1\server\lib/MPEG/Audio/Frame.pm line 616, <$mpeg> chunk 8.

[08-04-08 02:05:38.8861] MPEG::Audio::Frame::broken (616) Warning: Use of uninitialized value in unpack at C:\PROGRA~1\SQUEEZ~1\server\lib/MPEG/Audio/Frame.pm line 616, <$mpeg> chunk 8.

[08-04-08 02:05:38.8864] MPEG::Audio::Frame::broken (618) Warning: Use of uninitialized value in right bitshift (>>) at C:\PROGRA~1\SQUEEZ~1\server\lib/MPEG/Audio/Frame.pm line 618, <$mpeg> chunk 8.

[08-04-08 02:05:38.8866] MPEG::Audio::Frame::broken (619) Warning: Use of uninitialized value in right bitshift (>>) at C:\PROGRA~1\SQUEEZ~1\server\lib/MPEG/Audio/Frame.pm line 619, <$mpeg> chunk 8.

[08-04-08 02:05:38.8869] MPEG::Audio::Frame::broken (620) Warning: Use of uninitialized value in right bitshift (>>) at C:\PROGRA~1\SQUEEZ~1\server\lib/MPEG/Audio/Frame.pm line 620, <$mpeg> chunk 8.

[08-04-08 02:05:38.8871] MPEG::Audio::Frame::broken (621) Warning: Use of uninitialized value in right bitshift (>>) at C:\PROGRA~1\SQUEEZ~1\server\lib/MPEG/Audio/Frame.pm line 621, <$mpeg> chunk 8.

[08-04-08 02:05:38.8873] MPEG::Audio::Frame::broken (616) Warning: substr outside of string at C:\PROGRA~1\SQUEEZ~1\server\lib/MPEG/Audio/Frame.pm line 616, <$mpeg> chunk 8.

[08-04-08 02:05:38.8876] MPEG::Audio::Frame::broken (616) Warning: Use of uninitialized value in unpack at C:\PROGRA~1\SQUEEZ~1\server\lib/MPEG/Audio/Frame.pm line 616, <$mpeg> chunk 8.

[08-04-08 02:05:38.8878] MPEG::Audio::Frame::broken (618) Warning: Use of uninitialized value in right bitshift (>>) at C:\PROGRA~1\SQUEEZ~1\server\lib/MPEG/Audio/Frame.pm line 618, <$mpeg> chunk 8.

[08-04-08 02:05:38.8881] MPEG::Audio::Frame::broken (619) Warning: Use of uninitialized value in right bitshift (>>) at C:\PROGRA~1\SQUEEZ~1\server\lib/MPEG/Audio/Frame.pm line 619, <$mpeg> chunk 8.

[08-04-08 02:05:38.8884] MPEG::Audio::Frame::broken (620) Warning: Use of uninitialized value in right bitshift (>>) at C:\PROGRA~1\SQUEEZ~1\server\lib/MPEG/Audio/Frame.pm line 620, <$mpeg> chunk 8.

[08-04-08 02:05:38.8887] MPEG::Audio::Frame::broken (621) Warning: Use of uninitialized value in right bitshift (>>) at C:\PROGRA~1\SQUEEZ~1\server\lib/MPEG/Audio/Frame.pm line 621, <$mpeg> chunk 8.

[08-04-08 02:06:08.3140] main::main (210) Error: Failed when running main scan: [Can't call method "id" on an undefined value at /<C:\PROGRA~1\SQUEEZ~1\server\scanner.exe>Slim/Schema.pm line 2077.
]
[08-04-08 02:06:08.3143] main::main (211) Error: Skipping post-process & Not updating lastRescanTime!
Comment 1 Michael Herger 2008-04-08 00:37:33 UTC
Alexander - could you please set all scan.* logging options to debug (beware - this will create a huge log file! don't forget to turn it of again!) and redo a wipe/rescan? Then upload the last few hundred lines.

When you say it never completes, you mean it crashes during the rescan? 

We'd need a few more details: where are your music files stored? Any iTunes and/or MusicIP integration? 

Are you using non-latin characters in your file paths? If you do so, could you please try to limit the scan to a subset with one of these paths to see whether that's where the scanner chokes?

We did quite a few changes in 7.0.1 which are mostly done to fix issues with non-latin characters. Thus I'd assume it would be related to these.
Comment 2 Michael Herger 2008-04-08 01:07:12 UTC
Also - what exact SC build is this? Make sure you're testing with the very latest (18536 right now). Thanks!
Comment 3 Andy Grundman 2008-04-09 09:27:41 UTC
This crash looks like it might be related to the patch I made for bug 4361.  It would be really useful to find the file that makes it crash.
Comment 4 Alexander Holy 2008-04-09 10:23:02 UTC
Created attachment 3214 [details]
MP3 likely to cause the problem

I could not detect exactly where it crashes, or if it crashes because some problem accumulates. However, it looks one of these files, likely track 3 or 4 is the last one scanned. I have reverted my installation to 7.0 in the meantime, which resolved the problem (for the moment :)
Comment 5 Andy Grundman 2008-04-09 11:46:05 UTC
Thanks.  Unfortunately all of those files scan fine for me.  You can change your music library path to point to just that directory to make sure it's one of these files.  Also, I probably have different preferences that change the way scanning operates.
Comment 6 Andy Grundman 2008-04-09 11:48:11 UTC
The debug option database.info would be helpful too, this should show the missing contributor that caused the crash.
Comment 7 Andy Grundman 2008-04-09 11:52:12 UTC
I put a fix in as change 18612 that will skip over that bit of code if a track doesn't have a contributor.  Should fix the crash.
Comment 8 Alexander Holy 2008-04-09 17:32:59 UTC
I tried two things - I took my dataset of 14525 files and installed 7.0.1 - 18481 on an XP machines.
I also turned on logging on database.info
To my great astonishment all files were indexed properly.

The I went back to my original Win2003 server and replaced 7.0 with 7.0.1
(SqueezeCenter Version: 7.0.1 - 18481 @ Mon Apr 7 00:21:40 PDT 2008 - Windows Server 2003 - EN - cp1252)

Reindexed with database.info. Indexing stopped after 7761 of 14525 files.

Log (debug for database.info last 1000 lines) is below.

I keep my Radio stations as "favourites". Two of them are mms:// (WMA-based) Radio stations.
It looks like this was bringing down the indexer?
But why does it stop after only 7761 of 14525 files???

I'll keep the unusable server in this state if you need more post-mortem info.



[08-04-08 03:13:26.6876] Slim::Schema::init (151) Warning: Creating new database - empty database or database from 6.3.x found
[08-04-08 03:13:35.1075] main::checkDataSource (870) Warning: Schema updated or tracks in the database, initiating scan.
[08-04-08 03:13:36.1120] Slim::Networking::SqueezeNetwork::_error (319) Unable to login to SN: Invalid SqueezeNetwork username or password.
[08-04-08 03:13:36.1124] Slim::Networking::SqueezeNetwork::_init_error (111) Unable to get SqueezeNetwork server time, sync is disabled: Invalid SqueezeNetwork username or password.
[08-04-08 03:13:36.1129] Slim::Networking::SqueezeNetwork::_init_error (121) SqueezeNetwork sync init failed: Invalid SqueezeNetwork username or password., will retry in 38700
[08-04-08 04:33:42.3292] Slim::Networking::Async::_connect_error (157) Failed to connect: Unknown error
[08-04-08 04:33:42.3297] Slim::Networking::Async::_async_error (256) Error: [Connect timed out]
[08-04-08 04:33:42.3300] Slim::Networking::Async::HTTP::_http_error (264) Error: [Connect timed out]
[08-04-08 13:58:36.4547] Slim::Networking::SqueezeNetwork::_error (319) Unable to login to SN: Invalid SqueezeNetwork username or password.
[08-04-08 13:58:36.4550] Slim::Networking::SqueezeNetwork::_init_error (111) Unable to get SqueezeNetwork server time, sync is disabled: Invalid SqueezeNetwork username or password.
[08-04-08 13:58:36.4555] Slim::Networking::SqueezeNetwork::_init_error (121) SqueezeNetwork sync init failed: Invalid SqueezeNetwork username or password., will retry in 39000
[08-04-08 21:29:00.4558] Slim::Networking::SqueezeNetwork::_error (319) Unable to login to SN: Invalid SqueezeNetwork username or password.
[08-04-08 21:29:00.6008] Slim::Networking::SqueezeNetwork::_init_error (111) Unable to get SqueezeNetwork server time, sync is disabled: Invalid SqueezeNetwork username or password.
[08-04-08 21:29:00.6013] Slim::Networking::SqueezeNetwork::_init_error (121) SqueezeNetwork sync init failed: Invalid SqueezeNetwork username or password., will retry in 39300
[08-04-09 07:01:32.2679] Slim::Display::Display::string (802) Error: missing string WELCOME_TO_SQUEEZESLAVE
[08-04-09 07:01:32.2683] Slim::Display::Display::string (802) Backtrace:

   frame 0: Slim::Utils::Log::logBacktrace (/<C:\PROGRA~1\SQUEEZ~1\server\SQUEEZ~1.EXE>Slim/Display/Display.pm line 802)
   frame 1: Slim::Display::Display::string (/<C:\PROGRA~1\SQUEEZ~1\server\SQUEEZ~1.EXE>Slim/Player/Player.pm line 153)
   frame 2: Slim::Player::Player::string (/<C:\PROGRA~1\SQUEEZ~1\server\SQUEEZ~1.EXE>Slim/Player/Player.pm line 241)
   frame 3: Slim::Player::Player::power (/<C:\PROGRA~1\SQUEEZ~1\server\SQUEEZ~1.EXE>Slim/Player/Player.pm line 97)
   frame 4: Slim::Player::Player::init (/<C:\PROGRA~1\SQUEEZ~1\server\SQUEEZ~1.EXE>Slim/Player/Squeezebox.pm line 38)
   frame 5: Slim::Player::Squeezebox::init (/<C:\PROGRA~1\SQUEEZ~1\server\SQUEEZ~1.EXE>Slim/Player/SqueezeSlave.pm line 62)
   frame 6: Slim::Player::SqueezeSlave::init (/<C:\PROGRA~1\SQUEEZ~1\server\SQUEEZ~1.EXE>Slim/Networking/Slimproto.pm line 1011)
   frame 7: Slim::Networking::Slimproto::_hello_handler (/<C:\PROGRA~1\SQUEEZ~1\server\SQUEEZ~1.EXE>Slim/Networking/Slimproto.pm line 377)
   frame 8: Slim::Networking::Slimproto::client_readable (/<C:\PROGRA~1\SQUEEZ~1\server\SQUEEZ~1.EXE>Slim/Networking/Select.pm line 243)
   frame 9: (eval) (/<C:\PROGRA~1\SQUEEZ~1\server\SQUEEZ~1.EXE>Slim/Networking/Select.pm line 243)
   frame 10: Slim::Networking::Select::select (slimserver.pl line 503)
   frame 11: main::idle (slimserver.pl line 453)
   frame 12: main::main (slimserver.pl line 86)
   frame 13: PerlSvc::Interactive (perlsvc line 855)
   frame 14: PerlSvc::_interactive (slimserver.pl line 0)
   frame 15: (eval) (slimserver.pl line 0)

[08-04-09 08:24:00.5299] Slim::Networking::SqueezeNetwork::_error (319) Unable to login to SN: Invalid SqueezeNetwork username or password.
[08-04-09 08:24:00.5302] Slim::Networking::SqueezeNetwork::_init_error (111) Unable to get SqueezeNetwork server time, sync is disabled: Invalid SqueezeNetwork username or password.
[08-04-09 08:24:00.5307] Slim::Networking::SqueezeNetwork::_init_error (121) SqueezeNetwork sync init failed: Invalid SqueezeNetwork username or password., will retry in 39600
[08-04-09 12:02:17.7309] Slim::Player::TranscodingHelper::getConvertCommand (297) Error: Didn't find any command matches for type: wma format: mp3
[08-04-09 12:02:18.4342] Slim::Player::TranscodingHelper::getConvertCommand (297) Error: Didn't find any command matches for type: wma format: mp3
[08-04-09 12:02:18.4344] Slim::Player::Protocols::MMS::new (51) Error: Couldn't find conversion command for wma!
[08-04-09 12:02:18.4354] Slim::Player::Source::openSong (1829) Warning: Remote stream failed to open [mms://stream4.orf.at/oe1-wort].
[08-04-09 12:02:37.8179] Slim::Player::TranscodingHelper::getConvertCommand (297) Error: Didn't find any command matches for type: wma format: mp3
[08-04-09 12:02:38.3820] Slim::Player::TranscodingHelper::getConvertCommand (297) Error: Didn't find any command matches for type: wma format: mp3
[08-04-09 12:02:38.3823] Slim::Player::Protocols::MMS::new (51) Error: Couldn't find conversion command for wma!
[08-04-09 12:02:38.3830] Slim::Player::Source::openSong (1829) Warning: Remote stream failed to open [mms://stream4.orf.at/oe1-wort].
[08-04-09 12:02:52.9785] Slim::Schema::objectForUrl (707) Error: Null track request! Returning undef.
[08-04-09 12:02:52.9789] Slim::Schema::objectForUrl (707) Backtrace:

   frame 0: Slim::Utils::Log::logBacktrace (/<C:\PROGRA~1\SQUEEZ~1\server\SQUEEZ~1.EXE>Slim/Schema.pm line 707)
   frame 1: Slim::Schema::objectForUrl (C:/PROGRA~1/SQUEEZ~1/server/Slim/Plugin/AudioScrobbler/Plugin.pm line 362)
   frame 2: Slim::Plugin::AudioScrobbler::Plugin::newsongCallback (/<C:\PROGRA~1\SQUEEZ~1\server\SQUEEZ~1.EXE>Slim/Control/Request.pm line 1923)
   frame 3: (eval) (/<C:\PROGRA~1\SQUEEZ~1\server\SQUEEZ~1.EXE>Slim/Control/Request.pm line 1923)
   frame 4: Slim::Control::Request::notify (/<C:\PROGRA~1\SQUEEZ~1\server\SQUEEZ~1.EXE>Slim/Control/Request.pm line 794)
   frame 5: Slim::Control::Request::checkNotifications (slimserver.pl line 482)
   frame 6: main::idle (slimserver.pl line 453)
   frame 7: main::main (slimserver.pl line 86)
   frame 8: PerlSvc::Interactive (perlsvc line 855)
   frame 9: PerlSvc::_interactive (slimserver.pl line 0)
   frame 10: (eval) (slimserver.pl line 0)

[08-04-09 12:02:52.9791] Slim::Control::Request::notify (1925) Error: Failed notify: Can't call method "secs" on an undefined value at C:/PROGRA~1/SQUEEZ~1/server/Slim/Plugin/AudioScrobbler/Plugin.pm line 366.
[08-04-09 12:03:12.2115] Slim::Player::TranscodingHelper::getConvertCommand (297) Error: Didn't find any command matches for type: wma format: mp3
[08-04-09 12:03:12.6057] Slim::Player::TranscodingHelper::getConvertCommand (297) Error: Didn't find any command matches for type: wma format: mp3
[08-04-09 12:03:12.6060] Slim::Player::Protocols::MMS::new (51) Error: Couldn't find conversion command for wma!
[08-04-09 12:03:12.6068] Slim::Player::Source::openSong (1829) Warning: Remote stream failed to open [mms://stream4.orf.at/oe1-wort].
[08-04-09 12:15:31.0770] Slim::Player::TranscodingHelper::getConvertCommand (297) Error: Didn't find any command matches for type: wma format: mp3
[08-04-09 12:18:21.3981] Slim::Player::TranscodingHelper::getConvertCommand (297) Error: Didn't find any command matches for type: wma format: mp3
[08-04-09 12:18:21.7187] Slim::Player::TranscodingHelper::getConvertCommand (297) Error: Didn't find any command matches for type: wma format: mp3
[08-04-09 12:18:21.7190] Slim::Player::Protocols::MMS::new (51) Error: Couldn't find conversion command for wma!
[08-04-09 12:18:21.7198] Slim::Player::Source::openSong (1829) Warning: Remote stream failed to open [mms://stream4.orf.at/oe1-wort].
[08-04-09 12:18:33.4064] Slim::Player::TranscodingHelper::getConvertCommand (297) Error: Didn't find any command matches for type: wma format: mp3
[08-04-09 12:18:33.7814] Slim::Player::TranscodingHelper::getConvertCommand (297) Error: Didn't find any command matches for type: wma format: mp3
[08-04-09 12:18:33.7817] Slim::Player::Protocols::MMS::new (51) Error: Couldn't find conversion command for wma!
[08-04-09 12:18:33.7824] Slim::Player::Source::openSong (1829) Warning: Remote stream failed to open [mms://stream4.orf.at/oe1-wort].
[08-04-09 12:18:34.4935] Slim::Player::TranscodingHelper::getConvertCommand (297) Error: Didn't find any command matches for type: wma format: mp3
[08-04-09 12:18:34.8282] Slim::Player::TranscodingHelper::getConvertCommand (297) Error: Didn't find any command matches for type: wma format: mp3
[08-04-09 12:18:34.8285] Slim::Player::Protocols::MMS::new (51) Error: Couldn't find conversion command for wma!
[08-04-09 12:18:34.8292] Slim::Player::Source::openSong (1829) Warning: Remote stream failed to open [mms://stream4.orf.at/oe1-wort].
[08-04-09 12:19:09.7688] Slim::Display::Display::string (802) Error: missing string WELCOME_TO_SQUEEZESLAVE
[08-04-09 12:19:09.7691] Slim::Display::Display::string (802) Backtrace:

   frame 0: Slim::Utils::Log::logBacktrace (/<C:\PROGRA~1\SQUEEZ~1\server\SQUEEZ~1.EXE>Slim/Display/Display.pm line 802)
   frame 1: Slim::Display::Display::string (/<C:\PROGRA~1\SQUEEZ~1\server\SQUEEZ~1.EXE>Slim/Player/Player.pm line 153)
   frame 2: Slim::Player::Player::string (/<C:\PROGRA~1\SQUEEZ~1\server\SQUEEZ~1.EXE>Slim/Player/Player.pm line 241)
   frame 3: Slim::Player::Player::power (/<C:\PROGRA~1\SQUEEZ~1\server\SQUEEZ~1.EXE>Slim/Player/Player.pm line 97)
   frame 4: Slim::Player::Player::init (/<C:\PROGRA~1\SQUEEZ~1\server\SQUEEZ~1.EXE>Slim/Player/Squeezebox.pm line 38)
   frame 5: Slim::Player::Squeezebox::init (/<C:\PROGRA~1\SQUEEZ~1\server\SQUEEZ~1.EXE>Slim/Player/SqueezeSlave.pm line 62)
   frame 6: Slim::Player::SqueezeSlave::init (/<C:\PROGRA~1\SQUEEZ~1\server\SQUEEZ~1.EXE>Slim/Networking/Slimproto.pm line 1011)
   frame 7: Slim::Networking::Slimproto::_hello_handler (/<C:\PROGRA~1\SQUEEZ~1\server\SQUEEZ~1.EXE>Slim/Networking/Slimproto.pm line 377)
   frame 8: Slim::Networking::Slimproto::client_readable (/<C:\PROGRA~1\SQUEEZ~1\server\SQUEEZ~1.EXE>Slim/Networking/Select.pm line 243)
   frame 9: (eval) (/<C:\PROGRA~1\SQUEEZ~1\server\SQUEEZ~1.EXE>Slim/Networking/Select.pm line 243)
   frame 10: Slim::Networking::Select::select (slimserver.pl line 503)
   frame 11: main::idle (slimserver.pl line 453)
   frame 12: main::main (slimserver.pl line 86)
   frame 13: PerlSvc::Interactive (perlsvc line 855)
   frame 14: PerlSvc::_interactive (slimserver.pl line 0)
   frame 15: (eval) (slimserver.pl line 0)

[08-04-09 12:19:26.8834] Slim::Player::TranscodingHelper::getConvertCommand (297) Error: Didn't find any command matches for type: wma format: mp3
[08-04-09 12:19:26.8836] Slim::Player::Protocols::MMS::new (51) Error: Couldn't find conversion command for wma!
[08-04-09 12:19:26.8843] Slim::Player::Source::openSong (1829) Warning: Remote stream failed to open [mms://stream4.orf.at/oe1-wort].
[08-04-09 12:19:28.8330] Slim::Player::TranscodingHelper::getConvertCommand (297) Error: Didn't find any command matches for type: wma format: mp3
[08-04-09 12:19:28.8332] Slim::Player::Protocols::MMS::new (51) Error: Couldn't find conversion command for wma!
[08-04-09 12:19:28.8339] Slim::Player::Source::openSong (1829) Warning: Remote stream failed to open [mms://stream4.orf.at/oe1-wort].
[08-04-09 12:19:31.9262] Slim::Player::TranscodingHelper::getConvertCommand (297) Error: Didn't find any command matches for type: wma format: mp3
[08-04-09 12:19:31.9264] Slim::Player::Protocols::MMS::new (51) Error: Couldn't find conversion command for wma!
[08-04-09 12:19:31.9271] Slim::Player::Source::openSong (1829) Warning: Remote stream failed to open [mms://stream4.orf.at/oe1-wort].
[08-04-09 12:20:05.8466] Slim::Player::TranscodingHelper::getConvertCommand (297) Error: Didn't find any command matches for type: wma format: mp3
[08-04-09 12:20:05.8468] Slim::Player::Protocols::MMS::new (51) Error: Couldn't find conversion command for wma!
[08-04-09 12:20:05.8475] Slim::Player::Source::openSong (1829) Warning: Remote stream failed to open [mms://stream4.orf.at/oe1-wort].
[08-04-09 12:20:09.4502] Slim::Player::TranscodingHelper::getConvertCommand (297) Error: Didn't find any command matches for type: wma format: mp3
[08-04-09 12:20:09.4505] Slim::Player::Protocols::MMS::new (51) Error: Couldn't find conversion command for wma!
[08-04-09 12:20:09.4511] Slim::Player::Source::openSong (1829) Warning: Remote stream failed to open [mms://stream4.orf.at/oe1-wort].
[08-04-09 12:20:10.3408] Slim::Player::TranscodingHelper::getConvertCommand (297) Error: Didn't find any command matches for type: wma format: mp3
[08-04-09 12:20:10.3410] Slim::Player::Protocols::MMS::new (51) Error: Couldn't find conversion command for wma!
[08-04-09 12:20:10.3417] Slim::Player::Source::openSong (1829) Warning: Remote stream failed to open [mms://stream4.orf.at/oe1-wort].
[08-04-09 12:20:11.0091] Slim::Player::TranscodingHelper::getConvertCommand (297) Error: Didn't find any command matches for type: wma format: mp3
[08-04-09 12:20:11.0094] Slim::Player::Protocols::MMS::new (51) Error: Couldn't find conversion command for wma!
[08-04-09 12:20:11.0101] Slim::Player::Source::openSong (1829) Warning: Remote stream failed to open [mms://stream4.orf.at/oe1-wort].
[08-04-09 12:20:11.6048] Slim::Player::TranscodingHelper::getConvertCommand (297) Error: Didn't find any command matches for type: wma format: mp3
[08-04-09 12:20:11.6051] Slim::Player::Protocols::MMS::new (51) Error: Couldn't find conversion command for wma!
[08-04-09 12:20:11.6057] Slim::Player::Source::openSong (1829) Warning: Remote stream failed to open [mms://stream4.orf.at/oe1-wort].
[08-04-09 12:20:16.3202] Slim::Player::TranscodingHelper::getConvertCommand (297) Error: Didn't find any command matches for type: wma format: mp3
[08-04-09 12:20:16.3205] Slim::Player::Protocols::MMS::new (51) Error: Couldn't find conversion command for wma!
[08-04-09 12:20:16.3211] Slim::Player::Source::openSong (1829) Warning: Remote stream failed to open [mms://stream4.orf.at/oe1-wort].
[08-04-09 12:20:17.1134] Slim::Player::TranscodingHelper::getConvertCommand (297) Error: Didn't find any command matches for type: wma format: mp3
[08-04-09 12:20:17.1136] Slim::Player::Protocols::MMS::new (51) Error: Couldn't find conversion command for wma!
[08-04-09 12:20:17.1144] Slim::Player::Source::openSong (1829) Warning: Remote stream failed to open [mms://stream4.orf.at/oe1-wort].
[08-04-09 12:20:17.5912] Slim::Player::TranscodingHelper::getConvertCommand (297) Error: Didn't find any command matches for type: wma format: mp3
[08-04-09 12:20:17.5915] Slim::Player::Protocols::MMS::new (51) Error: Couldn't find conversion command for wma!
[08-04-09 12:20:17.5922] Slim::Player::Source::openSong (1829) Warning: Remote stream failed to open [mms://stream4.orf.at/oe1-wort].
[08-04-09 12:20:19.4817] Slim::Player::TranscodingHelper::getConvertCommand (297) Error: Didn't find any command matches for type: wma format: mp3
[08-04-09 12:20:19.4820] Slim::Player::Protocols::MMS::new (51) Error: Couldn't find conversion command for wma!
[08-04-09 12:20:19.4827] Slim::Player::Source::openSong (1829) Warning: Remote stream failed to open [mms://stream4.orf.at/oe1-wort].
[08-04-09 12:20:20.1535] Slim::Player::TranscodingHelper::getConvertCommand (297) Error: Didn't find any command matches for type: wma format: mp3
[08-04-09 12:20:20.1538] Slim::Player::Protocols::MMS::new (51) Error: Couldn't find conversion command for wma!
[08-04-09 12:20:20.1545] Slim::Player::Source::openSong (1829) Warning: Remote stream failed to open [mms://stream4.orf.at/oe1-wort].
[08-04-09 12:20:20.7783] Slim::Player::TranscodingHelper::getConvertCommand (297) Error: Didn't find any command matches for type: wma format: mp3
[08-04-09 12:20:20.7785] Slim::Player::Protocols::MMS::new (51) Error: Couldn't find conversion command for wma!
[08-04-09 12:20:20.7792] Slim::Player::Source::openSong (1829) Warning: Remote stream failed to open [mms://stream4.orf.at/oe1-wort].
[08-04-09 12:21:34.5157] Slim::Player::TranscodingHelper::getConvertCommand (297) Error: Didn't find any command matches for type: wma format: mp3
[08-04-09 12:21:34.5160] Slim::Player::Protocols::MMS::new (51) Error: Couldn't find conversion command for wma!
[08-04-09 12:21:34.5167] Slim::Player::Source::openSong (1829) Warning: Remote stream failed to open [mms://stream4.orf.at/oe1-wort].
[08-04-09 12:22:32.9349] Slim::Player::TranscodingHelper::getConvertCommand (297) Error: Didn't find any command matches for type: wma format: mp3
[08-04-09 12:22:32.9352] Slim::Player::Protocols::MMS::new (51) Error: Couldn't find conversion command for wma!
[08-04-09 12:22:32.9359] Slim::Player::Source::openSong (1829) Warning: Remote stream failed to open [mms://stream4.orf.at/oe1-wort].
[08-04-09 12:22:34.8966] Slim::Player::TranscodingHelper::getConvertCommand (297) Error: Didn't find any command matches for type: wma format: mp3
[08-04-09 12:22:34.8969] Slim::Player::Protocols::MMS::new (51) Error: Couldn't find conversion command for wma!
[08-04-09 12:22:34.8975] Slim::Player::Source::openSong (1829) Warning: Remote stream failed to open [mms://stream4.orf.at/oe1-wort].
[08-04-09 12:23:43.7413] Slim::Player::TranscodingHelper::getConvertCommand (297) Error: Didn't find any command matches for type: wma format: mp3
[08-04-09 12:23:43.7415] Slim::Player::Protocols::MMS::new (51) Error: Couldn't find conversion command for wma!
[08-04-09 12:23:43.7422] Slim::Player::Source::openSong (1829) Warning: Remote stream failed to open [mms://stream4.orf.at/oe1-wort].
[08-04-09 12:24:06.1066] Slim::Player::TranscodingHelper::getConvertCommand (297) Error: Didn't find any command matches for type: wma format: mp3
[08-04-09 13:27:11.3228] Slim::Player::TranscodingHelper::getConvertCommand (297) Error: Didn't find any command matches for type: wma format: mp3
[08-04-09 19:24:00.6417] Slim::Networking::SqueezeNetwork::_error (319) Unable to login to SN: Invalid SqueezeNetwork username or password.
[08-04-09 19:24:00.6420] Slim::Networking::SqueezeNetwork::_init_error (111) Unable to get SqueezeNetwork server time, sync is disabled: Invalid SqueezeNetwork username or password.
[08-04-09 19:24:00.6425] Slim::Networking::SqueezeNetwork::_init_error (121) SqueezeNetwork sync init failed: Invalid SqueezeNetwork username or password., will retry in 39900
[08-04-09 22:22:02.5215] Slim::Schema::forceCommit (1382) forceCommit ignored, database is in AutoCommit mode
[08-04-09 22:22:03.4532] Slim::Schema::Storage::throw_exception (67) Error: DBI Connection failed: DBI connect('hostname=127.0.0.1;port=9092;database=slimserver','slimserver',...) failed: Can't connect to MySQL server on '127.0.0.1' (10061) at C:\PROGRA~1\SQUEEZ~1\server\CPAN/DBIx/Class/Storage/DBI.pm line 652
[08-04-09 22:22:03.4537] Slim::Schema::Storage::throw_exception (67) Backtrace:

   frame 0: Slim::Utils::Log::logBacktrace (C:/PROGRA~1/SQUEEZ~1/server/Slim/Schema/Storage.pm line 67)
   frame 1: Slim::Schema::Storage::throw_exception (C:\PROGRA~1\SQUEEZ~1\server\CPAN/DBIx/Class/Storage/DBI.pm line 658)
   frame 2: DBIx::Class::Storage::DBI::_connect (C:\PROGRA~1\SQUEEZ~1\server\CPAN/DBIx/Class/Storage/DBI.pm line 616)
   frame 3: DBIx::Class::Storage::DBI::_populate_dbh (C:\PROGRA~1\SQUEEZ~1\server\CPAN/DBIx/Class/Storage/DBI.pm line 543)
   frame 4: DBIx::Class::Storage::DBI::ensure_connected (C:/PROGRA~1/SQUEEZ~1/server/Slim/Schema/Storage.pm line 27)
   frame 5: (eval) (C:/PROGRA~1/SQUEEZ~1/server/Slim/Schema/Storage.pm line 27)
   frame 6: Slim::Schema::Storage::dbh (/<C:\PROGRA~1\SQUEEZ~1\server\SQUEEZ~1.EXE>Slim/Schema.pm line 244)
   frame 7: (eval) (/<C:\PROGRA~1\SQUEEZ~1\server\SQUEEZ~1.EXE>Slim/Schema.pm line 244)
   frame 8: Slim::Schema::disconnect (slimserver.pl line 964)
   frame 9: main::cleanup (slimserver.pl line 953)
   frame 10: main::stopServer (/<C:\PROGRA~1\SQUEEZ~1\server\SQUEEZ~1.EXE>Slim/Control/Request.pm line 1746)
   frame 11: (eval) (/<C:\PROGRA~1\SQUEEZ~1\server\SQUEEZ~1.EXE>Slim/Control/Request.pm line 1746)
   frame 12: Slim::Control::Request::execute (C:/PROGRA~1/SQUEEZ~1/server/Slim/Plugin/CLI/Plugin.pm line 604)
   frame 13: Slim::Plugin::CLI::Plugin::cli_process (C:/PROGRA~1/SQUEEZ~1/server/Slim/Plugin/CLI/Plugin.pm line 395)
   frame 14: Slim::Plugin::CLI::Plugin::client_socket_buf_parse (C:/PROGRA~1/SQUEEZ~1/server/Slim/Plugin/CLI/Plugin.pm line 340)
   frame 15: Slim::Plugin::CLI::Plugin::client_socket_read (/<C:\PROGRA~1\SQUEEZ~1\server\SQUEEZ~1.EXE>Slim/Networking/Select.pm line 243)
   frame 16: (eval) (/<C:\PROGRA~1\SQUEEZ~1\server\SQUEEZ~1.EXE>Slim/Networking/Select.pm line 243)
   frame 17: Slim::Networking::Select::select (slimserver.pl line 503)
   frame 18: main::idle (slimserver.pl line 453)
   frame 19: main::main (slimserver.pl line 86)
   frame 20: PerlSvc::Interactive (perlsvc line 855)
   frame 21: PerlSvc::_interactive (slimserver.pl line 0)
   frame 22: (eval) (slimserver.pl line 0)

[08-04-09 22:22:03.4544] Slim::Schema::Storage::dbh (38) Warning: Unable to connect to the database - trying to bring it up!
[08-04-09 22:22:06.6406] Slim::Schema::forceCommit (1363) Warning: Trying to commit transactions before DB is initialized!
[08-04-09 22:22:07.5782] Slim::Schema::Storage::throw_exception (67) Error: DBI Connection failed: DBI connect('hostname=127.0.0.1;port=9092;database=slimserver','slimserver',...) failed: Can't connect to MySQL server on '127.0.0.1' (10061) at C:\PROGRA~1\SQUEEZ~1\server\CPAN/DBIx/Class/Storage/DBI.pm line 652
[08-04-09 22:22:07.5786] Slim::Schema::Storage::throw_exception (67) Backtrace:

   frame 0: Slim::Utils::Log::logBacktrace (C:/PROGRA~1/SQUEEZ~1/server/Slim/Schema/Storage.pm line 67)
   frame 1: Slim::Schema::Storage::throw_exception (C:\PROGRA~1\SQUEEZ~1\server\CPAN/DBIx/Class/Storage/DBI.pm line 658)
   frame 2: DBIx::Class::Storage::DBI::_connect (C:\PROGRA~1\SQUEEZ~1\server\CPAN/DBIx/Class/Storage/DBI.pm line 616)
   frame 3: DBIx::Class::Storage::DBI::_populate_dbh (C:\PROGRA~1\SQUEEZ~1\server\CPAN/DBIx/Class/Storage/DBI.pm line 543)
   frame 4: DBIx::Class::Storage::DBI::ensure_connected (C:/PROGRA~1/SQUEEZ~1/server/Slim/Schema/Storage.pm line 27)
   frame 5: (eval) (C:/PROGRA~1/SQUEEZ~1/server/Slim/Schema/Storage.pm line 27)
   frame 6: Slim::Schema::Storage::dbh (/<C:\PROGRA~1\SQUEEZ~1\server\SQUEEZ~1.EXE>Slim/Schema.pm line 244)
   frame 7: (eval) (/<C:\PROGRA~1\SQUEEZ~1\server\SQUEEZ~1.EXE>Slim/Schema.pm line 244)
   frame 8: Slim::Schema::disconnect (slimserver.pl line 964)
   frame 9: main::cleanup (/<C:\PROGRA~1\SQUEEZ~1\server\SQUEEZ~1.EXE>Slim/bootstrap.pm line 368)
   frame 10: Slim::bootstrap::sigint (/<C:\PROGRA~1\SQUEEZ~1\server\SQUEEZ~1.EXE>Slim/bootstrap.pm line 402)
   frame 11: Slim::bootstrap::theEND (slimserver.pl line 1001)
   frame 12: main::END (slimserver.pl line 0)
   frame 13: (eval) (slimserver.pl line 0)

[08-04-09 22:25:46.5001] Slim::Schema::init (151) Warning: Creating new database - empty database or database from 6.3.x found
[08-04-09 22:25:53.6407] Slim::Schema::migrateDB (384) Connected to database dbi:mysql:hostname=127.0.0.1;port=9092;database=slimserver - schema version: [5]
[08-04-09 22:25:53.6410] Slim::Schema::migrateDB (390) Migrated database from schema version: 0 to version: 5
[08-04-09 22:25:53.8467] Slim::Music::Info::loadTypesConfig (96) Loading config file...
[08-04-09 22:25:56.2492] main::checkDataSource (877) Warning: Schema updated or tracks in the database, initiating scan.
[08-04-09 22:25:57.3385] Slim::Networking::SqueezeNetwork::_error (342) Unable to login to SN: Invalid SqueezeNetwork username or password.
[08-04-09 22:25:57.3388] Slim::Networking::SqueezeNetwork::_init_error (131) Unable to login to SqueezeNetwork, sync is disabled: Invalid SqueezeNetwork username or password.
[08-04-09 22:25:57.3393] Slim::Networking::SqueezeNetwork::_init_error (141) SqueezeNetwork sync init failed: Invalid SqueezeNetwork username or password., will retry in 40200
[08-04-09 22:26:02.5842] Slim::Web::Pages::BrowseDB::browsedb (55) Hierarchy: album,track level: 0
[08-04-09 22:39:26.9567] Slim::Schema::forceCommit (1382) forceCommit ignored, database is in AutoCommit mode

Comment 9 Andy Grundman 2008-04-09 17:54:22 UTC
That doesn't look like a scan log, but it's hard to read... can you attach it to the bug instead?  Also, please test with tonight's nightly, the fix I added is not in the build you have.
Comment 10 Alexander Holy 2008-04-09 18:14:59 UTC
Sorry, it's quite late here now...
Retested with Build 18576
Now this looks like a clue - I'm attaching the mp3 likely to cause trouble in log_and_mp3_18675.zip
Comment 11 Alexander Holy 2008-04-09 18:22:58 UTC
Created attachment 3217 [details]
scan log indicating problems and mp3 file

scan log indicating problems and mp3 file
Comment 12 Andy Grundman 2008-04-09 18:34:41 UTC
Ah yes, that track has no ARTIST or ALBUMARTIST tag, only BAND.  My patch will fix your crash.
Comment 13 James Richardson 2008-05-08 13:42:14 UTC
(In reply to comment #12)
> Ah yes, that track has no ARTIST or ALBUMARTIST tag, only BAND.  My patch will
> fix your crash.
> 

Verified fixed in 7.0.1 - 19325  r2409 - r23

Alexander: please reopen the bug if this did not fix your issue
Comment 14 James Richardson 2008-05-15 12:30:24 UTC
This bug has recently been fixed in the latest release of SqueezeCenter 7.0.1

Please try that version, if you still see the error, then reopen this bug.

To download this version, please navigate to: http://www.slimdevices.com/su_downloads.html
Comment 15 Chris Owens 2009-07-31 10:19:21 UTC
Reduce number of active targets for SC