-- Logs begin at Mon 2024-07-01 19:22:08 CEST, end at Thu 2024-07-11 17:37:43 CEST. -- Jul 11 17:37:19 hifiberry3 ntpd[899]: receive: Unexpected origin timestamp 0xea2d6454.b8ec4556 does not match aorg 0000000000.00000000 from server@17.253.14.251 xmt 0xea3a7aaf.7cc2247d Jul 11 17:37:19 hifiberry3 ntpd[899]: receive: Unexpected origin timestamp 0xea2d6454.b8d6d858 does not match aorg 0000000000.00000000 from server@51.75.67.47 xmt 0xea3a7aaf.7d0ce144 Jul 11 17:37:19 hifiberry3 ntpd[899]: receive: Unexpected origin timestamp 0xea2d6454.b8f14896 does not match aorg 0000000000.00000000 from server@185.232.69.65 xmt 0xea3a7aaf.7d533aae Jul 11 17:37:19 hifiberry3 ntpd[899]: receive: Unexpected origin timestamp 0xea2d6454.b8fd6330 does not match aorg 0000000000.00000000 from server@217.91.44.17 xmt 0xea3a7aaf.7f5357f0 Jul 11 17:37:19 hifiberry3 systemd[1]: Starting Daily apt download activities... Jul 11 17:37:21 hifiberry3 volumio[837]: info: Loading plugin "network"... Jul 11 17:37:21 hifiberry3 volumio[837]: info: Refreshing Cached IP Addresses Jul 11 17:37:21 hifiberry3 volumio[837]: info: Loading plugin "services"... Jul 11 17:37:21 hifiberry3 sudo[974]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 11 17:37:21 hifiberry3 sudo[974]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 17:37:21 hifiberry3 volumio[837]: info: Loading plugin "alsa_controller"... Jul 11 17:37:21 hifiberry3 sudo[974]: pam_unix(sudo:session): session closed for user root Jul 11 17:37:21 hifiberry3 sudo[976]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 11 17:37:21 hifiberry3 sudo[976]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 17:37:21 hifiberry3 sudo[976]: pam_unix(sudo:session): session closed for user root Jul 11 17:37:21 hifiberry3 volumio[837]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 11 17:37:21 hifiberry3 volumio[837]: info: Loading plugin "wizard"... Jul 11 17:37:21 hifiberry3 volumio[837]: info: Loading plugin "networkfs"... Jul 11 17:37:22 hifiberry3 volumio[837]: info: Starting Udev Watcher for removable devices Jul 11 17:37:22 hifiberry3 volumio[837]: info: Ignoring mount for partition: boot Jul 11 17:37:22 hifiberry3 volumio[837]: info: Ignoring mount for partition: volumio Jul 11 17:37:22 hifiberry3 volumio[837]: info: Ignoring mount for partition: volumio_data Jul 11 17:37:22 hifiberry3 volumio[837]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 11 17:37:22 hifiberry3 volumio[837]: info: Loading plugin "volumio_command_line_client"... Jul 11 17:37:22 hifiberry3 volumio[837]: info: Loading plugin "upnp"... Jul 11 17:37:22 hifiberry3 volumio[837]: info: [1720712242199] Starting Upmpd Daemon Jul 11 17:37:22 hifiberry3 volumio[837]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 11 17:37:22 hifiberry3 volumio[837]: info: Loading plugin "my_music"... Jul 11 17:37:22 hifiberry3 volumio[837]: info: Loading plugin "mpd"... Jul 11 17:37:23 hifiberry3 volumio[837]: info: Loading plugin "upnp_browser"... Jul 11 17:37:23 hifiberry3 volumio[837]: info: Loading plugin "alarm-clock"... Jul 11 17:37:24 hifiberry3 volumio[837]: info: Loading plugin "airplay_emulation"... Jul 11 17:37:24 hifiberry3 volumio[837]: info: Starting Shairport Sync Jul 11 17:37:24 hifiberry3 volumio[837]: info: Loading plugin "last_100"... Jul 11 17:37:24 hifiberry3 volumio[837]: info: Loading plugin "webradio"... Jul 11 17:37:24 hifiberry3 systemd[1]: apt-daily.service: Succeeded. Jul 11 17:37:24 hifiberry3 systemd[1]: Started Daily apt download activities. Jul 11 17:37:24 hifiberry3 systemd[1]: Starting Daily apt upgrade and clean activities... Jul 11 17:37:24 hifiberry3 volumio[837]: info: Loading plugin "i2s_dacs"... Jul 11 17:37:24 hifiberry3 volumio[837]: info: Loading plugin "volumiodiscovery"... Jul 11 17:37:25 hifiberry3 volumio[837]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 11 17:37:25 hifiberry3 node[837]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 11 17:37:25 hifiberry3 volumio[837]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 11 17:37:25 hifiberry3 node[837]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 11 17:37:25 hifiberry3 volumio[837]: *** WARNING *** For more information see Jul 11 17:37:25 hifiberry3 node[837]: *** WARNING *** For more information see Jul 11 17:37:25 hifiberry3 volumio[837]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 11 17:37:25 hifiberry3 node[837]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 11 17:37:25 hifiberry3 volumio[837]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 11 17:37:25 hifiberry3 node[837]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 11 17:37:25 hifiberry3 volumio[837]: *** WARNING *** For more information see Jul 11 17:37:25 hifiberry3 node[837]: *** WARNING *** For more information see Jul 11 17:37:25 hifiberry3 volumio[837]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 11 17:37:25 hifiberry3 volumio[837]: info: Discovery: Started advertising with name: hifiberry3 Jul 11 17:37:25 hifiberry3 volumio[837]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 11 17:37:25 hifiberry3 volumio[837]: info: Plugin soundcloud is not enabled Jul 11 17:37:25 hifiberry3 volumio[837]: info: Loading plugin "spop"... Jul 11 17:37:28 hifiberry3 systemd[1]: apt-daily-upgrade.service: Succeeded. Jul 11 17:37:28 hifiberry3 systemd[1]: Started Daily apt upgrade and clean activities. Jul 11 17:37:28 hifiberry3 volumio[837]: info: Loading plugin "outputs"... Jul 11 17:37:28 hifiberry3 volumio[837]: info: Loading plugin "albumart"... Jul 11 17:37:28 hifiberry3 volumio[837]: info: Plugin example_plugin is not enabled Jul 11 17:37:28 hifiberry3 volumio[837]: info: Loading plugin "inputs"... Jul 11 17:37:28 hifiberry3 volumio[837]: info: Loading plugin "updater_comm"... Jul 11 17:37:28 hifiberry3 volumio[837]: info: Plugin mpdemulation is not enabled Jul 11 17:37:28 hifiberry3 volumio[837]: info: Loading plugin "rest_api"... Jul 11 17:37:28 hifiberry3 volumio[837]: info: Loading plugin "websocket"... Jul 11 17:37:28 hifiberry3 volumio[837]: info: Starting Socket.io Server version 2.3.0 Jul 11 17:37:28 hifiberry3 volumio[837]: info: Loading plugin "ir_controller"... Jul 11 17:37:29 hifiberry3 volumio[837]: Forking 3 albumart workers Jul 11 17:37:29 hifiberry3 volumio[837]: info: Applying required configuration parameters for plugin ir_controller Jul 11 17:37:29 hifiberry3 volumio[837]: info: Loading plugin "mpd_oled"... Jul 11 17:37:30 hifiberry3 volumio[837]: info: Applying required configuration parameters for plugin mpd_oled Jul 11 17:37:30 hifiberry3 volumio[837]: info: [MPD_OLED Plugin] Reading configuration file at: /data/configuration/system_hardware/mpd_oled/config.json Jul 11 17:37:30 hifiberry3 volumio[837]: info: Loading i18n strings for locale de Jul 11 17:37:30 hifiberry3 volumio[837]: Updating browse sources language Jul 11 17:37:30 hifiberry3 volumio[837]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 11 17:37:30 hifiberry3 volumio[837]: Starting albumart workers Jul 11 17:37:30 hifiberry3 volumio[837]: Starting albumart workers Jul 11 17:37:31 hifiberry3 volumio[837]: Starting albumart workers Jul 11 17:37:31 hifiberry3 volumio[837]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 11 17:37:31 hifiberry3 volumio[837]: info: CoreCommandRouter::initPlayerControls Jul 11 17:37:31 hifiberry3 volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:31 hifiberry3 volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:31 hifiberry3 volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:31 hifiberry3 volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:31 hifiberry3 volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:31 hifiberry3 volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:31 hifiberry3 volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:31 hifiberry3 volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:31 hifiberry3 volumio[837]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 11 17:37:31 hifiberry3 volumio[837]: Express server listening on port 3000 Jul 11 17:37:31 hifiberry3 volumio[837]: [Metrics] WebUI: 17s 101.56ms Jul 11 17:37:31 hifiberry3 volumio[837]: info: CoreStateMachine::resetVolumioState Jul 11 17:37:31 hifiberry3 volumio[837]: info: CoreStateMachine::getcurrentVolume Jul 11 17:37:31 hifiberry3 volumio[837]: info: CoreCommandRouter::volumioRetrievevolume Jul 11 17:37:31 hifiberry3 systemd[1]: systemd-fsckd.service: Succeeded. Jul 11 17:37:31 hifiberry3 volumio[837]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 11 17:37:31 hifiberry3 volumio[837]: info: Reloading queue from file Jul 11 17:37:31 hifiberry3 volumio[837]: info: Setting Device type: Raspberry PI Jul 11 17:37:31 hifiberry3 volumio[837]: info: CoreStateMachine::setRepeat null single undefined Jul 11 17:37:31 hifiberry3 volumio[837]: info: CoreStateMachine::pushState Jul 11 17:37:31 hifiberry3 volumio[837]: info: CorePlayQueue::getTrack 0 Jul 11 17:37:31 hifiberry3 volumio[837]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 11 17:37:31 hifiberry3 volumio[837]: info: CoreCommandRouter::volumioPushState Jul 11 17:37:31 hifiberry3 volumio[837]: info: CoreStateMachine::setRandom null Jul 11 17:37:31 hifiberry3 volumio[837]: info: CoreStateMachine::pushState Jul 11 17:37:31 hifiberry3 volumio[837]: info: CorePlayQueue::getTrack 0 Jul 11 17:37:31 hifiberry3 volumio[837]: info: CoreCommandRouter::volumioPushState Jul 11 17:37:31 hifiberry3 volumio[837]: info: Completed loading Core Plugins Jul 11 17:37:31 hifiberry3 volumio[837]: info: Preparing to generate the ALSA configuration file Jul 11 17:37:32 hifiberry3 volumio[837]: info: VolumeController:: Volume=41 Mute =false Jul 11 17:37:32 hifiberry3 volumio[837]: info: CoreStateMachine::pushState Jul 11 17:37:32 hifiberry3 volumio[837]: info: CorePlayQueue::getTrack 0 Jul 11 17:37:32 hifiberry3 volumio[837]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 11 17:37:32 hifiberry3 volumio[837]: info: CoreCommandRouter::volumioPushState Jul 11 17:37:32 hifiberry3 volumio[837]: info: CoreStateMachine::updateTrackBlock Jul 11 17:37:32 hifiberry3 volumio[837]: info: CorePlayQueue::getTrackBlock Jul 11 17:37:32 hifiberry3 volumio[837]: info: CoreCommandRouter::volumioRetrievevolume Jul 11 17:37:32 hifiberry3 volumio[837]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Jul 11 17:37:32 hifiberry3 volumio[837]: info: Reading ALSA contributions from plugins. Jul 11 17:37:32 hifiberry3 volumio[837]: info: Asound.conf file unchanged, so no further update is needed Jul 11 17:37:32 hifiberry3 volumio[837]: info: Output device has changed, restarting MPD Jul 11 17:37:32 hifiberry3 volumio[837]: info: Output device has changed, restarting Shairport Sync Jul 11 17:37:32 hifiberry3 volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:32 hifiberry3 volumio[837]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 11 17:37:32 hifiberry3 sudo[1106]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 11 17:37:32 hifiberry3 sudo[1106]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 17:37:32 hifiberry3 sudo[1106]: pam_unix(sudo:session): session closed for user root Jul 11 17:37:32 hifiberry3 sudo[1107]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 11 17:37:32 hifiberry3 sudo[1107]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 17:37:32 hifiberry3 volumio[837]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 11 17:37:32 hifiberry3 volumio[837]: info: ___________ START PLUGINS ___________ Jul 11 17:37:32 hifiberry3 volumio[837]: info: ControllerMpd::onStart: Initializing MPD Jul 11 17:37:32 hifiberry3 volumio[837]: info: Creating MPD Configuration file Jul 11 17:37:32 hifiberry3 volumio[837]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 11 17:37:32 hifiberry3 sudo[1131]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 11 17:37:32 hifiberry3 sudo[1131]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 17:37:32 hifiberry3 volumio[837]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 11 17:37:32 hifiberry3 volumio[837]: info: [1720712252561] CoreMusicLibrary::Adding element Medienserver Jul 11 17:37:32 hifiberry3 volumio[837]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 11 17:37:32 hifiberry3 systemd[1]: Listening on mpd.socket. Jul 11 17:37:32 hifiberry3 sudo[1131]: pam_unix(sudo:session): session closed for user root Jul 11 17:37:32 hifiberry3 systemd[1]: Starting Music Player Daemon... Jul 11 17:37:32 hifiberry3 sudo[1134]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 11 17:37:32 hifiberry3 sudo[1134]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 17:37:32 hifiberry3 volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:32 hifiberry3 volumio[837]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 11 17:37:32 hifiberry3 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jul 11 17:37:32 hifiberry3 systemd[1]: mpd.service: Succeeded. Jul 11 17:37:32 hifiberry3 systemd[1]: Stopped Music Player Daemon. Jul 11 17:37:32 hifiberry3 volumio[837]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 11 17:37:32 hifiberry3 volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:32 hifiberry3 volumio[837]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 11 17:37:32 hifiberry3 systemd[1]: Starting Music Player Daemon... Jul 11 17:37:32 hifiberry3 volumio[837]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 11 17:37:32 hifiberry3 volumio[837]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 11 17:37:32 hifiberry3 volumio[837]: info: [1720712252884] CoreMusicLibrary::Adding element Last_100 Jul 11 17:37:32 hifiberry3 volumio[837]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 11 17:37:32 hifiberry3 volumio[837]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 11 17:37:32 hifiberry3 volumio[837]: info: [1720712252891] CoreMusicLibrary::Adding element Webradio Jul 11 17:37:32 hifiberry3 volumio[837]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 11 17:37:32 hifiberry3 volumio[837]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 11 17:37:32 hifiberry3 volumio[837]: info: Initializing BBC Radios Jul 11 17:37:33 hifiberry3 volumio[837]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 11 17:37:33 hifiberry3 volumio[837]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 11 17:37:33 hifiberry3 volumio[837]: info: Creating Spotify config file Jul 11 17:37:33 hifiberry3 volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:33 hifiberry3 sudo[1144]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 11 17:37:33 hifiberry3 sudo[1144]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 17:37:33 hifiberry3 sudo[1144]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jul 11 17:37:33 hifiberry3 sudo[1144]: pam_unix(sudo:session): session closed for user root Jul 11 17:37:33 hifiberry3 volumio[837]: info: Loading i18n strings for locale de Jul 11 17:37:33 hifiberry3 volumio[837]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 11 17:37:33 hifiberry3 volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jul 11 17:37:33 hifiberry3 volumio[837]: info: Volumio Calling Home Jul 11 17:37:33 hifiberry3 sudo[1167]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod +x /tmp/mpd_oled_plugin.sh Jul 11 17:37:33 hifiberry3 sudo[1167]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 17:37:33 hifiberry3 sudo[1167]: pam_unix(sudo:session): session closed for user root Jul 11 17:37:33 hifiberry3 sudo[1169]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start mpd_oled_plugin.service Jul 11 17:37:33 hifiberry3 sudo[1169]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 17:37:33 hifiberry3 volumio-remote-updater[619]: [2024-07-11 17:37:33] [connect] Successful connection Jul 11 17:37:33 hifiberry3 volumio[837]: info: Preparing to generate the ALSA configuration file Jul 11 17:37:33 hifiberry3 sudo[1178]: 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 Jul 11 17:37:33 hifiberry3 sudo[1178]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 17:37:33 hifiberry3 sudo[1178]: pam_unix(sudo:session): session closed for user root Jul 11 17:37:33 hifiberry3 volumio[837]: info: Discovery: adding edaa3bd7-709c-4833-9500-5cae3a177772 Jul 11 17:37:33 hifiberry3 volumio[837]: info: Discovery: Found device hifiberry3 Jul 11 17:37:33 hifiberry3 volumio[837]: info: CoreCommandRouter::volumioGetState Jul 11 17:37:33 hifiberry3 volumio[837]: info: CorePlayQueue::getTrack 0 Jul 11 17:37:34 hifiberry3 sudo[1181]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 11 17:37:34 hifiberry3 sudo[1181]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 17:37:34 hifiberry3 volumio[837]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Jul 11 17:37:34 hifiberry3 volumio[837]: info: Reading ALSA contributions from plugins. Jul 11 17:37:34 hifiberry3 sudo[1181]: pam_unix(sudo:session): session closed for user root Jul 11 17:37:34 hifiberry3 sudo[1183]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 11 17:37:34 hifiberry3 sudo[1183]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 17:37:34 hifiberry3 sudo[1183]: pam_unix(sudo:session): session closed for user root Jul 11 17:37:34 hifiberry3 volumio[837]: info: ir_controller: File permissions successfully set on /etc/lirc/*. Jul 11 17:37:34 hifiberry3 volumio[837]: info: [MPD_OLED Plugin] Set execute permissions on /tmp/mpd_oled_plugin.sh Jul 11 17:37:34 hifiberry3 volumio[837]: info: MPD Permissions set Jul 11 17:37:34 hifiberry3 volumio[837]: info: MPD Permissions set Jul 11 17:37:34 hifiberry3 volumio-remote-updater[619]: [2024-07-11 17:37:34] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1720712253 101 Jul 11 17:37:34 hifiberry3 volumio[837]: 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: 1 Jul 11 17:37:34 hifiberry3 volumio[837]: info: Volumio called home Jul 11 17:37:34 hifiberry3 volumio[837]: info: [MPD_OLED Plugin] tmp script created /tmp/mpd_oled_plugin.sh Jul 11 17:37:34 hifiberry3 volumio[837]: info: Spotify config file written Jul 11 17:37:34 hifiberry3 sudo[1188]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jul 11 17:37:34 hifiberry3 sudo[1188]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 17:37:34 hifiberry3 volumio[837]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jul 11 17:37:34 hifiberry3 volumio[837]: info: VolumeController:: Volume=41 Mute =false Jul 11 17:37:34 hifiberry3 volumio[837]: info: CoreStateMachine::pushState Jul 11 17:37:34 hifiberry3 volumio[837]: info: CorePlayQueue::getTrack 0 Jul 11 17:37:34 hifiberry3 volumio[837]: info: CoreCommandRouter::volumioPushState Jul 11 17:37:34 hifiberry3 systemd[1]: Started go-librespot Daemon. Jul 11 17:37:34 hifiberry3 go-librespot[1190]: Librespot-go daemon starting... Jul 11 17:37:34 hifiberry3 volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:34 hifiberry3 volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:34 hifiberry3 volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:34 hifiberry3 volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:34 hifiberry3 volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:34 hifiberry3 volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:34 hifiberry3 volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:34 hifiberry3 volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:34 hifiberry3 sudo[1188]: pam_unix(sudo:session): session closed for user root Jul 11 17:37:34 hifiberry3 volumio[837]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 11 17:37:34 hifiberry3 volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:34 hifiberry3 volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:34 hifiberry3 volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:34 hifiberry3 volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:34 hifiberry3 volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:34 hifiberry3 volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:34 hifiberry3 volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:34 hifiberry3 volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:34 hifiberry3 volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:34 hifiberry3 volumio[837]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 11 17:37:34 hifiberry3 volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:35 hifiberry3 volumio[837]: info: An error occurred while refreshing Spotify Token Error: Bad Request Jul 11 17:37:35 hifiberry3 volumio[837]: info: Starting Shairport Sync Jul 11 17:37:35 hifiberry3 volumio[837]: info: Starting Shairport Sync Jul 11 17:37:35 hifiberry3 volumio[837]: info: Starting Shairport Sync Jul 11 17:37:35 hifiberry3 sudo[1207]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 11 17:37:35 hifiberry3 sudo[1209]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 11 17:37:35 hifiberry3 sudo[1207]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 17:37:35 hifiberry3 sudo[1209]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 17:37:35 hifiberry3 go-librespot[1190]: time="2024-07-11T17:37:35+02:00" level=info msg="generated new device id: 79c702b65721a9763beb8c5bd4a958967915d762" Jul 11 17:37:35 hifiberry3 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 11 17:37:35 hifiberry3 systemd[1]: shairport-sync.service: Succeeded. Jul 11 17:37:35 hifiberry3 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 11 17:37:35 hifiberry3 sudo[1213]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 11 17:37:35 hifiberry3 sudo[1213]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 17:37:35 hifiberry3 sudo[1215]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jul 11 17:37:35 hifiberry3 sudo[1215]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 17:37:35 hifiberry3 volumio[837]: info: CoreCommandRouter::volumioGetState Jul 11 17:37:35 hifiberry3 volumio[837]: info: CorePlayQueue::getTrack 0 Jul 11 17:37:35 hifiberry3 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 11 17:37:35 hifiberry3 volumio[837]: info: ir_controller: LIRC correctly updated. Jul 11 17:37:35 hifiberry3 sudo[1209]: pam_unix(sudo:session): session closed for user root Jul 11 17:37:35 hifiberry3 volumio[837]: info: ir_controller: HAT did not load /proc/device-tree/ir_receiver! Jul 11 17:37:35 hifiberry3 sudo[1207]: pam_unix(sudo:session): session closed for user root Jul 11 17:37:35 hifiberry3 volumio[837]: info: Asound.conf file unchanged, so no further update is needed Jul 11 17:37:35 hifiberry3 volumio[837]: info: Output device has changed, restarting MPD Jul 11 17:37:35 hifiberry3 volumio[837]: info: Output device has changed, restarting Shairport Sync Jul 11 17:37:35 hifiberry3 volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:35 hifiberry3 volumio[837]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 11 17:37:35 hifiberry3 systemd[1]: Started UPnP Renderer front-end to MPD. Jul 11 17:37:35 hifiberry3 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 11 17:37:35 hifiberry3 systemd[1]: shairport-sync.service: Succeeded. Jul 11 17:37:35 hifiberry3 sudo[1215]: pam_unix(sudo:session): session closed for user root Jul 11 17:37:35 hifiberry3 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 11 17:37:35 hifiberry3 sudo[1225]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 11 17:37:35 hifiberry3 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 11 17:37:35 hifiberry3 sudo[1230]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 11 17:37:35 hifiberry3 sudo[1230]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 17:37:35 hifiberry3 sudo[1213]: pam_unix(sudo:session): session closed for user root Jul 11 17:37:35 hifiberry3 sudo[1225]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 17:37:35 hifiberry3 sudo[1225]: pam_unix(sudo:session): session closed for user root Jul 11 17:37:35 hifiberry3 volumio[837]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 11 17:37:35 hifiberry3 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jul 11 17:37:35 hifiberry3 systemd[1]: mpd.service: Succeeded. Jul 11 17:37:35 hifiberry3 systemd[1]: Stopped Music Player Daemon. Jul 11 17:37:35 hifiberry3 volumio[837]: info: ir_controller: Raspberry Pi revision code: a020d3 Jul 11 17:37:35 hifiberry3 volumio[837]: info: Shairport-Sync Started Jul 11 17:37:35 hifiberry3 volumio[837]: Error adding Membership: Error: addMembership EINVAL Jul 11 17:37:35 hifiberry3 volumio[837]: info: Shairport-Sync Started Jul 11 17:37:35 hifiberry3 systemd[1]: Starting Music Player Daemon... Jul 11 17:37:35 hifiberry3 volumio[837]: info: MPD Permissions set Jul 11 17:37:35 hifiberry3 volumio[837]: info: Shairport-Sync Started Jul 11 17:37:35 hifiberry3 volumio[837]: info: Upmpdcli Daemon Started Jul 11 17:37:35 hifiberry3 volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:35 hifiberry3 volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:35 hifiberry3 volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:35 hifiberry3 volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:35 hifiberry3 volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:35 hifiberry3 volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:35 hifiberry3 volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:35 hifiberry3 volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:36 hifiberry3 volumio[837]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 11 17:37:36 hifiberry3 volumio[837]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 11 17:37:36 hifiberry3 volumio[837]: info: Starting Shairport Sync Jul 11 17:37:36 hifiberry3 sudo[1246]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -r -1 Jul 11 17:37:36 hifiberry3 sudo[1246]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 17:37:36 hifiberry3 sudo[1246]: pam_unix(sudo:session): session closed for user root Jul 11 17:37:36 hifiberry3 sudo[1249]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 11 17:37:36 hifiberry3 sudo[1249]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 17:37:36 hifiberry3 sudo[1235]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 11 17:37:36 hifiberry3 sudo[1235]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 17:37:36 hifiberry3 sudo[1235]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jul 11 17:37:36 hifiberry3 sudo[1235]: pam_unix(sudo:session): session closed for user root Jul 11 17:37:36 hifiberry3 go-librespot[1190]: time="2024-07-11T17:37:36+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jul 11 17:37:36 hifiberry3 go-librespot[1190]: time="2024-07-11T17:37:36+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Jul 11 17:37:36 hifiberry3 go-librespot[1190]: time="2024-07-11T17:37:36+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Jul 11 17:37:36 hifiberry3 go-librespot[1190]: time="2024-07-11T17:37:36+02:00" level=debug msg="zeroconf server listening on port 44539" Jul 11 17:37:36 hifiberry3 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 11 17:37:36 hifiberry3 systemd[1]: shairport-sync.service: Succeeded. Jul 11 17:37:36 hifiberry3 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 11 17:37:36 hifiberry3 sudo[1258]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay gpio-ir gpio_pin=18 gpio_pull=up Jul 11 17:37:36 hifiberry3 sudo[1258]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 17:37:36 hifiberry3 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 11 17:37:36 hifiberry3 sudo[1249]: pam_unix(sudo:session): session closed for user root Jul 11 17:37:36 hifiberry3 volumio[837]: info: Shairport-Sync Started Jul 11 17:37:36 hifiberry3 kernel: pinctrl-bcm2835 3f200000.gpio: pin gpio18 already requested by 3f203000.i2s; cannot claim for ir-receiver@12 Jul 11 17:37:36 hifiberry3 kernel: pinctrl-bcm2835 3f200000.gpio: pin-18 (ir-receiver@12) status -22 Jul 11 17:37:36 hifiberry3 kernel: pinctrl-bcm2835 3f200000.gpio: could not request pin 18 (gpio18) from group gpio18 on device pinctrl-bcm2835 Jul 11 17:37:36 hifiberry3 kernel: gpio_ir_recv ir-receiver@12: Error applying setting, reverse things back Jul 11 17:37:36 hifiberry3 sudo[1268]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart lircd.service Jul 11 17:37:36 hifiberry3 sudo[1268]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 17:37:36 hifiberry3 sudo[1258]: pam_unix(sudo:session): session closed for user root Jul 11 17:37:36 hifiberry3 volumio[837]: info: ir_controller: Overlay gpio-ir gpio_pin=18 gpio_pull=up loaded. Jul 11 17:37:36 hifiberry3 sudo[1276]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart lircd.service Jul 11 17:37:36 hifiberry3 sudo[1276]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 17:37:36 hifiberry3 systemd[1]: Starting lircd(8) initialization helper tool... Jul 11 17:37:37 hifiberry3 lircd-setup[1277]: /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. Jul 11 17:37:37 hifiberry3 lircd-setup[1277]: parser = configparser.SafeConfigParser() Jul 11 17:37:37 hifiberry3 systemd[1]: systemd-hostnamed.service: Succeeded. Jul 11 17:37:37 hifiberry3 systemd[1]: lircd-setup.service: Succeeded. Jul 11 17:37:37 hifiberry3 systemd[1]: Started lircd(8) initialization helper tool. Jul 11 17:37:37 hifiberry3 systemd[1]: Starting Flexible IR remote input/output application support... Jul 11 17:37:37 hifiberry3 lircd-0.10.1[1281]: Info: lircd: Opening log, level: Info Jul 11 17:37:37 hifiberry3 lircd-0.10.1[1281]: Notice: Version: lircd 0.10.1 Jul 11 17:37:37 hifiberry3 lircd-0.10.1[1281]: Notice: System info: Linux hifiberry3 6.1.77-v7+ #1730 SMP Thu Feb 8 15:17:51 GMT 2024 armv7l GNU/Linux Jul 11 17:37:38 hifiberry3 lircd-0.10.1[1281]: Info: Initial device: /dev/lirc0 Jul 11 17:37:38 hifiberry3 lircd-0.10.1[1281]: Warning: Cannot access device: /dev/lirc0 Jul 11 17:37:38 hifiberry3 lircd-0.10.1[1281]: Info: Initial device: /dev/lirc0 Jul 11 17:37:38 hifiberry3 lircd-0.10.1[1281]: Warning: Cannot access device: /dev/lirc0 Jul 11 17:37:38 hifiberry3 lircd[1281]: lircd-0.10.1[1281]: Info: lircd: Opening log, level: Info Jul 11 17:37:38 hifiberry3 lircd[1281]: lircd-0.10.1[1281]: Notice: Using systemd fd Jul 11 17:37:38 hifiberry3 lircd-0.10.1[1281]: Notice: Options: driver: default Jul 11 17:37:38 hifiberry3 lircd[1281]: lircd-0.10.1[1281]: Warning: Running as root Jul 11 17:37:38 hifiberry3 lircd[1281]: lircd-0.10.1[1281]: Info: Using remote: lircd.conf. Jul 11 17:37:38 hifiberry3 lircd-0.10.1[1281]: Notice: Options: output: /var/run/lirc/lircd Jul 11 17:37:38 hifiberry3 lircd[1281]: lircd-0.10.1[1281]: Notice: lircd(default) ready, using /var/run/lirc/lircd Jul 11 17:37:38 hifiberry3 systemd[1]: Started Flexible IR remote input/output application support. Jul 11 17:37:38 hifiberry3 lircd-0.10.1[1281]: Notice: Options: nodaemon: 1 Jul 11 17:37:38 hifiberry3 lircd-0.10.1[1281]: Notice: Options: plugindir: /usr/lib/arm-linux-gnueabihf/lirc/plugins Jul 11 17:37:38 hifiberry3 lircd-0.10.1[1281]: Notice: Options: logfile: syslog Jul 11 17:37:38 hifiberry3 lircd-0.10.1[1281]: Notice: Options: immediate-init: 0 Jul 11 17:37:38 hifiberry3 lircd-0.10.1[1281]: Notice: Options: permission: 666 Jul 11 17:37:38 hifiberry3 lircd-0.10.1[1281]: Notice: Options: driver-options: Jul 11 17:37:38 hifiberry3 lircd-0.10.1[1281]: Notice: Options: pidfile: /var/run/lirc/lircd.pid Jul 11 17:37:38 hifiberry3 lircd-0.10.1[1281]: Notice: Options: listen: 0 Jul 11 17:37:38 hifiberry3 lircd-0.10.1[1281]: Notice: Options: connect: (null) Jul 11 17:37:38 hifiberry3 lircd-0.10.1[1281]: Notice: Options: userelease: 0 Jul 11 17:37:38 hifiberry3 lircd-0.10.1[1281]: Notice: Options: effective_user: (null) Jul 11 17:37:38 hifiberry3 lircd-0.10.1[1281]: Notice: Options: release_suffix: _EVUP Jul 11 17:37:38 hifiberry3 lircd-0.10.1[1281]: Notice: Options: allow_simulate: 0 Jul 11 17:37:38 hifiberry3 lircd-0.10.1[1281]: Notice: Options: repeat_max: 600 Jul 11 17:37:38 hifiberry3 lircd-0.10.1[1281]: Notice: Options: configfile: /etc/lirc/lircd.conf Jul 11 17:37:38 hifiberry3 lircd-0.10.1[1281]: Notice: Options: dynamic_codes: (null) Jul 11 17:37:38 hifiberry3 lircd-0.10.1[1281]: Notice: Current driver: default Jul 11 17:37:38 hifiberry3 lircd-0.10.1[1281]: Notice: Driver API version: 3 Jul 11 17:37:38 hifiberry3 lircd-0.10.1[1281]: Notice: Driver version: 0.10.0 Jul 11 17:37:38 hifiberry3 volumio[837]: info: go-librespot daemon successfully initialized Jul 11 17:37:38 hifiberry3 lircd-0.10.1[1281]: Notice: Driver info: See file:///usr/share/doc/lirc/plugindocs/default.html Jul 11 17:37:38 hifiberry3 lircd-0.10.1[1281]: Info: lircd: Opening log, level: Info Jul 11 17:37:38 hifiberry3 lircd-0.10.1[1281]: Notice: Using systemd fd Jul 11 17:37:38 hifiberry3 lircd-0.10.1[1281]: Warning: Running as root Jul 11 17:37:38 hifiberry3 lircd-0.10.1[1281]: Info: Using remote: lircd.conf. Jul 11 17:37:38 hifiberry3 lircd-0.10.1[1281]: Notice: lircd(default) ready, using /var/run/lirc/lircd Jul 11 17:37:38 hifiberry3 sudo[1268]: pam_unix(sudo:session): session closed for user root Jul 11 17:37:38 hifiberry3 sudo[1276]: pam_unix(sudo:session): session closed for user root Jul 11 17:37:38 hifiberry3 volumio[837]: info: ir_controller: systemctl restart lircd.service succeeded. Jul 11 17:37:38 hifiberry3 volumio[837]: info: ir_controller: systemctl restart lircd.service succeeded. Jul 11 17:37:38 hifiberry3 sudo[1286]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart irexec.service Jul 11 17:37:38 hifiberry3 sudo[1286]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 17:37:38 hifiberry3 sudo[1288]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart irexec.service Jul 11 17:37:38 hifiberry3 sudo[1288]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 11 17:37:38 hifiberry3 go-librespot[1190]: time="2024-07-11T17:37:38+02:00" level=debug msg="obtained new client token: AAC9KVOLBcidowwMHN42rELxPyiMP7vqdjs42YDFfNJkAa/qkvL3bXS7uagqL0DzwhjkxkK3qGvVewA/wyTjo+BHxEx+QirgE8igFDv6sRA5NkyzsvZGhuYbZbViNM/A7RDULWYfXsMiGWBXC9/v9Gf4yvT+Sc7/RLgNahNZQsTBzv1f2AIY4zqkmjbjQGpDcpvwKFaBIV/KgbEQoflr3A5mDkWeV2SuFJudmwcO58U7OdDax75fYd+soXkzTQ==" Jul 11 17:37:38 hifiberry3 systemd[1]: Started Handle events from IR remotes decoded by lircd(8). Jul 11 17:37:38 hifiberry3 lircd[1281]: lircd-0.10.1[1281]: Notice: accepted new client on /var/run/lirc/lircd Jul 11 17:37:38 hifiberry3 lircd[1281]: lircd-0.10.1[1281]: Info: Cannot configure the rc device for /dev/lirc0 Jul 11 17:37:38 hifiberry3 lircd[1281]: lircd-0.10.1[1281]: Error: could not get file information for /dev/lirc0 Jul 11 17:37:38 hifiberry3 lircd[1281]: lircd-0.10.1[1281]: default_init(): No such file or directory Jul 11 17:37:38 hifiberry3 lircd[1281]: lircd-0.10.1[1281]: Warning: Failed to initialize hardware Jul 11 17:37:38 hifiberry3 lircd-0.10.1[1281]: Notice: accepted new client on /var/run/lirc/lircd Jul 11 17:37:38 hifiberry3 lircd-0.10.1[1281]: Info: Cannot configure the rc device for /dev/lirc0 Jul 11 17:37:38 hifiberry3 lircd-0.10.1[1281]: Error: could not get file information for /dev/lirc0 Jul 11 17:37:38 hifiberry3 lircd-0.10.1[1281]: default_init(): No such file or directory Jul 11 17:37:38 hifiberry3 lircd-0.10.1[1281]: Warning: Failed to initialize hardware Jul 11 17:37:38 hifiberry3 sudo[1286]: pam_unix(sudo:session): session closed for user root Jul 11 17:37:38 hifiberry3 volumio[837]: info: ir_controller: systemctl restart irexec.service succeeded. Jul 11 17:37:38 hifiberry3 lircd[1281]: lircd-0.10.1[1281]: Error: could not get file information for /dev/lirc0 Jul 11 17:37:38 hifiberry3 lircd[1281]: lircd-0.10.1[1281]: default_init(): No such file or directory Jul 11 17:37:38 hifiberry3 lircd[1281]: lircd-0.10.1[1281]: Info: removed client Jul 11 17:37:38 hifiberry3 lircd-0.10.1[1281]: Error: could not get file information for /dev/lirc0 Jul 11 17:37:38 hifiberry3 lircd-0.10.1[1281]: default_init(): No such file or directory Jul 11 17:37:38 hifiberry3 lircd-0.10.1[1281]: Info: removed client Jul 11 17:37:38 hifiberry3 systemd[1]: Stopping Handle events from IR remotes decoded by lircd(8)... Jul 11 17:37:38 hifiberry3 systemd[1]: irexec.service: Main process exited, code=killed, status=15/TERM Jul 11 17:37:38 hifiberry3 systemd[1]: irexec.service: Succeeded. Jul 11 17:37:38 hifiberry3 systemd[1]: Stopped Handle events from IR remotes decoded by lircd(8). Jul 11 17:37:38 hifiberry3 systemd[1]: Started Handle events from IR remotes decoded by lircd(8). Jul 11 17:37:38 hifiberry3 lircd[1281]: lircd-0.10.1[1281]: Notice: accepted new client on /var/run/lirc/lircd Jul 11 17:37:38 hifiberry3 lircd[1281]: lircd-0.10.1[1281]: Info: Cannot configure the rc device for /dev/lirc0 Jul 11 17:37:38 hifiberry3 lircd[1281]: lircd-0.10.1[1281]: Error: could not get file information for /dev/lirc0 Jul 11 17:37:38 hifiberry3 lircd[1281]: lircd-0.10.1[1281]: default_init(): No such file or directory Jul 11 17:37:38 hifiberry3 lircd[1281]: lircd-0.10.1[1281]: Warning: Failed to initialize hardware Jul 11 17:37:38 hifiberry3 lircd-0.10.1[1281]: Notice: accepted new client on /var/run/lirc/lircd Jul 11 17:37:38 hifiberry3 lircd-0.10.1[1281]: Info: Cannot configure the rc device for /dev/lirc0 Jul 11 17:37:38 hifiberry3 lircd-0.10.1[1281]: Error: could not get file information for /dev/lirc0 Jul 11 17:37:38 hifiberry3 lircd-0.10.1[1281]: default_init(): No such file or directory Jul 11 17:37:38 hifiberry3 lircd-0.10.1[1281]: Warning: Failed to initialize hardware Jul 11 17:37:38 hifiberry3 sudo[1288]: pam_unix(sudo:session): session closed for user root Jul 11 17:37:38 hifiberry3 go-librespot[1190]: time="2024-07-11T17:37:38+02:00" level=debug msg="completed keyexchange" Jul 11 17:37:38 hifiberry3 volumio[837]: info: ir_controller: systemctl restart irexec.service succeeded. Jul 11 17:37:38 hifiberry3 go-librespot[1190]: time="2024-07-11T17:37:38+02:00" level=debug msg="completed challenge" Jul 11 17:37:39 hifiberry3 go-librespot[1190]: time="2024-07-11T17:37:39+02:00" level=debug msg="authenticated as 31fthnure2vfdpdrw47ypwz7267e" Jul 11 17:37:39 hifiberry3 go-librespot[1190]: time="2024-07-11T17:37:39+02:00" level=debug msg="authenticated as 31fthnure2vfdpdrw47ypwz7267e" Jul 11 17:37:39 hifiberry3 lircd[1281]: lircd-0.10.1[1281]: Error: could not get file information for /dev/lirc0 Jul 11 17:37:39 hifiberry3 lircd[1281]: lircd-0.10.1[1281]: default_init(): No such file or directory Jul 11 17:37:39 hifiberry3 lircd-0.10.1[1281]: Error: could not get file information for /dev/lirc0 Jul 11 17:37:39 hifiberry3 lircd-0.10.1[1281]: default_init(): No such file or directory Jul 11 17:37:39 hifiberry3 go-librespot[1190]: time="2024-07-11T17:37:39+02:00" level=debug msg="dealer connection opened" Jul 11 17:37:39 hifiberry3 go-librespot[1190]: time="2024-07-11T17:37:39+02:00" level=info msg="accepted zeroconf user 31fthnure2vfdpdrw47ypwz7267e from SM-A546B" Jul 11 17:37:39 hifiberry3 go-librespot[1190]: time="2024-07-11T17:37:39+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jul 11 17:37:39 hifiberry3 go-librespot[1190]: time="2024-07-11T17:37:39+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jul 11 17:37:39 hifiberry3 go-librespot[1190]: time="2024-07-11T17:37:39+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jul 11 17:37:39 hifiberry3 go-librespot[1190]: time="2024-07-11T17:37:39+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jul 11 17:37:39 hifiberry3 go-librespot[1190]: time="2024-07-11T17:37:39+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 513" Jul 11 17:37:39 hifiberry3 go-librespot[1190]: time="2024-07-11T17:37:39+02:00" level=debug msg="received connection id: MTQ3ODFlZDktODlmZS00MzY3LTg1NDQtNmM4NDg2NDEzZTJhK2RlYWxlcit0Y3A6Ly9nZXc0LWRlYWxlci1hLXgzdGYuZ2V3NC5zcG90aWZ5Lm5ldDo1NzAwK0UwNERCMUJFMkQ2Q0UzQkMwNUI0NkZCMjg1MjRFQTUyMEVDQzJGMzczMDUxRUY5NTBCNTQwRDY0MDZGNjcxNTI=" Jul 11 17:37:39 hifiberry3 go-librespot[1190]: time="2024-07-11T17:37:39+02:00" level=debug msg="put connect state because NEW_DEVICE" Jul 11 17:37:39 hifiberry3 go-librespot[1190]: time="2024-07-11T17:37:39+02:00" level=debug msg="handling transfer player command from 253fdc7460d9833c49f429f7e5a8f8f311b60a45" Jul 11 17:37:40 hifiberry3 go-librespot[1190]: time="2024-07-11T17:37:40+02:00" level=trace msg="fetched new page 0 with 50 items (list: 50)" Jul 11 17:37:40 hifiberry3 go-librespot[1190]: time="2024-07-11T17:37:40+02:00" level=debug msg="loading track spotify:track:3pPMgoouYtezLiBTEeL9be (paused: true, position: 119105ms)" Jul 11 17:37:40 hifiberry3 go-librespot[1190]: time="2024-07-11T17:37:40+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 11 17:37:40 hifiberry3 go-librespot[1190]: time="2024-07-11T17:37:40+02:00" level=trace msg="emitting websocket event: will_play" Jul 11 17:37:40 hifiberry3 go-librespot[1190]: time="2024-07-11T17:37:40+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 241" Jul 11 17:37:40 hifiberry3 mpd[1255]: Jul 11 17:37 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 11 17:37:40 hifiberry3 go-librespot[1190]: time="2024-07-11T17:37:40+02:00" level=debug msg="selected format OGG_VORBIS_320 for spotify:track:3pPMgoouYtezLiBTEeL9be" Jul 11 17:37:40 hifiberry3 go-librespot[1190]: time="2024-07-11T17:37:40+02:00" level=debug msg="requested aes key for file c6afac434dd3a7f3c06e82872c130aba91ffd6c4, gid: 3pPMgoouYtezLiBTEeL9be" Jul 11 17:37:40 hifiberry3 go-librespot[1190]: time="2024-07-11T17:37:40+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1631" Jul 11 17:37:40 hifiberry3 systemd[1]: Started Music Player Daemon. Jul 11 17:37:40 hifiberry3 volumio[837]: Upnp client error: Error: This socket has been ended by the other party Jul 11 17:37:40 hifiberry3 sudo[1230]: pam_unix(sudo:session): session closed for user root Jul 11 17:37:40 hifiberry3 sudo[1134]: pam_unix(sudo:session): session closed for user root Jul 11 17:37:40 hifiberry3 sudo[1107]: pam_unix(sudo:session): session closed for user root Jul 11 17:37:40 hifiberry3 volumio[837]: info: Completed starting Core Plugins Jul 11 17:37:40 hifiberry3 volumio[837]: info: ------------------------------------------- Jul 11 17:37:40 hifiberry3 volumio[837]: info: ----- MyVolumio plugins startup ---- Jul 11 17:37:40 hifiberry3 volumio[837]: info: ------------------------------------------- Jul 11 17:37:40 hifiberry3 volumio[837]: info: [MyVolumio PluginManager] Fetching plans data.... Jul 11 17:37:40 hifiberry3 volumio[837]: info: MPD running with PID1255 Jul 11 17:37:40 hifiberry3 volumio[837]: ,establishing connection Jul 11 17:37:40 hifiberry3 systemd[1]: Started MPD OLED Plugin. Jul 11 17:37:40 hifiberry3 sudo[1169]: pam_unix(sudo:session): session closed for user root Jul 11 17:37:40 hifiberry3 lircd[1281]: lircd-0.10.1[1281]: Error: could not get file information for /dev/lirc0 Jul 11 17:37:40 hifiberry3 lircd[1281]: lircd-0.10.1[1281]: default_init(): No such file or directory Jul 11 17:37:40 hifiberry3 lircd-0.10.1[1281]: Error: could not get file information for /dev/lirc0 Jul 11 17:37:40 hifiberry3 lircd-0.10.1[1281]: default_init(): No such file or directory Jul 11 17:37:40 hifiberry3 volumio[837]: error: MPD error: The expression evaluated to a falsy value: Jul 11 17:37:40 hifiberry3 volumio[837]: assert.ok(self.idling) Jul 11 17:37:40 hifiberry3 volumio[837]: error: The expression evaluated to a falsy value: Jul 11 17:37:40 hifiberry3 volumio[837]: assert.ok(self.idling) Jul 11 17:37:40 hifiberry3 volumio[837]: error: MPD error: The expression evaluated to a falsy value: Jul 11 17:37:40 hifiberry3 volumio[837]: assert.ok(self.idling) Jul 11 17:37:40 hifiberry3 volumio[837]: error: The expression evaluated to a falsy value: Jul 11 17:37:40 hifiberry3 volumio[837]: assert.ok(self.idling) Jul 11 17:37:40 hifiberry3 volumio[837]: error: MPD error: The expression evaluated to a falsy value: Jul 11 17:37:40 hifiberry3 volumio[837]: assert.ok(self.idling) Jul 11 17:37:40 hifiberry3 volumio[837]: error: The expression evaluated to a falsy value: Jul 11 17:37:40 hifiberry3 volumio[837]: assert.ok(self.idling) Jul 11 17:37:40 hifiberry3 volumio[837]: info: [MPD_OLED Plugin] Started mpd_oled_plugin service Jul 11 17:37:40 hifiberry3 volumio[837]: error: updateQueue error: null Jul 11 17:37:40 hifiberry3 go-librespot[1190]: time="2024-07-11T17:37:40+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1390" Jul 11 17:37:40 hifiberry3 volumio[837]: info: CoreCommandRouter::volumioGetState Jul 11 17:37:40 hifiberry3 volumio[837]: info: CorePlayQueue::getTrack 0 Jul 11 17:37:40 hifiberry3 volumio[837]: info: CoreCommandRouter::volumioGetState Jul 11 17:37:40 hifiberry3 volumio[837]: info: CorePlayQueue::getTrack 0 Jul 11 17:37:40 hifiberry3 volumio[1223]: Generating RSA private key, 4096 bit long modulus (2 primes) Jul 11 17:37:40 hifiberry3 volumio[837]: info: CoreCommandRouter::volumioGetState Jul 11 17:37:40 hifiberry3 volumio[837]: info: CorePlayQueue::getTrack 0 Jul 11 17:37:40 hifiberry3 volumio[837]: info: CoreCommandRouter::volumioGetState Jul 11 17:37:40 hifiberry3 volumio[837]: info: CorePlayQueue::getTrack 0 Jul 11 17:37:40 hifiberry3 go-librespot[1190]: time="2024-07-11T17:37:40+02:00" level=debug msg="fetched first chunk of 12, total size is 5969200 bytes" Jul 11 17:37:41 hifiberry3 volumio[837]: info: CoreCommandRouter::volumioGetState Jul 11 17:37:41 hifiberry3 volumio[837]: info: CorePlayQueue::getTrack 0 Jul 11 17:37:41 hifiberry3 volumio[837]: info: Initializing connection to go-librespot Websocket Jul 11 17:37:41 hifiberry3 go-librespot[1190]: time="2024-07-11T17:37:41+02:00" level=debug msg="fetched chunk 1/11, size: 524288" Jul 11 17:37:41 hifiberry3 go-librespot[1190]: time="2024-07-11T17:37:41+02:00" level=debug msg="new websocket client" Jul 11 17:37:41 hifiberry3 volumio[837]: info: Connection to go-librespot Websocket established Jul 11 17:37:41 hifiberry3 volumio[837]: info: CoreCommandRouter::volumioGetState Jul 11 17:37:41 hifiberry3 volumio[837]: info: CorePlayQueue::getTrack 0 Jul 11 17:37:41 hifiberry3 go-librespot[1190]: time="2024-07-11T17:37:41+02:00" level=debug msg="fetched chunk 2/11, size: 524288" Jul 11 17:37:41 hifiberry3 go-librespot[1190]: time="2024-07-11T17:37:41+02:00" level=debug msg="fetched chunk 11/11, size: 202032" Jul 11 17:37:41 hifiberry3 lircd[1281]: lircd-0.10.1[1281]: Error: could not get file information for /dev/lirc0 Jul 11 17:37:41 hifiberry3 lircd[1281]: lircd-0.10.1[1281]: default_init(): No such file or directory Jul 11 17:37:41 hifiberry3 lircd-0.10.1[1281]: Error: could not get file information for /dev/lirc0 Jul 11 17:37:41 hifiberry3 lircd-0.10.1[1281]: default_init(): No such file or directory Jul 11 17:37:41 hifiberry3 go-librespot[1190]: time="2024-07-11T17:37:41+02:00" level=debug msg="fetched chunk 3/11, size: 524288" Jul 11 17:37:41 hifiberry3 volumio[837]: info: CoreCommandRouter::volumioGetState Jul 11 17:37:41 hifiberry3 volumio[837]: info: CorePlayQueue::getTrack 0 Jul 11 17:37:41 hifiberry3 go-librespot[1190]: time="2024-07-11T17:37:41+02:00" level=debug msg="fetched chunk 10/11, size: 524288" Jul 11 17:37:41 hifiberry3 volumio[837]: info: CoreCommandRouter::volumioGetState Jul 11 17:37:41 hifiberry3 volumio[837]: info: CorePlayQueue::getTrack 0 Jul 11 17:37:41 hifiberry3 go-librespot[1190]: time="2024-07-11T17:37:41+02:00" level=trace msg="seek to 119105ms (diff: 50ms, samples: 5252530, bytes: 5310797)" Jul 11 17:37:41 hifiberry3 kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Jul 11 17:37:41 hifiberry3 go-librespot[1190]: time="2024-07-11T17:37:41+02:00" level=info msg="loaded track \"VEMPA\" (uri: spotify:track:3pPMgoouYtezLiBTEeL9be, paused: true, position: 119105ms, duration: 134528ms)" Jul 11 17:37:41 hifiberry3 go-librespot[1190]: time="2024-07-11T17:37:41+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 11 17:37:41 hifiberry3 go-librespot[1190]: time="2024-07-11T17:37:41+02:00" level=trace msg="emitting websocket event: metadata" Jul 11 17:37:41 hifiberry3 go-librespot[1190]: time="2024-07-11T17:37:41+02:00" level=trace msg="emitting websocket event: active" Jul 11 17:37:41 hifiberry3 go-librespot[1190]: time="2024-07-11T17:37:41+02:00" level=debug msg="sending successful reply for delaer request" Jul 11 17:37:41 hifiberry3 go-librespot[1190]: time="2024-07-11T17:37:41+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Jul 11 17:37:41 hifiberry3 go-librespot[1190]: time="2024-07-11T17:37:41+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Jul 11 17:37:41 hifiberry3 go-librespot[1190]: time="2024-07-11T17:37:41+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Jul 11 17:37:41 hifiberry3 volumio[837]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:3pPMgoouYtezLiBTEeL9be","name":"VEMPA","artist_names":["FOURTY","Bausa"],"album_name":"VEMPA","album_cover_url":"https://i.scdn.co/image/ab67616d00001e026423f32068faad29d0b50e22","position":119105,"duration":134528,"release_date":"year:2024 month:4 day:19","track_number":1,"disc_number":1}} Jul 11 17:37:41 hifiberry3 volumio[837]: SPOTIFY: received: {"type":"active","data":null} Jul 11 17:37:41 hifiberry3 volumio[837]: info: Aligning Spotify Volume to Volumio Volume Jul 11 17:37:41 hifiberry3 volumio[837]: info: CoreCommandRouter::volumioGetState Jul 11 17:37:41 hifiberry3 volumio[837]: info: CorePlayQueue::getTrack 0 Jul 11 17:37:41 hifiberry3 volumio[837]: info: Setting Spotify Volume from Volumio: 41 Jul 11 17:37:41 hifiberry3 volumio[837]: info: CoreCommandRouter::volumioGetState Jul 11 17:37:41 hifiberry3 volumio[837]: info: CorePlayQueue::getTrack 0 Jul 11 17:37:41 hifiberry3 volumio[837]: info: CoreCommandRouter::volumioGetState Jul 11 17:37:41 hifiberry3 volumio[837]: info: CorePlayQueue::getTrack 0 Jul 11 17:37:41 hifiberry3 go-librespot[1190]: time="2024-07-11T17:37:41+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 11 17:37:41 hifiberry3 go-librespot[1190]: time="2024-07-11T17:37:41+02:00" level=trace msg="emitting websocket event: paused" Jul 11 17:37:41 hifiberry3 volumio[837]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:3pPMgoouYtezLiBTEeL9be","play_origin":"playlist"}} Jul 11 17:37:41 hifiberry3 volumio[837]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 11 17:37:41 hifiberry3 volumio[837]: TypeError: Cannot read property 'service' of undefined Jul 11 17:37:41 hifiberry3 volumio[837]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:336:50) Jul 11 17:37:41 hifiberry3 volumio[837]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:270:18) Jul 11 17:37:41 hifiberry3 volumio[837]: at WebSocket.message (/data/plugins/music_service/spop/index.js:189:14) Jul 11 17:37:41 hifiberry3 volumio[837]: at WebSocket.emit (events.js:315:20) Jul 11 17:37:41 hifiberry3 volumio[837]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1209:20) Jul 11 17:37:41 hifiberry3 volumio[837]: at Receiver.emit (events.js:315:20) Jul 11 17:37:41 hifiberry3 volumio[837]: at Receiver.dataMessage (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:594:14) Jul 11 17:37:41 hifiberry3 volumio[837]: at Receiver.getData (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:496:10) Jul 11 17:37:41 hifiberry3 volumio[837]: at Receiver.startLoop (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:167:16) Jul 11 17:37:41 hifiberry3 volumio[837]: at Receiver._write (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:94:10) Jul 11 17:37:41 hifiberry3 volumio[837]: at writeOrBuffer (internal/streams/writable.js:358:12) Jul 11 17:37:41 hifiberry3 volumio[837]: at Receiver.Writable.write (internal/streams/writable.js:303:10) Jul 11 17:37:41 hifiberry3 volumio[837]: at Socket.socketOnData (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1303:35) Jul 11 17:37:41 hifiberry3 volumio[837]: at Socket.emit (events.js:315:20) Jul 11 17:37:41 hifiberry3 volumio[837]: at addChunk (internal/streams/readable.js:309:12) Jul 11 17:37:41 hifiberry3 volumio[837]: at readableAddChunk (internal/streams/readable.js:284:9) Jul 11 17:37:41 hifiberry3 volumio[837]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 11 17:37:42 hifiberry3 lircd[1281]: lircd-0.10.1[1281]: Error: could not get file information for /dev/lirc0 Jul 11 17:37:42 hifiberry3 lircd[1281]: lircd-0.10.1[1281]: default_init(): No such file or directory Jul 11 17:37:42 hifiberry3 lircd-0.10.1[1281]: Error: could not get file information for /dev/lirc0 Jul 11 17:37:42 hifiberry3 lircd-0.10.1[1281]: default_init(): No such file or directory Jul 11 17:37:43 hifiberry3 sudo[1325]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-11 17:36 Jul 11 17:37:43 hifiberry3 sudo[1325]: 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"