-- Logs begin at Wed 2026-01-28 11:45:51 JST, end at Wed 2026-01-28 12:04:38 JST. -- Jan 28 12:03:01 volumio3-nfj12v volumio[3648]: info: Plugin mpdemulation is not enabled Jan 28 12:03:01 volumio3-nfj12v volumio[3648]: info: Loading plugin "rest_api"... Jan 28 12:03:01 volumio3-nfj12v volumio[3648]: info: Loading plugin "websocket"... Jan 28 12:03:01 volumio3-nfj12v volumio[3648]: info: Starting Socket.io Server version 2.3.0 Jan 28 12:03:01 volumio3-nfj12v volumio[3648]: info: Plugin backup_restore is not enabled Jan 28 12:03:02 volumio3-nfj12v volumio[3648]: info: Plugin Systeminfo is not enabled Jan 28 12:03:02 volumio3-nfj12v volumio[3648]: info: Loading i18n strings for locale ja Jan 28 12:03:02 volumio3-nfj12v volumio[3648]: Updating browse sources language Jan 28 12:03:02 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 12:03:02 volumio3-nfj12v volumio[3648]: Forking 1 albumart workers Jan 28 12:03:04 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 12:03:04 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::initPlayerControls Jan 28 12:03:04 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 12:03:04 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 12:03:04 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 12:03:04 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 12:03:04 volumio3-nfj12v volumio[3648]: Express server listening on port 3000 Jan 28 12:03:04 volumio3-nfj12v volumio[3648]: [Metrics] WebUI: 29s 880.82ms Jan 28 12:03:04 volumio3-nfj12v volumio[3648]: info: CoreStateMachine::resetVolumioState Jan 28 12:03:04 volumio3-nfj12v volumio[3648]: info: CoreStateMachine::getcurrentVolume Jan 28 12:03:04 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::volumioRetrievevolume Jan 28 12:03:05 volumio3-nfj12v sudo[3745]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 28 12:03:05 volumio3-nfj12v sudo[3745]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 12:03:05 volumio3-nfj12v sudo[3747]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 28 12:03:05 volumio3-nfj12v sudo[3747]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 12:03:05 volumio3-nfj12v sudo[3745]: pam_unix(sudo:session): session closed for user root Jan 28 12:03:05 volumio3-nfj12v sudo[3747]: pam_unix(sudo:session): session closed for user root Jan 28 12:03:05 volumio3-nfj12v volumio[3648]: info: Volumio Network Manager: Network status updated: 2 Jan 28 12:03:06 volumio3-nfj12v volumio[3648]: verbose: New Socket.io Connection to 192.168.11.11 from 192.168.11.20 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Jan 28 12:03:06 volumio3-nfj12v volumio[3648]: verbose: New Socket.io Connection to 192.168.11.11 from 192.168.11.20 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Jan 28 12:03:06 volumio3-nfj12v volumio[3648]: verbose: New Socket.io Connection to 192.168.11.11 from 192.168.11.20 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Jan 28 12:03:06 volumio3-nfj12v volumio[3648]: verbose: New Socket.io Connection to 192.168.11.11 from 192.168.11.20 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Jan 28 12:03:06 volumio3-nfj12v volumio[3648]: verbose: New Socket.io Connection to 192.168.11.11 from 192.168.11.20 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Jan 28 12:03:06 volumio3-nfj12v volumio[3648]: verbose: New Socket.io Connection to 192.168.11.11 from 192.168.11.20 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Jan 28 12:03:07 volumio3-nfj12v volumio[3648]: info: CoreStateMachine::pushState Jan 28 12:03:07 volumio3-nfj12v volumio[3648]: info: CorePlayQueue::getTrack 0 Jan 28 12:03:07 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 12:03:07 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::volumioPushState Jan 28 12:03:07 volumio3-nfj12v volumio[3648]: info: CoreStateMachine::updateTrackBlock Jan 28 12:03:07 volumio3-nfj12v volumio[3648]: info: CorePlayQueue::getTrackBlock Jan 28 12:03:07 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::volumioRetrievevolume Jan 28 12:03:07 volumio3-nfj12v volumio[3648]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jan 28 12:03:07 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 12:03:07 volumio3-nfj12v volumio[3648]: info: Reloading queue from file Jan 28 12:03:08 volumio3-nfj12v volumio[3648]: info: CoreStateMachine::setRepeat true single undefined Jan 28 12:03:08 volumio3-nfj12v volumio[3648]: info: CoreStateMachine::pushState Jan 28 12:03:08 volumio3-nfj12v volumio[3648]: info: CorePlayQueue::getTrack 0 Jan 28 12:03:08 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 12:03:08 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::volumioPushState Jan 28 12:03:08 volumio3-nfj12v volumio[3648]: info: CoreStateMachine::setRandom null Jan 28 12:03:08 volumio3-nfj12v volumio[3648]: info: CoreStateMachine::pushState Jan 28 12:03:08 volumio3-nfj12v volumio[3648]: info: CorePlayQueue::getTrack 0 Jan 28 12:03:08 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::volumioPushState Jan 28 12:03:08 volumio3-nfj12v volumio[3648]: info: Setting Device type: Raspberry PI Jan 28 12:03:08 volumio3-nfj12v volumio[3648]: info: Completed loading Core Plugins Jan 28 12:03:08 volumio3-nfj12v volumio[3648]: info: Preparing to generate the ALSA configuration file Jan 28 12:03:08 volumio3-nfj12v volumio[3648]: info: CoreStateMachine::pushState Jan 28 12:03:08 volumio3-nfj12v volumio[3648]: info: CorePlayQueue::getTrack 0 Jan 28 12:03:08 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::volumioPushState Jan 28 12:03:08 volumio3-nfj12v volumio[3648]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jan 28 12:03:09 volumio3-nfj12v volumio[3648]: info: Reading ALSA contributions from plugins. Jan 28 12:03:09 volumio3-nfj12v sudo[3778]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 28 12:03:09 volumio3-nfj12v sudo[3778]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 12:03:10 volumio3-nfj12v sudo[3778]: pam_unix(sudo:session): session closed for user root Jan 28 12:03:10 volumio3-nfj12v volumio[3648]: info: Discovery: adding aea6e32c-45b3-4655-b2e9-5ed6cb715e0e Jan 28 12:03:10 volumio3-nfj12v volumio[3648]: info: Discovery: Found device Volumio3-NFJ12V Jan 28 12:03:10 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::volumioGetState Jan 28 12:03:10 volumio3-nfj12v volumio[3648]: info: CorePlayQueue::getTrack 0 Jan 28 12:03:10 volumio3-nfj12v volumio[3648]: verbose: New Socket.io Connection to 192.168.11.11 from 192.168.11.20 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Jan 28 12:03:10 volumio3-nfj12v volumio-remote-updater[456]: [2026-01-28 12:03:10] [connect] Successful connection Jan 28 12:03:10 volumio3-nfj12v volumio[3648]: info: Upmpdcli Daemon Started Jan 28 12:03:10 volumio3-nfj12v volumio-remote-updater[456]: [2026-01-28 12:03:10] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1769569390 101 Jan 28 12:03:10 volumio3-nfj12v volumio[3648]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 9 Jan 28 12:03:10 volumio3-nfj12v volumio[3648]: info: Asound.conf file unchanged, so no further update is needed Jan 28 12:03:10 volumio3-nfj12v volumio[3648]: info: Output device has changed, restarting MPD Jan 28 12:03:11 volumio3-nfj12v volumio[3648]: info: Output device has changed, restarting Shairport Sync Jan 28 12:03:11 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 12:03:11 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 12:03:11 volumio3-nfj12v sudo[3819]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 28 12:03:11 volumio3-nfj12v sudo[3819]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 12:03:11 volumio3-nfj12v sudo[3819]: pam_unix(sudo:session): session closed for user root Jan 28 12:03:11 volumio3-nfj12v sudo[3821]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 28 12:03:11 volumio3-nfj12v sudo[3821]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 12:03:11 volumio3-nfj12v systemd[1]: Stopping Music Player Daemon... Jan 28 12:03:11 volumio3-nfj12v volumio[3648]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 28 12:03:11 volumio3-nfj12v volumio[3648]: info: ___________ START PLUGINS ___________ Jan 28 12:03:11 volumio3-nfj12v volumio[3648]: info: ControllerMpd::onStart: Initializing MPD Jan 28 12:03:11 volumio3-nfj12v volumio[3648]: info: Creating MPD Configuration file Jan 28 12:03:12 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 12:03:12 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 28 12:03:12 volumio3-nfj12v volumio[3648]: info: [1769569392348] CoreMusicLibrary::Adding element DLNAサーバー Jan 28 12:03:12 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 12:03:12 volumio3-nfj12v sudo[3828]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 28 12:03:12 volumio3-nfj12v sudo[3828]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 12:03:12 volumio3-nfj12v sudo[3828]: pam_unix(sudo:session): session closed for user root Jan 28 12:03:12 volumio3-nfj12v sudo[3830]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 28 12:03:12 volumio3-nfj12v volumio[3648]: info: UPNP Browser: Client initialized successfully Jan 28 12:03:12 volumio3-nfj12v sudo[3830]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 12:03:12 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 12:03:13 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 12:03:13 volumio3-nfj12v volumio[3648]: Starting albumart workers Jan 28 12:03:13 volumio3-nfj12v systemd[1]: mpd.service: Succeeded. Jan 28 12:03:13 volumio3-nfj12v systemd[1]: Stopped Music Player Daemon. Jan 28 12:03:13 volumio3-nfj12v systemd[1]: Starting Music Player Daemon... Jan 28 12:03:13 volumio3-nfj12v volumio[3648]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 28 12:03:13 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 12:03:13 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 12:03:14 volumio3-nfj12v sudo[3837]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 28 12:03:14 volumio3-nfj12v sudo[3837]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 12:03:14 volumio3-nfj12v sudo[3837]: pam_unix(sudo:session): session closed for user root Jan 28 12:03:14 volumio3-nfj12v volumio[3648]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 28 12:03:14 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 28 12:03:14 volumio3-nfj12v volumio[3648]: info: [1769569394440] CoreMusicLibrary::Adding element Last_100 Jan 28 12:03:14 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 12:03:14 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 28 12:03:14 volumio3-nfj12v volumio[3648]: info: [1769569394483] CoreMusicLibrary::Adding element Webradio Jan 28 12:03:14 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 12:03:14 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 28 12:03:14 volumio3-nfj12v volumio[3648]: info: Initializing BBC Radios Jan 28 12:03:14 volumio3-nfj12v volumio[3648]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Jan 28 12:03:14 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 12:03:15 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 28 12:03:15 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 12:03:15 volumio3-nfj12v volumio[3648]: info: AutoStart - onStart Jan 28 12:03:15 volumio3-nfj12v volumio[3648]: info: GPIO-Buttons: Reading config and creating triggers... Jan 28 12:03:15 volumio3-nfj12v volumio[3648]: info: GPIO-Buttons: shutdown on pin 518 Jan 28 12:03:16 volumio3-nfj12v volumio[3648]: info: Volumio Calling Home Jan 28 12:03:17 volumio3-nfj12v volumio[3648]: info: GPIO-Buttons started Jan 28 12:03:21 volumio3-nfj12v volumio[3648]: info: MPD Permissions set Jan 28 12:03:22 volumio3-nfj12v volumio[3648]: info: MPD Permissions set Jan 28 12:03:22 volumio3-nfj12v volumio[3648]: verbose: New Socket.io Connection to 192.168.11.11 from 192.168.11.20 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Jan 28 12:03:22 volumio3-nfj12v volumio[3648]: verbose: New Socket.io Connection to 192.168.11.11 from 192.168.11.20 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Jan 28 12:03:24 volumio3-nfj12v volumio[3648]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Jan 28 12:03:24 volumio3-nfj12v volumio[3648]: info: Volumio called home Jan 28 12:03:24 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 12:03:24 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 12:03:24 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 12:03:24 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 12:03:24 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 12:03:24 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 12:03:24 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 12:03:24 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 12:03:25 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 12:03:25 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 12:03:25 volumio3-nfj12v volumio[3648]: info: Starting Shairport Sync Jan 28 12:03:25 volumio3-nfj12v volumio[3648]: info: Starting Shairport Sync Jan 28 12:03:26 volumio3-nfj12v volumio[3648]: info: Starting Shairport Sync Jan 28 12:03:26 volumio3-nfj12v sudo[3899]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 28 12:03:26 volumio3-nfj12v sudo[3899]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 12:03:26 volumio3-nfj12v sudo[3901]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 28 12:03:26 volumio3-nfj12v sudo[3901]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 12:03:26 volumio3-nfj12v systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 28 12:03:26 volumio3-nfj12v systemd[1]: shairport-sync.service: Succeeded. Jan 28 12:03:26 volumio3-nfj12v systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 28 12:03:26 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::volumioGetState Jan 28 12:03:26 volumio3-nfj12v volumio[3648]: info: CorePlayQueue::getTrack 0 Jan 28 12:03:26 volumio3-nfj12v sudo[3903]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 28 12:03:26 volumio3-nfj12v systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 28 12:03:26 volumio3-nfj12v sudo[3903]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 12:03:26 volumio3-nfj12v sudo[3899]: pam_unix(sudo:session): session closed for user root Jan 28 12:03:27 volumio3-nfj12v systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 28 12:03:27 volumio3-nfj12v systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jan 28 12:03:27 volumio3-nfj12v systemd[1]: shairport-sync.service: Succeeded. Jan 28 12:03:27 volumio3-nfj12v systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 28 12:03:27 volumio3-nfj12v volumio[3648]: verbose: New Socket.io Connection to 192.168.11.11 from 192.168.11.20 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 Jan 28 12:03:27 volumio3-nfj12v systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 28 12:03:27 volumio3-nfj12v sudo[3901]: pam_unix(sudo:session): session closed for user root Jan 28 12:03:27 volumio3-nfj12v systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 28 12:03:27 volumio3-nfj12v systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jan 28 12:03:27 volumio3-nfj12v systemd[1]: shairport-sync.service: Succeeded. Jan 28 12:03:27 volumio3-nfj12v systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 28 12:03:27 volumio3-nfj12v systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 28 12:03:27 volumio3-nfj12v sudo[3903]: pam_unix(sudo:session): session closed for user root Jan 28 12:03:28 volumio3-nfj12v volumio[3648]: info: Shairport-Sync Started Jan 28 12:03:28 volumio3-nfj12v volumio[3648]: Error adding Membership: Error: addMembership EINVAL Jan 28 12:03:28 volumio3-nfj12v volumio[3648]: info: Shairport-Sync Started Jan 28 12:03:29 volumio3-nfj12v mpd[3842]: Jan 28 12:03 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 28 12:03:29 volumio3-nfj12v systemd[1]: Started Music Player Daemon. Jan 28 12:03:29 volumio3-nfj12v sudo[3821]: pam_unix(sudo:session): session closed for user root Jan 28 12:03:29 volumio3-nfj12v sudo[3830]: pam_unix(sudo:session): session closed for user root Jan 28 12:03:30 volumio3-nfj12v volumio[3648]: info: Completed starting Core Plugins Jan 28 12:03:30 volumio3-nfj12v volumio[3648]: info: ------------------------------------------- Jan 28 12:03:30 volumio3-nfj12v volumio[3648]: info: ----- MyVolumio plugins startup ---- Jan 28 12:03:30 volumio3-nfj12v volumio[3648]: info: ------------------------------------------- Jan 28 12:03:30 volumio3-nfj12v volumio[3648]: info: [MyVolumio PluginManager] Fetching plans data.... Jan 28 12:03:30 volumio3-nfj12v volumio[3648]: error: MPD error: The expression evaluated to a falsy value: Jan 28 12:03:30 volumio3-nfj12v volumio[3648]: assert.ok(self.idling) Jan 28 12:03:30 volumio3-nfj12v volumio[3648]: error: The expression evaluated to a falsy value: Jan 28 12:03:30 volumio3-nfj12v volumio[3648]: assert.ok(self.idling) Jan 28 12:03:30 volumio3-nfj12v volumio[3648]: info: MPD running with PID3842 Jan 28 12:03:30 volumio3-nfj12v volumio[3648]: ,establishing connection Jan 28 12:03:30 volumio3-nfj12v volumio[3648]: error: MPD error: The expression evaluated to a falsy value: Jan 28 12:03:30 volumio3-nfj12v volumio[3648]: assert.ok(self.idling) Jan 28 12:03:30 volumio3-nfj12v volumio[3648]: error: The expression evaluated to a falsy value: Jan 28 12:03:30 volumio3-nfj12v volumio[3648]: assert.ok(self.idling) Jan 28 12:03:30 volumio3-nfj12v volumio[3648]: error: updateQueue error: null Jan 28 12:03:31 volumio3-nfj12v volumio[3648]: verbose: New Socket.io Connection to 192.168.11.11 from 192.168.11.20 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 14 Jan 28 12:03:32 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::volumioGetVisibleSources Jan 28 12:03:32 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 28 12:03:32 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::volumioGetState Jan 28 12:03:32 volumio3-nfj12v volumio[3648]: info: CorePlayQueue::getTrack 0 Jan 28 12:03:32 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::volumioGetQueue Jan 28 12:03:32 volumio3-nfj12v volumio[3648]: info: CoreStateMachine::getQueue Jan 28 12:03:32 volumio3-nfj12v volumio[3648]: info: CorePlayQueue::getQueue Jan 28 12:03:32 volumio3-nfj12v volumio[3648]: info: Listing playlists Jan 28 12:03:32 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 28 12:03:35 volumio3-nfj12v volumio[3648]: info: AutoStart - Plugin is starting Jan 28 12:03:35 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::volumioGetQueue Jan 28 12:03:35 volumio3-nfj12v volumio[3648]: info: CoreStateMachine::getQueue Jan 28 12:03:35 volumio3-nfj12v volumio[3648]: info: CorePlayQueue::getQueue Jan 28 12:03:35 volumio3-nfj12v volumio[3648]: info: AutoStart - start playing Jan 28 12:03:35 volumio3-nfj12v volumio[3648]: info: AutoStart - start playing with no specific position Jan 28 12:03:35 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::volumioPlay Jan 28 12:03:35 volumio3-nfj12v volumio[3648]: info: CoreStateMachine::play index 0 Jan 28 12:03:35 volumio3-nfj12v volumio[3648]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 12:03:35 volumio3-nfj12v volumio[3648]: info: CoreStateMachine::stop Jan 28 12:03:35 volumio3-nfj12v volumio[3648]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 12:03:35 volumio3-nfj12v volumio[3648]: info: CoreStateMachine::play index undefined Jan 28 12:03:35 volumio3-nfj12v volumio[3648]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 12:03:35 volumio3-nfj12v volumio[3648]: info: CorePlayQueue::getTrack 0 Jan 28 12:03:35 volumio3-nfj12v volumio[3648]: info: CoreStateMachine::startPlaybackTimer Jan 28 12:03:35 volumio3-nfj12v volumio[3648]: info: CorePlayQueue::getTrack 0 Jan 28 12:03:35 volumio3-nfj12v volumio[3648]: verbose: ControllerMpd::clearAddPlayTracks USB/USB-32GB/Music/Davichi ★/Davichi - 2025 - TIMECAPSULE/01. TIMECAPSULE.mp3 Jan 28 12:03:35 volumio3-nfj12v volumio[3648]: verbose: ControllerMpd::sendMpdCommand stop Jan 28 12:03:36 volumio3-nfj12v volumio[3648]: info: sendMpdCommand stop took 1103 milliseconds Jan 28 12:03:36 volumio3-nfj12v volumio[3648]: verbose: ControllerMpd::sendMpdCommand clear Jan 28 12:03:36 volumio3-nfj12v volumio[3648]: info: Jan 28 12:03:36 volumio3-nfj12v volumio[3648]: ---------------------------- MPD announces system playlist update Jan 28 12:03:36 volumio3-nfj12v volumio[3648]: info: Ignoring MPD Status Update Jan 28 12:03:36 volumio3-nfj12v volumio[3648]: info: sendMpdCommand clear took 66 milliseconds Jan 28 12:03:36 volumio3-nfj12v volumio[3648]: verbose: ControllerMpd::sendMpdCommand add "USB/USB-32GB/Music/Davichi ★/Davichi - 2025 - TIMECAPSULE/01. TIMECAPSULE.mp3" Jan 28 12:03:36 volumio3-nfj12v volumio[3648]: info: Jan 28 12:03:36 volumio3-nfj12v volumio[3648]: ---------------------------- MPD announces system playlist update Jan 28 12:03:36 volumio3-nfj12v volumio[3648]: info: Ignoring MPD Status Update Jan 28 12:03:36 volumio3-nfj12v volumio[3648]: info: Jan 28 12:03:36 volumio3-nfj12v volumio[3648]: ---------------------------- MPD announces system playlist update Jan 28 12:03:36 volumio3-nfj12v volumio[3648]: info: Ignoring MPD Status Update Jan 28 12:03:36 volumio3-nfj12v volumio[3648]: error: updateQueue error: null Jan 28 12:03:36 volumio3-nfj12v volumio[3648]: info: Jan 28 12:03:36 volumio3-nfj12v volumio[3648]: ---------------------------- MPD announces system playlist update Jan 28 12:03:36 volumio3-nfj12v volumio[3648]: info: Ignoring MPD Status Update Jan 28 12:03:36 volumio3-nfj12v volumio[3648]: info: ------------------------------ 120ms Jan 28 12:03:36 volumio3-nfj12v volumio[3648]: info: sendMpdCommand add "USB/USB-32GB/Music/Davichi ★/Davichi - 2025 - TIMECAPSULE/01. TIMECAPSULE.mp3" took 81 milliseconds Jan 28 12:03:36 volumio3-nfj12v volumio[3648]: info: ------------------------------ 58ms Jan 28 12:03:36 volumio3-nfj12v volumio[3648]: info: ------------------------------ 38ms Jan 28 12:03:36 volumio3-nfj12v volumio[3648]: verbose: ControllerMpd::sendMpdCommand play Jan 28 12:03:36 volumio3-nfj12v volumio[3648]: info: Jan 28 12:03:36 volumio3-nfj12v volumio[3648]: ---------------------------- MPD announces system playlist update Jan 28 12:03:36 volumio3-nfj12v volumio[3648]: info: Ignoring MPD Status Update Jan 28 12:03:36 volumio3-nfj12v volumio[3648]: info: Jan 28 12:03:36 volumio3-nfj12v volumio[3648]: ---------------------------- MPD announces system playlist update Jan 28 12:03:36 volumio3-nfj12v volumio[3648]: info: Ignoring MPD Status Update Jan 28 12:03:36 volumio3-nfj12v volumio[3648]: info: Jan 28 12:03:36 volumio3-nfj12v volumio[3648]: ---------------------------- MPD announces state update: player Jan 28 12:03:36 volumio3-nfj12v volumio[3648]: info: Jan 28 12:03:36 volumio3-nfj12v volumio[3648]: ---------------------------- MPD announces state update: player Jan 28 12:03:36 volumio3-nfj12v volumio[3648]: info: ------------------------------ 205ms Jan 28 12:03:36 volumio3-nfj12v volumio[3648]: info: sendMpdCommand play took 182 milliseconds Jan 28 12:03:36 volumio3-nfj12v volumio[3648]: info: ------------------------------ 174ms Jan 28 12:03:36 volumio3-nfj12v volumio[3648]: info: ControllerMpd::getState Jan 28 12:03:36 volumio3-nfj12v volumio[3648]: verbose: ControllerMpd::sendMpdCommand status Jan 28 12:03:36 volumio3-nfj12v volumio[3648]: info: ------------------------------ 175ms Jan 28 12:03:36 volumio3-nfj12v volumio[3648]: info: ControllerMpd::getState Jan 28 12:03:36 volumio3-nfj12v volumio[3648]: verbose: ControllerMpd::sendMpdCommand status Jan 28 12:03:36 volumio3-nfj12v volumio[3648]: info: Jan 28 12:03:36 volumio3-nfj12v volumio[3648]: ---------------------------- MPD announces state update: player Jan 28 12:03:36 volumio3-nfj12v volumio[3648]: info: ControllerMpd::getState Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: verbose: ControllerMpd::sendMpdCommand status Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: info: Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: ---------------------------- MPD announces state update: player Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: info: ControllerMpd::getState Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: verbose: ControllerMpd::sendMpdCommand status Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: info: sendMpdCommand status took 76 milliseconds Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: info: sendMpdCommand status took 73 milliseconds Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: info: sendMpdCommand status took 49 milliseconds Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: info: sendMpdCommand status took 36 milliseconds Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: verbose: ControllerMpd::parseState Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: verbose: ControllerMpd::parseState Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: verbose: ControllerMpd::parseState Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: verbose: ControllerMpd::parseState Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: info: Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: ---------------------------- MPD announces state update: player Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: info: ControllerMpd::getState Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: verbose: ControllerMpd::sendMpdCommand status Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: info: sendMpdCommand playlistinfo took 274 milliseconds Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: info: sendMpdCommand playlistinfo took 277 milliseconds Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: info: sendMpdCommand playlistinfo took 280 milliseconds Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: info: sendMpdCommand playlistinfo took 166 milliseconds Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: info: sendMpdCommand status took 49 milliseconds Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: verbose: ControllerMpd::parseTrackInfo Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: verbose: ControllerMpd::parseTrackInfo Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: verbose: ControllerMpd::parseTrackInfo Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: verbose: ControllerMpd::parseTrackInfo Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: verbose: ControllerMpd::parseState Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: info: ControllerMpd::pushState Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::servicePushState Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: info: CorePlayQueue::getTrack 0 Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":252,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"TIMECAPSULE","artist":"Davichi","album":"2025 - TIMECAPSULE","uri":"USB/USB-32GB/Music/Davichi ★/Davichi - 2025 - TIMECAPSULE/01. TIMECAPSULE.mp3","trackType":"mp3"} Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: verbose: CURRENT POSITION 0 Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: info: CoreStateMachine::syncState stateService play Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: info: CoreStateMachine::syncState currentStatus stop Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: info: ControllerMpd::pushState Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::servicePushState Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: info: CorePlayQueue::getTrack 0 Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":252,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"TIMECAPSULE","artist":"Davichi","album":"2025 - TIMECAPSULE","uri":"USB/USB-32GB/Music/Davichi ★/Davichi - 2025 - TIMECAPSULE/01. TIMECAPSULE.mp3","trackType":"mp3"} Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: verbose: CURRENT POSITION 0 Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: info: CoreStateMachine::syncState stateService play Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: info: CoreStateMachine::syncState currentStatus play Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: info: Received an update from plugin. extracting info from payload Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: info: CoreStateMachine::pushState Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: info: CorePlayQueue::getTrack 0 Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::volumioPushState Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: info: CoreStateMachine::pushState Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: info: CorePlayQueue::getTrack 0 Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::volumioPushState Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: info: ControllerMpd::pushState Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::servicePushState Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: info: CorePlayQueue::getTrack 0 Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":252,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"TIMECAPSULE","artist":"Davichi","album":"2025 - TIMECAPSULE","uri":"USB/USB-32GB/Music/Davichi ★/Davichi - 2025 - TIMECAPSULE/01. TIMECAPSULE.mp3","trackType":"mp3"} Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: verbose: CURRENT POSITION 0 Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: info: CoreStateMachine::syncState stateService play Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: info: CoreStateMachine::syncState currentStatus play Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: info: Received an update from plugin. extracting info from payload Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: info: CoreStateMachine::pushState Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: info: CorePlayQueue::getTrack 0 Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::volumioPushState Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: info: CoreStateMachine::pushState Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: info: CorePlayQueue::getTrack 0 Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::volumioPushState Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: info: ControllerMpd::pushState Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::servicePushState Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: info: CorePlayQueue::getTrack 0 Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":252,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"TIMECAPSULE","artist":"Davichi","album":"2025 - TIMECAPSULE","uri":"USB/USB-32GB/Music/Davichi ★/Davichi - 2025 - TIMECAPSULE/01. TIMECAPSULE.mp3","trackType":"mp3"} Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: verbose: CURRENT POSITION 0 Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: info: CoreStateMachine::syncState stateService play Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: info: CoreStateMachine::syncState currentStatus play Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: info: Received an update from plugin. extracting info from payload Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: info: CoreStateMachine::pushState Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: info: CorePlayQueue::getTrack 0 Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::volumioPushState Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: info: CoreStateMachine::pushState Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: info: CorePlayQueue::getTrack 0 Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::volumioPushState Jan 28 12:03:37 volumio3-nfj12v volumio[3648]: info: ------------------------------ 964ms Jan 28 12:03:38 volumio3-nfj12v volumio[3648]: info: ------------------------------ 1417ms Jan 28 12:03:38 volumio3-nfj12v volumio[3648]: info: ------------------------------ 1389ms Jan 28 12:03:38 volumio3-nfj12v volumio[3648]: info: ------------------------------ 1403ms Jan 28 12:03:38 volumio3-nfj12v volumio[3648]: info: sendMpdCommand playlistinfo took 986 milliseconds Jan 28 12:03:38 volumio3-nfj12v volumio[3648]: verbose: ControllerMpd::parseTrackInfo Jan 28 12:03:38 volumio3-nfj12v volumio[3648]: info: ControllerMpd::pushState Jan 28 12:03:38 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::servicePushState Jan 28 12:03:38 volumio3-nfj12v volumio[3648]: info: CorePlayQueue::getTrack 0 Jan 28 12:03:38 volumio3-nfj12v volumio[3648]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1131,"duration":252,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"TIMECAPSULE","artist":"Davichi","album":"2025 - TIMECAPSULE","uri":"USB/USB-32GB/Music/Davichi ★/Davichi - 2025 - TIMECAPSULE/01. TIMECAPSULE.mp3","trackType":"mp3"} Jan 28 12:03:38 volumio3-nfj12v volumio[3648]: verbose: CURRENT POSITION 0 Jan 28 12:03:38 volumio3-nfj12v volumio[3648]: info: CoreStateMachine::syncState stateService play Jan 28 12:03:38 volumio3-nfj12v volumio[3648]: info: CoreStateMachine::syncState currentStatus play Jan 28 12:03:38 volumio3-nfj12v volumio[3648]: info: Received an update from plugin. extracting info from payload Jan 28 12:03:38 volumio3-nfj12v volumio[3648]: info: CoreStateMachine::pushState Jan 28 12:03:38 volumio3-nfj12v volumio[3648]: info: CorePlayQueue::getTrack 0 Jan 28 12:03:38 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 12:03:38 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::volumioPushState Jan 28 12:03:38 volumio3-nfj12v volumio[3648]: info: CoreStateMachine::pushState Jan 28 12:03:38 volumio3-nfj12v volumio[3648]: info: CorePlayQueue::getTrack 0 Jan 28 12:03:38 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::volumioPushState Jan 28 12:03:38 volumio3-nfj12v volumio[3648]: info: ------------------------------ 1276ms Jan 28 12:03:39 volumio3-nfj12v volumio[3648]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jan 28 12:03:39 volumio3-nfj12v volumio[3648]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jan 28 12:03:39 volumio3-nfj12v volumio[3648]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jan 28 12:03:39 volumio3-nfj12v volumio[3648]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jan 28 12:03:39 volumio3-nfj12v volumio[3648]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jan 28 12:03:39 volumio3-nfj12v volumio[3648]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jan 28 12:03:39 volumio3-nfj12v volumio[3648]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jan 28 12:03:39 volumio3-nfj12v volumio[3648]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jan 28 12:03:39 volumio3-nfj12v volumio[3648]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jan 28 12:03:39 volumio3-nfj12v volumio[3648]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jan 28 12:03:39 volumio3-nfj12v volumio[3648]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jan 28 12:03:39 volumio3-nfj12v volumio[3648]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jan 28 12:03:39 volumio3-nfj12v volumio[3648]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jan 28 12:03:39 volumio3-nfj12v volumio[3648]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jan 28 12:03:39 volumio3-nfj12v volumio[3648]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jan 28 12:03:39 volumio3-nfj12v volumio[3648]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jan 28 12:03:39 volumio3-nfj12v volumio[3648]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jan 28 12:03:39 volumio3-nfj12v volumio[3648]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jan 28 12:03:39 volumio3-nfj12v volumio[3648]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jan 28 12:03:39 volumio3-nfj12v volumio[3648]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jan 28 12:03:39 volumio3-nfj12v volumio[3648]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jan 28 12:03:39 volumio3-nfj12v volumio[3648]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jan 28 12:03:39 volumio3-nfj12v volumio[3648]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jan 28 12:03:39 volumio3-nfj12v volumio[3648]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jan 28 12:03:39 volumio3-nfj12v volumio[3648]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jan 28 12:03:39 volumio3-nfj12v volumio[3648]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jan 28 12:03:39 volumio3-nfj12v volumio[3648]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jan 28 12:03:39 volumio3-nfj12v volumio[3648]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jan 28 12:03:39 volumio3-nfj12v volumio[3648]: info: Adding plugin bluetooth to MyMusic Plugins Jan 28 12:03:39 volumio3-nfj12v volumio[3648]: info: Adding plugin multiroom to MyMusic Plugins Jan 28 12:03:39 volumio3-nfj12v volumio[3648]: info: Adding plugin metavolumio to MyMusic Plugins Jan 28 12:03:39 volumio3-nfj12v volumio[3648]: info: Adding plugin cd_controller to MyMusic Plugins Jan 28 12:03:39 volumio3-nfj12v volumio[3648]: info: Adding plugin qobuzconnect to MyMusic Plugins Jan 28 12:03:39 volumio3-nfj12v volumio[3648]: info: Adding plugin smart_inputs to MyMusic Plugins Jan 28 12:03:39 volumio3-nfj12v volumio[3648]: info: Adding plugin tidalconnect to MyMusic Plugins Jan 28 12:03:39 volumio3-nfj12v volumio[3648]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jan 28 12:03:47 volumio3-nfj12v volumio[3648]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jan 28 12:03:47 volumio3-nfj12v volumio[3648]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jan 28 12:03:47 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 12:03:47 volumio3-nfj12v volumio[3648]: info: Starting MyVolumio Remote Streaming Endpoints Jan 28 12:03:47 volumio3-nfj12v volumio[3648]: info: MyVolumio not started Jan 28 12:03:47 volumio3-nfj12v volumio[3648]: info: Initializing device activation check Jan 28 12:03:47 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 12:03:47 volumio3-nfj12v volumio[3648]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jan 28 12:03:47 volumio3-nfj12v volumio[3648]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jan 28 12:03:56 volumio3-nfj12v volumio[3648]: info: Starting Streaming Service Transparent Proxy Jan 28 12:03:56 volumio3-nfj12v volumio[3648]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jan 28 12:03:56 volumio3-nfj12v volumio[3648]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jan 28 12:03:56 volumio3-nfj12v volumio[3648]: info: Streaming services startup Jan 28 12:03:56 volumio3-nfj12v volumio[3648]: info: Starting Streaming Daemon Jan 28 12:03:57 volumio3-nfj12v sudo[3971]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 28 12:03:57 volumio3-nfj12v sudo[3971]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 28 12:03:57 volumio3-nfj12v volumio[3648]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jan 28 12:03:57 volumio3-nfj12v sudo[3971]: pam_unix(sudo:session): session closed for user root Jan 28 12:03:57 volumio3-nfj12v volumio[3648]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Jan 28 12:03:57 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 12:03:57 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 28 12:03:57 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 12:03:57 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 28 12:03:58 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 28 12:03:58 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 28 12:03:58 volumio3-nfj12v volumio[3648]: info: Retrieving Cloud Streaming UI Jan 28 12:03:58 volumio3-nfj12v volumio[3648]: error: Cannot start Volumio Streaming Daemon Jan 28 12:03:58 volumio3-nfj12v volumio[3648]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 28 12:03:58 volumio3-nfj12v volumio[3648]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 28 12:04:00 volumio3-nfj12v volumio[3648]: info: Getting Tidal Cloud Configuration Jan 28 12:04:00 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 28 12:04:00 volumio3-nfj12v volumio[3648]: info: Getting Qobuz Cloud Configuration Jan 28 12:04:00 volumio3-nfj12v volumio[3648]: info: Asking plugin for UI Config Jan 28 12:04:00 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 28 12:04:00 volumio3-nfj12v volumio[3648]: info: Getting Spotify Cloud Configuration Jan 28 12:04:00 volumio3-nfj12v volumio[3648]: info: Asking plugin for UI Config Jan 28 12:04:00 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 28 12:04:00 volumio3-nfj12v volumio[3648]: info: Saving Spotify Acccount Jan 28 12:04:00 volumio3-nfj12v volumio[3648]: info: Got Tidal Cloud Configuration Jan 28 12:04:00 volumio3-nfj12v volumio[3648]: info: Got it Jan 28 12:04:00 volumio3-nfj12v volumio[3648]: info: Got it Jan 28 12:04:00 volumio3-nfj12v volumio[3648]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jan 28 12:04:01 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 28 12:04:01 volumio3-nfj12v volumio[3648]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Jan 28 12:04:01 volumio3-nfj12v volumio-remote-updater[456]: No test mode Jan 28 12:04:01 volumio3-nfj12v volumio-remote-updater[456]: No alpha test mode Jan 28 12:04:01 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jan 28 12:04:02 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 28 12:04:02 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 28 12:04:02 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 28 12:04:02 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 28 12:04:02 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 28 12:04:02 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::volumioGetBrowseSources Jan 28 12:04:02 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::volumioGetBrowseSources Jan 28 12:04:02 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::volumioGetBrowseSources Jan 28 12:04:02 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 28 12:04:03 volumio3-nfj12v volumio[3648]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jan 28 12:04:03 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jan 28 12:04:03 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 28 12:04:03 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jan 28 12:04:03 volumio3-nfj12v volumio[3648]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jan 28 12:04:04 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::volumioGetState Jan 28 12:04:04 volumio3-nfj12v volumio[3648]: info: CorePlayQueue::getTrack 0 Jan 28 12:04:07 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 28 12:04:07 volumio3-nfj12v volumio[3648]: info: CALLMETHOD: miscellanea albumart clearAlbumartCache Jan 28 12:04:07 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: albumart , clearAlbumartCache Jan 28 12:04:07 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 12:04:07 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 12:04:07 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jan 28 12:04:07 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jan 28 12:04:07 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jan 28 12:04:07 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::volumioGetBrowseSources Jan 28 12:04:07 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 28 12:04:09 volumio3-nfj12v volumio[3648]: info: CALLMETHOD: miscellanea albumart clearAlbumartCache Jan 28 12:04:09 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: albumart , clearAlbumartCache Jan 28 12:04:09 volumio3-nfj12v volumio[3648]: STREAMING PROXY: Starting server on port 3245 Jan 28 12:04:09 volumio3-nfj12v volumio[3648]: Node JS runtime: 14 Jan 28 12:04:09 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: mpd , rebuildAlbumCache Jan 28 12:04:09 volumio3-nfj12v volumio[3648]: info: Rebuild Album cache Jan 28 12:04:09 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: mpd , rebuildAlbumCache Jan 28 12:04:09 volumio3-nfj12v volumio[3648]: info: Rebuild Album cache Jan 28 12:04:15 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 28 12:04:15 volumio3-nfj12v volumio[3648]: info: CALLMETHOD: miscellanea albumart saveAlbumartOptions [object Object] Jan 28 12:04:15 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: albumart , saveAlbumartOptions Jan 28 12:04:15 volumio3-nfj12v volumio[3648]: info: CALLMETHOD: miscellanea albumart saveAlbumartOptions [object Object] Jan 28 12:04:15 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: albumart , saveAlbumartOptions Jan 28 12:04:15 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 28 12:04:17 volumio3-nfj12v volumio[3648]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jan 28 12:04:17 volumio3-nfj12v volumio[3648]: info: Completed starting MyVolumio Plugin Jan 28 12:04:17 volumio3-nfj12v volumio[3648]: [Metrics] CommandRouter: 99s 875.02ms Jan 28 12:04:17 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::volumiosetStartupVolume Jan 28 12:04:17 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 28 12:04:17 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 28 12:04:17 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::Close All Modals sent Jan 28 12:04:17 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::Close All Modals sent Jan 28 12:04:17 volumio3-nfj12v volumio[3648]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Jan 28 12:04:17 volumio3-nfj12v volumio[3648]: aplay: main:828: audio open error: Device or resource busy Jan 28 12:04:18 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jan 28 12:04:18 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 28 12:04:18 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jan 28 12:04:19 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 28 12:04:24 volumio3-nfj12v volumio[3648]: info: BOOT COMPLETED Jan 28 12:04:25 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::volumioPause Jan 28 12:04:25 volumio3-nfj12v volumio[3648]: info: CoreStateMachine::pause Jan 28 12:04:25 volumio3-nfj12v volumio[3648]: info: CoreStateMachine::stPlaybackTimer Jan 28 12:04:25 volumio3-nfj12v volumio[3648]: info: CoreStateMachine::servicePause Jan 28 12:04:25 volumio3-nfj12v volumio[3648]: info: CorePlayQueue::getTrack 0 Jan 28 12:04:25 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::servicePause Jan 28 12:04:25 volumio3-nfj12v volumio[3648]: info: ControllerMpd::pause Jan 28 12:04:25 volumio3-nfj12v volumio[3648]: verbose: ControllerMpd::sendMpdCommand pause Jan 28 12:04:25 volumio3-nfj12v volumio[3648]: info: Jan 28 12:04:25 volumio3-nfj12v volumio[3648]: ---------------------------- MPD announces state update: player Jan 28 12:04:25 volumio3-nfj12v volumio[3648]: info: sendMpdCommand pause took 19 milliseconds Jan 28 12:04:25 volumio3-nfj12v volumio[3648]: info: ControllerMpd::getState Jan 28 12:04:25 volumio3-nfj12v volumio[3648]: verbose: ControllerMpd::sendMpdCommand status Jan 28 12:04:25 volumio3-nfj12v volumio[3648]: info: Jan 28 12:04:25 volumio3-nfj12v volumio[3648]: ---------------------------- MPD announces state update: player Jan 28 12:04:25 volumio3-nfj12v volumio[3648]: info: ControllerMpd::getState Jan 28 12:04:25 volumio3-nfj12v volumio[3648]: verbose: ControllerMpd::sendMpdCommand status Jan 28 12:04:25 volumio3-nfj12v volumio[3648]: info: Jan 28 12:04:25 volumio3-nfj12v volumio[3648]: ---------------------------- MPD announces state update: player Jan 28 12:04:25 volumio3-nfj12v volumio[3648]: info: ControllerMpd::getState Jan 28 12:04:25 volumio3-nfj12v volumio[3648]: verbose: ControllerMpd::sendMpdCommand status Jan 28 12:04:25 volumio3-nfj12v volumio[3648]: info: sendMpdCommand status took 40 milliseconds Jan 28 12:04:25 volumio3-nfj12v volumio[3648]: info: sendMpdCommand status took 27 milliseconds Jan 28 12:04:25 volumio3-nfj12v volumio[3648]: info: sendMpdCommand status took 16 milliseconds Jan 28 12:04:25 volumio3-nfj12v volumio[3648]: verbose: ControllerMpd::parseState Jan 28 12:04:25 volumio3-nfj12v volumio[3648]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 12:04:25 volumio3-nfj12v volumio[3648]: verbose: ControllerMpd::parseState Jan 28 12:04:25 volumio3-nfj12v volumio[3648]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 12:04:25 volumio3-nfj12v volumio[3648]: verbose: ControllerMpd::parseState Jan 28 12:04:25 volumio3-nfj12v volumio[3648]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 12:04:25 volumio3-nfj12v volumio[3648]: info: sendMpdCommand playlistinfo took 32 milliseconds Jan 28 12:04:25 volumio3-nfj12v volumio[3648]: info: sendMpdCommand playlistinfo took 31 milliseconds Jan 28 12:04:25 volumio3-nfj12v volumio[3648]: verbose: ControllerMpd::parseTrackInfo Jan 28 12:04:25 volumio3-nfj12v volumio[3648]: info: ControllerMpd::pushState Jan 28 12:04:25 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::servicePushState Jan 28 12:04:25 volumio3-nfj12v volumio[3648]: info: CorePlayQueue::getTrack 0 Jan 28 12:04:25 volumio3-nfj12v volumio[3648]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":49257,"duration":252,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"TIMECAPSULE","artist":"Davichi","album":"2025 - TIMECAPSULE","uri":"USB/USB-32GB/Music/Davichi ★/Davichi - 2025 - TIMECAPSULE/01. TIMECAPSULE.mp3","trackType":"mp3"} Jan 28 12:04:25 volumio3-nfj12v volumio[3648]: verbose: CURRENT POSITION 0 Jan 28 12:04:25 volumio3-nfj12v volumio[3648]: info: CoreStateMachine::syncState stateService pause Jan 28 12:04:25 volumio3-nfj12v volumio[3648]: info: CoreStateMachine::syncState currentStatus pause Jan 28 12:04:25 volumio3-nfj12v volumio[3648]: info: CoreStateMachine::pushState Jan 28 12:04:25 volumio3-nfj12v volumio[3648]: info: CorePlayQueue::getTrack 0 Jan 28 12:04:25 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 12:04:25 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::volumioPushState Jan 28 12:04:25 volumio3-nfj12v volumio[3648]: info: CoreStateMachine::stPlaybackTimer Jan 28 12:04:25 volumio3-nfj12v volumio[3648]: info: ControllerMpd::pushState Jan 28 12:04:25 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::servicePushState Jan 28 12:04:25 volumio3-nfj12v volumio[3648]: info: CorePlayQueue::getTrack 0 Jan 28 12:04:25 volumio3-nfj12v volumio[3648]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":49257,"duration":252,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"TIMECAPSULE","artist":"Davichi","album":"2025 - TIMECAPSULE","uri":"USB/USB-32GB/Music/Davichi ★/Davichi - 2025 - TIMECAPSULE/01. TIMECAPSULE.mp3","trackType":"mp3"} Jan 28 12:04:25 volumio3-nfj12v volumio[3648]: verbose: CURRENT POSITION 0 Jan 28 12:04:25 volumio3-nfj12v volumio[3648]: info: CoreStateMachine::syncState stateService pause Jan 28 12:04:25 volumio3-nfj12v volumio[3648]: info: CoreStateMachine::syncState currentStatus pause Jan 28 12:04:25 volumio3-nfj12v volumio[3648]: info: CoreStateMachine::pushState Jan 28 12:04:25 volumio3-nfj12v volumio[3648]: info: CorePlayQueue::getTrack 0 Jan 28 12:04:25 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::volumioPushState Jan 28 12:04:25 volumio3-nfj12v volumio[3648]: info: CoreStateMachine::stPlaybackTimer Jan 28 12:04:25 volumio3-nfj12v volumio[3648]: info: ControllerMpd::pushState Jan 28 12:04:25 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::servicePushState Jan 28 12:04:25 volumio3-nfj12v volumio[3648]: info: CorePlayQueue::getTrack 0 Jan 28 12:04:25 volumio3-nfj12v volumio[3648]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":49257,"duration":252,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"TIMECAPSULE","artist":"Davichi","album":"2025 - TIMECAPSULE","uri":"USB/USB-32GB/Music/Davichi ★/Davichi - 2025 - TIMECAPSULE/01. TIMECAPSULE.mp3","trackType":"mp3"} Jan 28 12:04:25 volumio3-nfj12v volumio[3648]: verbose: CURRENT POSITION 0 Jan 28 12:04:25 volumio3-nfj12v volumio[3648]: info: CoreStateMachine::syncState stateService pause Jan 28 12:04:25 volumio3-nfj12v volumio[3648]: info: CoreStateMachine::syncState currentStatus pause Jan 28 12:04:25 volumio3-nfj12v volumio[3648]: info: CoreStateMachine::pushState Jan 28 12:04:25 volumio3-nfj12v volumio[3648]: info: CorePlayQueue::getTrack 0 Jan 28 12:04:25 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::volumioPushState Jan 28 12:04:25 volumio3-nfj12v volumio[3648]: info: CoreStateMachine::stPlaybackTimer Jan 28 12:04:25 volumio3-nfj12v volumio[3648]: info: ------------------------------ 333ms Jan 28 12:04:25 volumio3-nfj12v volumio[3648]: info: ------------------------------ 318ms Jan 28 12:04:25 volumio3-nfj12v volumio[3648]: info: ------------------------------ 308ms Jan 28 12:04:33 volumio3-nfj12v volumio[3648]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jan 28 12:04:33 volumio3-nfj12v volumio[3648]: info: CURURI: artists:// Jan 28 12:04:34 volumio3-nfj12v volumio[3648]: info: Preload queue cleared Jan 28 12:04:35 volumio3-nfj12v volumio[3648]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 28 12:04:35 volumio3-nfj12v volumio[3648]: Error: connect ECONNREFUSED 158.69.209.125:443 Jan 28 12:04:35 volumio3-nfj12v volumio[3648]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Jan 28 12:04:35 volumio3-nfj12v volumio[3648]: errno: -111, Jan 28 12:04:35 volumio3-nfj12v volumio[3648]: code: 'ECONNREFUSED', Jan 28 12:04:35 volumio3-nfj12v volumio[3648]: syscall: 'connect', Jan 28 12:04:35 volumio3-nfj12v volumio[3648]: address: '158.69.209.125', Jan 28 12:04:35 volumio3-nfj12v volumio[3648]: port: 443 Jan 28 12:04:35 volumio3-nfj12v volumio[3648]: } Jan 28 12:04:35 volumio3-nfj12v volumio[3648]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 28 12:04:38 volumio3-nfj12v sudo[4092]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-01-28 12:03 Jan 28 12:04:38 volumio3-nfj12v sudo[4092]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="49c352e1d55e9b76c3bd7b0e3940507619bf455a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="1d32690fc900ac8c739e7eabd35ed0f570899eb8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 19 Dec 2025 02:07:26 PM CET" VOLUMIO_VERSION="3.887" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8f8c1d964af93dfdda0f02ac4140eec2"