-- Logs begin at Wed 2024-05-08 21:08:08 CEST, end at Wed 2024-05-08 21:10:11 CEST. -- May 08 21:09:00 minerva volumio[968]: info: Initializing connection to go-librespot Websocket May 08 21:09:00 minerva go-librespot[1223]: time="2024-05-08T21:09:00+02:00" level=debug msg="new websocket client" May 08 21:09:00 minerva volumio[968]: info: Connection to go-librespot Websocket established May 08 21:09:03 minerva volumio[968]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 08 21:09:03 minerva volumio[968]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 08 21:09:03 minerva volumio[968]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 08 21:09:03 minerva volumio[968]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 08 21:09:03 minerva volumio[968]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 08 21:09:03 minerva volumio[968]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 08 21:09:03 minerva volumio[968]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 08 21:09:03 minerva volumio[968]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 08 21:09:03 minerva volumio[968]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 08 21:09:03 minerva volumio[968]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 08 21:09:03 minerva volumio[968]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 08 21:09:03 minerva volumio[968]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 08 21:09:03 minerva volumio[968]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 08 21:09:03 minerva volumio[968]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 08 21:09:03 minerva volumio[968]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 08 21:09:03 minerva volumio[968]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 08 21:09:03 minerva volumio[968]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 08 21:09:03 minerva volumio[968]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 08 21:09:03 minerva volumio[968]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 08 21:09:03 minerva volumio[968]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 08 21:09:03 minerva volumio[968]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 08 21:09:03 minerva volumio[968]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 08 21:09:03 minerva volumio[968]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 08 21:09:03 minerva volumio[968]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 08 21:09:03 minerva volumio[968]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 08 21:09:03 minerva volumio[968]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 08 21:09:03 minerva volumio[968]: info: Adding plugin bluetooth to MyMusic Plugins May 08 21:09:03 minerva volumio[968]: info: Adding plugin multiroom to MyMusic Plugins May 08 21:09:03 minerva volumio[968]: info: Adding plugin metavolumio to MyMusic Plugins May 08 21:09:03 minerva volumio[968]: info: Adding plugin cd_controller to MyMusic Plugins May 08 21:09:03 minerva volumio[968]: info: Adding plugin smart_inputs to MyMusic Plugins May 08 21:09:03 minerva volumio[968]: info: Adding plugin tidalconnect to MyMusic Plugins May 08 21:09:03 minerva volumio[968]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 08 21:09:05 minerva volumio[968]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 08 21:09:05 minerva volumio[968]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 08 21:09:05 minerva volumio[968]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 08 21:09:05 minerva volumio[968]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 08 21:09:05 minerva volumio[968]: info: Starting MyVolumio Remote Streaming Endpoints May 08 21:09:05 minerva volumio[968]: info: MyVolumio login type: Token May 08 21:09:05 minerva volumio[968]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 08 21:09:05 minerva volumio[968]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 08 21:09:05 minerva volumio[1227]: ...............................................................................++++ May 08 21:09:07 minerva volumio[968]: info: Starting Streaming Service Transparent Proxy May 08 21:09:07 minerva volumio[968]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 08 21:09:07 minerva volumio[968]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 08 21:09:07 minerva volumio[968]: info: Streaming services startup May 08 21:09:07 minerva volumio[968]: info: Starting Streaming Daemon May 08 21:09:07 minerva volumio[968]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 08 21:09:07 minerva volumio[968]: info: Getting Spotify volume May 08 21:09:07 minerva volumio[968]: info: Spotify volume: 100 May 08 21:09:07 minerva sudo[1487]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 08 21:09:07 minerva sudo[1487]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 21:09:07 minerva sudo[1487]: pam_unix(sudo:session): session closed for user root May 08 21:09:07 minerva volumio[968]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 7 May 08 21:09:07 minerva volumio[968]: error: Cannot start Volumio Streaming Daemon May 08 21:09:07 minerva volumio[968]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 08 21:09:07 minerva volumio[968]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 08 21:09:07 minerva volumio[968]: info: CoreCommandRouter::volumioGetState May 08 21:09:07 minerva volumio[968]: info: CorePlayQueue::getTrack 0 May 08 21:09:07 minerva volumio[968]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 May 08 21:09:07 minerva volumio[968]: SPOTIFY: SPOTIFY VOLUME 100 May 08 21:09:07 minerva volumio[968]: SPOTIFY: VOLUMIO VOLUME 33 May 08 21:09:07 minerva volumio[968]: SPOTIFY: DELTA VOLUME ENOUGH: true May 08 21:09:07 minerva volumio[968]: info: Setting Spotify Volume from Volumio: 33 May 08 21:09:08 minerva volumio[968]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 08 21:09:09 minerva volumio[968]: SPOTIFY: SETTING SPOTIFY VOLUME 33 May 08 21:09:09 minerva volumio[968]: info: Sending Spotify command with payload to local API: /player/volume May 08 21:09:09 minerva go-librespot[1223]: time="2024-05-08T21:09:09+02:00" level=debug msg="update volume to 21626/65535" May 08 21:09:09 minerva go-librespot[1223]: time="2024-05-08T21:09:09+02:00" level=debug msg="put connect state because VOLUME_CHANGED" May 08 21:09:09 minerva go-librespot[1223]: time="2024-05-08T21:09:09+02:00" level=trace msg="emitting websocket event: volume" May 08 21:09:09 minerva volumio[968]: SPOTIFY: received: {"type":"volume","data":{"value":33,"max":100}} May 08 21:09:09 minerva volumio[968]: SPOTIFY: RECEIVED SPOTIFY VOLUME 33 May 08 21:09:09 minerva volumio[968]: info: MyVolumio token set successfully May 08 21:09:09 minerva volumio[968]: info: MYVOLUMIO: Adding device May 08 21:09:09 minerva volumio[968]: info: MYVOLUMIO: Evaluating Server May 08 21:09:09 minerva volumio[968]: STREAMING PROXY: Starting server on port 3245 May 08 21:09:09 minerva volumio[968]: Node JS runtime: 14 May 08 21:09:10 minerva volumio[968]: info: MyVolumio status changed May 08 21:09:10 minerva volumio[968]: info: Streaming services startup May 08 21:09:10 minerva volumio[968]: info: Starting Streaming Daemon May 08 21:09:10 minerva volumio[968]: info: Removing browser output: myVolumio user plan is not superstar May 08 21:09:10 minerva volumio[968]: info: Removing audio output: May 08 21:09:10 minerva volumio[968]: info: Stoppping Tunnel 1 May 08 21:09:10 minerva sudo[1530]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 08 21:09:10 minerva sudo[1530]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 21:09:10 minerva sudo[1532]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service May 08 21:09:10 minerva sudo[1532]: pam_unix(sudo:session): session opened for user root by (uid=0) May 08 21:09:10 minerva sudo[1530]: pam_unix(sudo:session): session closed for user root May 08 21:09:10 minerva sudo[1532]: pam_unix(sudo:session): session closed for user root May 08 21:09:10 minerva volumio[968]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Total Clients: 8 May 08 21:09:10 minerva volumio[968]: error: Cannot start Volumio Streaming Daemon May 08 21:09:10 minerva volumio[968]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 08 21:09:10 minerva volumio[968]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 08 21:09:10 minerva volumio[968]: info: Remote SSH Stopped May 08 21:09:10 minerva volumio[968]: info: Setting Geolocation for MyVolumio to eu6 May 08 21:09:10 minerva volumio[968]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 08 21:09:10 minerva volumio[968]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 08 21:09:10 minerva volumio[968]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 08 21:09:11 minerva volumio[968]: info: Successfully Added MyVolumio device May 08 21:09:11 minerva volumio[968]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined May 08 21:09:11 minerva volumio[968]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo May 08 21:09:11 minerva volumio[968]: info: Updating MyVolumio device info May 08 21:09:11 minerva volumio[968]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 08 21:09:11 minerva volumio[968]: info: Listing playlists May 08 21:09:11 minerva volumio[968]: info: CoreCommandRouter::volumioGetQueue May 08 21:09:11 minerva volumio[968]: info: CoreStateMachine::getQueue May 08 21:09:11 minerva volumio[968]: info: CorePlayQueue::getQueue May 08 21:09:11 minerva volumio[968]: info: CoreCommandRouter::volumioGetVisibleSources May 08 21:09:11 minerva volumio[968]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 08 21:09:11 minerva volumio[968]: info: CoreCommandRouter::volumioGetState May 08 21:09:11 minerva volumio[968]: info: CorePlayQueue::getTrack 0 May 08 21:09:12 minerva volumio[968]: info: Successfully Updated MyVolumio device May 08 21:09:12 minerva button_listener.sh[599]: % Total % Received % Xferd Average Speed Time Time Time Current May 08 21:09:12 minerva button_listener.sh[599]: Dload Upload Total Spent Left Speed May 08 21:09:12 minerva volumio[968]: info: May 08 21:09:12 minerva volumio[968]: ---------------------------- Client requests Volumio Play Playlist OE1 May 08 21:09:12 minerva volumio[968]: info: Play playlist OE1 May 08 21:09:12 minerva volumio[968]: info: CoreCommandRouter::volumioClearQueue May 08 21:09:12 minerva volumio[968]: info: CoreStateMachine::ClearQueue May 08 21:09:12 minerva volumio[968]: info: CoreStateMachine::stop May 08 21:09:12 minerva volumio[968]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 21:09:12 minerva volumio[968]: info: CorePlayQueue::clearPlayQueue May 08 21:09:12 minerva volumio[968]: info: CorePlayQueue::saveQueue May 08 21:09:12 minerva volumio[968]: info: CoreCommandRouter::volumioPushState May 08 21:09:12 minerva volumio[968]: info: CoreCommandRouter::volumioPushQueue May 08 21:09:12 minerva volumio[968]: info: CoreCommandRouter::volumioAddQueueItems May 08 21:09:12 minerva volumio[968]: info: CoreStateMachine::addQueueItems May 08 21:09:12 minerva volumio[968]: info: CorePlayQueue::addQueueItems May 08 21:09:12 minerva volumio[968]: info: Preload queue cleared May 08 21:09:12 minerva volumio[968]: info: Adding Item to queue: https://orf-live.ors-shoutcast.at/oe1-q2a May 08 21:09:12 minerva volumio[968]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 08 21:09:12 minerva volumio[968]: info: CoreCommandRouter::volumioPushQueue May 08 21:09:12 minerva volumio[968]: info: CorePlayQueue::saveQueue May 08 21:09:12 minerva volumio[968]: info: CoreStateMachine::updateTrackBlock May 08 21:09:12 minerva volumio[968]: info: CorePlayQueue::getTrackBlock May 08 21:09:12 minerva volumio[968]: info: CoreCommandRouter::volumioPlay May 08 21:09:12 minerva volumio[968]: info: CoreStateMachine::play index 0 May 08 21:09:12 minerva volumio[968]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 21:09:12 minerva volumio[968]: info: CoreStateMachine::stop May 08 21:09:12 minerva volumio[968]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 21:09:12 minerva button_listener.sh[599]: [158B blob data] May 08 21:09:12 minerva volumio[968]: info: CoreStateMachine::play index undefined May 08 21:09:12 minerva volumio[968]: info: CoreStateMachine::setConsumeUpdateService undefined May 08 21:09:12 minerva volumio[968]: info: CorePlayQueue::getTrack 0 May 08 21:09:12 minerva volumio[968]: info: CoreStateMachine::startPlaybackTimer May 08 21:09:12 minerva volumio[968]: info: CorePlayQueue::getTrack 0 May 08 21:09:12 minerva volumio[968]: info: [1715195352465] ControllerWebradio::clearAddPlayTrack May 08 21:09:12 minerva volumio[968]: verbose: ControllerMpd::sendMpdCommand stop May 08 21:09:12 minerva volumio[968]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 May 08 21:09:12 minerva volumio[968]: info: sendMpdCommand stop took 24 milliseconds May 08 21:09:12 minerva volumio[968]: verbose: ControllerMpd::sendMpdCommand clear May 08 21:09:12 minerva volumio[968]: info: May 08 21:09:12 minerva volumio[968]: ---------------------------- MPD announces system playlist update May 08 21:09:12 minerva volumio[968]: info: Ignoring MPD Status Update May 08 21:09:12 minerva volumio[968]: info: sendMpdCommand clear took 10 milliseconds May 08 21:09:12 minerva volumio[968]: verbose: ControllerMpd::sendMpdCommand load "https://orf-live.ors-shoutcast.at/oe1-q2a" May 08 21:09:12 minerva volumio[968]: info: May 08 21:09:12 minerva volumio[968]: ---------------------------- MPD announces system playlist update May 08 21:09:12 minerva volumio[968]: info: Ignoring MPD Status Update May 08 21:09:12 minerva volumio[968]: info: May 08 21:09:12 minerva volumio[968]: ---------------------------- MPD announces system playlist update May 08 21:09:12 minerva volumio[968]: info: Ignoring MPD Status Update May 08 21:09:12 minerva volumio[968]: error: updateQueue error: null May 08 21:09:12 minerva volumio[968]: info: ------------------------------ 25ms May 08 21:09:12 minerva button_listener.sh[599]: {"time":1715195352319,"response":"playplaylist Success"} % Total % Received % Xferd Average Speed Time Time Time Current May 08 21:09:12 minerva button_listener.sh[599]: Dload Upload Total Spent Left Speed May 08 21:09:12 minerva volumio[968]: info: May 08 21:09:12 minerva volumio[968]: ---------------------------- Client requests Volume 25 May 08 21:09:12 minerva volumio[968]: info: VolumeController::SetAlsaVolume25 May 08 21:09:12 minerva volumio[968]: info: CoreStateMachine::pushState May 08 21:09:12 minerva volumio[968]: info: CorePlayQueue::getTrack 0 May 08 21:09:12 minerva volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 21:09:12 minerva volumio[968]: info: CoreCommandRouter::volumioPushState May 08 21:09:12 minerva button_listener.sh[599]: [158B blob data] May 08 21:09:12 minerva volumio[968]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 May 08 21:09:12 minerva volumio[968]: SPOTIFY: SPOTIFY VOLUME 33 May 08 21:09:12 minerva volumio[968]: SPOTIFY: VOLUMIO VOLUME 25 May 08 21:09:12 minerva volumio[968]: SPOTIFY: DELTA VOLUME ENOUGH: true May 08 21:09:12 minerva volumio[968]: info: Setting Spotify Volume from Volumio: 25 May 08 21:09:14 minerva volumio[968]: verbose: ControllerMpd::sendMpdCommand add "https://orf-live.ors-shoutcast.at/oe1-q2a" May 08 21:09:14 minerva volumio[968]: info: May 08 21:09:14 minerva volumio[968]: ---------------------------- MPD announces state update: mixer May 08 21:09:14 minerva volumio[968]: info: ControllerMpd::getState May 08 21:09:14 minerva volumio[968]: verbose: ControllerMpd::sendMpdCommand status May 08 21:09:14 minerva volumio[968]: info: May 08 21:09:14 minerva volumio[968]: ---------------------------- MPD announces state update: mixer May 08 21:09:14 minerva volumio[968]: info: ControllerMpd::getState May 08 21:09:14 minerva volumio[968]: verbose: ControllerMpd::sendMpdCommand status May 08 21:09:14 minerva volumio[968]: info: May 08 21:09:14 minerva volumio[968]: ---------------------------- MPD announces state update: mixer May 08 21:09:14 minerva volumio[968]: error: updateQueue error: null May 08 21:09:14 minerva volumio[968]: error: updateQueue error: null May 08 21:09:14 minerva volumio[968]: info: May 08 21:09:14 minerva volumio[968]: ---------------------------- MPD announces system playlist update May 08 21:09:14 minerva volumio[968]: info: Ignoring MPD Status Update May 08 21:09:14 minerva volumio[968]: info: ControllerMpd::getState May 08 21:09:14 minerva volumio[968]: verbose: ControllerMpd::sendMpdCommand status May 08 21:09:14 minerva volumio[968]: info: ------------------------------ 1665ms May 08 21:09:14 minerva volumio[968]: info: ------------------------------ 1661ms May 08 21:09:14 minerva volumio[968]: info: sendMpdCommand add "https://orf-live.ors-shoutcast.at/oe1-q2a" took 17 milliseconds May 08 21:09:14 minerva volumio[968]: info: sendMpdCommand status took 12 milliseconds May 08 21:09:14 minerva volumio[968]: info: CoreStateMachine::setConsumeUpdateService mpd May 08 21:09:14 minerva volumio[968]: verbose: ControllerMpd::sendMpdCommand play May 08 21:09:14 minerva volumio[968]: verbose: ControllerMpd::parseState May 08 21:09:14 minerva volumio[968]: info: ControllerMpd::pushState May 08 21:09:14 minerva volumio[968]: info: CoreCommandRouter::servicePushState May 08 21:09:14 minerva volumio[968]: info: CoreStateMachine::pushState May 08 21:09:14 minerva volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 21:09:14 minerva volumio[968]: info: CoreCommandRouter::volumioPushState May 08 21:09:14 minerva volumio[968]: info: CorePlayQueue::getTrack 0 May 08 21:09:14 minerva volumio[968]: 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} May 08 21:09:14 minerva volumio[968]: verbose: CURRENT POSITION 0 May 08 21:09:14 minerva volumio[968]: info: CoreStateMachine::syncState stateService stop May 08 21:09:14 minerva volumio[968]: info: CoreStateMachine::syncState currentStatus stop May 08 21:09:14 minerva volumio[968]: info: CoreStateMachine::pushState May 08 21:09:14 minerva volumio[968]: info: CoreCommandRouter::volumioPushState May 08 21:09:14 minerva volumio[968]: info: No code May 08 21:09:14 minerva volumio[968]: info: CoreStateMachine::pushState May 08 21:09:14 minerva volumio[968]: info: CoreCommandRouter::volumioPushState May 08 21:09:14 minerva volumio[968]: info: ------------------------------ 93ms May 08 21:09:14 minerva volumio[968]: info: May 08 21:09:14 minerva volumio[968]: ---------------------------- MPD announces system playlist update May 08 21:09:14 minerva volumio[968]: info: Ignoring MPD Status Update May 08 21:09:14 minerva volumio[968]: SPOTIFY: SETTING SPOTIFY VOLUME 25 May 08 21:09:14 minerva volumio[968]: info: Sending Spotify command with payload to local API: /player/volume May 08 21:09:14 minerva volumio[968]: info: May 08 21:09:14 minerva volumio[968]: ---------------------------- MPD announces system playlist update May 08 21:09:14 minerva volumio[968]: info: Ignoring MPD Status Update May 08 21:09:14 minerva volumio[968]: info: sendMpdCommand status took 100 milliseconds May 08 21:09:14 minerva volumio[968]: info: sendMpdCommand status took 94 milliseconds May 08 21:09:14 minerva volumio[968]: info: ------------------------------ 96ms May 08 21:09:14 minerva volumio[968]: info: sendMpdCommand play took 91 milliseconds May 08 21:09:14 minerva volumio[968]: info: ------------------------------ 14ms May 08 21:09:14 minerva volumio[968]: info: ------------------------------ 7ms May 08 21:09:14 minerva volumio[968]: verbose: ControllerMpd::parseState May 08 21:09:14 minerva volumio[968]: verbose: ControllerMpd::parseState May 08 21:09:14 minerva volumio[968]: info: ControllerMpd::pushState May 08 21:09:14 minerva volumio[968]: info: CoreCommandRouter::servicePushState May 08 21:09:14 minerva volumio[968]: info: CoreStateMachine::pushState May 08 21:09:14 minerva volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 21:09:14 minerva volumio[968]: info: CoreCommandRouter::volumioPushState May 08 21:09:14 minerva volumio[968]: info: CorePlayQueue::getTrack 0 May 08 21:09:14 minerva volumio[968]: 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} May 08 21:09:14 minerva volumio[968]: verbose: CURRENT POSITION 0 May 08 21:09:14 minerva volumio[968]: info: CoreStateMachine::syncState stateService stop May 08 21:09:14 minerva volumio[968]: info: CoreStateMachine::syncState currentStatus stop May 08 21:09:14 minerva volumio[968]: info: CoreStateMachine::pushState May 08 21:09:14 minerva volumio[968]: info: CoreCommandRouter::volumioPushState May 08 21:09:14 minerva volumio[968]: info: No code May 08 21:09:14 minerva volumio[968]: info: CoreStateMachine::pushState May 08 21:09:14 minerva volumio[968]: info: CoreCommandRouter::volumioPushState May 08 21:09:14 minerva volumio[968]: info: ControllerMpd::pushState May 08 21:09:14 minerva volumio[968]: info: CoreCommandRouter::servicePushState May 08 21:09:14 minerva volumio[968]: info: CoreStateMachine::pushState May 08 21:09:14 minerva volumio[968]: info: CoreCommandRouter::volumioPushState May 08 21:09:14 minerva volumio[968]: info: CorePlayQueue::getTrack 0 May 08 21:09:14 minerva volumio[968]: 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} May 08 21:09:14 minerva volumio[968]: verbose: CURRENT POSITION 0 May 08 21:09:14 minerva volumio[968]: info: CoreStateMachine::syncState stateService stop May 08 21:09:14 minerva volumio[968]: info: CoreStateMachine::syncState currentStatus stop May 08 21:09:14 minerva volumio[968]: info: CoreStateMachine::pushState May 08 21:09:14 minerva volumio[968]: info: CoreCommandRouter::volumioPushState May 08 21:09:14 minerva volumio[968]: info: No code May 08 21:09:14 minerva volumio[968]: info: CoreStateMachine::pushState May 08 21:09:14 minerva volumio[968]: info: CoreCommandRouter::volumioPushState May 08 21:09:14 minerva volumio[968]: info: ------------------------------ 292ms May 08 21:09:14 minerva volumio[968]: info: ------------------------------ 290ms May 08 21:09:14 minerva volumio[968]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 May 08 21:09:14 minerva volumio[968]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 May 08 21:09:14 minerva volumio[968]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 May 08 21:09:14 minerva volumio[968]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 May 08 21:09:14 minerva volumio[968]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 May 08 21:09:14 minerva volumio[968]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 May 08 21:09:14 minerva volumio[968]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 May 08 21:09:14 minerva go-librespot[1223]: time="2024-05-08T21:09:14+02:00" level=debug msg="update volume to 16383/65535" May 08 21:09:14 minerva volumio[968]: info: May 08 21:09:14 minerva volumio[968]: ---------------------------- MPD announces state update: player May 08 21:09:14 minerva volumio[968]: info: ControllerMpd::getState May 08 21:09:14 minerva volumio[968]: verbose: ControllerMpd::sendMpdCommand status May 08 21:09:14 minerva volumio[968]: info: May 08 21:09:14 minerva volumio[968]: ---------------------------- MPD announces state update: player May 08 21:09:14 minerva volumio[968]: info: May 08 21:09:14 minerva volumio[968]: ---------------------------- MPD announces system playlist update May 08 21:09:14 minerva volumio[968]: info: Ignoring MPD Status Update May 08 21:09:14 minerva volumio[968]: info: May 08 21:09:14 minerva volumio[968]: ---------------------------- MPD announces state update: player May 08 21:09:14 minerva volumio[968]: info: ControllerMpd::getState May 08 21:09:14 minerva volumio[968]: verbose: ControllerMpd::sendMpdCommand status May 08 21:09:14 minerva volumio[968]: info: sendMpdCommand status took 4 milliseconds May 08 21:09:14 minerva volumio[968]: info: ControllerMpd::getState May 08 21:09:14 minerva volumio[968]: verbose: ControllerMpd::sendMpdCommand status May 08 21:09:14 minerva volumio[968]: verbose: ControllerMpd::parseState May 08 21:09:14 minerva volumio[968]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 21:09:14 minerva volumio[968]: info: May 08 21:09:14 minerva volumio[968]: ---------------------------- MPD announces state update: player May 08 21:09:14 minerva volumio[968]: info: ControllerMpd::getState May 08 21:09:14 minerva volumio[968]: verbose: ControllerMpd::sendMpdCommand status May 08 21:09:14 minerva volumio[968]: info: May 08 21:09:14 minerva volumio[968]: ---------------------------- MPD announces system playlist update May 08 21:09:14 minerva volumio[968]: info: Ignoring MPD Status Update May 08 21:09:14 minerva volumio[968]: info: May 08 21:09:14 minerva volumio[968]: ---------------------------- MPD announces state update: player May 08 21:09:14 minerva volumio[968]: info: ControllerMpd::getState May 08 21:09:14 minerva volumio[968]: verbose: ControllerMpd::sendMpdCommand status May 08 21:09:14 minerva volumio[968]: info: sendMpdCommand status took 16 milliseconds May 08 21:09:14 minerva volumio[968]: info: ------------------------------ 18ms May 08 21:09:14 minerva volumio[968]: info: sendMpdCommand status took 16 milliseconds May 08 21:09:14 minerva volumio[968]: info: sendMpdCommand playlistinfo took 15 milliseconds May 08 21:09:14 minerva volumio[968]: info: sendMpdCommand status took 10 milliseconds May 08 21:09:14 minerva volumio[968]: info: ------------------------------ 9ms May 08 21:09:14 minerva volumio[968]: info: sendMpdCommand status took 8 milliseconds May 08 21:09:14 minerva volumio[968]: verbose: ControllerMpd::parseState May 08 21:09:14 minerva volumio[968]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 21:09:14 minerva volumio[968]: verbose: ControllerMpd::parseState May 08 21:09:14 minerva volumio[968]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 21:09:14 minerva volumio[968]: verbose: ControllerMpd::parseTrackInfo May 08 21:09:14 minerva volumio[968]: verbose: ControllerMpd::parseState May 08 21:09:14 minerva volumio[968]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 21:09:14 minerva volumio[968]: verbose: ControllerMpd::parseState May 08 21:09:14 minerva volumio[968]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 21:09:14 minerva volumio[968]: info: ControllerMpd::pushState May 08 21:09:14 minerva volumio[968]: info: CoreCommandRouter::servicePushState May 08 21:09:14 minerva volumio[968]: info: CorePlayQueue::getTrack 0 May 08 21:09:14 minerva volumio[968]: verbose: STATE SERVICE {"status":"play","position":0,"seek":980,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Aus dem RadioKulturhaus Wien - Im Zeit-Raum: Wie entkommen wir","artist":null,"album":null,"uri":"https://orf-live.ors-shoutcast.at/oe1-q2a","trackType":"at/oe1-q2a"} May 08 21:09:14 minerva volumio[968]: verbose: CURRENT POSITION 0 May 08 21:09:14 minerva volumio[968]: info: CoreStateMachine::syncState stateService play May 08 21:09:14 minerva volumio[968]: info: CoreStateMachine::syncState currentStatus stop May 08 21:09:14 minerva volumio[968]: info: ------------------------------ 39ms May 08 21:09:14 minerva volumio[968]: info: May 08 21:09:14 minerva volumio[968]: ---------------------------- MPD announces system playlist update May 08 21:09:14 minerva volumio[968]: info: Ignoring MPD Status Update May 08 21:09:14 minerva volumio[968]: info: May 08 21:09:14 minerva volumio[968]: ---------------------------- MPD announces state update: player May 08 21:09:14 minerva volumio[968]: info: ControllerMpd::getState May 08 21:09:14 minerva volumio[968]: verbose: ControllerMpd::sendMpdCommand status May 08 21:09:14 minerva volumio[968]: info: sendMpdCommand playlistinfo took 17 milliseconds May 08 21:09:14 minerva volumio[968]: info: sendMpdCommand playlistinfo took 17 milliseconds May 08 21:09:14 minerva volumio[968]: info: sendMpdCommand playlistinfo took 14 milliseconds May 08 21:09:14 minerva volumio[968]: info: sendMpdCommand playlistinfo took 14 milliseconds May 08 21:09:14 minerva volumio[968]: info: ------------------------------ 8ms May 08 21:09:14 minerva volumio[968]: info: sendMpdCommand status took 6 milliseconds May 08 21:09:14 minerva volumio[968]: verbose: ControllerMpd::parseTrackInfo May 08 21:09:14 minerva volumio[968]: verbose: ControllerMpd::parseTrackInfo May 08 21:09:14 minerva volumio[968]: verbose: ControllerMpd::parseTrackInfo May 08 21:09:14 minerva volumio[968]: verbose: ControllerMpd::parseTrackInfo May 08 21:09:14 minerva volumio[968]: verbose: ControllerMpd::parseState May 08 21:09:14 minerva volumio[968]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 21:09:14 minerva volumio[968]: info: ControllerMpd::pushState May 08 21:09:14 minerva volumio[968]: info: CoreCommandRouter::servicePushState May 08 21:09:14 minerva volumio[968]: info: CorePlayQueue::getTrack 0 May 08 21:09:14 minerva volumio[968]: verbose: STATE SERVICE {"status":"play","position":0,"seek":980,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Aus dem RadioKulturhaus Wien - Im Zeit-Raum: Wie entkommen wir","artist":null,"album":null,"uri":"https://orf-live.ors-shoutcast.at/oe1-q2a","trackType":"at/oe1-q2a"} May 08 21:09:14 minerva volumio[968]: verbose: CURRENT POSITION 0 May 08 21:09:14 minerva volumio[968]: info: CoreStateMachine::syncState stateService play May 08 21:09:14 minerva volumio[968]: info: CoreStateMachine::syncState currentStatus play May 08 21:09:14 minerva volumio[968]: info: Received an update from plugin. extracting info from payload May 08 21:09:14 minerva volumio[968]: info: CoreStateMachine::pushState May 08 21:09:14 minerva volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 21:09:14 minerva volumio[968]: info: CoreCommandRouter::volumioPushState May 08 21:09:14 minerva go-librespot[1223]: time="2024-05-08T21:09:14+02:00" level=debug msg="put connect state because VOLUME_CHANGED" May 08 21:09:14 minerva go-librespot[1223]: time="2024-05-08T21:09:14+02:00" level=trace msg="emitting websocket event: volume" May 08 21:09:14 minerva volumio[968]: info: CoreStateMachine::pushState May 08 21:09:14 minerva volumio[968]: info: CoreCommandRouter::volumioPushState May 08 21:09:14 minerva volumio[968]: info: ControllerMpd::pushState May 08 21:09:14 minerva volumio[968]: info: CoreCommandRouter::servicePushState May 08 21:09:14 minerva volumio[968]: info: CorePlayQueue::getTrack 0 May 08 21:09:14 minerva volumio[968]: verbose: STATE SERVICE {"status":"play","position":0,"seek":980,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Aus dem RadioKulturhaus Wien - Im Zeit-Raum: Wie entkommen wir","artist":null,"album":null,"uri":"https://orf-live.ors-shoutcast.at/oe1-q2a","trackType":"at/oe1-q2a"} May 08 21:09:14 minerva volumio[968]: verbose: CURRENT POSITION 0 May 08 21:09:14 minerva volumio[968]: info: CoreStateMachine::syncState stateService play May 08 21:09:14 minerva volumio[968]: info: CoreStateMachine::syncState currentStatus play May 08 21:09:14 minerva volumio[968]: info: Received an update from plugin. extracting info from payload May 08 21:09:14 minerva volumio[968]: info: CoreStateMachine::pushState May 08 21:09:14 minerva volumio[968]: info: CoreCommandRouter::volumioPushState May 08 21:09:14 minerva volumio[968]: info: CoreStateMachine::pushState May 08 21:09:14 minerva volumio[968]: info: CoreCommandRouter::volumioPushState May 08 21:09:14 minerva volumio[968]: info: ControllerMpd::pushState May 08 21:09:14 minerva volumio[968]: info: CoreCommandRouter::servicePushState May 08 21:09:14 minerva volumio[968]: info: CorePlayQueue::getTrack 0 May 08 21:09:14 minerva volumio[968]: verbose: STATE SERVICE {"status":"play","position":0,"seek":980,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Aus dem RadioKulturhaus Wien - Im Zeit-Raum: Wie entkommen wir","artist":null,"album":null,"uri":"https://orf-live.ors-shoutcast.at/oe1-q2a","trackType":"at/oe1-q2a"} May 08 21:09:14 minerva volumio[968]: verbose: CURRENT POSITION 0 May 08 21:09:14 minerva volumio[968]: info: CoreStateMachine::syncState stateService play May 08 21:09:14 minerva volumio[968]: info: CoreStateMachine::syncState currentStatus play May 08 21:09:14 minerva volumio[968]: info: Received an update from plugin. extracting info from payload May 08 21:09:14 minerva volumio[968]: info: CoreStateMachine::pushState May 08 21:09:14 minerva volumio[968]: info: CoreCommandRouter::volumioPushState May 08 21:09:14 minerva volumio[968]: info: CoreStateMachine::pushState May 08 21:09:14 minerva volumio[968]: info: CoreCommandRouter::volumioPushState May 08 21:09:14 minerva volumio[968]: info: ControllerMpd::pushState May 08 21:09:14 minerva volumio[968]: info: CoreCommandRouter::servicePushState May 08 21:09:14 minerva volumio[968]: info: CorePlayQueue::getTrack 0 May 08 21:09:14 minerva volumio[968]: verbose: STATE SERVICE {"status":"play","position":0,"seek":980,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Aus dem RadioKulturhaus Wien - Im Zeit-Raum: Wie entkommen wir","artist":null,"album":null,"uri":"https://orf-live.ors-shoutcast.at/oe1-q2a","trackType":"at/oe1-q2a"} May 08 21:09:14 minerva volumio[968]: verbose: CURRENT POSITION 0 May 08 21:09:14 minerva volumio[968]: info: CoreStateMachine::syncState stateService play May 08 21:09:14 minerva volumio[968]: info: CoreStateMachine::syncState currentStatus play May 08 21:09:14 minerva volumio[968]: info: Received an update from plugin. extracting info from payload May 08 21:09:14 minerva volumio[968]: info: CoreStateMachine::pushState May 08 21:09:14 minerva volumio[968]: info: CoreCommandRouter::volumioPushState May 08 21:09:14 minerva volumio[968]: info: CoreStateMachine::pushState May 08 21:09:14 minerva volumio[968]: info: CoreCommandRouter::volumioPushState May 08 21:09:14 minerva volumio[968]: info: ------------------------------ 302ms May 08 21:09:14 minerva volumio[968]: info: ------------------------------ 303ms May 08 21:09:14 minerva volumio[968]: info: ------------------------------ 297ms May 08 21:09:14 minerva volumio[968]: info: ------------------------------ 293ms May 08 21:09:14 minerva volumio[968]: info: sendMpdCommand playlistinfo took 262 milliseconds May 08 21:09:14 minerva volumio[968]: verbose: ControllerMpd::parseTrackInfo May 08 21:09:14 minerva volumio[968]: info: ControllerMpd::pushState May 08 21:09:14 minerva volumio[968]: info: CoreCommandRouter::servicePushState May 08 21:09:14 minerva volumio[968]: info: CorePlayQueue::getTrack 0 May 08 21:09:14 minerva volumio[968]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1106,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Aus dem RadioKulturhaus Wien - Im Zeit-Raum: Wie entkommen wir","artist":null,"album":null,"uri":"https://orf-live.ors-shoutcast.at/oe1-q2a","trackType":"at/oe1-q2a"} May 08 21:09:14 minerva volumio[968]: verbose: CURRENT POSITION 0 May 08 21:09:14 minerva volumio[968]: info: CoreStateMachine::syncState stateService play May 08 21:09:14 minerva volumio[968]: info: CoreStateMachine::syncState currentStatus play May 08 21:09:14 minerva volumio[968]: info: Received an update from plugin. extracting info from payload May 08 21:09:14 minerva volumio[968]: info: CoreStateMachine::pushState May 08 21:09:14 minerva volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 21:09:14 minerva volumio[968]: info: CoreCommandRouter::volumioPushState May 08 21:09:14 minerva volumio[968]: info: CoreStateMachine::pushState May 08 21:09:14 minerva volumio[968]: info: CoreCommandRouter::volumioPushState May 08 21:09:14 minerva volumio[968]: info: ------------------------------ 340ms May 08 21:09:14 minerva volumio[968]: SPOTIFY: received: {"type":"volume","data":{"value":25,"max":100}} May 08 21:09:14 minerva volumio[968]: SPOTIFY: RECEIVED SPOTIFY VOLUME 25 May 08 21:09:15 minerva volumio[968]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 May 08 21:09:15 minerva volumio[968]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 May 08 21:09:15 minerva volumio[968]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 May 08 21:09:15 minerva volumio[968]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 May 08 21:09:15 minerva volumio[968]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 May 08 21:09:15 minerva volumio[968]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 May 08 21:09:15 minerva volumio[968]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 May 08 21:09:15 minerva volumio[968]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 May 08 21:09:15 minerva volumio[968]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 May 08 21:09:15 minerva volumio[968]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 May 08 21:09:15 minerva volumio[968]: info: touch_display: Setting screensaver timeout to 0 seconds. May 08 21:09:15 minerva volumio[1227]: .......................................................................++++ May 08 21:09:15 minerva volumio[1227]: e is 65537 (0x010001) May 08 21:09:15 minerva volumio[1227]: writing RSA key May 08 21:09:20 minerva volumio[968]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 08 21:09:20 minerva volumio[968]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9 May 08 21:09:20 minerva volumio[968]: info: CoreCommandRouter::volumioGetState May 08 21:09:21 minerva volumio[968]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 08 21:09:21 minerva volumio[968]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 08 21:09:21 minerva volumio[968]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 08 21:09:21 minerva volumio-remote-updater[554]: No test mode May 08 21:09:21 minerva volumio-remote-updater[554]: No alpha test mode May 08 21:09:21 minerva volumio[968]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 08 21:09:21 minerva volumio[968]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 08 21:09:21 minerva volumio[968]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 08 21:09:24 minerva ntpd[713]: Soliciting pool server 162.159.200.123 May 08 21:09:24 minerva ntpd[713]: Soliciting pool server 152.53.15.127 May 08 21:09:25 minerva volumio[968]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 21:09:25 minerva volumio[968]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 21:09:25 minerva volumio[968]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 08 21:09:25 minerva volumio[968]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 08 21:09:25 minerva volumio[968]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 08 21:09:25 minerva volumio[968]: info: CoreCommandRouter::volumioGetBrowseSources May 08 21:09:25 minerva volumio[968]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 08 21:09:25 minerva ntpd[713]: Soliciting pool server 178.251.64.52 May 08 21:09:25 minerva ntpd[713]: Soliciting pool server 46.102.157.67 May 08 21:09:25 minerva ntpd[713]: Soliciting pool server 185.119.117.217 May 08 21:09:25 minerva ntpd[713]: Soliciting pool server 217.196.145.42 May 08 21:09:26 minerva ntpd[713]: Soliciting pool server 86.59.80.170 May 08 21:09:26 minerva ntpd[713]: Soliciting pool server 144.76.197.108 May 08 21:09:26 minerva ntpd[713]: Soliciting pool server 86.59.113.124 May 08 21:09:26 minerva ntpd[713]: Soliciting pool server 86.59.113.124 May 08 21:09:27 minerva volumio[968]: error: MyVolumio Plugin failed to authenticate in a timely fashion May 08 21:09:27 minerva volumio[968]: info: Completed starting MyVolumio Plugin May 08 21:09:27 minerva volumio[968]: info: BOOT COMPLETED May 08 21:09:27 minerva volumio[968]: [Metrics] CommandRouter: 50s 356.72ms May 08 21:09:27 minerva volumio[968]: info: CoreCommandRouter::volumiosetStartupVolume May 08 21:09:27 minerva volumio[968]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 08 21:09:27 minerva volumio[968]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 08 21:09:27 minerva volumio[968]: info: CoreCommandRouter::Close All Modals sent May 08 21:09:27 minerva volumio[968]: info: CoreCommandRouter::Close All Modals sent May 08 21:09:27 minerva ntpd[713]: Soliciting pool server 83.215.130.11 May 08 21:09:27 minerva ntpd[713]: Soliciting pool server 91.206.8.36 May 08 21:09:28 minerva volumio[968]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 08 21:09:28 minerva volumio[968]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 08 21:09:28 minerva volumio[968]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 08 21:09:28 minerva ntpd[713]: Soliciting pool server 78.41.116.149 May 08 21:09:29 minerva ntpd[713]: Soliciting pool server 2a0d:f302:101:357::1 May 08 21:09:49 minerva ntpd[713]: receive: Unexpected origin timestamp 0xe9e64c6b.b6d0eaa6 does not match aorg 0000000000.00000000 from server@78.41.116.149 xmt 0xe9e64c7d.e1375e2d May 08 21:09:49 minerva ntpd[713]: receive: Unexpected origin timestamp 0xe9e64c6b.b6e0f799 does not match aorg 0000000000.00000000 from server@152.53.15.127 xmt 0xe9e64c7d.e157c6c8 May 08 21:09:49 minerva ntpd[713]: receive: Unexpected origin timestamp 0xe9e64c6b.b6df0695 does not match aorg 0000000000.00000000 from server@86.59.113.124 xmt 0xe9e64c7d.e254c53a May 08 21:09:49 minerva ntpd[713]: receive: Unexpected origin timestamp 0xe9e64c6b.b6d9a75b does not match aorg 0000000000.00000000 from server@144.76.197.108 xmt 0xe9e64c7d.e28cc7ed May 08 21:09:57 minerva volumio[968]: info: May 08 21:09:57 minerva volumio[968]: ---------------------------- MPD announces system playlist update May 08 21:09:57 minerva volumio[968]: info: Ignoring MPD Status Update May 08 21:09:57 minerva volumio[968]: info: May 08 21:09:57 minerva volumio[968]: ---------------------------- MPD announces state update: player May 08 21:09:57 minerva volumio[968]: info: ControllerMpd::getState May 08 21:09:57 minerva volumio[968]: verbose: ControllerMpd::sendMpdCommand status May 08 21:09:57 minerva volumio[968]: info: May 08 21:09:57 minerva volumio[968]: ---------------------------- MPD announces system playlist update May 08 21:09:57 minerva volumio[968]: info: Ignoring MPD Status Update May 08 21:09:57 minerva volumio[968]: info: May 08 21:09:57 minerva volumio[968]: ---------------------------- MPD announces state update: player May 08 21:09:57 minerva volumio[968]: info: ControllerMpd::getState May 08 21:09:57 minerva volumio[968]: verbose: ControllerMpd::sendMpdCommand status May 08 21:09:57 minerva volumio[968]: info: May 08 21:09:57 minerva volumio[968]: ---------------------------- MPD announces system playlist update May 08 21:09:57 minerva volumio[968]: info: Ignoring MPD Status Update May 08 21:09:57 minerva volumio[968]: info: May 08 21:09:57 minerva volumio[968]: ---------------------------- MPD announces state update: player May 08 21:09:57 minerva volumio[968]: info: ControllerMpd::getState May 08 21:09:57 minerva volumio[968]: verbose: ControllerMpd::sendMpdCommand status May 08 21:09:57 minerva volumio[968]: info: ------------------------------ 11ms May 08 21:09:57 minerva volumio[968]: info: sendMpdCommand status took 8 milliseconds May 08 21:09:57 minerva volumio[968]: info: ------------------------------ 8ms May 08 21:09:57 minerva volumio[968]: info: sendMpdCommand status took 7 milliseconds May 08 21:09:57 minerva volumio[968]: info: ------------------------------ 5ms May 08 21:09:57 minerva volumio[968]: info: sendMpdCommand status took 5 milliseconds May 08 21:09:57 minerva volumio[968]: verbose: ControllerMpd::parseState May 08 21:09:57 minerva volumio[968]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 21:09:57 minerva volumio[968]: verbose: ControllerMpd::parseState May 08 21:09:57 minerva volumio[968]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 21:09:57 minerva volumio[968]: verbose: ControllerMpd::parseState May 08 21:09:57 minerva volumio[968]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 08 21:09:57 minerva volumio[968]: info: sendMpdCommand playlistinfo took 4 milliseconds May 08 21:09:57 minerva volumio[968]: info: sendMpdCommand playlistinfo took 3 milliseconds May 08 21:09:57 minerva volumio[968]: info: sendMpdCommand playlistinfo took 4 milliseconds May 08 21:09:57 minerva volumio[968]: verbose: ControllerMpd::parseTrackInfo May 08 21:09:57 minerva volumio[968]: verbose: ControllerMpd::parseTrackInfo May 08 21:09:57 minerva volumio[968]: verbose: ControllerMpd::parseTrackInfo May 08 21:09:57 minerva volumio[968]: info: ControllerMpd::pushState May 08 21:09:57 minerva volumio[968]: info: CoreCommandRouter::servicePushState May 08 21:09:57 minerva volumio[968]: info: CorePlayQueue::getTrack 0 May 08 21:09:57 minerva volumio[968]: verbose: STATE SERVICE {"status":"play","position":0,"seek":26233,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"dem Untertan-Sein?\" Johannes Kaup im Gespräch mit der ...","artist":null,"album":null,"uri":"https://orf-live.ors-shoutcast.at/oe1-q2a","trackType":"at/oe1-q2a"} May 08 21:09:57 minerva volumio[968]: verbose: CURRENT POSITION 0 May 08 21:09:57 minerva volumio[968]: info: CoreStateMachine::syncState stateService play May 08 21:09:57 minerva volumio[968]: info: CoreStateMachine::syncState currentStatus play May 08 21:09:57 minerva volumio[968]: info: Received an update from plugin. extracting info from payload May 08 21:09:57 minerva volumio[968]: info: CoreStateMachine::pushState May 08 21:09:57 minerva volumio[968]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 08 21:09:57 minerva volumio[968]: info: CoreCommandRouter::volumioPushState May 08 21:09:57 minerva volumio[968]: info: CoreStateMachine::pushState May 08 21:09:57 minerva volumio[968]: info: CoreCommandRouter::volumioPushState May 08 21:09:57 minerva volumio[968]: info: ControllerMpd::pushState May 08 21:09:57 minerva volumio[968]: info: CoreCommandRouter::servicePushState May 08 21:09:57 minerva volumio[968]: info: CorePlayQueue::getTrack 0 May 08 21:09:57 minerva volumio[968]: verbose: STATE SERVICE {"status":"play","position":0,"seek":26233,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"dem Untertan-Sein?\" Johannes Kaup im Gespräch mit der ...","artist":null,"album":null,"uri":"https://orf-live.ors-shoutcast.at/oe1-q2a","trackType":"at/oe1-q2a"} May 08 21:09:57 minerva volumio[968]: verbose: CURRENT POSITION 0 May 08 21:09:57 minerva volumio[968]: info: CoreStateMachine::syncState stateService play May 08 21:09:57 minerva volumio[968]: info: CoreStateMachine::syncState currentStatus play May 08 21:09:57 minerva volumio[968]: info: Received an update from plugin. extracting info from payload May 08 21:09:57 minerva volumio[968]: info: CoreStateMachine::pushState May 08 21:09:57 minerva volumio[968]: info: CoreCommandRouter::volumioPushState May 08 21:09:57 minerva volumio[968]: info: CoreStateMachine::pushState May 08 21:09:57 minerva volumio[968]: info: CoreCommandRouter::volumioPushState May 08 21:09:57 minerva volumio[968]: info: ControllerMpd::pushState May 08 21:09:57 minerva volumio[968]: info: CoreCommandRouter::servicePushState May 08 21:09:57 minerva volumio[968]: info: CorePlayQueue::getTrack 0 May 08 21:09:57 minerva volumio[968]: verbose: STATE SERVICE {"status":"play","position":0,"seek":26233,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"dem Untertan-Sein?\" Johannes Kaup im Gespräch mit der ...","artist":null,"album":null,"uri":"https://orf-live.ors-shoutcast.at/oe1-q2a","trackType":"at/oe1-q2a"} May 08 21:09:57 minerva volumio[968]: verbose: CURRENT POSITION 0 May 08 21:09:57 minerva volumio[968]: info: CoreStateMachine::syncState stateService play May 08 21:09:57 minerva volumio[968]: info: CoreStateMachine::syncState currentStatus play May 08 21:09:57 minerva volumio[968]: info: Received an update from plugin. extracting info from payload May 08 21:09:57 minerva volumio[968]: info: CoreStateMachine::pushState May 08 21:09:57 minerva volumio[968]: info: CoreCommandRouter::volumioPushState May 08 21:09:57 minerva volumio[968]: info: CoreStateMachine::pushState May 08 21:09:57 minerva volumio[968]: info: CoreCommandRouter::volumioPushState May 08 21:09:57 minerva volumio[968]: info: ------------------------------ 121ms May 08 21:09:57 minerva volumio[968]: info: ------------------------------ 119ms May 08 21:09:57 minerva volumio[968]: info: ------------------------------ 117ms May 08 21:09:58 minerva volumio[968]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 May 08 21:09:58 minerva volumio[968]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 May 08 21:09:58 minerva volumio[968]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 May 08 21:09:58 minerva volumio[968]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 May 08 21:09:58 minerva volumio[968]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 May 08 21:09:58 minerva volumio[968]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 May 08 21:10:09 minerva volumio[968]: info: [ROTARYENCODER2] Push Button 2 pressed. May 08 21:10:09 minerva volumio[968]: info: [ROTARYENCODER2] Push Button 2 starting timers. May 08 21:10:10 minerva volumio[968]: info: [ROTARYENCODER2] Push Button 2 doublepush timer elapsed. (true, 1) May 08 21:10:11 minerva volumio[968]: info: [ROTARYENCODER2] Push Button 2 longpush timer elapsed. (true, 1) May 08 21:10:11 minerva volumio[968]: info: [ROTARYENCODER2] Push Button 2 sending long push command. May 08 21:10:11 minerva volumio[968]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 08 21:10:11 minerva volumio[968]: SyntaxError: Unexpected token a in JSON at position 0 May 08 21:10:11 minerva volumio[968]: at JSON.parse () May 08 21:10:11 minerva volumio[968]: at rotaryencoder2.emitPushCommand (/data/plugins/system_hardware/rotaryencoder2/index.js:751:17) May 08 21:10:11 minerva volumio[968]: at Timeout._onTimeout (/data/plugins/system_hardware/rotaryencoder2/index.js:553:18) May 08 21:10:11 minerva volumio[968]: at listOnTimeout (internal/timers.js:554:17) May 08 21:10:11 minerva volumio[968]: at processTimers (internal/timers.js:497:7) May 08 21:10:11 minerva volumio[968]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 08 21:10:11 minerva sudo[1891]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-08 21:09 May 08 21:10:11 minerva sudo[1891]: 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="e7445fab547cd22e23d9bef72406cfb610bc1d7d" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:10:14 PM CEST" VOLUMIO_VERSION="3.661" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5bdefd4af4423fd17aacb83a8538bf1f"