Aug 27 22:20:28 quad4 ntpd[1085]: CLOCK: time stepped by 80.605634 Aug 27 22:20:28 quad4 ntpd[1085]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Aug 27 22:20:28 quad4 volumio[872]: verbose: New Socket.io Connection to 192.168.1.155 from 192.168.1.47 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/151.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Aug 27 22:20:28 quad4 volumio[872]: info: VolumeController:: Volume=91 Mute =false Aug 27 22:20:28 quad4 volumio[872]: info: CoreStateMachine::pushState Aug 27 22:20:28 quad4 volumio[872]: info: CorePlayQueue::getTrack 0 Aug 27 22:20:28 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 22:20:28 quad4 volumio[872]: info: CoreCommandRouter::volumioPushState Aug 27 22:20:28 quad4 volumio[872]: info: CoreStateMachine::updateTrackBlock Aug 27 22:20:28 quad4 volumio[872]: info: CorePlayQueue::getTrackBlock Aug 27 22:20:28 quad4 volumio[872]: info: CoreCommandRouter::volumioRetrievevolume Aug 27 22:20:28 quad4 python3[1406]: opening SSD1322 (rotate=0deg, fps=20) ... Aug 27 22:20:28 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 22:20:28 quad4 volumio[872]: info: Reloading queue from file Aug 27 22:20:28 quad4 volumio[872]: verbose: New Socket.io Connection to 192.168.1.155 from 192.168.1.47 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/151.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Aug 27 22:20:28 quad4 volumio[872]: info: Setting Device type: Raspberry PI Aug 27 22:20:28 quad4 volumio[872]: info: CoreStateMachine::setRepeat null single undefined Aug 27 22:20:28 quad4 volumio[872]: info: CoreStateMachine::pushState Aug 27 22:20:28 quad4 volumio[872]: info: CorePlayQueue::getTrack 0 Aug 27 22:20:28 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 22:20:28 quad4 volumio[872]: info: CoreCommandRouter::volumioPushState Aug 27 22:20:28 quad4 volumio[872]: info: CoreStateMachine::setRandom null Aug 27 22:20:28 quad4 volumio[872]: info: CoreStateMachine::pushState Aug 27 22:20:28 quad4 volumio[872]: info: CorePlayQueue::getTrack 0 Aug 27 22:20:28 quad4 volumio[872]: info: CoreCommandRouter::volumioPushState Aug 27 22:20:28 quad4 volumio[872]: verbose: New Socket.io Connection to 192.168.1.155:3000 from 192.168.1.151 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Aug 27 22:20:28 quad4 volumio[872]: info: Completed loading Core Plugins Aug 27 22:20:28 quad4 volumio[872]: info: Preparing to generate the ALSA configuration file Aug 27 22:20:28 quad4 volumio[872]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Aug 27 22:20:28 quad4 volumio[872]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03115 Aug 27 22:20:28 quad4 volumio[872]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Aug 27 22:20:28 quad4 volumio[872]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Aug 27 22:20:28 quad4 volumio[872]: info: Reading ALSA contributions from plugins. Aug 27 22:20:28 quad4 volumio[872]: verbose: New Socket.io Connection to 192.168.1.155:3000 from 192.168.1.151 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Aug 27 22:20:28 quad4 volumio[872]: verbose: New Socket.io Connection to 192.168.1.155 from 192.168.1.47 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/151.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Aug 27 22:20:28 quad4 volumio[872]: info: VolumeController:: Volume=91 Mute =false Aug 27 22:20:28 quad4 volumio[872]: info: CoreStateMachine::pushState Aug 27 22:20:28 quad4 volumio[872]: info: CorePlayQueue::getTrack 0 Aug 27 22:20:28 quad4 volumio[872]: info: CoreCommandRouter::volumioPushState Aug 27 22:20:28 quad4 volumio[872]: info: Discovery: adding f6607b4c-1ec1-45db-8b04-ab24d2feb5d2 Aug 27 22:20:28 quad4 volumio[872]: info: Discovery: Found device Quad4 Aug 27 22:20:28 quad4 volumio[872]: info: CoreCommandRouter::volumioGetState Aug 27 22:20:28 quad4 volumio[872]: info: CorePlayQueue::getTrack 0 Aug 27 22:20:28 quad4 volumio[872]: verbose: New Socket.io Connection to 192.168.1.155:3000 from 192.168.1.151 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Aug 27 22:20:28 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Aug 27 22:20:28 quad4 volumio[872]: info: Discovery: this is already registered, f6607b4c-1ec1-45db-8b04-ab24d2feb5d2 Aug 27 22:20:28 quad4 volumio[872]: info: Discovery: Found device Quad4 Aug 27 22:20:28 quad4 volumio[872]: info: CoreCommandRouter::volumioGetState Aug 27 22:20:28 quad4 volumio[872]: info: CorePlayQueue::getTrack 0 Aug 27 22:20:28 quad4 volumio[872]: info: Discovery: adding 45366483-ff85-418d-9a90-0fd80b14e631 Aug 27 22:20:28 quad4 volumio[872]: info: Discovery: Found device Volumio 2 Aug 27 22:20:28 quad4 volumio[872]: info: Discovery: Connecting to remote: 192.168.1.151 Aug 27 22:20:29 quad4 volumio[872]: info: CoreCommandRouter::volumioGetState Aug 27 22:20:29 quad4 volumio[872]: info: CorePlayQueue::getTrack 0 Aug 27 22:20:29 quad4 volumio[872]: info: Discovery: Connected to remote: 192.168.1.151 Aug 27 22:20:29 quad4 volumio[872]: info: Asound.conf file written Aug 27 22:20:29 quad4 sudo[1428]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Aug 27 22:20:29 quad4 sudo[1428]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 22:20:29 quad4 python3[1406]: Traceback (most recent call last): Aug 27 22:20:29 quad4 python3[1406]: File "/usr/local/lib/python3.11/dist-packages/luma/core/interface/serial.py", line 303, in __init__ Aug 27 22:20:29 quad4 python3[1406]: self._spi.open(port, device) Aug 27 22:20:29 quad4 python3[1406]: FileNotFoundError: [Errno 2] No such file or directory Aug 27 22:20:29 quad4 python3[1406]: During handling of the above exception, another exception occurred: Aug 27 22:20:29 quad4 python3[1406]: Traceback (most recent call last): Aug 27 22:20:29 quad4 python3[1406]: File "", line 198, in _run_module_as_main Aug 27 22:20:29 quad4 python3[1406]: File "", line 88, in _run_code Aug 27 22:20:29 quad4 python3[1406]: File "/home/volumio/sable/src/sable/app.py", line 1385, in Aug 27 22:20:29 quad4 python3[1406]: raise SystemExit(main()) Aug 27 22:20:29 quad4 python3[1406]: ^^^^^^ Aug 27 22:20:29 quad4 python3[1406]: File "/home/volumio/sable/src/sable/app.py", line 1347, in main Aug 27 22:20:29 quad4 python3[1406]: return run_hardware(stage=args.stage, rotate=args.rotate, Aug 27 22:20:29 quad4 python3[1406]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Aug 27 22:20:29 quad4 python3[1406]: File "/home/volumio/sable/src/sable/app.py", line 1083, in run_hardware Aug 27 22:20:29 quad4 python3[1406]: display = OledDisplay(hardware.OLED, rotate=rotate_deg, log=log) Aug 27 22:20:29 quad4 python3[1406]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Aug 27 22:20:29 quad4 python3[1406]: File "/home/volumio/sable/src/sable/display/oled.py", line 90, in __init__ Aug 27 22:20:29 quad4 python3[1406]: self._serial = spi( Aug 27 22:20:29 quad4 python3[1406]: ^^^^ Aug 27 22:20:29 quad4 python3[1406]: File "/usr/local/lib/python3.11/dist-packages/luma/core/interface/serial.py", line 314, in __init__ Aug 27 22:20:29 quad4 python3[1406]: raise luma.core.error.DeviceNotFoundError('SPI device not found') Aug 27 22:20:29 quad4 python3[1406]: luma.core.error.DeviceNotFoundError: SPI device not found Aug 27 22:20:29 quad4 sudo[1428]: pam_unix(sudo:session): session closed for user root Aug 27 22:20:29 quad4 systemd[1]: sable.service: Main process exited, code=exited, status=1/FAILURE Aug 27 22:20:29 quad4 systemd[1]: sable.service: Failed with result 'exit-code'. Aug 27 22:20:29 quad4 volumio[872]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Aug 27 22:20:29 quad4 volumio[872]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Aug 27 22:20:29 quad4 volumio[872]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Aug 27 22:20:29 quad4 volumio[872]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Aug 27 22:20:29 quad4 volumio[872]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:4 use case configuration -2 Aug 27 22:20:29 quad4 volumio[872]: info: Output device has changed, restarting MPD Aug 27 22:20:29 quad4 volumio[872]: info: ___________ START PLUGINS ___________ Aug 27 22:20:29 quad4 volumio[872]: info: ControllerMpd::onStart: Initializing MPD Aug 27 22:20:29 quad4 volumio[872]: info: Creating MPD Configuration file Aug 27 22:20:29 quad4 sudo[1449]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 27 22:20:29 quad4 sudo[1449]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 22:20:29 quad4 sudo[1443]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 27 22:20:29 quad4 sudo[1443]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 22:20:29 quad4 volumio[872]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 27 22:20:29 quad4 volumio[872]: info: [1787862029514] CoreMusicLibrary::Adding element Last_100 Aug 27 22:20:29 quad4 sudo[1443]: pam_unix(sudo:session): session closed for user root Aug 27 22:20:29 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 22:20:29 quad4 sudo[1453]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 27 22:20:29 quad4 sudo[1453]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 22:20:29 quad4 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 27 22:20:29 quad4 systemd[1]: Starting mpd.service - Music Player Daemon... Aug 27 22:20:29 quad4 sudo[1453]: pam_unix(sudo:session): session closed for user root Aug 27 22:20:29 quad4 sudo[1456]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 27 22:20:29 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Aug 27 22:20:29 quad4 volumio[872]: info: [MPD_OLED Plugin] Not starting mpd_oled service because oled type is not configured yet Aug 27 22:20:29 quad4 sudo[1456]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 22:20:29 quad4 sudo[1461]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 27 22:20:29 quad4 sudo[1461]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 27 22:20:29 quad4 sudo[1461]: pam_unix(sudo:session): session closed for user root Aug 27 22:20:29 quad4 systemd[1]: mpd.service: Deactivated successfully. Aug 27 22:20:29 quad4 systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 27 22:20:29 quad4 systemd[1]: mpd.socket: Deactivated successfully. Aug 27 22:20:29 quad4 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 27 22:20:29 quad4 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 27 22:20:29 quad4 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 27 22:20:29 quad4 systemd[1]: Starting mpd.service - Music Player Daemon... Aug 27 22:20:29 quad4 sudo[1466]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Aug 27 22:20:29 quad4 sudo[1466]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 22:20:29 quad4 volumio[872]: info: Volumio Calling Home Aug 27 22:20:29 quad4 sudo[1469]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start sable.service Aug 27 22:20:29 quad4 sudo[1469]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 22:20:29 quad4 sudo[1467]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 27 22:20:29 quad4 sudo[1467]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 27 22:20:29 quad4 volumio[872]: info: Preparing to generate the ALSA configuration file Aug 27 22:20:29 quad4 sudo[1474]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Aug 27 22:20:29 quad4 sudo[1467]: pam_unix(sudo:session): session closed for user root Aug 27 22:20:29 quad4 sudo[1466]: pam_unix(sudo:session): session closed for user root Aug 27 22:20:29 quad4 volumio[872]: info: Volumio Network Manager: Network status updated: 1 Aug 27 22:20:29 quad4 volumio[872]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Aug 27 22:20:29 quad4 volumio[872]: info: Reading ALSA contributions from plugins. Aug 27 22:20:29 quad4 volumio[872]: info: MPD Permissions set Aug 27 22:20:29 quad4 volumio[872]: info: MPD Permissions set Aug 27 22:20:30 quad4 sudo[1478]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Aug 27 22:20:30 quad4 sudo[1478]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 22:20:30 quad4 volumio[872]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Aug 27 22:20:30 quad4 volumio[872]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Aug 27 22:20:30 quad4 sudo[1478]: pam_unix(sudo:session): session closed for user root Aug 27 22:20:30 quad4 volumio[872]: info: [ROTARYENCODER2] Socket connected to Volumio Aug 27 22:20:30 quad4 volumio[872]: info: Volumio called home Aug 27 22:20:30 quad4 sudo[1482]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Aug 27 22:20:30 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:20:30 quad4 sudo[1482]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 22:20:30 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:20:30 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:20:30 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:20:30 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:20:30 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:20:30 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:20:30 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:20:30 quad4 sudo[1482]: pam_unix(sudo:session): session closed for user root Aug 27 22:20:30 quad4 volumio-remote-updater[846]: [2026-08-27 22:20:30] [connect] Successful connection Aug 27 22:20:30 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 22:20:30 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:20:30 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:20:30 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:20:30 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:20:30 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:20:30 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:20:30 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:20:30 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:20:30 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:20:30 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 22:20:30 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:20:30 quad4 volumio[872]: info: CoreCommandRouter::volumioGetState Aug 27 22:20:30 quad4 volumio[872]: info: CorePlayQueue::getTrack 0 Aug 27 22:20:30 quad4 volumio-remote-updater[846]: [2026-08-27 22:20:30] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1787862030 101 Aug 27 22:20:30 quad4 volumio[872]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 9 Aug 27 22:20:30 quad4 volumio[872]: info: CoreCommandRouter::volumioGetState Aug 27 22:20:30 quad4 volumio[872]: info: CorePlayQueue::getTrack 0 Aug 27 22:20:30 quad4 volumio[872]: info: Asound.conf file unchanged, so no further update is needed Aug 27 22:20:30 quad4 volumio[872]: info: Output device has changed, restarting MPD Aug 27 22:20:30 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:20:30 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:20:30 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:20:30 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:20:30 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:20:30 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:20:30 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:20:30 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:20:30 quad4 sudo[1495]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 27 22:20:30 quad4 sudo[1495]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 22:20:30 quad4 sudo[1495]: pam_unix(sudo:session): session closed for user root Aug 27 22:20:30 quad4 sudo[1497]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 27 22:20:30 quad4 sudo[1497]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 22:20:30 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 22:20:30 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:20:30 quad4 volumio[872]: verbose: New Socket.io Connection to 192.168.1.155 from 192.168.1.47 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/151.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Aug 27 22:20:30 quad4 systemd[1]: mpd.service: Deactivated successfully. Aug 27 22:20:30 quad4 systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 27 22:20:30 quad4 volumio[872]: info: MPD Permissions set Aug 27 22:20:30 quad4 systemd[1]: mpd.socket: Deactivated successfully. Aug 27 22:20:30 quad4 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 27 22:20:30 quad4 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 27 22:20:30 quad4 volumio[872]: info: CoreCommandRouter::volumioGetState Aug 27 22:20:30 quad4 volumio[872]: info: CorePlayQueue::getTrack 0 Aug 27 22:20:30 quad4 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 27 22:20:30 quad4 systemd[1]: Starting mpd.service - Music Player Daemon... Aug 27 22:20:30 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 27 22:20:30 quad4 volumio[872]: info: Listing playlists Aug 27 22:20:30 quad4 sudo[1506]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 27 22:20:30 quad4 sudo[1506]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 27 22:20:31 quad4 sudo[1507]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Aug 27 22:20:31 quad4 sudo[1506]: pam_unix(sudo:session): session closed for user root Aug 27 22:20:31 quad4 volumio[872]: info: CoreCommandRouter::volumioGetQueue Aug 27 22:20:31 quad4 volumio[872]: info: CoreStateMachine::getQueue Aug 27 22:20:31 quad4 volumio[872]: info: CorePlayQueue::getQueue Aug 27 22:20:31 quad4 volumio[872]: info: CoreCommandRouter::volumioGetVisibleSources Aug 27 22:20:31 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 22:20:31 quad4 volumio[872]: info: CoreCommandRouter::volumioGetState Aug 27 22:20:31 quad4 volumio[872]: info: CorePlayQueue::getTrack 0 Aug 27 22:20:31 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 27 22:20:31 quad4 volumio[872]: info: Listing playlists Aug 27 22:20:31 quad4 volumio[872]: info: CoreCommandRouter::volumioGetQueue Aug 27 22:20:31 quad4 volumio[872]: info: CoreStateMachine::getQueue Aug 27 22:20:31 quad4 volumio[872]: info: CorePlayQueue::getQueue Aug 27 22:20:31 quad4 volumio[872]: info: CoreCommandRouter::volumioGetVisibleSources Aug 27 22:20:31 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 22:20:31 quad4 volumio[872]: info: CoreCommandRouter::volumioGetState Aug 27 22:20:31 quad4 volumio[872]: info: CorePlayQueue::getTrack 0 Aug 27 22:20:31 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 27 22:20:31 quad4 volumio[872]: info: Listing playlists Aug 27 22:20:31 quad4 volumio[872]: info: CoreCommandRouter::volumioGetQueue Aug 27 22:20:31 quad4 volumio[872]: info: CoreStateMachine::getQueue Aug 27 22:20:31 quad4 volumio[872]: info: CorePlayQueue::getQueue Aug 27 22:20:31 quad4 volumio[872]: info: CoreCommandRouter::volumioGetVisibleSources Aug 27 22:20:31 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 22:20:32 quad4 systemd[1]: sable.service: Scheduled restart job, restart counter is at 4. Aug 27 22:20:32 quad4 systemd[1]: Stopped sable.service - Sable Display (Quadify successor) - OLED + rotary + spectrum. Aug 27 22:20:32 quad4 systemd[1]: Started sable.service - Sable Display (Quadify successor) - OLED + rotary + spectrum. Aug 27 22:20:32 quad4 sudo[1469]: pam_unix(sudo:session): session closed for user root Aug 27 22:20:32 quad4 volumio[872]: info: Sable: service started Aug 27 22:20:33 quad4 python3[1509]: opening SSD1322 (rotate=0deg, fps=20) ... Aug 27 22:20:33 quad4 python3[1509]: Traceback (most recent call last): Aug 27 22:20:33 quad4 python3[1509]: File "/usr/local/lib/python3.11/dist-packages/luma/core/interface/serial.py", line 303, in __init__ Aug 27 22:20:33 quad4 python3[1509]: self._spi.open(port, device) Aug 27 22:20:33 quad4 python3[1509]: FileNotFoundError: [Errno 2] No such file or directory Aug 27 22:20:33 quad4 python3[1509]: During handling of the above exception, another exception occurred: Aug 27 22:20:33 quad4 python3[1509]: Traceback (most recent call last): Aug 27 22:20:33 quad4 python3[1509]: File "", line 198, in _run_module_as_main Aug 27 22:20:33 quad4 python3[1509]: File "", line 88, in _run_code Aug 27 22:20:33 quad4 python3[1509]: File "/home/volumio/sable/src/sable/app.py", line 1385, in Aug 27 22:20:33 quad4 python3[1509]: raise SystemExit(main()) Aug 27 22:20:33 quad4 python3[1509]: ^^^^^^ Aug 27 22:20:33 quad4 python3[1509]: File "/home/volumio/sable/src/sable/app.py", line 1347, in main Aug 27 22:20:33 quad4 python3[1509]: return run_hardware(stage=args.stage, rotate=args.rotate, Aug 27 22:20:33 quad4 python3[1509]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Aug 27 22:20:33 quad4 python3[1509]: File "/home/volumio/sable/src/sable/app.py", line 1083, in run_hardware Aug 27 22:20:33 quad4 python3[1509]: display = OledDisplay(hardware.OLED, rotate=rotate_deg, log=log) Aug 27 22:20:34 quad4 python3[1509]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Aug 27 22:20:34 quad4 python3[1509]: File "/home/volumio/sable/src/sable/display/oled.py", line 90, in __init__ Aug 27 22:20:34 quad4 python3[1509]: self._serial = spi( Aug 27 22:20:34 quad4 python3[1509]: ^^^^ Aug 27 22:20:34 quad4 python3[1509]: File "/usr/local/lib/python3.11/dist-packages/luma/core/interface/serial.py", line 314, in __init__ Aug 27 22:20:34 quad4 python3[1509]: raise luma.core.error.DeviceNotFoundError('SPI device not found') Aug 27 22:20:34 quad4 python3[1509]: luma.core.error.DeviceNotFoundError: SPI device not found Aug 27 22:20:34 quad4 systemd[1]: sable.service: Main process exited, code=exited, status=1/FAILURE Aug 27 22:20:34 quad4 systemd[1]: sable.service: Failed with result 'exit-code'. Aug 27 22:20:34 quad4 mpd[1508]: 2026-08-27T22:20:34 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 27 22:20:34 quad4 systemd[1]: Started mpd.service - Music Player Daemon. Aug 27 22:20:34 quad4 sudo[1449]: pam_unix(sudo:session): session closed for user root Aug 27 22:20:34 quad4 sudo[1497]: pam_unix(sudo:session): session closed for user root Aug 27 22:20:34 quad4 sudo[1456]: pam_unix(sudo:session): session closed for user root Aug 27 22:20:34 quad4 volumio[872]: info: Completed starting Core Plugins Aug 27 22:20:34 quad4 volumio[872]: info: ------------------------------------------- Aug 27 22:20:34 quad4 volumio[872]: info: ----- MyVolumio plugins startup ---- Aug 27 22:20:34 quad4 volumio[872]: info: ------------------------------------------- Aug 27 22:20:34 quad4 volumio[872]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 27 22:20:34 quad4 volumio[872]: error: MPD error: The expression evaluated to a falsy value: Aug 27 22:20:34 quad4 volumio[872]: assert.ok(self.idling) Aug 27 22:20:34 quad4 volumio[872]: error: The expression evaluated to a falsy value: Aug 27 22:20:34 quad4 volumio[872]: assert.ok(self.idling) Aug 27 22:20:34 quad4 volumio[872]: error: MPD error: The expression evaluated to a falsy value: Aug 27 22:20:34 quad4 volumio[872]: assert.ok(self.idling) Aug 27 22:20:34 quad4 volumio[872]: error: The expression evaluated to a falsy value: Aug 27 22:20:34 quad4 volumio[872]: assert.ok(self.idling) Aug 27 22:20:34 quad4 volumio[872]: error: updateQueue error: null Aug 27 22:20:34 quad4 volumio[872]: info: MPD running with PID1508 Aug 27 22:20:34 quad4 volumio[872]: ,establishing connection Aug 27 22:20:34 quad4 volumio[872]: error: updateQueue error: null Aug 27 22:20:37 quad4 systemd[1]: sable.service: Scheduled restart job, restart counter is at 5. Aug 27 22:20:37 quad4 systemd[1]: Stopped sable.service - Sable Display (Quadify successor) - OLED + rotary + spectrum. Aug 27 22:20:37 quad4 systemd[1]: Started sable.service - Sable Display (Quadify successor) - OLED + rotary + spectrum. Aug 27 22:20:37 quad4 python3[1519]: opening SSD1322 (rotate=0deg, fps=20) ... Aug 27 22:20:38 quad4 python3[1519]: Traceback (most recent call last): Aug 27 22:20:38 quad4 python3[1519]: File "/usr/local/lib/python3.11/dist-packages/luma/core/interface/serial.py", line 303, in __init__ Aug 27 22:20:38 quad4 python3[1519]: self._spi.open(port, device) Aug 27 22:20:38 quad4 python3[1519]: FileNotFoundError: [Errno 2] No such file or directory Aug 27 22:20:38 quad4 python3[1519]: During handling of the above exception, another exception occurred: Aug 27 22:20:38 quad4 python3[1519]: Traceback (most recent call last): Aug 27 22:20:38 quad4 python3[1519]: File "", line 198, in _run_module_as_main Aug 27 22:20:38 quad4 python3[1519]: File "", line 88, in _run_code Aug 27 22:20:38 quad4 python3[1519]: File "/home/volumio/sable/src/sable/app.py", line 1385, in Aug 27 22:20:38 quad4 python3[1519]: raise SystemExit(main()) Aug 27 22:20:38 quad4 python3[1519]: ^^^^^^ Aug 27 22:20:38 quad4 python3[1519]: File "/home/volumio/sable/src/sable/app.py", line 1347, in main Aug 27 22:20:38 quad4 python3[1519]: return run_hardware(stage=args.stage, rotate=args.rotate, Aug 27 22:20:38 quad4 python3[1519]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Aug 27 22:20:38 quad4 python3[1519]: File "/home/volumio/sable/src/sable/app.py", line 1083, in run_hardware Aug 27 22:20:38 quad4 python3[1519]: display = OledDisplay(hardware.OLED, rotate=rotate_deg, log=log) Aug 27 22:20:38 quad4 python3[1519]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Aug 27 22:20:38 quad4 python3[1519]: File "/home/volumio/sable/src/sable/display/oled.py", line 90, in __init__ Aug 27 22:20:38 quad4 python3[1519]: self._serial = spi( Aug 27 22:20:38 quad4 python3[1519]: ^^^^ Aug 27 22:20:38 quad4 python3[1519]: File "/usr/local/lib/python3.11/dist-packages/luma/core/interface/serial.py", line 314, in __init__ Aug 27 22:20:38 quad4 python3[1519]: raise luma.core.error.DeviceNotFoundError('SPI device not found') Aug 27 22:20:38 quad4 python3[1519]: luma.core.error.DeviceNotFoundError: SPI device not found Aug 27 22:20:38 quad4 systemd[1]: sable.service: Main process exited, code=exited, status=1/FAILURE Aug 27 22:20:38 quad4 systemd[1]: sable.service: Failed with result 'exit-code'. Aug 27 22:20:39 quad4 volumio[872]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Aug 27 22:20:39 quad4 systemd[1]: systemd-fsckd.service: Deactivated successfully. Aug 27 22:20:41 quad4 systemd[1]: sable.service: Scheduled restart job, restart counter is at 6. Aug 27 22:20:41 quad4 systemd[1]: Stopped sable.service - Sable Display (Quadify successor) - OLED + rotary + spectrum. Aug 27 22:20:41 quad4 systemd[1]: Started sable.service - Sable Display (Quadify successor) - OLED + rotary + spectrum. Aug 27 22:20:42 quad4 python3[1539]: opening SSD1322 (rotate=0deg, fps=20) ... Aug 27 22:20:42 quad4 systemd[1]: systemd-hostnamed.service: Deactivated successfully. Aug 27 22:20:42 quad4 python3[1539]: Traceback (most recent call last): Aug 27 22:20:42 quad4 python3[1539]: File "/usr/local/lib/python3.11/dist-packages/luma/core/interface/serial.py", line 303, in __init__ Aug 27 22:20:42 quad4 python3[1539]: self._spi.open(port, device) Aug 27 22:20:42 quad4 python3[1539]: FileNotFoundError: [Errno 2] No such file or directory Aug 27 22:20:42 quad4 python3[1539]: During handling of the above exception, another exception occurred: Aug 27 22:20:42 quad4 python3[1539]: Traceback (most recent call last): Aug 27 22:20:42 quad4 python3[1539]: File "", line 198, in _run_module_as_main Aug 27 22:20:42 quad4 python3[1539]: File "", line 88, in _run_code Aug 27 22:20:42 quad4 python3[1539]: File "/home/volumio/sable/src/sable/app.py", line 1385, in Aug 27 22:20:42 quad4 python3[1539]: raise SystemExit(main()) Aug 27 22:20:42 quad4 python3[1539]: ^^^^^^ Aug 27 22:20:42 quad4 python3[1539]: File "/home/volumio/sable/src/sable/app.py", line 1347, in main Aug 27 22:20:42 quad4 python3[1539]: return run_hardware(stage=args.stage, rotate=args.rotate, Aug 27 22:20:42 quad4 python3[1539]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Aug 27 22:20:42 quad4 python3[1539]: File "/home/volumio/sable/src/sable/app.py", line 1083, in run_hardware Aug 27 22:20:42 quad4 python3[1539]: display = OledDisplay(hardware.OLED, rotate=rotate_deg, log=log) Aug 27 22:20:42 quad4 python3[1539]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Aug 27 22:20:42 quad4 python3[1539]: File "/home/volumio/sable/src/sable/display/oled.py", line 90, in __init__ Aug 27 22:20:42 quad4 python3[1539]: self._serial = spi( Aug 27 22:20:42 quad4 python3[1539]: ^^^^ Aug 27 22:20:42 quad4 python3[1539]: File "/usr/local/lib/python3.11/dist-packages/luma/core/interface/serial.py", line 314, in __init__ Aug 27 22:20:42 quad4 python3[1539]: raise luma.core.error.DeviceNotFoundError('SPI device not found') Aug 27 22:20:42 quad4 python3[1539]: luma.core.error.DeviceNotFoundError: SPI device not found Aug 27 22:20:43 quad4 systemd[1]: sable.service: Main process exited, code=exited, status=1/FAILURE Aug 27 22:20:43 quad4 systemd[1]: sable.service: Failed with result 'exit-code'. Aug 27 22:20:43 quad4 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Aug 27 22:20:43 quad4 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 27 22:20:43 quad4 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 27 22:20:43 quad4 upmpdcli[1548]: Could not open config: /tmp/upmpdcli.conf Aug 27 22:20:43 quad4 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Aug 27 22:20:43 quad4 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Aug 27 22:20:43 quad4 volumio[872]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Aug 27 22:20:43 quad4 volumio[872]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Aug 27 22:20:43 quad4 volumio[872]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Aug 27 22:20:43 quad4 volumio[872]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Aug 27 22:20:43 quad4 volumio[872]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Aug 27 22:20:43 quad4 volumio[872]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Aug 27 22:20:43 quad4 volumio[872]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Aug 27 22:20:43 quad4 volumio[872]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Aug 27 22:20:43 quad4 volumio[872]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Aug 27 22:20:43 quad4 volumio[872]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Aug 27 22:20:43 quad4 volumio[872]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Aug 27 22:20:43 quad4 volumio[872]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Aug 27 22:20:43 quad4 volumio[872]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Aug 27 22:20:43 quad4 volumio[872]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Aug 27 22:20:43 quad4 volumio[872]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Aug 27 22:20:43 quad4 volumio[872]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Aug 27 22:20:43 quad4 volumio[872]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Aug 27 22:20:43 quad4 volumio[872]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Aug 27 22:20:43 quad4 volumio[872]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Aug 27 22:20:43 quad4 volumio[872]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Aug 27 22:20:43 quad4 volumio[872]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Aug 27 22:20:43 quad4 volumio[872]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Aug 27 22:20:43 quad4 volumio[872]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Aug 27 22:20:43 quad4 volumio[872]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Aug 27 22:20:43 quad4 volumio[872]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Aug 27 22:20:43 quad4 volumio[872]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Aug 27 22:20:43 quad4 volumio[872]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Aug 27 22:20:43 quad4 volumio[872]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Aug 27 22:20:43 quad4 volumio[872]: info: Adding plugin bluetooth to MyMusic Plugins Aug 27 22:20:43 quad4 volumio[872]: info: Adding plugin multiroom to MyMusic Plugins Aug 27 22:20:43 quad4 volumio[872]: info: Adding plugin metavolumio to MyMusic Plugins Aug 27 22:20:43 quad4 volumio[872]: info: Adding plugin cd_controller to MyMusic Plugins Aug 27 22:20:43 quad4 volumio[872]: info: Adding plugin qobuzconnect to MyMusic Plugins Aug 27 22:20:43 quad4 volumio[872]: info: Adding plugin smart_inputs to MyMusic Plugins Aug 27 22:20:43 quad4 volumio[872]: info: Adding plugin tidalconnect to MyMusic Plugins Aug 27 22:20:43 quad4 volumio[872]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Aug 27 22:20:45 quad4 volumio[872]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Aug 27 22:20:45 quad4 volumio[872]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Aug 27 22:20:45 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 22:20:45 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 22:20:45 quad4 volumio[872]: info: Starting MyVolumio Remote Streaming Endpoints Aug 27 22:20:45 quad4 volumio[872]: info: MyVolumio login type: Token Aug 27 22:20:45 quad4 volumio[872]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Aug 27 22:20:45 quad4 volumio[872]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Aug 27 22:20:46 quad4 systemd[1]: sable.service: Scheduled restart job, restart counter is at 7. Aug 27 22:20:46 quad4 systemd[1]: Stopped sable.service - Sable Display (Quadify successor) - OLED + rotary + spectrum. Aug 27 22:20:46 quad4 systemd[1]: Started sable.service - Sable Display (Quadify successor) - OLED + rotary + spectrum. Aug 27 22:20:46 quad4 python3[1549]: opening SSD1322 (rotate=0deg, fps=20) ... Aug 27 22:20:47 quad4 volumio[872]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Aug 27 22:20:47 quad4 volumio[872]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Aug 27 22:20:47 quad4 volumio[872]: info: Streaming services startup Aug 27 22:20:47 quad4 volumio[872]: info: Starting Streaming Daemon Aug 27 22:20:47 quad4 volumio[872]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Aug 27 22:20:47 quad4 sudo[1556]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 27 22:20:47 quad4 sudo[1556]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 22:20:47 quad4 sudo[1556]: pam_unix(sudo:session): session closed for user root Aug 27 22:20:47 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 27 22:20:47 quad4 volumio[872]: info: Systeminfo: --[object Object] Aug 27 22:20:47 quad4 volumio[872]: info: Systeminfo web UI listening on port 12334 Aug 27 22:20:47 quad4 volumio[872]: info: VolumeController::SetAlsaVolume98 Aug 27 22:20:47 quad4 volumio[872]: info: CoreStateMachine::pushState Aug 27 22:20:47 quad4 volumio[872]: info: CorePlayQueue::getTrack 0 Aug 27 22:20:47 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 22:20:47 quad4 volumio[872]: info: CoreCommandRouter::volumioPushState Aug 27 22:20:47 quad4 volumio[872]: error: Cannot start Volumio Streaming Daemon Aug 27 22:20:47 quad4 volumio[872]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 27 22:20:47 quad4 volumio[872]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 27 22:20:47 quad4 python3[1549]: Traceback (most recent call last): Aug 27 22:20:47 quad4 python3[1549]: File "/usr/local/lib/python3.11/dist-packages/luma/core/interface/serial.py", line 303, in __init__ Aug 27 22:20:47 quad4 python3[1549]: self._spi.open(port, device) Aug 27 22:20:47 quad4 python3[1549]: FileNotFoundError: [Errno 2] No such file or directory Aug 27 22:20:47 quad4 python3[1549]: During handling of the above exception, another exception occurred: Aug 27 22:20:47 quad4 python3[1549]: Traceback (most recent call last): Aug 27 22:20:47 quad4 python3[1549]: File "", line 198, in _run_module_as_main Aug 27 22:20:47 quad4 python3[1549]: File "", line 88, in _run_code Aug 27 22:20:47 quad4 python3[1549]: File "/home/volumio/sable/src/sable/app.py", line 1385, in Aug 27 22:20:47 quad4 python3[1549]: raise SystemExit(main()) Aug 27 22:20:47 quad4 python3[1549]: ^^^^^^ Aug 27 22:20:47 quad4 python3[1549]: File "/home/volumio/sable/src/sable/app.py", line 1347, in main Aug 27 22:20:47 quad4 python3[1549]: return run_hardware(stage=args.stage, rotate=args.rotate, Aug 27 22:20:47 quad4 python3[1549]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Aug 27 22:20:47 quad4 python3[1549]: File "/home/volumio/sable/src/sable/app.py", line 1083, in run_hardware Aug 27 22:20:47 quad4 python3[1549]: display = OledDisplay(hardware.OLED, rotate=rotate_deg, log=log) Aug 27 22:20:47 quad4 python3[1549]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Aug 27 22:20:47 quad4 python3[1549]: File "/home/volumio/sable/src/sable/display/oled.py", line 90, in __init__ Aug 27 22:20:47 quad4 python3[1549]: self._serial = spi( Aug 27 22:20:47 quad4 python3[1549]: ^^^^ Aug 27 22:20:47 quad4 python3[1549]: File "/usr/local/lib/python3.11/dist-packages/luma/core/interface/serial.py", line 314, in __init__ Aug 27 22:20:47 quad4 python3[1549]: raise luma.core.error.DeviceNotFoundError('SPI device not found') Aug 27 22:20:47 quad4 python3[1549]: luma.core.error.DeviceNotFoundError: SPI device not found Aug 27 22:20:47 quad4 systemd[1]: sable.service: Main process exited, code=exited, status=1/FAILURE Aug 27 22:20:47 quad4 systemd[1]: sable.service: Failed with result 'exit-code'. Aug 27 22:20:48 quad4 volumio[872]: info: VolumeController::SetAlsaVolume100 Aug 27 22:20:48 quad4 volumio[872]: info: CoreStateMachine::pushState Aug 27 22:20:48 quad4 volumio[872]: info: CorePlayQueue::getTrack 0 Aug 27 22:20:48 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 22:20:48 quad4 volumio[872]: info: CoreCommandRouter::volumioPushState Aug 27 22:20:48 quad4 volumio[872]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Aug 27 22:20:49 quad4 volumio[872]: info: MyVolumio token set successfully Aug 27 22:20:49 quad4 volumio[872]: info: MYVOLUMIO: Adding device Aug 27 22:20:49 quad4 volumio[872]: info: MYVOLUMIO: Evaluating Server Aug 27 22:20:49 quad4 volumio[872]: info: MyVolumio Plan changed: superstar Aug 27 22:20:49 quad4 volumio[872]: info: [MyVolumio PluginManager] Subscribed plan changed to superstar Aug 27 22:20:49 quad4 volumio[872]: info: Adding audio output: Aug 27 22:20:49 quad4 volumio[872]: info: MYVOLUMIO: Adding device Aug 27 22:20:49 quad4 volumio[872]: info: MYVOLUMIO: Evaluating Server Aug 27 22:20:49 quad4 volumio[872]: info: Remote config written successfully Aug 27 22:20:49 quad4 volumio[872]: info: Starting Tunnel 1 Aug 27 22:20:49 quad4 volumio[872]: info: Starting Tunnel Connection Checker Aug 27 22:20:49 quad4 volumio[872]: info: Completed starting MyVolumio Plugin Aug 27 22:20:50 quad4 volumio[872]: info: MYVolumio Device enabled Aug 27 22:20:50 quad4 volumio[872]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Aug 27 22:20:50 quad4 volumio[872]: info: MyVolumio status changed Aug 27 22:20:50 quad4 volumio[872]: info: Streaming services startup Aug 27 22:20:50 quad4 volumio[872]: info: Starting Streaming Daemon Aug 27 22:20:50 quad4 sudo[1621]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 27 22:20:50 quad4 sudo[1621]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 22:20:50 quad4 volumio[872]: info: Setting Geolocation for MyVolumio to eu5 Aug 27 22:20:50 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 22:20:50 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 22:20:50 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 22:20:50 quad4 sudo[1621]: pam_unix(sudo:session): session closed for user root Aug 27 22:20:50 quad4 volumio[872]: error: Cannot start Volumio Streaming Daemon Aug 27 22:20:50 quad4 volumio[872]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 27 22:20:50 quad4 volumio[872]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 27 22:20:50 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Aug 27 22:20:50 quad4 volumio[872]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Aug 27 22:20:50 quad4 volumio[872]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin bluetooth Aug 27 22:20:50 quad4 systemd[1]: sable.service: Scheduled restart job, restart counter is at 8. Aug 27 22:20:50 quad4 volumio[872]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin bluetooth Aug 27 22:20:50 quad4 systemd[1]: Stopped sable.service - Sable Display (Quadify successor) - OLED + rotary + spectrum. Aug 27 22:20:50 quad4 volumio[872]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Aug 27 22:20:50 quad4 volumio[872]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Aug 27 22:20:50 quad4 systemd[1]: Started sable.service - Sable Display (Quadify successor) - OLED + rotary + spectrum. Aug 27 22:20:51 quad4 python3[1624]: opening SSD1322 (rotate=0deg, fps=20) ... Aug 27 22:20:52 quad4 python3[1624]: Traceback (most recent call last): Aug 27 22:20:52 quad4 python3[1624]: File "/usr/local/lib/python3.11/dist-packages/luma/core/interface/serial.py", line 303, in __init__ Aug 27 22:20:52 quad4 python3[1624]: self._spi.open(port, device) Aug 27 22:20:52 quad4 python3[1624]: FileNotFoundError: [Errno 2] No such file or directory Aug 27 22:20:52 quad4 python3[1624]: During handling of the above exception, another exception occurred: Aug 27 22:20:52 quad4 python3[1624]: Traceback (most recent call last): Aug 27 22:20:52 quad4 python3[1624]: File "", line 198, in _run_module_as_main Aug 27 22:20:52 quad4 python3[1624]: File "", line 88, in _run_code Aug 27 22:20:52 quad4 python3[1624]: File "/home/volumio/sable/src/sable/app.py", line 1385, in Aug 27 22:20:52 quad4 python3[1624]: raise SystemExit(main()) Aug 27 22:20:52 quad4 python3[1624]: ^^^^^^ Aug 27 22:20:52 quad4 python3[1624]: File "/home/volumio/sable/src/sable/app.py", line 1347, in main Aug 27 22:20:52 quad4 python3[1624]: return run_hardware(stage=args.stage, rotate=args.rotate, Aug 27 22:20:52 quad4 python3[1624]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Aug 27 22:20:52 quad4 python3[1624]: File "/home/volumio/sable/src/sable/app.py", line 1083, in run_hardware Aug 27 22:20:52 quad4 python3[1624]: display = OledDisplay(hardware.OLED, rotate=rotate_deg, log=log) Aug 27 22:20:52 quad4 python3[1624]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Aug 27 22:20:52 quad4 python3[1624]: File "/home/volumio/sable/src/sable/display/oled.py", line 90, in __init__ Aug 27 22:20:52 quad4 python3[1624]: self._serial = spi( Aug 27 22:20:52 quad4 python3[1624]: ^^^^ Aug 27 22:20:52 quad4 python3[1624]: File "/usr/local/lib/python3.11/dist-packages/luma/core/interface/serial.py", line 314, in __init__ Aug 27 22:20:52 quad4 python3[1624]: raise luma.core.error.DeviceNotFoundError('SPI device not found') Aug 27 22:20:52 quad4 python3[1624]: luma.core.error.DeviceNotFoundError: SPI device not found Aug 27 22:20:52 quad4 systemd[1]: sable.service: Main process exited, code=exited, status=1/FAILURE Aug 27 22:20:52 quad4 systemd[1]: sable.service: Failed with result 'exit-code'. Aug 27 22:20:53 quad4 volumio[872]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Aug 27 22:20:53 quad4 sudo[1632]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Aug 27 22:20:53 quad4 sudo[1632]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 22:20:53 quad4 sudo[1632]: pam_unix(sudo:session): session closed for user root Aug 27 22:20:53 quad4 volumio[872]: info: MRS: MultiRoom plugin initialized Aug 27 22:20:53 quad4 volumio[872]: info: MRS: STOPPING SNAPCLIENT Aug 27 22:20:53 quad4 volumio[872]: info: MRS: Snap server stop Aug 27 22:20:53 quad4 volumio[872]: info: MRS: STOPPING volumioStreaming Aug 27 22:20:53 quad4 volumio[872]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Aug 27 22:20:53 quad4 sudo[1649]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Aug 27 22:20:53 quad4 sudo[1649]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 22:20:53 quad4 sudo[1651]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Aug 27 22:20:53 quad4 sudo[1651]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 22:20:53 quad4 sudo[1655]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Aug 27 22:20:53 quad4 sudo[1655]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 22:20:53 quad4 volumio[872]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Aug 27 22:20:53 quad4 sudo[1653]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Aug 27 22:20:53 quad4 sudo[1653]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 22:20:53 quad4 sudo[1655]: pam_unix(sudo:session): session closed for user root Aug 27 22:20:53 quad4 sudo[1649]: pam_unix(sudo:session): session closed for user root Aug 27 22:20:53 quad4 volumio[872]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Aug 27 22:20:53 quad4 sudo[1651]: pam_unix(sudo:session): session closed for user root Aug 27 22:20:53 quad4 sudo[1653]: pam_unix(sudo:session): session closed for user root Aug 27 22:20:53 quad4 volumio[872]: warn: [MyVolumio PluginManager] It looks like we're trying to load a cleartext plugin in: /myvolumio/plugins/music_service/cd_controller/index.js Aug 27 22:20:53 quad4 volumio[872]: error: [MyVolumio PluginManager] !!!! WARNING !!!! Aug 27 22:20:53 quad4 volumio[872]: error: [MyVolumio PluginManager] The plugin music_service/cd_controller failed to load, setting it to stopped. Error: TypeError: Cannot read properties of undefined (reading 'iv') Aug 27 22:20:53 quad4 volumio[872]: error: [MyVolumio PluginManager] !!!! WARNING !!!! Aug 27 22:20:53 quad4 volumio[872]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Aug 27 22:20:53 quad4 volumio[872]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Aug 27 22:20:53 quad4 volumio[872]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Aug 27 22:20:53 quad4 volumio[872]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Aug 27 22:20:53 quad4 volumio[872]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Aug 27 22:20:53 quad4 volumio[872]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Aug 27 22:20:53 quad4 volumio[872]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Aug 27 22:20:53 quad4 volumio[872]: info: Preparing to generate the ALSA configuration file Aug 27 22:20:53 quad4 volumio[872]: info: Updating MyVolumio device info Aug 27 22:20:53 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 22:20:53 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 22:20:53 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 22:20:53 quad4 volumio[872]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Aug 27 22:20:53 quad4 volumio[872]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Aug 27 22:20:53 quad4 volumio[872]: info: Reading ALSA contributions from plugins. Aug 27 22:20:53 quad4 volumio[872]: info: Setting Geolocation for MyVolumio to eu12 Aug 27 22:20:53 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 22:20:53 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 22:20:53 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 22:20:53 quad4 volumio[872]: info: Successfully Added MyVolumio device Aug 27 22:20:53 quad4 volumio[872]: info: MRS: Removed streaming files Aug 27 22:20:53 quad4 volumio[872]: info: MRS: volumioStreaming STOPPED Aug 27 22:20:53 quad4 volumio[872]: info: MRS: SNAPSERVER STOPPED Aug 27 22:20:53 quad4 volumio[872]: info: MRS: SNAPCLIENT STOPPED Aug 27 22:20:54 quad4 volumio[872]: info: Successfully Updated MyVolumio device Aug 27 22:20:54 quad4 volumio[872]: info: Asound.conf file written Aug 27 22:20:54 quad4 sudo[1664]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Aug 27 22:20:54 quad4 sudo[1664]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 22:20:54 quad4 sudo[1664]: pam_unix(sudo:session): session closed for user root Aug 27 22:20:54 quad4 volumio[872]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Aug 27 22:20:54 quad4 volumio[872]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Aug 27 22:20:54 quad4 volumio[872]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Aug 27 22:20:54 quad4 volumio[872]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Aug 27 22:20:54 quad4 volumio[872]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:4 use case configuration -2 Aug 27 22:20:54 quad4 volumio[872]: info: Output device has changed, restarting MPD Aug 27 22:20:54 quad4 volumio[872]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 27 22:20:54 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:20:54 quad4 volumio[872]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Aug 27 22:20:54 quad4 volumio[872]: info: [MyVolumio PluginManager] Plugin bluetooth disabled by user. Not starting Aug 27 22:20:54 quad4 volumio[872]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Aug 27 22:20:54 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Aug 27 22:20:54 quad4 volumio[872]: info: MRS: Pushing multiroomSync output for this device Aug 27 22:20:54 quad4 sudo[1673]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 27 22:20:54 quad4 volumio[872]: info: MRS: Pushing multiroomSync output Aug 27 22:20:54 quad4 sudo[1673]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 22:20:54 quad4 sudo[1670]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 27 22:20:54 quad4 volumio[872]: info: Adding audio output: Aug 27 22:20:54 quad4 systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Aug 27 22:20:54 quad4 volumio[872]: info: Adding audio output: Aug 27 22:20:54 quad4 sudo[1670]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 22:20:54 quad4 sudo[1670]: pam_unix(sudo:session): session closed for user root Aug 27 22:20:54 quad4 volumio[872]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 27 22:20:54 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:20:54 quad4 systemd[1]: Stopping mpd.service - Music Player Daemon... Aug 27 22:20:54 quad4 volumio[872]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Aug 27 22:20:54 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 22:20:54 quad4 volumio[872]: info: Adding METAVOLUMIO REST API Endpoints Aug 27 22:20:54 quad4 volumio[872]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Aug 27 22:20:54 quad4 volumio[872]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Aug 27 22:20:54 quad4 volumio[872]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Aug 27 22:20:54 quad4 volumio[872]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Aug 27 22:20:54 quad4 volumio[872]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Aug 27 22:20:54 quad4 volumio[872]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Aug 27 22:20:54 quad4 volumio[872]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Aug 27 22:20:54 quad4 volumio[872]: info: [MyVolumio PluginManager] Plugin smart_inputs disabled by user. Not starting Aug 27 22:20:54 quad4 volumio[872]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Aug 27 22:20:54 quad4 volumio[872]: error: Hi Res Audio Failed Login: Missing Login Data Aug 27 22:20:54 quad4 volumio[872]: info: Adding HIGHRESAUDIO REST API Endpoints Aug 27 22:20:54 quad4 volumio[872]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Aug 27 22:20:54 quad4 volumio[872]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Aug 27 22:20:54 quad4 volumio[872]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Aug 27 22:20:54 quad4 volumio[872]: info: Refreshing QOBUZ token Aug 27 22:20:54 quad4 volumio[872]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Aug 27 22:20:54 quad4 volumio[872]: info: [MyVolumio PluginManager] Plugin tidalconnect disabled by user. Not starting Aug 27 22:20:54 quad4 volumio[872]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Aug 27 22:20:54 quad4 volumio[872]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Aug 27 22:20:54 quad4 volumio[872]: info: QobuzConnect: Starting Qobuz Connect socket and service Aug 27 22:20:54 quad4 volumio[872]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Aug 27 22:20:54 quad4 volumio[872]: info: Adding TIDAL REST API Endpoints Aug 27 22:20:54 quad4 volumio[872]: error: QobuzConnect: Error starting QobuzConnect exec is not defined {"stack":"ReferenceError: exec is not defined\n at /myvolumio/plugins/music_service/qobuzconnect/qobuzconnect_real:1:3227\n at new Promise ()\n at QobuzConnectClient.restartDaemon (/myvolumio/plugins/music_service/qobuzconnect/qobuzconnect_real:1:3038)\n at QobuzConnectClient.initialize (/myvolumio/plugins/music_service/qobuzconnect/qobuzconnect_real:1:2993)\n at o (/myvolumio/plugins/music_service/qobuzconnect/qobuzconnect_real:1:9478)\n at ControllerQobuzConnect.onStart (/myvolumio/plugins/music_service/qobuzconnect/qobuzconnect_real:1:11125)\n at /myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:14605\n at Promise.fin (/volumio/node_modules/kew/kew.js:340:5)\n at MyVolumioPluginManager.startMyVolumioPlugin (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:14522)\n at HashMap. (/myvolumio/app/myvolumio-pluginmanager/myvolumio_pluginmanager_real:1:14071)"} Aug 27 22:20:54 quad4 systemd[1]: mpd.service: Deactivated successfully. Aug 27 22:20:54 quad4 systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 27 22:20:54 quad4 systemd[1]: mpd.service: Consumed 2.628s CPU time. Aug 27 22:20:54 quad4 systemd[1]: mpd.socket: Deactivated successfully. Aug 27 22:20:54 quad4 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 27 22:20:54 quad4 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 27 22:20:55 quad4 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 27 22:20:55 quad4 systemd[1]: Starting mpd.service - Music Player Daemon... Aug 27 22:20:55 quad4 sudo[1704]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 27 22:20:55 quad4 sudo[1704]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 27 22:20:55 quad4 sudo[1704]: pam_unix(sudo:session): session closed for user root Aug 27 22:20:55 quad4 systemd[1]: sable.service: Scheduled restart job, restart counter is at 9. Aug 27 22:20:55 quad4 systemd[1]: Stopped sable.service - Sable Display (Quadify successor) - OLED + rotary + spectrum. Aug 27 22:20:55 quad4 systemd[1]: Started sable.service - Sable Display (Quadify successor) - OLED + rotary + spectrum. Aug 27 22:20:55 quad4 volumio[872]: info: Updating MyVolumio device info Aug 27 22:20:55 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 22:20:55 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 22:20:55 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 22:20:55 quad4 volumio[872]: info: Successfully Added MyVolumio device Aug 27 22:20:55 quad4 python3[1707]: opening SSD1322 (rotate=0deg, fps=20) ... Aug 27 22:20:55 quad4 volumio[872]: info: MRS: Adding multiroomSync output Aug 27 22:20:55 quad4 volumio[872]: info: Adding audio output: Aug 27 22:20:55 quad4 volumio[872]: info: MRS: Pushing multiroomSync output Aug 27 22:20:56 quad4 volumio[872]: info: MPD Permissions set Aug 27 22:20:56 quad4 volumio[872]: info: VolumeController:: External volume change detected: vol=91 mute=false Aug 27 22:20:56 quad4 volumio[872]: info: CoreStateMachine::pushState Aug 27 22:20:56 quad4 volumio[872]: info: CorePlayQueue::getTrack 0 Aug 27 22:20:56 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 22:20:56 quad4 volumio[872]: info: CoreCommandRouter::volumioPushState Aug 27 22:20:56 quad4 volumio[872]: info: MRS: Pushing multiroomSync output update for this device Aug 27 22:20:56 quad4 volumio[872]: info: MRS: Pushing multiroomSync output Aug 27 22:20:56 quad4 volumio[872]: info: CoreCommandRouter::volumioGetState Aug 27 22:20:56 quad4 volumio[872]: info: CorePlayQueue::getTrack 0 Aug 27 22:20:56 quad4 systemd[1]: setdatetime-helper.service: Deactivated successfully. Aug 27 22:20:56 quad4 systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Aug 27 22:20:56 quad4 python3[1707]: Traceback (most recent call last): Aug 27 22:20:56 quad4 python3[1707]: File "/usr/local/lib/python3.11/dist-packages/luma/core/interface/serial.py", line 303, in __init__ Aug 27 22:20:56 quad4 python3[1707]: self._spi.open(port, device) Aug 27 22:20:56 quad4 python3[1707]: FileNotFoundError: [Errno 2] No such file or directory Aug 27 22:20:56 quad4 python3[1707]: During handling of the above exception, another exception occurred: Aug 27 22:20:56 quad4 python3[1707]: Traceback (most recent call last): Aug 27 22:20:56 quad4 python3[1707]: File "", line 198, in _run_module_as_main Aug 27 22:20:56 quad4 python3[1707]: File "", line 88, in _run_code Aug 27 22:20:56 quad4 python3[1707]: File "/home/volumio/sable/src/sable/app.py", line 1385, in Aug 27 22:20:56 quad4 python3[1707]: raise SystemExit(main()) Aug 27 22:20:56 quad4 python3[1707]: ^^^^^^ Aug 27 22:20:56 quad4 python3[1707]: File "/home/volumio/sable/src/sable/app.py", line 1347, in main Aug 27 22:20:56 quad4 python3[1707]: return run_hardware(stage=args.stage, rotate=args.rotate, Aug 27 22:20:56 quad4 python3[1707]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Aug 27 22:20:56 quad4 python3[1707]: File "/home/volumio/sable/src/sable/app.py", line 1083, in run_hardware Aug 27 22:20:56 quad4 python3[1707]: display = OledDisplay(hardware.OLED, rotate=rotate_deg, log=log) Aug 27 22:20:56 quad4 python3[1707]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Aug 27 22:20:56 quad4 python3[1707]: File "/home/volumio/sable/src/sable/display/oled.py", line 90, in __init__ Aug 27 22:20:56 quad4 python3[1707]: self._serial = spi( Aug 27 22:20:56 quad4 python3[1707]: ^^^^ Aug 27 22:20:56 quad4 python3[1707]: File "/usr/local/lib/python3.11/dist-packages/luma/core/interface/serial.py", line 314, in __init__ Aug 27 22:20:56 quad4 python3[1707]: raise luma.core.error.DeviceNotFoundError('SPI device not found') Aug 27 22:20:56 quad4 python3[1707]: luma.core.error.DeviceNotFoundError: SPI device not found Aug 27 22:20:56 quad4 mpd[1706]: 2026-08-27T22:20:56 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 27 22:20:56 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:20:56 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:20:56 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:20:56 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:20:56 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:20:56 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:20:56 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:20:56 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:20:56 quad4 systemd[1]: Started mpd.service - Music Player Daemon. Aug 27 22:20:56 quad4 sudo[1673]: pam_unix(sudo:session): session closed for user root Aug 27 22:20:56 quad4 systemd[1]: sable.service: Main process exited, code=exited, status=1/FAILURE Aug 27 22:20:56 quad4 systemd[1]: sable.service: Failed with result 'exit-code'. Aug 27 22:20:56 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 22:20:56 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:20:56 quad4 volumio[872]: info: Successfully Updated MyVolumio device Aug 27 22:20:56 quad4 volumio[872]: info: Access Token successfully retrieved Aug 27 22:20:56 quad4 volumio[872]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 27 22:20:56 quad4 volumio[872]: info: [1787862056702] CoreMusicLibrary::Adding element QOBUZ Aug 27 22:20:56 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 22:20:56 quad4 volumio[872]: Cannot find translation for source QOBUZ Aug 27 22:20:56 quad4 volumio[872]: info: Stopping AccessToken refresher cron for QOBUZ Aug 27 22:20:56 quad4 volumio[872]: info: AccessToken refresher cron started for QOBUZ Aug 27 22:20:56 quad4 volumio[872]: info: Adding QOBUZ REST API Endpoints Aug 27 22:20:56 quad4 volumio[872]: [Metrics] CommandRouter: 39s 894.77ms Aug 27 22:20:56 quad4 volumio[872]: info: CoreCommandRouter::volumiosetStartupVolume Aug 27 22:20:56 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:20:56 quad4 volumio[872]: info: VolumeController:: Setting startup Volume 90 Aug 27 22:20:56 quad4 volumio[872]: info: VolumeController::SetAlsaVolume90 Aug 27 22:20:56 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 22:20:56 quad4 volumio[872]: info: CoreCommandRouter::Close All Modals sent Aug 27 22:20:56 quad4 volumio[872]: info: CoreCommandRouter::Close All Modals sent Aug 27 22:20:56 quad4 volumio[872]: info: CoreStateMachine::pushState Aug 27 22:20:56 quad4 volumio[872]: info: CorePlayQueue::getTrack 0 Aug 27 22:20:56 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 22:20:56 quad4 volumio[872]: info: CoreCommandRouter::volumioPushState Aug 27 22:20:56 quad4 volumio[872]: info: MRS: Pushing multiroomSync output update for this device Aug 27 22:20:56 quad4 volumio[872]: info: MRS: Pushing multiroomSync output Aug 27 22:20:56 quad4 volumio[872]: info: CoreCommandRouter::volumioGetState Aug 27 22:20:56 quad4 volumio[872]: info: CorePlayQueue::getTrack 0 Aug 27 22:20:56 quad4 volumio[872]: error: updateQueue error: null Aug 27 22:20:56 quad4 sudo[1740]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Aug 27 22:20:56 quad4 sudo[1740]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 22:20:56 quad4 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. Aug 27 22:20:56 quad4 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. Aug 27 22:20:56 quad4 systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Aug 27 22:20:56 quad4 sudo[1740]: pam_unix(sudo:session): session closed for user root Aug 27 22:20:56 quad4 volumio[872]: info: Remote SSH Started Aug 27 22:20:56 quad4 autossh[1743]: port set to 0, monitoring disabled Aug 27 22:20:56 quad4 autossh[1743]: starting ssh (count 1) Aug 27 22:20:56 quad4 autossh[1743]: ssh child pid is 1746 Aug 27 22:20:57 quad4 volumiossh-tunnel[1746]: Warning: Permanently added '[eu12.myvolumio.org]:2222' (ED25519) to the list of known hosts. Aug 27 22:20:57 quad4 volumio[872]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Aug 27 22:20:57 quad4 volumio[872]: info: CoreCommandRouter::volumioGetState Aug 27 22:20:57 quad4 volumio[872]: info: CorePlayQueue::getTrack 0 Aug 27 22:20:57 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Aug 27 22:20:57 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 27 22:20:57 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Aug 27 22:20:58 quad4 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Aug 27 22:20:58 quad4 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 27 22:20:58 quad4 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 27 22:20:58 quad4 upmpdcli[1747]: Could not open config: /tmp/upmpdcli.conf Aug 27 22:20:58 quad4 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Aug 27 22:20:58 quad4 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Aug 27 22:20:59 quad4 volumio[872]: info: MRS: Getting audio outputs on start Aug 27 22:20:59 quad4 volumio[872]: info: MRS: Requesting all other devices output Aug 27 22:20:59 quad4 systemd[1]: sable.service: Scheduled restart job, restart counter is at 10. Aug 27 22:20:59 quad4 systemd[1]: Stopped sable.service - Sable Display (Quadify successor) - OLED + rotary + spectrum. Aug 27 22:20:59 quad4 systemd[1]: Started sable.service - Sable Display (Quadify successor) - OLED + rotary + spectrum. Aug 27 22:21:00 quad4 python3[1748]: opening SSD1322 (rotate=0deg, fps=20) ... Aug 27 22:21:00 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 27 22:21:00 quad4 volumio[872]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Aug 27 22:21:00 quad4 volumio[872]: info: CoreCommandRouter::volumioGetState Aug 27 22:21:00 quad4 volumio[872]: info: CorePlayQueue::getTrack 0 Aug 27 22:21:00 quad4 python3[1748]: Traceback (most recent call last): Aug 27 22:21:00 quad4 python3[1748]: File "/usr/local/lib/python3.11/dist-packages/luma/core/interface/serial.py", line 303, in __init__ Aug 27 22:21:00 quad4 python3[1748]: self._spi.open(port, device) Aug 27 22:21:00 quad4 python3[1748]: FileNotFoundError: [Errno 2] No such file or directory Aug 27 22:21:00 quad4 python3[1748]: During handling of the above exception, another exception occurred: Aug 27 22:21:00 quad4 python3[1748]: Traceback (most recent call last): Aug 27 22:21:00 quad4 python3[1748]: File "", line 198, in _run_module_as_main Aug 27 22:21:00 quad4 python3[1748]: File "", line 88, in _run_code Aug 27 22:21:00 quad4 python3[1748]: File "/home/volumio/sable/src/sable/app.py", line 1385, in Aug 27 22:21:00 quad4 python3[1748]: raise SystemExit(main()) Aug 27 22:21:00 quad4 python3[1748]: ^^^^^^ Aug 27 22:21:00 quad4 python3[1748]: File "/home/volumio/sable/src/sable/app.py", line 1347, in main Aug 27 22:21:00 quad4 python3[1748]: return run_hardware(stage=args.stage, rotate=args.rotate, Aug 27 22:21:00 quad4 python3[1748]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Aug 27 22:21:00 quad4 python3[1748]: File "/home/volumio/sable/src/sable/app.py", line 1083, in run_hardware Aug 27 22:21:00 quad4 python3[1748]: display = OledDisplay(hardware.OLED, rotate=rotate_deg, log=log) Aug 27 22:21:00 quad4 python3[1748]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Aug 27 22:21:00 quad4 python3[1748]: File "/home/volumio/sable/src/sable/display/oled.py", line 90, in __init__ Aug 27 22:21:00 quad4 python3[1748]: self._serial = spi( Aug 27 22:21:00 quad4 python3[1748]: ^^^^ Aug 27 22:21:00 quad4 python3[1748]: File "/usr/local/lib/python3.11/dist-packages/luma/core/interface/serial.py", line 314, in __init__ Aug 27 22:21:00 quad4 python3[1748]: raise luma.core.error.DeviceNotFoundError('SPI device not found') Aug 27 22:21:00 quad4 python3[1748]: luma.core.error.DeviceNotFoundError: SPI device not found Aug 27 22:21:01 quad4 systemd[1]: sable.service: Main process exited, code=exited, status=1/FAILURE Aug 27 22:21:01 quad4 systemd[1]: sable.service: Failed with result 'exit-code'. Aug 27 22:21:03 quad4 volumio[872]: info: BOOT COMPLETED Aug 27 22:21:04 quad4 volumio[872]: info: CoreCommandRouter::volumioPlay Aug 27 22:21:04 quad4 volumio[872]: info: CoreStateMachine::play index undefined Aug 27 22:21:04 quad4 volumio[872]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 22:21:04 quad4 volumio[872]: info: CorePlayQueue::getTrack 0 Aug 27 22:21:04 quad4 volumio[872]: info: CoreStateMachine::startPlaybackTimer Aug 27 22:21:04 quad4 volumio[872]: info: CorePlayQueue::getTrack 0 Aug 27 22:21:04 quad4 volumio[872]: info: [1787862064170] ControllerQobuz::clearAddPlayTrack Aug 27 22:21:04 quad4 systemd[1]: sable.service: Scheduled restart job, restart counter is at 11. Aug 27 22:21:04 quad4 systemd[1]: Stopped sable.service - Sable Display (Quadify successor) - OLED + rotary + spectrum. Aug 27 22:21:04 quad4 systemd[1]: Started sable.service - Sable Display (Quadify successor) - OLED + rotary + spectrum. Aug 27 22:21:04 quad4 volumio[872]: info: getStreamUrl took 344 milliseconds Aug 27 22:21:04 quad4 volumio[872]: verbose: ControllerMpd::sendMpdCommand stop Aug 27 22:21:04 quad4 volumio[872]: info: sendMpdCommand stop took 1 milliseconds Aug 27 22:21:04 quad4 volumio[872]: verbose: ControllerMpd::sendMpdCommand clear Aug 27 22:21:04 quad4 volumio[872]: info: Aug 27 22:21:04 quad4 volumio[872]: ---------------------------- MPD announces system playlist update Aug 27 22:21:04 quad4 volumio[872]: info: Ignoring MPD Status Update Aug 27 22:21:04 quad4 volumio[872]: info: sendMpdCommand clear took 2 milliseconds Aug 27 22:21:04 quad4 volumio[872]: verbose: ControllerMpd::sendMpdCommand load "https://streaming-qobuz-std.akamaized.net/file?uid=6030242&eid=6419180&fmt=6&profile=raw&app_id=539451548&cid=2927406&etsp=1787865664&hmac=vK9F1VdeM5GVajScBlBnrzbwMOQ" Aug 27 22:21:04 quad4 volumio[872]: error: updateQueue error: null Aug 27 22:21:04 quad4 volumio[872]: info: ------------------------------ 3ms Aug 27 22:21:04 quad4 python3[1768]: opening SSD1322 (rotate=0deg, fps=20) ... Aug 27 22:21:05 quad4 volumio[872]: info: CoreCommandRouter::volumioPlay Aug 27 22:21:05 quad4 volumio[872]: info: CoreStateMachine::play index undefined Aug 27 22:21:05 quad4 volumio[872]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 22:21:05 quad4 volumio[872]: info: CorePlayQueue::getTrack 0 Aug 27 22:21:05 quad4 volumio[872]: info: CoreStateMachine::startPlaybackTimer Aug 27 22:21:05 quad4 volumio[872]: info: CorePlayQueue::getTrack 0 Aug 27 22:21:05 quad4 volumio[872]: info: [1787862065006] ControllerQobuz::clearAddPlayTrack Aug 27 22:21:05 quad4 volumio[872]: verbose: ControllerMpd::sendMpdCommand add "https://streaming-qobuz-std.akamaized.net/file?uid=6030242&eid=6419180&fmt=6&profile=raw&app_id=539451548&cid=2927406&etsp=1787865664&hmac=vK9F1VdeM5GVajScBlBnrzbwMOQ" Aug 27 22:21:05 quad4 volumio[872]: info: Aug 27 22:21:05 quad4 volumio[872]: ---------------------------- MPD announces system playlist update Aug 27 22:21:05 quad4 volumio[872]: info: Ignoring MPD Status Update Aug 27 22:21:05 quad4 volumio[872]: info: sendMpdCommand add "https://streaming-qobuz-std.akamaized.net/file?uid=6030242&eid=6419180&fmt=6&profile=raw&app_id=539451548&cid=2927406&etsp=1787865664&hmac=vK9F1VdeM5GVajScBlBnrzbwMOQ" took 1 milliseconds Aug 27 22:21:05 quad4 volumio[872]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 27 22:21:05 quad4 volumio[872]: verbose: ControllerMpd::sendMpdCommand play Aug 27 22:21:05 quad4 volumio[872]: info: ------------------------------ 1ms Aug 27 22:21:05 quad4 volumio[872]: info: sendMpdCommand play took 1 milliseconds Aug 27 22:21:05 quad4 volumio[872]: info: getStreamUrl took 115 milliseconds Aug 27 22:21:05 quad4 volumio[872]: verbose: ControllerMpd::sendMpdCommand stop Aug 27 22:21:05 quad4 volumio[872]: info: Aug 27 22:21:05 quad4 volumio[872]: ---------------------------- MPD announces state update: player Aug 27 22:21:05 quad4 volumio[872]: info: sendMpdCommand stop took 1 milliseconds Aug 27 22:21:05 quad4 volumio[872]: info: ControllerMpd::getState Aug 27 22:21:05 quad4 volumio[872]: verbose: ControllerMpd::sendMpdCommand status Aug 27 22:21:05 quad4 volumio[872]: verbose: ControllerMpd::sendMpdCommand clear Aug 27 22:21:05 quad4 volumio[872]: info: Aug 27 22:21:05 quad4 volumio[872]: ---------------------------- MPD announces system playlist update Aug 27 22:21:05 quad4 volumio[872]: info: Ignoring MPD Status Update Aug 27 22:21:05 quad4 volumio[872]: info: sendMpdCommand status took 29 milliseconds Aug 27 22:21:05 quad4 volumio[872]: info: sendMpdCommand clear took 29 milliseconds Aug 27 22:21:05 quad4 volumio[872]: verbose: ControllerMpd::parseState Aug 27 22:21:05 quad4 volumio[872]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 27 22:21:05 quad4 volumio[872]: verbose: ControllerMpd::sendMpdCommand load "https://streaming-qobuz-std.akamaized.net/file?uid=6030242&eid=6419180&fmt=6&profile=raw&app_id=539451548&cid=2927406&etsp=1787865665&hmac=JaJeKQ6uMnr6WiYZL1g1SABqXKU" Aug 27 22:21:05 quad4 volumio[872]: info: CoreCommandRouter::volumioPlay Aug 27 22:21:05 quad4 volumio[872]: info: CoreStateMachine::play index undefined Aug 27 22:21:05 quad4 volumio[872]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 22:21:05 quad4 volumio[872]: info: CorePlayQueue::getTrack 0 Aug 27 22:21:05 quad4 volumio[872]: info: CoreStateMachine::startPlaybackTimer Aug 27 22:21:05 quad4 volumio[872]: info: CorePlayQueue::getTrack 0 Aug 27 22:21:05 quad4 volumio[872]: info: [1787862065223] ControllerQobuz::clearAddPlayTrack Aug 27 22:21:05 quad4 volumio[872]: info: getStreamUrl took 132 milliseconds Aug 27 22:21:05 quad4 volumio[872]: verbose: ControllerMpd::sendMpdCommand stop Aug 27 22:21:05 quad4 volumio[872]: error: updateQueue error: null Aug 27 22:21:05 quad4 volumio[872]: info: ------------------------------ 286ms Aug 27 22:21:05 quad4 volumio[872]: info: sendMpdCommand playlistinfo took 284 milliseconds Aug 27 22:21:05 quad4 volumio[872]: verbose: ControllerMpd::sendMpdCommand add "https://streaming-qobuz-std.akamaized.net/file?uid=6030242&eid=6419180&fmt=6&profile=raw&app_id=539451548&cid=2927406&etsp=1787865665&hmac=JaJeKQ6uMnr6WiYZL1g1SABqXKU" Aug 27 22:21:05 quad4 volumio[872]: info: sendMpdCommand stop took 83 milliseconds Aug 27 22:21:05 quad4 volumio[872]: verbose: ControllerMpd::parseTrackInfo Aug 27 22:21:05 quad4 volumio[872]: verbose: ControllerMpd::sendMpdCommand clear Aug 27 22:21:05 quad4 volumio[872]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Aug 27 22:21:05 quad4 volumio[872]: info: ------------------------------ 318ms Aug 27 22:21:05 quad4 volumio[872]: info: Aug 27 22:21:05 quad4 volumio[872]: ---------------------------- MPD announces system playlist update Aug 27 22:21:05 quad4 volumio[872]: info: Ignoring MPD Status Update Aug 27 22:21:05 quad4 volumio[872]: info: Aug 27 22:21:05 quad4 volumio[872]: ---------------------------- MPD announces system playlist update Aug 27 22:21:05 quad4 volumio[872]: info: Ignoring MPD Status Update Aug 27 22:21:05 quad4 volumio[872]: info: sendMpdCommand add "https://streaming-qobuz-std.akamaized.net/file?uid=6030242&eid=6419180&fmt=6&profile=raw&app_id=539451548&cid=2927406&etsp=1787865665&hmac=JaJeKQ6uMnr6WiYZL1g1SABqXKU" took 23 milliseconds Aug 27 22:21:05 quad4 volumio[872]: info: sendMpdCommand clear took 22 milliseconds Aug 27 22:21:05 quad4 volumio[872]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 27 22:21:05 quad4 volumio[872]: verbose: ControllerMpd::sendMpdCommand play Aug 27 22:21:05 quad4 volumio[872]: verbose: ControllerMpd::sendMpdCommand load "https://streaming-qobuz-std.akamaized.net/file?uid=6030242&eid=6419180&fmt=6&profile=raw&app_id=539451548&cid=2927406&etsp=1787865665&hmac=JaJeKQ6uMnr6WiYZL1g1SABqXKU" Aug 27 22:21:05 quad4 volumio[872]: error: updateQueue error: null Aug 27 22:21:05 quad4 volumio[872]: error: updateQueue error: null Aug 27 22:21:05 quad4 volumio[872]: info: ------------------------------ 23ms Aug 27 22:21:05 quad4 volumio[872]: info: ------------------------------ 3ms Aug 27 22:21:05 quad4 python3[1768]: Traceback (most recent call last): Aug 27 22:21:05 quad4 python3[1768]: File "/usr/local/lib/python3.11/dist-packages/luma/core/interface/serial.py", line 303, in __init__ Aug 27 22:21:05 quad4 volumio[872]: info: sendMpdCommand play took 2 milliseconds Aug 27 22:21:05 quad4 python3[1768]: self._spi.open(port, device) Aug 27 22:21:05 quad4 python3[1768]: FileNotFoundError: [Errno 2] No such file or directory Aug 27 22:21:05 quad4 python3[1768]: During handling of the above exception, another exception occurred: Aug 27 22:21:05 quad4 python3[1768]: Traceback (most recent call last): Aug 27 22:21:05 quad4 python3[1768]: File "", line 198, in _run_module_as_main Aug 27 22:21:05 quad4 python3[1768]: File "", line 88, in _run_code Aug 27 22:21:05 quad4 python3[1768]: File "/home/volumio/sable/src/sable/app.py", line 1385, in Aug 27 22:21:05 quad4 python3[1768]: raise SystemExit(main()) Aug 27 22:21:05 quad4 python3[1768]: ^^^^^^ Aug 27 22:21:05 quad4 python3[1768]: File "/home/volumio/sable/src/sable/app.py", line 1347, in main Aug 27 22:21:05 quad4 python3[1768]: return run_hardware(stage=args.stage, rotate=args.rotate, Aug 27 22:21:05 quad4 python3[1768]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Aug 27 22:21:05 quad4 python3[1768]: File "/home/volumio/sable/src/sable/app.py", line 1083, in run_hardware Aug 27 22:21:05 quad4 python3[1768]: display = OledDisplay(hardware.OLED, rotate=rotate_deg, log=log) Aug 27 22:21:05 quad4 python3[1768]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Aug 27 22:21:05 quad4 python3[1768]: File "/home/volumio/sable/src/sable/display/oled.py", line 90, in __init__ Aug 27 22:21:05 quad4 python3[1768]: self._serial = spi( Aug 27 22:21:05 quad4 python3[1768]: ^^^^ Aug 27 22:21:05 quad4 python3[1768]: File "/usr/local/lib/python3.11/dist-packages/luma/core/interface/serial.py", line 314, in __init__ Aug 27 22:21:05 quad4 python3[1768]: raise luma.core.error.DeviceNotFoundError('SPI device not found') Aug 27 22:21:05 quad4 python3[1768]: luma.core.error.DeviceNotFoundError: SPI device not found Aug 27 22:21:05 quad4 systemd[1]: sable.service: Main process exited, code=exited, status=1/FAILURE Aug 27 22:21:05 quad4 systemd[1]: sable.service: Failed with result 'exit-code'. Aug 27 22:21:05 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:21:05 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:21:05 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Aug 27 22:21:05 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Aug 27 22:21:05 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Aug 27 22:21:05 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Aug 27 22:21:05 quad4 volumio[872]: info: CoreCommandRouter::volumioGetBrowseSources Aug 27 22:21:05 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 27 22:21:05 quad4 volumio[872]: verbose: ControllerMpd::sendMpdCommand add "https://streaming-qobuz-std.akamaized.net/file?uid=6030242&eid=6419180&fmt=6&profile=raw&app_id=539451548&cid=2927406&etsp=1787865665&hmac=JaJeKQ6uMnr6WiYZL1g1SABqXKU" Aug 27 22:21:05 quad4 volumio[872]: info: Aug 27 22:21:05 quad4 volumio[872]: ---------------------------- MPD announces system playlist update Aug 27 22:21:05 quad4 volumio[872]: info: Ignoring MPD Status Update Aug 27 22:21:05 quad4 volumio[872]: info: sendMpdCommand add "https://streaming-qobuz-std.akamaized.net/file?uid=6030242&eid=6419180&fmt=6&profile=raw&app_id=539451548&cid=2927406&etsp=1787865665&hmac=JaJeKQ6uMnr6WiYZL1g1SABqXKU" took 0 milliseconds Aug 27 22:21:05 quad4 volumio[872]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 27 22:21:05 quad4 volumio[872]: verbose: ControllerMpd::sendMpdCommand play Aug 27 22:21:05 quad4 volumio[872]: info: ------------------------------ 2ms Aug 27 22:21:05 quad4 volumio[872]: info: sendMpdCommand play took 1 milliseconds Aug 27 22:21:06 quad4 volumio[872]: info: Aug 27 22:21:06 quad4 volumio[872]: ---------------------------- MPD announces state update: player Aug 27 22:21:06 quad4 volumio[872]: info: ControllerMpd::getState Aug 27 22:21:06 quad4 volumio[872]: verbose: ControllerMpd::sendMpdCommand status Aug 27 22:21:06 quad4 volumio[872]: info: Aug 27 22:21:06 quad4 volumio[872]: ---------------------------- MPD announces state update: player Aug 27 22:21:06 quad4 volumio[872]: info: sendMpdCommand status took 58 milliseconds Aug 27 22:21:06 quad4 volumio[872]: info: ControllerMpd::getState Aug 27 22:21:06 quad4 volumio[872]: verbose: ControllerMpd::sendMpdCommand status Aug 27 22:21:06 quad4 volumio[872]: verbose: ControllerMpd::parseState Aug 27 22:21:06 quad4 volumio[872]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 27 22:21:06 quad4 volumio[872]: info: sendMpdCommand status took 1 milliseconds Aug 27 22:21:06 quad4 volumio[872]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 27 22:21:06 quad4 volumio[872]: verbose: ControllerMpd::parseState Aug 27 22:21:06 quad4 volumio[872]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 27 22:21:06 quad4 volumio[872]: verbose: ControllerMpd::parseTrackInfo Aug 27 22:21:06 quad4 volumio[872]: info: ControllerMpd::pushState Aug 27 22:21:06 quad4 volumio[872]: info: CoreCommandRouter::servicePushState Aug 27 22:21:06 quad4 volumio[872]: info: CorePlayQueue::getTrack 0 Aug 27 22:21:06 quad4 volumio[872]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":365,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file?uid=6030242&eid=6419180&fmt=6&profile=raw&app_id=539451548&cid=2927406&etsp=1787865665&hmac=JaJeKQ6uMnr6WiYZL1g1SABqXKU","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=6030242&eid=6419180&fmt=6&profile=raw&app_id=539451548&cid=2927406&etsp=1787865665&hmac=JaJeKQ6uMnr6WiYZL1g1SABqXKU","trackType":"qobuz"} Aug 27 22:21:06 quad4 volumio[872]: verbose: CURRENT POSITION 0 Aug 27 22:21:06 quad4 volumio[872]: info: CoreStateMachine::syncState stateService play Aug 27 22:21:06 quad4 volumio[872]: info: CoreStateMachine::syncState currentStatus stop Aug 27 22:21:06 quad4 volumio[872]: info: ------------------------------ 73ms Aug 27 22:21:06 quad4 volumio[872]: info: sendMpdCommand playlistinfo took 10 milliseconds Aug 27 22:21:06 quad4 volumio[872]: verbose: ControllerMpd::parseTrackInfo Aug 27 22:21:06 quad4 volumio[872]: info: ControllerMpd::pushState Aug 27 22:21:06 quad4 volumio[872]: info: CoreCommandRouter::servicePushState Aug 27 22:21:06 quad4 volumio[872]: info: CorePlayQueue::getTrack 0 Aug 27 22:21:06 quad4 volumio[872]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":365,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"778 Kbps","isStreaming":false,"title":"file?uid=6030242&eid=6419180&fmt=6&profile=raw&app_id=539451548&cid=2927406&etsp=1787865665&hmac=JaJeKQ6uMnr6WiYZL1g1SABqXKU","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=6030242&eid=6419180&fmt=6&profile=raw&app_id=539451548&cid=2927406&etsp=1787865665&hmac=JaJeKQ6uMnr6WiYZL1g1SABqXKU","trackType":"qobuz"} Aug 27 22:21:06 quad4 volumio[872]: verbose: CURRENT POSITION 0 Aug 27 22:21:06 quad4 volumio[872]: info: CoreStateMachine::syncState stateService play Aug 27 22:21:06 quad4 volumio[872]: info: CoreStateMachine::syncState currentStatus play Aug 27 22:21:06 quad4 volumio[872]: info: Received an update from plugin. extracting info from payload Aug 27 22:21:06 quad4 volumio[872]: info: CoreStateMachine::pushState Aug 27 22:21:06 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 22:21:06 quad4 volumio[872]: info: CoreCommandRouter::volumioPushState Aug 27 22:21:06 quad4 volumio[872]: info: MRS: Pushing multiroomSync output update for this device Aug 27 22:21:06 quad4 volumio[872]: info: MRS: Pushing multiroomSync output Aug 27 22:21:06 quad4 volumio[872]: info: CoreCommandRouter::volumioGetState Aug 27 22:21:06 quad4 volumio[872]: info: CoreStateMachine::pushState Aug 27 22:21:06 quad4 volumio[872]: info: CoreCommandRouter::volumioPushState Aug 27 22:21:06 quad4 volumio[872]: info: MRS: Pushing multiroomSync output update for this device Aug 27 22:21:06 quad4 volumio[872]: info: MRS: Pushing multiroomSync output Aug 27 22:21:06 quad4 volumio[872]: info: CoreCommandRouter::volumioGetState Aug 27 22:21:06 quad4 volumio[872]: info: ------------------------------ 96ms Aug 27 22:21:08 quad4 systemd[1]: sable.service: Scheduled restart job, restart counter is at 12. Aug 27 22:21:08 quad4 systemd[1]: Stopped sable.service - Sable Display (Quadify successor) - OLED + rotary + spectrum. Aug 27 22:21:08 quad4 systemd[1]: Started sable.service - Sable Display (Quadify successor) - OLED + rotary + spectrum. Aug 27 22:21:09 quad4 python3[1783]: opening SSD1322 (rotate=0deg, fps=20) ... Aug 27 22:21:09 quad4 python3[1783]: Traceback (most recent call last): Aug 27 22:21:09 quad4 python3[1783]: File "/usr/local/lib/python3.11/dist-packages/luma/core/interface/serial.py", line 303, in __init__ Aug 27 22:21:09 quad4 python3[1783]: self._spi.open(port, device) Aug 27 22:21:09 quad4 python3[1783]: FileNotFoundError: [Errno 2] No such file or directory Aug 27 22:21:09 quad4 python3[1783]: During handling of the above exception, another exception occurred: Aug 27 22:21:09 quad4 python3[1783]: Traceback (most recent call last): Aug 27 22:21:09 quad4 python3[1783]: File "", line 198, in _run_module_as_main Aug 27 22:21:09 quad4 python3[1783]: File "", line 88, in _run_code Aug 27 22:21:09 quad4 python3[1783]: File "/home/volumio/sable/src/sable/app.py", line 1385, in Aug 27 22:21:09 quad4 python3[1783]: raise SystemExit(main()) Aug 27 22:21:09 quad4 python3[1783]: ^^^^^^ Aug 27 22:21:09 quad4 python3[1783]: File "/home/volumio/sable/src/sable/app.py", line 1347, in main Aug 27 22:21:09 quad4 python3[1783]: return run_hardware(stage=args.stage, rotate=args.rotate, Aug 27 22:21:09 quad4 python3[1783]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Aug 27 22:21:09 quad4 python3[1783]: File "/home/volumio/sable/src/sable/app.py", line 1083, in run_hardware Aug 27 22:21:09 quad4 python3[1783]: display = OledDisplay(hardware.OLED, rotate=rotate_deg, log=log) Aug 27 22:21:09 quad4 python3[1783]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Aug 27 22:21:09 quad4 python3[1783]: File "/home/volumio/sable/src/sable/display/oled.py", line 90, in __init__ Aug 27 22:21:09 quad4 python3[1783]: self._serial = spi( Aug 27 22:21:09 quad4 python3[1783]: ^^^^ Aug 27 22:21:09 quad4 python3[1783]: File "/usr/local/lib/python3.11/dist-packages/luma/core/interface/serial.py", line 314, in __init__ Aug 27 22:21:09 quad4 python3[1783]: raise luma.core.error.DeviceNotFoundError('SPI device not found') Aug 27 22:21:09 quad4 python3[1783]: luma.core.error.DeviceNotFoundError: SPI device not found Aug 27 22:21:10 quad4 systemd[1]: sable.service: Main process exited, code=exited, status=1/FAILURE Aug 27 22:21:10 quad4 systemd[1]: sable.service: Failed with result 'exit-code'. Aug 27 22:21:13 quad4 systemd[1]: sable.service: Scheduled restart job, restart counter is at 13. Aug 27 22:21:13 quad4 systemd[1]: Stopped sable.service - Sable Display (Quadify successor) - OLED + rotary + spectrum. Aug 27 22:21:13 quad4 systemd[1]: Started sable.service - Sable Display (Quadify successor) - OLED + rotary + spectrum. Aug 27 22:21:13 quad4 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 4. Aug 27 22:21:13 quad4 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 27 22:21:13 quad4 python3[1803]: opening SSD1322 (rotate=0deg, fps=20) ... Aug 27 22:21:13 quad4 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 27 22:21:13 quad4 upmpdcli[1805]: Could not open config: /tmp/upmpdcli.conf Aug 27 22:21:13 quad4 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Aug 27 22:21:13 quad4 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Aug 27 22:21:14 quad4 python3[1803]: Traceback (most recent call last): Aug 27 22:21:14 quad4 python3[1803]: File "/usr/local/lib/python3.11/dist-packages/luma/core/interface/serial.py", line 303, in __init__ Aug 27 22:21:14 quad4 python3[1803]: self._spi.open(port, device) Aug 27 22:21:14 quad4 python3[1803]: FileNotFoundError: [Errno 2] No such file or directory Aug 27 22:21:14 quad4 python3[1803]: During handling of the above exception, another exception occurred: Aug 27 22:21:14 quad4 python3[1803]: Traceback (most recent call last): Aug 27 22:21:14 quad4 python3[1803]: File "", line 198, in _run_module_as_main Aug 27 22:21:14 quad4 python3[1803]: File "", line 88, in _run_code Aug 27 22:21:14 quad4 python3[1803]: File "/home/volumio/sable/src/sable/app.py", line 1385, in Aug 27 22:21:14 quad4 python3[1803]: raise SystemExit(main()) Aug 27 22:21:14 quad4 python3[1803]: ^^^^^^ Aug 27 22:21:14 quad4 python3[1803]: File "/home/volumio/sable/src/sable/app.py", line 1347, in main Aug 27 22:21:14 quad4 python3[1803]: return run_hardware(stage=args.stage, rotate=args.rotate, Aug 27 22:21:14 quad4 python3[1803]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Aug 27 22:21:14 quad4 python3[1803]: File "/home/volumio/sable/src/sable/app.py", line 1083, in run_hardware Aug 27 22:21:14 quad4 python3[1803]: display = OledDisplay(hardware.OLED, rotate=rotate_deg, log=log) Aug 27 22:21:14 quad4 python3[1803]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Aug 27 22:21:14 quad4 python3[1803]: File "/home/volumio/sable/src/sable/display/oled.py", line 90, in __init__ Aug 27 22:21:14 quad4 python3[1803]: self._serial = spi( Aug 27 22:21:14 quad4 python3[1803]: ^^^^ Aug 27 22:21:14 quad4 python3[1803]: File "/usr/local/lib/python3.11/dist-packages/luma/core/interface/serial.py", line 314, in __init__ Aug 27 22:21:14 quad4 python3[1803]: raise luma.core.error.DeviceNotFoundError('SPI device not found') Aug 27 22:21:14 quad4 python3[1803]: luma.core.error.DeviceNotFoundError: SPI device not found Aug 27 22:21:14 quad4 systemd[1]: sable.service: Main process exited, code=exited, status=1/FAILURE Aug 27 22:21:14 quad4 systemd[1]: sable.service: Failed with result 'exit-code'. Aug 27 22:21:17 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 27 22:21:17 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 27 22:21:17 quad4 systemd[1]: sable.service: Scheduled restart job, restart counter is at 14. Aug 27 22:21:17 quad4 systemd[1]: Stopped sable.service - Sable Display (Quadify successor) - OLED + rotary + spectrum. Aug 27 22:21:17 quad4 systemd[1]: Started sable.service - Sable Display (Quadify successor) - OLED + rotary + spectrum. Aug 27 22:21:18 quad4 python3[1810]: opening SSD1322 (rotate=0deg, fps=20) ... Aug 27 22:21:18 quad4 python3[1810]: Traceback (most recent call last): Aug 27 22:21:18 quad4 python3[1810]: File "/usr/local/lib/python3.11/dist-packages/luma/core/interface/serial.py", line 303, in __init__ Aug 27 22:21:18 quad4 python3[1810]: self._spi.open(port, device) Aug 27 22:21:18 quad4 python3[1810]: FileNotFoundError: [Errno 2] No such file or directory Aug 27 22:21:18 quad4 python3[1810]: During handling of the above exception, another exception occurred: Aug 27 22:21:18 quad4 python3[1810]: Traceback (most recent call last): Aug 27 22:21:18 quad4 python3[1810]: File "", line 198, in _run_module_as_main Aug 27 22:21:18 quad4 python3[1810]: File "", line 88, in _run_code Aug 27 22:21:18 quad4 python3[1810]: File "/home/volumio/sable/src/sable/app.py", line 1385, in Aug 27 22:21:18 quad4 python3[1810]: raise SystemExit(main()) Aug 27 22:21:18 quad4 python3[1810]: ^^^^^^ Aug 27 22:21:18 quad4 python3[1810]: File "/home/volumio/sable/src/sable/app.py", line 1347, in main Aug 27 22:21:18 quad4 python3[1810]: return run_hardware(stage=args.stage, rotate=args.rotate, Aug 27 22:21:18 quad4 python3[1810]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Aug 27 22:21:18 quad4 python3[1810]: File "/home/volumio/sable/src/sable/app.py", line 1083, in run_hardware Aug 27 22:21:18 quad4 python3[1810]: display = OledDisplay(hardware.OLED, rotate=rotate_deg, log=log) Aug 27 22:21:18 quad4 python3[1810]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Aug 27 22:21:18 quad4 python3[1810]: File "/home/volumio/sable/src/sable/display/oled.py", line 90, in __init__ Aug 27 22:21:18 quad4 python3[1810]: self._serial = spi( Aug 27 22:21:18 quad4 python3[1810]: ^^^^ Aug 27 22:21:18 quad4 python3[1810]: File "/usr/local/lib/python3.11/dist-packages/luma/core/interface/serial.py", line 314, in __init__ Aug 27 22:21:18 quad4 python3[1810]: raise luma.core.error.DeviceNotFoundError('SPI device not found') Aug 27 22:21:18 quad4 python3[1810]: luma.core.error.DeviceNotFoundError: SPI device not found Aug 27 22:21:19 quad4 systemd[1]: sable.service: Main process exited, code=exited, status=1/FAILURE Aug 27 22:21:19 quad4 systemd[1]: sable.service: Failed with result 'exit-code'. Aug 27 22:21:22 quad4 systemd[1]: sable.service: Scheduled restart job, restart counter is at 15. Aug 27 22:21:22 quad4 systemd[1]: Stopped sable.service - Sable Display (Quadify successor) - OLED + rotary + spectrum. Aug 27 22:21:22 quad4 systemd[1]: Started sable.service - Sable Display (Quadify successor) - OLED + rotary + spectrum. Aug 27 22:21:22 quad4 python3[1830]: opening SSD1322 (rotate=0deg, fps=20) ... Aug 27 22:21:23 quad4 python3[1830]: Traceback (most recent call last): Aug 27 22:21:23 quad4 python3[1830]: File "/usr/local/lib/python3.11/dist-packages/luma/core/interface/serial.py", line 303, in __init__ Aug 27 22:21:23 quad4 python3[1830]: self._spi.open(port, device) Aug 27 22:21:23 quad4 python3[1830]: FileNotFoundError: [Errno 2] No such file or directory Aug 27 22:21:23 quad4 python3[1830]: During handling of the above exception, another exception occurred: Aug 27 22:21:23 quad4 python3[1830]: Traceback (most recent call last): Aug 27 22:21:23 quad4 python3[1830]: File "", line 198, in _run_module_as_main Aug 27 22:21:23 quad4 python3[1830]: File "", line 88, in _run_code Aug 27 22:21:23 quad4 python3[1830]: File "/home/volumio/sable/src/sable/app.py", line 1385, in Aug 27 22:21:23 quad4 python3[1830]: raise SystemExit(main()) Aug 27 22:21:23 quad4 python3[1830]: ^^^^^^ Aug 27 22:21:23 quad4 python3[1830]: File "/home/volumio/sable/src/sable/app.py", line 1347, in main Aug 27 22:21:23 quad4 python3[1830]: return run_hardware(stage=args.stage, rotate=args.rotate, Aug 27 22:21:23 quad4 python3[1830]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Aug 27 22:21:23 quad4 python3[1830]: File "/home/volumio/sable/src/sable/app.py", line 1083, in run_hardware Aug 27 22:21:23 quad4 python3[1830]: display = OledDisplay(hardware.OLED, rotate=rotate_deg, log=log) Aug 27 22:21:23 quad4 python3[1830]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Aug 27 22:21:23 quad4 python3[1830]: File "/home/volumio/sable/src/sable/display/oled.py", line 90, in __init__ Aug 27 22:21:23 quad4 python3[1830]: self._serial = spi( Aug 27 22:21:23 quad4 python3[1830]: ^^^^ Aug 27 22:21:23 quad4 python3[1830]: File "/usr/local/lib/python3.11/dist-packages/luma/core/interface/serial.py", line 314, in __init__ Aug 27 22:21:23 quad4 python3[1830]: raise luma.core.error.DeviceNotFoundError('SPI device not found') Aug 27 22:21:23 quad4 python3[1830]: luma.core.error.DeviceNotFoundError: SPI device not found Aug 27 22:21:23 quad4 systemd[1]: sable.service: Main process exited, code=exited, status=1/FAILURE Aug 27 22:21:23 quad4 systemd[1]: sable.service: Failed with result 'exit-code'. Aug 27 22:21:26 quad4 systemd[1]: sable.service: Scheduled restart job, restart counter is at 16. Aug 27 22:21:26 quad4 systemd[1]: Stopped sable.service - Sable Display (Quadify successor) - OLED + rotary + spectrum. Aug 27 22:21:26 quad4 systemd[1]: Started sable.service - Sable Display (Quadify successor) - OLED + rotary + spectrum. Aug 27 22:21:27 quad4 python3[1836]: opening SSD1322 (rotate=0deg, fps=20) ... Aug 27 22:21:27 quad4 python3[1836]: Traceback (most recent call last): Aug 27 22:21:27 quad4 python3[1836]: File "/usr/local/lib/python3.11/dist-packages/luma/core/interface/serial.py", line 303, in __init__ Aug 27 22:21:27 quad4 python3[1836]: self._spi.open(port, device) Aug 27 22:21:27 quad4 python3[1836]: FileNotFoundError: [Errno 2] No such file or directory Aug 27 22:21:27 quad4 python3[1836]: During handling of the above exception, another exception occurred: Aug 27 22:21:27 quad4 python3[1836]: Traceback (most recent call last): Aug 27 22:21:27 quad4 python3[1836]: File "", line 198, in _run_module_as_main Aug 27 22:21:27 quad4 python3[1836]: File "", line 88, in _run_code Aug 27 22:21:27 quad4 python3[1836]: File "/home/volumio/sable/src/sable/app.py", line 1385, in Aug 27 22:21:27 quad4 python3[1836]: raise SystemExit(main()) Aug 27 22:21:27 quad4 python3[1836]: ^^^^^^ Aug 27 22:21:27 quad4 python3[1836]: File "/home/volumio/sable/src/sable/app.py", line 1347, in main Aug 27 22:21:27 quad4 python3[1836]: return run_hardware(stage=args.stage, rotate=args.rotate, Aug 27 22:21:27 quad4 python3[1836]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Aug 27 22:21:27 quad4 python3[1836]: File "/home/volumio/sable/src/sable/app.py", line 1083, in run_hardware Aug 27 22:21:27 quad4 python3[1836]: display = OledDisplay(hardware.OLED, rotate=rotate_deg, log=log) Aug 27 22:21:27 quad4 python3[1836]: ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ Aug 27 22:21:27 quad4 python3[1836]: File "/home/volumio/sable/src/sable/display/oled.py", line 90, in __init__ Aug 27 22:21:27 quad4 python3[1836]: self._serial = spi( Aug 27 22:21:27 quad4 python3[1836]: ^^^^ Aug 27 22:21:27 quad4 python3[1836]: File "/usr/local/lib/python3.11/dist-packages/luma/core/interface/serial.py", line 314, in __init__ Aug 27 22:21:27 quad4 python3[1836]: raise luma.core.error.DeviceNotFoundError('SPI device not found') Aug 27 22:21:27 quad4 python3[1836]: luma.core.error.DeviceNotFoundError: SPI device not found Aug 27 22:21:28 quad4 systemd[1]: sable.service: Main process exited, code=exited, status=1/FAILURE Aug 27 22:21:28 quad4 systemd[1]: sable.service: Failed with result 'exit-code'. Aug 27 22:21:28 quad4 volumio[872]: info: Starting Uninstall of plugin system_hardware - mpd_oled Aug 27 22:21:28 quad4 volumio[872]: info: Uninstalling plugin mpd_oled Aug 27 22:21:28 quad4 volumio[872]: info: Disabling plugin mpd_oled Aug 27 22:21:28 quad4 sudo[1843]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop mpd_oled_plugin.service Aug 27 22:21:28 quad4 sudo[1843]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 22:21:28 quad4 volumio[872]: info: Preparing to generate the ALSA configuration file Aug 27 22:21:28 quad4 volumio[872]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Aug 27 22:21:28 quad4 volumio[872]: info: Reading ALSA contributions from plugins. Aug 27 22:21:28 quad4 sudo[1843]: pam_unix(sudo:session): session closed for user root Aug 27 22:21:28 quad4 volumio[872]: info: [MPD_OLED Plugin] Stopped mpd_oled_plugin service Aug 27 22:21:28 quad4 volumio[872]: info: Asound.conf file written Aug 27 22:21:28 quad4 sudo[1850]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Aug 27 22:21:28 quad4 sudo[1850]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 22:21:28 quad4 sudo[1850]: pam_unix(sudo:session): session closed for user root Aug 27 22:21:28 quad4 volumio[872]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Aug 27 22:21:28 quad4 volumio[872]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Aug 27 22:21:28 quad4 volumio[872]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Aug 27 22:21:28 quad4 volumio[872]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Aug 27 22:21:28 quad4 volumio[872]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:4 use case configuration -2 Aug 27 22:21:28 quad4 volumio[872]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:Max Overclock PLL:0' : Device or resource busy Aug 27 22:21:28 quad4 volumio[872]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:Max Overclock DSP:0' : Device or resource busy Aug 27 22:21:28 quad4 volumio[872]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:Max Overclock DAC:0' : Device or resource busy Aug 27 22:21:28 quad4 volumio[872]: info: Output device has changed, restarting MPD Aug 27 22:21:28 quad4 volumio[872]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 27 22:21:28 quad4 volumio[872]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 22:21:28 quad4 sudo[1856]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 27 22:21:28 quad4 sudo[1856]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 22:21:28 quad4 volumio[872]: info: QobuzConnect: setDeactiveState invoked Aug 27 22:21:28 quad4 volumio[872]: info: CoreCommandRouter::volumioGetState Aug 27 22:21:28 quad4 volumio[872]: info: Checking if uninstall.sh is present Aug 27 22:21:28 quad4 sudo[1858]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 27 22:21:28 quad4 sudo[1858]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 22:21:28 quad4 sudo[1856]: pam_unix(sudo:session): session closed for user root Aug 27 22:21:28 quad4 systemd[1]: Stopping mpd.service - Music Player Daemon... Aug 27 22:21:28 quad4 volumio[872]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 27 22:21:28 quad4 volumio[872]: ReferenceError: exec is not defined Aug 27 22:21:28 quad4 volumio[872]: at /myvolumio/plugins/music_service/qobuzconnect/qobuzconnect_real:1:3227 Aug 27 22:21:28 quad4 volumio[872]: at new Promise () Aug 27 22:21:28 quad4 volumio[872]: at QobuzConnectClient.restartDaemon (/myvolumio/plugins/music_service/qobuzconnect/qobuzconnect_real:1:3038) Aug 27 22:21:28 quad4 volumio[872]: at ControllerQobuzConnect.r (/myvolumio/plugins/music_service/qobuzconnect/qobuzconnect_real:1:9829) Aug 27 22:21:28 quad4 volumio[872]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Aug 27 22:21:28 quad4 volumio[872]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 27 22:21:28 quad4 systemd[1]: mpd.service: Deactivated successfully. Aug 27 22:21:28 quad4 systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 27 22:21:28 quad4 systemd[1]: mpd.service: Consumed 4.173s CPU time. Aug 27 22:21:28 quad4 systemd[1]: mpd.socket: Deactivated successfully. Aug 27 22:21:28 quad4 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 27 22:21:28 quad4 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 27 22:21:28 quad4 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 27 22:21:28 quad4 systemd[1]: Starting mpd.service - Music Player Daemon... Aug 27 22:21:28 quad4 systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 5. Aug 27 22:21:28 quad4 systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 27 22:21:28 quad4 systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 27 22:21:28 quad4 upmpdcli[1872]: Could not open config: /tmp/upmpdcli.conf Aug 27 22:21:28 quad4 systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Aug 27 22:21:28 quad4 systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Aug 27 22:21:28 quad4 sudo[1871]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 27 22:21:28 quad4 sudo[1871]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 27 22:21:28 quad4 sudo[1871]: pam_unix(sudo:session): session closed for user root Aug 27 22:21:30 quad4 mpd[1882]: 2026-08-27T22:21:30 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 27 22:21:30 quad4 systemd[1]: Started mpd.service - Music Player Daemon. Aug 27 22:21:30 quad4 sudo[1858]: pam_unix(sudo:session): session closed for user root Aug 27 22:21:30 quad4 sudo[1902]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-27 22:20' Aug 27 22:21:30 quad4 sudo[1902]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 22:21:31 quad4 systemd[1]: sable.service: Scheduled restart job, restart counter is at 17. Aug 27 22:21:31 quad4 systemd[1]: Stopped sable.service - Sable Display (Quadify successor) - OLED + rotary + spectrum. 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"