-- Logs begin at Sat 2025-03-08 17:34:33 KST, end at Sat 2025-03-08 18:29:44 KST. -- Mar 08 18:28:00 volumiorpi5 volumio[1069]: info: [1741426080194] [RadioParadise] received new metadata: {"time":211,"artist":"Sting","title":"La Belle Dame sans regrets","album":"Mercury Falling","year":"1996","cover":"https://img.radioparadise.com/covers/l/B000005ILF.jpg","cover_med":"https://img.radioparadise.com/covers/m/B000005ILF.jpg","cover_small":"https://img.radioparadise.com/covers/s/B000005ILF.jpg"} Mar 08 18:28:00 volumiorpi5 volumio[1069]: info: CorePlayQueue::getTrack 0 Mar 08 18:28:00 volumiorpi5 volumio[1069]: info: CoreCommandRouter::servicePushState Mar 08 18:28:00 volumiorpi5 volumio[1069]: info: CorePlayQueue::getTrack 0 Mar 08 18:28:00 volumiorpi5 volumio[1069]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B000005ILF.jpg","uri":"https://stream.radioparadise.com/mellow-flac","name":"La Belle Dame sans regrets","title":"La Belle Dame sans regrets","artist":"Sting","album":"Mercury Falling","streaming":true,"disableUiControls":true,"duration":216,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Mar 08 18:28:00 volumiorpi5 volumio[1069]: verbose: CURRENT POSITION 0 Mar 08 18:28:00 volumiorpi5 volumio[1069]: info: CoreStateMachine::syncState stateService play Mar 08 18:28:00 volumiorpi5 volumio[1069]: info: CoreStateMachine::syncState currentStatus play Mar 08 18:28:00 volumiorpi5 volumio[1069]: info: Received an update from plugin. extracting info from payload Mar 08 18:28:00 volumiorpi5 volumio[1069]: info: CoreStateMachine::pushState Mar 08 18:28:00 volumiorpi5 volumio[1069]: info: CorePlayQueue::getTrack 0 Mar 08 18:28:00 volumiorpi5 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 18:28:00 volumiorpi5 volumio[1069]: info: CoreCommandRouter::volumioPushState Mar 08 18:28:00 volumiorpi5 volumio[1069]: info: CoreStateMachine::pushState Mar 08 18:28:00 volumiorpi5 volumio[1069]: info: CorePlayQueue::getTrack 0 Mar 08 18:28:00 volumiorpi5 volumio[1069]: info: CoreCommandRouter::volumioPushState Mar 08 18:28:00 volumiorpi5 volumio[1069]: info: [1741426080203] [RadioParadise] setting new timer with duration of 216000 seconds. Mar 08 18:28:00 volumiorpi5 volumio[1069]: info: CorePlayQueue::getTrack 0 Mar 08 18:28:00 volumiorpi5 volumio[1069]: info: CorePlayQueue::getTrack 1 Mar 08 18:28:01 volumiorpi5 volumio[1069]: info: Mar 08 18:28:01 volumiorpi5 volumio[1069]: ---------------------------- MPD announces state update: player Mar 08 18:28:01 volumiorpi5 volumio[1069]: info: ControllerMpd::getState Mar 08 18:28:01 volumiorpi5 volumio[1069]: verbose: ControllerMpd::sendMpdCommand status Mar 08 18:28:01 volumiorpi5 volumio[1069]: info: Mar 08 18:28:01 volumiorpi5 volumio[1069]: ---------------------------- MPD announces state update: player Mar 08 18:28:01 volumiorpi5 volumio[1069]: info: ControllerMpd::getState Mar 08 18:28:01 volumiorpi5 volumio[1069]: verbose: ControllerMpd::sendMpdCommand status Mar 08 18:28:01 volumiorpi5 volumio[1069]: info: Mar 08 18:28:01 volumiorpi5 volumio[1069]: ---------------------------- MPD announces state update: player Mar 08 18:28:01 volumiorpi5 volumio[1069]: info: sendMpdCommand status took 26 milliseconds Mar 08 18:28:01 volumiorpi5 volumio[1069]: info: sendMpdCommand status took 26 milliseconds Mar 08 18:28:01 volumiorpi5 volumio[1069]: info: ControllerMpd::getState Mar 08 18:28:01 volumiorpi5 volumio[1069]: verbose: ControllerMpd::sendMpdCommand status Mar 08 18:28:01 volumiorpi5 volumio[1069]: verbose: ControllerMpd::parseState Mar 08 18:28:01 volumiorpi5 volumio[1069]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 18:28:01 volumiorpi5 volumio[1069]: verbose: ControllerMpd::parseState Mar 08 18:28:01 volumiorpi5 volumio[1069]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 18:28:01 volumiorpi5 volumio[1069]: info: sendMpdCommand status took 0 milliseconds Mar 08 18:28:01 volumiorpi5 volumio[1069]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 08 18:28:01 volumiorpi5 volumio[1069]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 08 18:28:01 volumiorpi5 volumio[1069]: verbose: ControllerMpd::parseState Mar 08 18:28:01 volumiorpi5 volumio[1069]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 18:28:01 volumiorpi5 volumio[1069]: verbose: ControllerMpd::parseTrackInfo Mar 08 18:28:01 volumiorpi5 volumio[1069]: verbose: ControllerMpd::parseTrackInfo Mar 08 18:28:01 volumiorpi5 volumio[1069]: info: ControllerMpd::pushState Mar 08 18:28:01 volumiorpi5 volumio[1069]: info: CoreCommandRouter::servicePushState Mar 08 18:28:01 volumiorpi5 volumio[1069]: info: CorePlayQueue::getTrack 0 Mar 08 18:28:01 volumiorpi5 volumio[1069]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Mar 08 18:28:01 volumiorpi5 volumio[1069]: info: ControllerMpd::pushState Mar 08 18:28:01 volumiorpi5 volumio[1069]: info: CoreCommandRouter::servicePushState Mar 08 18:28:01 volumiorpi5 volumio[1069]: info: CorePlayQueue::getTrack 0 Mar 08 18:28:01 volumiorpi5 volumio[1069]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Mar 08 18:28:01 volumiorpi5 volumio[1069]: info: ------------------------------ 29ms Mar 08 18:28:01 volumiorpi5 volumio[1069]: info: ------------------------------ 28ms Mar 08 18:28:01 volumiorpi5 volumio[1069]: info: Mar 08 18:28:01 volumiorpi5 volumio[1069]: ---------------------------- MPD announces state update: player Mar 08 18:28:01 volumiorpi5 volumio[1069]: info: ControllerMpd::getState Mar 08 18:28:01 volumiorpi5 volumio[1069]: verbose: ControllerMpd::sendMpdCommand status Mar 08 18:28:01 volumiorpi5 volumio[1069]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 08 18:28:01 volumiorpi5 volumio[1069]: info: sendMpdCommand status took 0 milliseconds Mar 08 18:28:01 volumiorpi5 volumio[1069]: verbose: ControllerMpd::parseTrackInfo Mar 08 18:28:01 volumiorpi5 volumio[1069]: verbose: ControllerMpd::parseState Mar 08 18:28:01 volumiorpi5 volumio[1069]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 18:28:01 volumiorpi5 volumio[1069]: info: ControllerMpd::pushState Mar 08 18:28:01 volumiorpi5 volumio[1069]: info: CoreCommandRouter::servicePushState Mar 08 18:28:01 volumiorpi5 volumio[1069]: info: CorePlayQueue::getTrack 0 Mar 08 18:28:01 volumiorpi5 volumio[1069]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Mar 08 18:28:01 volumiorpi5 volumio[1069]: info: ------------------------------ 4ms Mar 08 18:28:01 volumiorpi5 volumio[1069]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 08 18:28:01 volumiorpi5 volumio[1069]: verbose: ControllerMpd::parseTrackInfo Mar 08 18:28:01 volumiorpi5 volumio[1069]: info: ControllerMpd::pushState Mar 08 18:28:01 volumiorpi5 volumio[1069]: info: CoreCommandRouter::servicePushState Mar 08 18:28:01 volumiorpi5 volumio[1069]: info: CorePlayQueue::getTrack 0 Mar 08 18:28:01 volumiorpi5 volumio[1069]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Mar 08 18:28:01 volumiorpi5 volumio[1069]: info: ------------------------------ 1ms Mar 08 18:28:01 volumiorpi5 volumio[1069]: info: Mar 08 18:28:01 volumiorpi5 volumio[1069]: ---------------------------- MPD announces system playlist update Mar 08 18:28:01 volumiorpi5 volumio[1069]: info: Ignoring MPD Status Update Mar 08 18:28:01 volumiorpi5 volumio[1069]: info: Mar 08 18:28:01 volumiorpi5 volumio[1069]: ---------------------------- MPD announces state update: player Mar 08 18:28:01 volumiorpi5 volumio[1069]: info: ControllerMpd::getState Mar 08 18:28:01 volumiorpi5 volumio[1069]: verbose: ControllerMpd::sendMpdCommand status Mar 08 18:28:01 volumiorpi5 volumio[1069]: info: Mar 08 18:28:01 volumiorpi5 volumio[1069]: ---------------------------- MPD announces system playlist update Mar 08 18:28:01 volumiorpi5 volumio[1069]: info: Ignoring MPD Status Update Mar 08 18:28:01 volumiorpi5 volumio[1069]: info: Mar 08 18:28:01 volumiorpi5 volumio[1069]: ---------------------------- MPD announces state update: player Mar 08 18:28:01 volumiorpi5 volumio[1069]: info: ControllerMpd::getState Mar 08 18:28:01 volumiorpi5 volumio[1069]: verbose: ControllerMpd::sendMpdCommand status Mar 08 18:28:01 volumiorpi5 volumio[1069]: info: ------------------------------ 3ms Mar 08 18:28:01 volumiorpi5 volumio[1069]: info: sendMpdCommand status took 2 milliseconds Mar 08 18:28:01 volumiorpi5 volumio[1069]: info: ------------------------------ 2ms Mar 08 18:28:01 volumiorpi5 volumio[1069]: info: sendMpdCommand status took 1 milliseconds Mar 08 18:28:01 volumiorpi5 volumio[1069]: verbose: ControllerMpd::parseState Mar 08 18:28:01 volumiorpi5 volumio[1069]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 18:28:01 volumiorpi5 volumio[1069]: verbose: ControllerMpd::parseState Mar 08 18:28:01 volumiorpi5 volumio[1069]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 18:28:01 volumiorpi5 volumio[1069]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 08 18:28:01 volumiorpi5 volumio[1069]: verbose: ControllerMpd::parseTrackInfo Mar 08 18:28:01 volumiorpi5 volumio[1069]: info: ControllerMpd::pushState Mar 08 18:28:01 volumiorpi5 volumio[1069]: info: CoreCommandRouter::servicePushState Mar 08 18:28:01 volumiorpi5 volumio[1069]: info: CorePlayQueue::getTrack 0 Mar 08 18:28:01 volumiorpi5 volumio[1069]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Mar 08 18:28:01 volumiorpi5 volumio[1069]: info: ControllerMpd::pushState Mar 08 18:28:01 volumiorpi5 volumio[1069]: info: CoreCommandRouter::servicePushState Mar 08 18:28:01 volumiorpi5 volumio[1069]: info: CorePlayQueue::getTrack 0 Mar 08 18:28:01 volumiorpi5 volumio[1069]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Mar 08 18:28:01 volumiorpi5 volumio[1069]: info: ------------------------------ 4ms Mar 08 18:28:01 volumiorpi5 volumio[1069]: info: ------------------------------ 3ms Mar 08 18:28:04 volumiorpi5 volumio[1069]: info: [squeezelite_mc] 'client' notification received from daphile (192.168.0.54); type is 'connect' Mar 08 18:28:04 volumiorpi5 volumio[1069]: info: [squeezelite_mc] Getting players connected to daphile (192.168.0.54) Mar 08 18:28:04 volumiorpi5 volumio[1069]: info: [squeezelite_mc] Players connected to daphile (192.168.0.54): [{"id":"2c:cf:67:47:68:2b","uuid":null,"ip":"192.168.0.87","name":"volumiorpi5","server":{"ip":"192.168.0.54","name":"daphile","ver":"8.5.3","uuid":"28096606-6163-4c29-b2bb-a41e81c649f0","jsonPort":"9000","cliPort":"9090"}},{"id":"cc:cc:26:97:d5:c9","uuid":null,"ip":"192.168.0.54","name":"거실 네스트","server":{"ip":"192.168.0.54","name":"daphile","ver":"8.5.3","uuid":"28096606-6163-4c29-b2bb-a41e81c649f0","jsonPort":"9000","cliPort":"9090"}},{"id":"bb:bb:6f:a9:51:9f","uuid":null,"ip":"192.168.0.54","name":"[LG] webOS TV SK9000PUA","server":{"ip":"192.168.0.54","name":"daphile","ver":"8.5.3","uuid":"28096606-6163-4c29-b2bb-a41e81c649f0","jsonPort":"9000","cliPort":"9090"}},{"id":"cc:cc:ae:77:c0:a3","uuid":null,"ip":"192.168.0.54","name":"거실 윔프로","server":{"ip":"192.168.0.54","name":"daphile","ver":"8.5.3","uuid":"28096606-6163-4c29-b2bb-a41e81c649f0","jsonPort":"9000","cliPort":"9090"}},{"id":"cc:cc:92:60:53:f3","uuid":null,"ip":"192.168.0.54","name":"서재 윔프로","server":{"ip":"192.168.0.54","name":"daphile","ver":"8.5.3","uuid":"28096606-6163-4c29-b2bb-a41e81c649f0","jsonPort":"9000","cliPort":"9090"}},{"id":"cc:cc:48:cd:11:82","uuid":null,"ip":"192.168.0.54","name":"SHIELD","server":{"ip":"192.168.0.54","name":"daphile","ver":"8.5.3","uuid":"28096606-6163-4c29-b2bb-a41e81c649f0","jsonPort":"9000","cliPort":"9090"}},{"id":"bb:bb:23:84:24:d1","uuid":null,"ip":"192.168.0.54","name":"Volumio_RPi5-UPnP/AV","server":{"ip":"192.168.0.54","name":"daphile","ver":"8.5.3","uuid":"28096606-6163-4c29-b2bb-a41e81c649f0","jsonPort":"9000","cliPort":"9090"}},{"id":"cc:cc:01:ca:db:a8","uuid":null,"ip":"192.168.0.54","name":"서재 크캐오","server":{"ip":"192.168.0.54","name":"daphile","ver":"8.5.3","uuid":"28096606-6163-4c29-b2bb-a41e81c649f0","jsonPort":"9000","cliPort":"9090"}},{"id":"bb:bb:c8:f4:3c:24","uuid":null,"ip":"192.168.0.54","name":"서재 윔프로","server":{"ip":"192.168.0.54","name":"daphile","ver":"8.5.3","uuid":"28096606-6163-4c29-b2bb-a41e81c649f0","jsonPort":"9000","cliPort":"9090"}},{"id":"bb:bb:27:89:de:a3","uuid":null,"ip":"192.168.0.54","name":"거실 윔프로","server":{"ip":"192.168.0.54","name":"daphile","ver":"8.5.3","uuid":"28096606-6163-4c29-b2bb-a41e81c649f0","jsonPort":"9000","cliPort":"9090"}},{"id":"00:22:6c:2a:38:92","uuid":null,"ip":"192.168.0.63","name":"서재 윔프로","server":{"ip":"192.168.0.54","name":"daphile","ver":"8.5.3","uuid":"28096606-6163-4c29-b2bb-a41e81c649f0","jsonPort":"9000","cliPort":"9090"}}] Mar 08 18:28:04 volumiorpi5 volumio[1069]: info: [squeezelite_mc] Player found: {"id":"2c:cf:67:47:68:2b","uuid":null,"ip":"192.168.0.87","name":"volumiorpi5","server":{"ip":"192.168.0.54","name":"daphile","ver":"8.5.3","uuid":"28096606-6163-4c29-b2bb-a41e81c649f0","jsonPort":"9000","cliPort":"9090"}} Mar 08 18:28:05 volumiorpi5 volumio[1069]: (node:1069) UnhandledPromiseRejectionWarning: TypeError: Cannot read property '0' of undefined Mar 08 18:28:05 volumiorpi5 volumio[1069]: at PlayerStatusMonitor._PlayerStatusMonitor_parsePlayerStatusResult (/data/plugins/music_service/squeezelite_mc/dist/lib/PlayerStatusMonitor.js:180:37) Mar 08 18:28:05 volumiorpi5 volumio[1069]: at PlayerStatusMonitor._PlayerStatusMonitor_getStatusAndEmit (/data/plugins/music_service/squeezelite_mc/dist/lib/PlayerStatusMonitor.js:118:129) Mar 08 18:28:05 volumiorpi5 volumio[1069]: at runMicrotasks () Mar 08 18:28:05 volumiorpi5 volumio[1069]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Mar 08 18:28:05 volumiorpi5 volumio[1069]: at async PlayerStatusMonitor.start (/data/plugins/music_service/squeezelite_mc/dist/lib/PlayerStatusMonitor.js:47:9) Mar 08 18:28:05 volumiorpi5 volumio[1069]: at async PlayerFinder. (/data/plugins/music_service/squeezelite_mc/dist/index.js:686:13) Mar 08 18:28:05 volumiorpi5 volumio[1069]: (node:1069) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 7) Mar 08 18:28:22 volumiorpi5 volumio[1069]: info: CoreCommandRouter::volumioPause Mar 08 18:28:22 volumiorpi5 volumio[1069]: info: CoreStateMachine::pause Mar 08 18:28:22 volumiorpi5 volumio[1069]: info: CoreStateMachine::stPlaybackTimer Mar 08 18:28:22 volumiorpi5 volumio[1069]: info: CoreStateMachine::servicePause Mar 08 18:28:22 volumiorpi5 volumio[1069]: info: CorePlayQueue::getTrack 0 Mar 08 18:28:22 volumiorpi5 volumio[1069]: info: CoreCommandRouter::servicePause Mar 08 18:28:22 volumiorpi5 volumio[1069]: verbose: ControllerMpd::sendMpdCommand pause Mar 08 18:28:22 volumiorpi5 volumio[1069]: info: Mar 08 18:28:22 volumiorpi5 volumio[1069]: ---------------------------- MPD announces state update: player Mar 08 18:28:22 volumiorpi5 volumio[1069]: info: sendMpdCommand pause took 1 milliseconds Mar 08 18:28:22 volumiorpi5 volumio[1069]: info: ControllerMpd::getState Mar 08 18:28:22 volumiorpi5 volumio[1069]: verbose: ControllerMpd::sendMpdCommand status Mar 08 18:28:22 volumiorpi5 volumio[1069]: info: CorePlayQueue::getTrack 0 Mar 08 18:28:22 volumiorpi5 volumio[1069]: info: CoreCommandRouter::servicePushState Mar 08 18:28:22 volumiorpi5 volumio[1069]: info: CorePlayQueue::getTrack 0 Mar 08 18:28:22 volumiorpi5 volumio[1069]: verbose: STATE SERVICE {"status":"pause","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B000005ILF.jpg","uri":"https://stream.radioparadise.com/mellow-flac","name":"La Belle Dame sans regrets","title":"La Belle Dame sans regrets","artist":"Sting","album":"Mercury Falling","streaming":true,"disableUiControls":true,"duration":216,"seek":22086,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Mar 08 18:28:22 volumiorpi5 volumio[1069]: verbose: CURRENT POSITION 0 Mar 08 18:28:22 volumiorpi5 volumio[1069]: info: CoreStateMachine::syncState stateService pause Mar 08 18:28:22 volumiorpi5 volumio[1069]: info: CoreStateMachine::syncState currentStatus pause Mar 08 18:28:22 volumiorpi5 volumio[1069]: info: CoreStateMachine::pushState Mar 08 18:28:22 volumiorpi5 volumio[1069]: info: CorePlayQueue::getTrack 0 Mar 08 18:28:22 volumiorpi5 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 18:28:22 volumiorpi5 volumio[1069]: info: CoreCommandRouter::volumioPushState Mar 08 18:28:22 volumiorpi5 volumio[1069]: info: CoreStateMachine::stPlaybackTimer Mar 08 18:28:22 volumiorpi5 volumio[1069]: info: Mar 08 18:28:22 volumiorpi5 volumio[1069]: ---------------------------- MPD announces state update: player Mar 08 18:28:22 volumiorpi5 volumio[1069]: info: ControllerMpd::getState Mar 08 18:28:22 volumiorpi5 volumio[1069]: verbose: ControllerMpd::sendMpdCommand status Mar 08 18:28:22 volumiorpi5 volumio[1069]: info: sendMpdCommand status took 9 milliseconds Mar 08 18:28:22 volumiorpi5 volumio[1069]: verbose: ControllerMpd::parseState Mar 08 18:28:22 volumiorpi5 volumio[1069]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 18:28:22 volumiorpi5 volumio[1069]: info: sendMpdCommand status took 6 milliseconds Mar 08 18:28:22 volumiorpi5 volumio[1069]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 08 18:28:22 volumiorpi5 volumio[1069]: verbose: ControllerMpd::parseState Mar 08 18:28:22 volumiorpi5 volumio[1069]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 18:28:22 volumiorpi5 volumio[1069]: verbose: ControllerMpd::parseTrackInfo Mar 08 18:28:22 volumiorpi5 volumio[1069]: info: ControllerMpd::pushState Mar 08 18:28:22 volumiorpi5 volumio[1069]: info: CoreCommandRouter::servicePushState Mar 08 18:28:22 volumiorpi5 volumio[1069]: info: CorePlayQueue::getTrack 0 Mar 08 18:28:22 volumiorpi5 volumio[1069]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Mar 08 18:28:22 volumiorpi5 volumio[1069]: info: ------------------------------ 13ms Mar 08 18:28:22 volumiorpi5 volumio[1069]: info: sendMpdCommand playlistinfo took 20 milliseconds Mar 08 18:28:22 volumiorpi5 volumio[1069]: verbose: ControllerMpd::parseTrackInfo Mar 08 18:28:22 volumiorpi5 volumio[1069]: info: ControllerMpd::pushState Mar 08 18:28:22 volumiorpi5 volumio[1069]: info: CoreCommandRouter::servicePushState Mar 08 18:28:22 volumiorpi5 volumio[1069]: info: CorePlayQueue::getTrack 0 Mar 08 18:28:22 volumiorpi5 volumio[1069]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Mar 08 18:28:22 volumiorpi5 volumio[1069]: info: ------------------------------ 28ms Mar 08 18:28:22 volumiorpi5 volumio[1069]: info: touch_display: Setting screensaver timeout to 120 seconds. Mar 08 18:29:20 volumiorpi5 volumio[1069]: info: Clearing queue after UPNP request Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: CoreStateMachine::ClearQueue Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: CoreStateMachine::stop Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: CoreStateMachine::updateTrackBlock Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: CorePlayQueue::getTrackBlock Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: CoreStateMachine::stPlaybackTimer Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: CoreStateMachine::pushState Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: CorePlayQueue::getTrack 0 Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: CoreCommandRouter::volumioPushState Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: CoreStateMachine::serviceStop Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: CorePlayQueue::getTrack 0 Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: CoreCommandRouter::serviceStop Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: ControllerMpd::stop Mar 08 18:29:21 volumiorpi5 volumio[1069]: verbose: ControllerMpd::sendMpdCommand stop Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: CorePlayQueue::clearPlayQueue Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: CorePlayQueue::saveQueue Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: CoreCommandRouter::volumioPushState Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: CoreCommandRouter::volumioPushQueue Mar 08 18:29:21 volumiorpi5 volumio[1069]: error: Upnp client error: Error: This socket has been ended by the other party Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: Mar 08 18:29:21 volumiorpi5 volumio[1069]: ---------------------------- MPD announces state update: player Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: sendMpdCommand stop took 109 milliseconds Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: ControllerMpd::getState Mar 08 18:29:21 volumiorpi5 volumio[1069]: verbose: ControllerMpd::sendMpdCommand status Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: CoreCommandRouter::servicePushState Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: CoreStateMachine::pushState Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: CorePlayQueue::getTrack 0 Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: CoreCommandRouter::volumioPushState Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: CorePlayQueue::getTrack 0 Mar 08 18:29:21 volumiorpi5 volumio[1069]: verbose: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B000005ILF.jpg","uri":"https://stream.radioparadise.com/mellow-flac","name":"La Belle Dame sans regrets","title":"La Belle Dame sans regrets","artist":"Sting","album":"Mercury Falling","streaming":true,"disableUiControls":true,"duration":216,"seek":22086,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Mar 08 18:29:21 volumiorpi5 volumio[1069]: verbose: CURRENT POSITION 0 Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: CoreStateMachine::syncState stateService stop Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: CoreStateMachine::syncState currentStatus stop Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: CoreStateMachine::pushState Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: CorePlayQueue::getTrack 0 Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: CoreCommandRouter::volumioPushState Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: No code Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: CoreStateMachine::pushState Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: CorePlayQueue::getTrack 0 Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: CoreCommandRouter::volumioPushState Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: Mar 08 18:29:21 volumiorpi5 volumio[1069]: ---------------------------- MPD announces state update: player Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: ControllerMpd::getState Mar 08 18:29:21 volumiorpi5 volumio[1069]: verbose: ControllerMpd::sendMpdCommand status Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: sendMpdCommand status took 24 milliseconds Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: sendMpdCommand status took 2 milliseconds Mar 08 18:29:21 volumiorpi5 volumio[1069]: verbose: ControllerMpd::parseState Mar 08 18:29:21 volumiorpi5 volumio[1069]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 18:29:21 volumiorpi5 volumio[1069]: verbose: ControllerMpd::parseState Mar 08 18:29:21 volumiorpi5 volumio[1069]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 08 18:29:21 volumiorpi5 volumio[1069]: verbose: ControllerMpd::parseTrackInfo Mar 08 18:29:21 volumiorpi5 volumio[1069]: verbose: ControllerMpd::parseTrackInfo Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: ControllerMpd::pushState Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: CoreCommandRouter::servicePushState Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: CoreStateMachine::pushState Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: CorePlayQueue::getTrack 0 Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: CoreCommandRouter::volumioPushState Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: CorePlayQueue::getTrack 0 Mar 08 18:29:21 volumiorpi5 volumio[1069]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mellow-flac","artist":"Radio Paradise: Mellow Mix (flac) - playlist: radioparadise.com","album":null,"uri":"https://stream.radioparadise.com/mellow-flac","trackType":""} Mar 08 18:29:21 volumiorpi5 volumio[1069]: verbose: CURRENT POSITION 0 Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: CoreStateMachine::syncState stateService stop Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: CoreStateMachine::syncState currentStatus stop Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: CoreStateMachine::pushState Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: CorePlayQueue::getTrack 0 Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: CoreCommandRouter::volumioPushState Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: No code Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: CoreStateMachine::pushState Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: CorePlayQueue::getTrack 0 Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: CoreCommandRouter::volumioPushState Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: ControllerMpd::pushState Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: CoreCommandRouter::servicePushState Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: CoreStateMachine::pushState Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: CorePlayQueue::getTrack 0 Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: CoreCommandRouter::volumioPushState Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: CorePlayQueue::getTrack 0 Mar 08 18:29:21 volumiorpi5 volumio[1069]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mellow-flac","artist":"Radio Paradise: Mellow Mix (flac) - playlist: radioparadise.com","album":null,"uri":"https://stream.radioparadise.com/mellow-flac","trackType":""} Mar 08 18:29:21 volumiorpi5 volumio[1069]: verbose: CURRENT POSITION 0 Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: CoreStateMachine::syncState stateService stop Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: CoreStateMachine::syncState currentStatus stop Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: CoreStateMachine::pushState Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: CorePlayQueue::getTrack 0 Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: CoreCommandRouter::volumioPushState Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: No code Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: CoreStateMachine::pushState Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: CorePlayQueue::getTrack 0 Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: CoreCommandRouter::volumioPushState Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: ------------------------------ 59ms Mar 08 18:29:21 volumiorpi5 volumio[1069]: info: ------------------------------ 35ms Mar 08 18:29:22 volumiorpi5 volumio[1069]: info: Mar 08 18:29:22 volumiorpi5 volumio[1069]: ---------------------------- MPD announces state update: options Mar 08 18:29:22 volumiorpi5 volumio[1069]: info: ControllerMpd::getState Mar 08 18:29:22 volumiorpi5 volumio[1069]: verbose: ControllerMpd::sendMpdCommand status Mar 08 18:29:22 volumiorpi5 volumio[1069]: info: Mar 08 18:29:22 volumiorpi5 volumio[1069]: ---------------------------- MPD announces state update: options Mar 08 18:29:22 volumiorpi5 volumio[1069]: info: ControllerMpd::getState Mar 08 18:29:22 volumiorpi5 volumio[1069]: verbose: ControllerMpd::sendMpdCommand status Mar 08 18:29:22 volumiorpi5 volumio[1069]: info: Starting UPNP Playback Mar 08 18:29:22 volumiorpi5 volumio[1069]: info: Preparing playback through UPNP Mar 08 18:29:22 volumiorpi5 volumio[1069]: info: CoreCommandRouter::volumioGetState Mar 08 18:29:22 volumiorpi5 volumio[1069]: info: CorePlayQueue::getTrack 0 Mar 08 18:29:22 volumiorpi5 volumio[1069]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 08 18:29:22 volumiorpi5 volumio[1069]: info: sendMpdCommand status took 1 milliseconds Mar 08 18:29:22 volumiorpi5 volumio[1069]: info: sendMpdCommand status took 1 milliseconds Mar 08 18:29:22 volumiorpi5 volumio[1069]: verbose: ControllerMpd::parseState Mar 08 18:29:22 volumiorpi5 volumio[1069]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 18:29:22 volumiorpi5 volumio[1069]: verbose: ControllerMpd::parseState Mar 08 18:29:22 volumiorpi5 volumio[1069]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 18:29:22 volumiorpi5 volumio[1069]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 08 18:29:22 volumiorpi5 volumio[1069]: verbose: ControllerMpd::parseTrackInfo Mar 08 18:29:22 volumiorpi5 volumio[1069]: info: ControllerMpd::pushState Mar 08 18:29:22 volumiorpi5 volumio[1069]: info: CoreCommandRouter::servicePushState Mar 08 18:29:22 volumiorpi5 volumio[1069]: info: CoreStateMachine::pushState Mar 08 18:29:22 volumiorpi5 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 18:29:22 volumiorpi5 volumio[1069]: info: CoreCommandRouter::volumioPushState Mar 08 18:29:22 volumiorpi5 volumio[1069]: info: CorePlayQueue::getTrack 0 Mar 08 18:29:22 volumiorpi5 volumio[1069]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mellow-flac","artist":"Radio Paradise: Mellow Mix (flac) - playlist: radioparadise.com","album":null,"uri":"https://stream.radioparadise.com/mellow-flac","trackType":""} Mar 08 18:29:22 volumiorpi5 volumio[1069]: verbose: CURRENT POSITION 0 Mar 08 18:29:22 volumiorpi5 volumio[1069]: info: CoreStateMachine::syncState stateService stop Mar 08 18:29:22 volumiorpi5 volumio[1069]: info: CoreStateMachine::syncState currentStatus stop Mar 08 18:29:22 volumiorpi5 volumio[1069]: info: CoreStateMachine::pushState Mar 08 18:29:22 volumiorpi5 volumio[1069]: info: CoreCommandRouter::volumioPushState Mar 08 18:29:22 volumiorpi5 volumio[1069]: info: No code Mar 08 18:29:22 volumiorpi5 volumio[1069]: info: CoreStateMachine::pushState Mar 08 18:29:22 volumiorpi5 volumio[1069]: info: CoreCommandRouter::volumioPushState Mar 08 18:29:22 volumiorpi5 volumio[1069]: info: ControllerMpd::pushState Mar 08 18:29:22 volumiorpi5 volumio[1069]: info: CoreCommandRouter::servicePushState Mar 08 18:29:22 volumiorpi5 volumio[1069]: info: CoreStateMachine::pushState Mar 08 18:29:22 volumiorpi5 volumio[1069]: info: CoreCommandRouter::volumioPushState Mar 08 18:29:22 volumiorpi5 volumio[1069]: info: CorePlayQueue::getTrack 0 Mar 08 18:29:22 volumiorpi5 volumio[1069]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mellow-flac","artist":"Radio Paradise: Mellow Mix (flac) - playlist: radioparadise.com","album":null,"uri":"https://stream.radioparadise.com/mellow-flac","trackType":""} Mar 08 18:29:22 volumiorpi5 volumio[1069]: verbose: CURRENT POSITION 0 Mar 08 18:29:22 volumiorpi5 volumio[1069]: info: CoreStateMachine::syncState stateService stop Mar 08 18:29:22 volumiorpi5 volumio[1069]: info: CoreStateMachine::syncState currentStatus stop Mar 08 18:29:22 volumiorpi5 volumio[1069]: info: CoreStateMachine::pushState Mar 08 18:29:22 volumiorpi5 volumio[1069]: info: CoreCommandRouter::volumioPushState Mar 08 18:29:22 volumiorpi5 volumio[1069]: info: No code Mar 08 18:29:22 volumiorpi5 volumio[1069]: info: CoreStateMachine::pushState Mar 08 18:29:22 volumiorpi5 volumio[1069]: info: CoreCommandRouter::volumioPushState Mar 08 18:29:22 volumiorpi5 volumio[1069]: info: ------------------------------ 28ms Mar 08 18:29:22 volumiorpi5 volumio[1069]: info: ------------------------------ 29ms Mar 08 18:29:22 volumiorpi5 volumio[1069]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.B5ClrpOGm4V6LbMMugpMkYvFics2.bef588de90194611f0a26731a3eeec7a.state.status' Mar 08 18:29:22 volumiorpi5 volumio[1069]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.B5ClrpOGm4V6LbMMugpMkYvFics2.bef588de90194611f0a26731a3eeec7a.state.status' Mar 08 18:29:22 volumiorpi5 volumio[1069]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.B5ClrpOGm4V6LbMMugpMkYvFics2.bef588de90194611f0a26731a3eeec7a.state.status' Mar 08 18:29:22 volumiorpi5 volumio[1069]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.B5ClrpOGm4V6LbMMugpMkYvFics2.bef588de90194611f0a26731a3eeec7a.state.status' Mar 08 18:29:22 volumiorpi5 volumio[1069]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.B5ClrpOGm4V6LbMMugpMkYvFics2.bef588de90194611f0a26731a3eeec7a.state.status' Mar 08 18:29:23 volumiorpi5 volumio[1069]: info: Mar 08 18:29:23 volumiorpi5 volumio[1069]: ---------------------------- MPD announces system playlist update Mar 08 18:29:23 volumiorpi5 volumio[1069]: info: Ignoring MPD Status Update Mar 08 18:29:23 volumiorpi5 volumio[1069]: info: Mar 08 18:29:23 volumiorpi5 volumio[1069]: ---------------------------- MPD announces system playlist update Mar 08 18:29:23 volumiorpi5 volumio[1069]: info: Ignoring MPD Status Update Mar 08 18:29:23 volumiorpi5 volumio[1069]: info: ------------------------------ 1ms Mar 08 18:29:23 volumiorpi5 volumio[1069]: info: ------------------------------ 0ms Mar 08 18:29:23 volumiorpi5 volumio[1069]: info: Mar 08 18:29:23 volumiorpi5 volumio[1069]: ---------------------------- MPD announces system playlist update Mar 08 18:29:23 volumiorpi5 volumio[1069]: info: Ignoring MPD Status Update Mar 08 18:29:23 volumiorpi5 volumio[1069]: info: Mar 08 18:29:23 volumiorpi5 volumio[1069]: ---------------------------- MPD announces system playlist update Mar 08 18:29:23 volumiorpi5 volumio[1069]: info: Ignoring MPD Status Update Mar 08 18:29:23 volumiorpi5 volumio[1069]: info: ------------------------------ 0ms Mar 08 18:29:23 volumiorpi5 volumio[1069]: info: ------------------------------ 0ms Mar 08 18:29:23 volumiorpi5 volumio[1069]: info: Mar 08 18:29:23 volumiorpi5 volumio[1069]: ---------------------------- MPD announces system playlist update Mar 08 18:29:23 volumiorpi5 volumio[1069]: info: Ignoring MPD Status Update Mar 08 18:29:23 volumiorpi5 volumio[1069]: info: Mar 08 18:29:23 volumiorpi5 volumio[1069]: ---------------------------- MPD announces system playlist update Mar 08 18:29:23 volumiorpi5 volumio[1069]: info: Ignoring MPD Status Update Mar 08 18:29:23 volumiorpi5 volumio[1069]: info: ------------------------------ 0ms Mar 08 18:29:23 volumiorpi5 volumio[1069]: info: ------------------------------ 0ms Mar 08 18:29:23 volumiorpi5 volumio[1069]: info: Mar 08 18:29:23 volumiorpi5 volumio[1069]: ---------------------------- MPD announces system playlist update Mar 08 18:29:23 volumiorpi5 volumio[1069]: info: Ignoring MPD Status Update Mar 08 18:29:23 volumiorpi5 volumio[1069]: info: Mar 08 18:29:23 volumiorpi5 volumio[1069]: ---------------------------- MPD announces system playlist update Mar 08 18:29:23 volumiorpi5 volumio[1069]: info: Ignoring MPD Status Update Mar 08 18:29:23 volumiorpi5 volumio[1069]: info: ------------------------------ 1ms Mar 08 18:29:23 volumiorpi5 volumio[1069]: info: ------------------------------ 0ms Mar 08 18:29:23 volumiorpi5 volumio[1069]: info: Mar 08 18:29:23 volumiorpi5 volumio[1069]: ---------------------------- MPD announces system playlist update Mar 08 18:29:23 volumiorpi5 volumio[1069]: info: Ignoring MPD Status Update Mar 08 18:29:23 volumiorpi5 volumio[1069]: info: Mar 08 18:29:23 volumiorpi5 volumio[1069]: ---------------------------- MPD announces system playlist update Mar 08 18:29:23 volumiorpi5 volumio[1069]: info: Ignoring MPD Status Update Mar 08 18:29:23 volumiorpi5 volumio[1069]: info: ------------------------------ 1ms Mar 08 18:29:23 volumiorpi5 volumio[1069]: info: ------------------------------ 1ms Mar 08 18:29:23 volumiorpi5 volumio[1069]: info: Mar 08 18:29:23 volumiorpi5 volumio[1069]: ---------------------------- MPD announces system playlist update Mar 08 18:29:23 volumiorpi5 volumio[1069]: info: Ignoring MPD Status Update Mar 08 18:29:23 volumiorpi5 volumio[1069]: info: Mar 08 18:29:23 volumiorpi5 volumio[1069]: ---------------------------- MPD announces system playlist update Mar 08 18:29:23 volumiorpi5 volumio[1069]: info: Ignoring MPD Status Update Mar 08 18:29:23 volumiorpi5 volumio[1069]: info: ------------------------------ 1ms Mar 08 18:29:23 volumiorpi5 volumio[1069]: info: ------------------------------ 1ms Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: Mar 08 18:29:25 volumiorpi5 volumio[1069]: ---------------------------- MPD announces state update: player Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: ControllerMpd::getState Mar 08 18:29:25 volumiorpi5 volumio[1069]: verbose: ControllerMpd::sendMpdCommand status Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: Mar 08 18:29:25 volumiorpi5 volumio[1069]: ---------------------------- MPD announces state update: player Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: ControllerMpd::getState Mar 08 18:29:25 volumiorpi5 volumio[1069]: verbose: ControllerMpd::sendMpdCommand status Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: Mar 08 18:29:25 volumiorpi5 volumio[1069]: ---------------------------- MPD announces state update: player Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: sendMpdCommand status took 4 milliseconds Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: sendMpdCommand status took 5 milliseconds Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: ControllerMpd::getState Mar 08 18:29:25 volumiorpi5 volumio[1069]: verbose: ControllerMpd::sendMpdCommand status Mar 08 18:29:25 volumiorpi5 volumio[1069]: verbose: ControllerMpd::parseState Mar 08 18:29:25 volumiorpi5 volumio[1069]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 18:29:25 volumiorpi5 volumio[1069]: verbose: ControllerMpd::parseState Mar 08 18:29:25 volumiorpi5 volumio[1069]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: Mar 08 18:29:25 volumiorpi5 volumio[1069]: ---------------------------- MPD announces state update: player Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: ControllerMpd::getState Mar 08 18:29:25 volumiorpi5 volumio[1069]: verbose: ControllerMpd::sendMpdCommand status Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: sendMpdCommand status took 1 milliseconds Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: sendMpdCommand status took 1 milliseconds Mar 08 18:29:25 volumiorpi5 volumio[1069]: verbose: ControllerMpd::parseState Mar 08 18:29:25 volumiorpi5 volumio[1069]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 18:29:25 volumiorpi5 volumio[1069]: verbose: ControllerMpd::parseTrackInfo Mar 08 18:29:25 volumiorpi5 volumio[1069]: verbose: ControllerMpd::parseTrackInfo Mar 08 18:29:25 volumiorpi5 volumio[1069]: verbose: ControllerMpd::parseState Mar 08 18:29:25 volumiorpi5 volumio[1069]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: ControllerMpd::pushState Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: CoreCommandRouter::servicePushState Mar 08 18:29:25 volumiorpi5 volumio[1069]: verbose: In UPNP mode Mar 08 18:29:25 volumiorpi5 volumio[1069]: verbose: STATE SERVICE {"status":"play","position":1,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mellow-flac","artist":"Radio Paradise: Mellow Mix (flac) - playlist: radioparadise.com","album":null,"uri":"https://stream.radioparadise.com/mellow-flac","trackType":""} Mar 08 18:29:25 volumiorpi5 volumio[1069]: verbose: CURRENT POSITION 0 Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: CoreStateMachine::syncState stateService play Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: CoreStateMachine::syncState currentStatus stop Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: ControllerMpd::pushState Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: CoreCommandRouter::servicePushState Mar 08 18:29:25 volumiorpi5 volumio[1069]: verbose: In UPNP mode Mar 08 18:29:25 volumiorpi5 volumio[1069]: verbose: STATE SERVICE {"status":"play","position":1,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mellow-flac","artist":"Radio Paradise: Mellow Mix (flac) - playlist: radioparadise.com","album":null,"uri":"https://stream.radioparadise.com/mellow-flac","trackType":""} Mar 08 18:29:25 volumiorpi5 volumio[1069]: verbose: CURRENT POSITION 0 Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: CoreStateMachine::syncState stateService play Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: CoreStateMachine::syncState currentStatus play Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: Received an update from plugin. extracting info from payload Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: CoreStateMachine::pushState Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: CorePlayQueue::getTrack 0 Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: ------------------------------ 9ms Mar 08 18:29:25 volumiorpi5 volumio[1069]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: ------------------------------ 8ms Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 08 18:29:25 volumiorpi5 volumio[1069]: verbose: ControllerMpd::parseTrackInfo Mar 08 18:29:25 volumiorpi5 volumio[1069]: verbose: ControllerMpd::parseTrackInfo Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: ControllerMpd::pushState Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: CoreCommandRouter::servicePushState Mar 08 18:29:25 volumiorpi5 volumio[1069]: verbose: In UPNP mode Mar 08 18:29:25 volumiorpi5 volumio[1069]: verbose: STATE SERVICE {"status":"play","position":1,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mellow-flac","artist":"Radio Paradise: Mellow Mix (flac) - playlist: radioparadise.com","album":null,"uri":"https://stream.radioparadise.com/mellow-flac","trackType":""} Mar 08 18:29:25 volumiorpi5 volumio[1069]: verbose: CURRENT POSITION 0 Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: CoreStateMachine::syncState stateService play Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: CoreStateMachine::syncState currentStatus play Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: Received an update from plugin. extracting info from payload Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: CoreStateMachine::pushState Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: CorePlayQueue::getTrack 0 Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: ControllerMpd::pushState Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: CoreCommandRouter::servicePushState Mar 08 18:29:25 volumiorpi5 volumio[1069]: verbose: In UPNP mode Mar 08 18:29:25 volumiorpi5 volumio[1069]: verbose: STATE SERVICE {"status":"play","position":1,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mellow-flac","artist":"Radio Paradise: Mellow Mix (flac) - playlist: radioparadise.com","album":null,"uri":"https://stream.radioparadise.com/mellow-flac","trackType":""} Mar 08 18:29:25 volumiorpi5 volumio[1069]: verbose: CURRENT POSITION 0 Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: CoreStateMachine::syncState stateService play Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: CoreStateMachine::syncState currentStatus play Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: Received an update from plugin. extracting info from payload Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: CoreStateMachine::pushState Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: CorePlayQueue::getTrack 0 Mar 08 18:29:25 volumiorpi5 volumio[1069]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Mar 08 18:29:25 volumiorpi5 volumio[1069]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: ------------------------------ 6ms Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: ------------------------------ 5ms Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: Mar 08 18:29:25 volumiorpi5 volumio[1069]: ---------------------------- MPD announces system playlist update Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: Ignoring MPD Status Update Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: Mar 08 18:29:25 volumiorpi5 volumio[1069]: ---------------------------- MPD announces state update: player Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: ControllerMpd::getState Mar 08 18:29:25 volumiorpi5 volumio[1069]: verbose: ControllerMpd::sendMpdCommand status Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: Mar 08 18:29:25 volumiorpi5 volumio[1069]: ---------------------------- MPD announces system playlist update Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: Ignoring MPD Status Update Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: Mar 08 18:29:25 volumiorpi5 volumio[1069]: ---------------------------- MPD announces state update: player Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: ControllerMpd::getState Mar 08 18:29:25 volumiorpi5 volumio[1069]: verbose: ControllerMpd::sendMpdCommand status Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: ------------------------------ 1ms Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: sendMpdCommand status took 1 milliseconds Mar 08 18:29:25 volumiorpi5 volumio[1069]: verbose: ControllerMpd::parseState Mar 08 18:29:25 volumiorpi5 volumio[1069]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: ------------------------------ 2ms Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: sendMpdCommand status took 1 milliseconds Mar 08 18:29:25 volumiorpi5 volumio[1069]: verbose: ControllerMpd::parseState Mar 08 18:29:25 volumiorpi5 volumio[1069]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 08 18:29:25 volumiorpi5 volumio[1069]: verbose: ControllerMpd::parseTrackInfo Mar 08 18:29:25 volumiorpi5 volumio[1069]: verbose: ControllerMpd::parseTrackInfo Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: ControllerMpd::pushState Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: CoreCommandRouter::servicePushState Mar 08 18:29:25 volumiorpi5 volumio[1069]: verbose: In UPNP mode Mar 08 18:29:25 volumiorpi5 volumio[1069]: verbose: STATE SERVICE {"status":"play","position":1,"seek":434,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mellow-flac","artist":"Radio Paradise: Mellow Mix (flac) - playlist: radioparadise.com","album":null,"uri":"https://stream.radioparadise.com/mellow-flac","trackType":""} Mar 08 18:29:25 volumiorpi5 volumio[1069]: verbose: CURRENT POSITION 0 Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: CoreStateMachine::syncState stateService play Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: CoreStateMachine::syncState currentStatus play Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: Received an update from plugin. extracting info from payload Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: CoreStateMachine::pushState Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: CorePlayQueue::getTrack 0 Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: ControllerMpd::pushState Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: CoreCommandRouter::servicePushState Mar 08 18:29:25 volumiorpi5 volumio[1069]: verbose: In UPNP mode Mar 08 18:29:25 volumiorpi5 volumio[1069]: verbose: STATE SERVICE {"status":"play","position":1,"seek":846,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mellow-flac","artist":"Radio Paradise: Mellow Mix (flac) - playlist: radioparadise.com","album":null,"uri":"https://stream.radioparadise.com/mellow-flac","trackType":""} Mar 08 18:29:25 volumiorpi5 volumio[1069]: verbose: CURRENT POSITION 0 Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: CoreStateMachine::syncState stateService play Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: CoreStateMachine::syncState currentStatus play Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: Received an update from plugin. extracting info from payload Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: CoreStateMachine::pushState Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: CorePlayQueue::getTrack 0 Mar 08 18:29:25 volumiorpi5 volumio[1069]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Mar 08 18:29:25 volumiorpi5 volumio[1069]: error: ControllerMpd::pushError: TypeError: Cannot read property 'name' of undefined Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: ------------------------------ 5ms Mar 08 18:29:25 volumiorpi5 volumio[1069]: info: ------------------------------ 5ms Mar 08 18:29:34 volumiorpi5 volumio[1069]: info: CoreCommandRouter::volumioGetState Mar 08 18:29:34 volumiorpi5 volumio[1069]: info: CorePlayQueue::getTrack 0 Mar 08 18:29:34 volumiorpi5 volumio[1069]: (node:1069) UnhandledPromiseRejectionWarning: TypeError: Cannot read property 'name' of undefined Mar 08 18:29:34 volumiorpi5 volumio[1069]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Mar 08 18:29:34 volumiorpi5 volumio[1069]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Mar 08 18:29:34 volumiorpi5 volumio[1069]: at ControllerSqueezeliteMC._ControllerSqueezeliteMC_getCurrentService (/data/plugins/music_service/squeezelite_mc/dist/index.js:960:100) Mar 08 18:29:34 volumiorpi5 volumio[1069]: at ControllerSqueezeliteMC._ControllerSqueezeliteMC_isCurrentService (/data/plugins/music_service/squeezelite_mc/dist/index.js:963:126) Mar 08 18:29:34 volumiorpi5 volumio[1069]: at ControllerSqueezeliteMC._ControllerSqueezeliteMC_handlePlayerStatusUpdate (/data/plugins/music_service/squeezelite_mc/dist/index.js:756:143) Mar 08 18:29:34 volumiorpi5 volumio[1069]: at PlayerStatusMonitor.emit (events.js:315:20) Mar 08 18:29:34 volumiorpi5 volumio[1069]: at PlayerStatusMonitor._PlayerStatusMonitor_getStatusAndEmit (/data/plugins/music_service/squeezelite_mc/dist/lib/PlayerStatusMonitor.js:116:10) Mar 08 18:29:34 volumiorpi5 volumio[1069]: at runMicrotasks () Mar 08 18:29:34 volumiorpi5 volumio[1069]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Mar 08 18:29:34 volumiorpi5 volumio[1069]: (node:1069) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 8) Mar 08 18:29:44 volumiorpi5 volumio[1069]: info: CoreCommandRouter::volumioGetState Mar 08 18:29:44 volumiorpi5 volumio[1069]: info: CorePlayQueue::getTrack 0 Mar 08 18:29:44 volumiorpi5 volumio[1069]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 08 18:29:44 volumiorpi5 volumio[1069]: TypeError: Cannot read property 'name' of undefined Mar 08 18:29:44 volumiorpi5 volumio[1069]: at CoreStateMachine.getState (/volumio/app/statemachine.js:126:48) Mar 08 18:29:44 volumiorpi5 volumio[1069]: at CoreCommandRouter.volumioGetState (/volumio/app/index.js:109:28) Mar 08 18:29:44 volumiorpi5 volumio[1069]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:58:38) Mar 08 18:29:44 volumiorpi5 volumio[1069]: at Socket.emit (events.js:315:20) Mar 08 18:29:44 volumiorpi5 volumio[1069]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Mar 08 18:29:44 volumiorpi5 volumio[1069]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Mar 08 18:29:44 volumiorpi5 volumio[1069]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 08 18:29:44 volumiorpi5 sudo[14380]: volumio : unable to resolve host volumiorpi5 Mar 08 18:29:44 volumiorpi5 sudo[14380]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 08 18:29:44 volumiorpi5 sudo[14380]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-08 18:28 Mar 08 18:29:44 volumiorpi5 sudo[14380]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 11:42:54 AM CET" VOLUMIO_VERSION="3.795" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4049a67f1c09d4a94e033ba35a80a144"