Jan 28 14:26:27 volumio-pi4 volumio[16252]: info: CorePlayQueue::getTrack 1 Jan 28 14:26:27 volumio-pi4 volumio[16252]: info: CorePlayQueue::getTrack 2 Jan 28 14:26:27 volumio-pi4 volumio[16252]: info: Prefetching next song Jan 28 14:26:27 volumio-pi4 volumio[16252]: info: [jellyfin-play] Stream URL for All The Promises We Made: http://avtm1.lan:8096/Audio/248157ac86322392c58b562726e6de7d/stream.flac?static=true&mediaSourceId=248157ac86322392c58b562726e6de7d&tag=b92c97471d8b98f66310495ef16d9467 Jan 28 14:26:27 volumio-pi4 volumio[16252]: verbose: ControllerMpd::sendMpdCommand addid "http://avtm1.lan:8096/Audio/248157ac86322392c58b562726e6de7d/stream.flac?static=true&mediaSourceId=248157ac86322392c58b562726e6de7d&tag=b92c97471d8b98f66310495ef16d9467&t.flac" Jan 28 14:26:27 volumio-pi4 upmpdcli[1605]: :3:../src/mpdcli.cxx:148::MPDCli::startEventLoop: already started Jan 28 14:26:27 volumio-pi4 volumio[16252]: info: sendMpdCommand addid "http://avtm1.lan:8096/Audio/248157ac86322392c58b562726e6de7d/stream.flac?static=true&mediaSourceId=248157ac86322392c58b562726e6de7d&tag=b92c97471d8b98f66310495ef16d9467&t.flac" took 1 milliseconds Jan 28 14:26:27 volumio-pi4 volumio[16252]: verbose: MPD COMMAND [object Object] Jan 28 14:26:27 volumio-pi4 volumio[16252]: verbose: MPD COMMAND [object Object] Jan 28 14:26:27 volumio-pi4 volumio[16252]: verbose: MPD COMMAND [object Object] Jan 28 14:26:27 volumio-pi4 volumio[16252]: info: Jan 28 14:26:27 volumio-pi4 volumio[16252]: ---------------------------- MPD announces system playlist update Jan 28 14:26:27 volumio-pi4 volumio[16252]: info: Ignoring MPD Status Update Jan 28 14:26:27 volumio-pi4 volumio[16252]: info: Jan 28 14:26:27 volumio-pi4 volumio[16252]: ---------------------------- MPD announces system playlist update Jan 28 14:26:27 volumio-pi4 volumio[16252]: info: Ignoring MPD Status Update Jan 28 14:26:27 volumio-pi4 volumio[16252]: info: [jellyfin-play] Prefetched and added song to MPD queue: All The Promises We Made Jan 28 14:26:27 volumio-pi4 volumio[16252]: verbose: ControllerMpd::sendMpdCommand consume 1 Jan 28 14:26:27 volumio-pi4 volumio[16252]: info: Jan 28 14:26:27 volumio-pi4 volumio[16252]: ---------------------------- MPD announces system playlist update Jan 28 14:26:27 volumio-pi4 volumio[16252]: info: Ignoring MPD Status Update Jan 28 14:26:27 volumio-pi4 volumio[16252]: info: Jan 28 14:26:27 volumio-pi4 volumio[16252]: ---------------------------- MPD announces system playlist update Jan 28 14:26:27 volumio-pi4 volumio[16252]: info: Ignoring MPD Status Update Jan 28 14:26:27 volumio-pi4 volumio[16252]: info: Jan 28 14:26:27 volumio-pi4 volumio[16252]: ---------------------------- MPD announces state update: options Jan 28 14:26:27 volumio-pi4 volumio[16252]: info: ------------------------------ 6ms Jan 28 14:26:27 volumio-pi4 volumio[16252]: info: ------------------------------ 6ms Jan 28 14:26:27 volumio-pi4 volumio[16252]: info: sendMpdCommand consume 1 took 4 milliseconds Jan 28 14:26:27 volumio-pi4 volumio[16252]: info: ControllerMpd::getState Jan 28 14:26:27 volumio-pi4 volumio[16252]: verbose: ControllerMpd::sendMpdCommand status Jan 28 14:26:27 volumio-pi4 volumio[16252]: info: ------------------------------ 5ms Jan 28 14:26:27 volumio-pi4 volumio[16252]: info: ------------------------------ 4ms Jan 28 14:26:27 volumio-pi4 volumio[16252]: info: Jan 28 14:26:27 volumio-pi4 volumio[16252]: ---------------------------- MPD announces system playlist update Jan 28 14:26:27 volumio-pi4 volumio[16252]: info: Ignoring MPD Status Update Jan 28 14:26:27 volumio-pi4 volumio[16252]: info: Jan 28 14:26:27 volumio-pi4 volumio[16252]: ---------------------------- MPD announces state update: options Jan 28 14:26:27 volumio-pi4 volumio[16252]: info: ControllerMpd::getState Jan 28 14:26:27 volumio-pi4 volumio[16252]: verbose: ControllerMpd::sendMpdCommand status Jan 28 14:26:27 volumio-pi4 volumio[16252]: info: Jan 28 14:26:27 volumio-pi4 volumio[16252]: ---------------------------- MPD announces system playlist update Jan 28 14:26:27 volumio-pi4 volumio[16252]: info: Ignoring MPD Status Update Jan 28 14:26:27 volumio-pi4 volumio[16252]: info: Jan 28 14:26:27 volumio-pi4 volumio[16252]: ---------------------------- MPD announces state update: options Jan 28 14:26:27 volumio-pi4 volumio[16252]: info: ControllerMpd::getState Jan 28 14:26:27 volumio-pi4 volumio[16252]: verbose: ControllerMpd::sendMpdCommand status Jan 28 14:26:27 volumio-pi4 volumio[16252]: info: sendMpdCommand status took 5 milliseconds Jan 28 14:26:27 volumio-pi4 volumio[16252]: info: ------------------------------ 4ms Jan 28 14:26:27 volumio-pi4 volumio[16252]: info: sendMpdCommand status took 4 milliseconds Jan 28 14:26:27 volumio-pi4 volumio[16252]: info: ------------------------------ 4ms Jan 28 14:26:27 volumio-pi4 volumio[16252]: info: sendMpdCommand status took 3 milliseconds Jan 28 14:26:27 volumio-pi4 volumio[16252]: verbose: ControllerMpd::parseState Jan 28 14:26:27 volumio-pi4 volumio[16252]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 14:26:27 volumio-pi4 volumio[16252]: verbose: ControllerMpd::parseState Jan 28 14:26:27 volumio-pi4 volumio[16252]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 14:26:27 volumio-pi4 volumio[16252]: verbose: ControllerMpd::parseState Jan 28 14:26:27 volumio-pi4 volumio[16252]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 14:26:28 volumio-pi4 volumio[16252]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 28 14:26:28 volumio-pi4 volumio[16252]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 28 14:26:28 volumio-pi4 volumio[16252]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 28 14:26:28 volumio-pi4 volumio[16252]: verbose: ControllerMpd::parseTrackInfo Jan 28 14:26:28 volumio-pi4 volumio[16252]: verbose: ControllerMpd::parseTrackInfo Jan 28 14:26:28 volumio-pi4 volumio[16252]: verbose: ControllerMpd::parseTrackInfo Jan 28 14:26:28 volumio-pi4 volumio[16252]: info: ControllerMpd::pushState Jan 28 14:26:28 volumio-pi4 volumio[16252]: info: CoreCommandRouter::servicePushState Jan 28 14:26:28 volumio-pi4 volumio[16252]: info: CorePlayQueue::getTrack 1 Jan 28 14:26:28 volumio-pi4 volumio[16252]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":156,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Adventure Time","artist":"izioq","album":"World of Izioq","uri":"http://avtm1.lan:8096/Audio/af7990fc82c3659a91d6c1b186242e98/stream.flac?static=true&mediaSourceId=af7990fc82c3659a91d6c1b186242e98&tag=b92c97471d8b98f66310495ef16d9467&t.flac","trackType":"flac"} Jan 28 14:26:28 volumio-pi4 volumio[16252]: verbose: CURRENT POSITION 1 Jan 28 14:26:28 volumio-pi4 volumio[16252]: info: CoreStateMachine::syncState stateService pause Jan 28 14:26:28 volumio-pi4 volumio[16252]: info: CoreStateMachine::syncState currentStatus stop Jan 28 14:26:28 volumio-pi4 volumio[16252]: info: CoreStateMachine::pushState Jan 28 14:26:28 volumio-pi4 volumio[16252]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 14:26:28 volumio-pi4 volumio[16252]: info: CoreCommandRouter::volumioPushState Jan 28 14:26:28 volumio-pi4 volumio[16252]: info: ControllerMpd::pushState Jan 28 14:26:28 volumio-pi4 volumio[16252]: info: CoreCommandRouter::servicePushState Jan 28 14:26:28 volumio-pi4 volumio[16252]: info: CorePlayQueue::getTrack 1 Jan 28 14:26:28 volumio-pi4 volumio[16252]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":156,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Adventure Time","artist":"izioq","album":"World of Izioq","uri":"http://avtm1.lan:8096/Audio/af7990fc82c3659a91d6c1b186242e98/stream.flac?static=true&mediaSourceId=af7990fc82c3659a91d6c1b186242e98&tag=b92c97471d8b98f66310495ef16d9467&t.flac","trackType":"flac"} Jan 28 14:26:28 volumio-pi4 volumio[16252]: verbose: CURRENT POSITION 1 Jan 28 14:26:28 volumio-pi4 volumio[16252]: info: CoreStateMachine::syncState stateService pause Jan 28 14:26:28 volumio-pi4 volumio[16252]: info: CoreStateMachine::syncState currentStatus stop Jan 28 14:26:28 volumio-pi4 volumio[16252]: info: CoreStateMachine::pushState Jan 28 14:26:28 volumio-pi4 volumio[16252]: info: CoreCommandRouter::volumioPushState Jan 28 14:26:28 volumio-pi4 volumio[16252]: info: ControllerMpd::pushState Jan 28 14:26:28 volumio-pi4 volumio[16252]: info: CoreCommandRouter::servicePushState Jan 28 14:26:28 volumio-pi4 volumio[16252]: info: CorePlayQueue::getTrack 1 Jan 28 14:26:28 volumio-pi4 volumio[16252]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":156,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Adventure Time","artist":"izioq","album":"World of Izioq","uri":"http://avtm1.lan:8096/Audio/af7990fc82c3659a91d6c1b186242e98/stream.flac?static=true&mediaSourceId=af7990fc82c3659a91d6c1b186242e98&tag=b92c97471d8b98f66310495ef16d9467&t.flac","trackType":"flac"} Jan 28 14:26:28 volumio-pi4 volumio[16252]: verbose: CURRENT POSITION 1 Jan 28 14:26:28 volumio-pi4 volumio[16252]: info: CoreStateMachine::syncState stateService pause Jan 28 14:26:28 volumio-pi4 volumio[16252]: info: CoreStateMachine::syncState currentStatus stop Jan 28 14:26:28 volumio-pi4 volumio[16252]: info: CoreStateMachine::pushState Jan 28 14:26:28 volumio-pi4 volumio[16252]: info: CoreCommandRouter::volumioPushState Jan 28 14:26:28 volumio-pi4 volumio[16252]: info: ------------------------------ 66ms Jan 28 14:26:28 volumio-pi4 volumio[16252]: info: ------------------------------ 64ms Jan 28 14:26:28 volumio-pi4 volumio[16252]: info: ------------------------------ 63ms Jan 28 14:26:32 volumio-pi4 volumio[16252]: info: CoreStateMachine::startPlaybackTimer Jan 28 14:26:32 volumio-pi4 volumio[16252]: info: CorePlayQueue::getTrack 2 Jan 28 14:26:32 volumio-pi4 volumio[16252]: info: CoreStateMachine::pushState Jan 28 14:26:32 volumio-pi4 volumio[16252]: info: CorePlayQueue::getTrack 2 Jan 28 14:26:32 volumio-pi4 volumio[16252]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 14:26:32 volumio-pi4 volumio[16252]: info: CoreCommandRouter::volumioPushState Jan 28 14:26:41 volumio-pi4 volumio[16252]: info: Preload queue cleared Jan 28 14:26:41 volumio-pi4 volumio[16252]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 28 14:26:41 volumio-pi4 volumio[16252]: info: CoreStateMachine::ClearQueue Jan 28 14:26:41 volumio-pi4 volumio[16252]: info: CoreStateMachine::stop Jan 28 14:26:41 volumio-pi4 volumio[16252]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 14:26:41 volumio-pi4 volumio[16252]: info: CorePlayQueue::clearPlayQueue Jan 28 14:26:41 volumio-pi4 volumio[16252]: info: CorePlayQueue::saveQueue Jan 28 14:26:41 volumio-pi4 volumio[16252]: info: CoreCommandRouter::volumioPushQueue Jan 28 14:26:41 volumio-pi4 volumio[16252]: info: CoreStateMachine::addQueueItems Jan 28 14:26:41 volumio-pi4 volumio[16252]: info: CorePlayQueue::addQueueItems Jan 28 14:26:41 volumio-pi4 volumio[16252]: info: Preload queue cleared Jan 28 14:26:41 volumio-pi4 volumio[16252]: info: Adding Item to queue: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=1b9bb71c4e895185a48ac1489b150997/song@songId=d26db8e315534311a86ce08a7185fae2 Jan 28 14:26:41 volumio-pi4 volumio[16252]: info: Using cached record of: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=1b9bb71c4e895185a48ac1489b150997/song@songId=d26db8e315534311a86ce08a7185fae2 Jan 28 14:26:41 volumio-pi4 volumio[16252]: info: CoreCommandRouter::volumioPushQueue Jan 28 14:26:41 volumio-pi4 volumio[16252]: info: CorePlayQueue::saveQueue Jan 28 14:26:41 volumio-pi4 volumio[16252]: info: CoreStateMachine::updateTrackBlock Jan 28 14:26:41 volumio-pi4 volumio[16252]: info: CorePlayQueue::getTrackBlock Jan 28 14:26:41 volumio-pi4 volumio[16252]: info: CoreCommandRouter::volumioPlay Jan 28 14:26:41 volumio-pi4 volumio[16252]: info: CoreStateMachine::play index 0 Jan 28 14:26:41 volumio-pi4 volumio[16252]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 14:26:41 volumio-pi4 volumio[16252]: info: CoreStateMachine::addQueueItems Jan 28 14:26:41 volumio-pi4 volumio[16252]: info: CorePlayQueue::addQueueItems Jan 28 14:26:41 volumio-pi4 volumio[16252]: info: Preload queue cleared Jan 28 14:26:41 volumio-pi4 volumio[16252]: info: Adding Item to queue: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=1b9bb71c4e895185a48ac1489b150997/song@songId=af7990fc82c3659a91d6c1b186242e98 Jan 28 14:26:41 volumio-pi4 volumio[16252]: info: Using cached record of: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=1b9bb71c4e895185a48ac1489b150997/song@songId=af7990fc82c3659a91d6c1b186242e98 Jan 28 14:26:41 volumio-pi4 volumio[16252]: info: Adding Item to queue: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=1b9bb71c4e895185a48ac1489b150997/song@songId=248157ac86322392c58b562726e6de7d Jan 28 14:26:41 volumio-pi4 volumio[16252]: info: Using cached record of: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=1b9bb71c4e895185a48ac1489b150997/song@songId=248157ac86322392c58b562726e6de7d Jan 28 14:26:41 volumio-pi4 volumio[16252]: info: Adding Item to queue: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=1b9bb71c4e895185a48ac1489b150997/song@songId=1acd134845baa1e47d503e24e6f8ff21 Jan 28 14:26:41 volumio-pi4 volumio[16252]: info: Using cached record of: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=1b9bb71c4e895185a48ac1489b150997/song@songId=1acd134845baa1e47d503e24e6f8ff21 Jan 28 14:26:41 volumio-pi4 volumio[16252]: info: Adding Item to queue: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=1b9bb71c4e895185a48ac1489b150997/song@songId=965d0e3246ddaa4dbdf92080dced24cb Jan 28 14:26:41 volumio-pi4 volumio[16252]: info: Using cached record of: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=1b9bb71c4e895185a48ac1489b150997/song@songId=965d0e3246ddaa4dbdf92080dced24cb Jan 28 14:26:41 volumio-pi4 volumio[16252]: info: Adding Item to queue: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=1b9bb71c4e895185a48ac1489b150997/song@songId=58a485e3db0710dcae774f02a8ade2a9 Jan 28 14:26:41 volumio-pi4 volumio[16252]: info: Using cached record of: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=1b9bb71c4e895185a48ac1489b150997/song@songId=58a485e3db0710dcae774f02a8ade2a9 Jan 28 14:26:41 volumio-pi4 volumio[16252]: info: Adding Item to queue: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=1b9bb71c4e895185a48ac1489b150997/song@songId=6b3a2ae9f32451d9b90157f5195afaa8 Jan 28 14:26:41 volumio-pi4 volumio[16252]: info: Using cached record of: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=1b9bb71c4e895185a48ac1489b150997/song@songId=6b3a2ae9f32451d9b90157f5195afaa8 Jan 28 14:26:41 volumio-pi4 volumio[16252]: info: Adding Item to queue: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=1b9bb71c4e895185a48ac1489b150997/song@songId=f9d44f655143c81212717402b910c87d Jan 28 14:26:41 volumio-pi4 volumio[16252]: info: Using cached record of: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=1b9bb71c4e895185a48ac1489b150997/song@songId=f9d44f655143c81212717402b910c87d Jan 28 14:26:41 volumio-pi4 volumio[16252]: info: Adding Item to queue: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=1b9bb71c4e895185a48ac1489b150997/song@songId=ad11fe2bab2238b25ad76bbab3bae762 Jan 28 14:26:41 volumio-pi4 volumio[16252]: info: Using cached record of: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=1b9bb71c4e895185a48ac1489b150997/song@songId=ad11fe2bab2238b25ad76bbab3bae762 Jan 28 14:26:41 volumio-pi4 volumio[16252]: info: Adding Item to queue: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=1b9bb71c4e895185a48ac1489b150997/song@songId=5d6bd1b89049d09dc6472575e3a91296 Jan 28 14:26:41 volumio-pi4 volumio[16252]: info: Using cached record of: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=1b9bb71c4e895185a48ac1489b150997/song@songId=5d6bd1b89049d09dc6472575e3a91296 Jan 28 14:26:41 volumio-pi4 volumio[16252]: info: Adding Item to queue: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=1b9bb71c4e895185a48ac1489b150997/song@songId=f74792f5e2d0708e1e4a815ec6997072 Jan 28 14:26:41 volumio-pi4 volumio[16252]: info: Using cached record of: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=1b9bb71c4e895185a48ac1489b150997/song@songId=f74792f5e2d0708e1e4a815ec6997072 Jan 28 14:26:41 volumio-pi4 volumio[16252]: info: Adding Item to queue: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=1b9bb71c4e895185a48ac1489b150997/song@songId=1c170ac241183e355ca58120412d8bc5 Jan 28 14:26:41 volumio-pi4 volumio[16252]: info: Using cached record of: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=1b9bb71c4e895185a48ac1489b150997/song@songId=1c170ac241183e355ca58120412d8bc5 Jan 28 14:26:41 volumio-pi4 volumio[16252]: info: Adding Item to queue: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=1b9bb71c4e895185a48ac1489b150997/song@songId=f137735e4d577a842f8387e19f555303 Jan 28 14:26:41 volumio-pi4 volumio[16252]: info: Using cached record of: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=1b9bb71c4e895185a48ac1489b150997/song@songId=f137735e4d577a842f8387e19f555303 Jan 28 14:26:41 volumio-pi4 volumio[16252]: info: Adding Item to queue: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=1b9bb71c4e895185a48ac1489b150997/song@songId=ed487659c4c892c2840cc8074e3981d7 Jan 28 14:26:41 volumio-pi4 volumio[16252]: info: Using cached record of: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=1b9bb71c4e895185a48ac1489b150997/song@songId=ed487659c4c892c2840cc8074e3981d7 Jan 28 14:26:41 volumio-pi4 volumio[16252]: info: Adding Item to queue: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=1b9bb71c4e895185a48ac1489b150997/song@songId=d2af7bbf7a99166715ece4fc4eb8f8b0 Jan 28 14:26:41 volumio-pi4 volumio[16252]: info: Using cached record of: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=1b9bb71c4e895185a48ac1489b150997/song@songId=d2af7bbf7a99166715ece4fc4eb8f8b0 Jan 28 14:26:41 volumio-pi4 volumio[16252]: info: CoreStateMachine::stop Jan 28 14:26:41 volumio-pi4 volumio[16252]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 14:26:41 volumio-pi4 volumio[16252]: info: CoreCommandRouter::volumioPushQueue Jan 28 14:26:41 volumio-pi4 volumio[16252]: info: CorePlayQueue::saveQueue Jan 28 14:26:41 volumio-pi4 volumio[16252]: info: CoreStateMachine::play index undefined Jan 28 14:26:41 volumio-pi4 volumio[16252]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 14:26:41 volumio-pi4 volumio[16252]: info: CoreStateMachine::updateTrackBlock Jan 28 14:26:41 volumio-pi4 volumio[16252]: info: CorePlayQueue::getTrackBlock Jan 28 14:26:41 volumio-pi4 volumio[16252]: info: CorePlayQueue::getTrack 0 Jan 28 14:26:41 volumio-pi4 volumio[16252]: info: CoreStateMachine::startPlaybackTimer Jan 28 14:26:41 volumio-pi4 volumio[16252]: info: CorePlayQueue::getTrack 0 Jan 28 14:26:41 volumio-pi4 volumio[16252]: info: [jellyfin-play] clearAddPlayTrack: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/song@songId=d26db8e315534311a86ce08a7185fae2 Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: [jellyfin-play] Stream URL for World of Izioq: http://avtm1.lan:8096/Audio/d26db8e315534311a86ce08a7185fae2/stream.flac?static=true&mediaSourceId=d26db8e315534311a86ce08a7185fae2&tag=b92c97471d8b98f66310495ef16d9467 Jan 28 14:26:42 volumio-pi4 volumio[16252]: verbose: ControllerMpd::sendMpdCommand stop Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: sendMpdCommand stop took 4 milliseconds Jan 28 14:26:42 volumio-pi4 volumio[16252]: verbose: ControllerMpd::sendMpdCommand clear Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: Jan 28 14:26:42 volumio-pi4 volumio[16252]: ---------------------------- MPD announces state update: player Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: ControllerMpd::getState Jan 28 14:26:42 volumio-pi4 volumio[16252]: verbose: ControllerMpd::sendMpdCommand status Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: ControllerMpd::getState Jan 28 14:26:42 volumio-pi4 volumio[16252]: verbose: ControllerMpd::sendMpdCommand status Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: Jan 28 14:26:42 volumio-pi4 volumio[16252]: ---------------------------- MPD announces state update: player Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: Jan 28 14:26:42 volumio-pi4 volumio[16252]: ---------------------------- MPD announces system playlist update Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: Ignoring MPD Status Update Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: ControllerMpd::getState Jan 28 14:26:42 volumio-pi4 volumio[16252]: verbose: ControllerMpd::sendMpdCommand status Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: sendMpdCommand clear took 6 milliseconds Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: sendMpdCommand status took 4 milliseconds Jan 28 14:26:42 volumio-pi4 volumio[16252]: verbose: ControllerMpd::sendMpdCommand load "http://avtm1.lan:8096/Audio/d26db8e315534311a86ce08a7185fae2/stream.flac?static=true&mediaSourceId=d26db8e315534311a86ce08a7185fae2&tag=b92c97471d8b98f66310495ef16d9467&t.flac" Jan 28 14:26:42 volumio-pi4 volumio[16252]: verbose: ControllerMpd::parseState Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: ControllerMpd::pushState Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: CoreCommandRouter::servicePushState Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: CoreStateMachine::pushState Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: CorePlayQueue::getTrack 0 Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: CoreCommandRouter::volumioPushState Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: CorePlayQueue::getTrack 0 Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current jellyfin Received mpd Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: ------------------------------ 27ms Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: Jan 28 14:26:42 volumio-pi4 volumio[16252]: ---------------------------- MPD announces system playlist update Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: Ignoring MPD Status Update Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: Jan 28 14:26:42 volumio-pi4 volumio[16252]: ---------------------------- MPD announces state update: player Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: ControllerMpd::getState Jan 28 14:26:42 volumio-pi4 volumio[16252]: verbose: ControllerMpd::sendMpdCommand status Jan 28 14:26:42 volumio-pi4 volumio[16252]: error: updateQueue error: null Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: sendMpdCommand status took 32 milliseconds Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: sendMpdCommand status took 31 milliseconds Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: ------------------------------ 33ms Jan 28 14:26:42 volumio-pi4 volumio[16252]: verbose: ControllerMpd::parseState Jan 28 14:26:42 volumio-pi4 volumio[16252]: verbose: ControllerMpd::parseState Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: ControllerMpd::pushState Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: CoreCommandRouter::servicePushState Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: CoreStateMachine::pushState Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: CorePlayQueue::getTrack 0 Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: CoreCommandRouter::volumioPushState Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: CorePlayQueue::getTrack 0 Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current jellyfin Received mpd Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: ------------------------------ 59ms Jan 28 14:26:42 volumio-pi4 volumio[16252]: verbose: ControllerMpd::sendMpdCommand addid "http://avtm1.lan:8096/Audio/d26db8e315534311a86ce08a7185fae2/stream.flac?static=true&mediaSourceId=d26db8e315534311a86ce08a7185fae2&tag=b92c97471d8b98f66310495ef16d9467&t.flac" Jan 28 14:26:42 volumio-pi4 volumio[16252]: error: updateQueue error: null Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: Jan 28 14:26:42 volumio-pi4 volumio[16252]: ---------------------------- MPD announces system playlist update Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: Ignoring MPD Status Update Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: ------------------------------ 72ms Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: sendMpdCommand status took 66 milliseconds Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: sendMpdCommand addid "http://avtm1.lan:8096/Audio/d26db8e315534311a86ce08a7185fae2/stream.flac?static=true&mediaSourceId=d26db8e315534311a86ce08a7185fae2&tag=b92c97471d8b98f66310495ef16d9467&t.flac" took 4 milliseconds Jan 28 14:26:42 volumio-pi4 volumio[16252]: verbose: ControllerMpd::parseState Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: ControllerMpd::pushState Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: CoreCommandRouter::servicePushState Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: CoreStateMachine::pushState Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: CorePlayQueue::getTrack 0 Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: CoreCommandRouter::volumioPushState Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: CorePlayQueue::getTrack 0 Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current jellyfin Received mpd Jan 28 14:26:42 volumio-pi4 volumio[16252]: verbose: MPD COMMAND [object Object] Jan 28 14:26:42 volumio-pi4 volumio[16252]: verbose: MPD COMMAND [object Object] Jan 28 14:26:42 volumio-pi4 volumio[16252]: verbose: MPD COMMAND [object Object] Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: ------------------------------ 91ms Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: Jan 28 14:26:42 volumio-pi4 volumio[16252]: ---------------------------- MPD announces system playlist update Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: Ignoring MPD Status Update Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: Jan 28 14:26:42 volumio-pi4 volumio[16252]: ---------------------------- MPD announces system playlist update Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: Ignoring MPD Status Update Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: ------------------------------ 29ms Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: ------------------------------ 3ms Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 28 14:26:42 volumio-pi4 volumio[16252]: verbose: ControllerMpd::sendMpdCommand play Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: Jan 28 14:26:42 volumio-pi4 volumio[16252]: ---------------------------- MPD announces system playlist update Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: Ignoring MPD Status Update Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: Jan 28 14:26:42 volumio-pi4 volumio[16252]: ---------------------------- MPD announces system playlist update Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: Ignoring MPD Status Update Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: [jellyfin-play]: Reported 'stop' for song: Adventure Time (at NaN ms) Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: ------------------------------ 17ms Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: sendMpdCommand play took 15 milliseconds Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: ------------------------------ 15ms Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: ------------------------------ 14ms Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: Jan 28 14:26:42 volumio-pi4 volumio[16252]: ---------------------------- MPD announces system playlist update Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: Ignoring MPD Status Update Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: ------------------------------ 4ms Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: [jellyfin-play]: Mark song World of Izioq as played by vvv. Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: Jan 28 14:26:42 volumio-pi4 volumio[16252]: ---------------------------- MPD announces state update: player Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: ControllerMpd::getState Jan 28 14:26:42 volumio-pi4 volumio[16252]: verbose: ControllerMpd::sendMpdCommand status Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: Jan 28 14:26:42 volumio-pi4 volumio[16252]: ---------------------------- MPD announces state update: player Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: ControllerMpd::getState Jan 28 14:26:42 volumio-pi4 volumio[16252]: verbose: ControllerMpd::sendMpdCommand status Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: ControllerMpd::getState Jan 28 14:26:42 volumio-pi4 volumio[16252]: verbose: ControllerMpd::sendMpdCommand status Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: Jan 28 14:26:42 volumio-pi4 volumio[16252]: ---------------------------- MPD announces state update: player Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: ControllerMpd::getState Jan 28 14:26:42 volumio-pi4 volumio[16252]: verbose: ControllerMpd::sendMpdCommand status Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: Jan 28 14:26:42 volumio-pi4 volumio[16252]: ---------------------------- MPD announces state update: player Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: ControllerMpd::getState Jan 28 14:26:42 volumio-pi4 volumio[16252]: verbose: ControllerMpd::sendMpdCommand status Jan 28 14:26:42 volumio-pi4 volumio[16252]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: sendMpdCommand status took 18 milliseconds Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: ControllerMpd::getState Jan 28 14:26:42 volumio-pi4 volumio[16252]: verbose: ControllerMpd::sendMpdCommand status Jan 28 14:26:42 volumio-pi4 volumio[16252]: verbose: ControllerMpd::parseState Jan 28 14:26:42 volumio-pi4 volumio[16252]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: Jan 28 14:26:42 volumio-pi4 volumio[16252]: ---------------------------- MPD announces state update: player Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: ControllerMpd::getState Jan 28 14:26:42 volumio-pi4 volumio[16252]: verbose: ControllerMpd::sendMpdCommand status Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: Jan 28 14:26:42 volumio-pi4 volumio[16252]: ---------------------------- MPD announces state update: player Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: ControllerMpd::getState Jan 28 14:26:42 volumio-pi4 volumio[16252]: verbose: ControllerMpd::sendMpdCommand status Jan 28 14:26:42 volumio-pi4 volumio[16252]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: sendMpdCommand status took 38 milliseconds Jan 28 14:26:42 volumio-pi4 volumio[16252]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: sendMpdCommand status took 39 milliseconds Jan 28 14:26:42 volumio-pi4 volumio[16252]: verbose: ControllerMpd::parseState Jan 28 14:26:42 volumio-pi4 volumio[16252]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 14:26:42 volumio-pi4 volumio[16252]: verbose: ControllerMpd::parseState Jan 28 14:26:42 volumio-pi4 volumio[16252]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 14:26:42 volumio-pi4 volumio[16252]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: sendMpdCommand status took 70 milliseconds Jan 28 14:26:42 volumio-pi4 volumio[16252]: verbose: ControllerMpd::sendMpdCommand clearerror Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: sendMpdCommand status took 70 milliseconds Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: sendMpdCommand clearerror took 57 milliseconds Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: sendMpdCommand status took 56 milliseconds Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: sendMpdCommand playlistinfo took 54 milliseconds Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: sendMpdCommand status took 52 milliseconds Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: sendMpdCommand status took 51 milliseconds Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: sendMpdCommand clearerror took 37 milliseconds Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: sendMpdCommand clearerror took 36 milliseconds Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: sendMpdCommand playlistinfo took 25 milliseconds Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: sendMpdCommand playlistinfo took 24 milliseconds Jan 28 14:26:42 volumio-pi4 volumio[16252]: verbose: ControllerMpd::parseState Jan 28 14:26:42 volumio-pi4 volumio[16252]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 14:26:42 volumio-pi4 volumio[16252]: verbose: ControllerMpd::parseState Jan 28 14:26:42 volumio-pi4 volumio[16252]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 14:26:42 volumio-pi4 volumio[16252]: verbose: ControllerMpd::parseState Jan 28 14:26:42 volumio-pi4 volumio[16252]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 14:26:42 volumio-pi4 volumio[16252]: verbose: ControllerMpd::parseTrackInfo Jan 28 14:26:42 volumio-pi4 volumio[16252]: verbose: ControllerMpd::parseState Jan 28 14:26:42 volumio-pi4 volumio[16252]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 14:26:42 volumio-pi4 volumio[16252]: verbose: ControllerMpd::parseState Jan 28 14:26:42 volumio-pi4 volumio[16252]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 14:26:42 volumio-pi4 volumio[16252]: verbose: ControllerMpd::parseTrackInfo Jan 28 14:26:42 volumio-pi4 volumio[16252]: verbose: ControllerMpd::parseTrackInfo Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: ControllerMpd::pushState Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: CoreCommandRouter::servicePushState Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: CorePlayQueue::getTrack 0 Jan 28 14:26:42 volumio-pi4 volumio[16252]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":158,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"World of Izioq","artist":"izioq","album":"World of Izioq","uri":"http://avtm1.lan:8096/Audio/d26db8e315534311a86ce08a7185fae2/stream.flac?static=true&mediaSourceId=d26db8e315534311a86ce08a7185fae2&tag=b92c97471d8b98f66310495ef16d9467&t.flac","trackType":"flac"} Jan 28 14:26:42 volumio-pi4 volumio[16252]: verbose: CURRENT POSITION 0 Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: CoreStateMachine::syncState stateService pause Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: CoreStateMachine::syncState currentStatus stop Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: CoreStateMachine::pushState Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: CoreCommandRouter::volumioPushState Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: Pushing Favourites {"service":"mpd","favourite":false} Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: ControllerMpd::pushState Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: CoreCommandRouter::servicePushState Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: CorePlayQueue::getTrack 0 Jan 28 14:26:42 volumio-pi4 volumio[16252]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":158,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"World of Izioq","artist":"izioq","album":"World of Izioq","uri":"http://avtm1.lan:8096/Audio/d26db8e315534311a86ce08a7185fae2/stream.flac?static=true&mediaSourceId=d26db8e315534311a86ce08a7185fae2&tag=b92c97471d8b98f66310495ef16d9467&t.flac","trackType":"flac"} Jan 28 14:26:42 volumio-pi4 volumio[16252]: verbose: CURRENT POSITION 0 Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: CoreStateMachine::syncState stateService pause Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: CoreStateMachine::syncState currentStatus stop Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: CoreStateMachine::pushState Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: CoreCommandRouter::volumioPushState Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: ------------------------------ 151ms Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: ------------------------------ 150ms Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: Pushing Favourites {"favourite":false} Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: sendMpdCommand clearerror took 94 milliseconds Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: sendMpdCommand clearerror took 94 milliseconds Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: sendMpdCommand playlistinfo took 82 milliseconds Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: sendMpdCommand playlistinfo took 81 milliseconds Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: sendMpdCommand playlistinfo took 81 milliseconds Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: sendMpdCommand playlistinfo took 80 milliseconds Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: sendMpdCommand playlistinfo took 81 milliseconds Jan 28 14:26:42 volumio-pi4 volumio[16252]: verbose: ControllerMpd::parseTrackInfo Jan 28 14:26:42 volumio-pi4 volumio[16252]: verbose: ControllerMpd::parseTrackInfo Jan 28 14:26:42 volumio-pi4 volumio[16252]: verbose: ControllerMpd::parseTrackInfo Jan 28 14:26:42 volumio-pi4 volumio[16252]: verbose: ControllerMpd::parseTrackInfo Jan 28 14:26:42 volumio-pi4 volumio[16252]: verbose: ControllerMpd::parseTrackInfo Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: ControllerMpd::pushState Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: CoreCommandRouter::servicePushState Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: CorePlayQueue::getTrack 0 Jan 28 14:26:42 volumio-pi4 volumio[16252]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":158,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"World of Izioq","artist":"izioq","album":"World of Izioq","uri":"http://avtm1.lan:8096/Audio/d26db8e315534311a86ce08a7185fae2/stream.flac?static=true&mediaSourceId=d26db8e315534311a86ce08a7185fae2&tag=b92c97471d8b98f66310495ef16d9467&t.flac","trackType":"flac"} Jan 28 14:26:42 volumio-pi4 volumio[16252]: verbose: CURRENT POSITION 0 Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: CoreStateMachine::syncState stateService pause Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: CoreStateMachine::syncState currentStatus stop Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: CoreStateMachine::pushState Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: CoreCommandRouter::volumioPushState Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: ControllerMpd::pushState Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: CoreCommandRouter::servicePushState Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: CorePlayQueue::getTrack 0 Jan 28 14:26:42 volumio-pi4 volumio[16252]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":158,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"World of Izioq","artist":"izioq","album":"World of Izioq","uri":"http://avtm1.lan:8096/Audio/d26db8e315534311a86ce08a7185fae2/stream.flac?static=true&mediaSourceId=d26db8e315534311a86ce08a7185fae2&tag=b92c97471d8b98f66310495ef16d9467&t.flac","trackType":"flac"} Jan 28 14:26:42 volumio-pi4 volumio[16252]: verbose: CURRENT POSITION 0 Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: CoreStateMachine::syncState stateService pause Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: CoreStateMachine::syncState currentStatus stop Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: CoreStateMachine::pushState Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: CoreCommandRouter::volumioPushState Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: ControllerMpd::pushState Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: CoreCommandRouter::servicePushState Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: CorePlayQueue::getTrack 0 Jan 28 14:26:42 volumio-pi4 volumio[16252]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":158,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"World of Izioq","artist":"izioq","album":"World of Izioq","uri":"http://avtm1.lan:8096/Audio/d26db8e315534311a86ce08a7185fae2/stream.flac?static=true&mediaSourceId=d26db8e315534311a86ce08a7185fae2&tag=b92c97471d8b98f66310495ef16d9467&t.flac","trackType":"flac"} Jan 28 14:26:42 volumio-pi4 volumio[16252]: verbose: CURRENT POSITION 0 Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: CoreStateMachine::syncState stateService pause Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: CoreStateMachine::syncState currentStatus stop Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: CoreStateMachine::pushState Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: CoreCommandRouter::volumioPushState Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: ControllerMpd::pushState Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: CoreCommandRouter::servicePushState Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: CorePlayQueue::getTrack 0 Jan 28 14:26:42 volumio-pi4 volumio[16252]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":158,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"World of Izioq","artist":"izioq","album":"World of Izioq","uri":"http://avtm1.lan:8096/Audio/d26db8e315534311a86ce08a7185fae2/stream.flac?static=true&mediaSourceId=d26db8e315534311a86ce08a7185fae2&tag=b92c97471d8b98f66310495ef16d9467&t.flac","trackType":"flac"} Jan 28 14:26:42 volumio-pi4 volumio[16252]: verbose: CURRENT POSITION 0 Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: CoreStateMachine::syncState stateService pause Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: CoreStateMachine::syncState currentStatus stop Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: CoreStateMachine::pushState Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: CoreCommandRouter::volumioPushState Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: ------------------------------ 406ms Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: ------------------------------ 406ms Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: ------------------------------ 388ms Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: ------------------------------ 387ms Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: Pushing Favourites {"favourite":false} Jan 28 14:26:42 volumio-pi4 volumio[16252]: info: [jellyfin-play]: Reported 'pause' for song: World of Izioq (at 0 ms) Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: Preload queue cleared Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: CoreStateMachine::ClearQueue Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: CoreStateMachine::stop Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: CorePlayQueue::clearPlayQueue Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: CorePlayQueue::saveQueue Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: CoreCommandRouter::volumioPushQueue Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: CoreStateMachine::addQueueItems Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: CorePlayQueue::addQueueItems Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: Preload queue cleared Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: Adding Item to queue: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=1b9bb71c4e895185a48ac1489b150997/song@songId=d26db8e315534311a86ce08a7185fae2 Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: Using cached record of: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=1b9bb71c4e895185a48ac1489b150997/song@songId=d26db8e315534311a86ce08a7185fae2 Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: CoreCommandRouter::volumioPushQueue Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: CorePlayQueue::saveQueue Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: CoreStateMachine::updateTrackBlock Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: CorePlayQueue::getTrackBlock Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: CoreCommandRouter::volumioPlay Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: CoreStateMachine::play index 0 Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: CoreStateMachine::addQueueItems Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: CorePlayQueue::addQueueItems Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: Preload queue cleared Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: Adding Item to queue: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=1b9bb71c4e895185a48ac1489b150997/song@songId=af7990fc82c3659a91d6c1b186242e98 Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: Using cached record of: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=1b9bb71c4e895185a48ac1489b150997/song@songId=af7990fc82c3659a91d6c1b186242e98 Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: Adding Item to queue: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=1b9bb71c4e895185a48ac1489b150997/song@songId=248157ac86322392c58b562726e6de7d Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: Using cached record of: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=1b9bb71c4e895185a48ac1489b150997/song@songId=248157ac86322392c58b562726e6de7d Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: Adding Item to queue: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=1b9bb71c4e895185a48ac1489b150997/song@songId=1acd134845baa1e47d503e24e6f8ff21 Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: Using cached record of: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=1b9bb71c4e895185a48ac1489b150997/song@songId=1acd134845baa1e47d503e24e6f8ff21 Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: Adding Item to queue: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=1b9bb71c4e895185a48ac1489b150997/song@songId=965d0e3246ddaa4dbdf92080dced24cb Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: Using cached record of: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=1b9bb71c4e895185a48ac1489b150997/song@songId=965d0e3246ddaa4dbdf92080dced24cb Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: Adding Item to queue: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=1b9bb71c4e895185a48ac1489b150997/song@songId=58a485e3db0710dcae774f02a8ade2a9 Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: Using cached record of: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=1b9bb71c4e895185a48ac1489b150997/song@songId=58a485e3db0710dcae774f02a8ade2a9 Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: Adding Item to queue: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=1b9bb71c4e895185a48ac1489b150997/song@songId=6b3a2ae9f32451d9b90157f5195afaa8 Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: Using cached record of: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=1b9bb71c4e895185a48ac1489b150997/song@songId=6b3a2ae9f32451d9b90157f5195afaa8 Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: Adding Item to queue: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=1b9bb71c4e895185a48ac1489b150997/song@songId=f9d44f655143c81212717402b910c87d Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: Using cached record of: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=1b9bb71c4e895185a48ac1489b150997/song@songId=f9d44f655143c81212717402b910c87d Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: Adding Item to queue: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=1b9bb71c4e895185a48ac1489b150997/song@songId=ad11fe2bab2238b25ad76bbab3bae762 Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: Using cached record of: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=1b9bb71c4e895185a48ac1489b150997/song@songId=ad11fe2bab2238b25ad76bbab3bae762 Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: Adding Item to queue: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=1b9bb71c4e895185a48ac1489b150997/song@songId=5d6bd1b89049d09dc6472575e3a91296 Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: Using cached record of: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=1b9bb71c4e895185a48ac1489b150997/song@songId=5d6bd1b89049d09dc6472575e3a91296 Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: Adding Item to queue: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=1b9bb71c4e895185a48ac1489b150997/song@songId=f74792f5e2d0708e1e4a815ec6997072 Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: Using cached record of: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=1b9bb71c4e895185a48ac1489b150997/song@songId=f74792f5e2d0708e1e4a815ec6997072 Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: Adding Item to queue: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=1b9bb71c4e895185a48ac1489b150997/song@songId=1c170ac241183e355ca58120412d8bc5 Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: Using cached record of: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=1b9bb71c4e895185a48ac1489b150997/song@songId=1c170ac241183e355ca58120412d8bc5 Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: Adding Item to queue: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=1b9bb71c4e895185a48ac1489b150997/song@songId=f137735e4d577a842f8387e19f555303 Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: Using cached record of: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=1b9bb71c4e895185a48ac1489b150997/song@songId=f137735e4d577a842f8387e19f555303 Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: Adding Item to queue: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=1b9bb71c4e895185a48ac1489b150997/song@songId=ed487659c4c892c2840cc8074e3981d7 Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: Using cached record of: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=1b9bb71c4e895185a48ac1489b150997/song@songId=ed487659c4c892c2840cc8074e3981d7 Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: Adding Item to queue: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=1b9bb71c4e895185a48ac1489b150997/song@songId=d2af7bbf7a99166715ece4fc4eb8f8b0 Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: Using cached record of: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=1b9bb71c4e895185a48ac1489b150997/song@songId=d2af7bbf7a99166715ece4fc4eb8f8b0 Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: CoreStateMachine::stop Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: CoreCommandRouter::volumioPushQueue Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: CorePlayQueue::saveQueue Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: CoreStateMachine::play index undefined Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: CoreStateMachine::updateTrackBlock Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: CorePlayQueue::getTrackBlock Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: CorePlayQueue::getTrack 0 Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: CoreStateMachine::startPlaybackTimer Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: CorePlayQueue::getTrack 0 Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: [jellyfin-play] clearAddPlayTrack: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/song@songId=d26db8e315534311a86ce08a7185fae2 Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: [jellyfin-play] Stream URL for World of Izioq: http://avtm1.lan:8096/Audio/d26db8e315534311a86ce08a7185fae2/stream.flac?static=true&mediaSourceId=d26db8e315534311a86ce08a7185fae2&tag=b92c97471d8b98f66310495ef16d9467 Jan 28 14:26:44 volumio-pi4 volumio[16252]: verbose: ControllerMpd::sendMpdCommand stop Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: ControllerMpd::getState Jan 28 14:26:44 volumio-pi4 volumio[16252]: verbose: ControllerMpd::sendMpdCommand status Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: Jan 28 14:26:44 volumio-pi4 volumio[16252]: ---------------------------- MPD announces state update: player Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: sendMpdCommand stop took 5 milliseconds Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: ControllerMpd::getState Jan 28 14:26:44 volumio-pi4 volumio[16252]: verbose: ControllerMpd::sendMpdCommand status Jan 28 14:26:44 volumio-pi4 volumio[16252]: verbose: ControllerMpd::sendMpdCommand clear Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: Jan 28 14:26:44 volumio-pi4 volumio[16252]: ---------------------------- MPD announces system playlist update Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: Ignoring MPD Status Update Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: sendMpdCommand status took 3 milliseconds Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: sendMpdCommand status took 2 milliseconds Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: sendMpdCommand clear took 1 milliseconds Jan 28 14:26:44 volumio-pi4 volumio[16252]: verbose: ControllerMpd::parseState Jan 28 14:26:44 volumio-pi4 volumio[16252]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 14:26:44 volumio-pi4 volumio[16252]: verbose: ControllerMpd::parseState Jan 28 14:26:44 volumio-pi4 volumio[16252]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 14:26:44 volumio-pi4 volumio[16252]: verbose: ControllerMpd::sendMpdCommand load "http://avtm1.lan:8096/Audio/d26db8e315534311a86ce08a7185fae2/stream.flac?static=true&mediaSourceId=d26db8e315534311a86ce08a7185fae2&tag=b92c97471d8b98f66310495ef16d9467&t.flac" Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: Jan 28 14:26:44 volumio-pi4 volumio[16252]: ---------------------------- MPD announces state update: player Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: ControllerMpd::getState Jan 28 14:26:44 volumio-pi4 volumio[16252]: verbose: ControllerMpd::sendMpdCommand status Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: Jan 28 14:26:44 volumio-pi4 volumio[16252]: ---------------------------- MPD announces state update: player Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: ControllerMpd::getState Jan 28 14:26:44 volumio-pi4 volumio[16252]: verbose: ControllerMpd::sendMpdCommand status Jan 28 14:26:44 volumio-pi4 volumio[16252]: error: updateQueue error: null Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: ------------------------------ 6ms Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: sendMpdCommand playlistinfo took 4 milliseconds Jan 28 14:26:44 volumio-pi4 volumio[16252]: verbose: ControllerMpd::parseTrackInfo Jan 28 14:26:44 volumio-pi4 volumio[16252]: verbose: ControllerMpd::parseTrackInfo Jan 28 14:26:44 volumio-pi4 volumio[16252]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Jan 28 14:26:44 volumio-pi4 volumio[16252]: info: ------------------------------ 10ms Jan 28 14:26:44 volumio-pi4 volumio[16252]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 28 14:26:44 volumio-pi4 volumio[16252]: TypeError: Cannot read properties of undefined (reading 'split') Jan 28 14:26:44 volumio-pi4 volumio[16252]: at Promise._successFn (/volumio/app/plugins/music_service/mpd/index.js:280:60) Jan 28 14:26:44 volumio-pi4 volumio[16252]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jan 28 14:26:44 volumio-pi4 volumio[16252]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Jan 28 14:26:44 volumio-pi4 volumio[16252]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 28 14:26:45 volumio-pi4 sudo[17003]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-28 14:25' Jan 28 14:26:45 volumio-pi4 sudo[17003]: 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"