-- Logs begin at Fri 2025-05-23 05:09:11 BST, end at Fri 2025-05-23 19:22:25 BST. -- May 23 19:21:00 volumio volumio[999]: info: CoreCommandRouter::volumioGetState May 23 19:21:00 volumio volumio[999]: info: CorePlayQueue::getTrack 10 May 23 19:21:00 volumio volumio[999]: info: Listing playlists May 23 19:21:06 volumio volumio[999]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 23 19:21:06 volumio volumio[999]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 23 19:21:06 volumio volumio[999]: info: Discovery: Getting this device information May 23 19:21:06 volumio volumio[999]: info: CoreCommandRouter::volumioGetState May 23 19:21:06 volumio volumio[999]: info: CorePlayQueue::getTrack 10 May 23 19:21:06 volumio volumio[999]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 23 19:21:10 volumio volumio[999]: info: CoreCommandRouter::volumioGetState May 23 19:21:10 volumio volumio[999]: info: CorePlayQueue::getTrack 10 May 23 19:21:20 volumio volumio[999]: info: CoreCommandRouter::volumioGetState May 23 19:21:20 volumio volumio[999]: info: CorePlayQueue::getTrack 10 May 23 19:21:20 volumio volumio[999]: info: Listing playlists May 23 19:21:20 volumio volumio[999]: info: Listing playlists May 23 19:21:30 volumio volumio[999]: info: CoreCommandRouter::volumioGetState May 23 19:21:30 volumio volumio[999]: info: CorePlayQueue::getTrack 10 May 23 19:21:35 volumio volumio[999]: info: CorePlayQueue::getTrack 10 May 23 19:21:35 volumio volumio[999]: info: CorePlayQueue::getTrack 11 May 23 19:21:35 volumio volumio[999]: info: Prefetching next song May 23 19:21:35 volumio volumio[999]: info: [jellyfin-play] Stream URL for The Tourist: http://192.168.1.40:8096/Audio/29d95765d15fd155b78b2fce6d153df5/stream.flac?static=true&mediaSourceId=29d95765d15fd155b78b2fce6d153df5&tag=2ec82e686e523b2aa0532ff1f3a3cdcb May 23 19:21:35 volumio volumio[999]: verbose: ControllerMpd::sendMpdCommand addid "http://192.168.1.40:8096/Audio/29d95765d15fd155b78b2fce6d153df5/stream.flac?static=true&mediaSourceId=29d95765d15fd155b78b2fce6d153df5&tag=2ec82e686e523b2aa0532ff1f3a3cdcb&t.flac" May 23 19:21:35 volumio volumio[999]: info: May 23 19:21:35 volumio volumio[999]: ---------------------------- MPD announces system playlist update May 23 19:21:35 volumio volumio[999]: info: Ignoring MPD Status Update May 23 19:21:35 volumio volumio[999]: info: sendMpdCommand addid "http://192.168.1.40:8096/Audio/29d95765d15fd155b78b2fce6d153df5/stream.flac?static=true&mediaSourceId=29d95765d15fd155b78b2fce6d153df5&tag=2ec82e686e523b2aa0532ff1f3a3cdcb&t.flac" took 1 milliseconds May 23 19:21:35 volumio volumio[999]: verbose: MPD COMMAND [object Object] May 23 19:21:35 volumio volumio[999]: info: May 23 19:21:35 volumio volumio[999]: ---------------------------- MPD announces system playlist update May 23 19:21:35 volumio volumio[999]: info: Ignoring MPD Status Update May 23 19:21:35 volumio volumio[999]: info: ------------------------------ 1ms May 23 19:21:35 volumio volumio[999]: info: [jellyfin-play] Prefetched and added song to MPD queue: The Tourist May 23 19:21:35 volumio volumio[999]: verbose: ControllerMpd::sendMpdCommand consume 1 May 23 19:21:35 volumio volumio[999]: info: ------------------------------ 1ms May 23 19:21:35 volumio volumio[999]: info: sendMpdCommand consume 1 took 0 milliseconds May 23 19:21:36 volumio volumio[999]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 23 19:21:36 volumio volumio[999]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 23 19:21:36 volumio volumio[999]: info: Discovery: Getting this device information May 23 19:21:36 volumio volumio[999]: info: CoreCommandRouter::volumioGetState May 23 19:21:36 volumio volumio[999]: info: CorePlayQueue::getTrack 10 May 23 19:21:36 volumio volumio[999]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 23 19:21:40 volumio volumio[999]: info: CoreStateMachine::startPlaybackTimer May 23 19:21:40 volumio volumio[999]: info: CorePlayQueue::getTrack 11 May 23 19:21:40 volumio volumio[999]: info: CoreStateMachine::pushState May 23 19:21:40 volumio volumio[999]: info: CorePlayQueue::getTrack 11 May 23 19:21:40 volumio volumio[999]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 19:21:40 volumio volumio[999]: info: CoreCommandRouter::volumioPushState May 23 19:21:40 volumio volumio[999]: info: CorePlayQueue::getTrack 11 May 23 19:21:40 volumio volumio[999]: info: CorePlayQueue::getTrack 11 May 23 19:21:40 volumio volumio[999]: info: [ASDebug] CurState: play PrevState: play May 23 19:21:40 volumio volumio[999]: info: CoreCommandRouter::volumioGetState May 23 19:21:40 volumio volumio[999]: info: CorePlayQueue::getTrack 11 May 23 19:21:40 volumio volumio[999]: info: Listing playlists May 23 19:21:40 volumio volumio[999]: info: Listing playlists May 23 19:21:41 volumio volumio[999]: info: May 23 19:21:41 volumio volumio[999]: ---------------------------- MPD announces system playlist update May 23 19:21:41 volumio volumio[999]: info: Ignoring MPD Status Update May 23 19:21:41 volumio volumio[999]: info: May 23 19:21:41 volumio volumio[999]: ---------------------------- MPD announces state update: player May 23 19:21:41 volumio volumio[999]: info: ControllerMpd::getState May 23 19:21:41 volumio volumio[999]: verbose: ControllerMpd::sendMpdCommand status May 23 19:21:41 volumio volumio[999]: info: ------------------------------ 2ms May 23 19:21:41 volumio volumio[999]: info: sendMpdCommand status took 1 milliseconds May 23 19:21:41 volumio volumio[999]: verbose: ControllerMpd::parseState May 23 19:21:41 volumio volumio[999]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 19:21:41 volumio volumio[999]: info: sendMpdCommand playlistinfo took 1 milliseconds May 23 19:21:41 volumio volumio[999]: verbose: ControllerMpd::parseTrackInfo May 23 19:21:41 volumio volumio[999]: info: ControllerMpd::pushState May 23 19:21:41 volumio volumio[999]: info: CoreCommandRouter::servicePushState May 23 19:21:41 volumio volumio[999]: info: CorePlayQueue::getTrack 11 May 23 19:21:41 volumio volumio[999]: verbose: STATE SERVICE {"status":"play","position":0,"seek":91,"duration":327,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"736 Kbps","isStreaming":false,"title":"The Tourist","artist":"Radiohead","album":"OK Computer (1)","uri":"http://192.168.1.40:8096/Audio/29d95765d15fd155b78b2fce6d153df5/stream.flac?static=true&mediaSourceId=29d95765d15fd155b78b2fce6d153df5&tag=2ec82e686e523b2aa0532ff1f3a3cdcb&t.flac","trackType":"flac"} May 23 19:21:41 volumio volumio[999]: verbose: CURRENT POSITION 11 May 23 19:21:41 volumio volumio[999]: info: CoreStateMachine::syncState stateService play May 23 19:21:41 volumio volumio[999]: info: CoreStateMachine::syncState currentStatus play May 23 19:21:41 volumio volumio[999]: info: Received an update from plugin. extracting info from payload May 23 19:21:41 volumio volumio[999]: info: CoreStateMachine::pushState May 23 19:21:41 volumio volumio[999]: info: CorePlayQueue::getTrack 11 May 23 19:21:41 volumio volumio[999]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 19:21:41 volumio volumio[999]: info: CoreCommandRouter::volumioPushState May 23 19:21:41 volumio volumio[999]: info: CoreStateMachine::pushState May 23 19:21:41 volumio volumio[999]: info: CorePlayQueue::getTrack 11 May 23 19:21:41 volumio volumio[999]: info: CoreCommandRouter::volumioPushState May 23 19:21:41 volumio volumio[999]: info: ------------------------------ 10ms May 23 19:21:41 volumio volumio[999]: info: [ASDebug] CurState: play PrevState: play May 23 19:21:41 volumio volumio[999]: info: [ASDebug] CurState: play PrevState: play May 23 19:21:50 volumio volumio[999]: info: CoreCommandRouter::volumioGetState May 23 19:21:50 volumio volumio[999]: info: CorePlayQueue::getTrack 11 May 23 19:22:00 volumio volumio[999]: info: CoreCommandRouter::volumioGetState May 23 19:22:00 volumio volumio[999]: info: CorePlayQueue::getTrack 11 May 23 19:22:00 volumio volumio[999]: info: Listing playlists May 23 19:22:06 volumio volumio[999]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 23 19:22:06 volumio volumio[999]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 23 19:22:06 volumio volumio[999]: info: Discovery: Getting this device information May 23 19:22:06 volumio volumio[999]: info: CoreCommandRouter::volumioGetState May 23 19:22:06 volumio volumio[999]: info: CorePlayQueue::getTrack 11 May 23 19:22:06 volumio volumio[999]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 23 19:22:10 volumio volumio[999]: info: CoreCommandRouter::volumioGetState May 23 19:22:10 volumio volumio[999]: info: CorePlayQueue::getTrack 11 May 23 19:22:16 volumio nmbd[758]: [2025/05/23 19:22:16.525053, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) May 23 19:22:16 volumio nmbd[758]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.85 for name WORKGROUP<1d>. May 23 19:22:16 volumio nmbd[758]: This response was from IP 192.168.1.115, reporting an IP address of 192.168.1.115. May 23 19:22:20 volumio volumio[999]: info: CoreCommandRouter::volumioGetState May 23 19:22:20 volumio volumio[999]: info: CorePlayQueue::getTrack 11 May 23 19:22:20 volumio volumio[999]: info: Listing playlists May 23 19:22:23 volumio volumio[999]: verbose: New Socket.io Connection to 192.168.1.85 from 192.168.1.156 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_5 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 May 23 19:22:23 volumio volumio[999]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 23 19:22:23 volumio volumio[999]: info: CoreCommandRouter::volumioGetVisibleSources May 23 19:22:23 volumio volumio[999]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 23 19:22:23 volumio volumio[999]: info: CoreCommandRouter::volumioGetState May 23 19:22:23 volumio volumio[999]: info: CorePlayQueue::getTrack 11 May 23 19:22:23 volumio volumio[999]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 23 19:22:23 volumio volumio[999]: info: CoreCommandRouter::volumioGetQueue May 23 19:22:23 volumio volumio[999]: info: CoreStateMachine::getQueue May 23 19:22:23 volumio volumio[999]: info: CorePlayQueue::getQueue May 23 19:22:23 volumio volumio[999]: info: Listing playlists May 23 19:22:23 volumio volumio[999]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 23 19:22:23 volumio volumio[999]: info: Received Get System Info May 23 19:22:23 volumio volumio[999]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 23 19:22:23 volumio volumio[999]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 23 19:22:23 volumio volumio[999]: info: Discovery: Getting this device information May 23 19:22:23 volumio volumio[999]: info: CoreCommandRouter::volumioGetState May 23 19:22:23 volumio volumio[999]: info: CorePlayQueue::getTrack 11 May 23 19:22:23 volumio volumio[999]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 23 19:22:23 volumio volumio[999]: info: CoreCommandRouter::volumioGetState May 23 19:22:23 volumio volumio[999]: info: CorePlayQueue::getTrack 11 May 23 19:22:23 volumio volumio[999]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 23 19:22:23 volumio volumio[999]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 23 19:22:23 volumio volumio[999]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 23 19:22:24 volumio volumio[999]: info: CoreCommandRouter::volumioPause May 23 19:22:24 volumio volumio[999]: info: CoreStateMachine::pause May 23 19:22:24 volumio volumio[999]: info: CoreStateMachine::stPlaybackTimer May 23 19:22:24 volumio volumio[999]: info: CoreStateMachine::servicePause May 23 19:22:24 volumio volumio[999]: info: CorePlayQueue::getTrack 11 May 23 19:22:24 volumio volumio[999]: info: CoreCommandRouter::servicePause May 23 19:22:24 volumio volumio[999]: info: CoreStateMachine::setConsumeUpdateService mpd May 23 19:22:24 volumio volumio[999]: info: ControllerMpd::pause May 23 19:22:24 volumio volumio[999]: verbose: ControllerMpd::sendMpdCommand pause May 23 19:22:24 volumio volumio[999]: info: May 23 19:22:24 volumio volumio[999]: ---------------------------- MPD announces state update: player May 23 19:22:24 volumio volumio[999]: info: sendMpdCommand pause took 1 milliseconds May 23 19:22:24 volumio volumio[999]: info: ControllerMpd::getState May 23 19:22:24 volumio volumio[999]: verbose: ControllerMpd::sendMpdCommand status May 23 19:22:24 volumio volumio[999]: info: sendMpdCommand status took 0 milliseconds May 23 19:22:24 volumio volumio[999]: verbose: ControllerMpd::parseState May 23 19:22:24 volumio volumio[999]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 23 19:22:24 volumio volumio[999]: info: sendMpdCommand playlistinfo took 0 milliseconds May 23 19:22:24 volumio volumio[999]: verbose: ControllerMpd::parseTrackInfo May 23 19:22:24 volumio volumio[999]: info: ControllerMpd::pushState May 23 19:22:24 volumio volumio[999]: info: CoreCommandRouter::servicePushState May 23 19:22:24 volumio volumio[999]: info: CorePlayQueue::getTrack 11 May 23 19:22:24 volumio volumio[999]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":43082,"duration":327,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"719 Kbps","isStreaming":false,"title":"The Tourist","artist":"Radiohead","album":"OK Computer (1)","uri":"http://192.168.1.40:8096/Audio/29d95765d15fd155b78b2fce6d153df5/stream.flac?static=true&mediaSourceId=29d95765d15fd155b78b2fce6d153df5&tag=2ec82e686e523b2aa0532ff1f3a3cdcb&t.flac","trackType":"flac"} May 23 19:22:24 volumio volumio[999]: verbose: CURRENT POSITION 11 May 23 19:22:24 volumio volumio[999]: info: CoreStateMachine::syncState stateService pause May 23 19:22:24 volumio volumio[999]: info: CoreStateMachine::syncState currentStatus pause May 23 19:22:24 volumio volumio[999]: info: CoreStateMachine::pushState May 23 19:22:24 volumio volumio[999]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 23 19:22:24 volumio volumio[999]: info: CoreCommandRouter::volumioPushState May 23 19:22:24 volumio volumio[999]: info: CoreStateMachine::stPlaybackTimer May 23 19:22:24 volumio volumio[999]: info: ------------------------------ 10ms May 23 19:22:24 volumio volumio[999]: info: [ASDebug] CurState: pause PrevState: play May 23 19:22:24 volumio volumio[999]: info: [ASDebug] InitTimeout - Amp off in: 720 ms May 23 19:22:24 volumio volumio[999]: info: Pushing Favourites {"service":"mpd","uri":"jellyfin/Music@a2cc436563134123bb42e85192b5c7f3/song@songId=1c95e5342b3b7d97dfaee48ee27f7f22","favourite":false} May 23 19:22:25 volumio volumio[999]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 23 19:22:25 volumio volumio[999]: info: [ASDebug] Togle GPIO: OFF May 23 19:22:25 volumio volumio[999]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 23 19:22:25 volumio volumio[999]: Error: ESPIPE: invalid seek, write May 23 19:22:25 volumio volumio[999]: at Object.writeSync (fs.js:711:3) May 23 19:22:25 volumio volumio[999]: at Gpio.writeSync (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:243:8) May 23 19:22:25 volumio volumio[999]: at AmpSwitchController.off (/data/plugins/system_controller/ampswitch/index.js:221:23) May 23 19:22:25 volumio volumio[999]: at Timeout._onTimeout (/data/plugins/system_controller/ampswitch/index.js:195:88) May 23 19:22:25 volumio volumio[999]: at listOnTimeout (internal/timers.js:554:17) May 23 19:22:25 volumio volumio[999]: at processTimers (internal/timers.js:497:7) { May 23 19:22:25 volumio volumio[999]: errno: -29, May 23 19:22:25 volumio volumio[999]: syscall: 'write', May 23 19:22:25 volumio volumio[999]: code: 'ESPIPE' May 23 19:22:25 volumio volumio[999]: } May 23 19:22:25 volumio volumio[999]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 23 19:22:25 volumio sudo[6860]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-23 19:21 May 23 19:22:25 volumio sudo[6860]: 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"