-- Logs begin at Wed 2025-06-11 19:45:09 CST, end at Wed 2025-06-11 20:00:58 CST. -- Jun 11 19:59:03 volumio wpa_supplicant[1159]: wlan0: CTRL-EVENT-BEACON-LOSS Jun 11 19:59:07 volumio volumio[4056]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 11 19:59:07 volumio volumio[4056]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 11 19:59:07 volumio volumio[4056]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 11 19:59:08 volumio volumio[4056]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 11 19:59:08 volumio volumio[4056]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jun 11 19:59:08 volumio volumio[4056]: info: CoreCommandRouter::volumioGetState Jun 11 19:59:08 volumio volumio[4056]: info: CorePlayQueue::getTrack 0 Jun 11 19:59:09 volumio wpa_supplicant[1159]: wlan0: CTRL-EVENT-BEACON-LOSS Jun 11 19:59:13 volumio volumio[4056]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 19:59:13 volumio volumio[4056]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 19:59:13 volumio volumio[4056]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jun 11 19:59:13 volumio volumio[4056]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 11 19:59:13 volumio volumio[4056]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jun 11 19:59:13 volumio volumio[4056]: info: CoreCommandRouter::volumioGetBrowseSources Jun 11 19:59:13 volumio volumio[4056]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 11 19:59:14 volumio volumio[4056]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jun 11 19:59:14 volumio volumio[4056]: info: Completed starting MyVolumio Plugin Jun 11 19:59:14 volumio volumio[4056]: [Metrics] CommandRouter: 49s 402.36ms Jun 11 19:59:14 volumio volumio[4056]: info: CoreCommandRouter::volumiosetStartupVolume Jun 11 19:59:14 volumio volumio[4056]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 19:59:14 volumio volumio[4056]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 11 19:59:14 volumio volumio[4056]: info: CoreCommandRouter::Close All Modals sent Jun 11 19:59:14 volumio volumio[4056]: info: CoreCommandRouter::Close All Modals sent Jun 11 19:59:14 volumio volumio[4056]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Jun 11 19:59:14 volumio volumio[4056]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card Jun 11 19:59:14 volumio volumio[4056]: aplay: main:828: audio open error: No such device Jun 11 19:59:15 volumio volumio[4056]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jun 11 19:59:15 volumio volumio[4056]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 11 19:59:15 volumio volumio[4056]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Jun 11 19:59:15 volumio volumio[4056]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jun 11 19:59:21 volumio volumio[4056]: info: BOOT COMPLETED Jun 11 19:59:21 volumio kernel: usb 3-1.1: new full-speed USB device number 5 using ehci-pci Jun 11 19:59:21 volumio kernel: usb 3-1.1: New USB device found, idVendor=2d99, idProduct=a057, bcdDevice= 1.00 Jun 11 19:59:21 volumio kernel: usb 3-1.1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Jun 11 19:59:21 volumio kernel: usb 3-1.1: Product: EDIFIER N300 Jun 11 19:59:21 volumio kernel: usb 3-1.1: Manufacturer: bestechnic Jun 11 19:59:21 volumio kernel: usb 3-1.1: SerialNumber: 20160406.1 Jun 11 19:59:21 volumio kernel: input: bestechnic EDIFIER N300 as /devices/pci0000:00/0000:00:12.0/usb3/3-1/3-1.1/3-1.1:1.2/0003:2D99:A057.0002/input/input8 Jun 11 19:59:21 volumio kernel: hid-generic 0003:2D99:A057.0002: input,hidraw0: USB HID v1.11 Device [bestechnic EDIFIER N300] on usb-0000:00:12.0-1.1/input2 Jun 11 19:59:21 volumio volumio[4056]: info: Jun 11 19:59:21 volumio volumio[4056]: ---------------------------- USB Audio Device Attached Jun 11 19:59:21 volumio volumio[4056]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Jun 11 19:59:22 volumio volumio[4056]: info: CoreCommandRouter::Close All Modals sent Jun 11 19:59:22 volumio volumio[4056]: info: Preparing to save Alsa Options, stopping services first Jun 11 19:59:22 volumio volumio[4056]: info: CoreCommandRouter::volumioGetState Jun 11 19:59:22 volumio volumio[4056]: info: CorePlayQueue::getTrack 0 Jun 11 19:59:22 volumio volumio[4056]: info: CoreCommandRouter::volumioPause Jun 11 19:59:22 volumio volumio[4056]: info: CoreStateMachine::pause Jun 11 19:59:22 volumio volumio[4056]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"EDIFIER N300","alsacard":"N300"},"i2s":false} Jun 11 19:59:22 volumio volumio[4056]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 11 19:59:22 volumio systemd-udevd[4426]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jun 11 19:59:22 volumio volumio[4056]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 11 19:59:22 volumio volumio[4056]: info: Setting mixer PCM for card EDIFIER N300 Jun 11 19:59:22 volumio volumio[4056]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 11 19:59:22 volumio volumio[4056]: info: Updating Volume Controller Parameters: Device: 5 Name: EDIFIER N300 Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jun 11 19:59:22 volumio volumio[4056]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jun 11 19:59:22 volumio volumio[4056]: info: Disabling external Volume Control Jun 11 19:59:22 volumio volumio[4056]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 11 19:59:22 volumio volumio[4056]: info: Preparing to generate the ALSA configuration file Jun 11 19:59:22 volumio volumio[4056]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 11 19:59:22 volumio volumio[4056]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 11 19:59:22 volumio volumio[4056]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 11 19:59:22 volumio volumio[4056]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 19:59:22 volumio volumio[4056]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 19:59:22 volumio volumio[4056]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 19:59:22 volumio volumio[4056]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 19:59:22 volumio volumio[4056]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 19:59:22 volumio volumio[4056]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 19:59:22 volumio volumio[4056]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 11 19:59:22 volumio volumio[4056]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 11 19:59:22 volumio volumio[4056]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 11 19:59:22 volumio volumio[4056]: info: Asound.conf file unchanged, so no further update is needed Jun 11 19:59:22 volumio volumio[4056]: info: Output device has changed, restarting MPD Jun 11 19:59:22 volumio sudo[4449]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 11 19:59:22 volumio sudo[4449]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 19:59:22 volumio volumio[4056]: info: Output device has changed, restarting Shairport Sync Jun 11 19:59:22 volumio sudo[4449]: pam_unix(sudo:session): session closed for user root Jun 11 19:59:22 volumio volumio[4056]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 19:59:22 volumio volumio[4056]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 11 19:59:22 volumio sudo[4451]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 11 19:59:22 volumio sudo[4451]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 19:59:22 volumio systemd[1]: Stopping Music Player Daemon... Jun 11 19:59:22 volumio volumio[4056]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 11 19:59:22 volumio systemd[1]: mpd.service: Succeeded. Jun 11 19:59:22 volumio systemd[1]: Stopped Music Player Daemon. Jun 11 19:59:22 volumio volumio[4056]: info: MPD Permissions set Jun 11 19:59:22 volumio volumio[4056]: info: VolumeController:: Volume=87 Mute =false Jun 11 19:59:22 volumio systemd[1]: Starting Music Player Daemon... Jun 11 19:59:22 volumio volumio[4056]: info: CoreStateMachine::pushState Jun 11 19:59:22 volumio volumio[4056]: info: CorePlayQueue::getTrack 0 Jun 11 19:59:22 volumio volumio[4056]: info: CoreCommandRouter::volumioPushState Jun 11 19:59:22 volumio volumio[4056]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 19:59:22 volumio volumio[4056]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 19:59:22 volumio volumio[4056]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 11 19:59:22 volumio volumio[4056]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jun 11 19:59:22 volumio sudo[4457]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 11 19:59:22 volumio sudo[4457]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 19:59:22 volumio volumio[4056]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 11 19:59:22 volumio volumio[4056]: info: Starting Shairport Sync Jun 11 19:59:22 volumio sudo[4457]: pam_unix(sudo:session): session closed for user root Jun 11 19:59:22 volumio volumio[4056]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Jun 11 19:59:22 volumio sudo[4467]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 11 19:59:22 volumio sudo[4467]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 11 19:59:22 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 11 19:59:22 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 11 19:59:22 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 11 19:59:22 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 11 19:59:22 volumio sudo[4467]: pam_unix(sudo:session): session closed for user root Jun 11 19:59:22 volumio volumio[4056]: info: Shairport-Sync Started Jun 11 19:59:23 volumio mpd[4465]: Jun 11 19:59 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 11 19:59:23 volumio systemd[1]: Started Music Player Daemon. Jun 11 19:59:23 volumio sudo[4451]: pam_unix(sudo:session): session closed for user root Jun 11 19:59:23 volumio volumio[4056]: error: updateQueue error: null Jun 11 19:59:25 volumio volumio-remote-updater[756]: No test mode Jun 11 19:59:25 volumio volumio-remote-updater[756]: No alpha test mode Jun 11 19:59:25 volumio volumio[4056]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jun 11 19:59:26 volumio volumio[4056]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jun 11 19:59:26 volumio volumio[4056]: info: CoreStateMachine::getcurrentVolume Jun 11 19:59:26 volumio volumio[4056]: info: CoreCommandRouter::volumioRetrievevolume Jun 11 19:59:26 volumio volumio[4056]: info: VolumeController:: Volume=87 Mute =false Jun 11 19:59:26 volumio volumio[4056]: info: CoreStateMachine::pushState Jun 11 19:59:26 volumio volumio[4056]: info: CorePlayQueue::getTrack 0 Jun 11 19:59:26 volumio volumio[4056]: info: CoreCommandRouter::volumioPushState Jun 11 19:59:26 volumio volumio[4056]: info: CoreStateMachine::updateTrackBlock Jun 11 19:59:26 volumio volumio[4056]: info: CorePlayQueue::getTrackBlock Jun 11 19:59:26 volumio volumio[4056]: info: CoreCommandRouter::volumioRetrievevolume Jun 11 19:59:26 volumio volumio[4056]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jun 11 19:59:26 volumio volumio[4056]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jun 11 19:59:26 volumio volumio[4056]: info: VolumeController:: Volume=87 Mute =false Jun 11 19:59:26 volumio volumio[4056]: info: CoreStateMachine::pushState Jun 11 19:59:26 volumio volumio[4056]: info: CorePlayQueue::getTrack 0 Jun 11 19:59:26 volumio volumio[4056]: info: CoreCommandRouter::volumioPushState Jun 11 19:59:49 volumio systemd[1]: Starting Cleanup of Temporary Directories... Jun 11 19:59:49 volumio systemd[1]: systemd-tmpfiles-clean.service: Succeeded. Jun 11 19:59:49 volumio systemd[1]: Started Cleanup of Temporary Directories. Jun 11 20:00:57 volumio volumio[4056]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 11 20:00:57 volumio volumio[4056]: Error: connect ETIMEDOUT 199.59.149.205:80 Jun 11 20:00:57 volumio volumio[4056]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Jun 11 20:00:57 volumio volumio[4056]: errno: -110, Jun 11 20:00:57 volumio volumio[4056]: code: 'ETIMEDOUT', Jun 11 20:00:57 volumio volumio[4056]: syscall: 'connect', Jun 11 20:00:57 volumio volumio[4056]: address: '199.59.149.205', Jun 11 20:00:57 volumio volumio[4056]: port: 80 Jun 11 20:00:57 volumio volumio[4056]: } Jun 11 20:00:57 volumio volumio[4056]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 11 20:00:58 volumio sudo[4687]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-11 19:59 Jun 11 20:00:58 volumio sudo[4687]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:50:12 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="39b0f8c200b2dcadf117e189b4b3632d"