Jun 22 09:30:20 volumio-2 ntpd[827]: CLOCK: time stepped by 55.702348 Jun 22 09:30:20 volumio-2 ntpd[827]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Jun 22 09:30:21 volumio-2 volumio[1059]: info: Loading plugin "network"... Jun 22 09:30:21 volumio-2 volumio[1059]: info: Refreshing Cached IP Addresses Jun 22 09:30:21 volumio-2 sudo[1121]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 22 09:30:21 volumio-2 sudo[1121]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 22 09:30:21 volumio-2 sudo[1121]: pam_unix(sudo:session): session closed for user root Jun 22 09:30:21 volumio-2 volumio[1059]: info: Loading plugin "services"... Jun 22 09:30:21 volumio-2 volumio[1059]: info: Loading plugin "alsa_controller"... Jun 22 09:30:21 volumio-2 sudo[1124]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 22 09:30:21 volumio-2 sudo[1124]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 22 09:30:21 volumio-2 sudo[1124]: pam_unix(sudo:session): session closed for user root Jun 22 09:30:21 volumio-2 sudo[1135]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 22 09:30:21 volumio-2 sudo[1135]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 22 09:30:21 volumio-2 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 22 09:30:21 volumio-2 volumio[1059]: info: Loading plugin "wizard"... Jun 22 09:30:21 volumio-2 volumio[1059]: info: Loading plugin "networkfs"... Jun 22 09:30:21 volumio-2 volumio[1059]: info: Starting Udev Watcher for removable devices Jun 22 09:30:22 volumio-2 sudo[1156]: 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 Jun 22 09:30:22 volumio-2 sudo[1156]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 22 09:30:22 volumio-2 volumio[1059]: info: Ignoring mount for partition: boot Jun 22 09:30:22 volumio-2 volumio[1059]: info: Ignoring mount for partition: volumio Jun 22 09:30:22 volumio-2 volumio[1059]: info: Ignoring mount for partition: volumio_data Jun 22 09:30:22 volumio-2 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 22 09:30:22 volumio-2 volumio[1059]: info: Loading plugin "volumio_command_line_client"... Jun 22 09:30:22 volumio-2 volumio[1059]: info: Loading plugin "upnp"... Jun 22 09:30:22 volumio-2 volumio[1059]: info: [1750577422083] Starting Upmpd Daemon Jun 22 09:30:22 volumio-2 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 22 09:30:22 volumio-2 volumio[1059]: info: Loading plugin "my_music"... Jun 22 09:30:22 volumio-2 volumio[1059]: info: Loading plugin "mpd"... Jun 22 09:30:22 volumio-2 kernel: netfs: FS-Cache loaded Jun 22 09:30:22 volumio-2 kernel: Key type cifs.spnego registered Jun 22 09:30:22 volumio-2 kernel: Key type cifs.idmap registered Jun 22 09:30:22 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. Jun 22 09:30:22 volumio-2 kernel: CIFS: Attempting to mount //192.168.88.148/Public/music Jun 22 09:30:22 volumio-2 volumio[1059]: info: Loading plugin "upnp_browser"... Jun 22 09:30:23 volumio-2 sudo[1156]: pam_unix(sudo:session): session closed for user root Jun 22 09:30:23 volumio-2 systemd[1]: systemd-fsckd.service: Deactivated successfully. Jun 22 09:30:24 volumio-2 volumio[1059]: info: Loading plugin "alarm-clock"... Jun 22 09:30:24 volumio-2 volumio[1059]: info: Loading plugin "airplay_emulation"... Jun 22 09:30:24 volumio-2 volumio[1059]: info: Starting Shairport Sync Jun 22 09:30:24 volumio-2 volumio[1059]: info: Loading plugin "last_100"... Jun 22 09:30:24 volumio-2 volumio[1059]: info: Loading plugin "webradio"... Jun 22 09:30:24 volumio-2 volumio[1059]: info: Loading plugin "i2s_dacs"... Jun 22 09:30:24 volumio-2 volumio[1059]: info: Loading plugin "volumiodiscovery"... Jun 22 09:30:25 volumio-2 volumio[1059]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 22 09:30:25 volumio-2 volumio[1059]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 22 09:30:25 volumio-2 node[1059]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 22 09:30:25 volumio-2 volumio[1059]: *** WARNING *** For more information see Jun 22 09:30:25 volumio-2 volumio[1059]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 22 09:30:25 volumio-2 volumio[1059]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 22 09:30:25 volumio-2 volumio[1059]: *** WARNING *** For more information see Jun 22 09:30:25 volumio-2 node[1059]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 22 09:30:25 volumio-2 node[1059]: *** WARNING *** For more information see Jun 22 09:30:25 volumio-2 node[1059]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 22 09:30:25 volumio-2 node[1059]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 22 09:30:25 volumio-2 node[1059]: *** WARNING *** For more information see Jun 22 09:30:25 volumio-2 volumio[1059]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 22 09:30:25 volumio-2 volumio[1059]: info: Discovery: Started advertising with name: Volumio 2 Jun 22 09:30:25 volumio-2 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 22 09:30:25 volumio-2 volumio[1059]: info: Loading plugin "youtube2"... Jun 22 09:30:25 volumio-2 dhcpcd[512]: timed out Jun 22 09:30:25 volumio-2 sh[505]: timed out Jun 22 09:30:25 volumio-2 sh[478]: ifup: failed to bring up eth0 Jun 22 09:30:25 volumio-2 systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Jun 22 09:30:25 volumio-2 systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Jun 22 09:30:25 volumio-2 sudo[1135]: pam_unix(sudo:session): session closed for user root Jun 22 09:30:28 volumio-2 systemd[1]: systemd-hostnamed.service: Deactivated successfully. Jun 22 09:30:29 volumio-2 volumio[1059]: info: Loading plugin "ytmusic"... Jun 22 09:30:33 volumio-2 volumio[1059]: info: Loading plugin "outputs"... Jun 22 09:30:33 volumio-2 volumio[1059]: info: Loading plugin "albumart"... Jun 22 09:30:33 volumio-2 volumio[1059]: info: Plugin example_plugin is not enabled Jun 22 09:30:33 volumio-2 volumio[1059]: info: Loading plugin "inputs"... Jun 22 09:30:33 volumio-2 volumio[1059]: info: Loading plugin "updater_comm"... Jun 22 09:30:33 volumio-2 volumio[1059]: info: Plugin mpdemulation is not enabled Jun 22 09:30:33 volumio-2 volumio[1059]: info: Loading plugin "rest_api"... Jun 22 09:30:33 volumio-2 volumio[1059]: info: Loading plugin "websocket"... Jun 22 09:30:33 volumio-2 volumio[1059]: info: Starting Socket.io Server version 1.7.4 Jun 22 09:30:33 volumio-2 volumio[1059]: info: Loading plugin "backup_restore"... Jun 22 09:30:33 volumio-2 volumio[1214]: Forking 3 albumart workers Jun 22 09:30:33 volumio-2 volumio[1059]: info: Applying required configuration parameters for plugin backup_restore Jun 22 09:30:33 volumio-2 volumio[1059]: info: Loading plugin "gpio_control"... Jun 22 09:30:34 volumio-2 volumio-remote-updater[533]: [2025-06-22 09:30:34] [connect] Successful connection Jun 22 09:30:34 volumio-2 volumio[1059]: gpiox_arm32 V2.2.1 Jun 22 09:30:36 volumio-2 volumio[1059]: info: Applying required configuration parameters for plugin gpio_control Jun 22 09:30:36 volumio-2 volumio[1059]: info: Loading plugin "ir_controller"... Jun 22 09:30:37 volumio-2 volumio[1059]: info: Applying required configuration parameters for plugin ir_controller Jun 22 09:30:37 volumio-2 volumio[1224]: Starting albumart workers Jun 22 09:30:38 volumio-2 volumio[1059]: info: Loading plugin "rotaryencoder2"... Jun 22 09:30:39 volumio-2 volumio[1226]: Starting albumart workers Jun 22 09:30:40 volumio-2 volumio[1225]: Starting albumart workers Jun 22 09:30:40 volumio-2 volumio[1059]: info: Loading i18n strings for locale en Jun 22 09:30:40 volumio-2 volumio[1059]: Updating browse sources language Jun 22 09:30:40 volumio-2 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 22 09:30:41 volumio-2 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 22 09:30:41 volumio-2 volumio[1059]: info: CoreCommandRouter::initPlayerControls Jun 22 09:30:41 volumio-2 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 22 09:30:41 volumio-2 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 22 09:30:41 volumio-2 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 22 09:30:41 volumio-2 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 22 09:30:41 volumio-2 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 22 09:30:41 volumio-2 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 22 09:30:41 volumio-2 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 22 09:30:41 volumio-2 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 22 09:30:41 volumio-2 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 22 09:30:41 volumio-2 volumio[1059]: Express server listening on port 3000 Jun 22 09:30:41 volumio-2 volumio[1059]: [Metrics] WebUI: 23s 968.43ms Jun 22 09:30:41 volumio-2 volumio[1059]: info: CoreStateMachine::resetVolumioState Jun 22 09:30:41 volumio-2 volumio[1059]: info: CoreStateMachine::getcurrentVolume Jun 22 09:30:41 volumio-2 volumio[1059]: info: CoreCommandRouter::volumioRetrievevolume Jun 22 09:30:41 volumio-2 sudo[1272]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 22 09:30:41 volumio-2 sudo[1272]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 22 09:30:41 volumio-2 sudo[1272]: pam_unix(sudo:session): session closed for user root Jun 22 09:30:41 volumio-2 sudo[1273]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 22 09:30:41 volumio-2 sudo[1273]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 22 09:30:41 volumio-2 sudo[1273]: pam_unix(sudo:session): session closed for user root Jun 22 09:30:41 volumio-2 volumio[1059]: info: Volumio Network Manager: Network status updated: 2 Jun 22 09:30:42 volumio-2 volumio[1059]: 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 Jun 22 09:30:42 volumio-2 volumio[1059]: info: VolumeController:: Volume=98 Mute =false Jun 22 09:30:42 volumio-2 volumio[1059]: info: CoreStateMachine::pushState Jun 22 09:30:42 volumio-2 volumio[1059]: info: CorePlayQueue::getTrack 0 Jun 22 09:30:42 volumio-2 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 22 09:30:42 volumio-2 volumio[1059]: info: CoreCommandRouter::volumioPushState Jun 22 09:30:42 volumio-2 volumio[1059]: info: CoreStateMachine::updateTrackBlock Jun 22 09:30:42 volumio-2 volumio[1059]: info: CorePlayQueue::getTrackBlock Jun 22 09:30:42 volumio-2 volumio[1059]: info: CoreCommandRouter::volumioRetrievevolume Jun 22 09:30:42 volumio-2 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 22 09:30:42 volumio-2 volumio[1059]: info: Reloading queue from file Jun 22 09:30:42 volumio-2 volumio[1059]: 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/137.0.7151.89 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Jun 22 09:30:42 volumio-2 volumio[1059]: info: CoreStateMachine::setRepeat null single undefined Jun 22 09:30:42 volumio-2 volumio[1059]: info: CoreStateMachine::pushState Jun 22 09:30:42 volumio-2 volumio[1059]: info: CorePlayQueue::getTrack 0 Jun 22 09:30:42 volumio-2 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 22 09:30:42 volumio-2 volumio[1059]: info: CoreCommandRouter::volumioPushState Jun 22 09:30:42 volumio-2 volumio[1059]: info: CoreStateMachine::setRandom null Jun 22 09:30:42 volumio-2 volumio[1059]: info: CoreStateMachine::pushState Jun 22 09:30:42 volumio-2 volumio[1059]: info: CorePlayQueue::getTrack 0 Jun 22 09:30:42 volumio-2 volumio[1059]: info: CoreCommandRouter::volumioPushState Jun 22 09:30:42 volumio-2 volumio[1059]: info: Setting Device type: Raspberry PI Jun 22 09:30:42 volumio-2 volumio[1059]: verbose: New Socket.io Connection to 192.168.88.184 from 192.168.88.161 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Jun 22 09:30:42 volumio-2 volumio[1059]: info: Completed loading Core Plugins Jun 22 09:30:42 volumio-2 volumio[1059]: info: Preparing to generate the ALSA configuration file Jun 22 09:30:42 volumio-2 volumio[1059]: info: VolumeController:: Volume=98 Mute =false Jun 22 09:30:42 volumio-2 volumio[1059]: info: CoreStateMachine::pushState Jun 22 09:30:42 volumio-2 volumio[1059]: info: CorePlayQueue::getTrack 0 Jun 22 09:30:42 volumio-2 volumio[1059]: info: CoreCommandRouter::volumioPushState Jun 22 09:30:43 volumio-2 volumio[1059]: info: CoreCommandRouter::volumioGetState Jun 22 09:30:43 volumio-2 volumio[1059]: info: CorePlayQueue::getTrack 0 Jun 22 09:30:43 volumio-2 volumio[1059]: 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/137.0.7151.89 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Jun 22 09:30:43 volumio-2 sudo[1309]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 22 09:30:43 volumio-2 sudo[1309]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 22 09:30:43 volumio-2 volumio[1059]: info: Discovery: adding c76eb5fc-b147-44b7-9900-6a1ebf56379e Jun 22 09:30:43 volumio-2 volumio[1059]: info: Discovery: Found device Volumio 2 Jun 22 09:30:43 volumio-2 volumio[1059]: info: CoreCommandRouter::volumioGetState Jun 22 09:30:43 volumio-2 volumio[1059]: info: CorePlayQueue::getTrack 0 Jun 22 09:30:43 volumio-2 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jun 22 09:30:43 volumio-2 volumio[1059]: info: Asound.conf file unchanged, so no further update is needed Jun 22 09:30:43 volumio-2 systemd[1]: systemd-timedated.service: Deactivated successfully. Jun 22 09:30:43 volumio-2 volumio[1059]: info: Output device has changed, restarting MPD Jun 22 09:30:43 volumio-2 sudo[1309]: pam_unix(sudo:session): session closed for user root Jun 22 09:30:43 volumio-2 volumio[1059]: info: Output device has changed, restarting Shairport Sync Jun 22 09:30:43 volumio-2 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 22 09:30:43 volumio-2 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 22 09:30:43 volumio-2 sudo[1317]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 22 09:30:43 volumio-2 sudo[1317]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 22 09:30:43 volumio-2 sudo[1315]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 22 09:30:43 volumio-2 sudo[1315]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 22 09:30:43 volumio-2 sudo[1315]: pam_unix(sudo:session): session closed for user root Jun 22 09:30:43 volumio-2 volumio[1059]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 22 09:30:43 volumio-2 volumio[1059]: info: ___________ START PLUGINS ___________ Jun 22 09:30:43 volumio-2 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 22 09:30:43 volumio-2 systemd[1]: Starting mpd.service - Music Player Daemon... Jun 22 09:30:43 volumio-2 volumio[1059]: info: ControllerMpd::onStart: Initializing MPD Jun 22 09:30:43 volumio-2 volumio[1059]: info: Creating MPD Configuration file Jun 22 09:30:43 volumio-2 sudo[1337]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 22 09:30:43 volumio-2 sudo[1337]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 22 09:30:43 volumio-2 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 22 09:30:43 volumio-2 volumio[1059]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 22 09:30:43 volumio-2 volumio[1059]: info: [1750577443613] CoreMusicLibrary::Adding element Media Servers Jun 22 09:30:43 volumio-2 sudo[1337]: pam_unix(sudo:session): session closed for user root Jun 22 09:30:43 volumio-2 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 22 09:30:43 volumio-2 sudo[1340]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 22 09:30:43 volumio-2 sudo[1340]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 22 09:30:43 volumio-2 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 22 09:30:43 volumio-2 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 22 09:30:43 volumio-2 sudo[1335]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 22 09:30:43 volumio-2 sudo[1335]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 22 09:30:43 volumio-2 sudo[1342]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jun 22 09:30:43 volumio-2 sudo[1335]: pam_unix(sudo:session): session closed for user root Jun 22 09:30:43 volumio-2 volumio[1059]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 22 09:30:43 volumio-2 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 22 09:30:43 volumio-2 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 22 09:30:43 volumio-2 systemd[1]: mpd.service: Deactivated successfully. Jun 22 09:30:43 volumio-2 systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 22 09:30:43 volumio-2 systemd[1]: mpd.socket: Deactivated successfully. Jun 22 09:30:43 volumio-2 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 22 09:30:43 volumio-2 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 22 09:30:43 volumio-2 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 22 09:30:43 volumio-2 volumio[1059]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 22 09:30:43 volumio-2 systemd[1]: Starting mpd.service - Music Player Daemon... Jun 22 09:30:43 volumio-2 volumio[1059]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 22 09:30:43 volumio-2 volumio[1059]: info: [1750577443900] CoreMusicLibrary::Adding element Last_100 Jun 22 09:30:43 volumio-2 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 22 09:30:43 volumio-2 volumio[1059]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 22 09:30:43 volumio-2 volumio[1059]: info: [1750577443905] CoreMusicLibrary::Adding element Webradio Jun 22 09:30:43 volumio-2 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 22 09:30:43 volumio-2 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 22 09:30:44 volumio-2 volumio[1059]: info: Initializing BBC Radios Jun 22 09:30:44 volumio-2 sudo[1353]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 22 09:30:44 volumio-2 sudo[1353]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 22 09:30:44 volumio-2 sudo[1359]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jun 22 09:30:44 volumio-2 sudo[1353]: pam_unix(sudo:session): session closed for user root Jun 22 09:30:44 volumio-2 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 22 09:30:44 volumio-2 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 22 09:30:44 volumio-2 volumio[1059]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 22 09:30:44 volumio-2 volumio[1059]: info: [1750577444276] CoreMusicLibrary::Adding element YouTube2 Jun 22 09:30:44 volumio-2 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 22 09:30:44 volumio-2 volumio[1059]: Cannot find translation for source YouTube2 Jun 22 09:30:44 volumio-2 volumio[1059]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 22 09:30:44 volumio-2 volumio[1059]: info: [1750577444340] CoreMusicLibrary::Adding element YouTube Music Jun 22 09:30:44 volumio-2 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 22 09:30:44 volumio-2 volumio[1059]: Cannot find translation for source YouTube2 Jun 22 09:30:44 volumio-2 volumio[1059]: Cannot find translation for source YouTube Music Jun 22 09:30:44 volumio-2 volumio[1059]: info: Loading i18n strings for locale en Jun 22 09:30:44 volumio-2 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 22 09:30:44 volumio-2 volumio[1059]: info: Volumio Calling Home Jun 22 09:30:45 volumio-2 sudo[1376]: 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 Jun 22 09:30:45 volumio-2 sudo[1376]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 22 09:30:45 volumio-2 sudo[1376]: pam_unix(sudo:session): session closed for user root Jun 22 09:30:45 volumio-2 sudo[1378]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay rotary-encoder pin_a=6 pin_b=24 relative_axis=true steps-per-period=1 Jun 22 09:30:45 volumio-2 sudo[1378]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 22 09:30:45 volumio-2 sudo[1378]: pam_unix(sudo:session): session closed for user root Jun 22 09:30:45 volumio-2 kernel: rotary-encoder rotary@6: gray Jun 22 09:30:45 volumio-2 kernel: input: rotary@6 as /devices/platform/rotary@6/input/input0 Jun 22 09:30:46 volumio-2 (udev-worker)[1385]: event0: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 22 09:30:47 volumio-2 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 22 09:30:47 volumio-2 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 22 09:30:47 volumio-2 volumio[1059]: info: Discovery: Getting this device information Jun 22 09:30:47 volumio-2 volumio[1059]: info: CoreCommandRouter::volumioGetState Jun 22 09:30:47 volumio-2 volumio[1059]: info: CorePlayQueue::getTrack 0 Jun 22 09:30:47 volumio-2 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 22 09:30:47 volumio-2 volumio[1059]: info: Discovery: this is already registered, c76eb5fc-b147-44b7-9900-6a1ebf56379e Jun 22 09:30:47 volumio-2 volumio[1059]: info: Discovery: Found device Volumio 2 Jun 22 09:30:47 volumio-2 volumio[1059]: info: CoreCommandRouter::volumioGetState Jun 22 09:30:47 volumio-2 volumio[1059]: info: CorePlayQueue::getTrack 0 Jun 22 09:30:47 volumio-2 volumio[1059]: verbose: New Socket.io Connection to 192.168.88.184 from 192.168.88.161 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Jun 22 09:30:47 volumio-2 volumio[1059]: 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/137.0.7151.89 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Jun 22 09:30:47 volumio-2 volumio[1059]: 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/137.0.7151.89 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Jun 22 09:30:48 volumio-2 volumio[1059]: info: ir_controller: File permissions successfully set on /etc/lirc/*. Jun 22 09:30:48 volumio-2 volumio[1059]: info: MPD Permissions set Jun 22 09:30:48 volumio-2 volumio[1059]: info: MPD Permissions set Jun 22 09:30:48 volumio-2 volumio[1059]: info: Upmpdcli Daemon Started Jun 22 09:30:48 volumio-2 volumio[1059]: info: Listing playlists Jun 22 09:30:48 volumio-2 volumio[1059]: info: Listing playlists Jun 22 09:30:48 volumio-2 volumio[1059]: info: Volumio called home Jun 22 09:30:48 volumio-2 volumio[1059]: verbose: New Socket.io Connection to 192.168.88.184 from 192.168.88.161 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Jun 22 09:30:48 volumio-2 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 22 09:30:48 volumio-2 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 22 09:30:48 volumio-2 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 22 09:30:48 volumio-2 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 22 09:30:48 volumio-2 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 22 09:30:48 volumio-2 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 22 09:30:48 volumio-2 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 22 09:30:48 volumio-2 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 22 09:30:48 volumio-2 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 22 09:30:48 volumio-2 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 22 09:30:48 volumio-2 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 22 09:30:48 volumio-2 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 22 09:30:48 volumio-2 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 22 09:30:48 volumio-2 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 22 09:30:48 volumio-2 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 22 09:30:48 volumio-2 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 22 09:30:48 volumio-2 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 22 09:30:48 volumio-2 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 22 09:30:48 volumio-2 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 22 09:30:48 volumio-2 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 22 09:30:49 volumio-2 volumio[1059]: info: CoreCommandRouter::volumioGetState Jun 22 09:30:49 volumio-2 volumio[1059]: info: CorePlayQueue::getTrack 0 Jun 22 09:30:49 volumio-2 volumio[1059]: 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/137.0.7151.89 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Jun 22 09:30:49 volumio-2 volumio-remote-updater[533]: [2025-06-22 09:30:49] [connect] Successful connection Jun 22 09:30:49 volumio-2 sudo[1406]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay gpio-key gpio=5 active_low=1 gpio_pull=up keycode=20 Jun 22 09:30:49 volumio-2 sudo[1406]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 22 09:30:49 volumio-2 sudo[1406]: pam_unix(sudo:session): session closed for user root Jun 22 09:30:49 volumio-2 kernel: input: button@5 as /devices/platform/button@5/input/input1 Jun 22 09:30:49 volumio-2 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 22 09:30:49 volumio-2 volumio[1059]: info: Listing playlists Jun 22 09:30:49 volumio-2 (udev-worker)[1415]: event1: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 22 09:30:49 volumio-2 volumio[1059]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Jun 22 09:30:50 volumio-2 volumio[1059]: info: CoreCommandRouter::volumioGetState Jun 22 09:30:50 volumio-2 volumio[1059]: info: CorePlayQueue::getTrack 0 Jun 22 09:30:50 volumio-2 volumio[1059]: 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/137.0.7151.89 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Jun 22 09:30:50 volumio-2 volumio[1059]: info: Starting Shairport Sync Jun 22 09:30:50 volumio-2 volumio[1059]: info: Starting Shairport Sync Jun 22 09:30:50 volumio-2 volumio[1059]: info: Starting Shairport Sync Jun 22 09:30:50 volumio-2 sudo[1421]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 22 09:30:50 volumio-2 sudo[1421]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 22 09:30:50 volumio-2 sudo[1425]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 22 09:30:50 volumio-2 sudo[1425]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 22 09:30:50 volumio-2 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 22 09:30:50 volumio-2 volumio[1059]: info: Listing playlists Jun 22 09:30:50 volumio-2 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 22 09:30:50 volumio-2 systemd[1]: shairport-sync.service: Deactivated successfully. Jun 22 09:30:50 volumio-2 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 22 09:30:50 volumio-2 systemd[1]: shairport-sync.service: Consumed 1.488s CPU time. Jun 22 09:30:50 volumio-2 sudo[1423]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 22 09:30:50 volumio-2 sudo[1423]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 22 09:30:50 volumio-2 volumio[1059]: 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: 12 Jun 22 09:30:50 volumio-2 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 22 09:30:50 volumio-2 sudo[1421]: pam_unix(sudo:session): session closed for user root Jun 22 09:30:50 volumio-2 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jun 22 09:30:50 volumio-2 systemd[1]: shairport-sync.service: Deactivated successfully. Jun 22 09:30:50 volumio-2 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 22 09:30:50 volumio-2 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jun 22 09:30:50 volumio-2 sudo[1425]: pam_unix(sudo:session): session closed for user root Jun 22 09:30:50 volumio-2 sudo[1423]: pam_unix(sudo:session): session closed for user root Jun 22 09:30:50 volumio-2 volumio[1059]: info: ir_controller: LIRC correctly updated. Jun 22 09:30:50 volumio-2 volumio[1059]: info: ir_controller: HAT did not load /proc/device-tree/ir_receiver! Jun 22 09:30:50 volumio-2 volumio[1059]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Jun 22 09:30:51 volumio-2 volumio[1059]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Jun 22 09:30:51 volumio-2 volumio[1059]: info: ir_controller: Raspberry Pi revision code: a020d3 Jun 22 09:30:51 volumio-2 volumio[1059]: info: Shairport-Sync Started Jun 22 09:30:51 volumio-2 volumio[1059]: Error adding Membership: Error: addMembership EINVAL Jun 22 09:30:51 volumio-2 volumio[1059]: info: Shairport-Sync Started Jun 22 09:30:51 volumio-2 volumio[1059]: info: Shairport-Sync Started Jun 22 09:30:51 volumio-2 mpd[1363]: 2025-06-22T09:30:51 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 22 09:30:51 volumio-2 systemd[1]: Started mpd.service - Music Player Daemon. Jun 22 09:30:51 volumio-2 sudo[1452]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay rotary-encoder pin_a=23 pin_b=16 relative_axis=true steps-per-period=1 Jun 22 09:30:51 volumio-2 sudo[1317]: pam_unix(sudo:session): session closed for user root Jun 22 09:30:51 volumio-2 sudo[1452]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 22 09:30:51 volumio-2 kernel: rotary-encoder rotary@17: gray Jun 22 09:30:51 volumio-2 sudo[1340]: pam_unix(sudo:session): session closed for user root Jun 22 09:30:51 volumio-2 kernel: input: rotary@17 as /devices/platform/rotary@17/input/input2 Jun 22 09:30:51 volumio-2 sudo[1452]: pam_unix(sudo:session): session closed for user root Jun 22 09:30:52 volumio-2 sudo[1468]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -r -1 Jun 22 09:30:52 volumio-2 sudo[1468]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 22 09:30:52 volumio-2 (udev-worker)[1415]: event2: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 22 09:30:52 volumio-2 sudo[1468]: pam_unix(sudo:session): session closed for user root Jun 22 09:30:52 volumio-2 volumio-remote-updater[533]: [2025-06-22 09:30:52] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1750577449 101 Jun 22 09:30:52 volumio-2 sudo[1471]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart lircd.service Jun 22 09:30:52 volumio-2 sudo[1471]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 22 09:30:52 volumio-2 volumio[1059]: 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: 15 Jun 22 09:30:52 volumio-2 systemd[1]: Starting lircd-setup.service - lircd(8) initialization helper tool... Jun 22 09:30:52 volumio-2 volumio[1059]: error: MPD error: The expression evaluated to a falsy value: Jun 22 09:30:52 volumio-2 volumio[1059]: assert.ok(self.idling) Jun 22 09:30:52 volumio-2 volumio[1059]: error: The expression evaluated to a falsy value: Jun 22 09:30:52 volumio-2 volumio[1059]: assert.ok(self.idling) Jun 22 09:30:52 volumio-2 lircd-setup[1485]: /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. Jun 22 09:30:52 volumio-2 lircd-setup[1485]: parser = configparser.SafeConfigParser() Jun 22 09:30:52 volumio-2 systemd[1]: lircd-setup.service: Deactivated successfully. Jun 22 09:30:52 volumio-2 volumio[1059]: info: MPD running with PID1363 Jun 22 09:30:52 volumio-2 volumio[1059]: ,establishing connection Jun 22 09:30:52 volumio-2 systemd[1]: Finished lircd-setup.service - lircd(8) initialization helper tool. Jun 22 09:30:52 volumio-2 volumio[1059]: error: updateQueue error: null Jun 22 09:30:52 volumio-2 systemd[1]: Starting lircd.service - Flexible IR remote input/output application support... Jun 22 09:30:52 volumio-2 volumio[1059]: error: updateQueue error: null Jun 22 09:30:53 volumio-2 lircd-0.10.1[1491]: Info: lircd: Opening log, level: Info Jun 22 09:30:53 volumio-2 lircd-0.10.1[1491]: Notice: Version: lircd 0.10.1 Jun 22 09:30:53 volumio-2 lircd-0.10.1[1491]: Notice: System info: Linux volumio-2 6.12.27-v7+ #1876 SMP Tue May 6 15:08:23 BST 2025 armv7l GNU/Linux Jun 22 09:30:53 volumio-2 sudo[1493]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay gpio-ir gpio_pin=25 gpio_pull=up Jun 22 09:30:53 volumio-2 sudo[1493]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 22 09:30:53 volumio-2 volumio[1059]: info: CoreCommandRouter::volumioGetState Jun 22 09:30:53 volumio-2 volumio[1059]: info: CorePlayQueue::getTrack 0 Jun 22 09:30:53 volumio-2 sudo[1493]: pam_unix(sudo:session): session closed for user root Jun 22 09:30:53 volumio-2 volumio[1059]: info: CoreCommandRouter::volumioGetState Jun 22 09:30:53 volumio-2 volumio[1059]: info: CorePlayQueue::getTrack 0 Jun 22 09:30:53 volumio-2 kernel: Registered IR keymap rc-rc6-mce Jun 22 09:30:53 volumio-2 kernel: IR RC6 protocol handler initialized Jun 22 09:30:53 volumio-2 volumio[1059]: info: ir_controller: Overlay gpio-ir gpio_pin=25 gpio_pull=up loaded. Jun 22 09:30:53 volumio-2 kernel: rc rc0: gpio_ir_recv as /devices/platform/ir-receiver@19/rc/rc0 Jun 22 09:30:53 volumio-2 kernel: rc rc0: lirc_dev: driver gpio_ir_recv registered at minor = 0, raw IR receiver, no transmitter Jun 22 09:30:53 volumio-2 kernel: input: gpio_ir_recv as /devices/platform/ir-receiver@19/rc/rc0/input3 Jun 22 09:30:53 volumio-2 volumio[1059]: info: CoreCommandRouter::volumioGetState Jun 22 09:30:53 volumio-2 volumio[1059]: info: CorePlayQueue::getTrack 0 Jun 22 09:30:53 volumio-2 sudo[1503]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart lircd.service Jun 22 09:30:53 volumio-2 sudo[1508]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Jun 22 09:30:53 volumio-2 sudo[1503]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 22 09:30:53 volumio-2 sudo[1508]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 22 09:30:53 volumio-2 sudo[1508]: pam_unix(sudo:session): session closed for user root Jun 22 09:30:53 volumio-2 systemd[1]: lircd.service: Deactivated successfully. Jun 22 09:30:53 volumio-2 systemd[1]: Stopped lircd.service - Flexible IR remote input/output application support. Jun 22 09:30:53 volumio-2 systemd[1]: Starting lircd-setup.service - lircd(8) initialization helper tool... Jun 22 09:30:53 volumio-2 sudo[1515]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Jun 22 09:30:53 volumio-2 sudo[1515]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 22 09:30:53 volumio-2 (udev-worker)[1505]: event3: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 22 09:30:53 volumio-2 systemd-logind[527]: Watching system buttons on /dev/input/event3 (gpio_ir_recv) Jun 22 09:30:53 volumio-2 sudo[1515]: pam_unix(sudo:session): session closed for user root Jun 22 09:30:53 volumio-2 lircd-setup[1512]: /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. Jun 22 09:30:53 volumio-2 lircd-setup[1512]: parser = configparser.SafeConfigParser() Jun 22 09:30:53 volumio-2 systemd[1]: lircd-setup.service: Deactivated successfully. Jun 22 09:30:53 volumio-2 systemd[1]: Finished lircd-setup.service - lircd(8) initialization helper tool. Jun 22 09:30:53 volumio-2 sudo[1525]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Jun 22 09:30:53 volumio-2 sudo[1525]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 22 09:30:53 volumio-2 sudo[1525]: pam_unix(sudo:session): session closed for user root Jun 22 09:30:53 volumio-2 systemd[1]: Starting lircd.service - Flexible IR remote input/output application support... Jun 22 09:30:53 volumio-2 lircd-0.10.1[1526]: Info: lircd: Opening log, level: Info Jun 22 09:30:53 volumio-2 lircd-0.10.1[1526]: Notice: Version: lircd 0.10.1 Jun 22 09:30:53 volumio-2 lircd-0.10.1[1526]: Notice: System info: Linux volumio-2 6.12.27-v7+ #1876 SMP Tue May 6 15:08:23 BST 2025 armv7l GNU/Linux Jun 22 09:30:53 volumio-2 sudo[1532]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Jun 22 09:30:53 volumio-2 sudo[1532]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 22 09:30:53 volumio-2 sudo[1532]: pam_unix(sudo:session): session closed for user root Jun 22 09:30:53 volumio-2 lircd-0.10.1[1526]: Info: Initial device: /dev/lirc0 Jun 22 09:30:54 volumio-2 lircd[1526]: lircd-0.10.1[1526]: Info: lircd: Opening log, level: Info Jun 22 09:30:54 volumio-2 lircd[1526]: lircd-0.10.1[1526]: Notice: Using systemd fd Jun 22 09:30:54 volumio-2 lircd[1526]: lircd-0.10.1[1526]: Warning: Running as root Jun 22 09:30:54 volumio-2 lircd[1526]: lircd-0.10.1[1526]: Info: Using remote: rc2. Jun 22 09:30:53 volumio-2 lircd-0.10.1[1526]: Notice: 'lirc' written to protocols file /sys/class/rc/rc0/protocols Jun 22 09:30:54 volumio-2 systemd[1]: Started lircd.service - Flexible IR remote input/output application support. Jun 22 09:30:54 volumio-2 lircd[1526]: lircd-0.10.1[1526]: Notice: lircd(default) ready, using /var/run/lirc/lircd Jun 22 09:30:53 volumio-2 lircd-0.10.1[1526]: Info: Initial device: /dev/lirc0 Jun 22 09:30:53 volumio-2 lircd-0.10.1[1526]: Info: [lirc] protocol is enabled Jun 22 09:30:53 volumio-2 lircd-0.10.1[1526]: Notice: Options: driver: default Jun 22 09:30:53 volumio-2 lircd-0.10.1[1526]: Notice: Options: output: /var/run/lirc/lircd Jun 22 09:30:53 volumio-2 lircd-0.10.1[1526]: Notice: Options: nodaemon: 1 Jun 22 09:30:53 volumio-2 lircd-0.10.1[1526]: Notice: Options: plugindir: /usr/lib/arm-linux-gnueabihf/lirc/plugins Jun 22 09:30:53 volumio-2 lircd-0.10.1[1526]: Notice: Options: logfile: syslog Jun 22 09:30:53 volumio-2 lircd-0.10.1[1526]: Notice: Options: immediate-init: 0 Jun 22 09:30:53 volumio-2 lircd-0.10.1[1526]: Notice: Options: permission: 666 Jun 22 09:30:53 volumio-2 lircd-0.10.1[1526]: Notice: Options: driver-options: Jun 22 09:30:53 volumio-2 lircd-0.10.1[1526]: Notice: Options: pidfile: /var/run/lirc/lircd.pid Jun 22 09:30:53 volumio-2 lircd-0.10.1[1526]: Notice: Options: listen: 0 Jun 22 09:30:53 volumio-2 lircd-0.10.1[1526]: Notice: Options: connect: (null) Jun 22 09:30:53 volumio-2 lircd-0.10.1[1526]: Notice: Options: userelease: 0 Jun 22 09:30:53 volumio-2 lircd-0.10.1[1526]: Notice: Options: effective_user: (null) Jun 22 09:30:53 volumio-2 lircd-0.10.1[1526]: Notice: Options: release_suffix: _EVUP Jun 22 09:30:53 volumio-2 lircd-0.10.1[1526]: Notice: Options: allow_simulate: 0 Jun 22 09:30:53 volumio-2 lircd-0.10.1[1526]: Notice: Options: repeat_max: 600 Jun 22 09:30:53 volumio-2 lircd-0.10.1[1526]: Notice: Options: configfile: /etc/lirc/lircd.conf Jun 22 09:30:53 volumio-2 lircd-0.10.1[1526]: Notice: Options: dynamic_codes: (null) Jun 22 09:30:53 volumio-2 lircd-0.10.1[1526]: Notice: Current driver: default Jun 22 09:30:53 volumio-2 lircd-0.10.1[1526]: Notice: Driver API version: 3 Jun 22 09:30:53 volumio-2 lircd-0.10.1[1526]: Notice: Driver version: 0.10.0 Jun 22 09:30:53 volumio-2 lircd-0.10.1[1526]: Notice: Driver info: See file:///usr/share/doc/lirc/plugindocs/default.html Jun 22 09:30:53 volumio-2 lircd-0.10.1[1526]: Info: lircd: Opening log, level: Info Jun 22 09:30:54 volumio-2 lircd-0.10.1[1526]: Notice: Using systemd fd Jun 22 09:30:54 volumio-2 lircd-0.10.1[1526]: Warning: Running as root Jun 22 09:30:54 volumio-2 lircd-0.10.1[1526]: Info: Using remote: rc2. Jun 22 09:30:54 volumio-2 lircd-0.10.1[1526]: Notice: lircd(default) ready, using /var/run/lirc/lircd Jun 22 09:30:54 volumio-2 sudo[1471]: pam_unix(sudo:session): session closed for user root Jun 22 09:30:54 volumio-2 sudo[1503]: pam_unix(sudo:session): session closed for user root Jun 22 09:30:54 volumio-2 volumio[1059]: info: Completed starting Core Plugins Jun 22 09:30:54 volumio-2 volumio[1059]: info: ------------------------------------------- Jun 22 09:30:54 volumio-2 volumio[1059]: info: ----- MyVolumio plugins startup ---- Jun 22 09:30:54 volumio-2 volumio[1059]: info: ------------------------------------------- Jun 22 09:30:54 volumio-2 volumio[1059]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 22 09:30:54 volumio-2 volumio[1059]: info: ir_controller: systemctl restart lircd.service succeeded. Jun 22 09:30:54 volumio-2 volumio[1059]: info: ir_controller: systemctl restart lircd.service succeeded. Jun 22 09:30:54 volumio-2 sudo[1535]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart irexec.service Jun 22 09:30:54 volumio-2 sudo[1535]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 22 09:30:54 volumio-2 volumio[1059]: info: CoreCommandRouter::volumioGetState Jun 22 09:30:54 volumio-2 volumio[1059]: info: CorePlayQueue::getTrack 0 Jun 22 09:30:54 volumio-2 sudo[1537]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart irexec.service Jun 22 09:30:54 volumio-2 sudo[1537]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 22 09:30:54 volumio-2 systemd[1]: Started irexec.service - Handle events from IR remotes decoded by lircd(8). Jun 22 09:30:54 volumio-2 lircd[1526]: lircd-0.10.1[1526]: Notice: accepted new client on /var/run/lirc/lircd Jun 22 09:30:54 volumio-2 lircd-0.10.1[1526]: Notice: accepted new client on /var/run/lirc/lircd Jun 22 09:30:54 volumio-2 lircd[1526]: lircd-0.10.1[1526]: Info: [lirc] protocol is enabled Jun 22 09:30:54 volumio-2 lircd-0.10.1[1526]: Info: [lirc] protocol is enabled Jun 22 09:30:54 volumio-2 sudo[1535]: pam_unix(sudo:session): session closed for user root Jun 22 09:30:54 volumio-2 volumio[1059]: info: ir_controller: systemctl restart irexec.service succeeded. Jun 22 09:30:54 volumio-2 lircd[1526]: lircd-0.10.1[1526]: Info: removed client Jun 22 09:30:54 volumio-2 lircd-0.10.1[1526]: Info: removed client Jun 22 09:30:54 volumio-2 systemd[1]: Stopping irexec.service - Handle events from IR remotes decoded by lircd(8)... Jun 22 09:30:54 volumio-2 systemd[1]: irexec.service: Deactivated successfully. Jun 22 09:30:54 volumio-2 systemd[1]: Stopped irexec.service - Handle events from IR remotes decoded by lircd(8). Jun 22 09:30:54 volumio-2 systemd[1]: Started irexec.service - Handle events from IR remotes decoded by lircd(8). Jun 22 09:30:54 volumio-2 lircd[1526]: lircd-0.10.1[1526]: Notice: accepted new client on /var/run/lirc/lircd Jun 22 09:30:54 volumio-2 lircd-0.10.1[1526]: Notice: accepted new client on /var/run/lirc/lircd Jun 22 09:30:54 volumio-2 lircd[1526]: lircd-0.10.1[1526]: Info: [lirc] protocol is enabled Jun 22 09:30:54 volumio-2 lircd-0.10.1[1526]: Info: [lirc] protocol is enabled Jun 22 09:30:54 volumio-2 sudo[1537]: pam_unix(sudo:session): session closed for user root Jun 22 09:30:54 volumio-2 volumio[1059]: info: ir_controller: systemctl restart irexec.service succeeded. Jun 22 09:30:57 volumio-2 volumio[1059]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jun 22 09:31:00 volumio-2 volumio[1059]: info: CoreCommandRouter::volumioGetQueue Jun 22 09:31:00 volumio-2 volumio[1059]: info: CoreStateMachine::getQueue Jun 22 09:31:00 volumio-2 volumio[1059]: info: CorePlayQueue::getQueue Jun 22 09:31:02 volumio-2 volumio[1059]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jun 22 09:31:02 volumio-2 volumio[1059]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jun 22 09:31:02 volumio-2 volumio[1059]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jun 22 09:31:02 volumio-2 volumio[1059]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jun 22 09:31:02 volumio-2 volumio[1059]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jun 22 09:31:02 volumio-2 volumio[1059]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jun 22 09:31:02 volumio-2 volumio[1059]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jun 22 09:31:02 volumio-2 volumio[1059]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jun 22 09:31:02 volumio-2 volumio[1059]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jun 22 09:31:02 volumio-2 volumio[1059]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jun 22 09:31:02 volumio-2 volumio[1059]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jun 22 09:31:02 volumio-2 volumio[1059]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jun 22 09:31:02 volumio-2 volumio[1059]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jun 22 09:31:02 volumio-2 volumio[1059]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jun 22 09:31:02 volumio-2 volumio[1059]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jun 22 09:31:02 volumio-2 volumio[1059]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jun 22 09:31:02 volumio-2 volumio[1059]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jun 22 09:31:02 volumio-2 volumio[1059]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jun 22 09:31:02 volumio-2 volumio[1059]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jun 22 09:31:02 volumio-2 volumio[1059]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jun 22 09:31:02 volumio-2 volumio[1059]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jun 22 09:31:02 volumio-2 volumio[1059]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jun 22 09:31:02 volumio-2 volumio[1059]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jun 22 09:31:02 volumio-2 volumio[1059]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jun 22 09:31:02 volumio-2 volumio[1059]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jun 22 09:31:02 volumio-2 volumio[1059]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jun 22 09:31:02 volumio-2 volumio[1059]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jun 22 09:31:02 volumio-2 volumio[1059]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jun 22 09:31:02 volumio-2 volumio[1059]: info: Adding plugin bluetooth to MyMusic Plugins Jun 22 09:31:03 volumio-2 volumio[1059]: info: Adding plugin multiroom to MyMusic Plugins Jun 22 09:31:03 volumio-2 volumio[1059]: info: Adding plugin metavolumio to MyMusic Plugins Jun 22 09:31:03 volumio-2 volumio[1059]: info: Adding plugin cd_controller to MyMusic Plugins Jun 22 09:31:03 volumio-2 volumio[1059]: info: Adding plugin qobuzconnect to MyMusic Plugins Jun 22 09:31:03 volumio-2 volumio[1059]: info: Adding plugin smart_inputs to MyMusic Plugins Jun 22 09:31:03 volumio-2 volumio[1059]: info: Adding plugin tidalconnect to MyMusic Plugins Jun 22 09:31:03 volumio-2 volumio[1059]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jun 22 09:31:07 volumio-2 volumio[1059]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jun 22 09:31:07 volumio-2 volumio[1059]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jun 22 09:31:07 volumio-2 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 22 09:31:07 volumio-2 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 22 09:31:07 volumio-2 volumio[1059]: info: Starting MyVolumio Remote Streaming Endpoints Jun 22 09:31:07 volumio-2 volumio[1059]: info: MyVolumio login type: Token Jun 22 09:31:07 volumio-2 volumio[1059]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jun 22 09:31:07 volumio-2 volumio[1059]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jun 22 09:31:11 volumio-2 volumio[1059]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jun 22 09:31:11 volumio-2 volumio[1059]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jun 22 09:31:11 volumio-2 volumio[1059]: info: Streaming services startup Jun 22 09:31:11 volumio-2 volumio[1059]: info: Starting Streaming Daemon Jun 22 09:31:11 volumio-2 volumio[1059]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jun 22 09:31:11 volumio-2 sudo[1558]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 22 09:31:11 volumio-2 sudo[1558]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 22 09:31:11 volumio-2 volumio[1059]: info: CoreCommandRouter::volumioPlay Jun 22 09:31:11 volumio-2 volumio[1059]: info: CoreStateMachine::play index 9 Jun 22 09:31:11 volumio-2 sudo[1558]: pam_unix(sudo:session): session closed for user root Jun 22 09:31:11 volumio-2 volumio[1059]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 22 09:31:11 volumio-2 volumio[1059]: info: CoreStateMachine::stop Jun 22 09:31:11 volumio-2 volumio[1059]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 22 09:31:11 volumio-2 volumio[1059]: info: CoreStateMachine::play index undefined Jun 22 09:31:11 volumio-2 volumio[1059]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 22 09:31:11 volumio-2 volumio[1059]: info: CorePlayQueue::getTrack 9 Jun 22 09:31:11 volumio-2 volumio[1059]: info: CoreStateMachine::startPlaybackTimer Jun 22 09:31:11 volumio-2 volumio[1059]: info: CorePlayQueue::getTrack 9 Jun 22 09:31:11 volumio-2 volumio[1059]: info: [ytmusic-play] clearAddPlayTrack: ytmusic/video@explodeTrackData:o=%7B%22type%22%3A%22video%22%2C%22title%22%3A%2280's%20Music%20Greatest%20Hits%20%20-%20Back%20To%20The%201980s%20-%20Classic%20Music%2080S%20Hits%22%2C%22artist%22%3A%2280s%20Music%20Hits%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2Fm38qWqTscOE%2Fsddefault.jpg%3Fsqp%3D-oaymwEWCJADEOEBIAQqCghqEJQEGHgg6AJIWg%26rs%3DAMzJL3lxi-KG4T9yBmRyD-oVUKZl851EkQ%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22m38qWqTscOE%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_UGC%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22m38qWqTscOE%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_UGC%22%7D%7D%7D Jun 22 09:31:11 volumio-2 volumio[1059]: info: [ytmusic] InnertubeLoader: creating Innertube instance... Jun 22 09:31:12 volumio-2 volumio[1059]: error: Cannot start Volumio Streaming Daemon Jun 22 09:31:12 volumio-2 volumio[1059]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 22 09:31:12 volumio-2 volumio[1059]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 22 09:31:12 volumio-2 volumio[1059]: info: CoreCommandRouter::volumioGetState Jun 22 09:31:12 volumio-2 volumio[1059]: info: CorePlayQueue::getTrack 9 Jun 22 09:31:13 volumio-2 volumio[1059]: info: Listing playlists Jun 22 09:31:13 volumio-2 volumio[1059]: info: Listing playlists Jun 22 09:31:14 volumio-2 volumio[1059]: info: CoreCommandRouter::volumioGetState Jun 22 09:31:14 volumio-2 volumio[1059]: info: CorePlayQueue::getTrack 9 Jun 22 09:31:14 volumio-2 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 22 09:31:14 volumio-2 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 22 09:31:14 volumio-2 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 22 09:31:14 volumio-2 volumio[1059]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jun 22 09:31:15 volumio-2 volumio-remote-updater[533]: Test mode enabled Jun 22 09:31:15 volumio-2 volumio-remote-updater[533]: Alpha mode disabled Jun 22 09:31:15 volumio-2 volumio-remote-updater[533]: Alpha legacy test mode disabled Jun 22 09:31:15 volumio-2 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jun 22 09:31:15 volumio-2 volumio[1059]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jun 22 09:31:15 volumio-2 volumio[1059]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jun 22 09:31:16 volumio-2 systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jun 22 09:31:16 volumio-2 dbus-daemon[521]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.22' (uid=0 pid=1583 comm="timedatectl show --property=NTPSynchronized --valu") Jun 22 09:31:16 volumio-2 systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jun 22 09:31:16 volumio-2 volumio[1059]: info: MyVolumio token set successfully Jun 22 09:31:16 volumio-2 volumio[1059]: info: MYVOLUMIO: Adding device Jun 22 09:31:16 volumio-2 volumio[1059]: info: MYVOLUMIO: Evaluating Server Jun 22 09:31:16 volumio-2 dbus-daemon[521]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 22 09:31:16 volumio-2 systemd[1]: Started systemd-timedated.service - Time & Date Service. Jun 22 09:31:16 volumio-2 setdatetime-helper.sh[1582]: Time is not synchronized. Attempting to sync... Jun 22 09:31:17 volumio-2 sudo[1596]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Sun, 22 Jun 2025 07:31:17 GMT#015' Jun 22 09:31:17 volumio-2 sudo[1596]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 22 09:31:17 volumio-2 setdatetime-helper.sh[1598]: Sun Jun 22 09:31:17 CEST 2025 Jun 22 09:31:17 volumio-2 systemd-journald[319]: Time jumped backwards, rotating. Jun 22 09:31:17 volumio-2 sudo[1596]: pam_unix(sudo:session): session closed for user root Jun 22 09:31:17 volumio-2 setdatetime-helper.sh[1582]: Time synchronized successfully. Jun 22 09:31:17 volumio-2 systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jun 22 09:31:17 volumio-2 systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jun 22 09:31:17 volumio-2 volumio[1059]: info: MyVolumio status changed Jun 22 09:31:17 volumio-2 volumio[1059]: info: Streaming services startup Jun 22 09:31:17 volumio-2 volumio[1059]: info: Starting Streaming Daemon Jun 22 09:31:17 volumio-2 volumio[1059]: info: Removing browser output: myVolumio user plan is not superstar Jun 22 09:31:17 volumio-2 volumio[1059]: info: Removing audio output: Jun 22 09:31:17 volumio-2 volumio[1059]: info: Stoppping Tunnel 1 Jun 22 09:31:17 volumio-2 sudo[1614]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jun 22 09:31:17 volumio-2 sudo[1614]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 22 09:31:18 volumio-2 volumio[1059]: error: Failed to ping endpoint eu2.myvolumio.org : unknown error Jun 22 09:31:18 volumio-2 volumio[1059]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 22 09:31:18 volumio-2 sudo[1612]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 22 09:31:18 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. Jun 22 09:31:18 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. Jun 22 09:31:18 volumio-2 volumio[1059]: Error: Unable to resolve or reject the same promise twice Jun 22 09:31:18 volumio-2 volumio[1059]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Jun 22 09:31:18 volumio-2 volumio[1059]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32371) Jun 22 09:31:18 volumio-2 volumio[1059]: at Socket.emit (node:events:514:28) Jun 22 09:31:18 volumio-2 volumio[1059]: at addChunk (node:internal/streams/readable:343:12) Jun 22 09:31:18 volumio-2 volumio[1059]: at readableAddChunk (node:internal/streams/readable:316:9) Jun 22 09:31:18 volumio-2 volumio[1059]: at Readable.push (node:internal/streams/readable:253:10) Jun 22 09:31:18 volumio-2 volumio[1059]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Jun 22 09:31:18 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. Jun 22 09:31:18 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. Jun 22 09:31:18 volumio-2 volumio[1059]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 22 09:31:18 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. Jun 22 09:31:18 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. Jun 22 09:31:18 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. Jun 22 09:31:18 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. Jun 22 09:31:18 volumio-2 sudo[1614]: pam_unix(sudo:session): session closed for user root Jun 22 09:31:18 volumio-2 sudo[1612]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 22 09:31:18 volumio-2 sudo[1612]: pam_unix(sudo:session): session closed for user root Jun 22 09:31:22 volumio-2 upmpdcli[1643]: writing RSA key Jun 22 09:31:22 volumio-2 sudo[1649]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-06-22 09:30' Jun 22 09:31:22 volumio-2 sudo[1649]: 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="cee9bb5b0ee50e4898478097a64b5c6c77d5a212" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="b0310d60a07948a2a8a2e8db322034758c86aad6" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Jun 21 15:01:23 UTC 2025" VOLUMIO_VERSION="4.013" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="6df2d5f0b86e6f2c0cd4dc68c66de48c"