-- Logs begin at Thu 2019-02-14 13:11:58 MSK, end at Sun 2025-05-04 11:52:16 MSK. -- May 04 11:51:01 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 04 11:51:01 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 04 11:51:01 volumio volumio[822]: info: Discovery: Getting this device information May 04 11:51:01 volumio volumio[822]: info: CoreCommandRouter::volumioGetState May 04 11:51:01 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 04 11:51:09 volumio go-librespot[2852]: time="2025-05-04T11:51:09+03:00" level=trace msg="received accesspoint ping" May 04 11:51:09 volumio go-librespot[2852]: time="2025-05-04T11:51:09+03:00" level=trace msg="received accesspoint pong ack" May 04 11:51:09 volumio go-librespot[2852]: time="2025-05-04T11:51:09+03:00" level=trace msg="sent dealer ping" May 04 11:51:09 volumio go-librespot[2852]: time="2025-05-04T11:51:09+03:00" level=trace msg="received dealer pong" May 04 11:51:18 volumio volumio[822]: verbose: New Socket.io Connection to 192.168.68.183:3000 from 192.168.68.182 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 11 May 04 11:51:21 volumio volumio[822]: verbose: New Socket.io Connection to 192.168.68.183 from 192.168.68.182 UA: Mozilla/5.0 (Linux; Android 15; SM-S928B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.111 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 May 04 11:51:21 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 04 11:51:21 volumio volumio[822]: info: CoreCommandRouter::volumioGetVisibleSources May 04 11:51:21 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 04 11:51:21 volumio volumio[822]: info: CoreCommandRouter::volumioGetState May 04 11:51:21 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 04 11:51:21 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 04 11:51:21 volumio volumio[822]: info: Received Get System Info May 04 11:51:21 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 04 11:51:21 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 04 11:51:21 volumio volumio[822]: info: Discovery: Getting this device information May 04 11:51:21 volumio volumio[822]: info: CoreCommandRouter::volumioGetState May 04 11:51:21 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 04 11:51:21 volumio volumio[822]: info: CoreCommandRouter::volumioGetState May 04 11:51:21 volumio volumio[822]: info: Listing playlists May 04 11:51:21 volumio volumio[822]: info: CoreCommandRouter::volumioGetQueue May 04 11:51:21 volumio volumio[822]: info: CoreStateMachine::getQueue May 04 11:51:21 volumio volumio[822]: info: CorePlayQueue::getQueue May 04 11:51:21 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 04 11:51:39 volumio go-librespot[2852]: time="2025-05-04T11:51:39+03:00" level=trace msg="sent dealer ping" May 04 11:51:39 volumio go-librespot[2852]: time="2025-05-04T11:51:39+03:00" level=trace msg="received dealer pong" May 04 11:51:48 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 04 11:51:48 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 04 11:51:48 volumio volumio[822]: info: Discovery: Getting this device information May 04 11:51:48 volumio volumio[822]: info: CoreCommandRouter::volumioGetState May 04 11:51:48 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 04 11:51:52 volumio volumio[822]: verbose: New Socket.io Connection to 192.168.68.183:3000 from 192.168.68.182 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 11 May 04 11:51:52 volumio volumio[822]: verbose: New Socket.io Connection to 192.168.68.183 from 192.168.68.182 UA: Mozilla/5.0 (Linux; Android 15; SM-S928B Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/135.0.7049.111 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 May 04 11:51:52 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 04 11:51:52 volumio volumio[822]: info: CoreCommandRouter::volumioGetVisibleSources May 04 11:51:52 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 04 11:51:52 volumio volumio[822]: info: CoreCommandRouter::volumioGetState May 04 11:51:52 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 04 11:51:52 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 04 11:51:52 volumio volumio[822]: info: Received Get System Info May 04 11:51:52 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 04 11:51:52 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 04 11:51:52 volumio volumio[822]: info: Discovery: Getting this device information May 04 11:51:52 volumio volumio[822]: info: CoreCommandRouter::volumioGetState May 04 11:51:52 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 04 11:51:52 volumio volumio[822]: info: CoreCommandRouter::volumioGetState May 04 11:51:52 volumio volumio[822]: info: Listing playlists May 04 11:51:52 volumio volumio[822]: info: CoreCommandRouter::volumioGetQueue May 04 11:51:52 volumio volumio[822]: info: CoreStateMachine::getQueue May 04 11:51:52 volumio volumio[822]: info: CorePlayQueue::getQueue May 04 11:51:52 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 04 11:51:56 volumio volumio[822]: info: CorePlayQueue::getTrack 0 May 04 11:51:56 volumio volumio[822]: info: CorePlayQueue::getTrack 1 May 04 11:51:56 volumio volumio[822]: info: Prefetching next song May 04 11:51:56 volumio volumio[822]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-141.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3De72bcc56ed38d6f9e75123467fef9b6c99e121053cf990cd0e154c4e76b7397f%2Clid%3D1562%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68254e11%2F0%2F1750834%2F5cbc72e9.87841595.8.59218154%2F320.mp3&ext=.mp3" May 04 11:51:56 volumio volumio[822]: info: May 04 11:51:56 volumio volumio[822]: ---------------------------- MPD announces system playlist update May 04 11:51:56 volumio volumio[822]: info: Ignoring MPD Status Update May 04 11:51:56 volumio volumio[822]: info: sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-141.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3De72bcc56ed38d6f9e75123467fef9b6c99e121053cf990cd0e154c4e76b7397f%2Clid%3D1562%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68254e11%2F0%2F1750834%2F5cbc72e9.87841595.8.59218154%2F320.mp3&ext=.mp3" took 3 milliseconds May 04 11:51:56 volumio volumio[822]: verbose: MPD COMMAND [object Object] May 04 11:51:56 volumio volumio[822]: info: May 04 11:51:56 volumio volumio[822]: ---------------------------- MPD announces system playlist update May 04 11:51:56 volumio volumio[822]: info: Ignoring MPD Status Update May 04 11:51:56 volumio volumio[822]: info: ------------------------------ 5ms May 04 11:51:56 volumio volumio[822]: verbose: ControllerMpd::sendMpdCommand consume 1 May 04 11:51:56 volumio volumio[822]: info: ------------------------------ 2ms May 04 11:51:56 volumio volumio[822]: info: sendMpdCommand consume 1 took 1 milliseconds May 04 11:51:57 volumio volumio[822]: info: Preload queue cleared May 04 11:51:57 volumio volumio[822]: info: CoreCommandRouter::volumioReplaceandPlayItems May 04 11:51:57 volumio volumio[822]: info: CoreStateMachine::ClearQueue May 04 11:51:57 volumio volumio[822]: info: CoreStateMachine::stop May 04 11:51:57 volumio volumio[822]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 11:51:57 volumio volumio[822]: info: CoreStateMachine::stPlaybackTimer May 04 11:51:57 volumio volumio[822]: info: CoreStateMachine::updateTrackBlock May 04 11:51:57 volumio volumio[822]: info: CorePlayQueue::getTrackBlock May 04 11:51:57 volumio volumio[822]: info: CoreStateMachine::pushState May 04 11:51:57 volumio volumio[822]: info: CorePlayQueue::getTrack 0 May 04 11:51:57 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 11:51:57 volumio volumio[822]: info: CoreCommandRouter::volumioPushState May 04 11:51:57 volumio volumio[822]: info: CoreStateMachine::serviceStop May 04 11:51:57 volumio volumio[822]: info: CorePlayQueue::getTrack 0 May 04 11:51:57 volumio volumio[822]: info: CoreCommandRouter::serviceStop May 04 11:51:57 volumio volumio[822]: info: CoreStateMachine::setConsumeUpdateService mpd May 04 11:51:57 volumio volumio[822]: info: ControllerMpd::stop May 04 11:51:57 volumio volumio[822]: verbose: ControllerMpd::sendMpdCommand stop May 04 11:51:57 volumio volumio[822]: info: CorePlayQueue::clearPlayQueue May 04 11:51:57 volumio volumio[822]: info: CorePlayQueue::saveQueue May 04 11:51:57 volumio volumio[822]: info: CoreCommandRouter::volumioPushQueue May 04 11:51:57 volumio volumio[822]: info: CoreStateMachine::addQueueItems May 04 11:51:57 volumio volumio[822]: info: CorePlayQueue::addQueueItems May 04 11:51:57 volumio volumio[822]: info: Preload queue cleared May 04 11:51:57 volumio volumio[822]: info: Adding Item to queue: yandex_music/track/31682812:3845672@692528232:122886992 May 04 11:51:57 volumio volumio[822]: info: Using cached record of: yandex_music/track/31682812:3845672@692528232:122886992 May 04 11:51:57 volumio volumio[822]: info: Adding Item to queue: yandex_music/track/59218154:34334055@692528232:122886992 May 04 11:51:57 volumio volumio[822]: info: Using cached record of: yandex_music/track/59218154:34334055@692528232:122886992 May 04 11:51:57 volumio volumio[822]: info: Adding Item to queue: yandex_music/track/44185830:13280170@692528232:122886992 May 04 11:51:57 volumio volumio[822]: info: Using cached record of: yandex_music/track/44185830:13280170@692528232:122886992 May 04 11:51:57 volumio volumio[822]: info: Adding Item to queue: yandex_music/track/58440533:9884212@692528232:122886992 May 04 11:51:57 volumio volumio[822]: info: Using cached record of: yandex_music/track/58440533:9884212@692528232:122886992 May 04 11:51:57 volumio volumio[822]: info: Adding Item to queue: yandex_music/track/2396802:3243402@692528232:122886992 May 04 11:51:57 volumio volumio[822]: info: Using cached record of: yandex_music/track/2396802:3243402@692528232:122886992 May 04 11:51:57 volumio volumio[822]: info: CoreCommandRouter::volumioPushQueue May 04 11:51:57 volumio volumio[822]: info: CorePlayQueue::saveQueue May 04 11:51:57 volumio volumio[822]: info: CoreStateMachine::updateTrackBlock May 04 11:51:57 volumio volumio[822]: info: CorePlayQueue::getTrackBlock May 04 11:51:57 volumio volumio[822]: info: CoreCommandRouter::volumioPlay May 04 11:51:57 volumio volumio[822]: info: CoreStateMachine::play index 4 May 04 11:51:57 volumio volumio[822]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 11:51:57 volumio volumio[822]: info: CoreStateMachine::addQueueItems May 04 11:51:57 volumio volumio[822]: info: CorePlayQueue::addQueueItems May 04 11:51:57 volumio volumio[822]: info: Preload queue cleared May 04 11:51:57 volumio volumio[822]: info: Adding Item to queue: yandex_music/track/37637986:4775672@692528232:122886992 May 04 11:51:57 volumio volumio[822]: info: Using cached record of: yandex_music/track/37637986:4775672@692528232:122886992 May 04 11:51:57 volumio volumio[822]: info: Adding Item to queue: yandex_music/track/66132347:10759152@692528232:122886992 May 04 11:51:57 volumio volumio[822]: info: Using cached record of: yandex_music/track/66132347:10759152@692528232:122886992 May 04 11:51:57 volumio volumio[822]: info: Adding Item to queue: yandex_music/track/104886317:22631941@692528232:122886992 May 04 11:51:57 volumio volumio[822]: info: Using cached record of: yandex_music/track/104886317:22631941@692528232:122886992 May 04 11:51:57 volumio volumio[822]: info: Adding Item to queue: yandex_music/track/78957324:15409784@692528232:122886992 May 04 11:51:57 volumio volumio[822]: info: Using cached record of: yandex_music/track/78957324:15409784@692528232:122886992 May 04 11:51:57 volumio volumio[822]: info: Adding Item to queue: yandex_music/track/98361832:20416441@692528232:122886992 May 04 11:51:57 volumio volumio[822]: info: Using cached record of: yandex_music/track/98361832:20416441@692528232:122886992 May 04 11:51:57 volumio volumio[822]: info: Adding Item to queue: yandex_music/track/55184247:8032290@692528232:122886992 May 04 11:51:57 volumio volumio[822]: info: Using cached record of: yandex_music/track/55184247:8032290@692528232:122886992 May 04 11:51:57 volumio volumio[822]: info: Adding Item to queue: yandex_music/track/104145309:22364881@692528232:122886992 May 04 11:51:57 volumio volumio[822]: info: Using cached record of: yandex_music/track/104145309:22364881@692528232:122886992 May 04 11:51:57 volumio volumio[822]: info: Adding Item to queue: yandex_music/track/61304967:9553259@692528232:122886992 May 04 11:51:57 volumio volumio[822]: info: Using cached record of: yandex_music/track/61304967:9553259@692528232:122886992 May 04 11:51:57 volumio volumio[822]: info: Adding Item to queue: yandex_music/track/137793926:36060775@692528232:122886992 May 04 11:51:57 volumio volumio[822]: info: Using cached record of: yandex_music/track/137793926:36060775@692528232:122886992 May 04 11:51:57 volumio volumio[822]: info: Adding Item to queue: yandex_music/track/21490575:2708193@692528232:122886992 May 04 11:51:57 volumio volumio[822]: info: Using cached record of: yandex_music/track/21490575:2708193@692528232:122886992 May 04 11:51:57 volumio volumio[822]: info: Adding Item to queue: yandex_music/track/65290740:10554179@692528232:122886992 May 04 11:51:57 volumio volumio[822]: info: Using cached record of: yandex_music/track/65290740:10554179@692528232:122886992 May 04 11:51:57 volumio volumio[822]: info: Adding Item to queue: yandex_music/track/84512131:16028867@692528232:122886992 May 04 11:51:57 volumio volumio[822]: info: Using cached record of: yandex_music/track/84512131:16028867@692528232:122886992 May 04 11:51:57 volumio volumio[822]: info: Adding Item to queue: yandex_music/track/112647460:25367479@692528232:122886992 May 04 11:51:57 volumio volumio[822]: info: Using cached record of: yandex_music/track/112647460:25367479@692528232:122886992 May 04 11:51:57 volumio volumio[822]: info: Adding Item to queue: yandex_music/track/103398401:22119376@692528232:122886992 May 04 11:51:57 volumio volumio[822]: info: Using cached record of: yandex_music/track/103398401:22119376@692528232:122886992 May 04 11:51:57 volumio volumio[822]: info: Adding Item to queue: yandex_music/track/40903115:5334300@692528232:122886992 May 04 11:51:57 volumio volumio[822]: info: Using cached record of: yandex_music/track/40903115:5334300@692528232:122886992 May 04 11:51:57 volumio volumio[822]: info: Adding Item to queue: yandex_music/track/21850862:2510629@692528232:122886992 May 04 11:51:57 volumio volumio[822]: info: Using cached record of: yandex_music/track/21850862:2510629@692528232:122886992 May 04 11:51:57 volumio volumio[822]: info: Adding Item to queue: yandex_music/track/98138498:32138149@692528232:122886992 May 04 11:51:57 volumio volumio[822]: info: Using cached record of: yandex_music/track/98138498:32138149@692528232:122886992 May 04 11:51:57 volumio volumio[822]: info: Adding Item to queue: yandex_music/track/52667089:7428011@692528232:122886992 May 04 11:51:57 volumio volumio[822]: info: Using cached record of: yandex_music/track/52667089:7428011@692528232:122886992 May 04 11:51:57 volumio volumio[822]: info: Adding Item to queue: yandex_music/track/97831313:20217937@692528232:122886992 May 04 11:51:57 volumio volumio[822]: info: Using cached record of: yandex_music/track/97831313:20217937@692528232:122886992 May 04 11:51:57 volumio volumio[822]: info: Adding Item to queue: yandex_music/track/77900219:13863889@692528232:122886992 May 04 11:51:57 volumio volumio[822]: info: Using cached record of: yandex_music/track/77900219:13863889@692528232:122886992 May 04 11:51:57 volumio volumio[822]: info: Adding Item to queue: yandex_music/track/111788059:25029653@692528232:122886992 May 04 11:51:57 volumio volumio[822]: info: Using cached record of: yandex_music/track/111788059:25029653@692528232:122886992 May 04 11:51:57 volumio volumio[822]: info: Adding Item to queue: yandex_music/track/115126287:26445651@692528232:122886992 May 04 11:51:57 volumio volumio[822]: info: Using cached record of: yandex_music/track/115126287:26445651@692528232:122886992 May 04 11:51:57 volumio volumio[822]: info: Adding Item to queue: yandex_music/track/88370895:17460402@692528232:122886992 May 04 11:51:57 volumio volumio[822]: info: Using cached record of: yandex_music/track/88370895:17460402@692528232:122886992 May 04 11:51:57 volumio volumio[822]: info: Adding Item to queue: yandex_music/track/53080728:8772035@692528232:122886992 May 04 11:51:57 volumio volumio[822]: info: Using cached record of: yandex_music/track/53080728:8772035@692528232:122886992 May 04 11:51:57 volumio volumio[822]: info: Adding Item to queue: yandex_music/track/33996118:9064788@692528232:122886992 May 04 11:51:57 volumio volumio[822]: info: Using cached record of: yandex_music/track/33996118:9064788@692528232:122886992 May 04 11:51:57 volumio volumio[822]: info: CoreStateMachine::stop May 04 11:51:57 volumio volumio[822]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 11:51:57 volumio volumio[822]: info: CoreCommandRouter::volumioPushQueue May 04 11:51:57 volumio volumio[822]: info: CorePlayQueue::saveQueue May 04 11:51:57 volumio volumio[822]: info: CoreStateMachine::play index undefined May 04 11:51:57 volumio volumio[822]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 11:51:57 volumio volumio[822]: info: CoreStateMachine::updateTrackBlock May 04 11:51:57 volumio volumio[822]: info: CorePlayQueue::getTrackBlock May 04 11:51:57 volumio volumio[822]: info: CorePlayQueue::getTrack 4 May 04 11:51:57 volumio volumio[822]: info: CoreStateMachine::startPlaybackTimer May 04 11:51:57 volumio volumio[822]: info: CorePlayQueue::getTrack 4 May 04 11:51:57 volumio volumio[822]: verbose: ControllerMpd::sendMpdCommand stop May 04 11:51:57 volumio volumio[822]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 04 11:51:57 volumio volumio[822]: SPOTIFY: SPOTIFY VOLUME 100 May 04 11:51:57 volumio volumio[822]: SPOTIFY: VOLUMIO VOLUME 100 May 04 11:51:57 volumio volumio[822]: SPOTIFY: DELTA VOLUME ENOUGH: false May 04 11:51:58 volumio volumio[822]: info: May 04 11:51:58 volumio volumio[822]: ---------------------------- MPD announces state update: player May 04 11:51:58 volumio volumio[822]: info: sendMpdCommand stop took 91 milliseconds May 04 11:51:58 volumio volumio[822]: info: ControllerMpd::getState May 04 11:51:58 volumio volumio[822]: verbose: ControllerMpd::sendMpdCommand status May 04 11:51:58 volumio volumio[822]: info: sendMpdCommand stop took 58 milliseconds May 04 11:51:58 volumio volumio[822]: verbose: ControllerMpd::sendMpdCommand clear May 04 11:51:58 volumio volumio[822]: info: May 04 11:51:58 volumio volumio[822]: ---------------------------- MPD announces system playlist update May 04 11:51:58 volumio volumio[822]: info: Ignoring MPD Status Update May 04 11:51:58 volumio volumio[822]: info: sendMpdCommand status took 6 milliseconds May 04 11:51:58 volumio volumio[822]: info: sendMpdCommand clear took 5 milliseconds May 04 11:51:58 volumio volumio[822]: verbose: ControllerMpd::parseState May 04 11:51:58 volumio volumio[822]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 11:51:58 volumio volumio[822]: info: touch_display: Setting screensaver timeout to 3600 seconds. May 04 11:51:58 volumio volumio[822]: error: updateQueue error: null May 04 11:51:58 volumio volumio[822]: info: ------------------------------ 9ms May 04 11:51:58 volumio volumio[822]: info: sendMpdCommand playlistinfo took 8 milliseconds May 04 11:51:58 volumio volumio[822]: verbose: ControllerMpd::parseTrackInfo May 04 11:51:58 volumio volumio[822]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 04 11:51:58 volumio volumio[822]: info: ------------------------------ 17ms May 04 11:51:58 volumio volumio[822]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-56.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D67cc01ebaac1c07bcdeb859486c5ab98bf29748d3a440e24a0dd3dc9d483f199%2Clid%3D1562%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68257024%2F33371%2F895594%2F8fb767e9.59740137.10.2396802%2F320.mp3&ext=.mp3" May 04 11:51:58 volumio volumio[822]: info: May 04 11:51:58 volumio volumio[822]: ---------------------------- MPD announces system playlist update May 04 11:51:58 volumio volumio[822]: info: Ignoring MPD Status Update May 04 11:51:58 volumio volumio[822]: info: sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-56.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D67cc01ebaac1c07bcdeb859486c5ab98bf29748d3a440e24a0dd3dc9d483f199%2Clid%3D1562%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68257024%2F33371%2F895594%2F8fb767e9.59740137.10.2396802%2F320.mp3&ext=.mp3" took 1 milliseconds May 04 11:51:58 volumio volumio[822]: verbose: MPD COMMAND [object Object] May 04 11:51:58 volumio volumio[822]: verbose: MPD COMMAND [object Object] May 04 11:51:58 volumio volumio[822]: info: May 04 11:51:58 volumio volumio[822]: ---------------------------- MPD announces system playlist update May 04 11:51:58 volumio volumio[822]: info: Ignoring MPD Status Update May 04 11:51:58 volumio volumio[822]: info: ------------------------------ 2ms May 04 11:51:58 volumio volumio[822]: info: CoreStateMachine::setConsumeUpdateService mpd May 04 11:51:58 volumio volumio[822]: verbose: ControllerMpd::sendMpdCommand play May 04 11:51:58 volumio volumio[822]: info: ------------------------------ 1ms May 04 11:51:58 volumio volumio[822]: info: sendMpdCommand play took 1 milliseconds May 04 11:51:58 volumio volumio[822]: info: May 04 11:51:58 volumio volumio[822]: ---------------------------- MPD announces state update: player May 04 11:51:58 volumio volumio[822]: info: ControllerMpd::getState May 04 11:51:58 volumio volumio[822]: verbose: ControllerMpd::sendMpdCommand status May 04 11:51:58 volumio volumio[822]: info: May 04 11:51:58 volumio volumio[822]: ---------------------------- MPD announces state update: player May 04 11:51:58 volumio volumio[822]: info: sendMpdCommand status took 287 milliseconds May 04 11:51:58 volumio volumio[822]: info: ControllerMpd::getState May 04 11:51:58 volumio volumio[822]: verbose: ControllerMpd::sendMpdCommand status May 04 11:51:58 volumio volumio[822]: verbose: ControllerMpd::parseState May 04 11:51:58 volumio volumio[822]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 11:51:58 volumio volumio[822]: info: sendMpdCommand status took 2 milliseconds May 04 11:51:58 volumio volumio[822]: info: sendMpdCommand playlistinfo took 2 milliseconds May 04 11:51:58 volumio volumio[822]: verbose: ControllerMpd::parseState May 04 11:51:58 volumio volumio[822]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 11:51:58 volumio volumio[822]: verbose: ControllerMpd::parseTrackInfo May 04 11:51:58 volumio volumio[822]: info: ControllerMpd::pushState May 04 11:51:58 volumio volumio[822]: info: CoreCommandRouter::servicePushState May 04 11:51:58 volumio volumio[822]: info: CorePlayQueue::getTrack 4 May 04 11:51:58 volumio volumio[822]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":213,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Magic Fly","artist":"Count Dee's Hit Explosion","album":"Star Wars & More Close Encounters of the Third Kind","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-56.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D67cc01ebaac1c07bcdeb859486c5ab98bf29748d3a440e24a0dd3dc9d483f199%2Clid%3D1562%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68257024%2F33371%2F895594%2F8fb767e9.59740137.10.2396802%2F320.mp3&ext=.mp3","trackType":"mp3"} May 04 11:51:58 volumio volumio[822]: verbose: CURRENT POSITION 4 May 04 11:51:58 volumio volumio[822]: info: CoreStateMachine::syncState stateService play May 04 11:51:58 volumio volumio[822]: info: CoreStateMachine::syncState currentStatus stop May 04 11:51:58 volumio volumio[822]: info: ------------------------------ 292ms May 04 11:51:58 volumio volumio[822]: info: sendMpdCommand playlistinfo took 2 milliseconds May 04 11:51:58 volumio volumio[822]: verbose: ControllerMpd::parseTrackInfo May 04 11:51:58 volumio volumio[822]: info: ControllerMpd::pushState May 04 11:51:58 volumio volumio[822]: info: CoreCommandRouter::servicePushState May 04 11:51:58 volumio volumio[822]: info: CorePlayQueue::getTrack 4 May 04 11:51:58 volumio volumio[822]: verbose: STATE SERVICE {"status":"play","position":0,"seek":783,"duration":213,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Magic Fly","artist":"Count Dee's Hit Explosion","album":"Star Wars & More Close Encounters of the Third Kind","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-56.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D67cc01ebaac1c07bcdeb859486c5ab98bf29748d3a440e24a0dd3dc9d483f199%2Clid%3D1562%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D68257024%2F33371%2F895594%2F8fb767e9.59740137.10.2396802%2F320.mp3&ext=.mp3","trackType":"mp3"} May 04 11:51:58 volumio volumio[822]: verbose: CURRENT POSITION 4 May 04 11:51:58 volumio volumio[822]: info: CoreStateMachine::syncState stateService play May 04 11:51:58 volumio volumio[822]: info: CoreStateMachine::syncState currentStatus play May 04 11:51:58 volumio volumio[822]: info: Received an update from plugin. extracting info from payload May 04 11:51:58 volumio volumio[822]: info: CoreStateMachine::pushState May 04 11:51:58 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 11:51:58 volumio volumio[822]: info: CoreCommandRouter::volumioPushState May 04 11:51:59 volumio volumio[822]: info: CoreStateMachine::pushState May 04 11:51:59 volumio volumio[822]: info: CoreCommandRouter::volumioPushState May 04 11:51:59 volumio volumio[822]: info: ------------------------------ 26ms May 04 11:51:59 volumio volumio[822]: info: CoreCommandRouter::volumioGetQueue May 04 11:51:59 volumio volumio[822]: info: CoreStateMachine::getQueue May 04 11:51:59 volumio volumio[822]: info: CorePlayQueue::getQueue May 04 11:51:59 volumio volumio[822]: info: CoreCommandRouter::volumioGetQueue May 04 11:51:59 volumio volumio[822]: info: CoreStateMachine::getQueue May 04 11:51:59 volumio volumio[822]: info: CorePlayQueue::getQueue May 04 11:51:59 volumio volumio[822]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 04 11:51:59 volumio volumio[822]: SPOTIFY: SPOTIFY VOLUME 100 May 04 11:51:59 volumio volumio[822]: SPOTIFY: VOLUMIO VOLUME 100 May 04 11:51:59 volumio volumio[822]: SPOTIFY: DELTA VOLUME ENOUGH: false May 04 11:51:59 volumio volumio[822]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 04 11:51:59 volumio volumio[822]: SPOTIFY: SPOTIFY VOLUME 100 May 04 11:51:59 volumio volumio[822]: SPOTIFY: VOLUMIO VOLUME 100 May 04 11:51:59 volumio volumio[822]: SPOTIFY: DELTA VOLUME ENOUGH: false May 04 11:51:59 volumio volumio[822]: info: touch_display: Setting screensaver timeout to 0 seconds. May 04 11:52:02 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 04 11:52:02 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 04 11:52:02 volumio volumio[822]: info: Discovery: Getting this device information May 04 11:52:02 volumio volumio[822]: info: CoreCommandRouter::volumioGetState May 04 11:52:02 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 04 11:52:04 volumio volumio[822]: info: Preload queue cleared May 04 11:52:04 volumio volumio[822]: info: CoreCommandRouter::volumioReplaceandPlayItems May 04 11:52:04 volumio volumio[822]: info: CoreStateMachine::ClearQueue May 04 11:52:04 volumio volumio[822]: info: CoreStateMachine::stop May 04 11:52:04 volumio volumio[822]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 11:52:04 volumio volumio[822]: info: CoreStateMachine::stPlaybackTimer May 04 11:52:04 volumio volumio[822]: info: CoreStateMachine::updateTrackBlock May 04 11:52:04 volumio volumio[822]: info: CorePlayQueue::getTrackBlock May 04 11:52:04 volumio volumio[822]: info: CoreStateMachine::pushState May 04 11:52:04 volumio volumio[822]: info: CorePlayQueue::getTrack 4 May 04 11:52:04 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 11:52:04 volumio volumio[822]: info: CoreCommandRouter::volumioPushState May 04 11:52:04 volumio volumio[822]: info: CoreStateMachine::serviceStop May 04 11:52:04 volumio volumio[822]: info: CorePlayQueue::getTrack 4 May 04 11:52:04 volumio volumio[822]: info: CoreCommandRouter::serviceStop May 04 11:52:04 volumio volumio[822]: info: CoreStateMachine::setConsumeUpdateService mpd May 04 11:52:04 volumio volumio[822]: info: ControllerMpd::stop May 04 11:52:04 volumio volumio[822]: verbose: ControllerMpd::sendMpdCommand stop May 04 11:52:04 volumio volumio[822]: info: CorePlayQueue::clearPlayQueue May 04 11:52:04 volumio volumio[822]: info: CorePlayQueue::saveQueue May 04 11:52:04 volumio volumio[822]: info: CoreCommandRouter::volumioPushQueue May 04 11:52:04 volumio volumio[822]: info: CoreStateMachine::addQueueItems May 04 11:52:04 volumio volumio[822]: info: CorePlayQueue::addQueueItems May 04 11:52:04 volumio volumio[822]: info: Preload queue cleared May 04 11:52:04 volumio volumio[822]: info: Adding Item to queue: yandex_music/track/31682812:3845672@692528232:122886992 May 04 11:52:04 volumio volumio[822]: info: Using cached record of: yandex_music/track/31682812:3845672@692528232:122886992 May 04 11:52:04 volumio volumio[822]: info: Adding Item to queue: yandex_music/track/59218154:34334055@692528232:122886992 May 04 11:52:04 volumio volumio[822]: info: Using cached record of: yandex_music/track/59218154:34334055@692528232:122886992 May 04 11:52:04 volumio volumio[822]: info: Adding Item to queue: yandex_music/track/44185830:13280170@692528232:122886992 May 04 11:52:04 volumio volumio[822]: info: Using cached record of: yandex_music/track/44185830:13280170@692528232:122886992 May 04 11:52:04 volumio volumio[822]: info: Adding Item to queue: yandex_music/track/58440533:9884212@692528232:122886992 May 04 11:52:04 volumio volumio[822]: info: Using cached record of: yandex_music/track/58440533:9884212@692528232:122886992 May 04 11:52:04 volumio volumio[822]: info: CoreCommandRouter::volumioPushQueue May 04 11:52:04 volumio volumio[822]: info: CorePlayQueue::saveQueue May 04 11:52:04 volumio volumio[822]: info: CoreStateMachine::updateTrackBlock May 04 11:52:04 volumio volumio[822]: info: CorePlayQueue::getTrackBlock May 04 11:52:04 volumio volumio[822]: info: CoreCommandRouter::volumioPlay May 04 11:52:04 volumio volumio[822]: info: CoreStateMachine::play index 3 May 04 11:52:04 volumio volumio[822]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 11:52:04 volumio volumio[822]: info: CoreStateMachine::addQueueItems May 04 11:52:04 volumio volumio[822]: info: CorePlayQueue::addQueueItems May 04 11:52:04 volumio volumio[822]: info: Preload queue cleared May 04 11:52:04 volumio volumio[822]: info: Adding Item to queue: yandex_music/track/2396802:3243402@692528232:122886992 May 04 11:52:04 volumio volumio[822]: info: Using cached record of: yandex_music/track/2396802:3243402@692528232:122886992 May 04 11:52:04 volumio volumio[822]: info: Adding Item to queue: yandex_music/track/37637986:4775672@692528232:122886992 May 04 11:52:04 volumio volumio[822]: info: Using cached record of: yandex_music/track/37637986:4775672@692528232:122886992 May 04 11:52:04 volumio volumio[822]: info: Adding Item to queue: yandex_music/track/66132347:10759152@692528232:122886992 May 04 11:52:04 volumio volumio[822]: info: Using cached record of: yandex_music/track/66132347:10759152@692528232:122886992 May 04 11:52:04 volumio volumio[822]: info: Adding Item to queue: yandex_music/track/104886317:22631941@692528232:122886992 May 04 11:52:04 volumio volumio[822]: info: Using cached record of: yandex_music/track/104886317:22631941@692528232:122886992 May 04 11:52:04 volumio volumio[822]: info: Adding Item to queue: yandex_music/track/78957324:15409784@692528232:122886992 May 04 11:52:04 volumio volumio[822]: info: Using cached record of: yandex_music/track/78957324:15409784@692528232:122886992 May 04 11:52:04 volumio volumio[822]: info: Adding Item to queue: yandex_music/track/98361832:20416441@692528232:122886992 May 04 11:52:04 volumio volumio[822]: info: Using cached record of: yandex_music/track/98361832:20416441@692528232:122886992 May 04 11:52:04 volumio volumio[822]: info: Adding Item to queue: yandex_music/track/55184247:8032290@692528232:122886992 May 04 11:52:04 volumio volumio[822]: info: Using cached record of: yandex_music/track/55184247:8032290@692528232:122886992 May 04 11:52:04 volumio volumio[822]: info: Adding Item to queue: yandex_music/track/104145309:22364881@692528232:122886992 May 04 11:52:04 volumio volumio[822]: info: Using cached record of: yandex_music/track/104145309:22364881@692528232:122886992 May 04 11:52:04 volumio volumio[822]: info: Adding Item to queue: yandex_music/track/61304967:9553259@692528232:122886992 May 04 11:52:04 volumio volumio[822]: info: Using cached record of: yandex_music/track/61304967:9553259@692528232:122886992 May 04 11:52:04 volumio volumio[822]: info: Adding Item to queue: yandex_music/track/137793926:36060775@692528232:122886992 May 04 11:52:04 volumio volumio[822]: info: Using cached record of: yandex_music/track/137793926:36060775@692528232:122886992 May 04 11:52:04 volumio volumio[822]: info: Adding Item to queue: yandex_music/track/21490575:2708193@692528232:122886992 May 04 11:52:04 volumio volumio[822]: info: Using cached record of: yandex_music/track/21490575:2708193@692528232:122886992 May 04 11:52:04 volumio volumio[822]: info: Adding Item to queue: yandex_music/track/65290740:10554179@692528232:122886992 May 04 11:52:04 volumio volumio[822]: info: Using cached record of: yandex_music/track/65290740:10554179@692528232:122886992 May 04 11:52:04 volumio volumio[822]: info: Adding Item to queue: yandex_music/track/84512131:16028867@692528232:122886992 May 04 11:52:04 volumio volumio[822]: info: Using cached record of: yandex_music/track/84512131:16028867@692528232:122886992 May 04 11:52:04 volumio volumio[822]: info: Adding Item to queue: yandex_music/track/112647460:25367479@692528232:122886992 May 04 11:52:04 volumio volumio[822]: info: Using cached record of: yandex_music/track/112647460:25367479@692528232:122886992 May 04 11:52:04 volumio volumio[822]: info: Adding Item to queue: yandex_music/track/103398401:22119376@692528232:122886992 May 04 11:52:04 volumio volumio[822]: info: Using cached record of: yandex_music/track/103398401:22119376@692528232:122886992 May 04 11:52:04 volumio volumio[822]: info: Adding Item to queue: yandex_music/track/40903115:5334300@692528232:122886992 May 04 11:52:04 volumio volumio[822]: info: Using cached record of: yandex_music/track/40903115:5334300@692528232:122886992 May 04 11:52:04 volumio volumio[822]: info: Adding Item to queue: yandex_music/track/21850862:2510629@692528232:122886992 May 04 11:52:04 volumio volumio[822]: info: Using cached record of: yandex_music/track/21850862:2510629@692528232:122886992 May 04 11:52:04 volumio volumio[822]: info: Adding Item to queue: yandex_music/track/98138498:32138149@692528232:122886992 May 04 11:52:04 volumio volumio[822]: info: Using cached record of: yandex_music/track/98138498:32138149@692528232:122886992 May 04 11:52:04 volumio volumio[822]: info: Adding Item to queue: yandex_music/track/52667089:7428011@692528232:122886992 May 04 11:52:04 volumio volumio[822]: info: Using cached record of: yandex_music/track/52667089:7428011@692528232:122886992 May 04 11:52:04 volumio volumio[822]: info: Adding Item to queue: yandex_music/track/97831313:20217937@692528232:122886992 May 04 11:52:04 volumio volumio[822]: info: Using cached record of: yandex_music/track/97831313:20217937@692528232:122886992 May 04 11:52:04 volumio volumio[822]: info: Adding Item to queue: yandex_music/track/77900219:13863889@692528232:122886992 May 04 11:52:04 volumio volumio[822]: info: Using cached record of: yandex_music/track/77900219:13863889@692528232:122886992 May 04 11:52:04 volumio volumio[822]: info: Adding Item to queue: yandex_music/track/111788059:25029653@692528232:122886992 May 04 11:52:04 volumio volumio[822]: info: Using cached record of: yandex_music/track/111788059:25029653@692528232:122886992 May 04 11:52:04 volumio volumio[822]: info: Adding Item to queue: yandex_music/track/115126287:26445651@692528232:122886992 May 04 11:52:04 volumio volumio[822]: info: Using cached record of: yandex_music/track/115126287:26445651@692528232:122886992 May 04 11:52:04 volumio volumio[822]: info: Adding Item to queue: yandex_music/track/88370895:17460402@692528232:122886992 May 04 11:52:04 volumio volumio[822]: info: Using cached record of: yandex_music/track/88370895:17460402@692528232:122886992 May 04 11:52:04 volumio volumio[822]: info: Adding Item to queue: yandex_music/track/53080728:8772035@692528232:122886992 May 04 11:52:04 volumio volumio[822]: info: Using cached record of: yandex_music/track/53080728:8772035@692528232:122886992 May 04 11:52:04 volumio volumio[822]: info: Adding Item to queue: yandex_music/track/33996118:9064788@692528232:122886992 May 04 11:52:04 volumio volumio[822]: info: Using cached record of: yandex_music/track/33996118:9064788@692528232:122886992 May 04 11:52:04 volumio volumio[822]: info: CoreStateMachine::stop May 04 11:52:04 volumio volumio[822]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 11:52:04 volumio volumio[822]: info: CoreCommandRouter::volumioPushQueue May 04 11:52:04 volumio volumio[822]: info: CorePlayQueue::saveQueue May 04 11:52:04 volumio volumio[822]: info: CoreStateMachine::play index undefined May 04 11:52:04 volumio volumio[822]: info: CoreStateMachine::setConsumeUpdateService undefined May 04 11:52:04 volumio volumio[822]: info: CoreStateMachine::updateTrackBlock May 04 11:52:04 volumio volumio[822]: info: CorePlayQueue::getTrackBlock May 04 11:52:04 volumio volumio[822]: info: CorePlayQueue::getTrack 3 May 04 11:52:04 volumio volumio[822]: info: CoreStateMachine::startPlaybackTimer May 04 11:52:04 volumio volumio[822]: info: CorePlayQueue::getTrack 3 May 04 11:52:04 volumio volumio[822]: verbose: ControllerMpd::sendMpdCommand stop May 04 11:52:04 volumio volumio[822]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 04 11:52:04 volumio volumio[822]: SPOTIFY: SPOTIFY VOLUME 100 May 04 11:52:04 volumio volumio[822]: SPOTIFY: VOLUMIO VOLUME 100 May 04 11:52:04 volumio volumio[822]: SPOTIFY: DELTA VOLUME ENOUGH: false May 04 11:52:04 volumio volumio[822]: info: May 04 11:52:04 volumio volumio[822]: ---------------------------- MPD announces state update: player May 04 11:52:04 volumio volumio[822]: info: sendMpdCommand stop took 51 milliseconds May 04 11:52:04 volumio volumio[822]: info: ControllerMpd::getState May 04 11:52:04 volumio volumio[822]: verbose: ControllerMpd::sendMpdCommand status May 04 11:52:04 volumio volumio[822]: info: sendMpdCommand stop took 23 milliseconds May 04 11:52:04 volumio volumio[822]: verbose: ControllerMpd::sendMpdCommand clear May 04 11:52:04 volumio volumio[822]: info: May 04 11:52:04 volumio volumio[822]: ---------------------------- MPD announces system playlist update May 04 11:52:04 volumio volumio[822]: info: Ignoring MPD Status Update May 04 11:52:04 volumio volumio[822]: info: sendMpdCommand status took 31 milliseconds May 04 11:52:04 volumio volumio[822]: info: sendMpdCommand clear took 31 milliseconds May 04 11:52:04 volumio volumio[822]: verbose: ControllerMpd::parseState May 04 11:52:04 volumio volumio[822]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 11:52:04 volumio volumio[822]: error: updateQueue error: null May 04 11:52:04 volumio volumio[822]: info: ------------------------------ 9ms May 04 11:52:04 volumio volumio[822]: info: sendMpdCommand playlistinfo took 7 milliseconds May 04 11:52:04 volumio volumio[822]: verbose: ControllerMpd::parseTrackInfo May 04 11:52:04 volumio volumio[822]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 04 11:52:04 volumio volumio[822]: info: ------------------------------ 41ms May 04 11:52:04 volumio volumio[822]: info: touch_display: Setting screensaver timeout to 3600 seconds. May 04 11:52:04 volumio volumio[822]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-114.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D2e07d3b3352ffe74e161cd0eef3748305a29d32b15e3d510907064e3dd3d670d%2Clid%3D1582%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6824ee9f%2F0%2F1770500%2F5591c27c.86781809.9.58440533%2F320.mp3&ext=.mp3" May 04 11:52:04 volumio volumio[822]: info: May 04 11:52:04 volumio volumio[822]: ---------------------------- MPD announces system playlist update May 04 11:52:04 volumio volumio[822]: info: Ignoring MPD Status Update May 04 11:52:04 volumio volumio[822]: info: sendMpdCommand addid "http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-114.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D2e07d3b3352ffe74e161cd0eef3748305a29d32b15e3d510907064e3dd3d670d%2Clid%3D1582%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6824ee9f%2F0%2F1770500%2F5591c27c.86781809.9.58440533%2F320.mp3&ext=.mp3" took 1 milliseconds May 04 11:52:04 volumio volumio[822]: verbose: MPD COMMAND [object Object] May 04 11:52:04 volumio volumio[822]: verbose: MPD COMMAND [object Object] May 04 11:52:04 volumio volumio[822]: info: May 04 11:52:04 volumio volumio[822]: ---------------------------- MPD announces system playlist update May 04 11:52:04 volumio volumio[822]: info: Ignoring MPD Status Update May 04 11:52:04 volumio volumio[822]: info: ------------------------------ 2ms May 04 11:52:04 volumio volumio[822]: info: CoreStateMachine::setConsumeUpdateService mpd May 04 11:52:04 volumio volumio[822]: verbose: ControllerMpd::sendMpdCommand play May 04 11:52:04 volumio volumio[822]: info: ------------------------------ 2ms May 04 11:52:04 volumio volumio[822]: info: sendMpdCommand play took 1 milliseconds May 04 11:52:04 volumio volumio[822]: info: May 04 11:52:04 volumio volumio[822]: ---------------------------- MPD announces state update: player May 04 11:52:04 volumio volumio[822]: info: ControllerMpd::getState May 04 11:52:04 volumio volumio[822]: verbose: ControllerMpd::sendMpdCommand status May 04 11:52:05 volumio volumio[822]: info: May 04 11:52:05 volumio volumio[822]: ---------------------------- MPD announces state update: player May 04 11:52:05 volumio volumio[822]: info: sendMpdCommand status took 284 milliseconds May 04 11:52:05 volumio volumio[822]: info: ControllerMpd::getState May 04 11:52:05 volumio volumio[822]: verbose: ControllerMpd::sendMpdCommand status May 04 11:52:05 volumio volumio[822]: verbose: ControllerMpd::parseState May 04 11:52:05 volumio volumio[822]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 11:52:05 volumio volumio[822]: info: sendMpdCommand status took 3 milliseconds May 04 11:52:05 volumio volumio[822]: info: sendMpdCommand playlistinfo took 3 milliseconds May 04 11:52:05 volumio volumio[822]: verbose: ControllerMpd::parseState May 04 11:52:05 volumio volumio[822]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 04 11:52:05 volumio volumio[822]: verbose: ControllerMpd::parseTrackInfo May 04 11:52:05 volumio volumio[822]: info: ControllerMpd::pushState May 04 11:52:05 volumio volumio[822]: info: CoreCommandRouter::servicePushState May 04 11:52:05 volumio volumio[822]: info: CorePlayQueue::getTrack 3 May 04 11:52:05 volumio volumio[822]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":108,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Discovery","artist":"Mapa","album":"Africa","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-114.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D2e07d3b3352ffe74e161cd0eef3748305a29d32b15e3d510907064e3dd3d670d%2Clid%3D1582%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6824ee9f%2F0%2F1770500%2F5591c27c.86781809.9.58440533%2F320.mp3&ext=.mp3","trackType":"mp3"} May 04 11:52:05 volumio volumio[822]: verbose: CURRENT POSITION 3 May 04 11:52:05 volumio volumio[822]: info: CoreStateMachine::syncState stateService play May 04 11:52:05 volumio volumio[822]: info: CoreStateMachine::syncState currentStatus stop May 04 11:52:05 volumio volumio[822]: info: ------------------------------ 295ms May 04 11:52:05 volumio volumio[822]: info: sendMpdCommand playlistinfo took 2 milliseconds May 04 11:52:05 volumio volumio[822]: verbose: ControllerMpd::parseTrackInfo May 04 11:52:05 volumio volumio[822]: info: ControllerMpd::pushState May 04 11:52:05 volumio volumio[822]: info: CoreCommandRouter::servicePushState May 04 11:52:05 volumio volumio[822]: info: CorePlayQueue::getTrack 3 May 04 11:52:05 volumio volumio[822]: verbose: STATE SERVICE {"status":"play","position":0,"seek":635,"duration":108,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Discovery","artist":"Mapa","album":"Africa","uri":"http://localhost:6601/?codec=mp3&url=https%3A%2F%2Fstrm-m9-114.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D2e07d3b3352ffe74e161cd0eef3748305a29d32b15e3d510907064e3dd3d670d%2Clid%3D1582%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D6824ee9f%2F0%2F1770500%2F5591c27c.86781809.9.58440533%2F320.mp3&ext=.mp3","trackType":"mp3"} May 04 11:52:05 volumio volumio[822]: verbose: CURRENT POSITION 3 May 04 11:52:05 volumio volumio[822]: info: CoreStateMachine::syncState stateService play May 04 11:52:05 volumio volumio[822]: info: CoreStateMachine::syncState currentStatus play May 04 11:52:05 volumio volumio[822]: info: Received an update from plugin. extracting info from payload May 04 11:52:05 volumio volumio[822]: info: CoreStateMachine::pushState May 04 11:52:05 volumio volumio[822]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 04 11:52:05 volumio volumio[822]: info: CoreCommandRouter::volumioPushState May 04 11:52:05 volumio volumio[822]: info: CoreStateMachine::pushState May 04 11:52:05 volumio volumio[822]: info: CoreCommandRouter::volumioPushState May 04 11:52:05 volumio volumio[822]: info: ------------------------------ 17ms May 04 11:52:09 volumio go-librespot[2852]: time="2025-05-04T11:52:09+03:00" level=trace msg="sent dealer ping" May 04 11:52:09 volumio go-librespot[2852]: time="2025-05-04T11:52:09+03:00" level=trace msg="received dealer pong" May 04 11:52:10 volumio volumio[822]: info: touch_display: Setting screensaver timeout to 0 seconds. May 04 11:52:10 volumio volumio[822]: info: CoreCommandRouter::volumioGetQueue May 04 11:52:10 volumio volumio[822]: info: CoreStateMachine::getQueue May 04 11:52:10 volumio volumio[822]: info: CorePlayQueue::getQueue May 04 11:52:10 volumio volumio[822]: info: CoreCommandRouter::volumioGetQueue May 04 11:52:10 volumio volumio[822]: info: CoreStateMachine::getQueue May 04 11:52:10 volumio volumio[822]: info: CorePlayQueue::getQueue May 04 11:52:13 volumio volumio[822]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 04 11:52:13 volumio volumio[822]: SPOTIFY: SPOTIFY VOLUME 100 May 04 11:52:13 volumio volumio[822]: SPOTIFY: VOLUMIO VOLUME 100 May 04 11:52:13 volumio volumio[822]: SPOTIFY: DELTA VOLUME ENOUGH: false May 04 11:52:13 volumio volumio[822]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 May 04 11:52:13 volumio volumio[822]: SPOTIFY: SPOTIFY VOLUME 100 May 04 11:52:13 volumio volumio[822]: SPOTIFY: VOLUMIO VOLUME 100 May 04 11:52:13 volumio volumio[822]: SPOTIFY: DELTA VOLUME ENOUGH: false May 04 11:52:15 volumio volumio[822]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 04 11:52:15 volumio volumio[822]: Error: read ECONNRESET May 04 11:52:15 volumio volumio[822]: at TLSWrap.onStreamRead (internal/stream_base_commons.js:209:20) { May 04 11:52:15 volumio volumio[822]: errno: -104, May 04 11:52:15 volumio volumio[822]: code: 'ECONNRESET', May 04 11:52:15 volumio volumio[822]: syscall: 'read' May 04 11:52:15 volumio volumio[822]: } May 04 11:52:15 volumio volumio[822]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 04 11:52:16 volumio sudo[16339]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-04 11:51 May 04 11:52:16 volumio sudo[16339]: 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"