-- Logs begin at Wed 2025-02-12 17:11:39 +04, end at Sun 2025-02-16 13:25:37 +04. -- Feb 16 13:24:00 pecanpiplus volumio[836]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 16 13:24:00 pecanpiplus volumio[836]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 16 13:24:00 pecanpiplus volumio[836]: info: Discovery: Getting this device information Feb 16 13:24:00 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioGetState Feb 16 13:24:00 pecanpiplus volumio[836]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 16 13:24:00 pecanpiplus volumio[836]: {"status":"play","position":0,"title":"Hotel California","artist":"Eagles","album":"Hell Freezes Over (Remaster 2018)","albumart":"https://resources.tidal.com/images/0c4a6832/8d14/4bac/b69d/7ef39abb1c55/640x640.jpg","uri":"tidal://song/105048155","trackType":"tidal","seek":46105,"duration":432,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"bitrate":null,"random":false,"repeat":false,"repeatSingle":false,"consume":true,"volume":85,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"updatedb":false,"volatile":false,"service":"tidal"} Feb 16 13:24:00 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:24:01 pecanpiplus volumio[836]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 16 13:24:05 pecanpiplus volumio[836]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 16 13:24:09 pecanpiplus volumio[836]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 16 13:24:13 pecanpiplus volumio[836]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 16 13:24:17 pecanpiplus volumio[836]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 16 13:24:21 pecanpiplus volumio[836]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 16 13:24:22 pecanpiplus volumio[836]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Feb 16 13:24:23 pecanpiplus volumio[836]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Feb 16 13:24:24 pecanpiplus volumio[836]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Feb 16 13:24:24 pecanpiplus volumio[836]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Feb 16 13:24:25 pecanpiplus volumio[836]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 16 13:24:29 pecanpiplus volumio[836]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 16 13:24:30 pecanpiplus volumio[836]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 16 13:24:30 pecanpiplus volumio[836]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 16 13:24:30 pecanpiplus volumio[836]: info: Discovery: Getting this device information Feb 16 13:24:30 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioGetState Feb 16 13:24:30 pecanpiplus volumio[836]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 16 13:24:30 pecanpiplus volumio[836]: {"status":"play","position":0,"title":"Hotel California","artist":"Eagles","album":"Hell Freezes Over (Remaster 2018)","albumart":"https://resources.tidal.com/images/0c4a6832/8d14/4bac/b69d/7ef39abb1c55/640x640.jpg","uri":"tidal://song/105048155","trackType":"tidal","seek":46105,"duration":432,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"bitrate":null,"random":false,"repeat":false,"repeatSingle":false,"consume":true,"volume":85,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"updatedb":false,"volatile":false,"service":"tidal"} Feb 16 13:24:30 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:24:33 pecanpiplus volumio[836]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 16 13:24:37 pecanpiplus volumio[836]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 16 13:24:41 pecanpiplus volumio[836]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 16 13:24:45 pecanpiplus volumio[836]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 16 13:24:49 pecanpiplus volumio[836]: info: CoreCommandRouter::executeOnPlugin: networkfs , addShare Feb 16 13:24:49 pecanpiplus volumio[836]: info: Adding a new share Feb 16 13:24:49 pecanpiplus volumio[836]: info: No correspondence found in configuration for share Synology on IP 192.168.68.96 Feb 16 13:24:49 pecanpiplus sudo[2629]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.68.96/volume1/music /mnt/NAS/Synology Feb 16 13:24:49 pecanpiplus sudo[2629]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 13:24:49 pecanpiplus volumio[836]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 16 13:24:49 pecanpiplus kernel: Key type cifs.spnego registered Feb 16 13:24:49 pecanpiplus kernel: Key type cifs.idmap registered Feb 16 13:24:49 pecanpiplus kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Feb 16 13:24:49 pecanpiplus kernel: CIFS: Attempting to mount \\192.168.68.96\volume1 Feb 16 13:24:49 pecanpiplus kernel: CIFS: Status code returned 0xc000006d STATUS_LOGON_FAILURE Feb 16 13:24:49 pecanpiplus kernel: CIFS: VFS: \\192.168.68.96 Send error in SessSetup = -13 Feb 16 13:24:49 pecanpiplus kernel: CIFS: VFS: cifs_mount failed w/return code = -13 Feb 16 13:24:49 pecanpiplus sudo[2629]: pam_unix(sudo:session): session closed for user root Feb 16 13:24:49 pecanpiplus volumio[836]: info: Permission denied for Synology on IP 192.168.68.96 Feb 16 13:24:50 pecanpiplus volumio[836]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Feb 16 13:24:53 pecanpiplus ntpd[803]: Soliciting pool server 2a02:2a50:6::123 Feb 16 13:24:53 pecanpiplus volumio[836]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 16 13:24:57 pecanpiplus volumio[836]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 16 13:25:00 pecanpiplus volumio[836]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 16 13:25:00 pecanpiplus volumio[836]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 16 13:25:00 pecanpiplus volumio[836]: info: Discovery: Getting this device information Feb 16 13:25:00 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioGetState Feb 16 13:25:00 pecanpiplus volumio[836]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 16 13:25:00 pecanpiplus volumio[836]: {"status":"play","position":0,"title":"Hotel California","artist":"Eagles","album":"Hell Freezes Over (Remaster 2018)","albumart":"https://resources.tidal.com/images/0c4a6832/8d14/4bac/b69d/7ef39abb1c55/640x640.jpg","uri":"tidal://song/105048155","trackType":"tidal","seek":46105,"duration":432,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"bitrate":null,"random":false,"repeat":false,"repeatSingle":false,"consume":true,"volume":85,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"updatedb":false,"volatile":false,"service":"tidal"} Feb 16 13:25:00 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:25:01 pecanpiplus volumio[836]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 16 13:25:03 pecanpiplus volumio[836]: info: CoreCommandRouter::executeOnPlugin: networkfs , editShare Feb 16 13:25:03 pecanpiplus volumio[836]: info: Share Synology successfully unmounted Feb 16 13:25:03 pecanpiplus sudo[2737]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=ArikHay,password=ArikoT.533037369,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.68.96/volume1/music /mnt/NAS/Synology Feb 16 13:25:03 pecanpiplus sudo[2737]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 16 13:25:03 pecanpiplus kernel: CIFS: Attempting to mount \\192.168.68.96\volume1 Feb 16 13:25:03 pecanpiplus sudo[2737]: pam_unix(sudo:session): session closed for user root Feb 16 13:25:03 pecanpiplus kernel: CIFS: VFS: BAD_NETWORK_NAME: \\192.168.68.96\volume1 Feb 16 13:25:03 pecanpiplus kernel: CIFS: VFS: cifs_mount failed w/return code = -2 Feb 16 13:25:03 pecanpiplus volumio[836]: info: An error occurred mounting the new share. Rolling back configuration Feb 16 13:25:04 pecanpiplus volumio[836]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Feb 16 13:25:05 pecanpiplus volumio[836]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 16 13:25:09 pecanpiplus volumio[836]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 16 13:25:13 pecanpiplus volumio[836]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 16 13:25:14 pecanpiplus volumio[836]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Feb 16 13:25:14 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand rescan Feb 16 13:25:14 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:25:14 pecanpiplus volumio[836]: info: Feb 16 13:25:14 pecanpiplus volumio[836]: ---------------------------- MPD announces state update: update Feb 16 13:25:14 pecanpiplus volumio[836]: info: sendMpdCommand rescan took 8 milliseconds Feb 16 13:25:14 pecanpiplus volumio[836]: info: ControllerMpd::getState Feb 16 13:25:14 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:25:14 pecanpiplus volumio[836]: info: sendMpdCommand status took 7 milliseconds Feb 16 13:25:14 pecanpiplus volumio[836]: info: sendMpdCommand status took 2 milliseconds Feb 16 13:25:14 pecanpiplus volumio[836]: verbose: ControllerMpd::parseState Feb 16 13:25:14 pecanpiplus volumio[836]: info: Command Router : Notfying DB Updatetrue Feb 16 13:25:14 pecanpiplus volumio[836]: verbose: ControllerMpd::parseState Feb 16 13:25:14 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 16 13:25:14 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:25:14 pecanpiplus volumio[836]: info: Feb 16 13:25:14 pecanpiplus volumio[836]: ---------------------------- MPD announces state update: update Feb 16 13:25:14 pecanpiplus volumio[836]: info: ControllerMpd::getState Feb 16 13:25:14 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:25:14 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:25:14 pecanpiplus volumio[836]: info: Feb 16 13:25:14 pecanpiplus volumio[836]: ---------------------------- MPD announces state update: update Feb 16 13:25:14 pecanpiplus volumio[836]: info: ControllerMpd::getState Feb 16 13:25:14 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:25:14 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:25:14 pecanpiplus volumio[836]: info: Feb 16 13:25:14 pecanpiplus volumio[836]: ---------------------------- MPD announces state update: update Feb 16 13:25:14 pecanpiplus volumio[836]: info: ControllerMpd::getState Feb 16 13:25:14 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:25:14 pecanpiplus volumio[836]: info: MPD Database updated - AlbumList cache refreshed Feb 16 13:25:14 pecanpiplus volumio[836]: info: Feb 16 13:25:14 pecanpiplus volumio[836]: ---------------------------- MPD announces state update: database Feb 16 13:25:14 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:25:14 pecanpiplus volumio[836]: info: Feb 16 13:25:14 pecanpiplus volumio[836]: ---------------------------- MPD announces state update: update Feb 16 13:25:14 pecanpiplus volumio[836]: info: ControllerMpd::getState Feb 16 13:25:14 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:25:14 pecanpiplus volumio[836]: info: ControllerMpd::getState Feb 16 13:25:14 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:25:14 pecanpiplus volumio[836]: info: sendMpdCommand playlistinfo took 30 milliseconds Feb 16 13:25:14 pecanpiplus volumio[836]: info: sendMpdCommand status took 15 milliseconds Feb 16 13:25:14 pecanpiplus volumio[836]: info: sendMpdCommand status took 13 milliseconds Feb 16 13:25:14 pecanpiplus volumio[836]: info: sendMpdCommand status took 12 milliseconds Feb 16 13:25:14 pecanpiplus volumio[836]: info: sendMpdCommand status took 10 milliseconds Feb 16 13:25:14 pecanpiplus volumio[836]: info: sendMpdCommand status took 10 milliseconds Feb 16 13:25:14 pecanpiplus volumio[836]: info: sendMpdCommand status took 9 milliseconds Feb 16 13:25:14 pecanpiplus volumio[836]: verbose: ControllerMpd::parseTrackInfo Feb 16 13:25:14 pecanpiplus volumio[836]: verbose: ControllerMpd::parseState Feb 16 13:25:14 pecanpiplus volumio[836]: info: Command Router : Notfying DB Updatefalse Feb 16 13:25:14 pecanpiplus volumio[836]: info: CoreCommandRouter::Close All Modals sent Feb 16 13:25:14 pecanpiplus volumio[836]: verbose: ControllerMpd::parseState Feb 16 13:25:14 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 16 13:25:14 pecanpiplus volumio[836]: verbose: ControllerMpd::parseState Feb 16 13:25:14 pecanpiplus volumio[836]: info: Command Router : Notfying DB Updatefalse Feb 16 13:25:14 pecanpiplus volumio[836]: info: CoreCommandRouter::Close All Modals sent Feb 16 13:25:14 pecanpiplus volumio[836]: verbose: ControllerMpd::parseState Feb 16 13:25:14 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 16 13:25:14 pecanpiplus volumio[836]: verbose: ControllerMpd::parseState Feb 16 13:25:14 pecanpiplus volumio[836]: info: Command Router : Notfying DB Updatefalse Feb 16 13:25:14 pecanpiplus volumio[836]: info: CoreCommandRouter::Close All Modals sent Feb 16 13:25:14 pecanpiplus volumio[836]: verbose: ControllerMpd::parseState Feb 16 13:25:14 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 16 13:25:14 pecanpiplus volumio[836]: info: ControllerMpd::pushState Feb 16 13:25:14 pecanpiplus volumio[836]: info: CoreCommandRouter::servicePushState Feb 16 13:25:14 pecanpiplus volumio[836]: info: CorePlayQueue::getTrack 0 Feb 16 13:25:14 pecanpiplus volumio[836]: verbose: STATE SERVICE {"status":"play","position":0,"seek":123459,"duration":432,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"926 Kbps","isStreaming":false,"title":"105048155&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/105048155&soundQuality=HI_RES","trackType":"tidal"} Feb 16 13:25:14 pecanpiplus volumio[836]: verbose: CURRENT POSITION 0 Feb 16 13:25:14 pecanpiplus volumio[836]: info: CoreStateMachine::syncState stateService play Feb 16 13:25:14 pecanpiplus volumio[836]: info: CoreStateMachine::syncState currentStatus play Feb 16 13:25:14 pecanpiplus volumio[836]: info: Received an update from plugin. extracting info from payload Feb 16 13:25:14 pecanpiplus volumio[836]: info: CoreStateMachine::pushState Feb 16 13:25:14 pecanpiplus volumio[836]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 13:25:14 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioPushState Feb 16 13:25:14 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output update for this device Feb 16 13:25:14 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output Feb 16 13:25:14 pecanpiplus volumio[836]: info: CoreStateMachine::pushState Feb 16 13:25:14 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioPushState Feb 16 13:25:14 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output update for this device Feb 16 13:25:14 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output Feb 16 13:25:14 pecanpiplus volumio[836]: info: ------------------------------ 470ms Feb 16 13:25:14 pecanpiplus volumio[836]: info: MPD Database updated - AlbumList cache refreshed Feb 16 13:25:14 pecanpiplus volumio[836]: info: Feb 16 13:25:14 pecanpiplus volumio[836]: ---------------------------- MPD announces state update: database Feb 16 13:25:14 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:25:14 pecanpiplus volumio[836]: info: Feb 16 13:25:14 pecanpiplus volumio[836]: ---------------------------- MPD announces state update: update Feb 16 13:25:14 pecanpiplus volumio[836]: info: ControllerMpd::getState Feb 16 13:25:14 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:25:14 pecanpiplus volumio[836]: info: ControllerMpd::getState Feb 16 13:25:14 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:25:14 pecanpiplus volumio[836]: info: MPD Database updated - AlbumList cache refreshed Feb 16 13:25:14 pecanpiplus volumio[836]: info: Feb 16 13:25:14 pecanpiplus volumio[836]: ---------------------------- MPD announces state update: database Feb 16 13:25:14 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:25:14 pecanpiplus volumio[836]: info: Feb 16 13:25:14 pecanpiplus volumio[836]: ---------------------------- MPD announces state update: update Feb 16 13:25:14 pecanpiplus volumio[836]: info: ControllerMpd::getState Feb 16 13:25:14 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:25:14 pecanpiplus volumio[836]: info: ControllerMpd::getState Feb 16 13:25:14 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:25:14 pecanpiplus volumio[836]: info: MPD Database updated - AlbumList cache refreshed Feb 16 13:25:14 pecanpiplus volumio[836]: info: Feb 16 13:25:14 pecanpiplus volumio[836]: ---------------------------- MPD announces state update: database Feb 16 13:25:14 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:25:14 pecanpiplus volumio[836]: info: Feb 16 13:25:14 pecanpiplus volumio[836]: ---------------------------- MPD announces state update: update Feb 16 13:25:14 pecanpiplus volumio[836]: info: ControllerMpd::getState Feb 16 13:25:14 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:25:14 pecanpiplus volumio[836]: info: ControllerMpd::getState Feb 16 13:25:14 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:25:14 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:25:14 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:25:14 pecanpiplus volumio[836]: info: sendMpdCommand status took 363 milliseconds Feb 16 13:25:14 pecanpiplus volumio[836]: info: sendMpdCommand status took 363 milliseconds Feb 16 13:25:14 pecanpiplus volumio[836]: info: sendMpdCommand status took 362 milliseconds Feb 16 13:25:14 pecanpiplus volumio[836]: info: sendMpdCommand playlistinfo took 285 milliseconds Feb 16 13:25:14 pecanpiplus volumio[836]: info: sendMpdCommand playlistinfo took 244 milliseconds Feb 16 13:25:14 pecanpiplus volumio[836]: info: sendMpdCommand playlistinfo took 203 milliseconds Feb 16 13:25:14 pecanpiplus volumio[836]: info: sendMpdCommand status took 49 milliseconds Feb 16 13:25:14 pecanpiplus volumio[836]: info: sendMpdCommand status took 48 milliseconds Feb 16 13:25:14 pecanpiplus volumio[836]: info: sendMpdCommand status took 47 milliseconds Feb 16 13:25:14 pecanpiplus volumio[836]: info: sendMpdCommand status took 46 milliseconds Feb 16 13:25:14 pecanpiplus volumio[836]: info: sendMpdCommand status took 46 milliseconds Feb 16 13:25:14 pecanpiplus volumio[836]: info: sendMpdCommand status took 44 milliseconds Feb 16 13:25:14 pecanpiplus volumio[836]: info: sendMpdCommand status took 44 milliseconds Feb 16 13:25:14 pecanpiplus volumio[836]: info: sendMpdCommand status took 43 milliseconds Feb 16 13:25:14 pecanpiplus volumio[836]: verbose: ControllerMpd::parseState Feb 16 13:25:14 pecanpiplus volumio[836]: info: Command Router : Notfying DB Updatefalse Feb 16 13:25:14 pecanpiplus volumio[836]: info: CoreCommandRouter::Close All Modals sent Feb 16 13:25:14 pecanpiplus volumio[836]: verbose: ControllerMpd::parseState Feb 16 13:25:14 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 16 13:25:14 pecanpiplus volumio[836]: verbose: ControllerMpd::parseState Feb 16 13:25:14 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 16 13:25:14 pecanpiplus volumio[836]: verbose: ControllerMpd::parseTrackInfo Feb 16 13:25:14 pecanpiplus volumio[836]: verbose: ControllerMpd::parseTrackInfo Feb 16 13:25:14 pecanpiplus volumio[836]: verbose: ControllerMpd::parseTrackInfo Feb 16 13:25:14 pecanpiplus volumio[836]: verbose: ControllerMpd::parseState Feb 16 13:25:14 pecanpiplus volumio[836]: info: Command Router : Notfying DB Updatefalse Feb 16 13:25:14 pecanpiplus volumio[836]: info: CoreCommandRouter::Close All Modals sent Feb 16 13:25:14 pecanpiplus volumio[836]: verbose: ControllerMpd::parseState Feb 16 13:25:14 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 16 13:25:14 pecanpiplus volumio[836]: verbose: ControllerMpd::parseState Feb 16 13:25:14 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 16 13:25:14 pecanpiplus volumio[836]: verbose: ControllerMpd::parseState Feb 16 13:25:14 pecanpiplus volumio[836]: info: Command Router : Notfying DB Updatefalse Feb 16 13:25:14 pecanpiplus volumio[836]: info: CoreCommandRouter::Close All Modals sent Feb 16 13:25:14 pecanpiplus volumio[836]: verbose: ControllerMpd::parseState Feb 16 13:25:14 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 16 13:25:14 pecanpiplus volumio[836]: verbose: ControllerMpd::parseState Feb 16 13:25:14 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 16 13:25:14 pecanpiplus volumio[836]: verbose: ControllerMpd::parseState Feb 16 13:25:14 pecanpiplus volumio[836]: info: Command Router : Notfying DB Updatefalse Feb 16 13:25:14 pecanpiplus volumio[836]: info: CoreCommandRouter::Close All Modals sent Feb 16 13:25:14 pecanpiplus volumio[836]: verbose: ControllerMpd::parseState Feb 16 13:25:14 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 16 13:25:14 pecanpiplus volumio[836]: verbose: ControllerMpd::parseState Feb 16 13:25:14 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 16 13:25:14 pecanpiplus volumio[836]: info: ControllerMpd::pushState Feb 16 13:25:14 pecanpiplus volumio[836]: info: CoreCommandRouter::servicePushState Feb 16 13:25:14 pecanpiplus volumio[836]: info: CorePlayQueue::getTrack 0 Feb 16 13:25:14 pecanpiplus volumio[836]: verbose: STATE SERVICE {"status":"play","position":0,"seek":123711,"duration":432,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"898 Kbps","isStreaming":false,"title":"105048155&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/105048155&soundQuality=HI_RES","trackType":"tidal"} Feb 16 13:25:14 pecanpiplus volumio[836]: verbose: CURRENT POSITION 0 Feb 16 13:25:14 pecanpiplus volumio[836]: info: CoreStateMachine::syncState stateService play Feb 16 13:25:14 pecanpiplus volumio[836]: info: CoreStateMachine::syncState currentStatus play Feb 16 13:25:14 pecanpiplus volumio[836]: info: Received an update from plugin. extracting info from payload Feb 16 13:25:14 pecanpiplus volumio[836]: info: CoreStateMachine::pushState Feb 16 13:25:14 pecanpiplus volumio[836]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 13:25:14 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioPushState Feb 16 13:25:14 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output update for this device Feb 16 13:25:14 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output Feb 16 13:25:14 pecanpiplus volumio[836]: info: CoreStateMachine::pushState Feb 16 13:25:14 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioPushState Feb 16 13:25:14 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output update for this device Feb 16 13:25:14 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output Feb 16 13:25:14 pecanpiplus volumio[836]: info: ControllerMpd::pushState Feb 16 13:25:14 pecanpiplus volumio[836]: info: CoreCommandRouter::servicePushState Feb 16 13:25:14 pecanpiplus volumio[836]: info: CorePlayQueue::getTrack 0 Feb 16 13:25:14 pecanpiplus volumio[836]: verbose: STATE SERVICE {"status":"play","position":0,"seek":123711,"duration":432,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"898 Kbps","isStreaming":false,"title":"105048155&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/105048155&soundQuality=HI_RES","trackType":"tidal"} Feb 16 13:25:14 pecanpiplus volumio[836]: verbose: CURRENT POSITION 0 Feb 16 13:25:14 pecanpiplus volumio[836]: info: CoreStateMachine::syncState stateService play Feb 16 13:25:14 pecanpiplus volumio[836]: info: CoreStateMachine::syncState currentStatus play Feb 16 13:25:14 pecanpiplus volumio[836]: info: Received an update from plugin. extracting info from payload Feb 16 13:25:14 pecanpiplus volumio[836]: info: CoreStateMachine::pushState Feb 16 13:25:14 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioPushState Feb 16 13:25:14 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output update for this device Feb 16 13:25:14 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output Feb 16 13:25:14 pecanpiplus volumio[836]: info: CoreStateMachine::pushState Feb 16 13:25:14 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioPushState Feb 16 13:25:14 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output update for this device Feb 16 13:25:14 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output Feb 16 13:25:14 pecanpiplus volumio[836]: info: ControllerMpd::pushState Feb 16 13:25:14 pecanpiplus volumio[836]: info: CoreCommandRouter::servicePushState Feb 16 13:25:14 pecanpiplus volumio[836]: info: CorePlayQueue::getTrack 0 Feb 16 13:25:14 pecanpiplus volumio[836]: verbose: STATE SERVICE {"status":"play","position":0,"seek":123711,"duration":432,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"898 Kbps","isStreaming":false,"title":"105048155&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/105048155&soundQuality=HI_RES","trackType":"tidal"} Feb 16 13:25:14 pecanpiplus volumio[836]: verbose: CURRENT POSITION 0 Feb 16 13:25:14 pecanpiplus volumio[836]: info: CoreStateMachine::syncState stateService play Feb 16 13:25:14 pecanpiplus volumio[836]: info: CoreStateMachine::syncState currentStatus play Feb 16 13:25:14 pecanpiplus volumio[836]: info: Received an update from plugin. extracting info from payload Feb 16 13:25:14 pecanpiplus volumio[836]: info: CoreStateMachine::pushState Feb 16 13:25:14 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioPushState Feb 16 13:25:14 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output update for this device Feb 16 13:25:14 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output Feb 16 13:25:14 pecanpiplus volumio[836]: info: CoreStateMachine::pushState Feb 16 13:25:14 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioPushState Feb 16 13:25:14 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output update for this device Feb 16 13:25:14 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output Feb 16 13:25:15 pecanpiplus volumio[836]: info: ------------------------------ 887ms Feb 16 13:25:15 pecanpiplus volumio[836]: info: ------------------------------ 884ms Feb 16 13:25:15 pecanpiplus volumio[836]: info: ------------------------------ 883ms Feb 16 13:25:15 pecanpiplus volumio[836]: info: sendMpdCommand playlistinfo took 438 milliseconds Feb 16 13:25:15 pecanpiplus volumio[836]: info: sendMpdCommand playlistinfo took 438 milliseconds Feb 16 13:25:15 pecanpiplus volumio[836]: info: sendMpdCommand playlistinfo took 378 milliseconds Feb 16 13:25:15 pecanpiplus volumio[836]: info: sendMpdCommand playlistinfo took 378 milliseconds Feb 16 13:25:15 pecanpiplus volumio[836]: info: sendMpdCommand playlistinfo took 325 milliseconds Feb 16 13:25:15 pecanpiplus volumio[836]: info: sendMpdCommand playlistinfo took 324 milliseconds Feb 16 13:25:15 pecanpiplus volumio[836]: info: sendMpdCommand playlistinfo took 284 milliseconds Feb 16 13:25:15 pecanpiplus volumio[836]: info: sendMpdCommand playlistinfo took 284 milliseconds Feb 16 13:25:15 pecanpiplus volumio[836]: verbose: ControllerMpd::parseTrackInfo Feb 16 13:25:15 pecanpiplus volumio[836]: verbose: ControllerMpd::parseTrackInfo Feb 16 13:25:15 pecanpiplus volumio[836]: verbose: ControllerMpd::parseTrackInfo Feb 16 13:25:15 pecanpiplus volumio[836]: verbose: ControllerMpd::parseTrackInfo Feb 16 13:25:15 pecanpiplus volumio[836]: verbose: ControllerMpd::parseTrackInfo Feb 16 13:25:15 pecanpiplus volumio[836]: verbose: ControllerMpd::parseTrackInfo Feb 16 13:25:15 pecanpiplus volumio[836]: verbose: ControllerMpd::parseTrackInfo Feb 16 13:25:15 pecanpiplus volumio[836]: verbose: ControllerMpd::parseTrackInfo Feb 16 13:25:15 pecanpiplus volumio[836]: info: ControllerMpd::pushState Feb 16 13:25:15 pecanpiplus volumio[836]: info: CoreCommandRouter::servicePushState Feb 16 13:25:15 pecanpiplus volumio[836]: info: CorePlayQueue::getTrack 0 Feb 16 13:25:15 pecanpiplus volumio[836]: verbose: STATE SERVICE {"status":"play","position":0,"seek":123711,"duration":432,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"898 Kbps","isStreaming":false,"title":"105048155&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/105048155&soundQuality=HI_RES","trackType":"tidal"} Feb 16 13:25:15 pecanpiplus volumio[836]: verbose: CURRENT POSITION 0 Feb 16 13:25:15 pecanpiplus volumio[836]: info: CoreStateMachine::syncState stateService play Feb 16 13:25:15 pecanpiplus volumio[836]: info: CoreStateMachine::syncState currentStatus play Feb 16 13:25:15 pecanpiplus volumio[836]: info: Received an update from plugin. extracting info from payload Feb 16 13:25:15 pecanpiplus volumio[836]: info: CoreStateMachine::pushState Feb 16 13:25:15 pecanpiplus volumio[836]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 13:25:15 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioPushState Feb 16 13:25:15 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output update for this device Feb 16 13:25:15 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output Feb 16 13:25:15 pecanpiplus volumio[836]: info: CoreStateMachine::pushState Feb 16 13:25:15 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioPushState Feb 16 13:25:15 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output update for this device Feb 16 13:25:15 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output Feb 16 13:25:15 pecanpiplus volumio[836]: info: ControllerMpd::pushState Feb 16 13:25:15 pecanpiplus volumio[836]: info: CoreCommandRouter::servicePushState Feb 16 13:25:15 pecanpiplus volumio[836]: info: CorePlayQueue::getTrack 0 Feb 16 13:25:15 pecanpiplus volumio[836]: verbose: STATE SERVICE {"status":"play","position":0,"seek":123711,"duration":432,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"898 Kbps","isStreaming":false,"title":"105048155&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/105048155&soundQuality=HI_RES","trackType":"tidal"} Feb 16 13:25:15 pecanpiplus volumio[836]: verbose: CURRENT POSITION 0 Feb 16 13:25:15 pecanpiplus volumio[836]: info: CoreStateMachine::syncState stateService play Feb 16 13:25:15 pecanpiplus volumio[836]: info: CoreStateMachine::syncState currentStatus play Feb 16 13:25:15 pecanpiplus volumio[836]: info: Received an update from plugin. extracting info from payload Feb 16 13:25:15 pecanpiplus volumio[836]: info: CoreStateMachine::pushState Feb 16 13:25:15 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioPushState Feb 16 13:25:15 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output update for this device Feb 16 13:25:15 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output Feb 16 13:25:15 pecanpiplus volumio[836]: info: CoreStateMachine::pushState Feb 16 13:25:15 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioPushState Feb 16 13:25:15 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output update for this device Feb 16 13:25:15 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output Feb 16 13:25:15 pecanpiplus volumio[836]: info: ControllerMpd::pushState Feb 16 13:25:15 pecanpiplus volumio[836]: info: CoreCommandRouter::servicePushState Feb 16 13:25:15 pecanpiplus volumio[836]: info: CorePlayQueue::getTrack 0 Feb 16 13:25:15 pecanpiplus volumio[836]: verbose: STATE SERVICE {"status":"play","position":0,"seek":124077,"duration":432,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"863 Kbps","isStreaming":false,"title":"105048155&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/105048155&soundQuality=HI_RES","trackType":"tidal"} Feb 16 13:25:15 pecanpiplus volumio[836]: verbose: CURRENT POSITION 0 Feb 16 13:25:15 pecanpiplus volumio[836]: info: CoreStateMachine::syncState stateService play Feb 16 13:25:15 pecanpiplus volumio[836]: info: CoreStateMachine::syncState currentStatus play Feb 16 13:25:15 pecanpiplus volumio[836]: info: Received an update from plugin. extracting info from payload Feb 16 13:25:15 pecanpiplus volumio[836]: info: CoreStateMachine::pushState Feb 16 13:25:15 pecanpiplus volumio[836]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 13:25:15 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioPushState Feb 16 13:25:15 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output update for this device Feb 16 13:25:15 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output Feb 16 13:25:15 pecanpiplus volumio[836]: info: CoreStateMachine::pushState Feb 16 13:25:15 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioPushState Feb 16 13:25:15 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output update for this device Feb 16 13:25:15 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output Feb 16 13:25:15 pecanpiplus volumio[836]: info: ControllerMpd::pushState Feb 16 13:25:15 pecanpiplus volumio[836]: info: CoreCommandRouter::servicePushState Feb 16 13:25:15 pecanpiplus volumio[836]: info: CorePlayQueue::getTrack 0 Feb 16 13:25:15 pecanpiplus volumio[836]: verbose: STATE SERVICE {"status":"play","position":0,"seek":124077,"duration":432,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"863 Kbps","isStreaming":false,"title":"105048155&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/105048155&soundQuality=HI_RES","trackType":"tidal"} Feb 16 13:25:15 pecanpiplus volumio[836]: verbose: CURRENT POSITION 0 Feb 16 13:25:15 pecanpiplus volumio[836]: info: CoreStateMachine::syncState stateService play Feb 16 13:25:15 pecanpiplus volumio[836]: info: CoreStateMachine::syncState currentStatus play Feb 16 13:25:15 pecanpiplus volumio[836]: info: Received an update from plugin. extracting info from payload Feb 16 13:25:15 pecanpiplus volumio[836]: info: CoreStateMachine::pushState Feb 16 13:25:15 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioPushState Feb 16 13:25:15 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output update for this device Feb 16 13:25:15 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output Feb 16 13:25:15 pecanpiplus volumio[836]: info: CoreStateMachine::pushState Feb 16 13:25:15 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioPushState Feb 16 13:25:15 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output update for this device Feb 16 13:25:15 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output Feb 16 13:25:15 pecanpiplus volumio[836]: info: ControllerMpd::pushState Feb 16 13:25:15 pecanpiplus volumio[836]: info: CoreCommandRouter::servicePushState Feb 16 13:25:15 pecanpiplus volumio[836]: info: CorePlayQueue::getTrack 0 Feb 16 13:25:15 pecanpiplus volumio[836]: verbose: STATE SERVICE {"status":"play","position":0,"seek":124077,"duration":432,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"863 Kbps","isStreaming":false,"title":"105048155&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/105048155&soundQuality=HI_RES","trackType":"tidal"} Feb 16 13:25:15 pecanpiplus volumio[836]: verbose: CURRENT POSITION 0 Feb 16 13:25:15 pecanpiplus volumio[836]: info: CoreStateMachine::syncState stateService play Feb 16 13:25:15 pecanpiplus volumio[836]: info: CoreStateMachine::syncState currentStatus play Feb 16 13:25:15 pecanpiplus volumio[836]: info: Received an update from plugin. extracting info from payload Feb 16 13:25:15 pecanpiplus volumio[836]: info: CoreStateMachine::pushState Feb 16 13:25:15 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioPushState Feb 16 13:25:15 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output update for this device Feb 16 13:25:15 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output Feb 16 13:25:15 pecanpiplus volumio[836]: info: CoreStateMachine::pushState Feb 16 13:25:15 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioPushState Feb 16 13:25:15 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output update for this device Feb 16 13:25:15 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output Feb 16 13:25:15 pecanpiplus volumio[836]: info: ControllerMpd::pushState Feb 16 13:25:15 pecanpiplus volumio[836]: info: CoreCommandRouter::servicePushState Feb 16 13:25:15 pecanpiplus volumio[836]: info: CorePlayQueue::getTrack 0 Feb 16 13:25:15 pecanpiplus volumio[836]: verbose: STATE SERVICE {"status":"play","position":0,"seek":124077,"duration":432,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"863 Kbps","isStreaming":false,"title":"105048155&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/105048155&soundQuality=HI_RES","trackType":"tidal"} Feb 16 13:25:15 pecanpiplus volumio[836]: verbose: CURRENT POSITION 0 Feb 16 13:25:15 pecanpiplus volumio[836]: info: CoreStateMachine::syncState stateService play Feb 16 13:25:15 pecanpiplus volumio[836]: info: CoreStateMachine::syncState currentStatus play Feb 16 13:25:15 pecanpiplus volumio[836]: info: Received an update from plugin. extracting info from payload Feb 16 13:25:15 pecanpiplus volumio[836]: info: CoreStateMachine::pushState Feb 16 13:25:15 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioPushState Feb 16 13:25:15 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output update for this device Feb 16 13:25:15 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output Feb 16 13:25:15 pecanpiplus volumio[836]: info: CoreStateMachine::pushState Feb 16 13:25:15 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioPushState Feb 16 13:25:15 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output update for this device Feb 16 13:25:15 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output Feb 16 13:25:15 pecanpiplus volumio[836]: info: ControllerMpd::pushState Feb 16 13:25:15 pecanpiplus volumio[836]: info: CoreCommandRouter::servicePushState Feb 16 13:25:15 pecanpiplus volumio[836]: info: CorePlayQueue::getTrack 0 Feb 16 13:25:15 pecanpiplus volumio[836]: verbose: STATE SERVICE {"status":"play","position":0,"seek":124077,"duration":432,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"863 Kbps","isStreaming":false,"title":"105048155&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/105048155&soundQuality=HI_RES","trackType":"tidal"} Feb 16 13:25:15 pecanpiplus volumio[836]: verbose: CURRENT POSITION 0 Feb 16 13:25:15 pecanpiplus volumio[836]: info: CoreStateMachine::syncState stateService play Feb 16 13:25:15 pecanpiplus volumio[836]: info: CoreStateMachine::syncState currentStatus play Feb 16 13:25:15 pecanpiplus volumio[836]: info: Received an update from plugin. extracting info from payload Feb 16 13:25:15 pecanpiplus volumio[836]: info: CoreStateMachine::pushState Feb 16 13:25:15 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioPushState Feb 16 13:25:15 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output update for this device Feb 16 13:25:15 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output Feb 16 13:25:15 pecanpiplus volumio[836]: info: CoreStateMachine::pushState Feb 16 13:25:15 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioPushState Feb 16 13:25:15 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output update for this device Feb 16 13:25:15 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output Feb 16 13:25:15 pecanpiplus volumio[836]: info: ControllerMpd::pushState Feb 16 13:25:15 pecanpiplus volumio[836]: info: CoreCommandRouter::servicePushState Feb 16 13:25:15 pecanpiplus volumio[836]: info: CorePlayQueue::getTrack 0 Feb 16 13:25:15 pecanpiplus volumio[836]: verbose: STATE SERVICE {"status":"play","position":0,"seek":124077,"duration":432,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"863 Kbps","isStreaming":false,"title":"105048155&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/105048155&soundQuality=HI_RES","trackType":"tidal"} Feb 16 13:25:15 pecanpiplus volumio[836]: verbose: CURRENT POSITION 0 Feb 16 13:25:15 pecanpiplus volumio[836]: info: CoreStateMachine::syncState stateService play Feb 16 13:25:15 pecanpiplus volumio[836]: info: CoreStateMachine::syncState currentStatus play Feb 16 13:25:15 pecanpiplus volumio[836]: info: Received an update from plugin. extracting info from payload Feb 16 13:25:15 pecanpiplus volumio[836]: info: CoreStateMachine::pushState Feb 16 13:25:15 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioPushState Feb 16 13:25:15 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output update for this device Feb 16 13:25:15 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output Feb 16 13:25:15 pecanpiplus volumio[836]: info: CoreStateMachine::pushState Feb 16 13:25:15 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioPushState Feb 16 13:25:15 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output update for this device Feb 16 13:25:15 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output Feb 16 13:25:15 pecanpiplus volumio[836]: info: ------------------------------ 1380ms Feb 16 13:25:15 pecanpiplus volumio[836]: info: ------------------------------ 1380ms Feb 16 13:25:15 pecanpiplus volumio[836]: info: ------------------------------ 1064ms Feb 16 13:25:15 pecanpiplus volumio[836]: info: ------------------------------ 1065ms Feb 16 13:25:15 pecanpiplus volumio[836]: info: ------------------------------ 1063ms Feb 16 13:25:15 pecanpiplus volumio[836]: info: ------------------------------ 1063ms Feb 16 13:25:15 pecanpiplus volumio[836]: info: ------------------------------ 1061ms Feb 16 13:25:15 pecanpiplus volumio[836]: info: ------------------------------ 1062ms Feb 16 13:25:15 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:25:15 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:25:15 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:25:15 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:25:15 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:25:15 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:25:15 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:25:15 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:25:15 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:25:15 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:25:15 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:25:15 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:25:15 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:25:15 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:25:15 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:25:15 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:25:15 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:25:15 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:25:15 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:25:15 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:25:15 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:25:15 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:25:15 pecanpiplus volumio[836]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Feb 16 13:25:15 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand update Feb 16 13:25:15 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:25:15 pecanpiplus volumio[836]: info: Feb 16 13:25:15 pecanpiplus volumio[836]: ---------------------------- MPD announces state update: update Feb 16 13:25:15 pecanpiplus volumio[836]: info: sendMpdCommand update took 17 milliseconds Feb 16 13:25:15 pecanpiplus volumio[836]: info: ControllerMpd::getState Feb 16 13:25:15 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:25:15 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:25:15 pecanpiplus volumio[836]: info: Feb 16 13:25:15 pecanpiplus volumio[836]: ---------------------------- MPD announces state update: update Feb 16 13:25:15 pecanpiplus volumio[836]: info: ControllerMpd::getState Feb 16 13:25:15 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:25:15 pecanpiplus volumio[836]: info: Feb 16 13:25:15 pecanpiplus volumio[836]: ---------------------------- MPD announces state update: update Feb 16 13:25:15 pecanpiplus volumio[836]: info: ControllerMpd::getState Feb 16 13:25:15 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:25:15 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:25:15 pecanpiplus volumio[836]: info: Feb 16 13:25:15 pecanpiplus volumio[836]: ---------------------------- MPD announces state update: update Feb 16 13:25:15 pecanpiplus volumio[836]: info: ControllerMpd::getState Feb 16 13:25:15 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:25:15 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:25:15 pecanpiplus volumio[836]: info: Feb 16 13:25:15 pecanpiplus volumio[836]: ---------------------------- MPD announces state update: update Feb 16 13:25:15 pecanpiplus volumio[836]: info: ControllerMpd::getState Feb 16 13:25:15 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:25:15 pecanpiplus volumio[836]: info: sendMpdCommand status took 18 milliseconds Feb 16 13:25:15 pecanpiplus volumio[836]: info: sendMpdCommand status took 16 milliseconds Feb 16 13:25:15 pecanpiplus volumio[836]: info: sendMpdCommand status took 15 milliseconds Feb 16 13:25:15 pecanpiplus volumio[836]: info: sendMpdCommand status took 15 milliseconds Feb 16 13:25:15 pecanpiplus volumio[836]: info: sendMpdCommand status took 13 milliseconds Feb 16 13:25:15 pecanpiplus volumio[836]: info: sendMpdCommand status took 12 milliseconds Feb 16 13:25:15 pecanpiplus volumio[836]: info: sendMpdCommand status took 11 milliseconds Feb 16 13:25:15 pecanpiplus volumio[836]: info: sendMpdCommand status took 8 milliseconds Feb 16 13:25:15 pecanpiplus volumio[836]: verbose: ControllerMpd::parseState Feb 16 13:25:15 pecanpiplus volumio[836]: info: Command Router : Notfying DB Updatefalse Feb 16 13:25:15 pecanpiplus volumio[836]: info: CoreCommandRouter::Close All Modals sent Feb 16 13:25:15 pecanpiplus volumio[836]: verbose: ControllerMpd::parseState Feb 16 13:25:15 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 16 13:25:15 pecanpiplus volumio[836]: verbose: ControllerMpd::parseState Feb 16 13:25:15 pecanpiplus volumio[836]: info: Command Router : Notfying DB Updatefalse Feb 16 13:25:15 pecanpiplus volumio[836]: info: CoreCommandRouter::Close All Modals sent Feb 16 13:25:15 pecanpiplus volumio[836]: verbose: ControllerMpd::parseState Feb 16 13:25:15 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 16 13:25:15 pecanpiplus volumio[836]: verbose: ControllerMpd::parseState Feb 16 13:25:15 pecanpiplus volumio[836]: info: Command Router : Notfying DB Updatefalse Feb 16 13:25:15 pecanpiplus volumio[836]: info: CoreCommandRouter::Close All Modals sent Feb 16 13:25:15 pecanpiplus volumio[836]: verbose: ControllerMpd::parseState Feb 16 13:25:15 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 16 13:25:15 pecanpiplus volumio[836]: verbose: ControllerMpd::parseState Feb 16 13:25:15 pecanpiplus volumio[836]: info: Command Router : Notfying DB Updatefalse Feb 16 13:25:15 pecanpiplus volumio[836]: info: CoreCommandRouter::Close All Modals sent Feb 16 13:25:15 pecanpiplus volumio[836]: verbose: ControllerMpd::parseState Feb 16 13:25:15 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 16 13:25:16 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:25:16 pecanpiplus volumio[836]: info: Feb 16 13:25:16 pecanpiplus volumio[836]: ---------------------------- MPD announces state update: update Feb 16 13:25:16 pecanpiplus volumio[836]: info: ControllerMpd::getState Feb 16 13:25:16 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:25:16 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:25:16 pecanpiplus volumio[836]: info: Feb 16 13:25:16 pecanpiplus volumio[836]: ---------------------------- MPD announces state update: update Feb 16 13:25:16 pecanpiplus volumio[836]: info: ControllerMpd::getState Feb 16 13:25:16 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:25:16 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:25:16 pecanpiplus volumio[836]: info: Feb 16 13:25:16 pecanpiplus volumio[836]: ---------------------------- MPD announces state update: update Feb 16 13:25:16 pecanpiplus volumio[836]: info: ControllerMpd::getState Feb 16 13:25:16 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:25:16 pecanpiplus volumio[836]: info: sendMpdCommand status took 226 milliseconds Feb 16 13:25:16 pecanpiplus volumio[836]: info: sendMpdCommand status took 224 milliseconds Feb 16 13:25:16 pecanpiplus volumio[836]: info: sendMpdCommand playlistinfo took 169 milliseconds Feb 16 13:25:16 pecanpiplus volumio[836]: info: sendMpdCommand playlistinfo took 132 milliseconds Feb 16 13:25:16 pecanpiplus volumio[836]: info: sendMpdCommand playlistinfo took 95 milliseconds Feb 16 13:25:16 pecanpiplus volumio[836]: info: sendMpdCommand playlistinfo took 59 milliseconds Feb 16 13:25:16 pecanpiplus volumio[836]: info: sendMpdCommand status took 10 milliseconds Feb 16 13:25:16 pecanpiplus volumio[836]: info: sendMpdCommand status took 9 milliseconds Feb 16 13:25:16 pecanpiplus volumio[836]: info: sendMpdCommand status took 8 milliseconds Feb 16 13:25:16 pecanpiplus volumio[836]: info: sendMpdCommand status took 7 milliseconds Feb 16 13:25:16 pecanpiplus volumio[836]: info: sendMpdCommand status took 7 milliseconds Feb 16 13:25:16 pecanpiplus volumio[836]: info: sendMpdCommand status took 6 milliseconds Feb 16 13:25:16 pecanpiplus volumio[836]: verbose: ControllerMpd::parseState Feb 16 13:25:16 pecanpiplus volumio[836]: info: Command Router : Notfying DB Updatefalse Feb 16 13:25:16 pecanpiplus volumio[836]: info: CoreCommandRouter::Close All Modals sent Feb 16 13:25:16 pecanpiplus volumio[836]: verbose: ControllerMpd::parseState Feb 16 13:25:16 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 16 13:25:16 pecanpiplus volumio[836]: verbose: ControllerMpd::parseTrackInfo Feb 16 13:25:16 pecanpiplus volumio[836]: verbose: ControllerMpd::parseTrackInfo Feb 16 13:25:16 pecanpiplus volumio[836]: verbose: ControllerMpd::parseTrackInfo Feb 16 13:25:16 pecanpiplus volumio[836]: verbose: ControllerMpd::parseTrackInfo Feb 16 13:25:16 pecanpiplus volumio[836]: verbose: ControllerMpd::parseState Feb 16 13:25:16 pecanpiplus volumio[836]: info: Command Router : Notfying DB Updatefalse Feb 16 13:25:16 pecanpiplus volumio[836]: info: CoreCommandRouter::Close All Modals sent Feb 16 13:25:16 pecanpiplus volumio[836]: verbose: ControllerMpd::parseState Feb 16 13:25:16 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 16 13:25:16 pecanpiplus volumio[836]: verbose: ControllerMpd::parseState Feb 16 13:25:16 pecanpiplus volumio[836]: info: Command Router : Notfying DB Updatefalse Feb 16 13:25:16 pecanpiplus volumio[836]: info: CoreCommandRouter::Close All Modals sent Feb 16 13:25:16 pecanpiplus volumio[836]: verbose: ControllerMpd::parseState Feb 16 13:25:16 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 16 13:25:16 pecanpiplus volumio[836]: verbose: ControllerMpd::parseState Feb 16 13:25:16 pecanpiplus volumio[836]: info: Command Router : Notfying DB Updatefalse Feb 16 13:25:16 pecanpiplus volumio[836]: info: CoreCommandRouter::Close All Modals sent Feb 16 13:25:16 pecanpiplus volumio[836]: verbose: ControllerMpd::parseState Feb 16 13:25:16 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 16 13:25:16 pecanpiplus volumio[836]: info: ControllerMpd::pushState Feb 16 13:25:16 pecanpiplus volumio[836]: info: CoreCommandRouter::servicePushState Feb 16 13:25:16 pecanpiplus volumio[836]: info: CorePlayQueue::getTrack 0 Feb 16 13:25:16 pecanpiplus volumio[836]: verbose: STATE SERVICE {"status":"play","position":0,"seek":125198,"duration":432,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"771 Kbps","isStreaming":false,"title":"105048155&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/105048155&soundQuality=HI_RES","trackType":"tidal"} Feb 16 13:25:16 pecanpiplus volumio[836]: verbose: CURRENT POSITION 0 Feb 16 13:25:16 pecanpiplus volumio[836]: info: CoreStateMachine::syncState stateService play Feb 16 13:25:16 pecanpiplus volumio[836]: info: CoreStateMachine::syncState currentStatus play Feb 16 13:25:16 pecanpiplus volumio[836]: info: Received an update from plugin. extracting info from payload Feb 16 13:25:16 pecanpiplus volumio[836]: info: CoreStateMachine::pushState Feb 16 13:25:16 pecanpiplus volumio[836]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 13:25:16 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioPushState Feb 16 13:25:16 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output update for this device Feb 16 13:25:16 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output Feb 16 13:25:16 pecanpiplus volumio[836]: info: CoreStateMachine::pushState Feb 16 13:25:16 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioPushState Feb 16 13:25:16 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output update for this device Feb 16 13:25:16 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output Feb 16 13:25:16 pecanpiplus volumio[836]: info: ControllerMpd::pushState Feb 16 13:25:16 pecanpiplus volumio[836]: info: CoreCommandRouter::servicePushState Feb 16 13:25:16 pecanpiplus volumio[836]: info: CorePlayQueue::getTrack 0 Feb 16 13:25:16 pecanpiplus volumio[836]: verbose: STATE SERVICE {"status":"play","position":0,"seek":125198,"duration":432,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"771 Kbps","isStreaming":false,"title":"105048155&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/105048155&soundQuality=HI_RES","trackType":"tidal"} Feb 16 13:25:16 pecanpiplus volumio[836]: verbose: CURRENT POSITION 0 Feb 16 13:25:16 pecanpiplus volumio[836]: info: CoreStateMachine::syncState stateService play Feb 16 13:25:16 pecanpiplus volumio[836]: info: CoreStateMachine::syncState currentStatus play Feb 16 13:25:16 pecanpiplus volumio[836]: info: Received an update from plugin. extracting info from payload Feb 16 13:25:16 pecanpiplus volumio[836]: info: CoreStateMachine::pushState Feb 16 13:25:16 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioPushState Feb 16 13:25:16 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output update for this device Feb 16 13:25:16 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output Feb 16 13:25:16 pecanpiplus volumio[836]: info: CoreStateMachine::pushState Feb 16 13:25:16 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioPushState Feb 16 13:25:16 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output update for this device Feb 16 13:25:16 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output Feb 16 13:25:16 pecanpiplus volumio[836]: info: ControllerMpd::pushState Feb 16 13:25:16 pecanpiplus volumio[836]: info: CoreCommandRouter::servicePushState Feb 16 13:25:16 pecanpiplus volumio[836]: info: CorePlayQueue::getTrack 0 Feb 16 13:25:16 pecanpiplus volumio[836]: verbose: STATE SERVICE {"status":"play","position":0,"seek":125198,"duration":432,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"771 Kbps","isStreaming":false,"title":"105048155&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/105048155&soundQuality=HI_RES","trackType":"tidal"} Feb 16 13:25:16 pecanpiplus volumio[836]: verbose: CURRENT POSITION 0 Feb 16 13:25:16 pecanpiplus volumio[836]: info: CoreStateMachine::syncState stateService play Feb 16 13:25:16 pecanpiplus volumio[836]: info: CoreStateMachine::syncState currentStatus play Feb 16 13:25:16 pecanpiplus volumio[836]: info: Received an update from plugin. extracting info from payload Feb 16 13:25:16 pecanpiplus volumio[836]: info: CoreStateMachine::pushState Feb 16 13:25:16 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioPushState Feb 16 13:25:16 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output update for this device Feb 16 13:25:16 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output Feb 16 13:25:16 pecanpiplus volumio[836]: info: CoreStateMachine::pushState Feb 16 13:25:16 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioPushState Feb 16 13:25:16 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output update for this device Feb 16 13:25:16 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output Feb 16 13:25:16 pecanpiplus volumio[836]: info: ControllerMpd::pushState Feb 16 13:25:16 pecanpiplus volumio[836]: info: CoreCommandRouter::servicePushState Feb 16 13:25:16 pecanpiplus volumio[836]: info: CorePlayQueue::getTrack 0 Feb 16 13:25:16 pecanpiplus volumio[836]: verbose: STATE SERVICE {"status":"play","position":0,"seek":125198,"duration":432,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"771 Kbps","isStreaming":false,"title":"105048155&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/105048155&soundQuality=HI_RES","trackType":"tidal"} Feb 16 13:25:16 pecanpiplus volumio[836]: verbose: CURRENT POSITION 0 Feb 16 13:25:16 pecanpiplus volumio[836]: info: CoreStateMachine::syncState stateService play Feb 16 13:25:16 pecanpiplus volumio[836]: info: CoreStateMachine::syncState currentStatus play Feb 16 13:25:16 pecanpiplus volumio[836]: info: Received an update from plugin. extracting info from payload Feb 16 13:25:16 pecanpiplus volumio[836]: info: CoreStateMachine::pushState Feb 16 13:25:16 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioPushState Feb 16 13:25:16 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output update for this device Feb 16 13:25:16 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output Feb 16 13:25:16 pecanpiplus volumio[836]: info: CoreStateMachine::pushState Feb 16 13:25:16 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioPushState Feb 16 13:25:16 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output update for this device Feb 16 13:25:16 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output Feb 16 13:25:16 pecanpiplus volumio[836]: info: ------------------------------ 711ms Feb 16 13:25:16 pecanpiplus volumio[836]: info: ------------------------------ 708ms Feb 16 13:25:16 pecanpiplus volumio[836]: info: ------------------------------ 707ms Feb 16 13:25:16 pecanpiplus volumio[836]: info: ------------------------------ 705ms Feb 16 13:25:16 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:25:16 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:25:16 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:25:16 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:25:16 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:25:16 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:25:16 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:25:16 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:25:16 pecanpiplus volumio[836]: info: sendMpdCommand playlistinfo took 468 milliseconds Feb 16 13:25:16 pecanpiplus volumio[836]: info: sendMpdCommand playlistinfo took 431 milliseconds Feb 16 13:25:16 pecanpiplus volumio[836]: info: sendMpdCommand playlistinfo took 395 milliseconds Feb 16 13:25:16 pecanpiplus volumio[836]: info: sendMpdCommand playlistinfo took 359 milliseconds Feb 16 13:25:16 pecanpiplus volumio[836]: verbose: ControllerMpd::parseTrackInfo Feb 16 13:25:16 pecanpiplus volumio[836]: verbose: ControllerMpd::parseTrackInfo Feb 16 13:25:16 pecanpiplus volumio[836]: verbose: ControllerMpd::parseTrackInfo Feb 16 13:25:16 pecanpiplus volumio[836]: verbose: ControllerMpd::parseTrackInfo Feb 16 13:25:16 pecanpiplus volumio[836]: info: ControllerMpd::pushState Feb 16 13:25:16 pecanpiplus volumio[836]: info: CoreCommandRouter::servicePushState Feb 16 13:25:16 pecanpiplus volumio[836]: info: CorePlayQueue::getTrack 0 Feb 16 13:25:16 pecanpiplus volumio[836]: verbose: STATE SERVICE {"status":"play","position":0,"seek":125198,"duration":432,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"771 Kbps","isStreaming":false,"title":"105048155&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/105048155&soundQuality=HI_RES","trackType":"tidal"} Feb 16 13:25:16 pecanpiplus volumio[836]: verbose: CURRENT POSITION 0 Feb 16 13:25:16 pecanpiplus volumio[836]: info: CoreStateMachine::syncState stateService play Feb 16 13:25:16 pecanpiplus volumio[836]: info: CoreStateMachine::syncState currentStatus play Feb 16 13:25:16 pecanpiplus volumio[836]: info: Received an update from plugin. extracting info from payload Feb 16 13:25:16 pecanpiplus volumio[836]: info: CoreStateMachine::pushState Feb 16 13:25:16 pecanpiplus volumio[836]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 13:25:16 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioPushState Feb 16 13:25:16 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output update for this device Feb 16 13:25:16 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output Feb 16 13:25:16 pecanpiplus volumio[836]: info: CoreStateMachine::pushState Feb 16 13:25:16 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioPushState Feb 16 13:25:16 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output update for this device Feb 16 13:25:16 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output Feb 16 13:25:16 pecanpiplus volumio[836]: info: ControllerMpd::pushState Feb 16 13:25:16 pecanpiplus volumio[836]: info: CoreCommandRouter::servicePushState Feb 16 13:25:16 pecanpiplus volumio[836]: info: CorePlayQueue::getTrack 0 Feb 16 13:25:16 pecanpiplus volumio[836]: verbose: STATE SERVICE {"status":"play","position":0,"seek":125450,"duration":432,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1046 Kbps","isStreaming":false,"title":"105048155&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/105048155&soundQuality=HI_RES","trackType":"tidal"} Feb 16 13:25:16 pecanpiplus volumio[836]: verbose: CURRENT POSITION 0 Feb 16 13:25:16 pecanpiplus volumio[836]: info: CoreStateMachine::syncState stateService play Feb 16 13:25:16 pecanpiplus volumio[836]: info: CoreStateMachine::syncState currentStatus play Feb 16 13:25:16 pecanpiplus volumio[836]: info: Received an update from plugin. extracting info from payload Feb 16 13:25:16 pecanpiplus volumio[836]: info: CoreStateMachine::pushState Feb 16 13:25:16 pecanpiplus volumio[836]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 13:25:16 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioPushState Feb 16 13:25:16 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output update for this device Feb 16 13:25:16 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output Feb 16 13:25:16 pecanpiplus volumio[836]: info: CoreStateMachine::pushState Feb 16 13:25:16 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioPushState Feb 16 13:25:16 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output update for this device Feb 16 13:25:16 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output Feb 16 13:25:16 pecanpiplus volumio[836]: info: ControllerMpd::pushState Feb 16 13:25:16 pecanpiplus volumio[836]: info: CoreCommandRouter::servicePushState Feb 16 13:25:16 pecanpiplus volumio[836]: info: CorePlayQueue::getTrack 0 Feb 16 13:25:16 pecanpiplus volumio[836]: verbose: STATE SERVICE {"status":"play","position":0,"seek":125450,"duration":432,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1046 Kbps","isStreaming":false,"title":"105048155&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/105048155&soundQuality=HI_RES","trackType":"tidal"} Feb 16 13:25:16 pecanpiplus volumio[836]: verbose: CURRENT POSITION 0 Feb 16 13:25:16 pecanpiplus volumio[836]: info: CoreStateMachine::syncState stateService play Feb 16 13:25:16 pecanpiplus volumio[836]: info: CoreStateMachine::syncState currentStatus play Feb 16 13:25:16 pecanpiplus volumio[836]: info: Received an update from plugin. extracting info from payload Feb 16 13:25:16 pecanpiplus volumio[836]: info: CoreStateMachine::pushState Feb 16 13:25:16 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioPushState Feb 16 13:25:16 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output update for this device Feb 16 13:25:16 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output Feb 16 13:25:16 pecanpiplus volumio[836]: info: CoreStateMachine::pushState Feb 16 13:25:16 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioPushState Feb 16 13:25:16 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output update for this device Feb 16 13:25:16 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output Feb 16 13:25:16 pecanpiplus volumio[836]: info: ControllerMpd::pushState Feb 16 13:25:16 pecanpiplus volumio[836]: info: CoreCommandRouter::servicePushState Feb 16 13:25:16 pecanpiplus volumio[836]: info: CorePlayQueue::getTrack 0 Feb 16 13:25:16 pecanpiplus volumio[836]: verbose: STATE SERVICE {"status":"play","position":0,"seek":125450,"duration":432,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1046 Kbps","isStreaming":false,"title":"105048155&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/105048155&soundQuality=HI_RES","trackType":"tidal"} Feb 16 13:25:16 pecanpiplus volumio[836]: verbose: CURRENT POSITION 0 Feb 16 13:25:16 pecanpiplus volumio[836]: info: CoreStateMachine::syncState stateService play Feb 16 13:25:16 pecanpiplus volumio[836]: info: CoreStateMachine::syncState currentStatus play Feb 16 13:25:16 pecanpiplus volumio[836]: info: Received an update from plugin. extracting info from payload Feb 16 13:25:16 pecanpiplus volumio[836]: info: CoreStateMachine::pushState Feb 16 13:25:16 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioPushState Feb 16 13:25:16 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output update for this device Feb 16 13:25:16 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output Feb 16 13:25:16 pecanpiplus volumio[836]: info: CoreStateMachine::pushState Feb 16 13:25:16 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioPushState Feb 16 13:25:16 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output update for this device Feb 16 13:25:16 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output Feb 16 13:25:16 pecanpiplus volumio[836]: info: ------------------------------ 986ms Feb 16 13:25:16 pecanpiplus volumio[836]: info: ------------------------------ 769ms Feb 16 13:25:16 pecanpiplus volumio[836]: info: ------------------------------ 768ms Feb 16 13:25:16 pecanpiplus volumio[836]: info: ------------------------------ 767ms Feb 16 13:25:16 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:25:16 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:25:16 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:25:16 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:25:16 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:25:16 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:25:16 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:25:16 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:25:17 pecanpiplus volumio[836]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 16 13:25:18 pecanpiplus volumio[836]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Feb 16 13:25:18 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand rescan Feb 16 13:25:18 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:25:18 pecanpiplus volumio[836]: info: Feb 16 13:25:18 pecanpiplus volumio[836]: ---------------------------- MPD announces state update: update Feb 16 13:25:18 pecanpiplus volumio[836]: info: sendMpdCommand rescan took 10 milliseconds Feb 16 13:25:18 pecanpiplus volumio[836]: info: ControllerMpd::getState Feb 16 13:25:18 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:25:18 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:25:18 pecanpiplus volumio[836]: info: Feb 16 13:25:18 pecanpiplus volumio[836]: ---------------------------- MPD announces state update: update Feb 16 13:25:18 pecanpiplus volumio[836]: info: ControllerMpd::getState Feb 16 13:25:18 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:25:18 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:25:18 pecanpiplus volumio[836]: info: Feb 16 13:25:18 pecanpiplus volumio[836]: ---------------------------- MPD announces state update: update Feb 16 13:25:18 pecanpiplus volumio[836]: info: ControllerMpd::getState Feb 16 13:25:18 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:25:18 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:25:18 pecanpiplus volumio[836]: info: Feb 16 13:25:18 pecanpiplus volumio[836]: ---------------------------- MPD announces state update: update Feb 16 13:25:18 pecanpiplus volumio[836]: info: ControllerMpd::getState Feb 16 13:25:18 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:25:18 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:25:18 pecanpiplus volumio[836]: info: Feb 16 13:25:18 pecanpiplus volumio[836]: ---------------------------- MPD announces state update: update Feb 16 13:25:18 pecanpiplus volumio[836]: info: ControllerMpd::getState Feb 16 13:25:18 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:25:18 pecanpiplus volumio[836]: info: sendMpdCommand status took 24 milliseconds Feb 16 13:25:18 pecanpiplus volumio[836]: info: sendMpdCommand status took 21 milliseconds Feb 16 13:25:18 pecanpiplus volumio[836]: info: sendMpdCommand status took 21 milliseconds Feb 16 13:25:18 pecanpiplus volumio[836]: info: sendMpdCommand status took 19 milliseconds Feb 16 13:25:18 pecanpiplus volumio[836]: info: sendMpdCommand status took 19 milliseconds Feb 16 13:25:18 pecanpiplus volumio[836]: info: sendMpdCommand status took 17 milliseconds Feb 16 13:25:18 pecanpiplus volumio[836]: info: sendMpdCommand status took 16 milliseconds Feb 16 13:25:18 pecanpiplus volumio[836]: info: sendMpdCommand status took 14 milliseconds Feb 16 13:25:18 pecanpiplus volumio[836]: verbose: ControllerMpd::parseState Feb 16 13:25:18 pecanpiplus volumio[836]: info: Command Router : Notfying DB Updatefalse Feb 16 13:25:18 pecanpiplus volumio[836]: info: CoreCommandRouter::Close All Modals sent Feb 16 13:25:18 pecanpiplus volumio[836]: verbose: ControllerMpd::parseState Feb 16 13:25:18 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 16 13:25:18 pecanpiplus volumio[836]: verbose: ControllerMpd::parseState Feb 16 13:25:18 pecanpiplus volumio[836]: info: Command Router : Notfying DB Updatefalse Feb 16 13:25:18 pecanpiplus volumio[836]: info: CoreCommandRouter::Close All Modals sent Feb 16 13:25:18 pecanpiplus volumio[836]: verbose: ControllerMpd::parseState Feb 16 13:25:18 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 16 13:25:18 pecanpiplus volumio[836]: verbose: ControllerMpd::parseState Feb 16 13:25:18 pecanpiplus volumio[836]: info: Command Router : Notfying DB Updatefalse Feb 16 13:25:18 pecanpiplus volumio[836]: info: CoreCommandRouter::Close All Modals sent Feb 16 13:25:18 pecanpiplus volumio[836]: verbose: ControllerMpd::parseState Feb 16 13:25:18 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 16 13:25:18 pecanpiplus volumio[836]: verbose: ControllerMpd::parseState Feb 16 13:25:18 pecanpiplus volumio[836]: info: Command Router : Notfying DB Updatefalse Feb 16 13:25:18 pecanpiplus volumio[836]: info: CoreCommandRouter::Close All Modals sent Feb 16 13:25:18 pecanpiplus volumio[836]: verbose: ControllerMpd::parseState Feb 16 13:25:18 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 16 13:25:18 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:25:18 pecanpiplus volumio[836]: info: Feb 16 13:25:18 pecanpiplus volumio[836]: ---------------------------- MPD announces state update: update Feb 16 13:25:18 pecanpiplus volumio[836]: info: ControllerMpd::getState Feb 16 13:25:18 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:25:18 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:25:18 pecanpiplus volumio[836]: info: Feb 16 13:25:18 pecanpiplus volumio[836]: ---------------------------- MPD announces state update: update Feb 16 13:25:18 pecanpiplus volumio[836]: info: ControllerMpd::getState Feb 16 13:25:18 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:25:18 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:25:18 pecanpiplus volumio[836]: info: Feb 16 13:25:18 pecanpiplus volumio[836]: ---------------------------- MPD announces state update: update Feb 16 13:25:18 pecanpiplus volumio[836]: info: ControllerMpd::getState Feb 16 13:25:18 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:25:18 pecanpiplus volumio[836]: info: sendMpdCommand status took 267 milliseconds Feb 16 13:25:18 pecanpiplus volumio[836]: info: sendMpdCommand status took 264 milliseconds Feb 16 13:25:18 pecanpiplus volumio[836]: info: sendMpdCommand playlistinfo took 198 milliseconds Feb 16 13:25:18 pecanpiplus volumio[836]: info: sendMpdCommand playlistinfo took 163 milliseconds Feb 16 13:25:18 pecanpiplus volumio[836]: info: sendMpdCommand playlistinfo took 123 milliseconds Feb 16 13:25:18 pecanpiplus volumio[836]: info: sendMpdCommand playlistinfo took 81 milliseconds Feb 16 13:25:18 pecanpiplus volumio[836]: info: sendMpdCommand status took 29 milliseconds Feb 16 13:25:18 pecanpiplus volumio[836]: info: sendMpdCommand status took 22 milliseconds Feb 16 13:25:18 pecanpiplus volumio[836]: info: sendMpdCommand status took 21 milliseconds Feb 16 13:25:18 pecanpiplus volumio[836]: info: sendMpdCommand status took 18 milliseconds Feb 16 13:25:18 pecanpiplus volumio[836]: info: sendMpdCommand status took 17 milliseconds Feb 16 13:25:18 pecanpiplus volumio[836]: info: sendMpdCommand status took 14 milliseconds Feb 16 13:25:18 pecanpiplus volumio[836]: verbose: ControllerMpd::parseState Feb 16 13:25:18 pecanpiplus volumio[836]: info: Command Router : Notfying DB Updatefalse Feb 16 13:25:18 pecanpiplus volumio[836]: info: CoreCommandRouter::Close All Modals sent Feb 16 13:25:18 pecanpiplus volumio[836]: verbose: ControllerMpd::parseState Feb 16 13:25:18 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 16 13:25:18 pecanpiplus volumio[836]: verbose: ControllerMpd::parseTrackInfo Feb 16 13:25:18 pecanpiplus volumio[836]: verbose: ControllerMpd::parseTrackInfo Feb 16 13:25:18 pecanpiplus volumio[836]: verbose: ControllerMpd::parseTrackInfo Feb 16 13:25:18 pecanpiplus volumio[836]: verbose: ControllerMpd::parseTrackInfo Feb 16 13:25:18 pecanpiplus volumio[836]: verbose: ControllerMpd::parseState Feb 16 13:25:18 pecanpiplus volumio[836]: info: Command Router : Notfying DB Updatefalse Feb 16 13:25:18 pecanpiplus volumio[836]: info: CoreCommandRouter::Close All Modals sent Feb 16 13:25:18 pecanpiplus volumio[836]: verbose: ControllerMpd::parseState Feb 16 13:25:18 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 16 13:25:18 pecanpiplus volumio[836]: verbose: ControllerMpd::parseState Feb 16 13:25:18 pecanpiplus volumio[836]: info: Command Router : Notfying DB Updatefalse Feb 16 13:25:19 pecanpiplus volumio[836]: info: CoreCommandRouter::Close All Modals sent Feb 16 13:25:19 pecanpiplus volumio[836]: verbose: ControllerMpd::parseState Feb 16 13:25:19 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 16 13:25:19 pecanpiplus volumio[836]: verbose: ControllerMpd::parseState Feb 16 13:25:19 pecanpiplus volumio[836]: info: Command Router : Notfying DB Updatefalse Feb 16 13:25:19 pecanpiplus volumio[836]: info: CoreCommandRouter::Close All Modals sent Feb 16 13:25:19 pecanpiplus volumio[836]: verbose: ControllerMpd::parseState Feb 16 13:25:19 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 16 13:25:19 pecanpiplus volumio[836]: info: ControllerMpd::pushState Feb 16 13:25:19 pecanpiplus volumio[836]: info: CoreCommandRouter::servicePushState Feb 16 13:25:19 pecanpiplus volumio[836]: info: CorePlayQueue::getTrack 0 Feb 16 13:25:19 pecanpiplus volumio[836]: verbose: STATE SERVICE {"status":"play","position":0,"seek":128081,"duration":432,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1009 Kbps","isStreaming":false,"title":"105048155&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/105048155&soundQuality=HI_RES","trackType":"tidal"} Feb 16 13:25:19 pecanpiplus volumio[836]: verbose: CURRENT POSITION 0 Feb 16 13:25:19 pecanpiplus volumio[836]: info: CoreStateMachine::syncState stateService play Feb 16 13:25:19 pecanpiplus volumio[836]: info: CoreStateMachine::syncState currentStatus play Feb 16 13:25:19 pecanpiplus volumio[836]: info: Received an update from plugin. extracting info from payload Feb 16 13:25:19 pecanpiplus volumio[836]: info: CoreStateMachine::pushState Feb 16 13:25:19 pecanpiplus volumio[836]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 13:25:19 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioPushState Feb 16 13:25:19 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output update for this device Feb 16 13:25:19 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output Feb 16 13:25:19 pecanpiplus volumio[836]: info: CoreStateMachine::pushState Feb 16 13:25:19 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioPushState Feb 16 13:25:19 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output update for this device Feb 16 13:25:19 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output Feb 16 13:25:19 pecanpiplus volumio[836]: info: ControllerMpd::pushState Feb 16 13:25:19 pecanpiplus volumio[836]: info: CoreCommandRouter::servicePushState Feb 16 13:25:19 pecanpiplus volumio[836]: info: CorePlayQueue::getTrack 0 Feb 16 13:25:19 pecanpiplus volumio[836]: verbose: STATE SERVICE {"status":"play","position":0,"seek":128081,"duration":432,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1009 Kbps","isStreaming":false,"title":"105048155&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/105048155&soundQuality=HI_RES","trackType":"tidal"} Feb 16 13:25:19 pecanpiplus volumio[836]: verbose: CURRENT POSITION 0 Feb 16 13:25:19 pecanpiplus volumio[836]: info: CoreStateMachine::syncState stateService play Feb 16 13:25:19 pecanpiplus volumio[836]: info: CoreStateMachine::syncState currentStatus play Feb 16 13:25:19 pecanpiplus volumio[836]: info: Received an update from plugin. extracting info from payload Feb 16 13:25:19 pecanpiplus volumio[836]: info: CoreStateMachine::pushState Feb 16 13:25:19 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioPushState Feb 16 13:25:19 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output update for this device Feb 16 13:25:19 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output Feb 16 13:25:19 pecanpiplus volumio[836]: info: CoreStateMachine::pushState Feb 16 13:25:19 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioPushState Feb 16 13:25:19 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output update for this device Feb 16 13:25:19 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output Feb 16 13:25:19 pecanpiplus volumio[836]: info: ControllerMpd::pushState Feb 16 13:25:19 pecanpiplus volumio[836]: info: CoreCommandRouter::servicePushState Feb 16 13:25:19 pecanpiplus volumio[836]: info: CorePlayQueue::getTrack 0 Feb 16 13:25:19 pecanpiplus volumio[836]: verbose: STATE SERVICE {"status":"play","position":0,"seek":128081,"duration":432,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1009 Kbps","isStreaming":false,"title":"105048155&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/105048155&soundQuality=HI_RES","trackType":"tidal"} Feb 16 13:25:19 pecanpiplus volumio[836]: verbose: CURRENT POSITION 0 Feb 16 13:25:19 pecanpiplus volumio[836]: info: CoreStateMachine::syncState stateService play Feb 16 13:25:19 pecanpiplus volumio[836]: info: CoreStateMachine::syncState currentStatus play Feb 16 13:25:19 pecanpiplus volumio[836]: info: Received an update from plugin. extracting info from payload Feb 16 13:25:19 pecanpiplus volumio[836]: info: CoreStateMachine::pushState Feb 16 13:25:19 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioPushState Feb 16 13:25:19 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output update for this device Feb 16 13:25:19 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output Feb 16 13:25:19 pecanpiplus volumio[836]: info: CoreStateMachine::pushState Feb 16 13:25:19 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioPushState Feb 16 13:25:19 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output update for this device Feb 16 13:25:19 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output Feb 16 13:25:19 pecanpiplus volumio[836]: info: ControllerMpd::pushState Feb 16 13:25:19 pecanpiplus volumio[836]: info: CoreCommandRouter::servicePushState Feb 16 13:25:19 pecanpiplus volumio[836]: info: CorePlayQueue::getTrack 0 Feb 16 13:25:19 pecanpiplus volumio[836]: verbose: STATE SERVICE {"status":"play","position":0,"seek":128081,"duration":432,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1009 Kbps","isStreaming":false,"title":"105048155&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/105048155&soundQuality=HI_RES","trackType":"tidal"} Feb 16 13:25:19 pecanpiplus volumio[836]: verbose: CURRENT POSITION 0 Feb 16 13:25:19 pecanpiplus volumio[836]: info: CoreStateMachine::syncState stateService play Feb 16 13:25:19 pecanpiplus volumio[836]: info: CoreStateMachine::syncState currentStatus play Feb 16 13:25:19 pecanpiplus volumio[836]: info: Received an update from plugin. extracting info from payload Feb 16 13:25:19 pecanpiplus volumio[836]: info: CoreStateMachine::pushState Feb 16 13:25:19 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioPushState Feb 16 13:25:19 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output update for this device Feb 16 13:25:19 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output Feb 16 13:25:19 pecanpiplus volumio[836]: info: CoreStateMachine::pushState Feb 16 13:25:19 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioPushState Feb 16 13:25:19 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output update for this device Feb 16 13:25:19 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output Feb 16 13:25:19 pecanpiplus volumio[836]: info: ------------------------------ 735ms Feb 16 13:25:19 pecanpiplus volumio[836]: info: ------------------------------ 731ms Feb 16 13:25:19 pecanpiplus volumio[836]: info: ------------------------------ 728ms Feb 16 13:25:19 pecanpiplus volumio[836]: info: ------------------------------ 724ms Feb 16 13:25:19 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:25:19 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:25:19 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:25:19 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:25:19 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:25:19 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:25:19 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:25:19 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:25:19 pecanpiplus volumio[836]: info: sendMpdCommand playlistinfo took 443 milliseconds Feb 16 13:25:19 pecanpiplus volumio[836]: info: sendMpdCommand playlistinfo took 405 milliseconds Feb 16 13:25:19 pecanpiplus volumio[836]: info: sendMpdCommand playlistinfo took 367 milliseconds Feb 16 13:25:19 pecanpiplus volumio[836]: info: sendMpdCommand playlistinfo took 332 milliseconds Feb 16 13:25:19 pecanpiplus volumio[836]: verbose: ControllerMpd::parseTrackInfo Feb 16 13:25:19 pecanpiplus volumio[836]: verbose: ControllerMpd::parseTrackInfo Feb 16 13:25:19 pecanpiplus volumio[836]: verbose: ControllerMpd::parseTrackInfo Feb 16 13:25:19 pecanpiplus volumio[836]: verbose: ControllerMpd::parseTrackInfo Feb 16 13:25:19 pecanpiplus volumio[836]: info: ControllerMpd::pushState Feb 16 13:25:19 pecanpiplus volumio[836]: info: CoreCommandRouter::servicePushState Feb 16 13:25:19 pecanpiplus volumio[836]: info: CorePlayQueue::getTrack 0 Feb 16 13:25:19 pecanpiplus volumio[836]: verbose: STATE SERVICE {"status":"play","position":0,"seek":128081,"duration":432,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1009 Kbps","isStreaming":false,"title":"105048155&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/105048155&soundQuality=HI_RES","trackType":"tidal"} Feb 16 13:25:19 pecanpiplus volumio[836]: verbose: CURRENT POSITION 0 Feb 16 13:25:19 pecanpiplus volumio[836]: info: CoreStateMachine::syncState stateService play Feb 16 13:25:19 pecanpiplus volumio[836]: info: CoreStateMachine::syncState currentStatus play Feb 16 13:25:19 pecanpiplus volumio[836]: info: Received an update from plugin. extracting info from payload Feb 16 13:25:19 pecanpiplus volumio[836]: info: CoreStateMachine::pushState Feb 16 13:25:19 pecanpiplus volumio[836]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 13:25:19 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioPushState Feb 16 13:25:19 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output update for this device Feb 16 13:25:19 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output Feb 16 13:25:19 pecanpiplus volumio[836]: info: CoreStateMachine::pushState Feb 16 13:25:19 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioPushState Feb 16 13:25:19 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output update for this device Feb 16 13:25:19 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output Feb 16 13:25:19 pecanpiplus volumio[836]: info: ControllerMpd::pushState Feb 16 13:25:19 pecanpiplus volumio[836]: info: CoreCommandRouter::servicePushState Feb 16 13:25:19 pecanpiplus volumio[836]: info: CorePlayQueue::getTrack 0 Feb 16 13:25:19 pecanpiplus volumio[836]: verbose: STATE SERVICE {"status":"play","position":0,"seek":128332,"duration":432,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"949 Kbps","isStreaming":false,"title":"105048155&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/105048155&soundQuality=HI_RES","trackType":"tidal"} Feb 16 13:25:19 pecanpiplus volumio[836]: verbose: CURRENT POSITION 0 Feb 16 13:25:19 pecanpiplus volumio[836]: info: CoreStateMachine::syncState stateService play Feb 16 13:25:19 pecanpiplus volumio[836]: info: CoreStateMachine::syncState currentStatus play Feb 16 13:25:19 pecanpiplus volumio[836]: info: Received an update from plugin. extracting info from payload Feb 16 13:25:19 pecanpiplus volumio[836]: info: CoreStateMachine::pushState Feb 16 13:25:19 pecanpiplus volumio[836]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 13:25:19 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioPushState Feb 16 13:25:19 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output update for this device Feb 16 13:25:19 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output Feb 16 13:25:19 pecanpiplus volumio[836]: info: CoreStateMachine::pushState Feb 16 13:25:19 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioPushState Feb 16 13:25:19 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output update for this device Feb 16 13:25:19 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output Feb 16 13:25:19 pecanpiplus volumio[836]: info: ControllerMpd::pushState Feb 16 13:25:19 pecanpiplus volumio[836]: info: CoreCommandRouter::servicePushState Feb 16 13:25:19 pecanpiplus volumio[836]: info: CorePlayQueue::getTrack 0 Feb 16 13:25:19 pecanpiplus volumio[836]: verbose: STATE SERVICE {"status":"play","position":0,"seek":128332,"duration":432,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"949 Kbps","isStreaming":false,"title":"105048155&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/105048155&soundQuality=HI_RES","trackType":"tidal"} Feb 16 13:25:19 pecanpiplus volumio[836]: verbose: CURRENT POSITION 0 Feb 16 13:25:19 pecanpiplus volumio[836]: info: CoreStateMachine::syncState stateService play Feb 16 13:25:19 pecanpiplus volumio[836]: info: CoreStateMachine::syncState currentStatus play Feb 16 13:25:19 pecanpiplus volumio[836]: info: Received an update from plugin. extracting info from payload Feb 16 13:25:19 pecanpiplus volumio[836]: info: CoreStateMachine::pushState Feb 16 13:25:19 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioPushState Feb 16 13:25:19 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output update for this device Feb 16 13:25:19 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output Feb 16 13:25:19 pecanpiplus volumio[836]: info: CoreStateMachine::pushState Feb 16 13:25:19 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioPushState Feb 16 13:25:19 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output update for this device Feb 16 13:25:19 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output Feb 16 13:25:19 pecanpiplus volumio[836]: info: ControllerMpd::pushState Feb 16 13:25:19 pecanpiplus volumio[836]: info: CoreCommandRouter::servicePushState Feb 16 13:25:19 pecanpiplus volumio[836]: info: CorePlayQueue::getTrack 0 Feb 16 13:25:19 pecanpiplus volumio[836]: verbose: STATE SERVICE {"status":"play","position":0,"seek":128332,"duration":432,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"949 Kbps","isStreaming":false,"title":"105048155&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/105048155&soundQuality=HI_RES","trackType":"tidal"} Feb 16 13:25:19 pecanpiplus volumio[836]: verbose: CURRENT POSITION 0 Feb 16 13:25:19 pecanpiplus volumio[836]: info: CoreStateMachine::syncState stateService play Feb 16 13:25:19 pecanpiplus volumio[836]: info: CoreStateMachine::syncState currentStatus play Feb 16 13:25:19 pecanpiplus volumio[836]: info: Received an update from plugin. extracting info from payload Feb 16 13:25:19 pecanpiplus volumio[836]: info: CoreStateMachine::pushState Feb 16 13:25:19 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioPushState Feb 16 13:25:19 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output update for this device Feb 16 13:25:19 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output Feb 16 13:25:19 pecanpiplus volumio[836]: info: CoreStateMachine::pushState Feb 16 13:25:19 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioPushState Feb 16 13:25:19 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output update for this device Feb 16 13:25:19 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output Feb 16 13:25:19 pecanpiplus volumio[836]: info: ------------------------------ 1017ms Feb 16 13:25:19 pecanpiplus volumio[836]: info: ------------------------------ 774ms Feb 16 13:25:19 pecanpiplus volumio[836]: info: ------------------------------ 764ms Feb 16 13:25:19 pecanpiplus volumio[836]: info: ------------------------------ 760ms Feb 16 13:25:19 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:25:19 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:25:19 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:25:19 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:25:19 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:25:19 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:25:19 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:25:19 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:25:19 pecanpiplus volumio[836]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Feb 16 13:25:19 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand update Feb 16 13:25:19 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:25:19 pecanpiplus volumio[836]: info: Feb 16 13:25:19 pecanpiplus volumio[836]: ---------------------------- MPD announces state update: update Feb 16 13:25:19 pecanpiplus volumio[836]: info: sendMpdCommand update took 18 milliseconds Feb 16 13:25:19 pecanpiplus volumio[836]: info: ControllerMpd::getState Feb 16 13:25:19 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:25:19 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:25:19 pecanpiplus volumio[836]: info: Feb 16 13:25:19 pecanpiplus volumio[836]: ---------------------------- MPD announces state update: update Feb 16 13:25:19 pecanpiplus volumio[836]: info: ControllerMpd::getState Feb 16 13:25:19 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:25:19 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:25:19 pecanpiplus volumio[836]: info: Feb 16 13:25:19 pecanpiplus volumio[836]: ---------------------------- MPD announces state update: update Feb 16 13:25:19 pecanpiplus volumio[836]: info: ControllerMpd::getState Feb 16 13:25:19 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:25:19 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:25:19 pecanpiplus volumio[836]: info: Feb 16 13:25:19 pecanpiplus volumio[836]: ---------------------------- MPD announces state update: update Feb 16 13:25:19 pecanpiplus volumio[836]: info: ControllerMpd::getState Feb 16 13:25:19 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:25:19 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:25:19 pecanpiplus volumio[836]: info: Feb 16 13:25:19 pecanpiplus volumio[836]: ---------------------------- MPD announces state update: update Feb 16 13:25:19 pecanpiplus volumio[836]: info: ControllerMpd::getState Feb 16 13:25:19 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:25:19 pecanpiplus volumio[836]: info: sendMpdCommand status took 16 milliseconds Feb 16 13:25:19 pecanpiplus volumio[836]: info: sendMpdCommand status took 13 milliseconds Feb 16 13:25:19 pecanpiplus volumio[836]: info: sendMpdCommand status took 13 milliseconds Feb 16 13:25:19 pecanpiplus volumio[836]: info: sendMpdCommand status took 11 milliseconds Feb 16 13:25:19 pecanpiplus volumio[836]: info: sendMpdCommand status took 10 milliseconds Feb 16 13:25:19 pecanpiplus volumio[836]: info: sendMpdCommand status took 10 milliseconds Feb 16 13:25:19 pecanpiplus volumio[836]: info: sendMpdCommand status took 9 milliseconds Feb 16 13:25:19 pecanpiplus volumio[836]: info: sendMpdCommand status took 9 milliseconds Feb 16 13:25:19 pecanpiplus volumio[836]: verbose: ControllerMpd::parseState Feb 16 13:25:19 pecanpiplus volumio[836]: info: Command Router : Notfying DB Updatefalse Feb 16 13:25:19 pecanpiplus volumio[836]: info: CoreCommandRouter::Close All Modals sent Feb 16 13:25:19 pecanpiplus volumio[836]: verbose: ControllerMpd::parseState Feb 16 13:25:19 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 16 13:25:19 pecanpiplus volumio[836]: verbose: ControllerMpd::parseState Feb 16 13:25:19 pecanpiplus volumio[836]: info: Command Router : Notfying DB Updatefalse Feb 16 13:25:19 pecanpiplus volumio[836]: info: CoreCommandRouter::Close All Modals sent Feb 16 13:25:19 pecanpiplus volumio[836]: verbose: ControllerMpd::parseState Feb 16 13:25:19 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 16 13:25:19 pecanpiplus volumio[836]: verbose: ControllerMpd::parseState Feb 16 13:25:19 pecanpiplus volumio[836]: info: Command Router : Notfying DB Updatefalse Feb 16 13:25:19 pecanpiplus volumio[836]: info: CoreCommandRouter::Close All Modals sent Feb 16 13:25:19 pecanpiplus volumio[836]: verbose: ControllerMpd::parseState Feb 16 13:25:19 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 16 13:25:19 pecanpiplus volumio[836]: verbose: ControllerMpd::parseState Feb 16 13:25:19 pecanpiplus volumio[836]: info: Command Router : Notfying DB Updatefalse Feb 16 13:25:19 pecanpiplus volumio[836]: info: CoreCommandRouter::Close All Modals sent Feb 16 13:25:19 pecanpiplus volumio[836]: verbose: ControllerMpd::parseState Feb 16 13:25:19 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 16 13:25:19 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:25:19 pecanpiplus volumio[836]: info: Feb 16 13:25:19 pecanpiplus volumio[836]: ---------------------------- MPD announces state update: update Feb 16 13:25:19 pecanpiplus volumio[836]: info: ControllerMpd::getState Feb 16 13:25:19 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:25:19 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:25:19 pecanpiplus volumio[836]: info: Feb 16 13:25:19 pecanpiplus volumio[836]: ---------------------------- MPD announces state update: update Feb 16 13:25:19 pecanpiplus volumio[836]: info: ControllerMpd::getState Feb 16 13:25:19 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:25:19 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:25:19 pecanpiplus volumio[836]: info: Feb 16 13:25:19 pecanpiplus volumio[836]: ---------------------------- MPD announces state update: update Feb 16 13:25:19 pecanpiplus volumio[836]: info: ControllerMpd::getState Feb 16 13:25:19 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand status Feb 16 13:25:19 pecanpiplus volumio[836]: info: sendMpdCommand status took 226 milliseconds Feb 16 13:25:19 pecanpiplus volumio[836]: info: sendMpdCommand status took 225 milliseconds Feb 16 13:25:19 pecanpiplus volumio[836]: info: sendMpdCommand playlistinfo took 163 milliseconds Feb 16 13:25:19 pecanpiplus volumio[836]: info: sendMpdCommand playlistinfo took 127 milliseconds Feb 16 13:25:19 pecanpiplus volumio[836]: info: sendMpdCommand playlistinfo took 90 milliseconds Feb 16 13:25:19 pecanpiplus volumio[836]: info: sendMpdCommand playlistinfo took 51 milliseconds Feb 16 13:25:19 pecanpiplus volumio[836]: info: sendMpdCommand status took 10 milliseconds Feb 16 13:25:19 pecanpiplus volumio[836]: info: sendMpdCommand status took 9 milliseconds Feb 16 13:25:19 pecanpiplus volumio[836]: info: sendMpdCommand status took 8 milliseconds Feb 16 13:25:19 pecanpiplus volumio[836]: info: sendMpdCommand status took 7 milliseconds Feb 16 13:25:19 pecanpiplus volumio[836]: info: sendMpdCommand status took 7 milliseconds Feb 16 13:25:19 pecanpiplus volumio[836]: info: sendMpdCommand status took 6 milliseconds Feb 16 13:25:19 pecanpiplus volumio[836]: verbose: ControllerMpd::parseState Feb 16 13:25:19 pecanpiplus volumio[836]: info: Command Router : Notfying DB Updatefalse Feb 16 13:25:19 pecanpiplus volumio[836]: info: CoreCommandRouter::Close All Modals sent Feb 16 13:25:19 pecanpiplus volumio[836]: verbose: ControllerMpd::parseState Feb 16 13:25:19 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 16 13:25:19 pecanpiplus volumio[836]: verbose: ControllerMpd::parseTrackInfo Feb 16 13:25:19 pecanpiplus volumio[836]: verbose: ControllerMpd::parseTrackInfo Feb 16 13:25:19 pecanpiplus volumio[836]: verbose: ControllerMpd::parseTrackInfo Feb 16 13:25:19 pecanpiplus volumio[836]: verbose: ControllerMpd::parseTrackInfo Feb 16 13:25:19 pecanpiplus volumio[836]: verbose: ControllerMpd::parseState Feb 16 13:25:20 pecanpiplus volumio[836]: info: Command Router : Notfying DB Updatefalse Feb 16 13:25:20 pecanpiplus volumio[836]: info: CoreCommandRouter::Close All Modals sent Feb 16 13:25:20 pecanpiplus volumio[836]: verbose: ControllerMpd::parseState Feb 16 13:25:20 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 16 13:25:20 pecanpiplus volumio[836]: verbose: ControllerMpd::parseState Feb 16 13:25:20 pecanpiplus volumio[836]: info: Command Router : Notfying DB Updatefalse Feb 16 13:25:20 pecanpiplus volumio[836]: info: CoreCommandRouter::Close All Modals sent Feb 16 13:25:20 pecanpiplus volumio[836]: verbose: ControllerMpd::parseState Feb 16 13:25:20 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 16 13:25:20 pecanpiplus volumio[836]: verbose: ControllerMpd::parseState Feb 16 13:25:20 pecanpiplus volumio[836]: info: Command Router : Notfying DB Updatefalse Feb 16 13:25:20 pecanpiplus volumio[836]: info: CoreCommandRouter::Close All Modals sent Feb 16 13:25:20 pecanpiplus volumio[836]: verbose: ControllerMpd::parseState Feb 16 13:25:20 pecanpiplus volumio[836]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 16 13:25:20 pecanpiplus volumio[836]: info: ControllerMpd::pushState Feb 16 13:25:20 pecanpiplus volumio[836]: info: CoreCommandRouter::servicePushState Feb 16 13:25:20 pecanpiplus volumio[836]: info: CorePlayQueue::getTrack 0 Feb 16 13:25:20 pecanpiplus volumio[836]: verbose: STATE SERVICE {"status":"play","position":0,"seek":129202,"duration":432,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"899 Kbps","isStreaming":false,"title":"105048155&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/105048155&soundQuality=HI_RES","trackType":"tidal"} Feb 16 13:25:20 pecanpiplus volumio[836]: verbose: CURRENT POSITION 0 Feb 16 13:25:20 pecanpiplus volumio[836]: info: CoreStateMachine::syncState stateService play Feb 16 13:25:20 pecanpiplus volumio[836]: info: CoreStateMachine::syncState currentStatus play Feb 16 13:25:20 pecanpiplus volumio[836]: info: Received an update from plugin. extracting info from payload Feb 16 13:25:20 pecanpiplus volumio[836]: info: CoreStateMachine::pushState Feb 16 13:25:20 pecanpiplus volumio[836]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 13:25:20 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioPushState Feb 16 13:25:20 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output update for this device Feb 16 13:25:20 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output Feb 16 13:25:20 pecanpiplus volumio[836]: info: CoreStateMachine::pushState Feb 16 13:25:20 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioPushState Feb 16 13:25:20 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output update for this device Feb 16 13:25:20 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output Feb 16 13:25:20 pecanpiplus volumio[836]: info: ControllerMpd::pushState Feb 16 13:25:20 pecanpiplus volumio[836]: info: CoreCommandRouter::servicePushState Feb 16 13:25:20 pecanpiplus volumio[836]: info: CorePlayQueue::getTrack 0 Feb 16 13:25:20 pecanpiplus volumio[836]: verbose: STATE SERVICE {"status":"play","position":0,"seek":129202,"duration":432,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"899 Kbps","isStreaming":false,"title":"105048155&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/105048155&soundQuality=HI_RES","trackType":"tidal"} Feb 16 13:25:20 pecanpiplus volumio[836]: verbose: CURRENT POSITION 0 Feb 16 13:25:20 pecanpiplus volumio[836]: info: CoreStateMachine::syncState stateService play Feb 16 13:25:20 pecanpiplus volumio[836]: info: CoreStateMachine::syncState currentStatus play Feb 16 13:25:20 pecanpiplus volumio[836]: info: Received an update from plugin. extracting info from payload Feb 16 13:25:20 pecanpiplus volumio[836]: info: CoreStateMachine::pushState Feb 16 13:25:20 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioPushState Feb 16 13:25:20 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output update for this device Feb 16 13:25:20 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output Feb 16 13:25:20 pecanpiplus volumio[836]: info: CoreStateMachine::pushState Feb 16 13:25:20 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioPushState Feb 16 13:25:20 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output update for this device Feb 16 13:25:20 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output Feb 16 13:25:20 pecanpiplus volumio[836]: info: ControllerMpd::pushState Feb 16 13:25:20 pecanpiplus volumio[836]: info: CoreCommandRouter::servicePushState Feb 16 13:25:20 pecanpiplus volumio[836]: info: CorePlayQueue::getTrack 0 Feb 16 13:25:20 pecanpiplus volumio[836]: verbose: STATE SERVICE {"status":"play","position":0,"seek":129202,"duration":432,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"899 Kbps","isStreaming":false,"title":"105048155&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/105048155&soundQuality=HI_RES","trackType":"tidal"} Feb 16 13:25:20 pecanpiplus volumio[836]: verbose: CURRENT POSITION 0 Feb 16 13:25:20 pecanpiplus volumio[836]: info: CoreStateMachine::syncState stateService play Feb 16 13:25:20 pecanpiplus volumio[836]: info: CoreStateMachine::syncState currentStatus play Feb 16 13:25:20 pecanpiplus volumio[836]: info: Received an update from plugin. extracting info from payload Feb 16 13:25:20 pecanpiplus volumio[836]: info: CoreStateMachine::pushState Feb 16 13:25:20 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioPushState Feb 16 13:25:20 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output update for this device Feb 16 13:25:20 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output Feb 16 13:25:20 pecanpiplus volumio[836]: info: CoreStateMachine::pushState Feb 16 13:25:20 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioPushState Feb 16 13:25:20 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output update for this device Feb 16 13:25:20 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output Feb 16 13:25:20 pecanpiplus volumio[836]: info: ControllerMpd::pushState Feb 16 13:25:20 pecanpiplus volumio[836]: info: CoreCommandRouter::servicePushState Feb 16 13:25:20 pecanpiplus volumio[836]: info: CorePlayQueue::getTrack 0 Feb 16 13:25:20 pecanpiplus volumio[836]: verbose: STATE SERVICE {"status":"play","position":0,"seek":129202,"duration":432,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"899 Kbps","isStreaming":false,"title":"105048155&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/105048155&soundQuality=HI_RES","trackType":"tidal"} Feb 16 13:25:20 pecanpiplus volumio[836]: verbose: CURRENT POSITION 0 Feb 16 13:25:20 pecanpiplus volumio[836]: info: CoreStateMachine::syncState stateService play Feb 16 13:25:20 pecanpiplus volumio[836]: info: CoreStateMachine::syncState currentStatus play Feb 16 13:25:20 pecanpiplus volumio[836]: info: Received an update from plugin. extracting info from payload Feb 16 13:25:20 pecanpiplus volumio[836]: info: CoreStateMachine::pushState Feb 16 13:25:20 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioPushState Feb 16 13:25:20 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output update for this device Feb 16 13:25:20 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output Feb 16 13:25:20 pecanpiplus volumio[836]: info: CoreStateMachine::pushState Feb 16 13:25:20 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioPushState Feb 16 13:25:20 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output update for this device Feb 16 13:25:20 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output Feb 16 13:25:20 pecanpiplus volumio[836]: info: ------------------------------ 752ms Feb 16 13:25:20 pecanpiplus volumio[836]: info: ------------------------------ 749ms Feb 16 13:25:20 pecanpiplus volumio[836]: info: ------------------------------ 748ms Feb 16 13:25:20 pecanpiplus volumio[836]: info: ------------------------------ 745ms Feb 16 13:25:20 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:25:20 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:25:20 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:25:20 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:25:20 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:25:20 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:25:20 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:25:20 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:25:20 pecanpiplus volumio[836]: info: sendMpdCommand playlistinfo took 507 milliseconds Feb 16 13:25:20 pecanpiplus volumio[836]: info: sendMpdCommand playlistinfo took 437 milliseconds Feb 16 13:25:20 pecanpiplus volumio[836]: info: sendMpdCommand playlistinfo took 400 milliseconds Feb 16 13:25:20 pecanpiplus volumio[836]: info: sendMpdCommand playlistinfo took 364 milliseconds Feb 16 13:25:20 pecanpiplus volumio[836]: verbose: ControllerMpd::parseTrackInfo Feb 16 13:25:20 pecanpiplus volumio[836]: verbose: ControllerMpd::parseTrackInfo Feb 16 13:25:20 pecanpiplus volumio[836]: verbose: ControllerMpd::parseTrackInfo Feb 16 13:25:20 pecanpiplus volumio[836]: verbose: ControllerMpd::parseTrackInfo Feb 16 13:25:20 pecanpiplus volumio[836]: info: ControllerMpd::pushState Feb 16 13:25:20 pecanpiplus volumio[836]: info: CoreCommandRouter::servicePushState Feb 16 13:25:20 pecanpiplus volumio[836]: info: CorePlayQueue::getTrack 0 Feb 16 13:25:20 pecanpiplus volumio[836]: verbose: STATE SERVICE {"status":"play","position":0,"seek":129202,"duration":432,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"899 Kbps","isStreaming":false,"title":"105048155&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/105048155&soundQuality=HI_RES","trackType":"tidal"} Feb 16 13:25:20 pecanpiplus volumio[836]: verbose: CURRENT POSITION 0 Feb 16 13:25:20 pecanpiplus volumio[836]: info: CoreStateMachine::syncState stateService play Feb 16 13:25:20 pecanpiplus volumio[836]: info: CoreStateMachine::syncState currentStatus play Feb 16 13:25:20 pecanpiplus volumio[836]: info: Received an update from plugin. extracting info from payload Feb 16 13:25:20 pecanpiplus volumio[836]: info: CoreStateMachine::pushState Feb 16 13:25:20 pecanpiplus volumio[836]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 13:25:20 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioPushState Feb 16 13:25:20 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output update for this device Feb 16 13:25:20 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output Feb 16 13:25:20 pecanpiplus volumio[836]: info: CoreStateMachine::pushState Feb 16 13:25:20 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioPushState Feb 16 13:25:20 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output update for this device Feb 16 13:25:20 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output Feb 16 13:25:20 pecanpiplus volumio[836]: info: ControllerMpd::pushState Feb 16 13:25:20 pecanpiplus volumio[836]: info: CoreCommandRouter::servicePushState Feb 16 13:25:20 pecanpiplus volumio[836]: info: CorePlayQueue::getTrack 0 Feb 16 13:25:20 pecanpiplus volumio[836]: verbose: STATE SERVICE {"status":"play","position":0,"seek":129339,"duration":432,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"944 Kbps","isStreaming":false,"title":"105048155&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/105048155&soundQuality=HI_RES","trackType":"tidal"} Feb 16 13:25:20 pecanpiplus volumio[836]: verbose: CURRENT POSITION 0 Feb 16 13:25:20 pecanpiplus volumio[836]: info: CoreStateMachine::syncState stateService play Feb 16 13:25:20 pecanpiplus volumio[836]: info: CoreStateMachine::syncState currentStatus play Feb 16 13:25:20 pecanpiplus volumio[836]: info: Received an update from plugin. extracting info from payload Feb 16 13:25:20 pecanpiplus volumio[836]: info: CoreStateMachine::pushState Feb 16 13:25:20 pecanpiplus volumio[836]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 16 13:25:20 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioPushState Feb 16 13:25:20 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output update for this device Feb 16 13:25:20 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output Feb 16 13:25:20 pecanpiplus volumio[836]: info: CoreStateMachine::pushState Feb 16 13:25:20 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioPushState Feb 16 13:25:20 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output update for this device Feb 16 13:25:20 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output Feb 16 13:25:20 pecanpiplus volumio[836]: info: ControllerMpd::pushState Feb 16 13:25:20 pecanpiplus volumio[836]: info: CoreCommandRouter::servicePushState Feb 16 13:25:20 pecanpiplus volumio[836]: info: CorePlayQueue::getTrack 0 Feb 16 13:25:20 pecanpiplus volumio[836]: verbose: STATE SERVICE {"status":"play","position":0,"seek":129339,"duration":432,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"944 Kbps","isStreaming":false,"title":"105048155&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/105048155&soundQuality=HI_RES","trackType":"tidal"} Feb 16 13:25:20 pecanpiplus volumio[836]: verbose: CURRENT POSITION 0 Feb 16 13:25:20 pecanpiplus volumio[836]: info: CoreStateMachine::syncState stateService play Feb 16 13:25:20 pecanpiplus volumio[836]: info: CoreStateMachine::syncState currentStatus play Feb 16 13:25:20 pecanpiplus volumio[836]: info: Received an update from plugin. extracting info from payload Feb 16 13:25:20 pecanpiplus volumio[836]: info: CoreStateMachine::pushState Feb 16 13:25:20 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioPushState Feb 16 13:25:20 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output update for this device Feb 16 13:25:20 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output Feb 16 13:25:20 pecanpiplus volumio[836]: info: CoreStateMachine::pushState Feb 16 13:25:20 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioPushState Feb 16 13:25:20 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output update for this device Feb 16 13:25:20 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output Feb 16 13:25:20 pecanpiplus volumio[836]: info: ControllerMpd::pushState Feb 16 13:25:20 pecanpiplus volumio[836]: info: CoreCommandRouter::servicePushState Feb 16 13:25:20 pecanpiplus volumio[836]: info: CorePlayQueue::getTrack 0 Feb 16 13:25:20 pecanpiplus volumio[836]: verbose: STATE SERVICE {"status":"play","position":0,"seek":129339,"duration":432,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"944 Kbps","isStreaming":false,"title":"105048155&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/105048155&soundQuality=HI_RES","trackType":"tidal"} Feb 16 13:25:20 pecanpiplus volumio[836]: verbose: CURRENT POSITION 0 Feb 16 13:25:20 pecanpiplus volumio[836]: info: CoreStateMachine::syncState stateService play Feb 16 13:25:20 pecanpiplus volumio[836]: info: CoreStateMachine::syncState currentStatus play Feb 16 13:25:20 pecanpiplus volumio[836]: info: Received an update from plugin. extracting info from payload Feb 16 13:25:20 pecanpiplus volumio[836]: info: CoreStateMachine::pushState Feb 16 13:25:20 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioPushState Feb 16 13:25:20 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output update for this device Feb 16 13:25:20 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output Feb 16 13:25:20 pecanpiplus volumio[836]: info: CoreStateMachine::pushState Feb 16 13:25:20 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioPushState Feb 16 13:25:20 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output update for this device Feb 16 13:25:20 pecanpiplus volumio[836]: info: MRS: Pushing multiroomSync output Feb 16 13:25:20 pecanpiplus volumio[836]: info: ------------------------------ 1029ms Feb 16 13:25:20 pecanpiplus volumio[836]: info: ------------------------------ 812ms Feb 16 13:25:20 pecanpiplus volumio[836]: info: ------------------------------ 811ms Feb 16 13:25:20 pecanpiplus volumio[836]: info: ------------------------------ 809ms Feb 16 13:25:20 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:25:20 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:25:20 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:25:20 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:25:20 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:25:20 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:25:20 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:25:20 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:25:21 pecanpiplus volumio[836]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 16 13:25:25 pecanpiplus volumio[836]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 16 13:25:29 pecanpiplus volumio[836]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 16 13:25:30 pecanpiplus volumio[836]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 16 13:25:30 pecanpiplus volumio[836]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 16 13:25:30 pecanpiplus volumio[836]: info: Discovery: Getting this device information Feb 16 13:25:30 pecanpiplus volumio[836]: info: CoreCommandRouter::volumioGetState Feb 16 13:25:30 pecanpiplus volumio[836]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 16 13:25:30 pecanpiplus volumio[836]: {"status":"play","position":0,"title":"Hotel California","artist":"Eagles","album":"Hell Freezes Over (Remaster 2018)","albumart":"https://resources.tidal.com/images/0c4a6832/8d14/4bac/b69d/7ef39abb1c55/640x640.jpg","uri":"tidal://song/105048155","trackType":"tidal","seek":127889,"duration":432,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"bitrate":"944 Kbps","random":false,"repeat":false,"repeatSingle":false,"consume":true,"volume":85,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"updatedb":false,"volatile":false,"service":"tidal"} Feb 16 13:25:30 pecanpiplus volumio[836]: ACTIVITY STATUS CHANGED TO: 1 Feb 16 13:25:30 pecanpiplus sudo[2243]: pam_unix(sudo:session): session closed for user root Feb 16 13:25:30 pecanpiplus volumio[836]: info: Cannot mount NAS Synology at system boot, trial number 4 ,retrying in 5 seconds Feb 16 13:25:33 pecanpiplus volumio[836]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 16 13:25:35 pecanpiplus volumio[836]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 16 13:25:35 pecanpiplus volumio[836]: TypeError: Cannot read property 'replace' of undefined Feb 16 13:25:35 pecanpiplus volumio[836]: at ControllerNetworkfs.mountShare (/volumio/app/plugins/system_controller/networkfs/index.js:202:28) Feb 16 13:25:35 pecanpiplus volumio[836]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/networkfs/index.js:255:20) Feb 16 13:25:35 pecanpiplus volumio[836]: at listOnTimeout (internal/timers.js:554:17) Feb 16 13:25:35 pecanpiplus volumio[836]: at processTimers (internal/timers.js:497:7) Feb 16 13:25:35 pecanpiplus volumio[836]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 16 13:25:37 pecanpiplus sudo[2884]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-16 13:24 Feb 16 13:25:37 pecanpiplus sudo[2884]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="orchard" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Sep 2024 07:34:39 PM CEST" VOLUMIO_VERSION="3.748" VOLUMIO_HARDWARE="pi-kiosk" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_VENDOR_MODEL="Orchard Audio PecanPi+ Streamer Premium" VOLUMIO_VENDOR="Orchard Audio" VOLUMIO_MODEL="PecanPi+ Streamer Premium" VOLUMIO_HASH="5aadc06e7acdb98d9c29f0e1fbdef53d"