-- Logs begin at Sun 2026-03-08 01:07:21 UTC, end at Sun 2026-03-29 04:01:12 UTC. -- Mar 29 03:59:20 volumiohappyhouse volumio[700]: verbose: New Socket.io Connection to 192.168.100.11 from 192.168.100.9 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) SamsungBrowser/29.0 Chrome/136.0.0.0 Mobile Safari/537.36 Total Clients: 11 Mar 29 03:59:20 volumiohappyhouse volumio[700]: verbose: New Socket.io Connection to 192.168.100.11 from 192.168.100.9 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) SamsungBrowser/29.0 Chrome/136.0.0.0 Mobile Safari/537.36 Total Clients: 12 Mar 29 03:59:20 volumiohappyhouse volumio[700]: verbose: New Socket.io Connection to 192.168.100.11 from 192.168.100.9 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) SamsungBrowser/29.0 Chrome/136.0.0.0 Mobile Safari/537.36 Total Clients: 13 Mar 29 03:59:20 volumiohappyhouse volumio[700]: verbose: New Socket.io Connection to 192.168.100.11 from 192.168.100.9 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) SamsungBrowser/29.0 Chrome/136.0.0.0 Mobile Safari/537.36 Total Clients: 14 Mar 29 03:59:32 volumiohappyhouse volumio[700]: verbose: New Socket.io Connection to 192.168.100.11 from 192.168.100.9 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) SamsungBrowser/29.0 Chrome/136.0.0.0 Mobile Safari/537.36 Total Clients: 15 Mar 29 03:59:32 volumiohappyhouse volumio[700]: verbose: New Socket.io Connection to 192.168.100.11 from 192.168.100.9 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) SamsungBrowser/29.0 Chrome/136.0.0.0 Mobile Safari/537.36 Total Clients: 16 Mar 29 03:59:34 volumiohappyhouse volumio[700]: info: CoreCommandRouter::volumioGetVisibleSources Mar 29 03:59:34 volumiohappyhouse volumio[700]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 03:59:34 volumiohappyhouse volumio[700]: info: CoreCommandRouter::volumioGetState Mar 29 03:59:34 volumiohappyhouse volumio[700]: info: CoreCommandRouter::volumioGetQueue Mar 29 03:59:34 volumiohappyhouse volumio[700]: info: CoreStateMachine::getQueue Mar 29 03:59:34 volumiohappyhouse volumio[700]: info: CorePlayQueue::getQueue Mar 29 03:59:34 volumiohappyhouse volumio[700]: info: Listing playlists Mar 29 03:59:35 volumiohappyhouse volumio[700]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 29 03:59:35 volumiohappyhouse volumio[700]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 29 03:59:35 volumiohappyhouse volumio[700]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 29 03:59:35 volumiohappyhouse volumio[700]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 29 03:59:35 volumiohappyhouse volumio[700]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 29 03:59:35 volumiohappyhouse volumio[700]: info: CoreCommandRouter::volumioGetVisibleSources Mar 29 03:59:35 volumiohappyhouse volumio[700]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 03:59:35 volumiohappyhouse volumio[700]: info: CoreCommandRouter::volumioGetState Mar 29 03:59:35 volumiohappyhouse volumio[700]: info: CoreCommandRouter::volumioGetQueue Mar 29 03:59:35 volumiohappyhouse volumio[700]: info: CoreStateMachine::getQueue Mar 29 03:59:35 volumiohappyhouse volumio[700]: info: CorePlayQueue::getQueue Mar 29 03:59:36 volumiohappyhouse volumio[700]: info: Listing playlists Mar 29 03:59:36 volumiohappyhouse volumio[700]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 29 03:59:42 volumiohappyhouse volumio[700]: info: CorePlayQueue::getTrack 63 Mar 29 03:59:42 volumiohappyhouse volumio[700]: info: CorePlayQueue::getTrack 9 Mar 29 03:59:42 volumiohappyhouse volumio[700]: info: Prefetching next song Mar 29 03:59:42 volumiohappyhouse volumio[700]: info: Doing Prefetch in UPNP Mar 29 03:59:42 volumiohappyhouse volumio[700]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.100.17:56923/content/fc83b01440fcebf867015b3d52685c09.mp3" Mar 29 03:59:42 volumiohappyhouse volumio[700]: info: Mar 29 03:59:42 volumiohappyhouse volumio[700]: ---------------------------- MPD announces system playlist update Mar 29 03:59:42 volumiohappyhouse volumio[700]: info: Ignoring MPD Status Update Mar 29 03:59:42 volumiohappyhouse volumio[700]: info: sendMpdCommand add "http://192.168.100.17:56923/content/fc83b01440fcebf867015b3d52685c09.mp3" took 60 milliseconds Mar 29 03:59:42 volumiohappyhouse volumio[700]: verbose: ControllerMpd::sendMpdCommand consume 1 Mar 29 03:59:42 volumiohappyhouse volumio[700]: info: Mar 29 03:59:42 volumiohappyhouse volumio[700]: ---------------------------- MPD announces system playlist update Mar 29 03:59:42 volumiohappyhouse volumio[700]: info: Ignoring MPD Status Update Mar 29 03:59:42 volumiohappyhouse volumio[700]: info: Mar 29 03:59:42 volumiohappyhouse volumio[700]: ---------------------------- MPD announces system playlist update Mar 29 03:59:42 volumiohappyhouse volumio[700]: info: Ignoring MPD Status Update Mar 29 03:59:42 volumiohappyhouse volumio[700]: info: Mar 29 03:59:42 volumiohappyhouse volumio[700]: ---------------------------- MPD announces system playlist update Mar 29 03:59:42 volumiohappyhouse volumio[700]: info: Ignoring MPD Status Update Mar 29 03:59:42 volumiohappyhouse volumio[700]: info: ------------------------------ 130ms Mar 29 03:59:42 volumiohappyhouse volumio[700]: info: sendMpdCommand consume 1 took 98 milliseconds Mar 29 03:59:42 volumiohappyhouse volumio[700]: info: ------------------------------ 88ms Mar 29 03:59:42 volumiohappyhouse volumio[700]: info: ------------------------------ 75ms Mar 29 03:59:42 volumiohappyhouse volumio[700]: info: ------------------------------ 64ms Mar 29 03:59:43 volumiohappyhouse volumio[700]: info: CoreCommandRouter::volumioPause Mar 29 03:59:43 volumiohappyhouse volumio[700]: info: CoreStateMachine::pause Mar 29 03:59:43 volumiohappyhouse volumio[700]: info: CoreStateMachine::stPlaybackTimer Mar 29 03:59:43 volumiohappyhouse volumio[700]: info: CoreStateMachine::servicePause Mar 29 03:59:43 volumiohappyhouse volumio[700]: info: CorePlayQueue::getTrack 63 Mar 29 03:59:43 volumiohappyhouse volumio[700]: info: CoreCommandRouter::servicePause Mar 29 03:59:43 volumiohappyhouse volumio[700]: info: [1774756783233] ControllerUPNPBrowser::pause Mar 29 03:59:43 volumiohappyhouse volumio[700]: verbose: ControllerMpd::sendMpdCommand pause Mar 29 03:59:43 volumiohappyhouse volumio[700]: info: Mar 29 03:59:43 volumiohappyhouse volumio[700]: ---------------------------- MPD announces state update: player Mar 29 03:59:43 volumiohappyhouse volumio[700]: info: sendMpdCommand pause took 20 milliseconds Mar 29 03:59:43 volumiohappyhouse volumio[700]: info: ControllerMpd::getState Mar 29 03:59:43 volumiohappyhouse volumio[700]: verbose: ControllerMpd::sendMpdCommand status Mar 29 03:59:43 volumiohappyhouse volumio[700]: info: Mar 29 03:59:43 volumiohappyhouse volumio[700]: ---------------------------- MPD announces state update: player Mar 29 03:59:43 volumiohappyhouse volumio[700]: info: ControllerMpd::getState Mar 29 03:59:43 volumiohappyhouse volumio[700]: verbose: ControllerMpd::sendMpdCommand status Mar 29 03:59:43 volumiohappyhouse volumio[700]: info: Mar 29 03:59:43 volumiohappyhouse volumio[700]: ---------------------------- MPD announces state update: player Mar 29 03:59:43 volumiohappyhouse volumio[700]: info: ControllerMpd::getState Mar 29 03:59:43 volumiohappyhouse volumio[700]: verbose: ControllerMpd::sendMpdCommand status Mar 29 03:59:43 volumiohappyhouse volumio[700]: info: Mar 29 03:59:43 volumiohappyhouse volumio[700]: ---------------------------- MPD announces state update: player Mar 29 03:59:43 volumiohappyhouse volumio[700]: info: ControllerMpd::getState Mar 29 03:59:43 volumiohappyhouse volumio[700]: verbose: ControllerMpd::sendMpdCommand status Mar 29 03:59:43 volumiohappyhouse volumio[700]: info: sendMpdCommand status took 79 milliseconds Mar 29 03:59:43 volumiohappyhouse volumio[700]: info: sendMpdCommand status took 61 milliseconds Mar 29 03:59:43 volumiohappyhouse volumio[700]: info: sendMpdCommand status took 45 milliseconds Mar 29 03:59:43 volumiohappyhouse volumio[700]: info: sendMpdCommand status took 30 milliseconds Mar 29 03:59:43 volumiohappyhouse volumio[700]: verbose: ControllerMpd::parseState Mar 29 03:59:43 volumiohappyhouse volumio[700]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 03:59:43 volumiohappyhouse volumio[700]: verbose: ControllerMpd::parseState Mar 29 03:59:43 volumiohappyhouse volumio[700]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 03:59:43 volumiohappyhouse volumio[700]: verbose: ControllerMpd::parseState Mar 29 03:59:43 volumiohappyhouse volumio[700]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 03:59:43 volumiohappyhouse volumio[700]: verbose: ControllerMpd::parseState Mar 29 03:59:43 volumiohappyhouse volumio[700]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 03:59:43 volumiohappyhouse volumio[700]: info: sendMpdCommand playlistinfo took 48 milliseconds Mar 29 03:59:43 volumiohappyhouse volumio[700]: info: sendMpdCommand playlistinfo took 44 milliseconds Mar 29 03:59:43 volumiohappyhouse volumio[700]: info: sendMpdCommand playlistinfo took 39 milliseconds Mar 29 03:59:43 volumiohappyhouse volumio[700]: info: sendMpdCommand playlistinfo took 35 milliseconds Mar 29 03:59:43 volumiohappyhouse volumio[700]: verbose: ControllerMpd::parseTrackInfo Mar 29 03:59:43 volumiohappyhouse volumio[700]: verbose: ControllerMpd::parseTrackInfo Mar 29 03:59:43 volumiohappyhouse volumio[700]: verbose: ControllerMpd::parseTrackInfo Mar 29 03:59:43 volumiohappyhouse volumio[700]: verbose: ControllerMpd::parseTrackInfo Mar 29 03:59:43 volumiohappyhouse volumio[700]: info: ControllerMpd::pushState Mar 29 03:59:43 volumiohappyhouse volumio[700]: info: CoreCommandRouter::servicePushState Mar 29 03:59:43 volumiohappyhouse volumio[700]: info: CorePlayQueue::getTrack 63 Mar 29 03:59:43 volumiohappyhouse volumio[700]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":82628,"duration":226,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"32f032b74ec8dbbcf83b69e8532f4caf.wav?profile_id=0&convert=wav","artist":null,"album":null,"uri":"http://192.168.100.17:56923/content/32f032b74ec8dbbcf83b69e8532f4caf.wav?profile_id=0&convert=wav","trackType":""} Mar 29 03:59:43 volumiohappyhouse volumio[700]: verbose: CURRENT POSITION 63 Mar 29 03:59:43 volumiohappyhouse volumio[700]: info: CoreStateMachine::syncState stateService pause Mar 29 03:59:43 volumiohappyhouse volumio[700]: info: CoreStateMachine::syncState currentStatus pause Mar 29 03:59:43 volumiohappyhouse volumio[700]: info: CoreStateMachine::pushState Mar 29 03:59:43 volumiohappyhouse volumio[700]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 03:59:43 volumiohappyhouse volumio[700]: info: CoreCommandRouter::volumioPushState Mar 29 03:59:43 volumiohappyhouse volumio[700]: info: CoreStateMachine::stPlaybackTimer Mar 29 03:59:43 volumiohappyhouse volumio[700]: info: ControllerMpd::pushState Mar 29 03:59:43 volumiohappyhouse volumio[700]: info: CoreCommandRouter::servicePushState Mar 29 03:59:43 volumiohappyhouse volumio[700]: info: CorePlayQueue::getTrack 63 Mar 29 03:59:43 volumiohappyhouse volumio[700]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":82628,"duration":226,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"32f032b74ec8dbbcf83b69e8532f4caf.wav?profile_id=0&convert=wav","artist":null,"album":null,"uri":"http://192.168.100.17:56923/content/32f032b74ec8dbbcf83b69e8532f4caf.wav?profile_id=0&convert=wav","trackType":""} Mar 29 03:59:43 volumiohappyhouse volumio[700]: verbose: CURRENT POSITION 63 Mar 29 03:59:43 volumiohappyhouse volumio[700]: info: CoreStateMachine::syncState stateService pause Mar 29 03:59:43 volumiohappyhouse volumio[700]: info: CoreStateMachine::syncState currentStatus pause Mar 29 03:59:43 volumiohappyhouse volumio[700]: info: CoreStateMachine::pushState Mar 29 03:59:43 volumiohappyhouse volumio[700]: info: CoreCommandRouter::volumioPushState Mar 29 03:59:43 volumiohappyhouse volumio[700]: info: CoreStateMachine::stPlaybackTimer Mar 29 03:59:43 volumiohappyhouse volumio[700]: info: ControllerMpd::pushState Mar 29 03:59:43 volumiohappyhouse volumio[700]: info: CoreCommandRouter::servicePushState Mar 29 03:59:43 volumiohappyhouse volumio[700]: info: CorePlayQueue::getTrack 63 Mar 29 03:59:43 volumiohappyhouse volumio[700]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":82628,"duration":226,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"32f032b74ec8dbbcf83b69e8532f4caf.wav?profile_id=0&convert=wav","artist":null,"album":null,"uri":"http://192.168.100.17:56923/content/32f032b74ec8dbbcf83b69e8532f4caf.wav?profile_id=0&convert=wav","trackType":""} Mar 29 03:59:43 volumiohappyhouse volumio[700]: verbose: CURRENT POSITION 63 Mar 29 03:59:43 volumiohappyhouse volumio[700]: info: CoreStateMachine::syncState stateService pause Mar 29 03:59:43 volumiohappyhouse volumio[700]: info: CoreStateMachine::syncState currentStatus pause Mar 29 03:59:43 volumiohappyhouse volumio[700]: info: CoreStateMachine::pushState Mar 29 03:59:43 volumiohappyhouse volumio[700]: info: CoreCommandRouter::volumioPushState Mar 29 03:59:43 volumiohappyhouse volumio[700]: info: CoreStateMachine::stPlaybackTimer Mar 29 03:59:43 volumiohappyhouse volumio[700]: info: ControllerMpd::pushState Mar 29 03:59:43 volumiohappyhouse volumio[700]: info: CoreCommandRouter::servicePushState Mar 29 03:59:43 volumiohappyhouse volumio[700]: info: CorePlayQueue::getTrack 63 Mar 29 03:59:43 volumiohappyhouse volumio[700]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":82628,"duration":226,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"32f032b74ec8dbbcf83b69e8532f4caf.wav?profile_id=0&convert=wav","artist":null,"album":null,"uri":"http://192.168.100.17:56923/content/32f032b74ec8dbbcf83b69e8532f4caf.wav?profile_id=0&convert=wav","trackType":""} Mar 29 03:59:43 volumiohappyhouse volumio[700]: verbose: CURRENT POSITION 63 Mar 29 03:59:43 volumiohappyhouse volumio[700]: info: CoreStateMachine::syncState stateService pause Mar 29 03:59:43 volumiohappyhouse volumio[700]: info: CoreStateMachine::syncState currentStatus pause Mar 29 03:59:43 volumiohappyhouse volumio[700]: info: CoreStateMachine::pushState Mar 29 03:59:43 volumiohappyhouse volumio[700]: info: CoreCommandRouter::volumioPushState Mar 29 03:59:43 volumiohappyhouse volumio[700]: info: CoreStateMachine::stPlaybackTimer Mar 29 03:59:43 volumiohappyhouse volumio[700]: info: ------------------------------ 645ms Mar 29 03:59:43 volumiohappyhouse volumio[700]: info: ------------------------------ 620ms Mar 29 03:59:43 volumiohappyhouse volumio[700]: info: ------------------------------ 608ms Mar 29 03:59:43 volumiohappyhouse volumio[700]: info: ------------------------------ 595ms Mar 29 04:00:17 volumiohappyhouse volumio[700]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 29 04:00:17 volumiohappyhouse volumio[700]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 29 04:00:17 volumiohappyhouse volumio[700]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 29 04:00:17 volumiohappyhouse volumio[700]: info: Error : CoreCommandRouter::executeOnPlugin: No method [retreiveBackendEventStates] in plugin my_volumio Mar 29 04:00:17 volumiohappyhouse volumio[700]: info: Received Get System Version Mar 29 04:00:17 volumiohappyhouse volumio[700]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 29 04:00:17 volumiohappyhouse volumio[700]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Mar 29 04:00:31 volumiohappyhouse volumio[700]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Mar 29 04:00:33 volumiohappyhouse volumio[700]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Mar 29 04:00:51 volumiohappyhouse volumio[700]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 29 04:00:51 volumiohappyhouse volumio[700]: info: CoreStateMachine::ClearQueue Mar 29 04:00:51 volumiohappyhouse volumio[700]: info: CoreStateMachine::stop Mar 29 04:00:51 volumiohappyhouse volumio[700]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 04:00:51 volumiohappyhouse volumio[700]: verbose: UNSET VOLATILE Mar 29 04:00:51 volumiohappyhouse volumio[700]: info: CoreStateMachine::updateTrackBlock Mar 29 04:00:51 volumiohappyhouse volumio[700]: info: CorePlayQueue::getTrackBlock Mar 29 04:00:51 volumiohappyhouse volumio[700]: info: CoreStateMachine::stPlaybackTimer Mar 29 04:00:51 volumiohappyhouse volumio[700]: info: CoreStateMachine::pushState Mar 29 04:00:51 volumiohappyhouse volumio[700]: info: CorePlayQueue::getTrack 63 Mar 29 04:00:51 volumiohappyhouse volumio[700]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 04:00:51 volumiohappyhouse volumio[700]: info: CoreCommandRouter::volumioPushState Mar 29 04:00:51 volumiohappyhouse volumio[700]: info: CoreStateMachine::serviceStop Mar 29 04:00:51 volumiohappyhouse volumio[700]: info: CorePlayQueue::getTrack 63 Mar 29 04:00:51 volumiohappyhouse volumio[700]: info: CoreCommandRouter::serviceStop Mar 29 04:00:51 volumiohappyhouse volumio[700]: info: [1774756851411] ControllerUPNPBrowser::stop Mar 29 04:00:51 volumiohappyhouse volumio[700]: verbose: ControllerMpd::sendMpdCommand stop Mar 29 04:00:51 volumiohappyhouse volumio[700]: info: CorePlayQueue::clearPlayQueue Mar 29 04:00:51 volumiohappyhouse volumio[700]: info: CorePlayQueue::saveQueue Mar 29 04:00:51 volumiohappyhouse volumio[700]: info: CoreCommandRouter::volumioPushState Mar 29 04:00:51 volumiohappyhouse volumio[700]: info: CoreCommandRouter::volumioPushQueue Mar 29 04:00:51 volumiohappyhouse volumio[700]: info: CoreStateMachine::addQueueItems Mar 29 04:00:51 volumiohappyhouse volumio[700]: info: CorePlayQueue::addQueueItems Mar 29 04:00:51 volumiohappyhouse volumio[700]: info: Adding Item to queue: webkbs/0 Mar 29 04:00:51 volumiohappyhouse volumio[700]: info: Exploding uri webkbs/0 in service personal_radio Mar 29 04:00:51 volumiohappyhouse volumio[700]: info: Mar 29 04:00:51 volumiohappyhouse volumio[700]: ---------------------------- MPD announces state update: player Mar 29 04:00:51 volumiohappyhouse volumio[700]: info: sendMpdCommand stop took 330 milliseconds Mar 29 04:00:51 volumiohappyhouse volumio[700]: info: ControllerMpd::getState Mar 29 04:00:51 volumiohappyhouse volumio[700]: verbose: ControllerMpd::sendMpdCommand status Mar 29 04:00:51 volumiohappyhouse volumio[700]: info: Mar 29 04:00:51 volumiohappyhouse volumio[700]: ---------------------------- MPD announces state update: player Mar 29 04:00:51 volumiohappyhouse volumio[700]: info: ControllerMpd::getState Mar 29 04:00:51 volumiohappyhouse volumio[700]: verbose: ControllerMpd::sendMpdCommand status Mar 29 04:00:51 volumiohappyhouse volumio[700]: info: Mar 29 04:00:51 volumiohappyhouse volumio[700]: ---------------------------- MPD announces state update: player Mar 29 04:00:51 volumiohappyhouse volumio[700]: info: ControllerMpd::getState Mar 29 04:00:51 volumiohappyhouse volumio[700]: verbose: ControllerMpd::sendMpdCommand status Mar 29 04:00:51 volumiohappyhouse volumio[700]: info: Mar 29 04:00:51 volumiohappyhouse volumio[700]: ---------------------------- MPD announces state update: player Mar 29 04:00:51 volumiohappyhouse volumio[700]: info: ControllerMpd::getState Mar 29 04:00:51 volumiohappyhouse volumio[700]: verbose: ControllerMpd::sendMpdCommand status Mar 29 04:00:51 volumiohappyhouse volumio[700]: info: sendMpdCommand status took 107 milliseconds Mar 29 04:00:51 volumiohappyhouse volumio[700]: info: sendMpdCommand status took 88 milliseconds Mar 29 04:00:51 volumiohappyhouse volumio[700]: info: sendMpdCommand status took 74 milliseconds Mar 29 04:00:51 volumiohappyhouse volumio[700]: info: sendMpdCommand status took 57 milliseconds Mar 29 04:00:51 volumiohappyhouse volumio[700]: verbose: ControllerMpd::parseState Mar 29 04:00:51 volumiohappyhouse volumio[700]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 04:00:51 volumiohappyhouse volumio[700]: verbose: ControllerMpd::parseState Mar 29 04:00:51 volumiohappyhouse volumio[700]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 04:00:51 volumiohappyhouse volumio[700]: verbose: ControllerMpd::parseState Mar 29 04:00:51 volumiohappyhouse volumio[700]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 04:00:51 volumiohappyhouse volumio[700]: verbose: ControllerMpd::parseState Mar 29 04:00:51 volumiohappyhouse volumio[700]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 04:00:51 volumiohappyhouse volumio[700]: error: Upnp client error: Error: This socket has been ended by the other party Mar 29 04:00:51 volumiohappyhouse volumio[700]: info: sendMpdCommand playlistinfo took 64 milliseconds Mar 29 04:00:51 volumiohappyhouse volumio[700]: info: sendMpdCommand playlistinfo took 60 milliseconds Mar 29 04:00:51 volumiohappyhouse volumio[700]: info: sendMpdCommand playlistinfo took 58 milliseconds Mar 29 04:00:51 volumiohappyhouse volumio[700]: info: sendMpdCommand playlistinfo took 54 milliseconds Mar 29 04:00:51 volumiohappyhouse volumio[700]: verbose: ControllerMpd::parseTrackInfo Mar 29 04:00:51 volumiohappyhouse volumio[700]: verbose: ControllerMpd::parseTrackInfo Mar 29 04:00:51 volumiohappyhouse volumio[700]: verbose: ControllerMpd::parseTrackInfo Mar 29 04:00:51 volumiohappyhouse volumio[700]: verbose: ControllerMpd::parseTrackInfo Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: ControllerMpd::pushState Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: CoreCommandRouter::servicePushState Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: CoreStateMachine::pushState Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: CorePlayQueue::getTrack 63 Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: CoreCommandRouter::volumioPushState Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: CorePlayQueue::getTrack 63 Mar 29 04:00:52 volumiohappyhouse volumio[700]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"32f032b74ec8dbbcf83b69e8532f4caf.wav?profile_id=0&convert=wav","artist":null,"album":null,"uri":"http://192.168.100.17:56923/content/32f032b74ec8dbbcf83b69e8532f4caf.wav?profile_id=0&convert=wav","trackType":""} Mar 29 04:00:52 volumiohappyhouse volumio[700]: verbose: CURRENT POSITION 63 Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: CoreStateMachine::syncState stateService stop Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: CoreStateMachine::syncState currentStatus stop Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: CoreStateMachine::pushState Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: CorePlayQueue::getTrack 63 Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: CoreCommandRouter::volumioPushState Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: No code Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: CoreStateMachine::pushState Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: CorePlayQueue::getTrack 63 Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: CoreCommandRouter::volumioPushState Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: ControllerMpd::pushState Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: CoreCommandRouter::servicePushState Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: CoreStateMachine::pushState Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: CorePlayQueue::getTrack 63 Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: CoreCommandRouter::volumioPushState Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: CorePlayQueue::getTrack 63 Mar 29 04:00:52 volumiohappyhouse volumio[700]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"32f032b74ec8dbbcf83b69e8532f4caf.wav?profile_id=0&convert=wav","artist":null,"album":null,"uri":"http://192.168.100.17:56923/content/32f032b74ec8dbbcf83b69e8532f4caf.wav?profile_id=0&convert=wav","trackType":""} Mar 29 04:00:52 volumiohappyhouse volumio[700]: verbose: CURRENT POSITION 63 Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: CoreStateMachine::syncState stateService stop Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: CoreStateMachine::syncState currentStatus stop Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: CoreStateMachine::pushState Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: CorePlayQueue::getTrack 63 Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: CoreCommandRouter::volumioPushState Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: No code Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: CoreStateMachine::pushState Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: CorePlayQueue::getTrack 63 Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: CoreCommandRouter::volumioPushState Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: ControllerMpd::pushState Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: CoreCommandRouter::servicePushState Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: CoreStateMachine::pushState Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: CorePlayQueue::getTrack 63 Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: CoreCommandRouter::volumioPushState Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: CorePlayQueue::getTrack 63 Mar 29 04:00:52 volumiohappyhouse volumio[700]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"32f032b74ec8dbbcf83b69e8532f4caf.wav?profile_id=0&convert=wav","artist":null,"album":null,"uri":"http://192.168.100.17:56923/content/32f032b74ec8dbbcf83b69e8532f4caf.wav?profile_id=0&convert=wav","trackType":""} Mar 29 04:00:52 volumiohappyhouse volumio[700]: verbose: CURRENT POSITION 63 Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: CoreStateMachine::syncState stateService stop Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: CoreStateMachine::syncState currentStatus stop Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: CoreStateMachine::pushState Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: CorePlayQueue::getTrack 63 Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: CoreCommandRouter::volumioPushState Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: No code Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: CoreStateMachine::pushState Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: CorePlayQueue::getTrack 63 Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: CoreCommandRouter::volumioPushState Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: ControllerMpd::pushState Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: CoreCommandRouter::servicePushState Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: CoreStateMachine::pushState Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: CorePlayQueue::getTrack 63 Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: CoreCommandRouter::volumioPushState Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: CorePlayQueue::getTrack 63 Mar 29 04:00:52 volumiohappyhouse volumio[700]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"32f032b74ec8dbbcf83b69e8532f4caf.wav?profile_id=0&convert=wav","artist":null,"album":null,"uri":"http://192.168.100.17:56923/content/32f032b74ec8dbbcf83b69e8532f4caf.wav?profile_id=0&convert=wav","trackType":""} Mar 29 04:00:52 volumiohappyhouse volumio[700]: verbose: CURRENT POSITION 63 Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: CoreStateMachine::syncState stateService stop Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: CoreStateMachine::syncState currentStatus stop Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: CoreStateMachine::pushState Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: CorePlayQueue::getTrack 63 Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: CoreCommandRouter::volumioPushState Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: No code Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: CoreStateMachine::pushState Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: CorePlayQueue::getTrack 63 Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: CoreCommandRouter::volumioPushState Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: ------------------------------ 908ms Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: ------------------------------ 892ms Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: ------------------------------ 882ms Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: ------------------------------ 873ms Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: CoreStateMachine::ClearQueue Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: CoreStateMachine::stop Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 04:00:52 volumiohappyhouse volumio[700]: verbose: UNSET VOLATILE Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: CorePlayQueue::clearPlayQueue Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: CorePlayQueue::saveQueue Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: CoreCommandRouter::volumioPushState Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: CoreCommandRouter::volumioPushQueue Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: CoreStateMachine::addQueueItems Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: CorePlayQueue::addQueueItems Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: Adding Item to queue: webkbs/0 Mar 29 04:00:52 volumiohappyhouse volumio[700]: info: Exploding uri webkbs/0 in service personal_radio Mar 29 04:00:53 volumiohappyhouse volumio[700]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 29 04:00:53 volumiohappyhouse volumio[700]: ReferenceError: self is not defined Mar 29 04:00:53 volumiohappyhouse volumio[700]: at ControllerPersonalRadio.errorToast (/data/plugins/music_service/personal_radio/index.js:575:22) Mar 29 04:00:53 volumiohappyhouse volumio[700]: at /data/plugins/music_service/personal_radio/index.js:487:12 Mar 29 04:00:53 volumiohappyhouse volumio[700]: at Request.handleRequestResponse [as _callback] (/data/plugins/music_service/personal_radio/node_modules/unirest/index.js:355:15) Mar 29 04:00:53 volumiohappyhouse volumio[700]: at self.callback (/data/plugins/music_service/personal_radio/node_modules/request/request.js:187:22) Mar 29 04:00:53 volumiohappyhouse volumio[700]: at Request.emit (events.js:315:20) Mar 29 04:00:53 volumiohappyhouse volumio[700]: at Request.onRequestError (/data/plugins/music_service/personal_radio/node_modules/request/request.js:813:8) Mar 29 04:00:53 volumiohappyhouse volumio[700]: at ClientRequest.emit (events.js:315:20) Mar 29 04:00:53 volumiohappyhouse volumio[700]: at Socket.socketErrorListener (_http_client.js:469:9) Mar 29 04:00:53 volumiohappyhouse volumio[700]: at Socket.emit (events.js:315:20) Mar 29 04:00:53 volumiohappyhouse volumio[700]: at emitErrorNT (internal/streams/destroy.js:106:8) Mar 29 04:00:53 volumiohappyhouse volumio[700]: at emitErrorCloseNT (internal/streams/destroy.js:74:3) Mar 29 04:00:53 volumiohappyhouse volumio[700]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Mar 29 04:00:53 volumiohappyhouse volumio[700]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 29 04:01:12 volumiohappyhouse sudo[4888]: volumio : unable to resolve host volumiohappyhouse Mar 29 04:01:12 volumiohappyhouse sudo[4888]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 29 04:01:12 volumiohappyhouse sudo[4888]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-03-29 03:59 Mar 29 04:01:12 volumiohappyhouse sudo[4888]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="e5f8795c07ba36ff2b02d7ba0a21c7a0f29b897e" VOLUMIO_FE_VERSION="473d8facbed5f9754db84a81b093baace7aa4b64" VOLUMIO_FE3_VERSION="934cddf6d46478e7a53a8d3664cd346f4de7cb34" VOLUMIO_BE_VERSION="359b2717b79cfc72b7d1c918d0fc2885a93bca70" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 24 Jan 2022 10:51:57 AM CET" VOLUMIO_VERSION="3.198" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="74e6a9ad035e912732893c8d6b060b17"