-- Logs begin at Mon 2024-07-22 06:10:32 SAST, end at Mon 2024-07-22 16:20:41 SAST. -- Jul 22 16:19:00 volumio-usbridge nmbd[547]: [2024/07/22 16:19:00.517332, 0] ../source3/nmbd/nmbd_serverlistdb.c:317(write_browse_list) Jul 22 16:19:00 volumio-usbridge nmbd[547]: write_browse_list: Can't open file /var/cache/samba/browse.dat.: Input/output error Jul 22 16:19:00 volumio-usbridge kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1536: inode #917584: comm nmbd: reading directory lblock 0 Jul 22 16:19:00 volumio-usbridge kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1536: inode #917542: comm nmbd: reading directory lblock 0 Jul 22 16:19:00 volumio-usbridge nmbd[10100]: [2024/07/22 16:19:00.537102, 0] ../source3/nmbd/nmbd_winsserver.c:2508(wins_write_database) Jul 22 16:19:00 volumio-usbridge nmbd[10100]: wins_write_database: Can't open /var/lib/samba/wins.dat.10100: Input/output error Jul 22 16:19:00 volumio-usbridge nmbd[547]: [2024/07/22 16:19:00.557360, 0] ../source3/nmbd/nmbd_serverlistdb.c:317(write_browse_list) Jul 22 16:19:00 volumio-usbridge nmbd[547]: write_browse_list: Can't open file /var/cache/samba/browse.dat.: Input/output error Jul 22 16:19:00 volumio-usbridge kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1536: inode #917584: comm nmbd: reading directory lblock 0 Jul 22 16:19:06 volumio-usbridge kernel: usb 1-1.2: new high-speed USB device number 35 using dwc_otg Jul 22 16:19:06 volumio-usbridge kernel: usb 1-1.2: New USB device found, idVendor=17ae, idProduct=0005, bcdDevice= 5.31 Jul 22 16:19:06 volumio-usbridge kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Jul 22 16:19:06 volumio-usbridge kernel: usb 1-1.2: Product: NAD USB Audio 2.0 Jul 22 16:19:06 volumio-usbridge kernel: usb 1-1.2: Manufacturer: NAD Jul 22 16:19:06 volumio-usbridge kernel: usb 1-1.2: SerialNumber: 0000 Jul 22 16:19:07 volumio-usbridge volumio[599]: info: Jul 22 16:19:07 volumio-usbridge volumio[599]: ---------------------------- USB Audio Device Attached Jul 22 16:19:07 volumio-usbridge volumio[599]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Jul 22 16:19:07 volumio-usbridge volumio[599]: xcb_connection_has_error() returned true Jul 22 16:19:07 volumio-usbridge volumio[599]: info: CoreCommandRouter::Close All Modals sent Jul 22 16:19:07 volumio-usbridge volumio[599]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 22 16:19:07 volumio-usbridge volumio[599]: xcb_connection_has_error() returned true Jul 22 16:19:07 volumio-usbridge volumio[599]: xcb_connection_has_error() returned true Jul 22 16:19:07 volumio-usbridge volumio[599]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 22 16:19:07 volumio-usbridge volumio[599]: xcb_connection_has_error() returned true Jul 22 16:19:07 volumio-usbridge volumio[599]: info: Setting mixer NAD Audio 2.0 Output for card NAD USB Audio 2.0 Jul 22 16:19:07 volumio-usbridge vtcs[8510]: [2024-07-22 16:19:07.321] [tisoc] [error] [SpkconServer.cpp:368] recv error. socket disconnected Jul 22 16:19:07 volumio-usbridge vtcs[8510]: [2024-07-22 16:19:07.328] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Jul 22 16:19:07 volumio-usbridge sudo[10117]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jul 22 16:19:07 volumio-usbridge sudo[10117]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 16:19:07 volumio-usbridge systemd[1]: Stopping Volumio Tidal Connect Service... Jul 22 16:19:07 volumio-usbridge systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Jul 22 16:19:07 volumio-usbridge systemd[1]: vtcs.service: Succeeded. Jul 22 16:19:07 volumio-usbridge systemd[1]: Stopped Volumio Tidal Connect Service. Jul 22 16:19:07 volumio-usbridge volumio[599]: xcb_connection_has_error() returned true Jul 22 16:19:07 volumio-usbridge volumio[599]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jul 22 16:19:07 volumio-usbridge volumio[599]: info: Updating Volume Controller Parameters: Device: 5 Name: NAD USB Audio 2.0 Mixer: NAD Audio 2.0 Output Max Vol: 100 Vol Curve; logarithmic Vol Steps: 10 Jul 22 16:19:07 volumio-usbridge volumio[599]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jul 22 16:19:07 volumio-usbridge volumio[599]: info: Disabling external Volume Control Jul 22 16:19:07 volumio-usbridge sudo[10117]: pam_unix(sudo:session): session closed for user root Jul 22 16:19:07 volumio-usbridge volumio[599]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 22 16:19:07 volumio-usbridge volumio[599]: info: Preparing to generate the ALSA configuration file Jul 22 16:19:07 volumio-usbridge kernel: EXT4-fs warning (device mmcblk0p3): htree_dirblock_to_tree:1003: inode #917778: lblock 0: comm node: error -5 reading directory block Jul 22 16:19:07 volumio-usbridge volumio[599]: warn: Unable to scan plugin multiroom for ALSA configuration: Error: EIO: i/o error, scandir '/data/configuration/audio_interface/multiroom/asound' Jul 22 16:19:07 volumio-usbridge volumio[599]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 22 16:19:07 volumio-usbridge sudo[10124]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jul 22 16:19:07 volumio-usbridge sudo[10124]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 16:19:07 volumio-usbridge sudo[10124]: pam_unix(sudo:session): session closed for user root Jul 22 16:19:07 volumio-usbridge volumio[599]: xcb_connection_has_error() returned true Jul 22 16:19:07 volumio-usbridge volumio[599]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jul 22 16:19:07 volumio-usbridge volumio[599]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 22 16:19:07 volumio-usbridge volumio[599]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 22 16:19:07 volumio-usbridge volumio[599]: xcb_connection_has_error() returned true Jul 22 16:19:07 volumio-usbridge volumio[599]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 22 16:19:07 volumio-usbridge volumio[599]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 22 16:19:07 volumio-usbridge volumio[599]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 22 16:19:07 volumio-usbridge volumio[599]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 22 16:19:07 volumio-usbridge volumio[599]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 22 16:19:07 volumio-usbridge volumio[599]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 22 16:19:07 volumio-usbridge volumio[599]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 22 16:19:07 volumio-usbridge volumio[599]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jul 22 16:19:07 volumio-usbridge volumio[599]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 22 16:19:07 volumio-usbridge volumio[599]: info: VolumeController:: Volume=100 Mute =false Jul 22 16:19:07 volumio-usbridge volumio[599]: info: CoreStateMachine::pushState Jul 22 16:19:07 volumio-usbridge volumio[599]: info: CorePlayQueue::getTrack 43 Jul 22 16:19:07 volumio-usbridge volumio[599]: info: CoreCommandRouter::volumioPushState Jul 22 16:19:07 volumio-usbridge volumio[599]: info: MRS: Pushing multiroomSync output update for this device Jul 22 16:19:07 volumio-usbridge volumio[599]: info: MRS: Pushing multiroomSync output Jul 22 16:19:07 volumio-usbridge volumio[599]: error: Cannot write /etc/asound.conf: Error: EROFS: read-only file system, open '/home/volumio/.asoundrc' Jul 22 16:19:07 volumio-usbridge volumio[599]: info: Output device has changed, restarting MPD Jul 22 16:19:07 volumio-usbridge sudo[10133]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 22 16:19:07 volumio-usbridge sudo[10133]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 16:19:07 volumio-usbridge volumio[599]: info: Output device has changed, restarting Shairport Sync Jul 22 16:19:07 volumio-usbridge volumio[599]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 22 16:19:07 volumio-usbridge volumio[599]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 22 16:19:07 volumio-usbridge sudo[10133]: pam_unix(sudo:session): session closed for user root Jul 22 16:19:07 volumio-usbridge sudo[10136]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 22 16:19:07 volumio-usbridge sudo[10136]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 16:19:07 volumio-usbridge systemd[1]: Stopping Music Player Daemon... Jul 22 16:19:07 volumio-usbridge volumio[599]: xcb_connection_has_error() returned true Jul 22 16:19:07 volumio-usbridge volumio[599]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 22 16:19:07 volumio-usbridge volumio[599]: xcb_connection_has_error() returned true Jul 22 16:19:07 volumio-usbridge systemd[1]: mpd.service: Succeeded. Jul 22 16:19:07 volumio-usbridge systemd[1]: Stopped Music Player Daemon. Jul 22 16:19:07 volumio-usbridge volumio[599]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jul 22 16:19:07 volumio-usbridge volumio[599]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 22 16:19:07 volumio-usbridge systemd[1]: Starting Music Player Daemon... Jul 22 16:19:07 volumio-usbridge sudo[10145]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jul 22 16:19:07 volumio-usbridge sudo[10145]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 16:19:07 volumio-usbridge volumio[599]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Jul 22 16:19:07 volumio-usbridge volumio[599]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 22 16:19:08 volumio-usbridge volumio[599]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 22 16:19:08 volumio-usbridge volumio[599]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 22 16:19:08 volumio-usbridge volumio[599]: info: Error setting mpd conf file perms: Error: Command failed: /usr/bin/sudo /bin/chmod 777 /etc/mpd.conf Jul 22 16:19:08 volumio-usbridge volumio[599]: /bin/chmod: changing permissions of '/etc/mpd.conf': Read-only file system Jul 22 16:19:08 volumio-usbridge sudo[10147]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jul 22 16:19:08 volumio-usbridge sudo[10147]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 16:19:08 volumio-usbridge volumio[599]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 22 16:19:08 volumio-usbridge sudo[10145]: pam_unix(sudo:session): session closed for user root Jul 22 16:19:08 volumio-usbridge volumio[599]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 22 16:19:08 volumio-usbridge volumio[599]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 22 16:19:08 volumio-usbridge sudo[10147]: pam_unix(sudo:session): session closed for user root Jul 22 16:19:08 volumio-usbridge volumio[599]: info: Could not write mpd.conf:Error: EROFS: read-only file system, open '/etc/mpd.conf' Jul 22 16:19:08 volumio-usbridge volumio[599]: info: Starting Shairport Sync Jul 22 16:19:08 volumio-usbridge volumio[599]: Configuration write error: Error: EROFS: read-only file system, open '/data/configuration/audio_interface/alsa_controller/config.json' Jul 22 16:19:08 volumio-usbridge sudo[10158]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 22 16:19:08 volumio-usbridge sudo[10158]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 16:19:08 volumio-usbridge systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 22 16:19:08 volumio-usbridge volumio[599]: ------------------------------------ BT MESSAGE: BT STATUS: running Jul 22 16:19:08 volumio-usbridge systemd[1]: shairport-sync.service: Succeeded. Jul 22 16:19:08 volumio-usbridge systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 22 16:19:08 volumio-usbridge volumio[599]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jul 22 16:19:08 volumio-usbridge systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 22 16:19:08 volumio-usbridge sudo[10158]: pam_unix(sudo:session): session closed for user root Jul 22 16:19:08 volumio-usbridge volumio[599]: info: Shairport-Sync Started Jul 22 16:19:09 volumio-usbridge mpd[10160]: Jul 22 16:19 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 22 16:19:09 volumio-usbridge mpd[10160]: Jul 22 16:19 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jul 22 16:19:09 volumio-usbridge mpd[10160]: Jul 22 16:19 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jul 22 16:19:09 volumio-usbridge systemd[1]: Started Music Player Daemon. Jul 22 16:19:09 volumio-usbridge sudo[10136]: pam_unix(sudo:session): session closed for user root Jul 22 16:19:09 volumio-usbridge volumio[599]: error: updateQueue error: null Jul 22 16:19:10 volumio-usbridge volumio[599]: info: TidalConnect service stoped! Jul 22 16:19:10 volumio-usbridge volumio[599]: info: TidalConnect service stoped! Jul 22 16:19:10 volumio-usbridge volumio[599]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jul 22 16:19:10 volumio-usbridge volumio[599]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jul 22 16:19:10 volumio-usbridge sudo[10175]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jul 22 16:19:10 volumio-usbridge sudo[10175]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 16:19:10 volumio-usbridge systemd[1]: Started Volumio Tidal Connect Service. Jul 22 16:19:10 volumio-usbridge sudo[10175]: pam_unix(sudo:session): session closed for user root Jul 22 16:19:11 volumio-usbridge volumio[599]: info: Executing endpoint tc_getconfig Jul 22 16:19:11 volumio-usbridge volumio[599]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jul 22 16:19:11 volumio-usbridge vtcs[10177]: STARTING TidalConnect services, version: 1.1.7.5 Jul 22 16:19:11 volumio-usbridge vtcs[10177]: STARTED TidalConnect services. Jul 22 16:19:11 volumio-usbridge volumio[599]: info: Executing endpoint tc_connect Jul 22 16:19:11 volumio-usbridge volumio[599]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jul 22 16:19:11 volumio-usbridge volumio[599]: info: Connecting to TidalConnect Jul 22 16:19:11 volumio-usbridge volumio[599]: info: CoreCommandRouter::servicePushState Jul 22 16:19:11 volumio-usbridge volumio[599]: info: CoreStateMachine::pushState Jul 22 16:19:11 volumio-usbridge volumio[599]: info: CorePlayQueue::getTrack 43 Jul 22 16:19:11 volumio-usbridge volumio[599]: info: CoreCommandRouter::volumioPushState Jul 22 16:19:11 volumio-usbridge volumio[599]: info: MRS: Pushing multiroomSync output update for this device Jul 22 16:19:11 volumio-usbridge volumio[599]: info: MRS: Pushing multiroomSync output Jul 22 16:19:11 volumio-usbridge volumio[599]: info: CorePlayQueue::getTrack 43 Jul 22 16:19:11 volumio-usbridge volumio[599]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Jul 22 16:19:11 volumio-usbridge volumio[599]: verbose: CURRENT POSITION 43 Jul 22 16:19:11 volumio-usbridge volumio[599]: info: CoreStateMachine::syncState stateService stop Jul 22 16:19:11 volumio-usbridge volumio[599]: info: CoreStateMachine::syncState currentStatus stop Jul 22 16:19:11 volumio-usbridge volumio[599]: info: CoreStateMachine::pushState Jul 22 16:19:11 volumio-usbridge volumio[599]: info: CorePlayQueue::getTrack 43 Jul 22 16:19:11 volumio-usbridge volumio[599]: info: CoreCommandRouter::volumioPushState Jul 22 16:19:11 volumio-usbridge volumio[599]: info: MRS: Pushing multiroomSync output update for this device Jul 22 16:19:11 volumio-usbridge volumio[599]: info: MRS: Pushing multiroomSync output Jul 22 16:19:11 volumio-usbridge volumio[599]: info: No code Jul 22 16:19:11 volumio-usbridge volumio[599]: info: CoreStateMachine::pushState Jul 22 16:19:11 volumio-usbridge volumio[599]: info: CorePlayQueue::getTrack 43 Jul 22 16:19:11 volumio-usbridge volumio[599]: info: CoreCommandRouter::volumioPushState Jul 22 16:19:11 volumio-usbridge volumio[599]: info: MRS: Pushing multiroomSync output update for this device Jul 22 16:19:11 volumio-usbridge volumio[599]: info: MRS: Pushing multiroomSync output Jul 22 16:19:11 volumio-usbridge volumio[599]: info: CoreCommandRouter::servicePushState Jul 22 16:19:11 volumio-usbridge volumio[599]: info: CoreStateMachine::pushState Jul 22 16:19:11 volumio-usbridge volumio[599]: info: CorePlayQueue::getTrack 43 Jul 22 16:19:11 volumio-usbridge volumio[599]: info: CoreCommandRouter::volumioPushState Jul 22 16:19:11 volumio-usbridge volumio[599]: info: MRS: Pushing multiroomSync output update for this device Jul 22 16:19:11 volumio-usbridge volumio[599]: info: MRS: Pushing multiroomSync output Jul 22 16:19:11 volumio-usbridge volumio[599]: info: CorePlayQueue::getTrack 43 Jul 22 16:19:11 volumio-usbridge volumio[599]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Jul 22 16:19:11 volumio-usbridge volumio[599]: verbose: CURRENT POSITION 43 Jul 22 16:19:11 volumio-usbridge volumio[599]: info: CoreStateMachine::syncState stateService stop Jul 22 16:19:11 volumio-usbridge volumio[599]: info: CoreStateMachine::syncState currentStatus stop Jul 22 16:19:11 volumio-usbridge volumio[599]: info: CoreStateMachine::pushState Jul 22 16:19:11 volumio-usbridge volumio[599]: info: CorePlayQueue::getTrack 43 Jul 22 16:19:11 volumio-usbridge volumio[599]: info: CoreCommandRouter::volumioPushState Jul 22 16:19:11 volumio-usbridge volumio[599]: info: MRS: Pushing multiroomSync output update for this device Jul 22 16:19:11 volumio-usbridge volumio[599]: info: MRS: Pushing multiroomSync output Jul 22 16:19:11 volumio-usbridge volumio[599]: info: No code Jul 22 16:19:11 volumio-usbridge volumio[599]: info: CoreStateMachine::pushState Jul 22 16:19:11 volumio-usbridge volumio[599]: info: CorePlayQueue::getTrack 43 Jul 22 16:19:11 volumio-usbridge volumio[599]: info: CoreCommandRouter::volumioPushState Jul 22 16:19:11 volumio-usbridge volumio[599]: info: MRS: Pushing multiroomSync output update for this device Jul 22 16:19:11 volumio-usbridge volumio[599]: info: MRS: Pushing multiroomSync output Jul 22 16:19:11 volumio-usbridge volumio[599]: info: TidalConnect service stoped! Jul 22 16:19:11 volumio-usbridge volumio[599]: info: TidalConnect service stoped! Jul 22 16:19:11 volumio-usbridge volumio[599]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jul 22 16:19:11 volumio-usbridge volumio[599]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jul 22 16:19:11 volumio-usbridge sudo[10204]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jul 22 16:19:11 volumio-usbridge sudo[10204]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 16:19:11 volumio-usbridge sudo[10204]: pam_unix(sudo:session): session closed for user root Jul 22 16:19:11 volumio-usbridge volumio[599]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jul 22 16:19:11 volumio-usbridge volumio[599]: info: CoreStateMachine::getcurrentVolume Jul 22 16:19:11 volumio-usbridge volumio[599]: info: CoreCommandRouter::volumioRetrievevolume Jul 22 16:19:11 volumio-usbridge volumio[599]: info: VolumeController:: Volume=100 Mute =false Jul 22 16:19:11 volumio-usbridge volumio[599]: info: CoreStateMachine::pushState Jul 22 16:19:11 volumio-usbridge volumio[599]: info: CorePlayQueue::getTrack 43 Jul 22 16:19:11 volumio-usbridge volumio[599]: info: CoreCommandRouter::volumioPushState Jul 22 16:19:11 volumio-usbridge volumio[599]: info: MRS: Pushing multiroomSync output update for this device Jul 22 16:19:11 volumio-usbridge volumio[599]: info: MRS: Pushing multiroomSync output Jul 22 16:19:11 volumio-usbridge volumio[599]: info: CoreStateMachine::updateTrackBlock Jul 22 16:19:11 volumio-usbridge volumio[599]: info: CorePlayQueue::getTrackBlock Jul 22 16:19:11 volumio-usbridge volumio[599]: info: CoreCommandRouter::volumioRetrievevolume Jul 22 16:19:11 volumio-usbridge volumio[599]: info: VolumeController:: Volume=100 Mute =false Jul 22 16:19:11 volumio-usbridge volumio[599]: info: CoreStateMachine::pushState Jul 22 16:19:11 volumio-usbridge volumio[599]: info: CorePlayQueue::getTrack 43 Jul 22 16:19:11 volumio-usbridge volumio[599]: info: CoreCommandRouter::volumioPushState Jul 22 16:19:11 volumio-usbridge volumio[599]: info: MRS: Pushing multiroomSync output update for this device Jul 22 16:19:11 volumio-usbridge volumio[599]: info: MRS: Pushing multiroomSync output Jul 22 16:19:13 volumio-usbridge volumio[599]: info: TidalConnect service started! Jul 22 16:19:14 volumio-usbridge volumio[599]: info: TidalConnect service started! Jul 22 16:19:20 volumio-usbridge nmbd[547]: [2024/07/22 16:19:20.594445, 0] ../source3/nmbd/nmbd_serverlistdb.c:317(write_browse_list) Jul 22 16:19:20 volumio-usbridge nmbd[547]: write_browse_list: Can't open file /var/cache/samba/browse.dat.: Input/output error Jul 22 16:19:20 volumio-usbridge kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1536: inode #917584: comm nmbd: reading directory lblock 0 Jul 22 16:19:20 volumio-usbridge kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1536: inode #917542: comm nmbd: reading directory lblock 0 Jul 22 16:19:20 volumio-usbridge nmbd[10211]: [2024/07/22 16:19:20.614245, 0] ../source3/nmbd/nmbd_winsserver.c:2508(wins_write_database) Jul 22 16:19:20 volumio-usbridge nmbd[10211]: wins_write_database: Can't open /var/lib/samba/wins.dat.10211: Input/output error Jul 22 16:19:20 volumio-usbridge kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1536: inode #917584: comm nmbd: reading directory lblock 0 Jul 22 16:19:20 volumio-usbridge nmbd[547]: [2024/07/22 16:19:20.634465, 0] ../source3/nmbd/nmbd_serverlistdb.c:317(write_browse_list) Jul 22 16:19:20 volumio-usbridge nmbd[547]: write_browse_list: Can't open file /var/cache/samba/browse.dat.: Input/output error Jul 22 16:19:20 volumio-usbridge kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1536: inode #917544: comm lpqd: reading directory lblock 0 Jul 22 16:19:20 volumio-usbridge kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1536: inode #917544: comm lpqd: reading directory lblock 0 Jul 22 16:19:20 volumio-usbridge smbd[10212]: [2024/07/22 16:19:20.698462, 0] ../source3/lib/util.c:493(reinit_after_fork) Jul 22 16:19:20 volumio-usbridge smbd[10212]: messaging_reinit() failed: NT_STATUS_IO_DEVICE_ERROR Jul 22 16:19:20 volumio-usbridge smbd[10212]: [2024/07/22 16:19:20.698969, 0] ../source3/printing/print_cups.c:486(cups_pcap_load_async) Jul 22 16:19:20 volumio-usbridge smbd[10212]: cups_pcap_load_async: reinit_after_fork() failed Jul 22 16:19:20 volumio-usbridge smbd[10212]: [2024/07/22 16:19:20.699098, 0] ../source3/lib/util.c:816(smb_panic_s3) Jul 22 16:19:20 volumio-usbridge smbd[10212]: PANIC (pid 10212): cups_pcap_load_async: reinit_after_fork() failed Jul 22 16:19:20 volumio-usbridge smbd[10212]: [2024/07/22 16:19:20.702664, 0] ../lib/util/fault.c:261(log_stack_trace) Jul 22 16:19:20 volumio-usbridge smbd[10212]: BACKTRACE: 0 stack frames: Jul 22 16:19:20 volumio-usbridge smbd[10212]: [2024/07/22 16:19:20.702957, 0] ../source3/lib/dumpcore.c:315(dump_core) Jul 22 16:19:20 volumio-usbridge smbd[10212]: dumping core in /var/log/samba/cores/smbd Jul 22 16:19:20 volumio-usbridge smbd[10212]: Jul 22 16:19:20 volumio-usbridge smbd[737]: [2024/07/22 16:19:20.717969, 0] ../source3/printing/print_cups.c:524(cups_async_callback) Jul 22 16:19:20 volumio-usbridge smbd[737]: failed to recv pcap blob Jul 22 16:19:40 volumio-usbridge nmbd[547]: [2024/07/22 16:19:40.671553, 0] ../source3/nmbd/nmbd_serverlistdb.c:317(write_browse_list) Jul 22 16:19:40 volumio-usbridge nmbd[547]: write_browse_list: Can't open file /var/cache/samba/browse.dat.: Input/output error Jul 22 16:19:40 volumio-usbridge kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1536: inode #917584: comm nmbd: reading directory lblock 0 Jul 22 16:19:40 volumio-usbridge kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1536: inode #917542: comm nmbd: reading directory lblock 0 Jul 22 16:19:40 volumio-usbridge nmbd[10223]: [2024/07/22 16:19:40.691348, 0] ../source3/nmbd/nmbd_winsserver.c:2508(wins_write_database) Jul 22 16:19:40 volumio-usbridge nmbd[10223]: wins_write_database: Can't open /var/lib/samba/wins.dat.10223: Input/output error Jul 22 16:19:40 volumio-usbridge kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1536: inode #917584: comm nmbd: reading directory lblock 0 Jul 22 16:19:40 volumio-usbridge nmbd[547]: [2024/07/22 16:19:40.711525, 0] ../source3/nmbd/nmbd_serverlistdb.c:317(write_browse_list) Jul 22 16:19:40 volumio-usbridge nmbd[547]: write_browse_list: Can't open file /var/cache/samba/browse.dat.: Input/output error Jul 22 16:19:48 volumio-usbridge volumio[599]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 22 16:19:48 volumio-usbridge volumio[599]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 22 16:19:48 volumio-usbridge volumio[599]: info: Discovery: Getting this device information Jul 22 16:19:48 volumio-usbridge volumio[599]: info: CoreCommandRouter::volumioGetState Jul 22 16:19:48 volumio-usbridge volumio[599]: info: CorePlayQueue::getTrack 43 Jul 22 16:19:48 volumio-usbridge volumio[599]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 22 16:19:48 volumio-usbridge volumio[599]: verbose: New Socket.io Connection to 10.0.0.130 from 10.0.0.124 UA: okhttp/4.9.2 Total Clients: 5 Jul 22 16:19:48 volumio-usbridge volumio[599]: info: CoreCommandRouter::volumioGetState Jul 22 16:19:48 volumio-usbridge volumio[599]: info: CorePlayQueue::getTrack 43 Jul 22 16:19:48 volumio-usbridge volumio[599]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jul 22 16:19:48 volumio-usbridge volumio[599]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jul 22 16:20:00 volumio-usbridge kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1536: inode #917584: comm nmbd: reading directory lblock 0 Jul 22 16:20:00 volumio-usbridge nmbd[547]: [2024/07/22 16:20:00.750469, 0] ../source3/nmbd/nmbd_serverlistdb.c:317(write_browse_list) Jul 22 16:20:00 volumio-usbridge nmbd[547]: write_browse_list: Can't open file /var/cache/samba/browse.dat.: Input/output error Jul 22 16:20:00 volumio-usbridge kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1536: inode #917542: comm nmbd: reading directory lblock 0 Jul 22 16:20:00 volumio-usbridge nmbd[10224]: [2024/07/22 16:20:00.770308, 0] ../source3/nmbd/nmbd_winsserver.c:2508(wins_write_database) Jul 22 16:20:00 volumio-usbridge nmbd[10224]: wins_write_database: Can't open /var/lib/samba/wins.dat.10224: Input/output error Jul 22 16:20:00 volumio-usbridge kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1536: inode #917584: comm nmbd: reading directory lblock 0 Jul 22 16:20:00 volumio-usbridge nmbd[547]: [2024/07/22 16:20:00.790488, 0] ../source3/nmbd/nmbd_serverlistdb.c:317(write_browse_list) Jul 22 16:20:00 volumio-usbridge nmbd[547]: write_browse_list: Can't open file /var/cache/samba/browse.dat.: Input/output error Jul 22 16:20:20 volumio-usbridge nmbd[547]: [2024/07/22 16:20:20.827599, 0] ../source3/nmbd/nmbd_serverlistdb.c:317(write_browse_list) Jul 22 16:20:20 volumio-usbridge kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1536: inode #917584: comm nmbd: reading directory lblock 0 Jul 22 16:20:20 volumio-usbridge kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1536: inode #917542: comm nmbd: reading directory lblock 0 Jul 22 16:20:20 volumio-usbridge nmbd[547]: write_browse_list: Can't open file /var/cache/samba/browse.dat.: Input/output error Jul 22 16:20:20 volumio-usbridge nmbd[10225]: [2024/07/22 16:20:20.847456, 0] ../source3/nmbd/nmbd_winsserver.c:2508(wins_write_database) Jul 22 16:20:20 volumio-usbridge nmbd[10225]: wins_write_database: Can't open /var/lib/samba/wins.dat.10225: Input/output error Jul 22 16:20:20 volumio-usbridge nmbd[547]: [2024/07/22 16:20:20.867703, 0] ../source3/nmbd/nmbd_serverlistdb.c:317(write_browse_list) Jul 22 16:20:20 volumio-usbridge nmbd[547]: write_browse_list: Can't open file /var/cache/samba/browse.dat.: Input/output error Jul 22 16:20:20 volumio-usbridge kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1536: inode #917584: comm nmbd: reading directory lblock 0 Jul 22 16:20:40 volumio-usbridge sudo[10227]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 22 16:20:40 volumio-usbridge sudo[10227]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 16:20:40 volumio-usbridge sudo[10227]: pam_unix(sudo:session): session closed for user root Jul 22 16:20:40 volumio-usbridge sudo[10229]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 22 16:20:40 volumio-usbridge sudo[10229]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 22 16:20:40 volumio-usbridge kernel: EXT4-fs error (device mmcblk0p2): ext4_journal_check_start:83: Detected aborted journal Jul 22 16:20:40 volumio-usbridge kernel: EXT4-fs (mmcblk0p2): Remounting filesystem read-only Jul 22 16:20:40 volumio-usbridge sudo[10229]: pam_unix(sudo:session): session closed for user root Jul 22 16:20:40 volumio-usbridge volumio[599]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 22 16:20:40 volumio-usbridge volumio[599]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 22 16:20:40 volumio-usbridge volumio[599]: info: Discovery: Getting this device information Jul 22 16:20:40 volumio-usbridge volumio[599]: info: CoreCommandRouter::volumioGetState Jul 22 16:20:40 volumio-usbridge volumio[599]: info: CorePlayQueue::getTrack 43 Jul 22 16:20:40 volumio-usbridge volumio[599]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 22 16:20:40 volumio-usbridge volumio[599]: verbose: New Socket.io Connection to 10.0.0.130 from 10.0.0.124 UA: okhttp/4.9.2 Total Clients: 6 Jul 22 16:20:40 volumio-usbridge volumio[599]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jul 22 16:20:40 volumio-usbridge volumio[599]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 22 16:20:40 volumio-usbridge volumio[599]: info: CoreCommandRouter::volumioGetVisibleSources Jul 22 16:20:40 volumio-usbridge volumio[599]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 22 16:20:40 volumio-usbridge volumio[599]: info: CoreCommandRouter::volumioGetState Jul 22 16:20:40 volumio-usbridge volumio[599]: info: CorePlayQueue::getTrack 43 Jul 22 16:20:40 volumio-usbridge volumio[599]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 22 16:20:40 volumio-usbridge volumio[599]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 22 16:20:40 volumio-usbridge volumio[599]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jul 22 16:20:40 volumio-usbridge volumio[599]: info: Received Get System Info Jul 22 16:20:40 volumio-usbridge volumio[599]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 22 16:20:40 volumio-usbridge volumio[599]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 22 16:20:40 volumio-usbridge volumio[599]: info: Discovery: Getting this device information Jul 22 16:20:40 volumio-usbridge volumio[599]: info: CoreCommandRouter::volumioGetState Jul 22 16:20:40 volumio-usbridge volumio[599]: info: CorePlayQueue::getTrack 43 Jul 22 16:20:40 volumio-usbridge volumio[599]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 22 16:20:40 volumio-usbridge volumio[599]: info: CoreCommandRouter::volumioGetState Jul 22 16:20:40 volumio-usbridge volumio[599]: info: CorePlayQueue::getTrack 43 Jul 22 16:20:40 volumio-usbridge nmbd[547]: [2024/07/22 16:20:40.904989, 0] ../source3/nmbd/nmbd_serverlistdb.c:317(write_browse_list) Jul 22 16:20:40 volumio-usbridge nmbd[547]: write_browse_list: Can't open file /var/cache/samba/browse.dat.: Input/output error Jul 22 16:20:40 volumio-usbridge kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1536: inode #917584: comm nmbd: reading directory lblock 0 Jul 22 16:20:40 volumio-usbridge kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1536: inode #917542: comm nmbd: reading directory lblock 0 Jul 22 16:20:40 volumio-usbridge kernel: EXT4-fs warning (device mmcblk0p3): htree_dirblock_to_tree:1003: inode #917599: lblock 0: comm node: error -5 reading directory block Jul 22 16:20:40 volumio-usbridge nmbd[10234]: [2024/07/22 16:20:40.925298, 0] ../source3/nmbd/nmbd_winsserver.c:2508(wins_write_database) Jul 22 16:20:40 volumio-usbridge nmbd[10234]: wins_write_database: Can't open /var/lib/samba/wins.dat.10234: Input/output error Jul 22 16:20:40 volumio-usbridge volumio[599]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 22 16:20:40 volumio-usbridge kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1536: inode #917584: comm nmbd: reading directory lblock 0 Jul 22 16:20:40 volumio-usbridge volumio[599]: Error: EIO: i/o error, scandir '/data/plugins/' Jul 22 16:20:40 volumio-usbridge volumio[599]: at Object.readdirSync (fs.js:1021:3) Jul 22 16:20:40 volumio-usbridge volumio[599]: at PluginManager.getInstalledPlugins (/volumio/app/pluginmanager.js:1627:30) Jul 22 16:20:40 volumio-usbridge volumio[599]: at CoreCommandRouter.getInstalledPlugins (/volumio/app/index.js:1509:29) Jul 22 16:20:40 volumio-usbridge volumio[599]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1300:45) Jul 22 16:20:40 volumio-usbridge volumio[599]: at Socket.emit (events.js:315:20) Jul 22 16:20:40 volumio-usbridge volumio[599]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Jul 22 16:20:40 volumio-usbridge volumio[599]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Jul 22 16:20:40 volumio-usbridge volumio[599]: errno: -5, Jul 22 16:20:40 volumio-usbridge volumio[599]: syscall: 'scandir', Jul 22 16:20:40 volumio-usbridge volumio[599]: code: 'EIO', Jul 22 16:20:40 volumio-usbridge volumio[599]: path: '/data/plugins/' Jul 22 16:20:40 volumio-usbridge volumio[599]: } Jul 22 16:20:40 volumio-usbridge volumio[599]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 22 16:20:40 volumio-usbridge nmbd[547]: [2024/07/22 16:20:40.945981, 0] ../source3/nmbd/nmbd_serverlistdb.c:317(write_browse_list) Jul 22 16:20:40 volumio-usbridge nmbd[547]: write_browse_list: Can't open file /var/cache/samba/browse.dat.: Input/output error Jul 22 16:20:41 volumio-usbridge sudo[10244]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-22 16:19 Jul 22 16:20:41 volumio-usbridge sudo[10244]: 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="b1c3cf61c2a0027c66bab1eb0a3795f80c1f2e95" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="e3eb3ee5b16063502f3a1735e11a28cfe54a0f46" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun 25 Jun 2023 07:20:58 PM CEST" VOLUMIO_VERSION="3.512" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="946a42b62509f37b5e7e2d86a2932a62"