Page 1 of 1

recently added not up-to-date after autoscan

Posted: 11 Mar 2017, 00:06
by Matt Zornig
no help

Code: Select all

=========================================================================================
VERSION: MADSONIC 6.2 | BUILD: 9080.619dd31.20161222.0327
REST: MADSONIC REST API v2.5.0, SUBSONIC REST API v1.14.0
OS: Linux 3.10.77 #8451 SMP Wed Jan 4 00:31:32 CST 2017 x86_64 GNU/Linux
SERVER: jetty/8.y.z-SNAPSHOT, java 1.8.0_121, Linux 3.10.77 (amd64)
HOST: Synology NAS 1815+ (DSM 6.0.2-8451 Update 9) | CPU: INTEL Atom C2538| ARCH: X64 | MEMORY: 6GB | HDD: 36TB, RAID6
BROWSER: FF 51.x
CLIENT: see browser
JAVA: ORACLE JDK 1.8.121
REPRODUCIBLE: yes.
logged in as admin
=========================================================================================
I have turned off the
[ ] Fast access mode

after setting up the mediafolders scan intervals before a week, i wanted to test, if the interval and the rescanning of media files is working properly.
it is not.
madsonic seems to scan the media folders, but no new media is added to the recently added page, or history or new100 or anywhere else, including the search results.
We have added plenty new media during the last week, so it should show something new on the recently-added pages, but it shows the same old stuff since a week.

(Madsonic does NOT show any new media added the last week, BUT shows newly added albums, when using the search, and opening the artists page of artists which were in the database before adding new media for those artists. After that, those albums only are shown on the recently-added-list) (this behaviour of madsonic is confusing and not userfriendly, but its a workaround to play newly added media)

This are my settings for the media folders:
media folders before running the automated rescan
media folders before running the automated rescan
This was shown, during the automated scan:
media foldes during the automated scan
media foldes during the automated scan
This was shown after the scan had finished.
media folders now being updated/rescanned
media folders now being updated/rescanned
This was shown in the log:

Code: Select all

[2017-03-10 22:48:23,017] INFO MediaScannerService - Scanned media library with 4555500 entries.
[2017-03-10 22:48:30,006] INFO MediaScannerService - Scanned media library with 4556000 entries.
[2017-03-10 22:48:39,692] INFO MediaScannerService - Scanned media library with 4556500 entries.
[2017-03-10 22:48:43,467] INFO MediaScannerService - Scanned media library with 4557000 entries.
[2017-03-10 22:48:49,371] INFO MediaScannerService - Scanned media library with 4557500 entries.
[2017-03-10 22:48:57,652] INFO MediaScannerService - Scanned media library with 4558000 entries.
[2017-03-10 22:49:01,511] INFO MediaScannerService - Scanned media library with 4558500 entries.
[2017-03-10 22:49:08,798] INFO MediaScannerService - Scanned media library with 4559000 entries.
[2017-03-10 22:49:13,082] INFO MediaScannerService - Scanned media library with 4559500 entries.
[2017-03-10 22:49:16,575] INFO MediaScannerService - Scanned media library with 4560000 entries.
[2017-03-10 22:49:19,081] INFO MediaScannerService - Scanned media library with 4560500 entries.
[2017-03-10 22:49:19,945] INFO MediaScannerService - Scanned media library with 4561000 entries.
[2017-03-10 22:49:20,003] INFO MediaScannerService - Scanned media library with 4561500 entries.
[2017-03-10 22:49:20,053] INFO MediaScannerService - Scanned media library with 4562000 entries.
[2017-03-10 22:49:20,101] INFO MediaScannerService - Scanned media library with 4562500 entries.
[2017-03-10 22:49:20,170] INFO MediaScannerService - Scanned media library with 4563000 entries.
[2017-03-10 22:49:20,216] INFO MediaScannerService - Scanned media library with 4563500 entries.
[2017-03-10 22:49:20,281] INFO MediaScannerService - Scanned media library with 4564000 entries.
[2017-03-10 22:49:23,802] INFO MediaScannerService - Scanned media library with 4564500 entries.
[2017-03-10 22:49:24,128] DEBUG MediaScannerService - WATCHDOG:RUN: doUpdateTasks()
[2017-03-10 22:49:24,128] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [Klangschale0]
[2017-03-10 22:49:24,128] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleA]
[2017-03-10 22:49:24,128] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleB]
[2017-03-10 22:49:24,128] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleC]
[2017-03-10 22:49:24,128] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleD]
[2017-03-10 22:49:24,128] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleE]
[2017-03-10 22:49:24,128] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleF]
[2017-03-10 22:49:24,128] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleG]
[2017-03-10 22:49:24,128] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleH]
[2017-03-10 22:49:24,128] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleI]
[2017-03-10 22:49:24,129] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleJ]
[2017-03-10 22:49:24,129] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleK]
[2017-03-10 22:49:24,129] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleL]
[2017-03-10 22:49:24,129] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleM]
[2017-03-10 22:49:24,129] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleN]
[2017-03-10 22:49:24,129] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleO]
[2017-03-10 22:49:24,129] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleP]
[2017-03-10 22:49:24,129] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleQ]
[2017-03-10 22:49:24,129] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleR]
[2017-03-10 22:49:24,129] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleS]
[2017-03-10 22:49:24,129] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleT]
[2017-03-10 22:49:24,129] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleU]
[2017-03-10 22:49:24,129] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleV]
[2017-03-10 22:49:24,129] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleW]
[2017-03-10 22:49:24,129] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleX]
[2017-03-10 22:49:24,129] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleY]
[2017-03-10 22:49:24,129] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleZ]
[2017-03-10 22:49:24,130] DEBUG MediaScannerService - WATCHDOG:TIMER: UPDATED [testing]
So everything seems ok, but it isnt.

Is there anything I can do to have madsonic scan the media folders as expected by a usual user? (set up an interval for scanning, scan for new media automaticly, add new media to the library, show new media to the user via i.e. recently-added-list)

EDIT:
I changed the interval of all the media folders to 1 hour and it helped a few hours. Newly added albums were added to the list (not all, but most) but now again, after some hours, no new media is added anymore. The log shows nothing interesting. Everything seems normal, but it isnt.

This is a showstopper!

A music server/player which is unreliable and cannot keep track of all added music is quite .... odd. nicely spoken :-)

EDIT2:
After changeing the interval to 10 mins, no media were added too. But while stopping the madsonic service, those errors popped up:

Code: Select all

[2017-03-12 22:45:16,292] INFO MediaScannerService - Scanned media library with 397000 entries.
[2017-03-12 22:45:23,131] INFO MediaScannerService - Scanned media library with 397500 entries.
[2017-03-12 22:45:28,442] INFO MediaScannerService - Scanned media library with 398000 entries.
[2017-03-12 22:45:29,728] INFO MediaScannerService - Scanned media library with 398500 entries.
[2017-03-12 22:45:29,826] INFO MediaScannerService - Scanned media library with 399000 entries.
[2017-03-12 22:45:29,850] DEBUG MediaScannerService - WATCHDOG:RUN: doUpdateTasks()
[2017-03-12 22:45:29,851] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [Klangschale0]
[2017-03-12 22:45:29,851] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleA]
[2017-03-12 22:45:29,851] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleB]
[2017-03-12 22:45:29,851] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleC]
[2017-03-12 22:45:29,851] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleD]
[2017-03-12 22:45:29,851] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleE]
[2017-03-12 22:45:29,851] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleF]
[2017-03-12 22:45:29,851] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleG]
[2017-03-12 22:45:29,851] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleH]
[2017-03-12 22:45:29,851] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleI]
[2017-03-12 22:45:29,851] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleJ]
[2017-03-12 22:45:29,851] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleK]
[2017-03-12 22:45:29,851] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleL]
[2017-03-12 22:45:29,851] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleM]
[2017-03-12 22:45:29,851] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleN]
[2017-03-12 22:45:29,851] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleO]
[2017-03-12 22:45:29,851] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleP]
[2017-03-12 22:45:29,851] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleQ]
[2017-03-12 22:45:29,851] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleR]
[2017-03-12 22:45:29,851] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleS]
[2017-03-12 22:45:29,852] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleT]
[2017-03-12 22:45:29,852] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleU]
[2017-03-12 22:45:29,852] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleV]
[2017-03-12 22:45:29,852] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleW]
[2017-03-12 22:45:29,852] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleX]
[2017-03-12 22:45:29,852] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleY]
[2017-03-12 22:45:29,852] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleZ]
[2017-03-12 22:45:29,853] DEBUG MediaScannerService - WATCHDOG:TIMER: UPDATED [testing]
[2017-03-12 23:45:00,002] INFO MediaScannerService - WATCHDOG:SCAN: doFolderScanLibrary() started
[2017-03-12 23:45:02,524] INFO MediaScannerService - Scanned media library with 399500 entries.
[2017-03-12 23:45:09,527] INFO MediaScannerService - Scanned media library with 400000 entries.
[2017-03-12 23:45:16,550] INFO MediaScannerService - Scanned media library with 400500 entries.
[2017-03-12 23:45:23,423] INFO MediaScannerService - Scanned media library with 401000 entries.
[2017-03-12 23:45:29,953] INFO MediaScannerService - Scanned media library with 401500 entries.
[2017-03-12 23:45:38,397] INFO MediaScannerService - Scanned media library with 402000 entries.
[2017-03-12 23:45:44,642] INFO MediaScannerService - Scanned media library with 402500 entries.
[2017-03-12 23:45:47,610] INFO MediaScannerService - Scanned media library with 403000 entries.
[2017-03-12 23:45:47,656] INFO MediaScannerService - Scanned media library with 403500 entries.
[2017-03-12 23:45:47,718] INFO MediaScannerService - Scanned media library with 404000 entries.
[2017-03-12 23:45:47,860] INFO MediaScannerService - Scanned media library with 404500 entries.
[2017-03-12 23:45:47,860] INFO MediaScannerService - Scanned media library with 405000 entries.
[2017-03-12 23:45:47,956] INFO MediaScannerService - Scanned media library with 405500 entries.
[2017-03-12 23:45:48,011] INFO MediaScannerService - Scanned media library with 406000 entries.
[2017-03-12 23:45:48,079] INFO MediaScannerService - Scanned media library with 406500 entries.
[2017-03-12 23:45:48,130] INFO MediaScannerService - Scanned media library with 407000 entries.
[2017-03-12 23:45:48,181] INFO MediaScannerService - Scanned media library with 407500 entries.
[2017-03-12 23:45:48,231] INFO MediaScannerService - Scanned media library with 408000 entries.
[2017-03-12 23:45:48,278] INFO MediaScannerService - Scanned media library with 408500 entries.
[2017-03-12 23:45:48,352] INFO MediaScannerService - Scanned media library with 409000 entries.
[2017-03-12 23:45:48,397] INFO MediaScannerService - Scanned media library with 409500 entries.
[2017-03-12 23:45:48,452] INFO MediaScannerService - Scanned media library with 410000 entries.
[2017-03-12 23:45:52,890] INFO MediaScannerService - Scanned media library with 410500 entries.
[2017-03-12 23:46:00,071] INFO MediaScannerService - Scanned media library with 411000 entries.
[2017-03-12 23:46:05,141] DEBUG MediaScannerService - WATCHDOG:RUN: doUpdateTasks()
[2017-03-12 23:46:05,142] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [Klangschale0]
[2017-03-12 23:46:05,142] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleA]
[2017-03-12 23:46:05,142] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleB]
[2017-03-12 23:46:05,142] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleC]
[2017-03-12 23:46:05,142] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleD]
[2017-03-12 23:46:05,142] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleE]
[2017-03-12 23:46:05,142] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleF]
[2017-03-12 23:46:05,142] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleG]
[2017-03-12 23:46:05,142] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleH]
[2017-03-12 23:46:05,142] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleI]
[2017-03-12 23:46:05,142] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleJ]
[2017-03-12 23:46:05,142] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleK]
[2017-03-12 23:46:05,143] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleL]
[2017-03-12 23:46:05,143] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleM]
[2017-03-12 23:46:05,143] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleN]
[2017-03-12 23:46:05,143] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleO]
[2017-03-12 23:46:05,143] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleP]
[2017-03-12 23:46:05,143] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleQ]
[2017-03-12 23:46:05,143] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleR]
[2017-03-12 23:46:05,143] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleS]
[2017-03-12 23:46:05,143] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleT]
[2017-03-12 23:46:05,143] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleU]
[2017-03-12 23:46:05,143] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleV]
[2017-03-12 23:46:05,143] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleW]
[2017-03-12 23:46:05,143] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleX]
[2017-03-12 23:46:05,143] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleY]
[2017-03-12 23:46:05,143] DEBUG MediaScannerService - WATCHDOG:TIMER: PLANNED [KlangschaleZ]
[2017-03-12 23:46:05,144] DEBUG MediaScannerService - WATCHDOG:TIMER: UPDATED [testing]
[2017-03-13 00:02:49,548] DEBUG AbstractDao - fn::run::getVideoConversionsByStatus:  5959 ms:  select id, media_file_id, audio_track_id, username, status, ta
rget_file, log_file, bit_rate, progress_seconds, created, changed, started from video_conversion where status=? order by created
[2017-03-13 00:02:49,548] DEBUG AbstractDao - fn::run::getAudioConversionsByStatus:  5959 ms:  select id, media_file_id, username, status, target_file, targe
t_format, log_file, bit_rate, progress_seconds, created, changed, started from audio_conversion where status=? order by created
[2017-03-13 00:02:49,548] DEBUG AbstractDao - fn::getPlayerById::queryOne:  5634 ms:  select id, name, type, username, ip_address, auto_control_enabled, last
_seen, cover_art_scheme, transcode_scheme, dynamic_ip, technology, client_id from player where id=?
[2017-03-13 00:02:52,748] DEBUG AbstractDao - fn::getMediaFile::queryOne:  9924 ms:  select id, path, folder, type, override, format, data, title, album, alb
um_name, artist, album_artist, disc_number, track_number, year, genre, mood, bpm, composer, bit_rate, variable_bit_rate, duration_seconds, file_size, width,
height, cover_art_path, parent_path, play_count, last_played, comment, created, changed, last_scanned, first_scanned, children_last_updated, present, version
, rank from media_file where path=?
[2017-03-13 00:02:52,749] DEBUG AbstractDao - fn::getMediaFile::queryOne:  9924 ms:  select id, path, folder, type, override, format, data, title, album, alb
um_name, artist, album_artist, disc_number, track_number, year, genre, mood, bpm, composer, bit_rate, variable_bit_rate, duration_seconds, file_size, width,
height, cover_art_path, parent_path, play_count, last_played, comment, created, changed, last_scanned, first_scanned, children_last_updated, present, version
, rank from media_file where path=?
[2017-03-13 00:03:44,401] DEBUG AbstractDao - fn::getArtistGenresforFolder::getArtistGenresforFolder:  67284 ms:  select distinct lower(genre) from media_fil
e where lower(genre) is not null and present and type='ARTIST' and folder in (select path from music_folder where id in (select music_folder_id from user_gro
up_access where music_folder_id=? and user_group_id=? and enabled))
[2017-03-13 00:03:47,600] DEBUG AbstractDao - fn::getArtistGenresforFolder::getArtistGenresforFolder:  3198 ms:  select distinct lower(genre) from media_file
 where lower(genre) is not null and present and type='ARTIST' and folder in (select path from music_folder where id in (select music_folder_id from user_grou
p_access where music_folder_id=? and user_group_id=? and enabled))
[2017-03-13 00:03:55,729] DEBUG AbstractDao - fn::getArtistGenresforFolder::getArtistGenresforFolder:  3828 ms:  select distinct lower(genre) from media_file
 where lower(genre) is not null and present and type='ARTIST' and folder in (select path from music_folder where id in (select music_folder_id from user_grou
p_access where music_folder_id=? and user_group_id=? and enabled))
[2017-03-13 00:03:56,710] DEBUG AbstractDao - fn::getNewestAlbums::getNewestAlbums:  79566 ms:  select id, path, folder, type, override, format, data, title,
 album, album_name, artist, album_artist, disc_number, track_number, year, genre, mood, bpm, composer, bit_rate, variable_bit_rate, duration_seconds, file_si
ze, width, height, cover_art_path, parent_path, play_count, last_played, comment, created, changed, last_scanned, first_scanned, children_last_updated, prese
nt, version, rank from media_file where type in (:type) and folder in (:folders) and present order by created desc limit :count offset :offset
[2017-03-13 00:04:31,725] DEBUG AbstractDao - fn::getNewestAlbums::getNewestAlbums:  9955 ms:  select id, path, folder, type, override, format, data, title,
album, album_name, artist, album_artist, disc_number, track_number, year, genre, mood, bpm, composer, bit_rate, variable_bit_rate, duration_seconds, file_siz
e, width, height, cover_art_path, parent_path, play_count, last_played, comment, created, changed, last_scanned, first_scanned, children_last_updated, presen
t, version, rank from media_file where type in (:type) and folder in (:folders) and present order by created desc limit :count offset :offset
[2017-03-13 00:17:22,963] DEBUG MediaScannerService - WATCHDOG:SCHEDULE: 2017-03-14 18:09:00.0 [Klangschale0]
[2017-03-13 00:17:22,963] DEBUG MediaScannerService - WATCHDOG:SCHEDULE: 2017-03-17 01:00:00.0 [KlangschaleA]
[2017-03-13 00:17:22,963] DEBUG MediaScannerService - WATCHDOG:SCHEDULE: 2017-03-17 01:00:00.0 [KlangschaleB]
[2017-03-13 00:17:22,963] DEBUG MediaScannerService - WATCHDOG:SCHEDULE: 2017-03-17 01:00:00.0 [KlangschaleC]
[2017-03-13 00:17:22,963] DEBUG MediaScannerService - WATCHDOG:SCHEDULE: 2017-03-17 01:00:00.0 [KlangschaleD]
[2017-03-13 00:17:22,963] DEBUG MediaScannerService - WATCHDOG:SCHEDULE: 2017-03-17 01:00:00.0 [KlangschaleE]
[2017-03-13 00:17:22,963] DEBUG MediaScannerService - WATCHDOG:SCHEDULE: 2017-03-17 01:00:00.0 [KlangschaleF]
[2017-03-13 00:17:22,963] DEBUG MediaScannerService - WATCHDOG:SCHEDULE: 2017-03-17 01:00:00.0 [KlangschaleG]
[2017-03-13 00:17:22,963] DEBUG MediaScannerService - WATCHDOG:SCHEDULE: 2017-03-17 01:00:00.0 [KlangschaleH]
[2017-03-13 00:17:22,963] DEBUG MediaScannerService - WATCHDOG:SCHEDULE: 2017-03-17 01:00:00.0 [KlangschaleI]
[2017-03-13 00:17:22,963] DEBUG MediaScannerService - WATCHDOG:SCHEDULE: 2017-03-17 01:00:00.0 [KlangschaleJ]
[2017-03-13 00:17:22,963] DEBUG MediaScannerService - WATCHDOG:SCHEDULE: 2017-03-17 01:00:00.0 [KlangschaleK]
[2017-03-13 00:17:22,963] DEBUG MediaScannerService - WATCHDOG:SCHEDULE: 2017-03-17 01:00:00.0 [KlangschaleL]
[2017-03-13 00:17:22,963] DEBUG MediaScannerService - WATCHDOG:SCHEDULE: 2017-03-17 01:00:00.0 [KlangschaleM]
[2017-03-13 00:17:22,964] DEBUG MediaScannerService - WATCHDOG:SCHEDULE: 2017-03-17 01:00:00.0 [KlangschaleN]
[2017-03-13 00:17:22,964] DEBUG MediaScannerService - WATCHDOG:SCHEDULE: 2017-03-17 01:00:00.0 [KlangschaleO]
[2017-03-13 00:17:22,964] DEBUG MediaScannerService - WATCHDOG:SCHEDULE: 2017-03-17 01:00:00.0 [KlangschaleP]
[2017-03-13 00:17:22,964] DEBUG MediaScannerService - WATCHDOG:SCHEDULE: 2017-03-17 01:00:00.0 [KlangschaleQ]
[2017-03-13 00:17:22,964] DEBUG MediaScannerService - WATCHDOG:SCHEDULE: 2017-03-17 01:00:00.0 [KlangschaleR]
[2017-03-13 00:17:22,964] DEBUG MediaScannerService - WATCHDOG:SCHEDULE: 2017-03-17 01:00:00.0 [KlangschaleS]
[2017-03-13 00:17:22,964] DEBUG MediaScannerService - WATCHDOG:SCHEDULE: 2017-03-17 01:00:00.0 [KlangschaleT]
[2017-03-13 00:17:22,964] DEBUG MediaScannerService - WATCHDOG:SCHEDULE: 2017-03-17 01:00:00.0 [KlangschaleU]
[2017-03-13 00:17:22,978] DEBUG MediaScannerService - WATCHDOG:SCHEDULE: 2017-03-17 01:00:00.0 [KlangschaleV]
[2017-03-13 00:17:22,979] DEBUG MediaScannerService - WATCHDOG:SCHEDULE: 2017-03-17 01:00:00.0 [KlangschaleW]
[2017-03-13 00:17:22,979] DEBUG MediaScannerService - WATCHDOG:SCHEDULE: 2017-03-17 01:00:00.0 [KlangschaleX]
[2017-03-13 00:17:22,979] DEBUG MediaScannerService - WATCHDOG:SCHEDULE: 2017-03-17 01:00:00.0 [KlangschaleY]
[2017-03-13 00:17:22,979] DEBUG MediaScannerService - WATCHDOG:SCHEDULE: 2017-03-17 01:00:00.0 [KlangschaleZ]
[2017-03-13 00:17:22,979] DEBUG MediaScannerService - WATCHDOG:SCHEDULE: 2017-03-13 00:45:00.0 [testing]
[2017-03-13 00:17:22,979] INFO MediaScannerService - Automatic media scanning disabled.
[2017-03-13 00:17:40,031] DEBUG MediaScannerService - WATCHDOG:SCHEDULE: 2017-03-14 18:09:00.0 [Klangschale0]
[2017-03-13 00:17:40,031] DEBUG MediaScannerService - WATCHDOG:SCHEDULE: 2017-03-17 01:00:00.0 [KlangschaleA]
[2017-03-13 00:17:40,031] DEBUG MediaScannerService - WATCHDOG:SCHEDULE: 2017-03-17 01:00:00.0 [KlangschaleB]
[2017-03-13 00:17:40,031] DEBUG MediaScannerService - WATCHDOG:SCHEDULE: 2017-03-17 01:00:00.0 [KlangschaleC]
[2017-03-13 00:17:40,031] DEBUG MediaScannerService - WATCHDOG:SCHEDULE: 2017-03-17 01:00:00.0 [KlangschaleD]
[2017-03-13 00:17:40,031] DEBUG MediaScannerService - WATCHDOG:SCHEDULE: 2017-03-17 01:00:00.0 [KlangschaleE]
[2017-03-13 00:17:40,031] DEBUG MediaScannerService - WATCHDOG:SCHEDULE: 2017-03-17 01:00:00.0 [KlangschaleF]
[2017-03-13 00:17:40,031] DEBUG MediaScannerService - WATCHDOG:SCHEDULE: 2017-03-17 01:00:00.0 [KlangschaleG]
[2017-03-13 00:17:40,031] DEBUG MediaScannerService - WATCHDOG:SCHEDULE: 2017-03-17 01:00:00.0 [KlangschaleH]
[2017-03-13 00:17:40,031] DEBUG MediaScannerService - WATCHDOG:SCHEDULE: 2017-03-17 01:00:00.0 [KlangschaleI]
[2017-03-13 00:17:40,031] DEBUG MediaScannerService - WATCHDOG:SCHEDULE: 2017-03-17 01:00:00.0 [KlangschaleJ]
[2017-03-13 00:17:40,031] DEBUG MediaScannerService - WATCHDOG:SCHEDULE: 2017-03-17 01:00:00.0 [KlangschaleK]
[2017-03-13 00:17:40,031] DEBUG MediaScannerService - WATCHDOG:SCHEDULE: 2017-03-17 01:00:00.0 [KlangschaleL]
[2017-03-13 00:17:40,031] DEBUG MediaScannerService - WATCHDOG:SCHEDULE: 2017-03-17 01:00:00.0 [KlangschaleM]
[2017-03-13 00:17:40,031] DEBUG MediaScannerService - WATCHDOG:SCHEDULE: 2017-03-17 01:00:00.0 [KlangschaleN]
[2017-03-13 00:17:40,032] DEBUG MediaScannerService - WATCHDOG:SCHEDULE: 2017-03-17 01:00:00.0 [KlangschaleO]
[2017-03-13 00:17:40,032] DEBUG MediaScannerService - WATCHDOG:SCHEDULE: 2017-03-17 01:00:00.0 [KlangschaleP]
[2017-03-13 00:17:40,032] DEBUG MediaScannerService - WATCHDOG:SCHEDULE: 2017-03-17 01:00:00.0 [KlangschaleQ]
[2017-03-13 00:17:40,032] DEBUG MediaScannerService - WATCHDOG:SCHEDULE: 2017-03-17 01:00:00.0 [KlangschaleR]
[2017-03-13 00:17:40,032] DEBUG MediaScannerService - WATCHDOG:SCHEDULE: 2017-03-17 01:00:00.0 [KlangschaleS]
[2017-03-13 00:17:40,032] DEBUG MediaScannerService - WATCHDOG:SCHEDULE: 2017-03-17 01:00:00.0 [KlangschaleT]
[2017-03-13 00:17:40,032] DEBUG MediaScannerService - WATCHDOG:SCHEDULE: 2017-03-17 01:00:00.0 [KlangschaleU]
[2017-03-13 00:17:40,032] DEBUG MediaScannerService - WATCHDOG:SCHEDULE: 2017-03-17 01:00:00.0 [KlangschaleV]
[2017-03-13 00:17:40,032] DEBUG MediaScannerService - WATCHDOG:SCHEDULE: 2017-03-17 01:00:00.0 [KlangschaleW]
[2017-03-13 00:17:40,032] DEBUG MediaScannerService - WATCHDOG:SCHEDULE: 2017-03-17 01:00:00.0 [KlangschaleX]
[2017-03-13 00:17:40,032] DEBUG MediaScannerService - WATCHDOG:SCHEDULE: 2017-03-17 01:00:00.0 [KlangschaleY]
[2017-03-13 00:17:40,032] DEBUG MediaScannerService - WATCHDOG:SCHEDULE: 2017-03-17 01:00:00.0 [KlangschaleZ]
[2017-03-13 00:17:40,032] DEBUG MediaScannerService - WATCHDOG:SCHEDULE: 2017-03-13 00:45:00.0 [testing]
[2017-03-13 00:17:40,032] INFO MediaScannerService - Automatic media scanning disabled.
[2017-03-13 00:21:55,072] DEBUG AbstractDao - fn::getNewestAlbums::getNewestAlbums:  15646 ms:  select id, path, folder, type, override, format, data, title,
 album, album_name, artist, album_artist, disc_number, track_number, year, genre, mood, bpm, composer, bit_rate, variable_bit_rate, duration_seconds, file_si
ze, width, height, cover_art_path, parent_path, play_count, last_played, comment, created, changed, last_scanned, first_scanned, children_last_updated, prese
nt, version, rank from media_file where type in (:type) and folder in (:folders) and present order by created desc limit :count offset :offset
[2017-03-13 00:29:30,148] DEBUG AbstractDao - fn::getNewestAlbums::getNewestAlbums:  7040 ms:  select id, path, folder, type, override, format, data, title,
album, album_name, artist, album_artist, disc_number, track_number, year, genre, mood, bpm, composer, bit_rate, variable_bit_rate, duration_seconds, file_siz
e, width, height, cover_art_path, parent_path, play_count, last_played, comment, created, changed, last_scanned, first_scanned, children_last_updated, presen
t, version, rank from media_file where type in (:type) and folder in (:folders) and present order by created desc limit :count offset :offset
[2017-03-13 00:31:47,112] WARN HsqlDaoHelper - Shutting down database...
[2017-03-13 00:31:47,599] ERROR NowPlayingService - Unexpected error in getNowListening: org.springframework.jdbc.CannotGetJdbcConnectionException: Could not
 get JDBC Connection; nested exception is java.sql.SQLException: Database lock acquisition failure: attempt to connect while db opening /closing
org.springframework.jdbc.CannotGetJdbcConnectionException: Could not get JDBC Connection; nested exception is java.sql.SQLException: Database lock acquisitio
n failure: attempt to connect while db opening /closing
        at org.springframework.jdbc.datasource.DataSourceUtils.getConnection(DataSourceUtils.java:80)
        at org.springframework.jdbc.core.JdbcTemplate.execute(JdbcTemplate.java:575)
        at org.springframework.jdbc.core.JdbcTemplate.query(JdbcTemplate.java:639)
        at org.springframework.jdbc.core.JdbcTemplate.query(JdbcTemplate.java:668)
        at org.springframework.jdbc.core.JdbcTemplate.query(JdbcTemplate.java:676)
        at org.springframework.jdbc.core.JdbcTemplate.query(JdbcTemplate.java:716)
        at org.madsonic.dao.AbstractDao.query(AbstractDao.java:107)
        at org.madsonic.dao.AbstractDao.queryOne(AbstractDao.java:181)
        at org.madsonic.dao.UserDao.getUserSettings(UserDao.java:207)
        at org.madsonic.service.SettingsService.getUserSettings(SettingsService.java:2508)
        at org.madsonic.ajax.NowPlayingService.convert(NowPlayingService.java:143)
        at org.madsonic.ajax.NowPlayingService.getNowListening(NowPlayingService.java:113)
        at sun.reflect.GeneratedMethodAccessor319.invoke(Unknown Source)
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:498)
        at org.directwebremoting.impl.CreatorModule$1.doFilter(CreatorModule.java:172)
        at org.directwebremoting.impl.CreatorModule.executeMethod(CreatorModule.java:184)
        at org.directwebremoting.impl.DefaultRemoter.execute(DefaultRemoter.java:353)
        at org.directwebremoting.impl.DefaultRemoter.execute(DefaultRemoter.java:306)
        at org.directwebremoting.dwrp.BaseCallHandler.handle(BaseCallHandler.java:110)
        at org.directwebremoting.servlet.UrlProcessor.handle(UrlProcessor.java:211)
        at org.directwebremoting.servlet.UrlProcessor.handle(UrlProcessor.java:185)
        at org.directwebremoting.servlet.DwrServlet.doPost(DwrServlet.java:144)
        at javax.servlet.http.HttpServlet.service(HttpServlet.java:755)
        at javax.servlet.http.HttpServlet.service(HttpServlet.java:848)
        at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:684)
        at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1507)
        at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:330)
        at org.springframework.security.web.access.intercept.FilterSecurityInterceptor.invoke(FilterSecurityInterceptor.java:118)
        at org.springframework.security.web.access.intercept.FilterSecurityInterceptor.doFilter(FilterSecurityInterceptor.java:84)
        at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342)
        at org.springframework.security.web.access.ExceptionTranslationFilter.doFilter(ExceptionTranslationFilter.java:113)
        at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342)
        at org.springframework.security.web.session.SessionManagementFilter.doFilter(SessionManagementFilter.java:103)
        at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342)
        at org.springframework.security.web.authentication.AnonymousAuthenticationFilter.doFilter(AnonymousAuthenticationFilter.java:113)
        at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342)
        at org.springframework.security.web.authentication.rememberme.RememberMeAuthenticationFilter.doFilter(RememberMeAuthenticationFilter.java:146)
        at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342)
        at org.springframework.security.web.servletapi.SecurityContextHolderAwareRequestFilter.doFilter(SecurityContextHolderAwareRequestFilter.java:154)
        at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342)
        at org.springframework.security.web.savedrequest.RequestCacheAwareFilter.doFilter(RequestCacheAwareFilter.java:45)
        at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342)
        at org.springframework.security.web.authentication.AbstractAuthenticationProcessingFilter.doFilter(AbstractAuthenticationProcessingFilter.java:199)
        at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342)
        at org.springframework.security.web.authentication.logout.LogoutFilter.doFilter(LogoutFilter.java:110)
        at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342)
        at org.springframework.security.web.context.request.async.WebAsyncManagerIntegrationFilter.doFilterInternal(WebAsyncManagerIntegrationFilter.java:50)
        at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:106)
        at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342)
        at org.springframework.security.web.context.SecurityContextPersistenceFilter.doFilter(SecurityContextPersistenceFilter.java:87)
        at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342)
        at org.springframework.security.web.FilterChainProxy.doFilterInternal(FilterChainProxy.java:192)
        at org.springframework.security.web.FilterChainProxy.doFilter(FilterChainProxy.java:160)
        at org.springframework.web.filter.DelegatingFilterProxy.invokeDelegate(DelegatingFilterProxy.java:343)
        at org.springframework.web.filter.DelegatingFilterProxy.doFilter(DelegatingFilterProxy.java:260)
        at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1495)
        at org.madsonic.filter.RequestEncodingFilter.doFilter(RequestEncodingFilter.java:43)
        at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1495)
        at org.madsonic.filter.ParameterDecodingFilter.doFilter(ParameterDecodingFilter.java:54)
        at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1495)
        at org.madsonic.filter.BootstrapVerificationFilter.doFilter(BootstrapVerificationFilter.java:58)
        at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1487)
        at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:499)
        at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:137)
        at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:557)
        at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:231)
        at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1086)
        at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:427)
        at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:193)
        at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1020)
        at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:135)
        at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:116)
        at org.eclipse.jetty.server.Server.handle(Server.java:370)
        at org.eclipse.jetty.server.AbstractHttpConnection.handleRequest(AbstractHttpConnection.java:494)
        at org.eclipse.jetty.server.AbstractHttpConnection.content(AbstractHttpConnection.java:984)
        at org.eclipse.jetty.server.AbstractHttpConnection$RequestHandler.content(AbstractHttpConnection.java:1045)
        at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:861)
        at org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:236)
        at org.eclipse.jetty.server.AsyncHttpConnection.handle(AsyncHttpConnection.java:82)
        at org.eclipse.jetty.io.nio.SelectChannelEndPoint.handle(SelectChannelEndPoint.java:696)
        at org.eclipse.jetty.io.nio.SelectChannelEndPoint$1.run(SelectChannelEndPoint.java:53)
        at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608)
        at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543)
        at java.lang.Thread.run(Thread.java:745)
Caused by: java.sql.SQLException: Database lock acquisition failure: attempt to connect while db opening /closing
        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)
        ... 84 more
Caused by: org.hsqldb.HsqlException: Database lock acquisition failure: attempt to connect while db opening /closing
        at org.hsqldb.error.Error.error(Unknown Source)
        at org.hsqldb.error.Error.error(Unknown Source)
        at org.hsqldb.error.Error.error(Unknown Source)
        at org.hsqldb.DatabaseManager.getDatabase(Unknown Source)
        at org.hsqldb.DatabaseManager.newSession(Unknown Source)
        ... 95 more

[2017-03-13 00:31:47,890] ERROR NowPlayingService - Unexpected error in getNowPlaying: org.springframework.jdbc.CannotGetJdbcConnectionException: Could not g
et JDBC Connection; nested exception is java.sql.SQLException: Database lock acquisition failure: attempt to connect while db opening /closing
org.springframework.jdbc.CannotGetJdbcConnectionException: Could not get JDBC Connection; nested exception is java.sql.SQLException: Database lock acquisitio
n failure: attempt to connect while db opening /closing
        at org.springframework.jdbc.datasource.DataSourceUtils.getConnection(DataSourceUtils.java:80)
        at org.springframework.jdbc.core.JdbcTemplate.execute(JdbcTemplate.java:575)
        at org.springframework.jdbc.core.JdbcTemplate.query(JdbcTemplate.java:639)
        at org.springframework.jdbc.core.JdbcTemplate.query(JdbcTemplate.java:668)
        at org.springframework.jdbc.core.JdbcTemplate.query(JdbcTemplate.java:676)
        at org.springframework.jdbc.core.JdbcTemplate.query(JdbcTemplate.java:716)
        at org.madsonic.dao.AbstractDao.query(AbstractDao.java:107)
        at org.madsonic.dao.AbstractDao.queryOne(AbstractDao.java:181)
        at org.madsonic.dao.UserDao.getUserSettings(UserDao.java:207)
        at org.madsonic.service.SettingsService.getUserSettings(SettingsService.java:2508)
        at org.madsonic.ajax.NowPlayingService.convert(NowPlayingService.java:143)
        at org.madsonic.ajax.NowPlayingService.getNowPlaying(NowPlayingService.java:101)
        at sun.reflect.GeneratedMethodAccessor320.invoke(Unknown Source)
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
        at java.lang.reflect.Method.invoke(Method.java:498)
        at org.directwebremoting.impl.CreatorModule$1.doFilter(CreatorModule.java:172)
        at org.directwebremoting.impl.CreatorModule.executeMethod(CreatorModule.java:184)
        at org.directwebremoting.impl.DefaultRemoter.execute(DefaultRemoter.java:353)
        at org.directwebremoting.impl.DefaultRemoter.execute(DefaultRemoter.java:306)
        at org.directwebremoting.dwrp.BaseCallHandler.handle(BaseCallHandler.java:110)
        at org.directwebremoting.servlet.UrlProcessor.handle(UrlProcessor.java:211)
        at org.directwebremoting.servlet.UrlProcessor.handle(UrlProcessor.java:185)
        at org.directwebremoting.servlet.DwrServlet.doPost(DwrServlet.java:144)
        at javax.servlet.http.HttpServlet.service(HttpServlet.java:755)
        at javax.servlet.http.HttpServlet.service(HttpServlet.java:848)
        at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:684)
        at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1507)
        at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:330)
        at org.springframework.security.web.access.intercept.FilterSecurityInterceptor.invoke(FilterSecurityInterceptor.java:118)
        at org.springframework.security.web.access.intercept.FilterSecurityInterceptor.doFilter(FilterSecurityInterceptor.java:84)
        at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342)
        at org.springframework.security.web.access.ExceptionTranslationFilter.doFilter(ExceptionTranslationFilter.java:113)
        at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342)
        at org.springframework.security.web.session.SessionManagementFilter.doFilter(SessionManagementFilter.java:103)
        at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342)
        at org.springframework.security.web.authentication.AnonymousAuthenticationFilter.doFilter(AnonymousAuthenticationFilter.java:113)
        at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342)
        at org.springframework.security.web.authentication.rememberme.RememberMeAuthenticationFilter.doFilter(RememberMeAuthenticationFilter.java:146)
        at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342)
        at org.springframework.security.web.servletapi.SecurityContextHolderAwareRequestFilter.doFilter(SecurityContextHolderAwareRequestFilter.java:154)
        at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342)
        at org.springframework.security.web.savedrequest.RequestCacheAwareFilter.doFilter(RequestCacheAwareFilter.java:45)
        at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342)
        at org.springframework.security.web.authentication.AbstractAuthenticationProcessingFilter.doFilter(AbstractAuthenticationProcessingFilter.java:199)
        at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342)
        at org.springframework.security.web.authentication.logout.LogoutFilter.doFilter(LogoutFilter.java:110)
        at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342)
        at org.springframework.security.web.context.request.async.WebAsyncManagerIntegrationFilter.doFilterInternal(WebAsyncManagerIntegrationFilter.java:50)
        at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:106)
        at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342)
        at org.springframework.security.web.context.SecurityContextPersistenceFilter.doFilter(SecurityContextPersistenceFilter.java:87)
        at org.springframework.security.web.FilterChainProxy$VirtualFilterChain.doFilter(FilterChainProxy.java:342)
        at org.springframework.security.web.FilterChainProxy.doFilterInternal(FilterChainProxy.java:192)
        at org.springframework.security.web.FilterChainProxy.doFilter(FilterChainProxy.java:160)
        at org.springframework.web.filter.DelegatingFilterProxy.invokeDelegate(DelegatingFilterProxy.java:343)
        at org.springframework.web.filter.DelegatingFilterProxy.doFilter(DelegatingFilterProxy.java:260)
        at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1495)
        at org.madsonic.filter.RequestEncodingFilter.doFilter(RequestEncodingFilter.java:43)
        at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1495)
        at org.madsonic.filter.ParameterDecodingFilter.doFilter(ParameterDecodingFilter.java:54)
        at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1495)
        at org.madsonic.filter.BootstrapVerificationFilter.doFilter(BootstrapVerificationFilter.java:58)
        at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1487)
        at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:499)
        at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:137)
        at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:557)
        at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:231)
        at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1086)
        at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:427)
        at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:193)
        at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1020)
        at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:135)
        at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:116)
        at org.eclipse.jetty.server.Server.handle(Server.java:370)
        at org.eclipse.jetty.server.AbstractHttpConnection.handleRequest(AbstractHttpConnection.java:494)
        at org.eclipse.jetty.server.AbstractHttpConnection.content(AbstractHttpConnection.java:984)
        at org.eclipse.jetty.server.AbstractHttpConnection$RequestHandler.content(AbstractHttpConnection.java:1045)
        at org.eclipse.jetty.http.HttpParser.parseNext(HttpParser.java:861)
        at org.eclipse.jetty.http.HttpParser.parseAvailable(HttpParser.java:236)
        at org.eclipse.jetty.server.AsyncHttpConnection.handle(AsyncHttpConnection.java:82)
        at org.eclipse.jetty.io.nio.SelectChannelEndPoint.handle(SelectChannelEndPoint.java:696)
        at org.eclipse.jetty.io.nio.SelectChannelEndPoint$1.run(SelectChannelEndPoint.java:53)
        at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:608)
        at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:543)
        at java.lang.Thread.run(Thread.java:745)
Caused by: java.sql.SQLException: Database lock acquisition failure: attempt to connect while db opening /closing
        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)
        ... 84 more
Caused by: org.hsqldb.HsqlException: Database lock acquisition failure: attempt to connect while db opening /closing
        at org.hsqldb.error.Error.error(Unknown Source)
        at org.hsqldb.error.Error.error(Unknown Source)
        at org.hsqldb.error.Error.error(Unknown Source)
        at org.hsqldb.DatabaseManager.getDatabase(Unknown Source)
        at org.hsqldb.DatabaseManager.newSession(Unknown Source)
        ... 95 more

[2017-03-13 00:31:48,439] INFO HsqlDaoHelper - Shutting down database - Done!

Re: recently added not up-to-date after autoscan

Posted: 17 Mar 2017, 11:12
by Matt Zornig
This is quite an annoying bug ;-)

Resume after some days:

changing the rescan interval to 1hour and doing a "Full Rescan with Cleanup" scan, did not help in any way.

After adding a lot of music, only 20% of that is shown.

I cannot figure out, what the problem could be (mp3 tags set to v2.3 or v2.4, mp4 and mp3 files). It seems random.

It might be connected with this madsonic is contantly stopping music after some minutes