-- Logs begin at Sun 2025-10-26 13:17:33 JST, end at Sun 2025-10-26 14:07:46 JST. --
Oct 26 14:06:01 volumio go-librespot[1291]: time="2025-10-26T14:06:01+09:00" level=trace msg="sent dealer ping"
Oct 26 14:06:01 volumio go-librespot[1291]: time="2025-10-26T14:06:01+09:00" level=trace msg="received dealer pong"
Oct 26 14:06:02 volumio volumio[1015]: info: CoreCommandRouter::volumioGetState
Oct 26 14:06:07 volumio volumio[1015]: info: CoreCommandRouter::getUIConfigOnPlugin
Oct 26 14:06:07 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions
Oct 26 14:06:07 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Oct 26 14:06:07 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Oct 26 14:06:07 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Oct 26 14:06:07 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Oct 26 14:06:07 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Oct 26 14:06:07 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Oct 26 14:06:07 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Oct 26 14:06:07 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Oct 26 14:06:07 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Oct 26 14:06:07 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode
Oct 26 14:06:07 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus
Oct 26 14:06:12 volumio volumio[1015]: info: CoreCommandRouter::volumioGetState
Oct 26 14:06:22 volumio volumio[1015]: info: CoreCommandRouter::getUIConfigOnPlugin
Oct 26 14:06:22 volumio volumio[1015]: info: CoreCommandRouter::getUIConfigOnPlugin
Oct 26 14:06:22 volumio volumio[1015]: info: Retrieving Cloud Streaming UI
Oct 26 14:06:22 volumio volumio[1015]: info: Getting Tidal Cloud Configuration
Oct 26 14:06:22 volumio volumio[1015]: info: CoreCommandRouter::getUIConfigOnPlugin
Oct 26 14:06:22 volumio volumio[1015]: info: Getting Qobuz Cloud Configuration
Oct 26 14:06:22 volumio volumio[1015]: info: Asking plugin for UI Config
Oct 26 14:06:22 volumio volumio[1015]: info: CoreCommandRouter::getUIConfigOnPlugin
Oct 26 14:06:22 volumio volumio[1015]: info: Getting Spotify Cloud Configuration
Oct 26 14:06:22 volumio volumio[1015]: info: Asking plugin for UI Config
Oct 26 14:06:22 volumio volumio[1015]: info: CoreCommandRouter::getUIConfigOnPlugin
Oct 26 14:06:22 volumio volumio[1015]: info: Saving Spotify Acccount
Oct 26 14:06:22 volumio volumio[1015]: info: Got Tidal Cloud Configuration
Oct 26 14:06:22 volumio volumio[1015]: info: Got it
Oct 26 14:06:22 volumio volumio[1015]: info: Got it
Oct 26 14:06:22 volumio volumio[1015]: error: Could not retrieve Spotify Config from plugin Spotify: no section found
Oct 26 14:06:22 volumio volumio[1015]: info: CoreCommandRouter::getUIConfigOnPlugin
Oct 26 14:06:22 volumio volumio[1015]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined
Oct 26 14:06:22 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus
Oct 26 14:06:22 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Oct 26 14:06:22 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Oct 26 14:06:22 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Oct 26 14:06:22 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Oct 26 14:06:22 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Oct 26 14:06:22 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Oct 26 14:06:22 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Oct 26 14:06:22 volumio volumio[1015]: info: CoreCommandRouter::volumioGetBrowseSources
Oct 26 14:06:22 volumio volumio[1015]: info: CoreCommandRouter::volumioGetBrowseSources
Oct 26 14:06:22 volumio volumio[1015]: info: CoreCommandRouter::volumioGetBrowseSources
Oct 26 14:06:22 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Oct 26 14:06:22 volumio volumio[1015]: error: Failed LSINFO: null
Oct 26 14:06:22 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares
Oct 26 14:06:22 volumio volumio[1015]: info: Preload queue cleared
Oct 26 14:06:26 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Oct 26 14:06:30 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Oct 26 14:06:31 volumio go-librespot[1291]: time="2025-10-26T14:06:31+09:00" level=trace msg="received accesspoint ping"
Oct 26 14:06:31 volumio go-librespot[1291]: time="2025-10-26T14:06:31+09:00" level=trace msg="received accesspoint pong ack"
Oct 26 14:06:31 volumio go-librespot[1291]: time="2025-10-26T14:06:31+09:00" level=trace msg="sent dealer ping"
Oct 26 14:06:31 volumio go-librespot[1291]: time="2025-10-26T14:06:31+09:00" level=trace msg="received dealer pong"
Oct 26 14:06:34 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Oct 26 14:06:38 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Oct 26 14:06:42 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Oct 26 14:06:46 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Oct 26 14:06:50 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Oct 26 14:06:54 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Oct 26 14:06:58 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Oct 26 14:07:01 volumio go-librespot[1291]: time="2025-10-26T14:07:01+09:00" level=trace msg="sent dealer ping"
Oct 26 14:07:01 volumio go-librespot[1291]: time="2025-10-26T14:07:01+09:00" level=trace msg="received dealer pong"
Oct 26 14:07:02 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Oct 26 14:07:06 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Oct 26 14:07:09 volumio volumio[1015]: info: CALLMETHOD: music_service mpd saveMusicLibraryOptions [object Object]
Oct 26 14:07:09 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , saveMusicLibraryOptions
Oct 26 14:07:10 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Oct 26 14:07:14 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Oct 26 14:07:18 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Oct 26 14:07:22 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Oct 26 14:07:26 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Oct 26 14:07:30 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Oct 26 14:07:31 volumio go-librespot[1291]: time="2025-10-26T14:07:31+09:00" level=trace msg="sent dealer ping"
Oct 26 14:07:31 volumio go-librespot[1291]: time="2025-10-26T14:07:31+09:00" level=trace msg="received dealer pong"
Oct 26 14:07:34 volumio volumio[1015]: info: CALLMETHOD: miscellanea albumart clearAlbumartCache
Oct 26 14:07:34 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: albumart , clearAlbumartCache
Oct 26 14:07:34 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , rebuildAlbumCache
Oct 26 14:07:34 volumio volumio[1015]: info: Rebuild Album cache
Oct 26 14:07:34 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Oct 26 14:07:38 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Oct 26 14:07:39 volumio volumio[1015]: info: CALLMETHOD: miscellanea albumart saveAlbumartOptions [object Object]
Oct 26 14:07:39 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: albumart , saveAlbumartOptions
Oct 26 14:07:42 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Oct 26 14:07:46 volumio volumio[1015]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb
Oct 26 14:07:46 volumio volumio[1015]: verbose: ControllerMpd::sendMpdCommand update
Oct 26 14:07:46 volumio volumio[1015]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Oct 26 14:07:46 volumio volumio[1015]: TypeError: Cannot read property 'substring' of undefined
Oct 26 14:07:46 volumio volumio[1015]:     at /volumio/app/plugins/music_service/mpd/index.js:2827:70
Oct 26 14:07:46 volumio volumio[1015]:     at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3)
Oct 26 14:07:46 volumio volumio[1015]:     at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12)
Oct 26 14:07:46 volumio volumio[1015]:     at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12)
Oct 26 14:07:46 volumio volumio[1015]:     at Socket.emit (events.js:315:20)
Oct 26 14:07:46 volumio volumio[1015]:     at addChunk (internal/streams/readable.js:309:12)
Oct 26 14:07:46 volumio volumio[1015]:     at readableAddChunk (internal/streams/readable.js:280:11)
Oct 26 14:07:46 volumio volumio[1015]:     at Socket.Readable.push (internal/streams/readable.js:223:10)
Oct 26 14:07:46 volumio volumio[1015]:     at Pipe.onStreamRead (internal/stream_base_commons.js:188:23)
Oct 26 14:07:46 volumio volumio[1015]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Oct 26 14:07:46 volumio sudo[8822]:  volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-26 14:06
Oct 26 14:07:46 volumio sudo[8822]: 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="29866754e5f1d7e4d0f581c10d9f22852f6f21db"
VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6"
VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a"
VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST"
VOLUMIO_VERSION="3.832"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"