-- Logs begin at Wed 2025-06-18 22:30:27 CST, end at Wed 2025-06-18 22:32:39 CST. -- Jun 18 22:31:00 volumio jellyfin[1528]: [22:31:00] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: TMDb 10.9.6.0 Jun 18 22:31:00 volumio jellyfin[1528]: [22:31:00] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: Studio Images 10.9.6.0 Jun 18 22:31:00 volumio jellyfin[1528]: [22:31:00] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: OMDb 10.9.6.0 Jun 18 22:31:00 volumio jellyfin[1528]: [22:31:00] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: MusicBrainz 10.9.6.0 Jun 18 22:31:00 volumio jellyfin[1528]: [22:31:00] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: AudioDB 10.9.6.0 Jun 18 22:31:00 volumio jellyfin[1528]: [22:31:00] [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"] Jun 18 22:31:00 volumio jellyfin[1528]: [22:31:00] [INF] [1] Main: Kestrel is listening on 0.0.0.0 Jun 18 22:31:00 volumio volumio[988]: info: Starting Streaming Service Transparent Proxy Jun 18 22:31:00 volumio volumio[988]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jun 18 22:31:00 volumio volumio[988]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jun 18 22:31:00 volumio volumio[988]: info: Streaming services startup Jun 18 22:31:00 volumio volumio[988]: info: Starting Streaming Daemon Jun 18 22:31:01 volumio volumio[988]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jun 18 22:31:01 volumio sudo[1966]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 18 22:31:01 volumio sudo[1966]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 22:31:01 volumio sudo[1966]: pam_unix(sudo:session): session closed for user root Jun 18 22:31:01 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 18 22:31:01 volumio volumio[988]: info: Received Get System Info Jun 18 22:31:01 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 18 22:31:01 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 18 22:31:01 volumio volumio[988]: info: Discovery: Getting this device information Jun 18 22:31:01 volumio volumio[988]: info: CoreCommandRouter::volumioGetState Jun 18 22:31:01 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Jun 18 22:31:01 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 18 22:31:01 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jun 18 22:31:01 volumio volumio[988]: error: Cannot start Volumio Streaming Daemon Jun 18 22:31:01 volumio volumio[988]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 18 22:31:01 volumio volumio[988]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 18 22:31:01 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Jun 18 22:31:01 volumio volumio[988]: info: MyVolumio login type: Token Jun 18 22:31:02 volumio volumio[988]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jun 18 22:31:02 volumio volumio[988]: STREAMING PROXY: Starting server on port 3245 Jun 18 22:31:02 volumio volumio[988]: Node JS runtime: 14 Jun 18 22:31:02 volumio volumio[988]: info: MyVolumio token set successfully Jun 18 22:31:02 volumio volumio[988]: info: MYVOLUMIO: Adding device Jun 18 22:31:02 volumio volumio[988]: info: MYVOLUMIO: Evaluating Server Jun 18 22:31:03 volumio volumio[988]: info: MyVolumio status changed Jun 18 22:31:03 volumio volumio[988]: info: Streaming services startup Jun 18 22:31:03 volumio volumio[988]: info: Starting Streaming Daemon Jun 18 22:31:03 volumio volumio[988]: info: Removing browser output: myVolumio user plan is not superstar Jun 18 22:31:03 volumio volumio[988]: info: Removing audio output: Jun 18 22:31:03 volumio volumio[988]: info: Stoppping Tunnel 1 Jun 18 22:31:03 volumio sudo[1995]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 18 22:31:03 volumio sudo[1995]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 22:31:03 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jun 18 22:31:03 volumio sudo[1998]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jun 18 22:31:03 volumio sudo[1995]: pam_unix(sudo:session): session closed for user root Jun 18 22:31:03 volumio sudo[1998]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 22:31:03 volumio volumio[988]: error: Cannot start Volumio Streaming Daemon Jun 18 22:31:03 volumio volumio[988]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 18 22:31:03 volumio volumio[988]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 18 22:31:03 volumio sudo[1998]: pam_unix(sudo:session): session closed for user root Jun 18 22:31:03 volumio volumio[988]: info: Remote SSH Stopped Jun 18 22:31:03 volumio jellyfin[1528]: [22:31:03] [WRN] [1] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The WebRootPath was not found: /wwwroot. Static files may be unavailable. Jun 18 22:31:04 volumio volumio[988]: info: AutoStart - Plugin is starting Jun 18 22:31:04 volumio volumio[988]: info: CoreCommandRouter::volumioGetQueue Jun 18 22:31:04 volumio volumio[988]: info: CoreStateMachine::getQueue Jun 18 22:31:04 volumio volumio[988]: info: CorePlayQueue::getQueue Jun 18 22:31:04 volumio volumio[988]: info: AutoStart - start playing Jun 18 22:31:04 volumio volumio[988]: info: AutoStart - start playing with no specific position Jun 18 22:31:04 volumio volumio[988]: info: CoreCommandRouter::volumioPlay Jun 18 22:31:04 volumio volumio[988]: info: CoreStateMachine::play index 0 Jun 18 22:31:04 volumio volumio[988]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 18 22:31:04 volumio volumio[988]: info: CoreStateMachine::stop Jun 18 22:31:04 volumio volumio[988]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 18 22:31:04 volumio volumio[988]: info: CoreStateMachine::play index undefined Jun 18 22:31:04 volumio volumio[988]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 18 22:31:04 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Jun 18 22:31:04 volumio jellyfin[1528]: [22:31:04] [INF] [1] Emby.Server.Implementations.ApplicationHost: Running startup tasks Jun 18 22:31:04 volumio volumio[988]: info: CoreStateMachine::startPlaybackTimer Jun 18 22:31:04 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Jun 18 22:31:04 volumio volumio[988]: verbose: ControllerMpd::clearAddPlayTracks USB/赵鹏 - 乌兰巴托的夜晚.flac Jun 18 22:31:04 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand stop Jun 18 22:31:04 volumio volumio[988]: info: sendMpdCommand stop took 3 milliseconds Jun 18 22:31:04 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand clear Jun 18 22:31:04 volumio volumio[988]: info: Jun 18 22:31:04 volumio volumio[988]: ---------------------------- MPD announces system playlist update Jun 18 22:31:04 volumio volumio[988]: info: Ignoring MPD Status Update Jun 18 22:31:04 volumio volumio[988]: info: sendMpdCommand clear took 4 milliseconds Jun 18 22:31:04 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand add "USB/赵鹏 - 乌兰巴托的夜晚.flac" Jun 18 22:31:04 volumio volumio[988]: info: Jun 18 22:31:04 volumio volumio[988]: ---------------------------- MPD announces system playlist update Jun 18 22:31:04 volumio volumio[988]: info: Ignoring MPD Status Update Jun 18 22:31:04 volumio volumio[988]: info: Jun 18 22:31:04 volumio volumio[988]: ---------------------------- MPD announces system playlist update Jun 18 22:31:04 volumio volumio[988]: info: Ignoring MPD Status Update Jun 18 22:31:04 volumio volumio[988]: info: Jun 18 22:31:04 volumio volumio[988]: ---------------------------- MPD announces system playlist update Jun 18 22:31:04 volumio volumio[988]: info: Ignoring MPD Status Update Jun 18 22:31:04 volumio volumio[988]: error: updateQueue error: null Jun 18 22:31:04 volumio volumio[988]: info: Jun 18 22:31:04 volumio volumio[988]: ---------------------------- MPD announces system playlist update Jun 18 22:31:04 volumio volumio[988]: info: Ignoring MPD Status Update Jun 18 22:31:04 volumio volumio[988]: info: ------------------------------ 12ms Jun 18 22:31:04 volumio volumio[988]: info: sendMpdCommand add "USB/赵鹏 - 乌兰巴托的夜晚.flac" took 9 milliseconds Jun 18 22:31:04 volumio volumio[988]: info: ------------------------------ 9ms Jun 18 22:31:04 volumio volumio[988]: info: ------------------------------ 8ms Jun 18 22:31:04 volumio volumio[988]: info: ------------------------------ 6ms Jun 18 22:31:04 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand play Jun 18 22:31:04 volumio volumio[988]: info: Jun 18 22:31:04 volumio volumio[988]: ---------------------------- MPD announces system playlist update Jun 18 22:31:04 volumio volumio[988]: info: Ignoring MPD Status Update Jun 18 22:31:04 volumio volumio[988]: info: Jun 18 22:31:04 volumio volumio[988]: ---------------------------- MPD announces system playlist update Jun 18 22:31:04 volumio volumio[988]: info: Ignoring MPD Status Update Jun 18 22:31:04 volumio volumio[988]: info: Jun 18 22:31:04 volumio volumio[988]: ---------------------------- MPD announces system playlist update Jun 18 22:31:04 volumio volumio[988]: info: Ignoring MPD Status Update Jun 18 22:31:04 volumio volumio[988]: info: ------------------------------ 11ms Jun 18 22:31:04 volumio volumio[988]: info: sendMpdCommand play took 7 milliseconds Jun 18 22:31:04 volumio volumio[988]: info: ------------------------------ 6ms Jun 18 22:31:04 volumio volumio[988]: info: ------------------------------ 5ms Jun 18 22:31:04 volumio volumio[988]: info: ------------------------------ 4ms Jun 18 22:31:04 volumio volumio[988]: info: Jun 18 22:31:04 volumio volumio[988]: ---------------------------- MPD announces state update: player Jun 18 22:31:04 volumio volumio[988]: info: ControllerMpd::getState Jun 18 22:31:04 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Jun 18 22:31:04 volumio volumio[988]: info: Jun 18 22:31:04 volumio volumio[988]: ---------------------------- MPD announces state update: player Jun 18 22:31:04 volumio volumio[988]: info: ControllerMpd::getState Jun 18 22:31:04 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Jun 18 22:31:04 volumio volumio[988]: info: Jun 18 22:31:04 volumio volumio[988]: ---------------------------- MPD announces state update: player Jun 18 22:31:04 volumio volumio[988]: info: ControllerMpd::getState Jun 18 22:31:04 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Jun 18 22:31:04 volumio volumio[988]: info: Jun 18 22:31:04 volumio volumio[988]: ---------------------------- MPD announces state update: player Jun 18 22:31:04 volumio volumio[988]: info: ControllerMpd::getState Jun 18 22:31:04 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Jun 18 22:31:04 volumio volumio[988]: info: sendMpdCommand status took 97 milliseconds Jun 18 22:31:04 volumio volumio[988]: verbose: ControllerMpd::parseState Jun 18 22:31:04 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 18 22:31:04 volumio volumio[988]: info: Jun 18 22:31:04 volumio volumio[988]: ---------------------------- MPD announces state update: player Jun 18 22:31:04 volumio volumio[988]: info: ControllerMpd::getState Jun 18 22:31:04 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Jun 18 22:31:04 volumio volumio[988]: info: sendMpdCommand status took 99 milliseconds Jun 18 22:31:04 volumio volumio[988]: info: sendMpdCommand status took 98 milliseconds Jun 18 22:31:04 volumio volumio[988]: info: sendMpdCommand status took 96 milliseconds Jun 18 22:31:04 volumio volumio[988]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 18 22:31:04 volumio volumio[988]: verbose: ControllerMpd::parseState Jun 18 22:31:04 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 18 22:31:04 volumio volumio[988]: verbose: ControllerMpd::parseState Jun 18 22:31:04 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 18 22:31:04 volumio volumio[988]: verbose: ControllerMpd::parseState Jun 18 22:31:04 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 18 22:31:04 volumio volumio[988]: verbose: ControllerMpd::parseTrackInfo Jun 18 22:31:04 volumio volumio[988]: info: ControllerMpd::pushState Jun 18 22:31:04 volumio volumio[988]: info: CoreCommandRouter::servicePushState Jun 18 22:31:04 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Jun 18 22:31:04 volumio volumio[988]: 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":"379 Kbps","isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"} Jun 18 22:31:04 volumio volumio[988]: verbose: CURRENT POSITION 0 Jun 18 22:31:04 volumio volumio[988]: info: CoreStateMachine::syncState stateService play Jun 18 22:31:04 volumio volumio[988]: info: CoreStateMachine::syncState currentStatus stop Jun 18 22:31:04 volumio volumio[988]: info: ------------------------------ 112ms Jun 18 22:31:04 volumio volumio[988]: info: Jun 18 22:31:04 volumio volumio[988]: ---------------------------- MPD announces state update: player Jun 18 22:31:04 volumio volumio[988]: info: ControllerMpd::getState Jun 18 22:31:04 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Jun 18 22:31:04 volumio volumio[988]: info: Jun 18 22:31:04 volumio volumio[988]: ---------------------------- MPD announces state update: player Jun 18 22:31:04 volumio volumio[988]: info: ControllerMpd::getState Jun 18 22:31:04 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Jun 18 22:31:04 volumio jellyfin[1528]: [22:31:04] [INF] [1] Emby.Server.Implementations.ScheduledTasks.TaskManager: Daily trigger for Generate Trickplay Images set to fire at 2025-06-19 03:00:00.000 +08:00, which is 04:28:55.6579359 from now. Jun 18 22:31:04 volumio volumio[988]: info: Jun 18 22:31:04 volumio volumio[988]: ---------------------------- MPD announces state update: player Jun 18 22:31:04 volumio volumio[988]: info: ControllerMpd::getState Jun 18 22:31:04 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Jun 18 22:31:04 volumio volumio[988]: info: sendMpdCommand status took 16 milliseconds Jun 18 22:31:04 volumio volumio[988]: info: sendMpdCommand playlistinfo took 14 milliseconds Jun 18 22:31:04 volumio volumio[988]: info: sendMpdCommand playlistinfo took 14 milliseconds Jun 18 22:31:04 volumio volumio[988]: info: sendMpdCommand status took 6 milliseconds Jun 18 22:31:04 volumio volumio[988]: verbose: ControllerMpd::parseState Jun 18 22:31:04 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 18 22:31:04 volumio volumio[988]: verbose: ControllerMpd::parseTrackInfo Jun 18 22:31:04 volumio volumio[988]: verbose: ControllerMpd::parseState Jun 18 22:31:04 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 18 22:31:04 volumio volumio[988]: info: ControllerMpd::pushState Jun 18 22:31:04 volumio volumio[988]: info: CoreCommandRouter::servicePushState Jun 18 22:31:04 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Jun 18 22:31:04 volumio volumio[988]: verbose: STATE SERVICE {"status":"play","position":0,"seek":250,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"655 Kbps","isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"} Jun 18 22:31:04 volumio volumio[988]: verbose: CURRENT POSITION 0 Jun 18 22:31:04 volumio volumio[988]: info: CoreStateMachine::syncState stateService play Jun 18 22:31:04 volumio volumio[988]: info: CoreStateMachine::syncState currentStatus play Jun 18 22:31:04 volumio volumio[988]: info: Received an update from plugin. extracting info from payload Jun 18 22:31:04 volumio volumio[988]: info: CoreStateMachine::pushState Jun 18 22:31:04 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Jun 18 22:31:04 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 22:31:04 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Jun 18 22:31:04 volumio jellyfin[1528]: [22:31:04] [INF] [1] Emby.Server.Implementations.ScheduledTasks.TaskManager: Daily trigger for Extract Chapter Images set to fire at 2025-06-19 02:00:00.000 +08:00, which is 03:28:55.6358450 from now. Jun 18 22:31:04 volumio volumio[988]: info: CoreStateMachine::pushState Jun 18 22:31:04 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Jun 18 22:31:04 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Jun 18 22:31:04 volumio volumio[988]: info: ControllerMpd::pushState Jun 18 22:31:04 volumio volumio[988]: info: CoreCommandRouter::servicePushState Jun 18 22:31:04 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Jun 18 22:31:04 volumio volumio[988]: verbose: STATE SERVICE {"status":"play","position":0,"seek":341,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"846 Kbps","isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"} Jun 18 22:31:04 volumio volumio[988]: verbose: CURRENT POSITION 0 Jun 18 22:31:04 volumio volumio[988]: info: CoreStateMachine::syncState stateService play Jun 18 22:31:04 volumio volumio[988]: info: CoreStateMachine::syncState currentStatus play Jun 18 22:31:04 volumio volumio[988]: info: Received an update from plugin. extracting info from payload Jun 18 22:31:04 volumio volumio[988]: info: CoreStateMachine::pushState Jun 18 22:31:04 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Jun 18 22:31:04 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 22:31:04 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Jun 18 22:31:04 volumio volumio[988]: info: CoreStateMachine::pushState Jun 18 22:31:04 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Jun 18 22:31:04 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Jun 18 22:31:04 volumio volumio[988]: info: ControllerMpd::pushState Jun 18 22:31:04 volumio volumio[988]: info: CoreCommandRouter::servicePushState Jun 18 22:31:04 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Jun 18 22:31:04 volumio volumio[988]: verbose: STATE SERVICE {"status":"play","position":0,"seek":341,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"691 Kbps","isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"} Jun 18 22:31:04 volumio volumio[988]: verbose: CURRENT POSITION 0 Jun 18 22:31:04 volumio volumio[988]: info: CoreStateMachine::syncState stateService play Jun 18 22:31:04 volumio volumio[988]: info: CoreStateMachine::syncState currentStatus play Jun 18 22:31:04 volumio volumio[988]: info: Received an update from plugin. extracting info from payload Jun 18 22:31:04 volumio volumio[988]: info: CoreStateMachine::pushState Jun 18 22:31:04 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Jun 18 22:31:04 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Jun 18 22:31:04 volumio volumio[988]: info: CoreStateMachine::pushState Jun 18 22:31:04 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Jun 18 22:31:04 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Jun 18 22:31:04 volumio volumio[988]: info: ------------------------------ 166ms Jun 18 22:31:04 volumio volumio[988]: info: ------------------------------ 165ms Jun 18 22:31:04 volumio volumio[988]: info: ------------------------------ 164ms Jun 18 22:31:04 volumio volumio[988]: info: sendMpdCommand status took 57 milliseconds Jun 18 22:31:04 volumio volumio[988]: info: sendMpdCommand status took 57 milliseconds Jun 18 22:31:04 volumio volumio[988]: info: sendMpdCommand playlistinfo took 53 milliseconds Jun 18 22:31:04 volumio volumio[988]: info: sendMpdCommand playlistinfo took 52 milliseconds Jun 18 22:31:04 volumio volumio[988]: verbose: ControllerMpd::parseState Jun 18 22:31:04 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 18 22:31:04 volumio volumio[988]: verbose: ControllerMpd::parseState Jun 18 22:31:04 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 18 22:31:04 volumio volumio[988]: verbose: ControllerMpd::parseTrackInfo Jun 18 22:31:04 volumio volumio[988]: info: ControllerMpd::pushState Jun 18 22:31:04 volumio volumio[988]: info: CoreCommandRouter::servicePushState Jun 18 22:31:04 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Jun 18 22:31:04 volumio volumio[988]: 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"} Jun 18 22:31:04 volumio volumio[988]: verbose: CURRENT POSITION 0 Jun 18 22:31:04 volumio volumio[988]: info: CoreStateMachine::syncState stateService play Jun 18 22:31:04 volumio volumio[988]: info: CoreStateMachine::syncState currentStatus play Jun 18 22:31:04 volumio volumio[988]: info: Received an update from plugin. extracting info from payload Jun 18 22:31:04 volumio volumio[988]: info: CoreStateMachine::pushState Jun 18 22:31:04 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Jun 18 22:31:04 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 22:31:04 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Jun 18 22:31:04 volumio volumio[988]: info: CoreStateMachine::pushState Jun 18 22:31:04 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Jun 18 22:31:04 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Jun 18 22:31:04 volumio volumio[988]: info: ControllerMpd::pushState Jun 18 22:31:04 volumio volumio[988]: info: CoreCommandRouter::servicePushState Jun 18 22:31:04 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Jun 18 22:31:04 volumio volumio[988]: 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"} Jun 18 22:31:04 volumio volumio[988]: verbose: CURRENT POSITION 0 Jun 18 22:31:04 volumio volumio[988]: info: CoreStateMachine::syncState stateService play Jun 18 22:31:04 volumio volumio[988]: info: CoreStateMachine::syncState currentStatus play Jun 18 22:31:04 volumio volumio[988]: info: Received an update from plugin. extracting info from payload Jun 18 22:31:04 volumio volumio[988]: info: CoreStateMachine::pushState Jun 18 22:31:04 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Jun 18 22:31:04 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Jun 18 22:31:04 volumio volumio[988]: info: CoreStateMachine::pushState Jun 18 22:31:04 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Jun 18 22:31:04 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Jun 18 22:31:04 volumio volumio[988]: info: ------------------------------ 120ms Jun 18 22:31:04 volumio volumio[988]: info: ------------------------------ 110ms Jun 18 22:31:04 volumio volumio[988]: info: sendMpdCommand playlistinfo took 89 milliseconds Jun 18 22:31:04 volumio volumio[988]: info: sendMpdCommand playlistinfo took 90 milliseconds Jun 18 22:31:04 volumio volumio[988]: verbose: ControllerMpd::parseTrackInfo Jun 18 22:31:04 volumio volumio[988]: info: ControllerMpd::pushState Jun 18 22:31:04 volumio volumio[988]: info: CoreCommandRouter::servicePushState Jun 18 22:31:04 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Jun 18 22:31:04 volumio volumio[988]: 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"} Jun 18 22:31:04 volumio volumio[988]: verbose: CURRENT POSITION 0 Jun 18 22:31:04 volumio volumio[988]: info: CoreStateMachine::syncState stateService play Jun 18 22:31:04 volumio volumio[988]: info: CoreStateMachine::syncState currentStatus play Jun 18 22:31:04 volumio volumio[988]: info: Received an update from plugin. extracting info from payload Jun 18 22:31:04 volumio volumio[988]: info: CoreStateMachine::pushState Jun 18 22:31:04 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Jun 18 22:31:04 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Jun 18 22:31:04 volumio volumio[988]: info: CoreStateMachine::pushState Jun 18 22:31:04 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Jun 18 22:31:04 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Jun 18 22:31:04 volumio volumio[988]: info: ControllerMpd::pushState Jun 18 22:31:04 volumio volumio[988]: info: CoreCommandRouter::servicePushState Jun 18 22:31:04 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Jun 18 22:31:04 volumio volumio[988]: 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"} Jun 18 22:31:04 volumio volumio[988]: verbose: CURRENT POSITION 0 Jun 18 22:31:04 volumio volumio[988]: info: CoreStateMachine::syncState stateService play Jun 18 22:31:04 volumio volumio[988]: info: CoreStateMachine::syncState currentStatus play Jun 18 22:31:04 volumio volumio[988]: info: Received an update from plugin. extracting info from payload Jun 18 22:31:04 volumio volumio[988]: info: CoreStateMachine::pushState Jun 18 22:31:04 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Jun 18 22:31:04 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Jun 18 22:31:04 volumio volumio[988]: info: CoreStateMachine::pushState Jun 18 22:31:04 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Jun 18 22:31:04 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Jun 18 22:31:04 volumio volumio[988]: info: ------------------------------ 190ms Jun 18 22:31:04 volumio volumio[988]: info: Setting Geolocation for MyVolumio to as2 Jun 18 22:31:04 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 22:31:04 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 18 22:31:04 volumio volumio[988]: info: Received Get System Info Jun 18 22:31:04 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 18 22:31:04 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 18 22:31:04 volumio volumio[988]: info: Discovery: Getting this device information Jun 18 22:31:04 volumio volumio[988]: info: CoreCommandRouter::volumioGetState Jun 18 22:31:04 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Jun 18 22:31:04 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 18 22:31:04 volumio jellyfin[1528]: [22:31:04] [INF] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: Found ffmpeg version 6.0.1 Jun 18 22:31:05 volumio volumio[988]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jun 18 22:31:05 volumio jellyfin[1528]: [22:31:05] [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"] Jun 18 22:31:05 volumio jellyfin[1528]: [22:31:05] [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"] Jun 18 22:31:05 volumio volumio[988]: info: Updating MyVolumio device info Jun 18 22:31:05 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 22:31:05 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 22:31:05 volumio jellyfin[1528]: [22:31:05] [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"] Jun 18 22:31:05 volumio volumio[988]: info: CoreCommandRouter::volumioGetState Jun 18 22:31:05 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Jun 18 22:31:05 volumio sudo[2025]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/usr/bin/python3 ./screensaver/volumio_peppymeter.py Jun 18 22:31:05 volumio sudo[2025]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 22:31:06 volumio volumio[988]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jun 18 22:31:06 volumio jellyfin[1528]: [22:31:06] [INF] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: Available hwaccel types: ["cuda", "vaapi", "qsv", "drm", "opencl", "vulkan"] Jun 18 22:31:07 volumio jellyfin[1528]: [22:31:07] [INF] [11] Emby.Server.Implementations.ScheduledTasks.TaskManager: Clean up collections and playlists Completed after 0 minute(s) and 0 seconds Jun 18 22:31:08 volumio jellyfin[1528]: [22:31:08] [INF] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: FFmpeg: /usr/lib/jellyfin-ffmpeg/ffmpeg Jun 18 22:31:08 volumio jellyfin[1528]: [22:31:08] [INF] [1] Emby.Server.Implementations.ApplicationHost: ServerId: b1cfc0d663974d88a7e5ad9e731e53ae Jun 18 22:31:08 volumio jellyfin[1528]: [22:31:08] [INF] [1] Emby.Server.Implementations.ApplicationHost: Core startup complete Jun 18 22:31:08 volumio jellyfin[1528]: [22:31:08] [INF] [1] Main: Startup complete 0:00:22.0656601 Jun 18 22:31:08 volumio volumio[988]: 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 Jun 18 22:31:08 volumio volumio[988]: info: CoreCommandRouter::volumioGetState Jun 18 22:31:08 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Jun 18 22:31:09 volumio volumio[988]: info: MYVOLUMIO: Adding device Jun 18 22:31:09 volumio volumio[988]: info: MYVOLUMIO: Evaluating Server Jun 18 22:31:09 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Jun 18 22:31:10 volumio jellyfin[1528]: [22:31:10] [INF] [8] Emby.Server.Implementations.ScheduledTasks.TaskManager: Update Plugins Completed after 0 minute(s) and 2 seconds Jun 18 22:31:10 volumio volumio[988]: 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 Jun 18 22:31:10 volumio volumio[988]: info: CoreCommandRouter::volumioGetState Jun 18 22:31:10 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Jun 18 22:31:10 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 18 22:31:10 volumio volumio[988]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jun 18 22:31:10 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jun 18 22:31:10 volumio volumio[988]: info: Received Get System Version Jun 18 22:31:10 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 18 22:31:10 volumio volumio[988]: info: Received Get System Info Jun 18 22:31:10 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 18 22:31:10 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 18 22:31:10 volumio volumio[988]: info: Discovery: Getting this device information Jun 18 22:31:10 volumio volumio[988]: info: CoreCommandRouter::volumioGetState Jun 18 22:31:10 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Jun 18 22:31:10 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 18 22:31:10 volumio volumio[988]: info: Setting Geolocation for MyVolumio to as2 Jun 18 22:31:10 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 22:31:11 volumio volumio[988]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jun 18 22:31:11 volumio volumio[988]: info: Updating MyVolumio device info Jun 18 22:31:11 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 22:31:12 volumio volumio[988]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jun 18 22:31:14 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 18 22:31:14 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 22:31:14 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 18 22:31:14 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 18 22:31:14 volumio volumio[988]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jun 18 22:31:14 volumio volumio[988]: info: CoreCommandRouter::volumioGetState Jun 18 22:31:14 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Jun 18 22:31:14 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jun 18 22:31:14 volumio volumio-remote-updater[777]: No test mode Jun 18 22:31:14 volumio volumio-remote-updater[777]: No alpha test mode Jun 18 22:31:15 volumio volumio[988]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

FIXES

","title":"Update v3.816","updateavailable":true} Jun 18 22:31:15 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jun 18 22:31:19 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 22:31:19 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 22:31:19 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jun 18 22:31:19 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 18 22:31:19 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jun 18 22:31:19 volumio volumio[988]: info: CoreCommandRouter::volumioGetBrowseSources Jun 18 22:31:19 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 18 22:31:21 volumio volumio[988]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jun 18 22:31:21 volumio volumio[988]: info: Completed starting MyVolumio Plugin Jun 18 22:31:21 volumio volumio[988]: info: BOOT COMPLETED Jun 18 22:31:21 volumio volumio[988]: [Metrics] CommandRouter: 47s 101.30ms Jun 18 22:31:21 volumio volumio[988]: info: CoreCommandRouter::volumiosetStartupVolume Jun 18 22:31:21 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 22:31:21 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 22:31:21 volumio volumio[988]: info: CoreCommandRouter::Close All Modals sent Jun 18 22:31:21 volumio volumio[988]: info: CoreCommandRouter::Close All Modals sent Jun 18 22:31:21 volumio volumio[988]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Jun 18 22:31:21 volumio volumio[988]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card Jun 18 22:31:21 volumio volumio[988]: aplay: main:828: audio open error: No such device Jun 18 22:31:22 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jun 18 22:31:22 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 18 22:31:22 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Jun 18 22:31:22 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jun 18 22:31:47 volumio ntpd[1447]: Soliciting pool server 222.166.0.136 Jun 18 22:31:50 volumio ntpd[1447]: Soliciting pool server 209.58.185.100 Jun 18 22:31:51 volumio ntpd[1447]: Soliciting pool server 222.166.0.136 Jun 18 22:31:52 volumio ntpd[1447]: Soliciting pool server 47.243.51.23 Jun 18 22:31:52 volumio ntpd[1447]: Soliciting pool server 118.143.17.82 Jun 18 22:31:53 volumio ntpd[1447]: Soliciting pool server 118.143.17.83 Jun 18 22:32:00 volumio ntpd[1447]: receive: Unexpected origin timestamp 0xebfd4c5f.d6f5af8e does not match aorg 0000000000.00000000 from server@118.143.17.82 xmt 0xebfd4c60.9a9d6076 Jun 18 22:32:12 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 18 22:32:12 volumio volumio[988]: info: Received Get System Info Jun 18 22:32:12 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 18 22:32:12 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 18 22:32:12 volumio volumio[988]: info: Discovery: Getting this device information Jun 18 22:32:12 volumio volumio[988]: info: CoreCommandRouter::volumioGetState Jun 18 22:32:12 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Jun 18 22:32:12 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 18 22:32:31 volumio volumio[988]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 18 22:32:32 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 18 22:32:32 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 22:32:32 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 18 22:32:32 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 22:32:32 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 22:32:32 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 22:32:32 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 22:32:32 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 22:32:32 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 22:32:32 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 22:32:32 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 18 22:32:32 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 18 22:32:38 volumio volumio[988]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Jun 18 22:32:38 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Jun 18 22:32:38 volumio volumio[988]: info: Preparing to save Alsa Options, stopping services first Jun 18 22:32:38 volumio volumio[988]: info: CoreCommandRouter::volumioGetState Jun 18 22:32:38 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Jun 18 22:32:38 volumio volumio[988]: info: CoreCommandRouter::volumioPause Jun 18 22:32:38 volumio volumio[988]: info: CoreStateMachine::pause Jun 18 22:32:38 volumio volumio[988]: info: CoreStateMachine::stPlaybackTimer Jun 18 22:32:38 volumio volumio[988]: info: CoreStateMachine::servicePause Jun 18 22:32:38 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Jun 18 22:32:38 volumio volumio[988]: info: CoreCommandRouter::servicePause Jun 18 22:32:38 volumio volumio[988]: info: ControllerMpd::pause Jun 18 22:32:38 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand pause Jun 18 22:32:38 volumio volumio[988]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"Bravo-X USB Audio"}} Jun 18 22:32:38 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 18 22:32:39 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 18 22:32:39 volumio volumio[988]: info: Setting mixer PCM for card Bravo-X USB Audio Jun 18 22:32:39 volumio volumio[988]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 18 22:32:39 volumio volumio[988]: info: Updating Volume Controller Parameters: Device: 5 Name: Bravo-X USB Audio Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jun 18 22:32:39 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jun 18 22:32:39 volumio volumio[988]: info: Disabling external Volume Control Jun 18 22:32:39 volumio volumio[988]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 18 22:32:39 volumio volumio[988]: info: Preparing to generate the ALSA configuration file Jun 18 22:32:39 volumio volumio[988]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Jun 18 22:32:39 volumio volumio[988]: info: Reading ALSA contributions from plugins. Jun 18 22:32:39 volumio volumio[988]: info: Jun 18 22:32:39 volumio volumio[988]: ---------------------------- MPD announces state update: player Jun 18 22:32:39 volumio volumio[988]: info: sendMpdCommand pause took 257 milliseconds Jun 18 22:32:39 volumio volumio[988]: info: ControllerMpd::getState Jun 18 22:32:39 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Jun 18 22:32:39 volumio volumio[988]: info: Jun 18 22:32:39 volumio volumio[988]: ---------------------------- MPD announces state update: player Jun 18 22:32:39 volumio volumio[988]: info: ControllerMpd::getState Jun 18 22:32:39 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Jun 18 22:32:39 volumio volumio[988]: info: Jun 18 22:32:39 volumio volumio[988]: ---------------------------- MPD announces state update: player Jun 18 22:32:39 volumio volumio[988]: info: ControllerMpd::getState Jun 18 22:32:39 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Jun 18 22:32:39 volumio volumio[988]: info: Jun 18 22:32:39 volumio volumio[988]: ---------------------------- MPD announces state update: player Jun 18 22:32:39 volumio volumio[988]: info: ControllerMpd::getState Jun 18 22:32:39 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand status Jun 18 22:32:39 volumio volumio[988]: info: sendMpdCommand status took 27 milliseconds Jun 18 22:32:39 volumio volumio[988]: info: sendMpdCommand status took 26 milliseconds Jun 18 22:32:39 volumio volumio[988]: info: sendMpdCommand status took 25 milliseconds Jun 18 22:32:39 volumio volumio[988]: info: sendMpdCommand status took 24 milliseconds Jun 18 22:32:39 volumio volumio[988]: verbose: ControllerMpd::parseState Jun 18 22:32:39 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 18 22:32:39 volumio volumio[988]: verbose: ControllerMpd::parseState Jun 18 22:32:39 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 18 22:32:39 volumio volumio[988]: verbose: ControllerMpd::parseState Jun 18 22:32:39 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 18 22:32:39 volumio volumio[988]: verbose: ControllerMpd::parseState Jun 18 22:32:39 volumio volumio[988]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 18 22:32:39 volumio volumio[988]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 18 22:32:39 volumio volumio[988]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 18 22:32:39 volumio volumio[988]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 18 22:32:39 volumio volumio[988]: verbose: ControllerMpd::parseTrackInfo Jun 18 22:32:39 volumio volumio[988]: verbose: ControllerMpd::parseTrackInfo Jun 18 22:32:39 volumio volumio[988]: verbose: ControllerMpd::parseTrackInfo Jun 18 22:32:39 volumio volumio[988]: verbose: ControllerMpd::parseTrackInfo Jun 18 22:32:39 volumio volumio[988]: info: ControllerMpd::pushState Jun 18 22:32:39 volumio volumio[988]: info: CoreCommandRouter::servicePushState Jun 18 22:32:39 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Jun 18 22:32:39 volumio volumio[988]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":94708,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"963 Kbps","isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"} Jun 18 22:32:39 volumio volumio[988]: verbose: CURRENT POSITION 0 Jun 18 22:32:39 volumio volumio[988]: info: CoreStateMachine::syncState stateService pause Jun 18 22:32:39 volumio volumio[988]: info: CoreStateMachine::syncState currentStatus pause Jun 18 22:32:39 volumio volumio[988]: info: CoreStateMachine::pushState Jun 18 22:32:39 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Jun 18 22:32:39 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 22:32:39 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Jun 18 22:32:39 volumio volumio[988]: info: CoreStateMachine::stPlaybackTimer Jun 18 22:32:39 volumio volumio[988]: info: ControllerMpd::pushState Jun 18 22:32:39 volumio volumio[988]: info: CoreCommandRouter::servicePushState Jun 18 22:32:39 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Jun 18 22:32:39 volumio volumio[988]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":94708,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"963 Kbps","isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"} Jun 18 22:32:39 volumio volumio[988]: verbose: CURRENT POSITION 0 Jun 18 22:32:39 volumio volumio[988]: info: CoreStateMachine::syncState stateService pause Jun 18 22:32:39 volumio volumio[988]: info: CoreStateMachine::syncState currentStatus pause Jun 18 22:32:39 volumio volumio[988]: info: CoreStateMachine::pushState Jun 18 22:32:39 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Jun 18 22:32:39 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Jun 18 22:32:39 volumio volumio[988]: info: CoreStateMachine::stPlaybackTimer Jun 18 22:32:39 volumio volumio[988]: info: ControllerMpd::pushState Jun 18 22:32:39 volumio volumio[988]: info: CoreCommandRouter::servicePushState Jun 18 22:32:39 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Jun 18 22:32:39 volumio volumio[988]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":94708,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"963 Kbps","isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"} Jun 18 22:32:39 volumio volumio[988]: verbose: CURRENT POSITION 0 Jun 18 22:32:39 volumio volumio[988]: info: CoreStateMachine::syncState stateService pause Jun 18 22:32:39 volumio volumio[988]: info: CoreStateMachine::syncState currentStatus pause Jun 18 22:32:39 volumio volumio[988]: info: CoreStateMachine::pushState Jun 18 22:32:39 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Jun 18 22:32:39 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Jun 18 22:32:39 volumio volumio[988]: info: CoreStateMachine::stPlaybackTimer Jun 18 22:32:39 volumio volumio[988]: info: ControllerMpd::pushState Jun 18 22:32:39 volumio volumio[988]: info: CoreCommandRouter::servicePushState Jun 18 22:32:39 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Jun 18 22:32:39 volumio volumio[988]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":94708,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"963 Kbps","isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"} Jun 18 22:32:39 volumio volumio[988]: verbose: CURRENT POSITION 0 Jun 18 22:32:39 volumio volumio[988]: info: CoreStateMachine::syncState stateService pause Jun 18 22:32:39 volumio volumio[988]: info: CoreStateMachine::syncState currentStatus pause Jun 18 22:32:39 volumio volumio[988]: info: CoreStateMachine::pushState Jun 18 22:32:39 volumio volumio[988]: info: CorePlayQueue::getTrack 0 Jun 18 22:32:39 volumio volumio[988]: info: CoreCommandRouter::volumioPushState Jun 18 22:32:39 volumio volumio[988]: info: CoreStateMachine::stPlaybackTimer Jun 18 22:32:39 volumio volumio[988]: info: ------------------------------ 98ms Jun 18 22:32:39 volumio volumio[988]: info: ------------------------------ 96ms Jun 18 22:32:39 volumio volumio[988]: info: ------------------------------ 95ms Jun 18 22:32:39 volumio volumio[988]: info: ------------------------------ 95ms Jun 18 22:32:39 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 18 22:32:39 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 22:32:39 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 18 22:32:39 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 22:32:39 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 22:32:39 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 22:32:39 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 22:32:39 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 22:32:39 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 22:32:39 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 22:32:39 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 18 22:32:39 volumio volumio[988]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 18 22:32:39 volumio volumio[988]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 18 22:32:39 volumio volumio[988]: Error: ENOTDIR: not a directory, scandir '/tmp/peppyrunning' Jun 18 22:32:39 volumio volumio[988]: at Object.readdirSync (fs.js:1048:3) Jun 18 22:32:39 volumio volumio[988]: at rmkidsSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:349:11) Jun 18 22:32:39 volumio volumio[988]: at rmdirSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:342:7) Jun 18 22:32:39 volumio volumio[988]: at Function.rimrafSync [as sync] (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:319:62) Jun 18 22:32:39 volumio volumio[988]: at Object.removeSync (/data/plugins/user_interface/peppy_screensaver/node_modules/fs-extra/lib/remove/index.js:4:17) Jun 18 22:32:39 volumio volumio[988]: at Socket. (/data/plugins/user_interface/peppy_screensaver/index.js:225:48) Jun 18 22:32:39 volumio volumio[988]: at Socket.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Jun 18 22:32:39 volumio volumio[988]: at Socket.onevent (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:278:10) Jun 18 22:32:39 volumio volumio[988]: at Socket.onpacket (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:236:12) Jun 18 22:32:39 volumio volumio[988]: at Manager. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Jun 18 22:32:39 volumio volumio[988]: at Manager.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Jun 18 22:32:39 volumio volumio[988]: at Manager.ondecoded (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:349:8) Jun 18 22:32:39 volumio volumio[988]: at Decoder. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Jun 18 22:32:39 volumio volumio[988]: at Decoder.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Jun 18 22:32:39 volumio volumio[988]: at Decoder.add (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-parser/index.js:251:12) Jun 18 22:32:39 volumio volumio[988]: at Manager.ondata (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:339:16) { Jun 18 22:32:39 volumio volumio[988]: errno: -20, Jun 18 22:32:39 volumio volumio[988]: syscall: 'scandir', Jun 18 22:32:39 volumio volumio[988]: code: 'ENOTDIR', Jun 18 22:32:39 volumio volumio[988]: path: '/tmp/peppyrunning' Jun 18 22:32:39 volumio volumio[988]: } Jun 18 22:32:39 volumio volumio[988]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 18 22:32:39 volumio sudo[2463]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-18 22:31 Jun 18 22:32:39 volumio sudo[2463]: 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"