Full Rescan gives OutOfMemoryError: Java heap space

Post your Server Bug Report

Full Rescan gives OutOfMemoryError: Java heap space

Unread postby Matt Zornig » 17 May 2018, 01:36

This Error occured before shortly
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
=========================================================================================


Clicking on Settings -> Media Tools -> Full Rescan
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


I stopped/started the Madsonic Package, but it did not start again:
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
ScreenClip.png
HTTP ERROR: 503
ScreenClip.png (5.54 KiB) Viewed 180 times


HTTP ERROR: 503Problem accessing /. Reason:
Service Unavailable
Powered by Jetty:// 8.y.z-SNAPSHOT


This is the whole log after NAS restart:
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 have the latest Java installed, deinstalled it, reinstalled it (via Package Java8 by Synology)

I guess, I have to restart from scratch, by removing the Madsonic Database :-(
User avatar
Matt Zornig
Contributor
Contributor
 
Posts: 230
Joined: 14 Feb 2017, 12:03
Has thanked: 34 times
Been thanked: 19 times

Return to Bug Reports 6.x

Who is online

Users browsing this forum: No registered users and 1 guest

cron