-- Logs begin at Thu 2019-02-14 18:11:59 CST, end at Thu 2025-03-06 09:44:43 CST. -- Mar 06 09:43:38 smsl-n100 volumio[5197]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 06 09:43:38 smsl-n100 volumio[5197]: info: Preload queue cleared Mar 06 09:43:39 smsl-n100 volumio[5197]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 06 09:43:40 smsl-n100 volumio[5197]: info: Preload queue cleared Mar 06 09:43:42 smsl-n100 volumio[5197]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 06 09:43:42 smsl-n100 volumio[5197]: info: Preload queue cleared Mar 06 09:43:47 smsl-n100 volumio[5197]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 06 09:43:47 smsl-n100 volumio[5197]: info: Preload queue cleared Mar 06 09:43:49 smsl-n100 volumio[5197]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 06 09:43:49 smsl-n100 volumio[5197]: info: Preload queue cleared Mar 06 09:43:51 smsl-n100 volumio[5197]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 06 09:43:51 smsl-n100 volumio[5197]: info: Preload queue cleared Mar 06 09:43:53 smsl-n100 volumio[5197]: info: Preload queue cleared Mar 06 09:43:53 smsl-n100 volumio[5197]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 06 09:43:53 smsl-n100 volumio[5197]: info: CoreStateMachine::ClearQueue Mar 06 09:43:53 smsl-n100 volumio[5197]: info: CoreStateMachine::stop Mar 06 09:43:53 smsl-n100 volumio[5197]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 06 09:43:53 smsl-n100 volumio[5197]: info: CoreStateMachine::stPlaybackTimer Mar 06 09:43:53 smsl-n100 volumio[5197]: info: CoreStateMachine::updateTrackBlock Mar 06 09:43:53 smsl-n100 volumio[5197]: info: CorePlayQueue::getTrackBlock Mar 06 09:43:53 smsl-n100 volumio[5197]: info: CoreStateMachine::pushState Mar 06 09:43:53 smsl-n100 volumio[5197]: info: CorePlayQueue::getTrack 4 Mar 06 09:43:53 smsl-n100 volumio[5197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 06 09:43:53 smsl-n100 volumio[5197]: info: CoreCommandRouter::volumioPushState Mar 06 09:43:53 smsl-n100 volumio[5197]: info: MRS: Pushing multiroomSync output update for this device Mar 06 09:43:53 smsl-n100 volumio[5197]: info: MRS: Pushing multiroomSync output Mar 06 09:43:53 smsl-n100 volumio[5197]: info: CoreStateMachine::serviceStop Mar 06 09:43:53 smsl-n100 volumio[5197]: info: CorePlayQueue::getTrack 4 Mar 06 09:43:53 smsl-n100 volumio[5197]: info: CoreCommandRouter::serviceStop Mar 06 09:43:53 smsl-n100 volumio[5197]: info: [1741225433899] ControllerUPNPBrowser::stop Mar 06 09:43:53 smsl-n100 volumio[5197]: verbose: ControllerMpd::sendMpdCommand stop Mar 06 09:43:53 smsl-n100 volumio[5197]: info: CorePlayQueue::clearPlayQueue Mar 06 09:43:53 smsl-n100 volumio[5197]: info: CorePlayQueue::saveQueue Mar 06 09:43:53 smsl-n100 volumio[5197]: info: CoreCommandRouter::volumioPushQueue Mar 06 09:43:53 smsl-n100 volumio[5197]: info: CoreStateMachine::addQueueItems Mar 06 09:43:53 smsl-n100 volumio[5197]: info: CorePlayQueue::addQueueItems Mar 06 09:43:53 smsl-n100 volumio[5197]: info: Preload queue cleared Mar 06 09:43:53 smsl-n100 volumio[5197]: info: Adding Item to queue: upnp/http://192.168.2.51:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@14_502ad853_c4e53aa_a49571a_6b5590d9-65343 Mar 06 09:43:53 smsl-n100 volumio[5197]: info: Using cached record of: upnp/http://192.168.2.51:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@14_502ad853_c4e53aa_a49571a_6b5590d9-65343 Mar 06 09:43:53 smsl-n100 volumio[5197]: info: Adding Item to queue: upnp/http://192.168.2.51:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@14_502ad853_c4e53aa_a49571a_6b5590d9-65344 Mar 06 09:43:53 smsl-n100 volumio[5197]: info: Using cached record of: upnp/http://192.168.2.51:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@14_502ad853_c4e53aa_a49571a_6b5590d9-65344 Mar 06 09:43:53 smsl-n100 volumio[5197]: info: Adding Item to queue: upnp/http://192.168.2.51:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@14_502ad853_c4e53aa_a49571a_6b5590d9-65345 Mar 06 09:43:53 smsl-n100 volumio[5197]: info: Using cached record of: upnp/http://192.168.2.51:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@14_502ad853_c4e53aa_a49571a_6b5590d9-65345 Mar 06 09:43:53 smsl-n100 volumio[5197]: info: Adding Item to queue: upnp/http://192.168.2.51:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@14_502ad853_c4e53aa_a49571a_6b5590d9-65346 Mar 06 09:43:53 smsl-n100 volumio[5197]: info: Using cached record of: upnp/http://192.168.2.51:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@14_502ad853_c4e53aa_a49571a_6b5590d9-65346 Mar 06 09:43:53 smsl-n100 volumio[5197]: info: Adding Item to queue: upnp/http://192.168.2.51:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@14_502ad853_c4e53aa_a49571a_6b5590d9-65347 Mar 06 09:43:53 smsl-n100 volumio[5197]: info: Using cached record of: upnp/http://192.168.2.51:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@14_502ad853_c4e53aa_a49571a_6b5590d9-65347 Mar 06 09:43:53 smsl-n100 volumio[5197]: info: CoreCommandRouter::volumioPushQueue Mar 06 09:43:53 smsl-n100 volumio[5197]: info: CorePlayQueue::saveQueue Mar 06 09:43:53 smsl-n100 volumio[5197]: info: CoreStateMachine::updateTrackBlock Mar 06 09:43:53 smsl-n100 volumio[5197]: info: CorePlayQueue::getTrackBlock Mar 06 09:43:53 smsl-n100 volumio[5197]: info: CoreCommandRouter::volumioPlay Mar 06 09:43:53 smsl-n100 volumio[5197]: info: CoreStateMachine::play index 4 Mar 06 09:43:53 smsl-n100 volumio[5197]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 06 09:43:53 smsl-n100 volumio[5197]: info: CoreStateMachine::addQueueItems Mar 06 09:43:53 smsl-n100 volumio[5197]: info: CorePlayQueue::addQueueItems Mar 06 09:43:53 smsl-n100 volumio[5197]: info: Preload queue cleared Mar 06 09:43:53 smsl-n100 volumio[5197]: info: Adding Item to queue: upnp/http://192.168.2.51:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@14_502ad853_c4e53aa_a49571a_6b5590d9-65348 Mar 06 09:43:53 smsl-n100 volumio[5197]: info: Using cached record of: upnp/http://192.168.2.51:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@14_502ad853_c4e53aa_a49571a_6b5590d9-65348 Mar 06 09:43:53 smsl-n100 volumio[5197]: info: Adding Item to queue: upnp/http://192.168.2.51:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@14_502ad853_c4e53aa_a49571a_6b5590d9-65349 Mar 06 09:43:53 smsl-n100 volumio[5197]: info: Using cached record of: upnp/http://192.168.2.51:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@14_502ad853_c4e53aa_a49571a_6b5590d9-65349 Mar 06 09:43:53 smsl-n100 volumio[5197]: info: Adding Item to queue: upnp/http://192.168.2.51:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@14_502ad853_c4e53aa_a49571a_6b5590d9-65350 Mar 06 09:43:53 smsl-n100 volumio[5197]: info: Using cached record of: upnp/http://192.168.2.51:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@14_502ad853_c4e53aa_a49571a_6b5590d9-65350 Mar 06 09:43:53 smsl-n100 volumio[5197]: info: Adding Item to queue: upnp/http://192.168.2.51:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@14_502ad853_c4e53aa_a49571a_6b5590d9-65351 Mar 06 09:43:53 smsl-n100 volumio[5197]: info: Using cached record of: upnp/http://192.168.2.51:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@14_502ad853_c4e53aa_a49571a_6b5590d9-65351 Mar 06 09:43:53 smsl-n100 volumio[5197]: info: Adding Item to queue: upnp/http://192.168.2.51:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@14_502ad853_c4e53aa_a49571a_6b5590d9-65352 Mar 06 09:43:53 smsl-n100 volumio[5197]: info: Using cached record of: upnp/http://192.168.2.51:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@14_502ad853_c4e53aa_a49571a_6b5590d9-65352 Mar 06 09:43:53 smsl-n100 volumio[5197]: info: Adding Item to queue: upnp/http://192.168.2.51:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@14_502ad853_c4e53aa_a49571a_6b5590d9-65355 Mar 06 09:43:53 smsl-n100 volumio[5197]: info: Using cached record of: upnp/http://192.168.2.51:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@14_502ad853_c4e53aa_a49571a_6b5590d9-65355 Mar 06 09:43:53 smsl-n100 volumio[5197]: info: Adding Item to queue: upnp/http://192.168.2.51:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@14_502ad853_c4e53aa_a49571a_6b5590d9-65396 Mar 06 09:43:53 smsl-n100 volumio[5197]: info: Using cached record of: upnp/http://192.168.2.51:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@14_502ad853_c4e53aa_a49571a_6b5590d9-65396 Mar 06 09:43:53 smsl-n100 volumio[5197]: info: Adding Item to queue: upnp/http://192.168.2.51:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@14_502ad853_c4e53aa_a49571a_6b5590d9-65405 Mar 06 09:43:53 smsl-n100 volumio[5197]: info: Using cached record of: upnp/http://192.168.2.51:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@14_502ad853_c4e53aa_a49571a_6b5590d9-65405 Mar 06 09:43:53 smsl-n100 volumio[5197]: info: Adding Item to queue: upnp/http://192.168.2.51:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@14_502ad853_c4e53aa_a49571a_6b5590d9-65414 Mar 06 09:43:53 smsl-n100 volumio[5197]: info: Using cached record of: upnp/http://192.168.2.51:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@14_502ad853_c4e53aa_a49571a_6b5590d9-65414 Mar 06 09:43:53 smsl-n100 volumio[5197]: info: Adding Item to queue: upnp/http://192.168.2.51:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@14_502ad853_c4e53aa_a49571a_6b5590d9-65431 Mar 06 09:43:53 smsl-n100 volumio[5197]: info: Using cached record of: upnp/http://192.168.2.51:2869/upnphost/udhisapi.dll?control=uuid:90636e5c-73d0-49ab-aaba-a3038aea7dd4+urn:upnp-org:serviceId:ContentDirectory@14_502ad853_c4e53aa_a49571a_6b5590d9-65431 Mar 06 09:43:53 smsl-n100 volumio[5197]: info: CoreStateMachine::stop Mar 06 09:43:53 smsl-n100 volumio[5197]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 06 09:43:53 smsl-n100 volumio[5197]: info: CoreCommandRouter::volumioPushQueue Mar 06 09:43:53 smsl-n100 volumio[5197]: info: CorePlayQueue::saveQueue Mar 06 09:43:54 smsl-n100 volumio[5197]: info: CoreStateMachine::play index undefined Mar 06 09:43:54 smsl-n100 volumio[5197]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 06 09:43:54 smsl-n100 volumio[5197]: info: CoreStateMachine::updateTrackBlock Mar 06 09:43:54 smsl-n100 volumio[5197]: info: CorePlayQueue::getTrackBlock Mar 06 09:43:54 smsl-n100 volumio[5197]: info: CorePlayQueue::getTrack 4 Mar 06 09:43:54 smsl-n100 volumio[5197]: info: CoreStateMachine::startPlaybackTimer Mar 06 09:43:54 smsl-n100 volumio[5197]: info: CorePlayQueue::getTrack 4 Mar 06 09:43:54 smsl-n100 volumio[5197]: info: [1741225434006] ControllerUPNPBrowser::clearAddPlayTrack Mar 06 09:43:54 smsl-n100 volumio[5197]: verbose: ControllerMpd::sendMpdCommand stop Mar 06 09:43:54 smsl-n100 volumio[5197]: info: Mar 06 09:43:54 smsl-n100 volumio[5197]: ---------------------------- MPD announces state update: player Mar 06 09:43:54 smsl-n100 volumio[5197]: info: sendMpdCommand stop took 140 milliseconds Mar 06 09:43:54 smsl-n100 volumio[5197]: info: ControllerMpd::getState Mar 06 09:43:54 smsl-n100 volumio[5197]: verbose: ControllerMpd::sendMpdCommand status Mar 06 09:43:54 smsl-n100 volumio[5197]: info: sendMpdCommand stop took 34 milliseconds Mar 06 09:43:54 smsl-n100 volumio[5197]: verbose: ControllerMpd::sendMpdCommand clear Mar 06 09:43:54 smsl-n100 volumio[5197]: info: Mar 06 09:43:54 smsl-n100 volumio[5197]: ---------------------------- MPD announces state update: player Mar 06 09:43:54 smsl-n100 volumio[5197]: info: ControllerMpd::getState Mar 06 09:43:54 smsl-n100 volumio[5197]: verbose: ControllerMpd::sendMpdCommand status Mar 06 09:43:54 smsl-n100 volumio[5197]: info: Mar 06 09:43:54 smsl-n100 volumio[5197]: ---------------------------- MPD announces system playlist update Mar 06 09:43:54 smsl-n100 volumio[5197]: info: Ignoring MPD Status Update Mar 06 09:43:54 smsl-n100 volumio[5197]: info: sendMpdCommand status took 17 milliseconds Mar 06 09:43:54 smsl-n100 volumio[5197]: info: sendMpdCommand clear took 15 milliseconds Mar 06 09:43:54 smsl-n100 volumio[5197]: info: sendMpdCommand status took 10 milliseconds Mar 06 09:43:54 smsl-n100 volumio[5197]: verbose: ControllerMpd::parseState Mar 06 09:43:54 smsl-n100 volumio[5197]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 06 09:43:54 smsl-n100 volumio[5197]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.2.51:10243/WMPNSSv4/2700598965/0_MTRfNTAyYWQ4NTNfYzRlNTNhYV9hNDk1NzFhXzZiNTU5MGQ5LTY1MzQ3" Mar 06 09:43:54 smsl-n100 volumio[5197]: verbose: ControllerMpd::parseState Mar 06 09:43:54 smsl-n100 volumio[5197]: info: ControllerMpd::pushState Mar 06 09:43:54 smsl-n100 volumio[5197]: info: CoreCommandRouter::servicePushState Mar 06 09:43:54 smsl-n100 volumio[5197]: info: CoreStateMachine::pushState Mar 06 09:43:54 smsl-n100 volumio[5197]: info: CorePlayQueue::getTrack 4 Mar 06 09:43:54 smsl-n100 volumio[5197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 06 09:43:54 smsl-n100 volumio[5197]: info: CoreCommandRouter::volumioPushState Mar 06 09:43:54 smsl-n100 volumio[5197]: info: MRS: Pushing multiroomSync output update for this device Mar 06 09:43:54 smsl-n100 volumio[5197]: info: MRS: Pushing multiroomSync output Mar 06 09:43:54 smsl-n100 volumio[5197]: info: CorePlayQueue::getTrack 4 Mar 06 09:43:54 smsl-n100 volumio[5197]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 06 09:43:54 smsl-n100 volumio[5197]: verbose: CURRENT POSITION 4 Mar 06 09:43:54 smsl-n100 volumio[5197]: info: CoreStateMachine::syncState stateService stop Mar 06 09:43:54 smsl-n100 volumio[5197]: info: CoreStateMachine::syncState currentStatus stop Mar 06 09:43:54 smsl-n100 volumio[5197]: info: CoreStateMachine::pushState Mar 06 09:43:54 smsl-n100 volumio[5197]: info: CorePlayQueue::getTrack 4 Mar 06 09:43:54 smsl-n100 volumio[5197]: info: CoreCommandRouter::volumioPushState Mar 06 09:43:54 smsl-n100 volumio[5197]: info: MRS: Pushing multiroomSync output update for this device Mar 06 09:43:54 smsl-n100 volumio[5197]: info: MRS: Pushing multiroomSync output Mar 06 09:43:54 smsl-n100 volumio[5197]: info: No code Mar 06 09:43:54 smsl-n100 volumio[5197]: info: CoreStateMachine::pushState Mar 06 09:43:54 smsl-n100 volumio[5197]: info: CorePlayQueue::getTrack 4 Mar 06 09:43:54 smsl-n100 volumio[5197]: info: CoreCommandRouter::volumioPushState Mar 06 09:43:54 smsl-n100 volumio[5197]: info: MRS: Pushing multiroomSync output update for this device Mar 06 09:43:54 smsl-n100 volumio[5197]: info: MRS: Pushing multiroomSync output Mar 06 09:43:54 smsl-n100 volumio[5197]: info: ------------------------------ 143ms Mar 06 09:43:54 smsl-n100 volumio[5197]: info: Mar 06 09:43:54 smsl-n100 volumio[5197]: ---------------------------- MPD announces system playlist update Mar 06 09:43:54 smsl-n100 volumio[5197]: info: Ignoring MPD Status Update Mar 06 09:43:54 smsl-n100 volumio[5197]: error: updateQueue error: null Mar 06 09:43:54 smsl-n100 volumio[5197]: error: updateQueue error: null Mar 06 09:43:54 smsl-n100 volumio[5197]: info: ------------------------------ 153ms Mar 06 09:43:54 smsl-n100 volumio[5197]: info: sendMpdCommand playlistinfo took 147 milliseconds Mar 06 09:43:54 smsl-n100 volumio[5197]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.2.51:10243/WMPNSSv4/2700598965/0_MTRfNTAyYWQ4NTNfYzRlNTNhYV9hNDk1NzFhXzZiNTU5MGQ5LTY1MzQ3" Mar 06 09:43:54 smsl-n100 volumio[5197]: info: ------------------------------ 19ms Mar 06 09:43:54 smsl-n100 volumio[5197]: verbose: ControllerMpd::parseTrackInfo Mar 06 09:43:54 smsl-n100 volumio[5197]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 06 09:43:54 smsl-n100 volumio[5197]: info: ------------------------------ 180ms Mar 06 09:43:54 smsl-n100 volumio[5197]: info: Mar 06 09:43:54 smsl-n100 volumio[5197]: ---------------------------- MPD announces system playlist update Mar 06 09:43:54 smsl-n100 volumio[5197]: info: Ignoring MPD Status Update Mar 06 09:43:54 smsl-n100 volumio[5197]: info: sendMpdCommand add "http://192.168.2.51:10243/WMPNSSv4/2700598965/0_MTRfNTAyYWQ4NTNfYzRlNTNhYV9hNDk1NzFhXzZiNTU5MGQ5LTY1MzQ3" took 42 milliseconds Mar 06 09:43:54 smsl-n100 volumio[5197]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 06 09:43:54 smsl-n100 volumio[5197]: verbose: ControllerMpd::sendMpdCommand play Mar 06 09:43:54 smsl-n100 volumio[5197]: info: CoreStateMachine::pushState Mar 06 09:43:54 smsl-n100 volumio[5197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 06 09:43:54 smsl-n100 volumio[5197]: info: CoreCommandRouter::volumioPushState Mar 06 09:43:54 smsl-n100 volumio[5197]: info: MRS: Pushing multiroomSync output update for this device Mar 06 09:43:54 smsl-n100 volumio[5197]: info: MRS: Pushing multiroomSync output Mar 06 09:43:54 smsl-n100 volumio[5197]: error: Serial API: Failed to decode command: 100, message: undefined Mar 06 09:43:54 smsl-n100 volumio[5197]: error: Serial API: Failed to decode command: MAXVOL, message: Mar 06 09:43:54 smsl-n100 volumio[5197]: error: Serial API: Failed to decode command: 100, message: undefined Mar 06 09:43:54 smsl-n100 volumio[5197]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Mar 06 09:43:54 smsl-n100 volumio[5197]: info: Mar 06 09:43:54 smsl-n100 volumio[5197]: ---------------------------- MPD announces system playlist update Mar 06 09:43:54 smsl-n100 volumio[5197]: info: Ignoring MPD Status Update Mar 06 09:43:54 smsl-n100 volumio[5197]: info: Mar 06 09:43:54 smsl-n100 volumio[5197]: ---------------------------- MPD announces state update: player Mar 06 09:43:54 smsl-n100 volumio[5197]: info: Mar 06 09:43:54 smsl-n100 volumio[5197]: ---------------------------- MPD announces system playlist update Mar 06 09:43:54 smsl-n100 volumio[5197]: info: Ignoring MPD Status Update Mar 06 09:43:54 smsl-n100 volumio[5197]: info: Mar 06 09:43:54 smsl-n100 volumio[5197]: ---------------------------- MPD announces state update: player Mar 06 09:43:54 smsl-n100 volumio[5197]: info: ------------------------------ 117ms Mar 06 09:43:54 smsl-n100 volumio[5197]: info: sendMpdCommand play took 114 milliseconds Mar 06 09:43:54 smsl-n100 volumio[5197]: info: ControllerMpd::getState Mar 06 09:43:54 smsl-n100 volumio[5197]: verbose: ControllerMpd::sendMpdCommand status Mar 06 09:43:54 smsl-n100 volumio[5197]: info: ------------------------------ 37ms Mar 06 09:43:54 smsl-n100 volumio[5197]: info: ControllerMpd::getState Mar 06 09:43:54 smsl-n100 volumio[5197]: verbose: ControllerMpd::sendMpdCommand status Mar 06 09:43:54 smsl-n100 volumio[5197]: info: Mar 06 09:43:54 smsl-n100 volumio[5197]: ---------------------------- MPD announces system playlist update Mar 06 09:43:54 smsl-n100 volumio[5197]: info: Ignoring MPD Status Update Mar 06 09:43:54 smsl-n100 volumio[5197]: info: Mar 06 09:43:54 smsl-n100 volumio[5197]: ---------------------------- MPD announces state update: player Mar 06 09:43:54 smsl-n100 volumio[5197]: info: ControllerMpd::getState Mar 06 09:43:54 smsl-n100 volumio[5197]: verbose: ControllerMpd::sendMpdCommand status Mar 06 09:43:54 smsl-n100 volumio[5197]: info: sendMpdCommand status took 111 milliseconds Mar 06 09:43:54 smsl-n100 volumio[5197]: info: ------------------------------ 130ms Mar 06 09:43:54 smsl-n100 volumio[5197]: info: sendMpdCommand status took 112 milliseconds Mar 06 09:43:54 smsl-n100 volumio[5197]: info: ------------------------------ 102ms Mar 06 09:43:54 smsl-n100 volumio[5197]: info: sendMpdCommand status took 94 milliseconds Mar 06 09:43:54 smsl-n100 volumio[5197]: verbose: ControllerMpd::parseState Mar 06 09:43:54 smsl-n100 volumio[5197]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 06 09:43:54 smsl-n100 volumio[5197]: verbose: ControllerMpd::parseState Mar 06 09:43:54 smsl-n100 volumio[5197]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 06 09:43:54 smsl-n100 volumio[5197]: verbose: ControllerMpd::parseState Mar 06 09:43:54 smsl-n100 volumio[5197]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 06 09:43:54 smsl-n100 volumio[5197]: info: sendMpdCommand playlistinfo took 31 milliseconds Mar 06 09:43:54 smsl-n100 volumio[5197]: info: sendMpdCommand playlistinfo took 29 milliseconds Mar 06 09:43:54 smsl-n100 volumio[5197]: info: sendMpdCommand playlistinfo took 27 milliseconds Mar 06 09:43:54 smsl-n100 volumio[5197]: verbose: ControllerMpd::parseTrackInfo Mar 06 09:43:54 smsl-n100 volumio[5197]: verbose: ControllerMpd::parseTrackInfo Mar 06 09:43:54 smsl-n100 volumio[5197]: verbose: ControllerMpd::parseTrackInfo Mar 06 09:43:54 smsl-n100 volumio[5197]: info: ControllerMpd::pushState Mar 06 09:43:54 smsl-n100 volumio[5197]: info: CoreCommandRouter::servicePushState Mar 06 09:43:54 smsl-n100 volumio[5197]: info: CorePlayQueue::getTrack 4 Mar 06 09:43:54 smsl-n100 volumio[5197]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1349,"duration":288,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Track 13","artist":null,"album":null,"uri":"http://192.168.2.51:10243/WMPNSSv4/2700598965/0_MTRfNTAyYWQ4NTNfYzRlNTNhYV9hNDk1NzFhXzZiNTU5MGQ5LTY1MzQ3","trackType":""} Mar 06 09:43:54 smsl-n100 volumio[5197]: verbose: CURRENT POSITION 4 Mar 06 09:43:54 smsl-n100 volumio[5197]: info: CoreStateMachine::syncState stateService play Mar 06 09:43:54 smsl-n100 volumio[5197]: info: CoreStateMachine::syncState currentStatus stop Mar 06 09:43:54 smsl-n100 volumio[5197]: info: ControllerMpd::pushState Mar 06 09:43:54 smsl-n100 volumio[5197]: info: CoreCommandRouter::servicePushState Mar 06 09:43:54 smsl-n100 volumio[5197]: info: CorePlayQueue::getTrack 4 Mar 06 09:43:54 smsl-n100 volumio[5197]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1349,"duration":288,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Track 13","artist":null,"album":null,"uri":"http://192.168.2.51:10243/WMPNSSv4/2700598965/0_MTRfNTAyYWQ4NTNfYzRlNTNhYV9hNDk1NzFhXzZiNTU5MGQ5LTY1MzQ3","trackType":""} Mar 06 09:43:54 smsl-n100 volumio[5197]: verbose: CURRENT POSITION 4 Mar 06 09:43:54 smsl-n100 volumio[5197]: info: CoreStateMachine::syncState stateService play Mar 06 09:43:54 smsl-n100 volumio[5197]: info: CoreStateMachine::syncState currentStatus play Mar 06 09:43:54 smsl-n100 volumio[5197]: info: Received an update from plugin. extracting info from payload Mar 06 09:43:54 smsl-n100 volumio[5197]: info: CoreStateMachine::pushState Mar 06 09:43:54 smsl-n100 volumio[5197]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 06 09:43:54 smsl-n100 volumio[5197]: info: CoreCommandRouter::volumioPushState Mar 06 09:43:54 smsl-n100 volumio[5197]: info: MRS: Pushing multiroomSync output update for this device Mar 06 09:43:54 smsl-n100 volumio[5197]: info: MRS: Pushing multiroomSync output Mar 06 09:43:54 smsl-n100 volumio[5197]: info: CoreStateMachine::pushState Mar 06 09:43:54 smsl-n100 volumio[5197]: info: CoreCommandRouter::volumioPushState Mar 06 09:43:54 smsl-n100 volumio[5197]: info: MRS: Pushing multiroomSync output update for this device Mar 06 09:43:54 smsl-n100 volumio[5197]: info: MRS: Pushing multiroomSync output Mar 06 09:43:54 smsl-n100 volumio[5197]: info: ControllerMpd::pushState Mar 06 09:43:54 smsl-n100 volumio[5197]: info: CoreCommandRouter::servicePushState Mar 06 09:43:54 smsl-n100 volumio[5197]: info: CorePlayQueue::getTrack 4 Mar 06 09:43:54 smsl-n100 volumio[5197]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1349,"duration":288,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Track 13","artist":null,"album":null,"uri":"http://192.168.2.51:10243/WMPNSSv4/2700598965/0_MTRfNTAyYWQ4NTNfYzRlNTNhYV9hNDk1NzFhXzZiNTU5MGQ5LTY1MzQ3","trackType":""} Mar 06 09:43:54 smsl-n100 volumio[5197]: verbose: CURRENT POSITION 4 Mar 06 09:43:54 smsl-n100 volumio[5197]: info: CoreStateMachine::syncState stateService play Mar 06 09:43:54 smsl-n100 volumio[5197]: info: CoreStateMachine::syncState currentStatus play Mar 06 09:43:54 smsl-n100 volumio[5197]: info: Received an update from plugin. extracting info from payload Mar 06 09:43:54 smsl-n100 volumio[5197]: info: CoreStateMachine::pushState Mar 06 09:43:54 smsl-n100 volumio[5197]: info: CoreCommandRouter::volumioPushState Mar 06 09:43:54 smsl-n100 volumio[5197]: info: MRS: Pushing multiroomSync output update for this device Mar 06 09:43:54 smsl-n100 volumio[5197]: info: MRS: Pushing multiroomSync output Mar 06 09:43:54 smsl-n100 volumio[5197]: info: CoreStateMachine::pushState Mar 06 09:43:54 smsl-n100 volumio[5197]: info: CoreCommandRouter::volumioPushState Mar 06 09:43:54 smsl-n100 volumio[5197]: info: MRS: Pushing multiroomSync output update for this device Mar 06 09:43:54 smsl-n100 volumio[5197]: info: MRS: Pushing multiroomSync output Mar 06 09:43:54 smsl-n100 volumio[5197]: info: ------------------------------ 400ms Mar 06 09:43:54 smsl-n100 volumio[5197]: info: ------------------------------ 552ms Mar 06 09:43:54 smsl-n100 volumio[5197]: info: ------------------------------ 525ms Mar 06 09:44:42 smsl-n100 volumio[5197]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 06 09:44:42 smsl-n100 volumio[5197]: Error: connect ETIMEDOUT 185.45.7.97:80 Mar 06 09:44:42 smsl-n100 volumio[5197]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Mar 06 09:44:42 smsl-n100 volumio[5197]: errno: -110, Mar 06 09:44:42 smsl-n100 volumio[5197]: code: 'ETIMEDOUT', Mar 06 09:44:42 smsl-n100 volumio[5197]: syscall: 'connect', Mar 06 09:44:42 smsl-n100 volumio[5197]: address: '185.45.7.97', Mar 06 09:44:42 smsl-n100 volumio[5197]: port: 80 Mar 06 09:44:42 smsl-n100 volumio[5197]: } Mar 06 09:44:42 smsl-n100 volumio[5197]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 06 09:44:43 smsl-n100 sudo[10126]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-06 09:43 Mar 06 09:44:43 smsl-n100 sudo[10126]: 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="cc0042a1eab1cb7982711a1347e242f8947b54b6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="723ba1d50268911818ebdca474fb7656df6050cb" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="smsl" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 14 Feb 2025 02:46:15 PM CET" VOLUMIO_VERSION="3.789" VOLUMIO_HARDWARE="nanopineo3" VOLUMIO_DEVICENAME="Nanopi Neo3" VOLUMIO_VENDOR_MODEL="SMSL S100" VOLUMIO_VENDOR="SMSL" VOLUMIO_MODEL="S100" VOLUMIO_HASH="215d731aee688e00fb3cc923952be9a7"