Full Rescan gives OutOfMemoryError: Java heap space

Post your Server Bug Report
User avatar
Matt Zornig
Contributor
Contributor
Posts: 242
Joined: 14 Feb 2017, 12:03
Has thanked: 38 times
Been thanked: 28 times

Full Rescan gives OutOfMemoryError: Java heap space

Unread post by Matt Zornig »

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
HTTP ERROR: 503
HTTP ERROR: 503
ScreenClip.png (5.54 KiB) Viewed 12040 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 :-(
Post Reply