-- Logs begin at Mon 2025-03-03 11:08:56 +07, end at Mon 2025-03-03 11:14:06 +07. -- Mar 03 11:13:02 volumio volumio[931]: verbose: ----------------------------------- Mar 03 11:13:02 volumio volumio[931]: verbose: [yt-cast-receiver] (AID: 12) (YouTube) Incoming message: 'noop' Mar 03 11:13:02 volumio volumio[931]: verbose: [yt-cast-receiver] (AID: 12) (YouTube) Not handled: 'noop' Mar 03 11:13:03 volumio volumio[931]: verbose: ----------------------------------- Mar 03 11:13:03 volumio volumio[931]: verbose: [yt-cast-receiver] (AID: 12) (YouTube Music) Incoming message: 'noop' Mar 03 11:13:03 volumio volumio[931]: verbose: [yt-cast-receiver] (AID: 12) (YouTube Music) Not handled: 'noop' Mar 03 11:13:04 volumio volumio[931]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 03 11:13:04 volumio volumio[931]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 03 11:13:05 volumio volumio[931]: error: Failed to check for new versions for plugin YouTube Music: Error: Invalid argument not valid semver ('' received) Mar 03 11:13:06 volumio volumio[931]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 03 11:13:18 volumio volumio[931]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 03 11:13:18 volumio volumio[931]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 03 11:13:19 volumio volumio[931]: error: Failed to check for new versions for plugin YouTube Music: Error: Invalid argument not valid semver ('' received) Mar 03 11:13:21 volumio volumio[931]: info: Enabling plugin fusiondsp Mar 03 11:13:21 volumio volumio[931]: info: Loading plugin "fusiondsp"... Mar 03 11:13:21 volumio volumio[931]: info: Applying required configuration parameters for plugin fusiondsp Mar 03 11:13:21 volumio volumio[931]: info: Preparing to generate the ALSA configuration file Mar 03 11:13:21 volumio volumio[931]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Mar 03 11:13:21 volumio volumio[931]: info: Reading ALSA contributions from plugins. Mar 03 11:13:21 volumio volumio[931]: info: Asound.conf file unchanged, so no further update is needed Mar 03 11:13:21 volumio volumio[931]: info: Output device has changed, restarting MPD Mar 03 11:13:21 volumio sudo[3195]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 03 11:13:21 volumio sudo[3195]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 11:13:21 volumio sudo[3195]: pam_unix(sudo:session): session closed for user root Mar 03 11:13:21 volumio volumio[931]: info: Output device has changed, restarting Shairport Sync Mar 03 11:13:21 volumio volumio[931]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 11:13:21 volumio volumio[931]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 11:13:21 volumio sudo[3198]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 03 11:13:21 volumio sudo[3198]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 11:13:21 volumio systemd[1]: musicservicesshield.service: Succeeded. Mar 03 11:13:21 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Mar 03 11:13:21 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Mar 03 11:13:21 volumio systemd[1]: Stopping Music Player Daemon... Mar 03 11:13:21 volumio systemd[1]: mpd.service: Succeeded. Mar 03 11:13:21 volumio systemd[1]: Stopped Music Player Daemon. Mar 03 11:13:21 volumio volumio[931]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 03 11:13:21 volumio systemd[1]: Starting Music Player Daemon... Mar 03 11:13:21 volumio volumio[931]: info: PLUGIN START: fusiondsp Mar 03 11:13:21 volumio volumio[931]: info: Loading i18n strings for locale vi Mar 03 11:13:21 volumio volumio[931]: info: Plugin mpdoutput has duplicated i18n key SAVE. It is ignored. Mar 03 11:13:21 volumio volumio[931]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Mar 03 11:13:21 volumio volumio[931]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Mar 03 11:13:21 volumio volumio[931]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 11:13:21 volumio volumio[931]: info: FusionDsp - mixtype--------------------- Hardware Mar 03 11:13:21 volumio volumio[931]: info: Preparing to generate the ALSA configuration file Mar 03 11:13:21 volumio volumio[931]: info: Done. Mar 03 11:13:21 volumio volumio[931]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Mar 03 11:13:21 volumio volumio[931]: info: Reading ALSA contributions from plugins. Mar 03 11:13:21 volumio volumio[931]: info: MPD Permissions set Mar 03 11:13:21 volumio volumio[931]: verbose: [ytcr] MPDSubsystemEventEmitter destroyed. Mar 03 11:13:21 volumio volumio[931]: verbose: [ytcr] Cancelling prefetch (abortIfPrefetching: true, clearIfPrefetched: true) Mar 03 11:13:21 volumio volumio[931]: info: CoreCommandRouter::volumioGetState Mar 03 11:13:21 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Mar 03 11:13:21 volumio volumio[931]: verbose: [ytcr] MPD disconnected. Reconnecting... Mar 03 11:13:21 volumio sudo[3203]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 03 11:13:21 volumio sudo[3203]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 11:13:21 volumio volumio[931]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 11:13:21 volumio volumio[931]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 11:13:21 volumio volumio[931]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 11:13:21 volumio volumio[931]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 11:13:21 volumio volumio[931]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 11:13:21 volumio volumio[931]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 11:13:21 volumio volumio[931]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 11:13:21 volumio volumio[931]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 11:13:21 volumio sudo[3203]: pam_unix(sudo:session): session closed for user root Mar 03 11:13:21 volumio volumio[931]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 11:13:21 volumio volumio[931]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 03 11:13:21 volumio volumio[931]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 11:13:21 volumio volumio[931]: info: Starting Shairport Sync Mar 03 11:13:21 volumio volumio[931]: info: CoreCommandRouter::volumioGetState Mar 03 11:13:21 volumio volumio[931]: info: CorePlayQueue::getTrack 0 Mar 03 11:13:21 volumio volumio[931]: info: CoreCommandRouter::volumioPause Mar 03 11:13:21 volumio volumio[931]: info: CoreStateMachine::pause Mar 03 11:13:21 volumio volumio[931]: info: mpdhttpout --- Volumio set on pause Mar 03 11:13:21 volumio volumio[931]: info: mpdhttpout ---Configuration successfully added to mpd.conf. Mar 03 11:13:21 volumio volumio[931]: info: Asound.conf file unchanged, so no further update is needed Mar 03 11:13:21 volumio volumio[931]: info: Output device has changed, restarting MPD Mar 03 11:13:21 volumio sudo[3213]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 03 11:13:21 volumio sudo[3213]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 11:13:22 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 03 11:13:22 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 03 11:13:22 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 03 11:13:22 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 03 11:13:22 volumio sudo[3213]: pam_unix(sudo:session): session closed for user root Mar 03 11:13:22 volumio sudo[3219]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 03 11:13:22 volumio sudo[3219]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 11:13:22 volumio sudo[3219]: pam_unix(sudo:session): session closed for user root Mar 03 11:13:22 volumio volumio[931]: info: Output device has changed, restarting Shairport Sync Mar 03 11:13:22 volumio volumio[931]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 11:13:22 volumio volumio[931]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 11:13:22 volumio sudo[3224]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 03 11:13:22 volumio sudo[3224]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 11:13:22 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Mar 03 11:13:22 volumio systemd[1]: mpd.service: Succeeded. Mar 03 11:13:22 volumio systemd[1]: Stopped Music Player Daemon. Mar 03 11:13:22 volumio volumio[931]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 03 11:13:22 volumio systemd[1]: Starting Music Player Daemon... Mar 03 11:13:22 volumio volumio[931]: info: MPD Permissions set Mar 03 11:13:22 volumio volumio[931]: info: Shairport-Sync Started Mar 03 11:13:22 volumio volumio[931]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 11:13:22 volumio volumio[931]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 11:13:22 volumio volumio[931]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 03 11:13:22 volumio sudo[3229]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 03 11:13:22 volumio sudo[3229]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 11:13:22 volumio sudo[3229]: pam_unix(sudo:session): session closed for user root Mar 03 11:13:22 volumio volumio[931]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 11:13:22 volumio volumio[931]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 03 11:13:22 volumio volumio[931]: TypeError: Cannot read property 'off' of undefined Mar 03 11:13:22 volumio volumio[931]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:107:15) Mar 03 11:13:22 volumio volumio[931]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Mar 03 11:13:22 volumio volumio[931]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8) Mar 03 11:13:22 volumio volumio[931]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1551:29) Mar 03 11:13:22 volumio volumio[931]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1395:47) Mar 03 11:13:22 volumio volumio[931]: at Socket.emit (events.js:400:28) Mar 03 11:13:22 volumio volumio[931]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Mar 03 11:13:22 volumio volumio[931]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Mar 03 11:13:22 volumio volumio[931]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 03 11:13:22 volumio sudo[3247]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-03 11:12 Mar 03 11:13:22 volumio sudo[3247]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 11:13:22 volumio sudo[3247]: pam_unix(sudo:session): session closed for user root Mar 03 11:13:23 volumio mpd[3236]: Mar 03 11:13 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 03 11:13:23 volumio systemd[1]: Started Music Player Daemon. Mar 03 11:13:23 volumio sudo[3198]: pam_unix(sudo:session): session closed for user root Mar 03 11:13:23 volumio sudo[3224]: pam_unix(sudo:session): session closed for user root Mar 03 11:13:23 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Mar 03 11:13:23 volumio volumio[3256]: cset: --> shielding system active with Mar 03 11:13:23 volumio volumio[3256]: cset: "system" cpuset of CPUSPEC(1) with 187 tasks running Mar 03 11:13:23 volumio volumio[3256]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Mar 03 11:13:23 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Mar 03 11:13:23 volumio go-librespot[1587]: time="2025-03-03T11:13:23+07:00" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" Mar 03 11:13:23 volumio volumio-remote-updater[622]: [2025-03-03 11:13:23] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Mar 03 11:13:23 volumio volumio-remote-updater[622]: [2025-03-03 11:13:23] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Mar 03 11:13:23 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Mar 03 11:13:23 volumio systemd[1]: media-New_Volume.mount: Succeeded. Mar 03 11:13:24 volumio ntfs-3g[1206]: Unmounting /dev/sdb4 (New Volume) Mar 03 11:13:24 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Mar 03 11:13:24 volumio systemd[1]: Started dynamicswap service. Mar 03 11:13:24 volumio systemd[1]: dynamicswap.service: Succeeded. Mar 03 11:13:24 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Mar 03 11:13:24 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Mar 03 11:13:24 volumio systemd[1]: Stopped Volumio Backend Module. Mar 03 11:13:24 volumio systemd[1]: Started Volumio Backend Module. Mar 03 11:13:24 volumio systemd[1]: Started dynamicswap service. Mar 03 11:13:24 volumio systemd[1]: dynamicswap.service: Succeeded. Mar 03 11:13:25 volumio volumio[3285]: info: ------------------------------------------- Mar 03 11:13:25 volumio volumio[3285]: info: ----- Volumio3 ---- Mar 03 11:13:25 volumio volumio[3285]: info: ------------------------------------------- Mar 03 11:13:25 volumio volumio[3285]: info: ----- System startup ---- Mar 03 11:13:25 volumio volumio[3285]: info: ------------------------------------------- Mar 03 11:13:26 volumio volumio[3285]: info: MYVOLUMIO Environment detected Mar 03 11:13:26 volumio volumio[3285]: info: Plugin folders cleanup Mar 03 11:13:26 volumio volumio[3285]: info: Scanning into folder /volumio/app/plugins/ Mar 03 11:13:26 volumio volumio[3285]: info: Scanning category audio_interface Mar 03 11:13:26 volumio volumio[3285]: info: Scanning category miscellanea Mar 03 11:13:26 volumio volumio[3285]: info: Scanning category music_service Mar 03 11:13:26 volumio volumio[3285]: info: Scanning category plugins.json Mar 03 11:13:26 volumio volumio[3285]: info: Scanning category system_controller Mar 03 11:13:26 volumio volumio[3285]: info: Scanning category user_interface Mar 03 11:13:26 volumio volumio[3285]: info: Scanning into folder /data/plugins/ Mar 03 11:13:26 volumio volumio[3285]: info: Scanning category audio_interface Mar 03 11:13:26 volumio volumio[3285]: info: Scanning category music_service Mar 03 11:13:26 volumio volumio[3285]: info: Scanning category system_controller Mar 03 11:13:26 volumio volumio[3285]: info: Scanning category system_hardware Mar 03 11:13:26 volumio volumio[3285]: info: Scanning category user_interface Mar 03 11:13:26 volumio volumio[3285]: info: Plugin folders cleanup completed Mar 03 11:13:26 volumio volumio[3285]: info: ------------------------------------------- Mar 03 11:13:26 volumio volumio[3285]: info: ----- Core plugins startup ---- Mar 03 11:13:26 volumio volumio[3285]: info: ------------------------------------------- Mar 03 11:13:26 volumio volumio[3285]: info: Loading plugins from folder /volumio/app/plugins/ Mar 03 11:13:26 volumio volumio[3285]: info: Adding plugin upnp to MyMusic Plugins Mar 03 11:13:26 volumio volumio[3285]: info: Adding plugin airplay_emulation to MyMusic Plugins Mar 03 11:13:26 volumio volumio[3285]: info: Adding plugin upnp_browser to MyMusic Plugins Mar 03 11:13:26 volumio volumio[3285]: info: Loading plugins from folder /data/plugins/ Mar 03 11:13:26 volumio volumio[3285]: info: Loading plugin "system"... Mar 03 11:13:26 volumio volumio[3285]: info: Loading plugin "appearance"... Mar 03 11:13:26 volumio volumio[3285]: info: Loading plugin "network"... Mar 03 11:13:27 volumio volumio[3285]: info: Refreshing Cached IP Addresses Mar 03 11:13:27 volumio sudo[3313]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 03 11:13:27 volumio sudo[3313]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 11:13:27 volumio sudo[3313]: pam_unix(sudo:session): session closed for user root Mar 03 11:13:27 volumio sudo[3317]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 03 11:13:27 volumio sudo[3317]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 11:13:27 volumio volumio[3285]: info: Loading plugin "services"... Mar 03 11:13:27 volumio sudo[3317]: pam_unix(sudo:session): session closed for user root Mar 03 11:13:27 volumio volumio[3285]: info: Loading plugin "alsa_controller"... Mar 03 11:13:27 volumio sudo[3324]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 03 11:13:27 volumio sudo[3324]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 11:13:27 volumio sudo[3324]: pam_unix(sudo:session): session closed for user root Mar 03 11:13:27 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 03 11:13:27 volumio volumio[3285]: info: Loading plugin "wizard"... Mar 03 11:13:27 volumio volumio[3285]: info: Loading plugin "networkfs"... Mar 03 11:13:27 volumio volumio[3285]: info: Starting Udev Watcher for removable devices Mar 03 11:13:27 volumio volumio[3285]: info: Ignoring mount for partition: boot Mar 03 11:13:27 volumio volumio[3285]: info: Ignoring mount for partition: volumio Mar 03 11:13:27 volumio volumio[3285]: info: Ignoring mount for partition: volumio_data Mar 03 11:13:27 volumio volumio[3285]: info: Mounting Device New_Volume Mar 03 11:13:27 volumio sudo[3348]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb4 /mnt/USB/New_Volume -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Mar 03 11:13:27 volumio sudo[3348]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 11:13:28 volumio ntfs-3g[3351]: Version 2017.3.23AR.3 integrated FUSE 28 Mar 03 11:13:28 volumio ntfs-3g[3351]: Mounted /dev/sdb4 (Read-Write, label "New Volume", NTFS 3.1) Mar 03 11:13:28 volumio ntfs-3g[3351]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Mar 03 11:13:28 volumio ntfs-3g[3351]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sdb4,blkdev,blksize=4096 Mar 03 11:13:28 volumio ntfs-3g[3351]: Global ownership and permissions enforced, configuration type 7 Mar 03 11:13:28 volumio sudo[3348]: pam_unix(sudo:session): session closed for user root Mar 03 11:13:28 volumio volumio[3285]: info: Ignoring mount for partition: boot Mar 03 11:13:28 volumio volumio[3285]: info: Ignoring mount for partition: volumio Mar 03 11:13:28 volumio volumio[3285]: info: Ignoring mount for partition: volumio_data Mar 03 11:13:28 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 03 11:13:28 volumio volumio[3285]: info: Loading plugin "volumio_command_line_client"... Mar 03 11:13:28 volumio volumio[3285]: info: Loading plugin "upnp"... Mar 03 11:13:28 volumio volumio[3285]: info: [1740975208099] Starting Upmpd Daemon Mar 03 11:13:28 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 03 11:13:28 volumio volumio[3285]: info: Loading plugin "my_music"... Mar 03 11:13:28 volumio volumio[3285]: info: Loading plugin "mpd"... Mar 03 11:13:28 volumio volumio[3285]: info: Loading plugin "upnp_browser"... Mar 03 11:13:28 volumio volumio[3285]: info: Loading plugin "alarm-clock"... Mar 03 11:13:28 volumio volumio[3285]: info: Loading plugin "airplay_emulation"... Mar 03 11:13:28 volumio volumio[3285]: info: Starting Shairport Sync Mar 03 11:13:28 volumio volumio[3285]: info: Loading plugin "last_100"... Mar 03 11:13:28 volumio volumio[3285]: info: Loading plugin "webradio"... Mar 03 11:13:28 volumio volumio[3285]: info: Loading plugin "i2s_dacs"... Mar 03 11:13:28 volumio volumio[3285]: info: I2S DAC not set, start Auto-detection Mar 03 11:13:28 volumio volumio[3285]: info: Loading plugin "volumiodiscovery"... Mar 03 11:13:28 volumio volumio[3285]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 03 11:13:28 volumio node[3285]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 03 11:13:28 volumio volumio[3285]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 03 11:13:28 volumio node[3285]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 03 11:13:28 volumio volumio[3285]: *** WARNING *** For more information see Mar 03 11:13:28 volumio node[3285]: *** WARNING *** For more information see Mar 03 11:13:28 volumio volumio[3285]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 03 11:13:28 volumio node[3285]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 03 11:13:28 volumio volumio[3285]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 03 11:13:28 volumio node[3285]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 03 11:13:28 volumio volumio[3285]: *** WARNING *** For more information see Mar 03 11:13:28 volumio node[3285]: *** WARNING *** For more information see Mar 03 11:13:28 volumio volumio[3285]: info: Applying required configuration parameters for plugin volumiodiscovery Mar 03 11:13:28 volumio volumio[3285]: info: Discovery: Started advertising with name: Volumio Mar 03 11:13:28 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 03 11:13:28 volumio volumio[3285]: info: Loading plugin "spop"... Mar 03 11:13:28 volumio volumio-remote-updater[622]: [2025-03-03 11:13:28] [connect] Successful connection Mar 03 11:13:28 volumio volumio[3285]: info: Loading plugin "youtube2"... Mar 03 11:13:30 volumio volumio[3285]: info: Loading plugin "ytcr"... Mar 03 11:13:31 volumio volumio[3285]: info: Loading plugin "ytmusic"... Mar 03 11:13:31 volumio ntpd[1347]: Soliciting pool server 2400:6760:0:200::254 Mar 03 11:13:32 volumio volumio[3285]: info: Loading plugin "autostart"... Mar 03 11:13:32 volumio volumio[3285]: info: Applying required configuration parameters for plugin autostart Mar 03 11:13:32 volumio volumio[3285]: info: AutoStart - onVolumioStart - read config.json Mar 03 11:13:32 volumio volumio[3285]: info: Loading plugin "now_playing"... Mar 03 11:13:33 volumio volumio[3285]: info: Loading plugin "outputs"... Mar 03 11:13:33 volumio volumio[3285]: info: Loading plugin "albumart"... Mar 03 11:13:33 volumio volumio[3285]: info: Plugin example_plugin is not enabled Mar 03 11:13:33 volumio volumio[3285]: info: Loading plugin "inputs"... Mar 03 11:13:33 volumio volumio[3285]: info: Loading plugin "updater_comm"... Mar 03 11:13:33 volumio volumio[3285]: info: Plugin mpdemulation is not enabled Mar 03 11:13:33 volumio volumio[3285]: info: Loading plugin "rest_api"... Mar 03 11:13:33 volumio volumio[3285]: info: Loading plugin "websocket"... Mar 03 11:13:33 volumio volumio[3285]: info: Starting Socket.io Server version 2.3.0 Mar 03 11:13:33 volumio volumio[3285]: info: Loading plugin "fusiondsp"... Mar 03 11:13:33 volumio volumio[3285]: Forking 1 albumart workers Mar 03 11:13:33 volumio volumio[3285]: info: Applying required configuration parameters for plugin fusiondsp Mar 03 11:13:33 volumio volumio[3285]: info: Loading plugin "mpdoutput"... Mar 03 11:13:33 volumio volumio[3285]: info: Loading plugin "minidlna"... Mar 03 11:13:33 volumio volumio[3285]: info: Applying required configuration parameters for plugin minidlna Mar 03 11:13:34 volumio volumio[3285]: Starting albumart workers Mar 03 11:13:34 volumio volumio[3285]: info: Loading plugin "moosbox"... Mar 03 11:13:34 volumio volumio[3285]: info: Applying required configuration parameters for plugin moosbox Mar 03 11:13:34 volumio volumio[3285]: info: Loading plugin "podcast"... Mar 03 11:13:34 volumio volumio[3285]: info: ControllerPodcast::constructor Mar 03 11:13:34 volumio volumio[3285]: info: Loading plugin "backup_restore"... Mar 03 11:13:34 volumio volumio[3285]: info: Applying required configuration parameters for plugin backup_restore Mar 03 11:13:34 volumio volumio[3285]: info: Loading plugin "music_services_shield"... Mar 03 11:13:34 volumio volumio[3285]: info: Applying required configuration parameters for plugin music_services_shield Mar 03 11:13:34 volumio volumio[3285]: info: Loading plugin "onkyo_control"... Mar 03 11:13:34 volumio volumio[3285]: info: Applying required configuration parameters for plugin onkyo_control Mar 03 11:13:34 volumio volumio[3285]: info: Loading i18n strings for locale vi Mar 03 11:13:34 volumio volumio[3285]: info: Plugin mpdoutput has duplicated i18n key SAVE. It is ignored. Mar 03 11:13:34 volumio volumio[3285]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Mar 03 11:13:34 volumio volumio[3285]: Updating browse sources language Mar 03 11:13:34 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 03 11:13:34 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 03 11:13:34 volumio volumio[3285]: info: CoreCommandRouter::initPlayerControls Mar 03 11:13:34 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 11:13:34 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 11:13:34 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 11:13:34 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 11:13:34 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 11:13:34 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 11:13:34 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 11:13:34 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 11:13:34 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 03 11:13:34 volumio volumio[3285]: Express server listening on port 3000 Mar 03 11:13:34 volumio volumio[3285]: [Metrics] WebUI: 9s 373.71ms Mar 03 11:13:34 volumio volumio[3285]: info: Setting Device type: x86 Mar 03 11:13:34 volumio volumio[3285]: info: CoreStateMachine::resetVolumioState Mar 03 11:13:34 volumio volumio[3285]: info: CoreStateMachine::getcurrentVolume Mar 03 11:13:34 volumio volumio[3285]: info: CoreCommandRouter::volumioRetrievevolume Mar 03 11:13:34 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 11:13:34 volumio volumio[3285]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Mar 03 11:13:34 volumio volumio[3285]: info: Completed loading Core Plugins Mar 03 11:13:34 volumio volumio[3285]: info: Preparing to generate the ALSA configuration file Mar 03 11:13:34 volumio volumio[3285]: info: Volumio Network Manager: Network status updated: 1 Mar 03 11:13:34 volumio volumio[3285]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Mar 03 11:13:34 volumio volumio[3285]: wlan0 Interface doesn't support scanning. Mar 03 11:13:34 volumio volumio[3285]: info: Cannot use regular scanning, forcing with ap-force Mar 03 11:13:35 volumio sudo[3381]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Mar 03 11:13:35 volumio sudo[3381]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 11:13:35 volumio sudo[3381]: pam_unix(sudo:session): session closed for user root Mar 03 11:13:35 volumio volumio[3285]: command failed: No such device (-19) Mar 03 11:13:35 volumio volumio[3285]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Mar 03 11:13:35 volumio volumio[3285]: command failed: No such device (-19) Mar 03 11:13:35 volumio volumio[3285]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Mar 03 11:13:35 volumio volumio[3285]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Mar 03 11:13:35 volumio volumio[3285]: info: Reading ALSA contributions from plugins. Mar 03 11:13:35 volumio volumio[3285]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Mar 03 11:13:35 volumio volumio[3285]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Mar 03 11:13:35 volumio volumio[3285]: info: Reloading queue from file Mar 03 11:13:35 volumio volumio[3285]: info: CoreStateMachine::setRepeat null single undefined Mar 03 11:13:35 volumio volumio[3285]: info: CoreStateMachine::pushState Mar 03 11:13:35 volumio volumio[3285]: info: CorePlayQueue::getTrack 0 Mar 03 11:13:35 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 03 11:13:35 volumio volumio[3285]: info: CoreCommandRouter::volumioPushState Mar 03 11:13:35 volumio volumio[3285]: info: CoreStateMachine::setRandom null Mar 03 11:13:35 volumio volumio[3285]: info: CoreStateMachine::pushState Mar 03 11:13:35 volumio volumio[3285]: info: CorePlayQueue::getTrack 0 Mar 03 11:13:35 volumio volumio[3285]: info: CoreCommandRouter::volumioPushState Mar 03 11:13:35 volumio volumio[3285]: info: VolumeController:: Volume=90 Mute =false Mar 03 11:13:35 volumio volumio[3285]: info: CoreStateMachine::pushState Mar 03 11:13:35 volumio volumio[3285]: info: CorePlayQueue::getTrack 0 Mar 03 11:13:35 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 03 11:13:35 volumio volumio[3285]: info: CoreCommandRouter::volumioPushState Mar 03 11:13:35 volumio volumio[3285]: info: CoreStateMachine::updateTrackBlock Mar 03 11:13:35 volumio volumio[3285]: info: CorePlayQueue::getTrackBlock Mar 03 11:13:35 volumio volumio[3285]: info: CoreCommandRouter::volumioRetrievevolume Mar 03 11:13:35 volumio volumio[3285]: info: Asound.conf file unchanged, so no further update is needed Mar 03 11:13:35 volumio volumio[3285]: info: Output device has changed, restarting MPD Mar 03 11:13:35 volumio sudo[3393]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 03 11:13:35 volumio sudo[3393]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 11:13:35 volumio sudo[3393]: pam_unix(sudo:session): session closed for user root Mar 03 11:13:35 volumio volumio[3285]: info: Output device has changed, restarting Shairport Sync Mar 03 11:13:35 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 11:13:35 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 11:13:35 volumio sudo[3396]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 03 11:13:35 volumio sudo[3396]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 11:13:35 volumio systemd[1]: musicservicesshield.service: Succeeded. Mar 03 11:13:35 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Mar 03 11:13:35 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Mar 03 11:13:35 volumio systemd[1]: Stopping Music Player Daemon... Mar 03 11:13:35 volumio systemd[1]: mpd.service: Succeeded. Mar 03 11:13:35 volumio systemd[1]: Stopped Music Player Daemon. Mar 03 11:13:35 volumio systemd[1]: Starting Music Player Daemon... Mar 03 11:13:35 volumio volumio[3285]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 03 11:13:35 volumio volumio[3285]: info: ___________ START PLUGINS ___________ Mar 03 11:13:35 volumio volumio[3285]: info: ControllerMpd::onStart: Initializing MPD Mar 03 11:13:35 volumio volumio[3285]: info: Creating MPD Configuration file Mar 03 11:13:35 volumio sudo[3399]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 03 11:13:35 volumio sudo[3399]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 11:13:35 volumio sudo[3399]: pam_unix(sudo:session): session closed for user root Mar 03 11:13:35 volumio sudo[3404]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 03 11:13:35 volumio sudo[3404]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 11:13:35 volumio sudo[3404]: pam_unix(sudo:session): session closed for user root Mar 03 11:13:35 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 03 11:13:35 volumio volumio[3285]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 03 11:13:35 volumio volumio[3285]: info: [1740975215562] CoreMusicLibrary::Adding element Máy chủ Media Mar 03 11:13:35 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 03 11:13:35 volumio sudo[3408]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 03 11:13:35 volumio sudo[3408]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 11:13:35 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 11:13:35 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 11:13:35 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Mar 03 11:13:35 volumio systemd[1]: mpd.service: Succeeded. Mar 03 11:13:35 volumio systemd[1]: Stopped Music Player Daemon. Mar 03 11:13:35 volumio systemd[1]: Starting Music Player Daemon... Mar 03 11:13:35 volumio volumio[3285]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 03 11:13:35 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 11:13:35 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 11:13:35 volumio sudo[3410]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 03 11:13:35 volumio sudo[3410]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 11:13:35 volumio sudo[3410]: pam_unix(sudo:session): session closed for user root Mar 03 11:13:35 volumio volumio[3285]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 03 11:13:35 volumio volumio[3285]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 03 11:13:35 volumio volumio[3285]: info: [1740975215765] CoreMusicLibrary::Adding element Last_100 Mar 03 11:13:35 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 03 11:13:35 volumio volumio[3285]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 03 11:13:35 volumio volumio[3285]: info: [1740975215766] CoreMusicLibrary::Adding element Webradio Mar 03 11:13:35 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 03 11:13:35 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 03 11:13:35 volumio volumio[3285]: info: Initializing BBC Radios Mar 03 11:13:35 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 03 11:13:35 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 11:13:35 volumio volumio[3285]: info: Creating Spotify config file Mar 03 11:13:35 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 11:13:35 volumio volumio[3285]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 03 11:13:35 volumio volumio[3285]: info: [1740975215993] CoreMusicLibrary::Adding element YouTube2 Mar 03 11:13:35 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 03 11:13:35 volumio volumio[3285]: Cannot find translation for source YouTube2 Mar 03 11:13:36 volumio volumio[3285]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 03 11:13:36 volumio volumio[3285]: info: [1740975216029] CoreMusicLibrary::Adding element YouTube Music Mar 03 11:13:36 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 03 11:13:36 volumio volumio[3285]: Cannot find translation for source YouTube2 Mar 03 11:13:36 volumio volumio[3285]: Cannot find translation for source YouTube Music Mar 03 11:13:36 volumio volumio[3285]: info: AutoStart - onStart Mar 03 11:13:36 volumio volumio[3285]: info: [now-playing] ConfigUpdater: config is up to date. Mar 03 11:13:36 volumio volumio[3285]: info: Loading i18n strings for locale vi Mar 03 11:13:36 volumio volumio[3285]: info: Plugin mpdoutput has duplicated i18n key SAVE. It is ignored. Mar 03 11:13:36 volumio volumio[3285]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Mar 03 11:13:36 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Mar 03 11:13:36 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 11:13:36 volumio volumio[3285]: info: FusionDsp - mixtype--------------------- Hardware Mar 03 11:13:36 volumio volumio[3285]: info: Loading i18n strings for locale vi Mar 03 11:13:36 volumio volumio[3285]: info: Plugin mpdoutput has duplicated i18n key SAVE. It is ignored. Mar 03 11:13:36 volumio volumio[3285]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Mar 03 11:13:36 volumio volumio[3285]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 03 11:13:36 volumio volumio[3285]: info: [1740975216153] CoreMusicLibrary::Adding element Podcast Mar 03 11:13:36 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 03 11:13:36 volumio volumio[3285]: Cannot find translation for source YouTube2 Mar 03 11:13:36 volumio volumio[3285]: Cannot find translation for source YouTube Music Mar 03 11:13:36 volumio volumio[3285]: Cannot find translation for source Podcast Mar 03 11:13:36 volumio mpd[3417]: Mar 03 11:13 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 03 11:13:36 volumio systemd[1]: Started Music Player Daemon. Mar 03 11:13:36 volumio sudo[3408]: pam_unix(sudo:session): session closed for user root Mar 03 11:13:36 volumio sudo[3396]: pam_unix(sudo:session): session closed for user root Mar 03 11:13:36 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Mar 03 11:13:36 volumio volumio[3285]: info: Volumio Calling Home Mar 03 11:13:36 volumio sudo[3468]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/moveallprocesses.sh Mar 03 11:13:36 volumio sudo[3468]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 11:13:36 volumio volumio[3449]: cset: --> shielding system active with Mar 03 11:13:36 volumio volumio[3449]: cset: "system" cpuset of CPUSPEC(1) with 174 tasks running Mar 03 11:13:36 volumio volumio[3449]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Mar 03 11:13:37 volumio volumio[3285]: info: Preparing to generate the ALSA configuration file Mar 03 11:13:37 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Mar 03 11:13:37 volumio volumio[3285]: verbose: [yt-cast-receiver] Setting app pid to stored value: 77bb9aa2-1c55-48e5-afd2-c1d345e10289 Mar 03 11:13:37 volumio volumio[3285]: verbose: [yt-cast-receiver] Starting YouTubeApp... Mar 03 11:13:37 volumio volumio[3285]: verbose: [yt-cast-receiver] (YouTube) Configuring session with stored MDX context: Mar 03 11:13:37 volumio volumio[3285]: { Mar 03 11:13:37 volumio volumio[3285]: deviceId: 'eec13241-706d-4267-8977-04dc894c3f13', Mar 03 11:13:37 volumio volumio[3285]: screenId: 'ie88phs4ga71rmdnadi8qvgbkc' Mar 03 11:13:37 volumio volumio[3285]: } Mar 03 11:13:37 volumio volumio[3285]: verbose: [yt-cast-receiver] (YouTube Music) Configuring session with stored MDX context: Mar 03 11:13:37 volumio volumio[3285]: { Mar 03 11:13:37 volumio volumio[3285]: deviceId: '0e37eada-20b1-4174-bd0c-b439c44fd52f', Mar 03 11:13:37 volumio volumio[3285]: screenId: '7bttucolsbq639mafc8j19k171' Mar 03 11:13:37 volumio volumio[3285]: } Mar 03 11:13:37 volumio volumio[3285]: info: [now-playing] App is listening on port 4004. Mar 03 11:13:37 volumio volumio[3285]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Mar 03 11:13:37 volumio volumio[3285]: info: Discovery: adding 53c71867-b549-41b4-9e26-f11dffe80537 Mar 03 11:13:37 volumio volumio[3285]: info: Discovery: Found device Volumio Mar 03 11:13:37 volumio volumio[3285]: info: CoreCommandRouter::volumioGetState Mar 03 11:13:37 volumio volumio[3285]: info: CorePlayQueue::getTrack 0 Mar 03 11:13:37 volumio volumio[3285]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Mar 03 11:13:37 volumio volumio[3285]: info: Reading ALSA contributions from plugins. Mar 03 11:13:37 volumio volumio[3285]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Mar 03 11:13:37 volumio volumio[3285]: info: minidlna: Found miniDLNA version 1.2.1 Mar 03 11:13:37 volumio volumio[3285]: info: minidlna: Starting minidlna.service Mar 03 11:13:37 volumio sudo[3518]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Mar 03 11:13:37 volumio sudo[3518]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 11:13:37 volumio sudo[3518]: pam_unix(sudo:session): session closed for user root Mar 03 11:13:37 volumio sudo[3468]: pam_unix(sudo:session): session closed for user root Mar 03 11:13:37 volumio volumio[3285]: info: MPD Permissions set Mar 03 11:13:37 volumio volumio[3285]: info: MPD Permissions set Mar 03 11:13:37 volumio volumio[3285]: info: MPD running with PID3417 Mar 03 11:13:37 volumio volumio[3285]: ,establishing connection Mar 03 11:13:37 volumio volumio[3285]: info: minidlna: systemctl start minidlna.service succeeded. Mar 03 11:13:37 volumio volumio[3285]: info: Volumio called home Mar 03 11:13:37 volumio volumio[3285]: info: Spotify config file written Mar 03 11:13:37 volumio sudo[3528]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/addservice.sh Mar 03 11:13:37 volumio sudo[3528]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 11:13:37 volumio sudo[3533]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Mar 03 11:13:37 volumio systemd[1]: Reloading. Mar 03 11:13:37 volumio sudo[3533]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 11:13:37 volumio volumio[3285]: error: MPD error: The expression evaluated to a falsy value: Mar 03 11:13:37 volumio volumio[3285]: assert.ok(self.idling) Mar 03 11:13:37 volumio volumio[3285]: error: The expression evaluated to a falsy value: Mar 03 11:13:37 volumio volumio[3285]: assert.ok(self.idling) Mar 03 11:13:37 volumio volumio[3285]: error: MPD error: The expression evaluated to a falsy value: Mar 03 11:13:37 volumio volumio[3285]: assert.ok(self.idling) Mar 03 11:13:37 volumio volumio[3285]: error: The expression evaluated to a falsy value: Mar 03 11:13:37 volumio volumio[3285]: assert.ok(self.idling) Mar 03 11:13:37 volumio volumio[3285]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Mar 03 11:13:37 volumio volumio[3285]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Mar 03 11:13:37 volumio volumio[3285]: info: VolumeController:: Volume=90 Mute =false Mar 03 11:13:37 volumio volumio[3285]: info: CoreCommandRouter::volumioGetState Mar 03 11:13:37 volumio volumio[3285]: info: CorePlayQueue::getTrack 0 Mar 03 11:13:37 volumio volumio[3285]: info: CoreStateMachine::pushState Mar 03 11:13:37 volumio volumio[3285]: info: CorePlayQueue::getTrack 0 Mar 03 11:13:37 volumio volumio[3285]: info: CoreCommandRouter::volumioPushState Mar 03 11:13:37 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 11:13:37 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 11:13:37 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 11:13:37 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 11:13:37 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 11:13:37 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 11:13:37 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 11:13:38 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 11:13:38 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 03 11:13:38 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 11:13:38 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 11:13:38 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 11:13:38 volumio 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. Mar 03 11:13:38 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 11:13:38 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 03 11:13:38 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 11:13:38 volumio volumio[3285]: info: No need to fix Spotify hosts Mar 03 11:13:38 volumio volumio[3285]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Mar 03 11:13:38 volumio volumio[3285]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Mar 03 11:13:38 volumio volumio[3285]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Mar 03 11:13:38 volumio sudo[3528]: pam_unix(sudo:session): session closed for user root Mar 03 11:13:38 volumio systemd[1]: Stopping go-librespot Daemon... Mar 03 11:13:38 volumio volumio[3285]: info: camilladsp spawned new process with pid 3567, instance 1, run: true Mar 03 11:13:38 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Mar 03 11:13:38 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Mar 03 11:13:38 volumio systemd[1]: Stopped go-librespot Daemon. Mar 03 11:13:38 volumio volumio[3285]: info: camilladsp service started and running in background, instance 1 Mar 03 11:13:38 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 11:13:38 volumio systemd[1]: Started go-librespot Daemon. Mar 03 11:13:38 volumio sudo[3533]: pam_unix(sudo:session): session closed for user root Mar 03 11:13:38 volumio go-librespot[3568]: go-librespot daemon starting... Mar 03 11:13:38 volumio go-librespot[3568]: time="2025-03-03T11:13:38+07:00" level=info msg="running go-librespot 0.2.0" Mar 03 11:13:38 volumio go-librespot[3568]: time="2025-03-03T11:13:38+07:00" level=debug msg="app state loaded" Mar 03 11:13:38 volumio go-librespot[3568]: time="2025-03-03T11:13:38+07:00" level=debug msg="stored credentials not found" Mar 03 11:13:38 volumio go-librespot[3568]: time="2025-03-03T11:13:38+07:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 03 11:13:38 volumio volumio[3285]: cannot open device 'volumioHw': Device or resource busy Mar 03 11:13:38 volumio volumio[3285]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Mar 03 11:13:38 volumio volumio[3285]: cannot open device 'volumioHw': Device or resource busy Mar 03 11:13:38 volumio volumio[3285]: info: FusionDsp loaded Mar 03 11:13:38 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 03 11:13:38 volumio sudo[3582]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Mar 03 11:13:38 volumio sudo[3582]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 11:13:38 volumio volumio[3285]: info: FusionDsp - Reporting Fusion DSP Enabled Mar 03 11:13:38 volumio volumio[3285]: info: Adding Signal Path Element [object Object] Mar 03 11:13:38 volumio volumio[3285]: info: Adding fusiondspeq DSP Signal Path Element Mar 03 11:13:38 volumio volumio[3285]: info: FusionDsp - ---- installed callbackRead Mar 03 11:13:38 volumio volumio[3285]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 03 11:13:38 volumio systemd[1]: Started FusionDsp Daemon. Mar 03 11:13:38 volumio go-librespot[3568]: time="2025-03-03T11:13:38+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]" Mar 03 11:13:38 volumio go-librespot[3568]: time="2025-03-03T11:13:38+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]" Mar 03 11:13:38 volumio go-librespot[3568]: time="2025-03-03T11:13:38+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]" Mar 03 11:13:38 volumio go-librespot[3568]: time="2025-03-03T11:13:38+07:00" level=info msg="zeroconf server listening on port 40425" Mar 03 11:13:38 volumio sudo[3582]: pam_unix(sudo:session): session closed for user root Mar 03 11:13:38 volumio sudo[3587]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 03 11:13:38 volumio sudo[3587]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 11:13:38 volumio sudo[3587]: pam_unix(sudo:session): session closed for user root Mar 03 11:13:38 volumio sudo[3590]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 03 11:13:38 volumio volumio[3285]: error: updateQueue error: null Mar 03 11:13:38 volumio sudo[3590]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 11:13:38 volumio sudo[3590]: pam_unix(sudo:session): session closed for user root Mar 03 11:13:38 volumio volumio[3285]: info: succeeded Mar 03 11:13:38 volumio volumio[3285]: info: Starting Shairport Sync Mar 03 11:13:38 volumio volumio[3285]: info: Starting Shairport Sync Mar 03 11:13:38 volumio sudo[3596]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 03 11:13:38 volumio sudo[3596]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 11:13:39 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 03 11:13:39 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 03 11:13:39 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 03 11:13:39 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 03 11:13:39 volumio volumio[3285]: info: Starting Shairport Sync Mar 03 11:13:39 volumio sudo[3596]: pam_unix(sudo:session): session closed for user root Mar 03 11:13:39 volumio sudo[3600]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 03 11:13:39 volumio sudo[3600]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 11:13:39 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 03 11:13:39 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 03 11:13:39 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 03 11:13:39 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 03 11:13:39 volumio sudo[3600]: pam_unix(sudo:session): session closed for user root Mar 03 11:13:39 volumio sudo[3606]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 03 11:13:39 volumio sudo[3606]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 11:13:39 volumio volumio[3285]: info: CoreCommandRouter::volumioGetState Mar 03 11:13:39 volumio volumio[3285]: info: CorePlayQueue::getTrack 0 Mar 03 11:13:39 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 03 11:13:39 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 03 11:13:39 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 03 11:13:39 volumio volumio[3285]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Mar 03 11:13:39 volumio volumio[3285]: info: FusionDsp - {"Reload":{"result":"Ok"}} Mar 03 11:13:39 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 03 11:13:39 volumio sudo[3606]: pam_unix(sudo:session): session closed for user root Mar 03 11:13:39 volumio volumio[3285]: info: Shairport-Sync Started Mar 03 11:13:39 volumio volumio[3285]: Error adding Membership: Error: addMembership EINVAL Mar 03 11:13:39 volumio volumio[3285]: info: Shairport-Sync Started Mar 03 11:13:39 volumio volumio[3285]: info: Asound.conf file unchanged, so no further update is needed Mar 03 11:13:39 volumio volumio[3285]: info: Output device has changed, restarting MPD Mar 03 11:13:39 volumio sudo[3618]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 03 11:13:39 volumio sudo[3618]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 11:13:39 volumio sudo[3618]: pam_unix(sudo:session): session closed for user root Mar 03 11:13:39 volumio volumio[3285]: info: Output device has changed, restarting Shairport Sync Mar 03 11:13:39 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 11:13:39 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 11:13:39 volumio sudo[3621]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 03 11:13:39 volumio sudo[3621]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 11:13:39 volumio systemd[1]: musicservicesshield.service: Succeeded. Mar 03 11:13:39 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Mar 03 11:13:39 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Mar 03 11:13:39 volumio systemd[1]: Stopping Music Player Daemon... Mar 03 11:13:39 volumio systemd[1]: mpd.service: Succeeded. Mar 03 11:13:39 volumio systemd[1]: Stopped Music Player Daemon. Mar 03 11:13:39 volumio systemd[1]: Starting Music Player Daemon... Mar 03 11:13:39 volumio volumio[3285]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 03 11:13:39 volumio volumio[3285]: info: Shairport-Sync Started Mar 03 11:13:39 volumio sudo[3624]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 03 11:13:39 volumio sudo[3624]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 11:13:39 volumio sudo[3624]: pam_unix(sudo:session): session closed for user root Mar 03 11:13:39 volumio volumio[3285]: info: MPD Permissions set Mar 03 11:13:39 volumio sudo[3630]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Mar 03 11:13:39 volumio sudo[3630]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 11:13:39 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 11:13:39 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 11:13:39 volumio sudo[3630]: pam_unix(sudo:session): session closed for user root Mar 03 11:13:39 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 11:13:39 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 11:13:39 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 03 11:13:40 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 11:13:40 volumio volumio[3285]: info: Upmpdcli Daemon Started Mar 03 11:13:40 volumio volumio[3285]: info: Starting Shairport Sync Mar 03 11:13:40 volumio volumio[3285]: info: CoreCommandRouter::volumioGetState Mar 03 11:13:40 volumio volumio[3285]: info: CorePlayQueue::getTrack 0 Mar 03 11:13:40 volumio volumio[3285]: info: CoreCommandRouter::volumioPause Mar 03 11:13:40 volumio volumio[3285]: info: CoreStateMachine::pause Mar 03 11:13:40 volumio volumio[3285]: info: mpdhttpout --- Volumio set on pause Mar 03 11:13:40 volumio volumio[3285]: info: mpdhttpout ---Configuration successfully added to mpd.conf. Mar 03 11:13:40 volumio sudo[3639]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 03 11:13:40 volumio volumio[3285]: verbose: [yt-cast-receiver] (YouTube) Obtained lounge token: Mar 03 11:13:40 volumio volumio[3285]: { Mar 03 11:13:40 volumio volumio[3285]: screenId: 'ie88phs4ga71rmdnadi8qvgbkc', Mar 03 11:13:40 volumio volumio[3285]: refreshIntervalInMillis: 1123200000, Mar 03 11:13:40 volumio volumio[3285]: remoteRefreshIntervalMs: 79200000, Mar 03 11:13:40 volumio volumio[3285]: refreshIntervalMs: 1123200000, Mar 03 11:13:40 volumio volumio[3285]: loungeTokenLifespanMs: 1209600000, Mar 03 11:13:40 volumio volumio[3285]: loungeToken: 'AGdO5p9ienOw3GywN-8afls6Jyt37-y-NGG0j7yYS2x2KVboD4be_L8FcnUcAC3VFIDfbajj_RgJhMw-fU2Y-IryRhKEHw8jfyRdOOtqsc7PPSj03yuzrT8', Mar 03 11:13:40 volumio volumio[3285]: remoteRefreshIntervalInMillis: 79200000, Mar 03 11:13:40 volumio volumio[3285]: expiration: 1742098417738 Mar 03 11:13:40 volumio volumio[3285]: } Mar 03 11:13:40 volumio sudo[3639]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 11:13:40 volumio volumio[3285]: verbose: [yt-cast-receiver] (YouTube Music) Obtained lounge token: Mar 03 11:13:40 volumio volumio[3285]: { Mar 03 11:13:40 volumio volumio[3285]: screenId: '7bttucolsbq639mafc8j19k171', Mar 03 11:13:40 volumio volumio[3285]: refreshIntervalInMillis: 1123200000, Mar 03 11:13:40 volumio volumio[3285]: remoteRefreshIntervalMs: 79200000, Mar 03 11:13:40 volumio volumio[3285]: refreshIntervalMs: 1123200000, Mar 03 11:13:40 volumio volumio[3285]: loungeTokenLifespanMs: 1209600000, Mar 03 11:13:40 volumio volumio[3285]: loungeToken: 'AGdO5p82AtTfUDj3sk7H2O2SAi2fONSe6SQxDDSvAsGy2acMuvqGuF_cQwxCWcFgZz-QnbV8-kzwcUR6ExQ-ZVu9_n_Z90fAmHLNKq4HosjXyiOymDXvsAM', Mar 03 11:13:40 volumio volumio[3285]: remoteRefreshIntervalInMillis: 79200000, Mar 03 11:13:40 volumio volumio[3285]: expiration: 1742098417747 Mar 03 11:13:40 volumio volumio[3285]: } Mar 03 11:13:40 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 03 11:13:40 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 03 11:13:40 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 03 11:13:40 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 03 11:13:40 volumio sudo[3639]: pam_unix(sudo:session): session closed for user root Mar 03 11:13:40 volumio volumio[3285]: info: Shairport-Sync Started Mar 03 11:13:40 volumio mpd[3628]: Mar 03 11:13 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 03 11:13:40 volumio systemd[1]: Started Music Player Daemon. Mar 03 11:13:40 volumio sudo[3621]: pam_unix(sudo:session): session closed for user root Mar 03 11:13:40 volumio volumio[3285]: error: updateQueue error: null Mar 03 11:13:40 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Mar 03 11:13:40 volumio volumio[3285]: verbose: [yt-cast-receiver] (YouTube Music) Received messages for establishing session: Mar 03 11:13:40 volumio volumio[3285]: [ Mar 03 11:13:40 volumio volumio[3285]: Message { AID: 0, name: 'c', payload: [ '8CA9556FA55B4640', '', 8 ] }, Mar 03 11:13:40 volumio volumio[3285]: Message { Mar 03 11:13:40 volumio volumio[3285]: AID: 1, Mar 03 11:13:40 volumio volumio[3285]: name: 'S', Mar 03 11:13:40 volumio volumio[3285]: payload: '0i9DWzVkaE7Zj2Ml8Fc9eef-I23RQj5l' Mar 03 11:13:40 volumio volumio[3285]: }, Mar 03 11:13:40 volumio volumio[3285]: Message { Mar 03 11:13:40 volumio volumio[3285]: AID: 2, Mar 03 11:13:40 volumio volumio[3285]: name: 'loungeStatus', Mar 03 11:13:40 volumio volumio[3285]: payload: { Mar 03 11:13:40 volumio volumio[3285]: queueId: 'RQ_An-UNeExZ2owDs-tErGKv1RM4w', Mar 03 11:13:40 volumio volumio[3285]: devices: '[{"app":"ytcr","capabilities":"dsp,mic,dpa,ntb","clientName":"unknown","experiments":"","name":"YouTube on Volumio","theme":"m","id":"0e37eada-20b1-4174-bd0c-b439c44fd52f","type":"LOUNGE_SCREEN","hasCc":"true","deviceInfo":"{\\"year\\":0,\\"os\\":\\"Windows\\",\\"osVersion\\":\\"10.0\\",\\"chipset\\":\\"\\",\\"clientName\\":\\"TVHTML5\\",\\"dialAdditionalDataSupportLevel\\":\\"unsupported\\",\\"mdxDialServerType\\":\\"MDX_DIAL_SERVER_TYPE_UNKNOWN\\",\\"brand\\":\\"Generic\\",\\"model\\":\\"SmartTV\\"}","receiverIdentityMatchStatus":"IS_RECEIVER"}]', Mar 03 11:13:40 volumio volumio[3285]: connectionEventDetails: '{"deviceId":"0e37eada-20b1-4174-bd0c-b439c44fd52f"}' Mar 03 11:13:40 volumio volumio[3285]: } Mar 03 11:13:40 volumio volumio[3285]: }, Mar 03 11:13:40 volumio volumio[3285]: Message { AID: 3, name: 'getNowPlaying', payload: {} } Mar 03 11:13:40 volumio volumio[3285]: ] Mar 03 11:13:40 volumio volumio[3285]: verbose: ----------------------------------- Mar 03 11:13:40 volumio volumio[3285]: verbose: [yt-cast-receiver] (AID: 2) (YouTube Music) Incoming message: 'loungeStatus' Mar 03 11:13:40 volumio volumio[3285]: verbose: [yt-cast-receiver] Connecting to RPC URL: https://www.youtube.com/api/lounge/bc/bind?AID=3&CI=0&RID=rpc&SID=8CA9556FA55B4640&TYPE=xmlhttp&VER=8&app=ytcr&capabilities=dsp%2Cmic%2Cdpa%2Cntb&cst=m&device=LOUNGE_SCREEN&gsessionid=0i9DWzVkaE7Zj2Ml8Fc9eef-I23RQj5l&id=0e37eada-20b1-4174-bd0c-b439c44fd52f&loungeIdToken=AGdO5p82AtTfUDj3sk7H2O2SAi2fONSe6SQxDDSvAsGy2acMuvqGuF_cQwxCWcFgZz-QnbV8-kzwcUR6ExQ-ZVu9_n_Z90fAmHLNKq4HosjXyiOymDXvsAM&mdxVersion=2&name=YouTube%20on%20Volumio&obfuscatedGaiaId=&t=1&theme=m&v=2&zx=e91afb36e3ca Mar 03 11:13:40 volumio volumio[3285]: verbose: [yt-cast-receiver] (YouTube) Received messages for establishing session: Mar 03 11:13:40 volumio volumio[3285]: [ Mar 03 11:13:40 volumio volumio[3285]: Message { AID: 0, name: 'c', payload: [ 'B397432EC450CDCA', '', 8 ] }, Mar 03 11:13:40 volumio volumio[3285]: Message { Mar 03 11:13:40 volumio volumio[3285]: AID: 1, Mar 03 11:13:40 volumio volumio[3285]: name: 'S', Mar 03 11:13:40 volumio volumio[3285]: payload: 'xzfodECEHSZEkYENbODMI9HY7X-S824N' Mar 03 11:13:40 volumio volumio[3285]: }, Mar 03 11:13:40 volumio volumio[3285]: Message { Mar 03 11:13:40 volumio volumio[3285]: AID: 2, Mar 03 11:13:40 volumio volumio[3285]: name: 'loungeStatus', Mar 03 11:13:40 volumio volumio[3285]: payload: { Mar 03 11:13:40 volumio volumio[3285]: queueId: 'RQkBbweOjt3U1hCi0u7SPUxjSVNJg', Mar 03 11:13:40 volumio volumio[3285]: devices: '[{"app":"ytcr","capabilities":"dsp,mic,dpa,ntb,que,mus","clientName":"unknown","experiments":"","name":"YouTube on Volumio","theme":"cl","id":"eec13241-706d-4267-8977-04dc894c3f13","type":"LOUNGE_SCREEN","hasCc":"true","deviceInfo":"{\\"year\\":0,\\"os\\":\\"Windows\\",\\"osVersion\\":\\"10.0\\",\\"chipset\\":\\"\\",\\"clientName\\":\\"TVHTML5\\",\\"dialAdditionalDataSupportLevel\\":\\"unsupported\\",\\"mdxDialServerType\\":\\"MDX_DIAL_SERVER_TYPE_UNKNOWN\\",\\"brand\\":\\"Generic\\",\\"model\\":\\"SmartTV\\"}","receiverIdentityMatchStatus":"IS_RECEIVER"}]', Mar 03 11:13:40 volumio volumio[3285]: connectionEventDetails: '{"deviceId":"eec13241-706d-4267-8977-04dc894c3f13"}' Mar 03 11:13:40 volumio volumio[3285]: } Mar 03 11:13:40 volumio volumio[3285]: }, Mar 03 11:13:40 volumio volumio[3285]: Message { AID: 3, name: 'getNowPlaying', payload: {} } Mar 03 11:13:40 volumio volumio[3285]: ] Mar 03 11:13:40 volumio volumio[3285]: verbose: ----------------------------------- Mar 03 11:13:40 volumio volumio[3285]: verbose: [yt-cast-receiver] (AID: 2) (YouTube) Incoming message: 'loungeStatus' Mar 03 11:13:40 volumio volumio[3285]: verbose: [yt-cast-receiver] Connecting to RPC URL: https://www.youtube.com/api/lounge/bc/bind?AID=3&CI=0&RID=rpc&SID=B397432EC450CDCA&TYPE=xmlhttp&VER=8&app=ytcr&capabilities=dsp%2Cmic%2Cdpa%2Cntb&cst=m&device=LOUNGE_SCREEN&gsessionid=xzfodECEHSZEkYENbODMI9HY7X-S824N&id=eec13241-706d-4267-8977-04dc894c3f13&loungeIdToken=AGdO5p9ienOw3GywN-8afls6Jyt37-y-NGG0j7yYS2x2KVboD4be_L8FcnUcAC3VFIDfbajj_RgJhMw-fU2Y-IryRhKEHw8jfyRdOOtqsc7PPSj03yuzrT8&mdxVersion=2&name=YouTube%20on%20Volumio&obfuscatedGaiaId=&t=1&theme=cl&v=2&zx=20bddf219afd Mar 03 11:13:40 volumio volumio[3646]: cset: --> shielding system active with Mar 03 11:13:40 volumio volumio[3646]: cset: "system" cpuset of CPUSPEC(1) with 177 tasks running Mar 03 11:13:40 volumio volumio[3646]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Mar 03 11:13:40 volumio ntpd[1347]: Soliciting pool server 103.184.124.254 Mar 03 11:13:40 volumio volumio[3285]: verbose: [yt-cast-receiver] RPC connection established. Mar 03 11:13:40 volumio volumio[3285]: verbose: [yt-cast-receiver] (YouTube Music) Session established. Mar 03 11:13:40 volumio volumio[3285]: verbose: [yt-cast-receiver] (YouTube Music) Saving MDX context to data store: Mar 03 11:13:40 volumio volumio[3285]: { Mar 03 11:13:40 volumio volumio[3285]: deviceId: '0e37eada-20b1-4174-bd0c-b439c44fd52f', Mar 03 11:13:40 volumio volumio[3285]: screenId: '7bttucolsbq639mafc8j19k171' Mar 03 11:13:40 volumio volumio[3285]: } Mar 03 11:13:40 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Mar 03 11:13:40 volumio volumio[3285]: verbose: ----------------------------------- Mar 03 11:13:40 volumio volumio[3285]: verbose: [yt-cast-receiver] (AID: 4) (YouTube Music) Incoming message: 'noop' Mar 03 11:13:40 volumio volumio[3285]: verbose: [yt-cast-receiver] (AID: 4) (YouTube Music) Not handled: 'noop' Mar 03 11:13:40 volumio volumio[3285]: verbose: [yt-cast-receiver] RPC connection established. Mar 03 11:13:40 volumio volumio[3285]: verbose: [yt-cast-receiver] (YouTube) Session established. Mar 03 11:13:40 volumio volumio[3285]: verbose: [yt-cast-receiver] (YouTube) Saving MDX context to data store: Mar 03 11:13:40 volumio volumio[3285]: { Mar 03 11:13:40 volumio volumio[3285]: deviceId: 'eec13241-706d-4267-8977-04dc894c3f13', Mar 03 11:13:40 volumio volumio[3285]: screenId: 'ie88phs4ga71rmdnadi8qvgbkc' Mar 03 11:13:40 volumio volumio[3285]: } Mar 03 11:13:40 volumio volumio[3285]: verbose: [yt-cast-receiver] Starting DIAL server... Mar 03 11:13:40 volumio volumio[3285]: info: [yt-cast-receiver] DIAL server listening on port 8098 Mar 03 11:13:40 volumio volumio[3285]: verbose: [ytcr] VolumeControl initialized with current volume: Mar 03 11:13:40 volumio volumio[3285]: { level: 90, muted: false } Mar 03 11:13:40 volumio volumio[3285]: verbose: ----------------------------------- Mar 03 11:13:40 volumio volumio[3285]: verbose: [yt-cast-receiver] (AID: 4) (YouTube) Incoming message: 'noop' Mar 03 11:13:40 volumio volumio[3285]: verbose: [yt-cast-receiver] (AID: 4) (YouTube) Not handled: 'noop' Mar 03 11:13:40 volumio volumio[3285]: verbose: [ytcr] MPD connected Mar 03 11:13:40 volumio volumio[3285]: verbose: [ytcr] MPDSubsystemEventEmitter enabled. Mar 03 11:13:40 volumio volumio[3285]: verbose: [ytcr] Receiver started with options: Mar 03 11:13:40 volumio volumio[3285]: { Mar 03 11:13:40 volumio volumio[3285]: dial: { port: 8098, bindToInterfaces: [ 'eth0' ] }, Mar 03 11:13:40 volumio volumio[3285]: app: { Mar 03 11:13:40 volumio volumio[3285]: enableAutoplayOnConnect: true, Mar 03 11:13:40 volumio volumio[3285]: resetPlayerOnDisconnectPolicy: 'allDisconnected' Mar 03 11:13:40 volumio volumio[3285]: }, Mar 03 11:13:40 volumio volumio[3285]: dataStore: ReceiverDataStore {}, Mar 03 11:13:40 volumio volumio[3285]: logger: Logger { level: 'debug', color: true }, Mar 03 11:13:40 volumio volumio[3285]: logLevel: 'debug', Mar 03 11:13:40 volumio volumio[3285]: device: { name: 'Volumio' } Mar 03 11:13:40 volumio volumio[3285]: } Mar 03 11:13:41 volumio volumio-remote-updater[622]: [2025-03-03 11:13:41] [connect] Successful connection Mar 03 11:13:41 volumio volumio-remote-updater[622]: [2025-03-03 11:13:41] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1740975221 101 Mar 03 11:13:41 volumio volumio[3285]: 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 Mar 03 11:13:41 volumio volumio[3285]: info: go-librespot daemon successfully initialized Mar 03 11:13:42 volumio volumio[3285]: info: ONKYO-CONTROL: *********** ONKYO PLUGIN STARTED ******** Mar 03 11:13:42 volumio volumio[3285]: info: Completed starting Core Plugins Mar 03 11:13:42 volumio volumio[3285]: info: ------------------------------------------- Mar 03 11:13:42 volumio volumio[3285]: info: ----- MyVolumio plugins startup ---- Mar 03 11:13:42 volumio volumio[3285]: info: ------------------------------------------- Mar 03 11:13:42 volumio volumio[3285]: info: [MyVolumio PluginManager] Fetching plans data.... Mar 03 11:13:42 volumio volumio[3285]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Mar 03 11:13:42 volumio volumio[3285]: info: CoreCommandRouter::volumioGetState Mar 03 11:13:42 volumio volumio[3285]: info: CorePlayQueue::getTrack 0 Mar 03 11:13:42 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 03 11:13:42 volumio volumio[3285]: info: CoreCommandRouter::volumioGetVisibleSources Mar 03 11:13:42 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 03 11:13:42 volumio volumio[3285]: info: Listing playlists Mar 03 11:13:42 volumio volumio[3285]: info: CoreCommandRouter::volumioGetQueue Mar 03 11:13:42 volumio volumio[3285]: info: CoreStateMachine::getQueue Mar 03 11:13:42 volumio volumio[3285]: info: CorePlayQueue::getQueue Mar 03 11:13:42 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Mar 03 11:13:42 volumio sudo[3668]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 03 11:13:42 volumio sudo[3668]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 11:13:42 volumio systemd[1]: musicservicesshield.service: Succeeded. Mar 03 11:13:42 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Mar 03 11:13:42 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Mar 03 11:13:42 volumio systemd[1]: Stopping Music Player Daemon... Mar 03 11:13:42 volumio systemd[1]: mpd.service: Succeeded. Mar 03 11:13:42 volumio systemd[1]: Stopped Music Player Daemon. Mar 03 11:13:42 volumio volumio[3285]: verbose: [ytcr] MPDSubsystemEventEmitter destroyed. Mar 03 11:13:42 volumio volumio[3285]: verbose: [ytcr] Cancelling prefetch (abortIfPrefetching: true, clearIfPrefetched: true) Mar 03 11:13:42 volumio volumio[3285]: info: CoreCommandRouter::volumioGetState Mar 03 11:13:42 volumio volumio[3285]: info: CorePlayQueue::getTrack 0 Mar 03 11:13:42 volumio volumio[3285]: verbose: [ytcr] MPDPlayer going to sleep... Mar 03 11:13:42 volumio volumio[3285]: verbose: [ytcr] MPD disconnected. Reconnecting... Mar 03 11:13:42 volumio systemd[1]: Starting Music Player Daemon... Mar 03 11:13:42 volumio sudo[3670]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 03 11:13:42 volumio sudo[3670]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 11:13:42 volumio sudo[3670]: pam_unix(sudo:session): session closed for user root Mar 03 11:13:42 volumio volumio[3285]: error: MPD error: Error: This socket has been ended by the other party Mar 03 11:13:42 volumio volumio[3285]: error: This socket has been ended by the other party {"code":"EPIPE"} Mar 03 11:13:42 volumio volumio[3285]: error: MPD error: Error: This socket has been ended by the other party Mar 03 11:13:42 volumio volumio[3285]: error: This socket has been ended by the other party {"code":"EPIPE"} Mar 03 11:13:42 volumio volumio[3285]: error: MPD error: Error: This socket has been ended by the other party Mar 03 11:13:42 volumio volumio[3285]: error: This socket has been ended by the other party {"code":"EPIPE"} Mar 03 11:13:42 volumio volumio[3285]: error: MPD error: Error: This socket has been ended by the other party Mar 03 11:13:42 volumio volumio[3285]: error: This socket has been ended by the other party {"code":"EPIPE"} Mar 03 11:13:42 volumio volumio[3285]: error: MPD error: Error: This socket has been ended by the other party Mar 03 11:13:42 volumio volumio[3285]: error: This socket has been ended by the other party {"code":"EPIPE"} Mar 03 11:13:42 volumio volumio[3285]: error: MPD error: Error: This socket has been ended by the other party Mar 03 11:13:42 volumio volumio[3285]: error: This socket has been ended by the other party {"code":"EPIPE"} Mar 03 11:13:42 volumio volumio[3285]: error: MPD error: Error: This socket has been ended by the other party Mar 03 11:13:42 volumio volumio[3285]: error: This socket has been ended by the other party {"code":"EPIPE"} Mar 03 11:13:42 volumio volumio[3285]: error: MPD error: Error: This socket has been ended by the other party Mar 03 11:13:42 volumio volumio[3285]: error: This socket has been ended by the other party {"code":"EPIPE"} Mar 03 11:13:42 volumio volumio[3285]: error: MPD error: Error: This socket has been ended by the other party Mar 03 11:13:42 volumio volumio[3285]: error: This socket has been ended by the other party {"code":"EPIPE"} Mar 03 11:13:43 volumio mpd[3672]: Mar 03 11:13 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 03 11:13:43 volumio systemd[1]: Started Music Player Daemon. Mar 03 11:13:43 volumio sudo[3668]: pam_unix(sudo:session): session closed for user root Mar 03 11:13:43 volumio volumio[3285]: error: updateQueue error: null Mar 03 11:13:43 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Mar 03 11:13:43 volumio volumio[3285]: verbose: [ytcr] MPD connected Mar 03 11:13:43 volumio volumio[3285]: verbose: [ytcr] MPDSubsystemEventEmitter enabled. Mar 03 11:13:43 volumio volumio[3675]: cset: --> shielding system active with Mar 03 11:13:43 volumio volumio[3675]: cset: "system" cpuset of CPUSPEC(1) with 177 tasks running Mar 03 11:13:43 volumio volumio[3675]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Mar 03 11:13:43 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Mar 03 11:13:44 volumio volumio[3285]: info: Initializing connection to go-librespot Websocket Mar 03 11:13:44 volumio go-librespot[3568]: time="2025-03-03T11:13:44+07:00" level=debug msg="new websocket client" Mar 03 11:13:44 volumio volumio[3285]: info: Connection to go-librespot Websocket established Mar 03 11:13:45 volumio volumio[3285]: info: FusionDsp - Stopping FusionDsp service Mar 03 11:13:45 volumio volumio[3285]: info: camilladsp stopping service pid 3567... Mar 03 11:13:45 volumio volumio[3285]: info: camilladsp service terminated, instance 1 Mar 03 11:13:45 volumio volumio[3285]: info: Disabling plugin fusiondsp Mar 03 11:13:45 volumio volumio[3285]: info: Preparing to generate the ALSA configuration file Mar 03 11:13:45 volumio sudo[3702]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop fusiondsp.service Mar 03 11:13:45 volumio sudo[3702]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 11:13:45 volumio systemd[1]: Stopping FusionDsp Daemon... Mar 03 11:13:45 volumio volumio[3285]: info: Asound.conf file written Mar 03 11:13:45 volumio volumio[3585]: Backend configuration: Mar 03 11:13:45 volumio volumio[3585]: active_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml Mar 03 11:13:45 volumio volumio[3585]: active_config_txt: null Mar 03 11:13:45 volumio volumio[3585]: camilla_host: 127.0.0.1 Mar 03 11:13:45 volumio volumio[3585]: camilla_port: 9876 Mar 03 11:13:45 volumio volumio[3585]: coeff_dir: /data/INTERNAL/FusionDsp/filters Mar 03 11:13:45 volumio volumio[3585]: config_dir: /data/configuration/audio_interface/fusiondsp Mar 03 11:13:45 volumio volumio[3585]: default_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml Mar 03 11:13:45 volumio volumio[3585]: log_file: /tmp/camilladsp.log Mar 03 11:13:45 volumio volumio[3585]: on_get_active_config: null Mar 03 11:13:45 volumio volumio[3585]: on_set_active_config: null Mar 03 11:13:45 volumio volumio[3585]: port: 5011 Mar 03 11:13:45 volumio volumio[3585]: supported_capture_types: null Mar 03 11:13:45 volumio volumio[3585]: supported_playback_types: null Mar 03 11:13:45 volumio volumio[3585]: update_config_symlink: false Mar 03 11:13:45 volumio volumio[3585]: update_config_txt: false Mar 03 11:13:45 volumio volumio[3585]: ======== Running on http://0.0.0.0:5011 ======== Mar 03 11:13:45 volumio volumio[3585]: (Press CTRL+C to quit) Mar 03 11:13:45 volumio sudo[3705]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 03 11:13:45 volumio sudo[3705]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 11:13:45 volumio sudo[3705]: pam_unix(sudo:session): session closed for user root Mar 03 11:13:45 volumio kernel: perf: interrupt took too long (3936 > 3932), lowering kernel.perf_event_max_sample_rate to 50750 Mar 03 11:13:45 volumio volumio[3285]: info: Output device has changed, restarting MPD Mar 03 11:13:45 volumio systemd[1]: fusiondsp.service: Succeeded. Mar 03 11:13:45 volumio systemd[1]: Stopped FusionDsp Daemon. Mar 03 11:13:45 volumio sudo[3702]: pam_unix(sudo:session): session closed for user root Mar 03 11:13:45 volumio sudo[3710]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 03 11:13:45 volumio sudo[3710]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 11:13:45 volumio sudo[3710]: pam_unix(sudo:session): session closed for user root Mar 03 11:13:45 volumio volumio[3285]: info: Output device has changed, restarting Shairport Sync Mar 03 11:13:45 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 11:13:45 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 11:13:45 volumio sudo[3713]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 03 11:13:45 volumio sudo[3713]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 11:13:45 volumio systemd[1]: musicservicesshield.service: Succeeded. Mar 03 11:13:45 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Mar 03 11:13:45 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Mar 03 11:13:45 volumio systemd[1]: Stopping Music Player Daemon... Mar 03 11:13:45 volumio systemd[1]: mpd.service: Succeeded. Mar 03 11:13:45 volumio systemd[1]: Stopped Music Player Daemon. Mar 03 11:13:45 volumio systemd[1]: Starting Music Player Daemon... Mar 03 11:13:46 volumio volumio[3285]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 03 11:13:46 volumio volumio[3285]: info: Done. Mar 03 11:13:46 volumio sudo[3716]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 03 11:13:46 volumio sudo[3716]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 11:13:46 volumio sudo[3716]: pam_unix(sudo:session): session closed for user root Mar 03 11:13:46 volumio volumio[3285]: info: MPD Permissions set Mar 03 11:13:46 volumio volumio[3285]: verbose: [ytcr] MPDSubsystemEventEmitter destroyed. Mar 03 11:13:46 volumio volumio[3285]: verbose: [ytcr] Cancelling prefetch (abortIfPrefetching: true, clearIfPrefetched: true) Mar 03 11:13:46 volumio volumio[3285]: info: CoreCommandRouter::volumioGetState Mar 03 11:13:46 volumio volumio[3285]: info: CorePlayQueue::getTrack 0 Mar 03 11:13:46 volumio volumio[3285]: verbose: [ytcr] MPD disconnected. Reconnecting... Mar 03 11:13:46 volumio volumio[3285]: info: FusionDsp - Reporting Fusion DSP Disabled Mar 03 11:13:46 volumio volumio[3285]: info: Removing fusiondspeq DSP Signal Path Element Mar 03 11:13:46 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 11:13:46 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 11:13:46 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 03 11:13:46 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 11:13:46 volumio volumio[3285]: info: Starting Shairport Sync Mar 03 11:13:46 volumio volumio[3285]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf. Mar 03 11:13:46 volumio volumio[3285]: info: CoreCommandRouter::volumioGetState Mar 03 11:13:46 volumio volumio[3285]: info: CorePlayQueue::getTrack 0 Mar 03 11:13:46 volumio volumio[3285]: info: CoreCommandRouter::volumioPause Mar 03 11:13:46 volumio volumio[3285]: info: CoreStateMachine::pause Mar 03 11:13:46 volumio volumio[3285]: info: mpdhttpout --- Volumio set on pause Mar 03 11:13:46 volumio sudo[3728]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 03 11:13:46 volumio sudo[3728]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 11:13:46 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 03 11:13:46 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 03 11:13:46 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 03 11:13:46 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 03 11:13:46 volumio sudo[3728]: pam_unix(sudo:session): session closed for user root Mar 03 11:13:46 volumio volumio[3285]: info: Shairport-Sync Started Mar 03 11:13:46 volumio mpd[3720]: Mar 03 11:13 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 03 11:13:46 volumio systemd[1]: Started Music Player Daemon. Mar 03 11:13:46 volumio sudo[3713]: pam_unix(sudo:session): session closed for user root Mar 03 11:13:46 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Mar 03 11:13:46 volumio volumio[3285]: error: updateQueue error: null Mar 03 11:13:46 volumio volumio[3285]: verbose: [ytcr] MPD connected Mar 03 11:13:46 volumio volumio[3285]: verbose: [ytcr] MPDSubsystemEventEmitter enabled. Mar 03 11:13:47 volumio volumio[3736]: cset: --> shielding system active with Mar 03 11:13:47 volumio volumio[3736]: cset: "system" cpuset of CPUSPEC(1) with 168 tasks running Mar 03 11:13:47 volumio volumio[3736]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Mar 03 11:13:47 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Mar 03 11:13:47 volumio volumio[3285]: info: Getting Spotify volume Mar 03 11:13:47 volumio volumio[3285]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Mar 03 11:13:47 volumio volumio[3285]: info: CoreCommandRouter::volumioGetState Mar 03 11:13:47 volumio volumio[3285]: info: CorePlayQueue::getTrack 0 Mar 03 11:13:47 volumio volumio[3285]: SPOTIFY: RECEIVED VOLUMIO VOLUME 90 Mar 03 11:13:47 volumio volumio[3285]: SPOTIFY: SPOTIFY VOLUME undefined Mar 03 11:13:47 volumio volumio[3285]: SPOTIFY: VOLUMIO VOLUME 90 Mar 03 11:13:47 volumio volumio[3285]: info: Aligning Spotify Volume to Volumio Volume Mar 03 11:13:47 volumio volumio[3285]: info: CoreCommandRouter::volumioGetState Mar 03 11:13:47 volumio volumio[3285]: info: CorePlayQueue::getTrack 0 Mar 03 11:13:47 volumio volumio[3285]: info: Setting Spotify Volume from Volumio: 90 Mar 03 11:13:48 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Mar 03 11:13:48 volumio sudo[3772]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 03 11:13:48 volumio sudo[3772]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 11:13:48 volumio systemd[1]: musicservicesshield.service: Succeeded. Mar 03 11:13:48 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Mar 03 11:13:48 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Mar 03 11:13:48 volumio volumio[3285]: verbose: [ytcr] MPDSubsystemEventEmitter destroyed. Mar 03 11:13:48 volumio volumio[3285]: verbose: [ytcr] Cancelling prefetch (abortIfPrefetching: true, clearIfPrefetched: true) Mar 03 11:13:48 volumio volumio[3285]: info: CoreCommandRouter::volumioGetState Mar 03 11:13:48 volumio volumio[3285]: info: CorePlayQueue::getTrack 0 Mar 03 11:13:48 volumio volumio[3285]: verbose: [ytcr] MPD disconnected. Reconnecting... Mar 03 11:13:48 volumio systemd[1]: Stopping Music Player Daemon... Mar 03 11:13:48 volumio systemd[1]: mpd.service: Succeeded. Mar 03 11:13:48 volumio systemd[1]: Stopped Music Player Daemon. Mar 03 11:13:48 volumio volumio[3285]: info: Enabling plugin fusiondsp Mar 03 11:13:48 volumio volumio[3285]: info: Loading plugin "fusiondsp"... Mar 03 11:13:48 volumio volumio[3285]: info: Applying required configuration parameters for plugin fusiondsp Mar 03 11:13:48 volumio volumio[3285]: info: Preparing to generate the ALSA configuration file Mar 03 11:13:48 volumio systemd[1]: Starting Music Player Daemon... Mar 03 11:13:48 volumio volumio[3285]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Mar 03 11:13:48 volumio volumio[3285]: info: Reading ALSA contributions from plugins. Mar 03 11:13:48 volumio volumio[3285]: info: Asound.conf file written Mar 03 11:13:48 volumio sudo[3774]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 03 11:13:48 volumio sudo[3774]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 11:13:48 volumio sudo[3776]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 03 11:13:48 volumio sudo[3776]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 11:13:48 volumio sudo[3774]: pam_unix(sudo:session): session closed for user root Mar 03 11:13:48 volumio sudo[3776]: pam_unix(sudo:session): session closed for user root Mar 03 11:13:48 volumio volumio[3285]: info: Output device has changed, restarting MPD Mar 03 11:13:48 volumio sudo[3783]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 03 11:13:48 volumio sudo[3783]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 11:13:48 volumio sudo[3783]: pam_unix(sudo:session): session closed for user root Mar 03 11:13:48 volumio volumio[3285]: info: Output device has changed, restarting Shairport Sync Mar 03 11:13:48 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 11:13:48 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 11:13:48 volumio sudo[3786]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 03 11:13:48 volumio sudo[3786]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 11:13:49 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Mar 03 11:13:49 volumio systemd[1]: mpd.service: Succeeded. Mar 03 11:13:49 volumio systemd[1]: Stopped Music Player Daemon. Mar 03 11:13:49 volumio systemd[1]: Starting Music Player Daemon... Mar 03 11:13:49 volumio volumio[3285]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 03 11:13:49 volumio volumio[3285]: info: PLUGIN START: fusiondsp Mar 03 11:13:49 volumio volumio[3285]: info: Loading i18n strings for locale vi Mar 03 11:13:49 volumio volumio[3285]: info: Plugin fusiondsp has duplicated i18n key SAVE. It is ignored. Mar 03 11:13:49 volumio volumio[3285]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Mar 03 11:13:49 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Mar 03 11:13:49 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 11:13:49 volumio volumio[3285]: info: FusionDsp - mixtype--------------------- Hardware Mar 03 11:13:49 volumio volumio[3285]: info: Preparing to generate the ALSA configuration file Mar 03 11:13:49 volumio volumio[3285]: info: Done. Mar 03 11:13:49 volumio volumio[3285]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Mar 03 11:13:49 volumio volumio[3285]: info: Reading ALSA contributions from plugins. Mar 03 11:13:49 volumio sudo[3789]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 03 11:13:49 volumio sudo[3789]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 11:13:49 volumio sudo[3789]: pam_unix(sudo:session): session closed for user root Mar 03 11:13:49 volumio volumio[3285]: info: MPD Permissions set Mar 03 11:13:49 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 11:13:49 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 11:13:49 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 11:13:49 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 11:13:49 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 11:13:49 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 11:13:49 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 11:13:49 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 11:13:49 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 11:13:49 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 03 11:13:49 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 11:13:49 volumio volumio[3285]: info: Starting Shairport Sync Mar 03 11:13:49 volumio volumio[3285]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf. Mar 03 11:13:49 volumio volumio[3285]: info: CoreCommandRouter::volumioGetState Mar 03 11:13:49 volumio volumio[3285]: info: CorePlayQueue::getTrack 0 Mar 03 11:13:49 volumio volumio[3285]: info: CoreCommandRouter::volumioPause Mar 03 11:13:49 volumio volumio[3285]: info: CoreStateMachine::pause Mar 03 11:13:49 volumio volumio[3285]: info: mpdhttpout --- Volumio set on pause Mar 03 11:13:49 volumio volumio[3285]: SPOTIFY: SETTING SPOTIFY VOLUME 90 Mar 03 11:13:49 volumio volumio[3285]: info: Sending Spotify command with payload to local API: /player/volume Mar 03 11:13:49 volumio sudo[3801]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 03 11:13:49 volumio volumio[3285]: info: Asound.conf file unchanged, so no further update is needed Mar 03 11:13:49 volumio sudo[3801]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 11:13:49 volumio volumio[3285]: info: Output device has changed, restarting MPD Mar 03 11:13:49 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 03 11:13:49 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 03 11:13:49 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 03 11:13:49 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 03 11:13:49 volumio sudo[3801]: pam_unix(sudo:session): session closed for user root Mar 03 11:13:49 volumio sudo[3806]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 03 11:13:49 volumio sudo[3806]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 11:13:49 volumio sudo[3806]: pam_unix(sudo:session): session closed for user root Mar 03 11:13:49 volumio volumio[3285]: info: Output device has changed, restarting Shairport Sync Mar 03 11:13:49 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 11:13:49 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 11:13:49 volumio sudo[3810]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 03 11:13:49 volumio sudo[3810]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 11:13:49 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Mar 03 11:13:49 volumio systemd[1]: mpd.service: Succeeded. Mar 03 11:13:49 volumio systemd[1]: Stopped Music Player Daemon. Mar 03 11:13:49 volumio systemd[1]: Starting Music Player Daemon... Mar 03 11:13:49 volumio volumio[3285]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 03 11:13:49 volumio volumio[3285]: info: MPD Permissions set Mar 03 11:13:49 volumio volumio[3285]: info: Shairport-Sync Started Mar 03 11:13:49 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 11:13:49 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 11:13:49 volumio sudo[3813]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 03 11:13:49 volumio sudo[3813]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 11:13:49 volumio sudo[3813]: pam_unix(sudo:session): session closed for user root Mar 03 11:13:49 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 11:13:49 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 03 11:13:50 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 11:13:50 volumio volumio[3285]: info: Starting Shairport Sync Mar 03 11:13:50 volumio volumio[3285]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf. Mar 03 11:13:50 volumio volumio[3285]: info: CoreCommandRouter::volumioGetState Mar 03 11:13:50 volumio volumio[3285]: info: CorePlayQueue::getTrack 0 Mar 03 11:13:50 volumio volumio[3285]: info: CoreCommandRouter::volumioPause Mar 03 11:13:50 volumio volumio[3285]: info: CoreStateMachine::pause Mar 03 11:13:50 volumio volumio[3285]: info: mpdhttpout --- Volumio set on pause Mar 03 11:13:50 volumio sudo[3825]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 03 11:13:50 volumio sudo[3825]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 11:13:50 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 03 11:13:50 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 03 11:13:50 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 03 11:13:50 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 03 11:13:50 volumio sudo[3825]: pam_unix(sudo:session): session closed for user root Mar 03 11:13:50 volumio volumio[3285]: info: Shairport-Sync Started Mar 03 11:13:50 volumio mpd[3817]: Mar 03 11:13 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 03 11:13:50 volumio systemd[1]: Started Music Player Daemon. Mar 03 11:13:50 volumio sudo[3772]: pam_unix(sudo:session): session closed for user root Mar 03 11:13:50 volumio sudo[3810]: pam_unix(sudo:session): session closed for user root Mar 03 11:13:50 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Mar 03 11:13:50 volumio volumio[3285]: error: updateQueue error: null Mar 03 11:13:50 volumio sudo[3786]: pam_unix(sudo:session): session closed for user root Mar 03 11:13:50 volumio volumio[3285]: error: MPD error: The expression evaluated to a falsy value: Mar 03 11:13:50 volumio volumio[3285]: assert.ok(self.idling) Mar 03 11:13:50 volumio volumio[3285]: error: The expression evaluated to a falsy value: Mar 03 11:13:50 volumio volumio[3285]: assert.ok(self.idling) Mar 03 11:13:50 volumio volumio[3285]: error: updateQueue error: null Mar 03 11:13:50 volumio volumio[3285]: verbose: [ytcr] MPD connected Mar 03 11:13:50 volumio volumio[3285]: verbose: [ytcr] MPDSubsystemEventEmitter enabled. Mar 03 11:13:50 volumio volumio[3285]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Mar 03 11:13:50 volumio volumio[3285]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Mar 03 11:13:50 volumio volumio[3285]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Mar 03 11:13:50 volumio volumio[3285]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Mar 03 11:13:50 volumio volumio[3285]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Mar 03 11:13:50 volumio volumio[3285]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Mar 03 11:13:50 volumio volumio[3285]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Mar 03 11:13:50 volumio volumio[3285]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Mar 03 11:13:50 volumio volumio[3285]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Mar 03 11:13:50 volumio volumio[3285]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Mar 03 11:13:50 volumio volumio[3285]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Mar 03 11:13:50 volumio volumio[3285]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Mar 03 11:13:50 volumio volumio[3285]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Mar 03 11:13:50 volumio volumio[3285]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Mar 03 11:13:50 volumio volumio[3285]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Mar 03 11:13:50 volumio volumio[3285]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Mar 03 11:13:50 volumio volumio[3285]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Mar 03 11:13:50 volumio volumio[3285]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Mar 03 11:13:50 volumio volumio[3285]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Mar 03 11:13:50 volumio volumio[3285]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Mar 03 11:13:50 volumio volumio[3285]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Mar 03 11:13:50 volumio volumio[3285]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Mar 03 11:13:50 volumio volumio[3285]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Mar 03 11:13:50 volumio volumio[3285]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Mar 03 11:13:50 volumio volumio[3285]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Mar 03 11:13:50 volumio volumio[3285]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Mar 03 11:13:50 volumio volumio[3285]: info: Adding plugin bluetooth to MyMusic Plugins Mar 03 11:13:50 volumio volumio[3285]: info: Adding plugin multiroom to MyMusic Plugins Mar 03 11:13:50 volumio volumio[3285]: info: Adding plugin metavolumio to MyMusic Plugins Mar 03 11:13:50 volumio volumio[3285]: info: Adding plugin cd_controller to MyMusic Plugins Mar 03 11:13:50 volumio volumio[3285]: info: Adding plugin smart_inputs to MyMusic Plugins Mar 03 11:13:50 volumio volumio[3285]: info: Adding plugin tidalconnect to MyMusic Plugins Mar 03 11:13:50 volumio volumio[3285]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Mar 03 11:13:51 volumio volumio[3832]: cset: --> shielding system active with Mar 03 11:13:51 volumio volumio[3832]: cset: "system" cpuset of CPUSPEC(1) with 168 tasks running Mar 03 11:13:51 volumio volumio[3832]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Mar 03 11:13:51 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Mar 03 11:13:51 volumio volumio[3285]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Mar 03 11:13:51 volumio volumio[3285]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Mar 03 11:13:51 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 11:13:51 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 11:13:51 volumio volumio[3285]: info: Starting MyVolumio Remote Streaming Endpoints Mar 03 11:13:51 volumio volumio[3285]: info: MyVolumio login type: Token Mar 03 11:13:51 volumio volumio[3285]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Mar 03 11:13:51 volumio volumio[3285]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Mar 03 11:13:51 volumio volumio[3285]: info: Starting Streaming Service Transparent Proxy Mar 03 11:13:51 volumio volumio[3285]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Mar 03 11:13:51 volumio volumio[3285]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Mar 03 11:13:51 volumio volumio[3285]: info: Streaming services startup Mar 03 11:13:51 volumio volumio[3285]: info: Starting Streaming Daemon Mar 03 11:13:52 volumio sudo[3861]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 03 11:13:52 volumio sudo[3861]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 11:13:52 volumio sudo[3861]: pam_unix(sudo:session): session closed for user root Mar 03 11:13:52 volumio volumio[3285]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Mar 03 11:13:52 volumio volumio[3285]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Mar 03 11:13:52 volumio volumio[3285]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Mar 03 11:13:52 volumio volumio[3285]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Mar 03 11:13:52 volumio volumio[3285]: info: camilladsp spawned new process with pid 3873, instance 2, run: true Mar 03 11:13:52 volumio volumio[3285]: info: camilladsp service started and running in background, instance 2 Mar 03 11:13:52 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 03 11:13:52 volumio volumio[3285]: cannot open device 'volumioHw': Device or resource busy Mar 03 11:13:52 volumio volumio[3285]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Mar 03 11:13:52 volumio volumio[3285]: cannot open device 'volumioHw': Device or resource busy Mar 03 11:13:52 volumio volumio[3285]: info: FusionDsp loaded Mar 03 11:13:52 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 03 11:13:52 volumio volumio[3285]: info: FusionDsp - Reporting Fusion DSP Enabled Mar 03 11:13:52 volumio volumio[3285]: info: Adding Signal Path Element [object Object] Mar 03 11:13:52 volumio volumio[3285]: info: Adding fusiondspeq DSP Signal Path Element Mar 03 11:13:52 volumio volumio[3285]: info: FusionDsp - ---- installed callbackRead Mar 03 11:13:52 volumio volumio[3285]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 03 11:13:52 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Mar 03 11:13:52 volumio sudo[3883]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Mar 03 11:13:52 volumio sudo[3883]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 11:13:52 volumio systemd[1]: Started FusionDsp Daemon. Mar 03 11:13:52 volumio sudo[3883]: pam_unix(sudo:session): session closed for user root Mar 03 11:13:52 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Mar 03 11:13:52 volumio sudo[3887]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 03 11:13:52 volumio sudo[3887]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 11:13:53 volumio systemd[1]: musicservicesshield.service: Succeeded. Mar 03 11:13:53 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Mar 03 11:13:53 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Mar 03 11:13:53 volumio systemd[1]: Stopping Music Player Daemon... Mar 03 11:13:53 volumio systemd[1]: mpd.service: Succeeded. Mar 03 11:13:53 volumio systemd[1]: Stopped Music Player Daemon. Mar 03 11:13:53 volumio systemd[1]: Starting Music Player Daemon... Mar 03 11:13:53 volumio sudo[3890]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 03 11:13:53 volumio sudo[3890]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 11:13:53 volumio sudo[3891]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 03 11:13:53 volumio sudo[3890]: pam_unix(sudo:session): session closed for user root Mar 03 11:13:53 volumio sudo[3891]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 11:13:53 volumio volumio[3285]: STREAMING PROXY: Starting server on port 3245 Mar 03 11:13:53 volumio volumio[3285]: Node JS runtime: 14 Mar 03 11:13:53 volumio volumio[3285]: info: FusionDsp - ---- read samplerate, raw: 192000,S32_LE,2,32 Mar 03 11:13:53 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Mar 03 11:13:53 volumio systemd[1]: mpd.service: Succeeded. Mar 03 11:13:53 volumio systemd[1]: Stopped Music Player Daemon. Mar 03 11:13:53 volumio volumio[3285]: info: FusionDsp - ---- read samplerate from file: 192000 Mar 03 11:13:53 volumio volumio[3285]: error: FusionDsp - TypeError: Cannot read property 'stop' of null Mar 03 11:13:53 volumio volumio[3285]: info: FusionDsp - ---- read samplerate, raw: 192000,S32_LE,2,32 Mar 03 11:13:53 volumio volumio[3285]: info: FusionDsp - ---- read samplerate from file: 192000 Mar 03 11:13:53 volumio volumio[3285]: info: camilladsp stopping service pid 3873... Mar 03 11:13:53 volumio systemd[1]: Starting Music Player Daemon... Mar 03 11:13:53 volumio volumio[3285]: info: camilladsp service terminated, instance 2 Mar 03 11:13:53 volumio volumio[3285]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 03 11:13:53 volumio volumio[3285]: info: camilladsp service started and running in background, instance 2 Mar 03 11:13:53 volumio sudo[3898]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 03 11:13:53 volumio sudo[3898]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 11:13:53 volumio volumio[3285]: error: Cannot start Volumio Streaming Daemon Mar 03 11:13:53 volumio volumio[3285]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 03 11:13:53 volumio volumio[3285]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 03 11:13:53 volumio volumio[3285]: verbose: [ytcr] MPDSubsystemEventEmitter destroyed. Mar 03 11:13:53 volumio volumio[3285]: verbose: [ytcr] Cancelling prefetch (abortIfPrefetching: true, clearIfPrefetched: true) Mar 03 11:13:53 volumio volumio[3285]: info: CoreCommandRouter::volumioGetState Mar 03 11:13:53 volumio volumio[3285]: info: CorePlayQueue::getTrack 0 Mar 03 11:13:53 volumio volumio[3285]: verbose: [ytcr] MPD disconnected. Reconnecting... Mar 03 11:13:53 volumio sudo[3898]: pam_unix(sudo:session): session closed for user root Mar 03 11:13:53 volumio volumio[3285]: error: FusionDsp - WebSocket error: [object Object] Mar 03 11:13:53 volumio volumio[3285]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Mar 03 11:13:53 volumio volumio[3285]: info: camilladsp spawned new process with pid 3903, instance 2, run: true Mar 03 11:13:54 volumio volumio[3285]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Mar 03 11:13:54 volumio mpd[3902]: Mar 03 11:13 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 03 11:13:54 volumio systemd[1]: Started Music Player Daemon. Mar 03 11:13:54 volumio sudo[3887]: pam_unix(sudo:session): session closed for user root Mar 03 11:13:54 volumio sudo[3891]: pam_unix(sudo:session): session closed for user root Mar 03 11:13:54 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Mar 03 11:13:54 volumio volumio[3285]: error: updateQueue error: null Mar 03 11:13:54 volumio volumio[3285]: error: updateQueue error: null Mar 03 11:13:54 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 03 11:13:54 volumio volumio[3285]: verbose: [ytcr] MPD connected Mar 03 11:13:54 volumio volumio[3285]: verbose: [ytcr] MPDSubsystemEventEmitter enabled. Mar 03 11:13:54 volumio volumio[3285]: info: Preload queue cleared Mar 03 11:13:54 volumio volumio[3912]: cset: --> shielding system active with Mar 03 11:13:54 volumio volumio[3912]: cset: "system" cpuset of CPUSPEC(1) with 183 tasks running Mar 03 11:13:54 volumio volumio[3912]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Mar 03 11:13:54 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Mar 03 11:13:54 volumio volumio[3285]: info: MyVolumio token set successfully Mar 03 11:13:54 volumio volumio[3285]: info: MYVOLUMIO: Adding device Mar 03 11:13:54 volumio volumio[3285]: info: MYVOLUMIO: Evaluating Server Mar 03 11:13:56 volumio volumio[3285]: info: MyVolumio status changed Mar 03 11:13:56 volumio volumio[3285]: info: Streaming services startup Mar 03 11:13:56 volumio volumio[3285]: info: Starting Streaming Daemon Mar 03 11:13:56 volumio volumio[3285]: info: Removing browser output: myVolumio user plan is not superstar Mar 03 11:13:56 volumio volumio[3285]: info: Removing audio output: Mar 03 11:13:56 volumio volumio[3285]: info: Stoppping Tunnel 1 Mar 03 11:13:56 volumio sudo[3953]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 03 11:13:56 volumio sudo[3953]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 11:13:56 volumio sudo[3953]: pam_unix(sudo:session): session closed for user root Mar 03 11:13:56 volumio volumio[3285]: info: AutoStart - Plugin is starting Mar 03 11:13:56 volumio sudo[3956]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Mar 03 11:13:56 volumio volumio[3285]: info: CoreCommandRouter::volumioGetQueue Mar 03 11:13:56 volumio volumio[3285]: info: CoreStateMachine::getQueue Mar 03 11:13:56 volumio volumio[3285]: info: CorePlayQueue::getQueue Mar 03 11:13:56 volumio volumio[3285]: error: Cannot start Volumio Streaming Daemon Mar 03 11:13:56 volumio volumio[3285]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 03 11:13:56 volumio volumio[3285]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 03 11:13:56 volumio sudo[3956]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 03 11:13:56 volumio volumio[3285]: info: Setting Geolocation for MyVolumio to as1 Mar 03 11:13:56 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 11:13:56 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 11:13:56 volumio sudo[3956]: pam_unix(sudo:session): session closed for user root Mar 03 11:13:56 volumio volumio[3285]: info: Remote SSH Stopped Mar 03 11:13:56 volumio volumio[3285]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Mar 03 11:13:57 volumio volumio[3285]: info: Updating MyVolumio device info Mar 03 11:13:57 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 11:13:57 volumio volumio[3285]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Mar 03 11:13:58 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 03 11:13:58 volumio volumio[3285]: info: Preload queue cleared Mar 03 11:14:00 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 03 11:14:00 volumio volumio[3285]: info: Preload queue cleared Mar 03 11:14:00 volumio volumio[3285]: info: MYVOLUMIO: Adding device Mar 03 11:14:00 volumio volumio[3285]: info: MYVOLUMIO: Evaluating Server Mar 03 11:14:01 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 03 11:14:01 volumio volumio[3285]: info: Setting Geolocation for MyVolumio to as1 Mar 03 11:14:01 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 11:14:01 volumio volumio[3285]: info: Preload queue cleared Mar 03 11:14:02 volumio volumio[3285]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Mar 03 11:14:02 volumio volumio[3285]: info: Updating MyVolumio device info Mar 03 11:14:02 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 11:14:04 volumio volumio[3285]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Mar 03 11:14:05 volumio volumio[3285]: verbose: ----------------------------------- Mar 03 11:14:05 volumio volumio[3285]: verbose: [yt-cast-receiver] (AID: 5) (YouTube Music) Incoming message: 'noop' Mar 03 11:14:05 volumio volumio[3285]: verbose: [yt-cast-receiver] (AID: 5) (YouTube Music) Not handled: 'noop' Mar 03 11:14:06 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 03 11:14:06 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 03 11:14:06 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 03 11:14:06 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Mar 03 11:14:06 volumio volumio-remote-updater[622]: No test mode Mar 03 11:14:06 volumio volumio-remote-updater[622]: No alpha test mode Mar 03 11:14:06 volumio volumio[3285]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Mar 03 11:14:06 volumio volumio[3285]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 03 11:14:06 volumio volumio[3285]: TypeError: Cannot read property 'length' of undefined Mar 03 11:14:06 volumio volumio[3285]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Mar 03 11:14:06 volumio volumio[3285]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Mar 03 11:14:06 volumio volumio[3285]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Mar 03 11:14:06 volumio volumio[3285]: at Parser.emit (events.js:400:28) Mar 03 11:14:06 volumio volumio[3285]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Mar 03 11:14:06 volumio volumio[3285]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Mar 03 11:14:06 volumio volumio[3285]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Mar 03 11:14:06 volumio volumio[3285]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Mar 03 11:14:06 volumio volumio[3285]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Mar 03 11:14:06 volumio volumio[3285]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Mar 03 11:14:06 volumio volumio[3285]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Mar 03 11:14:06 volumio volumio[3285]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Mar 03 11:14:06 volumio volumio[3285]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Mar 03 11:14:06 volumio volumio[3285]: at IncomingMessage.emit (events.js:412:35) Mar 03 11:14:06 volumio volumio[3285]: at endReadableNT (internal/streams/readable.js:1333:12) Mar 03 11:14:06 volumio volumio[3285]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Mar 03 11:14:06 volumio volumio[3285]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 03 11:14:06 volumio sudo[4049]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-03 11:13 Mar 03 11:14:06 volumio sudo[4049]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 07:25:16 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="66c567362bdbb3dc1644a18f7879afe7"