Nov 03 09:46:36 amp ntpd[927]: CLOCK: time stepped by 927074.530100 Nov 03 09:46:36 amp ntpd[927]: CLOCK: time changed from 2025-10-23 to 2025-11-03 Nov 03 09:46:36 amp ntpd[927]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Nov 03 09:46:36 amp volumio[1194]: info: Loading plugin "i2s_dacs"... Nov 03 09:46:36 amp volumio[1194]: info: Loading plugin "volumiodiscovery"... Nov 03 09:46:36 amp volumio[1194]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 03 09:46:36 amp node[1194]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 03 09:46:36 amp volumio[1194]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 03 09:46:36 amp node[1194]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 03 09:46:36 amp volumio[1194]: *** WARNING *** For more information see Nov 03 09:46:36 amp node[1194]: *** WARNING *** For more information see Nov 03 09:46:36 amp volumio[1194]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 03 09:46:36 amp node[1194]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 03 09:46:36 amp volumio[1194]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 03 09:46:36 amp node[1194]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 03 09:46:36 amp volumio[1194]: *** WARNING *** For more information see Nov 03 09:46:36 amp node[1194]: *** WARNING *** For more information see Nov 03 09:46:36 amp volumio[1194]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 03 09:46:36 amp volumio[1194]: info: Discovery: Started advertising with name: amp Nov 03 09:46:36 amp volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 03 09:46:36 amp volumio[1194]: info: Loading plugin "spop"... Nov 03 09:46:37 amp systemd[1]: systemd-fsckd.service: Deactivated successfully. Nov 03 09:46:37 amp volumio[1194]: info: Loading plugin "outputs"... Nov 03 09:46:37 amp volumio[1194]: info: Loading plugin "albumart"... Nov 03 09:46:37 amp volumio[1194]: info: Plugin example_plugin is not enabled Nov 03 09:46:37 amp volumio[1194]: info: Loading plugin "inputs"... Nov 03 09:46:37 amp volumio[1194]: info: Loading plugin "updater_comm"... Nov 03 09:46:37 amp systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service... Nov 03 09:46:37 amp systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats. Nov 03 09:46:37 amp systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully. Nov 03 09:46:37 amp systemd[1]: dpkg-db-backup.service: Deactivated successfully. Nov 03 09:46:37 amp systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service. Nov 03 09:46:37 amp volumio[1194]: info: Plugin mpdemulation is not enabled Nov 03 09:46:37 amp volumio[1194]: info: Loading plugin "rest_api"... Nov 03 09:46:37 amp volumio[1194]: info: Loading plugin "websocket"... Nov 03 09:46:37 amp volumio[1194]: info: Starting Socket.io Server version 1.7.4 Nov 03 09:46:38 amp volumio[1194]: info: Loading plugin "es9038procontroller"... Nov 03 09:46:38 amp volumio[1332]: Forking 3 albumart workers Nov 03 09:46:38 amp dhcpcd[606]: timed out Nov 03 09:46:38 amp sh[603]: timed out Nov 03 09:46:38 amp sh[551]: ifup: failed to bring up eth0 Nov 03 09:46:38 amp systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Nov 03 09:46:38 amp systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Nov 03 09:46:38 amp sudo[1264]: pam_unix(sudo:session): session closed for user root Nov 03 09:46:38 amp volumio[1194]: info: ES9038PROController::constructor Nov 03 09:46:38 amp volumio[1194]: info: Loading plugin "Bluetoothremote"... Nov 03 09:46:39 amp systemd[1]: systemd-hostnamed.service: Deactivated successfully. Nov 03 09:46:39 amp volumio[1194]: info: Applying required configuration parameters for plugin Bluetoothremote Nov 03 09:46:39 amp volumio[1194]: info: Loading plugin "audiophonics_evo_sabre"... Nov 03 09:46:40 amp volumio[1194]: info: Applying required configuration parameters for plugin audiophonics_evo_sabre Nov 03 09:46:40 amp volumio[1194]: info: Loading plugin "ir_controller"... Nov 03 09:46:40 amp volumio[1194]: info: Applying required configuration parameters for plugin ir_controller Nov 03 09:46:40 amp volumio[1353]: Starting albumart workers Nov 03 09:46:41 amp volumio[1194]: info: Loading i18n strings for locale en Nov 03 09:46:41 amp volumio[1194]: Updating browse sources language Nov 03 09:46:41 amp volumio[1194]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 03 09:46:41 amp volumio[1352]: Starting albumart workers Nov 03 09:46:41 amp volumio[1194]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 03 09:46:41 amp volumio[1194]: info: CoreCommandRouter::initPlayerControls Nov 03 09:46:41 amp volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 09:46:41 amp volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 09:46:41 amp volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 09:46:41 amp volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 09:46:41 amp volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 09:46:41 amp volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 09:46:41 amp volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 09:46:41 amp volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 09:46:41 amp volumio[1194]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 03 09:46:41 amp volumio[1194]: Express server listening on port 3000 Nov 03 09:46:41 amp volumio[1194]: [Metrics] WebUI: 9s 141.40ms Nov 03 09:46:41 amp volumio[1351]: Starting albumart workers Nov 03 09:46:41 amp volumio[1194]: info: CoreStateMachine::resetVolumioState Nov 03 09:46:41 amp volumio[1194]: info: CoreStateMachine::getcurrentVolume Nov 03 09:46:41 amp volumio[1194]: info: CoreCommandRouter::volumioRetrievevolume Nov 03 09:46:41 amp volumio[1194]: info: Volumio Network Manager: Network status updated: 2 Nov 03 09:46:41 amp volumio-remote-updater[663]: [2025-11-03 09:46:41] [connect] Successful connection Nov 03 09:46:41 amp nmbd[994]: [2025/11/03 09:46:41.637168, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Nov 03 09:46:41 amp nmbd[994]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.55.7 for name WORKGROUP<1d>. Nov 03 09:46:41 amp nmbd[994]: This response was from IP 192.168.55.8, reporting an IP address of 192.168.55.8. Nov 03 09:46:41 amp volumio[1194]: info: VolumeController:: Volume=80 Mute =false Nov 03 09:46:41 amp volumio[1194]: info: CoreStateMachine::pushState Nov 03 09:46:41 amp volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 03 09:46:41 amp volumio[1194]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 03 09:46:41 amp volumio[1194]: info: CoreCommandRouter::volumioPushState Nov 03 09:46:41 amp volumio[1194]: info: CoreStateMachine::updateTrackBlock Nov 03 09:46:41 amp volumio[1194]: info: CorePlayQueue::getTrackBlock Nov 03 09:46:41 amp volumio[1194]: info: CoreCommandRouter::volumioRetrievevolume Nov 03 09:46:41 amp volumio-remote-updater[663]: [2025-11-03 09:46:41] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1762163201 101 Nov 03 09:46:41 amp volumio[1194]: 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: 1 Nov 03 09:46:42 amp volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 03 09:46:42 amp volumio[1194]: info: Cannot read play queue from file Nov 03 09:46:42 amp volumio[1194]: info: CoreStateMachine::setRepeat null single undefined Nov 03 09:46:42 amp volumio[1194]: info: CoreStateMachine::pushState Nov 03 09:46:42 amp volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 03 09:46:42 amp volumio[1194]: info: CoreCommandRouter::volumioPushState Nov 03 09:46:42 amp volumio[1194]: info: CoreStateMachine::setRandom null Nov 03 09:46:42 amp volumio[1194]: info: CoreStateMachine::pushState Nov 03 09:46:42 amp volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 03 09:46:42 amp volumio[1194]: info: CoreCommandRouter::volumioPushState Nov 03 09:46:42 amp volumio[1194]: info: Setting Device type: Raspberry PI Nov 03 09:46:42 amp volumio[1194]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Nov 03 09:46:42 amp volumio[1194]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03111 Nov 03 09:46:42 amp volumio[1194]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Nov 03 09:46:42 amp volumio[1194]: info: VolumeController:: Volume=80 Mute =false Nov 03 09:46:42 amp volumio[1194]: info: CoreStateMachine::pushState Nov 03 09:46:42 amp volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 03 09:46:42 amp volumio[1194]: info: CoreCommandRouter::volumioPushState Nov 03 09:46:42 amp volumio[1194]: info: Completed loading Core Plugins Nov 03 09:46:42 amp volumio[1194]: info: Preparing to generate the ALSA configuration file Nov 03 09:46:42 amp volumio[1194]: info: Discovery: adding 3d6419d0-51ce-4dbd-95cc-a10b90228bdf Nov 03 09:46:42 amp volumio[1194]: info: Discovery: Found device amp Nov 03 09:46:42 amp volumio[1194]: info: CoreCommandRouter::volumioGetState Nov 03 09:46:42 amp volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 03 09:46:42 amp volumio[1194]: info: Discovery: this is already registered, 3d6419d0-51ce-4dbd-95cc-a10b90228bdf Nov 03 09:46:42 amp volumio[1194]: info: Discovery: Found device amp Nov 03 09:46:42 amp volumio[1194]: info: CoreCommandRouter::volumioGetState Nov 03 09:46:42 amp volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 03 09:46:42 amp volumio[1194]: info: Asound.conf file unchanged, so no further update is needed Nov 03 09:46:42 amp volumio[1194]: info: Output device has changed, restarting MPD Nov 03 09:46:42 amp sudo[1410]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 03 09:46:42 amp sudo[1410]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 03 09:46:42 amp volumio[1194]: info: Output device has changed, restarting Shairport Sync Nov 03 09:46:42 amp volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 09:46:42 amp sudo[1410]: pam_unix(sudo:session): session closed for user root Nov 03 09:46:42 amp volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 03 09:46:42 amp sudo[1413]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 03 09:46:42 amp sudo[1413]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 03 09:46:42 amp volumio[1194]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 03 09:46:42 amp volumio[1194]: info: ___________ START PLUGINS ___________ Nov 03 09:46:42 amp volumio[1194]: info: ControllerMpd::onStart: Initializing MPD Nov 03 09:46:42 amp volumio[1194]: info: Creating MPD Configuration file Nov 03 09:46:42 amp systemd[1]: Stopping mpd.service - Music Player Daemon... Nov 03 09:46:42 amp sudo[1420]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 03 09:46:42 amp sudo[1420]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 03 09:46:42 amp volumio[1194]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 03 09:46:42 amp sudo[1420]: pam_unix(sudo:session): session closed for user root Nov 03 09:46:42 amp volumio[1194]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 03 09:46:42 amp volumio[1194]: info: [1762163202609] CoreMusicLibrary::Adding element Media Servers Nov 03 09:46:42 amp volumio[1194]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 03 09:46:42 amp sudo[1423]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 03 09:46:42 amp sudo[1423]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 03 09:46:42 amp volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 09:46:42 amp volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 03 09:46:42 amp volumio[1194]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 03 09:46:42 amp volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 09:46:42 amp volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 03 09:46:42 amp systemd[1]: mpd.service: Deactivated successfully. Nov 03 09:46:42 amp systemd[1]: Stopped mpd.service - Music Player Daemon. Nov 03 09:46:42 amp systemd[1]: mpd.service: Consumed 3.744s CPU time. Nov 03 09:46:42 amp systemd[1]: mpd.socket: Deactivated successfully. Nov 03 09:46:42 amp systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Nov 03 09:46:42 amp systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Nov 03 09:46:42 amp volumio[1194]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 03 09:46:42 amp volumio[1194]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 03 09:46:42 amp volumio[1194]: info: [1762163202894] CoreMusicLibrary::Adding element Last_100 Nov 03 09:46:42 amp volumio[1194]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 03 09:46:42 amp volumio[1194]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 03 09:46:42 amp volumio[1194]: info: [1762163202896] CoreMusicLibrary::Adding element Webradio Nov 03 09:46:42 amp volumio[1194]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 03 09:46:42 amp volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 03 09:46:42 amp systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Nov 03 09:46:42 amp systemd[1]: Starting mpd.service - Music Player Daemon... Nov 03 09:46:42 amp volumio[1194]: info: Initializing BBC Radios Nov 03 09:46:43 amp sudo[1446]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 03 09:46:43 amp sudo[1446]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Nov 03 09:46:43 amp sudo[1446]: pam_unix(sudo:session): session closed for user root Nov 03 09:46:43 amp volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 03 09:46:43 amp volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 03 09:46:43 amp volumio[1194]: info: Creating Spotify config file Nov 03 09:46:43 amp volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 09:46:43 amp volumio[1194]: info: Loading i18n strings for locale en Nov 03 09:46:43 amp volumio[1194]: info: EVO SABRE : Starting Plugin Nov 03 09:46:43 amp volumio[1194]: info: Loading i18n strings for locale en Nov 03 09:46:43 amp volumio[1194]: info: Loading i18n strings for locale en Nov 03 09:46:43 amp volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 03 09:46:43 amp volumio[1194]: info: Volumio Calling Home Nov 03 09:46:44 amp sudo[1473]: 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 Nov 03 09:46:44 amp sudo[1473]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 03 09:46:44 amp mpd[1457]: 2025-11-03T09:46:44 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 03 09:46:44 amp sudo[1473]: pam_unix(sudo:session): session closed for user root Nov 03 09:46:44 amp volumio[1194]: info: ir_controller: File permissions successfully set on /etc/lirc/*. Nov 03 09:46:44 amp volumio[1194]: info: MPD Permissions set Nov 03 09:46:44 amp volumio[1194]: info: MPD Permissions set Nov 03 09:46:44 amp volumio[1194]: error: ES9038PROController::readRegister:SendByte:Error: {"errno":5,"code":"EIO","syscall":"sendByte"} Nov 03 09:46:44 amp volumio[1194]: info: ES9038PROController::readRegister:SendByte:regAddr: 64 Nov 03 09:46:44 amp volumio[1194]: info: ES9038PROController::readRegister:SendByte:rawData: undefined Nov 03 09:46:44 amp volumio[1194]: error: ES9038PROController::readRegister:SendByte:Error: {"errno":121,"code":"","syscall":"readByte"} Nov 03 09:46:44 amp volumio[1194]: info: ES9038PROController::readRegister:readByte:rawData: undefined Nov 03 09:46:44 amp sudo[1478]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Nov 03 09:46:44 amp sudo[1478]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 03 09:46:44 amp systemd[1]: Started mpd.service - Music Player Daemon. Nov 03 09:46:44 amp sudo[1423]: pam_unix(sudo:session): session closed for user root Nov 03 09:46:44 amp systemd[1]: Reloading. Nov 03 09:46:44 amp sudo[1413]: pam_unix(sudo:session): session closed for user root Nov 03 09:46:44 amp volumio[1194]: info: Volumio called home Nov 03 09:46:44 amp volumio[1194]: info: Spotify config file written Nov 03 09:46:44 amp sudo[1484]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Nov 03 09:46:44 amp sudo[1484]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 03 09:46:44 amp volumio[1194]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Nov 03 09:46:45 amp volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 09:46:45 amp volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 09:46:45 amp volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 09:46:45 amp volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 09:46:45 amp volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 09:46:45 amp volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 09:46:45 amp volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 09:46:45 amp volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 09:46:45 amp volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 03 09:46:45 amp volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 09:46:45 amp volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 09:46:45 amp volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 09:46:45 amp volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 09:46:45 amp volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 09:46:45 amp volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 09:46:45 amp volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 09:46:45 amp volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 09:46:45 amp volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 09:46:45 amp volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 03 09:46:45 amp volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 09:46:45 amp volumio[1194]: info: No need to fix Spotify hosts Nov 03 09:46:45 amp sudo[1530]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 03 09:46:45 amp sudo[1530]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 03 09:46:45 amp sudo[1530]: pam_unix(sudo:session): session closed for user root Nov 03 09:46:45 amp sudo[1533]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 03 09:46:45 amp sudo[1533]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 03 09:46:45 amp sudo[1533]: pam_unix(sudo:session): session closed for user root Nov 03 09:46:45 amp sudo[1478]: pam_unix(sudo:session): session closed for user root Nov 03 09:46:45 amp systemd[1]: Starting e2scrub_all.service - Online ext4 Metadata Check for All Filesystems... Nov 03 09:46:45 amp systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Nov 03 09:46:45 amp systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Nov 03 09:46:45 amp systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Nov 03 09:46:45 amp systemd[1]: e2scrub_all.service: Deactivated successfully. Nov 03 09:46:45 amp go-librespot[1536]: go-librespot daemon starting... Nov 03 09:46:45 amp systemd[1]: Finished e2scrub_all.service - Online ext4 Metadata Check for All Filesystems. Nov 03 09:46:45 amp sudo[1484]: pam_unix(sudo:session): session closed for user root Nov 03 09:46:45 amp volumio[1194]: error: MPD error: The expression evaluated to a falsy value: Nov 03 09:46:45 amp volumio[1194]: assert.ok(self.idling) Nov 03 09:46:45 amp volumio[1194]: error: The expression evaluated to a falsy value: Nov 03 09:46:45 amp volumio[1194]: assert.ok(self.idling) Nov 03 09:46:45 amp volumio[1194]: info: MPD running with PID1457 Nov 03 09:46:45 amp volumio[1194]: ,establishing connection Nov 03 09:46:45 amp volumio[1194]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Nov 03 09:46:45 amp volumio[1194]: SPOTIFY: BQDQZ3BUSyRvJA3FWu7LHipVwjdTbqdd6OtHgFmZZGjlZxT2RqQ_aoB-dbrg61EajA1Uk-v3Am0QML-bEiU61B0cnkTVrM8O6BYhph9gt1kU9XUr2glJ20125Eak5_kQyw7UgNtcebHQO95AmOhtSF4cmWc47AhUhbBJKnO4RjFeSyqe-TdKw0HcAZdQ8ZeclZ03RugqeqLHxrzer7weg4oDkDujBj9ibs9J5DDM89XmtTw6mYhwNhXaQ-4S5tNwsEjccJerv7-iQNs1iXablFOOsiUuB9Q Nov 03 09:46:45 amp volumio[1194]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Nov 03 09:46:45 amp volumio[1194]: info: New Spotify access token = BQDQZ3BUSyRvJA3FWu7LHipVwjdTbqdd6OtHgFmZZGjlZxT2RqQ_aoB-dbrg61EajA1Uk-v3Am0QML-bEiU61B0cnkTVrM8O6BYhph9gt1kU9XUr2glJ20125Eak5_kQyw7UgNtcebHQO95AmOhtSF4cmWc47AhUhbBJKnO4RjFeSyqe-TdKw0HcAZdQ8ZeclZ03RugqeqLHxrzer7weg4oDkDujBj9ibs9J5DDM89XmtTw6mYhwNhXaQ-4S5tNwsEjccJerv7-iQNs1iXablFOOsiUuB9Q Nov 03 09:46:45 amp volumio[1194]: info: Spotify credentials grant success - running version from March 24, 2019 Nov 03 09:46:45 amp volumio[1194]: error: updateQueue error: null Nov 03 09:46:46 amp volumio[1194]: info: EVO SABRE : systemd cmd daemon-reload : success Nov 03 09:46:46 amp go-librespot[1537]: time="2025-11-03T09:46:46Z" level=info msg="running go-librespot 0.4.0" Nov 03 09:46:46 amp go-librespot[1537]: time="2025-11-03T09:46:46Z" level=debug msg="app state loaded" Nov 03 09:46:46 amp go-librespot[1537]: time="2025-11-03T09:46:46Z" level=info msg="api server listening on 127.0.0.1:9879" Nov 03 09:46:46 amp volumio[1194]: info: Starting Shairport Sync Nov 03 09:46:46 amp volumio[1194]: info: Starting Shairport Sync Nov 03 09:46:46 amp sudo[1545]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart evo_oled2.service Nov 03 09:46:46 amp volumio[1194]: info: Starting Shairport Sync Nov 03 09:46:46 amp sudo[1547]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 03 09:46:46 amp sudo[1547]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 03 09:46:46 amp sudo[1549]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 03 09:46:46 amp sudo[1549]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 03 09:46:46 amp sudo[1545]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 03 09:46:46 amp sudo[1554]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 03 09:46:46 amp sudo[1554]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 03 09:46:46 amp systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Nov 03 09:46:46 amp systemd[1]: shairport-sync.service: Deactivated successfully. Nov 03 09:46:46 amp systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Nov 03 09:46:46 amp systemd[1]: shairport-sync.service: Consumed 1.470s CPU time. Nov 03 09:46:46 amp volumio[1194]: info: ir_controller: LIRC correctly updated. Nov 03 09:46:46 amp systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Nov 03 09:46:46 amp sudo[1554]: pam_unix(sudo:session): session closed for user root Nov 03 09:46:46 amp sudo[1549]: pam_unix(sudo:session): session closed for user root Nov 03 09:46:46 amp volumio[1194]: info: ir_controller: HAT already loaded /proc/device-tree/ir_receiver! Nov 03 09:46:46 amp volumio[1194]: error: updateQueue error: null Nov 03 09:46:46 amp sudo[1547]: pam_unix(sudo:session): session closed for user root Nov 03 09:46:46 amp systemd[1]: Started evo_oled2.service - OLED Display Service for EVO SABRE. Nov 03 09:46:46 amp sudo[1545]: pam_unix(sudo:session): session closed for user root Nov 03 09:46:46 amp go-librespot[1537]: time="2025-11-03T09:46:46Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 03 09:46:46 amp go-librespot[1537]: time="2025-11-03T09:46:46Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 03 09:46:46 amp go-librespot[1537]: time="2025-11-03T09:46:46Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 03 09:46:46 amp go-librespot[1537]: time="2025-11-03T09:46:46Z" level=info msg="zeroconf server listening on port 43313" Nov 03 09:46:46 amp go-librespot[1537]: time="2025-11-03T09:46:46Z" level=debug msg="obtained new client token: AAAb0jwSuLQzKfWRVQsq+Vf0K7OPvzO9H23YDh2JEOtDHlAk3Fi9X87KYre9R0Wb/oYYw8IuGOhQ4P8ZkLfXPk0DT11jA581ixrpRfLNGztvWBmPjZJNJElUVpSH5i6QYvBwgWfsx/qwlDx0W+ckXCdRk7xII1zl+AAQsfsNRU4XKrQqSp7vCSQ2RSsDRD8OZ0qYwq8LW9g+0InhsnvvRRRG219pyj3hrPyDEvZOug41cVu/D71XagI2" Nov 03 09:46:46 amp go-librespot[1537]: time="2025-11-03T09:46:46Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Nov 03 09:46:46 amp go-librespot[1537]: time="2025-11-03T09:46:46Z" level=debug msg="completed keyexchange" Nov 03 09:46:46 amp go-librespot[1537]: time="2025-11-03T09:46:46Z" level=debug msg="completed challenge" Nov 03 09:46:46 amp go-librespot[1537]: time="2025-11-03T09:46:46Z" level=info msg="authenticated AP" username="11******11" Nov 03 09:46:46 amp volumio[1194]: info: EVO SABRE : systemd cmd restart evo_oled2.service : success Nov 03 09:46:46 amp go-librespot[1537]: time="2025-11-03T09:46:46Z" level=info msg="authenticated Login5" username="11******11" Nov 03 09:46:46 amp go-librespot[1537]: time="2025-11-03T09:46:46Z" level=debug msg="initializing zeroconf session" username="11******11" Nov 03 09:46:46 amp sudo[1583]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart evo_remote.service Nov 03 09:46:46 amp sudo[1583]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 03 09:46:46 amp volumio[1194]: info: Shairport-Sync Started Nov 03 09:46:46 amp volumio[1194]: Error adding Membership: Error: addMembership EINVAL Nov 03 09:46:46 amp volumio[1194]: info: Shairport-Sync Started Nov 03 09:46:46 amp volumio[1194]: info: Shairport-Sync Started Nov 03 09:46:46 amp go-librespot[1537]: time="2025-11-03T09:46:46Z" level=debug msg="dealer connection opened" Nov 03 09:46:46 amp go-librespot[1537]: time="2025-11-03T09:46:46Z" level=trace msg="starting accesspoint recv loop" Nov 03 09:46:46 amp go-librespot[1537]: time="2025-11-03T09:46:46Z" level=trace msg="starting dealer recv loop" Nov 03 09:46:46 amp go-librespot[1537]: time="2025-11-03T09:46:46Z" level=trace msg="received accesspoint ping" Nov 03 09:46:46 amp go-librespot[1537]: time="2025-11-03T09:46:46Z" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Nov 03 09:46:46 amp go-librespot[1537]: time="2025-11-03T09:46:46Z" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Nov 03 09:46:46 amp go-librespot[1537]: time="2025-11-03T09:46:46Z" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Nov 03 09:46:46 amp go-librespot[1537]: time="2025-11-03T09:46:46Z" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Nov 03 09:46:46 amp go-librespot[1537]: time="2025-11-03T09:46:46Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Nov 03 09:46:46 amp go-librespot[1537]: time="2025-11-03T09:46:46Z" level=debug msg="received connection id: MzlmZjgyYjMtNWUx...MDdBRDc5NzcyRg==" Nov 03 09:46:46 amp go-librespot[1537]: time="2025-11-03T09:46:46Z" level=trace msg="received accesspoint pong ack" Nov 03 09:46:46 amp go-librespot[1537]: time="2025-11-03T09:46:46Z" level=debug msg="put connect state because NEW_DEVICE" Nov 03 09:46:47 amp systemd[1]: Starting lircd-setup.service - lircd(8) initialization helper tool... Nov 03 09:46:47 amp volumio[1194]: info: CoreCommandRouter::volumioGetState Nov 03 09:46:47 amp volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 03 09:46:47 amp lircd-setup[1585]: /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. Nov 03 09:46:47 amp lircd-setup[1585]: parser = configparser.SafeConfigParser() Nov 03 09:46:47 amp systemd[1]: lircd-setup.service: Deactivated successfully. Nov 03 09:46:47 amp systemd[1]: Finished lircd-setup.service - lircd(8) initialization helper tool. Nov 03 09:46:47 amp systemd[1]: Started evo_remote.service. Nov 03 09:46:47 amp sudo[1583]: pam_unix(sudo:session): session closed for user root Nov 03 09:46:47 amp lircd-0.10.1[1589]: Info: lircd: Opening log, level: Info Nov 03 09:46:47 amp lircd-0.10.1[1589]: Notice: Version: lircd 0.10.1 Nov 03 09:46:47 amp lircd-0.10.1[1589]: Notice: System info: Linux amp 6.12.34-v7l+ #1889 SMP Mon Jun 23 11:08:21 BST 2025 armv7l GNU/Linux Nov 03 09:46:47 amp sudo[1588]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Nov 03 09:46:47 amp sudo[1588]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 03 09:46:47 amp sudo[1593]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart lircd.service Nov 03 09:46:47 amp sudo[1593]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 03 09:46:47 amp volumio[1194]: info: EVO SABRE : systemd cmd restart evo_remote.service : success Nov 03 09:46:47 amp lircd-0.10.1[1589]: Info: Initial device: /dev/lirc0 Nov 03 09:46:47 amp lircd-0.10.1[1589]: Notice: 'lirc' written to protocols file /sys/class/rc/rc0/protocols Nov 03 09:46:47 amp lircd-0.10.1[1589]: Info: Initial device: /dev/lirc0 Nov 03 09:46:47 amp lircd-0.10.1[1589]: Info: [lirc] protocol is enabled Nov 03 09:46:47 amp lircd[1589]: lircd-0.10.1[1589]: Info: lircd: Opening log, level: Info Nov 03 09:46:47 amp lircd[1589]: lircd-0.10.1[1589]: Warning: Running as root Nov 03 09:46:47 amp lircd[1589]: lircd-0.10.1[1589]: Info: Using remote: ApEvo. Nov 03 09:46:47 amp lircd[1589]: lircd-0.10.1[1589]: Notice: lircd(default) ready, using /var/run/lirc/lircd Nov 03 09:46:47 amp lircd-0.10.1[1589]: Notice: Options: driver: default Nov 03 09:46:47 amp lircd-0.10.1[1589]: Notice: Options: output: /var/run/lirc/lircd Nov 03 09:46:47 amp lircd-0.10.1[1589]: Notice: Options: nodaemon: 1 Nov 03 09:46:47 amp lircd-0.10.1[1589]: Notice: Options: plugindir: /usr/lib/arm-linux-gnueabihf/lirc/plugins Nov 03 09:46:47 amp lircd-0.10.1[1589]: Notice: Options: logfile: syslog Nov 03 09:46:47 amp lircd-0.10.1[1589]: Notice: Options: immediate-init: 0 Nov 03 09:46:47 amp lircd-0.10.1[1589]: Notice: Options: permission: 666 Nov 03 09:46:47 amp lircd-0.10.1[1589]: Notice: Options: driver-options: Nov 03 09:46:47 amp lircd-0.10.1[1589]: Notice: Options: pidfile: /var/run/lirc/lircd.pid Nov 03 09:46:47 amp lircd-0.10.1[1589]: Notice: Options: listen: 0 Nov 03 09:46:47 amp lircd-0.10.1[1589]: Notice: Options: connect: (null) Nov 03 09:46:47 amp lircd-0.10.1[1589]: Notice: Options: userelease: 0 Nov 03 09:46:47 amp lircd-0.10.1[1589]: Notice: Options: effective_user: (null) Nov 03 09:46:47 amp lircd-0.10.1[1589]: Notice: Options: release_suffix: _EVUP Nov 03 09:46:47 amp lircd-0.10.1[1589]: Notice: Options: allow_simulate: 0 Nov 03 09:46:47 amp lircd-0.10.1[1589]: Notice: Options: repeat_max: 600 Nov 03 09:46:47 amp lircd-0.10.1[1589]: Notice: Options: configfile: /data/plugins/system_hardware/audiophonics_evo_sabre/apps/lirc/lircd.conf Nov 03 09:46:47 amp lircd-0.10.1[1589]: Notice: Options: dynamic_codes: (null) Nov 03 09:46:47 amp lircd-0.10.1[1589]: Notice: Current driver: default Nov 03 09:46:47 amp lircd-0.10.1[1589]: Notice: Driver API version: 3 Nov 03 09:46:47 amp lircd-0.10.1[1589]: Notice: Driver version: 0.10.0 Nov 03 09:46:47 amp lircd-0.10.1[1589]: Notice: Driver info: See file:///usr/share/doc/lirc/plugindocs/default.html Nov 03 09:46:47 amp lircd-0.10.1[1589]: Info: lircd: Opening log, level: Info Nov 03 09:46:47 amp lircd-0.10.1[1589]: Warning: Running as root Nov 03 09:46:47 amp lircd-0.10.1[1589]: Info: Using remote: ApEvo. Nov 03 09:46:47 amp lircd-0.10.1[1589]: Notice: lircd(default) ready, using /var/run/lirc/lircd Nov 03 09:46:47 amp systemd[1]: Starting lircd-setup.service - lircd(8) initialization helper tool... Nov 03 09:46:47 amp sudo[1603]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart evo_irexec.service Nov 03 09:46:47 amp sudo[1603]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 03 09:46:47 amp systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Nov 03 09:46:47 amp sudo[1588]: pam_unix(sudo:session): session closed for user root Nov 03 09:46:47 amp volumio[1194]: info: Upmpdcli Daemon Started Nov 03 09:46:47 amp lircd-setup[1601]: /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. Nov 03 09:46:47 amp lircd-setup[1601]: parser = configparser.SafeConfigParser() Nov 03 09:46:47 amp systemd[1]: lircd-setup.service: Deactivated successfully. Nov 03 09:46:47 amp systemd[1]: Finished lircd-setup.service - lircd(8) initialization helper tool. Nov 03 09:46:48 amp volumio[1194]: SPOTIFY: User informations: {"country":"GB","display_name":"Nerijus Linauskas","email":"nerijus.linauskas@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/1163510411"},"followers":{"href":null,"total":31},"href":"https://api.spotify.com/v1/users/1163510411","id":"1163510411","images":[{"height":300,"url":"https://scontent-fra5-2.xx.fbcdn.net/v/t1.6435-1/32645964_10155362882036161_2420554722332114944_n.jpg?stp=dst-jpg_s320x320_tt6&_nc_cat=107&ccb=1-7&_nc_sid=79bf43&_nc_ohc=5iDedVF9IAMQ7kNvwH4Ojaq&_nc_oc=Adni3GGSHnoL0U862fU7ctvcRCQSU6diU1CkQwEYVePnHNMV98WYY33vM0PTfsV676cGjiHY-hYoZrNCTLRzR1y1&_nc_zt=24&_nc_ht=scontent-fra5-2.xx&edm=AP4hL3IEAAAA&_nc_gid=uIOg0ZsiYbb5ATuHQm0DUw&_nc_tpa=Q5bMBQFtbrtbUNxPFjNAERUU7bDU6-M15ndNN6S1t6JM4wGA15woZ_dRskRWRmaW6JMUsWLdwzx-&oh=00_AfhYaTowlCpmxoaiB-VjLs2EoczKji1Wgz0DomV1m72UHA&oe=692FEBC3","width":300},{"height":64,"url":"https://scontent-fra5-2.xx.fbcdn.net/v/t1.6435-1/32645964_10155362882036161_2420554722332114944_n.jpg?stp=cp0_dst-jpg_s50x50_tt6&_nc_cat=107&ccb=1-7&_nc_sid=28885b&_nc_ohc=5iDedVF9IAMQ7kNvwH4Ojaq&_nc_oc=Adni3GGSHnoL0U862fU7ctvcRCQSU6diU1CkQwEYVePnHNMV98WYY33vM0PTfsV676cGjiHY-hYoZrNCTLRzR1y1&_nc_zt=24&_nc_ht=scontent-fra5-2.xx&edm=AP4hL3IEAAAA&_nc_gid=uIOg0ZsiYbb5ATuHQm0DUw&_nc_tpa=Q5bMBQFRVapTNGDl4Q4a8VyYp3X2ZzQGAM5HGzBmr7l_2-NLl-gLwdL0QNL4T7eU9OwRKyEEkfgE&oh=00_Afg7DZ9SUerq2Q2ybH1ZAQs2399AUEZVjdKsG0X1u8Kk1w&oe=692FEBC3","width":64}],"product":"premium","type":"user","uri":"spotify:user:1163510411"} Nov 03 09:46:48 amp volumio[1194]: info: Spotify Successfully logged in Nov 03 09:46:48 amp volumio[1194]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 03 09:46:48 amp volumio[1194]: info: [1762163208050] CoreMusicLibrary::Adding element Spotify Nov 03 09:46:48 amp volumio[1194]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 03 09:46:48 amp volumio[1194]: Cannot find translation for source Spotify Nov 03 09:46:48 amp systemd[1]: Started evo_irexec.service. Nov 03 09:46:48 amp systemd[1]: Starting lircd.service - Flexible IR remote input/output application support... Nov 03 09:46:48 amp sudo[1603]: pam_unix(sudo:session): session closed for user root Nov 03 09:46:48 amp lircd-0.10.1[1633]: Info: lircd: Opening log, level: Info Nov 03 09:46:48 amp lircd-0.10.1[1633]: Notice: Version: lircd 0.10.1 Nov 03 09:46:48 amp lircd-0.10.1[1633]: Notice: System info: Linux amp 6.12.34-v7l+ #1889 SMP Mon Jun 23 11:08:21 BST 2025 armv7l GNU/Linux Nov 03 09:46:48 amp lircd[1589]: lircd-0.10.1[1589]: Notice: accepted new client on /var/run/lirc/lircd Nov 03 09:46:48 amp lircd-0.10.1[1589]: Notice: accepted new client on /var/run/lirc/lircd Nov 03 09:46:48 amp lircd[1589]: lircd-0.10.1[1589]: Info: [lirc] protocol is enabled Nov 03 09:46:48 amp lircd-0.10.1[1589]: Info: [lirc] protocol is enabled Nov 03 09:46:48 amp lircd-0.10.1[1633]: Info: Initial device: /dev/lirc0 Nov 03 09:46:48 amp lircd-0.10.1[1633]: Info: [lirc] protocol is enabled Nov 03 09:46:48 amp lircd-0.10.1[1633]: Info: Initial device: /dev/lirc0 Nov 03 09:46:48 amp lircd-0.10.1[1633]: Info: [lirc] protocol is enabled Nov 03 09:46:48 amp lircd[1633]: lircd-0.10.1[1633]: Info: lircd: Opening log, level: Info Nov 03 09:46:48 amp lircd[1633]: lircd: there seems to already be a lircd process with pid 1589 Nov 03 09:46:48 amp lircd[1633]: lircd: otherwise delete stale lockfile /var/run/lirc/lircd.pid Nov 03 09:46:48 amp lircd-0.10.1[1633]: Notice: Options: driver: default Nov 03 09:46:48 amp lircd-0.10.1[1633]: Notice: Options: output: /var/run/lirc/lircd Nov 03 09:46:48 amp lircd-0.10.1[1633]: Notice: Options: nodaemon: 1 Nov 03 09:46:48 amp lircd-0.10.1[1633]: Notice: Options: plugindir: /usr/lib/arm-linux-gnueabihf/lirc/plugins Nov 03 09:46:48 amp lircd-0.10.1[1633]: Notice: Options: logfile: syslog Nov 03 09:46:48 amp lircd-0.10.1[1633]: Notice: Options: immediate-init: 0 Nov 03 09:46:48 amp lircd-0.10.1[1633]: Notice: Options: permission: 666 Nov 03 09:46:48 amp lircd-0.10.1[1633]: Notice: Options: driver-options: Nov 03 09:46:48 amp lircd-0.10.1[1633]: Notice: Options: pidfile: /var/run/lirc/lircd.pid Nov 03 09:46:48 amp systemd[1]: lircd.service: Main process exited, code=exited, status=1/FAILURE Nov 03 09:46:48 amp lircd-0.10.1[1633]: Notice: Options: listen: 0 Nov 03 09:46:48 amp lircd-0.10.1[1633]: Notice: Options: connect: (null) Nov 03 09:46:48 amp lircd-0.10.1[1633]: Notice: Options: userelease: 0 Nov 03 09:46:48 amp systemd[1]: lircd.service: Failed with result 'exit-code'. Nov 03 09:46:48 amp lircd-0.10.1[1633]: Notice: Options: effective_user: (null) Nov 03 09:46:48 amp lircd-0.10.1[1633]: Notice: Options: release_suffix: _EVUP Nov 03 09:46:48 amp lircd-0.10.1[1633]: Notice: Options: allow_simulate: 0 Nov 03 09:46:48 amp lircd-0.10.1[1633]: Notice: Options: repeat_max: 600 Nov 03 09:46:48 amp lircd-0.10.1[1633]: Notice: Options: configfile: /etc/lirc/lircd.conf Nov 03 09:46:48 amp lircd-0.10.1[1633]: Notice: Options: dynamic_codes: (null) Nov 03 09:46:48 amp lircd-0.10.1[1633]: Notice: Current driver: default Nov 03 09:46:48 amp lircd-0.10.1[1633]: Notice: Driver API version: 3 Nov 03 09:46:48 amp lircd-0.10.1[1633]: Notice: Driver version: 0.10.0 Nov 03 09:46:48 amp lircd-0.10.1[1633]: Notice: Driver info: See file:///usr/share/doc/lirc/plugindocs/default.html Nov 03 09:46:48 amp lircd-0.10.1[1633]: Info: lircd: Opening log, level: Info Nov 03 09:46:48 amp systemd[1]: Failed to start lircd.service - Flexible IR remote input/output application support. Nov 03 09:46:48 amp sudo[1593]: pam_unix(sudo:session): session closed for user root Nov 03 09:46:48 amp volumio[1194]: error: ir_controller: Failed to restart lircd.service: Error: Command failed: /usr/bin/sudo /bin/systemctl restart lircd.service Nov 03 09:46:48 amp volumio[1194]: Job for lircd.service failed because the control process exited with error code. Nov 03 09:46:48 amp volumio[1194]: See "systemctl status lircd.service" and "journalctl -xeu lircd.service" for details. Nov 03 09:46:48 amp volumio[1194]: info: EVO SABRE : systemd cmd restart evo_irexec.service : success Nov 03 09:46:48 amp volumio[1194]: info: Completed starting Core Plugins Nov 03 09:46:48 amp volumio[1194]: info: ------------------------------------------- Nov 03 09:46:48 amp volumio[1194]: info: ----- MyVolumio plugins startup ---- Nov 03 09:46:48 amp volumio[1194]: info: ------------------------------------------- Nov 03 09:46:48 amp volumio[1194]: info: [MyVolumio PluginManager] Fetching plans data.... Nov 03 09:46:49 amp volumio[1194]: info: go-librespot daemon successfully initialized Nov 03 09:46:51 amp volumio[1194]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Nov 03 09:46:52 amp volumio[1194]: info: Initializing connection to go-librespot Websocket Nov 03 09:46:54 amp volumio[1194]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Nov 03 09:46:54 amp go-librespot[1537]: time="2025-11-03T09:46:54Z" level=debug msg="new websocket client" Nov 03 09:46:54 amp volumio[1194]: info: Connection to go-librespot Websocket established Nov 03 09:46:59 amp volumio[1194]: info: CoreCommandRouter::volumioGetState Nov 03 09:46:59 amp volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 03 09:46:59 amp volumio[1194]: info: Getting Spotify volume Nov 03 09:46:59 amp systemd[1]: systemd-timedated.service: Deactivated successfully. Nov 03 09:47:00 amp volumio[1194]: info: Spotify volume: 100 Nov 03 09:47:00 amp volumio[1194]: info: CoreCommandRouter::volumioGetQueue Nov 03 09:47:00 amp volumio[1194]: info: CoreStateMachine::getQueue Nov 03 09:47:00 amp volumio[1194]: info: CorePlayQueue::getQueue Nov 03 09:47:00 amp volumio[1194]: info: CoreCommandRouter::volumioGetState Nov 03 09:47:00 amp volumio[1194]: info: CorePlayQueue::getTrack 0 Nov 03 09:47:04 amp volumio[1194]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Nov 03 09:47:10 amp volumio[1194]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Nov 03 09:47:10 amp volumio[1194]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Nov 03 09:47:10 amp volumio[1194]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Nov 03 09:47:10 amp volumio[1194]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Nov 03 09:47:10 amp volumio[1194]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Nov 03 09:47:10 amp volumio[1194]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Nov 03 09:47:10 amp volumio[1194]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Nov 03 09:47:10 amp volumio[1194]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Nov 03 09:47:10 amp volumio[1194]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Nov 03 09:47:10 amp volumio[1194]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Nov 03 09:47:10 amp volumio[1194]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Nov 03 09:47:10 amp volumio[1194]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Nov 03 09:47:10 amp volumio[1194]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Nov 03 09:47:10 amp volumio[1194]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Nov 03 09:47:10 amp volumio[1194]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Nov 03 09:47:10 amp volumio[1194]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Nov 03 09:47:10 amp volumio[1194]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Nov 03 09:47:10 amp volumio[1194]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Nov 03 09:47:10 amp volumio[1194]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Nov 03 09:47:10 amp volumio[1194]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Nov 03 09:47:10 amp volumio[1194]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Nov 03 09:47:10 amp volumio[1194]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Nov 03 09:47:10 amp volumio[1194]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Nov 03 09:47:10 amp volumio[1194]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Nov 03 09:47:10 amp volumio[1194]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Nov 03 09:47:10 amp volumio[1194]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Nov 03 09:47:10 amp volumio[1194]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Nov 03 09:47:10 amp volumio[1194]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Nov 03 09:47:10 amp volumio[1194]: info: Adding plugin bluetooth to MyMusic Plugins Nov 03 09:47:10 amp volumio[1194]: info: Adding plugin multiroom to MyMusic Plugins Nov 03 09:47:10 amp volumio[1194]: info: Adding plugin metavolumio to MyMusic Plugins Nov 03 09:47:10 amp volumio[1194]: info: Adding plugin cd_controller to MyMusic Plugins Nov 03 09:47:10 amp volumio[1194]: info: Adding plugin qobuzconnect to MyMusic Plugins Nov 03 09:47:10 amp volumio[1194]: info: Adding plugin smart_inputs to MyMusic Plugins Nov 03 09:47:10 amp volumio[1194]: info: Adding plugin tidalconnect to MyMusic Plugins Nov 03 09:47:10 amp volumio[1194]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Nov 03 09:47:13 amp upmpdcli[1674]: writing RSA key Nov 03 09:47:14 amp volumio[1194]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Nov 03 09:47:14 amp volumio[1194]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Nov 03 09:47:14 amp volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 03 09:47:14 amp volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 03 09:47:14 amp volumio[1194]: info: Starting MyVolumio Remote Streaming Endpoints Nov 03 09:47:15 amp volumio[1194]: info: MyVolumio login type: Token Nov 03 09:47:15 amp volumio[1194]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Nov 03 09:47:15 amp volumio[1194]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Nov 03 09:47:16 amp go-librespot[1537]: time="2025-11-03T09:47:16Z" level=trace msg="sent dealer ping" Nov 03 09:47:16 amp go-librespot[1537]: time="2025-11-03T09:47:16Z" level=trace msg="received dealer pong" Nov 03 09:47:17 amp volumio[1194]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Nov 03 09:47:17 amp volumio[1194]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Nov 03 09:47:17 amp volumio[1194]: info: Streaming services startup Nov 03 09:47:17 amp volumio[1194]: info: Starting Streaming Daemon Nov 03 09:47:17 amp volumio[1194]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Nov 03 09:47:17 amp sudo[1698]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 03 09:47:17 amp sudo[1698]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Nov 03 09:47:17 amp sudo[1698]: pam_unix(sudo:session): session closed for user root Nov 03 09:47:17 amp volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 03 09:47:17 amp volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 03 09:47:17 amp volumio[1194]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 03 09:47:17 amp volumio[1194]: info: Bluetoothremote--- Checking for trusted devices to reconnect... Nov 03 09:47:17 amp volumio[1194]: info: Bluetoothremote--- Device list cleared and placeholder written. Nov 03 09:47:17 amp volumio[1194]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 03 09:47:17 amp volumio[1194]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals. Nov 03 09:47:17 amp volumio[1194]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues Nov 03 09:47:17 amp volumio[1194]: at new NodeError (node:internal/errors:405:5) Nov 03 09:47:17 amp volumio[1194]: at assert (node:internal/assert:14:11) Nov 03 09:47:17 amp volumio[1194]: at internalConnectMultiple (node:net:1118:3) Nov 03 09:47:17 amp volumio[1194]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3) Nov 03 09:47:17 amp volumio[1194]: at listOnTimeout (node:internal/timers:575:11) Nov 03 09:47:17 amp volumio[1194]: at process.processTimers (node:internal/timers:514:7) { Nov 03 09:47:17 amp volumio[1194]: code: 'ERR_INTERNAL_ASSERTION' Nov 03 09:47:17 amp volumio[1194]: } Nov 03 09:47:17 amp volumio[1194]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 03 09:47:17 amp bluetoothd[652]: Path / reserved for Adv Monitor app :1.22 Nov 03 09:47:17 amp bluetoothd[652]: Adv Monitor app :1.22 disconnected from D-Bus Nov 03 09:47:20 amp sudo[1723]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-11-03 09:46' Nov 03 09:47:20 amp sudo[1723]: 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="dfc7bb04e09bf2260691765a2340814af1fa2971" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="f3aa78921e436026799f7dbbcd51946daae2a94b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Sep 20 15:16:44 UTC 2025" VOLUMIO_VERSION="4.023" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="73301b86bd75e54847b48c3b65ee1b06"