-- Logs begin at Thu 2025-12-18 16:54:14 CST, end at Thu 2025-12-18 16:56:24 CST. -- Dec 18 16:55:02 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Dec 18 16:55:02 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Dec 18 16:55:02 volumio kernel: CIFS: Attempting to mount //192.168.3.40/3T Dec 18 16:55:02 volumio sudo[1041]: pam_unix(sudo:session): session closed for user root Dec 18 16:55:03 volumio kernel: CIFS: Attempting to mount //192.168.3.40/音乐 Dec 18 16:55:03 volumio sudo[1037]: pam_unix(sudo:session): session closed for user root Dec 18 16:55:03 volumio kernel: CIFS: Attempting to mount //192.168.3.21/usb Dec 18 16:55:03 volumio sudo[1035]: pam_unix(sudo:session): session closed for user root Dec 18 16:55:07 volumio sudo[1238]: volumio : unable to resolve host volumio Dec 18 16:55:07 volumio sudo[1238]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Dec 18 16:55:07 volumio sudo[1238]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 18 16:55:07 volumio sudo[1238]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 16:55:07 volumio sudo[1238]: pam_unix(sudo:session): session closed for user root Dec 18 16:55:07 volumio sudo[1245]: volumio : unable to resolve host volumio Dec 18 16:55:07 volumio sudo[1245]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Dec 18 16:55:07 volumio sudo[1245]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 18 16:55:07 volumio sudo[1245]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 16:55:07 volumio sudo[1247]: volumio : unable to resolve host volumio Dec 18 16:55:07 volumio sudo[1247]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Dec 18 16:55:07 volumio sudo[1245]: pam_unix(sudo:session): session closed for user root Dec 18 16:55:07 volumio sudo[1247]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 18 16:55:07 volumio sudo[1247]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 16:55:07 volumio systemd[1]: Listening on mpd.socket. Dec 18 16:55:07 volumio systemd[1]: Starting Music Player Daemon... Dec 18 16:55:08 volumio sudo[1259]: volumio : unable to resolve host volumio Dec 18 16:55:08 volumio sudo[1259]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Dec 18 16:55:08 volumio sudo[1259]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd-dummy index=7 pcm_substreams=1 fake_buffer=0 Dec 18 16:55:08 volumio sudo[1259]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 16:55:08 volumio sudo[1259]: pam_unix(sudo:session): session closed for user root Dec 18 16:55:08 volumio volumio[779]: sudo: unable to resolve host volumio: System error Dec 18 16:55:08 volumio volumio[779]: info: snd-dummy loaded Dec 18 16:55:08 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 16:55:08 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 16:55:08 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 16:55:08 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startShairportSync Dec 18 16:55:08 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 16:55:08 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 16:55:08 volumio volumio[779]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 18 16:55:08 volumio volumio[779]: info: Volumio Calling Home Dec 18 16:55:08 volumio volumio[779]: info: AutoStart - Plugin is starting Dec 18 16:55:08 volumio volumio[779]: info: CoreCommandRouter::volumioGetQueue Dec 18 16:55:08 volumio volumio[779]: info: CoreStateMachine::getQueue Dec 18 16:55:08 volumio volumio[779]: info: CorePlayQueue::getQueue Dec 18 16:55:08 volumio volumio[779]: info: AutoStart - start playing Dec 18 16:55:08 volumio volumio[779]: info: AutoStart - start playing with no specific position Dec 18 16:55:08 volumio volumio[779]: info: CoreCommandRouter::volumioPlay Dec 18 16:55:08 volumio volumio[779]: info: CoreStateMachine::play index 0 Dec 18 16:55:08 volumio volumio[779]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 16:55:08 volumio volumio[779]: info: CoreStateMachine::stop Dec 18 16:55:08 volumio volumio[779]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 16:55:08 volumio volumio[779]: info: CoreStateMachine::play index undefined Dec 18 16:55:08 volumio volumio[779]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 16:55:08 volumio volumio[779]: info: CorePlayQueue::getTrack 0 Dec 18 16:55:08 volumio volumio[779]: info: CoreStateMachine::startPlaybackTimer Dec 18 16:55:08 volumio volumio[779]: info: CorePlayQueue::getTrack 0 Dec 18 16:55:08 volumio volumio[779]: verbose: ControllerMpd::clearAddPlayTracks USB/音乐移动盘/酷我/男歌手/GALA-Young For You.flac Dec 18 16:55:08 volumio volumio[779]: verbose: ControllerMpd::sendMpdCommand stop Dec 18 16:55:08 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 18 16:55:08 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 18 16:55:08 volumio volumio[779]: info: Discovery: Getting this device information Dec 18 16:55:08 volumio volumio[779]: info: CoreCommandRouter::volumioGetState Dec 18 16:55:08 volumio volumio[779]: info: CorePlayQueue::getTrack 0 Dec 18 16:55:08 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 18 16:55:08 volumio volumio[779]: info: MPD Permissions set Dec 18 16:55:08 volumio volumio[779]: info: MPD Permissions set Dec 18 16:55:08 volumio volumio[779]: info: Cannot mount NAS usb at system boot, trial number 1 ,retrying in 5 seconds Dec 18 16:55:08 volumio volumio[779]: verbose: New Socket.io Connection to 192.168.3.21:3000 from 192.168.3.6 UA: Dart/3.9 (dart:io) Total Clients: 4 Dec 18 16:55:08 volumio volumio[779]: info: CoreCommandRouter::volumioGetState Dec 18 16:55:08 volumio volumio[779]: info: CorePlayQueue::getTrack 0 Dec 18 16:55:08 volumio volumio[779]: info: CoreCommandRouter::volumioGetState Dec 18 16:55:08 volumio volumio[779]: info: CorePlayQueue::getTrack 0 Dec 18 16:55:08 volumio volumio[779]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 5 Dec 18 16:55:09 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 16:55:09 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 16:55:09 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 16:55:09 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 18 16:55:09 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 16:55:09 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 16:55:09 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 16:55:09 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 16:55:09 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 16:55:09 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 16:55:09 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 16:55:09 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 18 16:55:09 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 16:55:09 volumio volumio[779]: info: Volumio called home Dec 18 16:55:09 volumio volumio[779]: verbose: New Socket.io Connection to 192.168.3.21:3000 from 192.168.3.6 UA: Dart/3.9 (dart:io) Total Clients: 6 Dec 18 16:55:09 volumio volumio[779]: info: Starting Shairport Sync Dec 18 16:55:09 volumio volumio[779]: info: Starting Shairport Sync Dec 18 16:55:09 volumio volumio[779]: info: Starting Shairport Sync Dec 18 16:55:09 volumio volumio[779]: info: Starting Shairport Sync Dec 18 16:55:10 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 18 16:55:10 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 18 16:55:10 volumio volumio[779]: info: Discovery: Getting this device information Dec 18 16:55:10 volumio volumio[779]: info: CoreCommandRouter::volumioGetState Dec 18 16:55:10 volumio volumio[779]: info: CorePlayQueue::getTrack 0 Dec 18 16:55:10 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 18 16:55:10 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 18 16:55:10 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 18 16:55:10 volumio volumio[779]: info: Discovery: Getting this device information Dec 18 16:55:10 volumio volumio[779]: info: CoreCommandRouter::volumioGetState Dec 18 16:55:10 volumio volumio[779]: info: CorePlayQueue::getTrack 0 Dec 18 16:55:10 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 18 16:55:10 volumio volumio[779]: info: CoreCommandRouter::volumioGetState Dec 18 16:55:10 volumio volumio[779]: info: CorePlayQueue::getTrack 0 Dec 18 16:55:10 volumio volumio[779]: verbose: New Socket.io Connection to 192.168.3.21:3000 from 192.168.3.6 UA: Dart/3.9 (dart:io) Total Clients: 7 Dec 18 16:55:10 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 18 16:55:10 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 18 16:55:10 volumio volumio[779]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 7 Dec 18 16:55:11 volumio mpd[1277]: Dec 18 16:55 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 18 16:55:11 volumio systemd[1]: Started Music Player Daemon. Dec 18 16:55:11 volumio sudo[1247]: pam_unix(sudo:session): session closed for user root Dec 18 16:55:11 volumio volumio[779]: error: updateQueue error: null Dec 18 16:55:12 volumio volumio[779]: info: MPD running with PID1277 Dec 18 16:55:12 volumio volumio[779]: ,establishing connection Dec 18 16:55:12 volumio volumio[779]: error: updateQueue error: null Dec 18 16:55:12 volumio volumio[779]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Total Clients: 8 Dec 18 16:55:12 volumio volumio-remote-updater[356]: [2025-12-18 16:55:12] [connect] Successful connection Dec 18 16:55:12 volumio volumio-remote-updater[356]: [2025-12-18 16:55:12] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1766048112 101 Dec 18 16:55:12 volumio volumio[779]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 9 Dec 18 16:55:12 volumio sudo[1240]: volumio : unable to resolve host volumio Dec 18 16:55:12 volumio sudo[1240]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Dec 18 16:55:12 volumio sudo[1240]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 18 16:55:12 volumio sudo[1240]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 16:55:12 volumio systemd[1]: Stopping Music Player Daemon... Dec 18 16:55:12 volumio systemd[1]: mpd.service: Succeeded. Dec 18 16:55:12 volumio systemd[1]: Stopped Music Player Daemon. Dec 18 16:55:12 volumio systemd[1]: Starting Music Player Daemon... Dec 18 16:55:14 volumio sudo[1315]: volumio : unable to resolve host volumio Dec 18 16:55:14 volumio sudo[1315]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Dec 18 16:55:14 volumio sudo[1315]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount --bind /tmp/mpd.conf.tmpl /volumio/app/plugins/music_service/mpd/mpd.conf.tmpl Dec 18 16:55:14 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Dec 18 16:55:14 volumio sudo[1315]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 16:55:14 volumio sudo[1315]: pam_unix(sudo:session): session closed for user root Dec 18 16:55:14 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: mpd , createMPDFile Dec 18 16:55:14 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 16:55:14 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 16:55:14 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 16:55:14 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 16:55:14 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 18 16:55:14 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 16:55:14 volumio mpd[1526]: Dec 18 16:55 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 18 16:55:14 volumio volumio[779]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Total Clients: 10 Dec 18 16:55:14 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Dec 18 16:55:15 volumio systemd[1]: Started Music Player Daemon. Dec 18 16:55:15 volumio sudo[1240]: pam_unix(sudo:session): session closed for user root Dec 18 16:55:15 volumio volumio[779]: error: updateQueue error: null Dec 18 16:55:16 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 18 16:55:17 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 18 16:55:17 volumio volumio[779]: info: CoreCommandRouter::volumioGetVisibleSources Dec 18 16:55:17 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 18 16:55:17 volumio volumio[779]: info: CoreCommandRouter::volumioGetState Dec 18 16:55:17 volumio volumio[779]: info: CorePlayQueue::getTrack 0 Dec 18 16:55:17 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 18 16:55:17 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 18 16:55:17 volumio volumio[779]: info: Received Get System Info Dec 18 16:55:17 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 18 16:55:17 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 18 16:55:17 volumio volumio[779]: info: Discovery: Getting this device information Dec 18 16:55:17 volumio volumio[779]: info: CoreCommandRouter::volumioGetState Dec 18 16:55:17 volumio volumio[779]: info: CorePlayQueue::getTrack 0 Dec 18 16:55:17 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 18 16:55:17 volumio volumio[779]: info: CoreCommandRouter::volumioGetState Dec 18 16:55:17 volumio volumio[779]: info: CorePlayQueue::getTrack 0 Dec 18 16:55:17 volumio volumio[779]: info: Listing playlists Dec 18 16:55:17 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 18 16:55:17 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 18 16:55:17 volumio volumio[779]: info: CoreCommandRouter::volumioGetState Dec 18 16:55:17 volumio volumio[779]: info: CorePlayQueue::getTrack 0 Dec 18 16:55:17 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 18 16:55:17 volumio volumio[779]: info: Received Get System Info Dec 18 16:55:17 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 18 16:55:17 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 18 16:55:17 volumio volumio[779]: info: Discovery: Getting this device information Dec 18 16:55:17 volumio volumio[779]: info: CoreCommandRouter::volumioGetState Dec 18 16:55:17 volumio volumio[779]: info: CorePlayQueue::getTrack 0 Dec 18 16:55:17 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 18 16:55:18 volumio volumio[779]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/GALA/Young%20For%20You/64769040-1f5b-4f47-9a37-06fa2769519e.jpg' Dec 18 16:55:18 volumio sudo[1298]: volumio : unable to resolve host volumio Dec 18 16:55:18 volumio sudo[1298]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Dec 18 16:55:18 volumio sudo[1298]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount --bind /tmp/shairport-sync.conf.tmpl /volumio/app/plugins/music_service/airplay_emulation/shairport-sync.conf.tmpl Dec 18 16:55:18 volumio sudo[1298]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 16:55:18 volumio sudo[1298]: pam_unix(sudo:session): session closed for user root Dec 18 16:55:18 volumio sudo[1300]: volumio : unable to resolve host volumio Dec 18 16:55:18 volumio sudo[1300]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Dec 18 16:55:18 volumio sudo[1039]: pam_unix(sudo:session): session closed for user root Dec 18 16:55:18 volumio sudo[1300]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Dec 18 16:55:18 volumio sudo[1300]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 16:55:18 volumio systemd[1]: Started RoonBridge. Dec 18 16:55:18 volumio sudo[1300]: pam_unix(sudo:session): session closed for user root Dec 18 16:55:18 volumio sudo[1305]: volumio : unable to resolve host volumio Dec 18 16:55:18 volumio sudo[1305]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Dec 18 16:55:18 volumio sudo[1307]: volumio : unable to resolve host volumio Dec 18 16:55:18 volumio sudo[1307]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Dec 18 16:55:18 volumio sudo[1305]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 18 16:55:18 volumio sudo[1305]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 16:55:18 volumio sudo[1307]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 16:55:18 volumio sudo[1307]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 16:55:18 volumio sudo[1305]: pam_unix(sudo:session): session closed for user root Dec 18 16:55:18 volumio sudo[1307]: pam_unix(sudo:session): session closed for user root Dec 18 16:55:18 volumio sudo[1528]: volumio : unable to resolve host volumio Dec 18 16:55:18 volumio sudo[1528]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Dec 18 16:55:18 volumio sudo[1528]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=fido1312,password=Fido1312,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.40/usb /mnt/NAS/usb Dec 18 16:55:18 volumio sudo[1528]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 16:55:19 volumio kernel: CIFS: Attempting to mount //192.168.1.40/usb Dec 18 16:55:19 volumio sudo[1333]: volumio : unable to resolve host volumio Dec 18 16:55:19 volumio sudo[1333]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Dec 18 16:55:19 volumio sudo[1337]: volumio : unable to resolve host volumio Dec 18 16:55:19 volumio sudo[1337]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Dec 18 16:55:19 volumio sudo[1333]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 18 16:55:19 volumio sudo[1333]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 16:55:19 volumio sudo[1337]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 18 16:55:19 volumio sudo[1337]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 16:55:19 volumio sudo[1341]: volumio : unable to resolve host volumio Dec 18 16:55:19 volumio sudo[1341]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Dec 18 16:55:19 volumio sudo[1344]: volumio : unable to resolve host volumio Dec 18 16:55:19 volumio sudo[1344]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Dec 18 16:55:19 volumio sudo[1341]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 18 16:55:19 volumio sudo[1341]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 16:55:19 volumio sudo[1344]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 18 16:55:19 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 18 16:55:19 volumio systemd[1]: shairport-sync.service: Succeeded. Dec 18 16:55:19 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 18 16:55:20 volumio sudo[1344]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 16:55:20 volumio sudo[1348]: volumio : unable to resolve host volumio Dec 18 16:55:20 volumio sudo[1348]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Dec 18 16:55:20 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 18 16:55:20 volumio sudo[1348]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 18 16:55:20 volumio sudo[1333]: pam_unix(sudo:session): session closed for user root Dec 18 16:55:20 volumio sudo[1348]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 16:55:20 volumio sudo[1337]: pam_unix(sudo:session): session closed for user root Dec 18 16:55:20 volumio RoonBridge[1547]: 00:00:00.015 Warn: get lock file path: /tmp/.rnbgem0- Dec 18 16:55:20 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Dec 18 16:55:20 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 18 16:55:20 volumio sudo[1348]: pam_unix(sudo:session): session closed for user root Dec 18 16:55:20 volumio systemd[1]: shairport-sync.service: Succeeded. Dec 18 16:55:20 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 18 16:55:20 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 18 16:55:20 volumio sudo[1344]: pam_unix(sudo:session): session closed for user root Dec 18 16:55:20 volumio sudo[1341]: pam_unix(sudo:session): session closed for user root Dec 18 16:55:20 volumio RoonBridge[1547]: 00:00:00.774 Trace: [childprocess] using unix child process Dec 18 16:55:20 volumio RoonBridge[1547]: 00:00:00.906 Debug: PathForResource, filename: ../.update Dec 18 16:55:20 volumio RoonBridge[1547]: 00:00:00.981 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/../.update Dec 18 16:55:20 volumio RoonBridge[1547]: 00:00:00.994 Debug: PathForResource, filename: RAATServer Dec 18 16:55:20 volumio RoonBridge[1547]: 00:00:00.995 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Dec 18 16:55:20 volumio RoonBridge[1547]: 00:00:00.997 Debug: PathForResource, filename: RoonBridgeHelper Dec 18 16:55:20 volumio RoonBridge[1547]: 00:00:00.997 Debug: PathForResource, candidate: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper Dec 18 16:55:20 volumio RoonBridge[1547]: Initializing Dec 18 16:55:20 volumio RoonBridge[1547]: 00:00:01.077 Info: Starting /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RoonBridgeHelper Dec 18 16:55:21 volumio RoonBridge[1547]: 00:00:01.160 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/RoonBridge/RoonBridge/Bridge/RAATServer Dec 18 16:55:21 volumio volumio[779]: info: Completed starting Core Plugins Dec 18 16:55:21 volumio volumio[779]: info: ------------------------------------------- Dec 18 16:55:21 volumio volumio[779]: info: ----- MyVolumio plugins startup ---- Dec 18 16:55:21 volumio volumio[779]: info: ------------------------------------------- Dec 18 16:55:21 volumio volumio[779]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 18 16:55:21 volumio RoonBridge[1547]: Not Running (.o) Dec 18 16:55:21 volumio kernel: smbd(1273): Attempt to set a LOCK_MAND lock via flock(2). This support has been removed and the request ignored. Dec 18 16:55:21 volumio volumio[779]: info: Upmpdcli Daemon Started Dec 18 16:55:21 volumio volumio[779]: info: Shairport-Sync Started Dec 18 16:55:21 volumio volumio[779]: Error adding Membership: Error: addMembership EINVAL Dec 18 16:55:21 volumio volumio[779]: info: Shairport-Sync Started Dec 18 16:55:21 volumio volumio[779]: info: Shairport-Sync Started Dec 18 16:55:21 volumio volumio[779]: info: Shairport-Sync Started Dec 18 16:55:21 volumio RoonBridge[1547]: Running Dec 18 16:55:22 volumio volumio[779]: info: CoreCommandRouter::volumioGetState Dec 18 16:55:22 volumio volumio[779]: info: CorePlayQueue::getTrack 0 Dec 18 16:55:23 volumio RoonBridge[1547]: 00:00:00.192 Warn: get lock file path: /tmp/.rnbhgem0- Dec 18 16:55:24 volumio sudo[1533]: volumio : unable to resolve host volumio Dec 18 16:55:24 volumio sudo[1533]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Dec 18 16:55:24 volumio sudo[1533]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 18 16:55:24 volumio sudo[1533]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 16:55:24 volumio sudo[1533]: pam_unix(sudo:session): session closed for user root Dec 18 16:55:25 volumio sudo[1528]: pam_unix(sudo:session): session closed for user root Dec 18 16:55:25 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Dec 18 16:55:25 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -113 Dec 18 16:55:27 volumio volumio[779]: info: Cannot mount NAS usb at system boot, trial number 2 ,retrying in 5 seconds Dec 18 16:55:27 volumio volumio[779]: info: MPD Permissions set Dec 18 16:55:29 volumio ntpd[687]: Soliciting pool server 193.182.111.14 Dec 18 16:55:29 volumio sudo[1541]: volumio : unable to resolve host volumio Dec 18 16:55:29 volumio sudo[1541]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Dec 18 16:55:30 volumio sudo[1541]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 18 16:55:30 volumio sudo[1541]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 16:55:30 volumio systemd[1]: Stopping Music Player Daemon... Dec 18 16:55:30 volumio ntpd[687]: Soliciting pool server 139.199.214.202 Dec 18 16:55:30 volumio volumio[779]: Upnp client error: Error: This socket has been ended by the other party Dec 18 16:55:30 volumio systemd[1]: mpd.service: Succeeded. Dec 18 16:55:30 volumio systemd[1]: Stopped Music Player Daemon. Dec 18 16:55:30 volumio systemd[1]: Starting Music Player Daemon... Dec 18 16:55:31 volumio ntpd[687]: Soliciting pool server 185.209.85.222 Dec 18 16:55:31 volumio ntpd[687]: Soliciting pool server 119.28.183.184 Dec 18 16:55:31 volumio ntpd[687]: Soliciting pool server 95.111.202.5 Dec 18 16:55:32 volumio volumio[779]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 18 16:55:32 volumio volumio[779]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 18 16:55:32 volumio volumio[779]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 18 16:55:32 volumio volumio[779]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 18 16:55:32 volumio volumio[779]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 18 16:55:32 volumio volumio[779]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 18 16:55:32 volumio volumio[779]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 18 16:55:32 volumio volumio[779]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 18 16:55:32 volumio volumio[779]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 18 16:55:32 volumio volumio[779]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 18 16:55:32 volumio volumio[779]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Dec 18 16:55:32 volumio volumio[779]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 18 16:55:32 volumio volumio[779]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 18 16:55:32 volumio volumio[779]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 18 16:55:32 volumio volumio[779]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 18 16:55:32 volumio volumio[779]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 18 16:55:32 volumio volumio[779]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 18 16:55:32 volumio volumio[779]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 18 16:55:32 volumio volumio[779]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 18 16:55:32 volumio volumio[779]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 18 16:55:32 volumio volumio[779]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 18 16:55:32 volumio volumio[779]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Dec 18 16:55:32 volumio volumio[779]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 18 16:55:32 volumio volumio[779]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 18 16:55:32 volumio volumio[779]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 18 16:55:32 volumio volumio[779]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 18 16:55:32 volumio volumio[779]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 18 16:55:32 volumio volumio[779]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 18 16:55:32 volumio volumio[779]: info: Adding plugin bluetooth to MyMusic Plugins Dec 18 16:55:32 volumio volumio[779]: info: Adding plugin multiroom to MyMusic Plugins Dec 18 16:55:32 volumio volumio[779]: info: Adding plugin metavolumio to MyMusic Plugins Dec 18 16:55:32 volumio volumio[779]: info: Adding plugin cd_controller to MyMusic Plugins Dec 18 16:55:32 volumio volumio[779]: info: Adding plugin smart_inputs to MyMusic Plugins Dec 18 16:55:32 volumio volumio[779]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 18 16:55:32 volumio volumio[779]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 18 16:55:32 volumio ntpd[687]: Soliciting pool server 193.182.111.141 Dec 18 16:55:32 volumio ntpd[687]: Soliciting pool server 162.159.200.123 Dec 18 16:55:32 volumio ntpd[687]: Soliciting pool server 193.182.111.143 Dec 18 16:55:33 volumio ntpd[687]: Soliciting pool server 111.230.189.174 Dec 18 16:55:33 volumio mpd[1664]: Dec 18 16:55 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 18 16:55:34 volumio ntpd[687]: Soliciting pool server 110.42.98.138 Dec 18 16:55:34 volumio volumio[779]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 18 16:55:34 volumio volumio[779]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 18 16:55:34 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 16:55:34 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 16:55:34 volumio volumio[779]: info: Starting MyVolumio Remote Streaming Endpoints Dec 18 16:55:34 volumio volumio[779]: info: MyVolumio not started Dec 18 16:55:34 volumio volumio[779]: info: Initializing device activation check Dec 18 16:55:34 volumio volumio[779]: info: Enabling MyVolumio plan superstar via Activation Code and Device Method - Enable: 0b8543ee4bf2882d2f998e38a1af94f9 Dec 18 16:55:34 volumio volumio[779]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 18 16:55:34 volumio volumio[779]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 18 16:55:34 volumio systemd[1]: Started Music Player Daemon. Dec 18 16:55:34 volumio sudo[1541]: pam_unix(sudo:session): session closed for user root Dec 18 16:55:35 volumio ntpd[687]: Soliciting pool server 240e:982:2000:c::371:3cc Dec 18 16:55:36 volumio volumio[779]: info: Starting Streaming Service Transparent Proxy Dec 18 16:55:36 volumio volumio[779]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 18 16:55:36 volumio volumio[779]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 18 16:55:36 volumio volumio[779]: info: Streaming services startup Dec 18 16:55:36 volumio volumio[779]: info: Starting Streaming Daemon Dec 18 16:55:36 volumio volumio[779]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Dec 18 16:55:36 volumio volumio[779]: info: Cannot mount NAS usb at system boot, trial number 3 ,retrying in 5 seconds Dec 18 16:55:36 volumio sudo[1691]: volumio : unable to resolve host volumio Dec 18 16:55:36 volumio sudo[1691]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Dec 18 16:55:36 volumio sudo[1691]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 18 16:55:36 volumio sudo[1691]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 16:55:36 volumio volumio[779]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Dec 18 16:55:36 volumio volumio[779]: info: [MyVolumio PluginManager] No plugins found for user plan free Dec 18 16:55:36 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Dec 18 16:55:36 volumio volumio[779]: info: MyVolumio status changed Dec 18 16:55:36 volumio volumio[779]: info: Streaming services startup Dec 18 16:55:36 volumio volumio[779]: info: Starting Streaming Daemon Dec 18 16:55:36 volumio sudo[1691]: pam_unix(sudo:session): session closed for user root Dec 18 16:55:36 volumio volumio[779]: error: [MyVolumio PluginManager] Could not read cache file: ENOENT: no such file or directory, open '/data/myvolumio/cache.data' Dec 18 16:55:36 volumio volumio[779]: Upnp client error: Error: This socket has been ended by the other party Dec 18 16:55:36 volumio sudo[1698]: volumio : unable to resolve host volumio Dec 18 16:55:36 volumio sudo[1698]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Dec 18 16:55:36 volumio sudo[1698]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 18 16:55:36 volumio sudo[1698]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 16:55:36 volumio volumio[779]: error: Cannot start Volumio Streaming Daemon Dec 18 16:55:36 volumio volumio[779]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 18 16:55:36 volumio volumio[779]: sudo: unable to resolve host volumio: System error Dec 18 16:55:36 volumio volumio[779]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 18 16:55:36 volumio sudo[1698]: pam_unix(sudo:session): session closed for user root Dec 18 16:55:36 volumio volumio[779]: error: Cannot start Volumio Streaming Daemon Dec 18 16:55:36 volumio volumio[779]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 18 16:55:36 volumio volumio[779]: sudo: unable to resolve host volumio: System error Dec 18 16:55:36 volumio volumio[779]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 18 16:55:36 volumio volumio[779]: info: CoreCommandRouter::volumioGetState Dec 18 16:55:36 volumio volumio[779]: info: CorePlayQueue::getTrack 0 Dec 18 16:55:36 volumio volumio[779]: info: Remote config written successfully Dec 18 16:55:37 volumio volumio[779]: error: updateQueue error: null Dec 18 16:55:38 volumio ntpd[687]: receive: Unexpected origin timestamp 0xecee4009.4b53e649 does not match aorg 0000000000.00000000 from server@110.42.98.138 xmt 0xecee400a.29937e4c Dec 18 16:55:38 volumio volumio[779]: STREAMING PROXY: Starting server on port 3245 Dec 18 16:55:38 volumio volumio[779]: Node JS runtime: 14 Dec 18 16:55:38 volumio volumio[779]: error: [MyVolumio PluginManager] Could not fetch plugin keys: {"success":false,"error":{"message":"DEVICE_NOT_FOUND"}} Dec 18 16:55:39 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 18 16:55:39 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 16:55:39 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 18 16:55:41 volumio volumio[1617]: Generating RSA private key, 4096 bit long modulus (2 primes) Dec 18 16:55:41 volumio volumio[1617]: .....++++ Dec 18 16:55:42 volumio volumio[779]: info: Cannot mount NAS usb at system boot, trial number 4 ,retrying in 5 seconds Dec 18 16:55:43 volumio volumio[1617]: ..............................................................................++++ Dec 18 16:55:43 volumio volumio[1617]: e is 65537 (0x010001) Dec 18 16:55:43 volumio volumio[1617]: writing RSA key Dec 18 16:55:47 volumio volumio[779]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Dec 18 16:55:48 volumio volumio[779]: info: CoreCommandRouter::volumioGetState Dec 18 16:55:48 volumio volumio[779]: info: CorePlayQueue::getTrack 0 Dec 18 16:55:50 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 18 16:55:50 volumio volumio[779]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 11 Dec 18 16:55:50 volumio volumio[779]: info: CoreCommandRouter::volumioGetState Dec 18 16:55:50 volumio volumio[779]: info: CorePlayQueue::getTrack 0 Dec 18 16:55:55 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 16:55:55 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 16:55:55 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 18 16:55:55 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 18 16:55:55 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 18 16:55:55 volumio volumio[779]: info: CoreCommandRouter::volumioGetBrowseSources Dec 18 16:55:55 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 18 16:55:57 volumio volumio[779]: error: MyVolumio Plugin failed to authenticate in a timely fashion Dec 18 16:55:57 volumio volumio[779]: info: Completed starting MyVolumio Plugin Dec 18 16:55:57 volumio volumio[779]: info: BOOT COMPLETED Dec 18 16:55:57 volumio volumio[779]: [Metrics] CommandRouter: 80s 863.45ms Dec 18 16:55:57 volumio volumio[779]: info: CoreCommandRouter::volumiosetStartupVolume Dec 18 16:55:57 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 16:55:57 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 16:55:57 volumio volumio[779]: info: CoreCommandRouter::Close All Modals sent Dec 18 16:55:57 volumio volumio[779]: info: CoreCommandRouter::Close All Modals sent Dec 18 16:55:58 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 18 16:55:58 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 18 16:55:58 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 18 16:56:00 volumio volumio[779]: info: CoreCommandRouter::volumioGetState Dec 18 16:56:00 volumio volumio[779]: info: CorePlayQueue::getTrack 0 Dec 18 16:56:02 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 18 16:56:02 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 18 16:56:02 volumio volumio[779]: info: Discovery: Getting this device information Dec 18 16:56:02 volumio volumio[779]: info: CoreCommandRouter::volumioGetState Dec 18 16:56:02 volumio volumio[779]: info: CorePlayQueue::getTrack 0 Dec 18 16:56:02 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 18 16:56:02 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 18 16:56:02 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Dec 18 16:56:02 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Dec 18 16:56:02 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Dec 18 16:56:02 volumio sudo[1761]: volumio : unable to resolve host volumio Dec 18 16:56:02 volumio sudo[1761]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Dec 18 16:56:02 volumio sudo[1766]: volumio : unable to resolve host volumio Dec 18 16:56:02 volumio sudo[1766]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Dec 18 16:56:02 volumio sudo[1761]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Dec 18 16:56:02 volumio sudo[1761]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 16:56:02 volumio sudo[1766]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 18 16:56:02 volumio sudo[1766]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 16:56:02 volumio sudo[1766]: pam_unix(sudo:session): session closed for user root Dec 18 16:56:02 volumio sudo[1761]: pam_unix(sudo:session): session closed for user root Dec 18 16:56:02 volumio sudo[1772]: volumio : unable to resolve host volumio Dec 18 16:56:02 volumio sudo[1776]: volumio : unable to resolve host volumio Dec 18 16:56:02 volumio sudo[1776]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Dec 18 16:56:02 volumio sudo[1781]: volumio : unable to resolve host volumio Dec 18 16:56:02 volumio sudo[1772]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Dec 18 16:56:02 volumio sudo[1781]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Dec 18 16:56:02 volumio sudo[1776]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 18 16:56:02 volumio sudo[1776]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 16:56:02 volumio sudo[1772]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 18 16:56:02 volumio sudo[1776]: pam_unix(sudo:session): session closed for user root Dec 18 16:56:02 volumio sudo[1772]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 16:56:02 volumio sudo[1781]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 18 16:56:02 volumio sudo[1781]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 16:56:02 volumio sudo[1772]: pam_unix(sudo:session): session closed for user root Dec 18 16:56:02 volumio sudo[1781]: pam_unix(sudo:session): session closed for user root Dec 18 16:56:02 volumio sudo[1783]: volumio : unable to resolve host volumio Dec 18 16:56:02 volumio sudo[1783]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Dec 18 16:56:02 volumio sudo[1783]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 16:56:02 volumio sudo[1783]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 16:56:02 volumio sudo[1783]: pam_unix(sudo:session): session closed for user root Dec 18 16:56:03 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 18 16:56:03 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 18 16:56:03 volumio volumio[779]: info: Discovery: Getting this device information Dec 18 16:56:03 volumio volumio[779]: info: CoreCommandRouter::volumioGetState Dec 18 16:56:03 volumio volumio[779]: info: CorePlayQueue::getTrack 0 Dec 18 16:56:03 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 18 16:56:03 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 18 16:56:03 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 18 16:56:03 volumio volumio[779]: info: Discovery: Getting this device information Dec 18 16:56:03 volumio volumio[779]: info: CoreCommandRouter::volumioGetState Dec 18 16:56:03 volumio volumio[779]: info: CorePlayQueue::getTrack 0 Dec 18 16:56:03 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 18 16:56:03 volumio volumio[779]: info: CoreCommandRouter::volumioGetState Dec 18 16:56:03 volumio volumio[779]: info: CorePlayQueue::getTrack 0 Dec 18 16:56:03 volumio volumio[779]: verbose: New Socket.io Connection to 192.168.3.21:3000 from 192.168.3.6 UA: Dart/3.9 (dart:io) Total Clients: 12 Dec 18 16:56:03 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 18 16:56:03 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 18 16:56:03 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 18 16:56:03 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 18 16:56:03 volumio volumio[779]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/GALA/Young%20For%20You/64769040-1f5b-4f47-9a37-06fa2769519e.jpg' Dec 18 16:56:04 volumio sudo[1793]: volumio : unable to resolve host volumio Dec 18 16:56:04 volumio sudo[1795]: volumio : unable to resolve host volumio Dec 18 16:56:04 volumio sudo[1793]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Dec 18 16:56:04 volumio sudo[1795]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Dec 18 16:56:04 volumio sudo[1793]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 18 16:56:04 volumio sudo[1793]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 16:56:04 volumio sudo[1795]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 16:56:04 volumio sudo[1795]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 16:56:04 volumio sudo[1793]: pam_unix(sudo:session): session closed for user root Dec 18 16:56:04 volumio sudo[1795]: pam_unix(sudo:session): session closed for user root Dec 18 16:56:04 volumio volumio[779]: verbose: New Socket.io Connection to 192.168.3.21 from 192.168.3.6 UA: Mozilla/5.0 (Linux; Android 15; PKG110 Build/UKQ1.231108.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.34 Mobile Safari/537.36 Total Clients: 12 Dec 18 16:56:04 volumio sudo[1799]: volumio : unable to resolve host volumio Dec 18 16:56:04 volumio sudo[1801]: volumio : unable to resolve host volumio Dec 18 16:56:04 volumio sudo[1799]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Dec 18 16:56:04 volumio sudo[1801]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Dec 18 16:56:04 volumio sudo[1799]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 18 16:56:04 volumio sudo[1799]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 16:56:04 volumio sudo[1801]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 16:56:04 volumio sudo[1801]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 16:56:04 volumio sudo[1799]: pam_unix(sudo:session): session closed for user root Dec 18 16:56:04 volumio sudo[1801]: pam_unix(sudo:session): session closed for user root Dec 18 16:56:05 volumio volumio[779]: verbose: New Socket.io Connection to 192.168.3.21 from 192.168.3.6 UA: Mozilla/5.0 (Linux; Android 15; PKG110 Build/UKQ1.231108.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.34 Mobile Safari/537.36 Total Clients: 13 Dec 18 16:56:05 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 18 16:56:05 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 18 16:56:05 volumio volumio[779]: info: CoreCommandRouter::volumioGetVisibleSources Dec 18 16:56:05 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 18 16:56:05 volumio volumio[779]: info: CoreCommandRouter::volumioGetState Dec 18 16:56:05 volumio volumio[779]: info: CorePlayQueue::getTrack 0 Dec 18 16:56:05 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 18 16:56:05 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 18 16:56:05 volumio volumio[779]: info: Received Get System Info Dec 18 16:56:05 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 18 16:56:05 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 18 16:56:05 volumio volumio[779]: info: Discovery: Getting this device information Dec 18 16:56:05 volumio volumio[779]: info: CoreCommandRouter::volumioGetState Dec 18 16:56:05 volumio volumio[779]: info: CorePlayQueue::getTrack 0 Dec 18 16:56:05 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 18 16:56:05 volumio volumio[779]: info: CoreCommandRouter::volumioGetState Dec 18 16:56:05 volumio volumio[779]: info: CorePlayQueue::getTrack 0 Dec 18 16:56:05 volumio volumio[779]: info: Listing playlists Dec 18 16:56:05 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 18 16:56:05 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 18 16:56:06 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 18 16:56:06 volumio volumio[779]: info: Received Get System Info Dec 18 16:56:06 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 18 16:56:06 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 18 16:56:06 volumio volumio[779]: info: Discovery: Getting this device information Dec 18 16:56:06 volumio volumio[779]: info: CoreCommandRouter::volumioGetState Dec 18 16:56:06 volumio volumio[779]: info: CorePlayQueue::getTrack 0 Dec 18 16:56:06 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 18 16:56:08 volumio volumio[779]: info: CoreCommandRouter::volumioPlay Dec 18 16:56:08 volumio volumio[779]: info: CoreStateMachine::play index undefined Dec 18 16:56:08 volumio volumio[779]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 16:56:08 volumio volumio[779]: info: CorePlayQueue::getTrack 0 Dec 18 16:56:08 volumio volumio[779]: info: CoreStateMachine::startPlaybackTimer Dec 18 16:56:08 volumio volumio[779]: info: CorePlayQueue::getTrack 0 Dec 18 16:56:08 volumio volumio[779]: verbose: ControllerMpd::clearAddPlayTracks USB/音乐移动盘/酷我/男歌手/GALA-Young For You.flac Dec 18 16:56:08 volumio volumio[779]: verbose: ControllerMpd::sendMpdCommand stop Dec 18 16:56:08 volumio volumio[779]: info: sendMpdCommand stop took 3 milliseconds Dec 18 16:56:08 volumio volumio[779]: verbose: ControllerMpd::sendMpdCommand clear Dec 18 16:56:08 volumio volumio[779]: info: sendMpdCommand clear took 1 milliseconds Dec 18 16:56:08 volumio volumio[779]: verbose: ControllerMpd::sendMpdCommand add "USB/音乐移动盘/酷我/男歌手/GALA-Young For You.flac" Dec 18 16:56:08 volumio volumio[779]: info: Dec 18 16:56:08 volumio volumio[779]: ---------------------------- MPD announces system playlist update Dec 18 16:56:08 volumio volumio[779]: info: Ignoring MPD Status Update Dec 18 16:56:08 volumio volumio[779]: info: Dec 18 16:56:08 volumio volumio[779]: ---------------------------- MPD announces system playlist update Dec 18 16:56:08 volumio volumio[779]: info: Ignoring MPD Status Update Dec 18 16:56:08 volumio volumio[779]: info: sendMpdCommand add "USB/音乐移动盘/酷我/男歌手/GALA-Young For You.flac" took 6 milliseconds Dec 18 16:56:08 volumio volumio[779]: verbose: ControllerMpd::sendMpdCommand play Dec 18 16:56:08 volumio volumio[779]: info: ------------------------------ 9ms Dec 18 16:56:08 volumio volumio[779]: info: ------------------------------ 8ms Dec 18 16:56:08 volumio volumio[779]: info: sendMpdCommand play took 6 milliseconds Dec 18 16:56:08 volumio volumio[779]: info: Dec 18 16:56:08 volumio volumio[779]: ---------------------------- MPD announces state update: player Dec 18 16:56:08 volumio volumio[779]: info: ControllerMpd::getState Dec 18 16:56:08 volumio volumio[779]: verbose: ControllerMpd::sendMpdCommand status Dec 18 16:56:08 volumio volumio[779]: info: Dec 18 16:56:08 volumio volumio[779]: ---------------------------- MPD announces state update: player Dec 18 16:56:08 volumio volumio[779]: info: sendMpdCommand status took 105 milliseconds Dec 18 16:56:08 volumio volumio[779]: info: ControllerMpd::getState Dec 18 16:56:08 volumio volumio[779]: verbose: ControllerMpd::sendMpdCommand status Dec 18 16:56:08 volumio volumio[779]: verbose: ControllerMpd::parseState Dec 18 16:56:08 volumio volumio[779]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 16:56:08 volumio volumio[779]: info: sendMpdCommand status took 5 milliseconds Dec 18 16:56:08 volumio volumio[779]: verbose: ControllerMpd::parseState Dec 18 16:56:08 volumio volumio[779]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 16:56:08 volumio volumio[779]: info: sendMpdCommand playlistinfo took 7 milliseconds Dec 18 16:56:08 volumio volumio[779]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 18 16:56:08 volumio volumio[779]: verbose: ControllerMpd::parseTrackInfo Dec 18 16:56:08 volumio volumio[779]: info: ControllerMpd::pushState Dec 18 16:56:08 volumio volumio[779]: info: CoreCommandRouter::servicePushState Dec 18 16:56:08 volumio volumio[779]: info: CorePlayQueue::getTrack 0 Dec 18 16:56:08 volumio volumio[779]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":219,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1 Kbps","isStreaming":false,"title":"Young For You","artist":"GALA","album":"Young For You","uri":"USB/音乐移动盘/酷我/男歌手/GALA-Young For You.flac","trackType":"flac"} Dec 18 16:56:08 volumio volumio[779]: verbose: CURRENT POSITION 0 Dec 18 16:56:08 volumio volumio[779]: info: CoreStateMachine::syncState stateService play Dec 18 16:56:08 volumio volumio[779]: info: CoreStateMachine::syncState currentStatus stop Dec 18 16:56:08 volumio volumio[779]: info: ControllerMpd::pushState Dec 18 16:56:08 volumio volumio[779]: info: CoreCommandRouter::servicePushState Dec 18 16:56:08 volumio volumio[779]: info: CorePlayQueue::getTrack 0 Dec 18 16:56:08 volumio volumio[779]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":219,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1 Kbps","isStreaming":false,"title":"Young For You","artist":"GALA","album":"Young For You","uri":"USB/音乐移动盘/酷我/男歌手/GALA-Young For You.flac","trackType":"flac"} Dec 18 16:56:08 volumio volumio[779]: verbose: CURRENT POSITION 0 Dec 18 16:56:08 volumio volumio[779]: info: CoreStateMachine::syncState stateService play Dec 18 16:56:08 volumio volumio[779]: info: CoreStateMachine::syncState currentStatus play Dec 18 16:56:08 volumio volumio[779]: info: Received an update from plugin. extracting info from payload Dec 18 16:56:08 volumio volumio[779]: info: CoreStateMachine::pushState Dec 18 16:56:08 volumio volumio[779]: info: CorePlayQueue::getTrack 0 Dec 18 16:56:08 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 16:56:08 volumio volumio[779]: info: CoreCommandRouter::volumioPushState Dec 18 16:56:08 volumio volumio[779]: info: CoreStateMachine::pushState Dec 18 16:56:08 volumio volumio[779]: info: CorePlayQueue::getTrack 0 Dec 18 16:56:08 volumio volumio[779]: info: CoreCommandRouter::volumioPushState Dec 18 16:56:08 volumio volumio[779]: info: ------------------------------ 167ms Dec 18 16:56:08 volumio volumio[779]: info: ------------------------------ 99ms Dec 18 16:56:11 volumio volumio[779]: info: CoreCommandRouter::volumioGetState Dec 18 16:56:11 volumio volumio[779]: info: CorePlayQueue::getTrack 0 Dec 18 16:56:11 volumio sudo[1826]: root : unable to resolve host volumio Dec 18 16:56:11 volumio sudo[1826]: root : problem with defaults entries ; TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; Dec 18 16:56:11 volumio screensaver.sh[369]: sudo: unable to resolve host volumio: System error Dec 18 16:56:11 volumio sudo[1826]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/usr/bin/python3 ./screensaver/volumio_peppymeter.py Dec 18 16:56:11 volumio sudo[1826]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 16:56:14 volumio volumio[779]: info: CoreCommandRouter::volumioNext Dec 18 16:56:14 volumio volumio[779]: info: CoreStateMachine::next Dec 18 16:56:14 volumio volumio[779]: info: CoreStateMachine::stop Dec 18 16:56:14 volumio volumio[779]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 16:56:14 volumio volumio[779]: info: CoreStateMachine::stPlaybackTimer Dec 18 16:56:14 volumio volumio[779]: info: CoreStateMachine::updateTrackBlock Dec 18 16:56:14 volumio volumio[779]: info: CorePlayQueue::getTrackBlock Dec 18 16:56:14 volumio volumio[779]: info: CoreStateMachine::pushState Dec 18 16:56:14 volumio volumio[779]: info: CorePlayQueue::getTrack 0 Dec 18 16:56:14 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 16:56:14 volumio volumio[779]: info: CoreCommandRouter::volumioPushState Dec 18 16:56:14 volumio volumio[779]: info: CoreStateMachine::serviceStop Dec 18 16:56:14 volumio volumio[779]: info: CorePlayQueue::getTrack 0 Dec 18 16:56:14 volumio volumio[779]: info: CoreCommandRouter::serviceStop Dec 18 16:56:14 volumio volumio[779]: info: ControllerMpd::stop Dec 18 16:56:14 volumio volumio[779]: verbose: ControllerMpd::sendMpdCommand stop Dec 18 16:56:14 volumio volumio[779]: info: sendMpdCommand stop took 30 milliseconds Dec 18 16:56:14 volumio volumio[779]: info: CoreStateMachine::play index undefined Dec 18 16:56:14 volumio volumio[779]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 16:56:14 volumio volumio[779]: info: CorePlayQueue::getTrack 1 Dec 18 16:56:14 volumio volumio[779]: info: CoreStateMachine::startPlaybackTimer Dec 18 16:56:14 volumio volumio[779]: info: CorePlayQueue::getTrack 1 Dec 18 16:56:14 volumio volumio[779]: verbose: ControllerMpd::clearAddPlayTracks USB/音乐移动盘/酷我/男歌手/五月天-后来的我们.flac Dec 18 16:56:14 volumio volumio[779]: verbose: ControllerMpd::sendMpdCommand stop Dec 18 16:56:14 volumio volumio[779]: info: CoreStateMachine::updateTrackBlock Dec 18 16:56:14 volumio volumio[779]: info: CorePlayQueue::getTrackBlock Dec 18 16:56:14 volumio volumio[779]: info: Dec 18 16:56:14 volumio volumio[779]: ---------------------------- MPD announces state update: player Dec 18 16:56:14 volumio volumio[779]: info: ControllerMpd::getState Dec 18 16:56:14 volumio volumio[779]: verbose: ControllerMpd::sendMpdCommand status Dec 18 16:56:14 volumio volumio[779]: info: sendMpdCommand stop took 6 milliseconds Dec 18 16:56:14 volumio volumio[779]: verbose: ControllerMpd::sendMpdCommand clear Dec 18 16:56:14 volumio volumio[779]: info: Dec 18 16:56:14 volumio volumio[779]: ---------------------------- MPD announces system playlist update Dec 18 16:56:14 volumio volumio[779]: info: Ignoring MPD Status Update Dec 18 16:56:14 volumio volumio[779]: info: sendMpdCommand status took 31 milliseconds Dec 18 16:56:14 volumio volumio[779]: info: sendMpdCommand clear took 25 milliseconds Dec 18 16:56:14 volumio volumio[779]: verbose: ControllerMpd::parseState Dec 18 16:56:14 volumio volumio[779]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 16:56:14 volumio volumio[779]: verbose: ControllerMpd::sendMpdCommand add "USB/音乐移动盘/酷我/男歌手/五月天-后来的我们.flac" Dec 18 16:56:14 volumio volumio[779]: error: updateQueue error: null Dec 18 16:56:14 volumio volumio[779]: info: Dec 18 16:56:14 volumio volumio[779]: ---------------------------- MPD announces system playlist update Dec 18 16:56:14 volumio volumio[779]: info: Ignoring MPD Status Update Dec 18 16:56:14 volumio volumio[779]: info: ------------------------------ 40ms Dec 18 16:56:14 volumio volumio[779]: info: sendMpdCommand playlistinfo took 19 milliseconds Dec 18 16:56:14 volumio volumio[779]: info: sendMpdCommand add "USB/音乐移动盘/酷我/男歌手/五月天-后来的我们.flac" took 19 milliseconds Dec 18 16:56:14 volumio volumio[779]: verbose: ControllerMpd::parseTrackInfo Dec 18 16:56:14 volumio volumio[779]: verbose: ControllerMpd::sendMpdCommand play Dec 18 16:56:14 volumio volumio[779]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Dec 18 16:56:14 volumio volumio[779]: info: ------------------------------ 64ms Dec 18 16:56:14 volumio volumio[779]: info: ------------------------------ 29ms Dec 18 16:56:14 volumio volumio[779]: info: sendMpdCommand play took 11 milliseconds Dec 18 16:56:14 volumio volumio[779]: info: Dec 18 16:56:14 volumio volumio[779]: ---------------------------- MPD announces state update: player Dec 18 16:56:14 volumio volumio[779]: info: ControllerMpd::getState Dec 18 16:56:14 volumio volumio[779]: verbose: ControllerMpd::sendMpdCommand status Dec 18 16:56:14 volumio volumio[779]: info: Dec 18 16:56:14 volumio volumio[779]: ---------------------------- MPD announces state update: player Dec 18 16:56:14 volumio volumio[779]: info: sendMpdCommand status took 13 milliseconds Dec 18 16:56:14 volumio volumio[779]: info: ControllerMpd::getState Dec 18 16:56:14 volumio volumio[779]: verbose: ControllerMpd::sendMpdCommand status Dec 18 16:56:14 volumio volumio[779]: verbose: ControllerMpd::parseState Dec 18 16:56:14 volumio volumio[779]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 16:56:14 volumio volumio[779]: info: sendMpdCommand status took 4 milliseconds Dec 18 16:56:14 volumio volumio[779]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 18 16:56:14 volumio volumio[779]: verbose: ControllerMpd::parseState Dec 18 16:56:14 volumio volumio[779]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 16:56:14 volumio volumio[779]: verbose: ControllerMpd::parseTrackInfo Dec 18 16:56:14 volumio volumio[779]: info: ControllerMpd::pushState Dec 18 16:56:14 volumio volumio[779]: info: CoreCommandRouter::servicePushState Dec 18 16:56:14 volumio volumio[779]: info: CorePlayQueue::getTrack 1 Dec 18 16:56:14 volumio volumio[779]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":346,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"后来的我们","artist":"五月天","album":"自传","uri":"USB/音乐移动盘/酷我/男歌手/五月天-后来的我们.flac","trackType":"flac"} Dec 18 16:56:14 volumio volumio[779]: verbose: CURRENT POSITION 1 Dec 18 16:56:14 volumio volumio[779]: info: CoreStateMachine::syncState stateService play Dec 18 16:56:14 volumio volumio[779]: info: CoreStateMachine::syncState currentStatus stop Dec 18 16:56:14 volumio volumio[779]: info: ------------------------------ 28ms Dec 18 16:56:14 volumio volumio[779]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 18 16:56:14 volumio volumio[779]: verbose: ControllerMpd::parseTrackInfo Dec 18 16:56:14 volumio volumio[779]: info: ControllerMpd::pushState Dec 18 16:56:14 volumio volumio[779]: info: CoreCommandRouter::servicePushState Dec 18 16:56:14 volumio volumio[779]: info: CorePlayQueue::getTrack 1 Dec 18 16:56:14 volumio volumio[779]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":346,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"后来的我们","artist":"五月天","album":"自传","uri":"USB/音乐移动盘/酷我/男歌手/五月天-后来的我们.flac","trackType":"flac"} Dec 18 16:56:14 volumio volumio[779]: verbose: CURRENT POSITION 1 Dec 18 16:56:14 volumio volumio[779]: info: CoreStateMachine::syncState stateService play Dec 18 16:56:14 volumio volumio[779]: info: CoreStateMachine::syncState currentStatus play Dec 18 16:56:14 volumio volumio[779]: info: Received an update from plugin. extracting info from payload Dec 18 16:56:14 volumio volumio[779]: info: CoreStateMachine::pushState Dec 18 16:56:14 volumio volumio[779]: info: CorePlayQueue::getTrack 1 Dec 18 16:56:14 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 16:56:14 volumio volumio[779]: info: CoreCommandRouter::volumioPushState Dec 18 16:56:14 volumio volumio[779]: info: CoreStateMachine::pushState Dec 18 16:56:14 volumio volumio[779]: info: CorePlayQueue::getTrack 1 Dec 18 16:56:14 volumio volumio[779]: info: CoreCommandRouter::volumioPushState Dec 18 16:56:14 volumio volumio[779]: info: ------------------------------ 158ms Dec 18 16:56:14 volumio volumio[779]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E4%BA%94%E6%9C%88%E5%A4%A9/%E8%87%AA%E4%BC%A0/0614be6c-b9bc-4bbe-a68a-cc358f0f2192.jpg' Dec 18 16:56:14 volumio volumio[779]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E4%BA%94%E6%9C%88%E5%A4%A9/%E8%87%AA%E4%BC%A0/0614be6c-b9bc-4bbe-a68a-cc358f0f2192.jpg' Dec 18 16:56:15 volumio volumio[779]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E4%BA%94%E6%9C%88%E5%A4%A9/%E8%87%AA%E4%BC%A0/0614be6c-b9bc-4bbe-a68a-cc358f0f2192.jpg' Dec 18 16:56:15 volumio volumio[779]: info: CoreCommandRouter::volumioNext Dec 18 16:56:15 volumio volumio[779]: info: CoreStateMachine::next Dec 18 16:56:15 volumio volumio[779]: info: CoreStateMachine::stop Dec 18 16:56:15 volumio volumio[779]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 16:56:15 volumio volumio[779]: info: CoreStateMachine::stPlaybackTimer Dec 18 16:56:15 volumio volumio[779]: info: CoreStateMachine::updateTrackBlock Dec 18 16:56:15 volumio volumio[779]: info: CorePlayQueue::getTrackBlock Dec 18 16:56:15 volumio volumio[779]: info: CoreStateMachine::pushState Dec 18 16:56:15 volumio volumio[779]: info: CorePlayQueue::getTrack 1 Dec 18 16:56:15 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 16:56:15 volumio volumio[779]: info: CoreCommandRouter::volumioPushState Dec 18 16:56:15 volumio volumio[779]: info: CoreStateMachine::serviceStop Dec 18 16:56:15 volumio volumio[779]: info: CorePlayQueue::getTrack 1 Dec 18 16:56:15 volumio volumio[779]: info: CoreCommandRouter::serviceStop Dec 18 16:56:15 volumio volumio[779]: info: ControllerMpd::stop Dec 18 16:56:15 volumio volumio[779]: verbose: ControllerMpd::sendMpdCommand stop Dec 18 16:56:15 volumio volumio[779]: info: Dec 18 16:56:15 volumio volumio[779]: ---------------------------- MPD announces state update: player Dec 18 16:56:15 volumio volumio[779]: info: sendMpdCommand stop took 84 milliseconds Dec 18 16:56:15 volumio volumio[779]: info: ControllerMpd::getState Dec 18 16:56:15 volumio volumio[779]: verbose: ControllerMpd::sendMpdCommand status Dec 18 16:56:16 volumio volumio[779]: info: CoreStateMachine::play index undefined Dec 18 16:56:16 volumio volumio[779]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 16:56:16 volumio volumio[779]: info: CorePlayQueue::getTrack 2 Dec 18 16:56:16 volumio volumio[779]: info: CoreStateMachine::startPlaybackTimer Dec 18 16:56:16 volumio volumio[779]: info: CorePlayQueue::getTrack 2 Dec 18 16:56:16 volumio volumio[779]: verbose: ControllerMpd::clearAddPlayTracks USB/音乐移动盘/酷我/男歌手/五月天-将军令.flac Dec 18 16:56:16 volumio volumio[779]: verbose: ControllerMpd::sendMpdCommand stop Dec 18 16:56:16 volumio volumio[779]: info: CoreStateMachine::updateTrackBlock Dec 18 16:56:16 volumio volumio[779]: info: CorePlayQueue::getTrackBlock Dec 18 16:56:16 volumio volumio[779]: info: sendMpdCommand status took 11 milliseconds Dec 18 16:56:16 volumio volumio[779]: verbose: ControllerMpd::parseState Dec 18 16:56:16 volumio volumio[779]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 16:56:16 volumio volumio[779]: info: sendMpdCommand stop took 10 milliseconds Dec 18 16:56:16 volumio volumio[779]: info: sendMpdCommand playlistinfo took 10 milliseconds Dec 18 16:56:16 volumio volumio[779]: verbose: ControllerMpd::sendMpdCommand clear Dec 18 16:56:16 volumio volumio[779]: verbose: ControllerMpd::parseTrackInfo Dec 18 16:56:16 volumio volumio[779]: info: ControllerMpd::pushState Dec 18 16:56:16 volumio volumio[779]: info: CoreCommandRouter::servicePushState Dec 18 16:56:16 volumio volumio[779]: info: CoreStateMachine::pushState Dec 18 16:56:16 volumio volumio[779]: info: CorePlayQueue::getTrack 2 Dec 18 16:56:16 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 16:56:16 volumio volumio[779]: info: CoreCommandRouter::volumioPushState Dec 18 16:56:16 volumio volumio[779]: info: CorePlayQueue::getTrack 2 Dec 18 16:56:16 volumio volumio[779]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"后来的我们","artist":"五月天","album":"自传","uri":"USB/音乐移动盘/酷我/男歌手/五月天-后来的我们.flac","trackType":"flac"} Dec 18 16:56:16 volumio volumio[779]: verbose: CURRENT POSITION 2 Dec 18 16:56:16 volumio volumio[779]: info: CoreStateMachine::syncState stateService stop Dec 18 16:56:16 volumio volumio[779]: info: CoreStateMachine::syncState currentStatus stop Dec 18 16:56:16 volumio volumio[779]: info: CoreStateMachine::pushState Dec 18 16:56:16 volumio volumio[779]: info: CorePlayQueue::getTrack 2 Dec 18 16:56:16 volumio volumio[779]: info: CoreCommandRouter::volumioPushState Dec 18 16:56:16 volumio volumio[779]: info: No code Dec 18 16:56:16 volumio volumio[779]: info: CoreStateMachine::pushState Dec 18 16:56:16 volumio volumio[779]: info: CorePlayQueue::getTrack 2 Dec 18 16:56:16 volumio volumio[779]: info: CoreCommandRouter::volumioPushState Dec 18 16:56:16 volumio volumio[779]: info: ------------------------------ 148ms Dec 18 16:56:16 volumio volumio[779]: info: Dec 18 16:56:16 volumio volumio[779]: ---------------------------- MPD announces system playlist update Dec 18 16:56:16 volumio volumio[779]: info: Ignoring MPD Status Update Dec 18 16:56:16 volumio volumio[779]: info: sendMpdCommand clear took 124 milliseconds Dec 18 16:56:16 volumio volumio[779]: verbose: ControllerMpd::sendMpdCommand add "USB/音乐移动盘/酷我/男歌手/五月天-将军令.flac" Dec 18 16:56:16 volumio volumio[779]: error: updateQueue error: null Dec 18 16:56:16 volumio volumio[779]: info: Dec 18 16:56:16 volumio volumio[779]: ---------------------------- MPD announces system playlist update Dec 18 16:56:16 volumio volumio[779]: info: Ignoring MPD Status Update Dec 18 16:56:16 volumio volumio[779]: info: ------------------------------ 39ms Dec 18 16:56:16 volumio volumio[779]: info: sendMpdCommand add "USB/音乐移动盘/酷我/男歌手/五月天-将军令.flac" took 35 milliseconds Dec 18 16:56:16 volumio volumio[779]: verbose: ControllerMpd::sendMpdCommand play Dec 18 16:56:16 volumio volumio[779]: info: Dec 18 16:56:16 volumio volumio[779]: ---------------------------- MPD announces state update: player Dec 18 16:56:16 volumio volumio[779]: info: ------------------------------ 57ms Dec 18 16:56:16 volumio volumio[779]: info: sendMpdCommand play took 51 milliseconds Dec 18 16:56:16 volumio volumio[779]: info: ControllerMpd::getState Dec 18 16:56:16 volumio volumio[779]: verbose: ControllerMpd::sendMpdCommand status Dec 18 16:56:16 volumio volumio[779]: info: Dec 18 16:56:16 volumio volumio[779]: ---------------------------- MPD announces state update: player Dec 18 16:56:16 volumio volumio[779]: info: ControllerMpd::getState Dec 18 16:56:16 volumio volumio[779]: verbose: ControllerMpd::sendMpdCommand status Dec 18 16:56:16 volumio volumio[779]: info: sendMpdCommand status took 117 milliseconds Dec 18 16:56:16 volumio volumio[779]: verbose: ControllerMpd::parseState Dec 18 16:56:16 volumio volumio[779]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 16:56:16 volumio volumio[779]: info: sendMpdCommand status took 103 milliseconds Dec 18 16:56:16 volumio volumio[779]: info: sendMpdCommand playlistinfo took 33 milliseconds Dec 18 16:56:16 volumio volumio[779]: verbose: ControllerMpd::parseState Dec 18 16:56:16 volumio volumio[779]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 16:56:16 volumio volumio[779]: verbose: ControllerMpd::parseTrackInfo Dec 18 16:56:16 volumio volumio[779]: info: ControllerMpd::pushState Dec 18 16:56:16 volumio volumio[779]: info: CoreCommandRouter::servicePushState Dec 18 16:56:16 volumio volumio[779]: info: CorePlayQueue::getTrack 2 Dec 18 16:56:16 volumio volumio[779]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45,"duration":219,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"742 Kbps","isStreaming":false,"title":"将军令","artist":"五月天","album":"超级剧无霸 原声带精选(2014 JUMBO BAR OF MEGA HITS )","uri":"USB/音乐移动盘/酷我/男歌手/五月天-将军令.flac","trackType":"flac"} Dec 18 16:56:16 volumio volumio[779]: verbose: CURRENT POSITION 2 Dec 18 16:56:16 volumio volumio[779]: info: CoreStateMachine::syncState stateService play Dec 18 16:56:16 volumio volumio[779]: info: CoreStateMachine::syncState currentStatus stop Dec 18 16:56:16 volumio volumio[779]: info: ------------------------------ 259ms Dec 18 16:56:16 volumio volumio[779]: info: sendMpdCommand playlistinfo took 19 milliseconds Dec 18 16:56:16 volumio volumio[779]: verbose: ControllerMpd::parseTrackInfo Dec 18 16:56:16 volumio volumio[779]: info: ControllerMpd::pushState Dec 18 16:56:16 volumio volumio[779]: info: CoreCommandRouter::servicePushState Dec 18 16:56:16 volumio volumio[779]: info: CorePlayQueue::getTrack 2 Dec 18 16:56:16 volumio volumio[779]: verbose: STATE SERVICE {"status":"play","position":0,"seek":500,"duration":219,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"991 Kbps","isStreaming":false,"title":"将军令","artist":"五月天","album":"超级剧无霸 原声带精选(2014 JUMBO BAR OF MEGA HITS )","uri":"USB/音乐移动盘/酷我/男歌手/五月天-将军令.flac","trackType":"flac"} Dec 18 16:56:16 volumio volumio[779]: verbose: CURRENT POSITION 2 Dec 18 16:56:16 volumio volumio[779]: info: CoreStateMachine::syncState stateService play Dec 18 16:56:16 volumio volumio[779]: info: CoreStateMachine::syncState currentStatus play Dec 18 16:56:16 volumio volumio[779]: info: Received an update from plugin. extracting info from payload Dec 18 16:56:16 volumio volumio[779]: info: CoreStateMachine::pushState Dec 18 16:56:16 volumio volumio[779]: info: CorePlayQueue::getTrack 2 Dec 18 16:56:16 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 16:56:16 volumio volumio[779]: info: CoreCommandRouter::volumioPushState Dec 18 16:56:16 volumio volumio[779]: info: CoreStateMachine::pushState Dec 18 16:56:16 volumio volumio[779]: info: CorePlayQueue::getTrack 2 Dec 18 16:56:16 volumio volumio[779]: info: CoreCommandRouter::volumioPushState Dec 18 16:56:16 volumio volumio[779]: info: ------------------------------ 221ms Dec 18 16:56:19 volumio volumio[779]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 14 Dec 18 16:56:19 volumio volumio[779]: info: CoreCommandRouter::volumioGetState Dec 18 16:56:19 volumio volumio[779]: info: CorePlayQueue::getTrack 2 Dec 18 16:56:23 volumio volumio[779]: info: CoreCommandRouter::volumioNext Dec 18 16:56:23 volumio volumio[779]: info: CoreStateMachine::next Dec 18 16:56:23 volumio volumio[779]: info: CoreStateMachine::stop Dec 18 16:56:23 volumio volumio[779]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 16:56:23 volumio volumio[779]: info: CoreStateMachine::stPlaybackTimer Dec 18 16:56:23 volumio volumio[779]: info: CoreStateMachine::updateTrackBlock Dec 18 16:56:23 volumio volumio[779]: info: CorePlayQueue::getTrackBlock Dec 18 16:56:23 volumio volumio[779]: info: CoreStateMachine::pushState Dec 18 16:56:23 volumio volumio[779]: info: CorePlayQueue::getTrack 2 Dec 18 16:56:23 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 16:56:23 volumio volumio[779]: info: CoreCommandRouter::volumioPushState Dec 18 16:56:23 volumio volumio[779]: info: CoreStateMachine::serviceStop Dec 18 16:56:23 volumio volumio[779]: info: CorePlayQueue::getTrack 2 Dec 18 16:56:23 volumio volumio[779]: info: CoreCommandRouter::serviceStop Dec 18 16:56:23 volumio volumio[779]: info: ControllerMpd::stop Dec 18 16:56:23 volumio volumio[779]: verbose: ControllerMpd::sendMpdCommand stop Dec 18 16:56:23 volumio volumio[779]: info: Dec 18 16:56:23 volumio volumio[779]: ---------------------------- MPD announces state update: player Dec 18 16:56:23 volumio volumio[779]: info: sendMpdCommand stop took 32 milliseconds Dec 18 16:56:23 volumio volumio[779]: info: ControllerMpd::getState Dec 18 16:56:23 volumio volumio[779]: verbose: ControllerMpd::sendMpdCommand status Dec 18 16:56:23 volumio volumio[779]: info: CoreStateMachine::play index undefined Dec 18 16:56:23 volumio volumio[779]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 16:56:23 volumio volumio[779]: info: CorePlayQueue::getTrack 3 Dec 18 16:56:23 volumio volumio[779]: info: CoreStateMachine::startPlaybackTimer Dec 18 16:56:23 volumio volumio[779]: info: CorePlayQueue::getTrack 3 Dec 18 16:56:23 volumio volumio[779]: verbose: ControllerMpd::clearAddPlayTracks USB/音乐移动盘/酷我/男歌手/五月天-温柔.flac Dec 18 16:56:23 volumio volumio[779]: verbose: ControllerMpd::sendMpdCommand stop Dec 18 16:56:23 volumio volumio[779]: info: CoreStateMachine::updateTrackBlock Dec 18 16:56:23 volumio volumio[779]: info: CorePlayQueue::getTrackBlock Dec 18 16:56:23 volumio volumio[779]: info: sendMpdCommand status took 6 milliseconds Dec 18 16:56:23 volumio volumio[779]: info: sendMpdCommand stop took 3 milliseconds Dec 18 16:56:23 volumio volumio[779]: verbose: ControllerMpd::parseState Dec 18 16:56:23 volumio volumio[779]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 16:56:23 volumio volumio[779]: verbose: ControllerMpd::sendMpdCommand clear Dec 18 16:56:23 volumio volumio[779]: info: Dec 18 16:56:23 volumio volumio[779]: ---------------------------- MPD announces system playlist update Dec 18 16:56:23 volumio volumio[779]: info: Ignoring MPD Status Update Dec 18 16:56:23 volumio volumio[779]: info: sendMpdCommand playlistinfo took 6 milliseconds Dec 18 16:56:23 volumio volumio[779]: info: sendMpdCommand clear took 6 milliseconds Dec 18 16:56:23 volumio volumio[779]: verbose: ControllerMpd::parseTrackInfo Dec 18 16:56:23 volumio volumio[779]: verbose: ControllerMpd::sendMpdCommand add "USB/音乐移动盘/酷我/男歌手/五月天-温柔.flac" Dec 18 16:56:23 volumio volumio[779]: info: ControllerMpd::pushState Dec 18 16:56:23 volumio volumio[779]: info: CoreCommandRouter::servicePushState Dec 18 16:56:23 volumio volumio[779]: info: CoreStateMachine::pushState Dec 18 16:56:23 volumio volumio[779]: info: CorePlayQueue::getTrack 3 Dec 18 16:56:23 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 16:56:23 volumio volumio[779]: info: CoreCommandRouter::volumioPushState Dec 18 16:56:23 volumio volumio[779]: info: CorePlayQueue::getTrack 3 Dec 18 16:56:23 volumio volumio[779]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"将军令","artist":"五月天","album":"超级剧无霸 原声带精选(2014 JUMBO BAR OF MEGA HITS )","uri":"USB/音乐移动盘/酷我/男歌手/五月天-将军令.flac","trackType":"flac"} Dec 18 16:56:23 volumio volumio[779]: verbose: CURRENT POSITION 3 Dec 18 16:56:23 volumio volumio[779]: info: CoreStateMachine::syncState stateService stop Dec 18 16:56:23 volumio volumio[779]: info: CoreStateMachine::syncState currentStatus stop Dec 18 16:56:23 volumio volumio[779]: info: CoreStateMachine::pushState Dec 18 16:56:23 volumio volumio[779]: info: CorePlayQueue::getTrack 3 Dec 18 16:56:23 volumio volumio[779]: info: CoreCommandRouter::volumioPushState Dec 18 16:56:23 volumio volumio[779]: info: No code Dec 18 16:56:23 volumio volumio[779]: info: CoreStateMachine::pushState Dec 18 16:56:23 volumio volumio[779]: info: CorePlayQueue::getTrack 3 Dec 18 16:56:23 volumio volumio[779]: info: CoreCommandRouter::volumioPushState Dec 18 16:56:23 volumio volumio[779]: info: ------------------------------ 88ms Dec 18 16:56:23 volumio volumio[779]: error: updateQueue error: null Dec 18 16:56:23 volumio volumio[779]: info: Dec 18 16:56:23 volumio volumio[779]: ---------------------------- MPD announces system playlist update Dec 18 16:56:23 volumio volumio[779]: info: Ignoring MPD Status Update Dec 18 16:56:23 volumio volumio[779]: info: ------------------------------ 78ms Dec 18 16:56:23 volumio volumio[779]: info: sendMpdCommand add "USB/音乐移动盘/酷我/男歌手/五月天-温柔.flac" took 75 milliseconds Dec 18 16:56:23 volumio volumio[779]: verbose: ControllerMpd::sendMpdCommand play Dec 18 16:56:23 volumio volumio[779]: info: ------------------------------ 38ms Dec 18 16:56:23 volumio volumio[779]: info: sendMpdCommand play took 36 milliseconds Dec 18 16:56:23 volumio volumio[779]: info: Dec 18 16:56:23 volumio volumio[779]: ---------------------------- MPD announces state update: player Dec 18 16:56:23 volumio volumio[779]: info: ControllerMpd::getState Dec 18 16:56:23 volumio volumio[779]: verbose: ControllerMpd::sendMpdCommand status Dec 18 16:56:23 volumio volumio[779]: info: Dec 18 16:56:23 volumio volumio[779]: ---------------------------- MPD announces state update: player Dec 18 16:56:23 volumio volumio[779]: info: ControllerMpd::getState Dec 18 16:56:23 volumio volumio[779]: verbose: ControllerMpd::sendMpdCommand status Dec 18 16:56:23 volumio volumio[779]: info: sendMpdCommand status took 50 milliseconds Dec 18 16:56:23 volumio volumio[779]: verbose: ControllerMpd::parseState Dec 18 16:56:23 volumio volumio[779]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 16:56:23 volumio volumio[779]: info: sendMpdCommand status took 4 milliseconds Dec 18 16:56:23 volumio volumio[779]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 18 16:56:23 volumio volumio[779]: verbose: ControllerMpd::parseState Dec 18 16:56:23 volumio volumio[779]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 16:56:23 volumio volumio[779]: verbose: ControllerMpd::parseTrackInfo Dec 18 16:56:23 volumio volumio[779]: info: ControllerMpd::pushState Dec 18 16:56:23 volumio volumio[779]: info: CoreCommandRouter::servicePushState Dec 18 16:56:23 volumio volumio[779]: info: CorePlayQueue::getTrack 3 Dec 18 16:56:23 volumio volumio[779]: verbose: STATE SERVICE {"status":"play","position":0,"seek":865,"duration":270,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"967 Kbps","isStreaming":false,"title":"温柔","artist":"五月天","album":"爱情万岁","uri":"USB/音乐移动盘/酷我/男歌手/五月天-温柔.flac","trackType":"flac"} Dec 18 16:56:23 volumio volumio[779]: verbose: CURRENT POSITION 3 Dec 18 16:56:23 volumio volumio[779]: info: CoreStateMachine::syncState stateService play Dec 18 16:56:23 volumio volumio[779]: info: CoreStateMachine::syncState currentStatus stop Dec 18 16:56:23 volumio volumio[779]: info: ------------------------------ 63ms Dec 18 16:56:23 volumio volumio[779]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 18 16:56:23 volumio volumio[779]: verbose: ControllerMpd::parseTrackInfo Dec 18 16:56:23 volumio volumio[779]: info: ControllerMpd::pushState Dec 18 16:56:23 volumio volumio[779]: info: CoreCommandRouter::servicePushState Dec 18 16:56:23 volumio volumio[779]: info: CorePlayQueue::getTrack 3 Dec 18 16:56:23 volumio volumio[779]: verbose: STATE SERVICE {"status":"play","position":0,"seek":887,"duration":270,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"967 Kbps","isStreaming":false,"title":"温柔","artist":"五月天","album":"爱情万岁","uri":"USB/音乐移动盘/酷我/男歌手/五月天-温柔.flac","trackType":"flac"} Dec 18 16:56:23 volumio volumio[779]: verbose: CURRENT POSITION 3 Dec 18 16:56:23 volumio volumio[779]: info: CoreStateMachine::syncState stateService play Dec 18 16:56:23 volumio volumio[779]: info: CoreStateMachine::syncState currentStatus play Dec 18 16:56:23 volumio volumio[779]: info: Received an update from plugin. extracting info from payload Dec 18 16:56:23 volumio volumio[779]: info: CoreStateMachine::pushState Dec 18 16:56:23 volumio volumio[779]: info: CorePlayQueue::getTrack 3 Dec 18 16:56:23 volumio volumio[779]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 16:56:23 volumio volumio[779]: info: CoreCommandRouter::volumioPushState Dec 18 16:56:23 volumio volumio[779]: info: CoreStateMachine::pushState Dec 18 16:56:23 volumio volumio[779]: info: CorePlayQueue::getTrack 3 Dec 18 16:56:23 volumio volumio[779]: info: CoreCommandRouter::volumioPushState Dec 18 16:56:23 volumio volumio[779]: info: ------------------------------ 75ms Dec 18 16:56:23 volumio volumio[779]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 18 16:56:23 volumio volumio[779]: Error: connect ECONNREFUSED 93.179.102.140:443 Dec 18 16:56:23 volumio volumio[779]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Dec 18 16:56:23 volumio volumio[779]: errno: -111, Dec 18 16:56:23 volumio volumio[779]: code: 'ECONNREFUSED', Dec 18 16:56:23 volumio volumio[779]: syscall: 'connect', Dec 18 16:56:23 volumio volumio[779]: address: '93.179.102.140', Dec 18 16:56:23 volumio volumio[779]: port: 443 Dec 18 16:56:23 volumio volumio[779]: } Dec 18 16:56:23 volumio volumio[779]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 18 16:56:24 volumio sudo[1943]: volumio : unable to resolve host volumio Dec 18 16:56:24 volumio sudo[1943]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Dec 18 16:56:24 volumio sudo[1943]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-18 16:55 Dec 18 16:56:24 volumio sudo[1943]: 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="e7445fab547cd22e23d9bef72406cfb610bc1d7d" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:42:03 PM CEST" VOLUMIO_VERSION="3.661" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="c434411fe8754b648dc7feb1d8f43761"