Rescan library not working

[Peddy]

Hey everyone

I'm not able to add more music to my library using rescan... Here's my scanner.log. If you could be of any help, please help! ;D

I'm running the .tar.gz package of SC 7.2 under Ubuntu 64-bit.

   frame 10: Slim::bootstrap::theEND (/home/peddy/Downloads/squeezecenter-7.2/scanner.pl line 348)
   frame 11: main::END (/home/peddy/Downloads/squeezecenter-7.2/Slim/Schema.pm line 135)
   frame 12: (eval) (/home/peddy/Downloads/squeezecenter-7.2/Slim/Schema.pm line 135)

[08-10-19 01:08:28.9345] main:: (135) Warning: Can't use an undefined value as a HASH reference at /home/peddy/Downloads/squeezecenter-7.2/Slim/Music/Import.pm line 277.
END failed--call queue aborted at /home/peddy/Downloads/squeezecenter-7.2/scanner.pl line 135.
[08-10-19 10:14:46.6386] Slim::Schema::Storage::throw_exception (70) Error: DBI Connection failed: DBI connect('hostname=127.0.0.1;port=9092;database=slimserver','slimserver',...) failed: Access denied for user 'slimserver'@'localhost' (using password: NO) at /home/peddy/Downloads/squeezecenter-7.2/CPAN/DBIx/Class/Storage/DBI.pm line 652
[08-10-19 10:14:46.6389] Slim::Schema::Storage::throw_exception (70) Backtrace:

   frame 0: Slim::Utils::Log::logBacktrace (/home/peddy/Downloads/squeezecenter-7.2/Slim/Schema/Storage.pm line 70)
   frame 1: Slim::Schema::Storage::throw_exception (/home/peddy/Downloads/squeezecenter-7.2/CPAN/DBIx/Class/Storage/DBI.pm line 658)
   frame 2: DBIx::Class::Storage::DBI::_connect (/home/peddy/Downloads/squeezecenter-7.2/CPAN/DBIx/Class/Storage/DBI.pm line 616)
   frame 3: DBIx::Class::Storage::DBI::_populate_dbh (/home/peddy/Downloads/squeezecenter-7.2/CPAN/DBIx/Class/Storage/DBI.pm line 543)
   frame 4: DBIx::Class::Storage::DBI::ensure_connected (/home/peddy/Downloads/squeezecenter-7.2/Slim/Schema/Storage.pm line 29)
   frame 5: (eval) (/home/peddy/Downloads/squeezecenter-7.2/Slim/Schema/Storage.pm line 29)
   frame 6: Slim::Schema::Storage::dbh (/home/peddy/Downloads/squeezecenter-7.2/Slim/Schema.pm line 131)
   frame 7: Slim::Schema::init (/home/peddy/Downloads/squeezecenter-7.2/Slim/Music/Info.pm line 76)
   frame 8: Slim::Music::Info::init (/home/peddy/Downloads/squeezecenter-7.2/scanner.pl line 271)
   frame 9: main::initializeFrameworks (/home/peddy/Downloads/squeezecenter-7.2/scanner.pl line 119)
   frame 10: main::main (/home/peddy/Downloads/squeezecenter-7.2/scanner.pl line 353)

[08-10-19 10:14:46.6392] Slim::Schema::init (134) Error: Couldn't connect to database! Fatal error: [Input/output error] Exiting!
[08-10-19 10:14:46.6394] Slim::Schema::init (134) Backtrace:

   frame 0: Slim::Utils::Log::logBacktrace (/home/peddy/Downloads/squeezecenter-7.2/Slim/Schema.pm line 134)
   frame 1: Slim::Schema::init (/home/peddy/Downloads/squeezecenter-7.2/Slim/Music/Info.pm line 76)
   frame 2: Slim::Music::Info::init (/home/peddy/Downloads/squeezecenter-7.2/scanner.pl line 271)
   frame 3: main::initializeFrameworks (/home/peddy/Downloads/squeezecenter-7.2/scanner.pl line 119)
   frame 4: main::main (/home/peddy/Downloads/squeezecenter-7.2/scanner.pl line 353)

[08-10-19 10:14:46.6403] Slim::Schema::Storage::throw_exception (70) Error: DBI Connection failed: DBI connect('hostname=127.0.0.1;port=9092;database=slimserver','slimserver',...) failed: Access denied for user 'slimserver'@'localhost' (using password: NO) at /home/peddy/Downloads/squeezecenter-7.2/CPAN/DBIx/Class/Storage/DBI.pm line 652
[08-10-19 10:14:46.6405] Slim::Schema::Storage::throw_exception (70) Backtrace:

   frame 0: Slim::Utils::Log::logBacktrace (/home/peddy/Downloads/squeezecenter-7.2/Slim/Schema/Storage.pm line 70)
   frame 1: Slim::Schema::Storage::throw_exception (/home/peddy/Downloads/squeezecenter-7.2/CPAN/DBIx/Class/Storage/DBI.pm line 658)
   frame 2: DBIx::Class::Storage::DBI::_connect (/home/peddy/Downloads/squeezecenter-7.2/CPAN/DBIx/Class/Storage/DBI.pm line 616)
   frame 3: DBIx::Class::Storage::DBI::_populate_dbh (/home/peddy/Downloads/squeezecenter-7.2/CPAN/DBIx/Class/Storage/DBI.pm line 543)
   frame 4: DBIx::Class::Storage::DBI::ensure_connected (/home/peddy/Downloads/squeezecenter-7.2/Slim/Schema/Storage.pm line 29)
   frame 5: (eval) (/home/peddy/Downloads/squeezecenter-7.2/Slim/Schema/Storage.pm line 29)
   frame 6: Slim::Schema::Storage::dbh (/home/peddy/Downloads/squeezecenter-7.2/Slim/Music/Import.pm line 277)
   frame 7: Slim::Music::Import::setIsScanning (/home/peddy/Downloads/squeezecenter-7.2/scanner.pl line 339)
   frame 8: main::cleanup (/home/peddy/Downloads/squeezecenter-7.2/Slim/bootstrap.pm line 373)
   frame 9: Slim::bootstrap::sigint (/home/peddy/Downloads/squeezecenter-7.2/Slim/bootstrap.pm line 407)
   frame 10: Slim::bootstrap::theEND (/home/peddy/Downloads/squeezecenter-7.2/scanner.pl line 348)
   frame 11: main::END (/home/peddy/Downloads/squeezecenter-7.2/Slim/Schema.pm line 135)
   frame 12: (eval) (/home/peddy/Downloads/squeezecenter-7.2/Slim/Schema.pm line 135)

[08-10-19 10:14:46.6408] main:: (135) Warning: Can't use an undefined value as a HASH reference at /home/peddy/Downloads/squeezecenter-7.2/Slim/Music/Import.pm line 277.
END failed--call queue aborted at /home/peddy/Downloads/squeezecenter-7.2/scanner.pl line 135.

[schiegl]

Hi,

are you sure it's not a permission problem within mysql:

[08-10-19 10:14:46.6386] Slim::Schema::Storage::throw_exception (70) Error: DBI Connection failed: DBI connect('hostname=127.0.0.1;port=9092;database=sli mserver','slimserver',...) failed: Access denied for user 'slimserver'@'localhost' (using password: NO) at /home/peddy/Downloads/squeezecenter-7.2/CPAN/DBIx/Class/Storage/DBI.pm line 652

SC doesn't use usernames at all - for it's bundled mysql db...
Your servers.prefs should contain the following lines (in case of the included mySQL)
...
dbpassword: ''
dbsource: dbi:mysql:hostname=127.0.0.1;port=9092;database=%s
dbusername: ''
...

Do you use the bundled mySQL or an external one?

kind regards,
Markus

[Peddy]

Thank you for your reply Markus,

It turns out, it was a MySQL problem; a mySQL server I already had running was conflicting with the standalone one. I managed to configure Squeezecenter to use my separate by one changing the config settings you gave me

Thanks

[OiPenguin]

I belive my problem may have a similar explanation. When I add music to the folders which are scanned nightly, they are not index. However, the become indexed after I've visited the folder in the Squeezecenter interface (Home > Music Folder 'name of folder').

Question: Where do I find servers.prefs?

Yours,

Lars

[schiegl]

Lars,

I belive my problem may have a similar explanation. When I add music to the folders which are scanned nightly, they are not index. However, the become indexed after I've visited the folder in the Squeezecenter interface (Home > Music Folder 'name of folder').

You can always enable the scanner log (settings->advanced->logging->scan* to some higher level) to see why the scan fails eventually. The Scanner Logfile is referenced on the status page.

Does a manual rescan work?

Maybe there is going "something" wrong in the night (powersave mode?)

Question: Where do I find servers.prefs?

Goto Settings->Status and there you'll see where the "preferences folder" is located on your system - assuming your SC version is not very old.

kind regards,
Markus

[OiPenguin]

You can always enable the scanner log (settings->advanced->logging->scan* to some higher level) to see why the scan fails eventually. The Scanner Logfile is referenced on the status page.

Does a manual rescan work?

Maybe there is going "something" wrong in the night (powersave mode?)

I believe this extract from the log confirms your suggestion? I tried a manual rescan now, with fail as a result. How do I resolve the issue reported by the logfile?

[08-10-24 01:01:16.0853] main::main (220) Error: Skipping post-process & Not updating lastRescanTime!
[08-10-25 01:01:31.9283] main::main (219) Error: Failed when running main scan: [Can't call method "content_type" on an undefined value at /usr/share/perl5/Slim/Schema.pm line 1155.
]

Goto Settings->Status and there you'll see where the "preferences folder" is located on your system - assuming your SC version is not very old.

I've confirmed that my servers.prefs contain the following lines (in case of the included mySQL)
...
dbpassword: ''
dbsource: dbi:mysql:hostname=127.0.0.1;port=9092;database=%s
dbusername: ''
...

Yours,

Lars

[schiegl]

I believe this extract from the log confirms your suggestion? I tried a manual rescan now, with fail as a result. How do I resolve the issue reported by the logfile?

[08-10-24 01:01:16.0853] main::main (220) Error: Skipping post-process & Not updating lastRescanTime!
[08-10-25 01:01:31.9283] main::main (219) Error: Failed when running main scan: [Can't call method "content_type" on an undefined value at /usr/share/perl5/Slim/Schema.pm line 1155.
]

Lars,

without more informations (version, etc.) and logs (set scan.* to debug) i can guess only. Schema.pm could relate to the database so you can try to rebuild the MySQL DB...

1. stop SC
2. remove (or better rename for safety) <Cachefolder>/MySQL
3. start SC

Cachefolder=check Settings->Status

This will automatically create a new DB and initiate a full rescan, which hopefully works...

beware: If your problem is something else you now have an empty DB ;-)
(rollback: stop SC, restore old MySQL folder, start SC)

kind regards,
Markus

[OiPenguin]

Lars,

without more informations (version, etc.) and logs (set scan.* to debug) i can guess only. Schema.pm could relate to the database so you can try to rebuild the MySQL DB...

1. stop SC
2. remove (or better rename for safety) <Cachefolder>/MySQL
3. start SC

Cachefolder=check Settings->Status

This will automatically create a new DB and initiate a full rescan, which hopefully works...

beware: If your problem is something else you now have an empty DB ;-)
(rollback: stop SC, restore old MySQL folder, start SC)

kind regards,
Markus

I did as suggested and left it for a few days. The initial rescan was successful, however the problem persist. This extract is repeated in the log daily:

[08-10-26 10:47:10.7684] main::main (220) Error: Skipping post-process & Not updating lastRescanTime!
[08-10-27 01:01:03.4971] main::main (219) Error: Failed when running main scan: [Can't call method "content_type" on an undefined value at /usr/share/perl5/Slim/Schema.pm line 1155.
]

I'm running Ubuntu server 8.04 (planning to upgrade to 8.10).
SqueezeCenter Version: 7.2 - 22900 @ Tue Aug 26 10:45:08 PDT 2008 - Debian - EN - utf8
Perl Version: 5.8.8 i486-linux-gnu-thread-multi
MySQL Version: 5.0.51a-3ubuntu5.1

Platform Architecture: i686-linux

I've set (scan.import) and (scan.scanner) to debug. Are there any other logs which should be set to debug?

Yours,

Lars

[schiegl]

I did as suggested and left it for a few days. The initial rescan was successful, however the problem persist. This extract is repeated in the log daily:

[08-10-26 10:47:10.7684] main::main (220) Error: Skipping post-process & Not updating lastRescanTime!
[08-10-27 01:01:03.4971] main::main (219) Error: Failed when running main scan: [Can't call method "content_type" on an undefined value at /usr/share/perl5/Slim/Schema.pm line 1155.
]

I'm running Ubuntu server 8.04 (planning to upgrade to 8.10).
SqueezeCenter Version: 7.2 - 22900 @ Tue Aug 26 10:45:08 PDT 2008 - Debian - EN - utf8
Perl Version: 5.8.8 i486-linux-gnu-thread-multi
MySQL Version: 5.0.51a-3ubuntu5.1

Platform Architecture: i686-linux

I've set (scan.import) and (scan.scanner) to debug. Are there any other logs which should be set to debug?

Yours,

Lars

Does the scanner.log File (not the server.log) contain anything useful? It should at least tell you at which file/playlist the scan aborts. If i understand the code around line 1155 in Schema.pm correctly (which i wont bet on) the scanner tries to set the content-type of a "non-usual" track and fails on retrieving this value. The scanner.log should reveal the responsible track.

The same happens if you do manual update rescans, doesn't it?

kind regards,
Markus

[OiPenguin]

Does the scanner.log File (not the server.log) contain anything useful? It should at least tell you at which file/playlist the scan aborts. If i understand the code around line 1155 in Schema.pm correctly (which i wont bet on) the scanner tries to set the content-type of a "non-usual" track and fails on retrieving this value. The scanner.log should reveal the responsible track.

The same happens if you do manual update rescans, doesn't it?

kind regards,
Markus

From what I can tell, scanner.log provides none of the information you request. This is the entire log. Both scheduled and manual scan fails.

[08-10-26 10:47:10.7679] main::main (219) Error: Failed when running main scan: [Can't call method "content_type" on an undefined value at /usr/share/perl5/Slim/Schema.pm line 1155.
]
[08-10-26 10:47:10.7684] main::main (220) Error: Skipping post-process & Not updating lastRescanTime!
[08-10-27 01:01:03.4971] main::main (219) Error: Failed when running main scan: [Can't call method "content_type" on an undefined value at /usr/share/perl5/Slim/Schema.pm line 1155.
]
[08-10-27 01:01:03.5163] main::main (220) Error: Skipping post-process & Not updating lastRescanTime!
[08-10-28 01:01:21.8936] main::main (219) Error: Failed when running main scan: [Can't call method "content_type" on an undefined value at /usr/share/perl5/Slim/Schema.pm line 1155.
]
[08-10-28 01:01:21.9109] main::main (220) Error: Skipping post-process & Not updating lastRescanTime!
[08-10-29 01:01:42.2117] main::main (219) Error: Failed when running main scan: [Can't call method "content_type" on an undefined value at /usr/share/perl5/Slim/Schema.pm line 1155.
]
[08-10-29 01:01:42.2682] main::main (220) Error: Skipping post-process & Not updating lastRescanTime!
[08-10-29 19:40:58.0119] main::main (219) Error: Failed when running main scan: [Can't call method "content_type" on an undefined value at /usr/share/perl5/Slim/Schema.pm line 1155.
]
[08-10-29 19:40:58.0126] main::main (220) Error: Skipping post-process & Not updating lastRescanTime!