Jul 03 16:33:19 volumio-2 ntpd[836]: CLOCK: time stepped by 76391.753258 Jul 03 16:33:19 volumio-2 ntpd[836]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Jul 03 16:33:19 volumio-2 volumio[1030]: info: Loading plugin "network"... Jul 03 16:33:19 volumio-2 volumio[1030]: info: Refreshing Cached IP Addresses Jul 03 16:33:19 volumio-2 sudo[1111]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 03 16:33:19 volumio-2 sudo[1111]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 03 16:33:19 volumio-2 sudo[1111]: pam_unix(sudo:session): session closed for user root Jul 03 16:33:19 volumio-2 systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service... Jul 03 16:33:19 volumio-2 sudo[1113]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 03 16:33:19 volumio-2 volumio[1030]: info: Loading plugin "services"... Jul 03 16:33:19 volumio-2 systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats. Jul 03 16:33:19 volumio-2 sudo[1113]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 03 16:33:19 volumio-2 systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully. Jul 03 16:33:19 volumio-2 volumio[1030]: info: Loading plugin "alsa_controller"... Jul 03 16:33:19 volumio-2 sudo[1113]: pam_unix(sudo:session): session closed for user root Jul 03 16:33:19 volumio-2 sudo[1119]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 03 16:33:19 volumio-2 sudo[1119]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 03 16:33:19 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 03 16:33:19 volumio-2 volumio[1030]: info: Loading plugin "wizard"... Jul 03 16:33:19 volumio-2 volumio[1030]: info: Loading plugin "networkfs"... Jul 03 16:33:19 volumio-2 systemd[1]: dpkg-db-backup.service: Deactivated successfully. Jul 03 16:33:19 volumio-2 systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service. Jul 03 16:33:19 volumio-2 volumio[1030]: info: Starting Udev Watcher for removable devices Jul 03 16:33:19 volumio-2 sudo[1170]: 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 Jul 03 16:33:19 volumio-2 sudo[1170]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 03 16:33:19 volumio-2 volumio[1030]: info: Ignoring mount for partition: boot Jul 03 16:33:19 volumio-2 volumio[1030]: info: Ignoring mount for partition: volumio Jul 03 16:33:19 volumio-2 volumio[1030]: info: Ignoring mount for partition: volumio_data Jul 03 16:33:19 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 03 16:33:19 volumio-2 volumio[1030]: info: Loading plugin "volumio_command_line_client"... Jul 03 16:33:19 volumio-2 volumio[1030]: info: Loading plugin "upnp"... Jul 03 16:33:19 volumio-2 volumio[1030]: info: [1751553199899] Starting Upmpd Daemon Jul 03 16:33:19 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 03 16:33:19 volumio-2 volumio[1030]: info: Loading plugin "my_music"... Jul 03 16:33:19 volumio-2 volumio[1030]: info: Loading plugin "mpd"... Jul 03 16:33:19 volumio-2 kernel: netfs: FS-Cache loaded Jul 03 16:33:20 volumio-2 kernel: Key type cifs.spnego registered Jul 03 16:33:20 volumio-2 kernel: Key type cifs.idmap registered Jul 03 16:33:20 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. Jul 03 16:33:20 volumio-2 kernel: CIFS: Attempting to mount //192.168.88.148/Public/music Jul 03 16:33:20 volumio-2 volumio[1030]: info: Loading plugin "upnp_browser"... Jul 03 16:33:21 volumio-2 sudo[1170]: pam_unix(sudo:session): session closed for user root Jul 03 16:33:22 volumio-2 volumio[1030]: info: Loading plugin "alarm-clock"... Jul 03 16:33:22 volumio-2 volumio[1030]: info: Loading plugin "airplay_emulation"... Jul 03 16:33:22 volumio-2 volumio[1030]: info: Starting Shairport Sync Jul 03 16:33:22 volumio-2 volumio[1030]: info: Loading plugin "last_100"... Jul 03 16:33:22 volumio-2 volumio[1030]: info: Loading plugin "webradio"... Jul 03 16:33:22 volumio-2 systemd[1]: systemd-fsckd.service: Deactivated successfully. Jul 03 16:33:22 volumio-2 volumio[1030]: info: Loading plugin "i2s_dacs"... Jul 03 16:33:22 volumio-2 volumio[1030]: info: Loading plugin "volumiodiscovery"... Jul 03 16:33:22 volumio-2 volumio[1030]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 03 16:33:22 volumio-2 volumio[1030]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 03 16:33:22 volumio-2 volumio[1030]: *** WARNING *** For more information see Jul 03 16:33:22 volumio-2 volumio[1030]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 03 16:33:22 volumio-2 volumio[1030]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 03 16:33:22 volumio-2 volumio[1030]: *** WARNING *** For more information see Jul 03 16:33:22 volumio-2 node[1030]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 03 16:33:22 volumio-2 node[1030]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 03 16:33:22 volumio-2 node[1030]: *** WARNING *** For more information see Jul 03 16:33:22 volumio-2 node[1030]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 03 16:33:22 volumio-2 node[1030]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 03 16:33:22 volumio-2 node[1030]: *** WARNING *** For more information see Jul 03 16:33:22 volumio-2 volumio[1030]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 03 16:33:22 volumio-2 volumio[1030]: info: Discovery: Started advertising with name: Volumio 2 Jul 03 16:33:22 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 03 16:33:22 volumio-2 volumio[1030]: info: Loading plugin "youtube2"... Jul 03 16:33:23 volumio-2 sudo[1119]: pam_unix(sudo:session): session closed for user root Jul 03 16:33:23 volumio-2 dhcpcd[510]: timed out Jul 03 16:33:23 volumio-2 sh[501]: timed out Jul 03 16:33:23 volumio-2 dhcpcd[510]: dhcpcd exited Jul 03 16:33:23 volumio-2 sh[473]: ifup: failed to bring up eth0 Jul 03 16:33:23 volumio-2 systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Jul 03 16:33:23 volumio-2 systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Jul 03 16:33:26 volumio-2 systemd[1]: systemd-hostnamed.service: Deactivated successfully. Jul 03 16:33:26 volumio-2 volumio[1030]: info: Loading plugin "ytmusic"... Jul 03 16:33:27 volumio-2 nmbd[869]: [2025/07/03 16:33:27.481551, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jul 03 16:33:27 volumio-2 nmbd[869]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.88.184 for name WORKGROUP<1d>. Jul 03 16:33:27 volumio-2 nmbd[869]: This response was from IP 192.168.88.30, reporting an IP address of 192.168.88.30. Jul 03 16:33:30 volumio-2 volumio[1030]: info: Loading plugin "outputs"... Jul 03 16:33:30 volumio-2 volumio[1030]: info: Loading plugin "albumart"... Jul 03 16:33:30 volumio-2 volumio[1030]: info: Plugin example_plugin is not enabled Jul 03 16:33:30 volumio-2 volumio[1030]: info: Loading plugin "inputs"... Jul 03 16:33:30 volumio-2 volumio[1030]: info: Loading plugin "updater_comm"... Jul 03 16:33:31 volumio-2 volumio[1030]: info: Plugin mpdemulation is not enabled Jul 03 16:33:31 volumio-2 volumio[1030]: info: Loading plugin "rest_api"... Jul 03 16:33:31 volumio-2 volumio[1030]: info: Loading plugin "websocket"... Jul 03 16:33:31 volumio-2 volumio[1030]: info: Starting Socket.io Server version 1.7.4 Jul 03 16:33:31 volumio-2 volumio[1030]: info: Loading plugin "backup_restore"... Jul 03 16:33:31 volumio-2 volumio[1230]: Forking 3 albumart workers Jul 03 16:33:32 volumio-2 volumio[1030]: info: Applying required configuration parameters for plugin backup_restore Jul 03 16:33:32 volumio-2 volumio[1030]: info: Loading plugin "gpio_control"... Jul 03 16:33:32 volumio-2 volumio-remote-updater[538]: [2025-07-03 16:33:32] [connect] Successful connection Jul 03 16:33:33 volumio-2 volumio[1030]: gpiox_arm32 V2.2.1 Jul 03 16:33:33 volumio-2 volumio[1242]: Starting albumart workers Jul 03 16:33:35 volumio-2 volumio[1030]: info: Applying required configuration parameters for plugin gpio_control Jul 03 16:33:35 volumio-2 volumio[1030]: info: Loading plugin "ir_controller"... Jul 03 16:33:35 volumio-2 volumio[1030]: info: Applying required configuration parameters for plugin ir_controller Jul 03 16:33:36 volumio-2 volumio[1030]: info: Loading plugin "rotaryencoder2"... Jul 03 16:33:36 volumio-2 volumio[1241]: Starting albumart workers Jul 03 16:33:36 volumio-2 volumio[1240]: Starting albumart workers Jul 03 16:33:38 volumio-2 volumio[1030]: info: Loading i18n strings for locale en Jul 03 16:33:38 volumio-2 volumio[1030]: Updating browse sources language Jul 03 16:33:38 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 03 16:33:39 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 03 16:33:39 volumio-2 volumio[1030]: info: CoreCommandRouter::initPlayerControls Jul 03 16:33:39 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 16:33:39 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 16:33:39 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 16:33:39 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 16:33:39 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 16:33:39 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 16:33:39 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 16:33:39 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 16:33:39 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 03 16:33:39 volumio-2 volumio[1030]: Express server listening on port 3000 Jul 03 16:33:39 volumio-2 volumio[1030]: [Metrics] WebUI: 23s 395.24ms Jul 03 16:33:39 volumio-2 volumio[1030]: info: CoreStateMachine::resetVolumioState Jul 03 16:33:39 volumio-2 volumio[1030]: info: CoreStateMachine::getcurrentVolume Jul 03 16:33:39 volumio-2 volumio[1030]: info: CoreCommandRouter::volumioRetrievevolume Jul 03 16:33:39 volumio-2 sudo[1293]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 03 16:33:39 volumio-2 sudo[1293]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 03 16:33:39 volumio-2 sudo[1293]: pam_unix(sudo:session): session closed for user root Jul 03 16:33:39 volumio-2 sudo[1295]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 03 16:33:39 volumio-2 sudo[1295]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 03 16:33:39 volumio-2 volumio[1030]: info: Volumio Network Manager: Network status updated: 2 Jul 03 16:33:39 volumio-2 sudo[1295]: pam_unix(sudo:session): session closed for user root Jul 03 16:33:39 volumio-2 volumio[1030]: info: VolumeController:: Volume=20 Mute =false Jul 03 16:33:39 volumio-2 volumio[1030]: info: CoreStateMachine::pushState Jul 03 16:33:39 volumio-2 volumio[1030]: info: CorePlayQueue::getTrack 0 Jul 03 16:33:39 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 03 16:33:39 volumio-2 volumio[1030]: info: CoreCommandRouter::volumioPushState Jul 03 16:33:39 volumio-2 volumio[1030]: info: CoreStateMachine::updateTrackBlock Jul 03 16:33:39 volumio-2 volumio[1030]: info: CorePlayQueue::getTrackBlock Jul 03 16:33:39 volumio-2 volumio[1030]: info: CoreCommandRouter::volumioRetrievevolume Jul 03 16:33:39 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 16:33:39 volumio-2 volumio[1030]: info: Reloading queue from file Jul 03 16:33:39 volumio-2 volumio[1030]: info: CoreCommandRouter::volumioGetState Jul 03 16:33:39 volumio-2 volumio[1030]: info: CorePlayQueue::getTrack 0 Jul 03 16:33:39 volumio-2 volumio[1030]: info: Setting Device type: Raspberry PI Jul 03 16:33:39 volumio-2 volumio[1030]: info: Completed loading Core Plugins Jul 03 16:33:39 volumio-2 volumio[1030]: info: Preparing to generate the ALSA configuration file Jul 03 16:33:39 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 03 16:33:39 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 03 16:33:39 volumio-2 volumio[1030]: info: Discovery: Getting this device information Jul 03 16:33:39 volumio-2 volumio[1030]: info: CoreCommandRouter::volumioGetState Jul 03 16:33:39 volumio-2 volumio[1030]: info: CorePlayQueue::getTrack 0 Jul 03 16:33:39 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 03 16:33:39 volumio-2 volumio[1030]: info: CoreStateMachine::setRepeat null single undefined Jul 03 16:33:39 volumio-2 volumio[1030]: info: CoreStateMachine::pushState Jul 03 16:33:39 volumio-2 volumio[1030]: info: CorePlayQueue::getTrack 0 Jul 03 16:33:39 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 03 16:33:39 volumio-2 volumio[1030]: info: CoreCommandRouter::volumioPushState Jul 03 16:33:39 volumio-2 volumio[1030]: info: CoreStateMachine::setRandom null Jul 03 16:33:39 volumio-2 volumio[1030]: info: CoreStateMachine::pushState Jul 03 16:33:39 volumio-2 volumio[1030]: info: CorePlayQueue::getTrack 0 Jul 03 16:33:39 volumio-2 volumio[1030]: info: CoreCommandRouter::volumioPushState Jul 03 16:33:39 volumio-2 volumio[1030]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Jul 03 16:33:39 volumio-2 volumio[1030]: info: VolumeController:: Volume=20 Mute =false Jul 03 16:33:39 volumio-2 volumio[1030]: info: CoreStateMachine::pushState Jul 03 16:33:39 volumio-2 volumio[1030]: info: CorePlayQueue::getTrack 0 Jul 03 16:33:39 volumio-2 volumio[1030]: info: CoreCommandRouter::volumioPushState Jul 03 16:33:39 volumio-2 volumio[1030]: info: Asound.conf file unchanged, so no further update is needed Jul 03 16:33:39 volumio-2 volumio[1030]: info: Output device has changed, restarting MPD Jul 03 16:33:39 volumio-2 sudo[1320]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jul 03 16:33:39 volumio-2 sudo[1320]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 03 16:33:39 volumio-2 volumio[1030]: info: Output device has changed, restarting Shairport Sync Jul 03 16:33:39 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 16:33:39 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 16:33:39 volumio-2 sudo[1329]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 03 16:33:39 volumio-2 sudo[1329]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 03 16:33:39 volumio-2 volumio[1030]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 03 16:33:39 volumio-2 sudo[1329]: pam_unix(sudo:session): session closed for user root Jul 03 16:33:39 volumio-2 volumio[1030]: info: ___________ START PLUGINS ___________ Jul 03 16:33:39 volumio-2 sudo[1331]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 03 16:33:39 volumio-2 sudo[1331]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 03 16:33:39 volumio-2 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jul 03 16:33:39 volumio-2 sudo[1320]: pam_unix(sudo:session): session closed for user root Jul 03 16:33:39 volumio-2 volumio[1030]: info: ControllerMpd::onStart: Initializing MPD Jul 03 16:33:39 volumio-2 volumio[1030]: info: Creating MPD Configuration file Jul 03 16:33:40 volumio-2 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jul 03 16:33:40 volumio-2 systemd[1]: Starting mpd.service - Music Player Daemon... Jul 03 16:33:40 volumio-2 sudo[1346]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 03 16:33:40 volumio-2 sudo[1346]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 03 16:33:40 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 03 16:33:40 volumio-2 volumio[1030]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 03 16:33:40 volumio-2 volumio[1030]: info: [1751553220077] CoreMusicLibrary::Adding element Media Servers Jul 03 16:33:40 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 03 16:33:40 volumio-2 sudo[1354]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 03 16:33:40 volumio-2 sudo[1346]: pam_unix(sudo:session): session closed for user root Jul 03 16:33:40 volumio-2 sudo[1354]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 03 16:33:40 volumio-2 systemd[1]: mpd.service: Deactivated successfully. Jul 03 16:33:40 volumio-2 systemd[1]: Stopped mpd.service - Music Player Daemon. Jul 03 16:33:40 volumio-2 systemd[1]: mpd.socket: Deactivated successfully. Jul 03 16:33:40 volumio-2 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jul 03 16:33:40 volumio-2 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jul 03 16:33:40 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 16:33:40 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 16:33:40 volumio-2 volumio[1030]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 03 16:33:40 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 16:33:40 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 16:33:40 volumio-2 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jul 03 16:33:40 volumio-2 systemd[1]: Starting mpd.service - Music Player Daemon... Jul 03 16:33:40 volumio-2 volumio[1030]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 03 16:33:40 volumio-2 volumio[1030]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 03 16:33:40 volumio-2 volumio[1030]: info: [1751553220259] CoreMusicLibrary::Adding element Last_100 Jul 03 16:33:40 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 03 16:33:40 volumio-2 volumio[1030]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 03 16:33:40 volumio-2 volumio[1030]: info: [1751553220270] CoreMusicLibrary::Adding element Webradio Jul 03 16:33:40 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 03 16:33:40 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 03 16:33:40 volumio-2 volumio[1030]: info: Initializing BBC Radios Jul 03 16:33:40 volumio-2 sudo[1365]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 03 16:33:40 volumio-2 sudo[1365]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jul 03 16:33:40 volumio-2 sudo[1378]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jul 03 16:33:40 volumio-2 sudo[1365]: pam_unix(sudo:session): session closed for user root Jul 03 16:33:40 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 03 16:33:40 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 16:33:40 volumio-2 volumio[1030]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 03 16:33:40 volumio-2 volumio[1030]: info: [1751553220504] CoreMusicLibrary::Adding element YouTube2 Jul 03 16:33:40 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 03 16:33:40 volumio-2 volumio[1030]: Cannot find translation for source YouTube2 Jul 03 16:33:40 volumio-2 volumio[1030]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 03 16:33:40 volumio-2 volumio[1030]: info: [1751553220591] CoreMusicLibrary::Adding element YouTube Music Jul 03 16:33:40 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 03 16:33:40 volumio-2 volumio[1030]: Cannot find translation for source YouTube2 Jul 03 16:33:40 volumio-2 volumio[1030]: Cannot find translation for source YouTube Music Jul 03 16:33:40 volumio-2 volumio[1030]: info: Loading i18n strings for locale en Jul 03 16:33:40 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 03 16:33:40 volumio-2 volumio[1030]: info: Volumio Calling Home Jul 03 16:33:41 volumio-2 systemd[1]: systemd-timedated.service: Deactivated successfully. Jul 03 16:33:41 volumio-2 sudo[1392]: 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 Jul 03 16:33:41 volumio-2 sudo[1394]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay rotary-encoder pin_a=6 pin_b=24 relative_axis=true steps-per-period=1 Jul 03 16:33:41 volumio-2 sudo[1394]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 03 16:33:41 volumio-2 sudo[1392]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 03 16:33:41 volumio-2 sudo[1392]: pam_unix(sudo:session): session closed for user root Jul 03 16:33:42 volumio-2 sudo[1394]: pam_unix(sudo:session): session closed for user root Jul 03 16:33:42 volumio-2 kernel: rotary-encoder rotary@6: gray Jul 03 16:33:42 volumio-2 kernel: input: rotary@6 as /devices/platform/rotary@6/input/input0 Jul 03 16:33:42 volumio-2 (udev-worker)[1401]: event0: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jul 03 16:33:43 volumio-2 volumio[1030]: info: Listing playlists Jul 03 16:33:43 volumio-2 volumio[1030]: info: Listing playlists Jul 03 16:33:43 volumio-2 volumio[1030]: info: Discovery: adding c76eb5fc-b147-44b7-9900-6a1ebf56379e Jul 03 16:33:43 volumio-2 volumio[1030]: info: Discovery: Found device Volumio 2 Jul 03 16:33:43 volumio-2 volumio[1030]: info: CoreCommandRouter::volumioGetState Jul 03 16:33:43 volumio-2 volumio[1030]: info: CorePlayQueue::getTrack 0 Jul 03 16:33:43 volumio-2 volumio[1030]: info: Discovery: this is already registered, c76eb5fc-b147-44b7-9900-6a1ebf56379e Jul 03 16:33:43 volumio-2 volumio[1030]: info: Discovery: Found device Volumio 2 Jul 03 16:33:43 volumio-2 volumio[1030]: info: CoreCommandRouter::volumioGetState Jul 03 16:33:43 volumio-2 volumio[1030]: info: CorePlayQueue::getTrack 0 Jul 03 16:33:43 volumio-2 volumio[1030]: info: ir_controller: File permissions successfully set on /etc/lirc/*. Jul 03 16:33:43 volumio-2 volumio[1030]: info: MPD Permissions set Jul 03 16:33:43 volumio-2 volumio[1030]: info: MPD Permissions set Jul 03 16:33:43 volumio-2 volumio[1030]: info: Upmpdcli Daemon Started Jul 03 16:33:43 volumio-2 volumio[1030]: info: Volumio called home Jul 03 16:33:44 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 16:33:44 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 16:33:44 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 16:33:44 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 16:33:44 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 16:33:44 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 16:33:44 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 16:33:44 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 16:33:44 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 16:33:44 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 16:33:44 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 16:33:44 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 16:33:44 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 16:33:44 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 16:33:44 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 16:33:44 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 16:33:44 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 16:33:44 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 16:33:44 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 16:33:44 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 16:33:44 volumio-2 volumio[1030]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jul 03 16:33:44 volumio-2 volumio[1030]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jul 03 16:33:44 volumio-2 sudo[1422]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay gpio-key gpio=5 active_low=1 gpio_pull=up keycode=20 Jul 03 16:33:44 volumio-2 sudo[1422]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 03 16:33:44 volumio-2 sudo[1422]: pam_unix(sudo:session): session closed for user root Jul 03 16:33:44 volumio-2 kernel: input: button@5 as /devices/platform/button@5/input/input1 Jul 03 16:33:44 volumio-2 volumio[1030]: info: Starting Shairport Sync Jul 03 16:33:44 volumio-2 volumio[1030]: info: Starting Shairport Sync Jul 03 16:33:45 volumio-2 sudo[1430]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 03 16:33:45 volumio-2 volumio[1030]: info: Starting Shairport Sync Jul 03 16:33:45 volumio-2 sudo[1430]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 03 16:33:45 volumio-2 sudo[1433]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 03 16:33:45 volumio-2 sudo[1433]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 03 16:33:45 volumio-2 sudo[1435]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 03 16:33:45 volumio-2 volumio[1030]: info: CoreCommandRouter::volumioGetState Jul 03 16:33:45 volumio-2 volumio[1030]: info: CorePlayQueue::getTrack 0 Jul 03 16:33:45 volumio-2 (udev-worker)[1401]: event1: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jul 03 16:33:45 volumio-2 sudo[1435]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 03 16:33:45 volumio-2 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jul 03 16:33:45 volumio-2 systemd[1]: shairport-sync.service: Deactivated successfully. Jul 03 16:33:45 volumio-2 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jul 03 16:33:45 volumio-2 systemd[1]: shairport-sync.service: Consumed 1.655s CPU time. Jul 03 16:33:45 volumio-2 volumio[1030]: info: ir_controller: LIRC correctly updated. Jul 03 16:33:45 volumio-2 volumio[1030]: info: ir_controller: HAT did not load /proc/device-tree/ir_receiver! Jul 03 16:33:45 volumio-2 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jul 03 16:33:45 volumio-2 sudo[1430]: pam_unix(sudo:session): session closed for user root Jul 03 16:33:45 volumio-2 volumio[1030]: info: ir_controller: Raspberry Pi revision code: a020d3 Jul 03 16:33:45 volumio-2 sudo[1433]: pam_unix(sudo:session): session closed for user root Jul 03 16:33:45 volumio-2 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jul 03 16:33:45 volumio-2 systemd[1]: shairport-sync.service: Deactivated successfully. Jul 03 16:33:45 volumio-2 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jul 03 16:33:45 volumio-2 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jul 03 16:33:45 volumio-2 sudo[1435]: pam_unix(sudo:session): session closed for user root Jul 03 16:33:45 volumio-2 volumio[1030]: info: Shairport-Sync Started Jul 03 16:33:45 volumio-2 volumio[1030]: Error adding Membership: Error: addMembership EINVAL Jul 03 16:33:45 volumio-2 volumio[1030]: info: Shairport-Sync Started Jul 03 16:33:45 volumio-2 sudo[1461]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -r -1 Jul 03 16:33:45 volumio-2 sudo[1461]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 03 16:33:45 volumio-2 sudo[1461]: pam_unix(sudo:session): session closed for user root Jul 03 16:33:45 volumio-2 volumio[1030]: info: Shairport-Sync Started Jul 03 16:33:45 volumio-2 volumio[1030]: info: CoreCommandRouter::volumioGetState Jul 03 16:33:45 volumio-2 volumio[1030]: info: CorePlayQueue::getTrack 0 Jul 03 16:33:46 volumio-2 volumio[1030]: info: CoreCommandRouter::volumioGetState Jul 03 16:33:46 volumio-2 volumio[1030]: info: CorePlayQueue::getTrack 0 Jul 03 16:33:46 volumio-2 sudo[1469]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay gpio-ir gpio_pin=25 gpio_pull=up Jul 03 16:33:46 volumio-2 sudo[1469]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 03 16:33:46 volumio-2 sudo[1471]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay rotary-encoder pin_a=23 pin_b=16 relative_axis=true steps-per-period=1 Jul 03 16:33:46 volumio-2 sudo[1471]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 03 16:33:46 volumio-2 sudo[1469]: pam_unix(sudo:session): session closed for user root Jul 03 16:33:46 volumio-2 kernel: Registered IR keymap rc-rc6-mce Jul 03 16:33:46 volumio-2 kernel: rotary-encoder rotary@17: gray Jul 03 16:33:46 volumio-2 kernel: IR RC6 protocol handler initialized Jul 03 16:33:46 volumio-2 volumio[1030]: info: ir_controller: Overlay gpio-ir gpio_pin=25 gpio_pull=up loaded. Jul 03 16:33:46 volumio-2 kernel: rc rc0: gpio_ir_recv as /devices/platform/ir-receiver@19/rc/rc0 Jul 03 16:33:46 volumio-2 kernel: rc rc0: lirc_dev: driver gpio_ir_recv registered at minor = 0, raw IR receiver, no transmitter Jul 03 16:33:46 volumio-2 kernel: input: gpio_ir_recv as /devices/platform/ir-receiver@19/rc/rc0/input2 Jul 03 16:33:46 volumio-2 kernel: input: rotary@17 as /devices/platform/rotary@17/input/input3 Jul 03 16:33:46 volumio-2 sudo[1480]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart lircd.service Jul 03 16:33:46 volumio-2 sudo[1480]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 03 16:33:46 volumio-2 sudo[1471]: pam_unix(sudo:session): session closed for user root Jul 03 16:33:46 volumio-2 systemd[1]: Starting lircd-setup.service - lircd(8) initialization helper tool... Jul 03 16:33:46 volumio-2 sudo[1491]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart lircd.service Jul 03 16:33:46 volumio-2 sudo[1491]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 03 16:33:46 volumio-2 (udev-worker)[1401]: event2: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jul 03 16:33:46 volumio-2 systemd-logind[532]: Watching system buttons on /dev/input/event2 (gpio_ir_recv) Jul 03 16:33:46 volumio-2 (udev-worker)[1402]: event3: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jul 03 16:33:47 volumio-2 volumio-remote-updater[538]: [2025-07-03 16:33:47] [connect] Successful connection Jul 03 16:33:47 volumio-2 volumio-remote-updater[538]: [2025-07-03 16:33:47] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1751553227 101 Jul 03 16:33:47 volumio-2 volumio[1030]: 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: 4 Jul 03 16:33:47 volumio-2 sudo[1498]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Jul 03 16:33:47 volumio-2 sudo[1498]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 03 16:33:47 volumio-2 sudo[1498]: pam_unix(sudo:session): session closed for user root Jul 03 16:33:47 volumio-2 lircd-setup[1495]: /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. Jul 03 16:33:47 volumio-2 lircd-setup[1495]: parser = configparser.SafeConfigParser() Jul 03 16:33:47 volumio-2 mpd[1381]: 2025-07-03T16:33:47 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 03 16:33:47 volumio-2 systemd[1]: lircd-setup.service: Deactivated successfully. Jul 03 16:33:47 volumio-2 systemd[1]: Finished lircd-setup.service - lircd(8) initialization helper tool. Jul 03 16:33:47 volumio-2 systemd[1]: Starting lircd.service - Flexible IR remote input/output application support... Jul 03 16:33:47 volumio-2 sudo[1354]: pam_unix(sudo:session): session closed for user root Jul 03 16:33:47 volumio-2 systemd[1]: Started mpd.service - Music Player Daemon. Jul 03 16:33:47 volumio-2 sudo[1331]: pam_unix(sudo:session): session closed for user root Jul 03 16:33:47 volumio-2 lircd-0.10.1[1501]: Info: lircd: Opening log, level: Info Jul 03 16:33:47 volumio-2 lircd-0.10.1[1501]: Notice: Version: lircd 0.10.1 Jul 03 16:33:47 volumio-2 lircd-0.10.1[1501]: Notice: System info: Linux volumio-2 6.12.27-v7+ #1876 SMP Tue May 6 15:08:23 BST 2025 armv7l GNU/Linux Jul 03 16:33:47 volumio-2 sudo[1505]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Jul 03 16:33:47 volumio-2 sudo[1505]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 03 16:33:47 volumio-2 sudo[1505]: pam_unix(sudo:session): session closed for user root Jul 03 16:33:48 volumio-2 sudo[1513]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Jul 03 16:33:48 volumio-2 sudo[1513]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 03 16:33:48 volumio-2 sudo[1513]: pam_unix(sudo:session): session closed for user root Jul 03 16:33:48 volumio-2 lircd-0.10.1[1501]: Info: Initial device: /dev/lirc0 Jul 03 16:33:48 volumio-2 lircd-0.10.1[1501]: Notice: 'lirc' written to protocols file /sys/class/rc/rc0/protocols Jul 03 16:33:48 volumio-2 lircd-0.10.1[1501]: Info: Initial device: /dev/lirc0 Jul 03 16:33:48 volumio-2 lircd-0.10.1[1501]: Info: [lirc] protocol is enabled Jul 03 16:33:48 volumio-2 lircd-0.10.1[1501]: Notice: Options: driver: default Jul 03 16:33:48 volumio-2 lircd[1501]: lircd-0.10.1[1501]: Info: lircd: Opening log, level: Info Jul 03 16:33:48 volumio-2 lircd[1501]: lircd-0.10.1[1501]: Notice: Using systemd fd Jul 03 16:33:48 volumio-2 lircd-0.10.1[1501]: Notice: Options: output: /var/run/lirc/lircd Jul 03 16:33:48 volumio-2 lircd[1501]: lircd-0.10.1[1501]: Warning: Running as root Jul 03 16:33:48 volumio-2 lircd[1501]: lircd-0.10.1[1501]: Info: Using remote: rc2. Jul 03 16:33:48 volumio-2 lircd-0.10.1[1501]: Notice: Options: nodaemon: 1 Jul 03 16:33:48 volumio-2 lircd[1501]: lircd-0.10.1[1501]: Notice: lircd(default) ready, using /var/run/lirc/lircd Jul 03 16:33:48 volumio-2 lircd-0.10.1[1501]: Notice: Options: plugindir: /usr/lib/arm-linux-gnueabihf/lirc/plugins Jul 03 16:33:48 volumio-2 lircd-0.10.1[1501]: Notice: Options: logfile: syslog Jul 03 16:33:48 volumio-2 lircd-0.10.1[1501]: Notice: Options: immediate-init: 0 Jul 03 16:33:48 volumio-2 lircd-0.10.1[1501]: Notice: Options: permission: 666 Jul 03 16:33:48 volumio-2 lircd-0.10.1[1501]: Notice: Options: driver-options: Jul 03 16:33:48 volumio-2 lircd-0.10.1[1501]: Notice: Options: pidfile: /var/run/lirc/lircd.pid Jul 03 16:33:48 volumio-2 lircd-0.10.1[1501]: Notice: Options: listen: 0 Jul 03 16:33:48 volumio-2 lircd-0.10.1[1501]: Notice: Options: connect: (null) Jul 03 16:33:48 volumio-2 lircd-0.10.1[1501]: Notice: Options: userelease: 0 Jul 03 16:33:48 volumio-2 lircd-0.10.1[1501]: Notice: Options: effective_user: (null) Jul 03 16:33:48 volumio-2 lircd-0.10.1[1501]: Notice: Options: release_suffix: _EVUP Jul 03 16:33:48 volumio-2 lircd-0.10.1[1501]: Notice: Options: allow_simulate: 0 Jul 03 16:33:48 volumio-2 lircd-0.10.1[1501]: Notice: Options: repeat_max: 600 Jul 03 16:33:48 volumio-2 lircd-0.10.1[1501]: Notice: Options: configfile: /etc/lirc/lircd.conf Jul 03 16:33:48 volumio-2 lircd-0.10.1[1501]: Notice: Options: dynamic_codes: (null) Jul 03 16:33:48 volumio-2 lircd-0.10.1[1501]: Notice: Current driver: default Jul 03 16:33:48 volumio-2 lircd-0.10.1[1501]: Notice: Driver API version: 3 Jul 03 16:33:48 volumio-2 lircd-0.10.1[1501]: Notice: Driver version: 0.10.0 Jul 03 16:33:48 volumio-2 lircd-0.10.1[1501]: Notice: Driver info: See file:///usr/share/doc/lirc/plugindocs/default.html Jul 03 16:33:48 volumio-2 lircd-0.10.1[1501]: Info: lircd: Opening log, level: Info Jul 03 16:33:48 volumio-2 lircd-0.10.1[1501]: Notice: Using systemd fd Jul 03 16:33:48 volumio-2 lircd-0.10.1[1501]: Warning: Running as root Jul 03 16:33:48 volumio-2 lircd-0.10.1[1501]: Info: Using remote: rc2. Jul 03 16:33:48 volumio-2 lircd-0.10.1[1501]: Notice: lircd(default) ready, using /var/run/lirc/lircd Jul 03 16:33:48 volumio-2 systemd[1]: Started lircd.service - Flexible IR remote input/output application support. Jul 03 16:33:48 volumio-2 sudo[1480]: pam_unix(sudo:session): session closed for user root Jul 03 16:33:48 volumio-2 sudo[1491]: pam_unix(sudo:session): session closed for user root Jul 03 16:33:48 volumio-2 volumio[1030]: error: MPD error: The expression evaluated to a falsy value: Jul 03 16:33:48 volumio-2 volumio[1030]: assert.ok(self.idling) Jul 03 16:33:48 volumio-2 volumio[1030]: error: The expression evaluated to a falsy value: Jul 03 16:33:48 volumio-2 volumio[1030]: assert.ok(self.idling) Jul 03 16:33:48 volumio-2 volumio[1030]: info: MPD running with PID1381 Jul 03 16:33:48 volumio-2 volumio[1030]: ,establishing connection Jul 03 16:33:48 volumio-2 volumio[1030]: error: updateQueue error: null Jul 03 16:33:48 volumio-2 volumio[1030]: info: ir_controller: systemctl restart lircd.service succeeded. Jul 03 16:33:48 volumio-2 volumio[1030]: info: ir_controller: systemctl restart lircd.service succeeded. Jul 03 16:33:48 volumio-2 sudo[1516]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart irexec.service Jul 03 16:33:48 volumio-2 sudo[1516]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 03 16:33:48 volumio-2 sudo[1518]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart irexec.service Jul 03 16:33:48 volumio-2 sudo[1518]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 03 16:33:48 volumio-2 volumio[1030]: error: updateQueue error: null Jul 03 16:33:48 volumio-2 sudo[1522]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Jul 03 16:33:48 volumio-2 sudo[1522]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 03 16:33:48 volumio-2 systemd[1]: Started irexec.service - Handle events from IR remotes decoded by lircd(8). Jul 03 16:33:48 volumio-2 sudo[1522]: pam_unix(sudo:session): session closed for user root Jul 03 16:33:48 volumio-2 lircd[1501]: lircd-0.10.1[1501]: Notice: accepted new client on /var/run/lirc/lircd Jul 03 16:33:48 volumio-2 lircd[1501]: lircd-0.10.1[1501]: Info: [lirc] protocol is enabled Jul 03 16:33:48 volumio-2 lircd-0.10.1[1501]: Notice: accepted new client on /var/run/lirc/lircd Jul 03 16:33:48 volumio-2 lircd-0.10.1[1501]: Info: [lirc] protocol is enabled Jul 03 16:33:48 volumio-2 sudo[1516]: pam_unix(sudo:session): session closed for user root Jul 03 16:33:48 volumio-2 lircd[1501]: lircd-0.10.1[1501]: Info: removed client Jul 03 16:33:48 volumio-2 lircd-0.10.1[1501]: Info: removed client Jul 03 16:33:48 volumio-2 systemd[1]: Stopping irexec.service - Handle events from IR remotes decoded by lircd(8)... Jul 03 16:33:48 volumio-2 systemd[1]: irexec.service: Deactivated successfully. Jul 03 16:33:48 volumio-2 volumio[1030]: info: Completed starting Core Plugins Jul 03 16:33:48 volumio-2 systemd[1]: Stopped irexec.service - Handle events from IR remotes decoded by lircd(8). Jul 03 16:33:48 volumio-2 volumio[1030]: info: ------------------------------------------- Jul 03 16:33:48 volumio-2 volumio[1030]: info: ----- MyVolumio plugins startup ---- Jul 03 16:33:48 volumio-2 volumio[1030]: info: ------------------------------------------- Jul 03 16:33:48 volumio-2 volumio[1030]: info: [MyVolumio PluginManager] Fetching plans data.... Jul 03 16:33:48 volumio-2 systemd[1]: Started irexec.service - Handle events from IR remotes decoded by lircd(8). Jul 03 16:33:48 volumio-2 lircd[1501]: lircd-0.10.1[1501]: Notice: accepted new client on /var/run/lirc/lircd Jul 03 16:33:48 volumio-2 lircd-0.10.1[1501]: Notice: accepted new client on /var/run/lirc/lircd Jul 03 16:33:48 volumio-2 lircd[1501]: lircd-0.10.1[1501]: Info: [lirc] protocol is enabled Jul 03 16:33:48 volumio-2 lircd-0.10.1[1501]: Info: [lirc] protocol is enabled Jul 03 16:33:48 volumio-2 sudo[1518]: pam_unix(sudo:session): session closed for user root Jul 03 16:33:48 volumio-2 volumio[1030]: info: ir_controller: systemctl restart irexec.service succeeded. Jul 03 16:33:48 volumio-2 volumio[1030]: info: ir_controller: systemctl restart irexec.service succeeded. Jul 03 16:33:52 volumio-2 volumio[1030]: info: CoreCommandRouter::volumioGetState Jul 03 16:33:52 volumio-2 volumio[1030]: info: CorePlayQueue::getTrack 0 Jul 03 16:33:53 volumio-2 volumio[1030]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jul 03 16:33:55 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 03 16:33:55 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 03 16:33:55 volumio-2 volumio[1030]: info: Discovery: Getting this device information Jul 03 16:33:55 volumio-2 volumio[1030]: info: CoreCommandRouter::volumioGetState Jul 03 16:33:55 volumio-2 volumio[1030]: info: CorePlayQueue::getTrack 0 Jul 03 16:33:55 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 03 16:33:55 volumio-2 volumio[1030]: verbose: New Socket.io Connection to 192.168.88.184:3000 from 192.168.88.124 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Jul 03 16:33:55 volumio-2 volumio[1030]: info: CoreCommandRouter::volumioGetState Jul 03 16:33:55 volumio-2 volumio[1030]: info: CorePlayQueue::getTrack 0 Jul 03 16:33:55 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jul 03 16:33:55 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jul 03 16:33:57 volumio-2 volumio[1030]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jul 03 16:33:57 volumio-2 volumio[1030]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jul 03 16:33:57 volumio-2 volumio[1030]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jul 03 16:33:57 volumio-2 volumio[1030]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jul 03 16:33:57 volumio-2 volumio[1030]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jul 03 16:33:57 volumio-2 volumio[1030]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jul 03 16:33:57 volumio-2 volumio[1030]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jul 03 16:33:57 volumio-2 volumio[1030]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jul 03 16:33:57 volumio-2 volumio[1030]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jul 03 16:33:57 volumio-2 volumio[1030]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jul 03 16:33:57 volumio-2 volumio[1030]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jul 03 16:33:57 volumio-2 volumio[1030]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jul 03 16:33:57 volumio-2 volumio[1030]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jul 03 16:33:57 volumio-2 volumio[1030]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jul 03 16:33:57 volumio-2 volumio[1030]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jul 03 16:33:57 volumio-2 volumio[1030]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jul 03 16:33:57 volumio-2 volumio[1030]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jul 03 16:33:57 volumio-2 volumio[1030]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jul 03 16:33:57 volumio-2 volumio[1030]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jul 03 16:33:57 volumio-2 volumio[1030]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jul 03 16:33:57 volumio-2 volumio[1030]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jul 03 16:33:57 volumio-2 volumio[1030]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jul 03 16:33:57 volumio-2 volumio[1030]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jul 03 16:33:57 volumio-2 volumio[1030]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jul 03 16:33:57 volumio-2 volumio[1030]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jul 03 16:33:57 volumio-2 volumio[1030]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jul 03 16:33:57 volumio-2 volumio[1030]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jul 03 16:33:57 volumio-2 volumio[1030]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jul 03 16:33:57 volumio-2 volumio[1030]: info: Adding plugin bluetooth to MyMusic Plugins Jul 03 16:33:57 volumio-2 volumio[1030]: info: Adding plugin multiroom to MyMusic Plugins Jul 03 16:33:57 volumio-2 volumio[1030]: info: Adding plugin metavolumio to MyMusic Plugins Jul 03 16:33:57 volumio-2 volumio[1030]: info: Adding plugin cd_controller to MyMusic Plugins Jul 03 16:33:57 volumio-2 volumio[1030]: info: Adding plugin qobuzconnect to MyMusic Plugins Jul 03 16:33:57 volumio-2 volumio[1030]: info: Adding plugin smart_inputs to MyMusic Plugins Jul 03 16:33:57 volumio-2 volumio[1030]: info: Adding plugin tidalconnect to MyMusic Plugins Jul 03 16:33:57 volumio-2 volumio[1030]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jul 03 16:34:00 volumio-2 volumio[1030]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jul 03 16:34:00 volumio-2 volumio[1030]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jul 03 16:34:00 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 16:34:00 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 16:34:00 volumio-2 volumio[1030]: info: Starting MyVolumio Remote Streaming Endpoints Jul 03 16:34:00 volumio-2 volumio[1030]: info: MyVolumio login type: Token Jul 03 16:34:00 volumio-2 volumio[1030]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jul 03 16:34:00 volumio-2 volumio[1030]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jul 03 16:34:03 volumio-2 volumio[1030]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jul 03 16:34:03 volumio-2 volumio[1030]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jul 03 16:34:03 volumio-2 volumio[1030]: info: Streaming services startup Jul 03 16:34:03 volumio-2 volumio[1030]: info: Starting Streaming Daemon Jul 03 16:34:03 volumio-2 volumio[1030]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jul 03 16:34:03 volumio-2 sudo[1562]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 03 16:34:03 volumio-2 sudo[1562]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 03 16:34:03 volumio-2 sudo[1562]: pam_unix(sudo:session): session closed for user root Jul 03 16:34:04 volumio-2 volumio[1030]: info: CoreCommandRouter::volumioGetState Jul 03 16:34:04 volumio-2 volumio[1030]: info: CorePlayQueue::getTrack 0 Jul 03 16:34:04 volumio-2 volumio[1030]: error: Cannot start Volumio Streaming Daemon Jul 03 16:34:04 volumio-2 volumio[1030]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 03 16:34:04 volumio-2 volumio[1030]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 03 16:34:04 volumio-2 volumio[1030]: verbose: New Socket.io Connection to 192.168.88.184:3000 from 192.168.88.124 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Jul 03 16:34:04 volumio-2 volumio[1030]: info: CoreCommandRouter::volumioGetState Jul 03 16:34:04 volumio-2 volumio[1030]: info: CorePlayQueue::getTrack 0 Jul 03 16:34:04 volumio-2 volumio[1030]: info: CoreCommandRouter::volumioGetState Jul 03 16:34:04 volumio-2 volumio[1030]: info: CorePlayQueue::getTrack 0 Jul 03 16:34:04 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jul 03 16:34:04 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jul 03 16:34:04 volumio-2 volumio[1030]: info: CoreCommandRouter::volumioGetState Jul 03 16:34:04 volumio-2 volumio[1030]: info: CorePlayQueue::getTrack 0 Jul 03 16:34:05 volumio-2 volumio[1030]: error: MyVolumio Custom Token format not valid, refreshing it Jul 03 16:34:05 volumio-2 volumio[1030]: info: Listing playlists Jul 03 16:34:05 volumio-2 volumio[1030]: info: Listing playlists Jul 03 16:34:08 volumio-2 volumio[1030]: info: MyVolumio login type: Token Jul 03 16:34:09 volumio-2 sudo[1572]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 03 16:34:09 volumio-2 sudo[1572]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 03 16:34:09 volumio-2 sudo[1570]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 03 16:34:09 volumio-2 sudo[1570]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 03 16:34:09 volumio-2 sudo[1572]: pam_unix(sudo:session): session closed for user root Jul 03 16:34:09 volumio-2 sudo[1570]: pam_unix(sudo:session): session closed for user root Jul 03 16:34:09 volumio-2 volumio[1030]: verbose: New Socket.io Connection to 192.168.88.184 from 192.168.88.124 UA: Mozilla/5.0 (Linux; Android 15; SM-A556B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/138.0.7204.63 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Jul 03 16:34:10 volumio-2 sudo[1592]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 03 16:34:10 volumio-2 sudo[1592]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 03 16:34:10 volumio-2 sudo[1592]: pam_unix(sudo:session): session closed for user root Jul 03 16:34:10 volumio-2 sudo[1593]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 03 16:34:10 volumio-2 sudo[1593]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 03 16:34:10 volumio-2 sudo[1593]: pam_unix(sudo:session): session closed for user root Jul 03 16:34:10 volumio-2 volumio[1030]: verbose: New Socket.io Connection to 192.168.88.184 from 192.168.88.124 UA: Mozilla/5.0 (Linux; Android 15; SM-A556B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/138.0.7204.63 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Jul 03 16:34:10 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 03 16:34:10 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 03 16:34:10 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 03 16:34:10 volumio-2 volumio[1030]: info: CoreCommandRouter::volumioGetState Jul 03 16:34:10 volumio-2 volumio[1030]: info: CorePlayQueue::getTrack 0 Jul 03 16:34:10 volumio-2 volumio[1030]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jul 03 16:34:11 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 03 16:34:11 volumio-2 volumio[1030]: info: Listing playlists Jul 03 16:34:11 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 03 16:34:11 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jul 03 16:34:11 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jul 03 16:34:11 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 03 16:34:11 volumio-2 volumio-remote-updater[538]: Test mode enabled Jul 03 16:34:11 volumio-2 volumio-remote-updater[538]: Alpha mode disabled Jul 03 16:34:11 volumio-2 volumio-remote-updater[538]: Alpha legacy test mode disabled Jul 03 16:34:11 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jul 03 16:34:11 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 03 16:34:11 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 03 16:34:11 volumio-2 volumio[1030]: info: Discovery: Getting this device information Jul 03 16:34:11 volumio-2 volumio[1030]: info: CoreCommandRouter::volumioGetState Jul 03 16:34:11 volumio-2 volumio[1030]: info: CorePlayQueue::getTrack 0 Jul 03 16:34:11 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 03 16:34:11 volumio-2 volumio[1030]: info: MyVolumio token set successfully Jul 03 16:34:11 volumio-2 volumio[1030]: info: MYVOLUMIO: Adding device Jul 03 16:34:11 volumio-2 volumio[1030]: info: MYVOLUMIO: Evaluating Server Jul 03 16:34:12 volumio-2 volumio[1030]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jul 03 16:34:12 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jul 03 16:34:13 volumio-2 volumio[1030]: info: MyVolumio status changed Jul 03 16:34:13 volumio-2 volumio[1030]: info: Streaming services startup Jul 03 16:34:13 volumio-2 volumio[1030]: info: Starting Streaming Daemon Jul 03 16:34:13 volumio-2 volumio[1030]: info: Removing browser output: myVolumio user plan is not superstar Jul 03 16:34:13 volumio-2 volumio[1030]: info: Removing audio output: Jul 03 16:34:13 volumio-2 volumio[1030]: info: Stoppping Tunnel 1 Jul 03 16:34:13 volumio-2 sudo[1619]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 03 16:34:13 volumio-2 sudo[1619]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 03 16:34:14 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 03 16:34:14 volumio-2 systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jul 03 16:34:14 volumio-2 sudo[1619]: pam_unix(sudo:session): session closed for user root Jul 03 16:34:14 volumio-2 volumio[1030]: info: Received Get System Info Jul 03 16:34:14 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 03 16:34:14 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 03 16:34:14 volumio-2 volumio[1030]: info: Discovery: Getting this device information Jul 03 16:34:14 volumio-2 volumio[1030]: info: CoreCommandRouter::volumioGetState Jul 03 16:34:14 volumio-2 volumio[1030]: info: CorePlayQueue::getTrack 0 Jul 03 16:34:14 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 03 16:34:14 volumio-2 sudo[1622]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jul 03 16:34:14 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=1624 comm="timedatectl show --property=NTPSynchronized --valu") Jul 03 16:34:14 volumio-2 sudo[1622]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 03 16:34:14 volumio-2 volumio[1030]: error: Cannot start Volumio Streaming Daemon Jul 03 16:34:14 volumio-2 systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jul 03 16:34: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. Jul 03 16:34: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. Jul 03 16:34: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. Jul 03 16:34: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. Jul 03 16:34: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. Jul 03 16:34: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. Jul 03 16:34: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. Jul 03 16:34: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. Jul 03 16:34:14 volumio-2 sudo[1622]: pam_unix(sudo:session): session closed for user root Jul 03 16:34:14 volumio-2 volumio[1030]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 03 16:34:14 volumio-2 volumio[1030]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 03 16:34:14 volumio-2 volumio[1030]: info: CoreCommandRouter::volumioGetState Jul 03 16:34:14 volumio-2 volumio[1030]: info: CorePlayQueue::getTrack 0 Jul 03 16:34:14 volumio-2 volumio[1030]: info: Remote SSH Stopped Jul 03 16:34:14 volumio-2 dbus-daemon[521]: [system] Successfully activated service 'org.freedesktop.timedate1' Jul 03 16:34:14 volumio-2 systemd[1]: Started systemd-timedated.service - Time & Date Service. Jul 03 16:34:14 volumio-2 setdatetime-helper.sh[1623]: Time is not synchronized. Attempting to sync... Jul 03 16:34:15 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 03 16:34:15 volumio-2 volumio[1030]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Jul 03 16:34:17 volumio-2 upmpdcli[1633]: writing RSA key Jul 03 16:34:20 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 16:34:20 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 03 16:34:20 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jul 03 16:34:20 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jul 03 16:34:20 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jul 03 16:34:20 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jul 03 16:34:20 volumio-2 volumio[1030]: info: CoreCommandRouter::volumioGetBrowseSources Jul 03 16:34:20 volumio-2 volumio[1030]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 03 16:34:22 volumio-2 volumio[1030]: info: CoreCommandRouter::volumioGetState Jul 03 16:34:22 volumio-2 volumio[1030]: info: CorePlayQueue::getTrack 0 Jul 03 16:34:22 volumio-2 sudo[1651]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Thu, 03 Jul 2025 14:34:21 GMT#015' Jul 03 16:34:22 volumio-2 sudo[1651]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jul 03 16:34:21 volumio-2 setdatetime-helper.sh[1652]: Thu Jul 3 16:34:21 CEST 2025 Jul 03 16:34:21 volumio-2 systemd-journald[317]: Time jumped backwards, rotating. Jul 03 16:34:21 volumio-2 sudo[1651]: pam_unix(sudo:session): session closed for user root Jul 03 16:34:21 volumio-2 systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jul 03 16:34:21 volumio-2 setdatetime-helper.sh[1623]: Time synchronized successfully. Jul 03 16:34:21 volumio-2 systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jul 03 16:34:21 volumio-2 volumio[1030]: error: Failed to ping endpoint as1.myvolumio.org : unknown error Jul 03 16:34:21 volumio-2 volumio[1030]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 03 16:34:21 volumio-2 volumio[1030]: Error: Unable to resolve or reject the same promise twice Jul 03 16:34:21 volumio-2 volumio[1030]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Jul 03 16:34:21 volumio-2 volumio[1030]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32371) Jul 03 16:34:21 volumio-2 volumio[1030]: at Socket.emit (node:events:514:28) Jul 03 16:34:21 volumio-2 volumio[1030]: at addChunk (node:internal/streams/readable:343:12) Jul 03 16:34:21 volumio-2 volumio[1030]: at readableAddChunk (node:internal/streams/readable:316:9) Jul 03 16:34:21 volumio-2 volumio[1030]: at Readable.push (node:internal/streams/readable:253:10) Jul 03 16:34:21 volumio-2 volumio[1030]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Jul 03 16:34:21 volumio-2 volumio[1030]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 03 16:34:24 volumio-2 sudo[1668]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-07-03 16:33' Jul 03 16:34:24 volumio-2 sudo[1668]: 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="f9454895cc8e2f1971c705a815a7226e021e37d7" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1fc282af1865fae9b40239cedf2cc17c112eef0d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Jun 28 16:48:46 UTC 2025" VOLUMIO_VERSION="4.014" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="1b557e4869cd2eea14f4113140ecea1c"