Code: Select all
=========================================================================================
VERSION: MADSONIC 6.3 | BUILD: 9840.8df1ddd.20180107.0710
REST: MADSONIC REST API v3.0.0, MADSONIC REST API v2.8.0, SUBSONIC REST API v1.16.0
OS: Linux 3.10.102 #15266 SMP Mon Mar 26 15:08:28 CST 2018 x86_64 GNU/Linux
SERVER: jetty/8.y.z-SNAPSHOT, java 1.8.0_172, Linux 3.10.102 (amd64)
HOST: Synology NAS 1815+ (DSM 6.1.6-15266 Update 1) | CPU: INTEL Atom C2538| ARCH: X64 | MEMORY: 16GB | HDD: 36TB, RAID6
CLIENT/BROWSER: FF 53.x
JAVA: ORACLE JDK 1.8.0_172
DESCRIPTION: as in the topic's subject or body
REPRODUCIBLE: Yes
logged in as admin
=========================================================================================
starts the scan, but then it stops.
This is the log:
Code: Select all
[5/17/18 1:55 AM] INFO PlaylistService Starting playlist statistics update.
[5/17/18 1:55 AM] INFO PlaylistService Completed playlist import.
[5/17/18 1:55 AM] INFO PlaylistService Starting playlist import.
[5/17/18 1:55 AM] DEBUG MediaScannerService WATCHDOG:END:
[5/17/18 1:55 AM] ERROR MediaScannerService ERROR PreparedStatementCallback; SQL [update media_file set present=false, children_last_updated=? where id between ? and ? and last_scanned != ? and present]; java.lang.OutOfMemoryError: Java heap space; nested exception is java.sql.SQLException: java.lang.OutOfMemoryError: Java heap space
[5/17/18 1:55 AM] ERROR MediaScannerService Failed to scan media library.
[5/17/18 1:55 AM] DEBUG AbstractDao fn::doFullScanLibrary::markNonPresent: 3386 ms: update media_file set present=false, children_last_updated=? where id between ? and ? and last_scanned != ? and present
[5/17/18 1:55 AM] DEBUG AbstractDao fn::handleRequestInternal::markNonPresent: 3389 ms: update media_file set present=false, children_last_updated=? where id between ? and ? and last_scanned != ? and present
[5/17/18 1:55 AM] DEBUG AbstractDao fn::doFullScanLibrary::markNonPresent: 3191 ms: update media_file set present=false, children_last_updated=? where id between ? and ? and last_scanned != ? and present
[5/17/18 1:55 AM] DEBUG AbstractDao fn::handleRequestInternal::markNonPresent: 3186 ms: update media_file set present=false, children_last_updated=? where id between ? and ? and last_scanned != ? and present
[5/17/18 1:54 AM] DEBUG AbstractDao fn::doFullScanLibrary::markNonPresent: 6705 ms: update media_file set present=false, children_last_updated=? where id between ? and ? and last_scanned != ? and present
[5/17/18 1:54 AM] DEBUG AbstractDao fn::handleRequestInternal::markNonPresent: 6917 ms: update media_file set present=false, children_last_updated=? where id between ? and ? and last_scanned != ? and present
[5/17/18 1:53 AM] DEBUG AbstractDao fn::handleRequestInternal::markNonPresent: 17447 ms: update media_file set present=false, children_last_updated=? where id between ? and ? and last_scanned != ? and present
[5/17/18 1:53 AM] DEBUG AbstractDao fn::getUserSettings::queryOne: 11312 ms: select username, locale, theme_id, final_version_notification, beta_version_notification, main_caption_cutoff, main_popular, main_disc_number, main_track_number, main_artist, main_album, main_genre, main_mood, main_comment, main_year, main_bit_rate, main_duration, main_format, main_file_size, main_bpm, main_composer, main_custom, playlist_caption_cutoff, playlist_popular, playlist_disc_number, playlist_track_number, playlist_artist, playlist_album, playlist_genre, playlist_mood, playlist_comment, playlist_year, playlist_bit_rate, playlist_duration, playlist_format, playlist_file_size, playlist_bpm, playlist_composer, playlist_custom, button_rank, button_loved, button_starred, button_play, button_playadd, button_playmore, button_addcontext, button_addnext, button_addlast, button_download, button_youtube, button_lyric, last_fm_enabled, last_fm_username, last_fm_password, transcode_scheme, show_now_playing, selected_music_folder_id, selected_genre, party_mode_enabled, now_playing_allowed, avatar_scheme, system_avatar_id, changed, show_artist_info, show_chat, list_type, list_default, playqueue_resize, leftframe_resize, customscrollbar, customaccordion, view_as, song_notification, show_left_shrinked, show_left_bar, show_left_panel, show_right_panel, show_side_panel from user_settings where username=?
[5/17/18 1:53 AM] DEBUG AbstractDao fn::getUserSettings::queryOne: 7223 ms: select username, locale, theme_id, final_version_notification, beta_version_notification, main_caption_cutoff, main_popular, main_disc_number, main_track_number, main_artist, main_album, main_genre, main_mood, main_comment, main_year, main_bit_rate, main_duration, main_format, main_file_size, main_bpm, main_composer, main_custom, playlist_caption_cutoff, playlist_popular, playlist_disc_number, playlist_track_number, playlist_artist, playlist_album, playlist_genre, playlist_mood, playlist_comment, playlist_year, playlist_bit_rate, playlist_duration, playlist_format, playlist_file_size, playlist_bpm, playlist_composer, playlist_custom, button_rank, button_loved, button_starred, button_play, button_playadd, button_playmore, button_addcontext, button_addnext, button_addlast, button_download, button_youtube, button_lyric, last_fm_enabled, last_fm_username, last_fm_password, transcode_scheme, show_now_playing, selected_music_folder_id, selected_genre, party_mode_enabled, now_playing_allowed, avatar_scheme, system_avatar_id, changed, show_artist_info, show_chat, list_type, list_default, playqueue_resize, leftframe_resize, customscrollbar, customaccordion, view_as, song_notification, show_left_shrinked, show_left_bar, show_left_panel, show_right_panel, show_side_panel from user_settings where username=?
[5/17/18 1:53 AM] DEBUG AbstractDao fn::doFullScanLibrary::markNonPresent: 17874 ms: update media_file set present=false, children_last_updated=? where id between ? and ? and last_scanned != ? and present
[5/17/18 1:53 AM] DEBUG AbstractDao fn::doFullScanLibrary::markNonPresent: 3630 ms: update media_file set present=false, children_last_updated=? where id between ? and ? and last_scanned != ? and present
[5/17/18 1:53 AM] DEBUG AbstractDao fn::handleRequestInternal::markNonPresent: 3880 ms: update media_file set present=false, children_last_updated=? where id between ? and ? and last_scanned != ? and present
[5/17/18 1:53 AM] DEBUG AbstractDao fn::doFullScanLibrary::markNonPresent: 4014 ms: update media_file set present=false, children_last_updated=? where id between ? and ? and last_scanned != ? and present
[5/17/18 1:53 AM] DEBUG AbstractDao fn::handleRequestInternal::markNonPresent: 3767 ms: update media_file set present=false, children_last_updated=? where id between ? and ? and last_scanned != ? and present
[5/17/18 1:53 AM] DEBUG AbstractDao fn::doFullScanLibrary::markNonPresent: 3185 ms: update media_file set present=false, children_last_updated=? where id between ? and ? and last_scanned != ? and present
[5/17/18 1:53 AM] DEBUG AbstractDao fn::handleRequestInternal::markNonPresent: 3180 ms: update media_file set present=false, children_last_updated=? where id between ? and ? and last_scanned != ? and present
[5/17/18 1:52 AM] DEBUG AbstractDao fn::doFullScanLibrary::markNonPresent: 6298 ms: update media_file set present=false, children_last_updated=? where id between ? and ? and last_scanned != ? and present
[5/17/18 1:52 AM] DEBUG AbstractDao fn::handleRequestInternal::markNonPresent: 6058 ms: update media_file set present=false, children_last_updated=? where id between ? and ? and last_scanned != ? and present
[5/17/18 1:52 AM] DEBUG AbstractDao fn::doFullScanLibrary::markNonPresent: 4993 ms: update media_file set present=false, children_last_updated=? where id between ? and ? and last_scanned != ? and present
[5/17/18 1:52 AM] DEBUG AbstractDao fn::handleRequestInternal::markNonPresent: 4992 ms: update media_file set present=false, children_last_updated=? where id between ? and ? and last_scanned != ? and present
[5/17/18 1:52 AM] DEBUG AbstractDao fn::doFullScanLibrary::markNonPresent: 3087 ms: update media_file set present=false, children_last_updated=? where id between ? and ? and last_scanned != ? and present
[5/17/18 1:52 AM] DEBUG AbstractDao fn::handleRequestInternal::markNonPresent: 3149 ms: update media_file set present=false, children_last_updated=? where id between ? and ? and last_scanned != ? and present
[5/17/18 1:52 AM] DEBUG AbstractDao fn::doFullScanLibrary::markNonPresent: 4419 ms: update media_file set present=false, children_last_updated=? where id between ? and ? and last_scanned != ? and present
[5/17/18 1:52 AM] DEBUG AbstractDao fn::handleRequestInternal::markNonPresent: 4357 ms: update media_file set present=false, children_last_updated=? where id between ? and ? and last_scanned != ? and present
[5/17/18 1:51 AM] DEBUG AbstractDao fn::doFullScanLibrary::markNonPresent: 7938 ms: update media_file set present=false, children_last_updated=? where id between ? and ? and last_scanned != ? and present
[5/17/18 1:51 AM] DEBUG AbstractDao fn::handleRequestInternal::markNonPresent: 7938 ms: update media_file set present=false, children_last_updated=? where id between ? and ? and last_scanned != ? and present
[5/17/18 1:51 AM] DEBUG AbstractDao fn::handleRequestInternal::markNonPresent: 17667 ms: update media_file set present=false, children_last_updated=? where id between ? and ? and last_scanned != ? and present
[5/17/18 1:51 AM] DEBUG AbstractDao fn::doFullScanLibrary::markNonPresent: 17513 ms: update media_file set present=false, children_last_updated=? where id between ? and ? and last_scanned != ? and present
[5/17/18 1:50 AM] DEBUG AbstractDao fn::doFullScanLibrary::markNonPresent: 3967 ms: update media_file set present=false, children_last_updated=? where id between ? and ? and last_scanned != ? and present
[5/17/18 1:50 AM] DEBUG AbstractDao fn::handleRequestInternal::markNonPresent: 3967 ms: update media_file set present=false, children_last_updated=? where id between ? and ? and last_scanned != ? and present
[5/17/18 1:50 AM] DEBUG AbstractDao fn::doFullScanLibrary::markNonPresent: 3306 ms: update media_file set present=false, children_last_updated=? where id between ? and ? and last_scanned != ? and present
[5/17/18 1:50 AM] DEBUG AbstractDao fn::handleRequestInternal::markNonPresent: 3301 ms: update media_file set present=false, children_last_updated=? where id between ? and ? and last_scanned != ? and present
[5/17/18 1:49 AM] DEBUG AbstractDao fn::doFullScanLibrary::markNonPresent: 3056 ms: update media_file set present=false, children_last_updated=? where id between ? and ? and last_scanned != ? and present
[5/17/18 1:49 AM] DEBUG AbstractDao fn::handleRequestInternal::markNonPresent: 3057 ms: update media_file set present=false, children_last_updated=? where id between ? and ? and last_scanned != ? and present
[5/17/18 1:49 AM] DEBUG AbstractDao fn::doFullScanLibrary::markNonPresent: 3255 ms: update media_file set present=false, children_last_updated=? where id between ? and ? and last_scanned != ? and present
[5/17/18 1:49 AM] DEBUG AbstractDao fn::handleRequestInternal::markNonPresent: 3386 ms: update media_file set present=false, children_last_updated=? where id between ? and ? and last_scanned != ? and present
[5/17/18 1:48 AM] DEBUG AbstractDao fn::doFullScanLibrary::markNonPresent: 3238 ms: update media_file set present=false, children_last_updated=? where id between ? and ? and last_scanned != ? and present
[5/17/18 1:48 AM] DEBUG AbstractDao fn::handleRequestInternal::markNonPresent: 3240 ms: update media_file set present=false, children_last_updated=? where id between ? and ? and last_scanned != ? and present
[5/17/18 1:48 AM] DEBUG AbstractDao fn::doFullScanLibrary::markNonPresent: 9474 ms: update media_file set present=false, children_last_updated=? where id between ? and ? and last_scanned != ? and present
[5/17/18 1:48 AM] DEBUG AbstractDao fn::handleRequestInternal::markNonPresent: 9472 ms: update media_file set present=false, children_last_updated=? where id between ? and ? and last_scanned != ? and present
[5/17/18 1:48 AM] DEBUG AbstractDao fn::doFullScanLibrary::markNonPresent: 15714 ms: update media_file set present=false, children_last_updated=? where id between ? and ? and last_scanned != ? and present
[5/17/18 1:48 AM] DEBUG AbstractDao fn::handleRequestInternal::markNonPresent: 15713 ms: update media_file set present=false, children_last_updated=? where id between ? and ? and last_scanned != ? and present
[5/17/18 1:48 AM] DEBUG AbstractDao fn::getUserSettings::queryOne: 13074 ms: select username, locale, theme_id, final_version_notification, beta_version_notification, main_caption_cutoff, main_popular, main_disc_number, main_track_number, main_artist, main_album, main_genre, main_mood, main_comment, main_year, main_bit_rate, main_duration, main_format, main_file_size, main_bpm, main_composer, main_custom, playlist_caption_cutoff, playlist_popular, playlist_disc_number, playlist_track_number, playlist_artist, playlist_album, playlist_genre, playlist_mood, playlist_comment, playlist_year, playlist_bit_rate, playlist_duration, playlist_format, playlist_file_size, playlist_bpm, playlist_composer, playlist_custom, button_rank, button_loved, button_starred, button_play, button_playadd, button_playmore, button_addcontext, button_addnext, button_addlast, button_download, button_youtube, button_lyric, last_fm_enabled, last_fm_username, last_fm_password, transcode_scheme, show_now_playing, selected_music_folder_id, selected_genre, party_mode_enabled, now_playing_allowed, avatar_scheme, system_avatar_id, changed, show_artist_info, show_chat, list_type, list_default, playqueue_resize, leftframe_resize, customscrollbar, customaccordion, view_as, song_notification, show_left_shrinked, show_left_bar, show_left_panel, show_right_panel, show_side_panel from user_settings where username=?
[5/17/18 1:48 AM] DEBUG AbstractDao fn::getUserSettings::queryOne: 3069 ms: select username, locale, theme_id, final_version_notification, beta_version_notification, main_caption_cutoff, main_popular, main_disc_number, main_track_number, main_artist, main_album, main_genre, main_mood, main_comment, main_year, main_bit_rate, main_duration, main_format, main_file_size, main_bpm, main_composer, main_custom, playlist_caption_cutoff, playlist_popular, playlist_disc_number, playlist_track_number, playlist_artist, playlist_album, playlist_genre, playlist_mood, playlist_comment, playlist_year, playlist_bit_rate, playlist_duration, playlist_format, playlist_file_size, playlist_bpm, playlist_composer, playlist_custom, button_rank, button_loved, button_starred, button_play, button_playadd, button_playmore, button_addcontext, button_addnext, button_addlast, button_download, button_youtube, button_lyric, last_fm_enabled, last_fm_username, last_fm_password, transcode_scheme, show_now_playing, selected_music_folder_id, selected_genre, party_mode_enabled, now_playing_allowed, avatar_scheme, system_avatar_id, changed, show_artist_info, show_chat, list_type, list_default, playqueue_resize, leftframe_resize, customscrollbar, customaccordion, view_as, song_notification, show_left_shrinked, show_left_bar, show_left_panel, show_right_panel, show_side_panel from user_settings where username=?
[5/17/18 1:48 AM] DEBUG AbstractDao fn::getUserSettings::queryOne: 3158 ms: select username, locale, theme_id, final_version_notification, beta_version_notification, main_caption_cutoff, main_popular, main_disc_number, main_track_number, main_artist, main_album, main_genre, main_mood, main_comment, main_year, main_bit_rate, main_duration, main_format, main_file_size, main_bpm, main_composer, main_custom, playlist_caption_cutoff, playlist_popular, playlist_disc_number, playlist_track_number, playlist_artist, playlist_album, playlist_genre, playlist_mood, playlist_comment, playlist_year, playlist_bit_rate, playlist_duration, playlist_format, playlist_file_size, playlist_bpm, playlist_composer, playlist_custom, button_rank, button_loved, button_starred, button_play, button_playadd, button_playmore, button_addcontext, button_addnext, button_addlast, button_download, button_youtube, button_lyric, last_fm_enabled, last_fm_username, last_fm_password, transcode_scheme, show_now_playing, selected_music_folder_id, selected_genre, party_mode_enabled, now_playing_allowed, avatar_scheme, system_avatar_id, changed, show_artist_info, show_chat, list_type, list_default, playqueue_resize, leftframe_resize, customscrollbar, customaccordion, view_as, song_notification, show_left_shrinked, show_left_bar, show_left_panel, show_right_panel, show_side_panel from user_settings where username=?
[5/17/18 1:48 AM] DEBUG AbstractDao fn::doFullScanLibrary::markNonPresent: 3100 ms: update media_file set present=false, children_last_updated=? where id between ? and ? and last_scanned != ? and present
[5/17/18 1:47 AM] DEBUG AbstractDao fn::doFullScanLibrary::markNonPresent: 3600 ms: update media_file set present=false, children_last_updated=? where id between ? and ? and last_scanned != ? and present
[5/17/18 1:47 AM] DEBUG AbstractDao fn::handleRequestInternal::markNonPresent: 3326 ms: update media_file set present=false, children_last_updated=? where id between ? and ? and last_scanned != ? and present
[5/17/18 1:47 AM] DEBUG AbstractDao fn::doFullScanLibrary::markNonPresent: 8059 ms: update media_file set present=false, children_last_updated=? where id between ? and ? and last_scanned != ? and present
[5/17/18 1:47 AM] DEBUG AbstractDao fn::handleRequestInternal::markNonPresent: 8084 ms: update media_file set present=false, children_last_updated=? where id between ? and ? and last_scanned != ? and present
[5/17/18 1:47 AM] DEBUG AbstractDao fn::doFullScanLibrary::markNonPresent: 3218 ms: update media_file set present=false, children_last_updated=? where id between ? and ? and last_scanned != ? and present
[5/17/18 1:47 AM] DEBUG AbstractDao fn::handleRequestInternal::markNonPresent: 3191 ms: update media_file set present=false, children_last_updated=? where id between ? and ? and last_scanned != ? and present
[5/17/18 1:46 AM] DEBUG AbstractDao fn::doFullScanLibrary::markNonPresent: 3073 ms: update media_file set present=false, children_last_updated=? where id between ? and ? and last_scanned != ? and present
[5/17/18 1:46 AM] DEBUG AbstractDao fn::handleRequestInternal::markNonPresent: 3076 ms: update media_file set present=false, children_last_updated=? where id between ? and ? and last_scanned != ? and present
[5/17/18 1:46 AM] DEBUG AbstractDao fn::doFullScanLibrary::markNonPresent: 4196 ms: update media_file set present=false, children_last_updated=? where id between ? and ? and last_scanned != ? and present
[5/17/18 1:46 AM] DEBUG AbstractDao fn::handleRequestInternal::markNonPresent: 4197 ms: update media_file set present=false, children_last_updated=? where id between ? and ? and last_scanned != ? and present
[5/17/18 1:46 AM] DEBUG AbstractDao fn::doFullScanLibrary::markNonPresent: 14387 ms: update media_file set present=false, children_last_updated=? where id between ? and ? and last_scanned != ? and present
[5/17/18 1:46 AM] DEBUG AbstractDao fn::handleRequestInternal::markNonPresent: 14384 ms: update media_file set present=false, children_last_updated=? where id between ? and ? and last_scanned != ? and present
[5/17/18 1:45 AM] DEBUG AbstractDao fn::doFullScanLibrary::markNonPresent: 17074 ms: update media_file set present=false, children_last_updated=? where id between ? and ? and last_scanned != ? and present
[5/17/18 1:45 AM] DEBUG AbstractDao fn::handleRequestInternal::markNonPresent: 17075 ms: update media_file set present=false, children_last_updated=? where id between ? and ? and last_scanned != ? and present
[5/17/18 1:45 AM] DEBUG AbstractDao fn::getUserSettings::queryOne: 13611 ms: select username, locale, theme_id, final_version_notification, beta_version_notification, main_caption_cutoff, main_popular, main_disc_number, main_track_number, main_artist, main_album, main_genre, main_mood, main_comment, main_year, main_bit_rate, main_duration, main_format, main_file_size, main_bpm, main_composer, main_custom, playlist_caption_cutoff, playlist_popular, playlist_disc_number, playlist_track_number, playlist_artist, playlist_album, playlist_genre, playlist_mood, playlist_comment, playlist_year, playlist_bit_rate, playlist_duration, playlist_format, playlist_file_size, playlist_bpm, playlist_composer, playlist_custom, button_rank, button_loved, button_starred, button_play, button_playadd, button_playmore, button_addcontext, button_addnext, button_addlast, button_download, button_youtube, button_lyric, last_fm_enabled, last_fm_username, last_fm_password, transcode_scheme, show_now_playing, selected_music_folder_id, selected_genre, party_mode_enabled, now_playing_allowed, avatar_scheme, system_avatar_id, changed, show_artist_info, show_chat, list_type, list_default, playqueue_resize, leftframe_resize, customscrollbar, customaccordion, view_as, song_notification, show_left_shrinked, show_left_bar, show_left_panel, show_right_panel, show_side_panel from user_settings where username=?
[5/17/18 1:45 AM] DEBUG AbstractDao fn::getUserSettings::queryOne: 3570 ms: select username, locale, theme_id, final_version_notification, beta_version_notification, main_caption_cutoff, main_popular, main_disc_number, main_track_number, main_artist, main_album, main_genre, main_mood, main_comment, main_year, main_bit_rate, main_duration, main_format, main_file_size, main_bpm, main_composer, main_custom, playlist_caption_cutoff, playlist_popular, playlist_disc_number, playlist_track_number, playlist_artist, playlist_album, playlist_genre, playlist_mood, playlist_comment, playlist_year, playlist_bit_rate, playlist_duration, playlist_format, playlist_file_size, playlist_bpm, playlist_composer, playlist_custom, button_rank, button_loved, button_starred, button_play, button_playadd, button_playmore, button_addcontext, button_addnext, button_addlast, button_download, button_youtube, button_lyric, last_fm_enabled, last_fm_username, last_fm_password, transcode_scheme, show_now_playing, selected_music_folder_id, selected_genre, party_mode_enabled, now_playing_allowed, avatar_scheme, system_avatar_id, changed, show_artist_info, show_chat, list_type, list_default, playqueue_resize, leftframe_resize, customscrollbar, customaccordion, view_as, song_notification, show_left_shrinked, show_left_bar, show_left_panel, show_right_panel, show_side_panel from user_settings where username=?
[5/17/18 1:45 AM] DEBUG AbstractDao fn::getUserSettings::queryOne: 13640 ms: select username, locale, theme_id, final_version_notification, beta_version_notification, main_caption_cutoff, main_popular, main_disc_number, main_track_number, main_artist, main_album, main_genre, main_mood, main_comment, main_year, main_bit_rate, main_duration, main_format, main_file_size, main_bpm, main_composer, main_custom, playlist_caption_cutoff, playlist_popular, playlist_disc_number, playlist_track_number, playlist_artist, playlist_album, playlist_genre, playlist_mood, playlist_comment, playlist_year, playlist_bit_rate, playlist_duration, playlist_format, playlist_file_size, playlist_bpm, playlist_composer, playlist_custom, button_rank, button_loved, button_starred, button_play, button_playadd, button_playmore, button_addcontext, button_addnext, button_addlast, button_download, button_youtube, button_lyric, last_fm_enabled, last_fm_username, last_fm_password, transcode_scheme, show_now_playing, selected_music_folder_id, selected_genre, party_mode_enabled, now_playing_allowed, avatar_scheme, system_avatar_id, changed, show_artist_info, show_chat, list_type, list_default, playqueue_resize, leftframe_resize, customscrollbar, customaccordion, view_as, song_notification, show_left_shrinked, show_left_bar, show_left_panel, show_right_panel, show_side_panel from user_settings where username=?
[5/17/18 1:44 AM] DEBUG AbstractDao fn::doFullScanLibrary::markNonPresent: 3562 ms: update media_file set present=false, children_last_updated=? where id between ? and ? and last_scanned != ? and present
[5/17/18 1:44 AM] DEBUG AbstractDao fn::handleRequestInternal::markNonPresent: 3564 ms: update media_file set present=false, children_last_updated=? where id between ? and ? and last_scanned != ? and present
[5/17/18 1:44 AM] DEBUG AbstractDao fn::doFullScanLibrary::markNonPresent: 6588 ms: update media_file set present=false, children_last_updated=? where id between ? and ? and last_scanned != ? and present
[5/17/18 1:44 AM] DEBUG AbstractDao fn::handleRequestInternal::markNonPresent: 6582 ms: update media_file set present=false, children_last_updated=? where id between ? and ? and last_scanned != ? and present
[5/17/18 1:43 AM] DEBUG AbstractDao fn::doFullScanLibrary::markNonPresent: 4402 ms: update media_file set present=false, children_last_updated=? where id between ? and ? and last_scanned != ? and present
[5/17/18 1:43 AM] DEBUG AbstractDao fn::handleRequestInternal::markNonPresent: 4386 ms: update media_file set present=false, children_last_updated=? where id between ? and ? and last_scanned != ? and present
[5/17/18 1:43 AM] DEBUG AbstractDao fn::handleRequestInternal::markNonPresent: 3703 ms: update media_file set present=false, children_last_updated=? where id between ? and ? and last_scanned != ? and present
[5/17/18 1:43 AM] INFO MediaScannerService Marking non-present files.
[5/17/18 1:43 AM] INFO MediaScannerService Scanned media library with 0 entries.
[5/17/18 1:43 AM] INFO MediaScannerService WATCHDOG:SCAN: doFullScanLibrary() started
[5/17/18 1:43 AM] DEBUG AbstractDao fn::doFullScanLibrary::reset: 84097 ms: update artist set album_count=0, song_count=0, play_count=0
[5/17/18 1:43 AM] DEBUG AbstractDao fn::handleRequestInternal::markNonPresent: 6386 ms: update media_file set present=false, children_last_updated=? where id between ? and ? and last_scanned != ? and present
[5/17/18 1:43 AM] DEBUG AbstractDao fn::handleRequestInternal::markNonPresent: 22007 ms: update media_file set present=false, children_last_updated=? where id between ? and ? and last_scanned != ? and present
[5/17/18 1:43 AM] DEBUG AbstractDao fn::handleRequestInternal::markNonPresent: 59257 ms: update media_file set present=false, children_last_updated=? where id between ? and ? and last_scanned != ? and present
[5/17/18 1:42 AM] DEBUG AbstractDao fn::doFullScanLibrary::expunge: 14544 ms: select top 1 id from artist where not present
[5/17/18 1:42 AM] DEBUG AbstractDao fn::handleRequestInternal::markNonPresent: 3427 ms: select max(id) from media_file where last_scanned != ? and present
[5/17/18 1:42 AM] DEBUG AbstractDao fn::handleRequestInternal::markNonPresent: 5409 ms: select top 1 id from media_file where last_scanned != ? and present
[5/17/18 1:41 AM] INFO MusicFolderDao Disable music folders
[5/17/18 1:41 AM] INFO PlaylistService Backup: 'Various Artists - admin - May 10, 2018 3:37 AM' successfully, entry size: 10
new log after restart:
https://pastebin.com/W2qHFjyH
So I waited a couple of hours, did a NAS restart (so nuke maybe Java-only problems) but now, I cannot log into Madsonic anymore.
The Logon Page shows an
This is the whole log after NAS restart:
HTTP ERROR: 503Problem accessing /. Reason:
Service Unavailable
Powered by Jetty:// 8.y.z-SNAPSHOT
Code: Select all
[2018-05-17 10:57:17,802] WARN HsqlDaoHelper - Checking HSQLDB database schema ...
[2018-05-17 10:57:29,227] INFO HSqlSchema01 - Database table 'version' not found. Creating it.
[2018-05-17 10:57:29,986] ERROR HsqlDaoHelper - Failed to initialize HSQLDB database.
org.springframework.jdbc.CannotGetJdbcConnectionException: Could not get JDBC Connection; nested exception is java.sql.SQLException: java.lang.NullPointerException
at org.springframework.jdbc.datasource.DataSourceUtils.getConnection(DataSourceUtils.java:80)
at org.springframework.jdbc.core.JdbcTemplate.execute(JdbcTemplate.java:382)
at org.springframework.jdbc.core.JdbcTemplate.execute(JdbcTemplate.java:428)
at org.madsonic.dao.schema.hsql.HSqlSchema01.execute(HSqlSchema01.java:76)
at org.madsonic.dao.HsqlDaoHelper.checkDatabase(HsqlDaoHelper.java:120)
at org.madsonic.dao.HsqlDaoHelper.<init>(HsqlDaoHelper.java:57)
at org.madsonic.dao.DaoHelperFactory.create(DaoHelperFactory.java:41)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at org.springframework.beans.factory.support.SimpleInstantiationStrategy.instantiate(SimpleInstantiationStrategy.java:160)
at org.springframework.beans.factory.support.ConstructorResolver.instantiateUsingFactoryMethod(ConstructorResolver.java:592)
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.instantiateUsingFactoryMethod(AbstractAutowireCapableBeanFactory.java:1057)
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBeanInstance(AbstractAutowireCapableBeanFactory.java:953)
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.doCreateBean(AbstractAutowireCapableBeanFactory.java:485)
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBean(AbstractAutowireCapableBeanFactory.java:456)
at org.springframework.beans.factory.support.AbstractBeanFactory$1.getObject(AbstractBeanFactory.java:293)
at org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.getSingleton(DefaultSingletonBeanRegistry.java:223)
at org.springframework.beans.factory.support.AbstractBeanFactory.doGetBean(AbstractBeanFactory.java:290)
at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:191)
at org.springframework.beans.factory.support.BeanDefinitionValueResolver.resolveReference(BeanDefinitionValueResolver.java:328)
at org.springframework.beans.factory.support.BeanDefinitionValueResolver.resolveValueIfNecessary(BeanDefinitionValueResolver.java:108)
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.applyPropertyValues(AbstractAutowireCapableBeanFactory.java:1419)
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.populateBean(AbstractAutowireCapableBeanFactory.java:1160)
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.doCreateBean(AbstractAutowireCapableBeanFactory.java:517)
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBean(AbstractAutowireCapableBeanFactory.java:456)
at org.springframework.beans.factory.support.AbstractBeanFactory$1.getObject(AbstractBeanFactory.java:293)
at org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.getSingleton(DefaultSingletonBeanRegistry.java:223)
at org.springframework.beans.factory.support.AbstractBeanFactory.doGetBean(AbstractBeanFactory.java:290)
at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:191)
at org.springframework.beans.factory.support.BeanDefinitionValueResolver.resolveReference(BeanDefinitionValueResolver.java:328)
at org.springframework.beans.factory.support.BeanDefinitionValueResolver.resolveValueIfNecessary(BeanDefinitionValueResolver.java:108)
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.applyPropertyValues(AbstractAutowireCapableBeanFactory.java:1419)
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.populateBean(AbstractAutowireCapableBeanFactory.java:1160)
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.doCreateBean(AbstractAutowireCapableBeanFactory.java:517)
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBean(AbstractAutowireCapableBeanFactory.java:456)
at org.springframework.beans.factory.support.AbstractBeanFactory$1.getObject(AbstractBeanFactory.java:293)
at org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.getSingleton(DefaultSingletonBeanRegistry.java:223)
at org.springframework.beans.factory.support.AbstractBeanFactory.doGetBean(AbstractBeanFactory.java:290)
at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:191)
at org.springframework.beans.factory.support.DefaultListableBeanFactory.findAutowireCandidates(DefaultListableBeanFactory.java:923)
at org.springframework.beans.factory.support.DefaultListableBeanFactory.doResolveDependency(DefaultListableBeanFactory.java:866)
at org.springframework.beans.factory.support.DefaultListableBeanFactory.resolveDependency(DefaultListableBeanFactory.java:781)
at org.springframework.beans.factory.annotation.AutowiredAnnotationBeanPostProcessor$AutowiredFieldElement.inject(AutowiredAnnotationBeanPostProcessor.java:503)
at org.springframework.beans.factory.annotation.InjectionMetadata.inject(InjectionMetadata.java:88)
at org.springframework.beans.factory.annotation.AutowiredAnnotationBeanPostProcessor.postProcessPropertyValues(AutowiredAnnotationBeanPostProcessor.java:295)
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.populateBean(AbstractAutowireCapableBeanFactory.java:1148)
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.doCreateBean(AbstractAutowireCapableBeanFactory.java:517)
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBean(AbstractAutowireCapableBeanFactory.java:456)
at org.springframework.beans.factory.support.AbstractBeanFactory$1.getObject(AbstractBeanFactory.java:293)
at org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.getSingleton(DefaultSingletonBeanRegistry.java:223)
at org.springframework.beans.factory.support.AbstractBeanFactory.doGetBean(AbstractBeanFactory.java:290)
at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:191)
at org.springframework.beans.factory.support.DefaultListableBeanFactory.findAutowireCandidates(DefaultListableBeanFactory.java:923)
at org.springframework.beans.factory.support.DefaultListableBeanFactory.doResolveDependency(DefaultListableBeanFactory.java:866)
at org.springframework.beans.factory.support.DefaultListableBeanFactory.resolveDependency(DefaultListableBeanFactory.java:781)
at org.springframework.beans.factory.annotation.AutowiredAnnotationBeanPostProcessor$AutowiredFieldElement.inject(AutowiredAnnotationBeanPostProcessor.java:503)
at org.springframework.beans.factory.annotation.InjectionMetadata.inject(InjectionMetadata.java:88)
at org.springframework.beans.factory.annotation.AutowiredAnnotationBeanPostProcessor.postProcessPropertyValues(AutowiredAnnotationBeanPostProcessor.java:295)
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.populateBean(AbstractAutowireCapableBeanFactory.java:1148)
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.doCreateBean(AbstractAutowireCapableBeanFactory.java:517)
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBean(AbstractAutowireCapableBeanFactory.java:456)
at org.springframework.beans.factory.support.AbstractBeanFactory$1.getObject(AbstractBeanFactory.java:293)
at org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.getSingleton(DefaultSingletonBeanRegistry.java:223)
at org.springframework.beans.factory.support.AbstractBeanFactory.doGetBean(AbstractBeanFactory.java:290)
at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:191)
at org.springframework.beans.factory.support.DefaultListableBeanFactory.preInstantiateSingletons(DefaultListableBeanFactory.java:638)
at org.springframework.context.support.AbstractApplicationContext.finishBeanFactoryInitialization(AbstractApplicationContext.java:942)
at org.springframework.context.support.AbstractApplicationContext.refresh(AbstractApplicationContext.java:482)
at org.springframework.web.context.ContextLoader.configureAndRefreshWebApplicationContext(ContextLoader.java:410)
at org.springframework.web.context.ContextLoader.initWebApplicationContext(ContextLoader.java:306)
at org.springframework.web.context.ContextLoaderListener.contextInitialized(ContextLoaderListener.java:112)
at org.eclipse.jetty.server.handler.ContextHandler.callContextInitialized(ContextHandler.java:782)
at org.eclipse.jetty.servlet.ServletContextHandler.callContextInitialized(ServletContextHandler.java:424)
at org.eclipse.jetty.server.handler.ContextHandler.startContext(ContextHandler.java:774)
at org.eclipse.jetty.servlet.ServletContextHandler.startContext(ServletContextHandler.java:249)
at org.eclipse.jetty.webapp.WebAppContext.startContext(WebAppContext.java:1242)
at org.eclipse.jetty.server.handler.ContextHandler.doStart(ContextHandler.java:717)
at org.eclipse.jetty.webapp.WebAppContext.doStart(WebAppContext.java:494)
at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:64)
at org.eclipse.jetty.server.handler.HandlerWrapper.doStart(HandlerWrapper.java:95)
at org.eclipse.jetty.server.Server.doStart(Server.java:282)
at org.eclipse.jetty.util.component.AbstractLifeCycle.start(AbstractLifeCycle.java:64)
at org.madsonic.booter.deployer.MadsonicDeployer.deployWebApp(MadsonicDeployer.java:247)
at org.madsonic.booter.deployer.MadsonicDeployer.<init>(MadsonicDeployer.java:102)
at sun.reflect.NativeConstructorAccessorImpl.newInstance0(Native Method)
at sun.reflect.NativeConstructorAccessorImpl.newInstance(NativeConstructorAccessorImpl.java:62)
at sun.reflect.DelegatingConstructorAccessorImpl.newInstance(DelegatingConstructorAccessorImpl.java:45)
at java.lang.reflect.Constructor.newInstance(Constructor.java:423)
at org.springframework.beans.BeanUtils.instantiateClass(BeanUtils.java:148)
at org.springframework.beans.factory.support.SimpleInstantiationStrategy.instantiate(SimpleInstantiationStrategy.java:87)
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.instantiateBean(AbstractAutowireCapableBeanFactory.java:1032)
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBeanInstance(AbstractAutowireCapableBeanFactory.java:985)
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.doCreateBean(AbstractAutowireCapableBeanFactory.java:485)
at org.springframework.beans.factory.support.AbstractAutowireCapableBeanFactory.createBean(AbstractAutowireCapableBeanFactory.java:456)
at org.springframework.beans.factory.support.AbstractBeanFactory$1.getObject(AbstractBeanFactory.java:293)
at org.springframework.beans.factory.support.DefaultSingletonBeanRegistry.getSingleton(DefaultSingletonBeanRegistry.java:223)
at org.springframework.beans.factory.support.AbstractBeanFactory.doGetBean(AbstractBeanFactory.java:290)
at org.springframework.beans.factory.support.AbstractBeanFactory.getBean(AbstractBeanFactory.java:191)
at org.springframework.beans.factory.support.DefaultListableBeanFactory.preInstantiateSingletons(DefaultListableBeanFactory.java:638)
at org.springframework.context.support.AbstractApplicationContext.finishBeanFactoryInitialization(AbstractApplicationContext.java:942)
at org.springframework.context.support.AbstractApplicationContext.refresh(AbstractApplicationContext.java:482)
at org.springframework.context.support.ClassPathXmlApplicationContext.<init>(ClassPathXmlApplicationContext.java:139)
at org.springframework.context.support.ClassPathXmlApplicationContext.<init>(ClassPathXmlApplicationContext.java:83)
at org.madsonic.booter.Main.<init>(Main.java:46)
at org.madsonic.booter.Main.main(Main.java:85)
Caused by: java.sql.SQLException: java.lang.NullPointerException
at org.hsqldb.jdbc.JDBCUtil.sqlException(Unknown Source)
at org.hsqldb.jdbc.JDBCUtil.sqlException(Unknown Source)
at org.hsqldb.jdbc.JDBCConnection.<init>(Unknown Source)
at org.hsqldb.jdbc.JDBCDriver.getConnection(Unknown Source)
at org.hsqldb.jdbc.JDBCDriver.connect(Unknown Source)
at java.sql.DriverManager.getConnection(DriverManager.java:664)
at java.sql.DriverManager.getConnection(DriverManager.java:208)
at org.springframework.jdbc.datasource.DriverManagerDataSource.getConnectionFromDriverManager(DriverManagerDataSource.java:173)
at org.springframework.jdbc.datasource.DriverManagerDataSource.getConnectionFromDriver(DriverManagerDataSource.java:164)
at org.springframework.jdbc.datasource.AbstractDriverBasedDataSource.getConnectionFromDriver(AbstractDriverBasedDataSource.java:153)
at org.springframework.jdbc.datasource.AbstractDriverBasedDataSource.getConnection(AbstractDriverBasedDataSource.java:119)
at org.springframework.jdbc.datasource.DataSourceUtils.doGetConnection(DataSourceUtils.java:111)
at org.springframework.jdbc.datasource.DataSourceUtils.getConnection(DataSourceUtils.java:77)
... 106 more
Caused by: org.hsqldb.HsqlException: java.lang.NullPointerException
at org.hsqldb.error.Error.error(Unknown Source)
at org.hsqldb.persist.RowStoreAVL.indexRow(Unknown Source)
at org.hsqldb.persist.RowStoreAVLDisk.indexRow(Unknown Source)
at org.hsqldb.persist.RowStoreAVLDisk.rollbackRow(Unknown Source)
at org.hsqldb.TransactionManager2PL.rollbackPartial(Unknown Source)
at org.hsqldb.TransactionManager2PL.rollback(Unknown Source)
at org.hsqldb.Session.rollbackNoCheck(Unknown Source)
at org.hsqldb.Session.rollback(Unknown Source)
at org.hsqldb.Session.close(Unknown Source)
at org.hsqldb.SessionManager.closeAllSessions(Unknown Source)
at org.hsqldb.persist.ScriptRunner.runScript(Unknown Source)
at org.hsqldb.persist.ScriptRunner.runScript(Unknown Source)
at org.hsqldb.persist.Log.processLog(Unknown Source)
at org.hsqldb.persist.Log.open(Unknown Source)
at org.hsqldb.persist.Logger.open(Unknown Source)
at org.hsqldb.Database.reopen(Unknown Source)
at org.hsqldb.Database.open(Unknown Source)
at org.hsqldb.DatabaseManager.getDatabase(Unknown Source)
at org.hsqldb.DatabaseManager.newSession(Unknown Source)
... 117 more
Caused by: java.lang.NullPointerException
at org.hsqldb.index.IndexAVL.balance(Unknown Source)
at org.hsqldb.index.IndexAVL.insert(Unknown Source)
... 135 more
I guess, I have to restart from scratch, by removing the Madsonic Database :-(