-- 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"