-- Logs begin at Sun 2025-11-02 10:52:01 WIB, end at Sun 2025-11-02 11:04:39 WIB. -- Nov 02 11:03:00 wh go-librespot[2437]: time="2025-11-02T11:03:00+07:00" level=trace msg="received dealer pong" Nov 02 11:03:04 wh volumio[2252]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 11:03:04 wh volumio[2252]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 11:03:04 wh volumio[2252]: info: Discovery: Getting this device information Nov 02 11:03:04 wh volumio[2252]: info: CoreCommandRouter::volumioGetState Nov 02 11:03:04 wh volumio[2252]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 11:03:29 wh go-librespot[2437]: time="2025-11-02T11:03:29+07:00" level=trace msg="sent dealer ping" Nov 02 11:03:30 wh go-librespot[2437]: time="2025-11-02T11:03:30+07:00" level=trace msg="received dealer pong" Nov 02 11:03:34 wh volumio[2252]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 11:03:34 wh volumio[2252]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 11:03:34 wh volumio[2252]: info: Discovery: Getting this device information Nov 02 11:03:34 wh volumio[2252]: info: CoreCommandRouter::volumioGetState Nov 02 11:03:34 wh volumio[2252]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 11:03:53 wh vtcs[2705]: [close:85] Entering Nov 02 11:03:53 wh volumio[2252]: info: CoreCommandRouter::servicePushState Nov 02 11:03:53 wh volumio[2252]: info: CoreStateMachine::pushState Nov 02 11:03:53 wh volumio[2252]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 11:03:53 wh volumio[2252]: info: CoreCommandRouter::volumioPushState Nov 02 11:03:53 wh volumio[2252]: info: MRS: Pushing multiroomSync output update for this device Nov 02 11:03:53 wh volumio[2252]: info: MRS: Pushing multiroomSync output Nov 02 11:03:53 wh vtcs[2705]: [feedThread:276] Exiting Nov 02 11:03:53 wh vtcs[2705]: [close:100] Exiting Nov 02 11:03:53 wh volumio[2252]: info: CoreCommandRouter::servicePushState Nov 02 11:03:53 wh volumio[2252]: info: CoreStateMachine::pushState Nov 02 11:03:53 wh volumio[2252]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 11:03:53 wh volumio[2252]: info: CoreCommandRouter::volumioPushState Nov 02 11:03:53 wh volumio[2252]: info: MRS: Pushing multiroomSync output update for this device Nov 02 11:03:53 wh volumio[2252]: info: MRS: Pushing multiroomSync output Nov 02 11:03:53 wh volumio[2252]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 02 11:03:53 wh volumio[2252]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 02 11:03:59 wh go-librespot[2437]: time="2025-11-02T11:03:59+07:00" level=trace msg="sent dealer ping" Nov 02 11:04:00 wh go-librespot[2437]: time="2025-11-02T11:04:00+07:00" level=trace msg="received dealer pong" Nov 02 11:04:04 wh volumio[2252]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 11:04:04 wh volumio[2252]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 11:04:04 wh volumio[2252]: info: Discovery: Getting this device information Nov 02 11:04:04 wh volumio[2252]: info: CoreCommandRouter::volumioGetState Nov 02 11:04:04 wh volumio[2252]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 11:04:07 wh volumio[2252]: info: Pushing metadata Nov 02 11:04:07 wh volumio[2252]: info: CoreCommandRouter::servicePushState Nov 02 11:04:07 wh volumio[2252]: info: CoreStateMachine::pushState Nov 02 11:04:07 wh volumio[2252]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 11:04:07 wh volumio[2252]: info: CoreCommandRouter::volumioPushState Nov 02 11:04:07 wh volumio[2252]: info: MRS: Pushing multiroomSync output update for this device Nov 02 11:04:07 wh volumio[2252]: info: MRS: Pushing multiroomSync output Nov 02 11:04:07 wh volumio[2252]: info: CoreCommandRouter::servicePushState Nov 02 11:04:07 wh volumio[2252]: info: CoreStateMachine::pushState Nov 02 11:04:07 wh volumio[2252]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 11:04:07 wh volumio[2252]: info: CoreCommandRouter::volumioPushState Nov 02 11:04:07 wh volumio[2252]: info: MRS: Pushing multiroomSync output update for this device Nov 02 11:04:07 wh volumio[2252]: info: MRS: Pushing multiroomSync output Nov 02 11:04:07 wh volumio[2252]: info: CoreCommandRouter::servicePushState Nov 02 11:04:07 wh volumio[2252]: info: CoreStateMachine::pushState Nov 02 11:04:07 wh volumio[2252]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 11:04:07 wh volumio[2252]: info: CoreCommandRouter::volumioPushState Nov 02 11:04:07 wh volumio[2252]: info: MRS: Pushing multiroomSync output update for this device Nov 02 11:04:07 wh volumio[2252]: info: MRS: Pushing multiroomSync output Nov 02 11:04:07 wh volumio[2252]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 02 11:04:07 wh vtcs[2705]: [close:85] Entering Nov 02 11:04:07 wh vtcs[2705]: [close:100] Exiting Nov 02 11:04:07 wh volumio[2252]: info: Pushing metadata Nov 02 11:04:07 wh volumio[2252]: info: CoreCommandRouter::servicePushState Nov 02 11:04:07 wh volumio[2252]: info: CoreStateMachine::pushState Nov 02 11:04:07 wh volumio[2252]: info: CoreCommandRouter::volumioPushState Nov 02 11:04:07 wh volumio[2252]: info: MRS: Pushing multiroomSync output update for this device Nov 02 11:04:07 wh volumio[2252]: info: MRS: Pushing multiroomSync output Nov 02 11:04:07 wh volumio[2252]: info: CoreCommandRouter::servicePushState Nov 02 11:04:07 wh volumio[2252]: info: CoreStateMachine::pushState Nov 02 11:04:07 wh volumio[2252]: info: CoreCommandRouter::volumioPushState Nov 02 11:04:07 wh volumio[2252]: info: MRS: Pushing multiroomSync output update for this device Nov 02 11:04:07 wh volumio[2252]: info: MRS: Pushing multiroomSync output Nov 02 11:04:07 wh volumio[2252]: info: CoreCommandRouter::servicePushState Nov 02 11:04:07 wh volumio[2252]: info: CoreStateMachine::pushState Nov 02 11:04:07 wh volumio[2252]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 11:04:07 wh volumio[2252]: info: CoreCommandRouter::volumioPushState Nov 02 11:04:07 wh volumio[2252]: info: MRS: Pushing multiroomSync output update for this device Nov 02 11:04:07 wh volumio[2252]: info: MRS: Pushing multiroomSync output Nov 02 11:04:08 wh volumio[2252]: info: CoreCommandRouter::servicePushState Nov 02 11:04:08 wh volumio[2252]: info: CoreStateMachine::pushState Nov 02 11:04:08 wh volumio[2252]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 11:04:08 wh volumio[2252]: info: CoreCommandRouter::volumioPushState Nov 02 11:04:08 wh volumio[2252]: info: MRS: Pushing multiroomSync output update for this device Nov 02 11:04:08 wh volumio[2252]: info: MRS: Pushing multiroomSync output Nov 02 11:04:08 wh volumio[2252]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 02 11:04:08 wh vtcs[2705]: [open:50] Opening with sample rate: 96000, type: 3 Nov 02 11:04:08 wh vtcs[2705]: [start:106] Entering Nov 02 11:04:08 wh vtcs[2705]: [start:113] Reopening PCM device... Nov 02 11:04:08 wh volumio[2252]: info: CoreCommandRouter::servicePushState Nov 02 11:04:08 wh volumio[2252]: info: CoreStateMachine::pushState Nov 02 11:04:08 wh volumio[2252]: info: CoreCommandRouter::volumioPushState Nov 02 11:04:08 wh vtcs[2705]: [start:115] PCM opened successfully. Nov 02 11:04:08 wh vtcs[2705]: [start:120] Exiting Nov 02 11:04:08 wh vtcs[2705]: [feedThread:223] Entering Nov 02 11:04:08 wh volumio[2252]: info: MRS: Pushing multiroomSync output update for this device Nov 02 11:04:08 wh volumio[2252]: info: MRS: Pushing multiroomSync output Nov 02 11:04:08 wh volumio[2252]: info: CoreCommandRouter::servicePushState Nov 02 11:04:08 wh volumio[2252]: info: CoreStateMachine::pushState Nov 02 11:04:08 wh volumio[2252]: info: CoreCommandRouter::volumioPushState Nov 02 11:04:08 wh volumio[2252]: info: MRS: Pushing multiroomSync output update for this device Nov 02 11:04:08 wh volumio[2252]: info: MRS: Pushing multiroomSync output Nov 02 11:04:08 wh volumio[2252]: info: CoreCommandRouter::servicePushState Nov 02 11:04:08 wh volumio[2252]: info: CoreStateMachine::pushState Nov 02 11:04:08 wh volumio[2252]: info: CoreCommandRouter::volumioPushState Nov 02 11:04:08 wh volumio[2252]: info: MRS: Pushing multiroomSync output update for this device Nov 02 11:04:08 wh volumio[2252]: info: MRS: Pushing multiroomSync output Nov 02 11:04:08 wh volumio[2252]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 02 11:04:08 wh volumio[2252]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 02 11:04:09 wh volumio[2252]: info: CoreCommandRouter::servicePushState Nov 02 11:04:09 wh volumio[2252]: info: CoreStateMachine::pushState Nov 02 11:04:09 wh volumio[2252]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 11:04:09 wh volumio[2252]: info: CoreCommandRouter::volumioPushState Nov 02 11:04:09 wh volumio[2252]: info: MRS: Pushing multiroomSync output update for this device Nov 02 11:04:09 wh volumio[2252]: info: MRS: Pushing multiroomSync output Nov 02 11:04:09 wh volumio[2252]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 02 11:04:09 wh volumio[2252]: info: Executing endpoint metavolumio Nov 02 11:04:09 wh volumio[2252]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 02 11:04:09 wh volumio[2252]: info: Executing endpoint metavolumio Nov 02 11:04:09 wh volumio[2252]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 02 11:04:09 wh volumio[2252]: info: Executing endpoint metavolumio Nov 02 11:04:09 wh volumio[2252]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 02 11:04:10 wh volumio[2252]: info: PlaybackInactive Nov 02 11:04:10 wh volumio[2252]: info: Device Session is_active: true Nov 02 11:04:10 wh volumio[2252]: info: DeviceInactive Nov 02 11:04:10 wh volumio[2252]: info: Relinquishing Volumio State Nov 02 11:04:10 wh volumio[2252]: verbose: UNSET VOLATILE: Service: tidalconnect Nov 02 11:04:10 wh volumio[2252]: info: unSetVolatile called Nov 02 11:04:10 wh volumio[2252]: info: Relinquishing Volumio State to another service Nov 02 11:04:10 wh vtcs[2705]: [2025-11-02 11:04:10.312] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Nov 02 11:04:10 wh vtcs[2705]: [close:85] Entering Nov 02 11:04:10 wh vtcs[2705]: [feedThread:276] Exiting Nov 02 11:04:10 wh vtcs[2705]: [close:100] Exiting Nov 02 11:04:10 wh vtcs[2705]: [2025-11-02 11:04:10] [info] asio async_shutdown error: asio.misc:2 (End of file) Nov 02 11:04:29 wh go-librespot[2437]: time="2025-11-02T11:04:29+07:00" level=trace msg="sent dealer ping" Nov 02 11:04:30 wh go-librespot[2437]: time="2025-11-02T11:04:30+07:00" level=trace msg="received dealer pong" Nov 02 11:04:35 wh volumio[2252]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 11:04:35 wh volumio[2252]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 11:04:35 wh volumio[2252]: info: Discovery: Getting this device information Nov 02 11:04:35 wh volumio[2252]: info: CoreCommandRouter::volumioGetState Nov 02 11:04:35 wh volumio[2252]: info: CorePlayQueue::getTrack 0 Nov 02 11:04:35 wh volumio[2252]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 11:04:39 wh volumio[2252]: info: CoreCommandRouter::volumioPrevious Nov 02 11:04:39 wh volumio[2252]: info: CoreStateMachine::previous Nov 02 11:04:39 wh volumio[2252]: info: CorePlayQueue::getTrack 0 Nov 02 11:04:39 wh volumio[2252]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 11:04:39 wh volumio[2252]: TypeError: Cannot read property 'service' of undefined Nov 02 11:04:39 wh volumio[2252]: at CoreStateMachine.previous (/volumio/app/statemachine.js:1327:97) Nov 02 11:04:39 wh volumio[2252]: at CoreCommandRouter.volumioPrevious (/volumio/app/index.js:97:28) Nov 02 11:04:39 wh volumio[2252]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:270:33) Nov 02 11:04:39 wh volumio[2252]: at Socket.emit (events.js:400:28) Nov 02 11:04:39 wh volumio[2252]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Nov 02 11:04:39 wh volumio[2252]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Nov 02 11:04:39 wh volumio[2252]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 11:04:39 wh sudo[4399]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-11-02 11:03 Nov 02 11:04:39 wh sudo[4399]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="669bb6cc406bb77b11c5184fedb8a4dfd19ba4ec" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 11:08:46 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="9ef0bfa9b61509be20c9051562554f31"