-- Logs begin at Sat 2025-07-05 03:28:51 UTC, end at Sat 2025-07-05 04:29:18 UTC. -- Jul 05 04:28:23 volumio systemd[1]: Time has been changed Jul 05 04:28:27 volumio kernel: aml_spdif_dai: aiu 958 playback disable Jul 05 04:28:27 volumio volumio[5047]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Jul 05 04:28:27 volumio volumio[5047]: info: CoreCommandRouter::Close All Modals sent Jul 05 04:28:27 volumio volumio[5047]: info: CoreCommandRouter::Close All Modals sent Jul 05 04:28:27 volumio volumio[5047]: Express server listening on port 3000 Jul 05 04:28:27 volumio volumio[5047]: [Metrics] WebUI: 12s 557.45ms Jul 05 04:28:27 volumio volumio[5047]: Volumio Calling Home Jul 05 04:28:28 volumio volumio[5047]: info: CoreStateMachine::resetVolumioState Jul 05 04:28:28 volumio volumio[5047]: info: CoreStateMachine::getcurrentVolume Jul 05 04:28:28 volumio volumio[5047]: info: CoreCommandRouter::volumioRetrievevolume Jul 05 04:28:28 volumio volumio[5047]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 04:28:28 volumio volumio[5047]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Jul 05 04:28:28 volumio volumio[5047]: Unable to parse: Jul 05 04:28:28 volumio volumio[5047]: Simple mixer control 'Audio i2s mute',0 Jul 05 04:28:28 volumio volumio[5047]: Capabilities: pswitch pswitch-joined Jul 05 04:28:28 volumio volumio[5047]: Playback channels: Mono Jul 05 04:28:28 volumio volumio[5047]: Mono: Playback [on] Jul 05 04:28:28 volumio volumio[5047]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Jul 05 04:28:28 volumio volumio[5047]: info: Setting Device type: Amlogic Jul 05 04:28:28 volumio volumio[5047]: error: Cannot start Roon Bridge Error: Command failed: /usr/bin/sudo /bin/systemctl start roonbridge.service Jul 05 04:28:28 volumio volumio[5047]: Failed to start roonbridge.service: Unit roonbridge.service failed to load: No such file or directory. Jul 05 04:28:28 volumio volumio[5047]: info: ir_controller: File permissions successfully set on /etc/lirc/*. Jul 05 04:28:28 volumio volumio[5047]: info: MPD running with PID4669 Jul 05 04:28:28 volumio volumio[5047]: ,establishing connection Jul 05 04:28:28 volumio sudo[5191]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 1 Jul 05 04:28:28 volumio sudo[5191]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 04:28:28 volumio sudo[5191]: pam_unix(sudo:session): session closed for user root Jul 05 04:28:28 volumio volumio[5047]: Unable to parse: Jul 05 04:28:28 volumio volumio[5047]: Simple mixer control 'Audio i2s mute',0 Jul 05 04:28:28 volumio volumio[5047]: Capabilities: pswitch pswitch-joined Jul 05 04:28:28 volumio volumio[5047]: Playback channels: Mono Jul 05 04:28:28 volumio volumio[5047]: Mono: Playback [on] Jul 05 04:28:28 volumio volumio[5047]: info: VolumeController:: Volume=undefined Mute =false Jul 05 04:28:28 volumio volumio[5047]: info: CoreStateMachine::pushState Jul 05 04:28:28 volumio volumio[5047]: info: CorePlayQueue::getTrack 0 Jul 05 04:28:28 volumio volumio[5047]: info: CoreCommandRouter::volumioPushState Jul 05 04:28:28 volumio volumio[5047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 04:28:28 volumio volumio[5047]: info: CoreStateMachine::updateTrackBlock Jul 05 04:28:28 volumio volumio[5047]: info: CorePlayQueue::getTrackBlock Jul 05 04:28:28 volumio volumio[5047]: info: CoreCommandRouter::volumioRetrievevolume Jul 05 04:28:28 volumio volumio[5047]: info: Jul 05 04:28:28 volumio volumio[5047]: ---------------------------- Client requests Volume 80 Jul 05 04:28:28 volumio volumio[5047]: info: VolumeController::SetAlsaVolume80 Jul 05 04:28:28 volumio volumio[5047]: info: CoreStateMachine::pushState Jul 05 04:28:28 volumio volumio[5047]: info: CorePlayQueue::getTrack 0 Jul 05 04:28:28 volumio volumio[5047]: info: CoreCommandRouter::volumioPushState Jul 05 04:28:28 volumio volumio[5047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 04:28:28 volumio volumio[5047]: info: Cannot read I2C interface or I2C interface not presentError: Command failed: /usr/bin/sudo /usr/sbin/i2cdetect -y 1 Jul 05 04:28:28 volumio volumio[5047]: Error: Could not open file `/dev/i2c-1' or `/dev/i2c/1': No such file or directory Jul 05 04:28:28 volumio volumio[5047]: info: Reloading queue from file Jul 05 04:28:28 volumio volumio[5047]: error: updateQueue error: null Jul 05 04:28:28 volumio volumio[5047]: info: Setting volume on startup at 80 Jul 05 04:28:28 volumio volumio[5047]: Unable to parse: Jul 05 04:28:28 volumio volumio[5047]: Simple mixer control 'Audio i2s mute',0 Jul 05 04:28:28 volumio volumio[5047]: Capabilities: pswitch pswitch-joined Jul 05 04:28:28 volumio volumio[5047]: Playback channels: Mono Jul 05 04:28:28 volumio volumio[5047]: Mono: Playback [on] Jul 05 04:28:28 volumio volumio[5047]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: failed to parse output Jul 05 04:28:28 volumio volumio[5047]: info: CoreStateMachine::setRepeat null single undefined Jul 05 04:28:28 volumio volumio[5047]: info: CoreStateMachine::pushState Jul 05 04:28:28 volumio volumio[5047]: info: CorePlayQueue::getTrack 0 Jul 05 04:28:28 volumio volumio[5047]: info: CoreCommandRouter::volumioPushState Jul 05 04:28:28 volumio volumio[5047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 04:28:28 volumio volumio[5047]: info: CoreStateMachine::setRandom null Jul 05 04:28:28 volumio volumio[5047]: info: CoreStateMachine::pushState Jul 05 04:28:28 volumio volumio[5047]: info: CorePlayQueue::getTrack 0 Jul 05 04:28:28 volumio volumio[5047]: info: CoreCommandRouter::volumioPushState Jul 05 04:28:28 volumio volumio[5047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 04:28:28 volumio volumio[5047]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jul 05 04:28:28 volumio volumio[5047]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 05 04:28:28 volumio volumio[5047]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jul 05 04:28:29 volumio volumio[5047]: Unable to parse: Jul 05 04:28:29 volumio volumio[5047]: Simple mixer control 'Audio i2s mute',0 Jul 05 04:28:29 volumio volumio[5047]: Capabilities: pswitch pswitch-joined Jul 05 04:28:29 volumio volumio[5047]: Playback channels: Mono Jul 05 04:28:29 volumio volumio[5047]: Mono: Playback [on] Jul 05 04:28:29 volumio volumio[5047]: info: VolumeController:: Volume=undefined Mute =false Jul 05 04:28:29 volumio volumio[5047]: info: CoreStateMachine::pushState Jul 05 04:28:29 volumio volumio[5047]: info: CorePlayQueue::getTrack 0 Jul 05 04:28:29 volumio volumio[5047]: info: CoreCommandRouter::volumioPushState Jul 05 04:28:29 volumio volumio[5047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 04:28:29 volumio sudo[5200]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 05 04:28:29 volumio sudo[5200]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 04:28:29 volumio sudo[5200]: pam_unix(sudo:session): session closed for user root Jul 05 04:28:29 volumio volumio[5047]: info: Starting Shairport Sync Jul 05 04:28:29 volumio sudo[5202]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 05 04:28:29 volumio sudo[5202]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 04:28:29 volumio sudo[5202]: pam_unix(sudo:session): session closed for user root Jul 05 04:28:29 volumio volumio[5047]: info: Starting Shairport Sync Jul 05 04:28:29 volumio sudo[5207]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 05 04:28:29 volumio sudo[5207]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 04:28:29 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Jul 05 04:28:29 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 05 04:28:29 volumio sudo[5210]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 05 04:28:29 volumio sudo[5210]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 04:28:29 volumio sudo[5207]: pam_unix(sudo:session): session closed for user root Jul 05 04:28:29 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 05 04:28:29 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Jul 05 04:28:29 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 05 04:28:29 volumio sudo[5210]: pam_unix(sudo:session): session closed for user root Jul 05 04:28:29 volumio nmbd[4805]: [2025/07/05 04:28:29.146689, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jul 05 04:28:29 volumio nmbd[4805]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.190 for name WORKGROUP<1d>. Jul 05 04:28:29 volumio nmbd[4805]: This response was from IP 192.168.1.192, reporting an IP address of 192.168.1.192. Jul 05 04:28:29 volumio volumio[5047]: info: ir_controller: LIRC correctly updated. Jul 05 04:28:29 volumio volumio[5047]: Discovery: adding 79bfc2c3-d29c-4839-9b52-f907310bef76 Jul 05 04:28:29 volumio volumio[5047]: info: mDNS: Found device Volumio Jul 05 04:28:29 volumio volumio[5047]: info: CoreCommandRouter::volumioGetState Jul 05 04:28:29 volumio volumio[5047]: info: CorePlayQueue::getTrack 0 Jul 05 04:28:29 volumio volumio[5047]: info: Shairport-Sync Started Jul 05 04:28:29 volumio volumio[5047]: Error adding Membership: Error: addMembership EINVAL Jul 05 04:28:29 volumio volumio[5047]: info: Shairport-Sync Started Jul 05 04:28:29 volumio volumio[5047]: info: CoreCommandRouter::volumioGetState Jul 05 04:28:29 volumio volumio[5047]: info: CorePlayQueue::getTrack 0 Jul 05 04:28:29 volumio sudo[5226]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jul 05 04:28:29 volumio sudo[5226]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 04:28:29 volumio systemd[1]: Starting UPnP Renderer front-end to MPD... Jul 05 04:28:29 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Jul 05 04:28:29 volumio sudo[5226]: pam_unix(sudo:session): session closed for user root Jul 05 04:28:29 volumio volumio[5047]: info: Upmpdcli Daemon Started Jul 05 04:28:30 volumio sudo[5245]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop lirc.service Jul 05 04:28:30 volumio sudo[5245]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 04:28:30 volumio systemd[1]: Stopping LSB: Starts LIRC daemon.... Jul 05 04:28:30 volumio lircd-0.9.0-pre1[4652]: removed client Jul 05 04:28:30 volumio lirc[5247]: Stopping execution daemon: irexec:. Jul 05 04:28:30 volumio lircd-0.9.0-pre1[4652]: caught signal Jul 05 04:28:30 volumio lirc[5247]: Stopping remote control daemon(s): LIRC:. Jul 05 04:28:30 volumio systemd[1]: Stopped LSB: Starts LIRC daemon.. Jul 05 04:28:30 volumio sudo[5245]: pam_unix(sudo:session): session closed for user root Jul 05 04:28:30 volumio volumio[5047]: info: ir_controller: systemctl stop lirc.service succeeded. Jul 05 04:28:31 volumio sudo[5263]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start lirc.service Jul 05 04:28:31 volumio sudo[5263]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 04:28:31 volumio systemd[1]: Starting LSB: Starts LIRC daemon.... Jul 05 04:28:31 volumio lirc[5265]: Loading LIRC modules:. Jul 05 04:28:31 volumio lircd-0.9.0-pre1[5276]: lircd(default) ready, using /var/run/lirc/lircd Jul 05 04:28:31 volumio kernel: input: lircd as /devices/virtual/input/input4 Jul 05 04:28:31 volumio lirc[5265]: Starting remote control daemon(s) : LIRC :. Jul 05 04:28:31 volumio lircd-0.9.0-pre1[5276]: accepted new client on /var/run/lirc/lircd Jul 05 04:28:31 volumio lirc[5265]: Starting execution daemon: irexec:. Jul 05 04:28:31 volumio sudo[5263]: pam_unix(sudo:session): session closed for user root Jul 05 04:28:31 volumio systemd[1]: Started LSB: Starts LIRC daemon.. Jul 05 04:28:31 volumio volumio[5047]: info: ir_controller: systemctl start lirc.service succeeded. Jul 05 04:28:31 volumio volumio[5047]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jul 05 04:28:31 volumio volumio[5047]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jul 05 04:28:31 volumio volumio[5047]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jul 05 04:28:31 volumio volumio[5047]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jul 05 04:28:31 volumio volumio[5047]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jul 05 04:28:31 volumio volumio[5047]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jul 05 04:28:31 volumio volumio[5047]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jul 05 04:28:31 volumio volumio[5047]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jul 05 04:28:31 volumio volumio[5047]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jul 05 04:28:31 volumio volumio[5047]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jul 05 04:28:31 volumio volumio[5047]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jul 05 04:28:31 volumio volumio[5047]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jul 05 04:28:31 volumio volumio[5047]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jul 05 04:28:31 volumio volumio[5047]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jul 05 04:28:31 volumio volumio[5047]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jul 05 04:28:31 volumio volumio[5047]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jul 05 04:28:31 volumio volumio[5047]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jul 05 04:28:31 volumio volumio[5047]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jul 05 04:28:31 volumio volumio[5047]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jul 05 04:28:31 volumio volumio[5047]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jul 05 04:28:31 volumio volumio[5047]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jul 05 04:28:31 volumio volumio[5047]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jul 05 04:28:31 volumio volumio[5047]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jul 05 04:28:31 volumio volumio[5047]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jul 05 04:28:31 volumio volumio[5047]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jul 05 04:28:31 volumio volumio[5047]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jul 05 04:28:31 volumio volumio[5047]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jul 05 04:28:31 volumio volumio[5047]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jul 05 04:28:31 volumio volumio[5047]: info: Adding plugin bluetooth to MyMusic Plugins Jul 05 04:28:31 volumio volumio[5047]: info: Adding plugin cd_controller to MyMusic Plugins Jul 05 04:28:31 volumio volumio[5047]: info: Adding plugin smart_inputs to MyMusic Plugins Jul 05 04:28:31 volumio volumio[5047]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jul 05 04:28:33 volumio volumio[5047]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jul 05 04:28:35 volumio volumio[5047]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jul 05 04:28:35 volumio volumio[5047]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jul 05 04:28:35 volumio volumio[5047]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 04:28:35 volumio volumio[5047]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 04:28:35 volumio volumio[5047]: info: Starting MyVolumio Remote Streaming Endpoints Jul 05 04:28:35 volumio volumio[5047]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jul 05 04:28:35 volumio volumio[5047]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jul 05 04:28:35 volumio volumio[5047]: info: Streaming services startup Jul 05 04:28:35 volumio volumio[5047]: info: Starting Streaming Daemon Jul 05 04:28:35 volumio sudo[5285]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 05 04:28:35 volumio sudo[5285]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 04:28:35 volumio systemd[1]: Stopping Volumio Streaming Daemon... Jul 05 04:28:35 volumio systemd[1]: Starting Volumio Streaming Daemon... Jul 05 04:28:35 volumio systemd[1]: Started Volumio Streaming Daemon. Jul 05 04:28:35 volumio sudo[5285]: pam_unix(sudo:session): session closed for user root Jul 05 04:28:35 volumio volumio-streaming-daemon[5291]: ############################ Jul 05 04:28:35 volumio volumio-streaming-daemon[5291]: # Volumio Streaming Daemon # Jul 05 04:28:35 volumio volumio-streaming-daemon[5291]: # Running on port 7777 # Jul 05 04:28:35 volumio volumio-streaming-daemon[5291]: ############################ Jul 05 04:28:35 volumio volumio-streaming-daemon[5291]: Environment: production Jul 05 04:28:35 volumio volumio[5047]: info: MyVolumio not started Jul 05 04:28:35 volumio volumio[5047]: info: Initializing device activation check Jul 05 04:28:35 volumio volumio[5047]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jul 05 04:28:36 volumio avahi-daemon[4667]: Invalid response packet from host 192.168.1.115. Jul 05 04:28:36 volumio avahi-daemon[4667]: Invalid response packet from host 192.168.1.115. Jul 05 04:28:36 volumio avahi-daemon[4667]: Invalid response packet from host 192.168.1.115. Jul 05 04:28:38 volumio volumio-remote-updater[4534]: [2025-07-05 04:28:38] [connect] Successful connection Jul 05 04:28:38 volumio volumio-remote-updater[4534]: [2025-07-05 04:28:38] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1751689718 101 Jul 05 04:28:38 volumio volumio-streaming-daemon[5291]: Environment: production Jul 05 04:28:38 volumio volumio[5047]: info: Fetching Streaming Services browse cache Jul 05 04:28:38 volumio volumio[5047]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=aml9xxxarmv7&variante=volumio&version=2.857&uuid=a487c6ed8a8db06c5ffb65c3b3fa3670" http://updates.volumio.org:7070/downloader-v1/track-device Jul 05 04:28:38 volumio volumio[5047]: % Total % Received % Xferd Average Speed Time Time Time Current Jul 05 04:28:38 volumio volumio[5047]: Dload Upload Total Spent Left Speed Jul 05 04:28:38 volumio volumio[5047]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Jul 05 04:28:38 volumio volumio[5047]: retrying in 5 seconds, trial 0 Jul 05 04:28:38 volumio volumio[5047]: Volumio Calling Home Jul 05 04:28:41 volumio volumio[5047]: info: AutoStart - getting queue Jul 05 04:28:41 volumio volumio[5047]: info: CoreCommandRouter::volumioGetQueue Jul 05 04:28:41 volumio volumio[5047]: info: CoreStateMachine::getQueue Jul 05 04:28:41 volumio volumio[5047]: info: CorePlayQueue::getQueue Jul 05 04:28:41 volumio volumio[5047]: info: AutoStart - start playing -> queue is not empty Jul 05 04:28:41 volumio volumio[5047]: info: CoreCommandRouter::volumioPlay Jul 05 04:28:41 volumio volumio[5047]: UNSET VOLATILE Jul 05 04:28:41 volumio volumio[5047]: info: CoreStateMachine::play index undefined Jul 05 04:28:41 volumio volumio[5047]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 05 04:28:41 volumio volumio[5047]: info: CorePlayQueue::getTrack 0 Jul 05 04:28:41 volumio volumio[5047]: info: CoreStateMachine::startPlaybackTimer Jul 05 04:28:41 volumio volumio[5047]: info: CorePlayQueue::getTrack 0 Jul 05 04:28:41 volumio volumio[5047]: verbose: ControllerMpd::clearAddPlayTracks NAS/FNOS/user/music/吴若希/使徒行者 原声大碟/吴若希 - 越难越爱.mp3 Jul 05 04:28:41 volumio volumio[5047]: verbose: ControllerMpd::sendMpdCommand stop Jul 05 04:28:41 volumio volumio[5047]: verbose: ControllerMpd::sendMpdCommand clear Jul 05 04:28:41 volumio volumio[5047]: info: Jul 05 04:28:41 volumio volumio[5047]: ---------------------------- MPD announces system playlist update Jul 05 04:28:41 volumio volumio[5047]: info: Ignoring MPD Status Update Jul 05 04:28:41 volumio volumio[5047]: verbose: ControllerMpd::sendMpdCommand add "NAS/FNOS/user/music/吴若希/使徒行者 原声大碟/吴若希 - 越难越爱.mp3" Jul 05 04:28:41 volumio volumio[5047]: error: updateQueue error: null Jul 05 04:28:41 volumio volumio[5047]: info: Jul 05 04:28:41 volumio volumio[5047]: ---------------------------- MPD announces system playlist update Jul 05 04:28:41 volumio volumio[5047]: info: Ignoring MPD Status Update Jul 05 04:28:41 volumio volumio[5047]: info: ------------------------------ 15ms Jul 05 04:28:41 volumio volumio[5047]: verbose: ControllerMpd::sendMpdCommand play Jul 05 04:28:41 volumio volumio[5047]: info: ------------------------------ 7ms Jul 05 04:28:41 volumio volumio[5047]: info: Jul 05 04:28:41 volumio volumio[5047]: ---------------------------- MPD announces state update: player Jul 05 04:28:41 volumio volumio[5047]: info: ControllerMpd::getState Jul 05 04:28:41 volumio volumio[5047]: verbose: ControllerMpd::sendMpdCommand status Jul 05 04:28:41 volumio volumio[5047]: info: Jul 05 04:28:41 volumio volumio[5047]: ---------------------------- MPD announces state update: player Jul 05 04:28:41 volumio volumio[5047]: info: ControllerMpd::getState Jul 05 04:28:41 volumio volumio[5047]: verbose: ControllerMpd::sendMpdCommand status Jul 05 04:28:41 volumio volumio[5047]: verbose: ControllerMpd::sendMpdCommand clearerror Jul 05 04:28:41 volumio volumio[5047]: verbose: ControllerMpd::parseState Jul 05 04:28:41 volumio volumio[5047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 04:28:41 volumio volumio[5047]: verbose: ControllerMpd::sendMpdCommand clearerror Jul 05 04:28:41 volumio volumio[5047]: verbose: ControllerMpd::parseState Jul 05 04:28:41 volumio volumio[5047]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 05 04:28:41 volumio volumio[5047]: verbose: ControllerMpd::parseTrackInfo Jul 05 04:28:41 volumio volumio[5047]: info: ControllerMpd::pushState Jul 05 04:28:41 volumio volumio[5047]: info: CoreCommandRouter::servicePushState Jul 05 04:28:41 volumio volumio[5047]: info: CorePlayQueue::getTrack 0 Jul 05 04:28:41 volumio volumio[5047]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":238,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"越难越爱","artist":"吴若希","album":"使徒行者 原声大碟","uri":"NAS/FNOS/user/music/吴若希/使徒行者 原声大碟/吴若希 - 越难越爱.mp3","trackType":"mp3"} Jul 05 04:28:41 volumio volumio[5047]: verbose: CURRENT POSITION 0 Jul 05 04:28:41 volumio volumio[5047]: info: CoreStateMachine::syncState stateService pause Jul 05 04:28:41 volumio volumio[5047]: info: CoreStateMachine::syncState currentStatus stop Jul 05 04:28:41 volumio volumio[5047]: info: CoreStateMachine::pushState Jul 05 04:28:41 volumio volumio[5047]: info: CorePlayQueue::getTrack 0 Jul 05 04:28:41 volumio volumio[5047]: info: CoreCommandRouter::volumioPushState Jul 05 04:28:41 volumio volumio[5047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 04:28:41 volumio volumio[5047]: info: ------------------------------ 67ms Jul 05 04:28:41 volumio volumio[5047]: verbose: ControllerMpd::parseTrackInfo Jul 05 04:28:41 volumio volumio[5047]: info: ControllerMpd::pushState Jul 05 04:28:41 volumio volumio[5047]: info: CoreCommandRouter::servicePushState Jul 05 04:28:41 volumio volumio[5047]: info: CorePlayQueue::getTrack 0 Jul 05 04:28:41 volumio volumio[5047]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":238,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"越难越爱","artist":"吴若希","album":"使徒行者 原声大碟","uri":"NAS/FNOS/user/music/吴若希/使徒行者 原声大碟/吴若希 - 越难越爱.mp3","trackType":"mp3"} Jul 05 04:28:41 volumio volumio[5047]: verbose: CURRENT POSITION 0 Jul 05 04:28:41 volumio volumio[5047]: info: CoreStateMachine::syncState stateService pause Jul 05 04:28:41 volumio volumio[5047]: info: CoreStateMachine::syncState currentStatus stop Jul 05 04:28:41 volumio volumio[5047]: info: CoreStateMachine::pushState Jul 05 04:28:41 volumio volumio[5047]: info: CorePlayQueue::getTrack 0 Jul 05 04:28:41 volumio volumio[5047]: info: CoreCommandRouter::volumioPushState Jul 05 04:28:41 volumio volumio[5047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 04:28:41 volumio volumio[5047]: info: ------------------------------ 84ms Jul 05 04:28:46 volumio sudo[5339]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 05 04:28:46 volumio sudo[5339]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 04:28:46 volumio sudo[5339]: pam_unix(sudo:session): session closed for user root Jul 05 04:28:46 volumio sudo[5342]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 05 04:28:46 volumio sudo[5342]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 04:28:46 volumio sudo[5342]: pam_unix(sudo:session): session closed for user root Jul 05 04:28:47 volumio sudo[5347]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 05 04:28:47 volumio sudo[5347]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 04:28:47 volumio sudo[5347]: pam_unix(sudo:session): session closed for user root Jul 05 04:28:47 volumio sudo[5350]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 05 04:28:47 volumio sudo[5350]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 04:28:47 volumio sudo[5350]: pam_unix(sudo:session): session closed for user root Jul 05 04:28:47 volumio volumio[5047]: info: CoreCommandRouter::volumioGetState Jul 05 04:28:47 volumio volumio[5047]: info: CorePlayQueue::getTrack 0 Jul 05 04:28:47 volumio volumio[5047]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 05 04:28:47 volumio volumio[5047]: info: Listing playlists Jul 05 04:28:47 volumio volumio[5047]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 05 04:28:47 volumio volumio[5047]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jul 05 04:28:47 volumio volumio[5047]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jul 05 04:28:47 volumio volumio[5047]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 05 04:28:48 volumio volumio[5047]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=aml9xxxarmv7&variante=volumio&version=2.857&uuid=a487c6ed8a8db06c5ffb65c3b3fa3670" http://updates.volumio.org:7070/downloader-v1/track-device Jul 05 04:28:48 volumio volumio[5047]: % Total % Received % Xferd Average Speed Time Time Time Current Jul 05 04:28:48 volumio volumio[5047]: Dload Upload Total Spent Left Speed Jul 05 04:28:48 volumio volumio[5047]: [235B blob data] Jul 05 04:28:48 volumio volumio[5047]: retrying in 5 seconds, trial 1 Jul 05 04:28:48 volumio volumio[5047]: Volumio Calling Home Jul 05 04:28:52 volumio volumio[5047]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 05 04:28:52 volumio volumio[5047]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 04:28:57 volumio volumio[5047]: info: CoreCommandRouter::volumioGetQueue Jul 05 04:28:57 volumio volumio[5047]: info: CoreStateMachine::getQueue Jul 05 04:28:57 volumio volumio[5047]: info: CorePlayQueue::getQueue Jul 05 04:28:59 volumio volumio[5047]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=aml9xxxarmv7&variante=volumio&version=2.857&uuid=a487c6ed8a8db06c5ffb65c3b3fa3670" http://updates.volumio.org:7070/downloader-v1/track-device Jul 05 04:28:59 volumio volumio[5047]: % Total % Received % Xferd Average Speed Time Time Time Current Jul 05 04:28:59 volumio volumio[5047]: Dload Upload Total Spent Left Speed Jul 05 04:28:59 volumio volumio[5047]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Jul 05 04:28:59 volumio volumio[5047]: retrying in 5 seconds, trial 2 Jul 05 04:28:59 volumio volumio[5047]: Volumio Calling Home Jul 05 04:28:59 volumio volumio[5047]: info: CoreCommandRouter::volumioClearQueue Jul 05 04:28:59 volumio volumio[5047]: info: CoreStateMachine::ClearQueue Jul 05 04:28:59 volumio volumio[5047]: info: CoreStateMachine::stop Jul 05 04:28:59 volumio volumio[5047]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 05 04:28:59 volumio volumio[5047]: UNSET VOLATILE Jul 05 04:28:59 volumio volumio[5047]: info: CorePlayQueue::clearPlayQueue Jul 05 04:28:59 volumio volumio[5047]: info: CorePlayQueue::saveQueue Jul 05 04:28:59 volumio volumio[5047]: info: CoreCommandRouter::volumioPushState Jul 05 04:28:59 volumio volumio[5047]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 04:29:00 volumio volumio[5047]: info: CoreCommandRouter::volumioPushQueue Jul 05 04:29:03 volumio volumio[5047]: info: CoreCommandRouter::volumioGetVisibleSources Jul 05 04:29:03 volumio volumio[5047]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 04:29:06 volumio avahi-daemon[4667]: Invalid response packet from host 192.168.1.115. Jul 05 04:29:06 volumio avahi-daemon[4667]: Invalid response packet from host 192.168.1.115. Jul 05 04:29:11 volumio volumio[5047]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 05 04:29:11 volumio volumio[5047]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 05 04:29:11 volumio volumio[5047]: info: Retrieving Cloud Streaming UI Jul 05 04:29:11 volumio volumio[5047]: info: Getting Tidal Cloud Configuration Jul 05 04:29:11 volumio volumio[5047]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 05 04:29:11 volumio volumio[5047]: info: Getting Qobuz Cloud Configuration Jul 05 04:29:11 volumio volumio[5047]: info: Asking plugin for UI Config Jul 05 04:29:11 volumio volumio[5047]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 05 04:29:11 volumio volumio[5047]: info: Getting Spotify Cloud Configuration Jul 05 04:29:11 volumio volumio[5047]: info: Asking plugin for UI Config Jul 05 04:29:11 volumio volumio[5047]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 05 04:29:11 volumio volumio[5047]: info: Saving Spotify Acccount Jul 05 04:29:11 volumio volumio[5047]: info: Got Tidal Cloud Configuration Jul 05 04:29:11 volumio volumio[5047]: info: Got it Jul 05 04:29:11 volumio volumio[5047]: info: Got it Jul 05 04:29:12 volumio volumio[5047]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jul 05 04:29:12 volumio volumio[5047]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 05 04:29:12 volumio volumio[5047]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Jul 05 04:29:12 volumio volumio[5047]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 05 04:29:12 volumio volumio[5047]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 05 04:29:12 volumio volumio[5047]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 05 04:29:12 volumio volumio[5047]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 05 04:29:12 volumio volumio[5047]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 04:29:12 volumio volumio[5047]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 04:29:12 volumio volumio[5047]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 04:29:12 volumio volumio[5047]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 04:29:12 volumio volumio[5047]: info: CoreCommandRouter::volumioGetBrowseSources Jul 05 04:29:12 volumio volumio[5047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 05 04:29:12 volumio volumio[5047]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jul 05 04:29:12 volumio volumio[5047]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 05 04:29:16 volumio volumio[5047]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 05 04:29:17 volumio volumio[5047]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Jul 05 04:29:17 volumio sudo[5475]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount /mnt/NAS/FNOS Jul 05 04:29:17 volumio sudo[5475]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 04:29:18 volumio sudo[5475]: pam_unix(sudo:session): session closed for user root Jul 05 04:29:18 volumio volumio[5047]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 05 04:29:18 volumio volumio[5047]: { Error: EBUSY: resource busy or locked, rmdir '/mnt/NAS/FNOS' Jul 05 04:29:18 volumio volumio[5047]: at Object.fs.rmdirSync (fs.js:846:18) Jul 05 04:29:18 volumio volumio[5047]: at /volumio/node_modules/linux-mountutils/mountutils.js:178:12 Jul 05 04:29:18 volumio volumio[5047]: at ChildProcess.exithandler (child_process.js:267:7) Jul 05 04:29:18 volumio volumio[5047]: at emitTwo (events.js:126:13) Jul 05 04:29:18 volumio volumio[5047]: at ChildProcess.emit (events.js:214:7) Jul 05 04:29:18 volumio volumio[5047]: at maybeClose (internal/child_process.js:925:16) Jul 05 04:29:18 volumio volumio[5047]: at Socket.stream.socket.on (internal/child_process.js:346:11) Jul 05 04:29:18 volumio volumio[5047]: at emitOne (events.js:116:13) Jul 05 04:29:18 volumio volumio[5047]: at Socket.emit (events.js:211:7) Jul 05 04:29:18 volumio volumio[5047]: at Pipe._handle.close [as _onclose] (net.js:567:12) Jul 05 04:29:18 volumio volumio[5047]: errno: -16, Jul 05 04:29:18 volumio volumio[5047]: code: 'EBUSY', Jul 05 04:29:18 volumio volumio[5047]: syscall: 'rmdir', Jul 05 04:29:18 volumio volumio[5047]: path: '/mnt/NAS/FNOS' } Jul 05 04:29:18 volumio volumio[5047]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 05 04:29:18 volumio sudo[5486]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-05 04:28 Jul 05 04:29:18 volumio sudo[5486]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 8 (jessie)" NAME="Debian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=debian HOME_URL="http://www.debian.org/" SUPPORT_URL="http://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="168ba7444df98cd868db72f24faec0b6346489cc" VOLUMIO_FE_VERSION="847a48ecf32d35cc502a0053d585d1a69236e391" VOLUMIO_BE_VERSION="99dfb342fe07851f1e26bd1d8046d0234eda91dd" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 11 15:53:56 CET 2020" VOLUMIO_VERSION="2.857" VOLUMIO_HARDWARE="aml9xxxarmv7" VOLUMIO_HASH="5e595833ee6075a27fef1ba35f928abb"