-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Wed 2025-12-10 11:29:23 CET. -- Dec 10 11:28:48 volumio-pb ntpd[765]: receive: Unexpected origin timestamp 0xeabce0a7.1c6ffd91 does not match aorg 0000000000.00000000 from server@31.58.102.164 xmt 0xece3c9e0.d50d2162 Dec 10 11:28:48 volumio-pb ntpd[765]: receive: Unexpected origin timestamp 0xeabce0a7.1c6d61d4 does not match aorg 0000000000.00000000 from server@54.36.61.42 xmt 0xece3c9e0.d5d35e91 Dec 10 11:28:48 volumio-pb ntpd[765]: receive: Unexpected origin timestamp 0xeabce0a7.1c6ec7c8 does not match aorg 0000000000.00000000 from server@51.210.104.72 xmt 0xece3c9e0.d5d45ad7 Dec 10 11:28:48 volumio-pb ntpd[765]: receive: Unexpected origin timestamp 0xeabce0a7.1c689ce6 does not match aorg 0000000000.00000000 from server@109.190.177.205 xmt 0xece3c9e0.d65e1944 Dec 10 11:28:48 volumio-pb systemd[1]: Starting Daily apt download activities... Dec 10 11:28:50 volumio-pb systemd[1]: apt-daily.service: Succeeded. Dec 10 11:28:50 volumio-pb systemd[1]: Started Daily apt download activities. Dec 10 11:28:50 volumio-pb systemd[1]: Starting Daily apt upgrade and clean activities... Dec 10 11:28:52 volumio-pb systemd[1]: apt-daily-upgrade.service: Succeeded. Dec 10 11:28:52 volumio-pb systemd[1]: Started Daily apt upgrade and clean activities. Dec 10 11:28:52 volumio-pb volumio[909]: info: Preload queue cleared Dec 10 11:28:52 volumio-pb volumio[909]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 10 11:28:52 volumio-pb volumio[909]: info: CoreStateMachine::ClearQueue Dec 10 11:28:52 volumio-pb volumio[909]: info: CoreStateMachine::stop Dec 10 11:28:52 volumio-pb volumio[909]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 10 11:28:52 volumio-pb volumio[909]: info: CorePlayQueue::clearPlayQueue Dec 10 11:28:52 volumio-pb volumio[909]: info: CorePlayQueue::saveQueue Dec 10 11:28:52 volumio-pb volumio[909]: info: CoreCommandRouter::volumioPushQueue Dec 10 11:28:52 volumio-pb volumio[909]: info: CoreStateMachine::addQueueItems Dec 10 11:28:52 volumio-pb volumio[909]: info: CorePlayQueue::addQueueItems Dec 10 11:28:52 volumio-pb volumio[909]: info: Preload queue cleared Dec 10 11:28:52 volumio-pb volumio[909]: info: Adding Item to queue: http://icecast.radiofrance.fr/francemusiquelajazz-hifi.aac Dec 10 11:28:52 volumio-pb volumio[909]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Dec 10 11:28:52 volumio-pb volumio[909]: info: CoreCommandRouter::volumioPushQueue Dec 10 11:28:52 volumio-pb volumio[909]: info: CorePlayQueue::saveQueue Dec 10 11:28:52 volumio-pb volumio[909]: info: CoreStateMachine::updateTrackBlock Dec 10 11:28:52 volumio-pb volumio[909]: info: CorePlayQueue::getTrackBlock Dec 10 11:28:52 volumio-pb volumio[909]: info: CoreCommandRouter::volumioPlay Dec 10 11:28:52 volumio-pb volumio[909]: info: CoreStateMachine::play index 0 Dec 10 11:28:52 volumio-pb volumio[909]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 10 11:28:52 volumio-pb volumio[909]: info: CoreStateMachine::stop Dec 10 11:28:52 volumio-pb volumio[909]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 10 11:28:52 volumio-pb volumio[909]: info: CoreStateMachine::play index undefined Dec 10 11:28:52 volumio-pb volumio[909]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 10 11:28:52 volumio-pb volumio[909]: info: CorePlayQueue::getTrack 0 Dec 10 11:28:52 volumio-pb volumio[909]: info: CoreStateMachine::startPlaybackTimer Dec 10 11:28:52 volumio-pb volumio[909]: info: CorePlayQueue::getTrack 0 Dec 10 11:28:52 volumio-pb volumio[909]: info: [1765362532202] ControllerWebradio::clearAddPlayTrack Dec 10 11:28:52 volumio-pb volumio[909]: verbose: ControllerMpd::sendMpdCommand stop Dec 10 11:28:52 volumio-pb volumio[909]: info: sendMpdCommand stop took 1 milliseconds Dec 10 11:28:52 volumio-pb volumio[909]: verbose: ControllerMpd::sendMpdCommand clear Dec 10 11:28:52 volumio-pb volumio[909]: info: Dec 10 11:28:52 volumio-pb volumio[909]: ---------------------------- MPD announces system playlist update Dec 10 11:28:52 volumio-pb volumio[909]: info: Ignoring MPD Status Update Dec 10 11:28:52 volumio-pb volumio[909]: info: sendMpdCommand clear took 2 milliseconds Dec 10 11:28:52 volumio-pb volumio[909]: verbose: ControllerMpd::sendMpdCommand load "http://icecast.radiofrance.fr/francemusiquelajazz-hifi.aac" Dec 10 11:28:52 volumio-pb volumio[909]: info: Dec 10 11:28:52 volumio-pb volumio[909]: ---------------------------- MPD announces system playlist update Dec 10 11:28:52 volumio-pb volumio[909]: info: Ignoring MPD Status Update Dec 10 11:28:52 volumio-pb volumio[909]: info: Dec 10 11:28:52 volumio-pb volumio[909]: ---------------------------- MPD announces system playlist update Dec 10 11:28:52 volumio-pb volumio[909]: info: Ignoring MPD Status Update Dec 10 11:28:52 volumio-pb volumio[909]: error: updateQueue error: null Dec 10 11:28:52 volumio-pb volumio[909]: info: ------------------------------ 4ms Dec 10 11:28:53 volumio-pb volumio[909]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 10 11:28:53 volumio-pb volumio[909]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 10 11:28:53 volumio-pb volumio[909]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 10 11:28:53 volumio-pb volumio[909]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 10 11:28:53 volumio-pb volumio[909]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 10 11:28:53 volumio-pb volumio[909]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 10 11:28:53 volumio-pb volumio[909]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 10 11:28:53 volumio-pb volumio[909]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 10 11:28:53 volumio-pb volumio[909]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 10 11:28:53 volumio-pb volumio[909]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 10 11:28:53 volumio-pb volumio[909]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Dec 10 11:28:53 volumio-pb volumio[909]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 10 11:28:53 volumio-pb volumio[909]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 10 11:28:53 volumio-pb volumio[909]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 10 11:28:53 volumio-pb volumio[909]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 10 11:28:53 volumio-pb volumio[909]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 10 11:28:53 volumio-pb volumio[909]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 10 11:28:53 volumio-pb volumio[909]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 10 11:28:53 volumio-pb volumio[909]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 10 11:28:53 volumio-pb volumio[909]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 10 11:28:53 volumio-pb volumio[909]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 10 11:28:53 volumio-pb volumio[909]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Dec 10 11:28:53 volumio-pb volumio[909]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 10 11:28:53 volumio-pb volumio[909]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 10 11:28:53 volumio-pb volumio[909]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 10 11:28:53 volumio-pb volumio[909]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 10 11:28:53 volumio-pb volumio[909]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 10 11:28:53 volumio-pb volumio[909]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 10 11:28:53 volumio-pb volumio[909]: info: Adding plugin bluetooth to MyMusic Plugins Dec 10 11:28:53 volumio-pb volumio[909]: info: Adding plugin multiroom to MyMusic Plugins Dec 10 11:28:53 volumio-pb volumio[909]: info: Adding plugin metavolumio to MyMusic Plugins Dec 10 11:28:53 volumio-pb volumio[909]: info: Adding plugin cd_controller to MyMusic Plugins Dec 10 11:28:53 volumio-pb volumio[909]: info: Adding plugin smart_inputs to MyMusic Plugins Dec 10 11:28:53 volumio-pb volumio[909]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 10 11:28:53 volumio-pb volumio[909]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 10 11:28:54 volumio-pb volumio[909]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 10 11:28:54 volumio-pb volumio[909]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 10 11:28:54 volumio-pb volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 11:28:54 volumio-pb volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 11:28:54 volumio-pb volumio[909]: info: Starting MyVolumio Remote Streaming Endpoints Dec 10 11:28:54 volumio-pb volumio[909]: info: MyVolumio login type: Token Dec 10 11:28:54 volumio-pb volumio[909]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 10 11:28:54 volumio-pb volumio[909]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 10 11:28:55 volumio-pb volumio[909]: info: Starting Streaming Service Transparent Proxy Dec 10 11:28:55 volumio-pb volumio[909]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 10 11:28:55 volumio-pb volumio[909]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 10 11:28:55 volumio-pb volumio[909]: info: Streaming services startup Dec 10 11:28:55 volumio-pb volumio[909]: info: Starting Streaming Daemon Dec 10 11:28:55 volumio-pb sudo[1396]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 10 11:28:55 volumio-pb sudo[1396]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 10 11:28:55 volumio-pb volumio[909]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Dec 10 11:28:55 volumio-pb sudo[1396]: pam_unix(sudo:session): session closed for user root Dec 10 11:28:55 volumio-pb volumio[909]: error: updateQueue error: null Dec 10 11:28:55 volumio-pb volumio[909]: error: updateQueue error: null Dec 10 11:28:55 volumio-pb volumio[909]: verbose: ControllerMpd::sendMpdCommand add "http://icecast.radiofrance.fr/francemusiquelajazz-hifi.aac" Dec 10 11:28:55 volumio-pb volumio[909]: info: ------------------------------ 3256ms Dec 10 11:28:55 volumio-pb volumio[909]: info: ------------------------------ 3255ms Dec 10 11:28:55 volumio-pb volumio[909]: error: Cannot start Volumio Streaming Daemon Dec 10 11:28:55 volumio-pb volumio[909]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 10 11:28:55 volumio-pb volumio[909]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 10 11:28:55 volumio-pb volumio[909]: info: Dec 10 11:28:55 volumio-pb volumio[909]: ---------------------------- MPD announces system playlist update Dec 10 11:28:55 volumio-pb volumio[909]: info: Ignoring MPD Status Update Dec 10 11:28:55 volumio-pb volumio[909]: info: sendMpdCommand add "http://icecast.radiofrance.fr/francemusiquelajazz-hifi.aac" took 19 milliseconds Dec 10 11:28:55 volumio-pb volumio[909]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 10 11:28:55 volumio-pb volumio[909]: verbose: ControllerMpd::sendMpdCommand play Dec 10 11:28:55 volumio-pb volumio[909]: info: Dec 10 11:28:55 volumio-pb volumio[909]: ---------------------------- MPD announces system playlist update Dec 10 11:28:55 volumio-pb volumio[909]: info: Ignoring MPD Status Update Dec 10 11:28:55 volumio-pb volumio[909]: info: Dec 10 11:28:55 volumio-pb volumio[909]: ---------------------------- MPD announces system playlist update Dec 10 11:28:55 volumio-pb volumio[909]: info: Ignoring MPD Status Update Dec 10 11:28:55 volumio-pb volumio[909]: info: ------------------------------ 6ms Dec 10 11:28:55 volumio-pb volumio[909]: info: sendMpdCommand play took 5 milliseconds Dec 10 11:28:55 volumio-pb volumio[909]: info: ------------------------------ 4ms Dec 10 11:28:55 volumio-pb volumio[909]: info: ------------------------------ 3ms Dec 10 11:28:55 volumio-pb volumio[909]: error: MyVolumio Custom Token format not valid, refreshing it Dec 10 11:28:56 volumio-pb volumio[909]: STREAMING PROXY: Starting server on port 3245 Dec 10 11:28:56 volumio-pb volumio[909]: Node JS runtime: 14 Dec 10 11:28:56 volumio-pb volumio[909]: info: MyVolumio login type: Token Dec 10 11:28:56 volumio-pb volumio[909]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Dec 10 11:28:56 volumio-pb volumio[909]: info: MyVolumio token set successfully Dec 10 11:28:56 volumio-pb volumio[909]: info: MYVOLUMIO: Adding device Dec 10 11:28:56 volumio-pb volumio[909]: info: MYVOLUMIO: Evaluating Server Dec 10 11:28:57 volumio-pb volumio[909]: info: MyVolumio status changed Dec 10 11:28:57 volumio-pb volumio[909]: info: Streaming services startup Dec 10 11:28:57 volumio-pb volumio[909]: info: Starting Streaming Daemon Dec 10 11:28:57 volumio-pb volumio[909]: info: Removing browser output: myVolumio user plan is not superstar Dec 10 11:28:57 volumio-pb volumio[909]: info: Removing audio output: Dec 10 11:28:57 volumio-pb volumio[909]: info: Stoppping Tunnel 1 Dec 10 11:28:57 volumio-pb sudo[1435]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 10 11:28:57 volumio-pb sudo[1435]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 10 11:28:57 volumio-pb sudo[1435]: pam_unix(sudo:session): session closed for user root Dec 10 11:28:57 volumio-pb sudo[1438]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Dec 10 11:28:57 volumio-pb sudo[1438]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 10 11:28:57 volumio-pb volumio[909]: error: Cannot start Volumio Streaming Daemon Dec 10 11:28:57 volumio-pb volumio[909]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 10 11:28:57 volumio-pb volumio[909]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 10 11:28:57 volumio-pb sudo[1438]: pam_unix(sudo:session): session closed for user root Dec 10 11:28:57 volumio-pb volumio[909]: info: Remote SSH Stopped Dec 10 11:28:57 volumio-pb volumio[909]: info: Setting Geolocation for MyVolumio to eu4 Dec 10 11:28:57 volumio-pb volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 11:28:57 volumio-pb volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 11:28:57 volumio-pb volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 11:28:57 volumio-pb volumio[909]: info: Dec 10 11:28:57 volumio-pb volumio[909]: ---------------------------- MPD announces state update: player Dec 10 11:28:57 volumio-pb volumio[909]: info: ControllerMpd::getState Dec 10 11:28:57 volumio-pb volumio[909]: verbose: ControllerMpd::sendMpdCommand status Dec 10 11:28:57 volumio-pb volumio[909]: info: Dec 10 11:28:57 volumio-pb volumio[909]: ---------------------------- MPD announces state update: player Dec 10 11:28:57 volumio-pb volumio[909]: info: ControllerMpd::getState Dec 10 11:28:57 volumio-pb volumio[909]: verbose: ControllerMpd::sendMpdCommand status Dec 10 11:28:57 volumio-pb volumio[909]: info: Dec 10 11:28:57 volumio-pb volumio[909]: ---------------------------- MPD announces state update: player Dec 10 11:28:57 volumio-pb volumio[909]: info: ControllerMpd::getState Dec 10 11:28:57 volumio-pb volumio[909]: verbose: ControllerMpd::sendMpdCommand status Dec 10 11:28:57 volumio-pb volumio[909]: info: Dec 10 11:28:57 volumio-pb volumio[909]: ---------------------------- MPD announces state update: player Dec 10 11:28:57 volumio-pb volumio[909]: info: Dec 10 11:28:57 volumio-pb volumio[909]: ---------------------------- MPD announces system playlist update Dec 10 11:28:57 volumio-pb volumio[909]: info: Ignoring MPD Status Update Dec 10 11:28:57 volumio-pb volumio[909]: info: Dec 10 11:28:57 volumio-pb volumio[909]: ---------------------------- MPD announces state update: player Dec 10 11:28:57 volumio-pb volumio[909]: info: sendMpdCommand status took 8 milliseconds Dec 10 11:28:57 volumio-pb volumio[909]: info: ControllerMpd::getState Dec 10 11:28:57 volumio-pb volumio[909]: verbose: ControllerMpd::sendMpdCommand status Dec 10 11:28:57 volumio-pb volumio[909]: info: sendMpdCommand status took 6 milliseconds Dec 10 11:28:57 volumio-pb volumio[909]: info: ControllerMpd::getState Dec 10 11:28:57 volumio-pb volumio[909]: verbose: ControllerMpd::sendMpdCommand status Dec 10 11:28:57 volumio-pb volumio[909]: info: sendMpdCommand status took 4 milliseconds Dec 10 11:28:57 volumio-pb volumio[909]: verbose: ControllerMpd::parseState Dec 10 11:28:57 volumio-pb volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 11:28:57 volumio-pb volumio[909]: verbose: ControllerMpd::parseState Dec 10 11:28:57 volumio-pb volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 11:28:57 volumio-pb volumio[909]: verbose: ControllerMpd::parseState Dec 10 11:28:57 volumio-pb volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 11:28:57 volumio-pb volumio[909]: info: Dec 10 11:28:57 volumio-pb volumio[909]: ---------------------------- MPD announces state update: player Dec 10 11:28:57 volumio-pb volumio[909]: info: ControllerMpd::getState Dec 10 11:28:57 volumio-pb volumio[909]: verbose: ControllerMpd::sendMpdCommand status Dec 10 11:28:57 volumio-pb volumio[909]: info: Dec 10 11:28:57 volumio-pb volumio[909]: ---------------------------- MPD announces state update: player Dec 10 11:28:57 volumio-pb volumio[909]: info: ControllerMpd::getState Dec 10 11:28:57 volumio-pb volumio[909]: verbose: ControllerMpd::sendMpdCommand status Dec 10 11:28:57 volumio-pb volumio[909]: info: sendMpdCommand status took 6 milliseconds Dec 10 11:28:57 volumio-pb volumio[909]: info: ------------------------------ 7ms Dec 10 11:28:57 volumio-pb volumio[909]: info: sendMpdCommand status took 6 milliseconds Dec 10 11:28:57 volumio-pb volumio[909]: info: sendMpdCommand playlistinfo took 6 milliseconds Dec 10 11:28:57 volumio-pb volumio[909]: info: sendMpdCommand playlistinfo took 6 milliseconds Dec 10 11:28:57 volumio-pb volumio[909]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 10 11:28:57 volumio-pb volumio[909]: info: sendMpdCommand status took 3 milliseconds Dec 10 11:28:57 volumio-pb volumio[909]: info: sendMpdCommand status took 2 milliseconds Dec 10 11:28:57 volumio-pb volumio[909]: verbose: ControllerMpd::parseState Dec 10 11:28:57 volumio-pb volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 11:28:57 volumio-pb volumio[909]: verbose: ControllerMpd::parseState Dec 10 11:28:57 volumio-pb volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 11:28:57 volumio-pb volumio[909]: verbose: ControllerMpd::parseTrackInfo Dec 10 11:28:57 volumio-pb volumio[909]: verbose: ControllerMpd::parseTrackInfo Dec 10 11:28:57 volumio-pb volumio[909]: verbose: ControllerMpd::parseTrackInfo Dec 10 11:28:57 volumio-pb volumio[909]: verbose: ControllerMpd::parseState Dec 10 11:28:57 volumio-pb volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 11:28:57 volumio-pb volumio[909]: verbose: ControllerMpd::parseState Dec 10 11:28:57 volumio-pb volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 11:28:57 volumio-pb volumio[909]: info: ControllerMpd::pushState Dec 10 11:28:57 volumio-pb volumio[909]: info: CoreCommandRouter::servicePushState Dec 10 11:28:57 volumio-pb volumio[909]: info: CorePlayQueue::getTrack 0 Dec 10 11:28:57 volumio-pb volumio[909]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"francemusiquelajazz-hifi.aac","artist":"francemusiquelajazz-hifi.aac","album":null,"uri":"http://icecast.radiofrance.fr/francemusiquelajazz-hifi.aac","trackType":"aac"} Dec 10 11:28:57 volumio-pb volumio[909]: verbose: CURRENT POSITION 0 Dec 10 11:28:57 volumio-pb volumio[909]: info: CoreStateMachine::syncState stateService play Dec 10 11:28:57 volumio-pb volumio[909]: info: CoreStateMachine::syncState currentStatus stop Dec 10 11:28:57 volumio-pb volumio[909]: info: ControllerMpd::pushState Dec 10 11:28:57 volumio-pb volumio[909]: info: CoreCommandRouter::servicePushState Dec 10 11:28:57 volumio-pb volumio[909]: info: CorePlayQueue::getTrack 0 Dec 10 11:28:57 volumio-pb volumio[909]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"francemusiquelajazz-hifi.aac","artist":"francemusiquelajazz-hifi.aac","album":null,"uri":"http://icecast.radiofrance.fr/francemusiquelajazz-hifi.aac","trackType":"aac"} Dec 10 11:28:57 volumio-pb volumio[909]: verbose: CURRENT POSITION 0 Dec 10 11:28:57 volumio-pb volumio[909]: info: CoreStateMachine::syncState stateService play Dec 10 11:28:57 volumio-pb volumio[909]: info: CoreStateMachine::syncState currentStatus play Dec 10 11:28:57 volumio-pb volumio[909]: info: Received an update from plugin. extracting info from payload Dec 10 11:28:57 volumio-pb volumio[909]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 10 11:28:57 volumio-pb volumio[909]: info: CoreStateMachine::pushState Dec 10 11:28:57 volumio-pb volumio[909]: info: CorePlayQueue::getTrack 0 Dec 10 11:28:57 volumio-pb volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 11:28:57 volumio-pb volumio[909]: info: CoreCommandRouter::volumioPushState Dec 10 11:28:57 volumio-pb volumio[909]: info: CoreStateMachine::pushState Dec 10 11:28:57 volumio-pb volumio[909]: info: CoreCommandRouter::volumioPushState Dec 10 11:28:57 volumio-pb volumio[909]: info: ControllerMpd::pushState Dec 10 11:28:57 volumio-pb volumio[909]: info: CoreCommandRouter::servicePushState Dec 10 11:28:57 volumio-pb volumio[909]: info: CorePlayQueue::getTrack 0 Dec 10 11:28:57 volumio-pb volumio[909]: verbose: STATE SERVICE {"status":"play","position":0,"seek":861,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"190 Kbps","isStreaming":false,"title":"francemusiquelajazz-hifi.aac","artist":"francemusiquelajazz-hifi.aac","album":null,"uri":"http://icecast.radiofrance.fr/francemusiquelajazz-hifi.aac","trackType":"aac"} Dec 10 11:28:57 volumio-pb volumio[909]: verbose: CURRENT POSITION 0 Dec 10 11:28:57 volumio-pb volumio[909]: info: CoreStateMachine::syncState stateService play Dec 10 11:28:57 volumio-pb volumio[909]: info: CoreStateMachine::syncState currentStatus play Dec 10 11:28:57 volumio-pb volumio[909]: info: Received an update from plugin. extracting info from payload Dec 10 11:28:57 volumio-pb volumio[909]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 10 11:28:57 volumio-pb volumio[909]: info: CoreStateMachine::pushState Dec 10 11:28:57 volumio-pb volumio[909]: info: CorePlayQueue::getTrack 0 Dec 10 11:28:57 volumio-pb volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 11:28:57 volumio-pb volumio[909]: info: CoreCommandRouter::volumioPushState Dec 10 11:28:57 volumio-pb volumio[909]: info: CoreStateMachine::pushState Dec 10 11:28:57 volumio-pb volumio[909]: info: CoreCommandRouter::volumioPushState Dec 10 11:28:57 volumio-pb volumio[909]: info: ------------------------------ 31ms Dec 10 11:28:57 volumio-pb volumio[909]: info: ------------------------------ 39ms Dec 10 11:28:57 volumio-pb volumio[909]: info: ------------------------------ 37ms Dec 10 11:28:57 volumio-pb volumio[909]: info: Dec 10 11:28:57 volumio-pb volumio[909]: ---------------------------- MPD announces system playlist update Dec 10 11:28:57 volumio-pb volumio[909]: info: Ignoring MPD Status Update Dec 10 11:28:57 volumio-pb volumio[909]: info: Dec 10 11:28:57 volumio-pb volumio[909]: ---------------------------- MPD announces state update: player Dec 10 11:28:57 volumio-pb volumio[909]: info: ControllerMpd::getState Dec 10 11:28:57 volumio-pb volumio[909]: verbose: ControllerMpd::sendMpdCommand status Dec 10 11:28:57 volumio-pb volumio[909]: info: Dec 10 11:28:57 volumio-pb volumio[909]: ---------------------------- MPD announces system playlist update Dec 10 11:28:57 volumio-pb volumio[909]: info: Ignoring MPD Status Update Dec 10 11:28:57 volumio-pb volumio[909]: info: Dec 10 11:28:57 volumio-pb volumio[909]: ---------------------------- MPD announces state update: player Dec 10 11:28:57 volumio-pb volumio[909]: info: ControllerMpd::getState Dec 10 11:28:57 volumio-pb volumio[909]: verbose: ControllerMpd::sendMpdCommand status Dec 10 11:28:57 volumio-pb volumio[909]: info: sendMpdCommand playlistinfo took 28 milliseconds Dec 10 11:28:57 volumio-pb volumio[909]: info: sendMpdCommand playlistinfo took 27 milliseconds Dec 10 11:28:57 volumio-pb volumio[909]: info: ------------------------------ 4ms Dec 10 11:28:57 volumio-pb volumio[909]: info: sendMpdCommand status took 3 milliseconds Dec 10 11:28:57 volumio-pb volumio[909]: info: ------------------------------ 2ms Dec 10 11:28:57 volumio-pb volumio[909]: info: sendMpdCommand status took 3 milliseconds Dec 10 11:28:57 volumio-pb volumio[909]: verbose: ControllerMpd::parseTrackInfo Dec 10 11:28:57 volumio-pb volumio[909]: verbose: ControllerMpd::parseState Dec 10 11:28:57 volumio-pb volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 11:28:57 volumio-pb volumio[909]: verbose: ControllerMpd::parseState Dec 10 11:28:57 volumio-pb volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 11:28:57 volumio-pb volumio[909]: info: ControllerMpd::pushState Dec 10 11:28:57 volumio-pb volumio[909]: info: CoreCommandRouter::servicePushState Dec 10 11:28:57 volumio-pb volumio[909]: info: CorePlayQueue::getTrack 0 Dec 10 11:28:57 volumio-pb volumio[909]: verbose: STATE SERVICE {"status":"play","position":0,"seek":987,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"190 Kbps","isStreaming":false,"title":"francemusiquelajazz-hifi.aac","artist":"francemusiquelajazz-hifi.aac","album":null,"uri":"http://icecast.radiofrance.fr/francemusiquelajazz-hifi.aac","trackType":"aac"} Dec 10 11:28:57 volumio-pb volumio[909]: verbose: CURRENT POSITION 0 Dec 10 11:28:57 volumio-pb volumio[909]: info: CoreStateMachine::syncState stateService play Dec 10 11:28:57 volumio-pb volumio[909]: info: CoreStateMachine::syncState currentStatus play Dec 10 11:28:57 volumio-pb volumio[909]: info: Received an update from plugin. extracting info from payload Dec 10 11:28:57 volumio-pb volumio[909]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 10 11:28:57 volumio-pb volumio[909]: info: CoreStateMachine::pushState Dec 10 11:28:57 volumio-pb volumio[909]: info: CorePlayQueue::getTrack 0 Dec 10 11:28:57 volumio-pb volumio[909]: info: CoreCommandRouter::volumioPushState Dec 10 11:28:57 volumio-pb volumio[909]: info: CoreStateMachine::pushState Dec 10 11:28:57 volumio-pb volumio[909]: info: CoreCommandRouter::volumioPushState Dec 10 11:28:57 volumio-pb volumio[909]: info: ControllerMpd::pushState Dec 10 11:28:57 volumio-pb volumio[909]: info: CoreCommandRouter::servicePushState Dec 10 11:28:57 volumio-pb volumio[909]: info: CorePlayQueue::getTrack 0 Dec 10 11:28:57 volumio-pb volumio[909]: verbose: STATE SERVICE {"status":"play","position":0,"seek":987,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"190 Kbps","isStreaming":false,"title":"francemusiquelajazz-hifi.aac","artist":"francemusiquelajazz-hifi.aac","album":null,"uri":"http://icecast.radiofrance.fr/francemusiquelajazz-hifi.aac","trackType":"aac"} Dec 10 11:28:57 volumio-pb volumio[909]: verbose: CURRENT POSITION 0 Dec 10 11:28:57 volumio-pb volumio[909]: info: CoreStateMachine::syncState stateService play Dec 10 11:28:57 volumio-pb volumio[909]: info: CoreStateMachine::syncState currentStatus play Dec 10 11:28:57 volumio-pb volumio[909]: info: Received an update from plugin. extracting info from payload Dec 10 11:28:57 volumio-pb volumio[909]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 10 11:28:57 volumio-pb volumio[909]: info: CoreStateMachine::pushState Dec 10 11:28:57 volumio-pb volumio[909]: info: CorePlayQueue::getTrack 0 Dec 10 11:28:57 volumio-pb volumio[909]: info: CoreCommandRouter::volumioPushState Dec 10 11:28:57 volumio-pb volumio[909]: info: CoreStateMachine::pushState Dec 10 11:28:57 volumio-pb volumio[909]: info: CoreCommandRouter::volumioPushState Dec 10 11:28:57 volumio-pb volumio[909]: info: ControllerMpd::pushState Dec 10 11:28:57 volumio-pb volumio[909]: info: CoreCommandRouter::servicePushState Dec 10 11:28:57 volumio-pb volumio[909]: info: CorePlayQueue::getTrack 0 Dec 10 11:28:57 volumio-pb volumio[909]: verbose: STATE SERVICE {"status":"play","position":0,"seek":987,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"190 Kbps","isStreaming":false,"title":"francemusiquelajazz-hifi.aac","artist":"francemusiquelajazz-hifi.aac","album":null,"uri":"http://icecast.radiofrance.fr/francemusiquelajazz-hifi.aac","trackType":"aac"} Dec 10 11:28:57 volumio-pb volumio[909]: verbose: CURRENT POSITION 0 Dec 10 11:28:57 volumio-pb volumio[909]: info: CoreStateMachine::syncState stateService play Dec 10 11:28:57 volumio-pb volumio[909]: info: CoreStateMachine::syncState currentStatus play Dec 10 11:28:57 volumio-pb volumio[909]: info: Received an update from plugin. extracting info from payload Dec 10 11:28:57 volumio-pb volumio[909]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 10 11:28:57 volumio-pb volumio[909]: info: CoreStateMachine::pushState Dec 10 11:28:57 volumio-pb volumio[909]: info: CorePlayQueue::getTrack 0 Dec 10 11:28:57 volumio-pb volumio[909]: info: CoreCommandRouter::volumioPushState Dec 10 11:28:57 volumio-pb volumio[909]: info: CoreStateMachine::pushState Dec 10 11:28:57 volumio-pb volumio[909]: info: CoreCommandRouter::volumioPushState Dec 10 11:28:57 volumio-pb volumio[909]: info: ControllerMpd::pushState Dec 10 11:28:57 volumio-pb volumio[909]: info: CoreCommandRouter::servicePushState Dec 10 11:28:57 volumio-pb volumio[909]: info: CorePlayQueue::getTrack 0 Dec 10 11:28:57 volumio-pb volumio[909]: verbose: STATE SERVICE {"status":"play","position":0,"seek":987,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"190 Kbps","isStreaming":false,"title":"francemusiquelajazz-hifi.aac","artist":"francemusiquelajazz-hifi.aac","album":null,"uri":"http://icecast.radiofrance.fr/francemusiquelajazz-hifi.aac","trackType":"aac"} Dec 10 11:28:57 volumio-pb volumio[909]: verbose: CURRENT POSITION 0 Dec 10 11:28:57 volumio-pb volumio[909]: info: CoreStateMachine::syncState stateService play Dec 10 11:28:57 volumio-pb volumio[909]: info: CoreStateMachine::syncState currentStatus play Dec 10 11:28:57 volumio-pb volumio[909]: info: Received an update from plugin. extracting info from payload Dec 10 11:28:57 volumio-pb volumio[909]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 10 11:28:57 volumio-pb volumio[909]: info: CoreStateMachine::pushState Dec 10 11:28:57 volumio-pb volumio[909]: info: CorePlayQueue::getTrack 0 Dec 10 11:28:57 volumio-pb volumio[909]: info: CoreCommandRouter::volumioPushState Dec 10 11:28:57 volumio-pb volumio[909]: info: CoreStateMachine::pushState Dec 10 11:28:57 volumio-pb volumio[909]: info: CoreCommandRouter::volumioPushState Dec 10 11:28:57 volumio-pb volumio[909]: info: ------------------------------ 83ms Dec 10 11:28:57 volumio-pb volumio[909]: info: ------------------------------ 83ms Dec 10 11:28:57 volumio-pb volumio[909]: info: ------------------------------ 79ms Dec 10 11:28:57 volumio-pb volumio[909]: info: ------------------------------ 79ms Dec 10 11:28:57 volumio-pb volumio[909]: info: sendMpdCommand playlistinfo took 51 milliseconds Dec 10 11:28:57 volumio-pb volumio[909]: info: sendMpdCommand playlistinfo took 50 milliseconds Dec 10 11:28:57 volumio-pb volumio[909]: verbose: ControllerMpd::parseTrackInfo Dec 10 11:28:57 volumio-pb volumio[909]: verbose: ControllerMpd::parseTrackInfo Dec 10 11:28:57 volumio-pb volumio[909]: info: ControllerMpd::pushState Dec 10 11:28:57 volumio-pb volumio[909]: info: CoreCommandRouter::servicePushState Dec 10 11:28:57 volumio-pb volumio[909]: info: CorePlayQueue::getTrack 0 Dec 10 11:28:57 volumio-pb volumio[909]: verbose: STATE SERVICE {"status":"play","position":0,"seek":987,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"190 Kbps","isStreaming":false,"title":"francemusiquelajazz-hifi.aac","artist":"francemusiquelajazz-hifi.aac","album":null,"uri":"http://icecast.radiofrance.fr/francemusiquelajazz-hifi.aac","trackType":"aac"} Dec 10 11:28:57 volumio-pb volumio[909]: verbose: CURRENT POSITION 0 Dec 10 11:28:57 volumio-pb volumio[909]: info: CoreStateMachine::syncState stateService play Dec 10 11:28:57 volumio-pb volumio[909]: info: CoreStateMachine::syncState currentStatus play Dec 10 11:28:57 volumio-pb volumio[909]: info: Received an update from plugin. extracting info from payload Dec 10 11:28:57 volumio-pb volumio[909]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 10 11:28:57 volumio-pb volumio[909]: info: CoreStateMachine::pushState Dec 10 11:28:57 volumio-pb volumio[909]: info: CorePlayQueue::getTrack 0 Dec 10 11:28:57 volumio-pb volumio[909]: info: CoreCommandRouter::volumioPushState Dec 10 11:28:57 volumio-pb volumio[909]: info: CoreStateMachine::pushState Dec 10 11:28:57 volumio-pb volumio[909]: info: CoreCommandRouter::volumioPushState Dec 10 11:28:57 volumio-pb volumio[909]: info: ControllerMpd::pushState Dec 10 11:28:57 volumio-pb volumio[909]: info: CoreCommandRouter::servicePushState Dec 10 11:28:57 volumio-pb volumio[909]: info: CorePlayQueue::getTrack 0 Dec 10 11:28:57 volumio-pb volumio[909]: verbose: STATE SERVICE {"status":"play","position":0,"seek":987,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"190 Kbps","isStreaming":false,"title":"francemusiquelajazz-hifi.aac","artist":"francemusiquelajazz-hifi.aac","album":null,"uri":"http://icecast.radiofrance.fr/francemusiquelajazz-hifi.aac","trackType":"aac"} Dec 10 11:28:57 volumio-pb volumio[909]: verbose: CURRENT POSITION 0 Dec 10 11:28:57 volumio-pb volumio[909]: info: CoreStateMachine::syncState stateService play Dec 10 11:28:57 volumio-pb volumio[909]: info: CoreStateMachine::syncState currentStatus play Dec 10 11:28:57 volumio-pb volumio[909]: info: Received an update from plugin. extracting info from payload Dec 10 11:28:57 volumio-pb volumio[909]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 10 11:28:57 volumio-pb volumio[909]: info: CoreStateMachine::pushState Dec 10 11:28:57 volumio-pb volumio[909]: info: CorePlayQueue::getTrack 0 Dec 10 11:28:57 volumio-pb volumio[909]: info: CoreCommandRouter::volumioPushState Dec 10 11:28:57 volumio-pb volumio[909]: info: CoreStateMachine::pushState Dec 10 11:28:57 volumio-pb volumio[909]: info: CoreCommandRouter::volumioPushState Dec 10 11:28:57 volumio-pb volumio[909]: info: ------------------------------ 83ms Dec 10 11:28:57 volumio-pb volumio[909]: info: ------------------------------ 82ms Dec 10 11:28:57 volumio-pb volumio[909]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Dec 10 11:28:58 volumio-pb volumio[909]: info: Discovery: A device disappeared from network Dec 10 11:28:58 volumio-pb volumio[909]: info: Discovery: Device volumio-pb disappeared from network Dec 10 11:28:58 volumio-pb volumio[909]: info: Discovery: A device disappeared from network Dec 10 11:28:58 volumio-pb volumio[909]: info: Discovery: adding c76ba02b-c050-4c77-b24b-75a86dc87ea4 Dec 10 11:28:58 volumio-pb volumio[909]: info: Discovery: Found device Volumio-pb Dec 10 11:28:58 volumio-pb volumio[909]: info: CoreCommandRouter::volumioGetState Dec 10 11:28:58 volumio-pb volumio[909]: info: Discovery: this is already registered, c76ba02b-c050-4c77-b24b-75a86dc87ea4 Dec 10 11:28:58 volumio-pb volumio[909]: info: Discovery: Found device Volumio-pb Dec 10 11:28:58 volumio-pb volumio[909]: info: CoreCommandRouter::volumioGetState Dec 10 11:28:58 volumio-pb volumio[909]: info: CoreCommandRouter::volumioGetState Dec 10 11:28:58 volumio-pb volumio[909]: info: CoreCommandRouter::volumioGetQueue Dec 10 11:28:58 volumio-pb volumio[909]: info: CoreStateMachine::getQueue Dec 10 11:28:58 volumio-pb volumio[909]: info: CorePlayQueue::getQueue Dec 10 11:28:58 volumio-pb volumio[909]: info: Updating MyVolumio device info Dec 10 11:28:58 volumio-pb volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 11:28:58 volumio-pb volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 11:28:58 volumio-pb volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 11:28:58 volumio-pb volumio[909]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Dec 10 11:28:59 volumio-pb volumio[909]: info: CoreCommandRouter::volumioGetState Dec 10 11:28:59 volumio-pb volumio[909]: info: CoreCommandRouter::volumioGetQueue Dec 10 11:28:59 volumio-pb volumio[909]: info: CoreStateMachine::getQueue Dec 10 11:28:59 volumio-pb volumio[909]: info: CorePlayQueue::getQueue Dec 10 11:28:59 volumio-pb volumio[909]: info: Preload queue cleared Dec 10 11:28:59 volumio-pb volumio[909]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 10 11:28:59 volumio-pb volumio[909]: info: CoreStateMachine::ClearQueue Dec 10 11:28:59 volumio-pb volumio[909]: info: CoreStateMachine::stop Dec 10 11:28:59 volumio-pb volumio[909]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 10 11:28:59 volumio-pb volumio[909]: info: CoreStateMachine::stPlaybackTimer Dec 10 11:28:59 volumio-pb volumio[909]: info: CoreStateMachine::updateTrackBlock Dec 10 11:28:59 volumio-pb volumio[909]: info: CorePlayQueue::getTrackBlock Dec 10 11:28:59 volumio-pb volumio[909]: info: CoreStateMachine::pushState Dec 10 11:28:59 volumio-pb volumio[909]: info: CorePlayQueue::getTrack 0 Dec 10 11:28:59 volumio-pb volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 11:28:59 volumio-pb volumio[909]: info: CoreCommandRouter::volumioPushState Dec 10 11:28:59 volumio-pb volumio[909]: info: CoreStateMachine::serviceStop Dec 10 11:28:59 volumio-pb volumio[909]: info: CorePlayQueue::getTrack 0 Dec 10 11:28:59 volumio-pb volumio[909]: info: CoreCommandRouter::serviceStop Dec 10 11:28:59 volumio-pb volumio[909]: info: [1765362539693] ControllerWebradio::stop Dec 10 11:28:59 volumio-pb volumio[909]: verbose: ControllerMpd::sendMpdCommand stop Dec 10 11:28:59 volumio-pb volumio[909]: info: CorePlayQueue::clearPlayQueue Dec 10 11:28:59 volumio-pb volumio[909]: info: CorePlayQueue::saveQueue Dec 10 11:28:59 volumio-pb volumio[909]: info: CoreCommandRouter::volumioPushQueue Dec 10 11:28:59 volumio-pb volumio[909]: info: CoreStateMachine::addQueueItems Dec 10 11:28:59 volumio-pb volumio[909]: info: CorePlayQueue::addQueueItems Dec 10 11:28:59 volumio-pb volumio[909]: info: Preload queue cleared Dec 10 11:28:59 volumio-pb volumio[909]: info: Adding Item to queue: https://uk2.streamingpulse.com/ssl/vcr1 Dec 10 11:28:59 volumio-pb volumio[909]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Dec 10 11:28:59 volumio-pb volumio[909]: info: CoreCommandRouter::volumioPushQueue Dec 10 11:28:59 volumio-pb volumio[909]: info: CorePlayQueue::saveQueue Dec 10 11:28:59 volumio-pb volumio[909]: info: CoreStateMachine::updateTrackBlock Dec 10 11:28:59 volumio-pb volumio[909]: info: CorePlayQueue::getTrackBlock Dec 10 11:28:59 volumio-pb volumio[909]: info: CoreCommandRouter::volumioPlay Dec 10 11:28:59 volumio-pb volumio[909]: info: CoreStateMachine::play index 0 Dec 10 11:28:59 volumio-pb volumio[909]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 10 11:28:59 volumio-pb volumio[909]: info: CoreStateMachine::stop Dec 10 11:28:59 volumio-pb volumio[909]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 10 11:28:59 volumio-pb volumio[909]: info: CoreStateMachine::play index undefined Dec 10 11:28:59 volumio-pb volumio[909]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 10 11:28:59 volumio-pb volumio[909]: info: CorePlayQueue::getTrack 0 Dec 10 11:28:59 volumio-pb volumio[909]: info: CoreStateMachine::startPlaybackTimer Dec 10 11:28:59 volumio-pb volumio[909]: info: CorePlayQueue::getTrack 0 Dec 10 11:28:59 volumio-pb volumio[909]: info: [1765362539705] ControllerWebradio::clearAddPlayTrack Dec 10 11:28:59 volumio-pb volumio[909]: verbose: ControllerMpd::sendMpdCommand stop Dec 10 11:28:59 volumio-pb volumio[909]: info: Dec 10 11:28:59 volumio-pb volumio[909]: ---------------------------- MPD announces state update: player Dec 10 11:28:59 volumio-pb volumio[909]: info: sendMpdCommand stop took 14 milliseconds Dec 10 11:28:59 volumio-pb volumio[909]: info: ControllerMpd::getState Dec 10 11:28:59 volumio-pb volumio[909]: verbose: ControllerMpd::sendMpdCommand status Dec 10 11:28:59 volumio-pb volumio[909]: info: sendMpdCommand stop took 2 milliseconds Dec 10 11:28:59 volumio-pb volumio[909]: verbose: ControllerMpd::sendMpdCommand clear Dec 10 11:28:59 volumio-pb volumio[909]: info: Dec 10 11:28:59 volumio-pb volumio[909]: ---------------------------- MPD announces state update: player Dec 10 11:28:59 volumio-pb volumio[909]: info: ControllerMpd::getState Dec 10 11:28:59 volumio-pb volumio[909]: verbose: ControllerMpd::sendMpdCommand status Dec 10 11:28:59 volumio-pb volumio[909]: info: Dec 10 11:28:59 volumio-pb volumio[909]: ---------------------------- MPD announces state update: player Dec 10 11:28:59 volumio-pb volumio[909]: info: ControllerMpd::getState Dec 10 11:28:59 volumio-pb volumio[909]: verbose: ControllerMpd::sendMpdCommand status Dec 10 11:28:59 volumio-pb volumio[909]: info: Dec 10 11:28:59 volumio-pb volumio[909]: ---------------------------- MPD announces system playlist update Dec 10 11:28:59 volumio-pb volumio[909]: info: Ignoring MPD Status Update Dec 10 11:28:59 volumio-pb volumio[909]: info: sendMpdCommand status took 4 milliseconds Dec 10 11:28:59 volumio-pb volumio[909]: info: sendMpdCommand clear took 3 milliseconds Dec 10 11:28:59 volumio-pb volumio[909]: info: sendMpdCommand status took 3 milliseconds Dec 10 11:28:59 volumio-pb volumio[909]: info: sendMpdCommand status took 2 milliseconds Dec 10 11:28:59 volumio-pb volumio[909]: verbose: ControllerMpd::parseState Dec 10 11:28:59 volumio-pb volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 11:28:59 volumio-pb volumio[909]: verbose: ControllerMpd::sendMpdCommand load "https://uk2.streamingpulse.com/ssl/vcr1" Dec 10 11:28:59 volumio-pb volumio[909]: verbose: ControllerMpd::parseState Dec 10 11:28:59 volumio-pb volumio[909]: verbose: ControllerMpd::parseState Dec 10 11:28:59 volumio-pb volumio[909]: info: ControllerMpd::pushState Dec 10 11:28:59 volumio-pb volumio[909]: info: CoreCommandRouter::servicePushState Dec 10 11:28:59 volumio-pb volumio[909]: info: CoreStateMachine::pushState Dec 10 11:28:59 volumio-pb volumio[909]: info: CorePlayQueue::getTrack 0 Dec 10 11:28:59 volumio-pb volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 11:28:59 volumio-pb volumio[909]: info: CoreCommandRouter::volumioPushState Dec 10 11:28:59 volumio-pb volumio[909]: info: CorePlayQueue::getTrack 0 Dec 10 11:28:59 volumio-pb volumio[909]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 10 11:28:59 volumio-pb volumio[909]: info: ControllerMpd::pushState Dec 10 11:28:59 volumio-pb volumio[909]: info: CoreCommandRouter::servicePushState Dec 10 11:28:59 volumio-pb volumio[909]: info: CoreStateMachine::pushState Dec 10 11:28:59 volumio-pb volumio[909]: info: CorePlayQueue::getTrack 0 Dec 10 11:28:59 volumio-pb volumio[909]: info: CoreCommandRouter::volumioPushState Dec 10 11:28:59 volumio-pb volumio[909]: info: CorePlayQueue::getTrack 0 Dec 10 11:28:59 volumio-pb volumio[909]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 10 11:28:59 volumio-pb volumio[909]: info: ------------------------------ 16ms Dec 10 11:28:59 volumio-pb volumio[909]: info: ------------------------------ 14ms Dec 10 11:28:59 volumio-pb volumio[909]: info: Dec 10 11:28:59 volumio-pb volumio[909]: ---------------------------- MPD announces system playlist update Dec 10 11:28:59 volumio-pb volumio[909]: info: Ignoring MPD Status Update Dec 10 11:28:59 volumio-pb volumio[909]: info: Dec 10 11:28:59 volumio-pb volumio[909]: ---------------------------- MPD announces system playlist update Dec 10 11:28:59 volumio-pb volumio[909]: info: Ignoring MPD Status Update Dec 10 11:28:59 volumio-pb volumio[909]: error: updateQueue error: null Dec 10 11:28:59 volumio-pb volumio[909]: info: ------------------------------ 16ms Dec 10 11:28:59 volumio-pb volumio[909]: info: sendMpdCommand playlistinfo took 15 milliseconds Dec 10 11:28:59 volumio-pb volumio[909]: verbose: ControllerMpd::parseTrackInfo Dec 10 11:28:59 volumio-pb volumio[909]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Dec 10 11:28:59 volumio-pb volumio[909]: info: ------------------------------ 21ms Dec 10 11:29:00 volumio-pb volumio[909]: error: updateQueue error: null Dec 10 11:29:00 volumio-pb volumio[909]: error: updateQueue error: null Dec 10 11:29:00 volumio-pb volumio[909]: verbose: ControllerMpd::sendMpdCommand add "https://uk2.streamingpulse.com/ssl/vcr1" Dec 10 11:29:00 volumio-pb volumio[909]: info: ------------------------------ 949ms Dec 10 11:29:00 volumio-pb volumio[909]: info: ------------------------------ 949ms Dec 10 11:29:00 volumio-pb volumio[909]: info: Dec 10 11:29:00 volumio-pb volumio[909]: ---------------------------- MPD announces system playlist update Dec 10 11:29:00 volumio-pb volumio[909]: info: Ignoring MPD Status Update Dec 10 11:29:00 volumio-pb volumio[909]: info: sendMpdCommand add "https://uk2.streamingpulse.com/ssl/vcr1" took 2 milliseconds Dec 10 11:29:00 volumio-pb volumio[909]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 10 11:29:00 volumio-pb volumio[909]: verbose: ControllerMpd::sendMpdCommand play Dec 10 11:29:00 volumio-pb volumio[909]: info: Dec 10 11:29:00 volumio-pb volumio[909]: ---------------------------- MPD announces system playlist update Dec 10 11:29:00 volumio-pb volumio[909]: info: Ignoring MPD Status Update Dec 10 11:29:00 volumio-pb volumio[909]: info: Dec 10 11:29:00 volumio-pb volumio[909]: ---------------------------- MPD announces system playlist update Dec 10 11:29:00 volumio-pb volumio[909]: info: Ignoring MPD Status Update Dec 10 11:29:00 volumio-pb volumio[909]: info: ------------------------------ 4ms Dec 10 11:29:00 volumio-pb volumio[909]: info: sendMpdCommand play took 4 milliseconds Dec 10 11:29:00 volumio-pb volumio[909]: info: ------------------------------ 3ms Dec 10 11:29:00 volumio-pb volumio[909]: info: ------------------------------ 2ms Dec 10 11:29:00 volumio-pb volumio[909]: info: Dec 10 11:29:00 volumio-pb volumio[909]: ---------------------------- MPD announces state update: player Dec 10 11:29:00 volumio-pb volumio[909]: info: ControllerMpd::getState Dec 10 11:29:00 volumio-pb volumio[909]: verbose: ControllerMpd::sendMpdCommand status Dec 10 11:29:00 volumio-pb volumio[909]: info: Dec 10 11:29:00 volumio-pb volumio[909]: ---------------------------- MPD announces state update: player Dec 10 11:29:00 volumio-pb volumio[909]: info: ControllerMpd::getState Dec 10 11:29:00 volumio-pb volumio[909]: verbose: ControllerMpd::sendMpdCommand status Dec 10 11:29:00 volumio-pb volumio[909]: info: Dec 10 11:29:00 volumio-pb volumio[909]: ---------------------------- MPD announces state update: player Dec 10 11:29:00 volumio-pb volumio[909]: info: ControllerMpd::getState Dec 10 11:29:00 volumio-pb volumio[909]: verbose: ControllerMpd::sendMpdCommand status Dec 10 11:29:00 volumio-pb volumio[909]: info: Dec 10 11:29:00 volumio-pb volumio[909]: ---------------------------- MPD announces state update: player Dec 10 11:29:00 volumio-pb volumio[909]: info: sendMpdCommand status took 4 milliseconds Dec 10 11:29:00 volumio-pb volumio[909]: info: ControllerMpd::getState Dec 10 11:29:00 volumio-pb volumio[909]: verbose: ControllerMpd::sendMpdCommand status Dec 10 11:29:00 volumio-pb volumio[909]: info: sendMpdCommand status took 4 milliseconds Dec 10 11:29:00 volumio-pb volumio[909]: info: sendMpdCommand status took 3 milliseconds Dec 10 11:29:00 volumio-pb volumio[909]: verbose: ControllerMpd::parseState Dec 10 11:29:00 volumio-pb volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 11:29:00 volumio-pb volumio[909]: verbose: ControllerMpd::parseState Dec 10 11:29:00 volumio-pb volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 11:29:00 volumio-pb volumio[909]: verbose: ControllerMpd::parseState Dec 10 11:29:00 volumio-pb volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 11:29:00 volumio-pb volumio[909]: info: Dec 10 11:29:00 volumio-pb volumio[909]: ---------------------------- MPD announces state update: player Dec 10 11:29:00 volumio-pb volumio[909]: info: ControllerMpd::getState Dec 10 11:29:00 volumio-pb volumio[909]: verbose: ControllerMpd::sendMpdCommand status Dec 10 11:29:00 volumio-pb volumio[909]: info: Dec 10 11:29:00 volumio-pb volumio[909]: ---------------------------- MPD announces state update: player Dec 10 11:29:00 volumio-pb volumio[909]: info: ControllerMpd::getState Dec 10 11:29:00 volumio-pb volumio[909]: verbose: ControllerMpd::sendMpdCommand status Dec 10 11:29:00 volumio-pb volumio[909]: info: sendMpdCommand status took 3 milliseconds Dec 10 11:29:00 volumio-pb volumio[909]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 10 11:29:00 volumio-pb volumio[909]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 10 11:29:00 volumio-pb volumio[909]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 10 11:29:00 volumio-pb volumio[909]: info: sendMpdCommand status took 2 milliseconds Dec 10 11:29:00 volumio-pb volumio[909]: info: sendMpdCommand status took 1 milliseconds Dec 10 11:29:00 volumio-pb volumio[909]: verbose: ControllerMpd::parseState Dec 10 11:29:00 volumio-pb volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 11:29:00 volumio-pb volumio[909]: verbose: ControllerMpd::parseTrackInfo Dec 10 11:29:00 volumio-pb volumio[909]: verbose: ControllerMpd::parseTrackInfo Dec 10 11:29:00 volumio-pb volumio[909]: verbose: ControllerMpd::parseTrackInfo Dec 10 11:29:00 volumio-pb volumio[909]: verbose: ControllerMpd::parseState Dec 10 11:29:00 volumio-pb volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 11:29:00 volumio-pb volumio[909]: verbose: ControllerMpd::parseState Dec 10 11:29:00 volumio-pb volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 11:29:00 volumio-pb volumio[909]: info: ControllerMpd::pushState Dec 10 11:29:00 volumio-pb volumio[909]: info: CoreCommandRouter::servicePushState Dec 10 11:29:00 volumio-pb volumio[909]: info: CorePlayQueue::getTrack 0 Dec 10 11:29:00 volumio-pb volumio[909]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"vcr1","artist":null,"album":null,"uri":"https://uk2.streamingpulse.com/ssl/vcr1","trackType":""} Dec 10 11:29:00 volumio-pb volumio[909]: verbose: CURRENT POSITION 0 Dec 10 11:29:00 volumio-pb volumio[909]: info: CoreStateMachine::syncState stateService play Dec 10 11:29:00 volumio-pb volumio[909]: info: CoreStateMachine::syncState currentStatus stop Dec 10 11:29:00 volumio-pb volumio[909]: info: ControllerMpd::pushState Dec 10 11:29:00 volumio-pb volumio[909]: info: CoreCommandRouter::servicePushState Dec 10 11:29:00 volumio-pb volumio[909]: info: CorePlayQueue::getTrack 0 Dec 10 11:29:00 volumio-pb volumio[909]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"vcr1","artist":null,"album":null,"uri":"https://uk2.streamingpulse.com/ssl/vcr1","trackType":""} Dec 10 11:29:00 volumio-pb volumio[909]: verbose: CURRENT POSITION 0 Dec 10 11:29:00 volumio-pb volumio[909]: info: CoreStateMachine::syncState stateService play Dec 10 11:29:00 volumio-pb volumio[909]: info: CoreStateMachine::syncState currentStatus play Dec 10 11:29:00 volumio-pb volumio[909]: info: Received an update from plugin. extracting info from payload Dec 10 11:29:00 volumio-pb volumio[909]: info: CoreStateMachine::pushState Dec 10 11:29:00 volumio-pb volumio[909]: info: CorePlayQueue::getTrack 0 Dec 10 11:29:00 volumio-pb volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 11:29:00 volumio-pb volumio[909]: info: CoreCommandRouter::volumioPushState Dec 10 11:29:00 volumio-pb volumio[909]: info: CoreStateMachine::pushState Dec 10 11:29:00 volumio-pb volumio[909]: info: CoreCommandRouter::volumioPushState Dec 10 11:29:00 volumio-pb volumio[909]: info: ControllerMpd::pushState Dec 10 11:29:00 volumio-pb volumio[909]: info: CoreCommandRouter::servicePushState Dec 10 11:29:00 volumio-pb volumio[909]: info: CorePlayQueue::getTrack 0 Dec 10 11:29:00 volumio-pb volumio[909]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"vcr1","artist":null,"album":null,"uri":"https://uk2.streamingpulse.com/ssl/vcr1","trackType":""} Dec 10 11:29:00 volumio-pb volumio[909]: verbose: CURRENT POSITION 0 Dec 10 11:29:00 volumio-pb volumio[909]: info: CoreStateMachine::syncState stateService play Dec 10 11:29:00 volumio-pb volumio[909]: info: CoreStateMachine::syncState currentStatus play Dec 10 11:29:00 volumio-pb volumio[909]: info: Received an update from plugin. extracting info from payload Dec 10 11:29:00 volumio-pb volumio[909]: info: CoreStateMachine::pushState Dec 10 11:29:00 volumio-pb volumio[909]: info: CorePlayQueue::getTrack 0 Dec 10 11:29:00 volumio-pb volumio[909]: info: CoreCommandRouter::volumioPushState Dec 10 11:29:00 volumio-pb volumio[909]: info: CoreStateMachine::pushState Dec 10 11:29:00 volumio-pb volumio[909]: info: CoreCommandRouter::volumioPushState Dec 10 11:29:00 volumio-pb volumio[909]: info: ------------------------------ 19ms Dec 10 11:29:00 volumio-pb volumio[909]: info: ------------------------------ 30ms Dec 10 11:29:00 volumio-pb volumio[909]: info: ------------------------------ 31ms Dec 10 11:29:00 volumio-pb volumio[909]: info: sendMpdCommand playlistinfo took 23 milliseconds Dec 10 11:29:00 volumio-pb volumio[909]: info: sendMpdCommand playlistinfo took 22 milliseconds Dec 10 11:29:00 volumio-pb volumio[909]: verbose: ControllerMpd::parseTrackInfo Dec 10 11:29:00 volumio-pb volumio[909]: info: ControllerMpd::pushState Dec 10 11:29:00 volumio-pb volumio[909]: info: CoreCommandRouter::servicePushState Dec 10 11:29:00 volumio-pb volumio[909]: info: CorePlayQueue::getTrack 0 Dec 10 11:29:00 volumio-pb volumio[909]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"vcr1","artist":null,"album":null,"uri":"https://uk2.streamingpulse.com/ssl/vcr1","trackType":""} Dec 10 11:29:00 volumio-pb volumio[909]: verbose: CURRENT POSITION 0 Dec 10 11:29:00 volumio-pb volumio[909]: info: CoreStateMachine::syncState stateService play Dec 10 11:29:00 volumio-pb volumio[909]: info: CoreStateMachine::syncState currentStatus play Dec 10 11:29:00 volumio-pb volumio[909]: info: Received an update from plugin. extracting info from payload Dec 10 11:29:00 volumio-pb volumio[909]: info: CoreStateMachine::pushState Dec 10 11:29:00 volumio-pb volumio[909]: info: CorePlayQueue::getTrack 0 Dec 10 11:29:00 volumio-pb volumio[909]: info: CoreCommandRouter::volumioPushState Dec 10 11:29:00 volumio-pb volumio[909]: info: CoreStateMachine::pushState Dec 10 11:29:00 volumio-pb volumio[909]: info: CoreCommandRouter::volumioPushState Dec 10 11:29:00 volumio-pb volumio[909]: info: ControllerMpd::pushState Dec 10 11:29:00 volumio-pb volumio[909]: info: CoreCommandRouter::servicePushState Dec 10 11:29:00 volumio-pb volumio[909]: info: CorePlayQueue::getTrack 0 Dec 10 11:29:00 volumio-pb volumio[909]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"vcr1","artist":null,"album":null,"uri":"https://uk2.streamingpulse.com/ssl/vcr1","trackType":""} Dec 10 11:29:00 volumio-pb volumio[909]: verbose: CURRENT POSITION 0 Dec 10 11:29:00 volumio-pb volumio[909]: info: CoreStateMachine::syncState stateService play Dec 10 11:29:00 volumio-pb volumio[909]: info: CoreStateMachine::syncState currentStatus play Dec 10 11:29:00 volumio-pb volumio[909]: info: Received an update from plugin. extracting info from payload Dec 10 11:29:00 volumio-pb volumio[909]: info: CoreStateMachine::pushState Dec 10 11:29:00 volumio-pb volumio[909]: info: CorePlayQueue::getTrack 0 Dec 10 11:29:00 volumio-pb volumio[909]: info: CoreCommandRouter::volumioPushState Dec 10 11:29:00 volumio-pb volumio[909]: info: CoreStateMachine::pushState Dec 10 11:29:00 volumio-pb volumio[909]: info: CoreCommandRouter::volumioPushState Dec 10 11:29:00 volumio-pb volumio[909]: info: ControllerMpd::pushState Dec 10 11:29:00 volumio-pb volumio[909]: info: CoreCommandRouter::servicePushState Dec 10 11:29:00 volumio-pb volumio[909]: info: CorePlayQueue::getTrack 0 Dec 10 11:29:00 volumio-pb volumio[909]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"vcr1","artist":null,"album":null,"uri":"https://uk2.streamingpulse.com/ssl/vcr1","trackType":""} Dec 10 11:29:00 volumio-pb volumio[909]: verbose: CURRENT POSITION 0 Dec 10 11:29:00 volumio-pb volumio[909]: info: CoreStateMachine::syncState stateService play Dec 10 11:29:00 volumio-pb volumio[909]: info: CoreStateMachine::syncState currentStatus play Dec 10 11:29:00 volumio-pb volumio[909]: info: Received an update from plugin. extracting info from payload Dec 10 11:29:00 volumio-pb volumio[909]: info: CoreStateMachine::pushState Dec 10 11:29:00 volumio-pb volumio[909]: info: CorePlayQueue::getTrack 0 Dec 10 11:29:00 volumio-pb volumio[909]: info: CoreCommandRouter::volumioPushState Dec 10 11:29:00 volumio-pb volumio[909]: info: CoreStateMachine::pushState Dec 10 11:29:00 volumio-pb volumio[909]: info: CoreCommandRouter::volumioPushState Dec 10 11:29:00 volumio-pb volumio[909]: info: ------------------------------ 58ms Dec 10 11:29:00 volumio-pb volumio[909]: info: ------------------------------ 55ms Dec 10 11:29:00 volumio-pb volumio[909]: info: ------------------------------ 56ms Dec 10 11:29:00 volumio-pb volumio[909]: info: Dec 10 11:29:00 volumio-pb volumio[909]: ---------------------------- MPD announces system playlist update Dec 10 11:29:00 volumio-pb volumio[909]: info: Ignoring MPD Status Update Dec 10 11:29:00 volumio-pb volumio[909]: info: Dec 10 11:29:00 volumio-pb volumio[909]: ---------------------------- MPD announces state update: player Dec 10 11:29:00 volumio-pb volumio[909]: info: ControllerMpd::getState Dec 10 11:29:00 volumio-pb volumio[909]: verbose: ControllerMpd::sendMpdCommand status Dec 10 11:29:01 volumio-pb volumio[909]: info: Dec 10 11:29:01 volumio-pb volumio[909]: ---------------------------- MPD announces system playlist update Dec 10 11:29:01 volumio-pb volumio[909]: info: Ignoring MPD Status Update Dec 10 11:29:01 volumio-pb volumio[909]: info: Dec 10 11:29:01 volumio-pb volumio[909]: ---------------------------- MPD announces state update: player Dec 10 11:29:01 volumio-pb volumio[909]: info: ControllerMpd::getState Dec 10 11:29:01 volumio-pb volumio[909]: verbose: ControllerMpd::sendMpdCommand status Dec 10 11:29:01 volumio-pb volumio[909]: info: Dec 10 11:29:01 volumio-pb volumio[909]: ---------------------------- MPD announces system playlist update Dec 10 11:29:01 volumio-pb volumio[909]: info: Ignoring MPD Status Update Dec 10 11:29:01 volumio-pb volumio[909]: info: Dec 10 11:29:01 volumio-pb volumio[909]: ---------------------------- MPD announces state update: player Dec 10 11:29:01 volumio-pb volumio[909]: info: ControllerMpd::getState Dec 10 11:29:01 volumio-pb volumio[909]: verbose: ControllerMpd::sendMpdCommand status Dec 10 11:29:01 volumio-pb volumio[909]: info: Dec 10 11:29:01 volumio-pb volumio[909]: ---------------------------- MPD announces system playlist update Dec 10 11:29:01 volumio-pb volumio[909]: info: Ignoring MPD Status Update Dec 10 11:29:01 volumio-pb volumio[909]: info: Dec 10 11:29:01 volumio-pb volumio[909]: ---------------------------- MPD announces state update: player Dec 10 11:29:01 volumio-pb volumio[909]: info: ControllerMpd::getState Dec 10 11:29:01 volumio-pb volumio[909]: verbose: ControllerMpd::sendMpdCommand status Dec 10 11:29:01 volumio-pb volumio[909]: info: ------------------------------ 5ms Dec 10 11:29:01 volumio-pb volumio[909]: info: sendMpdCommand status took 4 milliseconds Dec 10 11:29:01 volumio-pb volumio[909]: info: ------------------------------ 4ms Dec 10 11:29:01 volumio-pb volumio[909]: info: sendMpdCommand status took 4 milliseconds Dec 10 11:29:01 volumio-pb volumio[909]: info: ------------------------------ 3ms Dec 10 11:29:01 volumio-pb volumio[909]: info: sendMpdCommand status took 2 milliseconds Dec 10 11:29:01 volumio-pb volumio[909]: verbose: ControllerMpd::parseState Dec 10 11:29:01 volumio-pb volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 11:29:01 volumio-pb volumio[909]: verbose: ControllerMpd::parseState Dec 10 11:29:01 volumio-pb volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 11:29:01 volumio-pb volumio[909]: verbose: ControllerMpd::parseState Dec 10 11:29:01 volumio-pb volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 11:29:01 volumio-pb volumio[909]: info: Dec 10 11:29:01 volumio-pb volumio[909]: ---------------------------- MPD announces system playlist update Dec 10 11:29:01 volumio-pb volumio[909]: info: Ignoring MPD Status Update Dec 10 11:29:01 volumio-pb volumio[909]: info: Dec 10 11:29:01 volumio-pb volumio[909]: ---------------------------- MPD announces state update: player Dec 10 11:29:01 volumio-pb volumio[909]: info: ControllerMpd::getState Dec 10 11:29:01 volumio-pb volumio[909]: verbose: ControllerMpd::sendMpdCommand status Dec 10 11:29:01 volumio-pb volumio[909]: info: Dec 10 11:29:01 volumio-pb volumio[909]: ---------------------------- MPD announces system playlist update Dec 10 11:29:01 volumio-pb volumio[909]: info: Ignoring MPD Status Update Dec 10 11:29:01 volumio-pb volumio[909]: info: Dec 10 11:29:01 volumio-pb volumio[909]: ---------------------------- MPD announces state update: player Dec 10 11:29:01 volumio-pb volumio[909]: info: ControllerMpd::getState Dec 10 11:29:01 volumio-pb volumio[909]: verbose: ControllerMpd::sendMpdCommand status Dec 10 11:29:01 volumio-pb volumio[909]: info: ------------------------------ 6ms Dec 10 11:29:01 volumio-pb volumio[909]: info: sendMpdCommand status took 5 milliseconds Dec 10 11:29:01 volumio-pb volumio[909]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 10 11:29:01 volumio-pb volumio[909]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 10 11:29:01 volumio-pb volumio[909]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 10 11:29:01 volumio-pb volumio[909]: info: ------------------------------ 4ms Dec 10 11:29:01 volumio-pb volumio[909]: info: sendMpdCommand status took 3 milliseconds Dec 10 11:29:01 volumio-pb volumio[909]: info: ------------------------------ 2ms Dec 10 11:29:01 volumio-pb volumio[909]: info: sendMpdCommand status took 2 milliseconds Dec 10 11:29:01 volumio-pb volumio[909]: verbose: ControllerMpd::parseState Dec 10 11:29:01 volumio-pb volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 11:29:01 volumio-pb volumio[909]: verbose: ControllerMpd::parseTrackInfo Dec 10 11:29:01 volumio-pb volumio[909]: verbose: ControllerMpd::parseTrackInfo Dec 10 11:29:01 volumio-pb volumio[909]: verbose: ControllerMpd::parseTrackInfo Dec 10 11:29:01 volumio-pb volumio[909]: verbose: ControllerMpd::parseState Dec 10 11:29:01 volumio-pb volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 11:29:01 volumio-pb volumio[909]: verbose: ControllerMpd::parseState Dec 10 11:29:01 volumio-pb volumio[909]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 10 11:29:01 volumio-pb volumio[909]: info: ControllerMpd::pushState Dec 10 11:29:01 volumio-pb volumio[909]: info: CoreCommandRouter::servicePushState Dec 10 11:29:01 volumio-pb volumio[909]: info: CorePlayQueue::getTrack 0 Dec 10 11:29:01 volumio-pb volumio[909]: verbose: STATE SERVICE {"status":"play","position":0,"seek":878,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Giuseppe Tartini (1692-1770) - Concerto per violino in mi Maggiore D51 (Carlo Lazari, violino - L'Arte dell'Arco (con strumenti originali)) {+info: veniceclassicradio.eu}","artist":"VCR Auditorium | Venice Classic Radio Italia","album":null,"uri":"https://uk2.streamingpulse.com/ssl/vcr1","trackType":""} Dec 10 11:29:01 volumio-pb volumio[909]: verbose: CURRENT POSITION 0 Dec 10 11:29:01 volumio-pb volumio[909]: info: CoreStateMachine::syncState stateService play Dec 10 11:29:01 volumio-pb volumio[909]: info: CoreStateMachine::syncState currentStatus play Dec 10 11:29:01 volumio-pb volumio[909]: info: Received an update from plugin. extracting info from payload Dec 10 11:29:01 volumio-pb volumio[909]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 10 11:29:01 volumio-pb volumio[909]: info: CoreStateMachine::pushState Dec 10 11:29:01 volumio-pb volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 10 11:29:01 volumio-pb volumio[909]: info: CoreCommandRouter::volumioPushState Dec 10 11:29:01 volumio-pb volumio[909]: info: CoreStateMachine::pushState Dec 10 11:29:01 volumio-pb volumio[909]: info: CoreCommandRouter::volumioPushState Dec 10 11:29:01 volumio-pb volumio[909]: info: ControllerMpd::pushState Dec 10 11:29:01 volumio-pb volumio[909]: info: CoreCommandRouter::servicePushState Dec 10 11:29:01 volumio-pb volumio[909]: info: CorePlayQueue::getTrack 0 Dec 10 11:29:01 volumio-pb volumio[909]: verbose: STATE SERVICE {"status":"play","position":0,"seek":878,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Giuseppe Tartini (1692-1770) - Concerto per violino in mi Maggiore D51 (Carlo Lazari, violino - L'Arte dell'Arco (con strumenti originali)) {+info: veniceclassicradio.eu}","artist":"VCR Auditorium | Venice Classic Radio Italia","album":null,"uri":"https://uk2.streamingpulse.com/ssl/vcr1","trackType":""} Dec 10 11:29:01 volumio-pb volumio[909]: verbose: CURRENT POSITION 0 Dec 10 11:29:01 volumio-pb volumio[909]: info: CoreStateMachine::syncState stateService play Dec 10 11:29:01 volumio-pb volumio[909]: info: CoreStateMachine::syncState currentStatus play Dec 10 11:29:01 volumio-pb volumio[909]: info: Received an update from plugin. extracting info from payload Dec 10 11:29:01 volumio-pb volumio[909]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 10 11:29:01 volumio-pb volumio[909]: info: CoreStateMachine::pushState Dec 10 11:29:01 volumio-pb volumio[909]: info: CoreCommandRouter::volumioPushState Dec 10 11:29:01 volumio-pb volumio[909]: info: CoreStateMachine::pushState Dec 10 11:29:01 volumio-pb volumio[909]: info: CoreCommandRouter::volumioPushState Dec 10 11:29:01 volumio-pb volumio[909]: info: ControllerMpd::pushState Dec 10 11:29:01 volumio-pb volumio[909]: info: CoreCommandRouter::servicePushState Dec 10 11:29:01 volumio-pb volumio[909]: info: CorePlayQueue::getTrack 0 Dec 10 11:29:01 volumio-pb volumio[909]: verbose: STATE SERVICE {"status":"play","position":0,"seek":878,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Giuseppe Tartini (1692-1770) - Concerto per violino in mi Maggiore D51 (Carlo Lazari, violino - L'Arte dell'Arco (con strumenti originali)) {+info: veniceclassicradio.eu}","artist":"VCR Auditorium | Venice Classic Radio Italia","album":null,"uri":"https://uk2.streamingpulse.com/ssl/vcr1","trackType":""} Dec 10 11:29:01 volumio-pb volumio[909]: verbose: CURRENT POSITION 0 Dec 10 11:29:01 volumio-pb volumio[909]: info: CoreStateMachine::syncState stateService play Dec 10 11:29:01 volumio-pb volumio[909]: info: CoreStateMachine::syncState currentStatus play Dec 10 11:29:01 volumio-pb volumio[909]: info: Received an update from plugin. extracting info from payload Dec 10 11:29:01 volumio-pb volumio[909]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 10 11:29:01 volumio-pb volumio[909]: info: CoreStateMachine::pushState Dec 10 11:29:01 volumio-pb volumio[909]: info: CoreCommandRouter::volumioPushState Dec 10 11:29:01 volumio-pb volumio[909]: info: CoreStateMachine::pushState Dec 10 11:29:01 volumio-pb volumio[909]: info: CoreCommandRouter::volumioPushState Dec 10 11:29:01 volumio-pb volumio[909]: info: ------------------------------ 40ms Dec 10 11:29:01 volumio-pb volumio[909]: info: ------------------------------ 39ms Dec 10 11:29:01 volumio-pb volumio[909]: info: ------------------------------ 39ms Dec 10 11:29:01 volumio-pb volumio[909]: info: sendMpdCommand playlistinfo took 31 milliseconds Dec 10 11:29:01 volumio-pb volumio[909]: info: sendMpdCommand playlistinfo took 30 milliseconds Dec 10 11:29:01 volumio-pb volumio[909]: info: sendMpdCommand playlistinfo took 30 milliseconds Dec 10 11:29:01 volumio-pb volumio[909]: verbose: ControllerMpd::parseTrackInfo Dec 10 11:29:01 volumio-pb volumio[909]: verbose: ControllerMpd::parseTrackInfo Dec 10 11:29:01 volumio-pb volumio[909]: verbose: ControllerMpd::parseTrackInfo Dec 10 11:29:01 volumio-pb volumio[909]: info: ControllerMpd::pushState Dec 10 11:29:01 volumio-pb volumio[909]: info: CoreCommandRouter::servicePushState Dec 10 11:29:01 volumio-pb volumio[909]: info: CorePlayQueue::getTrack 0 Dec 10 11:29:01 volumio-pb volumio[909]: verbose: STATE SERVICE {"status":"play","position":0,"seek":878,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Giuseppe Tartini (1692-1770) - Concerto per violino in mi Maggiore D51 (Carlo Lazari, violino - L'Arte dell'Arco (con strumenti originali)) {+info: veniceclassicradio.eu}","artist":"VCR Auditorium | Venice Classic Radio Italia","album":null,"uri":"https://uk2.streamingpulse.com/ssl/vcr1","trackType":""} Dec 10 11:29:01 volumio-pb volumio[909]: verbose: CURRENT POSITION 0 Dec 10 11:29:01 volumio-pb volumio[909]: info: CoreStateMachine::syncState stateService play Dec 10 11:29:01 volumio-pb volumio[909]: info: CoreStateMachine::syncState currentStatus play Dec 10 11:29:01 volumio-pb volumio[909]: info: Received an update from plugin. extracting info from payload Dec 10 11:29:01 volumio-pb volumio[909]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 10 11:29:01 volumio-pb volumio[909]: info: CoreStateMachine::pushState Dec 10 11:29:01 volumio-pb volumio[909]: info: CoreCommandRouter::volumioPushState Dec 10 11:29:01 volumio-pb volumio[909]: info: CoreStateMachine::pushState Dec 10 11:29:01 volumio-pb volumio[909]: info: CoreCommandRouter::volumioPushState Dec 10 11:29:01 volumio-pb volumio[909]: info: ControllerMpd::pushState Dec 10 11:29:01 volumio-pb volumio[909]: info: CoreCommandRouter::servicePushState Dec 10 11:29:01 volumio-pb volumio[909]: info: CorePlayQueue::getTrack 0 Dec 10 11:29:01 volumio-pb volumio[909]: verbose: STATE SERVICE {"status":"play","position":0,"seek":878,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Giuseppe Tartini (1692-1770) - Concerto per violino in mi Maggiore D51 (Carlo Lazari, violino - L'Arte dell'Arco (con strumenti originali)) {+info: veniceclassicradio.eu}","artist":"VCR Auditorium | Venice Classic Radio Italia","album":null,"uri":"https://uk2.streamingpulse.com/ssl/vcr1","trackType":""} Dec 10 11:29:01 volumio-pb volumio[909]: verbose: CURRENT POSITION 0 Dec 10 11:29:01 volumio-pb volumio[909]: info: CoreStateMachine::syncState stateService play Dec 10 11:29:01 volumio-pb volumio[909]: info: CoreStateMachine::syncState currentStatus play Dec 10 11:29:01 volumio-pb volumio[909]: info: Received an update from plugin. extracting info from payload Dec 10 11:29:01 volumio-pb volumio[909]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 10 11:29:01 volumio-pb volumio[909]: info: CoreStateMachine::pushState Dec 10 11:29:01 volumio-pb volumio[909]: info: CoreCommandRouter::volumioPushState Dec 10 11:29:01 volumio-pb volumio[909]: info: CoreStateMachine::pushState Dec 10 11:29:01 volumio-pb volumio[909]: info: CoreCommandRouter::volumioPushState Dec 10 11:29:01 volumio-pb volumio[909]: info: ControllerMpd::pushState Dec 10 11:29:01 volumio-pb volumio[909]: info: CoreCommandRouter::servicePushState Dec 10 11:29:01 volumio-pb volumio[909]: info: CorePlayQueue::getTrack 0 Dec 10 11:29:01 volumio-pb volumio[909]: verbose: STATE SERVICE {"status":"play","position":0,"seek":878,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Giuseppe Tartini (1692-1770) - Concerto per violino in mi Maggiore D51 (Carlo Lazari, violino - L'Arte dell'Arco (con strumenti originali)) {+info: veniceclassicradio.eu}","artist":"VCR Auditorium | Venice Classic Radio Italia","album":null,"uri":"https://uk2.streamingpulse.com/ssl/vcr1","trackType":""} Dec 10 11:29:01 volumio-pb volumio[909]: verbose: CURRENT POSITION 0 Dec 10 11:29:01 volumio-pb volumio[909]: info: CoreStateMachine::syncState stateService play Dec 10 11:29:01 volumio-pb volumio[909]: info: CoreStateMachine::syncState currentStatus play Dec 10 11:29:01 volumio-pb volumio[909]: info: Received an update from plugin. extracting info from payload Dec 10 11:29:01 volumio-pb volumio[909]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Dec 10 11:29:01 volumio-pb volumio[909]: info: CoreStateMachine::pushState Dec 10 11:29:01 volumio-pb volumio[909]: info: CoreCommandRouter::volumioPushState Dec 10 11:29:01 volumio-pb volumio[909]: info: CoreStateMachine::pushState Dec 10 11:29:01 volumio-pb volumio[909]: info: CoreCommandRouter::volumioPushState Dec 10 11:29:01 volumio-pb volumio[909]: info: ------------------------------ 67ms Dec 10 11:29:01 volumio-pb volumio[909]: info: ------------------------------ 63ms Dec 10 11:29:01 volumio-pb volumio[909]: info: ------------------------------ 63ms Dec 10 11:29:01 volumio-pb volumio[909]: info: CoreCommandRouter::volumioGetState Dec 10 11:29:01 volumio-pb volumio[909]: info: CoreCommandRouter::volumioGetQueue Dec 10 11:29:01 volumio-pb volumio[909]: info: CoreStateMachine::getQueue Dec 10 11:29:01 volumio-pb volumio[909]: info: CorePlayQueue::getQueue Dec 10 11:29:01 volumio-pb volumio[909]: info: MYVOLUMIO: Adding device Dec 10 11:29:01 volumio-pb volumio[909]: info: MYVOLUMIO: Evaluating Server Dec 10 11:29:02 volumio-pb volumio[909]: info: Setting Geolocation for MyVolumio to eu4 Dec 10 11:29:02 volumio-pb volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 11:29:02 volumio-pb volumio[909]: info: CoreCommandRouter::volumioGetState Dec 10 11:29:02 volumio-pb volumio[909]: info: CoreCommandRouter::volumioGetQueue Dec 10 11:29:02 volumio-pb volumio[909]: info: CoreStateMachine::getQueue Dec 10 11:29:02 volumio-pb volumio[909]: info: CorePlayQueue::getQueue Dec 10 11:29:02 volumio-pb volumio[909]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Dec 10 11:29:03 volumio-pb volumio[909]: info: Updating MyVolumio device info Dec 10 11:29:03 volumio-pb volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 10 11:29:03 volumio-pb volumio[909]: info: CoreCommandRouter::volumioGetState Dec 10 11:29:03 volumio-pb volumio[909]: info: CoreCommandRouter::volumioGetQueue Dec 10 11:29:03 volumio-pb volumio[909]: info: CoreStateMachine::getQueue Dec 10 11:29:03 volumio-pb volumio[909]: info: CorePlayQueue::getQueue Dec 10 11:29:03 volumio-pb volumio[909]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Dec 10 11:29:04 volumio-pb volumio[909]: info: CoreCommandRouter::volumioGetState Dec 10 11:29:04 volumio-pb volumio[909]: info: CoreCommandRouter::volumioGetQueue Dec 10 11:29:04 volumio-pb volumio[909]: info: CoreStateMachine::getQueue Dec 10 11:29:04 volumio-pb volumio[909]: info: CorePlayQueue::getQueue Dec 10 11:29:05 volumio-pb volumio[909]: info: CoreCommandRouter::volumioGetState Dec 10 11:29:05 volumio-pb volumio[909]: info: CoreCommandRouter::volumioGetQueue Dec 10 11:29:05 volumio-pb volumio[909]: info: CoreStateMachine::getQueue Dec 10 11:29:05 volumio-pb volumio[909]: info: CorePlayQueue::getQueue Dec 10 11:29:05 volumio-pb volumio[909]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Dec 10 11:29:05 volumio-pb volumio[909]: info: Preload queue cleared Dec 10 11:29:06 volumio-pb volumio[909]: info: CoreCommandRouter::volumioGetState Dec 10 11:29:06 volumio-pb volumio[909]: info: CoreCommandRouter::volumioGetQueue Dec 10 11:29:06 volumio-pb volumio[909]: info: CoreStateMachine::getQueue Dec 10 11:29:06 volumio-pb volumio[909]: info: CorePlayQueue::getQueue Dec 10 11:29:07 volumio-pb volumio[909]: info: CoreCommandRouter::volumioGetState Dec 10 11:29:07 volumio-pb volumio[909]: info: CoreCommandRouter::volumioGetQueue Dec 10 11:29:07 volumio-pb volumio[909]: info: CoreStateMachine::getQueue Dec 10 11:29:07 volumio-pb volumio[909]: info: CorePlayQueue::getQueue Dec 10 11:29:08 volumio-pb volumio[909]: info: CoreCommandRouter::volumioGetState Dec 10 11:29:08 volumio-pb volumio[909]: info: CoreCommandRouter::volumioGetQueue Dec 10 11:29:08 volumio-pb volumio[909]: info: CoreStateMachine::getQueue Dec 10 11:29:08 volumio-pb volumio[909]: info: CorePlayQueue::getQueue Dec 10 11:29:09 volumio-pb volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 10 11:29:09 volumio-pb volumio[909]: info: CoreCommandRouter::volumioGetState Dec 10 11:29:09 volumio-pb volumio[909]: info: CoreCommandRouter::volumioGetQueue Dec 10 11:29:09 volumio-pb volumio[909]: info: CoreStateMachine::getQueue Dec 10 11:29:09 volumio-pb volumio[909]: info: CorePlayQueue::getQueue Dec 10 11:29:09 volumio-pb volumio[909]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Dec 10 11:29:09 volumio-pb volumio[909]: info: CoreCommandRouter::volumioGetState Dec 10 11:29:10 volumio-pb volumio[909]: info: CoreCommandRouter::volumioGetState Dec 10 11:29:10 volumio-pb volumio[909]: info: CoreCommandRouter::volumioGetQueue Dec 10 11:29:10 volumio-pb volumio[909]: info: CoreStateMachine::getQueue Dec 10 11:29:10 volumio-pb volumio[909]: info: CorePlayQueue::getQueue Dec 10 11:29:10 volumio-pb volumio[909]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Dec 10 11:29:11 volumio-pb volumio[909]: info: Preload queue cleared Dec 10 11:29:11 volumio-pb volumio[909]: info: CoreCommandRouter::volumioGetState Dec 10 11:29:11 volumio-pb volumio[909]: info: CoreCommandRouter::volumioGetQueue Dec 10 11:29:11 volumio-pb volumio[909]: info: CoreStateMachine::getQueue Dec 10 11:29:11 volumio-pb volumio[909]: info: CorePlayQueue::getQueue Dec 10 11:29:12 volumio-pb volumio[909]: info: CoreCommandRouter::volumioGetState Dec 10 11:29:12 volumio-pb volumio[909]: info: CoreCommandRouter::volumioGetQueue Dec 10 11:29:12 volumio-pb volumio[909]: info: CoreStateMachine::getQueue Dec 10 11:29:12 volumio-pb volumio[909]: info: CorePlayQueue::getQueue Dec 10 11:29:13 volumio-pb volumio[909]: info: CoreCommandRouter::volumioGetState Dec 10 11:29:13 volumio-pb volumio[909]: info: CoreCommandRouter::volumioGetQueue Dec 10 11:29:13 volumio-pb volumio[909]: info: CoreStateMachine::getQueue Dec 10 11:29:13 volumio-pb volumio[909]: info: CorePlayQueue::getQueue Dec 10 11:29:14 volumio-pb volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 11:29:14 volumio-pb volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 10 11:29:14 volumio-pb volumio[909]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 10 11:29:14 volumio-pb volumio[909]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 10 11:29:14 volumio-pb volumio[909]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 10 11:29:14 volumio-pb volumio[909]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 10 11:29:14 volumio-pb volumio[909]: info: CoreCommandRouter::volumioGetBrowseSources Dec 10 11:29:14 volumio-pb volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 10 11:29:14 volumio-pb volumio[909]: info: CoreCommandRouter::volumioGetState Dec 10 11:29:14 volumio-pb volumio[909]: info: CoreCommandRouter::volumioGetQueue Dec 10 11:29:14 volumio-pb volumio[909]: info: CoreStateMachine::getQueue Dec 10 11:29:14 volumio-pb volumio[909]: info: CorePlayQueue::getQueue Dec 10 11:29:15 volumio-pb volumio[909]: info: CoreCommandRouter::volumioGetState Dec 10 11:29:15 volumio-pb volumio[909]: info: CoreCommandRouter::volumioGetQueue Dec 10 11:29:15 volumio-pb volumio[909]: info: CoreStateMachine::getQueue Dec 10 11:29:15 volumio-pb volumio[909]: info: CorePlayQueue::getQueue Dec 10 11:29:16 volumio-pb volumio[909]: info: CoreCommandRouter::volumioGetState Dec 10 11:29:16 volumio-pb volumio[909]: info: CoreCommandRouter::volumioGetQueue Dec 10 11:29:16 volumio-pb volumio[909]: info: CoreStateMachine::getQueue Dec 10 11:29:16 volumio-pb volumio[909]: info: CorePlayQueue::getQueue Dec 10 11:29:17 volumio-pb volumio[909]: info: CoreCommandRouter::volumioGetState Dec 10 11:29:17 volumio-pb volumio[909]: info: CoreCommandRouter::volumioGetQueue Dec 10 11:29:17 volumio-pb volumio[909]: info: CoreStateMachine::getQueue Dec 10 11:29:17 volumio-pb volumio[909]: info: CorePlayQueue::getQueue Dec 10 11:29:18 volumio-pb volumio[909]: info: CoreCommandRouter::volumioGetState Dec 10 11:29:18 volumio-pb volumio[909]: info: CoreCommandRouter::volumioGetQueue Dec 10 11:29:18 volumio-pb volumio[909]: info: CoreStateMachine::getQueue Dec 10 11:29:18 volumio-pb volumio[909]: info: CorePlayQueue::getQueue Dec 10 11:29:19 volumio-pb volumio[909]: info: CoreCommandRouter::volumioGetState Dec 10 11:29:19 volumio-pb volumio[909]: info: CoreCommandRouter::volumioGetQueue Dec 10 11:29:19 volumio-pb volumio[909]: info: CoreStateMachine::getQueue Dec 10 11:29:19 volumio-pb volumio[909]: info: CorePlayQueue::getQueue Dec 10 11:29:20 volumio-pb volumio[909]: info: CoreCommandRouter::volumioGetState Dec 10 11:29:20 volumio-pb volumio[909]: info: CoreCommandRouter::volumioGetQueue Dec 10 11:29:20 volumio-pb volumio[909]: info: CoreStateMachine::getQueue Dec 10 11:29:20 volumio-pb volumio[909]: info: CorePlayQueue::getQueue Dec 10 11:29:21 volumio-pb volumio[909]: info: CoreCommandRouter::volumioGetState Dec 10 11:29:21 volumio-pb volumio[909]: info: CoreCommandRouter::volumioGetQueue Dec 10 11:29:21 volumio-pb volumio[909]: info: CoreStateMachine::getQueue Dec 10 11:29:21 volumio-pb volumio[909]: info: CorePlayQueue::getQueue Dec 10 11:29:22 volumio-pb volumio[909]: info: CoreCommandRouter::volumioGetState Dec 10 11:29:22 volumio-pb volumio[909]: info: CoreCommandRouter::volumioGetQueue Dec 10 11:29:22 volumio-pb volumio[909]: info: CoreStateMachine::getQueue Dec 10 11:29:22 volumio-pb volumio[909]: info: CorePlayQueue::getQueue Dec 10 11:29:23 volumio-pb volumio[909]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ Dec 10 11:29:23 volumio-pb volumio[909]: info: PLUGIN onReboot : networkfs Dec 10 11:29:23 volumio-pb volumio[909]: info: PLUGIN onReboot : audiophonicsonoff Dec 10 11:29:23 volumio-pb volumio[909]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 10 11:29:23 volumio-pb volumio[909]: TypeError: Cannot read property 'writeSync' of undefined Dec 10 11:29:23 volumio-pb volumio[909]: at ControllerAudiophonicsOnOff.onVolumioReboot (/data/plugins/system_controller/audiophonicsonoff/index.js:35:25) Dec 10 11:29:23 volumio-pb volumio[909]: at PluginManager.onVolumioRebootPlugin (/volumio/app/pluginmanager.js:684:30) Dec 10 11:29:23 volumio-pb volumio[909]: at HashMap. (/volumio/app/pluginmanager.js:668:31) Dec 10 11:29:23 volumio-pb volumio[909]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Dec 10 11:29:23 volumio-pb volumio[909]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Dec 10 11:29:23 volumio-pb volumio[909]: at PluginManager.onVolumioReboot (/volumio/app/pluginmanager.js:666:20) Dec 10 11:29:23 volumio-pb volumio[909]: at CoreCommandRouter.reboot (/volumio/app/index.js:1331:22) Dec 10 11:29:23 volumio-pb volumio[909]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:864:33) Dec 10 11:29:23 volumio-pb volumio[909]: at Socket.emit (events.js:315:20) Dec 10 11:29:23 volumio-pb volumio[909]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Dec 10 11:29:23 volumio-pb volumio[909]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Dec 10 11:29:23 volumio-pb volumio[909]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 10 11:29:23 volumio-pb sudo[1529]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-10 11:28 Dec 10 11:29:23 volumio-pb sudo[1529]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"