-- Logs begin at Thu 2019-02-14 10:12:00 UTC, end at Fri 2024-11-22 16:37:39 UTC. -- Nov 22 16:36:01 martin-logan-streamer kernel: CIFS: VFS: \\192.168.1.15 has not responded in 180 seconds. Reconnecting... Nov 22 16:36:43 martin-logan-streamer volumiologrotate[611]: ls: cannot access '/var/log/samba/log.wb-MARTIN': No such file or directory Nov 22 16:36:43 martin-logan-streamer volumiologrotate[611]: ls: cannot access 'LOGAN': No such file or directory Nov 22 16:36:43 martin-logan-streamer volumiologrotate[611]: ls: cannot access 'STREAMER': No such file or directory Nov 22 16:36:58 martin-logan-streamer volumio[992]: verbose: New Socket.io Connection to 192.168.1.52 from 192.168.1.51 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 9 Nov 22 16:36:58 martin-logan-streamer volumio[992]: info: Received Get System Info Nov 22 16:36:58 martin-logan-streamer volumio[992]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 22 16:36:58 martin-logan-streamer volumio[992]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 22 16:36:58 martin-logan-streamer volumio[992]: info: Discovery: Getting this device information Nov 22 16:36:58 martin-logan-streamer volumio[992]: info: CoreCommandRouter::volumioGetState Nov 22 16:36:58 martin-logan-streamer volumio[992]: info: CorePlayQueue::getTrack 0 Nov 22 16:36:58 martin-logan-streamer volumio[992]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 22 16:36:58 martin-logan-streamer volumio[992]: info: CoreCommandRouter::volumioGetState Nov 22 16:36:58 martin-logan-streamer volumio[992]: info: CorePlayQueue::getTrack 0 Nov 22 16:36:58 martin-logan-streamer volumio[992]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 22 16:36:58 martin-logan-streamer volumio[992]: info: CoreCommandRouter::volumioGetVisibleSources Nov 22 16:36:58 martin-logan-streamer volumio[992]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 22 16:36:58 martin-logan-streamer volumio[992]: info: CoreCommandRouter::volumioGetState Nov 22 16:36:58 martin-logan-streamer volumio[992]: info: CorePlayQueue::getTrack 0 Nov 22 16:36:58 martin-logan-streamer volumio[992]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 22 16:36:58 martin-logan-streamer volumio[992]: info: CoreCommandRouter::volumioGetQueue Nov 22 16:36:58 martin-logan-streamer volumio[992]: info: CoreStateMachine::getQueue Nov 22 16:36:58 martin-logan-streamer volumio[992]: info: CorePlayQueue::getQueue Nov 22 16:36:58 martin-logan-streamer volumio[992]: info: Listing playlists Nov 22 16:36:58 martin-logan-streamer volumio[992]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 22 16:36:58 martin-logan-streamer volumio[992]: info: Received Get System Info Nov 22 16:36:58 martin-logan-streamer volumio[992]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 22 16:36:58 martin-logan-streamer volumio[992]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 22 16:36:58 martin-logan-streamer volumio[992]: info: Discovery: Getting this device information Nov 22 16:36:58 martin-logan-streamer volumio[992]: info: CoreCommandRouter::volumioGetState Nov 22 16:36:58 martin-logan-streamer volumio[992]: info: CorePlayQueue::getTrack 0 Nov 22 16:36:58 martin-logan-streamer volumio[992]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 22 16:36:58 martin-logan-streamer volumio[992]: info: CoreCommandRouter::volumioGetState Nov 22 16:36:58 martin-logan-streamer volumio[992]: info: CorePlayQueue::getTrack 0 Nov 22 16:36:58 martin-logan-streamer volumio[992]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: Preload queue cleared Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: CoreStateMachine::ClearQueue Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: CoreStateMachine::stop Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: CoreStateMachine::stPlaybackTimer Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: CoreStateMachine::updateTrackBlock Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: CorePlayQueue::getTrackBlock Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: CoreStateMachine::pushState Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: CorePlayQueue::getTrack 0 Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: CoreCommandRouter::volumioPushState Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: CoreStateMachine::serviceStop Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: CorePlayQueue::getTrack 0 Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: CoreCommandRouter::serviceStop Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: ControllerMpd::stop Nov 22 16:36:59 martin-logan-streamer volumio[992]: verbose: ControllerMpd::sendMpdCommand stop Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: CorePlayQueue::clearPlayQueue Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: CorePlayQueue::saveQueue Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: CoreCommandRouter::volumioPushQueue Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: CoreStateMachine::addQueueItems Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: CorePlayQueue::addQueueItems Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: Preload queue cleared Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: Adding Item to queue: webrp/1 Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: Exploding uri webrp/1 in service radio_paradise Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: CoreCommandRouter::volumioPushQueue Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: CorePlayQueue::saveQueue Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: CoreStateMachine::updateTrackBlock Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: CorePlayQueue::getTrackBlock Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: CoreCommandRouter::volumioPlay Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: CoreStateMachine::play index 0 Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: CoreStateMachine::stop Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: CoreStateMachine::play index undefined Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: CorePlayQueue::getTrack 0 Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: CoreStateMachine::startPlaybackTimer Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: CorePlayQueue::getTrack 0 Nov 22 16:36:59 martin-logan-streamer volumio[992]: verbose: ControllerMpd::sendMpdCommand stop Nov 22 16:36:59 martin-logan-streamer volumio[992]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: Nov 22 16:36:59 martin-logan-streamer volumio[992]: ---------------------------- MPD announces state update: player Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: sendMpdCommand stop took 39 milliseconds Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: ControllerMpd::getState Nov 22 16:36:59 martin-logan-streamer volumio[992]: verbose: ControllerMpd::sendMpdCommand status Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: sendMpdCommand stop took 15 milliseconds Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: CoreCommandRouter::servicePushState Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: CoreStateMachine::pushState Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: CorePlayQueue::getTrack 0 Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: CoreCommandRouter::volumioPushState Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: CorePlayQueue::getTrack 0 Nov 22 16:36:59 martin-logan-streamer volumio[992]: verbose: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B0000024BT.jpg","uri":"https://stream.radioparadise.com/flac","name":"To the Shores of Your Heart","title":"To the Shores of Your Heart","artist":"Andrew White","album":"The Heart of the Celtic Guitar","streaming":true,"disableUiControls":true,"duration":205,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Nov 22 16:36:59 martin-logan-streamer volumio[992]: verbose: CURRENT POSITION 0 Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: CoreStateMachine::syncState stateService stop Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: CoreStateMachine::syncState currentStatus stop Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: CoreStateMachine::pushState Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: CorePlayQueue::getTrack 0 Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: CoreCommandRouter::volumioPushState Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: No code Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: CoreStateMachine::pushState Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: CorePlayQueue::getTrack 0 Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: CoreCommandRouter::volumioPushState Nov 22 16:36:59 martin-logan-streamer volumio[992]: verbose: ControllerMpd::sendMpdCommand clear Nov 22 16:36:59 martin-logan-streamer volumio[992]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 22 16:36:59 martin-logan-streamer volumio[992]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 22 16:36:59 martin-logan-streamer volumio[992]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: Nov 22 16:36:59 martin-logan-streamer volumio[992]: ---------------------------- MPD announces system playlist update Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: Ignoring MPD Status Update Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: sendMpdCommand status took 62 milliseconds Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: sendMpdCommand clear took 36 milliseconds Nov 22 16:36:59 martin-logan-streamer volumio[992]: verbose: ControllerMpd::parseState Nov 22 16:36:59 martin-logan-streamer volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 16:36:59 martin-logan-streamer volumio[992]: verbose: ControllerMpd::sendMpdCommand consume 1 Nov 22 16:36:59 martin-logan-streamer volumio[992]: error: updateQueue error: null Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: ------------------------------ 32ms Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: sendMpdCommand playlistinfo took 30 milliseconds Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: sendMpdCommand consume 1 took 29 milliseconds Nov 22 16:36:59 martin-logan-streamer volumio[992]: verbose: ControllerMpd::parseTrackInfo Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: [1732293419551] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/mellow-flac Nov 22 16:36:59 martin-logan-streamer volumio[992]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/mellow-flac" Nov 22 16:36:59 martin-logan-streamer volumio[992]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: ------------------------------ 101ms Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: Nov 22 16:36:59 martin-logan-streamer volumio[992]: ---------------------------- MPD announces system playlist update Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: Ignoring MPD Status Update Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: sendMpdCommand add "https://stream.radioparadise.com/mellow-flac" took 3 milliseconds Nov 22 16:36:59 martin-logan-streamer volumio[992]: verbose: ControllerMpd::sendMpdCommand play Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: ------------------------------ 11ms Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: touch_display: Setting screensaver timeout to 0 seconds. Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: sendMpdCommand play took 16 milliseconds Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: [1732293419580] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=1 Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: CoreCommandRouter::volumioGetState Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: CorePlayQueue::getTrack 0 Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Nov 22 16:36:59 martin-logan-streamer volumio[992]: info: touch_display: Setting screensaver timeout to 0 seconds. Nov 22 16:37:00 martin-logan-streamer volumio[992]: info: [1732293420171] [RadioParadise] received new metadata: {"time":179,"artist":"Patty Griffin","title":"Luminous Places","album":"Patty Griffin","year":"2019","cover":"https://img.radioparadise.com/covers/l/B07LDNGMGV.jpg","cover_med":"https://img.radioparadise.com/covers/m/B07LDNGMGV.jpg","cover_small":"https://img.radioparadise.com/covers/s/B07LDNGMGV.jpg"} Nov 22 16:37:00 martin-logan-streamer volumio[992]: info: CorePlayQueue::getTrack 0 Nov 22 16:37:00 martin-logan-streamer volumio[992]: info: CoreCommandRouter::servicePushState Nov 22 16:37:00 martin-logan-streamer volumio[992]: info: CorePlayQueue::getTrack 0 Nov 22 16:37:00 martin-logan-streamer volumio[992]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B07LDNGMGV.jpg","uri":"https://stream.radioparadise.com/mellow-flac","name":"Luminous Places","title":"Luminous Places","artist":"Patty Griffin","album":"Patty Griffin","streaming":true,"disableUiControls":true,"duration":184,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Nov 22 16:37:00 martin-logan-streamer volumio[992]: verbose: CURRENT POSITION 0 Nov 22 16:37:00 martin-logan-streamer volumio[992]: info: CoreStateMachine::syncState stateService play Nov 22 16:37:00 martin-logan-streamer volumio[992]: info: CoreStateMachine::syncState currentStatus stop Nov 22 16:37:00 martin-logan-streamer volumio[992]: info: [1732293420174] [RadioParadise] setting new timer with duration of 184000 seconds. Nov 22 16:37:00 martin-logan-streamer volumio[992]: info: CorePlayQueue::getTrack 0 Nov 22 16:37:00 martin-logan-streamer volumio[992]: info: CorePlayQueue::getTrack 1 Nov 22 16:37:00 martin-logan-streamer volumio[992]: info: Nov 22 16:37:00 martin-logan-streamer volumio[992]: ---------------------------- MPD announces state update: player Nov 22 16:37:00 martin-logan-streamer volumio[992]: info: ControllerMpd::getState Nov 22 16:37:00 martin-logan-streamer volumio[992]: verbose: ControllerMpd::sendMpdCommand status Nov 22 16:37:00 martin-logan-streamer volumio[992]: info: sendMpdCommand status took 3 milliseconds Nov 22 16:37:00 martin-logan-streamer volumio[992]: verbose: ControllerMpd::parseState Nov 22 16:37:00 martin-logan-streamer volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 16:37:00 martin-logan-streamer volumio[992]: info: Nov 22 16:37:00 martin-logan-streamer volumio[992]: ---------------------------- MPD announces state update: player Nov 22 16:37:00 martin-logan-streamer volumio[992]: info: ControllerMpd::getState Nov 22 16:37:00 martin-logan-streamer volumio[992]: verbose: ControllerMpd::sendMpdCommand status Nov 22 16:37:00 martin-logan-streamer volumio[992]: info: sendMpdCommand playlistinfo took 8 milliseconds Nov 22 16:37:00 martin-logan-streamer volumio[992]: verbose: ControllerMpd::parseTrackInfo Nov 22 16:37:00 martin-logan-streamer volumio[992]: info: ControllerMpd::pushState Nov 22 16:37:00 martin-logan-streamer volumio[992]: info: CoreCommandRouter::servicePushState Nov 22 16:37:00 martin-logan-streamer volumio[992]: info: CorePlayQueue::getTrack 0 Nov 22 16:37:00 martin-logan-streamer volumio[992]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Nov 22 16:37:00 martin-logan-streamer volumio[992]: info: ------------------------------ 19ms Nov 22 16:37:00 martin-logan-streamer volumio[992]: info: sendMpdCommand status took 6 milliseconds Nov 22 16:37:00 martin-logan-streamer volumio[992]: verbose: ControllerMpd::parseState Nov 22 16:37:00 martin-logan-streamer volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 16:37:00 martin-logan-streamer volumio[992]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 22 16:37:00 martin-logan-streamer volumio[992]: verbose: ControllerMpd::parseTrackInfo Nov 22 16:37:00 martin-logan-streamer volumio[992]: info: ControllerMpd::pushState Nov 22 16:37:00 martin-logan-streamer volumio[992]: info: CoreCommandRouter::servicePushState Nov 22 16:37:00 martin-logan-streamer volumio[992]: info: CorePlayQueue::getTrack 0 Nov 22 16:37:00 martin-logan-streamer volumio[992]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Nov 22 16:37:00 martin-logan-streamer volumio[992]: info: ------------------------------ 14ms Nov 22 16:37:00 martin-logan-streamer volumio[992]: info: Nov 22 16:37:00 martin-logan-streamer volumio[992]: ---------------------------- MPD announces system playlist update Nov 22 16:37:00 martin-logan-streamer volumio[992]: info: Ignoring MPD Status Update Nov 22 16:37:00 martin-logan-streamer volumio[992]: info: Nov 22 16:37:00 martin-logan-streamer volumio[992]: ---------------------------- MPD announces state update: player Nov 22 16:37:00 martin-logan-streamer volumio[992]: info: ControllerMpd::getState Nov 22 16:37:00 martin-logan-streamer volumio[992]: verbose: ControllerMpd::sendMpdCommand status Nov 22 16:37:00 martin-logan-streamer volumio[992]: info: ------------------------------ 5ms Nov 22 16:37:00 martin-logan-streamer volumio[992]: info: sendMpdCommand status took 4 milliseconds Nov 22 16:37:00 martin-logan-streamer volumio[992]: verbose: ControllerMpd::parseState Nov 22 16:37:00 martin-logan-streamer volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 16:37:00 martin-logan-streamer volumio[992]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 22 16:37:00 martin-logan-streamer volumio[992]: verbose: ControllerMpd::parseTrackInfo Nov 22 16:37:00 martin-logan-streamer volumio[992]: info: ControllerMpd::pushState Nov 22 16:37:00 martin-logan-streamer volumio[992]: info: CoreCommandRouter::servicePushState Nov 22 16:37:00 martin-logan-streamer volumio[992]: info: CorePlayQueue::getTrack 0 Nov 22 16:37:00 martin-logan-streamer volumio[992]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Nov 22 16:37:00 martin-logan-streamer volumio[992]: info: ------------------------------ 11ms Nov 22 16:37:04 martin-logan-streamer volumio[992]: info: Preload queue cleared Nov 22 16:37:04 martin-logan-streamer volumio[992]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 22 16:37:04 martin-logan-streamer volumio[992]: info: CoreStateMachine::ClearQueue Nov 22 16:37:04 martin-logan-streamer volumio[992]: info: CoreStateMachine::stop Nov 22 16:37:04 martin-logan-streamer volumio[992]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 22 16:37:04 martin-logan-streamer volumio[992]: info: CoreStateMachine::stPlaybackTimer Nov 22 16:37:04 martin-logan-streamer volumio[992]: info: CoreStateMachine::updateTrackBlock Nov 22 16:37:04 martin-logan-streamer volumio[992]: info: CorePlayQueue::getTrackBlock Nov 22 16:37:04 martin-logan-streamer volumio[992]: info: CoreStateMachine::pushState Nov 22 16:37:04 martin-logan-streamer volumio[992]: info: CorePlayQueue::getTrack 0 Nov 22 16:37:04 martin-logan-streamer volumio[992]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 22 16:37:04 martin-logan-streamer volumio[992]: info: CoreCommandRouter::volumioPushState Nov 22 16:37:04 martin-logan-streamer volumio[992]: info: CoreStateMachine::serviceStop Nov 22 16:37:04 martin-logan-streamer volumio[992]: info: CorePlayQueue::getTrack 0 Nov 22 16:37:04 martin-logan-streamer volumio[992]: info: CoreCommandRouter::serviceStop Nov 22 16:37:04 martin-logan-streamer volumio[992]: info: ControllerMpd::stop Nov 22 16:37:04 martin-logan-streamer volumio[992]: verbose: ControllerMpd::sendMpdCommand stop Nov 22 16:37:04 martin-logan-streamer volumio[992]: info: CorePlayQueue::clearPlayQueue Nov 22 16:37:04 martin-logan-streamer volumio[992]: info: CorePlayQueue::saveQueue Nov 22 16:37:04 martin-logan-streamer volumio[992]: info: CoreCommandRouter::volumioPushQueue Nov 22 16:37:04 martin-logan-streamer volumio[992]: info: CoreStateMachine::addQueueItems Nov 22 16:37:04 martin-logan-streamer volumio[992]: info: CorePlayQueue::addQueueItems Nov 22 16:37:04 martin-logan-streamer volumio[992]: info: Preload queue cleared Nov 22 16:37:04 martin-logan-streamer volumio[992]: info: Adding Item to queue: webrp/2 Nov 22 16:37:04 martin-logan-streamer volumio[992]: info: Exploding uri webrp/2 in service radio_paradise Nov 22 16:37:04 martin-logan-streamer volumio[992]: info: CoreCommandRouter::volumioPushQueue Nov 22 16:37:04 martin-logan-streamer volumio[992]: info: CorePlayQueue::saveQueue Nov 22 16:37:04 martin-logan-streamer volumio[992]: info: CoreStateMachine::updateTrackBlock Nov 22 16:37:04 martin-logan-streamer volumio[992]: info: CorePlayQueue::getTrackBlock Nov 22 16:37:04 martin-logan-streamer volumio[992]: info: CoreCommandRouter::volumioPlay Nov 22 16:37:04 martin-logan-streamer volumio[992]: info: CoreStateMachine::play index 0 Nov 22 16:37:04 martin-logan-streamer volumio[992]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 22 16:37:04 martin-logan-streamer volumio[992]: info: CoreStateMachine::stop Nov 22 16:37:04 martin-logan-streamer volumio[992]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 22 16:37:04 martin-logan-streamer volumio[992]: info: CoreStateMachine::play index undefined Nov 22 16:37:04 martin-logan-streamer volumio[992]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 22 16:37:04 martin-logan-streamer volumio[992]: info: CorePlayQueue::getTrack 0 Nov 22 16:37:04 martin-logan-streamer volumio[992]: info: CoreStateMachine::startPlaybackTimer Nov 22 16:37:04 martin-logan-streamer volumio[992]: info: CorePlayQueue::getTrack 0 Nov 22 16:37:04 martin-logan-streamer volumio[992]: verbose: ControllerMpd::sendMpdCommand stop Nov 22 16:37:04 martin-logan-streamer volumio[992]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 22 16:37:04 martin-logan-streamer volumio[992]: info: Nov 22 16:37:04 martin-logan-streamer volumio[992]: ---------------------------- MPD announces state update: player Nov 22 16:37:04 martin-logan-streamer volumio[992]: info: sendMpdCommand stop took 53 milliseconds Nov 22 16:37:04 martin-logan-streamer volumio[992]: info: ControllerMpd::getState Nov 22 16:37:04 martin-logan-streamer volumio[992]: verbose: ControllerMpd::sendMpdCommand status Nov 22 16:37:04 martin-logan-streamer volumio[992]: info: sendMpdCommand stop took 14 milliseconds Nov 22 16:37:04 martin-logan-streamer volumio[992]: info: CoreCommandRouter::servicePushState Nov 22 16:37:04 martin-logan-streamer volumio[992]: info: CoreStateMachine::pushState Nov 22 16:37:04 martin-logan-streamer volumio[992]: info: CorePlayQueue::getTrack 0 Nov 22 16:37:04 martin-logan-streamer volumio[992]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 22 16:37:04 martin-logan-streamer volumio[992]: info: CoreCommandRouter::volumioPushState Nov 22 16:37:04 martin-logan-streamer volumio[992]: info: CorePlayQueue::getTrack 0 Nov 22 16:37:04 martin-logan-streamer volumio[992]: verbose: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B07LDNGMGV.jpg","uri":"https://stream.radioparadise.com/mellow-flac","name":"Luminous Places","title":"Luminous Places","artist":"Patty Griffin","album":"Patty Griffin","streaming":true,"disableUiControls":true,"duration":184,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Nov 22 16:37:04 martin-logan-streamer volumio[992]: verbose: CURRENT POSITION 0 Nov 22 16:37:04 martin-logan-streamer volumio[992]: info: CoreStateMachine::syncState stateService stop Nov 22 16:37:04 martin-logan-streamer volumio[992]: info: CoreStateMachine::syncState currentStatus stop Nov 22 16:37:04 martin-logan-streamer volumio[992]: info: CoreStateMachine::pushState Nov 22 16:37:04 martin-logan-streamer volumio[992]: info: CorePlayQueue::getTrack 0 Nov 22 16:37:04 martin-logan-streamer volumio[992]: info: CoreCommandRouter::volumioPushState Nov 22 16:37:04 martin-logan-streamer volumio[992]: info: No code Nov 22 16:37:04 martin-logan-streamer volumio[992]: info: CoreStateMachine::pushState Nov 22 16:37:04 martin-logan-streamer volumio[992]: info: CorePlayQueue::getTrack 0 Nov 22 16:37:04 martin-logan-streamer volumio[992]: info: CoreCommandRouter::volumioPushState Nov 22 16:37:04 martin-logan-streamer volumio[992]: verbose: ControllerMpd::sendMpdCommand clear Nov 22 16:37:04 martin-logan-streamer volumio[992]: info: Nov 22 16:37:04 martin-logan-streamer volumio[992]: ---------------------------- MPD announces system playlist update Nov 22 16:37:04 martin-logan-streamer volumio[992]: info: Ignoring MPD Status Update Nov 22 16:37:04 martin-logan-streamer volumio[992]: info: sendMpdCommand status took 49 milliseconds Nov 22 16:37:04 martin-logan-streamer volumio[992]: info: sendMpdCommand clear took 30 milliseconds Nov 22 16:37:04 martin-logan-streamer volumio[992]: verbose: ControllerMpd::parseState Nov 22 16:37:04 martin-logan-streamer volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 16:37:04 martin-logan-streamer volumio[992]: verbose: ControllerMpd::sendMpdCommand consume 1 Nov 22 16:37:04 martin-logan-streamer volumio[992]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 22 16:37:04 martin-logan-streamer volumio[992]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 22 16:37:04 martin-logan-streamer volumio[992]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 22 16:37:04 martin-logan-streamer volumio[992]: error: updateQueue error: null Nov 22 16:37:04 martin-logan-streamer volumio[992]: info: ------------------------------ 26ms Nov 22 16:37:04 martin-logan-streamer volumio[992]: info: sendMpdCommand playlistinfo took 23 milliseconds Nov 22 16:37:04 martin-logan-streamer volumio[992]: info: sendMpdCommand consume 1 took 28 milliseconds Nov 22 16:37:04 martin-logan-streamer volumio[992]: verbose: ControllerMpd::parseTrackInfo Nov 22 16:37:04 martin-logan-streamer volumio[992]: info: [1732293424864] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/rock-flac Nov 22 16:37:04 martin-logan-streamer volumio[992]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/rock-flac" Nov 22 16:37:04 martin-logan-streamer volumio[992]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Nov 22 16:37:04 martin-logan-streamer volumio[992]: info: ------------------------------ 88ms Nov 22 16:37:04 martin-logan-streamer volumio[992]: info: Nov 22 16:37:04 martin-logan-streamer volumio[992]: ---------------------------- MPD announces system playlist update Nov 22 16:37:04 martin-logan-streamer volumio[992]: info: Ignoring MPD Status Update Nov 22 16:37:04 martin-logan-streamer volumio[992]: info: sendMpdCommand add "https://stream.radioparadise.com/rock-flac" took 5 milliseconds Nov 22 16:37:04 martin-logan-streamer volumio[992]: verbose: ControllerMpd::sendMpdCommand play Nov 22 16:37:04 martin-logan-streamer volumio[992]: info: ------------------------------ 11ms Nov 22 16:37:04 martin-logan-streamer volumio[992]: info: sendMpdCommand play took 10 milliseconds Nov 22 16:37:04 martin-logan-streamer volumio[992]: info: [1732293424888] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=2 Nov 22 16:37:05 martin-logan-streamer volumio[992]: info: [1732293425469] [RadioParadise] received new metadata: {"time":141,"artist":"Metric","title":"What Feels Like Eternity","album":"Formentera","year":"2022","cover":"https://img.radioparadise.com/covers/l/18527_69f1d253-ce56-4346-8bfa-90b9b5cb8e1f.jpg","cover_med":"https://img.radioparadise.com/covers/m/18527_69f1d253-ce56-4346-8bfa-90b9b5cb8e1f.jpg","cover_small":"https://img.radioparadise.com/covers/s/18527_69f1d253-ce56-4346-8bfa-90b9b5cb8e1f.jpg"} Nov 22 16:37:05 martin-logan-streamer volumio[992]: info: CorePlayQueue::getTrack 0 Nov 22 16:37:05 martin-logan-streamer volumio[992]: info: CoreCommandRouter::servicePushState Nov 22 16:37:05 martin-logan-streamer volumio[992]: info: CorePlayQueue::getTrack 0 Nov 22 16:37:05 martin-logan-streamer volumio[992]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/18527_69f1d253-ce56-4346-8bfa-90b9b5cb8e1f.jpg","uri":"https://stream.radioparadise.com/rock-flac","name":"What Feels Like Eternity","title":"What Feels Like Eternity","artist":"Metric","album":"Formentera","streaming":true,"disableUiControls":true,"duration":146,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Nov 22 16:37:05 martin-logan-streamer volumio[992]: verbose: CURRENT POSITION 0 Nov 22 16:37:05 martin-logan-streamer volumio[992]: info: CoreStateMachine::syncState stateService play Nov 22 16:37:05 martin-logan-streamer volumio[992]: info: CoreStateMachine::syncState currentStatus stop Nov 22 16:37:05 martin-logan-streamer volumio[992]: info: [1732293425477] [RadioParadise] setting new timer with duration of 146000 seconds. Nov 22 16:37:05 martin-logan-streamer volumio[992]: info: CorePlayQueue::getTrack 0 Nov 22 16:37:05 martin-logan-streamer volumio[992]: info: CorePlayQueue::getTrack 1 Nov 22 16:37:05 martin-logan-streamer volumio[992]: info: Nov 22 16:37:05 martin-logan-streamer volumio[992]: ---------------------------- MPD announces state update: player Nov 22 16:37:05 martin-logan-streamer volumio[992]: info: ControllerMpd::getState Nov 22 16:37:05 martin-logan-streamer volumio[992]: verbose: ControllerMpd::sendMpdCommand status Nov 22 16:37:05 martin-logan-streamer volumio[992]: info: Nov 22 16:37:05 martin-logan-streamer volumio[992]: ---------------------------- MPD announces state update: player Nov 22 16:37:05 martin-logan-streamer volumio[992]: info: ControllerMpd::getState Nov 22 16:37:05 martin-logan-streamer volumio[992]: verbose: ControllerMpd::sendMpdCommand status Nov 22 16:37:05 martin-logan-streamer volumio[992]: info: sendMpdCommand status took 7 milliseconds Nov 22 16:37:05 martin-logan-streamer volumio[992]: verbose: ControllerMpd::parseState Nov 22 16:37:05 martin-logan-streamer volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 16:37:05 martin-logan-streamer volumio[992]: info: sendMpdCommand status took 10 milliseconds Nov 22 16:37:05 martin-logan-streamer volumio[992]: info: sendMpdCommand playlistinfo took 5 milliseconds Nov 22 16:37:05 martin-logan-streamer volumio[992]: verbose: ControllerMpd::parseState Nov 22 16:37:05 martin-logan-streamer volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 16:37:05 martin-logan-streamer volumio[992]: verbose: ControllerMpd::parseTrackInfo Nov 22 16:37:05 martin-logan-streamer volumio[992]: info: ControllerMpd::pushState Nov 22 16:37:05 martin-logan-streamer volumio[992]: info: CoreCommandRouter::servicePushState Nov 22 16:37:05 martin-logan-streamer volumio[992]: info: CorePlayQueue::getTrack 0 Nov 22 16:37:05 martin-logan-streamer volumio[992]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Nov 22 16:37:05 martin-logan-streamer volumio[992]: info: ------------------------------ 23ms Nov 22 16:37:05 martin-logan-streamer volumio[992]: info: sendMpdCommand playlistinfo took 5 milliseconds Nov 22 16:37:05 martin-logan-streamer volumio[992]: verbose: ControllerMpd::parseTrackInfo Nov 22 16:37:05 martin-logan-streamer volumio[992]: info: ControllerMpd::pushState Nov 22 16:37:05 martin-logan-streamer volumio[992]: info: CoreCommandRouter::servicePushState Nov 22 16:37:05 martin-logan-streamer volumio[992]: info: CorePlayQueue::getTrack 0 Nov 22 16:37:05 martin-logan-streamer volumio[992]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Nov 22 16:37:05 martin-logan-streamer volumio[992]: info: ------------------------------ 22ms Nov 22 16:37:05 martin-logan-streamer volumio[992]: info: Nov 22 16:37:05 martin-logan-streamer volumio[992]: ---------------------------- MPD announces system playlist update Nov 22 16:37:05 martin-logan-streamer volumio[992]: info: Ignoring MPD Status Update Nov 22 16:37:05 martin-logan-streamer volumio[992]: info: Nov 22 16:37:05 martin-logan-streamer volumio[992]: ---------------------------- MPD announces state update: player Nov 22 16:37:05 martin-logan-streamer volumio[992]: info: ControllerMpd::getState Nov 22 16:37:05 martin-logan-streamer volumio[992]: verbose: ControllerMpd::sendMpdCommand status Nov 22 16:37:05 martin-logan-streamer volumio[992]: info: ------------------------------ 3ms Nov 22 16:37:05 martin-logan-streamer volumio[992]: info: sendMpdCommand status took 2 milliseconds Nov 22 16:37:05 martin-logan-streamer volumio[992]: verbose: ControllerMpd::parseState Nov 22 16:37:05 martin-logan-streamer volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 16:37:05 martin-logan-streamer volumio[992]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 22 16:37:05 martin-logan-streamer volumio[992]: verbose: ControllerMpd::parseTrackInfo Nov 22 16:37:05 martin-logan-streamer volumio[992]: info: ControllerMpd::pushState Nov 22 16:37:05 martin-logan-streamer volumio[992]: info: CoreCommandRouter::servicePushState Nov 22 16:37:05 martin-logan-streamer volumio[992]: info: CorePlayQueue::getTrack 0 Nov 22 16:37:05 martin-logan-streamer volumio[992]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Nov 22 16:37:05 martin-logan-streamer volumio[992]: info: ------------------------------ 7ms Nov 22 16:37:08 martin-logan-streamer volumio[992]: info: Preload queue cleared Nov 22 16:37:08 martin-logan-streamer volumio[992]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 22 16:37:08 martin-logan-streamer volumio[992]: info: CoreStateMachine::ClearQueue Nov 22 16:37:08 martin-logan-streamer volumio[992]: info: CoreStateMachine::stop Nov 22 16:37:08 martin-logan-streamer volumio[992]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 22 16:37:08 martin-logan-streamer volumio[992]: info: CoreStateMachine::stPlaybackTimer Nov 22 16:37:08 martin-logan-streamer volumio[992]: info: CoreStateMachine::updateTrackBlock Nov 22 16:37:08 martin-logan-streamer volumio[992]: info: CorePlayQueue::getTrackBlock Nov 22 16:37:08 martin-logan-streamer volumio[992]: info: CoreStateMachine::pushState Nov 22 16:37:08 martin-logan-streamer volumio[992]: info: CorePlayQueue::getTrack 0 Nov 22 16:37:08 martin-logan-streamer volumio[992]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 22 16:37:08 martin-logan-streamer volumio[992]: info: CoreCommandRouter::volumioPushState Nov 22 16:37:08 martin-logan-streamer volumio[992]: info: CoreStateMachine::serviceStop Nov 22 16:37:08 martin-logan-streamer volumio[992]: info: CorePlayQueue::getTrack 0 Nov 22 16:37:08 martin-logan-streamer volumio[992]: info: CoreCommandRouter::serviceStop Nov 22 16:37:08 martin-logan-streamer volumio[992]: info: ControllerMpd::stop Nov 22 16:37:08 martin-logan-streamer volumio[992]: verbose: ControllerMpd::sendMpdCommand stop Nov 22 16:37:08 martin-logan-streamer volumio[992]: info: CorePlayQueue::clearPlayQueue Nov 22 16:37:08 martin-logan-streamer volumio[992]: info: CorePlayQueue::saveQueue Nov 22 16:37:08 martin-logan-streamer volumio[992]: info: CoreCommandRouter::volumioPushQueue Nov 22 16:37:08 martin-logan-streamer volumio[992]: info: CoreStateMachine::addQueueItems Nov 22 16:37:08 martin-logan-streamer volumio[992]: info: CorePlayQueue::addQueueItems Nov 22 16:37:08 martin-logan-streamer volumio[992]: info: Preload queue cleared Nov 22 16:37:08 martin-logan-streamer volumio[992]: info: Adding Item to queue: webrp/3 Nov 22 16:37:08 martin-logan-streamer volumio[992]: info: Exploding uri webrp/3 in service radio_paradise Nov 22 16:37:08 martin-logan-streamer volumio[992]: info: CoreCommandRouter::volumioPushQueue Nov 22 16:37:08 martin-logan-streamer volumio[992]: info: CorePlayQueue::saveQueue Nov 22 16:37:08 martin-logan-streamer volumio[992]: info: CoreStateMachine::updateTrackBlock Nov 22 16:37:08 martin-logan-streamer volumio[992]: info: CorePlayQueue::getTrackBlock Nov 22 16:37:08 martin-logan-streamer volumio[992]: info: CoreCommandRouter::volumioPlay Nov 22 16:37:08 martin-logan-streamer volumio[992]: info: CoreStateMachine::play index 0 Nov 22 16:37:08 martin-logan-streamer volumio[992]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 22 16:37:08 martin-logan-streamer volumio[992]: info: CoreStateMachine::stop Nov 22 16:37:08 martin-logan-streamer volumio[992]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 22 16:37:08 martin-logan-streamer volumio[992]: info: CoreStateMachine::play index undefined Nov 22 16:37:08 martin-logan-streamer volumio[992]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 22 16:37:08 martin-logan-streamer volumio[992]: info: CorePlayQueue::getTrack 0 Nov 22 16:37:08 martin-logan-streamer volumio[992]: info: CoreStateMachine::startPlaybackTimer Nov 22 16:37:08 martin-logan-streamer volumio[992]: info: CorePlayQueue::getTrack 0 Nov 22 16:37:08 martin-logan-streamer volumio[992]: verbose: ControllerMpd::sendMpdCommand stop Nov 22 16:37:08 martin-logan-streamer volumio[992]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 22 16:37:08 martin-logan-streamer volumio[992]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 22 16:37:08 martin-logan-streamer volumio[992]: info: Nov 22 16:37:08 martin-logan-streamer volumio[992]: ---------------------------- MPD announces state update: player Nov 22 16:37:08 martin-logan-streamer volumio[992]: info: sendMpdCommand stop took 68 milliseconds Nov 22 16:37:08 martin-logan-streamer volumio[992]: info: ControllerMpd::getState Nov 22 16:37:08 martin-logan-streamer volumio[992]: verbose: ControllerMpd::sendMpdCommand status Nov 22 16:37:08 martin-logan-streamer volumio[992]: info: sendMpdCommand stop took 28 milliseconds Nov 22 16:37:08 martin-logan-streamer volumio[992]: info: CoreCommandRouter::servicePushState Nov 22 16:37:08 martin-logan-streamer volumio[992]: info: CoreStateMachine::pushState Nov 22 16:37:08 martin-logan-streamer volumio[992]: info: CorePlayQueue::getTrack 0 Nov 22 16:37:08 martin-logan-streamer volumio[992]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 22 16:37:08 martin-logan-streamer volumio[992]: info: CoreCommandRouter::volumioPushState Nov 22 16:37:08 martin-logan-streamer volumio[992]: info: CorePlayQueue::getTrack 0 Nov 22 16:37:08 martin-logan-streamer volumio[992]: verbose: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/18527_69f1d253-ce56-4346-8bfa-90b9b5cb8e1f.jpg","uri":"https://stream.radioparadise.com/rock-flac","name":"What Feels Like Eternity","title":"What Feels Like Eternity","artist":"Metric","album":"Formentera","streaming":true,"disableUiControls":true,"duration":146,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Nov 22 16:37:08 martin-logan-streamer volumio[992]: verbose: CURRENT POSITION 0 Nov 22 16:37:08 martin-logan-streamer volumio[992]: info: CoreStateMachine::syncState stateService stop Nov 22 16:37:08 martin-logan-streamer volumio[992]: info: CoreStateMachine::syncState currentStatus stop Nov 22 16:37:08 martin-logan-streamer volumio[992]: info: CoreStateMachine::pushState Nov 22 16:37:08 martin-logan-streamer volumio[992]: info: CorePlayQueue::getTrack 0 Nov 22 16:37:08 martin-logan-streamer volumio[992]: info: CoreCommandRouter::volumioPushState Nov 22 16:37:08 martin-logan-streamer volumio[992]: info: No code Nov 22 16:37:08 martin-logan-streamer volumio[992]: info: CoreStateMachine::pushState Nov 22 16:37:08 martin-logan-streamer volumio[992]: info: CorePlayQueue::getTrack 0 Nov 22 16:37:08 martin-logan-streamer volumio[992]: info: CoreCommandRouter::volumioPushState Nov 22 16:37:08 martin-logan-streamer volumio[992]: verbose: ControllerMpd::sendMpdCommand clear Nov 22 16:37:08 martin-logan-streamer volumio[992]: info: Nov 22 16:37:08 martin-logan-streamer volumio[992]: ---------------------------- MPD announces system playlist update Nov 22 16:37:08 martin-logan-streamer volumio[992]: info: Ignoring MPD Status Update Nov 22 16:37:08 martin-logan-streamer volumio[992]: info: sendMpdCommand status took 56 milliseconds Nov 22 16:37:08 martin-logan-streamer volumio[992]: info: sendMpdCommand clear took 22 milliseconds Nov 22 16:37:08 martin-logan-streamer volumio[992]: verbose: ControllerMpd::parseState Nov 22 16:37:08 martin-logan-streamer volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 16:37:08 martin-logan-streamer volumio[992]: verbose: ControllerMpd::sendMpdCommand consume 1 Nov 22 16:37:08 martin-logan-streamer volumio[992]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 22 16:37:08 martin-logan-streamer volumio[992]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 22 16:37:08 martin-logan-streamer volumio[992]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 22 16:37:08 martin-logan-streamer volumio[992]: error: updateQueue error: null Nov 22 16:37:08 martin-logan-streamer volumio[992]: info: ------------------------------ 29ms Nov 22 16:37:08 martin-logan-streamer volumio[992]: info: sendMpdCommand playlistinfo took 27 milliseconds Nov 22 16:37:08 martin-logan-streamer volumio[992]: info: sendMpdCommand consume 1 took 27 milliseconds Nov 22 16:37:08 martin-logan-streamer volumio[992]: verbose: ControllerMpd::parseTrackInfo Nov 22 16:37:08 martin-logan-streamer volumio[992]: info: [1732293428488] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/world-etc-flac Nov 22 16:37:08 martin-logan-streamer volumio[992]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/world-etc-flac" Nov 22 16:37:08 martin-logan-streamer volumio[992]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Nov 22 16:37:08 martin-logan-streamer volumio[992]: info: ------------------------------ 93ms Nov 22 16:37:08 martin-logan-streamer volumio[992]: info: Nov 22 16:37:08 martin-logan-streamer volumio[992]: ---------------------------- MPD announces system playlist update Nov 22 16:37:08 martin-logan-streamer volumio[992]: info: Ignoring MPD Status Update Nov 22 16:37:08 martin-logan-streamer volumio[992]: info: sendMpdCommand add "https://stream.radioparadise.com/world-etc-flac" took 4 milliseconds Nov 22 16:37:08 martin-logan-streamer volumio[992]: verbose: ControllerMpd::sendMpdCommand play Nov 22 16:37:08 martin-logan-streamer volumio[992]: info: ------------------------------ 19ms Nov 22 16:37:08 martin-logan-streamer volumio[992]: info: sendMpdCommand play took 4 milliseconds Nov 22 16:37:08 martin-logan-streamer volumio[992]: info: [1732293428511] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=3 Nov 22 16:37:09 martin-logan-streamer volumio[992]: info: Nov 22 16:37:09 martin-logan-streamer volumio[992]: ---------------------------- MPD announces state update: player Nov 22 16:37:09 martin-logan-streamer volumio[992]: info: ControllerMpd::getState Nov 22 16:37:09 martin-logan-streamer volumio[992]: verbose: ControllerMpd::sendMpdCommand status Nov 22 16:37:09 martin-logan-streamer volumio[992]: info: Nov 22 16:37:09 martin-logan-streamer volumio[992]: ---------------------------- MPD announces state update: player Nov 22 16:37:09 martin-logan-streamer volumio[992]: info: sendMpdCommand status took 6 milliseconds Nov 22 16:37:09 martin-logan-streamer volumio[992]: info: ControllerMpd::getState Nov 22 16:37:09 martin-logan-streamer volumio[992]: verbose: ControllerMpd::sendMpdCommand status Nov 22 16:37:09 martin-logan-streamer volumio[992]: verbose: ControllerMpd::parseState Nov 22 16:37:09 martin-logan-streamer volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 16:37:09 martin-logan-streamer volumio[992]: info: sendMpdCommand status took 6 milliseconds Nov 22 16:37:09 martin-logan-streamer volumio[992]: info: sendMpdCommand playlistinfo took 5 milliseconds Nov 22 16:37:09 martin-logan-streamer volumio[992]: verbose: ControllerMpd::parseState Nov 22 16:37:09 martin-logan-streamer volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 16:37:09 martin-logan-streamer volumio[992]: verbose: ControllerMpd::parseTrackInfo Nov 22 16:37:09 martin-logan-streamer volumio[992]: info: ControllerMpd::pushState Nov 22 16:37:09 martin-logan-streamer volumio[992]: info: CoreCommandRouter::servicePushState Nov 22 16:37:09 martin-logan-streamer volumio[992]: info: CorePlayQueue::getTrack 0 Nov 22 16:37:09 martin-logan-streamer volumio[992]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Nov 22 16:37:09 martin-logan-streamer volumio[992]: info: ------------------------------ 20ms Nov 22 16:37:09 martin-logan-streamer volumio[992]: info: sendMpdCommand playlistinfo took 10 milliseconds Nov 22 16:37:09 martin-logan-streamer volumio[992]: verbose: ControllerMpd::parseTrackInfo Nov 22 16:37:09 martin-logan-streamer volumio[992]: info: ControllerMpd::pushState Nov 22 16:37:09 martin-logan-streamer volumio[992]: info: CoreCommandRouter::servicePushState Nov 22 16:37:09 martin-logan-streamer volumio[992]: info: CorePlayQueue::getTrack 0 Nov 22 16:37:09 martin-logan-streamer volumio[992]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Nov 22 16:37:09 martin-logan-streamer volumio[992]: info: ------------------------------ 27ms Nov 22 16:37:09 martin-logan-streamer volumio[992]: info: [1732293429145] [RadioParadise] received new metadata: {"time":127,"artist":"Habib Koité & Bamada","title":"I Mada","album":"Ma Ya","year":"1998","cover":"https://img.radioparadise.com/covers/l/B00000GWYH.jpg","cover_med":"https://img.radioparadise.com/covers/m/B00000GWYH.jpg","cover_small":"https://img.radioparadise.com/covers/s/B00000GWYH.jpg"} Nov 22 16:37:09 martin-logan-streamer volumio[992]: info: CorePlayQueue::getTrack 0 Nov 22 16:37:09 martin-logan-streamer volumio[992]: info: CoreCommandRouter::servicePushState Nov 22 16:37:09 martin-logan-streamer volumio[992]: info: CorePlayQueue::getTrack 0 Nov 22 16:37:09 martin-logan-streamer volumio[992]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B00000GWYH.jpg","uri":"https://stream.radioparadise.com/world-etc-flac","name":"I Mada","title":"I Mada","artist":"Habib Koité & Bamada","album":"Ma Ya","streaming":true,"disableUiControls":true,"duration":132,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Nov 22 16:37:09 martin-logan-streamer volumio[992]: verbose: CURRENT POSITION 0 Nov 22 16:37:09 martin-logan-streamer volumio[992]: info: CoreStateMachine::syncState stateService play Nov 22 16:37:09 martin-logan-streamer volumio[992]: info: CoreStateMachine::syncState currentStatus stop Nov 22 16:37:09 martin-logan-streamer volumio[992]: info: [1732293429147] [RadioParadise] setting new timer with duration of 132000 seconds. Nov 22 16:37:09 martin-logan-streamer volumio[992]: info: Nov 22 16:37:09 martin-logan-streamer volumio[992]: ---------------------------- MPD announces system playlist update Nov 22 16:37:09 martin-logan-streamer volumio[992]: info: Ignoring MPD Status Update Nov 22 16:37:09 martin-logan-streamer volumio[992]: info: Nov 22 16:37:09 martin-logan-streamer volumio[992]: ---------------------------- MPD announces state update: player Nov 22 16:37:09 martin-logan-streamer volumio[992]: info: ControllerMpd::getState Nov 22 16:37:09 martin-logan-streamer volumio[992]: verbose: ControllerMpd::sendMpdCommand status Nov 22 16:37:09 martin-logan-streamer volumio[992]: info: ------------------------------ 14ms Nov 22 16:37:09 martin-logan-streamer volumio[992]: info: sendMpdCommand status took 4 milliseconds Nov 22 16:37:09 martin-logan-streamer volumio[992]: verbose: ControllerMpd::parseState Nov 22 16:37:09 martin-logan-streamer volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 16:37:09 martin-logan-streamer volumio[992]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 22 16:37:09 martin-logan-streamer volumio[992]: verbose: ControllerMpd::parseTrackInfo Nov 22 16:37:09 martin-logan-streamer volumio[992]: info: ControllerMpd::pushState Nov 22 16:37:09 martin-logan-streamer volumio[992]: info: CoreCommandRouter::servicePushState Nov 22 16:37:09 martin-logan-streamer volumio[992]: info: CorePlayQueue::getTrack 0 Nov 22 16:37:09 martin-logan-streamer volumio[992]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Nov 22 16:37:09 martin-logan-streamer volumio[992]: info: ------------------------------ 12ms Nov 22 16:37:09 martin-logan-streamer volumio[992]: info: CorePlayQueue::getTrack 0 Nov 22 16:37:09 martin-logan-streamer volumio[992]: info: CorePlayQueue::getTrack 1 Nov 22 16:37:11 martin-logan-streamer volumio[992]: info: Preload queue cleared Nov 22 16:37:11 martin-logan-streamer volumio[992]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 22 16:37:11 martin-logan-streamer volumio[992]: info: CoreStateMachine::ClearQueue Nov 22 16:37:11 martin-logan-streamer volumio[992]: info: CoreStateMachine::stop Nov 22 16:37:11 martin-logan-streamer volumio[992]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 22 16:37:11 martin-logan-streamer volumio[992]: info: CoreStateMachine::stPlaybackTimer Nov 22 16:37:11 martin-logan-streamer volumio[992]: info: CoreStateMachine::updateTrackBlock Nov 22 16:37:11 martin-logan-streamer volumio[992]: info: CorePlayQueue::getTrackBlock Nov 22 16:37:11 martin-logan-streamer volumio[992]: info: CoreStateMachine::pushState Nov 22 16:37:11 martin-logan-streamer volumio[992]: info: CorePlayQueue::getTrack 0 Nov 22 16:37:11 martin-logan-streamer volumio[992]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 22 16:37:11 martin-logan-streamer volumio[992]: info: CoreCommandRouter::volumioPushState Nov 22 16:37:11 martin-logan-streamer volumio[992]: info: CoreStateMachine::serviceStop Nov 22 16:37:11 martin-logan-streamer volumio[992]: info: CorePlayQueue::getTrack 0 Nov 22 16:37:11 martin-logan-streamer volumio[992]: info: CoreCommandRouter::serviceStop Nov 22 16:37:11 martin-logan-streamer volumio[992]: info: ControllerMpd::stop Nov 22 16:37:11 martin-logan-streamer volumio[992]: verbose: ControllerMpd::sendMpdCommand stop Nov 22 16:37:11 martin-logan-streamer volumio[992]: info: CorePlayQueue::clearPlayQueue Nov 22 16:37:11 martin-logan-streamer volumio[992]: info: CorePlayQueue::saveQueue Nov 22 16:37:11 martin-logan-streamer volumio[992]: info: CoreCommandRouter::volumioPushQueue Nov 22 16:37:11 martin-logan-streamer volumio[992]: info: CoreStateMachine::addQueueItems Nov 22 16:37:11 martin-logan-streamer volumio[992]: info: CorePlayQueue::addQueueItems Nov 22 16:37:11 martin-logan-streamer volumio[992]: info: Preload queue cleared Nov 22 16:37:11 martin-logan-streamer volumio[992]: info: Adding Item to queue: webrp/1 Nov 22 16:37:11 martin-logan-streamer volumio[992]: info: Using cached record of: webrp/1 Nov 22 16:37:11 martin-logan-streamer volumio[992]: info: CoreCommandRouter::volumioPushQueue Nov 22 16:37:11 martin-logan-streamer volumio[992]: info: CorePlayQueue::saveQueue Nov 22 16:37:11 martin-logan-streamer volumio[992]: info: CoreStateMachine::updateTrackBlock Nov 22 16:37:11 martin-logan-streamer volumio[992]: info: CorePlayQueue::getTrackBlock Nov 22 16:37:11 martin-logan-streamer volumio[992]: info: CoreCommandRouter::volumioPlay Nov 22 16:37:11 martin-logan-streamer volumio[992]: info: CoreStateMachine::play index 0 Nov 22 16:37:11 martin-logan-streamer volumio[992]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 22 16:37:11 martin-logan-streamer volumio[992]: info: CoreStateMachine::stop Nov 22 16:37:11 martin-logan-streamer volumio[992]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 22 16:37:11 martin-logan-streamer volumio[992]: info: CoreStateMachine::play index undefined Nov 22 16:37:11 martin-logan-streamer volumio[992]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 22 16:37:11 martin-logan-streamer volumio[992]: info: CorePlayQueue::getTrack 0 Nov 22 16:37:11 martin-logan-streamer volumio[992]: info: CoreStateMachine::startPlaybackTimer Nov 22 16:37:11 martin-logan-streamer volumio[992]: info: CorePlayQueue::getTrack 0 Nov 22 16:37:11 martin-logan-streamer volumio[992]: verbose: ControllerMpd::sendMpdCommand stop Nov 22 16:37:11 martin-logan-streamer volumio[992]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 22 16:37:11 martin-logan-streamer volumio[992]: info: Nov 22 16:37:11 martin-logan-streamer volumio[992]: ---------------------------- MPD announces state update: player Nov 22 16:37:11 martin-logan-streamer volumio[992]: info: sendMpdCommand stop took 58 milliseconds Nov 22 16:37:11 martin-logan-streamer volumio[992]: info: ControllerMpd::getState Nov 22 16:37:11 martin-logan-streamer volumio[992]: verbose: ControllerMpd::sendMpdCommand status Nov 22 16:37:11 martin-logan-streamer volumio[992]: info: sendMpdCommand stop took 24 milliseconds Nov 22 16:37:11 martin-logan-streamer volumio[992]: info: CoreCommandRouter::servicePushState Nov 22 16:37:11 martin-logan-streamer volumio[992]: info: CoreStateMachine::pushState Nov 22 16:37:11 martin-logan-streamer volumio[992]: info: CorePlayQueue::getTrack 0 Nov 22 16:37:11 martin-logan-streamer volumio[992]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 22 16:37:11 martin-logan-streamer volumio[992]: info: CoreCommandRouter::volumioPushState Nov 22 16:37:11 martin-logan-streamer volumio[992]: info: CorePlayQueue::getTrack 0 Nov 22 16:37:11 martin-logan-streamer volumio[992]: verbose: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B00000GWYH.jpg","uri":"https://stream.radioparadise.com/world-etc-flac","name":"I Mada","title":"I Mada","artist":"Habib Koité & Bamada","album":"Ma Ya","streaming":true,"disableUiControls":true,"duration":132,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Nov 22 16:37:11 martin-logan-streamer volumio[992]: verbose: CURRENT POSITION 0 Nov 22 16:37:11 martin-logan-streamer volumio[992]: info: CoreStateMachine::syncState stateService stop Nov 22 16:37:11 martin-logan-streamer volumio[992]: info: CoreStateMachine::syncState currentStatus stop Nov 22 16:37:11 martin-logan-streamer volumio[992]: info: CoreStateMachine::pushState Nov 22 16:37:11 martin-logan-streamer volumio[992]: info: CorePlayQueue::getTrack 0 Nov 22 16:37:11 martin-logan-streamer volumio[992]: info: CoreCommandRouter::volumioPushState Nov 22 16:37:11 martin-logan-streamer volumio[992]: info: No code Nov 22 16:37:11 martin-logan-streamer volumio[992]: info: CoreStateMachine::pushState Nov 22 16:37:11 martin-logan-streamer volumio[992]: info: CorePlayQueue::getTrack 0 Nov 22 16:37:11 martin-logan-streamer volumio[992]: info: CoreCommandRouter::volumioPushState Nov 22 16:37:11 martin-logan-streamer volumio[992]: verbose: ControllerMpd::sendMpdCommand clear Nov 22 16:37:11 martin-logan-streamer volumio[992]: info: Nov 22 16:37:11 martin-logan-streamer volumio[992]: ---------------------------- MPD announces system playlist update Nov 22 16:37:11 martin-logan-streamer volumio[992]: info: Ignoring MPD Status Update Nov 22 16:37:11 martin-logan-streamer volumio[992]: info: sendMpdCommand status took 34 milliseconds Nov 22 16:37:11 martin-logan-streamer volumio[992]: info: sendMpdCommand clear took 17 milliseconds Nov 22 16:37:11 martin-logan-streamer volumio[992]: verbose: ControllerMpd::parseState Nov 22 16:37:11 martin-logan-streamer volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 16:37:11 martin-logan-streamer volumio[992]: verbose: ControllerMpd::sendMpdCommand consume 1 Nov 22 16:37:11 martin-logan-streamer volumio[992]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 22 16:37:11 martin-logan-streamer volumio[992]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 22 16:37:11 martin-logan-streamer volumio[992]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 22 16:37:11 martin-logan-streamer volumio[992]: error: updateQueue error: null Nov 22 16:37:11 martin-logan-streamer volumio[992]: info: ------------------------------ 26ms Nov 22 16:37:11 martin-logan-streamer volumio[992]: info: sendMpdCommand playlistinfo took 22 milliseconds Nov 22 16:37:11 martin-logan-streamer volumio[992]: info: sendMpdCommand consume 1 took 21 milliseconds Nov 22 16:37:11 martin-logan-streamer volumio[992]: verbose: ControllerMpd::parseTrackInfo Nov 22 16:37:11 martin-logan-streamer volumio[992]: info: [1732293431729] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/mellow-flac Nov 22 16:37:11 martin-logan-streamer volumio[992]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/mellow-flac" Nov 22 16:37:11 martin-logan-streamer volumio[992]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Nov 22 16:37:11 martin-logan-streamer volumio[992]: info: ------------------------------ 64ms Nov 22 16:37:11 martin-logan-streamer volumio[992]: info: Nov 22 16:37:11 martin-logan-streamer volumio[992]: ---------------------------- MPD announces system playlist update Nov 22 16:37:11 martin-logan-streamer volumio[992]: info: Ignoring MPD Status Update Nov 22 16:37:11 martin-logan-streamer volumio[992]: info: sendMpdCommand add "https://stream.radioparadise.com/mellow-flac" took 4 milliseconds Nov 22 16:37:11 martin-logan-streamer volumio[992]: verbose: ControllerMpd::sendMpdCommand play Nov 22 16:37:11 martin-logan-streamer volumio[992]: info: ------------------------------ 9ms Nov 22 16:37:11 martin-logan-streamer volumio[992]: info: sendMpdCommand play took 4 milliseconds Nov 22 16:37:11 martin-logan-streamer volumio[992]: info: [1732293431743] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=1 Nov 22 16:37:12 martin-logan-streamer volumio[992]: info: Nov 22 16:37:12 martin-logan-streamer volumio[992]: ---------------------------- MPD announces state update: player Nov 22 16:37:12 martin-logan-streamer volumio[992]: info: ControllerMpd::getState Nov 22 16:37:12 martin-logan-streamer volumio[992]: verbose: ControllerMpd::sendMpdCommand status Nov 22 16:37:12 martin-logan-streamer volumio[992]: info: [1732293432337] [RadioParadise] received new metadata: {"time":167,"artist":"Patty Griffin","title":"Luminous Places","album":"Patty Griffin","year":"2019","cover":"https://img.radioparadise.com/covers/l/B07LDNGMGV.jpg","cover_med":"https://img.radioparadise.com/covers/m/B07LDNGMGV.jpg","cover_small":"https://img.radioparadise.com/covers/s/B07LDNGMGV.jpg"} Nov 22 16:37:12 martin-logan-streamer volumio[992]: info: CorePlayQueue::getTrack 0 Nov 22 16:37:12 martin-logan-streamer volumio[992]: info: CoreCommandRouter::servicePushState Nov 22 16:37:12 martin-logan-streamer volumio[992]: info: CorePlayQueue::getTrack 0 Nov 22 16:37:12 martin-logan-streamer volumio[992]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B07LDNGMGV.jpg","uri":"https://stream.radioparadise.com/mellow-flac","name":"Luminous Places","title":"Luminous Places","artist":"Patty Griffin","album":"Patty Griffin","streaming":true,"disableUiControls":true,"duration":172,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Nov 22 16:37:12 martin-logan-streamer volumio[992]: verbose: CURRENT POSITION 0 Nov 22 16:37:12 martin-logan-streamer volumio[992]: info: CoreStateMachine::syncState stateService play Nov 22 16:37:12 martin-logan-streamer volumio[992]: info: CoreStateMachine::syncState currentStatus stop Nov 22 16:37:12 martin-logan-streamer volumio[992]: info: [1732293432341] [RadioParadise] setting new timer with duration of 172000 seconds. Nov 22 16:37:12 martin-logan-streamer volumio[992]: info: Nov 22 16:37:12 martin-logan-streamer volumio[992]: ---------------------------- MPD announces state update: player Nov 22 16:37:12 martin-logan-streamer volumio[992]: info: ControllerMpd::getState Nov 22 16:37:12 martin-logan-streamer volumio[992]: verbose: ControllerMpd::sendMpdCommand status Nov 22 16:37:12 martin-logan-streamer volumio[992]: info: sendMpdCommand status took 11 milliseconds Nov 22 16:37:12 martin-logan-streamer volumio[992]: verbose: ControllerMpd::parseState Nov 22 16:37:12 martin-logan-streamer volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 16:37:12 martin-logan-streamer volumio[992]: info: sendMpdCommand status took 3 milliseconds Nov 22 16:37:12 martin-logan-streamer volumio[992]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 22 16:37:12 martin-logan-streamer volumio[992]: verbose: ControllerMpd::parseState Nov 22 16:37:12 martin-logan-streamer volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 16:37:12 martin-logan-streamer volumio[992]: verbose: ControllerMpd::parseTrackInfo Nov 22 16:37:12 martin-logan-streamer volumio[992]: info: ControllerMpd::pushState Nov 22 16:37:12 martin-logan-streamer volumio[992]: info: CoreCommandRouter::servicePushState Nov 22 16:37:12 martin-logan-streamer volumio[992]: info: CorePlayQueue::getTrack 0 Nov 22 16:37:12 martin-logan-streamer volumio[992]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Nov 22 16:37:12 martin-logan-streamer volumio[992]: info: ------------------------------ 18ms Nov 22 16:37:12 martin-logan-streamer volumio[992]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 22 16:37:12 martin-logan-streamer volumio[992]: verbose: ControllerMpd::parseTrackInfo Nov 22 16:37:12 martin-logan-streamer volumio[992]: info: ControllerMpd::pushState Nov 22 16:37:12 martin-logan-streamer volumio[992]: info: CoreCommandRouter::servicePushState Nov 22 16:37:12 martin-logan-streamer volumio[992]: info: CorePlayQueue::getTrack 0 Nov 22 16:37:12 martin-logan-streamer volumio[992]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Nov 22 16:37:12 martin-logan-streamer volumio[992]: info: ------------------------------ 12ms Nov 22 16:37:12 martin-logan-streamer volumio[992]: info: Nov 22 16:37:12 martin-logan-streamer volumio[992]: ---------------------------- MPD announces system playlist update Nov 22 16:37:12 martin-logan-streamer volumio[992]: info: Ignoring MPD Status Update Nov 22 16:37:12 martin-logan-streamer volumio[992]: info: Nov 22 16:37:12 martin-logan-streamer volumio[992]: ---------------------------- MPD announces state update: player Nov 22 16:37:12 martin-logan-streamer volumio[992]: info: ControllerMpd::getState Nov 22 16:37:12 martin-logan-streamer volumio[992]: verbose: ControllerMpd::sendMpdCommand status Nov 22 16:37:12 martin-logan-streamer volumio[992]: info: CorePlayQueue::getTrack 0 Nov 22 16:37:12 martin-logan-streamer volumio[992]: info: CorePlayQueue::getTrack 1 Nov 22 16:37:12 martin-logan-streamer volumio[992]: info: ------------------------------ 21ms Nov 22 16:37:12 martin-logan-streamer volumio[992]: info: sendMpdCommand status took 15 milliseconds Nov 22 16:37:12 martin-logan-streamer volumio[992]: verbose: ControllerMpd::parseState Nov 22 16:37:12 martin-logan-streamer volumio[992]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 22 16:37:12 martin-logan-streamer volumio[992]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 22 16:37:12 martin-logan-streamer volumio[992]: verbose: ControllerMpd::parseTrackInfo Nov 22 16:37:12 martin-logan-streamer volumio[992]: info: ControllerMpd::pushState Nov 22 16:37:12 martin-logan-streamer volumio[992]: info: CoreCommandRouter::servicePushState Nov 22 16:37:12 martin-logan-streamer volumio[992]: info: CorePlayQueue::getTrack 0 Nov 22 16:37:12 martin-logan-streamer volumio[992]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Nov 22 16:37:12 martin-logan-streamer volumio[992]: info: ------------------------------ 26ms Nov 22 16:37:30 martin-logan-streamer volumio[992]: error: [now-playing-app] App error: Nov 22 16:37:32 martin-logan-streamer volumio[992]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 22 16:37:32 martin-logan-streamer volumio[992]: info: Preload queue cleared Nov 22 16:37:33 martin-logan-streamer volumio[992]: (node:992) UnhandledPromiseRejectionWarning: Error: {"cod":401,"message":"Invalid API key. Please see https://openweathermap.org/faq#error401 for more info."} Nov 22 16:37:33 martin-logan-streamer volumio[992]: at OpenWeatherAPI.#fetch (/data/plugins/user_interface/now_playing/node_modules/openweather-api-node/src/index.js:739:19) Nov 22 16:37:33 martin-logan-streamer volumio[992]: at runMicrotasks () Nov 22 16:37:33 martin-logan-streamer volumio[992]: at processTicksAndRejections (internal/process/task_queues.js:93:5) Nov 22 16:37:33 martin-logan-streamer volumio[992]: at async OpenWeatherAPI.getEverything (/data/plugins/user_interface/now_playing/node_modules/openweather-api-node/src/index.js:559:24) Nov 22 16:37:33 martin-logan-streamer volumio[992]: at async /data/plugins/user_interface/now_playing/lib/api/weather.js:286:21 Nov 22 16:37:33 martin-logan-streamer volumio[992]: (node:992) 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: 55) Nov 22 16:37:33 martin-logan-streamer volumio[992]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 22 16:37:33 martin-logan-streamer volumio[992]: info: Preload queue cleared Nov 22 16:37:35 martin-logan-streamer volumio[992]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 22 16:37:35 martin-logan-streamer volumio[992]: info: Preload queue cleared Nov 22 16:37:38 martin-logan-streamer volumio[992]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Nov 22 16:37:38 martin-logan-streamer volumio[992]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 22 16:37:38 martin-logan-streamer volumio[992]: TypeError: Cannot read property 'length' of undefined Nov 22 16:37:38 martin-logan-streamer volumio[992]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Nov 22 16:37:38 martin-logan-streamer volumio[992]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Nov 22 16:37:38 martin-logan-streamer volumio[992]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Nov 22 16:37:38 martin-logan-streamer volumio[992]: at Parser.emit (events.js:315:20) Nov 22 16:37:38 martin-logan-streamer volumio[992]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Nov 22 16:37:38 martin-logan-streamer volumio[992]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Nov 22 16:37:38 martin-logan-streamer volumio[992]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Nov 22 16:37:38 martin-logan-streamer volumio[992]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Nov 22 16:37:38 martin-logan-streamer volumio[992]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Nov 22 16:37:38 martin-logan-streamer volumio[992]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Nov 22 16:37:38 martin-logan-streamer volumio[992]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Nov 22 16:37:38 martin-logan-streamer volumio[992]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Nov 22 16:37:38 martin-logan-streamer volumio[992]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Nov 22 16:37:38 martin-logan-streamer volumio[992]: at IncomingMessage.emit (events.js:327:22) Nov 22 16:37:38 martin-logan-streamer volumio[992]: at endReadableNT (internal/streams/readable.js:1327:12) Nov 22 16:37:38 martin-logan-streamer volumio[992]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Nov 22 16:37:38 martin-logan-streamer volumio[992]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 22 16:37:39 martin-logan-streamer sudo[18833]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-22 16:36 Nov 22 16:37:39 martin-logan-streamer sudo[18833]: 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 06:51:56 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b9009e0280f50389e80a537357ff3961"