-- 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"