-- Logs begin at Sun 2025-03-02 18:22:24 CST, end at Sun 2025-03-02 18:25:01 CST. -- Mar 02 18:24:00 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 18:24:00 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 18:24:00 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Mar 02 18:24:00 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 02 18:24:00 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 02 18:24:00 volumio volumio[1118]: info: CoreCommandRouter::volumioGetBrowseSources Mar 02 18:24:00 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 02 18:24:00 volumio jellyfin[1638]: [18:24:00] [INF] [1] Main: Kestrel is listening on 0.0.0.0 Mar 02 18:24:00 volumio ntpd[1172]: Soliciting pool server 106.10.186.201 Mar 02 18:24:00 volumio ntpd[1172]: Soliciting pool server 95.111.200.190 Mar 02 18:24:00 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 02 18:24:00 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 02 18:24:00 volumio volumio-remote-updater[710]: No test mode Mar 02 18:24:00 volumio volumio-remote-updater[710]: No alpha test mode Mar 02 18:24:00 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Mar 02 18:24:00 volumio jellyfin[1638]: [18:24:00] [WRN] [1] Microsoft.AspNetCore.DataProtection.KeyManagement.XmlKeyManager: No XML encryptor configured. Key {90eb92e6-8f59-44ed-9b92-919bf991c7a7} may be persisted to storage in unencrypted form. Mar 02 18:24:01 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 02 18:24:02 volumio volumio[1118]: info: Received Get System Info Mar 02 18:24:02 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 02 18:24:02 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 02 18:24:02 volumio volumio[1118]: info: Discovery: Getting this device information Mar 02 18:24:02 volumio volumio[1118]: info: CoreCommandRouter::volumioGetState Mar 02 18:24:02 volumio volumio[1118]: info: CorePlayQueue::getTrack 0 Mar 02 18:24:02 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 02 18:24:02 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 02 18:24:02 volumio ntpd[1172]: Soliciting pool server 173.234.15.82 Mar 02 18:24:02 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 02 18:24:03 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Mar 02 18:24:03 volumio volumio[1118]: info: MyVolumio login type: Token Mar 02 18:24:04 volumio volumio[1118]: error: MyVolumio Plugin failed to authenticate in a timely fashion Mar 02 18:24:04 volumio volumio[1118]: info: Completed starting MyVolumio Plugin Mar 02 18:24:04 volumio volumio[1118]: info: BOOT COMPLETED Mar 02 18:24:04 volumio volumio[1118]: [Metrics] CommandRouter: 65s 33.12ms Mar 02 18:24:04 volumio volumio[1118]: info: CoreCommandRouter::volumiosetStartupVolume Mar 02 18:24:04 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 02 18:24:04 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 02 18:24:04 volumio volumio[1118]: info: CoreCommandRouter::Close All Modals sent Mar 02 18:24:04 volumio volumio[1118]: info: CoreCommandRouter::Close All Modals sent Mar 02 18:24:04 volumio volumio[1118]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Mar 02 18:24:04 volumio volumio[1118]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Mar 02 18:24:04 volumio volumio[1118]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card Mar 02 18:24:04 volumio volumio[1118]: aplay: main:828: audio open error: No such device Mar 02 18:24:05 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Mar 02 18:24:05 volumio volumio[1118]: info: MyVolumio login type: Token Mar 02 18:24:05 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Mar 02 18:24:05 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 02 18:24:05 volumio RoonBridge[1672]: 00:00:45.240 Warn: exception starting raatserver: System.Net.Sockets.SocketException (0x80004005): Connection refused Mar 02 18:24:05 volumio RoonBridge[1672]: at System.Net.Sockets.TcpClient..ctor (System.String hostname, System.Int32 port) [0x0006d] in <08911f3881ec4a92acc8fdc4d13b73bf>:0 Mar 02 18:24:05 volumio RoonBridge[1672]: at Sooloos.RAATServer.ConnectOrStartAndWaitForExit (System.String path, System.String args, System.Action`1[T] status, Base.ChildProcess& p) [0x00165] in <5529660cb9214b5a92e49e1607b30d50>:0 Mar 02 18:24:05 volumio RoonBridge[1672]: Not Running (.o) Mar 02 18:24:05 volumio RoonBridge[1672]: 00:00:45.263 Debug: PathForResource, filename: ../.update Mar 02 18:24:05 volumio RoonBridge[1672]: 00:00:45.265 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update Mar 02 18:24:05 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Mar 02 18:24:06 volumio volumio[1118]: info: MyVolumio token set successfully Mar 02 18:24:06 volumio volumio[1118]: info: MYVOLUMIO: Adding device Mar 02 18:24:06 volumio volumio[1118]: info: MYVOLUMIO: Evaluating Server Mar 02 18:24:06 volumio volumio[1118]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

FIXES

 

 

","title":"Update v3.779","updateavailable":true} Mar 02 18:24:06 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 02 18:24:07 volumio RoonBridge[1672]: 00:00:47.266 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Mar 02 18:24:07 volumio RoonBridge[1672]: Running Mar 02 18:24:07 volumio volumio[1118]: info: MyVolumio status changed Mar 02 18:24:07 volumio volumio[1118]: info: Streaming services startup Mar 02 18:24:07 volumio volumio[1118]: info: Starting Streaming Daemon Mar 02 18:24:07 volumio volumio[1118]: info: Removing browser output: myVolumio user plan is not superstar Mar 02 18:24:08 volumio volumio[1118]: info: Removing audio output: Mar 02 18:24:08 volumio volumio[1118]: info: Stoppping Tunnel 1 Mar 02 18:24:08 volumio sudo[2253]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 02 18:24:08 volumio sudo[2253]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 18:24:08 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 02 18:24:08 volumio volumio[1118]: info: Received Get System Info Mar 02 18:24:08 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 02 18:24:08 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 02 18:24:08 volumio volumio[1118]: info: Discovery: Getting this device information Mar 02 18:24:08 volumio volumio[1118]: info: CoreCommandRouter::volumioGetState Mar 02 18:24:08 volumio volumio[1118]: info: CorePlayQueue::getTrack 0 Mar 02 18:24:08 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 02 18:24:08 volumio sudo[2253]: pam_unix(sudo:session): session closed for user root Mar 02 18:24:08 volumio sudo[2256]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Mar 02 18:24:08 volumio sudo[2256]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 18:24:08 volumio volumio[1118]: 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: 7 Mar 02 18:24:08 volumio volumio[1118]: error: Cannot start Volumio Streaming Daemon Mar 02 18:24:08 volumio volumio[1118]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 02 18:24:08 volumio volumio[1118]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 02 18:24:08 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 02 18:24:08 volumio volumio[1118]: info: Received Get System Info Mar 02 18:24:08 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 02 18:24:08 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 02 18:24:08 volumio volumio[1118]: info: Discovery: Getting this device information Mar 02 18:24:08 volumio volumio[1118]: info: CoreCommandRouter::volumioGetState Mar 02 18:24:08 volumio volumio[1118]: info: CorePlayQueue::getTrack 0 Mar 02 18:24:08 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 02 18:24:08 volumio sudo[2256]: pam_unix(sudo:session): session closed for user root Mar 02 18:24:08 volumio volumio[1118]: info: CoreCommandRouter::volumioGetState Mar 02 18:24:08 volumio volumio[1118]: info: CorePlayQueue::getTrack 0 Mar 02 18:24:08 volumio volumio[1118]: info: Remote SSH Stopped Mar 02 18:24:13 volumio volumio[1118]: 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: 8 Mar 02 18:24:13 volumio volumio[1118]: info: Setting Geolocation for MyVolumio to us2 Mar 02 18:24:13 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 02 18:24:13 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 02 18:24:13 volumio volumio[1118]: info: CoreCommandRouter::volumioGetState Mar 02 18:24:13 volumio volumio[1118]: info: CorePlayQueue::getTrack 0 Mar 02 18:24:13 volumio RoonBridge[1672]: 00:00:53.295 Warn: exception starting raatserver: System.Net.Sockets.SocketException (0x80004005): Connection refused Mar 02 18:24:13 volumio RoonBridge[1672]: at System.Net.Sockets.TcpClient..ctor (System.String hostname, System.Int32 port) [0x0006d] in <08911f3881ec4a92acc8fdc4d13b73bf>:0 Mar 02 18:24:13 volumio RoonBridge[1672]: at Sooloos.RAATServer.ConnectOrStartAndWaitForExit (System.String path, System.String args, System.Action`1[T] status, Base.ChildProcess& p) [0x00165] in <5529660cb9214b5a92e49e1607b30d50>:0 Mar 02 18:24:13 volumio RoonBridge[1672]: Not Running (.o) Mar 02 18:24:13 volumio RoonBridge[1672]: 00:00:53.297 Debug: PathForResource, filename: ../.update Mar 02 18:24:13 volumio RoonBridge[1672]: 00:00:53.299 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update Mar 02 18:24:14 volumio jellyfin[1638]: [18:24:14] [WRN] [1] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The WebRootPath was not found: /wwwroot. Static files may be unavailable. Mar 02 18:24:14 volumio volumio[1118]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Mar 02 18:24:14 volumio volumio[1118]: info: Updating MyVolumio device info Mar 02 18:24:14 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 02 18:24:14 volumio jellyfin[1638]: [18:24:14] [INF] [1] Emby.Server.Implementations.ApplicationHost: Running startup tasks Mar 02 18:24:15 volumio jellyfin[1638]: [18:24:15] [INF] [1] Emby.Server.Implementations.ScheduledTasks.TaskManager: Daily trigger for Generate Trickplay Images set to fire at 2025-03-03 03:00:00.000 +08:00, which is 08:35:44.7991063 from now. Mar 02 18:24:15 volumio volumio[1118]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Mar 02 18:24:15 volumio jellyfin[1638]: [18:24:15] [INF] [1] Emby.Server.Implementations.ScheduledTasks.TaskManager: Daily trigger for Extract Chapter Images set to fire at 2025-03-03 02:00:00.000 +08:00, which is 07:35:44.7714183 from now. Mar 02 18:24:15 volumio RoonBridge[1672]: 00:00:55.299 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Mar 02 18:24:15 volumio RoonBridge[1672]: Running Mar 02 18:24:16 volumio jellyfin[1638]: [18:24:16] [INF] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: Found ffmpeg version 6.0.1 Mar 02 18:24:17 volumio jellyfin[1638]: [18:24:17] [INF] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: Available decoders: ["libdav1d", "av1", "av1_cuvid", "av1_qsv", "h264", "h264_qsv", "h264_cuvid", "hevc", "hevc_qsv", "hevc_cuvid", "mpeg2video", "mpeg2_qsv", "mpeg2_cuvid", "mpeg4", "mpeg4_cuvid", "msmpeg4", "vc1_qsv", "vc1_cuvid", "vp8", "libvpx", "vp8_cuvid", "vp8_qsv", "vp9", "libvpx-vp9", "vp9_cuvid", "vp9_qsv", "aac", "ac3", "dca", "flac", "mp3", "truehd"] Mar 02 18:24:18 volumio volumio[1118]: info: MYVOLUMIO: Adding device Mar 02 18:24:18 volumio volumio[1118]: info: MYVOLUMIO: Evaluating Server Mar 02 18:24:18 volumio jellyfin[1638]: [18:24:18] [INF] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: Available encoders: ["libsvtav1", "av1_nvenc", "av1_qsv", "av1_amf", "av1_vaapi", "libx264", "h264_amf", "h264_nvenc", "h264_qsv", "h264_v4l2m2m", "h264_vaapi", "libx265", "hevc_amf", "hevc_nvenc", "hevc_qsv", "hevc_vaapi", "mjpeg_qsv", "mjpeg_vaapi", "mpeg4", "msmpeg4", "libvpx", "libvpx-vp9", "aac", "libfdk_aac", "ac3", "alac", "dca", "flac", "libmp3lame", "libopus", "truehd", "libvorbis", "srt"] Mar 02 18:24:18 volumio jellyfin[1638]: [18:24:18] [INF] [8] Emby.Server.Implementations.ScheduledTasks.TaskManager: Clean up collections and playlists Completed after 0 minute(s) and 0 seconds Mar 02 18:24:19 volumio jellyfin[1638]: [18:24:19] [INF] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: Available filters: ["deinterlace_qsv", "deinterlace_vaapi", "hwupload_cuda", "hwupload_vaapi", "libplacebo", "overlay_opencl", "overlay_qsv", "overlay_vaapi", "overlay_vulkan", "overlay_cuda", "procamp_vaapi", "scale_cuda", "scale_opencl", "scale_qsv", "scale_vaapi", "scale_vulkan", "tonemap_cuda", "tonemap_opencl", "tonemap_vaapi", "vpp_qsv", "yadif_cuda", "zscale", "alphasrc"] Mar 02 18:24:21 volumio volumio[1118]: info: Setting Geolocation for MyVolumio to us2 Mar 02 18:24:21 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 02 18:24:21 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 02 18:24:22 volumio volumio[1118]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Mar 02 18:24:22 volumio volumio[1118]: info: Updating MyVolumio device info Mar 02 18:24:22 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 02 18:24:23 volumio volumio[1118]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Mar 02 18:24:24 volumio jellyfin[1638]: [18:24:24] [INF] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: Available hwaccel types: ["cuda", "vaapi", "qsv", "drm", "opencl", "vulkan"] Mar 02 18:24:29 volumio jellyfin[1638]: [18:24:29] [INF] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: FFmpeg: /usr/lib/jellyfin-ffmpeg/ffmpeg Mar 02 18:24:29 volumio jellyfin[1638]: [18:24:29] [INF] [1] Emby.Server.Implementations.ApplicationHost: ServerId: b1cfc0d663974d88a7e5ad9e731e53ae Mar 02 18:24:29 volumio jellyfin[1638]: [18:24:29] [INF] [1] Emby.Server.Implementations.ApplicationHost: Core startup complete Mar 02 18:24:29 volumio jellyfin[1638]: [18:24:29] [INF] [1] Main: Startup complete 0:01:09.3203743 Mar 02 18:24:32 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 02 18:24:32 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 02 18:24:32 volumio volumio[1118]: info: Discovery: Getting this device information Mar 02 18:24:32 volumio volumio[1118]: info: CoreCommandRouter::volumioGetState Mar 02 18:24:32 volumio volumio[1118]: info: CorePlayQueue::getTrack 0 Mar 02 18:24:32 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 02 18:24:32 volumio volumio[1118]: verbose: New Socket.io Connection to 192.168.31.214:3000 from 192.168.31.107 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Mar 02 18:24:32 volumio volumio[1118]: info: CoreCommandRouter::volumioGetState Mar 02 18:24:32 volumio volumio[1118]: info: CorePlayQueue::getTrack 0 Mar 02 18:24:32 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 02 18:24:32 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 02 18:24:33 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 02 18:24:33 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 02 18:24:33 volumio volumio[1118]: info: Discovery: Getting this device information Mar 02 18:24:33 volumio volumio[1118]: info: CoreCommandRouter::volumioGetState Mar 02 18:24:33 volumio volumio[1118]: info: CorePlayQueue::getTrack 0 Mar 02 18:24:33 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 02 18:24:36 volumio sudo[2465]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 02 18:24:36 volumio sudo[2465]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 18:24:36 volumio sudo[2465]: pam_unix(sudo:session): session closed for user root Mar 02 18:24:36 volumio sudo[2468]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 02 18:24:36 volumio sudo[2468]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 18:24:36 volumio sudo[2468]: pam_unix(sudo:session): session closed for user root Mar 02 18:24:36 volumio jellyfin[1638]: [18:24:36] [INF] [12] Emby.Server.Implementations.ScheduledTasks.TaskManager: Update Plugins Completed after 0 minute(s) and 18 seconds Mar 02 18:24:36 volumio volumio[1118]: verbose: New Socket.io Connection to 192.168.31.214 from 192.168.31.107 UA: Mozilla/5.0 (Linux; Android 14; 22122RK93C Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/133.0.6943.121 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Mar 02 18:24:36 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 02 18:24:37 volumio volumio[1118]: info: CoreCommandRouter::volumioGetVisibleSources Mar 02 18:24:37 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 02 18:24:37 volumio volumio[1118]: info: CoreCommandRouter::volumioGetState Mar 02 18:24:37 volumio volumio[1118]: info: CorePlayQueue::getTrack 0 Mar 02 18:24:37 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 02 18:24:37 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 02 18:24:37 volumio volumio[1118]: info: Received Get System Info Mar 02 18:24:37 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 02 18:24:37 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 02 18:24:37 volumio volumio[1118]: info: Discovery: Getting this device information Mar 02 18:24:37 volumio volumio[1118]: info: CoreCommandRouter::volumioGetState Mar 02 18:24:37 volumio volumio[1118]: info: CorePlayQueue::getTrack 0 Mar 02 18:24:37 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 02 18:24:37 volumio volumio[1118]: info: CoreCommandRouter::volumioGetState Mar 02 18:24:37 volumio volumio[1118]: info: CorePlayQueue::getTrack 0 Mar 02 18:24:37 volumio volumio[1118]: info: Listing playlists Mar 02 18:24:37 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 02 18:24:37 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 02 18:24:37 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 02 18:24:37 volumio sudo[2473]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 02 18:24:37 volumio sudo[2473]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 18:24:37 volumio sudo[2473]: pam_unix(sudo:session): session closed for user root Mar 02 18:24:37 volumio sudo[2476]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 02 18:24:37 volumio sudo[2476]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 18:24:37 volumio sudo[2476]: pam_unix(sudo:session): session closed for user root Mar 02 18:24:37 volumio volumio[1118]: verbose: New Socket.io Connection to 192.168.31.214 from 192.168.31.107 UA: Mozilla/5.0 (Linux; Android 14; 22122RK93C Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/133.0.6943.121 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Mar 02 18:24:37 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 02 18:24:37 volumio volumio[1118]: info: CoreCommandRouter::volumioGetVisibleSources Mar 02 18:24:37 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 02 18:24:37 volumio volumio[1118]: info: CoreCommandRouter::volumioGetState Mar 02 18:24:37 volumio volumio[1118]: info: CorePlayQueue::getTrack 0 Mar 02 18:24:37 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 02 18:24:37 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 02 18:24:37 volumio volumio[1118]: info: Received Get System Info Mar 02 18:24:37 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 02 18:24:37 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 02 18:24:37 volumio volumio[1118]: info: Discovery: Getting this device information Mar 02 18:24:37 volumio volumio[1118]: info: CoreCommandRouter::volumioGetState Mar 02 18:24:37 volumio volumio[1118]: info: CorePlayQueue::getTrack 0 Mar 02 18:24:37 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 02 18:24:37 volumio volumio[1118]: info: CoreCommandRouter::volumioGetState Mar 02 18:24:37 volumio volumio[1118]: info: CorePlayQueue::getTrack 0 Mar 02 18:24:37 volumio volumio[1118]: info: Listing playlists Mar 02 18:24:37 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 02 18:24:37 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 02 18:24:37 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 02 18:24:39 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 02 18:24:39 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 02 18:24:39 volumio volumio[1118]: info: Received Get System Info Mar 02 18:24:39 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 02 18:24:39 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 02 18:24:39 volumio volumio[1118]: info: Discovery: Getting this device information Mar 02 18:24:39 volumio volumio[1118]: info: CoreCommandRouter::volumioGetState Mar 02 18:24:39 volumio volumio[1118]: info: CorePlayQueue::getTrack 0 Mar 02 18:24:39 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 02 18:24:39 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 02 18:24:43 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 02 18:24:43 volumio volumio[1118]: info: Received Get System Info Mar 02 18:24:43 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 02 18:24:43 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 02 18:24:43 volumio volumio[1118]: info: Discovery: Getting this device information Mar 02 18:24:43 volumio volumio[1118]: info: CoreCommandRouter::volumioGetState Mar 02 18:24:43 volumio volumio[1118]: info: CorePlayQueue::getTrack 0 Mar 02 18:24:43 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 02 18:24:47 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 02 18:24:55 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 02 18:24:55 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 02 18:24:55 volumio volumio[1118]: info: Discovery: Getting this device information Mar 02 18:24:55 volumio volumio[1118]: info: CoreCommandRouter::volumioGetState Mar 02 18:24:55 volumio volumio[1118]: info: CorePlayQueue::getTrack 0 Mar 02 18:24:55 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 02 18:24:56 volumio volumio[1118]: verbose: New Socket.io Connection to 192.168.31.214:3000 from 192.168.31.107 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Mar 02 18:24:56 volumio volumio[1118]: verbose: New Socket.io Connection to 192.168.31.214 from 192.168.31.107 UA: Mozilla/5.0 (Linux; Android 14; 22122RK93C Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/133.0.6943.121 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Mar 02 18:24:56 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 02 18:24:56 volumio volumio[1118]: info: CoreCommandRouter::volumioGetVisibleSources Mar 02 18:24:56 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 02 18:24:56 volumio volumio[1118]: info: CoreCommandRouter::volumioGetState Mar 02 18:24:56 volumio volumio[1118]: info: CorePlayQueue::getTrack 0 Mar 02 18:24:56 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 02 18:24:56 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 02 18:24:56 volumio volumio[1118]: info: Received Get System Info Mar 02 18:24:56 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 02 18:24:56 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 02 18:24:56 volumio volumio[1118]: info: Discovery: Getting this device information Mar 02 18:24:56 volumio volumio[1118]: info: CoreCommandRouter::volumioGetState Mar 02 18:24:56 volumio volumio[1118]: info: CorePlayQueue::getTrack 0 Mar 02 18:24:56 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 02 18:24:56 volumio volumio[1118]: info: CoreCommandRouter::volumioGetState Mar 02 18:24:56 volumio volumio[1118]: info: CorePlayQueue::getTrack 0 Mar 02 18:24:56 volumio volumio[1118]: info: Listing playlists Mar 02 18:24:56 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 02 18:24:56 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 02 18:24:56 volumio volumio[1118]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 02 18:24:56 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 02 18:24:56 volumio volumio[1118]: info: Received Get System Version Mar 02 18:24:56 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 02 18:24:56 volumio volumio[1118]: info: Received Get System Info Mar 02 18:24:56 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 02 18:24:56 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 02 18:24:56 volumio volumio[1118]: info: Discovery: Getting this device information Mar 02 18:24:56 volumio volumio[1118]: info: CoreCommandRouter::volumioGetState Mar 02 18:24:56 volumio volumio[1118]: info: CorePlayQueue::getTrack 0 Mar 02 18:24:56 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 02 18:24:58 volumio volumio[1118]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 02 18:24:58 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 02 18:24:58 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 02 18:24:58 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 02 18:24:58 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 02 18:24:58 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 02 18:24:58 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 02 18:24:58 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 02 18:24:58 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 02 18:24:58 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 02 18:24:58 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 02 18:24:58 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 02 18:24:58 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 02 18:25:00 volumio volumio[1118]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Mar 02 18:25:00 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Mar 02 18:25:00 volumio volumio[1118]: info: Preparing to save Alsa Options, stopping services first Mar 02 18:25:00 volumio volumio[1118]: info: CoreCommandRouter::volumioGetState Mar 02 18:25:00 volumio volumio[1118]: info: CorePlayQueue::getTrack 0 Mar 02 18:25:00 volumio volumio[1118]: info: CoreCommandRouter::volumioPause Mar 02 18:25:00 volumio volumio[1118]: info: CoreStateMachine::pause Mar 02 18:25:00 volumio volumio[1118]: info: CoreStateMachine::stPlaybackTimer Mar 02 18:25:00 volumio volumio[1118]: info: CoreStateMachine::servicePause Mar 02 18:25:00 volumio volumio[1118]: info: CorePlayQueue::getTrack 0 Mar 02 18:25:00 volumio volumio[1118]: info: CoreCommandRouter::servicePause Mar 02 18:25:00 volumio volumio[1118]: info: ControllerMpd::pause Mar 02 18:25:00 volumio volumio[1118]: verbose: ControllerMpd::sendMpdCommand pause Mar 02 18:25:00 volumio volumio[1118]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"SMSL USB AUDIO"}} Mar 02 18:25:00 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 02 18:25:00 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 02 18:25:00 volumio volumio[1118]: info: Device 5 does not have any Mixer Control Available Mar 02 18:25:01 volumio volumio[1118]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 02 18:25:01 volumio volumio[1118]: info: Updating Volume Controller Parameters: Device: 5 Name: SMSL USB AUDIO Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Mar 02 18:25:01 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Mar 02 18:25:01 volumio volumio[1118]: info: Disabling external Volume Control Mar 02 18:25:01 volumio volumio[1118]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 02 18:25:01 volumio volumio[1118]: info: CoreStateMachine::pushState Mar 02 18:25:01 volumio volumio[1118]: info: CorePlayQueue::getTrack 0 Mar 02 18:25:01 volumio volumio[1118]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 02 18:25:01 volumio volumio[1118]: info: CoreCommandRouter::volumioPushState Mar 02 18:25:01 volumio volumio[1118]: info: Preparing to generate the ALSA configuration file Mar 02 18:25:01 volumio volumio[1118]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Mar 02 18:25:01 volumio volumio[1118]: info: Reading ALSA contributions from plugins. Mar 02 18:25:01 volumio volumio[1118]: info: Mar 02 18:25:01 volumio volumio[1118]: ---------------------------- MPD announces state update: player Mar 02 18:25:01 volumio volumio[1118]: info: sendMpdCommand pause took 453 milliseconds Mar 02 18:25:01 volumio volumio[1118]: info: ControllerMpd::getState Mar 02 18:25:01 volumio volumio[1118]: verbose: ControllerMpd::sendMpdCommand status Mar 02 18:25:01 volumio volumio[1118]: info: Mar 02 18:25:01 volumio volumio[1118]: ---------------------------- MPD announces state update: player Mar 02 18:25:01 volumio volumio[1118]: info: ControllerMpd::getState Mar 02 18:25:01 volumio volumio[1118]: verbose: ControllerMpd::sendMpdCommand status Mar 02 18:25:01 volumio volumio[1118]: info: Mar 02 18:25:01 volumio volumio[1118]: ---------------------------- MPD announces state update: player Mar 02 18:25:01 volumio volumio[1118]: info: ControllerMpd::getState Mar 02 18:25:01 volumio volumio[1118]: verbose: ControllerMpd::sendMpdCommand status Mar 02 18:25:01 volumio volumio[1118]: info: Mar 02 18:25:01 volumio volumio[1118]: ---------------------------- MPD announces state update: player Mar 02 18:25:01 volumio volumio[1118]: info: ControllerMpd::getState Mar 02 18:25:01 volumio volumio[1118]: verbose: ControllerMpd::sendMpdCommand status Mar 02 18:25:01 volumio volumio[1118]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 02 18:25:01 volumio volumio[1118]: Error: ENOTDIR: not a directory, scandir '/tmp/peppyrunning' Mar 02 18:25:01 volumio volumio[1118]: at Object.readdirSync (fs.js:1048:3) Mar 02 18:25:01 volumio volumio[1118]: at rmkidsSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:349:11) Mar 02 18:25:01 volumio volumio[1118]: at rmdirSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:342:7) Mar 02 18:25:01 volumio volumio[1118]: at Function.rimrafSync [as sync] (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:319:62) Mar 02 18:25:01 volumio volumio[1118]: at Object.removeSync (/data/plugins/user_interface/peppy_screensaver/node_modules/fs-extra/lib/remove/index.js:4:17) Mar 02 18:25:01 volumio volumio[1118]: at Socket. (/data/plugins/user_interface/peppy_screensaver/index.js:225:48) Mar 02 18:25:01 volumio volumio[1118]: at Socket.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Mar 02 18:25:01 volumio volumio[1118]: at Socket.onevent (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:278:10) Mar 02 18:25:01 volumio volumio[1118]: at Socket.onpacket (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:236:12) Mar 02 18:25:01 volumio volumio[1118]: at Manager. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Mar 02 18:25:01 volumio volumio[1118]: at Manager.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Mar 02 18:25:01 volumio volumio[1118]: at Manager.ondecoded (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:349:8) Mar 02 18:25:01 volumio volumio[1118]: at Decoder. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Mar 02 18:25:01 volumio volumio[1118]: at Decoder.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Mar 02 18:25:01 volumio volumio[1118]: at Decoder.add (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-parser/index.js:251:12) Mar 02 18:25:01 volumio volumio[1118]: at Manager.ondata (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:339:16) { Mar 02 18:25:01 volumio volumio[1118]: errno: -20, Mar 02 18:25:01 volumio volumio[1118]: syscall: 'scandir', Mar 02 18:25:01 volumio volumio[1118]: code: 'ENOTDIR', Mar 02 18:25:01 volumio volumio[1118]: path: '/tmp/peppyrunning' Mar 02 18:25:01 volumio volumio[1118]: } Mar 02 18:25:01 volumio volumio[1118]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 02 18:25:01 volumio sudo[2551]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-02 18:24 Mar 02 18:25:01 volumio sudo[2551]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:33:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="ee834e1c2a28de3c5d8c48611ecf1167"