-- Logs begin at Tue 2025-06-03 14:57:13 UTC, end at Tue 2025-06-03 15:06:07 UTC. --
Jun 03 15:05:12 volumio volumio[2115]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object]
Jun 03 15:05:12 volumio volumio[2115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions
Jun 03 15:05:12 volumio volumio[2115]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Jun 03 15:05:12 volumio volumio[2115]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Jun 03 15:05:12 volumio volumio[2115]: info: Found match in Cards Database: setting mixer DAC1 for card Headphone Jack/ Speaker
Jun 03 15:05:12 volumio volumio[2115]: info: CoreCommandRouter::volumioUpdateVolumeSettings
Jun 03 15:05:12 volumio volumio[2115]: info: Updating Volume Controller Parameters: Device: 0 Name: Headphone Jack/ Speaker Mixer: DAC1 Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1
Jun 03 15:05:12 volumio volumio[2115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume
Jun 03 15:05:12 volumio volumio[2115]: info: Disabling external Volume Control
Jun 03 15:05:12 volumio volumio[2115]: info: CoreCommandRouter::getUIConfigOnPlugin
Jun 03 15:05:12 volumio volumio[2115]: info: Preparing to generate the ALSA configuration file
Jun 03 15:05:12 volumio volumio[2115]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions
Jun 03 15:05:12 volumio volumio[2115]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 03 15:05:12 volumio volumio[2115]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Jun 03 15:05:12 volumio volumio[2115]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jun 03 15:05:12 volumio volumio[2115]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jun 03 15:05:12 volumio volumio[2115]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jun 03 15:05:12 volumio volumio[2115]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jun 03 15:05:12 volumio volumio[2115]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jun 03 15:05:12 volumio volumio[2115]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jun 03 15:05:12 volumio volumio[2115]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode
Jun 03 15:05:12 volumio volumio[2115]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus
Jun 03 15:05:12 volumio volumio[2115]: info: Asound.conf file written
Jun 03 15:05:12 volumio sudo[2349]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf
Jun 03 15:05:12 volumio sudo[2349]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 03 15:05:12 volumio sudo[2349]: pam_unix(sudo:session): session closed for user root
Jun 03 15:05:12 volumio volumio[2115]: info: Output device has changed, restarting MPD
Jun 03 15:05:12 volumio sudo[2355]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Jun 03 15:05:12 volumio sudo[2355]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 03 15:05:12 volumio volumio[2115]: info: Output device has changed, restarting Shairport Sync
Jun 03 15:05:12 volumio volumio[2115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 03 15:05:12 volumio volumio[2115]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 03 15:05:12 volumio sudo[2355]: pam_unix(sudo:session): session closed for user root
Jun 03 15:05:12 volumio sudo[2358]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Jun 03 15:05:12 volumio sudo[2358]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 03 15:05:12 volumio systemd[1]: Stopping Music Player Daemon...
Jun 03 15:05:12 volumio volumio[2115]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Jun 03 15:05:12 volumio volumio[2115]: info: Output device has changed, restarting MPD
Jun 03 15:05:12 volumio sudo[2364]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Jun 03 15:05:12 volumio sudo[2364]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 03 15:05:12 volumio sudo[2364]: pam_unix(sudo:session): session closed for user root
Jun 03 15:05:12 volumio volumio[2115]: info: Output device has changed, restarting Shairport Sync
Jun 03 15:05:12 volumio volumio[2115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 03 15:05:12 volumio volumio[2115]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 03 15:05:12 volumio sudo[2367]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Jun 03 15:05:12 volumio sudo[2367]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 03 15:05:12 volumio volumio[2115]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Jun 03 15:05:12 volumio volumio[2115]: info: MPD Permissions set
Jun 03 15:05:12 volumio volumio[2115]: info: MPD Permissions set
Jun 03 15:05:12 volumio volumio[2115]: info: VolumeController:: Volume=98 Mute =false
Jun 03 15:05:12 volumio volumio[2115]: info: CoreStateMachine::pushState
Jun 03 15:05:12 volumio volumio[2115]: info: CorePlayQueue::getTrack 0
Jun 03 15:05:12 volumio volumio[2115]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jun 03 15:05:12 volumio volumio[2115]: info: CoreCommandRouter::volumioPushState
Jun 03 15:05:12 volumio volumio[2115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 03 15:05:12 volumio volumio[2115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 03 15:05:12 volumio volumio[2115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 03 15:05:12 volumio volumio[2115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 03 15:05:12 volumio volumio[2115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 03 15:05:12 volumio volumio[2115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 03 15:05:12 volumio volumio[2115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 03 15:05:12 volumio volumio[2115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 03 15:05:12 volumio systemd[1]: mpd.service: Succeeded.
Jun 03 15:05:12 volumio systemd[1]: Stopped Music Player Daemon.
Jun 03 15:05:12 volumio systemd[1]: Starting Music Player Daemon...
Jun 03 15:05:12 volumio volumio[2115]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 03 15:05:12 volumio volumio[2115]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber
Jun 03 15:05:12 volumio volumio[2115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 03 15:05:12 volumio volumio[2115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 03 15:05:12 volumio volumio[2115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 03 15:05:12 volumio volumio[2115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 03 15:05:12 volumio volumio[2115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 03 15:05:12 volumio volumio[2115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 03 15:05:12 volumio volumio[2115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 03 15:05:12 volumio volumio[2115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 03 15:05:12 volumio volumio[2115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 03 15:05:12 volumio volumio[2115]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Jun 03 15:05:12 volumio volumio[2115]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber
Jun 03 15:05:12 volumio volumio[2115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Jun 03 15:05:12 volumio volumio[2115]: info: Starting Shairport Sync
Jun 03 15:05:12 volumio volumio[2115]: info: Starting Shairport Sync
Jun 03 15:05:12 volumio sudo[2387]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Jun 03 15:05:12 volumio sudo[2387]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 03 15:05:13 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Jun 03 15:05:13 volumio sudo[2390]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Jun 03 15:05:13 volumio sudo[2390]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 03 15:05:13 volumio systemd[1]: shairport-sync.service: Succeeded.
Jun 03 15:05:13 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Jun 03 15:05:13 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Jun 03 15:05:13 volumio sudo[2387]: pam_unix(sudo:session): session closed for user root
Jun 03 15:05:13 volumio volumio[2115]: info: Shairport-Sync Started
Jun 03 15:05:13 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Jun 03 15:05:13 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM
Jun 03 15:05:13 volumio systemd[1]: shairport-sync.service: Succeeded.
Jun 03 15:05:13 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Jun 03 15:05:13 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Jun 03 15:05:13 volumio sudo[2390]: pam_unix(sudo:session): session closed for user root
Jun 03 15:05:13 volumio volumio[2115]: info: Shairport-Sync Started
Jun 03 15:05:13 volumio mpd[2391]: Jun 03 15:05 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Jun 03 15:05:13 volumio mpd[2391]: Jun 03 15:05 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured
Jun 03 15:05:13 volumio mpd[2391]: Jun 03 15:05 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured
Jun 03 15:05:13 volumio systemd[1]: Started Music Player Daemon.
Jun 03 15:05:13 volumio sudo[2367]: pam_unix(sudo:session): session closed for user root
Jun 03 15:05:13 volumio sudo[2358]: pam_unix(sudo:session): session closed for user root
Jun 03 15:05:13 volumio volumio[2115]: error: MPD error: The expression evaluated to a falsy value:
Jun 03 15:05:13 volumio volumio[2115]: assert.ok(self.idling)
Jun 03 15:05:13 volumio volumio[2115]: error: updateQueue error: null
Jun 03 15:05:15 volumio volumio-remote-updater[610]: No test mode
Jun 03 15:05:15 volumio volumio-remote-updater[610]: No alpha test mode
Jun 03 15:05:15 volumio volumio[2115]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Jun 03 15:05:16 volumio volumio[2115]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
\n\n- Various Fixes for QOBUZ Connect
\n
\nNEW ADDITIONS
\n\n- Qobuz Connect Ready
\n
\n","title":"Update v3.812","updateavailable":true}
Jun 03 15:05:16 volumio volumio[2115]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Jun 03 15:05:16 volumio volumio[2115]: info: CoreCommandRouter::volumioRetrieveVolumeLevels
Jun 03 15:05:16 volumio volumio[2115]: info: CoreStateMachine::getcurrentVolume
Jun 03 15:05:16 volumio volumio[2115]: info: CoreCommandRouter::volumioRetrievevolume
Jun 03 15:05:16 volumio volumio[2115]: info: VolumeController:: Volume=98 Mute =false
Jun 03 15:05:16 volumio volumio[2115]: info: CoreStateMachine::pushState
Jun 03 15:05:16 volumio volumio[2115]: info: CorePlayQueue::getTrack 0
Jun 03 15:05:16 volumio volumio[2115]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jun 03 15:05:16 volumio volumio[2115]: info: CoreCommandRouter::volumioPushState
Jun 03 15:05:16 volumio volumio[2115]: info: CoreStateMachine::updateTrackBlock
Jun 03 15:05:16 volumio volumio[2115]: info: CorePlayQueue::getTrackBlock
Jun 03 15:05:16 volumio volumio[2115]: info: CoreCommandRouter::volumioRetrievevolume
Jun 03 15:05:16 volumio volumio[2115]: info: VolumeController:: Volume=98 Mute =false
Jun 03 15:05:16 volumio volumio[2115]: info: CoreStateMachine::pushState
Jun 03 15:05:16 volumio volumio[2115]: info: CorePlayQueue::getTrack 0
Jun 03 15:05:16 volumio volumio[2115]: info: CoreCommandRouter::volumioPushState
Jun 03 15:05:18 volumio volumio[2115]: error: updateQueue error: null
Jun 03 15:05:19 volumio kernel: usb 1-2.1.2: USB disconnect, device number 10
Jun 03 15:05:19 volumio acpid[989]: input device has been disconnected, fd 9
Jun 03 15:05:19 volumio volumio[2115]: info:
Jun 03 15:05:19 volumio volumio[2115]: ---------------------------- USB Audio Device Detached
Jun 03 15:05:19 volumio volumio[2115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach
Jun 03 15:05:19 volumio volumio[2115]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Jun 03 15:05:19 volumio volumio[2115]: info: No valid Plugin REST Endpoint
Jun 03 15:05:19 volumio kernel: usb 1-2.1.2: new full-speed USB device number 11 using xhci_hcd
Jun 03 15:05:20 volumio kernel: usb 1-2.1.2: New USB device found, idVendor=4c4a, idProduct=4155, bcdDevice= 1.00
Jun 03 15:05:20 volumio kernel: usb 1-2.1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Jun 03 15:05:20 volumio kernel: usb 1-2.1.2: Product: USB Composite Device
Jun 03 15:05:20 volumio kernel: usb 1-2.1.2: Manufacturer: Jieli Technology
Jun 03 15:05:20 volumio kernel: usb 1-2.1.2: SerialNumber: 4250303639343719
Jun 03 15:05:20 volumio kernel: usb-storage 1-2.1.2:1.0: USB Mass Storage device detected
Jun 03 15:05:20 volumio kernel: scsi host1: usb-storage 1-2.1.2:1.0
Jun 03 15:05:20 volumio kernel: input: Jieli Technology USB Composite Device as /devices/pci0000:00/0000:00:14.0/usb1/1-2/1-2.1/1-2.1.2/1-2.1.2:1.4/0003:4C4A:4155.0008/input/input20
Jun 03 15:05:20 volumio kernel: hid-generic 0003:4C4A:4155.0008: input,hidraw4: USB HID v1.00 Device [Jieli Technology USB Composite Device] on usb-0000:00:14.0-2.1.2/input4
Jun 03 15:05:20 volumio volumio[2115]: info: No valid Plugin REST Endpoint
Jun 03 15:05:20 volumio volumio[2115]: info:
Jun 03 15:05:20 volumio volumio[2115]: ---------------------------- USB Audio Device Attached
Jun 03 15:05:20 volumio volumio[2115]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach
Jun 03 15:05:20 volumio systemd-udevd[2406]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1.
Jun 03 15:05:21 volumio kernel: scsi 1:0:0:0: Direct-Access BR23 UDISK 1.00 PQ: 0 ANSI: 2
Jun 03 15:05:21 volumio kernel: sd 1:0:0:0: Attached scsi generic sg2 type 0
Jun 03 15:05:21 volumio kernel: sd 1:0:0:0: [sdb] Attached SCSI removable disk
Jun 03 15:05:50 volumio volumio[2115]: info: CoreCommandRouter::volumioPause
Jun 03 15:05:50 volumio volumio[2115]: info: CoreStateMachine::pause
Jun 03 15:05:50 volumio volumio[2115]: info: CoreStateMachine::stPlaybackTimer
Jun 03 15:05:50 volumio volumio[2115]: info: CoreStateMachine::servicePause
Jun 03 15:05:50 volumio volumio[2115]: info: CorePlayQueue::getTrack 0
Jun 03 15:05:50 volumio volumio[2115]: info: CoreCommandRouter::servicePause
Jun 03 15:05:50 volumio volumio[2115]: info: ControllerMpd::pause
Jun 03 15:05:50 volumio volumio[2115]: verbose: ControllerMpd::sendMpdCommand pause
Jun 03 15:05:50 volumio volumio[2115]: info: sendMpdCommand pause took 1 milliseconds
Jun 03 15:05:52 volumio volumio[2115]: info: CoreCommandRouter::volumioPause
Jun 03 15:05:52 volumio volumio[2115]: info: CoreStateMachine::pause
Jun 03 15:05:57 volumio volumio[2115]: info: CoreCommandRouter::volumioGetState
Jun 03 15:05:57 volumio volumio[2115]: info: CorePlayQueue::getTrack 0
Jun 03 15:05:59 volumio volumio[2115]: info: CoreCommandRouter::volumioPlay
Jun 03 15:05:59 volumio volumio[2115]: info: CoreStateMachine::play index undefined
Jun 03 15:05:59 volumio volumio[2115]: info: CoreStateMachine::setConsumeUpdateService undefined
Jun 03 15:05:59 volumio volumio[2115]: info: CorePlayQueue::getTrack 0
Jun 03 15:05:59 volumio volumio[2115]: info: CoreStateMachine::startPlaybackTimer
Jun 03 15:05:59 volumio volumio[2115]: info: CorePlayQueue::getTrack 0
Jun 03 15:05:59 volumio volumio[2115]: info: ControllerMpd::resume
Jun 03 15:05:59 volumio volumio[2115]: verbose: ControllerMpd::sendMpdCommand play
Jun 03 15:05:59 volumio volumio[2115]: info: sendMpdCommand play took 1 milliseconds
Jun 03 15:06:06 volumio volumio[2115]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jun 03 15:06:06 volumio volumio[2115]: Error: connect ETIMEDOUT 202.160.128.210:443
Jun 03 15:06:06 volumio volumio[2115]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) {
Jun 03 15:06:06 volumio volumio[2115]: errno: -110,
Jun 03 15:06:06 volumio volumio[2115]: code: 'ETIMEDOUT',
Jun 03 15:06:06 volumio volumio[2115]: syscall: 'connect',
Jun 03 15:06:06 volumio volumio[2115]: address: '202.160.128.210',
Jun 03 15:06:06 volumio volumio[2115]: port: 443
Jun 03 15:06:06 volumio volumio[2115]: }
Jun 03 15:06:06 volumio volumio[2115]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jun 03 15:06:07 volumio sudo[2445]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-03 15:05
Jun 03 15:06:07 volumio sudo[2445]: pam_unix(sudo:session): session opened for user root by (uid=0)
PRETTY_NAME="Debian GNU/Linux 10 (buster)"
NAME="Debian GNU/Linux"
VERSION_ID="10"
VERSION="10 (buster)"
VERSION_CODENAME=buster
ID=debian
HOME_URL="https://www.debian.org/"
SUPPORT_URL="https://www.debian.org/support"
BUG_REPORT_URL="https://bugs.debian.org/"
VOLUMIO_BUILD_VERSION="1d63a568b7d5e4a317f16be7d0961cbb2d82e282"
VOLUMIO_FE_VERSION="c433755e569617abac52264d17ad61be303ef463"
VOLUMIO_FE3_VERSION="2419ddd8e895c5bdc456962163c9dc7c0f187aa7"
VOLUMIO_BE_VERSION="4f8978e0148ab5e1610548b80abf1b4b5e03ea0a"
VOLUMIO_ARCH="x64"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Tue 07 Feb 2023 05:04:19 PM CET"
VOLUMIO_VERSION="3.429"
VOLUMIO_HARDWARE="x86_amd64"
VOLUMIO_DEVICENAME="x86_64"
VOLUMIO_HASH="4c8dcdad83349056095d42fbad08221f"