-- Logs begin at Thu 2019-02-14 11:12:00 CET, end at Sat 2024-11-02 15:34:16 CET. -- Nov 02 15:33:01 doosachterdetv volumio[1105]: info: ------------------------------------------- Nov 02 15:33:01 doosachterdetv volumio[1105]: info: ----- Volumio3 ---- Nov 02 15:33:01 doosachterdetv volumio[1105]: info: ------------------------------------------- Nov 02 15:33:01 doosachterdetv volumio[1105]: info: ----- System startup ---- Nov 02 15:33:01 doosachterdetv volumio[1105]: info: ------------------------------------------- Nov 02 15:33:02 doosachterdetv sh[523]: timed out Nov 02 15:33:02 doosachterdetv dhcpcd[566]: timed out Nov 02 15:33:02 doosachterdetv sh[523]: dhcpcd exited Nov 02 15:33:02 doosachterdetv dhcpcd[566]: dhcpcd exited Nov 02 15:33:02 doosachterdetv sh[523]: ifup: failed to bring up eth0 Nov 02 15:33:02 doosachterdetv systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Nov 02 15:33:02 doosachterdetv systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Nov 02 15:33:02 doosachterdetv volumio[1105]: info: MYVOLUMIO Environment detected Nov 02 15:33:02 doosachterdetv volumio[1105]: info: Plugin folders cleanup Nov 02 15:33:02 doosachterdetv volumio[1105]: info: Scanning into folder /volumio/app/plugins/ Nov 02 15:33:02 doosachterdetv volumio[1105]: info: Scanning category audio_interface Nov 02 15:33:02 doosachterdetv volumio[1105]: info: Scanning category miscellanea Nov 02 15:33:02 doosachterdetv volumio[1105]: info: Scanning category music_service Nov 02 15:33:02 doosachterdetv volumio[1105]: info: Scanning category plugins.json Nov 02 15:33:02 doosachterdetv volumio[1105]: info: Scanning category system_controller Nov 02 15:33:02 doosachterdetv volumio[1105]: info: Scanning category user_interface Nov 02 15:33:02 doosachterdetv volumio[1105]: info: Scanning into folder /data/plugins/ Nov 02 15:33:02 doosachterdetv volumio[1105]: info: Scanning category music_service Nov 02 15:33:02 doosachterdetv volumio[1105]: info: Plugin folders cleanup completed Nov 02 15:33:02 doosachterdetv volumio[1105]: info: ------------------------------------------- Nov 02 15:33:02 doosachterdetv volumio[1105]: info: ----- Core plugins startup ---- Nov 02 15:33:02 doosachterdetv volumio[1105]: info: ------------------------------------------- Nov 02 15:33:02 doosachterdetv volumio[1105]: info: Loading plugins from folder /volumio/app/plugins/ Nov 02 15:33:02 doosachterdetv volumio[1105]: info: Adding plugin upnp to MyMusic Plugins Nov 02 15:33:02 doosachterdetv volumio[1105]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 02 15:33:02 doosachterdetv volumio[1105]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 02 15:33:02 doosachterdetv volumio[1105]: info: Loading plugins from folder /data/plugins/ Nov 02 15:33:02 doosachterdetv volumio[1105]: info: Loading plugin "system"... Nov 02 15:33:03 doosachterdetv volumio[1105]: info: Loading plugin "appearance"... Nov 02 15:33:04 doosachterdetv volumio[1105]: info: Loading plugin "network"... Nov 02 15:33:04 doosachterdetv volumio[1105]: info: Refreshing Cached IP Addresses Nov 02 15:33:04 doosachterdetv sudo[1145]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 02 15:33:04 doosachterdetv sudo[1145]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 15:33:04 doosachterdetv volumio[1105]: info: Loading plugin "services"... Nov 02 15:33:04 doosachterdetv sudo[1145]: pam_unix(sudo:session): session closed for user root Nov 02 15:33:04 doosachterdetv sudo[1147]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 02 15:33:04 doosachterdetv sudo[1147]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 15:33:04 doosachterdetv volumio[1105]: info: Loading plugin "alsa_controller"... Nov 02 15:33:04 doosachterdetv sudo[1147]: pam_unix(sudo:session): session closed for user root Nov 02 15:33:04 doosachterdetv sudo[1150]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 02 15:33:04 doosachterdetv sudo[1150]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 15:33:04 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 15:33:04 doosachterdetv volumio[1105]: info: Loading plugin "wizard"... Nov 02 15:33:04 doosachterdetv volumio[1105]: info: Loading plugin "networkfs"... Nov 02 15:33:04 doosachterdetv sudo[1156]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=buizenradio@jongbloed.net,password=BuizenRadio_101,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.108/NAS /mnt/NAS/NAS Nov 02 15:33:04 doosachterdetv sudo[1156]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 15:33:04 doosachterdetv volumio[1105]: info: Starting Udev Watcher for removable devices Nov 02 15:33:04 doosachterdetv sudo[1158]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=buizenradio@jongbloed.net,password=BuizenRadio_101,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.106/media/Audio/Pianoconcert /mnt/NAS/Pianoconcerten Nov 02 15:33:04 doosachterdetv sudo[1158]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 15:33:04 doosachterdetv sudo[1162]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=buizenradio@jongbloed.net,password=BuizenRadio_101,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.106/media/Audio/CD-collectie /mnt/NAS/CD-collectie Nov 02 15:33:04 doosachterdetv sudo[1162]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 15:33:04 doosachterdetv volumio[1105]: info: Ignoring mount for partition: boot Nov 02 15:33:04 doosachterdetv volumio[1105]: info: Ignoring mount for partition: volumio Nov 02 15:33:04 doosachterdetv volumio[1105]: info: Ignoring mount for partition: volumio_data Nov 02 15:33:04 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 02 15:33:04 doosachterdetv volumio[1105]: info: Loading plugin "volumio_command_line_client"... Nov 02 15:33:04 doosachterdetv volumio[1105]: info: Loading plugin "upnp"... Nov 02 15:33:04 doosachterdetv volumio[1105]: info: [1730557984907] Starting Upmpd Daemon Nov 02 15:33:04 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 02 15:33:04 doosachterdetv volumio[1105]: info: Loading plugin "my_music"... Nov 02 15:33:04 doosachterdetv volumio[1105]: info: Loading plugin "mpd"... Nov 02 15:33:05 doosachterdetv kernel: Key type cifs.spnego registered Nov 02 15:33:05 doosachterdetv kernel: Key type cifs.idmap registered Nov 02 15:33:05 doosachterdetv 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. Nov 02 15:33:05 doosachterdetv kernel: CIFS: Attempting to mount \\192.168.1.106\media Nov 02 15:33:05 doosachterdetv volumio[1105]: info: Loading plugin "upnp_browser"... Nov 02 15:33:05 doosachterdetv sudo[1162]: pam_unix(sudo:session): session closed for user root Nov 02 15:33:05 doosachterdetv kernel: CIFS: Attempting to mount \\192.168.1.106\media Nov 02 15:33:05 doosachterdetv volumio[1105]: info: Loading plugin "alarm-clock"... Nov 02 15:33:05 doosachterdetv sudo[1158]: pam_unix(sudo:session): session closed for user root Nov 02 15:33:05 doosachterdetv kernel: CIFS: Attempting to mount \\192.168.1.108\NAS Nov 02 15:33:06 doosachterdetv volumio[1105]: info: Loading plugin "airplay_emulation"... Nov 02 15:33:06 doosachterdetv volumio[1105]: info: Starting Shairport Sync Nov 02 15:33:06 doosachterdetv volumio[1105]: info: Loading plugin "last_100"... Nov 02 15:33:06 doosachterdetv volumio[1105]: info: Loading plugin "webradio"... Nov 02 15:33:06 doosachterdetv volumio[1105]: info: Loading plugin "i2s_dacs"... Nov 02 15:33:06 doosachterdetv volumio[1105]: info: Loading plugin "volumiodiscovery"... Nov 02 15:33:06 doosachterdetv volumio[1105]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 02 15:33:06 doosachterdetv volumio[1105]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 15:33:06 doosachterdetv volumio[1105]: *** WARNING *** For more information see Nov 02 15:33:06 doosachterdetv volumio[1105]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 02 15:33:06 doosachterdetv volumio[1105]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 15:33:06 doosachterdetv node[1105]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 02 15:33:06 doosachterdetv volumio[1105]: *** WARNING *** For more information see Nov 02 15:33:06 doosachterdetv node[1105]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 15:33:06 doosachterdetv node[1105]: *** WARNING *** For more information see Nov 02 15:33:06 doosachterdetv node[1105]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 02 15:33:06 doosachterdetv node[1105]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 15:33:06 doosachterdetv node[1105]: *** WARNING *** For more information see Nov 02 15:33:06 doosachterdetv volumio[1105]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 02 15:33:06 doosachterdetv volumio[1105]: info: Discovery: Started advertising with name: DoosAchterDeTV Nov 02 15:33:06 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 02 15:33:06 doosachterdetv volumio[1105]: info: Loading plugin "youtube2"... Nov 02 15:33:07 doosachterdetv systemd[1]: systemd-hostnamed.service: Succeeded. Nov 02 15:33:07 doosachterdetv volumio-remote-updater[633]: [2024-11-02 15:33:07] [connect] Successful connection Nov 02 15:33:08 doosachterdetv sudo[1150]: pam_unix(sudo:session): session closed for user root Nov 02 15:33:08 doosachterdetv volumio[1105]: info: Loading plugin "outputs"... Nov 02 15:33:08 doosachterdetv volumio[1105]: info: Loading plugin "albumart"... Nov 02 15:33:08 doosachterdetv volumio[1105]: info: Plugin example_plugin is not enabled Nov 02 15:33:08 doosachterdetv volumio[1105]: info: Loading plugin "inputs"... Nov 02 15:33:08 doosachterdetv volumio[1105]: info: Loading plugin "updater_comm"... Nov 02 15:33:08 doosachterdetv volumio[1105]: info: Plugin mpdemulation is not enabled Nov 02 15:33:08 doosachterdetv volumio[1105]: info: Loading plugin "rest_api"... Nov 02 15:33:08 doosachterdetv volumio[1105]: info: Loading plugin "websocket"... Nov 02 15:33:08 doosachterdetv volumio[1105]: info: Starting Socket.io Server version 2.3.0 Nov 02 15:33:08 doosachterdetv volumio[1105]: info: Loading i18n strings for locale en Nov 02 15:33:08 doosachterdetv volumio[1105]: Updating browse sources language Nov 02 15:33:08 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 15:33:08 doosachterdetv volumio[1105]: Forking 3 albumart workers Nov 02 15:33:09 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 15:33:09 doosachterdetv volumio[1105]: info: CoreCommandRouter::initPlayerControls Nov 02 15:33:09 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 15:33:09 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 15:33:09 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 15:33:09 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 15:33:09 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 15:33:09 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 15:33:09 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 15:33:09 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 15:33:09 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 15:33:09 doosachterdetv volumio[1105]: Express server listening on port 3000 Nov 02 15:33:09 doosachterdetv volumio[1105]: [Metrics] WebUI: 8s 293.63ms Nov 02 15:33:09 doosachterdetv volumio[1105]: info: CoreStateMachine::resetVolumioState Nov 02 15:33:09 doosachterdetv volumio[1105]: info: CoreStateMachine::getcurrentVolume Nov 02 15:33:09 doosachterdetv volumio[1105]: info: CoreCommandRouter::volumioRetrievevolume Nov 02 15:33:09 doosachterdetv volumio[1105]: verbose: New Socket.io Connection to 192.168.1.130 from 192.168.1.101 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:132.0) Gecko/20100101 Firefox/132.0 Engine version: 3 Transport: polling Total Clients: 1 Nov 02 15:33:09 doosachterdetv volumio[1105]: verbose: New Socket.io Connection to 192.168.1.130 from 192.168.1.101 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:132.0) Gecko/20100101 Firefox/132.0 Engine version: 3 Transport: polling Total Clients: 2 Nov 02 15:33:09 doosachterdetv volumio[1105]: verbose: New Socket.io Connection to 192.168.1.130 from 192.168.1.101 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:132.0) Gecko/20100101 Firefox/132.0 Engine version: 3 Transport: polling Total Clients: 3 Nov 02 15:33:09 doosachterdetv volumio[1105]: verbose: New Socket.io Connection to 192.168.1.130 from 192.168.1.101 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:132.0) Gecko/20100101 Firefox/132.0 Engine version: 3 Transport: polling Total Clients: 4 Nov 02 15:33:09 doosachterdetv volumio[1105]: verbose: New Socket.io Connection to 192.168.1.130 from 192.168.1.101 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:132.0) Gecko/20100101 Firefox/132.0 Engine version: 3 Transport: polling Total Clients: 5 Nov 02 15:33:09 doosachterdetv volumio-remote-updater[633]: [2024-11-02 15:33:09] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1730557987 101 Nov 02 15:33:09 doosachterdetv volumio[1105]: 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: 6 Nov 02 15:33:09 doosachterdetv volumio[1105]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Nov 02 15:33:09 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 15:33:09 doosachterdetv volumio[1105]: info: Reloading queue from file Nov 02 15:33:09 doosachterdetv volumio[1105]: info: Setting Device type: Raspberry PI Nov 02 15:33:09 doosachterdetv volumio[1105]: info: CoreStateMachine::setRepeat true single undefined Nov 02 15:33:09 doosachterdetv volumio[1105]: info: CoreStateMachine::pushState Nov 02 15:33:09 doosachterdetv volumio[1105]: info: CorePlayQueue::getTrack 0 Nov 02 15:33:09 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 15:33:09 doosachterdetv volumio[1105]: info: CoreCommandRouter::volumioPushState Nov 02 15:33:09 doosachterdetv volumio[1105]: info: CoreStateMachine::setRandom true Nov 02 15:33:09 doosachterdetv volumio[1105]: info: CoreStateMachine::pushState Nov 02 15:33:09 doosachterdetv volumio[1105]: info: CorePlayQueue::getTrack 0 Nov 02 15:33:09 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 15:33:09 doosachterdetv volumio[1105]: info: CoreCommandRouter::volumioPushState Nov 02 15:33:09 doosachterdetv volumio[1105]: info: VolumeController:: Volume=undefined Mute =false Nov 02 15:33:09 doosachterdetv volumio[1105]: info: CoreStateMachine::pushState Nov 02 15:33:09 doosachterdetv volumio[1105]: info: CorePlayQueue::getTrack 0 Nov 02 15:33:09 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 15:33:09 doosachterdetv volumio[1105]: info: CoreCommandRouter::volumioPushState Nov 02 15:33:09 doosachterdetv volumio[1105]: info: CoreStateMachine::updateTrackBlock Nov 02 15:33:09 doosachterdetv volumio[1105]: info: CorePlayQueue::getTrackBlock Nov 02 15:33:09 doosachterdetv volumio[1105]: info: CoreCommandRouter::volumioRetrievevolume Nov 02 15:33:09 doosachterdetv volumio[1105]: info: Discovery: adding 5bf49eff-6dc5-418b-b057-4d88a2a28f8a Nov 02 15:33:09 doosachterdetv volumio[1105]: info: Discovery: Found device DoosAchterDeTV Nov 02 15:33:09 doosachterdetv volumio[1105]: info: CoreCommandRouter::volumioGetState Nov 02 15:33:09 doosachterdetv volumio[1105]: info: CorePlayQueue::getTrack 0 Nov 02 15:33:09 doosachterdetv volumio[1105]: Starting albumart workers Nov 02 15:33:09 doosachterdetv volumio[1105]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Nov 02 15:33:09 doosachterdetv volumio[1105]: Starting albumart workers Nov 02 15:33:09 doosachterdetv volumio[1105]: Starting albumart workers Nov 02 15:33:09 doosachterdetv volumio[1105]: info: VolumeController:: Volume=undefined Mute =false Nov 02 15:33:09 doosachterdetv volumio[1105]: info: CoreStateMachine::pushState Nov 02 15:33:09 doosachterdetv volumio[1105]: info: CorePlayQueue::getTrack 0 Nov 02 15:33:09 doosachterdetv volumio[1105]: info: CoreCommandRouter::volumioPushState Nov 02 15:33:10 doosachterdetv volumio[1105]: verbose: New Socket.io Connection to 192.168.1.130 from 192.168.1.101 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:132.0) Gecko/20100101 Firefox/132.0 Engine version: 3 Transport: polling Total Clients: 7 Nov 02 15:33:10 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 02 15:33:11 doosachterdetv volumio[1105]: info: CoreCommandRouter::volumioGetVisibleSources Nov 02 15:33:11 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 15:33:11 doosachterdetv volumio[1105]: info: CoreCommandRouter::volumioGetState Nov 02 15:33:11 doosachterdetv volumio[1105]: info: CorePlayQueue::getTrack 0 Nov 02 15:33:11 doosachterdetv volumio[1105]: info: CoreCommandRouter::volumioGetState Nov 02 15:33:11 doosachterdetv volumio[1105]: info: CorePlayQueue::getTrack 0 Nov 02 15:33:11 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 02 15:33:11 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 02 15:33:11 doosachterdetv volumio[1105]: info: Received Get System Info Nov 02 15:33:11 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 15:33:11 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 15:33:11 doosachterdetv volumio[1105]: info: Discovery: Getting this device information Nov 02 15:33:11 doosachterdetv volumio[1105]: info: CoreCommandRouter::volumioGetState Nov 02 15:33:11 doosachterdetv volumio[1105]: info: CorePlayQueue::getTrack 0 Nov 02 15:33:11 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 15:33:11 doosachterdetv volumio[1105]: info: CoreCommandRouter::volumioGetState Nov 02 15:33:11 doosachterdetv volumio[1105]: info: CorePlayQueue::getTrack 0 Nov 02 15:33:11 doosachterdetv volumio[1105]: info: Listing playlists Nov 02 15:33:12 doosachterdetv sudo[1156]: pam_unix(sudo:session): session closed for user root Nov 02 15:33:12 doosachterdetv volumio[1105]: info: Cannot mount NAS NAS at system boot, trial number 1 ,retrying in 5 seconds Nov 02 15:33:12 doosachterdetv volumio[1105]: info: Completed loading Core Plugins Nov 02 15:33:12 doosachterdetv volumio[1105]: info: Preparing to generate the ALSA configuration file Nov 02 15:33:12 doosachterdetv kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Nov 02 15:33:12 doosachterdetv kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Nov 02 15:33:12 doosachterdetv volumio[1105]: info: Asound.conf file unchanged, so no further update is needed Nov 02 15:33:12 doosachterdetv volumio[1105]: info: Output device has changed, restarting MPD Nov 02 15:33:12 doosachterdetv volumio[1105]: info: Output device has changed, restarting Shairport Sync Nov 02 15:33:12 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 15:33:12 doosachterdetv sudo[1264]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 02 15:33:12 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 15:33:12 doosachterdetv sudo[1264]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 15:33:12 doosachterdetv sudo[1264]: pam_unix(sudo:session): session closed for user root Nov 02 15:33:12 doosachterdetv sudo[1266]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 02 15:33:12 doosachterdetv sudo[1266]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 15:33:12 doosachterdetv volumio[1105]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 02 15:33:12 doosachterdetv volumio[1105]: info: ___________ START PLUGINS ___________ Nov 02 15:33:12 doosachterdetv volumio[1105]: info: ControllerMpd::onStart: Initializing MPD Nov 02 15:33:12 doosachterdetv volumio[1105]: info: Creating MPD Configuration file Nov 02 15:33:12 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 15:33:12 doosachterdetv volumio[1105]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 15:33:12 doosachterdetv volumio[1105]: info: [1730557992261] CoreMusicLibrary::Adding element Media Servers Nov 02 15:33:12 doosachterdetv sudo[1274]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 02 15:33:12 doosachterdetv sudo[1274]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 15:33:12 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 15:33:12 doosachterdetv sudo[1274]: pam_unix(sudo:session): session closed for user root Nov 02 15:33:12 doosachterdetv systemd[1]: Listening on mpd.socket. Nov 02 15:33:12 doosachterdetv sudo[1276]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 02 15:33:12 doosachterdetv sudo[1276]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 15:33:12 doosachterdetv systemd[1]: Starting Music Player Daemon... Nov 02 15:33:12 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 15:33:12 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 15:33:12 doosachterdetv systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Nov 02 15:33:12 doosachterdetv systemd[1]: mpd.service: Succeeded. Nov 02 15:33:12 doosachterdetv systemd[1]: Stopped Music Player Daemon. Nov 02 15:33:12 doosachterdetv systemd[1]: Starting Music Player Daemon... Nov 02 15:33:12 doosachterdetv volumio[1105]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 02 15:33:12 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 15:33:12 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 15:33:12 doosachterdetv volumio[1105]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 02 15:33:12 doosachterdetv volumio[1105]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 15:33:12 doosachterdetv volumio[1105]: info: [1730557992396] CoreMusicLibrary::Adding element Last_100 Nov 02 15:33:12 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 15:33:12 doosachterdetv volumio[1105]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 15:33:12 doosachterdetv volumio[1105]: info: [1730557992400] CoreMusicLibrary::Adding element Webradio Nov 02 15:33:12 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 15:33:12 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 15:33:12 doosachterdetv volumio[1105]: info: Initializing BBC Radios Nov 02 15:33:12 doosachterdetv sudo[1283]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 02 15:33:12 doosachterdetv sudo[1283]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 15:33:12 doosachterdetv sudo[1283]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Nov 02 15:33:12 doosachterdetv sudo[1283]: pam_unix(sudo:session): session closed for user root Nov 02 15:33:12 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 15:33:12 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 15:33:12 doosachterdetv volumio[1105]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 15:33:12 doosachterdetv volumio[1105]: info: [1730557992482] CoreMusicLibrary::Adding element YouTube2 Nov 02 15:33:12 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 15:33:12 doosachterdetv volumio[1105]: Cannot find translation for source YouTube2 Nov 02 15:33:12 doosachterdetv volumio[1105]: info: Volumio Calling Home Nov 02 15:33:12 doosachterdetv volumio[1105]: info: MPD Permissions set Nov 02 15:33:12 doosachterdetv volumio[1105]: info: MPD Permissions set Nov 02 15:33:12 doosachterdetv volumio[1105]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Nov 02 15:33:12 doosachterdetv volumio[1105]: info: Volumio called home Nov 02 15:33:12 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 15:33:12 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 15:33:12 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 15:33:12 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 15:33:12 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 15:33:12 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 15:33:12 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 15:33:12 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 15:33:12 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 15:33:12 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 15:33:12 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 15:33:12 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 15:33:12 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 15:33:12 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 15:33:12 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 15:33:12 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 15:33:12 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 15:33:12 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 15:33:12 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 15:33:12 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 15:33:12 doosachterdetv volumio[1105]: info: CoreCommandRouter::volumioGetState Nov 02 15:33:12 doosachterdetv volumio[1105]: info: CorePlayQueue::getTrack 0 Nov 02 15:33:12 doosachterdetv volumio[1105]: info: Starting Shairport Sync Nov 02 15:33:12 doosachterdetv volumio[1105]: info: Starting Shairport Sync Nov 02 15:33:12 doosachterdetv volumio[1105]: info: Starting Shairport Sync Nov 02 15:33:12 doosachterdetv sudo[1311]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 02 15:33:12 doosachterdetv sudo[1311]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 15:33:12 doosachterdetv sudo[1313]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 02 15:33:12 doosachterdetv sudo[1313]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 15:33:12 doosachterdetv sudo[1317]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 02 15:33:12 doosachterdetv sudo[1317]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 15:33:12 doosachterdetv systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 02 15:33:12 doosachterdetv systemd[1]: shairport-sync.service: Succeeded. Nov 02 15:33:12 doosachterdetv systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 02 15:33:13 doosachterdetv sudo[1311]: pam_unix(sudo:session): session closed for user root Nov 02 15:33:13 doosachterdetv systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 02 15:33:13 doosachterdetv sudo[1313]: pam_unix(sudo:session): session closed for user root Nov 02 15:33:13 doosachterdetv volumio[1105]: info: Shairport-Sync Started Nov 02 15:33:13 doosachterdetv volumio[1105]: Error adding Membership: Error: addMembership EINVAL Nov 02 15:33:13 doosachterdetv systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 02 15:33:13 doosachterdetv volumio[1105]: info: Shairport-Sync Started Nov 02 15:33:13 doosachterdetv systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Nov 02 15:33:13 doosachterdetv systemd[1]: shairport-sync.service: Succeeded. Nov 02 15:33:13 doosachterdetv systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 02 15:33:13 doosachterdetv systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 02 15:33:13 doosachterdetv sudo[1317]: pam_unix(sudo:session): session closed for user root Nov 02 15:33:13 doosachterdetv volumio[1105]: info: Shairport-Sync Started Nov 02 15:33:14 doosachterdetv sudo[1326]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 02 15:33:14 doosachterdetv sudo[1326]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 15:33:14 doosachterdetv sudo[1326]: pam_unix(sudo:session): session closed for user root Nov 02 15:33:14 doosachterdetv sudo[1328]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 02 15:33:14 doosachterdetv sudo[1328]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 15:33:14 doosachterdetv sudo[1328]: pam_unix(sudo:session): session closed for user root Nov 02 15:33:14 doosachterdetv sudo[1332]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Nov 02 15:33:14 doosachterdetv sudo[1332]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 15:33:15 doosachterdetv systemd[1]: Started UPnP Renderer front-end to MPD. Nov 02 15:33:15 doosachterdetv sudo[1332]: pam_unix(sudo:session): session closed for user root Nov 02 15:33:15 doosachterdetv volumio[1105]: info: Upmpdcli Daemon Started Nov 02 15:33:16 doosachterdetv mpd[1291]: Nov 02 15:33 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 02 15:33:16 doosachterdetv systemd[1]: Started Music Player Daemon. Nov 02 15:33:16 doosachterdetv sudo[1266]: pam_unix(sudo:session): session closed for user root Nov 02 15:33:16 doosachterdetv sudo[1276]: pam_unix(sudo:session): session closed for user root Nov 02 15:33:16 doosachterdetv volumio[1105]: info: Completed starting Core Plugins Nov 02 15:33:16 doosachterdetv volumio[1105]: info: ------------------------------------------- Nov 02 15:33:16 doosachterdetv volumio[1105]: info: ----- MyVolumio plugins startup ---- Nov 02 15:33:16 doosachterdetv volumio[1105]: info: ------------------------------------------- Nov 02 15:33:16 doosachterdetv volumio[1105]: info: [MyVolumio PluginManager] Fetching plans data.... Nov 02 15:33:16 doosachterdetv volumio[1105]: error: MPD error: The expression evaluated to a falsy value: Nov 02 15:33:16 doosachterdetv volumio[1105]: assert.ok(self.idling) Nov 02 15:33:16 doosachterdetv volumio[1105]: error: The expression evaluated to a falsy value: Nov 02 15:33:16 doosachterdetv volumio[1105]: assert.ok(self.idling) Nov 02 15:33:16 doosachterdetv volumio[1105]: error: updateQueue error: null Nov 02 15:33:16 doosachterdetv volumio[1105]: info: MPD running with PID1291 Nov 02 15:33:16 doosachterdetv volumio[1105]: ,establishing connection Nov 02 15:33:16 doosachterdetv volumio[1334]: Generating RSA private key, 4096 bit long modulus (2 primes) Nov 02 15:33:16 doosachterdetv volumio[1105]: error: updateQueue error: null Nov 02 15:33:16 doosachterdetv volumio[1334]: .++++ Nov 02 15:33:17 doosachterdetv sudo[1355]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=buizenradio@jongbloed.net,password=BuizenRadio_101,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.108/NAS /mnt/NAS/NAS Nov 02 15:33:17 doosachterdetv sudo[1355]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 15:33:17 doosachterdetv kernel: CIFS: Attempting to mount \\192.168.1.108\NAS Nov 02 15:33:21 doosachterdetv volumio[1105]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Nov 02 15:33:23 doosachterdetv sudo[1355]: pam_unix(sudo:session): session closed for user root Nov 02 15:33:23 doosachterdetv volumio[1105]: info: Cannot mount NAS NAS at system boot, trial number 2 ,retrying in 5 seconds Nov 02 15:33:23 doosachterdetv kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Nov 02 15:33:23 doosachterdetv kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Nov 02 15:33:24 doosachterdetv volumio[1105]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Nov 02 15:33:24 doosachterdetv volumio[1105]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Nov 02 15:33:24 doosachterdetv volumio[1105]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Nov 02 15:33:24 doosachterdetv volumio[1105]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Nov 02 15:33:24 doosachterdetv volumio[1105]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Nov 02 15:33:24 doosachterdetv volumio[1105]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Nov 02 15:33:24 doosachterdetv volumio[1105]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Nov 02 15:33:24 doosachterdetv volumio[1105]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Nov 02 15:33:24 doosachterdetv volumio[1105]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Nov 02 15:33:24 doosachterdetv volumio[1105]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Nov 02 15:33:24 doosachterdetv volumio[1105]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Nov 02 15:33:24 doosachterdetv volumio[1105]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Nov 02 15:33:24 doosachterdetv volumio[1105]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Nov 02 15:33:24 doosachterdetv volumio[1105]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Nov 02 15:33:24 doosachterdetv volumio[1105]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Nov 02 15:33:24 doosachterdetv volumio[1105]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Nov 02 15:33:24 doosachterdetv volumio[1105]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Nov 02 15:33:24 doosachterdetv volumio[1105]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Nov 02 15:33:24 doosachterdetv volumio[1105]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Nov 02 15:33:24 doosachterdetv volumio[1105]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Nov 02 15:33:24 doosachterdetv volumio[1105]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Nov 02 15:33:24 doosachterdetv volumio[1105]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Nov 02 15:33:24 doosachterdetv volumio[1105]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Nov 02 15:33:24 doosachterdetv volumio[1105]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Nov 02 15:33:24 doosachterdetv volumio[1105]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Nov 02 15:33:24 doosachterdetv volumio[1105]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Nov 02 15:33:24 doosachterdetv volumio[1105]: info: Adding plugin bluetooth to MyMusic Plugins Nov 02 15:33:24 doosachterdetv volumio[1105]: info: Adding plugin multiroom to MyMusic Plugins Nov 02 15:33:24 doosachterdetv volumio[1105]: info: Adding plugin metavolumio to MyMusic Plugins Nov 02 15:33:24 doosachterdetv volumio[1105]: info: Adding plugin cd_controller to MyMusic Plugins Nov 02 15:33:24 doosachterdetv volumio[1105]: info: Adding plugin smart_inputs to MyMusic Plugins Nov 02 15:33:24 doosachterdetv volumio[1105]: info: Adding plugin tidalconnect to MyMusic Plugins Nov 02 15:33:24 doosachterdetv volumio[1105]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Nov 02 15:33:26 doosachterdetv volumio[1105]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Nov 02 15:33:26 doosachterdetv volumio[1105]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Nov 02 15:33:26 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 15:33:26 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 15:33:26 doosachterdetv volumio[1105]: info: Starting MyVolumio Remote Streaming Endpoints Nov 02 15:33:26 doosachterdetv volumio[1105]: info: MyVolumio login type: Token Nov 02 15:33:26 doosachterdetv volumio[1105]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Nov 02 15:33:26 doosachterdetv volumio[1105]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Nov 02 15:33:27 doosachterdetv volumio[1105]: info: Starting Streaming Service Transparent Proxy Nov 02 15:33:27 doosachterdetv volumio[1105]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Nov 02 15:33:27 doosachterdetv volumio[1105]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Nov 02 15:33:27 doosachterdetv volumio[1105]: info: Streaming services startup Nov 02 15:33:27 doosachterdetv volumio[1105]: info: Starting Streaming Daemon Nov 02 15:33:27 doosachterdetv volumio[1105]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Nov 02 15:33:27 doosachterdetv sudo[1366]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 02 15:33:27 doosachterdetv sudo[1366]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 15:33:27 doosachterdetv sudo[1366]: pam_unix(sudo:session): session closed for user root Nov 02 15:33:27 doosachterdetv volumio[1105]: error: Cannot start Volumio Streaming Daemon Nov 02 15:33:27 doosachterdetv volumio[1105]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Nov 02 15:33:27 doosachterdetv volumio[1105]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Nov 02 15:33:28 doosachterdetv volumio[1105]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Nov 02 15:33:28 doosachterdetv sudo[1374]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=buizenradio@jongbloed.net,password=BuizenRadio_101,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.108/NAS /mnt/NAS/NAS Nov 02 15:33:28 doosachterdetv sudo[1374]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 15:33:28 doosachterdetv kernel: CIFS: Attempting to mount \\192.168.1.108\NAS Nov 02 15:33:28 doosachterdetv volumio[1105]: STREAMING PROXY: Starting server on port 3245 Nov 02 15:33:28 doosachterdetv volumio[1105]: Node JS runtime: 14 Nov 02 15:33:28 doosachterdetv volumio[1105]: info: MyVolumio token set successfully Nov 02 15:33:28 doosachterdetv volumio[1105]: info: MYVOLUMIO: Adding device Nov 02 15:33:28 doosachterdetv volumio[1105]: info: MYVOLUMIO: Evaluating Server Nov 02 15:33:29 doosachterdetv volumio[1105]: info: MyVolumio status changed Nov 02 15:33:29 doosachterdetv volumio[1105]: info: Streaming services startup Nov 02 15:33:29 doosachterdetv volumio[1105]: info: Starting Streaming Daemon Nov 02 15:33:29 doosachterdetv volumio[1105]: info: Removing browser output: myVolumio user plan is not superstar Nov 02 15:33:29 doosachterdetv volumio[1105]: info: Removing audio output: Nov 02 15:33:29 doosachterdetv volumio[1105]: info: Stoppping Tunnel 1 Nov 02 15:33:29 doosachterdetv sudo[1396]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 02 15:33:29 doosachterdetv sudo[1396]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 15:33:29 doosachterdetv sudo[1399]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Nov 02 15:33:29 doosachterdetv sudo[1399]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 15:33:29 doosachterdetv sudo[1396]: pam_unix(sudo:session): session closed for user root Nov 02 15:33:29 doosachterdetv volumio[1105]: error: Cannot start Volumio Streaming Daemon Nov 02 15:33:29 doosachterdetv volumio[1105]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Nov 02 15:33:29 doosachterdetv volumio[1105]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Nov 02 15:33:29 doosachterdetv sudo[1399]: pam_unix(sudo:session): session closed for user root Nov 02 15:33:29 doosachterdetv volumio[1105]: info: Remote SSH Stopped Nov 02 15:33:29 doosachterdetv volumio[1105]: info: Setting Geolocation for MyVolumio to eu5 Nov 02 15:33:29 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 15:33:29 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 15:33:29 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 15:33:30 doosachterdetv volumio[1105]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Nov 02 15:33:30 doosachterdetv volumio[1334]: ....................................................................................................................++++ Nov 02 15:33:30 doosachterdetv volumio[1334]: e is 65537 (0x010001) Nov 02 15:33:30 doosachterdetv volumio[1334]: writing RSA key Nov 02 15:33:30 doosachterdetv volumio[1105]: info: Updating MyVolumio device info Nov 02 15:33:30 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 15:33:30 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 15:33:30 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 15:33:30 doosachterdetv volumio[1105]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Nov 02 15:33:34 doosachterdetv volumio[1105]: info: MYVOLUMIO: Adding device Nov 02 15:33:34 doosachterdetv volumio[1105]: info: MYVOLUMIO: Evaluating Server Nov 02 15:33:34 doosachterdetv sudo[1374]: pam_unix(sudo:session): session closed for user root Nov 02 15:33:34 doosachterdetv kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Nov 02 15:33:34 doosachterdetv kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Nov 02 15:33:34 doosachterdetv volumio[1105]: info: Cannot mount NAS NAS at system boot, trial number 3 ,retrying in 5 seconds Nov 02 15:33:34 doosachterdetv volumio[1105]: info: Setting Geolocation for MyVolumio to eu5 Nov 02 15:33:34 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 15:33:34 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 15:33:34 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 15:33:35 doosachterdetv volumio[1105]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Nov 02 15:33:35 doosachterdetv volumio[1105]: info: Updating MyVolumio device info Nov 02 15:33:35 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 15:33:36 doosachterdetv volumio[1105]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Nov 02 15:33:37 doosachterdetv volumio[1105]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 15:33:37 doosachterdetv volumio[1105]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 15:33:37 doosachterdetv volumio[1105]: info: Retrieving Cloud Streaming UI Nov 02 15:33:37 doosachterdetv volumio[1105]: info: Getting Tidal Cloud Configuration Nov 02 15:33:37 doosachterdetv volumio[1105]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 15:33:37 doosachterdetv volumio[1105]: info: Getting Qobuz Cloud Configuration Nov 02 15:33:37 doosachterdetv volumio[1105]: info: Asking plugin for UI Config Nov 02 15:33:37 doosachterdetv volumio[1105]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 15:33:37 doosachterdetv volumio[1105]: info: Getting Spotify Cloud Configuration Nov 02 15:33:37 doosachterdetv volumio[1105]: info: Asking plugin for UI Config Nov 02 15:33:37 doosachterdetv volumio[1105]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 15:33:37 doosachterdetv volumio[1105]: info: Saving Spotify Acccount Nov 02 15:33:37 doosachterdetv volumio[1105]: info: Got Tidal Cloud Configuration Nov 02 15:33:37 doosachterdetv volumio[1105]: info: Got it Nov 02 15:33:37 doosachterdetv volumio[1105]: info: Got it Nov 02 15:33:37 doosachterdetv volumio[1105]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Nov 02 15:33:37 doosachterdetv volumio[1105]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 15:33:37 doosachterdetv volumio[1105]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Nov 02 15:33:37 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 02 15:33:37 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 02 15:33:37 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 02 15:33:37 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 02 15:33:37 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 15:33:37 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 15:33:37 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 15:33:37 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 15:33:37 doosachterdetv volumio[1105]: info: CoreCommandRouter::volumioGetBrowseSources Nov 02 15:33:37 doosachterdetv volumio[1105]: info: CoreCommandRouter::volumioGetBrowseSources Nov 02 15:33:37 doosachterdetv volumio[1105]: info: CoreCommandRouter::volumioGetBrowseSources Nov 02 15:33:37 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 02 15:33:37 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Nov 02 15:33:39 doosachterdetv sudo[1491]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=buizenradio@jongbloed.net,password=BuizenRadio_101,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.108/NAS /mnt/NAS/NAS Nov 02 15:33:39 doosachterdetv sudo[1491]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 15:33:39 doosachterdetv kernel: CIFS: Attempting to mount \\192.168.1.108\NAS Nov 02 15:33:41 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 02 15:33:41 doosachterdetv volumio[1105]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Nov 02 15:33:41 doosachterdetv volumio[1105]: info: CoreCommandRouter::volumioGetState Nov 02 15:33:41 doosachterdetv volumio[1105]: info: CorePlayQueue::getTrack 0 Nov 02 15:33:41 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 02 15:33:42 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Nov 02 15:33:42 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 15:33:42 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 15:33:42 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 02 15:33:42 doosachterdetv ntpd[819]: Soliciting pool server 193.148.248.179 Nov 02 15:33:42 doosachterdetv volumio-remote-updater[633]: No test mode Nov 02 15:33:42 doosachterdetv volumio-remote-updater[633]: No alpha test mode Nov 02 15:33:42 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Nov 02 15:33:42 doosachterdetv volumio[1105]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Nov 02 15:33:42 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Nov 02 15:33:43 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Nov 02 15:33:43 doosachterdetv ntpd[819]: Soliciting pool server 213.239.154.12 Nov 02 15:33:44 doosachterdetv ntpd[819]: Soliciting pool server 162.159.200.1 Nov 02 15:33:44 doosachterdetv ntpd[819]: Soliciting pool server 193.123.56.220 Nov 02 15:33:45 doosachterdetv ntpd[819]: Soliciting pool server 178.239.19.61 Nov 02 15:33:45 doosachterdetv ntpd[819]: Soliciting pool server 193.123.37.231 Nov 02 15:33:45 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 02 15:33:45 doosachterdetv ntpd[819]: Soliciting pool server 195.123.221.21 Nov 02 15:33:45 doosachterdetv sudo[1491]: pam_unix(sudo:session): session closed for user root Nov 02 15:33:45 doosachterdetv volumio[1105]: info: Cannot mount NAS NAS at system boot, trial number 4 ,retrying in 5 seconds Nov 02 15:33:45 doosachterdetv kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Nov 02 15:33:45 doosachterdetv kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Nov 02 15:33:46 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 15:33:46 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 15:33:46 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Nov 02 15:33:46 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Nov 02 15:33:46 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Nov 02 15:33:46 doosachterdetv volumio[1105]: info: CoreCommandRouter::volumioGetBrowseSources Nov 02 15:33:46 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 15:33:46 doosachterdetv ntpd[819]: Soliciting pool server 45.137.101.154 Nov 02 15:33:46 doosachterdetv ntpd[819]: Soliciting pool server 194.104.0.153 Nov 02 15:33:46 doosachterdetv ntpd[819]: Soliciting pool server 178.239.19.59 Nov 02 15:33:47 doosachterdetv ntpd[819]: Soliciting pool server 45.138.55.60 Nov 02 15:33:47 doosachterdetv ntpd[819]: Soliciting pool server 45.92.156.47 Nov 02 15:33:47 doosachterdetv volumio[1105]: error: MyVolumio Plugin failed to authenticate in a timely fashion Nov 02 15:33:47 doosachterdetv volumio[1105]: info: Completed starting MyVolumio Plugin Nov 02 15:33:47 doosachterdetv volumio[1105]: info: BOOT COMPLETED Nov 02 15:33:47 doosachterdetv volumio[1105]: [Metrics] CommandRouter: 45s 993.74ms Nov 02 15:33:47 doosachterdetv volumio[1105]: info: CoreCommandRouter::volumiosetStartupVolume Nov 02 15:33:47 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 15:33:47 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 15:33:47 doosachterdetv volumio[1105]: info: CoreCommandRouter::Close All Modals sent Nov 02 15:33:47 doosachterdetv volumio[1105]: info: CoreCommandRouter::Close All Modals sent Nov 02 15:33:47 doosachterdetv kernel: bcm2835-i2s 3f203000.i2s: I2S SYNC error! Nov 02 15:33:47 doosachterdetv kernel: arizona spi0.0: ASRC underclocked Nov 02 15:33:48 doosachterdetv ntpd[819]: Soliciting pool server 2606:4700:f1::1 Nov 02 15:33:48 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Nov 02 15:33:48 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 02 15:33:48 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Nov 02 15:33:49 doosachterdetv volumio[1105]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 02 15:34:14 doosachterdetv ntpd[819]: receive: Unexpected origin timestamp 0xead0b6cd.894f925c does not match aorg 0000000000.00000000 from server@213.239.154.12 xmt 0xead0b6e6.1127ac7c Nov 02 15:34:14 doosachterdetv ntpd[819]: receive: Unexpected origin timestamp 0xead0b6cd.894e817f does not match aorg 0000000000.00000000 from server@162.159.200.1 xmt 0xead0b6e6.1152df02 Nov 02 15:34:14 doosachterdetv ntpd[819]: receive: Unexpected origin timestamp 0xead0b6cd.894d57b0 does not match aorg 0000000000.00000000 from server@194.104.0.153 xmt 0xead0b6e6.1151bcfa Nov 02 15:34:14 doosachterdetv ntpd[819]: receive: Unexpected origin timestamp 0xead0b6cd.894b9071 does not match aorg 0000000000.00000000 from server@45.137.101.154 xmt 0xead0b6e6.11b777f6 Nov 02 15:34:14 doosachterdetv ntpd[819]: receive: Unexpected origin timestamp 0xead0b6cd.8945dcc6 does not match aorg 0000000000.00000000 from server@45.138.55.60 xmt 0xead0b6e6.1194bc01 Nov 02 15:34:15 doosachterdetv volumio[1105]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 15:34:15 doosachterdetv volumio[1105]: TypeError: Cannot read property 'replace' of undefined Nov 02 15:34:15 doosachterdetv volumio[1105]: at ControllerNetworkfs.mountShare (/volumio/app/plugins/system_controller/networkfs/index.js:202:28) Nov 02 15:34:15 doosachterdetv volumio[1105]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/networkfs/index.js:255:20) Nov 02 15:34:15 doosachterdetv volumio[1105]: at listOnTimeout (internal/timers.js:554:17) Nov 02 15:34:15 doosachterdetv volumio[1105]: at processTimers (internal/timers.js:497:7) Nov 02 15:34:15 doosachterdetv volumio[1105]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 15:34:16 doosachterdetv sudo[1519]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-02 15:33 Nov 02 15:34:16 doosachterdetv sudo[1519]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"