May 29 19:45:24 volumio-2 ntpd[830]: CLOCK: time stepped by 1607.102712 May 29 19:45:24 volumio-2 ntpd[830]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes May 29 19:45:24 volumio-2 volumio[1069]: info: Loading plugin "network"... May 29 19:45:24 volumio-2 volumio[1069]: info: Refreshing Cached IP Addresses May 29 19:45:24 volumio-2 sudo[1116]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 29 19:45:24 volumio-2 sudo[1116]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 19:45:24 volumio-2 sudo[1116]: pam_unix(sudo:session): session closed for user root May 29 19:45:24 volumio-2 sudo[1119]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 29 19:45:24 volumio-2 sudo[1119]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 19:45:24 volumio-2 volumio[1069]: info: Loading plugin "services"... May 29 19:45:24 volumio-2 volumio[1069]: info: Loading plugin "alsa_controller"... May 29 19:45:24 volumio-2 sudo[1119]: pam_unix(sudo:session): session closed for user root May 29 19:45:24 volumio-2 sudo[1126]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 29 19:45:24 volumio-2 sudo[1126]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 19:45:24 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 29 19:45:24 volumio-2 volumio[1069]: info: Loading plugin "wizard"... May 29 19:45:24 volumio-2 volumio[1069]: info: Loading plugin "networkfs"... May 29 19:45:24 volumio-2 volumio[1069]: info: Starting Udev Watcher for removable devices May 29 19:45:24 volumio-2 sudo[1152]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=carmel,password=alinco,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.88.148/Public/music /mnt/NAS/NAS May 29 19:45:24 volumio-2 sudo[1152]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 19:45:24 volumio-2 volumio[1069]: info: Ignoring mount for partition: boot May 29 19:45:24 volumio-2 volumio[1069]: info: Ignoring mount for partition: volumio May 29 19:45:24 volumio-2 volumio[1069]: info: Ignoring mount for partition: volumio_data May 29 19:45:24 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 29 19:45:24 volumio-2 volumio[1069]: info: Loading plugin "volumio_command_line_client"... May 29 19:45:24 volumio-2 volumio[1069]: info: Loading plugin "upnp"... May 29 19:45:24 volumio-2 volumio[1069]: info: [1748540724688] Starting Upmpd Daemon May 29 19:45:24 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 29 19:45:24 volumio-2 volumio[1069]: info: Loading plugin "my_music"... May 29 19:45:24 volumio-2 volumio[1069]: info: Loading plugin "mpd"... May 29 19:45:24 volumio-2 kernel: netfs: FS-Cache loaded May 29 19:45:24 volumio-2 kernel: Key type cifs.spnego registered May 29 19:45:24 volumio-2 kernel: Key type cifs.idmap registered May 29 19:45:24 volumio-2 kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. May 29 19:45:24 volumio-2 kernel: CIFS: Attempting to mount //192.168.88.148/Public/music May 29 19:45:25 volumio-2 volumio[1069]: info: Loading plugin "upnp_browser"... May 29 19:45:25 volumio-2 sudo[1152]: pam_unix(sudo:session): session closed for user root May 29 19:45:26 volumio-2 volumio[1069]: info: Loading plugin "alarm-clock"... May 29 19:45:26 volumio-2 systemd[1]: systemd-fsckd.service: Deactivated successfully. May 29 19:45:27 volumio-2 volumio[1069]: info: Loading plugin "airplay_emulation"... May 29 19:45:27 volumio-2 volumio[1069]: info: Starting Shairport Sync May 29 19:45:27 volumio-2 volumio[1069]: info: Loading plugin "last_100"... May 29 19:45:27 volumio-2 volumio[1069]: info: Loading plugin "webradio"... May 29 19:45:27 volumio-2 volumio[1069]: info: Loading plugin "i2s_dacs"... May 29 19:45:27 volumio-2 volumio[1069]: info: Loading plugin "volumiodiscovery"... May 29 19:45:27 volumio-2 volumio[1069]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 29 19:45:27 volumio-2 volumio[1069]: *** WARNING *** Please fix your application to use the native API of Avahi! May 29 19:45:27 volumio-2 volumio[1069]: *** WARNING *** For more information see May 29 19:45:27 volumio-2 volumio[1069]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 29 19:45:27 volumio-2 volumio[1069]: *** WARNING *** Please fix your application to use the native API of Avahi! May 29 19:45:27 volumio-2 volumio[1069]: *** WARNING *** For more information see May 29 19:45:27 volumio-2 node[1069]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 29 19:45:27 volumio-2 node[1069]: *** WARNING *** Please fix your application to use the native API of Avahi! May 29 19:45:27 volumio-2 node[1069]: *** WARNING *** For more information see May 29 19:45:27 volumio-2 node[1069]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 29 19:45:27 volumio-2 node[1069]: *** WARNING *** Please fix your application to use the native API of Avahi! May 29 19:45:27 volumio-2 node[1069]: *** WARNING *** For more information see May 29 19:45:27 volumio-2 volumio[1069]: info: Applying required configuration parameters for plugin volumiodiscovery May 29 19:45:27 volumio-2 volumio[1069]: info: Discovery: Started advertising with name: Volumio 2 May 29 19:45:27 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 29 19:45:27 volumio-2 volumio[1069]: info: Loading plugin "youtube2"... May 29 19:45:27 volumio-2 sh[518]: timed out May 29 19:45:27 volumio-2 dhcpcd[522]: timed out May 29 19:45:27 volumio-2 dhcpcd[522]: dhcpcd exited May 29 19:45:27 volumio-2 sh[518]: dhcpcd exited May 29 19:45:27 volumio-2 sh[485]: ifup: failed to bring up eth0 May 29 19:45:27 volumio-2 systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE May 29 19:45:27 volumio-2 systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. May 29 19:45:28 volumio-2 sudo[1126]: pam_unix(sudo:session): session closed for user root May 29 19:45:30 volumio-2 systemd[1]: systemd-hostnamed.service: Deactivated successfully. May 29 19:45:31 volumio-2 nmbd[874]: [2025/05/29 19:45:31.371328, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) May 29 19:45:31 volumio-2 nmbd[874]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.88.184 for name WORKGROUP<1d>. May 29 19:45:31 volumio-2 nmbd[874]: This response was from IP 192.168.88.30, reporting an IP address of 192.168.88.30. May 29 19:45:31 volumio-2 volumio[1069]: info: Loading plugin "ytmusic"... May 29 19:45:34 volumio-2 wpa_supplicant[956]: RRM: Ignoring radio measurement request: Not RRM network May 29 19:45:35 volumio-2 volumio[1069]: info: Loading plugin "outputs"... May 29 19:45:35 volumio-2 volumio[1069]: info: Loading plugin "albumart"... May 29 19:45:35 volumio-2 volumio[1069]: info: Plugin example_plugin is not enabled May 29 19:45:35 volumio-2 volumio[1069]: info: Loading plugin "inputs"... May 29 19:45:35 volumio-2 volumio[1069]: info: Loading plugin "updater_comm"... May 29 19:45:35 volumio-2 volumio[1069]: info: Plugin mpdemulation is not enabled May 29 19:45:35 volumio-2 volumio[1069]: info: Loading plugin "rest_api"... May 29 19:45:35 volumio-2 volumio[1069]: info: Loading plugin "websocket"... May 29 19:45:35 volumio-2 volumio[1069]: info: Starting Socket.io Server version 1.7.4 May 29 19:45:35 volumio-2 volumio[1069]: info: Loading plugin "backup_restore"... May 29 19:45:36 volumio-2 volumio[1211]: Forking 3 albumart workers May 29 19:45:37 volumio-2 volumio-remote-updater[552]: [2025-05-29 19:45:37] [connect] Successful connection May 29 19:45:37 volumio-2 volumio[1069]: info: Applying required configuration parameters for plugin backup_restore May 29 19:45:37 volumio-2 volumio[1069]: info: Loading plugin "gpio_control"... May 29 19:45:38 volumio-2 volumio[1069]: gpiox_arm32 V2.2.1 May 29 19:45:38 volumio-2 volumio[1222]: Starting albumart workers May 29 19:45:40 volumio-2 volumio[1069]: info: Applying required configuration parameters for plugin gpio_control May 29 19:45:40 volumio-2 volumio[1069]: info: Loading plugin "ir_controller"... May 29 19:45:40 volumio-2 volumio[1069]: info: Applying required configuration parameters for plugin ir_controller May 29 19:45:41 volumio-2 volumio[1069]: info: Loading plugin "rotaryencoder2"... May 29 19:45:41 volumio-2 volumio[1223]: Starting albumart workers May 29 19:45:41 volumio-2 volumio[1221]: Starting albumart workers May 29 19:45:43 volumio-2 volumio[1069]: info: Loading i18n strings for locale en May 29 19:45:43 volumio-2 volumio[1069]: Updating browse sources language May 29 19:45:43 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 29 19:45:44 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 29 19:45:44 volumio-2 volumio[1069]: info: CoreCommandRouter::initPlayerControls May 29 19:45:44 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 19:45:44 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 19:45:44 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 19:45:44 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 19:45:44 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 19:45:44 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 19:45:44 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 19:45:44 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 19:45:44 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 29 19:45:44 volumio-2 volumio[1069]: Express server listening on port 3000 May 29 19:45:44 volumio-2 volumio[1069]: [Metrics] WebUI: 23s 387.60ms May 29 19:45:44 volumio-2 volumio[1069]: info: CoreStateMachine::resetVolumioState May 29 19:45:44 volumio-2 volumio[1069]: info: CoreStateMachine::getcurrentVolume May 29 19:45:44 volumio-2 volumio[1069]: info: CoreCommandRouter::volumioRetrievevolume May 29 19:45:44 volumio-2 sudo[1274]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 29 19:45:44 volumio-2 sudo[1274]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 19:45:44 volumio-2 sudo[1276]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 29 19:45:44 volumio-2 sudo[1274]: pam_unix(sudo:session): session closed for user root May 29 19:45:44 volumio-2 sudo[1276]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 19:45:44 volumio-2 sudo[1276]: pam_unix(sudo:session): session closed for user root May 29 19:45:44 volumio-2 volumio[1069]: info: Volumio Network Manager: Network status updated: 2 May 29 19:45:44 volumio-2 volumio[1069]: info: VolumeController:: Volume=32 Mute =false May 29 19:45:44 volumio-2 volumio[1069]: info: CoreStateMachine::pushState May 29 19:45:44 volumio-2 volumio[1069]: info: CorePlayQueue::getTrack 0 May 29 19:45:44 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 19:45:44 volumio-2 volumio[1069]: info: CoreCommandRouter::volumioPushState May 29 19:45:44 volumio-2 volumio[1069]: info: CoreStateMachine::updateTrackBlock May 29 19:45:44 volumio-2 volumio[1069]: info: CorePlayQueue::getTrackBlock May 29 19:45:44 volumio-2 volumio[1069]: info: CoreCommandRouter::volumioRetrievevolume May 29 19:45:44 volumio-2 volumio[1069]: verbose: New Socket.io Connection to 192.168.88.184:3000 from 192.168.88.68 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 1 May 29 19:45:44 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 19:45:44 volumio-2 volumio[1069]: info: Reloading queue from file May 29 19:45:44 volumio-2 volumio[1069]: info: CoreStateMachine::setRepeat false single undefined May 29 19:45:44 volumio-2 volumio[1069]: info: CoreStateMachine::pushState May 29 19:45:44 volumio-2 volumio[1069]: info: CorePlayQueue::getTrack 0 May 29 19:45:44 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 19:45:44 volumio-2 volumio[1069]: info: CoreCommandRouter::volumioPushState May 29 19:45:45 volumio-2 volumio[1069]: info: CoreStateMachine::setRandom false May 29 19:45:45 volumio-2 volumio[1069]: info: CoreStateMachine::pushState May 29 19:45:45 volumio-2 volumio[1069]: info: CorePlayQueue::getTrack 0 May 29 19:45:45 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 19:45:45 volumio-2 volumio[1069]: info: CoreCommandRouter::volumioPushState May 29 19:45:45 volumio-2 volumio[1069]: info: Setting Device type: Raspberry PI May 29 19:45:45 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 29 19:45:45 volumio-2 volumio[1069]: info: VolumeController::SetAlsaVolume33 May 29 19:45:45 volumio-2 volumio[1069]: info: CoreStateMachine::pushState May 29 19:45:45 volumio-2 volumio[1069]: info: CorePlayQueue::getTrack 0 May 29 19:45:45 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 19:45:45 volumio-2 volumio[1069]: info: CoreCommandRouter::volumioPushState May 29 19:45:45 volumio-2 volumio[1069]: info: Completed loading Core Plugins May 29 19:45:45 volumio-2 volumio[1069]: info: Preparing to generate the ALSA configuration file May 29 19:45:45 volumio-2 volumio[1069]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 May 29 19:45:45 volumio-2 volumio[1069]: info: VolumeController:: Volume=32 Mute =false May 29 19:45:45 volumio-2 volumio[1069]: info: CoreStateMachine::pushState May 29 19:45:45 volumio-2 volumio[1069]: info: CorePlayQueue::getTrack 0 May 29 19:45:45 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 19:45:45 volumio-2 volumio[1069]: info: CoreCommandRouter::volumioPushState May 29 19:45:45 volumio-2 volumio[1069]: info: Discovery: adding c76eb5fc-b147-44b7-9900-6a1ebf56379e May 29 19:45:45 volumio-2 volumio[1069]: info: Discovery: Found device Volumio 2 May 29 19:45:45 volumio-2 volumio[1069]: info: CoreCommandRouter::volumioGetState May 29 19:45:45 volumio-2 volumio[1069]: info: CorePlayQueue::getTrack 0 May 29 19:45:45 volumio-2 sudo[1307]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 29 19:45:45 volumio-2 sudo[1307]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 19:45:45 volumio-2 volumio[1069]: info: Discovery: this is already registered, c76eb5fc-b147-44b7-9900-6a1ebf56379e May 29 19:45:45 volumio-2 volumio[1069]: info: Discovery: Found device Volumio 2 May 29 19:45:45 volumio-2 volumio[1069]: info: CoreCommandRouter::volumioGetState May 29 19:45:45 volumio-2 volumio[1069]: info: CorePlayQueue::getTrack 0 May 29 19:45:45 volumio-2 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. May 29 19:45:45 volumio-2 volumio[1069]: info: Asound.conf file unchanged, so no further update is needed May 29 19:45:45 volumio-2 volumio[1069]: info: Output device has changed, restarting MPD May 29 19:45:45 volumio-2 sudo[1307]: pam_unix(sudo:session): session closed for user root May 29 19:45:45 volumio-2 volumio[1069]: info: Output device has changed, restarting Shairport Sync May 29 19:45:45 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 19:45:45 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 19:45:45 volumio-2 sudo[1322]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 29 19:45:45 volumio-2 sudo[1322]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 19:45:46 volumio-2 sudo[1311]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 29 19:45:46 volumio-2 volumio[1069]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 29 19:45:46 volumio-2 sudo[1311]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 19:45:46 volumio-2 sudo[1311]: pam_unix(sudo:session): session closed for user root May 29 19:45:46 volumio-2 volumio[1069]: info: ___________ START PLUGINS ___________ May 29 19:45:46 volumio-2 volumio[1069]: info: ControllerMpd::onStart: Initializing MPD May 29 19:45:46 volumio-2 volumio[1069]: info: Creating MPD Configuration file May 29 19:45:46 volumio-2 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 29 19:45:46 volumio-2 systemd[1]: Starting mpd.service - Music Player Daemon... May 29 19:45:46 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 29 19:45:46 volumio-2 volumio[1069]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 29 19:45:46 volumio-2 volumio[1069]: info: [1748540746142] CoreMusicLibrary::Adding element Media Servers May 29 19:45:46 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 29 19:45:46 volumio-2 systemd[1]: systemd-timedated.service: Deactivated successfully. May 29 19:45:46 volumio-2 sudo[1334]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 29 19:45:46 volumio-2 sudo[1334]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 19:45:46 volumio-2 sudo[1335]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 29 19:45:46 volumio-2 sudo[1334]: pam_unix(sudo:session): session closed for user root May 29 19:45:46 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 19:45:46 volumio-2 sudo[1335]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 19:45:46 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 19:45:46 volumio-2 volumio[1069]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 29 19:45:46 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 19:45:46 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 19:45:46 volumio-2 sudo[1332]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 29 19:45:46 volumio-2 sudo[1332]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 29 19:45:46 volumio-2 sudo[1346]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory May 29 19:45:46 volumio-2 sudo[1332]: pam_unix(sudo:session): session closed for user root May 29 19:45:46 volumio-2 volumio[1069]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 29 19:45:46 volumio-2 volumio[1069]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 29 19:45:46 volumio-2 volumio[1069]: info: [1748540746462] CoreMusicLibrary::Adding element Last_100 May 29 19:45:46 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 29 19:45:46 volumio-2 volumio[1069]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 29 19:45:46 volumio-2 volumio[1069]: info: [1748540746464] CoreMusicLibrary::Adding element Webradio May 29 19:45:46 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 29 19:45:46 volumio-2 systemd[1]: mpd.service: Deactivated successfully. May 29 19:45:46 volumio-2 systemd[1]: Stopped mpd.service - Music Player Daemon. May 29 19:45:46 volumio-2 systemd[1]: mpd.socket: Deactivated successfully. May 29 19:45:46 volumio-2 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 29 19:45:46 volumio-2 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 29 19:45:46 volumio-2 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 29 19:45:46 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 29 19:45:46 volumio-2 systemd[1]: Starting mpd.service - Music Player Daemon... May 29 19:45:46 volumio-2 volumio[1069]: info: Initializing BBC Radios May 29 19:45:46 volumio-2 sudo[1351]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 29 19:45:46 volumio-2 sudo[1351]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 29 19:45:46 volumio-2 sudo[1362]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory May 29 19:45:46 volumio-2 sudo[1351]: pam_unix(sudo:session): session closed for user root May 29 19:45:46 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 29 19:45:46 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 19:45:46 volumio-2 volumio[1069]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 29 19:45:46 volumio-2 volumio[1069]: info: [1748540746840] CoreMusicLibrary::Adding element YouTube2 May 29 19:45:46 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 29 19:45:46 volumio-2 volumio[1069]: Cannot find translation for source YouTube2 May 29 19:45:46 volumio-2 volumio[1069]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 29 19:45:46 volumio-2 volumio[1069]: info: [1748540746888] CoreMusicLibrary::Adding element YouTube Music May 29 19:45:46 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 29 19:45:46 volumio-2 volumio[1069]: Cannot find translation for source YouTube2 May 29 19:45:46 volumio-2 volumio[1069]: Cannot find translation for source YouTube Music May 29 19:45:46 volumio-2 volumio[1069]: info: Loading i18n strings for locale en May 29 19:45:47 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 29 19:45:47 volumio-2 volumio[1069]: info: Volumio Calling Home May 29 19:45:47 volumio-2 sudo[1374]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R a+rwX /etc/lirc/irexec.lircrc /etc/lirc/lirc_options.conf /etc/lirc/lircd.conf /etc/lirc/lircd.conf.d /etc/lirc/lircmd.conf /etc/lirc/lircrc May 29 19:45:47 volumio-2 sudo[1374]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 19:45:47 volumio-2 sudo[1374]: pam_unix(sudo:session): session closed for user root May 29 19:45:47 volumio-2 sudo[1376]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay rotary-encoder pin_a=6 pin_b=24 relative_axis=true steps-per-period=1 May 29 19:45:47 volumio-2 sudo[1376]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 19:45:48 volumio-2 sudo[1376]: pam_unix(sudo:session): session closed for user root May 29 19:45:48 volumio-2 kernel: rotary-encoder rotary@6: gray May 29 19:45:48 volumio-2 kernel: input: rotary@6 as /devices/platform/rotary@6/input/input0 May 29 19:45:48 volumio-2 (udev-worker)[1384]: event0: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 29 19:45:49 volumio-2 volumio[1069]: info: ir_controller: File permissions successfully set on /etc/lirc/*. May 29 19:45:49 volumio-2 volumio[1069]: info: MPD Permissions set May 29 19:45:49 volumio-2 volumio[1069]: info: MPD Permissions set May 29 19:45:49 volumio-2 volumio[1069]: info: Upmpdcli Daemon Started May 29 19:45:50 volumio-2 volumio[1069]: info: Volumio called home May 29 19:45:50 volumio-2 volumio[1069]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 May 29 19:45:50 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 19:45:50 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 19:45:50 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 19:45:50 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 19:45:50 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 19:45:50 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 19:45:50 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 19:45:50 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 19:45:50 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 19:45:50 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 19:45:50 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 19:45:50 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 19:45:50 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 19:45:50 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 19:45:50 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 19:45:50 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 19:45:50 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 19:45:50 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 19:45:50 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 19:45:50 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 19:45:50 volumio-2 volumio[1069]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 May 29 19:45:50 volumio-2 volumio[1069]: info: CoreCommandRouter::volumioGetState May 29 19:45:50 volumio-2 volumio[1069]: info: CorePlayQueue::getTrack 0 May 29 19:45:51 volumio-2 volumio[1069]: info: Starting Shairport Sync May 29 19:45:51 volumio-2 sudo[1405]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay gpio-key gpio=5 active_low=1 gpio_pull=up keycode=20 May 29 19:45:51 volumio-2 volumio[1069]: info: Starting Shairport Sync May 29 19:45:51 volumio-2 sudo[1405]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 19:45:51 volumio-2 volumio[1069]: info: Starting Shairport Sync May 29 19:45:51 volumio-2 kernel: input: button@5 as /devices/platform/button@5/input/input1 May 29 19:45:51 volumio-2 sudo[1405]: pam_unix(sudo:session): session closed for user root May 29 19:45:51 volumio-2 sudo[1417]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 29 19:45:51 volumio-2 sudo[1417]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 19:45:51 volumio-2 volumio[1069]: info: ir_controller: LIRC correctly updated. May 29 19:45:51 volumio-2 volumio[1069]: info: ir_controller: HAT did not load /proc/device-tree/ir_receiver! May 29 19:45:51 volumio-2 sudo[1412]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 29 19:45:51 volumio-2 sudo[1412]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 19:45:51 volumio-2 sudo[1407]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 29 19:45:51 volumio-2 sudo[1407]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 19:45:51 volumio-2 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 29 19:45:51 volumio-2 systemd[1]: shairport-sync.service: Deactivated successfully. May 29 19:45:51 volumio-2 (udev-worker)[1384]: event1: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 29 19:45:51 volumio-2 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 29 19:45:51 volumio-2 systemd[1]: shairport-sync.service: Consumed 1.666s CPU time. May 29 19:45:51 volumio-2 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 29 19:45:51 volumio-2 sudo[1412]: pam_unix(sudo:session): session closed for user root May 29 19:45:51 volumio-2 sudo[1407]: pam_unix(sudo:session): session closed for user root May 29 19:45:51 volumio-2 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 29 19:45:51 volumio-2 systemd[1]: shairport-sync.service: Deactivated successfully. May 29 19:45:51 volumio-2 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 29 19:45:51 volumio-2 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 29 19:45:51 volumio-2 sudo[1417]: pam_unix(sudo:session): session closed for user root May 29 19:45:51 volumio-2 volumio[1069]: info: ir_controller: Raspberry Pi revision code: a020d3 May 29 19:45:52 volumio-2 volumio[1069]: info: Shairport-Sync Started May 29 19:45:52 volumio-2 volumio[1069]: Error adding Membership: Error: addMembership EINVAL May 29 19:45:52 volumio-2 volumio[1069]: info: Shairport-Sync Started May 29 19:45:52 volumio-2 volumio[1069]: info: Shairport-Sync Started May 29 19:45:52 volumio-2 volumio-remote-updater[552]: [2025-05-29 19:45:52] [connect] Successful connection May 29 19:45:52 volumio-2 volumio[1069]: info: CoreCommandRouter::volumioGetState May 29 19:45:52 volumio-2 volumio[1069]: info: CorePlayQueue::getTrack 0 May 29 19:45:52 volumio-2 sudo[1444]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -r -1 May 29 19:45:52 volumio-2 sudo[1444]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 19:45:52 volumio-2 sudo[1444]: pam_unix(sudo:session): session closed for user root May 29 19:45:52 volumio-2 volumio-remote-updater[552]: [2025-05-29 19:45:52] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1748540752 101 May 29 19:45:52 volumio-2 volumio[1069]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 5 May 29 19:45:52 volumio-2 sudo[1447]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart lircd.service May 29 19:45:52 volumio-2 sudo[1447]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 19:45:52 volumio-2 systemd[1]: Starting lircd-setup.service - lircd(8) initialization helper tool... May 29 19:45:52 volumio-2 volumio[1069]: info: CoreCommandRouter::volumioGetState May 29 19:45:52 volumio-2 volumio[1069]: info: CorePlayQueue::getTrack 0 May 29 19:45:52 volumio-2 sudo[1453]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 29 19:45:52 volumio-2 sudo[1453]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 19:45:52 volumio-2 sudo[1453]: pam_unix(sudo:session): session closed for user root May 29 19:45:53 volumio-2 sudo[1459]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l May 29 19:45:53 volumio-2 sudo[1459]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 19:45:53 volumio-2 sudo[1459]: pam_unix(sudo:session): session closed for user root May 29 19:45:53 volumio-2 sudo[1462]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay gpio-ir gpio_pin=25 gpio_pull=up May 29 19:45:53 volumio-2 sudo[1462]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 19:45:53 volumio-2 sudo[1462]: pam_unix(sudo:session): session closed for user root May 29 19:45:53 volumio-2 kernel: Registered IR keymap rc-rc6-mce May 29 19:45:53 volumio-2 volumio[1069]: info: ir_controller: Overlay gpio-ir gpio_pin=25 gpio_pull=up loaded. May 29 19:45:53 volumio-2 kernel: IR RC6 protocol handler initialized May 29 19:45:53 volumio-2 kernel: rc rc0: gpio_ir_recv as /devices/platform/ir-receiver@19/rc/rc0 May 29 19:45:53 volumio-2 kernel: rc rc0: lirc_dev: driver gpio_ir_recv registered at minor = 0, raw IR receiver, no transmitter May 29 19:45:53 volumio-2 kernel: input: gpio_ir_recv as /devices/platform/ir-receiver@19/rc/rc0/input2 May 29 19:45:53 volumio-2 sudo[1473]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart lircd.service May 29 19:45:53 volumio-2 sudo[1473]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 19:45:53 volumio-2 lircd-setup[1450]: /usr/sbin/lircd-setup:37: DeprecationWarning: The SafeConfigParser class has been renamed to ConfigParser in Python 3.2. This alias will be removed in Python 3.12. Use ConfigParser directly instead. May 29 19:45:53 volumio-2 lircd-setup[1450]: parser = configparser.SafeConfigParser() May 29 19:45:53 volumio-2 systemd[1]: lircd-setup.service: Deactivated successfully. May 29 19:45:53 volumio-2 systemd[1]: Finished lircd-setup.service - lircd(8) initialization helper tool. May 29 19:45:53 volumio-2 (udev-worker)[1384]: event2: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. May 29 19:45:54 volumio-2 systemd-logind[546]: Watching system buttons on /dev/input/event2 (gpio_ir_recv) May 29 19:45:54 volumio-2 systemd[1]: Starting lircd.service - Flexible IR remote input/output application support... May 29 19:45:54 volumio-2 mpd[1366]: 2025-05-29T19:45:54 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 29 19:45:54 volumio-2 systemd[1]: lircd.service: Deactivated successfully. May 29 19:45:54 volumio-2 systemd[1]: Stopped lircd.service - Flexible IR remote input/output application support. May 29 19:45:54 volumio-2 systemd[1]: Starting lircd-setup.service - lircd(8) initialization helper tool... May 29 19:45:54 volumio-2 systemd[1]: Started mpd.service - Music Player Daemon. May 29 19:45:54 volumio-2 sudo[1322]: pam_unix(sudo:session): session closed for user root May 29 19:45:54 volumio-2 sudo[1335]: pam_unix(sudo:session): session closed for user root May 29 19:45:54 volumio-2 volumio[1069]: info: Completed starting Core Plugins May 29 19:45:54 volumio-2 volumio[1069]: info: ------------------------------------------- May 29 19:45:54 volumio-2 volumio[1069]: info: ----- MyVolumio plugins startup ---- May 29 19:45:54 volumio-2 volumio[1069]: info: ------------------------------------------- May 29 19:45:54 volumio-2 lircd-setup[1477]: /usr/sbin/lircd-setup:37: DeprecationWarning: The SafeConfigParser class has been renamed to ConfigParser in Python 3.2. This alias will be removed in Python 3.12. Use ConfigParser directly instead. May 29 19:45:54 volumio-2 lircd-setup[1477]: parser = configparser.SafeConfigParser() May 29 19:45:54 volumio-2 volumio[1069]: info: [MyVolumio PluginManager] Fetching plans data.... May 29 19:45:54 volumio-2 systemd[1]: lircd-setup.service: Deactivated successfully. May 29 19:45:54 volumio-2 systemd[1]: Finished lircd-setup.service - lircd(8) initialization helper tool. May 29 19:45:54 volumio-2 systemd[1]: Starting lircd.service - Flexible IR remote input/output application support... May 29 19:45:54 volumio-2 lircd-0.10.1[1482]: Info: lircd: Opening log, level: Info May 29 19:45:54 volumio-2 lircd-0.10.1[1482]: Notice: Version: lircd 0.10.1 May 29 19:45:54 volumio-2 lircd-0.10.1[1482]: Notice: System info: Linux volumio-2 6.12.27-v7+ #1876 SMP Tue May 6 15:08:23 BST 2025 armv7l GNU/Linux May 29 19:45:54 volumio-2 volumio[1069]: error: MPD error: The expression evaluated to a falsy value: May 29 19:45:54 volumio-2 volumio[1069]: assert.ok(self.idling) May 29 19:45:54 volumio-2 volumio[1069]: error: The expression evaluated to a falsy value: May 29 19:45:54 volumio-2 volumio[1069]: assert.ok(self.idling) May 29 19:45:54 volumio-2 volumio[1069]: verbose: New Socket.io Connection to 192.168.88.184 from 192.168.88.68 UA: Mozilla/5.0 (Linux; Android 14; SM-A556B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.125 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 May 29 19:45:54 volumio-2 volumio[1069]: error: updateQueue error: null May 29 19:45:54 volumio-2 volumio[1069]: info: MPD running with PID1366 May 29 19:45:54 volumio-2 volumio[1069]: ,establishing connection May 29 19:45:54 volumio-2 volumio[1069]: error: updateQueue error: null May 29 19:45:54 volumio-2 lircd-0.10.1[1482]: Info: Initial device: /dev/lirc0 May 29 19:45:54 volumio-2 lircd-0.10.1[1482]: Notice: 'lirc' written to protocols file /sys/class/rc/rc0/protocols May 29 19:45:54 volumio-2 lircd-0.10.1[1482]: Info: Initial device: /dev/lirc0 May 29 19:45:54 volumio-2 lircd-0.10.1[1482]: Info: [lirc] protocol is enabled May 29 19:45:54 volumio-2 lircd[1482]: lircd-0.10.1[1482]: Info: lircd: Opening log, level: Info May 29 19:45:54 volumio-2 lircd-0.10.1[1482]: Notice: Options: driver: default May 29 19:45:54 volumio-2 lircd[1482]: lircd-0.10.1[1482]: Notice: Using systemd fd May 29 19:45:54 volumio-2 lircd[1482]: lircd-0.10.1[1482]: Warning: Running as root May 29 19:45:54 volumio-2 lircd[1482]: lircd-0.10.1[1482]: Info: Using remote: rc2. May 29 19:45:54 volumio-2 lircd-0.10.1[1482]: Notice: Options: output: /var/run/lirc/lircd May 29 19:45:54 volumio-2 lircd[1482]: lircd-0.10.1[1482]: Notice: lircd(default) ready, using /var/run/lirc/lircd May 29 19:45:54 volumio-2 lircd-0.10.1[1482]: Notice: Options: nodaemon: 1 May 29 19:45:54 volumio-2 systemd[1]: Started lircd.service - Flexible IR remote input/output application support. May 29 19:45:54 volumio-2 lircd-0.10.1[1482]: Notice: Options: plugindir: /usr/lib/arm-linux-gnueabihf/lirc/plugins May 29 19:45:54 volumio-2 lircd-0.10.1[1482]: Notice: Options: logfile: syslog May 29 19:45:54 volumio-2 lircd-0.10.1[1482]: Notice: Options: immediate-init: 0 May 29 19:45:54 volumio-2 lircd-0.10.1[1482]: Notice: Options: permission: 666 May 29 19:45:54 volumio-2 lircd-0.10.1[1482]: Notice: Options: driver-options: May 29 19:45:54 volumio-2 lircd-0.10.1[1482]: Notice: Options: pidfile: /var/run/lirc/lircd.pid May 29 19:45:54 volumio-2 lircd-0.10.1[1482]: Notice: Options: listen: 0 May 29 19:45:54 volumio-2 lircd-0.10.1[1482]: Notice: Options: connect: (null) May 29 19:45:54 volumio-2 lircd-0.10.1[1482]: Notice: Options: userelease: 0 May 29 19:45:54 volumio-2 lircd-0.10.1[1482]: Notice: Options: effective_user: (null) May 29 19:45:54 volumio-2 lircd-0.10.1[1482]: Notice: Options: release_suffix: _EVUP May 29 19:45:54 volumio-2 lircd-0.10.1[1482]: Notice: Options: allow_simulate: 0 May 29 19:45:54 volumio-2 lircd-0.10.1[1482]: Notice: Options: repeat_max: 600 May 29 19:45:54 volumio-2 lircd-0.10.1[1482]: Notice: Options: configfile: /etc/lirc/lircd.conf May 29 19:45:54 volumio-2 lircd-0.10.1[1482]: Notice: Options: dynamic_codes: (null) May 29 19:45:54 volumio-2 lircd-0.10.1[1482]: Notice: Current driver: default May 29 19:45:54 volumio-2 lircd-0.10.1[1482]: Notice: Driver API version: 3 May 29 19:45:54 volumio-2 lircd-0.10.1[1482]: Notice: Driver version: 0.10.0 May 29 19:45:54 volumio-2 lircd-0.10.1[1482]: Notice: Driver info: See file:///usr/share/doc/lirc/plugindocs/default.html May 29 19:45:54 volumio-2 lircd-0.10.1[1482]: Info: lircd: Opening log, level: Info May 29 19:45:54 volumio-2 lircd-0.10.1[1482]: Notice: Using systemd fd May 29 19:45:54 volumio-2 lircd-0.10.1[1482]: Warning: Running as root May 29 19:45:54 volumio-2 lircd-0.10.1[1482]: Info: Using remote: rc2. May 29 19:45:54 volumio-2 lircd-0.10.1[1482]: Notice: lircd(default) ready, using /var/run/lirc/lircd May 29 19:45:54 volumio-2 sudo[1447]: pam_unix(sudo:session): session closed for user root May 29 19:45:54 volumio-2 sudo[1473]: pam_unix(sudo:session): session closed for user root May 29 19:45:54 volumio-2 volumio[1069]: info: ir_controller: systemctl restart lircd.service succeeded. May 29 19:45:54 volumio-2 volumio[1069]: info: ir_controller: systemctl restart lircd.service succeeded. May 29 19:45:54 volumio-2 sudo[1500]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart irexec.service May 29 19:45:54 volumio-2 sudo[1500]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 19:45:54 volumio-2 sudo[1502]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart irexec.service May 29 19:45:54 volumio-2 sudo[1502]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 19:45:54 volumio-2 systemd[1]: Started irexec.service - Handle events from IR remotes decoded by lircd(8). May 29 19:45:54 volumio-2 sudo[1500]: pam_unix(sudo:session): session closed for user root May 29 19:45:54 volumio-2 lircd[1482]: lircd-0.10.1[1482]: Notice: accepted new client on /var/run/lirc/lircd May 29 19:45:54 volumio-2 lircd-0.10.1[1482]: Notice: accepted new client on /var/run/lirc/lircd May 29 19:45:54 volumio-2 lircd[1482]: lircd-0.10.1[1482]: Info: [lirc] protocol is enabled May 29 19:45:54 volumio-2 lircd-0.10.1[1482]: Info: [lirc] protocol is enabled May 29 19:45:54 volumio-2 volumio[1069]: info: ir_controller: systemctl restart irexec.service succeeded. May 29 19:45:54 volumio-2 lircd[1482]: lircd-0.10.1[1482]: Info: removed client May 29 19:45:54 volumio-2 lircd-0.10.1[1482]: Info: removed client May 29 19:45:54 volumio-2 systemd[1]: Stopping irexec.service - Handle events from IR remotes decoded by lircd(8)... May 29 19:45:54 volumio-2 systemd[1]: irexec.service: Deactivated successfully. May 29 19:45:54 volumio-2 systemd[1]: Stopped irexec.service - Handle events from IR remotes decoded by lircd(8). May 29 19:45:54 volumio-2 systemd[1]: Started irexec.service - Handle events from IR remotes decoded by lircd(8). May 29 19:45:54 volumio-2 sudo[1502]: pam_unix(sudo:session): session closed for user root May 29 19:45:54 volumio-2 lircd[1482]: lircd-0.10.1[1482]: Notice: accepted new client on /var/run/lirc/lircd May 29 19:45:54 volumio-2 lircd-0.10.1[1482]: Notice: accepted new client on /var/run/lirc/lircd May 29 19:45:54 volumio-2 lircd[1482]: lircd-0.10.1[1482]: Info: [lirc] protocol is enabled May 29 19:45:54 volumio-2 lircd-0.10.1[1482]: Info: [lirc] protocol is enabled May 29 19:45:54 volumio-2 volumio[1069]: info: ir_controller: systemctl restart irexec.service succeeded. May 29 19:45:55 volumio-2 volumio[1069]: info: CoreCommandRouter::volumioGetState May 29 19:45:55 volumio-2 volumio[1069]: info: CorePlayQueue::getTrack 0 May 29 19:45:55 volumio-2 volumio[1069]: info: Listing playlists May 29 19:45:55 volumio-2 volumio[1069]: info: Listing playlists May 29 19:45:56 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 29 19:45:56 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 29 19:45:56 volumio-2 volumio[1069]: info: Discovery: Getting this device information May 29 19:45:56 volumio-2 volumio[1069]: info: CoreCommandRouter::volumioGetState May 29 19:45:56 volumio-2 volumio[1069]: info: CorePlayQueue::getTrack 0 May 29 19:45:56 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 29 19:45:56 volumio-2 volumio[1069]: verbose: New Socket.io Connection to 192.168.88.184:3000 from 192.168.88.68 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 May 29 19:45:56 volumio-2 volumio[1069]: info: CoreCommandRouter::volumioGetState May 29 19:45:56 volumio-2 volumio[1069]: info: CorePlayQueue::getTrack 0 May 29 19:45:56 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 29 19:45:56 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 29 19:45:59 volumio-2 volumio[1069]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 29 19:45:59 volumio-2 volumio[1069]: verbose: New Socket.io Connection to 192.168.88.184 from 192.168.88.68 UA: Mozilla/5.0 (Linux; Android 14; SM-A556B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.125 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 May 29 19:45:59 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 29 19:46:00 volumio-2 volumio[1069]: info: Received Get System Info May 29 19:46:00 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 29 19:46:00 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 29 19:46:00 volumio-2 volumio[1069]: info: Discovery: Getting this device information May 29 19:46:00 volumio-2 volumio[1069]: info: CoreCommandRouter::volumioGetState May 29 19:46:00 volumio-2 volumio[1069]: info: CorePlayQueue::getTrack 0 May 29 19:46:00 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 29 19:46:00 volumio-2 volumio[1069]: info: CoreCommandRouter::volumioGetState May 29 19:46:00 volumio-2 volumio[1069]: info: CorePlayQueue::getTrack 0 May 29 19:46:00 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 29 19:46:00 volumio-2 volumio[1069]: info: Listing playlists May 29 19:46:01 volumio-2 volumio[1069]: info: CoreCommandRouter::volumioGetState May 29 19:46:01 volumio-2 volumio[1069]: info: CorePlayQueue::getTrack 0 May 29 19:46:01 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken May 29 19:46:01 volumio-2 sudo[1514]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 29 19:46:01 volumio-2 sudo[1514]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 19:46:02 volumio-2 sudo[1516]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 29 19:46:02 volumio-2 sudo[1516]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 19:46:02 volumio-2 sudo[1514]: pam_unix(sudo:session): session closed for user root May 29 19:46:02 volumio-2 sudo[1516]: pam_unix(sudo:session): session closed for user root May 29 19:46:02 volumio-2 volumio[1069]: verbose: New Socket.io Connection to 192.168.88.184 from 192.168.88.68 UA: Mozilla/5.0 (Linux; Android 14; SM-A556B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.125 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 May 29 19:46:02 volumio-2 volumio[1069]: info: CoreCommandRouter::volumioGetVisibleSources May 29 19:46:02 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 29 19:46:02 volumio-2 volumio[1069]: info: CoreCommandRouter::volumioGetState May 29 19:46:02 volumio-2 volumio[1069]: info: CorePlayQueue::getTrack 0 May 29 19:46:02 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 29 19:46:02 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 29 19:46:02 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 29 19:46:02 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 29 19:46:03 volumio-2 volumio[1069]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 29 19:46:03 volumio-2 volumio[1069]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 29 19:46:03 volumio-2 volumio[1069]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 29 19:46:03 volumio-2 volumio[1069]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 29 19:46:03 volumio-2 volumio[1069]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 29 19:46:03 volumio-2 volumio[1069]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 29 19:46:03 volumio-2 volumio[1069]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 29 19:46:03 volumio-2 volumio[1069]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 29 19:46:03 volumio-2 volumio[1069]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 29 19:46:03 volumio-2 volumio[1069]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 29 19:46:03 volumio-2 volumio[1069]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium May 29 19:46:03 volumio-2 volumio[1069]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 29 19:46:03 volumio-2 volumio[1069]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 29 19:46:03 volumio-2 volumio[1069]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 29 19:46:03 volumio-2 volumio[1069]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 29 19:46:03 volumio-2 volumio[1069]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 29 19:46:03 volumio-2 volumio[1069]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 29 19:46:03 volumio-2 volumio[1069]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 29 19:46:03 volumio-2 volumio[1069]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 29 19:46:03 volumio-2 volumio[1069]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 29 19:46:03 volumio-2 volumio[1069]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 29 19:46:03 volumio-2 volumio[1069]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar May 29 19:46:03 volumio-2 volumio[1069]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 29 19:46:03 volumio-2 volumio[1069]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 29 19:46:03 volumio-2 volumio[1069]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 29 19:46:03 volumio-2 volumio[1069]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 29 19:46:03 volumio-2 volumio[1069]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 29 19:46:03 volumio-2 volumio[1069]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 29 19:46:03 volumio-2 volumio[1069]: info: Adding plugin bluetooth to MyMusic Plugins May 29 19:46:03 volumio-2 volumio[1069]: info: Adding plugin multiroom to MyMusic Plugins May 29 19:46:03 volumio-2 volumio[1069]: info: Adding plugin metavolumio to MyMusic Plugins May 29 19:46:03 volumio-2 volumio[1069]: info: Adding plugin cd_controller to MyMusic Plugins May 29 19:46:03 volumio-2 volumio[1069]: info: Adding plugin smart_inputs to MyMusic Plugins May 29 19:46:03 volumio-2 volumio[1069]: info: Adding plugin tidalconnect to MyMusic Plugins May 29 19:46:03 volumio-2 volumio[1069]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 29 19:46:06 volumio-2 volumio[1069]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 29 19:46:06 volumio-2 volumio[1069]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 29 19:46:06 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 19:46:06 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 19:46:06 volumio-2 volumio[1069]: info: Starting MyVolumio Remote Streaming Endpoints May 29 19:46:06 volumio-2 volumio[1069]: info: MyVolumio login type: Token May 29 19:46:06 volumio-2 volumio[1069]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 29 19:46:06 volumio-2 volumio[1069]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 29 19:46:09 volumio-2 volumio[1069]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 29 19:46:09 volumio-2 volumio[1069]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 29 19:46:09 volumio-2 volumio[1069]: info: Streaming services startup May 29 19:46:09 volumio-2 volumio[1069]: info: Starting Streaming Daemon May 29 19:46:09 volumio-2 volumio[1069]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 29 19:46:09 volumio-2 sudo[1534]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 29 19:46:09 volumio-2 sudo[1534]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 19:46:09 volumio-2 sudo[1534]: pam_unix(sudo:session): session closed for user root May 29 19:46:09 volumio-2 volumio[1069]: error: Cannot start Volumio Streaming Daemon May 29 19:46:09 volumio-2 volumio[1069]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 29 19:46:09 volumio-2 volumio[1069]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 29 19:46:09 volumio-2 volumio[1069]: info: CoreCommandRouter::volumioGetState May 29 19:46:09 volumio-2 volumio[1069]: info: CorePlayQueue::getTrack 0 May 29 19:46:10 volumio-2 volumio[1069]: error: MyVolumio Custom Token format not valid, refreshing it May 29 19:46:10 volumio-2 sudo[1541]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 29 19:46:10 volumio-2 sudo[1541]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 19:46:10 volumio-2 sudo[1541]: pam_unix(sudo:session): session closed for user root May 29 19:46:10 volumio-2 sudo[1543]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 29 19:46:10 volumio-2 sudo[1543]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 19:46:10 volumio-2 sudo[1543]: pam_unix(sudo:session): session closed for user root May 29 19:46:11 volumio-2 volumio[1069]: verbose: New Socket.io Connection to 192.168.88.184 from 192.168.88.68 UA: Mozilla/5.0 (Linux; Android 14; SM-A556B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/136.0.7103.125 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 May 29 19:46:11 volumio-2 volumio[1069]: info: CoreCommandRouter::volumioGetState May 29 19:46:11 volumio-2 volumio[1069]: info: CorePlayQueue::getTrack 0 May 29 19:46:11 volumio-2 volumio[1069]: info: MyVolumio login type: Token May 29 19:46:12 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 29 19:46:12 volumio-2 volumio[1069]: info: Listing playlists May 29 19:46:12 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 29 19:46:12 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 29 19:46:12 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 29 19:46:12 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 29 19:46:12 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 29 19:46:12 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 29 19:46:12 volumio-2 volumio[1069]: info: Discovery: Getting this device information May 29 19:46:12 volumio-2 volumio[1069]: info: CoreCommandRouter::volumioGetState May 29 19:46:12 volumio-2 volumio[1069]: info: CorePlayQueue::getTrack 0 May 29 19:46:12 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 29 19:46:12 volumio-2 volumio[1069]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 29 19:46:13 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 29 19:46:13 volumio-2 volumio[1069]: info: Received Get System Info May 29 19:46:13 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 29 19:46:13 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 29 19:46:13 volumio-2 volumio[1069]: info: Discovery: Getting this device information May 29 19:46:13 volumio-2 volumio[1069]: info: CoreCommandRouter::volumioGetState May 29 19:46:13 volumio-2 volumio[1069]: info: CorePlayQueue::getTrack 0 May 29 19:46:13 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 29 19:46:13 volumio-2 volumio[1069]: info: MyVolumio token set successfully May 29 19:46:13 volumio-2 volumio[1069]: info: MYVOLUMIO: Adding device May 29 19:46:13 volumio-2 volumio[1069]: info: MYVOLUMIO: Evaluating Server May 29 19:46:13 volumio-2 volumio[1069]: info: CoreCommandRouter::volumioGetQueue May 29 19:46:13 volumio-2 volumio[1069]: info: CoreStateMachine::getQueue May 29 19:46:13 volumio-2 volumio[1069]: info: CorePlayQueue::getQueue May 29 19:46:14 volumio-2 volumio[1069]: info: MyVolumio status changed May 29 19:46:14 volumio-2 volumio[1069]: info: Streaming services startup May 29 19:46:14 volumio-2 volumio[1069]: info: Starting Streaming Daemon May 29 19:46:14 volumio-2 volumio[1069]: info: Removing browser output: myVolumio user plan is not superstar May 29 19:46:14 volumio-2 volumio[1069]: info: Removing audio output: May 29 19:46:14 volumio-2 volumio[1069]: info: Stoppping Tunnel 1 May 29 19:46:14 volumio-2 sudo[1566]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service May 29 19:46:14 volumio-2 sudo[1564]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 29 19:46:14 volumio-2 sudo[1564]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 19:46:14 volumio-2 sudo[1566]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 19:46:14 volumio-2 systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 29 19:46:14 volumio-2 systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 29 19:46:14 volumio-2 systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 29 19:46:14 volumio-2 systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 29 19:46:14 volumio-2 systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 29 19:46:14 volumio-2 systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 29 19:46:14 volumio-2 systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 29 19:46:14 volumio-2 systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 29 19:46:14 volumio-2 sudo[1566]: pam_unix(sudo:session): session closed for user root May 29 19:46:14 volumio-2 volumio[1069]: info: Remote SSH Stopped May 29 19:46:14 volumio-2 sudo[1564]: pam_unix(sudo:session): session closed for user root May 29 19:46:14 volumio-2 volumio[1069]: error: Cannot start Volumio Streaming Daemon May 29 19:46:14 volumio-2 volumio[1069]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 29 19:46:14 volumio-2 volumio[1069]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 29 19:46:14 volumio-2 volumio[1069]: info: Setting Geolocation for MyVolumio to eu6 May 29 19:46:14 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 19:46:14 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 19:46:14 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 19:46:14 volumio-2 volumio[1069]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} May 29 19:46:15 volumio-2 volumio[1069]: info: CoreCommandRouter::volumioGetState May 29 19:46:15 volumio-2 volumio[1069]: info: CorePlayQueue::getTrack 0 May 29 19:46:15 volumio-2 volumio[1069]: info: Listing playlists May 29 19:46:15 volumio-2 volumio[1069]: info: Listing playlists May 29 19:46:15 volumio-2 volumio[1069]: info: Updating MyVolumio device info May 29 19:46:15 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 19:46:15 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 19:46:15 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 19:46:16 volumio-2 volumio[1069]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} May 29 19:46:16 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 29 19:46:16 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 19:46:16 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 29 19:46:17 volumio-2 volumio-remote-updater[552]: No test mode May 29 19:46:17 volumio-2 volumio-remote-updater[552]: Alpha test mode enabled May 29 19:46:17 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 29 19:46:17 volumio-2 volumio[1069]: info: CoreCommandRouter::volumioPlay May 29 19:46:17 volumio-2 volumio[1069]: info: CoreStateMachine::play index 4 May 29 19:46:17 volumio-2 volumio[1069]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 19:46:17 volumio-2 volumio[1069]: info: CoreStateMachine::stop May 29 19:46:17 volumio-2 volumio[1069]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 19:46:17 volumio-2 volumio[1069]: info: CoreStateMachine::play index undefined May 29 19:46:17 volumio-2 volumio[1069]: info: CoreStateMachine::setConsumeUpdateService undefined May 29 19:46:17 volumio-2 volumio[1069]: info: CorePlayQueue::getTrack 4 May 29 19:46:17 volumio-2 volumio[1069]: info: CoreStateMachine::startPlaybackTimer May 29 19:46:17 volumio-2 volumio[1069]: info: CorePlayQueue::getTrack 4 May 29 19:46:17 volumio-2 volumio[1069]: info: [ytmusic-play] clearAddPlayTrack: ytmusic/video@explodeTrackData:o=%7B%22type%22%3A%22video%22%2C%22title%22%3A%22The%20Best%20of%20Mozart%22%2C%22artist%22%3A%22ALIBI%20Music%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FRb0UmrCXxVA%2Fhqdefault.jpg%3Fsqp%3D-oaymwEWCMACELQBIAQqCghQEJADGFogjgJIWg%26rs%3DAMzJL3kiTeiRgxasqznYegA9R9ghoRO21A%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22Rb0UmrCXxVA%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_OMV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22Rb0UmrCXxVA%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_OMV%22%7D%7D%7D May 29 19:46:17 volumio-2 volumio[1069]: info: [ytmusic] InnertubeLoader: creating Innertube instance... May 29 19:46:17 volumio-2 volumio[1069]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 29 19:46:17 volumio-2 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 29 19:46:18 volumio-2 volumio[1069]: info: MYVOLUMIO: Adding device May 29 19:46:18 volumio-2 volumio[1069]: info: MYVOLUMIO: Evaluating Server May 29 19:46:19 volumio-2 systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... May 29 19:46:19 volumio-2 dbus-daemon[533]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.22' (uid=0 pid=1593 comm="timedatectl show --property=NTPSynchronized --valu") May 29 19:46:19 volumio-2 systemd[1]: Starting systemd-timedated.service - Time & Date Service... May 29 19:46:19 volumio-2 dbus-daemon[533]: [system] Successfully activated service 'org.freedesktop.timedate1' May 29 19:46:19 volumio-2 systemd[1]: Started systemd-timedated.service - Time & Date Service. May 29 19:46:19 volumio-2 setdatetime-helper.sh[1590]: Time is not synchronized. Attempting to sync... May 29 19:46:20 volumio-2 sudo[1610]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Thu, 29 May 2025 17:46:20 GMT#015' May 29 19:46:20 volumio-2 sudo[1610]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 29 19:46:20 volumio-2 setdatetime-helper.sh[1611]: Thu May 29 19:46:20 CEST 2025 May 29 19:46:20 volumio-2 systemd-journald[317]: Time jumped backwards, rotating. May 29 19:46:20 volumio-2 sudo[1610]: pam_unix(sudo:session): session closed for user root May 29 19:46:20 volumio-2 setdatetime-helper.sh[1590]: Time synchronized successfully. May 29 19:46:20 volumio-2 systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. May 29 19:46:20 volumio-2 systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. May 29 19:46:20 volumio-2 volumio[1069]: info: CoreCommandRouter::volumioRemoveQueueItem May 29 19:46:20 volumio-2 volumio[1069]: info: CoreStateMachine::removeQueueItem May 29 19:46:20 volumio-2 volumio[1069]: info: CorePlayQueue::removeQueueItem 3 May 29 19:46:20 volumio-2 volumio[1069]: info: CorePlayQueue::saveQueue May 29 19:46:20 volumio-2 volumio[1069]: info: CoreCommandRouter::volumioPushQueue May 29 19:46:20 volumio-2 volumio[1069]: error: Failed to ping endpoint us1.myvolumio.org : unknown error May 29 19:46:20 volumio-2 volumio[1069]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 29 19:46:20 volumio-2 volumio[1069]: Error: Unable to resolve or reject the same promise twice May 29 19:46:20 volumio-2 volumio[1069]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) May 29 19:46:20 volumio-2 volumio[1069]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32371) May 29 19:46:20 volumio-2 volumio[1069]: at Socket.emit (node:events:514:28) May 29 19:46:20 volumio-2 volumio[1069]: at addChunk (node:internal/streams/readable:343:12) May 29 19:46:20 volumio-2 volumio[1069]: at readableAddChunk (node:internal/streams/readable:316:9) May 29 19:46:20 volumio-2 volumio[1069]: at Readable.push (node:internal/streams/readable:253:10) May 29 19:46:20 volumio-2 volumio[1069]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) May 29 19:46:20 volumio-2 volumio[1069]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 29 19:46:23 volumio-2 sudo[1628]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-05-29 19:45' May 29 19:46:23 volumio-2 sudo[1628]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="1da895bfdbb6eb90d965df32a25a3568df7c84ed" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu May 29 13:12:59 UTC 2025" VOLUMIO_VERSION="4.004" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2df63d74196e499fa1e34fb765df1cca"