PDA

View Full Version : Still scanning..



sand
2007-11-08, 21:59
Hi all,

I initiated a rescan yesterday, since I've edited some tags with Mp3tag and SlimServer still says its scanning, after I left it overnight (approx 5000 songs). It used to take only a couple of minutes..?!

"Note: SlimServer is still scanning your music library, so some items may not appear in this area yet."
What could be the problem?

Is there a log or something so that I can see what it's doing?
Thank's in advance for any help!

Here is my log. Is there really not a more detailed log anywhere?

2007-11-08 12:06:44.9532 TrackStat:Backup: Backup completed at 2007-11-08 12:06:44
2007-11-08 15:55:40.2884 commit ineffective with AutoCommit enabled at /opt/slimserver/CPAN/DBIx/Class/Storage/DBI.pm line 706.
2007-11-08 15:55:40.3737 Commmit ineffective while AutoCommit is on at /opt/slimserver/CPAN/DBIx/Class/Storage/DBI.pm line 706.
2007-11-08 15:55:40.3913 ERROR: DBD::mysql::db commit failed: Commmit ineffective while AutoCommit is on at /opt/slimserver/CPAN/DBIx/Class/Storage/DBI.pm line 706.

2007-11-08 15:55:40.3932 ERROR: Backtrace follows:

2007-11-08 15:55:40.4739 Backtrace:

frame 0: Slim::Schema::throw_exception (/opt/slimserver/CPAN/DBIx/Class/Schema.pm line 606)
frame 1: DBIx::Class::Schema::txn_do (/opt/slimserver/Slim/Music/Import.pm line 241)
frame 2: (eval) (/opt/slimserver/Slim/Music/Import.pm line 233)
frame 3: Slim::Music::Import::setIsScanning (/opt/slimserver/Slim/Music/Import.pm line 152)
frame 4: Slim::Music::Import::launchScan (/opt/slimserver/Slim/Control/Commands.pm line 1725)
frame 5: Slim::Control::Commands::rescanCommand (/opt/slimserver/Slim/Control/Request.pm line 1483)
frame 6: (eval) (/opt/slimserver/Slim/Control/Request.pm line 1483)
frame 7: Slim::Control::Request::execute (/opt/slimserver/Slim/Control/Request.pm line 772)
frame 8: Slim::Control::Request::executeRequest (/opt/slimserver/Slim/Web/Setup.pm line 1446)
frame 9: Slim::Web::Setup::__ANON__ (/opt/slimserver/Slim/Web/Setup.pm line 2928)
frame 10: Slim::Web::Setup:rocessChanges (/opt/slimserver/Slim/Web/Setup.pm line 2682)
frame 11: Slim::Web::Setup::setup_HTTP (/opt/slimserver/Slim/Web/HTTP.pm line 837)
frame 12: Slim::Web::HTTP::generateHTTPResponse (/opt/slimserver/Slim/Web/HTTP.pm line 703)
frame 13: Slim::Web::HTTP:rocessURL (/opt/slimserver/Slim/Web/HTTP.pm line 549)
frame 14: Slim::Web::HTTP:rocessHTTP (/opt/slimserver/Slim/Networking/Select.pm line 238)
frame 15: (eval) (/opt/slimserver/Slim/Networking/Select.pm line 238)
frame 16: Slim::Networking::Select::select (/opt/slimserver/slimserver.pl line 492)
frame 17: main::idle (/opt/slimserver/slimserver.pl line 445)
frame 18: main::main (/opt/slimserver/slimserver.pl line 1069)

2007-11-09 03:07:16.6897 TrackStat:Backup: Backup completed at 2007-11-09 03:07:16

Mark Lanctot
2007-11-09, 08:25
Any recursive shortcuts? Any new playlists or playlists that may reference now-obsolete filenames or locations?

Try the d_scan debugging flag.

Also from the log above, it seems to be something with TrackStat, you may want to disable it.

sand
2007-11-09, 09:50
I have been editing tags, seems like a long-shot, but could some new tags make the scan abort?
I think the log is not detailed enough (but sill very long..!), at least it doesn't tell me what goes wrong..?

OK, here is my latest log:


2007-11-09 17:40:27.5051 SELECT me.name, me.value FROM metainformation me WHERE ( name = ? ): 'isScanning'
2007-11-09 17:40:27.6338 SELECT COUNT( DISTINCT( me.id ) ) FROM tracks me WHERE ( me.audio = ? ): '1'
2007-11-09 17:40:27.8780 SELECT COUNT( DISTINCT( me.id ) ) FROM albums me:
2007-11-09 17:40:27.9218 SELECT COUNT( DISTINCT( me.id ) ) FROM contributors me LEFT JOIN contributor_album contributorAlbums ON ( contributorAlbums.contributor = me.id ) WHERE ( contributorAlbums.role IN ( ?, ? ) ): '1', '5'
2007-11-09 17:40:28.0017 ->addLibraryStats() found 2431 songs, 575 albums & 235 artists
2007-11-09 17:40:37.2763 Reading firmware version file: /opt/slimserver/Firmware/squeezebox2.version
2007-11-09 17:40:37.2800 squeezebox2 firmware is up-to-date, v. 81
2007-11-09 17:40:38.4793 Reading firmware version file: /opt/slimserver/Firmware/squeezebox2.version
2007-11-09 17:40:38.4829 squeezebox2 firmware is up-to-date, v. 81
2007-11-09 17:41:09.0187 Reading firmware version file: /opt/slimserver/Firmware/squeezebox2.version
2007-11-09 17:41:09.0224 squeezebox2 firmware is up-to-date, v. 81
2007-11-09 17:41:10.1087 Reading firmware version file: /opt/slimserver/Firmware/squeezebox2.version
2007-11-09 17:41:10.1123 squeezebox2 firmware is up-to-date, v. 81
2007-11-09 17:41:23.4747 Setup::rescan - initiating scan of type: [wipecache]
2007-11-09 17:41:23.4869
2007-11-09 17:41:23.5175 SELECT me.name, me.value FROM metainformation me WHERE ( name = ? ): 'isScanning'
2007-11-09 17:41:23.5388
2007-11-09 17:41:23.5945 SELECT me.id, me.url, me.content_type, me.title, me.titlesort, me.titlesearch, me.album, me.tracknum, me.timestamp, me.filesize, me.disc, me.thumb, me.remote, me.audio, me.audio_size, me.audio_offset, me.year, me.secs, me.cover, me.vbr_scale, me.bitrate, me.samplerate, me.samplesize, me.channels, me.block_alignment, me.endian, me.bpm, me.tagversion, me.drm, me.musicmagic_mixable, me.musicbrainz_id, me.playcount, me.lastplayed, me.lossless, me.lyrics, me.rating, me.replay_gain, me.replay_peak FROM tracks me WHERE ( url = ? ): 'clientplaylist://00:04:20:07:2c:32'
2007-11-09 17:41:23.6477 DELETE FROM playlist_track WHERE ( playlist = ? ): '1059'
2007-11-09 17:41:23.9257 Request: Command [00:04:20:07:2c:32->playlist clear] (Done)
2007-11-09 17:41:24.3518 SELECT me.name, me.value FROM metainformation me WHERE ( ( me.name = ? ) ): 'isScanning'
2007-11-09 17:41:24.4351 forceCommit: syncing to the database.
2007-11-09 17:41:24.4455 commit ineffective with AutoCommit enabled at /opt/slimserver/CPAN/DBIx/Class/Storage/DBI.pm line 706.
2007-11-09 17:41:24.4480 Commmit ineffective while AutoCommit is on at /opt/slimserver/CPAN/DBIx/Class/Storage/DBI.pm line 706.
2007-11-09 17:41:24.4531 ERROR: DBD::mysql::db commit failed: Commmit ineffective while AutoCommit is on at /opt/slimserver/CPAN/DBIx/Class/Storage/DBI.pm line 706.

2007-11-09 17:41:24.4551 ERROR: Backtrace follows:

2007-11-09 17:41:24.4618 Backtrace:

frame 0: Slim::Schema::throw_exception (/opt/slimserver/CPAN/DBIx/Class/Schema.pm line 606)
frame 1: DBIx::Class::Schema::txn_do (/opt/slimserver/Slim/Music/Import.pm line 241)
frame 2: (eval) (/opt/slimserver/Slim/Music/Import.pm line 233)
frame 3: Slim::Music::Import::setIsScanning (/opt/slimserver/Slim/Music/Import.pm line 152)
frame 4: Slim::Music::Import::launchScan (/opt/slimserver/Slim/Control/Commands.pm line 1958)
frame 5: Slim::Control::Commands::wipecacheCommand (/opt/slimserver/Slim/Control/Request.pm line 1483)
frame 6: (eval) (/opt/slimserver/Slim/Control/Request.pm line 1483)
frame 7: Slim::Control::Request::execute (/opt/slimserver/Slim/Control/Request.pm line 772)
frame 8: Slim::Control::Request::executeRequest (/opt/slimserver/Slim/Web/Setup.pm line 1446)
frame 9: Slim::Web::Setup::__ANON__ (/opt/slimserver/Slim/Web/Setup.pm line 2928)
frame 10: Slim::Web::Setup::processChanges (/opt/slimserver/Slim/Web/Setup.pm line 2682)
frame 11: Slim::Web::Setup::setup_HTTP (/opt/slimserver/Slim/Web/HTTP.pm line 843)
frame 12: Slim::Web::HTTP::generateHTTPResponse (/opt/slimserver/Slim/Web/HTTP.pm line 709)
frame 13: Slim::Web::HTTP::processURL (/opt/slimserver/Slim/Web/HTTP.pm line 555)
frame 14: Slim::Web::HTTP::processHTTP (/opt/slimserver/Slim/Networking/Select.pm line 238)
frame 15: (eval) (/opt/slimserver/Slim/Networking/Select.pm line 238)
frame 16: Slim::Networking::Select::select (/opt/slimserver/slimserver.pl line 499)
frame 17: main::idle (/opt/slimserver/slimserver.pl line 452)
frame 18: main::main (/opt/slimserver/slimserver.pl line 1076)

2007-11-09 17:41:24.4676 Request: Command [wipecache] (Done)
2007-11-09 17:41:26.4615 SELECT me.id, me.url, me.content_type, me.title, me.titlesort, me.titlesearch, me.album, me.tracknum, me.timestamp, me.filesize, me.disc, me.thumb, me.remote, me.audio, me.audio_size, me.audio_offset, me.year, me.secs, me.cover, me.vbr_scale, me.bitrate, me.samplerate, me.samplesize, me.channels, me.block_alignment, me.endian, me.bpm, me.tagversion, me.drm, me.musicmagic_mixable, me.musicbrainz_id, me.playcount, me.lastplayed, me.lossless, me.lyrics, me.rating, me.replay_gain, me.replay_peak FROM tracks me WHERE ( url = ? ): 'clientplaylist://00:04:20:07:2c:32'
2007-11-09 17:41:26.4986 Use of uninitialized value in concatenation (.) or string at /opt/slimserver/Slim/Schema.pm line 952.
2007-11-09 17:41:26.5038 Merging entry for clientplaylist://00:04:20:07:2c:32 readTags is: []
2007-11-09 17:41:27.4626 Updating clientplaylist://00:04:20:07:2c:32 : title to Now Playing - 192.168.1.2
2007-11-09 17:41:27.5280 Updating clientplaylist://00:04:20:07:2c:32 : year to 0
2007-11-09 17:41:27.5329 Updating clientplaylist://00:04:20:07:2c:32 : content_type to cpl
2007-11-09 17:41:27.5375 Updating clientplaylist://00:04:20:07:2c:32 : remote to 0
2007-11-09 17:41:27.5422 Updating clientplaylist://00:04:20:07:2c:32 : titlesearch to NOW PLAYING 192 168 1 2
2007-11-09 17:41:27.5467 Updating clientplaylist://00:04:20:07:2c:32 : titlesort to NOW PLAYING 192 168 1 2
2007-11-09 17:41:27.5518 Updating clientplaylist://00:04:20:07:2c:32 : rating to 0
2007-11-09 17:41:27.5889 DELETE FROM playlist_track WHERE ( playlist = ? ): '1059'
2007-11-09 17:41:29.4876 SELECT me.name, me.value FROM metainformation me WHERE ( name = ? ): 'isScanning'
2007-11-09 17:41:34.5286 SELECT me.name, me.value FROM metainformation me WHERE ( name = ? ): 'isScanning'
2007-11-09 17:41:39.5629 SELECT me.name, me.value FROM metainformation me WHERE ( name = ? ): 'isScanning'
2007-11-09 17:41:40.7949 Reading firmware version file: /opt/slimserver/Firmware/squeezebox2.version
2007-11-09 17:41:40.8113 squeezebox2 firmware is up-to-date, v. 81
2007-11-09 17:41:41.7158 Reading firmware version file: /opt/slimserver/Firmware/squeezebox2.version
2007-11-09 17:41:41.7195 squeezebox2 firmware is up-to-date, v. 81
2007-11-09 17:41:44.5984 SELECT me.name, me.value FROM metainformation me WHERE ( name = ? ): 'isScanning'
2007-11-09 17:41:49.6434 SELECT me.name, me.value FROM metainformation me WHERE ( name = ? ): 'isScanning'
2007-11-09 17:41:54.6734 SELECT me.name, me.value FROM metainformation me WHERE ( name = ? ): 'isScanning'
2007-11-09 17:41:59.7028 SELECT me.name, me.value FROM metainformation me WHERE ( name = ? ): 'isScanning'
2007-11-09 17:42:04.7431 SELECT me.name, me.value FROM metainformation me WHERE ( name = ? ): 'isScanning'
2007-11-09 17:42:09.8383 SELECT me.name, me.value FROM metainformation me WHERE ( name = ? ): 'isScanning'
2007-11-09 17:42:16.4670 SELECT me.name, me.value FROM metainformation me WHERE ( name = ? ): 'isScanning'
2007-11-09 17:42:21.6326 SELECT me.name, me.value FROM metainformation me WHERE ( name = ? ): 'isScanning'
2007-11-09 17:42:26.9165 SELECT me.name, me.value FROM metainformation me WHERE ( name = ? ): 'isScanning'
2007-11-09 17:42:32.7016 SELECT me.name, me.value FROM metainformation me WHERE ( name = ? ): 'isScanning'
2007-11-09 17:42:36.4444 Reading firmware version file: /opt/slimserver/Firmware/squeezebox2.version
2007-11-09 17:42:36.4793 squeezebox2 firmware is up-to-date, v. 81
2007-11-09 17:42:38.9079 SELECT me.name, me.value FROM metainformation me WHERE ( name = ? ): 'isScanning'
2007-11-09 17:42:39.2105
2007-11-09 17:42:39.3538 Request: Command [00:04:20:07:2c:32->ir] (Done)
2007-11-09 17:42:39.3566 Param: [_ircode] = [000848b7]
2007-11-09 17:42:39.3592 Param: [_time] = [88633.399]
2007-11-09 17:42:39.4373
2007-11-09 17:42:39.4487 Request: Command [00:04:20:07:2c:32->ir] (Done)
2007-11-09 17:42:39.4533 Param: [_ircode] = [000848b7]
2007-11-09 17:42:39.4559 Param: [_time] = [88633.459]
2007-11-09 17:42:39.4944
2007-11-09 17:42:39.5048 Request: Command [00:04:20:07:2c:32->ir] (Done)
2007-11-09 17:42:39.5075 Param: [_ircode] = [000848b7]
2007-11-09 17:42:39.5104 Param: [_time] = [88633.882]
2007-11-09 17:42:39.9525 Reading firmware version file: /opt/slimserver/Firmware/squeezebox2.version
2007-11-09 17:42:39.9562 squeezebox2 firmware is up-to-date, v. 81
2007-11-09 17:42:43.9652 SELECT me.name, me.value FROM metainformation me WHERE ( name = ? ): 'isScanning'
2007-11-09 17:42:49.0693 SELECT me.name, me.value FROM metainformation me WHERE ( name = ? ): 'isScanning'
2007-11-09 17:42:54.1227 SELECT me.name, me.value FROM metainformation me WHERE ( name = ? ): 'isScanning'