-- Logs begin at Sun 2025-06-29 13:12:23 +07, end at Wed 2025-07-02 20:08:09 +07. -- Jul 02 20:07:00 volumio9 volumio[821]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 02 20:07:00 volumio9 volumio[821]: info: CURURI: music-library Jul 02 20:07:00 volumio9 volumio[821]: error: Failed LSINFO: null Jul 02 20:07:00 volumio9 volumio[821]: info: Preload queue cleared Jul 02 20:07:01 volumio9 volumio[821]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 02 20:07:01 volumio9 volumio[821]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 02 20:07:01 volumio9 volumio[821]: info: Discovery: Getting this device information Jul 02 20:07:01 volumio9 volumio[821]: info: CoreCommandRouter::volumioGetState Jul 02 20:07:01 volumio9 volumio[821]: info: CorePlayQueue::getTrack 0 Jul 02 20:07:01 volumio9 volumio[821]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 02 20:07:04 volumio9 volumio[821]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 02 20:07:04 volumio9 volumio[821]: info: Preload queue cleared Jul 02 20:07:05 volumio9 go-librespot[1153]: time="2025-07-02T20:07:05+07:00" level=trace msg="received accesspoint ping" Jul 02 20:07:05 volumio9 go-librespot[1153]: time="2025-07-02T20:07:05+07:00" level=trace msg="received accesspoint pong ack" Jul 02 20:07:06 volumio9 go-librespot[1153]: time="2025-07-02T20:07:06+07:00" level=trace msg="sent dealer ping" Jul 02 20:07:06 volumio9 go-librespot[1153]: time="2025-07-02T20:07:06+07:00" level=trace msg="received dealer pong" Jul 02 20:07:08 volumio9 volumio[821]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jul 02 20:07:08 volumio9 volumio[821]: info: Preload queue cleared Jul 02 20:07:14 volumio9 volumio[821]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 02 20:07:14 volumio9 volumio[821]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 02 20:07:14 volumio9 volumio[821]: info: Retrieving Cloud Streaming UI Jul 02 20:07:14 volumio9 volumio[821]: info: Getting Tidal Cloud Configuration Jul 02 20:07:14 volumio9 volumio[821]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 02 20:07:14 volumio9 volumio[821]: info: Getting Qobuz Cloud Configuration Jul 02 20:07:14 volumio9 volumio[821]: info: Asking plugin for UI Config Jul 02 20:07:14 volumio9 volumio[821]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 02 20:07:14 volumio9 volumio[821]: info: Getting Spotify Cloud Configuration Jul 02 20:07:14 volumio9 volumio[821]: info: Asking plugin for UI Config Jul 02 20:07:14 volumio9 volumio[821]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 02 20:07:14 volumio9 volumio[821]: info: Saving Spotify Acccount Jul 02 20:07:14 volumio9 volumio[821]: info: Got Tidal Cloud Configuration Jul 02 20:07:14 volumio9 volumio[821]: info: Got it Jul 02 20:07:14 volumio9 volumio[821]: info: Got it Jul 02 20:07:14 volumio9 volumio[821]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jul 02 20:07:14 volumio9 volumio[821]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 02 20:07:14 volumio9 volumio[821]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Jul 02 20:07:14 volumio9 volumio[821]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 02 20:07:14 volumio9 volumio[821]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 02 20:07:14 volumio9 volumio[821]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 02 20:07:14 volumio9 volumio[821]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 02 20:07:14 volumio9 volumio[821]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 02 20:07:14 volumio9 volumio[821]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 02 20:07:14 volumio9 volumio[821]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 02 20:07:14 volumio9 volumio[821]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 02 20:07:14 volumio9 volumio[821]: info: CoreCommandRouter::volumioGetBrowseSources Jul 02 20:07:14 volumio9 volumio[821]: info: CoreCommandRouter::volumioGetBrowseSources Jul 02 20:07:14 volumio9 volumio[821]: info: CoreCommandRouter::volumioGetBrowseSources Jul 02 20:07:14 volumio9 volumio[821]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 02 20:07:15 volumio9 volumio[821]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jul 02 20:07:18 volumio9 volumio[821]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 02 20:07:22 volumio9 volumio[821]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 02 20:07:25 volumio9 volumio[821]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Jul 02 20:07:26 volumio9 volumio[821]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jul 02 20:07:26 volumio9 sudo[1253]: pam_unix(sudo:session): session closed for user root Jul 02 20:07:26 volumio9 volumio[821]: info: Cannot mount NAS Hires_Music at system boot, trial number 2 ,retrying in 5 seconds Jul 02 20:07:26 volumio9 volumio[821]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 02 20:07:28 volumio9 volumio[821]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Jul 02 20:07:29 volumio9 volumio[821]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jul 02 20:07:30 volumio9 volumio[821]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 02 20:07:31 volumio9 volumio[821]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 02 20:07:31 volumio9 volumio[821]: TypeError: Cannot read property 'replace' of undefined Jul 02 20:07:31 volumio9 volumio[821]: at ControllerNetworkfs.mountShare (/volumio/app/plugins/system_controller/networkfs/index.js:202:28) Jul 02 20:07:31 volumio9 volumio[821]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/networkfs/index.js:255:20) Jul 02 20:07:31 volumio9 volumio[821]: at listOnTimeout (internal/timers.js:554:17) Jul 02 20:07:31 volumio9 volumio[821]: at processTimers (internal/timers.js:497:7) Jul 02 20:07:31 volumio9 volumio[821]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 02 20:07:32 volumio9 sudo[2562]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-02 20:06 Jul 02 20:07:32 volumio9 sudo[2562]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 20:07:32 volumio9 sudo[2562]: pam_unix(sudo:session): session closed for user root Jul 02 20:07:32 volumio9 go-librespot[1153]: time="2025-07-02T20:07:32+07:00" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" Jul 02 20:07:33 volumio9 volumio-remote-updater[618]: [2025-07-02 20:07:32] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jul 02 20:07:33 volumio9 volumio-remote-updater[618]: [2025-07-02 20:07:32] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jul 02 20:07:33 volumio9 systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jul 02 20:07:33 volumio9 systemd[1]: volumio.service: Failed with result 'exit-code'. Jul 02 20:07:33 volumio9 systemd[1]: Started dynamicswap service. Jul 02 20:07:33 volumio9 systemd[1]: dynamicswap.service: Succeeded. Jul 02 20:07:33 volumio9 systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jul 02 20:07:33 volumio9 systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Jul 02 20:07:33 volumio9 systemd[1]: Started dynamicswap service. Jul 02 20:07:33 volumio9 systemd[1]: Stopped Volumio Backend Module. Jul 02 20:07:33 volumio9 systemd[1]: Started Volumio Backend Module. Jul 02 20:07:33 volumio9 systemd[1]: dynamicswap.service: Succeeded. Jul 02 20:07:33 volumio9 kernel: usb 1-1.1: USB disconnect, device number 17 Jul 02 20:07:33 volumio9 systemd-udevd[2589]: Process '/bin/bash -c '/usr/local/bin/volumio usbdetach'' failed with exit code 7. Jul 02 20:07:33 volumio9 kernel: usb 1-1.1: new high-speed USB device number 18 using xhci_hcd Jul 02 20:07:34 volumio9 kernel: usb 1-1.1: New USB device found, idVendor=152a, idProduct=85dd, bcdDevice= 3.53 Jul 02 20:07:34 volumio9 kernel: usb 1-1.1: New USB device strings: Mfr=1, Product=3, SerialNumber=2 Jul 02 20:07:34 volumio9 kernel: usb 1-1.1: Product: SMSL USB AUDIO Jul 02 20:07:34 volumio9 kernel: usb 1-1.1: Manufacturer: SMSL Jul 02 20:07:34 volumio9 kernel: usb 1-1.1: 1:3 : unsupported format bits 0x100000000 Jul 02 20:07:34 volumio9 kernel: hid-generic 0003:152A:85DD.000B: hiddev96,hidraw0: USB HID v1.10 Device [SMSL SMSL USB AUDIO] on usb-0000:01:00.0-1.1/input3 Jul 02 20:07:34 volumio9 volumio[2576]: info: ------------------------------------------- Jul 02 20:07:34 volumio9 volumio[2576]: info: ----- Volumio3 ---- Jul 02 20:07:34 volumio9 volumio[2576]: info: ------------------------------------------- Jul 02 20:07:34 volumio9 volumio[2576]: info: ----- System startup ---- Jul 02 20:07:34 volumio9 volumio[2576]: info: ------------------------------------------- Jul 02 20:07:34 volumio9 volumio[2576]: info: MYVOLUMIO Environment detected Jul 02 20:07:34 volumio9 volumio[2576]: info: Plugin folders cleanup Jul 02 20:07:34 volumio9 volumio[2576]: info: Scanning into folder /volumio/app/plugins/ Jul 02 20:07:34 volumio9 volumio[2576]: info: Scanning category audio_interface Jul 02 20:07:34 volumio9 volumio[2576]: info: Scanning category miscellanea Jul 02 20:07:34 volumio9 volumio[2576]: info: Scanning category music_service Jul 02 20:07:34 volumio9 volumio[2576]: info: Scanning category plugins.json Jul 02 20:07:34 volumio9 volumio[2576]: info: Scanning category system_controller Jul 02 20:07:34 volumio9 volumio[2576]: info: Scanning category user_interface Jul 02 20:07:34 volumio9 volumio[2576]: info: Scanning into folder /data/plugins/ Jul 02 20:07:34 volumio9 volumio[2576]: info: Scanning category music_service Jul 02 20:07:34 volumio9 volumio[2576]: info: Scanning category user_interface Jul 02 20:07:34 volumio9 volumio[2576]: info: Plugin folders cleanup completed Jul 02 20:07:34 volumio9 volumio[2576]: info: ------------------------------------------- Jul 02 20:07:34 volumio9 volumio[2576]: info: ----- Core plugins startup ---- Jul 02 20:07:34 volumio9 volumio[2576]: info: ------------------------------------------- Jul 02 20:07:34 volumio9 volumio[2576]: info: Loading plugins from folder /volumio/app/plugins/ Jul 02 20:07:34 volumio9 volumio[2576]: info: Adding plugin upnp to MyMusic Plugins Jul 02 20:07:34 volumio9 volumio[2576]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 02 20:07:34 volumio9 volumio[2576]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 02 20:07:34 volumio9 volumio[2576]: info: Loading plugins from folder /data/plugins/ Jul 02 20:07:34 volumio9 volumio[2576]: info: Loading plugin "system"... Jul 02 20:07:34 volumio9 volumio[2576]: info: Loading plugin "appearance"... Jul 02 20:07:35 volumio9 volumio[2576]: info: Loading plugin "network"... Jul 02 20:07:35 volumio9 volumio[2576]: info: Refreshing Cached IP Addresses Jul 02 20:07:35 volumio9 sudo[2616]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 02 20:07:35 volumio9 sudo[2616]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 20:07:35 volumio9 volumio[2576]: info: Loading plugin "services"... Jul 02 20:07:35 volumio9 sudo[2618]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 02 20:07:35 volumio9 sudo[2618]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 20:07:35 volumio9 sudo[2616]: pam_unix(sudo:session): session closed for user root Jul 02 20:07:35 volumio9 sudo[2618]: pam_unix(sudo:session): session closed for user root Jul 02 20:07:35 volumio9 volumio[2576]: info: Loading plugin "alsa_controller"... Jul 02 20:07:35 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 02 20:07:35 volumio9 volumio[2576]: info: Loading plugin "wizard"... Jul 02 20:07:35 volumio9 volumio[2576]: info: Loading plugin "networkfs"... Jul 02 20:07:35 volumio9 volumio[2576]: info: Starting Udev Watcher for removable devices Jul 02 20:07:35 volumio9 sudo[2639]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Santichai,password=MmXp3152,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.12/Hires Music /mnt/NAS/Hires_Music Jul 02 20:07:35 volumio9 sudo[2639]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 20:07:35 volumio9 kernel: CIFS: Attempting to mount //192.168.1.12/Hires Music Jul 02 20:07:35 volumio9 volumio[2576]: info: Ignoring mount for partition: boot Jul 02 20:07:35 volumio9 volumio[2576]: info: Ignoring mount for partition: volumio Jul 02 20:07:35 volumio9 volumio[2576]: info: Ignoring mount for partition: volumio_data Jul 02 20:07:35 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 02 20:07:35 volumio9 volumio[2576]: info: Loading plugin "volumio_command_line_client"... Jul 02 20:07:35 volumio9 volumio[2576]: info: Loading plugin "upnp"... Jul 02 20:07:35 volumio9 volumio[2576]: info: [1751461655627] Starting Upmpd Daemon Jul 02 20:07:35 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 02 20:07:35 volumio9 volumio[2576]: info: Loading plugin "my_music"... Jul 02 20:07:35 volumio9 volumio[2576]: info: Loading plugin "mpd"... Jul 02 20:07:35 volumio9 volumio[2576]: info: Loading plugin "upnp_browser"... Jul 02 20:07:36 volumio9 volumio[2576]: info: Loading plugin "alarm-clock"... Jul 02 20:07:36 volumio9 go-librespot[1153]: time="2025-07-02T20:07:36+07:00" level=trace msg="sent dealer ping" Jul 02 20:07:36 volumio9 volumio[2576]: info: Loading plugin "airplay_emulation"... Jul 02 20:07:36 volumio9 volumio[2576]: info: Starting Shairport Sync Jul 02 20:07:36 volumio9 volumio[2576]: info: Loading plugin "last_100"... Jul 02 20:07:36 volumio9 go-librespot[1153]: time="2025-07-02T20:07:36+07:00" level=trace msg="received dealer pong" Jul 02 20:07:36 volumio9 volumio[2576]: info: Loading plugin "webradio"... Jul 02 20:07:36 volumio9 volumio[2576]: info: Loading plugin "i2s_dacs"... Jul 02 20:07:36 volumio9 volumio[2576]: info: I2S DAC not set, start Auto-detection Jul 02 20:07:36 volumio9 volumio[2576]: info: Loading plugin "volumiodiscovery"... Jul 02 20:07:36 volumio9 volumio[2576]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 02 20:07:36 volumio9 volumio[2576]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 02 20:07:36 volumio9 volumio[2576]: *** WARNING *** For more information see Jul 02 20:07:36 volumio9 node[2576]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 02 20:07:36 volumio9 volumio[2576]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 02 20:07:36 volumio9 volumio[2576]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 02 20:07:36 volumio9 volumio[2576]: *** WARNING *** For more information see Jul 02 20:07:36 volumio9 node[2576]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 02 20:07:36 volumio9 node[2576]: *** WARNING *** For more information see Jul 02 20:07:36 volumio9 node[2576]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 02 20:07:36 volumio9 node[2576]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 02 20:07:36 volumio9 node[2576]: *** WARNING *** For more information see Jul 02 20:07:36 volumio9 volumio[2576]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 02 20:07:36 volumio9 volumio[2576]: info: Discovery: Started advertising with name: Volumio9 Jul 02 20:07:36 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 02 20:07:36 volumio9 volumio[2576]: info: Loading plugin "spop"... Jul 02 20:07:36 volumio9 volumio[2576]: info: Loading plugin "outputs"... Jul 02 20:07:36 volumio9 volumio[2576]: info: Loading plugin "albumart"... Jul 02 20:07:36 volumio9 volumio[2576]: info: Plugin example_plugin is not enabled Jul 02 20:07:36 volumio9 volumio[2576]: info: Loading plugin "inputs"... Jul 02 20:07:36 volumio9 volumio[2576]: info: Loading plugin "updater_comm"... Jul 02 20:07:37 volumio9 volumio[2576]: info: Plugin mpdemulation is not enabled Jul 02 20:07:37 volumio9 volumio[2576]: info: Loading plugin "rest_api"... Jul 02 20:07:37 volumio9 volumio[2576]: info: Loading plugin "websocket"... Jul 02 20:07:37 volumio9 volumio[2576]: info: Starting Socket.io Server version 2.3.0 Jul 02 20:07:37 volumio9 volumio[2576]: info: Plugin peppymeterbasic is not enabled Jul 02 20:07:37 volumio9 volumio[2576]: info: Loading plugin "touch_display"... Jul 02 20:07:37 volumio9 volumio[2576]: Forking 3 albumart workers Jul 02 20:07:37 volumio9 volumio[2576]: info: Applying required configuration parameters for plugin touch_display Jul 02 20:07:37 volumio9 volumio[2576]: info: Loading i18n strings for locale th Jul 02 20:07:37 volumio9 volumio[2576]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Jul 02 20:07:37 volumio9 volumio[2576]: Updating browse sources language Jul 02 20:07:37 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 02 20:07:37 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 02 20:07:37 volumio9 volumio[2576]: info: CoreCommandRouter::initPlayerControls Jul 02 20:07:37 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 02 20:07:37 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 02 20:07:37 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 02 20:07:37 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 02 20:07:37 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 02 20:07:37 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 02 20:07:37 volumio9 volumio[2576]: Express server listening on port 3000 Jul 02 20:07:37 volumio9 volumio[2576]: [Metrics] WebUI: 3s 516.04ms Jul 02 20:07:37 volumio9 volumio[2576]: info: CoreStateMachine::resetVolumioState Jul 02 20:07:37 volumio9 volumio[2576]: info: CoreStateMachine::getcurrentVolume Jul 02 20:07:37 volumio9 volumio[2576]: info: CoreCommandRouter::volumioRetrievevolume Jul 02 20:07:37 volumio9 volumio[2576]: info: CoreStateMachine::pushState Jul 02 20:07:37 volumio9 volumio[2576]: info: CorePlayQueue::getTrack 0 Jul 02 20:07:37 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 20:07:37 volumio9 volumio[2576]: info: CoreCommandRouter::volumioPushState Jul 02 20:07:37 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 02 20:07:37 volumio9 volumio[2576]: info: Volumio Network Manager: Network status updated: 1 Jul 02 20:07:37 volumio9 volumio[2576]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Jul 02 20:07:37 volumio9 volumio[2576]: verbose: New Socket.io Connection to 192.168.1.74:3000 from 192.168.1.73 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 2 Jul 02 20:07:37 volumio9 volumio[2576]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Jul 02 20:07:37 volumio9 volumio[2576]: verbose: New Socket.io Connection to 192.168.1.74 from 192.168.1.73 UA: Mozilla/5.0 (Linux; Android 13; 2201117TG Build/TKQ1.221114.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.117 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Jul 02 20:07:37 volumio9 volumio[2576]: Starting albumart workers Jul 02 20:07:37 volumio9 volumio[2576]: Starting albumart workers Jul 02 20:07:37 volumio9 volumio[2576]: info: Jul 02 20:07:37 volumio9 volumio[2576]: ---------------------------- USB Audio Device Attached Jul 02 20:07:37 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Jul 02 20:07:37 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 02 20:07:37 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 02 20:07:37 volumio9 volumio[2576]: info: Discovery: Getting this device information Jul 02 20:07:37 volumio9 volumio[2576]: info: CoreCommandRouter::volumioGetState Jul 02 20:07:37 volumio9 volumio[2576]: info: CorePlayQueue::getTrack 0 Jul 02 20:07:37 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 02 20:07:37 volumio9 volumio[2576]: Starting albumart workers Jul 02 20:07:37 volumio9 volumio[2576]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jul 02 20:07:37 volumio9 volumio[2576]: info: Reloading queue from file Jul 02 20:07:37 volumio9 volumio[2576]: info: CoreStateMachine::setRepeat null single undefined Jul 02 20:07:37 volumio9 volumio[2576]: info: CoreStateMachine::pushState Jul 02 20:07:37 volumio9 volumio[2576]: info: CorePlayQueue::getTrack 0 Jul 02 20:07:37 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 20:07:37 volumio9 volumio[2576]: info: CoreCommandRouter::volumioPushState Jul 02 20:07:38 volumio9 volumio[2576]: info: CoreStateMachine::setRandom null Jul 02 20:07:38 volumio9 volumio[2576]: info: CoreStateMachine::pushState Jul 02 20:07:38 volumio9 volumio[2576]: info: CorePlayQueue::getTrack 0 Jul 02 20:07:38 volumio9 volumio[2576]: info: CoreCommandRouter::volumioPushState Jul 02 20:07:38 volumio9 volumio[2576]: info: Setting Device type: Raspberry PI Jul 02 20:07:38 volumio9 volumio-remote-updater[618]: [2025-07-02 20:07:38] [connect] Successful connection Jul 02 20:07:38 volumio9 volumio[2576]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jul 02 20:07:38 volumio9 volumio[2576]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03115 Jul 02 20:07:38 volumio9 volumio[2576]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jul 02 20:07:38 volumio9 volumio[2576]: verbose: New Socket.io Connection to 192.168.1.74:3000 from 192.168.1.73 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Jul 02 20:07:38 volumio9 volumio-remote-updater[618]: [2025-07-02 20:07:38] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1751461658 101 Jul 02 20:07:38 volumio9 volumio[2576]: 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: 7 Jul 02 20:07:38 volumio9 volumio[2576]: info: CoreCommandRouter::volumioGetState Jul 02 20:07:38 volumio9 volumio[2576]: info: CorePlayQueue::getTrack 0 Jul 02 20:07:38 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jul 02 20:07:38 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jul 02 20:07:38 volumio9 volumio[2576]: info: Discovery: Browse raised the following error Error: getaddrinfo -3007 Jul 02 20:07:38 volumio9 volumio[2576]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Jul 02 20:07:39 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 02 20:07:39 volumio9 volumio[2576]: info: CoreCommandRouter::volumioGetVisibleSources Jul 02 20:07:39 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 02 20:07:39 volumio9 volumio[2576]: info: CoreCommandRouter::volumioGetState Jul 02 20:07:39 volumio9 volumio[2576]: info: CorePlayQueue::getTrack 0 Jul 02 20:07:39 volumio9 volumio[2576]: info: CoreCommandRouter::volumioGetState Jul 02 20:07:39 volumio9 volumio[2576]: info: CorePlayQueue::getTrack 0 Jul 02 20:07:39 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 02 20:07:39 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 02 20:07:39 volumio9 volumio[2576]: info: Received Get System Info Jul 02 20:07:39 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 02 20:07:39 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 02 20:07:39 volumio9 volumio[2576]: info: Discovery: Getting this device information Jul 02 20:07:39 volumio9 volumio[2576]: info: CoreCommandRouter::volumioGetState Jul 02 20:07:39 volumio9 volumio[2576]: info: CorePlayQueue::getTrack 0 Jul 02 20:07:39 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 02 20:07:39 volumio9 volumio[2576]: info: CoreCommandRouter::volumioGetState Jul 02 20:07:39 volumio9 volumio[2576]: info: CorePlayQueue::getTrack 0 Jul 02 20:07:39 volumio9 volumio[2576]: info: Listing playlists Jul 02 20:07:39 volumio9 volumio[2576]: verbose: New Socket.io Connection to 192.168.1.74 from 192.168.1.73 UA: Mozilla/5.0 (Linux; Android 13; 2201117TG Build/TKQ1.221114.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.117 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Jul 02 20:07:39 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 02 20:07:39 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 02 20:07:39 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 02 20:07:39 volumio9 volumio[2576]: info: CoreCommandRouter::volumioGetVisibleSources Jul 02 20:07:39 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 02 20:07:39 volumio9 volumio[2576]: info: CoreCommandRouter::volumioGetState Jul 02 20:07:39 volumio9 volumio[2576]: info: CorePlayQueue::getTrack 0 Jul 02 20:07:39 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 02 20:07:39 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 02 20:07:39 volumio9 volumio[2576]: info: Received Get System Info Jul 02 20:07:39 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 02 20:07:39 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 02 20:07:39 volumio9 volumio[2576]: info: Discovery: Getting this device information Jul 02 20:07:39 volumio9 volumio[2576]: info: CoreCommandRouter::volumioGetState Jul 02 20:07:39 volumio9 volumio[2576]: info: CorePlayQueue::getTrack 0 Jul 02 20:07:39 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 02 20:07:39 volumio9 volumio[2576]: info: CoreCommandRouter::volumioGetState Jul 02 20:07:39 volumio9 volumio[2576]: info: CorePlayQueue::getTrack 0 Jul 02 20:07:39 volumio9 volumio[2576]: info: Listing playlists Jul 02 20:07:39 volumio9 volumio[2576]: info: CoreCommandRouter::volumioGetQueue Jul 02 20:07:39 volumio9 volumio[2576]: info: CoreStateMachine::getQueue Jul 02 20:07:39 volumio9 volumio[2576]: info: CorePlayQueue::getQueue Jul 02 20:07:41 volumio9 sudo[2639]: pam_unix(sudo:session): session closed for user root Jul 02 20:07:41 volumio9 volumio[2576]: info: Cannot mount NAS Hires_Music at system boot, trial number 1 ,retrying in 5 seconds Jul 02 20:07:41 volumio9 volumio[2576]: info: Completed loading Core Plugins Jul 02 20:07:41 volumio9 volumio[2576]: info: Preparing to generate the ALSA configuration file Jul 02 20:07:41 volumio9 kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jul 02 20:07:41 volumio9 kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Jul 02 20:07:41 volumio9 volumio[2576]: info: Asound.conf file unchanged, so no further update is needed Jul 02 20:07:41 volumio9 volumio[2576]: info: Output device has changed, restarting MPD Jul 02 20:07:41 volumio9 sudo[2708]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 02 20:07:41 volumio9 sudo[2708]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 20:07:41 volumio9 volumio[2576]: info: Output device has changed, restarting Shairport Sync Jul 02 20:07:41 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 02 20:07:41 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 02 20:07:41 volumio9 sudo[2708]: pam_unix(sudo:session): session closed for user root Jul 02 20:07:41 volumio9 sudo[2710]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 02 20:07:41 volumio9 sudo[2710]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 20:07:41 volumio9 systemd[1]: Stopping Music Player Daemon... Jul 02 20:07:41 volumio9 volumio[2576]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 02 20:07:41 volumio9 volumio[2576]: info: ___________ START PLUGINS ___________ Jul 02 20:07:41 volumio9 volumio[2576]: info: ControllerMpd::onStart: Initializing MPD Jul 02 20:07:41 volumio9 volumio[2576]: info: Creating MPD Configuration file Jul 02 20:07:41 volumio9 systemd[1]: mpd.service: Succeeded. Jul 02 20:07:41 volumio9 systemd[1]: Stopped Music Player Daemon. Jul 02 20:07:42 volumio9 sudo[2718]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 02 20:07:42 volumio9 sudo[2718]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 20:07:42 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 02 20:07:42 volumio9 volumio[2576]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 02 20:07:42 volumio9 volumio[2576]: info: [1751461662022] CoreMusicLibrary::Adding element Media Servers Jul 02 20:07:42 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 02 20:07:42 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 02 20:07:42 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 02 20:07:42 volumio9 sudo[2718]: pam_unix(sudo:session): session closed for user root Jul 02 20:07:42 volumio9 sudo[2721]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 02 20:07:42 volumio9 systemd[1]: Starting Music Player Daemon... Jul 02 20:07:42 volumio9 sudo[2721]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 20:07:42 volumio9 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jul 02 20:07:42 volumio9 systemd[1]: mpd.service: Succeeded. Jul 02 20:07:42 volumio9 systemd[1]: Stopped Music Player Daemon. Jul 02 20:07:42 volumio9 systemd[1]: Starting Music Player Daemon... Jul 02 20:07:42 volumio9 volumio[2576]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 02 20:07:42 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 02 20:07:42 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 02 20:07:42 volumio9 volumio[2576]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 02 20:07:42 volumio9 volumio[2576]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 02 20:07:42 volumio9 volumio[2576]: info: [1751461662116] CoreMusicLibrary::Adding element Last_100 Jul 02 20:07:42 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 02 20:07:42 volumio9 volumio[2576]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 02 20:07:42 volumio9 volumio[2576]: info: [1751461662118] CoreMusicLibrary::Adding element Webradio Jul 02 20:07:42 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 02 20:07:42 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 02 20:07:42 volumio9 volumio[2576]: info: Initializing BBC Radios Jul 02 20:07:42 volumio9 sudo[2726]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 02 20:07:42 volumio9 sudo[2726]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 20:07:42 volumio9 sudo[2726]: pam_unix(sudo:session): session closed for user root Jul 02 20:07:42 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 02 20:07:42 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 02 20:07:42 volumio9 volumio[2576]: info: Creating Spotify config file Jul 02 20:07:42 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 02 20:07:42 volumio9 volumio[2576]: info: Loading i18n strings for locale th Jul 02 20:07:42 volumio9 volumio[2576]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Jul 02 20:07:42 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 02 20:07:42 volumio9 volumio[2576]: info: Volumio Calling Home Jul 02 20:07:42 volumio9 sudo[2746]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Jul 02 20:07:42 volumio9 sudo[2746]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 20:07:42 volumio9 sudo[2748]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Jul 02 20:07:42 volumio9 sudo[2748]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 20:07:42 volumio9 sudo[2751]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jul 02 20:07:42 volumio9 sudo[2751]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 20:07:42 volumio9 volumio[2576]: error: touch_display: Error detecting backlight interface: Error: ENOENT: no such file or directory, scandir '/sys/class/backlight/' Jul 02 20:07:42 volumio9 volumio[2576]: info: MPD Permissions set Jul 02 20:07:42 volumio9 volumio[2576]: info: MPD Permissions set Jul 02 20:07:42 volumio9 volumio[2576]: info: Spotify config file written Jul 02 20:07:42 volumio9 sudo[2746]: pam_unix(sudo:session): session closed for user root Jul 02 20:07:42 volumio9 systemd[1]: Reloading. Jul 02 20:07:42 volumio9 volumio[2576]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Jul 02 20:07:42 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 02 20:07:42 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 02 20:07:42 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 02 20:07:42 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 02 20:07:42 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 02 20:07:42 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 02 20:07:42 volumio9 sudo[2755]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jul 02 20:07:42 volumio9 sudo[2755]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 20:07:42 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 02 20:07:42 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 02 20:07:42 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 02 20:07:42 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 02 20:07:42 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 02 20:07:42 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 02 20:07:42 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 02 20:07:42 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 02 20:07:42 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 02 20:07:42 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 02 20:07:42 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 02 20:07:42 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 02 20:07:42 volumio9 volumio[2576]: info: No need to fix Spotify hosts Jul 02 20:07:42 volumio9 volumio[2576]: info: touch_display: systemctl stop getty@tty1.service succeeded. Jul 02 20:07:42 volumio9 volumio[2576]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Jul 02 20:07:42 volumio9 volumio[2576]: info: Starting Shairport Sync Jul 02 20:07:42 volumio9 sudo[2779]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Jul 02 20:07:42 volumio9 volumio[2576]: info: Starting Shairport Sync Jul 02 20:07:42 volumio9 sudo[2779]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 20:07:42 volumio9 sudo[2781]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 02 20:07:42 volumio9 sudo[2779]: pam_unix(sudo:session): session closed for user root Jul 02 20:07:42 volumio9 sudo[2781]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 20:07:42 volumio9 volumio[2576]: info: Starting Shairport Sync Jul 02 20:07:42 volumio9 sudo[2785]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 02 20:07:42 volumio9 sudo[2785]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 20:07:42 volumio9 volumio[2576]: info: CoreCommandRouter::volumioGetState Jul 02 20:07:42 volumio9 volumio[2576]: info: CorePlayQueue::getTrack 0 Jul 02 20:07:42 volumio9 sudo[2787]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 02 20:07:42 volumio9 sudo[2787]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 20:07:42 volumio9 systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jul 02 20:07:42 volumio9 volumio[2576]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Jul 02 20:07:42 volumio9 volumio[2576]: info: Volumio called home Jul 02 20:07:42 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 02 20:07:42 volumio9 volumio[2576]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jul 02 20:07:42 volumio9 volumio[2576]: SPOTIFY: BQCo7p5ZPmtClPL1y5GtfG5_vljdHMktYFkgfuug1OM2T-qxfi_ToIXILkzPxmL2Xv1C6OkKGwtAEIrB16logVlgibKUNZ_0HA32mF_4vNHA9ffy9B8zD0AClo8HoAnjTdF4FBMZXTkupNxWvaNFqdBZgwLPd0nykEXDvDN7ifZiG1VieTBIMH-A0N8hejXAMCy2He_BiOQDpzp-XmtttTbezd8fPA_4LN5b_y-pqa5_pRIlQ_RArDcQCZ1ld6XI7wj-_ZTG3ZRGG0YytwzyYl1TVzyaL2xnQNNMmXtlg4PKgIz2_o0 Jul 02 20:07:42 volumio9 volumio[2576]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jul 02 20:07:42 volumio9 volumio[2576]: info: New Spotify access token = BQCo7p5ZPmtClPL1y5GtfG5_vljdHMktYFkgfuug1OM2T-qxfi_ToIXILkzPxmL2Xv1C6OkKGwtAEIrB16logVlgibKUNZ_0HA32mF_4vNHA9ffy9B8zD0AClo8HoAnjTdF4FBMZXTkupNxWvaNFqdBZgwLPd0nykEXDvDN7ifZiG1VieTBIMH-A0N8hejXAMCy2He_BiOQDpzp-XmtttTbezd8fPA_4LN5b_y-pqa5_pRIlQ_RArDcQCZ1ld6XI7wj-_ZTG3ZRGG0YytwzyYl1TVzyaL2xnQNNMmXtlg4PKgIz2_o0 Jul 02 20:07:42 volumio9 volumio[2576]: info: Spotify credentials grant success - running version from March 24, 2019 Jul 02 20:07:43 volumio9 mpd[2734]: Jul 02 20:07 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 02 20:07:43 volumio9 systemd[1]: Started Music Player Daemon. Jul 02 20:07:43 volumio9 sudo[2751]: pam_unix(sudo:session): session closed for user root Jul 02 20:07:43 volumio9 sudo[2721]: pam_unix(sudo:session): session closed for user root Jul 02 20:07:43 volumio9 sudo[2710]: pam_unix(sudo:session): session closed for user root Jul 02 20:07:43 volumio9 volumio[2576]: info: touch_display: systemctl daemon-reload succeeded. Jul 02 20:07:43 volumio9 systemd[1]: Reloading. Jul 02 20:07:43 volumio9 sudo[2795]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Jul 02 20:07:43 volumio9 sudo[2795]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 20:07:43 volumio9 volumio[2576]: error: MPD error: The expression evaluated to a falsy value: Jul 02 20:07:43 volumio9 volumio[2576]: assert.ok(self.idling) Jul 02 20:07:43 volumio9 volumio[2576]: error: The expression evaluated to a falsy value: Jul 02 20:07:43 volumio9 volumio[2576]: assert.ok(self.idling) Jul 02 20:07:43 volumio9 volumio[2576]: info: MPD running with PID2734 Jul 02 20:07:43 volumio9 volumio[2576]: ,establishing connection Jul 02 20:07:43 volumio9 volumio[2576]: error: MPD error: The expression evaluated to a falsy value: Jul 02 20:07:43 volumio9 volumio[2576]: assert.ok(self.idling) Jul 02 20:07:43 volumio9 volumio[2576]: error: The expression evaluated to a falsy value: Jul 02 20:07:43 volumio9 volumio[2576]: assert.ok(self.idling) Jul 02 20:07:43 volumio9 volumio[2576]: error: updateQueue error: null Jul 02 20:07:43 volumio9 volumio[2576]: SPOTIFY: User informations: {"country":"TH","display_name":"ชนะ ชนะภัย","email":"schanapai@hotmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/31ujwh6hkh6kshabxeokjduu7j3e"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/31ujwh6hkh6kshabxeokjduu7j3e","id":"31ujwh6hkh6kshabxeokjduu7j3e","images":[{"height":300,"url":"https://scontent-cdg4-3.xx.fbcdn.net/v/t39.30808-1/405560085_6916165708500864_5513590859254949333_n.jpg?stp=c427.0.1518.1518a_dst-jpg_s240x240_tt6&_nc_cat=106&ccb=1-7&_nc_sid=79bf43&_nc_ohc=0FpSZJcCOEkQ7kNvwFzNl9H&_nc_oc=AdljQZr-IjfuZEJcnhWYNFlmF80LG7XwW_Ll03BJaLTa_DE4ACaJG0WE3Gz0p0H6-qHKmm_Y8SlBNytfPPTYNVI3&_nc_zt=24&_nc_ht=scontent-cdg4-3.xx&edm=AP4hL3IEAAAA&_nc_gid=GNItHep7QnpwnN17pB0dUQ&oh=00_AfP4YT_8dMbCnFFhKk-xTgl5C3lRABrxEXlveN34Wjb-vw&oe=686AD635","width":300},{"height":64,"url":"https://scontent-cdg4-3.xx.fbcdn.net/v/t39.30808-1/405560085_6916165708500864_5513590859254949333_n.jpg?stp=c427.0.1518.1518a_cp0_dst-jpg_s50x50_tt6&_nc_cat=106&ccb=1-7&_nc_sid=28885b&_nc_ohc=0FpSZJcCOEkQ7kNvwFzNl9H&_nc_oc=AdljQZr-IjfuZEJcnhWYNFlmF80LG7XwW_Ll03BJaLTa_DE4ACaJG0WE3Gz0p0H6-qHKmm_Y8SlBNytfPPTYNVI3&_nc_zt=24&_nc_ht=scontent-cdg4-3.xx&edm=AP4hL3IEAAAA&_nc_gid=GNItHep7QnpwnN17pB0dUQ&oh=00_AfNGRBS3OCPEo0Q0hQBSs7Rxj05aazjlytA7x1JeuSz5-Q&oe=686AD635","width":64}],"product":"premium","type":"user","uri":"spotify:user:31ujwh6hkh6kshabxeokjduu7j3e"} Jul 02 20:07:43 volumio9 volumio[2576]: info: Spotify Successfully logged in Jul 02 20:07:43 volumio9 volumio[2576]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 02 20:07:43 volumio9 volumio[2576]: info: [1751461663315] CoreMusicLibrary::Adding element Spotify Jul 02 20:07:43 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 02 20:07:43 volumio9 volumio[2576]: Cannot find translation for source Spotify Jul 02 20:07:43 volumio9 systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jul 02 20:07:43 volumio9 sudo[2748]: pam_unix(sudo:session): session closed for user root Jul 02 20:07:43 volumio9 volumio[2576]: info: touch_display: systemctl disable getty@tty1.service succeeded. Jul 02 20:07:43 volumio9 sudo[2795]: pam_unix(sudo:session): session closed for user root Jul 02 20:07:43 volumio9 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 02 20:07:43 volumio9 systemd[1]: Stopping go-librespot Daemon... Jul 02 20:07:43 volumio9 volumio[2576]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Jul 02 20:07:43 volumio9 volumio[2576]: info: touch_display: Volumio Kiosk started. Jul 02 20:07:43 volumio9 systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Jul 02 20:07:43 volumio9 systemd[1]: go-librespot-daemon.service: Succeeded. Jul 02 20:07:43 volumio9 systemd[1]: Stopped go-librespot Daemon. Jul 02 20:07:43 volumio9 systemd[1]: shairport-sync.service: Succeeded. Jul 02 20:07:43 volumio9 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 02 20:07:43 volumio9 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 02 20:07:43 volumio9 sudo[2785]: pam_unix(sudo:session): session closed for user root Jul 02 20:07:43 volumio9 systemd[1]: Started go-librespot Daemon. Jul 02 20:07:43 volumio9 sudo[2781]: pam_unix(sudo:session): session closed for user root Jul 02 20:07:43 volumio9 volumio[2576]: info: CoreCommandRouter::volumioGetState Jul 02 20:07:43 volumio9 volumio[2576]: info: CorePlayQueue::getTrack 0 Jul 02 20:07:43 volumio9 go-librespot[2813]: go-librespot daemon starting... Jul 02 20:07:43 volumio9 volumio[2576]: info: Completed starting Core Plugins Jul 02 20:07:43 volumio9 volumio[2576]: info: ------------------------------------------- Jul 02 20:07:43 volumio9 volumio[2576]: info: ----- MyVolumio plugins startup ---- Jul 02 20:07:43 volumio9 volumio[2576]: info: ------------------------------------------- Jul 02 20:07:43 volumio9 volumio[2576]: info: [MyVolumio PluginManager] Fetching plans data.... Jul 02 20:07:43 volumio9 sudo[2787]: pam_unix(sudo:session): session closed for user root Jul 02 20:07:43 volumio9 sudo[2755]: pam_unix(sudo:session): session closed for user root Jul 02 20:07:43 volumio9 volumio[2576]: info: CoreCommandRouter::volumioGetState Jul 02 20:07:43 volumio9 volumio[2576]: info: CorePlayQueue::getTrack 0 Jul 02 20:07:43 volumio9 volumio[2576]: info: Shairport-Sync Started Jul 02 20:07:43 volumio9 go-librespot[2813]: time="2025-07-02T20:07:43+07:00" level=info msg="running go-librespot 0.2.0" Jul 02 20:07:43 volumio9 go-librespot[2813]: time="2025-07-02T20:07:43+07:00" level=debug msg="app state loaded" Jul 02 20:07:43 volumio9 go-librespot[2813]: time="2025-07-02T20:07:43+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 02 20:07:43 volumio9 volumio[2576]: Error adding Membership: Error: addMembership EINVAL Jul 02 20:07:43 volumio9 volumio[2576]: info: Shairport-Sync Started Jul 02 20:07:43 volumio9 volumio[2576]: info: Shairport-Sync Started Jul 02 20:07:43 volumio9 volumio[2576]: info: touch_display: X display number found: 0 Jul 02 20:07:43 volumio9 volumio[2576]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Jul 02 20:07:43 volumio9 volumio[2576]: info: touch_display: X display number found: 0 Jul 02 20:07:43 volumio9 volumio[2576]: info: CoreCommandRouter::volumioGetState Jul 02 20:07:43 volumio9 volumio[2576]: info: CorePlayQueue::getTrack 0 Jul 02 20:07:43 volumio9 volumio[2576]: info: touch_display: Setting screensaver timeout to 45 seconds. Jul 02 20:07:44 volumio9 go-librespot[2813]: time="2025-07-02T20:07:44+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jul 02 20:07:44 volumio9 go-librespot[2813]: time="2025-07-02T20:07:44+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Jul 02 20:07:44 volumio9 go-librespot[2813]: time="2025-07-02T20:07:44+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Jul 02 20:07:44 volumio9 go-librespot[2813]: time="2025-07-02T20:07:44+07:00" level=info msg="zeroconf server listening on port 36663" Jul 02 20:07:44 volumio9 go-librespot[2813]: time="2025-07-02T20:07:44+07:00" level=debug msg="obtained new client token: AACHgG01ezWv9CIjoHifPGJVOHcqZ89Z1I/9WdZCRPNCNh48cwOyia0Odi1eZlLjlmLlltPpjgEIvOYkfkG99J1XBkD+HBWI0MsgG3+sw8UXe8fq1QvFRMCWrBbZBuiytuCh+2rhnbQXl9h1hjDiYELkf1kxN0Zi0bPvSGKfHTq4Y5feIIlD6CSuMrTSdjcgJHyWvwvop0ynr2qsxeavfjCcvu0IK2PVpUxXoKTs500NiZhREUDhE/E=" Jul 02 20:07:44 volumio9 go-librespot[2813]: time="2025-07-02T20:07:44+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Jul 02 20:07:44 volumio9 go-librespot[2813]: time="2025-07-02T20:07:44+07:00" level=debug msg="completed keyexchange" Jul 02 20:07:44 volumio9 go-librespot[2813]: time="2025-07-02T20:07:44+07:00" level=debug msg="completed challenge" Jul 02 20:07:44 volumio9 go-librespot[2813]: time="2025-07-02T20:07:44+07:00" level=info msg="authenticated AP as 31ujwh6hkh6kshabxeokjduu7j3e" Jul 02 20:07:44 volumio9 go-librespot[2813]: time="2025-07-02T20:07:44+07:00" level=info msg="authenticated Login5 as 31ujwh6hkh6kshabxeokjduu7j3e" Jul 02 20:07:44 volumio9 go-librespot[2813]: time="2025-07-02T20:07:44+07:00" level=debug msg="initializing zeroconf session, username: 31ujwh6hkh6kshabxeokjduu7j3e" Jul 02 20:07:44 volumio9 go-librespot[2813]: time="2025-07-02T20:07:44+07:00" level=debug msg="dealer connection opened" Jul 02 20:07:44 volumio9 go-librespot[2813]: time="2025-07-02T20:07:44+07:00" level=trace msg="starting accesspoint recv loop" Jul 02 20:07:44 volumio9 go-librespot[2813]: time="2025-07-02T20:07:44+07:00" level=trace msg="starting dealer recv loop" Jul 02 20:07:44 volumio9 go-librespot[2813]: time="2025-07-02T20:07:44+07:00" level=trace msg="received accesspoint ping" Jul 02 20:07:44 volumio9 go-librespot[2813]: time="2025-07-02T20:07:44+07:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jul 02 20:07:44 volumio9 go-librespot[2813]: time="2025-07-02T20:07:44+07:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jul 02 20:07:44 volumio9 go-librespot[2813]: time="2025-07-02T20:07:44+07:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jul 02 20:07:44 volumio9 go-librespot[2813]: time="2025-07-02T20:07:44+07:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jul 02 20:07:44 volumio9 go-librespot[2813]: time="2025-07-02T20:07:44+07:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Jul 02 20:07:44 volumio9 go-librespot[2813]: time="2025-07-02T20:07:44+07:00" level=debug msg="received connection id: MTE1ZWM5MzktODJiYi00ODIyLWJiY2MtNzQ1ZTUyZTliNjc5K2RlYWxlcit0Y3A6Ly8wYWIxNTg5Yi5pcC5nYWUyLnNwb3RpZnkubmV0OjU3MDArREQxNDA2Mzc3OUY1QjNBMzhFMzYyNjlDQzI3OEU0MEEzOTE5OURGQTU2RkIxRENCMTQ3MTdDQjY5OERERjZEMA==" Jul 02 20:07:44 volumio9 go-librespot[2813]: time="2025-07-02T20:07:44+07:00" level=trace msg="received accesspoint pong ack" Jul 02 20:07:45 volumio9 go-librespot[2813]: time="2025-07-02T20:07:45+07:00" level=debug msg="put connect state because NEW_DEVICE" Jul 02 20:07:45 volumio9 go-librespot[2813]: time="2025-07-02T20:07:45+07:00" level=debug msg="update volume to 65535/65535" Jul 02 20:07:45 volumio9 sudo[2845]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 02 20:07:45 volumio9 sudo[2845]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 20:07:45 volumio9 sudo[2845]: pam_unix(sudo:session): session closed for user root Jul 02 20:07:45 volumio9 sudo[2847]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 02 20:07:45 volumio9 sudo[2847]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 20:07:45 volumio9 sudo[2847]: pam_unix(sudo:session): session closed for user root Jul 02 20:07:45 volumio9 sudo[2851]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jul 02 20:07:45 volumio9 sudo[2851]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 20:07:45 volumio9 sudo[2851]: pam_unix(sudo:session): session closed for user root Jul 02 20:07:45 volumio9 volumio[2576]: info: Upmpdcli Daemon Started Jul 02 20:07:46 volumio9 go-librespot[2813]: time="2025-07-02T20:07:46+07:00" level=debug msg="put connect state because VOLUME_CHANGED" Jul 02 20:07:46 volumio9 go-librespot[2813]: time="2025-07-02T20:07:46+07:00" level=trace msg="emitting websocket event: volume" Jul 02 20:07:46 volumio9 volumio[2576]: info: go-librespot daemon successfully initialized Jul 02 20:07:46 volumio9 sudo[2854]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Santichai,password=MmXp3152,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.12/Hires Music /mnt/NAS/Hires_Music Jul 02 20:07:46 volumio9 sudo[2854]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 20:07:46 volumio9 kernel: CIFS: Attempting to mount //192.168.1.12/Hires Music Jul 02 20:07:47 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 02 20:07:48 volumio9 volumio[2576]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jul 02 20:07:49 volumio9 volumio[2576]: info: Initializing connection to go-librespot Websocket Jul 02 20:07:49 volumio9 go-librespot[2813]: time="2025-07-02T20:07:49+07:00" level=debug msg="new websocket client" Jul 02 20:07:49 volumio9 volumio[2576]: info: Connection to go-librespot Websocket established Jul 02 20:07:50 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 02 20:07:52 volumio9 volumio[2576]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jul 02 20:07:52 volumio9 volumio[2576]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jul 02 20:07:52 volumio9 volumio[2576]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jul 02 20:07:52 volumio9 volumio[2576]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jul 02 20:07:52 volumio9 volumio[2576]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jul 02 20:07:52 volumio9 volumio[2576]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jul 02 20:07:52 volumio9 volumio[2576]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jul 02 20:07:52 volumio9 volumio[2576]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jul 02 20:07:52 volumio9 volumio[2576]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jul 02 20:07:52 volumio9 volumio[2576]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jul 02 20:07:52 volumio9 volumio[2576]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jul 02 20:07:52 volumio9 volumio[2576]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jul 02 20:07:52 volumio9 volumio[2576]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jul 02 20:07:52 volumio9 volumio[2576]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jul 02 20:07:52 volumio9 volumio[2576]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jul 02 20:07:52 volumio9 volumio[2576]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jul 02 20:07:52 volumio9 volumio[2576]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jul 02 20:07:52 volumio9 volumio[2576]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jul 02 20:07:52 volumio9 volumio[2576]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jul 02 20:07:52 volumio9 volumio[2576]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jul 02 20:07:52 volumio9 volumio[2576]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jul 02 20:07:52 volumio9 volumio[2576]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jul 02 20:07:52 volumio9 volumio[2576]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jul 02 20:07:52 volumio9 volumio[2576]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jul 02 20:07:52 volumio9 volumio[2576]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jul 02 20:07:52 volumio9 volumio[2576]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jul 02 20:07:52 volumio9 volumio[2576]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jul 02 20:07:52 volumio9 volumio[2576]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jul 02 20:07:52 volumio9 volumio[2576]: info: Adding plugin bluetooth to MyMusic Plugins Jul 02 20:07:52 volumio9 volumio[2576]: info: Adding plugin multiroom to MyMusic Plugins Jul 02 20:07:52 volumio9 volumio[2576]: info: Adding plugin metavolumio to MyMusic Plugins Jul 02 20:07:52 volumio9 volumio[2576]: info: Adding plugin cd_controller to MyMusic Plugins Jul 02 20:07:52 volumio9 volumio[2576]: info: Adding plugin qobuzconnect to MyMusic Plugins Jul 02 20:07:52 volumio9 volumio[2576]: info: Adding plugin smart_inputs to MyMusic Plugins Jul 02 20:07:52 volumio9 volumio[2576]: info: Adding plugin tidalconnect to MyMusic Plugins Jul 02 20:07:52 volumio9 volumio[2576]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jul 02 20:07:52 volumio9 volumio[2576]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jul 02 20:07:52 volumio9 volumio[2576]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jul 02 20:07:52 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 02 20:07:52 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 02 20:07:52 volumio9 volumio[2576]: info: Starting MyVolumio Remote Streaming Endpoints Jul 02 20:07:52 volumio9 volumio[2576]: info: MyVolumio login type: Token Jul 02 20:07:52 volumio9 volumio[2576]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jul 02 20:07:52 volumio9 volumio[2576]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jul 02 20:07:53 volumio9 sudo[2854]: pam_unix(sudo:session): session closed for user root Jul 02 20:07:53 volumio9 kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jul 02 20:07:53 volumio9 kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Jul 02 20:07:53 volumio9 volumio[2576]: info: Starting Streaming Service Transparent Proxy Jul 02 20:07:53 volumio9 volumio[2576]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jul 02 20:07:53 volumio9 volumio[2576]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jul 02 20:07:53 volumio9 volumio[2576]: info: Streaming services startup Jul 02 20:07:53 volumio9 volumio[2576]: info: Starting Streaming Daemon Jul 02 20:07:53 volumio9 sudo[2906]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 02 20:07:53 volumio9 sudo[2906]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 20:07:53 volumio9 sudo[2906]: pam_unix(sudo:session): session closed for user root Jul 02 20:07:53 volumio9 volumio[2576]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jul 02 20:07:53 volumio9 volumio[2576]: info: Getting Spotify volume Jul 02 20:07:53 volumio9 volumio[2576]: error: Cannot start Volumio Streaming Daemon Jul 02 20:07:53 volumio9 volumio[2576]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 02 20:07:53 volumio9 volumio[2576]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 02 20:07:53 volumio9 volumio[2576]: info: Cannot mount NAS Hires_Music at system boot, trial number 2 ,retrying in 5 seconds Jul 02 20:07:53 volumio9 volumio[2576]: info: Spotify volume: 100 Jul 02 20:07:53 volumio9 volumio[2576]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Jul 02 20:07:53 volumio9 volumio[2576]: info: CoreCommandRouter::volumioGetState Jul 02 20:07:53 volumio9 volumio[2576]: info: CorePlayQueue::getTrack 0 Jul 02 20:07:54 volumio9 volumio[2576]: STREAMING PROXY: Starting server on port 3245 Jul 02 20:07:54 volumio9 volumio[2576]: Node JS runtime: 14 Jul 02 20:07:54 volumio9 volumio[2576]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jul 02 20:07:54 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 02 20:07:56 volumio9 volumio[2576]: info: MyVolumio token set successfully Jul 02 20:07:56 volumio9 volumio[2576]: info: MYVOLUMIO: Adding device Jul 02 20:07:56 volumio9 volumio[2576]: info: MYVOLUMIO: Evaluating Server Jul 02 20:07:57 volumio9 volumio[2576]: info: MyVolumio status changed Jul 02 20:07:57 volumio9 volumio[2576]: info: Streaming services startup Jul 02 20:07:57 volumio9 volumio[2576]: info: Starting Streaming Daemon Jul 02 20:07:57 volumio9 volumio[2576]: info: Removing browser output: myVolumio user plan is not superstar Jul 02 20:07:57 volumio9 volumio[2576]: info: Removing audio output: Jul 02 20:07:57 volumio9 volumio[2576]: info: Stoppping Tunnel 1 Jul 02 20:07:57 volumio9 sudo[2948]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jul 02 20:07:57 volumio9 sudo[2948]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 20:07:57 volumio9 sudo[2951]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jul 02 20:07:57 volumio9 sudo[2951]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 20:07:57 volumio9 sudo[2948]: pam_unix(sudo:session): session closed for user root Jul 02 20:07:57 volumio9 volumio[2576]: error: Cannot start Volumio Streaming Daemon Jul 02 20:07:57 volumio9 volumio[2576]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jul 02 20:07:57 volumio9 volumio[2576]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jul 02 20:07:57 volumio9 sudo[2951]: pam_unix(sudo:session): session closed for user root Jul 02 20:07:57 volumio9 volumio[2576]: info: Remote SSH Stopped Jul 02 20:07:57 volumio9 kernel: usb 1-1.1: USB disconnect, device number 18 Jul 02 20:07:57 volumio9 volumio[2576]: info: Setting Geolocation for MyVolumio to as1 Jul 02 20:07:57 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 02 20:07:57 volumio9 volumio[2576]: info: Jul 02 20:07:57 volumio9 volumio[2576]: ---------------------------- USB Audio Device Detached Jul 02 20:07:57 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Jul 02 20:07:57 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 02 20:07:57 volumio9 kernel: usb 1-1.1: new high-speed USB device number 19 using xhci_hcd Jul 02 20:07:58 volumio9 kernel: usb 1-1.1: New USB device found, idVendor=152a, idProduct=85dd, bcdDevice= 3.53 Jul 02 20:07:58 volumio9 kernel: usb 1-1.1: New USB device strings: Mfr=1, Product=3, SerialNumber=2 Jul 02 20:07:58 volumio9 kernel: usb 1-1.1: Product: SMSL USB AUDIO Jul 02 20:07:58 volumio9 kernel: usb 1-1.1: Manufacturer: SMSL Jul 02 20:07:58 volumio9 kernel: usb 1-1.1: 1:3 : unsupported format bits 0x100000000 Jul 02 20:07:58 volumio9 kernel: hid-generic 0003:152A:85DD.000C: hiddev96,hidraw0: USB HID v1.10 Device [SMSL SMSL USB AUDIO] on usb-0000:01:00.0-1.1/input3 Jul 02 20:07:58 volumio9 volumio[2576]: info: Jul 02 20:07:58 volumio9 volumio[2576]: ---------------------------- USB Audio Device Attached Jul 02 20:07:58 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Jul 02 20:07:58 volumio9 volumio[2576]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jul 02 20:07:58 volumio9 sudo[2966]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Santichai,password=MmXp3152,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.12/Hires Music /mnt/NAS/Hires_Music Jul 02 20:07:58 volumio9 sudo[2966]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 20:07:58 volumio9 kernel: CIFS: Attempting to mount //192.168.1.12/Hires Music Jul 02 20:07:58 volumio9 volumio[2576]: info: Updating MyVolumio device info Jul 02 20:07:58 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 02 20:07:58 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 02 20:07:58 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 02 20:07:58 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 02 20:07:59 volumio9 volumio[2576]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jul 02 20:08:01 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 02 20:08:01 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 02 20:08:01 volumio9 volumio[2576]: info: Discovery: Getting this device information Jul 02 20:08:01 volumio9 volumio[2576]: info: CoreCommandRouter::volumioGetState Jul 02 20:08:01 volumio9 volumio[2576]: info: CorePlayQueue::getTrack 0 Jul 02 20:08:01 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 02 20:08:02 volumio9 volumio[2576]: info: MYVOLUMIO: Adding device Jul 02 20:08:02 volumio9 volumio[2576]: info: MYVOLUMIO: Evaluating Server Jul 02 20:08:02 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 02 20:08:03 volumio9 volumio[2576]: info: Setting Geolocation for MyVolumio to as1 Jul 02 20:08:03 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 02 20:08:03 volumio9 volumio[2576]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jul 02 20:08:04 volumio9 volumio[2576]: info: Updating MyVolumio device info Jul 02 20:08:04 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 02 20:08:04 volumio9 volumio[2576]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jul 02 20:08:04 volumio9 sudo[2966]: pam_unix(sudo:session): session closed for user root Jul 02 20:08:04 volumio9 volumio[2576]: info: Cannot mount NAS Hires_Music at system boot, trial number 3 ,retrying in 5 seconds Jul 02 20:08:04 volumio9 kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Jul 02 20:08:04 volumio9 kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Jul 02 20:08:07 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Jul 02 20:08:07 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 02 20:08:07 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 02 20:08:07 volumio9 volumio[2576]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Jul 02 20:08:07 volumio9 volumio[2576]: info: CoreCommandRouter::volumioGetState Jul 02 20:08:07 volumio9 volumio[2576]: info: CorePlayQueue::getTrack 0 Jul 02 20:08:08 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jul 02 20:08:09 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Jul 02 20:08:09 volumio9 volumio[2576]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Jul 02 20:08:09 volumio9 volumio[2576]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 02 20:08:09 volumio9 volumio[2576]: Error: getaddrinfo -3007 Jul 02 20:08:09 volumio9 volumio[2576]: at errnoException (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:199:11) Jul 02 20:08:09 volumio9 volumio[2576]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:112:10) Jul 02 20:08:09 volumio9 volumio[2576]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) { Jul 02 20:08:09 volumio9 volumio[2576]: code: -3007, Jul 02 20:08:09 volumio9 volumio[2576]: errno: -3007, Jul 02 20:08:09 volumio9 volumio[2576]: syscall: 'getaddrinfo' Jul 02 20:08:09 volumio9 volumio[2576]: } Jul 02 20:08:09 volumio9 volumio[2576]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 02 20:08:09 volumio9 sudo[3013]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-02 20:07 Jul 02 20:08:09 volumio9 sudo[3013]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 04:52:53 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="a72866a0de4045751d03a035de6290e1"