-- Logs begin at Tue 2025-12-16 21:56:26 CST, end at Tue 2025-12-16 21:59:30 CST. -- Dec 16 21:58:00 volumio jellyfin[1624]: [21:58:00] [INF] [1] Emby.Server.Implementations.ApplicationHost: Loading assemblies Dec 16 21:58:01 volumio mpd[1748]: Dec 16 21:58 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 16 21:58:01 volumio systemd[1]: Started Music Player Daemon. Dec 16 21:58:01 volumio sudo[1724]: pam_unix(sudo:session): session closed for user root Dec 16 21:58:01 volumio volumio[1172]: Upnp client error: Error: This socket has been ended by the other party Dec 16 21:58:01 volumio volumio[1172]: error: updateQueue error: null Dec 16 21:58:01 volumio volumio[1172]: info: sendMpdCommand stop took 1481 milliseconds Dec 16 21:58:01 volumio volumio[1172]: verbose: ControllerMpd::sendMpdCommand clear Dec 16 21:58:01 volumio volumio[1172]: info: Dec 16 21:58:01 volumio volumio[1172]: ---------------------------- MPD announces system playlist update Dec 16 21:58:01 volumio volumio[1172]: info: Ignoring MPD Status Update Dec 16 21:58:01 volumio volumio[1172]: info: sendMpdCommand clear took 39 milliseconds Dec 16 21:58:01 volumio volumio[1172]: verbose: ControllerMpd::sendMpdCommand add "USB/赵鹏 - 乌兰巴托的夜晚.flac" Dec 16 21:58:01 volumio volumio[1172]: error: updateQueue error: null Dec 16 21:58:01 volumio volumio[1172]: info: Dec 16 21:58:01 volumio volumio[1172]: ---------------------------- MPD announces system playlist update Dec 16 21:58:01 volumio volumio[1172]: info: Ignoring MPD Status Update Dec 16 21:58:01 volumio volumio[1172]: info: ------------------------------ 43ms Dec 16 21:58:01 volumio volumio[1172]: info: sendMpdCommand add "USB/赵鹏 - 乌兰巴托的夜晚.flac" took 30 milliseconds Dec 16 21:58:01 volumio volumio[1172]: verbose: ControllerMpd::sendMpdCommand play Dec 16 21:58:01 volumio volumio[1172]: info: Dec 16 21:58:01 volumio volumio[1172]: ---------------------------- MPD announces system playlist update Dec 16 21:58:01 volumio volumio[1172]: info: Ignoring MPD Status Update Dec 16 21:58:01 volumio volumio[1172]: info: Dec 16 21:58:01 volumio volumio[1172]: ---------------------------- MPD announces state update: player Dec 16 21:58:01 volumio volumio[1172]: info: ------------------------------ 58ms Dec 16 21:58:01 volumio volumio[1172]: info: sendMpdCommand play took 40 milliseconds Dec 16 21:58:01 volumio volumio[1172]: info: ControllerMpd::getState Dec 16 21:58:01 volumio volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Dec 16 21:58:01 volumio volumio[1172]: info: Dec 16 21:58:01 volumio volumio[1172]: ---------------------------- MPD announces state update: player Dec 16 21:58:01 volumio volumio[1172]: info: ControllerMpd::getState Dec 16 21:58:01 volumio volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Dec 16 21:58:01 volumio volumio[1172]: info: Dec 16 21:58:01 volumio volumio[1172]: ---------------------------- MPD announces state update: player Dec 16 21:58:01 volumio volumio[1172]: info: ------------------------------ 157ms Dec 16 21:58:01 volumio volumio[1172]: info: sendMpdCommand status took 130 milliseconds Dec 16 21:58:01 volumio volumio[1172]: info: sendMpdCommand status took 133 milliseconds Dec 16 21:58:01 volumio volumio[1172]: info: ControllerMpd::getState Dec 16 21:58:01 volumio volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Dec 16 21:58:01 volumio volumio[1172]: verbose: ControllerMpd::parseState Dec 16 21:58:01 volumio volumio[1172]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 21:58:01 volumio volumio[1172]: verbose: ControllerMpd::parseState Dec 16 21:58:01 volumio volumio[1172]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 21:58:01 volumio volumio[1172]: info: Dec 16 21:58:01 volumio volumio[1172]: ---------------------------- MPD announces state update: player Dec 16 21:58:01 volumio volumio[1172]: info: ControllerMpd::getState Dec 16 21:58:01 volumio volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Dec 16 21:58:01 volumio volumio[1172]: info: sendMpdCommand status took 67 milliseconds Dec 16 21:58:01 volumio volumio[1172]: info: sendMpdCommand playlistinfo took 52 milliseconds Dec 16 21:58:01 volumio volumio[1172]: info: sendMpdCommand playlistinfo took 53 milliseconds Dec 16 21:58:01 volumio volumio[1172]: verbose: ControllerMpd::parseState Dec 16 21:58:01 volumio volumio[1172]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 21:58:01 volumio volumio[1172]: verbose: ControllerMpd::parseTrackInfo Dec 16 21:58:01 volumio volumio[1172]: verbose: ControllerMpd::parseTrackInfo Dec 16 21:58:01 volumio volumio[1172]: info: ControllerMpd::pushState Dec 16 21:58:01 volumio volumio[1172]: info: CoreCommandRouter::servicePushState Dec 16 21:58:01 volumio volumio[1172]: info: CorePlayQueue::getTrack 0 Dec 16 21:58:01 volumio volumio[1172]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"305 Kbps","isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"} Dec 16 21:58:01 volumio volumio[1172]: verbose: CURRENT POSITION 0 Dec 16 21:58:01 volumio volumio[1172]: info: CoreStateMachine::syncState stateService play Dec 16 21:58:01 volumio volumio[1172]: info: CoreStateMachine::syncState currentStatus stop Dec 16 21:58:01 volumio volumio[1172]: info: ControllerMpd::pushState Dec 16 21:58:01 volumio volumio[1172]: info: CoreCommandRouter::servicePushState Dec 16 21:58:01 volumio volumio[1172]: info: CorePlayQueue::getTrack 0 Dec 16 21:58:01 volumio volumio[1172]: verbose: STATE SERVICE {"status":"play","position":0,"seek":295,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"303 Kbps","isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"} Dec 16 21:58:01 volumio volumio[1172]: verbose: CURRENT POSITION 0 Dec 16 21:58:01 volumio volumio[1172]: info: CoreStateMachine::syncState stateService play Dec 16 21:58:01 volumio volumio[1172]: info: CoreStateMachine::syncState currentStatus play Dec 16 21:58:01 volumio volumio[1172]: info: Received an update from plugin. extracting info from payload Dec 16 21:58:01 volumio volumio[1172]: info: CoreStateMachine::pushState Dec 16 21:58:01 volumio volumio[1172]: info: CorePlayQueue::getTrack 0 Dec 16 21:58:01 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 21:58:01 volumio volumio[1172]: info: CoreCommandRouter::volumioPushState Dec 16 21:58:01 volumio volumio[1172]: info: CoreStateMachine::pushState Dec 16 21:58:01 volumio volumio[1172]: info: CorePlayQueue::getTrack 0 Dec 16 21:58:01 volumio volumio[1172]: info: CoreCommandRouter::volumioPushState Dec 16 21:58:01 volumio volumio[1172]: info: ------------------------------ 370ms Dec 16 21:58:01 volumio volumio[1172]: info: ------------------------------ 444ms Dec 16 21:58:01 volumio volumio[1172]: info: sendMpdCommand status took 272 milliseconds Dec 16 21:58:01 volumio volumio[1172]: info: sendMpdCommand playlistinfo took 256 milliseconds Dec 16 21:58:01 volumio volumio[1172]: verbose: ControllerMpd::parseState Dec 16 21:58:01 volumio volumio[1172]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 21:58:01 volumio volumio[1172]: verbose: ControllerMpd::parseTrackInfo Dec 16 21:58:01 volumio volumio[1172]: info: ControllerMpd::pushState Dec 16 21:58:01 volumio volumio[1172]: info: CoreCommandRouter::servicePushState Dec 16 21:58:01 volumio volumio[1172]: info: CorePlayQueue::getTrack 0 Dec 16 21:58:01 volumio volumio[1172]: verbose: STATE SERVICE {"status":"play","position":0,"seek":705,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"558 Kbps","isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"} Dec 16 21:58:01 volumio volumio[1172]: verbose: CURRENT POSITION 0 Dec 16 21:58:01 volumio volumio[1172]: info: CoreStateMachine::syncState stateService play Dec 16 21:58:01 volumio volumio[1172]: info: CoreStateMachine::syncState currentStatus play Dec 16 21:58:01 volumio volumio[1172]: info: Received an update from plugin. extracting info from payload Dec 16 21:58:01 volumio volumio[1172]: info: CoreStateMachine::pushState Dec 16 21:58:01 volumio volumio[1172]: info: CorePlayQueue::getTrack 0 Dec 16 21:58:01 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 21:58:01 volumio volumio[1172]: info: CoreCommandRouter::volumioPushState Dec 16 21:58:01 volumio volumio[1172]: info: CoreStateMachine::pushState Dec 16 21:58:01 volumio volumio[1172]: info: CorePlayQueue::getTrack 0 Dec 16 21:58:01 volumio volumio[1172]: info: CoreCommandRouter::volumioPushState Dec 16 21:58:02 volumio volumio[1172]: info: ------------------------------ 538ms Dec 16 21:58:02 volumio volumio[1172]: info: sendMpdCommand playlistinfo took 297 milliseconds Dec 16 21:58:02 volumio volumio[1172]: verbose: ControllerMpd::parseTrackInfo Dec 16 21:58:02 volumio volumio[1172]: info: ControllerMpd::pushState Dec 16 21:58:02 volumio volumio[1172]: info: CoreCommandRouter::servicePushState Dec 16 21:58:02 volumio volumio[1172]: info: CorePlayQueue::getTrack 0 Dec 16 21:58:02 volumio volumio[1172]: verbose: STATE SERVICE {"status":"play","position":0,"seek":796,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"664 Kbps","isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"} Dec 16 21:58:02 volumio volumio[1172]: verbose: CURRENT POSITION 0 Dec 16 21:58:02 volumio volumio[1172]: info: CoreStateMachine::syncState stateService play Dec 16 21:58:02 volumio volumio[1172]: info: CoreStateMachine::syncState currentStatus play Dec 16 21:58:02 volumio volumio[1172]: info: Received an update from plugin. extracting info from payload Dec 16 21:58:02 volumio volumio[1172]: info: CoreStateMachine::pushState Dec 16 21:58:02 volumio volumio[1172]: info: CorePlayQueue::getTrack 0 Dec 16 21:58:02 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 21:58:02 volumio volumio[1172]: info: CoreCommandRouter::volumioPushState Dec 16 21:58:02 volumio volumio[1172]: info: CoreStateMachine::pushState Dec 16 21:58:02 volumio volumio[1172]: info: CorePlayQueue::getTrack 0 Dec 16 21:58:02 volumio volumio[1172]: info: CoreCommandRouter::volumioPushState Dec 16 21:58:02 volumio volumio[1172]: info: ------------------------------ 745ms Dec 16 21:58:03 volumio jellyfin[1624]: [21:58:03] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Defined LAN subnets: ["127.0.0.1/8", "10.0.0.0/8", "172.16.0.0/12", "192.168.0.0/16"] Dec 16 21:58:03 volumio jellyfin[1624]: [21:58:03] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Defined LAN exclusions: [] Dec 16 21:58:03 volumio jellyfin[1624]: [21:58:03] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Used LAN subnets: ["127.0.0.1/8", "10.0.0.0/8", "172.16.0.0/12", "192.168.0.0/16"] Dec 16 21:58:03 volumio jellyfin[1624]: [21:58:03] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Filtered interface addresses: ["127.0.0.1", "192.168.1.6"] Dec 16 21:58:03 volumio jellyfin[1624]: [21:58:03] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Bind Addresses ["0.0.0.0"] Dec 16 21:58:03 volumio jellyfin[1624]: [21:58:03] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Remote IP filter is Allowlist Dec 16 21:58:03 volumio jellyfin[1624]: [21:58:03] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Filtered subnets: [] Dec 16 21:58:03 volumio RoonBridge[1658]: 00:00:16.996 Warn: exception starting raatserver: System.Net.Sockets.SocketException (0x80004005): Connection refused Dec 16 21:58:03 volumio RoonBridge[1658]: at System.Net.Sockets.TcpClient..ctor (System.String hostname, System.Int32 port) [0x0006d] in <08911f3881ec4a92acc8fdc4d13b73bf>:0 Dec 16 21:58:03 volumio RoonBridge[1658]: at Sooloos.RAATServer.ConnectOrStartAndWaitForExit (System.String path, System.String args, System.Action`1[T] status, Base.ChildProcess& p) [0x00165] in <5529660cb9214b5a92e49e1607b30d50>:0 Dec 16 21:58:03 volumio RoonBridge[1658]: Not Running (.o) Dec 16 21:58:03 volumio RoonBridge[1658]: 00:00:17.269 Debug: PathForResource, filename: ../.update Dec 16 21:58:03 volumio RoonBridge[1658]: 00:00:17.269 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update Dec 16 21:58:04 volumio RoonBridge[1658]: 00:00:00.698 Warn: get lock file path: /tmp/.rnbhgem0- Dec 16 21:58:04 volumio volumio[1172]: info: CoreCommandRouter::volumioGetState Dec 16 21:58:04 volumio volumio[1172]: info: CorePlayQueue::getTrack 0 Dec 16 21:58:04 volumio sudo[1832]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/usr/bin/python3 ./screensaver/volumio_peppymeter.py Dec 16 21:58:04 volumio sudo[1832]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 21:58:06 volumio RoonBridge[1658]: 00:00:19.285 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Dec 16 21:58:06 volumio RoonBridge[1658]: Running Dec 16 21:58:09 volumio volumio[1172]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 16 21:58:09 volumio volumio[1172]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 16 21:58:09 volumio volumio[1172]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 16 21:58:09 volumio volumio[1172]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 16 21:58:09 volumio volumio[1172]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 16 21:58:09 volumio volumio[1172]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 16 21:58:09 volumio volumio[1172]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 16 21:58:09 volumio volumio[1172]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 16 21:58:09 volumio volumio[1172]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 16 21:58:09 volumio volumio[1172]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 16 21:58:09 volumio volumio[1172]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Dec 16 21:58:09 volumio volumio[1172]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 16 21:58:09 volumio volumio[1172]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 16 21:58:09 volumio volumio[1172]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 16 21:58:09 volumio volumio[1172]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 16 21:58:09 volumio volumio[1172]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 16 21:58:09 volumio volumio[1172]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 16 21:58:09 volumio volumio[1172]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 16 21:58:09 volumio volumio[1172]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 16 21:58:09 volumio volumio[1172]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 16 21:58:09 volumio volumio[1172]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 16 21:58:09 volumio volumio[1172]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Dec 16 21:58:09 volumio volumio[1172]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 16 21:58:09 volumio volumio[1172]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 16 21:58:09 volumio volumio[1172]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 16 21:58:09 volumio volumio[1172]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 16 21:58:09 volumio volumio[1172]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 16 21:58:09 volumio volumio[1172]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 16 21:58:09 volumio volumio[1172]: info: Adding plugin bluetooth to MyMusic Plugins Dec 16 21:58:09 volumio volumio[1172]: info: Adding plugin multiroom to MyMusic Plugins Dec 16 21:58:09 volumio volumio[1172]: info: Adding plugin metavolumio to MyMusic Plugins Dec 16 21:58:09 volumio volumio[1172]: info: Adding plugin cd_controller to MyMusic Plugins Dec 16 21:58:09 volumio volumio[1172]: info: Adding plugin smart_inputs to MyMusic Plugins Dec 16 21:58:09 volumio volumio[1172]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 16 21:58:09 volumio volumio[1172]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 16 21:58:12 volumio RoonBridge[1658]: 00:00:25.390 Warn: exception starting raatserver: System.Net.Sockets.SocketException (0x80004005): Connection refused Dec 16 21:58:12 volumio RoonBridge[1658]: at System.Net.Sockets.TcpClient..ctor (System.String hostname, System.Int32 port) [0x0006d] in <08911f3881ec4a92acc8fdc4d13b73bf>:0 Dec 16 21:58:12 volumio RoonBridge[1658]: at Sooloos.RAATServer.ConnectOrStartAndWaitForExit (System.String path, System.String args, System.Action`1[T] status, Base.ChildProcess& p) [0x00165] in <5529660cb9214b5a92e49e1607b30d50>:0 Dec 16 21:58:12 volumio RoonBridge[1658]: 00:00:25.398 Debug: PathForResource, filename: ../.update Dec 16 21:58:12 volumio RoonBridge[1658]: 00:00:25.398 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update Dec 16 21:58:12 volumio RoonBridge[1658]: Not Running (.o) Dec 16 21:58:14 volumio RoonBridge[1658]: 00:00:27.406 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Dec 16 21:58:14 volumio RoonBridge[1658]: Running Dec 16 21:58:16 volumio volumio[1172]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 16 21:58:16 volumio volumio[1172]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 16 21:58:16 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 21:58:16 volumio volumio[1172]: info: Starting MyVolumio Remote Streaming Endpoints Dec 16 21:58:16 volumio volumio[1172]: info: MyVolumio login type: Token Dec 16 21:58:16 volumio volumio[1172]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 16 21:58:16 volumio volumio[1172]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 16 21:58:20 volumio RoonBridge[1658]: 00:00:33.470 Warn: exception starting raatserver: System.Net.Sockets.SocketException (0x80004005): Connection refused Dec 16 21:58:20 volumio RoonBridge[1658]: at System.Net.Sockets.TcpClient..ctor (System.String hostname, System.Int32 port) [0x0006d] in <08911f3881ec4a92acc8fdc4d13b73bf>:0 Dec 16 21:58:20 volumio RoonBridge[1658]: at Sooloos.RAATServer.ConnectOrStartAndWaitForExit (System.String path, System.String args, System.Action`1[T] status, Base.ChildProcess& p) [0x00165] in <5529660cb9214b5a92e49e1607b30d50>:0 Dec 16 21:58:20 volumio RoonBridge[1658]: 00:00:33.505 Debug: PathForResource, filename: ../.update Dec 16 21:58:20 volumio RoonBridge[1658]: 00:00:33.505 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update Dec 16 21:58:20 volumio RoonBridge[1658]: Not Running (.o) Dec 16 21:58:22 volumio RoonBridge[1658]: 00:00:35.516 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Dec 16 21:58:22 volumio RoonBridge[1658]: Running Dec 16 21:58:24 volumio volumio[1172]: info: Starting Streaming Service Transparent Proxy Dec 16 21:58:24 volumio volumio[1172]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 16 21:58:24 volumio volumio[1172]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 16 21:58:24 volumio volumio[1172]: info: Streaming services startup Dec 16 21:58:24 volumio volumio[1172]: info: Starting Streaming Daemon Dec 16 21:58:25 volumio sudo[2046]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 16 21:58:25 volumio sudo[2046]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 16 21:58:25 volumio sudo[2046]: pam_unix(sudo:session): session closed for user root Dec 16 21:58:25 volumio volumio[1172]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Dec 16 21:58:26 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 21:58:26 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 21:58:26 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 16 21:58:26 volumio volumio[1172]: error: Cannot start Volumio Streaming Daemon Dec 16 21:58:26 volumio volumio[1172]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 16 21:58:26 volumio volumio[1172]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 16 21:58:27 volumio volumio[1172]: Upnp client error: Error: This socket has been ended by the other party Dec 16 21:58:28 volumio volumio[1172]: 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: 4 Dec 16 21:58:28 volumio RoonBridge[1658]: 00:00:41.633 Warn: exception starting raatserver: System.Net.Sockets.SocketException (0x80004005): Connection refused Dec 16 21:58:28 volumio RoonBridge[1658]: at System.Net.Sockets.TcpClient..ctor (System.String hostname, System.Int32 port) [0x0006d] in <08911f3881ec4a92acc8fdc4d13b73bf>:0 Dec 16 21:58:28 volumio RoonBridge[1658]: at Sooloos.RAATServer.ConnectOrStartAndWaitForExit (System.String path, System.String args, System.Action`1[T] status, Base.ChildProcess& p) [0x00165] in <5529660cb9214b5a92e49e1607b30d50>:0 Dec 16 21:58:28 volumio RoonBridge[1658]: 00:00:41.633 Debug: PathForResource, filename: ../.update Dec 16 21:58:28 volumio RoonBridge[1658]: 00:00:41.633 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update Dec 16 21:58:28 volumio RoonBridge[1658]: Not Running (.o) Dec 16 21:58:28 volumio volumio[1172]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Dec 16 21:58:30 volumio RoonBridge[1658]: 00:00:43.633 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Dec 16 21:58:30 volumio RoonBridge[1658]: Running Dec 16 21:58:30 volumio volumio[1172]: 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 16 21:58:30 volumio volumio[1172]: info: CoreCommandRouter::volumioGetState Dec 16 21:58:30 volumio volumio[1172]: info: CorePlayQueue::getTrack 0 Dec 16 21:58:31 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 16 21:58:31 volumio volumio[1172]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Dec 16 21:58:32 volumio volumio[1172]: info: CoreCommandRouter::volumioGetState Dec 16 21:58:32 volumio volumio[1172]: info: CorePlayQueue::getTrack 0 Dec 16 21:58:33 volumio volumio[1172]: STREAMING PROXY: Starting server on port 3245 Dec 16 21:58:34 volumio volumio[1172]: Node JS runtime: 14 Dec 16 21:58:36 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 21:58:36 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 16 21:58:36 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 16 21:58:36 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 16 21:58:36 volumio volumio[1172]: info: CoreCommandRouter::volumioGetBrowseSources Dec 16 21:58:36 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 16 21:58:36 volumio RoonBridge[1658]: 00:00:49.731 Warn: exception starting raatserver: System.Net.Sockets.SocketException (0x80004005): Connection refused Dec 16 21:58:36 volumio RoonBridge[1658]: at System.Net.Sockets.TcpClient..ctor (System.String hostname, System.Int32 port) [0x0006d] in <08911f3881ec4a92acc8fdc4d13b73bf>:0 Dec 16 21:58:36 volumio RoonBridge[1658]: at Sooloos.RAATServer.ConnectOrStartAndWaitForExit (System.String path, System.String args, System.Action`1[T] status, Base.ChildProcess& p) [0x00165] in <5529660cb9214b5a92e49e1607b30d50>:0 Dec 16 21:58:36 volumio RoonBridge[1658]: 00:00:49.756 Debug: PathForResource, filename: ../.update Dec 16 21:58:36 volumio RoonBridge[1658]: 00:00:49.756 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update Dec 16 21:58:36 volumio RoonBridge[1658]: Not Running (.o) Dec 16 21:58:38 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 16 21:58:38 volumio RoonBridge[1658]: 00:00:51.760 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Dec 16 21:58:38 volumio RoonBridge[1658]: Running Dec 16 21:58:44 volumio RoonBridge[1658]: 00:00:57.797 Warn: exception starting raatserver: System.Net.Sockets.SocketException (0x80004005): Connection refused Dec 16 21:58:44 volumio RoonBridge[1658]: at System.Net.Sockets.TcpClient..ctor (System.String hostname, System.Int32 port) [0x0006d] in <08911f3881ec4a92acc8fdc4d13b73bf>:0 Dec 16 21:58:44 volumio RoonBridge[1658]: at Sooloos.RAATServer.ConnectOrStartAndWaitForExit (System.String path, System.String args, System.Action`1[T] status, Base.ChildProcess& p) [0x00165] in <5529660cb9214b5a92e49e1607b30d50>:0 Dec 16 21:58:44 volumio RoonBridge[1658]: Not Running (.o) Dec 16 21:58:44 volumio RoonBridge[1658]: 00:00:57.812 Debug: PathForResource, filename: ../.update Dec 16 21:58:44 volumio RoonBridge[1658]: 00:00:57.812 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update Dec 16 21:58:45 volumio volumio[1172]: error: MyVolumio Plugin failed to authenticate in a timely fashion Dec 16 21:58:45 volumio volumio[1172]: info: Completed starting MyVolumio Plugin Dec 16 21:58:45 volumio volumio[1172]: info: BOOT COMPLETED Dec 16 21:58:45 volumio volumio[1172]: [Metrics] CommandRouter: 91s 228.14ms Dec 16 21:58:45 volumio volumio[1172]: info: CoreCommandRouter::volumiosetStartupVolume Dec 16 21:58:45 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 21:58:45 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 21:58:45 volumio volumio[1172]: info: CoreCommandRouter::Close All Modals sent Dec 16 21:58:45 volumio volumio[1172]: info: CoreCommandRouter::Close All Modals sent Dec 16 21:58:45 volumio volumio[1172]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Dec 16 21:58:45 volumio volumio[1172]: aplay: main:828: audio open error: Device or resource busy Dec 16 21:58:46 volumio RoonBridge[1658]: 00:00:59.812 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Dec 16 21:58:46 volumio RoonBridge[1658]: Running Dec 16 21:58:46 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 16 21:58:46 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 16 21:58:46 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 16 21:58:48 volumio volumio[1172]: 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: 8 Dec 16 21:58:50 volumio volumio[1581]: Generating RSA private key, 4096 bit long modulus (2 primes) Dec 16 21:58:52 volumio RoonBridge[1658]: 00:01:05.850 Warn: exception starting raatserver: System.Net.Sockets.SocketException (0x80004005): Connection refused Dec 16 21:58:52 volumio RoonBridge[1658]: at System.Net.Sockets.TcpClient..ctor (System.String hostname, System.Int32 port) [0x0006d] in <08911f3881ec4a92acc8fdc4d13b73bf>:0 Dec 16 21:58:52 volumio RoonBridge[1658]: at Sooloos.RAATServer.ConnectOrStartAndWaitForExit (System.String path, System.String args, System.Action`1[T] status, Base.ChildProcess& p) [0x00165] in <5529660cb9214b5a92e49e1607b30d50>:0 Dec 16 21:58:52 volumio RoonBridge[1658]: 00:01:05.893 Debug: PathForResource, filename: ../.update Dec 16 21:58:52 volumio RoonBridge[1658]: 00:01:05.893 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update Dec 16 21:58:52 volumio RoonBridge[1658]: Not Running (.o) Dec 16 21:58:53 volumio jellyfin[1624]: [21:58:53] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: TMDb 10.9.6.0 Dec 16 21:58:53 volumio jellyfin[1624]: [21:58:53] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: Studio Images 10.9.6.0 Dec 16 21:58:53 volumio jellyfin[1624]: [21:58:53] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: OMDb 10.9.6.0 Dec 16 21:58:54 volumio jellyfin[1624]: [21:58:54] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: MusicBrainz 10.9.6.0 Dec 16 21:58:54 volumio jellyfin[1624]: [21:58:54] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: AudioDB 10.9.6.0 Dec 16 21:58:54 volumio RoonBridge[1658]: 00:01:07.905 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Dec 16 21:58:54 volumio RoonBridge[1658]: Running Dec 16 21:58:55 volumio volumio[1172]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Dec 16 21:58:55 volumio volumio[1172]: info: Retrying Login Due to Network Error Dec 16 21:58:55 volumio jellyfin[1624]: [21:58:55] [INF] [1] Jellyfin.Server.Migrations.MigrationRunner: Marking following migrations as applied because this is a fresh install: ["DisableTranscodingThrottling", "CreateLoggingConfigHeirarchy", "MigrateActivityLogDatabase", "RemoveDuplicateExtras", "MigrateUserDatabase", "MigrateDisplayPreferencesDatabase", "RemoveDownloadImagesInAdvance", "MigrateAuthenticationDatabase", "FixPlaylistOwner", "MigrateRatingLevels", "FixAudioData"] Dec 16 21:58:56 volumio jellyfin[1624]: [21:58:56] [INF] [1] Main: Kestrel is listening on 0.0.0.0 Dec 16 21:58:57 volumio volumio[1581]: .....................................................++++ Dec 16 21:58:58 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 16 21:58:59 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 16 21:59:00 volumio RoonBridge[1658]: 00:01:14.031 Warn: exception starting raatserver: System.Net.Sockets.SocketException (0x80004005): Connection refused Dec 16 21:59:00 volumio RoonBridge[1658]: at System.Net.Sockets.TcpClient..ctor (System.String hostname, System.Int32 port) [0x0006d] in <08911f3881ec4a92acc8fdc4d13b73bf>:0 Dec 16 21:59:00 volumio RoonBridge[1658]: at Sooloos.RAATServer.ConnectOrStartAndWaitForExit (System.String path, System.String args, System.Action`1[T] status, Base.ChildProcess& p) [0x00165] in <5529660cb9214b5a92e49e1607b30d50>:0 Dec 16 21:59:00 volumio RoonBridge[1658]: 00:01:14.036 Debug: PathForResource, filename: ../.update Dec 16 21:59:00 volumio RoonBridge[1658]: 00:01:14.042 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update Dec 16 21:59:00 volumio RoonBridge[1658]: Not Running (.o) Dec 16 21:59:01 volumio volumio[1172]: info: CoreCommandRouter::volumioGetVisibleSources Dec 16 21:59:01 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 21:59:01 volumio volumio[1172]: info: CoreCommandRouter::volumioGetState Dec 16 21:59:01 volumio volumio[1172]: info: CorePlayQueue::getTrack 0 Dec 16 21:59:01 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 16 21:59:01 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 16 21:59:01 volumio volumio[1172]: info: Received Get System Info Dec 16 21:59:01 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 21:59:01 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 21:59:01 volumio volumio[1172]: info: Discovery: Getting this device information Dec 16 21:59:01 volumio volumio[1172]: info: CoreCommandRouter::volumioGetState Dec 16 21:59:01 volumio volumio[1172]: info: CorePlayQueue::getTrack 0 Dec 16 21:59:01 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 21:59:01 volumio volumio[1172]: info: CoreCommandRouter::volumioGetState Dec 16 21:59:01 volumio volumio[1172]: info: CorePlayQueue::getTrack 0 Dec 16 21:59:01 volumio volumio[1172]: info: Listing playlists Dec 16 21:59:01 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 16 21:59:01 volumio volumio[1172]: info: Received Get System Info Dec 16 21:59:01 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 21:59:01 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 21:59:01 volumio volumio[1172]: info: Discovery: Getting this device information Dec 16 21:59:01 volumio volumio[1172]: info: CoreCommandRouter::volumioGetState Dec 16 21:59:01 volumio volumio[1172]: info: CorePlayQueue::getTrack 0 Dec 16 21:59:01 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 21:59:01 volumio volumio[1172]: info: CoreCommandRouter::volumioGetState Dec 16 21:59:01 volumio volumio[1172]: info: CorePlayQueue::getTrack 0 Dec 16 21:59:01 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 16 21:59:01 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 16 21:59:01 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 16 21:59:02 volumio volumio[1172]: info: CoreCommandRouter::Close All Modals sent Dec 16 21:59:02 volumio RoonBridge[1658]: 00:01:16.043 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Dec 16 21:59:02 volumio RoonBridge[1658]: Running Dec 16 21:59:05 volumio volumio[1172]: info: MyVolumio login type: Token Dec 16 21:59:07 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 16 21:59:08 volumio volumio[1172]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 16 21:59:08 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 21:59:08 volumio RoonBridge[1658]: 00:01:22.083 Warn: exception starting raatserver: System.Net.Sockets.SocketException (0x80004005): Connection refused Dec 16 21:59:08 volumio RoonBridge[1658]: at System.Net.Sockets.TcpClient..ctor (System.String hostname, System.Int32 port) [0x0006d] in <08911f3881ec4a92acc8fdc4d13b73bf>:0 Dec 16 21:59:08 volumio RoonBridge[1658]: at Sooloos.RAATServer.ConnectOrStartAndWaitForExit (System.String path, System.String args, System.Action`1[T] status, Base.ChildProcess& p) [0x00165] in <5529660cb9214b5a92e49e1607b30d50>:0 Dec 16 21:59:08 volumio RoonBridge[1658]: Not Running (.o) Dec 16 21:59:08 volumio RoonBridge[1658]: 00:01:22.089 Debug: PathForResource, filename: ../.update Dec 16 21:59:08 volumio RoonBridge[1658]: 00:01:22.089 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update Dec 16 21:59:10 volumio RoonBridge[1658]: 00:01:24.090 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Dec 16 21:59:10 volumio RoonBridge[1658]: Running Dec 16 21:59:12 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 16 21:59:14 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 16 21:59:16 volumio RoonBridge[1658]: 00:01:30.122 Warn: exception starting raatserver: System.Net.Sockets.SocketException (0x80004005): Connection refused Dec 16 21:59:16 volumio RoonBridge[1658]: at System.Net.Sockets.TcpClient..ctor (System.String hostname, System.Int32 port) [0x0006d] in <08911f3881ec4a92acc8fdc4d13b73bf>:0 Dec 16 21:59:16 volumio RoonBridge[1658]: at Sooloos.RAATServer.ConnectOrStartAndWaitForExit (System.String path, System.String args, System.Action`1[T] status, Base.ChildProcess& p) [0x00165] in <5529660cb9214b5a92e49e1607b30d50>:0 Dec 16 21:59:16 volumio RoonBridge[1658]: Not Running (.o) Dec 16 21:59:16 volumio RoonBridge[1658]: 00:01:30.143 Debug: PathForResource, filename: ../.update Dec 16 21:59:16 volumio RoonBridge[1658]: 00:01:30.144 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update Dec 16 21:59:18 volumio jellyfin[1624]: [21:59:18] [WRN] [1] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The WebRootPath was not found: /wwwroot. Static files may be unavailable. Dec 16 21:59:18 volumio RoonBridge[1658]: 00:01:32.148 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Dec 16 21:59:18 volumio RoonBridge[1658]: Running Dec 16 21:59:20 volumio jellyfin[1624]: [21:59:20] [INF] [1] Emby.Server.Implementations.ApplicationHost: Running startup tasks Dec 16 21:59:20 volumio jellyfin[1624]: [21:59:20] [INF] [1] Emby.Server.Implementations.ScheduledTasks.TaskManager: Daily trigger for Generate Trickplay Images set to fire at 2025-12-17 03:00:00.000 +08:00, which is 05:00:39.0529655 from now. Dec 16 21:59:21 volumio jellyfin[1624]: [21:59:21] [INF] [1] Emby.Server.Implementations.ScheduledTasks.TaskManager: Daily trigger for Extract Chapter Images set to fire at 2025-12-17 02:00:00.000 +08:00, which is 04:00:38.9273286 from now. Dec 16 21:59:23 volumio jellyfin[1624]: [21:59:23] [INF] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: Found ffmpeg version 6.0.1 Dec 16 21:59:23 volumio volumio[1172]: info: CALLMETHOD: user_interface peppy_screensaver saveVUMeterConf [object Object] Dec 16 21:59:23 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: peppy_screensaver , saveVUMeterConf Dec 16 21:59:23 volumio volumio[1172]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 16 21:59:24 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 16 21:59:24 volumio RoonBridge[1658]: 00:01:38.201 Warn: exception starting raatserver: System.Net.Sockets.SocketException (0x80004005): Connection refused Dec 16 21:59:24 volumio RoonBridge[1658]: at System.Net.Sockets.TcpClient..ctor (System.String hostname, System.Int32 port) [0x0006d] in <08911f3881ec4a92acc8fdc4d13b73bf>:0 Dec 16 21:59:24 volumio RoonBridge[1658]: at Sooloos.RAATServer.ConnectOrStartAndWaitForExit (System.String path, System.String args, System.Action`1[T] status, Base.ChildProcess& p) [0x00165] in <5529660cb9214b5a92e49e1607b30d50>:0 Dec 16 21:59:24 volumio RoonBridge[1658]: Not Running (.o) Dec 16 21:59:24 volumio RoonBridge[1658]: 00:01:38.233 Debug: PathForResource, filename: ../.update Dec 16 21:59:24 volumio RoonBridge[1658]: 00:01:38.233 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update Dec 16 21:59:25 volumio jellyfin[1624]: [21:59:25] [INF] [10] Emby.Server.Implementations.ScheduledTasks.TaskManager: Clean up collections and playlists Completed after 0 minute(s) and 0 seconds Dec 16 21:59:26 volumio jellyfin[1624]: [21:59:26] [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"] Dec 16 21:59:26 volumio RoonBridge[1658]: 00:01:40.233 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Dec 16 21:59:26 volumio volumio[1581]: ...............................................................................................................................................................................................................................................................++++ Dec 16 21:59:26 volumio RoonBridge[1658]: Running Dec 16 21:59:26 volumio volumio[1581]: e is 65537 (0x010001) Dec 16 21:59:27 volumio volumio[1581]: writing RSA key Dec 16 21:59:28 volumio volumio[1172]: info: CoreCommandRouter::volumioPause Dec 16 21:59:28 volumio volumio[1172]: info: CoreStateMachine::pause Dec 16 21:59:28 volumio volumio[1172]: info: CoreStateMachine::stPlaybackTimer Dec 16 21:59:28 volumio volumio[1172]: info: CoreStateMachine::servicePause Dec 16 21:59:28 volumio volumio[1172]: info: CorePlayQueue::getTrack 0 Dec 16 21:59:28 volumio volumio[1172]: info: CoreCommandRouter::servicePause Dec 16 21:59:28 volumio volumio[1172]: info: ControllerMpd::pause Dec 16 21:59:28 volumio volumio[1172]: verbose: ControllerMpd::sendMpdCommand pause Dec 16 21:59:28 volumio volumio[1172]: info: Dec 16 21:59:28 volumio volumio[1172]: ---------------------------- MPD announces state update: player Dec 16 21:59:28 volumio volumio[1172]: info: sendMpdCommand pause took 12 milliseconds Dec 16 21:59:28 volumio volumio[1172]: info: ControllerMpd::getState Dec 16 21:59:28 volumio volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Dec 16 21:59:28 volumio volumio[1172]: info: Dec 16 21:59:28 volumio volumio[1172]: ---------------------------- MPD announces state update: player Dec 16 21:59:28 volumio volumio[1172]: info: ControllerMpd::getState Dec 16 21:59:28 volumio volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Dec 16 21:59:28 volumio volumio[1172]: info: sendMpdCommand status took 21 milliseconds Dec 16 21:59:28 volumio volumio[1172]: verbose: ControllerMpd::parseState Dec 16 21:59:28 volumio volumio[1172]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 21:59:28 volumio volumio[1172]: info: sendMpdCommand status took 21 milliseconds Dec 16 21:59:28 volumio volumio[1172]: info: sendMpdCommand playlistinfo took 11 milliseconds Dec 16 21:59:28 volumio volumio[1172]: verbose: ControllerMpd::parseState Dec 16 21:59:28 volumio volumio[1172]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 16 21:59:28 volumio volumio[1172]: verbose: ControllerMpd::parseTrackInfo Dec 16 21:59:28 volumio volumio[1172]: info: ControllerMpd::pushState Dec 16 21:59:28 volumio volumio[1172]: info: CoreCommandRouter::servicePushState Dec 16 21:59:28 volumio volumio[1172]: info: CorePlayQueue::getTrack 0 Dec 16 21:59:28 volumio volumio[1172]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":87468,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"954 Kbps","isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"} Dec 16 21:59:28 volumio volumio[1172]: verbose: CURRENT POSITION 0 Dec 16 21:59:28 volumio volumio[1172]: info: CoreStateMachine::syncState stateService pause Dec 16 21:59:28 volumio volumio[1172]: info: CoreStateMachine::syncState currentStatus pause Dec 16 21:59:28 volumio volumio[1172]: info: CoreStateMachine::pushState Dec 16 21:59:28 volumio volumio[1172]: info: CorePlayQueue::getTrack 0 Dec 16 21:59:28 volumio volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 21:59:28 volumio volumio[1172]: info: CoreCommandRouter::volumioPushState Dec 16 21:59:28 volumio jellyfin[1624]: [21:59:28] [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"] Dec 16 21:59:28 volumio volumio[1172]: info: CoreStateMachine::stPlaybackTimer Dec 16 21:59:28 volumio volumio[1172]: info: ------------------------------ 178ms Dec 16 21:59:28 volumio volumio[1172]: info: sendMpdCommand playlistinfo took 128 milliseconds Dec 16 21:59:28 volumio volumio[1172]: verbose: ControllerMpd::parseTrackInfo Dec 16 21:59:28 volumio volumio[1172]: info: ControllerMpd::pushState Dec 16 21:59:28 volumio volumio[1172]: info: CoreCommandRouter::servicePushState Dec 16 21:59:28 volumio volumio[1172]: info: CorePlayQueue::getTrack 0 Dec 16 21:59:28 volumio volumio[1172]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":87468,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"954 Kbps","isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"} Dec 16 21:59:28 volumio volumio[1172]: verbose: CURRENT POSITION 0 Dec 16 21:59:28 volumio volumio[1172]: info: CoreStateMachine::syncState stateService pause Dec 16 21:59:28 volumio volumio[1172]: info: CoreStateMachine::syncState currentStatus pause Dec 16 21:59:28 volumio volumio[1172]: info: CoreStateMachine::pushState Dec 16 21:59:28 volumio volumio[1172]: info: CorePlayQueue::getTrack 0 Dec 16 21:59:28 volumio volumio[1172]: info: CoreCommandRouter::volumioPushState Dec 16 21:59:28 volumio volumio[1172]: info: CoreStateMachine::stPlaybackTimer Dec 16 21:59:28 volumio volumio[1172]: info: ------------------------------ 286ms Dec 16 21:59:28 volumio volumio[1172]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 21:59:28 volumio volumio[1172]: Error: ENOTDIR: not a directory, scandir '/tmp/peppyrunning' Dec 16 21:59:28 volumio volumio[1172]: at Object.readdirSync (fs.js:1048:3) Dec 16 21:59:28 volumio volumio[1172]: at rmkidsSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:349:11) Dec 16 21:59:28 volumio volumio[1172]: at rmdirSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:342:7) Dec 16 21:59:28 volumio volumio[1172]: at Function.rimrafSync [as sync] (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:319:62) Dec 16 21:59:28 volumio volumio[1172]: at Object.removeSync (/data/plugins/user_interface/peppy_screensaver/node_modules/fs-extra/lib/remove/index.js:4:17) Dec 16 21:59:28 volumio volumio[1172]: at Socket. (/data/plugins/user_interface/peppy_screensaver/index.js:225:48) Dec 16 21:59:28 volumio volumio[1172]: at Socket.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Dec 16 21:59:28 volumio volumio[1172]: at Socket.onevent (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:278:10) Dec 16 21:59:28 volumio volumio[1172]: at Socket.onpacket (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:236:12) Dec 16 21:59:28 volumio volumio[1172]: at Manager. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Dec 16 21:59:28 volumio volumio[1172]: at Manager.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Dec 16 21:59:28 volumio volumio[1172]: at Manager.ondecoded (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:349:8) Dec 16 21:59:28 volumio volumio[1172]: at Decoder. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Dec 16 21:59:28 volumio volumio[1172]: at Decoder.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Dec 16 21:59:28 volumio volumio[1172]: at Decoder.add (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-parser/index.js:251:12) Dec 16 21:59:28 volumio volumio[1172]: at Manager.ondata (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:339:16) { Dec 16 21:59:28 volumio volumio[1172]: errno: -20, Dec 16 21:59:28 volumio volumio[1172]: syscall: 'scandir', Dec 16 21:59:28 volumio volumio[1172]: code: 'ENOTDIR', Dec 16 21:59:28 volumio volumio[1172]: path: '/tmp/peppyrunning' Dec 16 21:59:28 volumio volumio[1172]: } Dec 16 21:59:28 volumio volumio[1172]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 21:59:29 volumio jellyfin[1624]: [21:59:29] [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"] Dec 16 21:59:30 volumio sudo[2338]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-16 21:58 Dec 16 21:59:30 volumio sudo[2338]: 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"