-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Sun 2025-11-02 12:25:27 CET. -- Nov 02 12:24:00 volumio volumio[3179]: info: FusionDsp - AAAAAAAAAAAAAA-> 22050 44100 48000 88200 96000 176400 192000 <-AAAAAAAAAAAAA Nov 02 12:24:00 volumio volumio[3179]: info: FusionDsp loaded Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 12:24:00 volumio go-librespot[3412]: time="2025-11-02T12:24:00+01:00" level=info msg="authenticated AP" username="an***75" Nov 02 12:24:00 volumio sudo[3456]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Nov 02 12:24:00 volumio sudo[3456]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:24:00 volumio volumio[3179]: info: FusionDsp - Reporting Fusion DSP Enabled Nov 02 12:24:00 volumio volumio[3179]: info: Adding Signal Path Element [object Object] Nov 02 12:24:00 volumio volumio[3179]: info: Adding fusiondspeq DSP Signal Path Element Nov 02 12:24:00 volumio volumio[3179]: info: FusionDsp - ---- installed callbackRead Nov 02 12:24:00 volumio volumio[3179]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 02 12:24:00 volumio volumio[3179]: info: FusionDsp - Loudness is ON true Nov 02 12:24:00 volumio sudo[3456]: pam_unix(sudo:session): session closed for user root Nov 02 12:24:00 volumio volumio[3179]: info: Asound.conf file unchanged, so no further update is needed Nov 02 12:24:00 volumio volumio[3179]: info: Output device has changed, restarting MPD Nov 02 12:24:00 volumio sudo[3459]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 02 12:24:00 volumio sudo[3459]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:24:00 volumio sudo[3459]: pam_unix(sudo:session): session closed for user root Nov 02 12:24:00 volumio volumio[3179]: info: Output device has changed, restarting Shairport Sync Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:24:00 volumio sudo[3462]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 02 12:24:00 volumio sudo[3462]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:24:00 volumio systemd[1]: Stopping Music Player Daemon... Nov 02 12:24:00 volumio systemd[1]: mpd.service: Succeeded. Nov 02 12:24:00 volumio systemd[1]: Stopped Music Player Daemon. Nov 02 12:24:00 volumio volumio[3179]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 02 12:24:00 volumio volumio[3179]: info: Preparing to generate the ALSA configuration file Nov 02 12:24:00 volumio volumio[3179]: error: updateQueue error: null Nov 02 12:24:00 volumio volumio[3179]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Nov 02 12:24:00 volumio volumio[3179]: info: touch_display: Volumio Kiosk started. Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::volumioGetState Nov 02 12:24:00 volumio volumio[3179]: info: CorePlayQueue::getTrack 0 Nov 02 12:24:00 volumio volumio[3179]: info: Completed starting Core Plugins Nov 02 12:24:00 volumio volumio[3179]: info: ------------------------------------------- Nov 02 12:24:00 volumio volumio[3179]: info: ----- MyVolumio plugins startup ---- Nov 02 12:24:00 volumio volumio[3179]: info: ------------------------------------------- Nov 02 12:24:00 volumio volumio[3179]: info: [MyVolumio PluginManager] Fetching plans data.... Nov 02 12:24:00 volumio volumio[3179]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Nov 02 12:24:00 volumio volumio[3179]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Nov 02 12:24:00 volumio volumio[3179]: info: Reading ALSA contributions from plugins. Nov 02 12:24:00 volumio go-librespot[3412]: time="2025-11-02T12:24:00+01:00" level=info msg="authenticated Login5" username="an***75" Nov 02 12:24:00 volumio go-librespot[3412]: time="2025-11-02T12:24:00+01:00" level=debug msg="initializing zeroconf session" username="an***75" Nov 02 12:24:00 volumio volumio[3179]: error: MPD error: Error: write EPIPE Nov 02 12:24:00 volumio volumio[3179]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Nov 02 12:24:00 volumio systemd[1]: Starting Music Player Daemon... Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::volumioGetState Nov 02 12:24:00 volumio volumio[3179]: info: CorePlayQueue::getTrack 0 Nov 02 12:24:00 volumio volumio[3179]: info: MPD Permissions set Nov 02 12:24:00 volumio volumio[3179]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Nov 02 12:24:00 volumio volumio[3179]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 02 12:24:00 volumio volumio[3179]: info: touch_display: X display number found: 0 Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:24:00 volumio sudo[3467]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 02 12:24:00 volumio sudo[3467]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:24:00 volumio sudo[3467]: pam_unix(sudo:session): session closed for user root Nov 02 12:24:00 volumio volumio[3179]: info: Starting Shairport Sync Nov 02 12:24:00 volumio go-librespot[3412]: time="2025-11-02T12:24:00+01:00" level=debug msg="dealer connection opened" Nov 02 12:24:00 volumio go-librespot[3412]: time="2025-11-02T12:24:00+01:00" level=trace msg="starting accesspoint recv loop" Nov 02 12:24:00 volumio go-librespot[3412]: time="2025-11-02T12:24:00+01:00" level=trace msg="starting dealer recv loop" Nov 02 12:24:00 volumio go-librespot[3412]: time="2025-11-02T12:24:00+01:00" level=trace msg="received accesspoint ping" Nov 02 12:24:00 volumio go-librespot[3412]: time="2025-11-02T12:24:00+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Nov 02 12:24:00 volumio go-librespot[3412]: time="2025-11-02T12:24:00+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Nov 02 12:24:00 volumio go-librespot[3412]: time="2025-11-02T12:24:00+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Nov 02 12:24:00 volumio go-librespot[3412]: time="2025-11-02T12:24:00+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Nov 02 12:24:00 volumio go-librespot[3412]: time="2025-11-02T12:24:00+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Nov 02 12:24:00 volumio go-librespot[3412]: time="2025-11-02T12:24:00+01:00" level=debug msg="received connection id: YTJmNjQwNmMtYjA4...MDgyM0FENTI3RQ==" Nov 02 12:24:00 volumio sudo[3478]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 02 12:24:00 volumio sudo[3478]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:24:00 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 02 12:24:00 volumio systemd[1]: shairport-sync.service: Succeeded. Nov 02 12:24:00 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 02 12:24:00 volumio go-librespot[3412]: time="2025-11-02T12:24:00+01:00" level=trace msg="received accesspoint pong ack" Nov 02 12:24:00 volumio volumio[3179]: SPOTIFY: User informations: {"country":"PL","display_name":"anzio75","email":"anzio75@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/anzio75"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/anzio75","id":"anzio75","images":[],"product":"free","type":"user","uri":"spotify:user:anzio75"} Nov 02 12:24:00 volumio volumio[3179]: info: Spotify Successfully logged in Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 12:24:00 volumio volumio[3179]: info: [1762082640460] CoreMusicLibrary::Adding element Spotify Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 12:24:00 volumio volumio[3179]: Cannot find translation for source YouTube Music Nov 02 12:24:00 volumio volumio[3179]: Cannot find translation for source 80s80s Radio Nov 02 12:24:00 volumio volumio[3179]: Cannot find translation for source Spotify Nov 02 12:24:00 volumio volumio[3179]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Nov 02 12:24:00 volumio volumio[3179]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Nov 02 12:24:00 volumio volumio[3179]: info: Asound.conf file unchanged, so no further update is needed Nov 02 12:24:00 volumio volumio[3179]: info: Output device has changed, restarting MPD Nov 02 12:24:00 volumio sudo[3478]: pam_unix(sudo:session): session closed for user root Nov 02 12:24:00 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 02 12:24:00 volumio sudo[3485]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 02 12:24:00 volumio sudo[3485]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:24:00 volumio volumio[3179]: info: Output device has changed, restarting Shairport Sync Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:24:00 volumio go-librespot[3412]: time="2025-11-02T12:24:00+01:00" level=debug msg="put connect state because NEW_DEVICE" Nov 02 12:24:00 volumio sudo[3485]: pam_unix(sudo:session): session closed for user root Nov 02 12:24:00 volumio sudo[3489]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 02 12:24:00 volumio sudo[3489]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:24:00 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Nov 02 12:24:00 volumio systemd[1]: mpd.service: Succeeded. Nov 02 12:24:00 volumio systemd[1]: Stopped Music Player Daemon. Nov 02 12:24:00 volumio volumio[3179]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 02 12:24:00 volumio systemd[1]: Starting Music Player Daemon... Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 02 12:24:00 volumio volumio[3179]: info: MPD Permissions set Nov 02 12:24:00 volumio volumio[3179]: info: touch_display: X display number found: 0 Nov 02 12:24:00 volumio volumio[3179]: info: Shairport-Sync Started Nov 02 12:24:00 volumio sudo[3495]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::volumioGetVisibleSources Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::volumioGetState Nov 02 12:24:00 volumio volumio[3179]: info: CorePlayQueue::getTrack 0 Nov 02 12:24:00 volumio sudo[3495]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 02 12:24:00 volumio volumio[3179]: info: Received Get System Info Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 12:24:00 volumio volumio[3179]: info: Discovery: Getting this device information Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::volumioGetState Nov 02 12:24:00 volumio volumio[3179]: info: CorePlayQueue::getTrack 0 Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::volumioGetState Nov 02 12:24:00 volumio volumio[3179]: info: CorePlayQueue::getTrack 0 Nov 02 12:24:00 volumio sudo[3495]: pam_unix(sudo:session): session closed for user root Nov 02 12:24:00 volumio volumio[3179]: info: Listing playlists Nov 02 12:24:00 volumio volumio[3179]: info: touch_display: Setting screensaver timeout to 120 seconds. Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:24:00 volumio volumio[3179]: info: Starting Shairport Sync Nov 02 12:24:00 volumio sudo[3510]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 02 12:24:00 volumio sudo[3510]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 12:24:00 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 12:24:00 volumio systemd[1]: shairport-sync.service: Succeeded. Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 12:24:00 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 02 12:24:00 volumio volumio[3179]: info: FusionDsp - no preset used Nov 02 12:24:00 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 02 12:24:00 volumio sudo[3510]: pam_unix(sudo:session): session closed for user root Nov 02 12:24:01 volumio volumio[3179]: info: FusionDsp - no preset used Nov 02 12:24:01 volumio volumio[3179]: info: FusionDsp - no preset used Nov 02 12:24:01 volumio volumio[3179]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Nov 02 12:24:01 volumio volumio[3179]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Nov 02 12:24:01 volumio volumio[3179]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Nov 02 12:24:01 volumio volumio[3179]: info: Shairport-Sync Started Nov 02 12:24:01 volumio mpd[3504]: Nov 02 12:24 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 02 12:24:01 volumio systemd[1]: Started Music Player Daemon. Nov 02 12:24:01 volumio sudo[3462]: pam_unix(sudo:session): session closed for user root Nov 02 12:24:01 volumio sudo[3489]: pam_unix(sudo:session): session closed for user root Nov 02 12:24:01 volumio volumio[3179]: error: MPD error: The expression evaluated to a falsy value: Nov 02 12:24:01 volumio volumio[3179]: assert.ok(self.idling) Nov 02 12:24:01 volumio volumio[3179]: error: The expression evaluated to a falsy value: Nov 02 12:24:01 volumio volumio[3179]: assert.ok(self.idling) Nov 02 12:24:01 volumio volumio[3179]: error: updateQueue error: null Nov 02 12:24:01 volumio sudo[3518]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //http://192.168.0.16//music /mnt/NAS/asustor Nov 02 12:24:01 volumio sudo[3518]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:24:01 volumio sudo[3518]: pam_unix(sudo:session): session closed for user root Nov 02 12:24:01 volumio volumio[3179]: info: Cannot mount NAS asustor at system boot, trial number 2 ,retrying in 5 seconds Nov 02 12:24:02 volumio sudo[3537]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 02 12:24:02 volumio sudo[3537]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:24:02 volumio sudo[3537]: pam_unix(sudo:session): session closed for user root Nov 02 12:24:02 volumio sudo[3540]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 02 12:24:02 volumio sudo[3540]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:24:02 volumio sudo[3540]: pam_unix(sudo:session): session closed for user root Nov 02 12:24:02 volumio sudo[3543]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Nov 02 12:24:02 volumio sudo[3543]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:24:02 volumio sudo[3543]: pam_unix(sudo:session): session closed for user root Nov 02 12:24:02 volumio volumio[3179]: info: Upmpdcli Daemon Started Nov 02 12:24:02 volumio volumio[3179]: info: go-librespot daemon successfully initialized Nov 02 12:24:04 volumio volumio[3179]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 12:24:04 volumio volumio[3179]: info: [ASDebug] Setting UI defaults Nov 02 12:24:04 volumio volumio[3179]: info: [ASDebug] Port: 24 Nov 02 12:24:04 volumio volumio[3179]: info: [ASDebug] Inverted: false Nov 02 12:24:04 volumio volumio[3179]: info: [ASDebug] Latched: false Nov 02 12:24:04 volumio volumio[3179]: info: [ASDebug] On pulse width: 500 Nov 02 12:24:04 volumio volumio[3179]: info: [ASDebug] Off pulse width: 500 Nov 02 12:24:04 volumio volumio[3179]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Nov 02 12:24:05 volumio volumio[3179]: info: Initializing connection to go-librespot Websocket Nov 02 12:24:05 volumio go-librespot[3412]: time="2025-11-02T12:24:05+01:00" level=debug msg="new websocket client" Nov 02 12:24:05 volumio volumio[3179]: info: Connection to go-librespot Websocket established Nov 02 12:24:06 volumio sudo[3549]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //http://192.168.0.16//music /mnt/NAS/asustor Nov 02 12:24:06 volumio sudo[3549]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:24:06 volumio sudo[3549]: pam_unix(sudo:session): session closed for user root Nov 02 12:24:06 volumio volumio[3179]: info: Cannot mount NAS asustor at system boot, trial number 3 ,retrying in 5 seconds Nov 02 12:24:08 volumio volumio[3179]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Nov 02 12:24:08 volumio volumio[3179]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Nov 02 12:24:08 volumio volumio[3179]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Nov 02 12:24:08 volumio volumio[3179]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Nov 02 12:24:08 volumio volumio[3179]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Nov 02 12:24:08 volumio volumio[3179]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Nov 02 12:24:08 volumio volumio[3179]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Nov 02 12:24:08 volumio volumio[3179]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Nov 02 12:24:08 volumio volumio[3179]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Nov 02 12:24:08 volumio volumio[3179]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Nov 02 12:24:08 volumio volumio[3179]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Nov 02 12:24:08 volumio volumio[3179]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Nov 02 12:24:08 volumio volumio[3179]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Nov 02 12:24:08 volumio volumio[3179]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Nov 02 12:24:08 volumio volumio[3179]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Nov 02 12:24:08 volumio volumio[3179]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Nov 02 12:24:08 volumio volumio[3179]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Nov 02 12:24:08 volumio volumio[3179]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Nov 02 12:24:08 volumio volumio[3179]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Nov 02 12:24:08 volumio volumio[3179]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Nov 02 12:24:08 volumio volumio[3179]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Nov 02 12:24:08 volumio volumio[3179]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Nov 02 12:24:08 volumio volumio[3179]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Nov 02 12:24:08 volumio volumio[3179]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Nov 02 12:24:08 volumio volumio[3179]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Nov 02 12:24:08 volumio volumio[3179]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Nov 02 12:24:08 volumio volumio[3179]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Nov 02 12:24:08 volumio volumio[3179]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Nov 02 12:24:08 volumio volumio[3179]: info: Adding plugin bluetooth to MyMusic Plugins Nov 02 12:24:08 volumio volumio[3179]: info: Adding plugin multiroom to MyMusic Plugins Nov 02 12:24:08 volumio volumio[3179]: info: Adding plugin metavolumio to MyMusic Plugins Nov 02 12:24:08 volumio volumio[3179]: info: Adding plugin cd_controller to MyMusic Plugins Nov 02 12:24:08 volumio volumio[3179]: info: Adding plugin qobuzconnect to MyMusic Plugins Nov 02 12:24:08 volumio volumio[3179]: info: Adding plugin smart_inputs to MyMusic Plugins Nov 02 12:24:08 volumio volumio[3179]: info: Adding plugin tidalconnect to MyMusic Plugins Nov 02 12:24:08 volumio volumio[3179]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Nov 02 12:24:09 volumio volumio[3179]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Nov 02 12:24:09 volumio volumio[3179]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Nov 02 12:24:09 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:24:09 volumio volumio[3179]: info: Starting MyVolumio Remote Streaming Endpoints Nov 02 12:24:09 volumio volumio[3179]: info: MyVolumio login type: Token Nov 02 12:24:09 volumio volumio[3179]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Nov 02 12:24:09 volumio volumio[3179]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Nov 02 12:24:09 volumio volumio[3179]: info: Starting Streaming Service Transparent Proxy Nov 02 12:24:09 volumio volumio[3179]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Nov 02 12:24:09 volumio volumio[3179]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Nov 02 12:24:09 volumio volumio[3179]: info: Streaming services startup Nov 02 12:24:09 volumio volumio[3179]: info: Starting Streaming Daemon Nov 02 12:24:09 volumio sudo[3560]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 02 12:24:09 volumio sudo[3560]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:24:09 volumio sudo[3560]: pam_unix(sudo:session): session closed for user root Nov 02 12:24:09 volumio volumio[3179]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Nov 02 12:24:09 volumio volumio[3179]: info: Getting Spotify volume Nov 02 12:24:09 volumio volumio[3179]: error: Cannot start Volumio Streaming Daemon Nov 02 12:24:09 volumio volumio[3179]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Nov 02 12:24:09 volumio volumio[3179]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Nov 02 12:24:09 volumio volumio[3179]: info: Spotify volume: 100 Nov 02 12:24:09 volumio volumio[3179]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Nov 02 12:24:09 volumio volumio[3179]: info: CoreCommandRouter::volumioGetState Nov 02 12:24:09 volumio volumio[3179]: info: CorePlayQueue::getTrack 0 Nov 02 12:24:09 volumio volumio[3179]: SPOTIFY: RECEIVED VOLUMIO VOLUME 79 Nov 02 12:24:09 volumio volumio[3179]: SPOTIFY: SPOTIFY VOLUME 100 Nov 02 12:24:09 volumio volumio[3179]: SPOTIFY: VOLUMIO VOLUME 79 Nov 02 12:24:09 volumio volumio[3179]: SPOTIFY: DELTA VOLUME ENOUGH: true Nov 02 12:24:09 volumio volumio[3179]: info: Setting Spotify Volume from Volumio: 79 Nov 02 12:24:10 volumio volumio[3179]: STREAMING PROXY: Starting server on port 3245 Nov 02 12:24:10 volumio volumio[3179]: Node JS runtime: 14 Nov 02 12:24:10 volumio volumio[3179]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Nov 02 12:24:10 volumio volumio[3179]: info: MyVolumio token set successfully Nov 02 12:24:10 volumio volumio[3179]: info: MYVOLUMIO: Adding device Nov 02 12:24:10 volumio volumio[3179]: info: MYVOLUMIO: Evaluating Server Nov 02 12:24:11 volumio volumio[3179]: info: MyVolumio status changed Nov 02 12:24:11 volumio volumio[3179]: info: Streaming services startup Nov 02 12:24:11 volumio volumio[3179]: info: Starting Streaming Daemon Nov 02 12:24:11 volumio volumio[3179]: info: Removing browser output: myVolumio user plan is not superstar Nov 02 12:24:11 volumio volumio[3179]: info: Removing audio output: Nov 02 12:24:11 volumio volumio[3179]: info: Stoppping Tunnel 1 Nov 02 12:24:11 volumio sudo[3585]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 02 12:24:11 volumio sudo[3585]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:24:11 volumio sudo[3585]: pam_unix(sudo:session): session closed for user root Nov 02 12:24:11 volumio volumio[3179]: SPOTIFY: SETTING SPOTIFY VOLUME 79 Nov 02 12:24:11 volumio sudo[3588]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Nov 02 12:24:11 volumio volumio[3179]: info: Sending Spotify command with payload to local API: /player/volume Nov 02 12:24:11 volumio sudo[3588]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:24:11 volumio go-librespot[3412]: time="2025-11-02T12:24:11+01:00" level=debug msg="update volume requested to 51772/65535" Nov 02 12:24:11 volumio volumio[3179]: error: Cannot start Volumio Streaming Daemon Nov 02 12:24:11 volumio volumio[3179]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Nov 02 12:24:11 volumio volumio[3179]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Nov 02 12:24:11 volumio sudo[3588]: pam_unix(sudo:session): session closed for user root Nov 02 12:24:11 volumio volumio[3179]: info: Remote SSH Stopped Nov 02 12:24:11 volumio go-librespot[3412]: time="2025-11-02T12:24:11+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Nov 02 12:24:11 volumio go-librespot[3412]: time="2025-11-02T12:24:11+01:00" level=trace msg="emitting websocket event: volume" Nov 02 12:24:11 volumio volumio[3179]: SPOTIFY: received: {"type":"volume","data":{"value":79,"max":100}} Nov 02 12:24:11 volumio volumio[3179]: SPOTIFY: RECEIVED SPOTIFY VOLUME 79 Nov 02 12:24:11 volumio volumio[3179]: info: Setting Geolocation for MyVolumio to eu9 Nov 02 12:24:11 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:24:11 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:24:11 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:24:11 volumio sudo[3591]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //http://192.168.0.16//music /mnt/NAS/asustor Nov 02 12:24:11 volumio sudo[3591]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:24:11 volumio sudo[3591]: pam_unix(sudo:session): session closed for user root Nov 02 12:24:11 volumio volumio[3179]: info: Cannot mount NAS asustor at system boot, trial number 4 ,retrying in 5 seconds Nov 02 12:24:12 volumio volumio[3179]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Nov 02 12:24:12 volumio volumio[3179]: info: Updating MyVolumio device info Nov 02 12:24:12 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:24:12 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:24:12 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:24:14 volumio volumio[3179]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Nov 02 12:24:16 volumio volumio[3179]: info: MYVOLUMIO: Adding device Nov 02 12:24:16 volumio volumio[3179]: info: MYVOLUMIO: Evaluating Server Nov 02 12:24:16 volumio sudo[3669]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //http://192.168.0.16//music /mnt/NAS/asustor Nov 02 12:24:16 volumio sudo[3669]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:24:16 volumio sudo[3669]: pam_unix(sudo:session): session closed for user root Nov 02 12:24:16 volumio volumio[3179]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Nov 02 12:24:16 volumio volumio[3179]: info: Setting Geolocation for MyVolumio to eu8 Nov 02 12:24:16 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:24:16 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:24:16 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:24:17 volumio volumio[3179]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Nov 02 12:24:17 volumio volumio[3179]: info: Updating MyVolumio device info Nov 02 12:24:17 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:24:17 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:24:17 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:24:18 volumio volumio[3179]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Nov 02 12:24:23 volumio volumio[3179]: info: CALLMETHOD: system_controller ampswitch saveOptions [object Object] Nov 02 12:24:23 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: ampswitch , saveOptions Nov 02 12:24:23 volumio volumio[3179]: info: [ASDebug] Saving Settings: Port: 2 Nov 02 12:24:23 volumio volumio[3179]: info: [ASDebug] Saving Settings: Inverted: false Nov 02 12:24:23 volumio volumio[3179]: info: [ASDebug] Saving Settings: Delay: 720 Nov 02 12:24:23 volumio volumio[3179]: info: [ASDebug] Saving Settings: Latched: false Nov 02 12:24:23 volumio volumio[3179]: info: [ASDebug] Saving Settings: On Pulse width: 500 Nov 02 12:24:23 volumio volumio[3179]: info: [ASDebug] Saving Settings: Off Pulse width: 500 Nov 02 12:24:24 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 02 12:24:24 volumio volumio[3179]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Nov 02 12:24:24 volumio volumio[3179]: info: CoreCommandRouter::volumioGetState Nov 02 12:24:24 volumio volumio[3179]: info: CorePlayQueue::getTrack 0 Nov 02 12:24:27 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 12:24:27 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:24:27 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 02 12:24:27 volumio volumio-remote-updater[644]: No test mode Nov 02 12:24:27 volumio volumio-remote-updater[644]: No alpha test mode Nov 02 12:24:27 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Nov 02 12:24:28 volumio volumio[3179]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Nov 02 12:24:28 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Nov 02 12:24:29 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:24:29 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:24:29 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Nov 02 12:24:29 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Nov 02 12:24:29 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Nov 02 12:24:29 volumio volumio[3179]: info: CoreCommandRouter::volumioGetBrowseSources Nov 02 12:24:29 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 12:24:29 volumio volumio[3179]: error: MyVolumio Plugin failed to authenticate in a timely fashion Nov 02 12:24:29 volumio volumio[3179]: info: Completed starting MyVolumio Plugin Nov 02 12:24:29 volumio volumio[3179]: [Metrics] CommandRouter: 38s 954.76ms Nov 02 12:24:29 volumio volumio[3179]: info: CoreCommandRouter::volumiosetStartupVolume Nov 02 12:24:29 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:24:29 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:24:29 volumio volumio[3179]: info: CoreCommandRouter::Close All Modals sent Nov 02 12:24:29 volumio volumio[3179]: info: CoreCommandRouter::Close All Modals sent Nov 02 12:24:29 volumio volumio[3179]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Nov 02 12:24:29 volumio volumio[3179]: info: FusionDsp - ---- read samplerate from file: 44100 Nov 02 12:24:29 volumio volumio[3179]: info: camilladsp stopping service pid 3445... Nov 02 12:24:29 volumio kernel: bcm2835_audio bcm2835_audio: failed to close VCHI service connection (status=-11) Nov 02 12:24:29 volumio volumio[3179]: info: camilladsp service terminated, instance 1 Nov 02 12:24:29 volumio volumio[3179]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 02 12:24:29 volumio volumio[3179]: info: FusionDsp - Loudness is ON true Nov 02 12:24:29 volumio volumio[3179]: info: camilladsp service started and running in background, instance 1 Nov 02 12:24:29 volumio volumio[3179]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Nov 02 12:24:29 volumio volumio[3179]: info: FusionDsp - ---- read samplerate from file: 44100 Nov 02 12:24:29 volumio volumio[3179]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 02 12:24:29 volumio volumio[3179]: info: FusionDsp - Loudness is ON true Nov 02 12:24:29 volumio volumio[3179]: error: FusionDsp - WebSocket error: [object Object] Nov 02 12:24:30 volumio go-librespot[3412]: time="2025-11-02T12:24:30+01:00" level=trace msg="sent dealer ping" Nov 02 12:24:30 volumio go-librespot[3412]: time="2025-11-02T12:24:30+01:00" level=trace msg="received dealer pong" Nov 02 12:24:30 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 02 12:24:30 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 02 12:24:30 volumio volumio[3179]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 12:24:30 volumio volumio[3179]: info: FusionDsp - no preset used Nov 02 12:24:30 volumio volumio[3179]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Nov 02 12:24:30 volumio volumio[3179]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 12:24:30 volumio volumio[3179]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 12:24:30 volumio volumio[3179]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 12:24:30 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Nov 02 12:24:30 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 02 12:24:30 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Nov 02 12:24:30 volumio volumio[3179]: info: FusionDsp - no preset used Nov 02 12:24:31 volumio volumio[3179]: info: FusionDsp - no preset used Nov 02 12:24:31 volumio volumio[3179]: info: FusionDsp - no preset used Nov 02 12:24:31 volumio volumio[3179]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Nov 02 12:24:31 volumio volumio[3179]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Nov 02 12:24:31 volumio volumio[3179]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Nov 02 12:24:36 volumio volumio[3179]: info: BOOT COMPLETED Nov 02 12:24:44 volumio volumio[3179]: info: Starting Uninstall of plugin system_controller - ampswitch Nov 02 12:24:44 volumio volumio[3179]: info: Uninstalling plugin ampswitch Nov 02 12:24:44 volumio volumio[3179]: info: [ASDebug] Port: 2 Nov 02 12:24:44 volumio volumio[3179]: info: [ASDebug] Inverted: false Nov 02 12:24:44 volumio volumio[3179]: info: [ASDebug] Delay: 720 Nov 02 12:24:53 volumio volumio[3179]: info: [ASDebug] Port: 2 Nov 02 12:24:53 volumio volumio[3179]: info: [ASDebug] Inverted: false Nov 02 12:24:53 volumio volumio[3179]: info: [ASDebug] Delay: 720 Nov 02 12:24:53 volumio volumio[3179]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 12:24:53 volumio volumio[3179]: Error: EBADF: bad file descriptor, close Nov 02 12:24:53 volumio volumio[3179]: at Object.closeSync (fs.js:439:3) Nov 02 12:24:53 volumio volumio[3179]: at Object.closeSync (/volumio/node_modules/graceful-fs/graceful-fs.js:74:20) Nov 02 12:24:53 volumio volumio[3179]: at Object.closeSync (/data/plugins/system_controller/ampswitch/node_modules/graceful-fs/graceful-fs.js:51:27) Nov 02 12:24:53 volumio volumio[3179]: at Gpio.unexport (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:307:8) Nov 02 12:24:53 volumio volumio[3179]: at AmpSwitchController.freeGPIO (/data/plugins/system_controller/ampswitch/index.js:242:19) Nov 02 12:24:53 volumio volumio[3179]: at AmpSwitchController.onStop (/data/plugins/system_controller/ampswitch/index.js:90:10) Nov 02 12:24:53 volumio volumio[3179]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Nov 02 12:24:53 volumio volumio[3179]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8) Nov 02 12:24:53 volumio volumio[3179]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1556:29) Nov 02 12:24:53 volumio volumio[3179]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1401:47) Nov 02 12:24:53 volumio volumio[3179]: at Socket.emit (events.js:315:20) Nov 02 12:24:53 volumio volumio[3179]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Nov 02 12:24:53 volumio volumio[3179]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Nov 02 12:24:53 volumio volumio[3179]: errno: -9, Nov 02 12:24:53 volumio volumio[3179]: syscall: 'close', Nov 02 12:24:53 volumio volumio[3179]: code: 'EBADF' Nov 02 12:24:53 volumio volumio[3179]: } Nov 02 12:24:53 volumio volumio[3179]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 12:24:53 volumio sudo[3780]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-11-02 12:23 Nov 02 12:24:53 volumio sudo[3780]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:24:53 volumio sudo[3780]: pam_unix(sudo:session): session closed for user root Nov 02 12:24:54 volumio volumio-remote-updater[644]: [2025-11-02 12:24:54] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Nov 02 12:24:54 volumio volumio-remote-updater[644]: [2025-11-02 12:24:54] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Nov 02 12:24:54 volumio go-librespot[3412]: time="2025-11-02T12:24:54+01:00" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" Nov 02 12:24:54 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Nov 02 12:24:54 volumio kernel: bcm2835_audio bcm2835_audio: failed to close VCHI service connection (status=-11) Nov 02 12:24:54 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Nov 02 12:24:54 volumio systemd[1]: Started dynamicswap service. Nov 02 12:24:54 volumio systemd[1]: dynamicswap.service: Succeeded. Nov 02 12:24:54 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Nov 02 12:24:54 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 3. Nov 02 12:24:54 volumio systemd[1]: Started dynamicswap service. Nov 02 12:24:54 volumio systemd[1]: Stopped Volumio Backend Module. Nov 02 12:24:54 volumio systemd[1]: Started Volumio Backend Module. Nov 02 12:24:54 volumio systemd[1]: dynamicswap.service: Succeeded. Nov 02 12:24:55 volumio volumio[3794]: info: ------------------------------------------- Nov 02 12:24:55 volumio volumio[3794]: info: ----- Volumio3 ---- Nov 02 12:24:55 volumio volumio[3794]: info: ------------------------------------------- Nov 02 12:24:55 volumio volumio[3794]: info: ----- System startup ---- Nov 02 12:24:55 volumio volumio[3794]: info: ------------------------------------------- Nov 02 12:24:55 volumio volumio[3794]: info: MYVOLUMIO Environment detected Nov 02 12:24:55 volumio volumio[3794]: info: Plugin folders cleanup Nov 02 12:24:55 volumio volumio[3794]: info: Scanning into folder /volumio/app/plugins/ Nov 02 12:24:55 volumio volumio[3794]: info: Scanning category audio_interface Nov 02 12:24:55 volumio volumio[3794]: info: Scanning category miscellanea Nov 02 12:24:55 volumio volumio[3794]: info: Scanning category music_service Nov 02 12:24:55 volumio volumio[3794]: info: Scanning category plugins.json Nov 02 12:24:55 volumio volumio[3794]: info: Scanning category system_controller Nov 02 12:24:55 volumio volumio[3794]: info: Scanning category user_interface Nov 02 12:24:55 volumio volumio[3794]: info: Scanning into folder /data/plugins/ Nov 02 12:24:55 volumio volumio[3794]: info: Scanning category audio_interface Nov 02 12:24:55 volumio volumio[3794]: info: Scanning category music_service Nov 02 12:24:55 volumio volumio[3794]: info: Scanning category system_controller Nov 02 12:24:55 volumio volumio[3794]: info: Scanning category user_interface Nov 02 12:24:55 volumio volumio[3794]: info: Plugin folders cleanup completed Nov 02 12:24:56 volumio volumio[3794]: info: ------------------------------------------- Nov 02 12:24:56 volumio volumio[3794]: info: ----- Core plugins startup ---- Nov 02 12:24:56 volumio volumio[3794]: info: ------------------------------------------- Nov 02 12:24:56 volumio volumio[3794]: info: Loading plugins from folder /volumio/app/plugins/ Nov 02 12:24:56 volumio volumio[3794]: info: Adding plugin upnp to MyMusic Plugins Nov 02 12:24:56 volumio volumio[3794]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 02 12:24:56 volumio volumio[3794]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 02 12:24:56 volumio volumio[3794]: info: Loading plugins from folder /data/plugins/ Nov 02 12:24:56 volumio volumio[3794]: info: Loading plugin "system"... Nov 02 12:24:56 volumio volumio[3794]: info: Loading plugin "ampswitch"... Nov 02 12:24:56 volumio volumio[3794]: info: Applying required configuration parameters for plugin ampswitch Nov 02 12:24:56 volumio volumio[3794]: info: Loading plugin "appearance"... Nov 02 12:24:56 volumio volumio[3794]: info: Loading plugin "network"... Nov 02 12:24:56 volumio volumio[3794]: info: Refreshing Cached IP Addresses Nov 02 12:24:56 volumio sudo[3824]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 02 12:24:56 volumio sudo[3824]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:24:56 volumio sudo[3824]: pam_unix(sudo:session): session closed for user root Nov 02 12:24:56 volumio sudo[3826]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 02 12:24:56 volumio sudo[3826]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:24:56 volumio sudo[3826]: pam_unix(sudo:session): session closed for user root Nov 02 12:24:56 volumio volumio[3794]: info: Loading plugin "services"... Nov 02 12:24:56 volumio volumio[3794]: info: Loading plugin "alsa_controller"... Nov 02 12:24:56 volumio sudo[3835]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 02 12:24:56 volumio sudo[3835]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:24:56 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 12:24:56 volumio volumio[3794]: info: Loading plugin "wizard"... Nov 02 12:24:56 volumio volumio[3794]: info: Loading plugin "networkfs"... Nov 02 12:24:57 volumio volumio[3794]: info: Starting Udev Watcher for removable devices Nov 02 12:24:57 volumio sudo[3852]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //http://192.168.0.16//music /mnt/NAS/asustor Nov 02 12:24:57 volumio sudo[3852]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:24:57 volumio volumio[3794]: info: Ignoring mount for partition: boot Nov 02 12:24:57 volumio volumio[3794]: info: Ignoring mount for partition: volumio Nov 02 12:24:57 volumio volumio[3794]: info: Ignoring mount for partition: volumio_data Nov 02 12:24:57 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 02 12:24:57 volumio volumio[3794]: info: Loading plugin "volumio_command_line_client"... Nov 02 12:24:57 volumio sudo[3852]: pam_unix(sudo:session): session closed for user root Nov 02 12:24:57 volumio volumio[3794]: info: Loading plugin "upnp"... Nov 02 12:24:57 volumio volumio[3794]: info: [1762082697043] Starting Upmpd Daemon Nov 02 12:24:57 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 02 12:24:57 volumio volumio[3794]: info: Loading plugin "my_music"... Nov 02 12:24:57 volumio volumio[3794]: info: Loading plugin "mpd"... Nov 02 12:24:57 volumio volumio[3794]: info: Loading plugin "upnp_browser"... Nov 02 12:24:57 volumio volumio[3794]: info: Loading plugin "alarm-clock"... Nov 02 12:24:57 volumio volumio[3794]: info: Loading plugin "airplay_emulation"... Nov 02 12:24:57 volumio volumio[3794]: info: Starting Shairport Sync Nov 02 12:24:57 volumio volumio[3794]: info: Loading plugin "last_100"... Nov 02 12:24:57 volumio volumio[3794]: info: Loading plugin "webradio"... Nov 02 12:24:57 volumio volumio[3794]: info: Loading plugin "i2s_dacs"... Nov 02 12:24:57 volumio volumio[3794]: info: Loading plugin "volumiodiscovery"... Nov 02 12:24:57 volumio volumio[3794]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 02 12:24:57 volumio volumio[3794]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 12:24:57 volumio volumio[3794]: *** WARNING *** For more information see Nov 02 12:24:57 volumio volumio[3794]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 02 12:24:57 volumio node[3794]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 02 12:24:57 volumio volumio[3794]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 12:24:57 volumio volumio[3794]: *** WARNING *** For more information see Nov 02 12:24:57 volumio node[3794]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 12:24:57 volumio node[3794]: *** WARNING *** For more information see Nov 02 12:24:57 volumio node[3794]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 02 12:24:57 volumio node[3794]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 12:24:57 volumio node[3794]: *** WARNING *** For more information see Nov 02 12:24:57 volumio volumio[3794]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 02 12:24:57 volumio volumio[3794]: info: Discovery: Started advertising with name: Volumio Nov 02 12:24:57 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 02 12:24:57 volumio volumio[3794]: info: Loading plugin "spop"... Nov 02 12:24:58 volumio volumio[3794]: info: Plugin youtube2 is not enabled Nov 02 12:24:58 volumio volumio[3794]: info: Plugin ytcr is not enabled Nov 02 12:24:58 volumio volumio[3794]: info: Loading plugin "ytmusic"... Nov 02 12:24:59 volumio volumio-remote-updater[644]: [2025-11-02 12:24:59] [connect] Successful connection Nov 02 12:24:59 volumio volumio[3794]: info: Loading plugin "outputs"... Nov 02 12:24:59 volumio volumio[3794]: info: Loading plugin "albumart"... Nov 02 12:24:59 volumio volumio[3794]: info: Plugin example_plugin is not enabled Nov 02 12:24:59 volumio volumio[3794]: info: Loading plugin "inputs"... Nov 02 12:25:00 volumio volumio[3794]: info: Loading plugin "updater_comm"... Nov 02 12:25:00 volumio volumio[3794]: info: Plugin mpdemulation is not enabled Nov 02 12:25:00 volumio volumio[3794]: info: Loading plugin "rest_api"... Nov 02 12:25:00 volumio volumio[3794]: info: Loading plugin "websocket"... Nov 02 12:25:00 volumio volumio[3794]: info: Starting Socket.io Server version 2.3.0 Nov 02 12:25:00 volumio volumio[3794]: info: Loading plugin "fusiondsp"... Nov 02 12:25:00 volumio sudo[3835]: pam_unix(sudo:session): session closed for user root Nov 02 12:25:00 volumio volumio[3794]: Forking 3 albumart workers Nov 02 12:25:00 volumio volumio[3794]: info: Applying required configuration parameters for plugin fusiondsp Nov 02 12:25:00 volumio volumio[3794]: info: Plugin mpdoutput is not enabled Nov 02 12:25:00 volumio volumio[3794]: info: Loading plugin "80s80s"... Nov 02 12:25:00 volumio go-librespot[3412]: time="2025-11-02T12:25:00+01:00" level=trace msg="sent dealer ping" Nov 02 12:25:00 volumio go-librespot[3412]: time="2025-11-02T12:25:00+01:00" level=trace msg="received dealer pong" Nov 02 12:25:00 volumio volumio[3794]: info: Applying required configuration parameters for plugin 80s80s Nov 02 12:25:00 volumio volumio[3794]: info: [1762082700592] [80s80s] API delay: 30 Nov 02 12:25:00 volumio volumio[3794]: info: Plugin minidlna is not enabled Nov 02 12:25:00 volumio volumio[3794]: info: Loading plugin "peppyspectrum"... Nov 02 12:25:00 volumio volumio[3794]: Starting albumart workers Nov 02 12:25:00 volumio volumio[3794]: info: Loading plugin "touch_display"... Nov 02 12:25:00 volumio volumio[3794]: Starting albumart workers Nov 02 12:25:00 volumio volumio[3794]: Starting albumart workers Nov 02 12:25:01 volumio volumio[3794]: info: Applying required configuration parameters for plugin touch_display Nov 02 12:25:01 volumio volumio[3794]: info: Loading i18n strings for locale pl Nov 02 12:25:01 volumio volumio[3794]: info: Plugin peppyspectrum has duplicated i18n key APPLY. It is ignored. Nov 02 12:25:01 volumio volumio[3794]: info: Plugin peppyspectrum has duplicated i18n key SAVE. It is ignored. Nov 02 12:25:01 volumio volumio[3794]: info: Plugin peppyspectrum has duplicated i18n key CLICK_TO_UPDATE_LIST. It is ignored. Nov 02 12:25:01 volumio volumio[3794]: info: Plugin peppyspectrum has duplicated i18n key LIST_SUCCESS_UPDATED. It is ignored. Nov 02 12:25:01 volumio volumio[3794]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Nov 02 12:25:01 volumio volumio[3794]: Updating browse sources language Nov 02 12:25:01 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 12:25:01 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 12:25:01 volumio volumio[3794]: info: CoreCommandRouter::initPlayerControls Nov 02 12:25:01 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:25:01 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:25:01 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:25:01 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:25:01 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:25:01 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:25:01 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:25:01 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:25:01 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 12:25:01 volumio volumio[3794]: Express server listening on port 3000 Nov 02 12:25:01 volumio volumio[3794]: [Metrics] WebUI: 6s 107.29ms Nov 02 12:25:01 volumio volumio[3794]: info: CoreStateMachine::resetVolumioState Nov 02 12:25:01 volumio volumio[3794]: info: CoreStateMachine::getcurrentVolume Nov 02 12:25:01 volumio volumio[3794]: info: CoreCommandRouter::volumioRetrievevolume Nov 02 12:25:01 volumio volumio[3794]: info: Volumio Network Manager: Network status updated: 2 Nov 02 12:25:01 volumio volumio[3794]: info: Cannot mount NAS asustor at system boot, trial number 1 ,retrying in 5 seconds Nov 02 12:25:01 volumio volumio[3794]: verbose: New Socket.io Connection to 192.168.0.111 from 192.168.0.164 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/141.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Nov 02 12:25:01 volumio volumio[3794]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Nov 02 12:25:01 volumio volumio[3794]: verbose: New Socket.io Connection to 192.168.0.111 from 192.168.0.164 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/141.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Nov 02 12:25:01 volumio volumio[3794]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Nov 02 12:25:01 volumio volumio-remote-updater[644]: [2025-11-02 12:25:01] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1762082699 101 Nov 02 12:25:01 volumio volumio[3794]: 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: 5 Nov 02 12:25:01 volumio volumio[3794]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Nov 02 12:25:01 volumio volumio[3794]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Nov 02 12:25:01 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:25:01 volumio volumio[3794]: info: Reloading queue from file Nov 02 12:25:01 volumio volumio[3794]: info: CoreStateMachine::setRepeat false single undefined Nov 02 12:25:01 volumio volumio[3794]: info: CoreStateMachine::pushState Nov 02 12:25:01 volumio volumio[3794]: info: CorePlayQueue::getTrack 0 Nov 02 12:25:01 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 12:25:01 volumio volumio[3794]: info: CoreCommandRouter::volumioPushState Nov 02 12:25:01 volumio volumio[3794]: info: CoreStateMachine::setRandom false Nov 02 12:25:01 volumio volumio[3794]: info: CoreStateMachine::pushState Nov 02 12:25:01 volumio volumio[3794]: info: CorePlayQueue::getTrack 0 Nov 02 12:25:01 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 12:25:01 volumio volumio[3794]: info: CoreCommandRouter::volumioPushState Nov 02 12:25:01 volumio volumio[3794]: info: Setting Device type: Raspberry PI Nov 02 12:25:01 volumio volumio[3794]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Nov 02 12:25:01 volumio volumio[3794]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03112 Nov 02 12:25:01 volumio volumio[3794]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Nov 02 12:25:01 volumio volumio[3794]: info: VolumeController:: Volume=79 Mute =false Nov 02 12:25:01 volumio volumio[3794]: info: CoreStateMachine::pushState Nov 02 12:25:01 volumio volumio[3794]: info: CorePlayQueue::getTrack 0 Nov 02 12:25:01 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 12:25:01 volumio volumio[3794]: info: CoreCommandRouter::volumioPushState Nov 02 12:25:01 volumio volumio[3794]: info: CoreStateMachine::updateTrackBlock Nov 02 12:25:01 volumio volumio[3794]: info: CorePlayQueue::getTrackBlock Nov 02 12:25:01 volumio volumio[3794]: info: CoreCommandRouter::volumioRetrievevolume Nov 02 12:25:01 volumio volumio[3794]: info: Discovery: adding b4632d2c-ae2b-4b74-93e2-04ef0f1277cd Nov 02 12:25:01 volumio volumio[3794]: info: Discovery: Found device Volumio Nov 02 12:25:01 volumio volumio[3794]: info: CoreCommandRouter::volumioGetState Nov 02 12:25:01 volumio volumio[3794]: info: CorePlayQueue::getTrack 0 Nov 02 12:25:01 volumio volumio[3794]: info: Completed loading Core Plugins Nov 02 12:25:01 volumio volumio[3794]: info: Preparing to generate the ALSA configuration file Nov 02 12:25:01 volumio volumio[3794]: info: VolumeController:: Volume=79 Mute =false Nov 02 12:25:01 volumio volumio[3794]: info: CoreStateMachine::pushState Nov 02 12:25:01 volumio volumio[3794]: info: CorePlayQueue::getTrack 0 Nov 02 12:25:01 volumio volumio[3794]: info: CoreCommandRouter::volumioPushState Nov 02 12:25:01 volumio volumio[3794]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Nov 02 12:25:01 volumio volumio[3794]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Nov 02 12:25:01 volumio volumio[3794]: info: Reading ALSA contributions from plugins. Nov 02 12:25:01 volumio volumio[3794]: info: Asound.conf file unchanged, so no further update is needed Nov 02 12:25:01 volumio volumio[3794]: info: Output device has changed, restarting MPD Nov 02 12:25:01 volumio sudo[3923]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 02 12:25:01 volumio sudo[3923]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:25:01 volumio volumio[3794]: info: Output device has changed, restarting Shairport Sync Nov 02 12:25:01 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:25:01 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:25:01 volumio sudo[3923]: pam_unix(sudo:session): session closed for user root Nov 02 12:25:01 volumio sudo[3926]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 02 12:25:01 volumio sudo[3926]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:25:01 volumio systemd[1]: Stopping Music Player Daemon... Nov 02 12:25:01 volumio volumio[3794]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 02 12:25:01 volumio volumio[3794]: info: ___________ START PLUGINS ___________ Nov 02 12:25:01 volumio systemd[1]: mpd.service: Succeeded. Nov 02 12:25:01 volumio systemd[1]: Stopped Music Player Daemon. Nov 02 12:25:01 volumio volumio[3794]: info: ControllerMpd::onStart: Initializing MPD Nov 02 12:25:01 volumio volumio[3794]: info: Creating MPD Configuration file Nov 02 12:25:01 volumio systemd[1]: Starting Music Player Daemon... Nov 02 12:25:01 volumio sudo[3946]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 02 12:25:01 volumio sudo[3946]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:25:01 volumio sudo[3946]: pam_unix(sudo:session): session closed for user root Nov 02 12:25:01 volumio sudo[3949]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 02 12:25:01 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 12:25:01 volumio sudo[3949]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:25:01 volumio volumio[3794]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 12:25:01 volumio volumio[3794]: info: [1762082701988] CoreMusicLibrary::Adding element Serwery Mediów Nov 02 12:25:01 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 12:25:02 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Nov 02 12:25:02 volumio systemd[1]: mpd.service: Succeeded. Nov 02 12:25:02 volumio systemd[1]: Stopped Music Player Daemon. Nov 02 12:25:02 volumio systemd[1]: Starting Music Player Daemon... Nov 02 12:25:02 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:25:02 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:25:02 volumio volumio[3794]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 02 12:25:02 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:25:02 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:25:02 volumio sudo[3951]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 02 12:25:02 volumio sudo[3951]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:25:02 volumio sudo[3951]: pam_unix(sudo:session): session closed for user root Nov 02 12:25:02 volumio volumio[3794]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 02 12:25:02 volumio volumio[3794]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 12:25:02 volumio volumio[3794]: info: [1762082702099] CoreMusicLibrary::Adding element Last_100 Nov 02 12:25:02 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 12:25:02 volumio volumio[3794]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 12:25:02 volumio volumio[3794]: info: [1762082702101] CoreMusicLibrary::Adding element Webradio Nov 02 12:25:02 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 12:25:02 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 12:25:02 volumio volumio[3794]: info: Initializing BBC Radios Nov 02 12:25:02 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 12:25:02 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:25:02 volumio volumio[3794]: info: Creating Spotify config file Nov 02 12:25:02 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:25:02 volumio volumio[3794]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 12:25:02 volumio volumio[3794]: info: [1762082702239] CoreMusicLibrary::Adding element YouTube Music Nov 02 12:25:02 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 12:25:02 volumio volumio[3794]: Cannot find translation for source YouTube Music Nov 02 12:25:02 volumio volumio[3794]: info: Loading i18n strings for locale pl Nov 02 12:25:02 volumio volumio[3794]: info: Plugin peppyspectrum has duplicated i18n key APPLY. It is ignored. Nov 02 12:25:02 volumio volumio[3794]: info: Plugin peppyspectrum has duplicated i18n key SAVE. It is ignored. Nov 02 12:25:02 volumio volumio[3794]: info: Plugin peppyspectrum has duplicated i18n key CLICK_TO_UPDATE_LIST. It is ignored. Nov 02 12:25:02 volumio volumio[3794]: info: Plugin peppyspectrum has duplicated i18n key LIST_SUCCESS_UPDATED. It is ignored. Nov 02 12:25:02 volumio volumio[3794]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Nov 02 12:25:02 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Nov 02 12:25:02 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:25:02 volumio volumio[3794]: info: FusionDsp - mixtype--------------------- Hardware Nov 02 12:25:02 volumio volumio[3794]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 12:25:02 volumio volumio[3794]: info: [1762082702261] CoreMusicLibrary::Adding element 80s80s Radio Nov 02 12:25:02 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 12:25:02 volumio volumio[3794]: Cannot find translation for source YouTube Music Nov 02 12:25:02 volumio volumio[3794]: Cannot find translation for source 80s80s Radio Nov 02 12:25:02 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Nov 02 12:25:02 volumio volumio[3794]: info: Loading i18n strings for locale pl Nov 02 12:25:02 volumio volumio[3794]: info: Plugin peppyspectrum has duplicated i18n key APPLY. It is ignored. Nov 02 12:25:02 volumio volumio[3794]: info: Plugin peppyspectrum has duplicated i18n key SAVE. It is ignored. Nov 02 12:25:02 volumio volumio[3794]: info: Plugin peppyspectrum has duplicated i18n key CLICK_TO_UPDATE_LIST. It is ignored. Nov 02 12:25:02 volumio volumio[3794]: info: Plugin peppyspectrum has duplicated i18n key LIST_SUCCESS_UPDATED. It is ignored. Nov 02 12:25:02 volumio volumio[3794]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Nov 02 12:25:02 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 12:25:02 volumio volumio[3794]: info: Volumio Calling Home Nov 02 12:25:02 volumio volumio[3794]: info: Preparing to generate the ALSA configuration file Nov 02 12:25:02 volumio sudo[3974]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Nov 02 12:25:02 volumio sudo[3974]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:25:02 volumio sudo[3977]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Nov 02 12:25:02 volumio sudo[3977]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:25:02 volumio systemd[1]: Reloading. Nov 02 12:25:02 volumio sudo[3980]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Nov 02 12:25:02 volumio sudo[3980]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:25:02 volumio volumio[3794]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Nov 02 12:25:02 volumio volumio[3794]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Nov 02 12:25:02 volumio volumio[3794]: info: Reading ALSA contributions from plugins. Nov 02 12:25:02 volumio volumio[3794]: error: touch_display: Error detecting backlight interface: Error: ENOENT: no such file or directory, scandir '/sys/class/backlight/' Nov 02 12:25:02 volumio volumio[3794]: info: CoreCommandRouter::volumioGetState Nov 02 12:25:02 volumio volumio[3794]: info: CorePlayQueue::getTrack 0 Nov 02 12:25:02 volumio volumio[3794]: info: [ASDebug] CurState: stop PrevState: na Nov 02 12:25:02 volumio volumio[3794]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Nov 02 12:25:02 volumio volumio[3794]: info: [ASDebug] CurState: stop PrevState: na Nov 02 12:25:02 volumio volumio[3794]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Nov 02 12:25:02 volumio volumio[3794]: verbose: New Socket.io Connection to 192.168.0.111 from 192.168.0.164 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/141.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Nov 02 12:25:02 volumio volumio[3794]: info: MPD Permissions set Nov 02 12:25:02 volumio volumio[3794]: info: Spotify config file written Nov 02 12:25:02 volumio volumio[3794]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Nov 02 12:25:02 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 02 12:25:02 volumio volumio[3794]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Nov 02 12:25:02 volumio volumio[3794]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Nov 02 12:25:02 volumio volumio[3794]: info: Volumio called home Nov 02 12:25:02 volumio sudo[3983]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Nov 02 12:25:02 volumio sudo[3983]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:25:02 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:25:02 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:25:02 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:25:02 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:25:02 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:25:02 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:25:02 volumio volumio[3794]: info: No need to fix Spotify hosts Nov 02 12:25:02 volumio volumio[3794]: info: CoreCommandRouter::volumioGetVisibleSources Nov 02 12:25:02 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 12:25:02 volumio volumio[3794]: info: CoreCommandRouter::volumioGetState Nov 02 12:25:02 volumio volumio[3794]: info: CorePlayQueue::getTrack 0 Nov 02 12:25:02 volumio volumio[3794]: info: CoreCommandRouter::volumioGetState Nov 02 12:25:02 volumio volumio[3794]: info: CorePlayQueue::getTrack 0 Nov 02 12:25:02 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 02 12:25:02 volumio volumio[3794]: info: CoreCommandRouter::volumioGetQueue Nov 02 12:25:02 volumio volumio[3794]: info: CoreStateMachine::getQueue Nov 02 12:25:02 volumio volumio[3794]: info: CorePlayQueue::getQueue Nov 02 12:25:02 volumio volumio[3794]: info: Listing playlists Nov 02 12:25:02 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 02 12:25:02 volumio volumio[3794]: info: Received Get System Info Nov 02 12:25:02 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 12:25:02 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 12:25:02 volumio volumio[3794]: info: Discovery: Getting this device information Nov 02 12:25:02 volumio volumio[3794]: info: CoreCommandRouter::volumioGetState Nov 02 12:25:02 volumio volumio[3794]: info: CorePlayQueue::getTrack 0 Nov 02 12:25:02 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 12:25:02 volumio volumio[3794]: info: CoreCommandRouter::volumioGetState Nov 02 12:25:02 volumio volumio[3794]: info: CorePlayQueue::getTrack 0 Nov 02 12:25:02 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. Nov 02 12:25:03 volumio volumio[3794]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Nov 02 12:25:03 volumio mpd[3959]: Nov 02 12:25 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 02 12:25:03 volumio sudo[4007]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Nov 02 12:25:03 volumio sudo[4007]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:25:03 volumio sudo[4007]: pam_unix(sudo:session): session closed for user root Nov 02 12:25:03 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 02 12:25:03 volumio volumio[3794]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Nov 02 12:25:03 volumio volumio[3794]: info: Starting Shairport Sync Nov 02 12:25:03 volumio sudo[4012]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 02 12:25:03 volumio sudo[4012]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:25:03 volumio volumio[3794]: info: Starting Shairport Sync Nov 02 12:25:03 volumio sudo[4015]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 02 12:25:03 volumio sudo[4015]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:25:03 volumio sudo[4018]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 02 12:25:03 volumio volumio[3794]: info: CoreCommandRouter::volumioGetVisibleSources Nov 02 12:25:03 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 12:25:03 volumio volumio[3794]: info: CoreCommandRouter::volumioGetState Nov 02 12:25:03 volumio volumio[3794]: info: CorePlayQueue::getTrack 0 Nov 02 12:25:03 volumio sudo[4018]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:25:03 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 02 12:25:03 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 02 12:25:03 volumio volumio[3794]: info: Received Get System Info Nov 02 12:25:03 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 12:25:03 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 12:25:03 volumio volumio[3794]: info: Discovery: Getting this device information Nov 02 12:25:03 volumio volumio[3794]: info: CoreCommandRouter::volumioGetState Nov 02 12:25:03 volumio volumio[3794]: info: CorePlayQueue::getTrack 0 Nov 02 12:25:03 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 12:25:03 volumio volumio[3794]: info: CoreCommandRouter::volumioGetState Nov 02 12:25:03 volumio volumio[3794]: info: CorePlayQueue::getTrack 0 Nov 02 12:25:03 volumio volumio[3794]: info: Listing playlists Nov 02 12:25:03 volumio systemd[1]: Started Music Player Daemon. Nov 02 12:25:03 volumio volumio[3794]: info: CoreCommandRouter::volumioGetState Nov 02 12:25:03 volumio volumio[3794]: info: CorePlayQueue::getTrack 0 Nov 02 12:25:03 volumio sudo[3977]: pam_unix(sudo:session): session closed for user root Nov 02 12:25:03 volumio sudo[3949]: pam_unix(sudo:session): session closed for user root Nov 02 12:25:03 volumio sudo[3926]: pam_unix(sudo:session): session closed for user root Nov 02 12:25:03 volumio sudo[3974]: pam_unix(sudo:session): session closed for user root Nov 02 12:25:03 volumio systemd[1]: Reloading. Nov 02 12:25:03 volumio volumio[3794]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Nov 02 12:25:03 volumio volumio[3794]: SPOTIFY: BQB26TiI8r330VBv1HjH3hjUFGZJfgKATw_-qYDlXRc8tNVnDhyKLxeyjie4tkKQiGPISC76EfGfd-mEZgJk4p7rz1kmOU17qKUDHttQphwNhblABbGDKB4BRm2Ry9f7xXA4MlddZHa4dKLgvLhjsKrA-6Lk2BIyTPuuM909imhVYecysXeMk1VLpM6LS6LWGIjHEhmj46y5fYAh0lgjT54IsK8uUfPaFLAlHUcQBGJIioUIBOiwdhJHtr72V4ybDtXxMbU_To4dEL9l5TU3AEk Nov 02 12:25:03 volumio volumio[3794]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Nov 02 12:25:03 volumio volumio[3794]: info: New Spotify access token = BQB26TiI8r330VBv1HjH3hjUFGZJfgKATw_-qYDlXRc8tNVnDhyKLxeyjie4tkKQiGPISC76EfGfd-mEZgJk4p7rz1kmOU17qKUDHttQphwNhblABbGDKB4BRm2Ry9f7xXA4MlddZHa4dKLgvLhjsKrA-6Lk2BIyTPuuM909imhVYecysXeMk1VLpM6LS6LWGIjHEhmj46y5fYAh0lgjT54IsK8uUfPaFLAlHUcQBGJIioUIBOiwdhJHtr72V4ybDtXxMbU_To4dEL9l5TU3AEk Nov 02 12:25:03 volumio volumio[3794]: info: Spotify credentials grant success - running version from March 24, 2019 Nov 02 12:25:03 volumio volumio[3794]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 12:25:03 volumio volumio[3794]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 12:25:03 volumio volumio[3794]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 12:25:03 volumio volumio[3794]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 12:25:03 volumio volumio[3794]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 12:25:03 volumio volumio[3794]: info: [ASDebug] Togle GPIO: OFF Nov 02 12:25:03 volumio volumio[3794]: info: Asound.conf file unchanged, so no further update is needed Nov 02 12:25:03 volumio volumio[3794]: info: Output device has changed, restarting MPD Nov 02 12:25:03 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. Nov 02 12:25:03 volumio sudo[4034]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 02 12:25:03 volumio sudo[4034]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:25:03 volumio volumio[3794]: info: Output device has changed, restarting Shairport Sync Nov 02 12:25:03 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:25:03 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:25:03 volumio sudo[4034]: pam_unix(sudo:session): session closed for user root Nov 02 12:25:03 volumio sudo[4037]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 02 12:25:03 volumio sudo[4037]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:25:03 volumio volumio[3794]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 02 12:25:03 volumio volumio[3794]: info: Preparing to generate the ALSA configuration file Nov 02 12:25:03 volumio volumio[3794]: info: touch_display: systemctl stop getty@tty1.service succeeded. Nov 02 12:25:03 volumio volumio[3794]: info: touch_display: systemctl disable getty@tty1.service succeeded. Nov 02 12:25:03 volumio volumio[3794]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Nov 02 12:25:03 volumio volumio[3794]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Nov 02 12:25:03 volumio volumio[3794]: info: Reading ALSA contributions from plugins. Nov 02 12:25:03 volumio volumio[3794]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 12:25:03 volumio volumio[3794]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 12:25:03 volumio volumio[3794]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 12:25:03 volumio volumio[3794]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 12:25:03 volumio volumio[3794]: info: MPD Permissions set Nov 02 12:25:03 volumio volumio[3794]: error: MPD error: The expression evaluated to a falsy value: Nov 02 12:25:03 volumio volumio[3794]: assert.ok(self.idling) Nov 02 12:25:03 volumio volumio[3794]: error: The expression evaluated to a falsy value: Nov 02 12:25:03 volumio volumio[3794]: assert.ok(self.idling) Nov 02 12:25:03 volumio volumio[3794]: info: MPD running with PID3959 Nov 02 12:25:03 volumio volumio[3794]: ,establishing connection Nov 02 12:25:03 volumio volumio[3794]: info: FusionDsp - no preset used Nov 02 12:25:03 volumio sudo[3980]: pam_unix(sudo:session): session closed for user root Nov 02 12:25:03 volumio systemd[1]: Stopping Music Player Daemon... Nov 02 12:25:03 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 02 12:25:03 volumio systemd[1]: Stopping go-librespot Daemon... Nov 02 12:25:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Nov 02 12:25:03 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Nov 02 12:25:03 volumio systemd[1]: Stopped go-librespot Daemon. Nov 02 12:25:03 volumio systemd[1]: shairport-sync.service: Succeeded. Nov 02 12:25:03 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 02 12:25:04 volumio volumio[3794]: info: FusionDsp - no preset used Nov 02 12:25:04 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 02 12:25:04 volumio systemd[1]: Started go-librespot Daemon. Nov 02 12:25:04 volumio sudo[4012]: pam_unix(sudo:session): session closed for user root Nov 02 12:25:04 volumio systemd[1]: mpd.service: Succeeded. Nov 02 12:25:04 volumio sudo[4015]: pam_unix(sudo:session): session closed for user root Nov 02 12:25:04 volumio systemd[1]: Stopped Music Player Daemon. Nov 02 12:25:04 volumio sudo[3983]: pam_unix(sudo:session): session closed for user root Nov 02 12:25:04 volumio sudo[4018]: pam_unix(sudo:session): session closed for user root Nov 02 12:25:04 volumio go-librespot[4046]: go-librespot daemon starting... Nov 02 12:25:04 volumio systemd[1]: Starting Music Player Daemon... Nov 02 12:25:04 volumio go-librespot[4046]: time="2025-11-02T12:25:04+01:00" level=info msg="running go-librespot 0.4.0" Nov 02 12:25:04 volumio go-librespot[4046]: time="2025-11-02T12:25:04+01:00" level=debug msg="app state loaded" Nov 02 12:25:04 volumio go-librespot[4046]: time="2025-11-02T12:25:04+01:00" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 12:25:04 volumio volumio[3794]: info: FusionDsp - no preset used Nov 02 12:25:04 volumio sudo[4049]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 02 12:25:04 volumio sudo[4049]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:25:04 volumio sudo[4049]: pam_unix(sudo:session): session closed for user root Nov 02 12:25:04 volumio volumio[3794]: info: FusionDsp - no preset used Nov 02 12:25:04 volumio go-librespot[4046]: time="2025-11-02T12:25:04+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 02 12:25:04 volumio go-librespot[4046]: time="2025-11-02T12:25:04+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 02 12:25:04 volumio go-librespot[4046]: time="2025-11-02T12:25:04+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 02 12:25:04 volumio go-librespot[4046]: time="2025-11-02T12:25:04+01:00" level=info msg="zeroconf server listening on port 35463" Nov 02 12:25:04 volumio volumio[3794]: info: FusionDsp - no preset used Nov 02 12:25:04 volumio go-librespot[4046]: time="2025-11-02T12:25:04+01:00" level=debug msg="obtained new client token: AAAWBN+4Mgz19lMiETxTx7zl8yz5Rz7tEj9fhKtYuDRoArhNaqaFm4QuunRcvB8/N1FmK29GqxgCgyY8Z8tSObLXFaKgd3PaxDop8IWPNqjvdNVh1pm1HYPnOk09rbQt+OhnN2gbl84SQUnDcLkxMpKGjNX1ZiQZwg2YNgF6NdPVHRzBfRQNHuFJre5UR7U15uRkUzFzLNWIOpqBttV6zDAII4sCEDlXRbFNY2w+/NijDRHHf0rCMZLH1H4=" Nov 02 12:25:04 volumio volumio[3794]: error: updateQueue error: null Nov 02 12:25:04 volumio go-librespot[4046]: time="2025-11-02T12:25:04+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Nov 02 12:25:04 volumio go-librespot[4046]: time="2025-11-02T12:25:04+01:00" level=debug msg="completed keyexchange" Nov 02 12:25:04 volumio go-librespot[4046]: time="2025-11-02T12:25:04+01:00" level=debug msg="completed challenge" Nov 02 12:25:04 volumio volumio[3794]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Nov 02 12:25:04 volumio volumio[3794]: 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 Nov 02 12:25:04 volumio volumio[3794]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Nov 02 12:25:04 volumio go-librespot[4046]: time="2025-11-02T12:25:04+01:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Nov 02 12:25:04 volumio volumio[3794]: info: camilladsp service started and running in background, instance 1 Nov 02 12:25:04 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:25:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 02 12:25:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 02 12:25:04 volumio volumio[3794]: info: FusionDsp - AAAAAAAAAAAAAA-> 22050 44100 48000 88200 96000 176400 192000 <-AAAAAAAAAAAAA Nov 02 12:25:04 volumio volumio[3794]: info: FusionDsp loaded Nov 02 12:25:04 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 12:25:04 volumio volumio[3794]: info: FusionDsp - Reporting Fusion DSP Enabled Nov 02 12:25:04 volumio sudo[4076]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Nov 02 12:25:04 volumio volumio[3794]: info: Adding Signal Path Element [object Object] Nov 02 12:25:04 volumio volumio[3794]: info: Adding fusiondspeq DSP Signal Path Element Nov 02 12:25:04 volumio sudo[4076]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:25:04 volumio volumio[3794]: info: FusionDsp - ---- installed callbackRead Nov 02 12:25:04 volumio volumio[3794]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 02 12:25:04 volumio volumio[3794]: info: FusionDsp - Loudness is ON true Nov 02 12:25:04 volumio sudo[4076]: pam_unix(sudo:session): session closed for user root Nov 02 12:25:04 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:25:04 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:25:04 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:25:04 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:25:04 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:25:04 volumio volumio[3794]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Nov 02 12:25:04 volumio volumio[3794]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Nov 02 12:25:05 volumio volumio[3794]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Nov 02 12:25:05 volumio volumio[3794]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Nov 02 12:25:05 volumio volumio[3794]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Nov 02 12:25:05 volumio volumio[3794]: error: MPD error: Error: write EPIPE Nov 02 12:25:05 volumio volumio[3794]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Nov 02 12:25:05 volumio volumio[3794]: info: Shairport-Sync Started Nov 02 12:25:05 volumio volumio[3794]: Error adding Membership: Error: addMembership EINVAL Nov 02 12:25:05 volumio volumio[3794]: info: Shairport-Sync Started Nov 02 12:25:05 volumio volumio[3794]: info: Shairport-Sync Started Nov 02 12:25:05 volumio volumio[3794]: info: touch_display: systemctl daemon-reload succeeded. Nov 02 12:25:05 volumio volumio[3794]: info: FusionDsp - no preset used Nov 02 12:25:05 volumio sudo[4084]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Nov 02 12:25:05 volumio sudo[4084]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:25:05 volumio sudo[4084]: pam_unix(sudo:session): session closed for user root Nov 02 12:25:05 volumio mpd[4057]: Nov 02 12:25 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 02 12:25:05 volumio systemd[1]: Started Music Player Daemon. Nov 02 12:25:05 volumio sudo[4037]: pam_unix(sudo:session): session closed for user root Nov 02 12:25:05 volumio volumio[3794]: info: FusionDsp - no preset used Nov 02 12:25:05 volumio volumio[3794]: info: FusionDsp - no preset used Nov 02 12:25:05 volumio volumio[3794]: info: FusionDsp - no preset used Nov 02 12:25:05 volumio volumio[3794]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Nov 02 12:25:05 volumio volumio[3794]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Nov 02 12:25:05 volumio volumio[3794]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Nov 02 12:25:05 volumio volumio[3794]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Nov 02 12:25:05 volumio volumio[3794]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Nov 02 12:25:05 volumio volumio[3794]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Nov 02 12:25:05 volumio volumio[3794]: info: touch_display: Volumio Kiosk started. Nov 02 12:25:05 volumio volumio[3794]: info: CoreCommandRouter::volumioGetState Nov 02 12:25:05 volumio volumio[3794]: info: CorePlayQueue::getTrack 0 Nov 02 12:25:05 volumio volumio[3794]: info: Completed starting Core Plugins Nov 02 12:25:05 volumio volumio[3794]: info: ------------------------------------------- Nov 02 12:25:05 volumio volumio[3794]: info: ----- MyVolumio plugins startup ---- Nov 02 12:25:05 volumio volumio[3794]: info: ------------------------------------------- Nov 02 12:25:05 volumio volumio[3794]: info: [MyVolumio PluginManager] Fetching plans data.... Nov 02 12:25:05 volumio volumio[3794]: info: Starting Shairport Sync Nov 02 12:25:05 volumio volumio[3794]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 02 12:25:05 volumio sudo[4091]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 02 12:25:05 volumio sudo[4091]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:25:05 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 02 12:25:05 volumio systemd[1]: shairport-sync.service: Succeeded. Nov 02 12:25:05 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 02 12:25:05 volumio volumio[3794]: info: CoreCommandRouter::volumioGetState Nov 02 12:25:05 volumio volumio[3794]: info: CorePlayQueue::getTrack 0 Nov 02 12:25:05 volumio volumio[3794]: info: touch_display: X display number found: 0 Nov 02 12:25:05 volumio volumio[3794]: info: Asound.conf file unchanged, so no further update is needed Nov 02 12:25:05 volumio volumio[3794]: info: Output device has changed, restarting MPD Nov 02 12:25:05 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 02 12:25:05 volumio sudo[4091]: pam_unix(sudo:session): session closed for user root Nov 02 12:25:05 volumio sudo[4096]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 02 12:25:05 volumio sudo[4096]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:25:05 volumio sudo[4096]: pam_unix(sudo:session): session closed for user root Nov 02 12:25:05 volumio volumio[3794]: info: Output device has changed, restarting Shairport Sync Nov 02 12:25:05 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:25:05 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:25:05 volumio sudo[4100]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 02 12:25:05 volumio sudo[4100]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:25:05 volumio systemd[1]: Stopping Music Player Daemon... Nov 02 12:25:05 volumio systemd[1]: mpd.service: Succeeded. Nov 02 12:25:05 volumio systemd[1]: Stopped Music Player Daemon. Nov 02 12:25:05 volumio volumio[3794]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 02 12:25:05 volumio systemd[1]: Starting Music Player Daemon... Nov 02 12:25:05 volumio volumio[3794]: error: updateQueue error: null Nov 02 12:25:05 volumio volumio[3794]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Nov 02 12:25:05 volumio volumio[3794]: info: MPD Permissions set Nov 02 12:25:05 volumio volumio[3794]: info: Shairport-Sync Started Nov 02 12:25:06 volumio sudo[4106]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 02 12:25:06 volumio sudo[4106]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:25:06 volumio sudo[4106]: pam_unix(sudo:session): session closed for user root Nov 02 12:25:06 volumio volumio[3794]: info: touch_display: X display number found: 0 Nov 02 12:25:06 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:25:06 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:25:06 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:25:06 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:25:06 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:25:06 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:25:06 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:25:06 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:25:06 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:25:06 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:25:06 volumio volumio[3794]: info: touch_display: Setting screensaver timeout to 120 seconds. Nov 02 12:25:06 volumio volumio[3794]: info: Starting Shairport Sync Nov 02 12:25:06 volumio volumio[3794]: SPOTIFY: User informations: {"country":"PL","display_name":"anzio75","email":"anzio75@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/anzio75"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/anzio75","id":"anzio75","images":[],"product":"free","type":"user","uri":"spotify:user:anzio75"} Nov 02 12:25:06 volumio volumio[3794]: info: Spotify Successfully logged in Nov 02 12:25:06 volumio volumio[3794]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 12:25:06 volumio volumio[3794]: info: [1762082706140] CoreMusicLibrary::Adding element Spotify Nov 02 12:25:06 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 12:25:06 volumio volumio[3794]: Cannot find translation for source YouTube Music Nov 02 12:25:06 volumio volumio[3794]: Cannot find translation for source 80s80s Radio Nov 02 12:25:06 volumio volumio[3794]: Cannot find translation for source Spotify Nov 02 12:25:06 volumio sudo[4123]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 02 12:25:06 volumio sudo[4123]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:25:06 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 02 12:25:06 volumio systemd[1]: shairport-sync.service: Succeeded. Nov 02 12:25:06 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 02 12:25:06 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 02 12:25:06 volumio sudo[4123]: pam_unix(sudo:session): session closed for user root Nov 02 12:25:06 volumio volumio[3794]: info: Shairport-Sync Started Nov 02 12:25:06 volumio sudo[4130]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //http://192.168.0.16//music /mnt/NAS/asustor Nov 02 12:25:06 volumio sudo[4130]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:25:06 volumio sudo[4130]: pam_unix(sudo:session): session closed for user root Nov 02 12:25:06 volumio volumio[3794]: info: Cannot mount NAS asustor at system boot, trial number 2 ,retrying in 5 seconds Nov 02 12:25:06 volumio mpd[4114]: Nov 02 12:25 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 02 12:25:06 volumio systemd[1]: Started Music Player Daemon. Nov 02 12:25:06 volumio sudo[4100]: pam_unix(sudo:session): session closed for user root Nov 02 12:25:06 volumio volumio[3794]: error: updateQueue error: null Nov 02 12:25:07 volumio sudo[4152]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 02 12:25:07 volumio sudo[4152]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:25:07 volumio sudo[4152]: pam_unix(sudo:session): session closed for user root Nov 02 12:25:07 volumio sudo[4155]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 02 12:25:07 volumio sudo[4155]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:25:07 volumio sudo[4155]: pam_unix(sudo:session): session closed for user root Nov 02 12:25:07 volumio sudo[4158]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Nov 02 12:25:07 volumio sudo[4158]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:25:07 volumio sudo[4158]: pam_unix(sudo:session): session closed for user root Nov 02 12:25:07 volumio volumio[3794]: info: Upmpdcli Daemon Started Nov 02 12:25:07 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Nov 02 12:25:07 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Nov 02 12:25:07 volumio systemd[1]: Stopped go-librespot Daemon. Nov 02 12:25:07 volumio systemd[1]: Started go-librespot Daemon. Nov 02 12:25:07 volumio go-librespot[4160]: go-librespot daemon starting... Nov 02 12:25:07 volumio go-librespot[4160]: time="2025-11-02T12:25:07+01:00" level=info msg="running go-librespot 0.4.0" Nov 02 12:25:07 volumio go-librespot[4160]: time="2025-11-02T12:25:07+01:00" level=debug msg="app state loaded" Nov 02 12:25:07 volumio go-librespot[4160]: time="2025-11-02T12:25:07+01:00" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 12:25:08 volumio go-librespot[4160]: time="2025-11-02T12:25:08+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 02 12:25:08 volumio go-librespot[4160]: time="2025-11-02T12:25:08+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 02 12:25:08 volumio go-librespot[4160]: time="2025-11-02T12:25:08+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 02 12:25:08 volumio go-librespot[4160]: time="2025-11-02T12:25:08+01:00" level=info msg="zeroconf server listening on port 37195" Nov 02 12:25:08 volumio volumio[3794]: info: go-librespot daemon successfully initialized Nov 02 12:25:08 volumio go-librespot[4160]: time="2025-11-02T12:25:08+01:00" level=debug msg="obtained new client token: AADoaGktzuB71GWtGeFSA1udi6YLeCra5/LRIPPoUm6h4e4id9f8j6lm64K8CR5juj0pHByVAZ13z0IB6vciGsqTUShYodouIuj60carE5uB9yRz591XMtu2vPO5Hgj38JFzR/10wOkrBB3cC0XCkpbLNrviE0bUj+X5xRbfVaNr0fyekK/F4Cvn+53MtIhVAN+rKUD2KdpJxW91L8nSv+saWyPiPZ5E42wBv56A7YD+z9r+IK5HURxm" Nov 02 12:25:08 volumio go-librespot[4160]: time="2025-11-02T12:25:08+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Nov 02 12:25:08 volumio go-librespot[4160]: time="2025-11-02T12:25:08+01:00" level=debug msg="completed keyexchange" Nov 02 12:25:08 volumio go-librespot[4160]: time="2025-11-02T12:25:08+01:00" level=debug msg="completed challenge" Nov 02 12:25:08 volumio go-librespot[4160]: time="2025-11-02T12:25:08+01:00" level=info msg="authenticated AP" username="an***75" Nov 02 12:25:08 volumio go-librespot[4160]: time="2025-11-02T12:25:08+01:00" level=info msg="authenticated Login5" username="an***75" Nov 02 12:25:08 volumio go-librespot[4160]: time="2025-11-02T12:25:08+01:00" level=debug msg="initializing zeroconf session" username="an***75" Nov 02 12:25:08 volumio go-librespot[4160]: time="2025-11-02T12:25:08+01:00" level=debug msg="dealer connection opened" Nov 02 12:25:08 volumio go-librespot[4160]: time="2025-11-02T12:25:08+01:00" level=trace msg="starting accesspoint recv loop" Nov 02 12:25:08 volumio go-librespot[4160]: time="2025-11-02T12:25:08+01:00" level=trace msg="starting dealer recv loop" Nov 02 12:25:08 volumio go-librespot[4160]: time="2025-11-02T12:25:08+01:00" level=trace msg="received accesspoint ping" Nov 02 12:25:08 volumio go-librespot[4160]: time="2025-11-02T12:25:08+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Nov 02 12:25:08 volumio go-librespot[4160]: time="2025-11-02T12:25:08+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Nov 02 12:25:08 volumio go-librespot[4160]: time="2025-11-02T12:25:08+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Nov 02 12:25:08 volumio go-librespot[4160]: time="2025-11-02T12:25:08+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Nov 02 12:25:08 volumio go-librespot[4160]: time="2025-11-02T12:25:08+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Nov 02 12:25:08 volumio go-librespot[4160]: time="2025-11-02T12:25:08+01:00" level=debug msg="received connection id: M2FjNTM5NDYtMzdh...MDlGNjY3RDgxNA==" Nov 02 12:25:08 volumio go-librespot[4160]: time="2025-11-02T12:25:08+01:00" level=trace msg="received accesspoint pong ack" Nov 02 12:25:08 volumio volumio[3794]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Nov 02 12:25:08 volumio go-librespot[4160]: time="2025-11-02T12:25:08+01:00" level=debug msg="put connect state because NEW_DEVICE" Nov 02 12:25:11 volumio volumio[3794]: info: Initializing connection to go-librespot Websocket Nov 02 12:25:11 volumio go-librespot[4160]: time="2025-11-02T12:25:11+01:00" level=debug msg="new websocket client" Nov 02 12:25:11 volumio volumio[3794]: info: Connection to go-librespot Websocket established Nov 02 12:25:11 volumio sudo[4169]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //http://192.168.0.16//music /mnt/NAS/asustor Nov 02 12:25:11 volumio sudo[4169]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:25:11 volumio sudo[4169]: pam_unix(sudo:session): session closed for user root Nov 02 12:25:11 volumio volumio[3794]: info: Cannot mount NAS asustor at system boot, trial number 3 ,retrying in 5 seconds Nov 02 12:25:11 volumio volumio[3794]: info: Starting Uninstall of plugin system_controller - ampswitch Nov 02 12:25:11 volumio volumio[3794]: info: Uninstalling plugin ampswitch Nov 02 12:25:11 volumio volumio[3794]: info: [ASDebug] Port: 2 Nov 02 12:25:11 volumio volumio[3794]: info: [ASDebug] Inverted: false Nov 02 12:25:11 volumio volumio[3794]: info: [ASDebug] Delay: 720 Nov 02 12:25:14 volumio volumio[3794]: info: Getting Spotify volume Nov 02 12:25:14 volumio volumio[3794]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Nov 02 12:25:14 volumio volumio[3794]: info: Spotify volume: 100 Nov 02 12:25:14 volumio volumio[3794]: info: CoreCommandRouter::volumioGetState Nov 02 12:25:14 volumio volumio[3794]: info: CorePlayQueue::getTrack 0 Nov 02 12:25:14 volumio volumio[3794]: SPOTIFY: RECEIVED VOLUMIO VOLUME 79 Nov 02 12:25:14 volumio volumio[3794]: SPOTIFY: SPOTIFY VOLUME 100 Nov 02 12:25:14 volumio volumio[3794]: SPOTIFY: VOLUMIO VOLUME 79 Nov 02 12:25:14 volumio volumio[3794]: SPOTIFY: DELTA VOLUME ENOUGH: true Nov 02 12:25:14 volumio volumio[3794]: info: Setting Spotify Volume from Volumio: 79 Nov 02 12:25:14 volumio volumio[3794]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Nov 02 12:25:14 volumio volumio[3794]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Nov 02 12:25:14 volumio volumio[3794]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Nov 02 12:25:14 volumio volumio[3794]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Nov 02 12:25:14 volumio volumio[3794]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Nov 02 12:25:14 volumio volumio[3794]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Nov 02 12:25:14 volumio volumio[3794]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Nov 02 12:25:14 volumio volumio[3794]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Nov 02 12:25:14 volumio volumio[3794]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Nov 02 12:25:14 volumio volumio[3794]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Nov 02 12:25:14 volumio volumio[3794]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Nov 02 12:25:14 volumio volumio[3794]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Nov 02 12:25:14 volumio volumio[3794]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Nov 02 12:25:14 volumio volumio[3794]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Nov 02 12:25:14 volumio volumio[3794]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Nov 02 12:25:14 volumio volumio[3794]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Nov 02 12:25:14 volumio volumio[3794]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Nov 02 12:25:14 volumio volumio[3794]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Nov 02 12:25:14 volumio volumio[3794]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Nov 02 12:25:14 volumio volumio[3794]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Nov 02 12:25:14 volumio volumio[3794]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Nov 02 12:25:14 volumio volumio[3794]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Nov 02 12:25:14 volumio volumio[3794]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Nov 02 12:25:14 volumio volumio[3794]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Nov 02 12:25:14 volumio volumio[3794]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Nov 02 12:25:14 volumio volumio[3794]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Nov 02 12:25:14 volumio volumio[3794]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Nov 02 12:25:14 volumio volumio[3794]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Nov 02 12:25:14 volumio volumio[3794]: info: Adding plugin bluetooth to MyMusic Plugins Nov 02 12:25:14 volumio volumio[3794]: info: Adding plugin multiroom to MyMusic Plugins Nov 02 12:25:14 volumio volumio[3794]: info: Adding plugin metavolumio to MyMusic Plugins Nov 02 12:25:14 volumio volumio[3794]: info: Adding plugin cd_controller to MyMusic Plugins Nov 02 12:25:14 volumio volumio[3794]: info: Adding plugin qobuzconnect to MyMusic Plugins Nov 02 12:25:14 volumio volumio[3794]: info: Adding plugin smart_inputs to MyMusic Plugins Nov 02 12:25:14 volumio volumio[3794]: info: Adding plugin tidalconnect to MyMusic Plugins Nov 02 12:25:14 volumio volumio[3794]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Nov 02 12:25:14 volumio volumio[3794]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Nov 02 12:25:14 volumio volumio[3794]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Nov 02 12:25:14 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:25:14 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:25:14 volumio volumio[3794]: info: Starting MyVolumio Remote Streaming Endpoints Nov 02 12:25:14 volumio volumio[3794]: info: MyVolumio login type: Token Nov 02 12:25:14 volumio volumio[3794]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Nov 02 12:25:14 volumio volumio[3794]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Nov 02 12:25:15 volumio volumio[3794]: info: Starting Streaming Service Transparent Proxy Nov 02 12:25:15 volumio volumio[3794]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Nov 02 12:25:15 volumio volumio[3794]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Nov 02 12:25:15 volumio volumio[3794]: info: Streaming services startup Nov 02 12:25:15 volumio volumio[3794]: info: Starting Streaming Daemon Nov 02 12:25:15 volumio sudo[4223]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 02 12:25:15 volumio sudo[4223]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:25:15 volumio sudo[4223]: pam_unix(sudo:session): session closed for user root Nov 02 12:25:15 volumio volumio[3794]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Nov 02 12:25:15 volumio volumio[3794]: info: CoreCommandRouter::volumioPlay Nov 02 12:25:15 volumio volumio[3794]: info: CoreStateMachine::play index undefined Nov 02 12:25:15 volumio volumio[3794]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 02 12:25:15 volumio volumio[3794]: info: CorePlayQueue::getTrack 0 Nov 02 12:25:15 volumio volumio[3794]: info: CoreStateMachine::startPlaybackTimer Nov 02 12:25:15 volumio volumio[3794]: info: CorePlayQueue::getTrack 0 Nov 02 12:25:15 volumio volumio[3794]: info: [ytmusic-play] clearAddPlayTrack: ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Voil%C3%A0%22%2C%22artist%22%3A%22Andr%C3%A9%20Rieu%2C%20Johann%20Strauss%20Orchestra%20%26%20Emma%20Kok%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2FsfSUY-eV0JffFwPIrHiDgCOAPrVK_17MDO6QpH1yqlEJ41DHoGM5rMS4F6PR5ko2_OVIU40DaVNzFsYu%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%2260r6ysvP0hU%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%2260r6ysvP0hU%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%7D%7D Nov 02 12:25:15 volumio volumio[3794]: info: [ytmusic] InnertubeLoader: creating Innertube instance... Nov 02 12:25:15 volumio volumio[3794]: error: Cannot start Volumio Streaming Daemon Nov 02 12:25:15 volumio volumio[3794]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Nov 02 12:25:15 volumio volumio[3794]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Nov 02 12:25:15 volumio volumio[3794]: SPOTIFY: SETTING SPOTIFY VOLUME 79 Nov 02 12:25:15 volumio volumio[3794]: info: Sending Spotify command with payload to local API: /player/volume Nov 02 12:25:15 volumio go-librespot[4160]: time="2025-11-02T12:25:15+01:00" level=debug msg="update volume requested to 51772/65535" Nov 02 12:25:15 volumio go-librespot[4160]: time="2025-11-02T12:25:15+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Nov 02 12:25:15 volumio go-librespot[4160]: time="2025-11-02T12:25:15+01:00" level=trace msg="emitting websocket event: volume" Nov 02 12:25:15 volumio volumio[3794]: SPOTIFY: received: {"type":"volume","data":{"value":79,"max":100}} Nov 02 12:25:15 volumio volumio[3794]: SPOTIFY: RECEIVED SPOTIFY VOLUME 79 Nov 02 12:25:15 volumio volumio[3794]: STREAMING PROXY: Starting server on port 3245 Nov 02 12:25:15 volumio volumio[3794]: Node JS runtime: 14 Nov 02 12:25:16 volumio volumio[3794]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Nov 02 12:25:19 volumio volumio[3794]: [YOUTUBEJS][Player]: Failed to extract signature decipher algorithm. Nov 02 12:25:19 volumio volumio[3794]: info: [ytmusic] InnertubeLoader: obtaining po_token by visitorData... Nov 02 12:25:20 volumio sudo[4248]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //http://192.168.0.16//music /mnt/NAS/asustor Nov 02 12:25:20 volumio sudo[4248]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:25:20 volumio sudo[4248]: pam_unix(sudo:session): session closed for user root Nov 02 12:25:20 volumio volumio[3794]: info: CoreCommandRouter::volumioPlay Nov 02 12:25:20 volumio volumio[3794]: info: CoreStateMachine::play index undefined Nov 02 12:25:20 volumio volumio[3794]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 02 12:25:20 volumio volumio[3794]: info: CoreCommandRouter::volumioPlay Nov 02 12:25:20 volumio volumio[3794]: info: CoreStateMachine::play index undefined Nov 02 12:25:20 volumio volumio[3794]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 02 12:25:20 volumio volumio[3794]: info: CoreCommandRouter::volumioPlay Nov 02 12:25:20 volumio volumio[3794]: info: CoreStateMachine::play index undefined Nov 02 12:25:20 volumio volumio[3794]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 02 12:25:20 volumio volumio[3794]: info: CoreCommandRouter::volumioPlay Nov 02 12:25:20 volumio volumio[3794]: info: CoreStateMachine::play index undefined Nov 02 12:25:20 volumio volumio[3794]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 02 12:25:20 volumio volumio[3794]: info: CoreCommandRouter::volumioPlay Nov 02 12:25:20 volumio volumio[3794]: info: CoreStateMachine::play index undefined Nov 02 12:25:20 volumio volumio[3794]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 02 12:25:20 volumio volumio[3794]: info: CoreCommandRouter::volumioPlay Nov 02 12:25:20 volumio volumio[3794]: info: CoreStateMachine::play index undefined Nov 02 12:25:20 volumio volumio[3794]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 02 12:25:20 volumio volumio[3794]: info: CorePlayQueue::getTrack 0 Nov 02 12:25:20 volumio volumio[3794]: info: CoreStateMachine::startPlaybackTimer Nov 02 12:25:20 volumio volumio[3794]: info: CorePlayQueue::getTrack 0 Nov 02 12:25:20 volumio volumio[3794]: info: [ytmusic-play] clearAddPlayTrack: ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Voil%C3%A0%22%2C%22artist%22%3A%22Andr%C3%A9%20Rieu%2C%20Johann%20Strauss%20Orchestra%20%26%20Emma%20Kok%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2FsfSUY-eV0JffFwPIrHiDgCOAPrVK_17MDO6QpH1yqlEJ41DHoGM5rMS4F6PR5ko2_OVIU40DaVNzFsYu%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%2260r6ysvP0hU%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%2260r6ysvP0hU%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%7D%7D Nov 02 12:25:20 volumio volumio[3794]: info: CorePlayQueue::getTrack 0 Nov 02 12:25:20 volumio volumio[3794]: info: CoreStateMachine::startPlaybackTimer Nov 02 12:25:20 volumio volumio[3794]: info: CorePlayQueue::getTrack 0 Nov 02 12:25:20 volumio volumio[3794]: info: [ytmusic-play] clearAddPlayTrack: ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Voil%C3%A0%22%2C%22artist%22%3A%22Andr%C3%A9%20Rieu%2C%20Johann%20Strauss%20Orchestra%20%26%20Emma%20Kok%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2FsfSUY-eV0JffFwPIrHiDgCOAPrVK_17MDO6QpH1yqlEJ41DHoGM5rMS4F6PR5ko2_OVIU40DaVNzFsYu%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%2260r6ysvP0hU%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%2260r6ysvP0hU%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%7D%7D Nov 02 12:25:20 volumio volumio[3794]: info: CorePlayQueue::getTrack 0 Nov 02 12:25:20 volumio volumio[3794]: info: CoreStateMachine::startPlaybackTimer Nov 02 12:25:20 volumio volumio[3794]: info: CorePlayQueue::getTrack 0 Nov 02 12:25:20 volumio volumio[3794]: info: [ytmusic-play] clearAddPlayTrack: ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Voil%C3%A0%22%2C%22artist%22%3A%22Andr%C3%A9%20Rieu%2C%20Johann%20Strauss%20Orchestra%20%26%20Emma%20Kok%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2FsfSUY-eV0JffFwPIrHiDgCOAPrVK_17MDO6QpH1yqlEJ41DHoGM5rMS4F6PR5ko2_OVIU40DaVNzFsYu%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%2260r6ysvP0hU%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%2260r6ysvP0hU%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%7D%7D Nov 02 12:25:20 volumio volumio[3794]: info: CorePlayQueue::getTrack 0 Nov 02 12:25:20 volumio volumio[3794]: info: CoreStateMachine::startPlaybackTimer Nov 02 12:25:20 volumio volumio[3794]: info: CorePlayQueue::getTrack 0 Nov 02 12:25:20 volumio volumio[3794]: info: [ytmusic-play] clearAddPlayTrack: ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Voil%C3%A0%22%2C%22artist%22%3A%22Andr%C3%A9%20Rieu%2C%20Johann%20Strauss%20Orchestra%20%26%20Emma%20Kok%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2FsfSUY-eV0JffFwPIrHiDgCOAPrVK_17MDO6QpH1yqlEJ41DHoGM5rMS4F6PR5ko2_OVIU40DaVNzFsYu%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%2260r6ysvP0hU%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%2260r6ysvP0hU%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%7D%7D Nov 02 12:25:20 volumio volumio[3794]: info: CorePlayQueue::getTrack 0 Nov 02 12:25:20 volumio volumio[3794]: info: CoreStateMachine::startPlaybackTimer Nov 02 12:25:20 volumio volumio[3794]: info: CorePlayQueue::getTrack 0 Nov 02 12:25:20 volumio volumio[3794]: info: [ytmusic-play] clearAddPlayTrack: ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Voil%C3%A0%22%2C%22artist%22%3A%22Andr%C3%A9%20Rieu%2C%20Johann%20Strauss%20Orchestra%20%26%20Emma%20Kok%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2FsfSUY-eV0JffFwPIrHiDgCOAPrVK_17MDO6QpH1yqlEJ41DHoGM5rMS4F6PR5ko2_OVIU40DaVNzFsYu%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%2260r6ysvP0hU%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%2260r6ysvP0hU%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%7D%7D Nov 02 12:25:20 volumio volumio[3794]: info: CorePlayQueue::getTrack 0 Nov 02 12:25:20 volumio volumio[3794]: info: CoreStateMachine::startPlaybackTimer Nov 02 12:25:20 volumio volumio[3794]: info: CorePlayQueue::getTrack 0 Nov 02 12:25:20 volumio volumio[3794]: info: [ytmusic-play] clearAddPlayTrack: ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Voil%C3%A0%22%2C%22artist%22%3A%22Andr%C3%A9%20Rieu%2C%20Johann%20Strauss%20Orchestra%20%26%20Emma%20Kok%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2FsfSUY-eV0JffFwPIrHiDgCOAPrVK_17MDO6QpH1yqlEJ41DHoGM5rMS4F6PR5ko2_OVIU40DaVNzFsYu%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%2260r6ysvP0hU%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%2260r6ysvP0hU%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%7D%7D Nov 02 12:25:20 volumio volumio[3794]: info: Cannot mount NAS asustor at system boot, trial number 4 ,retrying in 5 seconds Nov 02 12:25:20 volumio volumio[3794]: info: MyVolumio token set successfully Nov 02 12:25:20 volumio volumio[3794]: info: MYVOLUMIO: Adding device Nov 02 12:25:20 volumio volumio[3794]: info: MYVOLUMIO: Evaluating Server Nov 02 12:25:21 volumio volumio[3794]: info: MyVolumio status changed Nov 02 12:25:21 volumio volumio[3794]: info: Streaming services startup Nov 02 12:25:21 volumio volumio[3794]: info: Starting Streaming Daemon Nov 02 12:25:21 volumio volumio[3794]: info: Removing browser output: myVolumio user plan is not superstar Nov 02 12:25:21 volumio volumio[3794]: info: Removing audio output: Nov 02 12:25:21 volumio volumio[3794]: info: Stoppping Tunnel 1 Nov 02 12:25:21 volumio sudo[4270]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 02 12:25:21 volumio sudo[4270]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:25:21 volumio sudo[4270]: pam_unix(sudo:session): session closed for user root Nov 02 12:25:21 volumio sudo[4273]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Nov 02 12:25:21 volumio volumio[3794]: error: Cannot start Volumio Streaming Daemon Nov 02 12:25:21 volumio sudo[4273]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:25:21 volumio volumio[3794]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Nov 02 12:25:21 volumio volumio[3794]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Nov 02 12:25:21 volumio sudo[4273]: pam_unix(sudo:session): session closed for user root Nov 02 12:25:21 volumio volumio[3794]: info: Remote SSH Stopped Nov 02 12:25:21 volumio volumio[3794]: info: [ytmusic] InnertubeLoader: obtained po_token (expires in 43200 seconds) Nov 02 12:25:21 volumio volumio[3794]: info: [ytmusic] InnertubeLoader: re-create Innertube instance with po_token Nov 02 12:25:21 volumio volumio[3794]: info: [ytmusic] InnertubeLoader: creating Innertube instance with po_token... Nov 02 12:25:21 volumio volumio[3794]: info: Setting Geolocation for MyVolumio to eu2 Nov 02 12:25:21 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:25:21 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:25:21 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:25:22 volumio volumio[3794]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Nov 02 12:25:24 volumio volumio[3794]: [YOUTUBEJS][Player]: Failed to extract signature decipher algorithm. Nov 02 12:25:24 volumio volumio[3794]: info: [ytmusic] InnertubeLoader: going to refresh po_token in 43100 seconds Nov 02 12:25:24 volumio volumio[3794]: info: Updating MyVolumio device info Nov 02 12:25:24 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:25:24 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:25:24 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:25:24 volumio volumio[3794]: info: CorePlayQueue::getTrack 0 Nov 02 12:25:24 volumio volumio[3794]: verbose: ControllerMpd::sendMpdCommand stop Nov 02 12:25:25 volumio volumio[3794]: info: CorePlayQueue::getTrack 0 Nov 02 12:25:25 volumio volumio[3794]: verbose: ControllerMpd::sendMpdCommand stop Nov 02 12:25:25 volumio volumio[3794]: info: sendMpdCommand stop took 127 milliseconds Nov 02 12:25:25 volumio volumio[3794]: info: sendMpdCommand stop took 20 milliseconds Nov 02 12:25:25 volumio volumio[3794]: verbose: ControllerMpd::sendMpdCommand clear Nov 02 12:25:25 volumio volumio[3794]: info: Nov 02 12:25:25 volumio volumio[3794]: ---------------------------- MPD announces system playlist update Nov 02 12:25:25 volumio volumio[3794]: info: Ignoring MPD Status Update Nov 02 12:25:25 volumio volumio[3794]: info: Nov 02 12:25:25 volumio volumio[3794]: ---------------------------- MPD announces system playlist update Nov 02 12:25:25 volumio volumio[3794]: info: Ignoring MPD Status Update Nov 02 12:25:25 volumio volumio[3794]: info: sendMpdCommand clear took 26 milliseconds Nov 02 12:25:25 volumio volumio[3794]: info: sendMpdCommand clear took 27 milliseconds Nov 02 12:25:25 volumio volumio[3794]: verbose: ControllerMpd::sendMpdCommand addid "https://rr3---sn-8xjug5oxu-f5fs.googlevideo.com/videoplayback?expire=1762104324&ei=pD8HacfEIsWDi9oPzYHYkQU&ip=89.231.107.132&id=o-AN5jmzMJMw3XYF_SEAig9m1MJRc_7ldiadqCWuRQBWKO&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=151&met=1762082724%2C&mh=ks&mm=31%2C29&mn=sn-8xjug5oxu-f5fs%2Csn-f5f7kn7z&ms=au%2Crdu&mv=m&mvi=3&pl=19&rms=au%2Cau&gcr=pl&initcwndbps=3485000&bui=AdEuB5R2Ismq6P27Jfa-zGlykzq5mzgLhKlOIAXQwijmRoECvQeIMl8AYv0ltBo2QRK0jzUq_Ie1TBhe&spc=6b0G_NTPTgZ3Y0ge4jx8LRI2_57YaFp_JcpFxGDU6a41oiMTo26xAyRA5DokzC8qISk&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=udLGG3D7k-AREG3OYApTObMQ&rqh=1&gir=yes&clen=5191365&dur=310.541&lmt=1760014346220842&mt=1762082338&fvip=5&keepalive=yes&fexp=51552689%2C51565115%2C51565681%2C51580968&c=WEB_REMIX&sefc=1&txp=1432534&n=v&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRAIgCZaX0kKJIANTDTWgAnZR8m_YXQJhblUcAaUo1ZqBrwcCIFI3ASkxFGN50vrYxVe9nsuISf9Zvkx0wshs0L2ZwHgu&pot=MpoB1rhdfObtiqbWhpPdO2XHuPAbna0eaSE8Z6gbAEJvK47YbKRm00zBTMa2MU9lPoeoDjy77N6Oe0uA8QroOtjAOWvpmYk4tMnQfaJmERlTSGqHRMQmZjriWN5z9CGwNeq60XoxGwpHIBavRzplYEspkliSNsjn7fcfnneCMkAvfFotS_WqHWVlmJssgL2ZqIIS5Fbq-dLhgfGvNg%3D%3D&cver=1.20250219.01.00&t.YouTube" Nov 02 12:25:25 volumio volumio[3794]: verbose: ControllerMpd::sendMpdCommand addid "https://rr3---sn-8xjug5oxu-f5fs.googlevideo.com/videoplayback?expire=1762104324&ei=pD8Haff0H9jLi9oPstmpSA&ip=89.231.107.132&id=o-AIY1v4BocqE-qPoCYOuOhFVW1iCIqrd_MTEbwqJoPBSm&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1762082724%2C&mh=ks&mm=31%2C29&mn=sn-8xjug5oxu-f5fs%2Csn-f5f7lnld&ms=au%2Crdu&mv=m&mvi=3&pl=19&rms=au%2Cau&gcr=pl&initcwndbps=3485000&bui=AdEuB5RPT2I3fcGrlK5J5OZuCZw-HbPQgcUlZRlNfrDKK0es70OzC4uGWcfULmw3l0BRvz7rT_gkEnqN&spc=6b0G_N0iObv09FyWLZu1fKFs0rxIDWQhU8d9nE-0pl9CuRHv0IF_ExI2eU3jGSZj4_U&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=dxSKAa0x0iXNDfwT8-lc_6gQ&rqh=1&gir=yes&clen=5191365&dur=310.541&lmt=1760014346220842&mt=1762082338&fvip=2&keepalive=yes&fexp=51552689%2C51565115%2C51565681%2C51580968&c=WEB_REMIX&sefc=1&txp=1432534&n=v&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhANmDjvQXx1AFPBfHMD8Kn44fFj7esTkuL4S_VPq3-tvdAiEAgFGU6gQFcWisestywapYqaStzlE2TZMYojrVFr444dA%3D&pot=MpoB1rhdfObtiqbWhpPdO2XHuPAbna0eaSE8Z6gbAEJvK47YbKRm00zBTMa2MU9lPoeoDjy77N6Oe0uA8QroOtjAOWvpmYk4tMnQfaJmERlTSGqHRMQmZjriWN5z9CGwNeq60XoxGwpHIBavRzplYEspkliSNsjn7fcfnneCMkAvfFotS_WqHWVlmJssgL2ZqIIS5Fbq-dLhgfGvNg%3D%3D&cver=1.20250219.01.00&t.YouTube" Nov 02 12:25:25 volumio volumio[3794]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Nov 02 12:25:25 volumio volumio[3794]: error: updateQueue error: null Nov 02 12:25:25 volumio volumio[3794]: error: updateQueue error: null Nov 02 12:25:25 volumio volumio[3794]: info: Nov 02 12:25:25 volumio volumio[3794]: ---------------------------- MPD announces system playlist update Nov 02 12:25:25 volumio volumio[3794]: info: Ignoring MPD Status Update Nov 02 12:25:25 volumio volumio[3794]: info: Nov 02 12:25:25 volumio volumio[3794]: ---------------------------- MPD announces system playlist update Nov 02 12:25:25 volumio volumio[3794]: info: Ignoring MPD Status Update Nov 02 12:25:25 volumio volumio[3794]: info: ------------------------------ 9ms Nov 02 12:25:25 volumio volumio[3794]: info: ------------------------------ 9ms Nov 02 12:25:25 volumio volumio[3794]: info: sendMpdCommand addid "https://rr3---sn-8xjug5oxu-f5fs.googlevideo.com/videoplayback?expire=1762104324&ei=pD8HacfEIsWDi9oPzYHYkQU&ip=89.231.107.132&id=o-AN5jmzMJMw3XYF_SEAig9m1MJRc_7ldiadqCWuRQBWKO&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=151&met=1762082724%2C&mh=ks&mm=31%2C29&mn=sn-8xjug5oxu-f5fs%2Csn-f5f7kn7z&ms=au%2Crdu&mv=m&mvi=3&pl=19&rms=au%2Cau&gcr=pl&initcwndbps=3485000&bui=AdEuB5R2Ismq6P27Jfa-zGlykzq5mzgLhKlOIAXQwijmRoECvQeIMl8AYv0ltBo2QRK0jzUq_Ie1TBhe&spc=6b0G_NTPTgZ3Y0ge4jx8LRI2_57YaFp_JcpFxGDU6a41oiMTo26xAyRA5DokzC8qISk&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=udLGG3D7k-AREG3OYApTObMQ&rqh=1&gir=yes&clen=5191365&dur=310.541&lmt=1760014346220842&mt=1762082338&fvip=5&keepalive=yes&fexp=51552689%2C51565115%2C51565681%2C51580968&c=WEB_REMIX&sefc=1&txp=1432534&n=v&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRAIgCZaX0kKJIANTDTWgAnZR8m_YXQJhblUcAaUo1ZqBrwcCIFI3ASkxFGN50vrYxVe9nsuISf9Zvkx0wshs0L2ZwHgu&pot=MpoB1rhdfObtiqbWhpPdO2XHuPAbna0eaSE8Z6gbAEJvK47YbKRm00zBTMa2MU9lPoeoDjy77N6Oe0uA8QroOtjAOWvpmYk4tMnQfaJmERlTSGqHRMQmZjriWN5z9CGwNeq60XoxGwpHIBavRzplYEspkliSNsjn7fcfnneCMkAvfFotS_WqHWVlmJssgL2ZqIIS5Fbq-dLhgfGvNg%3D%3D&cver=1.20250219.01.00&t.YouTube" took 8 milliseconds Nov 02 12:25:25 volumio volumio[3794]: info: sendMpdCommand addid "https://rr3---sn-8xjug5oxu-f5fs.googlevideo.com/videoplayback?expire=1762104324&ei=pD8Haff0H9jLi9oPstmpSA&ip=89.231.107.132&id=o-AIY1v4BocqE-qPoCYOuOhFVW1iCIqrd_MTEbwqJoPBSm&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1762082724%2C&mh=ks&mm=31%2C29&mn=sn-8xjug5oxu-f5fs%2Csn-f5f7lnld&ms=au%2Crdu&mv=m&mvi=3&pl=19&rms=au%2Cau&gcr=pl&initcwndbps=3485000&bui=AdEuB5RPT2I3fcGrlK5J5OZuCZw-HbPQgcUlZRlNfrDKK0es70OzC4uGWcfULmw3l0BRvz7rT_gkEnqN&spc=6b0G_N0iObv09FyWLZu1fKFs0rxIDWQhU8d9nE-0pl9CuRHv0IF_ExI2eU3jGSZj4_U&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=dxSKAa0x0iXNDfwT8-lc_6gQ&rqh=1&gir=yes&clen=5191365&dur=310.541&lmt=1760014346220842&mt=1762082338&fvip=2&keepalive=yes&fexp=51552689%2C51565115%2C51565681%2C51580968&c=WEB_REMIX&sefc=1&txp=1432534&n=v&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhANmDjvQXx1AFPBfHMD8Kn44fFj7esTkuL4S_VPq3-tvdAiEAgFGU6gQFcWisestywapYqaStzlE2TZMYojrVFr444dA%3D&pot=MpoB1rhdfObtiqbWhpPdO2XHuPAbna0eaSE8Z6gbAEJvK47YbKRm00zBTMa2MU9lPoeoDjy77N6Oe0uA8QroOtjAOWvpmYk4tMnQfaJmERlTSGqHRMQmZjriWN5z9CGwNeq60XoxGwpHIBavRzplYEspkliSNsjn7fcfnneCMkAvfFotS_WqHWVlmJssgL2ZqIIS5Fbq-dLhgfGvNg%3D%3D&cver=1.20250219.01.00&t.YouTube" took 7 milliseconds Nov 02 12:25:25 volumio volumio[3794]: verbose: MPD COMMAND [object Object] Nov 02 12:25:25 volumio volumio[3794]: verbose: MPD COMMAND [object Object] Nov 02 12:25:25 volumio volumio[3794]: verbose: MPD COMMAND [object Object] Nov 02 12:25:25 volumio volumio[3794]: verbose: MPD COMMAND [object Object] Nov 02 12:25:25 volumio volumio[3794]: verbose: MPD COMMAND [object Object] Nov 02 12:25:25 volumio volumio[3794]: verbose: MPD COMMAND [object Object] Nov 02 12:25:25 volumio volumio[3794]: info: Nov 02 12:25:25 volumio volumio[3794]: ---------------------------- MPD announces system playlist update Nov 02 12:25:25 volumio volumio[3794]: info: Ignoring MPD Status Update Nov 02 12:25:25 volumio volumio[3794]: info: Nov 02 12:25:25 volumio volumio[3794]: ---------------------------- MPD announces system playlist update Nov 02 12:25:25 volumio volumio[3794]: info: Ignoring MPD Status Update Nov 02 12:25:25 volumio volumio[3794]: info: ------------------------------ 8ms Nov 02 12:25:25 volumio volumio[3794]: info: ------------------------------ 7ms Nov 02 12:25:25 volumio volumio[3794]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 02 12:25:25 volumio volumio[3794]: verbose: ControllerMpd::sendMpdCommand play Nov 02 12:25:25 volumio volumio[3794]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 02 12:25:25 volumio volumio[3794]: verbose: ControllerMpd::sendMpdCommand play Nov 02 12:25:25 volumio volumio[3794]: info: ------------------------------ 4ms Nov 02 12:25:25 volumio volumio[3794]: info: sendMpdCommand play took 3 milliseconds Nov 02 12:25:25 volumio volumio[3794]: info: sendMpdCommand play took 3 milliseconds Nov 02 12:25:25 volumio volumio[3794]: verbose: ControllerMpd::sendMpdCommand stop Nov 02 12:25:25 volumio volumio[3794]: verbose: ControllerMpd::sendMpdCommand stop Nov 02 12:25:25 volumio volumio[3794]: info: ControllerMpd::getState Nov 02 12:25:25 volumio volumio[3794]: verbose: ControllerMpd::sendMpdCommand status Nov 02 12:25:25 volumio volumio[3794]: info: Nov 02 12:25:25 volumio volumio[3794]: ---------------------------- MPD announces state update: player Nov 02 12:25:25 volumio volumio[3794]: info: sendMpdCommand stop took 72 milliseconds Nov 02 12:25:25 volumio volumio[3794]: info: ControllerMpd::getState Nov 02 12:25:25 volumio volumio[3794]: verbose: ControllerMpd::sendMpdCommand status Nov 02 12:25:25 volumio volumio[3794]: info: sendMpdCommand stop took 40 milliseconds Nov 02 12:25:25 volumio volumio[3794]: verbose: ControllerMpd::sendMpdCommand clear Nov 02 12:25:25 volumio volumio[3794]: info: Nov 02 12:25:25 volumio volumio[3794]: ---------------------------- MPD announces system playlist update Nov 02 12:25:25 volumio volumio[3794]: info: Ignoring MPD Status Update Nov 02 12:25:25 volumio volumio[3794]: info: Nov 02 12:25:25 volumio volumio[3794]: ---------------------------- MPD announces system playlist update Nov 02 12:25:25 volumio volumio[3794]: info: Ignoring MPD Status Update Nov 02 12:25:25 volumio volumio[3794]: info: sendMpdCommand status took 4 milliseconds Nov 02 12:25:25 volumio volumio[3794]: info: sendMpdCommand status took 3 milliseconds Nov 02 12:25:25 volumio volumio[3794]: info: sendMpdCommand clear took 4 milliseconds Nov 02 12:25:25 volumio volumio[3794]: info: sendMpdCommand clear took 3 milliseconds Nov 02 12:25:25 volumio volumio[3794]: verbose: ControllerMpd::parseState Nov 02 12:25:25 volumio volumio[3794]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 12:25:25 volumio volumio[3794]: verbose: ControllerMpd::parseState Nov 02 12:25:25 volumio volumio[3794]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 12:25:25 volumio volumio[3794]: verbose: ControllerMpd::sendMpdCommand addid "https://rr3---sn-8xjug5oxu-f5fs.googlevideo.com/videoplayback?expire=1762104324&ei=pD8Haf-7IdGDi9oP9YzYwAY&ip=89.231.107.132&id=o-ABjEOPyWPnCVgNYJlvc8yLbYNJEqWz7LK_Xdjy_OnY-9&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=129&met=1762082724%2C&mh=ks&mm=31%2C29&mn=sn-8xjug5oxu-f5fs%2Csn-f5f7lnld&ms=au%2Crdu&mv=m&mvi=3&pl=19&rms=au%2Cau&gcr=pl&initcwndbps=3485000&bui=AdEuB5Sjxv9Bh8KG7jwOkpS1Qpg7-dEaOh6lULetO9BiJlQpBOtGZxVeUpiWzZPk4Za7jBHpaePUbgp5&spc=6b0G_OVszPxOJ0iI9TTzJyBv8Yii1gq9eUSWVTtFSka0ikqUI-PUWxUd2IOI5WvhEO8&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=OtGRpNPHsZzzvF5FgzWmfGcQ&rqh=1&gir=yes&clen=5191365&dur=310.541&lmt=1760014346220842&mt=1762082338&fvip=2&keepalive=yes&fexp=51552689%2C51565115%2C51565681%2C51580968&c=WEB_REMIX&sefc=1&txp=1432534&n=v&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAJj272a5iA0-C0EqgKzt-Eaz5svGHeML2c-w4K0N_t4CAiEAmuhYfLlLjCDxPhy8_PJ7nOf4kkyRgcZduMMgU6reumQ%3D&pot=MpoB1rhdfObtiqbWhpPdO2XHuPAbna0eaSE8Z6gbAEJvK47YbKRm00zBTMa2MU9lPoeoDjy77N6Oe0uA8QroOtjAOWvpmYk4tMnQfaJmERlTSGqHRMQmZjriWN5z9CGwNeq60XoxGwpHIBavRzplYEspkliSNsjn7fcfnneCMkAvfFotS_WqHWVlmJssgL2ZqIIS5Fbq-dLhgfGvNg%3D%3D&cver=1.20250219.01.00&t.YouTube" Nov 02 12:25:25 volumio volumio[3794]: verbose: ControllerMpd::sendMpdCommand addid "https://rr3---sn-8xjug5oxu-f5fs.googlevideo.com/videoplayback?expire=1762104324&ei=pD8Hafq3JtjNi9oPseeZgAU&ip=89.231.107.132&id=o-AD-YDad-SHyQjjJcmAcDL0eeLKoHuYt0lZGKeSS1R9Fp&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1762082724%2C&mh=ks&mm=31%2C29&mn=sn-8xjug5oxu-f5fs%2Csn-f5f7lnld&ms=au%2Crdu&mv=m&mvi=3&pl=19&rms=au%2Cau&gcr=pl&initcwndbps=3485000&bui=AdEuB5TCLgsyGEn4QjqWbPpO0IxPxG_wLjfEhxSP2iSZHo6Yfcg-s0D5TqhTSw7G_ANDAhjZAl7pQXLK&spc=6b0G_NIN3HsyUdPIcb7eKKuxa77EnTRvok8RZYotdrkZNzgRmTnvvWOQH6JyxivOAIA&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=-pmpMn1qUHrymX-Ws16vPYwQ&rqh=1&gir=yes&clen=5191365&dur=310.541&lmt=1760014346220842&mt=1762082338&fvip=2&keepalive=yes&fexp=51552689%2C51565115%2C51565681%2C51580968&c=WEB_REMIX&sefc=1&txp=1432534&n=v&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRAIgYr2oD7VvUWn7s6B5-UG9U5BfXoJiO2tipiqkpyz05LMCIFP1UuLgWRb6A0FcIvYo8-V4XD4kUVd_WK0a6Ml6JcTx&pot=MpoB1rhdfObtiqbWhpPdO2XHuPAbna0eaSE8Z6gbAEJvK47YbKRm00zBTMa2MU9lPoeoDjy77N6Oe0uA8QroOtjAOWvpmYk4tMnQfaJmERlTSGqHRMQmZjriWN5z9CGwNeq60XoxGwpHIBavRzplYEspkliSNsjn7fcfnneCMkAvfFotS_WqHWVlmJssgL2ZqIIS5Fbq-dLhgfGvNg%3D%3D&cver=1.20250219.01.00&t.YouTube" Nov 02 12:25:25 volumio volumio[3794]: error: updateQueue error: null Nov 02 12:25:25 volumio volumio[3794]: error: updateQueue error: null Nov 02 12:25:25 volumio volumio[3794]: info: Nov 02 12:25:25 volumio volumio[3794]: ---------------------------- MPD announces system playlist update Nov 02 12:25:25 volumio volumio[3794]: info: Ignoring MPD Status Update Nov 02 12:25:25 volumio volumio[3794]: info: Nov 02 12:25:25 volumio volumio[3794]: ---------------------------- MPD announces system playlist update Nov 02 12:25:25 volumio volumio[3794]: info: Ignoring MPD Status Update Nov 02 12:25:25 volumio volumio[3794]: info: ------------------------------ 7ms Nov 02 12:25:25 volumio volumio[3794]: info: ------------------------------ 7ms Nov 02 12:25:25 volumio volumio[3794]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 02 12:25:25 volumio volumio[3794]: info: sendMpdCommand playlistinfo took 4 milliseconds Nov 02 12:25:25 volumio volumio[3794]: info: sendMpdCommand addid "https://rr3---sn-8xjug5oxu-f5fs.googlevideo.com/videoplayback?expire=1762104324&ei=pD8Haf-7IdGDi9oP9YzYwAY&ip=89.231.107.132&id=o-ABjEOPyWPnCVgNYJlvc8yLbYNJEqWz7LK_Xdjy_OnY-9&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=129&met=1762082724%2C&mh=ks&mm=31%2C29&mn=sn-8xjug5oxu-f5fs%2Csn-f5f7lnld&ms=au%2Crdu&mv=m&mvi=3&pl=19&rms=au%2Cau&gcr=pl&initcwndbps=3485000&bui=AdEuB5Sjxv9Bh8KG7jwOkpS1Qpg7-dEaOh6lULetO9BiJlQpBOtGZxVeUpiWzZPk4Za7jBHpaePUbgp5&spc=6b0G_OVszPxOJ0iI9TTzJyBv8Yii1gq9eUSWVTtFSka0ikqUI-PUWxUd2IOI5WvhEO8&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=OtGRpNPHsZzzvF5FgzWmfGcQ&rqh=1&gir=yes&clen=5191365&dur=310.541&lmt=1760014346220842&mt=1762082338&fvip=2&keepalive=yes&fexp=51552689%2C51565115%2C51565681%2C51580968&c=WEB_REMIX&sefc=1&txp=1432534&n=v&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAJj272a5iA0-C0EqgKzt-Eaz5svGHeML2c-w4K0N_t4CAiEAmuhYfLlLjCDxPhy8_PJ7nOf4kkyRgcZduMMgU6reumQ%3D&pot=MpoB1rhdfObtiqbWhpPdO2XHuPAbna0eaSE8Z6gbAEJvK47YbKRm00zBTMa2MU9lPoeoDjy77N6Oe0uA8QroOtjAOWvpmYk4tMnQfaJmERlTSGqHRMQmZjriWN5z9CGwNeq60XoxGwpHIBavRzplYEspkliSNsjn7fcfnneCMkAvfFotS_WqHWVlmJssgL2ZqIIS5Fbq-dLhgfGvNg%3D%3D&cver=1.20250219.01.00&t.YouTube" took 4 milliseconds Nov 02 12:25:25 volumio volumio[3794]: info: sendMpdCommand addid "https://rr3---sn-8xjug5oxu-f5fs.googlevideo.com/videoplayback?expire=1762104324&ei=pD8Hafq3JtjNi9oPseeZgAU&ip=89.231.107.132&id=o-AD-YDad-SHyQjjJcmAcDL0eeLKoHuYt0lZGKeSS1R9Fp&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1762082724%2C&mh=ks&mm=31%2C29&mn=sn-8xjug5oxu-f5fs%2Csn-f5f7lnld&ms=au%2Crdu&mv=m&mvi=3&pl=19&rms=au%2Cau&gcr=pl&initcwndbps=3485000&bui=AdEuB5TCLgsyGEn4QjqWbPpO0IxPxG_wLjfEhxSP2iSZHo6Yfcg-s0D5TqhTSw7G_ANDAhjZAl7pQXLK&spc=6b0G_NIN3HsyUdPIcb7eKKuxa77EnTRvok8RZYotdrkZNzgRmTnvvWOQH6JyxivOAIA&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=-pmpMn1qUHrymX-Ws16vPYwQ&rqh=1&gir=yes&clen=5191365&dur=310.541&lmt=1760014346220842&mt=1762082338&fvip=2&keepalive=yes&fexp=51552689%2C51565115%2C51565681%2C51580968&c=WEB_REMIX&sefc=1&txp=1432534&n=v&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRAIgYr2oD7VvUWn7s6B5-UG9U5BfXoJiO2tipiqkpyz05LMCIFP1UuLgWRb6A0FcIvYo8-V4XD4kUVd_WK0a6Ml6JcTx&pot=MpoB1rhdfObtiqbWhpPdO2XHuPAbna0eaSE8Z6gbAEJvK47YbKRm00zBTMa2MU9lPoeoDjy77N6Oe0uA8QroOtjAOWvpmYk4tMnQfaJmERlTSGqHRMQmZjriWN5z9CGwNeq60XoxGwpHIBavRzplYEspkliSNsjn7fcfnneCMkAvfFotS_WqHWVlmJssgL2ZqIIS5Fbq-dLhgfGvNg%3D%3D&cver=1.20250219.01.00&t.YouTube" took 3 milliseconds Nov 02 12:25:25 volumio volumio[3794]: verbose: ControllerMpd::parseTrackInfo Nov 02 12:25:25 volumio volumio[3794]: verbose: ControllerMpd::parseTrackInfo Nov 02 12:25:25 volumio volumio[3794]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Nov 02 12:25:25 volumio volumio[3794]: verbose: MPD COMMAND [object Object] Nov 02 12:25:25 volumio volumio[3794]: verbose: MPD COMMAND [object Object] Nov 02 12:25:25 volumio volumio[3794]: verbose: MPD COMMAND [object Object] Nov 02 12:25:25 volumio volumio[3794]: verbose: MPD COMMAND [object Object] Nov 02 12:25:25 volumio volumio[3794]: verbose: MPD COMMAND [object Object] Nov 02 12:25:25 volumio volumio[3794]: info: ------------------------------ 15ms Nov 02 12:25:25 volumio sudo[4280]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //http://192.168.0.16//music /mnt/NAS/asustor Nov 02 12:25:25 volumio volumio[3794]: info: Nov 02 12:25:25 volumio volumio[3794]: ---------------------------- MPD announces system playlist update Nov 02 12:25:25 volumio volumio[3794]: info: Ignoring MPD Status Update Nov 02 12:25:25 volumio volumio[3794]: info: Nov 02 12:25:25 volumio volumio[3794]: ---------------------------- MPD announces system playlist update Nov 02 12:25:25 volumio volumio[3794]: info: Ignoring MPD Status Update Nov 02 12:25:25 volumio volumio[3794]: info: ------------------------------ 9ms Nov 02 12:25:25 volumio volumio[3794]: info: ------------------------------ 8ms Nov 02 12:25:25 volumio volumio[3794]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 02 12:25:25 volumio volumio[3794]: verbose: ControllerMpd::sendMpdCommand play Nov 02 12:25:25 volumio volumio[3794]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 02 12:25:25 volumio sudo[4280]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:25:25 volumio volumio[3794]: verbose: ControllerMpd::sendMpdCommand play Nov 02 12:25:25 volumio volumio[3794]: info: ------------------------------ 5ms Nov 02 12:25:25 volumio volumio[3794]: info: ------------------------------ 5ms Nov 02 12:25:25 volumio volumio[3794]: info: sendMpdCommand play took 4 milliseconds Nov 02 12:25:25 volumio volumio[3794]: info: sendMpdCommand play took 8 milliseconds Nov 02 12:25:25 volumio sudo[4280]: pam_unix(sudo:session): session closed for user root Nov 02 12:25:25 volumio volumio[3794]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Nov 02 12:25:25 volumio volumio[3794]: verbose: ControllerMpd::sendMpdCommand stop Nov 02 12:25:25 volumio volumio[3794]: verbose: ControllerMpd::sendMpdCommand stop Nov 02 12:25:25 volumio volumio[3794]: info: ControllerMpd::getState Nov 02 12:25:25 volumio volumio[3794]: verbose: ControllerMpd::sendMpdCommand status Nov 02 12:25:25 volumio volumio[3794]: info: Nov 02 12:25:25 volumio volumio[3794]: ---------------------------- MPD announces state update: player Nov 02 12:25:25 volumio volumio[3794]: info: sendMpdCommand stop took 83 milliseconds Nov 02 12:25:25 volumio volumio[3794]: info: ControllerMpd::getState Nov 02 12:25:25 volumio volumio[3794]: verbose: ControllerMpd::sendMpdCommand status Nov 02 12:25:25 volumio volumio[3794]: info: sendMpdCommand stop took 53 milliseconds Nov 02 12:25:25 volumio volumio[3794]: verbose: ControllerMpd::sendMpdCommand clear Nov 02 12:25:25 volumio volumio[3794]: verbose: ControllerMpd::sendMpdCommand clear Nov 02 12:25:25 volumio volumio[3794]: info: Nov 02 12:25:25 volumio volumio[3794]: ---------------------------- MPD announces system playlist update Nov 02 12:25:25 volumio volumio[3794]: info: Ignoring MPD Status Update Nov 02 12:25:25 volumio volumio[3794]: info: Nov 02 12:25:25 volumio volumio[3794]: ---------------------------- MPD announces system playlist update Nov 02 12:25:25 volumio volumio[3794]: info: Ignoring MPD Status Update Nov 02 12:25:25 volumio volumio[3794]: info: sendMpdCommand status took 3 milliseconds Nov 02 12:25:25 volumio volumio[3794]: info: sendMpdCommand status took 2 milliseconds Nov 02 12:25:25 volumio volumio[3794]: info: sendMpdCommand clear took 2 milliseconds Nov 02 12:25:25 volumio volumio[3794]: info: sendMpdCommand clear took 3 milliseconds Nov 02 12:25:25 volumio volumio[3794]: verbose: ControllerMpd::parseState Nov 02 12:25:25 volumio volumio[3794]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 12:25:25 volumio volumio[3794]: verbose: ControllerMpd::parseState Nov 02 12:25:25 volumio volumio[3794]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 12:25:25 volumio volumio[3794]: verbose: ControllerMpd::sendMpdCommand addid "https://rr3---sn-8xjug5oxu-f5fs.googlevideo.com/videoplayback?expire=1762104324&ei=pD8HacLyH7zGi9oPuaej2Ak&ip=89.231.107.132&id=o-APZdCWC3wH9RAfe4zzHHNXgRU9Vmxfl4COYPvW5Rplg7&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1762082724%2C&mh=ks&mm=31%2C29&mn=sn-8xjug5oxu-f5fs%2Csn-f5f7kn7z&ms=au%2Crdu&mv=m&mvi=3&pcm2cms=yes&pl=19&rms=au%2Cau&gcr=pl&initcwndbps=3485000&bui=AdEuB5T3uixKEo3vt4FuBc-lEB4HE3ZQ7_Awy_4C5N9LiRAjEgmt37SGL6VUNILeZmGe8xJmg8qtXDof&spc=6b0G_Cy1X5KmNc4q1GkqGvTsfPznfQBxT-Z8ip0qESgGEwHlBQWvqWRcvI9R0QzohGI&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=SOk6VmMNICtOVj3PxJd_j8AQ&rqh=1&gir=yes&clen=5191365&dur=310.541&lmt=1760014346220842&mt=1762082338&fvip=5&keepalive=yes&fexp=51552689%2C51565115%2C51565681%2C51580968&c=WEB_REMIX&sefc=1&txp=1432534&n=v&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpcm2cms%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIhAO1XPxtBtXH0JEekVeoftntMks8xEW82eD3A33_79N1EAiAPsY-EBSBXuZU6GUsGDddhq9PsP_nQO67lo2J0dL3VwA%3D%3D&pot=MpoB1rhdfObtiqbWhpPdO2XHuPAbna0eaSE8Z6gbAEJvK47YbKRm00zBTMa2MU9lPoeoDjy77N6Oe0uA8QroOtjAOWvpmYk4tMnQfaJmERlTSGqHRMQmZjriWN5z9CGwNeq60XoxGwpHIBavRzplYEspkliSNsjn7fcfnneCMkAvfFotS_WqHWVlmJssgL2ZqIIS5Fbq-dLhgfGvNg%3D%3D&cver=1.20250219.01.00&t.YouTube" Nov 02 12:25:25 volumio volumio[3794]: verbose: ControllerMpd::sendMpdCommand addid "https://rr3---sn-8xjug5oxu-f5fs.googlevideo.com/videoplayback?expire=1762104324&ei=pD8HaeWpKLSDi9oPzYvcIQ&ip=89.231.107.132&id=o-AMpoOqYDSkoQbxX2IlABmJ2ygdkf2Fr7el9Ak5BKhu7c&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1762082724%2C&mh=ks&mm=31%2C29&mn=sn-8xjug5oxu-f5fs%2Csn-f5f7kn7z&ms=au%2Crdu&mv=m&mvi=3&pl=19&rms=au%2Cau&gcr=pl&initcwndbps=3485000&bui=AdEuB5RO5GaEOaHZ7MQOJHWelgV7atCODvMkSUNtxe67rkxHkL2hnMDKGhN_ipABeEDn9tTabZ8dSLSx&spc=6b0G_AKTA04xpXk7rCz7CV2euHFHx-_Fog7EV2EjhTpivMqUNhmwTg3pTOzKl9CJGZA&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=XbhflUOypR0Bw6EkWutqiBAQ&rqh=1&gir=yes&clen=5191365&dur=310.541&lmt=1760014346220842&mt=1762082338&fvip=5&keepalive=yes&fexp=51552689%2C51565115%2C51565681%2C51580968&c=WEB_REMIX&sefc=1&txp=1432534&n=v&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAP-dyTKYLlyGdihAboBi5A5iFRGpPti7hTBex2OmJ8x3AiEA-4ZtC_auauph-sqAVnNjqTDdcJZBOjHT6ZznvGLfFTo%3D&pot=MpoB1rhdfObtiqbWhpPdO2XHuPAbna0eaSE8Z6gbAEJvK47YbKRm00zBTMa2MU9lPoeoDjy77N6Oe0uA8QroOtjAOWvpmYk4tMnQfaJmERlTSGqHRMQmZjriWN5z9CGwNeq60XoxGwpHIBavRzplYEspkliSNsjn7fcfnneCMkAvfFotS_WqHWVlmJssgL2ZqIIS5Fbq-dLhgfGvNg%3D%3D&cver=1.20250219.01.00&t.YouTube" Nov 02 12:25:25 volumio volumio[3794]: error: updateQueue error: null Nov 02 12:25:25 volumio volumio[3794]: error: updateQueue error: null Nov 02 12:25:25 volumio volumio[3794]: info: Nov 02 12:25:25 volumio volumio[3794]: ---------------------------- MPD announces system playlist update Nov 02 12:25:25 volumio volumio[3794]: info: Ignoring MPD Status Update Nov 02 12:25:25 volumio volumio[3794]: info: Nov 02 12:25:25 volumio volumio[3794]: ---------------------------- MPD announces system playlist update Nov 02 12:25:25 volumio volumio[3794]: info: Ignoring MPD Status Update Nov 02 12:25:25 volumio volumio[3794]: info: ------------------------------ 6ms Nov 02 12:25:25 volumio volumio[3794]: info: ------------------------------ 5ms Nov 02 12:25:25 volumio volumio[3794]: info: sendMpdCommand playlistinfo took 4 milliseconds Nov 02 12:25:25 volumio volumio[3794]: info: sendMpdCommand playlistinfo took 4 milliseconds Nov 02 12:25:25 volumio volumio[3794]: info: sendMpdCommand addid "https://rr3---sn-8xjug5oxu-f5fs.googlevideo.com/videoplayback?expire=1762104324&ei=pD8HacLyH7zGi9oPuaej2Ak&ip=89.231.107.132&id=o-APZdCWC3wH9RAfe4zzHHNXgRU9Vmxfl4COYPvW5Rplg7&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1762082724%2C&mh=ks&mm=31%2C29&mn=sn-8xjug5oxu-f5fs%2Csn-f5f7kn7z&ms=au%2Crdu&mv=m&mvi=3&pcm2cms=yes&pl=19&rms=au%2Cau&gcr=pl&initcwndbps=3485000&bui=AdEuB5T3uixKEo3vt4FuBc-lEB4HE3ZQ7_Awy_4C5N9LiRAjEgmt37SGL6VUNILeZmGe8xJmg8qtXDof&spc=6b0G_Cy1X5KmNc4q1GkqGvTsfPznfQBxT-Z8ip0qESgGEwHlBQWvqWRcvI9R0QzohGI&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=SOk6VmMNICtOVj3PxJd_j8AQ&rqh=1&gir=yes&clen=5191365&dur=310.541&lmt=1760014346220842&mt=1762082338&fvip=5&keepalive=yes&fexp=51552689%2C51565115%2C51565681%2C51580968&c=WEB_REMIX&sefc=1&txp=1432534&n=v&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpcm2cms%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIhAO1XPxtBtXH0JEekVeoftntMks8xEW82eD3A33_79N1EAiAPsY-EBSBXuZU6GUsGDddhq9PsP_nQO67lo2J0dL3VwA%3D%3D&pot=MpoB1rhdfObtiqbWhpPdO2XHuPAbna0eaSE8Z6gbAEJvK47YbKRm00zBTMa2MU9lPoeoDjy77N6Oe0uA8QroOtjAOWvpmYk4tMnQfaJmERlTSGqHRMQmZjriWN5z9CGwNeq60XoxGwpHIBavRzplYEspkliSNsjn7fcfnneCMkAvfFotS_WqHWVlmJssgL2ZqIIS5Fbq-dLhgfGvNg%3D%3D&cver=1.20250219.01.00&t.YouTube" took 4 milliseconds Nov 02 12:25:25 volumio volumio[3794]: info: sendMpdCommand addid "https://rr3---sn-8xjug5oxu-f5fs.googlevideo.com/videoplayback?expire=1762104324&ei=pD8HaeWpKLSDi9oPzYvcIQ&ip=89.231.107.132&id=o-AMpoOqYDSkoQbxX2IlABmJ2ygdkf2Fr7el9Ak5BKhu7c&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1762082724%2C&mh=ks&mm=31%2C29&mn=sn-8xjug5oxu-f5fs%2Csn-f5f7kn7z&ms=au%2Crdu&mv=m&mvi=3&pl=19&rms=au%2Cau&gcr=pl&initcwndbps=3485000&bui=AdEuB5RO5GaEOaHZ7MQOJHWelgV7atCODvMkSUNtxe67rkxHkL2hnMDKGhN_ipABeEDn9tTabZ8dSLSx&spc=6b0G_AKTA04xpXk7rCz7CV2euHFHx-_Fog7EV2EjhTpivMqUNhmwTg3pTOzKl9CJGZA&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=XbhflUOypR0Bw6EkWutqiBAQ&rqh=1&gir=yes&clen=5191365&dur=310.541&lmt=1760014346220842&mt=1762082338&fvip=5&keepalive=yes&fexp=51552689%2C51565115%2C51565681%2C51580968&c=WEB_REMIX&sefc=1&txp=1432534&n=v&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhAP-dyTKYLlyGdihAboBi5A5iFRGpPti7hTBex2OmJ8x3AiEA-4ZtC_auauph-sqAVnNjqTDdcJZBOjHT6ZznvGLfFTo%3D&pot=MpoB1rhdfObtiqbWhpPdO2XHuPAbna0eaSE8Z6gbAEJvK47YbKRm00zBTMa2MU9lPoeoDjy77N6Oe0uA8QroOtjAOWvpmYk4tMnQfaJmERlTSGqHRMQmZjriWN5z9CGwNeq60XoxGwpHIBavRzplYEspkliSNsjn7fcfnneCMkAvfFotS_WqHWVlmJssgL2ZqIIS5Fbq-dLhgfGvNg%3D%3D&cver=1.20250219.01.00&t.YouTube" took 4 milliseconds Nov 02 12:25:25 volumio volumio[3794]: verbose: ControllerMpd::parseTrackInfo Nov 02 12:25:25 volumio volumio[3794]: verbose: ControllerMpd::parseTrackInfo Nov 02 12:25:25 volumio volumio[3794]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Nov 02 12:25:25 volumio volumio[3794]: verbose: MPD COMMAND [object Object] Nov 02 12:25:25 volumio volumio[3794]: verbose: MPD COMMAND [object Object] Nov 02 12:25:25 volumio volumio[3794]: verbose: MPD COMMAND [object Object] Nov 02 12:25:25 volumio volumio[3794]: verbose: MPD COMMAND [object Object] Nov 02 12:25:25 volumio volumio[3794]: verbose: MPD COMMAND [object Object] Nov 02 12:25:25 volumio volumio[3794]: verbose: MPD COMMAND [object Object] Nov 02 12:25:25 volumio volumio[3794]: info: ------------------------------ 12ms Nov 02 12:25:25 volumio volumio[3794]: info: Nov 02 12:25:25 volumio volumio[3794]: ---------------------------- MPD announces system playlist update Nov 02 12:25:25 volumio volumio[3794]: info: Ignoring MPD Status Update Nov 02 12:25:25 volumio volumio[3794]: info: Nov 02 12:25:25 volumio volumio[3794]: ---------------------------- MPD announces system playlist update Nov 02 12:25:25 volumio volumio[3794]: info: Ignoring MPD Status Update Nov 02 12:25:25 volumio volumio[3794]: info: ------------------------------ 6ms Nov 02 12:25:25 volumio volumio[3794]: info: ------------------------------ 5ms Nov 02 12:25:25 volumio volumio[3794]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 02 12:25:25 volumio volumio[3794]: verbose: ControllerMpd::sendMpdCommand play Nov 02 12:25:25 volumio volumio[3794]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 02 12:25:25 volumio volumio[3794]: verbose: ControllerMpd::sendMpdCommand play Nov 02 12:25:25 volumio volumio[3794]: info: ------------------------------ 3ms Nov 02 12:25:25 volumio volumio[3794]: info: ------------------------------ 3ms Nov 02 12:25:25 volumio volumio[3794]: info: sendMpdCommand play took 2 milliseconds Nov 02 12:25:25 volumio volumio[3794]: info: sendMpdCommand play took 2 milliseconds Nov 02 12:25:25 volumio volumio[3794]: verbose: ControllerMpd::sendMpdCommand stop Nov 02 12:25:25 volumio volumio[3794]: info: ControllerMpd::getState Nov 02 12:25:25 volumio volumio[3794]: verbose: ControllerMpd::sendMpdCommand status Nov 02 12:25:25 volumio volumio[3794]: info: Nov 02 12:25:25 volumio volumio[3794]: ---------------------------- MPD announces state update: player Nov 02 12:25:25 volumio volumio[3794]: info: sendMpdCommand stop took 44 milliseconds Nov 02 12:25:25 volumio volumio[3794]: info: ControllerMpd::getState Nov 02 12:25:25 volumio volumio[3794]: verbose: ControllerMpd::sendMpdCommand status Nov 02 12:25:25 volumio volumio[3794]: verbose: ControllerMpd::sendMpdCommand clear Nov 02 12:25:25 volumio volumio[3794]: info: Nov 02 12:25:25 volumio volumio[3794]: ---------------------------- MPD announces system playlist update Nov 02 12:25:25 volumio volumio[3794]: info: Ignoring MPD Status Update Nov 02 12:25:25 volumio volumio[3794]: info: sendMpdCommand status took 3 milliseconds Nov 02 12:25:25 volumio volumio[3794]: info: sendMpdCommand status took 2 milliseconds Nov 02 12:25:25 volumio volumio[3794]: info: sendMpdCommand clear took 2 milliseconds Nov 02 12:25:25 volumio volumio[3794]: verbose: ControllerMpd::parseState Nov 02 12:25:25 volumio volumio[3794]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 12:25:25 volumio volumio[3794]: verbose: ControllerMpd::parseState Nov 02 12:25:25 volumio volumio[3794]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 12:25:25 volumio volumio[3794]: verbose: ControllerMpd::sendMpdCommand addid "https://rr3---sn-8xjug5oxu-f5fs.googlevideo.com/videoplayback?expire=1762104324&ei=pD8HaabVJJC9zPsPj72TCA&ip=89.231.107.132&id=o-ALmBCmWnF7FvO9Ca4QilbR_QwGMyyd8hrFqU7bCPZ7Uw&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1762082724%2C&mh=ks&mm=31%2C29&mn=sn-8xjug5oxu-f5fs%2Csn-f5f7kn7z&ms=au%2Crdu&mv=m&mvi=3&pl=19&rms=au%2Cau&gcr=pl&initcwndbps=3485000&bui=AdEuB5TFVrF9mW2qteAQHLEY9ukKXkBNeZyDI8KR3lgPP2K78FbxRJ3f0Jx6BpFLKJ2PPwoUxkbPUr9F&spc=6b0G_DcZ5oyKzKJuP3dM0Cc2G9d-36pndKBfrdbnxFs_8OoLuE9PmtyrPIjrIe1i0qw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=XHSP9wWFrUF7cDvcveO-cfUQ&rqh=1&gir=yes&clen=5191365&dur=310.541&lmt=1760014346220842&mt=1762082338&fvip=5&keepalive=yes&fexp=51552689%2C51565115%2C51565681%2C51580968&c=WEB_REMIX&sefc=1&txp=1432534&n=v&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgBOnK_n_ptMWW4GfS0EID4Bs38zIGFlfZShDye2mZDpQCIQDteytrESRlUAH_nOJopyipYRwwSCOglMY06JaRGjT9kQ%3D%3D&pot=MpoB1rhdfObtiqbWhpPdO2XHuPAbna0eaSE8Z6gbAEJvK47YbKRm00zBTMa2MU9lPoeoDjy77N6Oe0uA8QroOtjAOWvpmYk4tMnQfaJmERlTSGqHRMQmZjriWN5z9CGwNeq60XoxGwpHIBavRzplYEspkliSNsjn7fcfnneCMkAvfFotS_WqHWVlmJssgL2ZqIIS5Fbq-dLhgfGvNg%3D%3D&cver=1.20250219.01.00&t.YouTube" Nov 02 12:25:25 volumio volumio[3794]: error: updateQueue error: null Nov 02 12:25:25 volumio volumio[3794]: info: Nov 02 12:25:25 volumio volumio[3794]: ---------------------------- MPD announces system playlist update Nov 02 12:25:25 volumio volumio[3794]: info: Ignoring MPD Status Update Nov 02 12:25:25 volumio volumio[3794]: info: ------------------------------ 5ms Nov 02 12:25:25 volumio volumio[3794]: info: sendMpdCommand playlistinfo took 4 milliseconds Nov 02 12:25:25 volumio volumio[3794]: info: sendMpdCommand playlistinfo took 4 milliseconds Nov 02 12:25:25 volumio volumio[3794]: info: sendMpdCommand addid "https://rr3---sn-8xjug5oxu-f5fs.googlevideo.com/videoplayback?expire=1762104324&ei=pD8HaabVJJC9zPsPj72TCA&ip=89.231.107.132&id=o-ALmBCmWnF7FvO9Ca4QilbR_QwGMyyd8hrFqU7bCPZ7Uw&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1762082724%2C&mh=ks&mm=31%2C29&mn=sn-8xjug5oxu-f5fs%2Csn-f5f7kn7z&ms=au%2Crdu&mv=m&mvi=3&pl=19&rms=au%2Cau&gcr=pl&initcwndbps=3485000&bui=AdEuB5TFVrF9mW2qteAQHLEY9ukKXkBNeZyDI8KR3lgPP2K78FbxRJ3f0Jx6BpFLKJ2PPwoUxkbPUr9F&spc=6b0G_DcZ5oyKzKJuP3dM0Cc2G9d-36pndKBfrdbnxFs_8OoLuE9PmtyrPIjrIe1i0qw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=XHSP9wWFrUF7cDvcveO-cfUQ&rqh=1&gir=yes&clen=5191365&dur=310.541&lmt=1760014346220842&mt=1762082338&fvip=5&keepalive=yes&fexp=51552689%2C51565115%2C51565681%2C51580968&c=WEB_REMIX&sefc=1&txp=1432534&n=v&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgBOnK_n_ptMWW4GfS0EID4Bs38zIGFlfZShDye2mZDpQCIQDteytrESRlUAH_nOJopyipYRwwSCOglMY06JaRGjT9kQ%3D%3D&pot=MpoB1rhdfObtiqbWhpPdO2XHuPAbna0eaSE8Z6gbAEJvK47YbKRm00zBTMa2MU9lPoeoDjy77N6Oe0uA8QroOtjAOWvpmYk4tMnQfaJmERlTSGqHRMQmZjriWN5z9CGwNeq60XoxGwpHIBavRzplYEspkliSNsjn7fcfnneCMkAvfFotS_WqHWVlmJssgL2ZqIIS5Fbq-dLhgfGvNg%3D%3D&cver=1.20250219.01.00&t.YouTube" took 4 milliseconds Nov 02 12:25:25 volumio volumio[3794]: verbose: ControllerMpd::parseTrackInfo Nov 02 12:25:25 volumio volumio[3794]: verbose: ControllerMpd::parseTrackInfo Nov 02 12:25:25 volumio volumio[3794]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Nov 02 12:25:25 volumio volumio[3794]: verbose: MPD COMMAND [object Object] Nov 02 12:25:25 volumio volumio[3794]: verbose: MPD COMMAND [object Object] Nov 02 12:25:25 volumio volumio[3794]: verbose: MPD COMMAND [object Object] Nov 02 12:25:25 volumio volumio[3794]: info: ------------------------------ 10ms Nov 02 12:25:25 volumio volumio[3794]: info: Nov 02 12:25:25 volumio volumio[3794]: ---------------------------- MPD announces system playlist update Nov 02 12:25:25 volumio volumio[3794]: info: Ignoring MPD Status Update Nov 02 12:25:25 volumio volumio[3794]: info: ------------------------------ 4ms Nov 02 12:25:25 volumio volumio[3794]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 02 12:25:25 volumio volumio[3794]: verbose: ControllerMpd::sendMpdCommand play Nov 02 12:25:25 volumio volumio[3794]: info: ------------------------------ 1ms Nov 02 12:25:25 volumio volumio[3794]: info: sendMpdCommand play took 1 milliseconds Nov 02 12:25:26 volumio volumio[3794]: info: MYVOLUMIO: Adding device Nov 02 12:25:26 volumio volumio[3794]: info: MYVOLUMIO: Evaluating Server Nov 02 12:25:26 volumio volumio[3794]: info: ControllerMpd::getState Nov 02 12:25:26 volumio volumio[3794]: verbose: ControllerMpd::sendMpdCommand status Nov 02 12:25:26 volumio volumio[3794]: info: Nov 02 12:25:26 volumio volumio[3794]: ---------------------------- MPD announces state update: player Nov 02 12:25:26 volumio volumio[3794]: info: ControllerMpd::getState Nov 02 12:25:26 volumio volumio[3794]: verbose: ControllerMpd::sendMpdCommand status Nov 02 12:25:26 volumio volumio[3794]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 02 12:25:26 volumio volumio[3794]: info: sendMpdCommand status took 16 milliseconds Nov 02 12:25:26 volumio volumio[3794]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 02 12:25:26 volumio volumio[3794]: info: sendMpdCommand status took 17 milliseconds Nov 02 12:25:26 volumio volumio[3794]: verbose: ControllerMpd::parseState Nov 02 12:25:26 volumio volumio[3794]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 12:25:26 volumio volumio[3794]: verbose: ControllerMpd::parseState Nov 02 12:25:26 volumio volumio[3794]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 12:25:26 volumio volumio[3794]: info: sendMpdCommand clearerror took 14 milliseconds Nov 02 12:25:26 volumio volumio[3794]: info: sendMpdCommand clearerror took 12 milliseconds Nov 02 12:25:26 volumio volumio[3794]: info: sendMpdCommand playlistinfo took 5 milliseconds Nov 02 12:25:26 volumio volumio[3794]: info: sendMpdCommand playlistinfo took 6 milliseconds Nov 02 12:25:26 volumio volumio[3794]: verbose: ControllerMpd::parseTrackInfo Nov 02 12:25:26 volumio volumio[3794]: verbose: ControllerMpd::parseTrackInfo Nov 02 12:25:26 volumio volumio[3794]: info: CoreStateMachine::getQueue Nov 02 12:25:26 volumio volumio[3794]: info: CorePlayQueue::getQueue Nov 02 12:25:26 volumio volumio[3794]: info: CoreStateMachine::getQueue Nov 02 12:25:26 volumio volumio[3794]: info: CorePlayQueue::getQueue Nov 02 12:25:26 volumio volumio[3794]: info: ControllerMpd::pushState Nov 02 12:25:26 volumio volumio[3794]: info: CoreCommandRouter::servicePushState Nov 02 12:25:26 volumio volumio[3794]: info: CoreStateMachine::pushState Nov 02 12:25:26 volumio volumio[3794]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 12:25:26 volumio volumio[3794]: info: CoreCommandRouter::volumioPushState Nov 02 12:25:26 volumio volumio[3794]: info: CorePlayQueue::getTrack 0 Nov 02 12:25:26 volumio volumio[3794]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Voilà","artist":"André Rieu, Johann Strauss Orchestra oraz Emma Kok","album":"Voilà","uri":"https://rr3---sn-8xjug5oxu-f5fs.googlevideo.com/videoplayback?expire=1762104324&ei=pD8HaabVJJC9zPsPj72TCA&ip=89.231.107.132&id=o-ALmBCmWnF7FvO9Ca4QilbR_QwGMyyd8hrFqU7bCPZ7Uw&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1762082724%2C&mh=ks&mm=31%2C29&mn=sn-8xjug5oxu-f5fs%2Csn-f5f7kn7z&ms=au%2Crdu&mv=m&mvi=3&pl=19&rms=au%2Cau&gcr=pl&initcwndbps=3485000&bui=AdEuB5TFVrF9mW2qteAQHLEY9ukKXkBNeZyDI8KR3lgPP2K78FbxRJ3f0Jx6BpFLKJ2PPwoUxkbPUr9F&spc=6b0G_DcZ5oyKzKJuP3dM0Cc2G9d-36pndKBfrdbnxFs_8OoLuE9PmtyrPIjrIe1i0qw&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=XHSP9wWFrUF7cDvcveO-cfUQ&rqh=1&gir=yes&clen=5191365&dur=310.541&lmt=1760014346220842&mt=1762082338&fvip=5&keepalive=yes&fexp=51552689%2C51565115%2C51565681%2C51580968&c=WEB_REMIX&sefc=1&txp=1432534&n=v&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIgBOnK_n_ptMWW4GfS0EID4Bs38zIGFlfZShDye2mZDpQCIQDteytrESRlUAH_nOJopyipYRwwSCOglMY06JaRGjT9kQ%3D%3D&pot=MpoB1rhdfObtiqbWhpPdO2XHuPAbna0eaSE8Z6gbAEJvK47YbKRm00zBTMa2MU9lPoeoDjy77N6Oe0uA8QroOtjAOWvpmYk4tMnQfaJmERlTSGqHRMQmZjriWN5z9CGwNeq60XoxGwpHIBavRzplYEspkliSNsjn7fcfnneCMkAvfFotS_WqHWVlmJssgL2ZqIIS5Fbq-dLhgfGvNg%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Nov 02 12:25:26 volumio volumio[3794]: verbose: CURRENT POSITION 0 Nov 02 12:25:26 volumio volumio[3794]: info: CoreStateMachine::syncState stateService stop Nov 02 12:25:26 volumio volumio[3794]: info: CoreStateMachine::syncState currentStatus stop Nov 02 12:25:26 volumio volumio[3794]: info: CoreStateMachine::pushState Nov 02 12:25:26 volumio volumio[3794]: info: CoreCommandRouter::volumioPushState Nov 02 12:25:26 volumio volumio[3794]: info: No code Nov 02 12:25:26 volumio volumio[3794]: info: CoreStateMachine::pushState Nov 02 12:25:26 volumio volumio[3794]: info: CoreCommandRouter::volumioPushState Nov 02 12:25:26 volumio volumio[3794]: info: ------------------------------ 75ms Nov 02 12:25:26 volumio volumio[3794]: info: PeppySpectrum ---peppyspectrum status Nov 02 12:25:26 volumio volumio[3794]: info: PeppySpectrum ---peppyspectrum status undefined Nov 02 12:25:26 volumio volumio[3794]: info: PeppySpectrum ---peppyspectrum status undefined Nov 02 12:25:26 volumio volumio[3794]: info: FusionDsp - volume level for loudness 79 gain applied 5.08 Nov 02 12:25:26 volumio volumio[3794]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 02 12:25:26 volumio volumio[3794]: info: FusionDsp - Loudness is ON true Nov 02 12:25:26 volumio volumio[3794]: info: FusionDsp - volume level for loudness 79 gain applied 5.08 Nov 02 12:25:26 volumio volumio[3794]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 02 12:25:26 volumio volumio[3794]: info: FusionDsp - Loudness is ON true Nov 02 12:25:26 volumio volumio[3794]: info: FusionDsp - volume level for loudness 79 gain applied 5.08 Nov 02 12:25:26 volumio volumio[3794]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 02 12:25:26 volumio volumio[3794]: info: FusionDsp - Loudness is ON true Nov 02 12:25:26 volumio volumio[3794]: SPOTIFY: RECEIVED VOLUMIO VOLUME 79 Nov 02 12:25:26 volumio volumio[3794]: SPOTIFY: RECEIVED VOLUMIO VOLUME 79 Nov 02 12:25:26 volumio volumio[3794]: info: [ASDebug] CurState: PrevState: stop Nov 02 12:25:26 volumio volumio[3794]: info: [ASDebug] Togle GPIO: ON Nov 02 12:25:26 volumio volumio[3794]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 12:25:26 volumio volumio[3794]: Error: ESPIPE: invalid seek, write Nov 02 12:25:26 volumio volumio[3794]: at Object.writeSync (fs.js:711:3) Nov 02 12:25:26 volumio volumio[3794]: at Gpio.writeSync (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:243:8) Nov 02 12:25:26 volumio volumio[3794]: at AmpSwitchController.on (/data/plugins/system_controller/ampswitch/index.js:207:23) Nov 02 12:25:26 volumio volumio[3794]: at AmpSwitchController.parseStatus (/data/plugins/system_controller/ampswitch/index.js:190:86) Nov 02 12:25:26 volumio volumio[3794]: at Socket.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) Nov 02 12:25:26 volumio volumio[3794]: at Socket.onevent (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/socket.js:278:10) Nov 02 12:25:26 volumio volumio[3794]: at Socket.onpacket (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/socket.js:236:12) Nov 02 12:25:26 volumio volumio[3794]: at Manager. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15) Nov 02 12:25:26 volumio volumio[3794]: at Manager.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) Nov 02 12:25:26 volumio volumio[3794]: at Manager.ondecoded (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/manager.js:349:8) Nov 02 12:25:26 volumio volumio[3794]: at Decoder. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15) Nov 02 12:25:26 volumio volumio[3794]: at Decoder.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) Nov 02 12:25:26 volumio volumio[3794]: at Decoder.add (/data/plugins/system_controller/ampswitch/node_modules/socket.io-parser/index.js:254:12) Nov 02 12:25:26 volumio volumio[3794]: at Manager.ondata (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/manager.js:339:16) Nov 02 12:25:26 volumio volumio[3794]: at Socket. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15) Nov 02 12:25:26 volumio volumio[3794]: at Socket.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) { Nov 02 12:25:26 volumio volumio[3794]: errno: -29, Nov 02 12:25:26 volumio volumio[3794]: syscall: 'write', Nov 02 12:25:26 volumio volumio[3794]: code: 'ESPIPE' Nov 02 12:25:26 volumio volumio[3794]: } Nov 02 12:25:26 volumio volumio[3794]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 12:25:27 volumio sudo[4326]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-11-02 12:24 Nov 02 12:25:27 volumio sudo[4326]: 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="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"