-- Logs begin at Sat 2025-05-17 20:31:02 CST, end at Sat 2025-05-17 21:55:44 CST. -- May 17 21:54:00 volumio volumio[14752]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 17 21:54:00 volumio volumio[14752]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 May 17 21:54:00 volumio volumio[14752]: info: CoreCommandRouter::volumioGetState May 17 21:54:03 volumio sudo[15033]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=18805727770,password=Yy514205,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=2.0 //192.168.1.42/SATA存储11/音乐 /mnt/NAS/极空间 May 17 21:54:03 volumio sudo[15033]: pam_unix(sudo:session): session opened for user root by (uid=0) May 17 21:54:03 volumio kernel: CIFS: Attempting to mount //192.168.1.42/SATA存储11/音乐 May 17 21:54:05 volumio volumio[14752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 17 21:54:05 volumio volumio[14752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 17 21:54:05 volumio volumio[14752]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 17 21:54:05 volumio volumio[14752]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 17 21:54:05 volumio volumio[14752]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 17 21:54:05 volumio volumio[14752]: info: CoreCommandRouter::volumioGetBrowseSources May 17 21:54:05 volumio volumio[14752]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 17 21:54:05 volumio volumio[14752]: error: MyVolumio Plugin failed to authenticate in a timely fashion May 17 21:54:05 volumio volumio[14752]: info: Completed starting MyVolumio Plugin May 17 21:54:05 volumio volumio[14752]: [Metrics] CommandRouter: 36s 387.27ms May 17 21:54:05 volumio volumio[14752]: info: CoreCommandRouter::volumiosetStartupVolume May 17 21:54:05 volumio volumio[14752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 17 21:54:05 volumio volumio[14752]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 17 21:54:05 volumio volumio[14752]: info: CoreCommandRouter::Close All Modals sent May 17 21:54:05 volumio volumio[14752]: info: CoreCommandRouter::Close All Modals sent May 17 21:54:05 volumio volumio[14752]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav May 17 21:54:05 volumio volumio[14752]: aplay: main:828: audio open error: Device or resource busy May 17 21:54:06 volumio volumio[14752]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 17 21:54:06 volumio volumio[14752]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 17 21:54:06 volumio volumio[14752]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 17 21:54:06 volumio volumio[14752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 17 21:54:06 volumio volumio[14752]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 17 21:54:06 volumio volumio[14752]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 17 21:54:08 volumio volumio[14752]: info: CoreCommandRouter::volumioGetState May 17 21:54:09 volumio sudo[15033]: pam_unix(sudo:session): session closed for user root May 17 21:54:09 volumio volumio[14752]: info: Cannot mount NAS 极空间 at system boot, trial number 4 ,retrying in 5 seconds May 17 21:54:09 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. May 17 21:54:09 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -113 May 17 21:54:11 volumio volumio[14752]: info: CoreCommandRouter::getUIConfigOnPlugin May 17 21:54:11 volumio volumio[14752]: info: CoreCommandRouter::getUIConfigOnPlugin May 17 21:54:11 volumio volumio[14752]: info: Retrieving Cloud Streaming UI May 17 21:54:11 volumio volumio[14752]: info: Getting Tidal Cloud Configuration May 17 21:54:11 volumio volumio[14752]: info: CoreCommandRouter::getUIConfigOnPlugin May 17 21:54:11 volumio volumio[14752]: info: Getting Qobuz Cloud Configuration May 17 21:54:11 volumio volumio[14752]: info: Asking plugin for UI Config May 17 21:54:11 volumio volumio[14752]: info: CoreCommandRouter::getUIConfigOnPlugin May 17 21:54:11 volumio volumio[14752]: info: Getting Spotify Cloud Configuration May 17 21:54:11 volumio volumio[14752]: info: Asking plugin for UI Config May 17 21:54:11 volumio volumio[14752]: info: CoreCommandRouter::getUIConfigOnPlugin May 17 21:54:11 volumio volumio[14752]: info: Saving Spotify Acccount May 17 21:54:11 volumio volumio[14752]: info: Got Tidal Cloud Configuration May 17 21:54:11 volumio volumio[14752]: info: Got it May 17 21:54:11 volumio volumio[14752]: info: Got it May 17 21:54:11 volumio volumio[14752]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 17 21:54:11 volumio volumio[14752]: info: CoreCommandRouter::getUIConfigOnPlugin May 17 21:54:11 volumio volumio[14752]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined May 17 21:54:11 volumio volumio[14752]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 17 21:54:11 volumio volumio[14752]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 17 21:54:11 volumio volumio[14752]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 17 21:54:11 volumio volumio[14752]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 17 21:54:11 volumio volumio[14752]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 17 21:54:11 volumio volumio[14752]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 17 21:54:11 volumio volumio[14752]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 17 21:54:11 volumio volumio[14752]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 17 21:54:11 volumio volumio[14752]: info: CoreCommandRouter::volumioGetBrowseSources May 17 21:54:11 volumio volumio[14752]: info: CoreCommandRouter::volumioGetBrowseSources May 17 21:54:11 volumio volumio[14752]: info: CoreCommandRouter::volumioGetBrowseSources May 17 21:54:12 volumio volumio[14752]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 17 21:54:12 volumio volumio[14752]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 17 21:54:12 volumio volumio[14752]: info: BOOT COMPLETED May 17 21:54:14 volumio sudo[15102]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=18805727770,password=Yy514205,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=2.0 //192.168.1.42/SATA存储11/音乐 /mnt/NAS/极空间 May 17 21:54:14 volumio sudo[15102]: pam_unix(sudo:session): session opened for user root by (uid=0) May 17 21:54:14 volumio kernel: CIFS: Attempting to mount //192.168.1.42/SATA存储11/音乐 May 17 21:54:16 volumio volumio[14752]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 17 21:54:16 volumio volumio-remote-updater[709]: No test mode May 17 21:54:16 volumio volumio-remote-updater[709]: No alpha test mode May 17 21:54:16 volumio volumio[14752]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 17 21:54:17 volumio volumio[14752]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} May 17 21:54:17 volumio volumio[14752]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 17 21:54:20 volumio volumio[14752]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 17 21:54:20 volumio sudo[15102]: pam_unix(sudo:session): session closed for user root May 17 21:54:20 volumio volumio[14752]: info: Cannot mount NAS at system boot, trial number 4 ,stopping May 17 21:54:20 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. May 17 21:54:20 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -113 May 17 21:54:24 volumio volumio[14752]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 17 21:54:28 volumio volumio[14752]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 17 21:54:29 volumio volumio[14752]: info: CALLMETHOD: music_service mpd saveMusicLibraryOptions [object Object] May 17 21:54:29 volumio volumio[14752]: info: CoreCommandRouter::executeOnPlugin: mpd , saveMusicLibraryOptions May 17 21:54:32 volumio volumio[14752]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 17 21:54:36 volumio volumio[14752]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 17 21:54:40 volumio volumio[14752]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 17 21:54:43 volumio volumio[14752]: info: CoreCommandRouter::volumioGetState May 17 21:54:55 volumio volumio[14752]: info: CoreCommandRouter::volumioPlay May 17 21:54:55 volumio volumio[14752]: info: CoreStateMachine::play index 15 May 17 21:54:55 volumio volumio[14752]: info: CoreStateMachine::setConsumeUpdateService undefined May 17 21:54:55 volumio volumio[14752]: info: CoreStateMachine::stop May 17 21:54:55 volumio volumio[14752]: info: CoreStateMachine::setConsumeUpdateService undefined May 17 21:54:55 volumio volumio[14752]: info: CoreStateMachine::stPlaybackTimer May 17 21:54:55 volumio volumio[14752]: info: CoreStateMachine::updateTrackBlock May 17 21:54:55 volumio volumio[14752]: info: CorePlayQueue::getTrackBlock May 17 21:54:55 volumio volumio[14752]: info: CoreStateMachine::pushState May 17 21:54:55 volumio volumio[14752]: info: CorePlayQueue::getTrack 0 May 17 21:54:55 volumio volumio[14752]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 21:54:55 volumio volumio[14752]: info: CoreCommandRouter::volumioPushState May 17 21:54:55 volumio volumio[14752]: info: CoreStateMachine::serviceStop May 17 21:54:55 volumio volumio[14752]: info: CorePlayQueue::getTrack 0 May 17 21:54:55 volumio volumio[14752]: info: CoreCommandRouter::serviceStop May 17 21:54:55 volumio volumio[14752]: info: [1747490095914] ControllerUPNPBrowser::stop May 17 21:54:55 volumio volumio[14752]: verbose: ControllerMpd::sendMpdCommand stop May 17 21:54:55 volumio volumio[14752]: info: sendMpdCommand stop took 9 milliseconds May 17 21:54:55 volumio volumio[14752]: info: CoreStateMachine::play index undefined May 17 21:54:55 volumio volumio[14752]: info: CoreStateMachine::setConsumeUpdateService undefined May 17 21:54:55 volumio volumio[14752]: info: CorePlayQueue::getTrack 15 May 17 21:54:55 volumio volumio[14752]: info: CoreStateMachine::startPlaybackTimer May 17 21:54:55 volumio volumio[14752]: info: CorePlayQueue::getTrack 15 May 17 21:54:55 volumio volumio[14752]: info: [1747490095925] ControllerUPNPBrowser::clearAddPlayTrack May 17 21:54:55 volumio volumio[14752]: verbose: ControllerMpd::sendMpdCommand stop May 17 21:54:55 volumio volumio[14752]: info: May 17 21:54:55 volumio volumio[14752]: ---------------------------- MPD announces state update: player May 17 21:54:55 volumio volumio[14752]: info: ControllerMpd::getState May 17 21:54:55 volumio volumio[14752]: verbose: ControllerMpd::sendMpdCommand status May 17 21:54:55 volumio volumio[14752]: info: sendMpdCommand stop took 0 milliseconds May 17 21:54:55 volumio volumio[14752]: verbose: ControllerMpd::sendMpdCommand clear May 17 21:54:55 volumio volumio[14752]: info: May 17 21:54:55 volumio volumio[14752]: ---------------------------- MPD announces state update: player May 17 21:54:55 volumio volumio[14752]: info: ControllerMpd::getState May 17 21:54:55 volumio volumio[14752]: verbose: ControllerMpd::sendMpdCommand status May 17 21:54:55 volumio volumio[14752]: info: May 17 21:54:55 volumio volumio[14752]: ---------------------------- MPD announces state update: player May 17 21:54:55 volumio volumio[14752]: info: ControllerMpd::getState May 17 21:54:55 volumio volumio[14752]: verbose: ControllerMpd::sendMpdCommand status May 17 21:54:55 volumio volumio[14752]: info: May 17 21:54:55 volumio volumio[14752]: ---------------------------- MPD announces system playlist update May 17 21:54:55 volumio volumio[14752]: info: Ignoring MPD Status Update May 17 21:54:55 volumio volumio[14752]: info: sendMpdCommand status took 2 milliseconds May 17 21:54:55 volumio volumio[14752]: info: sendMpdCommand clear took 1 milliseconds May 17 21:54:55 volumio volumio[14752]: info: sendMpdCommand status took 1 milliseconds May 17 21:54:55 volumio volumio[14752]: info: sendMpdCommand status took 0 milliseconds May 17 21:54:55 volumio volumio[14752]: verbose: ControllerMpd::parseState May 17 21:54:55 volumio volumio[14752]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 21:54:55 volumio volumio[14752]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.46:8200/MediaItems/6505.wav" May 17 21:54:55 volumio volumio[14752]: verbose: ControllerMpd::parseState May 17 21:54:55 volumio volumio[14752]: verbose: ControllerMpd::parseState May 17 21:54:55 volumio volumio[14752]: info: ControllerMpd::pushState May 17 21:54:55 volumio volumio[14752]: info: CoreCommandRouter::servicePushState May 17 21:54:55 volumio volumio[14752]: info: CoreStateMachine::pushState May 17 21:54:55 volumio volumio[14752]: info: CorePlayQueue::getTrack 15 May 17 21:54:55 volumio volumio[14752]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 21:54:55 volumio volumio[14752]: info: CoreCommandRouter::volumioPushState May 17 21:54:55 volumio volumio[14752]: info: CorePlayQueue::getTrack 15 May 17 21:54:55 volumio volumio[14752]: 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 17 21:54:55 volumio volumio[14752]: verbose: CURRENT POSITION 15 May 17 21:54:55 volumio volumio[14752]: info: CoreStateMachine::syncState stateService stop May 17 21:54:55 volumio volumio[14752]: info: CoreStateMachine::syncState currentStatus stop May 17 21:54:55 volumio volumio[14752]: info: CoreStateMachine::pushState May 17 21:54:55 volumio volumio[14752]: info: CorePlayQueue::getTrack 15 May 17 21:54:55 volumio volumio[14752]: info: CoreCommandRouter::volumioPushState May 17 21:54:55 volumio volumio[14752]: info: No code May 17 21:54:55 volumio volumio[14752]: info: CoreStateMachine::pushState May 17 21:54:55 volumio volumio[14752]: info: CorePlayQueue::getTrack 15 May 17 21:54:55 volumio volumio[14752]: info: CoreCommandRouter::volumioPushState May 17 21:54:55 volumio volumio[14752]: info: ControllerMpd::pushState May 17 21:54:55 volumio volumio[14752]: info: CoreCommandRouter::servicePushState May 17 21:54:55 volumio volumio[14752]: info: CoreStateMachine::pushState May 17 21:54:55 volumio volumio[14752]: info: CorePlayQueue::getTrack 15 May 17 21:54:55 volumio volumio[14752]: info: CoreCommandRouter::volumioPushState May 17 21:54:55 volumio volumio[14752]: info: CorePlayQueue::getTrack 15 May 17 21:54:55 volumio volumio[14752]: 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 17 21:54:55 volumio volumio[14752]: verbose: CURRENT POSITION 15 May 17 21:54:55 volumio volumio[14752]: info: CoreStateMachine::syncState stateService stop May 17 21:54:55 volumio volumio[14752]: info: CoreStateMachine::syncState currentStatus stop May 17 21:54:55 volumio volumio[14752]: info: CoreStateMachine::pushState May 17 21:54:55 volumio volumio[14752]: info: CorePlayQueue::getTrack 15 May 17 21:54:55 volumio volumio[14752]: info: CoreCommandRouter::volumioPushState May 17 21:54:55 volumio volumio[14752]: info: No code May 17 21:54:55 volumio volumio[14752]: info: CoreStateMachine::pushState May 17 21:54:55 volumio volumio[14752]: info: CorePlayQueue::getTrack 15 May 17 21:54:55 volumio volumio[14752]: info: CoreCommandRouter::volumioPushState May 17 21:54:55 volumio volumio[14752]: info: ------------------------------ 15ms May 17 21:54:55 volumio volumio[14752]: info: ------------------------------ 16ms May 17 21:54:55 volumio volumio[14752]: info: May 17 21:54:55 volumio volumio[14752]: ---------------------------- MPD announces system playlist update May 17 21:54:55 volumio volumio[14752]: info: Ignoring MPD Status Update May 17 21:54:55 volumio volumio[14752]: info: May 17 21:54:55 volumio volumio[14752]: ---------------------------- MPD announces system playlist update May 17 21:54:55 volumio volumio[14752]: info: Ignoring MPD Status Update May 17 21:54:55 volumio volumio[14752]: error: updateQueue error: null May 17 21:54:55 volumio volumio[14752]: info: ------------------------------ 17ms May 17 21:54:55 volumio volumio[14752]: info: sendMpdCommand playlistinfo took 16 milliseconds May 17 21:54:55 volumio volumio[14752]: verbose: ControllerMpd::parseTrackInfo May 17 21:54:55 volumio volumio[14752]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 17 21:54:55 volumio volumio[14752]: info: ------------------------------ 20ms May 17 21:54:55 volumio volumio[14752]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.46:8200/MediaItems/6505.wav" May 17 21:54:55 volumio volumio[14752]: error: updateQueue error: null May 17 21:54:55 volumio volumio[14752]: error: updateQueue error: null May 17 21:54:55 volumio volumio[14752]: info: May 17 21:54:55 volumio volumio[14752]: ---------------------------- MPD announces system playlist update May 17 21:54:55 volumio volumio[14752]: info: Ignoring MPD Status Update May 17 21:54:55 volumio volumio[14752]: info: ------------------------------ 15ms May 17 21:54:55 volumio volumio[14752]: info: ------------------------------ 15ms May 17 21:54:55 volumio volumio[14752]: info: sendMpdCommand add "http://192.168.1.46:8200/MediaItems/6505.wav" took 1 milliseconds May 17 21:54:55 volumio volumio[14752]: info: CoreStateMachine::setConsumeUpdateService mpd May 17 21:54:55 volumio volumio[14752]: verbose: ControllerMpd::sendMpdCommand play May 17 21:54:55 volumio volumio[14752]: info: May 17 21:54:55 volumio volumio[14752]: ---------------------------- MPD announces system playlist update May 17 21:54:55 volumio volumio[14752]: info: Ignoring MPD Status Update May 17 21:54:55 volumio volumio[14752]: info: May 17 21:54:55 volumio volumio[14752]: ---------------------------- MPD announces system playlist update May 17 21:54:55 volumio volumio[14752]: info: Ignoring MPD Status Update May 17 21:54:55 volumio volumio[14752]: info: ------------------------------ 2ms May 17 21:54:55 volumio volumio[14752]: info: sendMpdCommand play took 1 milliseconds May 17 21:54:55 volumio volumio[14752]: info: ------------------------------ 1ms May 17 21:54:55 volumio volumio[14752]: info: ------------------------------ 1ms May 17 21:54:55 volumio volumio[14752]: info: May 17 21:54:55 volumio volumio[14752]: ---------------------------- MPD announces state update: player May 17 21:54:55 volumio volumio[14752]: info: ControllerMpd::getState May 17 21:54:55 volumio volumio[14752]: verbose: ControllerMpd::sendMpdCommand status May 17 21:54:55 volumio volumio[14752]: info: May 17 21:54:55 volumio volumio[14752]: ---------------------------- MPD announces state update: player May 17 21:54:55 volumio volumio[14752]: info: ControllerMpd::getState May 17 21:54:55 volumio volumio[14752]: verbose: ControllerMpd::sendMpdCommand status May 17 21:54:55 volumio volumio[14752]: info: May 17 21:54:55 volumio volumio[14752]: ---------------------------- MPD announces state update: player May 17 21:54:55 volumio volumio[14752]: info: ControllerMpd::getState May 17 21:54:55 volumio volumio[14752]: verbose: ControllerMpd::sendMpdCommand status May 17 21:54:55 volumio volumio[14752]: info: sendMpdCommand status took 3 milliseconds May 17 21:54:55 volumio volumio[14752]: verbose: ControllerMpd::parseState May 17 21:54:55 volumio volumio[14752]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 21:54:55 volumio volumio[14752]: info: May 17 21:54:55 volumio volumio[14752]: ---------------------------- MPD announces state update: player May 17 21:54:55 volumio volumio[14752]: info: ControllerMpd::getState May 17 21:54:55 volumio volumio[14752]: verbose: ControllerMpd::sendMpdCommand status May 17 21:54:55 volumio volumio[14752]: info: sendMpdCommand status took 4 milliseconds May 17 21:54:55 volumio volumio[14752]: info: sendMpdCommand status took 4 milliseconds May 17 21:54:55 volumio volumio[14752]: info: sendMpdCommand playlistinfo took 1 milliseconds May 17 21:54:55 volumio volumio[14752]: verbose: ControllerMpd::parseState May 17 21:54:55 volumio volumio[14752]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 21:54:55 volumio volumio[14752]: verbose: ControllerMpd::parseState May 17 21:54:55 volumio volumio[14752]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 21:54:55 volumio volumio[14752]: verbose: ControllerMpd::parseTrackInfo May 17 21:54:55 volumio volumio[14752]: info: ControllerMpd::pushState May 17 21:54:55 volumio volumio[14752]: info: CoreCommandRouter::servicePushState May 17 21:54:55 volumio volumio[14752]: info: CorePlayQueue::getTrack 15 May 17 21:54:55 volumio volumio[14752]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":201,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"6505.wav","artist":null,"album":null,"uri":"http://192.168.1.46:8200/MediaItems/6505.wav","trackType":"wav"} May 17 21:54:55 volumio volumio[14752]: verbose: CURRENT POSITION 15 May 17 21:54:55 volumio volumio[14752]: info: CoreStateMachine::syncState stateService play May 17 21:54:55 volumio volumio[14752]: info: CoreStateMachine::syncState currentStatus stop May 17 21:54:55 volumio volumio[14752]: info: ------------------------------ 6ms May 17 21:54:55 volumio volumio[14752]: info: May 17 21:54:55 volumio volumio[14752]: ---------------------------- MPD announces state update: player May 17 21:54:55 volumio volumio[14752]: info: ControllerMpd::getState May 17 21:54:55 volumio volumio[14752]: verbose: ControllerMpd::sendMpdCommand status May 17 21:54:55 volumio volumio[14752]: info: May 17 21:54:55 volumio volumio[14752]: ---------------------------- MPD announces state update: player May 17 21:54:55 volumio volumio[14752]: info: ControllerMpd::getState May 17 21:54:55 volumio volumio[14752]: verbose: ControllerMpd::sendMpdCommand status May 17 21:54:55 volumio volumio[14752]: info: sendMpdCommand status took 3 milliseconds May 17 21:54:55 volumio volumio[14752]: info: sendMpdCommand playlistinfo took 5 milliseconds May 17 21:54:55 volumio volumio[14752]: info: sendMpdCommand playlistinfo took 5 milliseconds May 17 21:54:55 volumio volumio[14752]: info: sendMpdCommand status took 3 milliseconds May 17 21:54:55 volumio volumio[14752]: verbose: ControllerMpd::parseState May 17 21:54:55 volumio volumio[14752]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 21:54:55 volumio volumio[14752]: verbose: ControllerMpd::parseTrackInfo May 17 21:54:55 volumio volumio[14752]: verbose: ControllerMpd::parseTrackInfo May 17 21:54:55 volumio volumio[14752]: verbose: ControllerMpd::parseState May 17 21:54:55 volumio volumio[14752]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 21:54:55 volumio volumio[14752]: info: ControllerMpd::pushState May 17 21:54:55 volumio volumio[14752]: info: CoreCommandRouter::servicePushState May 17 21:54:55 volumio volumio[14752]: info: CorePlayQueue::getTrack 15 May 17 21:54:55 volumio volumio[14752]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":201,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"6505.wav","artist":null,"album":null,"uri":"http://192.168.1.46:8200/MediaItems/6505.wav","trackType":"wav"} May 17 21:54:55 volumio volumio[14752]: verbose: CURRENT POSITION 15 May 17 21:54:55 volumio volumio[14752]: info: CoreStateMachine::syncState stateService play May 17 21:54:55 volumio volumio[14752]: info: CoreStateMachine::syncState currentStatus play May 17 21:54:55 volumio volumio[14752]: info: Received an update from plugin. extracting info from payload May 17 21:54:55 volumio volumio[14752]: info: CoreStateMachine::pushState May 17 21:54:55 volumio volumio[14752]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 21:54:55 volumio volumio[14752]: info: CoreCommandRouter::volumioPushState May 17 21:54:55 volumio volumio[14752]: info: CoreStateMachine::pushState May 17 21:54:55 volumio volumio[14752]: info: CoreCommandRouter::volumioPushState May 17 21:54:55 volumio volumio[14752]: info: ControllerMpd::pushState May 17 21:54:55 volumio volumio[14752]: info: CoreCommandRouter::servicePushState May 17 21:54:55 volumio volumio[14752]: info: CorePlayQueue::getTrack 15 May 17 21:54:55 volumio volumio[14752]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":201,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"6505.wav","artist":null,"album":null,"uri":"http://192.168.1.46:8200/MediaItems/6505.wav","trackType":"wav"} May 17 21:54:55 volumio volumio[14752]: verbose: CURRENT POSITION 15 May 17 21:54:55 volumio volumio[14752]: info: CoreStateMachine::syncState stateService play May 17 21:54:55 volumio volumio[14752]: info: CoreStateMachine::syncState currentStatus play May 17 21:54:55 volumio volumio[14752]: info: Received an update from plugin. extracting info from payload May 17 21:54:55 volumio volumio[14752]: info: CoreStateMachine::pushState May 17 21:54:55 volumio volumio[14752]: info: CoreCommandRouter::volumioPushState May 17 21:54:55 volumio volumio[14752]: info: CoreStateMachine::pushState May 17 21:54:55 volumio volumio[14752]: info: CoreCommandRouter::volumioPushState May 17 21:54:55 volumio volumio[14752]: info: ------------------------------ 21ms May 17 21:54:55 volumio volumio[14752]: info: ------------------------------ 22ms May 17 21:54:55 volumio volumio[14752]: info: sendMpdCommand status took 16 milliseconds May 17 21:54:55 volumio volumio[14752]: info: sendMpdCommand playlistinfo took 12 milliseconds May 17 21:54:55 volumio volumio[14752]: info: sendMpdCommand playlistinfo took 11 milliseconds May 17 21:54:55 volumio volumio[14752]: verbose: ControllerMpd::parseState May 17 21:54:55 volumio volumio[14752]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 21:54:55 volumio volumio[14752]: verbose: ControllerMpd::parseTrackInfo May 17 21:54:55 volumio volumio[14752]: verbose: ControllerMpd::parseTrackInfo May 17 21:54:55 volumio volumio[14752]: info: ControllerMpd::pushState May 17 21:54:55 volumio volumio[14752]: info: CoreCommandRouter::servicePushState May 17 21:54:55 volumio volumio[14752]: info: CorePlayQueue::getTrack 15 May 17 21:54:55 volumio volumio[14752]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":201,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"6505.wav","artist":null,"album":null,"uri":"http://192.168.1.46:8200/MediaItems/6505.wav","trackType":"wav"} May 17 21:54:55 volumio volumio[14752]: verbose: CURRENT POSITION 15 May 17 21:54:55 volumio volumio[14752]: info: CoreStateMachine::syncState stateService play May 17 21:54:55 volumio volumio[14752]: info: CoreStateMachine::syncState currentStatus play May 17 21:54:55 volumio volumio[14752]: info: Received an update from plugin. extracting info from payload May 17 21:54:55 volumio volumio[14752]: info: CoreStateMachine::pushState May 17 21:54:55 volumio volumio[14752]: info: CoreCommandRouter::volumioPushState May 17 21:54:55 volumio volumio[14752]: info: CoreStateMachine::pushState May 17 21:54:55 volumio volumio[14752]: info: CoreCommandRouter::volumioPushState May 17 21:54:55 volumio volumio[14752]: info: ControllerMpd::pushState May 17 21:54:55 volumio volumio[14752]: info: CoreCommandRouter::servicePushState May 17 21:54:55 volumio volumio[14752]: info: CorePlayQueue::getTrack 15 May 17 21:54:55 volumio volumio[14752]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":201,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"6505.wav","artist":null,"album":null,"uri":"http://192.168.1.46:8200/MediaItems/6505.wav","trackType":"wav"} May 17 21:54:55 volumio volumio[14752]: verbose: CURRENT POSITION 15 May 17 21:54:55 volumio volumio[14752]: info: CoreStateMachine::syncState stateService play May 17 21:54:55 volumio volumio[14752]: info: CoreStateMachine::syncState currentStatus play May 17 21:54:55 volumio volumio[14752]: info: Received an update from plugin. extracting info from payload May 17 21:54:55 volumio volumio[14752]: info: CoreStateMachine::pushState May 17 21:54:55 volumio volumio[14752]: info: CoreCommandRouter::volumioPushState May 17 21:54:55 volumio volumio[14752]: info: CoreStateMachine::pushState May 17 21:54:55 volumio volumio[14752]: info: CoreCommandRouter::volumioPushState May 17 21:54:56 volumio volumio[14752]: info: ------------------------------ 28ms May 17 21:54:56 volumio volumio[14752]: info: ------------------------------ 26ms May 17 21:54:56 volumio volumio[14752]: info: sendMpdCommand playlistinfo took 11 milliseconds May 17 21:54:56 volumio volumio[14752]: verbose: ControllerMpd::parseTrackInfo May 17 21:54:56 volumio volumio[14752]: info: ControllerMpd::pushState May 17 21:54:56 volumio volumio[14752]: info: CoreCommandRouter::servicePushState May 17 21:54:56 volumio volumio[14752]: info: CorePlayQueue::getTrack 15 May 17 21:54:56 volumio volumio[14752]: verbose: STATE SERVICE {"status":"play","position":0,"seek":137,"duration":201,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"6505.wav","artist":null,"album":null,"uri":"http://192.168.1.46:8200/MediaItems/6505.wav","trackType":"wav"} May 17 21:54:56 volumio volumio[14752]: verbose: CURRENT POSITION 15 May 17 21:54:56 volumio volumio[14752]: info: CoreStateMachine::syncState stateService play May 17 21:54:56 volumio volumio[14752]: info: CoreStateMachine::syncState currentStatus play May 17 21:54:56 volumio volumio[14752]: info: Received an update from plugin. extracting info from payload May 17 21:54:56 volumio volumio[14752]: info: CoreStateMachine::pushState May 17 21:54:56 volumio volumio[14752]: info: CoreCommandRouter::volumioPushState May 17 21:54:56 volumio volumio[14752]: info: CoreStateMachine::pushState May 17 21:54:56 volumio volumio[14752]: info: CoreCommandRouter::volumioPushState May 17 21:54:56 volumio volumio[14752]: info: ------------------------------ 32ms May 17 21:55:10 volumio volumio[14752]: info: CoreCommandRouter::volumioPlay May 17 21:55:10 volumio volumio[14752]: info: CoreStateMachine::play index 20 May 17 21:55:10 volumio volumio[14752]: info: CoreStateMachine::setConsumeUpdateService undefined May 17 21:55:10 volumio volumio[14752]: info: CoreStateMachine::stop May 17 21:55:10 volumio volumio[14752]: info: CoreStateMachine::setConsumeUpdateService undefined May 17 21:55:10 volumio volumio[14752]: info: CoreStateMachine::stPlaybackTimer May 17 21:55:10 volumio volumio[14752]: info: CoreStateMachine::updateTrackBlock May 17 21:55:10 volumio volumio[14752]: info: CorePlayQueue::getTrackBlock May 17 21:55:10 volumio volumio[14752]: info: CoreStateMachine::pushState May 17 21:55:10 volumio volumio[14752]: info: CorePlayQueue::getTrack 15 May 17 21:55:10 volumio volumio[14752]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 21:55:10 volumio volumio[14752]: info: CoreCommandRouter::volumioPushState May 17 21:55:10 volumio volumio[14752]: info: CoreStateMachine::serviceStop May 17 21:55:10 volumio volumio[14752]: info: CorePlayQueue::getTrack 15 May 17 21:55:10 volumio volumio[14752]: info: CoreCommandRouter::serviceStop May 17 21:55:10 volumio volumio[14752]: info: [1747490110832] ControllerUPNPBrowser::stop May 17 21:55:10 volumio volumio[14752]: verbose: ControllerMpd::sendMpdCommand stop May 17 21:55:10 volumio volumio[14752]: info: May 17 21:55:10 volumio volumio[14752]: ---------------------------- MPD announces state update: player May 17 21:55:10 volumio volumio[14752]: info: sendMpdCommand stop took 10 milliseconds May 17 21:55:10 volumio volumio[14752]: info: ControllerMpd::getState May 17 21:55:10 volumio volumio[14752]: verbose: ControllerMpd::sendMpdCommand status May 17 21:55:10 volumio volumio[14752]: info: CoreStateMachine::play index undefined May 17 21:55:10 volumio volumio[14752]: info: CoreStateMachine::setConsumeUpdateService undefined May 17 21:55:10 volumio volumio[14752]: info: CorePlayQueue::getTrack 20 May 17 21:55:10 volumio volumio[14752]: info: CoreStateMachine::startPlaybackTimer May 17 21:55:10 volumio volumio[14752]: info: CorePlayQueue::getTrack 20 May 17 21:55:10 volumio volumio[14752]: info: [1747490110843] ControllerUPNPBrowser::clearAddPlayTrack May 17 21:55:10 volumio volumio[14752]: verbose: ControllerMpd::sendMpdCommand stop May 17 21:55:10 volumio volumio[14752]: info: sendMpdCommand status took 4 milliseconds May 17 21:55:10 volumio volumio[14752]: info: sendMpdCommand stop took 3 milliseconds May 17 21:55:10 volumio volumio[14752]: verbose: ControllerMpd::parseState May 17 21:55:10 volumio volumio[14752]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 21:55:10 volumio volumio[14752]: verbose: ControllerMpd::sendMpdCommand clear May 17 21:55:10 volumio volumio[14752]: info: May 17 21:55:10 volumio volumio[14752]: ---------------------------- MPD announces state update: player May 17 21:55:10 volumio volumio[14752]: info: ControllerMpd::getState May 17 21:55:10 volumio volumio[14752]: verbose: ControllerMpd::sendMpdCommand status May 17 21:55:10 volumio volumio[14752]: info: May 17 21:55:10 volumio volumio[14752]: ---------------------------- MPD announces state update: player May 17 21:55:10 volumio volumio[14752]: info: ControllerMpd::getState May 17 21:55:10 volumio volumio[14752]: verbose: ControllerMpd::sendMpdCommand status May 17 21:55:10 volumio volumio[14752]: info: May 17 21:55:10 volumio volumio[14752]: ---------------------------- MPD announces system playlist update May 17 21:55:10 volumio volumio[14752]: info: Ignoring MPD Status Update May 17 21:55:10 volumio volumio[14752]: info: sendMpdCommand playlistinfo took 2 milliseconds May 17 21:55:10 volumio volumio[14752]: info: sendMpdCommand clear took 2 milliseconds May 17 21:55:10 volumio volumio[14752]: info: sendMpdCommand status took 1 milliseconds May 17 21:55:10 volumio volumio[14752]: info: sendMpdCommand status took 1 milliseconds May 17 21:55:10 volumio volumio[14752]: verbose: ControllerMpd::parseTrackInfo May 17 21:55:10 volumio volumio[14752]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.46:8200/MediaItems/6521.wav" May 17 21:55:10 volumio volumio[14752]: verbose: ControllerMpd::parseState May 17 21:55:10 volumio volumio[14752]: verbose: ControllerMpd::parseState May 17 21:55:10 volumio volumio[14752]: info: ControllerMpd::pushState May 17 21:55:10 volumio volumio[14752]: info: CoreCommandRouter::servicePushState May 17 21:55:10 volumio volumio[14752]: info: CoreStateMachine::pushState May 17 21:55:10 volumio volumio[14752]: info: CorePlayQueue::getTrack 20 May 17 21:55:10 volumio volumio[14752]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 21:55:10 volumio volumio[14752]: info: CoreCommandRouter::volumioPushState May 17 21:55:10 volumio volumio[14752]: info: CorePlayQueue::getTrack 20 May 17 21:55:10 volumio volumio[14752]: 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":"6505.wav","artist":null,"album":null,"uri":"http://192.168.1.46:8200/MediaItems/6505.wav","trackType":"wav"} May 17 21:55:10 volumio volumio[14752]: verbose: CURRENT POSITION 20 May 17 21:55:10 volumio volumio[14752]: info: CoreStateMachine::syncState stateService stop May 17 21:55:10 volumio volumio[14752]: info: CoreStateMachine::syncState currentStatus stop May 17 21:55:10 volumio volumio[14752]: info: CoreStateMachine::pushState May 17 21:55:10 volumio volumio[14752]: info: CorePlayQueue::getTrack 20 May 17 21:55:10 volumio volumio[14752]: info: CoreCommandRouter::volumioPushState May 17 21:55:10 volumio volumio[14752]: info: No code May 17 21:55:10 volumio volumio[14752]: info: CoreStateMachine::pushState May 17 21:55:10 volumio volumio[14752]: info: CorePlayQueue::getTrack 20 May 17 21:55:10 volumio volumio[14752]: info: CoreCommandRouter::volumioPushState May 17 21:55:10 volumio volumio[14752]: info: ControllerMpd::pushState May 17 21:55:10 volumio volumio[14752]: info: CoreCommandRouter::servicePushState May 17 21:55:10 volumio volumio[14752]: info: CoreStateMachine::pushState May 17 21:55:10 volumio volumio[14752]: info: CorePlayQueue::getTrack 20 May 17 21:55:10 volumio volumio[14752]: info: CoreCommandRouter::volumioPushState May 17 21:55:10 volumio volumio[14752]: info: CorePlayQueue::getTrack 20 May 17 21:55:10 volumio volumio[14752]: 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 17 21:55:10 volumio volumio[14752]: verbose: CURRENT POSITION 20 May 17 21:55:10 volumio volumio[14752]: info: CoreStateMachine::syncState stateService stop May 17 21:55:10 volumio volumio[14752]: info: CoreStateMachine::syncState currentStatus stop May 17 21:55:10 volumio volumio[14752]: info: CoreStateMachine::pushState May 17 21:55:10 volumio volumio[14752]: info: CorePlayQueue::getTrack 20 May 17 21:55:10 volumio volumio[14752]: info: CoreCommandRouter::volumioPushState May 17 21:55:10 volumio volumio[14752]: info: No code May 17 21:55:10 volumio volumio[14752]: info: CoreStateMachine::pushState May 17 21:55:10 volumio volumio[14752]: info: CorePlayQueue::getTrack 20 May 17 21:55:10 volumio volumio[14752]: info: CoreCommandRouter::volumioPushState May 17 21:55:10 volumio volumio[14752]: info: ControllerMpd::pushState May 17 21:55:10 volumio volumio[14752]: info: CoreCommandRouter::servicePushState May 17 21:55:10 volumio volumio[14752]: info: CoreStateMachine::pushState May 17 21:55:10 volumio volumio[14752]: info: CorePlayQueue::getTrack 20 May 17 21:55:10 volumio volumio[14752]: info: CoreCommandRouter::volumioPushState May 17 21:55:10 volumio volumio[14752]: info: CorePlayQueue::getTrack 20 May 17 21:55:10 volumio volumio[14752]: 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 17 21:55:10 volumio volumio[14752]: verbose: CURRENT POSITION 20 May 17 21:55:10 volumio volumio[14752]: info: CoreStateMachine::syncState stateService stop May 17 21:55:10 volumio volumio[14752]: info: CoreStateMachine::syncState currentStatus stop May 17 21:55:10 volumio volumio[14752]: info: CoreStateMachine::pushState May 17 21:55:10 volumio volumio[14752]: info: CorePlayQueue::getTrack 20 May 17 21:55:10 volumio volumio[14752]: info: CoreCommandRouter::volumioPushState May 17 21:55:10 volumio volumio[14752]: info: No code May 17 21:55:10 volumio volumio[14752]: info: CoreStateMachine::pushState May 17 21:55:10 volumio volumio[14752]: info: CorePlayQueue::getTrack 20 May 17 21:55:10 volumio volumio[14752]: info: CoreCommandRouter::volumioPushState May 17 21:55:10 volumio volumio[14752]: info: ------------------------------ 26ms May 17 21:55:10 volumio volumio[14752]: info: ------------------------------ 21ms May 17 21:55:10 volumio volumio[14752]: info: ------------------------------ 21ms May 17 21:55:10 volumio volumio[14752]: info: May 17 21:55:10 volumio volumio[14752]: ---------------------------- MPD announces system playlist update May 17 21:55:10 volumio volumio[14752]: info: Ignoring MPD Status Update May 17 21:55:10 volumio volumio[14752]: info: May 17 21:55:10 volumio volumio[14752]: ---------------------------- MPD announces system playlist update May 17 21:55:10 volumio volumio[14752]: info: Ignoring MPD Status Update May 17 21:55:10 volumio volumio[14752]: error: updateQueue error: null May 17 21:55:10 volumio volumio[14752]: info: ------------------------------ 22ms May 17 21:55:10 volumio volumio[14752]: error: updateQueue error: null May 17 21:55:10 volumio volumio[14752]: error: updateQueue error: null May 17 21:55:10 volumio volumio[14752]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.46:8200/MediaItems/6521.wav" May 17 21:55:10 volumio volumio[14752]: info: ------------------------------ 15ms May 17 21:55:10 volumio volumio[14752]: info: ------------------------------ 15ms May 17 21:55:10 volumio volumio[14752]: info: May 17 21:55:10 volumio volumio[14752]: ---------------------------- MPD announces system playlist update May 17 21:55:10 volumio volumio[14752]: info: Ignoring MPD Status Update May 17 21:55:10 volumio volumio[14752]: info: sendMpdCommand add "http://192.168.1.46:8200/MediaItems/6521.wav" took 0 milliseconds May 17 21:55:10 volumio volumio[14752]: info: CoreStateMachine::setConsumeUpdateService mpd May 17 21:55:10 volumio volumio[14752]: verbose: ControllerMpd::sendMpdCommand play May 17 21:55:10 volumio volumio[14752]: info: May 17 21:55:10 volumio volumio[14752]: ---------------------------- MPD announces system playlist update May 17 21:55:10 volumio volumio[14752]: info: Ignoring MPD Status Update May 17 21:55:10 volumio volumio[14752]: info: May 17 21:55:10 volumio volumio[14752]: ---------------------------- MPD announces system playlist update May 17 21:55:10 volumio volumio[14752]: info: Ignoring MPD Status Update May 17 21:55:10 volumio volumio[14752]: info: ------------------------------ 1ms May 17 21:55:10 volumio volumio[14752]: info: sendMpdCommand play took 0 milliseconds May 17 21:55:10 volumio volumio[14752]: info: ------------------------------ 1ms May 17 21:55:10 volumio volumio[14752]: info: ------------------------------ 1ms May 17 21:55:10 volumio volumio[14752]: info: May 17 21:55:10 volumio volumio[14752]: ---------------------------- MPD announces state update: player May 17 21:55:10 volumio volumio[14752]: info: ControllerMpd::getState May 17 21:55:10 volumio volumio[14752]: verbose: ControllerMpd::sendMpdCommand status May 17 21:55:10 volumio volumio[14752]: info: May 17 21:55:10 volumio volumio[14752]: ---------------------------- MPD announces state update: player May 17 21:55:10 volumio volumio[14752]: info: ControllerMpd::getState May 17 21:55:10 volumio volumio[14752]: verbose: ControllerMpd::sendMpdCommand status May 17 21:55:10 volumio volumio[14752]: info: May 17 21:55:10 volumio volumio[14752]: ---------------------------- MPD announces state update: player May 17 21:55:10 volumio volumio[14752]: info: ControllerMpd::getState May 17 21:55:10 volumio volumio[14752]: verbose: ControllerMpd::sendMpdCommand status May 17 21:55:10 volumio volumio[14752]: info: sendMpdCommand status took 3 milliseconds May 17 21:55:10 volumio volumio[14752]: verbose: ControllerMpd::parseState May 17 21:55:10 volumio volumio[14752]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 21:55:10 volumio volumio[14752]: info: May 17 21:55:10 volumio volumio[14752]: ---------------------------- MPD announces state update: player May 17 21:55:10 volumio volumio[14752]: info: ControllerMpd::getState May 17 21:55:10 volumio volumio[14752]: verbose: ControllerMpd::sendMpdCommand status May 17 21:55:10 volumio volumio[14752]: info: sendMpdCommand status took 3 milliseconds May 17 21:55:10 volumio volumio[14752]: info: sendMpdCommand status took 3 milliseconds May 17 21:55:10 volumio volumio[14752]: info: sendMpdCommand playlistinfo took 0 milliseconds May 17 21:55:10 volumio volumio[14752]: verbose: ControllerMpd::parseState May 17 21:55:10 volumio volumio[14752]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 21:55:10 volumio volumio[14752]: verbose: ControllerMpd::parseState May 17 21:55:10 volumio volumio[14752]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 21:55:10 volumio volumio[14752]: verbose: ControllerMpd::parseTrackInfo May 17 21:55:10 volumio volumio[14752]: info: ControllerMpd::pushState May 17 21:55:10 volumio volumio[14752]: info: CoreCommandRouter::servicePushState May 17 21:55:10 volumio volumio[14752]: info: CorePlayQueue::getTrack 20 May 17 21:55:10 volumio volumio[14752]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":230,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"6521.wav","artist":null,"album":null,"uri":"http://192.168.1.46:8200/MediaItems/6521.wav","trackType":"wav"} May 17 21:55:10 volumio volumio[14752]: verbose: CURRENT POSITION 20 May 17 21:55:10 volumio volumio[14752]: info: CoreStateMachine::syncState stateService play May 17 21:55:10 volumio volumio[14752]: info: CoreStateMachine::syncState currentStatus stop May 17 21:55:10 volumio volumio[14752]: info: ------------------------------ 5ms May 17 21:55:10 volumio volumio[14752]: info: May 17 21:55:10 volumio volumio[14752]: ---------------------------- MPD announces state update: player May 17 21:55:10 volumio volumio[14752]: info: ControllerMpd::getState May 17 21:55:10 volumio volumio[14752]: verbose: ControllerMpd::sendMpdCommand status May 17 21:55:10 volumio volumio[14752]: info: May 17 21:55:10 volumio volumio[14752]: ---------------------------- MPD announces state update: player May 17 21:55:10 volumio volumio[14752]: info: ControllerMpd::getState May 17 21:55:10 volumio volumio[14752]: verbose: ControllerMpd::sendMpdCommand status May 17 21:55:10 volumio volumio[14752]: info: sendMpdCommand status took 2 milliseconds May 17 21:55:10 volumio volumio[14752]: info: sendMpdCommand playlistinfo took 1 milliseconds May 17 21:55:10 volumio volumio[14752]: info: sendMpdCommand playlistinfo took 1 milliseconds May 17 21:55:10 volumio volumio[14752]: info: sendMpdCommand status took 1 milliseconds May 17 21:55:10 volumio volumio[14752]: info: sendMpdCommand status took 1 milliseconds May 17 21:55:10 volumio volumio[14752]: verbose: ControllerMpd::parseState May 17 21:55:10 volumio volumio[14752]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 21:55:10 volumio volumio[14752]: verbose: ControllerMpd::parseTrackInfo May 17 21:55:10 volumio volumio[14752]: verbose: ControllerMpd::parseTrackInfo May 17 21:55:10 volumio volumio[14752]: verbose: ControllerMpd::parseState May 17 21:55:10 volumio volumio[14752]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 21:55:10 volumio volumio[14752]: verbose: ControllerMpd::parseState May 17 21:55:10 volumio volumio[14752]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 21:55:10 volumio volumio[14752]: info: ControllerMpd::pushState May 17 21:55:10 volumio volumio[14752]: info: CoreCommandRouter::servicePushState May 17 21:55:10 volumio volumio[14752]: info: CorePlayQueue::getTrack 20 May 17 21:55:10 volumio volumio[14752]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":230,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"6521.wav","artist":null,"album":null,"uri":"http://192.168.1.46:8200/MediaItems/6521.wav","trackType":"wav"} May 17 21:55:10 volumio volumio[14752]: verbose: CURRENT POSITION 20 May 17 21:55:10 volumio volumio[14752]: info: CoreStateMachine::syncState stateService play May 17 21:55:10 volumio volumio[14752]: info: CoreStateMachine::syncState currentStatus play May 17 21:55:10 volumio volumio[14752]: info: Received an update from plugin. extracting info from payload May 17 21:55:10 volumio volumio[14752]: info: CoreStateMachine::pushState May 17 21:55:10 volumio volumio[14752]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 21:55:10 volumio volumio[14752]: info: CoreCommandRouter::volumioPushState May 17 21:55:10 volumio volumio[14752]: info: CoreStateMachine::pushState May 17 21:55:10 volumio volumio[14752]: info: CoreCommandRouter::volumioPushState May 17 21:55:10 volumio volumio[14752]: info: ControllerMpd::pushState May 17 21:55:10 volumio volumio[14752]: info: CoreCommandRouter::servicePushState May 17 21:55:10 volumio volumio[14752]: info: CorePlayQueue::getTrack 20 May 17 21:55:10 volumio volumio[14752]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":230,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"6521.wav","artist":null,"album":null,"uri":"http://192.168.1.46:8200/MediaItems/6521.wav","trackType":"wav"} May 17 21:55:10 volumio volumio[14752]: verbose: CURRENT POSITION 20 May 17 21:55:10 volumio volumio[14752]: info: CoreStateMachine::syncState stateService play May 17 21:55:10 volumio volumio[14752]: info: CoreStateMachine::syncState currentStatus play May 17 21:55:10 volumio volumio[14752]: info: Received an update from plugin. extracting info from payload May 17 21:55:10 volumio volumio[14752]: info: CoreStateMachine::pushState May 17 21:55:10 volumio volumio[14752]: info: CoreCommandRouter::volumioPushState May 17 21:55:10 volumio volumio[14752]: info: CoreStateMachine::pushState May 17 21:55:10 volumio volumio[14752]: info: CoreCommandRouter::volumioPushState May 17 21:55:10 volumio volumio[14752]: info: ------------------------------ 32ms May 17 21:55:10 volumio volumio[14752]: info: ------------------------------ 33ms May 17 21:55:10 volumio volumio[14752]: info: sendMpdCommand playlistinfo took 28 milliseconds May 17 21:55:10 volumio volumio[14752]: info: sendMpdCommand playlistinfo took 29 milliseconds May 17 21:55:10 volumio volumio[14752]: info: sendMpdCommand playlistinfo took 29 milliseconds May 17 21:55:10 volumio volumio[14752]: verbose: ControllerMpd::parseTrackInfo May 17 21:55:10 volumio volumio[14752]: verbose: ControllerMpd::parseTrackInfo May 17 21:55:10 volumio volumio[14752]: verbose: ControllerMpd::parseTrackInfo May 17 21:55:10 volumio volumio[14752]: info: ControllerMpd::pushState May 17 21:55:10 volumio volumio[14752]: info: CoreCommandRouter::servicePushState May 17 21:55:10 volumio volumio[14752]: info: CorePlayQueue::getTrack 20 May 17 21:55:10 volumio volumio[14752]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":230,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"6521.wav","artist":null,"album":null,"uri":"http://192.168.1.46:8200/MediaItems/6521.wav","trackType":"wav"} May 17 21:55:10 volumio volumio[14752]: verbose: CURRENT POSITION 20 May 17 21:55:10 volumio volumio[14752]: info: CoreStateMachine::syncState stateService play May 17 21:55:10 volumio volumio[14752]: info: CoreStateMachine::syncState currentStatus play May 17 21:55:10 volumio volumio[14752]: info: Received an update from plugin. extracting info from payload May 17 21:55:10 volumio volumio[14752]: info: CoreStateMachine::pushState May 17 21:55:10 volumio volumio[14752]: info: CoreCommandRouter::volumioPushState May 17 21:55:10 volumio volumio[14752]: info: CoreStateMachine::pushState May 17 21:55:10 volumio volumio[14752]: info: CoreCommandRouter::volumioPushState May 17 21:55:10 volumio volumio[14752]: info: ControllerMpd::pushState May 17 21:55:10 volumio volumio[14752]: info: CoreCommandRouter::servicePushState May 17 21:55:10 volumio volumio[14752]: info: CorePlayQueue::getTrack 20 May 17 21:55:10 volumio volumio[14752]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":230,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"6521.wav","artist":null,"album":null,"uri":"http://192.168.1.46:8200/MediaItems/6521.wav","trackType":"wav"} May 17 21:55:10 volumio volumio[14752]: verbose: CURRENT POSITION 20 May 17 21:55:10 volumio volumio[14752]: info: CoreStateMachine::syncState stateService play May 17 21:55:10 volumio volumio[14752]: info: CoreStateMachine::syncState currentStatus play May 17 21:55:10 volumio volumio[14752]: info: Received an update from plugin. extracting info from payload May 17 21:55:10 volumio volumio[14752]: info: CoreStateMachine::pushState May 17 21:55:10 volumio volumio[14752]: info: CoreCommandRouter::volumioPushState May 17 21:55:10 volumio volumio[14752]: info: CoreStateMachine::pushState May 17 21:55:10 volumio volumio[14752]: info: CoreCommandRouter::volumioPushState May 17 21:55:10 volumio volumio[14752]: info: ControllerMpd::pushState May 17 21:55:10 volumio volumio[14752]: info: CoreCommandRouter::servicePushState May 17 21:55:10 volumio volumio[14752]: info: CorePlayQueue::getTrack 20 May 17 21:55:10 volumio volumio[14752]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":230,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"6521.wav","artist":null,"album":null,"uri":"http://192.168.1.46:8200/MediaItems/6521.wav","trackType":"wav"} May 17 21:55:10 volumio volumio[14752]: verbose: CURRENT POSITION 20 May 17 21:55:10 volumio volumio[14752]: info: CoreStateMachine::syncState stateService play May 17 21:55:10 volumio volumio[14752]: info: CoreStateMachine::syncState currentStatus play May 17 21:55:10 volumio volumio[14752]: info: Received an update from plugin. extracting info from payload May 17 21:55:10 volumio volumio[14752]: info: CoreStateMachine::pushState May 17 21:55:10 volumio volumio[14752]: info: CoreCommandRouter::volumioPushState May 17 21:55:10 volumio volumio[14752]: info: CoreStateMachine::pushState May 17 21:55:10 volumio volumio[14752]: info: CoreCommandRouter::volumioPushState May 17 21:55:10 volumio volumio[14752]: info: ------------------------------ 66ms May 17 21:55:10 volumio volumio[14752]: info: ------------------------------ 66ms May 17 21:55:10 volumio volumio[14752]: info: ------------------------------ 66ms May 17 21:55:44 volumio volumio[14752]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 17 21:55:44 volumio volumio[14752]: Error: connect ETIMEDOUT 104.244.43.182:443 May 17 21:55:44 volumio volumio[14752]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { May 17 21:55:44 volumio volumio[14752]: errno: -110, May 17 21:55:44 volumio volumio[14752]: code: 'ETIMEDOUT', May 17 21:55:44 volumio volumio[14752]: syscall: 'connect', May 17 21:55:44 volumio volumio[14752]: address: '104.244.43.182', May 17 21:55:44 volumio volumio[14752]: port: 443 May 17 21:55:44 volumio volumio[14752]: } May 17 21:55:44 volumio volumio[14752]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 17 21:55:44 volumio sudo[15301]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-17 21:54 May 17 21:55:44 volumio sudo[15301]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"