-- Logs begin at Thu 2019-02-14 18:11:59 CST, end at Sat 2024-10-05 07:58:52 CST. -- Oct 05 07:57:10 volumio kernel: usb 1-1.4: new high-speed USB device number 3 using xhci_hcd Oct 05 07:57:10 volumio kernel: usb 1-1.4: New USB device found, idVendor=22e1, idProduct=f00c, bcdDevice= 1.01 Oct 05 07:57:10 volumio kernel: usb 1-1.4: New USB device strings: Mfr=2, Product=3, SerialNumber=1 Oct 05 07:57:10 volumio kernel: usb 1-1.4: Product: TempoTec M3 Oct 05 07:57:10 volumio kernel: usb 1-1.4: Manufacturer: TempoTec Oct 05 07:57:10 volumio kernel: usb 1-1.4: SerialNumber: TempoTec M3 Oct 05 07:57:10 volumio kernel: input: TempoTec TempoTec M3 as /devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.4/1-1.4:1.2/0003:22E1:F00C.0001/input/input0 Oct 05 07:57:10 volumio kernel: input: TempoTec TempoTec M3 Consumer Control as /devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.4/1-1.4:1.2/0003:22E1:F00C.0001/input/input1 Oct 05 07:57:10 volumio kernel: hid-generic 0003:22E1:F00C.0001: input,hiddev96,hidraw0: USB HID v1.10 Device [TempoTec TempoTec M3] on usb-0000:01:00.0-1.4/input2 Oct 05 07:57:10 volumio kernel: hid-generic 0003:22E1:F00C.0002: hiddev97,hidraw1: USB HID v1.00 Device [TempoTec TempoTec M3] on usb-0000:01:00.0-1.4/input3 Oct 05 07:57:11 volumio kernel: usb 1-1.4: 1:4 : unsupported format bits 0x100000000 Oct 05 07:57:11 volumio kernel: usbcore: registered new interface driver snd-usb-audio Oct 05 07:57:11 volumio systemd-udevd[2070]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Oct 05 07:57:11 volumio systemd-udevd[2066]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Oct 05 07:57:11 volumio systemd-udevd[2066]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 5' failed with exit code 99. Oct 05 07:57:11 volumio volumio[1045]: info: Oct 05 07:57:11 volumio volumio[1045]: ---------------------------- USB Audio Device Attached Oct 05 07:57:11 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Oct 05 07:57:11 volumio volumio[1045]: info: CoreCommandRouter::Close All Modals sent Oct 05 07:57:11 volumio volumio[1045]: info: Preparing to save Alsa Options, stopping services first Oct 05 07:57:11 volumio volumio[1045]: info: CoreCommandRouter::volumioGetState Oct 05 07:57:11 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 05 07:57:11 volumio volumio[1045]: info: CoreCommandRouter::volumioPause Oct 05 07:57:11 volumio volumio[1045]: info: CoreStateMachine::pause Oct 05 07:57:11 volumio volumio[1045]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"TempoTec M3","alsacard":"M3"},"i2s":false} Oct 05 07:57:11 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 05 07:57:11 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 05 07:57:11 volumio volumio[1045]: info: Device 5 does not have any Mixer Control Available Oct 05 07:57:11 volumio volumio[1045]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 05 07:57:11 volumio volumio[1045]: info: Updating Volume Controller Parameters: Device: 5 Name: TempoTec M3 Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 05 07:57:11 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 05 07:57:11 volumio volumio[1045]: info: Disabling external Volume Control Oct 05 07:57:11 volumio volumio[1045]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 05 07:57:11 volumio volumio[1045]: info: CoreStateMachine::pushState Oct 05 07:57:11 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 05 07:57:11 volumio volumio[1045]: info: CoreCommandRouter::volumioPushState Oct 05 07:57:11 volumio volumio[1045]: info: Preparing to generate the ALSA configuration file Oct 05 07:57:11 volumio volumio[1045]: info: Asound.conf file unchanged, so no further update is needed Oct 05 07:57:11 volumio volumio[1045]: info: Output device has changed, restarting MPD Oct 05 07:57:11 volumio volumio[1045]: info: Output device has changed, restarting Shairport Sync Oct 05 07:57:11 volumio sudo[2132]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 05 07:57:11 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 07:57:11 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 07:57:11 volumio sudo[2132]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 07:57:11 volumio sudo[2132]: pam_unix(sudo:session): session closed for user root Oct 05 07:57:11 volumio sudo[2135]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 05 07:57:11 volumio sudo[2135]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 07:57:11 volumio systemd[1]: Stopping Music Player Daemon... Oct 05 07:57:11 volumio volumio[1045]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 05 07:57:11 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 05 07:57:11 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 07:57:11 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 05 07:57:11 volumio systemd[1]: mpd.service: Succeeded. Oct 05 07:57:11 volumio systemd[1]: Stopped Music Player Daemon. Oct 05 07:57:11 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 07:57:11 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 07:57:11 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 07:57:11 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 07:57:11 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 07:57:11 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 07:57:11 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 07:57:11 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 05 07:57:11 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 05 07:57:11 volumio volumio[1045]: info: MPD Permissions set Oct 05 07:57:11 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 07:57:11 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 07:57:11 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 07:57:11 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 07:57:11 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 07:57:11 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 07:57:11 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 07:57:11 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 07:57:11 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 07:57:11 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 07:57:11 volumio volumio[1045]: info: Starting Shairport Sync Oct 05 07:57:11 volumio systemd[1]: Starting Music Player Daemon... Oct 05 07:57:11 volumio sudo[2150]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 05 07:57:11 volumio sudo[2150]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 07:57:11 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 05 07:57:11 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 05 07:57:11 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 05 07:57:11 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 05 07:57:11 volumio sudo[2150]: pam_unix(sudo:session): session closed for user root Oct 05 07:57:11 volumio volumio[1045]: info: Shairport-Sync Started Oct 05 07:57:11 volumio sudo[2143]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 05 07:57:11 volumio sudo[2143]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 07:57:11 volumio sudo[2143]: pam_unix(sudo:session): session closed for user root Oct 05 07:57:12 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Oct 05 07:57:12 volumio mpd[2155]: Oct 05 07:57 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 05 07:57:12 volumio systemd[1]: Started Music Player Daemon. Oct 05 07:57:12 volumio sudo[2135]: pam_unix(sudo:session): session closed for user root Oct 05 07:57:12 volumio volumio[1045]: error: updateQueue error: null Oct 05 07:57:15 volumio volumio[1045]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Oct 05 07:57:15 volumio volumio[1045]: info: CoreStateMachine::getcurrentVolume Oct 05 07:57:15 volumio volumio[1045]: info: CoreCommandRouter::volumioRetrievevolume Oct 05 07:57:15 volumio volumio[1045]: info: CoreStateMachine::pushState Oct 05 07:57:15 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 05 07:57:15 volumio volumio[1045]: info: CoreCommandRouter::volumioPushState Oct 05 07:57:22 volumio volumio[1045]: info: CoreCommandRouter::volumioGetState Oct 05 07:57:22 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 05 07:57:25 volumio volumio[1045]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 05 07:57:25 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 05 07:57:25 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 07:57:25 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 05 07:57:25 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 07:57:25 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 07:57:25 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 07:57:25 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 07:57:25 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 07:57:25 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 07:57:25 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 07:57:25 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 05 07:57:25 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 05 07:57:49 volumio nmbd[733]: [2024/10/05 07:57:49.038853, 0] ../source3/libsmb/nmblib.c:917(send_udp) Oct 05 07:57:49 volumio nmbd[733]: Packet send failed to 192.168.1.255(138) ERRNO=Network is unreachable Oct 05 07:57:49 volumio nmbd[733]: [2024/10/05 07:57:49.039026, 0] ../source3/libsmb/nmblib.c:917(send_udp) Oct 05 07:57:49 volumio nmbd[733]: Packet send failed to 169.254.255.255(138) ERRNO=Network is unreachable Oct 05 07:58:04 volumio volumio[1045]: info: CoreCommandRouter::volumioGetState Oct 05 07:58:04 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 05 07:58:09 volumio volumio[1045]: info: CoreCommandRouter::volumioGetQueue Oct 05 07:58:09 volumio volumio[1045]: info: CoreStateMachine::getQueue Oct 05 07:58:09 volumio volumio[1045]: info: CorePlayQueue::getQueue Oct 05 07:58:15 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 05 07:58:15 volumio volumio[1045]: info: CURURI: music-library/USB/0214DE213657696C/globaldata Oct 05 07:58:15 volumio volumio[1045]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Oct 05 07:58:15 volumio volumio[1045]: info: Preload queue cleared Oct 05 07:58:22 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 05 07:58:22 volumio volumio[1045]: info: CURURI: music-library/NAS Oct 05 07:58:22 volumio volumio[1045]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Oct 05 07:58:22 volumio volumio[1045]: info: Preload queue cleared Oct 05 07:58:25 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 05 07:58:25 volumio volumio[1045]: info: CURURI: music-library/NAS Oct 05 07:58:25 volumio volumio[1045]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Oct 05 07:58:25 volumio volumio[1045]: info: Preload queue cleared Oct 05 07:58:28 volumio volumio[1045]: info: CoreCommandRouter::volumioGetState Oct 05 07:58:28 volumio volumio[1045]: info: CorePlayQueue::getTrack 0 Oct 05 07:58:31 volumio volumio[1045]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 05 07:58:31 volumio volumio[1045]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 05 07:58:31 volumio volumio[1045]: info: Retrieving Cloud Streaming UI Oct 05 07:58:31 volumio volumio[1045]: info: Getting Tidal Cloud Configuration Oct 05 07:58:31 volumio volumio[1045]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 05 07:58:31 volumio volumio[1045]: info: Getting Qobuz Cloud Configuration Oct 05 07:58:31 volumio volumio[1045]: info: Asking plugin for UI Config Oct 05 07:58:31 volumio volumio[1045]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 05 07:58:31 volumio volumio[1045]: info: Getting Spotify Cloud Configuration Oct 05 07:58:31 volumio volumio[1045]: info: Asking plugin for UI Config Oct 05 07:58:31 volumio volumio[1045]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 05 07:58:31 volumio volumio[1045]: info: Saving Spotify Acccount Oct 05 07:58:31 volumio volumio[1045]: info: Got Tidal Cloud Configuration Oct 05 07:58:31 volumio volumio[1045]: info: Got it Oct 05 07:58:31 volumio volumio[1045]: info: Got it Oct 05 07:58:31 volumio volumio[1045]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 05 07:58:31 volumio volumio[1045]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 05 07:58:31 volumio volumio[1045]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Oct 05 07:58:31 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 05 07:58:31 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 05 07:58:31 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 05 07:58:31 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 05 07:58:31 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 07:58:31 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 07:58:31 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 07:58:31 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 05 07:58:31 volumio volumio[1045]: info: CoreCommandRouter::volumioGetBrowseSources Oct 05 07:58:31 volumio volumio[1045]: info: CoreCommandRouter::volumioGetBrowseSources Oct 05 07:58:31 volumio volumio[1045]: info: CoreCommandRouter::volumioGetBrowseSources Oct 05 07:58:32 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 05 07:58:32 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 05 07:58:36 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 05 07:58:40 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 05 07:58:44 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 05 07:58:47 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Oct 05 07:58:47 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Oct 05 07:58:48 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 05 07:58:52 volumio volumio[1045]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 05 07:58:52 volumio volumio[1045]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 05 07:58:52 volumio volumio[1045]: Error: dns service error: unknown Oct 05 07:58:52 volumio volumio[1045]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30) Oct 05 07:58:52 volumio volumio[1045]: at SocketWatcher.MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Oct 05 07:58:52 volumio volumio[1045]: errorCode: -65537 Oct 05 07:58:52 volumio volumio[1045]: } Oct 05 07:58:52 volumio volumio[1045]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 05 07:58:52 volumio sudo[2256]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-05 07:57 Oct 05 07:58:52 volumio sudo[2256]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"