-- Logs begin at Sat 2024-10-26 14:09:04 CST, end at Sat 2024-10-26 18:10:10 CST. -- Oct 26 18:09:26 volumio sudo[1787]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 26 18:09:26 volumio sudo[1787]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 18:09:26 volumio sudo[1787]: pam_unix(sudo:session): session closed for user root Oct 26 18:09:26 volumio sudo[1792]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 26 18:09:26 volumio sudo[1792]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 18:09:26 volumio volumio[1014]: info: [jellyfin_server] Executing /usr/bin/sudo /bin/systemctl status jellyfin || true Oct 26 18:09:26 volumio sudo[1792]: pam_unix(sudo:session): session closed for user root Oct 26 18:09:26 volumio volumio[1014]: info: Discovery: A device disappeared from network Oct 26 18:09:26 volumio volumio[1014]: info: Discovery: Device volumio disappeared from network Oct 26 18:09:26 volumio sudo[1797]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status jellyfin Oct 26 18:09:26 volumio sudo[1797]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 18:09:26 volumio sudo[1797]: pam_unix(sudo:session): session closed for user root Oct 26 18:09:26 volumio jellyfin[1637]: [18:09:26] [INF] [1] Main: Jellyfin version: 10.9.6 Oct 26 18:09:26 volumio sudo[1803]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 26 18:09:26 volumio sudo[1803]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 18:09:26 volumio mpd[1757]: Oct 26 18:09 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 26 18:09:26 volumio jellyfin[1637]: [18:09:26] [INF] [1] Main: Environment Variables: ["[JELLYFIN_LOG_DIR, /opt/jellyfin/log]"] Oct 26 18:09:26 volumio jellyfin[1637]: [18:09:26] [INF] [1] Main: Arguments: ["/opt/jellyfin/jellyfin_10.9.6/jellyfin.dll", "-d", "/opt/jellyfin/data", "-C", "/opt/jellyfin/cache", "-c", "/opt/jellyfin/config", "-l", "/opt/jellyfin/log", "--ffmpeg", "/usr/lib/jellyfin-ffmpeg/ffmpeg"] Oct 26 18:09:26 volumio jellyfin[1637]: [18:09:26] [INF] [1] Main: Operating system: Debian GNU/Linux 10 (buster) Oct 26 18:09:26 volumio jellyfin[1637]: [18:09:26] [INF] [1] Main: Architecture: X64 Oct 26 18:09:26 volumio jellyfin[1637]: [18:09:26] [INF] [1] Main: 64-Bit Process: True Oct 26 18:09:26 volumio jellyfin[1637]: [18:09:26] [INF] [1] Main: User Interactive: True Oct 26 18:09:26 volumio jellyfin[1637]: [18:09:26] [INF] [1] Main: Processor count: 4 Oct 26 18:09:26 volumio jellyfin[1637]: [18:09:26] [INF] [1] Main: Program data path: /opt/jellyfin/data Oct 26 18:09:26 volumio jellyfin[1637]: [18:09:26] [INF] [1] Main: Log directory path: /opt/jellyfin/log Oct 26 18:09:26 volumio jellyfin[1637]: [18:09:26] [INF] [1] Main: Config directory path: /opt/jellyfin/config Oct 26 18:09:26 volumio jellyfin[1637]: [18:09:26] [INF] [1] Main: Cache path: /opt/jellyfin/cache Oct 26 18:09:26 volumio jellyfin[1637]: [18:09:26] [INF] [1] Main: Web resources path: /opt/jellyfin/jellyfin_10.9.6/jellyfin-web Oct 26 18:09:26 volumio jellyfin[1637]: [18:09:26] [INF] [1] Main: Application directory: /opt/jellyfin/jellyfin_10.9.6/ Oct 26 18:09:26 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Oct 26 18:09:26 volumio systemd[1]: Started Music Player Daemon. Oct 26 18:09:27 volumio sudo[1803]: pam_unix(sudo:session): session closed for user root Oct 26 18:09:27 volumio sudo[1749]: pam_unix(sudo:session): session closed for user root Oct 26 18:09:27 volumio sudo[1607]: pam_unix(sudo:session): session closed for user root Oct 26 18:09:27 volumio sudo[1597]: pam_unix(sudo:session): session closed for user root Oct 26 18:09:27 volumio volumio[1014]: info: Discovery: adding 6576be8c-9ca3-4f6e-ad09-3e064e726785 Oct 26 18:09:27 volumio volumio[1014]: info: Discovery: Found device Volumio Oct 26 18:09:27 volumio volumio[1014]: info: CoreCommandRouter::volumioGetState Oct 26 18:09:27 volumio volumio[1014]: info: CorePlayQueue::getTrack 0 Oct 26 18:09:27 volumio volumio[1014]: info: Upmpdcli Daemon Started Oct 26 18:09:27 volumio volumio[1014]: error: MPD error: The expression evaluated to a falsy value: Oct 26 18:09:27 volumio volumio[1014]: assert.ok(self.idling) Oct 26 18:09:27 volumio volumio[1014]: error: The expression evaluated to a falsy value: Oct 26 18:09:27 volumio volumio[1014]: assert.ok(self.idling) Oct 26 18:09:27 volumio volumio[1014]: info: MPD running with PID1757 Oct 26 18:09:27 volumio volumio[1014]: ,establishing connection Oct 26 18:09:27 volumio volumio[1014]: error: MPD error: The expression evaluated to a falsy value: Oct 26 18:09:27 volumio volumio[1014]: assert.ok(self.idling) Oct 26 18:09:27 volumio volumio[1014]: error: The expression evaluated to a falsy value: Oct 26 18:09:27 volumio volumio[1014]: assert.ok(self.idling) Oct 26 18:09:27 volumio volumio[1014]: error: MPD error: The expression evaluated to a falsy value: Oct 26 18:09:27 volumio volumio[1014]: assert.ok(self.idling) Oct 26 18:09:27 volumio volumio[1014]: error: The expression evaluated to a falsy value: Oct 26 18:09:27 volumio volumio[1014]: assert.ok(self.idling) Oct 26 18:09:27 volumio volumio[1014]: error: updateQueue error: null Oct 26 18:09:27 volumio RoonBridge[1674]: Running Oct 26 18:09:27 volumio volumio[1014]: info: [jellyfin_server] Executing /usr/bin/sudo /bin/systemctl status jellyfin || true Oct 26 18:09:27 volumio sudo[1850]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status jellyfin Oct 26 18:09:27 volumio sudo[1850]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 18:09:27 volumio sudo[1850]: pam_unix(sudo:session): session closed for user root Oct 26 18:09:27 volumio volumio[1014]: info: Completed starting Core Plugins Oct 26 18:09:27 volumio volumio[1014]: info: ------------------------------------------- Oct 26 18:09:27 volumio volumio[1014]: info: ----- MyVolumio plugins startup ---- Oct 26 18:09:27 volumio volumio[1014]: info: ------------------------------------------- Oct 26 18:09:27 volumio volumio[1014]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 26 18:09:27 volumio volumio[1809]: Generating RSA private key, 4096 bit long modulus (2 primes) Oct 26 18:09:27 volumio RoonBridge[1674]: 00:00:00.035 Warn: get lock file path: /tmp/.rnbhgem0- Oct 26 18:09:28 volumio volumio[1809]: ..........++++ Oct 26 18:09:28 volumio jellyfin[1637]: [18:09:28] [INF] [1] Jellyfin.Server.Migrations.MigrationRunner: Marking following migrations as applied because this is a fresh install: ["CreateNetworkConfiguration", "MigrateMusicBrainzTimeout", "MigrateNetworkConfiguration"] Oct 26 18:09:28 volumio jellyfin[1637]: [18:09:28] [INF] [1] Emby.Server.Implementations.AppBase.BaseConfigurationManager: Setting cache path: /opt/jellyfin/cache Oct 26 18:09:29 volumio volumio[1014]: 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 Oct 26 18:09:29 volumio jellyfin[1637]: [18:09:29] [INF] [1] Emby.Server.Implementations.ApplicationHost: Loading assemblies Oct 26 18:09:30 volumio jellyfin[1637]: [18:09:30] [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"] Oct 26 18:09:30 volumio jellyfin[1637]: [18:09:30] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Defined LAN exclusions: [] Oct 26 18:09:30 volumio jellyfin[1637]: [18:09:30] [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"] Oct 26 18:09:30 volumio jellyfin[1637]: [18:09:30] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Filtered interface addresses: ["127.0.0.1", "192.168.10.111"] Oct 26 18:09:30 volumio jellyfin[1637]: [18:09:30] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Bind Addresses ["0.0.0.0"] Oct 26 18:09:30 volumio jellyfin[1637]: [18:09:30] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Remote IP filter is Allowlist Oct 26 18:09:30 volumio jellyfin[1637]: [18:09:30] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Filtered subnets: [] Oct 26 18:09:31 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 26 18:09:33 volumio volumio[1014]: info: CoreCommandRouter::volumioGetState Oct 26 18:09:33 volumio volumio[1014]: info: CorePlayQueue::getTrack 0 Oct 26 18:09:33 volumio volumio[1014]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Oct 26 18:09:34 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 26 18:09:34 volumio volumio[1014]: info: CoreCommandRouter::volumioGetVisibleSources Oct 26 18:09:34 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 26 18:09:34 volumio volumio[1014]: info: CoreCommandRouter::volumioGetState Oct 26 18:09:34 volumio volumio[1014]: info: CorePlayQueue::getTrack 0 Oct 26 18:09:34 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 26 18:09:34 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 26 18:09:34 volumio volumio[1014]: info: Received Get System Info Oct 26 18:09:34 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 26 18:09:34 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 26 18:09:34 volumio volumio[1014]: info: Discovery: Getting this device information Oct 26 18:09:34 volumio volumio[1014]: info: CoreCommandRouter::volumioGetState Oct 26 18:09:34 volumio volumio[1014]: info: CorePlayQueue::getTrack 0 Oct 26 18:09:34 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 26 18:09:34 volumio volumio[1014]: info: CoreCommandRouter::volumioGetState Oct 26 18:09:34 volumio volumio[1014]: info: CorePlayQueue::getTrack 0 Oct 26 18:09:34 volumio volumio[1014]: info: Listing playlists Oct 26 18:09:34 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 26 18:09:34 volumio volumio[1014]: info: Received Get System Info Oct 26 18:09:34 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 26 18:09:34 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 26 18:09:34 volumio volumio[1014]: info: Discovery: Getting this device information Oct 26 18:09:34 volumio volumio[1014]: info: CoreCommandRouter::volumioGetState Oct 26 18:09:34 volumio volumio[1014]: info: CorePlayQueue::getTrack 0 Oct 26 18:09:34 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 26 18:09:34 volumio volumio[1014]: info: CoreCommandRouter::volumioGetState Oct 26 18:09:34 volumio volumio[1014]: info: CorePlayQueue::getTrack 0 Oct 26 18:09:34 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 26 18:09:34 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 26 18:09:35 volumio volumio[1809]: .........................................................................................................................................................................................................++++ Oct 26 18:09:35 volumio volumio[1809]: e is 65537 (0x010001) Oct 26 18:09:35 volumio volumio[1809]: writing RSA key Oct 26 18:09:36 volumio volumio[1014]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 26 18:09:36 volumio volumio[1014]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 26 18:09:36 volumio volumio[1014]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 26 18:09:36 volumio volumio[1014]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 26 18:09:36 volumio volumio[1014]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 26 18:09:36 volumio volumio[1014]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 26 18:09:36 volumio volumio[1014]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 26 18:09:36 volumio volumio[1014]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 26 18:09:36 volumio volumio[1014]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 26 18:09:36 volumio volumio[1014]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 26 18:09:36 volumio volumio[1014]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 26 18:09:36 volumio volumio[1014]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 26 18:09:36 volumio volumio[1014]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 26 18:09:36 volumio volumio[1014]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 26 18:09:36 volumio volumio[1014]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 26 18:09:36 volumio volumio[1014]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 26 18:09:36 volumio volumio[1014]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 26 18:09:36 volumio volumio[1014]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 26 18:09:36 volumio volumio[1014]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 26 18:09:36 volumio volumio[1014]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 26 18:09:36 volumio volumio[1014]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 26 18:09:36 volumio volumio[1014]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 26 18:09:36 volumio volumio[1014]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 26 18:09:36 volumio volumio[1014]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 26 18:09:36 volumio volumio[1014]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 26 18:09:36 volumio volumio[1014]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 26 18:09:36 volumio volumio[1014]: info: Adding plugin bluetooth to MyMusic Plugins Oct 26 18:09:36 volumio volumio[1014]: info: Adding plugin multiroom to MyMusic Plugins Oct 26 18:09:36 volumio volumio[1014]: info: Adding plugin metavolumio to MyMusic Plugins Oct 26 18:09:36 volumio volumio[1014]: info: Adding plugin cd_controller to MyMusic Plugins Oct 26 18:09:36 volumio volumio[1014]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 26 18:09:36 volumio volumio[1014]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 26 18:09:36 volumio volumio[1014]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 26 18:09:36 volumio systemd[1]: systemd-fsckd.service: Succeeded. Oct 26 18:09:37 volumio volumio[1014]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 26 18:09:37 volumio volumio[1014]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 26 18:09:37 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 18:09:37 volumio volumio[1014]: info: Starting MyVolumio Remote Streaming Endpoints Oct 26 18:09:37 volumio volumio[1014]: info: MyVolumio login type: Token Oct 26 18:09:37 volumio volumio[1014]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 26 18:09:37 volumio volumio[1014]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 26 18:09:38 volumio volumio[1014]: info: Starting Streaming Service Transparent Proxy Oct 26 18:09:38 volumio volumio[1014]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 26 18:09:38 volumio volumio[1014]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 26 18:09:38 volumio volumio[1014]: info: Streaming services startup Oct 26 18:09:38 volumio volumio[1014]: info: Starting Streaming Daemon Oct 26 18:09:38 volumio sudo[2086]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 26 18:09:38 volumio sudo[2086]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 18:09:38 volumio sudo[2086]: pam_unix(sudo:session): session closed for user root Oct 26 18:09:39 volumio volumio[1014]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Oct 26 18:09:39 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 26 18:09:39 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 26 18:09:39 volumio volumio[1014]: info: Received Get System Info Oct 26 18:09:39 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 26 18:09:39 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 26 18:09:39 volumio volumio[1014]: info: Discovery: Getting this device information Oct 26 18:09:39 volumio volumio[1014]: info: CoreCommandRouter::volumioGetState Oct 26 18:09:39 volumio volumio[1014]: info: CorePlayQueue::getTrack 0 Oct 26 18:09:39 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 26 18:09:39 volumio volumio[1014]: error: Cannot start Volumio Streaming Daemon Oct 26 18:09:39 volumio volumio[1014]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 26 18:09:39 volumio volumio[1014]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 26 18:09:39 volumio volumio[1014]: error: MyVolumio Custom Token format not valid, refreshing it Oct 26 18:09:39 volumio volumio[1014]: STREAMING PROXY: Starting server on port 3245 Oct 26 18:09:39 volumio volumio[1014]: Node JS runtime: 14 Oct 26 18:09:40 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Oct 26 18:09:40 volumio volumio[1014]: info: MyVolumio login type: Token Oct 26 18:09:40 volumio volumio[1014]: info: MyVolumio login type: Token Oct 26 18:09:40 volumio jellyfin[1637]: [18:09:40] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: TMDb 10.9.6.0 Oct 26 18:09:40 volumio jellyfin[1637]: [18:09:40] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: Studio Images 10.9.6.0 Oct 26 18:09:40 volumio jellyfin[1637]: [18:09:40] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: OMDb 10.9.6.0 Oct 26 18:09:40 volumio volumio[1014]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Oct 26 18:09:40 volumio jellyfin[1637]: [18:09:40] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: MusicBrainz 10.9.6.0 Oct 26 18:09:40 volumio jellyfin[1637]: [18:09:40] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: AudioDB 10.9.6.0 Oct 26 18:09:40 volumio jellyfin[1637]: [18:09:40] [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"] Oct 26 18:09:41 volumio jellyfin[1637]: [18:09:41] [INF] [1] Main: Kestrel is listening on 0.0.0.0 Oct 26 18:09:41 volumio volumio[1014]: info: MyVolumio token set successfully Oct 26 18:09:41 volumio volumio[1014]: info: MYVOLUMIO: Adding device Oct 26 18:09:41 volumio volumio[1014]: info: MYVOLUMIO: Evaluating Server Oct 26 18:09:42 volumio volumio[1014]: info: MyVolumio status changed Oct 26 18:09:42 volumio volumio[1014]: info: Streaming services startup Oct 26 18:09:42 volumio volumio[1014]: info: Starting Streaming Daemon Oct 26 18:09:42 volumio volumio[1014]: info: Removing browser output: myVolumio user plan is not superstar Oct 26 18:09:42 volumio volumio[1014]: info: Removing audio output: Oct 26 18:09:42 volumio volumio[1014]: info: Stoppping Tunnel 1 Oct 26 18:09:42 volumio sudo[2110]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 26 18:09:42 volumio sudo[2110]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 18:09:42 volumio sudo[2110]: pam_unix(sudo:session): session closed for user root Oct 26 18:09:42 volumio volumio[1014]: error: Cannot start Volumio Streaming Daemon Oct 26 18:09:42 volumio volumio[1014]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 26 18:09:42 volumio volumio[1014]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 26 18:09:42 volumio sudo[2113]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Oct 26 18:09:42 volumio sudo[2113]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 18:09:42 volumio sudo[2113]: pam_unix(sudo:session): session closed for user root Oct 26 18:09:42 volumio volumio[1014]: info: Remote SSH Stopped Oct 26 18:09:42 volumio volumio[1014]: info: Setting Geolocation for MyVolumio to as1 Oct 26 18:09:42 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 18:09:42 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 18:09:42 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 18:09:42 volumio volumio[1014]: info: AutoStart - Plugin is starting Oct 26 18:09:42 volumio volumio[1014]: info: CoreCommandRouter::volumioGetQueue Oct 26 18:09:42 volumio volumio[1014]: info: CoreStateMachine::getQueue Oct 26 18:09:42 volumio volumio[1014]: info: CorePlayQueue::getQueue Oct 26 18:09:42 volumio volumio[1014]: info: AutoStart - start playing Oct 26 18:09:42 volumio volumio[1014]: info: AutoStart - start playing with no specific position Oct 26 18:09:42 volumio volumio[1014]: info: CoreCommandRouter::volumioPlay Oct 26 18:09:42 volumio volumio[1014]: info: CoreStateMachine::play index 0 Oct 26 18:09:42 volumio volumio[1014]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 26 18:09:42 volumio volumio[1014]: info: CoreStateMachine::stop Oct 26 18:09:42 volumio volumio[1014]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 26 18:09:42 volumio volumio[1014]: info: CoreStateMachine::play index undefined Oct 26 18:09:42 volumio volumio[1014]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 26 18:09:42 volumio volumio[1014]: info: CorePlayQueue::getTrack 0 Oct 26 18:09:42 volumio volumio[1014]: info: CoreStateMachine::startPlaybackTimer Oct 26 18:09:42 volumio volumio[1014]: info: CorePlayQueue::getTrack 0 Oct 26 18:09:42 volumio volumio[1014]: verbose: ControllerMpd::clearAddPlayTracks USB/赵鹏 - 乌兰巴托的夜晚.flac Oct 26 18:09:42 volumio volumio[1014]: verbose: ControllerMpd::sendMpdCommand stop Oct 26 18:09:42 volumio volumio[1014]: info: sendMpdCommand stop took 6 milliseconds Oct 26 18:09:42 volumio volumio[1014]: verbose: ControllerMpd::sendMpdCommand clear Oct 26 18:09:42 volumio volumio[1014]: info: Oct 26 18:09:42 volumio volumio[1014]: ---------------------------- MPD announces system playlist update Oct 26 18:09:42 volumio volumio[1014]: info: Ignoring MPD Status Update Oct 26 18:09:42 volumio volumio[1014]: info: sendMpdCommand clear took 5 milliseconds Oct 26 18:09:42 volumio volumio[1014]: verbose: ControllerMpd::sendMpdCommand add "USB/赵鹏 - 乌兰巴托的夜晚.flac" Oct 26 18:09:42 volumio volumio[1014]: info: Oct 26 18:09:42 volumio volumio[1014]: ---------------------------- MPD announces system playlist update Oct 26 18:09:42 volumio volumio[1014]: info: Ignoring MPD Status Update Oct 26 18:09:42 volumio volumio[1014]: info: Oct 26 18:09:42 volumio volumio[1014]: ---------------------------- MPD announces system playlist update Oct 26 18:09:42 volumio volumio[1014]: info: Ignoring MPD Status Update Oct 26 18:09:42 volumio volumio[1014]: info: Oct 26 18:09:42 volumio volumio[1014]: ---------------------------- MPD announces system playlist update Oct 26 18:09:42 volumio volumio[1014]: info: Ignoring MPD Status Update Oct 26 18:09:42 volumio volumio[1014]: error: updateQueue error: null Oct 26 18:09:42 volumio volumio[1014]: info: Oct 26 18:09:42 volumio volumio[1014]: ---------------------------- MPD announces system playlist update Oct 26 18:09:42 volumio volumio[1014]: info: Ignoring MPD Status Update Oct 26 18:09:42 volumio volumio[1014]: info: ------------------------------ 35ms Oct 26 18:09:42 volumio volumio[1014]: info: sendMpdCommand add "USB/赵鹏 - 乌兰巴托的夜晚.flac" took 31 milliseconds Oct 26 18:09:42 volumio volumio[1014]: info: ------------------------------ 30ms Oct 26 18:09:42 volumio volumio[1014]: info: ------------------------------ 28ms Oct 26 18:09:42 volumio volumio[1014]: info: ------------------------------ 26ms Oct 26 18:09:42 volumio volumio[1014]: verbose: ControllerMpd::sendMpdCommand play Oct 26 18:09:42 volumio volumio[1014]: info: Oct 26 18:09:42 volumio volumio[1014]: ---------------------------- MPD announces system playlist update Oct 26 18:09:42 volumio volumio[1014]: info: Ignoring MPD Status Update Oct 26 18:09:42 volumio volumio[1014]: info: Oct 26 18:09:42 volumio volumio[1014]: ---------------------------- MPD announces system playlist update Oct 26 18:09:42 volumio volumio[1014]: info: Ignoring MPD Status Update Oct 26 18:09:42 volumio volumio[1014]: info: Oct 26 18:09:42 volumio volumio[1014]: ---------------------------- MPD announces system playlist update Oct 26 18:09:42 volumio volumio[1014]: info: Ignoring MPD Status Update Oct 26 18:09:42 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 26 18:09:42 volumio volumio[1014]: info: Received Get System Info Oct 26 18:09:42 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 26 18:09:42 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 26 18:09:42 volumio volumio[1014]: info: Discovery: Getting this device information Oct 26 18:09:42 volumio volumio[1014]: info: CoreCommandRouter::volumioGetState Oct 26 18:09:42 volumio volumio[1014]: info: CorePlayQueue::getTrack 0 Oct 26 18:09:42 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 26 18:09:42 volumio volumio[1014]: info: Oct 26 18:09:42 volumio volumio[1014]: ---------------------------- MPD announces state update: player Oct 26 18:09:42 volumio volumio[1014]: info: ------------------------------ 27ms Oct 26 18:09:42 volumio volumio[1014]: info: sendMpdCommand play took 22 milliseconds Oct 26 18:09:42 volumio volumio[1014]: info: ------------------------------ 22ms Oct 26 18:09:42 volumio volumio[1014]: info: ------------------------------ 21ms Oct 26 18:09:42 volumio volumio[1014]: info: ------------------------------ 19ms Oct 26 18:09:42 volumio volumio[1014]: info: ControllerMpd::getState Oct 26 18:09:42 volumio volumio[1014]: verbose: ControllerMpd::sendMpdCommand status Oct 26 18:09:42 volumio volumio[1014]: info: Oct 26 18:09:42 volumio volumio[1014]: ---------------------------- MPD announces state update: player Oct 26 18:09:42 volumio volumio[1014]: info: ControllerMpd::getState Oct 26 18:09:42 volumio volumio[1014]: verbose: ControllerMpd::sendMpdCommand status Oct 26 18:09:42 volumio volumio[1014]: info: Oct 26 18:09:42 volumio volumio[1014]: ---------------------------- MPD announces state update: player Oct 26 18:09:42 volumio volumio[1014]: info: ControllerMpd::getState Oct 26 18:09:42 volumio volumio[1014]: verbose: ControllerMpd::sendMpdCommand status Oct 26 18:09:42 volumio volumio[1014]: info: Oct 26 18:09:42 volumio volumio[1014]: ---------------------------- MPD announces state update: player Oct 26 18:09:42 volumio volumio[1014]: info: ControllerMpd::getState Oct 26 18:09:42 volumio volumio[1014]: verbose: ControllerMpd::sendMpdCommand status Oct 26 18:09:42 volumio volumio[1014]: info: Oct 26 18:09:42 volumio volumio[1014]: ---------------------------- MPD announces state update: player Oct 26 18:09:42 volumio volumio[1014]: info: ControllerMpd::getState Oct 26 18:09:42 volumio volumio[1014]: verbose: ControllerMpd::sendMpdCommand status Oct 26 18:09:42 volumio volumio[1014]: info: Oct 26 18:09:42 volumio volumio[1014]: ---------------------------- MPD announces state update: player Oct 26 18:09:42 volumio volumio[1014]: info: ControllerMpd::getState Oct 26 18:09:42 volumio volumio[1014]: verbose: ControllerMpd::sendMpdCommand status Oct 26 18:09:42 volumio volumio[1014]: info: Oct 26 18:09:42 volumio volumio[1014]: ---------------------------- MPD announces state update: player Oct 26 18:09:42 volumio volumio[1014]: info: ControllerMpd::getState Oct 26 18:09:42 volumio volumio[1014]: verbose: ControllerMpd::sendMpdCommand status Oct 26 18:09:42 volumio volumio[1014]: info: Oct 26 18:09:42 volumio volumio[1014]: ---------------------------- MPD announces state update: player Oct 26 18:09:42 volumio volumio[1014]: info: ControllerMpd::getState Oct 26 18:09:42 volumio volumio[1014]: verbose: ControllerMpd::sendMpdCommand status Oct 26 18:09:42 volumio volumio[1014]: info: sendMpdCommand status took 68 milliseconds Oct 26 18:09:42 volumio volumio[1014]: info: sendMpdCommand status took 67 milliseconds Oct 26 18:09:42 volumio volumio[1014]: info: sendMpdCommand status took 65 milliseconds Oct 26 18:09:42 volumio volumio[1014]: info: sendMpdCommand status took 63 milliseconds Oct 26 18:09:42 volumio volumio[1014]: info: sendMpdCommand status took 9 milliseconds Oct 26 18:09:42 volumio volumio[1014]: info: sendMpdCommand status took 7 milliseconds Oct 26 18:09:42 volumio volumio[1014]: info: sendMpdCommand status took 6 milliseconds Oct 26 18:09:42 volumio volumio[1014]: verbose: ControllerMpd::parseState Oct 26 18:09:42 volumio volumio[1014]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 18:09:42 volumio volumio[1014]: verbose: ControllerMpd::parseState Oct 26 18:09:42 volumio volumio[1014]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 18:09:42 volumio volumio[1014]: verbose: ControllerMpd::parseState Oct 26 18:09:42 volumio volumio[1014]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 18:09:42 volumio volumio[1014]: verbose: ControllerMpd::parseState Oct 26 18:09:42 volumio volumio[1014]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 18:09:42 volumio volumio[1014]: verbose: ControllerMpd::parseState Oct 26 18:09:42 volumio volumio[1014]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 18:09:42 volumio volumio[1014]: verbose: ControllerMpd::parseState Oct 26 18:09:42 volumio volumio[1014]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 18:09:42 volumio volumio[1014]: verbose: ControllerMpd::parseState Oct 26 18:09:42 volumio volumio[1014]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 18:09:42 volumio volumio[1014]: info: sendMpdCommand status took 13 milliseconds Oct 26 18:09:42 volumio volumio[1014]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 26 18:09:42 volumio volumio[1014]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 26 18:09:42 volumio volumio[1014]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 26 18:09:42 volumio volumio[1014]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 26 18:09:42 volumio volumio[1014]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 26 18:09:42 volumio volumio[1014]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 26 18:09:42 volumio volumio[1014]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 26 18:09:42 volumio volumio[1014]: verbose: ControllerMpd::parseState Oct 26 18:09:42 volumio volumio[1014]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 18:09:42 volumio volumio[1014]: verbose: ControllerMpd::parseTrackInfo Oct 26 18:09:42 volumio volumio[1014]: verbose: ControllerMpd::parseTrackInfo Oct 26 18:09:42 volumio volumio[1014]: verbose: ControllerMpd::parseTrackInfo Oct 26 18:09:42 volumio volumio[1014]: verbose: ControllerMpd::parseTrackInfo Oct 26 18:09:42 volumio volumio[1014]: verbose: ControllerMpd::parseTrackInfo Oct 26 18:09:42 volumio volumio[1014]: verbose: ControllerMpd::parseTrackInfo Oct 26 18:09:42 volumio volumio[1014]: verbose: ControllerMpd::parseTrackInfo Oct 26 18:09:42 volumio volumio[1014]: info: ControllerMpd::pushState Oct 26 18:09:42 volumio volumio[1014]: info: CoreCommandRouter::servicePushState Oct 26 18:09:42 volumio volumio[1014]: info: CorePlayQueue::getTrack 0 Oct 26 18:09:42 volumio volumio[1014]: 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"} Oct 26 18:09:42 volumio volumio[1014]: verbose: CURRENT POSITION 0 Oct 26 18:09:42 volumio volumio[1014]: info: CoreStateMachine::syncState stateService play Oct 26 18:09:42 volumio volumio[1014]: info: CoreStateMachine::syncState currentStatus stop Oct 26 18:09:42 volumio volumio[1014]: info: ControllerMpd::pushState Oct 26 18:09:42 volumio volumio[1014]: info: CoreCommandRouter::servicePushState Oct 26 18:09:42 volumio volumio[1014]: info: CorePlayQueue::getTrack 0 Oct 26 18:09:42 volumio volumio[1014]: 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"} Oct 26 18:09:42 volumio volumio[1014]: verbose: CURRENT POSITION 0 Oct 26 18:09:42 volumio volumio[1014]: info: CoreStateMachine::syncState stateService play Oct 26 18:09:42 volumio volumio[1014]: info: CoreStateMachine::syncState currentStatus play Oct 26 18:09:42 volumio volumio[1014]: info: Received an update from plugin. extracting info from payload Oct 26 18:09:42 volumio volumio[1014]: info: CoreStateMachine::pushState Oct 26 18:09:42 volumio volumio[1014]: info: CorePlayQueue::getTrack 0 Oct 26 18:09:42 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 18:09:42 volumio volumio[1014]: info: CoreCommandRouter::volumioPushState Oct 26 18:09:42 volumio volumio[1014]: info: CoreStateMachine::pushState Oct 26 18:09:42 volumio volumio[1014]: info: CorePlayQueue::getTrack 0 Oct 26 18:09:42 volumio volumio[1014]: info: CoreCommandRouter::volumioPushState Oct 26 18:09:42 volumio volumio[1014]: info: ControllerMpd::pushState Oct 26 18:09:42 volumio volumio[1014]: info: CoreCommandRouter::servicePushState Oct 26 18:09:42 volumio volumio[1014]: info: CorePlayQueue::getTrack 0 Oct 26 18:09:42 volumio volumio[1014]: 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"} Oct 26 18:09:42 volumio volumio[1014]: verbose: CURRENT POSITION 0 Oct 26 18:09:42 volumio volumio[1014]: info: CoreStateMachine::syncState stateService play Oct 26 18:09:42 volumio volumio[1014]: info: CoreStateMachine::syncState currentStatus play Oct 26 18:09:42 volumio volumio[1014]: info: Received an update from plugin. extracting info from payload Oct 26 18:09:42 volumio volumio[1014]: info: CoreStateMachine::pushState Oct 26 18:09:42 volumio volumio[1014]: info: CorePlayQueue::getTrack 0 Oct 26 18:09:42 volumio volumio[1014]: info: CoreCommandRouter::volumioPushState Oct 26 18:09:42 volumio volumio[1014]: info: CoreStateMachine::pushState Oct 26 18:09:42 volumio volumio[1014]: info: CorePlayQueue::getTrack 0 Oct 26 18:09:42 volumio volumio[1014]: info: CoreCommandRouter::volumioPushState Oct 26 18:09:42 volumio volumio[1014]: info: ControllerMpd::pushState Oct 26 18:09:42 volumio volumio[1014]: info: CoreCommandRouter::servicePushState Oct 26 18:09:42 volumio volumio[1014]: info: CorePlayQueue::getTrack 0 Oct 26 18:09:42 volumio volumio[1014]: 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"} Oct 26 18:09:42 volumio volumio[1014]: verbose: CURRENT POSITION 0 Oct 26 18:09:42 volumio volumio[1014]: info: CoreStateMachine::syncState stateService play Oct 26 18:09:42 volumio volumio[1014]: info: CoreStateMachine::syncState currentStatus play Oct 26 18:09:42 volumio volumio[1014]: info: Received an update from plugin. extracting info from payload Oct 26 18:09:42 volumio volumio[1014]: info: CoreStateMachine::pushState Oct 26 18:09:42 volumio volumio[1014]: info: CorePlayQueue::getTrack 0 Oct 26 18:09:42 volumio volumio[1014]: info: CoreCommandRouter::volumioPushState Oct 26 18:09:42 volumio volumio[1014]: info: CoreStateMachine::pushState Oct 26 18:09:42 volumio volumio[1014]: info: CorePlayQueue::getTrack 0 Oct 26 18:09:42 volumio volumio[1014]: info: CoreCommandRouter::volumioPushState Oct 26 18:09:42 volumio volumio[1014]: info: ControllerMpd::pushState Oct 26 18:09:42 volumio volumio[1014]: info: CoreCommandRouter::servicePushState Oct 26 18:09:42 volumio volumio[1014]: info: CorePlayQueue::getTrack 0 Oct 26 18:09:42 volumio volumio[1014]: 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"} Oct 26 18:09:42 volumio volumio[1014]: verbose: CURRENT POSITION 0 Oct 26 18:09:42 volumio volumio[1014]: info: CoreStateMachine::syncState stateService play Oct 26 18:09:42 volumio volumio[1014]: info: CoreStateMachine::syncState currentStatus play Oct 26 18:09:42 volumio volumio[1014]: info: Received an update from plugin. extracting info from payload Oct 26 18:09:42 volumio volumio[1014]: info: CoreStateMachine::pushState Oct 26 18:09:42 volumio volumio[1014]: info: CorePlayQueue::getTrack 0 Oct 26 18:09:42 volumio volumio[1014]: info: CoreCommandRouter::volumioPushState Oct 26 18:09:42 volumio volumio[1014]: info: CoreStateMachine::pushState Oct 26 18:09:42 volumio volumio[1014]: info: CorePlayQueue::getTrack 0 Oct 26 18:09:42 volumio volumio[1014]: info: CoreCommandRouter::volumioPushState Oct 26 18:09:42 volumio volumio[1014]: info: ControllerMpd::pushState Oct 26 18:09:42 volumio volumio[1014]: info: CoreCommandRouter::servicePushState Oct 26 18:09:42 volumio volumio[1014]: info: CorePlayQueue::getTrack 0 Oct 26 18:09:42 volumio volumio[1014]: 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"} Oct 26 18:09:42 volumio volumio[1014]: verbose: CURRENT POSITION 0 Oct 26 18:09:42 volumio volumio[1014]: info: CoreStateMachine::syncState stateService play Oct 26 18:09:42 volumio volumio[1014]: info: CoreStateMachine::syncState currentStatus play Oct 26 18:09:42 volumio volumio[1014]: info: Received an update from plugin. extracting info from payload Oct 26 18:09:42 volumio volumio[1014]: info: CoreStateMachine::pushState Oct 26 18:09:42 volumio volumio[1014]: info: CorePlayQueue::getTrack 0 Oct 26 18:09:42 volumio volumio[1014]: info: CoreCommandRouter::volumioPushState Oct 26 18:09:42 volumio volumio[1014]: info: CoreStateMachine::pushState Oct 26 18:09:42 volumio volumio[1014]: info: CorePlayQueue::getTrack 0 Oct 26 18:09:42 volumio volumio[1014]: info: CoreCommandRouter::volumioPushState Oct 26 18:09:42 volumio volumio[1014]: info: ControllerMpd::pushState Oct 26 18:09:42 volumio volumio[1014]: info: CoreCommandRouter::servicePushState Oct 26 18:09:42 volumio volumio[1014]: info: CorePlayQueue::getTrack 0 Oct 26 18:09:42 volumio volumio[1014]: 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"} Oct 26 18:09:42 volumio volumio[1014]: verbose: CURRENT POSITION 0 Oct 26 18:09:42 volumio volumio[1014]: info: CoreStateMachine::syncState stateService play Oct 26 18:09:42 volumio volumio[1014]: info: CoreStateMachine::syncState currentStatus play Oct 26 18:09:42 volumio volumio[1014]: info: Received an update from plugin. extracting info from payload Oct 26 18:09:42 volumio volumio[1014]: info: CoreStateMachine::pushState Oct 26 18:09:42 volumio volumio[1014]: info: CorePlayQueue::getTrack 0 Oct 26 18:09:42 volumio volumio[1014]: info: CoreCommandRouter::volumioPushState Oct 26 18:09:42 volumio volumio[1014]: info: CoreStateMachine::pushState Oct 26 18:09:42 volumio volumio[1014]: info: CorePlayQueue::getTrack 0 Oct 26 18:09:42 volumio volumio[1014]: info: CoreCommandRouter::volumioPushState Oct 26 18:09:42 volumio volumio[1014]: info: ------------------------------ 129ms Oct 26 18:09:42 volumio volumio[1014]: info: ------------------------------ 174ms Oct 26 18:09:42 volumio volumio[1014]: info: ------------------------------ 172ms Oct 26 18:09:42 volumio volumio[1014]: info: ------------------------------ 117ms Oct 26 18:09:42 volumio volumio[1014]: info: ------------------------------ 115ms Oct 26 18:09:42 volumio volumio[1014]: info: ------------------------------ 114ms Oct 26 18:09:42 volumio volumio[1014]: info: sendMpdCommand playlistinfo took 100 milliseconds Oct 26 18:09:42 volumio volumio[1014]: verbose: ControllerMpd::parseTrackInfo Oct 26 18:09:42 volumio volumio[1014]: info: ControllerMpd::pushState Oct 26 18:09:42 volumio volumio[1014]: info: CoreCommandRouter::servicePushState Oct 26 18:09:42 volumio volumio[1014]: info: CorePlayQueue::getTrack 0 Oct 26 18:09:42 volumio volumio[1014]: 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"} Oct 26 18:09:42 volumio volumio[1014]: verbose: CURRENT POSITION 0 Oct 26 18:09:42 volumio volumio[1014]: info: CoreStateMachine::syncState stateService play Oct 26 18:09:42 volumio volumio[1014]: info: CoreStateMachine::syncState currentStatus play Oct 26 18:09:42 volumio volumio[1014]: info: Received an update from plugin. extracting info from payload Oct 26 18:09:42 volumio volumio[1014]: info: CoreStateMachine::pushState Oct 26 18:09:42 volumio volumio[1014]: info: CorePlayQueue::getTrack 0 Oct 26 18:09:42 volumio volumio[1014]: info: CoreCommandRouter::volumioPushState Oct 26 18:09:42 volumio volumio[1014]: info: CoreStateMachine::pushState Oct 26 18:09:42 volumio volumio[1014]: info: CorePlayQueue::getTrack 0 Oct 26 18:09:42 volumio volumio[1014]: info: CoreCommandRouter::volumioPushState Oct 26 18:09:42 volumio volumio[1014]: info: ------------------------------ 149ms Oct 26 18:09:43 volumio volumio[1014]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 26 18:09:43 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 26 18:09:43 volumio volumio[1014]: info: Updating MyVolumio device info Oct 26 18:09:43 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 18:09:44 volumio volumio[1014]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 26 18:09:44 volumio jellyfin[1637]: [18:09:44] [WRN] [1] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The WebRootPath was not found: /wwwroot. Static files may be unavailable. Oct 26 18:09:44 volumio volumio[1014]: info: CoreCommandRouter::volumioGetState Oct 26 18:09:44 volumio volumio[1014]: info: CorePlayQueue::getTrack 0 Oct 26 18:09:44 volumio sudo[2135]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/usr/bin/python3 ./screensaver/volumio_peppymeter.py Oct 26 18:09:44 volumio sudo[2135]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 18:09:44 volumio jellyfin[1637]: [18:09:44] [INF] [1] Emby.Server.Implementations.ApplicationHost: Running startup tasks Oct 26 18:09:44 volumio jellyfin[1637]: [18:09:44] [INF] [1] Emby.Server.Implementations.ScheduledTasks.TaskManager: Daily trigger for Generate Trickplay Images set to fire at 2024-10-27 03:00:00.000 +08:00, which is 08:50:15.1448629 from now. Oct 26 18:09:44 volumio jellyfin[1637]: [18:09:44] [INF] [1] Emby.Server.Implementations.ScheduledTasks.TaskManager: Daily trigger for Extract Chapter Images set to fire at 2024-10-27 02:00:00.000 +08:00, which is 07:50:15.1244649 from now. Oct 26 18:09:45 volumio jellyfin[1637]: [18:09:45] [INF] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: Found ffmpeg version 6.0.1 Oct 26 18:09:45 volumio jellyfin[1637]: [18:09:45] [INF] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: Available decoders: ["libdav1d", "av1", "av1_cuvid", "av1_qsv", "h264", "h264_qsv", "h264_cuvid", "hevc", "hevc_qsv", "hevc_cuvid", "mpeg2video", "mpeg2_qsv", "mpeg2_cuvid", "mpeg4", "mpeg4_cuvid", "msmpeg4", "vc1_qsv", "vc1_cuvid", "vp8", "libvpx", "vp8_cuvid", "vp8_qsv", "vp9", "libvpx-vp9", "vp9_cuvid", "vp9_qsv", "aac", "ac3", "dca", "flac", "mp3", "truehd"] Oct 26 18:09:46 volumio jellyfin[1637]: [18:09:46] [INF] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: Available encoders: ["libsvtav1", "av1_nvenc", "av1_qsv", "av1_amf", "av1_vaapi", "libx264", "h264_amf", "h264_nvenc", "h264_qsv", "h264_v4l2m2m", "h264_vaapi", "libx265", "hevc_amf", "hevc_nvenc", "hevc_qsv", "hevc_vaapi", "mjpeg_qsv", "mjpeg_vaapi", "mpeg4", "msmpeg4", "libvpx", "libvpx-vp9", "aac", "libfdk_aac", "ac3", "alac", "dca", "flac", "libmp3lame", "libopus", "truehd", "libvorbis", "srt"] Oct 26 18:09:46 volumio jellyfin[1637]: [18:09:46] [INF] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: Available filters: ["deinterlace_qsv", "deinterlace_vaapi", "hwupload_cuda", "hwupload_vaapi", "libplacebo", "overlay_opencl", "overlay_qsv", "overlay_vaapi", "overlay_vulkan", "overlay_cuda", "procamp_vaapi", "scale_cuda", "scale_opencl", "scale_qsv", "scale_vaapi", "scale_vulkan", "tonemap_cuda", "tonemap_opencl", "tonemap_vaapi", "vpp_qsv", "yadif_cuda", "zscale", "alphasrc"] Oct 26 18:09:47 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 26 18:09:47 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 26 18:09:47 volumio volumio[1014]: info: MYVOLUMIO: Adding device Oct 26 18:09:47 volumio volumio[1014]: info: MYVOLUMIO: Evaluating Server Oct 26 18:09:47 volumio jellyfin[1637]: [18:09:47] [INF] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: Available hwaccel types: ["cuda", "vaapi", "qsv", "drm", "opencl", "vulkan"] Oct 26 18:09:48 volumio volumio[1014]: 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 Oct 26 18:09:48 volumio volumio[1014]: info: CoreCommandRouter::volumioGetState Oct 26 18:09:48 volumio volumio[1014]: info: CorePlayQueue::getTrack 0 Oct 26 18:09:48 volumio jellyfin[1637]: [18:09:48] [INF] [11] Emby.Server.Implementations.ScheduledTasks.TaskManager: Clean up collections and playlists Completed after 0 minute(s) and 0 seconds Oct 26 18:09:48 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Oct 26 18:09:48 volumio volumio[1014]: info: Setting Geolocation for MyVolumio to as1 Oct 26 18:09:48 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 18:09:48 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 18:09:48 volumio volumio[1014]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Oct 26 18:09:49 volumio volumio[1014]: info: Updating MyVolumio device info Oct 26 18:09:49 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 18:09:49 volumio jellyfin[1637]: [18:09:49] [INF] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: FFmpeg: /usr/lib/jellyfin-ffmpeg/ffmpeg Oct 26 18:09:49 volumio jellyfin[1637]: [18:09:49] [INF] [1] Emby.Server.Implementations.ApplicationHost: ServerId: b1cfc0d663974d88a7e5ad9e731e53ae Oct 26 18:09:49 volumio jellyfin[1637]: [18:09:49] [INF] [1] Emby.Server.Implementations.ApplicationHost: Core startup complete Oct 26 18:09:49 volumio jellyfin[1637]: [18:09:49] [INF] [1] Main: Startup complete 0:00:25.1562496 Oct 26 18:09:49 volumio volumio[1014]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 7 Oct 26 18:09:50 volumio volumio[1014]: info: CoreCommandRouter::volumioGetState Oct 26 18:09:50 volumio volumio[1014]: info: CorePlayQueue::getTrack 0 Oct 26 18:09:50 volumio volumio[1014]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Oct 26 18:09:50 volumio jellyfin[1637]: [18:09:50] [INF] [11] Emby.Server.Implementations.ScheduledTasks.TaskManager: Update Plugins Completed after 0 minute(s) and 2 seconds Oct 26 18:09:51 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 26 18:09:51 volumio volumio[1014]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 26 18:09:51 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 26 18:09:51 volumio volumio[1014]: info: Received Get System Version Oct 26 18:09:51 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 26 18:09:51 volumio volumio[1014]: info: Received Get System Info Oct 26 18:09:51 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 26 18:09:51 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 26 18:09:51 volumio volumio[1014]: info: Discovery: Getting this device information Oct 26 18:09:51 volumio volumio[1014]: info: CoreCommandRouter::volumioGetState Oct 26 18:09:51 volumio volumio[1014]: info: CorePlayQueue::getTrack 0 Oct 26 18:09:51 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 26 18:09:51 volumio sudo[2135]: pam_unix(sudo:session): session closed for user root Oct 26 18:09:52 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 26 18:09:52 volumio volumio[1014]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 26 18:09:52 volumio volumio[1014]: info: CoreCommandRouter::volumioGetState Oct 26 18:09:52 volumio volumio[1014]: info: CorePlayQueue::getTrack 0 Oct 26 18:09:53 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 26 18:09:53 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 18:09:53 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 26 18:09:53 volumio volumio-remote-updater[739]: No test mode Oct 26 18:09:53 volumio volumio-remote-updater[739]: No alpha test mode Oct 26 18:09:53 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Oct 26 18:09:53 volumio volumio[1014]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 26 18:09:53 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 26 18:09:57 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 18:09:57 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 18:09:57 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 26 18:09:57 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 26 18:09:57 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 26 18:09:57 volumio volumio[1014]: info: CoreCommandRouter::volumioGetBrowseSources Oct 26 18:09:57 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 26 18:09:57 volumio volumio[1014]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 26 18:09:58 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 18:09:59 volumio volumio[1014]: error: MyVolumio Plugin failed to authenticate in a timely fashion Oct 26 18:09:59 volumio volumio[1014]: info: Completed starting MyVolumio Plugin Oct 26 18:09:59 volumio volumio[1014]: info: BOOT COMPLETED Oct 26 18:09:59 volumio volumio[1014]: [Metrics] CommandRouter: 47s 156.66ms Oct 26 18:09:59 volumio volumio[1014]: info: CoreCommandRouter::volumiosetStartupVolume Oct 26 18:09:59 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 18:09:59 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 18:09:59 volumio volumio[1014]: info: CoreCommandRouter::Close All Modals sent Oct 26 18:09:59 volumio volumio[1014]: info: CoreCommandRouter::Close All Modals sent Oct 26 18:09:59 volumio volumio[1014]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Oct 26 18:09:59 volumio volumio[1014]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card Oct 26 18:09:59 volumio volumio[1014]: aplay: main:828: audio open error: No such device Oct 26 18:10:00 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 26 18:10:00 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 26 18:10:00 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Oct 26 18:10:00 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 26 18:10:03 volumio volumio[1014]: info: CoreCommandRouter::volumioGetState Oct 26 18:10:03 volumio volumio[1014]: info: CorePlayQueue::getTrack 0 Oct 26 18:10:03 volumio sudo[2268]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/usr/bin/python3 ./screensaver/volumio_peppymeter.py Oct 26 18:10:03 volumio sudo[2268]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 18:10:05 volumio volumio[1014]: 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 Oct 26 18:10:05 volumio volumio[1014]: info: CoreCommandRouter::volumioGetState Oct 26 18:10:05 volumio volumio[1014]: info: CorePlayQueue::getTrack 0 Oct 26 18:10:07 volumio volumio[1014]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 7 Oct 26 18:10:07 volumio volumio[1014]: info: CoreCommandRouter::volumioGetState Oct 26 18:10:07 volumio volumio[1014]: info: CorePlayQueue::getTrack 0 Oct 26 18:10:07 volumio screensaver.sh[743]: Fatal Python error: Cannot recover from stack overflow. Oct 26 18:10:07 volumio screensaver.sh[743]: Thread 0x00007f979cff9700 (most recent call first): Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/lib/python3.7/threading.py", line 300 in wait Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/lib/python3.7/threading.py", line 552 in wait Oct 26 18:10:07 volumio screensaver.sh[743]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 742 in run Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/lib/python3.7/threading.py", line 917 in _bootstrap_inner Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/lib/python3.7/threading.py", line 885 in _bootstrap Oct 26 18:10:07 volumio screensaver.sh[743]: Thread 0x00007f979d7fa700 (most recent call first): Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/lib/python3.7/dataclasses.py", line 475 in _init_fn Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/lib/python3.7/dataclasses.py", line 904 in _process_class Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/lib/python3.7/dataclasses.py", line 983 in wrap Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/lib/python3.7/dataclasses.py", line 991 in dataclass Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/local/lib/python3.7/dist-packages/setuptools/_vendor/packaging/_tokenizer.py", line 9 in Oct 26 18:10:07 volumio screensaver.sh[743]: File "", line 219 in _call_with_frames_removed Oct 26 18:10:07 volumio screensaver.sh[743]: File "", line 728 in exec_module Oct 26 18:10:07 volumio screensaver.sh[743]: File "", line 677 in _load_unlocked Oct 26 18:10:07 volumio screensaver.sh[743]: File "", line 967 in _find_and_load_unlocked Oct 26 18:10:07 volumio screensaver.sh[743]: File "", line 983 in _find_and_load Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/local/lib/python3.7/dist-packages/setuptools/_vendor/packaging/_parser.py", line 10 in Oct 26 18:10:07 volumio screensaver.sh[743]: File "", line 219 in _call_with_frames_removed Oct 26 18:10:07 volumio screensaver.sh[743]: File "", line 728 in exec_module Oct 26 18:10:07 volumio screensaver.sh[743]: File "", line 677 in _load_unlocked Oct 26 18:10:07 volumio screensaver.sh[743]: File "", line 967 in _find_and_load_unlocked Oct 26 18:10:07 volumio screensaver.sh[743]: File "", line 983 in _find_and_load Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/local/lib/python3.7/dist-packages/setuptools/_vendor/packaging/markers.py", line 11 in Oct 26 18:10:07 volumio screensaver.sh[743]: File "", line 219 in _call_with_frames_removed Oct 26 18:10:07 volumio screensaver.sh[743]: File "", line 728 in exec_module Oct 26 18:10:07 volumio screensaver.sh[743]: File "", line 677 in _load_unlocked Oct 26 18:10:07 volumio screensaver.sh[743]: File "", line 967 in _find_and_load_unlocked Oct 26 18:10:07 volumio screensaver.sh[743]: File "", line 983 in _find_and_load Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/local/lib/python3.7/dist-packages/setuptools/config/setupcfg.py", line 33 in Oct 26 18:10:07 volumio screensaver.sh[743]: File "", line 219 in _call_with_frames_removed Oct 26 18:10:07 volumio screensaver.sh[743]: File "", line 728 in exec_module Oct 26 18:10:07 volumio screensaver.sh[743]: File "", line 677 in _load_unlocked Oct 26 18:10:07 volumio screensaver.sh[743]: File "", line 967 in _find_and_load_unlocked Oct 26 18:10:07 volumio screensaver.sh[743]: File "", line 983 in _find_and_load Oct 26 18:10:07 volumio screensaver.sh[743]: File "", line 219 in _call_with_frames_removed Oct 26 18:10:07 volumio screensaver.sh[743]: File "", line 1035 in _handle_fromlist Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/local/lib/python3.7/dist-packages/setuptools/config/__init__.py", line 8 in Oct 26 18:10:07 volumio screensaver.sh[743]: File "", line 219 in _call_with_frames_removed Oct 26 18:10:07 volumio screensaver.sh[743]: File "", line 728 in exec_module Oct 26 18:10:07 volumio screensaver.sh[743]: File "", line 677 in _load_unlocked Oct 26 18:10:07 volumio screensaver.sh[743]: File "", line 967 in _find_and_load_unlocked Oct 26 18:10:07 volumio screensaver.sh[743]: File "", line 983 in _find_and_load Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/local/lib/python3.7/dist-packages/setuptools/dist.py", line 37 in Oct 26 18:10:07 volumio screensaver.sh[743]: File "", line 219 in _call_with_frames_removed Oct 26 18:10:07 volumio screensaver.sh[743]: File "", line 728 in exec_module Oct 26 18:10:07 volumio screensaver.sh[743]: File "", line 677 in _load_unlocked Oct 26 18:10:07 volumio screensaver.sh[743]: File "", line 967 in _find_and_load_unlocked Oct 26 18:10:07 volumio screensaver.sh[743]: File "", line 983 in _find_and_load Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/local/lib/python3.7/dist-packages/setuptools/__init__.py", line 17 in Oct 26 18:10:07 volumio screensaver.sh[743]: File "", line 219 in _call_with_frames_removed Oct 26 18:10:07 volumio screensaver.sh[743]: File "", line 728 in exec_module Oct 26 18:10:07 volumio screensaver.sh[743]: File "", line 677 in _load_unlocked Oct 26 18:10:07 volumio screensaver.sh[743]: File "", line 967 in _find_and_load_unlocked Oct 26 18:10:07 volumio screensaver.sh[743]: File "", line 983 in _find_and_load Oct 26 18:10:07 volumio screensaver.sh[743]: File "", line 1006 in _gcd_import Oct 26 18:10:07 volumio screensaver.sh[743]: File "", line 219 in _call_with_frames_removed Oct 26 18:10:07 volumio screensaver.sh[743]: File "", line 953 in _find_and_load_unlocked Oct 26 18:10:07 volumio screensaver.sh[743]: File "", line 983 in _find_and_load Oct 26 18:10:07 volumio screensaver.sh[743]: File "", line 1006 in _gcd_import Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/lib/python3.7/importlib/__init__.py", line 127 in import_module Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/local/lib/python3.7/dist-packages/_distutils_hack/__init__.py", line 108 in spec_for_distutils Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/local/lib/python3.7/dist-packages/_distutils_hack/__init__.py", line 97 in find_spec Oct 26 18:10:07 volumio screensaver.sh[743]: File "", line 906 in _find_spec Oct 26 18:10:07 volumio screensaver.sh[743]: File "", line 963 in _find_and_load_unlocked Oct 26 18:10:07 volumio screensaver.sh[743]: File "", line 983 in _find_and_load Oct 26 18:10:07 volumio screensaver.sh[743]: File "", line 219 in _call_with_frames_removed Oct 26 18:10:07 volumio screensaver.sh[743]: File "", line 953 in _find_and_load_unlocked Oct 26 18:10:07 volumio screensaver.sh[743]: File "", line 983 in _find_and_load Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/lib/python3/dist-packages/PIL/TiffImagePlugin.py", line 57 in Oct 26 18:10:07 volumio screensaver.sh[743]: File "", line 219 in _call_with_frames_removed Oct 26 18:10:07 volumio screensaver.sh[743]: File "", line 728 in exec_module Oct 26 18:10:07 volumio screensaver.sh[743]: File "", line 677 in _load_unlocked Oct 26 18:10:07 volumio screensaver.sh[743]: File "", line 967 in _find_and_load_unlocked Oct 26 18:10:07 volumio screensaver.sh[743]: File "", line 983 in _find_and_load Oct 26 18:10:07 volumio screensaver.sh[743]: File "", line 219 in _call_with_frames_removed Oct 26 18:10:07 volumio screensaver.sh[743]: File "", line 1035 in _handle_fromlist Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/lib/python3/dist-packages/PIL/JpegImagePlugin.py", line 41 in Oct 26 18:10:07 volumio screensaver.sh[743]: File "", line 219 in _call_with_frames_removed Oct 26 18:10:07 volumio screensaver.sh[743]: File "", line 728 in exec_module Oct 26 18:10:07 volumio screensaver.sh[743]: File "", line 677 in _load_unlocked Oct 26 18:10:07 volumio screensaver.sh[743]: File "", line 967 in _find_and_load_unlocked Oct 26 18:10:07 volumio screensaver.sh[743]: File "", line 983 in _find_and_load Oct 26 18:10:07 volumio screensaver.sh[743]: File "", line 219 in _call_with_frames_removed Oct 26 18:10:07 volumio screensaver.sh[743]: File "", line 1035 in _handle_fromlist Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/lib/python3/dist-packages/PIL/Image.py", line 392 in preinit Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/lib/python3/dist-packages/PIL/Image.py", line 2645 in open Oct 26 18:10:07 volumio screensaver.sh[743]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 555 in render_text Oct 26 18:10:07 volumio screensaver.sh[743]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 121 in titleinfo_thread Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/lib/python3.7/threading.py", line 865 in run Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/lib/python3.7/threading.py", line 917 in _bootstrap_inner Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/lib/python3.7/threading.py", line 885 in _bootstrap Oct 26 18:10:07 volumio screensaver.sh[743]: Current thread 0x00007f979dffb700 (most recent call first): Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/lib/python3/dist-packages/requests/sessions.py", line 735 in close Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/lib/python3/dist-packages/requests/sessions.py", line 424 in __exit__ Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/lib/python3/dist-packages/requests/api.py", line 60 in request Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/lib/python3/dist-packages/requests/api.py", line 75 in get Oct 26 18:10:07 volumio screensaver.sh[743]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 305 in get_albumart_data Oct 26 18:10:07 volumio screensaver.sh[743]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 114 in albumart_thread Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/lib/python3.7/threading.py", line 865 in run Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/lib/python3.7/threading.py", line 917 in _bootstrap_inner Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/lib/python3.7/threading.py", line 885 in _bootstrap Oct 26 18:10:07 volumio screensaver.sh[743]: Thread 0x00007f979effd700 (most recent call first): Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/local/lib/python3.7/dist-packages/websocket/_socket.py", line 87 in _recv Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/local/lib/python3.7/dist-packages/websocket/_socket.py", line 108 in recv Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/local/lib/python3.7/dist-packages/websocket/_core.py", line 529 in _recv Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/local/lib/python3.7/dist-packages/websocket/_abnf.py", line 373 in recv_strict Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/local/lib/python3.7/dist-packages/websocket/_abnf.py", line 294 in recv_header Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/local/lib/python3.7/dist-packages/websocket/_abnf.py", line 338 in recv_frame Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/local/lib/python3.7/dist-packages/websocket/_core.py", line 445 in recv_frame Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/local/lib/python3.7/dist-packages/websocket/_core.py", line 406 in recv_data_frame Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/local/lib/python3.7/dist-packages/websocket/_core.py", line 385 in recv_data Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/local/lib/python3.7/dist-packages/websocket/_core.py", line 362 in recv Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/local/lib/python3.7/dist-packages/engineio/client.py", line 648 in _read_loop_websocket Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/lib/python3.7/threading.py", line 865 in run Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/lib/python3.7/threading.py", line 917 in _bootstrap_inner Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/lib/python3.7/threading.py", line 885 in _bootstrap Oct 26 18:10:07 volumio screensaver.sh[743]: Thread 0x00007f979f7fe700 (most recent call first): Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/lib/python3.7/threading.py", line 300 in wait Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/lib/python3.7/queue.py", line 179 in get Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/local/lib/python3.7/dist-packages/engineio/client.py", line 697 in _write_loop Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/lib/python3.7/threading.py", line 865 in run Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/lib/python3.7/threading.py", line 917 in _bootstrap_inner Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/lib/python3.7/threading.py", line 885 in _bootstrap Oct 26 18:10:07 volumio screensaver.sh[743]: Thread 0x00007f979ffff700 (most recent call first): Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/lib/python3.7/threading.py", line 300 in wait Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/lib/python3.7/threading.py", line 552 in wait Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/local/lib/python3.7/dist-packages/engineio/client.py", line 597 in _ping_loop Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/lib/python3.7/threading.py", line 865 in run Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/lib/python3.7/threading.py", line 917 in _bootstrap_inner Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/lib/python3.7/threading.py", line 885 in _bootstrap Oct 26 18:10:07 volumio screensaver.sh[743]: Thread 0x00007f97b0a3a700 (most recent call first): Oct 26 18:10:07 volumio screensaver.sh[743]: File "./screensaver/volumio_peppymeter.py", line 58 in vol_FadeIn_thread Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/lib/python3.7/threading.py", line 865 in run Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/lib/python3.7/threading.py", line 917 in _bootstrap_inner Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/lib/python3.7/threading.py", line 885 in _bootstrap Oct 26 18:10:07 volumio screensaver.sh[743]: Thread 0x00007f97b2ffd700 (most recent call first): Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/local/lib/python3.7/dist-packages/engineio/client.py", line 270 in sleep Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/local/lib/python3.7/dist-packages/socketio/client.py", line 451 in sleep Oct 26 18:10:07 volumio screensaver.sh[743]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 166 in run Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/lib/python3.7/threading.py", line 917 in _bootstrap_inner Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/lib/python3.7/threading.py", line 885 in _bootstrap Oct 26 18:10:07 volumio screensaver.sh[743]: Thread 0x00007f97b37fe700 (most recent call first): Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/local/lib/python3.7/dist-packages/websocket/_socket.py", line 87 in _recv Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/local/lib/python3.7/dist-packages/websocket/_socket.py", line 108 in recv Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/local/lib/python3.7/dist-packages/websocket/_core.py", line 529 in _recv Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/local/lib/python3.7/dist-packages/websocket/_abnf.py", line 373 in recv_strict Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/local/lib/python3.7/dist-packages/websocket/_abnf.py", line 294 in recv_header Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/local/lib/python3.7/dist-packages/websocket/_abnf.py", line 338 in recv_frame Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/local/lib/python3.7/dist-packages/websocket/_core.py", line 445 in recv_frame Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/local/lib/python3.7/dist-packages/websocket/_core.py", line 406 in recv_data_frame Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/local/lib/python3.7/dist-packages/websocket/_core.py", line 385 in recv_data Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/local/lib/python3.7/dist-packages/websocket/_core.py", line 362 in recv Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/local/lib/python3.7/dist-packages/engineio/client.py", line 648 in _read_loop_websocket Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/lib/python3.7/threading.py", line 865 in run Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/lib/python3.7/threading.py", line 917 in _bootstrap_inner Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/lib/python3.7/threading.py", line 885 in _bootstrap Oct 26 18:10:07 volumio screensaver.sh[743]: Thread 0x00007f97b3fff700 (most recent call first): Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/lib/python3.7/threading.py", line 300 in wait Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/lib/python3.7/queue.py", line 179 in get Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/local/lib/python3.7/dist-packages/engineio/client.py", line 697 in _write_loop Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/lib/python3.7/threading.py", line 865 in run Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/lib/python3.7/threading.py", line 917 in _bootstrap_inner Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/lib/python3.7/threading.py", line 885 in _bootstrap Oct 26 18:10:07 volumio screensaver.sh[743]: Thread 0x00007f97c0a59700 (most recent call first): Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/lib/python3.7/threading.py", line 300 in wait Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/lib/python3.7/threading.py", line 552 in wait Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/local/lib/python3.7/dist-packages/engineio/client.py", line 597 in _ping_loop Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/lib/python3.7/threading.py", line 865 in run Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/lib/python3.7/threading.py", line 917 in _bootstrap_inner Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/lib/python3.7/threading.py", line 885 in _bootstrap Oct 26 18:10:07 volumio screensaver.sh[743]: Thread 0x00007f97c2299700 (most recent call first): Oct 26 18:10:07 volumio screensaver.sh[743]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/peppymeter/peppymeter.py", line 209 in start_display_output Oct 26 18:10:07 volumio screensaver.sh[743]: File "./screensaver/volumio_peppymeter.py", line 237 in meter_thread Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/lib/python3.7/threading.py", line 865 in run Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/lib/python3.7/threading.py", line 917 in _bootstrap_inner Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/lib/python3.7/threading.py", line 885 in _bootstrap Oct 26 18:10:07 volumio screensaver.sh[743]: Thread 0x00007f97c2a9a700 (most recent call first): Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/lib/python3.7/threading.py", line 1048 in _wait_for_tstate_lock Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/lib/python3.7/threading.py", line 1032 in join Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/local/lib/python3.7/dist-packages/engineio/client.py", line 204 in wait Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/local/lib/python3.7/dist-packages/socketio/client.py", line 292 in wait Oct 26 18:10:07 volumio screensaver.sh[743]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_random.py", line 75 in run Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/lib/python3.7/threading.py", line 917 in _bootstrap_inner Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/lib/python3.7/threading.py", line 885 in _bootstrap Oct 26 18:10:07 volumio screensaver.sh[743]: Thread 0x00007f97c32db700 (most recent call first): Oct 26 18:10:07 volumio screensaver.sh[743]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/peppymeter/datasource.py", line 256 in get_latest_pipe_data Oct 26 18:10:07 volumio screensaver.sh[743]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/peppymeter/datasource.py", line 281 in get_pipe_value Oct 26 18:10:07 volumio screensaver.sh[743]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/peppymeter/datasource.py", line 180 in get_value Oct 26 18:10:07 volumio screensaver.sh[743]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/peppymeter/datasource.py", line 174 in get_data Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/lib/python3.7/threading.py", line 865 in run Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/lib/python3.7/threading.py", line 917 in _bootstrap_inner Oct 26 18:10:07 volumio screensaver.sh[743]: File "/usr/lib/python3.7/threading.py", line 885 in _bootstrap Oct 26 18:10:07 volumio screensaver.sh[743]: Thread 0x00007f97c9bd5740 (most recent call first): Oct 26 18:10:07 volumio screensaver.sh[743]: File "./screensaver/volumio_peppymeter.py", line 356 in Oct 26 18:10:07 volumio sudo[2268]: pam_unix(sudo:session): session closed for user root Oct 26 18:10:07 volumio screensaver.sh[743]: /home/volumio/peppy_screensaver/screensaver.sh: line 24: 2268 Aborted sudo python3 ./screensaver/volumio_peppymeter.py Oct 26 18:10:09 volumio volumio[1014]: info: CoreCommandRouter::volumioPause Oct 26 18:10:09 volumio volumio[1014]: info: CoreStateMachine::pause Oct 26 18:10:09 volumio volumio[1014]: info: CoreStateMachine::stPlaybackTimer Oct 26 18:10:09 volumio volumio[1014]: info: CoreStateMachine::servicePause Oct 26 18:10:09 volumio volumio[1014]: info: CorePlayQueue::getTrack 0 Oct 26 18:10:09 volumio volumio[1014]: info: CoreCommandRouter::servicePause Oct 26 18:10:09 volumio volumio[1014]: info: ControllerMpd::pause Oct 26 18:10:09 volumio volumio[1014]: verbose: ControllerMpd::sendMpdCommand pause Oct 26 18:10:09 volumio volumio[1014]: info: sendMpdCommand pause took 2 milliseconds Oct 26 18:10:09 volumio volumio[1014]: info: Oct 26 18:10:09 volumio volumio[1014]: ---------------------------- MPD announces state update: player Oct 26 18:10:09 volumio volumio[1014]: info: ControllerMpd::getState Oct 26 18:10:09 volumio volumio[1014]: verbose: ControllerMpd::sendMpdCommand status Oct 26 18:10:09 volumio volumio[1014]: info: Oct 26 18:10:09 volumio volumio[1014]: ---------------------------- MPD announces state update: player Oct 26 18:10:09 volumio volumio[1014]: info: ControllerMpd::getState Oct 26 18:10:09 volumio volumio[1014]: verbose: ControllerMpd::sendMpdCommand status Oct 26 18:10:09 volumio volumio[1014]: info: Oct 26 18:10:09 volumio volumio[1014]: ---------------------------- MPD announces state update: player Oct 26 18:10:09 volumio volumio[1014]: info: ControllerMpd::getState Oct 26 18:10:09 volumio volumio[1014]: verbose: ControllerMpd::sendMpdCommand status Oct 26 18:10:09 volumio volumio[1014]: info: Oct 26 18:10:09 volumio volumio[1014]: ---------------------------- MPD announces state update: player Oct 26 18:10:09 volumio volumio[1014]: info: ControllerMpd::getState Oct 26 18:10:09 volumio volumio[1014]: verbose: ControllerMpd::sendMpdCommand status Oct 26 18:10:09 volumio volumio[1014]: info: sendMpdCommand status took 5 milliseconds Oct 26 18:10:09 volumio volumio[1014]: info: sendMpdCommand status took 3 milliseconds Oct 26 18:10:09 volumio volumio[1014]: verbose: ControllerMpd::parseState Oct 26 18:10:09 volumio volumio[1014]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 18:10:09 volumio volumio[1014]: verbose: ControllerMpd::parseState Oct 26 18:10:09 volumio volumio[1014]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 18:10:09 volumio volumio[1014]: info: sendMpdCommand status took 5 milliseconds Oct 26 18:10:09 volumio volumio[1014]: info: sendMpdCommand status took 4 milliseconds Oct 26 18:10:09 volumio volumio[1014]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 26 18:10:09 volumio volumio[1014]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 26 18:10:09 volumio volumio[1014]: verbose: ControllerMpd::parseState Oct 26 18:10:09 volumio volumio[1014]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 18:10:09 volumio volumio[1014]: verbose: ControllerMpd::parseState Oct 26 18:10:09 volumio volumio[1014]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 26 18:10:09 volumio volumio[1014]: verbose: ControllerMpd::parseTrackInfo Oct 26 18:10:09 volumio volumio[1014]: verbose: ControllerMpd::parseTrackInfo Oct 26 18:10:09 volumio volumio[1014]: info: ControllerMpd::pushState Oct 26 18:10:09 volumio volumio[1014]: info: CoreCommandRouter::servicePushState Oct 26 18:10:09 volumio volumio[1014]: info: CorePlayQueue::getTrack 0 Oct 26 18:10:09 volumio volumio[1014]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":27456,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"843 Kbps","isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"} Oct 26 18:10:09 volumio volumio[1014]: verbose: CURRENT POSITION 0 Oct 26 18:10:09 volumio volumio[1014]: info: CoreStateMachine::syncState stateService pause Oct 26 18:10:09 volumio volumio[1014]: info: CoreStateMachine::syncState currentStatus pause Oct 26 18:10:09 volumio volumio[1014]: info: CoreStateMachine::pushState Oct 26 18:10:09 volumio volumio[1014]: info: CorePlayQueue::getTrack 0 Oct 26 18:10:09 volumio volumio[1014]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 26 18:10:09 volumio volumio[1014]: info: CoreCommandRouter::volumioPushState Oct 26 18:10:09 volumio volumio[1014]: info: CoreStateMachine::stPlaybackTimer Oct 26 18:10:09 volumio volumio[1014]: info: ControllerMpd::pushState Oct 26 18:10:09 volumio volumio[1014]: info: CoreCommandRouter::servicePushState Oct 26 18:10:09 volumio volumio[1014]: info: CorePlayQueue::getTrack 0 Oct 26 18:10:09 volumio volumio[1014]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":27456,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"843 Kbps","isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"} Oct 26 18:10:09 volumio volumio[1014]: verbose: CURRENT POSITION 0 Oct 26 18:10:09 volumio volumio[1014]: info: CoreStateMachine::syncState stateService pause Oct 26 18:10:09 volumio volumio[1014]: info: CoreStateMachine::syncState currentStatus pause Oct 26 18:10:09 volumio volumio[1014]: info: CoreStateMachine::pushState Oct 26 18:10:09 volumio volumio[1014]: info: CorePlayQueue::getTrack 0 Oct 26 18:10:09 volumio volumio[1014]: info: CoreCommandRouter::volumioPushState Oct 26 18:10:09 volumio volumio[1014]: info: CoreStateMachine::stPlaybackTimer Oct 26 18:10:09 volumio volumio[1014]: info: ------------------------------ 36ms Oct 26 18:10:09 volumio volumio[1014]: info: ------------------------------ 35ms Oct 26 18:10:09 volumio volumio[1014]: info: sendMpdCommand playlistinfo took 29 milliseconds Oct 26 18:10:09 volumio volumio[1014]: verbose: ControllerMpd::parseTrackInfo Oct 26 18:10:09 volumio volumio[1014]: info: ControllerMpd::pushState Oct 26 18:10:09 volumio volumio[1014]: info: CoreCommandRouter::servicePushState Oct 26 18:10:09 volumio volumio[1014]: info: CorePlayQueue::getTrack 0 Oct 26 18:10:09 volumio volumio[1014]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":27456,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"843 Kbps","isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"} Oct 26 18:10:09 volumio volumio[1014]: verbose: CURRENT POSITION 0 Oct 26 18:10:09 volumio volumio[1014]: info: CoreStateMachine::syncState stateService pause Oct 26 18:10:09 volumio volumio[1014]: info: CoreStateMachine::syncState currentStatus pause Oct 26 18:10:09 volumio volumio[1014]: info: CoreStateMachine::pushState Oct 26 18:10:09 volumio volumio[1014]: info: CorePlayQueue::getTrack 0 Oct 26 18:10:09 volumio volumio[1014]: info: CoreCommandRouter::volumioPushState Oct 26 18:10:09 volumio volumio[1014]: info: CoreStateMachine::stPlaybackTimer Oct 26 18:10:09 volumio volumio[1014]: info: ControllerMpd::pushState Oct 26 18:10:09 volumio volumio[1014]: info: CoreCommandRouter::servicePushState Oct 26 18:10:09 volumio volumio[1014]: info: CorePlayQueue::getTrack 0 Oct 26 18:10:09 volumio volumio[1014]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":27456,"duration":337,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"843 Kbps","isStreaming":false,"title":"乌兰巴托的夜晚","artist":"赵鹏","album":"中国最好声音:人声测试专用大碟","uri":"USB/赵鹏 - 乌兰巴托的夜晚.flac","trackType":"flac"} Oct 26 18:10:09 volumio volumio[1014]: verbose: CURRENT POSITION 0 Oct 26 18:10:09 volumio volumio[1014]: info: CoreStateMachine::syncState stateService pause Oct 26 18:10:09 volumio volumio[1014]: info: CoreStateMachine::syncState currentStatus pause Oct 26 18:10:09 volumio volumio[1014]: info: CoreStateMachine::pushState Oct 26 18:10:09 volumio volumio[1014]: info: CorePlayQueue::getTrack 0 Oct 26 18:10:09 volumio volumio[1014]: info: CoreCommandRouter::volumioPushState Oct 26 18:10:09 volumio volumio[1014]: info: CoreStateMachine::stPlaybackTimer Oct 26 18:10:09 volumio volumio[1014]: info: ------------------------------ 68ms Oct 26 18:10:09 volumio volumio[1014]: info: ------------------------------ 67ms Oct 26 18:10:09 volumio volumio[1014]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 26 18:10:09 volumio volumio[1014]: Error: ENOTDIR: not a directory, scandir '/tmp/peppyrunning' Oct 26 18:10:09 volumio volumio[1014]: at Object.readdirSync (fs.js:1048:3) Oct 26 18:10:09 volumio volumio[1014]: at rmkidsSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:349:11) Oct 26 18:10:09 volumio volumio[1014]: at rmdirSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:342:7) Oct 26 18:10:09 volumio volumio[1014]: at Function.rimrafSync [as sync] (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:319:62) Oct 26 18:10:09 volumio volumio[1014]: at Object.removeSync (/data/plugins/user_interface/peppy_screensaver/node_modules/fs-extra/lib/remove/index.js:4:17) Oct 26 18:10:09 volumio volumio[1014]: at Socket. (/data/plugins/user_interface/peppy_screensaver/index.js:225:48) Oct 26 18:10:09 volumio volumio[1014]: at Socket.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Oct 26 18:10:09 volumio volumio[1014]: at Socket.onevent (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:278:10) Oct 26 18:10:09 volumio volumio[1014]: at Socket.onpacket (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:236:12) Oct 26 18:10:09 volumio volumio[1014]: at Manager. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Oct 26 18:10:09 volumio volumio[1014]: at Manager.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Oct 26 18:10:09 volumio volumio[1014]: at Manager.ondecoded (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:349:8) Oct 26 18:10:09 volumio volumio[1014]: at Decoder. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Oct 26 18:10:09 volumio volumio[1014]: at Decoder.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Oct 26 18:10:09 volumio volumio[1014]: at Decoder.add (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-parser/index.js:251:12) Oct 26 18:10:09 volumio volumio[1014]: at Manager.ondata (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:339:16) { Oct 26 18:10:09 volumio volumio[1014]: errno: -20, Oct 26 18:10:09 volumio volumio[1014]: syscall: 'scandir', Oct 26 18:10:09 volumio volumio[1014]: code: 'ENOTDIR', Oct 26 18:10:09 volumio volumio[1014]: path: '/tmp/peppyrunning' Oct 26 18:10:09 volumio volumio[1014]: } Oct 26 18:10:09 volumio volumio[1014]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 26 18:10:10 volumio sudo[2379]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-26 18:09 Oct 26 18:10:10 volumio sudo[2379]: 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"