Apr 16 03:31:01 pi5dev bluealsa[1121]: bluez.c:1480: Signal: org.freedesktop.DBus.Properties.PropertiesChanged(): org.bluez.MediaTransport1: State Apr 16 03:31:01 pi5dev bluealsa[1121]: ../src/ba-transport.c:319: New A2DP transport: 15 Apr 16 03:31:01 pi5dev bluealsa[1121]: ../src/ba-transport.c:320: A2DP socket MTU: 15: R:672 W:1008 Apr 16 03:31:01 pi5dev volumio[3665]: ------------------------------------ BT MESSAGE: [dbus-next] Playback state changed: true from 5C:17:CF:0D:C0:70 Apr 16 03:31:01 pi5dev volumio[3665]: ------------------------------------ BT MESSAGE: Playback started, enabling output Apr 16 03:31:01 pi5dev volumio[3665]: info: CoreCommandRouter::volumioStop Apr 16 03:31:01 pi5dev volumio[3665]: info: CoreStateMachine::stop Apr 16 03:31:01 pi5dev volumio[3665]: info: CoreStateMachine::serviceStop Apr 16 03:31:01 pi5dev volumio[3665]: info: CoreCommandRouter::serviceStop Apr 16 03:31:01 pi5dev bluealsa[1121]: bluez.c:1480: Signal: org.freedesktop.DBus.Properties.PropertiesChanged(): org.bluez.MediaTransport1: State Apr 16 03:31:01 pi5dev volumio[3665]: error: WARNING: No stop method for service [object Object] Apr 16 03:31:01 pi5dev bluealsa[1121]: ../src/ba-transport.c:1075: Starting transport: A2DP Sink (SBC) Apr 16 03:31:01 pi5dev bluealsa[1121]: ../src/ba-transport-pcm.c:294: Created BT socket duplicate: [15]: 16 Apr 16 03:31:01 pi5dev bluealsa[1121]: ../src/a2dp-sbc.c:331: PCM IO loop: START: a2dp_sbc_dec_thread: A2DP Sink (SBC) Apr 16 03:31:01 pi5dev bluealsa[1121]: ../src/ba-transport-pcm.c:373: Created new IO thread [ba-a2dp-sbc]: A2DP Sink (SBC) Apr 16 03:31:01 pi5dev dbus-daemon[958]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.81" (uid=1000 pid=3665 comm="/usr/bin/node /volumio/index.js") interface="(unset)" member="(unset)" error name="com.github.dbus_next.Error" requested_reply="0" destination=":1.1" (uid=0 pid=957 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") Apr 16 03:31:01 pi5dev volumio[3665]: ------------------------------------ BT MESSAGE: Seek received post-resume, pushing metadata Apr 16 03:31:01 pi5dev volumio[3665]: info: CoreCommandRouter::servicePushState Apr 16 03:31:01 pi5dev volumio[3665]: info: CoreStateMachine::pushState Apr 16 03:31:01 pi5dev volumio[3665]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 03:31:01 pi5dev volumio[3665]: info: CoreCommandRouter::volumioPushState Apr 16 03:31:01 pi5dev volumio[3665]: info: MRS: Pushing multiroomSync output update for this device Apr 16 03:31:01 pi5dev volumio[3665]: info: MRS: Pushing multiroomSync output Apr 16 03:31:01 pi5dev volumio[3665]: info: PeppyMeterBasic ---peppymeterbasic status play Apr 16 03:31:01 pi5dev sudo[5941]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Apr 16 03:31:01 pi5dev sudo[5941]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 16 03:31:01 pi5dev volumio[3665]: info: touch_display: Setting screensaver timeout to 0 seconds. Apr 16 03:31:01 pi5dev systemd[1]: /etc/systemd/system/peppymeterbasic.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Apr 16 03:31:01 pi5dev systemd[1]: /etc/systemd/system/peppymeterbasic.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Apr 16 03:31:01 pi5dev systemd[1]: Started peppymeterbasic.service - peppymeterbasic Daemon. Apr 16 03:31:01 pi5dev sudo[5941]: pam_unix(sudo:session): session closed for user root Apr 16 03:31:01 pi5dev volumio[3665]: info: peppymeterbasic Daemon Started Apr 16 03:31:01 pi5dev volumio[5946]: failed to open /dev/dri/renderD128: Permission denied Apr 16 03:31:01 pi5dev volumio[5946]: glx: failed to create dri3 screen Apr 16 03:31:01 pi5dev volumio[5946]: failed to load driver: drm-rp1-dsi Apr 16 03:31:01 pi5dev volumio[5946]: failed to open /dev/dri/renderD128: Permission denied Apr 16 03:31:01 pi5dev volumio[5946]: glx: failed to create dri2 screen Apr 16 03:31:01 pi5dev volumio[5946]: failed to load driver: drm-rp1-dsi Apr 16 03:31:01 pi5dev volumio[5946]: failed to open /dev/dri/renderD128: Permission denied Apr 16 03:31:01 pi5dev volumio[5946]: glx: failed to create dri3 screen Apr 16 03:31:01 pi5dev volumio[5946]: failed to load driver: drm-rp1-dsi Apr 16 03:31:01 pi5dev volumio[5946]: failed to open /dev/dri/renderD128: Permission denied Apr 16 03:31:01 pi5dev volumio[5946]: glx: failed to create dri2 screen Apr 16 03:31:01 pi5dev volumio[5946]: failed to load driver: drm-rp1-dsi Apr 16 03:31:01 pi5dev volumio[5946]: Failed to create /home/volumio/.cache/mesa_shader_cache for shader cache (Permission denied)---disabling. Apr 16 03:31:01 pi5dev volumio[5946]: Failed to create /home/volumio/.cache/mesa_shader_cache_db for shader cache (Permission denied)---disabling. Apr 16 03:31:02 pi5dev volumio[5946]: libpng warning: iCCP: known incorrect sRGB profile Apr 16 03:31:02 pi5dev volumio[5946]: libpng warning: iCCP: known incorrect sRGB profile Apr 16 03:31:03 pi5dev volumio[3665]: ------------------------------------ BT MESSAGE: [DEBUG] registerVolumeHandler: MAC = 5C:17:CF:0D:C0:70, rawVolume = 56 Apr 16 03:31:03 pi5dev volumio[3665]: ------------------------------------ BT MESSAGE: Received volume: raw = 56, scaled = 44 Apr 16 03:31:03 pi5dev volumio[3665]: info: VolumeController::SetAlsaVolume44 Apr 16 03:31:03 pi5dev volumio[3665]: ------------------------------------ BT MESSAGE: [VOLUME HOOK] Intercepted volume 44 - forwarding to Bluetooth device Apr 16 03:31:03 pi5dev bluealsa[1121]: bluez.c:1480: Signal: org.freedesktop.DBus.Properties.PropertiesChanged(): org.bluez.MediaTransport1: Volume Apr 16 03:31:03 pi5dev bluealsa[1121]: bluez.c:1502: Updating A2DP volume: 56 [-11.81 dB] Apr 16 03:31:03 pi5dev dbus-daemon[958]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.81" (uid=1000 pid=3665 comm="/usr/bin/node /volumio/index.js") interface="(unset)" member="(unset)" error name="com.github.dbus_next.Error" requested_reply="0" destination=":1.1" (uid=0 pid=957 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") Apr 16 03:31:03 pi5dev volumio[3665]: info: CoreStateMachine::pushState Apr 16 03:31:03 pi5dev volumio[3665]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 03:31:03 pi5dev volumio[3665]: info: CoreCommandRouter::volumioPushState Apr 16 03:31:03 pi5dev volumio[3665]: info: MRS: Pushing multiroomSync output update for this device Apr 16 03:31:03 pi5dev volumio[3665]: info: MRS: Pushing multiroomSync output Apr 16 03:31:03 pi5dev volumio[3665]: info: PeppyMeterBasic ---peppymeterbasic status play Apr 16 03:31:03 pi5dev sudo[5961]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Apr 16 03:31:03 pi5dev sudo[5961]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 16 03:31:03 pi5dev sudo[5961]: pam_unix(sudo:session): session closed for user root Apr 16 03:31:03 pi5dev volumio[3665]: info: peppymeterbasic Daemon Started Apr 16 03:31:04 pi5dev bluealsa[1121]: bluez.c:1480: Signal: org.freedesktop.DBus.Properties.PropertiesChanged(): org.bluez.MediaTransport1: Volume Apr 16 03:31:04 pi5dev bluealsa[1121]: bluez.c:1502: Updating A2DP volume: 60 [-10.81 dB] Apr 16 03:31:04 pi5dev volumio[3665]: ------------------------------------ BT MESSAGE: [DEBUG] registerVolumeHandler: MAC = 5C:17:CF:0D:C0:70, rawVolume = 60 Apr 16 03:31:04 pi5dev volumio[3665]: ------------------------------------ BT MESSAGE: Received volume: raw = 60, scaled = 47 Apr 16 03:31:04 pi5dev volumio[3665]: info: VolumeController::SetAlsaVolume47 Apr 16 03:31:04 pi5dev volumio[3665]: ------------------------------------ BT MESSAGE: [VOLUME HOOK] Intercepted volume 47 - forwarding to Bluetooth device Apr 16 03:31:04 pi5dev dbus-daemon[958]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.81" (uid=1000 pid=3665 comm="/usr/bin/node /volumio/index.js") interface="(unset)" member="(unset)" error name="com.github.dbus_next.Error" requested_reply="0" destination=":1.1" (uid=0 pid=957 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") Apr 16 03:31:04 pi5dev volumio[3665]: info: CoreStateMachine::pushState Apr 16 03:31:04 pi5dev volumio[3665]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 03:31:04 pi5dev volumio[3665]: info: CoreCommandRouter::volumioPushState Apr 16 03:31:04 pi5dev volumio[3665]: info: MRS: Pushing multiroomSync output update for this device Apr 16 03:31:04 pi5dev volumio[3665]: info: MRS: Pushing multiroomSync output Apr 16 03:31:04 pi5dev volumio[3665]: info: PeppyMeterBasic ---peppymeterbasic status play Apr 16 03:31:04 pi5dev sudo[5965]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Apr 16 03:31:04 pi5dev sudo[5965]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 16 03:31:04 pi5dev sudo[5965]: pam_unix(sudo:session): session closed for user root Apr 16 03:31:04 pi5dev volumio[3665]: info: peppymeterbasic Daemon Started Apr 16 03:31:06 pi5dev bluealsa[1121]: bluez.c:1480: Signal: org.freedesktop.DBus.Properties.PropertiesChanged(): org.bluez.MediaTransport1: Volume Apr 16 03:31:06 pi5dev bluealsa[1121]: bluez.c:1502: Updating A2DP volume: 56 [-11.81 dB] Apr 16 03:31:06 pi5dev volumio[3665]: ------------------------------------ BT MESSAGE: [DEBUG] registerVolumeHandler: MAC = 5C:17:CF:0D:C0:70, rawVolume = 56 Apr 16 03:31:06 pi5dev volumio[3665]: ------------------------------------ BT MESSAGE: Received volume: raw = 56, scaled = 44 Apr 16 03:31:06 pi5dev volumio[3665]: info: VolumeController::SetAlsaVolume44 Apr 16 03:31:06 pi5dev volumio[3665]: ------------------------------------ BT MESSAGE: [VOLUME HOOK] Intercepted volume 44 - forwarding to Bluetooth device Apr 16 03:31:06 pi5dev dbus-daemon[958]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.81" (uid=1000 pid=3665 comm="/usr/bin/node /volumio/index.js") interface="(unset)" member="(unset)" error name="com.github.dbus_next.Error" requested_reply="0" destination=":1.1" (uid=0 pid=957 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") Apr 16 03:31:06 pi5dev volumio[3665]: info: CoreStateMachine::pushState Apr 16 03:31:06 pi5dev volumio[3665]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 03:31:06 pi5dev volumio[3665]: info: CoreCommandRouter::volumioPushState Apr 16 03:31:06 pi5dev volumio[3665]: info: MRS: Pushing multiroomSync output update for this device Apr 16 03:31:06 pi5dev volumio[3665]: info: MRS: Pushing multiroomSync output Apr 16 03:31:06 pi5dev volumio[3665]: info: PeppyMeterBasic ---peppymeterbasic status play Apr 16 03:31:06 pi5dev sudo[5983]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Apr 16 03:31:06 pi5dev sudo[5983]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 16 03:31:06 pi5dev sudo[5983]: pam_unix(sudo:session): session closed for user root Apr 16 03:31:06 pi5dev volumio[3665]: info: peppymeterbasic Daemon Started Apr 16 03:31:07 pi5dev bluealsa[1121]: bluez.c:1480: Signal: org.freedesktop.DBus.Properties.PropertiesChanged(): org.bluez.MediaTransport1: Volume Apr 16 03:31:07 pi5dev volumio[3665]: ------------------------------------ BT MESSAGE: [DEBUG] registerVolumeHandler: MAC = 5C:17:CF:0D:C0:70, rawVolume = 51 Apr 16 03:31:07 pi5dev volumio[3665]: ------------------------------------ BT MESSAGE: Received volume: raw = 51, scaled = 40 Apr 16 03:31:07 pi5dev volumio[3665]: info: VolumeController::SetAlsaVolume40 Apr 16 03:31:07 pi5dev volumio[3665]: ------------------------------------ BT MESSAGE: [VOLUME HOOK] Intercepted volume 40 - forwarding to Bluetooth device Apr 16 03:31:07 pi5dev bluealsa[1121]: bluez.c:1502: Updating A2DP volume: 51 [-13.16 dB] Apr 16 03:31:07 pi5dev dbus-daemon[958]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.81" (uid=1000 pid=3665 comm="/usr/bin/node /volumio/index.js") interface="(unset)" member="(unset)" error name="com.github.dbus_next.Error" requested_reply="0" destination=":1.1" (uid=0 pid=957 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") Apr 16 03:31:07 pi5dev volumio[3665]: info: CoreStateMachine::pushState Apr 16 03:31:07 pi5dev volumio[3665]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 03:31:07 pi5dev volumio[3665]: info: CoreCommandRouter::volumioPushState Apr 16 03:31:07 pi5dev volumio[3665]: info: MRS: Pushing multiroomSync output update for this device Apr 16 03:31:07 pi5dev volumio[3665]: info: MRS: Pushing multiroomSync output Apr 16 03:31:07 pi5dev volumio[3665]: info: PeppyMeterBasic ---peppymeterbasic status play Apr 16 03:31:07 pi5dev sudo[5987]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Apr 16 03:31:07 pi5dev sudo[5987]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 16 03:31:07 pi5dev sudo[5987]: pam_unix(sudo:session): session closed for user root Apr 16 03:31:07 pi5dev volumio[3665]: info: peppymeterbasic Daemon Started Apr 16 03:31:11 pi5dev bluealsa[1121]: bluez.c:1480: Signal: org.freedesktop.DBus.Properties.PropertiesChanged(): org.bluez.MediaTransport1: State Apr 16 03:31:11 pi5dev bluealsa[1121]: ../src/ba-transport-pcm.c:307: Closing BT socket duplicate [15]: 16 Apr 16 03:31:11 pi5dev volumio[3665]: ------------------------------------ BT MESSAGE: [dbus-next] Playback state changed: false from 5C:17:CF:0D:C0:70 Apr 16 03:31:11 pi5dev volumio[3665]: ------------------------------------ BT MESSAGE: Received pause signal, scheduling idle check Apr 16 03:31:11 pi5dev volumio[3665]: info: CoreCommandRouter::servicePushState Apr 16 03:31:11 pi5dev volumio[3665]: info: CoreStateMachine::pushState Apr 16 03:31:11 pi5dev bluealsa[1121]: ../src/ba-transport.c:381: Closing A2DP transport: 15 Apr 16 03:31:11 pi5dev volumio[3665]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 03:31:11 pi5dev volumio[3665]: info: CoreCommandRouter::volumioPushState Apr 16 03:31:11 pi5dev bluealsa[1121]: ../src/ba-transport-pcm.c:257: Exiting IO thread [ba-a2dp-sbc]: A2DP Sink (SBC) Apr 16 03:31:11 pi5dev volumio[3665]: info: MRS: Pushing multiroomSync output update for this device Apr 16 03:31:11 pi5dev volumio[3665]: info: MRS: Pushing multiroomSync output Apr 16 03:31:11 pi5dev volumio[3665]: info: PeppyMeterBasic ---peppymeterbasic status pause Apr 16 03:31:11 pi5dev sudo[5990]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Apr 16 03:31:11 pi5dev sudo[5990]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 16 03:31:11 pi5dev volumio[3665]: info: touch_display: Setting screensaver timeout to 120 seconds. Apr 16 03:31:11 pi5dev systemd[1]: Stopping peppymeterbasic.service - peppymeterbasic Daemon... Apr 16 03:31:12 pi5dev systemd[1]: peppymeterbasic.service: Deactivated successfully. Apr 16 03:31:12 pi5dev systemd[1]: Stopped peppymeterbasic.service - peppymeterbasic Daemon. Apr 16 03:31:12 pi5dev systemd[1]: peppymeterbasic.service: Consumed 2.716s CPU time. Apr 16 03:31:12 pi5dev systemd[1]: /etc/systemd/system/peppymeterbasic.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Apr 16 03:31:12 pi5dev systemd[1]: /etc/systemd/system/peppymeterbasic.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Apr 16 03:31:12 pi5dev systemd[1]: /etc/systemd/system/peppymeterbasic.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Apr 16 03:31:12 pi5dev systemd[1]: /etc/systemd/system/peppymeterbasic.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Apr 16 03:31:12 pi5dev sudo[5990]: pam_unix(sudo:session): session closed for user root Apr 16 03:31:12 pi5dev volumio[3665]: info: peppymeterbasic Daemon Stop Apr 16 03:31:19 pi5dev volumio[3665]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 16 03:31:19 pi5dev volumio[3665]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 16 03:31:25 pi5dev volumio[3665]: info: Stopping peppymeterbasic service Apr 16 03:31:25 pi5dev volumio[3665]: info: CoreStateMachine::stop Apr 16 03:31:25 pi5dev volumio[3665]: info: CoreStateMachine::serviceStop Apr 16 03:31:25 pi5dev volumio[3665]: info: CoreCommandRouter::serviceStop Apr 16 03:31:25 pi5dev volumio[3665]: error: WARNING: No stop method for service [object Object] Apr 16 03:31:25 pi5dev volumio[3665]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 16 03:31:25 pi5dev volumio[3665]: TypeError: Cannot read properties of undefined (reading 'then') Apr 16 03:31:25 pi5dev volumio[3665]: at peppymeterbasic.onStop (/data/plugins/user_interface/peppymeterbasic/index.js:60:43) Apr 16 03:31:25 pi5dev volumio[3665]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Apr 16 03:31:25 pi5dev volumio[3665]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8) Apr 16 03:31:25 pi5dev volumio[3665]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1556:29) Apr 16 03:31:25 pi5dev volumio[3665]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1401:47) Apr 16 03:31:25 pi5dev volumio[3665]: at Socket.emit (node:events:514:28) Apr 16 03:31:25 pi5dev volumio[3665]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Apr 16 03:31:25 pi5dev volumio[3665]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Apr 16 03:31:25 pi5dev volumio[3665]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 16 03:31:25 pi5dev sudo[6024]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-04-16 03:30' Apr 16 03:31:25 pi5dev sudo[6024]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 16 03:31:25 pi5dev sudo[6024]: pam_unix(sudo:session): session closed for user root Apr 16 03:31:25 pi5dev volumio-remote-updater[998]: [2025-04-16 03:31:25] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Apr 16 03:31:25 pi5dev volumio-remote-updater[998]: [2025-04-16 03:31:25] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Apr 16 03:31:25 pi5dev vtcs[5178]: [2025-04-16 03:31:25.326] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Apr 16 03:31:25 pi5dev systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 16 03:31:25 pi5dev systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 16 03:31:25 pi5dev systemd[1]: volumio.service: Consumed 30.793s CPU time. Apr 16 03:31:25 pi5dev systemd[1]: Started dynamicswap.service - dynamicswap service. Apr 16 03:31:25 pi5dev systemd[1]: dynamicswap.service: Deactivated successfully. Apr 16 03:31:25 pi5dev systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Apr 16 03:31:25 pi5dev systemd[1]: Started dynamicswap.service - dynamicswap service. Apr 16 03:31:25 pi5dev systemd[1]: Stopped volumio.service - Volumio Backend Module. Apr 16 03:31:25 pi5dev systemd[1]: volumio.service: Consumed 30.793s CPU time. Apr 16 03:31:25 pi5dev systemd[1]: Started volumio.service - Volumio Backend Module. Apr 16 03:31:25 pi5dev systemd[1]: dynamicswap.service: Deactivated successfully. Apr 16 03:31:26 pi5dev volumio[6066]: info: ------------------------------------------- Apr 16 03:31:26 pi5dev volumio[6066]: info: ----- Volumio3 ---- Apr 16 03:31:26 pi5dev volumio[6066]: info: ------------------------------------------- Apr 16 03:31:26 pi5dev volumio[6066]: info: ----- System startup ---- Apr 16 03:31:26 pi5dev volumio[6066]: info: ------------------------------------------- Apr 16 03:31:26 pi5dev volumio[6066]: info: MYVOLUMIO Environment detected Apr 16 03:31:26 pi5dev volumio[6066]: info: Plugin folders cleanup Apr 16 03:31:26 pi5dev volumio[6066]: info: Scanning into folder /volumio/app/plugins/ Apr 16 03:31:26 pi5dev volumio[6066]: info: Scanning category audio_interface Apr 16 03:31:26 pi5dev volumio[6066]: info: Scanning category miscellanea Apr 16 03:31:26 pi5dev volumio[6066]: info: Scanning category music_service Apr 16 03:31:26 pi5dev volumio[6066]: info: Scanning category plugins.json Apr 16 03:31:26 pi5dev volumio[6066]: info: Scanning category system_controller Apr 16 03:31:26 pi5dev volumio[6066]: info: Scanning category user_interface Apr 16 03:31:26 pi5dev volumio[6066]: info: Scanning into folder /data/plugins/ Apr 16 03:31:26 pi5dev volumio[6066]: info: Scanning category user_interface Apr 16 03:31:26 pi5dev volumio[6066]: info: Plugin folders cleanup completed Apr 16 03:31:26 pi5dev volumio[6066]: info: ------------------------------------------- Apr 16 03:31:26 pi5dev volumio[6066]: info: ----- Core plugins startup ---- Apr 16 03:31:26 pi5dev volumio[6066]: info: ------------------------------------------- Apr 16 03:31:26 pi5dev volumio[6066]: info: Loading plugins from folder /volumio/app/plugins/ Apr 16 03:31:26 pi5dev volumio[6066]: info: Adding plugin upnp to MyMusic Plugins Apr 16 03:31:26 pi5dev volumio[6066]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 16 03:31:26 pi5dev volumio[6066]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 16 03:31:26 pi5dev volumio[6066]: info: Loading plugins from folder /data/plugins/ Apr 16 03:31:26 pi5dev volumio[6066]: info: Loading plugin "system"... Apr 16 03:31:26 pi5dev volumio[6066]: info: Loading plugin "appearance"... Apr 16 03:31:26 pi5dev volumio[6066]: info: Loading plugin "network"... Apr 16 03:31:26 pi5dev volumio[6066]: info: Refreshing Cached IP Addresses Apr 16 03:31:26 pi5dev sudo[6094]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 16 03:31:26 pi5dev sudo[6096]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 16 03:31:26 pi5dev sudo[6096]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 16 03:31:26 pi5dev sudo[6094]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 16 03:31:26 pi5dev sudo[6096]: pam_unix(sudo:session): session closed for user root Apr 16 03:31:26 pi5dev sudo[6094]: pam_unix(sudo:session): session closed for user root Apr 16 03:31:26 pi5dev volumio[6066]: info: Loading plugin "services"... Apr 16 03:31:26 pi5dev volumio[6066]: info: Loading plugin "alsa_controller"... Apr 16 03:31:26 pi5dev sudo[6105]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 16 03:31:26 pi5dev sudo[6105]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 16 03:31:26 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 16 03:31:26 pi5dev volumio[6066]: info: Loading plugin "wizard"... Apr 16 03:31:26 pi5dev volumio[6066]: info: Loading plugin "networkfs"... Apr 16 03:31:26 pi5dev volumio[6066]: info: Starting Udev Watcher for removable devices Apr 16 03:31:26 pi5dev volumio[6066]: info: Ignoring mount for partition: boot Apr 16 03:31:26 pi5dev volumio[6066]: info: Ignoring mount for partition: volumio Apr 16 03:31:26 pi5dev volumio[6066]: info: Ignoring mount for partition: volumio_data Apr 16 03:31:26 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 16 03:31:26 pi5dev volumio[6066]: info: Loading plugin "volumio_command_line_client"... Apr 16 03:31:26 pi5dev volumio[6066]: info: Loading plugin "upnp"... Apr 16 03:31:26 pi5dev volumio[6066]: info: [1744770686491] Starting Upmpd Daemon Apr 16 03:31:26 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 16 03:31:26 pi5dev volumio[6066]: info: Loading plugin "my_music"... Apr 16 03:31:26 pi5dev volumio[6066]: info: Loading plugin "mpd"... Apr 16 03:31:26 pi5dev volumio[6066]: info: Loading plugin "upnp_browser"... Apr 16 03:31:26 pi5dev volumio[6066]: info: Loading plugin "alarm-clock"... Apr 16 03:31:26 pi5dev volumio[6066]: info: Loading plugin "airplay_emulation"... Apr 16 03:31:26 pi5dev volumio[6066]: info: Starting Shairport Sync Apr 16 03:31:26 pi5dev volumio[6066]: info: Loading plugin "last_100"... Apr 16 03:31:26 pi5dev volumio[6066]: info: Loading plugin "webradio"... Apr 16 03:31:26 pi5dev volumio[6066]: info: Loading plugin "i2s_dacs"... Apr 16 03:31:26 pi5dev volumio[6066]: info: Loading plugin "volumiodiscovery"... Apr 16 03:31:26 pi5dev volumio[6066]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 16 03:31:26 pi5dev volumio[6066]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 16 03:31:26 pi5dev volumio[6066]: *** WARNING *** For more information see Apr 16 03:31:26 pi5dev volumio[6066]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 16 03:31:26 pi5dev volumio[6066]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 16 03:31:26 pi5dev volumio[6066]: *** WARNING *** For more information see Apr 16 03:31:26 pi5dev volumio[6066]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 16 03:31:26 pi5dev node[6066]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 16 03:31:26 pi5dev node[6066]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 16 03:31:26 pi5dev node[6066]: *** WARNING *** For more information see Apr 16 03:31:26 pi5dev node[6066]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 16 03:31:26 pi5dev node[6066]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 16 03:31:26 pi5dev node[6066]: *** WARNING *** For more information see Apr 16 03:31:26 pi5dev volumio[6066]: info: Discovery: Started advertising with name: pi5dev Apr 16 03:31:26 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 16 03:31:26 pi5dev volumio[6066]: info: Loading plugin "outputs"... Apr 16 03:31:26 pi5dev volumio[6066]: info: Loading plugin "albumart"... Apr 16 03:31:26 pi5dev volumio[6066]: info: Plugin example_plugin is not enabled Apr 16 03:31:26 pi5dev volumio[6066]: info: Loading plugin "inputs"... Apr 16 03:31:26 pi5dev volumio[6066]: info: Loading plugin "updater_comm"... Apr 16 03:31:26 pi5dev volumio[6066]: info: Plugin mpdemulation is not enabled Apr 16 03:31:26 pi5dev volumio[6066]: info: Loading plugin "rest_api"... Apr 16 03:31:26 pi5dev volumio[6066]: info: Loading plugin "websocket"... Apr 16 03:31:26 pi5dev volumio[6066]: info: Starting Socket.io Server version 1.7.4 Apr 16 03:31:26 pi5dev volumio[6066]: info: Loading plugin "Systeminfo"... Apr 16 03:31:27 pi5dev volumio[6066]: info: Loading plugin "peppymeterbasic"... Apr 16 03:31:27 pi5dev volumio[6126]: Forking 3 albumart workers Apr 16 03:31:27 pi5dev volumio[6066]: info: Loading plugin "touch_display"... Apr 16 03:31:27 pi5dev volumio[6066]: info: Applying required configuration parameters for plugin touch_display Apr 16 03:31:27 pi5dev volumio[6066]: info: Loading i18n strings for locale en Apr 16 03:31:27 pi5dev volumio[6066]: Updating browse sources language Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::initPlayerControls Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 16 03:31:27 pi5dev volumio[6066]: Express server listening on port 3000 Apr 16 03:31:27 pi5dev volumio[6066]: [Metrics] WebUI: 1s 303.24ms Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreStateMachine::resetVolumioState Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreStateMachine::getcurrentVolume Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::volumioRetrievevolume Apr 16 03:31:27 pi5dev volumio[6066]: info: Cannot read play queue from file Apr 16 03:31:27 pi5dev volumio[6066]: info: Volumio Network Manager: Network status updated: 1 Apr 16 03:31:27 pi5dev volumio[6066]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Apr 16 03:31:27 pi5dev volumio[6138]: Starting albumart workers Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreStateMachine::pushState Apr 16 03:31:27 pi5dev volumio[6066]: info: CorePlayQueue::getTrack 0 Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::volumioPushState Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreStateMachine::updateTrackBlock Apr 16 03:31:27 pi5dev volumio[6137]: Starting albumart workers Apr 16 03:31:27 pi5dev volumio[6066]: info: CorePlayQueue::getTrackBlock Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::volumioRetrievevolume Apr 16 03:31:27 pi5dev volumio[6066]: verbose: New Socket.io Connection to 192.168.30.36 from 192.168.30.10 UA: Mozilla/5.0 (X11; Linux x86_64; rv:137.0) Gecko/20100101 Firefox/137.0 Engine version: 3 Transport: polling Total Clients: 2 Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreStateMachine::setRepeat true single undefined Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreStateMachine::pushState Apr 16 03:31:27 pi5dev volumio[6066]: info: CorePlayQueue::getTrack 0 Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::volumioPushState Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreStateMachine::setRandom undefined Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreStateMachine::pushState Apr 16 03:31:27 pi5dev volumio[6066]: info: CorePlayQueue::getTrack 0 Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::volumioPushState Apr 16 03:31:27 pi5dev volumio[6066]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Apr 16 03:31:27 pi5dev volumio[6066]: info: USB Boot Capable - System SBC Revision found in cpuinfo: e04171 Apr 16 03:31:27 pi5dev volumio[6066]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreStateMachine::pushState Apr 16 03:31:27 pi5dev volumio[6066]: info: CorePlayQueue::getTrack 0 Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::volumioPushState Apr 16 03:31:27 pi5dev volumio[6136]: Starting albumart workers Apr 16 03:31:27 pi5dev volumio[6066]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Apr 16 03:31:27 pi5dev volumio[6066]: info: Setting Device type: Raspberry PI Apr 16 03:31:27 pi5dev volumio[6066]: info: Completed loading Core Plugins Apr 16 03:31:27 pi5dev volumio[6066]: info: Preparing to generate the ALSA configuration file Apr 16 03:31:27 pi5dev volumio[6066]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Apr 16 03:31:27 pi5dev volumio[6066]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Apr 16 03:31:27 pi5dev volumio[6066]: info: Reading ALSA contributions from plugins. Apr 16 03:31:27 pi5dev volumio[6066]: info: Asound.conf file written Apr 16 03:31:27 pi5dev sudo[6198]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 16 03:31:27 pi5dev sudo[6198]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 16 03:31:27 pi5dev sudo[6198]: pam_unix(sudo:session): session closed for user root Apr 16 03:31:27 pi5dev volumio[6066]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Apr 16 03:31:27 pi5dev volumio[6066]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Apr 16 03:31:27 pi5dev volumio[6066]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Apr 16 03:31:27 pi5dev volumio[6066]: info: Output device has changed, restarting MPD Apr 16 03:31:27 pi5dev volumio[6066]: info: Output device has changed, restarting Shairport Sync Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 16 03:31:27 pi5dev sudo[6204]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 16 03:31:27 pi5dev sudo[6204]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 16 03:31:27 pi5dev sudo[6207]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 16 03:31:27 pi5dev sudo[6207]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 16 03:31:27 pi5dev sudo[6204]: pam_unix(sudo:session): session closed for user root Apr 16 03:31:27 pi5dev volumio[6066]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 16 03:31:27 pi5dev volumio[6066]: info: ___________ START PLUGINS ___________ Apr 16 03:31:27 pi5dev volumio[6066]: info: ControllerMpd::onStart: Initializing MPD Apr 16 03:31:27 pi5dev volumio[6066]: info: Creating MPD Configuration file Apr 16 03:31:27 pi5dev systemd[1]: Stopping mpd.service - Music Player Daemon... Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 16 03:31:27 pi5dev volumio[6066]: info: [1744770687551] CoreMusicLibrary::Adding element Media Servers Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 16 03:31:27 pi5dev sudo[6220]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 16 03:31:27 pi5dev sudo[6220]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 16 03:31:27 pi5dev sudo[6222]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 16 03:31:27 pi5dev sudo[6222]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 16 03:31:27 pi5dev systemd[1]: mpd.service: Deactivated successfully. Apr 16 03:31:27 pi5dev systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 16 03:31:27 pi5dev systemd[1]: mpd.socket: Deactivated successfully. Apr 16 03:31:27 pi5dev systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 16 03:31:27 pi5dev systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 16 03:31:27 pi5dev sudo[6220]: pam_unix(sudo:session): session closed for user root Apr 16 03:31:27 pi5dev volumio[6066]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 16 03:31:27 pi5dev systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 16 03:31:27 pi5dev systemd[1]: Starting mpd.service - Music Player Daemon... Apr 16 03:31:27 pi5dev systemd[1]: mpd.service: Deactivated successfully. Apr 16 03:31:27 pi5dev systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 16 03:31:27 pi5dev systemd[1]: mpd.socket: Deactivated successfully. Apr 16 03:31:27 pi5dev systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 16 03:31:27 pi5dev systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 16 03:31:27 pi5dev systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 16 03:31:27 pi5dev systemd[1]: Starting mpd.service - Music Player Daemon... Apr 16 03:31:27 pi5dev volumio[6066]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 16 03:31:27 pi5dev volumio[6066]: info: [1744770687598] CoreMusicLibrary::Adding element Last_100 Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 16 03:31:27 pi5dev volumio[6066]: info: [1744770687599] CoreMusicLibrary::Adding element Webradio Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 16 03:31:27 pi5dev volumio[6066]: info: Initializing BBC Radios Apr 16 03:31:27 pi5dev sudo[6242]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 16 03:31:27 pi5dev sudo[6242]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 16 03:31:27 pi5dev sudo[6242]: pam_unix(sudo:session): session closed for user root Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Apr 16 03:31:27 pi5dev volumio[6066]: info: Loading i18n strings for locale en Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 16 03:31:27 pi5dev volumio[6066]: info: Volumio Calling Home Apr 16 03:31:27 pi5dev volumio[6066]: info: Preparing to generate the ALSA configuration file Apr 16 03:31:27 pi5dev sudo[6263]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Apr 16 03:31:27 pi5dev sudo[6263]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 16 03:31:27 pi5dev sudo[6268]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Apr 16 03:31:27 pi5dev sudo[6268]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 16 03:31:27 pi5dev sudo[6265]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Apr 16 03:31:27 pi5dev sudo[6265]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 16 03:31:27 pi5dev systemd[1]: Reloading. Apr 16 03:31:27 pi5dev volumio[6066]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Apr 16 03:31:27 pi5dev volumio[6066]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Apr 16 03:31:27 pi5dev volumio[6066]: info: Reading ALSA contributions from plugins. Apr 16 03:31:27 pi5dev volumio[6066]: info: touch_display: Backlight interface detected. Apr 16 03:31:27 pi5dev volumio[6066]: verbose: New Socket.io Connection to 192.168.30.36 from 192.168.30.10 UA: Mozilla/5.0 (X11; Linux x86_64; rv:137.0) Gecko/20100101 Firefox/137.0 Engine version: 3 Transport: polling Total Clients: 4 Apr 16 03:31:27 pi5dev volumio[6066]: info: MPD Permissions set Apr 16 03:31:27 pi5dev volumio[6066]: info: MPD Permissions set Apr 16 03:31:27 pi5dev volumio[6066]: info: Volumio called home Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::volumioGetState Apr 16 03:31:27 pi5dev volumio[6066]: info: CorePlayQueue::getTrack 0 Apr 16 03:31:27 pi5dev volumio[6066]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 03:31:27 pi5dev systemd[1]: /lib/systemd/system/volumio-remote-updater.service:5: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Apr 16 03:31:27 pi5dev systemd[1]: /lib/systemd/system/volumio-remote-updater.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 03:31:27 pi5dev volumio[6066]: info: Received Get System Info Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 16 03:31:27 pi5dev volumio[6066]: info: Discovery: Getting this device information Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::volumioGetState Apr 16 03:31:27 pi5dev volumio[6066]: info: CorePlayQueue::getTrack 0 Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::volumioGetState Apr 16 03:31:27 pi5dev volumio[6066]: info: CorePlayQueue::getTrack 0 Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::volumioGetVisibleSources Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::volumioGetState Apr 16 03:31:27 pi5dev volumio[6066]: info: CorePlayQueue::getTrack 0 Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::volumioGetState Apr 16 03:31:27 pi5dev volumio[6066]: info: CorePlayQueue::getTrack 0 Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 16 03:31:27 pi5dev volumio[6066]: info: Received Get System Info Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 16 03:31:27 pi5dev volumio[6066]: info: Discovery: Getting this device information Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::volumioGetState Apr 16 03:31:27 pi5dev volumio[6066]: info: CorePlayQueue::getTrack 0 Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 16 03:31:27 pi5dev volumio[6066]: info: CoreCommandRouter::volumioGetState Apr 16 03:31:27 pi5dev volumio[6066]: info: CorePlayQueue::getTrack 0 Apr 16 03:31:27 pi5dev volumio[6066]: info: Listing playlists Apr 16 03:31:27 pi5dev volumio[6066]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Apr 16 03:31:28 pi5dev volumio[6066]: info: touch_display: Raspberry Pi Foundation touch screen detected. Apr 16 03:31:28 pi5dev sudo[6299]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Apr 16 03:31:28 pi5dev sudo[6299]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 16 03:31:28 pi5dev sudo[6299]: pam_unix(sudo:session): session closed for user root Apr 16 03:31:28 pi5dev sudo[6303]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+w /sys/class/backlight/11-0045/brightness Apr 16 03:31:28 pi5dev sudo[6303]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 16 03:31:28 pi5dev sudo[6303]: pam_unix(sudo:session): session closed for user root Apr 16 03:31:28 pi5dev sudo[6301]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Apr 16 03:31:28 pi5dev sudo[6301]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 16 03:31:28 pi5dev sudo[6301]: pam_unix(sudo:session): session closed for user root Apr 16 03:31:28 pi5dev volumio[6066]: info: Starting Shairport Sync Apr 16 03:31:28 pi5dev volumio[6066]: info: Starting Shairport Sync Apr 16 03:31:28 pi5dev volumio[6066]: info: Starting Shairport Sync Apr 16 03:31:28 pi5dev sudo[6309]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 16 03:31:28 pi5dev sudo[6309]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 16 03:31:28 pi5dev sudo[6311]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 16 03:31:28 pi5dev sudo[6311]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 16 03:31:28 pi5dev sudo[6313]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 16 03:31:28 pi5dev volumio[6066]: info: touch_display: File permissions for backlight brightness control set. Apr 16 03:31:28 pi5dev sudo[6313]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 16 03:31:28 pi5dev volumio[6066]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Apr 16 03:31:28 pi5dev volumio[6066]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Apr 16 03:31:28 pi5dev volumio[6066]: info: Asound.conf file unchanged, so no further update is needed Apr 16 03:31:28 pi5dev volumio[6066]: info: Output device has changed, restarting MPD Apr 16 03:31:28 pi5dev systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Apr 16 03:31:28 pi5dev systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Apr 16 03:31:28 pi5dev sudo[6322]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 16 03:31:28 pi5dev sudo[6322]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 16 03:31:28 pi5dev volumio[6066]: info: Output device has changed, restarting Shairport Sync Apr 16 03:31:28 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 03:31:28 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 16 03:31:28 pi5dev sudo[6322]: pam_unix(sudo:session): session closed for user root Apr 16 03:31:28 pi5dev volumio[6066]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 16 03:31:28 pi5dev sudo[6324]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 16 03:31:28 pi5dev sudo[6324]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 16 03:31:28 pi5dev volumio[6066]: info: Discovery: adding 20ed410c-3060-416c-9e85-112a5bec4c9b Apr 16 03:31:28 pi5dev volumio[6066]: info: Discovery: Found device pi5dev Apr 16 03:31:28 pi5dev volumio[6066]: info: CoreCommandRouter::volumioGetState Apr 16 03:31:28 pi5dev volumio[6066]: info: CorePlayQueue::getTrack 0 Apr 16 03:31:28 pi5dev volumio[6066]: info: Discovery: this is already registered, 20ed410c-3060-416c-9e85-112a5bec4c9b Apr 16 03:31:28 pi5dev volumio[6066]: info: Discovery: Found device pi5dev Apr 16 03:31:28 pi5dev volumio[6066]: info: CoreCommandRouter::volumioGetState Apr 16 03:31:28 pi5dev volumio[6066]: info: CorePlayQueue::getTrack 0 Apr 16 03:31:28 pi5dev sudo[6268]: pam_unix(sudo:session): session closed for user root Apr 16 03:31:28 pi5dev mpd[6257]: Apr 16 03:31 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 16 03:31:28 pi5dev volumio[6066]: info: Discovery: this is already registered, 20ed410c-3060-416c-9e85-112a5bec4c9b Apr 16 03:31:28 pi5dev volumio[6066]: info: Discovery: Found device pi5dev Apr 16 03:31:28 pi5dev volumio[6066]: info: CoreCommandRouter::volumioGetState Apr 16 03:31:28 pi5dev volumio[6066]: info: CorePlayQueue::getTrack 0 Apr 16 03:31:28 pi5dev systemd[1]: Started mpd.service - Music Player Daemon. Apr 16 03:31:28 pi5dev sudo[6222]: pam_unix(sudo:session): session closed for user root Apr 16 03:31:28 pi5dev systemd[1]: Reloading. Apr 16 03:31:28 pi5dev sudo[6207]: pam_unix(sudo:session): session closed for user root Apr 16 03:31:28 pi5dev volumio[6066]: info: MPD Permissions set Apr 16 03:31:28 pi5dev volumio[6066]: info: touch_display: systemctl daemon-reload succeeded. Apr 16 03:31:28 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 03:31:28 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 03:31:28 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 03:31:28 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 03:31:28 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 03:31:28 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 03:31:28 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 03:31:28 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 03:31:28 pi5dev sudo[6339]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Apr 16 03:31:28 pi5dev sudo[6339]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 16 03:31:28 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 16 03:31:28 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 03:31:28 pi5dev volumio[6066]: error: MPD error: The expression evaluated to a falsy value: Apr 16 03:31:28 pi5dev volumio[6066]: assert.ok(self.idling) Apr 16 03:31:28 pi5dev volumio[6066]: error: The expression evaluated to a falsy value: Apr 16 03:31:28 pi5dev volumio[6066]: assert.ok(self.idling) Apr 16 03:31:28 pi5dev volumio[6066]: info: MPD running with PID6257 Apr 16 03:31:28 pi5dev volumio[6066]: ,establishing connection Apr 16 03:31:28 pi5dev volumio[6066]: error: updateQueue error: null Apr 16 03:31:28 pi5dev volumio[6066]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/135.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Apr 16 03:31:28 pi5dev volumio[6066]: info: CoreCommandRouter::volumioGetState Apr 16 03:31:28 pi5dev volumio[6066]: info: CorePlayQueue::getTrack 0 Apr 16 03:31:28 pi5dev volumio[6066]: info: Starting Shairport Sync Apr 16 03:31:28 pi5dev sudo[6363]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 16 03:31:28 pi5dev sudo[6363]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 16 03:31:28 pi5dev volumio[6066]: error: updateQueue error: null Apr 16 03:31:28 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 16 03:31:28 pi5dev systemd[1]: /lib/systemd/system/volumio-remote-updater.service:5: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Apr 16 03:31:28 pi5dev systemd[1]: /lib/systemd/system/volumio-remote-updater.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Apr 16 03:31:28 pi5dev volumio[6066]: info: CoreCommandRouter::volumioGetVisibleSources Apr 16 03:31:28 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 16 03:31:28 pi5dev volumio[6066]: info: CoreCommandRouter::volumioGetState Apr 16 03:31:28 pi5dev volumio[6066]: info: CorePlayQueue::getTrack 0 Apr 16 03:31:28 pi5dev volumio[6066]: info: CoreCommandRouter::volumioGetState Apr 16 03:31:28 pi5dev volumio[6066]: info: CorePlayQueue::getTrack 0 Apr 16 03:31:28 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 16 03:31:28 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 16 03:31:28 pi5dev volumio[6066]: info: Received Get System Info Apr 16 03:31:28 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 16 03:31:28 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 16 03:31:28 pi5dev volumio[6066]: info: Discovery: Getting this device information Apr 16 03:31:28 pi5dev volumio[6066]: info: CoreCommandRouter::volumioGetState Apr 16 03:31:28 pi5dev volumio[6066]: info: CorePlayQueue::getTrack 0 Apr 16 03:31:28 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 16 03:31:28 pi5dev volumio[6066]: info: CoreCommandRouter::volumioGetState Apr 16 03:31:28 pi5dev volumio[6066]: info: CorePlayQueue::getTrack 0 Apr 16 03:31:28 pi5dev volumio[6066]: info: Listing playlists Apr 16 03:31:28 pi5dev systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Apr 16 03:31:28 pi5dev systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Apr 16 03:31:28 pi5dev sudo[6265]: pam_unix(sudo:session): session closed for user root Apr 16 03:31:28 pi5dev volumio[6066]: info: touch_display: systemctl disable getty@tty1.service succeeded. Apr 16 03:31:28 pi5dev systemd[1]: Stopping mpd.service - Music Player Daemon... Apr 16 03:31:28 pi5dev systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 16 03:31:28 pi5dev sudo[6339]: pam_unix(sudo:session): session closed for user root Apr 16 03:31:28 pi5dev volumio[6066]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Apr 16 03:31:28 pi5dev volumio[6066]: info: touch_display: Volumio Kiosk started. Apr 16 03:31:28 pi5dev systemd[1]: shairport-sync.service: Deactivated successfully. Apr 16 03:31:28 pi5dev systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 16 03:31:28 pi5dev systemd[1]: shairport-sync.service: Consumed 1.988s CPU time. Apr 16 03:31:28 pi5dev volumio[6066]: info: CoreCommandRouter::volumioGetState Apr 16 03:31:28 pi5dev volumio[6066]: info: CorePlayQueue::getTrack 0 Apr 16 03:31:28 pi5dev systemd[1]: mpd.service: Deactivated successfully. Apr 16 03:31:28 pi5dev systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 16 03:31:28 pi5dev volumio[6066]: info: Completed starting Core Plugins Apr 16 03:31:28 pi5dev volumio[6066]: info: ------------------------------------------- Apr 16 03:31:28 pi5dev volumio[6066]: info: ----- MyVolumio plugins startup ---- Apr 16 03:31:28 pi5dev volumio[6066]: info: ------------------------------------------- Apr 16 03:31:28 pi5dev volumio[6066]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 16 03:31:28 pi5dev sudo[6263]: pam_unix(sudo:session): session closed for user root Apr 16 03:31:28 pi5dev volumio[6066]: info: CoreCommandRouter::volumioGetState Apr 16 03:31:28 pi5dev volumio[6066]: info: CorePlayQueue::getTrack 0 Apr 16 03:31:28 pi5dev volumio[6066]: info: touch_display: systemctl stop getty@tty1.service succeeded. Apr 16 03:31:28 pi5dev systemd[1]: mpd.socket: Deactivated successfully. Apr 16 03:31:28 pi5dev systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 16 03:31:28 pi5dev systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 16 03:31:28 pi5dev systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 16 03:31:28 pi5dev systemd[1]: Starting mpd.service - Music Player Daemon... Apr 16 03:31:28 pi5dev systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 16 03:31:28 pi5dev sudo[6309]: pam_unix(sudo:session): session closed for user root Apr 16 03:31:28 pi5dev sudo[6313]: pam_unix(sudo:session): session closed for user root Apr 16 03:31:28 pi5dev sudo[6363]: pam_unix(sudo:session): session closed for user root Apr 16 03:31:28 pi5dev volumio[6066]: info: Shairport-Sync Started Apr 16 03:31:28 pi5dev volumio[6066]: Error adding Membership: Error: addMembership EINVAL Apr 16 03:31:28 pi5dev volumio[6066]: info: Shairport-Sync Started Apr 16 03:31:28 pi5dev volumio[6066]: info: Shairport-Sync Started Apr 16 03:31:28 pi5dev sudo[6311]: pam_unix(sudo:session): session closed for user root Apr 16 03:31:28 pi5dev volumio[6066]: info: Shairport-Sync Started Apr 16 03:31:28 pi5dev volumio[6066]: info: touch_display: X display number found: 0 Apr 16 03:31:28 pi5dev sudo[6369]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 16 03:31:28 pi5dev sudo[6369]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 16 03:31:28 pi5dev sudo[6369]: pam_unix(sudo:session): session closed for user root Apr 16 03:31:28 pi5dev volumio[6066]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Apr 16 03:31:28 pi5dev volumio[6066]: info: touch_display: X display number found: 0 Apr 16 03:31:28 pi5dev volumio[6066]: info: CoreCommandRouter::volumioGetState Apr 16 03:31:28 pi5dev volumio[6066]: info: CorePlayQueue::getTrack 0 Apr 16 03:31:28 pi5dev volumio[6066]: info: touch_display: Setting screensaver timeout to 120 seconds. Apr 16 03:31:29 pi5dev mpd[6386]: Apr 16 03:31 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 16 03:31:29 pi5dev sudo[6324]: pam_unix(sudo:session): session closed for user root Apr 16 03:31:29 pi5dev systemd[1]: Started mpd.service - Music Player Daemon. Apr 16 03:31:29 pi5dev volumio[6066]: error: updateQueue error: null Apr 16 03:31:30 pi5dev volumio-remote-updater[998]: [2025-04-16 03:31:30] [connect] Successful connection Apr 16 03:31:30 pi5dev volumio-remote-updater[998]: [2025-04-16 03:31:30] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1744770690 101 Apr 16 03:31:30 pi5dev volumio[6066]: 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: 8 Apr 16 03:31:30 pi5dev sudo[6105]: pam_unix(sudo:session): session closed for user root Apr 16 03:31:33 pi5dev volumio[6066]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Apr 16 03:31:36 pi5dev sudo[6410]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 16 03:31:36 pi5dev sudo[6410]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 16 03:31:36 pi5dev sudo[6412]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 16 03:31:36 pi5dev sudo[6412]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 16 03:31:36 pi5dev sudo[6410]: pam_unix(sudo:session): session closed for user root Apr 16 03:31:36 pi5dev sudo[6412]: pam_unix(sudo:session): session closed for user root Apr 16 03:31:36 pi5dev sudo[6416]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 16 03:31:36 pi5dev sudo[6416]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 16 03:31:36 pi5dev sudo[6416]: pam_unix(sudo:session): session closed for user root Apr 16 03:31:36 pi5dev volumio[6066]: info: Upmpdcli Daemon Started Apr 16 03:31:36 pi5dev volumio[6066]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Apr 16 03:31:36 pi5dev volumio[6066]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Apr 16 03:31:36 pi5dev volumio[6066]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Apr 16 03:31:36 pi5dev volumio[6066]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Apr 16 03:31:36 pi5dev volumio[6066]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Apr 16 03:31:36 pi5dev volumio[6066]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Apr 16 03:31:36 pi5dev volumio[6066]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Apr 16 03:31:36 pi5dev volumio[6066]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Apr 16 03:31:36 pi5dev volumio[6066]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Apr 16 03:31:36 pi5dev volumio[6066]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Apr 16 03:31:36 pi5dev volumio[6066]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Apr 16 03:31:36 pi5dev volumio[6066]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Apr 16 03:31:36 pi5dev volumio[6066]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Apr 16 03:31:36 pi5dev volumio[6066]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Apr 16 03:31:36 pi5dev volumio[6066]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Apr 16 03:31:36 pi5dev volumio[6066]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Apr 16 03:31:36 pi5dev volumio[6066]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Apr 16 03:31:36 pi5dev volumio[6066]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Apr 16 03:31:36 pi5dev volumio[6066]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Apr 16 03:31:36 pi5dev volumio[6066]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Apr 16 03:31:36 pi5dev volumio[6066]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Apr 16 03:31:36 pi5dev volumio[6066]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Apr 16 03:31:36 pi5dev volumio[6066]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Apr 16 03:31:36 pi5dev volumio[6066]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Apr 16 03:31:36 pi5dev volumio[6066]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Apr 16 03:31:36 pi5dev volumio[6066]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Apr 16 03:31:36 pi5dev volumio[6066]: info: Adding plugin bluetooth to MyMusic Plugins Apr 16 03:31:36 pi5dev volumio[6066]: info: Adding plugin multiroom to MyMusic Plugins Apr 16 03:31:36 pi5dev volumio[6066]: info: Adding plugin metavolumio to MyMusic Plugins Apr 16 03:31:36 pi5dev volumio[6066]: info: Adding plugin cd_controller to MyMusic Plugins Apr 16 03:31:36 pi5dev volumio[6066]: info: Adding plugin smart_inputs to MyMusic Plugins Apr 16 03:31:36 pi5dev volumio[6066]: info: Adding plugin tidalconnect to MyMusic Plugins Apr 16 03:31:36 pi5dev volumio[6066]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Apr 16 03:31:37 pi5dev volumio[6066]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Apr 16 03:31:37 pi5dev volumio[6066]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Apr 16 03:31:37 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 16 03:31:37 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 16 03:31:37 pi5dev volumio[6066]: info: Starting MyVolumio Remote Streaming Endpoints Apr 16 03:31:37 pi5dev volumio[6066]: info: MyVolumio login type: Token Apr 16 03:31:37 pi5dev volumio[6066]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Apr 16 03:31:37 pi5dev volumio[6066]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Apr 16 03:31:37 pi5dev volumio[6066]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Apr 16 03:31:37 pi5dev volumio[6066]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Apr 16 03:31:37 pi5dev volumio[6066]: info: Streaming services startup Apr 16 03:31:37 pi5dev volumio[6066]: info: Starting Streaming Daemon Apr 16 03:31:37 pi5dev sudo[6419]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 16 03:31:37 pi5dev sudo[6419]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 16 03:31:37 pi5dev volumio[6066]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Apr 16 03:31:37 pi5dev sudo[6419]: pam_unix(sudo:session): session closed for user root Apr 16 03:31:37 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 16 03:31:37 pi5dev volumio[6066]: error: Cannot start Volumio Streaming Daemon Apr 16 03:31:37 pi5dev volumio[6066]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 16 03:31:37 pi5dev volumio[6066]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 16 03:31:37 pi5dev volumio[6066]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Apr 16 03:31:37 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 16 03:31:38 pi5dev volumio[6066]: info: MyVolumio token set successfully Apr 16 03:31:38 pi5dev volumio[6066]: info: MYVOLUMIO: Adding device Apr 16 03:31:38 pi5dev volumio[6066]: info: MYVOLUMIO: Evaluating Server Apr 16 03:31:38 pi5dev volumio[6066]: info: MyVolumio Plan changed: premium Apr 16 03:31:38 pi5dev volumio[6066]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Apr 16 03:31:38 pi5dev volumio[6066]: info: Removing browser output: myVolumio user plan is not superstar Apr 16 03:31:38 pi5dev volumio[6066]: info: Removing audio output: Apr 16 03:31:38 pi5dev volumio[6066]: info: MYVOLUMIO: Adding device Apr 16 03:31:38 pi5dev volumio[6066]: info: MYVOLUMIO: Evaluating Server Apr 16 03:31:38 pi5dev volumio[6066]: info: Remote config written successfully Apr 16 03:31:38 pi5dev volumio[6066]: info: Starting Tunnel 1 Apr 16 03:31:38 pi5dev volumio[6066]: info: Starting Tunnel Connection Checker Apr 16 03:31:38 pi5dev volumio[6066]: info: Completed starting MyVolumio Plugin Apr 16 03:31:38 pi5dev volumio[6066]: info: MYVolumio Device enabled Apr 16 03:31:38 pi5dev volumio[6066]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Apr 16 03:31:38 pi5dev volumio[6066]: info: MyVolumio status changed Apr 16 03:31:38 pi5dev volumio[6066]: info: Streaming services startup Apr 16 03:31:38 pi5dev volumio[6066]: info: Starting Streaming Daemon Apr 16 03:31:38 pi5dev sudo[6458]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 16 03:31:38 pi5dev sudo[6458]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 16 03:31:38 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Apr 16 03:31:38 pi5dev volumio[6066]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Apr 16 03:31:38 pi5dev sudo[6458]: pam_unix(sudo:session): session closed for user root Apr 16 03:31:38 pi5dev volumio[6066]: warn: [MyVolumio PluginManager] It looks like we're trying to load a cleartext plugin in: /myvolumio/plugins/audio_interface/bluetooth/index.js Apr 16 03:31:38 pi5dev volumio[6066]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Apr 16 03:31:38 pi5dev volumio[6066]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Apr 16 03:31:38 pi5dev volumio[6066]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Apr 16 03:31:38 pi5dev sudo[6462]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Apr 16 03:31:38 pi5dev sudo[6462]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 16 03:31:38 pi5dev sudo[6462]: pam_unix(sudo:session): session closed for user root Apr 16 03:31:38 pi5dev volumio[6066]: /usr/bin/mkdir: cannot create directory ‘/tmp/hls’: File exists Apr 16 03:31:38 pi5dev volumio[6066]: info: MRS: MultiRoom plugin initialized Apr 16 03:31:38 pi5dev volumio[6066]: info: MRS: STOPPING SNAPCLIENT Apr 16 03:31:38 pi5dev volumio[6066]: info: MRS: Snap server stop Apr 16 03:31:38 pi5dev sudo[6479]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Apr 16 03:31:38 pi5dev sudo[6479]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 16 03:31:38 pi5dev volumio[6066]: info: MRS: STOPPING volumioStreaming Apr 16 03:31:38 pi5dev sudo[6481]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Apr 16 03:31:38 pi5dev sudo[6481]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 16 03:31:38 pi5dev sudo[6485]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Apr 16 03:31:38 pi5dev sudo[6485]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 16 03:31:38 pi5dev volumio[6066]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Apr 16 03:31:38 pi5dev sudo[6488]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Apr 16 03:31:38 pi5dev sudo[6488]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 16 03:31:38 pi5dev sudo[6488]: pam_unix(sudo:session): session closed for user root Apr 16 03:31:38 pi5dev volumio[6066]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Apr 16 03:31:38 pi5dev volumio[6066]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Apr 16 03:31:38 pi5dev volumio[6066]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Apr 16 03:31:38 pi5dev sudo[6479]: pam_unix(sudo:session): session closed for user root Apr 16 03:31:38 pi5dev volumio[6066]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Apr 16 03:31:38 pi5dev sudo[6481]: pam_unix(sudo:session): session closed for user root Apr 16 03:31:38 pi5dev sudo[6485]: pam_unix(sudo:session): session closed for user root Apr 16 03:31:38 pi5dev volumio[6066]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Apr 16 03:31:38 pi5dev volumio[6066]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Apr 16 03:31:38 pi5dev volumio[6066]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Apr 16 03:31:38 pi5dev volumio[6066]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Apr 16 03:31:38 pi5dev volumio[6066]: info: Preparing to generate the ALSA configuration file Apr 16 03:31:38 pi5dev volumio[6066]: info: Setting Geolocation for MyVolumio to eu3 Apr 16 03:31:38 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 16 03:31:38 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 16 03:31:38 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 16 03:31:38 pi5dev volumio[6066]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Apr 16 03:31:38 pi5dev volumio[6066]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 16 03:31:38 pi5dev volumio[6066]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Apr 16 03:31:38 pi5dev volumio[6066]: info: Reading ALSA contributions from plugins. Apr 16 03:31:38 pi5dev volumio[6066]: info: Setting Geolocation for MyVolumio to eu3 Apr 16 03:31:38 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 16 03:31:38 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 16 03:31:38 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 16 03:31:38 pi5dev volumio[6066]: info: MRS: Removed streaming files Apr 16 03:31:38 pi5dev volumio[6066]: info: MRS: volumioStreaming STOPPED Apr 16 03:31:38 pi5dev volumio[6066]: info: MRS: SNAPSERVER STOPPED Apr 16 03:31:38 pi5dev volumio[6066]: info: MRS: SNAPCLIENT STOPPED Apr 16 03:31:38 pi5dev volumio[6066]: error: Cannot start Volumio Streaming Daemon Apr 16 03:31:38 pi5dev volumio[6066]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 16 03:31:38 pi5dev volumio[6066]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 16 03:31:38 pi5dev volumio[6066]: info: Asound.conf file written Apr 16 03:31:38 pi5dev sudo[6492]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 16 03:31:38 pi5dev sudo[6492]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 16 03:31:38 pi5dev sudo[6492]: pam_unix(sudo:session): session closed for user root Apr 16 03:31:38 pi5dev volumio[6066]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Apr 16 03:31:38 pi5dev volumio[6066]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Apr 16 03:31:38 pi5dev volumio[6066]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Apr 16 03:31:38 pi5dev volumio[6066]: info: Output device has changed, restarting MPD Apr 16 03:31:38 pi5dev volumio[6066]: info: Output device has changed, restarting Shairport Sync Apr 16 03:31:38 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 03:31:38 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 16 03:31:38 pi5dev sudo[6498]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 16 03:31:38 pi5dev volumio[6066]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 16 03:31:38 pi5dev sudo[6500]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 16 03:31:38 pi5dev sudo[6500]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 16 03:31:38 pi5dev volumio[6066]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 16 03:31:38 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 03:31:38 pi5dev sudo[6498]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 16 03:31:38 pi5dev volumio[6066]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Apr 16 03:31:38 pi5dev volumio[6066]: ------------------------------------ BT MESSAGE: [FUNC] onStart Apr 16 03:31:38 pi5dev volumio[6066]: ------------------------------------ BT MESSAGE: Starting Volumio Bluetooth Service Apr 16 03:31:38 pi5dev sudo[6498]: pam_unix(sudo:session): session closed for user root Apr 16 03:31:38 pi5dev volumio[6066]: ------------------------------------ BT MESSAGE: Boot config /etc/bluetooth/volumio.conf: cache mode = tmp Apr 16 03:31:38 pi5dev volumio[6066]: ------------------------------------ BT MESSAGE: [metaCache] Directory exists and is ready. Apr 16 03:31:38 pi5dev systemd[1]: Stopping mpd.service - Music Player Daemon... Apr 16 03:31:38 pi5dev volumio[6066]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Apr 16 03:31:38 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Apr 16 03:31:38 pi5dev volumio[6066]: info: MRS: Pushing multiroomSync output for this device Apr 16 03:31:38 pi5dev volumio[6066]: info: MRS: Pushing multiroomSync output Apr 16 03:31:38 pi5dev volumio[6066]: info: Adding audio output: Apr 16 03:31:38 pi5dev volumio[6066]: info: Adding audio output: Apr 16 03:31:38 pi5dev systemd[1]: mpd.service: Deactivated successfully. Apr 16 03:31:38 pi5dev systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 16 03:31:38 pi5dev systemd[1]: mpd.socket: Deactivated successfully. Apr 16 03:31:38 pi5dev systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 16 03:31:38 pi5dev systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 16 03:31:38 pi5dev volumio[6066]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 16 03:31:38 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 03:31:38 pi5dev bluetoothd[957]: Adv Monitor app :1.109 disconnected from D-Bus Apr 16 03:31:38 pi5dev sudo[6519]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 16 03:31:38 pi5dev volumio[6066]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Apr 16 03:31:38 pi5dev volumio[6066]: info: Adding METAVOLUMIO REST API Endpoints Apr 16 03:31:38 pi5dev volumio[6066]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Apr 16 03:31:38 pi5dev volumio[6066]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Apr 16 03:31:38 pi5dev volumio[6066]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Apr 16 03:31:38 pi5dev volumio[6066]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Apr 16 03:31:38 pi5dev volumio[6066]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Apr 16 03:31:38 pi5dev volumio[6066]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Apr 16 03:31:38 pi5dev volumio[6066]: info: Preparing CD Folders Apr 16 03:31:38 pi5dev volumio[6066]: info: Adding CD REST API Endpoints Apr 16 03:31:38 pi5dev volumio[6066]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Apr 16 03:31:38 pi5dev volumio[6066]: info: Starting UDEV Watcher for CD Apr 16 03:31:38 pi5dev volumio[6066]: info: Detecting CD presence with UDEV Apr 16 03:31:38 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Apr 16 03:31:38 pi5dev volumio[6066]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Apr 16 03:31:38 pi5dev volumio[6066]: info: Adding inputs REST Endpoints Apr 16 03:31:38 pi5dev volumio[6066]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Apr 16 03:31:38 pi5dev volumio[6066]: info: Scanning Audio Inputs Apr 16 03:31:38 pi5dev volumio[6066]: info: Checking against Known Cards name Apr 16 03:31:38 pi5dev volumio[6066]: info: Adding Server instance for streaming Apr 16 03:31:38 pi5dev volumio[6066]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Apr 16 03:31:38 pi5dev volumio[6066]: error: Hi Res Audio Failed Login: Missing Login Data Apr 16 03:31:38 pi5dev volumio[6066]: info: Adding HIGHRESAUDIO REST API Endpoints Apr 16 03:31:38 pi5dev volumio[6066]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Apr 16 03:31:38 pi5dev volumio[6066]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Apr 16 03:31:38 pi5dev volumio[6066]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Apr 16 03:31:38 pi5dev volumio[6066]: info: Stopping AccessToken refresher cron Apr 16 03:31:38 pi5dev systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 16 03:31:38 pi5dev sudo[6519]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 16 03:31:38 pi5dev systemd[1]: Starting mpd.service - Music Player Daemon... Apr 16 03:31:38 pi5dev systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Apr 16 03:31:38 pi5dev systemd[1]: vtcs.service: Killing process 5192 (vtcs) with signal SIGKILL. Apr 16 03:31:39 pi5dev volumio[6066]: info: AccessToken refresher cron started Apr 16 03:31:39 pi5dev volumio[6066]: info: Stopping AccessToken refresher cron for QOBUZ Apr 16 03:31:38 pi5dev systemd[1]: vtcs.service: Deactivated successfully. Apr 16 03:31:38 pi5dev systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Apr 16 03:31:39 pi5dev volumio[6066]: info: AccessToken refresher cron started for QOBUZ Apr 16 03:31:39 pi5dev volumio[6066]: info: Adding TIDAL REST API Endpoints Apr 16 03:31:39 pi5dev volumio[6066]: info: Adding QOBUZ REST API Endpoints Apr 16 03:31:39 pi5dev sudo[6519]: pam_unix(sudo:session): session closed for user root Apr 16 03:31:39 pi5dev volumio[6066]: ------------------------------------ BT MESSAGE: Bluetooth adapter powered on Apr 16 03:31:39 pi5dev volumio[6066]: info: MPD Permissions set Apr 16 03:31:39 pi5dev sudo[6524]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Apr 16 03:31:39 pi5dev sudo[6524]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 16 03:31:39 pi5dev sudo[6521]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 16 03:31:39 pi5dev sudo[6521]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 16 03:31:39 pi5dev sudo[6521]: pam_unix(sudo:session): session closed for user root Apr 16 03:31:39 pi5dev volumio[6066]: ------------------------------------ BT MESSAGE: [_watchMediaPlayer] Binding activePlayer for path: /org/bluez/hci0/dev_5C_17_CF_0D_C0_70/player0 Apr 16 03:31:39 pi5dev volumio[6066]: ------------------------------------ BT MESSAGE: activePlayer bound: /org/bluez/hci0/dev_5C_17_CF_0D_C0_70/player0 Apr 16 03:31:39 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 03:31:39 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 03:31:39 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 03:31:39 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 03:31:39 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 03:31:39 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 03:31:39 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 03:31:39 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 03:31:39 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 16 03:31:39 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 03:31:39 pi5dev volumio[6066]: info: Starting Shairport Sync Apr 16 03:31:39 pi5dev sudo[6536]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 16 03:31:39 pi5dev sudo[6536]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 16 03:31:39 pi5dev sudo[6524]: pam_unix(sudo:session): session closed for user root Apr 16 03:31:39 pi5dev volumio[6066]: ------------------------------------ BT MESSAGE: volumiobt.service started successfully Apr 16 03:31:39 pi5dev volumio[6066]: info: CoreCommandRouter::volumioStop Apr 16 03:31:39 pi5dev volumio[6066]: info: CoreStateMachine::stop Apr 16 03:31:39 pi5dev volumio[6066]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 16 03:31:39 pi5dev volumio[6066]: ------------------------------------ BT MESSAGE: [FUNC] dbusStart Apr 16 03:31:39 pi5dev volumio[6066]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 16 03:31:39 pi5dev systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 16 03:31:39 pi5dev volumio[6066]: info: MRS: Found cast device: Chromecast-5702c0fa26409e4dc282cbccce3735c4 Apr 16 03:31:39 pi5dev volumio[6066]: info: Adding audio output: Apr 16 03:31:39 pi5dev systemd[1]: shairport-sync.service: Deactivated successfully. Apr 16 03:31:39 pi5dev systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 16 03:31:39 pi5dev systemd[1]: shairport-sync.service: Consumed 1.518s CPU time. Apr 16 03:31:39 pi5dev volumio[6066]: info: MRS: Found cast device: Google-Nest-Hub-da739cebc7bb692ba8bab67636d07159 Apr 16 03:31:39 pi5dev volumio[6066]: info: Adding audio output: Apr 16 03:31:39 pi5dev systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 16 03:31:39 pi5dev sudo[6536]: pam_unix(sudo:session): session closed for user root Apr 16 03:31:39 pi5dev volumio[6066]: info: Shairport-Sync Started Apr 16 03:31:39 pi5dev volumio[6066]: info: MRS: Found cast device: Chromecast-Ultra-b8d5e9e179c52d9e75eab34ae52a3903 Apr 16 03:31:39 pi5dev volumio[6066]: info: Adding audio output: Apr 16 03:31:39 pi5dev mpd[6532]: Apr 16 03:31 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 16 03:31:39 pi5dev systemd[1]: Started mpd.service - Music Player Daemon. Apr 16 03:31:39 pi5dev sudo[6500]: pam_unix(sudo:session): session closed for user root Apr 16 03:31:39 pi5dev volumio[6066]: error: updateQueue error: null Apr 16 03:31:39 pi5dev volumio[6066]: info: Successfully Added MyVolumio device Apr 16 03:31:39 pi5dev volumio[6066]: info: Successfully Added MyVolumio device Apr 16 03:31:39 pi5dev volumio[6066]: info: Updating MyVolumio device info Apr 16 03:31:39 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 16 03:31:39 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 16 03:31:39 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 16 03:31:39 pi5dev volumio[6066]: info: Updating MyVolumio device info Apr 16 03:31:39 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 16 03:31:39 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 16 03:31:39 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 16 03:31:40 pi5dev volumio[6066]: info: Successfully Updated MyVolumio device Apr 16 03:31:40 pi5dev volumio[6066]: info: Successfully Updated MyVolumio device Apr 16 03:31:42 pi5dev volumio[6066]: info: TidalConnect service stoped! Apr 16 03:31:42 pi5dev volumio[6066]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 16 03:31:42 pi5dev volumio[6066]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 16 03:31:42 pi5dev sudo[6566]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 16 03:31:42 pi5dev sudo[6566]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 16 03:31:42 pi5dev systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Apr 16 03:31:42 pi5dev sudo[6566]: pam_unix(sudo:session): session closed for user root Apr 16 03:31:42 pi5dev volumio[6066]: info: Executing endpoint tc_getconfig Apr 16 03:31:42 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Apr 16 03:31:42 pi5dev vtcs[6569]: STARTING TidalConnect services, version: 1.5.0.45 Apr 16 03:31:42 pi5dev vtcs[6569]: STARTED TidalConnect services. Apr 16 03:31:42 pi5dev volumio[6066]: info: Executing endpoint tc_connect Apr 16 03:31:42 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Apr 16 03:31:42 pi5dev volumio[6066]: info: Connecting to TidalConnect Apr 16 03:31:42 pi5dev volumio[6066]: info: CoreCommandRouter::servicePushState Apr 16 03:31:42 pi5dev volumio[6066]: info: CoreStateMachine::pushState Apr 16 03:31:42 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 03:31:42 pi5dev volumio[6066]: info: CoreCommandRouter::volumioPushState Apr 16 03:31:42 pi5dev volumio[6066]: info: MRS: Pushing multiroomSync output update for this device Apr 16 03:31:42 pi5dev volumio[6066]: info: MRS: Pushing multiroomSync output Apr 16 03:31:42 pi5dev volumio[6066]: info: CorePlayQueue::getTrack 0 Apr 16 03:31:42 pi5dev volumio[6066]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Apr 16 03:31:42 pi5dev volumio[6066]: verbose: CURRENT POSITION 0 Apr 16 03:31:42 pi5dev volumio[6066]: info: CoreStateMachine::syncState stateService stop Apr 16 03:31:42 pi5dev volumio[6066]: info: CoreStateMachine::syncState currentStatus stop Apr 16 03:31:42 pi5dev volumio[6066]: info: CoreStateMachine::pushState Apr 16 03:31:42 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 03:31:42 pi5dev volumio[6066]: info: CoreCommandRouter::volumioPushState Apr 16 03:31:42 pi5dev volumio[6066]: info: MRS: Pushing multiroomSync output update for this device Apr 16 03:31:42 pi5dev volumio[6066]: info: MRS: Pushing multiroomSync output Apr 16 03:31:42 pi5dev volumio[6066]: info: No code Apr 16 03:31:42 pi5dev volumio[6066]: info: CoreStateMachine::pushState Apr 16 03:31:42 pi5dev volumio[6066]: info: CoreCommandRouter::volumioPushState Apr 16 03:31:42 pi5dev volumio[6066]: info: MRS: Pushing multiroomSync output update for this device Apr 16 03:31:42 pi5dev volumio[6066]: info: MRS: Pushing multiroomSync output Apr 16 03:31:42 pi5dev volumio[6066]: info: CoreCommandRouter::servicePushState Apr 16 03:31:42 pi5dev volumio[6066]: info: CoreStateMachine::pushState Apr 16 03:31:42 pi5dev volumio[6066]: info: CoreCommandRouter::volumioPushState Apr 16 03:31:42 pi5dev volumio[6066]: info: MRS: Pushing multiroomSync output update for this device Apr 16 03:31:42 pi5dev volumio[6066]: info: MRS: Pushing multiroomSync output Apr 16 03:31:42 pi5dev volumio[6066]: info: CorePlayQueue::getTrack 0 Apr 16 03:31:42 pi5dev volumio[6066]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Apr 16 03:31:42 pi5dev volumio[6066]: verbose: CURRENT POSITION 0 Apr 16 03:31:42 pi5dev volumio[6066]: info: CoreStateMachine::syncState stateService stop Apr 16 03:31:42 pi5dev volumio[6066]: info: CoreStateMachine::syncState currentStatus stop Apr 16 03:31:42 pi5dev volumio[6066]: info: CoreStateMachine::pushState Apr 16 03:31:42 pi5dev volumio[6066]: info: CoreCommandRouter::volumioPushState Apr 16 03:31:42 pi5dev volumio[6066]: info: MRS: Pushing multiroomSync output update for this device Apr 16 03:31:42 pi5dev volumio[6066]: info: MRS: Pushing multiroomSync output Apr 16 03:31:42 pi5dev volumio[6066]: info: No code Apr 16 03:31:42 pi5dev volumio[6066]: info: CoreStateMachine::pushState Apr 16 03:31:42 pi5dev volumio[6066]: info: CoreCommandRouter::volumioPushState Apr 16 03:31:42 pi5dev volumio[6066]: info: MRS: Pushing multiroomSync output update for this device Apr 16 03:31:42 pi5dev volumio[6066]: info: MRS: Pushing multiroomSync output Apr 16 03:31:42 pi5dev volumio[6066]: info: PeppyMeterBasic ---peppymeterbasic status Apr 16 03:31:42 pi5dev volumio[6066]: info: PeppyMeterBasic ---peppymeterbasic status Apr 16 03:31:42 pi5dev volumio[6066]: info: PeppyMeterBasic ---peppymeterbasic status undefined Apr 16 03:31:42 pi5dev volumio[6066]: info: PeppyMeterBasic ---peppymeterbasic status undefined Apr 16 03:31:42 pi5dev volumio[6066]: info: PeppyMeterBasic ---peppymeterbasic status undefined Apr 16 03:31:42 pi5dev volumio[6066]: info: PeppyMeterBasic ---peppymeterbasic status undefined Apr 16 03:31:43 pi5dev sudo[6591]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Apr 16 03:31:43 pi5dev sudo[6591]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 16 03:31:43 pi5dev autossh[4166]: received signal to exit (15) Apr 16 03:31:43 pi5dev systemd[1]: Stopping sshtunnel.service - MyVolumio SSH Tunnel... Apr 16 03:31:43 pi5dev systemd[1]: sshtunnel.service: Deactivated successfully. Apr 16 03:31:43 pi5dev systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Apr 16 03:31:43 pi5dev systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Apr 16 03:31:43 pi5dev sudo[6591]: pam_unix(sudo:session): session closed for user root Apr 16 03:31:43 pi5dev volumio[6066]: info: Remote SSH Started Apr 16 03:31:43 pi5dev autossh[6594]: port set to 0, monitoring disabled Apr 16 03:31:43 pi5dev autossh[6594]: starting ssh (count 1) Apr 16 03:31:43 pi5dev autossh[6594]: ssh child pid is 6597 Apr 16 03:31:43 pi5dev volumio[6066]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Apr 16 03:31:43 pi5dev volumiossh-tunnel[6597]: Warning: Permanently added '[eu3.myvolumio.org]:2222' (RSA) to the list of known hosts. Apr 16 03:31:43 pi5dev volumio[6066]: info: CoreCommandRouter::volumioGetState Apr 16 03:31:43 pi5dev volumio[6066]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.dQFd7rFMkUZnTXfKxRPos4Ns44L2.96e25e8b036fdbb047a6171fc7e87d28.state.status' Apr 16 03:31:43 pi5dev volumio[6066]: info: MRS: Getting audio outputs on start Apr 16 03:31:43 pi5dev volumio[6066]: info: MRS: Requesting all other devices output Apr 16 03:31:44 pi5dev volumio[6066]: info: Executing endpoint metavolumio Apr 16 03:31:44 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 16 03:31:45 pi5dev volumio[6066]: info: TidalConnect service started! Apr 16 03:31:45 pi5dev volumio[6066]: [Metrics] CommandRouter: 19s 22.50ms Apr 16 03:31:45 pi5dev volumio[6066]: info: CoreCommandRouter::volumiosetStartupVolume Apr 16 03:31:45 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 03:31:45 pi5dev volumio[6066]: info: VolumeController:: Setting startup Volume 65 Apr 16 03:31:45 pi5dev volumio[6066]: info: VolumeController::SetAlsaVolume65 Apr 16 03:31:45 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 16 03:31:45 pi5dev volumio[6066]: info: CoreCommandRouter::Close All Modals sent Apr 16 03:31:45 pi5dev volumio[6066]: info: CoreCommandRouter::Close All Modals sent Apr 16 03:31:45 pi5dev volumio[6066]: info: CoreStateMachine::pushState Apr 16 03:31:45 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 03:31:45 pi5dev volumio[6066]: info: CoreCommandRouter::volumioPushState Apr 16 03:31:45 pi5dev volumio[6066]: info: MRS: Pushing multiroomSync output update for this device Apr 16 03:31:45 pi5dev volumio[6066]: info: MRS: Pushing multiroomSync output Apr 16 03:31:45 pi5dev volumio[6066]: info: PeppyMeterBasic ---peppymeterbasic status Apr 16 03:31:46 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Apr 16 03:31:46 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 16 03:31:46 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Apr 16 03:31:47 pi5dev volumio[6066]: info: Executing endpoint metavolumio Apr 16 03:31:47 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 16 03:31:52 pi5dev volumio[6066]: info: BOOT COMPLETED Apr 16 03:31:52 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 16 03:31:52 pi5dev volumio[6066]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Apr 16 03:31:52 pi5dev volumio[6066]: info: CoreCommandRouter::volumioGetState Apr 16 03:31:54 pi5dev volumio[6066]: info: MRS: Found cast device: Chromecast-Ultra-bb5475a65116e0c6bfcdcd498eee7139 Apr 16 03:31:54 pi5dev volumio[6066]: info: Adding audio output: Apr 16 03:31:54 pi5dev bluealsa[1121]: bluez.c:1480: Signal: org.freedesktop.DBus.Properties.PropertiesChanged(): org.bluez.MediaTransport1: State Apr 16 03:31:54 pi5dev volumio[6066]: ------------------------------------ BT MESSAGE: [dbus-next] Playback state changed: false from 5C:17:CF:0D:C0:70 Apr 16 03:31:54 pi5dev bluealsa[1121]: ../src/ba-transport.c:319: New A2DP transport: 15 Apr 16 03:31:54 pi5dev bluealsa[1121]: ../src/ba-transport.c:320: A2DP socket MTU: 15: R:672 W:1008 Apr 16 03:31:54 pi5dev bluealsa[1121]: bluez.c:1480: Signal: org.freedesktop.DBus.Properties.PropertiesChanged(): org.bluez.MediaTransport1: State Apr 16 03:31:54 pi5dev bluealsa[1121]: ../src/ba-transport.c:1075: Starting transport: A2DP Sink (SBC) Apr 16 03:31:54 pi5dev bluealsa[1121]: ../src/ba-transport-pcm.c:294: Created BT socket duplicate: [15]: 16 Apr 16 03:31:54 pi5dev bluealsa[1121]: ../src/a2dp-sbc.c:331: PCM IO loop: START: a2dp_sbc_dec_thread: A2DP Sink (SBC) Apr 16 03:31:54 pi5dev bluealsa[1121]: ../src/ba-transport-pcm.c:373: Created new IO thread [ba-a2dp-sbc]: A2DP Sink (SBC) Apr 16 03:31:54 pi5dev volumio[6066]: ------------------------------------ BT MESSAGE: [dbus-next] Playback state changed: true from 5C:17:CF:0D:C0:70 Apr 16 03:31:54 pi5dev volumio[6066]: ------------------------------------ BT MESSAGE: Playback started, enabling output Apr 16 03:31:54 pi5dev volumio[6066]: info: CoreCommandRouter::volumioStop Apr 16 03:31:54 pi5dev volumio[6066]: info: CoreStateMachine::stop Apr 16 03:31:54 pi5dev volumio[6066]: info: CoreStateMachine::serviceStop Apr 16 03:31:54 pi5dev volumio[6066]: info: Received STOP, but no service to execute it Apr 16 03:31:54 pi5dev dbus-daemon[958]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.108" (uid=1000 pid=6066 comm="/usr/bin/node /volumio/index.js") interface="(unset)" member="(unset)" error name="com.github.dbus_next.Error" requested_reply="0" destination=":1.1" (uid=0 pid=957 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") Apr 16 03:31:54 pi5dev volumio[6066]: ------------------------------------ BT MESSAGE: [FUNC] btAudioOutput Apr 16 03:31:54 pi5dev volumio[6066]: ------------------------------------ BT MESSAGE: [AAMP] Modular pipeline enabled - using default ALSA route: volumio Apr 16 03:31:54 pi5dev bluetoothd[957]: Adv Monitor app :1.118 disconnected from D-Bus Apr 16 03:31:54 pi5dev volumio[6066]: ------------------------------------ BT MESSAGE: Detected connected BT MAC: 5C:17:CF:0D:C0:70 Apr 16 03:31:54 pi5dev volumio[6066]: ------------------------------------ BT MESSAGE: Spawning bluealsa-aplay with args: --profile-a2dp --pcm=volumio --pcm-buffer-time=500000 --pcm-period-time=100000 5C:17:CF:0D:C0:70 Apr 16 03:31:54 pi5dev volumio[6066]: ------------------------------------ BT MESSAGE: [metaCache] Loaded metadata for 5C:17:CF:0D:C0:70 from file Apr 16 03:31:54 pi5dev volumio[6066]: ------------------------------------ BT MESSAGE: Loaded metadata from cache for 5C:17:CF:0D:C0:70 Apr 16 03:31:54 pi5dev volumio[6066]: ------------------------------------ BT MESSAGE: [dbus-next] Playback state changed: false from 5C:17:CF:0D:C0:70 Apr 16 03:31:54 pi5dev volumio[6066]: ------------------------------------ BT MESSAGE: Received pause signal, scheduling idle check Apr 16 03:31:54 pi5dev volumio[6066]: info: CoreCommandRouter::servicePushState Apr 16 03:31:54 pi5dev volumio[6066]: info: CoreStateMachine::pushState Apr 16 03:31:54 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 03:31:54 pi5dev volumio[6066]: info: CoreCommandRouter::volumioPushState Apr 16 03:31:54 pi5dev volumio[6066]: info: MRS: Pushing multiroomSync output update for this device Apr 16 03:31:54 pi5dev volumio[6066]: info: MRS: Pushing multiroomSync output Apr 16 03:31:54 pi5dev bluealsa[1121]: ../src/dbus.c:47: Called: org.bluealsa.PCM1.Open() on /org/bluealsa/hci0/dev_5C_17_CF_0D_C0_70/a2dpsnk/source Apr 16 03:31:54 pi5dev volumio[6066]: ------------------------------------ BT MESSAGE: Seek received post-resume, pushing metadata Apr 16 03:31:54 pi5dev volumio[6066]: info: CoreCommandRouter::servicePushState Apr 16 03:31:54 pi5dev volumio[6066]: info: CoreStateMachine::pushState Apr 16 03:31:54 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 03:31:54 pi5dev volumio[6066]: info: CoreCommandRouter::volumioPushState Apr 16 03:31:54 pi5dev volumio[6066]: info: MRS: Pushing multiroomSync output update for this device Apr 16 03:31:54 pi5dev volumio[6066]: info: MRS: Pushing multiroomSync output Apr 16 03:31:54 pi5dev volumio[6066]: ------------------------------------ BT MESSAGE: bluealsa-aplay stderr: bluealsa-aplay: [6622] D: aplay.c:904: Creating IO worker 5C:17:CF:0D:C0:70 Apr 16 03:31:54 pi5dev volumio[6066]: bluealsa-aplay: [6622] D: aplay.c:1320: Starting main loop Apr 16 03:31:54 pi5dev volumio[6066]: bluealsa-aplay: [6623] D: aplay.c:577: Opening BlueALSA source PCM: /org/bluealsa/hci0/dev_5C_17_CF_0D_C0_70/a2dpsnk/source Apr 16 03:31:54 pi5dev volumio[6066]: bluealsa-aplay: [6623] D: aplay.c:603: Starting IO loop Apr 16 03:31:54 pi5dev volumio[6066]: info: PeppyMeterBasic ---peppymeterbasic status pause Apr 16 03:31:54 pi5dev volumio[6066]: info: PeppyMeterBasic ---peppymeterbasic status pause Apr 16 03:31:54 pi5dev bluealsa[1121]: ../src/codec-sbc.c:278: SBC setup: 44100 Hz JointStereo allocation=Loudness blocks=16 sub-bands=8 bit-pool=53 => 327993 bps Apr 16 03:31:54 pi5dev sudo[6626]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Apr 16 03:31:54 pi5dev sudo[6626]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 16 03:31:54 pi5dev sudo[6628]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Apr 16 03:31:54 pi5dev sudo[6628]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 16 03:31:54 pi5dev volumio[6066]: ------------------------------------ BT MESSAGE: bluealsa-aplay stderr: bluealsa-aplay: [6623] D: aplay.c:732: Opening ALSA playback PCM: name=volumio channels=2 rate=44100 Apr 16 03:31:54 pi5dev systemd[1]: /etc/systemd/system/peppymeterbasic.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Apr 16 03:31:54 pi5dev systemd[1]: /etc/systemd/system/peppymeterbasic.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Apr 16 03:31:54 pi5dev volumio[6066]: ------------------------------------ BT MESSAGE: bluealsa-aplay stderr: bluealsa-aplay: [6623] D: aplay.c:339: Opening ALSA mixer: name=default elem=Master index=0 Apr 16 03:31:54 pi5dev volumio[6066]: bluealsa-aplay: [6623] W: aplay.c:350: Couldn't open ALSA mixer: Mixer element not found Apr 16 03:31:54 pi5dev systemd[1]: /etc/systemd/system/peppymeterbasic.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Apr 16 03:31:54 pi5dev systemd[1]: /etc/systemd/system/peppymeterbasic.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Apr 16 03:31:54 pi5dev volumio[6066]: ------------------------------------ BT MESSAGE: [dbus-next] Playback state changed: true from 5C:17:CF:0D:C0:70 Apr 16 03:31:54 pi5dev volumio[6066]: ------------------------------------ BT MESSAGE: Playback started, enabling output Apr 16 03:31:54 pi5dev volumio[6066]: info: CoreCommandRouter::servicePushState Apr 16 03:31:54 pi5dev volumio[6066]: info: CoreStateMachine::pushState Apr 16 03:31:54 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 03:31:54 pi5dev systemd[1]: /etc/systemd/system/peppymeterbasic.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Apr 16 03:31:54 pi5dev systemd[1]: /etc/systemd/system/peppymeterbasic.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Apr 16 03:31:54 pi5dev volumio[6066]: info: CoreCommandRouter::volumioPushState Apr 16 03:31:54 pi5dev volumio[6066]: info: MRS: Pushing multiroomSync output update for this device Apr 16 03:31:54 pi5dev volumio[6066]: info: MRS: Pushing multiroomSync output Apr 16 03:31:54 pi5dev volumio[6066]: info: CoreCommandRouter::servicePushState Apr 16 03:31:54 pi5dev volumio[6066]: info: CoreStateMachine::pushState Apr 16 03:31:54 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 03:31:54 pi5dev volumio[6066]: info: CoreCommandRouter::volumioPushState Apr 16 03:31:54 pi5dev volumio[6066]: info: MRS: Pushing multiroomSync output update for this device Apr 16 03:31:54 pi5dev volumio[6066]: info: MRS: Pushing multiroomSync output Apr 16 03:31:54 pi5dev sudo[6626]: pam_unix(sudo:session): session closed for user root Apr 16 03:31:54 pi5dev systemd[1]: /etc/systemd/system/peppymeterbasic.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Apr 16 03:31:54 pi5dev systemd[1]: /etc/systemd/system/peppymeterbasic.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Apr 16 03:31:54 pi5dev systemd[1]: /etc/systemd/system/peppymeterbasic.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Apr 16 03:31:54 pi5dev systemd[1]: /etc/systemd/system/peppymeterbasic.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Apr 16 03:31:54 pi5dev sudo[6628]: pam_unix(sudo:session): session closed for user root Apr 16 03:31:54 pi5dev volumio[6066]: ------------------------------------ BT MESSAGE: Received new metadata for 5C:17:CF:0D:C0:70 Apr 16 03:31:54 pi5dev volumio[6066]: ------------------------------------ BT MESSAGE: [metaCache] Saved metadata to /tmp/bluetooth-cache/meta-5C:17:CF:0D:C0:70.json Apr 16 03:31:54 pi5dev volumio[6066]: info: CoreCommandRouter::servicePushState Apr 16 03:31:54 pi5dev volumio[6066]: info: CoreStateMachine::pushState Apr 16 03:31:54 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 03:31:54 pi5dev volumio[6066]: info: CoreCommandRouter::volumioPushState Apr 16 03:31:54 pi5dev volumio[6066]: info: MRS: Pushing multiroomSync output update for this device Apr 16 03:31:54 pi5dev volumio[6066]: info: MRS: Pushing multiroomSync output Apr 16 03:31:54 pi5dev volumio[6066]: ------------------------------------ BT MESSAGE: Seek received post-resume, pushing metadata Apr 16 03:31:54 pi5dev volumio[6066]: info: CoreCommandRouter::servicePushState Apr 16 03:31:54 pi5dev volumio[6066]: info: CoreStateMachine::pushState Apr 16 03:31:54 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 03:31:54 pi5dev volumio[6066]: info: CoreCommandRouter::volumioPushState Apr 16 03:31:54 pi5dev volumio[6066]: info: MRS: Pushing multiroomSync output update for this device Apr 16 03:31:54 pi5dev volumio[6066]: info: MRS: Pushing multiroomSync output Apr 16 03:31:54 pi5dev volumio[6066]: info: PeppyMeterBasic ---peppymeterbasic status play Apr 16 03:31:54 pi5dev volumio[6066]: info: PeppyMeterBasic ---peppymeterbasic status play Apr 16 03:31:54 pi5dev sudo[6635]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Apr 16 03:31:54 pi5dev sudo[6635]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 16 03:31:54 pi5dev volumio[6066]: info: PeppyMeterBasic ---peppymeterbasic status play Apr 16 03:31:54 pi5dev volumio[6066]: info: PeppyMeterBasic ---peppymeterbasic status play Apr 16 03:31:54 pi5dev systemd[1]: /etc/systemd/system/peppymeterbasic.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Apr 16 03:31:54 pi5dev sudo[6638]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Apr 16 03:31:54 pi5dev systemd[1]: /etc/systemd/system/peppymeterbasic.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Apr 16 03:31:54 pi5dev sudo[6638]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 16 03:31:54 pi5dev sudo[6640]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Apr 16 03:31:54 pi5dev sudo[6640]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 16 03:31:54 pi5dev sudo[6643]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Apr 16 03:31:54 pi5dev sudo[6643]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 16 03:31:54 pi5dev volumio[6066]: info: peppymeterbasic Daemon Stop Apr 16 03:31:54 pi5dev volumio[6066]: info: peppymeterbasic Daemon Stop Apr 16 03:31:54 pi5dev volumio[6066]: info: peppymeterbasic Daemon Started Apr 16 03:31:54 pi5dev volumio[6066]: info: peppymeterbasic Daemon Started Apr 16 03:31:54 pi5dev systemd[1]: Started peppymeterbasic.service - peppymeterbasic Daemon. Apr 16 03:31:54 pi5dev sudo[6638]: pam_unix(sudo:session): session closed for user root Apr 16 03:31:54 pi5dev sudo[6635]: pam_unix(sudo:session): session closed for user root Apr 16 03:31:54 pi5dev sudo[6640]: pam_unix(sudo:session): session closed for user root Apr 16 03:31:54 pi5dev sudo[6643]: pam_unix(sudo:session): session closed for user root Apr 16 03:31:54 pi5dev volumio[6066]: info: touch_display: Setting screensaver timeout to 0 seconds. Apr 16 03:31:54 pi5dev volumio[6066]: info: peppymeterbasic Daemon Started Apr 16 03:31:54 pi5dev volumio[6066]: info: peppymeterbasic Daemon Started Apr 16 03:31:55 pi5dev volumio[6647]: failed to open /dev/dri/renderD128: Permission denied Apr 16 03:31:55 pi5dev volumio[6647]: glx: failed to create dri3 screen Apr 16 03:31:55 pi5dev volumio[6647]: failed to load driver: drm-rp1-dsi Apr 16 03:31:55 pi5dev volumio[6647]: failed to open /dev/dri/renderD128: Permission denied Apr 16 03:31:55 pi5dev volumio[6647]: glx: failed to create dri2 screen Apr 16 03:31:55 pi5dev volumio[6647]: failed to load driver: drm-rp1-dsi Apr 16 03:31:55 pi5dev volumio[6647]: failed to open /dev/dri/renderD128: Permission denied Apr 16 03:31:55 pi5dev volumio[6647]: glx: failed to create dri3 screen Apr 16 03:31:55 pi5dev volumio[6647]: failed to load driver: drm-rp1-dsi Apr 16 03:31:55 pi5dev volumio[6647]: failed to open /dev/dri/renderD128: Permission denied Apr 16 03:31:55 pi5dev volumio[6647]: glx: failed to create dri2 screen Apr 16 03:31:55 pi5dev volumio[6647]: failed to load driver: drm-rp1-dsi Apr 16 03:31:55 pi5dev volumio[6647]: Failed to create /home/volumio/.cache/mesa_shader_cache for shader cache (Permission denied)---disabling. Apr 16 03:31:55 pi5dev volumio[6647]: Failed to create /home/volumio/.cache/mesa_shader_cache_db for shader cache (Permission denied)---disabling. Apr 16 03:31:56 pi5dev volumio[6066]: info: Executing endpoint metavolumio Apr 16 03:31:56 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 16 03:31:56 pi5dev volumio[6066]: info: Executing endpoint metavolumio Apr 16 03:31:56 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 16 03:31:56 pi5dev volumio[6066]: info: Executing endpoint metavolumio Apr 16 03:31:56 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Apr 16 03:31:57 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 03:31:57 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 16 03:31:57 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Apr 16 03:31:57 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Apr 16 03:31:57 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 16 03:31:57 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 16 03:31:57 pi5dev volumio[6066]: info: CoreCommandRouter::volumioGetBrowseSources Apr 16 03:31:57 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 16 03:31:57 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 16 03:31:57 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 16 03:31:57 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 16 03:31:57 pi5dev volumio-remote-updater[998]: No test mode Apr 16 03:31:57 pi5dev volumio-remote-updater[998]: Alpha test mode enabled Apr 16 03:31:57 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Apr 16 03:31:57 pi5dev volumio[6066]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Apr 16 03:31:57 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 16 03:31:58 pi5dev volumio[6066]: error: Failed request for metavolumio API Apr 16 03:32:07 pi5dev bluealsa[1121]: bluez.c:1480: Signal: org.freedesktop.DBus.Properties.PropertiesChanged(): org.bluez.MediaTransport1: Volume Apr 16 03:32:07 pi5dev bluealsa[1121]: bluez.c:1502: Updating A2DP volume: 56 [-11.81 dB] Apr 16 03:32:07 pi5dev volumio[6066]: ------------------------------------ BT MESSAGE: [DEBUG] registerVolumeHandler: MAC = 5C:17:CF:0D:C0:70, rawVolume = 56 Apr 16 03:32:07 pi5dev volumio[6066]: ------------------------------------ BT MESSAGE: Received volume: raw = 56, scaled = 44 Apr 16 03:32:07 pi5dev volumio[6066]: info: VolumeController::SetAlsaVolume44 Apr 16 03:32:07 pi5dev volumio[6066]: ------------------------------------ BT MESSAGE: [VOLUME HOOK] Intercepted volume 44 - forwarding to Bluetooth device Apr 16 03:32:07 pi5dev volumio[6066]: info: CoreStateMachine::pushState Apr 16 03:32:07 pi5dev dbus-daemon[958]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.108" (uid=1000 pid=6066 comm="/usr/bin/node /volumio/index.js") interface="(unset)" member="(unset)" error name="com.github.dbus_next.Error" requested_reply="0" destination=":1.1" (uid=0 pid=957 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") Apr 16 03:32:07 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 03:32:07 pi5dev volumio[6066]: info: CoreCommandRouter::volumioPushState Apr 16 03:32:07 pi5dev volumio[6066]: info: MRS: Pushing multiroomSync output update for this device Apr 16 03:32:07 pi5dev volumio[6066]: info: MRS: Pushing multiroomSync output Apr 16 03:32:07 pi5dev volumio[6066]: info: PeppyMeterBasic ---peppymeterbasic status play Apr 16 03:32:07 pi5dev sudo[6697]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Apr 16 03:32:07 pi5dev sudo[6697]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 16 03:32:07 pi5dev sudo[6697]: pam_unix(sudo:session): session closed for user root Apr 16 03:32:07 pi5dev volumio[6066]: info: peppymeterbasic Daemon Started Apr 16 03:32:08 pi5dev volumio[6066]: ------------------------------------ BT MESSAGE: [DEBUG] registerVolumeHandler: MAC = 5C:17:CF:0D:C0:70, rawVolume = 60 Apr 16 03:32:08 pi5dev volumio[6066]: ------------------------------------ BT MESSAGE: Received volume: raw = 60, scaled = 47 Apr 16 03:32:08 pi5dev volumio[6066]: info: VolumeController::SetAlsaVolume47 Apr 16 03:32:08 pi5dev volumio[6066]: ------------------------------------ BT MESSAGE: [VOLUME HOOK] Intercepted volume 47 - forwarding to Bluetooth device Apr 16 03:32:08 pi5dev bluealsa[1121]: bluez.c:1480: Signal: org.freedesktop.DBus.Properties.PropertiesChanged(): org.bluez.MediaTransport1: Volume Apr 16 03:32:08 pi5dev bluealsa[1121]: bluez.c:1502: Updating A2DP volume: 60 [-10.81 dB] Apr 16 03:32:08 pi5dev volumio[6066]: info: CoreStateMachine::pushState Apr 16 03:32:08 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 03:32:08 pi5dev volumio[6066]: info: CoreCommandRouter::volumioPushState Apr 16 03:32:08 pi5dev dbus-daemon[958]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.108" (uid=1000 pid=6066 comm="/usr/bin/node /volumio/index.js") interface="(unset)" member="(unset)" error name="com.github.dbus_next.Error" requested_reply="0" destination=":1.1" (uid=0 pid=957 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") Apr 16 03:32:08 pi5dev volumio[6066]: info: MRS: Pushing multiroomSync output update for this device Apr 16 03:32:08 pi5dev volumio[6066]: info: MRS: Pushing multiroomSync output Apr 16 03:32:08 pi5dev volumio[6066]: info: PeppyMeterBasic ---peppymeterbasic status play Apr 16 03:32:08 pi5dev sudo[6701]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Apr 16 03:32:08 pi5dev sudo[6701]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 16 03:32:08 pi5dev sudo[6701]: pam_unix(sudo:session): session closed for user root Apr 16 03:32:08 pi5dev volumio[6066]: info: peppymeterbasic Daemon Started Apr 16 03:32:09 pi5dev volumio[6066]: ------------------------------------ BT MESSAGE: [DEBUG] registerVolumeHandler: MAC = 5C:17:CF:0D:C0:70, rawVolume = 64 Apr 16 03:32:09 pi5dev volumio[6066]: ------------------------------------ BT MESSAGE: Received volume: raw = 64, scaled = 50 Apr 16 03:32:09 pi5dev volumio[6066]: info: VolumeController::SetAlsaVolume50 Apr 16 03:32:09 pi5dev volumio[6066]: ------------------------------------ BT MESSAGE: [VOLUME HOOK] Intercepted volume 50 - forwarding to Bluetooth device Apr 16 03:32:09 pi5dev bluealsa[1121]: bluez.c:1480: Signal: org.freedesktop.DBus.Properties.PropertiesChanged(): org.bluez.MediaTransport1: Volume Apr 16 03:32:09 pi5dev bluealsa[1121]: bluez.c:1502: Updating A2DP volume: 64 [-9.88 dB] Apr 16 03:32:09 pi5dev volumio[6066]: info: CoreStateMachine::pushState Apr 16 03:32:09 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 03:32:09 pi5dev volumio[6066]: info: CoreCommandRouter::volumioPushState Apr 16 03:32:09 pi5dev dbus-daemon[958]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.108" (uid=1000 pid=6066 comm="/usr/bin/node /volumio/index.js") interface="(unset)" member="(unset)" error name="com.github.dbus_next.Error" requested_reply="0" destination=":1.1" (uid=0 pid=957 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") Apr 16 03:32:09 pi5dev volumio[6066]: info: MRS: Pushing multiroomSync output update for this device Apr 16 03:32:09 pi5dev volumio[6066]: info: MRS: Pushing multiroomSync output Apr 16 03:32:09 pi5dev volumio[6066]: info: PeppyMeterBasic ---peppymeterbasic status play Apr 16 03:32:09 pi5dev sudo[6705]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Apr 16 03:32:09 pi5dev sudo[6705]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 16 03:32:09 pi5dev sudo[6705]: pam_unix(sudo:session): session closed for user root Apr 16 03:32:09 pi5dev volumio[6066]: info: peppymeterbasic Daemon Started Apr 16 03:32:09 pi5dev volumio[6066]: ------------------------------------ BT MESSAGE: [DEBUG] registerVolumeHandler: MAC = 5C:17:CF:0D:C0:70, rawVolume = 68 Apr 16 03:32:09 pi5dev volumio[6066]: ------------------------------------ BT MESSAGE: Received volume: raw = 68, scaled = 54 Apr 16 03:32:09 pi5dev volumio[6066]: info: VolumeController::SetAlsaVolume54 Apr 16 03:32:09 pi5dev volumio[6066]: ------------------------------------ BT MESSAGE: [VOLUME HOOK] Intercepted volume 54 - forwarding to Bluetooth device Apr 16 03:32:09 pi5dev bluealsa[1121]: bluez.c:1480: Signal: org.freedesktop.DBus.Properties.PropertiesChanged(): org.bluez.MediaTransport1: Volume Apr 16 03:32:09 pi5dev bluealsa[1121]: bluez.c:1502: Updating A2DP volume: 68 [-9.01 dB] Apr 16 03:32:09 pi5dev volumio[6066]: info: CoreStateMachine::pushState Apr 16 03:32:09 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 03:32:09 pi5dev volumio[6066]: info: CoreCommandRouter::volumioPushState Apr 16 03:32:09 pi5dev dbus-daemon[958]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.108" (uid=1000 pid=6066 comm="/usr/bin/node /volumio/index.js") interface="(unset)" member="(unset)" error name="com.github.dbus_next.Error" requested_reply="0" destination=":1.1" (uid=0 pid=957 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") Apr 16 03:32:09 pi5dev volumio[6066]: info: MRS: Pushing multiroomSync output update for this device Apr 16 03:32:09 pi5dev volumio[6066]: info: MRS: Pushing multiroomSync output Apr 16 03:32:09 pi5dev volumio[6066]: info: PeppyMeterBasic ---peppymeterbasic status play Apr 16 03:32:10 pi5dev sudo[6709]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Apr 16 03:32:10 pi5dev sudo[6709]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 16 03:32:10 pi5dev sudo[6709]: pam_unix(sudo:session): session closed for user root Apr 16 03:32:10 pi5dev volumio[6066]: info: peppymeterbasic Daemon Started Apr 16 03:32:10 pi5dev bluealsa[1121]: bluez.c:1480: Signal: org.freedesktop.DBus.Properties.PropertiesChanged(): org.bluez.MediaTransport1: Volume Apr 16 03:32:10 pi5dev bluealsa[1121]: bluez.c:1502: Updating A2DP volume: 72 [-8.18 dB] Apr 16 03:32:10 pi5dev volumio[6066]: ------------------------------------ BT MESSAGE: [DEBUG] registerVolumeHandler: MAC = 5C:17:CF:0D:C0:70, rawVolume = 72 Apr 16 03:32:10 pi5dev volumio[6066]: ------------------------------------ BT MESSAGE: Received volume: raw = 72, scaled = 57 Apr 16 03:32:10 pi5dev volumio[6066]: info: VolumeController::SetAlsaVolume57 Apr 16 03:32:10 pi5dev volumio[6066]: ------------------------------------ BT MESSAGE: [VOLUME HOOK] Intercepted volume 57 - forwarding to Bluetooth device Apr 16 03:32:10 pi5dev dbus-daemon[958]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.108" (uid=1000 pid=6066 comm="/usr/bin/node /volumio/index.js") interface="(unset)" member="(unset)" error name="com.github.dbus_next.Error" requested_reply="0" destination=":1.1" (uid=0 pid=957 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") Apr 16 03:32:10 pi5dev volumio[6066]: info: CoreStateMachine::pushState Apr 16 03:32:10 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 03:32:10 pi5dev volumio[6066]: info: CoreCommandRouter::volumioPushState Apr 16 03:32:10 pi5dev volumio[6066]: info: MRS: Pushing multiroomSync output update for this device Apr 16 03:32:10 pi5dev volumio[6066]: info: MRS: Pushing multiroomSync output Apr 16 03:32:10 pi5dev volumio[6066]: info: PeppyMeterBasic ---peppymeterbasic status play Apr 16 03:32:10 pi5dev sudo[6714]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Apr 16 03:32:10 pi5dev sudo[6714]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 16 03:32:10 pi5dev sudo[6714]: pam_unix(sudo:session): session closed for user root Apr 16 03:32:10 pi5dev volumio[6066]: info: peppymeterbasic Daemon Started Apr 16 03:32:10 pi5dev bluealsa[1121]: bluez.c:1480: Signal: org.freedesktop.DBus.Properties.PropertiesChanged(): org.bluez.MediaTransport1: Volume Apr 16 03:32:10 pi5dev bluealsa[1121]: bluez.c:1502: Updating A2DP volume: 77 [-7.21 dB] Apr 16 03:32:10 pi5dev volumio[6066]: ------------------------------------ BT MESSAGE: [DEBUG] registerVolumeHandler: MAC = 5C:17:CF:0D:C0:70, rawVolume = 77 Apr 16 03:32:10 pi5dev volumio[6066]: ------------------------------------ BT MESSAGE: Received volume: raw = 77, scaled = 61 Apr 16 03:32:10 pi5dev volumio[6066]: info: VolumeController::SetAlsaVolume61 Apr 16 03:32:10 pi5dev volumio[6066]: ------------------------------------ BT MESSAGE: [VOLUME HOOK] Intercepted volume 61 - forwarding to Bluetooth device Apr 16 03:32:10 pi5dev dbus-daemon[958]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.108" (uid=1000 pid=6066 comm="/usr/bin/node /volumio/index.js") interface="(unset)" member="(unset)" error name="com.github.dbus_next.Error" requested_reply="0" destination=":1.1" (uid=0 pid=957 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") Apr 16 03:32:10 pi5dev volumio[6066]: info: CoreStateMachine::pushState Apr 16 03:32:10 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 03:32:10 pi5dev volumio[6066]: info: CoreCommandRouter::volumioPushState Apr 16 03:32:10 pi5dev volumio[6066]: info: MRS: Pushing multiroomSync output update for this device Apr 16 03:32:10 pi5dev volumio[6066]: info: MRS: Pushing multiroomSync output Apr 16 03:32:10 pi5dev volumio[6066]: info: PeppyMeterBasic ---peppymeterbasic status play Apr 16 03:32:10 pi5dev sudo[6718]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Apr 16 03:32:10 pi5dev sudo[6718]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 16 03:32:10 pi5dev sudo[6718]: pam_unix(sudo:session): session closed for user root Apr 16 03:32:10 pi5dev volumio[6066]: info: peppymeterbasic Daemon Started Apr 16 03:32:12 pi5dev volumio[6066]: ------------------------------------ BT MESSAGE: [DEBUG] registerVolumeHandler: MAC = 5C:17:CF:0D:C0:70, rawVolume = 72 Apr 16 03:32:12 pi5dev volumio[6066]: ------------------------------------ BT MESSAGE: Received volume: raw = 72, scaled = 57 Apr 16 03:32:12 pi5dev volumio[6066]: info: VolumeController::SetAlsaVolume57 Apr 16 03:32:12 pi5dev volumio[6066]: ------------------------------------ BT MESSAGE: [VOLUME HOOK] Intercepted volume 57 - forwarding to Bluetooth device Apr 16 03:32:12 pi5dev bluealsa[1121]: bluez.c:1480: Signal: org.freedesktop.DBus.Properties.PropertiesChanged(): org.bluez.MediaTransport1: Volume Apr 16 03:32:12 pi5dev bluealsa[1121]: bluez.c:1502: Updating A2DP volume: 72 [-8.18 dB] Apr 16 03:32:12 pi5dev dbus-daemon[958]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.108" (uid=1000 pid=6066 comm="/usr/bin/node /volumio/index.js") interface="(unset)" member="(unset)" error name="com.github.dbus_next.Error" requested_reply="0" destination=":1.1" (uid=0 pid=957 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") Apr 16 03:32:12 pi5dev volumio[6066]: info: CoreStateMachine::pushState Apr 16 03:32:12 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 03:32:12 pi5dev volumio[6066]: info: CoreCommandRouter::volumioPushState Apr 16 03:32:12 pi5dev volumio[6066]: info: MRS: Pushing multiroomSync output update for this device Apr 16 03:32:12 pi5dev volumio[6066]: info: MRS: Pushing multiroomSync output Apr 16 03:32:12 pi5dev volumio[6066]: info: PeppyMeterBasic ---peppymeterbasic status play Apr 16 03:32:12 pi5dev sudo[6722]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Apr 16 03:32:12 pi5dev sudo[6722]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 16 03:32:12 pi5dev sudo[6722]: pam_unix(sudo:session): session closed for user root Apr 16 03:32:12 pi5dev volumio[6066]: info: peppymeterbasic Daemon Started Apr 16 03:32:13 pi5dev bluealsa[1121]: bluez.c:1480: Signal: org.freedesktop.DBus.Properties.PropertiesChanged(): org.bluez.MediaTransport1: Volume Apr 16 03:32:13 pi5dev bluealsa[1121]: bluez.c:1502: Updating A2DP volume: 68 [-9.01 dB] Apr 16 03:32:13 pi5dev volumio[6066]: ------------------------------------ BT MESSAGE: [DEBUG] registerVolumeHandler: MAC = 5C:17:CF:0D:C0:70, rawVolume = 68 Apr 16 03:32:13 pi5dev volumio[6066]: ------------------------------------ BT MESSAGE: Received volume: raw = 68, scaled = 54 Apr 16 03:32:13 pi5dev volumio[6066]: info: VolumeController::SetAlsaVolume54 Apr 16 03:32:13 pi5dev volumio[6066]: ------------------------------------ BT MESSAGE: [VOLUME HOOK] Intercepted volume 54 - forwarding to Bluetooth device Apr 16 03:32:13 pi5dev dbus-daemon[958]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.108" (uid=1000 pid=6066 comm="/usr/bin/node /volumio/index.js") interface="(unset)" member="(unset)" error name="com.github.dbus_next.Error" requested_reply="0" destination=":1.1" (uid=0 pid=957 comm="/usr/libexec/bluetooth/bluetoothd --noplugin=sap,b") Apr 16 03:32:13 pi5dev volumio[6066]: info: CoreStateMachine::pushState Apr 16 03:32:13 pi5dev volumio[6066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 16 03:32:13 pi5dev volumio[6066]: info: CoreCommandRouter::volumioPushState Apr 16 03:32:13 pi5dev volumio[6066]: info: MRS: Pushing multiroomSync output update for this device Apr 16 03:32:13 pi5dev volumio[6066]: info: MRS: Pushing multiroomSync output Apr 16 03:32:13 pi5dev volumio[6066]: info: PeppyMeterBasic ---peppymeterbasic status play Apr 16 03:32:13 pi5dev sudo[6726]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Apr 16 03:32:13 pi5dev sudo[6726]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 16 03:32:13 pi5dev sudo[6726]: pam_unix(sudo:session): session closed for user root Apr 16 03:32:13 pi5dev volumio[6066]: info: peppymeterbasic Daemon Started Apr 16 03:32:15 pi5dev volumio[6647]: libpng warning: iCCP: known incorrect sRGB profile Apr 16 03:32:25 pi5dev volumio[6066]: info: Stopping peppymeterbasic service Apr 16 03:32:25 pi5dev volumio[6066]: info: CoreStateMachine::stop Apr 16 03:32:25 pi5dev volumio[6066]: info: CoreStateMachine::serviceStop Apr 16 03:32:25 pi5dev volumio[6066]: info: CoreCommandRouter::serviceStop Apr 16 03:32:25 pi5dev volumio[6066]: ------------------------------------ BT MESSAGE: [FUNC] stop Apr 16 03:32:25 pi5dev volumio[6066]: ------------------------------------ BT MESSAGE: [FUNC] btAudioOutput Apr 16 03:32:25 pi5dev volumio[6066]: ------------------------------------ BT MESSAGE: [dbus-next] Disabling Bluetooth Audio Output Apr 16 03:32:25 pi5dev volumio[6066]: ------------------------------------ BT MESSAGE: Killing bluealsa-aplay process Apr 16 03:32:25 pi5dev volumio[6066]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 16 03:32:25 pi5dev bluealsa[1121]: ../src/ba-transport-pcm.c:453: Closing PCM: 18 Apr 16 03:32:25 pi5dev bluealsa[1121]: ../src/ba-transport.c:203: PCM clients check keep-alive: 0 ms Apr 16 03:32:25 pi5dev volumio[6066]: TypeError: self.btAudioOutput(...).then(...).catch is not a function Apr 16 03:32:25 pi5dev volumio[6066]: at BluetoothInterface.stop (/myvolumio/plugins/audio_interface/bluetooth/index.js:331:11) Apr 16 03:32:25 pi5dev volumio[6066]: at CoreCommandRouter.serviceStop (/volumio/app/index.js:371:25) Apr 16 03:32:25 pi5dev volumio[6066]: at CoreStateMachine.serviceStop (/volumio/app/statemachine.js:1265:31) Apr 16 03:32:25 pi5dev volumio[6066]: at CoreStateMachine.stop (/volumio/app/statemachine.js:1231:17) Apr 16 03:32:25 pi5dev volumio[6066]: at peppymeterbasic.onStop (/data/plugins/user_interface/peppymeterbasic/index.js:60:37) Apr 16 03:32:25 pi5dev volumio[6066]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Apr 16 03:32:25 pi5dev volumio[6066]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8) Apr 16 03:32:25 pi5dev volumio[6066]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1556:29) Apr 16 03:32:25 pi5dev volumio[6066]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1401:47) Apr 16 03:32:25 pi5dev volumio[6066]: at Socket.emit (node:events:514:28) Apr 16 03:32:25 pi5dev volumio[6066]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Apr 16 03:32:25 pi5dev volumio[6066]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Apr 16 03:32:25 pi5dev volumio[6066]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 16 03:32:25 pi5dev sudo[6757]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-04-16 03:31' Apr 16 03:32:25 pi5dev sudo[6757]: 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="8a3b6fcf0dbd7d42207332f6d209906dd2daab56" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Apr 12 14:26:54 UTC 2025" VOLUMIO_VERSION="0.054" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="25d223077b0ad14151d6c5b08157bae8"