Mar 24 09:02:11 volumio-pi volumio[1014]: info: CoreCommandRouter::volumioPlay Mar 24 09:02:11 volumio-pi volumio[1014]: info: CoreStateMachine::play index 8 Mar 24 09:02:11 volumio-pi volumio[1014]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 09:02:11 volumio-pi volumio[1014]: info: CoreStateMachine::stop Mar 24 09:02:11 volumio-pi volumio[1014]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 09:02:11 volumio-pi volumio[1014]: info: CoreStateMachine::stPlaybackTimer Mar 24 09:02:11 volumio-pi volumio[1014]: info: CoreStateMachine::updateTrackBlock Mar 24 09:02:11 volumio-pi volumio[1014]: info: CorePlayQueue::getTrackBlock Mar 24 09:02:11 volumio-pi volumio[1014]: info: CoreStateMachine::pushState Mar 24 09:02:11 volumio-pi volumio[1014]: info: CorePlayQueue::getTrack 5 Mar 24 09:02:11 volumio-pi volumio[1014]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 09:02:11 volumio-pi volumio[1014]: info: CoreCommandRouter::volumioPushState Mar 24 09:02:11 volumio-pi volumio[1014]: info: CoreStateMachine::serviceStop Mar 24 09:02:11 volumio-pi volumio[1014]: info: CorePlayQueue::getTrack 5 Mar 24 09:02:11 volumio-pi volumio[1014]: info: CoreCommandRouter::serviceStop Mar 24 09:02:11 volumio-pi volumio[1014]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 24 09:02:11 volumio-pi volumio[1014]: info: ControllerMpd::stop Mar 24 09:02:11 volumio-pi volumio[1014]: verbose: ControllerMpd::sendMpdCommand stop Mar 24 09:02:11 volumio-pi volumio5-onboarding[1370]: time=2026-03-24T09:02:11.850+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.127:60954 @ 0x1f4ee10" state=STATUS_STOPPED positionMs=0 volume=81 Mar 24 09:02:11 volumio-pi volumio5-onboarding[1370]: time=2026-03-24T09:02:11.850+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.127:60954 @ 0x1f4ee10" id="jellyfin/anhthy@de59f0ff0f17402d8d2f3797278cbbc6/song@songId=7f0a230179f6a896598b435f8a09bc2e" title="Liszt - Liebestraum No.3, Op.62 (Jorge Bolet)" Mar 24 09:02:11 volumio-pi volumio[1014]: info: Mar 24 09:02:11 volumio-pi volumio[1014]: ---------------------------- MPD announces state update: player Mar 24 09:02:11 volumio-pi volumio[1014]: info: sendMpdCommand stop took 21 milliseconds Mar 24 09:02:11 volumio-pi volumio[1014]: info: ControllerMpd::getState Mar 24 09:02:11 volumio-pi volumio[1014]: verbose: ControllerMpd::sendMpdCommand status Mar 24 09:02:11 volumio-pi volumio[1014]: info: CoreStateMachine::play index undefined Mar 24 09:02:11 volumio-pi volumio[1014]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 09:02:11 volumio-pi volumio[1014]: info: CorePlayQueue::getTrack 8 Mar 24 09:02:11 volumio-pi volumio[1014]: info: CoreStateMachine::startPlaybackTimer Mar 24 09:02:11 volumio-pi volumio[1014]: info: CorePlayQueue::getTrack 8 Mar 24 09:02:11 volumio-pi volumio[1014]: info: [jellyfin-play] clearAddPlayTrack: jellyfin/anhthy@de59f0ff0f17402d8d2f3797278cbbc6/song@songId=4dc1dbd4708fff9786c45f59c55f45a7 Mar 24 09:02:11 volumio-pi volumio[1014]: info: Mar 24 09:02:11 volumio-pi volumio[1014]: ---------------------------- MPD announces state update: player Mar 24 09:02:11 volumio-pi volumio[1014]: info: ControllerMpd::getState Mar 24 09:02:11 volumio-pi volumio[1014]: verbose: ControllerMpd::sendMpdCommand status Mar 24 09:02:11 volumio-pi volumio[1014]: info: sendMpdCommand status took 9 milliseconds Mar 24 09:02:11 volumio-pi volumio[1014]: info: sendMpdCommand status took 2 milliseconds Mar 24 09:02:11 volumio-pi volumio[1014]: verbose: ControllerMpd::parseState Mar 24 09:02:11 volumio-pi volumio[1014]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 09:02:11 volumio-pi volumio[1014]: verbose: ControllerMpd::parseState Mar 24 09:02:11 volumio-pi volumio[1014]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 09:02:11 volumio-pi volumio[1014]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 24 09:02:11 volumio-pi volumio[1014]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 24 09:02:11 volumio-pi volumio[1014]: verbose: ControllerMpd::parseTrackInfo Mar 24 09:02:11 volumio-pi volumio[1014]: verbose: ControllerMpd::parseTrackInfo Mar 24 09:02:11 volumio-pi volumio[1014]: info: ControllerMpd::pushState Mar 24 09:02:11 volumio-pi volumio[1014]: info: CoreCommandRouter::servicePushState Mar 24 09:02:11 volumio-pi volumio[1014]: info: CoreStateMachine::pushState Mar 24 09:02:11 volumio-pi volumio[1014]: info: CorePlayQueue::getTrack 8 Mar 24 09:02:11 volumio-pi volumio[1014]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 09:02:11 volumio-pi volumio[1014]: info: CoreCommandRouter::volumioPushState Mar 24 09:02:11 volumio-pi volumio[1014]: info: CorePlayQueue::getTrack 8 Mar 24 09:02:11 volumio-pi volumio[1014]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current jellyfin Received mpd Mar 24 09:02:11 volumio-pi volumio[1014]: info: ControllerMpd::pushState Mar 24 09:02:11 volumio-pi volumio[1014]: info: CoreCommandRouter::servicePushState Mar 24 09:02:11 volumio-pi volumio[1014]: info: CoreStateMachine::pushState Mar 24 09:02:11 volumio-pi volumio[1014]: info: CorePlayQueue::getTrack 8 Mar 24 09:02:11 volumio-pi volumio[1014]: info: CoreCommandRouter::volumioPushState Mar 24 09:02:11 volumio-pi volumio[1014]: info: CorePlayQueue::getTrack 8 Mar 24 09:02:11 volumio-pi volumio[1014]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current jellyfin Received mpd Mar 24 09:02:11 volumio-pi volumio5-onboarding[1370]: time=2026-03-24T09:02:11.900+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.127:60954 @ 0x1f4ee10" state=STATUS_STOPPED positionMs=0 volume=81 Mar 24 09:02:11 volumio-pi volumio5-onboarding[1370]: time=2026-03-24T09:02:11.900+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.127:60954 @ 0x1f4ee10" id="jellyfin/anhthy@de59f0ff0f17402d8d2f3797278cbbc6/song@songId=4dc1dbd4708fff9786c45f59c55f45a7" title="Liszt - Hungarian Rhapsody No.2 In C Sharp Minor (Misha Dichter)" Mar 24 09:02:11 volumio-pi volumio5-onboarding[1370]: time=2026-03-24T09:02:11.902+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.127:60954 @ 0x1f4ee10" state=STATUS_STOPPED positionMs=0 volume=81 Mar 24 09:02:11 volumio-pi volumio5-onboarding[1370]: time=2026-03-24T09:02:11.903+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.127:60954 @ 0x1f4ee10" id="jellyfin/anhthy@de59f0ff0f17402d8d2f3797278cbbc6/song@songId=4dc1dbd4708fff9786c45f59c55f45a7" title="Liszt - Hungarian Rhapsody No.2 In C Sharp Minor (Misha Dichter)" Mar 24 09:02:11 volumio-pi volumio[1014]: info: ------------------------------ 44ms Mar 24 09:02:11 volumio-pi volumio[1014]: info: ------------------------------ 37ms Mar 24 09:02:11 volumio-pi volumio[1014]: info: [jellyfin-play] Stream URL for Liszt - Hungarian Rhapsody No.2 In C Sharp Minor (Misha Dichter): http://anhthy.duckdns.org:19999/Audio/4dc1dbd4708fff9786c45f59c55f45a7/stream.flac?static=true&mediaSourceId=4dc1dbd4708fff9786c45f59c55f45a7&tag=01b1938a065a5fa2233f223d8c21a247 Mar 24 09:02:11 volumio-pi volumio[1014]: verbose: ControllerMpd::sendMpdCommand stop Mar 24 09:02:11 volumio-pi volumio[1014]: info: sendMpdCommand stop took 1 milliseconds Mar 24 09:02:11 volumio-pi volumio[1014]: verbose: ControllerMpd::sendMpdCommand clear Mar 24 09:02:11 volumio-pi volumio[1014]: info: Mar 24 09:02:11 volumio-pi volumio[1014]: ---------------------------- MPD announces system playlist update Mar 24 09:02:11 volumio-pi volumio[1014]: info: Ignoring MPD Status Update Mar 24 09:02:11 volumio-pi volumio[1014]: info: sendMpdCommand clear took 1 milliseconds Mar 24 09:02:11 volumio-pi volumio[1014]: verbose: ControllerMpd::sendMpdCommand load "http://anhthy.duckdns.org:19999/Audio/4dc1dbd4708fff9786c45f59c55f45a7/stream.flac?static=true&mediaSourceId=4dc1dbd4708fff9786c45f59c55f45a7&tag=01b1938a065a5fa2233f223d8c21a247&t.flac" Mar 24 09:02:11 volumio-pi volumio[1014]: info: Mar 24 09:02:11 volumio-pi volumio[1014]: ---------------------------- MPD announces system playlist update Mar 24 09:02:11 volumio-pi volumio[1014]: info: Ignoring MPD Status Update Mar 24 09:02:11 volumio-pi volumio[1014]: error: updateQueue error: null Mar 24 09:02:11 volumio-pi volumio[1014]: info: ------------------------------ 4ms Mar 24 09:02:22 volumio-pi systemd[1]: mpd.service: Watchdog timeout (limit 20s)! Mar 24 09:02:22 volumio-pi systemd[1]: mpd.service: Killing process 2339 (mpd) with signal SIGABRT. Mar 24 09:02:22 volumio-pi volumio[1014]: error: MPD error: Error: read ECONNRESET Mar 24 09:02:22 volumio-pi volumio[1014]: error: MPD error: Error: read ECONNRESET Mar 24 09:02:22 volumio-pi systemd[1]: mpd.service: Main process exited, code=killed, status=6/ABRT Mar 24 09:02:22 volumio-pi systemd[1]: mpd.service: Failed with result 'watchdog'. Mar 24 09:02:22 volumio-pi systemd[1]: mpd.service: Consumed 13.270s CPU time. Mar 24 09:02:27 volumio-pi systemd[1]: Starting mpd.service - Music Player Daemon... Mar 24 09:02:27 volumio-pi sudo[3783]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 24 09:02:27 volumio-pi sudo[3783]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 24 09:02:27 volumio-pi sudo[3783]: pam_unix(sudo:session): session closed for user root Mar 24 09:02:30 volumio-pi mpd[3785]: 2026-03-24T09:02:30 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 24 09:02:30 volumio-pi systemd[1]: Started mpd.service - Music Player Daemon. Mar 24 09:02:30 volumio-pi volumio[1014]: error: MPD error: The expression evaluated to a falsy value: Mar 24 09:02:30 volumio-pi volumio[1014]: assert.ok(self.idling) Mar 24 09:02:30 volumio-pi volumio[1014]: error: The expression evaluated to a falsy value: Mar 24 09:02:30 volumio-pi volumio[1014]: assert.ok(self.idling) Mar 24 09:02:30 volumio-pi volumio[1014]: error: updateQueue error: null Mar 24 09:02:51 volumio-pi systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Mar 24 09:02:52 volumio-pi systemd[1]: setdatetime-helper.service: Deactivated successfully. Mar 24 09:02:52 volumio-pi systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Mar 24 09:02:52 volumio-pi systemd[1]: setdatetime-helper.service: Consumed 1.075s CPU time. Mar 24 09:03:17 volumio-pi volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 09:03:17 volumio-pi volumio[1014]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 09:03:17 volumio-pi volumio[1014]: info: Discovery: Getting this device information Mar 24 09:03:17 volumio-pi volumio[1014]: info: CoreCommandRouter::volumioGetState Mar 24 09:03:17 volumio-pi volumio[1014]: info: CorePlayQueue::getTrack 8 Mar 24 09:03:17 volumio-pi volumio[1014]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 09:03:17 volumio-pi volumio[1014]: verbose: New Socket.io Connection to 192.168.10.87:3000 from 192.168.10.127 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Mar 24 09:03:17 volumio-pi volumio[1014]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 24 09:03:17 volumio-pi volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 24 09:03:18 volumio-pi volumio[1014]: info: CoreCommandRouter::volumioPlay Mar 24 09:03:18 volumio-pi volumio[1014]: info: CoreStateMachine::play index undefined Mar 24 09:03:18 volumio-pi volumio[1014]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 09:03:18 volumio-pi volumio[1014]: info: CorePlayQueue::getTrack 8 Mar 24 09:03:18 volumio-pi volumio[1014]: info: CoreStateMachine::startPlaybackTimer Mar 24 09:03:18 volumio-pi volumio[1014]: info: CorePlayQueue::getTrack 8 Mar 24 09:03:18 volumio-pi volumio[1014]: info: [jellyfin-play] clearAddPlayTrack: jellyfin/anhthy@de59f0ff0f17402d8d2f3797278cbbc6/song@songId=4dc1dbd4708fff9786c45f59c55f45a7 Mar 24 09:03:18 volumio-pi volumio[1014]: info: [jellyfin-play] Stream URL for Liszt - Hungarian Rhapsody No.2 In C Sharp Minor (Misha Dichter): http://anhthy.duckdns.org:19999/Audio/4dc1dbd4708fff9786c45f59c55f45a7/stream.flac?static=true&mediaSourceId=4dc1dbd4708fff9786c45f59c55f45a7&tag=01b1938a065a5fa2233f223d8c21a247 Mar 24 09:03:18 volumio-pi volumio[1014]: verbose: ControllerMpd::sendMpdCommand stop Mar 24 09:03:18 volumio-pi volumio[1014]: info: sendMpdCommand stop took 2 milliseconds Mar 24 09:03:18 volumio-pi volumio[1014]: verbose: ControllerMpd::sendMpdCommand clear Mar 24 09:03:18 volumio-pi volumio[1014]: info: Mar 24 09:03:18 volumio-pi volumio[1014]: ---------------------------- MPD announces system playlist update Mar 24 09:03:18 volumio-pi volumio[1014]: info: Ignoring MPD Status Update Mar 24 09:03:18 volumio-pi volumio[1014]: info: sendMpdCommand clear took 3 milliseconds Mar 24 09:03:18 volumio-pi volumio[1014]: verbose: ControllerMpd::sendMpdCommand load "http://anhthy.duckdns.org:19999/Audio/4dc1dbd4708fff9786c45f59c55f45a7/stream.flac?static=true&mediaSourceId=4dc1dbd4708fff9786c45f59c55f45a7&tag=01b1938a065a5fa2233f223d8c21a247&t.flac" Mar 24 09:03:18 volumio-pi volumio[1014]: info: Mar 24 09:03:18 volumio-pi volumio[1014]: ---------------------------- MPD announces system playlist update Mar 24 09:03:18 volumio-pi volumio[1014]: info: Ignoring MPD Status Update Mar 24 09:03:18 volumio-pi volumio[1014]: error: updateQueue error: null Mar 24 09:03:18 volumio-pi volumio[1014]: info: ------------------------------ 11ms Mar 24 09:03:31 volumio-pi systemd[1]: mpd.service: Watchdog timeout (limit 20s)! Mar 24 09:03:31 volumio-pi systemd[1]: mpd.service: Killing process 3785 (mpd) with signal SIGABRT. Mar 24 09:03:31 volumio-pi volumio[1014]: error: MPD error: Error: read ECONNRESET Mar 24 09:03:31 volumio-pi volumio[1014]: error: MPD error: Error: read ECONNRESET Mar 24 09:03:31 volumio-pi systemd[1]: mpd.service: Main process exited, code=killed, status=6/ABRT Mar 24 09:03:31 volumio-pi systemd[1]: mpd.service: Failed with result 'watchdog'. Mar 24 09:03:31 volumio-pi systemd[1]: mpd.service: Consumed 3.523s CPU time. Mar 24 09:03:36 volumio-pi systemd[1]: Starting mpd.service - Music Player Daemon... Mar 24 09:03:36 volumio-pi sudo[3921]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 24 09:03:36 volumio-pi sudo[3921]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 24 09:03:36 volumio-pi sudo[3921]: pam_unix(sudo:session): session closed for user root Mar 24 09:03:39 volumio-pi mpd[3923]: 2026-03-24T09:03:39 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 24 09:03:39 volumio-pi systemd[1]: Started mpd.service - Music Player Daemon. Mar 24 09:03:39 volumio-pi volumio[1014]: error: MPD error: The expression evaluated to a falsy value: Mar 24 09:03:39 volumio-pi volumio[1014]: assert.ok(self.idling) Mar 24 09:03:39 volumio-pi volumio[1014]: error: The expression evaluated to a falsy value: Mar 24 09:03:39 volumio-pi volumio[1014]: assert.ok(self.idling) Mar 24 09:03:39 volumio-pi volumio[1014]: error: updateQueue error: null Mar 24 09:03:41 volumio-pi volumio[1014]: info: CoreCommandRouter::volumioPlay Mar 24 09:03:41 volumio-pi volumio[1014]: info: CoreStateMachine::play index 8 Mar 24 09:03:41 volumio-pi volumio[1014]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 09:03:41 volumio-pi volumio[1014]: info: CoreStateMachine::stop Mar 24 09:03:41 volumio-pi volumio[1014]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 09:03:41 volumio-pi volumio[1014]: info: CoreStateMachine::play index undefined Mar 24 09:03:41 volumio-pi volumio[1014]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 09:03:41 volumio-pi volumio[1014]: info: CorePlayQueue::getTrack 8 Mar 24 09:03:41 volumio-pi volumio[1014]: info: CoreStateMachine::startPlaybackTimer Mar 24 09:03:41 volumio-pi volumio[1014]: info: CorePlayQueue::getTrack 8 Mar 24 09:03:41 volumio-pi volumio[1014]: info: [jellyfin-play] clearAddPlayTrack: jellyfin/anhthy@de59f0ff0f17402d8d2f3797278cbbc6/song@songId=4dc1dbd4708fff9786c45f59c55f45a7 Mar 24 09:03:41 volumio-pi volumio[1014]: info: [jellyfin-play] Stream URL for Liszt - Hungarian Rhapsody No.2 In C Sharp Minor (Misha Dichter): http://anhthy.duckdns.org:19999/Audio/4dc1dbd4708fff9786c45f59c55f45a7/stream.flac?static=true&mediaSourceId=4dc1dbd4708fff9786c45f59c55f45a7&tag=01b1938a065a5fa2233f223d8c21a247 Mar 24 09:03:41 volumio-pi volumio[1014]: verbose: ControllerMpd::sendMpdCommand stop Mar 24 09:03:41 volumio-pi volumio[1014]: info: sendMpdCommand stop took 1 milliseconds Mar 24 09:03:41 volumio-pi volumio[1014]: verbose: ControllerMpd::sendMpdCommand clear Mar 24 09:03:41 volumio-pi volumio[1014]: info: Mar 24 09:03:41 volumio-pi volumio[1014]: ---------------------------- MPD announces system playlist update Mar 24 09:03:41 volumio-pi volumio[1014]: info: Ignoring MPD Status Update Mar 24 09:03:41 volumio-pi volumio[1014]: info: sendMpdCommand clear took 1 milliseconds Mar 24 09:03:41 volumio-pi volumio[1014]: verbose: ControllerMpd::sendMpdCommand load "http://anhthy.duckdns.org:19999/Audio/4dc1dbd4708fff9786c45f59c55f45a7/stream.flac?static=true&mediaSourceId=4dc1dbd4708fff9786c45f59c55f45a7&tag=01b1938a065a5fa2233f223d8c21a247&t.flac" Mar 24 09:03:41 volumio-pi volumio[1014]: info: Mar 24 09:03:41 volumio-pi volumio[1014]: ---------------------------- MPD announces system playlist update Mar 24 09:03:41 volumio-pi volumio[1014]: info: Ignoring MPD Status Update Mar 24 09:03:41 volumio-pi volumio[1014]: error: updateQueue error: null Mar 24 09:03:41 volumio-pi volumio[1014]: info: ------------------------------ 126ms Mar 24 09:03:41 volumio-pi volumio[1014]: verbose: ControllerMpd::sendMpdCommand addid "http://anhthy.duckdns.org:19999/Audio/4dc1dbd4708fff9786c45f59c55f45a7/stream.flac?static=true&mediaSourceId=4dc1dbd4708fff9786c45f59c55f45a7&tag=01b1938a065a5fa2233f223d8c21a247&t.flac" Mar 24 09:03:41 volumio-pi volumio[1014]: error: updateQueue error: null Mar 24 09:03:41 volumio-pi volumio[1014]: info: Mar 24 09:03:41 volumio-pi volumio[1014]: ---------------------------- MPD announces system playlist update Mar 24 09:03:41 volumio-pi volumio[1014]: info: Ignoring MPD Status Update Mar 24 09:03:41 volumio-pi volumio[1014]: info: ------------------------------ 128ms Mar 24 09:03:41 volumio-pi volumio[1014]: info: sendMpdCommand addid "http://anhthy.duckdns.org:19999/Audio/4dc1dbd4708fff9786c45f59c55f45a7/stream.flac?static=true&mediaSourceId=4dc1dbd4708fff9786c45f59c55f45a7&tag=01b1938a065a5fa2233f223d8c21a247&t.flac" took 3 milliseconds Mar 24 09:03:41 volumio-pi volumio[1014]: verbose: MPD COMMAND [object Object] Mar 24 09:03:41 volumio-pi volumio[1014]: verbose: MPD COMMAND [object Object] Mar 24 09:03:41 volumio-pi volumio[1014]: verbose: MPD COMMAND [object Object] Mar 24 09:03:41 volumio-pi volumio[1014]: info: Mar 24 09:03:41 volumio-pi volumio[1014]: ---------------------------- MPD announces system playlist update Mar 24 09:03:41 volumio-pi volumio[1014]: info: Ignoring MPD Status Update Mar 24 09:03:41 volumio-pi volumio[1014]: info: Mar 24 09:03:41 volumio-pi volumio[1014]: ---------------------------- MPD announces system playlist update Mar 24 09:03:41 volumio-pi volumio[1014]: info: Ignoring MPD Status Update Mar 24 09:03:41 volumio-pi volumio[1014]: info: ------------------------------ 6ms Mar 24 09:03:41 volumio-pi volumio[1014]: info: ------------------------------ 3ms Mar 24 09:03:41 volumio-pi volumio[1014]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 24 09:03:41 volumio-pi volumio[1014]: verbose: ControllerMpd::sendMpdCommand play Mar 24 09:03:41 volumio-pi volumio[1014]: info: Mar 24 09:03:41 volumio-pi volumio[1014]: ---------------------------- MPD announces system playlist update Mar 24 09:03:41 volumio-pi volumio[1014]: info: Ignoring MPD Status Update Mar 24 09:03:41 volumio-pi volumio[1014]: info: ------------------------------ 4ms Mar 24 09:03:41 volumio-pi volumio[1014]: info: sendMpdCommand play took 3 milliseconds Mar 24 09:03:41 volumio-pi volumio[1014]: info: ------------------------------ 7ms Mar 24 09:03:41 volumio-pi volumio[1014]: info: [jellyfin-play]: Mark song Liszt - Hungarian Rhapsody No.2 In C Sharp Minor (Misha Dichter) as played by anhthy. Mar 24 09:03:42 volumio-pi volumio[1014]: info: CoreCommandRouter::volumioPlay Mar 24 09:03:42 volumio-pi volumio[1014]: info: CoreStateMachine::play index 8 Mar 24 09:03:42 volumio-pi volumio[1014]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 09:03:42 volumio-pi volumio[1014]: info: CoreStateMachine::stop Mar 24 09:03:42 volumio-pi volumio[1014]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 09:03:42 volumio-pi volumio[1014]: info: CoreStateMachine::play index undefined Mar 24 09:03:42 volumio-pi volumio[1014]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 09:03:42 volumio-pi volumio[1014]: info: CorePlayQueue::getTrack 8 Mar 24 09:03:42 volumio-pi volumio[1014]: info: CoreStateMachine::startPlaybackTimer Mar 24 09:03:42 volumio-pi volumio[1014]: info: CorePlayQueue::getTrack 8 Mar 24 09:03:42 volumio-pi volumio[1014]: info: [jellyfin-play] clearAddPlayTrack: jellyfin/anhthy@de59f0ff0f17402d8d2f3797278cbbc6/song@songId=4dc1dbd4708fff9786c45f59c55f45a7 Mar 24 09:03:42 volumio-pi volumio[1014]: info: [jellyfin-play] Stream URL for Liszt - Hungarian Rhapsody No.2 In C Sharp Minor (Misha Dichter): http://anhthy.duckdns.org:19999/Audio/4dc1dbd4708fff9786c45f59c55f45a7/stream.flac?static=true&mediaSourceId=4dc1dbd4708fff9786c45f59c55f45a7&tag=01b1938a065a5fa2233f223d8c21a247 Mar 24 09:03:42 volumio-pi volumio[1014]: verbose: ControllerMpd::sendMpdCommand stop Mar 24 09:03:42 volumio-pi volumio[1014]: info: Mar 24 09:03:42 volumio-pi volumio[1014]: ---------------------------- MPD announces state update: player Mar 24 09:03:42 volumio-pi volumio[1014]: info: sendMpdCommand stop took 2 milliseconds Mar 24 09:03:42 volumio-pi volumio[1014]: info: ControllerMpd::getState Mar 24 09:03:42 volumio-pi volumio[1014]: verbose: ControllerMpd::sendMpdCommand status Mar 24 09:03:42 volumio-pi volumio[1014]: verbose: ControllerMpd::sendMpdCommand clear Mar 24 09:03:42 volumio-pi volumio[1014]: info: Mar 24 09:03:42 volumio-pi volumio[1014]: ---------------------------- MPD announces state update: player Mar 24 09:03:42 volumio-pi volumio[1014]: info: ControllerMpd::getState Mar 24 09:03:42 volumio-pi volumio[1014]: verbose: ControllerMpd::sendMpdCommand status Mar 24 09:03:42 volumio-pi volumio[1014]: info: Mar 24 09:03:42 volumio-pi volumio[1014]: ---------------------------- MPD announces system playlist update Mar 24 09:03:42 volumio-pi volumio[1014]: info: Ignoring MPD Status Update Mar 24 09:03:42 volumio-pi volumio[1014]: info: sendMpdCommand status took 36 milliseconds Mar 24 09:03:42 volumio-pi volumio[1014]: info: sendMpdCommand clear took 33 milliseconds Mar 24 09:03:42 volumio-pi volumio[1014]: info: sendMpdCommand status took 31 milliseconds Mar 24 09:03:42 volumio-pi volumio[1014]: verbose: ControllerMpd::parseState Mar 24 09:03:42 volumio-pi volumio[1014]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 09:03:42 volumio-pi volumio[1014]: verbose: ControllerMpd::sendMpdCommand load "http://anhthy.duckdns.org:19999/Audio/4dc1dbd4708fff9786c45f59c55f45a7/stream.flac?static=true&mediaSourceId=4dc1dbd4708fff9786c45f59c55f45a7&tag=01b1938a065a5fa2233f223d8c21a247&t.flac" Mar 24 09:03:42 volumio-pi volumio[1014]: verbose: ControllerMpd::parseState Mar 24 09:03:42 volumio-pi volumio[1014]: info: ControllerMpd::pushState Mar 24 09:03:42 volumio-pi volumio[1014]: info: CoreCommandRouter::servicePushState Mar 24 09:03:42 volumio-pi volumio[1014]: info: CoreStateMachine::pushState Mar 24 09:03:42 volumio-pi volumio[1014]: info: CorePlayQueue::getTrack 8 Mar 24 09:03:42 volumio-pi volumio[1014]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 09:03:42 volumio-pi volumio[1014]: info: CoreCommandRouter::volumioPushState Mar 24 09:03:42 volumio-pi volumio[1014]: info: CorePlayQueue::getTrack 8 Mar 24 09:03:42 volumio-pi volumio[1014]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current jellyfin Received mpd Mar 24 09:03:42 volumio-pi volumio5-onboarding[1370]: time=2026-03-24T09:03:42.430+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.127:60954 @ 0x1f4ee10" state=STATUS_STOPPED positionMs=17 volume=81 Mar 24 09:03:42 volumio-pi volumio5-onboarding[1370]: time=2026-03-24T09:03:42.430+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.127:60954 @ 0x1f4ee10" id="jellyfin/anhthy@de59f0ff0f17402d8d2f3797278cbbc6/song@songId=4dc1dbd4708fff9786c45f59c55f45a7" title="Liszt - Hungarian Rhapsody No.2 In C Sharp Minor (Misha Dichter)" Mar 24 09:03:42 volumio-pi volumio[1014]: info: ------------------------------ 51ms Mar 24 09:03:42 volumio-pi volumio[1014]: info: Mar 24 09:03:42 volumio-pi volumio[1014]: ---------------------------- MPD announces system playlist update Mar 24 09:03:42 volumio-pi volumio[1014]: info: Ignoring MPD Status Update Mar 24 09:03:42 volumio-pi volumio[1014]: error: updateQueue error: null Mar 24 09:03:42 volumio-pi volumio[1014]: info: ------------------------------ 26ms Mar 24 09:03:42 volumio-pi volumio[1014]: info: sendMpdCommand playlistinfo took 24 milliseconds Mar 24 09:03:42 volumio-pi volumio[1014]: verbose: ControllerMpd::parseTrackInfo Mar 24 09:03:42 volumio-pi volumio[1014]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Mar 24 09:03:42 volumio-pi volumio[1014]: info: ------------------------------ 64ms Mar 24 09:03:44 volumio-pi volumio[1014]: verbose: ControllerMpd::sendMpdCommand addid "http://anhthy.duckdns.org:19999/Audio/4dc1dbd4708fff9786c45f59c55f45a7/stream.flac?static=true&mediaSourceId=4dc1dbd4708fff9786c45f59c55f45a7&tag=01b1938a065a5fa2233f223d8c21a247&t.flac" Mar 24 09:03:44 volumio-pi volumio[1014]: error: updateQueue error: null Mar 24 09:03:44 volumio-pi volumio[1014]: info: ------------------------------ 1888ms Mar 24 09:03:44 volumio-pi volumio[1014]: info: Mar 24 09:03:44 volumio-pi volumio[1014]: ---------------------------- MPD announces system playlist update Mar 24 09:03:44 volumio-pi volumio[1014]: info: Ignoring MPD Status Update Mar 24 09:03:44 volumio-pi volumio[1014]: info: sendMpdCommand addid "http://anhthy.duckdns.org:19999/Audio/4dc1dbd4708fff9786c45f59c55f45a7/stream.flac?static=true&mediaSourceId=4dc1dbd4708fff9786c45f59c55f45a7&tag=01b1938a065a5fa2233f223d8c21a247&t.flac" took 3 milliseconds Mar 24 09:03:44 volumio-pi volumio[1014]: verbose: MPD COMMAND [object Object] Mar 24 09:03:44 volumio-pi volumio[1014]: verbose: MPD COMMAND [object Object] Mar 24 09:03:44 volumio-pi volumio[1014]: verbose: MPD COMMAND [object Object] Mar 24 09:03:44 volumio-pi volumio[1014]: info: Mar 24 09:03:44 volumio-pi volumio[1014]: ---------------------------- MPD announces system playlist update Mar 24 09:03:44 volumio-pi volumio[1014]: info: Ignoring MPD Status Update Mar 24 09:03:44 volumio-pi volumio[1014]: info: ------------------------------ 4ms Mar 24 09:03:44 volumio-pi volumio[1014]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 24 09:03:44 volumio-pi volumio[1014]: verbose: ControllerMpd::sendMpdCommand play Mar 24 09:03:44 volumio-pi volumio[1014]: info: Mar 24 09:03:44 volumio-pi volumio[1014]: ---------------------------- MPD announces system playlist update Mar 24 09:03:44 volumio-pi volumio[1014]: info: Ignoring MPD Status Update Mar 24 09:03:44 volumio-pi volumio[1014]: info: ------------------------------ 5ms Mar 24 09:03:44 volumio-pi volumio[1014]: info: sendMpdCommand play took 5 milliseconds Mar 24 09:03:44 volumio-pi volumio[1014]: info: ------------------------------ 3ms Mar 24 09:03:44 volumio-pi volumio[1014]: info: Mar 24 09:03:44 volumio-pi volumio[1014]: ---------------------------- MPD announces system playlist update Mar 24 09:03:44 volumio-pi volumio[1014]: info: Ignoring MPD Status Update Mar 24 09:03:44 volumio-pi volumio[1014]: info: ------------------------------ 2ms Mar 24 09:03:44 volumio-pi volumio[1014]: info: [jellyfin-play]: Mark song Liszt - Hungarian Rhapsody No.2 In C Sharp Minor (Misha Dichter) as played by anhthy. Mar 24 09:03:44 volumio-pi volumio[1014]: info: Mar 24 09:03:44 volumio-pi volumio[1014]: ---------------------------- MPD announces state update: player Mar 24 09:03:44 volumio-pi volumio[1014]: info: ControllerMpd::getState Mar 24 09:03:44 volumio-pi volumio[1014]: verbose: ControllerMpd::sendMpdCommand status Mar 24 09:03:44 volumio-pi volumio[1014]: info: Mar 24 09:03:44 volumio-pi volumio[1014]: ---------------------------- MPD announces state update: player Mar 24 09:03:44 volumio-pi volumio[1014]: info: ControllerMpd::getState Mar 24 09:03:44 volumio-pi volumio[1014]: verbose: ControllerMpd::sendMpdCommand status Mar 24 09:03:44 volumio-pi volumio[1014]: info: Mar 24 09:03:44 volumio-pi volumio[1014]: ---------------------------- MPD announces state update: player Mar 24 09:03:44 volumio-pi volumio[1014]: info: sendMpdCommand status took 10 milliseconds Mar 24 09:03:44 volumio-pi volumio[1014]: info: ControllerMpd::getState Mar 24 09:03:44 volumio-pi volumio[1014]: verbose: ControllerMpd::sendMpdCommand status Mar 24 09:03:44 volumio-pi volumio[1014]: info: sendMpdCommand status took 9 milliseconds Mar 24 09:03:44 volumio-pi volumio[1014]: verbose: ControllerMpd::parseState Mar 24 09:03:44 volumio-pi volumio[1014]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 09:03:44 volumio-pi volumio[1014]: verbose: ControllerMpd::parseState Mar 24 09:03:44 volumio-pi volumio[1014]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 09:03:44 volumio-pi volumio[1014]: info: Mar 24 09:03:44 volumio-pi volumio[1014]: ---------------------------- MPD announces state update: player Mar 24 09:03:44 volumio-pi volumio[1014]: info: ControllerMpd::getState Mar 24 09:03:44 volumio-pi volumio[1014]: verbose: ControllerMpd::sendMpdCommand status Mar 24 09:03:44 volumio-pi volumio[1014]: info: sendMpdCommand status took 7 milliseconds Mar 24 09:03:44 volumio-pi volumio[1014]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 24 09:03:44 volumio-pi volumio[1014]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 24 09:03:44 volumio-pi volumio[1014]: verbose: ControllerMpd::parseState Mar 24 09:03:44 volumio-pi volumio[1014]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 09:03:44 volumio-pi volumio[1014]: verbose: ControllerMpd::parseTrackInfo Mar 24 09:03:44 volumio-pi volumio[1014]: verbose: ControllerMpd::parseTrackInfo Mar 24 09:03:44 volumio-pi volumio[1014]: info: ControllerMpd::pushState Mar 24 09:03:44 volumio-pi volumio[1014]: info: CoreCommandRouter::servicePushState Mar 24 09:03:44 volumio-pi volumio[1014]: info: CorePlayQueue::getTrack 8 Mar 24 09:03:44 volumio-pi volumio[1014]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":535,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Liszt - Hungarian Rhapsody No.2 In C Sharp Minor (Misha Dichter)","artist":"Various Artists","album":"Masters of the Piano (2CD) CD1","uri":"http://anhthy.duckdns.org:19999/Audio/4dc1dbd4708fff9786c45f59c55f45a7/stream.flac?static=true&mediaSourceId=4dc1dbd4708fff9786c45f59c55f45a7&tag=01b1938a065a5fa2233f223d8c21a247&t.flac","trackType":"flac"} Mar 24 09:03:44 volumio-pi volumio[1014]: verbose: CURRENT POSITION 8 Mar 24 09:03:44 volumio-pi volumio[1014]: info: CoreStateMachine::syncState stateService play Mar 24 09:03:44 volumio-pi volumio[1014]: info: CoreStateMachine::syncState currentStatus stop Mar 24 09:03:44 volumio-pi volumio[1014]: info: ControllerMpd::pushState Mar 24 09:03:44 volumio-pi volumio[1014]: info: CoreCommandRouter::servicePushState Mar 24 09:03:44 volumio-pi volumio[1014]: info: CorePlayQueue::getTrack 8 Mar 24 09:03:44 volumio-pi volumio[1014]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":535,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Liszt - Hungarian Rhapsody No.2 In C Sharp Minor (Misha Dichter)","artist":"Various Artists","album":"Masters of the Piano (2CD) CD1","uri":"http://anhthy.duckdns.org:19999/Audio/4dc1dbd4708fff9786c45f59c55f45a7/stream.flac?static=true&mediaSourceId=4dc1dbd4708fff9786c45f59c55f45a7&tag=01b1938a065a5fa2233f223d8c21a247&t.flac","trackType":"flac"} Mar 24 09:03:44 volumio-pi volumio[1014]: verbose: CURRENT POSITION 8 Mar 24 09:03:44 volumio-pi volumio[1014]: info: CoreStateMachine::syncState stateService play Mar 24 09:03:44 volumio-pi volumio[1014]: info: CoreStateMachine::syncState currentStatus play Mar 24 09:03:44 volumio-pi volumio[1014]: info: Received an update from plugin. extracting info from payload Mar 24 09:03:44 volumio-pi volumio[1014]: info: CoreStateMachine::pushState Mar 24 09:03:44 volumio-pi volumio[1014]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 09:03:44 volumio-pi volumio[1014]: info: CoreCommandRouter::volumioPushState Mar 24 09:03:44 volumio-pi volumio[1014]: info: CoreStateMachine::pushState Mar 24 09:03:44 volumio-pi volumio[1014]: info: CoreCommandRouter::volumioPushState Mar 24 09:03:44 volumio-pi volumio[1014]: info: ------------------------------ 39ms Mar 24 09:03:44 volumio-pi volumio5-onboarding[1370]: time=2026-03-24T09:03:44.443+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.127:60954 @ 0x1f4ee10" state=STATUS_PLAYING positionMs=0 volume=81 Mar 24 09:03:44 volumio-pi volumio5-onboarding[1370]: time=2026-03-24T09:03:44.447+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.127:60954 @ 0x1f4ee10" state=STATUS_PLAYING positionMs=0 volume=81 Mar 24 09:03:44 volumio-pi volumio5-onboarding[1370]: time=2026-03-24T09:03:44.448+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.127:60954 @ 0x1f4ee10" id="jellyfin/anhthy@de59f0ff0f17402d8d2f3797278cbbc6/song@songId=4dc1dbd4708fff9786c45f59c55f45a7" title="Liszt - Hungarian Rhapsody No.2 In C Sharp Minor (Misha Dichter)" Mar 24 09:03:44 volumio-pi volumio5-onboarding[1370]: time=2026-03-24T09:03:44.449+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.127:60954 @ 0x1f4ee10" id="jellyfin/anhthy@de59f0ff0f17402d8d2f3797278cbbc6/song@songId=4dc1dbd4708fff9786c45f59c55f45a7" title="Liszt - Hungarian Rhapsody No.2 In C Sharp Minor (Misha Dichter)" Mar 24 09:03:44 volumio-pi volumio[1014]: info: ------------------------------ 63ms Mar 24 09:03:44 volumio-pi volumio[1014]: info: sendMpdCommand status took 49 milliseconds Mar 24 09:03:44 volumio-pi volumio[1014]: info: sendMpdCommand playlistinfo took 47 milliseconds Mar 24 09:03:44 volumio-pi volumio[1014]: verbose: ControllerMpd::parseState Mar 24 09:03:44 volumio-pi volumio[1014]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 09:03:44 volumio-pi volumio[1014]: verbose: ControllerMpd::parseTrackInfo Mar 24 09:03:44 volumio-pi volumio[1014]: info: ControllerMpd::pushState Mar 24 09:03:44 volumio-pi volumio[1014]: info: CoreCommandRouter::servicePushState Mar 24 09:03:44 volumio-pi volumio[1014]: info: CorePlayQueue::getTrack 8 Mar 24 09:03:44 volumio-pi volumio[1014]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":535,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Liszt - Hungarian Rhapsody No.2 In C Sharp Minor (Misha Dichter)","artist":"Various Artists","album":"Masters of the Piano (2CD) CD1","uri":"http://anhthy.duckdns.org:19999/Audio/4dc1dbd4708fff9786c45f59c55f45a7/stream.flac?static=true&mediaSourceId=4dc1dbd4708fff9786c45f59c55f45a7&tag=01b1938a065a5fa2233f223d8c21a247&t.flac","trackType":"flac"} Mar 24 09:03:44 volumio-pi volumio[1014]: verbose: CURRENT POSITION 8 Mar 24 09:03:44 volumio-pi volumio[1014]: info: CoreStateMachine::syncState stateService play Mar 24 09:03:44 volumio-pi volumio[1014]: info: CoreStateMachine::syncState currentStatus play Mar 24 09:03:44 volumio-pi volumio[1014]: info: Received an update from plugin. extracting info from payload Mar 24 09:03:44 volumio-pi volumio[1014]: info: CoreStateMachine::pushState Mar 24 09:03:44 volumio-pi volumio[1014]: info: CoreCommandRouter::volumioPushState Mar 24 09:03:44 volumio-pi volumio[1014]: info: CoreStateMachine::pushState Mar 24 09:03:44 volumio-pi volumio[1014]: info: CoreCommandRouter::volumioPushState Mar 24 09:03:44 volumio-pi volumio5-onboarding[1370]: time=2026-03-24T09:03:44.490+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.127:60954 @ 0x1f4ee10" state=STATUS_PLAYING positionMs=0 volume=81 Mar 24 09:03:44 volumio-pi volumio5-onboarding[1370]: time=2026-03-24T09:03:44.492+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.127:60954 @ 0x1f4ee10" id="jellyfin/anhthy@de59f0ff0f17402d8d2f3797278cbbc6/song@songId=4dc1dbd4708fff9786c45f59c55f45a7" title="Liszt - Hungarian Rhapsody No.2 In C Sharp Minor (Misha Dichter)" Mar 24 09:03:44 volumio-pi volumio5-onboarding[1370]: time=2026-03-24T09:03:44.494+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.127:60954 @ 0x1f4ee10" state=STATUS_PLAYING positionMs=0 volume=81 Mar 24 09:03:44 volumio-pi volumio5-onboarding[1370]: time=2026-03-24T09:03:44.496+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.127:60954 @ 0x1f4ee10" id="jellyfin/anhthy@de59f0ff0f17402d8d2f3797278cbbc6/song@songId=4dc1dbd4708fff9786c45f59c55f45a7" title="Liszt - Hungarian Rhapsody No.2 In C Sharp Minor (Misha Dichter)" Mar 24 09:03:44 volumio-pi volumio[1014]: info: ------------------------------ 99ms Mar 24 09:03:44 volumio-pi volumio[1014]: info: Mar 24 09:03:44 volumio-pi volumio[1014]: ---------------------------- MPD announces system playlist update Mar 24 09:03:44 volumio-pi volumio[1014]: info: Ignoring MPD Status Update Mar 24 09:03:44 volumio-pi volumio[1014]: info: Mar 24 09:03:44 volumio-pi volumio[1014]: ---------------------------- MPD announces state update: player Mar 24 09:03:44 volumio-pi volumio[1014]: info: sendMpdCommand playlistinfo took 84 milliseconds Mar 24 09:03:44 volumio-pi volumio[1014]: info: ControllerMpd::getState Mar 24 09:03:44 volumio-pi volumio[1014]: verbose: ControllerMpd::sendMpdCommand status Mar 24 09:03:44 volumio-pi volumio[1014]: verbose: ControllerMpd::parseTrackInfo Mar 24 09:03:44 volumio-pi volumio[1014]: info: ControllerMpd::pushState Mar 24 09:03:44 volumio-pi volumio[1014]: info: CoreCommandRouter::servicePushState Mar 24 09:03:44 volumio-pi volumio[1014]: info: CorePlayQueue::getTrack 8 Mar 24 09:03:44 volumio-pi volumio[1014]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":535,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Liszt - Hungarian Rhapsody No.2 In C Sharp Minor (Misha Dichter)","artist":"Various Artists","album":"Masters of the Piano (2CD) CD1","uri":"http://anhthy.duckdns.org:19999/Audio/4dc1dbd4708fff9786c45f59c55f45a7/stream.flac?static=true&mediaSourceId=4dc1dbd4708fff9786c45f59c55f45a7&tag=01b1938a065a5fa2233f223d8c21a247&t.flac","trackType":"flac"} Mar 24 09:03:44 volumio-pi volumio[1014]: verbose: CURRENT POSITION 8 Mar 24 09:03:44 volumio-pi volumio[1014]: info: CoreStateMachine::syncState stateService play Mar 24 09:03:44 volumio-pi volumio[1014]: info: CoreStateMachine::syncState currentStatus play Mar 24 09:03:44 volumio-pi volumio[1014]: info: Received an update from plugin. extracting info from payload Mar 24 09:03:44 volumio-pi volumio[1014]: info: CoreStateMachine::pushState Mar 24 09:03:44 volumio-pi volumio[1014]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 09:03:44 volumio-pi volumio[1014]: info: CoreCommandRouter::volumioPushState Mar 24 09:03:44 volumio-pi volumio[1014]: info: CoreStateMachine::pushState Mar 24 09:03:44 volumio-pi volumio[1014]: info: CoreCommandRouter::volumioPushState Mar 24 09:03:44 volumio-pi volumio5-onboarding[1370]: time=2026-03-24T09:03:44.580+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.127:60954 @ 0x1f4ee10" state=STATUS_PLAYING positionMs=189 volume=81 Mar 24 09:03:44 volumio-pi volumio5-onboarding[1370]: time=2026-03-24T09:03:44.581+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.127:60954 @ 0x1f4ee10" id="jellyfin/anhthy@de59f0ff0f17402d8d2f3797278cbbc6/song@songId=4dc1dbd4708fff9786c45f59c55f45a7" title="Liszt - Hungarian Rhapsody No.2 In C Sharp Minor (Misha Dichter)" Mar 24 09:03:44 volumio-pi volumio5-onboarding[1370]: time=2026-03-24T09:03:44.583+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.127:60954 @ 0x1f4ee10" state=STATUS_PLAYING positionMs=189 volume=81 Mar 24 09:03:44 volumio-pi volumio5-onboarding[1370]: time=2026-03-24T09:03:44.584+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.127:60954 @ 0x1f4ee10" id="jellyfin/anhthy@de59f0ff0f17402d8d2f3797278cbbc6/song@songId=4dc1dbd4708fff9786c45f59c55f45a7" title="Liszt - Hungarian Rhapsody No.2 In C Sharp Minor (Misha Dichter)" Mar 24 09:03:44 volumio-pi volumio[1014]: info: ------------------------------ 180ms Mar 24 09:03:44 volumio-pi volumio[1014]: info: Mar 24 09:03:44 volumio-pi volumio[1014]: ---------------------------- MPD announces system playlist update Mar 24 09:03:44 volumio-pi volumio[1014]: info: Ignoring MPD Status Update Mar 24 09:03:44 volumio-pi volumio[1014]: info: Mar 24 09:03:44 volumio-pi volumio[1014]: ---------------------------- MPD announces state update: player Mar 24 09:03:44 volumio-pi volumio[1014]: info: ControllerMpd::getState Mar 24 09:03:44 volumio-pi volumio[1014]: verbose: ControllerMpd::sendMpdCommand status Mar 24 09:03:44 volumio-pi volumio[1014]: info: ------------------------------ 52ms Mar 24 09:03:44 volumio-pi volumio[1014]: info: sendMpdCommand status took 50 milliseconds Mar 24 09:03:44 volumio-pi volumio[1014]: verbose: ControllerMpd::parseState Mar 24 09:03:44 volumio-pi volumio[1014]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 09:03:44 volumio-pi volumio[1014]: info: ------------------------------ 27ms Mar 24 09:03:44 volumio-pi volumio[1014]: info: sendMpdCommand status took 26 milliseconds Mar 24 09:03:44 volumio-pi volumio[1014]: info: sendMpdCommand playlistinfo took 22 milliseconds Mar 24 09:03:44 volumio-pi volumio[1014]: verbose: ControllerMpd::parseState Mar 24 09:03:44 volumio-pi volumio[1014]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 09:03:44 volumio-pi volumio[1014]: verbose: ControllerMpd::parseTrackInfo Mar 24 09:03:44 volumio-pi volumio[1014]: info: ControllerMpd::pushState Mar 24 09:03:44 volumio-pi volumio[1014]: info: CoreCommandRouter::servicePushState Mar 24 09:03:44 volumio-pi volumio[1014]: info: CorePlayQueue::getTrack 8 Mar 24 09:03:44 volumio-pi volumio[1014]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":535,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"514 Kbps","isStreaming":false,"title":"Liszt - Hungarian Rhapsody No.2 In C Sharp Minor (Misha Dichter)","artist":"Various Artists","album":"Masters of the Piano (2CD) CD1","uri":"http://anhthy.duckdns.org:19999/Audio/4dc1dbd4708fff9786c45f59c55f45a7/stream.flac?static=true&mediaSourceId=4dc1dbd4708fff9786c45f59c55f45a7&tag=01b1938a065a5fa2233f223d8c21a247&t.flac","trackType":"flac"} Mar 24 09:03:44 volumio-pi volumio[1014]: verbose: CURRENT POSITION 8 Mar 24 09:03:44 volumio-pi volumio[1014]: info: CoreStateMachine::syncState stateService play Mar 24 09:03:44 volumio-pi volumio[1014]: info: CoreStateMachine::syncState currentStatus play Mar 24 09:03:44 volumio-pi volumio[1014]: info: Received an update from plugin. extracting info from payload Mar 24 09:03:44 volumio-pi volumio[1014]: info: CoreStateMachine::pushState Mar 24 09:03:44 volumio-pi volumio[1014]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 09:03:44 volumio-pi volumio[1014]: info: CoreCommandRouter::volumioPushState Mar 24 09:03:44 volumio-pi volumio[1014]: info: CoreStateMachine::pushState Mar 24 09:03:44 volumio-pi volumio[1014]: info: CoreCommandRouter::volumioPushState Mar 24 09:03:44 volumio-pi volumio5-onboarding[1370]: time=2026-03-24T09:03:44.654+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.127:60954 @ 0x1f4ee10" state=STATUS_PLAYING positionMs=263 volume=81 Mar 24 09:03:44 volumio-pi volumio5-onboarding[1370]: time=2026-03-24T09:03:44.658+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.127:60954 @ 0x1f4ee10" id="jellyfin/anhthy@de59f0ff0f17402d8d2f3797278cbbc6/song@songId=4dc1dbd4708fff9786c45f59c55f45a7" title="Liszt - Hungarian Rhapsody No.2 In C Sharp Minor (Misha Dichter)" Mar 24 09:03:44 volumio-pi volumio5-onboarding[1370]: time=2026-03-24T09:03:44.659+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.127:60954 @ 0x1f4ee10" state=STATUS_PLAYING positionMs=263 volume=81 Mar 24 09:03:44 volumio-pi volumio5-onboarding[1370]: time=2026-03-24T09:03:44.664+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.127:60954 @ 0x1f4ee10" id="jellyfin/anhthy@de59f0ff0f17402d8d2f3797278cbbc6/song@songId=4dc1dbd4708fff9786c45f59c55f45a7" title="Liszt - Hungarian Rhapsody No.2 In C Sharp Minor (Misha Dichter)" Mar 24 09:03:44 volumio-pi volumio[1014]: info: ------------------------------ 122ms Mar 24 09:03:44 volumio-pi volumio[1014]: info: sendMpdCommand playlistinfo took 50 milliseconds Mar 24 09:03:44 volumio-pi volumio[1014]: verbose: ControllerMpd::parseTrackInfo Mar 24 09:03:44 volumio-pi volumio[1014]: info: ControllerMpd::pushState Mar 24 09:03:44 volumio-pi volumio[1014]: info: CoreCommandRouter::servicePushState Mar 24 09:03:44 volumio-pi volumio[1014]: info: CorePlayQueue::getTrack 8 Mar 24 09:03:44 volumio-pi volumio[1014]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":535,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"448 Kbps","isStreaming":false,"title":"Liszt - Hungarian Rhapsody No.2 In C Sharp Minor (Misha Dichter)","artist":"Various Artists","album":"Masters of the Piano (2CD) CD1","uri":"http://anhthy.duckdns.org:19999/Audio/4dc1dbd4708fff9786c45f59c55f45a7/stream.flac?static=true&mediaSourceId=4dc1dbd4708fff9786c45f59c55f45a7&tag=01b1938a065a5fa2233f223d8c21a247&t.flac","trackType":"flac"} Mar 24 09:03:44 volumio-pi volumio[1014]: verbose: CURRENT POSITION 8 Mar 24 09:03:44 volumio-pi volumio[1014]: info: CoreStateMachine::syncState stateService play Mar 24 09:03:44 volumio-pi volumio[1014]: info: CoreStateMachine::syncState currentStatus play Mar 24 09:03:44 volumio-pi volumio[1014]: info: Received an update from plugin. extracting info from payload Mar 24 09:03:44 volumio-pi volumio[1014]: info: CoreStateMachine::pushState Mar 24 09:03:44 volumio-pi volumio[1014]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 09:03:44 volumio-pi volumio[1014]: info: CoreCommandRouter::volumioPushState Mar 24 09:03:44 volumio-pi volumio[1014]: info: CoreStateMachine::pushState Mar 24 09:03:44 volumio-pi volumio[1014]: info: CoreCommandRouter::volumioPushState Mar 24 09:03:44 volumio-pi volumio5-onboarding[1370]: time=2026-03-24T09:03:44.707+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.127:60954 @ 0x1f4ee10" state=STATUS_PLAYING positionMs=263 volume=81 Mar 24 09:03:44 volumio-pi volumio5-onboarding[1370]: time=2026-03-24T09:03:44.710+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.127:60954 @ 0x1f4ee10" state=STATUS_PLAYING positionMs=263 volume=81 Mar 24 09:03:44 volumio-pi volumio5-onboarding[1370]: time=2026-03-24T09:03:44.710+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.127:60954 @ 0x1f4ee10" id="jellyfin/anhthy@de59f0ff0f17402d8d2f3797278cbbc6/song@songId=4dc1dbd4708fff9786c45f59c55f45a7" title="Liszt - Hungarian Rhapsody No.2 In C Sharp Minor (Misha Dichter)" Mar 24 09:03:44 volumio-pi volumio5-onboarding[1370]: time=2026-03-24T09:03:44.711+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.127:60954 @ 0x1f4ee10" id="jellyfin/anhthy@de59f0ff0f17402d8d2f3797278cbbc6/song@songId=4dc1dbd4708fff9786c45f59c55f45a7" title="Liszt - Hungarian Rhapsody No.2 In C Sharp Minor (Misha Dichter)" Mar 24 09:03:44 volumio-pi volumio[1014]: info: ------------------------------ 126ms Mar 24 09:03:45 volumio-pi volumio[1014]: info: CoreCommandRouter::volumioPlay Mar 24 09:03:45 volumio-pi volumio[1014]: info: CoreStateMachine::play index 8 Mar 24 09:03:45 volumio-pi volumio[1014]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 09:03:45 volumio-pi volumio[1014]: info: CoreStateMachine::stop Mar 24 09:03:45 volumio-pi volumio[1014]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 09:03:45 volumio-pi volumio[1014]: info: CoreStateMachine::stPlaybackTimer Mar 24 09:03:45 volumio-pi volumio[1014]: info: CoreStateMachine::updateTrackBlock Mar 24 09:03:45 volumio-pi volumio[1014]: info: CorePlayQueue::getTrackBlock Mar 24 09:03:45 volumio-pi volumio[1014]: info: CoreStateMachine::pushState Mar 24 09:03:45 volumio-pi volumio[1014]: info: CorePlayQueue::getTrack 8 Mar 24 09:03:45 volumio-pi volumio[1014]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 09:03:45 volumio-pi volumio[1014]: info: CoreCommandRouter::volumioPushState Mar 24 09:03:45 volumio-pi volumio[1014]: info: CoreStateMachine::serviceStop Mar 24 09:03:45 volumio-pi volumio[1014]: info: CorePlayQueue::getTrack 8 Mar 24 09:03:45 volumio-pi volumio[1014]: info: CoreCommandRouter::serviceStop Mar 24 09:03:45 volumio-pi volumio[1014]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 24 09:03:45 volumio-pi volumio[1014]: info: ControllerMpd::stop Mar 24 09:03:45 volumio-pi volumio[1014]: verbose: ControllerMpd::sendMpdCommand stop Mar 24 09:03:45 volumio-pi volumio5-onboarding[1370]: time=2026-03-24T09:03:45.937+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.127:60954 @ 0x1f4ee10" state=STATUS_STOPPED positionMs=0 volume=81 Mar 24 09:03:45 volumio-pi volumio5-onboarding[1370]: time=2026-03-24T09:03:45.938+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.127:60954 @ 0x1f4ee10" id="jellyfin/anhthy@de59f0ff0f17402d8d2f3797278cbbc6/song@songId=4dc1dbd4708fff9786c45f59c55f45a7" title="Liszt - Hungarian Rhapsody No.2 In C Sharp Minor (Misha Dichter)" Mar 24 09:03:45 volumio-pi volumio[1014]: info: Mar 24 09:03:45 volumio-pi volumio[1014]: ---------------------------- MPD announces state update: player Mar 24 09:03:45 volumio-pi volumio[1014]: info: sendMpdCommand stop took 16 milliseconds Mar 24 09:03:45 volumio-pi volumio[1014]: info: ControllerMpd::getState Mar 24 09:03:45 volumio-pi volumio[1014]: verbose: ControllerMpd::sendMpdCommand status Mar 24 09:03:45 volumio-pi volumio[1014]: info: CoreStateMachine::play index undefined Mar 24 09:03:45 volumio-pi volumio[1014]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 09:03:45 volumio-pi volumio[1014]: info: CorePlayQueue::getTrack 8 Mar 24 09:03:45 volumio-pi volumio[1014]: info: CoreStateMachine::startPlaybackTimer Mar 24 09:03:45 volumio-pi volumio[1014]: info: CorePlayQueue::getTrack 8 Mar 24 09:03:45 volumio-pi volumio[1014]: info: [jellyfin-play] clearAddPlayTrack: jellyfin/anhthy@de59f0ff0f17402d8d2f3797278cbbc6/song@songId=4dc1dbd4708fff9786c45f59c55f45a7 Mar 24 09:03:45 volumio-pi volumio[1014]: info: sendMpdCommand status took 5 milliseconds Mar 24 09:03:45 volumio-pi volumio[1014]: verbose: ControllerMpd::parseState Mar 24 09:03:45 volumio-pi volumio[1014]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 09:03:45 volumio-pi volumio[1014]: info: Mar 24 09:03:45 volumio-pi volumio[1014]: ---------------------------- MPD announces state update: player Mar 24 09:03:45 volumio-pi volumio[1014]: info: ControllerMpd::getState Mar 24 09:03:45 volumio-pi volumio[1014]: verbose: ControllerMpd::sendMpdCommand status Mar 24 09:03:45 volumio-pi volumio[1014]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 24 09:03:45 volumio-pi volumio[1014]: info: sendMpdCommand status took 4 milliseconds Mar 24 09:03:45 volumio-pi volumio[1014]: verbose: ControllerMpd::parseTrackInfo Mar 24 09:03:45 volumio-pi volumio[1014]: verbose: ControllerMpd::parseState Mar 24 09:03:45 volumio-pi volumio[1014]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 09:03:45 volumio-pi volumio[1014]: info: ControllerMpd::pushState Mar 24 09:03:45 volumio-pi volumio[1014]: info: CoreCommandRouter::servicePushState Mar 24 09:03:45 volumio-pi volumio[1014]: info: CoreStateMachine::pushState Mar 24 09:03:45 volumio-pi volumio[1014]: info: CorePlayQueue::getTrack 8 Mar 24 09:03:45 volumio-pi volumio[1014]: info: CoreCommandRouter::volumioPushState Mar 24 09:03:45 volumio-pi volumio[1014]: info: CorePlayQueue::getTrack 8 Mar 24 09:03:45 volumio-pi volumio[1014]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current jellyfin Received mpd Mar 24 09:03:45 volumio-pi volumio5-onboarding[1370]: time=2026-03-24T09:03:45.974+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.127:60954 @ 0x1f4ee10" state=STATUS_STOPPED positionMs=0 volume=81 Mar 24 09:03:45 volumio-pi volumio5-onboarding[1370]: time=2026-03-24T09:03:45.975+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.127:60954 @ 0x1f4ee10" id="jellyfin/anhthy@de59f0ff0f17402d8d2f3797278cbbc6/song@songId=4dc1dbd4708fff9786c45f59c55f45a7" title="Liszt - Hungarian Rhapsody No.2 In C Sharp Minor (Misha Dichter)" Mar 24 09:03:45 volumio-pi volumio[1014]: info: ------------------------------ 29ms Mar 24 09:03:45 volumio-pi volumio[1014]: info: sendMpdCommand playlistinfo took 16 milliseconds Mar 24 09:03:45 volumio-pi volumio[1014]: verbose: ControllerMpd::parseTrackInfo Mar 24 09:03:45 volumio-pi volumio[1014]: info: ControllerMpd::pushState Mar 24 09:03:45 volumio-pi volumio[1014]: info: CoreCommandRouter::servicePushState Mar 24 09:03:45 volumio-pi volumio[1014]: info: CoreStateMachine::pushState Mar 24 09:03:45 volumio-pi volumio[1014]: info: CorePlayQueue::getTrack 8 Mar 24 09:03:45 volumio-pi volumio[1014]: info: CoreCommandRouter::volumioPushState Mar 24 09:03:45 volumio-pi volumio[1014]: info: CorePlayQueue::getTrack 8 Mar 24 09:03:45 volumio-pi volumio[1014]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current jellyfin Received mpd Mar 24 09:03:45 volumio-pi volumio5-onboarding[1370]: time=2026-03-24T09:03:45.991+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.10.127:60954 @ 0x1f4ee10" state=STATUS_STOPPED positionMs=0 volume=81 Mar 24 09:03:45 volumio-pi volumio5-onboarding[1370]: time=2026-03-24T09:03:45.991+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.10.127:60954 @ 0x1f4ee10" id="jellyfin/anhthy@de59f0ff0f17402d8d2f3797278cbbc6/song@songId=4dc1dbd4708fff9786c45f59c55f45a7" title="Liszt - Hungarian Rhapsody No.2 In C Sharp Minor (Misha Dichter)" Mar 24 09:03:45 volumio-pi volumio[1014]: info: ------------------------------ 38ms Mar 24 09:03:45 volumio-pi volumio[1014]: info: [jellyfin-play] Stream URL for Liszt - Hungarian Rhapsody No.2 In C Sharp Minor (Misha Dichter): http://anhthy.duckdns.org:19999/Audio/4dc1dbd4708fff9786c45f59c55f45a7/stream.flac?static=true&mediaSourceId=4dc1dbd4708fff9786c45f59c55f45a7&tag=01b1938a065a5fa2233f223d8c21a247 Mar 24 09:03:45 volumio-pi volumio[1014]: verbose: ControllerMpd::sendMpdCommand stop Mar 24 09:03:46 volumio-pi volumio[1014]: info: sendMpdCommand stop took 11 milliseconds Mar 24 09:03:46 volumio-pi volumio[1014]: verbose: ControllerMpd::sendMpdCommand clear Mar 24 09:03:46 volumio-pi volumio[1014]: info: Mar 24 09:03:46 volumio-pi volumio[1014]: ---------------------------- MPD announces system playlist update Mar 24 09:03:46 volumio-pi volumio[1014]: info: Ignoring MPD Status Update Mar 24 09:03:46 volumio-pi volumio[1014]: info: sendMpdCommand clear took 5 milliseconds Mar 24 09:03:46 volumio-pi volumio[1014]: verbose: ControllerMpd::sendMpdCommand load "http://anhthy.duckdns.org:19999/Audio/4dc1dbd4708fff9786c45f59c55f45a7/stream.flac?static=true&mediaSourceId=4dc1dbd4708fff9786c45f59c55f45a7&tag=01b1938a065a5fa2233f223d8c21a247&t.flac" Mar 24 09:03:46 volumio-pi volumio[1014]: info: Mar 24 09:03:46 volumio-pi volumio[1014]: ---------------------------- MPD announces system playlist update Mar 24 09:03:46 volumio-pi volumio[1014]: info: Ignoring MPD Status Update Mar 24 09:03:46 volumio-pi volumio[1014]: info: CoreCommandRouter::volumioPlay Mar 24 09:03:46 volumio-pi volumio[1014]: info: CoreStateMachine::play index 8 Mar 24 09:03:46 volumio-pi volumio[1014]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 09:03:46 volumio-pi volumio[1014]: info: CoreStateMachine::stop Mar 24 09:03:46 volumio-pi volumio[1014]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 09:03:46 volumio-pi volumio[1014]: info: CoreStateMachine::play index undefined Mar 24 09:03:46 volumio-pi volumio[1014]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 09:03:46 volumio-pi volumio[1014]: info: CorePlayQueue::getTrack 8 Mar 24 09:03:46 volumio-pi volumio[1014]: info: CoreStateMachine::startPlaybackTimer Mar 24 09:03:46 volumio-pi volumio[1014]: info: CorePlayQueue::getTrack 8 Mar 24 09:03:46 volumio-pi volumio[1014]: info: [jellyfin-play] clearAddPlayTrack: jellyfin/anhthy@de59f0ff0f17402d8d2f3797278cbbc6/song@songId=4dc1dbd4708fff9786c45f59c55f45a7 Mar 24 09:03:46 volumio-pi volumio[1014]: info: [jellyfin-play] Stream URL for Liszt - Hungarian Rhapsody No.2 In C Sharp Minor (Misha Dichter): http://anhthy.duckdns.org:19999/Audio/4dc1dbd4708fff9786c45f59c55f45a7/stream.flac?static=true&mediaSourceId=4dc1dbd4708fff9786c45f59c55f45a7&tag=01b1938a065a5fa2233f223d8c21a247 Mar 24 09:03:46 volumio-pi volumio[1014]: verbose: ControllerMpd::sendMpdCommand stop Mar 24 09:03:47 volumio-pi volumio[1014]: error: updateQueue error: null Mar 24 09:03:47 volumio-pi volumio[1014]: info: ------------------------------ 1456ms Mar 24 09:03:47 volumio-pi volumio[1014]: verbose: ControllerMpd::sendMpdCommand addid "http://anhthy.duckdns.org:19999/Audio/4dc1dbd4708fff9786c45f59c55f45a7/stream.flac?static=true&mediaSourceId=4dc1dbd4708fff9786c45f59c55f45a7&tag=01b1938a065a5fa2233f223d8c21a247&t.flac" Mar 24 09:03:47 volumio-pi volumio[1014]: error: updateQueue error: null Mar 24 09:03:47 volumio-pi volumio[1014]: info: ------------------------------ 1456ms Mar 24 09:03:47 volumio-pi volumio[1014]: info: sendMpdCommand stop took 1378 milliseconds Mar 24 09:03:47 volumio-pi volumio[1014]: verbose: ControllerMpd::sendMpdCommand clear Mar 24 09:03:47 volumio-pi volumio[1014]: info: Mar 24 09:03:47 volumio-pi volumio[1014]: ---------------------------- MPD announces system playlist update Mar 24 09:03:47 volumio-pi volumio[1014]: info: Ignoring MPD Status Update Mar 24 09:03:47 volumio-pi volumio[1014]: info: Mar 24 09:03:47 volumio-pi volumio[1014]: ---------------------------- MPD announces system playlist update Mar 24 09:03:47 volumio-pi volumio[1014]: info: Ignoring MPD Status Update Mar 24 09:03:47 volumio-pi volumio[1014]: info: sendMpdCommand addid "http://anhthy.duckdns.org:19999/Audio/4dc1dbd4708fff9786c45f59c55f45a7/stream.flac?static=true&mediaSourceId=4dc1dbd4708fff9786c45f59c55f45a7&tag=01b1938a065a5fa2233f223d8c21a247&t.flac" took 5 milliseconds Mar 24 09:03:47 volumio-pi volumio[1014]: info: sendMpdCommand clear took 3 milliseconds Mar 24 09:03:47 volumio-pi volumio[1014]: verbose: ControllerMpd::sendMpdCommand load "http://anhthy.duckdns.org:19999/Audio/4dc1dbd4708fff9786c45f59c55f45a7/stream.flac?static=true&mediaSourceId=4dc1dbd4708fff9786c45f59c55f45a7&tag=01b1938a065a5fa2233f223d8c21a247&t.flac" Mar 24 09:03:47 volumio-pi volumio[1014]: verbose: MPD COMMAND [object Object] Mar 24 09:03:47 volumio-pi volumio[1014]: verbose: MPD COMMAND [object Object] Mar 24 09:03:47 volumio-pi volumio[1014]: verbose: MPD COMMAND [object Object] Mar 24 09:03:47 volumio-pi volumio[1014]: info: Mar 24 09:03:47 volumio-pi volumio[1014]: ---------------------------- MPD announces system playlist update Mar 24 09:03:47 volumio-pi volumio[1014]: info: Ignoring MPD Status Update Mar 24 09:03:47 volumio-pi volumio[1014]: error: updateQueue error: null Mar 24 09:03:47 volumio-pi volumio[1014]: error: updateQueue error: null Mar 24 09:03:47 volumio-pi volumio[1014]: info: ------------------------------ 7ms Mar 24 09:03:47 volumio-pi volumio[1014]: info: ------------------------------ 6ms Mar 24 09:03:47 volumio-pi volumio[1014]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 24 09:03:47 volumio-pi volumio[1014]: Error: [50@0] {addtagid} No such song Mar 24 09:03:47 volumio-pi volumio[1014]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:63:17) Mar 24 09:03:47 volumio-pi volumio[1014]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Mar 24 09:03:47 volumio-pi volumio[1014]: at Socket.emit (node:events:514:28) Mar 24 09:03:47 volumio-pi volumio[1014]: at addChunk (node:internal/streams/readable:343:12) Mar 24 09:03:47 volumio-pi volumio[1014]: at readableAddChunk (node:internal/streams/readable:312:11) Mar 24 09:03:47 volumio-pi volumio[1014]: at Readable.push (node:internal/streams/readable:253:10) Mar 24 09:03:47 volumio-pi volumio[1014]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Mar 24 09:03:47 volumio-pi volumio[1014]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 24 09:03:50 volumio-pi sudo[3967]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-24 09:02' Mar 24 09:03:50 volumio-pi sudo[3967]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="dc61260dec5515dafd2b634881860b4c46c919ff" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Mar 6 16:46:58 UTC 2026" VOLUMIO_VERSION="4.103" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="aa741395b8bfc648ff5d04e312f53d2c"