-- Logs begin at Wed 2025-12-17 12:42:27 CST, end at Wed 2025-12-17 12:44:10 CST. -- Dec 17 12:43:00 volumio volumio[1097]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Dec 17 12:43:01 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 17 12:43:01 volumio volumio[1097]: info: CoreCommandRouter::volumioGetVisibleSources Dec 17 12:43:01 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 17 12:43:01 volumio volumio[1097]: info: CoreCommandRouter::volumioGetState Dec 17 12:43:01 volumio volumio[1097]: info: CorePlayQueue::getTrack 0 Dec 17 12:43:01 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 17 12:43:01 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 17 12:43:01 volumio volumio[1097]: info: Received Get System Info Dec 17 12:43:01 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 17 12:43:01 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 17 12:43:01 volumio volumio[1097]: info: Discovery: Getting this device information Dec 17 12:43:01 volumio volumio[1097]: info: CoreCommandRouter::volumioGetState Dec 17 12:43:01 volumio volumio[1097]: info: CorePlayQueue::getTrack 0 Dec 17 12:43:01 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 17 12:43:01 volumio volumio[1097]: info: CoreCommandRouter::volumioGetState Dec 17 12:43:01 volumio volumio[1097]: info: CorePlayQueue::getTrack 0 Dec 17 12:43:01 volumio volumio[1097]: info: Listing playlists Dec 17 12:43:02 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 17 12:43:02 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 17 12:43:02 volumio sudo[1660]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 17 12:43:02 volumio sudo[1660]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 17 12:43:02 volumio sudo[1665]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 17 12:43:02 volumio sudo[1665]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 17 12:43:02 volumio sudo[1662]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 17 12:43:02 volumio sudo[1662]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 17 12:43:02 volumio systemd[1]: Started MPD Monitor Service. Dec 17 12:43:02 volumio sudo[1660]: pam_unix(sudo:session): session closed for user root Dec 17 12:43:02 volumio mpd_monitor.sh[1667]: MPD Monitor Service: Starting MPD Monitor Service Dec 17 12:43:02 volumio volumio[1097]: info: Successfully started MPD Monitor Dec 17 12:43:02 volumio systemd[1]: Stopping MPD Monitor Service... Dec 17 12:43:02 volumio systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Dec 17 12:43:02 volumio systemd[1]: mpd_monitor.service: Succeeded. Dec 17 12:43:02 volumio systemd[1]: Stopped MPD Monitor Service. Dec 17 12:43:02 volumio systemd[1]: Started MPD Monitor Service. Dec 17 12:43:02 volumio sudo[1665]: pam_unix(sudo:session): session closed for user root Dec 17 12:43:02 volumio volumio[1097]: info: Successfully started MPD Monitor Dec 17 12:43:02 volumio sudo[1662]: pam_unix(sudo:session): session closed for user root Dec 17 12:43:02 volumio mpd_monitor.sh[1670]: MPD Monitor Service: Starting MPD Monitor Service Dec 17 12:43:02 volumio volumio[1097]: info: Successfully started MPD Monitor Dec 17 12:43:02 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 17 12:43:03 volumio volumio[1097]: info: Received Get System Info Dec 17 12:43:03 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 17 12:43:03 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 17 12:43:03 volumio volumio[1097]: info: Discovery: Getting this device information Dec 17 12:43:03 volumio volumio[1097]: info: CoreCommandRouter::volumioGetState Dec 17 12:43:03 volumio volumio[1097]: info: CorePlayQueue::getTrack 0 Dec 17 12:43:03 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 17 12:43:03 volumio sudo[1689]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 17 12:43:03 volumio sudo[1689]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 17 12:43:03 volumio sudo[1689]: pam_unix(sudo:session): session closed for user root Dec 17 12:43:03 volumio sudo[1693]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 17 12:43:03 volumio sudo[1693]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 17 12:43:03 volumio sudo[1693]: pam_unix(sudo:session): session closed for user root Dec 17 12:43:03 volumio sudo[1697]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 17 12:43:03 volumio sudo[1697]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 17 12:43:03 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Dec 17 12:43:03 volumio sudo[1697]: pam_unix(sudo:session): session closed for user root Dec 17 12:43:03 volumio volumio[1097]: info: Upmpdcli Daemon Started Dec 17 12:43:03 volumio volumio[1699]: Generating RSA private key, 4096 bit long modulus (2 primes) Dec 17 12:43:04 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 17 12:43:04 volumio volumio[1097]: info: Discovery: adding a4a38f5c-3c06-4b6c-addf-1f4213f4058a Dec 17 12:43:04 volumio volumio[1097]: info: Discovery: Found device Volumio Dec 17 12:43:04 volumio volumio[1097]: info: CoreCommandRouter::volumioGetState Dec 17 12:43:04 volumio volumio[1097]: info: CorePlayQueue::getTrack 0 Dec 17 12:43:04 volumio volumio[1097]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Dec 17 12:43:10 volumio volumio[1097]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 17 12:43:10 volumio volumio[1097]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 17 12:43:10 volumio volumio[1097]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 17 12:43:10 volumio volumio[1097]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 17 12:43:10 volumio volumio[1097]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 17 12:43:10 volumio volumio[1097]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 17 12:43:10 volumio volumio[1097]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 17 12:43:10 volumio volumio[1097]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 17 12:43:10 volumio volumio[1097]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 17 12:43:10 volumio volumio[1097]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 17 12:43:10 volumio volumio[1097]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Dec 17 12:43:10 volumio volumio[1097]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 17 12:43:10 volumio volumio[1097]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 17 12:43:10 volumio volumio[1097]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 17 12:43:10 volumio volumio[1097]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 17 12:43:10 volumio volumio[1097]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 17 12:43:10 volumio volumio[1097]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 17 12:43:10 volumio volumio[1097]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 17 12:43:10 volumio volumio[1097]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 17 12:43:10 volumio volumio[1097]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 17 12:43:10 volumio volumio[1097]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 17 12:43:10 volumio volumio[1097]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Dec 17 12:43:10 volumio volumio[1097]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 17 12:43:10 volumio volumio[1097]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 17 12:43:10 volumio volumio[1097]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 17 12:43:10 volumio volumio[1097]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 17 12:43:10 volumio volumio[1097]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 17 12:43:10 volumio volumio[1097]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 17 12:43:10 volumio volumio[1097]: info: Adding plugin bluetooth to MyMusic Plugins Dec 17 12:43:10 volumio volumio[1097]: info: Adding plugin multiroom to MyMusic Plugins Dec 17 12:43:10 volumio volumio[1097]: info: Adding plugin metavolumio to MyMusic Plugins Dec 17 12:43:10 volumio volumio[1097]: info: Adding plugin cd_controller to MyMusic Plugins Dec 17 12:43:10 volumio volumio[1097]: info: Adding plugin qobuzconnect to MyMusic Plugins Dec 17 12:43:10 volumio volumio[1097]: info: Adding plugin smart_inputs to MyMusic Plugins Dec 17 12:43:10 volumio volumio[1097]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 17 12:43:10 volumio volumio[1097]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 17 12:43:11 volumio volumio[1097]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 17 12:43:11 volumio volumio[1097]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 17 12:43:11 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 17 12:43:11 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 17 12:43:11 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 17 12:43:11 volumio volumio[1097]: info: Starting MyVolumio Remote Streaming Endpoints Dec 17 12:43:11 volumio volumio[1097]: info: MyVolumio not started Dec 17 12:43:11 volumio volumio[1097]: info: Initializing device activation check Dec 17 12:43:11 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 17 12:43:11 volumio volumio[1097]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 17 12:43:11 volumio volumio[1097]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 17 12:43:12 volumio volumio[1097]: info: Starting Streaming Service Transparent Proxy Dec 17 12:43:12 volumio volumio[1097]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 17 12:43:12 volumio volumio[1097]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 17 12:43:12 volumio volumio[1097]: info: Streaming services startup Dec 17 12:43:12 volumio volumio[1097]: info: Starting Streaming Daemon Dec 17 12:43:12 volumio sudo[1899]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 17 12:43:12 volumio sudo[1899]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 17 12:43:12 volumio sudo[1899]: pam_unix(sudo:session): session closed for user root Dec 17 12:43:12 volumio volumio[1097]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Dec 17 12:43:12 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 17 12:43:12 volumio volumio[1097]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 17 12:43:12 volumio volumio[1097]: error: Cannot start Volumio Streaming Daemon Dec 17 12:43:12 volumio volumio[1097]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 17 12:43:12 volumio volumio[1097]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 17 12:43:12 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Dec 17 12:43:12 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 17 12:43:12 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 17 12:43:12 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 17 12:43:12 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 17 12:43:12 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 17 12:43:12 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 17 12:43:12 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 17 12:43:12 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 17 12:43:12 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 17 12:43:12 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Dec 17 12:43:12 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 17 12:43:13 volumio volumio[1097]: STREAMING PROXY: Starting server on port 3245 Dec 17 12:43:13 volumio volumio[1097]: Node JS runtime: 14 Dec 17 12:43:14 volumio volumio[1699]: ...........................................................................................................................................................................................................................................................................................................................................................................................................................++++ Dec 17 12:43:15 volumio volumio[1699]: ..............................................++++ Dec 17 12:43:15 volumio volumio[1699]: e is 65537 (0x010001) Dec 17 12:43:15 volumio volumio[1699]: writing RSA key Dec 17 12:43:22 volumio systemd[1]: systemd-timedated.service: Succeeded. Dec 17 12:43:26 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 17 12:43:26 volumio volumio[1097]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Dec 17 12:43:26 volumio volumio[1097]: info: CoreCommandRouter::volumioGetState Dec 17 12:43:26 volumio volumio[1097]: info: CorePlayQueue::getTrack 0 Dec 17 12:43:27 volumio volumio[1097]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Dec 17 12:43:27 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 17 12:43:27 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 17 12:43:27 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 17 12:43:27 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 17 12:43:27 volumio volumio-remote-updater[758]: Test mode enabled Dec 17 12:43:27 volumio volumio-remote-updater[758]: No alpha test mode Dec 17 12:43:27 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Dec 17 12:43:28 volumio volumio[1097]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 17 12:43:28 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 17 12:43:29 volumio systemd[1]: Started My Shell Script. Dec 17 12:43:29 volumio systemd[1]: Reached target Multi-User System. Dec 17 12:43:29 volumio systemd[1]: Reached target Graphical Interface. Dec 17 12:43:29 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Dec 17 12:43:29 volumio sudo[1961]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/bash /home/volumio/scripts/peppy.sh Dec 17 12:43:29 volumio sudo[1961]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 17 12:43:29 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Dec 17 12:43:29 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Dec 17 12:43:29 volumio systemd[1]: Startup finished in 7.524s (firmware) + 2.761s (loader) + 10.909s (kernel) + 1min 3.726s (userspace) = 1min 24.922s. Dec 17 12:43:31 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 12:43:31 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 12:43:31 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 17 12:43:31 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 17 12:43:31 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 17 12:43:31 volumio volumio[1097]: info: CoreCommandRouter::volumioGetBrowseSources Dec 17 12:43:31 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 17 12:43:32 volumio volumio[1097]: error: MyVolumio Plugin failed to authenticate in a timely fashion Dec 17 12:43:32 volumio volumio[1097]: info: Completed starting MyVolumio Plugin Dec 17 12:43:32 volumio volumio[1097]: [Metrics] CommandRouter: 43s 275.75ms Dec 17 12:43:32 volumio volumio[1097]: info: CoreCommandRouter::volumiosetStartupVolume Dec 17 12:43:32 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 17 12:43:32 volumio volumio[1097]: info: VolumeController:: Setting startup Volume 40 Dec 17 12:43:32 volumio volumio[1097]: info: VolumeController::SetAlsaVolume40 Dec 17 12:43:32 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 17 12:43:32 volumio volumio[1097]: info: CoreCommandRouter::Close All Modals sent Dec 17 12:43:32 volumio volumio[1097]: info: CoreCommandRouter::Close All Modals sent Dec 17 12:43:32 volumio volumio[1097]: info: CoreStateMachine::pushState Dec 17 12:43:32 volumio volumio[1097]: info: CorePlayQueue::getTrack 0 Dec 17 12:43:32 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 17 12:43:32 volumio volumio[1097]: info: CoreCommandRouter::volumioPushState Dec 17 12:43:33 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 17 12:43:33 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 17 12:43:33 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 17 12:43:36 volumio ntpd[966]: Soliciting pool server 193.182.111.142 Dec 17 12:43:36 volumio ntpd[966]: Soliciting pool server 193.182.111.14 Dec 17 12:43:37 volumio ntpd[966]: Soliciting pool server 210.16.166.99 Dec 17 12:43:38 volumio ntpd[966]: Soliciting pool server 84.16.73.33 Dec 17 12:43:38 volumio ntpd[966]: Soliciting pool server 162.159.200.1 Dec 17 12:43:39 volumio volumio[1097]: info: BOOT COMPLETED Dec 17 12:43:39 volumio volumio[1097]: info: CoreCommandRouter::volumioGetState Dec 17 12:43:39 volumio volumio[1097]: info: CorePlayQueue::getTrack 0 Dec 17 12:43:40 volumio ntpd[966]: Soliciting pool server 119.28.206.193 Dec 17 12:43:40 volumio ntpd[966]: Soliciting pool server 139.199.214.202 Dec 17 12:43:41 volumio ntpd[966]: Soliciting pool server 162.159.200.123 Dec 17 12:43:42 volumio ntpd[966]: Soliciting pool server 95.111.202.5 Dec 17 12:43:42 volumio ntpd[966]: Soliciting pool server 129.204.45.40 Dec 17 12:43:42 volumio ntpd[966]: Soliciting pool server 119.28.183.184 Dec 17 12:43:43 volumio ntpd[966]: Soliciting pool server 84.16.67.12 Dec 17 12:43:44 volumio volumio[1097]: info: CoreCommandRouter::volumioGetState Dec 17 12:43:44 volumio volumio[1097]: info: CorePlayQueue::getTrack 0 Dec 17 12:43:44 volumio ntpd[966]: Soliciting pool server 193.182.111.12 Dec 17 12:43:48 volumio volumio[1097]: info: CoreCommandRouter::volumioGetQueue Dec 17 12:43:48 volumio volumio[1097]: info: CoreStateMachine::getQueue Dec 17 12:43:48 volumio volumio[1097]: info: CorePlayQueue::getQueue Dec 17 12:43:50 volumio volumio[1097]: info: CoreCommandRouter::volumioGetState Dec 17 12:43:50 volumio volumio[1097]: info: CorePlayQueue::getTrack 0 Dec 17 12:43:53 volumio volumio[1097]: info: Preload queue cleared Dec 17 12:43:53 volumio volumio[1097]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 17 12:43:53 volumio volumio[1097]: info: CoreStateMachine::ClearQueue Dec 17 12:43:53 volumio volumio[1097]: info: CoreStateMachine::stop Dec 17 12:43:53 volumio volumio[1097]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 17 12:43:53 volumio volumio[1097]: info: CorePlayQueue::clearPlayQueue Dec 17 12:43:53 volumio volumio[1097]: info: CorePlayQueue::saveQueue Dec 17 12:43:53 volumio volumio[1097]: info: CoreCommandRouter::volumioPushQueue Dec 17 12:43:53 volumio volumio[1097]: info: CoreStateMachine::addQueueItems Dec 17 12:43:53 volumio volumio[1097]: info: CorePlayQueue::addQueueItems Dec 17 12:43:53 volumio volumio[1097]: info: Preload queue cleared Dec 17 12:43:53 volumio volumio[1097]: info: Adding Item to queue: music-library/INTERNAL/凰逸欣《初恋情人HQCDⅡ（头版限量编号）》[低速原抓WAV+CUE] Dec 17 12:43:53 volumio volumio[1097]: info: Exploding uri music-library/INTERNAL/凰逸欣《初恋情人HQCDⅡ（头版限量编号）》[低速原抓WAV+CUE] in service mpd Dec 17 12:43:53 volumio volumio[1097]: info: ALBUMART /albumart?cacheid=870&path=%2Fmnt%2FINTERNAL%2F%E5%87%B0%E9%80%B8%E6%AC%A3%E3%80%8A%E5%88%9D%E6%81%8B%E6%83%85%E4%BA%BAHQCD%E2%85%A1%EF%BC%88%E5%A4%B4%E7%89%88%E9%99%90%E9%87%8F%E7%BC%96%E5%8F%B7%EF%BC%89%E3%80%8B%5B%E4%BD%8E%E9%80%9F%E5%8E%9F%E6%8A%93WAV%2BCUE%5D%2F%E5%87%B0%E9%80%B8%E6%AC%A3%20-%20%E5%88%9D%E6%81%8B%E6%83%85%E4%BA%BA%20%5BHQ%E2%85%A1%5D.wav&metadata=false Dec 17 12:43:53 volumio volumio[1097]: info: URI /mnt/INTERNAL/凰逸欣《初恋情人HQCDⅡ（头版限量编号）》[低速原抓WAV+CUE]/凰逸欣 - 初恋情人 [HQⅡ].wav Dec 17 12:43:53 volumio volumio[1097]: info: CoreCommandRouter::volumioPushQueue Dec 17 12:43:53 volumio volumio[1097]: info: CorePlayQueue::saveQueue Dec 17 12:43:53 volumio volumio[1097]: info: CoreStateMachine::updateTrackBlock Dec 17 12:43:53 volumio volumio[1097]: info: CorePlayQueue::getTrackBlock Dec 17 12:43:53 volumio volumio[1097]: info: CoreCommandRouter::volumioPlay Dec 17 12:43:53 volumio volumio[1097]: info: CoreStateMachine::play index 0 Dec 17 12:43:53 volumio volumio[1097]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 17 12:43:53 volumio volumio[1097]: info: CoreStateMachine::stop Dec 17 12:43:53 volumio volumio[1097]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 17 12:43:53 volumio volumio[1097]: info: CoreStateMachine::play index undefined Dec 17 12:43:53 volumio volumio[1097]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 17 12:43:53 volumio volumio[1097]: info: CorePlayQueue::getTrack 0 Dec 17 12:43:53 volumio volumio[1097]: info: CoreStateMachine::startPlaybackTimer Dec 17 12:43:53 volumio volumio[1097]: info: CorePlayQueue::getTrack 0 Dec 17 12:43:53 volumio volumio[1097]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/凰逸欣《初恋情人HQCDⅡ（头版限量编号）》[低速原抓WAV+CUE]/凰逸欣 - 初恋情人 [HQⅡ].wav Dec 17 12:43:53 volumio volumio[1097]: verbose: ControllerMpd::sendMpdCommand stop Dec 17 12:43:53 volumio volumio[1097]: info: sendMpdCommand stop took 5 milliseconds Dec 17 12:43:53 volumio volumio[1097]: verbose: ControllerMpd::sendMpdCommand clear Dec 17 12:43:53 volumio volumio[1097]: info: Dec 17 12:43:53 volumio volumio[1097]: ---------------------------- MPD announces system playlist update Dec 17 12:43:53 volumio volumio[1097]: info: Ignoring MPD Status Update Dec 17 12:43:53 volumio volumio[1097]: info: sendMpdCommand clear took 4 milliseconds Dec 17 12:43:53 volumio volumio[1097]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/凰逸欣《初恋情人HQCDⅡ（头版限量编号）》[低速原抓WAV+CUE]/凰逸欣 - 初恋情人 [HQⅡ].wav" Dec 17 12:43:53 volumio volumio[1097]: info: Dec 17 12:43:53 volumio volumio[1097]: ---------------------------- MPD announces system playlist update Dec 17 12:43:53 volumio volumio[1097]: info: Ignoring MPD Status Update Dec 17 12:43:53 volumio volumio[1097]: info: Dec 17 12:43:53 volumio volumio[1097]: ---------------------------- MPD announces system playlist update Dec 17 12:43:53 volumio volumio[1097]: info: Ignoring MPD Status Update Dec 17 12:43:53 volumio volumio[1097]: info: Dec 17 12:43:53 volumio volumio[1097]: ---------------------------- MPD announces system playlist update Dec 17 12:43:53 volumio volumio[1097]: info: Ignoring MPD Status Update Dec 17 12:43:53 volumio volumio[1097]: error: updateQueue error: null Dec 17 12:43:53 volumio volumio[1097]: info: Dec 17 12:43:53 volumio volumio[1097]: ---------------------------- MPD announces system playlist update Dec 17 12:43:53 volumio volumio[1097]: info: Ignoring MPD Status Update Dec 17 12:43:53 volumio volumio[1097]: info: ------------------------------ 14ms Dec 17 12:43:53 volumio volumio[1097]: info: sendMpdCommand add "INTERNAL/凰逸欣《初恋情人HQCDⅡ（头版限量编号）》[低速原抓WAV+CUE]/凰逸欣 - 初恋情人 [HQⅡ].wav" took 11 milliseconds Dec 17 12:43:53 volumio volumio[1097]: info: ------------------------------ 10ms Dec 17 12:43:53 volumio volumio[1097]: info: ------------------------------ 8ms Dec 17 12:43:53 volumio volumio[1097]: info: ------------------------------ 7ms Dec 17 12:43:53 volumio volumio[1097]: verbose: ControllerMpd::sendMpdCommand play Dec 17 12:43:53 volumio volumio[1097]: info: Dec 17 12:43:53 volumio volumio[1097]: ---------------------------- MPD announces system playlist update Dec 17 12:43:53 volumio volumio[1097]: info: Ignoring MPD Status Update Dec 17 12:43:53 volumio volumio[1097]: info: Dec 17 12:43:53 volumio volumio[1097]: ---------------------------- MPD announces system playlist update Dec 17 12:43:53 volumio volumio[1097]: info: Ignoring MPD Status Update Dec 17 12:43:53 volumio volumio[1097]: info: Dec 17 12:43:53 volumio volumio[1097]: ---------------------------- MPD announces system playlist update Dec 17 12:43:53 volumio volumio[1097]: info: Ignoring MPD Status Update Dec 17 12:43:53 volumio volumio[1097]: info: Dec 17 12:43:53 volumio volumio[1097]: ---------------------------- MPD announces state update: player Dec 17 12:43:53 volumio volumio[1097]: info: ------------------------------ 13ms Dec 17 12:43:53 volumio volumio[1097]: info: sendMpdCommand play took 9 milliseconds Dec 17 12:43:53 volumio volumio[1097]: info: ------------------------------ 8ms Dec 17 12:43:53 volumio volumio[1097]: info: ------------------------------ 6ms Dec 17 12:43:53 volumio volumio[1097]: info: ControllerMpd::getState Dec 17 12:43:53 volumio volumio[1097]: verbose: ControllerMpd::sendMpdCommand status Dec 17 12:43:53 volumio volumio[1097]: info: ------------------------------ 6ms Dec 17 12:43:53 volumio volumio[1097]: info: Dec 17 12:43:53 volumio volumio[1097]: ---------------------------- MPD announces state update: player Dec 17 12:43:53 volumio volumio[1097]: info: ControllerMpd::getState Dec 17 12:43:53 volumio volumio[1097]: verbose: ControllerMpd::sendMpdCommand status Dec 17 12:43:53 volumio volumio[1097]: info: Dec 17 12:43:53 volumio volumio[1097]: ---------------------------- MPD announces state update: player Dec 17 12:43:53 volumio volumio[1097]: info: ControllerMpd::getState Dec 17 12:43:53 volumio volumio[1097]: verbose: ControllerMpd::sendMpdCommand status Dec 17 12:43:53 volumio volumio[1097]: info: Dec 17 12:43:53 volumio volumio[1097]: ---------------------------- MPD announces state update: player Dec 17 12:43:53 volumio volumio[1097]: info: ControllerMpd::getState Dec 17 12:43:53 volumio volumio[1097]: verbose: ControllerMpd::sendMpdCommand status Dec 17 12:43:53 volumio volumio[1097]: info: Dec 17 12:43:53 volumio volumio[1097]: ---------------------------- MPD announces state update: player Dec 17 12:43:53 volumio volumio[1097]: info: ControllerMpd::getState Dec 17 12:43:53 volumio volumio[1097]: verbose: ControllerMpd::sendMpdCommand status Dec 17 12:43:53 volumio volumio[1097]: info: sendMpdCommand status took 390 milliseconds Dec 17 12:43:53 volumio volumio[1097]: info: sendMpdCommand status took 389 milliseconds Dec 17 12:43:53 volumio volumio[1097]: info: sendMpdCommand status took 388 milliseconds Dec 17 12:43:53 volumio volumio[1097]: info: sendMpdCommand status took 387 milliseconds Dec 17 12:43:53 volumio volumio[1097]: verbose: ControllerMpd::parseState Dec 17 12:43:53 volumio volumio[1097]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 17 12:43:53 volumio volumio[1097]: verbose: ControllerMpd::parseState Dec 17 12:43:53 volumio volumio[1097]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 17 12:43:53 volumio volumio[1097]: verbose: ControllerMpd::parseState Dec 17 12:43:53 volumio volumio[1097]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 17 12:43:53 volumio volumio[1097]: verbose: ControllerMpd::parseState Dec 17 12:43:53 volumio volumio[1097]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 17 12:43:53 volumio volumio[1097]: info: sendMpdCommand status took 14 milliseconds Dec 17 12:43:53 volumio volumio[1097]: verbose: ControllerMpd::parseState Dec 17 12:43:53 volumio volumio[1097]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 17 12:43:53 volumio volumio[1097]: info: Dec 17 12:43:53 volumio volumio[1097]: ---------------------------- MPD announces state update: player Dec 17 12:43:53 volumio volumio[1097]: info: ControllerMpd::getState Dec 17 12:43:53 volumio volumio[1097]: verbose: ControllerMpd::sendMpdCommand status Dec 17 12:43:53 volumio volumio[1097]: info: Dec 17 12:43:53 volumio volumio[1097]: ---------------------------- MPD announces state update: player Dec 17 12:43:53 volumio volumio[1097]: info: ControllerMpd::getState Dec 17 12:43:53 volumio volumio[1097]: verbose: ControllerMpd::sendMpdCommand status Dec 17 12:43:53 volumio volumio[1097]: info: Dec 17 12:43:53 volumio volumio[1097]: ---------------------------- MPD announces state update: player Dec 17 12:43:53 volumio volumio[1097]: info: ControllerMpd::getState Dec 17 12:43:53 volumio volumio[1097]: verbose: ControllerMpd::sendMpdCommand status Dec 17 12:43:53 volumio volumio[1097]: info: sendMpdCommand playlistinfo took 19 milliseconds Dec 17 12:43:53 volumio volumio[1097]: info: sendMpdCommand playlistinfo took 18 milliseconds Dec 17 12:43:53 volumio volumio[1097]: info: sendMpdCommand playlistinfo took 17 milliseconds Dec 17 12:43:53 volumio volumio[1097]: info: sendMpdCommand playlistinfo took 17 milliseconds Dec 17 12:43:53 volumio volumio[1097]: info: sendMpdCommand playlistinfo took 14 milliseconds Dec 17 12:43:53 volumio volumio[1097]: info: sendMpdCommand status took 14 milliseconds Dec 17 12:43:53 volumio volumio[1097]: info: sendMpdCommand status took 10 milliseconds Dec 17 12:43:53 volumio volumio[1097]: info: sendMpdCommand status took 7 milliseconds Dec 17 12:43:53 volumio volumio[1097]: verbose: ControllerMpd::parseTrackInfo Dec 17 12:43:53 volumio volumio[1097]: verbose: ControllerMpd::parseTrackInfo Dec 17 12:43:53 volumio volumio[1097]: verbose: ControllerMpd::parseTrackInfo Dec 17 12:43:53 volumio volumio[1097]: verbose: ControllerMpd::parseTrackInfo Dec 17 12:43:53 volumio volumio[1097]: verbose: ControllerMpd::parseState Dec 17 12:43:53 volumio volumio[1097]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 17 12:43:53 volumio volumio[1097]: verbose: ControllerMpd::parseState Dec 17 12:43:53 volumio volumio[1097]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 17 12:43:53 volumio volumio[1097]: verbose: ControllerMpd::parseState Dec 17 12:43:53 volumio volumio[1097]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 17 12:43:53 volumio volumio[1097]: info: ControllerMpd::pushState Dec 17 12:43:53 volumio volumio[1097]: info: CoreCommandRouter::servicePushState Dec 17 12:43:53 volumio volumio[1097]: info: CorePlayQueue::getTrack 0 Dec 17 12:43:53 volumio volumio[1097]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3267,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"凰逸欣 - 初恋情人 [HQⅡ].wav","artist":null,"album":null,"uri":"INTERNAL/凰逸欣《初恋情人HQCDⅡ（头版限量编号）》[低速原抓WAV+CUE]/凰逸欣 - 初恋情人 [HQⅡ].wav","trackType":"wav"} Dec 17 12:43:53 volumio volumio[1097]: verbose: CURRENT POSITION 0 Dec 17 12:43:53 volumio volumio[1097]: info: CoreStateMachine::syncState stateService play Dec 17 12:43:53 volumio volumio[1097]: info: CoreStateMachine::syncState currentStatus stop Dec 17 12:43:53 volumio volumio[1097]: info: ControllerMpd::pushState Dec 17 12:43:53 volumio volumio[1097]: info: CoreCommandRouter::servicePushState Dec 17 12:43:53 volumio volumio[1097]: info: CorePlayQueue::getTrack 0 Dec 17 12:43:53 volumio volumio[1097]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3267,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"凰逸欣 - 初恋情人 [HQⅡ].wav","artist":null,"album":null,"uri":"INTERNAL/凰逸欣《初恋情人HQCDⅡ（头版限量编号）》[低速原抓WAV+CUE]/凰逸欣 - 初恋情人 [HQⅡ].wav","trackType":"wav"} Dec 17 12:43:53 volumio volumio[1097]: verbose: CURRENT POSITION 0 Dec 17 12:43:53 volumio volumio[1097]: info: CoreStateMachine::syncState stateService play Dec 17 12:43:53 volumio volumio[1097]: info: CoreStateMachine::syncState currentStatus play Dec 17 12:43:53 volumio volumio[1097]: info: Received an update from plugin. extracting info from payload Dec 17 12:43:53 volumio volumio[1097]: info: CoreStateMachine::pushState Dec 17 12:43:53 volumio volumio[1097]: info: CorePlayQueue::getTrack 0 Dec 17 12:43:53 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 17 12:43:53 volumio volumio[1097]: info: CoreCommandRouter::volumioPushState Dec 17 12:43:53 volumio volumio[1097]: info: CoreStateMachine::pushState Dec 17 12:43:53 volumio volumio[1097]: info: CorePlayQueue::getTrack 0 Dec 17 12:43:53 volumio volumio[1097]: info: CoreCommandRouter::volumioPushState Dec 17 12:43:53 volumio volumio[1097]: info: ControllerMpd::pushState Dec 17 12:43:53 volumio volumio[1097]: info: CoreCommandRouter::servicePushState Dec 17 12:43:53 volumio volumio[1097]: info: CorePlayQueue::getTrack 0 Dec 17 12:43:53 volumio volumio[1097]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3267,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"凰逸欣 - 初恋情人 [HQⅡ].wav","artist":null,"album":null,"uri":"INTERNAL/凰逸欣《初恋情人HQCDⅡ（头版限量编号）》[低速原抓WAV+CUE]/凰逸欣 - 初恋情人 [HQⅡ].wav","trackType":"wav"} Dec 17 12:43:53 volumio volumio[1097]: verbose: CURRENT POSITION 0 Dec 17 12:43:53 volumio volumio[1097]: info: CoreStateMachine::syncState stateService play Dec 17 12:43:53 volumio volumio[1097]: info: CoreStateMachine::syncState currentStatus play Dec 17 12:43:53 volumio volumio[1097]: info: Received an update from plugin. extracting info from payload Dec 17 12:43:53 volumio volumio[1097]: info: CoreStateMachine::pushState Dec 17 12:43:53 volumio volumio[1097]: info: CorePlayQueue::getTrack 0 Dec 17 12:43:53 volumio volumio[1097]: info: CoreCommandRouter::volumioPushState Dec 17 12:43:53 volumio volumio[1097]: info: CoreStateMachine::pushState Dec 17 12:43:53 volumio volumio[1097]: info: CorePlayQueue::getTrack 0 Dec 17 12:43:53 volumio volumio[1097]: info: CoreCommandRouter::volumioPushState Dec 17 12:43:53 volumio volumio[1097]: info: ControllerMpd::pushState Dec 17 12:43:53 volumio volumio[1097]: info: CoreCommandRouter::servicePushState Dec 17 12:43:53 volumio volumio[1097]: info: CorePlayQueue::getTrack 0 Dec 17 12:43:53 volumio volumio[1097]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3267,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"凰逸欣 - 初恋情人 [HQⅡ].wav","artist":null,"album":null,"uri":"INTERNAL/凰逸欣《初恋情人HQCDⅡ（头版限量编号）》[低速原抓WAV+CUE]/凰逸欣 - 初恋情人 [HQⅡ].wav","trackType":"wav"} Dec 17 12:43:53 volumio volumio[1097]: verbose: CURRENT POSITION 0 Dec 17 12:43:53 volumio volumio[1097]: info: CoreStateMachine::syncState stateService play Dec 17 12:43:53 volumio volumio[1097]: info: CoreStateMachine::syncState currentStatus play Dec 17 12:43:53 volumio volumio[1097]: info: Received an update from plugin. extracting info from payload Dec 17 12:43:53 volumio volumio[1097]: info: CoreStateMachine::pushState Dec 17 12:43:53 volumio volumio[1097]: info: CorePlayQueue::getTrack 0 Dec 17 12:43:53 volumio volumio[1097]: info: CoreCommandRouter::volumioPushState Dec 17 12:43:53 volumio volumio[1097]: info: CoreStateMachine::pushState Dec 17 12:43:53 volumio volumio[1097]: info: CorePlayQueue::getTrack 0 Dec 17 12:43:53 volumio volumio[1097]: info: CoreCommandRouter::volumioPushState Dec 17 12:43:53 volumio volumio[1097]: info: ControllerMpd::pushState Dec 17 12:43:53 volumio volumio[1097]: info: CoreCommandRouter::servicePushState Dec 17 12:43:53 volumio volumio[1097]: info: CorePlayQueue::getTrack 0 Dec 17 12:43:53 volumio volumio[1097]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":3267,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"凰逸欣 - 初恋情人 [HQⅡ].wav","artist":null,"album":null,"uri":"INTERNAL/凰逸欣《初恋情人HQCDⅡ（头版限量编号）》[低速原抓WAV+CUE]/凰逸欣 - 初恋情人 [HQⅡ].wav","trackType":"wav"} Dec 17 12:43:53 volumio volumio[1097]: verbose: CURRENT POSITION 0 Dec 17 12:43:53 volumio volumio[1097]: info: CoreStateMachine::syncState stateService play Dec 17 12:43:53 volumio volumio[1097]: info: CoreStateMachine::syncState currentStatus play Dec 17 12:43:53 volumio volumio[1097]: info: Received an update from plugin. extracting info from payload Dec 17 12:43:53 volumio volumio[1097]: info: CoreStateMachine::pushState Dec 17 12:43:53 volumio volumio[1097]: info: CorePlayQueue::getTrack 0 Dec 17 12:43:53 volumio volumio[1097]: info: CoreCommandRouter::volumioPushState Dec 17 12:43:53 volumio volumio[1097]: info: CoreStateMachine::pushState Dec 17 12:43:53 volumio volumio[1097]: info: CorePlayQueue::getTrack 0 Dec 17 12:43:53 volumio volumio[1097]: info: CoreCommandRouter::volumioPushState Dec 17 12:43:53 volumio volumio[1097]: info: ------------------------------ 480ms Dec 17 12:43:53 volumio volumio[1097]: info: ------------------------------ 573ms Dec 17 12:43:53 volumio volumio[1097]: info: ------------------------------ 583ms Dec 17 12:43:53 volumio volumio[1097]: info: ------------------------------ 202ms Dec 17 12:43:53 volumio volumio[1097]: info: sendMpdCommand playlistinfo took 174 milliseconds Dec 17 12:43:53 volumio volumio[1097]: info: sendMpdCommand playlistinfo took 175 milliseconds Dec 17 12:43:53 volumio volumio[1097]: info: sendMpdCommand playlistinfo took 174 milliseconds Dec 17 12:43:53 volumio volumio[1097]: verbose: ControllerMpd::parseTrackInfo Dec 17 12:43:53 volumio volumio[1097]: verbose: ControllerMpd::parseTrackInfo Dec 17 12:43:53 volumio volumio[1097]: info: ControllerMpd::pushState Dec 17 12:43:53 volumio volumio[1097]: info: CoreCommandRouter::servicePushState Dec 17 12:43:53 volumio volumio[1097]: info: CorePlayQueue::getTrack 0 Dec 17 12:43:53 volumio volumio[1097]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":3267,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"凰逸欣 - 初恋情人 [HQⅡ].wav","artist":null,"album":null,"uri":"INTERNAL/凰逸欣《初恋情人HQCDⅡ（头版限量编号）》[低速原抓WAV+CUE]/凰逸欣 - 初恋情人 [HQⅡ].wav","trackType":"wav"} Dec 17 12:43:53 volumio volumio[1097]: verbose: CURRENT POSITION 0 Dec 17 12:43:53 volumio volumio[1097]: info: CoreStateMachine::syncState stateService play Dec 17 12:43:53 volumio volumio[1097]: info: CoreStateMachine::syncState currentStatus play Dec 17 12:43:53 volumio volumio[1097]: info: Received an update from plugin. extracting info from payload Dec 17 12:43:53 volumio volumio[1097]: info: CoreStateMachine::pushState Dec 17 12:43:53 volumio volumio[1097]: info: CorePlayQueue::getTrack 0 Dec 17 12:43:53 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 17 12:43:53 volumio volumio[1097]: info: CoreCommandRouter::volumioPushState Dec 17 12:43:53 volumio volumio[1097]: info: CoreStateMachine::pushState Dec 17 12:43:53 volumio volumio[1097]: info: CorePlayQueue::getTrack 0 Dec 17 12:43:53 volumio volumio[1097]: info: CoreCommandRouter::volumioPushState Dec 17 12:43:53 volumio volumio[1097]: info: ControllerMpd::pushState Dec 17 12:43:53 volumio volumio[1097]: info: CoreCommandRouter::servicePushState Dec 17 12:43:53 volumio volumio[1097]: info: CorePlayQueue::getTrack 0 Dec 17 12:43:53 volumio volumio[1097]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":3267,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"凰逸欣 - 初恋情人 [HQⅡ].wav","artist":null,"album":null,"uri":"INTERNAL/凰逸欣《初恋情人HQCDⅡ（头版限量编号）》[低速原抓WAV+CUE]/凰逸欣 - 初恋情人 [HQⅡ].wav","trackType":"wav"} Dec 17 12:43:53 volumio volumio[1097]: verbose: CURRENT POSITION 0 Dec 17 12:43:53 volumio volumio[1097]: info: CoreStateMachine::syncState stateService play Dec 17 12:43:53 volumio volumio[1097]: info: CoreStateMachine::syncState currentStatus play Dec 17 12:43:53 volumio volumio[1097]: info: Received an update from plugin. extracting info from payload Dec 17 12:43:53 volumio volumio[1097]: info: CoreStateMachine::pushState Dec 17 12:43:53 volumio volumio[1097]: info: CorePlayQueue::getTrack 0 Dec 17 12:43:53 volumio volumio[1097]: info: CoreCommandRouter::volumioPushState Dec 17 12:43:53 volumio volumio[1097]: info: CoreStateMachine::pushState Dec 17 12:43:53 volumio volumio[1097]: info: CorePlayQueue::getTrack 0 Dec 17 12:43:53 volumio volumio[1097]: info: CoreCommandRouter::volumioPushState Dec 17 12:43:54 volumio volumio[1097]: info: ControllerMpd::pushState Dec 17 12:43:54 volumio volumio[1097]: info: CoreCommandRouter::servicePushState Dec 17 12:43:54 volumio volumio[1097]: info: CorePlayQueue::getTrack 0 Dec 17 12:43:54 volumio volumio[1097]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45,"duration":3267,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"凰逸欣 - 初恋情人 [HQⅡ].wav","artist":null,"album":null,"uri":"INTERNAL/凰逸欣《初恋情人HQCDⅡ（头版限量编号）》[低速原抓WAV+CUE]/凰逸欣 - 初恋情人 [HQⅡ].wav","trackType":"wav"} Dec 17 12:43:54 volumio volumio[1097]: verbose: CURRENT POSITION 0 Dec 17 12:43:54 volumio volumio[1097]: info: CoreStateMachine::syncState stateService play Dec 17 12:43:54 volumio volumio[1097]: info: CoreStateMachine::syncState currentStatus play Dec 17 12:43:54 volumio volumio[1097]: info: Received an update from plugin. extracting info from payload Dec 17 12:43:54 volumio volumio[1097]: info: CoreStateMachine::pushState Dec 17 12:43:54 volumio volumio[1097]: info: CorePlayQueue::getTrack 0 Dec 17 12:43:54 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 17 12:43:54 volumio volumio[1097]: info: CoreCommandRouter::volumioPushState Dec 17 12:43:54 volumio volumio[1097]: info: CoreStateMachine::pushState Dec 17 12:43:54 volumio volumio[1097]: info: CorePlayQueue::getTrack 0 Dec 17 12:43:54 volumio volumio[1097]: info: CoreCommandRouter::volumioPushState Dec 17 12:43:54 volumio volumio[1097]: info: ------------------------------ 298ms Dec 17 12:43:54 volumio volumio[1097]: info: ------------------------------ 299ms Dec 17 12:43:54 volumio volumio[1097]: info: ------------------------------ 295ms Dec 17 12:43:55 volumio volumio[1097]: info: CoreCommandRouter::volumioGetState Dec 17 12:43:55 volumio volumio[1097]: info: CorePlayQueue::getTrack 0 Dec 17 12:43:55 volumio sudo[2101]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/bin/bash run_peppymeter.sh Dec 17 12:43:55 volumio sudo[2101]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 17 12:44:00 volumio volumio[1097]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 6 Dec 17 12:44:00 volumio volumio[1097]: info: CoreCommandRouter::volumioGetState Dec 17 12:44:00 volumio volumio[1097]: info: CorePlayQueue::getTrack 0 Dec 17 12:44:06 volumio kernel: usb 1-3.4: USB disconnect, device number 5 Dec 17 12:44:06 volumio volumio[1097]: info: Dec 17 12:44:06 volumio volumio[1097]: ---------------------------- USB Audio Device Detached Dec 17 12:44:06 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Dec 17 12:44:06 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 17 12:44:06 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Dec 17 12:44:06 volumio kernel: usb 1-3.4: new full-speed USB device number 6 using xhci_hcd Dec 17 12:44:06 volumio kernel: usb 1-3.4: not running at top speed; connect to a high speed hub Dec 17 12:44:06 volumio kernel: usb 1-3.4: New USB device found, idVendor=20b1, idProduct=000a, bcdDevice= 6.c5 Dec 17 12:44:06 volumio kernel: usb 1-3.4: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Dec 17 12:44:06 volumio kernel: usb 1-3.4: Product: xCORE USB Audio 2.0 Dec 17 12:44:06 volumio kernel: usb 1-3.4: Manufacturer: XMOS Dec 17 12:44:07 volumio kernel: usb 1-3.4: uac_clock_source_is_valid(): cannot get clock validity for id 41 Dec 17 12:44:07 volumio kernel: usb 1-3.4: clock source 41 is not valid, cannot use Dec 17 12:44:07 volumio kernel: usb 1-3.4: 1:2: cannot get freq (v2/v3): err -71 Dec 17 12:44:07 volumio kernel: usb 1-3.4: 1:2: cannot set freq 96000 (v2/v3): err -71 Dec 17 12:44:07 volumio kernel: usb 1-3.4: 1:3 : unsupported format bits 0x100000000 Dec 17 12:44:07 volumio kernel: usb 1-3.4: parse_audio_format_rates_v2v3(): unable to retrieve number of sample rates (clock 41) Dec 17 12:44:07 volumio kernel: usb 1-3.4: 10:0: failed to get current value for ch 1 (-71) Dec 17 12:44:07 volumio kernel: usb 1-3.4: 10:0: failed to get current value for ch 2 (-71) Dec 17 12:44:07 volumio kernel: usb 1-3.4: 10:0: failed to get current value for ch 0 (-71) Dec 17 12:44:07 volumio kernel: usb 1-3.4: 10:0: cannot get min/max values for control 2 (id 10) Dec 17 12:44:07 volumio kernel: usb 1-3.4: 10:0: cannot get min/max values for control 2 (id 10) Dec 17 12:44:07 volumio kernel: usb 1-3.4: USB disconnect, device number 6 Dec 17 12:44:07 volumio systemd-udevd[2173]: controlC5: Failed to open ATTR{/sys/devices/pci0000:00/0000:00:14.0/usb1/1-3/1-3.4/1-3.4:1.0/sound/card5/controlC5/../uevent} for writing: No such file or directory Dec 17 12:44:07 volumio volumio[1097]: info: Dec 17 12:44:07 volumio volumio[1097]: ---------------------------- USB Audio Device Attached Dec 17 12:44:07 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Dec 17 12:44:07 volumio volumio[1097]: error: API:pushError: TypeError: Cannot read property 'name' of null Dec 17 12:44:07 volumio volumio[1097]: info: Dec 17 12:44:07 volumio volumio[1097]: ---------------------------- USB Audio Device Detached Dec 17 12:44:07 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Dec 17 12:44:07 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 17 12:44:07 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Dec 17 12:44:07 volumio kernel: usb 1-3.4: new full-speed USB device number 7 using xhci_hcd Dec 17 12:44:07 volumio kernel: usb 1-3.4: not running at top speed; connect to a high speed hub Dec 17 12:44:07 volumio kernel: usb 1-3.4: New USB device found, idVendor=20b1, idProduct=000a, bcdDevice= 6.c5 Dec 17 12:44:07 volumio kernel: usb 1-3.4: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Dec 17 12:44:07 volumio kernel: usb 1-3.4: Product: xCORE USB Audio 2.0 Dec 17 12:44:07 volumio kernel: usb 1-3.4: Manufacturer: XMOS Dec 17 12:44:08 volumio kernel: usb 1-3.4: 1:3 : unsupported format bits 0x100000000 Dec 17 12:44:09 volumio volumio[1097]: info: Dec 17 12:44:09 volumio volumio[1097]: ---------------------------- USB Audio Device Attached Dec 17 12:44:09 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Dec 17 12:44:09 volumio volumio[1097]: info: CoreCommandRouter::Close All Modals sent Dec 17 12:44:09 volumio volumio[1097]: info: Preparing to save Alsa Options, stopping services first Dec 17 12:44:09 volumio volumio[1097]: info: CoreCommandRouter::volumioGetState Dec 17 12:44:09 volumio volumio[1097]: info: CorePlayQueue::getTrack 0 Dec 17 12:44:09 volumio volumio[1097]: info: CoreCommandRouter::volumioPause Dec 17 12:44:09 volumio volumio[1097]: info: CoreStateMachine::pause Dec 17 12:44:09 volumio volumio[1097]: info: CoreStateMachine::stPlaybackTimer Dec 17 12:44:09 volumio volumio[1097]: info: CoreStateMachine::servicePause Dec 17 12:44:09 volumio volumio[1097]: info: CorePlayQueue::getTrack 0 Dec 17 12:44:09 volumio volumio[1097]: info: CoreCommandRouter::servicePause Dec 17 12:44:09 volumio volumio[1097]: info: ControllerMpd::pause Dec 17 12:44:09 volumio volumio[1097]: verbose: ControllerMpd::sendMpdCommand pause Dec 17 12:44:09 volumio volumio[1097]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"xCORE USB Audio 2.0","alsacard":"x20"},"i2s":false} Dec 17 12:44:09 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 17 12:44:09 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 17 12:44:09 volumio volumio[1097]: info: Setting default mixerSoftMaster for Softvolume device Dec 17 12:44:09 volumio volumio[1097]: info: CoreCommandRouter::volumioUpdateVolumeSettings Dec 17 12:44:09 volumio volumio[1097]: info: Updating Volume Controller Parameters: Device: 5 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Dec 17 12:44:09 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 17 12:44:09 volumio volumio[1097]: info: Disabling external Volume Control Dec 17 12:44:09 volumio volumio[1097]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 17 12:44:09 volumio volumio[1097]: info: Enable softmixer device for audio device undefined Dec 17 12:44:10 volumio volumio[1097]: info: Dec 17 12:44:10 volumio volumio[1097]: ---------------------------- MPD announces state update: player Dec 17 12:44:10 volumio volumio[1097]: info: sendMpdCommand pause took 238 milliseconds Dec 17 12:44:10 volumio volumio[1097]: info: ControllerMpd::getState Dec 17 12:44:10 volumio volumio[1097]: verbose: ControllerMpd::sendMpdCommand status Dec 17 12:44:10 volumio volumio[1097]: info: Dec 17 12:44:10 volumio volumio[1097]: ---------------------------- MPD announces state update: player Dec 17 12:44:10 volumio volumio[1097]: info: ControllerMpd::getState Dec 17 12:44:10 volumio volumio[1097]: verbose: ControllerMpd::sendMpdCommand status Dec 17 12:44:10 volumio volumio[1097]: info: Dec 17 12:44:10 volumio volumio[1097]: ---------------------------- MPD announces state update: player Dec 17 12:44:10 volumio volumio[1097]: info: ControllerMpd::getState Dec 17 12:44:10 volumio volumio[1097]: verbose: ControllerMpd::sendMpdCommand status Dec 17 12:44:10 volumio volumio[1097]: info: Dec 17 12:44:10 volumio volumio[1097]: ---------------------------- MPD announces state update: player Dec 17 12:44:10 volumio volumio[1097]: info: ControllerMpd::getState Dec 17 12:44:10 volumio volumio[1097]: verbose: ControllerMpd::sendMpdCommand status Dec 17 12:44:10 volumio volumio[1097]: info: CoreStateMachine::pushState Dec 17 12:44:10 volumio volumio[1097]: info: CorePlayQueue::getTrack 0 Dec 17 12:44:10 volumio volumio[1097]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 17 12:44:10 volumio volumio[1097]: info: CoreCommandRouter::volumioPushState Dec 17 12:44:10 volumio volumio[1097]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 17 12:44:10 volumio volumio[1097]: Error: ENOTDIR: not a directory, scandir '/tmp/peppyrunning' Dec 17 12:44:10 volumio volumio[1097]: at Object.readdirSync (fs.js:1048:3) Dec 17 12:44:10 volumio volumio[1097]: at rmkidsSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:349:11) Dec 17 12:44:10 volumio volumio[1097]: at rmdirSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:342:7) Dec 17 12:44:10 volumio volumio[1097]: at Function.rimrafSync [as sync] (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:319:62) Dec 17 12:44:10 volumio volumio[1097]: at Object.removeSync (/data/plugins/user_interface/peppy_screensaver/node_modules/fs-extra/lib/remove/index.js:4:17) Dec 17 12:44:10 volumio volumio[1097]: at Socket. (/data/plugins/user_interface/peppy_screensaver/index.js:225:48) Dec 17 12:44:10 volumio volumio[1097]: at Socket.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Dec 17 12:44:10 volumio volumio[1097]: at Socket.onevent (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:278:10) Dec 17 12:44:10 volumio volumio[1097]: at Socket.onpacket (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:236:12) Dec 17 12:44:10 volumio volumio[1097]: at Manager. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Dec 17 12:44:10 volumio volumio[1097]: at Manager.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Dec 17 12:44:10 volumio volumio[1097]: at Manager.ondecoded (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:349:8) Dec 17 12:44:10 volumio volumio[1097]: at Decoder. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Dec 17 12:44:10 volumio volumio[1097]: at Decoder.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Dec 17 12:44:10 volumio volumio[1097]: at Decoder.add (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-parser/index.js:251:12) Dec 17 12:44:10 volumio volumio[1097]: at Manager.ondata (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:339:16) { Dec 17 12:44:10 volumio volumio[1097]: errno: -20, Dec 17 12:44:10 volumio volumio[1097]: syscall: 'scandir', Dec 17 12:44:10 volumio volumio[1097]: code: 'ENOTDIR', Dec 17 12:44:10 volumio volumio[1097]: path: '/tmp/peppyrunning' Dec 17 12:44:10 volumio volumio[1097]: } Dec 17 12:44:10 volumio volumio[1097]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 17 12:44:10 volumio sudo[2231]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-17 12:43 Dec 17 12:44:10 volumio sudo[2231]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 06:18:30 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="33f9369b544b4488ff4979d2ee3813a5"