-- Logs begin at Thu 2024-08-22 07:18:02 JST, end at Sun 2024-08-25 05:55:39 JST. -- Aug 25 05:54:23 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 25 05:54:23 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 25 05:54:23 volumio volumio[829]: info: Discovery: Getting this device information Aug 25 05:54:23 volumio volumio[829]: info: CoreCommandRouter::volumioGetState Aug 25 05:54:23 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Aug 25 05:54:23 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 25 05:54:42 volumio kernel: usb 1-1.3: USB disconnect, device number 3 Aug 25 05:54:42 volumio kernel: ch341-uart ttyUSB0: ch341-uart converter now disconnected from ttyUSB0 Aug 25 05:54:42 volumio kernel: ch341 1-1.3:1.0: device disconnected Aug 25 05:54:45 volumio kernel: usb 1-1.3: new high-speed USB device number 5 using xhci_hcd Aug 25 05:54:45 volumio kernel: usb 1-1.3: Device not responding to setup address. Aug 25 05:54:45 volumio kernel: usb 1-1.3: Device not responding to setup address. Aug 25 05:54:46 volumio kernel: usb 1-1.3: device not accepting address 5, error -71 Aug 25 05:54:46 volumio kernel: usb 1-1.3: new high-speed USB device number 6 using xhci_hcd Aug 25 05:54:46 volumio kernel: usb 1-1.3: New USB device found, idVendor=152a, idProduct=85dd, bcdDevice= 1.00 Aug 25 05:54:46 volumio kernel: usb 1-1.3: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Aug 25 05:54:46 volumio kernel: usb 1-1.3: Product: SMSL USB AUDIO Aug 25 05:54:46 volumio kernel: usb 1-1.3: Manufacturer: SMSL Aug 25 05:54:46 volumio kernel: usb 1-1.3: parse_audio_format_rates_v2v3(): unable to find clock source (clock -71) Aug 25 05:54:46 volumio kernel: usb 1-1.3: uac_clock_source_is_valid(): cannot get clock validity for id 41 Aug 25 05:54:46 volumio kernel: usb 1-1.3: clock source 41 is not valid, cannot use Aug 25 05:54:46 volumio kernel: usb 1-1.3: 1:3 : unsupported format bits 0x100000000 Aug 25 05:54:46 volumio kernel: usb 1-1.3: parse_audio_format_rates_v2v3(): unable to find clock source (clock -71) Aug 25 05:54:46 volumio kernel: usb 1-1.3: uac_clock_source_is_valid(): cannot get clock validity for id 41 Aug 25 05:54:46 volumio kernel: usb 1-1.3: clock source 41 is not valid, cannot use Aug 25 05:54:46 volumio kernel: usb 1-1.3: 10:0: failed to get current value for ch 1 (-71) Aug 25 05:54:46 volumio kernel: usb 1-1.3: 10:0: failed to get current value for ch 2 (-71) Aug 25 05:54:46 volumio kernel: usb 1-1.3: 10:0: failed to get current value for ch 0 (-71) Aug 25 05:54:46 volumio kernel: usb 1-1.3: 10:0: cannot get min/max values for control 2 (id 10) Aug 25 05:54:46 volumio kernel: usb 1-1.3: 10:0: cannot get min/max values for control 2 (id 10) Aug 25 05:54:46 volumio kernel: usbcore: registered new interface driver snd-usb-audio Aug 25 05:54:46 volumio kernel: usb 1-1.3: 10:0: cannot get min/max values for control 2 (id 10) Aug 25 05:54:46 volumio kernel: usb 1-1.3: 10:0: cannot get min/max values for control 2 (id 10) Aug 25 05:54:46 volumio kernel: usb 1-1.3: 10:0: cannot get min/max values for control 2 (id 10) Aug 25 05:54:46 volumio kernel: usb 1-1.3: 10:0: cannot get min/max values for control 2 (id 10) Aug 25 05:54:46 volumio kernel: usb 1-1.3: 10:0: cannot get min/max values for control 2 (id 10) Aug 25 05:54:46 volumio kernel: usb 1-1.3: 10:0: cannot get min/max values for control 2 (id 10) Aug 25 05:54:46 volumio kernel: usb 1-1.3: 10:0: cannot get min/max values for control 2 (id 10) Aug 25 05:54:46 volumio kernel: usb 1-1.3: 10:0: cannot get min/max values for control 2 (id 10) Aug 25 05:54:46 volumio kernel: usb 1-1.3: 10:0: cannot get min/max values for control 2 (id 10) Aug 25 05:54:46 volumio kernel: usb 1-1.3: 10:0: cannot get min/max values for control 2 (id 10) Aug 25 05:54:46 volumio kernel: usb 1-1.3: 10:0: cannot get min/max values for control 2 (id 10) Aug 25 05:54:46 volumio kernel: usb 1-1.3: 10:0: cannot get min/max values for control 2 (id 10) Aug 25 05:54:46 volumio kernel: usb 1-1.3: 10:0: cannot get min/max values for control 2 (id 10) Aug 25 05:54:46 volumio kernel: usb 1-1.3: 10:0: cannot get min/max values for control 2 (id 10) Aug 25 05:54:46 volumio kernel: usb 1-1.3: 10:0: cannot get min/max values for control 2 (id 10) Aug 25 05:54:46 volumio kernel: usb 1-1.3: 10:0: cannot get min/max values for control 2 (id 10) Aug 25 05:54:46 volumio systemd-udevd[1328]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 5' failed with exit code 99. Aug 25 05:54:46 volumio volumio[829]: info: Aug 25 05:54:46 volumio volumio[829]: ---------------------------- USB Audio Device Attached Aug 25 05:54:46 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Aug 25 05:54:46 volumio kernel: usb 1-1.3: USB disconnect, device number 6 Aug 25 05:54:46 volumio volumio[829]: error: API:pushError: TypeError: Cannot read property 'name' of null Aug 25 05:54:46 volumio volumio[829]: info: Aug 25 05:54:46 volumio volumio[829]: ---------------------------- USB Audio Device Detached Aug 25 05:54:46 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Aug 25 05:54:46 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 25 05:54:46 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Aug 25 05:54:47 volumio kernel: usb 1-1.3: new high-speed USB device number 7 using xhci_hcd Aug 25 05:54:47 volumio kernel: usb 1-1.3: New USB device found, idVendor=152a, idProduct=85dd, bcdDevice= 1.00 Aug 25 05:54:47 volumio kernel: usb 1-1.3: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Aug 25 05:54:47 volumio kernel: usb 1-1.3: Product: SMSL USB AUDIO Aug 25 05:54:47 volumio kernel: usb 1-1.3: Manufacturer: SMSL Aug 25 05:54:48 volumio kernel: usb 1-1.3: 1:3 : unsupported format bits 0x100000000 Aug 25 05:54:48 volumio volumio[829]: info: Aug 25 05:54:48 volumio volumio[829]: ---------------------------- USB Audio Device Attached Aug 25 05:54:48 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Aug 25 05:54:48 volumio volumio[829]: info: CoreCommandRouter::Close All Modals sent Aug 25 05:54:48 volumio volumio[829]: info: Preparing to save Alsa Options, stopping services first Aug 25 05:54:48 volumio volumio[829]: info: CoreCommandRouter::volumioGetState Aug 25 05:54:48 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Aug 25 05:54:48 volumio volumio[829]: info: CoreCommandRouter::volumioPause Aug 25 05:54:48 volumio volumio[829]: info: CoreStateMachine::pause Aug 25 05:54:48 volumio volumio[829]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"SMSL USB AUDIO","alsacard":"AUDIO"},"i2s":false} Aug 25 05:54:48 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 25 05:54:48 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 25 05:54:48 volumio volumio[829]: info: Setting mixer SMSL USB AUDIO for card SMSL USB AUDIO Aug 25 05:54:48 volumio volumio[829]: info: CoreCommandRouter::volumioUpdateVolumeSettings Aug 25 05:54:48 volumio volumio[829]: info: Updating Volume Controller Parameters: Device: 5 Name: SMSL USB AUDIO Mixer: SMSL USB AUDIO Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Aug 25 05:54:48 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Aug 25 05:54:48 volumio volumio[829]: info: Disabling external Volume Control Aug 25 05:54:48 volumio volumio[829]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 25 05:54:48 volumio volumio[829]: info: Preparing to generate the ALSA configuration file Aug 25 05:54:49 volumio volumio[829]: info: Asound.conf file unchanged, so no further update is needed Aug 25 05:54:49 volumio volumio[829]: info: Output device has changed, restarting MPD Aug 25 05:54:49 volumio sudo[1359]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 25 05:54:49 volumio sudo[1359]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 05:54:49 volumio sudo[1359]: pam_unix(sudo:session): session closed for user root Aug 25 05:54:49 volumio sudo[1362]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 25 05:54:49 volumio sudo[1362]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 05:54:49 volumio systemd[1]: Stopping Music Player Daemon... Aug 25 05:54:49 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 25 05:54:49 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 25 05:54:49 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 25 05:54:49 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 25 05:54:49 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 25 05:54:49 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 25 05:54:49 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 25 05:54:49 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 25 05:54:49 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 25 05:54:49 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 25 05:54:49 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 25 05:54:49 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 25 05:54:49 volumio volumio[829]: info: VolumeController:: Volume=100 Mute =false Aug 25 05:54:49 volumio volumio[829]: info: CoreStateMachine::pushState Aug 25 05:54:49 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Aug 25 05:54:49 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 05:54:49 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Aug 25 05:54:49 volumio volumio[829]: info: MPD Permissions set Aug 25 05:54:49 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 05:54:49 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 05:54:49 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 05:54:49 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 05:54:49 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 05:54:49 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 05:54:49 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 05:54:49 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 05:54:49 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 25 05:54:49 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 05:54:49 volumio systemd[1]: mpd.service: Succeeded. Aug 25 05:54:49 volumio systemd[1]: Stopped Music Player Daemon. Aug 25 05:54:49 volumio systemd[1]: Starting Music Player Daemon... Aug 25 05:54:49 volumio sudo[1372]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 25 05:54:49 volumio sudo[1372]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 05:54:49 volumio sudo[1372]: pam_unix(sudo:session): session closed for user root Aug 25 05:54:49 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Aug 25 05:54:50 volumio mpd[1374]: Aug 25 05:54 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 25 05:54:50 volumio systemd[1]: Started Music Player Daemon. Aug 25 05:54:50 volumio sudo[1362]: pam_unix(sudo:session): session closed for user root Aug 25 05:54:50 volumio volumio[829]: error: updateQueue error: null Aug 25 05:54:52 volumio volumio[829]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Aug 25 05:54:52 volumio volumio[829]: info: CoreStateMachine::getcurrentVolume Aug 25 05:54:52 volumio volumio[829]: info: CoreCommandRouter::volumioRetrievevolume Aug 25 05:54:53 volumio volumio[829]: info: VolumeController:: Volume=100 Mute =false Aug 25 05:54:53 volumio volumio[829]: info: CoreStateMachine::pushState Aug 25 05:54:53 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Aug 25 05:54:53 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Aug 25 05:54:53 volumio volumio[829]: info: CoreStateMachine::updateTrackBlock Aug 25 05:54:53 volumio volumio[829]: info: CorePlayQueue::getTrackBlock Aug 25 05:54:53 volumio volumio[829]: info: CoreCommandRouter::volumioRetrievevolume Aug 25 05:54:53 volumio volumio[829]: info: VolumeController:: Volume=100 Mute =false Aug 25 05:54:53 volumio volumio[829]: info: CoreStateMachine::pushState Aug 25 05:54:53 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Aug 25 05:54:53 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Aug 25 05:54:53 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 25 05:54:53 volumio volumio[829]: info: VolumeController:: Setting startup Volume 75 Aug 25 05:54:53 volumio volumio[829]: info: VolumeController::SetAlsaVolume75 Aug 25 05:54:53 volumio volumio[829]: info: CoreStateMachine::pushState Aug 25 05:54:53 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Aug 25 05:54:53 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 05:54:53 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Aug 25 05:54:53 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 25 05:54:53 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 25 05:54:53 volumio volumio[829]: info: Discovery: Getting this device information Aug 25 05:54:53 volumio volumio[829]: info: CoreCommandRouter::volumioGetState Aug 25 05:54:53 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Aug 25 05:54:53 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 25 05:55:23 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 25 05:55:23 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 25 05:55:23 volumio volumio[829]: info: Discovery: Getting this device information Aug 25 05:55:23 volumio volumio[829]: info: CoreCommandRouter::volumioGetState Aug 25 05:55:23 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Aug 25 05:55:23 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 25 05:55:29 volumio sudo[1423]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 25 05:55:29 volumio sudo[1423]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 05:55:29 volumio sudo[1423]: pam_unix(sudo:session): session closed for user root Aug 25 05:55:29 volumio sudo[1426]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 25 05:55:29 volumio sudo[1426]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 05:55:29 volumio sudo[1426]: pam_unix(sudo:session): session closed for user root Aug 25 05:55:29 volumio volumio[829]: verbose: New Socket.io Connection to 192.168.3.3 from 192.168.3.9 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/127.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Aug 25 05:55:29 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 25 05:55:29 volumio volumio[829]: info: CoreCommandRouter::volumioGetVisibleSources Aug 25 05:55:29 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 25 05:55:29 volumio volumio[829]: info: CoreCommandRouter::volumioGetState Aug 25 05:55:29 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Aug 25 05:55:29 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 25 05:55:29 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 25 05:55:29 volumio volumio[829]: info: Received Get System Info Aug 25 05:55:29 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 25 05:55:29 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 25 05:55:29 volumio volumio[829]: info: Discovery: Getting this device information Aug 25 05:55:29 volumio volumio[829]: info: CoreCommandRouter::volumioGetState Aug 25 05:55:29 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Aug 25 05:55:29 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 25 05:55:29 volumio volumio[829]: info: CoreCommandRouter::volumioGetState Aug 25 05:55:29 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Aug 25 05:55:29 volumio volumio[829]: info: Listing playlists Aug 25 05:55:29 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 25 05:55:29 volumio volumio[829]: error: Cannot get UI Settings Aug 25 05:55:29 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 25 05:55:29 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 25 05:55:30 volumio volumio[829]: info: CoreCommandRouter::volumioGetState Aug 25 05:55:30 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Aug 25 05:55:31 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 25 05:55:31 volumio volumio[829]: info: Received Get System Info Aug 25 05:55:31 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 25 05:55:31 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 25 05:55:31 volumio volumio[829]: info: Discovery: Getting this device information Aug 25 05:55:31 volumio volumio[829]: info: CoreCommandRouter::volumioGetState Aug 25 05:55:31 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Aug 25 05:55:31 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 25 05:55:32 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 25 05:55:32 volumio volumio[829]: info: Received Get System Info Aug 25 05:55:32 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 25 05:55:32 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 25 05:55:32 volumio volumio[829]: info: Discovery: Getting this device information Aug 25 05:55:32 volumio volumio[829]: info: CoreCommandRouter::volumioGetState Aug 25 05:55:32 volumio volumio[829]: info: CorePlayQueue::getTrack 0 Aug 25 05:55:32 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 25 05:55:33 volumio volumio[829]: info: CoreCommandRouter::volumioNext Aug 25 05:55:33 volumio volumio[829]: info: CoreStateMachine::next Aug 25 05:55:33 volumio volumio[829]: info: CoreStateMachine::stop Aug 25 05:55:33 volumio volumio[829]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 25 05:55:33 volumio volumio[829]: info: CoreStateMachine::play index undefined Aug 25 05:55:33 volumio volumio[829]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 25 05:55:33 volumio volumio[829]: info: CorePlayQueue::getTrack 13605 Aug 25 05:55:33 volumio volumio[829]: info: CoreStateMachine::startPlaybackTimer Aug 25 05:55:33 volumio volumio[829]: info: CorePlayQueue::getTrack 13605 Aug 25 05:55:33 volumio volumio[829]: verbose: ControllerMpd::clearAddPlayTracks USB/3934-3033/flac/Rodgers & Hammerstein/The sound of music/10 The Sound Of Music.flac Aug 25 05:55:33 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand stop Aug 25 05:55:33 volumio volumio[829]: info: CoreStateMachine::updateTrackBlock Aug 25 05:55:33 volumio volumio[829]: info: CorePlayQueue::getTrackBlock Aug 25 05:55:33 volumio volumio[829]: info: sendMpdCommand stop took 15 milliseconds Aug 25 05:55:33 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand clear Aug 25 05:55:33 volumio volumio[829]: info: Aug 25 05:55:33 volumio volumio[829]: ---------------------------- MPD announces system playlist update Aug 25 05:55:33 volumio volumio[829]: info: Ignoring MPD Status Update Aug 25 05:55:33 volumio volumio[829]: info: sendMpdCommand clear took 2 milliseconds Aug 25 05:55:33 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand add "USB/3934-3033/flac/Rodgers & Hammerstein/The sound of music/10 The Sound Of Music.flac" Aug 25 05:55:33 volumio volumio[829]: error: updateQueue error: null Aug 25 05:55:33 volumio volumio[829]: info: Aug 25 05:55:33 volumio volumio[829]: ---------------------------- MPD announces system playlist update Aug 25 05:55:33 volumio volumio[829]: info: Ignoring MPD Status Update Aug 25 05:55:33 volumio volumio[829]: info: ------------------------------ 4ms Aug 25 05:55:33 volumio volumio[829]: info: sendMpdCommand add "USB/3934-3033/flac/Rodgers & Hammerstein/The sound of music/10 The Sound Of Music.flac" took 3 milliseconds Aug 25 05:55:33 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand play Aug 25 05:55:33 volumio volumio[829]: info: ------------------------------ 4ms Aug 25 05:55:33 volumio volumio[829]: info: sendMpdCommand play took 2 milliseconds Aug 25 05:55:34 volumio volumio[829]: info: Aug 25 05:55:34 volumio volumio[829]: ---------------------------- MPD announces state update: player Aug 25 05:55:34 volumio volumio[829]: info: ControllerMpd::getState Aug 25 05:55:34 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Aug 25 05:55:34 volumio volumio[829]: info: Aug 25 05:55:34 volumio volumio[829]: ---------------------------- MPD announces state update: player Aug 25 05:55:34 volumio volumio[829]: info: sendMpdCommand status took 418 milliseconds Aug 25 05:55:34 volumio volumio[829]: info: ControllerMpd::getState Aug 25 05:55:34 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand status Aug 25 05:55:34 volumio volumio[829]: verbose: ControllerMpd::parseState Aug 25 05:55:34 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 05:55:34 volumio volumio[829]: info: sendMpdCommand status took 6 milliseconds Aug 25 05:55:34 volumio volumio[829]: verbose: ControllerMpd::parseState Aug 25 05:55:34 volumio volumio[829]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 05:55:34 volumio volumio[829]: info: sendMpdCommand playlistinfo took 7 milliseconds Aug 25 05:55:34 volumio volumio[829]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 25 05:55:34 volumio volumio[829]: verbose: ControllerMpd::parseTrackInfo Aug 25 05:55:34 volumio volumio[829]: verbose: ControllerMpd::parseTrackInfo Aug 25 05:55:34 volumio volumio[829]: info: ControllerMpd::pushState Aug 25 05:55:34 volumio volumio[829]: info: CoreCommandRouter::servicePushState Aug 25 05:55:34 volumio volumio[829]: info: CorePlayQueue::getTrack 13605 Aug 25 05:55:34 volumio volumio[829]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":132,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Sound Of Music","artist":"Rodgers & Hammerstein","album":"The sound of music","uri":"USB/3934-3033/flac/Rodgers & Hammerstein/The sound of music/10 The Sound Of Music.flac","trackType":"flac"} Aug 25 05:55:34 volumio volumio[829]: verbose: CURRENT POSITION 13605 Aug 25 05:55:34 volumio volumio[829]: info: CoreStateMachine::syncState stateService play Aug 25 05:55:34 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus stop Aug 25 05:55:34 volumio volumio[829]: info: ControllerMpd::pushState Aug 25 05:55:34 volumio volumio[829]: info: CoreCommandRouter::servicePushState Aug 25 05:55:34 volumio volumio[829]: info: CorePlayQueue::getTrack 13605 Aug 25 05:55:34 volumio volumio[829]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45,"duration":132,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Sound Of Music","artist":"Rodgers & Hammerstein","album":"The sound of music","uri":"USB/3934-3033/flac/Rodgers & Hammerstein/The sound of music/10 The Sound Of Music.flac","trackType":"flac"} Aug 25 05:55:34 volumio volumio[829]: verbose: CURRENT POSITION 13605 Aug 25 05:55:34 volumio volumio[829]: info: CoreStateMachine::syncState stateService play Aug 25 05:55:34 volumio volumio[829]: info: CoreStateMachine::syncState currentStatus play Aug 25 05:55:34 volumio volumio[829]: info: Received an update from plugin. extracting info from payload Aug 25 05:55:34 volumio volumio[829]: info: CoreStateMachine::pushState Aug 25 05:55:34 volumio volumio[829]: info: CorePlayQueue::getTrack 13605 Aug 25 05:55:34 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 05:55:34 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Aug 25 05:55:34 volumio volumio[829]: info: CoreStateMachine::pushState Aug 25 05:55:34 volumio volumio[829]: info: CorePlayQueue::getTrack 13605 Aug 25 05:55:34 volumio volumio[829]: info: CoreCommandRouter::volumioPushState Aug 25 05:55:34 volumio volumio[829]: info: ------------------------------ 454ms Aug 25 05:55:34 volumio volumio[829]: info: ------------------------------ 44ms Aug 25 05:55:39 volumio volumio[829]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 25 05:55:39 volumio volumio[829]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 25 05:55:39 volumio volumio[829]: TypeError: Cannot read property 'then' of undefined Aug 25 05:55:39 volumio volumio[829]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1991:20) Aug 25 05:55:39 volumio volumio[829]: at Socket.emit (events.js:315:20) Aug 25 05:55:39 volumio volumio[829]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Aug 25 05:55:39 volumio volumio[829]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Aug 25 05:55:39 volumio volumio[829]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 25 05:55:39 volumio sudo[1440]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-25 05:54 Aug 25 05:55:39 volumio sudo[1440]: 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"