-- Logs begin at Thu 2025-04-17 11:53:56 CEST, end at Sat 2025-04-19 12:15:29 CEST. -- Apr 19 12:14:00 volumio-rivo vtcs[3619]: [2025-04-19 12:14:00.906] [tisoc] [warning] [PlaybackControllerImpl.cpp:201] Illegal state:1 Apr 19 12:14:05 volumio-rivo vtcs[3619]: [2025-04-19 12:14:05] [info] asio async_shutdown error: asio.misc:2 (End of file) Apr 19 12:14:13 volumio-rivo vtcs[3619]: [2025-04-19 12:14:13.312] [tisoc] [warning] [QueueManagerImpl.cpp:844] State=IDLE. Not send NotifyQueueChanged. Apr 19 12:14:13 volumio-rivo vtcs[3619]: [2025-04-19 12:14:13.312] [tisoc] [warning] [PlaybackControllerImpl.cpp:923] Not send NotifyMediaChanged(media_info is null) Apr 19 12:14:13 volumio-rivo volumio[3115]: info: CoreCommandRouter::servicePushState Apr 19 12:14:13 volumio-rivo volumio[3115]: info: CoreStateMachine::pushState Apr 19 12:14:13 volumio-rivo volumio[3115]: info: CoreCommandRouter::volumioPushState Apr 19 12:14:13 volumio-rivo volumio[3115]: info: MRS: Pushing multiroomSync output update for this device Apr 19 12:14:13 volumio-rivo volumio[3115]: info: MRS: Pushing multiroomSync output Apr 19 12:14:15 volumio-rivo vtcs[3619]: [2025-04-19 12:14:15.498] [tisoc] [warning] [PlaybackControllerImpl.cpp:201] Illegal state:1 Apr 19 12:14:16 volumio-rivo vtcs[3619]: [2025-04-19 12:14:16.271] [tisoc] [warning] [PlaybackControllerImpl.cpp:201] Illegal state:1 Apr 19 12:14:16 volumio-rivo vtcs[3619]: [2025-04-19 12:14:16.851] [tisoc] [warning] [PlaybackControllerImpl.cpp:201] Illegal state:1 Apr 19 12:14:17 volumio-rivo vtcs[3619]: [2025-04-19 12:14:17.217] [tisoc] [warning] [PlaybackControllerImpl.cpp:201] Illegal state:1 Apr 19 12:14:19 volumio-rivo volumiologrotate[2835]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Apr 19 12:14:19 volumio-rivo volumiologrotate[2835]: ls: cannot access 'RIVO+': No such file or directory Apr 19 12:14:26 volumio-rivo vtcs[3619]: [2025-04-19 12:14:26.849] [tisoc] [warning] [PlaybackControllerImpl.cpp:201] Illegal state:1 Apr 19 12:14:28 volumio-rivo vtcs[3619]: [2025-04-19 12:14:28.215] [tisoc] [warning] [PlaybackControllerImpl.cpp:201] Illegal state:1 Apr 19 12:14:52 volumio-rivo vtcs[3619]: [2025-04-19 12:14:52.567] [tisoc] [warning] [PlaybackControllerImpl.cpp:201] Illegal state:1 Apr 19 12:15:01 volumio-rivo vtcs[3619]: [2025-04-19 12:15:01.944] [tisoc] [warning] [PlaybackControllerImpl.cpp:520] Illegal state:1 Apr 19 12:15:01 volumio-rivo volumio[3115]: info: PlaybackInactive Apr 19 12:15:01 volumio-rivo volumio[3115]: info: Device Session is_active: true Apr 19 12:15:01 volumio-rivo volumio[3115]: info: DeviceInactive Apr 19 12:15:01 volumio-rivo volumio[3115]: info: Relinquishing Volumio State Apr 19 12:15:01 volumio-rivo volumio[3115]: verbose: UNSET VOLATILE: Service: tidalconnect Apr 19 12:15:01 volumio-rivo volumio[3115]: info: unSetVolatile called Apr 19 12:15:01 volumio-rivo volumio[3115]: info: Relinquishing Volumio State to another service Apr 19 12:15:01 volumio-rivo vtcs[3619]: [2025-04-19 12:15:01.947] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Apr 19 12:15:02 volumio-rivo vtcs[3619]: [2025-04-19 12:15:02] [info] asio async_shutdown error: asio.misc:2 (End of file) Apr 19 12:15:02 volumio-rivo volumio[3115]: info: CoreCommandRouter::volumioGetState Apr 19 12:15:02 volumio-rivo volumio[3115]: info: CorePlayQueue::getTrack 0 Apr 19 12:15:02 volumio-rivo volumio[3115]: info: CoreCommandRouter::volumioGetState Apr 19 12:15:02 volumio-rivo volumio[3115]: info: CorePlayQueue::getTrack 0 Apr 19 12:15:02 volumio-rivo volumio[3115]: info: Currently active: webradio Apr 19 12:15:02 volumio-rivo volumio[3115]: info: Stopping currently active service Apr 19 12:15:02 volumio-rivo volumio[3115]: info: CoreCommandRouter::volumioStop Apr 19 12:15:02 volumio-rivo volumio[3115]: info: CoreStateMachine::stop Apr 19 12:15:02 volumio-rivo volumio[3115]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 19 12:15:02 volumio-rivo volumio[3115]: info: CoreStateMachine::updateTrackBlock Apr 19 12:15:02 volumio-rivo volumio[3115]: info: CorePlayQueue::getTrackBlock Apr 19 12:15:02 volumio-rivo volumio[3115]: info: CoreStateMachine::stPlaybackTimer Apr 19 12:15:02 volumio-rivo volumio[3115]: info: CoreStateMachine::pushState Apr 19 12:15:02 volumio-rivo volumio[3115]: info: CorePlayQueue::getTrack 0 Apr 19 12:15:02 volumio-rivo volumio[3115]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 12:15:02 volumio-rivo volumio[3115]: info: CoreCommandRouter::volumioPushState Apr 19 12:15:02 volumio-rivo volumio[3115]: info: MRS: Pushing multiroomSync output update for this device Apr 19 12:15:02 volumio-rivo volumio[3115]: info: MRS: Pushing multiroomSync output Apr 19 12:15:02 volumio-rivo volumio[3115]: info: CoreStateMachine::serviceStop Apr 19 12:15:02 volumio-rivo volumio[3115]: info: CorePlayQueue::getTrack 0 Apr 19 12:15:02 volumio-rivo volumio[3115]: info: CoreCommandRouter::serviceStop Apr 19 12:15:02 volumio-rivo volumio[3115]: info: [1745057702318] ControllerWebradio::stop Apr 19 12:15:02 volumio-rivo volumio[3115]: verbose: ControllerMpd::sendMpdCommand stop Apr 19 12:15:02 volumio-rivo volumio[3115]: info: sendMpdCommand stop took 14 milliseconds Apr 19 12:15:02 volumio-rivo volumio[3115]: info: TidalConnect Active Apr 19 12:15:02 volumio-rivo volumio[3115]: info: CoreCommandRouter::volumioGetState Apr 19 12:15:02 volumio-rivo volumio[3115]: info: CorePlayQueue::getTrack 0 Apr 19 12:15:02 volumio-rivo volumio[3115]: info: Currently active: webradio Apr 19 12:15:02 volumio-rivo volumio[3115]: info: Setting Volatile state to tidalconnect Apr 19 12:15:02 volumio-rivo volumio[3115]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 19 12:15:02 volumio-rivo volumio[3115]: info: CoreCommandRouter::servicePushState Apr 19 12:15:02 volumio-rivo volumio[3115]: info: CoreStateMachine::pushState Apr 19 12:15:02 volumio-rivo volumio[3115]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 19 12:15:02 volumio-rivo volumio[3115]: info: CoreCommandRouter::volumioPushState Apr 19 12:15:02 volumio-rivo volumio[3115]: info: MRS: Pushing multiroomSync output update for this device Apr 19 12:15:02 volumio-rivo volumio[3115]: info: MRS: Pushing multiroomSync output Apr 19 12:15:02 volumio-rivo volumio[3115]: info: CoreCommandRouter::servicePushState Apr 19 12:15:02 volumio-rivo volumio[3115]: info: CoreStateMachine::pushState Apr 19 12:15:02 volumio-rivo volumio[3115]: info: CoreCommandRouter::volumioPushState Apr 19 12:15:02 volumio-rivo volumio[3115]: info: MRS: Pushing multiroomSync output update for this device Apr 19 12:15:02 volumio-rivo volumio[3115]: info: MRS: Pushing multiroomSync output Apr 19 12:15:08 volumio-rivo vtcs[3619]: [2025-04-19 12:15:08.928] [tisoc] [warning] [PlaybackControllerImpl.cpp:201] Illegal state:1 Apr 19 12:15:09 volumio-rivo vtcs[3619]: [2025-04-19 12:15:09.732] [tisoc] [warning] [PlaybackControllerImpl.cpp:201] Illegal state:1 Apr 19 12:15:13 volumio-rivo vtcs[3619]: [2025-04-19 12:15:13] [info] asio async_shutdown error: asio.misc:2 (End of file) Apr 19 12:15:15 volumio-rivo volumio[3115]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 19 12:15:15 volumio-rivo volumio[3115]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 19 12:15:15 volumio-rivo volumio[3115]: info: Discovery: Getting this device information Apr 19 12:15:15 volumio-rivo volumio[3115]: info: CoreCommandRouter::volumioGetState Apr 19 12:15:15 volumio-rivo volumio[3115]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 19 12:15:15 volumio-rivo volumio[3115]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 19 12:15:15 volumio-rivo volumio[3115]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 19 12:15:15 volumio-rivo volumio[3115]: info: Discovery: Getting this device information Apr 19 12:15:15 volumio-rivo volumio[3115]: info: CoreCommandRouter::volumioGetState Apr 19 12:15:15 volumio-rivo volumio[3115]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 19 12:15:15 volumio-rivo volumio[3115]: verbose: New Socket.io Connection to 192.168.1.101 from 192.168.1.118 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 7 Apr 19 12:15:16 volumio-rivo volumio[3115]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 19 12:15:16 volumio-rivo volumio[3115]: info: CoreCommandRouter::volumioGetVisibleSources Apr 19 12:15:16 volumio-rivo volumio[3115]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 19 12:15:16 volumio-rivo volumio[3115]: info: CoreCommandRouter::volumioGetState Apr 19 12:15:16 volumio-rivo volumio[3115]: info: CoreCommandRouter::volumioGetState Apr 19 12:15:16 volumio-rivo volumio[3115]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 19 12:15:16 volumio-rivo volumio[3115]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 19 12:15:16 volumio-rivo volumio[3115]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Apr 19 12:15:16 volumio-rivo volumio[3115]: info: Received Get System Info Apr 19 12:15:16 volumio-rivo volumio[3115]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 19 12:15:16 volumio-rivo volumio[3115]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 19 12:15:16 volumio-rivo volumio[3115]: info: Discovery: Getting this device information Apr 19 12:15:16 volumio-rivo volumio[3115]: info: CoreCommandRouter::volumioGetState Apr 19 12:15:16 volumio-rivo volumio[3115]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 19 12:15:16 volumio-rivo volumio[3115]: info: CoreCommandRouter::volumioGetState Apr 19 12:15:16 volumio-rivo volumio[3115]: info: Listing playlists Apr 19 12:15:16 volumio-rivo volumio[3115]: info: CoreCommandRouter::volumioGetQueue Apr 19 12:15:16 volumio-rivo volumio[3115]: info: CoreStateMachine::getQueue Apr 19 12:15:16 volumio-rivo volumio[3115]: info: CorePlayQueue::getQueue Apr 19 12:15:16 volumio-rivo volumio[3115]: verbose: New Socket.io Connection to 192.168.1.101:3000 from 192.168.1.118 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Apr 19 12:15:16 volumio-rivo volumio[3115]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 19 12:15:19 volumio-rivo volumiologrotate[2835]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Apr 19 12:15:19 volumio-rivo volumiologrotate[2835]: ls: cannot access 'RIVO+': No such file or directory Apr 19 12:15:23 volumio-rivo volumio[3115]: info: CoreCommandRouter::volumioGetState Apr 19 12:15:25 volumio-rivo volumio[3115]: info: Executing endpoint metavolumio Apr 19 12:15:25 volumio-rivo volumio[3115]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 19 12:15:25 volumio-rivo volumio[3115]: info: Executing endpoint metavolumio Apr 19 12:15:25 volumio-rivo volumio[3115]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 19 12:15:25 volumio-rivo volumio[3115]: info: Executing endpoint metavolumio Apr 19 12:15:25 volumio-rivo volumio[3115]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 19 12:15:28 volumio-rivo volumio[3115]: info: CoreCommandRouter::volumioRemoveQueueItem Apr 19 12:15:28 volumio-rivo volumio[3115]: info: CoreStateMachine::removeQueueItem Apr 19 12:15:28 volumio-rivo volumio[3115]: info: CoreStateMachine::stop Apr 19 12:15:28 volumio-rivo volumio[3115]: info: CoreStateMachine::serviceStop Apr 19 12:15:28 volumio-rivo volumio[3115]: info: CoreCommandRouter::serviceStop Apr 19 12:15:28 volumio-rivo vtcs[3619]: [2025-04-19 12:15:28.930] [tisoc] [warning] [PlaybackControllerImpl.cpp:520] Illegal state:1 Apr 19 12:15:28 volumio-rivo volumio[3115]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 19 12:15:28 volumio-rivo volumio[3115]: TypeError: Cannot read property 'then' of undefined Apr 19 12:15:28 volumio-rivo volumio[3115]: at CoreStateMachine.removeQueueItem (/volumio/app/statemachine.js:1368:7) Apr 19 12:15:28 volumio-rivo volumio[3115]: at CoreCommandRouter.volumioRemoveQueueItem (/volumio/app/index.js:121:28) Apr 19 12:15:28 volumio-rivo volumio[3115]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:173:33) Apr 19 12:15:28 volumio-rivo volumio[3115]: at Socket.emit (events.js:400:28) Apr 19 12:15:28 volumio-rivo volumio[3115]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Apr 19 12:15:28 volumio-rivo volumio[3115]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Apr 19 12:15:28 volumio-rivo volumio[3115]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 19 12:15:29 volumio-rivo sudo[3899]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-19 12:14 Apr 19 12:15:29 volumio-rivo sudo[3899]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivoplus" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 07:42:11 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="4f5519a96ae5ba7dcfda362cc37343d4"