-- Logs begin at Wed 2024-09-18 22:10:22 CST, end at Fri 2024-10-25 20:18:44 CST. -- Oct 25 20:17:00 volumio jellyfin[1797]: [20:17:00] [WRN] [1] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The WebRootPath was not found: /wwwroot. Static files may be unavailable. Oct 25 20:17:00 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 25 20:17:01 volumio jellyfin[1797]: [20:17:01] [INF] [1] Emby.Server.Implementations.ApplicationHost: Running startup tasks Oct 25 20:17:01 volumio jellyfin[1797]: [20:17:01] [INF] [1] Emby.Server.Implementations.ScheduledTasks.TaskManager: Daily trigger for Generate Trickplay Images set to fire at 2024-10-26 03:00:00.000 +08:00, which is 06:42:58.6310063 from now. Oct 25 20:17:01 volumio jellyfin[1797]: [20:17:01] [INF] [1] Emby.Server.Implementations.ScheduledTasks.TaskManager: Daily trigger for Extract Chapter Images set to fire at 2024-10-26 02:00:00.000 +08:00, which is 05:42:58.6092428 from now. Oct 25 20:17:01 volumio RoonBridge[1833]: 00:01:15.797 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Oct 25 20:17:01 volumio RoonBridge[1833]: Running Oct 25 20:17:02 volumio jellyfin[1797]: [20:17:02] [INF] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: Found ffmpeg version 6.0.1 Oct 25 20:17:02 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 25 20:17:02 volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Oct 25 20:17:02 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 25 20:17:03 volumio jellyfin[1797]: [20:17:03] [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"] Oct 25 20:17:04 volumio jellyfin[1797]: [20:17:04] [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"] Oct 25 20:17:04 volumio jellyfin[1797]: [20:17:04] [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"] Oct 25 20:17:05 volumio jellyfin[1797]: [20:17:05] [INF] [10] Emby.Server.Implementations.ScheduledTasks.TaskManager: Clean up collections and playlists Completed after 0 minute(s) and 0 seconds Oct 25 20:17:07 volumio volumio[1187]: info: MyVolumio login type: Token Oct 25 20:17:07 volumio RoonBridge[1833]: 00:01:21.822 Warn: exception starting raatserver: System.Net.Sockets.SocketException (0x80004005): Connection refused Oct 25 20:17:07 volumio RoonBridge[1833]: at System.Net.Sockets.TcpClient..ctor (System.String hostname, System.Int32 port) [0x0006d] in <08911f3881ec4a92acc8fdc4d13b73bf>:0 Oct 25 20:17:07 volumio RoonBridge[1833]: at Sooloos.RAATServer.ConnectOrStartAndWaitForExit (System.String path, System.String args, System.Action`1[T] status, Base.ChildProcess& p) [0x00165] in <5529660cb9214b5a92e49e1607b30d50>:0 Oct 25 20:17:07 volumio RoonBridge[1833]: 00:01:21.833 Debug: PathForResource, filename: ../.update Oct 25 20:17:07 volumio RoonBridge[1833]: 00:01:21.833 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update Oct 25 20:17:07 volumio RoonBridge[1833]: Not Running (.o) Oct 25 20:17:08 volumio jellyfin[1797]: [20:17:08] [INF] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: Available hwaccel types: ["cuda", "vaapi", "qsv", "drm", "opencl", "vulkan"] Oct 25 20:17:09 volumio RoonBridge[1833]: 00:01:23.834 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Oct 25 20:17:09 volumio RoonBridge[1833]: Running Oct 25 20:17:14 volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Oct 25 20:17:14 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 25 20:17:14 volumio jellyfin[1797]: [20:17:14] [INF] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: FFmpeg: /usr/lib/jellyfin-ffmpeg/ffmpeg Oct 25 20:17:14 volumio jellyfin[1797]: [20:17:14] [INF] [1] Emby.Server.Implementations.ApplicationHost: ServerId: a89faa26233a48d3b4820894abf7ef9f Oct 25 20:17:14 volumio jellyfin[1797]: [20:17:14] [INF] [1] Emby.Server.Implementations.ApplicationHost: Core startup complete Oct 25 20:17:14 volumio jellyfin[1797]: [20:17:14] [INF] [1] Main: Startup complete 0:01:31.9978477 Oct 25 20:17:15 volumio jellyfin[1797]: [20:17:15] [INF] [10] Emby.Server.Implementations.ScheduledTasks.TaskManager: Update Plugins Completed after 0 minute(s) and 10 seconds Oct 25 20:17:15 volumio RoonBridge[1833]: 00:01:29.858 Warn: exception starting raatserver: System.Net.Sockets.SocketException (0x80004005): Connection refused Oct 25 20:17:15 volumio RoonBridge[1833]: at System.Net.Sockets.TcpClient..ctor (System.String hostname, System.Int32 port) [0x0006d] in <08911f3881ec4a92acc8fdc4d13b73bf>:0 Oct 25 20:17:15 volumio RoonBridge[1833]: at Sooloos.RAATServer.ConnectOrStartAndWaitForExit (System.String path, System.String args, System.Action`1[T] status, Base.ChildProcess& p) [0x00165] in <5529660cb9214b5a92e49e1607b30d50>:0 Oct 25 20:17:15 volumio RoonBridge[1833]: 00:01:29.869 Debug: PathForResource, filename: ../.update Oct 25 20:17:15 volumio RoonBridge[1833]: 00:01:29.869 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update Oct 25 20:17:15 volumio RoonBridge[1833]: Not Running (.o) Oct 25 20:17:16 volumio volumio[1187]: info: CoreCommandRouter::volumioPlay Oct 25 20:17:16 volumio volumio[1187]: info: CoreStateMachine::play index undefined Oct 25 20:17:16 volumio volumio[1187]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 25 20:17:16 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 25 20:17:16 volumio volumio[1187]: info: CoreStateMachine::startPlaybackTimer Oct 25 20:17:16 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 25 20:17:16 volumio volumio[1187]: verbose: ControllerMpd::clearAddPlayTracks USB/Kiroro - 長い間.flac Oct 25 20:17:16 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand stop Oct 25 20:17:16 volumio volumio[1187]: info: sendMpdCommand stop took 3 milliseconds Oct 25 20:17:16 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand clear Oct 25 20:17:16 volumio volumio[1187]: info: Oct 25 20:17:16 volumio volumio[1187]: ---------------------------- MPD announces system playlist update Oct 25 20:17:16 volumio volumio[1187]: info: Ignoring MPD Status Update Oct 25 20:17:16 volumio volumio[1187]: info: sendMpdCommand clear took 10 milliseconds Oct 25 20:17:16 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand add "USB/Kiroro - 長い間.flac" Oct 25 20:17:16 volumio volumio[1187]: error: updateQueue error: null Oct 25 20:17:16 volumio volumio[1187]: info: Oct 25 20:17:16 volumio volumio[1187]: ---------------------------- MPD announces system playlist update Oct 25 20:17:16 volumio volumio[1187]: info: Ignoring MPD Status Update Oct 25 20:17:16 volumio volumio[1187]: info: ------------------------------ 12ms Oct 25 20:17:16 volumio volumio[1187]: info: sendMpdCommand add "USB/Kiroro - 長い間.flac" took 5 milliseconds Oct 25 20:17:16 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand play Oct 25 20:17:16 volumio volumio[1187]: info: ------------------------------ 17ms Oct 25 20:17:16 volumio volumio[1187]: info: Oct 25 20:17:16 volumio volumio[1187]: ---------------------------- MPD announces state update: player Oct 25 20:17:16 volumio volumio[1187]: info: sendMpdCommand play took 13 milliseconds Oct 25 20:17:16 volumio volumio[1187]: info: ControllerMpd::getState Oct 25 20:17:16 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand status Oct 25 20:17:17 volumio volumio[1187]: info: Oct 25 20:17:17 volumio volumio[1187]: ---------------------------- MPD announces state update: player Oct 25 20:17:17 volumio volumio[1187]: info: sendMpdCommand status took 67 milliseconds Oct 25 20:17:17 volumio volumio[1187]: info: ControllerMpd::getState Oct 25 20:17:17 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand status Oct 25 20:17:17 volumio volumio[1187]: verbose: ControllerMpd::parseState Oct 25 20:17:17 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 20:17:17 volumio volumio[1187]: info: sendMpdCommand status took 12 milliseconds Oct 25 20:17:17 volumio volumio[1187]: info: sendMpdCommand playlistinfo took 12 milliseconds Oct 25 20:17:17 volumio volumio[1187]: verbose: ControllerMpd::parseState Oct 25 20:17:17 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 20:17:17 volumio volumio[1187]: verbose: ControllerMpd::parseTrackInfo Oct 25 20:17:17 volumio volumio[1187]: info: ControllerMpd::pushState Oct 25 20:17:17 volumio volumio[1187]: info: CoreCommandRouter::servicePushState Oct 25 20:17:17 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 25 20:17:17 volumio volumio[1187]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":281,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"長い間","artist":"Kiroro","album":"Nagai Aida ~Kiroro no Mori~","uri":"USB/Kiroro - 長い間.flac","trackType":"flac"} Oct 25 20:17:17 volumio volumio[1187]: verbose: CURRENT POSITION 0 Oct 25 20:17:17 volumio volumio[1187]: info: CoreStateMachine::syncState stateService play Oct 25 20:17:17 volumio volumio[1187]: info: CoreStateMachine::syncState currentStatus stop Oct 25 20:17:17 volumio volumio[1187]: info: ------------------------------ 123ms Oct 25 20:17:17 volumio volumio[1187]: info: sendMpdCommand playlistinfo took 25 milliseconds Oct 25 20:17:17 volumio volumio[1187]: verbose: ControllerMpd::parseTrackInfo Oct 25 20:17:17 volumio volumio[1187]: info: ControllerMpd::pushState Oct 25 20:17:17 volumio volumio[1187]: info: CoreCommandRouter::servicePushState Oct 25 20:17:17 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 25 20:17:17 volumio volumio[1187]: verbose: STATE SERVICE {"status":"play","position":0,"seek":65,"duration":281,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"長い間","artist":"Kiroro","album":"Nagai Aida ~Kiroro no Mori~","uri":"USB/Kiroro - 長い間.flac","trackType":"flac"} Oct 25 20:17:17 volumio volumio[1187]: verbose: CURRENT POSITION 0 Oct 25 20:17:17 volumio volumio[1187]: info: CoreStateMachine::syncState stateService play Oct 25 20:17:17 volumio volumio[1187]: info: CoreStateMachine::syncState currentStatus play Oct 25 20:17:17 volumio volumio[1187]: info: Received an update from plugin. extracting info from payload Oct 25 20:17:17 volumio volumio[1187]: info: CoreStateMachine::pushState Oct 25 20:17:17 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 25 20:17:17 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 20:17:17 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Oct 25 20:17:17 volumio volumio[1187]: info: CoreStateMachine::pushState Oct 25 20:17:17 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 25 20:17:17 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Oct 25 20:17:17 volumio volumio[1187]: info: ------------------------------ 121ms Oct 25 20:17:17 volumio RoonBridge[1833]: 00:01:31.869 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Oct 25 20:17:17 volumio RoonBridge[1833]: Running Oct 25 20:17:25 volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Oct 25 20:17:25 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 25 20:17:25 volumio sudo[2636]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/usr/bin/python3 ./screensaver/volumio_peppymeter.py Oct 25 20:17:25 volumio sudo[2636]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 20:17:32 volumio startx[1191]: (EE) client bug: timer event2 debounce: offset negative (-231ms) Oct 25 20:17:32 volumio startx[1191]: (EE) client bug: timer event2 debounce: offset negative (-80ms) Oct 25 20:17:32 volumio startx[1191]: (EE) client bug: timer event2 debounce short: offset negative (-93ms) Oct 25 20:17:34 volumio volumio[1187]: 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 Oct 25 20:17:34 volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Oct 25 20:17:34 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 25 20:17:37 volumio sudo[2636]: pam_unix(sudo:session): session closed for user root Oct 25 20:17:37 volumio volumio[1187]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Oct 25 20:17:37 volumio volumio[1187]: info: Retrying Login Due to Network Error Oct 25 20:17:43 volumio volumio[1187]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 25 20:17:43 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 25 20:17:43 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 25 20:17:43 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Oct 25 20:17:47 volumio volumio[1187]: info: MyVolumio login type: Token Oct 25 20:17:47 volumio volumio[1187]: info: Received Get System Version Oct 25 20:17:47 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 25 20:17:48 volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Oct 25 20:17:48 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 25 20:17:48 volumio sudo[2723]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/usr/bin/python3 ./screensaver/volumio_peppymeter.py Oct 25 20:17:48 volumio sudo[2723]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 20:17:53 volumio volumio[1187]: 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 Oct 25 20:17:53 volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Oct 25 20:17:53 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 25 20:17:54 volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Oct 25 20:17:54 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 25 20:17:58 volumio volumio[1187]: 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 Oct 25 20:17:58 volumio sudo[2723]: pam_unix(sudo:session): session closed for user root Oct 25 20:18:07 volumio volumio[1187]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 25 20:18:07 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 25 20:18:07 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 25 20:18:07 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 25 20:18:07 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Oct 25 20:18:09 volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Oct 25 20:18:09 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 25 20:18:09 volumio sudo[2808]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/usr/bin/python3 ./screensaver/volumio_peppymeter.py Oct 25 20:18:09 volumio sudo[2808]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 25 20:18:10 volumio jellyfin[1797]: [20:18:10] [INF] [19] Emby.Server.Implementations.ScheduledTasks.TaskManager: Update Plugins Completed after 0 minute(s) and 8 seconds Oct 25 20:18:10 volumio volumio[1187]: info: Received Get System Version Oct 25 20:18:10 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 25 20:18:17 volumio volumio[1187]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Oct 25 20:18:17 volumio volumio[1187]: info: Retrying Login Due to Network Error Oct 25 20:18:17 volumio volumio[1187]: 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 Oct 25 20:18:17 volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Oct 25 20:18:17 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 25 20:18:21 volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Oct 25 20:18:21 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 25 20:18:23 volumio volumio[1187]: 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: 9 Oct 25 20:18:23 volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Oct 25 20:18:23 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 25 20:18:27 volumio volumio[1187]: info: MyVolumio login type: Token Oct 25 20:18:31 volumio volumio[1187]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 25 20:18:31 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 25 20:18:31 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 20:18:31 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 25 20:18:32 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 25 20:18:32 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 25 20:18:32 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 25 20:18:32 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 25 20:18:32 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 25 20:18:32 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 25 20:18:32 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 25 20:18:32 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 25 20:18:42 volumio volumio[1187]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Oct 25 20:18:42 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Oct 25 20:18:42 volumio volumio[1187]: info: Preparing to save Alsa Options, stopping services first Oct 25 20:18:42 volumio volumio[1187]: info: CoreCommandRouter::volumioGetState Oct 25 20:18:42 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 25 20:18:42 volumio volumio[1187]: info: CoreCommandRouter::volumioPause Oct 25 20:18:42 volumio volumio[1187]: info: CoreStateMachine::pause Oct 25 20:18:42 volumio volumio[1187]: info: CoreStateMachine::stPlaybackTimer Oct 25 20:18:42 volumio volumio[1187]: info: CoreStateMachine::servicePause Oct 25 20:18:42 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 25 20:18:42 volumio volumio[1187]: info: CoreCommandRouter::servicePause Oct 25 20:18:42 volumio volumio[1187]: info: ControllerMpd::pause Oct 25 20:18:42 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand pause Oct 25 20:18:42 volumio volumio[1187]: info: Saving Audio Output to: {"output_device":{"value":"1,1","label":"SPDIF"}} Oct 25 20:18:42 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 25 20:18:42 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 25 20:18:42 volumio volumio[1187]: info: Found match in Cards Database: setting mixer for card SPDIF Oct 25 20:18:42 volumio volumio[1187]: info: Setting mixer Master for card SPDIF Oct 25 20:18:42 volumio volumio[1187]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 25 20:18:42 volumio volumio[1187]: info: Updating Volume Controller Parameters: Device: 1,1 Name: SPDIF Mixer: Master Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 25 20:18:42 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 25 20:18:42 volumio volumio[1187]: info: Disabling external Volume Control Oct 25 20:18:42 volumio volumio[1187]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 25 20:18:42 volumio volumio[1187]: info: Preparing to generate the ALSA configuration file Oct 25 20:18:42 volumio volumio[1187]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Oct 25 20:18:42 volumio volumio[1187]: info: Reading ALSA contributions from plugins. Oct 25 20:18:42 volumio volumio[1187]: info: Oct 25 20:18:42 volumio volumio[1187]: ---------------------------- MPD announces state update: player Oct 25 20:18:42 volumio volumio[1187]: info: sendMpdCommand pause took 670 milliseconds Oct 25 20:18:42 volumio volumio[1187]: info: ControllerMpd::getState Oct 25 20:18:42 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand status Oct 25 20:18:42 volumio volumio[1187]: info: sendMpdCommand status took 92 milliseconds Oct 25 20:18:42 volumio volumio[1187]: verbose: ControllerMpd::parseState Oct 25 20:18:42 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 25 20:18:42 volumio volumio[1187]: info: sendMpdCommand playlistinfo took 23 milliseconds Oct 25 20:18:43 volumio volumio[1187]: verbose: ControllerMpd::parseTrackInfo Oct 25 20:18:43 volumio volumio[1187]: info: ControllerMpd::pushState Oct 25 20:18:43 volumio volumio[1187]: info: CoreCommandRouter::servicePushState Oct 25 20:18:43 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 25 20:18:43 volumio volumio[1187]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":86393,"duration":281,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5063 Kbps","isStreaming":false,"title":"長い間","artist":"Kiroro","album":"Nagai Aida ~Kiroro no Mori~","uri":"USB/Kiroro - 長い間.flac","trackType":"flac"} Oct 25 20:18:43 volumio volumio[1187]: verbose: CURRENT POSITION 0 Oct 25 20:18:43 volumio volumio[1187]: info: CoreStateMachine::syncState stateService pause Oct 25 20:18:43 volumio volumio[1187]: info: CoreStateMachine::syncState currentStatus pause Oct 25 20:18:43 volumio volumio[1187]: info: CoreStateMachine::pushState Oct 25 20:18:43 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Oct 25 20:18:43 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 25 20:18:43 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Oct 25 20:18:43 volumio volumio[1187]: info: CoreStateMachine::stPlaybackTimer Oct 25 20:18:43 volumio volumio[1187]: info: ------------------------------ 332ms Oct 25 20:18:43 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 25 20:18:43 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 25 20:18:43 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 25 20:18:43 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 25 20:18:43 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 25 20:18:43 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 25 20:18:43 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 25 20:18:43 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 25 20:18:43 volumio volumio[1187]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 25 20:18:43 volumio volumio[1187]: Error: ENOTDIR: not a directory, scandir '/tmp/peppyrunning' Oct 25 20:18:43 volumio volumio[1187]: at Object.readdirSync (fs.js:1048:3) Oct 25 20:18:43 volumio volumio[1187]: at rmkidsSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:349:11) Oct 25 20:18:43 volumio volumio[1187]: at rmdirSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:342:7) Oct 25 20:18:43 volumio volumio[1187]: at Function.rimrafSync [as sync] (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:319:62) Oct 25 20:18:43 volumio volumio[1187]: at Object.removeSync (/data/plugins/user_interface/peppy_screensaver/node_modules/fs-extra/lib/remove/index.js:4:17) Oct 25 20:18:43 volumio volumio[1187]: at Socket. (/data/plugins/user_interface/peppy_screensaver/index.js:225:48) Oct 25 20:18:43 volumio volumio[1187]: at Socket.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Oct 25 20:18:43 volumio volumio[1187]: at Socket.onevent (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:278:10) Oct 25 20:18:43 volumio volumio[1187]: at Socket.onpacket (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:236:12) Oct 25 20:18:43 volumio volumio[1187]: at Manager. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Oct 25 20:18:43 volumio volumio[1187]: at Manager.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Oct 25 20:18:43 volumio volumio[1187]: at Manager.ondecoded (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:349:8) Oct 25 20:18:43 volumio volumio[1187]: at Decoder. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Oct 25 20:18:43 volumio volumio[1187]: at Decoder.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Oct 25 20:18:43 volumio volumio[1187]: at Decoder.add (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-parser/index.js:251:12) Oct 25 20:18:43 volumio volumio[1187]: at Manager.ondata (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:339:16) { Oct 25 20:18:43 volumio volumio[1187]: errno: -20, Oct 25 20:18:43 volumio volumio[1187]: syscall: 'scandir', Oct 25 20:18:43 volumio volumio[1187]: code: 'ENOTDIR', Oct 25 20:18:43 volumio volumio[1187]: path: '/tmp/peppyrunning' Oct 25 20:18:43 volumio volumio[1187]: } Oct 25 20:18:43 volumio volumio[1187]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 25 20:18:44 volumio sudo[3003]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-25 20:17 Oct 25 20:18:44 volumio sudo[3003]: 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:48:50 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="1049be5fd382035818795b7f5c38e10d"