-- Logs begin at Sun 2024-12-01 15:38:56 CST, end at Sun 2024-12-01 15:47:41 CST. -- Dec 01 15:46:00 volumio volumio[1259]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Dec 01 15:46:00 volumio volumio[1259]: info: Retrying Login Due to Network Error Dec 01 15:46:00 volumio volumio[1259]: verbose: New Socket.io Connection to 192.168.1.105 from 192.168.1.102 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 8 Dec 01 15:46:07 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 01 15:46:07 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 01 15:46:07 volumio volumio[1259]: info: Discovery: Getting this device information Dec 01 15:46:07 volumio volumio[1259]: info: CoreCommandRouter::volumioGetState Dec 01 15:46:07 volumio volumio[1259]: info: CorePlayQueue::getTrack 0 Dec 01 15:46:07 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 01 15:46:09 volumio volumio[1259]: verbose: New Socket.io Connection to 192.168.1.105 from 192.168.1.102 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 8 Dec 01 15:46:10 volumio volumio[1259]: info: MyVolumio login type: Token Dec 01 15:46:10 volumio volumio[1259]: verbose: New Socket.io Connection to 192.168.1.105 from 192.168.1.102 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 9 Dec 01 15:46:11 volumio volumio[1259]: verbose: New Socket.io Connection to 192.168.1.105 from 192.168.1.102 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 10 Dec 01 15:46:13 volumio volumio[1259]: verbose: New Socket.io Connection to 192.168.1.105 from 192.168.1.102 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 11 Dec 01 15:46:13 volumio volumio[1259]: info: CoreCommandRouter::volumioGetState Dec 01 15:46:13 volumio volumio[1259]: info: CorePlayQueue::getTrack 0 Dec 01 15:46:13 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 01 15:46:13 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 01 15:46:17 volumio sudo[2886]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 01 15:46:17 volumio sudo[2886]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 15:46:17 volumio sudo[2886]: pam_unix(sudo:session): session closed for user root Dec 01 15:46:17 volumio sudo[2889]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 01 15:46:17 volumio sudo[2889]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 15:46:17 volumio sudo[2889]: pam_unix(sudo:session): session closed for user root Dec 01 15:46:17 volumio volumio[1259]: verbose: New Socket.io Connection to 192.168.1.105 from 192.168.1.102 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 12 Dec 01 15:46:18 volumio volumio[1259]: info: CoreCommandRouter::volumioGetState Dec 01 15:46:18 volumio volumio[1259]: info: CorePlayQueue::getTrack 0 Dec 01 15:46:18 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 01 15:46:18 volumio volumio[1259]: info: Listing playlists Dec 01 15:46:18 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 01 15:46:18 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 01 15:46:18 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 01 15:46:18 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 01 15:46:19 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 01 15:46:19 volumio volumio[1259]: info: Received Get System Info Dec 01 15:46:19 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 01 15:46:19 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 01 15:46:19 volumio volumio[1259]: info: Discovery: Getting this device information Dec 01 15:46:19 volumio volumio[1259]: info: CoreCommandRouter::volumioGetState Dec 01 15:46:19 volumio volumio[1259]: info: CorePlayQueue::getTrack 0 Dec 01 15:46:19 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 01 15:46:20 volumio volumio[1259]: info: CoreCommandRouter::volumioGetVisibleSources Dec 01 15:46:20 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 01 15:46:20 volumio volumio[1259]: info: CoreCommandRouter::volumioGetQueue Dec 01 15:46:20 volumio volumio[1259]: info: CoreStateMachine::getQueue Dec 01 15:46:20 volumio volumio[1259]: info: CorePlayQueue::getQueue Dec 01 15:46:22 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 01 15:46:22 volumio volumio[1259]: info: CURURI: music-library Dec 01 15:46:22 volumio volumio[1259]: info: Preload queue cleared Dec 01 15:46:25 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 01 15:46:25 volumio volumio[1259]: info: CURURI: music-library/USB Dec 01 15:46:25 volumio volumio[1259]: info: Preload queue cleared Dec 01 15:46:32 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 01 15:46:32 volumio volumio[1259]: info: CURURI: playlists Dec 01 15:46:32 volumio volumio[1259]: info: Listing playlists Dec 01 15:46:32 volumio volumio[1259]: info: Preload queue cleared Dec 01 15:46:37 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 01 15:46:37 volumio volumio[1259]: info: CURURI: music-library Dec 01 15:46:37 volumio volumio[1259]: info: Preload queue cleared Dec 01 15:46:40 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Dec 01 15:46:40 volumio volumio[1259]: verbose: ControllerMpd::sendMpdCommand update Dec 01 15:46:40 volumio volumio[1259]: verbose: ControllerMpd::sendMpdCommand status Dec 01 15:46:40 volumio volumio[1259]: info: Dec 01 15:46:40 volumio volumio[1259]: ---------------------------- MPD announces state update: update Dec 01 15:46:40 volumio volumio[1259]: info: sendMpdCommand update took 9 milliseconds Dec 01 15:46:40 volumio volumio[1259]: info: ControllerMpd::getState Dec 01 15:46:40 volumio volumio[1259]: verbose: ControllerMpd::sendMpdCommand status Dec 01 15:46:40 volumio volumio[1259]: info: Dec 01 15:46:40 volumio volumio[1259]: ---------------------------- MPD announces state update: update Dec 01 15:46:40 volumio volumio[1259]: info: ControllerMpd::getState Dec 01 15:46:40 volumio volumio[1259]: verbose: ControllerMpd::sendMpdCommand status Dec 01 15:46:40 volumio volumio[1259]: verbose: ControllerMpd::sendMpdCommand status Dec 01 15:46:40 volumio volumio[1259]: info: Dec 01 15:46:40 volumio volumio[1259]: ---------------------------- MPD announces state update: update Dec 01 15:46:40 volumio volumio[1259]: info: ControllerMpd::getState Dec 01 15:46:40 volumio volumio[1259]: verbose: ControllerMpd::sendMpdCommand status Dec 01 15:46:40 volumio volumio[1259]: verbose: ControllerMpd::sendMpdCommand status Dec 01 15:46:40 volumio volumio[1259]: info: Dec 01 15:46:40 volumio volumio[1259]: ---------------------------- MPD announces state update: update Dec 01 15:46:40 volumio volumio[1259]: info: ControllerMpd::getState Dec 01 15:46:40 volumio volumio[1259]: verbose: ControllerMpd::sendMpdCommand status Dec 01 15:46:40 volumio volumio[1259]: info: sendMpdCommand status took 23 milliseconds Dec 01 15:46:40 volumio volumio[1259]: info: sendMpdCommand status took 20 milliseconds Dec 01 15:46:40 volumio volumio[1259]: info: sendMpdCommand status took 20 milliseconds Dec 01 15:46:40 volumio volumio[1259]: info: sendMpdCommand status took 17 milliseconds Dec 01 15:46:40 volumio volumio[1259]: info: sendMpdCommand status took 15 milliseconds Dec 01 15:46:40 volumio volumio[1259]: info: sendMpdCommand status took 13 milliseconds Dec 01 15:46:40 volumio volumio[1259]: info: sendMpdCommand status took 12 milliseconds Dec 01 15:46:40 volumio volumio[1259]: verbose: ControllerMpd::parseState Dec 01 15:46:40 volumio volumio[1259]: info: Command Router : Notfying DB Updatetrue Dec 01 15:46:40 volumio volumio[1259]: verbose: ControllerMpd::parseState Dec 01 15:46:40 volumio volumio[1259]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 15:46:40 volumio volumio[1259]: verbose: ControllerMpd::parseState Dec 01 15:46:40 volumio volumio[1259]: info: Command Router : Notfying DB Updatetrue Dec 01 15:46:40 volumio volumio[1259]: info: CoreCommandRouter::Close All Modals sent Dec 01 15:46:40 volumio volumio[1259]: verbose: ControllerMpd::parseState Dec 01 15:46:40 volumio volumio[1259]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 15:46:40 volumio volumio[1259]: verbose: ControllerMpd::parseState Dec 01 15:46:40 volumio volumio[1259]: info: Command Router : Notfying DB Updatetrue Dec 01 15:46:40 volumio volumio[1259]: info: CoreCommandRouter::Close All Modals sent Dec 01 15:46:40 volumio volumio[1259]: verbose: ControllerMpd::parseState Dec 01 15:46:40 volumio volumio[1259]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 15:46:40 volumio volumio[1259]: verbose: ControllerMpd::parseState Dec 01 15:46:40 volumio volumio[1259]: info: Command Router : Notfying DB Updatetrue Dec 01 15:46:40 volumio volumio[1259]: info: CoreCommandRouter::Close All Modals sent Dec 01 15:46:40 volumio volumio[1259]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Dec 01 15:46:40 volumio volumio[1259]: info: Retrying Login Due to Network Error Dec 01 15:46:40 volumio volumio[1259]: info: sendMpdCommand status took 654 milliseconds Dec 01 15:46:40 volumio volumio[1259]: info: sendMpdCommand playlistinfo took 429 milliseconds Dec 01 15:46:40 volumio volumio[1259]: info: sendMpdCommand playlistinfo took 327 milliseconds Dec 01 15:46:40 volumio volumio[1259]: info: sendMpdCommand playlistinfo took 270 milliseconds Dec 01 15:46:40 volumio volumio[1259]: verbose: ControllerMpd::parseState Dec 01 15:46:40 volumio volumio[1259]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 01 15:46:40 volumio volumio[1259]: verbose: ControllerMpd::parseTrackInfo Dec 01 15:46:40 volumio volumio[1259]: verbose: ControllerMpd::parseTrackInfo Dec 01 15:46:40 volumio volumio[1259]: verbose: ControllerMpd::parseTrackInfo Dec 01 15:46:40 volumio volumio[1259]: info: ControllerMpd::pushState Dec 01 15:46:40 volumio volumio[1259]: info: CoreCommandRouter::servicePushState Dec 01 15:46:40 volumio volumio[1259]: info: CorePlayQueue::getTrack 0 Dec 01 15:46:40 volumio volumio[1259]: verbose: STATE SERVICE {"status":"play","position":0,"seek":215985,"duration":255,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"632 Kbps","isStreaming":false,"title":"恰似你的温柔","artist":"蔡琴","album":"精选蔡琴","uri":"USB/蔡琴 - 恰似你的温柔.flac","trackType":"flac"} Dec 01 15:46:40 volumio volumio[1259]: verbose: CURRENT POSITION 0 Dec 01 15:46:40 volumio volumio[1259]: info: CoreStateMachine::syncState stateService play Dec 01 15:46:40 volumio volumio[1259]: info: CoreStateMachine::syncState currentStatus play Dec 01 15:46:40 volumio volumio[1259]: info: Received an update from plugin. extracting info from payload Dec 01 15:46:40 volumio volumio[1259]: info: CoreStateMachine::pushState Dec 01 15:46:40 volumio volumio[1259]: info: CorePlayQueue::getTrack 0 Dec 01 15:46:40 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 15:46:40 volumio volumio[1259]: info: CoreCommandRouter::volumioPushState Dec 01 15:46:40 volumio volumio[1259]: info: CoreStateMachine::pushState Dec 01 15:46:40 volumio volumio[1259]: info: CorePlayQueue::getTrack 0 Dec 01 15:46:40 volumio volumio[1259]: info: CoreCommandRouter::volumioPushState Dec 01 15:46:40 volumio volumio[1259]: info: ControllerMpd::pushState Dec 01 15:46:40 volumio volumio[1259]: info: CoreCommandRouter::servicePushState Dec 01 15:46:40 volumio volumio[1259]: info: CorePlayQueue::getTrack 0 Dec 01 15:46:40 volumio volumio[1259]: verbose: STATE SERVICE {"status":"play","position":0,"seek":215985,"duration":255,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"632 Kbps","isStreaming":false,"title":"恰似你的温柔","artist":"蔡琴","album":"精选蔡琴","uri":"USB/蔡琴 - 恰似你的温柔.flac","trackType":"flac"} Dec 01 15:46:40 volumio volumio[1259]: verbose: CURRENT POSITION 0 Dec 01 15:46:40 volumio volumio[1259]: info: CoreStateMachine::syncState stateService play Dec 01 15:46:40 volumio volumio[1259]: info: CoreStateMachine::syncState currentStatus play Dec 01 15:46:40 volumio volumio[1259]: info: Received an update from plugin. extracting info from payload Dec 01 15:46:40 volumio volumio[1259]: info: CoreStateMachine::pushState Dec 01 15:46:40 volumio volumio[1259]: info: CorePlayQueue::getTrack 0 Dec 01 15:46:40 volumio volumio[1259]: info: CoreCommandRouter::volumioPushState Dec 01 15:46:40 volumio volumio[1259]: info: CoreStateMachine::pushState Dec 01 15:46:40 volumio volumio[1259]: info: CorePlayQueue::getTrack 0 Dec 01 15:46:40 volumio volumio[1259]: info: CoreCommandRouter::volumioPushState Dec 01 15:46:40 volumio volumio[1259]: info: ControllerMpd::pushState Dec 01 15:46:40 volumio volumio[1259]: info: CoreCommandRouter::servicePushState Dec 01 15:46:40 volumio volumio[1259]: info: CorePlayQueue::getTrack 0 Dec 01 15:46:40 volumio volumio[1259]: verbose: STATE SERVICE {"status":"play","position":0,"seek":215985,"duration":255,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"632 Kbps","isStreaming":false,"title":"恰似你的温柔","artist":"蔡琴","album":"精选蔡琴","uri":"USB/蔡琴 - 恰似你的温柔.flac","trackType":"flac"} Dec 01 15:46:40 volumio volumio[1259]: verbose: CURRENT POSITION 0 Dec 01 15:46:40 volumio volumio[1259]: info: CoreStateMachine::syncState stateService play Dec 01 15:46:40 volumio volumio[1259]: info: CoreStateMachine::syncState currentStatus play Dec 01 15:46:40 volumio volumio[1259]: info: Received an update from plugin. extracting info from payload Dec 01 15:46:40 volumio volumio[1259]: info: CoreStateMachine::pushState Dec 01 15:46:40 volumio volumio[1259]: info: CorePlayQueue::getTrack 0 Dec 01 15:46:40 volumio volumio[1259]: info: CoreCommandRouter::volumioPushState Dec 01 15:46:40 volumio volumio[1259]: info: CoreStateMachine::pushState Dec 01 15:46:40 volumio volumio[1259]: info: CorePlayQueue::getTrack 0 Dec 01 15:46:40 volumio volumio[1259]: info: CoreCommandRouter::volumioPushState Dec 01 15:46:41 volumio volumio[1259]: info: ------------------------------ 1248ms Dec 01 15:46:41 volumio volumio[1259]: info: ------------------------------ 1246ms Dec 01 15:46:41 volumio volumio[1259]: info: ------------------------------ 1240ms Dec 01 15:46:41 volumio volumio[1259]: info: sendMpdCommand playlistinfo took 623 milliseconds Dec 01 15:46:41 volumio volumio[1259]: verbose: ControllerMpd::parseTrackInfo Dec 01 15:46:41 volumio volumio[1259]: info: ControllerMpd::pushState Dec 01 15:46:41 volumio volumio[1259]: info: CoreCommandRouter::servicePushState Dec 01 15:46:41 volumio volumio[1259]: info: CorePlayQueue::getTrack 0 Dec 01 15:46:41 volumio volumio[1259]: verbose: STATE SERVICE {"status":"play","position":0,"seek":215985,"duration":255,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"632 Kbps","isStreaming":false,"title":"恰似你的温柔","artist":"蔡琴","album":"精选蔡琴","uri":"USB/蔡琴 - 恰似你的温柔.flac","trackType":"flac"} Dec 01 15:46:41 volumio volumio[1259]: verbose: CURRENT POSITION 0 Dec 01 15:46:41 volumio volumio[1259]: info: CoreStateMachine::syncState stateService play Dec 01 15:46:41 volumio volumio[1259]: info: CoreStateMachine::syncState currentStatus play Dec 01 15:46:41 volumio volumio[1259]: info: Received an update from plugin. extracting info from payload Dec 01 15:46:41 volumio volumio[1259]: info: CoreStateMachine::pushState Dec 01 15:46:41 volumio volumio[1259]: info: CorePlayQueue::getTrack 0 Dec 01 15:46:41 volumio volumio[1259]: info: CoreCommandRouter::volumioPushState Dec 01 15:46:41 volumio volumio[1259]: info: CoreStateMachine::pushState Dec 01 15:46:41 volumio volumio[1259]: info: CorePlayQueue::getTrack 0 Dec 01 15:46:41 volumio volumio[1259]: info: CoreCommandRouter::volumioPushState Dec 01 15:46:41 volumio volumio[1259]: info: ------------------------------ 1417ms Dec 01 15:46:46 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 01 15:46:46 volumio volumio[1259]: info: CURURI: music-library/USB Dec 01 15:46:46 volumio volumio[1259]: info: Preload queue cleared Dec 01 15:46:49 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 01 15:46:49 volumio volumio[1259]: info: CURURI: music-library/USB/TOSHIBA_EXT Dec 01 15:46:49 volumio volumio[1259]: info: Preload queue cleared Dec 01 15:46:50 volumio volumio[1259]: info: MyVolumio login type: Token Dec 01 15:46:51 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Dec 01 15:46:51 volumio volumio[1259]: verbose: ControllerMpd::sendMpdCommand update Dec 01 15:46:51 volumio volumio[1259]: info: sendMpdCommand update took 16 milliseconds Dec 01 15:46:59 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 01 15:46:59 volumio volumio[1259]: info: CURURI: music-library Dec 01 15:46:59 volumio volumio[1259]: info: Preload queue cleared Dec 01 15:47:00 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 01 15:47:00 volumio volumio[1259]: info: CURURI: music-library/USB Dec 01 15:47:00 volumio volumio[1259]: info: Preload queue cleared Dec 01 15:47:01 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 01 15:47:01 volumio volumio[1259]: info: CURURI: music-library/USB/TOSHIBA_EXT Dec 01 15:47:01 volumio volumio[1259]: info: Preload queue cleared Dec 01 15:47:05 volumio sudo[3090]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount /mnt/USB/TOSHIBA_EXT Dec 01 15:47:05 volumio sudo[3090]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 15:47:05 volumio sudo[3090]: pam_unix(sudo:session): session closed for user root Dec 01 15:47:06 volumio volumio[1259]: info: Command failed: /usr/bin/sudo /bin/umount /mnt/USB/TOSHIBA_EXT Dec 01 15:47:06 volumio volumio[1259]: umount: /mnt/USB/TOSHIBA_EXT: target is busy. Dec 01 15:47:06 volumio volumio[1259]: {"killed":false,"code":32,"signal":null,"cmd":"/usr/bin/sudo /bin/umount /mnt/USB/TOSHIBA_EXT"} Dec 01 15:47:15 volumio volumio[1259]: info: CorePlayQueue::getTrack 0 Dec 01 15:47:15 volumio volumio[1259]: info: CorePlayQueue::getTrack 1 Dec 01 15:47:17 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Dec 01 15:47:17 volumio volumio[1259]: verbose: ControllerMpd::sendMpdCommand update Dec 01 15:47:17 volumio volumio[1259]: info: sendMpdCommand update took 33 milliseconds Dec 01 15:47:20 volumio volumio[1259]: info: Dec 01 15:47:20 volumio volumio[1259]: ---------------------------- MPD announces state update: player Dec 01 15:47:20 volumio volumio[1259]: info: ControllerMpd::getState Dec 01 15:47:20 volumio volumio[1259]: verbose: ControllerMpd::sendMpdCommand status Dec 01 15:47:20 volumio volumio[1259]: info: Dec 01 15:47:20 volumio volumio[1259]: ---------------------------- MPD announces state update: player Dec 01 15:47:20 volumio volumio[1259]: info: ControllerMpd::getState Dec 01 15:47:20 volumio volumio[1259]: verbose: ControllerMpd::sendMpdCommand status Dec 01 15:47:20 volumio volumio[1259]: info: Dec 01 15:47:20 volumio volumio[1259]: ---------------------------- MPD announces state update: player Dec 01 15:47:20 volumio volumio[1259]: info: ControllerMpd::getState Dec 01 15:47:20 volumio volumio[1259]: verbose: ControllerMpd::sendMpdCommand status Dec 01 15:47:20 volumio volumio[1259]: info: Dec 01 15:47:20 volumio volumio[1259]: ---------------------------- MPD announces state update: player Dec 01 15:47:20 volumio volumio[1259]: info: ControllerMpd::getState Dec 01 15:47:20 volumio volumio[1259]: verbose: ControllerMpd::sendMpdCommand status Dec 01 15:47:20 volumio volumio[1259]: info: sendMpdCommand status took 29 milliseconds Dec 01 15:47:20 volumio volumio[1259]: info: sendMpdCommand status took 21 milliseconds Dec 01 15:47:20 volumio volumio[1259]: info: sendMpdCommand status took 15 milliseconds Dec 01 15:47:20 volumio volumio[1259]: info: sendMpdCommand status took 10 milliseconds Dec 01 15:47:20 volumio volumio[1259]: verbose: ControllerMpd::parseState Dec 01 15:47:20 volumio volumio[1259]: info: ControllerMpd::pushState Dec 01 15:47:20 volumio volumio[1259]: info: CoreCommandRouter::servicePushState Dec 01 15:47:20 volumio volumio[1259]: info: CoreStateMachine::pushState Dec 01 15:47:20 volumio volumio[1259]: info: CorePlayQueue::getTrack 0 Dec 01 15:47:20 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 15:47:20 volumio volumio[1259]: info: CoreCommandRouter::volumioPushState Dec 01 15:47:20 volumio volumio[1259]: info: CorePlayQueue::getTrack 0 Dec 01 15:47:20 volumio volumio[1259]: 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} Dec 01 15:47:20 volumio volumio[1259]: verbose: CURRENT POSITION 0 Dec 01 15:47:20 volumio volumio[1259]: info: CoreStateMachine::syncState stateService stop Dec 01 15:47:20 volumio volumio[1259]: info: CoreStateMachine::syncState currentStatus play Dec 01 15:47:20 volumio volumio[1259]: info: CoreCommandRouter::volumioPushState Dec 01 15:47:20 volumio volumio[1259]: info: CoreStateMachine::stPlaybackTimer Dec 01 15:47:20 volumio volumio[1259]: info: ControllerMpd::pushState Dec 01 15:47:20 volumio volumio[1259]: info: CoreCommandRouter::servicePushState Dec 01 15:47:20 volumio volumio[1259]: info: CoreStateMachine::pushState Dec 01 15:47:20 volumio volumio[1259]: info: CorePlayQueue::getTrack 0 Dec 01 15:47:20 volumio volumio[1259]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 15:47:20 volumio volumio[1259]: info: CoreCommandRouter::volumioPushState Dec 01 15:47:20 volumio volumio[1259]: info: CorePlayQueue::getTrack 0 Dec 01 15:47:20 volumio volumio[1259]: 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} Dec 01 15:47:20 volumio volumio[1259]: verbose: CURRENT POSITION 0 Dec 01 15:47:20 volumio volumio[1259]: info: CoreStateMachine::syncState stateService stop Dec 01 15:47:20 volumio volumio[1259]: info: CoreStateMachine::syncState currentStatus stop Dec 01 15:47:20 volumio volumio[1259]: info: CoreStateMachine::pushState Dec 01 15:47:20 volumio volumio[1259]: info: CorePlayQueue::getTrack 0 Dec 01 15:47:20 volumio volumio[1259]: info: CoreCommandRouter::volumioPushState Dec 01 15:47:20 volumio volumio[1259]: info: No code Dec 01 15:47:20 volumio volumio[1259]: info: CoreStateMachine::pushState Dec 01 15:47:20 volumio volumio[1259]: info: CorePlayQueue::getTrack 0 Dec 01 15:47:20 volumio volumio[1259]: info: CoreCommandRouter::volumioPushState Dec 01 15:47:20 volumio volumio[1259]: info: ControllerMpd::pushState Dec 01 15:47:20 volumio volumio[1259]: info: CoreCommandRouter::servicePushState Dec 01 15:47:20 volumio volumio[1259]: info: CoreStateMachine::pushState Dec 01 15:47:20 volumio volumio[1259]: info: CorePlayQueue::getTrack 0 Dec 01 15:47:20 volumio volumio[1259]: info: CoreCommandRouter::volumioPushState Dec 01 15:47:20 volumio volumio[1259]: info: CorePlayQueue::getTrack 0 Dec 01 15:47:20 volumio volumio[1259]: 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} Dec 01 15:47:20 volumio volumio[1259]: verbose: CURRENT POSITION 0 Dec 01 15:47:20 volumio volumio[1259]: info: CoreStateMachine::syncState stateService stop Dec 01 15:47:20 volumio volumio[1259]: info: CoreStateMachine::syncState currentStatus stop Dec 01 15:47:20 volumio volumio[1259]: info: CoreStateMachine::pushState Dec 01 15:47:20 volumio volumio[1259]: info: CorePlayQueue::getTrack 0 Dec 01 15:47:20 volumio volumio[1259]: info: CoreCommandRouter::volumioPushState Dec 01 15:47:20 volumio volumio[1259]: info: No code Dec 01 15:47:20 volumio volumio[1259]: info: CoreStateMachine::pushState Dec 01 15:47:20 volumio volumio[1259]: info: CorePlayQueue::getTrack 0 Dec 01 15:47:20 volumio volumio[1259]: info: CoreCommandRouter::volumioPushState Dec 01 15:47:20 volumio volumio[1259]: info: ControllerMpd::pushState Dec 01 15:47:20 volumio volumio[1259]: info: CoreCommandRouter::servicePushState Dec 01 15:47:20 volumio volumio[1259]: info: CoreStateMachine::pushState Dec 01 15:47:20 volumio volumio[1259]: info: CorePlayQueue::getTrack 0 Dec 01 15:47:20 volumio volumio[1259]: info: CoreCommandRouter::volumioPushState Dec 01 15:47:20 volumio volumio[1259]: info: CorePlayQueue::getTrack 0 Dec 01 15:47:20 volumio volumio[1259]: 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} Dec 01 15:47:20 volumio volumio[1259]: verbose: CURRENT POSITION 0 Dec 01 15:47:20 volumio volumio[1259]: info: CoreStateMachine::syncState stateService stop Dec 01 15:47:20 volumio volumio[1259]: info: CoreStateMachine::syncState currentStatus stop Dec 01 15:47:20 volumio volumio[1259]: info: CoreStateMachine::pushState Dec 01 15:47:20 volumio volumio[1259]: info: CorePlayQueue::getTrack 0 Dec 01 15:47:20 volumio volumio[1259]: info: CoreCommandRouter::volumioPushState Dec 01 15:47:20 volumio volumio[1259]: info: No code Dec 01 15:47:20 volumio volumio[1259]: info: CoreStateMachine::pushState Dec 01 15:47:20 volumio volumio[1259]: info: CorePlayQueue::getTrack 0 Dec 01 15:47:20 volumio volumio[1259]: info: CoreCommandRouter::volumioPushState Dec 01 15:47:20 volumio volumio[1259]: info: ------------------------------ 586ms Dec 01 15:47:20 volumio volumio[1259]: info: ------------------------------ 578ms Dec 01 15:47:20 volumio volumio[1259]: info: ------------------------------ 576ms Dec 01 15:47:20 volumio volumio[1259]: info: ------------------------------ 571ms Dec 01 15:47:20 volumio volumio[1259]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 01 15:47:20 volumio volumio[1259]: Error: ENOTDIR: not a directory, scandir '/tmp/peppyrunning' Dec 01 15:47:20 volumio volumio[1259]: at Object.readdirSync (fs.js:1048:3) Dec 01 15:47:20 volumio volumio[1259]: at rmkidsSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:349:11) Dec 01 15:47:20 volumio volumio[1259]: at rmdirSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:342:7) Dec 01 15:47:20 volumio volumio[1259]: at Function.rimrafSync [as sync] (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:319:62) Dec 01 15:47:20 volumio volumio[1259]: at Object.removeSync (/data/plugins/user_interface/peppy_screensaver/node_modules/fs-extra/lib/remove/index.js:4:17) Dec 01 15:47:20 volumio volumio[1259]: at Socket. (/data/plugins/user_interface/peppy_screensaver/index.js:225:48) Dec 01 15:47:20 volumio volumio[1259]: at Socket.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Dec 01 15:47:20 volumio volumio[1259]: at Socket.onevent (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:278:10) Dec 01 15:47:20 volumio volumio[1259]: at Socket.onpacket (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:236:12) Dec 01 15:47:20 volumio volumio[1259]: at Manager. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Dec 01 15:47:20 volumio volumio[1259]: at Manager.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Dec 01 15:47:20 volumio volumio[1259]: at Manager.ondecoded (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:349:8) Dec 01 15:47:20 volumio volumio[1259]: at Decoder. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Dec 01 15:47:20 volumio volumio[1259]: at Decoder.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Dec 01 15:47:20 volumio volumio[1259]: at Decoder.add (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-parser/index.js:251:12) Dec 01 15:47:20 volumio volumio[1259]: at Manager.ondata (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:339:16) { Dec 01 15:47:20 volumio volumio[1259]: errno: -20, Dec 01 15:47:20 volumio volumio[1259]: syscall: 'scandir', Dec 01 15:47:20 volumio volumio[1259]: code: 'ENOTDIR', Dec 01 15:47:20 volumio volumio[1259]: path: '/tmp/peppyrunning' Dec 01 15:47:20 volumio volumio[1259]: } Dec 01 15:47:20 volumio volumio[1259]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 01 15:47:21 volumio sudo[3179]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-12-01 15:46 Dec 01 15:47:21 volumio sudo[3179]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 15:47:21 volumio sudo[3179]: pam_unix(sudo:session): session closed for user root Dec 01 15:47:22 volumio volumio-remote-updater[714]: [2024-12-01 15:47:22] [info] asio async_read_at_least error: system:104 (Connection reset by peer) Dec 01 15:47:22 volumio volumio-remote-updater[714]: [2024-12-01 15:47:22] [error] handle_read_frame error: websocketpp.transport:2 (Underlying Transport Error) Dec 01 15:47:22 volumio volumio-remote-updater[714]: [2024-12-01 15:47:22] [disconnect] Disconnect close local:[1006,Underlying Transport Error] remote:[1006] Dec 01 15:47:22 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 01 15:47:22 volumio ntfs-3g[1548]: Unmounting /dev/sdb1 (TOSHIBA EXT) Dec 01 15:47:22 volumio systemd[1]: media-TOSHIBA_EXT.mount: Succeeded. Dec 01 15:47:22 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 01 15:47:22 volumio systemd[1]: Started dynamicswap service. Dec 01 15:47:22 volumio systemd[1]: dynamicswap.service: Succeeded. Dec 01 15:47:23 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 01 15:47:23 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Dec 01 15:47:23 volumio systemd[1]: Started dynamicswap service. Dec 01 15:47:23 volumio systemd[1]: Stopped Volumio Backend Module. Dec 01 15:47:23 volumio systemd[1]: Started Volumio Backend Module. Dec 01 15:47:23 volumio systemd[1]: dynamicswap.service: Succeeded. Dec 01 15:47:25 volumio volumio[3201]: info: ------------------------------------------- Dec 01 15:47:25 volumio volumio[3201]: info: ----- Volumio3 ---- Dec 01 15:47:25 volumio volumio[3201]: info: ------------------------------------------- Dec 01 15:47:25 volumio volumio[3201]: info: ----- System startup ---- Dec 01 15:47:25 volumio volumio[3201]: info: ------------------------------------------- Dec 01 15:47:26 volumio volumio[3201]: info: MYVOLUMIO Environment detected Dec 01 15:47:26 volumio volumio[3201]: info: Plugin folders cleanup Dec 01 15:47:26 volumio volumio[3201]: info: Scanning into folder /volumio/app/plugins/ Dec 01 15:47:26 volumio volumio[3201]: info: Scanning category audio_interface Dec 01 15:47:26 volumio volumio[3201]: info: Scanning category miscellanea Dec 01 15:47:26 volumio volumio[3201]: info: Scanning category music_service Dec 01 15:47:26 volumio volumio[3201]: info: Scanning category plugins.json Dec 01 15:47:26 volumio volumio[3201]: info: Scanning category system_controller Dec 01 15:47:26 volumio volumio[3201]: info: Scanning category user_interface Dec 01 15:47:26 volumio volumio[3201]: info: Scanning into folder /data/plugins/ Dec 01 15:47:26 volumio volumio[3201]: info: Scanning category user_interface Dec 01 15:47:26 volumio volumio[3201]: info: Plugin folders cleanup completed Dec 01 15:47:26 volumio volumio[3201]: info: ------------------------------------------- Dec 01 15:47:26 volumio volumio[3201]: info: ----- Core plugins startup ---- Dec 01 15:47:26 volumio volumio[3201]: info: ------------------------------------------- Dec 01 15:47:26 volumio volumio[3201]: info: Loading plugins from folder /volumio/app/plugins/ Dec 01 15:47:26 volumio volumio[3201]: info: Adding plugin upnp to MyMusic Plugins Dec 01 15:47:26 volumio volumio[3201]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 01 15:47:26 volumio volumio[3201]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 01 15:47:26 volumio volumio[3201]: info: Loading plugins from folder /data/plugins/ Dec 01 15:47:26 volumio volumio[3201]: info: Loading plugin "system"... Dec 01 15:47:26 volumio volumio[3201]: info: Loading plugin "appearance"... Dec 01 15:47:27 volumio volumio-remote-updater[714]: [2024-12-01 15:47:27] [connect] Successful connection Dec 01 15:47:27 volumio volumio[3201]: info: Loading plugin "network"... Dec 01 15:47:27 volumio volumio[3201]: info: Refreshing Cached IP Addresses Dec 01 15:47:28 volumio sudo[3229]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 01 15:47:28 volumio sudo[3229]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 15:47:28 volumio sudo[3229]: pam_unix(sudo:session): session closed for user root Dec 01 15:47:28 volumio volumio[3201]: info: Loading plugin "services"... Dec 01 15:47:28 volumio volumio[3201]: info: Loading plugin "alsa_controller"... Dec 01 15:47:28 volumio sudo[3232]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 01 15:47:28 volumio sudo[3232]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 15:47:28 volumio sudo[3234]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 01 15:47:28 volumio sudo[3234]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 15:47:28 volumio sudo[3232]: pam_unix(sudo:session): session closed for user root Dec 01 15:47:28 volumio volumio[3201]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 01 15:47:28 volumio volumio[3201]: info: Loading plugin "wizard"... Dec 01 15:47:28 volumio volumio[3201]: info: Loading plugin "networkfs"... Dec 01 15:47:28 volumio volumio[3201]: info: Starting Udev Watcher for removable devices Dec 01 15:47:28 volumio volumio[3201]: info: Ignoring mount for partition: boot Dec 01 15:47:28 volumio volumio[3201]: info: Ignoring mount for partition: volumio Dec 01 15:47:28 volumio volumio[3201]: info: Ignoring mount for partition: volumio_data Dec 01 15:47:28 volumio volumio[3201]: info: Mounting Device TOSHIBA_EXT Dec 01 15:47:28 volumio sudo[3245]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb1 /mnt/USB/TOSHIBA_EXT -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Dec 01 15:47:28 volumio sudo[3245]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 15:47:31 volumio ntfs-3g[3249]: Version 2017.3.23AR.3 integrated FUSE 28 Dec 01 15:47:31 volumio ntfs-3g[3249]: Mounted /dev/sdb1 (Read-Write, label "TOSHIBA EXT", NTFS 3.1) Dec 01 15:47:31 volumio ntfs-3g[3249]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Dec 01 15:47:31 volumio ntfs-3g[3249]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sdb1,blkdev,blksize=4096 Dec 01 15:47:31 volumio ntfs-3g[3249]: Global ownership and permissions enforced, configuration type 7 Dec 01 15:47:31 volumio sudo[3245]: pam_unix(sudo:session): session closed for user root Dec 01 15:47:31 volumio volumio[3201]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 01 15:47:31 volumio volumio[3201]: info: Loading plugin "volumio_command_line_client"... Dec 01 15:47:31 volumio volumio[3201]: info: Loading plugin "upnp"... Dec 01 15:47:31 volumio volumio[3201]: info: [1733039251161] Starting Upmpd Daemon Dec 01 15:47:31 volumio volumio[3201]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 01 15:47:31 volumio volumio[3201]: info: Loading plugin "my_music"... Dec 01 15:47:31 volumio volumio[3201]: info: Loading plugin "mpd"... Dec 01 15:47:31 volumio volumio[3201]: info: Loading plugin "upnp_browser"... Dec 01 15:47:32 volumio sudo[3234]: pam_unix(sudo:session): session closed for user root Dec 01 15:47:32 volumio volumio[3201]: info: Loading plugin "alarm-clock"... Dec 01 15:47:32 volumio volumio[3201]: info: Loading plugin "airplay_emulation"... Dec 01 15:47:32 volumio volumio[3201]: info: Starting Shairport Sync Dec 01 15:47:32 volumio volumio[3201]: info: Loading plugin "last_100"... Dec 01 15:47:32 volumio volumio[3201]: info: Loading plugin "webradio"... Dec 01 15:47:32 volumio volumio[3201]: info: Loading plugin "i2s_dacs"... Dec 01 15:47:32 volumio volumio[3201]: info: I2S DAC not set, start Auto-detection Dec 01 15:47:32 volumio volumio[3201]: info: Loading plugin "volumiodiscovery"... Dec 01 15:47:32 volumio volumio[3201]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 01 15:47:32 volumio volumio[3201]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 01 15:47:32 volumio volumio[3201]: *** WARNING *** For more information see Dec 01 15:47:32 volumio volumio[3201]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 01 15:47:32 volumio volumio[3201]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 01 15:47:32 volumio volumio[3201]: *** WARNING *** For more information see Dec 01 15:47:32 volumio node[3201]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 01 15:47:32 volumio node[3201]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 01 15:47:32 volumio node[3201]: *** WARNING *** For more information see Dec 01 15:47:32 volumio node[3201]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 01 15:47:32 volumio node[3201]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 01 15:47:32 volumio node[3201]: *** WARNING *** For more information see Dec 01 15:47:32 volumio volumio[3201]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 01 15:47:32 volumio volumio[3201]: info: Discovery: Started advertising with name: Volumio Dec 01 15:47:32 volumio volumio[3201]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 01 15:47:32 volumio volumio[3201]: info: Loading plugin "peppy_screensaver"... Dec 01 15:47:33 volumio volumio[3201]: info: Applying required configuration parameters for plugin peppy_screensaver Dec 01 15:47:33 volumio volumio[3201]: info: Loading plugin "outputs"... Dec 01 15:47:33 volumio volumio[3201]: info: Loading plugin "albumart"... Dec 01 15:47:33 volumio volumio[3201]: info: Plugin example_plugin is not enabled Dec 01 15:47:33 volumio volumio[3201]: info: Loading plugin "inputs"... Dec 01 15:47:33 volumio volumio[3201]: info: Loading plugin "updater_comm"... Dec 01 15:47:33 volumio volumio[3201]: info: Plugin mpdemulation is not enabled Dec 01 15:47:33 volumio volumio[3201]: info: Loading plugin "rest_api"... Dec 01 15:47:33 volumio volumio[3201]: Forking 1 albumart workers Dec 01 15:47:33 volumio volumio[3201]: info: Loading plugin "websocket"... Dec 01 15:47:33 volumio volumio[3201]: info: Starting Socket.io Server version 2.3.0 Dec 01 15:47:33 volumio volumio[3201]: info: Loading i18n strings for locale zh Dec 01 15:47:33 volumio volumio[3201]: Updating browse sources language Dec 01 15:47:33 volumio volumio[3201]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 01 15:47:34 volumio volumio[3201]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 01 15:47:34 volumio volumio[3201]: info: CoreCommandRouter::initPlayerControls Dec 01 15:47:34 volumio volumio[3201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 15:47:34 volumio volumio[3201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 15:47:34 volumio volumio[3201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 15:47:34 volumio volumio[3201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 15:47:34 volumio volumio[3201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 15:47:34 volumio volumio[3201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 15:47:34 volumio volumio[3201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 15:47:34 volumio volumio[3201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 15:47:34 volumio volumio[3201]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 01 15:47:34 volumio volumio[3201]: Express server listening on port 3000 Dec 01 15:47:34 volumio volumio[3201]: [Metrics] WebUI: 9s 636.75ms Dec 01 15:47:34 volumio volumio[3201]: info: Setting Device type: x86 Dec 01 15:47:34 volumio volumio[3201]: info: CoreStateMachine::resetVolumioState Dec 01 15:47:34 volumio volumio[3201]: info: CoreStateMachine::getcurrentVolume Dec 01 15:47:34 volumio volumio[3201]: info: CoreCommandRouter::volumioRetrievevolume Dec 01 15:47:34 volumio volumio[3201]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 01 15:47:34 volumio volumio[3201]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Dec 01 15:47:34 volumio volumio[3201]: info: Completed loading Core Plugins Dec 01 15:47:34 volumio volumio[3201]: info: Preparing to generate the ALSA configuration file Dec 01 15:47:34 volumio volumio[3201]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Dec 01 15:47:34 volumio volumio[3201]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Dec 01 15:47:34 volumio volumio[3201]: info: Reading ALSA contributions from plugins. Dec 01 15:47:35 volumio volumio[3201]: verbose: New Socket.io Connection to 192.168.1.105 from 192.168.1.102 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 1 Dec 01 15:47:35 volumio volumio[3201]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 2 Dec 01 15:47:35 volumio volumio[3201]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 3 Dec 01 15:47:35 volumio volumio[3201]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Dec 01 15:47:35 volumio volumio[3201]: verbose: New Socket.io Connection to 192.168.1.105 from 192.168.1.102 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 5 Dec 01 15:47:35 volumio volumio[3201]: verbose: New Socket.io Connection to 192.168.1.105 from 192.168.1.102 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 6 Dec 01 15:47:35 volumio volumio[3201]: Starting albumart workers Dec 01 15:47:35 volumio volumio[3201]: verbose: New Socket.io Connection to 192.168.1.105 from 192.168.1.102 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 7 Dec 01 15:47:35 volumio volumio[3201]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Dec 01 15:47:35 volumio volumio[3201]: verbose: New Socket.io Connection to 192.168.1.105 from 192.168.1.102 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 9 Dec 01 15:47:35 volumio volumio[3201]: verbose: New Socket.io Connection to 192.168.1.105 from 192.168.1.102 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 10 Dec 01 15:47:35 volumio volumio[3201]: verbose: New Socket.io Connection to 192.168.1.105 from 192.168.1.102 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 11 Dec 01 15:47:35 volumio volumio[3201]: verbose: New Socket.io Connection to 192.168.1.105 from 192.168.1.102 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 12 Dec 01 15:47:35 volumio volumio[3201]: verbose: New Socket.io Connection to 192.168.1.105 from 192.168.1.102 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 13 Dec 01 15:47:35 volumio volumio[3201]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 14 Dec 01 15:47:35 volumio volumio[3201]: verbose: New Socket.io Connection to 192.168.1.105 from 192.168.1.102 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 15 Dec 01 15:47:35 volumio volumio[3201]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 16 Dec 01 15:47:35 volumio volumio[3201]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 17 Dec 01 15:47:35 volumio volumio[3201]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 18 Dec 01 15:47:35 volumio volumio[3201]: info: Reloading queue from file Dec 01 15:47:35 volumio volumio[3201]: info: CoreCommandRouter::volumioGetState Dec 01 15:47:35 volumio volumio[3201]: info: CorePlayQueue::getTrack 0 Dec 01 15:47:35 volumio volumio[3201]: verbose: New Socket.io Connection to 192.168.1.105 from 192.168.1.102 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 19 Dec 01 15:47:35 volumio volumio[3201]: info: VolumeController:: Volume=100 Mute =false Dec 01 15:47:35 volumio volumio[3201]: info: CoreStateMachine::pushState Dec 01 15:47:35 volumio volumio[3201]: info: CorePlayQueue::getTrack 0 Dec 01 15:47:35 volumio volumio[3201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 15:47:35 volumio volumio[3201]: info: CoreCommandRouter::volumioPushState Dec 01 15:47:35 volumio volumio[3201]: info: CoreStateMachine::updateTrackBlock Dec 01 15:47:35 volumio volumio[3201]: info: CorePlayQueue::getTrackBlock Dec 01 15:47:35 volumio volumio[3201]: info: CoreCommandRouter::volumioRetrievevolume Dec 01 15:47:35 volumio volumio[3201]: info: CoreStateMachine::setRepeat false single undefined Dec 01 15:47:35 volumio volumio[3201]: info: CoreStateMachine::pushState Dec 01 15:47:35 volumio volumio[3201]: info: CorePlayQueue::getTrack 0 Dec 01 15:47:35 volumio volumio[3201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 15:47:35 volumio volumio[3201]: info: CoreCommandRouter::volumioPushState Dec 01 15:47:35 volumio volumio[3201]: info: CoreStateMachine::setRandom true Dec 01 15:47:35 volumio volumio[3201]: info: CoreStateMachine::pushState Dec 01 15:47:35 volumio volumio[3201]: info: CorePlayQueue::getTrack 0 Dec 01 15:47:35 volumio volumio[3201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 01 15:47:35 volumio volumio[3201]: info: CoreCommandRouter::volumioPushState Dec 01 15:47:36 volumio volumio[3201]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 01 15:47:36 volumio volumio[3201]: info: Listing playlists Dec 01 15:47:36 volumio volumio[3201]: info: CoreCommandRouter::volumioGetVisibleSources Dec 01 15:47:36 volumio volumio[3201]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 01 15:47:36 volumio volumio[3201]: info: CoreCommandRouter::volumioGetQueue Dec 01 15:47:36 volumio volumio[3201]: info: CoreStateMachine::getQueue Dec 01 15:47:36 volumio volumio[3201]: info: CorePlayQueue::getQueue Dec 01 15:47:36 volumio volumio[3201]: info: CoreCommandRouter::volumioGetState Dec 01 15:47:36 volumio volumio[3201]: info: CorePlayQueue::getTrack 0 Dec 01 15:47:36 volumio volumio[3201]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 01 15:47:36 volumio volumio[3201]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 01 15:47:36 volumio volumio[3201]: info: CoreCommandRouter::volumioGetState Dec 01 15:47:36 volumio volumio[3201]: info: CorePlayQueue::getTrack 0 Dec 01 15:47:36 volumio volumio[3201]: info: CoreCommandRouter::volumioGetState Dec 01 15:47:36 volumio volumio[3201]: info: CorePlayQueue::getTrack 0 Dec 01 15:47:36 volumio volumio[3201]: info: VolumeController:: Volume=100 Mute =false Dec 01 15:47:36 volumio volumio[3201]: info: CoreStateMachine::pushState Dec 01 15:47:36 volumio volumio[3201]: info: CorePlayQueue::getTrack 0 Dec 01 15:47:36 volumio volumio[3201]: info: CoreCommandRouter::volumioPushState Dec 01 15:47:36 volumio volumio[3201]: info: Discovery: adding 492524ff-0d18-4e9b-b0b7-e00a3046faf7 Dec 01 15:47:36 volumio volumio[3201]: info: Discovery: Found device Volumio Dec 01 15:47:36 volumio volumio[3201]: info: CoreCommandRouter::volumioGetState Dec 01 15:47:36 volumio volumio[3201]: info: CorePlayQueue::getTrack 0 Dec 01 15:47:36 volumio volumio[3201]: info: Asound.conf file unchanged, so no further update is needed Dec 01 15:47:36 volumio volumio[3201]: info: Output device has changed, restarting MPD Dec 01 15:47:36 volumio volumio[3201]: info: Output device has changed, restarting Shairport Sync Dec 01 15:47:36 volumio volumio[3201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 15:47:36 volumio volumio[3201]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 01 15:47:36 volumio sudo[3317]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 01 15:47:36 volumio sudo[3317]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 15:47:36 volumio sudo[3319]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 01 15:47:36 volumio sudo[3319]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 15:47:36 volumio sudo[3317]: pam_unix(sudo:session): session closed for user root Dec 01 15:47:36 volumio systemd[1]: Stopping Music Player Daemon... Dec 01 15:47:36 volumio volumio[3201]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 01 15:47:36 volumio volumio[3201]: info: ___________ START PLUGINS ___________ Dec 01 15:47:36 volumio volumio[3201]: info: ControllerMpd::onStart: Initializing MPD Dec 01 15:47:36 volumio volumio[3201]: info: Creating MPD Configuration file Dec 01 15:47:36 volumio systemd[1]: mpd.service: Succeeded. Dec 01 15:47:36 volumio systemd[1]: Stopped Music Player Daemon. Dec 01 15:47:36 volumio sudo[3326]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 01 15:47:36 volumio systemd[1]: Starting Music Player Daemon... Dec 01 15:47:37 volumio sudo[3326]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 15:47:37 volumio sudo[3329]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 01 15:47:37 volumio volumio[3201]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 01 15:47:37 volumio volumio[3201]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 01 15:47:37 volumio sudo[3329]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 15:47:37 volumio volumio[3201]: info: [1733039257013] CoreMusicLibrary::Adding element 媒体服务器 Dec 01 15:47:37 volumio volumio[3201]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 01 15:47:37 volumio sudo[3326]: pam_unix(sudo:session): session closed for user root Dec 01 15:47:37 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Dec 01 15:47:37 volumio systemd[1]: mpd.service: Succeeded. Dec 01 15:47:37 volumio systemd[1]: Stopped Music Player Daemon. Dec 01 15:47:37 volumio systemd[1]: Starting Music Player Daemon... Dec 01 15:47:37 volumio volumio[3201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 15:47:37 volumio volumio[3201]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 01 15:47:37 volumio volumio[3201]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 01 15:47:37 volumio volumio[3201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 15:47:37 volumio volumio[3201]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 01 15:47:37 volumio volumio[3201]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 01 15:47:37 volumio volumio[3201]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 01 15:47:37 volumio volumio[3201]: info: [1733039257315] CoreMusicLibrary::Adding element Last_100 Dec 01 15:47:37 volumio volumio[3201]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 01 15:47:37 volumio volumio[3201]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 01 15:47:37 volumio volumio[3201]: info: [1733039257325] CoreMusicLibrary::Adding element Webradio Dec 01 15:47:37 volumio volumio[3201]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 01 15:47:37 volumio volumio[3201]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 01 15:47:37 volumio volumio[3201]: info: Initializing BBC Radios Dec 01 15:47:37 volumio sudo[3332]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 01 15:47:37 volumio sudo[3332]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 15:47:37 volumio sudo[3332]: pam_unix(sudo:session): session closed for user root Dec 01 15:47:37 volumio volumio[3201]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 01 15:47:37 volumio volumio[3201]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 01 15:47:37 volumio volumio[3201]: info: Loading i18n strings for locale zh Dec 01 15:47:37 volumio volumio[3201]: info: /tmp/myfifo created Dec 01 15:47:37 volumio volumio[3201]: info: /tmp/myfifosa created Dec 01 15:47:37 volumio sudo[3358]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-dummy index=7 pcm_substreams=1 fake_buffer=0 Dec 01 15:47:37 volumio sudo[3358]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 15:47:37 volumio sudo[3358]: pam_unix(sudo:session): session closed for user root Dec 01 15:47:37 volumio volumio[3201]: info: snd-dummy loaded Dec 01 15:47:37 volumio volumio[3201]: error: Plugin peppy_screensaver failed to start! Error: ENOTDIR: not a directory, scandir '/tmp/peppyrunning' Dec 01 15:47:37 volumio volumio[3201]: info: Volumio Calling Home Dec 01 15:47:38 volumio volumio[3201]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 01 15:47:38 volumio volumio[3201]: info: CURURI: music-library/USB Dec 01 15:47:38 volumio volumio[3201]: error: Failed to execute browseSource, failure: TypeError: Cannot read property 'then' of undefined Dec 01 15:47:38 volumio volumio[3201]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 01 15:47:38 volumio volumio[3201]: info: CURURI: music-library/USB Dec 01 15:47:38 volumio volumio[3201]: error: Failed to execute browseSource, failure: TypeError: Cannot read property 'then' of undefined Dec 01 15:47:38 volumio volumio[3201]: info: MPD Permissions set Dec 01 15:47:38 volumio volumio[3201]: info: MPD Permissions set Dec 01 15:47:38 volumio volumio[3201]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 20 Dec 01 15:47:38 volumio volumio[3201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 15:47:38 volumio volumio[3201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 15:47:38 volumio volumio[3201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 15:47:38 volumio volumio[3201]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 01 15:47:38 volumio volumio[3201]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 01 15:47:38 volumio volumio[3201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 15:47:38 volumio volumio[3201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 15:47:38 volumio volumio[3201]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 01 15:47:38 volumio volumio[3201]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 01 15:47:38 volumio volumio[3201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 01 15:47:38 volumio volumio[3201]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 01 15:47:38 volumio volumio[3201]: info: CURURI: music-library/USB Dec 01 15:47:38 volumio volumio[3201]: error: Failed to execute browseSource, failure: TypeError: Cannot read property 'then' of undefined Dec 01 15:47:38 volumio volumio[3201]: info: Volumio called home Dec 01 15:47:38 volumio volumio[3201]: info: Starting Shairport Sync Dec 01 15:47:38 volumio volumio[3201]: info: Starting Shairport Sync Dec 01 15:47:38 volumio volumio[3201]: info: Starting Shairport Sync Dec 01 15:47:38 volumio sudo[3380]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 01 15:47:38 volumio sudo[3380]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 15:47:38 volumio sudo[3382]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 01 15:47:38 volumio sudo[3382]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 15:47:39 volumio volumio[3201]: info: CoreCommandRouter::volumioGetState Dec 01 15:47:39 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 01 15:47:39 volumio volumio[3201]: info: CorePlayQueue::getTrack 0 Dec 01 15:47:39 volumio systemd[1]: shairport-sync.service: Succeeded. Dec 01 15:47:39 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 01 15:47:39 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 01 15:47:39 volumio sudo[3380]: pam_unix(sudo:session): session closed for user root Dec 01 15:47:39 volumio sudo[3385]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 01 15:47:39 volumio sudo[3385]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 01 15:47:39 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 01 15:47:39 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Dec 01 15:47:39 volumio systemd[1]: shairport-sync.service: Succeeded. Dec 01 15:47:39 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 01 15:47:39 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 01 15:47:39 volumio sudo[3382]: pam_unix(sudo:session): session closed for user root Dec 01 15:47:39 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 01 15:47:39 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Dec 01 15:47:39 volumio systemd[1]: shairport-sync.service: Succeeded. Dec 01 15:47:39 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 01 15:47:39 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 01 15:47:39 volumio sudo[3385]: pam_unix(sudo:session): session closed for user root Dec 01 15:47:39 volumio volumio[3201]: info: Shairport-Sync Started Dec 01 15:47:39 volumio volumio[3201]: Error adding Membership: Error: addMembership EINVAL Dec 01 15:47:39 volumio volumio[3201]: info: Shairport-Sync Started Dec 01 15:47:39 volumio volumio[3201]: info: Shairport-Sync Started Dec 01 15:47:40 volumio volumio-remote-updater[714]: [2024-12-01 15:47:40] [connect] Successful connection Dec 01 15:47:40 volumio volumio-remote-updater[714]: [2024-12-01 15:47:40] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1733039260 101 Dec 01 15:47:40 volumio volumio[3201]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 21 Dec 01 15:47:40 volumio volumio[3201]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 22 Dec 01 15:47:40 volumio volumio[3201]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Dec 01 15:47:40 volumio volumio[3201]: verbose: ControllerMpd::sendMpdCommand update Dec 01 15:47:40 volumio volumio[3201]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 01 15:47:40 volumio volumio[3201]: TypeError: Cannot read property 'then' of undefined Dec 01 15:47:40 volumio volumio[3201]: at ControllerMpd.sendMpdCommand (/volumio/app/plugins/music_service/mpd/index.js:331:6) Dec 01 15:47:40 volumio volumio[3201]: at ControllerMpd.updateDb (/volumio/app/plugins/music_service/mpd/index.js:1898:15) Dec 01 15:47:40 volumio volumio[3201]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1083:32) Dec 01 15:47:40 volumio volumio[3201]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1077:26) Dec 01 15:47:40 volumio volumio[3201]: at Socket.emit (events.js:400:28) Dec 01 15:47:40 volumio volumio[3201]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Dec 01 15:47:40 volumio volumio[3201]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Dec 01 15:47:40 volumio volumio[3201]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 01 15:47:40 volumio mpd[3343]: Dec 01 15:47 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 01 15:47:40 volumio systemd[1]: Started Music Player Daemon. Dec 01 15:47:40 volumio sudo[3329]: pam_unix(sudo:session): session closed for user root Dec 01 15:47:40 volumio sudo[3319]: pam_unix(sudo:session): session closed for user root Dec 01 15:47:41 volumio sudo[3408]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-12-01 15:46 Dec 01 15:47:41 volumio sudo[3408]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:48:50 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="1049be5fd382035818795b7f5c38e10d"