-- Logs begin at Sun 2024-08-11 10:39:17 CST, end at Sat 2024-10-19 18:33:06 CST. -- Oct 19 18:32:06 volumio kernel: usb 1-1.2: USB disconnect, device number 4 Oct 19 18:32:06 volumio kernel: usb 1-1.2.2: USB disconnect, device number 5 Oct 19 18:32:06 volumio volumio[905]: info: Oct 19 18:32:06 volumio volumio[905]: ---------------------------- USB Audio Device Detached Oct 19 18:32:06 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Oct 19 18:32:06 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 19 18:32:06 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Oct 19 18:32:10 volumio volumio[905]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Oct 19 18:32:10 volumio volumio[905]: info: Retrying Login Due to Network Error Oct 19 18:32:11 volumio kernel: usb 1-1.3: new high-speed USB device number 7 using dwc_otg Oct 19 18:32:11 volumio kernel: usb 1-1.3: New USB device found, idVendor=0451, idProduct=8027, bcdDevice= 1.10 Oct 19 18:32:11 volumio kernel: usb 1-1.3: New USB device strings: Mfr=0, Product=0, SerialNumber=1 Oct 19 18:32:11 volumio kernel: usb 1-1.3: SerialNumber: 230000795DA2 Oct 19 18:32:11 volumio kernel: hub 1-1.3:1.0: USB hub found Oct 19 18:32:11 volumio kernel: hub 1-1.3:1.0: 2 ports detected Oct 19 18:32:11 volumio kernel: usb 1-1.5: USB disconnect, device number 6 Oct 19 18:32:11 volumio volumio[905]: info: Partition removed: {"syspath":"/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.5/1-1.5:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","ACTION":"remove","DEVLINKS":"/dev/disk/by-label/Elements /dev/disk/by-partuuid/f410eb64-0a14-4bc0-ae0b-84fcc4d17553 /dev/disk/by-id/usb-WD_Elements_2621_575831324141303453504439-0:0-part1 /dev/disk/by-uuid/062EF7A02EF786C9 /dev/disk/by-partlabel/Elements /dev/disk/by-path/platform-3f980000.usb-usb-0:1.5:1.0-scsi-0:0:0:0-part1","DEVNAME":"/dev/sda1","DEVPATH":"/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.5/1-1.5:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","DEVTYPE":"partition","DISKSEQ":"26","ID_BUS":"usb","ID_FS_LABEL":"Elements","ID_FS_LABEL_ENC":"Elements","ID_FS_TYPE":"ntfs","ID_FS_USAGE":"filesystem","ID_FS_UUID":"062EF7A02EF786C9","ID_FS_UUID_ENC":"062EF7A02EF786C9","ID_INSTANCE":"0:0","ID_MODEL":"Elements_2621","ID_MODEL_ENC":"Elements\\x202621\\x20\\x20\\x20","ID_MODEL_ID":"2621","ID_PART_ENTRY_DISK":"8:0","ID_PART_ENTRY_NAME":"Elements","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"2048","ID_PART_ENTRY_SCHEME":"gpt","ID_PART_ENTRY_SIZE":"3906959360","ID_PART_ENTRY_TYPE":"ebd0a0a2-b9e5-4433-87c0-68b6b72699c7","ID_PART_ENTRY_UUID":"f410eb64-0a14-4bc0-ae0b-84fcc4d17553","ID_PART_TABLE_TYPE":"atari","ID_PART_TABLE_UUID":"08f2e6bb-479b-4528-a876-dcc820124e94","ID_PATH":"platform-3f980000.usb-usb-0:1.5:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-3f980000_usb-usb-0_1_5_1_0-scsi-0_0_0_0","ID_REVISION":"1026","ID_SERIAL":"WD_Elements_2621_575831324141303453504439-0:0","ID_SERIAL_SHORT":"575831324141303453504439","ID_TYPE":"disk","ID_USB_DRIVER":"usb-storage","ID_USB_INTERFACES":":080650:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"WD","ID_VENDOR_ENC":"WD\\x20\\x20\\x20\\x20\\x20\\x20","ID_VENDOR_ID":"1058","MAJOR":"8","MINOR":"1","PARTN":"1","PARTNAME":"Elements","SEQNUM":"2009","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"9834478"} Oct 19 18:32:11 volumio sudo[2291]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sda1 Oct 19 18:32:11 volumio sudo[2291]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 18:32:11 volumio ntfs-3g[1291]: Unmounting /dev/sda1 (Elements) Oct 19 18:32:11 volumio sudo[2291]: pam_unix(sudo:session): session closed for user root Oct 19 18:32:11 volumio systemd[1]: media-Elements.mount: Succeeded. Oct 19 18:32:11 volumio kernel: usb 1-1.3.2: new high-speed USB device number 8 using dwc_otg Oct 19 18:32:12 volumio kernel: usb 1-1.3.2: New USB device found, idVendor=0644, idProduct=8043, bcdDevice= 1.00 Oct 19 18:32:12 volumio kernel: usb 1-1.3.2: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Oct 19 18:32:12 volumio kernel: usb 1-1.3.2: Product: UD-505 Oct 19 18:32:12 volumio kernel: usb 1-1.3.2: Manufacturer: TEAC Corporation Oct 19 18:32:12 volumio kernel: hwmon hwmon1: Undervoltage detected! Oct 19 18:32:13 volumio kernel: hid-generic 0003:0644:8043.0002: hiddev96,hidraw0: USB HID v1.10 Device [TEAC Corporation UD-505] on usb-3f980000.usb-1.3.2/input2 Oct 19 18:32:13 volumio volumio[905]: info: Oct 19 18:32:13 volumio volumio[905]: ---------------------------- USB Audio Device Attached Oct 19 18:32:13 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Oct 19 18:32:13 volumio volumio[905]: info: CoreCommandRouter::Close All Modals sent Oct 19 18:32:13 volumio volumio[905]: info: Preparing to save Alsa Options, stopping services first Oct 19 18:32:13 volumio volumio[905]: info: CoreCommandRouter::volumioGetState Oct 19 18:32:13 volumio volumio[905]: info: CoreCommandRouter::volumioPause Oct 19 18:32:13 volumio volumio[905]: info: CoreStateMachine::pause Oct 19 18:32:13 volumio volumio[905]: info: CoreStateMachine::stPlaybackTimer Oct 19 18:32:13 volumio volumio[905]: info: CoreStateMachine::servicePause Oct 19 18:32:13 volumio volumio[905]: info: CorePlayQueue::getTrack 0 Oct 19 18:32:13 volumio volumio[905]: info: Error: no service or no trackblock to pause Oct 19 18:32:13 volumio volumio[905]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"UD-505","alsacard":"UD505"},"i2s":false} Oct 19 18:32:13 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 19 18:32:13 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 19 18:32:14 volumio volumio[905]: info: Setting mixer PCM for card UD-505 Oct 19 18:32:14 volumio volumio[905]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 19 18:32:14 volumio volumio[905]: info: Updating Volume Controller Parameters: Device: 5 Name: UD-505 Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 4 Oct 19 18:32:14 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 19 18:32:14 volumio volumio[905]: info: Disabling external Volume Control Oct 19 18:32:14 volumio volumio[905]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 19 18:32:14 volumio volumio[905]: info: Preparing to generate the ALSA configuration file Oct 19 18:32:14 volumio volumio[905]: info: Asound.conf file unchanged, so no further update is needed Oct 19 18:32:14 volumio volumio[905]: info: Output device has changed, restarting MPD Oct 19 18:32:14 volumio sudo[2316]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 19 18:32:14 volumio volumio[905]: info: Output device has changed, restarting Shairport Sync Oct 19 18:32:14 volumio sudo[2316]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 18:32:14 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 18:32:14 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 19 18:32:14 volumio sudo[2316]: pam_unix(sudo:session): session closed for user root Oct 19 18:32:14 volumio sudo[2318]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 19 18:32:14 volumio sudo[2318]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 18:32:14 volumio systemd[1]: Stopping Music Player Daemon... Oct 19 18:32:14 volumio volumio[905]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 19 18:32:14 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 19 18:32:14 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 19 18:32:14 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 19 18:32:14 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 19 18:32:14 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 19 18:32:14 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 19 18:32:14 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 19 18:32:14 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 19 18:32:14 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 19 18:32:14 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 19 18:32:14 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 19 18:32:14 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 19 18:32:14 volumio systemd[1]: mpd.service: Succeeded. Oct 19 18:32:14 volumio systemd[1]: Stopped Music Player Daemon. Oct 19 18:32:14 volumio volumio[905]: info: MPD Permissions set Oct 19 18:32:14 volumio volumio[905]: info: VolumeController:: Volume=25 Mute =false Oct 19 18:32:14 volumio volumio[905]: info: CoreCommandRouter::volumioGetState Oct 19 18:32:14 volumio volumio[905]: info: CoreStateMachine::pushState Oct 19 18:32:14 volumio volumio[905]: info: CoreCommandRouter::volumioPushState Oct 19 18:32:14 volumio volumio[905]: info: CoreCommandRouter::volumioGetQueue Oct 19 18:32:14 volumio volumio[905]: info: CoreStateMachine::getQueue Oct 19 18:32:14 volumio volumio[905]: info: CorePlayQueue::getQueue Oct 19 18:32:14 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 18:32:14 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 18:32:14 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 18:32:14 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 18:32:14 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 18:32:14 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 18:32:14 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 18:32:14 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 18:32:14 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 19 18:32:14 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 19 18:32:14 volumio systemd[1]: Starting Music Player Daemon... Oct 19 18:32:14 volumio volumio[905]: info: Starting Shairport Sync Oct 19 18:32:14 volumio sudo[2334]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 19 18:32:14 volumio sudo[2334]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 18:32:14 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 19 18:32:14 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 19 18:32:14 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 19 18:32:14 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 19 18:32:14 volumio sudo[2334]: pam_unix(sudo:session): session closed for user root Oct 19 18:32:14 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Oct 19 18:32:14 volumio volumio[905]: info: Shairport-Sync Started Oct 19 18:32:14 volumio sudo[2328]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 19 18:32:14 volumio sudo[2328]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 18:32:14 volumio sudo[2328]: pam_unix(sudo:session): session closed for user root Oct 19 18:32:15 volumio volumio[905]: info: TEST: Here we shall have deleted mounted folder: /mnt/USB/Elements Oct 19 18:32:16 volumio mpd[2339]: Oct 19 18:32 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 19 18:32:16 volumio systemd[1]: Started Music Player Daemon. Oct 19 18:32:16 volumio sudo[2318]: pam_unix(sudo:session): session closed for user root Oct 19 18:32:16 volumio volumio[905]: error: updateQueue error: null Oct 19 18:32:16 volumio kernel: hwmon hwmon1: Voltage normalised Oct 19 18:32:16 volumio volumio[905]: info: Scanning removed location : "USB/Elements" Oct 19 18:32:16 volumio volumio[905]: verbose: ControllerMpd::sendMpdCommand status Oct 19 18:32:16 volumio volumio[905]: info: Oct 19 18:32:16 volumio volumio[905]: ---------------------------- MPD announces state update: update Oct 19 18:32:16 volumio volumio[905]: info: ControllerMpd::getState Oct 19 18:32:16 volumio volumio[905]: verbose: ControllerMpd::sendMpdCommand status Oct 19 18:32:16 volumio volumio[905]: info: sendMpdCommand status took 4 milliseconds Oct 19 18:32:16 volumio volumio[905]: info: sendMpdCommand status took 2 milliseconds Oct 19 18:32:16 volumio volumio[905]: verbose: ControllerMpd::parseState Oct 19 18:32:16 volumio volumio[905]: info: Command Router : Notfying DB Updatetrue Oct 19 18:32:16 volumio volumio[905]: verbose: ControllerMpd::parseState Oct 19 18:32:16 volumio volumio[905]: info: ControllerMpd::pushState Oct 19 18:32:16 volumio volumio[905]: info: CoreCommandRouter::servicePushState Oct 19 18:32:16 volumio volumio[905]: info: CoreStateMachine::pushState Oct 19 18:32:16 volumio volumio[905]: info: CoreCommandRouter::volumioPushState Oct 19 18:32:16 volumio volumio[905]: info: CorePlayQueue::getTrack 0 Oct 19 18:32:16 volumio volumio[905]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 19 18:32:16 volumio volumio[905]: verbose: CURRENT POSITION 0 Oct 19 18:32:16 volumio volumio[905]: info: CoreStateMachine::syncState stateService stop Oct 19 18:32:16 volumio volumio[905]: info: CoreStateMachine::syncState currentStatus pause Oct 19 18:32:16 volumio volumio[905]: info: CoreStateMachine::pushState Oct 19 18:32:16 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 19 18:32:16 volumio volumio[905]: info: CoreCommandRouter::volumioPushState Oct 19 18:32:16 volumio volumio[905]: info: ------------------------------ 106ms Oct 19 18:32:16 volumio volumio[905]: verbose: ControllerMpd::sendMpdCommand status Oct 19 18:32:16 volumio volumio[905]: info: Oct 19 18:32:16 volumio volumio[905]: ---------------------------- MPD announces state update: update Oct 19 18:32:16 volumio volumio[905]: info: ControllerMpd::getState Oct 19 18:32:16 volumio volumio[905]: verbose: ControllerMpd::sendMpdCommand status Oct 19 18:32:16 volumio volumio[905]: info: CoreCommandRouter::volumioGetQueue Oct 19 18:32:16 volumio volumio[905]: info: CoreStateMachine::getQueue Oct 19 18:32:16 volumio volumio[905]: info: CorePlayQueue::getQueue Oct 19 18:32:16 volumio volumio[905]: info: CoreCommandRouter::volumioGetQueue Oct 19 18:32:16 volumio volumio[905]: info: CoreStateMachine::getQueue Oct 19 18:32:16 volumio volumio[905]: info: CorePlayQueue::getQueue Oct 19 18:32:16 volumio volumio[905]: info: sendMpdCommand status took 10 milliseconds Oct 19 18:32:16 volumio volumio[905]: info: sendMpdCommand status took 9 milliseconds Oct 19 18:32:16 volumio volumio[905]: verbose: ControllerMpd::parseState Oct 19 18:32:16 volumio volumio[905]: info: Command Router : Notfying DB Updatefalse Oct 19 18:32:16 volumio volumio[905]: info: CoreCommandRouter::Close All Modals sent Oct 19 18:32:16 volumio volumio[905]: verbose: ControllerMpd::parseState Oct 19 18:32:16 volumio volumio[905]: info: ControllerMpd::pushState Oct 19 18:32:16 volumio volumio[905]: info: CoreCommandRouter::servicePushState Oct 19 18:32:16 volumio volumio[905]: info: CoreStateMachine::pushState Oct 19 18:32:16 volumio volumio[905]: info: CoreCommandRouter::volumioPushState Oct 19 18:32:16 volumio volumio[905]: info: CorePlayQueue::getTrack 0 Oct 19 18:32:16 volumio volumio[905]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 19 18:32:16 volumio volumio[905]: verbose: CURRENT POSITION 0 Oct 19 18:32:16 volumio volumio[905]: info: CoreStateMachine::syncState stateService stop Oct 19 18:32:16 volumio volumio[905]: info: CoreStateMachine::syncState currentStatus pause Oct 19 18:32:16 volumio volumio[905]: info: CoreStateMachine::pushState Oct 19 18:32:16 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 19 18:32:16 volumio volumio[905]: info: CoreCommandRouter::volumioPushState Oct 19 18:32:16 volumio volumio[905]: info: ------------------------------ 101ms Oct 19 18:32:16 volumio volumio[905]: info: CoreCommandRouter::volumioGetQueue Oct 19 18:32:16 volumio volumio[905]: info: CoreStateMachine::getQueue Oct 19 18:32:16 volumio volumio[905]: info: CorePlayQueue::getQueue Oct 19 18:32:16 volumio volumio[905]: info: CoreCommandRouter::volumioGetQueue Oct 19 18:32:16 volumio volumio[905]: info: CoreStateMachine::getQueue Oct 19 18:32:16 volumio volumio[905]: info: CorePlayQueue::getQueue Oct 19 18:32:18 volumio volumio[905]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Oct 19 18:32:18 volumio volumio[905]: info: CoreStateMachine::getcurrentVolume Oct 19 18:32:18 volumio volumio[905]: info: CoreCommandRouter::volumioRetrievevolume Oct 19 18:32:18 volumio volumio[905]: info: VolumeController:: Volume=25 Mute =false Oct 19 18:32:18 volumio volumio[905]: info: CoreCommandRouter::volumioGetState Oct 19 18:32:18 volumio volumio[905]: info: CoreStateMachine::pushState Oct 19 18:32:18 volumio volumio[905]: info: CoreCommandRouter::volumioPushState Oct 19 18:32:18 volumio volumio[905]: info: CoreStateMachine::updateTrackBlock Oct 19 18:32:18 volumio volumio[905]: info: CorePlayQueue::getTrackBlock Oct 19 18:32:18 volumio volumio[905]: info: CoreCommandRouter::volumioRetrievevolume Oct 19 18:32:18 volumio volumio[905]: info: CoreCommandRouter::volumioGetQueue Oct 19 18:32:18 volumio volumio[905]: info: CoreStateMachine::getQueue Oct 19 18:32:18 volumio volumio[905]: info: CorePlayQueue::getQueue Oct 19 18:32:18 volumio volumio[905]: info: VolumeController:: Volume=25 Mute =false Oct 19 18:32:18 volumio volumio[905]: info: CoreCommandRouter::volumioGetState Oct 19 18:32:18 volumio volumio[905]: info: CoreStateMachine::pushState Oct 19 18:32:18 volumio volumio[905]: info: CoreCommandRouter::volumioPushState Oct 19 18:32:18 volumio volumio[905]: info: CoreCommandRouter::volumioGetQueue Oct 19 18:32:18 volumio volumio[905]: info: CoreStateMachine::getQueue Oct 19 18:32:18 volumio volumio[905]: info: CorePlayQueue::getQueue Oct 19 18:32:20 volumio volumio[905]: info: MyVolumio login type: Token Oct 19 18:32:21 volumio volumio[905]: error: Upnp client error: Error: This socket has been ended by the other party Oct 19 18:32:27 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 19 18:32:27 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 19 18:32:27 volumio volumio[905]: info: Discovery: Getting this device information Oct 19 18:32:27 volumio volumio[905]: info: CoreCommandRouter::volumioGetState Oct 19 18:32:27 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 19 18:32:27 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 19 18:32:27 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 19 18:32:27 volumio volumio[905]: info: Discovery: Getting this device information Oct 19 18:32:27 volumio volumio[905]: info: CoreCommandRouter::volumioGetState Oct 19 18:32:27 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 19 18:32:27 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 19 18:32:27 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 19 18:32:27 volumio volumio[905]: info: Discovery: Getting this device information Oct 19 18:32:27 volumio volumio[905]: info: CoreCommandRouter::volumioGetState Oct 19 18:32:27 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 19 18:32:28 volumio volumio[905]: verbose: New Socket.io Connection to 192.168.0.102:3000 from 192.168.0.101 UA: Dart/3.4 (dart:io) Engine version: 3 Transport: websocket Total Clients: 4 Oct 19 18:32:29 volumio volumio[905]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E9%99%88%E4%BD%B3/%E5%BA%94%E6%98%AF%E6%95%85%E4%BA%BA%E6%9D%A5/64c30988-d061-4f40-aebd-9c4c2b6fc992.jpg' Oct 19 18:32:30 volumio sudo[2382]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 19 18:32:30 volumio sudo[2382]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 18:32:30 volumio sudo[2382]: pam_unix(sudo:session): session closed for user root Oct 19 18:32:30 volumio sudo[2385]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 19 18:32:30 volumio sudo[2385]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 18:32:30 volumio sudo[2385]: pam_unix(sudo:session): session closed for user root Oct 19 18:32:30 volumio volumio[905]: verbose: New Socket.io Connection to 192.168.0.102 from 192.168.0.101 UA: Mozilla/5.0 (Linux; Android 14; 23049RAD8C Build/UKQ1.230804.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/129.0.6668.102 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Oct 19 18:32:30 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 19 18:32:30 volumio sudo[2388]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 19 18:32:30 volumio sudo[2388]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 18:32:30 volumio sudo[2388]: pam_unix(sudo:session): session closed for user root Oct 19 18:32:30 volumio sudo[2391]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 19 18:32:30 volumio sudo[2391]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 18:32:30 volumio sudo[2391]: pam_unix(sudo:session): session closed for user root Oct 19 18:32:30 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 19 18:32:30 volumio volumio[905]: verbose: New Socket.io Connection to 192.168.0.102 from 192.168.0.101 UA: Mozilla/5.0 (Linux; Android 14; 23049RAD8C Build/UKQ1.230804.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/129.0.6668.102 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Oct 19 18:32:31 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 19 18:32:31 volumio volumio[905]: info: CoreCommandRouter::volumioGetVisibleSources Oct 19 18:32:31 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 19 18:32:31 volumio volumio[905]: info: CoreCommandRouter::volumioGetState Oct 19 18:32:31 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 19 18:32:31 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 19 18:32:31 volumio volumio[905]: info: Received Get System Info Oct 19 18:32:31 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 19 18:32:31 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 19 18:32:31 volumio volumio[905]: info: Discovery: Getting this device information Oct 19 18:32:31 volumio volumio[905]: info: CoreCommandRouter::volumioGetState Oct 19 18:32:31 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 19 18:32:31 volumio volumio[905]: info: CoreCommandRouter::volumioGetState Oct 19 18:32:31 volumio volumio[905]: info: Listing playlists Oct 19 18:32:31 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 19 18:32:31 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 19 18:32:31 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 19 18:32:32 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 19 18:32:32 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 19 18:32:32 volumio volumio[905]: info: Received Get System Info Oct 19 18:32:32 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 19 18:32:32 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 19 18:32:32 volumio volumio[905]: info: Discovery: Getting this device information Oct 19 18:32:32 volumio volumio[905]: info: CoreCommandRouter::volumioGetState Oct 19 18:32:32 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 19 18:32:32 volumio volumio[905]: info: CoreCommandRouter::volumioGetQueue Oct 19 18:32:32 volumio volumio[905]: info: CoreStateMachine::getQueue Oct 19 18:32:32 volumio volumio[905]: info: CorePlayQueue::getQueue Oct 19 18:32:34 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 19 18:32:34 volumio volumio[905]: info: CURURI: music-library Oct 19 18:32:34 volumio volumio[905]: info: Preload queue cleared Oct 19 18:32:35 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 19 18:32:35 volumio volumio[905]: info: CURURI: music-library/USB Oct 19 18:32:35 volumio volumio[905]: info: Preload queue cleared Oct 19 18:32:36 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 19 18:32:36 volumio volumio[905]: info: CURURI: music-library/USB/Elements Oct 19 18:32:36 volumio volumio[905]: error: Failed LSINFO: null Oct 19 18:32:36 volumio volumio[905]: info: Preload queue cleared Oct 19 18:32:42 volumio sudo[2396]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 19 18:32:42 volumio sudo[2396]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 18:32:42 volumio sudo[2396]: pam_unix(sudo:session): session closed for user root Oct 19 18:32:42 volumio sudo[2398]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 19 18:32:42 volumio sudo[2398]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 18:32:42 volumio sudo[2398]: pam_unix(sudo:session): session closed for user root Oct 19 18:32:42 volumio volumio[905]: verbose: New Socket.io Connection to 192.168.0.102 from 192.168.0.101 UA: Mozilla/5.0 (Linux; Android 14; 23049RAD8C Build/UKQ1.230804.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/129.0.6668.102 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Oct 19 18:32:42 volumio sudo[2402]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 19 18:32:42 volumio sudo[2402]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 18:32:42 volumio sudo[2402]: pam_unix(sudo:session): session closed for user root Oct 19 18:32:42 volumio sudo[2404]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 19 18:32:42 volumio sudo[2404]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 18:32:42 volumio sudo[2404]: pam_unix(sudo:session): session closed for user root Oct 19 18:32:42 volumio volumio[905]: verbose: New Socket.io Connection to 192.168.0.102 from 192.168.0.101 UA: Mozilla/5.0 (Linux; Android 14; 23049RAD8C Build/UKQ1.230804.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/129.0.6668.102 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Oct 19 18:32:42 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 19 18:32:42 volumio volumio[905]: info: CoreCommandRouter::volumioGetVisibleSources Oct 19 18:32:42 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 19 18:32:42 volumio volumio[905]: info: CoreCommandRouter::volumioGetState Oct 19 18:32:42 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 19 18:32:42 volumio volumio[905]: info: CoreCommandRouter::volumioGetQueue Oct 19 18:32:42 volumio volumio[905]: info: CoreStateMachine::getQueue Oct 19 18:32:42 volumio volumio[905]: info: CorePlayQueue::getQueue Oct 19 18:32:42 volumio volumio[905]: info: Listing playlists Oct 19 18:32:42 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 19 18:32:42 volumio volumio[905]: info: Received Get System Info Oct 19 18:32:42 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 19 18:32:42 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 19 18:32:42 volumio volumio[905]: info: Discovery: Getting this device information Oct 19 18:32:42 volumio volumio[905]: info: CoreCommandRouter::volumioGetState Oct 19 18:32:42 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 19 18:32:42 volumio volumio[905]: info: CoreCommandRouter::volumioGetState Oct 19 18:32:42 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 19 18:32:42 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 19 18:32:43 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 19 18:32:44 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 19 18:32:44 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 19 18:32:44 volumio volumio[905]: info: Received Get System Info Oct 19 18:32:44 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 19 18:32:44 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 19 18:32:44 volumio volumio[905]: info: Discovery: Getting this device information Oct 19 18:32:44 volumio volumio[905]: info: CoreCommandRouter::volumioGetState Oct 19 18:32:44 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 19 18:32:47 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 19 18:32:47 volumio volumio[905]: info: CURURI: music-library Oct 19 18:32:47 volumio volumio[905]: info: Preload queue cleared Oct 19 18:32:50 volumio volumio[905]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Oct 19 18:32:50 volumio volumio[905]: info: Retrying Login Due to Network Error Oct 19 18:32:50 volumio sudo[2408]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 19 18:32:50 volumio sudo[2408]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 18:32:50 volumio sudo[2408]: pam_unix(sudo:session): session closed for user root Oct 19 18:32:50 volumio sudo[2414]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 19 18:32:50 volumio sudo[2414]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 18:32:50 volumio sudo[2414]: pam_unix(sudo:session): session closed for user root Oct 19 18:32:50 volumio volumio[905]: verbose: New Socket.io Connection to 192.168.0.102 from 192.168.0.101 UA: Mozilla/5.0 (Linux; Android 14; 23049RAD8C Build/UKQ1.230804.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/129.0.6668.102 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Oct 19 18:32:51 volumio sudo[2418]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 19 18:32:51 volumio sudo[2418]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 18:32:51 volumio sudo[2418]: pam_unix(sudo:session): session closed for user root Oct 19 18:32:51 volumio sudo[2420]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 19 18:32:51 volumio sudo[2420]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 19 18:32:51 volumio sudo[2420]: pam_unix(sudo:session): session closed for user root Oct 19 18:32:51 volumio volumio[905]: verbose: New Socket.io Connection to 192.168.0.102 from 192.168.0.101 UA: Mozilla/5.0 (Linux; Android 14; 23049RAD8C Build/UKQ1.230804.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/129.0.6668.102 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Oct 19 18:32:51 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 19 18:32:51 volumio volumio[905]: info: CoreCommandRouter::volumioGetVisibleSources Oct 19 18:32:51 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 19 18:32:51 volumio volumio[905]: info: CoreCommandRouter::volumioGetState Oct 19 18:32:51 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 19 18:32:51 volumio volumio[905]: info: CoreCommandRouter::volumioGetQueue Oct 19 18:32:51 volumio volumio[905]: info: CoreStateMachine::getQueue Oct 19 18:32:51 volumio volumio[905]: info: CorePlayQueue::getQueue Oct 19 18:32:51 volumio volumio[905]: info: Listing playlists Oct 19 18:32:51 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 19 18:32:51 volumio volumio[905]: info: Received Get System Info Oct 19 18:32:51 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 19 18:32:51 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 19 18:32:51 volumio volumio[905]: info: Discovery: Getting this device information Oct 19 18:32:51 volumio volumio[905]: info: CoreCommandRouter::volumioGetState Oct 19 18:32:51 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 19 18:32:51 volumio volumio[905]: info: CoreCommandRouter::volumioGetState Oct 19 18:32:51 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 19 18:32:51 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 19 18:32:51 volumio openvpn[801]: TLS Error: TLS key negotiation failed to occur within 60 seconds (check your network connectivity) Oct 19 18:32:51 volumio openvpn[801]: TLS Error: TLS handshake failed Oct 19 18:32:51 volumio openvpn[801]: SIGUSR1[soft,tls-error] received, process restarting Oct 19 18:32:51 volumio openvpn[801]: Restart pause, 40 second(s) Oct 19 18:32:51 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 19 18:32:52 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 19 18:32:53 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 19 18:32:53 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 19 18:32:53 volumio volumio[905]: info: Received Get System Info Oct 19 18:32:53 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 19 18:32:53 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 19 18:32:53 volumio volumio[905]: info: Discovery: Getting this device information Oct 19 18:32:53 volumio volumio[905]: info: CoreCommandRouter::volumioGetState Oct 19 18:32:53 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 19 18:32:54 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 19 18:32:54 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 19 18:32:54 volumio volumio[905]: info: Discovery: Getting this device information Oct 19 18:32:54 volumio volumio[905]: info: CoreCommandRouter::volumioGetState Oct 19 18:32:54 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 19 18:32:55 volumio volumio[905]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 19 18:32:55 volumio volumio[905]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 19 18:32:55 volumio volumio[905]: info: Retrieving Cloud Streaming UI Oct 19 18:32:55 volumio volumio[905]: info: Getting Tidal Cloud Configuration Oct 19 18:32:55 volumio volumio[905]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 19 18:32:55 volumio volumio[905]: info: Getting Qobuz Cloud Configuration Oct 19 18:32:55 volumio volumio[905]: info: Asking plugin for UI Config Oct 19 18:32:55 volumio volumio[905]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 19 18:32:55 volumio volumio[905]: info: Getting Spotify Cloud Configuration Oct 19 18:32:55 volumio volumio[905]: info: Asking plugin for UI Config Oct 19 18:32:55 volumio volumio[905]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 19 18:32:55 volumio volumio[905]: info: Saving Spotify Acccount Oct 19 18:32:55 volumio volumio[905]: info: Got Tidal Cloud Configuration Oct 19 18:32:55 volumio volumio[905]: info: Got it Oct 19 18:32:55 volumio volumio[905]: info: Got it Oct 19 18:32:55 volumio volumio[905]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 19 18:32:55 volumio volumio[905]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 19 18:32:55 volumio volumio[905]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Oct 19 18:32:55 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 19 18:32:55 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 19 18:32:55 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 19 18:32:55 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 19 18:32:55 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 19 18:32:55 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 19 18:32:55 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 19 18:32:55 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 19 18:32:55 volumio volumio[905]: info: CoreCommandRouter::volumioGetBrowseSources Oct 19 18:32:55 volumio volumio[905]: info: CoreCommandRouter::volumioGetBrowseSources Oct 19 18:32:55 volumio volumio[905]: info: CoreCommandRouter::volumioGetBrowseSources Oct 19 18:32:55 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 19 18:32:55 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 19 18:32:56 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Oct 19 18:32:56 volumio volumio[905]: verbose: ControllerMpd::sendMpdCommand update Oct 19 18:32:56 volumio volumio[905]: verbose: ControllerMpd::sendMpdCommand status Oct 19 18:32:56 volumio volumio[905]: info: Oct 19 18:32:56 volumio volumio[905]: ---------------------------- MPD announces state update: update Oct 19 18:32:56 volumio volumio[905]: info: sendMpdCommand update took 10 milliseconds Oct 19 18:32:56 volumio volumio[905]: info: ControllerMpd::getState Oct 19 18:32:56 volumio volumio[905]: verbose: ControllerMpd::sendMpdCommand status Oct 19 18:32:56 volumio volumio[905]: verbose: ControllerMpd::sendMpdCommand status Oct 19 18:32:56 volumio volumio[905]: info: Oct 19 18:32:56 volumio volumio[905]: ---------------------------- MPD announces state update: update Oct 19 18:32:56 volumio volumio[905]: info: ControllerMpd::getState Oct 19 18:32:56 volumio volumio[905]: verbose: ControllerMpd::sendMpdCommand status Oct 19 18:32:56 volumio volumio[905]: info: sendMpdCommand status took 5 milliseconds Oct 19 18:32:56 volumio volumio[905]: info: sendMpdCommand status took 3 milliseconds Oct 19 18:32:56 volumio volumio[905]: verbose: ControllerMpd::parseState Oct 19 18:32:57 volumio volumio[905]: info: Command Router : Notfying DB Updatefalse Oct 19 18:32:57 volumio volumio[905]: info: CoreCommandRouter::Close All Modals sent Oct 19 18:32:57 volumio volumio[905]: verbose: ControllerMpd::parseState Oct 19 18:32:57 volumio volumio[905]: info: ControllerMpd::pushState Oct 19 18:32:57 volumio volumio[905]: info: CoreCommandRouter::servicePushState Oct 19 18:32:57 volumio volumio[905]: info: CoreStateMachine::pushState Oct 19 18:32:57 volumio volumio[905]: info: CoreCommandRouter::volumioPushState Oct 19 18:32:57 volumio volumio[905]: info: CorePlayQueue::getTrack 0 Oct 19 18:32:57 volumio volumio[905]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 19 18:32:57 volumio volumio[905]: verbose: CURRENT POSITION 0 Oct 19 18:32:57 volumio volumio[905]: info: CoreStateMachine::syncState stateService stop Oct 19 18:32:57 volumio volumio[905]: info: CoreStateMachine::syncState currentStatus pause Oct 19 18:32:57 volumio volumio[905]: info: CoreStateMachine::pushState Oct 19 18:32:57 volumio volumio[905]: info: CoreCommandRouter::volumioPushState Oct 19 18:32:57 volumio volumio[905]: info: ------------------------------ 120ms Oct 19 18:32:57 volumio volumio[905]: info: sendMpdCommand status took 119 milliseconds Oct 19 18:32:57 volumio volumio[905]: info: sendMpdCommand status took 117 milliseconds Oct 19 18:32:57 volumio volumio[905]: verbose: ControllerMpd::parseState Oct 19 18:32:57 volumio volumio[905]: info: Command Router : Notfying DB Updatefalse Oct 19 18:32:57 volumio volumio[905]: info: CoreCommandRouter::Close All Modals sent Oct 19 18:32:57 volumio volumio[905]: verbose: ControllerMpd::parseState Oct 19 18:32:57 volumio volumio[905]: info: ControllerMpd::pushState Oct 19 18:32:57 volumio volumio[905]: info: CoreCommandRouter::servicePushState Oct 19 18:32:57 volumio volumio[905]: info: CoreStateMachine::pushState Oct 19 18:32:57 volumio volumio[905]: info: CoreCommandRouter::volumioPushState Oct 19 18:32:57 volumio volumio[905]: info: CorePlayQueue::getTrack 0 Oct 19 18:32:57 volumio volumio[905]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 19 18:32:57 volumio volumio[905]: verbose: CURRENT POSITION 0 Oct 19 18:32:57 volumio volumio[905]: info: CoreStateMachine::syncState stateService stop Oct 19 18:32:57 volumio volumio[905]: info: CoreStateMachine::syncState currentStatus pause Oct 19 18:32:57 volumio volumio[905]: info: CoreStateMachine::pushState Oct 19 18:32:57 volumio volumio[905]: info: CoreCommandRouter::volumioPushState Oct 19 18:32:57 volumio volumio[905]: info: ------------------------------ 183ms Oct 19 18:32:57 volumio volumio[905]: info: CoreCommandRouter::volumioGetQueue Oct 19 18:32:57 volumio volumio[905]: info: CoreStateMachine::getQueue Oct 19 18:32:57 volumio volumio[905]: info: CorePlayQueue::getQueue Oct 19 18:32:57 volumio volumio[905]: info: CoreCommandRouter::volumioGetQueue Oct 19 18:32:57 volumio volumio[905]: info: CoreStateMachine::getQueue Oct 19 18:32:57 volumio volumio[905]: info: CorePlayQueue::getQueue Oct 19 18:32:57 volumio volumio[905]: info: CoreCommandRouter::volumioGetQueue Oct 19 18:32:57 volumio volumio[905]: info: CoreStateMachine::getQueue Oct 19 18:32:57 volumio volumio[905]: info: CorePlayQueue::getQueue Oct 19 18:32:57 volumio volumio[905]: info: CoreCommandRouter::volumioGetQueue Oct 19 18:32:57 volumio volumio[905]: info: CoreStateMachine::getQueue Oct 19 18:32:57 volumio volumio[905]: info: CorePlayQueue::getQueue Oct 19 18:32:59 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 19 18:33:00 volumio volumio[905]: info: MyVolumio login type: Token Oct 19 18:33:01 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 19 18:33:03 volumio volumio[905]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 19 18:33:05 volumio volumio[905]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 19 18:33:05 volumio volumio[905]: Error: connect ETIMEDOUT 69.63.178.13:443 Oct 19 18:33:05 volumio volumio[905]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Oct 19 18:33:05 volumio volumio[905]: errno: -110, Oct 19 18:33:05 volumio volumio[905]: code: 'ETIMEDOUT', Oct 19 18:33:05 volumio volumio[905]: syscall: 'connect', Oct 19 18:33:05 volumio volumio[905]: address: '69.63.178.13', Oct 19 18:33:05 volumio volumio[905]: port: 443 Oct 19 18:33:05 volumio volumio[905]: } Oct 19 18:33:05 volumio volumio[905]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 19 18:33:06 volumio sudo[2476]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-19 18:32 Oct 19 18:33:06 volumio sudo[2476]: 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"