-- Logs begin at Sun 2024-04-07 22:07:32 MSK, end at Mon 2024-04-08 10:32:52 MSK. --
Apr 08 10:31:02 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Apr 08 10:31:06 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Apr 08 10:31:08 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: networkfs , addShare
Apr 08 10:31:08 volumio volumio[3715]: info: Adding a new share
Apr 08 10:31:08 volumio volumio[3715]: info: No correspondence found in configuration for share comp on IP 192.168.31.202
Apr 08 10:31:08 volumio sudo[3842]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.31.202/Music /mnt/NAS/comp
Apr 08 10:31:08 volumio sudo[3842]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 08 10:31:08 volumio kernel: Key type cifs.spnego registered
Apr 08 10:31:08 volumio kernel: Key type cifs.idmap registered
Apr 08 10:31:08 volumio kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount.
Apr 08 10:31:08 volumio kernel: CIFS: Attempting to mount \\192.168.31.202\Music
Apr 08 10:31:10 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Apr 08 10:31:13 volumio volumio[3715]: error: Plugin system_controller system failed to complete 'onVolumioStart' in a timely fashion
Apr 08 10:31:13 volumio volumio[3715]: info: Preparing to generate the ALSA configuration file
Apr 08 10:31:13 volumio volumio[3715]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf
Apr 08 10:31:13 volumio volumio[3715]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf
Apr 08 10:31:13 volumio volumio[3715]: info: Reading ALSA contributions from plugins.
Apr 08 10:31:13 volumio volumio[3715]: info: Asound.conf file unchanged, so no further update is needed
Apr 08 10:31:13 volumio volumio[3715]: info: Output device has changed, restarting MPD
Apr 08 10:31:13 volumio sudo[3857]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Apr 08 10:31:13 volumio sudo[3857]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 08 10:31:13 volumio volumio[3715]: info: Output device has changed, restarting Shairport Sync
Apr 08 10:31:13 volumio sudo[3857]: pam_unix(sudo:session): session closed for user root
Apr 08 10:31:13 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 08 10:31:13 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 08 10:31:13 volumio sudo[3860]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Apr 08 10:31:13 volumio sudo[3860]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 08 10:31:13 volumio systemd[1]: Stopping Music Player Daemon...
Apr 08 10:31:13 volumio systemd[1]: mpd.service: Succeeded.
Apr 08 10:31:13 volumio systemd[1]: Stopped Music Player Daemon.
Apr 08 10:31:13 volumio volumio[3715]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Apr 08 10:31:13 volumio volumio[3715]: info: ___________ START PLUGINS ___________
Apr 08 10:31:13 volumio volumio[3715]: info: ControllerMpd::onStart: Initializing MPD
Apr 08 10:31:13 volumio volumio[3715]: info: Creating MPD Configuration file
Apr 08 10:31:13 volumio sudo[3868]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Apr 08 10:31:13 volumio sudo[3868]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 08 10:31:13 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 08 10:31:13 volumio volumio[3715]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Apr 08 10:31:13 volumio volumio[3715]: info: [1712561473712] CoreMusicLibrary::Adding element Медиа-серверы
Apr 08 10:31:13 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 08 10:31:13 volumio sudo[3868]: pam_unix(sudo:session): session closed for user root
Apr 08 10:31:13 volumio sudo[3871]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Apr 08 10:31:13 volumio systemd[1]: Starting Music Player Daemon...
Apr 08 10:31:13 volumio sudo[3871]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 08 10:31:13 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 08 10:31:13 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 08 10:31:13 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM
Apr 08 10:31:13 volumio systemd[1]: mpd.service: Succeeded.
Apr 08 10:31:13 volumio systemd[1]: Stopped Music Player Daemon.
Apr 08 10:31:13 volumio systemd[1]: Starting Music Player Daemon...
Apr 08 10:31:13 volumio volumio[3715]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Apr 08 10:31:13 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 08 10:31:13 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 08 10:31:13 volumio volumio[3715]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Apr 08 10:31:13 volumio volumio[3715]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Apr 08 10:31:13 volumio volumio[3715]: info: [1712561473863] CoreMusicLibrary::Adding element Last_100
Apr 08 10:31:13 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 08 10:31:13 volumio volumio[3715]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Apr 08 10:31:13 volumio volumio[3715]: info: [1712561473869] CoreMusicLibrary::Adding element Webradio
Apr 08 10:31:13 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 08 10:31:13 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Apr 08 10:31:13 volumio volumio[3715]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Apr 08 10:31:13 volumio volumio[3715]: info: [1712561473891] CoreMusicLibrary::Adding element YouTube2
Apr 08 10:31:13 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 08 10:31:13 volumio volumio[3715]: Cannot find translation for source YouTube2
Apr 08 10:31:13 volumio volumio[3715]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Apr 08 10:31:13 volumio volumio[3715]: info: [1712561473962] CoreMusicLibrary::Adding element YouTube Music
Apr 08 10:31:13 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 08 10:31:13 volumio volumio[3715]: Cannot find translation for source YouTube2
Apr 08 10:31:13 volumio volumio[3715]: Cannot find translation for source YouTube Music
Apr 08 10:31:13 volumio volumio[3715]: info: Loading i18n strings for locale ru
Apr 08 10:31:13 volumio volumio[3715]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found.
Apr 08 10:31:13 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile
Apr 08 10:31:14 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 08 10:31:14 volumio volumio[3715]: info: FusionDsp - mixtype--------------------- Software
Apr 08 10:31:14 volumio volumio[3715]: info: Loading i18n strings for locale ru
Apr 08 10:31:14 volumio volumio[3715]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found.
Apr 08 10:31:14 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Apr 08 10:31:14 volumio volumio[3715]: info: Volumio Calling Home
Apr 08 10:31:14 volumio volumio[3715]: info: Preparing to generate the ALSA configuration file
Apr 08 10:31:14 volumio sudo[3884]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service
Apr 08 10:31:14 volumio sudo[3884]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 08 10:31:14 volumio sudo[3887]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service
Apr 08 10:31:14 volumio sudo[3887]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 08 10:31:14 volumio sudo[3889]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload
Apr 08 10:31:14 volumio sudo[3889]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 08 10:31:14 volumio sudo[3884]: pam_unix(sudo:session): session closed for user root
Apr 08 10:31:14 volumio systemd[1]: Reloading.
Apr 08 10:31:14 volumio volumio[3715]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf
Apr 08 10:31:14 volumio volumio[3715]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf
Apr 08 10:31:14 volumio volumio[3715]: info: Reading ALSA contributions from plugins.
Apr 08 10:31:14 volumio volumio[3715]: error: touch_display: Error detecting backlight interface: Error: ENOENT: no such file or directory, scandir '/sys/class/backlight/'
Apr 08 10:31:14 volumio volumio[3715]: info: touch_display: systemctl stop getty@tty1.service succeeded.
Apr 08 10:31:14 volumio volumio[3715]: info: MPD Permissions set
Apr 08 10:31:14 volumio volumio[3715]: info: MPD Permissions set
Apr 08 10:31:14 volumio volumio[3715]: info: Volumio called home
Apr 08 10:31:14 volumio volumio[3715]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 11
Apr 08 10:31:14 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 08 10:31:14 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 08 10:31:14 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 08 10:31:14 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 08 10:31:14 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 08 10:31:14 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 08 10:31:14 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 08 10:31:14 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 08 10:31:14 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 08 10:31:14 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 08 10:31:14 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 08 10:31:14 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 08 10:31:14 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 08 10:31:14 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 08 10:31:14 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 08 10:31:14 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 08 10:31:14 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 08 10:31:14 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 08 10:31:14 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 08 10:31:14 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 08 10:31:14 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.
Apr 08 10:31:14 volumio volumio[3715]: info: touch_display: No Raspberry Pi Foundation touch screen detected.
Apr 08 10:31:14 volumio sudo[3914]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf
Apr 08 10:31:14 volumio sudo[3914]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 08 10:31:14 volumio sudo[3914]: pam_unix(sudo:session): session closed for user root
Apr 08 10:31:14 volumio volumio[3715]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set.
Apr 08 10:31:14 volumio volumio[3715]: info: Starting Shairport Sync
Apr 08 10:31:14 volumio volumio[3715]: info: Starting Shairport Sync
Apr 08 10:31:14 volumio sudo[3917]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Apr 08 10:31:14 volumio sudo[3917]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 08 10:31:14 volumio volumio[3715]: info: Starting Shairport Sync
Apr 08 10:31:14 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Apr 08 10:31:14 volumio sudo[3922]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Apr 08 10:31:14 volumio sudo[3922]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 08 10:31:14 volumio sudo[3921]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Apr 08 10:31:14 volumio sudo[3921]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 08 10:31:15 volumio volumio[3715]: info: Asound.conf file unchanged, so no further update is needed
Apr 08 10:31:15 volumio volumio[3715]: info: Output device has changed, restarting MPD
Apr 08 10:31:15 volumio mpd[3874]: Apr 08 10:31 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Apr 08 10:31:15 volumio sudo[3928]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Apr 08 10:31:15 volumio sudo[3928]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 08 10:31:15 volumio volumio[3715]: info: Output device has changed, restarting Shairport Sync
Apr 08 10:31:15 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 08 10:31:15 volumio sudo[3928]: pam_unix(sudo:session): session closed for user root
Apr 08 10:31:15 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 08 10:31:15 volumio sudo[3931]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Apr 08 10:31:15 volumio sudo[3931]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 08 10:31:15 volumio volumio[3715]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Apr 08 10:31:15 volumio volumio[3715]: info: MPD Permissions set
Apr 08 10:31:15 volumio volumio[3715]: info: CoreCommandRouter::volumioGetState
Apr 08 10:31:15 volumio volumio[3715]: info: CorePlayQueue::getTrack 0
Apr 08 10:31:15 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 08 10:31:15 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 08 10:31:15 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 08 10:31:15 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 08 10:31:15 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 08 10:31:15 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 08 10:31:15 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 08 10:31:15 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 08 10:31:15 volumio systemd[1]: Started Music Player Daemon.
Apr 08 10:31:15 volumio sudo[3889]: pam_unix(sudo:session): session closed for user root
Apr 08 10:31:15 volumio sudo[3871]: pam_unix(sudo:session): session closed for user root
Apr 08 10:31:15 volumio sudo[3860]: pam_unix(sudo:session): session closed for user root
Apr 08 10:31:15 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 08 10:31:15 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 08 10:31:15 volumio systemd[1]: Reloading.
Apr 08 10:31:15 volumio volumio[3715]: info: touch_display: systemctl daemon-reload succeeded.
Apr 08 10:31:15 volumio volumio[3715]: info: Starting Shairport Sync
Apr 08 10:31:15 volumio sudo[3943]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service
Apr 08 10:31:15 volumio sudo[3943]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 08 10:31:15 volumio sudo[3953]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Apr 08 10:31:15 volumio sudo[3953]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 08 10:31:15 volumio volumio[3715]: error: MPD error: The expression evaluated to a falsy value:
Apr 08 10:31:15 volumio volumio[3715]: assert.ok(self.idling)
Apr 08 10:31:15 volumio volumio[3715]: error: The expression evaluated to a falsy value:
Apr 08 10:31:15 volumio volumio[3715]: assert.ok(self.idling)
Apr 08 10:31:15 volumio volumio[3715]: info: MPD running with PID3874
Apr 08 10:31:15 volumio volumio[3715]: ,establishing connection
Apr 08 10:31:15 volumio volumio[3715]: error: updateQueue error: null
Apr 08 10:31:15 volumio volumio[3715]: error: updateQueue error: null
Apr 08 10:31:15 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.
Apr 08 10:31:16 volumio volumio[3715]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists
Apr 08 10:31:16 volumio volumio[3715]: 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
Apr 08 10:31:16 volumio volumio[3715]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists
Apr 08 10:31:16 volumio volumio[3715]: info: camilladsp spawned new process with pid 4008, instance 1, run: true
Apr 08 10:31:16 volumio volumio[3715]: info: camilladsp service started and running in background, instance 1
Apr 08 10:31:16 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 08 10:31:16 volumio volumio[3715]: info: FusionDsp - AAAAAAAAAAAAAA-> 22050 44100 48000 88200 96000 176400 192000 <-AAAAAAAAAAAAA
Apr 08 10:31:16 volumio volumio[3715]: info: FusionDsp loaded
Apr 08 10:31:16 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Apr 08 10:31:16 volumio sudo[4018]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service
Apr 08 10:31:16 volumio sudo[4018]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 08 10:31:16 volumio volumio[3715]: info: FusionDsp - Reporting Fusion DSP Enabled
Apr 08 10:31:16 volumio volumio[3715]: info: Adding Signal Path Element [object Object]
Apr 08 10:31:16 volumio volumio[3715]: info: Adding fusiondspeq DSP Signal Path Element
Apr 08 10:31:16 volumio volumio[3715]: info: FusionDsp - ---- installed callbackRead
Apr 08 10:31:16 volumio volumio[3715]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Apr 08 10:31:16 volumio volumio[3715]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 12
Apr 08 10:31:16 volumio volumio[3715]: info: FusionDsp - {"Reload":{"result":"Ok"}}
Apr 08 10:31:16 volumio sudo[3887]: pam_unix(sudo:session): session closed for user root
Apr 08 10:31:16 volumio volumio[3715]: info: touch_display: systemctl disable getty@tty1.service succeeded.
Apr 08 10:31:16 volumio sudo[3943]: pam_unix(sudo:session): session closed for user root
Apr 08 10:31:16 volumio systemd[1]: Stopping Music Player Daemon...
Apr 08 10:31:16 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Apr 08 10:31:16 volumio sudo[4018]: pam_unix(sudo:session): session closed for user root
Apr 08 10:31:16 volumio systemd[1]: Starting Daily apt download activities...
Apr 08 10:31:16 volumio volumio[3715]: info: touch_display: systemctl start volumio-kiosk.service succeeded.
Apr 08 10:31:16 volumio volumio[3715]: info: touch_display: Volumio Kiosk started.
Apr 08 10:31:16 volumio systemd[1]: shairport-sync.service: Succeeded.
Apr 08 10:31:16 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Apr 08 10:31:16 volumio systemd[1]: mpd.service: Succeeded.
Apr 08 10:31:16 volumio systemd[1]: Stopped Music Player Daemon.
Apr 08 10:31:16 volumio volumio[3715]: info: CoreCommandRouter::volumioGetState
Apr 08 10:31:16 volumio volumio[3715]: info: CorePlayQueue::getTrack 0
Apr 08 10:31:16 volumio systemd[1]: Starting Music Player Daemon...
Apr 08 10:31:16 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Apr 08 10:31:16 volumio sudo[3917]: pam_unix(sudo:session): session closed for user root
Apr 08 10:31:16 volumio sudo[3922]: pam_unix(sudo:session): session closed for user root
Apr 08 10:31:16 volumio sudo[3953]: pam_unix(sudo:session): session closed for user root
Apr 08 10:31:16 volumio sudo[3921]: pam_unix(sudo:session): session closed for user root
Apr 08 10:31:16 volumio volumio[3715]: info: CoreCommandRouter::volumioGetState
Apr 08 10:31:16 volumio volumio[3715]: info: CorePlayQueue::getTrack 0
Apr 08 10:31:16 volumio volumio[3715]: info: Shairport-Sync Started
Apr 08 10:31:16 volumio volumio[3715]: Error adding Membership: Error: addMembership EINVAL
Apr 08 10:31:16 volumio volumio[3715]: info: Shairport-Sync Started
Apr 08 10:31:16 volumio volumio[3715]: info: Shairport-Sync Started
Apr 08 10:31:16 volumio volumio[3715]: info: Shairport-Sync Started
Apr 08 10:31:16 volumio volumio[3715]: info: touch_display: X display number found: 0
Apr 08 10:31:16 volumio volumio[3715]: info: [yt-cast-receiver] DIAL server listening on port 8098
Apr 08 10:31:16 volumio volumio[3715]: info: CoreCommandRouter::volumioRetrievevolume
Apr 08 10:31:16 volumio volumio[3715]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0
Apr 08 10:31:16 volumio volumio[3715]: info: CoreCommandRouter::volumioGetState
Apr 08 10:31:16 volumio volumio[3715]: info: CorePlayQueue::getTrack 0
Apr 08 10:31:16 volumio volumio[3715]: info: CoreStateMachine::pushState
Apr 08 10:31:16 volumio volumio[3715]: info: CorePlayQueue::getTrack 0
Apr 08 10:31:16 volumio volumio[3715]: info: CoreCommandRouter::volumioPushState
Apr 08 10:31:16 volumio volumio[3715]: info: Completed starting Core Plugins
Apr 08 10:31:16 volumio volumio[3715]: info: -------------------------------------------
Apr 08 10:31:16 volumio volumio[3715]: info: ----- MyVolumio plugins startup ----
Apr 08 10:31:16 volumio volumio[3715]: info: -------------------------------------------
Apr 08 10:31:16 volumio volumio[3715]: info: [MyVolumio PluginManager] Fetching plans data....
Apr 08 10:31:16 volumio volumio[3715]: info: CoreCommandRouter::volumioGetState
Apr 08 10:31:16 volumio volumio[3715]: info: CorePlayQueue::getTrack 0
Apr 08 10:31:16 volumio volumio[3715]: info: touch_display: X display number found: 0
Apr 08 10:31:16 volumio volumio[3715]: info: touch_display: Setting screensaver timeout to 999999 seconds.
Apr 08 10:31:17 volumio mpd[4024]: Apr 08 10:31 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Apr 08 10:31:17 volumio systemd[1]: Started Music Player Daemon.
Apr 08 10:31:17 volumio sudo[3931]: pam_unix(sudo:session): session closed for user root
Apr 08 10:31:17 volumio volumio[3715]: error: updateQueue error: null
Apr 08 10:31:18 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Apr 08 10:31:18 volumio sudo[3842]: pam_unix(sudo:session): session closed for user root
Apr 08 10:31:18 volumio volumio[3715]: info: Error mounting comp on IP 192.168.31.202 : (115): Operation now in progress
Apr 08 10:31:18 volumio volumio[3715]: Refer to the mount.cifs(8) manual page (e.g. man mount.cifs)
Apr 08 10:31:18 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation.
Apr 08 10:31:18 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -115
Apr 08 10:31:19 volumio systemd[1]: apt-daily.service: Succeeded.
Apr 08 10:31:19 volumio systemd[1]: Started Daily apt download activities.
Apr 08 10:31:19 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares
Apr 08 10:31:22 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Apr 08 10:31:25 volumio volumio[3715]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
Apr 08 10:31:25 volumio volumio[3715]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
Apr 08 10:31:25 volumio volumio[3715]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
Apr 08 10:31:25 volumio volumio[3715]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
Apr 08 10:31:25 volumio volumio[3715]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
Apr 08 10:31:25 volumio volumio[3715]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
Apr 08 10:31:25 volumio volumio[3715]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
Apr 08 10:31:25 volumio volumio[3715]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
Apr 08 10:31:25 volumio volumio[3715]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
Apr 08 10:31:25 volumio volumio[3715]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
Apr 08 10:31:25 volumio volumio[3715]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
Apr 08 10:31:25 volumio volumio[3715]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
Apr 08 10:31:25 volumio volumio[3715]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
Apr 08 10:31:25 volumio volumio[3715]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
Apr 08 10:31:25 volumio volumio[3715]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
Apr 08 10:31:25 volumio volumio[3715]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
Apr 08 10:31:25 volumio volumio[3715]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
Apr 08 10:31:25 volumio volumio[3715]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
Apr 08 10:31:25 volumio volumio[3715]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
Apr 08 10:31:25 volumio volumio[3715]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
Apr 08 10:31:25 volumio volumio[3715]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
Apr 08 10:31:25 volumio volumio[3715]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
Apr 08 10:31:25 volumio volumio[3715]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
Apr 08 10:31:25 volumio volumio[3715]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
Apr 08 10:31:25 volumio volumio[3715]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
Apr 08 10:31:25 volumio volumio[3715]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
Apr 08 10:31:25 volumio volumio[3715]: info: Adding plugin bluetooth to MyMusic Plugins
Apr 08 10:31:25 volumio volumio[3715]: info: Adding plugin multiroom to MyMusic Plugins
Apr 08 10:31:25 volumio volumio[3715]: info: Adding plugin metavolumio to MyMusic Plugins
Apr 08 10:31:25 volumio volumio[3715]: info: Adding plugin cd_controller to MyMusic Plugins
Apr 08 10:31:25 volumio volumio[3715]: info: Adding plugin smart_inputs to MyMusic Plugins
Apr 08 10:31:25 volumio volumio[3715]: info: Adding plugin tidalconnect to MyMusic Plugins
Apr 08 10:31:25 volumio volumio[3715]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
Apr 08 10:31:26 volumio volumio[3715]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Apr 08 10:31:26 volumio volumio[3715]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Apr 08 10:31:26 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 08 10:31:26 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 08 10:31:26 volumio volumio[3715]: info: Starting MyVolumio Remote Streaming Endpoints
Apr 08 10:31:26 volumio volumio[3715]: info: MyVolumio login type: Token
Apr 08 10:31:26 volumio volumio[3715]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Apr 08 10:31:26 volumio volumio[3715]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
Apr 08 10:31:27 volumio volumio[3715]: info: Starting Streaming Service Transparent Proxy
Apr 08 10:31:27 volumio volumio[3715]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Apr 08 10:31:27 volumio volumio[3715]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Apr 08 10:31:27 volumio volumio[3715]: info: Streaming services startup
Apr 08 10:31:27 volumio volumio[3715]: info: Starting Streaming Daemon
Apr 08 10:31:27 volumio sudo[4100]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Apr 08 10:31:27 volumio sudo[4100]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 08 10:31:27 volumio sudo[4100]: pam_unix(sudo:session): session closed for user root
Apr 08 10:31:27 volumio volumio[3715]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
Apr 08 10:31:27 volumio volumio[3715]: error: Cannot start Volumio Streaming Daemon
Apr 08 10:31:27 volumio volumio[3715]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Apr 08 10:31:27 volumio volumio[3715]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Apr 08 10:31:27 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Apr 08 10:31:28 volumio volumio[3715]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN
Apr 08 10:31:28 volumio volumio[3715]: STREAMING PROXY: Starting server on port 3245
Apr 08 10:31:28 volumio volumio[3715]: Node JS runtime: 14
Apr 08 10:31:28 volumio volumio[3715]: info: MyVolumio token set successfully
Apr 08 10:31:28 volumio volumio[3715]: info: MYVOLUMIO: Adding device
Apr 08 10:31:28 volumio volumio[3715]: info: MYVOLUMIO: Evaluating Server
Apr 08 10:31:29 volumio volumio[3715]: info: MyVolumio status changed
Apr 08 10:31:29 volumio volumio[3715]: info: Streaming services startup
Apr 08 10:31:29 volumio volumio[3715]: info: Starting Streaming Daemon
Apr 08 10:31:29 volumio volumio[3715]: info: Removing browser output: myVolumio user plan is not superstar
Apr 08 10:31:29 volumio volumio[3715]: info: Removing audio output:
Apr 08 10:31:29 volumio volumio[3715]: info: Stoppping Tunnel 1
Apr 08 10:31:29 volumio sudo[4125]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Apr 08 10:31:29 volumio sudo[4125]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 08 10:31:29 volumio sudo[4125]: pam_unix(sudo:session): session closed for user root
Apr 08 10:31:29 volumio volumio[3715]: error: Cannot start Volumio Streaming Daemon
Apr 08 10:31:29 volumio volumio[3715]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Apr 08 10:31:29 volumio volumio[3715]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Apr 08 10:31:29 volumio sudo[4128]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service
Apr 08 10:31:29 volumio sudo[4128]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 08 10:31:29 volumio sudo[4128]: pam_unix(sudo:session): session closed for user root
Apr 08 10:31:29 volumio volumio[3715]: info: Remote SSH Stopped
Apr 08 10:31:29 volumio volumio[3715]: info: Setting Geolocation for MyVolumio to eu10
Apr 08 10:31:29 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 08 10:31:29 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 08 10:31:29 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 08 10:31:30 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Apr 08 10:31:30 volumio volumio[3715]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
Apr 08 10:31:30 volumio volumio[3715]: info: Updating MyVolumio device info
Apr 08 10:31:30 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 08 10:31:30 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 08 10:31:30 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 08 10:31:31 volumio volumio[3715]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
Apr 08 10:31:34 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Apr 08 10:31:34 volumio volumio[3715]: info: MYVOLUMIO: Adding device
Apr 08 10:31:34 volumio volumio[3715]: info: MYVOLUMIO: Evaluating Server
Apr 08 10:31:35 volumio volumio[3715]: info: Setting Geolocation for MyVolumio to eu6
Apr 08 10:31:35 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 08 10:31:35 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 08 10:31:35 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 08 10:31:35 volumio volumio[3715]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"}
Apr 08 10:31:36 volumio volumio[3715]: info: Updating MyVolumio device info
Apr 08 10:31:36 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 08 10:31:36 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 08 10:31:36 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 08 10:31:36 volumio volumio[3715]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"}
Apr 08 10:31:38 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Apr 08 10:31:41 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Apr 08 10:31:41 volumio volumio[3715]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 13
Apr 08 10:31:41 volumio volumio[3715]: info: CoreCommandRouter::volumioGetState
Apr 08 10:31:41 volumio volumio[3715]: info: CorePlayQueue::getTrack 0
Apr 08 10:31:42 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Apr 08 10:31:44 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Apr 08 10:31:44 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 08 10:31:44 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Apr 08 10:31:44 volumio volumio-remote-updater[472]: No test mode
Apr 08 10:31:44 volumio volumio-remote-updater[472]: No alpha test mode
Apr 08 10:31:44 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Apr 08 10:31:44 volumio volumio[3715]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false}
Apr 08 10:31:44 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Apr 08 10:31:46 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 08 10:31:46 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 08 10:31:46 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Apr 08 10:31:46 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Apr 08 10:31:46 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Apr 08 10:31:46 volumio volumio[3715]: info: CoreCommandRouter::volumioGetBrowseSources
Apr 08 10:31:46 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Apr 08 10:31:47 volumio volumio[3715]: info: CoreCommandRouter::volumioGetState
Apr 08 10:31:47 volumio volumio[3715]: info: CorePlayQueue::getTrack 0
Apr 08 10:31:47 volumio volumio[3715]: error: MyVolumio Plugin failed to authenticate in a timely fashion
Apr 08 10:31:47 volumio volumio[3715]: info: Completed starting MyVolumio Plugin
Apr 08 10:31:47 volumio volumio[3715]: info: BOOT COMPLETED
Apr 08 10:31:47 volumio volumio[3715]: [Metrics] CommandRouter: 73s 663.92ms
Apr 08 10:31:47 volumio volumio[3715]: info: CoreCommandRouter::volumiosetStartupVolume
Apr 08 10:31:47 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 08 10:31:47 volumio volumio[3715]: info: VolumeController:: Setting startup Volume 100
Apr 08 10:31:47 volumio volumio[3715]: info: VolumeController::SetAlsaVolume100
Apr 08 10:31:47 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 08 10:31:47 volumio volumio[3715]: info: CoreCommandRouter::Close All Modals sent
Apr 08 10:31:47 volumio volumio[3715]: info: CoreCommandRouter::Close All Modals sent
Apr 08 10:31:47 volumio volumio[3715]: info: CoreCommandRouter::volumioGetState
Apr 08 10:31:47 volumio volumio[3715]: info: CorePlayQueue::getTrack 0
Apr 08 10:31:47 volumio volumio[3715]: info: CoreStateMachine::pushState
Apr 08 10:31:47 volumio volumio[3715]: info: CorePlayQueue::getTrack 0
Apr 08 10:31:47 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 08 10:31:47 volumio volumio[3715]: info: CoreCommandRouter::volumioPushState
Apr 08 10:31:47 volumio volumio[3715]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32
Apr 08 10:31:47 volumio volumio[3715]: info: FusionDsp - ---- read samplerate from file: 44100
Apr 08 10:31:47 volumio volumio[3715]: info: camilladsp stopping service pid 4008...
Apr 08 10:31:47 volumio kernel: bcm2835_audio bcm2835_audio: failed to close VCHI service connection (status=1)
Apr 08 10:31:47 volumio volumio[3715]: info: camilladsp service terminated, instance 1
Apr 08 10:31:47 volumio volumio[3715]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Apr 08 10:31:47 volumio volumio[3715]: info: camilladsp service started and running in background, instance 1
Apr 08 10:31:47 volumio volumio[3715]: info: camilladsp spawned new process with pid 4155, instance 1, run: true
Apr 08 10:31:48 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Apr 08 10:31:48 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Apr 08 10:31:48 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Apr 08 10:31:52 volumio volumio[3715]: info: camilladsp spawned new process with pid 4165, instance 1, run: true
Apr 08 10:31:54 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Apr 08 10:31:54 volumio volumio[3715]: info: Preload queue cleared
Apr 08 10:31:56 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Apr 08 10:31:56 volumio volumio[3715]: info: Preload queue cleared
Apr 08 10:31:57 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Apr 08 10:31:58 volumio volumio[3715]: info: Preload queue cleared
Apr 08 10:32:04 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Apr 08 10:32:04 volumio volumio[3715]: info: Preload queue cleared
Apr 08 10:32:04 volumio volumio[3715]: info: Preloading song: upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-29990
Apr 08 10:32:04 volumio volumio[3715]: info: Preloading song: upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-29992
Apr 08 10:32:04 volumio volumio[3715]: info: Preloading song: upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30091
Apr 08 10:32:04 volumio volumio[3715]: info: Preloading song: upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30100
Apr 08 10:32:04 volumio volumio[3715]: info: Preloading song: upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30102
Apr 08 10:32:04 volumio volumio[3715]: info: Preloading song: upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30093
Apr 08 10:32:04 volumio volumio[3715]: info: Preloading song: upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30101
Apr 08 10:32:04 volumio volumio[3715]: info: Preloading song: upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30092
Apr 08 10:32:04 volumio volumio[3715]: info: Preloading song: upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30104
Apr 08 10:32:04 volumio volumio[3715]: info: Preloading song: upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30095
Apr 08 10:32:04 volumio volumio[3715]: info: Preloading song: upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30103
Apr 08 10:32:04 volumio volumio[3715]: info: Preloading song: upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30094
Apr 08 10:32:04 volumio volumio[3715]: info: Preloading song: upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30106
Apr 08 10:32:04 volumio volumio[3715]: info: Preloading song: upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30099
Apr 08 10:32:04 volumio volumio[3715]: info: Preloading song: upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30097
Apr 08 10:32:04 volumio volumio[3715]: info: Preloading song: upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30105
Apr 08 10:32:04 volumio volumio[3715]: info: Preloading song: upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30108
Apr 08 10:32:04 volumio volumio[3715]: info: Preloading song: upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30098
Apr 08 10:32:04 volumio volumio[3715]: info: Preloading song: upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30096
Apr 08 10:32:04 volumio volumio[3715]: info: Preloading song: upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30107
Apr 08 10:32:04 volumio volumio[3715]: info: Preloading song: upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30056
Apr 08 10:32:04 volumio volumio[3715]: info: Preloading song: upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-29014
Apr 08 10:32:04 volumio volumio[3715]: info: Preloading song: upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30342
Apr 08 10:32:04 volumio volumio[3715]: info: Preloading song: upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30326
Apr 08 10:32:04 volumio volumio[3715]: info: Exploding uri upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-29990 in service upnp_browser
Apr 08 10:32:04 volumio volumio[3715]: info: Exploding uri upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-29992 in service upnp_browser
Apr 08 10:32:05 volumio volumio[3715]: info: Exploding uri upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30091 in service upnp_browser
Apr 08 10:32:05 volumio volumio[3715]: info: Exploding uri upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30100 in service upnp_browser
Apr 08 10:32:05 volumio volumio[3715]: info: Exploding uri upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30102 in service upnp_browser
Apr 08 10:32:05 volumio volumio[3715]: info: Exploding uri upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30093 in service upnp_browser
Apr 08 10:32:05 volumio volumio[3715]: info: Exploding uri upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30101 in service upnp_browser
Apr 08 10:32:05 volumio volumio[3715]: info: Exploding uri upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30092 in service upnp_browser
Apr 08 10:32:05 volumio volumio[3715]: info: Exploding uri upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30104 in service upnp_browser
Apr 08 10:32:05 volumio volumio[3715]: info: Exploding uri upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30095 in service upnp_browser
Apr 08 10:32:05 volumio volumio[3715]: info: Exploding uri upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30103 in service upnp_browser
Apr 08 10:32:05 volumio volumio[3715]: info: Exploding uri upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30094 in service upnp_browser
Apr 08 10:32:05 volumio volumio[3715]: info: Exploding uri upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30106 in service upnp_browser
Apr 08 10:32:05 volumio volumio[3715]: info: Exploding uri upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30099 in service upnp_browser
Apr 08 10:32:05 volumio volumio[3715]: info: Exploding uri upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30097 in service upnp_browser
Apr 08 10:32:05 volumio volumio[3715]: info: Exploding uri upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30105 in service upnp_browser
Apr 08 10:32:05 volumio volumio[3715]: info: Exploding uri upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30108 in service upnp_browser
Apr 08 10:32:05 volumio volumio[3715]: info: Exploding uri upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30098 in service upnp_browser
Apr 08 10:32:05 volumio volumio[3715]: info: Exploding uri upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30096 in service upnp_browser
Apr 08 10:32:05 volumio volumio[3715]: info: Exploding uri upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30107 in service upnp_browser
Apr 08 10:32:05 volumio volumio[3715]: info: Exploding uri upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30056 in service upnp_browser
Apr 08 10:32:05 volumio volumio[3715]: info: Exploding uri upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-29014 in service upnp_browser
Apr 08 10:32:05 volumio volumio[3715]: info: Exploding uri upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30342 in service upnp_browser
Apr 08 10:32:06 volumio volumio[3715]: info: Exploding uri upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30326 in service upnp_browser
Apr 08 10:32:15 volumio volumio[3715]: info: Preload queue cleared
Apr 08 10:32:15 volumio volumio[3715]: info: CoreCommandRouter::volumioReplaceandPlayItems
Apr 08 10:32:15 volumio volumio[3715]: info: CoreStateMachine::ClearQueue
Apr 08 10:32:15 volumio volumio[3715]: info: CoreStateMachine::stop
Apr 08 10:32:15 volumio volumio[3715]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 08 10:32:15 volumio volumio[3715]: info: CorePlayQueue::clearPlayQueue
Apr 08 10:32:15 volumio volumio[3715]: info: CorePlayQueue::saveQueue
Apr 08 10:32:15 volumio volumio[3715]: info: CoreCommandRouter::volumioPushQueue
Apr 08 10:32:15 volumio volumio[3715]: info: CoreStateMachine::addQueueItems
Apr 08 10:32:15 volumio volumio[3715]: info: CorePlayQueue::addQueueItems
Apr 08 10:32:15 volumio volumio[3715]: info: Preload queue cleared
Apr 08 10:32:15 volumio volumio[3715]: info: Adding Item to queue: upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-29990
Apr 08 10:32:15 volumio volumio[3715]: info: Using cached record of: upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-29990
Apr 08 10:32:15 volumio volumio[3715]: info: Adding Item to queue: upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-29992
Apr 08 10:32:15 volumio volumio[3715]: info: Using cached record of: upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-29992
Apr 08 10:32:15 volumio volumio[3715]: info: Adding Item to queue: upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30091
Apr 08 10:32:15 volumio volumio[3715]: info: Using cached record of: upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30091
Apr 08 10:32:15 volumio volumio[3715]: info: Adding Item to queue: upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30100
Apr 08 10:32:15 volumio volumio[3715]: info: Using cached record of: upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30100
Apr 08 10:32:15 volumio volumio[3715]: info: Adding Item to queue: upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30102
Apr 08 10:32:15 volumio volumio[3715]: info: Using cached record of: upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30102
Apr 08 10:32:15 volumio volumio[3715]: info: Adding Item to queue: upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30093
Apr 08 10:32:15 volumio volumio[3715]: info: Using cached record of: upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30093
Apr 08 10:32:15 volumio volumio[3715]: info: Adding Item to queue: upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30101
Apr 08 10:32:15 volumio volumio[3715]: info: Using cached record of: upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30101
Apr 08 10:32:15 volumio volumio[3715]: info: Adding Item to queue: upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30092
Apr 08 10:32:15 volumio volumio[3715]: info: Using cached record of: upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30092
Apr 08 10:32:15 volumio volumio[3715]: info: Adding Item to queue: upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30104
Apr 08 10:32:15 volumio volumio[3715]: info: Using cached record of: upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30104
Apr 08 10:32:15 volumio volumio[3715]: info: Adding Item to queue: upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30095
Apr 08 10:32:15 volumio volumio[3715]: info: Using cached record of: upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30095
Apr 08 10:32:15 volumio volumio[3715]: info: Adding Item to queue: upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30103
Apr 08 10:32:15 volumio volumio[3715]: info: Using cached record of: upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30103
Apr 08 10:32:15 volumio volumio[3715]: info: Adding Item to queue: upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30094
Apr 08 10:32:15 volumio volumio[3715]: info: Using cached record of: upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30094
Apr 08 10:32:15 volumio volumio[3715]: info: Adding Item to queue: upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30106
Apr 08 10:32:15 volumio volumio[3715]: info: Using cached record of: upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30106
Apr 08 10:32:15 volumio volumio[3715]: info: Adding Item to queue: upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30099
Apr 08 10:32:15 volumio volumio[3715]: info: Using cached record of: upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30099
Apr 08 10:32:15 volumio volumio[3715]: info: Adding Item to queue: upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30097
Apr 08 10:32:15 volumio volumio[3715]: info: Using cached record of: upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30097
Apr 08 10:32:15 volumio volumio[3715]: info: Adding Item to queue: upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30105
Apr 08 10:32:15 volumio volumio[3715]: info: Using cached record of: upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30105
Apr 08 10:32:15 volumio volumio[3715]: info: Adding Item to queue: upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30108
Apr 08 10:32:15 volumio volumio[3715]: info: Using cached record of: upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30108
Apr 08 10:32:15 volumio volumio[3715]: info: Adding Item to queue: upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30098
Apr 08 10:32:15 volumio volumio[3715]: info: Using cached record of: upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30098
Apr 08 10:32:15 volumio volumio[3715]: info: Adding Item to queue: upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30096
Apr 08 10:32:15 volumio volumio[3715]: info: Using cached record of: upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30096
Apr 08 10:32:15 volumio volumio[3715]: info: Adding Item to queue: upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30107
Apr 08 10:32:15 volumio volumio[3715]: info: Using cached record of: upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30107
Apr 08 10:32:15 volumio volumio[3715]: info: Adding Item to queue: upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30056
Apr 08 10:32:15 volumio volumio[3715]: info: Using cached record of: upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30056
Apr 08 10:32:15 volumio volumio[3715]: info: Adding Item to queue: upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-29014
Apr 08 10:32:15 volumio volumio[3715]: info: Using cached record of: upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-29014
Apr 08 10:32:15 volumio volumio[3715]: info: Adding Item to queue: upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30342
Apr 08 10:32:15 volumio volumio[3715]: info: Using cached record of: upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30342
Apr 08 10:32:15 volumio volumio[3715]: info: Adding Item to queue: upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30326
Apr 08 10:32:15 volumio volumio[3715]: info: Using cached record of: upnp/http://192.168.31.202:2869/upnphost/udhisapi.dll?control=uuid:836495e3-c448-4e0f-a3fc-be42c4678130+urn:upnp-org:serviceId:ContentDirectory@4-30326
Apr 08 10:32:15 volumio volumio[3715]: info: CoreCommandRouter::volumioPushQueue
Apr 08 10:32:15 volumio volumio[3715]: info: CorePlayQueue::saveQueue
Apr 08 10:32:15 volumio volumio[3715]: info: CoreStateMachine::updateTrackBlock
Apr 08 10:32:15 volumio volumio[3715]: info: CorePlayQueue::getTrackBlock
Apr 08 10:32:15 volumio volumio[3715]: info: CoreCommandRouter::volumioPlay
Apr 08 10:32:15 volumio volumio[3715]: info: CoreStateMachine::play index 21
Apr 08 10:32:15 volumio volumio[3715]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 08 10:32:15 volumio volumio[3715]: info: CoreStateMachine::stop
Apr 08 10:32:15 volumio volumio[3715]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 08 10:32:15 volumio volumio[3715]: info: CoreStateMachine::play index undefined
Apr 08 10:32:15 volumio volumio[3715]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 08 10:32:15 volumio volumio[3715]: info: CorePlayQueue::getTrack 21
Apr 08 10:32:15 volumio volumio[3715]: info: CoreStateMachine::startPlaybackTimer
Apr 08 10:32:15 volumio volumio[3715]: info: CorePlayQueue::getTrack 21
Apr 08 10:32:15 volumio volumio[3715]: info: [1712561535955] ControllerUPNPBrowser::clearAddPlayTrack
Apr 08 10:32:15 volumio volumio[3715]: verbose: ControllerMpd::sendMpdCommand stop
Apr 08 10:32:15 volumio volumio[3715]: info: sendMpdCommand stop took 13 milliseconds
Apr 08 10:32:15 volumio volumio[3715]: verbose: ControllerMpd::sendMpdCommand clear
Apr 08 10:32:15 volumio volumio[3715]: info:
Apr 08 10:32:15 volumio volumio[3715]: ---------------------------- MPD announces system playlist update
Apr 08 10:32:15 volumio volumio[3715]: info: Ignoring MPD Status Update
Apr 08 10:32:15 volumio volumio[3715]: info: sendMpdCommand clear took 6 milliseconds
Apr 08 10:32:15 volumio volumio[3715]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.31.202:10243/WMPNSSv4/4050842991/0_NC0yOTAxNA"
Apr 08 10:32:15 volumio volumio[3715]: error: updateQueue error: null
Apr 08 10:32:15 volumio volumio[3715]: info: ------------------------------ 10ms
Apr 08 10:32:16 volumio volumio[3715]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.31.202:10243/WMPNSSv4/4050842991/0_NC0yOTAxNA"
Apr 08 10:32:16 volumio volumio[3715]: info:
Apr 08 10:32:16 volumio volumio[3715]: ---------------------------- MPD announces system playlist update
Apr 08 10:32:16 volumio volumio[3715]: info: Ignoring MPD Status Update
Apr 08 10:32:16 volumio volumio[3715]: info: sendMpdCommand add "http://192.168.31.202:10243/WMPNSSv4/4050842991/0_NC0yOTAxNA" took 2 milliseconds
Apr 08 10:32:16 volumio volumio[3715]: info: CoreStateMachine::setConsumeUpdateService mpd
Apr 08 10:32:16 volumio volumio[3715]: verbose: ControllerMpd::sendMpdCommand play
Apr 08 10:32:16 volumio volumio[3715]: info: ------------------------------ 5ms
Apr 08 10:32:16 volumio volumio[3715]: info: sendMpdCommand play took 4 milliseconds
Apr 08 10:32:16 volumio volumio[3715]: info:
Apr 08 10:32:16 volumio volumio[3715]: ---------------------------- MPD announces state update: player
Apr 08 10:32:16 volumio volumio[3715]: info: ControllerMpd::getState
Apr 08 10:32:16 volumio volumio[3715]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 10:32:16 volumio volumio[3715]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32
Apr 08 10:32:16 volumio volumio[3715]: info: FusionDsp - ---- read samplerate from file: 44100
Apr 08 10:32:16 volumio volumio[3715]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Apr 08 10:32:16 volumio volumio[3715]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32
Apr 08 10:32:16 volumio volumio[3715]: info: FusionDsp - ---- read samplerate from file: 44100
Apr 08 10:32:16 volumio volumio[3715]: info: FusionDsp - If filter freq >samplerate/2 then disable it
Apr 08 10:32:16 volumio volumio[3715]: info:
Apr 08 10:32:16 volumio volumio[3715]: ---------------------------- MPD announces state update: player
Apr 08 10:32:16 volumio volumio[3715]: info: sendMpdCommand status took 58 milliseconds
Apr 08 10:32:16 volumio volumio[3715]: info: ControllerMpd::getState
Apr 08 10:32:16 volumio volumio[3715]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 10:32:16 volumio volumio[3715]: verbose: ControllerMpd::parseState
Apr 08 10:32:16 volumio volumio[3715]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 10:32:16 volumio volumio[3715]: info:
Apr 08 10:32:16 volumio volumio[3715]: ---------------------------- MPD announces system playlist update
Apr 08 10:32:16 volumio volumio[3715]: info: Ignoring MPD Status Update
Apr 08 10:32:16 volumio volumio[3715]: info:
Apr 08 10:32:16 volumio volumio[3715]: ---------------------------- MPD announces state update: player
Apr 08 10:32:16 volumio volumio[3715]: info: ControllerMpd::getState
Apr 08 10:32:16 volumio volumio[3715]: verbose: ControllerMpd::sendMpdCommand status
Apr 08 10:32:16 volumio volumio[3715]: info: sendMpdCommand status took 22 milliseconds
Apr 08 10:32:16 volumio volumio[3715]: info: sendMpdCommand playlistinfo took 20 milliseconds
Apr 08 10:32:16 volumio volumio[3715]: verbose: ControllerMpd::parseState
Apr 08 10:32:16 volumio volumio[3715]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 10:32:16 volumio volumio[3715]: verbose: ControllerMpd::parseTrackInfo
Apr 08 10:32:16 volumio volumio[3715]: info: ControllerMpd::pushState
Apr 08 10:32:16 volumio volumio[3715]: info: CoreCommandRouter::servicePushState
Apr 08 10:32:16 volumio volumio[3715]: info: CorePlayQueue::getTrack 21
Apr 08 10:32:16 volumio volumio[3715]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":314,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"A Lover in Berlin","artist":"Kari Bremnes","album":"Audiophile Female Voice","uri":"http://192.168.31.202:10243/WMPNSSv4/4050842991/0_NC0yOTAxNA","trackType":""}
Apr 08 10:32:16 volumio volumio[3715]: verbose: CURRENT POSITION 21
Apr 08 10:32:16 volumio volumio[3715]: info: CoreStateMachine::syncState stateService play
Apr 08 10:32:16 volumio volumio[3715]: info: CoreStateMachine::syncState currentStatus stop
Apr 08 10:32:16 volumio volumio[3715]: info: ------------------------------ 97ms
Apr 08 10:32:16 volumio volumio[3715]: info: ------------------------------ 22ms
Apr 08 10:32:16 volumio volumio[3715]: info: sendMpdCommand status took 20 milliseconds
Apr 08 10:32:16 volumio volumio[3715]: info: sendMpdCommand playlistinfo took 15 milliseconds
Apr 08 10:32:16 volumio volumio[3715]: verbose: ControllerMpd::parseState
Apr 08 10:32:16 volumio volumio[3715]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 08 10:32:16 volumio volumio[3715]: verbose: ControllerMpd::parseTrackInfo
Apr 08 10:32:16 volumio volumio[3715]: info: ControllerMpd::pushState
Apr 08 10:32:16 volumio volumio[3715]: info: CoreCommandRouter::servicePushState
Apr 08 10:32:16 volumio volumio[3715]: info: CorePlayQueue::getTrack 21
Apr 08 10:32:16 volumio volumio[3715]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1601,"duration":314,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"840 Kbps","isStreaming":false,"title":"A Lover in Berlin","artist":"Kari Bremnes","album":"Audiophile Female Voice","uri":"http://192.168.31.202:10243/WMPNSSv4/4050842991/0_NC0yOTAxNA","trackType":""}
Apr 08 10:32:16 volumio volumio[3715]: verbose: CURRENT POSITION 21
Apr 08 10:32:16 volumio volumio[3715]: info: CoreStateMachine::syncState stateService play
Apr 08 10:32:16 volumio volumio[3715]: info: CoreStateMachine::syncState currentStatus play
Apr 08 10:32:16 volumio volumio[3715]: info: Received an update from plugin. extracting info from payload
Apr 08 10:32:16 volumio volumio[3715]: info: CoreStateMachine::pushState
Apr 08 10:32:16 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 08 10:32:16 volumio volumio[3715]: info: CoreCommandRouter::volumioPushState
Apr 08 10:32:16 volumio volumio[3715]: info: CoreStateMachine::pushState
Apr 08 10:32:16 volumio volumio[3715]: info: CoreCommandRouter::volumioPushState
Apr 08 10:32:16 volumio volumio[3715]: info: ------------------------------ 101ms
Apr 08 10:32:16 volumio volumio[3715]: info: sendMpdCommand playlistinfo took 69 milliseconds
Apr 08 10:32:16 volumio volumio[3715]: verbose: ControllerMpd::parseTrackInfo
Apr 08 10:32:16 volumio volumio[3715]: info: ControllerMpd::pushState
Apr 08 10:32:16 volumio volumio[3715]: info: CoreCommandRouter::servicePushState
Apr 08 10:32:16 volumio volumio[3715]: info: CorePlayQueue::getTrack 21
Apr 08 10:32:16 volumio volumio[3715]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1830,"duration":314,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"957 Kbps","isStreaming":false,"title":"A Lover in Berlin","artist":"Kari Bremnes","album":"Audiophile Female Voice","uri":"http://192.168.31.202:10243/WMPNSSv4/4050842991/0_NC0yOTAxNA","trackType":""}
Apr 08 10:32:16 volumio volumio[3715]: verbose: CURRENT POSITION 21
Apr 08 10:32:16 volumio volumio[3715]: info: CoreStateMachine::syncState stateService play
Apr 08 10:32:16 volumio volumio[3715]: info: CoreStateMachine::syncState currentStatus play
Apr 08 10:32:16 volumio volumio[3715]: info: Received an update from plugin. extracting info from payload
Apr 08 10:32:16 volumio volumio[3715]: info: CoreStateMachine::pushState
Apr 08 10:32:16 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 08 10:32:16 volumio volumio[3715]: info: CoreCommandRouter::volumioPushState
Apr 08 10:32:16 volumio volumio[3715]: info: CoreStateMachine::pushState
Apr 08 10:32:16 volumio volumio[3715]: info: CoreCommandRouter::volumioPushState
Apr 08 10:32:16 volumio volumio[3715]: info: ------------------------------ 156ms
Apr 08 10:32:16 volumio volumio[3715]: info: FusionDsp - {"Reload":{"result":"Ok"}}
Apr 08 10:32:16 volumio volumio[3715]: info: FusionDsp - {"Reload":{"result":"Ok"}}
Apr 08 10:32:16 volumio volumio[3715]: info: CoreCommandRouter::volumioGetState
Apr 08 10:32:16 volumio volumio[3715]: info: CoreCommandRouter::volumioGetQueue
Apr 08 10:32:16 volumio volumio[3715]: info: CoreStateMachine::getQueue
Apr 08 10:32:16 volumio volumio[3715]: info: CorePlayQueue::getQueue
Apr 08 10:32:16 volumio volumio[3715]: info: CoreCommandRouter::volumioGetQueue
Apr 08 10:32:16 volumio volumio[3715]: info: CoreStateMachine::getQueue
Apr 08 10:32:16 volumio volumio[3715]: info: CorePlayQueue::getQueue
Apr 08 10:32:16 volumio volumio[3715]: info: CoreCommandRouter::volumioGetQueue
Apr 08 10:32:16 volumio volumio[3715]: info: CoreStateMachine::getQueue
Apr 08 10:32:16 volumio volumio[3715]: info: CorePlayQueue::getQueue
Apr 08 10:32:16 volumio volumio[3715]: info: CoreCommandRouter::volumioGetQueue
Apr 08 10:32:16 volumio volumio[3715]: info: CoreStateMachine::getQueue
Apr 08 10:32:16 volumio volumio[3715]: info: CorePlayQueue::getQueue
Apr 08 10:32:16 volumio volumio[3715]: info: CoreCommandRouter::volumioGetState
Apr 08 10:32:16 volumio volumio[3715]: info: touch_display: Setting screensaver timeout to 0 seconds.
Apr 08 10:32:20 volumio volumio[3715]: info: VolumeController::SetAlsaVolume68
Apr 08 10:32:20 volumio volumio[3715]: info: CoreCommandRouter::volumioGetState
Apr 08 10:32:20 volumio volumio[3715]: info: CoreStateMachine::pushState
Apr 08 10:32:20 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 08 10:32:20 volumio volumio[3715]: info: CoreCommandRouter::volumioPushState
Apr 08 10:32:20 volumio volumio[3715]: info: CoreCommandRouter::volumioGetQueue
Apr 08 10:32:20 volumio volumio[3715]: info: CoreStateMachine::getQueue
Apr 08 10:32:20 volumio volumio[3715]: info: CorePlayQueue::getQueue
Apr 08 10:32:20 volumio volumio[3715]: info: VolumeController::SetAlsaVolume71
Apr 08 10:32:20 volumio volumio[3715]: info: CoreCommandRouter::volumioGetState
Apr 08 10:32:20 volumio volumio[3715]: info: CoreStateMachine::pushState
Apr 08 10:32:20 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 08 10:32:20 volumio volumio[3715]: info: CoreCommandRouter::volumioPushState
Apr 08 10:32:20 volumio volumio[3715]: info: CoreCommandRouter::volumioGetQueue
Apr 08 10:32:20 volumio volumio[3715]: info: CoreStateMachine::getQueue
Apr 08 10:32:20 volumio volumio[3715]: info: CorePlayQueue::getQueue
Apr 08 10:32:21 volumio volumio[3715]: info: VolumeController::SetAlsaVolume81
Apr 08 10:32:21 volumio volumio[3715]: info: CoreCommandRouter::volumioGetState
Apr 08 10:32:21 volumio volumio[3715]: info: CoreStateMachine::pushState
Apr 08 10:32:21 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 08 10:32:21 volumio volumio[3715]: info: CoreCommandRouter::volumioPushState
Apr 08 10:32:21 volumio volumio[3715]: info: CoreCommandRouter::volumioGetQueue
Apr 08 10:32:21 volumio volumio[3715]: info: CoreStateMachine::getQueue
Apr 08 10:32:21 volumio volumio[3715]: info: CorePlayQueue::getQueue
Apr 08 10:32:21 volumio volumio[3715]: info: VolumeController::SetAlsaVolume82
Apr 08 10:32:21 volumio volumio[3715]: info: CoreCommandRouter::volumioGetState
Apr 08 10:32:21 volumio volumio[3715]: info: CoreStateMachine::pushState
Apr 08 10:32:21 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 08 10:32:21 volumio volumio[3715]: info: CoreCommandRouter::volumioPushState
Apr 08 10:32:21 volumio volumio[3715]: info: CoreCommandRouter::volumioGetQueue
Apr 08 10:32:21 volumio volumio[3715]: info: CoreStateMachine::getQueue
Apr 08 10:32:21 volumio volumio[3715]: info: CorePlayQueue::getQueue
Apr 08 10:32:28 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Apr 08 10:32:31 volumio volumio[3715]: info: Preload queue cleared
Apr 08 10:32:41 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Apr 08 10:32:42 volumio volumio[3715]: info: Preload queue cleared
Apr 08 10:32:42 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Apr 08 10:32:43 volumio volumio[3715]: info: Preload queue cleared
Apr 08 10:32:51 volumio volumio[3715]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri
Apr 08 10:32:51 volumio volumio[3715]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 08 10:32:51 volumio volumio[3715]: TypeError: Cannot read property 'length' of undefined
Apr 08 10:32:51 volumio volumio[3715]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25
Apr 08 10:32:51 volumio volumio[3715]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13
Apr 08 10:32:51 volumio volumio[3715]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18)
Apr 08 10:32:51 volumio volumio[3715]: at Parser.emit (events.js:315:20)
Apr 08 10:32:51 volumio volumio[3715]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26)
Apr 08 10:32:51 volumio volumio[3715]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35)
Apr 08 10:32:51 volumio volumio[3715]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5)
Apr 08 10:32:51 volumio volumio[3715]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7)
Apr 08 10:32:51 volumio volumio[3715]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13)
Apr 08 10:32:51 volumio volumio[3715]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31)
Apr 08 10:32:51 volumio volumio[3715]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59)
Apr 08 10:32:51 volumio volumio[3715]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19)
Apr 08 10:32:51 volumio volumio[3715]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15)
Apr 08 10:32:51 volumio volumio[3715]: at IncomingMessage.emit (events.js:327:22)
Apr 08 10:32:51 volumio volumio[3715]: at endReadableNT (internal/streams/readable.js:1327:12)
Apr 08 10:32:51 volumio volumio[3715]: at processTicksAndRejections (internal/process/task_queues.js:80:21)
Apr 08 10:32:51 volumio volumio[3715]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 08 10:32:52 volumio sudo[4243]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-08 10:31
Apr 08 10:32:52 volumio sudo[4243]: pam_unix(sudo:session): session opened for user root by (uid=0)
PRETTY_NAME="Raspbian GNU/Linux 10 (buster)"
NAME="Raspbian GNU/Linux"
VERSION_ID="10"
VERSION="10 (buster)"
VERSION_CODENAME=buster
ID=raspbian
ID_LIKE=debian
HOME_URL="http://www.raspbian.org/"
SUPPORT_URL="http://www.raspbian.org/RaspbianForums"
BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs"
VOLUMIO_BUILD_VERSION="7f9f1bf001add7a8b19a4516e3c74ef598b2730d"
VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71"
VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1"
VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET"
VOLUMIO_VERSION="3.631"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"