-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Sun 2025-01-19 16:15:58 CET. -- Jan 19 16:14:12 volumio systemd-timedated[1042]: Changed local time to Sun Jan 19 16:14:12 2025 Jan 19 16:14:12 volumio sudo[1040]: pam_unix(sudo:session): session closed for user root Jan 19 16:14:12 volumio volumio-time-update[619]: volumio-time-update-util: System time updated successfully. Jan 19 16:14:12 volumio systemd[1]: Started Volumio Time Update Utility. Jan 19 16:14:12 volumio ntpd[786]: Listen normally on 3 wlan0 192.168.178.31:123 Jan 19 16:14:12 volumio ntpd[786]: new interface(s) found: waking up resolver Jan 19 16:14:13 volumio nmbd[743]: [2025/01/19 16:14:13.099710, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 19 16:14:13 volumio systemd[1]: Started Samba NMB Daemon. Jan 19 16:14:13 volumio nmbd[743]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Jan 19 16:14:13 volumio systemd[1]: Starting Samba Winbind Daemon... Jan 19 16:14:13 volumio volumio[1018]: info: ------------------------------------------- Jan 19 16:14:13 volumio volumio[1018]: info: ----- Volumio3 ---- Jan 19 16:14:13 volumio volumio[1018]: info: ------------------------------------------- Jan 19 16:14:13 volumio volumio[1018]: info: ----- System startup ---- Jan 19 16:14:13 volumio volumio[1018]: info: ------------------------------------------- Jan 19 16:14:13 volumio winbindd[1052]: [2025/01/19 16:14:13.354426, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Jan 19 16:14:13 volumio winbindd[1052]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Jan 19 16:14:13 volumio winbindd[1052]: [2025/01/19 16:14:13.376480, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 19 16:14:13 volumio winbindd[1052]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Jan 19 16:14:13 volumio systemd[1]: Started Samba Winbind Daemon. Jan 19 16:14:13 volumio systemd[1]: Starting Samba SMB Daemon... Jan 19 16:14:13 volumio winbindd[1052]: [2025/01/19 16:14:13.761966, 0] ../source3/winbindd/wb_lookupsids.c:659(wb_lookupsids_recv) Jan 19 16:14:13 volumio winbindd[1052]: res_names->count = 3, expected 4 Jan 19 16:14:13 volumio smbd[1057]: [2025/01/19 16:14:13.782162, 0] ../lib/util/become_daemon.c:138(daemon_ready) Jan 19 16:14:13 volumio systemd[1]: Started Samba SMB Daemon. Jan 19 16:14:13 volumio smbd[1057]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Jan 19 16:14:13 volumio systemd[1]: Reached target Multi-User System. Jan 19 16:14:13 volumio systemd[1]: Reached target Graphical Interface. Jan 19 16:14:13 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Jan 19 16:14:13 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jan 19 16:14:13 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Jan 19 16:14:13 volumio systemd[1]: Startup finished in 11.865s (kernel) + 31.395s (userspace) = 43.261s. Jan 19 16:14:14 volumio volumio[1018]: info: MYVOLUMIO Environment detected Jan 19 16:14:14 volumio volumio[1018]: info: Plugin folders cleanup Jan 19 16:14:14 volumio volumio[1018]: info: Scanning into folder /volumio/app/plugins/ Jan 19 16:14:14 volumio volumio[1018]: info: Scanning category audio_interface Jan 19 16:14:14 volumio volumio[1018]: info: Scanning category miscellanea Jan 19 16:14:14 volumio volumio[1018]: info: Scanning category music_service Jan 19 16:14:14 volumio volumio[1018]: info: Scanning category plugins.json Jan 19 16:14:14 volumio volumio[1018]: info: Scanning category system_controller Jan 19 16:14:14 volumio volumio[1018]: info: Scanning category user_interface Jan 19 16:14:14 volumio volumio[1018]: info: Scanning into folder /data/plugins/ Jan 19 16:14:14 volumio volumio[1018]: info: Scanning category audio_interface Jan 19 16:14:14 volumio volumio[1018]: info: Scanning category system_controller Jan 19 16:14:14 volumio volumio[1018]: info: Scanning category system_hardware Jan 19 16:14:14 volumio volumio[1018]: info: Scanning category user_interface Jan 19 16:14:14 volumio volumio[1018]: info: Plugin folders cleanup completed Jan 19 16:14:14 volumio volumio[1018]: info: ------------------------------------------- Jan 19 16:14:14 volumio volumio[1018]: info: ----- Core plugins startup ---- Jan 19 16:14:14 volumio volumio[1018]: info: ------------------------------------------- Jan 19 16:14:14 volumio volumio[1018]: info: Loading plugins from folder /volumio/app/plugins/ Jan 19 16:14:14 volumio volumio[1018]: info: Adding plugin upnp to MyMusic Plugins Jan 19 16:14:14 volumio volumio[1018]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 19 16:14:14 volumio volumio[1018]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 19 16:14:14 volumio volumio[1018]: info: Loading plugins from folder /data/plugins/ Jan 19 16:14:14 volumio volumio[1018]: info: Loading plugin "system"... Jan 19 16:14:14 volumio volumio[1018]: info: Loading plugin "appearance"... Jan 19 16:14:14 volumio wpa_supplicant[887]: RRM: Ignoring radio measurement request: Not RRM network Jan 19 16:14:15 volumio volumio[1018]: info: Loading plugin "network"... Jan 19 16:14:15 volumio volumio[1018]: info: Refreshing Cached IP Addresses Jan 19 16:14:15 volumio nmbd[743]: [2025/01/19 16:14:15.177560, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jan 19 16:14:15 volumio nmbd[743]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.178.31 for name WORKGROUP<1d>. Jan 19 16:14:15 volumio nmbd[743]: This response was from IP 192.168.178.20, reporting an IP address of 192.168.178.20. Jan 19 16:14:15 volumio sudo[1072]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 19 16:14:15 volumio sudo[1072]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 16:14:15 volumio sudo[1074]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 19 16:14:15 volumio sudo[1074]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 16:14:15 volumio sudo[1072]: pam_unix(sudo:session): session closed for user root Jan 19 16:14:15 volumio sudo[1074]: pam_unix(sudo:session): session closed for user root Jan 19 16:14:15 volumio volumio[1018]: info: Loading plugin "services"... Jan 19 16:14:15 volumio volumio[1018]: info: Loading plugin "alsa_controller"... Jan 19 16:14:15 volumio sudo[1083]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 19 16:14:15 volumio sudo[1083]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 16:14:15 volumio systemd[1]: systemd-fsckd.service: Succeeded. Jan 19 16:14:15 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 19 16:14:15 volumio volumio[1018]: info: Loading plugin "wizard"... Jan 19 16:14:15 volumio volumio[1018]: info: Loading plugin "networkfs"... Jan 19 16:14:15 volumio volumio[1018]: info: Starting Udev Watcher for removable devices Jan 19 16:14:15 volumio sudo[1100]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=emi,password=elisa91,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.178.34/Music /mnt/NAS/HC2 Jan 19 16:14:15 volumio sudo[1100]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 16:14:15 volumio volumio[1018]: info: Ignoring mount for partition: boot Jan 19 16:14:15 volumio volumio[1018]: info: Ignoring mount for partition: volumio Jan 19 16:14:15 volumio volumio[1018]: info: Ignoring mount for partition: volumio_data Jan 19 16:14:15 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 19 16:14:15 volumio volumio[1018]: info: Loading plugin "volumio_command_line_client"... Jan 19 16:14:15 volumio volumio[1018]: info: Loading plugin "upnp"... Jan 19 16:14:15 volumio volumio[1018]: info: [1737299655400] Starting Upmpd Daemon Jan 19 16:14:15 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 19 16:14:15 volumio volumio[1018]: info: Loading plugin "my_music"... Jan 19 16:14:15 volumio volumio[1018]: info: Loading plugin "mpd"... Jan 19 16:14:15 volumio kernel: Key type cifs.spnego registered Jan 19 16:14:15 volumio kernel: Key type cifs.idmap registered Jan 19 16:14:15 volumio 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. Jan 19 16:14:15 volumio kernel: CIFS: Attempting to mount //192.168.178.34/Music Jan 19 16:14:15 volumio sh[507]: timed out Jan 19 16:14:15 volumio dhcpcd[549]: timed out Jan 19 16:14:15 volumio sh[507]: dhcpcd exited Jan 19 16:14:15 volumio dhcpcd[549]: dhcpcd exited Jan 19 16:14:15 volumio sh[507]: ifup: failed to bring up eth0 Jan 19 16:14:15 volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Jan 19 16:14:15 volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Jan 19 16:14:15 volumio volumio[1018]: info: Loading plugin "upnp_browser"... Jan 19 16:14:15 volumio kernel: cryptd: max_cpu_qlen set to 1000 Jan 19 16:14:16 volumio volumio[1018]: info: Loading plugin "alarm-clock"... Jan 19 16:14:16 volumio volumio[1018]: info: Loading plugin "airplay_emulation"... Jan 19 16:14:16 volumio volumio[1018]: info: Starting Shairport Sync Jan 19 16:14:16 volumio volumio[1018]: info: Loading plugin "last_100"... Jan 19 16:14:16 volumio volumio[1018]: info: Loading plugin "webradio"... Jan 19 16:14:16 volumio volumio[1018]: info: Loading plugin "i2s_dacs"... Jan 19 16:14:16 volumio volumio[1018]: info: Loading plugin "volumiodiscovery"... Jan 19 16:14:16 volumio volumio[1018]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 19 16:14:16 volumio volumio[1018]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 19 16:14:16 volumio volumio[1018]: *** WARNING *** For more information see Jan 19 16:14:16 volumio volumio[1018]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 19 16:14:16 volumio volumio[1018]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 19 16:14:16 volumio volumio[1018]: *** WARNING *** For more information see Jan 19 16:14:16 volumio node[1018]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 19 16:14:16 volumio node[1018]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 19 16:14:16 volumio node[1018]: *** WARNING *** For more information see Jan 19 16:14:16 volumio node[1018]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 19 16:14:16 volumio node[1018]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 19 16:14:16 volumio node[1018]: *** WARNING *** For more information see Jan 19 16:14:16 volumio volumio[1018]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 19 16:14:16 volumio volumio[1018]: info: Discovery: Started advertising with name: Volumio Jan 19 16:14:16 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 19 16:14:16 volumio volumio[1018]: info: Loading plugin "outputs"... Jan 19 16:14:16 volumio volumio[1018]: info: Loading plugin "albumart"... Jan 19 16:14:16 volumio volumio[1018]: info: Plugin example_plugin is not enabled Jan 19 16:14:16 volumio volumio[1018]: info: Loading plugin "inputs"... Jan 19 16:14:16 volumio volumio[1018]: info: Loading plugin "updater_comm"... Jan 19 16:14:16 volumio volumio[1018]: info: Plugin mpdemulation is not enabled Jan 19 16:14:16 volumio volumio[1018]: info: Loading plugin "rest_api"... Jan 19 16:14:16 volumio volumio[1018]: info: Loading plugin "websocket"... Jan 19 16:14:16 volumio volumio[1018]: info: Starting Socket.io Server version 2.3.0 Jan 19 16:14:16 volumio volumio[1018]: Forking 3 albumart workers Jan 19 16:14:16 volumio volumio[1018]: info: Loading plugin "fusiondsp"... Jan 19 16:14:16 volumio sudo[1100]: pam_unix(sudo:session): session closed for user root Jan 19 16:14:17 volumio volumio[1018]: info: Applying required configuration parameters for plugin fusiondsp Jan 19 16:14:17 volumio volumio[1018]: info: Loading plugin "audiophonicsonoff"... Jan 19 16:14:17 volumio volumio[1018]: Starting albumart workers Jan 19 16:14:17 volumio volumio[1018]: Starting albumart workers Jan 19 16:14:17 volumio volumio[1018]: Starting albumart workers Jan 19 16:14:17 volumio volumio[1018]: info: Audiophonics on/off initiated Jan 19 16:14:17 volumio volumio[1018]: info: Loading plugin "ir_controller"... Jan 19 16:14:17 volumio volumio[1018]: info: Applying required configuration parameters for plugin ir_controller Jan 19 16:14:17 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Jan 19 16:14:17 volumio volumio[1018]: info: Loading plugin "music_services_shield"... Jan 19 16:14:18 volumio volumio[1018]: info: Applying required configuration parameters for plugin music_services_shield Jan 19 16:14:18 volumio volumio[1018]: info: Loading plugin "Systeminfo"... Jan 19 16:14:18 volumio volumio[1018]: info: Loading plugin "touch_display"... Jan 19 16:14:18 volumio volumio[1018]: info: Applying required configuration parameters for plugin touch_display Jan 19 16:14:18 volumio volumio[1018]: info: Loading i18n strings for locale en Jan 19 16:14:18 volumio volumio[1018]: Updating browse sources language Jan 19 16:14:18 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 19 16:14:18 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 19 16:14:18 volumio volumio[1018]: info: CoreCommandRouter::initPlayerControls Jan 19 16:14:18 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 16:14:18 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 16:14:18 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 16:14:18 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 16:14:18 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 16:14:18 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 16:14:18 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 16:14:18 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 16:14:19 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 19 16:14:19 volumio volumio[1018]: Express server listening on port 3000 Jan 19 16:14:19 volumio volumio[1018]: [Metrics] WebUI: 6s 384.21ms Jan 19 16:14:19 volumio sudo[1083]: pam_unix(sudo:session): session closed for user root Jan 19 16:14:19 volumio volumio[1018]: info: CoreStateMachine::resetVolumioState Jan 19 16:14:19 volumio volumio[1018]: info: CoreStateMachine::getcurrentVolume Jan 19 16:14:19 volumio volumio[1018]: info: CoreCommandRouter::volumioRetrievevolume Jan 19 16:14:19 volumio volumio-remote-updater[626]: [2025-01-19 16:14:19] [connect] Successful connection Jan 19 16:14:19 volumio volumio[1018]: info: Volumio Network Manager: Network status updated: 2 Jan 19 16:14:19 volumio volumio[1018]: verbose: New Socket.io Connection to 192.168.178.31 from 192.168.178.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Jan 19 16:14:19 volumio volumio-remote-updater[626]: [2025-01-19 16:14:19] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1737299659 101 Jan 19 16:14:19 volumio volumio[1018]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 2 Jan 19 16:14:19 volumio volumio[1018]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jan 19 16:14:19 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 19 16:14:19 volumio volumio[1018]: info: Reloading queue from file Jan 19 16:14:19 volumio volumio[1018]: info: Setting Device type: Raspberry PI Jan 19 16:14:19 volumio volumio[1018]: info: CoreStateMachine::setRepeat null single undefined Jan 19 16:14:19 volumio volumio[1018]: info: CoreStateMachine::pushState Jan 19 16:14:19 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jan 19 16:14:19 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 16:14:19 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Jan 19 16:14:19 volumio volumio[1018]: info: CoreStateMachine::setRandom false Jan 19 16:14:19 volumio volumio[1018]: info: CoreStateMachine::pushState Jan 19 16:14:19 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jan 19 16:14:19 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 16:14:19 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Jan 19 16:14:19 volumio volumio[1018]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jan 19 16:14:19 volumio volumio[1018]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03114 Jan 19 16:14:19 volumio volumio[1018]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jan 19 16:14:19 volumio volumio[1018]: info: Completed loading Core Plugins Jan 19 16:14:19 volumio volumio[1018]: info: Preparing to generate the ALSA configuration file Jan 19 16:14:19 volumio volumio[1018]: info: VolumeController:: Volume=25 Mute =false Jan 19 16:14:19 volumio volumio[1018]: info: CoreStateMachine::pushState Jan 19 16:14:19 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jan 19 16:14:19 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 19 16:14:19 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Jan 19 16:14:19 volumio volumio[1018]: info: CoreStateMachine::updateTrackBlock Jan 19 16:14:19 volumio volumio[1018]: info: CorePlayQueue::getTrackBlock Jan 19 16:14:19 volumio volumio[1018]: info: CoreCommandRouter::volumioRetrievevolume Jan 19 16:14:19 volumio volumio[1018]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jan 19 16:14:19 volumio volumio[1018]: info: Reading ALSA contributions from plugins. Jan 19 16:14:19 volumio volumio[1018]: info: Asound.conf file unchanged, so no further update is needed Jan 19 16:14:19 volumio volumio[1018]: info: Output device has changed, restarting MPD Jan 19 16:14:19 volumio sudo[1209]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 19 16:14:19 volumio volumio[1018]: info: Output device has changed, restarting Shairport Sync Jan 19 16:14:19 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 16:14:19 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 19 16:14:19 volumio sudo[1209]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 16:14:19 volumio sudo[1209]: pam_unix(sudo:session): session closed for user root Jan 19 16:14:19 volumio sudo[1211]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 19 16:14:19 volumio sudo[1211]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 16:14:19 volumio systemd[1]: musicservicesshield.service: Succeeded. Jan 19 16:14:19 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Jan 19 16:14:19 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Jan 19 16:14:19 volumio systemd[1]: Stopping Music Player Daemon... Jan 19 16:14:19 volumio volumio[1018]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 19 16:14:19 volumio volumio[1018]: info: ___________ START PLUGINS ___________ Jan 19 16:14:19 volumio volumio[1018]: info: ControllerMpd::onStart: Initializing MPD Jan 19 16:14:19 volumio volumio[1018]: info: Creating MPD Configuration file Jan 19 16:14:19 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 19 16:14:19 volumio sudo[1218]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 19 16:14:19 volumio sudo[1218]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 16:14:19 volumio volumio[1018]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 19 16:14:19 volumio volumio[1018]: info: [1737299659424] CoreMusicLibrary::Adding element Media Servers Jan 19 16:14:19 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 19 16:14:19 volumio sudo[1218]: pam_unix(sudo:session): session closed for user root Jan 19 16:14:19 volumio sudo[1220]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 19 16:14:19 volumio sudo[1220]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 16:14:19 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 16:14:19 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 19 16:14:19 volumio volumio[1018]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 19 16:14:19 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 16:14:19 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 19 16:14:19 volumio systemd[1]: mpd.service: Succeeded. Jan 19 16:14:19 volumio systemd[1]: Stopped Music Player Daemon. Jan 19 16:14:19 volumio volumio[1018]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 19 16:14:19 volumio volumio[1018]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 19 16:14:19 volumio volumio[1018]: info: [1737299659513] CoreMusicLibrary::Adding element Last_100 Jan 19 16:14:19 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 19 16:14:19 volumio volumio[1018]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 19 16:14:19 volumio volumio[1018]: info: [1737299659515] CoreMusicLibrary::Adding element Webradio Jan 19 16:14:19 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 19 16:14:19 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 19 16:14:19 volumio volumio[1018]: info: Initializing BBC Radios Jan 19 16:14:19 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 19 16:14:19 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 19 16:14:19 volumio systemd[1]: Starting Music Player Daemon... Jan 19 16:14:19 volumio volumio[1018]: info: Loading i18n strings for locale en Jan 19 16:14:19 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jan 19 16:14:19 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 16:14:19 volumio volumio[1018]: info: FusionDsp - mixtype--------------------- Hardware Jan 19 16:14:19 volumio volumio[1018]: info: Configuring GPIO pins Jan 19 16:14:19 volumio volumio[1018]: error: Plugin audiophonicsonoff failed to start! Error: EINVAL: invalid argument, write Jan 19 16:14:19 volumio volumio[1018]: info: Loading i18n strings for locale en Jan 19 16:14:19 volumio kernel: export_store: invalid GPIO 4 Jan 19 16:14:19 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 19 16:14:19 volumio sudo[1229]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 19 16:14:19 volumio sudo[1229]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 16:14:19 volumio sudo[1229]: pam_unix(sudo:session): session closed for user root Jan 19 16:14:19 volumio volumio[1018]: info: Loading i18n strings for locale en Jan 19 16:14:19 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 19 16:14:19 volumio volumio[1018]: info: Volumio Calling Home Jan 19 16:14:19 volumio sudo[1268]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/moveallprocesses.sh Jan 19 16:14:19 volumio sudo[1268]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 16:14:19 volumio volumio[1018]: info: Preparing to generate the ALSA configuration file Jan 19 16:14:20 volumio sudo[1289]: volumio : TTY=unknown ; 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 Jan 19 16:14:20 volumio sudo[1289]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 16:14:20 volumio sudo[1289]: pam_unix(sudo:session): session closed for user root Jan 19 16:14:20 volumio sudo[1293]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Jan 19 16:14:20 volumio sudo[1293]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 16:14:20 volumio sudo[1294]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Jan 19 16:14:20 volumio sudo[1294]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 16:14:20 volumio sudo[1298]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jan 19 16:14:20 volumio sudo[1298]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 16:14:20 volumio sudo[1293]: pam_unix(sudo:session): session closed for user root Jan 19 16:14:20 volumio systemd[1]: Reloading. Jan 19 16:14:20 volumio volumio[1018]: info: Discovery: adding 1bcccc96-a10f-48f4-afd4-25bc1a559d13 Jan 19 16:14:20 volumio volumio[1018]: info: Discovery: Found device Volumio Jan 19 16:14:20 volumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jan 19 16:14:20 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jan 19 16:14:20 volumio volumio[1018]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jan 19 16:14:20 volumio volumio[1018]: info: Reading ALSA contributions from plugins. Jan 19 16:14:20 volumio volumio[1018]: info: touch_display: Backlight interface detected. Jan 19 16:14:20 volumio volumio[1018]: verbose: New Socket.io Connection to 192.168.178.31 from 192.168.178.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Jan 19 16:14:20 volumio volumio[1018]: info: touch_display: systemctl stop getty@tty1.service succeeded. Jan 19 16:14:20 volumio volumio[1018]: info: ir_controller: File permissions successfully set on /etc/lirc/*. Jan 19 16:14:20 volumio volumio[1018]: info: MPD Permissions set Jan 19 16:14:20 volumio volumio[1018]: info: MPD Permissions set Jan 19 16:14:20 volumio volumio[1018]: info: VolumeController:: Volume=25 Mute =false Jan 19 16:14:20 volumio volumio[1018]: info: CoreStateMachine::pushState Jan 19 16:14:20 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jan 19 16:14:20 volumio volumio[1018]: info: CoreCommandRouter::volumioPushState Jan 19 16:14:20 volumio volumio[1018]: info: Volumio called home Jan 19 16:14:20 volumio volumio[1018]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jan 19 16:14:20 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 16:14:20 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 16:14:20 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 16:14:20 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 19 16:14:20 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 16:14:20 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 16:14:20 volumio sudo[1268]: pam_unix(sudo:session): session closed for user root Jan 19 16:14:20 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 19 16:14:20 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 16:14:20 volumio volumio[1018]: info: touch_display: Raspberry Pi Foundation touch screen detected. Jan 19 16:14:20 volumio volumio[1018]: info: Starting Shairport Sync Jan 19 16:14:20 volumio sudo[1328]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Jan 19 16:14:20 volumio sudo[1328]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 16:14:20 volumio volumio[1018]: info: Starting Shairport Sync Jan 19 16:14:20 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jan 19 16:14:20 volumio mpd[1251]: Jan 19 16:14 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 19 16:14:20 volumio sudo[1326]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/addservice.sh Jan 19 16:14:20 volumio sudo[1326]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 16:14:20 volumio sudo[1330]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+w /sys/class/backlight/rpi_backlight/brightness Jan 19 16:14:20 volumio sudo[1330]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 16:14:20 volumio sudo[1330]: pam_unix(sudo:session): session closed for user root Jan 19 16:14:20 volumio volumio[1018]: info: Starting Shairport Sync Jan 19 16:14:20 volumio sudo[1333]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 19 16:14:20 volumio sudo[1333]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 16:14:20 volumio sudo[1338]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 19 16:14:20 volumio sudo[1328]: pam_unix(sudo:session): session closed for user root Jan 19 16:14:20 volumio sudo[1338]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 16:14:20 volumio sudo[1342]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 19 16:14:20 volumio sudo[1342]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 16:14:20 volumio volumio[1018]: info: ir_controller: LIRC correctly updated. Jan 19 16:14:20 volumio volumio[1018]: info: ir_controller: HAT did not load /proc/device-tree/ir_receiver! Jan 19 16:14:20 volumio volumio[1018]: info: touch_display: File permissions for backlight brightness control set. Jan 19 16:14:20 volumio volumio[1018]: info: Asound.conf file unchanged, so no further update is needed Jan 19 16:14:20 volumio volumio[1018]: info: Output device has changed, restarting MPD Jan 19 16:14:20 volumio sudo[1348]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 19 16:14:20 volumio sudo[1348]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 16:14:20 volumio volumio[1018]: info: Output device has changed, restarting Shairport Sync Jan 19 16:14:20 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 16:14:20 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 19 16:14:20 volumio sudo[1348]: pam_unix(sudo:session): session closed for user root Jan 19 16:14:20 volumio sudo[1350]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 19 16:14:20 volumio sudo[1350]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 16:14:20 volumio volumio[1018]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 19 16:14:20 volumio volumio[1018]: info: ir_controller: Raspberry Pi revision code: b03114 Jan 19 16:14:20 volumio volumio[1018]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Jan 19 16:14:20 volumio volumio[1018]: info: MPD Permissions set Jan 19 16:14:20 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 16:14:20 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 16:14:20 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 16:14:20 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 16:14:20 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 16:14:20 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 16:14:20 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 16:14:20 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 16:14:20 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 19 16:14:20 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 16:14:20 volumio volumio[1018]: info: Starting Shairport Sync Jan 19 16:14:20 volumio sudo[1367]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -r -1 Jan 19 16:14:20 volumio sudo[1367]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 16:14:20 volumio sudo[1367]: pam_unix(sudo:session): session closed for user root Jan 19 16:14:20 volumio sudo[1369]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 19 16:14:20 volumio sudo[1369]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 16:14:20 volumio sudo[1377]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay gpio-ir gpio_pin=25 gpio_pull=up invert=1 Jan 19 16:14:20 volumio sudo[1377]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 16:14:20 volumio kernel: Registered IR keymap rc-rc6-mce Jan 19 16:14:20 volumio sudo[1377]: pam_unix(sudo:session): session closed for user root Jan 19 16:14:20 volumio volumio[1018]: info: ir_controller: Overlay gpio-ir gpio_pin=25 gpio_pull=up invert=1 loaded. Jan 19 16:14:20 volumio kernel: IR RC6 protocol handler initialized Jan 19 16:14:20 volumio systemd[1]: Started Music Player Daemon. Jan 19 16:14:20 volumio sudo[1298]: pam_unix(sudo:session): session closed for user root Jan 19 16:14:20 volumio sudo[1211]: pam_unix(sudo:session): session closed for user root Jan 19 16:14:20 volumio sudo[1387]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart lircd.service Jan 19 16:14:20 volumio sudo[1387]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 16:14:20 volumio sudo[1220]: pam_unix(sudo:session): session closed for user root Jan 19 16:14:20 volumio kernel: rc rc0: gpio_ir_recv as /devices/platform/ir-receiver@19/rc/rc0 Jan 19 16:14:20 volumio kernel: rc rc0: lirc_dev: driver gpio_ir_recv registered at minor = 0, raw IR receiver, no transmitter Jan 19 16:14:20 volumio kernel: input: gpio_ir_recv as /devices/platform/ir-receiver@19/rc/rc0/input1 Jan 19 16:14:20 volumio volumio[1018]: info: touch_display: systemctl daemon-reload succeeded. Jan 19 16:14:20 volumio systemd[1]: Reloading. Jan 19 16:14:20 volumio sudo[1393]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Jan 19 16:14:20 volumio sudo[1393]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 16:14:21 volumio volumio[1018]: error: MPD error: The expression evaluated to a falsy value: Jan 19 16:14:21 volumio volumio[1018]: assert.ok(self.idling) Jan 19 16:14:21 volumio volumio[1018]: error: The expression evaluated to a falsy value: Jan 19 16:14:21 volumio volumio[1018]: assert.ok(self.idling) Jan 19 16:14:21 volumio volumio[1018]: info: MPD running with PID1251 Jan 19 16:14:21 volumio volumio[1018]: ,establishing connection Jan 19 16:14:21 volumio volumio[1018]: error: updateQueue error: null Jan 19 16:14:21 volumio volumio[1018]: error: updateQueue error: null Jan 19 16:14:21 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jan 19 16:14:21 volumio systemd-udevd[1105]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jan 19 16:14:21 volumio systemd-logind[620]: Watching system buttons on /dev/input/event1 (gpio_ir_recv) Jan 19 16:14:21 volumio sudo[1294]: pam_unix(sudo:session): session closed for user root Jan 19 16:14:21 volumio volumio[1018]: info: touch_display: systemctl disable getty@tty1.service succeeded. Jan 19 16:14:21 volumio systemd[1]: Started Volumio Kiosk. Jan 19 16:14:21 volumio systemd[1]: Starting lircd(8) initialization helper tool... Jan 19 16:14:21 volumio systemd[1]: Stopping Music Player Daemon... Jan 19 16:14:21 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 19 16:14:21 volumio systemd[1]: shairport-sync.service: Succeeded. Jan 19 16:14:21 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 19 16:14:21 volumio sudo[1393]: pam_unix(sudo:session): session closed for user root Jan 19 16:14:21 volumio volumio[1018]: info: camilladsp spawned new process with pid 1424, instance 1, run: true Jan 19 16:14:21 volumio volumio[1018]: info: camilladsp service started and running in background, instance 1 Jan 19 16:14:21 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 16:14:21 volumio volumio[1018]: info: FusionDsp - AAAAAAAAAAAAAA-> 22050 44100 48000 88200 96000 176400 192000 384000 <-AAAAAAAAAAAAA Jan 19 16:14:21 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 19 16:14:21 volumio sudo[1333]: pam_unix(sudo:session): session closed for user root Jan 19 16:14:21 volumio sudo[1342]: pam_unix(sudo:session): session closed for user root Jan 19 16:14:21 volumio sudo[1369]: pam_unix(sudo:session): session closed for user root Jan 19 16:14:21 volumio systemd[1]: mpd.service: Succeeded. Jan 19 16:14:21 volumio systemd[1]: Stopped Music Player Daemon. Jan 19 16:14:21 volumio volumio[1018]: info: FusionDsp loaded Jan 19 16:14:21 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 19 16:14:21 volumio sudo[1338]: pam_unix(sudo:session): session closed for user root Jan 19 16:14:21 volumio volumio[1018]: info: FusionDsp - Reporting Fusion DSP Enabled Jan 19 16:14:21 volumio volumio[1018]: info: Adding Signal Path Element [object Object] Jan 19 16:14:21 volumio volumio[1018]: info: Adding fusiondspeq DSP Signal Path Element Jan 19 16:14:21 volumio systemd[1]: Starting Music Player Daemon... Jan 19 16:14:21 volumio volumio[1018]: info: FusionDsp - ---- installed callbackRead Jan 19 16:14:21 volumio sudo[1438]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jan 19 16:14:21 volumio sudo[1438]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 16:14:21 volumio volumio[1018]: info: Shairport-Sync Started Jan 19 16:14:21 volumio volumio[1018]: Error adding Membership: Error: addMembership EINVAL Jan 19 16:14:21 volumio volumio[1018]: info: Shairport-Sync Started Jan 19 16:14:21 volumio volumio[1018]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Jan 19 16:14:21 volumio volumio[1018]: info: touch_display: Volumio Kiosk started. Jan 19 16:14:21 volumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jan 19 16:14:21 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jan 19 16:14:21 volumio volumio[1018]: info: Completed starting Core Plugins Jan 19 16:14:21 volumio volumio[1018]: info: ------------------------------------------- Jan 19 16:14:21 volumio volumio[1018]: info: ----- MyVolumio plugins startup ---- Jan 19 16:14:21 volumio volumio[1018]: info: ------------------------------------------- Jan 19 16:14:21 volumio volumio[1018]: info: [MyVolumio PluginManager] Fetching plans data.... Jan 19 16:14:21 volumio volumio[1018]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jan 19 16:14:21 volumio sudo[1440]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 19 16:14:21 volumio sudo[1440]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 16:14:21 volumio volumio[1018]: info: FusionDsp - Loudness is ON true Jan 19 16:14:21 volumio sudo[1440]: pam_unix(sudo:session): session closed for user root Jan 19 16:14:21 volumio sudo[1465]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart lircd.service Jan 19 16:14:21 volumio sudo[1465]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 16:14:21 volumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jan 19 16:14:21 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jan 19 16:14:21 volumio systemd[1]: Started FusionDsp Daemon. Jan 19 16:14:21 volumio sudo[1438]: pam_unix(sudo:session): session closed for user root Jan 19 16:14:21 volumio volumio[1018]: info: touch_display: X display number found: 0 Jan 19 16:14:21 volumio startx[1414]: X.Org X Server 1.20.4 Jan 19 16:14:21 volumio startx[1414]: X Protocol Version 11, Revision 0 Jan 19 16:14:21 volumio startx[1414]: Build Operating System: Linux 5.4.0-109-generic armv8l Raspbian Jan 19 16:14:21 volumio startx[1414]: Current Operating System: Linux volumio 6.6.62-v7l+ #1816 SMP Mon Nov 18 15:33:33 GMT 2024 armv7l Jan 19 16:14:21 volumio startx[1414]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory numa_policy=interleave snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=800 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 smsc95xx.macaddr=E4:5F:01:34:23:E0 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=UUID=ed280c2b-b4b4-43e6-87d7-b67377eacab2 imgfile=/volumio_current.sqsh bootpart=UUID=FCA2-3F9A datapart=UUID=0367e456-8c2f-458a-8465-47f124ad9fdd uuidconfig=cmdline.txt pcie_aspm=off pci=pcie_bus_safe rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa= snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Jan 19 16:14:21 volumio startx[1414]: Build Date: 04 April 2023 07:50:56AM Jan 19 16:14:21 volumio startx[1414]: xorg-server 2:1.20.4-1+rpt4+deb10u9 (https://www.debian.org/support) Jan 19 16:14:21 volumio startx[1414]: Current version of pixman: 0.36.0 Jan 19 16:14:21 volumio startx[1414]: Before reporting problems, check http://wiki.x.org Jan 19 16:14:21 volumio startx[1414]: to make sure that you have the latest version. Jan 19 16:14:21 volumio startx[1414]: Markers: (--) probed, (**) from config file, (==) default setting, Jan 19 16:14:21 volumio startx[1414]: (++) from command line, (!!) notice, (II) informational, Jan 19 16:14:21 volumio startx[1414]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Jan 19 16:14:21 volumio startx[1414]: (==) Log file: "/var/log/Xorg.0.log", Time: Sun Jan 19 16:14:21 2025 Jan 19 16:14:21 volumio startx[1414]: (==) Using config directory: "/etc/X11/xorg.conf.d" Jan 19 16:14:21 volumio startx[1414]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Jan 19 16:14:22 volumio volumio[1018]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Jan 19 16:14:22 volumio volumio[1018]: info: touch_display: X display number found: 0 Jan 19 16:14:22 volumio lircd-setup[1416]: /usr/sbin/lircd-setup:37: DeprecationWarning: The SafeConfigParser class has been renamed to ConfigParser in Python 3.2. This alias will be removed in future versions. Use ConfigParser directly instead. Jan 19 16:14:22 volumio lircd-setup[1416]: parser = configparser.SafeConfigParser() Jan 19 16:14:22 volumio systemd[1]: lircd-setup.service: Succeeded. Jan 19 16:14:22 volumio systemd[1]: Started lircd(8) initialization helper tool. Jan 19 16:14:22 volumio systemd[1]: Starting Flexible IR remote input/output application support... Jan 19 16:14:22 volumio lircd-0.10.1[1472]: Info: lircd: Opening log, level: Info Jan 19 16:14:22 volumio lircd-0.10.1[1472]: Notice: Version: lircd 0.10.1 Jan 19 16:14:22 volumio lircd-0.10.1[1472]: Notice: System info: Linux volumio 6.6.62-v7l+ #1816 SMP Mon Nov 18 15:33:33 GMT 2024 armv7l GNU/Linux Jan 19 16:14:22 volumio lircd-0.10.1[1472]: Info: Initial device: /dev/lirc0 Jan 19 16:14:22 volumio lircd-0.10.1[1472]: Notice: 'lirc' written to protocols file /sys/class/rc/rc0/protocols Jan 19 16:14:22 volumio lircd-0.10.1[1472]: Info: Initial device: /dev/lirc0 Jan 19 16:14:22 volumio lircd-0.10.1[1472]: Info: [lirc] protocol is enabled Jan 19 16:14:22 volumio lircd-0.10.1[1472]: Notice: Options: driver: default Jan 19 16:14:22 volumio lircd[1472]: lircd-0.10.1[1472]: Info: lircd: Opening log, level: Info Jan 19 16:14:22 volumio lircd[1472]: lircd-0.10.1[1472]: Notice: Using systemd fd Jan 19 16:14:22 volumio lircd-0.10.1[1472]: Notice: Options: output: /var/run/lirc/lircd Jan 19 16:14:22 volumio lircd[1472]: lircd-0.10.1[1472]: Warning: Running as root Jan 19 16:14:22 volumio lircd[1472]: lircd-0.10.1[1472]: Info: Using remote: Mimo. Jan 19 16:14:22 volumio lircd-0.10.1[1472]: Notice: Options: nodaemon: 1 Jan 19 16:14:22 volumio lircd[1472]: lircd-0.10.1[1472]: Notice: lircd(default) ready, using /var/run/lirc/lircd Jan 19 16:14:22 volumio lircd-0.10.1[1472]: Notice: Options: plugindir: /usr/lib/arm-linux-gnueabihf/lirc/plugins Jan 19 16:14:22 volumio lircd-0.10.1[1472]: Notice: Options: logfile: syslog Jan 19 16:14:22 volumio lircd-0.10.1[1472]: Notice: Options: immediate-init: 0 Jan 19 16:14:22 volumio lircd-0.10.1[1472]: Notice: Options: permission: 666 Jan 19 16:14:22 volumio lircd-0.10.1[1472]: Notice: Options: driver-options: Jan 19 16:14:22 volumio lircd-0.10.1[1472]: Notice: Options: pidfile: /var/run/lirc/lircd.pid Jan 19 16:14:22 volumio lircd-0.10.1[1472]: Notice: Options: listen: 0 Jan 19 16:14:22 volumio lircd-0.10.1[1472]: Notice: Options: connect: (null) Jan 19 16:14:22 volumio systemd[1]: Started Flexible IR remote input/output application support. Jan 19 16:14:22 volumio lircd-0.10.1[1472]: Notice: Options: userelease: 0 Jan 19 16:14:22 volumio lircd-0.10.1[1472]: Notice: Options: effective_user: (null) Jan 19 16:14:22 volumio lircd-0.10.1[1472]: Notice: Options: release_suffix: _EVUP Jan 19 16:14:22 volumio lircd-0.10.1[1472]: Notice: Options: allow_simulate: 0 Jan 19 16:14:22 volumio lircd-0.10.1[1472]: Notice: Options: repeat_max: 600 Jan 19 16:14:22 volumio lircd-0.10.1[1472]: Notice: Options: configfile: /etc/lirc/lircd.conf Jan 19 16:14:22 volumio lircd-0.10.1[1472]: Notice: Options: dynamic_codes: (null) Jan 19 16:14:22 volumio lircd-0.10.1[1472]: Notice: Current driver: default Jan 19 16:14:22 volumio lircd-0.10.1[1472]: Notice: Driver API version: 3 Jan 19 16:14:22 volumio lircd-0.10.1[1472]: Notice: Driver version: 0.10.0 Jan 19 16:14:22 volumio lircd-0.10.1[1472]: Notice: Driver info: See file:///usr/share/doc/lirc/plugindocs/default.html Jan 19 16:14:22 volumio lircd-0.10.1[1472]: Info: lircd: Opening log, level: Info Jan 19 16:14:22 volumio lircd-0.10.1[1472]: Notice: Using systemd fd Jan 19 16:14:22 volumio lircd-0.10.1[1472]: Warning: Running as root Jan 19 16:14:22 volumio lircd-0.10.1[1472]: Info: Using remote: Mimo. Jan 19 16:14:22 volumio lircd-0.10.1[1472]: Notice: lircd(default) ready, using /var/run/lirc/lircd Jan 19 16:14:22 volumio sudo[1387]: pam_unix(sudo:session): session closed for user root Jan 19 16:14:22 volumio sudo[1465]: pam_unix(sudo:session): session closed for user root Jan 19 16:14:22 volumio volumio[1018]: info: ir_controller: systemctl restart lircd.service succeeded. Jan 19 16:14:22 volumio volumio[1018]: info: ir_controller: systemctl restart lircd.service succeeded. Jan 19 16:14:22 volumio sudo[1476]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart irexec.service Jan 19 16:14:22 volumio sudo[1476]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 16:14:22 volumio sudo[1478]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart irexec.service Jan 19 16:14:22 volumio sudo[1478]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 16:14:22 volumio systemd[1]: Started Handle events from IR remotes decoded by lircd(8). Jan 19 16:14:22 volumio sudo[1478]: pam_unix(sudo:session): session closed for user root Jan 19 16:14:22 volumio volumio[1018]: info: ir_controller: systemctl restart irexec.service succeeded. Jan 19 16:14:22 volumio lircd[1472]: lircd-0.10.1[1472]: Notice: accepted new client on /var/run/lirc/lircd Jan 19 16:14:22 volumio lircd[1472]: lircd-0.10.1[1472]: Info: [lirc] protocol is enabled Jan 19 16:14:22 volumio lircd-0.10.1[1472]: Notice: accepted new client on /var/run/lirc/lircd Jan 19 16:14:22 volumio lircd-0.10.1[1472]: Info: [lirc] protocol is enabled Jan 19 16:14:22 volumio sudo[1476]: pam_unix(sudo:session): session closed for user root Jan 19 16:14:22 volumio volumio[1018]: info: ir_controller: systemctl restart irexec.service succeeded. Jan 19 16:14:22 volumio mpd[1463]: Jan 19 16:14 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 19 16:14:23 volumio systemd[1]: Started Music Player Daemon. Jan 19 16:14:23 volumio sudo[1350]: pam_unix(sudo:session): session closed for user root Jan 19 16:14:23 volumio volumio[1018]: error: updateQueue error: null Jan 19 16:14:23 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Jan 19 16:14:23 volumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jan 19 16:14:23 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jan 19 16:14:23 volumio volumio[1492]: cset: --> shielding system active with Jan 19 16:14:23 volumio volumio[1492]: cset: "system" cpuset of CPUSPEC(1-3) with 130 tasks running Jan 19 16:14:23 volumio volumio[1492]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Jan 19 16:14:23 volumio volumio[1018]: info: touch_display: Setting screensaver timeout to 300 seconds. Jan 19 16:14:23 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Jan 19 16:14:23 volumio systemd[1]: Reloading. Jan 19 16:14:23 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jan 19 16:14:24 volumio wpa_supplicant[887]: RRM: Ignoring radio measurement request: Not RRM network Jan 19 16:14:24 volumio sudo[1326]: pam_unix(sudo:session): session closed for user root Jan 19 16:14:24 volumio volumio[1018]: info: succeeded Jan 19 16:14:24 volumio volumio[1018]: verbose: New Socket.io Connection to 192.168.178.31 from 192.168.178.51 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Jan 19 16:14:25 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 19 16:14:25 volumio volumio[1018]: info: CoreCommandRouter::volumioGetVisibleSources Jan 19 16:14:25 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 19 16:14:25 volumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jan 19 16:14:25 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jan 19 16:14:25 volumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jan 19 16:14:25 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jan 19 16:14:25 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 19 16:14:25 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 19 16:14:25 volumio volumio[1018]: info: Received Get System Info Jan 19 16:14:25 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 19 16:14:25 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 19 16:14:25 volumio volumio[1018]: info: Discovery: Getting this device information Jan 19 16:14:25 volumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jan 19 16:14:25 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jan 19 16:14:25 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 19 16:14:25 volumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jan 19 16:14:25 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jan 19 16:14:25 volumio volumio[1018]: info: Listing playlists Jan 19 16:14:25 volumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jan 19 16:14:25 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jan 19 16:14:25 volumio volumio[1018]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jan 19 16:14:25 volumio volumio[1018]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jan 19 16:14:25 volumio volumio[1018]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 19 16:14:25 volumio sudo[1610]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 19 16:14:25 volumio sudo[1610]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 16:14:25 volumio sudo[1610]: pam_unix(sudo:session): session closed for user root Jan 19 16:14:25 volumio sudo[1612]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 19 16:14:25 volumio sudo[1612]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 16:14:25 volumio sudo[1612]: pam_unix(sudo:session): session closed for user root Jan 19 16:14:25 volumio sudo[1616]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 19 16:14:25 volumio sudo[1616]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 16:14:25 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Jan 19 16:14:25 volumio sudo[1616]: pam_unix(sudo:session): session closed for user root Jan 19 16:14:25 volumio volumio[1018]: info: Upmpdcli Daemon Started Jan 19 16:14:25 volumio volumio[1618]: Generating RSA private key, 4096 bit long modulus (2 primes) Jan 19 16:14:27 volumio kernel: hwmon hwmon1: Undervoltage detected! Jan 19 16:14:33 volumio wpa_supplicant[887]: RRM: Ignoring radio measurement request: Not RRM network Jan 19 16:14:33 volumio volumio[1618]: .............................................................++++ Jan 19 16:14:34 volumio volumio[1018]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Jan 19 16:14:34 volumio kernel: hwmon hwmon1: Voltage normalised Jan 19 16:14:35 volumio volumio[1018]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Jan 19 16:14:35 volumio volumio[1018]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jan 19 16:14:35 volumio volumio[1018]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jan 19 16:14:35 volumio volumio[1018]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jan 19 16:14:35 volumio volumio[1018]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jan 19 16:14:35 volumio volumio[1018]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jan 19 16:14:35 volumio volumio[1018]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jan 19 16:14:35 volumio volumio[1018]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jan 19 16:14:35 volumio volumio[1018]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jan 19 16:14:35 volumio volumio[1018]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jan 19 16:14:35 volumio volumio[1018]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jan 19 16:14:35 volumio volumio[1018]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jan 19 16:14:35 volumio volumio[1018]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jan 19 16:14:35 volumio volumio[1018]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jan 19 16:14:35 volumio volumio[1018]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jan 19 16:14:35 volumio volumio[1018]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jan 19 16:14:35 volumio volumio[1018]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jan 19 16:14:35 volumio volumio[1018]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jan 19 16:14:35 volumio volumio[1018]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jan 19 16:14:35 volumio volumio[1018]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jan 19 16:14:35 volumio volumio[1018]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jan 19 16:14:35 volumio volumio[1018]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jan 19 16:14:35 volumio volumio[1018]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jan 19 16:14:35 volumio volumio[1018]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jan 19 16:14:35 volumio volumio[1018]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jan 19 16:14:35 volumio volumio[1018]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jan 19 16:14:35 volumio volumio[1018]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jan 19 16:14:35 volumio volumio[1018]: info: Adding plugin bluetooth to MyMusic Plugins Jan 19 16:14:35 volumio volumio[1018]: info: Adding plugin multiroom to MyMusic Plugins Jan 19 16:14:35 volumio volumio[1018]: info: Adding plugin metavolumio to MyMusic Plugins Jan 19 16:14:35 volumio volumio[1018]: info: Adding plugin cd_controller to MyMusic Plugins Jan 19 16:14:35 volumio volumio[1018]: info: Adding plugin smart_inputs to MyMusic Plugins Jan 19 16:14:35 volumio volumio[1018]: info: Adding plugin tidalconnect to MyMusic Plugins Jan 19 16:14:35 volumio volumio[1018]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jan 19 16:14:36 volumio kernel: hwmon hwmon1: Undervoltage detected! Jan 19 16:14:37 volumio volumio[1018]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jan 19 16:14:37 volumio volumio[1018]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jan 19 16:14:37 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 19 16:14:37 volumio volumio[1018]: info: Starting MyVolumio Remote Streaming Endpoints Jan 19 16:14:37 volumio volumio[1018]: info: MyVolumio login type: Token Jan 19 16:14:37 volumio volumio[1018]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jan 19 16:14:37 volumio volumio[1018]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jan 19 16:14:39 volumio volumio[1018]: info: Starting Streaming Service Transparent Proxy Jan 19 16:14:39 volumio volumio[1018]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jan 19 16:14:39 volumio volumio[1018]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jan 19 16:14:39 volumio volumio[1018]: info: Streaming services startup Jan 19 16:14:39 volumio volumio[1018]: info: Starting Streaming Daemon Jan 19 16:14:39 volumio volumio[1018]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jan 19 16:14:39 volumio sudo[1769]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 19 16:14:39 volumio sudo[1769]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 16:14:39 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 19 16:14:39 volumio sudo[1769]: pam_unix(sudo:session): session closed for user root Jan 19 16:14:39 volumio volumio[1018]: error: Cannot start Volumio Streaming Daemon Jan 19 16:14:39 volumio volumio[1018]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 19 16:14:39 volumio volumio[1018]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 19 16:14:39 volumio volumio[1018]: info: CoreCommandRouter::volumioGetVisibleSources Jan 19 16:14:39 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 19 16:14:39 volumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jan 19 16:14:39 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jan 19 16:14:39 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 19 16:14:39 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 19 16:14:39 volumio volumio[1018]: info: Received Get System Info Jan 19 16:14:39 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 19 16:14:39 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 19 16:14:39 volumio volumio[1018]: info: Discovery: Getting this device information Jan 19 16:14:39 volumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jan 19 16:14:39 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jan 19 16:14:39 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 19 16:14:39 volumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jan 19 16:14:39 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jan 19 16:14:39 volumio volumio[1018]: info: Listing playlists Jan 19 16:14:39 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 19 16:14:39 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 19 16:14:39 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 19 16:14:39 volumio volumio[1018]: info: Received Get System Info Jan 19 16:14:39 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 19 16:14:39 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 19 16:14:39 volumio volumio[1018]: info: Discovery: Getting this device information Jan 19 16:14:39 volumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jan 19 16:14:39 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jan 19 16:14:39 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 19 16:14:39 volumio wpa_supplicant[887]: RRM: Ignoring radio measurement request: Not RRM network Jan 19 16:14:39 volumio volumio[1018]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 19 16:14:40 volumio volumio[1018]: STREAMING PROXY: Starting server on port 3245 Jan 19 16:14:40 volumio volumio[1018]: Node JS runtime: 14 Jan 19 16:14:41 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jan 19 16:14:41 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 19 16:14:42 volumio systemd[1]: systemd-timedated.service: Succeeded. Jan 19 16:14:42 volumio volumio[1018]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jan 19 16:14:42 volumio kernel: hwmon hwmon1: Voltage normalised Jan 19 16:14:43 volumio volumio[1018]: info: MyVolumio token set successfully Jan 19 16:14:43 volumio volumio[1018]: info: MYVOLUMIO: Adding device Jan 19 16:14:43 volumio volumio[1018]: info: MYVOLUMIO: Evaluating Server Jan 19 16:14:43 volumio volumio[1018]: info: MyVolumio status changed Jan 19 16:14:43 volumio volumio[1018]: info: Streaming services startup Jan 19 16:14:43 volumio volumio[1018]: info: Starting Streaming Daemon Jan 19 16:14:43 volumio volumio[1018]: info: Removing browser output: myVolumio user plan is not superstar Jan 19 16:14:43 volumio volumio[1018]: info: Removing audio output: Jan 19 16:14:43 volumio volumio[1018]: info: Stoppping Tunnel 1 Jan 19 16:14:43 volumio sudo[1805]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 19 16:14:43 volumio sudo[1805]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 16:14:43 volumio sudo[1808]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jan 19 16:14:43 volumio sudo[1808]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 16:14:43 volumio sudo[1805]: pam_unix(sudo:session): session closed for user root Jan 19 16:14:43 volumio volumio[1018]: error: Cannot start Volumio Streaming Daemon Jan 19 16:14:43 volumio volumio[1018]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 19 16:14:43 volumio volumio[1018]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 19 16:14:43 volumio sudo[1808]: pam_unix(sudo:session): session closed for user root Jan 19 16:14:43 volumio volumio[1018]: info: Remote SSH Stopped Jan 19 16:14:43 volumio volumio[1018]: info: Setting Geolocation for MyVolumio to eu6 Jan 19 16:14:43 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 19 16:14:44 volumio volumio[1618]: ............................................................................................++++ Jan 19 16:14:44 volumio volumio[1618]: e is 65537 (0x010001) Jan 19 16:14:44 volumio volumio[1618]: writing RSA key Jan 19 16:14:44 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 19 16:14:44 volumio volumio[1018]: info: Received Get System Info Jan 19 16:14:44 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 19 16:14:44 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 19 16:14:44 volumio volumio[1018]: info: Discovery: Getting this device information Jan 19 16:14:44 volumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jan 19 16:14:44 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jan 19 16:14:44 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 19 16:14:44 volumio volumio[1018]: info: Successfully Added MyVolumio device Jan 19 16:14:44 volumio volumio[1018]: info: Updating MyVolumio device info Jan 19 16:14:44 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 19 16:14:45 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 19 16:14:45 volumio volumio[1018]: info: Successfully Updated MyVolumio device Jan 19 16:14:45 volumio wpa_supplicant[887]: RRM: Ignoring radio measurement request: Not RRM network Jan 19 16:14:49 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 19 16:14:49 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 19 16:14:49 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 19 16:14:49 volumio volumio-remote-updater[626]: Test mode enabled Jan 19 16:14:49 volumio volumio-remote-updater[626]: No alpha test mode Jan 19 16:14:49 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jan 19 16:14:49 volumio volumio[1018]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jan 19 16:14:49 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jan 19 16:14:52 volumio ntpd[786]: Soliciting pool server 195.201.19.162 Jan 19 16:14:52 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 19 16:14:52 volumio volumio[1018]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jan 19 16:14:52 volumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jan 19 16:14:52 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jan 19 16:14:53 volumio ntpd[786]: Soliciting pool server 176.9.44.212 Jan 19 16:14:53 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 19 16:14:53 volumio volumio[1018]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jan 19 16:14:53 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jan 19 16:14:53 volumio volumio[1018]: info: Received Get System Version Jan 19 16:14:53 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 19 16:14:53 volumio volumio[1018]: info: Received Get System Info Jan 19 16:14:53 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 19 16:14:53 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 19 16:14:53 volumio volumio[1018]: info: Discovery: Getting this device information Jan 19 16:14:53 volumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jan 19 16:14:53 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jan 19 16:14:53 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 19 16:14:54 volumio ntpd[786]: Soliciting pool server 79.133.44.137 Jan 19 16:14:54 volumio ntpd[786]: Soliciting pool server 213.239.234.28 Jan 19 16:14:54 volumio ntpd[786]: Soliciting pool server 85.215.166.214 Jan 19 16:14:55 volumio wpa_supplicant[887]: RRM: Ignoring radio measurement request: Not RRM network Jan 19 16:14:55 volumio ntpd[786]: Soliciting pool server 168.119.211.223 Jan 19 16:14:55 volumio ntpd[786]: Soliciting pool server 31.209.85.242 Jan 19 16:14:55 volumio ntpd[786]: Soliciting pool server 62.75.236.38 Jan 19 16:14:56 volumio ntpd[786]: Soliciting pool server 116.202.171.176 Jan 19 16:14:56 volumio ntpd[786]: Soliciting pool server 116.202.100.11 Jan 19 16:14:57 volumio ntpd[786]: Soliciting pool server 2606:4700:f1::1 Jan 19 16:14:57 volumio ntpd[786]: Soliciting pool server 81.169.204.195 Jan 19 16:14:57 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 16:14:57 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jan 19 16:14:57 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jan 19 16:14:57 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jan 19 16:14:57 volumio volumio[1018]: info: CoreCommandRouter::volumioGetBrowseSources Jan 19 16:14:57 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 19 16:14:58 volumio ntpd[786]: Soliciting pool server 173.249.58.145 Jan 19 16:14:59 volumio ntpd[786]: Soliciting pool server 176.9.157.155 Jan 19 16:14:59 volumio volumio[1018]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jan 19 16:14:59 volumio volumio[1018]: info: Completed starting MyVolumio Plugin Jan 19 16:14:59 volumio volumio[1018]: [Metrics] CommandRouter: 46s 135.84ms Jan 19 16:14:59 volumio volumio[1018]: info: CoreCommandRouter::volumiosetStartupVolume Jan 19 16:14:59 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 19 16:14:59 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 19 16:14:59 volumio volumio[1018]: info: CoreCommandRouter::Close All Modals sent Jan 19 16:14:59 volumio volumio[1018]: info: CoreCommandRouter::Close All Modals sent Jan 19 16:14:59 volumio volumio[1018]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jan 19 16:14:59 volumio volumio[1018]: info: FusionDsp - ---- read samplerate from file: 44100 Jan 19 16:14:59 volumio volumio[1018]: info: camilladsp stopping service pid 1424... Jan 19 16:14:59 volumio volumio[1018]: info: camilladsp service terminated, instance 1 Jan 19 16:14:59 volumio volumio[1018]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jan 19 16:14:59 volumio volumio[1018]: info: FusionDsp - Loudness is ON true Jan 19 16:14:59 volumio volumio[1018]: info: camilladsp service started and running in background, instance 1 Jan 19 16:14:59 volumio volumio[1018]: info: camilladsp spawned new process with pid 2087, instance 1, run: true Jan 19 16:15:00 volumio ntpd[786]: Soliciting pool server 131.188.3.222 Jan 19 16:15:00 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jan 19 16:15:00 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 19 16:15:00 volumio volumio[1018]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jan 19 16:15:01 volumio ntpd[786]: Soliciting pool server 78.47.56.71 Jan 19 16:15:05 volumio wpa_supplicant[887]: RRM: Ignoring radio measurement request: Not RRM network Jan 19 16:15:05 volumio volumio[1018]: info: camilladsp spawned new process with pid 2097, instance 1, run: true Jan 19 16:15:07 volumio volumio[1018]: info: BOOT COMPLETED Jan 19 16:15:14 volumio wpa_supplicant[887]: RRM: Ignoring radio measurement request: Not RRM network Jan 19 16:15:23 volumio wpa_supplicant[887]: RRM: Ignoring radio measurement request: Not RRM network Jan 19 16:15:33 volumio wpa_supplicant[887]: RRM: Ignoring radio measurement request: Not RRM network Jan 19 16:15:42 volumio wpa_supplicant[887]: RRM: Ignoring radio measurement request: Not RRM network Jan 19 16:15:49 volumio volumio[1018]: info: CoreCommandRouter::volumioGetState Jan 19 16:15:49 volumio volumio[1018]: info: CorePlayQueue::getTrack 0 Jan 19 16:15:51 volumio wpa_supplicant[887]: RRM: Ignoring radio measurement request: Not RRM network Jan 19 16:15:57 volumio volumio[1018]: info: ___________ PLUGINS: Run Shutdown Tasks ___________ Jan 19 16:15:57 volumio volumio[1018]: info: PLUGIN onShutdown : networkfs Jan 19 16:15:57 volumio sudo[2247]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /mnt/NAS/HC2 Jan 19 16:15:57 volumio sudo[2247]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 19 16:15:57 volumio systemd[1]: mnt-NAS-HC2.mount: Succeeded. Jan 19 16:15:57 volumio sudo[2247]: pam_unix(sudo:session): session closed for user root Jan 19 16:15:57 volumio volumio[1018]: info: PLUGIN onShutdown : audiophonicsonoff Jan 19 16:15:57 volumio volumio[1018]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 19 16:15:57 volumio volumio[1018]: TypeError: Cannot read property 'writeSync' of undefined Jan 19 16:15:57 volumio volumio[1018]: at ControllerAudiophonicsOnOff.onVolumioShutdown (/data/plugins/system_controller/audiophonicsonoff/index.js:43:25) Jan 19 16:15:57 volumio volumio[1018]: at PluginManager.onVolumioShutdownPlugin (/volumio/app/pluginmanager.js:638:30) Jan 19 16:15:57 volumio volumio[1018]: at HashMap. (/volumio/app/pluginmanager.js:621:31) Jan 19 16:15:57 volumio volumio[1018]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jan 19 16:15:57 volumio volumio[1018]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jan 19 16:15:57 volumio volumio[1018]: at PluginManager.onVolumioShutdown (/volumio/app/pluginmanager.js:619:20) Jan 19 16:15:57 volumio volumio[1018]: at CoreCommandRouter.shutdown (/volumio/app/index.js:1332:24) Jan 19 16:15:57 volumio volumio[1018]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:859:33) Jan 19 16:15:57 volumio volumio[1018]: at Socket.emit (events.js:315:20) Jan 19 16:15:57 volumio volumio[1018]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jan 19 16:15:57 volumio volumio[1018]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Jan 19 16:15:57 volumio volumio[1018]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 19 16:15:58 volumio sudo[2259]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-19 16:14 Jan 19 16:15:58 volumio sudo[2259]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="cc0042a1eab1cb7982711a1347e242f8947b54b6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="d316d397153fbb98c246d670c12f98a120b995a2" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 11 Jan 2025 10:58:50 AM CET" VOLUMIO_VERSION="3.788" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e6f1869148b01453dc48d3e2d79d1421"