-- Logs begin at Fri 2024-03-29 15:53:05 UTC, end at Fri 2024-03-29 15:57:14 UTC. -- Mar 29 15:56:01 test volumio[626]: verbose: New Socket.io Connection to 10.116.89.28 from 172.18.5.206 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:123.0) Gecko/20100101 Firefox/123.0 Total Clients: 1 Mar 29 15:56:01 test volumio[626]: verbose: New Socket.io Connection to 10.116.89.28 from 172.18.5.206 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:123.0) Gecko/20100101 Firefox/123.0 Total Clients: 2 Mar 29 15:56:01 test volumio[626]: verbose: New Socket.io Connection to 10.116.89.28 from 172.18.5.206 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:123.0) Gecko/20100101 Firefox/123.0 Total Clients: 3 Mar 29 15:56:02 test volumio[626]: verbose: New Socket.io Connection to 10.116.89.28 from 172.18.5.206 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:123.0) Gecko/20100101 Firefox/123.0 Total Clients: 4 Mar 29 15:56:02 test volumio[626]: verbose: New Socket.io Connection to 10.116.89.28 from 172.18.5.206 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:123.0) Gecko/20100101 Firefox/123.0 Total Clients: 5 Mar 29 15:56:02 test volumio[626]: verbose: New Socket.io Connection to 10.116.89.28 from 172.18.5.206 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:123.0) Gecko/20100101 Firefox/123.0 Total Clients: 6 Mar 29 15:56:02 test volumio[626]: verbose: New Socket.io Connection to 10.116.89.28:3000 from 10.116.89.18 UA: node-XMLHttpRequest Total Clients: 7 Mar 29 15:56:02 test volumio[626]: info: CoreCommandRouter::volumioGetState Mar 29 15:56:03 test volumio[626]: info: CorePlayQueue::getTrack 0 Mar 29 15:56:03 test volumio[626]: info: CoreCommandRouter::volumioGetState Mar 29 15:56:03 test volumio[626]: info: CorePlayQueue::getTrack 0 Mar 29 15:56:03 test volumio[626]: info: CoreCommandRouter::volumioGetState Mar 29 15:56:03 test volumio[626]: info: CorePlayQueue::getTrack 0 Mar 29 15:56:03 test volumio[626]: info: CoreCommandRouter::volumioGetState Mar 29 15:56:03 test volumio[626]: info: CorePlayQueue::getTrack 0 Mar 29 15:56:03 test volumio[626]: verbose: New Socket.io Connection to 10.116.89.28 from 172.18.5.206 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:123.0) Gecko/20100101 Firefox/123.0 Total Clients: 8 Mar 29 15:56:03 test volumio[626]: verbose: New Socket.io Connection to 10.116.89.28 from 172.18.5.206 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:123.0) Gecko/20100101 Firefox/123.0 Total Clients: 9 Mar 29 15:56:03 test volumio[626]: verbose: New Socket.io Connection to 10.116.89.28 from 172.18.5.206 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:123.0) Gecko/20100101 Firefox/123.0 Total Clients: 10 Mar 29 15:56:03 test volumio[626]: verbose: New Socket.io Connection to 10.116.89.28 from 172.18.5.206 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:123.0) Gecko/20100101 Firefox/123.0 Total Clients: 11 Mar 29 15:56:04 test volumio[626]: verbose: New Socket.io Connection to 10.116.89.28:3000 from 10.116.89.18 UA: node-XMLHttpRequest Total Clients: 12 Mar 29 15:56:04 test volumio[626]: info: MPD running with PID717 Mar 29 15:56:04 test volumio[626]: ,establishing connection Mar 29 15:56:04 test volumio[626]: info: VolumeController:: Volume=65 Mute =false Mar 29 15:56:04 test volumio[626]: info: CoreStateMachine::pushState Mar 29 15:56:04 test volumio[626]: info: CorePlayQueue::getTrack 0 Mar 29 15:56:04 test volumio[626]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 15:56:04 test volumio[626]: info: CoreCommandRouter::volumioPushState Mar 29 15:56:04 test volumio[626]: info: CoreStateMachine::updateTrackBlock Mar 29 15:56:04 test volumio[626]: info: CorePlayQueue::getTrackBlock Mar 29 15:56:04 test volumio[626]: info: CoreCommandRouter::volumioRetrievevolume Mar 29 15:56:04 test volumio[626]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 15:56:04 test volumio[626]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 15:56:04 test volumio[626]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 15:56:04 test volumio[626]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 15:56:04 test volumio[626]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 15:56:04 test volumio[626]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 15:56:04 test volumio[626]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 15:56:04 test volumio[626]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 15:56:04 test volumio[626]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 15:56:05 test volumio[626]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 15:56:05 test volumio[626]: info: Cannot read play queue from file Mar 29 15:56:05 test volumio[626]: error: updateQueue error: null Mar 29 15:56:06 test volumio[626]: info: CoreStateMachine::setRepeat false single undefined Mar 29 15:56:06 test volumio[626]: info: CoreStateMachine::pushState Mar 29 15:56:06 test volumio[626]: info: CorePlayQueue::getTrack 0 Mar 29 15:56:06 test volumio[626]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 15:56:06 test volumio[626]: info: CoreCommandRouter::volumioPushState Mar 29 15:56:06 test volumio[626]: info: CoreStateMachine::setRandom true Mar 29 15:56:06 test volumio[626]: info: CoreStateMachine::pushState Mar 29 15:56:06 test volumio[626]: info: CorePlayQueue::getTrack 0 Mar 29 15:56:06 test volumio[626]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 15:56:06 test volumio[626]: info: CoreCommandRouter::volumioPushState Mar 29 15:56:06 test volumio[626]: info: Setting Device type: Raspberry PI Mar 29 15:56:06 test sudo[811]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=lmsuser,password=lmsuser,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //10.116.89.204/music /mnt/NAS/lmsuser Mar 29 15:56:06 test sudo[811]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 15:56:06 test kernel: CIFS: Attempting to mount //10.116.89.204/music Mar 29 15:56:07 test volumio[626]: error: updateQueue error: null Mar 29 15:56:07 test kernel: CIFS: VFS: BAD_NETWORK_NAME: \\10.116.89.204\music Mar 29 15:56:07 test kernel: CIFS: VFS: cifs_mount failed w/return code = -2 Mar 29 15:56:07 test sudo[811]: pam_unix(sudo:session): session closed for user root Mar 29 15:56:07 test sudo[820]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Mar 29 15:56:07 test sudo[820]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 15:56:08 test volumio[626]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Mar 29 15:56:08 test volumio[626]: info: VolumeController:: Volume=65 Mute =false Mar 29 15:56:08 test volumio[626]: info: CoreStateMachine::pushState Mar 29 15:56:08 test volumio[626]: info: CorePlayQueue::getTrack 0 Mar 29 15:56:08 test volumio[626]: info: CoreCommandRouter::volumioPushState Mar 29 15:56:08 test systemd[1]: Started UPnP Renderer front-end to MPD. Mar 29 15:56:08 test sudo[820]: pam_unix(sudo:session): session closed for user root Mar 29 15:56:08 test volumio[626]: info: Completed loading Core Plugins Mar 29 15:56:08 test volumio[626]: info: Preparing to generate the ALSA configuration file Mar 29 15:56:09 test volumio[626]: verbose: New Socket.io Connection to 10.116.89.28 from 172.18.5.206 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:123.0) Gecko/20100101 Firefox/123.0 Total Clients: 13 Mar 29 15:56:09 test volumio[626]: info: CoreCommandRouter::volumioGetState Mar 29 15:56:09 test volumio[626]: info: CorePlayQueue::getTrack 0 Mar 29 15:56:09 test volumio[626]: info: Upmpdcli Daemon Started Mar 29 15:56:09 test volumio[626]: info: Cannot mount NAS lmsuser at system boot, trial number 2 ,retrying in 5 seconds Mar 29 15:56:09 test volumio[626]: info: Asound.conf file unchanged, so no further update is needed Mar 29 15:56:09 test volumio[626]: info: Output device has changed, restarting MPD Mar 29 15:56:09 test volumio[626]: info: Output device has changed, restarting Shairport Sync Mar 29 15:56:09 test volumio[626]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 15:56:09 test volumio[626]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 15:56:10 test sudo[826]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 29 15:56:10 test sudo[826]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 15:56:10 test sudo[826]: pam_unix(sudo:session): session closed for user root Mar 29 15:56:10 test sudo[828]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 29 15:56:10 test sudo[828]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 15:56:10 test systemd[1]: Stopping Music Player Daemon... Mar 29 15:56:10 test systemd[1]: mpd.service: Succeeded. Mar 29 15:56:10 test systemd[1]: Stopped Music Player Daemon. Mar 29 15:56:10 test systemd[1]: Starting Music Player Daemon... Mar 29 15:56:10 test volumio[626]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 29 15:56:10 test volumio[626]: info: ___________ START PLUGINS ___________ Mar 29 15:56:11 test volumio[626]: Starting albumart workers Mar 29 15:56:11 test volumio[626]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 15:56:11 test volumio[626]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 29 15:56:11 test volumio[626]: info: [1711727771198] CoreMusicLibrary::Adding element Media Servers Mar 29 15:56:11 test volumio[626]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 15:56:11 test volumio[626]: Cannot find translation for sourceMedia Servers Mar 29 15:56:11 test volumio-remote-updater[295]: [2024-03-29 15:56:11] [connect] Successful connection Mar 29 15:56:11 test volumio[626]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 15:56:11 test volumio[626]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 15:56:12 test volumio[626]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 29 15:56:12 test volumio[626]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 15:56:12 test volumio[626]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 15:56:13 test volumio[626]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 29 15:56:13 test volumio[626]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 29 15:56:13 test volumio[626]: info: [1711727773776] CoreMusicLibrary::Adding element Last_100 Mar 29 15:56:13 test volumio[626]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 15:56:13 test volumio[626]: Cannot find translation for sourceMedia Servers Mar 29 15:56:13 test volumio[626]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 29 15:56:13 test volumio[626]: info: [1711727773867] CoreMusicLibrary::Adding element Webradio Mar 29 15:56:13 test volumio[626]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 15:56:13 test volumio[626]: Cannot find translation for sourceMedia Servers Mar 29 15:56:13 test volumio[626]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 29 15:56:14 test volumio[626]: info: Volumio Calling Home Mar 29 15:56:17 test volumio[626]: info: Completed starting Core Plugins Mar 29 15:56:17 test volumio[626]: info: ------------------------------------------- Mar 29 15:56:17 test volumio[626]: info: ----- MyVolumio plugins startup ---- Mar 29 15:56:17 test volumio[626]: info: ------------------------------------------- Mar 29 15:56:17 test volumio[626]: info: [MyVolumio PluginManager] Fetching plans data.... Mar 29 15:56:19 test volumio[626]: info: Discovery: adding a1e8942d-8f85-4337-af4e-fe8cdfd48d03 Mar 29 15:56:19 test volumio[626]: info: mDNS: Found device Volumio Mar 29 15:56:19 test sudo[847]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=lmsuser,password=lmsuser,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //10.116.89.204/music /mnt/NAS/lmsuser Mar 29 15:56:19 test volumio[626]: info: Discovery: adding be4221ea-6182-4cf9-afa1-3c11a9ddc094 Mar 29 15:56:19 test sudo[847]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 15:56:20 test volumio[626]: info: mDNS: Found device TEST Mar 29 15:56:20 test volumio[626]: info: CoreCommandRouter::volumioGetState Mar 29 15:56:20 test volumio[626]: info: CorePlayQueue::getTrack 0 Mar 29 15:56:20 test kernel: CIFS: Attempting to mount //10.116.89.204/music Mar 29 15:56:20 test kernel: CIFS: VFS: BAD_NETWORK_NAME: \\10.116.89.204\music Mar 29 15:56:20 test kernel: CIFS: VFS: cifs_mount failed w/return code = -2 Mar 29 15:56:20 test sudo[847]: pam_unix(sudo:session): session closed for user root Mar 29 15:56:20 test volumio[626]: info: MPD Permissions set Mar 29 15:56:21 test sudo[853]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 29 15:56:21 test sudo[853]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 15:56:21 test sudo[853]: pam_unix(sudo:session): session closed for user root Mar 29 15:56:22 test sudo[855]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 29 15:56:22 test sudo[855]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 15:56:22 test volumio[626]: info: Cannot mount NAS lmsuser at system boot, trial number 3 ,retrying in 5 seconds Mar 29 15:56:22 test sudo[855]: pam_unix(sudo:session): session closed for user root Mar 29 15:56:23 test volumio[626]: info: Volumio called home Mar 29 15:56:23 test volumio[626]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 15:56:23 test volumio[626]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 15:56:23 test volumio[626]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 15:56:23 test volumio[626]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 15:56:23 test volumio[626]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 15:56:23 test volumio[626]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 15:56:23 test volumio[626]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 15:56:23 test volumio[626]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 15:56:24 test volumio[626]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 15:56:24 test volumio[626]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 15:56:25 test volumio[626]: info: Starting Shairport Sync Mar 29 15:56:25 test volumio[626]: info: Starting Shairport Sync Mar 29 15:56:26 test volumio[626]: info: Starting Shairport Sync Mar 29 15:56:26 test sudo[873]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 29 15:56:26 test sudo[873]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 15:56:26 test volumio-remote-updater[295]: [2024-03-29 15:56:26] [connect] Successful connection Mar 29 15:56:26 test sudo[875]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 29 15:56:26 test mpd[839]: Mar 29 15:56 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 29 15:56:26 test sudo[875]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 15:56:27 test mpd[839]: Mar 29 15:56 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Mar 29 15:56:27 test mpd[839]: Mar 29 15:56 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Mar 29 15:56:27 test systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 29 15:56:27 test systemd[1]: Started Music Player Daemon. Mar 29 15:56:27 test systemd[1]: shairport-sync.service: Succeeded. Mar 29 15:56:27 test systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 29 15:56:27 test sudo[828]: pam_unix(sudo:session): session closed for user root Mar 29 15:56:27 test sudo[877]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 29 15:56:27 test sudo[877]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 15:56:27 test systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 29 15:56:27 test sudo[873]: pam_unix(sudo:session): session closed for user root Mar 29 15:56:27 test systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 29 15:56:27 test systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Mar 29 15:56:27 test systemd[1]: shairport-sync.service: Succeeded. Mar 29 15:56:27 test systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 29 15:56:28 test volumio[626]: info: Discovery: this is already registered, be4221ea-6182-4cf9-afa1-3c11a9ddc094 Mar 29 15:56:28 test volumio[626]: info: mDNS: Found device TEST Mar 29 15:56:28 test systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 29 15:56:28 test sudo[875]: pam_unix(sudo:session): session closed for user root Mar 29 15:56:28 test sudo[877]: pam_unix(sudo:session): session closed for user root Mar 29 15:56:28 test volumio[626]: info: CoreCommandRouter::volumioGetState Mar 29 15:56:28 test volumio[626]: info: CorePlayQueue::getTrack 0 Mar 29 15:56:28 test sudo[885]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=lmsuser,password=lmsuser,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //10.116.89.204/music /mnt/NAS/lmsuser Mar 29 15:56:28 test sudo[885]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 15:56:28 test volumio[626]: info: Discovery: this is already registered, be4221ea-6182-4cf9-afa1-3c11a9ddc094 Mar 29 15:56:28 test volumio[626]: info: mDNS: Found device TEST Mar 29 15:56:28 test volumio[626]: info: CoreCommandRouter::volumioGetState Mar 29 15:56:28 test volumio[626]: info: CorePlayQueue::getTrack 0 Mar 29 15:56:28 test kernel: CIFS: Attempting to mount //10.116.89.204/music Mar 29 15:56:28 test volumio[626]: Upnp client error: Error: This socket has been ended by the other party Mar 29 15:56:28 test volumio[626]: info: Shairport-Sync Started Mar 29 15:56:28 test volumio[626]: Error adding Membership: Error: addMembership EINVAL Mar 29 15:56:28 test kernel: CIFS: VFS: BAD_NETWORK_NAME: \\10.116.89.204\music Mar 29 15:56:28 test kernel: CIFS: VFS: cifs_mount failed w/return code = -2 Mar 29 15:56:28 test sudo[885]: pam_unix(sudo:session): session closed for user root Mar 29 15:56:28 test volumio-remote-updater[295]: [2024-03-29 15:56:28] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1711727786 101 Mar 29 15:56:28 test volumio[626]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 14 Mar 29 15:56:28 test volumio[626]: info: Shairport-Sync Started Mar 29 15:56:28 test volumio[626]: info: Shairport-Sync Started Mar 29 15:56:29 test volumio[626]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 15 Mar 29 15:56:29 test volumio[626]: info: Cannot mount NAS lmsuser at system boot, trial number 4 ,retrying in 5 seconds Mar 29 15:56:29 test volumio[626]: error: updateQueue error: null Mar 29 15:56:30 test sudo[894]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 29 15:56:30 test sudo[894]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 15:56:30 test sudo[896]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 29 15:56:30 test sudo[894]: pam_unix(sudo:session): session closed for user root Mar 29 15:56:30 test sudo[896]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 15:56:30 test volumio[626]: info: CoreCommandRouter::volumioGetState Mar 29 15:56:30 test volumio[626]: info: CorePlayQueue::getTrack 0 Mar 29 15:56:30 test volumio[626]: info: CoreCommandRouter::volumioGetState Mar 29 15:56:30 test volumio[626]: info: CorePlayQueue::getTrack 0 Mar 29 15:56:30 test sudo[896]: pam_unix(sudo:session): session closed for user root Mar 29 15:56:30 test volumio[626]: info: CoreCommandRouter::volumioGetState Mar 29 15:56:30 test volumio[626]: info: CorePlayQueue::getTrack 0 Mar 29 15:56:30 test volumio[626]: info: CoreCommandRouter::volumioGetState Mar 29 15:56:30 test volumio[626]: info: CorePlayQueue::getTrack 0 Mar 29 15:56:30 test volumio[626]: verbose: New Socket.io Connection to 10.116.89.28 from 172.18.5.206 UA: Mozilla/5.0 (X11; Ubuntu; Linux x86_64; rv:123.0) Gecko/20100101 Firefox/123.0 Total Clients: 16 Mar 29 15:56:30 test volumio[626]: info: CoreCommandRouter::volumioGetVisibleSources Mar 29 15:56:30 test volumio[626]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 15:56:30 test volumio[626]: info: CoreCommandRouter::volumioGetState Mar 29 15:56:30 test volumio[626]: info: CorePlayQueue::getTrack 0 Mar 29 15:56:30 test volumio[626]: info: CoreCommandRouter::volumioGetQueue Mar 29 15:56:30 test volumio[626]: info: CoreStateMachine::getQueue Mar 29 15:56:30 test volumio[626]: info: CorePlayQueue::getQueue Mar 29 15:56:30 test volumio[626]: info: Listing playlists Mar 29 15:56:30 test volumio[626]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 29 15:56:30 test volumio[626]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 29 15:56:31 test volumio[626]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 29 15:56:31 test volumio[626]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 29 15:56:31 test volumio[626]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 29 15:56:33 test volumio[626]: info: AutoStart - getting queue Mar 29 15:56:33 test volumio[626]: info: CoreCommandRouter::volumioGetQueue Mar 29 15:56:33 test volumio[626]: info: CoreStateMachine::getQueue Mar 29 15:56:33 test volumio[626]: info: CorePlayQueue::getQueue Mar 29 15:56:34 test sudo[906]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=lmsuser,password=lmsuser,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //10.116.89.204/music /mnt/NAS/lmsuser Mar 29 15:56:34 test sudo[906]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 15:56:34 test kernel: CIFS: Attempting to mount //10.116.89.204/music Mar 29 15:56:34 test kernel: CIFS: VFS: BAD_NETWORK_NAME: \\10.116.89.204\music Mar 29 15:56:34 test kernel: CIFS: VFS: cifs_mount failed w/return code = -2 Mar 29 15:56:34 test sudo[906]: pam_unix(sudo:session): session closed for user root Mar 29 15:56:34 test volumio[626]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Mar 29 15:56:36 test volumio[626]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 29 15:56:37 test volumio[626]: error: MyVolumio Plugin failed to start in a timely fashion Mar 29 15:56:37 test volumio[626]: info: BOOT COMPLETED Mar 29 15:56:37 test volumio[626]: [Metrics] CommandRouter: 82s 994.30ms Mar 29 15:56:37 test volumio[626]: info: CoreCommandRouter::volumiosetStartupVolume Mar 29 15:56:37 test volumio[626]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 29 15:56:37 test volumio[626]: info: VolumeController:: Setting startup Volume 65 Mar 29 15:56:37 test volumio[626]: info: VolumeController::SetAlsaVolume65 Mar 29 15:56:37 test volumio[626]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 15:56:37 test volumio[626]: info: CoreCommandRouter::Close All Modals sent Mar 29 15:56:37 test volumio[626]: info: CoreCommandRouter::Close All Modals sent Mar 29 15:56:37 test volumio[626]: info: CoreStateMachine::pushState Mar 29 15:56:37 test volumio[626]: info: CorePlayQueue::getTrack 0 Mar 29 15:56:37 test volumio[626]: info: CoreCommandRouter::volumioPushState Mar 29 15:56:37 test volumio[626]: info: CoreCommandRouter::volumioGetState Mar 29 15:56:37 test volumio[626]: info: CorePlayQueue::getTrack 0 Mar 29 15:56:38 test volumio[626]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Mar 29 15:56:38 test volumio[626]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 29 15:56:38 test volumio[626]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Mar 29 15:56:39 test volumio[626]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Mar 29 15:56:39 test volumio[626]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Mar 29 15:56:39 test volumio[626]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Mar 29 15:56:39 test volumio[626]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Mar 29 15:56:39 test volumio[626]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Mar 29 15:56:39 test volumio[626]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Mar 29 15:56:39 test volumio[626]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Mar 29 15:56:39 test volumio[626]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Mar 29 15:56:39 test volumio[626]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Mar 29 15:56:39 test volumio[626]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Mar 29 15:56:39 test volumio[626]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Mar 29 15:56:39 test volumio[626]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Mar 29 15:56:39 test volumio[626]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Mar 29 15:56:39 test volumio[626]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Mar 29 15:56:39 test volumio[626]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Mar 29 15:56:39 test volumio[626]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Mar 29 15:56:39 test volumio[626]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Mar 29 15:56:39 test volumio[626]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Mar 29 15:56:39 test volumio[626]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Mar 29 15:56:39 test volumio[626]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Mar 29 15:56:39 test volumio[626]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Mar 29 15:56:39 test volumio[626]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Mar 29 15:56:39 test volumio[626]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Mar 29 15:56:39 test volumio[626]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Mar 29 15:56:39 test volumio[626]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Mar 29 15:56:39 test volumio[626]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Mar 29 15:56:39 test volumio[626]: info: Adding plugin bluetooth to MyMusic Plugins Mar 29 15:56:39 test volumio[626]: info: Adding plugin multiroom to MyMusic Plugins Mar 29 15:56:39 test volumio[626]: info: Adding plugin cd_controller to MyMusic Plugins Mar 29 15:56:39 test volumio[626]: info: Adding plugin smart_inputs to MyMusic Plugins Mar 29 15:56:39 test volumio[626]: info: Adding plugin tidalconnect to MyMusic Plugins Mar 29 15:56:39 test volumio[626]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Mar 29 15:56:46 test volumio[626]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Mar 29 15:56:46 test volumio[626]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Mar 29 15:56:46 test volumio[626]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 15:56:46 test volumio[626]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 15:56:46 test volumio[626]: info: Starting MyVolumio Remote Streaming Endpoints Mar 29 15:56:46 test volumio[626]: info: MyVolumio not started Mar 29 15:56:46 test volumio[626]: info: Initializing device activation check Mar 29 15:56:46 test volumio[626]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Mar 29 15:56:46 test volumio[626]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Mar 29 15:56:47 test volumio[626]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Mar 29 15:56:47 test volumio[626]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Mar 29 15:56:47 test volumio[626]: info: Streaming services startup Mar 29 15:56:47 test volumio[626]: info: Starting Streaming Daemon Mar 29 15:56:48 test sudo[917]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 29 15:56:48 test sudo[917]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 15:56:48 test systemd[1]: Stopping Volumio Streaming Daemon... Mar 29 15:56:48 test systemd[1]: volumio-streaming-daemon.service: Main process exited, code=killed, status=15/TERM Mar 29 15:56:48 test systemd[1]: volumio-streaming-daemon.service: Succeeded. Mar 29 15:56:48 test systemd[1]: Stopped Volumio Streaming Daemon. Mar 29 15:56:48 test systemd[1]: Started Volumio Streaming Daemon. Mar 29 15:56:48 test sudo[917]: pam_unix(sudo:session): session closed for user root Mar 29 15:56:48 test volumio-streaming-daemon[923]: ############################ Mar 29 15:56:48 test volumio-streaming-daemon[923]: # Volumio Streaming Daemon # Mar 29 15:56:48 test volumio-streaming-daemon[923]: # Running on port 7777 # Mar 29 15:56:48 test volumio-streaming-daemon[923]: ############################ Mar 29 15:56:48 test volumio-streaming-daemon[923]: Environment: production Mar 29 15:56:48 test volumio[626]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 29 15:56:48 test volumio[626]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 15:56:48 test volumio[626]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 29 15:56:49 test volumio[626]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 29 15:56:49 test volumio[626]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 29 15:56:49 test volumio[626]: info: Error : CoreCommandRouter::executeOnPlugin: No method [retreiveBackendEventStates] in plugin my_volumio Mar 29 15:56:49 test volumio[626]: info: Received Get System Version Mar 29 15:56:49 test volumio[626]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 29 15:56:51 test volumio-streaming-daemon[923]: Environment: production Mar 29 15:56:51 test volumio[626]: info: Fetching Streaming Services browse cache Mar 29 15:57:00 test volumio[626]: info: CoreCommandRouter::volumioGetState Mar 29 15:57:00 test volumio[626]: info: CorePlayQueue::getTrack 0 Mar 29 15:57:00 test volumio[626]: info: CoreCommandRouter::volumioGetState Mar 29 15:57:00 test volumio[626]: info: CorePlayQueue::getTrack 0 Mar 29 15:57:00 test volumio[626]: info: CoreCommandRouter::volumioGetState Mar 29 15:57:00 test volumio[626]: info: CorePlayQueue::getTrack 0 Mar 29 15:57:00 test volumio[626]: info: CoreCommandRouter::volumioGetState Mar 29 15:57:00 test volumio[626]: info: CorePlayQueue::getTrack 0 Mar 29 15:57:10 test volumio[626]: info: Mar 29 15:57:10 test volumio[626]: ---------------------------- Client requests Volumio Clear Queue Mar 29 15:57:10 test volumio[626]: info: CoreCommandRouter::volumioClearQueue Mar 29 15:57:10 test volumio[626]: info: CoreStateMachine::ClearQueue Mar 29 15:57:10 test volumio[626]: info: CoreStateMachine::stop Mar 29 15:57:10 test volumio[626]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 15:57:10 test volumio[626]: verbose: UNSET VOLATILE Mar 29 15:57:10 test volumio[626]: info: CorePlayQueue::clearPlayQueue Mar 29 15:57:10 test volumio[626]: info: CorePlayQueue::saveQueue Mar 29 15:57:10 test volumio[626]: info: CoreCommandRouter::volumioPushState Mar 29 15:57:10 test volumio[626]: info: CoreCommandRouter::volumioPushQueue Mar 29 15:57:10 test volumio[626]: error: API:pushError: TypeError: callback.apply is not a function Mar 29 15:57:10 test volumio[626]: info: Mar 29 15:57:10 test volumio[626]: ---------------------------- Client requests Volumio Play Playlist Test-TMS-29-03 Mar 29 15:57:10 test volumio[626]: info: Play playlist Test-TMS-29-03 Mar 29 15:57:11 test volumio[626]: info: CoreCommandRouter::volumioClearQueue Mar 29 15:57:11 test volumio[626]: info: CoreStateMachine::ClearQueue Mar 29 15:57:11 test volumio[626]: info: CoreStateMachine::stop Mar 29 15:57:11 test volumio[626]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 15:57:11 test volumio[626]: verbose: UNSET VOLATILE Mar 29 15:57:11 test volumio[626]: info: CorePlayQueue::clearPlayQueue Mar 29 15:57:11 test volumio[626]: info: CorePlayQueue::saveQueue Mar 29 15:57:11 test volumio[626]: info: CoreCommandRouter::volumioPushState Mar 29 15:57:11 test volumio[626]: info: CoreCommandRouter::volumioPushQueue Mar 29 15:57:11 test volumio[626]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 29 15:57:11 test volumio[626]: TypeError: Cannot read property 'lastIndexOf' of undefined Mar 29 15:57:11 test volumio[626]: at S.startsWith (/volumio/node_modules/string/lib/string.js:427:20) Mar 29 15:57:11 test volumio[626]: at /volumio/app/playlistManager.js:695:25 Mar 29 15:57:11 test volumio[626]: at /volumio/node_modules/jsonfile/index.js:46:5 Mar 29 15:57:11 test volumio[626]: at /volumio/node_modules/graceful-fs/graceful-fs.js:123:16 Mar 29 15:57:11 test volumio[626]: at FSReqCallback.readFileAfterClose [as oncomplete] (internal/fs/read_file_context.js:63:3) Mar 29 15:57:11 test volumio[626]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 29 15:57:14 test sudo[946]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-03-29 15:56 Mar 29 15:57:14 test sudo[946]: 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="e5f8795c07ba36ff2b02d7ba0a21c7a0f29b897e" VOLUMIO_FE_VERSION="473d8facbed5f9754db84a81b093baace7aa4b64" VOLUMIO_FE3_VERSION="934cddf6d46478e7a53a8d3664cd346f4de7cb34" VOLUMIO_BE_VERSION="359b2717b79cfc72b7d1c918d0fc2885a93bca70" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 24 Jan 2022 10:51:57 AM CET" VOLUMIO_VERSION="3.198" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="74e6a9ad035e912732893c8d6b060b17"