-- Logs begin at Sun 2025-06-08 16:29:34 EDT, end at Sun 2025-06-08 16:32:04 EDT. -- Jun 08 16:31:00 volumio volumio[1066]: info: TidalConnect service stoped! Jun 08 16:31:00 volumio volumio[1066]: info: TidalConnect service stoped! Jun 08 16:31:00 volumio volumio[1066]: info: TidalConnect service stoped! Jun 08 16:31:00 volumio volumio[1066]: info: TidalConnect service stoped! Jun 08 16:31:00 volumio volumio[1066]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 08 16:31:00 volumio volumio[1066]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 08 16:31:00 volumio volumio[1066]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 08 16:31:00 volumio volumio[1066]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 08 16:31:00 volumio sudo[2054]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jun 08 16:31:00 volumio sudo[2054]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 08 16:31:00 volumio sudo[2057]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jun 08 16:31:00 volumio sudo[2057]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 08 16:31:00 volumio systemd[1]: Started Volumio Tidal Connect Service. Jun 08 16:31:00 volumio sudo[2054]: pam_unix(sudo:session): session closed for user root Jun 08 16:31:00 volumio sudo[2057]: pam_unix(sudo:session): session closed for user root Jun 08 16:31:00 volumio volumio[1066]: info: Executing endpoint tc_getconfig Jun 08 16:31:00 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jun 08 16:31:00 volumio vtcs[2059]: STARTING TidalConnect services, version: 1.4.0.34 Jun 08 16:31:00 volumio vtcs[2059]: STARTED TidalConnect services. Jun 08 16:31:00 volumio volumio[1066]: info: Executing endpoint tc_connect Jun 08 16:31:00 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jun 08 16:31:00 volumio volumio[1066]: info: Connecting to TidalConnect Jun 08 16:31:00 volumio volumio[1066]: info: CoreCommandRouter::servicePushState Jun 08 16:31:00 volumio volumio[1066]: info: CoreStateMachine::pushState Jun 08 16:31:00 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Jun 08 16:31:00 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 16:31:00 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Jun 08 16:31:00 volumio volumio[1066]: info: MRS: Pushing multiroomSync output update for this device Jun 08 16:31:00 volumio volumio[1066]: info: MRS: Pushing multiroomSync output Jun 08 16:31:00 volumio volumio[1066]: info: CoreCommandRouter::volumioGetState Jun 08 16:31:00 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Jun 08 16:31:00 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Jun 08 16:31:00 volumio volumio[1066]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received tidalconnect Jun 08 16:31:00 volumio volumio[1066]: info: CoreCommandRouter::servicePushState Jun 08 16:31:00 volumio volumio[1066]: info: CoreStateMachine::pushState Jun 08 16:31:00 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Jun 08 16:31:00 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Jun 08 16:31:00 volumio volumio[1066]: info: MRS: Pushing multiroomSync output update for this device Jun 08 16:31:00 volumio volumio[1066]: info: MRS: Pushing multiroomSync output Jun 08 16:31:00 volumio volumio[1066]: info: CoreCommandRouter::volumioGetState Jun 08 16:31:00 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Jun 08 16:31:00 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Jun 08 16:31:00 volumio volumio[1066]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received tidalconnect Jun 08 16:31:00 volumio volumio[1066]: info: TidalConnect service stoped! Jun 08 16:31:00 volumio volumio[1066]: info: TidalConnect service stoped! Jun 08 16:31:00 volumio volumio[1066]: info: TidalConnect service stoped! Jun 08 16:31:00 volumio volumio[1066]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 08 16:31:00 volumio volumio[1066]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 08 16:31:00 volumio volumio[1066]: info: TidalConnect service stoped! Jun 08 16:31:00 volumio sudo[2088]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jun 08 16:31:00 volumio sudo[2088]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 08 16:31:01 volumio sudo[2088]: pam_unix(sudo:session): session closed for user root Jun 08 16:31:01 volumio volumio[1066]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 08 16:31:01 volumio volumio[1066]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 08 16:31:01 volumio sudo[2100]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jun 08 16:31:01 volumio sudo[2100]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 08 16:31:01 volumio sudo[2100]: pam_unix(sudo:session): session closed for user root Jun 08 16:31:01 volumio volumio[1066]: info: TidalConnect service stoped! Jun 08 16:31:01 volumio volumio[1066]: info: TidalConnect service stoped! Jun 08 16:31:01 volumio volumio[1066]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 08 16:31:01 volumio volumio[1066]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 08 16:31:01 volumio sudo[2112]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jun 08 16:31:01 volumio sudo[2112]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 08 16:31:01 volumio sudo[2112]: pam_unix(sudo:session): session closed for user root Jun 08 16:31:01 volumio volumio[1066]: info: TidalConnect service stoped! Jun 08 16:31:01 volumio volumio[1066]: info: TidalConnect service stoped! Jun 08 16:31:01 volumio volumio[1066]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jun 08 16:31:01 volumio volumio[1066]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Jun 08 16:31:01 volumio sudo[2124]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jun 08 16:31:01 volumio sudo[2124]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 08 16:31:01 volumio sudo[2124]: pam_unix(sudo:session): session closed for user root Jun 08 16:31:01 volumio volumio[1066]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jun 08 16:31:01 volumio volumio[1066]: info: CoreStateMachine::getcurrentVolume Jun 08 16:31:01 volumio volumio[1066]: info: CoreCommandRouter::volumioRetrievevolume Jun 08 16:31:01 volumio volumio[1066]: info: CoreStateMachine::pushState Jun 08 16:31:01 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Jun 08 16:31:01 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Jun 08 16:31:01 volumio volumio[1066]: info: MRS: Pushing multiroomSync output update for this device Jun 08 16:31:01 volumio volumio[1066]: info: MRS: Pushing multiroomSync output Jun 08 16:31:01 volumio volumio[1066]: info: CoreCommandRouter::volumioGetState Jun 08 16:31:01 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Jun 08 16:31:03 volumio volumio[1066]: info: TidalConnect service started! Jun 08 16:31:03 volumio volumio[1066]: info: TidalConnect service started! Jun 08 16:31:04 volumio volumio[1066]: info: TidalConnect service started! Jun 08 16:31:04 volumio volumio[1066]: info: TidalConnect service started! Jun 08 16:31:04 volumio volumio[1066]: info: TidalConnect service started! Jun 08 16:31:04 volumio volumio[1066]: info: TidalConnect service started! Jun 08 16:31:11 volumio volumio[1066]: info: CoreCommandRouter::volumioPlay Jun 08 16:31:11 volumio volumio[1066]: info: CoreStateMachine::play index undefined Jun 08 16:31:11 volumio volumio[1066]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 08 16:31:11 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Jun 08 16:31:11 volumio volumio[1066]: info: CoreStateMachine::startPlaybackTimer Jun 08 16:31:11 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Jun 08 16:31:11 volumio volumio[1066]: info: CoreCommandRouter::volumioGetVisibleSources Jun 08 16:31:11 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 08 16:31:11 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jun 08 16:31:11 volumio volumio[1066]: info: [1749414671051] ControllerWebradio::clearAddPlayTrack Jun 08 16:31:11 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand stop Jun 08 16:31:11 volumio volumio[1066]: info: sendMpdCommand stop took 1 milliseconds Jun 08 16:31:11 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand clear Jun 08 16:31:11 volumio volumio[1066]: info: Jun 08 16:31:11 volumio volumio[1066]: ---------------------------- MPD announces system playlist update Jun 08 16:31:11 volumio volumio[1066]: info: Ignoring MPD Status Update Jun 08 16:31:11 volumio volumio[1066]: info: sendMpdCommand clear took 0 milliseconds Jun 08 16:31:11 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand load "https://absolut-ai.live-sm.absolutradio.de/absolut-ai/stream/mp3" Jun 08 16:31:11 volumio volumio[1066]: info: Jun 08 16:31:11 volumio volumio[1066]: ---------------------------- MPD announces system playlist update Jun 08 16:31:11 volumio volumio[1066]: info: Ignoring MPD Status Update Jun 08 16:31:11 volumio volumio[1066]: info: Jun 08 16:31:11 volumio volumio[1066]: ---------------------------- MPD announces system playlist update Jun 08 16:31:11 volumio volumio[1066]: info: Ignoring MPD Status Update Jun 08 16:31:11 volumio volumio[1066]: info: Jun 08 16:31:11 volumio volumio[1066]: ---------------------------- MPD announces system playlist update Jun 08 16:31:11 volumio volumio[1066]: info: Ignoring MPD Status Update Jun 08 16:31:11 volumio volumio[1066]: error: updateQueue error: null Jun 08 16:31:11 volumio volumio[1066]: info: ------------------------------ 2ms Jun 08 16:31:12 volumio go-librespot[1671]: time="2025-06-08T16:31:12-04:00" level=trace msg="sent dealer ping" Jun 08 16:31:12 volumio go-librespot[1671]: time="2025-06-08T16:31:12-04:00" level=trace msg="received dealer pong" Jun 08 16:31:13 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand add "https://absolut-ai.live-sm.absolutradio.de/absolut-ai/stream/mp3" Jun 08 16:31:13 volumio volumio[1066]: error: updateQueue error: null Jun 08 16:31:13 volumio volumio[1066]: info: ------------------------------ 2436ms Jun 08 16:31:13 volumio volumio[1066]: info: ------------------------------ 2436ms Jun 08 16:31:13 volumio volumio[1066]: info: Jun 08 16:31:13 volumio volumio[1066]: ---------------------------- MPD announces system playlist update Jun 08 16:31:13 volumio volumio[1066]: info: Ignoring MPD Status Update Jun 08 16:31:13 volumio volumio[1066]: info: sendMpdCommand add "https://absolut-ai.live-sm.absolutradio.de/absolut-ai/stream/mp3" took 1 milliseconds Jun 08 16:31:13 volumio volumio[1066]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 08 16:31:13 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand play Jun 08 16:31:13 volumio volumio[1066]: info: Jun 08 16:31:13 volumio volumio[1066]: ---------------------------- MPD announces system playlist update Jun 08 16:31:13 volumio volumio[1066]: info: Ignoring MPD Status Update Jun 08 16:31:13 volumio volumio[1066]: info: Jun 08 16:31:13 volumio volumio[1066]: ---------------------------- MPD announces system playlist update Jun 08 16:31:13 volumio volumio[1066]: info: Ignoring MPD Status Update Jun 08 16:31:13 volumio volumio[1066]: info: Jun 08 16:31:13 volumio volumio[1066]: ---------------------------- MPD announces system playlist update Jun 08 16:31:13 volumio volumio[1066]: info: Ignoring MPD Status Update Jun 08 16:31:13 volumio volumio[1066]: info: ------------------------------ 1ms Jun 08 16:31:13 volumio volumio[1066]: info: sendMpdCommand play took 2 milliseconds Jun 08 16:31:13 volumio volumio[1066]: info: ------------------------------ 2ms Jun 08 16:31:13 volumio volumio[1066]: info: ------------------------------ 1ms Jun 08 16:31:13 volumio volumio[1066]: info: ------------------------------ 1ms Jun 08 16:31:14 volumio volumio[1066]: info: Jun 08 16:31:14 volumio volumio[1066]: ---------------------------- MPD announces state update: player Jun 08 16:31:14 volumio volumio[1066]: info: ControllerMpd::getState Jun 08 16:31:14 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Jun 08 16:31:14 volumio volumio[1066]: info: Jun 08 16:31:14 volumio volumio[1066]: ---------------------------- MPD announces state update: player Jun 08 16:31:14 volumio volumio[1066]: info: ControllerMpd::getState Jun 08 16:31:14 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Jun 08 16:31:14 volumio volumio[1066]: info: Jun 08 16:31:14 volumio volumio[1066]: ---------------------------- MPD announces state update: player Jun 08 16:31:14 volumio volumio[1066]: info: ControllerMpd::getState Jun 08 16:31:14 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Jun 08 16:31:14 volumio volumio[1066]: info: Jun 08 16:31:14 volumio volumio[1066]: ---------------------------- MPD announces state update: player Jun 08 16:31:14 volumio volumio[1066]: info: ControllerMpd::getState Jun 08 16:31:14 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Jun 08 16:31:14 volumio volumio[1066]: info: Jun 08 16:31:14 volumio volumio[1066]: ---------------------------- MPD announces state update: player Jun 08 16:31:14 volumio volumio[1066]: info: sendMpdCommand status took 2 milliseconds Jun 08 16:31:14 volumio volumio[1066]: info: sendMpdCommand status took 2 milliseconds Jun 08 16:31:14 volumio volumio[1066]: info: sendMpdCommand status took 2 milliseconds Jun 08 16:31:14 volumio volumio[1066]: info: ControllerMpd::getState Jun 08 16:31:14 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Jun 08 16:31:14 volumio volumio[1066]: verbose: ControllerMpd::parseState Jun 08 16:31:14 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 16:31:14 volumio volumio[1066]: verbose: ControllerMpd::parseState Jun 08 16:31:14 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 16:31:14 volumio volumio[1066]: verbose: ControllerMpd::parseState Jun 08 16:31:14 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 16:31:14 volumio volumio[1066]: info: sendMpdCommand status took 2 milliseconds Jun 08 16:31:14 volumio volumio[1066]: info: sendMpdCommand status took 1 milliseconds Jun 08 16:31:14 volumio volumio[1066]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 08 16:31:14 volumio volumio[1066]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 08 16:31:14 volumio volumio[1066]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 08 16:31:14 volumio volumio[1066]: verbose: ControllerMpd::parseState Jun 08 16:31:14 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 16:31:14 volumio volumio[1066]: verbose: ControllerMpd::parseState Jun 08 16:31:14 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 16:31:14 volumio volumio[1066]: verbose: ControllerMpd::parseTrackInfo Jun 08 16:31:14 volumio volumio[1066]: verbose: ControllerMpd::parseTrackInfo Jun 08 16:31:14 volumio volumio[1066]: verbose: ControllerMpd::parseTrackInfo Jun 08 16:31:14 volumio volumio[1066]: info: ControllerMpd::pushState Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::servicePushState Jun 08 16:31:14 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Jun 08 16:31:14 volumio volumio[1066]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3","artist":null,"album":null,"uri":"https://absolut-ai.live-sm.absolutradio.de/absolut-ai/stream/mp3","trackType":""} Jun 08 16:31:14 volumio volumio[1066]: verbose: CURRENT POSITION 0 Jun 08 16:31:14 volumio volumio[1066]: info: CoreStateMachine::syncState stateService play Jun 08 16:31:14 volumio volumio[1066]: info: CoreStateMachine::syncState currentStatus stop Jun 08 16:31:14 volumio volumio[1066]: info: ControllerMpd::pushState Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::servicePushState Jun 08 16:31:14 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Jun 08 16:31:14 volumio volumio[1066]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3","artist":null,"album":null,"uri":"https://absolut-ai.live-sm.absolutradio.de/absolut-ai/stream/mp3","trackType":""} Jun 08 16:31:14 volumio volumio[1066]: verbose: CURRENT POSITION 0 Jun 08 16:31:14 volumio volumio[1066]: info: CoreStateMachine::syncState stateService play Jun 08 16:31:14 volumio volumio[1066]: info: CoreStateMachine::syncState currentStatus play Jun 08 16:31:14 volumio volumio[1066]: info: Received an update from plugin. extracting info from payload Jun 08 16:31:14 volumio volumio[1066]: info: CoreStateMachine::pushState Jun 08 16:31:14 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Jun 08 16:31:14 volumio volumio[1066]: info: MRS: Pushing multiroomSync output update for this device Jun 08 16:31:14 volumio volumio[1066]: info: MRS: Pushing multiroomSync output Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::volumioGetState Jun 08 16:31:14 volumio volumio[1066]: info: CoreStateMachine::pushState Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Jun 08 16:31:14 volumio volumio[1066]: info: MRS: Pushing multiroomSync output update for this device Jun 08 16:31:14 volumio volumio[1066]: info: MRS: Pushing multiroomSync output Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::volumioGetState Jun 08 16:31:14 volumio volumio[1066]: info: ControllerMpd::pushState Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::servicePushState Jun 08 16:31:14 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Jun 08 16:31:14 volumio volumio[1066]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3","artist":null,"album":null,"uri":"https://absolut-ai.live-sm.absolutradio.de/absolut-ai/stream/mp3","trackType":""} Jun 08 16:31:14 volumio volumio[1066]: verbose: CURRENT POSITION 0 Jun 08 16:31:14 volumio volumio[1066]: info: CoreStateMachine::syncState stateService play Jun 08 16:31:14 volumio volumio[1066]: info: CoreStateMachine::syncState currentStatus play Jun 08 16:31:14 volumio volumio[1066]: info: Received an update from plugin. extracting info from payload Jun 08 16:31:14 volumio volumio[1066]: info: CoreStateMachine::pushState Jun 08 16:31:14 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Jun 08 16:31:14 volumio volumio[1066]: info: MRS: Pushing multiroomSync output update for this device Jun 08 16:31:14 volumio volumio[1066]: info: MRS: Pushing multiroomSync output Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::volumioGetState Jun 08 16:31:14 volumio volumio[1066]: info: CoreStateMachine::pushState Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Jun 08 16:31:14 volumio volumio[1066]: info: MRS: Pushing multiroomSync output update for this device Jun 08 16:31:14 volumio volumio[1066]: info: MRS: Pushing multiroomSync output Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::volumioGetState Jun 08 16:31:14 volumio volumio[1066]: info: ------------------------------ 16ms Jun 08 16:31:14 volumio volumio[1066]: info: ------------------------------ 33ms Jun 08 16:31:14 volumio volumio[1066]: info: ------------------------------ 33ms Jun 08 16:31:14 volumio volumio[1066]: info: Jun 08 16:31:14 volumio volumio[1066]: ---------------------------- MPD announces state update: player Jun 08 16:31:14 volumio volumio[1066]: info: ControllerMpd::getState Jun 08 16:31:14 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Jun 08 16:31:14 volumio volumio[1066]: info: Jun 08 16:31:14 volumio volumio[1066]: ---------------------------- MPD announces state update: player Jun 08 16:31:14 volumio volumio[1066]: info: ControllerMpd::getState Jun 08 16:31:14 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Jun 08 16:31:14 volumio volumio[1066]: info: Jun 08 16:31:14 volumio volumio[1066]: ---------------------------- MPD announces state update: player Jun 08 16:31:14 volumio volumio[1066]: info: ControllerMpd::getState Jun 08 16:31:14 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Jun 08 16:31:14 volumio volumio[1066]: info: Jun 08 16:31:14 volumio volumio[1066]: ---------------------------- MPD announces system playlist update Jun 08 16:31:14 volumio volumio[1066]: info: Ignoring MPD Status Update Jun 08 16:31:14 volumio volumio[1066]: info: Jun 08 16:31:14 volumio volumio[1066]: ---------------------------- MPD announces state update: player Jun 08 16:31:14 volumio volumio[1066]: info: sendMpdCommand playlistinfo took 33 milliseconds Jun 08 16:31:14 volumio volumio[1066]: info: sendMpdCommand playlistinfo took 33 milliseconds Jun 08 16:31:14 volumio volumio[1066]: info: ControllerMpd::getState Jun 08 16:31:14 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Jun 08 16:31:14 volumio volumio[1066]: info: sendMpdCommand status took 2 milliseconds Jun 08 16:31:14 volumio volumio[1066]: info: sendMpdCommand status took 1 milliseconds Jun 08 16:31:14 volumio volumio[1066]: info: sendMpdCommand status took 1 milliseconds Jun 08 16:31:14 volumio volumio[1066]: verbose: ControllerMpd::parseTrackInfo Jun 08 16:31:14 volumio volumio[1066]: verbose: ControllerMpd::parseTrackInfo Jun 08 16:31:14 volumio volumio[1066]: verbose: ControllerMpd::parseState Jun 08 16:31:14 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 16:31:14 volumio volumio[1066]: verbose: ControllerMpd::parseState Jun 08 16:31:14 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 16:31:14 volumio volumio[1066]: verbose: ControllerMpd::parseState Jun 08 16:31:14 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 16:31:14 volumio volumio[1066]: info: ControllerMpd::pushState Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::servicePushState Jun 08 16:31:14 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Jun 08 16:31:14 volumio volumio[1066]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3","artist":null,"album":null,"uri":"https://absolut-ai.live-sm.absolutradio.de/absolut-ai/stream/mp3","trackType":""} Jun 08 16:31:14 volumio volumio[1066]: verbose: CURRENT POSITION 0 Jun 08 16:31:14 volumio volumio[1066]: info: CoreStateMachine::syncState stateService play Jun 08 16:31:14 volumio volumio[1066]: info: CoreStateMachine::syncState currentStatus play Jun 08 16:31:14 volumio volumio[1066]: info: Received an update from plugin. extracting info from payload Jun 08 16:31:14 volumio volumio[1066]: info: CoreStateMachine::pushState Jun 08 16:31:14 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Jun 08 16:31:14 volumio volumio[1066]: info: MRS: Pushing multiroomSync output update for this device Jun 08 16:31:14 volumio volumio[1066]: info: MRS: Pushing multiroomSync output Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::volumioGetState Jun 08 16:31:14 volumio volumio[1066]: info: CoreStateMachine::pushState Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Jun 08 16:31:14 volumio volumio[1066]: info: MRS: Pushing multiroomSync output update for this device Jun 08 16:31:14 volumio volumio[1066]: info: MRS: Pushing multiroomSync output Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::volumioGetState Jun 08 16:31:14 volumio volumio[1066]: info: ControllerMpd::pushState Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::servicePushState Jun 08 16:31:14 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Jun 08 16:31:14 volumio volumio[1066]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3","artist":null,"album":null,"uri":"https://absolut-ai.live-sm.absolutradio.de/absolut-ai/stream/mp3","trackType":""} Jun 08 16:31:14 volumio volumio[1066]: verbose: CURRENT POSITION 0 Jun 08 16:31:14 volumio volumio[1066]: info: CoreStateMachine::syncState stateService play Jun 08 16:31:14 volumio volumio[1066]: info: CoreStateMachine::syncState currentStatus play Jun 08 16:31:14 volumio volumio[1066]: info: Received an update from plugin. extracting info from payload Jun 08 16:31:14 volumio volumio[1066]: info: CoreStateMachine::pushState Jun 08 16:31:14 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Jun 08 16:31:14 volumio volumio[1066]: info: MRS: Pushing multiroomSync output update for this device Jun 08 16:31:14 volumio volumio[1066]: info: MRS: Pushing multiroomSync output Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::volumioGetState Jun 08 16:31:14 volumio volumio[1066]: info: CoreStateMachine::pushState Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Jun 08 16:31:14 volumio volumio[1066]: info: MRS: Pushing multiroomSync output update for this device Jun 08 16:31:14 volumio volumio[1066]: info: MRS: Pushing multiroomSync output Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::volumioGetState Jun 08 16:31:14 volumio volumio[1066]: info: ------------------------------ 55ms Jun 08 16:31:14 volumio volumio[1066]: info: ------------------------------ 55ms Jun 08 16:31:14 volumio volumio[1066]: info: Jun 08 16:31:14 volumio volumio[1066]: ---------------------------- MPD announces system playlist update Jun 08 16:31:14 volumio volumio[1066]: info: Ignoring MPD Status Update Jun 08 16:31:14 volumio volumio[1066]: info: Jun 08 16:31:14 volumio volumio[1066]: ---------------------------- MPD announces state update: player Jun 08 16:31:14 volumio volumio[1066]: info: ControllerMpd::getState Jun 08 16:31:14 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Jun 08 16:31:14 volumio volumio[1066]: info: Jun 08 16:31:14 volumio volumio[1066]: ---------------------------- MPD announces system playlist update Jun 08 16:31:14 volumio volumio[1066]: info: Ignoring MPD Status Update Jun 08 16:31:14 volumio volumio[1066]: info: Jun 08 16:31:14 volumio volumio[1066]: ---------------------------- MPD announces state update: player Jun 08 16:31:14 volumio volumio[1066]: info: ControllerMpd::getState Jun 08 16:31:14 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Jun 08 16:31:14 volumio volumio[1066]: info: Jun 08 16:31:14 volumio volumio[1066]: ---------------------------- MPD announces system playlist update Jun 08 16:31:14 volumio volumio[1066]: info: Ignoring MPD Status Update Jun 08 16:31:14 volumio volumio[1066]: info: Jun 08 16:31:14 volumio volumio[1066]: ---------------------------- MPD announces state update: player Jun 08 16:31:14 volumio volumio[1066]: info: ControllerMpd::getState Jun 08 16:31:14 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Jun 08 16:31:14 volumio volumio[1066]: info: ------------------------------ 31ms Jun 08 16:31:14 volumio volumio[1066]: info: sendMpdCommand status took 31 milliseconds Jun 08 16:31:14 volumio volumio[1066]: info: sendMpdCommand playlistinfo took 31 milliseconds Jun 08 16:31:14 volumio volumio[1066]: info: sendMpdCommand playlistinfo took 31 milliseconds Jun 08 16:31:14 volumio volumio[1066]: info: sendMpdCommand playlistinfo took 30 milliseconds Jun 08 16:31:14 volumio volumio[1066]: info: ------------------------------ 10ms Jun 08 16:31:14 volumio volumio[1066]: info: sendMpdCommand status took 10 milliseconds Jun 08 16:31:14 volumio volumio[1066]: info: ------------------------------ 10ms Jun 08 16:31:14 volumio volumio[1066]: info: sendMpdCommand status took 10 milliseconds Jun 08 16:31:14 volumio volumio[1066]: info: ------------------------------ 9ms Jun 08 16:31:14 volumio volumio[1066]: info: sendMpdCommand status took 9 milliseconds Jun 08 16:31:14 volumio volumio[1066]: verbose: ControllerMpd::parseState Jun 08 16:31:14 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 16:31:14 volumio volumio[1066]: verbose: ControllerMpd::parseTrackInfo Jun 08 16:31:14 volumio volumio[1066]: verbose: ControllerMpd::parseTrackInfo Jun 08 16:31:14 volumio volumio[1066]: verbose: ControllerMpd::parseTrackInfo Jun 08 16:31:14 volumio volumio[1066]: verbose: ControllerMpd::parseState Jun 08 16:31:14 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 16:31:14 volumio volumio[1066]: verbose: ControllerMpd::parseState Jun 08 16:31:14 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 16:31:14 volumio volumio[1066]: verbose: ControllerMpd::parseState Jun 08 16:31:14 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 16:31:14 volumio volumio[1066]: info: ControllerMpd::pushState Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::servicePushState Jun 08 16:31:14 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Jun 08 16:31:14 volumio volumio[1066]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1108,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"PASCAL LETOUBLON - FRIENDSHIPS","artist":"Absolut AI","album":null,"uri":"https://absolut-ai.live-sm.absolutradio.de/absolut-ai/stream/mp3","trackType":""} Jun 08 16:31:14 volumio volumio[1066]: verbose: CURRENT POSITION 0 Jun 08 16:31:14 volumio volumio[1066]: info: CoreStateMachine::syncState stateService play Jun 08 16:31:14 volumio volumio[1066]: info: CoreStateMachine::syncState currentStatus play Jun 08 16:31:14 volumio volumio[1066]: info: Received an update from plugin. extracting info from payload Jun 08 16:31:14 volumio volumio[1066]: info: CoreStateMachine::pushState Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Jun 08 16:31:14 volumio volumio[1066]: info: MRS: Pushing multiroomSync output update for this device Jun 08 16:31:14 volumio volumio[1066]: info: MRS: Pushing multiroomSync output Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::volumioGetState Jun 08 16:31:14 volumio volumio[1066]: info: CoreStateMachine::pushState Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Jun 08 16:31:14 volumio volumio[1066]: info: MRS: Pushing multiroomSync output update for this device Jun 08 16:31:14 volumio volumio[1066]: info: MRS: Pushing multiroomSync output Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::volumioGetState Jun 08 16:31:14 volumio volumio[1066]: info: ControllerMpd::pushState Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::servicePushState Jun 08 16:31:14 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Jun 08 16:31:14 volumio volumio[1066]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1108,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"PASCAL LETOUBLON - FRIENDSHIPS","artist":"Absolut AI","album":null,"uri":"https://absolut-ai.live-sm.absolutradio.de/absolut-ai/stream/mp3","trackType":""} Jun 08 16:31:14 volumio volumio[1066]: verbose: CURRENT POSITION 0 Jun 08 16:31:14 volumio volumio[1066]: info: CoreStateMachine::syncState stateService play Jun 08 16:31:14 volumio volumio[1066]: info: CoreStateMachine::syncState currentStatus play Jun 08 16:31:14 volumio volumio[1066]: info: Received an update from plugin. extracting info from payload Jun 08 16:31:14 volumio volumio[1066]: info: CoreStateMachine::pushState Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Jun 08 16:31:14 volumio volumio[1066]: info: MRS: Pushing multiroomSync output update for this device Jun 08 16:31:14 volumio volumio[1066]: info: MRS: Pushing multiroomSync output Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::volumioGetState Jun 08 16:31:14 volumio volumio[1066]: info: CoreStateMachine::pushState Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Jun 08 16:31:14 volumio volumio[1066]: info: MRS: Pushing multiroomSync output update for this device Jun 08 16:31:14 volumio volumio[1066]: info: MRS: Pushing multiroomSync output Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::volumioGetState Jun 08 16:31:14 volumio volumio[1066]: info: ControllerMpd::pushState Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::servicePushState Jun 08 16:31:14 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Jun 08 16:31:14 volumio volumio[1066]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1108,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"PASCAL LETOUBLON - FRIENDSHIPS","artist":"Absolut AI","album":null,"uri":"https://absolut-ai.live-sm.absolutradio.de/absolut-ai/stream/mp3","trackType":""} Jun 08 16:31:14 volumio volumio[1066]: verbose: CURRENT POSITION 0 Jun 08 16:31:14 volumio volumio[1066]: info: CoreStateMachine::syncState stateService play Jun 08 16:31:14 volumio volumio[1066]: info: CoreStateMachine::syncState currentStatus play Jun 08 16:31:14 volumio volumio[1066]: info: Received an update from plugin. extracting info from payload Jun 08 16:31:14 volumio volumio[1066]: info: CoreStateMachine::pushState Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Jun 08 16:31:14 volumio volumio[1066]: info: MRS: Pushing multiroomSync output update for this device Jun 08 16:31:14 volumio volumio[1066]: info: MRS: Pushing multiroomSync output Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::volumioGetState Jun 08 16:31:14 volumio volumio[1066]: info: CoreStateMachine::pushState Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Jun 08 16:31:14 volumio volumio[1066]: info: MRS: Pushing multiroomSync output update for this device Jun 08 16:31:14 volumio volumio[1066]: info: MRS: Pushing multiroomSync output Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::volumioGetState Jun 08 16:31:14 volumio volumio[1066]: info: ------------------------------ 58ms Jun 08 16:31:14 volumio volumio[1066]: info: ------------------------------ 58ms Jun 08 16:31:14 volumio volumio[1066]: info: ------------------------------ 57ms Jun 08 16:31:14 volumio volumio[1066]: info: sendMpdCommand playlistinfo took 37 milliseconds Jun 08 16:31:14 volumio volumio[1066]: info: sendMpdCommand playlistinfo took 37 milliseconds Jun 08 16:31:14 volumio volumio[1066]: info: sendMpdCommand playlistinfo took 37 milliseconds Jun 08 16:31:14 volumio volumio[1066]: info: sendMpdCommand playlistinfo took 36 milliseconds Jun 08 16:31:14 volumio volumio[1066]: verbose: ControllerMpd::parseTrackInfo Jun 08 16:31:14 volumio volumio[1066]: verbose: ControllerMpd::parseTrackInfo Jun 08 16:31:14 volumio volumio[1066]: verbose: ControllerMpd::parseTrackInfo Jun 08 16:31:14 volumio volumio[1066]: verbose: ControllerMpd::parseTrackInfo Jun 08 16:31:14 volumio volumio[1066]: info: ControllerMpd::pushState Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::servicePushState Jun 08 16:31:14 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Jun 08 16:31:14 volumio volumio[1066]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1108,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"PASCAL LETOUBLON - FRIENDSHIPS","artist":"Absolut AI","album":null,"uri":"https://absolut-ai.live-sm.absolutradio.de/absolut-ai/stream/mp3","trackType":""} Jun 08 16:31:14 volumio volumio[1066]: verbose: CURRENT POSITION 0 Jun 08 16:31:14 volumio volumio[1066]: info: CoreStateMachine::syncState stateService play Jun 08 16:31:14 volumio volumio[1066]: info: CoreStateMachine::syncState currentStatus play Jun 08 16:31:14 volumio volumio[1066]: info: Received an update from plugin. extracting info from payload Jun 08 16:31:14 volumio volumio[1066]: info: CoreStateMachine::pushState Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Jun 08 16:31:14 volumio volumio[1066]: info: MRS: Pushing multiroomSync output update for this device Jun 08 16:31:14 volumio volumio[1066]: info: MRS: Pushing multiroomSync output Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::volumioGetState Jun 08 16:31:14 volumio volumio[1066]: info: CoreStateMachine::pushState Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Jun 08 16:31:14 volumio volumio[1066]: info: MRS: Pushing multiroomSync output update for this device Jun 08 16:31:14 volumio volumio[1066]: info: MRS: Pushing multiroomSync output Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::volumioGetState Jun 08 16:31:14 volumio volumio[1066]: info: ControllerMpd::pushState Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::servicePushState Jun 08 16:31:14 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Jun 08 16:31:14 volumio volumio[1066]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1234,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"PASCAL LETOUBLON - FRIENDSHIPS","artist":"Absolut AI","album":null,"uri":"https://absolut-ai.live-sm.absolutradio.de/absolut-ai/stream/mp3","trackType":""} Jun 08 16:31:14 volumio volumio[1066]: verbose: CURRENT POSITION 0 Jun 08 16:31:14 volumio volumio[1066]: info: CoreStateMachine::syncState stateService play Jun 08 16:31:14 volumio volumio[1066]: info: CoreStateMachine::syncState currentStatus play Jun 08 16:31:14 volumio volumio[1066]: info: Received an update from plugin. extracting info from payload Jun 08 16:31:14 volumio volumio[1066]: info: CoreStateMachine::pushState Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Jun 08 16:31:14 volumio volumio[1066]: info: MRS: Pushing multiroomSync output update for this device Jun 08 16:31:14 volumio volumio[1066]: info: MRS: Pushing multiroomSync output Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::volumioGetState Jun 08 16:31:14 volumio volumio[1066]: info: CoreStateMachine::pushState Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Jun 08 16:31:14 volumio volumio[1066]: info: MRS: Pushing multiroomSync output update for this device Jun 08 16:31:14 volumio volumio[1066]: info: MRS: Pushing multiroomSync output Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::volumioGetState Jun 08 16:31:14 volumio volumio[1066]: info: ControllerMpd::pushState Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::servicePushState Jun 08 16:31:14 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Jun 08 16:31:14 volumio volumio[1066]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1234,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"PASCAL LETOUBLON - FRIENDSHIPS","artist":"Absolut AI","album":null,"uri":"https://absolut-ai.live-sm.absolutradio.de/absolut-ai/stream/mp3","trackType":""} Jun 08 16:31:14 volumio volumio[1066]: verbose: CURRENT POSITION 0 Jun 08 16:31:14 volumio volumio[1066]: info: CoreStateMachine::syncState stateService play Jun 08 16:31:14 volumio volumio[1066]: info: CoreStateMachine::syncState currentStatus play Jun 08 16:31:14 volumio volumio[1066]: info: Received an update from plugin. extracting info from payload Jun 08 16:31:14 volumio volumio[1066]: info: CoreStateMachine::pushState Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Jun 08 16:31:14 volumio volumio[1066]: info: MRS: Pushing multiroomSync output update for this device Jun 08 16:31:14 volumio volumio[1066]: info: MRS: Pushing multiroomSync output Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::volumioGetState Jun 08 16:31:14 volumio volumio[1066]: info: CoreStateMachine::pushState Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Jun 08 16:31:14 volumio volumio[1066]: info: MRS: Pushing multiroomSync output update for this device Jun 08 16:31:14 volumio volumio[1066]: info: MRS: Pushing multiroomSync output Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::volumioGetState Jun 08 16:31:14 volumio volumio[1066]: info: ControllerMpd::pushState Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::servicePushState Jun 08 16:31:14 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Jun 08 16:31:14 volumio volumio[1066]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1234,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"PASCAL LETOUBLON - FRIENDSHIPS","artist":"Absolut AI","album":null,"uri":"https://absolut-ai.live-sm.absolutradio.de/absolut-ai/stream/mp3","trackType":""} Jun 08 16:31:14 volumio volumio[1066]: verbose: CURRENT POSITION 0 Jun 08 16:31:14 volumio volumio[1066]: info: CoreStateMachine::syncState stateService play Jun 08 16:31:14 volumio volumio[1066]: info: CoreStateMachine::syncState currentStatus play Jun 08 16:31:14 volumio volumio[1066]: info: Received an update from plugin. extracting info from payload Jun 08 16:31:14 volumio volumio[1066]: info: CoreStateMachine::pushState Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Jun 08 16:31:14 volumio volumio[1066]: info: MRS: Pushing multiroomSync output update for this device Jun 08 16:31:14 volumio volumio[1066]: info: MRS: Pushing multiroomSync output Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::volumioGetState Jun 08 16:31:14 volumio volumio[1066]: info: CoreStateMachine::pushState Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Jun 08 16:31:14 volumio volumio[1066]: info: MRS: Pushing multiroomSync output update for this device Jun 08 16:31:14 volumio volumio[1066]: info: MRS: Pushing multiroomSync output Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::volumioGetState Jun 08 16:31:14 volumio volumio[1066]: info: ------------------------------ 96ms Jun 08 16:31:14 volumio volumio[1066]: info: ------------------------------ 74ms Jun 08 16:31:14 volumio volumio[1066]: info: ------------------------------ 74ms Jun 08 16:31:14 volumio volumio[1066]: info: ------------------------------ 73ms Jun 08 16:31:14 volumio volumio[1066]: info: Jun 08 16:31:14 volumio volumio[1066]: ---------------------------- MPD announces system playlist update Jun 08 16:31:14 volumio volumio[1066]: info: Ignoring MPD Status Update Jun 08 16:31:14 volumio volumio[1066]: info: Jun 08 16:31:14 volumio volumio[1066]: ---------------------------- MPD announces state update: player Jun 08 16:31:14 volumio volumio[1066]: info: ControllerMpd::getState Jun 08 16:31:14 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Jun 08 16:31:14 volumio volumio[1066]: info: Jun 08 16:31:14 volumio volumio[1066]: ---------------------------- MPD announces system playlist update Jun 08 16:31:14 volumio volumio[1066]: info: Ignoring MPD Status Update Jun 08 16:31:14 volumio volumio[1066]: info: Jun 08 16:31:14 volumio volumio[1066]: ---------------------------- MPD announces state update: player Jun 08 16:31:14 volumio volumio[1066]: info: ControllerMpd::getState Jun 08 16:31:14 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Jun 08 16:31:14 volumio volumio[1066]: info: Jun 08 16:31:14 volumio volumio[1066]: ---------------------------- MPD announces system playlist update Jun 08 16:31:14 volumio volumio[1066]: info: Ignoring MPD Status Update Jun 08 16:31:14 volumio volumio[1066]: info: Jun 08 16:31:14 volumio volumio[1066]: ---------------------------- MPD announces state update: player Jun 08 16:31:14 volumio volumio[1066]: info: ControllerMpd::getState Jun 08 16:31:14 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Jun 08 16:31:14 volumio volumio[1066]: info: ------------------------------ 2ms Jun 08 16:31:14 volumio volumio[1066]: info: sendMpdCommand status took 2 milliseconds Jun 08 16:31:14 volumio volumio[1066]: info: ------------------------------ 2ms Jun 08 16:31:14 volumio volumio[1066]: info: sendMpdCommand status took 2 milliseconds Jun 08 16:31:14 volumio volumio[1066]: info: ------------------------------ 1ms Jun 08 16:31:14 volumio volumio[1066]: info: sendMpdCommand status took 1 milliseconds Jun 08 16:31:14 volumio volumio[1066]: verbose: ControllerMpd::parseState Jun 08 16:31:14 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 16:31:14 volumio volumio[1066]: verbose: ControllerMpd::parseState Jun 08 16:31:14 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 16:31:14 volumio volumio[1066]: verbose: ControllerMpd::parseState Jun 08 16:31:14 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 16:31:14 volumio volumio[1066]: info: Jun 08 16:31:14 volumio volumio[1066]: ---------------------------- MPD announces system playlist update Jun 08 16:31:14 volumio volumio[1066]: info: Ignoring MPD Status Update Jun 08 16:31:14 volumio volumio[1066]: info: Jun 08 16:31:14 volumio volumio[1066]: ---------------------------- MPD announces state update: player Jun 08 16:31:14 volumio volumio[1066]: info: ControllerMpd::getState Jun 08 16:31:14 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand status Jun 08 16:31:14 volumio volumio[1066]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 08 16:31:14 volumio volumio[1066]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 08 16:31:14 volumio volumio[1066]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 08 16:31:14 volumio volumio[1066]: info: ------------------------------ 1ms Jun 08 16:31:14 volumio volumio[1066]: info: sendMpdCommand status took 0 milliseconds Jun 08 16:31:14 volumio volumio[1066]: verbose: ControllerMpd::parseTrackInfo Jun 08 16:31:14 volumio volumio[1066]: verbose: ControllerMpd::parseTrackInfo Jun 08 16:31:14 volumio volumio[1066]: verbose: ControllerMpd::parseTrackInfo Jun 08 16:31:14 volumio volumio[1066]: verbose: ControllerMpd::parseState Jun 08 16:31:14 volumio volumio[1066]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 08 16:31:14 volumio volumio[1066]: info: ControllerMpd::pushState Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::servicePushState Jun 08 16:31:14 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Jun 08 16:31:14 volumio volumio[1066]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1862,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"PASCAL LETOUBLON - FRIENDSHIPS","artist":"Absolut AI","album":null,"uri":"https://absolut-ai.live-sm.absolutradio.de/absolut-ai/stream/mp3","trackType":""} Jun 08 16:31:14 volumio volumio[1066]: verbose: CURRENT POSITION 0 Jun 08 16:31:14 volumio volumio[1066]: info: CoreStateMachine::syncState stateService play Jun 08 16:31:14 volumio volumio[1066]: info: CoreStateMachine::syncState currentStatus play Jun 08 16:31:14 volumio volumio[1066]: info: Received an update from plugin. extracting info from payload Jun 08 16:31:14 volumio volumio[1066]: info: CoreStateMachine::pushState Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Jun 08 16:31:14 volumio volumio[1066]: info: MRS: Pushing multiroomSync output update for this device Jun 08 16:31:14 volumio volumio[1066]: info: MRS: Pushing multiroomSync output Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::volumioGetState Jun 08 16:31:14 volumio volumio[1066]: info: CoreStateMachine::pushState Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Jun 08 16:31:14 volumio volumio[1066]: info: MRS: Pushing multiroomSync output update for this device Jun 08 16:31:14 volumio volumio[1066]: info: MRS: Pushing multiroomSync output Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::volumioGetState Jun 08 16:31:14 volumio volumio[1066]: info: ControllerMpd::pushState Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::servicePushState Jun 08 16:31:14 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Jun 08 16:31:14 volumio volumio[1066]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1862,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"PASCAL LETOUBLON - FRIENDSHIPS","artist":"Absolut AI","album":null,"uri":"https://absolut-ai.live-sm.absolutradio.de/absolut-ai/stream/mp3","trackType":""} Jun 08 16:31:14 volumio volumio[1066]: verbose: CURRENT POSITION 0 Jun 08 16:31:14 volumio volumio[1066]: info: CoreStateMachine::syncState stateService play Jun 08 16:31:14 volumio volumio[1066]: info: CoreStateMachine::syncState currentStatus play Jun 08 16:31:14 volumio volumio[1066]: info: Received an update from plugin. extracting info from payload Jun 08 16:31:14 volumio volumio[1066]: info: CoreStateMachine::pushState Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Jun 08 16:31:14 volumio volumio[1066]: info: MRS: Pushing multiroomSync output update for this device Jun 08 16:31:14 volumio volumio[1066]: info: MRS: Pushing multiroomSync output Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::volumioGetState Jun 08 16:31:14 volumio volumio[1066]: info: CoreStateMachine::pushState Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Jun 08 16:31:14 volumio volumio[1066]: info: MRS: Pushing multiroomSync output update for this device Jun 08 16:31:14 volumio volumio[1066]: info: MRS: Pushing multiroomSync output Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::volumioGetState Jun 08 16:31:14 volumio volumio[1066]: info: ControllerMpd::pushState Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::servicePushState Jun 08 16:31:14 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Jun 08 16:31:14 volumio volumio[1066]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1862,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"PASCAL LETOUBLON - FRIENDSHIPS","artist":"Absolut AI","album":null,"uri":"https://absolut-ai.live-sm.absolutradio.de/absolut-ai/stream/mp3","trackType":""} Jun 08 16:31:14 volumio volumio[1066]: verbose: CURRENT POSITION 0 Jun 08 16:31:14 volumio volumio[1066]: info: CoreStateMachine::syncState stateService play Jun 08 16:31:14 volumio volumio[1066]: info: CoreStateMachine::syncState currentStatus play Jun 08 16:31:14 volumio volumio[1066]: info: Received an update from plugin. extracting info from payload Jun 08 16:31:14 volumio volumio[1066]: info: CoreStateMachine::pushState Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Jun 08 16:31:14 volumio volumio[1066]: info: MRS: Pushing multiroomSync output update for this device Jun 08 16:31:14 volumio volumio[1066]: info: MRS: Pushing multiroomSync output Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::volumioGetState Jun 08 16:31:14 volumio volumio[1066]: info: CoreStateMachine::pushState Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Jun 08 16:31:14 volumio volumio[1066]: info: MRS: Pushing multiroomSync output update for this device Jun 08 16:31:14 volumio volumio[1066]: info: MRS: Pushing multiroomSync output Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::volumioGetState Jun 08 16:31:14 volumio volumio[1066]: info: ------------------------------ 32ms Jun 08 16:31:14 volumio volumio[1066]: info: ------------------------------ 32ms Jun 08 16:31:14 volumio volumio[1066]: info: ------------------------------ 32ms Jun 08 16:31:14 volumio volumio[1066]: info: sendMpdCommand playlistinfo took 29 milliseconds Jun 08 16:31:14 volumio volumio[1066]: verbose: ControllerMpd::parseTrackInfo Jun 08 16:31:14 volumio volumio[1066]: info: ControllerMpd::pushState Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::servicePushState Jun 08 16:31:14 volumio volumio[1066]: info: CorePlayQueue::getTrack 0 Jun 08 16:31:14 volumio volumio[1066]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1862,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"PASCAL LETOUBLON - FRIENDSHIPS","artist":"Absolut AI","album":null,"uri":"https://absolut-ai.live-sm.absolutradio.de/absolut-ai/stream/mp3","trackType":""} Jun 08 16:31:14 volumio volumio[1066]: verbose: CURRENT POSITION 0 Jun 08 16:31:14 volumio volumio[1066]: info: CoreStateMachine::syncState stateService play Jun 08 16:31:14 volumio volumio[1066]: info: CoreStateMachine::syncState currentStatus play Jun 08 16:31:14 volumio volumio[1066]: info: Received an update from plugin. extracting info from payload Jun 08 16:31:14 volumio volumio[1066]: info: CoreStateMachine::pushState Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Jun 08 16:31:14 volumio volumio[1066]: info: MRS: Pushing multiroomSync output update for this device Jun 08 16:31:14 volumio volumio[1066]: info: MRS: Pushing multiroomSync output Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::volumioGetState Jun 08 16:31:14 volumio volumio[1066]: info: CoreStateMachine::pushState Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::volumioPushState Jun 08 16:31:14 volumio volumio[1066]: info: MRS: Pushing multiroomSync output update for this device Jun 08 16:31:14 volumio volumio[1066]: info: MRS: Pushing multiroomSync output Jun 08 16:31:14 volumio volumio[1066]: info: CoreCommandRouter::volumioGetState Jun 08 16:31:14 volumio volumio[1066]: info: ------------------------------ 39ms Jun 08 16:31:24 volumio volumio[1066]: error: error Jun 08 16:31:30 volumio volumio[1066]: info: CoreCommandRouter::volumioGetState Jun 08 16:31:32 volumio volumio[1066]: info: Executing endpoint metavolumio Jun 08 16:31:32 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 08 16:31:32 volumio volumio[1066]: info: Executing endpoint metavolumio Jun 08 16:31:32 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 08 16:31:33 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 08 16:31:33 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jun 08 16:31:36 volumio volumio[1066]: info: Enabling plugin touch_display Jun 08 16:31:36 volumio volumio[1066]: info: Loading plugin "touch_display"... Jun 08 16:31:36 volumio volumio[1066]: info: Applying required configuration parameters for plugin touch_display Jun 08 16:31:36 volumio volumio[1066]: info: PLUGIN START: touch_display Jun 08 16:31:36 volumio volumio[1066]: info: Loading i18n strings for locale en Jun 08 16:31:36 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 08 16:31:36 volumio sudo[2231]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Jun 08 16:31:36 volumio sudo[2231]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 08 16:31:36 volumio sudo[2234]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Jun 08 16:31:36 volumio systemd[1]: Stopping Getty on tty1... Jun 08 16:31:36 volumio sudo[2234]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 08 16:31:36 volumio volumio[1066]: info: touch_display: No backlight interface detected. Jun 08 16:31:36 volumio sudo[2237]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jun 08 16:31:36 volumio sudo[2237]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 08 16:31:36 volumio volumio[1066]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 08 16:31:36 volumio volumio[1066]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jun 08 16:31:36 volumio systemd[1]: getty@tty1.service: Main process exited, code=killed, status=15/TERM Jun 08 16:31:36 volumio systemd[1]: getty@tty1.service: Succeeded. Jun 08 16:31:36 volumio systemd[1]: Stopped Getty on tty1. Jun 08 16:31:36 volumio volumio[1066]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Jun 08 16:31:36 volumio systemd[1]: Reloading. Jun 08 16:31:36 volumio sudo[2231]: pam_unix(sudo:session): session closed for user root Jun 08 16:31:36 volumio sudo[2240]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Jun 08 16:31:36 volumio sudo[2240]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 08 16:31:36 volumio sudo[2240]: pam_unix(sudo:session): session closed for user root Jun 08 16:31:36 volumio sudo[2243]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Jun 08 16:31:36 volumio volumio[1066]: info: touch_display: systemctl stop getty@tty1.service succeeded. Jun 08 16:31:36 volumio sudo[2243]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 08 16:31:36 volumio sudo[2243]: pam_unix(sudo:session): session closed for user root Jun 08 16:31:36 volumio volumio[1066]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Jun 08 16:31:36 volumio volumio[1066]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Jun 08 16:31:36 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jun 08 16:31:36 volumio systemd[1]: serial-getty@ttyAMA10.service: Current command vanished from the unit file, execution of the command list won't be resumed. Jun 08 16:31:36 volumio sudo[2237]: pam_unix(sudo:session): session closed for user root Jun 08 16:31:36 volumio volumio[1066]: info: touch_display: systemctl daemon-reload succeeded. Jun 08 16:31:36 volumio systemd[1]: Reloading. Jun 08 16:31:36 volumio sudo[2259]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Jun 08 16:31:36 volumio sudo[2259]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 08 16:31:36 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jun 08 16:31:37 volumio sudo[2234]: pam_unix(sudo:session): session closed for user root Jun 08 16:31:37 volumio systemd[1]: Started Volumio Kiosk. Jun 08 16:31:37 volumio sudo[2259]: pam_unix(sudo:session): session closed for user root Jun 08 16:31:37 volumio volumio[1066]: info: touch_display: systemctl disable getty@tty1.service succeeded. Jun 08 16:31:37 volumio volumio[1066]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Jun 08 16:31:37 volumio volumio[1066]: info: touch_display: Volumio Kiosk started. Jun 08 16:31:37 volumio volumio[1066]: info: CoreCommandRouter::volumioGetState Jun 08 16:31:37 volumio volumio[1066]: info: Done. Jun 08 16:31:37 volumio volumio[1066]: info: CoreCommandRouter::volumioGetState Jun 08 16:31:37 volumio volumio[1066]: info: touch_display: X display number found: Jun 08 16:31:37 volumio startx[2274]: X.Org X Server 1.20.4 Jun 08 16:31:37 volumio startx[2274]: X Protocol Version 11, Revision 0 Jun 08 16:31:37 volumio startx[2274]: Build Operating System: Linux 5.4.0-109-generic armv8l Raspbian Jun 08 16:31:37 volumio startx[2274]: Current Operating System: Linux volumio 6.6.62-v8+ #1816 SMP PREEMPT Mon Nov 18 15:35:52 GMT 2024 aarch64 Jun 08 16:31:37 volumio startx[2274]: Kernel command line: reboot=w coherent_pool=1M 8250.nr_uarts=1 pci=pcie_bus_safe cgroup_disable=memory numa_policy=interleave smsc95xx.macaddr=2C:CF:67:34:EF:EA vc_mem.mem_base=0x3fc00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyAMA10,115200 console=tty1 imgpart=UUID=bc357b65-966a-45c3-975f-98ac1d7c9730 imgfile=/volumio_current.sqsh bootpart=UUID=25F2-3254 datapart=UUID=7dd62884-5187-4911-ace1-087fb2a7e220 uuidconfig=cmdline.txt pcie_aspm=off pci=pcie_bus_safe rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa= snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Jun 08 16:31:37 volumio startx[2274]: Build Date: 04 April 2023 07:50:56AM Jun 08 16:31:37 volumio startx[2274]: xorg-server 2:1.20.4-1+rpt4+deb10u9 (https://www.debian.org/support) Jun 08 16:31:37 volumio startx[2274]: Current version of pixman: 0.36.0 Jun 08 16:31:37 volumio startx[2274]: Before reporting problems, check http://wiki.x.org Jun 08 16:31:37 volumio startx[2274]: to make sure that you have the latest version. Jun 08 16:31:37 volumio startx[2274]: Markers: (--) probed, (**) from config file, (==) default setting, Jun 08 16:31:37 volumio startx[2274]: (++) from command line, (!!) notice, (II) informational, Jun 08 16:31:37 volumio startx[2274]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Jun 08 16:31:37 volumio startx[2274]: (==) Log file: "/var/log/Xorg.0.log", Time: Sun Jun 8 16:31:37 2025 Jun 08 16:31:37 volumio startx[2274]: (==) Using config directory: "/etc/X11/xorg.conf.d" Jun 08 16:31:37 volumio startx[2274]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Jun 08 16:31:37 volumio volumio[1066]: info: touch_display: X display number found: 0 Jun 08 16:31:37 volumio volumio[1066]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Jun 08 16:31:37 volumio volumio[1066]: info: touch_display: X display number found: 0 Jun 08 16:31:37 volumio startx[2274]: V3D 7.1 not supported by this version of Mesa. Jun 08 16:31:37 volumio kernel: broken atomic modeset userspace detected, disabling atomic Jun 08 16:31:38 volumio startx[2274]: (II) modeset(0): Initializing kms color map for depth 24, 8 bpc. Jun 08 16:31:38 volumio volumio[1066]: info: touch_display: Setting screensaver timeout to 120 seconds. Jun 08 16:31:42 volumio go-librespot[1671]: time="2025-06-08T16:31:42-04:00" level=trace msg="sent dealer ping" Jun 08 16:31:42 volumio go-librespot[1671]: time="2025-06-08T16:31:42-04:00" level=trace msg="received dealer pong" Jun 08 16:31:42 volumio volumio[1066]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Jun 08 16:31:42 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 08 16:31:42 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 08 16:31:42 volumio volumio[1066]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Jun 08 16:31:43 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 08 16:31:43 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jun 08 16:31:43 volumio volumio[1066]: info: CoreCommandRouter::volumioGetVisibleSources Jun 08 16:31:43 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 08 16:31:43 volumio volumio[1066]: info: CoreCommandRouter::volumioGetState Jun 08 16:31:43 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 08 16:31:43 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 08 16:31:43 volumio volumio[1066]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jun 08 16:31:43 volumio volumio[1066]: info: Received Get System Info Jun 08 16:31:43 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 08 16:31:43 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 08 16:31:43 volumio volumio[1066]: info: Discovery: Getting this device information Jun 08 16:31:43 volumio volumio[1066]: info: CoreCommandRouter::volumioGetState Jun 08 16:31:43 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 08 16:31:43 volumio volumio[1066]: info: CoreCommandRouter::volumioGetState Jun 08 16:31:43 volumio volumio[1066]: info: Listing playlists Jun 08 16:31:43 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jun 08 16:31:43 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 08 16:31:43 volumio volumio[1066]: info: CoreCommandRouter::volumioGetState Jun 08 16:31:44 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 08 16:31:44 volumio volumio[1066]: info: Received Get System Info Jun 08 16:31:44 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 08 16:31:44 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 08 16:31:44 volumio volumio[1066]: info: Discovery: Getting this device information Jun 08 16:31:44 volumio volumio[1066]: info: CoreCommandRouter::volumioGetState Jun 08 16:31:44 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 08 16:31:45 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jun 08 16:31:45 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 08 16:31:45 volumio volumio[1066]: info: Received Get System Info Jun 08 16:31:45 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 08 16:31:45 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 08 16:31:45 volumio volumio[1066]: info: Discovery: Getting this device information Jun 08 16:31:45 volumio volumio[1066]: info: CoreCommandRouter::volumioGetState Jun 08 16:31:45 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 08 16:31:46 volumio volumio[1066]: info: Executing endpoint metavolumio Jun 08 16:31:46 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 08 16:31:46 volumio volumio[1066]: info: Executing endpoint metavolumio Jun 08 16:31:46 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 08 16:31:48 volumio volumio[1066]: info: CoreCommandRouter::volumioGetState Jun 08 16:31:50 volumio volumio[1066]: info: Executing endpoint metavolumio Jun 08 16:31:50 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 08 16:31:50 volumio volumio[1066]: info: Executing endpoint metavolumio Jun 08 16:31:50 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 08 16:31:52 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 08 16:31:58 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 08 16:31:58 volumio volumio[1066]: info: Preload queue cleared Jun 08 16:31:59 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 08 16:31:59 volumio volumio[1066]: info: Preload queue cleared Jun 08 16:32:00 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 08 16:32:00 volumio volumio[1066]: info: Preload queue cleared Jun 08 16:32:01 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 08 16:32:01 volumio volumio[1066]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jun 08 16:32:01 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jun 08 16:32:01 volumio volumio[1066]: info: Received Get System Version Jun 08 16:32:01 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 08 16:32:01 volumio volumio[1066]: info: Received Get System Info Jun 08 16:32:01 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 08 16:32:01 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 08 16:32:01 volumio volumio[1066]: info: Discovery: Getting this device information Jun 08 16:32:01 volumio volumio[1066]: info: CoreCommandRouter::volumioGetState Jun 08 16:32:01 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 08 16:32:03 volumio volumio[1066]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 08 16:32:03 volumio volumio[1066]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 08 16:32:03 volumio volumio[1066]: TypeError: Cannot read property 'length' of undefined Jun 08 16:32:03 volumio volumio[1066]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Jun 08 16:32:03 volumio volumio[1066]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Jun 08 16:32:03 volumio volumio[1066]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Jun 08 16:32:03 volumio volumio[1066]: at Parser.emit (events.js:315:20) Jun 08 16:32:03 volumio volumio[1066]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Jun 08 16:32:03 volumio volumio[1066]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Jun 08 16:32:03 volumio volumio[1066]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Jun 08 16:32:03 volumio volumio[1066]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Jun 08 16:32:03 volumio volumio[1066]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Jun 08 16:32:03 volumio volumio[1066]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Jun 08 16:32:03 volumio volumio[1066]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Jun 08 16:32:03 volumio volumio[1066]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Jun 08 16:32:03 volumio volumio[1066]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Jun 08 16:32:03 volumio volumio[1066]: at IncomingMessage.emit (events.js:327:22) Jun 08 16:32:03 volumio volumio[1066]: at endReadableNT (internal/streams/readable.js:1327:12) Jun 08 16:32:03 volumio volumio[1066]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jun 08 16:32:03 volumio volumio[1066]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 08 16:32:04 volumio sudo[2742]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-08 16:31 Jun 08 16:32:04 volumio sudo[2742]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"