Mar 25 20:57:02 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 25 20:57:02 bevolinnomkpro volumio[1329]: info: Preload queue cleared Mar 25 20:57:05 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 25 20:57:05 bevolinnomkpro volumio[1329]: info: Preload queue cleared Mar 25 20:57:06 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 25 20:57:06 bevolinnomkpro volumio[1329]: info: Preload queue cleared Mar 25 20:57:09 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 25 20:57:09 bevolinnomkpro volumio[1329]: info: Preload queue cleared Mar 25 20:57:15 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 25 20:57:15 bevolinnomkpro volumio[1329]: info: Preload queue cleared Mar 25 20:57:20 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 25 20:57:20 bevolinnomkpro volumio[1329]: info: Preload queue cleared Mar 25 20:57:20 bevolinnomkpro volumio[1329]: info: Preloading song: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42988 Mar 25 20:57:20 bevolinnomkpro volumio[1329]: info: Preloading song: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42989 Mar 25 20:57:20 bevolinnomkpro volumio[1329]: info: Preloading song: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42990 Mar 25 20:57:20 bevolinnomkpro volumio[1329]: info: Preloading song: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42991 Mar 25 20:57:20 bevolinnomkpro volumio[1329]: info: Preloading song: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42992 Mar 25 20:57:20 bevolinnomkpro volumio[1329]: info: Preloading song: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42993 Mar 25 20:57:20 bevolinnomkpro volumio[1329]: info: Preloading song: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42994 Mar 25 20:57:20 bevolinnomkpro volumio[1329]: info: Preloading song: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42995 Mar 25 20:57:20 bevolinnomkpro volumio[1329]: info: Preloading song: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42996 Mar 25 20:57:20 bevolinnomkpro volumio[1329]: info: Preloading song: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42997 Mar 25 20:57:20 bevolinnomkpro volumio[1329]: info: Preloading song: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42998 Mar 25 20:57:20 bevolinnomkpro volumio[1329]: info: Preloading song: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42999 Mar 25 20:57:20 bevolinnomkpro volumio[1329]: info: Preloading song: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@43000 Mar 25 20:57:20 bevolinnomkpro volumio[1329]: info: Preloading song: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@43001 Mar 25 20:57:20 bevolinnomkpro volumio[1329]: info: Preloading song: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@43002 Mar 25 20:57:20 bevolinnomkpro volumio[1329]: info: Preloading song: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@43003 Mar 25 20:57:20 bevolinnomkpro volumio[1329]: info: Preloading song: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@43004 Mar 25 20:57:20 bevolinnomkpro volumio[1329]: info: Exploding uri upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42988 in service upnp_browser Mar 25 20:57:20 bevolinnomkpro volumio[1329]: info: Exploding uri upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42989 in service upnp_browser Mar 25 20:57:21 bevolinnomkpro volumio[1329]: info: Exploding uri upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42990 in service upnp_browser Mar 25 20:57:21 bevolinnomkpro volumio[1329]: info: Exploding uri upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42991 in service upnp_browser Mar 25 20:57:21 bevolinnomkpro volumio[1329]: info: Exploding uri upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42992 in service upnp_browser Mar 25 20:57:21 bevolinnomkpro volumio[1329]: info: Exploding uri upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42993 in service upnp_browser Mar 25 20:57:21 bevolinnomkpro volumio[1329]: info: Exploding uri upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42994 in service upnp_browser Mar 25 20:57:21 bevolinnomkpro volumio[1329]: info: Exploding uri upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42995 in service upnp_browser Mar 25 20:57:21 bevolinnomkpro volumio[1329]: info: Exploding uri upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42996 in service upnp_browser Mar 25 20:57:21 bevolinnomkpro volumio[1329]: info: Exploding uri upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42997 in service upnp_browser Mar 25 20:57:21 bevolinnomkpro volumio[1329]: info: Exploding uri upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42998 in service upnp_browser Mar 25 20:57:21 bevolinnomkpro volumio[1329]: info: Exploding uri upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42999 in service upnp_browser Mar 25 20:57:21 bevolinnomkpro volumio[1329]: info: Exploding uri upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@43000 in service upnp_browser Mar 25 20:57:21 bevolinnomkpro volumio[1329]: info: Exploding uri upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@43001 in service upnp_browser Mar 25 20:57:21 bevolinnomkpro volumio[1329]: info: Exploding uri upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@43002 in service upnp_browser Mar 25 20:57:21 bevolinnomkpro volumio[1329]: info: Exploding uri upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@43003 in service upnp_browser Mar 25 20:57:21 bevolinnomkpro volumio[1329]: info: Exploding uri upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@43004 in service upnp_browser Mar 25 20:57:25 bevolinnomkpro volumio[1329]: info: Preload queue cleared Mar 25 20:57:25 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 25 20:57:25 bevolinnomkpro volumio[1329]: info: CoreStateMachine::ClearQueue Mar 25 20:57:25 bevolinnomkpro volumio[1329]: info: CoreStateMachine::stop Mar 25 20:57:25 bevolinnomkpro volumio[1329]: info: CoreStateMachine::serviceStop Mar 25 20:57:25 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::serviceStop Mar 25 20:57:25 bevolinnomkpro volumio[1329]: info: Airplay Stop Mar 25 20:57:25 bevolinnomkpro volumio[1329]: info: Stopping Airplay Playback and sending pause command to client via USR2 Mar 25 20:57:25 bevolinnomkpro volumio[1329]: info: CorePlayQueue::clearPlayQueue Mar 25 20:57:25 bevolinnomkpro volumio[1329]: info: CorePlayQueue::saveQueue Mar 25 20:57:25 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioPushQueue Mar 25 20:57:25 bevolinnomkpro volumio[1329]: info: CoreStateMachine::addQueueItems Mar 25 20:57:26 bevolinnomkpro volumio[1329]: info: CorePlayQueue::addQueueItems Mar 25 20:57:26 bevolinnomkpro volumio[1329]: info: Preload queue cleared Mar 25 20:57:26 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42988 Mar 25 20:57:26 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42988 Mar 25 20:57:26 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42989 Mar 25 20:57:26 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42989 Mar 25 20:57:26 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42990 Mar 25 20:57:26 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42990 Mar 25 20:57:26 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42991 Mar 25 20:57:26 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42991 Mar 25 20:57:26 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42992 Mar 25 20:57:26 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42992 Mar 25 20:57:26 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42993 Mar 25 20:57:26 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42993 Mar 25 20:57:26 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42994 Mar 25 20:57:26 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42994 Mar 25 20:57:26 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42995 Mar 25 20:57:26 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42995 Mar 25 20:57:26 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42996 Mar 25 20:57:26 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42996 Mar 25 20:57:26 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42997 Mar 25 20:57:26 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42997 Mar 25 20:57:26 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42998 Mar 25 20:57:26 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42998 Mar 25 20:57:26 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioPushQueue Mar 25 20:57:26 bevolinnomkpro volumio[1329]: info: CorePlayQueue::saveQueue Mar 25 20:57:26 bevolinnomkpro volumio[1329]: info: CoreStateMachine::updateTrackBlock Mar 25 20:57:26 bevolinnomkpro volumio[1329]: info: CorePlayQueue::getTrackBlock Mar 25 20:57:26 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioPlay Mar 25 20:57:26 bevolinnomkpro volumio[1329]: verbose: UNSET VOLATILE: Service: airplay_emulation Mar 25 20:57:26 bevolinnomkpro volumio[1329]: info: Stopping Airplay Playback and sending pause command to client via USR2 Mar 25 20:57:26 bevolinnomkpro volumio[1329]: info: CoreStateMachine::play index 10 Mar 25 20:57:26 bevolinnomkpro volumio[1329]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 20:57:26 bevolinnomkpro volumio[1329]: info: CoreStateMachine::addQueueItems Mar 25 20:57:26 bevolinnomkpro volumio[1329]: info: CorePlayQueue::addQueueItems Mar 25 20:57:26 bevolinnomkpro volumio[1329]: info: Preload queue cleared Mar 25 20:57:26 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42999 Mar 25 20:57:26 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42999 Mar 25 20:57:26 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@43000 Mar 25 20:57:26 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@43000 Mar 25 20:57:26 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@43001 Mar 25 20:57:26 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@43001 Mar 25 20:57:26 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@43002 Mar 25 20:57:26 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@43002 Mar 25 20:57:26 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@43003 Mar 25 20:57:26 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@43003 Mar 25 20:57:26 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@43004 Mar 25 20:57:26 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@43004 Mar 25 20:57:26 bevolinnomkpro volumio[1329]: info: CoreStateMachine::stop Mar 25 20:57:26 bevolinnomkpro volumio[1329]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 20:57:26 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioPushQueue Mar 25 20:57:26 bevolinnomkpro volumio[1329]: info: CorePlayQueue::saveQueue Mar 25 20:57:26 bevolinnomkpro volumio[1329]: info: CoreStateMachine::play index undefined Mar 25 20:57:26 bevolinnomkpro volumio[1329]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 20:57:26 bevolinnomkpro volumio[1329]: info: CoreStateMachine::updateTrackBlock Mar 25 20:57:26 bevolinnomkpro volumio[1329]: info: CorePlayQueue::getTrackBlock Mar 25 20:57:26 bevolinnomkpro volumio[1329]: info: CorePlayQueue::getTrack 10 Mar 25 20:57:26 bevolinnomkpro sudo[3637]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 1936 Mar 25 20:57:26 bevolinnomkpro sudo[3640]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 1936 Mar 25 20:57:26 bevolinnomkpro sudo[3640]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 20:57:26 bevolinnomkpro sudo[3637]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 20:57:26 bevolinnomkpro sudo[3637]: pam_unix(sudo:session): session closed for user root Mar 25 20:57:26 bevolinnomkpro sudo[3640]: pam_unix(sudo:session): session closed for user root Mar 25 20:57:26 bevolinnomkpro shairport-sync[3542]: {"time":1774468611818,"response":"airplayInactive Success"} Mar 25 20:57:26 bevolinnomkpro volumio[1329]: info: Shairport-Sync paused with USR2 Mar 25 20:57:26 bevolinnomkpro volumio[1329]: info: Shairport-Sync paused with USR2 Mar 25 20:57:26 bevolinnomkpro systemd[1]: shairport-sync.service: Main process exited, code=killed, status=12/USR2 Mar 25 20:57:26 bevolinnomkpro systemd[1]: shairport-sync.service: Failed with result 'signal'. Mar 25 20:57:26 bevolinnomkpro systemd[1]: shairport-sync.service: Consumed 6.259s CPU time. Mar 25 20:57:27 bevolinnomkpro volumio[1329]: info: Restarting Shairport-Sync after stop Mar 25 20:57:27 bevolinnomkpro volumio[1329]: info: Restarting Shairport-Sync after stop Mar 25 20:57:27 bevolinnomkpro sudo[3645]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 25 20:57:27 bevolinnomkpro sudo[3645]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 20:57:27 bevolinnomkpro sudo[3647]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 25 20:57:27 bevolinnomkpro sudo[3647]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 25 20:57:27 bevolinnomkpro systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 25 20:57:27 bevolinnomkpro sudo[3647]: pam_unix(sudo:session): session closed for user root Mar 25 20:57:27 bevolinnomkpro sudo[3645]: pam_unix(sudo:session): session closed for user root Mar 25 20:57:27 bevolinnomkpro volumio[1329]: info: Shairport-Sync retarted Mar 25 20:57:27 bevolinnomkpro volumio[1329]: info: Shairport-Sync retarted Mar 25 20:57:30 bevolinnomkpro volumio[1329]: error: error Mar 25 20:57:30 bevolinnomkpro volumio[1329]: error: error Mar 25 20:57:30 bevolinnomkpro volumio[1329]: error: error Mar 25 20:57:30 bevolinnomkpro volumio[1329]: error: error Mar 25 20:57:31 bevolinnomkpro volumio[1329]: error: error Mar 25 20:57:31 bevolinnomkpro volumio[1329]: error: error Mar 25 20:57:31 bevolinnomkpro volumio[1329]: error: error Mar 25 20:57:31 bevolinnomkpro volumio[1329]: error: error Mar 25 20:57:31 bevolinnomkpro volumio[1329]: error: error Mar 25 20:57:31 bevolinnomkpro volumio[1329]: error: error Mar 25 20:57:31 bevolinnomkpro volumio[1329]: error: error Mar 25 20:57:31 bevolinnomkpro volumio[1329]: error: error Mar 25 20:57:31 bevolinnomkpro volumio[1329]: error: error Mar 25 20:57:31 bevolinnomkpro volumio[1329]: error: error Mar 25 20:57:31 bevolinnomkpro volumio[1329]: error: error Mar 25 20:57:31 bevolinnomkpro volumio[1329]: error: error Mar 25 20:57:31 bevolinnomkpro volumio[1329]: error: error Mar 25 20:57:31 bevolinnomkpro volumio[1329]: error: error Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Preload queue cleared Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CoreStateMachine::ClearQueue Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CoreStateMachine::stop Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CorePlayQueue::clearPlayQueue Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CorePlayQueue::saveQueue Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioPushQueue Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CoreStateMachine::addQueueItems Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CorePlayQueue::addQueueItems Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Preload queue cleared Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42988 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42988 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42989 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42989 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42990 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42990 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42991 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42991 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42992 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42992 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42993 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42993 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42994 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42994 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42995 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42995 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42996 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42996 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42997 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42997 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42998 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42998 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioPushQueue Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CorePlayQueue::saveQueue Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CoreStateMachine::updateTrackBlock Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CorePlayQueue::getTrackBlock Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioPlay Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CoreStateMachine::play index 10 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CoreStateMachine::addQueueItems Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CorePlayQueue::addQueueItems Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Preload queue cleared Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42999 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42999 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@43000 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@43000 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@43001 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@43001 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@43002 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@43002 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@43003 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@43003 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@43004 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@43004 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CoreStateMachine::stop Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioPushQueue Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CorePlayQueue::saveQueue Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CoreStateMachine::play index undefined Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CoreStateMachine::updateTrackBlock Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CorePlayQueue::getTrackBlock Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CorePlayQueue::getTrack 10 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Preload queue cleared Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CoreStateMachine::ClearQueue Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CoreStateMachine::stop Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CorePlayQueue::clearPlayQueue Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CorePlayQueue::saveQueue Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioPushQueue Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CoreStateMachine::addQueueItems Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CorePlayQueue::addQueueItems Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Preload queue cleared Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42988 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42988 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42989 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42989 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42990 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42990 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42991 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42991 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42992 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42992 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42993 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42993 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42994 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42994 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42995 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42995 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42996 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42996 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42997 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42997 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42998 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42998 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioPushQueue Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CorePlayQueue::saveQueue Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CoreStateMachine::updateTrackBlock Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CorePlayQueue::getTrackBlock Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioPlay Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CoreStateMachine::play index 10 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CoreStateMachine::addQueueItems Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CorePlayQueue::addQueueItems Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Preload queue cleared Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42999 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42999 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@43000 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@43000 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@43001 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@43001 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@43002 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@43002 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@43003 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@43003 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@43004 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@43004 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CoreStateMachine::stop Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioPushQueue Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CorePlayQueue::saveQueue Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CoreStateMachine::play index undefined Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CoreStateMachine::updateTrackBlock Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CorePlayQueue::getTrackBlock Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CorePlayQueue::getTrack 10 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Preload queue cleared Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CoreStateMachine::ClearQueue Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CoreStateMachine::stop Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CorePlayQueue::clearPlayQueue Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CorePlayQueue::saveQueue Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioPushQueue Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CoreStateMachine::addQueueItems Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CorePlayQueue::addQueueItems Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Preload queue cleared Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42988 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42988 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42989 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42989 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42990 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42990 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42991 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42991 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42992 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42992 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42993 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42993 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42994 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42994 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42995 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42995 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42996 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42996 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42997 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42997 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42998 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42998 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioPushQueue Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CorePlayQueue::saveQueue Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CoreStateMachine::updateTrackBlock Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CorePlayQueue::getTrackBlock Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioPlay Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CoreStateMachine::play index 10 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CoreStateMachine::addQueueItems Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CorePlayQueue::addQueueItems Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Preload queue cleared Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42999 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42999 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@43000 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@43000 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@43001 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@43001 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@43002 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@43002 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@43003 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@43003 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@43004 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@43004 Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CoreStateMachine::stop Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioPushQueue Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CorePlayQueue::saveQueue Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CoreStateMachine::play index undefined Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CoreStateMachine::updateTrackBlock Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CorePlayQueue::getTrackBlock Mar 25 20:57:32 bevolinnomkpro volumio[1329]: info: CorePlayQueue::getTrack 10 Mar 25 20:57:40 bevolinnomkpro volumio[1329]: info: Preload queue cleared Mar 25 20:57:40 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 25 20:57:40 bevolinnomkpro volumio[1329]: info: CoreStateMachine::ClearQueue Mar 25 20:57:40 bevolinnomkpro volumio[1329]: info: CoreStateMachine::stop Mar 25 20:57:40 bevolinnomkpro volumio[1329]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 20:57:40 bevolinnomkpro volumio[1329]: info: CorePlayQueue::clearPlayQueue Mar 25 20:57:40 bevolinnomkpro volumio[1329]: info: CorePlayQueue::saveQueue Mar 25 20:57:40 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioPushQueue Mar 25 20:57:40 bevolinnomkpro volumio[1329]: info: CoreStateMachine::addQueueItems Mar 25 20:57:40 bevolinnomkpro volumio[1329]: info: CorePlayQueue::addQueueItems Mar 25 20:57:40 bevolinnomkpro volumio[1329]: info: Preload queue cleared Mar 25 20:57:40 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42988 Mar 25 20:57:40 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42988 Mar 25 20:57:40 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42989 Mar 25 20:57:40 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42989 Mar 25 20:57:40 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42990 Mar 25 20:57:40 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42990 Mar 25 20:57:40 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42991 Mar 25 20:57:40 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42991 Mar 25 20:57:40 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42992 Mar 25 20:57:40 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42992 Mar 25 20:57:40 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42993 Mar 25 20:57:40 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42993 Mar 25 20:57:40 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42994 Mar 25 20:57:40 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42994 Mar 25 20:57:40 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42995 Mar 25 20:57:40 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42995 Mar 25 20:57:40 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42996 Mar 25 20:57:40 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42996 Mar 25 20:57:40 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42997 Mar 25 20:57:40 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42997 Mar 25 20:57:40 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42998 Mar 25 20:57:40 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42998 Mar 25 20:57:40 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioPushQueue Mar 25 20:57:40 bevolinnomkpro volumio[1329]: info: CorePlayQueue::saveQueue Mar 25 20:57:40 bevolinnomkpro volumio[1329]: info: CoreStateMachine::updateTrackBlock Mar 25 20:57:40 bevolinnomkpro volumio[1329]: info: CorePlayQueue::getTrackBlock Mar 25 20:57:40 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioPlay Mar 25 20:57:40 bevolinnomkpro volumio[1329]: info: CoreStateMachine::play index 10 Mar 25 20:57:40 bevolinnomkpro volumio[1329]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 20:57:40 bevolinnomkpro volumio[1329]: info: CoreStateMachine::addQueueItems Mar 25 20:57:40 bevolinnomkpro volumio[1329]: info: CorePlayQueue::addQueueItems Mar 25 20:57:40 bevolinnomkpro volumio[1329]: info: Preload queue cleared Mar 25 20:57:40 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42999 Mar 25 20:57:40 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@42999 Mar 25 20:57:40 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@43000 Mar 25 20:57:40 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@43000 Mar 25 20:57:40 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@43001 Mar 25 20:57:40 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@43001 Mar 25 20:57:40 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@43002 Mar 25 20:57:40 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@43002 Mar 25 20:57:40 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@43003 Mar 25 20:57:40 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@43003 Mar 25 20:57:40 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@43004 Mar 25 20:57:40 bevolinnomkpro volumio[1329]: info: Using cached record of: upnp/http://192.168.170.45:50001/ContentDirectory/control@26$42988$@43004 Mar 25 20:57:40 bevolinnomkpro volumio[1329]: info: CoreStateMachine::stop Mar 25 20:57:40 bevolinnomkpro volumio[1329]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 20:57:40 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioPushQueue Mar 25 20:57:40 bevolinnomkpro volumio[1329]: info: CorePlayQueue::saveQueue Mar 25 20:57:40 bevolinnomkpro volumio[1329]: info: CoreStateMachine::play index undefined Mar 25 20:57:40 bevolinnomkpro volumio[1329]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 20:57:40 bevolinnomkpro volumio[1329]: info: CoreStateMachine::updateTrackBlock Mar 25 20:57:40 bevolinnomkpro volumio[1329]: info: CorePlayQueue::getTrackBlock Mar 25 20:57:40 bevolinnomkpro volumio[1329]: info: CorePlayQueue::getTrack 10 Mar 25 20:57:42 bevolinnomkpro volumio[1329]: info: Preload queue cleared Mar 25 20:57:42 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 25 20:57:42 bevolinnomkpro volumio[1329]: info: CoreStateMachine::ClearQueue Mar 25 20:57:42 bevolinnomkpro volumio[1329]: info: CoreStateMachine::stop Mar 25 20:57:42 bevolinnomkpro volumio[1329]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 20:57:42 bevolinnomkpro volumio[1329]: info: CorePlayQueue::clearPlayQueue Mar 25 20:57:42 bevolinnomkpro volumio[1329]: info: CorePlayQueue::saveQueue Mar 25 20:57:42 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioPushQueue Mar 25 20:57:42 bevolinnomkpro volumio[1329]: info: CoreStateMachine::addQueueItems Mar 25 20:57:42 bevolinnomkpro volumio[1329]: info: CorePlayQueue::addQueueItems Mar 25 20:57:42 bevolinnomkpro volumio[1329]: info: Preload queue cleared Mar 25 20:57:42 bevolinnomkpro volumio[1329]: info: Adding Item to queue: upnp/folder/http://192.168.170.45:50001/ContentDirectory/control@26$42988 Mar 25 20:57:42 bevolinnomkpro volumio[1329]: info: Exploding uri upnp/folder/http://192.168.170.45:50001/ContentDirectory/control@26$42988 in service upnp_browser Mar 25 20:57:42 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioPushQueue Mar 25 20:57:42 bevolinnomkpro volumio[1329]: info: CorePlayQueue::saveQueue Mar 25 20:57:42 bevolinnomkpro volumio[1329]: info: CoreStateMachine::updateTrackBlock Mar 25 20:57:42 bevolinnomkpro volumio[1329]: info: CorePlayQueue::getTrackBlock Mar 25 20:57:42 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioPlay Mar 25 20:57:42 bevolinnomkpro volumio[1329]: info: CoreStateMachine::play index 0 Mar 25 20:57:42 bevolinnomkpro volumio[1329]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 20:57:42 bevolinnomkpro volumio[1329]: info: CoreStateMachine::stop Mar 25 20:57:42 bevolinnomkpro volumio[1329]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 20:57:42 bevolinnomkpro volumio[1329]: info: CoreStateMachine::play index undefined Mar 25 20:57:42 bevolinnomkpro volumio[1329]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 20:57:42 bevolinnomkpro volumio[1329]: info: CorePlayQueue::getTrack 0 Mar 25 20:57:42 bevolinnomkpro volumio[1329]: info: CoreStateMachine::startPlaybackTimer Mar 25 20:57:42 bevolinnomkpro volumio[1329]: info: CorePlayQueue::getTrack 0 Mar 25 20:57:42 bevolinnomkpro volumio[1329]: info: [1774468662540] ControllerUPNPBrowser::clearAddPlayTrack Mar 25 20:57:42 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand stop Mar 25 20:57:42 bevolinnomkpro volumio[1329]: info: sendMpdCommand stop took 13 milliseconds Mar 25 20:57:42 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand clear Mar 25 20:57:42 bevolinnomkpro volumio[1329]: info: Mar 25 20:57:42 bevolinnomkpro volumio[1329]: ---------------------------- MPD announces system playlist update Mar 25 20:57:42 bevolinnomkpro volumio[1329]: info: Ignoring MPD Status Update Mar 25 20:57:42 bevolinnomkpro volumio[1329]: info: sendMpdCommand clear took 3 milliseconds Mar 25 20:57:42 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.170.45:50002/m/NDLNA/42988.m4a" Mar 25 20:57:42 bevolinnomkpro volumio[1329]: error: updateQueue error: null Mar 25 20:57:42 bevolinnomkpro volumio[1329]: info: ------------------------------ 6ms Mar 25 20:57:42 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.170.45:50002/m/NDLNA/42988.m4a" Mar 25 20:57:42 bevolinnomkpro volumio[1329]: info: Mar 25 20:57:42 bevolinnomkpro volumio[1329]: ---------------------------- MPD announces system playlist update Mar 25 20:57:42 bevolinnomkpro volumio[1329]: info: Ignoring MPD Status Update Mar 25 20:57:42 bevolinnomkpro volumio[1329]: info: sendMpdCommand add "http://192.168.170.45:50002/m/NDLNA/42988.m4a" took 3 milliseconds Mar 25 20:57:42 bevolinnomkpro volumio[1329]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 25 20:57:42 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand play Mar 25 20:57:42 bevolinnomkpro volumio[1329]: info: ------------------------------ 6ms Mar 25 20:57:42 bevolinnomkpro volumio[1329]: info: sendMpdCommand play took 4 milliseconds Mar 25 20:57:43 bevolinnomkpro volumio[1329]: info: Mar 25 20:57:43 bevolinnomkpro volumio[1329]: ---------------------------- MPD announces state update: player Mar 25 20:57:43 bevolinnomkpro volumio[1329]: info: ControllerMpd::getState Mar 25 20:57:43 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand status Mar 25 20:57:43 bevolinnomkpro volumio[1329]: info: Mar 25 20:57:43 bevolinnomkpro volumio[1329]: ---------------------------- MPD announces state update: player Mar 25 20:57:43 bevolinnomkpro volumio[1329]: info: sendMpdCommand status took 8 milliseconds Mar 25 20:57:43 bevolinnomkpro volumio[1329]: info: ControllerMpd::getState Mar 25 20:57:43 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand status Mar 25 20:57:43 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::parseState Mar 25 20:57:43 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 20:57:43 bevolinnomkpro volumio[1329]: info: sendMpdCommand status took 4 milliseconds Mar 25 20:57:43 bevolinnomkpro volumio[1329]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 25 20:57:43 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::parseState Mar 25 20:57:43 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 20:57:43 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::parseTrackInfo Mar 25 20:57:43 bevolinnomkpro volumio[1329]: info: ControllerMpd::pushState Mar 25 20:57:43 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::servicePushState Mar 25 20:57:43 bevolinnomkpro volumio[1329]: info: CorePlayQueue::getTrack 0 Mar 25 20:57:43 bevolinnomkpro volumio[1329]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":50,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"42988.m4a","artist":null,"album":null,"uri":"http://192.168.170.45:50002/m/NDLNA/42988.m4a","trackType":"m4a"} Mar 25 20:57:43 bevolinnomkpro volumio[1329]: verbose: CURRENT POSITION 0 Mar 25 20:57:43 bevolinnomkpro volumio[1329]: info: CoreStateMachine::syncState stateService play Mar 25 20:57:43 bevolinnomkpro volumio[1329]: info: CoreStateMachine::syncState currentStatus stop Mar 25 20:57:43 bevolinnomkpro volumio[1329]: info: ------------------------------ 23ms Mar 25 20:57:43 bevolinnomkpro volumio[1329]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 25 20:57:43 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::parseTrackInfo Mar 25 20:57:43 bevolinnomkpro volumio[1329]: info: ControllerMpd::pushState Mar 25 20:57:43 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::servicePushState Mar 25 20:57:43 bevolinnomkpro volumio[1329]: info: CorePlayQueue::getTrack 0 Mar 25 20:57:43 bevolinnomkpro volumio[1329]: verbose: STATE SERVICE {"status":"play","position":0,"seek":962,"duration":50,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"615 Kbps","isStreaming":false,"title":"42988.m4a","artist":null,"album":null,"uri":"http://192.168.170.45:50002/m/NDLNA/42988.m4a","trackType":"m4a"} Mar 25 20:57:43 bevolinnomkpro volumio[1329]: verbose: CURRENT POSITION 0 Mar 25 20:57:43 bevolinnomkpro volumio[1329]: info: CoreStateMachine::syncState stateService play Mar 25 20:57:43 bevolinnomkpro volumio[1329]: info: CoreStateMachine::syncState currentStatus play Mar 25 20:57:43 bevolinnomkpro volumio[1329]: info: Received an update from plugin. extracting info from payload Mar 25 20:57:43 bevolinnomkpro volumio[1329]: info: CoreStateMachine::pushState Mar 25 20:57:43 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 20:57:43 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioPushState Mar 25 20:57:43 bevolinnomkpro volumio[1329]: info: CoreStateMachine::pushState Mar 25 20:57:43 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioPushState Mar 25 20:57:43 bevolinnomkpro volumio[1329]: info: ------------------------------ 77ms Mar 25 20:57:43 bevolinnomkpro volumio[1329]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Mar 25 20:57:43 bevolinnomkpro volumio[1329]: info: FusionDsp - ---- read samplerate from file: 44100 Mar 25 20:57:43 bevolinnomkpro volumio[1329]: info: touch_display: Setting screensaver timeout to 0 seconds. Mar 25 20:57:43 bevolinnomkpro volumio[1329]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 25 20:57:52 bevolinnomkpro volumio[1329]: info: VolumeController::SetAlsaVolume55 Mar 25 20:57:52 bevolinnomkpro volumio[1329]: info: CoreStateMachine::pushState Mar 25 20:57:52 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 20:57:52 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioPushState Mar 25 20:57:53 bevolinnomkpro volumio[1329]: info: VolumeController::SetAlsaVolume45 Mar 25 20:57:53 bevolinnomkpro volumio[1329]: info: CoreStateMachine::pushState Mar 25 20:57:53 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 20:57:53 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioPushState Mar 25 20:57:57 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioNext Mar 25 20:57:57 bevolinnomkpro volumio[1329]: info: CoreStateMachine::next Mar 25 20:57:57 bevolinnomkpro volumio[1329]: info: ControllerMpd::next Mar 25 20:57:57 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand next Mar 25 20:57:57 bevolinnomkpro volumio[1329]: info: Mar 25 20:57:57 bevolinnomkpro volumio[1329]: ---------------------------- MPD announces state update: player Mar 25 20:57:57 bevolinnomkpro volumio[1329]: info: sendMpdCommand next took 6 milliseconds Mar 25 20:57:57 bevolinnomkpro volumio[1329]: info: ControllerMpd::getState Mar 25 20:57:57 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand status Mar 25 20:57:57 bevolinnomkpro volumio[1329]: info: sendMpdCommand status took 3 milliseconds Mar 25 20:57:57 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::parseState Mar 25 20:57:57 bevolinnomkpro volumio[1329]: info: ControllerMpd::pushState Mar 25 20:57:57 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::servicePushState Mar 25 20:57:57 bevolinnomkpro volumio[1329]: info: CoreStateMachine::pushState Mar 25 20:57:57 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 20:57:57 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioPushState Mar 25 20:57:57 bevolinnomkpro volumio[1329]: info: CorePlayQueue::getTrack 0 Mar 25 20:57:57 bevolinnomkpro volumio[1329]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 25 20:57:57 bevolinnomkpro volumio[1329]: verbose: CURRENT POSITION 0 Mar 25 20:57:57 bevolinnomkpro volumio[1329]: info: CoreStateMachine::syncState stateService stop Mar 25 20:57:57 bevolinnomkpro volumio[1329]: info: CoreStateMachine::syncState currentStatus play Mar 25 20:57:57 bevolinnomkpro volumio[1329]: info: CoreStateMachine::play index undefined Mar 25 20:57:57 bevolinnomkpro volumio[1329]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 20:57:57 bevolinnomkpro volumio[1329]: info: CoreStateMachine::pushState Mar 25 20:57:57 bevolinnomkpro volumio[1329]: info: CorePlayQueue::getTrack 1 Mar 25 20:57:57 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 20:57:57 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioPushState Mar 25 20:57:57 bevolinnomkpro volumio[1329]: info: CorePlayQueue::getTrack 1 Mar 25 20:57:57 bevolinnomkpro volumio[1329]: info: CoreStateMachine::startPlaybackTimer Mar 25 20:57:57 bevolinnomkpro volumio[1329]: info: CorePlayQueue::getTrack 1 Mar 25 20:57:57 bevolinnomkpro volumio[1329]: info: [1774468677302] ControllerUPNPBrowser::clearAddPlayTrack Mar 25 20:57:57 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand stop Mar 25 20:57:57 bevolinnomkpro volumio[1329]: info: ------------------------------ 48ms Mar 25 20:57:57 bevolinnomkpro volumio[1329]: info: CoreStateMachine::pushState Mar 25 20:57:57 bevolinnomkpro volumio[1329]: info: CorePlayQueue::getTrack 1 Mar 25 20:57:57 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 20:57:57 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioPushState Mar 25 20:57:57 bevolinnomkpro volumio[1329]: info: sendMpdCommand stop took 43 milliseconds Mar 25 20:57:57 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand clear Mar 25 20:57:57 bevolinnomkpro volumio[1329]: info: Mar 25 20:57:57 bevolinnomkpro volumio[1329]: ---------------------------- MPD announces system playlist update Mar 25 20:57:57 bevolinnomkpro volumio[1329]: info: Ignoring MPD Status Update Mar 25 20:57:57 bevolinnomkpro volumio[1329]: info: sendMpdCommand clear took 48 milliseconds Mar 25 20:57:57 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.170.45:50002/m/NDLNA/42989.m4a" Mar 25 20:57:57 bevolinnomkpro volumio[1329]: error: updateQueue error: null Mar 25 20:57:57 bevolinnomkpro volumio[1329]: info: ------------------------------ 22ms Mar 25 20:57:57 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.170.45:50002/m/NDLNA/42989.m4a" Mar 25 20:57:57 bevolinnomkpro volumio[1329]: info: Mar 25 20:57:57 bevolinnomkpro volumio[1329]: ---------------------------- MPD announces system playlist update Mar 25 20:57:57 bevolinnomkpro volumio[1329]: info: Ignoring MPD Status Update Mar 25 20:57:57 bevolinnomkpro volumio[1329]: info: sendMpdCommand add "http://192.168.170.45:50002/m/NDLNA/42989.m4a" took 4 milliseconds Mar 25 20:57:57 bevolinnomkpro volumio[1329]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 25 20:57:57 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand play Mar 25 20:57:57 bevolinnomkpro volumio[1329]: info: ------------------------------ 9ms Mar 25 20:57:57 bevolinnomkpro volumio[1329]: info: sendMpdCommand play took 7 milliseconds Mar 25 20:57:57 bevolinnomkpro volumio[1329]: info: touch_display: Setting screensaver timeout to 300 seconds. Mar 25 20:57:57 bevolinnomkpro volumio[1329]: info: Mar 25 20:57:57 bevolinnomkpro volumio[1329]: ---------------------------- MPD announces state update: player Mar 25 20:57:57 bevolinnomkpro volumio[1329]: info: ControllerMpd::getState Mar 25 20:57:57 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand status Mar 25 20:57:57 bevolinnomkpro volumio[1329]: info: Mar 25 20:57:57 bevolinnomkpro volumio[1329]: ---------------------------- MPD announces state update: player Mar 25 20:57:57 bevolinnomkpro volumio[1329]: info: sendMpdCommand status took 9 milliseconds Mar 25 20:57:57 bevolinnomkpro volumio[1329]: info: ControllerMpd::getState Mar 25 20:57:57 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand status Mar 25 20:57:57 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::parseState Mar 25 20:57:57 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 20:57:57 bevolinnomkpro volumio[1329]: info: sendMpdCommand status took 4 milliseconds Mar 25 20:57:57 bevolinnomkpro volumio[1329]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 25 20:57:57 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::parseState Mar 25 20:57:57 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 20:57:57 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::parseTrackInfo Mar 25 20:57:57 bevolinnomkpro volumio[1329]: info: ControllerMpd::pushState Mar 25 20:57:57 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::servicePushState Mar 25 20:57:57 bevolinnomkpro volumio[1329]: info: CorePlayQueue::getTrack 1 Mar 25 20:57:57 bevolinnomkpro volumio[1329]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":250,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"42989.m4a","artist":null,"album":null,"uri":"http://192.168.170.45:50002/m/NDLNA/42989.m4a","trackType":"m4a"} Mar 25 20:57:57 bevolinnomkpro volumio[1329]: verbose: CURRENT POSITION 1 Mar 25 20:57:57 bevolinnomkpro volumio[1329]: info: CoreStateMachine::syncState stateService play Mar 25 20:57:57 bevolinnomkpro volumio[1329]: info: CoreStateMachine::syncState currentStatus stop Mar 25 20:57:57 bevolinnomkpro volumio[1329]: info: ------------------------------ 27ms Mar 25 20:57:57 bevolinnomkpro volumio[1329]: info: sendMpdCommand playlistinfo took 15 milliseconds Mar 25 20:57:57 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::parseTrackInfo Mar 25 20:57:57 bevolinnomkpro volumio[1329]: info: ControllerMpd::pushState Mar 25 20:57:57 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::servicePushState Mar 25 20:57:57 bevolinnomkpro volumio[1329]: info: CorePlayQueue::getTrack 1 Mar 25 20:57:57 bevolinnomkpro volumio[1329]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":250,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"42989.m4a","artist":null,"album":null,"uri":"http://192.168.170.45:50002/m/NDLNA/42989.m4a","trackType":"m4a"} Mar 25 20:57:57 bevolinnomkpro volumio[1329]: verbose: CURRENT POSITION 1 Mar 25 20:57:57 bevolinnomkpro volumio[1329]: info: CoreStateMachine::syncState stateService play Mar 25 20:57:57 bevolinnomkpro volumio[1329]: info: CoreStateMachine::syncState currentStatus play Mar 25 20:57:57 bevolinnomkpro volumio[1329]: info: Received an update from plugin. extracting info from payload Mar 25 20:57:57 bevolinnomkpro volumio[1329]: info: CoreStateMachine::pushState Mar 25 20:57:57 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 20:57:57 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioPushState Mar 25 20:57:57 bevolinnomkpro volumio[1329]: info: CoreStateMachine::pushState Mar 25 20:57:57 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioPushState Mar 25 20:57:57 bevolinnomkpro volumio[1329]: info: ------------------------------ 122ms Mar 25 20:57:57 bevolinnomkpro volumio[1329]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Mar 25 20:57:57 bevolinnomkpro volumio[1329]: info: FusionDsp - ---- read samplerate from file: 44100 Mar 25 20:57:57 bevolinnomkpro volumio[1329]: info: touch_display: Setting screensaver timeout to 0 seconds. Mar 25 20:57:57 bevolinnomkpro volumio[1329]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 25 20:58:00 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioNext Mar 25 20:58:00 bevolinnomkpro volumio[1329]: info: CoreStateMachine::next Mar 25 20:58:00 bevolinnomkpro volumio[1329]: info: ControllerMpd::next Mar 25 20:58:00 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand next Mar 25 20:58:00 bevolinnomkpro volumio[1329]: info: sendMpdCommand next took 12 milliseconds Mar 25 20:58:00 bevolinnomkpro volumio[1329]: info: Mar 25 20:58:00 bevolinnomkpro volumio[1329]: ---------------------------- MPD announces state update: player Mar 25 20:58:00 bevolinnomkpro volumio[1329]: info: ControllerMpd::getState Mar 25 20:58:00 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand status Mar 25 20:58:00 bevolinnomkpro volumio[1329]: info: sendMpdCommand status took 1 milliseconds Mar 25 20:58:00 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::parseState Mar 25 20:58:00 bevolinnomkpro volumio[1329]: info: ControllerMpd::pushState Mar 25 20:58:00 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::servicePushState Mar 25 20:58:00 bevolinnomkpro volumio[1329]: info: CoreStateMachine::pushState Mar 25 20:58:00 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 20:58:00 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioPushState Mar 25 20:58:00 bevolinnomkpro volumio[1329]: info: CorePlayQueue::getTrack 1 Mar 25 20:58:00 bevolinnomkpro volumio[1329]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 25 20:58:00 bevolinnomkpro volumio[1329]: verbose: CURRENT POSITION 1 Mar 25 20:58:00 bevolinnomkpro volumio[1329]: info: CoreStateMachine::syncState stateService stop Mar 25 20:58:00 bevolinnomkpro volumio[1329]: info: CoreStateMachine::syncState currentStatus play Mar 25 20:58:00 bevolinnomkpro volumio[1329]: info: CoreStateMachine::play index undefined Mar 25 20:58:00 bevolinnomkpro volumio[1329]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 20:58:00 bevolinnomkpro volumio[1329]: info: CoreStateMachine::pushState Mar 25 20:58:00 bevolinnomkpro volumio[1329]: info: CorePlayQueue::getTrack 2 Mar 25 20:58:00 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 20:58:00 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioPushState Mar 25 20:58:00 bevolinnomkpro volumio[1329]: info: CorePlayQueue::getTrack 2 Mar 25 20:58:00 bevolinnomkpro volumio[1329]: info: CoreStateMachine::startPlaybackTimer Mar 25 20:58:00 bevolinnomkpro volumio[1329]: info: CorePlayQueue::getTrack 2 Mar 25 20:58:00 bevolinnomkpro volumio[1329]: info: [1774468680704] ControllerUPNPBrowser::clearAddPlayTrack Mar 25 20:58:00 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand stop Mar 25 20:58:00 bevolinnomkpro volumio[1329]: info: ------------------------------ 59ms Mar 25 20:58:00 bevolinnomkpro volumio[1329]: info: CoreStateMachine::pushState Mar 25 20:58:00 bevolinnomkpro volumio[1329]: info: CorePlayQueue::getTrack 2 Mar 25 20:58:00 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 20:58:00 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioPushState Mar 25 20:58:00 bevolinnomkpro volumio[1329]: info: sendMpdCommand stop took 33 milliseconds Mar 25 20:58:00 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand clear Mar 25 20:58:00 bevolinnomkpro volumio[1329]: info: Mar 25 20:58:00 bevolinnomkpro volumio[1329]: ---------------------------- MPD announces system playlist update Mar 25 20:58:00 bevolinnomkpro volumio[1329]: info: Ignoring MPD Status Update Mar 25 20:58:00 bevolinnomkpro volumio[1329]: info: sendMpdCommand clear took 42 milliseconds Mar 25 20:58:00 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.170.45:50002/m/NDLNA/42990.m4a" Mar 25 20:58:00 bevolinnomkpro volumio[1329]: error: updateQueue error: null Mar 25 20:58:00 bevolinnomkpro volumio[1329]: info: ------------------------------ 25ms Mar 25 20:58:00 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.170.45:50002/m/NDLNA/42990.m4a" Mar 25 20:58:00 bevolinnomkpro volumio[1329]: info: Mar 25 20:58:00 bevolinnomkpro volumio[1329]: ---------------------------- MPD announces system playlist update Mar 25 20:58:00 bevolinnomkpro volumio[1329]: info: Ignoring MPD Status Update Mar 25 20:58:00 bevolinnomkpro volumio[1329]: info: sendMpdCommand add "http://192.168.170.45:50002/m/NDLNA/42990.m4a" took 6 milliseconds Mar 25 20:58:00 bevolinnomkpro volumio[1329]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 25 20:58:00 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand play Mar 25 20:58:00 bevolinnomkpro volumio[1329]: info: ------------------------------ 5ms Mar 25 20:58:00 bevolinnomkpro volumio[1329]: info: sendMpdCommand play took 4 milliseconds Mar 25 20:58:00 bevolinnomkpro volumio[1329]: info: touch_display: Setting screensaver timeout to 300 seconds. Mar 25 20:58:00 bevolinnomkpro volumio[1329]: info: Mar 25 20:58:00 bevolinnomkpro volumio[1329]: ---------------------------- MPD announces state update: player Mar 25 20:58:00 bevolinnomkpro volumio[1329]: info: ControllerMpd::getState Mar 25 20:58:00 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand status Mar 25 20:58:00 bevolinnomkpro volumio[1329]: info: Mar 25 20:58:00 bevolinnomkpro volumio[1329]: ---------------------------- MPD announces state update: player Mar 25 20:58:00 bevolinnomkpro volumio[1329]: info: sendMpdCommand status took 10 milliseconds Mar 25 20:58:00 bevolinnomkpro volumio[1329]: info: ControllerMpd::getState Mar 25 20:58:00 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand status Mar 25 20:58:00 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::parseState Mar 25 20:58:00 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 20:58:00 bevolinnomkpro volumio[1329]: info: sendMpdCommand status took 3 milliseconds Mar 25 20:58:00 bevolinnomkpro volumio[1329]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 25 20:58:00 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::parseState Mar 25 20:58:00 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 20:58:00 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::parseTrackInfo Mar 25 20:58:00 bevolinnomkpro volumio[1329]: info: ControllerMpd::pushState Mar 25 20:58:00 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::servicePushState Mar 25 20:58:00 bevolinnomkpro volumio[1329]: info: CorePlayQueue::getTrack 2 Mar 25 20:58:00 bevolinnomkpro volumio[1329]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":278,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"42990.m4a","artist":null,"album":null,"uri":"http://192.168.170.45:50002/m/NDLNA/42990.m4a","trackType":"m4a"} Mar 25 20:58:00 bevolinnomkpro volumio[1329]: verbose: CURRENT POSITION 2 Mar 25 20:58:00 bevolinnomkpro volumio[1329]: info: CoreStateMachine::syncState stateService play Mar 25 20:58:00 bevolinnomkpro volumio[1329]: info: CoreStateMachine::syncState currentStatus stop Mar 25 20:58:00 bevolinnomkpro volumio[1329]: info: ------------------------------ 23ms Mar 25 20:58:00 bevolinnomkpro volumio[1329]: info: sendMpdCommand playlistinfo took 7 milliseconds Mar 25 20:58:00 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::parseTrackInfo Mar 25 20:58:00 bevolinnomkpro volumio[1329]: info: ControllerMpd::pushState Mar 25 20:58:00 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::servicePushState Mar 25 20:58:00 bevolinnomkpro volumio[1329]: info: CorePlayQueue::getTrack 2 Mar 25 20:58:00 bevolinnomkpro volumio[1329]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":278,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"42990.m4a","artist":null,"album":null,"uri":"http://192.168.170.45:50002/m/NDLNA/42990.m4a","trackType":"m4a"} Mar 25 20:58:00 bevolinnomkpro volumio[1329]: verbose: CURRENT POSITION 2 Mar 25 20:58:00 bevolinnomkpro volumio[1329]: info: CoreStateMachine::syncState stateService play Mar 25 20:58:00 bevolinnomkpro volumio[1329]: info: CoreStateMachine::syncState currentStatus play Mar 25 20:58:00 bevolinnomkpro volumio[1329]: info: Received an update from plugin. extracting info from payload Mar 25 20:58:00 bevolinnomkpro volumio[1329]: info: CoreStateMachine::pushState Mar 25 20:58:00 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 20:58:00 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioPushState Mar 25 20:58:00 bevolinnomkpro volumio[1329]: info: CoreStateMachine::pushState Mar 25 20:58:00 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioPushState Mar 25 20:58:00 bevolinnomkpro volumio[1329]: info: ------------------------------ 102ms Mar 25 20:58:01 bevolinnomkpro volumio[1329]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Mar 25 20:58:01 bevolinnomkpro volumio[1329]: info: FusionDsp - ---- read samplerate from file: 44100 Mar 25 20:58:01 bevolinnomkpro volumio[1329]: info: touch_display: Setting screensaver timeout to 0 seconds. Mar 25 20:58:01 bevolinnomkpro volumio[1329]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 25 20:58:05 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioNext Mar 25 20:58:05 bevolinnomkpro volumio[1329]: info: CoreStateMachine::next Mar 25 20:58:05 bevolinnomkpro volumio[1329]: info: ControllerMpd::next Mar 25 20:58:05 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand next Mar 25 20:58:05 bevolinnomkpro volumio[1329]: info: Mar 25 20:58:05 bevolinnomkpro volumio[1329]: ---------------------------- MPD announces state update: player Mar 25 20:58:05 bevolinnomkpro volumio[1329]: info: sendMpdCommand next took 12 milliseconds Mar 25 20:58:05 bevolinnomkpro volumio[1329]: info: ControllerMpd::getState Mar 25 20:58:05 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand status Mar 25 20:58:05 bevolinnomkpro volumio[1329]: info: sendMpdCommand status took 2 milliseconds Mar 25 20:58:05 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::parseState Mar 25 20:58:05 bevolinnomkpro volumio[1329]: info: ControllerMpd::pushState Mar 25 20:58:05 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::servicePushState Mar 25 20:58:05 bevolinnomkpro volumio[1329]: info: CoreStateMachine::pushState Mar 25 20:58:05 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 20:58:05 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioPushState Mar 25 20:58:05 bevolinnomkpro volumio[1329]: info: CorePlayQueue::getTrack 2 Mar 25 20:58:05 bevolinnomkpro volumio[1329]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 25 20:58:05 bevolinnomkpro volumio[1329]: verbose: CURRENT POSITION 2 Mar 25 20:58:05 bevolinnomkpro volumio[1329]: info: CoreStateMachine::syncState stateService stop Mar 25 20:58:05 bevolinnomkpro volumio[1329]: info: CoreStateMachine::syncState currentStatus play Mar 25 20:58:05 bevolinnomkpro volumio[1329]: info: CoreStateMachine::play index undefined Mar 25 20:58:05 bevolinnomkpro volumio[1329]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 20:58:05 bevolinnomkpro volumio[1329]: info: CoreStateMachine::pushState Mar 25 20:58:05 bevolinnomkpro volumio[1329]: info: CorePlayQueue::getTrack 3 Mar 25 20:58:05 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 20:58:05 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioPushState Mar 25 20:58:05 bevolinnomkpro volumio[1329]: info: CorePlayQueue::getTrack 3 Mar 25 20:58:05 bevolinnomkpro volumio[1329]: info: CoreStateMachine::startPlaybackTimer Mar 25 20:58:05 bevolinnomkpro volumio[1329]: info: CorePlayQueue::getTrack 3 Mar 25 20:58:05 bevolinnomkpro volumio[1329]: info: [1774468685454] ControllerUPNPBrowser::clearAddPlayTrack Mar 25 20:58:05 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand stop Mar 25 20:58:05 bevolinnomkpro volumio[1329]: info: ------------------------------ 39ms Mar 25 20:58:05 bevolinnomkpro volumio[1329]: info: CoreStateMachine::pushState Mar 25 20:58:05 bevolinnomkpro volumio[1329]: info: CorePlayQueue::getTrack 3 Mar 25 20:58:05 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 20:58:05 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioPushState Mar 25 20:58:05 bevolinnomkpro volumio[1329]: info: sendMpdCommand stop took 30 milliseconds Mar 25 20:58:05 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand clear Mar 25 20:58:05 bevolinnomkpro volumio[1329]: info: Mar 25 20:58:05 bevolinnomkpro volumio[1329]: ---------------------------- MPD announces system playlist update Mar 25 20:58:05 bevolinnomkpro volumio[1329]: info: Ignoring MPD Status Update Mar 25 20:58:05 bevolinnomkpro volumio[1329]: info: sendMpdCommand clear took 65 milliseconds Mar 25 20:58:05 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.170.45:50002/m/NDLNA/42991.m4a" Mar 25 20:58:05 bevolinnomkpro volumio[1329]: error: updateQueue error: null Mar 25 20:58:05 bevolinnomkpro volumio[1329]: info: ------------------------------ 28ms Mar 25 20:58:05 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.170.45:50002/m/NDLNA/42991.m4a" Mar 25 20:58:05 bevolinnomkpro volumio[1329]: info: touch_display: Setting screensaver timeout to 300 seconds. Mar 25 20:58:05 bevolinnomkpro volumio[1329]: info: Mar 25 20:58:05 bevolinnomkpro volumio[1329]: ---------------------------- MPD announces system playlist update Mar 25 20:58:05 bevolinnomkpro volumio[1329]: info: Ignoring MPD Status Update Mar 25 20:58:05 bevolinnomkpro volumio[1329]: info: sendMpdCommand add "http://192.168.170.45:50002/m/NDLNA/42991.m4a" took 4 milliseconds Mar 25 20:58:05 bevolinnomkpro volumio[1329]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 25 20:58:05 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand play Mar 25 20:58:05 bevolinnomkpro volumio[1329]: info: ------------------------------ 3ms Mar 25 20:58:05 bevolinnomkpro volumio[1329]: info: sendMpdCommand play took 2 milliseconds Mar 25 20:58:05 bevolinnomkpro volumio[1329]: info: Mar 25 20:58:05 bevolinnomkpro volumio[1329]: ---------------------------- MPD announces state update: player Mar 25 20:58:05 bevolinnomkpro volumio[1329]: info: ControllerMpd::getState Mar 25 20:58:05 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand status Mar 25 20:58:05 bevolinnomkpro volumio[1329]: info: sendMpdCommand status took 2 milliseconds Mar 25 20:58:05 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::parseState Mar 25 20:58:05 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 20:58:05 bevolinnomkpro volumio[1329]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 25 20:58:05 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::parseTrackInfo Mar 25 20:58:05 bevolinnomkpro volumio[1329]: info: ControllerMpd::pushState Mar 25 20:58:05 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::servicePushState Mar 25 20:58:05 bevolinnomkpro volumio[1329]: info: CorePlayQueue::getTrack 3 Mar 25 20:58:05 bevolinnomkpro volumio[1329]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":296,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"42991.m4a","artist":null,"album":null,"uri":"http://192.168.170.45:50002/m/NDLNA/42991.m4a","trackType":"m4a"} Mar 25 20:58:05 bevolinnomkpro volumio[1329]: verbose: CURRENT POSITION 3 Mar 25 20:58:05 bevolinnomkpro volumio[1329]: info: CoreStateMachine::syncState stateService play Mar 25 20:58:05 bevolinnomkpro volumio[1329]: info: CoreStateMachine::syncState currentStatus stop Mar 25 20:58:05 bevolinnomkpro volumio[1329]: info: ------------------------------ 12ms Mar 25 20:58:05 bevolinnomkpro volumio[1329]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Mar 25 20:58:05 bevolinnomkpro volumio[1329]: info: FusionDsp - ---- read samplerate from file: 44100 Mar 25 20:58:06 bevolinnomkpro volumio[1329]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 25 20:58:09 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioPrevious Mar 25 20:58:09 bevolinnomkpro volumio[1329]: info: CoreStateMachine::previous Mar 25 20:58:09 bevolinnomkpro volumio[1329]: info: ControllerMpd::seek Mar 25 20:58:09 bevolinnomkpro volumio[1329]: error: null Mar 25 20:58:09 bevolinnomkpro volumio[1329]: info: Mar 25 20:58:09 bevolinnomkpro volumio[1329]: ---------------------------- MPD announces state update: player Mar 25 20:58:09 bevolinnomkpro volumio[1329]: info: ControllerMpd::getState Mar 25 20:58:09 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand status Mar 25 20:58:09 bevolinnomkpro volumio[1329]: info: sendMpdCommand status took 1 milliseconds Mar 25 20:58:09 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::parseState Mar 25 20:58:09 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 20:58:09 bevolinnomkpro volumio[1329]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 25 20:58:09 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::parseTrackInfo Mar 25 20:58:09 bevolinnomkpro volumio[1329]: info: ControllerMpd::pushState Mar 25 20:58:09 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::servicePushState Mar 25 20:58:09 bevolinnomkpro volumio[1329]: info: CorePlayQueue::getTrack 3 Mar 25 20:58:09 bevolinnomkpro volumio[1329]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":296,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"964 Kbps","isStreaming":false,"title":"42991.m4a","artist":null,"album":null,"uri":"http://192.168.170.45:50002/m/NDLNA/42991.m4a","trackType":"m4a"} Mar 25 20:58:09 bevolinnomkpro volumio[1329]: verbose: CURRENT POSITION 3 Mar 25 20:58:09 bevolinnomkpro volumio[1329]: info: CoreStateMachine::syncState stateService play Mar 25 20:58:09 bevolinnomkpro volumio[1329]: info: CoreStateMachine::syncState currentStatus play Mar 25 20:58:09 bevolinnomkpro volumio[1329]: info: Received an update from plugin. extracting info from payload Mar 25 20:58:09 bevolinnomkpro volumio[1329]: info: CoreStateMachine::pushState Mar 25 20:58:09 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 20:58:09 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioPushState Mar 25 20:58:09 bevolinnomkpro volumio[1329]: info: CoreStateMachine::pushState Mar 25 20:58:09 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioPushState Mar 25 20:58:09 bevolinnomkpro volumio[1329]: info: ------------------------------ 56ms Mar 25 20:58:09 bevolinnomkpro volumio[1329]: info: touch_display: Setting screensaver timeout to 0 seconds. Mar 25 20:58:13 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioPrevious Mar 25 20:58:13 bevolinnomkpro volumio[1329]: info: CoreStateMachine::previous Mar 25 20:58:13 bevolinnomkpro volumio[1329]: info: ControllerMpd::seek Mar 25 20:58:13 bevolinnomkpro volumio[1329]: error: null Mar 25 20:58:13 bevolinnomkpro volumio[1329]: info: Mar 25 20:58:13 bevolinnomkpro volumio[1329]: ---------------------------- MPD announces state update: player Mar 25 20:58:13 bevolinnomkpro volumio[1329]: info: ControllerMpd::getState Mar 25 20:58:13 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand status Mar 25 20:58:13 bevolinnomkpro volumio[1329]: info: sendMpdCommand status took 1 milliseconds Mar 25 20:58:13 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::parseState Mar 25 20:58:13 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 20:58:13 bevolinnomkpro volumio[1329]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 25 20:58:13 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::parseTrackInfo Mar 25 20:58:13 bevolinnomkpro volumio[1329]: info: ControllerMpd::pushState Mar 25 20:58:13 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::servicePushState Mar 25 20:58:13 bevolinnomkpro volumio[1329]: info: CorePlayQueue::getTrack 3 Mar 25 20:58:13 bevolinnomkpro volumio[1329]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":296,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"964 Kbps","isStreaming":false,"title":"42991.m4a","artist":null,"album":null,"uri":"http://192.168.170.45:50002/m/NDLNA/42991.m4a","trackType":"m4a"} Mar 25 20:58:13 bevolinnomkpro volumio[1329]: verbose: CURRENT POSITION 3 Mar 25 20:58:13 bevolinnomkpro volumio[1329]: info: CoreStateMachine::syncState stateService play Mar 25 20:58:13 bevolinnomkpro volumio[1329]: info: CoreStateMachine::syncState currentStatus play Mar 25 20:58:13 bevolinnomkpro volumio[1329]: info: Received an update from plugin. extracting info from payload Mar 25 20:58:13 bevolinnomkpro volumio[1329]: info: CoreStateMachine::pushState Mar 25 20:58:13 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioPushState Mar 25 20:58:13 bevolinnomkpro volumio[1329]: info: CoreStateMachine::pushState Mar 25 20:58:13 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioPushState Mar 25 20:58:13 bevolinnomkpro volumio[1329]: info: ------------------------------ 50ms Mar 25 20:58:13 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioPrevious Mar 25 20:58:13 bevolinnomkpro volumio[1329]: info: CoreStateMachine::previous Mar 25 20:58:13 bevolinnomkpro volumio[1329]: info: ControllerMpd::previous Mar 25 20:58:13 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand previous Mar 25 20:58:13 bevolinnomkpro volumio[1329]: info: Mar 25 20:58:13 bevolinnomkpro volumio[1329]: ---------------------------- MPD announces state update: player Mar 25 20:58:13 bevolinnomkpro volumio[1329]: info: sendMpdCommand previous took 5 milliseconds Mar 25 20:58:13 bevolinnomkpro volumio[1329]: info: ControllerMpd::getState Mar 25 20:58:13 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand status Mar 25 20:58:13 bevolinnomkpro volumio[1329]: info: sendMpdCommand status took 1 milliseconds Mar 25 20:58:13 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::parseState Mar 25 20:58:13 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 20:58:13 bevolinnomkpro volumio[1329]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 25 20:58:13 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::parseTrackInfo Mar 25 20:58:13 bevolinnomkpro volumio[1329]: info: ControllerMpd::pushState Mar 25 20:58:13 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::servicePushState Mar 25 20:58:13 bevolinnomkpro volumio[1329]: info: CorePlayQueue::getTrack 3 Mar 25 20:58:13 bevolinnomkpro volumio[1329]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":296,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"964 Kbps","isStreaming":false,"title":"42991.m4a","artist":null,"album":null,"uri":"http://192.168.170.45:50002/m/NDLNA/42991.m4a","trackType":"m4a"} Mar 25 20:58:13 bevolinnomkpro volumio[1329]: verbose: CURRENT POSITION 3 Mar 25 20:58:13 bevolinnomkpro volumio[1329]: info: CoreStateMachine::syncState stateService play Mar 25 20:58:13 bevolinnomkpro volumio[1329]: info: CoreStateMachine::syncState currentStatus play Mar 25 20:58:13 bevolinnomkpro volumio[1329]: info: Received an update from plugin. extracting info from payload Mar 25 20:58:13 bevolinnomkpro volumio[1329]: info: CoreStateMachine::pushState Mar 25 20:58:13 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 20:58:13 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioPushState Mar 25 20:58:13 bevolinnomkpro volumio[1329]: info: CoreStateMachine::pushState Mar 25 20:58:13 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioPushState Mar 25 20:58:14 bevolinnomkpro volumio[1329]: info: ------------------------------ 49ms Mar 25 20:58:19 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioNext Mar 25 20:58:19 bevolinnomkpro volumio[1329]: info: CoreStateMachine::next Mar 25 20:58:19 bevolinnomkpro volumio[1329]: info: ControllerMpd::next Mar 25 20:58:19 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand next Mar 25 20:58:19 bevolinnomkpro volumio[1329]: info: Mar 25 20:58:19 bevolinnomkpro volumio[1329]: ---------------------------- MPD announces state update: player Mar 25 20:58:19 bevolinnomkpro volumio[1329]: info: sendMpdCommand next took 14 milliseconds Mar 25 20:58:19 bevolinnomkpro volumio[1329]: info: ControllerMpd::getState Mar 25 20:58:19 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand status Mar 25 20:58:19 bevolinnomkpro volumio[1329]: info: sendMpdCommand status took 1 milliseconds Mar 25 20:58:19 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::parseState Mar 25 20:58:19 bevolinnomkpro volumio[1329]: info: ControllerMpd::pushState Mar 25 20:58:19 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::servicePushState Mar 25 20:58:19 bevolinnomkpro volumio[1329]: info: CoreStateMachine::pushState Mar 25 20:58:19 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 20:58:19 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioPushState Mar 25 20:58:19 bevolinnomkpro volumio[1329]: info: CorePlayQueue::getTrack 3 Mar 25 20:58:19 bevolinnomkpro volumio[1329]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 25 20:58:19 bevolinnomkpro volumio[1329]: verbose: CURRENT POSITION 3 Mar 25 20:58:19 bevolinnomkpro volumio[1329]: info: CoreStateMachine::syncState stateService stop Mar 25 20:58:19 bevolinnomkpro volumio[1329]: info: CoreStateMachine::syncState currentStatus play Mar 25 20:58:19 bevolinnomkpro volumio[1329]: info: CoreStateMachine::play index undefined Mar 25 20:58:19 bevolinnomkpro volumio[1329]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 20:58:19 bevolinnomkpro volumio[1329]: info: CoreStateMachine::pushState Mar 25 20:58:19 bevolinnomkpro volumio[1329]: info: CorePlayQueue::getTrack 4 Mar 25 20:58:19 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 20:58:19 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioPushState Mar 25 20:58:19 bevolinnomkpro volumio[1329]: info: CorePlayQueue::getTrack 4 Mar 25 20:58:19 bevolinnomkpro volumio[1329]: info: CoreStateMachine::startPlaybackTimer Mar 25 20:58:19 bevolinnomkpro volumio[1329]: info: CorePlayQueue::getTrack 4 Mar 25 20:58:19 bevolinnomkpro volumio[1329]: info: [1774468699635] ControllerUPNPBrowser::clearAddPlayTrack Mar 25 20:58:19 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand stop Mar 25 20:58:19 bevolinnomkpro volumio[1329]: info: ------------------------------ 39ms Mar 25 20:58:19 bevolinnomkpro volumio[1329]: info: CoreStateMachine::pushState Mar 25 20:58:19 bevolinnomkpro volumio[1329]: info: CorePlayQueue::getTrack 4 Mar 25 20:58:19 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 20:58:19 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioPushState Mar 25 20:58:19 bevolinnomkpro volumio[1329]: info: sendMpdCommand stop took 37 milliseconds Mar 25 20:58:19 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand clear Mar 25 20:58:19 bevolinnomkpro volumio[1329]: info: Mar 25 20:58:19 bevolinnomkpro volumio[1329]: ---------------------------- MPD announces system playlist update Mar 25 20:58:19 bevolinnomkpro volumio[1329]: info: Ignoring MPD Status Update Mar 25 20:58:19 bevolinnomkpro volumio[1329]: info: sendMpdCommand clear took 35 milliseconds Mar 25 20:58:19 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.170.45:50002/m/NDLNA/42992.m4a" Mar 25 20:58:19 bevolinnomkpro volumio[1329]: error: updateQueue error: null Mar 25 20:58:19 bevolinnomkpro volumio[1329]: info: ------------------------------ 24ms Mar 25 20:58:19 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.170.45:50002/m/NDLNA/42992.m4a" Mar 25 20:58:19 bevolinnomkpro volumio[1329]: info: Mar 25 20:58:19 bevolinnomkpro volumio[1329]: ---------------------------- MPD announces system playlist update Mar 25 20:58:19 bevolinnomkpro volumio[1329]: info: Ignoring MPD Status Update Mar 25 20:58:19 bevolinnomkpro volumio[1329]: info: sendMpdCommand add "http://192.168.170.45:50002/m/NDLNA/42992.m4a" took 1 milliseconds Mar 25 20:58:19 bevolinnomkpro volumio[1329]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 25 20:58:19 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand play Mar 25 20:58:19 bevolinnomkpro volumio[1329]: info: ------------------------------ 3ms Mar 25 20:58:19 bevolinnomkpro volumio[1329]: info: sendMpdCommand play took 6 milliseconds Mar 25 20:58:19 bevolinnomkpro volumio[1329]: info: touch_display: Setting screensaver timeout to 300 seconds. Mar 25 20:58:19 bevolinnomkpro volumio[1329]: info: Mar 25 20:58:19 bevolinnomkpro volumio[1329]: ---------------------------- MPD announces state update: player Mar 25 20:58:19 bevolinnomkpro volumio[1329]: info: ControllerMpd::getState Mar 25 20:58:19 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand status Mar 25 20:58:19 bevolinnomkpro volumio[1329]: info: Mar 25 20:58:19 bevolinnomkpro volumio[1329]: ---------------------------- MPD announces state update: player Mar 25 20:58:19 bevolinnomkpro volumio[1329]: info: sendMpdCommand status took 12 milliseconds Mar 25 20:58:19 bevolinnomkpro volumio[1329]: info: ControllerMpd::getState Mar 25 20:58:19 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand status Mar 25 20:58:19 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::parseState Mar 25 20:58:19 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 20:58:19 bevolinnomkpro volumio[1329]: info: sendMpdCommand status took 4 milliseconds Mar 25 20:58:19 bevolinnomkpro volumio[1329]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 25 20:58:19 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::parseState Mar 25 20:58:19 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 20:58:19 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::parseTrackInfo Mar 25 20:58:19 bevolinnomkpro volumio[1329]: info: ControllerMpd::pushState Mar 25 20:58:19 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::servicePushState Mar 25 20:58:19 bevolinnomkpro volumio[1329]: info: CorePlayQueue::getTrack 4 Mar 25 20:58:19 bevolinnomkpro volumio[1329]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":298,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"42992.m4a","artist":null,"album":null,"uri":"http://192.168.170.45:50002/m/NDLNA/42992.m4a","trackType":"m4a"} Mar 25 20:58:19 bevolinnomkpro volumio[1329]: verbose: CURRENT POSITION 4 Mar 25 20:58:19 bevolinnomkpro volumio[1329]: info: CoreStateMachine::syncState stateService play Mar 25 20:58:19 bevolinnomkpro volumio[1329]: info: CoreStateMachine::syncState currentStatus stop Mar 25 20:58:19 bevolinnomkpro volumio[1329]: info: ------------------------------ 26ms Mar 25 20:58:19 bevolinnomkpro volumio[1329]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 25 20:58:19 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::parseTrackInfo Mar 25 20:58:19 bevolinnomkpro volumio[1329]: info: ControllerMpd::pushState Mar 25 20:58:19 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::servicePushState Mar 25 20:58:19 bevolinnomkpro volumio[1329]: info: CorePlayQueue::getTrack 4 Mar 25 20:58:19 bevolinnomkpro volumio[1329]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":298,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"42992.m4a","artist":null,"album":null,"uri":"http://192.168.170.45:50002/m/NDLNA/42992.m4a","trackType":"m4a"} Mar 25 20:58:19 bevolinnomkpro volumio[1329]: verbose: CURRENT POSITION 4 Mar 25 20:58:19 bevolinnomkpro volumio[1329]: info: CoreStateMachine::syncState stateService play Mar 25 20:58:19 bevolinnomkpro volumio[1329]: info: CoreStateMachine::syncState currentStatus play Mar 25 20:58:19 bevolinnomkpro volumio[1329]: info: Received an update from plugin. extracting info from payload Mar 25 20:58:19 bevolinnomkpro volumio[1329]: info: CoreStateMachine::pushState Mar 25 20:58:19 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 20:58:19 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioPushState Mar 25 20:58:19 bevolinnomkpro volumio[1329]: info: CoreStateMachine::pushState Mar 25 20:58:19 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioPushState Mar 25 20:58:19 bevolinnomkpro volumio[1329]: info: ------------------------------ 94ms Mar 25 20:58:19 bevolinnomkpro volumio[1329]: info: touch_display: Setting screensaver timeout to 0 seconds. Mar 25 20:58:19 bevolinnomkpro volumio[1329]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Mar 25 20:58:19 bevolinnomkpro volumio[1329]: info: FusionDsp - ---- read samplerate from file: 44100 Mar 25 20:58:20 bevolinnomkpro volumio[1329]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 25 20:58:26 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioNext Mar 25 20:58:26 bevolinnomkpro volumio[1329]: info: CoreStateMachine::next Mar 25 20:58:26 bevolinnomkpro volumio[1329]: info: ControllerMpd::next Mar 25 20:58:26 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand next Mar 25 20:58:26 bevolinnomkpro volumio[1329]: info: Mar 25 20:58:26 bevolinnomkpro volumio[1329]: ---------------------------- MPD announces state update: player Mar 25 20:58:26 bevolinnomkpro volumio[1329]: info: sendMpdCommand next took 14 milliseconds Mar 25 20:58:26 bevolinnomkpro volumio[1329]: info: ControllerMpd::getState Mar 25 20:58:26 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand status Mar 25 20:58:26 bevolinnomkpro volumio[1329]: info: sendMpdCommand status took 0 milliseconds Mar 25 20:58:26 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::parseState Mar 25 20:58:26 bevolinnomkpro volumio[1329]: info: ControllerMpd::pushState Mar 25 20:58:26 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::servicePushState Mar 25 20:58:26 bevolinnomkpro volumio[1329]: info: CoreStateMachine::pushState Mar 25 20:58:26 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 20:58:26 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioPushState Mar 25 20:58:27 bevolinnomkpro volumio[1329]: info: CorePlayQueue::getTrack 4 Mar 25 20:58:27 bevolinnomkpro volumio[1329]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 25 20:58:27 bevolinnomkpro volumio[1329]: verbose: CURRENT POSITION 4 Mar 25 20:58:27 bevolinnomkpro volumio[1329]: info: CoreStateMachine::syncState stateService stop Mar 25 20:58:27 bevolinnomkpro volumio[1329]: info: CoreStateMachine::syncState currentStatus play Mar 25 20:58:27 bevolinnomkpro volumio[1329]: info: CoreStateMachine::play index undefined Mar 25 20:58:27 bevolinnomkpro volumio[1329]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 20:58:27 bevolinnomkpro volumio[1329]: info: CoreStateMachine::pushState Mar 25 20:58:27 bevolinnomkpro volumio[1329]: info: CorePlayQueue::getTrack 5 Mar 25 20:58:27 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 20:58:27 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioPushState Mar 25 20:58:27 bevolinnomkpro volumio[1329]: info: CorePlayQueue::getTrack 5 Mar 25 20:58:27 bevolinnomkpro volumio[1329]: info: CoreStateMachine::startPlaybackTimer Mar 25 20:58:27 bevolinnomkpro volumio[1329]: info: CorePlayQueue::getTrack 5 Mar 25 20:58:27 bevolinnomkpro volumio[1329]: info: [1774468707027] ControllerUPNPBrowser::clearAddPlayTrack Mar 25 20:58:27 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand stop Mar 25 20:58:27 bevolinnomkpro volumio[1329]: info: ------------------------------ 41ms Mar 25 20:58:27 bevolinnomkpro volumio[1329]: info: CoreStateMachine::pushState Mar 25 20:58:27 bevolinnomkpro volumio[1329]: info: CorePlayQueue::getTrack 5 Mar 25 20:58:27 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 20:58:27 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioPushState Mar 25 20:58:27 bevolinnomkpro volumio[1329]: info: sendMpdCommand stop took 51 milliseconds Mar 25 20:58:27 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand clear Mar 25 20:58:27 bevolinnomkpro volumio[1329]: info: Mar 25 20:58:27 bevolinnomkpro volumio[1329]: ---------------------------- MPD announces system playlist update Mar 25 20:58:27 bevolinnomkpro volumio[1329]: info: Ignoring MPD Status Update Mar 25 20:58:27 bevolinnomkpro volumio[1329]: info: sendMpdCommand clear took 55 milliseconds Mar 25 20:58:27 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.170.45:50002/m/NDLNA/42993.m4a" Mar 25 20:58:27 bevolinnomkpro volumio[1329]: error: updateQueue error: null Mar 25 20:58:27 bevolinnomkpro volumio[1329]: info: ------------------------------ 30ms Mar 25 20:58:27 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.170.45:50002/m/NDLNA/42993.m4a" Mar 25 20:58:27 bevolinnomkpro volumio[1329]: info: Mar 25 20:58:27 bevolinnomkpro volumio[1329]: ---------------------------- MPD announces system playlist update Mar 25 20:58:27 bevolinnomkpro volumio[1329]: info: Ignoring MPD Status Update Mar 25 20:58:27 bevolinnomkpro volumio[1329]: info: sendMpdCommand add "http://192.168.170.45:50002/m/NDLNA/42993.m4a" took 3 milliseconds Mar 25 20:58:27 bevolinnomkpro volumio[1329]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 25 20:58:27 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand play Mar 25 20:58:27 bevolinnomkpro volumio[1329]: info: ------------------------------ 6ms Mar 25 20:58:27 bevolinnomkpro volumio[1329]: info: sendMpdCommand play took 5 milliseconds Mar 25 20:58:27 bevolinnomkpro volumio[1329]: info: touch_display: Setting screensaver timeout to 300 seconds. Mar 25 20:58:27 bevolinnomkpro volumio[1329]: info: Mar 25 20:58:27 bevolinnomkpro volumio[1329]: ---------------------------- MPD announces state update: player Mar 25 20:58:27 bevolinnomkpro volumio[1329]: info: ControllerMpd::getState Mar 25 20:58:27 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand status Mar 25 20:58:27 bevolinnomkpro volumio[1329]: info: Mar 25 20:58:27 bevolinnomkpro volumio[1329]: ---------------------------- MPD announces state update: player Mar 25 20:58:27 bevolinnomkpro volumio[1329]: info: sendMpdCommand status took 18 milliseconds Mar 25 20:58:27 bevolinnomkpro volumio[1329]: info: ControllerMpd::getState Mar 25 20:58:27 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand status Mar 25 20:58:27 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::parseState Mar 25 20:58:27 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 20:58:27 bevolinnomkpro volumio[1329]: info: sendMpdCommand status took 3 milliseconds Mar 25 20:58:27 bevolinnomkpro volumio[1329]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 25 20:58:27 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::parseState Mar 25 20:58:27 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 20:58:27 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::parseTrackInfo Mar 25 20:58:27 bevolinnomkpro volumio[1329]: info: ControllerMpd::pushState Mar 25 20:58:27 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::servicePushState Mar 25 20:58:27 bevolinnomkpro volumio[1329]: info: CorePlayQueue::getTrack 5 Mar 25 20:58:27 bevolinnomkpro volumio[1329]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":298,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"42993.m4a","artist":null,"album":null,"uri":"http://192.168.170.45:50002/m/NDLNA/42993.m4a","trackType":"m4a"} Mar 25 20:58:27 bevolinnomkpro volumio[1329]: verbose: CURRENT POSITION 5 Mar 25 20:58:27 bevolinnomkpro volumio[1329]: info: CoreStateMachine::syncState stateService play Mar 25 20:58:27 bevolinnomkpro volumio[1329]: info: CoreStateMachine::syncState currentStatus stop Mar 25 20:58:27 bevolinnomkpro volumio[1329]: info: ------------------------------ 36ms Mar 25 20:58:27 bevolinnomkpro volumio[1329]: info: sendMpdCommand playlistinfo took 12 milliseconds Mar 25 20:58:27 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::parseTrackInfo Mar 25 20:58:27 bevolinnomkpro volumio[1329]: info: ControllerMpd::pushState Mar 25 20:58:27 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::servicePushState Mar 25 20:58:27 bevolinnomkpro volumio[1329]: info: CorePlayQueue::getTrack 5 Mar 25 20:58:27 bevolinnomkpro volumio[1329]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":298,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"42993.m4a","artist":null,"album":null,"uri":"http://192.168.170.45:50002/m/NDLNA/42993.m4a","trackType":"m4a"} Mar 25 20:58:27 bevolinnomkpro volumio[1329]: verbose: CURRENT POSITION 5 Mar 25 20:58:27 bevolinnomkpro volumio[1329]: info: CoreStateMachine::syncState stateService play Mar 25 20:58:27 bevolinnomkpro volumio[1329]: info: CoreStateMachine::syncState currentStatus play Mar 25 20:58:27 bevolinnomkpro volumio[1329]: info: Received an update from plugin. extracting info from payload Mar 25 20:58:27 bevolinnomkpro volumio[1329]: info: CoreStateMachine::pushState Mar 25 20:58:27 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 20:58:27 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioPushState Mar 25 20:58:27 bevolinnomkpro volumio[1329]: info: CoreStateMachine::pushState Mar 25 20:58:27 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioPushState Mar 25 20:58:27 bevolinnomkpro volumio[1329]: info: ------------------------------ 107ms Mar 25 20:58:27 bevolinnomkpro volumio[1329]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Mar 25 20:58:27 bevolinnomkpro volumio[1329]: info: FusionDsp - ---- read samplerate from file: 44100 Mar 25 20:58:27 bevolinnomkpro volumio[1329]: info: touch_display: Setting screensaver timeout to 0 seconds. Mar 25 20:58:27 bevolinnomkpro volumio[1329]: info: camilladsp respawn in 100 ms (attempt 1/10) Mar 25 20:58:27 bevolinnomkpro volumio[1329]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 25 20:58:27 bevolinnomkpro volumio[1329]: error: FusionDsp - Reload WebSocket error: [object Object] Mar 25 20:58:29 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioNext Mar 25 20:58:29 bevolinnomkpro volumio[1329]: info: CoreStateMachine::next Mar 25 20:58:29 bevolinnomkpro volumio[1329]: info: ControllerMpd::next Mar 25 20:58:29 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand next Mar 25 20:58:29 bevolinnomkpro volumio[1329]: info: Mar 25 20:58:29 bevolinnomkpro volumio[1329]: ---------------------------- MPD announces state update: player Mar 25 20:58:29 bevolinnomkpro volumio[1329]: info: sendMpdCommand next took 7 milliseconds Mar 25 20:58:29 bevolinnomkpro volumio[1329]: info: ControllerMpd::getState Mar 25 20:58:29 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand status Mar 25 20:58:29 bevolinnomkpro volumio[1329]: info: sendMpdCommand status took 1 milliseconds Mar 25 20:58:29 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::parseState Mar 25 20:58:29 bevolinnomkpro volumio[1329]: info: ControllerMpd::pushState Mar 25 20:58:29 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::servicePushState Mar 25 20:58:29 bevolinnomkpro volumio[1329]: info: CoreStateMachine::pushState Mar 25 20:58:29 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 20:58:29 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioPushState Mar 25 20:58:29 bevolinnomkpro volumio[1329]: info: CorePlayQueue::getTrack 5 Mar 25 20:58:29 bevolinnomkpro volumio[1329]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 25 20:58:29 bevolinnomkpro volumio[1329]: verbose: CURRENT POSITION 5 Mar 25 20:58:29 bevolinnomkpro volumio[1329]: info: CoreStateMachine::syncState stateService stop Mar 25 20:58:29 bevolinnomkpro volumio[1329]: info: CoreStateMachine::syncState currentStatus play Mar 25 20:58:29 bevolinnomkpro volumio[1329]: info: CoreStateMachine::play index undefined Mar 25 20:58:29 bevolinnomkpro volumio[1329]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 25 20:58:29 bevolinnomkpro volumio[1329]: info: CoreStateMachine::pushState Mar 25 20:58:29 bevolinnomkpro volumio[1329]: info: CorePlayQueue::getTrack 6 Mar 25 20:58:29 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 20:58:29 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioPushState Mar 25 20:58:29 bevolinnomkpro volumio[1329]: info: CorePlayQueue::getTrack 6 Mar 25 20:58:29 bevolinnomkpro volumio[1329]: info: CoreStateMachine::startPlaybackTimer Mar 25 20:58:29 bevolinnomkpro volumio[1329]: info: CorePlayQueue::getTrack 6 Mar 25 20:58:29 bevolinnomkpro volumio[1329]: info: [1774468709910] ControllerUPNPBrowser::clearAddPlayTrack Mar 25 20:58:29 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand stop Mar 25 20:58:29 bevolinnomkpro volumio[1329]: info: ------------------------------ 60ms Mar 25 20:58:29 bevolinnomkpro volumio[1329]: info: CoreStateMachine::pushState Mar 25 20:58:29 bevolinnomkpro volumio[1329]: info: CorePlayQueue::getTrack 6 Mar 25 20:58:29 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 20:58:29 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioPushState Mar 25 20:58:29 bevolinnomkpro volumio[1329]: info: sendMpdCommand stop took 37 milliseconds Mar 25 20:58:29 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand clear Mar 25 20:58:29 bevolinnomkpro volumio[1329]: info: Mar 25 20:58:29 bevolinnomkpro volumio[1329]: ---------------------------- MPD announces system playlist update Mar 25 20:58:29 bevolinnomkpro volumio[1329]: info: Ignoring MPD Status Update Mar 25 20:58:29 bevolinnomkpro volumio[1329]: info: sendMpdCommand clear took 27 milliseconds Mar 25 20:58:29 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.170.45:50002/m/NDLNA/42994.m4a" Mar 25 20:58:29 bevolinnomkpro volumio[1329]: error: updateQueue error: null Mar 25 20:58:30 bevolinnomkpro volumio[1329]: info: ------------------------------ 23ms Mar 25 20:58:30 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.170.45:50002/m/NDLNA/42994.m4a" Mar 25 20:58:30 bevolinnomkpro volumio[1329]: info: Mar 25 20:58:30 bevolinnomkpro volumio[1329]: ---------------------------- MPD announces system playlist update Mar 25 20:58:30 bevolinnomkpro volumio[1329]: info: Ignoring MPD Status Update Mar 25 20:58:30 bevolinnomkpro volumio[1329]: info: sendMpdCommand add "http://192.168.170.45:50002/m/NDLNA/42994.m4a" took 5 milliseconds Mar 25 20:58:30 bevolinnomkpro volumio[1329]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 25 20:58:30 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand play Mar 25 20:58:30 bevolinnomkpro volumio[1329]: info: ------------------------------ 5ms Mar 25 20:58:30 bevolinnomkpro volumio[1329]: info: touch_display: Setting screensaver timeout to 300 seconds. Mar 25 20:58:30 bevolinnomkpro volumio[1329]: info: sendMpdCommand play took 3 milliseconds Mar 25 20:58:30 bevolinnomkpro volumio[1329]: info: Mar 25 20:58:30 bevolinnomkpro volumio[1329]: ---------------------------- MPD announces state update: player Mar 25 20:58:30 bevolinnomkpro volumio[1329]: info: ControllerMpd::getState Mar 25 20:58:30 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand status Mar 25 20:58:30 bevolinnomkpro volumio[1329]: info: Mar 25 20:58:30 bevolinnomkpro volumio[1329]: ---------------------------- MPD announces state update: player Mar 25 20:58:30 bevolinnomkpro volumio[1329]: info: sendMpdCommand status took 22 milliseconds Mar 25 20:58:30 bevolinnomkpro volumio[1329]: info: ControllerMpd::getState Mar 25 20:58:30 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand status Mar 25 20:58:30 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::parseState Mar 25 20:58:30 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 20:58:30 bevolinnomkpro volumio[1329]: info: sendMpdCommand status took 9 milliseconds Mar 25 20:58:30 bevolinnomkpro volumio[1329]: info: sendMpdCommand playlistinfo took 8 milliseconds Mar 25 20:58:30 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::parseState Mar 25 20:58:30 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 25 20:58:30 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::parseTrackInfo Mar 25 20:58:30 bevolinnomkpro volumio[1329]: info: ControllerMpd::pushState Mar 25 20:58:30 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::servicePushState Mar 25 20:58:30 bevolinnomkpro volumio[1329]: info: CorePlayQueue::getTrack 6 Mar 25 20:58:30 bevolinnomkpro volumio[1329]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"42994.m4a","artist":null,"album":null,"uri":"http://192.168.170.45:50002/m/NDLNA/42994.m4a","trackType":"m4a"} Mar 25 20:58:30 bevolinnomkpro volumio[1329]: verbose: CURRENT POSITION 6 Mar 25 20:58:30 bevolinnomkpro volumio[1329]: info: CoreStateMachine::syncState stateService play Mar 25 20:58:30 bevolinnomkpro volumio[1329]: info: CoreStateMachine::syncState currentStatus stop Mar 25 20:58:30 bevolinnomkpro volumio[1329]: info: ------------------------------ 38ms Mar 25 20:58:30 bevolinnomkpro volumio[1329]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 25 20:58:30 bevolinnomkpro volumio[1329]: verbose: ControllerMpd::parseTrackInfo Mar 25 20:58:30 bevolinnomkpro volumio[1329]: info: ControllerMpd::pushState Mar 25 20:58:30 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::servicePushState Mar 25 20:58:30 bevolinnomkpro volumio[1329]: info: CorePlayQueue::getTrack 6 Mar 25 20:58:30 bevolinnomkpro volumio[1329]: verbose: STATE SERVICE {"status":"play","position":0,"seek":962,"duration":265,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"898 Kbps","isStreaming":false,"title":"42994.m4a","artist":null,"album":null,"uri":"http://192.168.170.45:50002/m/NDLNA/42994.m4a","trackType":"m4a"} Mar 25 20:58:30 bevolinnomkpro volumio[1329]: verbose: CURRENT POSITION 6 Mar 25 20:58:30 bevolinnomkpro volumio[1329]: info: CoreStateMachine::syncState stateService play Mar 25 20:58:30 bevolinnomkpro volumio[1329]: info: CoreStateMachine::syncState currentStatus play Mar 25 20:58:30 bevolinnomkpro volumio[1329]: info: Received an update from plugin. extracting info from payload Mar 25 20:58:30 bevolinnomkpro volumio[1329]: info: CoreStateMachine::pushState Mar 25 20:58:30 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 20:58:30 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioPushState Mar 25 20:58:30 bevolinnomkpro volumio[1329]: info: CoreStateMachine::pushState Mar 25 20:58:30 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioPushState Mar 25 20:58:30 bevolinnomkpro volumio[1329]: info: ------------------------------ 97ms Mar 25 20:58:30 bevolinnomkpro volumio[1329]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Mar 25 20:58:30 bevolinnomkpro volumio[1329]: info: FusionDsp - ---- read samplerate from file: 44100 Mar 25 20:58:30 bevolinnomkpro volumio[1329]: info: touch_display: Setting screensaver timeout to 0 seconds. Mar 25 20:58:30 bevolinnomkpro volumio[1329]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 25 20:58:30 bevolinnomkpro volumio[1329]: error: error Mar 25 20:58:30 bevolinnomkpro volumio[1329]: error: error Mar 25 20:58:30 bevolinnomkpro volumio[1329]: error: error Mar 25 20:58:30 bevolinnomkpro volumio[1329]: error: error Mar 25 20:58:31 bevolinnomkpro volumio[1329]: error: error Mar 25 20:58:31 bevolinnomkpro volumio[1329]: error: error Mar 25 20:58:31 bevolinnomkpro volumio[1329]: error: error Mar 25 20:58:31 bevolinnomkpro volumio[1329]: error: error Mar 25 20:58:31 bevolinnomkpro volumio[1329]: error: error Mar 25 20:58:31 bevolinnomkpro volumio[1329]: error: error Mar 25 20:58:31 bevolinnomkpro volumio[1329]: error: error Mar 25 20:58:31 bevolinnomkpro volumio[1329]: error: error Mar 25 20:58:31 bevolinnomkpro volumio[1329]: error: error Mar 25 20:58:31 bevolinnomkpro volumio[1329]: error: error Mar 25 20:58:31 bevolinnomkpro volumio[1329]: error: error Mar 25 20:58:31 bevolinnomkpro volumio[1329]: error: error Mar 25 20:58:31 bevolinnomkpro volumio[1329]: error: error Mar 25 20:58:31 bevolinnomkpro volumio[1329]: error: error Mar 25 20:58:31 bevolinnomkpro volumio[1329]: error: error Mar 25 20:58:31 bevolinnomkpro volumio[1329]: error: error Mar 25 20:58:31 bevolinnomkpro volumio[1329]: error: error Mar 25 20:58:31 bevolinnomkpro volumio[1329]: error: error Mar 25 20:58:31 bevolinnomkpro volumio[1329]: error: error Mar 25 20:58:31 bevolinnomkpro volumio[1329]: error: error Mar 25 20:58:31 bevolinnomkpro volumio[1329]: error: error Mar 25 20:58:31 bevolinnomkpro volumio[1329]: error: error Mar 25 20:58:31 bevolinnomkpro volumio[1329]: error: error Mar 25 20:58:31 bevolinnomkpro volumio[1329]: error: error Mar 25 20:58:31 bevolinnomkpro volumio[1329]: error: error Mar 25 20:58:31 bevolinnomkpro volumio[1329]: error: error Mar 25 20:58:31 bevolinnomkpro volumio[1329]: error: error Mar 25 20:58:31 bevolinnomkpro volumio[1329]: error: error Mar 25 20:58:31 bevolinnomkpro volumio[1329]: error: error Mar 25 20:58:31 bevolinnomkpro volumio[1329]: error: error Mar 25 20:58:31 bevolinnomkpro volumio[1329]: error: error Mar 25 20:58:31 bevolinnomkpro volumio[1329]: error: error Mar 25 20:58:35 bevolinnomkpro volumio[1329]: info: VolumeController::SetAlsaVolume60 Mar 25 20:58:35 bevolinnomkpro volumio[1329]: info: CoreStateMachine::pushState Mar 25 20:58:35 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 25 20:58:35 bevolinnomkpro volumio[1329]: info: CoreCommandRouter::volumioPushState Mar 25 20:58:46 bevolinnomkpro volumio[1329]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 25 20:58:46 bevolinnomkpro volumio[1329]: TypeError: Cannot read properties of null (reading 'slice') Mar 25 20:58:46 bevolinnomkpro volumio[1329]: at download (/volumio/app/plugins/miscellanea/albumart/albumart.js:718:41) Mar 25 20:58:46 bevolinnomkpro volumio[1329]: at ClientRequest. (/volumio/app/plugins/miscellanea/albumart/albumart.js:728:7) Mar 25 20:58:46 bevolinnomkpro volumio[1329]: at Object.onceWrapper (node:events:629:26) Mar 25 20:58:46 bevolinnomkpro volumio[1329]: at ClientRequest.emit (node:events:514:28) Mar 25 20:58:46 bevolinnomkpro volumio[1329]: at HTTPParser.parserOnIncomingClient [as onIncoming] (node:_http_client:693:27) Mar 25 20:58:46 bevolinnomkpro volumio[1329]: at HTTPParser.parserOnHeadersComplete (node:_http_common:119:17) Mar 25 20:58:46 bevolinnomkpro volumio[1329]: at TLSSocket.socketOnData (node:_http_client:535:22) Mar 25 20:58:46 bevolinnomkpro volumio[1329]: at TLSSocket.emit (node:events:514:28) Mar 25 20:58:46 bevolinnomkpro volumio[1329]: at addChunk (node:internal/streams/readable:343:12) Mar 25 20:58:46 bevolinnomkpro volumio[1329]: at readableAddChunk (node:internal/streams/readable:316:9) Mar 25 20:58:46 bevolinnomkpro volumio[1329]: at Readable.push (node:internal/streams/readable:253:10) Mar 25 20:58:46 bevolinnomkpro volumio[1329]: at TLSWrap.onStreamRead (node:internal/stream_base_commons:190:23) Mar 25 20:58:46 bevolinnomkpro volumio[1329]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 25 20:58:48 bevolinnomkpro sudo[4105]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-25 20:57' Mar 25 20:58:48 bevolinnomkpro sudo[4105]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="dc61260dec5515dafd2b634881860b4c46c919ff" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Mar 6 16:46:58 UTC 2026" VOLUMIO_VERSION="4.103" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="aa741395b8bfc648ff5d04e312f53d2c"