[17:22:15.2374] Slim::Schema::migrateDB (425) Connected to database dbi:mysql:hostname=127.0.0.1;database=slimserver - schema version: [6]
[17:22:15.4947] Slim::Music::Info::loadTypesConfig (96) Loading config file...
[17:22:15.5068] Slim::Schema::Debug::query_start (26) SELECT me.name, me.value FROM metainformation me WHERE ( name = ? ): 'isScanning'
[17:22:15.5104] Slim::Music::Import::addImporter (471) Adding Slim::Music::PlaylistFolderScan Scan
[17:22:15.5115] Slim::Music::Import::addImporter (471) Adding Slim::Music::MusicFolderScan Scan
[17:22:15.5452] Slim::Schema::Debug::query_start (26) SELECT me.name, me.value FROM metainformation me WHERE ( ( me.name = ? ) ): 'isScanning'
[17:22:15.5497] Slim::Schema::forceCommit (1494) Syncing to the database.
[17:22:15.5527] Slim::Schema::forceCommit (1494) Syncing to the database.
[17:22:15.5546] Slim::Schema::wipeCaches (1456) Wiped all in-memory caches.
[17:22:15.5557] Slim::Schema::wipeDB (335) Start schema_clear
[17:22:15.5577] Slim::Utils::SQLHelper::executeSQLFile (52) Executing SQL file /usr/local/slimserver/SQL/mysql/schema_clear.sql
[17:22:15.5591] Slim::Utils::SQLHelper::executeSQLFile (81) Executing SQL: [SET foreign_key_checks = 0;]
[17:22:15.5608] Slim::Utils::SQLHelper::executeSQLFile (81) Executing SQL: [DELETE FROM tracks;]
[17:22:15.5670] Slim::Utils::SQLHelper::executeSQLFile (81) Executing SQL: [DELETE FROM playlist_track;]
[17:22:15.5688] Slim::Utils::SQLHelper::executeSQLFile (81) Executing SQL: [DELETE FROM albums;]
[17:22:15.5708] Slim::Utils::SQLHelper::executeSQLFile (81) Executing SQL: [DELETE FROM years;]
[17:22:15.5725] Slim::Utils::SQLHelper::executeSQLFile (81) Executing SQL: [DELETE FROM contributors;]
[17:22:15.5744] Slim::Utils::SQLHelper::executeSQLFile (81) Executing SQL: [DELETE FROM contributor_track;]
[17:22:15.5762] Slim::Utils::SQLHelper::executeSQLFile (81) Executing SQL: [DELETE FROM contributor_album;]
[17:22:15.5781] Slim::Utils::SQLHelper::executeSQLFile (81) Executing SQL: [DELETE FROM genres;]
[17:22:15.5803] Slim::Utils::SQLHelper::executeSQLFile (81) Executing SQL: [DELETE FROM genre_track;]
[17:22:15.5822] Slim::Utils::SQLHelper::executeSQLFile (81) Executing SQL: [DELETE FROM comments;]
[17:22:15.5840] Slim::Utils::SQLHelper::executeSQLFile (81) Executing SQL: [DELETE FROM pluginversion;]
[17:22:15.5859] Slim::Utils::SQLHelper::executeSQLFile (81) Executing SQL: [DELETE FROM unreadable_tracks;]
[17:22:15.5877] Slim::Utils::SQLHelper::executeSQLFile (81) Executing SQL: [UPDATE metainformation SET value = 0 WHERE name = 'lastRescanTime';]
[17:22:15.5897] Slim::Utils::SQLHelper::executeSQLFile (81) Executing SQL: [UPDATE tracks_persistent SET track = NULL;]
[17:22:15.5916] Slim::Utils::SQLHelper::executeSQLFile (81) Executing SQL: [SET foreign_key_checks = 1;]
[17:22:15.6046] Slim::Schema::migrateDB (425) Connected to database dbi:mysql:hostname=127.0.0.1;database=slimserver - schema version: [6]
[17:22:15.6061] Slim::Schema::optimizeDB (371) Start schema_optimize
[17:22:15.6094] Slim::Schema::Debug::query_start (26) SELECT me.id, me.type, me.name, me.active, me.total, me.done, me.start, me.finish, me.info FROM progress me WHERE ( me.name = ? AND me.type = ? ): 'dboptimize', 'importer'
[17:22:15.6147] Slim::Schema::Debug::query_start (26) INSERT INTO progress (name, type) VALUES (?, ?): 'dboptimize', 'importer'
[17:22:15.6200] Slim::Schema::Debug::query_start (26) UPDATE progress SET active = ?, done = ?, start = ? WHERE ( id IS NULL ): '1', '0', '1220804535'
[17:22:15.6240] Slim::Utils::SQLHelper::executeSQLFile (52) Executing SQL file /usr/local/slimserver/SQL/mysql/schema_optimize.sql
[17:22:15.6256] Slim::Utils::SQLHelper::executeSQLFile (81) Executing SQL: [DELETE FROM contributor_album;]
[17:22:15.6273] Slim::Utils::SQLHelper::executeSQLFile (81) Executing SQL: [INSERT INTO contributor_album (role,contributor,album) SELECT DISTINCT role,contributor,album FROM contributor_track,tracks where tracks.id=contributor_track.track;]
[17:22:15.6303] Slim::Utils::SQLHelper::executeSQLFile (81) Executing SQL: [OPTIMIZE TABLE tracks;]
[17:22:15.6898] Slim::Utils::SQLHelper::executeSQLFile (81) Executing SQL: [OPTIMIZE TABLE playlist_track;]
[17:22:15.7203] Slim::Utils::SQLHelper::executeSQLFile (81) Executing SQL: [OPTIMIZE TABLE albums;]
[17:22:15.7530] Slim::Utils::SQLHelper::executeSQLFile (81) Executing SQL: [OPTIMIZE TABLE years;]
[17:22:15.7784] Slim::Utils::SQLHelper::executeSQLFile (81) Executing SQL: [OPTIMIZE TABLE contributors;]
[17:22:15.7975] Slim::Utils::SQLHelper::executeSQLFile (81) Executing SQL: [OPTIMIZE TABLE contributor_track;]
[17:22:15.8115] Slim::Utils::SQLHelper::executeSQLFile (81) Executing SQL: [OPTIMIZE TABLE contributor_album;]
[17:22:15.8272] Slim::Utils::SQLHelper::executeSQLFile (81) Executing SQL: [OPTIMIZE TABLE genres;]
[17:22:15.8617] Slim::Utils::SQLHelper::executeSQLFile (81) Executing SQL: [OPTIMIZE TABLE genre_track;]
[17:22:15.8881] Slim::Utils::SQLHelper::executeSQLFile (81) Executing SQL: [OPTIMIZE TABLE comments;]
[17:22:15.9121] Slim::Utils::SQLHelper::executeSQLFile (81) Executing SQL: [OPTIMIZE TABLE pluginversion;]
[17:22:15.9357] Slim::Utils::SQLHelper::executeSQLFile (81) Executing SQL: [OPTIMIZE TABLE unreadable_tracks;]
[17:22:15.9542] Slim::Schema::optimizeDB (390) End schema_optimize
[17:22:15.9571] Slim::Schema::Debug::query_start (26) UPDATE progress SET active = ?, done = ?, finish = ? WHERE ( id IS NULL ): '0', 'NULL', '1220804535'
[17:22:15.9599] Slim::Schema::throw_exception (236) Error: Can't update Slim::Schema::Progress=HASH(0xbade6d0): row not found
[17:22:15.9617] Slim::Schema::throw_exception (236) Backtrace:

   frame 0: Slim::Utils::Log::logBacktrace (/usr/local/slimserver/Slim/Schema.pm line 236)
   frame 1: Slim::Schema::throw_exception (/usr/local/slimserver/CPAN/DBIx/Class/Row.pm line 461)
   frame 2: DBIx::Class::Row::throw_exception (/usr/local/slimserver/CPAN/DBIx/Class/Row.pm line 117)
   frame 3: DBIx::Class::Row::update (/usr/local/slimserver/CPAN/DBIx/Class/Relationship/CascadeActions.pm line 30)
   frame 4: DBIx::Class::Relationship::CascadeActions::update (/usr/local/slimserver/CPAN/DBIx/Class/InflateColumn.pm line 222)
   frame 5: DBIx::Class::InflateColumn::update (/usr/local/slimserver/Slim/Schema/DBI.pm line 35)
   frame 6: Slim::Schema::DBI::update (/usr/local/slimserver/Slim/Utils/Progress.pm line 195)
   frame 7: Slim::Utils::Progress::final (/usr/local/slimserver/Slim/Schema.pm line 392)
   frame 8: Slim::Schema::optimizeDB (/usr/local/slimserver/Slim/Schema.pm line 347)
   frame 9: Slim::Schema::__ANON__ (/usr/local/slimserver/CPAN/DBIx/Class/Schema.pm line 584)
   frame 10: (eval) (/usr/local/slimserver/CPAN/DBIx/Class/Schema.pm line 572)
   frame 11: DBIx::Class::Schema::txn_do (/usr/local/slimserver/Slim/Schema.pm line 348)
   frame 12: (eval) (/usr/local/slimserver/Slim/Schema.pm line 339)
   frame 13: Slim::Schema::wipeDB (/usr/local/slimserver/Slim/Schema.pm line 1469)
   frame 14: Slim::Schema::wipeAllData (/usr/local/slimserver/scanner.pl line 172)
   frame 15: main::__ANON__ (/usr/local/slimserver/CPAN/DBIx/Class/Schema.pm line 584)
   frame 16: (eval) (/usr/local/slimserver/CPAN/DBIx/Class/Schema.pm line 572)
   frame 17: DBIx::Class::Schema::txn_do (/usr/local/slimserver/scanner.pl line 172)
   frame 18: (eval) (/usr/local/slimserver/scanner.pl line 172)
   frame 19: main::main (/usr/local/slimserver/scanner.pl line 353)

[17:22:15.9637] Slim::Schema::wipeDB (355) End schema_clear
[17:22:15.9647] Slim::Schema::wipeAllData (1471) Wiped the database.
[17:22:15.9726] Slim::Schema::Debug::query_start (26) SELECT me.id, me.type, me.name, me.active, me.total, me.done, me.start, me.finish, me.info FROM progress me WHERE ( type = ? ): 'importer'
[17:22:15.9772] Slim::Schema::Debug::query_start (26) DELETE FROM progress WHERE ( id = ? ): '9'
[17:22:15.9800] Slim::Music::Import::runImporter (489) Starting Slim::Music::MusicFolderScan scan
[17:22:15.9816] Slim::Music::MusicFolderScan::startScan (77) Starting music folder scan in /data/music
[17:22:15.9833] Slim::Utils::Scanner::scanDirectory (289) About to look for files in /data/music
[17:22:15.9889] Slim::Utils::Scanner::scanDirectory (290) For files with extensions in: (?i-xsm:\.(?:ape|wma|wv|wav|wave|mp3|mp2|flc|flac|fla|m4a|mov|m4b|mp4|mp+|mpc|ogg|aif|aiff|cue)$)
[17:22:15.9922] Slim::Schema::Debug::query_start (26) SELECT me.id, me.type, me.name, me.active, me.total, me.done, me.start, me.finish, me.info FROM progress me WHERE ( me.name = ? AND me.type = ? ): 'directory', 'importer'
[17:22:15.9964] Slim::Schema::Debug::query_start (26) INSERT INTO progress (name, type) VALUES (?, ?): 'directory', 'importer'
[17:22:16.0036] Slim::Schema::Debug::query_start (26) UPDATE progress SET active = ?, done = ?, start = ? WHERE ( id IS NULL ): '1', '0', '1220804535'
[17:22:22.4798] Slim::Utils::Scanner::scanDirectory (319) Found 6356 files in /data/music
[17:22:22.4822] Slim::Schema::Debug::query_start (26) UPDATE progress SET total = ? WHERE ( id IS NULL ): '6356'
[17:22:22.4848] Slim::Schema::throw_exception (236) Error: Can't update Slim::Schema::Progress=HASH(0xbb26b40): row not found
[17:22:22.4863] Slim::Schema::throw_exception (236) Backtrace:

   frame 0: Slim::Utils::Log::logBacktrace (/usr/local/slimserver/Slim/Schema.pm line 236)
   frame 1: Slim::Schema::throw_exception (/usr/local/slimserver/CPAN/DBIx/Class/Row.pm line 461)
   frame 2: DBIx::Class::Row::throw_exception (/usr/local/slimserver/CPAN/DBIx/Class/Row.pm line 117)
   frame 3: DBIx::Class::Row::update (/usr/local/slimserver/CPAN/DBIx/Class/Relationship/CascadeActions.pm line 30)
   frame 4: DBIx::Class::Relationship::CascadeActions::update (/usr/local/slimserver/CPAN/DBIx/Class/InflateColumn.pm line 222)
   frame 5: DBIx::Class::InflateColumn::update (/usr/local/slimserver/Slim/Schema/DBI.pm line 35)
   frame 6: Slim::Schema::DBI::update (/usr/local/slimserver/Slim/Utils/Progress.pm line 115)
   frame 7: Slim::Utils::Progress::total (/usr/local/slimserver/Slim/Utils/Scanner.pm line 324)
   frame 8: Slim::Utils::Scanner::scanDirectory (/usr/local/slimserver/Slim/Music/MusicFolderScan.pm line 79)
   frame 9: Slim::Music::MusicFolderScan::startScan (/usr/local/slimserver/Slim/Music/Import.pm line 491)
   frame 10: Slim::Music::Import::runImporter (/usr/local/slimserver/Slim/Music/Import.pm line 335)
   frame 11: Slim::Music::Import::runScan (/usr/local/slimserver/scanner.pl line 213)
   frame 12: (eval) (/usr/local/slimserver/scanner.pl line 207)
   frame 13: main::main (/usr/local/slimserver/scanner.pl line 353)

[17:22:22.4895] Slim::Schema::Debug::query_start (26) UPDATE progress SET done = ?, info = ? WHERE ( id IS NULL ): '1', '/data/music/Borrowed/Apollo 440/Dude Descending A Staircase (1,2)/01-Dude Descending A Staircase.flac'
[17:22:22.4921] Slim::Schema::throw_exception (236) Error: Can't update Slim::Schema::Progress=HASH(0xbb26b40): row not found
[17:22:22.4936] Slim::Schema::throw_exception (236) Backtrace:

   frame 0: Slim::Utils::Log::logBacktrace (/usr/local/slimserver/Slim/Schema.pm line 236)
   frame 1: Slim::Schema::throw_exception (/usr/local/slimserver/CPAN/DBIx/Class/Row.pm line 461)
   frame 2: DBIx::Class::Row::throw_exception (/usr/local/slimserver/CPAN/DBIx/Class/Row.pm line 117)
   frame 3: DBIx::Class::Row::update (/usr/local/slimserver/CPAN/DBIx/Class/Relationship/CascadeActions.pm line 30)
   frame 4: DBIx::Class::Relationship::CascadeActions::update (/usr/local/slimserver/CPAN/DBIx/Class/InflateColumn.pm line 222)
   frame 5: DBIx::Class::InflateColumn::update (/usr/local/slimserver/Slim/Schema/DBI.pm line 35)
   frame 6: Slim::Schema::DBI::update (/usr/local/slimserver/Slim/Utils/Progress.pm line 164)
   frame 7: Slim::Utils::Progress::update (/usr/local/slimserver/Slim/Utils/Scanner.pm line 331)
   frame 8: Slim::Utils::Scanner::scanDirectory (/usr/local/slimserver/Slim/Music/MusicFolderScan.pm line 79)
   frame 9: Slim::Music::MusicFolderScan::startScan (/usr/local/slimserver/Slim/Music/Import.pm line 491)
   frame 10: Slim::Music::Import::runImporter (/usr/local/slimserver/Slim/Music/Import.pm line 335)
   frame 11: Slim::Music::Import::runScan (/usr/local/slimserver/scanner.pl line 213)
   frame 12: (eval) (/usr/local/slimserver/scanner.pl line 207)
   frame 13: main::main (/usr/local/slimserver/scanner.pl line 353)

[17:22:22.5022] Slim::Utils::Scanner::scanDirectory (337) Adding file:///data/music/Borrowed/Apollo%20440/Dude%20Descending%20A%20Staircase%20(1,2)/01-Dude%20Descending%20A%20Staircase.flac to database.
[17:22:22.5033] Slim::Schema::newTrack (846) 
New Track: [file:///data/music/Borrowed/Apollo%20440/Dude%20Descending%20A%20Staircase%20(1,2)/01-Dude%20Descending%20A%20Staircase.flac]
[17:22:22.5040] Slim::Schema::newTrack (856) readTags is 1
[17:22:22.5676] Slim::Schema::Debug::query_start (26) INSERT INTO tracks (audio, audio_offset, audio_size, bitrate, channels, content_type, drm, filesize, lossless, remote, replay_gain, replay_peak, samplerate, secs, timestamp, title, titlesearch, titlesort, tracknum, url, vbr_scale, year) VALUES (?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?, ?): '1', '0', '36584647', '955667.308194723', '2', 'flc', '0', '36584647', '1', '0', '-9.15', '0.99893188', '44100', '306.213333333333', '1129654872', 'Dude Descending A Staircase', 'DUDE DESCENDING A STAIRCASE', 'DUDE DESCENDING A STAIRCASE', '1', 'file:///data/music/Borrowed/Apollo%20440/Dude%20Descending%20A%20Staircase%20(1,2)/01-Dude%20Descending%20A%20Staircase.flac', '1', '2003'
[17:22:22.5724] Slim::Schema::newTrack (939) Warning: Use of uninitialized value in sprintf at /usr/local/slimserver/Slim/Schema.pm line 940.
[17:22:22.5735] Slim::Schema::newTrack (939) Created track 'Dude Descending A Staircase' (id: [0])
[17:22:22.5766] Slim::Schema::Debug::query_start (26) SELECT me.id, me.url, me.musicbrainz_id, me.track, me.added, me.playcount, me.lastplayed, me.rating FROM tracks_persistent me WHERE ( url = ? ): 'file:///data/music/Borrowed/Apollo%20440/Dude%20Descending%20A%20Staircase%20(1,2)/01-Dude%20Descending%20A%20Staircase.flac'
[17:22:22.5850] Slim::Schema::Debug::query_start (26) SELECT me.id, me.name, me.namesort, me.namesearch, me.musicmagic_mixable FROM genres me WHERE ( ( me.namesearch = ? ) ): 'ELECTRONICA DANCE'
[17:22:22.5904] Slim::Schema::Debug::query_start (26) INSERT INTO genres (name, namesearch, namesort) VALUES (?, ?, ?): 'Electronica;Dance', 'ELECTRONICA DANCE', 'ELECTRONICA DANCE'
[17:22:22.5958] Slim::Schema::Debug::query_start (26) SELECT me.genre, me.track FROM genre_track me WHERE ( ( me.genre IS NULL AND me.track IS NULL ) OR ( me.genre IS NULL AND me.track IS NULL ) ): 
[17:22:22.6004] Slim::Schema::Debug::query_start (26) INSERT INTO genre_track (genre, track) VALUES (?, ?): 'NULL', 'NULL'
[17:22:22.6031] Slim::Schema::Storage::throw_exception (70) Error: Error executing 'INSERT INTO genre_track (genre, track) VALUES (?, ?)': DBD::mysql::st execute failed: Column 'genre' cannot be null at /usr/local/slimserver/CPAN/DBIx/Class/Storage/DBI.pm line 771.
[17:22:22.6045] Slim::Schema::Storage::throw_exception (70) Backtrace:

   frame 0: Slim::Utils::Log::logBacktrace (/usr/local/slimserver/Slim/Schema/Storage.pm line 70)
   frame 1: Slim::Schema::Storage::throw_exception (/usr/local/slimserver/CPAN/DBIx/Class/Storage/DBI.pm line 773)
   frame 2: DBIx::Class::Storage::DBI::_execute (/usr/local/slimserver/CPAN/DBIx/Class/Storage/DBI.pm line 788)
   frame 3: DBIx::Class::Storage::DBI::insert (/usr/local/slimserver/CPAN/DBIx/Class/Row.pm line 75)
   frame 4: DBIx::Class::Row::insert (/usr/local/slimserver/CPAN/DBIx/Class/ResultSet.pm line 1335)
   frame 5: DBIx::Class::ResultSet::create (/usr/local/slimserver/CPAN/DBIx/Class/ResultSet.pm line 1381)
   frame 6: DBIx::Class::ResultSet::find_or_create (/usr/local/slimserver/Slim/Schema/Genre.pm line 83)
   frame 7: Slim::Schema::Genre::add (/usr/local/slimserver/Slim/Schema.pm line 2104)
   frame 8: Slim::Schema::_postCheckAttributes (/usr/local/slimserver/Slim/Schema.pm line 985)
   frame 9: Slim::Schema::newTrack (/usr/local/slimserver/Slim/Utils/Scanner.pm line 339)
   frame 10: Slim::Utils::Scanner::scanDirectory (/usr/local/slimserver/Slim/Music/MusicFolderScan.pm line 79)
   frame 11: Slim::Music::MusicFolderScan::startScan (/usr/local/slimserver/Slim/Music/Import.pm line 491)
   frame 12: Slim::Music::Import::runImporter (/usr/local/slimserver/Slim/Music/Import.pm line 335)
   frame 13: Slim::Music::Import::runScan (/usr/local/slimserver/scanner.pl line 213)
   frame 14: (eval) (/usr/local/slimserver/scanner.pl line 207)
   frame 15: main::main (/usr/local/slimserver/scanner.pl line 353)

[17:22:22.6107] main::main (219) Error: Failed when running main scan: [Carp::Clan::__ANON__(): Error executing 'INSERT INTO genre_track (genre, track) VALUES (?, ?)': DBD::mysql::st execute failed: Column 'genre' cannot be null at /usr/local/slimserver/CPAN/DBIx/Class/Storage/DBI.pm line 771.
]
[17:22:22.6130] main::main (220) Error: Skipping post-process & Not updating lastRescanTime!
[17:22:22.6538] Slim::Schema::Debug::query_start (26) SELECT me.name, me.value FROM metainformation me WHERE ( ( me.name = ? ) ): 'isScanning'
[17:22:22.6580] Slim::Schema::Debug::query_start (26) UPDATE metainformation SET value = ? WHERE ( name = ? ): '0', 'isScanning'
[17:22:22.6678] Slim::Schema::forceCommit (1494) Syncing to the database.