-- Logs begin at Sun 2025-05-18 14:13:48 UTC, end at Sun 2025-05-18 14:17:39 UTC. -- May 18 14:16:25 music systemd[1]: Time has been changed May 18 14:16:25 music wireless.js[798]: trying... May 18 14:16:25 music sudo[1167]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 18 14:16:25 music sudo[1167]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 14:16:25 music sudo[1167]: pam_unix(sudo:session): session closed for user root May 18 14:16:26 music wireless.js[798]: trying... May 18 14:16:26 music sudo[1176]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 18 14:16:26 music sudo[1176]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 14:16:26 music sudo[1176]: pam_unix(sudo:session): session closed for user root May 18 14:16:27 music wireless.js[798]: trying... May 18 14:16:27 music sudo[1185]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 18 14:16:27 music sudo[1185]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 14:16:27 music sudo[1185]: pam_unix(sudo:session): session closed for user root May 18 14:16:28 music wireless.js[798]: trying... May 18 14:16:28 music sudo[1194]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 18 14:16:28 music sudo[1194]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 14:16:28 music sudo[1194]: pam_unix(sudo:session): session closed for user root May 18 14:16:29 music wireless.js[798]: trying... May 18 14:16:29 music sudo[1203]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 18 14:16:29 music sudo[1203]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 14:16:29 music sudo[1203]: pam_unix(sudo:session): session closed for user root May 18 14:16:30 music wireless.js[798]: trying... May 18 14:16:30 music sudo[1212]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 18 14:16:30 music sudo[1212]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 14:16:30 music sudo[1212]: pam_unix(sudo:session): session closed for user root May 18 14:16:31 music wireless.js[798]: trying... May 18 14:16:31 music sudo[1221]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 18 14:16:31 music sudo[1221]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 14:16:31 music sudo[1221]: pam_unix(sudo:session): session closed for user root May 18 14:16:32 music wireless.js[798]: trying... May 18 14:16:32 music sudo[1230]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 18 14:16:32 music sudo[1230]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 14:16:32 music sudo[1230]: pam_unix(sudo:session): session closed for user root May 18 14:16:33 music wpa_supplicant[948]: wlan0: Failed to initiate sched scan May 18 14:16:33 music volumio-remote-updater[612]: [2025-05-18 14:16:33] [info] asio async_connect error: system:111 (Connection refused) May 18 14:16:33 music volumio-remote-updater[612]: [2025-05-18 14:16:33] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) May 18 14:16:33 music volumio-remote-updater[612]: [2025-05-18 14:16:33] [error] handle_connect error: Underlying Transport Error May 18 14:16:33 music wireless.js[798]: trying... May 18 14:16:33 music sudo[1239]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 18 14:16:33 music sudo[1239]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 14:16:33 music sudo[1239]: pam_unix(sudo:session): session closed for user root May 18 14:16:34 music wireless.js[798]: trying... May 18 14:16:34 music sudo[1248]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 18 14:16:34 music sudo[1248]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 14:16:34 music sudo[1248]: pam_unix(sudo:session): session closed for user root May 18 14:16:35 music wireless.js[798]: Overtime, starting plan B May 18 14:16:35 music wireless.js[798]: STARTING HOTSPOT May 18 14:16:36 music wpa_supplicant[948]: wlan0: CTRL-EVENT-TERMINATING May 18 14:16:39 music systemd[1]: Stopped hotspot.service. May 18 14:16:39 music sudo[1263]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 May 18 14:16:39 music sudo[1263]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 14:16:39 music sudo[1263]: pam_unix(sudo:session): session closed for user root May 18 14:16:39 music sudo[1271]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down May 18 14:16:39 music sudo[1271]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 14:16:39 music sudo[1271]: pam_unix(sudo:session): session closed for user root May 18 14:16:39 music wireless.js[798]: Hotspot is disabled, not starting it May 18 14:16:39 music kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled May 18 14:16:39 music systemd[1]: Started Wireless Services. May 18 14:16:39 music systemd[1]: Starting Volumio Backend Module... May 18 14:16:39 music systemd[1]: Started Volumio Backend Module. May 18 14:16:39 music systemd[1]: Starting Volumio Streaming Daemon... May 18 14:16:39 music systemd[1]: Started Volumio Streaming Daemon. May 18 14:16:39 music systemd[1]: Starting Multi-User System. May 18 14:16:39 music systemd[1]: Reached target Multi-User System. May 18 14:16:39 music systemd[1]: Starting Graphical Interface. May 18 14:16:39 music systemd[1]: Reached target Graphical Interface. May 18 14:16:39 music systemd[1]: Starting Update UTMP about System Runlevel Changes... May 18 14:16:39 music systemd[1]: Started Update UTMP about System Runlevel Changes. May 18 14:16:39 music systemd[1]: Startup finished in 22.402s (kernel) + 37.910s (userspace) = 1min 313ms. May 18 14:16:39 music volumio-streaming-daemon[1282]: ############################ May 18 14:16:39 music volumio-streaming-daemon[1282]: # Volumio Streaming Daemon # May 18 14:16:39 music volumio-streaming-daemon[1282]: # Running on port 7777 # May 18 14:16:39 music volumio-streaming-daemon[1282]: ############################ May 18 14:16:39 music volumio-streaming-daemon[1282]: Environment: production May 18 14:16:42 music volumio[1281]: info: ------------------------------------------- May 18 14:16:42 music volumio[1281]: info: ----- Volumio2 ---- May 18 14:16:42 music volumio[1281]: info: ------------------------------------------- May 18 14:16:42 music volumio[1281]: info: ----- System startup ---- May 18 14:16:42 music volumio[1281]: info: ------------------------------------------- May 18 14:16:43 music volumio[1281]: info: MYVOLUMIO Environment detected May 18 14:16:43 music volumio[1281]: info: Plugin folders cleanup May 18 14:16:43 music volumio[1281]: info: Scanning into folder /volumio/app/plugins/ May 18 14:16:43 music volumio[1281]: info: Scanning category audio_interface May 18 14:16:43 music volumio[1281]: info: Scanning category miscellanea May 18 14:16:43 music volumio[1281]: info: Scanning category music_service May 18 14:16:43 music volumio[1281]: info: Scanning category plugins.json May 18 14:16:43 music volumio[1281]: info: Scanning category system_controller May 18 14:16:43 music volumio[1281]: info: Scanning category user_interface May 18 14:16:43 music volumio[1281]: info: Scanning into folder /data/plugins/ May 18 14:16:43 music volumio[1281]: info: Scanning category miscellanea May 18 14:16:43 music volumio[1281]: info: Scanning category system_controller May 18 14:16:43 music volumio[1281]: info: Scanning category user_interface May 18 14:16:43 music volumio[1281]: info: Plugin folders cleanup completed May 18 14:16:43 music volumio[1281]: info: ------------------------------------------- May 18 14:16:43 music volumio[1281]: info: ----- Core plugins startup ---- May 18 14:16:43 music volumio[1281]: info: ------------------------------------------- May 18 14:16:43 music volumio[1281]: info: Loading plugins from folder /volumio/app/plugins/ May 18 14:16:43 music volumio[1281]: info: Adding plugin upnp to MyMusic Plugins May 18 14:16:43 music volumio[1281]: info: Adding plugin airplay_emulation to MyMusic Plugins May 18 14:16:43 music volumio[1281]: info: Adding plugin upnp_browser to MyMusic Plugins May 18 14:16:43 music volumio[1281]: info: Loading plugins from folder /data/plugins/ May 18 14:16:43 music volumio[1281]: info: Loading plugin "system"... May 18 14:16:43 music volumio[1281]: info: Loading plugin "appearance"... May 18 14:16:43 music volumio-remote-updater[612]: [2025-05-18 14:16:43] [connect] Successful connection May 18 14:16:45 music volumio[1281]: info: Loading plugin "network"... May 18 14:16:45 music volumio[1281]: info: Refreshing Cached IP Addresses May 18 14:16:45 music sudo[1310]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 18 14:16:45 music volumio[1281]: info: Loading plugin "touch_display"... May 18 14:16:45 music sudo[1310]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 14:16:45 music sudo[1310]: pam_unix(sudo:session): session closed for user root May 18 14:16:45 music sudo[1313]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 18 14:16:45 music sudo[1313]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 14:16:45 music sudo[1313]: pam_unix(sudo:session): session closed for user root May 18 14:16:46 music volumio[1281]: info: Applying required configuration parameters for plugin touch_display May 18 14:16:46 music volumio[1281]: info: Loading plugin "services"... May 18 14:16:46 music volumio[1281]: info: Loading plugin "alsa_controller"... May 18 14:16:46 music volumio[1281]: info: Loading plugin "wizard"... May 18 14:16:46 music volumio[1281]: info: Loading plugin "volumio_command_line_client"... May 18 14:16:46 music volumio[1281]: info: Loading plugin "upnp"... May 18 14:16:46 music volumio[1281]: info: [1747577806524] Starting Upmpd Daemon May 18 14:16:46 music volumio[1281]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 18 14:16:46 music volumio[1281]: info: Loading plugin "my_music"... May 18 14:16:46 music volumio[1281]: info: Loading plugin "mpd"... May 18 14:16:47 music volumio[1281]: info: Loading plugin "upnp_browser"... May 18 14:16:47 music volumio[1281]: info: Loading plugin "networkfs"... May 18 14:16:47 music volumio[1281]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 18 14:16:47 music sudo[1334]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=li36771,password=Ll3387850,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.9/音乐1 /mnt/NAS/NAS May 18 14:16:47 music volumio[1281]: info: Loading plugin "alarm-clock"... May 18 14:16:47 music sudo[1334]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 14:16:47 music sudo[1336]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=li36771,password=Ll3387850,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.9/音乐 /mnt/NAS/NAS1 May 18 14:16:47 music sudo[1336]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 14:16:47 music kernel: FS-Cache: Netfs 'cifs' registered for caching May 18 14:16:47 music kernel: Key type cifs.spnego registered May 18 14:16:47 music kernel: Key type cifs.idmap registered May 18 14:16:47 music kernel: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3 (or SMB2.1) specify vers=1.0 on mount. May 18 14:16:47 music kernel: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3 (or SMB2.1) specify vers=1.0 on mount. May 18 14:16:47 music kernel: FS-Cache: Duplicate cookie detected May 18 14:16:47 music kernel: FS-Cache: O-cookie c=3cb7ce50 [p=350df353 fl=222 nc=0 na=1] May 18 14:16:47 music kernel: FS-Cache: O-cookie d=eb581ff9 n=274ca6dd May 18 14:16:47 music kernel: FS-Cache: O-key=[8] '020001bdc0a80109' May 18 14:16:47 music kernel: FS-Cache: N-cookie c=ff77e7d2 [p=350df353 fl=2 nc=0 na=1] May 18 14:16:47 music kernel: FS-Cache: N-cookie d=eb581ff9 n=655a59f0 May 18 14:16:47 music kernel: FS-Cache: N-key=[8] '020001bdc0a80109' May 18 14:16:47 music sudo[1334]: pam_unix(sudo:session): session closed for user root May 18 14:16:47 music sudo[1336]: pam_unix(sudo:session): session closed for user root May 18 14:16:47 music volumio[1281]: info: Loading plugin "airplay_emulation"... May 18 14:16:48 music volumio[1281]: info: Starting Shairport Sync May 18 14:16:48 music volumio[1281]: info: Loading plugin "last_100"... May 18 14:16:48 music volumio[1281]: info: Loading plugin "webradio"... May 18 14:16:48 music volumio[1281]: info: Loading plugin "i2s_dacs"... May 18 14:16:48 music volumio[1281]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 14:16:48 music volumio[1281]: info: Loading plugin "volumiodiscovery"... May 18 14:16:48 music volumio[1281]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 18 14:16:48 music node[1281]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 18 14:16:48 music node[1281]: *** WARNING *** Please fix your application to use the native API of Avahi! May 18 14:16:48 music node[1281]: *** WARNING *** For more information see May 18 14:16:48 music node[1281]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 18 14:16:48 music node[1281]: *** WARNING *** Please fix your application to use the native API of Avahi! May 18 14:16:48 music node[1281]: *** WARNING *** For more information see May 18 14:16:48 music volumio[1281]: *** WARNING *** Please fix your application to use the native API of Avahi! May 18 14:16:48 music volumio[1281]: *** WARNING *** For more information see May 18 14:16:48 music volumio[1281]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 18 14:16:48 music volumio[1281]: *** WARNING *** Please fix your application to use the native API of Avahi! May 18 14:16:48 music volumio[1281]: *** WARNING *** For more information see May 18 14:16:48 music volumio[1281]: info: Applying required configuration parameters for plugin volumiodiscovery May 18 14:16:48 music volumio[1281]: Discovery: StartAdv! undefined May 18 14:16:48 music volumio[1281]: Discovery: Started advertising... music - undefined May 18 14:16:48 music volumio[1281]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 18 14:16:48 music volumio[1281]: info: Loading plugin "auto_play"... May 18 14:16:48 music volumio[1281]: info: ControllerAutoPlay - connecting mpd on host: localhost; port: 6600 May 18 14:16:48 music volumio[1281]: info: Loading plugin "now_playing"... May 18 14:16:49 music volumio[1281]: info: Plugin gpio-buttons is not enabled May 18 14:16:49 music volumio[1281]: info: Loading plugin "outputs"... May 18 14:16:49 music volumio[1281]: info: Loading plugin "albumart"... May 18 14:16:49 music volumio[1281]: info: Plugin example_plugin is not enabled May 18 14:16:49 music volumio[1281]: info: Loading plugin "inputs"... May 18 14:16:49 music volumio[1281]: info: Loading plugin "updater_comm"... May 18 14:16:49 music volumio[1281]: info: Plugin mpdemulation is not enabled May 18 14:16:49 music volumio[1281]: info: Loading plugin "rest_api"... May 18 14:16:49 music volumio[1281]: info: Loading plugin "websocket"... May 18 14:16:49 music volumio[1281]: info: Plugin randomizer is not enabled May 18 14:16:49 music volumio[1281]: info: Loading plugin "rotaryencoder"... May 18 14:16:49 music volumio[1281]: Forking 3 albumart workers May 18 14:16:50 music volumio[1281]: Starting albumart workers May 18 14:16:50 music volumio[1281]: Starting albumart workers May 18 14:16:50 music volumio[1281]: Starting albumart workers May 18 14:16:50 music volumio[1281]: info: Applying required configuration parameters for plugin rotaryencoder May 18 14:16:51 music volumio[1281]: info: Plugin rotaryencoder2 is not enabled May 18 14:16:51 music volumio[1281]: info: ___________ START PLUGINS ___________ May 18 14:16:51 music volumio[1281]: info: Loading i18n strings for locale zh May 18 14:16:51 music volumio[1281]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 18 14:16:51 music volumio[1281]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 18 14:16:51 music volumio[1281]: info: [1747577811258] CoreMusicLibrary::Adding element Media Servers May 18 14:16:51 music volumio[1281]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 18 14:16:51 music sudo[1460]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload May 18 14:16:51 music sudo[1460]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 14:16:51 music systemd[1]: Reloading. May 18 14:16:51 music volumio[1281]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 18 14:16:51 music systemd[1]: [/lib/systemd/system/mpd.service:18] Unknown lvalue 'ProtectKernelTunables' in section 'Service' May 18 14:16:51 music systemd[1]: [/lib/systemd/system/mpd.service:19] Unknown lvalue 'ProtectControlGroups' in section 'Service' May 18 14:16:51 music systemd[1]: [/lib/systemd/system/mpd.service:20] Unknown lvalue 'ProtectKernelModules' in section 'Service' May 18 14:16:51 music systemd[1]: [/lib/systemd/system/mpd.service:23] Unknown lvalue 'RestrictNamespaces' in section 'Service' May 18 14:16:51 music volumio[1281]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 18 14:16:51 music volumio[1281]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 18 14:16:51 music volumio[1281]: info: [1747577811412] CoreMusicLibrary::Adding element Last_100 May 18 14:16:51 music volumio[1281]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 18 14:16:51 music volumio[1281]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 18 14:16:51 music volumio[1281]: info: [1747577811416] CoreMusicLibrary::Adding element Webradio May 18 14:16:51 music volumio[1281]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 18 14:16:51 music volumio[1281]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 18 14:16:51 music sudo[1460]: pam_unix(sudo:session): session closed for user root May 18 14:16:51 music volumio[1281]: info: ------------------------------------------- May 18 14:16:51 music volumio[1281]: info: ----- MyVolumio plugins startup ---- May 18 14:16:51 music volumio[1281]: info: ------------------------------------------- May 18 14:16:51 music volumio[1281]: info: [MyVolumio PluginManager] Fetching plans data.... May 18 14:16:51 music volumio[1281]: info: Loading i18n strings for locale zh May 18 14:16:51 music volumio[1281]: Updating browse sources language May 18 14:16:51 music volumio[1281]: Cannot find translation for sourceMedia Servers May 18 14:16:51 music volumio[1281]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 18 14:16:51 music volumio[1281]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 18 14:16:51 music volumio[1281]: info: CoreCommandRouter::initPlayerControls May 18 14:16:51 music volumio[1281]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 14:16:51 music volumio[1281]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards May 18 14:16:51 music volumio[1281]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 14:16:51 music volumio[1281]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 14:16:51 music volumio[1281]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 14:16:51 music volumio[1281]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 14:16:51 music volumio[1281]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 14:16:51 music volumio[1281]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 18 14:16:51 music volumio[1281]: info: BOOT COMPLETED May 18 14:16:51 music volumio[1281]: [Metrics] CommandRouter: 9s 550.99ms May 18 14:16:51 music volumio[1281]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 14:16:51 music volumio[1281]: info: CoreCommandRouter::Close All Modals sent May 18 14:16:51 music volumio[1281]: info: CoreCommandRouter::Close All Modals sent May 18 14:16:51 music volumio[1281]: Express server listening on port 3000 May 18 14:16:51 music volumio[1281]: [Metrics] WebUI: 10s 203.66ms May 18 14:16:51 music volumio[1281]: Volumio Calling Home May 18 14:16:51 music volumio[1281]: info: [now-playing-app] App is listening on port 4004. May 18 14:16:51 music volumio[1281]: info: CoreStateMachine::resetVolumioState May 18 14:16:51 music volumio[1281]: info: CoreStateMachine::getcurrentVolume May 18 14:16:51 music volumio[1281]: info: CoreCommandRouter::volumioRetrievevolume May 18 14:16:51 music volumio[1281]: info: CoreStateMachine::pushState May 18 14:16:51 music volumio[1281]: info: CorePlayQueue::getTrack 0 May 18 14:16:51 music volumio[1281]: info: CoreCommandRouter::volumioPushState May 18 14:16:51 music volumio[1281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 14:16:52 music volumio[1281]: info: Setting Device type: Raspberry PI May 18 14:16:52 music volumio[1281]: info: touch_display: systemctl daemon-reload succeeded. May 18 14:16:52 music volumio[1281]: info: MPD running with PID678 May 18 14:16:52 music volumio[1281]: ,establishing connection May 18 14:16:52 music sudo[1524]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service May 18 14:16:52 music sudo[1524]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 14:16:52 music systemd[1]: Starting Volumio Kiosk... May 18 14:16:52 music systemd[1]: Started Volumio Kiosk. May 18 14:16:52 music sudo[1524]: pam_unix(sudo:session): session closed for user root May 18 14:16:52 music startx[1529]: X.Org X Server 1.18.4 May 18 14:16:52 music startx[1529]: Release Date: 2016-07-19 May 18 14:16:52 music startx[1529]: X Protocol Version 11, Revision 0 May 18 14:16:52 music startx[1529]: Build Operating System: Linux 4.4.26-v7+ armv7l Raspbian May 18 14:16:52 music startx[1529]: Current Operating System: Linux music 4.19.118-v7+ #1311 SMP Mon Apr 27 14:21:24 BST 2020 armv7l May 18 14:16:52 music startx[1529]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 bcm2708_fb.fbwidth=640 bcm2708_fb.fbheight=480 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash quiet plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 console=ttyS0,115200 kgdboc=ttyS0,115200 console=tty1 imgpart=/dev/mmcblk0p2 imgfile=/volumio_current.sqsh elevator=noop rootwait bootdelay=5 logo.nologo vt.global_cursor_default=0 loglevel=0 snd-bcm2835.enable_compat_alsa=1 snd_bcm2835.enable_headphones=1 May 18 14:16:52 music startx[1529]: Build Date: 11 November 2016 11:59:59AM May 18 14:16:52 music startx[1529]: xorg-server 2:1.18.4-2+rpi1 (https://www.debian.org/support) May 18 14:16:52 music startx[1529]: Current version of pixman: 0.32.6 May 18 14:16:52 music startx[1529]: Before reporting problems, check http://wiki.x.org May 18 14:16:52 music startx[1529]: to make sure that you have the latest version. May 18 14:16:52 music startx[1529]: Markers: (--) probed, (**) from config file, (==) default setting, May 18 14:16:52 music startx[1529]: (++) from command line, (!!) notice, (II) informational, May 18 14:16:52 music startx[1529]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. May 18 14:16:52 music startx[1529]: (==) Log file: "/var/log/Xorg.0.log", Time: Sun May 18 14:16:52 2025 May 18 14:16:52 music volumio[1281]: info: touch_display: systemctl start volumio-kiosk.service succeeded. May 18 14:16:52 music volumio[1281]: info: touch_display: Volumio Kiosk started May 18 14:16:52 music volumio[1281]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 14:16:52 music volumio[1281]: info: CoreCommandRouter::volumioGetState May 18 14:16:52 music volumio[1281]: info: CorePlayQueue::getTrack 0 May 18 14:16:52 music volumio[1281]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 18 14:16:52 music volumio[1281]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 18 14:16:52 music volumio[1281]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 18 14:16:52 music volumio[1281]: info: Reloading queue from file May 18 14:16:52 music volumio[1281]: error: updateQueue error: null May 18 14:16:52 music volumio[1281]: info: Volumio called home May 18 14:16:52 music volumio[1281]: info: CoreStateMachine::setRepeat false single undefined May 18 14:16:52 music volumio[1281]: info: CoreStateMachine::pushState May 18 14:16:52 music volumio[1281]: info: CorePlayQueue::getTrack 0 May 18 14:16:52 music volumio[1281]: info: CoreCommandRouter::volumioPushState May 18 14:16:52 music volumio[1281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 14:16:52 music volumio[1281]: info: CoreStateMachine::setRandom true May 18 14:16:52 music volumio[1281]: info: CoreStateMachine::pushState May 18 14:16:52 music volumio[1281]: info: CorePlayQueue::getTrack 0 May 18 14:16:52 music volumio[1281]: info: CoreCommandRouter::volumioPushState May 18 14:16:52 music volumio[1281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 14:16:52 music volumio[1281]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 May 18 14:16:52 music volumio[1281]: info: Starting Shairport Sync May 18 14:16:52 music volumio[1281]: info: Starting Shairport Sync May 18 14:16:52 music sudo[1559]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 18 14:16:52 music sudo[1559]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 14:16:52 music sudo[1561]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 18 14:16:52 music sudo[1561]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 14:16:52 music systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... May 18 14:16:52 music systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 18 14:16:52 music sudo[1559]: pam_unix(sudo:session): session closed for user root May 18 14:16:52 music volumio[1281]: info: touch_display: No Raspberry Pi Foundation touch screen detected. May 18 14:16:52 music systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 18 14:16:52 music systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... May 18 14:16:53 music systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 18 14:16:53 music sudo[1561]: pam_unix(sudo:session): session closed for user root May 18 14:16:53 music startx[1529]: (==) Using config directory: "/etc/X11/xorg.conf.d" May 18 14:16:53 music startx[1529]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" May 18 14:16:53 music volumio[1281]: Discovery: adding 718518fa-1979-4f00-9068-686a3f1ca8e6 May 18 14:16:53 music volumio[1281]: info: mDNS: Found device music May 18 14:16:53 music volumio[1281]: info: CoreCommandRouter::volumioGetState May 18 14:16:53 music volumio[1281]: info: CorePlayQueue::getTrack 0 May 18 14:16:53 music sudo[1579]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sed -i -e /Option "TransformationMatrix"/d /etc/X11/xorg.conf.d/95-touch_display-plugin.conf May 18 14:16:53 music volumio[1281]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 14:16:53 music volumio[1281]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 18 14:16:53 music sudo[1579]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 14:16:53 music sudo[1579]: pam_unix(sudo:session): session closed for user root May 18 14:16:53 music volumio[1281]: info: CoreCommandRouter::volumioGetState May 18 14:16:53 music volumio[1281]: info: CorePlayQueue::getTrack 0 May 18 14:16:53 music volumio[1281]: info: Shairport-Sync Started May 18 14:16:53 music volumio[1281]: Error adding Membership: Error: addMembership EINVAL May 18 14:16:53 music volumio[1281]: info: Shairport-Sync Started May 18 14:16:53 music volumio[1281]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 May 18 14:16:53 music volumio[1281]: info: touch_display: Touchscreen transformation matrix removed. May 18 14:16:53 music volumio[1281]: info: CoreCommandRouter::volumioGetState May 18 14:16:53 music volumio[1281]: info: CorePlayQueue::getTrack 0 May 18 14:16:53 music startx[1529]: modprobe: FATAL: Module g2d_23 not found. May 18 14:16:55 music volumio[1281]: info: ControllerAutoPlay - mpd ready May 18 14:16:55 music volumio[1281]: Cannot compose Albumart path May 18 14:16:58 music volumio-remote-updater[612]: [2025-05-18 14:16:58] [connect] Successful connection May 18 14:16:59 music sudo[1608]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 18 14:16:59 music sudo[1608]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 14:16:59 music sudo[1608]: pam_unix(sudo:session): session closed for user root May 18 14:16:59 music sudo[1611]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 18 14:16:59 music sudo[1611]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 14:16:59 music sudo[1611]: pam_unix(sudo:session): session closed for user root May 18 14:17:00 music volumio[1281]: info: ControllerAutoPlay - getting queue May 18 14:17:00 music volumio[1281]: info: CoreCommandRouter::volumioGetQueue May 18 14:17:00 music volumio[1281]: info: CoreStateMachine::getQueue May 18 14:17:00 music volumio[1281]: info: CorePlayQueue::getQueue May 18 14:17:00 music volumio[1281]: info: ControllerAutoPlay - start playing -> queue is not empty May 18 14:17:00 music volumio[1281]: info: CoreCommandRouter::volumioPlay May 18 14:17:00 music volumio[1281]: UNSET VOLATILE May 18 14:17:00 music volumio[1281]: info: CoreStateMachine::play index undefined May 18 14:17:00 music volumio[1281]: info: CoreStateMachine::setConsumeUpdateService undefined May 18 14:17:00 music volumio[1281]: info: CorePlayQueue::getTrack 0 May 18 14:17:00 music volumio[1281]: info: CoreStateMachine::startPlaybackTimer May 18 14:17:00 music volumio[1281]: info: CorePlayQueue::getTrack 0 May 18 14:17:00 music volumio[1281]: verbose: ControllerMpd::clearAddPlayTracks NAS/NAS/[Hires]2022抖音年度新歌CD2 24bit96khz/01. 时光洪流.flac May 18 14:17:00 music volumio[1281]: verbose: ControllerMpd::sendMpdCommand stop May 18 14:17:00 music volumio[1281]: info: sendMpdCommand stop took 15 milliseconds May 18 14:17:00 music volumio[1281]: verbose: ControllerMpd::sendMpdCommand clear May 18 14:17:00 music volumio-remote-updater[612]: [2025-05-18 14:17:00] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1747577818 101 May 18 14:17:01 music volumio[1281]: info: May 18 14:17:01 music volumio[1281]: ---------------------------- MPD announces system playlist update May 18 14:17:01 music volumio[1281]: info: Ignoring MPD Status Update May 18 14:17:01 music volumio[1281]: info: sendMpdCommand clear took 865 milliseconds May 18 14:17:01 music volumio[1281]: verbose: ControllerMpd::sendMpdCommand add "NAS/NAS/[Hires]2022抖音年度新歌CD2 24bit96khz/01. 时光洪流.flac" May 18 14:17:01 music volumio[1281]: error: updateQueue error: null May 18 14:17:01 music volumio[1281]: info: May 18 14:17:01 music volumio[1281]: ---------------------------- MPD announces system playlist update May 18 14:17:01 music volumio[1281]: info: Ignoring MPD Status Update May 18 14:17:01 music volumio[1281]: info: ------------------------------ 28ms May 18 14:17:01 music volumio[1281]: info: sendMpdCommand add "NAS/NAS/[Hires]2022抖音年度新歌CD2 24bit96khz/01. 时光洪流.flac" took 24 milliseconds May 18 14:17:01 music volumio[1281]: verbose: ControllerMpd::sendMpdCommand play May 18 14:17:01 music volumio[1281]: info: ------------------------------ 55ms May 18 14:17:01 music volumio[1281]: info: sendMpdCommand play took 52 milliseconds May 18 14:17:01 music volumio[1281]: info: CoreCommandRouter::volumioGetState May 18 14:17:01 music volumio[1281]: info: CorePlayQueue::getTrack 0 May 18 14:17:01 music sudo[1626]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 18 14:17:02 music sudo[1626]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 14:17:02 music systemd[1]: Starting UPnP Renderer front-end to MPD... May 18 14:17:02 music systemd[1]: Started UPnP Renderer front-end to MPD. May 18 14:17:02 music volumio[1281]: info: May 18 14:17:02 music volumio[1281]: ---------------------------- MPD announces state update: player May 18 14:17:02 music volumio[1281]: info: ControllerMpd::getState May 18 14:17:02 music volumio[1281]: verbose: ControllerMpd::sendMpdCommand status May 18 14:17:02 music sudo[1626]: pam_unix(sudo:session): session closed for user root May 18 14:17:02 music volumio[1281]: info: May 18 14:17:02 music volumio[1281]: ---------------------------- MPD announces state update: player May 18 14:17:02 music volumio[1281]: info: ControllerMpd::getState May 18 14:17:02 music volumio[1281]: verbose: ControllerMpd::sendMpdCommand status May 18 14:17:02 music volumio[1281]: info: sendMpdCommand status took 22 milliseconds May 18 14:17:02 music volumio[1281]: verbose: ControllerMpd::parseState May 18 14:17:02 music volumio[1281]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 14:17:02 music volumio[1281]: info: sendMpdCommand status took 17 milliseconds May 18 14:17:02 music volumio[1281]: info: sendMpdCommand playlistinfo took 4 milliseconds May 18 14:17:02 music volumio[1281]: verbose: ControllerMpd::parseState May 18 14:17:02 music volumio[1281]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 14:17:02 music volumio[1281]: verbose: ControllerMpd::parseTrackInfo May 18 14:17:02 music volumio[1281]: info: ControllerMpd::pushState May 18 14:17:02 music volumio[1281]: info: CoreCommandRouter::servicePushState May 18 14:17:02 music volumio[1281]: info: CorePlayQueue::getTrack 0 May 18 14:17:02 music volumio[1281]: verbose: STATE SERVICE {"status":"play","position":0,"seek":341,"duration":255,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1677 Kbps","isStreaming":false,"title":"01. 时光洪流.flac","artist":"程响","album":"时光洪流","uri":"NAS/NAS/[Hires]2022抖音年度新歌CD2 24bit96khz/01. 时光洪流.flac","trackType":"flac"} May 18 14:17:02 music volumio[1281]: verbose: CURRENT POSITION 0 May 18 14:17:02 music volumio[1281]: info: CoreStateMachine::syncState stateService play May 18 14:17:02 music volumio[1281]: info: CoreStateMachine::syncState currentStatus stop May 18 14:17:02 music volumio[1281]: info: CoreStateMachine::pushState May 18 14:17:02 music volumio[1281]: info: CorePlayQueue::getTrack 0 May 18 14:17:02 music volumio[1281]: info: CoreCommandRouter::volumioPushState May 18 14:17:02 music volumio[1281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 14:17:02 music volumio[1281]: info: ------------------------------ 74ms May 18 14:17:02 music volumio[1281]: info: sendMpdCommand playlistinfo took 36 milliseconds May 18 14:17:02 music volumio[1281]: verbose: ControllerMpd::parseTrackInfo May 18 14:17:02 music volumio[1281]: info: ControllerMpd::pushState May 18 14:17:02 music volumio[1281]: info: CoreCommandRouter::servicePushState May 18 14:17:02 music volumio[1281]: info: CorePlayQueue::getTrack 0 May 18 14:17:02 music volumio[1281]: verbose: STATE SERVICE {"status":"play","position":0,"seek":533,"duration":255,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2256 Kbps","isStreaming":false,"title":"01. 时光洪流.flac","artist":"程响","album":"时光洪流","uri":"NAS/NAS/[Hires]2022抖音年度新歌CD2 24bit96khz/01. 时光洪流.flac","trackType":"flac"} May 18 14:17:02 music volumio[1281]: verbose: CURRENT POSITION 0 May 18 14:17:02 music volumio[1281]: info: CoreStateMachine::syncState stateService play May 18 14:17:02 music volumio[1281]: info: CoreStateMachine::syncState currentStatus play May 18 14:17:02 music volumio[1281]: info: Received an update from plugin. extracting info from payload May 18 14:17:02 music volumio[1281]: info: CoreStateMachine::pushState May 18 14:17:02 music volumio[1281]: info: CorePlayQueue::getTrack 0 May 18 14:17:02 music volumio[1281]: info: CoreCommandRouter::volumioPushState May 18 14:17:02 music volumio[1281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 14:17:02 music volumio[1281]: info: CoreStateMachine::pushState May 18 14:17:02 music volumio[1281]: info: CorePlayQueue::getTrack 0 May 18 14:17:02 music volumio[1281]: info: CoreCommandRouter::volumioPushState May 18 14:17:02 music volumio[1281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 14:17:02 music volumio[1281]: info: ------------------------------ 101ms May 18 14:17:02 music volumio[1281]: info: Upmpdcli Daemon Started May 18 14:17:02 music volumio[1281]: error: touch_display: Error waking up the screen: Error: Command failed: /usr/bin/xset -display :0 s reset dpms force on May 18 14:17:02 music volumio[1281]: /usr/bin/xset: unable to open display ":0" May 18 14:17:02 music volumio[1281]: error: touch_display: Error setting screensaver timeout: Error: Command failed: /usr/bin/xset -display :0 s off +dpms dpms 0 0 0 May 18 14:17:02 music volumio[1281]: /usr/bin/xset: unable to open display ":0" May 18 14:17:02 music startx[1529]: modprobe: FATAL: Module g2d_23 not found. May 18 14:17:02 music sudo[1664]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 18 14:17:02 music sudo[1664]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 14:17:02 music sudo[1664]: pam_unix(sudo:session): session closed for user root May 18 14:17:02 music sudo[1669]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 18 14:17:02 music sudo[1669]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 14:17:02 music sudo[1669]: pam_unix(sudo:session): session closed for user root May 18 14:17:02 music volumio[1281]: info: CoreCommandRouter::volumioGetState May 18 14:17:02 music volumio[1281]: info: CorePlayQueue::getTrack 0 May 18 14:17:02 music volumio[1281]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 May 18 14:17:02 music volumio[1281]: info: touch_display: Setting screensaver timeout to 0 seconds. May 18 14:17:02 music volumio[1281]: info: touch_display: Setting screensaver timeout to 0 seconds. May 18 14:17:02 music sudo[1705]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 18 14:17:02 music sudo[1705]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 14:17:02 music sudo[1707]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 18 14:17:02 music sudo[1705]: pam_unix(sudo:session): session closed for user root May 18 14:17:02 music sudo[1707]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 14:17:02 music sudo[1707]: pam_unix(sudo:session): session closed for user root May 18 14:17:03 music volumio[1281]: info: CoreCommandRouter::volumioGetState May 18 14:17:03 music volumio[1281]: info: CorePlayQueue::getTrack 0 May 18 14:17:03 music volumio[1281]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 18 14:17:03 music volumio[1281]: info: CoreCommandRouter::volumioGetVisibleSources May 18 14:17:03 music volumio[1281]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 18 14:17:03 music volumio[1281]: info: Listing playlists May 18 14:17:03 music volumio[1281]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 18 14:17:03 music volumio[1281]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 18 14:17:03 music volumio[1281]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 18 14:17:03 music volumio[1281]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 18 14:17:03 music volumio[1281]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 18 14:17:03 music volumio[1281]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 18 14:17:03 music volumio[1281]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 18 14:17:03 music volumio[1281]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 18 14:17:03 music volumio[1281]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 18 14:17:03 music volumio[1281]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 18 14:17:03 music volumio[1281]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 18 14:17:03 music volumio[1281]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 18 14:17:03 music volumio[1281]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 18 14:17:03 music volumio[1281]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 18 14:17:03 music volumio[1281]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium May 18 14:17:03 music volumio[1281]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 18 14:17:03 music volumio[1281]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 18 14:17:03 music volumio[1281]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 18 14:17:03 music volumio[1281]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 18 14:17:03 music volumio[1281]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 18 14:17:03 music volumio[1281]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 18 14:17:03 music volumio[1281]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 18 14:17:03 music volumio[1281]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 18 14:17:03 music volumio[1281]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 18 14:17:03 music volumio[1281]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 18 14:17:03 music volumio[1281]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar May 18 14:17:03 music volumio[1281]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 18 14:17:03 music volumio[1281]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 18 14:17:03 music volumio[1281]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 18 14:17:03 music volumio[1281]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 18 14:17:03 music volumio[1281]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 18 14:17:03 music volumio[1281]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 18 14:17:03 music volumio[1281]: info: Adding plugin bluetooth to MyMusic Plugins May 18 14:17:03 music volumio[1281]: info: Adding plugin cd_controller to MyMusic Plugins May 18 14:17:03 music volumio[1281]: info: Adding plugin smart_inputs to MyMusic Plugins May 18 14:17:03 music volumio[1281]: info: Adding plugin tidalconnect to MyMusic Plugins May 18 14:17:03 music volumio[1281]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 18 14:17:04 music dbus[686]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' May 18 14:17:04 music systemd[1]: Starting RealtimeKit Scheduling Policy Service... May 18 14:17:04 music dbus[686]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' May 18 14:17:04 music systemd[1]: Started RealtimeKit Scheduling Policy Service. May 18 14:17:04 music rtkit-daemon[1764]: Successfully called chroot. May 18 14:17:04 music rtkit-daemon[1764]: Successfully dropped privileges. May 18 14:17:04 music rtkit-daemon[1764]: Successfully limited resources. May 18 14:17:04 music rtkit-daemon[1764]: Running. May 18 14:17:04 music rtkit-daemon[1764]: Canary thread running. May 18 14:17:04 music rtkit-daemon[1764]: Watchdog thread running. May 18 14:17:04 music volumio[1281]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 18 14:17:05 music pulseaudio[1763]: Unable to contact D-Bus: org.freedesktop.DBus.Error.Spawn.ExecFailed: /usr/bin/dbus-launch terminated abnormally without any error message May 18 14:17:05 music pulseaudio[1763]: Unable to contact D-Bus: org.freedesktop.DBus.Error.Spawn.ExecFailed: /usr/bin/dbus-launch terminated abnormally without any error message May 18 14:17:05 music pulseaudio[1763]: org.bluez.Manager.GetProperties() failed: org.freedesktop.DBus.Error.UnknownMethod: Method "GetProperties" with signature "" on interface "org.bluez.Manager" doesn't exist May 18 14:17:05 music bluetoothd[782]: Endpoint registered: sender=:1.20 path=/MediaEndpoint/A2DPSource May 18 14:17:05 music bluetoothd[782]: Endpoint registered: sender=:1.20 path=/MediaEndpoint/A2DPSink May 18 14:17:05 music volumio[1281]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 18 14:17:05 music volumio[1281]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 18 14:17:05 music volumio[1281]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 14:17:05 music volumio[1281]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 14:17:05 music volumio[1281]: info: Starting MyVolumio Remote Streaming Endpoints May 18 14:17:05 music volumio[1281]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 18 14:17:05 music volumio[1281]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 18 14:17:05 music volumio[1281]: info: Streaming services startup May 18 14:17:05 music volumio[1281]: info: Starting Streaming Daemon May 18 14:17:05 music sudo[1775]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 18 14:17:05 music sudo[1775]: pam_unix(sudo:session): session opened for user root by (uid=0) May 18 14:17:05 music systemd[1]: Stopping Volumio Streaming Daemon... May 18 14:17:05 music volumio[1281]: info: MyVolumio login type: Token May 18 14:17:05 music volumio[1281]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 18 14:17:05 music systemd[1]: Starting Volumio Streaming Daemon... May 18 14:17:05 music sudo[1775]: pam_unix(sudo:session): session closed for user root May 18 14:17:05 music systemd[1]: Started Volumio Streaming Daemon. May 18 14:17:05 music volumio-streaming-daemon[1785]: ############################ May 18 14:17:05 music volumio-streaming-daemon[1785]: # Volumio Streaming Daemon # May 18 14:17:05 music volumio-streaming-daemon[1785]: # Running on port 7777 # May 18 14:17:05 music volumio-streaming-daemon[1785]: ############################ May 18 14:17:05 music volumio-streaming-daemon[1785]: Environment: production May 18 14:17:05 music volumio[1281]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 18 14:17:07 music volumio-streaming-daemon[1785]: Environment: production May 18 14:17:07 music volumio[1281]: info: Fetching Streaming Services browse cache May 18 14:17:13 music volumio[1281]: error: [now-playing-app] App error: May 18 14:17:13 music volumio[1281]: info: CoreCommandRouter::volumioGetState May 18 14:17:13 music volumio[1281]: info: CorePlayQueue::getTrack 0 May 18 14:17:14 music volumio[1281]: info: CoreCommandRouter::volumioNext May 18 14:17:14 music volumio[1281]: info: CoreStateMachine::next May 18 14:17:14 music volumio[1281]: info: CoreStateMachine::stop May 18 14:17:14 music volumio[1281]: info: CoreStateMachine::setConsumeUpdateService undefined May 18 14:17:14 music volumio[1281]: UNSET VOLATILE May 18 14:17:14 music volumio[1281]: info: CoreStateMachine::stPlaybackTimer May 18 14:17:14 music volumio[1281]: info: CoreStateMachine::updateTrackBlock May 18 14:17:14 music volumio[1281]: info: CorePlayQueue::getTrackBlock May 18 14:17:14 music volumio[1281]: info: CoreStateMachine::pushState May 18 14:17:14 music volumio[1281]: info: CorePlayQueue::getTrack 0 May 18 14:17:14 music volumio[1281]: info: CoreCommandRouter::volumioPushState May 18 14:17:14 music volumio[1281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 14:17:14 music volumio[1281]: info: CoreStateMachine::serviceStop May 18 14:17:14 music volumio[1281]: info: CorePlayQueue::getTrack 0 May 18 14:17:14 music volumio[1281]: info: CoreCommandRouter::serviceStop May 18 14:17:14 music volumio[1281]: info: ControllerMpd::stop May 18 14:17:14 music volumio[1281]: verbose: ControllerMpd::sendMpdCommand stop May 18 14:17:14 music volumio[1281]: info: touch_display: Setting screensaver timeout to 0 seconds. May 18 14:17:14 music volumio[1281]: info: sendMpdCommand stop took 58 milliseconds May 18 14:17:14 music volumio[1281]: info: CoreStateMachine::play index undefined May 18 14:17:14 music volumio[1281]: info: CoreStateMachine::setConsumeUpdateService undefined May 18 14:17:14 music volumio[1281]: info: CorePlayQueue::getTrack 13 May 18 14:17:14 music volumio[1281]: info: CoreStateMachine::startPlaybackTimer May 18 14:17:14 music volumio[1281]: info: CorePlayQueue::getTrack 13 May 18 14:17:14 music volumio[1281]: verbose: ControllerMpd::clearAddPlayTracks NAS/NAS/[Hires]2022抖音年度新歌CD2 24bit96khz/15. 可可托海的牧羊人.flac May 18 14:17:14 music volumio[1281]: verbose: ControllerMpd::sendMpdCommand stop May 18 14:17:14 music volumio[1281]: info: CoreStateMachine::updateTrackBlock May 18 14:17:14 music volumio[1281]: info: CorePlayQueue::getTrackBlock May 18 14:17:14 music volumio[1281]: info: May 18 14:17:14 music volumio[1281]: ---------------------------- MPD announces state update: player May 18 14:17:14 music volumio[1281]: info: ControllerMpd::getState May 18 14:17:14 music volumio[1281]: verbose: ControllerMpd::sendMpdCommand status May 18 14:17:14 music volumio[1281]: info: sendMpdCommand stop took 11 milliseconds May 18 14:17:14 music volumio[1281]: verbose: ControllerMpd::sendMpdCommand clear May 18 14:17:14 music volumio[1281]: info: May 18 14:17:14 music volumio[1281]: ---------------------------- MPD announces system playlist update May 18 14:17:14 music volumio[1281]: info: Ignoring MPD Status Update May 18 14:17:14 music volumio[1281]: info: sendMpdCommand status took 9 milliseconds May 18 14:17:14 music volumio[1281]: info: sendMpdCommand clear took 8 milliseconds May 18 14:17:14 music volumio[1281]: verbose: ControllerMpd::parseState May 18 14:17:14 music volumio[1281]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 14:17:14 music volumio[1281]: verbose: ControllerMpd::sendMpdCommand add "NAS/NAS/[Hires]2022抖音年度新歌CD2 24bit96khz/15. 可可托海的牧羊人.flac" May 18 14:17:14 music volumio[1281]: error: updateQueue error: null May 18 14:17:14 music volumio[1281]: info: May 18 14:17:14 music volumio[1281]: ---------------------------- MPD announces system playlist update May 18 14:17:14 music volumio[1281]: info: Ignoring MPD Status Update May 18 14:17:14 music volumio[1281]: info: ------------------------------ 14ms May 18 14:17:14 music volumio[1281]: info: sendMpdCommand playlistinfo took 6 milliseconds May 18 14:17:14 music volumio[1281]: info: sendMpdCommand add "NAS/NAS/[Hires]2022抖音年度新歌CD2 24bit96khz/15. 可可托海的牧羊人.flac" took 5 milliseconds May 18 14:17:14 music volumio[1281]: verbose: ControllerMpd::parseTrackInfo May 18 14:17:14 music volumio[1281]: verbose: ControllerMpd::sendMpdCommand play May 18 14:17:14 music volumio[1281]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 18 14:17:14 music volumio[1281]: info: ------------------------------ 28ms May 18 14:17:14 music volumio[1281]: info: ------------------------------ 8ms May 18 14:17:14 music volumio[1281]: info: sendMpdCommand play took 4 milliseconds May 18 14:17:14 music volumio[1281]: info: May 18 14:17:14 music volumio[1281]: ---------------------------- MPD announces state update: player May 18 14:17:14 music volumio[1281]: info: ControllerMpd::getState May 18 14:17:14 music volumio[1281]: verbose: ControllerMpd::sendMpdCommand status May 18 14:17:14 music volumio[1281]: info: May 18 14:17:14 music volumio[1281]: ---------------------------- MPD announces state update: player May 18 14:17:14 music volumio[1281]: info: sendMpdCommand status took 2 milliseconds May 18 14:17:14 music volumio[1281]: info: ControllerMpd::getState May 18 14:17:14 music volumio[1281]: verbose: ControllerMpd::sendMpdCommand status May 18 14:17:14 music volumio[1281]: verbose: ControllerMpd::parseState May 18 14:17:14 music volumio[1281]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 14:17:14 music volumio[1281]: info: sendMpdCommand status took 3 milliseconds May 18 14:17:14 music volumio[1281]: info: sendMpdCommand playlistinfo took 3 milliseconds May 18 14:17:14 music volumio[1281]: verbose: ControllerMpd::parseState May 18 14:17:14 music volumio[1281]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 18 14:17:14 music volumio[1281]: verbose: ControllerMpd::parseTrackInfo May 18 14:17:14 music volumio[1281]: info: ControllerMpd::pushState May 18 14:17:14 music volumio[1281]: info: CoreCommandRouter::servicePushState May 18 14:17:14 music volumio[1281]: info: CorePlayQueue::getTrack 13 May 18 14:17:14 music volumio[1281]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":342,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"15. 可可托海的牧羊人.flac","artist":"王琪","album":"可可托海的牧羊人","uri":"NAS/NAS/[Hires]2022抖音年度新歌CD2 24bit96khz/15. 可可托海的牧羊人.flac","trackType":"flac"} May 18 14:17:14 music volumio[1281]: verbose: CURRENT POSITION 13 May 18 14:17:14 music volumio[1281]: info: CoreStateMachine::syncState stateService play May 18 14:17:14 music volumio[1281]: info: CoreStateMachine::syncState currentStatus stop May 18 14:17:14 music volumio[1281]: info: CoreStateMachine::pushState May 18 14:17:14 music volumio[1281]: info: CorePlayQueue::getTrack 13 May 18 14:17:14 music volumio[1281]: info: CoreCommandRouter::volumioPushState May 18 14:17:14 music volumio[1281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 14:17:14 music volumio[1281]: info: ------------------------------ 50ms May 18 14:17:14 music volumio[1281]: info: sendMpdCommand playlistinfo took 45 milliseconds May 18 14:17:14 music volumio[1281]: verbose: ControllerMpd::parseTrackInfo May 18 14:17:14 music volumio[1281]: info: ControllerMpd::pushState May 18 14:17:14 music volumio[1281]: info: CoreCommandRouter::servicePushState May 18 14:17:14 music volumio[1281]: info: CorePlayQueue::getTrack 13 May 18 14:17:14 music volumio[1281]: verbose: STATE SERVICE {"status":"play","position":0,"seek":106,"duration":342,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"3 Kbps","isStreaming":false,"title":"15. 可可托海的牧羊人.flac","artist":"王琪","album":"可可托海的牧羊人","uri":"NAS/NAS/[Hires]2022抖音年度新歌CD2 24bit96khz/15. 可可托海的牧羊人.flac","trackType":"flac"} May 18 14:17:14 music volumio[1281]: verbose: CURRENT POSITION 13 May 18 14:17:14 music volumio[1281]: info: CoreStateMachine::syncState stateService play May 18 14:17:14 music volumio[1281]: info: CoreStateMachine::syncState currentStatus play May 18 14:17:14 music volumio[1281]: info: Received an update from plugin. extracting info from payload May 18 14:17:14 music volumio[1281]: info: CoreStateMachine::pushState May 18 14:17:14 music volumio[1281]: info: CorePlayQueue::getTrack 13 May 18 14:17:14 music volumio[1281]: info: CoreCommandRouter::volumioPushState May 18 14:17:14 music volumio[1281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 14:17:14 music volumio[1281]: info: CoreStateMachine::pushState May 18 14:17:14 music volumio[1281]: info: CorePlayQueue::getTrack 13 May 18 14:17:14 music volumio[1281]: info: CoreCommandRouter::volumioPushState May 18 14:17:14 music volumio[1281]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 18 14:17:14 music volumio[1281]: info: ------------------------------ 116ms May 18 14:17:14 music volumio[1281]: info: touch_display: Setting screensaver timeout to 0 seconds. May 18 14:17:21 music volumio[1281]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 18 14:17:21 music volumio[1281]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 18 14:17:35 music volumio[1281]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 18 14:17:35 music volumio[1281]: { Error: connect ETIMEDOUT 199.16.158.8:443 May 18 14:17:35 music volumio[1281]: at Object._errnoException (util.js:1022:11) May 18 14:17:35 music volumio[1281]: at _exceptionWithHostPort (util.js:1044:20) May 18 14:17:35 music volumio[1281]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) May 18 14:17:35 music volumio[1281]: code: 'ETIMEDOUT', May 18 14:17:35 music volumio[1281]: errno: 'ETIMEDOUT', May 18 14:17:35 music volumio[1281]: syscall: 'connect', May 18 14:17:35 music volumio[1281]: address: '199.16.158.8', May 18 14:17:35 music volumio[1281]: port: 443 } May 18 14:17:35 music volumio[1281]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 18 14:17:39 music sudo[1958]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-18 14:16 May 18 14:17:39 music sudo[1958]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" 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="cd11dc738d0d82822d0aa776d0729686b82e3758" VOLUMIO_FE_VERSION="77db3ca2a7f5a7023ece8c5b86e997d58abdace6" VOLUMIO_BE_VERSION="748352701ebc6ec2b59443fcac1ec9aea1f276df" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Jul 2 12:45:33 CEST 2021" VOLUMIO_VERSION="2.907" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="858e764292794952fe9c14f4c1868f5a"