-- Logs begin at Thu 2019-02-14 02:11:59 PST, end at Thu 2025-02-27 11:35:46 PST. -- Feb 27 11:34:00 volumio-studio dbus-daemon[498]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.25' (uid=1000 pid=1942 comm="/usr/bin/pulseaudio --high-priority=true ") Feb 27 11:34:00 volumio-studio systemd[1]: Starting RealtimeKit Scheduling Policy Service... Feb 27 11:34:00 volumio-studio dbus-daemon[498]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Feb 27 11:34:00 volumio-studio rtkit-daemon[1943]: Successfully called chroot. Feb 27 11:34:00 volumio-studio systemd[1]: Started RealtimeKit Scheduling Policy Service. Feb 27 11:34:00 volumio-studio rtkit-daemon[1943]: Successfully dropped privileges. Feb 27 11:34:00 volumio-studio rtkit-daemon[1943]: Successfully limited resources. Feb 27 11:34:00 volumio-studio rtkit-daemon[1943]: Running. Feb 27 11:34:00 volumio-studio rtkit-daemon[1943]: Canary thread running. Feb 27 11:34:00 volumio-studio rtkit-daemon[1943]: Watchdog thread running. Feb 27 11:34:00 volumio-studio dbus-daemon[498]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.26' (uid=0 pid=1943 comm="/usr/lib/rtkit/rtkit-daemon ") Feb 27 11:34:00 volumio-studio systemd[1]: Starting Authorization Manager... Feb 27 11:34:01 volumio-studio polkitd[1946]: started daemon version 0.105 using authority implementation `local' version `0.105' Feb 27 11:34:01 volumio-studio dbus-daemon[498]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Feb 27 11:34:01 volumio-studio systemd[1]: Started Authorization Manager. Feb 27 11:34:02 volumio-studio pulseaudio[1942]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Feb 27 11:34:02 volumio-studio pulseaudio[1942]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Feb 27 11:34:02 volumio-studio pulseaudio[1942]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files Feb 27 11:34:02 volumio-studio bluetoothd[659]: Endpoint registered: sender=:1.46 path=/MediaEndpoint/A2DPSource Feb 27 11:34:02 volumio-studio bluetoothd[659]: Endpoint registered: sender=:1.46 path=/MediaEndpoint/A2DPSink Feb 27 11:34:02 volumio-studio volumiobt[1915]: Applying permissions Feb 27 11:34:02 volumio-studio volumiobt[1915]: Setting BT discoverable and pairable Feb 27 11:34:03 volumio-studio kernel: Bluetooth: RFCOMM TTY layer initialized Feb 27 11:34:03 volumio-studio kernel: Bluetooth: RFCOMM socket layer initialized Feb 27 11:34:03 volumio-studio kernel: Bluetooth: RFCOMM ver 1.11 Feb 27 11:34:03 volumio-studio volumiobt[1915]: [CHG] Controller 2C:CF:67:90:B3:94 Class: 0x0008041c Feb 27 11:34:03 volumio-studio volumiobt[1915]: [117B blob data] Feb 27 11:34:03 volumio-studio volumiobt[1915]: [117B blob data] Feb 27 11:34:03 volumio-studio volumiobt[1915]: [117B blob data] Feb 27 11:34:03 volumio-studio volumiobt[1915]: [117B blob data] Feb 27 11:34:03 volumio-studio volumiobt[1915]: [117B blob data] Feb 27 11:34:03 volumio-studio volumiobt[1915]: [117B blob data] Feb 27 11:34:03 volumio-studio volumiobt[1915]: [117B blob data] Feb 27 11:34:03 volumio-studio volumiobt[1915]: [117B blob data] Feb 27 11:34:03 volumio-studio volumiobt[1915]: [117B blob data] Feb 27 11:34:03 volumio-studio volumiobt[1915]: [91B blob data] Feb 27 11:34:03 volumio-studio volumiobt[1915]: [117B blob data] Feb 27 11:34:03 volumio-studio volumiobt[1915]: [117B blob data] Feb 27 11:34:03 volumio-studio volumiobt[1915]: [117B blob data] Feb 27 11:34:03 volumio-studio volumiobt[1915]: [117B blob data] Feb 27 11:34:03 volumio-studio volumiobt[1915]: [117B blob data] Feb 27 11:34:03 volumio-studio volumiobt[1915]: [117B blob data] Feb 27 11:34:03 volumio-studio volumiobt[1915]: [117B blob data] Feb 27 11:34:03 volumio-studio volumiobt[1915]: [117B blob data] Feb 27 11:34:03 volumio-studio volumiobt[1915]: [117B blob data] Feb 27 11:34:03 volumio-studio volumiobt[1915]: [55B blob data] Feb 27 11:34:03 volumio-studio volumiobt[1915]: [117B blob data] Feb 27 11:34:03 volumio-studio volumiobt[1915]: [117B blob data] Feb 27 11:34:03 volumio-studio volumiobt[1915]: [117B blob data] Feb 27 11:34:03 volumio-studio volumiobt[1915]: [117B blob data] Feb 27 11:34:03 volumio-studio volumiobt[1915]: [117B blob data] Feb 27 11:34:03 volumio-studio volumiobt[1915]: [117B blob data] Feb 27 11:34:03 volumio-studio volumiobt[1915]: [117B blob data] Feb 27 11:34:03 volumio-studio volumiobt[1915]: [117B blob data] Feb 27 11:34:03 volumio-studio volumiobt[1915]: [117B blob data] Feb 27 11:34:03 volumio-studio volumiobt[1915]: [117B blob data] Feb 27 11:34:03 volumio-studio volumiobt[1915]: [117B blob data] Feb 27 11:34:03 volumio-studio volumiobt[1915]: [117B blob data] Feb 27 11:34:03 volumio-studio volumiobt[1915]: [117B blob data] Feb 27 11:34:03 volumio-studio volumiobt[1915]: [117B blob data] Feb 27 11:34:03 volumio-studio volumiobt[1915]: [117B blob data] Feb 27 11:34:03 volumio-studio volumiobt[1915]: [117B blob data] Feb 27 11:34:03 volumio-studio volumiobt[1915]: [117B blob data] Feb 27 11:34:03 volumio-studio volumiobt[1915]: [117B blob data] Feb 27 11:34:03 volumio-studio volumiobt[1915]: [bluetooth]# -e power on Feb 27 11:34:03 volumio-studio volumiobt[1915]: Invalid command in menu main: -e Feb 27 11:34:03 volumio-studio volumiobt[1915]: Feb 27 11:34:03 volumio-studio volumiobt[1915]: Use "help" for a list of available commands in a menu. Feb 27 11:34:03 volumio-studio volumiobt[1915]: Use "menu " if you want to enter any submenu. Feb 27 11:34:03 volumio-studio volumiobt[1915]: Use "back" if you want to return to menu main. Feb 27 11:34:03 volumio-studio volumiobt[1915]: [bluetooth]# agent on Feb 27 11:34:03 volumio-studio volumiobt[1915]: Agent is already registered Feb 27 11:34:03 volumio-studio volumiobt[1915]: [bluetooth]# discoverable on Feb 27 11:34:03 volumio-studio volumiobt[1915]: [bluetooth]# pairable on Feb 27 11:34:03 volumio-studio volumiobt[1915]: [bluetooth]# agent NoInputNoOutput Feb 27 11:34:03 volumio-studio volumiobt[1915]: Agent is already registered Feb 27 11:34:03 volumio-studio volumiobt[1915]: [bluetooth]# default-agent Feb 27 11:34:03 volumio-studio volumiobt[1915]: [bluetooth]# quit Feb 27 11:34:03 volumio-studio volumiobt[1915]: [59B blob data] Feb 27 11:34:03 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Feb 27 11:34:03 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 27 11:34:03 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Feb 27 11:34:03 volumio-studio volumio[828]: error: error Feb 27 11:34:03 volumio-studio volumio[828]: error: error Feb 27 11:34:04 volumio-studio volumio[828]: verbose: New Socket.io Connection to 172.16.0.36:3000 from 172.16.0.32 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Feb 27 11:34:04 volumio-studio volumio[828]: verbose: New Socket.io Connection to 172.16.0.36:3000 from 172.16.0.5 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Feb 27 11:34:04 volumio-studio volumio[828]: verbose: New Socket.io Connection to 172.16.0.36:3000 from 172.16.0.32 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Feb 27 11:34:04 volumio-studio volumio[828]: verbose: New Socket.io Connection to 172.16.0.36:3000 from 172.16.0.5 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Feb 27 11:34:04 volumio-studio volumio[828]: verbose: New Socket.io Connection to 172.16.0.36:3000 from 172.16.0.32 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Feb 27 11:34:04 volumio-studio volumio[828]: verbose: New Socket.io Connection to 172.16.0.36:3000 from 172.16.0.5 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 27 11:34:04 volumio-studio volumio[828]: verbose: New Socket.io Connection to 172.16.0.36:3000 from 172.16.0.32 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Feb 27 11:34:04 volumio-studio volumio[828]: verbose: New Socket.io Connection to 172.16.0.36:3000 from 172.16.0.5 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Feb 27 11:34:04 volumio-studio volumio[828]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Feb 27 11:34:04 volumio-studio volumio[828]: verbose: New Socket.io Connection to 172.16.0.36:3000 from 172.16.0.32 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Feb 27 11:34:04 volumio-studio volumio[828]: verbose: New Socket.io Connection to 172.16.0.36:3000 from 172.16.0.5 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Feb 27 11:34:05 volumio-studio volumio[828]: info: Volumio BT Module successfully started Feb 27 11:34:05 volumio-studio volumio[828]: info: BOOT COMPLETED Feb 27 11:34:05 volumio-studio volumio[828]: error: No access token received for TIDAL Feb 27 11:34:05 volumio-studio volumio[828]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Feb 27 11:34:05 volumio-studio volumio[828]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Feb 27 11:34:05 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 11:34:05 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 11:34:05 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 11:34:06 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 27 11:34:06 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 27 11:34:06 volumio-studio volumio[828]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Feb 27 11:34:06 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Feb 27 11:34:06 volumio-studio volumio[828]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Feb 27 11:34:06 volumio-studio volumio[828]: error: updateQueue error: null Feb 27 11:34:06 volumio-studio volumio[828]: info: MRS: Pushing audio output on request Feb 27 11:34:06 volumio-studio volumio[828]: info: MRS: Pushing multiroomSync output Feb 27 11:34:06 volumio-studio volumio[828]: info: Discovery: Connected to remote: 172.16.0.5 Feb 27 11:34:06 volumio-studio volumio[828]: info: MRS: Pushing audio output on request Feb 27 11:34:06 volumio-studio volumio[828]: info: MRS: Pushing multiroomSync output Feb 27 11:34:06 volumio-studio volumio[828]: info: Discovery: Connected to remote: 172.16.0.32 Feb 27 11:34:06 volumio-studio volumio[828]: info: Successfully Updated MyVolumio device Feb 27 11:34:06 volumio-studio volumio[828]: verbose: New Socket.io Connection to 172.16.0.36:3000 from 172.16.0.32 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Feb 27 11:34:06 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 27 11:34:06 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 27 11:34:06 volumio-studio volumio[828]: info: Discovery: Getting this device information Feb 27 11:34:06 volumio-studio volumio[828]: info: CoreCommandRouter::volumioGetState Feb 27 11:34:07 volumio-studio volumio[828]: info: CorePlayQueue::getTrack 0 Feb 27 11:34:07 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 27 11:34:07 volumio-studio volumio[828]: info: Starting Shairport Sync Feb 27 11:34:07 volumio-studio volumio[828]: info: MRS: Found cast device: SHIELD-Android-TV-3aec8a7239a2efea2381b7ce8e72fbff Feb 27 11:34:07 volumio-studio volumio[828]: info: Adding audio output: Feb 27 11:34:07 volumio-studio sudo[1971]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 27 11:34:07 volumio-studio sudo[1969]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Feb 27 11:34:07 volumio-studio sudo[1969]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 11:34:07 volumio-studio sudo[1971]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 11:34:07 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Feb 27 11:34:07 volumio-studio volumio[828]: verbose: New Socket.io Connection to 172.16.0.36:3000 from 172.16.0.5 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 15 Feb 27 11:34:07 volumio-studio volumio[828]: verbose: New Socket.io Connection to 172.16.0.36:3000 from 172.16.0.58 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 16 Feb 27 11:34:07 volumio-studio volumio[828]: info: MRS: Found cast device: Smart-TV-Pro-5284531cf4973a2388cc09932da98dfa Feb 27 11:34:07 volumio-studio volumio[828]: info: Adding audio output: Feb 27 11:34:07 volumio-studio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 27 11:34:07 volumio-studio systemd[1]: Started Volumio Tidal Connect Service. Feb 27 11:34:07 volumio-studio sudo[1969]: pam_unix(sudo:session): session closed for user root Feb 27 11:34:07 volumio-studio volumio[828]: info: MRS: Adding multiroomSync output Feb 27 11:34:07 volumio-studio volumio[828]: info: Adding audio output: Feb 27 11:34:07 volumio-studio volumio[828]: info: MRS: Pushing multiroomSync output Feb 27 11:34:07 volumio-studio volumio[828]: info: MRS: Adding multiroomSync output Feb 27 11:34:07 volumio-studio volumio[828]: info: Adding audio output: Feb 27 11:34:07 volumio-studio volumio[828]: info: MRS: Pushing multiroomSync output Feb 27 11:34:07 volumio-studio volumio[828]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 17 Feb 27 11:34:07 volumio-studio volumio[828]: info: CoreCommandRouter::volumioGetState Feb 27 11:34:07 volumio-studio volumio[828]: info: CorePlayQueue::getTrack 0 Feb 27 11:34:07 volumio-studio volumio[828]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 18 Feb 27 11:34:08 volumio-studio volumio[828]: info: MRS: Found cast device: SHIELD-Android-TV-ba74f2ec38fdb4d006dc8163624b72a2 Feb 27 11:34:08 volumio-studio volumio[828]: info: Adding audio output: Feb 27 11:34:08 volumio-studio volumio[828]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 27 11:34:08 volumio-studio volumio[828]: info: Listing playlists Feb 27 11:34:08 volumio-studio volumio[828]: info: CoreCommandRouter::volumioGetQueue Feb 27 11:34:08 volumio-studio volumio[828]: info: CoreStateMachine::getQueue Feb 27 11:34:08 volumio-studio volumio[828]: info: CorePlayQueue::getQueue Feb 27 11:34:08 volumio-studio volumio[828]: info: CoreCommandRouter::volumioGetVisibleSources Feb 27 11:34:08 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 27 11:34:08 volumio-studio volumio[828]: info: CoreCommandRouter::volumioGetState Feb 27 11:34:08 volumio-studio volumio[828]: info: CorePlayQueue::getTrack 0 Feb 27 11:34:08 volumio-studio volumio[828]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Feb 27 11:34:08 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Feb 27 11:34:08 volumio-studio volumio[828]: info: Listing playlists Feb 27 11:34:08 volumio-studio volumio[828]: info: CoreCommandRouter::volumioGetQueue Feb 27 11:34:08 volumio-studio volumio[828]: info: CoreStateMachine::getQueue Feb 27 11:34:08 volumio-studio volumio[828]: info: CorePlayQueue::getQueue Feb 27 11:34:08 volumio-studio volumio[828]: info: CoreCommandRouter::volumioGetVisibleSources Feb 27 11:34:08 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 27 11:34:08 volumio-studio volumio[828]: info: CoreCommandRouter::volumioGetState Feb 27 11:34:08 volumio-studio volumio[828]: info: CorePlayQueue::getTrack 0 Feb 27 11:34:08 volumio-studio volumio[828]: info: CoreCommandRouter::volumioGetState Feb 27 11:34:08 volumio-studio volumio[828]: info: CorePlayQueue::getTrack 0 Feb 27 11:34:08 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 27 11:34:08 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 27 11:34:09 volumio-studio systemd[1]: shairport-sync.service: Succeeded. Feb 27 11:34:09 volumio-studio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 27 11:34:10 volumio-studio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 27 11:34:10 volumio-studio sudo[1971]: pam_unix(sudo:session): session closed for user root Feb 27 11:34:18 volumio-studio volumio-remote-updater[495]: [2025-02-27 11:34:18] [connect] Successful connection Feb 27 11:34:24 volumio-studio systemd[1]: Stopping Music Player Daemon... Feb 27 11:34:28 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Feb 27 11:34:30 volumio-studio volumio[828]: info: CoreCommandRouter::volumioGetState Feb 27 11:34:30 volumio-studio volumio[828]: info: CorePlayQueue::getTrack 0 Feb 27 11:34:34 volumio-studio volumio-remote-updater[495]: [2025-02-27 11:34:34] [connect] Successful connection Feb 27 11:34:41 volumio-studio systemd[1]: mpd.service: Succeeded. Feb 27 11:34:41 volumio-studio systemd[1]: Stopped Music Player Daemon. Feb 27 11:34:41 volumio-studio systemd[1]: Starting Music Player Daemon... Feb 27 11:34:42 volumio-studio sudo[2016]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 27 11:34:42 volumio-studio sudo[2016]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 11:34:42 volumio-studio sudo[2016]: pam_unix(sudo:session): session closed for user root Feb 27 11:34:43 volumio-studio volumio[828]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 27 11:34:43 volumio-studio volumiologrotate[522]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Feb 27 11:34:43 volumio-studio volumiologrotate[522]: ls: cannot access 'STUDIO': No such file or directory Feb 27 11:34:45 volumio-studio volumio[828]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 27 11:34:45 volumio-studio volumio[828]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 27 11:34:45 volumio-studio volumio[828]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 27 11:34:46 volumio-studio volumio[828]: info: TidalConnect service started! Feb 27 11:34:46 volumio-studio volumio[828]: info: Shairport-Sync Started Feb 27 11:34:49 volumio-studio mpd[2032]: Feb 27 11:34 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 27 11:34:49 volumio-studio systemd[1]: Started Music Player Daemon. Feb 27 11:34:49 volumio-studio volumio[828]: info: CoreCommandRouter::volumioGetState Feb 27 11:34:49 volumio-studio volumio[828]: info: CorePlayQueue::getTrack 0 Feb 27 11:34:49 volumio-studio mpd_monitor.sh[509]: MPD restarted due to no mpc output. Feb 27 11:34:49 volumio-studio volumio-remote-updater[495]: [2025-02-27 11:34:49] [connect] Successful connection Feb 27 11:34:50 volumio-studio volumio[828]: info: Executing endpoint tc_getconfig Feb 27 11:34:50 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Feb 27 11:34:50 volumio-studio volumio[828]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Feb 27 11:34:50 volumio-studio vtcs[1974]: STARTING TidalConnect services, version: 1.4.0.34 Feb 27 11:34:50 volumio-studio volumio[828]: info: Discovery: Disconnected from remote: 172.16.0.5 Feb 27 11:34:50 volumio-studio volumio[828]: info: MRS: Cleaning up device: e86638e3-c70d-4ef4-ad2f-734170b38d76 Feb 27 11:34:50 volumio-studio volumio[828]: info: Removing audio output: Feb 27 11:34:50 volumio-studio volumio[828]: info: Discovery: Disconnected from remote: 172.16.0.5 Feb 27 11:34:50 volumio-studio volumio[828]: info: Discovery: Disconnected from remote: 172.16.0.32 Feb 27 11:34:50 volumio-studio volumio[828]: info: MRS: Cleaning up device: 81bea0bc-0ff3-4ff5-a8b6-139fc777251c Feb 27 11:34:50 volumio-studio volumio[828]: info: Removing audio output: Feb 27 11:34:50 volumio-studio volumio[828]: info: Discovery: Disconnected from remote: 172.16.0.32 Feb 27 11:34:50 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 27 11:34:50 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 27 11:34:50 volumio-studio volumio[828]: info: Discovery: Getting this device information Feb 27 11:34:50 volumio-studio volumio[828]: info: CoreCommandRouter::volumioGetState Feb 27 11:34:50 volumio-studio volumio[828]: info: CorePlayQueue::getTrack 0 Feb 27 11:34:50 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 27 11:34:51 volumio-studio volumio[828]: error: error Feb 27 11:34:51 volumio-studio vtcs[1974]: [2025-02-27 11:34:51.192] [tisoc] [critical] [DeviceInfo.cpp:56] ioctl error. ret=-1, errno="No such device" Feb 27 11:34:51 volumio-studio vtcs[1974]: [2025-02-27 11:34:51.197] [tisoc] [critical] [DeviceInfo.cpp:57] [ASSERT] generateDeviceId(0) Feb 27 11:34:51 volumio-studio vtcs[1974]: STARTED TidalConnect services. Feb 27 11:34:51 volumio-studio volumio[828]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Feb 27 11:34:51 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Feb 27 11:34:51 volumio-studio volumio[828]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Feb 27 11:34:51 volumio-studio volumio[828]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 5 Feb 27 11:34:51 volumio-studio volumio[828]: verbose: New Socket.io Connection to 172.16.0.36:3000 from 172.16.0.32 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Feb 27 11:34:51 volumio-studio volumio-remote-updater[495]: [2025-02-27 11:34:51] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1740684889 101 Feb 27 11:34:51 volumio-studio volumio[828]: info: Listing playlists Feb 27 11:34:51 volumio-studio volumio[828]: info: CoreCommandRouter::volumioGetQueue Feb 27 11:34:51 volumio-studio volumio[828]: info: CoreStateMachine::getQueue Feb 27 11:34:51 volumio-studio volumio[828]: info: CorePlayQueue::getQueue Feb 27 11:34:51 volumio-studio volumio[828]: info: CoreCommandRouter::volumioGetVisibleSources Feb 27 11:34:51 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 27 11:34:51 volumio-studio volumio[828]: info: CoreCommandRouter::volumioGetState Feb 27 11:34:51 volumio-studio volumio[828]: info: CorePlayQueue::getTrack 0 Feb 27 11:34:51 volumio-studio volumio[828]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Feb 27 11:34:51 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Feb 27 11:34:51 volumio-studio volumio[828]: info: Listing playlists Feb 27 11:34:51 volumio-studio volumio[828]: info: CoreCommandRouter::volumioGetQueue Feb 27 11:34:51 volumio-studio volumio[828]: info: CoreStateMachine::getQueue Feb 27 11:34:51 volumio-studio volumio[828]: info: CorePlayQueue::getQueue Feb 27 11:34:51 volumio-studio volumio[828]: info: CoreCommandRouter::volumioGetVisibleSources Feb 27 11:34:51 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 27 11:34:51 volumio-studio volumio[828]: info: CoreCommandRouter::volumioGetState Feb 27 11:34:51 volumio-studio volumio[828]: info: CorePlayQueue::getTrack 0 Feb 27 11:34:51 volumio-studio volumio[828]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 27 11:34:51 volumio-studio volumio[828]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Feb 27 11:34:51 volumio-studio volumio[828]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Feb 27 11:34:51 volumio-studio volumio[828]: info: MRS: Pushing audio output on request Feb 27 11:34:51 volumio-studio volumio[828]: info: MRS: Pushing multiroomSync output Feb 27 11:34:51 volumio-studio volumio[828]: info: Discovery: Connected to remote: 172.16.0.5 Feb 27 11:34:51 volumio-studio volumio[828]: info: Executing endpoint tc_connect Feb 27 11:34:51 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Feb 27 11:34:51 volumio-studio volumio[828]: info: Connecting to TidalConnect Feb 27 11:34:51 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Feb 27 11:34:51 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 27 11:34:51 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 27 11:34:51 volumio-studio volumio[828]: info: Discovery: Getting this device information Feb 27 11:34:51 volumio-studio volumio[828]: info: CoreCommandRouter::volumioGetState Feb 27 11:34:51 volumio-studio volumio[828]: info: CorePlayQueue::getTrack 0 Feb 27 11:34:51 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 27 11:34:51 volumio-studio volumio[828]: info: CoreCommandRouter::servicePushState Feb 27 11:34:51 volumio-studio volumio[828]: info: CoreStateMachine::pushState Feb 27 11:34:51 volumio-studio volumio[828]: info: CorePlayQueue::getTrack 0 Feb 27 11:34:51 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 27 11:34:51 volumio-studio volumio[828]: info: CoreCommandRouter::volumioPushState Feb 27 11:34:52 volumio-studio volumio[828]: info: MRS: Pushing multiroomSync output update for this device Feb 27 11:34:52 volumio-studio volumio[828]: info: MRS: Pushing multiroomSync output Feb 27 11:34:52 volumio-studio volumio[828]: info: CorePlayQueue::getTrack 0 Feb 27 11:34:52 volumio-studio volumio[828]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect Feb 27 11:34:52 volumio-studio volumio[828]: info: CoreCommandRouter::servicePushState Feb 27 11:34:52 volumio-studio volumio[828]: info: CoreStateMachine::pushState Feb 27 11:34:52 volumio-studio volumio[828]: info: CorePlayQueue::getTrack 0 Feb 27 11:34:52 volumio-studio volumio[828]: info: CoreCommandRouter::volumioPushState Feb 27 11:34:52 volumio-studio volumio[828]: info: MRS: Pushing multiroomSync output update for this device Feb 27 11:34:52 volumio-studio volumio[828]: info: MRS: Pushing multiroomSync output Feb 27 11:34:52 volumio-studio volumio[828]: info: CorePlayQueue::getTrack 0 Feb 27 11:34:52 volumio-studio volumio[828]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect Feb 27 11:34:52 volumio-studio volumio[828]: info: CoreCommandRouter::volumioGetState Feb 27 11:34:52 volumio-studio volumio[828]: info: CorePlayQueue::getTrack 0 Feb 27 11:34:52 volumio-studio volumio[828]: info: CoreCommandRouter::volumioGetState Feb 27 11:34:52 volumio-studio volumio[828]: info: CorePlayQueue::getTrack 0 Feb 27 11:34:52 volumio-studio volumio[828]: info: MRS: Adding multiroomSync output Feb 27 11:34:52 volumio-studio volumio[828]: info: Adding audio output: Feb 27 11:34:52 volumio-studio volumio[828]: info: MRS: Pushing multiroomSync output Feb 27 11:34:52 volumio-studio volumio[828]: info: CoreCommandRouter::volumioGetState Feb 27 11:34:52 volumio-studio volumio[828]: info: CorePlayQueue::getTrack 0 Feb 27 11:34:52 volumio-studio volumio[828]: verbose: New Socket.io Connection to 172.16.0.36:3000 from 172.16.0.58 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Feb 27 11:34:52 volumio-studio volumio[828]: info: MRS: Pushing audio output on request Feb 27 11:34:52 volumio-studio volumio[828]: info: MRS: Pushing multiroomSync output Feb 27 11:34:52 volumio-studio volumio[828]: info: Discovery: Connected to remote: 172.16.0.32 Feb 27 11:34:52 volumio-studio volumio[828]: info: CoreCommandRouter::volumioGetState Feb 27 11:34:52 volumio-studio volumio[828]: info: CorePlayQueue::getTrack 0 Feb 27 11:34:52 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 27 11:34:52 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 27 11:34:53 volumio-studio sudo[2110]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 27 11:34:53 volumio-studio sudo[2112]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 27 11:34:53 volumio-studio sudo[2112]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 11:34:53 volumio-studio sudo[2110]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 11:34:53 volumio-studio sudo[2112]: pam_unix(sudo:session): session closed for user root Feb 27 11:34:53 volumio-studio sudo[2110]: pam_unix(sudo:session): session closed for user root Feb 27 11:34:53 volumio-studio volumio[828]: verbose: New Socket.io Connection to 172.16.0.36 from 172.16.0.58 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 11 Feb 27 11:34:53 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 27 11:34:54 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 27 11:34:54 volumio-studio sudo[2118]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 27 11:34:54 volumio-studio sudo[2118]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 11:34:54 volumio-studio sudo[2120]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 27 11:34:54 volumio-studio sudo[2120]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 11:34:54 volumio-studio sudo[2120]: pam_unix(sudo:session): session closed for user root Feb 27 11:34:54 volumio-studio sudo[2118]: pam_unix(sudo:session): session closed for user root Feb 27 11:34:54 volumio-studio volumio[828]: verbose: New Socket.io Connection to 172.16.0.36 from 172.16.0.58 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_3_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 12 Feb 27 11:34:54 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 27 11:34:54 volumio-studio volumio[828]: info: CoreCommandRouter::volumioGetVisibleSources Feb 27 11:34:54 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 27 11:34:54 volumio-studio volumio[828]: info: CoreCommandRouter::volumioGetState Feb 27 11:34:54 volumio-studio volumio[828]: info: CorePlayQueue::getTrack 0 Feb 27 11:34:54 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 27 11:34:54 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 27 11:34:54 volumio-studio volumio[828]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Feb 27 11:34:54 volumio-studio volumio[828]: info: Received Get System Info Feb 27 11:34:54 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 27 11:34:54 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 27 11:34:54 volumio-studio volumio[828]: info: Discovery: Getting this device information Feb 27 11:34:54 volumio-studio volumio[828]: info: CoreCommandRouter::volumioGetState Feb 27 11:34:54 volumio-studio volumio[828]: info: CorePlayQueue::getTrack 0 Feb 27 11:34:54 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 27 11:34:54 volumio-studio volumio[828]: info: CoreCommandRouter::volumioGetState Feb 27 11:34:54 volumio-studio volumio[828]: info: CorePlayQueue::getTrack 0 Feb 27 11:34:54 volumio-studio volumio[828]: info: Listing playlists Feb 27 11:34:54 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 27 11:34:54 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 27 11:34:54 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 27 11:34:55 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Feb 27 11:34:56 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 27 11:34:56 volumio-studio volumio[828]: info: Received Get System Info Feb 27 11:34:56 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 27 11:34:56 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 27 11:34:56 volumio-studio volumio[828]: info: Discovery: Getting this device information Feb 27 11:34:56 volumio-studio volumio[828]: info: CoreCommandRouter::volumioGetState Feb 27 11:34:56 volumio-studio volumio[828]: info: CorePlayQueue::getTrack 0 Feb 27 11:34:56 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 27 11:34:56 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 27 11:34:56 volumio-studio volumio[828]: info: Received Get System Info Feb 27 11:34:56 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 27 11:34:56 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 27 11:34:56 volumio-studio volumio[828]: info: Discovery: Getting this device information Feb 27 11:34:56 volumio-studio volumio[828]: info: CoreCommandRouter::volumioGetState Feb 27 11:34:56 volumio-studio volumio[828]: info: CorePlayQueue::getTrack 0 Feb 27 11:34:56 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 27 11:34:57 volumio-studio volumio[828]: info: CoreCommandRouter::volumioGetQueue Feb 27 11:34:57 volumio-studio volumio[828]: info: CoreStateMachine::getQueue Feb 27 11:34:57 volumio-studio volumio[828]: info: CorePlayQueue::getQueue Feb 27 11:35:03 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 27 11:35:03 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 27 11:35:05 volumio-studio volumio[828]: info: Discovery: Getting this device information Feb 27 11:35:05 volumio-studio volumio[828]: info: CoreCommandRouter::volumioGetState Feb 27 11:35:05 volumio-studio volumio[828]: info: CorePlayQueue::getTrack 0 Feb 27 11:35:05 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 27 11:35:10 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 27 11:35:11 volumio-studio systemd[1]: Stopping Music Player Daemon... Feb 27 11:35:14 volumio-studio volumio[828]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 27 11:35:15 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 27 11:35:15 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 27 11:35:15 volumio-studio volumio[828]: info: Discovery: Getting this device information Feb 27 11:35:15 volumio-studio volumio[828]: info: CoreCommandRouter::volumioGetState Feb 27 11:35:15 volumio-studio volumio[828]: info: CorePlayQueue::getTrack 0 Feb 27 11:35:15 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 27 11:35:16 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 27 11:35:23 volumio-studio volumio[828]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 27 11:35:23 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 27 11:35:23 volumio-studio volumio-remote-updater[495]: [2025-02-27 11:35:23] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] Feb 27 11:35:23 volumio-studio volumio[828]: info: Received Get System Version Feb 27 11:35:23 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 27 11:35:24 volumio-studio volumio[828]: info: Received Get System Info Feb 27 11:35:24 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 27 11:35:24 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 27 11:35:24 volumio-studio volumio[828]: info: Discovery: Getting this device information Feb 27 11:35:24 volumio-studio volumio[828]: info: CoreCommandRouter::volumioGetState Feb 27 11:35:24 volumio-studio volumio[828]: info: CorePlayQueue::getTrack 0 Feb 27 11:35:24 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 27 11:35:25 volumio-studio volumio[828]: verbose: New Socket.io Connection to 172.16.0.36:3000 from 172.16.0.5 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Feb 27 11:35:25 volumio-studio volumio[828]: verbose: New Socket.io Connection to 172.16.0.36:3000 from 172.16.0.32 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Feb 27 11:35:25 volumio-studio volumio[828]: info: Discovery: Disconnected from remote: 172.16.0.32 Feb 27 11:35:25 volumio-studio volumio[828]: info: Discovery: Disconnected from remote: 172.16.0.32 Feb 27 11:35:25 volumio-studio volumio[828]: info: Discovery: Disconnected from remote: 172.16.0.32 Feb 27 11:35:25 volumio-studio volumio[828]: info: Discovery: Disconnected from remote: 172.16.0.32 Feb 27 11:35:25 volumio-studio volumio[828]: info: Discovery: Disconnected from remote: 172.16.0.5 Feb 27 11:35:25 volumio-studio volumio[828]: info: MRS: Cleaning up device: e86638e3-c70d-4ef4-ad2f-734170b38d76 Feb 27 11:35:25 volumio-studio volumio[828]: info: Removing audio output: Feb 27 11:35:25 volumio-studio volumio[828]: info: Discovery: Disconnected from remote: 172.16.0.5 Feb 27 11:35:25 volumio-studio volumio[828]: info: Discovery: Disconnected from remote: 172.16.0.5 Feb 27 11:35:25 volumio-studio volumio[828]: info: Discovery: Disconnected from remote: 172.16.0.5 Feb 27 11:35:25 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Feb 27 11:35:26 volumio-studio systemd[1]: mpd.service: Succeeded. Feb 27 11:35:26 volumio-studio systemd[1]: Stopped Music Player Daemon. Feb 27 11:35:26 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Feb 27 11:35:26 volumio-studio volumio[828]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 27 11:35:26 volumio-studio volumio[828]: info: CoreCommandRouter::volumioGetState Feb 27 11:35:26 volumio-studio volumio[828]: info: CorePlayQueue::getTrack 0 Feb 27 11:35:26 volumio-studio volumio[828]: info: CoreCommandRouter::volumioGetState Feb 27 11:35:26 volumio-studio volumio[828]: info: CorePlayQueue::getTrack 0 Feb 27 11:35:26 volumio-studio systemd[1]: Starting Music Player Daemon... Feb 27 11:35:26 volumio-studio volumio[828]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 27 11:35:26 volumio-studio sudo[2173]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 27 11:35:26 volumio-studio sudo[2173]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 27 11:35:26 volumio-studio volumio[828]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Feb 27 11:35:26 volumio-studio volumio[828]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Feb 27 11:35:26 volumio-studio volumio[828]: info: MRS: Pushing audio output on request Feb 27 11:35:26 volumio-studio volumio[828]: info: MRS: Pushing multiroomSync output Feb 27 11:35:26 volumio-studio volumio[828]: info: Discovery: Connected to remote: 172.16.0.32 Feb 27 11:35:26 volumio-studio sudo[2173]: pam_unix(sudo:session): session closed for user root Feb 27 11:35:27 volumio-studio volumio[828]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Feb 27 11:35:27 volumio-studio volumio[828]: info: MRS: Adding multiroomSync output Feb 27 11:35:27 volumio-studio volumio[828]: info: Adding audio output: Feb 27 11:35:27 volumio-studio volumio[828]: info: MRS: Pushing multiroomSync output Feb 27 11:35:27 volumio-studio volumio[828]: info: MRS: Pushing audio output on request Feb 27 11:35:27 volumio-studio volumio[828]: info: MRS: Pushing multiroomSync output Feb 27 11:35:27 volumio-studio volumio[828]: info: Discovery: Connected to remote: 172.16.0.5 Feb 27 11:35:27 volumio-studio volumio[828]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 27 11:35:27 volumio-studio volumio[828]: info: CoreCommandRouter::volumioGetState Feb 27 11:35:27 volumio-studio volumio[828]: info: CorePlayQueue::getTrack 0 Feb 27 11:35:27 volumio-studio volumio[828]: info: CoreCommandRouter::volumioGetState Feb 27 11:35:27 volumio-studio volumio[828]: info: CorePlayQueue::getTrack 0 Feb 27 11:35:28 volumio-studio volumio[828]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Feb 27 11:35:28 volumio-studio volumio-remote-updater[495]: [2025-02-27 11:35:28] [connect] Successful connection Feb 27 11:35:28 volumio-studio volumio[828]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 9 Feb 27 11:35:28 volumio-studio volumio-remote-updater[495]: [2025-02-27 11:35:28] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1740684928 101 Feb 27 11:35:28 volumio-studio volumio[828]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Feb 27 11:35:28 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Feb 27 11:35:29 volumio-studio volumio[828]: info: Listing playlists Feb 27 11:35:29 volumio-studio volumio[828]: info: CoreCommandRouter::volumioGetQueue Feb 27 11:35:29 volumio-studio volumio[828]: info: CoreStateMachine::getQueue Feb 27 11:35:29 volumio-studio volumio[828]: info: CorePlayQueue::getQueue Feb 27 11:35:29 volumio-studio volumio[828]: info: CoreCommandRouter::volumioGetVisibleSources Feb 27 11:35:29 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 27 11:35:29 volumio-studio volumio[828]: info: CoreCommandRouter::volumioGetState Feb 27 11:35:29 volumio-studio volumio[828]: info: CorePlayQueue::getTrack 0 Feb 27 11:35:29 volumio-studio volumio[828]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Feb 27 11:35:29 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Feb 27 11:35:29 volumio-studio volumio[828]: info: Listing playlists Feb 27 11:35:29 volumio-studio volumio[828]: info: CoreCommandRouter::volumioGetQueue Feb 27 11:35:29 volumio-studio volumio[828]: info: CoreStateMachine::getQueue Feb 27 11:35:29 volumio-studio volumio[828]: info: CorePlayQueue::getQueue Feb 27 11:35:29 volumio-studio volumio[828]: info: CoreCommandRouter::volumioGetVisibleSources Feb 27 11:35:29 volumio-studio volumio[828]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 27 11:35:29 volumio-studio volumio[828]: info: CoreCommandRouter::volumioGetState Feb 27 11:35:29 volumio-studio volumio[828]: info: CorePlayQueue::getTrack 0 Feb 27 11:35:31 volumio-studio mpd[2175]: Feb 27 11:35 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 27 11:35:31 volumio-studio systemd[1]: Started Music Player Daemon. Feb 27 11:35:31 volumio-studio mpd_monitor.sh[509]: MPD restarted due to no mpc output. Feb 27 11:35:38 volumio-studio volumio[828]: error: error Feb 27 11:35:43 volumio-studio volumiologrotate[522]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Feb 27 11:35:43 volumio-studio volumiologrotate[522]: ls: cannot access 'STUDIO': No such file or directory Feb 27 11:35:44 volumio-studio volumio[828]: info: CorePlayQueue::getTrack 0 Feb 27 11:35:44 volumio-studio volumio[828]: info: CorePlayQueue::getTrack 1 Feb 27 11:35:44 volumio-studio volumio[828]: info: Prefetching next song Feb 27 11:35:44 volumio-studio volumio[828]: info: [1740684944279] ControllerTidal::prefetch Feb 27 11:35:44 volumio-studio volumio[828]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 27 11:35:44 volumio-studio volumio[828]: TypeError: Cannot read property 'highestSoundQuality' of undefined Feb 27 11:35:44 volumio-studio volumio[828]: at ControllerTidal.getProperSoundQuality (/myvolumio/plugins/music_service/tidal/tidal_real:1:19037) Feb 27 11:35:44 volumio-studio volumio[828]: at ControllerTidal.prefetch (/myvolumio/plugins/music_service/tidal/tidal_real:1:19741) Feb 27 11:35:44 volumio-studio volumio[828]: at CoreStateMachine.increasePlaybackTimer (/volumio/app/statemachine.js:460:18) Feb 27 11:35:44 volumio-studio volumio[828]: at listOnTimeout (internal/timers.js:554:17) Feb 27 11:35:44 volumio-studio volumio[828]: at processTimers (internal/timers.js:497:7) Feb 27 11:35:44 volumio-studio volumio[828]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 27 11:35:46 volumio-studio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Feb 27 11:35:46 volumio-studio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Feb 27 11:35:46 volumio-studio shairport-sync[1982]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:257:(_snd_pcm_volumioswitch_hw_params) PCM volumioMultiRoomServer has buffer size 131072, but target_pcm softvolume has buffer size 65536 Feb 27 11:35:46 volumio-studio shairport-sync[1982]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:268:(_snd_pcm_volumioswitch_hw_params) PCM volumioMultiRoomServer has buffer size 131072, but target_pcm softvolume has buffer size 65536 Feb 27 11:35:46 volumio-studio shairport-sync[1982]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:257:(_snd_pcm_volumioswitch_hw_params) PCM volumioMultiRoomServer has buffer size 131072, but target_pcm softvolume has buffer size 65536 Feb 27 11:35:46 volumio-studio shairport-sync[1982]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:268:(_snd_pcm_volumioswitch_hw_params) PCM volumioMultiRoomServer has buffer size 131072, but target_pcm softvolume has buffer size 65536 Feb 27 11:35:46 volumio-studio shairport-sync[1982]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 27 11:35:46 volumio-studio shairport-sync[1982]: Dload Upload Total Spent Left Speed Feb 27 11:35:46 volumio-studio sudo[2272]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-27 11:34 Feb 27 11:35:46 volumio-studio sudo[2272]: 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"