-- Logs begin at Thu 2019-02-14 05:12:00 EST, end at Fri 2026-08-28 20:17:25 EDT. -- Aug 28 20:16:00 volumio volumio[1090]: info: Starting Streaming Service Transparent Proxy Aug 28 20:16:00 volumio volumio[1090]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Aug 28 20:16:00 volumio volumio[1090]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Aug 28 20:16:00 volumio volumio[1090]: info: Streaming services startup Aug 28 20:16:00 volumio volumio[1090]: info: Starting Streaming Daemon Aug 28 20:16:00 volumio sudo[2450]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 28 20:16:00 volumio sudo[2450]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 20:16:00 volumio volumio[1090]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Aug 28 20:16:00 volumio sudo[2450]: pam_unix(sudo:session): session closed for user root Aug 28 20:16:00 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 28 20:16:00 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 20:16:00 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 28 20:16:01 volumio volumio[1090]: info: Received Get System Info Aug 28 20:16:01 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 20:16:01 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 20:16:01 volumio volumio[1090]: info: Discovery: Getting this device information Aug 28 20:16:01 volumio volumio[1090]: info: CoreCommandRouter::volumioGetState Aug 28 20:16:01 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:01 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 20:16:01 volumio volumio[1090]: error: Cannot start Volumio Streaming Daemon Aug 28 20:16:01 volumio volumio[1090]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 28 20:16:01 volumio volumio[1090]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 28 20:16:01 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 28 20:16:01 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 28 20:16:01 volumio volumio-remote-updater[639]: No test mode Aug 28 20:16:01 volumio volumio-remote-updater[639]: No alpha test mode Aug 28 20:16:01 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Aug 28 20:16:01 volumio volumio[1090]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Aug 28 20:16:01 volumio volumio[1304]: Generating RSA private key, 4096 bit long modulus (2 primes) Aug 28 20:16:01 volumio volumio[1090]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Aug 28 20:16:01 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Aug 28 20:16:02 volumio volumio5-onboarding[1337]: time=2026-08-28T20:16:02.045-04:00 level=INFO msg="service successfully established" component=discovery/localnet Aug 28 20:16:02 volumio volumio[1090]: info: MyVolumio token set successfully Aug 28 20:16:02 volumio volumio[1090]: info: MYVOLUMIO: Adding device Aug 28 20:16:02 volumio volumio[1090]: info: MYVOLUMIO: Evaluating Server Aug 28 20:16:03 volumio volumio[1090]: STREAMING PROXY: Starting server on port 3245 Aug 28 20:16:03 volumio volumio[1090]: Node JS runtime: 14 Aug 28 20:16:03 volumio wpa_supplicant[915]: wlan0: Failed to initiate sched scan Aug 28 20:16:03 volumio volumio[1090]: info: MyVolumio status changed Aug 28 20:16:03 volumio volumio[1090]: info: Streaming services startup Aug 28 20:16:03 volumio volumio[1090]: info: Starting Streaming Daemon Aug 28 20:16:03 volumio volumio[1090]: info: Removing browser output: myVolumio user plan is not superstar Aug 28 20:16:03 volumio volumio[1090]: info: Removing audio output: Aug 28 20:16:03 volumio volumio[1090]: info: Stoppping Tunnel 1 Aug 28 20:16:03 volumio sudo[2552]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 28 20:16:03 volumio sudo[2552]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 20:16:03 volumio sudo[2555]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Aug 28 20:16:03 volumio sudo[2555]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 20:16:03 volumio sudo[2552]: pam_unix(sudo:session): session closed for user root Aug 28 20:16:03 volumio volumio[1090]: info: Setting Geolocation for MyVolumio to us4 Aug 28 20:16:03 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 20:16:03 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 20:16:03 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 20:16:03 volumio sudo[2555]: pam_unix(sudo:session): session closed for user root Aug 28 20:16:03 volumio volumio[1090]: error: Cannot start Volumio Streaming Daemon Aug 28 20:16:03 volumio volumio[1090]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 28 20:16:03 volumio volumio[1090]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 28 20:16:03 volumio volumio[1090]: info: Remote SSH Stopped Aug 28 20:16:03 volumio volumio[1090]: info: Received Get System Info Aug 28 20:16:03 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 20:16:03 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 20:16:03 volumio volumio[1090]: info: Discovery: Getting this device information Aug 28 20:16:03 volumio volumio[1090]: info: CoreCommandRouter::volumioGetState Aug 28 20:16:03 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:03 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 20:16:03 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 28 20:16:03 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 28 20:16:04 volumio volumio[1090]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Aug 28 20:16:04 volumio volumio[1090]: info: Updating MyVolumio device info Aug 28 20:16:04 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 20:16:04 volumio volumio5-onboarding[1337]: time=2026-08-28T20:16:04.847-04:00 level=INFO msg="service successfully established" component=discovery/localnet Aug 28 20:16:04 volumio volumio[1090]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Aug 28 20:16:08 volumio volumio[1090]: info: MYVOLUMIO: Adding device Aug 28 20:16:08 volumio volumio[1090]: info: MYVOLUMIO: Evaluating Server Aug 28 20:16:08 volumio wpa_supplicant[915]: wlan0: Failed to initiate sched scan Aug 28 20:16:09 volumio volumio[1090]: info: Received Get System Info Aug 28 20:16:09 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 20:16:09 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 20:16:09 volumio volumio[1090]: info: Discovery: Getting this device information Aug 28 20:16:09 volumio volumio[1090]: info: CoreCommandRouter::volumioGetState Aug 28 20:16:09 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:09 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 20:16:09 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 28 20:16:09 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 28 20:16:10 volumio volumio5-onboarding[1337]: time=2026-08-28T20:16:10.364-04:00 level=INFO msg="service successfully established" component=discovery/localnet Aug 28 20:16:10 volumio volumio[1090]: info: Setting Geolocation for MyVolumio to us4 Aug 28 20:16:10 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 20:16:11 volumio volumio[1090]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Aug 28 20:16:11 volumio volumio[1090]: info: Updating MyVolumio device info Aug 28 20:16:11 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 20:16:11 volumio volumio[1090]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Aug 28 20:16:12 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 28 20:16:12 volumio volumio[1090]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Aug 28 20:16:12 volumio volumio[1090]: info: CoreCommandRouter::volumioGetState Aug 28 20:16:12 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:14 volumio wpa_supplicant[915]: wlan0: Failed to initiate sched scan Aug 28 20:16:14 volumio volumio[1090]: info: Received Get System Info Aug 28 20:16:14 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 20:16:14 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 20:16:14 volumio volumio[1090]: info: Discovery: Getting this device information Aug 28 20:16:14 volumio volumio[1090]: info: CoreCommandRouter::volumioGetState Aug 28 20:16:14 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:14 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 20:16:14 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 28 20:16:14 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 28 20:16:15 volumio volumio5-onboarding[1337]: time=2026-08-28T20:16:15.816-04:00 level=INFO msg="service successfully established" component=discovery/localnet Aug 28 20:16:17 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 20:16:17 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Aug 28 20:16:17 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Aug 28 20:16:17 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Aug 28 20:16:17 volumio volumio[1090]: info: CoreCommandRouter::volumioGetBrowseSources Aug 28 20:16:17 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 28 20:16:17 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: 80s80s , handleBrowseUri Aug 28 20:16:17 volumio volumio[1090]: info: [1787962577671] [80s80s] handleBrowseUri curUri: 80s80s Aug 28 20:16:17 volumio volumio[1090]: info: Preload queue cleared Aug 28 20:16:20 volumio wpa_supplicant[915]: wlan0: Failed to initiate sched scan Aug 28 20:16:20 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: 80s80s , handleBrowseUri Aug 28 20:16:20 volumio volumio[1090]: info: [1787962580255] [80s80s] handleBrowseUri curUri: 80s80s/eighties Aug 28 20:16:20 volumio volumio[1090]: info: [1787962580257] [80s80s] getRadioContent url: eighties Aug 28 20:16:20 volumio volumio[1090]: info: Preload queue cleared Aug 28 20:16:20 volumio volumio[1090]: info: Received Get System Info Aug 28 20:16:20 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 20:16:20 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 20:16:20 volumio volumio[1090]: info: Discovery: Getting this device information Aug 28 20:16:20 volumio volumio[1090]: info: CoreCommandRouter::volumioGetState Aug 28 20:16:20 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:20 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 20:16:20 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 28 20:16:20 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 28 20:16:20 volumio volumio[1090]: error: MyVolumio Plugin failed to authenticate in a timely fashion Aug 28 20:16:20 volumio volumio[1090]: info: Completed starting MyVolumio Plugin Aug 28 20:16:20 volumio volumio[1090]: [Metrics] CommandRouter: 92s 445.84ms Aug 28 20:16:20 volumio volumio[1090]: info: CoreCommandRouter::volumiosetStartupVolume Aug 28 20:16:20 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 28 20:16:20 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 28 20:16:20 volumio volumio[1090]: info: CoreCommandRouter::Close All Modals sent Aug 28 20:16:20 volumio volumio[1090]: info: CoreCommandRouter::Close All Modals sent Aug 28 20:16:21 volumio volumio[1090]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Aug 28 20:16:21 volumio volumio[1090]: info: FusionDsp - ---- read samplerate from file: 44100 Aug 28 20:16:21 volumio volumio[1090]: info: camilladsp stopping service pid 3745... Aug 28 20:16:21 volumio volumio[1090]: info: camilladsp service terminated, instance 1 Aug 28 20:16:21 volumio volumio[1090]: info: FusionDsp - If filter freq >samplerate/2 then disable it Aug 28 20:16:21 volumio volumio[1090]: info: camilladsp service started and running in background, instance 1 Aug 28 20:16:21 volumio volumio5-onboarding[1337]: time=2026-08-28T20:16:21.333-04:00 level=INFO msg="service successfully established" component=discovery/localnet Aug 28 20:16:21 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Aug 28 20:16:21 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 28 20:16:22 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Aug 28 20:16:22 volumio volumio[1090]: info: Preload queue cleared Aug 28 20:16:22 volumio volumio[1090]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 28 20:16:22 volumio volumio[1090]: info: CoreStateMachine::ClearQueue Aug 28 20:16:22 volumio volumio[1090]: info: CoreStateMachine::stop Aug 28 20:16:22 volumio volumio[1090]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 28 20:16:22 volumio volumio[1090]: info: CorePlayQueue::clearPlayQueue Aug 28 20:16:22 volumio volumio[1090]: info: CorePlayQueue::saveQueue Aug 28 20:16:22 volumio volumio[1090]: info: CoreCommandRouter::volumioPushQueue Aug 28 20:16:22 volumio volumio[1090]: info: CoreStateMachine::addQueueItems Aug 28 20:16:22 volumio volumio[1090]: info: CorePlayQueue::addQueueItems Aug 28 20:16:22 volumio volumio[1090]: info: Preload queue cleared Aug 28 20:16:22 volumio volumio[1090]: info: Adding Item to queue: webeighties/0 Aug 28 20:16:22 volumio volumio[1090]: info: Exploding uri webeighties/0 in service 80s80s Aug 28 20:16:22 volumio volumio[1090]: info: [1787962582210] [80s80s] explodeUri: webeighties/0 Aug 28 20:16:22 volumio volumio[1090]: info: CoreCommandRouter::volumioPushQueue Aug 28 20:16:22 volumio volumio[1090]: info: CorePlayQueue::saveQueue Aug 28 20:16:22 volumio volumio[1090]: info: CoreStateMachine::updateTrackBlock Aug 28 20:16:22 volumio volumio[1090]: info: CorePlayQueue::getTrackBlock Aug 28 20:16:22 volumio volumio[1090]: info: CoreCommandRouter::volumioPlay Aug 28 20:16:22 volumio volumio[1090]: info: CoreStateMachine::play index 0 Aug 28 20:16:22 volumio volumio[1090]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 28 20:16:22 volumio volumio[1090]: info: CoreStateMachine::stop Aug 28 20:16:22 volumio volumio[1090]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 28 20:16:22 volumio volumio[1090]: info: CoreStateMachine::play index undefined Aug 28 20:16:22 volumio volumio[1090]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 28 20:16:22 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:22 volumio volumio[1090]: info: CoreStateMachine::startPlaybackTimer Aug 28 20:16:22 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:22 volumio volumio[1090]: info: [1787962582264] [80s80s] clearAddPlayTrack url: http://streams.80s80s.de/web/mp3-192/volumio Aug 28 20:16:22 volumio volumio[1090]: info: [1787962582266] [80s80s] getContentOfUrl started with url http://iris-80s80s.loverad.io/flow.json?station=62&count=2 Aug 28 20:16:22 volumio volumio[1090]: info: Preload queue cleared Aug 28 20:16:22 volumio volumio[1090]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 28 20:16:22 volumio volumio[1090]: info: CoreStateMachine::ClearQueue Aug 28 20:16:22 volumio volumio[1090]: info: CoreStateMachine::stop Aug 28 20:16:22 volumio volumio[1090]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 28 20:16:22 volumio volumio[1090]: info: CorePlayQueue::clearPlayQueue Aug 28 20:16:22 volumio volumio[1090]: info: CorePlayQueue::saveQueue Aug 28 20:16:22 volumio volumio[1090]: info: CoreCommandRouter::volumioPushQueue Aug 28 20:16:22 volumio volumio[1090]: info: CoreStateMachine::addQueueItems Aug 28 20:16:22 volumio volumio[1090]: info: CorePlayQueue::addQueueItems Aug 28 20:16:22 volumio volumio[1090]: info: Preload queue cleared Aug 28 20:16:22 volumio volumio[1090]: info: Adding Item to queue: webeighties/0 Aug 28 20:16:22 volumio volumio[1090]: info: Using cached record of: webeighties/0 Aug 28 20:16:22 volumio volumio[1090]: info: CoreCommandRouter::volumioPushQueue Aug 28 20:16:22 volumio volumio[1090]: info: CorePlayQueue::saveQueue Aug 28 20:16:22 volumio volumio[1090]: info: CoreStateMachine::updateTrackBlock Aug 28 20:16:22 volumio volumio[1090]: info: CorePlayQueue::getTrackBlock Aug 28 20:16:22 volumio volumio[1090]: info: CoreCommandRouter::volumioPlay Aug 28 20:16:22 volumio volumio[1090]: info: CoreStateMachine::play index 0 Aug 28 20:16:22 volumio volumio[1090]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 28 20:16:22 volumio volumio[1090]: info: CoreStateMachine::stop Aug 28 20:16:22 volumio volumio[1090]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 28 20:16:22 volumio volumio[1090]: info: CoreStateMachine::play index undefined Aug 28 20:16:22 volumio volumio[1090]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 28 20:16:22 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:22 volumio volumio[1090]: info: CoreStateMachine::startPlaybackTimer Aug 28 20:16:22 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:22 volumio volumio[1090]: info: [1787962582703] [80s80s] clearAddPlayTrack url: http://streams.80s80s.de/web/mp3-192/volumio Aug 28 20:16:22 volumio volumio[1090]: info: [1787962582704] [80s80s] getContentOfUrl started with url http://iris-80s80s.loverad.io/flow.json?station=62&count=2 Aug 28 20:16:22 volumio volumio[1090]: info: [1787962582717] [80s80s] received new event containing 2 songs. Aug 28 20:16:22 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand stop Aug 28 20:16:22 volumio volumio[1090]: info: sendMpdCommand stop took 27 milliseconds Aug 28 20:16:22 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand clear Aug 28 20:16:22 volumio volumio[1090]: info: Aug 28 20:16:22 volumio volumio[1090]: ---------------------------- MPD announces system playlist update Aug 28 20:16:22 volumio volumio[1090]: info: Ignoring MPD Status Update Aug 28 20:16:22 volumio volumio[1090]: info: sendMpdCommand clear took 51 milliseconds Aug 28 20:16:22 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand consume 1 Aug 28 20:16:22 volumio volumio[1090]: info: Aug 28 20:16:22 volumio volumio[1090]: ---------------------------- MPD announces system playlist update Aug 28 20:16:22 volumio volumio[1090]: info: Ignoring MPD Status Update Aug 28 20:16:22 volumio volumio[1090]: info: Aug 28 20:16:22 volumio volumio[1090]: ---------------------------- MPD announces system playlist update Aug 28 20:16:22 volumio volumio[1090]: info: Ignoring MPD Status Update Aug 28 20:16:22 volumio volumio[1090]: info: Aug 28 20:16:22 volumio volumio[1090]: ---------------------------- MPD announces system playlist update Aug 28 20:16:22 volumio volumio[1090]: info: Ignoring MPD Status Update Aug 28 20:16:22 volumio volumio[1090]: info: Aug 28 20:16:22 volumio volumio[1090]: ---------------------------- MPD announces system playlist update Aug 28 20:16:22 volumio volumio[1090]: info: Ignoring MPD Status Update Aug 28 20:16:22 volumio volumio[1090]: info: Aug 28 20:16:22 volumio volumio[1090]: ---------------------------- MPD announces system playlist update Aug 28 20:16:22 volumio volumio[1090]: info: Ignoring MPD Status Update Aug 28 20:16:22 volumio volumio[1090]: info: Aug 28 20:16:22 volumio volumio[1090]: ---------------------------- MPD announces system playlist update Aug 28 20:16:22 volumio volumio[1090]: info: Ignoring MPD Status Update Aug 28 20:16:22 volumio volumio[1090]: info: Aug 28 20:16:22 volumio volumio[1090]: ---------------------------- MPD announces system playlist update Aug 28 20:16:22 volumio volumio[1090]: info: Ignoring MPD Status Update Aug 28 20:16:22 volumio volumio[1090]: info: Aug 28 20:16:22 volumio volumio[1090]: ---------------------------- MPD announces system playlist update Aug 28 20:16:22 volumio volumio[1090]: info: Ignoring MPD Status Update Aug 28 20:16:22 volumio volumio[1090]: error: updateQueue error: null Aug 28 20:16:22 volumio volumio[1090]: info: Aug 28 20:16:22 volumio volumio[1090]: ---------------------------- MPD announces state update: options Aug 28 20:16:22 volumio volumio[1090]: error: updateQueue error: null Aug 28 20:16:22 volumio volumio[1090]: error: updateQueue error: null Aug 28 20:16:22 volumio volumio[1090]: error: updateQueue error: null Aug 28 20:16:22 volumio volumio[1090]: error: updateQueue error: null Aug 28 20:16:22 volumio volumio[1090]: error: updateQueue error: null Aug 28 20:16:22 volumio volumio[1090]: error: updateQueue error: null Aug 28 20:16:22 volumio volumio[1090]: error: updateQueue error: null Aug 28 20:16:22 volumio volumio[1090]: error: updateQueue error: null Aug 28 20:16:22 volumio volumio[1090]: info: ------------------------------ 43ms Aug 28 20:16:22 volumio volumio[1090]: info: sendMpdCommand consume 1 took 37 milliseconds Aug 28 20:16:22 volumio volumio[1090]: info: ControllerMpd::getState Aug 28 20:16:22 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand status Aug 28 20:16:22 volumio volumio[1090]: info: ------------------------------ 37ms Aug 28 20:16:22 volumio volumio[1090]: info: ------------------------------ 35ms Aug 28 20:16:22 volumio volumio[1090]: info: ------------------------------ 32ms Aug 28 20:16:22 volumio volumio[1090]: info: ------------------------------ 30ms Aug 28 20:16:22 volumio volumio[1090]: info: ------------------------------ 28ms Aug 28 20:16:22 volumio volumio[1090]: info: ------------------------------ 26ms Aug 28 20:16:22 volumio volumio[1090]: info: ------------------------------ 25ms Aug 28 20:16:22 volumio volumio[1090]: info: ------------------------------ 22ms Aug 28 20:16:22 volumio volumio[1090]: info: [1787962582846] [80s80s] adding url: http://streams.80s80s.de/web/mp3-192/volumio Aug 28 20:16:22 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand add "http://streams.80s80s.de/web/mp3-192/volumio" Aug 28 20:16:22 volumio volumio[1090]: info: Aug 28 20:16:22 volumio volumio[1090]: ---------------------------- MPD announces state update: options Aug 28 20:16:22 volumio volumio[1090]: info: ControllerMpd::getState Aug 28 20:16:22 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand status Aug 28 20:16:22 volumio volumio[1090]: info: Aug 28 20:16:22 volumio volumio[1090]: ---------------------------- MPD announces state update: options Aug 28 20:16:22 volumio volumio[1090]: info: ControllerMpd::getState Aug 28 20:16:22 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand status Aug 28 20:16:22 volumio volumio[1090]: info: Aug 28 20:16:22 volumio volumio[1090]: ---------------------------- MPD announces state update: options Aug 28 20:16:22 volumio volumio[1090]: info: ControllerMpd::getState Aug 28 20:16:22 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand status Aug 28 20:16:22 volumio volumio[1090]: info: Aug 28 20:16:22 volumio volumio[1090]: ---------------------------- MPD announces state update: options Aug 28 20:16:22 volumio volumio[1090]: info: ControllerMpd::getState Aug 28 20:16:22 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand status Aug 28 20:16:22 volumio volumio[1090]: info: Aug 28 20:16:22 volumio volumio[1090]: ---------------------------- MPD announces state update: options Aug 28 20:16:22 volumio volumio[1090]: info: ControllerMpd::getState Aug 28 20:16:22 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand status Aug 28 20:16:22 volumio volumio[1090]: info: Aug 28 20:16:22 volumio volumio[1090]: ---------------------------- MPD announces state update: options Aug 28 20:16:22 volumio volumio[1090]: info: ControllerMpd::getState Aug 28 20:16:22 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand status Aug 28 20:16:22 volumio volumio[1090]: info: Aug 28 20:16:22 volumio volumio[1090]: ---------------------------- MPD announces state update: options Aug 28 20:16:22 volumio volumio[1090]: info: ControllerMpd::getState Aug 28 20:16:22 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand status Aug 28 20:16:22 volumio volumio[1090]: info: Aug 28 20:16:22 volumio volumio[1090]: ---------------------------- MPD announces state update: options Aug 28 20:16:22 volumio volumio[1090]: info: ControllerMpd::getState Aug 28 20:16:22 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand status Aug 28 20:16:22 volumio volumio[1090]: info: Aug 28 20:16:22 volumio volumio[1090]: ---------------------------- MPD announces system playlist update Aug 28 20:16:22 volumio volumio[1090]: info: Ignoring MPD Status Update Aug 28 20:16:22 volumio volumio[1090]: info: sendMpdCommand status took 42 milliseconds Aug 28 20:16:22 volumio volumio[1090]: info: sendMpdCommand add "http://streams.80s80s.de/web/mp3-192/volumio" took 38 milliseconds Aug 28 20:16:22 volumio volumio[1090]: info: sendMpdCommand status took 35 milliseconds Aug 28 20:16:22 volumio volumio[1090]: info: sendMpdCommand status took 32 milliseconds Aug 28 20:16:22 volumio volumio[1090]: info: sendMpdCommand status took 30 milliseconds Aug 28 20:16:22 volumio volumio[1090]: info: sendMpdCommand status took 27 milliseconds Aug 28 20:16:22 volumio volumio[1090]: info: sendMpdCommand status took 24 milliseconds Aug 28 20:16:22 volumio volumio[1090]: info: sendMpdCommand status took 20 milliseconds Aug 28 20:16:22 volumio volumio[1090]: info: sendMpdCommand status took 18 milliseconds Aug 28 20:16:22 volumio volumio[1090]: info: sendMpdCommand status took 23 milliseconds Aug 28 20:16:22 volumio volumio[1090]: verbose: ControllerMpd::parseState Aug 28 20:16:22 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand play Aug 28 20:16:22 volumio volumio[1090]: verbose: ControllerMpd::parseState Aug 28 20:16:22 volumio volumio[1090]: verbose: ControllerMpd::parseState Aug 28 20:16:22 volumio volumio[1090]: verbose: ControllerMpd::parseState Aug 28 20:16:22 volumio volumio[1090]: verbose: ControllerMpd::parseState Aug 28 20:16:22 volumio volumio[1090]: verbose: ControllerMpd::parseState Aug 28 20:16:22 volumio volumio[1090]: verbose: ControllerMpd::parseState Aug 28 20:16:22 volumio volumio[1090]: verbose: ControllerMpd::parseState Aug 28 20:16:22 volumio volumio[1090]: verbose: ControllerMpd::parseState Aug 28 20:16:22 volumio volumio[1090]: info: ControllerMpd::pushState Aug 28 20:16:22 volumio volumio[1090]: info: CoreCommandRouter::servicePushState Aug 28 20:16:22 volumio volumio[1090]: info: CoreStateMachine::pushState Aug 28 20:16:22 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:22 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 20:16:22 volumio volumio[1090]: info: CoreCommandRouter::volumioPushState Aug 28 20:16:22 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:22 volumio volumio[1090]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Aug 28 20:16:22 volumio volumio[1090]: info: ControllerMpd::pushState Aug 28 20:16:22 volumio volumio[1090]: info: CoreCommandRouter::servicePushState Aug 28 20:16:22 volumio volumio[1090]: info: CoreStateMachine::pushState Aug 28 20:16:22 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:22 volumio volumio[1090]: info: CoreCommandRouter::volumioPushState Aug 28 20:16:23 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:23 volumio volumio[1090]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Aug 28 20:16:23 volumio volumio[1090]: info: ControllerMpd::pushState Aug 28 20:16:23 volumio volumio[1090]: info: CoreCommandRouter::servicePushState Aug 28 20:16:23 volumio volumio[1090]: info: CoreStateMachine::pushState Aug 28 20:16:23 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:23 volumio volumio[1090]: info: CoreCommandRouter::volumioPushState Aug 28 20:16:23 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:23 volumio volumio[1090]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Aug 28 20:16:23 volumio volumio[1090]: info: ControllerMpd::pushState Aug 28 20:16:23 volumio volumio[1090]: info: CoreCommandRouter::servicePushState Aug 28 20:16:23 volumio volumio[1090]: info: CoreStateMachine::pushState Aug 28 20:16:23 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:23 volumio volumio[1090]: info: CoreCommandRouter::volumioPushState Aug 28 20:16:23 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:23 volumio volumio[1090]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Aug 28 20:16:23 volumio volumio[1090]: info: ControllerMpd::pushState Aug 28 20:16:23 volumio volumio[1090]: info: CoreCommandRouter::servicePushState Aug 28 20:16:23 volumio volumio[1090]: info: CoreStateMachine::pushState Aug 28 20:16:23 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:23 volumio volumio[1090]: info: CoreCommandRouter::volumioPushState Aug 28 20:16:23 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:23 volumio volumio[1090]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Aug 28 20:16:23 volumio volumio[1090]: info: ControllerMpd::pushState Aug 28 20:16:23 volumio volumio[1090]: info: CoreCommandRouter::servicePushState Aug 28 20:16:23 volumio volumio[1090]: info: CoreStateMachine::pushState Aug 28 20:16:23 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:23 volumio volumio[1090]: info: CoreCommandRouter::volumioPushState Aug 28 20:16:23 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:23 volumio volumio[1090]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Aug 28 20:16:23 volumio volumio[1090]: info: ControllerMpd::pushState Aug 28 20:16:23 volumio volumio[1090]: info: CoreCommandRouter::servicePushState Aug 28 20:16:23 volumio volumio[1090]: info: CoreStateMachine::pushState Aug 28 20:16:23 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:23 volumio volumio[1090]: info: CoreCommandRouter::volumioPushState Aug 28 20:16:23 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:23 volumio volumio[1090]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Aug 28 20:16:23 volumio volumio[1090]: info: ControllerMpd::pushState Aug 28 20:16:23 volumio volumio[1090]: info: CoreCommandRouter::servicePushState Aug 28 20:16:23 volumio volumio[1090]: info: CoreStateMachine::pushState Aug 28 20:16:23 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:23 volumio volumio[1090]: info: CoreCommandRouter::volumioPushState Aug 28 20:16:23 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:23 volumio volumio[1090]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Aug 28 20:16:23 volumio volumio[1090]: info: ControllerMpd::pushState Aug 28 20:16:23 volumio volumio[1090]: info: CoreCommandRouter::servicePushState Aug 28 20:16:23 volumio volumio[1090]: info: CoreStateMachine::pushState Aug 28 20:16:23 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:23 volumio volumio[1090]: info: CoreCommandRouter::volumioPushState Aug 28 20:16:23 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:23 volumio volumio[1090]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Aug 28 20:16:23 volumio volumio[1090]: info: ------------------------------ 237ms Aug 28 20:16:23 volumio volumio[1090]: info: ------------------------------ 476ms Aug 28 20:16:23 volumio volumio[1090]: info: ------------------------------ 473ms Aug 28 20:16:23 volumio volumio[1090]: info: ------------------------------ 471ms Aug 28 20:16:23 volumio volumio[1090]: info: ------------------------------ 470ms Aug 28 20:16:23 volumio volumio[1090]: info: ------------------------------ 468ms Aug 28 20:16:23 volumio volumio[1090]: info: ------------------------------ 464ms Aug 28 20:16:23 volumio volumio[1090]: info: ------------------------------ 462ms Aug 28 20:16:23 volumio volumio[1090]: info: ------------------------------ 460ms Aug 28 20:16:23 volumio volumio[1090]: info: Aug 28 20:16:23 volumio volumio[1090]: ---------------------------- MPD announces system playlist update Aug 28 20:16:23 volumio volumio[1090]: info: Ignoring MPD Status Update Aug 28 20:16:23 volumio volumio[1090]: info: Aug 28 20:16:23 volumio volumio[1090]: ---------------------------- MPD announces system playlist update Aug 28 20:16:23 volumio volumio[1090]: info: Ignoring MPD Status Update Aug 28 20:16:23 volumio volumio[1090]: info: Aug 28 20:16:23 volumio volumio[1090]: ---------------------------- MPD announces system playlist update Aug 28 20:16:23 volumio volumio[1090]: info: Ignoring MPD Status Update Aug 28 20:16:23 volumio volumio[1090]: info: Aug 28 20:16:23 volumio volumio[1090]: ---------------------------- MPD announces system playlist update Aug 28 20:16:23 volumio volumio[1090]: info: Ignoring MPD Status Update Aug 28 20:16:23 volumio volumio[1090]: info: Aug 28 20:16:23 volumio volumio[1090]: ---------------------------- MPD announces system playlist update Aug 28 20:16:23 volumio volumio[1090]: info: Ignoring MPD Status Update Aug 28 20:16:23 volumio volumio[1090]: info: Aug 28 20:16:23 volumio volumio[1090]: ---------------------------- MPD announces system playlist update Aug 28 20:16:23 volumio volumio[1090]: info: Ignoring MPD Status Update Aug 28 20:16:23 volumio volumio[1090]: info: Aug 28 20:16:23 volumio volumio[1090]: ---------------------------- MPD announces system playlist update Aug 28 20:16:23 volumio volumio[1090]: info: Ignoring MPD Status Update Aug 28 20:16:23 volumio volumio[1090]: info: Aug 28 20:16:23 volumio volumio[1090]: ---------------------------- MPD announces system playlist update Aug 28 20:16:23 volumio volumio[1090]: info: Ignoring MPD Status Update Aug 28 20:16:23 volumio volumio[1090]: info: ------------------------------ 544ms Aug 28 20:16:23 volumio volumio[1090]: info: sendMpdCommand play took 498 milliseconds Aug 28 20:16:23 volumio volumio[1090]: info: ------------------------------ 88ms Aug 28 20:16:23 volumio volumio[1090]: info: ------------------------------ 87ms Aug 28 20:16:23 volumio volumio[1090]: info: ------------------------------ 85ms Aug 28 20:16:23 volumio volumio[1090]: info: ------------------------------ 84ms Aug 28 20:16:23 volumio volumio[1090]: info: ------------------------------ 81ms Aug 28 20:16:23 volumio volumio[1090]: info: ------------------------------ 80ms Aug 28 20:16:23 volumio volumio[1090]: info: ------------------------------ 78ms Aug 28 20:16:23 volumio volumio[1090]: info: ------------------------------ 77ms Aug 28 20:16:23 volumio volumio[1090]: info: [1787962583432] [80s80s] Pushing the next song state: Pat Benatar - Love Is a Battlefield Aug 28 20:16:23 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:23 volumio volumio[1090]: info: CoreCommandRouter::servicePushState Aug 28 20:16:23 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:23 volumio volumio[1090]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is3-ssl.mzstatic.com/image/thumb/Music/v4/5e/db/3c/5edb3c8c-5a2f-6326-0519-02154be17723/source/600x600bb.jpg","name":"Pat Benatar - Love Is a Battlefield","title":"Love Is a Battlefield","artist":"80s80s Real 80s Radio","album":"","streaming":true,"disableUiControls":true,"duration":"176","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Aug 28 20:16:23 volumio volumio[1090]: verbose: CURRENT POSITION 0 Aug 28 20:16:23 volumio volumio[1090]: info: CoreStateMachine::syncState stateService play Aug 28 20:16:23 volumio volumio[1090]: info: CoreStateMachine::syncState currentStatus stop Aug 28 20:16:23 volumio volumio[1090]: info: [1787962583441] [80s80s] PlayNextTrack API delay: 30 Aug 28 20:16:23 volumio volumio[1090]: info: [1787962583483] [80s80s] Setting timer to: 176000 milliseconds. Aug 28 20:16:23 volumio volumio[1090]: info: PeppyMeterBasic ---peppymeterbasic status stop Aug 28 20:16:23 volumio volumio[1090]: info: PeppyMeterBasic ---peppymeterbasic status stop Aug 28 20:16:23 volumio sudo[3856]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Aug 28 20:16:23 volumio sudo[3856]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 20:16:23 volumio volumio[1090]: info: PeppyMeterBasic ---peppymeterbasic status stop Aug 28 20:16:23 volumio sudo[3859]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Aug 28 20:16:23 volumio sudo[3859]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 20:16:23 volumio volumio[1090]: info: PeppyMeterBasic ---peppymeterbasic status stop Aug 28 20:16:23 volumio sudo[3862]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Aug 28 20:16:23 volumio sudo[3862]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 20:16:23 volumio sudo[3856]: pam_unix(sudo:session): session closed for user root Aug 28 20:16:23 volumio sudo[3859]: pam_unix(sudo:session): session closed for user root Aug 28 20:16:23 volumio volumio[1090]: info: PeppyMeterBasic ---peppymeterbasic status stop Aug 28 20:16:23 volumio sudo[3865]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Aug 28 20:16:23 volumio sudo[3865]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 20:16:23 volumio sudo[3862]: pam_unix(sudo:session): session closed for user root Aug 28 20:16:23 volumio volumio[1090]: info: PeppyMeterBasic ---peppymeterbasic status stop Aug 28 20:16:23 volumio volumio[1090]: info: PeppyMeterBasic ---peppymeterbasic status stop Aug 28 20:16:23 volumio sudo[3868]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Aug 28 20:16:23 volumio sudo[3868]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 20:16:23 volumio sudo[3870]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Aug 28 20:16:23 volumio sudo[3870]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 20:16:23 volumio sudo[3865]: pam_unix(sudo:session): session closed for user root Aug 28 20:16:24 volumio volumio[1090]: info: PeppyMeterBasic ---peppymeterbasic status stop Aug 28 20:16:24 volumio sudo[3874]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Aug 28 20:16:24 volumio sudo[3874]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 20:16:24 volumio volumio[1090]: info: PeppyMeterBasic ---peppymeterbasic status stop Aug 28 20:16:24 volumio sudo[3870]: pam_unix(sudo:session): session closed for user root Aug 28 20:16:24 volumio sudo[3868]: pam_unix(sudo:session): session closed for user root Aug 28 20:16:24 volumio sudo[3877]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Aug 28 20:16:24 volumio sudo[3877]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 20:16:24 volumio volumio[1090]: info: PeppySpectrum ---peppyspectrum status stop Aug 28 20:16:24 volumio sudo[3874]: pam_unix(sudo:session): session closed for user root Aug 28 20:16:24 volumio volumio[1090]: info: PeppySpectrum ---peppyspectrum status stop Aug 28 20:16:24 volumio sudo[3880]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Aug 28 20:16:24 volumio sudo[3880]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 20:16:24 volumio sudo[3877]: pam_unix(sudo:session): session closed for user root Aug 28 20:16:24 volumio sudo[3882]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Aug 28 20:16:24 volumio volumio[1090]: info: PeppySpectrum ---peppyspectrum status stop Aug 28 20:16:24 volumio sudo[3882]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 20:16:24 volumio volumio[1090]: info: PeppySpectrum ---peppyspectrum status stop Aug 28 20:16:24 volumio sudo[3880]: pam_unix(sudo:session): session closed for user root Aug 28 20:16:24 volumio sudo[3889]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Aug 28 20:16:24 volumio sudo[3889]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 20:16:24 volumio sudo[3887]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Aug 28 20:16:24 volumio sudo[3887]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 20:16:24 volumio sudo[3882]: pam_unix(sudo:session): session closed for user root Aug 28 20:16:24 volumio volumio[1090]: info: PeppySpectrum ---peppyspectrum status stop Aug 28 20:16:24 volumio sudo[3906]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Aug 28 20:16:24 volumio sudo[3906]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 20:16:24 volumio sudo[3889]: pam_unix(sudo:session): session closed for user root Aug 28 20:16:24 volumio sudo[3887]: pam_unix(sudo:session): session closed for user root Aug 28 20:16:24 volumio volumio[1090]: info: PeppySpectrum ---peppyspectrum status stop Aug 28 20:16:24 volumio volumio[1090]: info: PeppySpectrum ---peppyspectrum status stop Aug 28 20:16:24 volumio sudo[3906]: pam_unix(sudo:session): session closed for user root Aug 28 20:16:24 volumio sudo[3912]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Aug 28 20:16:24 volumio sudo[3910]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Aug 28 20:16:24 volumio sudo[3910]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 20:16:24 volumio sudo[3912]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 20:16:24 volumio volumio[1090]: info: PeppySpectrum ---peppyspectrum status stop Aug 28 20:16:24 volumio volumio[1090]: info: PeppySpectrum ---peppyspectrum status stop Aug 28 20:16:24 volumio sudo[3916]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Aug 28 20:16:24 volumio sudo[3916]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 20:16:24 volumio sudo[3912]: pam_unix(sudo:session): session closed for user root Aug 28 20:16:24 volumio sudo[3918]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Aug 28 20:16:24 volumio sudo[3918]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 20:16:24 volumio sudo[3922]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Aug 28 20:16:24 volumio sudo[3922]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 20:16:24 volumio sudo[3910]: pam_unix(sudo:session): session closed for user root Aug 28 20:16:24 volumio sudo[3916]: pam_unix(sudo:session): session closed for user root Aug 28 20:16:24 volumio sudo[3918]: pam_unix(sudo:session): session closed for user root Aug 28 20:16:25 volumio sudo[3922]: pam_unix(sudo:session): session closed for user root Aug 28 20:16:25 volumio volumio[1090]: info: [1787962585075] [80s80s] received new event containing 2 songs. Aug 28 20:16:25 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand stop Aug 28 20:16:25 volumio volumio[1090]: info: peppyspectrum Daemon Stop Aug 28 20:16:25 volumio volumio[1090]: info: peppyspectrum Daemon Stop Aug 28 20:16:25 volumio volumio[1090]: info: peppyspectrum Daemon Stop Aug 28 20:16:25 volumio volumio[1090]: info: peppyspectrum Daemon Stop Aug 28 20:16:25 volumio volumio[1090]: info: peppymeterbasic Daemon Stop Aug 28 20:16:25 volumio volumio[1090]: info: peppymeterbasic Daemon Stop Aug 28 20:16:25 volumio volumio[1090]: info: peppymeterbasic Daemon Stop Aug 28 20:16:25 volumio volumio[1090]: info: peppymeterbasic Daemon Stop Aug 28 20:16:25 volumio volumio[1090]: info: peppymeterbasic Daemon Stop Aug 28 20:16:25 volumio volumio[1090]: info: peppymeterbasic Daemon Stop Aug 28 20:16:25 volumio volumio[1090]: info: peppymeterbasic Daemon Stop Aug 28 20:16:25 volumio volumio[1090]: info: peppymeterbasic Daemon Stop Aug 28 20:16:25 volumio volumio[1090]: info: peppymeterbasic Daemon Stop Aug 28 20:16:25 volumio volumio[1090]: info: Aug 28 20:16:25 volumio volumio[1090]: ---------------------------- MPD announces state update: player Aug 28 20:16:25 volumio volumio[1090]: info: sendMpdCommand stop took 290 milliseconds Aug 28 20:16:25 volumio volumio[1090]: info: ControllerMpd::getState Aug 28 20:16:25 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand status Aug 28 20:16:25 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand clear Aug 28 20:16:25 volumio volumio[1090]: info: Aug 28 20:16:25 volumio volumio[1090]: ---------------------------- MPD announces state update: player Aug 28 20:16:25 volumio volumio[1090]: info: ControllerMpd::getState Aug 28 20:16:25 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand status Aug 28 20:16:25 volumio volumio[1090]: info: Aug 28 20:16:25 volumio volumio[1090]: ---------------------------- MPD announces state update: player Aug 28 20:16:25 volumio volumio[1090]: info: ControllerMpd::getState Aug 28 20:16:25 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand status Aug 28 20:16:25 volumio volumio[1090]: info: Aug 28 20:16:25 volumio volumio[1090]: ---------------------------- MPD announces state update: player Aug 28 20:16:25 volumio volumio[1090]: info: ControllerMpd::getState Aug 28 20:16:25 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand status Aug 28 20:16:25 volumio volumio[1090]: info: Aug 28 20:16:25 volumio volumio[1090]: ---------------------------- MPD announces state update: player Aug 28 20:16:25 volumio volumio[1090]: info: ControllerMpd::getState Aug 28 20:16:25 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand status Aug 28 20:16:25 volumio volumio[1090]: info: Aug 28 20:16:25 volumio volumio[1090]: ---------------------------- MPD announces state update: player Aug 28 20:16:25 volumio volumio[1090]: info: ControllerMpd::getState Aug 28 20:16:25 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand status Aug 28 20:16:25 volumio volumio[1090]: info: Aug 28 20:16:25 volumio volumio[1090]: ---------------------------- MPD announces state update: player Aug 28 20:16:25 volumio volumio[1090]: info: ControllerMpd::getState Aug 28 20:16:25 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand status Aug 28 20:16:25 volumio volumio[1090]: info: Aug 28 20:16:25 volumio volumio[1090]: ---------------------------- MPD announces state update: player Aug 28 20:16:25 volumio volumio[1090]: info: ControllerMpd::getState Aug 28 20:16:25 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand status Aug 28 20:16:25 volumio volumio[1090]: info: Aug 28 20:16:25 volumio volumio[1090]: ---------------------------- MPD announces state update: player Aug 28 20:16:25 volumio volumio[1090]: info: ControllerMpd::getState Aug 28 20:16:25 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand status Aug 28 20:16:25 volumio volumio[1090]: info: Aug 28 20:16:25 volumio volumio[1090]: ---------------------------- MPD announces system playlist update Aug 28 20:16:25 volumio volumio[1090]: info: Ignoring MPD Status Update Aug 28 20:16:25 volumio wpa_supplicant[915]: wlan0: Failed to initiate sched scan Aug 28 20:16:25 volumio volumio[1090]: info: sendMpdCommand status took 209 milliseconds Aug 28 20:16:25 volumio volumio[1090]: info: sendMpdCommand clear took 210 milliseconds Aug 28 20:16:25 volumio volumio[1090]: info: sendMpdCommand status took 208 milliseconds Aug 28 20:16:25 volumio volumio[1090]: info: sendMpdCommand status took 186 milliseconds Aug 28 20:16:25 volumio volumio[1090]: info: sendMpdCommand status took 184 milliseconds Aug 28 20:16:25 volumio volumio[1090]: info: sendMpdCommand status took 161 milliseconds Aug 28 20:16:25 volumio volumio[1090]: info: sendMpdCommand status took 143 milliseconds Aug 28 20:16:25 volumio volumio[1090]: info: sendMpdCommand status took 132 milliseconds Aug 28 20:16:25 volumio volumio[1090]: info: sendMpdCommand status took 124 milliseconds Aug 28 20:16:25 volumio volumio[1090]: info: sendMpdCommand status took 106 milliseconds Aug 28 20:16:25 volumio volumio[1090]: verbose: ControllerMpd::parseState Aug 28 20:16:25 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 28 20:16:25 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand consume 1 Aug 28 20:16:25 volumio volumio[1090]: verbose: ControllerMpd::parseState Aug 28 20:16:25 volumio volumio[1090]: verbose: ControllerMpd::parseState Aug 28 20:16:25 volumio volumio[1090]: verbose: ControllerMpd::parseState Aug 28 20:16:25 volumio volumio[1090]: verbose: ControllerMpd::parseState Aug 28 20:16:25 volumio volumio[1090]: verbose: ControllerMpd::parseState Aug 28 20:16:25 volumio volumio[1090]: verbose: ControllerMpd::parseState Aug 28 20:16:25 volumio volumio[1090]: verbose: ControllerMpd::parseState Aug 28 20:16:25 volumio volumio[1090]: verbose: ControllerMpd::parseState Aug 28 20:16:25 volumio volumio[1090]: info: ControllerMpd::pushState Aug 28 20:16:25 volumio volumio[1090]: info: CoreCommandRouter::servicePushState Aug 28 20:16:25 volumio volumio[1090]: info: CoreStateMachine::pushState Aug 28 20:16:25 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:25 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 20:16:25 volumio volumio[1090]: info: CoreCommandRouter::volumioPushState Aug 28 20:16:25 volumio volumio[1090]: info: ControllerMpd::pushState Aug 28 20:16:25 volumio volumio[1090]: info: CoreCommandRouter::servicePushState Aug 28 20:16:25 volumio volumio[1090]: info: CoreStateMachine::pushState Aug 28 20:16:25 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:25 volumio volumio[1090]: info: CoreCommandRouter::volumioPushState Aug 28 20:16:25 volumio volumio[1090]: info: ControllerMpd::pushState Aug 28 20:16:25 volumio volumio[1090]: info: CoreCommandRouter::servicePushState Aug 28 20:16:25 volumio volumio[1090]: info: CoreStateMachine::pushState Aug 28 20:16:25 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:25 volumio volumio[1090]: info: CoreCommandRouter::volumioPushState Aug 28 20:16:25 volumio volumio[1090]: info: ControllerMpd::pushState Aug 28 20:16:25 volumio volumio[1090]: info: CoreCommandRouter::servicePushState Aug 28 20:16:25 volumio volumio[1090]: info: CoreStateMachine::pushState Aug 28 20:16:25 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:25 volumio volumio[1090]: info: CoreCommandRouter::volumioPushState Aug 28 20:16:25 volumio volumio[1090]: info: ControllerMpd::pushState Aug 28 20:16:25 volumio volumio[1090]: info: CoreCommandRouter::servicePushState Aug 28 20:16:25 volumio volumio[1090]: info: CoreStateMachine::pushState Aug 28 20:16:25 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:25 volumio volumio[1090]: info: CoreCommandRouter::volumioPushState Aug 28 20:16:25 volumio volumio[1090]: info: ControllerMpd::pushState Aug 28 20:16:25 volumio volumio[1090]: info: CoreCommandRouter::servicePushState Aug 28 20:16:25 volumio volumio[1090]: info: CoreStateMachine::pushState Aug 28 20:16:25 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:25 volumio volumio[1090]: info: CoreCommandRouter::volumioPushState Aug 28 20:16:25 volumio volumio[1090]: info: ControllerMpd::pushState Aug 28 20:16:25 volumio volumio[1090]: info: CoreCommandRouter::servicePushState Aug 28 20:16:25 volumio volumio[1090]: info: CoreStateMachine::pushState Aug 28 20:16:25 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:25 volumio volumio[1090]: info: CoreCommandRouter::volumioPushState Aug 28 20:16:25 volumio volumio[1090]: info: ControllerMpd::pushState Aug 28 20:16:25 volumio volumio[1090]: info: CoreCommandRouter::servicePushState Aug 28 20:16:25 volumio volumio[1090]: info: CoreStateMachine::pushState Aug 28 20:16:25 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:25 volumio volumio[1090]: info: CoreCommandRouter::volumioPushState Aug 28 20:16:26 volumio volumio[1090]: error: ControllerMpd::pushError: Error: Failed to convert napi value String into rust type `f64` on TimelineInfo.duration Aug 28 20:16:26 volumio volumio[1090]: error: ControllerMpd::pushError: Error: Failed to convert napi value String into rust type `f64` on TimelineInfo.duration Aug 28 20:16:26 volumio volumio[1090]: error: ControllerMpd::pushError: Error: Failed to convert napi value String into rust type `f64` on TimelineInfo.duration Aug 28 20:16:26 volumio volumio[1090]: error: ControllerMpd::pushError: Error: Failed to convert napi value String into rust type `f64` on TimelineInfo.duration Aug 28 20:16:26 volumio volumio[1090]: error: ControllerMpd::pushError: Error: Failed to convert napi value String into rust type `f64` on TimelineInfo.duration Aug 28 20:16:26 volumio volumio[1090]: error: ControllerMpd::pushError: Error: Failed to convert napi value String into rust type `f64` on TimelineInfo.duration Aug 28 20:16:26 volumio volumio[1090]: info: ------------------------------ 728ms Aug 28 20:16:26 volumio volumio[1090]: info: ------------------------------ 705ms Aug 28 20:16:26 volumio volumio[1090]: info: ------------------------------ 702ms Aug 28 20:16:26 volumio volumio[1090]: info: ------------------------------ 699ms Aug 28 20:16:26 volumio volumio[1090]: info: ------------------------------ 675ms Aug 28 20:16:26 volumio volumio[1090]: info: ------------------------------ 656ms Aug 28 20:16:26 volumio volumio[1090]: info: ------------------------------ 644ms Aug 28 20:16:26 volumio volumio[1090]: info: ------------------------------ 635ms Aug 28 20:16:26 volumio volumio[1090]: info: Aug 28 20:16:26 volumio volumio[1090]: ---------------------------- MPD announces system playlist update Aug 28 20:16:26 volumio volumio[1090]: info: Ignoring MPD Status Update Aug 28 20:16:26 volumio volumio[1090]: info: Aug 28 20:16:26 volumio volumio[1090]: ---------------------------- MPD announces system playlist update Aug 28 20:16:26 volumio volumio[1090]: info: Ignoring MPD Status Update Aug 28 20:16:26 volumio volumio[1090]: info: Aug 28 20:16:26 volumio volumio[1090]: ---------------------------- MPD announces system playlist update Aug 28 20:16:26 volumio volumio[1090]: info: Ignoring MPD Status Update Aug 28 20:16:26 volumio volumio[1090]: info: Aug 28 20:16:26 volumio volumio[1090]: ---------------------------- MPD announces system playlist update Aug 28 20:16:26 volumio volumio[1090]: info: Ignoring MPD Status Update Aug 28 20:16:26 volumio volumio[1090]: info: Aug 28 20:16:26 volumio volumio[1090]: ---------------------------- MPD announces system playlist update Aug 28 20:16:26 volumio volumio[1090]: info: Ignoring MPD Status Update Aug 28 20:16:26 volumio volumio[1090]: info: Aug 28 20:16:26 volumio volumio[1090]: ---------------------------- MPD announces system playlist update Aug 28 20:16:26 volumio volumio[1090]: info: Ignoring MPD Status Update Aug 28 20:16:26 volumio volumio[1090]: info: Aug 28 20:16:26 volumio volumio[1090]: ---------------------------- MPD announces system playlist update Aug 28 20:16:26 volumio volumio[1090]: info: Ignoring MPD Status Update Aug 28 20:16:26 volumio volumio[1090]: info: Aug 28 20:16:26 volumio volumio[1090]: ---------------------------- MPD announces system playlist update Aug 28 20:16:26 volumio volumio[1090]: info: Ignoring MPD Status Update Aug 28 20:16:26 volumio volumio[1090]: error: updateQueue error: null Aug 28 20:16:26 volumio volumio[1090]: error: updateQueue error: null Aug 28 20:16:26 volumio volumio[1090]: error: updateQueue error: null Aug 28 20:16:26 volumio volumio[1090]: error: updateQueue error: null Aug 28 20:16:26 volumio volumio[1090]: error: updateQueue error: null Aug 28 20:16:26 volumio volumio[1090]: error: updateQueue error: null Aug 28 20:16:26 volumio volumio[1090]: error: updateQueue error: null Aug 28 20:16:26 volumio volumio[1090]: error: updateQueue error: null Aug 28 20:16:26 volumio volumio[1090]: error: updateQueue error: null Aug 28 20:16:26 volumio volumio[1090]: info: ------------------------------ 616ms Aug 28 20:16:26 volumio volumio[1090]: info: sendMpdCommand playlistinfo took 563 milliseconds Aug 28 20:16:26 volumio volumio[1090]: info: sendMpdCommand consume 1 took 564 milliseconds Aug 28 20:16:26 volumio volumio[1090]: info: ------------------------------ 58ms Aug 28 20:16:26 volumio volumio[1090]: info: ------------------------------ 56ms Aug 28 20:16:26 volumio volumio[1090]: info: ------------------------------ 50ms Aug 28 20:16:26 volumio volumio[1090]: info: ------------------------------ 47ms Aug 28 20:16:26 volumio volumio[1090]: info: ------------------------------ 46ms Aug 28 20:16:26 volumio volumio[1090]: info: ------------------------------ 43ms Aug 28 20:16:26 volumio volumio[1090]: info: ------------------------------ 40ms Aug 28 20:16:26 volumio volumio[1090]: info: ------------------------------ 30ms Aug 28 20:16:26 volumio volumio[1090]: verbose: ControllerMpd::parseTrackInfo Aug 28 20:16:26 volumio volumio[1090]: info: [1787962586169] [80s80s] adding url: http://streams.80s80s.de/web/mp3-192/volumio Aug 28 20:16:26 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand add "http://streams.80s80s.de/web/mp3-192/volumio" Aug 28 20:16:26 volumio volumio[1090]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 28 20:16:26 volumio volumio[1090]: info: ------------------------------ 807ms Aug 28 20:16:26 volumio volumio[1090]: info: PeppyMeterBasic ---peppymeterbasic status play Aug 28 20:16:26 volumio volumio[1090]: info: PeppyMeterBasic ---peppymeterbasic status play Aug 28 20:16:26 volumio sudo[3932]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Aug 28 20:16:26 volumio sudo[3932]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 20:16:26 volumio volumio[1090]: info: PeppyMeterBasic ---peppymeterbasic status play Aug 28 20:16:26 volumio sudo[3935]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Aug 28 20:16:26 volumio sudo[3935]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 20:16:26 volumio volumio[1090]: info: PeppyMeterBasic ---peppymeterbasic status play Aug 28 20:16:26 volumio sudo[3938]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Aug 28 20:16:26 volumio sudo[3938]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 20:16:26 volumio volumio[1090]: info: PeppyMeterBasic ---peppymeterbasic status play Aug 28 20:16:26 volumio systemd[1]: Started peppymeterbasic Daemon. Aug 28 20:16:26 volumio sudo[3942]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Aug 28 20:16:26 volumio volumio[1090]: info: PeppyMeterBasic ---peppymeterbasic status play Aug 28 20:16:26 volumio sudo[3942]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 20:16:26 volumio sudo[3945]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Aug 28 20:16:26 volumio sudo[3945]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 20:16:26 volumio sudo[3932]: pam_unix(sudo:session): session closed for user root Aug 28 20:16:26 volumio volumio[1090]: info: PeppyMeterBasic ---peppymeterbasic status play Aug 28 20:16:26 volumio sudo[3938]: pam_unix(sudo:session): session closed for user root Aug 28 20:16:26 volumio sudo[3935]: pam_unix(sudo:session): session closed for user root Aug 28 20:16:26 volumio sudo[3945]: pam_unix(sudo:session): session closed for user root Aug 28 20:16:26 volumio sudo[3942]: pam_unix(sudo:session): session closed for user root Aug 28 20:16:26 volumio volumio[1090]: info: PeppyMeterBasic ---peppymeterbasic status play Aug 28 20:16:26 volumio sudo[3949]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Aug 28 20:16:26 volumio sudo[3949]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 20:16:26 volumio sudo[3951]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Aug 28 20:16:26 volumio sudo[3951]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 20:16:26 volumio volumio[1090]: info: PeppySpectrum ---peppyspectrum status play Aug 28 20:16:26 volumio sudo[3955]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Aug 28 20:16:26 volumio sudo[3955]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 20:16:26 volumio sudo[3951]: pam_unix(sudo:session): session closed for user root Aug 28 20:16:26 volumio sudo[3949]: pam_unix(sudo:session): session closed for user root Aug 28 20:16:26 volumio sudo[3955]: pam_unix(sudo:session): session closed for user root Aug 28 20:16:26 volumio volumio[1090]: info: PeppySpectrum ---peppyspectrum status play Aug 28 20:16:26 volumio sudo[3958]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Aug 28 20:16:26 volumio sudo[3958]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 20:16:27 volumio volumio[1090]: info: PeppySpectrum ---peppyspectrum status play Aug 28 20:16:27 volumio sudo[3961]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Aug 28 20:16:27 volumio sudo[3961]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 20:16:27 volumio systemd[1]: Started peppyspectrum Daemon. Aug 28 20:16:27 volumio volumio[1090]: info: PeppySpectrum ---peppyspectrum status play Aug 28 20:16:27 volumio sudo[3958]: pam_unix(sudo:session): session closed for user root Aug 28 20:16:27 volumio volumio[1090]: info: PeppySpectrum ---peppyspectrum status play Aug 28 20:16:27 volumio sudo[3965]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Aug 28 20:16:27 volumio sudo[3961]: pam_unix(sudo:session): session closed for user root Aug 28 20:16:27 volumio sudo[3965]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 20:16:27 volumio volumio[1090]: info: PeppySpectrum ---peppyspectrum status play Aug 28 20:16:27 volumio sudo[3968]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Aug 28 20:16:27 volumio sudo[3968]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 20:16:27 volumio sudo[3971]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Aug 28 20:16:27 volumio sudo[3971]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 20:16:27 volumio sudo[3965]: pam_unix(sudo:session): session closed for user root Aug 28 20:16:27 volumio volumio[1090]: info: PeppySpectrum ---peppyspectrum status play Aug 28 20:16:27 volumio sudo[3975]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Aug 28 20:16:27 volumio sudo[3975]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 20:16:27 volumio sudo[3971]: pam_unix(sudo:session): session closed for user root Aug 28 20:16:27 volumio volumio[1090]: info: PeppySpectrum ---peppyspectrum status play Aug 28 20:16:27 volumio sudo[3968]: pam_unix(sudo:session): session closed for user root Aug 28 20:16:27 volumio sudo[3975]: pam_unix(sudo:session): session closed for user root Aug 28 20:16:27 volumio sudo[3978]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Aug 28 20:16:27 volumio sudo[3978]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 20:16:27 volumio sudo[3980]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Aug 28 20:16:27 volumio sudo[3980]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 20:16:27 volumio sudo[3978]: pam_unix(sudo:session): session closed for user root Aug 28 20:16:27 volumio sudo[3980]: pam_unix(sudo:session): session closed for user root Aug 28 20:16:27 volumio volumio[1090]: info: [LastFM] Current track has sufficient metadata: title (Pat Benatar - Love Is a Battlefield) and artist (80s80s Real 80s Radio) passed on explicitly Aug 28 20:16:27 volumio volumio[1090]: error: [LastFM] plugin is not authenticated, please retry, or track info could not be determined (very rare) Aug 28 20:16:27 volumio volumio[1090]: info: Received Get System Info Aug 28 20:16:27 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 20:16:27 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 20:16:27 volumio volumio[1090]: info: Discovery: Getting this device information Aug 28 20:16:27 volumio volumio[1090]: info: CoreCommandRouter::volumioGetState Aug 28 20:16:27 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:27 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 20:16:27 volumio volumio[1090]: info: Aug 28 20:16:27 volumio volumio[1090]: ---------------------------- MPD announces system playlist update Aug 28 20:16:27 volumio volumio[1090]: info: Ignoring MPD Status Update Aug 28 20:16:27 volumio volumio[1090]: info: sendMpdCommand add "http://streams.80s80s.de/web/mp3-192/volumio" took 1717 milliseconds Aug 28 20:16:27 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand play Aug 28 20:16:27 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 28 20:16:27 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 28 20:16:27 volumio volumio[1090]: info: Aug 28 20:16:27 volumio volumio[1090]: ---------------------------- MPD announces system playlist update Aug 28 20:16:27 volumio volumio[1090]: info: Ignoring MPD Status Update Aug 28 20:16:27 volumio volumio[1090]: info: Aug 28 20:16:27 volumio volumio[1090]: ---------------------------- MPD announces system playlist update Aug 28 20:16:27 volumio volumio[1090]: info: Ignoring MPD Status Update Aug 28 20:16:27 volumio volumio[1090]: info: Aug 28 20:16:27 volumio volumio[1090]: ---------------------------- MPD announces system playlist update Aug 28 20:16:27 volumio volumio[1090]: info: Ignoring MPD Status Update Aug 28 20:16:27 volumio volumio[1090]: info: Aug 28 20:16:27 volumio volumio[1090]: ---------------------------- MPD announces system playlist update Aug 28 20:16:27 volumio volumio[1090]: info: Ignoring MPD Status Update Aug 28 20:16:27 volumio volumio[1090]: info: Aug 28 20:16:27 volumio volumio[1090]: ---------------------------- MPD announces system playlist update Aug 28 20:16:27 volumio volumio[1090]: info: Ignoring MPD Status Update Aug 28 20:16:27 volumio volumio[1090]: info: Aug 28 20:16:27 volumio volumio[1090]: ---------------------------- MPD announces system playlist update Aug 28 20:16:27 volumio volumio[1090]: info: Ignoring MPD Status Update Aug 28 20:16:27 volumio volumio[1090]: info: Aug 28 20:16:27 volumio volumio[1090]: ---------------------------- MPD announces system playlist update Aug 28 20:16:27 volumio volumio[1090]: info: Ignoring MPD Status Update Aug 28 20:16:27 volumio volumio[1090]: info: Aug 28 20:16:27 volumio volumio[1090]: ---------------------------- MPD announces system playlist update Aug 28 20:16:27 volumio volumio[1090]: info: Ignoring MPD Status Update Aug 28 20:16:27 volumio volumio[1090]: info: CoreCommandRouter::volumioGetState Aug 28 20:16:27 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:27 volumio volumio[1090]: info: CoreCommandRouter::volumioGetQueue Aug 28 20:16:27 volumio volumio[1090]: info: CoreStateMachine::getQueue Aug 28 20:16:27 volumio volumio[1090]: info: CorePlayQueue::getQueue Aug 28 20:16:28 volumio volumio[1090]: info: peppyspectrum Daemon Started Aug 28 20:16:28 volumio volumio[1090]: info: peppyspectrum Daemon Started Aug 28 20:16:28 volumio volumio[1090]: info: peppyspectrum Daemon Started Aug 28 20:16:28 volumio volumio[1090]: info: peppyspectrum Daemon Started Aug 28 20:16:28 volumio volumio[1090]: info: peppyspectrum Daemon Started Aug 28 20:16:28 volumio volumio[1090]: info: peppyspectrum Daemon Started Aug 28 20:16:28 volumio volumio[1090]: info: peppyspectrum Daemon Started Aug 28 20:16:28 volumio volumio[1090]: info: peppyspectrum Daemon Started Aug 28 20:16:28 volumio volumio[1090]: info: peppymeterbasic Daemon Started Aug 28 20:16:28 volumio volumio[1090]: info: peppymeterbasic Daemon Started Aug 28 20:16:28 volumio volumio[1090]: info: peppymeterbasic Daemon Started Aug 28 20:16:28 volumio volumio[1090]: info: peppymeterbasic Daemon Started Aug 28 20:16:28 volumio volumio[1090]: info: BOOT COMPLETED Aug 28 20:16:28 volumio volumio[1090]: info: ------------------------------ 161ms Aug 28 20:16:28 volumio volumio[1090]: info: sendMpdCommand play took 122 milliseconds Aug 28 20:16:28 volumio volumio[1090]: info: ------------------------------ 108ms Aug 28 20:16:28 volumio volumio[1090]: info: ------------------------------ 103ms Aug 28 20:16:28 volumio volumio[1090]: info: ------------------------------ 98ms Aug 28 20:16:28 volumio volumio[1090]: info: ------------------------------ 94ms Aug 28 20:16:28 volumio volumio[1090]: info: ------------------------------ 87ms Aug 28 20:16:28 volumio volumio[1090]: info: ------------------------------ 83ms Aug 28 20:16:28 volumio volumio[1090]: info: ------------------------------ 80ms Aug 28 20:16:28 volumio volumio[1090]: info: ------------------------------ 78ms Aug 28 20:16:28 volumio volumio[1090]: info: [1787962588049] [80s80s] Pushing the next song state: Pat Benatar - Love Is a Battlefield Aug 28 20:16:28 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:28 volumio volumio[1090]: info: CoreCommandRouter::servicePushState Aug 28 20:16:28 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:28 volumio volumio[1090]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is3-ssl.mzstatic.com/image/thumb/Music/v4/5e/db/3c/5edb3c8c-5a2f-6326-0519-02154be17723/source/600x600bb.jpg","name":"Pat Benatar - Love Is a Battlefield","title":"Love Is a Battlefield","artist":"80s80s Real 80s Radio","album":"","streaming":true,"disableUiControls":true,"duration":"176","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Aug 28 20:16:28 volumio volumio[1090]: verbose: CURRENT POSITION 0 Aug 28 20:16:28 volumio volumio[1090]: info: CoreStateMachine::syncState stateService play Aug 28 20:16:28 volumio volumio[1090]: info: CoreStateMachine::syncState currentStatus play Aug 28 20:16:28 volumio volumio[1090]: info: Received an update from plugin. extracting info from payload Aug 28 20:16:28 volumio volumio[1090]: info: CoreStateMachine::pushState Aug 28 20:16:28 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:28 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 20:16:28 volumio volumio[1090]: info: CoreCommandRouter::volumioPushState Aug 28 20:16:28 volumio volumio[1090]: info: PeppyMeterBasic ---peppymeterbasic status play Aug 28 20:16:28 volumio volumio[1090]: info: PeppySpectrum ---peppyspectrum status play Aug 28 20:16:28 volumio sudo[3991]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppymeterbasic.service Aug 28 20:16:28 volumio sudo[3991]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 20:16:28 volumio sudo[3993]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Aug 28 20:16:28 volumio sudo[3993]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 20:16:28 volumio sudo[3991]: pam_unix(sudo:session): session closed for user root Aug 28 20:16:28 volumio sudo[3993]: pam_unix(sudo:session): session closed for user root Aug 28 20:16:28 volumio volumio[1090]: info: mpdhttpout ---Boot completed detected! Patching mpd now! Aug 28 20:16:28 volumio volumio[1090]: info: Aug 28 20:16:28 volumio volumio[1090]: ---------------------------- MPD announces state update: player Aug 28 20:16:28 volumio volumio[1090]: info: ControllerMpd::getState Aug 28 20:16:28 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand status Aug 28 20:16:28 volumio volumio[1090]: info: Aug 28 20:16:28 volumio volumio[1090]: ---------------------------- MPD announces state update: player Aug 28 20:16:28 volumio volumio[1090]: info: ControllerMpd::getState Aug 28 20:16:28 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand status Aug 28 20:16:28 volumio volumio[1090]: info: Aug 28 20:16:28 volumio volumio[1090]: ---------------------------- MPD announces state update: player Aug 28 20:16:28 volumio volumio[1090]: info: ControllerMpd::getState Aug 28 20:16:28 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand status Aug 28 20:16:28 volumio volumio[1090]: info: Aug 28 20:16:28 volumio volumio[1090]: ---------------------------- MPD announces state update: player Aug 28 20:16:28 volumio volumio[1090]: info: ControllerMpd::getState Aug 28 20:16:28 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand status Aug 28 20:16:28 volumio volumio[1090]: info: Aug 28 20:16:28 volumio volumio[1090]: ---------------------------- MPD announces state update: player Aug 28 20:16:28 volumio volumio[1090]: info: ControllerMpd::getState Aug 28 20:16:28 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand status Aug 28 20:16:28 volumio volumio[1090]: info: Aug 28 20:16:28 volumio volumio[1090]: ---------------------------- MPD announces state update: player Aug 28 20:16:28 volumio volumio[1090]: info: ControllerMpd::getState Aug 28 20:16:28 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand status Aug 28 20:16:28 volumio volumio[1090]: info: Aug 28 20:16:28 volumio volumio[1090]: ---------------------------- MPD announces state update: player Aug 28 20:16:28 volumio volumio[1090]: info: ControllerMpd::getState Aug 28 20:16:28 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand status Aug 28 20:16:28 volumio volumio[1090]: info: Aug 28 20:16:28 volumio volumio[1090]: ---------------------------- MPD announces state update: player Aug 28 20:16:28 volumio volumio[1090]: info: ControllerMpd::getState Aug 28 20:16:28 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand status Aug 28 20:16:28 volumio volumio[1090]: info: Aug 28 20:16:28 volumio volumio[1090]: ---------------------------- MPD announces state update: player Aug 28 20:16:28 volumio volumio[1090]: info: ControllerMpd::getState Aug 28 20:16:28 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand status Aug 28 20:16:28 volumio volumio[1090]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Aug 28 20:16:28 volumio volumio[1090]: info: FusionDsp - ---- read samplerate from file: 44100 Aug 28 20:16:28 volumio volumio[1090]: info: FusionDsp - If filter freq >samplerate/2 then disable it Aug 28 20:16:28 volumio volumio[1090]: info: Aug 28 20:16:28 volumio volumio[1090]: ---------------------------- MPD announces system playlist update Aug 28 20:16:28 volumio volumio[1090]: info: Ignoring MPD Status Update Aug 28 20:16:28 volumio volumio[1090]: info: Aug 28 20:16:28 volumio volumio[1090]: ---------------------------- MPD announces state update: player Aug 28 20:16:28 volumio volumio[1090]: info: ControllerMpd::getState Aug 28 20:16:28 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand status Aug 28 20:16:28 volumio volumio[1090]: info: sendMpdCommand status took 118 milliseconds Aug 28 20:16:28 volumio volumio[1090]: info: sendMpdCommand status took 97 milliseconds Aug 28 20:16:28 volumio volumio[1090]: info: sendMpdCommand status took 84 milliseconds Aug 28 20:16:28 volumio volumio[1090]: info: sendMpdCommand status took 80 milliseconds Aug 28 20:16:28 volumio volumio[1090]: info: sendMpdCommand status took 67 milliseconds Aug 28 20:16:28 volumio volumio[1090]: info: sendMpdCommand status took 64 milliseconds Aug 28 20:16:28 volumio volumio[1090]: info: sendMpdCommand status took 61 milliseconds Aug 28 20:16:28 volumio volumio[1090]: info: sendMpdCommand status took 48 milliseconds Aug 28 20:16:28 volumio volumio[1090]: info: sendMpdCommand status took 45 milliseconds Aug 28 20:16:28 volumio volumio[1090]: verbose: ControllerMpd::parseState Aug 28 20:16:28 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 28 20:16:28 volumio volumio[1090]: verbose: ControllerMpd::parseState Aug 28 20:16:28 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 28 20:16:28 volumio volumio[1090]: verbose: ControllerMpd::parseState Aug 28 20:16:28 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 28 20:16:28 volumio volumio[1090]: verbose: ControllerMpd::parseState Aug 28 20:16:28 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 28 20:16:28 volumio volumio[1090]: verbose: ControllerMpd::parseState Aug 28 20:16:28 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 28 20:16:28 volumio volumio[1090]: verbose: ControllerMpd::parseState Aug 28 20:16:28 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 28 20:16:28 volumio volumio[1090]: verbose: ControllerMpd::parseState Aug 28 20:16:28 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 28 20:16:28 volumio volumio[1090]: verbose: ControllerMpd::parseState Aug 28 20:16:28 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 28 20:16:28 volumio volumio[1090]: verbose: ControllerMpd::parseState Aug 28 20:16:28 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 28 20:16:28 volumio volumio[1090]: info: Aug 28 20:16:28 volumio volumio[1090]: ---------------------------- MPD announces system playlist update Aug 28 20:16:28 volumio volumio[1090]: info: Ignoring MPD Status Update Aug 28 20:16:28 volumio volumio[1090]: info: Aug 28 20:16:28 volumio volumio[1090]: ---------------------------- MPD announces state update: player Aug 28 20:16:28 volumio volumio[1090]: info: ControllerMpd::getState Aug 28 20:16:28 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand status Aug 28 20:16:28 volumio volumio[1090]: info: Aug 28 20:16:28 volumio volumio[1090]: ---------------------------- MPD announces system playlist update Aug 28 20:16:28 volumio volumio[1090]: info: Ignoring MPD Status Update Aug 28 20:16:28 volumio volumio[1090]: info: Aug 28 20:16:28 volumio volumio[1090]: ---------------------------- MPD announces state update: player Aug 28 20:16:28 volumio volumio[1090]: info: ControllerMpd::getState Aug 28 20:16:28 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand status Aug 28 20:16:28 volumio volumio[1090]: info: Aug 28 20:16:28 volumio volumio[1090]: ---------------------------- MPD announces system playlist update Aug 28 20:16:28 volumio volumio[1090]: info: Ignoring MPD Status Update Aug 28 20:16:28 volumio volumio[1090]: info: Aug 28 20:16:28 volumio volumio[1090]: ---------------------------- MPD announces state update: player Aug 28 20:16:28 volumio volumio[1090]: info: ControllerMpd::getState Aug 28 20:16:28 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand status Aug 28 20:16:28 volumio volumio[1090]: info: Aug 28 20:16:28 volumio volumio[1090]: ---------------------------- MPD announces system playlist update Aug 28 20:16:28 volumio volumio[1090]: info: Ignoring MPD Status Update Aug 28 20:16:28 volumio volumio[1090]: info: Aug 28 20:16:28 volumio volumio[1090]: ---------------------------- MPD announces state update: player Aug 28 20:16:28 volumio volumio[1090]: info: ControllerMpd::getState Aug 28 20:16:28 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand status Aug 28 20:16:28 volumio volumio[1090]: info: Aug 28 20:16:28 volumio volumio[1090]: ---------------------------- MPD announces system playlist update Aug 28 20:16:28 volumio volumio[1090]: info: Ignoring MPD Status Update Aug 28 20:16:28 volumio volumio[1090]: info: Aug 28 20:16:28 volumio volumio[1090]: ---------------------------- MPD announces state update: player Aug 28 20:16:28 volumio volumio[1090]: info: ControllerMpd::getState Aug 28 20:16:28 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand status Aug 28 20:16:28 volumio volumio[1090]: info: Aug 28 20:16:28 volumio volumio[1090]: ---------------------------- MPD announces system playlist update Aug 28 20:16:28 volumio volumio[1090]: info: Ignoring MPD Status Update Aug 28 20:16:28 volumio volumio[1090]: info: Aug 28 20:16:28 volumio volumio[1090]: ---------------------------- MPD announces state update: player Aug 28 20:16:28 volumio volumio[1090]: info: ControllerMpd::getState Aug 28 20:16:28 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand status Aug 28 20:16:28 volumio volumio[1090]: info: Aug 28 20:16:28 volumio volumio[1090]: ---------------------------- MPD announces system playlist update Aug 28 20:16:28 volumio volumio[1090]: info: Ignoring MPD Status Update Aug 28 20:16:28 volumio volumio[1090]: info: Aug 28 20:16:28 volumio volumio[1090]: ---------------------------- MPD announces state update: player Aug 28 20:16:28 volumio volumio[1090]: info: ControllerMpd::getState Aug 28 20:16:28 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand status Aug 28 20:16:28 volumio volumio[1090]: info: Aug 28 20:16:28 volumio volumio[1090]: ---------------------------- MPD announces system playlist update Aug 28 20:16:28 volumio volumio[1090]: info: Ignoring MPD Status Update Aug 28 20:16:28 volumio volumio[1090]: info: Aug 28 20:16:28 volumio volumio[1090]: ---------------------------- MPD announces state update: player Aug 28 20:16:28 volumio volumio[1090]: info: ControllerMpd::getState Aug 28 20:16:28 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand status Aug 28 20:16:28 volumio volumio5-onboarding[1337]: time=2026-08-28T20:16:28.876-04:00 level=INFO msg="service successfully established" component=discovery/localnet Aug 28 20:16:28 volumio volumio[1090]: info: CoreCommandRouter::volumioGetState Aug 28 20:16:28 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:28 volumio volumio[1090]: info: CoreCommandRouter::volumioGetQueue Aug 28 20:16:28 volumio volumio[1090]: info: CoreStateMachine::getQueue Aug 28 20:16:28 volumio volumio[1090]: info: CorePlayQueue::getQueue Aug 28 20:16:28 volumio volumio[1090]: info: peppymeterbasic Daemon Started Aug 28 20:16:28 volumio volumio[1090]: info: peppyspectrum Daemon Started Aug 28 20:16:29 volumio volumio[1090]: info: ------------------------------ 329ms Aug 28 20:16:29 volumio volumio[1090]: info: sendMpdCommand status took 326 milliseconds Aug 28 20:16:29 volumio volumio[1090]: info: sendMpdCommand playlistinfo took 318 milliseconds Aug 28 20:16:29 volumio volumio[1090]: info: sendMpdCommand playlistinfo took 318 milliseconds Aug 28 20:16:29 volumio volumio[1090]: info: sendMpdCommand playlistinfo took 318 milliseconds Aug 28 20:16:29 volumio volumio[1090]: info: sendMpdCommand playlistinfo took 317 milliseconds Aug 28 20:16:29 volumio volumio[1090]: info: sendMpdCommand playlistinfo took 316 milliseconds Aug 28 20:16:29 volumio volumio[1090]: info: sendMpdCommand playlistinfo took 315 milliseconds Aug 28 20:16:29 volumio volumio[1090]: info: sendMpdCommand playlistinfo took 315 milliseconds Aug 28 20:16:29 volumio volumio[1090]: info: sendMpdCommand playlistinfo took 323 milliseconds Aug 28 20:16:29 volumio volumio[1090]: info: ------------------------------ 319ms Aug 28 20:16:29 volumio volumio[1090]: info: sendMpdCommand status took 324 milliseconds Aug 28 20:16:29 volumio volumio[1090]: info: ------------------------------ 323ms Aug 28 20:16:29 volumio volumio[1090]: info: sendMpdCommand status took 320 milliseconds Aug 28 20:16:29 volumio volumio[1090]: info: ------------------------------ 319ms Aug 28 20:16:29 volumio volumio[1090]: info: sendMpdCommand status took 317 milliseconds Aug 28 20:16:29 volumio volumio[1090]: info: ------------------------------ 316ms Aug 28 20:16:29 volumio volumio[1090]: info: sendMpdCommand status took 312 milliseconds Aug 28 20:16:29 volumio volumio[1090]: info: ------------------------------ 311ms Aug 28 20:16:29 volumio volumio[1090]: info: sendMpdCommand status took 307 milliseconds Aug 28 20:16:29 volumio volumio[1090]: info: ------------------------------ 306ms Aug 28 20:16:29 volumio volumio[1090]: info: sendMpdCommand status took 305 milliseconds Aug 28 20:16:29 volumio volumio[1090]: info: ------------------------------ 304ms Aug 28 20:16:29 volumio volumio[1090]: info: sendMpdCommand status took 301 milliseconds Aug 28 20:16:29 volumio volumio[1090]: info: ------------------------------ 300ms Aug 28 20:16:29 volumio volumio[1090]: info: sendMpdCommand status took 307 milliseconds Aug 28 20:16:29 volumio volumio[1090]: verbose: ControllerMpd::parseState Aug 28 20:16:29 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 28 20:16:29 volumio volumio[1090]: verbose: ControllerMpd::parseTrackInfo Aug 28 20:16:29 volumio volumio[1090]: verbose: ControllerMpd::parseTrackInfo Aug 28 20:16:29 volumio volumio[1090]: verbose: ControllerMpd::parseTrackInfo Aug 28 20:16:29 volumio volumio[1090]: verbose: ControllerMpd::parseTrackInfo Aug 28 20:16:29 volumio volumio[1090]: verbose: ControllerMpd::parseState Aug 28 20:16:29 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 28 20:16:29 volumio volumio[1090]: verbose: ControllerMpd::parseState Aug 28 20:16:29 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 28 20:16:29 volumio volumio[1090]: verbose: ControllerMpd::parseState Aug 28 20:16:29 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 28 20:16:29 volumio volumio[1090]: verbose: ControllerMpd::parseState Aug 28 20:16:29 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 28 20:16:29 volumio volumio[1090]: verbose: ControllerMpd::parseState Aug 28 20:16:29 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 28 20:16:29 volumio volumio[1090]: verbose: ControllerMpd::parseState Aug 28 20:16:29 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 28 20:16:29 volumio volumio[1090]: verbose: ControllerMpd::parseState Aug 28 20:16:29 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 28 20:16:29 volumio volumio[1090]: verbose: ControllerMpd::parseState Aug 28 20:16:29 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 28 20:16:29 volumio volumio[1090]: info: ControllerMpd::pushState Aug 28 20:16:29 volumio volumio[1090]: info: CoreCommandRouter::servicePushState Aug 28 20:16:29 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:29 volumio volumio[1090]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Aug 28 20:16:29 volumio volumio[1090]: info: ControllerMpd::pushState Aug 28 20:16:29 volumio volumio[1090]: info: CoreCommandRouter::servicePushState Aug 28 20:16:29 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:29 volumio volumio[1090]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Aug 28 20:16:29 volumio volumio[1090]: info: ControllerMpd::pushState Aug 28 20:16:29 volumio volumio[1090]: info: CoreCommandRouter::servicePushState Aug 28 20:16:29 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:29 volumio volumio[1090]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Aug 28 20:16:29 volumio volumio[1090]: info: ControllerMpd::pushState Aug 28 20:16:29 volumio volumio[1090]: info: CoreCommandRouter::servicePushState Aug 28 20:16:29 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:29 volumio volumio[1090]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Aug 28 20:16:29 volumio volumio[1090]: info: ControllerMpd::pushState Aug 28 20:16:29 volumio volumio[1090]: info: CoreCommandRouter::servicePushState Aug 28 20:16:29 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:29 volumio volumio[1090]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Aug 28 20:16:29 volumio volumio[1090]: info: ControllerMpd::pushState Aug 28 20:16:29 volumio volumio[1090]: info: CoreCommandRouter::servicePushState Aug 28 20:16:29 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:29 volumio volumio[1090]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Aug 28 20:16:29 volumio volumio[1090]: info: ControllerMpd::pushState Aug 28 20:16:29 volumio volumio[1090]: info: CoreCommandRouter::servicePushState Aug 28 20:16:29 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:29 volumio volumio[1090]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Aug 28 20:16:29 volumio volumio[1090]: info: ControllerMpd::pushState Aug 28 20:16:29 volumio volumio[1090]: info: CoreCommandRouter::servicePushState Aug 28 20:16:29 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:29 volumio volumio[1090]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Aug 28 20:16:29 volumio volumio[1090]: info: ControllerMpd::pushState Aug 28 20:16:29 volumio volumio[1090]: info: CoreCommandRouter::servicePushState Aug 28 20:16:29 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:29 volumio volumio[1090]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Aug 28 20:16:29 volumio volumio[1090]: info: ------------------------------ 621ms Aug 28 20:16:29 volumio volumio[1090]: info: ------------------------------ 599ms Aug 28 20:16:29 volumio volumio[1090]: info: ------------------------------ 596ms Aug 28 20:16:29 volumio volumio[1090]: info: ------------------------------ 582ms Aug 28 20:16:29 volumio volumio[1090]: info: ------------------------------ 578ms Aug 28 20:16:29 volumio volumio[1090]: info: ------------------------------ 563ms Aug 28 20:16:29 volumio volumio[1090]: info: ------------------------------ 561ms Aug 28 20:16:29 volumio volumio[1090]: info: ------------------------------ 546ms Aug 28 20:16:29 volumio volumio[1090]: info: ------------------------------ 544ms Aug 28 20:16:29 volumio volumio[1090]: error: FusionDsp - WebSocket error: [object Object] Aug 28 20:16:29 volumio volumio[1090]: info: sendMpdCommand playlistinfo took 138 milliseconds Aug 28 20:16:29 volumio volumio[1090]: info: sendMpdCommand playlistinfo took 120 milliseconds Aug 28 20:16:29 volumio volumio[1090]: info: sendMpdCommand playlistinfo took 119 milliseconds Aug 28 20:16:29 volumio volumio[1090]: info: sendMpdCommand playlistinfo took 119 milliseconds Aug 28 20:16:29 volumio volumio[1090]: info: sendMpdCommand playlistinfo took 119 milliseconds Aug 28 20:16:29 volumio volumio[1090]: info: sendMpdCommand playlistinfo took 119 milliseconds Aug 28 20:16:29 volumio volumio[1090]: info: sendMpdCommand playlistinfo took 119 milliseconds Aug 28 20:16:29 volumio volumio[1090]: info: sendMpdCommand playlistinfo took 119 milliseconds Aug 28 20:16:29 volumio volumio[1090]: info: sendMpdCommand playlistinfo took 119 milliseconds Aug 28 20:16:29 volumio volumio[1090]: verbose: ControllerMpd::parseTrackInfo Aug 28 20:16:29 volumio volumio[1090]: verbose: ControllerMpd::parseTrackInfo Aug 28 20:16:29 volumio volumio[1090]: verbose: ControllerMpd::parseTrackInfo Aug 28 20:16:29 volumio volumio[1090]: verbose: ControllerMpd::parseTrackInfo Aug 28 20:16:29 volumio volumio[1090]: verbose: ControllerMpd::parseTrackInfo Aug 28 20:16:29 volumio volumio[1090]: verbose: ControllerMpd::parseTrackInfo Aug 28 20:16:29 volumio volumio[1090]: verbose: ControllerMpd::parseTrackInfo Aug 28 20:16:29 volumio volumio[1090]: verbose: ControllerMpd::parseTrackInfo Aug 28 20:16:29 volumio volumio[1090]: verbose: ControllerMpd::parseTrackInfo Aug 28 20:16:29 volumio volumio[1090]: info: ControllerMpd::pushState Aug 28 20:16:29 volumio volumio[1090]: info: CoreCommandRouter::servicePushState Aug 28 20:16:29 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:29 volumio volumio[1090]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Aug 28 20:16:29 volumio volumio[1090]: info: ControllerMpd::pushState Aug 28 20:16:29 volumio volumio[1090]: info: CoreCommandRouter::servicePushState Aug 28 20:16:29 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:29 volumio volumio[1090]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Aug 28 20:16:29 volumio volumio[1090]: info: ControllerMpd::pushState Aug 28 20:16:29 volumio volumio[1090]: info: CoreCommandRouter::servicePushState Aug 28 20:16:29 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:29 volumio volumio[1090]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Aug 28 20:16:29 volumio volumio[1090]: info: ControllerMpd::pushState Aug 28 20:16:29 volumio volumio[1090]: info: CoreCommandRouter::servicePushState Aug 28 20:16:29 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:29 volumio volumio[1090]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Aug 28 20:16:29 volumio volumio[1090]: info: ControllerMpd::pushState Aug 28 20:16:29 volumio volumio[1090]: info: CoreCommandRouter::servicePushState Aug 28 20:16:29 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:29 volumio volumio[1090]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Aug 28 20:16:29 volumio volumio[1090]: info: ControllerMpd::pushState Aug 28 20:16:29 volumio volumio[1090]: info: CoreCommandRouter::servicePushState Aug 28 20:16:29 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:29 volumio volumio[1090]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Aug 28 20:16:29 volumio volumio[1090]: info: ControllerMpd::pushState Aug 28 20:16:29 volumio volumio[1090]: info: CoreCommandRouter::servicePushState Aug 28 20:16:29 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:29 volumio volumio[1090]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Aug 28 20:16:29 volumio volumio[1090]: info: ControllerMpd::pushState Aug 28 20:16:29 volumio volumio[1090]: info: CoreCommandRouter::servicePushState Aug 28 20:16:29 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:29 volumio volumio[1090]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Aug 28 20:16:29 volumio volumio[1090]: info: ControllerMpd::pushState Aug 28 20:16:29 volumio volumio[1090]: info: CoreCommandRouter::servicePushState Aug 28 20:16:29 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:29 volumio volumio[1090]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Aug 28 20:16:29 volumio volumio[1090]: info: ------------------------------ 580ms Aug 28 20:16:29 volumio volumio[1090]: info: ------------------------------ 554ms Aug 28 20:16:29 volumio volumio[1090]: info: ------------------------------ 550ms Aug 28 20:16:29 volumio volumio[1090]: info: ------------------------------ 545ms Aug 28 20:16:29 volumio volumio[1090]: info: ------------------------------ 539ms Aug 28 20:16:29 volumio volumio[1090]: info: ------------------------------ 534ms Aug 28 20:16:29 volumio volumio[1090]: info: ------------------------------ 528ms Aug 28 20:16:29 volumio volumio[1090]: info: ------------------------------ 525ms Aug 28 20:16:29 volumio volumio[1090]: info: ------------------------------ 520ms Aug 28 20:16:29 volumio volumio[1090]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf. Aug 28 20:16:29 volumio volumio[1090]: info: CoreCommandRouter::volumioGetState Aug 28 20:16:29 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:29 volumio volumio[1090]: info: CoreCommandRouter::volumioPause Aug 28 20:16:29 volumio volumio[1090]: info: CoreStateMachine::pause Aug 28 20:16:29 volumio volumio[1090]: info: CoreStateMachine::stPlaybackTimer Aug 28 20:16:29 volumio volumio[1090]: info: CoreStateMachine::servicePause Aug 28 20:16:29 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:29 volumio volumio[1090]: info: CoreCommandRouter::servicePause Aug 28 20:16:29 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand pause Aug 28 20:16:29 volumio volumio[1090]: info: mpdhttpout --- Volumio set on pause Aug 28 20:16:29 volumio volumio[1090]: info: Aug 28 20:16:29 volumio volumio[1090]: ---------------------------- MPD announces state update: player Aug 28 20:16:29 volumio volumio[1090]: info: sendMpdCommand pause took 36 milliseconds Aug 28 20:16:29 volumio volumio[1090]: info: ControllerMpd::getState Aug 28 20:16:29 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand status Aug 28 20:16:29 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:29 volumio volumio[1090]: info: CoreCommandRouter::servicePushState Aug 28 20:16:29 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:29 volumio volumio[1090]: verbose: STATE SERVICE {"status":"pause","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is3-ssl.mzstatic.com/image/thumb/Music/v4/5e/db/3c/5edb3c8c-5a2f-6326-0519-02154be17723/source/600x600bb.jpg","name":"Pat Benatar - Love Is a Battlefield","title":"Love Is a Battlefield","artist":"80s80s Real 80s Radio","album":"","streaming":true,"disableUiControls":true,"duration":"176","seek":1250,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Aug 28 20:16:29 volumio volumio[1090]: verbose: CURRENT POSITION 0 Aug 28 20:16:29 volumio volumio[1090]: info: CoreStateMachine::syncState stateService pause Aug 28 20:16:29 volumio volumio[1090]: info: CoreStateMachine::syncState currentStatus pause Aug 28 20:16:29 volumio volumio[1090]: info: CoreStateMachine::pushState Aug 28 20:16:29 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:29 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 20:16:29 volumio volumio[1090]: info: CoreCommandRouter::volumioPushState Aug 28 20:16:29 volumio volumio[1090]: info: Aug 28 20:16:29 volumio volumio[1090]: ---------------------------- MPD announces state update: player Aug 28 20:16:29 volumio volumio[1090]: info: ControllerMpd::getState Aug 28 20:16:29 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand status Aug 28 20:16:29 volumio volumio[1090]: info: Aug 28 20:16:29 volumio volumio[1090]: ---------------------------- MPD announces state update: player Aug 28 20:16:29 volumio volumio[1090]: info: ControllerMpd::getState Aug 28 20:16:29 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand status Aug 28 20:16:29 volumio volumio[1090]: info: Aug 28 20:16:29 volumio volumio[1090]: ---------------------------- MPD announces state update: player Aug 28 20:16:29 volumio volumio[1090]: info: ControllerMpd::getState Aug 28 20:16:29 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand status Aug 28 20:16:29 volumio volumio[1090]: info: Aug 28 20:16:29 volumio volumio[1090]: ---------------------------- MPD announces state update: player Aug 28 20:16:29 volumio volumio[1090]: info: ControllerMpd::getState Aug 28 20:16:29 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand status Aug 28 20:16:29 volumio volumio[1090]: info: Aug 28 20:16:29 volumio volumio[1090]: ---------------------------- MPD announces state update: player Aug 28 20:16:29 volumio volumio[1090]: info: ControllerMpd::getState Aug 28 20:16:29 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand status Aug 28 20:16:29 volumio volumio[1090]: info: Aug 28 20:16:29 volumio volumio[1090]: ---------------------------- MPD announces state update: player Aug 28 20:16:29 volumio volumio[3962]: pygame 1.9.4.post1 Aug 28 20:16:29 volumio volumio[3962]: Hello from the pygame community. https://www.pygame.org/contribute.html Aug 28 20:16:29 volumio volumio[3962]: Traceback (most recent call last): Aug 28 20:16:29 volumio volumio[3962]: File "spectrum.py", line 715, in Aug 28 20:16:29 volumio volumio[3962]: pm = Spectrum(None, True) Aug 28 20:16:29 volumio volumio[3962]: File "spectrum.py", line 63, in __init__ Aug 28 20:16:29 volumio volumio[3962]: self.init_display() Aug 28 20:16:29 volumio volumio[3962]: File "spectrum.py", line 104, in init_display Aug 28 20:16:29 volumio volumio[3962]: pygame.display.init() Aug 28 20:16:29 volumio volumio[3962]: pygame.error: Unable to open /dev/fb1 Aug 28 20:16:29 volumio volumio[1090]: info: ControllerMpd::getState Aug 28 20:16:29 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand status Aug 28 20:16:29 volumio volumio[1090]: info: Aug 28 20:16:29 volumio volumio[1090]: ---------------------------- MPD announces state update: player Aug 28 20:16:29 volumio volumio[1090]: info: ControllerMpd::getState Aug 28 20:16:29 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand status Aug 28 20:16:29 volumio volumio[1090]: info: Aug 28 20:16:29 volumio volumio[1090]: ---------------------------- MPD announces state update: player Aug 28 20:16:29 volumio volumio[1090]: info: ControllerMpd::getState Aug 28 20:16:29 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand status Aug 28 20:16:29 volumio volumio[1090]: info: sendMpdCommand status took 301 milliseconds Aug 28 20:16:29 volumio volumio[1090]: info: sendMpdCommand status took 92 milliseconds Aug 28 20:16:29 volumio volumio[1090]: info: sendMpdCommand status took 89 milliseconds Aug 28 20:16:29 volumio volumio[1090]: info: sendMpdCommand status took 86 milliseconds Aug 28 20:16:29 volumio volumio[1090]: info: sendMpdCommand status took 68 milliseconds Aug 28 20:16:29 volumio volumio[1090]: info: sendMpdCommand status took 61 milliseconds Aug 28 20:16:29 volumio volumio[1090]: info: sendMpdCommand status took 43 milliseconds Aug 28 20:16:29 volumio volumio[1090]: info: sendMpdCommand status took 40 milliseconds Aug 28 20:16:29 volumio volumio[1090]: info: sendMpdCommand status took 32 milliseconds Aug 28 20:16:29 volumio volumio[1090]: verbose: ControllerMpd::parseState Aug 28 20:16:29 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 28 20:16:29 volumio volumio[1090]: verbose: ControllerMpd::parseState Aug 28 20:16:29 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 28 20:16:29 volumio volumio[1090]: verbose: ControllerMpd::parseState Aug 28 20:16:29 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 28 20:16:29 volumio volumio[1090]: verbose: ControllerMpd::parseState Aug 28 20:16:29 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 28 20:16:29 volumio volumio[1090]: verbose: ControllerMpd::parseState Aug 28 20:16:29 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 28 20:16:29 volumio volumio[1090]: verbose: ControllerMpd::parseState Aug 28 20:16:29 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 28 20:16:29 volumio volumio[1090]: verbose: ControllerMpd::parseState Aug 28 20:16:29 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 28 20:16:29 volumio volumio[1090]: verbose: ControllerMpd::parseState Aug 28 20:16:29 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 28 20:16:29 volumio volumio[1090]: verbose: ControllerMpd::parseState Aug 28 20:16:29 volumio volumio[1090]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 28 20:16:29 volumio volumio[1090]: info: CoreCommandRouter::volumioGetState Aug 28 20:16:29 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:29 volumio volumio[1090]: info: CoreCommandRouter::volumioGetQueue Aug 28 20:16:29 volumio volumio[1090]: info: CoreStateMachine::getQueue Aug 28 20:16:29 volumio volumio[1090]: info: CorePlayQueue::getQueue Aug 28 20:16:29 volumio volumio[1090]: info: PeppyMeterBasic ---peppymeterbasic status pause Aug 28 20:16:29 volumio volumio[1090]: info: PeppySpectrum ---peppyspectrum status pause Aug 28 20:16:29 volumio sudo[4018]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Aug 28 20:16:29 volumio sudo[4018]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 20:16:30 volumio sudo[4020]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Aug 28 20:16:30 volumio sudo[4020]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 20:16:30 volumio systemd[1]: peppyspectrum.service: Main process exited, code=exited, status=1/FAILURE Aug 28 20:16:30 volumio systemd[1]: peppyspectrum.service: Failed with result 'exit-code'. Aug 28 20:16:30 volumio volumio[1090]: info: sendMpdCommand playlistinfo took 274 milliseconds Aug 28 20:16:30 volumio volumio[1090]: info: sendMpdCommand playlistinfo took 282 milliseconds Aug 28 20:16:30 volumio volumio[1090]: info: sendMpdCommand playlistinfo took 280 milliseconds Aug 28 20:16:30 volumio volumio[1090]: info: sendMpdCommand playlistinfo took 279 milliseconds Aug 28 20:16:30 volumio volumio[1090]: info: sendMpdCommand playlistinfo took 276 milliseconds Aug 28 20:16:30 volumio volumio[1090]: info: sendMpdCommand playlistinfo took 275 milliseconds Aug 28 20:16:30 volumio volumio[1090]: info: sendMpdCommand playlistinfo took 274 milliseconds Aug 28 20:16:30 volumio volumio[1090]: verbose: ControllerMpd::parseTrackInfo Aug 28 20:16:30 volumio volumio[1090]: verbose: ControllerMpd::parseTrackInfo Aug 28 20:16:30 volumio volumio[1090]: verbose: ControllerMpd::parseTrackInfo Aug 28 20:16:30 volumio systemd[1]: Stopping peppymeterbasic Daemon... Aug 28 20:16:30 volumio volumio[1090]: verbose: ControllerMpd::parseTrackInfo Aug 28 20:16:30 volumio volumio[1090]: verbose: ControllerMpd::parseTrackInfo Aug 28 20:16:30 volumio volumio[1090]: verbose: ControllerMpd::parseTrackInfo Aug 28 20:16:30 volumio systemd[1]: peppymeterbasic.service: Main process exited, code=killed, status=15/TERM Aug 28 20:16:30 volumio systemd[1]: peppymeterbasic.service: Succeeded. Aug 28 20:16:30 volumio systemd[1]: Stopped peppymeterbasic Daemon. Aug 28 20:16:30 volumio volumio[1090]: info: ControllerMpd::pushState Aug 28 20:16:30 volumio volumio[1090]: info: CoreCommandRouter::servicePushState Aug 28 20:16:30 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:30 volumio volumio[1090]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Aug 28 20:16:30 volumio volumio[1090]: info: ControllerMpd::pushState Aug 28 20:16:30 volumio volumio[1090]: info: CoreCommandRouter::servicePushState Aug 28 20:16:30 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:30 volumio volumio[1090]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Aug 28 20:16:30 volumio volumio[1090]: info: ControllerMpd::pushState Aug 28 20:16:30 volumio volumio[1090]: info: CoreCommandRouter::servicePushState Aug 28 20:16:30 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:30 volumio volumio[1090]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Aug 28 20:16:30 volumio volumio[1090]: info: ControllerMpd::pushState Aug 28 20:16:30 volumio volumio[1090]: info: CoreCommandRouter::servicePushState Aug 28 20:16:30 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:30 volumio volumio[1090]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Aug 28 20:16:30 volumio volumio[1090]: info: ControllerMpd::pushState Aug 28 20:16:30 volumio volumio[1090]: info: CoreCommandRouter::servicePushState Aug 28 20:16:30 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:30 volumio volumio[1090]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Aug 28 20:16:30 volumio volumio[1090]: info: ControllerMpd::pushState Aug 28 20:16:30 volumio volumio[1090]: info: CoreCommandRouter::servicePushState Aug 28 20:16:30 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:30 volumio volumio[1090]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Aug 28 20:16:30 volumio volumio[1090]: info: ControllerMpd::pushState Aug 28 20:16:30 volumio volumio[1090]: info: CoreCommandRouter::servicePushState Aug 28 20:16:30 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:30 volumio volumio[1090]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Aug 28 20:16:30 volumio volumio[1090]: info: ControllerMpd::pushState Aug 28 20:16:30 volumio volumio[1090]: info: CoreCommandRouter::servicePushState Aug 28 20:16:30 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:30 volumio volumio[1090]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Aug 28 20:16:30 volumio volumio[1090]: info: ControllerMpd::pushState Aug 28 20:16:30 volumio volumio[1090]: info: CoreCommandRouter::servicePushState Aug 28 20:16:30 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:30 volumio volumio[1090]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Aug 28 20:16:30 volumio volumio[1090]: info: ------------------------------ 680ms Aug 28 20:16:30 volumio volumio[1090]: info: ------------------------------ 473ms Aug 28 20:16:30 volumio volumio[1090]: info: ------------------------------ 459ms Aug 28 20:16:30 volumio volumio[1090]: info: ------------------------------ 456ms Aug 28 20:16:30 volumio volumio[1090]: info: ------------------------------ 439ms Aug 28 20:16:30 volumio volumio[1090]: info: ------------------------------ 434ms Aug 28 20:16:30 volumio volumio[1090]: info: ------------------------------ 427ms Aug 28 20:16:30 volumio volumio[1090]: info: ------------------------------ 409ms Aug 28 20:16:30 volumio volumio[1090]: info: ------------------------------ 407ms Aug 28 20:16:30 volumio sudo[4018]: pam_unix(sudo:session): session closed for user root Aug 28 20:16:30 volumio sudo[4020]: pam_unix(sudo:session): session closed for user root Aug 28 20:16:30 volumio volumio[1090]: info: peppymeterbasic Daemon Stop Aug 28 20:16:30 volumio volumio[1090]: info: peppyspectrum Daemon Stop Aug 28 20:16:31 volumio wpa_supplicant[915]: wlan0: Failed to initiate sched scan Aug 28 20:16:31 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Aug 28 20:16:31 volumio sudo[4120]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 28 20:16:31 volumio sudo[4120]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 20:16:31 volumio volumio[1090]: info: Received Get System Info Aug 28 20:16:31 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 20:16:31 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 20:16:31 volumio volumio[1090]: info: Discovery: Getting this device information Aug 28 20:16:31 volumio volumio[1090]: info: CoreCommandRouter::volumioGetState Aug 28 20:16:31 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:31 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 20:16:31 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 28 20:16:31 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 28 20:16:31 volumio systemd[1]: Stopping Music Player Daemon... Aug 28 20:16:31 volumio systemd[1]: mpd.service: Succeeded. Aug 28 20:16:31 volumio systemd[1]: Stopped Music Player Daemon. Aug 28 20:16:32 volumio systemd[1]: Starting Music Player Daemon... Aug 28 20:16:32 volumio sudo[4136]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 28 20:16:32 volumio sudo[4136]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 20:16:32 volumio sudo[4136]: pam_unix(sudo:session): session closed for user root Aug 28 20:16:32 volumio volumio5-onboarding[1337]: time=2026-08-28T20:16:32.479-04:00 level=INFO msg="service successfully established" component=discovery/localnet Aug 28 20:16:33 volumio volumio[1090]: error: Upnp client error: Error: This socket has been ended by the other party Aug 28 20:16:35 volumio mpd[4159]: Aug 28 20:16 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 28 20:16:35 volumio systemd[1]: Started Music Player Daemon. Aug 28 20:16:35 volumio sudo[4120]: pam_unix(sudo:session): session closed for user root Aug 28 20:16:35 volumio volumio[1090]: error: updateQueue error: null Aug 28 20:16:36 volumio wpa_supplicant[915]: wlan0: Failed to initiate sched scan Aug 28 20:16:37 volumio volumio[1304]: ....................................................................................................................................++++ Aug 28 20:16:37 volumio volumio[1090]: info: Received Get System Info Aug 28 20:16:37 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 20:16:37 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 20:16:37 volumio volumio[1090]: info: Discovery: Getting this device information Aug 28 20:16:37 volumio volumio[1090]: info: CoreCommandRouter::volumioGetState Aug 28 20:16:37 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:37 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 20:16:37 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 28 20:16:37 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 28 20:16:37 volumio volumio5-onboarding[1337]: time=2026-08-28T20:16:37.955-04:00 level=INFO msg="service successfully established" component=discovery/localnet Aug 28 20:16:42 volumio wpa_supplicant[915]: wlan0: Failed to initiate sched scan Aug 28 20:16:42 volumio volumio[1304]: .........................++++ Aug 28 20:16:42 volumio volumio[1304]: e is 65537 (0x010001) Aug 28 20:16:42 volumio volumio[1304]: writing RSA key Aug 28 20:16:42 volumio volumio[1090]: info: Received Get System Info Aug 28 20:16:42 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 20:16:42 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 20:16:42 volumio volumio[1090]: info: Discovery: Getting this device information Aug 28 20:16:42 volumio volumio[1090]: info: CoreCommandRouter::volumioGetState Aug 28 20:16:42 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:42 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 20:16:42 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 28 20:16:42 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 28 20:16:43 volumio volumio5-onboarding[1337]: time=2026-08-28T20:16:43.587-04:00 level=INFO msg="service successfully established" component=discovery/localnet Aug 28 20:16:47 volumio wpa_supplicant[915]: wlan0: Failed to initiate sched scan Aug 28 20:16:48 volumio volumio[1090]: info: Received Get System Info Aug 28 20:16:48 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 20:16:48 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 20:16:48 volumio volumio[1090]: info: Discovery: Getting this device information Aug 28 20:16:48 volumio volumio[1090]: info: CoreCommandRouter::volumioGetState Aug 28 20:16:48 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:48 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 20:16:48 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 28 20:16:48 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 28 20:16:49 volumio volumio5-onboarding[1337]: time=2026-08-28T20:16:49.100-04:00 level=INFO msg="service successfully established" component=discovery/localnet Aug 28 20:16:53 volumio wpa_supplicant[915]: wlan0: Failed to initiate sched scan Aug 28 20:16:53 volumio volumio[1090]: info: Received Get System Info Aug 28 20:16:53 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 20:16:53 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 20:16:53 volumio volumio[1090]: info: Discovery: Getting this device information Aug 28 20:16:53 volumio volumio[1090]: info: CoreCommandRouter::volumioGetState Aug 28 20:16:53 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:53 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 20:16:53 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 28 20:16:53 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 28 20:16:54 volumio kernel: hwmon hwmon1: Voltage normalised Aug 28 20:16:54 volumio volumio5-onboarding[1337]: time=2026-08-28T20:16:54.749-04:00 level=INFO msg="service successfully established" component=discovery/localnet Aug 28 20:16:56 volumio kernel: hwmon hwmon1: Undervoltage detected! Aug 28 20:16:58 volumio wpa_supplicant[915]: wlan0: Failed to initiate sched scan Aug 28 20:16:59 volumio volumio[1090]: info: Received Get System Info Aug 28 20:16:59 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 20:16:59 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 20:16:59 volumio volumio[1090]: info: Discovery: Getting this device information Aug 28 20:16:59 volumio volumio[1090]: info: CoreCommandRouter::volumioGetState Aug 28 20:16:59 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:16:59 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 20:16:59 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 28 20:16:59 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 28 20:17:00 volumio volumio5-onboarding[1337]: time=2026-08-28T20:17:00.283-04:00 level=INFO msg="service successfully established" component=discovery/localnet Aug 28 20:17:00 volumio kernel: hwmon hwmon1: Voltage normalised Aug 28 20:17:04 volumio wpa_supplicant[915]: wlan0: Failed to initiate sched scan Aug 28 20:17:04 volumio volumio[1090]: info: Received Get System Info Aug 28 20:17:04 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 20:17:04 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 20:17:04 volumio volumio[1090]: info: Discovery: Getting this device information Aug 28 20:17:04 volumio volumio[1090]: info: CoreCommandRouter::volumioGetState Aug 28 20:17:04 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:17:04 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 20:17:04 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 28 20:17:04 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 28 20:17:04 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Aug 28 20:17:04 volumio volumio[1090]: info: In handleBrowseUri, curUri=spotify Aug 28 20:17:05 volumio volumio[1090]: info: Preload queue cleared Aug 28 20:17:05 volumio volumio[1090]: info: Preload queue cleared Aug 28 20:17:05 volumio volumio[1090]: info: Preload queue cleared Aug 28 20:17:05 volumio volumio[1090]: info: Preload queue cleared Aug 28 20:17:05 volumio volumio5-onboarding[1337]: time=2026-08-28T20:17:05.709-04:00 level=INFO msg="service successfully established" component=discovery/localnet Aug 28 20:17:06 volumio kernel: hwmon hwmon1: Undervoltage detected! Aug 28 20:17:09 volumio wpa_supplicant[915]: wlan0: Failed to initiate sched scan Aug 28 20:17:10 volumio volumio[1090]: info: Received Get System Info Aug 28 20:17:10 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 20:17:10 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 20:17:10 volumio volumio[1090]: info: Discovery: Getting this device information Aug 28 20:17:10 volumio volumio[1090]: info: CoreCommandRouter::volumioGetState Aug 28 20:17:10 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:17:10 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 20:17:10 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 28 20:17:10 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 28 20:17:10 volumio kernel: hwmon hwmon1: Voltage normalised Aug 28 20:17:11 volumio volumio5-onboarding[1337]: time=2026-08-28T20:17:11.211-04:00 level=INFO msg="service successfully established" component=discovery/localnet Aug 28 20:17:14 volumio kernel: hwmon hwmon1: Undervoltage detected! Aug 28 20:17:15 volumio wpa_supplicant[915]: wlan0: Failed to initiate sched scan Aug 28 20:17:15 volumio volumio[1090]: info: Received Get System Info Aug 28 20:17:15 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 20:17:15 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 20:17:15 volumio volumio[1090]: info: Discovery: Getting this device information Aug 28 20:17:15 volumio volumio[1090]: info: CoreCommandRouter::volumioGetState Aug 28 20:17:15 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:17:15 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 20:17:15 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 28 20:17:15 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 28 20:17:16 volumio volumio5-onboarding[1337]: time=2026-08-28T20:17:16.833-04:00 level=INFO msg="service successfully established" component=discovery/localnet Aug 28 20:17:20 volumio wpa_supplicant[915]: wlan0: Failed to initiate sched scan Aug 28 20:17:21 volumio volumio[1090]: info: Received Get System Info Aug 28 20:17:21 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 28 20:17:21 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 28 20:17:21 volumio volumio[1090]: info: Discovery: Getting this device information Aug 28 20:17:21 volumio volumio[1090]: info: CoreCommandRouter::volumioGetState Aug 28 20:17:21 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:17:21 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 28 20:17:21 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 28 20:17:21 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 28 20:17:22 volumio volumio5-onboarding[1337]: time=2026-08-28T20:17:22.388-04:00 level=INFO msg="service successfully established" component=discovery/localnet Aug 28 20:17:22 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Aug 28 20:17:22 volumio volumio[1090]: info: In handleBrowseUri, curUri=spotify:playlist:37i9dQZF1DXcBWIGoYBM5M Aug 28 20:17:24 volumio volumio[1090]: info: Preload queue cleared Aug 28 20:17:24 volumio volumio[1090]: info: Preloading song: spotify:track:4LfCY65LvojKjWEnU7fNN4 Aug 28 20:17:24 volumio volumio[1090]: info: Preloading song: spotify:track:0eAuGrXyGFYwur9ARUe7LJ Aug 28 20:17:24 volumio volumio[1090]: info: Preloading song: spotify:track:20jbSiX29FDX4oQxBXyUEi Aug 28 20:17:24 volumio volumio[1090]: info: Preloading song: spotify:track:7bxaFZ1O3cHkgLKMsdC3xR Aug 28 20:17:24 volumio volumio[1090]: info: Preloading song: spotify:track:0kosUz0jePvjiz4ctmR6wL Aug 28 20:17:24 volumio volumio[1090]: info: Preloading song: spotify:track:3ouNEk0tv5TTi8VWMe1xbX Aug 28 20:17:24 volumio volumio[1090]: info: Preloading song: spotify:track:7yNf9YjeO5JXUE3JEBgnYc Aug 28 20:17:24 volumio volumio[1090]: info: Preloading song: spotify:track:02HyFYmpzt02VJ8k0CqxKj Aug 28 20:17:24 volumio volumio[1090]: info: Preloading song: spotify:track:65DbTqJKhbwqYbZ1Okr0rc Aug 28 20:17:24 volumio volumio[1090]: info: Preloading song: spotify:track:1qbmS6ep2hbBRaEZFpn7BX Aug 28 20:17:24 volumio volumio[1090]: info: Preloading song: spotify:track:4qW3BbQAwZsrnu8a3ZRdyT Aug 28 20:17:24 volumio volumio[1090]: info: Preloading song: spotify:track:6LJYBq1h5GElQO4sKFdRA9 Aug 28 20:17:24 volumio volumio[1090]: info: Preloading song: spotify:track:70pVCVMGjmIWPbWXDwf11e Aug 28 20:17:24 volumio volumio[1090]: info: Preloading song: spotify:track:3h5T5JypYU7huFiVYhv1dr Aug 28 20:17:24 volumio volumio[1090]: info: Preloading song: spotify:track:0kl6Ozan3fuUdCl6TlB15v Aug 28 20:17:24 volumio volumio[1090]: info: Preloading song: spotify:track:514joG57v4yKTsfQmz7stz Aug 28 20:17:24 volumio volumio[1090]: info: Preloading song: spotify:track:7MZHqgTVTnN6xZGYAcEEAf Aug 28 20:17:24 volumio volumio[1090]: info: Preloading song: spotify:track:3sK8wGT43QFpWrvNQsrQya Aug 28 20:17:24 volumio volumio[1090]: info: Preloading song: spotify:track:31TXxq8gfgYyrYClnYY48m Aug 28 20:17:24 volumio volumio[1090]: info: Preloading song: spotify:track:75QkBCdRc5DGgcyPiVSg4b Aug 28 20:17:24 volumio volumio[1090]: info: Preloading song: spotify:track:55lijDD6OAjLFFUHU9tcDm Aug 28 20:17:24 volumio volumio[1090]: info: Preloading song: spotify:track:5BZsQlgw21vDOAjoqkNgKb Aug 28 20:17:24 volumio volumio[1090]: info: Preloading song: spotify:track:4EoJ151oQ5jY48z4RhSE96 Aug 28 20:17:24 volumio volumio[1090]: info: Preloading song: spotify:track:5y2ijHECwFYWqcAHKTZgzD Aug 28 20:17:24 volumio volumio[1090]: info: Preloading song: spotify:track:3oTuTpF1F3A7rEC6RKsMRz Aug 28 20:17:24 volumio volumio[1090]: info: Preloading song: spotify:track:2oWFJJW9d1bIra3zHjR6GO Aug 28 20:17:24 volumio volumio[1090]: info: Preloading song: spotify:track:1DwscornXpj8fmOmYVlqZt Aug 28 20:17:24 volumio volumio[1090]: info: Preloading song: spotify:track:7e4zDInS6tA2jwzphvs2Ay Aug 28 20:17:24 volumio volumio[1090]: info: Preloading song: spotify:track:6oUUmBcUbZa5O48V5pjgAD Aug 28 20:17:24 volumio volumio[1090]: info: Preloading song: spotify:track:6sGIMrtIzQjdzNndVxe397 Aug 28 20:17:24 volumio volumio[1090]: info: Preloading song: spotify:track:36idurZmYRjJ56KQ8JD9bN Aug 28 20:17:24 volumio volumio[1090]: info: Preloading song: spotify:track:5uPaqMMt59KGrdKIitDRqa Aug 28 20:17:24 volumio volumio[1090]: info: Preloading song: spotify:track:6Bd6dcMIg6EPfF7cYTWa36 Aug 28 20:17:24 volumio volumio[1090]: info: Preloading song: spotify:track:3qhlB30KknSejmIvZZLjOD Aug 28 20:17:24 volumio volumio[1090]: info: Preloading song: spotify:track:6ZAuQOgLrNQb9s7BXheuTy Aug 28 20:17:24 volumio volumio[1090]: info: Preloading song: spotify:track:37UCSVSqiPGdR1DijOFyYY Aug 28 20:17:24 volumio volumio[1090]: info: Preloading song: spotify:track:4W6yulge3h7FSy0zGFrNKs Aug 28 20:17:24 volumio volumio[1090]: info: Preloading song: spotify:track:7ujApz6iFP3PmKIDGcaGbe Aug 28 20:17:24 volumio volumio[1090]: info: Preloading song: spotify:track:3xwMjQriBVW0OGEvNKo9c0 Aug 28 20:17:24 volumio volumio[1090]: info: Preloading song: spotify:track:42UBPzRMh5yyz0EDPr6fr1 Aug 28 20:17:24 volumio volumio[1090]: info: Preloading song: spotify:track:7tICCrK3CcyRFKza7yrR0z Aug 28 20:17:24 volumio volumio[1090]: info: Preloading song: spotify:track:7gtG45ieyQzKtNKobfLd49 Aug 28 20:17:24 volumio volumio[1090]: info: Preloading song: spotify:track:12bYYQaLqHliSXvRIYlq8G Aug 28 20:17:24 volumio volumio[1090]: info: Preloading song: spotify:track:22NHkFYbgxB2Zirj29Gbp8 Aug 28 20:17:24 volumio volumio[1090]: info: Preloading song: spotify:track:5IMpnpD0tQVcqWlVIgtAtV Aug 28 20:17:24 volumio volumio[1090]: info: Preloading song: spotify:track:05CjWFAcAlY6O9po6Qwakk Aug 28 20:17:24 volumio volumio[1090]: info: Preloading song: spotify:track:4Jsus7N4d8hFB2G9gcRdBv Aug 28 20:17:24 volumio volumio[1090]: info: Preloading song: spotify:track:5ViLKrbyL3HD6wsq3AB9eI Aug 28 20:17:24 volumio volumio[1090]: info: Preloading song: spotify:track:68lbSrXDORS51pmyjZv712 Aug 28 20:17:24 volumio volumio[1090]: info: Preloading song: spotify:track:3B4cjvGlPvyBLNG3AzEgkZ Aug 28 20:17:24 volumio volumio[1090]: info: Exploding uri spotify:track:4LfCY65LvojKjWEnU7fNN4 in service spop Aug 28 20:17:24 volumio volumio[1090]: info: Preload queue cleared Aug 28 20:17:24 volumio volumio[1090]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 28 20:17:24 volumio volumio[1090]: info: CoreStateMachine::ClearQueue Aug 28 20:17:24 volumio volumio[1090]: info: CoreStateMachine::stop Aug 28 20:17:24 volumio volumio[1090]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 28 20:17:24 volumio volumio[1090]: info: CoreStateMachine::updateTrackBlock Aug 28 20:17:24 volumio volumio[1090]: info: CorePlayQueue::getTrackBlock Aug 28 20:17:24 volumio volumio[1090]: info: CoreStateMachine::stPlaybackTimer Aug 28 20:17:24 volumio volumio[1090]: info: CoreStateMachine::pushState Aug 28 20:17:24 volumio volumio[1090]: info: CorePlayQueue::getTrack 0 Aug 28 20:17:24 volumio volumio[1090]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 20:17:24 volumio volumio[1090]: info: CoreCommandRouter::volumioPushState Aug 28 20:17:24 volumio volumio[1090]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 28 20:17:24 volumio volumio[1090]: Error: Failed to convert napi value String into rust type `f64` on TimelineInfo.duration Aug 28 20:17:24 volumio volumio[1090]: at FerrumStreamingControlTechnology.updateStateOnPlayer (/data/plugins/user_interface/ferrum_streaming_control_technology/index.js:72:12) Aug 28 20:17:24 volumio volumio[1090]: at FerrumStreamingControlTechnology.pushState (/data/plugins/user_interface/ferrum_streaming_control_technology/index.js:182:10) Aug 28 20:17:24 volumio volumio[1090]: at /volumio/app/index.js:326:81 Aug 28 20:17:24 volumio volumio[1090]: at fastMap (/volumio/node_modules/fast.js/array/map.js:21:17) Aug 28 20:17:24 volumio volumio[1090]: at Function.fastMap [as map] (/volumio/node_modules/fast.js/map.js:18:12) Aug 28 20:17:24 volumio volumio[1090]: at CoreCommandRouter.volumioPushState (/volumio/app/index.js:324:13) Aug 28 20:17:24 volumio volumio[1090]: at CoreStateMachine.pushState (/volumio/app/statemachine.js:534:22) Aug 28 20:17:24 volumio volumio[1090]: at CoreStateMachine.stop (/volumio/app/statemachine.js:1256:12) Aug 28 20:17:24 volumio volumio[1090]: at CoreStateMachine.clearQueue (/volumio/app/statemachine.js:277:8) Aug 28 20:17:24 volumio volumio[1090]: at CoreCommandRouter.replaceAndPlay (/volumio/app/index.js:494:21) Aug 28 20:17:24 volumio volumio[1090]: at CoreCommandRouter.playItemsList (/volumio/app/index.js:484:17) Aug 28 20:17:24 volumio volumio[1090]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:142:33) Aug 28 20:17:24 volumio volumio[1090]: at Socket.emit (events.js:315:20) Aug 28 20:17:24 volumio volumio[1090]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Aug 28 20:17:24 volumio volumio[1090]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Aug 28 20:17:24 volumio volumio[1090]: code: 'NumberExpected' Aug 28 20:17:24 volumio volumio[1090]: } Aug 28 20:17:24 volumio volumio[1090]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 28 20:17:25 volumio sudo[8626]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-08-28 20:16 Aug 28 20:17:25 volumio sudo[8626]: 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="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 10:59:40 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="37c6ab864cb114e1344d540995c69f86"