-- Logs begin at Fri 2022-04-15 15:54:15 UTC, end at Wed 2024-05-01 14:31:40 UTC. -- May 01 14:30:00 volumio-01 volumio[7660]: 2024-05-01 14:30:00 [SPTF] 14:30:00.190 E [ap:1694] AP Socket Error: Software caused connection abort (103) May 01 14:30:00 volumio-01 volumio[7660]: 2024-05-01 14:30:00 [SPTF] 14:30:00.191 E [ap:3953] Connection error: 112 May 01 14:30:00 volumio-01 volumio[7660]: 2024-05-01 14:30:00 [SPTF] 14:30:00.192 I [ap:1752] Connecting to AP ap.spotify.com:443 May 01 14:30:00 volumio-01 volumio[7660]: 2024-05-01 14:30:00 [SPTF] 14:30:00.262 I [ap:1226] Connected to AP: 104.199.240.237:443 May 01 14:30:00 volumio-01 volumio[7660]: 2024-05-01 14:30:00 [SPTF] 14:30:00.443 E [ap:1694] AP Socket Error: Software caused connection abort (103) May 01 14:30:00 volumio-01 volumio[7660]: 2024-05-01 14:30:00 [SPTF] 14:30:00.444 E [ap:3953] Connection error: 112 May 01 14:30:00 volumio-01 volumio[7660]: 2024-05-01 14:30:00 [SPTF] 14:30:00.444 I [ap:1752] Connecting to AP ap.spotify.com:80 May 01 14:30:00 volumio-01 volumio[7660]: 2024-05-01 14:30:00 [SPTF] 14:30:00.523 I [ap:1226] Connected to AP: 104.199.240.237:80 May 01 14:30:00 volumio-01 volumio[7660]: 2024-05-01 14:30:00 [SPTF] 14:30:00.759 E [ap:1694] AP Socket Error: Software caused connection abort (103) May 01 14:30:00 volumio-01 volumio[7660]: 2024-05-01 14:30:00 [SPTF] 14:30:00.760 E [ap:3953] Connection error: 112 May 01 14:30:00 volumio-01 volumio[7660]: 2024-05-01 14:30:00 [WARN] Login failed: Cannot connect to Spotify May 01 14:30:00 volumio-01 volumio[7660]: 2024-05-01 14:30:00 [ERR ] Could not get the playlist container. May 01 14:30:00 volumio-01 systemd[1]: spop.service: Main process exited, code=killed, status=5/TRAP May 01 14:30:00 volumio-01 systemd[1]: spop.service: Failed with result 'signal'. May 01 14:30:05 volumio-01 volumio[5513]: verbose: ----------------------------------- May 01 14:30:05 volumio-01 volumio[5513]: verbose: [yt-cast-receiver] (AID: 17) (YouTube Music) Incoming message: 'noop' May 01 14:30:05 volumio-01 volumio[5513]: verbose: [yt-cast-receiver] (AID: 17) (YouTube Music) Not handled: 'noop' May 01 14:30:14 volumio-01 volumio[5513]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 01 14:30:14 volumio-01 volumio[5513]: info: CURURI: music-library May 01 14:30:14 volumio-01 volumio[5513]: error: Failed LSINFO: null May 01 14:30:19 volumio-01 volumio[5513]: info: CoreCommandRouter::getUIConfigOnPlugin May 01 14:30:19 volumio-01 volumio[5513]: info: CoreCommandRouter::getUIConfigOnPlugin May 01 14:30:19 volumio-01 volumio[5513]: info: Retrieving Cloud Streaming UI May 01 14:30:19 volumio-01 volumio[5513]: info: Getting Tidal Cloud Configuration May 01 14:30:19 volumio-01 volumio[5513]: info: CoreCommandRouter::getUIConfigOnPlugin May 01 14:30:19 volumio-01 volumio[5513]: info: Getting Qobuz Cloud Configuration May 01 14:30:19 volumio-01 volumio[5513]: info: Asking plugin for UI Config May 01 14:30:19 volumio-01 volumio[5513]: info: CoreCommandRouter::getUIConfigOnPlugin May 01 14:30:19 volumio-01 volumio[5513]: info: Getting Spotify Cloud Configuration May 01 14:30:19 volumio-01 volumio[5513]: info: Asking plugin for UI Config May 01 14:30:19 volumio-01 volumio[5513]: info: CoreCommandRouter::getUIConfigOnPlugin May 01 14:30:19 volumio-01 volumio[5513]: info: Saving Spotify Acccount May 01 14:30:19 volumio-01 volumio[5513]: info: Got Tidal Cloud Configuration May 01 14:30:19 volumio-01 volumio[5513]: info: Got it May 01 14:30:19 volumio-01 volumio[5513]: info: Got it May 01 14:30:19 volumio-01 volumio[5513]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 01 14:30:19 volumio-01 volumio[5513]: info: CoreCommandRouter::getUIConfigOnPlugin May 01 14:30:19 volumio-01 volumio[5513]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined May 01 14:30:19 volumio-01 volumio[5513]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 01 14:30:19 volumio-01 volumio[5513]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 01 14:30:19 volumio-01 volumio[5513]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 01 14:30:19 volumio-01 volumio[5513]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 01 14:30:19 volumio-01 volumio[5513]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 01 14:30:19 volumio-01 volumio[5513]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 01 14:30:19 volumio-01 volumio[5513]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 01 14:30:19 volumio-01 volumio[5513]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 01 14:30:19 volumio-01 volumio[5513]: info: CoreCommandRouter::volumioGetBrowseSources May 01 14:30:19 volumio-01 volumio[5513]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 01 14:30:19 volumio-01 volumio[5513]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 01 14:30:20 volumio-01 volumio[5513]: verbose: ----------------------------------- May 01 14:30:20 volumio-01 volumio[5513]: verbose: [yt-cast-receiver] (AID: 18) (YouTube) Incoming message: 'noop' May 01 14:30:20 volumio-01 volumio[5513]: verbose: [yt-cast-receiver] (AID: 18) (YouTube) Not handled: 'noop' May 01 14:30:21 volumio-01 volumio[5513]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb May 01 14:30:21 volumio-01 volumio[5513]: verbose: ControllerMpd::sendMpdCommand rescan May 01 14:30:21 volumio-01 volumio[5513]: verbose: ControllerMpd::sendMpdCommand status May 01 14:30:21 volumio-01 volumio[5513]: info: May 01 14:30:21 volumio-01 volumio[5513]: ---------------------------- MPD announces state update: update May 01 14:30:21 volumio-01 volumio[5513]: info: sendMpdCommand rescan took 8 milliseconds May 01 14:30:21 volumio-01 volumio[5513]: info: ControllerMpd::getState May 01 14:30:21 volumio-01 volumio[5513]: verbose: ControllerMpd::sendMpdCommand status May 01 14:30:21 volumio-01 volumio[5513]: verbose: ControllerMpd::sendMpdCommand status May 01 14:30:21 volumio-01 volumio[5513]: info: May 01 14:30:21 volumio-01 volumio[5513]: ---------------------------- MPD announces state update: update May 01 14:30:21 volumio-01 volumio[5513]: info: sendMpdCommand status took 25 milliseconds May 01 14:30:21 volumio-01 volumio[5513]: info: ControllerMpd::getState May 01 14:30:21 volumio-01 volumio[5513]: verbose: ControllerMpd::sendMpdCommand status May 01 14:30:21 volumio-01 volumio[5513]: verbose: ControllerMpd::parseState May 01 14:30:21 volumio-01 volumio[5513]: info: Command Router : Notfying DB Updatefalse May 01 14:30:21 volumio-01 volumio[5513]: info: CoreCommandRouter::Close All Modals sent May 01 14:30:21 volumio-01 volumio[5513]: info: sendMpdCommand status took 103 milliseconds May 01 14:30:21 volumio-01 volumio[5513]: info: sendMpdCommand status took 85 milliseconds May 01 14:30:21 volumio-01 volumio[5513]: info: sendMpdCommand status took 82 milliseconds May 01 14:30:21 volumio-01 volumio[5513]: verbose: ControllerMpd::parseState May 01 14:30:21 volumio-01 volumio[5513]: verbose: ControllerMpd::parseState May 01 14:30:21 volumio-01 volumio[5513]: info: Command Router : Notfying DB Updatefalse May 01 14:30:21 volumio-01 volumio[5513]: info: CoreCommandRouter::Close All Modals sent May 01 14:30:21 volumio-01 volumio[5513]: verbose: ControllerMpd::parseState May 01 14:30:21 volumio-01 volumio[5513]: info: ControllerMpd::pushState May 01 14:30:21 volumio-01 volumio[5513]: info: CoreCommandRouter::servicePushState May 01 14:30:21 volumio-01 volumio[5513]: info: CoreStateMachine::pushState May 01 14:30:21 volumio-01 volumio[5513]: info: CorePlayQueue::getTrack 0 May 01 14:30:21 volumio-01 volumio[5513]: info: CoreCommandRouter::volumioPushState May 01 14:30:21 volumio-01 volumio[5513]: info: CorePlayQueue::getTrack 0 May 01 14:30:21 volumio-01 volumio[5513]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current ytmusic Received mpd May 01 14:30:21 volumio-01 volumio[5513]: info: ControllerMpd::pushState May 01 14:30:21 volumio-01 volumio[5513]: info: CoreCommandRouter::servicePushState May 01 14:30:21 volumio-01 volumio[5513]: info: CoreStateMachine::pushState May 01 14:30:21 volumio-01 volumio[5513]: info: CorePlayQueue::getTrack 0 May 01 14:30:21 volumio-01 volumio[5513]: info: CoreCommandRouter::volumioPushState May 01 14:30:21 volumio-01 volumio[5513]: info: CorePlayQueue::getTrack 0 May 01 14:30:21 volumio-01 volumio[5513]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current ytmusic Received mpd May 01 14:30:21 volumio-01 volumio[5513]: info: ------------------------------ 219ms May 01 14:30:21 volumio-01 volumio[5513]: info: ------------------------------ 207ms May 01 14:30:24 volumio-01 ntpd[5516]: Soliciting pool server 2001:470:1d:281::123 May 01 14:30:26 volumio-01 volumio[5513]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 01 14:30:27 volumio-01 volumio[5513]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 01 14:30:34 volumio-01 volumio[5513]: verbose: ----------------------------------- May 01 14:30:34 volumio-01 volumio[5513]: verbose: [yt-cast-receiver] (AID: 18) (YouTube Music) Incoming message: 'noop' May 01 14:30:34 volumio-01 volumio[5513]: verbose: [yt-cast-receiver] (AID: 18) (YouTube Music) Not handled: 'noop' May 01 14:30:35 volumio-01 volumio[5513]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 01 14:30:35 volumio-01 volumio[5513]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 01 14:30:35 volumio-01 volumio[5513]: info: Discovery: Getting this device information May 01 14:30:35 volumio-01 volumio[5513]: info: CoreCommandRouter::volumioGetState May 01 14:30:35 volumio-01 volumio[5513]: info: CorePlayQueue::getTrack 0 May 01 14:30:35 volumio-01 volumio[5513]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 01 14:30:35 volumio-01 volumio[5513]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 01 14:30:35 volumio-01 volumio[5513]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 01 14:30:35 volumio-01 volumio[5513]: info: Discovery: Getting this device information May 01 14:30:35 volumio-01 volumio[5513]: info: CoreCommandRouter::volumioGetState May 01 14:30:35 volumio-01 volumio[5513]: info: CorePlayQueue::getTrack 0 May 01 14:30:35 volumio-01 volumio[5513]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 01 14:30:35 volumio-01 volumio[5513]: verbose: New Socket.io Connection to 192.168.1.14 from 192.168.1.9 UA: unknown Total Clients: 3 May 01 14:30:35 volumio-01 volumio[5513]: verbose: New Socket.io Connection to 192.168.1.15 from 192.168.1.9 UA: unknown Total Clients: 4 May 01 14:30:35 volumio-01 volumio[5513]: info: CoreCommandRouter::volumioGetState May 01 14:30:35 volumio-01 volumio[5513]: info: CorePlayQueue::getTrack 0 May 01 14:30:35 volumio-01 volumio[5513]: info: CoreCommandRouter::volumioGetState May 01 14:30:35 volumio-01 volumio[5513]: info: CorePlayQueue::getTrack 0 May 01 14:30:35 volumio-01 volumio[5513]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 01 14:30:35 volumio-01 volumio[5513]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 01 14:30:35 volumio-01 volumio[5513]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 01 14:30:35 volumio-01 volumio[5513]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 01 14:30:37 volumio-01 sudo[7777]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 01 14:30:37 volumio-01 sudo[7777]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 14:30:37 volumio-01 sudo[7777]: pam_unix(sudo:session): session closed for user root May 01 14:30:37 volumio-01 sudo[7780]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 01 14:30:37 volumio-01 sudo[7780]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 14:30:37 volumio-01 sudo[7780]: pam_unix(sudo:session): session closed for user root May 01 14:30:37 volumio-01 volumio[5513]: verbose: New Socket.io Connection to 192.168.1.14 from 192.168.1.9 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 5 May 01 14:30:37 volumio-01 volumio[5513]: info: CoreCommandRouter::volumioGetState May 01 14:30:37 volumio-01 volumio[5513]: info: CorePlayQueue::getTrack 0 May 01 14:30:37 volumio-01 volumio[5513]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 01 14:30:37 volumio-01 volumio[5513]: info: Listing playlists May 01 14:30:37 volumio-01 volumio[5513]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 01 14:30:37 volumio-01 volumio[5513]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 01 14:30:37 volumio-01 volumio[5513]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 01 14:30:37 volumio-01 volumio[5513]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 01 14:30:43 volumio-01 sudo[7793]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 01 14:30:43 volumio-01 sudo[7793]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 14:30:43 volumio-01 sudo[7793]: pam_unix(sudo:session): session closed for user root May 01 14:30:43 volumio-01 sudo[7796]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 01 14:30:43 volumio-01 sudo[7796]: pam_unix(sudo:session): session opened for user root by (uid=0) May 01 14:30:43 volumio-01 sudo[7796]: pam_unix(sudo:session): session closed for user root May 01 14:30:43 volumio-01 volumio[5513]: verbose: New Socket.io Connection to 192.168.1.14 from 192.168.1.9 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 5 May 01 14:30:43 volumio-01 volumio[5513]: info: CoreCommandRouter::volumioGetState May 01 14:30:43 volumio-01 volumio[5513]: info: CorePlayQueue::getTrack 0 May 01 14:30:43 volumio-01 volumio[5513]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 01 14:30:43 volumio-01 volumio[5513]: info: Listing playlists May 01 14:30:43 volumio-01 volumio[5513]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 01 14:30:43 volumio-01 volumio[5513]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 01 14:30:43 volumio-01 volumio[5513]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 01 14:30:43 volumio-01 volumio[5513]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 01 14:30:46 volumio-01 volumio[5513]: verbose: ----------------------------------- May 01 14:30:46 volumio-01 volumio[5513]: verbose: [yt-cast-receiver] (AID: 19) (YouTube) Incoming message: 'noop' May 01 14:30:46 volumio-01 volumio[5513]: verbose: [yt-cast-receiver] (AID: 19) (YouTube) Not handled: 'noop' May 01 14:30:51 volumio-01 volumio[5513]: info: CoreCommandRouter::volumioGetQueue May 01 14:30:51 volumio-01 volumio[5513]: info: CoreStateMachine::getQueue May 01 14:30:51 volumio-01 volumio[5513]: info: CorePlayQueue::getQueue May 01 14:30:53 volumio-01 volumio[5513]: info: CoreCommandRouter::volumioPlay May 01 14:30:53 volumio-01 volumio[5513]: verbose: UNSET VOLATILE May 01 14:30:53 volumio-01 volumio[5513]: info: CoreStateMachine::play index 3 May 01 14:30:53 volumio-01 volumio[5513]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 14:30:53 volumio-01 volumio[5513]: info: CoreStateMachine::stop May 01 14:30:53 volumio-01 volumio[5513]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 14:30:53 volumio-01 volumio[5513]: verbose: UNSET VOLATILE May 01 14:30:53 volumio-01 volumio[5513]: info: CoreStateMachine::stPlaybackTimer May 01 14:30:53 volumio-01 volumio[5513]: info: CoreStateMachine::updateTrackBlock May 01 14:30:53 volumio-01 volumio[5513]: info: CorePlayQueue::getTrackBlock May 01 14:30:53 volumio-01 volumio[5513]: info: CoreStateMachine::pushState May 01 14:30:53 volumio-01 volumio[5513]: info: CorePlayQueue::getTrack 0 May 01 14:30:53 volumio-01 volumio[5513]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 14:30:53 volumio-01 volumio[5513]: info: CoreCommandRouter::volumioPushState May 01 14:30:53 volumio-01 volumio[5513]: info: CoreStateMachine::serviceStop May 01 14:30:53 volumio-01 volumio[5513]: info: CorePlayQueue::getTrack 0 May 01 14:30:53 volumio-01 volumio[5513]: info: CoreCommandRouter::serviceStop May 01 14:30:53 volumio-01 volumio[5513]: info: CoreStateMachine::setConsumeUpdateService mpd May 01 14:30:53 volumio-01 volumio[5513]: info: ControllerMpd::stop May 01 14:30:53 volumio-01 volumio[5513]: verbose: ControllerMpd::sendMpdCommand stop May 01 14:30:53 volumio-01 volumio[5513]: info: sendMpdCommand stop took 18 milliseconds May 01 14:30:53 volumio-01 volumio[5513]: info: CoreStateMachine::play index undefined May 01 14:30:53 volumio-01 volumio[5513]: info: CoreStateMachine::setConsumeUpdateService undefined May 01 14:30:53 volumio-01 volumio[5513]: info: CorePlayQueue::getTrack 3 May 01 14:30:53 volumio-01 volumio[5513]: info: CoreStateMachine::startPlaybackTimer May 01 14:30:53 volumio-01 volumio[5513]: info: CorePlayQueue::getTrack 3 May 01 14:30:53 volumio-01 volumio[5513]: info: [ytmusic-play] clearAddPlayTrack: ytmusic/video@explodeTrackData:o=%7B%22type%22%3A%22video%22%2C%22title%22%3A%22NH%E1%BA%A0C%20AUDIOPHILE%20VOL.2%22%2C%22artist%22%3A%22Audiophile%20vietnam%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fi.ytimg.com%2Fvi%2FYpJDKuGjWxA%2Fhq720.jpg%3Fsqp%3D-oaymwEXCNUGEOADIAQqCwjVARCqCBh4INgESFo%26rs%3DAMzJL3k1ceucT2D93Hz0Tee9hNqTvHql1g%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22YpJDKuGjWxA%22%2C%22playlistId%22%3A%22PLUMiYXaqq0s7M6O7fwd84TzJo6aOdmZf0%22%2C%22params%22%3A%22OAHyAQIIAQ%253D%253D%22%2C%22index%22%3A3%2C%22playlistSetVideoId%22%3A%2252152B4946C2F73F%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_UGC%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22playlistId%22%3A%22PLUMiYXaqq0s7M6O7fwd84TzJo6aOdmZf0%22%2C%22params%22%3A%22OAHyAQIIAQ%253D%253D%22%2C%22videoId%22%3A%22wITXKmMNClQ%22%7D%7D%7D%7D May 01 14:30:53 volumio-01 volumio[5513]: InnertubeError: Something went wrong at PlaylistExpandableMessage! May 01 14:30:53 volumio-01 volumio[5513]: This is a bug, please report it at https://github.com/patrickkfkan/Volumio-YouTube.js/issues May 01 14:30:53 volumio-01 volumio[5513]: at Function._Parser_printError (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:18226:16) May 01 14:30:53 volumio-01 volumio[5513]: at Function.parseItem (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:18059:70) May 01 14:30:53 volumio-01 volumio[5513]: at Function.parseArray (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:18069:29) May 01 14:30:53 volumio-01 volumio[5513]: at new PlaylistPanel (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:15522:36) May 01 14:30:53 volumio-01 volumio[5513]: at Function.parseItem (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:18055:24) May 01 14:30:53 volumio-01 volumio[5513]: at new MusicQueue (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:15539:35) May 01 14:30:53 volumio-01 volumio[5513]: at Function.parseItem (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:18055:24) May 01 14:30:53 volumio-01 volumio[5513]: at new Tab (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:16592:35) May 01 14:30:53 volumio-01 volumio[5513]: at Function.parseItem (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:18055:24) May 01 14:30:53 volumio-01 volumio[5513]: at Function.parse (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:18086:29) May 01 14:30:53 volumio-01 volumio[5513]: at new TwoColumnBrowseResults (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:16943:32) May 01 14:30:53 volumio-01 volumio[5513]: at new WatchNextTabbedResults (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:17264:5) May 01 14:30:53 volumio-01 volumio[5513]: at Function.parseItem (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:18055:24) May 01 14:30:53 volumio-01 volumio[5513]: at Function.parse (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:18096:39) May 01 14:30:53 volumio-01 volumio[5513]: at new Tabbed (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:16693:36) May 01 14:30:53 volumio-01 volumio[5513]: at Function.parseItem (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:18055:24) { May 01 14:30:53 volumio-01 volumio[5513]: info: { May 01 14:30:53 volumio-01 volumio[5513]: stack: 'Error: Type mismatch, got PlaylistExpandableMessage but expected one of PlaylistPanelVideoWrapper, PlaylistPanelVideo, AutomixPreviewVideo\n' + May 01 14:30:53 volumio-01 volumio[5513]: ' at Function.parseItem (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:18051:21)\n' + May 01 14:30:53 volumio-01 volumio[5513]: ' at Function.parseArray (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:18069:29)\n' + May 01 14:30:53 volumio-01 volumio[5513]: ' at new PlaylistPanel (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:15522:36)\n' + May 01 14:30:53 volumio-01 volumio[5513]: ' at Function.parseItem (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:18055:24)\n' + May 01 14:30:53 volumio-01 volumio[5513]: ' at new MusicQueue (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:15539:35)\n' + May 01 14:30:53 volumio-01 volumio[5513]: ' at Function.parseItem (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:18055:24)\n' + May 01 14:30:53 volumio-01 volumio[5513]: ' at new Tab (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:16592:35)\n' + May 01 14:30:53 volumio-01 volumio[5513]: ' at Function.parseItem (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:18055:24)\n' + May 01 14:30:53 volumio-01 volumio[5513]: ' at Function.parse (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:18086:29)\n' + May 01 14:30:53 volumio-01 volumio[5513]: ' at new TwoColumnBrowseResults (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:16943:32)\n' + May 01 14:30:53 volumio-01 volumio[5513]: ' at new WatchNextTabbedResults (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:17264:5)\n' + May 01 14:30:53 volumio-01 volumio[5513]: ' at Function.parseItem (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:18055:24)\n' + May 01 14:30:53 volumio-01 volumio[5513]: ' at Function.parse (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:18096:39)\n' + May 01 14:30:53 volumio-01 volumio[5513]: ' at new Tabbed (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:16693:36)\n' + May 01 14:30:53 volumio-01 volumio[5513]: ' at Function.parseItem (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:18055:24)\n' + May 01 14:30:53 volumio-01 volumio[5513]: ' at Function.parse (/data/plugins/music_service/ytmusic/node_modules/volumio-youtubei.js/bundle/node.cjs:18096:39)' May 01 14:30:53 volumio-01 volumio[5513]: }, May 01 14:30:53 volumio-01 volumio[5513]: date: 2024-05-01T14:30:53.951Z, May 01 14:30:53 volumio-01 volumio[5513]: version: '0.3.3' May 01 14:30:53 volumio-01 volumio[5513]: } May 01 14:30:54 volumio-01 volumio[5513]: info: CorePlayQueue::getTrack 3 May 01 14:30:54 volumio-01 volumio[5513]: verbose: ControllerMpd::sendMpdCommand stop May 01 14:30:54 volumio-01 volumio[5513]: info: sendMpdCommand stop took 3 milliseconds May 01 14:30:54 volumio-01 volumio[5513]: verbose: ControllerMpd::sendMpdCommand clear May 01 14:30:54 volumio-01 volumio[5513]: info: May 01 14:30:54 volumio-01 volumio[5513]: ---------------------------- MPD announces system playlist update May 01 14:30:54 volumio-01 volumio[5513]: info: Ignoring MPD Status Update May 01 14:30:54 volumio-01 volumio[5513]: info: sendMpdCommand clear took 4 milliseconds May 01 14:30:54 volumio-01 volumio[5513]: verbose: ControllerMpd::sendMpdCommand addid "https://rr4---sn-8pxuuxa-i5ol6.googlevideo.com/videoplayback?expire=1714595453&ei=HVIyZqa6H9uy1d8Pnv6IyAo&ip=171.228.150.234&id=o-AIbL2J_6a_vzS1FGCiMH3DC4SYq7_Z8xDX6Ppc22N0YN&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=hq&mm=31%2C29&mn=sn-8pxuuxa-i5ol6%2Csn-8pxuuxa-i5o6d&ms=au%2Crdu&mv=m&mvi=4&pl=25&initcwndbps=1635000&bui=AWRWj2QKfYYfJdqBTBB_iNUrvnmT55Vl58jUv-ncuxAEqVZZV5r0qhA74-m3gliTYpgiUi6s0e8STfkK&spc=UWF9fwGja0hCGKUbJrgNtFo38yOihNtY0Yq0KJzBKKYyj1Y&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=Z0lvws0yBnvtgPYYOi0DDQwQ&gir=yes&clen=59890679&dur=3296.221&lmt=1540097608390565&mt=1714572300&fvip=5&keepalive=yes&c=WEB_REMIX&sefc=1&txp=5411222&n=kQU1ljCqKQZAmw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRAIgUZJlPjcaN-Sgr-R_fN2haRSzK6yLtczo35ZUbPWVQuYCICc7qz0iU7ynQ3k24kNIXDIH0sjmcNjgAueJ0bHu_uhg&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHWaYeowRAIgV8lv3HnBly3eXCe--61Pme8j2DXejI5qZatvGeIl9w0CIFsj5zK2jYsZ3TeyGPC4aUNsX2zv2x89abgJ8QctmUfe&cver=1.20211213.00.00&t.YouTube" May 01 14:30:54 volumio-01 volumio[5513]: error: updateQueue error: null May 01 14:30:54 volumio-01 volumio[5513]: info: May 01 14:30:54 volumio-01 volumio[5513]: ---------------------------- MPD announces system playlist update May 01 14:30:54 volumio-01 volumio[5513]: info: Ignoring MPD Status Update May 01 14:30:54 volumio-01 volumio[5513]: info: ------------------------------ 11ms May 01 14:30:54 volumio-01 volumio[5513]: info: sendMpdCommand addid "https://rr4---sn-8pxuuxa-i5ol6.googlevideo.com/videoplayback?expire=1714595453&ei=HVIyZqa6H9uy1d8Pnv6IyAo&ip=171.228.150.234&id=o-AIbL2J_6a_vzS1FGCiMH3DC4SYq7_Z8xDX6Ppc22N0YN&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=hq&mm=31%2C29&mn=sn-8pxuuxa-i5ol6%2Csn-8pxuuxa-i5o6d&ms=au%2Crdu&mv=m&mvi=4&pl=25&initcwndbps=1635000&bui=AWRWj2QKfYYfJdqBTBB_iNUrvnmT55Vl58jUv-ncuxAEqVZZV5r0qhA74-m3gliTYpgiUi6s0e8STfkK&spc=UWF9fwGja0hCGKUbJrgNtFo38yOihNtY0Yq0KJzBKKYyj1Y&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=Z0lvws0yBnvtgPYYOi0DDQwQ&gir=yes&clen=59890679&dur=3296.221&lmt=1540097608390565&mt=1714572300&fvip=5&keepalive=yes&c=WEB_REMIX&sefc=1&txp=5411222&n=kQU1ljCqKQZAmw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRAIgUZJlPjcaN-Sgr-R_fN2haRSzK6yLtczo35ZUbPWVQuYCICc7qz0iU7ynQ3k24kNIXDIH0sjmcNjgAueJ0bHu_uhg&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHWaYeowRAIgV8lv3HnBly3eXCe--61Pme8j2DXejI5qZatvGeIl9w0CIFsj5zK2jYsZ3TeyGPC4aUNsX2zv2x89abgJ8QctmUfe&cver=1.20211213.00.00&t.YouTube" took 8 milliseconds May 01 14:30:54 volumio-01 volumio[5513]: verbose: MPD COMMAND [object Object] May 01 14:30:54 volumio-01 volumio[5513]: verbose: MPD COMMAND [object Object] May 01 14:30:54 volumio-01 volumio[5513]: info: May 01 14:30:54 volumio-01 volumio[5513]: ---------------------------- MPD announces system playlist update May 01 14:30:54 volumio-01 volumio[5513]: info: Ignoring MPD Status Update May 01 14:30:54 volumio-01 volumio[5513]: info: ------------------------------ 17ms May 01 14:30:54 volumio-01 volumio[5513]: info: CoreStateMachine::setConsumeUpdateService mpd May 01 14:30:54 volumio-01 volumio[5513]: verbose: ControllerMpd::sendMpdCommand play May 01 14:30:54 volumio-01 volumio[5513]: info: ------------------------------ 13ms May 01 14:30:54 volumio-01 volumio[5513]: info: sendMpdCommand play took 6 milliseconds May 01 14:30:58 volumio-01 volumio[5513]: info: May 01 14:30:58 volumio-01 volumio[5513]: ---------------------------- MPD announces state update: player May 01 14:30:58 volumio-01 volumio[5513]: info: ControllerMpd::getState May 01 14:30:58 volumio-01 volumio[5513]: verbose: ControllerMpd::sendMpdCommand status May 01 14:30:58 volumio-01 volumio[5513]: info: May 01 14:30:58 volumio-01 volumio[5513]: ---------------------------- MPD announces state update: player May 01 14:30:58 volumio-01 volumio[5513]: info: sendMpdCommand status took 10 milliseconds May 01 14:30:58 volumio-01 volumio[5513]: info: ControllerMpd::getState May 01 14:30:58 volumio-01 volumio[5513]: verbose: ControllerMpd::sendMpdCommand status May 01 14:30:58 volumio-01 volumio[5513]: verbose: ControllerMpd::parseState May 01 14:30:58 volumio-01 volumio[5513]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 14:30:58 volumio-01 volumio[5513]: info: sendMpdCommand status took 6 milliseconds May 01 14:30:58 volumio-01 volumio[5513]: info: sendMpdCommand playlistinfo took 5 milliseconds May 01 14:30:58 volumio-01 volumio[5513]: verbose: ControllerMpd::parseState May 01 14:30:58 volumio-01 volumio[5513]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 01 14:30:58 volumio-01 volumio[5513]: verbose: ControllerMpd::parseTrackInfo May 01 14:30:58 volumio-01 volumio[5513]: info: ControllerMpd::pushState May 01 14:30:58 volumio-01 volumio[5513]: info: CoreCommandRouter::servicePushState May 01 14:30:58 volumio-01 volumio[5513]: info: CorePlayQueue::getTrack 3 May 01 14:30:58 volumio-01 volumio[5513]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3296,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"NHẠC AUDIOPHILE VOL.2","artist":"Audiophile vietnam","album":null,"uri":"https://rr4---sn-8pxuuxa-i5ol6.googlevideo.com/videoplayback?expire=1714595453&ei=HVIyZqa6H9uy1d8Pnv6IyAo&ip=171.228.150.234&id=o-AIbL2J_6a_vzS1FGCiMH3DC4SYq7_Z8xDX6Ppc22N0YN&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=hq&mm=31%2C29&mn=sn-8pxuuxa-i5ol6%2Csn-8pxuuxa-i5o6d&ms=au%2Crdu&mv=m&mvi=4&pl=25&initcwndbps=1635000&bui=AWRWj2QKfYYfJdqBTBB_iNUrvnmT55Vl58jUv-ncuxAEqVZZV5r0qhA74-m3gliTYpgiUi6s0e8STfkK&spc=UWF9fwGja0hCGKUbJrgNtFo38yOihNtY0Yq0KJzBKKYyj1Y&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=Z0lvws0yBnvtgPYYOi0DDQwQ&gir=yes&clen=59890679&dur=3296.221&lmt=1540097608390565&mt=1714572300&fvip=5&keepalive=yes&c=WEB_REMIX&sefc=1&txp=5411222&n=kQU1ljCqKQZAmw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRAIgUZJlPjcaN-Sgr-R_fN2haRSzK6yLtczo35ZUbPWVQuYCICc7qz0iU7ynQ3k24kNIXDIH0sjmcNjgAueJ0bHu_uhg&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHWaYeowRAIgV8lv3HnBly3eXCe--61Pme8j2DXejI5qZatvGeIl9w0CIFsj5zK2jYsZ3TeyGPC4aUNsX2zv2x89abgJ8QctmUfe&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} May 01 14:30:58 volumio-01 volumio[5513]: verbose: CURRENT POSITION 3 May 01 14:30:58 volumio-01 volumio[5513]: info: CoreStateMachine::syncState stateService play May 01 14:30:58 volumio-01 volumio[5513]: info: CoreStateMachine::syncState currentStatus stop May 01 14:30:58 volumio-01 volumio[5513]: info: CoreStateMachine::pushState May 01 14:30:58 volumio-01 volumio[5513]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 14:30:58 volumio-01 volumio[5513]: info: CoreCommandRouter::volumioPushState May 01 14:30:58 volumio-01 volumio[5513]: info: ------------------------------ 66ms May 01 14:30:58 volumio-01 volumio[5513]: info: sendMpdCommand playlistinfo took 43 milliseconds May 01 14:30:58 volumio-01 volumio[5513]: verbose: ControllerMpd::parseTrackInfo May 01 14:30:58 volumio-01 volumio[5513]: info: ControllerMpd::pushState May 01 14:30:58 volumio-01 volumio[5513]: info: CoreCommandRouter::servicePushState May 01 14:30:58 volumio-01 volumio[5513]: info: CorePlayQueue::getTrack 3 May 01 14:30:58 volumio-01 volumio[5513]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3296,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"NHẠC AUDIOPHILE VOL.2","artist":"Audiophile vietnam","album":null,"uri":"https://rr4---sn-8pxuuxa-i5ol6.googlevideo.com/videoplayback?expire=1714595453&ei=HVIyZqa6H9uy1d8Pnv6IyAo&ip=171.228.150.234&id=o-AIbL2J_6a_vzS1FGCiMH3DC4SYq7_Z8xDX6Ppc22N0YN&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&mh=hq&mm=31%2C29&mn=sn-8pxuuxa-i5ol6%2Csn-8pxuuxa-i5o6d&ms=au%2Crdu&mv=m&mvi=4&pl=25&initcwndbps=1635000&bui=AWRWj2QKfYYfJdqBTBB_iNUrvnmT55Vl58jUv-ncuxAEqVZZV5r0qhA74-m3gliTYpgiUi6s0e8STfkK&spc=UWF9fwGja0hCGKUbJrgNtFo38yOihNtY0Yq0KJzBKKYyj1Y&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=Z0lvws0yBnvtgPYYOi0DDQwQ&gir=yes&clen=59890679&dur=3296.221&lmt=1540097608390565&mt=1714572300&fvip=5&keepalive=yes&c=WEB_REMIX&sefc=1&txp=5411222&n=kQU1ljCqKQZAmw&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Cgir%2Cclen%2Cdur%2Clmt&sig=AJfQdSswRAIgUZJlPjcaN-Sgr-R_fN2haRSzK6yLtczo35ZUbPWVQuYCICc7qz0iU7ynQ3k24kNIXDIH0sjmcNjgAueJ0bHu_uhg&lsparams=mh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Cinitcwndbps&lsig=AHWaYeowRAIgV8lv3HnBly3eXCe--61Pme8j2DXejI5qZatvGeIl9w0CIFsj5zK2jYsZ3TeyGPC4aUNsX2zv2x89abgJ8QctmUfe&cver=1.20211213.00.00&t.YouTube","trackType":"YouTube"} May 01 14:30:58 volumio-01 volumio[5513]: verbose: CURRENT POSITION 3 May 01 14:30:58 volumio-01 volumio[5513]: info: CoreStateMachine::syncState stateService play May 01 14:30:58 volumio-01 volumio[5513]: info: CoreStateMachine::syncState currentStatus play May 01 14:30:58 volumio-01 volumio[5513]: info: Received an update from plugin. extracting info from payload May 01 14:30:58 volumio-01 volumio[5513]: info: CoreStateMachine::pushState May 01 14:30:58 volumio-01 volumio[5513]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 01 14:30:58 volumio-01 volumio[5513]: info: CoreCommandRouter::volumioPushState May 01 14:30:58 volumio-01 volumio[5513]: info: CoreStateMachine::pushState May 01 14:30:58 volumio-01 volumio[5513]: info: CoreCommandRouter::volumioPushState May 01 14:30:58 volumio-01 volumio[5513]: info: ------------------------------ 96ms May 01 14:30:58 volumio-01 volumio[5513]: verbose: [ytcr] MPDSubsystemEventEmitter invoking 1 SubsystemEventListener callbacks for: player May 01 14:30:59 volumio-01 volumio[5513]: verbose: ----------------------------------- May 01 14:30:59 volumio-01 volumio[5513]: verbose: [yt-cast-receiver] (AID: 19) (YouTube Music) Incoming message: 'noop' May 01 14:30:59 volumio-01 volumio[5513]: verbose: [yt-cast-receiver] (AID: 19) (YouTube Music) Not handled: 'noop' May 01 14:31:02 volumio-01 volumio[5513]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 01 14:31:03 volumio-01 volumio[5513]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 01 14:31:03 volumio-01 volumio[5513]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 01 14:31:03 volumio-01 volumio[5513]: info: Error : CoreCommandRouter::executeOnPlugin: No method [retreiveBackendEventStates] in plugin my_volumio May 01 14:31:03 volumio-01 volumio[5513]: info: Received Get System Version May 01 14:31:03 volumio-01 volumio[5513]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 01 14:31:03 volumio-01 volumio[5513]: info: CoreCommandRouter::volumioGetVisibleSources May 01 14:31:03 volumio-01 volumio[5513]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 01 14:31:06 volumio-01 volumio[5513]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 01 14:31:06 volumio-01 volumio[5513]: info: CURURI: music-library May 01 14:31:07 volumio-01 volumio[5513]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 01 14:31:07 volumio-01 volumio[5513]: info: CURURI: music-library/NAS May 01 14:31:07 volumio-01 volumio[5513]: error: Failed LSINFO: null May 01 14:31:09 volumio-01 volumio[5513]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 01 14:31:09 volumio-01 volumio[5513]: info: CURURI: music-library/INTERNAL May 01 14:31:10 volumio-01 volumio[5513]: verbose: ----------------------------------- May 01 14:31:10 volumio-01 volumio[5513]: verbose: [yt-cast-receiver] (AID: 20) (YouTube) Incoming message: 'noop' May 01 14:31:10 volumio-01 volumio[5513]: verbose: [yt-cast-receiver] (AID: 20) (YouTube) Not handled: 'noop' May 01 14:31:10 volumio-01 volumio[5513]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 01 14:31:10 volumio-01 volumio[5513]: info: CURURI: music-library/INTERNAL/NowPlayingPlugin May 01 14:31:18 volumio-01 volumio[5513]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 01 14:31:18 volumio-01 volumio[5513]: info: CURURI: music-library/USB May 01 14:31:20 volumio-01 volumio[5513]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 01 14:31:20 volumio-01 volumio[5513]: info: CURURI: music-library/USB/HUY_HOANG May 01 14:31:20 volumio-01 volumio[5513]: error: Failed LSINFO: null May 01 14:31:29 volumio-01 volumio[5513]: verbose: ----------------------------------- May 01 14:31:29 volumio-01 volumio[5513]: verbose: [yt-cast-receiver] (AID: 20) (YouTube Music) Incoming message: 'noop' May 01 14:31:29 volumio-01 volumio[5513]: verbose: [yt-cast-receiver] (AID: 20) (YouTube Music) Not handled: 'noop' May 01 14:31:30 volumio-01 ntpd[5516]: Soliciting pool server 2a02:2a50:6::123 May 01 14:31:32 volumio-01 volumio[5513]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 01 14:31:32 volumio-01 volumio[5513]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 01 14:31:32 volumio-01 volumio[5513]: info: Discovery: Getting this device information May 01 14:31:32 volumio-01 volumio[5513]: info: CoreCommandRouter::volumioGetState May 01 14:31:32 volumio-01 volumio[5513]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 01 14:31:32 volumio-01 volumio[5513]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 01 14:31:32 volumio-01 volumio[5513]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 01 14:31:32 volumio-01 volumio[5513]: info: Discovery: Getting this device information May 01 14:31:32 volumio-01 volumio[5513]: info: CoreCommandRouter::volumioGetState May 01 14:31:32 volumio-01 volumio[5513]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 01 14:31:34 volumio-01 volumio[5513]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 01 14:31:39 volumio-01 volumio[5513]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ May 01 14:31:39 volumio-01 volumio[5513]: info: PLUGIN onReboot : networkfs May 01 14:31:39 volumio-01 volumio[5513]: info: PLUGIN onReboot : audiophonicsonoff May 01 14:31:39 volumio-01 volumio[5513]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 01 14:31:39 volumio-01 volumio[5513]: TypeError: Cannot read property 'writeSync' of undefined May 01 14:31:39 volumio-01 volumio[5513]: at ControllerAudiophonicsOnOff.onVolumioReboot (/data/plugins/system_controller/audiophonicsonoff/index.js:35:25) May 01 14:31:39 volumio-01 volumio[5513]: at PluginManager.onVolumioRebootPlugin (/volumio/app/pluginmanager.js:684:30) May 01 14:31:39 volumio-01 volumio[5513]: at HashMap. (/volumio/app/pluginmanager.js:668:31) May 01 14:31:39 volumio-01 volumio[5513]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) May 01 14:31:39 volumio-01 volumio[5513]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) May 01 14:31:39 volumio-01 volumio[5513]: at PluginManager.onVolumioReboot (/volumio/app/pluginmanager.js:666:20) May 01 14:31:39 volumio-01 volumio[5513]: at CoreCommandRouter.reboot (/volumio/app/index.js:1292:22) May 01 14:31:39 volumio-01 volumio[5513]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:772:33) May 01 14:31:39 volumio-01 volumio[5513]: at Socket.emit (events.js:400:28) May 01 14:31:39 volumio-01 volumio[5513]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 May 01 14:31:39 volumio-01 volumio[5513]: at processTicksAndRejections (internal/process/task_queues.js:77:11) May 01 14:31:39 volumio-01 volumio[5513]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 01 14:31:40 volumio-01 sudo[7882]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-01 14:30 May 01 14:31:40 volumio-01 sudo[7882]: 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="613d705c083fd29de3027cf6e63a923a6020da8e" VOLUMIO_FE_VERSION="575f38b17bfc836dbd47f560a440d36ecd98d5af" VOLUMIO_FE3_VERSION="af818e7eb038d49989134ddd7bf2b2ffaa8cf083" VOLUMIO_BE_VERSION="5936439c04ed803864b9ef314729c659b841752e" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 29 Jan 2022 12:36:00 AM CET" VOLUMIO_VERSION="3.212" VOLUMIO_HARDWARE="kvim1" VOLUMIO_DEVICENAME="Khadas VIM1" VOLUMIO_HASH="a41712c8cd708e7cae9978278a240084"