12月 23 15:54:03 raspi volumio[3856]: info: CoreCommandRouter::volumioGetState 12月 23 15:54:03 raspi volumio[3856]: info: CorePlayQueue::getTrack 0 12月 23 15:54:04 raspi volumio[3856]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium 12月 23 15:54:04 raspi volumio[3856]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium 12月 23 15:54:04 raspi volumio[3856]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium 12月 23 15:54:04 raspi volumio[3856]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium 12月 23 15:54:04 raspi volumio[3856]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium 12月 23 15:54:04 raspi volumio[3856]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium 12月 23 15:54:04 raspi volumio[3856]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium 12月 23 15:54:04 raspi volumio[3856]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium 12月 23 15:54:04 raspi volumio[3856]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium 12月 23 15:54:04 raspi volumio[3856]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium 12月 23 15:54:04 raspi volumio[3856]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium 12月 23 15:54:04 raspi volumio[3856]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar 12月 23 15:54:04 raspi volumio[3856]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar 12月 23 15:54:04 raspi volumio[3856]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar 12月 23 15:54:04 raspi volumio[3856]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar 12月 23 15:54:04 raspi volumio[3856]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar 12月 23 15:54:04 raspi volumio[3856]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar 12月 23 15:54:04 raspi volumio[3856]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar 12月 23 15:54:04 raspi volumio[3856]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar 12月 23 15:54:04 raspi volumio[3856]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar 12月 23 15:54:04 raspi volumio[3856]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar 12月 23 15:54:04 raspi volumio[3856]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar 12月 23 15:54:04 raspi volumio[3856]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso 12月 23 15:54:04 raspi volumio[3856]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso 12月 23 15:54:04 raspi volumio[3856]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso 12月 23 15:54:04 raspi volumio[3856]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso 12月 23 15:54:04 raspi volumio[3856]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso 12月 23 15:54:04 raspi volumio[3856]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso 12月 23 15:54:04 raspi volumio[3856]: info: Adding plugin bluetooth to MyMusic Plugins 12月 23 15:54:04 raspi volumio[3856]: info: Adding plugin multiroom to MyMusic Plugins 12月 23 15:54:04 raspi volumio[3856]: info: Adding plugin metavolumio to MyMusic Plugins 12月 23 15:54:04 raspi volumio[3856]: info: Adding plugin cd_controller to MyMusic Plugins 12月 23 15:54:04 raspi volumio[3856]: info: Adding plugin qobuzconnect to MyMusic Plugins 12月 23 15:54:04 raspi volumio[3856]: info: Adding plugin smart_inputs to MyMusic Plugins 12月 23 15:54:04 raspi volumio[3856]: info: Adding plugin tidalconnect to MyMusic Plugins 12月 23 15:54:04 raspi volumio[3856]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... 12月 23 15:54:05 raspi volumio[3856]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded 12月 23 15:54:05 raspi volumio[3856]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio 12月 23 15:54:05 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam 12月 23 15:54:05 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam 12月 23 15:54:05 raspi volumio[3856]: info: Starting MyVolumio Remote Streaming Endpoints 12月 23 15:54:05 raspi volumio[3856]: info: MyVolumio login type: Token 12月 23 15:54:05 raspi volumio[3856]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started 12月 23 15:54:05 raspi volumio[3856]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... 12月 23 15:54:05 raspi volumio[3856]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded 12月 23 15:54:05 raspi volumio[3856]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services 12月 23 15:54:05 raspi volumio[3856]: info: Streaming services startup 12月 23 15:54:05 raspi volumio[3856]: info: Starting Streaming Daemon 12月 23 15:54:05 raspi sudo[4233]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service 12月 23 15:54:05 raspi sudo[4233]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 12月 23 15:54:05 raspi volumio[3856]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started 12月 23 15:54:05 raspi sudo[4233]: pam_unix(sudo:session): session closed for user root 12月 23 15:54:06 raspi volumio[3856]: error: Cannot start Volumio Streaming Daemon 12月 23 15:54:06 raspi volumio[3856]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service 12月 23 15:54:06 raspi volumio[3856]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. 12月 23 15:54:06 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus 12月 23 15:54:06 raspi volumio[3856]: error: MyVolumio Custom Token format not valid, refreshing it 12月 23 15:54:07 raspi volumio[3856]: info: MyVolumio login type: Token 12月 23 15:54:07 raspi volumio[3856]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN 12月 23 15:54:08 raspi volumio[3856]: info: MyVolumio token set successfully 12月 23 15:54:08 raspi volumio[3856]: info: MYVOLUMIO: Adding device 12月 23 15:54:08 raspi volumio[3856]: info: MYVOLUMIO: Evaluating Server 12月 23 15:54:08 raspi volumio[3856]: info: MyVolumio status changed 12月 23 15:54:08 raspi volumio[3856]: info: Streaming services startup 12月 23 15:54:08 raspi volumio[3856]: info: Starting Streaming Daemon 12月 23 15:54:08 raspi volumio[3856]: info: Removing browser output: myVolumio user plan is not superstar 12月 23 15:54:08 raspi volumio[3856]: info: Removing audio output: 12月 23 15:54:08 raspi volumio[3856]: info: Stoppping Tunnel 1 12月 23 15:54:08 raspi sudo[4274]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service 12月 23 15:54:08 raspi sudo[4274]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 12月 23 15:54:08 raspi sudo[4276]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service 12月 23 15:54:08 raspi sudo[4276]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 12月 23 15:54:08 raspi volumio[3856]: info: CoreCommandRouter::Close All Modals sent 12月 23 15:54:08 raspi systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. 12月 23 15:54:08 raspi systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. 12月 23 15:54:08 raspi sudo[4274]: pam_unix(sudo:session): session closed for user root 12月 23 15:54:08 raspi volumio[3856]: error: Cannot start Volumio Streaming Daemon 12月 23 15:54:08 raspi volumio[3856]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service 12月 23 15:54:08 raspi volumio[3856]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. 12月 23 15:54:08 raspi systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. 12月 23 15:54:08 raspi systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. 12月 23 15:54:08 raspi systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. 12月 23 15:54:08 raspi systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. 12月 23 15:54:08 raspi systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. 12月 23 15:54:08 raspi systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. 12月 23 15:54:08 raspi sudo[4276]: pam_unix(sudo:session): session closed for user root 12月 23 15:54:08 raspi volumio[3856]: info: Remote SSH Stopped 12月 23 15:54:08 raspi volumio[3856]: info: Setting Geolocation for MyVolumio to as1 12月 23 15:54:08 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam 12月 23 15:54:08 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam 12月 23 15:54:08 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam 12月 23 15:54:09 raspi volumio[3856]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} 12月 23 15:54:09 raspi volumio[3856]: info: Updating MyVolumio device info 12月 23 15:54:09 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam 12月 23 15:54:09 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam 12月 23 15:54:09 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam 12月 23 15:54:09 raspi volumio[3856]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} 12月 23 15:54:12 raspi volumio[3856]: info: Enabling plugin peppymeterbasic 12月 23 15:54:12 raspi volumio[3856]: info: Loading plugin "peppymeterbasic"... 12月 23 15:54:12 raspi volumio[3856]: info: Preparing to generate the ALSA configuration file 12月 23 15:54:12 raspi volumio[3856]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf 12月 23 15:54:12 raspi volumio[3856]: info: Reading ALSA contributions from plugins. 12月 23 15:54:12 raspi volumio[3856]: info: Asound.conf file written 12月 23 15:54:12 raspi sudo[4292]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf 12月 23 15:54:12 raspi sudo[4292]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 12月 23 15:54:12 raspi sudo[4292]: pam_unix(sudo:session): session closed for user root 12月 23 15:54:12 raspi volumio[3856]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 12月 23 15:54:12 raspi volumio[3856]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 12月 23 15:54:12 raspi volumio[3856]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:7 use case configuration -2 12月 23 15:54:12 raspi volumio[3856]: No state is present for card Dummy 12月 23 15:54:12 raspi volumio[3856]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:7 use case configuration -2 12月 23 15:54:12 raspi volumio[3856]: Found hardware: "Dummy" "Dummy Mixer" "" "" "" 12月 23 15:54:12 raspi volumio[3856]: Hardware is initialized using a generic method 12月 23 15:54:12 raspi volumio[3856]: No state is present for card Dummy 12月 23 15:54:12 raspi volumio[3856]: info: Output device has changed, restarting MPD 12月 23 15:54:12 raspi volumio[3856]: info: Output device has changed, restarting Shairport Sync 12月 23 15:54:12 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 12月 23 15:54:12 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam 12月 23 15:54:12 raspi sudo[4298]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf 12月 23 15:54:12 raspi sudo[4298]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 12月 23 15:54:12 raspi sudo[4298]: pam_unix(sudo:session): session closed for user root 12月 23 15:54:12 raspi sudo[4302]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service 12月 23 15:54:12 raspi volumio[3856]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 12月 23 15:54:12 raspi sudo[4302]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 12月 23 15:54:12 raspi volumio[3856]: info: PLUGIN START: peppymeterbasic 12月 23 15:54:12 raspi systemd[1]: Stopping mpd.service - Music Player Daemon... 12月 23 15:54:12 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile 12月 23 15:54:12 raspi volumio[3856]: info: Preparing to generate the ALSA configuration file 12月 23 15:54:12 raspi sudo[4308]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd_aloop index=7 pcm_substreams=2 12月 23 15:54:12 raspi sudo[4308]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 12月 23 15:54:12 raspi systemd[1]: mpd.service: Deactivated successfully. 12月 23 15:54:12 raspi systemd[1]: Stopped mpd.service - Music Player Daemon. 12月 23 15:54:12 raspi systemd[1]: mpd.service: Consumed 2.137s CPU time. 12月 23 15:54:12 raspi systemd[1]: mpd.socket: Deactivated successfully. 12月 23 15:54:12 raspi systemd[1]: Closed mpd.socket - Music Player Daemon Socket. 12月 23 15:54:12 raspi systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... 12月 23 15:54:12 raspi kernel: snd_aloop snd_aloop.0: cannot find the slot for index 7 (range 0-7), error: -16 12月 23 15:54:12 raspi kernel: snd_aloop snd_aloop.0: probe with driver snd_aloop failed with error -16 12月 23 15:54:12 raspi kernel: aloop: No loopback enabled 12月 23 15:54:12 raspi volumio[3856]: info: Done. 12月 23 15:54:12 raspi volumio[3856]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf 12月 23 15:54:12 raspi volumio[3856]: info: Reading ALSA contributions from plugins. 12月 23 15:54:12 raspi volumio[3856]: info: MPD Permissions set 12月 23 15:54:12 raspi volumio[3856]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 12月 23 15:54:12 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 12月 23 15:54:12 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 12月 23 15:54:12 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 12月 23 15:54:12 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 12月 23 15:54:12 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 12月 23 15:54:12 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 12月 23 15:54:12 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 12月 23 15:54:12 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 12月 23 15:54:12 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam 12月 23 15:54:12 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 12月 23 15:54:12 raspi systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. 12月 23 15:54:12 raspi systemd[1]: Starting mpd.service - Music Player Daemon... 12月 23 15:54:12 raspi volumio[3856]: info: Starting Shairport Sync 12月 23 15:54:12 raspi volumio[3856]: info: Asound.conf file unchanged, so no further update is needed 12月 23 15:54:12 raspi volumio[3856]: info: Output device has changed, restarting MPD 12月 23 15:54:12 raspi sudo[4321]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync 12月 23 15:54:12 raspi sudo[4321]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 12月 23 15:54:12 raspi volumio[3856]: info: Output device has changed, restarting Shairport Sync 12月 23 15:54:12 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 12月 23 15:54:12 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam 12月 23 15:54:12 raspi sudo[4326]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service 12月 23 15:54:12 raspi sudo[4326]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 12月 23 15:54:12 raspi sudo[4324]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf 12月 23 15:54:12 raspi sudo[4324]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 12月 23 15:54:12 raspi volumio[3856]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 12月 23 15:54:12 raspi sudo[4324]: pam_unix(sudo:session): session closed for user root 12月 23 15:54:12 raspi volumio[3856]: info: MPD Permissions set 12月 23 15:54:12 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 12月 23 15:54:12 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 12月 23 15:54:12 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 12月 23 15:54:12 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 12月 23 15:54:12 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 12月 23 15:54:12 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 12月 23 15:54:12 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 12月 23 15:54:12 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 12月 23 15:54:12 raspi systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... 12月 23 15:54:12 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam 12月 23 15:54:12 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 12月 23 15:54:12 raspi systemd[1]: shairport-sync.service: Deactivated successfully. 12月 23 15:54:12 raspi systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. 12月 23 15:54:12 raspi systemd[1]: shairport-sync.service: Consumed 1.597s CPU time. 12月 23 15:54:12 raspi systemd[1]: mpd.service: Deactivated successfully. 12月 23 15:54:12 raspi systemd[1]: Stopped mpd.service - Music Player Daemon. 12月 23 15:54:12 raspi systemd[1]: mpd.socket: Deactivated successfully. 12月 23 15:54:12 raspi sudo[4308]: pam_unix(sudo:session): session closed for user root 12月 23 15:54:12 raspi systemd[1]: Closed mpd.socket - Music Player Daemon Socket. 12月 23 15:54:12 raspi systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... 12月 23 15:54:12 raspi systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. 12月 23 15:54:12 raspi volumio[3856]: error: failed to load snd_dummy: Error: Command failed: /usr/bin/sudo /sbin/modprobe snd_aloop index=7 pcm_substreams=2 12月 23 15:54:12 raspi volumio[3856]: modprobe: ERROR: could not insert 'snd_aloop': No such device 12月 23 15:54:12 raspi volumio[3856]: info: Starting Shairport Sync 12月 23 15:54:12 raspi systemd[1]: Starting mpd.service - Music Player Daemon... 12月 23 15:54:12 raspi systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. 12月 23 15:54:12 raspi sudo[4321]: pam_unix(sudo:session): session closed for user root 12月 23 15:54:12 raspi sudo[4345]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync 12月 23 15:54:12 raspi sudo[4345]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 12月 23 15:54:12 raspi volumio[3856]: info: Shairport-Sync Started 12月 23 15:54:12 raspi systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... 12月 23 15:54:12 raspi systemd[1]: shairport-sync.service: Deactivated successfully. 12月 23 15:54:12 raspi systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. 12月 23 15:54:12 raspi systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. 12月 23 15:54:12 raspi sudo[4345]: pam_unix(sudo:session): session closed for user root 12月 23 15:54:12 raspi volumio[3856]: info: Shairport-Sync Started 12月 23 15:54:13 raspi sudo[4342]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log 12月 23 15:54:13 raspi sudo[4342]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) 12月 23 15:54:13 raspi sudo[4342]: pam_unix(sudo:session): session closed for user root 12月 23 15:54:13 raspi volumio[3856]: info: MYVOLUMIO: Adding device 12月 23 15:54:13 raspi volumio[3856]: info: MYVOLUMIO: Evaluating Server 12月 23 15:54:13 raspi volumio[3856]: info: CoreCommandRouter::getUIConfigOnPlugin 12月 23 15:54:13 raspi volumio[3856]: info: Setting Geolocation for MyVolumio to as1 12月 23 15:54:13 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam 12月 23 15:54:13 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam 12月 23 15:54:13 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam 12月 23 15:54:14 raspi mpd[4363]: 2025-12-23T15:54:14 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg 12月 23 15:54:14 raspi systemd[1]: Started mpd.service - Music Player Daemon. 12月 23 15:54:14 raspi sudo[4302]: pam_unix(sudo:session): session closed for user root 12月 23 15:54:14 raspi sudo[4326]: pam_unix(sudo:session): session closed for user root 12月 23 15:54:14 raspi volumio[3856]: error: MPD error: The expression evaluated to a falsy value: 12月 23 15:54:14 raspi volumio[3856]: assert.ok(self.idling) 12月 23 15:54:14 raspi volumio[3856]: error: The expression evaluated to a falsy value: 12月 23 15:54:14 raspi volumio[3856]: assert.ok(self.idling) 12月 23 15:54:14 raspi volumio[3856]: error: updateQueue error: null 12月 23 15:54:14 raspi volumio[3856]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} 12月 23 15:54:14 raspi volumio[3856]: info: Updating MyVolumio device info 12月 23 15:54:14 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam 12月 23 15:54:14 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam 12月 23 15:54:14 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam 12月 23 15:54:15 raspi volumio[3856]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} 12月 23 15:54:17 raspi sudo[4386]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service 12月 23 15:54:17 raspi sudo[4386]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 12月 23 15:54:17 raspi sudo[4388]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service 12月 23 15:54:17 raspi sudo[4388]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 12月 23 15:54:17 raspi systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... 12月 23 15:54:17 raspi systemd[1]: mpd_monitor.service: Deactivated successfully. 12月 23 15:54:17 raspi systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. 12月 23 15:54:17 raspi systemd[1]: Started mpd_monitor.service - MPD Monitor Service. 12月 23 15:54:17 raspi sudo[4388]: pam_unix(sudo:session): session closed for user root 12月 23 15:54:17 raspi sudo[4386]: pam_unix(sudo:session): session closed for user root 12月 23 15:54:17 raspi volumio[3856]: info: Successfully started MPD Monitor 12月 23 15:54:17 raspi mpd_monitor.sh[4391]: MPD Monitor Service: Starting MPD Monitor Service 12月 23 15:54:17 raspi volumio[3856]: info: Successfully started MPD Monitor 12月 23 15:54:18 raspi volumio[3856]: info: CoreCommandRouter::volumioGetState 12月 23 15:54:18 raspi volumio[3856]: info: CorePlayQueue::getTrack 0 12月 23 15:54:18 raspi volumio[3856]: info: CoreCommandRouter::volumioGetQueue 12月 23 15:54:18 raspi volumio[3856]: info: CoreStateMachine::getQueue 12月 23 15:54:18 raspi volumio[3856]: info: CorePlayQueue::getQueue 12月 23 15:54:20 raspi volumio[3856]: info: Preload queue cleared 12月 23 15:54:20 raspi volumio[3856]: info: CoreCommandRouter::volumioReplaceandPlayItems 12月 23 15:54:20 raspi volumio[3856]: info: CoreStateMachine::ClearQueue 12月 23 15:54:20 raspi volumio[3856]: info: CoreStateMachine::stop 12月 23 15:54:20 raspi volumio[3856]: info: CoreStateMachine::setConsumeUpdateService undefined 12月 23 15:54:20 raspi volumio[3856]: info: CorePlayQueue::clearPlayQueue 12月 23 15:54:20 raspi volumio[3856]: info: CorePlayQueue::saveQueue 12月 23 15:54:20 raspi volumio[3856]: info: CoreCommandRouter::volumioPushQueue 12月 23 15:54:20 raspi volumio[3856]: info: CoreStateMachine::addQueueItems 12月 23 15:54:20 raspi volumio[3856]: info: CorePlayQueue::addQueueItems 12月 23 15:54:20 raspi volumio[3856]: info: Preload queue cleared 12月 23 15:54:20 raspi volumio[3856]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s333272 12月 23 15:54:20 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri 12月 23 15:54:20 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings 12月 23 15:54:20 raspi volumio[3856]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 12月 23 15:54:20 raspi volumio[3856]: info: CoreCommandRouter::volumioGetState 12月 23 15:54:20 raspi volumio[3856]: info: CorePlayQueue::getTrack 0 12月 23 15:54:20 raspi volumio[3856]: info: CoreCommandRouter::volumioPushQueue 12月 23 15:54:20 raspi volumio[3856]: info: CorePlayQueue::saveQueue 12月 23 15:54:20 raspi volumio[3856]: info: CoreStateMachine::updateTrackBlock 12月 23 15:54:20 raspi volumio[3856]: info: CorePlayQueue::getTrackBlock 12月 23 15:54:20 raspi volumio[3856]: info: CoreCommandRouter::volumioPlay 12月 23 15:54:20 raspi volumio[3856]: info: CoreStateMachine::play index 0 12月 23 15:54:20 raspi volumio[3856]: info: CoreStateMachine::setConsumeUpdateService undefined 12月 23 15:54:20 raspi volumio[3856]: info: CoreStateMachine::stop 12月 23 15:54:20 raspi volumio[3856]: info: CoreStateMachine::setConsumeUpdateService undefined 12月 23 15:54:20 raspi volumio[3856]: info: CoreStateMachine::play index undefined 12月 23 15:54:20 raspi volumio[3856]: info: CoreStateMachine::setConsumeUpdateService undefined 12月 23 15:54:20 raspi volumio[3856]: info: CorePlayQueue::getTrack 0 12月 23 15:54:20 raspi volumio[3856]: info: CoreStateMachine::startPlaybackTimer 12月 23 15:54:20 raspi volumio[3856]: info: CorePlayQueue::getTrack 0 12月 23 15:54:20 raspi volumio[3856]: info: [1766472860483] ControllerWebradio::clearAddPlayTrack 12月 23 15:54:20 raspi volumio[3856]: verbose: ControllerMpd::sendMpdCommand stop 12月 23 15:54:20 raspi volumio[3856]: info: sendMpdCommand stop took 4 milliseconds 12月 23 15:54:20 raspi volumio[3856]: verbose: ControllerMpd::sendMpdCommand clear 12月 23 15:54:20 raspi volumio[3856]: info: 12月 23 15:54:20 raspi volumio[3856]: ---------------------------- MPD announces system playlist update 12月 23 15:54:20 raspi volumio[3856]: info: Ignoring MPD Status Update 12月 23 15:54:20 raspi volumio[3856]: info: sendMpdCommand clear took 2 milliseconds 12月 23 15:54:20 raspi volumio[3856]: verbose: ControllerMpd::sendMpdCommand load "http://media-ice.musicradio.com/ClassicFMCalmMP3" 12月 23 15:54:20 raspi volumio[3856]: info: 12月 23 15:54:20 raspi volumio[3856]: ---------------------------- MPD announces system playlist update 12月 23 15:54:20 raspi volumio[3856]: info: Ignoring MPD Status Update 12月 23 15:54:20 raspi volumio[3856]: error: updateQueue error: null 12月 23 15:54:20 raspi volumio[3856]: info: ------------------------------ 4ms 12月 23 15:54:21 raspi volumio[3856]: error: updateQueue error: null 12月 23 15:54:21 raspi volumio[3856]: verbose: ControllerMpd::sendMpdCommand add "http://media-ice.musicradio.com/ClassicFMCalmMP3" 12月 23 15:54:21 raspi volumio[3856]: info: ------------------------------ 766ms 12月 23 15:54:21 raspi volumio[3856]: info: 12月 23 15:54:21 raspi volumio[3856]: ---------------------------- MPD announces system playlist update 12月 23 15:54:21 raspi volumio[3856]: info: Ignoring MPD Status Update 12月 23 15:54:21 raspi volumio[3856]: info: sendMpdCommand add "http://media-ice.musicradio.com/ClassicFMCalmMP3" took 1 milliseconds 12月 23 15:54:21 raspi volumio[3856]: info: CoreStateMachine::setConsumeUpdateService mpd 12月 23 15:54:21 raspi volumio[3856]: verbose: ControllerMpd::sendMpdCommand play 12月 23 15:54:21 raspi volumio[3856]: info: 12月 23 15:54:21 raspi volumio[3856]: ---------------------------- MPD announces system playlist update 12月 23 15:54:21 raspi volumio[3856]: info: Ignoring MPD Status Update 12月 23 15:54:21 raspi volumio[3856]: info: ------------------------------ 3ms 12月 23 15:54:21 raspi volumio[3856]: info: sendMpdCommand play took 2 milliseconds 12月 23 15:54:21 raspi volumio[3856]: info: ------------------------------ 2ms 12月 23 15:54:21 raspi volumio[3856]: info: 12月 23 15:54:21 raspi volumio[3856]: ---------------------------- MPD announces state update: player 12月 23 15:54:21 raspi volumio[3856]: info: ControllerMpd::getState 12月 23 15:54:21 raspi volumio[3856]: verbose: ControllerMpd::sendMpdCommand status 12月 23 15:54:21 raspi volumio[3856]: info: 12月 23 15:54:21 raspi volumio[3856]: ---------------------------- MPD announces state update: player 12月 23 15:54:21 raspi volumio[3856]: info: ControllerMpd::getState 12月 23 15:54:21 raspi volumio[3856]: verbose: ControllerMpd::sendMpdCommand status 12月 23 15:54:21 raspi volumio[3856]: info: 12月 23 15:54:21 raspi volumio[3856]: ---------------------------- MPD announces state update: player 12月 23 15:54:21 raspi volumio[3856]: info: ControllerMpd::getState 12月 23 15:54:21 raspi volumio[3856]: verbose: ControllerMpd::sendMpdCommand status 12月 23 15:54:21 raspi volumio[3856]: info: 12月 23 15:54:21 raspi volumio[3856]: ---------------------------- MPD announces state update: player 12月 23 15:54:21 raspi volumio[3856]: verbose: ControllerMpd::sendMpdCommand clearerror 12月 23 15:54:21 raspi volumio[3856]: info: sendMpdCommand status took 10 milliseconds 12月 23 15:54:21 raspi volumio[3856]: info: ControllerMpd::getState 12月 23 15:54:21 raspi volumio[3856]: verbose: ControllerMpd::sendMpdCommand status 12月 23 15:54:21 raspi volumio[3856]: verbose: ControllerMpd::sendMpdCommand clearerror 12月 23 15:54:21 raspi volumio[3856]: info: sendMpdCommand status took 9 milliseconds 12月 23 15:54:21 raspi volumio[3856]: verbose: ControllerMpd::parseState 12月 23 15:54:21 raspi volumio[3856]: verbose: ControllerMpd::sendMpdCommand playlistinfo 12月 23 15:54:21 raspi volumio[3856]: verbose: ControllerMpd::parseState 12月 23 15:54:21 raspi volumio[3856]: verbose: ControllerMpd::sendMpdCommand playlistinfo 12月 23 15:54:21 raspi volumio[3856]: verbose: ControllerMpd::sendMpdCommand clearerror 12月 23 15:54:21 raspi volumio[3856]: info: sendMpdCommand status took 23 milliseconds 12月 23 15:54:21 raspi volumio[3856]: info: sendMpdCommand clearerror took 16 milliseconds 12月 23 15:54:21 raspi volumio[3856]: info: sendMpdCommand status took 16 milliseconds 12月 23 15:54:21 raspi volumio[3856]: info: sendMpdCommand clearerror took 16 milliseconds 12月 23 15:54:21 raspi volumio[3856]: info: sendMpdCommand playlistinfo took 10 milliseconds 12月 23 15:54:21 raspi volumio[3856]: info: sendMpdCommand playlistinfo took 9 milliseconds 12月 23 15:54:21 raspi volumio[3856]: verbose: ControllerMpd::parseState 12月 23 15:54:21 raspi volumio[3856]: verbose: ControllerMpd::sendMpdCommand playlistinfo 12月 23 15:54:21 raspi volumio[3856]: verbose: ControllerMpd::parseState 12月 23 15:54:21 raspi volumio[3856]: verbose: ControllerMpd::sendMpdCommand playlistinfo 12月 23 15:54:21 raspi volumio[3856]: verbose: ControllerMpd::parseTrackInfo 12月 23 15:54:21 raspi volumio[3856]: verbose: ControllerMpd::parseTrackInfo 12月 23 15:54:21 raspi volumio[3856]: info: ControllerMpd::pushState 12月 23 15:54:21 raspi volumio[3856]: info: CoreCommandRouter::servicePushState 12月 23 15:54:21 raspi volumio[3856]: info: CorePlayQueue::getTrack 0 12月 23 15:54:21 raspi volumio[3856]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ClassicFMCalmMP3","artist":null,"album":null,"uri":"http://media-ice.musicradio.com/ClassicFMCalmMP3","trackType":""} 12月 23 15:54:21 raspi volumio[3856]: verbose: CURRENT POSITION 0 12月 23 15:54:21 raspi volumio[3856]: info: CoreStateMachine::syncState stateService pause 12月 23 15:54:21 raspi volumio[3856]: info: CoreStateMachine::syncState currentStatus stop 12月 23 15:54:21 raspi volumio[3856]: info: CoreStateMachine::pushState 12月 23 15:54:21 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo 12月 23 15:54:21 raspi volumio[3856]: info: CoreCommandRouter::volumioPushState 12月 23 15:54:21 raspi volumio[3856]: info: ControllerMpd::pushState 12月 23 15:54:21 raspi volumio[3856]: info: CoreCommandRouter::servicePushState 12月 23 15:54:21 raspi volumio[3856]: info: CorePlayQueue::getTrack 0 12月 23 15:54:21 raspi volumio[3856]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ClassicFMCalmMP3","artist":null,"album":null,"uri":"http://media-ice.musicradio.com/ClassicFMCalmMP3","trackType":""} 12月 23 15:54:21 raspi volumio[3856]: verbose: CURRENT POSITION 0 12月 23 15:54:21 raspi volumio[3856]: info: CoreStateMachine::syncState stateService pause 12月 23 15:54:21 raspi volumio[3856]: info: CoreStateMachine::syncState currentStatus stop 12月 23 15:54:21 raspi volumio[3856]: info: CoreStateMachine::pushState 12月 23 15:54:21 raspi volumio[3856]: info: CoreCommandRouter::volumioPushState 12月 23 15:54:21 raspi volumio[3856]: info: ------------------------------ 57ms 12月 23 15:54:21 raspi volumio[3856]: info: ------------------------------ 57ms 12月 23 15:54:21 raspi volumio[3856]: info: PeppyMeterBasic ---peppymeterbasic status 12月 23 15:54:21 raspi volumio[3856]: info: PeppyMeterBasic ---peppymeterbasic status undefined 12月 23 15:54:21 raspi volumio[3856]: info: sendMpdCommand clearerror took 47 milliseconds 12月 23 15:54:21 raspi volumio[3856]: info: sendMpdCommand playlistinfo took 45 milliseconds 12月 23 15:54:21 raspi volumio[3856]: info: sendMpdCommand playlistinfo took 45 milliseconds 12月 23 15:54:21 raspi volumio[3856]: verbose: ControllerMpd::parseTrackInfo 12月 23 15:54:21 raspi volumio[3856]: verbose: ControllerMpd::parseTrackInfo 12月 23 15:54:21 raspi volumio[3856]: info: ControllerMpd::pushState 12月 23 15:54:21 raspi volumio[3856]: info: CoreCommandRouter::servicePushState 12月 23 15:54:21 raspi volumio[3856]: info: CorePlayQueue::getTrack 0 12月 23 15:54:21 raspi volumio[3856]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ClassicFMCalmMP3","artist":null,"album":null,"uri":"http://media-ice.musicradio.com/ClassicFMCalmMP3","trackType":""} 12月 23 15:54:21 raspi volumio[3856]: verbose: CURRENT POSITION 0 12月 23 15:54:21 raspi volumio[3856]: info: CoreStateMachine::syncState stateService pause 12月 23 15:54:21 raspi volumio[3856]: info: CoreStateMachine::syncState currentStatus stop 12月 23 15:54:21 raspi volumio[3856]: info: CoreStateMachine::pushState 12月 23 15:54:21 raspi volumio[3856]: info: CoreCommandRouter::volumioPushState 12月 23 15:54:21 raspi volumio[3856]: info: ControllerMpd::pushState 12月 23 15:54:21 raspi volumio[3856]: info: CoreCommandRouter::servicePushState 12月 23 15:54:21 raspi volumio[3856]: info: CorePlayQueue::getTrack 0 12月 23 15:54:21 raspi volumio[3856]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ClassicFMCalmMP3","artist":null,"album":null,"uri":"http://media-ice.musicradio.com/ClassicFMCalmMP3","trackType":""} 12月 23 15:54:21 raspi volumio[3856]: verbose: CURRENT POSITION 0 12月 23 15:54:21 raspi volumio[3856]: info: CoreStateMachine::syncState stateService pause 12月 23 15:54:21 raspi volumio[3856]: info: CoreStateMachine::syncState currentStatus stop 12月 23 15:54:21 raspi volumio[3856]: info: CoreStateMachine::pushState 12月 23 15:54:21 raspi volumio[3856]: info: CoreCommandRouter::volumioPushState 12月 23 15:54:21 raspi volumio[3856]: info: ------------------------------ 97ms 12月 23 15:54:21 raspi volumio[3856]: info: ------------------------------ 97ms 12月 23 15:54:21 raspi volumio[3856]: info: PeppyMeterBasic ---peppymeterbasic status undefined 12月 23 15:54:21 raspi volumio[3856]: info: PeppyMeterBasic ---peppymeterbasic status undefined 12月 23 15:54:23 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion 12月 23 15:54:23 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam 12月 23 15:54:23 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled 12月 23 15:54:23 raspi volumio-remote-updater[812]: Test mode disabled 12月 23 15:54:23 raspi volumio-remote-updater[812]: Alpha mode disabled 12月 23 15:54:23 raspi volumio-remote-updater[812]: Alpha legacy test mode disabled 12月 23 15:54:23 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled 12月 23 15:54:24 raspi volumio[3856]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} 12月 23 15:54:24 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache 12月 23 15:54:25 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 12月 23 15:54:25 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 12月 23 15:54:25 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam 12月 23 15:54:25 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam 12月 23 15:54:25 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject 12月 23 15:54:25 raspi volumio[3856]: info: CoreCommandRouter::volumioGetBrowseSources 12月 23 15:54:25 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion 12月 23 15:54:25 raspi volumio[3856]: error: MyVolumio Plugin failed to authenticate in a timely fashion 12月 23 15:54:25 raspi volumio[3856]: info: Completed starting MyVolumio Plugin 12月 23 15:54:25 raspi volumio[3856]: [Metrics] CommandRouter: 39s 609.27ms 12月 23 15:54:25 raspi volumio[3856]: info: CoreCommandRouter::volumiosetStartupVolume 12月 23 15:54:25 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 12月 23 15:54:25 raspi volumio[3856]: info: VolumeController:: Setting startup Volume 100 12月 23 15:54:25 raspi volumio[3856]: info: VolumeController::SetAlsaVolume100 12月 23 15:54:25 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam 12月 23 15:54:25 raspi volumio[3856]: info: CoreCommandRouter::Close All Modals sent 12月 23 15:54:25 raspi volumio[3856]: info: CoreCommandRouter::Close All Modals sent 12月 23 15:54:25 raspi volumio[3856]: info: CoreStateMachine::pushState 12月 23 15:54:25 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo 12月 23 15:54:25 raspi volumio[3856]: info: CoreCommandRouter::volumioPushState 12月 23 15:54:25 raspi volumio[3856]: info: PeppyMeterBasic ---peppymeterbasic status 12月 23 15:54:25 raspi volumio[3856]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav 12月 23 15:54:25 raspi volumio[3856]: ALSA lib confmisc.c:165:(snd_config_get_card) Cannot get card index for Loopback 12月 23 15:54:25 raspi volumio[3856]: aplay: main:831: audio open error: No such device 12月 23 15:54:26 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable 12月 23 15:54:26 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus 12月 23 15:54:26 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: raat , onStop 12月 23 15:54:26 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect 12月 23 15:54:31 raspi volumio[3856]: info: CoreCommandRouter::volumioGetState 12月 23 15:54:32 raspi volumio[3856]: info: BOOT COMPLETED 12月 23 15:54:59 raspi kernel: usb 1-1.3: new high-speed USB device number 4 using xhci_hcd 12月 23 15:54:59 raspi kernel: usb 1-1.3: New USB device found, idVendor=3255, idProduct=0008, bcdDevice= 0.0b 12月 23 15:54:59 raspi kernel: usb 1-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=0 12月 23 15:54:59 raspi kernel: usb 1-1.3: Product: USB D/A Converter DA-07X 12月 23 15:54:59 raspi kernel: usb 1-1.3: Manufacturer: Luxman 12月 23 15:54:59 raspi kernel: hid-generic 0003:3255:0008.0002: hiddev96,hidraw1: USB HID v1.10 Device [Luxman USB D/A Converter DA-07X] on usb-0000:01:00.0-1.3/input2 12月 23 15:54:59 raspi kernel: hid-generic 0003:3255:0008.0003: hiddev97,hidraw2: USB HID v1.10 Device [Luxman USB D/A Converter DA-07X] on usb-0000:01:00.0-1.3/input3 12月 23 15:54:59 raspi kernel: usbcore: registered new interface driver snd-usb-audio 12月 23 15:54:59 raspi volumio[3856]: info: 12月 23 15:54:59 raspi volumio[3856]: ---------------------------- USB Audio Device Attached 12月 23 15:54:59 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach 12月 23 15:54:59 raspi volumio[3856]: info: CoreCommandRouter::Close All Modals sent 12月 23 15:54:59 raspi volumio[3856]: info: Preparing to save Alsa Options, stopping services first 12月 23 15:54:59 raspi volumio[3856]: info: CoreCommandRouter::volumioGetState 12月 23 15:54:59 raspi volumio[3856]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"USB D/A Converter DA-07X","alsacard":"DA07X"},"i2s":false} 12月 23 15:54:59 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus 12月 23 15:54:59 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus 12月 23 15:54:59 raspi volumio[3856]: info: Setting mixer PCM for card USB D/A Converter DA-07X 12月 23 15:54:59 raspi volumio[3856]: info: CoreCommandRouter::volumioUpdateVolumeSettings 12月 23 15:54:59 raspi volumio[3856]: info: Updating Volume Controller Parameters: Device: 5 Name: USB D/A Converter DA-07X Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 12月 23 15:54:59 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume 12月 23 15:54:59 raspi volumio[3856]: info: Disabling external Volume Control 12月 23 15:54:59 raspi volumio[3856]: info: CoreCommandRouter::getUIConfigOnPlugin 12月 23 15:54:59 raspi volumio[3856]: info: Preparing to generate the ALSA configuration file 12月 23 15:54:59 raspi volumio[3856]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf 12月 23 15:54:59 raspi volumio[3856]: info: Reading ALSA contributions from plugins. 12月 23 15:54:59 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions 12月 23 15:54:59 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam 12月 23 15:54:59 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus 12月 23 15:54:59 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam 12月 23 15:54:59 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam 12月 23 15:54:59 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam 12月 23 15:54:59 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam 12月 23 15:54:59 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam 12月 23 15:54:59 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam 12月 23 15:54:59 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam 12月 23 15:54:59 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode 12月 23 15:54:59 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus 12月 23 15:54:59 raspi volumio[3856]: info: VolumeController:: Volume=100 Mute =false 12月 23 15:54:59 raspi volumio[3856]: info: CoreStateMachine::pushState 12月 23 15:54:59 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo 12月 23 15:54:59 raspi volumio[3856]: info: CoreCommandRouter::volumioPushState 12月 23 15:54:59 raspi volumio[3856]: info: PeppyMeterBasic ---peppymeterbasic status 12月 23 15:54:59 raspi volumio[3856]: info: Asound.conf file unchanged, so no further update is needed 12月 23 15:54:59 raspi volumio[3856]: info: Output device has changed, restarting MPD 12月 23 15:54:59 raspi volumio[3856]: info: Output device has changed, restarting Shairport Sync 12月 23 15:54:59 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 12月 23 15:54:59 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam 12月 23 15:54:59 raspi sudo[4556]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service 12月 23 15:54:59 raspi sudo[4555]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf 12月 23 15:54:59 raspi sudo[4555]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 12月 23 15:54:59 raspi sudo[4556]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 12月 23 15:54:59 raspi sudo[4555]: pam_unix(sudo:session): session closed for user root 12月 23 15:54:59 raspi volumio[3856]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 12月 23 15:54:59 raspi volumio[3856]: info: MPD Permissions set 12月 23 15:54:59 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 12月 23 15:54:59 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 12月 23 15:54:59 raspi systemd[1]: Stopping mpd.service - Music Player Daemon... 12月 23 15:54:59 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 12月 23 15:54:59 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 12月 23 15:54:59 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 12月 23 15:54:59 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 12月 23 15:54:59 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 12月 23 15:54:59 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 12月 23 15:54:59 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam 12月 23 15:54:59 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 12月 23 15:54:59 raspi volumio[3856]: info: Starting Shairport Sync 12月 23 15:55:00 raspi systemd[1]: mpd.service: Deactivated successfully. 12月 23 15:55:00 raspi systemd[1]: Stopped mpd.service - Music Player Daemon. 12月 23 15:55:00 raspi sudo[4569]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync 12月 23 15:55:00 raspi systemd[1]: mpd.service: Consumed 1.913s CPU time. 12月 23 15:55:00 raspi sudo[4569]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 12月 23 15:55:00 raspi systemd[1]: mpd.socket: Deactivated successfully. 12月 23 15:55:00 raspi systemd[1]: Closed mpd.socket - Music Player Daemon Socket. 12月 23 15:55:00 raspi systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... 12月 23 15:55:00 raspi systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. 12月 23 15:55:00 raspi systemd[1]: Starting mpd.service - Music Player Daemon... 12月 23 15:55:00 raspi systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... 12月 23 15:55:00 raspi systemd[1]: shairport-sync.service: Deactivated successfully. 12月 23 15:55:00 raspi systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. 12月 23 15:55:00 raspi systemd[1]: shairport-sync.service: Consumed 1.698s CPU time. 12月 23 15:55:00 raspi systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. 12月 23 15:55:00 raspi sudo[4569]: pam_unix(sudo:session): session closed for user root 12月 23 15:55:00 raspi volumio[3856]: info: Shairport-Sync Started 12月 23 15:55:00 raspi sudo[4572]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log 12月 23 15:55:00 raspi sudo[4572]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) 12月 23 15:55:00 raspi sudo[4572]: pam_unix(sudo:session): session closed for user root 12月 23 15:55:00 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat 12月 23 15:55:01 raspi mpd[4589]: 2025-12-23T15:55:01 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg 12月 23 15:55:01 raspi systemd[1]: Started mpd.service - Music Player Daemon. 12月 23 15:55:01 raspi sudo[4556]: pam_unix(sudo:session): session closed for user root 12月 23 15:55:01 raspi volumio[3856]: error: updateQueue error: null 12月 23 15:55:03 raspi volumio[3856]: info: CoreCommandRouter::volumioRetrieveVolumeLevels 12月 23 15:55:03 raspi volumio[3856]: info: CoreStateMachine::getcurrentVolume 12月 23 15:55:03 raspi volumio[3856]: info: CoreCommandRouter::volumioRetrievevolume 12月 23 15:55:03 raspi volumio[3856]: info: VolumeController:: Volume=100 Mute =false 12月 23 15:55:03 raspi volumio[3856]: info: CoreStateMachine::pushState 12月 23 15:55:03 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo 12月 23 15:55:03 raspi volumio[3856]: info: CoreCommandRouter::volumioPushState 12月 23 15:55:03 raspi volumio[3856]: info: CoreStateMachine::updateTrackBlock 12月 23 15:55:03 raspi volumio[3856]: info: CorePlayQueue::getTrackBlock 12月 23 15:55:03 raspi volumio[3856]: info: CoreCommandRouter::volumioRetrievevolume 12月 23 15:55:03 raspi volumio[3856]: info: PeppyMeterBasic ---peppymeterbasic status 12月 23 15:55:03 raspi volumio[3856]: info: VolumeController:: Volume=100 Mute =false 12月 23 15:55:03 raspi volumio[3856]: info: CoreStateMachine::pushState 12月 23 15:55:03 raspi volumio[3856]: info: CoreCommandRouter::volumioPushState 12月 23 15:55:03 raspi volumio[3856]: info: PeppyMeterBasic ---peppymeterbasic status undefined 12月 23 15:55:04 raspi sudo[4598]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service 12月 23 15:55:04 raspi sudo[4598]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 12月 23 15:55:04 raspi systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... 12月 23 15:55:04 raspi systemd[1]: mpd_monitor.service: Deactivated successfully. 12月 23 15:55:04 raspi systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. 12月 23 15:55:04 raspi systemd[1]: Started mpd_monitor.service - MPD Monitor Service. 12月 23 15:55:04 raspi sudo[4598]: pam_unix(sudo:session): session closed for user root 12月 23 15:55:04 raspi mpd_monitor.sh[4600]: MPD Monitor Service: Starting MPD Monitor Service 12月 23 15:55:04 raspi volumio[3856]: info: Successfully started MPD Monitor 12月 23 15:55:04 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 12月 23 15:55:04 raspi volumio[3856]: info: VolumeController:: Setting startup Volume 100 12月 23 15:55:04 raspi volumio[3856]: info: VolumeController::SetAlsaVolume100 12月 23 15:55:04 raspi volumio[3856]: info: CoreStateMachine::pushState 12月 23 15:55:04 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo 12月 23 15:55:04 raspi volumio[3856]: info: CoreCommandRouter::volumioPushState 12月 23 15:55:04 raspi volumio[3856]: info: PeppyMeterBasic ---peppymeterbasic status 12月 23 15:55:11 raspi volumio[3856]: info: Preload queue cleared 12月 23 15:55:11 raspi volumio[3856]: info: CoreCommandRouter::volumioReplaceandPlayItems 12月 23 15:55:11 raspi volumio[3856]: info: CoreStateMachine::ClearQueue 12月 23 15:55:11 raspi volumio[3856]: info: CoreStateMachine::stop 12月 23 15:55:11 raspi volumio[3856]: info: CoreStateMachine::setConsumeUpdateService undefined 12月 23 15:55:11 raspi volumio[3856]: info: CorePlayQueue::clearPlayQueue 12月 23 15:55:11 raspi volumio[3856]: info: CorePlayQueue::saveQueue 12月 23 15:55:11 raspi volumio[3856]: info: CoreCommandRouter::volumioPushQueue 12月 23 15:55:11 raspi volumio[3856]: info: CoreStateMachine::addQueueItems 12月 23 15:55:11 raspi volumio[3856]: info: CorePlayQueue::addQueueItems 12月 23 15:55:11 raspi volumio[3856]: info: Preload queue cleared 12月 23 15:55:11 raspi volumio[3856]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s333272 12月 23 15:55:11 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri 12月 23 15:55:11 raspi volumio[3856]: info: CoreCommandRouter::volumioPushQueue 12月 23 15:55:11 raspi volumio[3856]: info: CorePlayQueue::saveQueue 12月 23 15:55:11 raspi volumio[3856]: info: CoreStateMachine::updateTrackBlock 12月 23 15:55:11 raspi volumio[3856]: info: CorePlayQueue::getTrackBlock 12月 23 15:55:11 raspi volumio[3856]: info: CoreCommandRouter::volumioPlay 12月 23 15:55:11 raspi volumio[3856]: info: CoreStateMachine::play index 0 12月 23 15:55:11 raspi volumio[3856]: info: CoreStateMachine::setConsumeUpdateService undefined 12月 23 15:55:11 raspi volumio[3856]: info: CoreStateMachine::stop 12月 23 15:55:11 raspi volumio[3856]: info: CoreStateMachine::setConsumeUpdateService undefined 12月 23 15:55:11 raspi volumio[3856]: info: CoreStateMachine::play index undefined 12月 23 15:55:11 raspi volumio[3856]: info: CoreStateMachine::setConsumeUpdateService undefined 12月 23 15:55:11 raspi volumio[3856]: info: CorePlayQueue::getTrack 0 12月 23 15:55:11 raspi volumio[3856]: info: CoreStateMachine::startPlaybackTimer 12月 23 15:55:11 raspi volumio[3856]: info: CorePlayQueue::getTrack 0 12月 23 15:55:11 raspi volumio[3856]: info: [1766472911507] ControllerWebradio::clearAddPlayTrack 12月 23 15:55:11 raspi volumio[3856]: verbose: ControllerMpd::sendMpdCommand stop 12月 23 15:55:11 raspi volumio[3856]: info: sendMpdCommand stop took 2 milliseconds 12月 23 15:55:11 raspi volumio[3856]: verbose: ControllerMpd::sendMpdCommand clear 12月 23 15:55:11 raspi volumio[3856]: info: 12月 23 15:55:11 raspi volumio[3856]: ---------------------------- MPD announces system playlist update 12月 23 15:55:11 raspi volumio[3856]: info: Ignoring MPD Status Update 12月 23 15:55:11 raspi volumio[3856]: info: sendMpdCommand clear took 0 milliseconds 12月 23 15:55:11 raspi volumio[3856]: verbose: ControllerMpd::sendMpdCommand load "http://media-ice.musicradio.com/ClassicFMCalmMP3" 12月 23 15:55:11 raspi volumio[3856]: error: updateQueue error: null 12月 23 15:55:11 raspi volumio[3856]: info: ------------------------------ 2ms 12月 23 15:55:12 raspi volumio[3856]: verbose: ControllerMpd::sendMpdCommand add "http://media-ice.musicradio.com/ClassicFMCalmMP3" 12月 23 15:55:12 raspi volumio[3856]: info: 12月 23 15:55:12 raspi volumio[3856]: ---------------------------- MPD announces system playlist update 12月 23 15:55:12 raspi volumio[3856]: info: Ignoring MPD Status Update 12月 23 15:55:12 raspi volumio[3856]: info: sendMpdCommand add "http://media-ice.musicradio.com/ClassicFMCalmMP3" took 1 milliseconds 12月 23 15:55:12 raspi volumio[3856]: info: CoreStateMachine::setConsumeUpdateService mpd 12月 23 15:55:12 raspi volumio[3856]: verbose: ControllerMpd::sendMpdCommand play 12月 23 15:55:12 raspi volumio[3856]: info: ------------------------------ 2ms 12月 23 15:55:12 raspi volumio[3856]: info: sendMpdCommand play took 1 milliseconds 12月 23 15:55:12 raspi volumio[3856]: info: 12月 23 15:55:12 raspi volumio[3856]: ---------------------------- MPD announces state update: player 12月 23 15:55:12 raspi volumio[3856]: info: ControllerMpd::getState 12月 23 15:55:12 raspi volumio[3856]: verbose: ControllerMpd::sendMpdCommand status 12月 23 15:55:12 raspi volumio[3856]: info: 12月 23 15:55:12 raspi volumio[3856]: ---------------------------- MPD announces state update: player 12月 23 15:55:12 raspi volumio[3856]: verbose: ControllerMpd::sendMpdCommand clearerror 12月 23 15:55:12 raspi volumio[3856]: info: sendMpdCommand status took 4 milliseconds 12月 23 15:55:12 raspi volumio[3856]: info: ControllerMpd::getState 12月 23 15:55:12 raspi volumio[3856]: verbose: ControllerMpd::sendMpdCommand status 12月 23 15:55:12 raspi volumio[3856]: verbose: ControllerMpd::parseState 12月 23 15:55:12 raspi volumio[3856]: verbose: ControllerMpd::sendMpdCommand playlistinfo 12月 23 15:55:12 raspi volumio[3856]: info: sendMpdCommand clearerror took 3 milliseconds 12月 23 15:55:12 raspi volumio[3856]: info: sendMpdCommand status took 5 milliseconds 12月 23 15:55:12 raspi volumio[3856]: info: sendMpdCommand playlistinfo took 5 milliseconds 12月 23 15:55:12 raspi volumio[3856]: verbose: ControllerMpd::parseState 12月 23 15:55:12 raspi volumio[3856]: verbose: ControllerMpd::sendMpdCommand playlistinfo 12月 23 15:55:12 raspi volumio[3856]: verbose: ControllerMpd::parseTrackInfo 12月 23 15:55:12 raspi volumio[3856]: info: ControllerMpd::pushState 12月 23 15:55:12 raspi volumio[3856]: info: CoreCommandRouter::servicePushState 12月 23 15:55:12 raspi volumio[3856]: info: CorePlayQueue::getTrack 0 12月 23 15:55:12 raspi volumio[3856]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ClassicFMCalmMP3","artist":null,"album":null,"uri":"http://media-ice.musicradio.com/ClassicFMCalmMP3","trackType":""} 12月 23 15:55:12 raspi volumio[3856]: verbose: CURRENT POSITION 0 12月 23 15:55:12 raspi volumio[3856]: info: CoreStateMachine::syncState stateService pause 12月 23 15:55:12 raspi volumio[3856]: info: CoreStateMachine::syncState currentStatus stop 12月 23 15:55:12 raspi volumio[3856]: info: CoreStateMachine::pushState 12月 23 15:55:12 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo 12月 23 15:55:12 raspi volumio[3856]: info: CoreCommandRouter::volumioPushState 12月 23 15:55:12 raspi volumio[3856]: info: ------------------------------ 21ms 12月 23 15:55:12 raspi volumio[3856]: info: sendMpdCommand playlistinfo took 10 milliseconds 12月 23 15:55:12 raspi volumio[3856]: verbose: ControllerMpd::parseTrackInfo 12月 23 15:55:12 raspi volumio[3856]: info: ControllerMpd::pushState 12月 23 15:55:12 raspi volumio[3856]: info: CoreCommandRouter::servicePushState 12月 23 15:55:12 raspi volumio[3856]: info: CorePlayQueue::getTrack 0 12月 23 15:55:12 raspi volumio[3856]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ClassicFMCalmMP3","artist":null,"album":null,"uri":"http://media-ice.musicradio.com/ClassicFMCalmMP3","trackType":""} 12月 23 15:55:12 raspi volumio[3856]: verbose: CURRENT POSITION 0 12月 23 15:55:12 raspi volumio[3856]: info: CoreStateMachine::syncState stateService pause 12月 23 15:55:12 raspi volumio[3856]: info: CoreStateMachine::syncState currentStatus stop 12月 23 15:55:12 raspi volumio[3856]: info: CoreStateMachine::pushState 12月 23 15:55:12 raspi volumio[3856]: info: CoreCommandRouter::volumioPushState 12月 23 15:55:12 raspi volumio[3856]: info: ------------------------------ 28ms 12月 23 15:55:12 raspi volumio[3856]: info: PeppyMeterBasic ---peppymeterbasic status 12月 23 15:55:12 raspi volumio[3856]: info: PeppyMeterBasic ---peppymeterbasic status undefined 12月 23 15:55:16 raspi volumio[3856]: info: CoreCommandRouter::volumioGetState 12月 23 15:55:18 raspi volumio[3856]: info: CoreCommandRouter::getUIConfigOnPlugin 12月 23 15:55:18 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions 12月 23 15:55:18 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam 12月 23 15:55:18 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus 12月 23 15:55:18 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam 12月 23 15:55:18 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam 12月 23 15:55:18 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam 12月 23 15:55:18 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam 12月 23 15:55:18 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam 12月 23 15:55:18 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam 12月 23 15:55:18 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam 12月 23 15:55:18 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode 12月 23 15:55:18 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus 12月 23 15:55:22 raspi volumio[3856]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] 12月 23 15:55:22 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions 12月 23 15:55:22 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 12月 23 15:55:22 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 12月 23 15:55:22 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 12月 23 15:55:22 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 12月 23 15:55:22 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 12月 23 15:55:22 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 12月 23 15:55:22 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 12月 23 15:55:22 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 12月 23 15:55:22 raspi sudo[4654]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf 12月 23 15:55:22 raspi sudo[4654]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 12月 23 15:55:22 raspi sudo[4654]: pam_unix(sudo:session): session closed for user root 12月 23 15:55:22 raspi sudo[4656]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service 12月 23 15:55:22 raspi sudo[4656]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 12月 23 15:55:22 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam 12月 23 15:55:22 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam 12月 23 15:55:22 raspi volumio[3856]: info: MPD Permissions set 12月 23 15:55:22 raspi systemd[1]: Stopping mpd.service - Music Player Daemon... 12月 23 15:55:22 raspi systemd[1]: mpd.service: Deactivated successfully. 12月 23 15:55:22 raspi systemd[1]: Stopped mpd.service - Music Player Daemon. 12月 23 15:55:22 raspi systemd[1]: mpd.service: Consumed 1.657s CPU time. 12月 23 15:55:22 raspi systemd[1]: mpd.socket: Deactivated successfully. 12月 23 15:55:22 raspi systemd[1]: Closed mpd.socket - Music Player Daemon Socket. 12月 23 15:55:22 raspi systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... 12月 23 15:55:22 raspi systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. 12月 23 15:55:22 raspi systemd[1]: Starting mpd.service - Music Player Daemon... 12月 23 15:55:22 raspi sudo[4665]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log 12月 23 15:55:22 raspi sudo[4665]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) 12月 23 15:55:23 raspi sudo[4665]: pam_unix(sudo:session): session closed for user root 12月 23 15:55:24 raspi mpd[4667]: 2025-12-23T15:55:24 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg 12月 23 15:55:24 raspi systemd[1]: Started mpd.service - Music Player Daemon. 12月 23 15:55:24 raspi sudo[4656]: pam_unix(sudo:session): session closed for user root 12月 23 15:55:24 raspi volumio[3856]: error: updateQueue error: null 12月 23 15:55:27 raspi sudo[4675]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service 12月 23 15:55:27 raspi sudo[4675]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) 12月 23 15:55:27 raspi systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... 12月 23 15:55:27 raspi systemd[1]: mpd_monitor.service: Deactivated successfully. 12月 23 15:55:27 raspi systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. 12月 23 15:55:27 raspi systemd[1]: Started mpd_monitor.service - MPD Monitor Service. 12月 23 15:55:27 raspi sudo[4675]: pam_unix(sudo:session): session closed for user root 12月 23 15:55:27 raspi mpd_monitor.sh[4678]: MPD Monitor Service: Starting MPD Monitor Service 12月 23 15:55:27 raspi volumio[3856]: info: Successfully started MPD Monitor 12月 23 15:55:27 raspi volumio[3856]: info: CoreCommandRouter::volumioGetState 12月 23 15:55:30 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus 12月 23 15:55:30 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken 12月 23 15:55:41 raspi volumio[3856]: info: CoreCommandRouter::getUIConfigOnPlugin 12月 23 15:55:47 raspi volumio[3856]: info: CALLMETHOD: user_interface peppymeterbasic dlmeter [object Object] 12月 23 15:55:47 raspi volumio[3856]: info: CoreCommandRouter::executeOnPlugin: peppymeterbasic , dlmeter 12月 23 15:55:50 raspi volumio[3856]: --2025-12-23 15:55:47-- https://github.com/balbuze/Meter-peppymeter/raw/main/Zipped-folders/Choose 12月 23 15:55:50 raspi volumio[3856]: Resolving github.com (github.com)... 20.27.177.113 12月 23 15:55:50 raspi volumio[3856]: Connecting to github.com (github.com)|20.27.177.113|:443... connected. 12月 23 15:55:50 raspi volumio[3856]: HTTP request sent, awaiting response... 404 Not Found 12月 23 15:55:50 raspi volumio[3856]: 2025-12-23 15:55:47 ERROR 404: Not Found. 12月 23 15:55:50 raspi volumio[3856]: --2025-12-23 15:55:47-- http://a/ 12月 23 15:55:50 raspi volumio[3856]: Resolving a (a)... failed: Device or resource busy. 12月 23 15:55:50 raspi volumio[3856]: wget: unable to resolve host address ‘a’ 12月 23 15:55:50 raspi volumio[3856]: --2025-12-23 15:55:48-- http://packet.zip/ 12月 23 15:55:50 raspi volumio[3856]: Resolving packet.zip (packet.zip)... 162.255.119.167 12月 23 15:55:50 raspi volumio[3856]: Connecting to packet.zip (packet.zip)|162.255.119.167|:80... connected. 12月 23 15:55:50 raspi volumio[3856]: HTTP request sent, awaiting response... 302 Found 12月 23 15:55:50 raspi volumio[3856]: Location: http://www.packet.zip/ [following] 12月 23 15:55:50 raspi volumio[3856]: --2025-12-23 15:55:48-- http://www.packet.zip/ 12月 23 15:55:50 raspi volumio[3856]: Resolving www.packet.zip (www.packet.zip)... 91.195.240.19 12月 23 15:55:50 raspi volumio[3856]: Connecting to www.packet.zip (www.packet.zip)|91.195.240.19|:80... connected. 12月 23 15:55:50 raspi volumio[3856]: HTTP request sent, awaiting response... Read error (Connection reset by peer) in headers. 12月 23 15:55:50 raspi volumio[3856]: Retrying. 12月 23 15:55:50 raspi volumio[3856]: --2025-12-23 15:55:49-- (try: 2) http://www.packet.zip/ 12月 23 15:55:50 raspi volumio[3856]: Connecting to www.packet.zip (www.packet.zip)|91.195.240.19|:80... connected. 12月 23 15:55:50 raspi volumio[3856]: HTTP request sent, awaiting response... 403 Forbidden 12月 23 15:55:50 raspi volumio[3856]: 2025-12-23 15:55:50 ERROR 403: Forbidden. 12月 23 15:55:50 raspi volumio[3856]: error: PeppyMeterBasic --- An error occurs while downloading or installing Meters 12月 23 15:55:50 raspi volumio[3856]: /bin/rm: cannot remove '/tmp/Choose': No such file or directory 12月 23 15:55:50 raspi volumio[3856]: /bin/rm: cannot remove 'a': No such file or directory 12月 23 15:55:50 raspi volumio[3856]: /bin/rm: cannot remove 'packet.zip*': No such file or directory 12月 23 15:55:50 raspi volumio[3856]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| 12月 23 15:55:50 raspi volumio[3856]: Error: Command failed: /bin/rm /tmp/Choose a packet.zip* 12月 23 15:55:50 raspi volumio[3856]: /bin/rm: cannot remove '/tmp/Choose': No such file or directory 12月 23 15:55:50 raspi volumio[3856]: /bin/rm: cannot remove 'a': No such file or directory 12月 23 15:55:50 raspi volumio[3856]: /bin/rm: cannot remove 'packet.zip*': No such file or directory 12月 23 15:55:50 raspi volumio[3856]: at checkExecSyncError (node:child_process:887:11) 12月 23 15:55:50 raspi volumio[3856]: at execSync (node:child_process:959:15) 12月 23 15:55:50 raspi volumio[3856]: at /data/plugins/user_interface/peppymeterbasic/index.js:710:19 12月 23 15:55:50 raspi volumio[3856]: at new Promise () 12月 23 15:55:50 raspi volumio[3856]: at peppymeterbasic.dlmeter (/data/plugins/user_interface/peppymeterbasic/index.js:685:12) 12月 23 15:55:50 raspi volumio[3856]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) 12月 23 15:55:50 raspi volumio[3856]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:337:42) 12月 23 15:55:50 raspi volumio[3856]: at Socket.emit (node:events:514:28) 12月 23 15:55:50 raspi volumio[3856]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 12月 23 15:55:50 raspi volumio[3856]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) { 12月 23 15:55:50 raspi volumio[3856]: status: 1, 12月 23 15:55:50 raspi volumio[3856]: signal: null, 12月 23 15:55:50 raspi volumio[3856]: output: [ 12月 23 15:55:50 raspi volumio[3856]: null, 12月 23 15:55:50 raspi volumio[3856]: , 12月 23 15:55:50 raspi volumio[3856]: 12月 23 15:55:50 raspi volumio[3856]: ], 12月 23 15:55:50 raspi volumio[3856]: pid: 4751, 12月 23 15:55:50 raspi volumio[3856]: stdout: , 12月 23 15:55:50 raspi volumio[3856]: stderr: 12月 23 15:55:50 raspi volumio[3856]: } 12月 23 15:55:50 raspi volumio[3856]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| 12月 23 15:55:50 raspi sudo[4767]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-23 15:54' 12月 23 15:55:50 raspi sudo[4767]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"