-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Mon 2026-02-09 19:56:53 CET. -- Feb 09 19:56:34 volumio systemd-timedated[1017]: Changed local time to Mon Feb 9 19:56:34 2026 Feb 09 19:56:34 volumio systemd[1]: Starting Daily apt download activities... Feb 09 19:56:34 volumio sudo[1015]: pam_unix(sudo:session): session closed for user root Feb 09 19:56:34 volumio volumio-time-update[553]: volumio-time-update-util: System time updated successfully. Feb 09 19:56:34 volumio systemd[1]: Started Volumio Time Update Utility. Feb 09 19:56:34 volumio volumio[980]: info: Loading plugin "network"... Feb 09 19:56:34 volumio volumio[980]: info: Refreshing Cached IP Addresses Feb 09 19:56:34 volumio sudo[1038]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 09 19:56:34 volumio sudo[1038]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 09 19:56:34 volumio sudo[1038]: pam_unix(sudo:session): session closed for user root Feb 09 19:56:34 volumio sudo[1040]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 09 19:56:34 volumio sudo[1040]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 09 19:56:34 volumio sudo[1040]: pam_unix(sudo:session): session closed for user root Feb 09 19:56:34 volumio volumio[980]: info: Loading plugin "services"... Feb 09 19:56:34 volumio volumio[980]: info: Loading plugin "alsa_controller"... Feb 09 19:56:34 volumio sudo[1045]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 09 19:56:34 volumio sudo[1045]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 09 19:56:34 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 09 19:56:34 volumio volumio[980]: info: Loading plugin "wizard"... Feb 09 19:56:34 volumio volumio[980]: info: Loading plugin "networkfs"... Feb 09 19:56:34 volumio sudo[1075]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=rutger,password=lustvooroog29,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=2.0 //192.168.178.46/MEDIA\\muziek\\vrienden /mnt/NAS/vrienden Feb 09 19:56:34 volumio ntpd[969]: Soliciting pool server 37.1.205.111 Feb 09 19:56:34 volumio ntpd[969]: Soliciting pool server 94.237.125.46 Feb 09 19:56:34 volumio ntpd[969]: Soliciting pool server 87.251.32.228 Feb 09 19:56:34 volumio sudo[1075]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 09 19:56:34 volumio sudo[1078]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=rutger,password=lustvooroog,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=2.0 //ANGELICA/Users\\rutger\\Downloads\\music /mnt/NAS/laptop Feb 09 19:56:34 volumio sudo[1078]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 09 19:56:34 volumio volumio[980]: info: Starting Udev Watcher for removable devices Feb 09 19:56:34 volumio ntpd[969]: Soliciting pool server 178.239.19.61 Feb 09 19:56:34 volumio sudo[1083]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=rutger,password=lustvooroog29,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //cosimo/MEDIA\\muziek /mnt/NAS/musica Feb 09 19:56:34 volumio sudo[1083]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 09 19:56:34 volumio nmbd[719]: [2026/02/09 19:56:34.612427, 0] ../lib/util/become_daemon.c:138(daemon_ready) Feb 09 19:56:34 volumio nmbd[719]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Feb 09 19:56:34 volumio systemd[1]: Started Samba NMB Daemon. Feb 09 19:56:34 volumio volumio[980]: info: Ignoring mount for partition: boot Feb 09 19:56:34 volumio volumio[980]: info: Ignoring mount for partition: volumio Feb 09 19:56:34 volumio volumio[980]: info: Ignoring mount for partition: volumio_data Feb 09 19:56:34 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 09 19:56:34 volumio volumio[980]: info: Loading plugin "volumio_command_line_client"... Feb 09 19:56:34 volumio systemd[1]: Starting Samba Winbind Daemon... Feb 09 19:56:34 volumio volumio[980]: info: Loading plugin "upnp"... Feb 09 19:56:34 volumio volumio[980]: info: [1770663394680] Starting Upmpd Daemon Feb 09 19:56:34 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 09 19:56:34 volumio volumio[980]: info: Loading plugin "my_music"... Feb 09 19:56:34 volumio volumio[980]: info: Loading plugin "mpd"... Feb 09 19:56:34 volumio kernel: Key type cifs.spnego registered Feb 09 19:56:34 volumio kernel: Key type cifs.idmap registered Feb 09 19:56:34 volumio kernel: CIFS: Attempting to mount //ANGELICA/Users/rutger/Downloads/music Feb 09 19:56:34 volumio kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Feb 09 19:56:34 volumio systemd[1]: apt-daily.service: Succeeded. Feb 09 19:56:34 volumio systemd[1]: Started Daily apt download activities. Feb 09 19:56:34 volumio systemd[1]: Starting Daily apt upgrade and clean activities... Feb 09 19:56:34 volumio winbindd[1096]: [2026/02/09 19:56:34.931917, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Feb 09 19:56:34 volumio winbindd[1096]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Feb 09 19:56:34 volumio winbindd[1096]: [2026/02/09 19:56:34.952495, 0] ../lib/util/become_daemon.c:138(daemon_ready) Feb 09 19:56:34 volumio winbindd[1096]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Feb 09 19:56:34 volumio systemd[1]: Started Samba Winbind Daemon. Feb 09 19:56:34 volumio systemd[1]: Starting Samba SMB Daemon... Feb 09 19:56:35 volumio volumio[980]: info: Loading plugin "upnp_browser"... Feb 09 19:56:35 volumio systemd[1]: apt-daily-upgrade.service: Succeeded. Feb 09 19:56:35 volumio systemd[1]: Started Daily apt upgrade and clean activities. Feb 09 19:56:35 volumio smbd[1138]: [2026/02/09 19:56:35.466379, 0] ../lib/util/become_daemon.c:138(daemon_ready) Feb 09 19:56:35 volumio smbd[1138]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Feb 09 19:56:35 volumio systemd[1]: Started Samba SMB Daemon. Feb 09 19:56:35 volumio systemd[1]: Reached target Multi-User System. Feb 09 19:56:35 volumio systemd[1]: Reached target Graphical Interface. Feb 09 19:56:35 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Feb 09 19:56:35 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Feb 09 19:56:35 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Feb 09 19:56:35 volumio systemd[1]: Startup finished in 12.129s (kernel) + 25.771s (userspace) = 37.900s. Feb 09 19:56:35 volumio ntpd[969]: Soliciting pool server 178.239.19.63 Feb 09 19:56:35 volumio ntpd[969]: Soliciting pool server 103.149.168.180 Feb 09 19:56:35 volumio ntpd[969]: Soliciting pool server 81.172.248.188 Feb 09 19:56:35 volumio volumio[980]: info: Starting UPNP Browser Feb 09 19:56:35 volumio volumio[980]: info: Loading plugin "alarm-clock"... Feb 09 19:56:35 volumio volumio[980]: info: Loading plugin "airplay_emulation"... Feb 09 19:56:35 volumio volumio[980]: info: Starting Shairport Sync Feb 09 19:56:35 volumio volumio[980]: info: Loading plugin "last_100"... Feb 09 19:56:35 volumio volumio[980]: info: Loading plugin "webradio"... Feb 09 19:56:35 volumio volumio[980]: info: Loading plugin "i2s_dacs"... Feb 09 19:56:35 volumio volumio[980]: info: Loading plugin "volumiodiscovery"... Feb 09 19:56:35 volumio volumio[980]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 09 19:56:35 volumio volumio[980]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 09 19:56:35 volumio node[980]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 09 19:56:35 volumio volumio[980]: *** WARNING *** For more information see Feb 09 19:56:35 volumio volumio[980]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 09 19:56:35 volumio volumio[980]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 09 19:56:35 volumio volumio[980]: *** WARNING *** For more information see Feb 09 19:56:35 volumio node[980]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 09 19:56:35 volumio node[980]: *** WARNING *** For more information see Feb 09 19:56:35 volumio node[980]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 09 19:56:35 volumio node[980]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 09 19:56:35 volumio node[980]: *** WARNING *** For more information see Feb 09 19:56:35 volumio volumio[980]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 09 19:56:35 volumio volumio[980]: info: Discovery: Started advertising with name: Volumio Feb 09 19:56:35 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 09 19:56:35 volumio volumio[980]: info: Loading plugin "bandcamp"... Feb 09 19:56:36 volumio volumio-remote-updater[558]: [2026-02-09 19:56:36] [connect] Successful connection Feb 09 19:56:36 volumio ntpd[969]: Soliciting pool server 178.239.19.56 Feb 09 19:56:36 volumio ntpd[969]: Soliciting pool server 83.98.155.30 Feb 09 19:56:36 volumio volumio[980]: info: Loading plugin "spop"... Feb 09 19:56:37 volumio ntpd[969]: Soliciting pool server 5.255.124.190 Feb 09 19:56:37 volumio ntpd[969]: Soliciting pool server 2a02:898:62:f6::c0 Feb 09 19:56:37 volumio sudo[1045]: pam_unix(sudo:session): session closed for user root Feb 09 19:56:37 volumio volumio[980]: info: Loading plugin "outputs"... Feb 09 19:56:37 volumio volumio[980]: info: Loading plugin "albumart"... Feb 09 19:56:37 volumio volumio[980]: info: Plugin example_plugin is not enabled Feb 09 19:56:37 volumio volumio[980]: info: Loading plugin "inputs"... Feb 09 19:56:37 volumio volumio[980]: info: Loading plugin "updater_comm"... Feb 09 19:56:37 volumio volumio[980]: info: Plugin mpdemulation is not enabled Feb 09 19:56:37 volumio volumio[980]: info: Loading plugin "rest_api"... Feb 09 19:56:37 volumio volumio[980]: info: Loading plugin "websocket"... Feb 09 19:56:38 volumio volumio[980]: info: Starting Socket.io Server version 2.3.0 Feb 09 19:56:38 volumio volumio[980]: info: Loading i18n strings for locale en Feb 09 19:56:38 volumio volumio[980]: Updating browse sources language Feb 09 19:56:38 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 09 19:56:38 volumio volumio[980]: Forking 3 albumart workers Feb 09 19:56:38 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 09 19:56:38 volumio volumio[980]: info: CoreCommandRouter::initPlayerControls Feb 09 19:56:38 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 09 19:56:38 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 09 19:56:38 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 09 19:56:38 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 09 19:56:38 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 09 19:56:38 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 09 19:56:38 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 09 19:56:38 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 09 19:56:38 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 09 19:56:38 volumio volumio[980]: Express server listening on port 3000 Feb 09 19:56:38 volumio volumio[980]: [Metrics] WebUI: 6s 376.20ms Feb 09 19:56:38 volumio volumio[980]: info: CoreStateMachine::resetVolumioState Feb 09 19:56:38 volumio volumio[980]: info: CoreStateMachine::getcurrentVolume Feb 09 19:56:38 volumio volumio[980]: info: CoreCommandRouter::volumioRetrievevolume Feb 09 19:56:38 volumio volumio[980]: info: Volumio Network Manager: Network status updated: 2 Feb 09 19:56:38 volumio volumio-remote-updater[558]: [2026-02-09 19:56:38] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1770663396 101 Feb 09 19:56:38 volumio volumio[980]: 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: 1 Feb 09 19:56:38 volumio volumio[980]: info: CoreStateMachine::pushState Feb 09 19:56:38 volumio volumio[980]: info: CorePlayQueue::getTrack 0 Feb 09 19:56:38 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 19:56:38 volumio volumio[980]: info: CoreCommandRouter::volumioPushState Feb 09 19:56:38 volumio volumio[980]: info: CoreStateMachine::updateTrackBlock Feb 09 19:56:38 volumio volumio[980]: info: CorePlayQueue::getTrackBlock Feb 09 19:56:38 volumio volumio[980]: info: CoreCommandRouter::volumioRetrievevolume Feb 09 19:56:38 volumio volumio[980]: info: Reloading queue from file Feb 09 19:56:38 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 09 19:56:38 volumio volumio[980]: info: CoreStateMachine::setRepeat null single undefined Feb 09 19:56:38 volumio volumio[980]: info: CoreStateMachine::pushState Feb 09 19:56:38 volumio volumio[980]: info: CorePlayQueue::getTrack 0 Feb 09 19:56:38 volumio volumio[980]: info: CoreCommandRouter::volumioPushState Feb 09 19:56:38 volumio volumio[980]: info: CoreStateMachine::setRandom null Feb 09 19:56:38 volumio volumio[980]: info: CoreStateMachine::pushState Feb 09 19:56:38 volumio volumio[980]: info: CorePlayQueue::getTrack 0 Feb 09 19:56:38 volumio volumio[980]: info: CoreCommandRouter::volumioPushState Feb 09 19:56:38 volumio volumio[980]: info: Setting Device type: Raspberry PI Feb 09 19:56:38 volumio volumio[980]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Feb 09 19:56:38 volumio volumio[980]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03112 Feb 09 19:56:38 volumio volumio[980]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Feb 09 19:56:38 volumio volumio[980]: info: CoreStateMachine::pushState Feb 09 19:56:38 volumio volumio[980]: info: CorePlayQueue::getTrack 0 Feb 09 19:56:38 volumio volumio[980]: info: CoreCommandRouter::volumioPushState Feb 09 19:56:38 volumio volumio[980]: Starting albumart workers Feb 09 19:56:38 volumio volumio[980]: info: Discovery: adding 110acada-71af-47ce-b174-51c52ac6eae9 Feb 09 19:56:38 volumio volumio[980]: info: Discovery: Found device Volumio Feb 09 19:56:38 volumio volumio[980]: info: CoreCommandRouter::volumioGetState Feb 09 19:56:38 volumio volumio[980]: info: CorePlayQueue::getTrack 0 Feb 09 19:56:38 volumio volumio[980]: Starting albumart workers Feb 09 19:56:38 volumio volumio[980]: Starting albumart workers Feb 09 19:56:42 volumio ntpd[969]: receive: Unexpected origin timestamp 0xed34ac69.91dc17a5 does not match aorg 0000000000.00000000 from server@94.237.125.46 xmt 0xed34ac6a.b8c5c3da Feb 09 19:56:42 volumio ntpd[969]: receive: Unexpected origin timestamp 0xed34ac69.91df3b85 does not match aorg 0000000000.00000000 from server@172.233.59.169 xmt 0xed34ac6a.b8058ed6 Feb 09 19:56:42 volumio ntpd[969]: receive: Unexpected origin timestamp 0xed34ac69.91d7e48c does not match aorg 0000000000.00000000 from server@83.98.155.30 xmt 0xed34ac6a.b8fa9633 Feb 09 19:56:42 volumio ntpd[969]: receive: Unexpected origin timestamp 0xed34ac69.91da5d24 does not match aorg 0000000000.00000000 from server@37.1.205.111 xmt 0xed34ac6a.b9237047 Feb 09 19:56:42 volumio ntpd[969]: receive: Unexpected origin timestamp 0xed34ac69.91d6b8e0 does not match aorg 0000000000.00000000 from server@178.239.19.56 xmt 0xed34ac6a.b9310510 Feb 09 19:56:42 volumio ntpd[969]: receive: Unexpected origin timestamp 0xed34ac69.91d997dc does not match aorg 0000000000.00000000 from server@178.239.19.61 xmt 0xed34ac6a.b936803a Feb 09 19:56:42 volumio ntpd[969]: receive: Unexpected origin timestamp 0xed34ac69.91ddbda7 does not match aorg 0000000000.00000000 from server@86.80.166.233 xmt 0xed34ac6a.b91abb9e Feb 09 19:56:42 volumio ntpd[969]: receive: Unexpected origin timestamp 0xed34ac69.91dff56c does not match aorg 0000000000.00000000 from server@178.215.228.24 xmt 0xed34ac6a.b992ab98 Feb 09 19:56:42 volumio ntpd[969]: receive: Unexpected origin timestamp 0xed34ac69.91d8caac does not match aorg 0000000000.00000000 from server@103.149.168.180 xmt 0xed34ac6a.b966c434 Feb 09 19:56:42 volumio ntpd[969]: receive: Unexpected origin timestamp 0xed34ac69.91dcf0e7 does not match aorg 0000000000.00000000 from server@103.149.168.181 xmt 0xed34ac6a.b9678c16 Feb 09 19:56:42 volumio ntpd[969]: receive: Unexpected origin timestamp 0xed34ac69.91d3eb42 does not match aorg 0000000000.00000000 from server@5.255.124.190 xmt 0xed34ac6a.c0ca4bf4 Feb 09 19:56:43 volumio systemd[1]: systemd-fsckd.service: Succeeded. Feb 09 19:56:44 volumio sh[497]: timed out Feb 09 19:56:44 volumio dhcpcd[518]: timed out Feb 09 19:56:44 volumio sh[497]: dhcpcd exited Feb 09 19:56:44 volumio dhcpcd[518]: dhcpcd exited Feb 09 19:56:44 volumio sh[497]: ifup: failed to bring up eth0 Feb 09 19:56:44 volumio systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Feb 09 19:56:44 volumio systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Feb 09 19:56:45 volumio sudo[1273]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 09 19:56:45 volumio sudo[1273]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 09 19:56:45 volumio sudo[1273]: pam_unix(sudo:session): session closed for user root Feb 09 19:56:45 volumio sudo[1275]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 09 19:56:45 volumio sudo[1275]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 09 19:56:45 volumio sudo[1275]: pam_unix(sudo:session): session closed for user root Feb 09 19:56:45 volumio sudo[1279]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 09 19:56:45 volumio sudo[1279]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 09 19:56:45 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Feb 09 19:56:45 volumio sudo[1279]: pam_unix(sudo:session): session closed for user root Feb 09 19:56:45 volumio volumio[980]: info: Upmpdcli Daemon Started Feb 09 19:56:46 volumio sudo[1078]: pam_unix(sudo:session): session closed for user root Feb 09 19:56:46 volumio volumio[980]: info: Cannot mount NAS laptop at system boot, trial number 1 ,retrying in 5 seconds Feb 09 19:56:46 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Feb 09 19:56:46 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -115 Feb 09 19:56:46 volumio kernel: CIFS: Attempting to mount //cosimo/MEDIA/muziek Feb 09 19:56:46 volumio volumio[980]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Feb 09 19:56:46 volumio sudo[1083]: pam_unix(sudo:session): session closed for user root Feb 09 19:56:46 volumio kernel: CIFS: Status code returned 0xc000006d STATUS_LOGON_FAILURE Feb 09 19:56:46 volumio kernel: CIFS: VFS: \\cosimo Send error in SessSetup = -13 Feb 09 19:56:46 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -13 Feb 09 19:56:46 volumio kernel: CIFS: Attempting to mount //192.168.178.46/MEDIA/muziek/vrienden Feb 09 19:56:46 volumio volumio[980]: info: Cannot mount NAS musica at system boot, trial number 1 ,retrying in 5 seconds Feb 09 19:56:46 volumio sudo[1075]: pam_unix(sudo:session): session closed for user root Feb 09 19:56:46 volumio kernel: CIFS: Status code returned 0xc000006d STATUS_LOGON_FAILURE Feb 09 19:56:46 volumio kernel: CIFS: VFS: \\192.168.178.46 Send error in SessSetup = -13 Feb 09 19:56:46 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -13 Feb 09 19:56:46 volumio volumio[980]: info: Cannot mount NAS vrienden at system boot, trial number 1 ,retrying in 5 seconds Feb 09 19:56:46 volumio volumio[980]: info: Completed loading Core Plugins Feb 09 19:56:46 volumio volumio[980]: info: Preparing to generate the ALSA configuration file Feb 09 19:56:46 volumio volumio[980]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Feb 09 19:56:46 volumio volumio[980]: info: Reading ALSA contributions from plugins. Feb 09 19:56:46 volumio volumio[980]: info: Asound.conf file unchanged, so no further update is needed Feb 09 19:56:46 volumio volumio[980]: info: Output device has changed, restarting MPD Feb 09 19:56:46 volumio volumio[980]: info: Output device has changed, restarting Shairport Sync Feb 09 19:56:46 volumio sudo[1305]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 09 19:56:46 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 09 19:56:46 volumio sudo[1305]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 09 19:56:46 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 09 19:56:46 volumio sudo[1305]: pam_unix(sudo:session): session closed for user root Feb 09 19:56:46 volumio sudo[1307]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 09 19:56:46 volumio sudo[1307]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 09 19:56:46 volumio volumio[980]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 09 19:56:46 volumio volumio[980]: info: ___________ START PLUGINS ___________ Feb 09 19:56:46 volumio volumio[980]: info: ControllerMpd::onStart: Initializing MPD Feb 09 19:56:46 volumio volumio[980]: info: Creating MPD Configuration file Feb 09 19:56:46 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 09 19:56:46 volumio volumio[980]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 09 19:56:46 volumio volumio[980]: info: [1770663406355] CoreMusicLibrary::Adding element Media Servers Feb 09 19:56:46 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 09 19:56:46 volumio sudo[1315]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 09 19:56:46 volumio sudo[1315]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 09 19:56:46 volumio sudo[1315]: pam_unix(sudo:session): session closed for user root Feb 09 19:56:46 volumio systemd[1]: Listening on mpd.socket. Feb 09 19:56:46 volumio volumio[980]: info: UPNP Browser: Client initialized successfully Feb 09 19:56:46 volumio systemd[1]: Starting Music Player Daemon... Feb 09 19:56:46 volumio sudo[1317]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 09 19:56:46 volumio sudo[1317]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 09 19:56:46 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 09 19:56:46 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 09 19:56:46 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Feb 09 19:56:46 volumio systemd[1]: mpd.service: Succeeded. Feb 09 19:56:46 volumio systemd[1]: Stopped Music Player Daemon. Feb 09 19:56:46 volumio systemd[1]: Starting Music Player Daemon... Feb 09 19:56:46 volumio volumio[980]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 09 19:56:46 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 09 19:56:46 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 09 19:56:46 volumio volumio[980]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 09 19:56:46 volumio volumio[980]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 09 19:56:46 volumio volumio[980]: info: [1770663406446] CoreMusicLibrary::Adding element Last_100 Feb 09 19:56:46 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 09 19:56:46 volumio volumio[980]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 09 19:56:46 volumio volumio[980]: info: [1770663406448] CoreMusicLibrary::Adding element Webradio Feb 09 19:56:46 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 09 19:56:46 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 09 19:56:46 volumio volumio[980]: info: Initializing BBC Radios Feb 09 19:56:46 volumio sudo[1324]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 09 19:56:46 volumio sudo[1324]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 09 19:56:46 volumio sudo[1324]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Feb 09 19:56:46 volumio sudo[1324]: pam_unix(sudo:session): session closed for user root Feb 09 19:56:46 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 09 19:56:46 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 09 19:56:46 volumio volumio[980]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 09 19:56:46 volumio volumio[980]: info: [1770663406507] CoreMusicLibrary::Adding element Bandcamp Discover Feb 09 19:56:46 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 09 19:56:46 volumio volumio[980]: Cannot find translation for source Bandcamp Discover Feb 09 19:56:46 volumio volumio[980]: info: Creating Spotify config file Feb 09 19:56:46 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 09 19:56:46 volumio volumio[980]: info: Volumio Calling Home Feb 09 19:56:46 volumio volumio[980]: info: MPD Permissions set Feb 09 19:56:46 volumio volumio[980]: info: MPD Permissions set Feb 09 19:56:46 volumio volumio[980]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Feb 09 19:56:46 volumio volumio[980]: info: Spotify config file written Feb 09 19:56:46 volumio sudo[1344]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 09 19:56:46 volumio sudo[1344]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 09 19:56:46 volumio volumio[980]: info: Volumio called home Feb 09 19:56:46 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 09 19:56:46 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 09 19:56:46 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 09 19:56:46 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 09 19:56:46 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 09 19:56:46 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 09 19:56:46 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 09 19:56:46 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 09 19:56:46 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 09 19:56:46 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 09 19:56:46 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 09 19:56:46 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 09 19:56:46 volumio systemd[1]: Started go-librespot Daemon. Feb 09 19:56:46 volumio sudo[1344]: pam_unix(sudo:session): session closed for user root Feb 09 19:56:46 volumio go-librespot[1346]: go-librespot daemon starting... Feb 09 19:56:46 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 09 19:56:46 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 09 19:56:46 volumio volumio[980]: info: No need to fix Spotify hosts Feb 09 19:56:46 volumio volumio[980]: info: CoreCommandRouter::volumioGetState Feb 09 19:56:46 volumio volumio[980]: info: CorePlayQueue::getTrack 0 Feb 09 19:56:46 volumio volumio[980]: info: Starting Shairport Sync Feb 09 19:56:46 volumio volumio[980]: info: Starting Shairport Sync Feb 09 19:56:46 volumio volumio[980]: info: Starting Shairport Sync Feb 09 19:56:46 volumio sudo[1357]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 09 19:56:46 volumio sudo[1357]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 09 19:56:46 volumio sudo[1359]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 09 19:56:46 volumio sudo[1359]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 09 19:56:46 volumio sudo[1362]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 09 19:56:46 volumio sudo[1362]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 09 19:56:46 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 09 19:56:46 volumio systemd[1]: shairport-sync.service: Succeeded. Feb 09 19:56:46 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 09 19:56:46 volumio volumio[980]: info: New Spotify access tokenBQCgELeUKi... Feb 09 19:56:46 volumio volumio[980]: info: Spotify credentials grant success - running version from March 24, 2019 Feb 09 19:56:47 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 09 19:56:47 volumio sudo[1359]: pam_unix(sudo:session): session closed for user root Feb 09 19:56:47 volumio volumio[980]: info: Shairport-Sync Started Feb 09 19:56:47 volumio volumio[980]: Error adding Membership: Error: addMembership EINVAL Feb 09 19:56:47 volumio sudo[1357]: pam_unix(sudo:session): session closed for user root Feb 09 19:56:47 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 09 19:56:47 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Feb 09 19:56:47 volumio systemd[1]: shairport-sync.service: Succeeded. Feb 09 19:56:47 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 09 19:56:47 volumio volumio[980]: info: Shairport-Sync Started Feb 09 19:56:47 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 09 19:56:47 volumio sudo[1362]: pam_unix(sudo:session): session closed for user root Feb 09 19:56:47 volumio volumio[980]: info: Shairport-Sync Started Feb 09 19:56:47 volumio volumio[980]: SPOTIFY: User informations: {"country":"NL","display_name":"Signor Elefante","email":"rutger@qualitylab.nl","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/eg1wgdxsof4iehq6we558z61g"},"followers":{"href":null,"total":6},"href":"https://api.spotify.com/v1/users/eg1wgdxsof4iehq6we558z61g","id":"eg1wgdxsof4iehq6we558z61g","images":[{"height":300,"url":"https://i.scdn.co/image/ab6775700000ee8594cf5f13a5a1d82c4c6af6a8","width":300},{"height":64,"url":"https://i.scdn.co/image/ab67757000003b8294cf5f13a5a1d82c4c6af6a8","width":64}],"product":"premium","type":"user","uri":"spotify:user:eg1wgdxsof4iehq6we558z61g"} Feb 09 19:56:47 volumio volumio[980]: info: Spotify Successfully logged in Feb 09 19:56:47 volumio volumio[980]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 09 19:56:47 volumio volumio[980]: info: [1770663407082] CoreMusicLibrary::Adding element Spotify Feb 09 19:56:47 volumio volumio[980]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 09 19:56:47 volumio volumio[980]: Cannot find translation for source Bandcamp Discover Feb 09 19:56:47 volumio volumio[980]: Cannot find translation for source Spotify Feb 09 19:56:47 volumio go-librespot[1346]: time="2026-02-09T19:56:47+01:00" level=info msg="running go-librespot 0.6.2" Feb 09 19:56:47 volumio go-librespot[1346]: time="2026-02-09T19:56:47+01:00" level=debug msg="app state loaded" Feb 09 19:56:47 volumio go-librespot[1346]: time="2026-02-09T19:56:47+01:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 09 19:56:47 volumio go-librespot[1346]: time="2026-02-09T19:56:47+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-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 09 19:56:47 volumio go-librespot[1346]: time="2026-02-09T19:56:47+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 09 19:56:47 volumio go-librespot[1346]: time="2026-02-09T19:56:47+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 09 19:56:47 volumio go-librespot[1346]: time="2026-02-09T19:56:47+01:00" level=info msg="zeroconf server listening on port 36875" Feb 09 19:56:47 volumio go-librespot[1346]: time="2026-02-09T19:56:47+01:00" level=debug msg="obtained new client token: AADNW5H+Urds4YDwt91WegRWGSweXmu0b7fzjbK7o2RAT9rEu/tMAgp4rzHbxp9dkN0GU9y6/NjXpNZfnC0xMTrGTY3HQtQP+bjIVtSGD5bJG0PyV0gBzHBU/Krj4CB2ilQY24+QHrvIvyGW8crehuzKY+YC/ffbC4ySqr5xss0ANV0hG2YEys8pu34TrbBmi5ZsWNcPZeql4QymSFojbJTyEpGWFOItTggg6RXESXqYbmToJIIa0pF36w==" Feb 09 19:56:47 volumio go-librespot[1346]: time="2026-02-09T19:56:47+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 09 19:56:47 volumio go-librespot[1346]: time="2026-02-09T19:56:47+01:00" level=debug msg="completed keyexchange" Feb 09 19:56:47 volumio go-librespot[1346]: time="2026-02-09T19:56:47+01:00" level=debug msg="completed challenge" Feb 09 19:56:47 volumio go-librespot[1346]: time="2026-02-09T19:56:47+01:00" level=info msg="authenticated AP" username="eg*********************1g" Feb 09 19:56:48 volumio go-librespot[1346]: time="2026-02-09T19:56:48+01:00" level=info msg="authenticated Login5" username="eg*********************1g" Feb 09 19:56:48 volumio go-librespot[1346]: time="2026-02-09T19:56:48+01:00" level=debug msg="initializing zeroconf session" username="eg*********************1g" Feb 09 19:56:48 volumio go-librespot[1346]: time="2026-02-09T19:56:48+01:00" level=debug msg="dealer connection opened" Feb 09 19:56:48 volumio go-librespot[1346]: time="2026-02-09T19:56:48+01:00" level=trace msg="starting accesspoint recv loop" Feb 09 19:56:48 volumio go-librespot[1346]: time="2026-02-09T19:56:48+01:00" level=trace msg="starting dealer recv loop" Feb 09 19:56:48 volumio go-librespot[1346]: time="2026-02-09T19:56:48+01:00" level=trace msg="received accesspoint ping" Feb 09 19:56:48 volumio go-librespot[1346]: time="2026-02-09T19:56:48+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Feb 09 19:56:48 volumio go-librespot[1346]: time="2026-02-09T19:56:48+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Feb 09 19:56:48 volumio go-librespot[1346]: time="2026-02-09T19:56:48+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Feb 09 19:56:48 volumio go-librespot[1346]: time="2026-02-09T19:56:48+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Feb 09 19:56:48 volumio go-librespot[1346]: time="2026-02-09T19:56:48+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Feb 09 19:56:48 volumio go-librespot[1346]: time="2026-02-09T19:56:48+01:00" level=debug msg="received connection id: YWZiMDY5OGItMTJh...QUZFMjA1QURDMg==" Feb 09 19:56:48 volumio go-librespot[1346]: time="2026-02-09T19:56:48+01:00" level=trace msg="received accesspoint pong ack" Feb 09 19:56:48 volumio go-librespot[1346]: time="2026-02-09T19:56:48+01:00" level=debug msg="put connect state because NEW_DEVICE" Feb 09 19:56:48 volumio mpd[1331]: Feb 09 19:56 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 09 19:56:48 volumio systemd[1]: Started Music Player Daemon. Feb 09 19:56:48 volumio sudo[1317]: pam_unix(sudo:session): session closed for user root Feb 09 19:56:48 volumio sudo[1307]: pam_unix(sudo:session): session closed for user root Feb 09 19:56:48 volumio volumio[980]: info: Completed starting Core Plugins Feb 09 19:56:48 volumio volumio[980]: info: ------------------------------------------- Feb 09 19:56:48 volumio volumio[980]: info: ----- MyVolumio plugins startup ---- Feb 09 19:56:48 volumio volumio[980]: info: ------------------------------------------- Feb 09 19:56:48 volumio volumio[980]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 09 19:56:48 volumio volumio[980]: info: MPD running with PID1331 Feb 09 19:56:48 volumio volumio[980]: ,establishing connection Feb 09 19:56:48 volumio volumio[980]: error: MPD error: The expression evaluated to a falsy value: Feb 09 19:56:48 volumio volumio[980]: assert.ok(self.idling) Feb 09 19:56:48 volumio volumio[980]: error: The expression evaluated to a falsy value: Feb 09 19:56:48 volumio volumio[980]: assert.ok(self.idling) Feb 09 19:56:48 volumio volumio[980]: error: MPD error: The expression evaluated to a falsy value: Feb 09 19:56:48 volumio volumio[980]: assert.ok(self.idling) Feb 09 19:56:48 volumio volumio[980]: error: The expression evaluated to a falsy value: Feb 09 19:56:48 volumio volumio[980]: assert.ok(self.idling) Feb 09 19:56:48 volumio volumio[980]: error: updateQueue error: null Feb 09 19:56:49 volumio volumio[980]: info: go-librespot daemon successfully initialized Feb 09 19:56:50 volumio volumio[1281]: Generating RSA private key, 4096 bit long modulus (2 primes) Feb 09 19:56:51 volumio sudo[1398]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=rutger,password=lustvooroog,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=2.0 //ANGELICA/Users\\rutger\\Downloads\\music /mnt/NAS/laptop Feb 09 19:56:51 volumio sudo[1398]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 09 19:56:51 volumio kernel: CIFS: Attempting to mount //ANGELICA/Users/rutger/Downloads/music Feb 09 19:56:51 volumio sudo[1403]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=rutger,password=lustvooroog29,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //cosimo/MEDIA\\muziek /mnt/NAS/musica Feb 09 19:56:51 volumio sudo[1403]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 09 19:56:51 volumio sudo[1408]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=rutger,password=lustvooroog29,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=2.0 //192.168.178.46/MEDIA\\muziek\\vrienden /mnt/NAS/vrienden Feb 09 19:56:51 volumio sudo[1408]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 09 19:56:51 volumio sudo[1413]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 09 19:56:51 volumio sudo[1413]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 09 19:56:51 volumio sudo[1416]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 09 19:56:51 volumio sudo[1416]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 09 19:56:51 volumio systemd[1]: Started MPD Monitor Service. Feb 09 19:56:51 volumio sudo[1413]: pam_unix(sudo:session): session closed for user root Feb 09 19:56:51 volumio mpd_monitor.sh[1417]: MPD Monitor Service: Starting MPD Monitor Service Feb 09 19:56:51 volumio volumio[980]: info: Successfully started MPD Monitor Feb 09 19:56:51 volumio systemd[1]: Stopping MPD Monitor Service... Feb 09 19:56:51 volumio systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Feb 09 19:56:51 volumio systemd[1]: mpd_monitor.service: Succeeded. Feb 09 19:56:51 volumio systemd[1]: Stopped MPD Monitor Service. Feb 09 19:56:51 volumio systemd[1]: Started MPD Monitor Service. Feb 09 19:56:51 volumio sudo[1416]: pam_unix(sudo:session): session closed for user root Feb 09 19:56:51 volumio mpd_monitor.sh[1420]: MPD Monitor Service: Starting MPD Monitor Service Feb 09 19:56:51 volumio volumio[980]: info: Successfully started MPD Monitor Feb 09 19:56:52 volumio volumio[1281]: .........................++++ Feb 09 19:56:52 volumio go-librespot[1346]: time="2026-02-09T19:56:52+01:00" level=debug msg="handling transfer player command from f43bf48a8e30898b406cb268af8266a129edc4e1" Feb 09 19:56:52 volumio go-librespot[1346]: time="2026-02-09T19:56:52+01:00" level=debug msg="resolved context of track" uri="spotify:album:1rZGSWC3pMmejDwYG0ZXma" Feb 09 19:56:52 volumio go-librespot[1346]: time="2026-02-09T19:56:52+01:00" level=trace msg="fetched new page 0 with 8 items (list: 8)" uri="spotify:album:1rZGSWC3pMmejDwYG0ZXma" Feb 09 19:56:52 volumio go-librespot[1346]: time="2026-02-09T19:56:52+01:00" level=debug msg="loading track (paused: false, position: 2770ms)" uri="spotify:track:5k10LV4157WEzdFmB97NQR" Feb 09 19:56:52 volumio go-librespot[1346]: time="2026-02-09T19:56:52+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 09 19:56:52 volumio go-librespot[1346]: time="2026-02-09T19:56:52+01:00" level=trace msg="emitting websocket event: will_play" Feb 09 19:56:52 volumio go-librespot[1346]: time="2026-02-09T19:56:52+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 310" Feb 09 19:56:52 volumio go-librespot[1346]: time="2026-02-09T19:56:52+01:00" level=debug msg="selected format OGG_VORBIS_320 (7e9a2baa48efef5e1fbd5a3b1e8e2f5e320459e7)" uri="spotify:track:5k10LV4157WEzdFmB97NQR" Feb 09 19:56:52 volumio go-librespot[1346]: time="2026-02-09T19:56:52+01:00" level=debug msg="requested aes key for file 7e9a2baa48efef5e1fbd5a3b1e8e2f5e320459e7, gid: 5k10LV4157WEzdFmB97NQR" Feb 09 19:56:52 volumio go-librespot[1346]: time="2026-02-09T19:56:52+01:00" level=trace msg="found 2 cdn urls" uri="spotify:track:5k10LV4157WEzdFmB97NQR" Feb 09 19:56:52 volumio volumio[980]: info: Initializing connection to go-librespot Websocket Feb 09 19:56:52 volumio go-librespot[1346]: time="2026-02-09T19:56:52+01:00" level=debug msg="new websocket client" Feb 09 19:56:52 volumio volumio[980]: info: Connection to go-librespot Websocket established Feb 09 19:56:52 volumio go-librespot[1346]: time="2026-02-09T19:56:52+01:00" level=debug msg="fetched first chunk of 52, total size is 26868632 bytes" uri="spotify:track:5k10LV4157WEzdFmB97NQR" Feb 09 19:56:52 volumio go-librespot[1346]: time="2026-02-09T19:56:52+01:00" level=trace msg="seek to 2770ms (diff: 128ms, samples: 122157, bytes: 109597)" uri="spotify:track:5k10LV4157WEzdFmB97NQR" Feb 09 19:56:52 volumio go-librespot[1346]: time="2026-02-09T19:56:52+01:00" level=debug msg="created new output device" Feb 09 19:56:53 volumio go-librespot[1346]: time="2026-02-09T19:56:53+01:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 100000 us, period size = 4410 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 5 frames, PCM format = FLOAT_LE" Feb 09 19:56:53 volumio go-librespot[1346]: time="2026-02-09T19:56:53+01:00" level=info msg="loaded track \"The Cinema Show - 2007 Stereo Mix\" (paused: false, position: 2770ms, duration: 648680ms, prefetched: false)" uri="spotify:track:5k10LV4157WEzdFmB97NQR" Feb 09 19:56:53 volumio kernel: bcm2835-i2s fe203000.i2s: I2S SYNC error! Feb 09 19:56:53 volumio go-librespot[1346]: time="2026-02-09T19:56:53+01:00" level=debug msg="fetched chunk 1/51, size: 524288" uri="spotify:track:5k10LV4157WEzdFmB97NQR" Feb 09 19:56:53 volumio go-librespot[1346]: time="2026-02-09T19:56:53+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 09 19:56:53 volumio go-librespot[1346]: time="2026-02-09T19:56:53+01:00" level=trace msg="scheduling prefetch in 616s" Feb 09 19:56:53 volumio go-librespot[1346]: time="2026-02-09T19:56:53+01:00" level=trace msg="emitting websocket event: metadata" Feb 09 19:56:53 volumio go-librespot[1346]: time="2026-02-09T19:56:53+01:00" level=trace msg="emitting websocket event: active" Feb 09 19:56:53 volumio go-librespot[1346]: time="2026-02-09T19:56:53+01:00" level=debug msg="sending successful reply for dealer request" Feb 09 19:56:53 volumio go-librespot[1346]: time="2026-02-09T19:56:53+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Feb 09 19:56:53 volumio volumio[980]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:5k10LV4157WEzdFmB97NQR","name":"The Cinema Show - 2007 Stereo Mix","artist_names":["Genesis"],"album_name":"Selling England by the Pound (2007 Stereo Mix)","album_cover_url":"https://i.scdn.co/image/ab67616d00001e021b4e7e89ede1a1c06078ff62","position":2770,"duration":648680,"release_date":"year:1973 month:10 day:12","track_number":7,"disc_number":1}} Feb 09 19:56:53 volumio volumio[980]: SPOTIFY: received: {"type":"active","data":null} Feb 09 19:56:53 volumio volumio[980]: info: Aligning Spotify Volume to Volumio Volume Feb 09 19:56:53 volumio volumio[980]: info: CoreCommandRouter::volumioGetState Feb 09 19:56:53 volumio volumio[980]: info: CorePlayQueue::getTrack 0 Feb 09 19:56:53 volumio volumio[980]: info: Setting Spotify Volume from Volumio: 100 Feb 09 19:56:53 volumio go-librespot[1346]: time="2026-02-09T19:56:53+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 09 19:56:53 volumio go-librespot[1346]: time="2026-02-09T19:56:53+01:00" level=trace msg="emitting websocket event: playing" Feb 09 19:56:53 volumio volumio[980]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:album:1rZGSWC3pMmejDwYG0ZXma","uri":"spotify:track:5k10LV4157WEzdFmB97NQR","resume":false,"play_origin":""}} Feb 09 19:56:53 volumio volumio[980]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 09 19:56:53 volumio volumio[980]: TypeError: Cannot read property 'service' of undefined Feb 09 19:56:53 volumio volumio[980]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:346:50) Feb 09 19:56:53 volumio volumio[980]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:272:18) Feb 09 19:56:53 volumio volumio[980]: at WebSocket.message (/data/plugins/music_service/spop/index.js:199:14) Feb 09 19:56:53 volumio volumio[980]: at WebSocket.emit (events.js:315:20) Feb 09 19:56:53 volumio volumio[980]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1220:20) Feb 09 19:56:53 volumio volumio[980]: at Receiver.emit (events.js:315:20) Feb 09 19:56:53 volumio volumio[980]: at Receiver.dataMessage (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:596:14) Feb 09 19:56:53 volumio volumio[980]: at Receiver.getData (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:496:10) Feb 09 19:56:53 volumio volumio[980]: at Receiver.startLoop (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:167:16) Feb 09 19:56:53 volumio volumio[980]: at Receiver._write (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:94:10) Feb 09 19:56:53 volumio volumio[980]: at writeOrBuffer (internal/streams/writable.js:358:12) Feb 09 19:56:53 volumio volumio[980]: at Receiver.Writable.write (internal/streams/writable.js:303:10) Feb 09 19:56:53 volumio volumio[980]: at Socket.socketOnData (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1355:35) Feb 09 19:56:53 volumio volumio[980]: at Socket.emit (events.js:315:20) Feb 09 19:56:53 volumio volumio[980]: at addChunk (internal/streams/readable.js:309:12) Feb 09 19:56:53 volumio volumio[980]: at readableAddChunk (internal/streams/readable.js:284:9) Feb 09 19:56:53 volumio volumio[980]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 09 19:56:53 volumio go-librespot[1346]: time="2026-02-09T19:56:53+01:00" level=debug msg="fetched chunk 2/51, size: 524288" uri="spotify:track:5k10LV4157WEzdFmB97NQR" Feb 09 19:56:53 volumio go-librespot[1346]: time="2026-02-09T19:56:53+01:00" level=debug msg="fetched chunk 3/51, size: 524288" uri="spotify:track:5k10LV4157WEzdFmB97NQR" Feb 09 19:56:53 volumio sudo[1435]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-09 19:55 Feb 09 19:56:53 volumio sudo[1435]: 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="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 05:43:24 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="15d0241b0cd805792809f3ddf4a0fc4e"