-- Logs begin at Tue 2025-04-29 13:06:01 CST, end at Mon 2025-05-05 01:49:20 CST. -- May 05 01:48:35 primo systemd-timedated[1303]: Changed local time to Mon May 5 01:48:35 2025 May 05 01:48:35 primo sudo[1297]: pam_unix(sudo:session): session closed for user root May 05 01:48:35 primo systemd[1]: Starting Daily man-db regeneration... May 05 01:48:35 primo systemd[1]: Starting Daily apt download activities... May 05 01:48:35 primo volumio-time-update[579]: volumio-time-update-util: System time updated successfully. May 05 01:48:35 primo systemd[1]: Started Volumio Time Update Utility. May 05 01:48:35 primo volumio[1101]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 05 01:48:35 primo volumio[1101]: *** WARNING *** Please fix your application to use the native API of Avahi! May 05 01:48:35 primo volumio[1101]: *** WARNING *** For more information see May 05 01:48:35 primo volumio[1101]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 05 01:48:35 primo volumio[1101]: *** WARNING *** Please fix your application to use the native API of Avahi! May 05 01:48:35 primo volumio[1101]: *** WARNING *** For more information see May 05 01:48:35 primo node[1101]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 05 01:48:35 primo node[1101]: *** WARNING *** Please fix your application to use the native API of Avahi! May 05 01:48:35 primo node[1101]: *** WARNING *** For more information see May 05 01:48:35 primo node[1101]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 05 01:48:35 primo node[1101]: *** WARNING *** Please fix your application to use the native API of Avahi! May 05 01:48:35 primo node[1101]: *** WARNING *** For more information see May 05 01:48:35 primo volumio[1101]: info: Applying required configuration parameters for plugin volumiodiscovery May 05 01:48:35 primo volumio[1101]: info: Discovery: Started advertising with name: primo May 05 01:48:35 primo systemd[1]: man-db.service: Succeeded. May 05 01:48:35 primo systemd[1]: Started Daily man-db regeneration. May 05 01:48:35 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 05 01:48:35 primo volumio[1101]: info: Loading plugin "outputs"... May 05 01:48:35 primo volumio[1101]: info: Loading plugin "albumart"... May 05 01:48:35 primo volumio[1101]: info: Plugin example_plugin is not enabled May 05 01:48:35 primo volumio[1101]: info: Loading plugin "inputs"... May 05 01:48:35 primo volumio[1101]: info: Loading plugin "updater_comm"... May 05 01:48:35 primo volumio[1101]: info: Plugin mpdemulation is not enabled May 05 01:48:35 primo volumio[1101]: info: Loading plugin "rest_api"... May 05 01:48:35 primo volumio[1101]: info: Loading plugin "websocket"... May 05 01:48:35 primo volumio[1101]: info: Starting Socket.io Server version 2.3.0 May 05 01:48:35 primo volumio[1101]: info: Loading i18n strings for locale zh May 05 01:48:35 primo volumio[1101]: Updating browse sources language May 05 01:48:35 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 05 01:48:35 primo ntpd[1142]: Soliciting pool server 45.76.221.157 May 05 01:48:35 primo volumio[1101]: Forking 3 albumart workers May 05 01:48:35 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 05 01:48:35 primo volumio[1101]: info: CoreCommandRouter::initPlayerControls May 05 01:48:35 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:48:35 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:48:35 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:48:35 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:48:35 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:48:35 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:48:35 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:48:35 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:48:35 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 05 01:48:35 primo volumio[1101]: Express server listening on port 3000 May 05 01:48:35 primo volumio[1101]: [Metrics] WebUI: 4s 77.28ms May 05 01:48:35 primo volumio[1101]: info: CoreStateMachine::resetVolumioState May 05 01:48:35 primo volumio[1101]: info: CoreStateMachine::getcurrentVolume May 05 01:48:35 primo volumio[1101]: info: CoreCommandRouter::volumioRetrievevolume May 05 01:48:35 primo volumio[1101]: info: CoreStateMachine::pushState May 05 01:48:35 primo volumio[1101]: info: CorePlayQueue::getTrack 0 May 05 01:48:35 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 01:48:35 primo volumio[1101]: info: CoreCommandRouter::volumioPushState May 05 01:48:35 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 01:48:35 primo volumio[1101]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' May 05 01:48:35 primo volumio[1101]: info: Completed loading Core Plugins May 05 01:48:35 primo volumio[1101]: info: Preparing to generate the ALSA configuration file May 05 01:48:35 primo volumio[1101]: info: Volumio Network Manager: Network status updated: 2 May 05 01:48:35 primo systemd[1]: apt-daily.service: Succeeded. May 05 01:48:35 primo systemd[1]: Started Daily apt download activities. May 05 01:48:35 primo systemd[1]: Starting Daily apt upgrade and clean activities... May 05 01:48:35 primo volumio-remote-updater[578]: [2025-05-05 01:48:35] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1745903176 101 May 05 01:48:35 primo volumio[1101]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 May 05 01:48:36 primo volumio[1101]: info: Reloading queue from file May 05 01:48:36 primo volumio[1101]: info: Setting Device type: Tinkerboard May 05 01:48:36 primo volumio[1101]: info: CoreStateMachine::setRepeat true single undefined May 05 01:48:36 primo volumio[1101]: info: CoreStateMachine::pushState May 05 01:48:36 primo volumio[1101]: info: CorePlayQueue::getTrack 0 May 05 01:48:36 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 01:48:36 primo volumio[1101]: info: CoreCommandRouter::volumioPushState May 05 01:48:36 primo volumio[1101]: info: CoreStateMachine::setRandom false May 05 01:48:36 primo volumio[1101]: info: CoreStateMachine::pushState May 05 01:48:36 primo volumio[1101]: info: CorePlayQueue::getTrack 0 May 05 01:48:36 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 01:48:36 primo volumio[1101]: info: CoreCommandRouter::volumioPushState May 05 01:48:36 primo volumio[1101]: Starting albumart workers May 05 01:48:36 primo volumio[1101]: info: Asound.conf file written May 05 01:48:36 primo volumio[1101]: Starting albumart workers May 05 01:48:36 primo sudo[1413]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf May 05 01:48:36 primo volumio[1101]: Starting albumart workers May 05 01:48:36 primo sudo[1413]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:48:36 primo sudo[1413]: pam_unix(sudo:session): session closed for user root May 05 01:48:36 primo volumio[1101]: info: Output device has changed, restarting MPD May 05 01:48:36 primo volumio[1101]: info: Output device has changed, restarting Shairport Sync May 05 01:48:36 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:48:36 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 01:48:36 primo sudo[1438]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 05 01:48:36 primo sudo[1444]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 05 01:48:36 primo ntpd[1142]: Soliciting pool server 2a03:8600::ff May 05 01:48:36 primo volumio[1101]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 05 01:48:36 primo volumio[1101]: info: ___________ START PLUGINS ___________ May 05 01:48:36 primo sudo[1438]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:48:36 primo sudo[1444]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:48:36 primo volumio[1101]: info: ControllerMpd::onStart: Initializing MPD May 05 01:48:36 primo volumio[1101]: info: Creating MPD Configuration file May 05 01:48:36 primo sudo[1438]: pam_unix(sudo:session): session closed for user root May 05 01:48:36 primo systemd[1]: Stopping Music Player Daemon... May 05 01:48:36 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 05 01:48:36 primo volumio[1101]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 05 01:48:36 primo sudo[1461]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 05 01:48:36 primo volumio[1101]: info: [1746380916497] CoreMusicLibrary::Adding element 媒体服务器 May 05 01:48:36 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 05 01:48:36 primo sudo[1461]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:48:36 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:48:36 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 01:48:36 primo sudo[1468]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 05 01:48:36 primo sudo[1461]: pam_unix(sudo:session): session closed for user root May 05 01:48:36 primo sudo[1468]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:48:36 primo volumio[1101]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 05 01:48:36 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:48:36 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 01:48:36 primo systemd[1]: mpd.service: Succeeded. May 05 01:48:36 primo systemd[1]: Stopped Music Player Daemon. May 05 01:48:36 primo systemd[1]: Starting Music Player Daemon... May 05 01:48:36 primo systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM May 05 01:48:36 primo systemd[1]: mpd.service: Succeeded. May 05 01:48:36 primo systemd[1]: Stopped Music Player Daemon. May 05 01:48:36 primo systemd[1]: Starting Music Player Daemon... May 05 01:48:36 primo volumio[1101]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 05 01:48:36 primo volumio[1101]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 05 01:48:36 primo volumio[1101]: info: [1746380916721] CoreMusicLibrary::Adding element Last_100 May 05 01:48:36 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 05 01:48:36 primo volumio[1101]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 05 01:48:36 primo volumio[1101]: info: [1746380916723] CoreMusicLibrary::Adding element Webradio May 05 01:48:36 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 05 01:48:36 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 05 01:48:36 primo volumio[1101]: info: Initializing BBC Radios May 05 01:48:36 primo sudo[1507]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 05 01:48:36 primo sudo[1507]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:48:36 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 05 01:48:36 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 01:48:36 primo sudo[1507]: pam_unix(sudo:session): session closed for user root May 05 01:48:36 primo volumio[1101]: info: Volumio Calling Home May 05 01:48:36 primo systemd[1]: apt-daily-upgrade.service: Succeeded. May 05 01:48:36 primo systemd[1]: Started Daily apt upgrade and clean activities. May 05 01:48:36 primo volumio[1101]: info: Discovery: adding c801c86f-eccc-4cf1-865f-c31df3bb196b May 05 01:48:36 primo volumio[1101]: info: Discovery: Found device primo May 05 01:48:36 primo volumio[1101]: info: CoreCommandRouter::volumioGetState May 05 01:48:36 primo volumio[1101]: info: CorePlayQueue::getTrack 0 May 05 01:48:36 primo volumio[1101]: info: MPD Permissions set May 05 01:48:36 primo volumio[1101]: info: MPD Permissions set May 05 01:48:36 primo volumio[1101]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 May 05 01:48:36 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:48:36 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:48:36 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:48:36 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:48:36 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:48:36 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:48:36 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:48:36 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:48:37 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 01:48:37 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 05 01:48:37 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:48:37 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:48:37 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:48:37 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:48:37 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:48:37 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:48:37 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:48:37 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:48:37 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:48:37 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 01:48:37 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber May 05 01:48:37 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:48:37 primo volumio[1101]: info: Starting Shairport Sync May 05 01:48:37 primo volumio[1101]: info: Starting Shairport Sync May 05 01:48:37 primo volumio[1101]: info: Starting Shairport Sync May 05 01:48:37 primo sudo[1568]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 05 01:48:37 primo sudo[1570]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 05 01:48:37 primo sudo[1570]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:48:37 primo sudo[1575]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 05 01:48:37 primo volumio[1101]: info: CoreCommandRouter::volumioGetState May 05 01:48:37 primo volumio[1101]: info: CorePlayQueue::getTrack 0 May 05 01:48:37 primo sudo[1568]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:48:37 primo sudo[1575]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:48:37 primo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 05 01:48:37 primo systemd[1]: shairport-sync.service: Succeeded. May 05 01:48:37 primo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 05 01:48:37 primo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 05 01:48:37 primo sudo[1570]: pam_unix(sudo:session): session closed for user root May 05 01:48:37 primo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 05 01:48:37 primo systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM May 05 01:48:37 primo systemd[1]: shairport-sync.service: Succeeded. May 05 01:48:37 primo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 05 01:48:37 primo volumio[1101]: info: Shairport-Sync Started May 05 01:48:37 primo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 05 01:48:37 primo sudo[1575]: pam_unix(sudo:session): session closed for user root May 05 01:48:37 primo sudo[1568]: pam_unix(sudo:session): session closed for user root May 05 01:48:37 primo volumio[1101]: Error adding Membership: Error: addMembership EINVAL May 05 01:48:37 primo volumio[1101]: info: Shairport-Sync Started May 05 01:48:37 primo volumio[1101]: info: Shairport-Sync Started May 05 01:48:37 primo volumio[1101]: info: Volumio called home May 05 01:48:37 primo mpd[1554]: May 05 01:48 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 05 01:48:37 primo systemd[1]: Started Music Player Daemon. May 05 01:48:38 primo sudo[1444]: pam_unix(sudo:session): session closed for user root May 05 01:48:38 primo sudo[1468]: pam_unix(sudo:session): session closed for user root May 05 01:48:38 primo volumio[1101]: info: Completed starting Core Plugins May 05 01:48:38 primo volumio[1101]: info: ------------------------------------------- May 05 01:48:38 primo volumio[1101]: info: ----- MyVolumio plugins startup ---- May 05 01:48:38 primo volumio[1101]: info: ------------------------------------------- May 05 01:48:38 primo volumio[1101]: info: [MyVolumio PluginManager] Fetching plans data.... May 05 01:48:38 primo volumio[1101]: error: MPD error: The expression evaluated to a falsy value: May 05 01:48:38 primo volumio[1101]: assert.ok(self.idling) May 05 01:48:38 primo volumio[1101]: error: The expression evaluated to a falsy value: May 05 01:48:38 primo volumio[1101]: assert.ok(self.idling) May 05 01:48:38 primo volumio[1101]: info: MPD running with PID1554 May 05 01:48:38 primo volumio[1101]: ,establishing connection May 05 01:48:38 primo volumio[1101]: error: updateQueue error: null May 05 01:48:38 primo volumio[1101]: error: updateQueue error: null May 05 01:48:42 primo ntpd[1142]: receive: Unexpected origin timestamp 0xebc226f9.64644c22 does not match aorg 0000000000.00000000 from server@193.182.111.12 xmt 0xebc226fa.04ef9cf0 May 05 01:48:42 primo ntpd[1142]: receive: Unexpected origin timestamp 0xebc226f9.6462bbb8 does not match aorg 0000000000.00000000 from server@193.182.111.142 xmt 0xebc226fa.0aece039 May 05 01:48:42 primo ntpd[1142]: receive: Unexpected origin timestamp 0xebc226f9.645929ca does not match aorg 0000000000.00000000 from server@176.126.78.152 xmt 0xebc226fa.0ee843ff May 05 01:48:43 primo volumio[1101]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 05 01:48:43 primo volumio[1101]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-touch Engine version: 3 Transport: polling Total Clients: 3 May 05 01:48:44 primo sudo[1284]: pam_unix(sudo:session): session closed for user root May 05 01:48:44 primo kernel: CIFS VFS: Error connecting to socket. Aborting operation. May 05 01:48:44 primo kernel: CIFS VFS: cifs_mount failed w/return code = -115 May 05 01:48:44 primo sudo[1781]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 05 01:48:44 primo volumio[1101]: info: Cannot mount NAS music at system boot, trial number 1 ,retrying in 5 seconds May 05 01:48:44 primo sudo[1784]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 05 01:48:44 primo sudo[1781]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:48:44 primo sudo[1789]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 05 01:48:44 primo sudo[1781]: pam_unix(sudo:session): session closed for user root May 05 01:48:44 primo sudo[1784]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:48:44 primo sudo[1789]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:48:44 primo sudo[1784]: pam_unix(sudo:session): session closed for user root May 05 01:48:44 primo systemd[1]: Started UPnP Renderer front-end to MPD. May 05 01:48:44 primo sudo[1789]: pam_unix(sudo:session): session closed for user root May 05 01:48:44 primo volumio[1101]: info: Upmpdcli Daemon Started May 05 01:48:44 primo volumio[1808]: Generating RSA private key, 4096 bit long modulus (2 primes) May 05 01:48:45 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 05 01:48:45 primo volumio[1101]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-touch Engine version: 3 Transport: polling Total Clients: 4 May 05 01:48:46 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 05 01:48:46 primo volumio[1101]: info: CoreCommandRouter::volumioGetVisibleSources May 05 01:48:46 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 05 01:48:46 primo volumio[1101]: info: CoreCommandRouter::volumioGetState May 05 01:48:46 primo volumio[1101]: info: CorePlayQueue::getTrack 0 May 05 01:48:46 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 05 01:48:46 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 05 01:48:46 primo volumio[1101]: info: Received Get System Info May 05 01:48:46 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 05 01:48:46 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 05 01:48:46 primo volumio[1101]: info: Discovery: Getting this device information May 05 01:48:46 primo volumio[1101]: info: CoreCommandRouter::volumioGetState May 05 01:48:46 primo volumio[1101]: info: CorePlayQueue::getTrack 0 May 05 01:48:46 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 05 01:48:46 primo volumio[1101]: info: CoreCommandRouter::volumioGetState May 05 01:48:46 primo volumio[1101]: info: CorePlayQueue::getTrack 0 May 05 01:48:46 primo volumio[1101]: info: Listing playlists May 05 01:48:46 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 05 01:48:46 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 05 01:48:47 primo volumio[1101]: info: CoreCommandRouter::volumioGetState May 05 01:48:47 primo volumio[1101]: info: CorePlayQueue::getTrack 0 May 05 01:48:47 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 05 01:48:47 primo volumio[1101]: info: Received Get System Info May 05 01:48:47 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 05 01:48:47 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 05 01:48:47 primo volumio[1101]: info: Discovery: Getting this device information May 05 01:48:47 primo volumio[1101]: info: CoreCommandRouter::volumioGetState May 05 01:48:47 primo volumio[1101]: info: CorePlayQueue::getTrack 0 May 05 01:48:47 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 05 01:48:47 primo volumio[1101]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 05 01:48:47 primo volumio[1101]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 05 01:48:47 primo volumio[1101]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 05 01:48:47 primo volumio[1101]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 05 01:48:47 primo volumio[1101]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 05 01:48:47 primo volumio[1101]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 05 01:48:47 primo volumio[1101]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 05 01:48:47 primo volumio[1101]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 05 01:48:47 primo volumio[1101]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 05 01:48:47 primo volumio[1101]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 05 01:48:47 primo volumio[1101]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium May 05 01:48:47 primo volumio[1101]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 05 01:48:47 primo volumio[1101]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 05 01:48:47 primo volumio[1101]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 05 01:48:47 primo volumio[1101]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 05 01:48:47 primo volumio[1101]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 05 01:48:47 primo volumio[1101]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 05 01:48:47 primo volumio[1101]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 05 01:48:47 primo volumio[1101]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 05 01:48:47 primo volumio[1101]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 05 01:48:47 primo volumio[1101]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 05 01:48:47 primo volumio[1101]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 05 01:48:47 primo volumio[1101]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 05 01:48:47 primo volumio[1101]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 05 01:48:47 primo volumio[1101]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 05 01:48:47 primo volumio[1101]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 05 01:48:47 primo volumio[1101]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 05 01:48:47 primo volumio[1101]: info: Adding plugin bluetooth to MyMusic Plugins May 05 01:48:47 primo volumio[1101]: info: Adding plugin multiroom to MyMusic Plugins May 05 01:48:47 primo volumio[1101]: info: Adding plugin metavolumio to MyMusic Plugins May 05 01:48:47 primo volumio[1101]: info: Adding plugin cd_controller to MyMusic Plugins May 05 01:48:47 primo volumio[1101]: info: Adding plugin smart_inputs to MyMusic Plugins May 05 01:48:47 primo volumio[1101]: info: Adding plugin tidalconnect to MyMusic Plugins May 05 01:48:47 primo volumio[1101]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 05 01:48:48 primo systemd[1]: systemd-fsckd.service: Succeeded. May 05 01:48:48 primo volumio[1101]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 05 01:48:48 primo volumio[1101]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 05 01:48:48 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 01:48:48 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 01:48:48 primo volumio[1101]: info: Starting MyVolumio Remote Streaming Endpoints May 05 01:48:48 primo volumio[1101]: info: MyVolumio login type: Token May 05 01:48:48 primo volumio[1101]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 05 01:48:48 primo volumio[1101]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 05 01:48:48 primo sh[501]: timed out May 05 01:48:48 primo dhcpcd[545]: timed out May 05 01:48:48 primo sh[501]: dhcpcd exited May 05 01:48:48 primo dhcpcd[545]: dhcpcd exited May 05 01:48:48 primo systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE May 05 01:48:48 primo sh[501]: ifup: failed to bring up eth0 May 05 01:48:48 primo systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. May 05 01:48:48 primo dhcpcd[1852]: sending commands to master dhcpcd process May 05 01:48:48 primo ifplugd(eth0)[782]: client: sending commands to master dhcpcd process May 05 01:48:48 primo dhcpcd[1143]: control command: /sbin/dhcpcd eth0 May 05 01:48:48 primo ifplugd(eth0)[782]: Program executed successfully. May 05 01:48:48 primo ifplugd(eth0)[782]: Link beat lost. May 05 01:48:48 primo ifplugd[731]: Network Interface Plugging Daemon...start eth0...done. May 05 01:48:48 primo systemd[1]: Started LSB: Brings up/down network automatically. May 05 01:48:48 primo systemd[1]: Reached target Multi-User System. May 05 01:48:48 primo systemd[1]: Reached target Graphical Interface. May 05 01:48:48 primo systemd[1]: Starting Update UTMP about System Runlevel Changes... May 05 01:48:49 primo systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. May 05 01:48:49 primo systemd[1]: Started Update UTMP about System Runlevel Changes. May 05 01:48:49 primo systemd[1]: Startup finished in 13.982s (kernel) + 32.129s (userspace) = 46.112s. May 05 01:48:49 primo volumio[1101]: info: Starting Streaming Service Transparent Proxy May 05 01:48:49 primo volumio[1101]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 05 01:48:49 primo volumio[1101]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 05 01:48:49 primo volumio[1101]: info: Streaming services startup May 05 01:48:49 primo volumio[1101]: info: Starting Streaming Daemon May 05 01:48:49 primo sudo[1900]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 05 01:48:49 primo volumio[1101]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 05 01:48:49 primo sudo[1900]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:48:49 primo sudo[1900]: pam_unix(sudo:session): session closed for user root May 05 01:48:49 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 05 01:48:49 primo volumio[1101]: error: Cannot start Volumio Streaming Daemon May 05 01:48:49 primo volumio[1101]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 05 01:48:49 primo volumio[1101]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 05 01:48:49 primo sudo[1915]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.10.10/音乐 /mnt/NAS/music May 05 01:48:49 primo sudo[1915]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:48:50 primo volumio[1808]: ...................................................................................................++++ May 05 01:48:50 primo volumio[1101]: STREAMING PROXY: Starting server on port 3245 May 05 01:48:50 primo volumio[1101]: Node JS runtime: 14 May 05 01:48:50 primo systemd[1]: systemd-hostnamed.service: Succeeded. May 05 01:48:50 primo sudo[1927]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 05 01:48:50 primo sudo[1927]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:48:50 primo sudo[1927]: pam_unix(sudo:session): session closed for user root May 05 01:48:50 primo sudo[1930]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 05 01:48:50 primo sudo[1930]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:48:50 primo sudo[1930]: pam_unix(sudo:session): session closed for user root May 05 01:48:50 primo volumio[1101]: verbose: New Socket.io Connection to 192.168.32.158 from 192.168.32.159 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/116.0.5845.97 Safari/537.36 SE 2.X MetaSr 1.0 Engine version: 3 Transport: polling Total Clients: 5 May 05 01:48:51 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 05 01:48:51 primo volumio[1101]: info: CoreCommandRouter::volumioGetVisibleSources May 05 01:48:51 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 05 01:48:51 primo volumio[1101]: info: CoreCommandRouter::volumioGetState May 05 01:48:51 primo volumio[1101]: info: CorePlayQueue::getTrack 0 May 05 01:48:51 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 05 01:48:51 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 05 01:48:51 primo volumio[1101]: info: Received Get System Info May 05 01:48:51 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 05 01:48:51 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 05 01:48:51 primo volumio[1101]: info: Discovery: Getting this device information May 05 01:48:51 primo volumio[1101]: info: CoreCommandRouter::volumioGetState May 05 01:48:51 primo volumio[1101]: info: CorePlayQueue::getTrack 0 May 05 01:48:51 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 05 01:48:51 primo volumio[1101]: info: CoreCommandRouter::volumioGetState May 05 01:48:51 primo volumio[1101]: info: CorePlayQueue::getTrack 0 May 05 01:48:51 primo volumio[1101]: info: Listing playlists May 05 01:48:51 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 05 01:48:51 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 05 01:48:51 primo sudo[1947]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 05 01:48:51 primo sudo[1949]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 05 01:48:51 primo sudo[1947]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:48:51 primo sudo[1949]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:48:51 primo sudo[1947]: pam_unix(sudo:session): session closed for user root May 05 01:48:51 primo sudo[1949]: pam_unix(sudo:session): session closed for user root May 05 01:48:51 primo volumio[1101]: verbose: New Socket.io Connection to 192.168.32.158 from 192.168.32.159 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/116.0.5845.97 Safari/537.36 SE 2.X MetaSr 1.0 Engine version: 3 Transport: polling Total Clients: 6 May 05 01:48:51 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 05 01:48:52 primo volumio[1101]: info: CoreCommandRouter::volumioGetVisibleSources May 05 01:48:52 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 05 01:48:52 primo volumio[1101]: info: CoreCommandRouter::volumioGetState May 05 01:48:52 primo volumio[1101]: info: CorePlayQueue::getTrack 0 May 05 01:48:52 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 05 01:48:52 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 05 01:48:52 primo volumio[1101]: info: Received Get System Info May 05 01:48:52 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 05 01:48:52 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 05 01:48:52 primo volumio[1101]: info: Discovery: Getting this device information May 05 01:48:52 primo volumio[1101]: info: CoreCommandRouter::volumioGetState May 05 01:48:52 primo volumio[1101]: info: CorePlayQueue::getTrack 0 May 05 01:48:52 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 05 01:48:52 primo volumio[1101]: info: CoreCommandRouter::volumioGetState May 05 01:48:52 primo volumio[1101]: info: CorePlayQueue::getTrack 0 May 05 01:48:52 primo volumio[1101]: info: Listing playlists May 05 01:48:52 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 05 01:48:52 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 05 01:48:52 primo volumio[1101]: info: CoreCommandRouter::volumioGetState May 05 01:48:52 primo volumio[1101]: info: CorePlayQueue::getTrack 0 May 05 01:48:53 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 05 01:48:53 primo volumio[1101]: info: Received Get System Info May 05 01:48:53 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 05 01:48:53 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 05 01:48:53 primo volumio[1101]: info: Discovery: Getting this device information May 05 01:48:53 primo volumio[1101]: info: CoreCommandRouter::volumioGetState May 05 01:48:53 primo volumio[1101]: info: CorePlayQueue::getTrack 0 May 05 01:48:53 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 05 01:48:53 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 05 01:48:54 primo volumio[1808]: .................................................................................++++ May 05 01:48:54 primo volumio[1808]: e is 65537 (0x010001) May 05 01:48:54 primo volumio[1808]: writing RSA key May 05 01:48:55 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 05 01:48:58 primo volumio[1101]: info: CoreCommandRouter::getUIConfigOnPlugin May 05 01:48:58 primo volumio[1101]: info: CoreCommandRouter::getUIConfigOnPlugin May 05 01:48:58 primo volumio[1101]: info: Retrieving Cloud Streaming UI May 05 01:48:58 primo volumio[1101]: info: Getting Tidal Cloud Configuration May 05 01:48:58 primo volumio[1101]: info: CoreCommandRouter::getUIConfigOnPlugin May 05 01:48:58 primo volumio[1101]: info: Getting Qobuz Cloud Configuration May 05 01:48:58 primo volumio[1101]: info: Asking plugin for UI Config May 05 01:48:58 primo volumio[1101]: info: CoreCommandRouter::getUIConfigOnPlugin May 05 01:48:58 primo volumio[1101]: info: Getting Spotify Cloud Configuration May 05 01:48:58 primo volumio[1101]: info: Asking plugin for UI Config May 05 01:48:58 primo volumio[1101]: info: CoreCommandRouter::getUIConfigOnPlugin May 05 01:48:58 primo volumio[1101]: info: Saving Spotify Acccount May 05 01:48:58 primo volumio[1101]: info: Got Tidal Cloud Configuration May 05 01:48:58 primo volumio[1101]: info: Got it May 05 01:48:58 primo volumio[1101]: info: Got it May 05 01:48:58 primo volumio[1101]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 05 01:48:58 primo volumio[1101]: info: CoreCommandRouter::getUIConfigOnPlugin May 05 01:48:58 primo volumio[1101]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined May 05 01:48:58 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 05 01:48:58 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 05 01:48:58 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 05 01:48:58 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 05 01:48:58 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 05 01:48:58 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 05 01:48:58 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 05 01:48:58 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 05 01:48:58 primo volumio[1101]: info: CoreCommandRouter::volumioGetBrowseSources May 05 01:48:58 primo volumio[1101]: info: CoreCommandRouter::volumioGetBrowseSources May 05 01:48:58 primo volumio[1101]: info: CoreCommandRouter::volumioGetBrowseSources May 05 01:48:58 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 05 01:48:58 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 05 01:48:59 primo ifplugd(eth0)[782]: Executing '/etc/ifplugd/ifplugd.action eth0 down'. May 05 01:48:59 primo dhcpcd[2002]: sending commands to master dhcpcd process May 05 01:48:59 primo ifplugd(eth0)[782]: client: sending commands to master dhcpcd process May 05 01:48:59 primo dhcpcd[1143]: control command: /sbin/dhcpcd -k eth0 May 05 01:48:59 primo dhcpcd[1143]: eth0: removing interface May 05 01:48:59 primo ifplugd(eth0)[782]: Program executed successfully. May 05 01:48:59 primo sudo[1915]: pam_unix(sudo:session): session closed for user root May 05 01:48:59 primo kernel: CIFS VFS: Error connecting to socket. Aborting operation. May 05 01:48:59 primo kernel: CIFS VFS: cifs_mount failed w/return code = -115 May 05 01:48:59 primo volumio[1101]: info: Cannot mount NAS music at system boot, trial number 2 ,retrying in 5 seconds May 05 01:49:01 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 05 01:49:02 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 05 01:49:03 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 05 01:49:03 primo volumio[1101]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 May 05 01:49:03 primo volumio[1101]: info: CoreCommandRouter::volumioGetState May 05 01:49:03 primo volumio[1101]: info: CorePlayQueue::getTrack 0 May 05 01:49:04 primo sudo[2050]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.10.10/音乐 /mnt/NAS/music May 05 01:49:04 primo sudo[2050]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 01:49:05 primo systemd[1]: systemd-timedated.service: Succeeded. May 05 01:49:05 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare May 05 01:49:06 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 05 01:49:06 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 05 01:49:07 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 05 01:49:07 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 01:49:07 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 05 01:49:08 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:49:08 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:49:08 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 05 01:49:08 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 05 01:49:08 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 05 01:49:08 primo volumio[1101]: info: CoreCommandRouter::volumioGetBrowseSources May 05 01:49:08 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 05 01:49:09 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb May 05 01:49:09 primo volumio[1101]: verbose: ControllerMpd::sendMpdCommand update May 05 01:49:09 primo volumio[1101]: verbose: ControllerMpd::sendMpdCommand status May 05 01:49:09 primo volumio[1101]: info: May 05 01:49:09 primo volumio[1101]: ---------------------------- MPD announces state update: update May 05 01:49:09 primo volumio[1101]: info: sendMpdCommand update took 6 milliseconds May 05 01:49:09 primo volumio[1101]: info: ControllerMpd::getState May 05 01:49:09 primo volumio[1101]: verbose: ControllerMpd::sendMpdCommand status May 05 01:49:09 primo volumio[1101]: verbose: ControllerMpd::sendMpdCommand status May 05 01:49:09 primo volumio[1101]: info: May 05 01:49:09 primo volumio[1101]: ---------------------------- MPD announces state update: update May 05 01:49:09 primo volumio[1101]: info: ControllerMpd::getState May 05 01:49:09 primo volumio[1101]: verbose: ControllerMpd::sendMpdCommand status May 05 01:49:09 primo volumio[1101]: verbose: ControllerMpd::sendMpdCommand status May 05 01:49:09 primo volumio[1101]: info: May 05 01:49:09 primo volumio[1101]: ---------------------------- MPD announces state update: update May 05 01:49:09 primo volumio[1101]: info: ControllerMpd::getState May 05 01:49:09 primo volumio[1101]: verbose: ControllerMpd::sendMpdCommand status May 05 01:49:09 primo volumio[1101]: info: sendMpdCommand status took 11 milliseconds May 05 01:49:09 primo volumio[1101]: verbose: ControllerMpd::parseState May 05 01:49:09 primo volumio[1101]: info: Command Router : Notfying DB Updatetrue May 05 01:49:09 primo volumio[1101]: info: MPD Database updated - AlbumList cache refreshed May 05 01:49:09 primo volumio[1101]: info: May 05 01:49:09 primo volumio[1101]: ---------------------------- MPD announces state update: database May 05 01:49:09 primo volumio[1101]: verbose: ControllerMpd::sendMpdCommand status May 05 01:49:09 primo volumio[1101]: info: May 05 01:49:09 primo volumio[1101]: ---------------------------- MPD announces state update: update May 05 01:49:09 primo volumio[1101]: info: ControllerMpd::getState May 05 01:49:09 primo volumio[1101]: verbose: ControllerMpd::sendMpdCommand status May 05 01:49:09 primo volumio[1101]: info: ControllerMpd::getState May 05 01:49:09 primo volumio[1101]: verbose: ControllerMpd::sendMpdCommand status May 05 01:49:09 primo volumio[1101]: info: sendMpdCommand status took 158 milliseconds May 05 01:49:09 primo volumio[1101]: info: sendMpdCommand status took 157 milliseconds May 05 01:49:09 primo volumio[1101]: info: sendMpdCommand status took 157 milliseconds May 05 01:49:09 primo volumio[1101]: info: sendMpdCommand status took 156 milliseconds May 05 01:49:09 primo volumio[1101]: info: sendMpdCommand status took 155 milliseconds May 05 01:49:09 primo volumio[1101]: verbose: ControllerMpd::parseState May 05 01:49:09 primo volumio[1101]: verbose: ControllerMpd::parseState May 05 01:49:09 primo volumio[1101]: info: Command Router : Notfying DB Updatefalse May 05 01:49:09 primo volumio[1101]: info: CoreCommandRouter::Close All Modals sent May 05 01:49:09 primo volumio[1101]: verbose: ControllerMpd::parseState May 05 01:49:09 primo volumio[1101]: verbose: ControllerMpd::parseState May 05 01:49:09 primo volumio[1101]: info: Command Router : Notfying DB Updatefalse May 05 01:49:09 primo volumio[1101]: info: CoreCommandRouter::Close All Modals sent May 05 01:49:09 primo volumio[1101]: verbose: ControllerMpd::parseState May 05 01:49:09 primo volumio[1101]: info: ControllerMpd::pushState May 05 01:49:09 primo volumio[1101]: info: CoreCommandRouter::servicePushState May 05 01:49:09 primo volumio[1101]: info: CoreStateMachine::pushState May 05 01:49:09 primo volumio[1101]: info: CorePlayQueue::getTrack 0 May 05 01:49:09 primo volumio[1101]: info: CoreCommandRouter::volumioPushState May 05 01:49:09 primo volumio[1101]: info: CorePlayQueue::getTrack 0 May 05 01:49:09 primo volumio[1101]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 05 01:49:09 primo volumio[1101]: verbose: CURRENT POSITION 0 May 05 01:49:09 primo volumio[1101]: info: CoreStateMachine::syncState stateService stop May 05 01:49:09 primo volumio[1101]: info: CoreStateMachine::syncState currentStatus stop May 05 01:49:09 primo volumio[1101]: info: CoreStateMachine::pushState May 05 01:49:09 primo volumio[1101]: info: CorePlayQueue::getTrack 0 May 05 01:49:09 primo volumio[1101]: info: CoreCommandRouter::volumioPushState May 05 01:49:09 primo volumio[1101]: info: No code May 05 01:49:09 primo volumio[1101]: info: CoreStateMachine::pushState May 05 01:49:09 primo volumio[1101]: info: CorePlayQueue::getTrack 0 May 05 01:49:09 primo volumio[1101]: info: CoreCommandRouter::volumioPushState May 05 01:49:09 primo volumio[1101]: info: ControllerMpd::pushState May 05 01:49:09 primo volumio[1101]: info: CoreCommandRouter::servicePushState May 05 01:49:09 primo volumio[1101]: info: CoreStateMachine::pushState May 05 01:49:09 primo volumio[1101]: info: CorePlayQueue::getTrack 0 May 05 01:49:09 primo volumio[1101]: info: CoreCommandRouter::volumioPushState May 05 01:49:09 primo volumio[1101]: info: CorePlayQueue::getTrack 0 May 05 01:49:09 primo volumio[1101]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 05 01:49:09 primo volumio[1101]: verbose: CURRENT POSITION 0 May 05 01:49:09 primo volumio[1101]: info: CoreStateMachine::syncState stateService stop May 05 01:49:09 primo volumio[1101]: info: CoreStateMachine::syncState currentStatus stop May 05 01:49:09 primo volumio[1101]: info: CoreStateMachine::pushState May 05 01:49:09 primo volumio[1101]: info: CorePlayQueue::getTrack 0 May 05 01:49:09 primo volumio[1101]: info: CoreCommandRouter::volumioPushState May 05 01:49:09 primo volumio[1101]: info: No code May 05 01:49:09 primo volumio[1101]: info: CoreStateMachine::pushState May 05 01:49:09 primo volumio[1101]: info: CorePlayQueue::getTrack 0 May 05 01:49:09 primo volumio[1101]: info: CoreCommandRouter::volumioPushState May 05 01:49:09 primo volumio[1101]: info: ControllerMpd::pushState May 05 01:49:09 primo volumio[1101]: info: CoreCommandRouter::servicePushState May 05 01:49:09 primo volumio[1101]: info: CoreStateMachine::pushState May 05 01:49:09 primo volumio[1101]: info: CorePlayQueue::getTrack 0 May 05 01:49:09 primo volumio[1101]: info: CoreCommandRouter::volumioPushState May 05 01:49:09 primo volumio[1101]: info: CorePlayQueue::getTrack 0 May 05 01:49:09 primo volumio[1101]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 05 01:49:09 primo volumio[1101]: verbose: CURRENT POSITION 0 May 05 01:49:09 primo volumio[1101]: info: CoreStateMachine::syncState stateService stop May 05 01:49:09 primo volumio[1101]: info: CoreStateMachine::syncState currentStatus stop May 05 01:49:09 primo volumio[1101]: info: CoreStateMachine::pushState May 05 01:49:09 primo volumio[1101]: info: CorePlayQueue::getTrack 0 May 05 01:49:09 primo volumio[1101]: info: CoreCommandRouter::volumioPushState May 05 01:49:09 primo volumio[1101]: info: No code May 05 01:49:09 primo volumio[1101]: info: CoreStateMachine::pushState May 05 01:49:09 primo volumio[1101]: info: CorePlayQueue::getTrack 0 May 05 01:49:09 primo volumio[1101]: info: CoreCommandRouter::volumioPushState May 05 01:49:09 primo volumio[1101]: info: ------------------------------ 449ms May 05 01:49:09 primo volumio[1101]: info: ------------------------------ 478ms May 05 01:49:09 primo volumio[1101]: info: ------------------------------ 478ms May 05 01:49:09 primo volumio[1101]: info: MPD Database updated - AlbumList cache refreshed May 05 01:49:09 primo volumio[1101]: info: May 05 01:49:09 primo volumio[1101]: ---------------------------- MPD announces state update: database May 05 01:49:09 primo volumio[1101]: verbose: ControllerMpd::sendMpdCommand status May 05 01:49:09 primo volumio[1101]: info: May 05 01:49:09 primo volumio[1101]: ---------------------------- MPD announces state update: update May 05 01:49:09 primo volumio[1101]: info: ControllerMpd::getState May 05 01:49:09 primo volumio[1101]: verbose: ControllerMpd::sendMpdCommand status May 05 01:49:09 primo volumio[1101]: info: ControllerMpd::getState May 05 01:49:09 primo volumio[1101]: verbose: ControllerMpd::sendMpdCommand status May 05 01:49:09 primo volumio[1101]: info: MPD Database updated - AlbumList cache refreshed May 05 01:49:09 primo volumio[1101]: info: May 05 01:49:09 primo volumio[1101]: ---------------------------- MPD announces state update: database May 05 01:49:09 primo volumio[1101]: verbose: ControllerMpd::sendMpdCommand status May 05 01:49:09 primo volumio[1101]: info: May 05 01:49:09 primo volumio[1101]: ---------------------------- MPD announces state update: update May 05 01:49:09 primo volumio[1101]: info: ControllerMpd::getState May 05 01:49:09 primo volumio[1101]: verbose: ControllerMpd::sendMpdCommand status May 05 01:49:09 primo volumio[1101]: info: ControllerMpd::getState May 05 01:49:09 primo volumio[1101]: verbose: ControllerMpd::sendMpdCommand status May 05 01:49:09 primo volumio[1101]: error: MyVolumio Plugin failed to authenticate in a timely fashion May 05 01:49:09 primo volumio[1101]: info: Completed starting MyVolumio Plugin May 05 01:49:09 primo volumio[1101]: [Metrics] CommandRouter: 36s 880.91ms May 05 01:49:09 primo volumio[1101]: info: CoreCommandRouter::volumiosetStartupVolume May 05 01:49:09 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 05 01:49:09 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 05 01:49:09 primo volumio[1101]: info: CoreCommandRouter::Close All Modals sent May 05 01:49:09 primo volumio[1101]: info: CoreCommandRouter::Close All Modals sent May 05 01:49:09 primo volumio[1101]: info: sendMpdCommand status took 374 milliseconds May 05 01:49:09 primo volumio[1101]: info: sendMpdCommand status took 373 milliseconds May 05 01:49:09 primo volumio[1101]: info: sendMpdCommand status took 372 milliseconds May 05 01:49:09 primo volumio[1101]: info: sendMpdCommand status took 45 milliseconds May 05 01:49:09 primo volumio[1101]: info: sendMpdCommand status took 43 milliseconds May 05 01:49:09 primo volumio[1101]: info: sendMpdCommand status took 44 milliseconds May 05 01:49:09 primo volumio[1101]: info: sendMpdCommand status took 42 milliseconds May 05 01:49:09 primo volumio[1101]: info: sendMpdCommand status took 42 milliseconds May 05 01:49:09 primo volumio[1101]: info: sendMpdCommand status took 40 milliseconds May 05 01:49:09 primo volumio[1101]: verbose: ControllerMpd::parseState May 05 01:49:09 primo volumio[1101]: info: Command Router : Notfying DB Updatefalse May 05 01:49:09 primo volumio[1101]: info: CoreCommandRouter::Close All Modals sent May 05 01:49:09 primo volumio[1101]: verbose: ControllerMpd::parseState May 05 01:49:09 primo volumio[1101]: verbose: ControllerMpd::parseState May 05 01:49:09 primo volumio[1101]: verbose: ControllerMpd::parseState May 05 01:49:09 primo volumio[1101]: info: Command Router : Notfying DB Updatefalse May 05 01:49:09 primo volumio[1101]: info: CoreCommandRouter::Close All Modals sent May 05 01:49:09 primo volumio[1101]: verbose: ControllerMpd::parseState May 05 01:49:09 primo volumio[1101]: verbose: ControllerMpd::parseState May 05 01:49:09 primo volumio[1101]: verbose: ControllerMpd::parseState May 05 01:49:09 primo volumio[1101]: info: Command Router : Notfying DB Updatefalse May 05 01:49:09 primo volumio[1101]: info: CoreCommandRouter::Close All Modals sent May 05 01:49:09 primo volumio[1101]: verbose: ControllerMpd::parseState May 05 01:49:09 primo volumio[1101]: verbose: ControllerMpd::parseState May 05 01:49:09 primo volumio[1101]: info: ControllerMpd::pushState May 05 01:49:09 primo volumio[1101]: info: CoreCommandRouter::servicePushState May 05 01:49:09 primo volumio[1101]: info: CoreStateMachine::pushState May 05 01:49:09 primo volumio[1101]: info: CorePlayQueue::getTrack 0 May 05 01:49:09 primo volumio[1101]: info: CoreCommandRouter::volumioPushState May 05 01:49:09 primo volumio[1101]: info: CorePlayQueue::getTrack 0 May 05 01:49:09 primo volumio[1101]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 05 01:49:09 primo volumio[1101]: verbose: CURRENT POSITION 0 May 05 01:49:09 primo volumio[1101]: info: CoreStateMachine::syncState stateService stop May 05 01:49:09 primo volumio[1101]: info: CoreStateMachine::syncState currentStatus stop May 05 01:49:09 primo volumio[1101]: info: CoreStateMachine::pushState May 05 01:49:09 primo volumio[1101]: info: CorePlayQueue::getTrack 0 May 05 01:49:09 primo volumio[1101]: info: CoreCommandRouter::volumioPushState May 05 01:49:09 primo volumio[1101]: info: No code May 05 01:49:09 primo volumio[1101]: info: CoreStateMachine::pushState May 05 01:49:09 primo volumio[1101]: info: CorePlayQueue::getTrack 0 May 05 01:49:09 primo volumio[1101]: info: CoreCommandRouter::volumioPushState May 05 01:49:09 primo volumio[1101]: info: ControllerMpd::pushState May 05 01:49:09 primo volumio[1101]: info: CoreCommandRouter::servicePushState May 05 01:49:09 primo volumio[1101]: info: CoreStateMachine::pushState May 05 01:49:09 primo volumio[1101]: info: CorePlayQueue::getTrack 0 May 05 01:49:09 primo volumio[1101]: info: CoreCommandRouter::volumioPushState May 05 01:49:09 primo volumio[1101]: info: CorePlayQueue::getTrack 0 May 05 01:49:09 primo volumio[1101]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 05 01:49:09 primo volumio[1101]: verbose: CURRENT POSITION 0 May 05 01:49:09 primo volumio[1101]: info: CoreStateMachine::syncState stateService stop May 05 01:49:09 primo volumio[1101]: info: CoreStateMachine::syncState currentStatus stop May 05 01:49:09 primo volumio[1101]: info: CoreStateMachine::pushState May 05 01:49:09 primo volumio[1101]: info: CorePlayQueue::getTrack 0 May 05 01:49:09 primo volumio[1101]: info: CoreCommandRouter::volumioPushState May 05 01:49:09 primo volumio[1101]: info: No code May 05 01:49:09 primo volumio[1101]: info: CoreStateMachine::pushState May 05 01:49:09 primo volumio[1101]: info: CorePlayQueue::getTrack 0 May 05 01:49:09 primo volumio[1101]: info: CoreCommandRouter::volumioPushState May 05 01:49:09 primo volumio[1101]: info: ControllerMpd::pushState May 05 01:49:09 primo volumio[1101]: info: CoreCommandRouter::servicePushState May 05 01:49:09 primo volumio[1101]: info: CoreStateMachine::pushState May 05 01:49:09 primo volumio[1101]: info: CorePlayQueue::getTrack 0 May 05 01:49:09 primo volumio[1101]: info: CoreCommandRouter::volumioPushState May 05 01:49:09 primo volumio[1101]: info: CorePlayQueue::getTrack 0 May 05 01:49:09 primo volumio[1101]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 05 01:49:09 primo volumio[1101]: verbose: CURRENT POSITION 0 May 05 01:49:09 primo volumio[1101]: info: CoreStateMachine::syncState stateService stop May 05 01:49:09 primo volumio[1101]: info: CoreStateMachine::syncState currentStatus stop May 05 01:49:09 primo volumio[1101]: info: CoreStateMachine::pushState May 05 01:49:09 primo volumio[1101]: info: CorePlayQueue::getTrack 0 May 05 01:49:09 primo volumio[1101]: info: CoreCommandRouter::volumioPushState May 05 01:49:09 primo volumio[1101]: info: No code May 05 01:49:09 primo volumio[1101]: info: CoreStateMachine::pushState May 05 01:49:09 primo volumio[1101]: info: CorePlayQueue::getTrack 0 May 05 01:49:09 primo volumio[1101]: info: CoreCommandRouter::volumioPushState May 05 01:49:09 primo volumio[1101]: info: ControllerMpd::pushState May 05 01:49:09 primo volumio[1101]: info: CoreCommandRouter::servicePushState May 05 01:49:09 primo volumio[1101]: info: CoreStateMachine::pushState May 05 01:49:09 primo volumio[1101]: info: CorePlayQueue::getTrack 0 May 05 01:49:09 primo volumio[1101]: info: CoreCommandRouter::volumioPushState May 05 01:49:09 primo volumio[1101]: info: CorePlayQueue::getTrack 0 May 05 01:49:09 primo volumio[1101]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 05 01:49:09 primo volumio[1101]: verbose: CURRENT POSITION 0 May 05 01:49:09 primo volumio[1101]: info: CoreStateMachine::syncState stateService stop May 05 01:49:09 primo volumio[1101]: info: CoreStateMachine::syncState currentStatus stop May 05 01:49:09 primo volumio[1101]: info: CoreStateMachine::pushState May 05 01:49:09 primo volumio[1101]: info: CorePlayQueue::getTrack 0 May 05 01:49:09 primo volumio[1101]: info: CoreCommandRouter::volumioPushState May 05 01:49:09 primo volumio[1101]: info: No code May 05 01:49:09 primo volumio[1101]: info: CoreStateMachine::pushState May 05 01:49:09 primo volumio[1101]: info: CorePlayQueue::getTrack 0 May 05 01:49:09 primo volumio[1101]: info: CoreCommandRouter::volumioPushState May 05 01:49:09 primo volumio[1101]: info: ControllerMpd::pushState May 05 01:49:09 primo volumio[1101]: info: CoreCommandRouter::servicePushState May 05 01:49:09 primo volumio[1101]: info: CoreStateMachine::pushState May 05 01:49:09 primo volumio[1101]: info: CorePlayQueue::getTrack 0 May 05 01:49:09 primo volumio[1101]: info: CoreCommandRouter::volumioPushState May 05 01:49:09 primo volumio[1101]: info: CorePlayQueue::getTrack 0 May 05 01:49:09 primo volumio[1101]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 05 01:49:09 primo volumio[1101]: verbose: CURRENT POSITION 0 May 05 01:49:09 primo volumio[1101]: info: CoreStateMachine::syncState stateService stop May 05 01:49:09 primo volumio[1101]: info: CoreStateMachine::syncState currentStatus stop May 05 01:49:09 primo volumio[1101]: info: CoreStateMachine::pushState May 05 01:49:09 primo volumio[1101]: info: CorePlayQueue::getTrack 0 May 05 01:49:09 primo volumio[1101]: info: CoreCommandRouter::volumioPushState May 05 01:49:09 primo volumio[1101]: info: No code May 05 01:49:09 primo volumio[1101]: info: CoreStateMachine::pushState May 05 01:49:09 primo volumio[1101]: info: CorePlayQueue::getTrack 0 May 05 01:49:09 primo volumio[1101]: info: CoreCommandRouter::volumioPushState May 05 01:49:09 primo volumio[1101]: info: ControllerMpd::pushState May 05 01:49:09 primo volumio[1101]: info: CoreCommandRouter::servicePushState May 05 01:49:09 primo volumio[1101]: info: CoreStateMachine::pushState May 05 01:49:09 primo volumio[1101]: info: CorePlayQueue::getTrack 0 May 05 01:49:09 primo volumio[1101]: info: CoreCommandRouter::volumioPushState May 05 01:49:09 primo volumio[1101]: info: CorePlayQueue::getTrack 0 May 05 01:49:09 primo volumio[1101]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 05 01:49:09 primo volumio[1101]: verbose: CURRENT POSITION 0 May 05 01:49:09 primo volumio[1101]: info: CoreStateMachine::syncState stateService stop May 05 01:49:09 primo volumio[1101]: info: CoreStateMachine::syncState currentStatus stop May 05 01:49:09 primo volumio[1101]: info: CoreStateMachine::pushState May 05 01:49:09 primo volumio[1101]: info: CorePlayQueue::getTrack 0 May 05 01:49:09 primo volumio[1101]: info: CoreCommandRouter::volumioPushState May 05 01:49:09 primo volumio[1101]: info: No code May 05 01:49:09 primo volumio[1101]: info: CoreStateMachine::pushState May 05 01:49:09 primo volumio[1101]: info: CorePlayQueue::getTrack 0 May 05 01:49:09 primo volumio[1101]: info: CoreCommandRouter::volumioPushState May 05 01:49:09 primo volumio[1101]: info: ------------------------------ 561ms May 05 01:49:09 primo volumio[1101]: info: ------------------------------ 562ms May 05 01:49:09 primo volumio[1101]: info: ------------------------------ 233ms May 05 01:49:09 primo volumio[1101]: info: ------------------------------ 234ms May 05 01:49:09 primo volumio[1101]: info: ------------------------------ 231ms May 05 01:49:09 primo volumio[1101]: info: ------------------------------ 232ms May 05 01:49:10 primo volumio[1101]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav May 05 01:49:10 primo volumio[1101]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card May 05 01:49:10 primo volumio[1101]: aplay: main:828: audio open error: No such device May 05 01:49:10 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 05 01:49:10 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 05 01:49:10 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 05 01:49:10 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: raat , onStop May 05 01:49:10 primo volumio[1101]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 05 01:49:14 primo volumio[1101]: info: CoreCommandRouter::volumioGetState May 05 01:49:14 primo volumio[1101]: info: CorePlayQueue::getTrack 0 May 05 01:49:14 primo sudo[2050]: pam_unix(sudo:session): session closed for user root May 05 01:49:14 primo kernel: CIFS VFS: Error connecting to socket. Aborting operation. May 05 01:49:14 primo kernel: CIFS VFS: cifs_mount failed w/return code = -115 May 05 01:49:14 primo volumio[1101]: info: Cannot mount NAS music at system boot, trial number 3 ,retrying in 5 seconds May 05 01:49:16 primo volumio[1101]: info: CoreCommandRouter::volumioGetQueue May 05 01:49:16 primo volumio[1101]: info: CoreStateMachine::getQueue May 05 01:49:16 primo volumio[1101]: info: CorePlayQueue::getQueue May 05 01:49:16 primo volumio[1101]: info: BOOT COMPLETED May 05 01:49:19 primo volumio[1101]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. May 05 01:49:19 primo volumio[1101]: info: Retrying Login Due to Network Error May 05 01:49:19 primo volumio[1101]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 05 01:49:19 primo volumio[1101]: TypeError: Cannot read property 'replace' of undefined May 05 01:49:19 primo volumio[1101]: at ControllerNetworkfs.mountShare (/volumio/app/plugins/system_controller/networkfs/index.js:202:28) May 05 01:49:19 primo volumio[1101]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/networkfs/index.js:255:20) May 05 01:49:19 primo volumio[1101]: at listOnTimeout (internal/timers.js:557:17) May 05 01:49:19 primo volumio[1101]: at processTimers (internal/timers.js:500:7) May 05 01:49:19 primo volumio[1101]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 05 01:49:20 primo sudo[2151]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-05 01:48 May 05 01:49:20 primo sudo[2151]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 07:14:40 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="a9bbde8819c9c7e405795a261e2a2f93"