-- Logs begin at Thu 2024-10-10 18:45:27 CEST, end at Fri 2024-10-11 16:19:14 CEST. -- Oct 11 16:18:20 volumio volumio[804]: info: Tunnel connection is inactive, restarting it Oct 11 16:18:20 volumio volumio[804]: info: Starting Tunnel 1 Oct 11 16:18:20 volumio volumio[804]: info: Starting Tunnel Connection Checker Oct 11 16:18:20 volumio sudo[2856]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Oct 11 16:18:20 volumio sudo[2856]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 16:18:20 volumio autossh[2721]: received signal to exit (15) Oct 11 16:18:20 volumio systemd[1]: Stopping MyVolumio SSH Tunnel... Oct 11 16:18:20 volumio systemd[1]: sshtunnel.service: Main process exited, code=killed, status=15/TERM Oct 11 16:18:20 volumio systemd[1]: sshtunnel.service: Succeeded. Oct 11 16:18:20 volumio systemd[1]: Stopped MyVolumio SSH Tunnel. Oct 11 16:18:20 volumio systemd[1]: Started MyVolumio SSH Tunnel. Oct 11 16:18:20 volumio sudo[2856]: pam_unix(sudo:session): session closed for user root Oct 11 16:18:20 volumio volumio[804]: info: Remote SSH Started Oct 11 16:18:20 volumio autossh[2860]: port set to 0, monitoring disabled Oct 11 16:18:20 volumio autossh[2860]: starting ssh (count 1) Oct 11 16:18:20 volumio autossh[2860]: ssh child pid is 2863 Oct 11 16:18:20 volumio volumio[804]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Oct 11 16:18:20 volumio volumio[804]: info: CoreCommandRouter::volumioGetState Oct 11 16:18:44 volumio volumiossh-tunnel[2858]: Warning: Permanently added '[eu4.myvolumio.org]:2222,[167.172.103.77]:2222' (RSA) to the list of known hosts. Oct 11 16:19:01 volumio volumio[804]: info: Pushing metadata Oct 11 16:19:01 volumio volumio[804]: info: CoreCommandRouter::servicePushState Oct 11 16:19:01 volumio volumio[804]: info: CoreStateMachine::pushState Oct 11 16:19:01 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 16:19:01 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Oct 11 16:19:01 volumio volumio[804]: info: CoreCommandRouter::servicePushState Oct 11 16:19:01 volumio volumio[804]: info: CoreStateMachine::pushState Oct 11 16:19:01 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Oct 11 16:19:02 volumio volumio[804]: info: CoreCommandRouter::servicePushState Oct 11 16:19:02 volumio volumio[804]: info: CoreStateMachine::pushState Oct 11 16:19:02 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 16:19:02 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Oct 11 16:19:07 volumio volumio[804]: info: PlaybackInactive Oct 11 16:19:07 volumio volumio[804]: info: Device Session is_active: true Oct 11 16:19:07 volumio volumio[804]: info: DeviceInactive Oct 11 16:19:07 volumio volumio[804]: info: Relinquishing Volumio State Oct 11 16:19:07 volumio volumio[804]: verbose: UNSET VOLATILE: Service: tidalconnect Oct 11 16:19:07 volumio volumio[804]: info: unSetVolatile called Oct 11 16:19:07 volumio volumio[804]: info: Relinquishing Volumio State to another service Oct 11 16:19:07 volumio vtcs[1324]: [2024-10-11 16:19:07.020] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Oct 11 16:19:07 volumio vtcs[1324]: [2024-10-11 16:19:07.228] [tisoc] [warning] [PlaybackControllerImpl.cpp:510] Illegal state:0 Oct 11 16:19:08 volumio vtcs[1324]: [2024-10-11 16:19:08] [info] asio async_shutdown error: asio.misc:2 (End of file) Oct 11 16:19:09 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 16:19:09 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 16:19:09 volumio volumio[804]: info: Discovery: Getting this device information Oct 11 16:19:09 volumio volumio[804]: info: CoreCommandRouter::volumioGetState Oct 11 16:19:09 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Oct 11 16:19:09 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 16:19:09 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 16:19:09 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 16:19:09 volumio volumio[804]: info: Discovery: Getting this device information Oct 11 16:19:09 volumio volumio[804]: info: CoreCommandRouter::volumioGetState Oct 11 16:19:09 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Oct 11 16:19:09 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 16:19:10 volumio volumio[804]: verbose: New Socket.io Connection to 192.168.2.156:3000 from 192.168.2.120 UA: Dart/3.4 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Oct 11 16:19:11 volumio volumio[804]: verbose: New Socket.io Connection to 192.168.2.156 from 192.168.2.120 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: 6 Oct 11 16:19:11 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 11 16:19:11 volumio volumio[804]: info: CoreCommandRouter::volumioGetVisibleSources Oct 11 16:19:11 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 16:19:11 volumio volumio[804]: info: CoreCommandRouter::volumioGetState Oct 11 16:19:11 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Oct 11 16:19:11 volumio volumio[804]: info: CoreCommandRouter::volumioGetState Oct 11 16:19:11 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Oct 11 16:19:11 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 11 16:19:11 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 11 16:19:11 volumio volumio[804]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 11 16:19:11 volumio volumio[804]: info: Received Get System Info Oct 11 16:19:11 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 11 16:19:11 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 11 16:19:11 volumio volumio[804]: info: Discovery: Getting this device information Oct 11 16:19:11 volumio volumio[804]: info: CoreCommandRouter::volumioGetState Oct 11 16:19:11 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Oct 11 16:19:11 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 11 16:19:11 volumio volumio[804]: info: CoreCommandRouter::volumioGetState Oct 11 16:19:11 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Oct 11 16:19:11 volumio volumio[804]: info: Listing playlists Oct 11 16:19:11 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 11 16:19:13 volumio volumio[804]: info: Executing endpoint metavolumio Oct 11 16:19:13 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 11 16:19:14 volumio volumio[804]: info: CoreCommandRouter::volumioPlay Oct 11 16:19:14 volumio volumio[804]: info: CoreStateMachine::play index undefined Oct 11 16:19:14 volumio volumio[804]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 16:19:14 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Oct 11 16:19:14 volumio volumio[804]: info: CoreStateMachine::startPlaybackTimer Oct 11 16:19:14 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Oct 11 16:19:14 volumio volumio[804]: info: CoreCommandRouter::volumioGetVisibleSources Oct 11 16:19:14 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 16:19:14 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 11 16:19:14 volumio volumio[804]: verbose: ControllerMpd::sendMpdCommand play Oct 11 16:19:14 volumio volumio[804]: info: sendMpdCommand play took 5 milliseconds Oct 11 16:19:14 volumio volumio[804]: info: CoreCommandRouter::servicePushState Oct 11 16:19:14 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Oct 11 16:19:14 volumio volumio[804]: verbose: STATE SERVICE {"status":"play"} Oct 11 16:19:14 volumio volumio[804]: verbose: CURRENT POSITION 0 Oct 11 16:19:14 volumio volumio[804]: info: CoreStateMachine::syncState stateService play Oct 11 16:19:14 volumio volumio[804]: info: CoreStateMachine::syncState currentStatus pause Oct 11 16:19:14 volumio volumio[804]: info: CoreStateMachine::pushState Oct 11 16:19:14 volumio volumio[804]: info: CorePlayQueue::getTrack 0 Oct 11 16:19:14 volumio volumio[804]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 16:19:14 volumio volumio[804]: info: CoreCommandRouter::volumioPushState Oct 11 16:19:14 volumio volumio[804]: info: [1728656354250] [MotherEarth] Error: connect ECONNREFUSED 127.0.0.1:443 Oct 11 16:19:14 volumio volumio[804]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 11 16:19:14 volumio volumio[804]: TypeError: Cannot read property 'toUpperCase' of undefined Oct 11 16:19:14 volumio volumio[804]: at motherearthradio.errorToast (/data/plugins/music_service/motherearthradio/index.js:423:41) Oct 11 16:19:14 volumio volumio[804]: at ClientRequest. (/data/plugins/music_service/motherearthradio/index.js:395:14) Oct 11 16:19:14 volumio volumio[804]: at ClientRequest.emit (events.js:315:20) Oct 11 16:19:14 volumio volumio[804]: at TLSSocket.socketErrorListener (_http_client.js:469:9) Oct 11 16:19:14 volumio volumio[804]: at TLSSocket.emit (events.js:315:20) Oct 11 16:19:14 volumio volumio[804]: at emitErrorNT (internal/streams/destroy.js:106:8) Oct 11 16:19:14 volumio volumio[804]: at emitErrorCloseNT (internal/streams/destroy.js:74:3) Oct 11 16:19:14 volumio volumio[804]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Oct 11 16:19:14 volumio volumio[804]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 11 16:19:14 volumio sudo[2916]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-11 16:18 Oct 11 16:19:14 volumio sudo[2916]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"