-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Fri 2026-04-10 16:35:06 CEST. -- Apr 10 16:35:04 ferrum-broen-jj kernel: usb 1-1.1: USB disconnect, device number 3 Apr 10 16:35:04 ferrum-broen-jj volumio[1221]: info: [HEM:SDDC] Device detached Apr 10 16:35:04 ferrum-broen-jj volumio[1221]: info: [HEM:SDDC] Closing device on detach Apr 10 16:35:04 ferrum-broen-jj volumio[1221]: info: [HEM:SDDC] Device state: DISCONNECTED Apr 10 16:35:04 ferrum-broen-jj volumio[1221]: info: Apr 10 16:35:04 ferrum-broen-jj volumio[1221]: ---------------------------- USB Audio Device Detached Apr 10 16:35:04 ferrum-broen-jj volumio[1221]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Apr 10 16:35:04 ferrum-broen-jj volumio[1221]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 10 16:35:04 ferrum-broen-jj volumio[1221]: aplay: device_list:272: no soundcards found... Apr 10 16:35:04 ferrum-broen-jj volumio[1221]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Apr 10 16:35:04 ferrum-broen-jj systemd[1]: Stopped target Sound Card. Apr 10 16:35:05 ferrum-broen-jj kernel: usb 1-1: reset high-speed USB device number 2 using dwc2 Apr 10 16:35:05 ferrum-broen-jj kernel: usb 1-1.1: new high-speed USB device number 4 using dwc2 Apr 10 16:35:05 ferrum-broen-jj kernel: usb 1-1.1: New USB device found, idVendor=3336, idProduct=0039, bcdDevice= 0.00 Apr 10 16:35:05 ferrum-broen-jj kernel: usb 1-1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=15 Apr 10 16:35:05 ferrum-broen-jj kernel: usb 1-1.1: Product: BROEN synchronous outputs Apr 10 16:35:05 ferrum-broen-jj kernel: usb 1-1.1: Manufacturer: HEM Apr 10 16:35:05 ferrum-broen-jj kernel: usb 1-1.1: SerialNumber: Default SN Apr 10 16:35:05 ferrum-broen-jj volumio[1221]: info: [HEM:SDDC] Device attached Apr 10 16:35:05 ferrum-broen-jj volumio[1221]: info: [HEM:SDDC] Device state: CONNECTED Apr 10 16:35:05 ferrum-broen-jj volumio[1221]: error: [HEM:SDDC] Failed to get HW version string: Error: LIBUSB_TRANSFER_STALL Apr 10 16:35:05 ferrum-broen-jj volumio[1221]: info: [HEM:SDDC] Info updated: {"firmwareVersion":"0.0.0-local","hardwareVersion":null,"bootloaderVersion":null,"connected":true} Apr 10 16:35:05 ferrum-broen-jj volumio[1221]: info: [HEM:SDDC] Device state: READY Apr 10 16:35:05 ferrum-broen-jj volumio[1221]: info: [HEM] SDDC attached, checking for updates... Apr 10 16:35:05 ferrum-broen-jj volumio[1221]: info: [HEM:FirmwareManager] Starting check for all targets... (force=false) Apr 10 16:35:05 ferrum-broen-jj systemd[1]: Reached target Sound Card. Apr 10 16:35:05 ferrum-broen-jj volumio[1221]: info: [HEM:FirmwareManager] Target SDDC Bootloader - Current: null, Available: 0.15.1 Apr 10 16:35:05 ferrum-broen-jj volumio[1221]: info: [HEM:FirmwareManager] Updating SDDC Bootloader... (forced: false) Apr 10 16:35:05 ferrum-broen-jj volumio[1221]: info: [HEM:SDDC] Flashing firmware: /volumio/app/plugins/system_controller/ferrumbroencontrol/mcu/062-SERCE-BOOT-UPDATER-SERCE-DDC_v.0.15.1+b.361-git.686adcb9579c76f61a92c9bf45b0539ce80fb58b-MinSizeRel.hff Apr 10 16:35:05 ferrum-broen-jj volumio[1221]: error: [HEM:FirmwareManager:SDDC:Boot] SddcTarget (SDDC Bootloader): Update failed: Error: Can't close device with a pending request Apr 10 16:35:05 ferrum-broen-jj volumio[1221]: error: [HEM:FirmwareManager] Error during update process: Error: Can't close device with a pending request Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: info: Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: ---------------------------- USB Audio Device Attached Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: No protocol specified Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: xcb_connection_has_error() returned true Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: info: CoreCommandRouter::Close All Modals sent Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: info: Preparing to save Alsa Options, stopping services first Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: info: CoreCommandRouter::volumioGetState Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: info: CorePlayQueue::getTrack 0 Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: info: CoreCommandRouter::volumioStop Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: info: CoreStateMachine::stop Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"BROEN synchronous outputs","alsacard":"outputs"},"i2s":false} Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: No protocol specified Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: xcb_connection_has_error() returned true Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: No protocol specified Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: xcb_connection_has_error() returned true Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: No protocol specified Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: xcb_connection_has_error() returned true Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: info: Device 5 does not have any Mixer Control Available Apr 10 16:35:06 ferrum-broen-jj vtcs[1856]: [2026-04-10 16:35:06.092] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Apr 10 16:35:06 ferrum-broen-jj vtcs[1856]: [2026-04-10 16:35:06.092] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Apr 10 16:35:06 ferrum-broen-jj sudo[13186]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 10 16:35:06 ferrum-broen-jj sudo[13186]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 10 16:35:06 ferrum-broen-jj systemd[1]: Stopping Volumio Tidal Connect Service... Apr 10 16:35:06 ferrum-broen-jj systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Apr 10 16:35:06 ferrum-broen-jj systemd[1]: vtcs.service: Succeeded. Apr 10 16:35:06 ferrum-broen-jj systemd[1]: Stopped Volumio Tidal Connect Service. Apr 10 16:35:06 ferrum-broen-jj sudo[13186]: pam_unix(sudo:session): session closed for user root Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: No protocol specified Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: xcb_connection_has_error() returned true Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: info: Updating Volume Controller Parameters: Device: 5 Name: BROEN synchronous outputs Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: info: Disabling external Volume Control Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: info: CoreStateMachine::pushState Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: info: CorePlayQueue::getTrack 0 Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: info: CoreCommandRouter::volumioPushState Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: info: Preparing to generate the ALSA configuration file Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 10 16:35:06 ferrum-broen-jj sudo[13192]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 10 16:35:06 ferrum-broen-jj sudo[13192]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 10 16:35:06 ferrum-broen-jj sudo[13192]: pam_unix(sudo:session): session closed for user root Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: No protocol specified Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: xcb_connection_has_error() returned true Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: No protocol specified Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: xcb_connection_has_error() returned true Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: info: CoreCommandRouter::executeOnPlugin: ferrumbroencontrol , getAdditionalUiSection Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: info: [HEM] Getting UI Config for section: audio Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: info: Asound.conf file unchanged, so no further update is needed Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: info: Output device has changed, restarting MPD Apr 10 16:35:06 ferrum-broen-jj sudo[13200]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 10 16:35:06 ferrum-broen-jj sudo[13200]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 10 16:35:06 ferrum-broen-jj sudo[13200]: pam_unix(sudo:session): session closed for user root Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: info: Output device has changed, restarting Shairport Sync Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 10 16:35:06 ferrum-broen-jj sudo[13203]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 10 16:35:06 ferrum-broen-jj sudo[13203]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 10 16:35:06 ferrum-broen-jj systemd[1]: Stopping Music Player Daemon... Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: No protocol specified Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: xcb_connection_has_error() returned true Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: No protocol specified Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: xcb_connection_has_error() returned true Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 10 16:35:06 ferrum-broen-jj sudo[13209]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 10 16:35:06 ferrum-broen-jj sudo[13209]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 10 16:35:06 ferrum-broen-jj sudo[13209]: pam_unix(sudo:session): session closed for user root Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: info: MPD Permissions set Apr 10 16:35:06 ferrum-broen-jj sudo[13212]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 10 16:35:06 ferrum-broen-jj sudo[13212]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 10 16:35:06 ferrum-broen-jj sudo[13212]: pam_unix(sudo:session): session closed for user root Apr 10 16:35:06 ferrum-broen-jj systemd[1]: mpd.service: Succeeded. Apr 10 16:35:06 ferrum-broen-jj systemd[1]: Stopped Music Player Daemon. Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: info: Starting Shairport Sync Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: TypeError: callback is not a function Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: at /volumio/app/plugins/system_controller/ferrumbroencontrol/node_modules/jsonfile/index.js:46:5 Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: at /volumio/app/plugins/system_controller/ferrumbroencontrol/node_modules/graceful-fs/graceful-fs.js:123:16 Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: at FSReqCallback.readFileAfterClose [as oncomplete] (internal/fs/read_file_context.js:63:3) Apr 10 16:35:06 ferrum-broen-jj volumio[1221]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 10 16:35:06 ferrum-broen-jj sudo[13222]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 10 16:35:06 ferrum-broen-jj systemd[1]: Starting Music Player Daemon... Apr 10 16:35:06 ferrum-broen-jj sudo[13222]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 10 16:35:06 ferrum-broen-jj systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 10 16:35:06 ferrum-broen-jj systemd[1]: shairport-sync.service: Succeeded. Apr 10 16:35:06 ferrum-broen-jj systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 10 16:35:06 ferrum-broen-jj systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 10 16:35:06 ferrum-broen-jj sudo[13222]: pam_unix(sudo:session): session closed for user root Apr 10 16:35:06 ferrum-broen-jj sudo[13220]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 10 16:35:06 ferrum-broen-jj sudo[13220]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 10 16:35:06 ferrum-broen-jj sudo[13220]: pam_unix(sudo:session): session closed for user root Apr 10 16:35:06 ferrum-broen-jj sudo[13238]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-04-10 16:34 Apr 10 16:35:06 ferrum-broen-jj sudo[13238]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="8a6f332409191c528e8bc93aa85193dc61811be6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="ferrumbroen" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 28 Mar 2026 12:32:58 AM CET" VOLUMIO_VERSION="3.922" VOLUMIO_HARDWARE="cm5" VOLUMIO_DEVICENAME="CM5" VOLUMIO_HASH="3d20dcd3a70d70c0c12359edb232763b"