-- Logs begin at Thu 2025-05-22 06:16:10 BST, end at Thu 2025-05-22 18:55:54 BST. -- May 22 18:54:00 volumio volumio[5950]: info: CoreCommandRouter::volumioGetState May 22 18:54:00 volumio volumio[5950]: info: CorePlayQueue::getTrack 3 May 22 18:54:10 volumio volumio[5950]: info: CoreCommandRouter::volumioGetState May 22 18:54:10 volumio volumio[5950]: info: CorePlayQueue::getTrack 3 May 22 18:54:10 volumio volumio[5950]: info: Listing playlists May 22 18:54:10 volumio volumio[5950]: info: Listing playlists May 22 18:54:20 volumio volumio[5950]: info: CoreCommandRouter::volumioGetState May 22 18:54:20 volumio volumio[5950]: info: CorePlayQueue::getTrack 3 May 22 18:54:22 volumio volumio[5950]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 22 18:54:22 volumio volumio[5950]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 22 18:54:22 volumio volumio[5950]: info: Discovery: Getting this device information May 22 18:54:22 volumio volumio[5950]: info: CoreCommandRouter::volumioGetState May 22 18:54:22 volumio volumio[5950]: info: CorePlayQueue::getTrack 3 May 22 18:54:22 volumio volumio[5950]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 22 18:54:24 volumio volumio[5950]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 22 18:54:24 volumio volumio[5950]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 22 18:54:27 volumio nmbd[750]: [2025/05/22 18:54:27.293790, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) May 22 18:54:27 volumio nmbd[750]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.85 for name WORKGROUP<1d>. May 22 18:54:27 volumio nmbd[750]: This response was from IP 192.168.1.115, reporting an IP address of 192.168.1.115. May 22 18:54:30 volumio volumio[5950]: info: CoreCommandRouter::volumioGetState May 22 18:54:30 volumio volumio[5950]: info: CorePlayQueue::getTrack 3 May 22 18:54:30 volumio volumio[5950]: info: Listing playlists May 22 18:54:30 volumio volumio[5950]: info: Listing playlists May 22 18:54:40 volumio volumio[5950]: info: CoreCommandRouter::volumioGetState May 22 18:54:40 volumio volumio[5950]: info: CorePlayQueue::getTrack 3 May 22 18:54:50 volumio volumio[5950]: info: CoreCommandRouter::volumioGetState May 22 18:54:50 volumio volumio[5950]: info: CorePlayQueue::getTrack 3 May 22 18:54:50 volumio volumio[5950]: info: Listing playlists May 22 18:54:50 volumio volumio[5950]: info: Listing playlists May 22 18:54:52 volumio volumio[5950]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 22 18:54:52 volumio volumio[5950]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 22 18:54:52 volumio volumio[5950]: info: Discovery: Getting this device information May 22 18:54:52 volumio volumio[5950]: info: CoreCommandRouter::volumioGetState May 22 18:54:52 volumio volumio[5950]: info: CorePlayQueue::getTrack 3 May 22 18:54:52 volumio volumio[5950]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 22 18:55:00 volumio volumio[5950]: info: CoreCommandRouter::volumioGetState May 22 18:55:00 volumio volumio[5950]: info: CorePlayQueue::getTrack 3 May 22 18:55:10 volumio volumio[5950]: info: CoreCommandRouter::volumioGetState May 22 18:55:10 volumio volumio[5950]: info: CorePlayQueue::getTrack 3 May 22 18:55:10 volumio volumio[5950]: info: Listing playlists May 22 18:55:10 volumio volumio[5950]: info: Listing playlists May 22 18:55:20 volumio volumio[5950]: info: CoreCommandRouter::volumioGetState May 22 18:55:20 volumio volumio[5950]: info: CorePlayQueue::getTrack 3 May 22 18:55:22 volumio volumio[5950]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 22 18:55:22 volumio volumio[5950]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 22 18:55:22 volumio volumio[5950]: info: Discovery: Getting this device information May 22 18:55:22 volumio volumio[5950]: info: CoreCommandRouter::volumioGetState May 22 18:55:22 volumio volumio[5950]: info: CorePlayQueue::getTrack 3 May 22 18:55:22 volumio volumio[5950]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 22 18:55:30 volumio volumio[5950]: info: CoreCommandRouter::volumioGetState May 22 18:55:30 volumio volumio[5950]: info: CorePlayQueue::getTrack 3 May 22 18:55:30 volumio volumio[5950]: info: Listing playlists May 22 18:55:30 volumio volumio[5950]: info: Listing playlists May 22 18:55:40 volumio volumio[5950]: info: CoreCommandRouter::volumioGetState May 22 18:55:40 volumio volumio[5950]: info: CorePlayQueue::getTrack 3 May 22 18:55:47 volumio volumio[5950]: info: CorePlayQueue::getTrack 3 May 22 18:55:47 volumio volumio[5950]: info: CorePlayQueue::getTrack 4 May 22 18:55:47 volumio volumio[5950]: info: Prefetching next song May 22 18:55:47 volumio volumio[5950]: info: DOING PREFETCH IN MPD May 22 18:55:47 volumio volumio[5950]: verbose: ControllerMpd::sendMpdCommand add "NAS/data/Terrorvision - We Are Not Robots (2024) [24Bit-44.1kHz] FLAC [PMEDIA] ⭐️/05. You Gotta Want To Be Happy.flac" May 22 18:55:47 volumio volumio[5950]: info: sendMpdCommand add "NAS/data/Terrorvision - We Are Not Robots (2024) [24Bit-44.1kHz] FLAC [PMEDIA] ⭐️/05. You Gotta Want To Be Happy.flac" took 1 milliseconds May 22 18:55:47 volumio volumio[5950]: verbose: ControllerMpd::sendMpdCommand consume 1 May 22 18:55:47 volumio volumio[5950]: info: May 22 18:55:47 volumio volumio[5950]: ---------------------------- MPD announces system playlist update May 22 18:55:47 volumio volumio[5950]: info: Ignoring MPD Status Update May 22 18:55:47 volumio volumio[5950]: info: May 22 18:55:47 volumio volumio[5950]: ---------------------------- MPD announces state update: options May 22 18:55:47 volumio volumio[5950]: info: sendMpdCommand consume 1 took 2 milliseconds May 22 18:55:47 volumio volumio[5950]: info: ControllerMpd::getState May 22 18:55:47 volumio volumio[5950]: verbose: ControllerMpd::sendMpdCommand status May 22 18:55:47 volumio volumio[5950]: info: May 22 18:55:47 volumio volumio[5950]: ---------------------------- MPD announces system playlist update May 22 18:55:47 volumio volumio[5950]: info: Ignoring MPD Status Update May 22 18:55:47 volumio volumio[5950]: info: May 22 18:55:47 volumio volumio[5950]: ---------------------------- MPD announces system playlist update May 22 18:55:47 volumio volumio[5950]: info: Ignoring MPD Status Update May 22 18:55:47 volumio volumio[5950]: info: ------------------------------ 5ms May 22 18:55:47 volumio volumio[5950]: info: sendMpdCommand status took 3 milliseconds May 22 18:55:47 volumio volumio[5950]: info: ------------------------------ 4ms May 22 18:55:47 volumio volumio[5950]: info: ------------------------------ 3ms May 22 18:55:47 volumio volumio[5950]: verbose: ControllerMpd::parseState May 22 18:55:47 volumio volumio[5950]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 18:55:47 volumio volumio[5950]: info: May 22 18:55:47 volumio volumio[5950]: ---------------------------- MPD announces state update: options May 22 18:55:47 volumio volumio[5950]: info: ControllerMpd::getState May 22 18:55:47 volumio volumio[5950]: verbose: ControllerMpd::sendMpdCommand status May 22 18:55:47 volumio volumio[5950]: info: May 22 18:55:47 volumio volumio[5950]: ---------------------------- MPD announces state update: options May 22 18:55:47 volumio volumio[5950]: info: ControllerMpd::getState May 22 18:55:47 volumio volumio[5950]: verbose: ControllerMpd::sendMpdCommand status May 22 18:55:47 volumio volumio[5950]: info: sendMpdCommand playlistinfo took 4 milliseconds May 22 18:55:47 volumio volumio[5950]: info: sendMpdCommand status took 3 milliseconds May 22 18:55:47 volumio volumio[5950]: info: sendMpdCommand status took 2 milliseconds May 22 18:55:47 volumio volumio[5950]: verbose: ControllerMpd::parseTrackInfo May 22 18:55:47 volumio volumio[5950]: verbose: ControllerMpd::parseState May 22 18:55:47 volumio volumio[5950]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 18:55:47 volumio volumio[5950]: verbose: ControllerMpd::parseState May 22 18:55:47 volumio volumio[5950]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 18:55:47 volumio volumio[5950]: info: ControllerMpd::pushState May 22 18:55:47 volumio volumio[5950]: info: CoreCommandRouter::servicePushState May 22 18:55:47 volumio volumio[5950]: info: CorePlayQueue::getTrack 3 May 22 18:55:47 volumio volumio[5950]: verbose: STATE SERVICE {"status":"play","position":0,"seek":217874,"duration":222,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1443 Kbps","isStreaming":false,"title":"Baby Blue","artist":"Terrorvision","album":"We Are Not Robots","uri":"NAS/data/Terrorvision - We Are Not Robots (2024) [24Bit-44.1kHz] FLAC [PMEDIA] ⭐️/04. Baby Blue.flac","trackType":"flac"} May 22 18:55:47 volumio volumio[5950]: verbose: CURRENT POSITION 3 May 22 18:55:47 volumio volumio[5950]: info: CoreStateMachine::syncState stateService play May 22 18:55:47 volumio volumio[5950]: info: CoreStateMachine::syncState currentStatus play May 22 18:55:47 volumio volumio[5950]: info: Received an update from plugin. extracting info from payload May 22 18:55:47 volumio volumio[5950]: info: CoreStateMachine::pushState May 22 18:55:47 volumio volumio[5950]: info: CorePlayQueue::getTrack 3 May 22 18:55:47 volumio volumio[5950]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 18:55:47 volumio volumio[5950]: info: CoreCommandRouter::volumioPushState May 22 18:55:47 volumio volumio[5950]: info: CoreStateMachine::pushState May 22 18:55:47 volumio volumio[5950]: info: CorePlayQueue::getTrack 3 May 22 18:55:47 volumio volumio[5950]: info: CoreCommandRouter::volumioPushState May 22 18:55:47 volumio volumio[5950]: info: ------------------------------ 32ms May 22 18:55:47 volumio volumio[5950]: info: sendMpdCommand playlistinfo took 18 milliseconds May 22 18:55:47 volumio volumio[5950]: info: sendMpdCommand playlistinfo took 18 milliseconds May 22 18:55:47 volumio volumio[5950]: verbose: ControllerMpd::parseTrackInfo May 22 18:55:47 volumio volumio[5950]: verbose: ControllerMpd::parseTrackInfo May 22 18:55:47 volumio volumio[5950]: info: ControllerMpd::pushState May 22 18:55:47 volumio volumio[5950]: info: CoreCommandRouter::servicePushState May 22 18:55:47 volumio volumio[5950]: info: CorePlayQueue::getTrack 3 May 22 18:55:47 volumio volumio[5950]: verbose: STATE SERVICE {"status":"play","position":0,"seek":218000,"duration":222,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1458 Kbps","isStreaming":false,"title":"Baby Blue","artist":"Terrorvision","album":"We Are Not Robots","uri":"NAS/data/Terrorvision - We Are Not Robots (2024) [24Bit-44.1kHz] FLAC [PMEDIA] ⭐️/04. Baby Blue.flac","trackType":"flac"} May 22 18:55:47 volumio volumio[5950]: verbose: CURRENT POSITION 3 May 22 18:55:47 volumio volumio[5950]: info: CoreStateMachine::syncState stateService play May 22 18:55:47 volumio volumio[5950]: info: CoreStateMachine::syncState currentStatus play May 22 18:55:47 volumio volumio[5950]: info: Received an update from plugin. extracting info from payload May 22 18:55:47 volumio volumio[5950]: info: CoreStateMachine::pushState May 22 18:55:47 volumio volumio[5950]: info: CorePlayQueue::getTrack 3 May 22 18:55:47 volumio volumio[5950]: info: CoreCommandRouter::volumioPushState May 22 18:55:47 volumio volumio[5950]: info: CoreStateMachine::pushState May 22 18:55:47 volumio volumio[5950]: info: CorePlayQueue::getTrack 3 May 22 18:55:47 volumio volumio[5950]: info: CoreCommandRouter::volumioPushState May 22 18:55:47 volumio volumio[5950]: info: ControllerMpd::pushState May 22 18:55:47 volumio volumio[5950]: info: CoreCommandRouter::servicePushState May 22 18:55:47 volumio volumio[5950]: info: CorePlayQueue::getTrack 3 May 22 18:55:47 volumio volumio[5950]: verbose: STATE SERVICE {"status":"play","position":0,"seek":218000,"duration":222,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1458 Kbps","isStreaming":false,"title":"Baby Blue","artist":"Terrorvision","album":"We Are Not Robots","uri":"NAS/data/Terrorvision - We Are Not Robots (2024) [24Bit-44.1kHz] FLAC [PMEDIA] ⭐️/04. Baby Blue.flac","trackType":"flac"} May 22 18:55:47 volumio volumio[5950]: verbose: CURRENT POSITION 3 May 22 18:55:47 volumio volumio[5950]: info: CoreStateMachine::syncState stateService play May 22 18:55:47 volumio volumio[5950]: info: CoreStateMachine::syncState currentStatus play May 22 18:55:47 volumio volumio[5950]: info: Received an update from plugin. extracting info from payload May 22 18:55:47 volumio volumio[5950]: info: CoreStateMachine::pushState May 22 18:55:47 volumio volumio[5950]: info: CorePlayQueue::getTrack 3 May 22 18:55:47 volumio volumio[5950]: info: CoreCommandRouter::volumioPushState May 22 18:55:47 volumio volumio[5950]: info: CoreStateMachine::pushState May 22 18:55:47 volumio volumio[5950]: info: CorePlayQueue::getTrack 3 May 22 18:55:47 volumio volumio[5950]: info: CoreCommandRouter::volumioPushState May 22 18:55:47 volumio volumio[5950]: info: ------------------------------ 54ms May 22 18:55:47 volumio volumio[5950]: info: ------------------------------ 53ms May 22 18:55:47 volumio volumio[5950]: info: [ASDebug] CurState: play PrevState: play May 22 18:55:47 volumio volumio[5950]: info: [ASDebug] CurState: play PrevState: play May 22 18:55:47 volumio volumio[5950]: info: [ASDebug] CurState: play PrevState: play May 22 18:55:47 volumio volumio[5950]: info: [ASDebug] CurState: play PrevState: play May 22 18:55:47 volumio volumio[5950]: info: [ASDebug] CurState: play PrevState: play May 22 18:55:47 volumio volumio[5950]: info: [ASDebug] CurState: play PrevState: play May 22 18:55:50 volumio volumio[5950]: info: CoreCommandRouter::volumioGetState May 22 18:55:50 volumio volumio[5950]: info: CorePlayQueue::getTrack 3 May 22 18:55:50 volumio volumio[5950]: info: Listing playlists May 22 18:55:50 volumio volumio[5950]: info: Listing playlists May 22 18:55:52 volumio volumio[5950]: info: May 22 18:55:52 volumio volumio[5950]: ---------------------------- MPD announces system playlist update May 22 18:55:52 volumio volumio[5950]: info: Ignoring MPD Status Update May 22 18:55:52 volumio volumio[5950]: info: May 22 18:55:52 volumio volumio[5950]: ---------------------------- MPD announces state update: player May 22 18:55:52 volumio volumio[5950]: info: ControllerMpd::getState May 22 18:55:52 volumio volumio[5950]: verbose: ControllerMpd::sendMpdCommand status May 22 18:55:52 volumio volumio[5950]: info: May 22 18:55:52 volumio volumio[5950]: ---------------------------- MPD announces system playlist update May 22 18:55:52 volumio volumio[5950]: info: Ignoring MPD Status Update May 22 18:55:52 volumio volumio[5950]: info: May 22 18:55:52 volumio volumio[5950]: ---------------------------- MPD announces state update: player May 22 18:55:52 volumio volumio[5950]: info: ControllerMpd::getState May 22 18:55:52 volumio volumio[5950]: verbose: ControllerMpd::sendMpdCommand status May 22 18:55:52 volumio volumio[5950]: info: May 22 18:55:52 volumio volumio[5950]: ---------------------------- MPD announces system playlist update May 22 18:55:52 volumio volumio[5950]: info: Ignoring MPD Status Update May 22 18:55:52 volumio volumio[5950]: info: May 22 18:55:52 volumio volumio[5950]: ---------------------------- MPD announces state update: player May 22 18:55:52 volumio volumio[5950]: info: ControllerMpd::getState May 22 18:55:52 volumio volumio[5950]: verbose: ControllerMpd::sendMpdCommand status May 22 18:55:52 volumio volumio[5950]: info: ------------------------------ 5ms May 22 18:55:52 volumio volumio[5950]: info: sendMpdCommand status took 5 milliseconds May 22 18:55:52 volumio volumio[5950]: info: ------------------------------ 4ms May 22 18:55:52 volumio volumio[5950]: info: sendMpdCommand status took 3 milliseconds May 22 18:55:52 volumio volumio[5950]: info: ------------------------------ 3ms May 22 18:55:52 volumio volumio[5950]: info: sendMpdCommand status took 2 milliseconds May 22 18:55:52 volumio volumio[5950]: verbose: ControllerMpd::parseState May 22 18:55:52 volumio volumio[5950]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 18:55:52 volumio volumio[5950]: verbose: ControllerMpd::parseState May 22 18:55:52 volumio volumio[5950]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 18:55:52 volumio volumio[5950]: verbose: ControllerMpd::parseState May 22 18:55:52 volumio volumio[5950]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 18:55:52 volumio volumio[5950]: info: sendMpdCommand playlistinfo took 6 milliseconds May 22 18:55:52 volumio volumio[5950]: info: sendMpdCommand playlistinfo took 7 milliseconds May 22 18:55:52 volumio volumio[5950]: info: sendMpdCommand playlistinfo took 6 milliseconds May 22 18:55:52 volumio volumio[5950]: verbose: ControllerMpd::parseTrackInfo May 22 18:55:52 volumio volumio[5950]: info: ControllerMpd::pushState May 22 18:55:52 volumio volumio[5950]: info: CoreCommandRouter::servicePushState May 22 18:55:52 volumio volumio[5950]: info: CorePlayQueue::getTrack 3 May 22 18:55:52 volumio volumio[5950]: verbose: STATE SERVICE {"status":"play","position":0,"seek":34,"duration":214,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1556 Kbps","isStreaming":false,"title":"You Gotta Want To Be Happy","artist":"Terrorvision","album":"We Are Not Robots","uri":"NAS/data/Terrorvision - We Are Not Robots (2024) [24Bit-44.1kHz] FLAC [PMEDIA] ⭐️/05. You Gotta Want To Be Happy.flac","trackType":"flac"} May 22 18:55:52 volumio volumio[5950]: verbose: CURRENT POSITION 3 May 22 18:55:52 volumio volumio[5950]: info: CoreStateMachine::syncState stateService play May 22 18:55:52 volumio volumio[5950]: info: CoreStateMachine::syncState currentStatus play May 22 18:55:52 volumio volumio[5950]: info: Received an update from plugin. extracting info from payload May 22 18:55:52 volumio volumio[5950]: info: CoreStateMachine::pushState May 22 18:55:52 volumio volumio[5950]: info: CorePlayQueue::getTrack 3 May 22 18:55:52 volumio volumio[5950]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 18:55:52 volumio volumio[5950]: info: CoreCommandRouter::volumioPushState May 22 18:55:52 volumio volumio[5950]: info: CoreStateMachine::pushState May 22 18:55:52 volumio volumio[5950]: info: CorePlayQueue::getTrack 3 May 22 18:55:52 volumio volumio[5950]: info: CoreCommandRouter::volumioPushState May 22 18:55:52 volumio volumio[5950]: info: ControllerMpd::pushState May 22 18:55:52 volumio volumio[5950]: info: CoreCommandRouter::servicePushState May 22 18:55:52 volumio volumio[5950]: info: CorePlayQueue::getTrack 3 May 22 18:55:52 volumio volumio[5950]: verbose: STATE SERVICE {"status":"play","position":0,"seek":34,"duration":214,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1556 Kbps","isStreaming":false,"title":"You Gotta Want To Be Happy","artist":"Terrorvision","album":"We Are Not Robots","uri":"NAS/data/Terrorvision - We Are Not Robots (2024) [24Bit-44.1kHz] FLAC [PMEDIA] ⭐️/05. You Gotta Want To Be Happy.flac","trackType":"flac"} May 22 18:55:52 volumio volumio[5950]: verbose: CURRENT POSITION 3 May 22 18:55:52 volumio volumio[5950]: info: CoreStateMachine::syncState stateService play May 22 18:55:52 volumio volumio[5950]: info: CoreStateMachine::syncState currentStatus play May 22 18:55:52 volumio volumio[5950]: info: Received an update from plugin. extracting info from payload May 22 18:55:52 volumio volumio[5950]: info: CoreStateMachine::pushState May 22 18:55:52 volumio volumio[5950]: info: CorePlayQueue::getTrack 3 May 22 18:55:52 volumio volumio[5950]: info: CoreCommandRouter::volumioPushState May 22 18:55:52 volumio volumio[5950]: info: CoreStateMachine::pushState May 22 18:55:52 volumio volumio[5950]: info: CorePlayQueue::getTrack 3 May 22 18:55:52 volumio volumio[5950]: info: CoreCommandRouter::volumioPushState May 22 18:55:52 volumio volumio[5950]: info: ControllerMpd::pushState May 22 18:55:52 volumio volumio[5950]: info: CoreCommandRouter::servicePushState May 22 18:55:52 volumio volumio[5950]: info: CorePlayQueue::getTrack 3 May 22 18:55:52 volumio volumio[5950]: verbose: STATE SERVICE {"status":"play","position":0,"seek":34,"duration":214,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1556 Kbps","isStreaming":false,"title":"You Gotta Want To Be Happy","artist":"Terrorvision","album":"We Are Not Robots","uri":"NAS/data/Terrorvision - We Are Not Robots (2024) [24Bit-44.1kHz] FLAC [PMEDIA] ⭐️/05. You Gotta Want To Be Happy.flac","trackType":"flac"} May 22 18:55:52 volumio volumio[5950]: verbose: CURRENT POSITION 3 May 22 18:55:52 volumio volumio[5950]: info: CoreStateMachine::syncState stateService play May 22 18:55:52 volumio volumio[5950]: info: CoreStateMachine::syncState currentStatus play May 22 18:55:52 volumio volumio[5950]: info: Received an update from plugin. extracting info from payload May 22 18:55:52 volumio volumio[5950]: info: CoreStateMachine::pushState May 22 18:55:52 volumio volumio[5950]: info: CorePlayQueue::getTrack 3 May 22 18:55:52 volumio volumio[5950]: info: CoreCommandRouter::volumioPushState May 22 18:55:52 volumio volumio[5950]: info: CoreStateMachine::pushState May 22 18:55:52 volumio volumio[5950]: info: CorePlayQueue::getTrack 3 May 22 18:55:52 volumio volumio[5950]: info: CoreCommandRouter::volumioPushState May 22 18:55:52 volumio volumio[5950]: info: ------------------------------ 53ms May 22 18:55:52 volumio volumio[5950]: info: ------------------------------ 52ms May 22 18:55:52 volumio volumio[5950]: info: ------------------------------ 51ms May 22 18:55:52 volumio volumio[5950]: info: [ASDebug] CurState: play PrevState: play May 22 18:55:52 volumio volumio[5950]: info: [ASDebug] CurState: play PrevState: play May 22 18:55:52 volumio volumio[5950]: info: [ASDebug] CurState: play PrevState: play May 22 18:55:52 volumio volumio[5950]: info: [ASDebug] CurState: play PrevState: play May 22 18:55:52 volumio volumio[5950]: info: [ASDebug] CurState: play PrevState: play May 22 18:55:52 volumio volumio[5950]: info: [ASDebug] CurState: play PrevState: play May 22 18:55:52 volumio volumio[5950]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 22 18:55:52 volumio volumio[5950]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 22 18:55:52 volumio volumio[5950]: info: Discovery: Getting this device information May 22 18:55:52 volumio volumio[5950]: info: CoreCommandRouter::volumioGetState May 22 18:55:52 volumio volumio[5950]: info: CorePlayQueue::getTrack 3 May 22 18:55:52 volumio volumio[5950]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 22 18:55:52 volumio volumio[5950]: info: CoreStateMachine::startPlaybackTimer May 22 18:55:52 volumio volumio[5950]: info: CorePlayQueue::getTrack 4 May 22 18:55:52 volumio volumio[5950]: info: CoreStateMachine::pushState May 22 18:55:52 volumio volumio[5950]: info: CorePlayQueue::getTrack 4 May 22 18:55:52 volumio volumio[5950]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 18:55:52 volumio volumio[5950]: info: CoreCommandRouter::volumioPushState May 22 18:55:52 volumio volumio[5950]: info: [ASDebug] CurState: play PrevState: play May 22 18:55:53 volumio volumio[5950]: info: CoreCommandRouter::volumioPause May 22 18:55:53 volumio volumio[5950]: info: CoreStateMachine::pause May 22 18:55:53 volumio volumio[5950]: info: CoreStateMachine::stPlaybackTimer May 22 18:55:53 volumio volumio[5950]: info: CoreStateMachine::servicePause May 22 18:55:53 volumio volumio[5950]: info: CorePlayQueue::getTrack 4 May 22 18:55:53 volumio volumio[5950]: info: CoreCommandRouter::servicePause May 22 18:55:53 volumio volumio[5950]: info: ControllerMpd::pause May 22 18:55:53 volumio volumio[5950]: verbose: ControllerMpd::sendMpdCommand pause May 22 18:55:53 volumio volumio[5950]: info: May 22 18:55:53 volumio volumio[5950]: ---------------------------- MPD announces state update: player May 22 18:55:53 volumio volumio[5950]: info: sendMpdCommand pause took 1 milliseconds May 22 18:55:53 volumio volumio[5950]: info: ControllerMpd::getState May 22 18:55:53 volumio volumio[5950]: verbose: ControllerMpd::sendMpdCommand status May 22 18:55:53 volumio volumio[5950]: info: May 22 18:55:53 volumio volumio[5950]: ---------------------------- MPD announces state update: player May 22 18:55:53 volumio volumio[5950]: info: ControllerMpd::getState May 22 18:55:53 volumio volumio[5950]: verbose: ControllerMpd::sendMpdCommand status May 22 18:55:53 volumio volumio[5950]: info: May 22 18:55:53 volumio volumio[5950]: ---------------------------- MPD announces state update: player May 22 18:55:53 volumio volumio[5950]: info: ControllerMpd::getState May 22 18:55:53 volumio volumio[5950]: verbose: ControllerMpd::sendMpdCommand status May 22 18:55:53 volumio volumio[5950]: info: sendMpdCommand status took 3 milliseconds May 22 18:55:53 volumio volumio[5950]: info: sendMpdCommand status took 2 milliseconds May 22 18:55:53 volumio volumio[5950]: info: sendMpdCommand status took 1 milliseconds May 22 18:55:53 volumio volumio[5950]: verbose: ControllerMpd::parseState May 22 18:55:53 volumio volumio[5950]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 18:55:53 volumio volumio[5950]: verbose: ControllerMpd::parseState May 22 18:55:53 volumio volumio[5950]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 18:55:53 volumio volumio[5950]: verbose: ControllerMpd::parseState May 22 18:55:53 volumio volumio[5950]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 22 18:55:53 volumio volumio[5950]: info: sendMpdCommand playlistinfo took 3 milliseconds May 22 18:55:53 volumio volumio[5950]: info: sendMpdCommand playlistinfo took 3 milliseconds May 22 18:55:53 volumio volumio[5950]: info: sendMpdCommand playlistinfo took 3 milliseconds May 22 18:55:53 volumio volumio[5950]: verbose: ControllerMpd::parseTrackInfo May 22 18:55:53 volumio volumio[5950]: verbose: ControllerMpd::parseTrackInfo May 22 18:55:53 volumio volumio[5950]: verbose: ControllerMpd::parseTrackInfo May 22 18:55:53 volumio volumio[5950]: info: ControllerMpd::pushState May 22 18:55:53 volumio volumio[5950]: info: CoreCommandRouter::servicePushState May 22 18:55:53 volumio volumio[5950]: info: CorePlayQueue::getTrack 4 May 22 18:55:53 volumio volumio[5950]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":1417,"duration":214,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1165 Kbps","isStreaming":false,"title":"You Gotta Want To Be Happy","artist":"Terrorvision","album":"We Are Not Robots","uri":"NAS/data/Terrorvision - We Are Not Robots (2024) [24Bit-44.1kHz] FLAC [PMEDIA] ⭐️/05. You Gotta Want To Be Happy.flac","trackType":"flac"} May 22 18:55:53 volumio volumio[5950]: verbose: CURRENT POSITION 4 May 22 18:55:53 volumio volumio[5950]: info: CoreStateMachine::syncState stateService pause May 22 18:55:53 volumio volumio[5950]: info: CoreStateMachine::syncState currentStatus pause May 22 18:55:53 volumio volumio[5950]: info: CoreStateMachine::pushState May 22 18:55:53 volumio volumio[5950]: info: CorePlayQueue::getTrack 4 May 22 18:55:53 volumio volumio[5950]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 22 18:55:53 volumio volumio[5950]: info: CoreCommandRouter::volumioPushState May 22 18:55:53 volumio volumio[5950]: info: CoreStateMachine::stPlaybackTimer May 22 18:55:53 volumio volumio[5950]: info: ControllerMpd::pushState May 22 18:55:53 volumio volumio[5950]: info: CoreCommandRouter::servicePushState May 22 18:55:53 volumio volumio[5950]: info: CorePlayQueue::getTrack 4 May 22 18:55:53 volumio volumio[5950]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":1417,"duration":214,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1165 Kbps","isStreaming":false,"title":"You Gotta Want To Be Happy","artist":"Terrorvision","album":"We Are Not Robots","uri":"NAS/data/Terrorvision - We Are Not Robots (2024) [24Bit-44.1kHz] FLAC [PMEDIA] ⭐️/05. You Gotta Want To Be Happy.flac","trackType":"flac"} May 22 18:55:53 volumio volumio[5950]: verbose: CURRENT POSITION 4 May 22 18:55:53 volumio volumio[5950]: info: CoreStateMachine::syncState stateService pause May 22 18:55:53 volumio volumio[5950]: info: CoreStateMachine::syncState currentStatus pause May 22 18:55:53 volumio volumio[5950]: info: CoreStateMachine::pushState May 22 18:55:53 volumio volumio[5950]: info: CorePlayQueue::getTrack 4 May 22 18:55:53 volumio volumio[5950]: info: CoreCommandRouter::volumioPushState May 22 18:55:53 volumio volumio[5950]: info: CoreStateMachine::stPlaybackTimer May 22 18:55:53 volumio volumio[5950]: info: ControllerMpd::pushState May 22 18:55:53 volumio volumio[5950]: info: CoreCommandRouter::servicePushState May 22 18:55:53 volumio volumio[5950]: info: CorePlayQueue::getTrack 4 May 22 18:55:53 volumio volumio[5950]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":1417,"duration":214,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1165 Kbps","isStreaming":false,"title":"You Gotta Want To Be Happy","artist":"Terrorvision","album":"We Are Not Robots","uri":"NAS/data/Terrorvision - We Are Not Robots (2024) [24Bit-44.1kHz] FLAC [PMEDIA] ⭐️/05. You Gotta Want To Be Happy.flac","trackType":"flac"} May 22 18:55:53 volumio volumio[5950]: verbose: CURRENT POSITION 4 May 22 18:55:53 volumio volumio[5950]: info: CoreStateMachine::syncState stateService pause May 22 18:55:53 volumio volumio[5950]: info: CoreStateMachine::syncState currentStatus pause May 22 18:55:53 volumio volumio[5950]: info: CoreStateMachine::pushState May 22 18:55:53 volumio volumio[5950]: info: CorePlayQueue::getTrack 4 May 22 18:55:53 volumio volumio[5950]: info: CoreCommandRouter::volumioPushState May 22 18:55:53 volumio volumio[5950]: info: CoreStateMachine::stPlaybackTimer May 22 18:55:53 volumio volumio[5950]: info: ------------------------------ 30ms May 22 18:55:53 volumio volumio[5950]: info: ------------------------------ 28ms May 22 18:55:53 volumio volumio[5950]: info: ------------------------------ 27ms May 22 18:55:53 volumio volumio[5950]: info: [ASDebug] CurState: pause PrevState: play May 22 18:55:53 volumio volumio[5950]: info: [ASDebug] InitTimeout - Amp off in: 720 ms May 22 18:55:53 volumio volumio[5950]: info: [ASDebug] CurState: pause PrevState: play May 22 18:55:53 volumio volumio[5950]: info: [ASDebug] InitTimeout - Amp off in: 720 ms May 22 18:55:53 volumio volumio[5950]: info: [ASDebug] CurState: pause PrevState: play May 22 18:55:53 volumio volumio[5950]: info: [ASDebug] InitTimeout - Amp off in: 720 ms May 22 18:55:54 volumio volumio[5950]: info: [ASDebug] Togle GPIO: OFF May 22 18:55:54 volumio volumio[5950]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 22 18:55:54 volumio volumio[5950]: Error: ESPIPE: invalid seek, write May 22 18:55:54 volumio volumio[5950]: at Object.writeSync (fs.js:711:3) May 22 18:55:54 volumio volumio[5950]: at Gpio.writeSync (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:243:8) May 22 18:55:54 volumio volumio[5950]: at AmpSwitchController.off (/data/plugins/system_controller/ampswitch/index.js:221:23) May 22 18:55:54 volumio volumio[5950]: at Timeout._onTimeout (/data/plugins/system_controller/ampswitch/index.js:195:88) May 22 18:55:54 volumio volumio[5950]: at listOnTimeout (internal/timers.js:554:17) May 22 18:55:54 volumio volumio[5950]: at processTimers (internal/timers.js:497:7) { May 22 18:55:54 volumio volumio[5950]: errno: -29, May 22 18:55:54 volumio volumio[5950]: syscall: 'write', May 22 18:55:54 volumio volumio[5950]: code: 'ESPIPE' May 22 18:55:54 volumio volumio[5950]: } May 22 18:55:54 volumio volumio[5950]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 22 18:55:54 volumio sudo[7176]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-22 18:54 May 22 18:55:54 volumio sudo[7176]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"