Mar 04 14:57:13 volumio5 volumio[1383]: info: CALLMETHOD: user_interface peppy_screensaver savePeppyMeterConf [object Object] Mar 04 14:57:13 volumio5 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: peppy_screensaver , savePeppyMeterConf Mar 04 14:57:17 volumio5 volumio[1383]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 04 14:57:27 volumio5 systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Mar 04 14:57:29 volumio5 volumio[1383]: info: CALLMETHOD: user_interface peppy_screensaver saveVUMeterConf [object Object] Mar 04 14:57:29 volumio5 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: peppy_screensaver , saveVUMeterConf Mar 04 14:57:30 volumio5 volumio[1383]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 04 14:58:17 volumio5 volumio[1383]: info: CoreCommandRouter::volumioPlay Mar 04 14:58:17 volumio5 volumio[1383]: info: CoreStateMachine::play index undefined Mar 04 14:58:17 volumio5 volumio[1383]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 14:58:17 volumio5 volumio[1383]: info: CoreCommandRouter::volumioPlay Mar 04 14:58:17 volumio5 volumio[1383]: info: CoreStateMachine::play index undefined Mar 04 14:58:17 volumio5 volumio[1383]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 14:58:23 volumio5 volumio[1383]: info: CoreCommandRouter::volumioGetState Mar 04 14:58:23 volumio5 volumio[1383]: info: CorePlayQueue::getTrack 0 Mar 04 14:58:23 volumio5 volumio[1383]: info: CoreCommandRouter::volumioGetQueue Mar 04 14:58:23 volumio5 volumio[1383]: info: CoreStateMachine::getQueue Mar 04 14:58:23 volumio5 volumio[1383]: info: CorePlayQueue::getQueue Mar 04 14:58:23 volumio5 volumio[1383]: info: Preload queue cleared Mar 04 14:58:24 volumio5 volumio[1383]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 04 14:58:24 volumio5 volumio[1383]: info: CoreStateMachine::ClearQueue Mar 04 14:58:24 volumio5 volumio[1383]: info: CoreStateMachine::stop Mar 04 14:58:24 volumio5 volumio[1383]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 14:58:24 volumio5 volumio[1383]: info: CoreStateMachine::updateTrackBlock Mar 04 14:58:24 volumio5 volumio[1383]: info: CorePlayQueue::getTrackBlock Mar 04 14:58:24 volumio5 volumio[1383]: info: CoreStateMachine::stPlaybackTimer Mar 04 14:58:24 volumio5 volumio[1383]: info: CoreStateMachine::pushState Mar 04 14:58:24 volumio5 volumio[1383]: info: CorePlayQueue::getTrack 0 Mar 04 14:58:24 volumio5 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 14:58:24 volumio5 volumio[1383]: info: CoreCommandRouter::volumioPushState Mar 04 14:58:24 volumio5 volumio[1383]: info: CoreStateMachine::serviceStop Mar 04 14:58:24 volumio5 volumio[1383]: info: CorePlayQueue::getTrack 0 Mar 04 14:58:24 volumio5 volumio[1383]: info: CoreCommandRouter::serviceStop Mar 04 14:58:25 volumio5 volumio[1383]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 04 14:58:25 volumio5 volumio[1383]: info: ControllerMpd::stop Mar 04 14:58:25 volumio5 volumio[1383]: verbose: ControllerMpd::sendMpdCommand stop Mar 04 14:58:25 volumio5 volumio[1383]: info: CorePlayQueue::clearPlayQueue Mar 04 14:58:25 volumio5 volumio[1383]: info: CorePlayQueue::saveQueue Mar 04 14:58:25 volumio5 volumio[1383]: info: CoreCommandRouter::volumioPushQueue Mar 04 14:58:25 volumio5 volumio[1383]: info: CoreStateMachine::addQueueItems Mar 04 14:58:25 volumio5 volumio[1383]: info: CorePlayQueue::addQueueItems Mar 04 14:58:25 volumio5 volumio[1383]: info: Preload queue cleared Mar 04 14:58:25 volumio5 volumio[1383]: info: Adding Item to queue: jellyfin/sinbad@318bf57d8a344079871c9904cc514f4b/songs@albumId=dace143c95a4907ff251c1f976c071ed Mar 04 14:58:25 volumio5 volumio[1383]: info: Using cached record of: jellyfin/sinbad@318bf57d8a344079871c9904cc514f4b/songs@albumId=dace143c95a4907ff251c1f976c071ed Mar 04 14:58:25 volumio5 volumio[1383]: info: Preload queue cleared Mar 04 14:58:25 volumio5 volumio[1383]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 04 14:58:25 volumio5 volumio[1383]: info: CoreStateMachine::ClearQueue Mar 04 14:58:25 volumio5 volumio[1383]: info: CoreStateMachine::stop Mar 04 14:58:25 volumio5 volumio[1383]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 14:58:25 volumio5 volumio[1383]: info: CorePlayQueue::clearPlayQueue Mar 04 14:58:25 volumio5 volumio[1383]: info: CorePlayQueue::saveQueue Mar 04 14:58:25 volumio5 volumio[1383]: info: CoreCommandRouter::volumioPushQueue Mar 04 14:58:25 volumio5 volumio[1383]: info: CoreStateMachine::addQueueItems Mar 04 14:58:25 volumio5 volumio[1383]: info: CorePlayQueue::addQueueItems Mar 04 14:58:25 volumio5 volumio[1383]: info: Preload queue cleared Mar 04 14:58:25 volumio5 volumio[1383]: info: Adding Item to queue: jellyfin/sinbad@318bf57d8a344079871c9904cc514f4b/songs@albumId=ac630d311bc43d4cf43ecf467e7ed311 Mar 04 14:58:25 volumio5 volumio[1383]: info: Exploding uri jellyfin/sinbad@318bf57d8a344079871c9904cc514f4b/songs@albumId=ac630d311bc43d4cf43ecf467e7ed311 in service jellyfin Mar 04 14:58:25 volumio5 volumio[1383]: info: [jellyfin-browse] explodeUri: jellyfin/sinbad@318bf57d8a344079871c9904cc514f4b/songs@albumId=ac630d311bc43d4cf43ecf467e7ed311 Mar 04 14:58:25 volumio5 volumio[1383]: info: CorePlayQueue::getTrack 0 Mar 04 14:58:25 volumio5 volumio[1383]: info: CorePlayQueue::getTrack 0 Mar 04 14:58:25 volumio5 volumio[1383]: info: CoreCommandRouter::volumioPushQueue Mar 04 14:58:25 volumio5 volumio[1383]: info: CorePlayQueue::saveQueue Mar 04 14:58:25 volumio5 systemd[1]: setdatetime-helper.service: Deactivated successfully. Mar 04 14:58:25 volumio5 volumio[1383]: info: CoreStateMachine::updateTrackBlock Mar 04 14:58:25 volumio5 volumio[1383]: info: CorePlayQueue::getTrackBlock Mar 04 14:58:25 volumio5 volumio[1383]: info: CoreCommandRouter::volumioPlay Mar 04 14:58:25 volumio5 volumio[1383]: info: CoreStateMachine::play index 0 Mar 04 14:58:25 volumio5 volumio[1383]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 14:58:25 volumio5 volumio[1383]: info: CoreStateMachine::stop Mar 04 14:58:25 volumio5 volumio[1383]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 14:58:25 volumio5 volumio[1383]: info: CoreStateMachine::play index undefined Mar 04 14:58:25 volumio5 volumio[1383]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 14:58:25 volumio5 volumio[1383]: info: CorePlayQueue::getTrack 0 Mar 04 14:58:25 volumio5 volumio[1383]: info: CoreStateMachine::startPlaybackTimer Mar 04 14:58:25 volumio5 volumio[1383]: info: CorePlayQueue::getTrack 0 Mar 04 14:58:26 volumio5 volumio[1383]: info: [jellyfin-play] clearAddPlayTrack: jellyfin/sinbad@318bf57d8a344079871c9904cc514f4b/song@songId=ccadb5915e701780e34841eae6035760 Mar 04 14:58:26 volumio5 systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Mar 04 14:58:26 volumio5 volumio[1383]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"e4a32306937b48ef8e105912a4414535","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"parentId":"ac630d311bc43d4cf43ecf467e7ed311","startIndex":0,"sortBy":["SortName"],"sortOrder":["Ascending"],"fields":["MediaSources"],"includeItemTypes":["Audio"]} Mar 04 14:58:26 volumio5 systemd[1]: setdatetime-helper.service: Consumed 1.386s CPU time. Mar 04 14:58:26 volumio5 volumio[1383]: info: Discovery: Disconnected from remote: 192.168.1.123 Mar 04 14:58:26 volumio5 volumio[1383]: info: Discovery: Disconnected from remote: 192.168.1.123 Mar 04 14:58:26 volumio5 volumio[1383]: info: Discovery: Disconnected from remote: 192.168.1.123 Mar 04 14:58:26 volumio5 volumio[1383]: info: Discovery: Disconnected from remote: 192.168.1.123 Mar 04 14:58:26 volumio5 volumio[1383]: info: Discovery: Disconnected from remote: 192.168.1.123 Mar 04 14:58:26 volumio5 volumio[1383]: info: Discovery: Disconnected from remote: 192.168.1.123 Mar 04 14:58:27 volumio5 volumio[1383]: info: peppy_screensaver: pushState - status=stop service=jellyfin volatile=false Mar 04 14:58:27 volumio5 volumio[1383]: info: ControllerMpd::getState Mar 04 14:58:27 volumio5 volumio[1383]: verbose: ControllerMpd::sendMpdCommand status Mar 04 14:58:27 volumio5 volumio[1383]: info: Mar 04 14:58:27 volumio5 volumio[1383]: ---------------------------- MPD announces state update: player Mar 04 14:58:27 volumio5 volumio[1383]: info: sendMpdCommand stop took 2119 milliseconds Mar 04 14:58:27 volumio5 volumio[1383]: info: ControllerMpd::getState Mar 04 14:58:27 volumio5 volumio[1383]: verbose: ControllerMpd::sendMpdCommand status Mar 04 14:58:27 volumio5 volumio[1383]: info: Mar 04 14:58:27 volumio5 volumio[1383]: ---------------------------- MPD announces state update: player Mar 04 14:58:27 volumio5 volumio[1383]: info: ControllerMpd::getState Mar 04 14:58:27 volumio5 volumio[1383]: verbose: ControllerMpd::sendMpdCommand status Mar 04 14:58:27 volumio5 volumio[1383]: info: peppy_screensaver: Start PeppyMeter Mar 04 14:58:27 volumio5 volumio[1383]: info: peppy_screensaver: Start PeppyMeter Mar 04 14:58:27 volumio5 volumio[1383]: info: peppy_screensaver: Start PeppyMeter Mar 04 14:58:27 volumio5 volumio[1383]: info: peppy_screensaver: Start PeppyMeter Mar 04 14:58:27 volumio5 volumio[1383]: info: peppy_screensaver: Start PeppyMeter Mar 04 14:58:27 volumio5 volumio[1383]: info: peppy_screensaver: Start PeppyMeter Mar 04 14:58:27 volumio5 volumio[1383]: info: peppy_screensaver: Start PeppyMeter Mar 04 14:58:27 volumio5 volumio[1383]: info: peppy_screensaver: Start PeppyMeter Mar 04 14:58:27 volumio5 volumio[1383]: info: peppy_screensaver: Start PeppyMeter Mar 04 14:58:27 volumio5 volumio[1383]: info: peppy_screensaver: Start PeppyMeter Mar 04 14:58:27 volumio5 volumio[1383]: info: peppy_screensaver: Start PeppyMeter Mar 04 14:58:27 volumio5 volumio[1383]: info: peppy_screensaver: Start PeppyMeter Mar 04 14:58:27 volumio5 volumio[1383]: info: peppy_screensaver: Start PeppyMeter Mar 04 14:58:27 volumio5 volumio[1383]: info: peppy_screensaver: Start PeppyMeter Mar 04 14:58:27 volumio5 volumio[1383]: info: peppy_screensaver: Start PeppyMeter Mar 04 14:58:27 volumio5 volumio[1383]: info: peppy_screensaver: Start PeppyMeter Mar 04 14:58:27 volumio5 volumio[1383]: info: peppy_screensaver: Start PeppyMeter Mar 04 14:58:27 volumio5 volumio[1383]: info: peppy_screensaver: Start PeppyMeter Mar 04 14:58:27 volumio5 volumio[1383]: info: peppy_screensaver: Start PeppyMeter Mar 04 14:58:27 volumio5 volumio[1383]: info: sendMpdCommand status took 147 milliseconds Mar 04 14:58:27 volumio5 volumio[1383]: info: sendMpdCommand status took 146 milliseconds Mar 04 14:58:27 volumio5 volumio[1383]: info: sendMpdCommand status took 113 milliseconds Mar 04 14:58:27 volumio5 volumio[1383]: verbose: ControllerMpd::parseState Mar 04 14:58:27 volumio5 volumio[1383]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 14:58:27 volumio5 volumio[1383]: verbose: ControllerMpd::parseState Mar 04 14:58:27 volumio5 volumio[1383]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 14:58:27 volumio5 volumio[1383]: verbose: ControllerMpd::parseState Mar 04 14:58:27 volumio5 volumio[1383]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 14:58:27 volumio5 volumio[1383]: info: sendMpdCommand playlistinfo took 241 milliseconds Mar 04 14:58:27 volumio5 volumio[1383]: info: sendMpdCommand playlistinfo took 242 milliseconds Mar 04 14:58:27 volumio5 volumio[1383]: info: sendMpdCommand playlistinfo took 242 milliseconds Mar 04 14:58:27 volumio5 volumio[1383]: verbose: ControllerMpd::parseTrackInfo Mar 04 14:58:27 volumio5 volumio[1383]: verbose: ControllerMpd::parseTrackInfo Mar 04 14:58:27 volumio5 volumio[1383]: verbose: ControllerMpd::parseTrackInfo Mar 04 14:58:27 volumio5 volumio[1383]: info: ControllerMpd::pushState Mar 04 14:58:27 volumio5 volumio[1383]: info: CoreCommandRouter::servicePushState Mar 04 14:58:27 volumio5 volumio[1383]: info: CoreStateMachine::pushState Mar 04 14:58:27 volumio5 volumio[1383]: info: CorePlayQueue::getTrack 0 Mar 04 14:58:27 volumio5 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 14:58:27 volumio5 volumio[1383]: info: CoreCommandRouter::volumioPushState Mar 04 14:58:27 volumio5 volumio[1383]: info: CorePlayQueue::getTrack 0 Mar 04 14:58:27 volumio5 volumio[1383]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current jellyfin Received mpd Mar 04 14:58:27 volumio5 volumio[1383]: info: ControllerMpd::pushState Mar 04 14:58:27 volumio5 volumio[1383]: info: CoreCommandRouter::servicePushState Mar 04 14:58:27 volumio5 volumio[1383]: info: CoreStateMachine::pushState Mar 04 14:58:27 volumio5 volumio[1383]: info: CorePlayQueue::getTrack 0 Mar 04 14:58:27 volumio5 volumio[1383]: info: CoreCommandRouter::volumioPushState Mar 04 14:58:27 volumio5 volumio[1383]: info: CorePlayQueue::getTrack 0 Mar 04 14:58:27 volumio5 volumio[1383]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current jellyfin Received mpd Mar 04 14:58:27 volumio5 volumio[1383]: info: ------------------------------ 396ms Mar 04 14:58:27 volumio5 volumio[1383]: info: ------------------------------ 396ms Mar 04 14:58:27 volumio5 volumio[1383]: info: Pushing Favourites {"favourite":false} Mar 04 14:58:27 volumio5 volumio[1383]: info: peppy_screensaver: pushState - status=stop service=jellyfin volatile=false Mar 04 14:58:27 volumio5 volumio[1383]: info: peppy_screensaver: pushState - status=stop service=jellyfin volatile=false Mar 04 14:58:27 volumio5 volumio[1383]: info: [jellyfin-play]: Reported 'stop' for song: Dark in This Room (at 181448 ms) Mar 04 14:58:27 volumio5 volumio[1383]: info: [jellyfin-play] Stream URL for Dark in This Room: http://truenas.local:30013/Audio/ccadb5915e701780e34841eae6035760/stream.mp3?static=true&mediaSourceId=ccadb5915e701780e34841eae6035760&tag=9440390df4eee458ec6a7bb16ab7d4a9 Mar 04 14:58:27 volumio5 volumio[1383]: verbose: ControllerMpd::sendMpdCommand stop Mar 04 14:58:27 volumio5 volumio[1383]: info: sendMpdCommand stop took 1 milliseconds Mar 04 14:58:27 volumio5 volumio[1383]: verbose: ControllerMpd::sendMpdCommand clear Mar 04 14:58:27 volumio5 volumio[1383]: info: Mar 04 14:58:27 volumio5 volumio[1383]: ---------------------------- MPD announces system playlist update Mar 04 14:58:27 volumio5 volumio[1383]: info: Ignoring MPD Status Update Mar 04 14:58:27 volumio5 volumio[1383]: info: sendMpdCommand clear took 27 milliseconds Mar 04 14:58:27 volumio5 volumio[1383]: verbose: ControllerMpd::sendMpdCommand load "http://truenas.local:30013/Audio/ccadb5915e701780e34841eae6035760/stream.mp3?static=true&mediaSourceId=ccadb5915e701780e34841eae6035760&tag=9440390df4eee458ec6a7bb16ab7d4a9&t.mp3" Mar 04 14:58:27 volumio5 volumio[1383]: info: Mar 04 14:58:27 volumio5 volumio[1383]: ---------------------------- MPD announces system playlist update Mar 04 14:58:27 volumio5 volumio[1383]: info: Ignoring MPD Status Update Mar 04 14:58:27 volumio5 volumio[1383]: error: updateQueue error: null Mar 04 14:58:27 volumio5 volumio[1383]: info: ------------------------------ 2ms Mar 04 14:58:27 volumio5 volumio[1383]: info: Discovery: Connected to remote: 192.168.1.123 Mar 04 14:58:27 volumio5 volumio[1383]: error: updateQueue error: null Mar 04 14:58:27 volumio5 volumio[1383]: verbose: ControllerMpd::sendMpdCommand addid "http://truenas.local:30013/Audio/ccadb5915e701780e34841eae6035760/stream.mp3?static=true&mediaSourceId=ccadb5915e701780e34841eae6035760&tag=9440390df4eee458ec6a7bb16ab7d4a9&t.mp3" Mar 04 14:58:27 volumio5 volumio[1383]: info: ------------------------------ 221ms Mar 04 14:58:27 volumio5 volumio[1383]: info: Mar 04 14:58:27 volumio5 volumio[1383]: ---------------------------- MPD announces system playlist update Mar 04 14:58:27 volumio5 volumio[1383]: info: Ignoring MPD Status Update Mar 04 14:58:27 volumio5 volumio[1383]: info: sendMpdCommand addid "http://truenas.local:30013/Audio/ccadb5915e701780e34841eae6035760/stream.mp3?static=true&mediaSourceId=ccadb5915e701780e34841eae6035760&tag=9440390df4eee458ec6a7bb16ab7d4a9&t.mp3" took 23 milliseconds Mar 04 14:58:27 volumio5 volumio[1383]: verbose: MPD COMMAND [object Object] Mar 04 14:58:27 volumio5 volumio[1383]: verbose: MPD COMMAND [object Object] Mar 04 14:58:27 volumio5 volumio[1383]: verbose: MPD COMMAND [object Object] Mar 04 14:58:27 volumio5 volumio[1383]: info: Mar 04 14:58:27 volumio5 volumio[1383]: ---------------------------- MPD announces system playlist update Mar 04 14:58:27 volumio5 volumio[1383]: info: Ignoring MPD Status Update Mar 04 14:58:27 volumio5 volumio[1383]: info: Mar 04 14:58:27 volumio5 volumio[1383]: ---------------------------- MPD announces system playlist update Mar 04 14:58:27 volumio5 volumio[1383]: info: Ignoring MPD Status Update Mar 04 14:58:27 volumio5 volumio[1383]: info: ------------------------------ 2ms Mar 04 14:58:27 volumio5 volumio[1383]: info: ------------------------------ 1ms Mar 04 14:58:27 volumio5 volumio[1383]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 04 14:58:27 volumio5 volumio[1383]: verbose: ControllerMpd::sendMpdCommand play Mar 04 14:58:27 volumio5 volumio[1383]: info: ------------------------------ 1ms Mar 04 14:58:27 volumio5 volumio[1383]: info: Mar 04 14:58:27 volumio5 volumio[1383]: ---------------------------- MPD announces system playlist update Mar 04 14:58:27 volumio5 volumio[1383]: info: Ignoring MPD Status Update Mar 04 14:58:27 volumio5 volumio[1383]: info: sendMpdCommand play took 0 milliseconds Mar 04 14:58:27 volumio5 volumio[1383]: info: ------------------------------ 1ms Mar 04 14:58:27 volumio5 volumio[1383]: info: [jellyfin-play]: Mark song Dark in This Room as played by sinbad. Mar 04 14:58:28 volumio5 volumio[1383]: verbose: [jellyfin] getItemsFromAPI(): http://truenas.local:30013/Items?userId=e4a32306937b48ef8e105912a4414535&startIndex=0&recursive=true&sortOrder=Ascending&parentId=ac630d311bc43d4cf43ecf467e7ed311&fields=MediaSources&includeItemTypes=Audio&sortBy=SortName&imageTypeLimit=1&enableImageTypes=Primary Mar 04 14:58:28 volumio5 volumio[1383]: info: CoreCommandRouter::volumioPushQueue Mar 04 14:58:28 volumio5 volumio[1383]: info: CorePlayQueue::saveQueue Mar 04 14:58:28 volumio5 volumio[1383]: info: CoreStateMachine::updateTrackBlock Mar 04 14:58:28 volumio5 volumio[1383]: info: CorePlayQueue::getTrackBlock Mar 04 14:58:28 volumio5 volumio[1383]: info: CoreCommandRouter::volumioPlay Mar 04 14:58:28 volumio5 volumio[1383]: info: CoreStateMachine::play index 0 Mar 04 14:58:28 volumio5 volumio[1383]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 14:58:28 volumio5 volumio[1383]: info: CoreStateMachine::stop Mar 04 14:58:28 volumio5 volumio[1383]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 14:58:28 volumio5 volumio[1383]: info: CoreStateMachine::play index undefined Mar 04 14:58:28 volumio5 volumio[1383]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 14:58:28 volumio5 volumio[1383]: info: CorePlayQueue::getTrack 0 Mar 04 14:58:28 volumio5 volumio[1383]: info: CoreStateMachine::startPlaybackTimer Mar 04 14:58:28 volumio5 volumio[1383]: info: CorePlayQueue::getTrack 0 Mar 04 14:58:28 volumio5 volumio[1383]: info: [jellyfin-play] clearAddPlayTrack: jellyfin/sinbad@318bf57d8a344079871c9904cc514f4b/song@songId=ccadb5915e701780e34841eae6035760 Mar 04 14:58:28 volumio5 volumio[1383]: info: [jellyfin-play] Stream URL for Dark in This Room: http://truenas.local:30013/Audio/ccadb5915e701780e34841eae6035760/stream.mp3?static=true&mediaSourceId=ccadb5915e701780e34841eae6035760&tag=9440390df4eee458ec6a7bb16ab7d4a9 Mar 04 14:58:28 volumio5 volumio[1383]: verbose: ControllerMpd::sendMpdCommand stop Mar 04 14:58:28 volumio5 volumio[1383]: info: ControllerMpd::getState Mar 04 14:58:28 volumio5 volumio[1383]: verbose: ControllerMpd::sendMpdCommand status Mar 04 14:58:28 volumio5 volumio[1383]: info: Mar 04 14:58:28 volumio5 volumio[1383]: ---------------------------- MPD announces state update: player Mar 04 14:58:28 volumio5 volumio[1383]: info: sendMpdCommand stop took 60 milliseconds Mar 04 14:58:28 volumio5 volumio[1383]: info: ControllerMpd::getState Mar 04 14:58:28 volumio5 volumio[1383]: verbose: ControllerMpd::sendMpdCommand status Mar 04 14:58:28 volumio5 volumio[1383]: verbose: ControllerMpd::sendMpdCommand clear Mar 04 14:58:28 volumio5 volumio[1383]: info: Mar 04 14:58:28 volumio5 volumio[1383]: ---------------------------- MPD announces state update: player Mar 04 14:58:28 volumio5 volumio[1383]: info: ControllerMpd::getState Mar 04 14:58:28 volumio5 volumio[1383]: verbose: ControllerMpd::sendMpdCommand status Mar 04 14:58:28 volumio5 volumio[1383]: info: sendMpdCommand status took 2 milliseconds Mar 04 14:58:28 volumio5 volumio[1383]: verbose: ControllerMpd::parseState Mar 04 14:58:28 volumio5 volumio[1383]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 14:58:28 volumio5 volumio[1383]: info: Mar 04 14:58:28 volumio5 volumio[1383]: ---------------------------- MPD announces system playlist update Mar 04 14:58:28 volumio5 volumio[1383]: info: Ignoring MPD Status Update Mar 04 14:58:28 volumio5 volumio[1383]: info: sendMpdCommand status took 2 milliseconds Mar 04 14:58:28 volumio5 volumio[1383]: info: sendMpdCommand clear took 2 milliseconds Mar 04 14:58:28 volumio5 volumio[1383]: info: sendMpdCommand status took 2 milliseconds Mar 04 14:58:28 volumio5 volumio[1383]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 04 14:58:28 volumio5 volumio[1383]: verbose: ControllerMpd::parseState Mar 04 14:58:28 volumio5 volumio[1383]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 14:58:28 volumio5 volumio[1383]: verbose: ControllerMpd::sendMpdCommand load "http://truenas.local:30013/Audio/ccadb5915e701780e34841eae6035760/stream.mp3?static=true&mediaSourceId=ccadb5915e701780e34841eae6035760&tag=9440390df4eee458ec6a7bb16ab7d4a9&t.mp3" Mar 04 14:58:28 volumio5 volumio[1383]: verbose: ControllerMpd::parseState Mar 04 14:58:28 volumio5 volumio[1383]: verbose: ControllerMpd::parseTrackInfo Mar 04 14:58:28 volumio5 volumio[1383]: info: ControllerMpd::pushState Mar 04 14:58:28 volumio5 volumio[1383]: info: CoreCommandRouter::servicePushState Mar 04 14:58:28 volumio5 volumio[1383]: info: CoreStateMachine::pushState Mar 04 14:58:28 volumio5 volumio[1383]: info: CorePlayQueue::getTrack 0 Mar 04 14:58:28 volumio5 volumio[1383]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 14:58:28 volumio5 volumio[1383]: info: CoreCommandRouter::volumioPushState Mar 04 14:58:28 volumio5 volumio[1383]: info: CorePlayQueue::getTrack 0 Mar 04 14:58:28 volumio5 volumio[1383]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current jellyfin Received mpd Mar 04 14:58:28 volumio5 volumio[1383]: info: ------------------------------ 7ms Mar 04 14:58:28 volumio5 volumio[1383]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 04 14:58:28 volumio5 volumio[1383]: TypeError: Cannot read properties of undefined (reading 'split') Mar 04 14:58:28 volumio5 volumio[1383]: at Promise._successFn (/volumio/app/plugins/music_service/mpd/index.js:280:60) Mar 04 14:58:28 volumio5 volumio[1383]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Mar 04 14:58:28 volumio5 volumio[1383]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Mar 04 14:58:28 volumio5 volumio[1383]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 04 14:58:30 volumio5 sudo[14514]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-04 14:57' Mar 04 14:58:30 volumio5 sudo[14514]: 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="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"