-- Logs begin at Sat 2025-03-08 13:49:57 UTC, end at Sat 2025-03-08 15:16:51 UTC. -- Mar 08 15:15:00 volumio volumio[19799]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 08 15:15:00 volumio volumio[19799]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Mar 08 15:15:00 volumio volumio[19799]: info: CoreCommandRouter::volumioGetState Mar 08 15:15:00 volumio volumio[19799]: info: CorePlayQueue::getTrack 0 Mar 08 15:15:03 volumio volumio[19799]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Mar 08 15:15:03 volumio volumio[19799]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 15:15:03 volumio volumio[19799]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 08 15:15:03 volumio volumio[19799]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 15:15:03 volumio volumio[19799]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 08 15:15:05 volumio volumio[19799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 15:15:05 volumio volumio[19799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 15:15:05 volumio volumio[19799]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Mar 08 15:15:05 volumio volumio[19799]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 08 15:15:05 volumio volumio[19799]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 08 15:15:05 volumio volumio[19799]: info: CoreCommandRouter::volumioGetBrowseSources Mar 08 15:15:05 volumio volumio[19799]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 08 15:15:06 volumio volumio[19799]: error: MyVolumio Plugin failed to authenticate in a timely fashion Mar 08 15:15:06 volumio volumio[19799]: info: Completed starting MyVolumio Plugin Mar 08 15:15:06 volumio volumio[19799]: [Metrics] CommandRouter: 39s 973.29ms Mar 08 15:15:06 volumio volumio[19799]: info: CoreCommandRouter::volumiosetStartupVolume Mar 08 15:15:06 volumio volumio[19799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 15:15:06 volumio volumio[19799]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 15:15:06 volumio volumio[19799]: info: CoreCommandRouter::Close All Modals sent Mar 08 15:15:06 volumio volumio[19799]: info: CoreCommandRouter::Close All Modals sent Mar 08 15:15:06 volumio volumio[19799]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Mar 08 15:15:06 volumio volumio[19799]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card Mar 08 15:15:06 volumio volumio[19799]: aplay: main:828: audio open error: No such device Mar 08 15:15:07 volumio volumio[19799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Mar 08 15:15:07 volumio volumio[19799]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 08 15:15:07 volumio volumio[19799]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Mar 08 15:15:07 volumio volumio[19799]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Mar 08 15:15:08 volumio volumio[19799]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 08 15:15:08 volumio volumio[19799]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 08 15:15:08 volumio volumio[19799]: info: Discovery: Getting this device information Mar 08 15:15:08 volumio volumio[19799]: info: CoreCommandRouter::volumioGetState Mar 08 15:15:08 volumio volumio[19799]: info: CorePlayQueue::getTrack 0 Mar 08 15:15:08 volumio volumio[19799]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 08 15:15:08 volumio volumio[19799]: verbose: New Socket.io Connection to 192.168.1.251 from 192.168.1.68 UA: okhttp/4.9.2 Engine version: 3 Transport: websocket Total Clients: 5 Mar 08 15:15:08 volumio volumio[19799]: info: CoreCommandRouter::volumioGetState Mar 08 15:15:08 volumio volumio[19799]: info: CorePlayQueue::getTrack 0 Mar 08 15:15:08 volumio volumio[19799]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 08 15:15:08 volumio volumio[19799]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 08 15:15:11 volumio sudo[20107]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 08 15:15:11 volumio sudo[20107]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 15:15:11 volumio sudo[20109]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 08 15:15:11 volumio sudo[20109]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 15:15:11 volumio sudo[20107]: pam_unix(sudo:session): session closed for user root Mar 08 15:15:11 volumio sudo[20109]: pam_unix(sudo:session): session closed for user root Mar 08 15:15:11 volumio volumio[19799]: verbose: New Socket.io Connection to 192.168.1.251 from 192.168.1.68 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 6 Mar 08 15:15:11 volumio volumio[19799]: info: CoreCommandRouter::volumioGetState Mar 08 15:15:11 volumio volumio[19799]: info: CorePlayQueue::getTrack 0 Mar 08 15:15:11 volumio volumio[19799]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 08 15:15:11 volumio volumio[19799]: info: Listing playlists Mar 08 15:15:11 volumio volumio[19799]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 08 15:15:11 volumio volumio[19799]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 08 15:15:11 volumio volumio[19799]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 08 15:15:11 volumio volumio[19799]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 08 15:15:13 volumio volumio[19799]: info: BOOT COMPLETED Mar 08 15:15:14 volumio volumio[19799]: info: CoreCommandRouter::volumioGetQueue Mar 08 15:15:14 volumio volumio[19799]: info: CoreStateMachine::getQueue Mar 08 15:15:14 volumio volumio[19799]: info: CorePlayQueue::getQueue Mar 08 15:15:17 volumio volumio-remote-updater[778]: No test mode Mar 08 15:15:17 volumio volumio-remote-updater[778]: No alpha test mode Mar 08 15:15:17 volumio volumio[19799]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Mar 08 15:15:17 volumio volumio[19799]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

FIXES

 

NEW ADDITIONS

 

","title":"Update v3.795","updateavailable":true} Mar 08 15:15:17 volumio volumio[19799]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 08 15:15:24 volumio volumio[19799]: info: CoreCommandRouter::volumioPlay Mar 08 15:15:24 volumio volumio[19799]: info: CoreStateMachine::play index 18 Mar 08 15:15:24 volumio volumio[19799]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 15:15:24 volumio volumio[19799]: info: CoreStateMachine::stop Mar 08 15:15:24 volumio volumio[19799]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 15:15:24 volumio volumio[19799]: info: CoreStateMachine::play index undefined Mar 08 15:15:24 volumio volumio[19799]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 15:15:24 volumio volumio[19799]: info: CorePlayQueue::getTrack 18 Mar 08 15:15:24 volumio volumio[19799]: info: CoreStateMachine::startPlaybackTimer Mar 08 15:15:24 volumio volumio[19799]: info: CorePlayQueue::getTrack 18 Mar 08 15:15:24 volumio volumio[19799]: info: [1741446924039] ControllerUPNPBrowser::clearAddPlayTrack Mar 08 15:15:24 volumio volumio[19799]: verbose: ControllerMpd::sendMpdCommand stop Mar 08 15:15:24 volumio volumio[19799]: info: sendMpdCommand stop took 1 milliseconds Mar 08 15:15:24 volumio volumio[19799]: verbose: ControllerMpd::sendMpdCommand clear Mar 08 15:15:24 volumio volumio[19799]: info: Mar 08 15:15:24 volumio volumio[19799]: ---------------------------- MPD announces system playlist update Mar 08 15:15:24 volumio volumio[19799]: info: Ignoring MPD Status Update Mar 08 15:15:24 volumio volumio[19799]: info: sendMpdCommand clear took 3 milliseconds Mar 08 15:15:24 volumio volumio[19799]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.250:9000/disk/DLNA-PNMP3-OP01-FLAGS01700000/O0$1$8I3776524.mp3" Mar 08 15:15:24 volumio volumio[19799]: error: updateQueue error: null Mar 08 15:15:24 volumio volumio[19799]: info: ------------------------------ 4ms Mar 08 15:15:24 volumio volumio[19799]: info: Mar 08 15:15:24 volumio volumio[19799]: ---------------------------- MPD announces system playlist update Mar 08 15:15:24 volumio volumio[19799]: info: Ignoring MPD Status Update Mar 08 15:15:24 volumio volumio[19799]: info: Mar 08 15:15:24 volumio volumio[19799]: ---------------------------- MPD announces system playlist update Mar 08 15:15:24 volumio volumio[19799]: info: Ignoring MPD Status Update Mar 08 15:15:24 volumio volumio[19799]: error: updateQueue error: null Mar 08 15:15:24 volumio volumio[19799]: error: updateQueue error: null Mar 08 15:15:24 volumio volumio[19799]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.250:9000/disk/DLNA-PNMP3-OP01-FLAGS01700000/O0$1$8I3776524.mp3" Mar 08 15:15:24 volumio volumio[19799]: info: ------------------------------ 136ms Mar 08 15:15:24 volumio volumio[19799]: info: ------------------------------ 135ms Mar 08 15:15:24 volumio volumio[19799]: info: Mar 08 15:15:24 volumio volumio[19799]: ---------------------------- MPD announces system playlist update Mar 08 15:15:24 volumio volumio[19799]: info: Ignoring MPD Status Update Mar 08 15:15:24 volumio volumio[19799]: info: sendMpdCommand add "http://192.168.1.250:9000/disk/DLNA-PNMP3-OP01-FLAGS01700000/O0$1$8I3776524.mp3" took 4 milliseconds Mar 08 15:15:24 volumio volumio[19799]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 08 15:15:24 volumio volumio[19799]: verbose: ControllerMpd::sendMpdCommand play Mar 08 15:15:24 volumio volumio[19799]: info: Mar 08 15:15:24 volumio volumio[19799]: ---------------------------- MPD announces system playlist update Mar 08 15:15:24 volumio volumio[19799]: info: Ignoring MPD Status Update Mar 08 15:15:24 volumio volumio[19799]: info: Mar 08 15:15:24 volumio volumio[19799]: ---------------------------- MPD announces system playlist update Mar 08 15:15:24 volumio volumio[19799]: info: Ignoring MPD Status Update Mar 08 15:15:24 volumio volumio[19799]: info: ------------------------------ 11ms Mar 08 15:15:24 volumio volumio[19799]: info: sendMpdCommand play took 8 milliseconds Mar 08 15:15:24 volumio volumio[19799]: info: ------------------------------ 7ms Mar 08 15:15:24 volumio volumio[19799]: info: ------------------------------ 6ms Mar 08 15:15:24 volumio volumio[19799]: info: Mar 08 15:15:24 volumio volumio[19799]: ---------------------------- MPD announces state update: player Mar 08 15:15:24 volumio volumio[19799]: info: ControllerMpd::getState Mar 08 15:15:24 volumio volumio[19799]: verbose: ControllerMpd::sendMpdCommand status Mar 08 15:15:24 volumio volumio[19799]: info: Mar 08 15:15:24 volumio volumio[19799]: ---------------------------- MPD announces state update: player Mar 08 15:15:24 volumio volumio[19799]: info: ControllerMpd::getState Mar 08 15:15:24 volumio volumio[19799]: verbose: ControllerMpd::sendMpdCommand status Mar 08 15:15:24 volumio volumio[19799]: info: Mar 08 15:15:24 volumio volumio[19799]: ---------------------------- MPD announces state update: player Mar 08 15:15:24 volumio volumio[19799]: info: ControllerMpd::getState Mar 08 15:15:24 volumio volumio[19799]: verbose: ControllerMpd::sendMpdCommand status Mar 08 15:15:24 volumio volumio[19799]: info: Mar 08 15:15:24 volumio volumio[19799]: ---------------------------- MPD announces state update: player Mar 08 15:15:24 volumio volumio[19799]: info: ControllerMpd::getState Mar 08 15:15:24 volumio volumio[19799]: verbose: ControllerMpd::sendMpdCommand status Mar 08 15:15:24 volumio volumio[19799]: verbose: ControllerMpd::sendMpdCommand clearerror Mar 08 15:15:24 volumio volumio[19799]: info: sendMpdCommand status took 14 milliseconds Mar 08 15:15:24 volumio volumio[19799]: verbose: ControllerMpd::sendMpdCommand clearerror Mar 08 15:15:24 volumio volumio[19799]: info: sendMpdCommand status took 14 milliseconds Mar 08 15:15:24 volumio volumio[19799]: verbose: ControllerMpd::sendMpdCommand clearerror Mar 08 15:15:24 volumio volumio[19799]: info: sendMpdCommand status took 13 milliseconds Mar 08 15:15:24 volumio volumio[19799]: verbose: ControllerMpd::parseState Mar 08 15:15:24 volumio volumio[19799]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 15:15:24 volumio volumio[19799]: verbose: ControllerMpd::parseState Mar 08 15:15:24 volumio volumio[19799]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 15:15:24 volumio volumio[19799]: verbose: ControllerMpd::parseState Mar 08 15:15:24 volumio volumio[19799]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 15:15:24 volumio volumio[19799]: info: Mar 08 15:15:24 volumio volumio[19799]: ---------------------------- MPD announces state update: player Mar 08 15:15:24 volumio volumio[19799]: info: ControllerMpd::getState Mar 08 15:15:24 volumio volumio[19799]: verbose: ControllerMpd::sendMpdCommand status Mar 08 15:15:24 volumio volumio[19799]: info: Mar 08 15:15:24 volumio volumio[19799]: ---------------------------- MPD announces state update: player Mar 08 15:15:24 volumio volumio[19799]: info: ControllerMpd::getState Mar 08 15:15:24 volumio volumio[19799]: verbose: ControllerMpd::sendMpdCommand status Mar 08 15:15:24 volumio volumio[19799]: verbose: ControllerMpd::sendMpdCommand clearerror Mar 08 15:15:24 volumio volumio[19799]: info: sendMpdCommand status took 43 milliseconds Mar 08 15:15:24 volumio volumio[19799]: info: sendMpdCommand clearerror took 36 milliseconds Mar 08 15:15:24 volumio volumio[19799]: info: sendMpdCommand clearerror took 36 milliseconds Mar 08 15:15:24 volumio volumio[19799]: info: sendMpdCommand clearerror took 34 milliseconds Mar 08 15:15:24 volumio volumio[19799]: info: sendMpdCommand playlistinfo took 26 milliseconds Mar 08 15:15:24 volumio volumio[19799]: info: sendMpdCommand playlistinfo took 24 milliseconds Mar 08 15:15:24 volumio volumio[19799]: info: sendMpdCommand playlistinfo took 23 milliseconds Mar 08 15:15:24 volumio volumio[19799]: info: sendMpdCommand status took 19 milliseconds Mar 08 15:15:24 volumio volumio[19799]: info: sendMpdCommand status took 18 milliseconds Mar 08 15:15:24 volumio volumio[19799]: verbose: ControllerMpd::parseState Mar 08 15:15:24 volumio volumio[19799]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 15:15:24 volumio volumio[19799]: verbose: ControllerMpd::parseTrackInfo Mar 08 15:15:24 volumio volumio[19799]: verbose: ControllerMpd::parseTrackInfo Mar 08 15:15:24 volumio volumio[19799]: verbose: ControllerMpd::parseState Mar 08 15:15:24 volumio volumio[19799]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 15:15:24 volumio volumio[19799]: verbose: ControllerMpd::parseState Mar 08 15:15:24 volumio volumio[19799]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 15:15:24 volumio volumio[19799]: info: ControllerMpd::pushState Mar 08 15:15:24 volumio volumio[19799]: info: CoreCommandRouter::servicePushState Mar 08 15:15:24 volumio volumio[19799]: info: CorePlayQueue::getTrack 18 Mar 08 15:15:24 volumio volumio[19799]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":2,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"O0$1$8I3776524.mp3","artist":null,"album":null,"uri":"http://192.168.1.250:9000/disk/DLNA-PNMP3-OP01-FLAGS01700000/O0$1$8I3776524.mp3","trackType":"mp3"} Mar 08 15:15:24 volumio volumio[19799]: verbose: CURRENT POSITION 18 Mar 08 15:15:24 volumio volumio[19799]: info: CoreStateMachine::syncState stateService pause Mar 08 15:15:24 volumio volumio[19799]: info: CoreStateMachine::syncState currentStatus stop Mar 08 15:15:24 volumio volumio[19799]: info: CoreStateMachine::pushState Mar 08 15:15:24 volumio volumio[19799]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 15:15:24 volumio volumio[19799]: info: CoreCommandRouter::volumioPushState Mar 08 15:15:24 volumio volumio[19799]: info: ControllerMpd::pushState Mar 08 15:15:24 volumio volumio[19799]: info: CoreCommandRouter::servicePushState Mar 08 15:15:24 volumio volumio[19799]: info: CorePlayQueue::getTrack 18 Mar 08 15:15:24 volumio volumio[19799]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":2,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"O0$1$8I3776524.mp3","artist":null,"album":null,"uri":"http://192.168.1.250:9000/disk/DLNA-PNMP3-OP01-FLAGS01700000/O0$1$8I3776524.mp3","trackType":"mp3"} Mar 08 15:15:24 volumio volumio[19799]: verbose: CURRENT POSITION 18 Mar 08 15:15:24 volumio volumio[19799]: info: CoreStateMachine::syncState stateService pause Mar 08 15:15:24 volumio volumio[19799]: info: CoreStateMachine::syncState currentStatus stop Mar 08 15:15:24 volumio volumio[19799]: info: CoreStateMachine::pushState Mar 08 15:15:24 volumio volumio[19799]: info: CoreCommandRouter::volumioPushState Mar 08 15:15:24 volumio volumio[19799]: info: ControllerMpd::pushState Mar 08 15:15:24 volumio volumio[19799]: info: CoreCommandRouter::servicePushState Mar 08 15:15:24 volumio volumio[19799]: info: CorePlayQueue::getTrack 18 Mar 08 15:15:24 volumio volumio[19799]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":2,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"O0$1$8I3776524.mp3","artist":null,"album":null,"uri":"http://192.168.1.250:9000/disk/DLNA-PNMP3-OP01-FLAGS01700000/O0$1$8I3776524.mp3","trackType":"mp3"} Mar 08 15:15:24 volumio volumio[19799]: verbose: CURRENT POSITION 18 Mar 08 15:15:24 volumio volumio[19799]: info: CoreStateMachine::syncState stateService pause Mar 08 15:15:24 volumio volumio[19799]: info: CoreStateMachine::syncState currentStatus stop Mar 08 15:15:24 volumio volumio[19799]: info: CoreStateMachine::pushState Mar 08 15:15:24 volumio volumio[19799]: info: CoreCommandRouter::volumioPushState Mar 08 15:15:24 volumio volumio[19799]: info: ------------------------------ 114ms Mar 08 15:15:24 volumio volumio[19799]: info: ------------------------------ 112ms Mar 08 15:15:24 volumio volumio[19799]: info: ------------------------------ 111ms Mar 08 15:15:24 volumio volumio[19799]: info: sendMpdCommand clearerror took 72 milliseconds Mar 08 15:15:24 volumio volumio[19799]: info: sendMpdCommand playlistinfo took 64 milliseconds Mar 08 15:15:24 volumio volumio[19799]: info: sendMpdCommand playlistinfo took 60 milliseconds Mar 08 15:15:24 volumio volumio[19799]: info: sendMpdCommand playlistinfo took 58 milliseconds Mar 08 15:15:24 volumio volumio[19799]: verbose: ControllerMpd::parseTrackInfo Mar 08 15:15:24 volumio volumio[19799]: verbose: ControllerMpd::parseTrackInfo Mar 08 15:15:24 volumio volumio[19799]: verbose: ControllerMpd::parseTrackInfo Mar 08 15:15:24 volumio volumio[19799]: info: ControllerMpd::pushState Mar 08 15:15:24 volumio volumio[19799]: info: CoreCommandRouter::servicePushState Mar 08 15:15:24 volumio volumio[19799]: info: CorePlayQueue::getTrack 18 Mar 08 15:15:24 volumio volumio[19799]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":2,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"O0$1$8I3776524.mp3","artist":null,"album":null,"uri":"http://192.168.1.250:9000/disk/DLNA-PNMP3-OP01-FLAGS01700000/O0$1$8I3776524.mp3","trackType":"mp3"} Mar 08 15:15:24 volumio volumio[19799]: verbose: CURRENT POSITION 18 Mar 08 15:15:24 volumio volumio[19799]: info: CoreStateMachine::syncState stateService pause Mar 08 15:15:24 volumio volumio[19799]: info: CoreStateMachine::syncState currentStatus stop Mar 08 15:15:24 volumio volumio[19799]: info: CoreStateMachine::pushState Mar 08 15:15:24 volumio volumio[19799]: info: CoreCommandRouter::volumioPushState Mar 08 15:15:24 volumio volumio[19799]: info: ControllerMpd::pushState Mar 08 15:15:24 volumio volumio[19799]: info: CoreCommandRouter::servicePushState Mar 08 15:15:24 volumio volumio[19799]: info: CorePlayQueue::getTrack 18 Mar 08 15:15:24 volumio volumio[19799]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":2,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"O0$1$8I3776524.mp3","artist":null,"album":null,"uri":"http://192.168.1.250:9000/disk/DLNA-PNMP3-OP01-FLAGS01700000/O0$1$8I3776524.mp3","trackType":"mp3"} Mar 08 15:15:24 volumio volumio[19799]: verbose: CURRENT POSITION 18 Mar 08 15:15:24 volumio volumio[19799]: info: CoreStateMachine::syncState stateService pause Mar 08 15:15:24 volumio volumio[19799]: info: CoreStateMachine::syncState currentStatus stop Mar 08 15:15:24 volumio volumio[19799]: info: CoreStateMachine::pushState Mar 08 15:15:24 volumio volumio[19799]: info: CoreCommandRouter::volumioPushState Mar 08 15:15:24 volumio volumio[19799]: info: ControllerMpd::pushState Mar 08 15:15:24 volumio volumio[19799]: info: CoreCommandRouter::servicePushState Mar 08 15:15:24 volumio volumio[19799]: info: CorePlayQueue::getTrack 18 Mar 08 15:15:24 volumio volumio[19799]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":2,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"O0$1$8I3776524.mp3","artist":null,"album":null,"uri":"http://192.168.1.250:9000/disk/DLNA-PNMP3-OP01-FLAGS01700000/O0$1$8I3776524.mp3","trackType":"mp3"} Mar 08 15:15:24 volumio volumio[19799]: verbose: CURRENT POSITION 18 Mar 08 15:15:24 volumio volumio[19799]: info: CoreStateMachine::syncState stateService pause Mar 08 15:15:24 volumio volumio[19799]: info: CoreStateMachine::syncState currentStatus stop Mar 08 15:15:24 volumio volumio[19799]: info: CoreStateMachine::pushState Mar 08 15:15:24 volumio volumio[19799]: info: CoreCommandRouter::volumioPushState Mar 08 15:15:24 volumio volumio[19799]: info: ------------------------------ 151ms Mar 08 15:15:24 volumio volumio[19799]: info: ------------------------------ 125ms Mar 08 15:15:24 volumio volumio[19799]: info: ------------------------------ 122ms Mar 08 15:15:47 volumio volumio[19799]: info: UPDATER: Scheduling automatic update Mar 08 15:15:47 volumio volumio[19799]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStartTime Mar 08 15:15:47 volumio volumio[19799]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStopTime Mar 08 15:15:47 volumio volumio[19799]: info: UPDATER: Auto update will take place at: Sun Mar 09 2025 04:54:28 GMT+0000 (Coordinated Universal Time) Mar 08 15:16:10 volumio kernel: usb 1-3: new high-speed USB device number 2 using xhci_hcd Mar 08 15:16:10 volumio kernel: usb 1-3: New USB device found, idVendor=16d0, idProduct=0a23, bcdDevice= 1.9b Mar 08 15:16:10 volumio kernel: usb 1-3: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Mar 08 15:16:10 volumio kernel: usb 1-3: Product: Combo384 Amanero Mar 08 15:16:10 volumio kernel: usb 1-3: Manufacturer: Amanero Technologies Mar 08 15:16:10 volumio kernel: usb 1-3: SerialNumber: 415-001 Mar 08 15:16:10 volumio kernel: usb 1-3: Warning! Unlikely big volume range (=32767), cval->res is probably wrong. Mar 08 15:16:10 volumio kernel: usb 1-3: [10] FU [PCM Playback Volume] ch = 2, val = -32767/0/1 Mar 08 15:16:10 volumio kernel: usb 1-3: Warning! Unlikely big volume range (=32767), cval->res is probably wrong. Mar 08 15:16:10 volumio kernel: usb 1-3: [10] FU [PCM Playback Volume] ch = 1, val = -32767/0/1 Mar 08 15:16:10 volumio kernel: usbcore: registered new interface driver snd-usb-audio Mar 08 15:16:10 volumio volumio[19799]: info: Mar 08 15:16:10 volumio volumio[19799]: ---------------------------- USB Audio Device Attached Mar 08 15:16:10 volumio volumio[19799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Mar 08 15:16:10 volumio volumio[19799]: info: CoreCommandRouter::Close All Modals sent Mar 08 15:16:10 volumio volumio[19799]: info: Preparing to save Alsa Options, stopping services first Mar 08 15:16:10 volumio volumio[19799]: info: CoreCommandRouter::volumioGetState Mar 08 15:16:10 volumio volumio[19799]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"Combo384 Amanero","alsacard":"Amanero"},"i2s":false} Mar 08 15:16:10 volumio volumio[19799]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 08 15:16:10 volumio volumio[19799]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 08 15:16:10 volumio volumio[19799]: info: Setting mixer PCM for card Combo384 Amanero Mar 08 15:16:10 volumio volumio[19799]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 08 15:16:10 volumio volumio[19799]: info: Updating Volume Controller Parameters: Device: 5 Name: Combo384 Amanero Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Mar 08 15:16:10 volumio volumio[19799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Mar 08 15:16:10 volumio volumio[19799]: info: Disabling external Volume Control Mar 08 15:16:10 volumio volumio[19799]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 08 15:16:10 volumio volumio[19799]: info: Preparing to generate the ALSA configuration file Mar 08 15:16:10 volumio volumio[19799]: info: Asound.conf file unchanged, so no further update is needed Mar 08 15:16:10 volumio volumio[19799]: info: Output device has changed, restarting MPD Mar 08 15:16:10 volumio sudo[20302]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 08 15:16:10 volumio sudo[20302]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 15:16:10 volumio volumio[19799]: info: Output device has changed, restarting Shairport Sync Mar 08 15:16:10 volumio volumio[19799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 15:16:10 volumio volumio[19799]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 15:16:10 volumio sudo[20302]: pam_unix(sudo:session): session closed for user root Mar 08 15:16:11 volumio sudo[20305]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 08 15:16:11 volumio sudo[20305]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 15:16:11 volumio systemd[1]: Stopping Music Player Daemon... Mar 08 15:16:11 volumio volumio[19799]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 08 15:16:11 volumio systemd[1]: mpd.service: Succeeded. Mar 08 15:16:11 volumio systemd[1]: Stopped Music Player Daemon. Mar 08 15:16:11 volumio systemd[1]: Starting Music Player Daemon... Mar 08 15:16:11 volumio volumio[19799]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 08 15:16:11 volumio volumio[19799]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 15:16:11 volumio volumio[19799]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 08 15:16:11 volumio volumio[19799]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 08 15:16:11 volumio volumio[19799]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 08 15:16:11 volumio volumio[19799]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 08 15:16:11 volumio volumio[19799]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 08 15:16:11 volumio volumio[19799]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 08 15:16:11 volumio volumio[19799]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 08 15:16:11 volumio volumio[19799]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 08 15:16:11 volumio volumio[19799]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 08 15:16:11 volumio volumio[19799]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 08 15:16:11 volumio sudo[20311]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 08 15:16:11 volumio sudo[20311]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 15:16:11 volumio sudo[20311]: pam_unix(sudo:session): session closed for user root Mar 08 15:16:11 volumio volumio[19799]: info: MPD Permissions set Mar 08 15:16:11 volumio volumio[19799]: info: VolumeController:: Volume=0 Mute =false Mar 08 15:16:11 volumio volumio[19799]: info: CoreStateMachine::pushState Mar 08 15:16:11 volumio volumio[19799]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 15:16:11 volumio volumio[19799]: info: CoreCommandRouter::volumioPushState Mar 08 15:16:11 volumio volumio[19799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 15:16:11 volumio volumio[19799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 15:16:11 volumio volumio[19799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 15:16:11 volumio volumio[19799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 15:16:11 volumio volumio[19799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 15:16:11 volumio volumio[19799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 15:16:11 volumio volumio[19799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 15:16:11 volumio volumio[19799]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 15:16:11 volumio volumio[19799]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 08 15:16:11 volumio volumio[19799]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 15:16:11 volumio volumio[19799]: info: Starting Shairport Sync Mar 08 15:16:11 volumio sudo[20325]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 08 15:16:11 volumio sudo[20325]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 15:16:11 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 08 15:16:11 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 08 15:16:11 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 08 15:16:11 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 08 15:16:11 volumio sudo[20325]: pam_unix(sudo:session): session closed for user root Mar 08 15:16:11 volumio volumio[19799]: info: Shairport-Sync Started Mar 08 15:16:11 volumio volumio[19799]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Mar 08 15:16:12 volumio mpd[20317]: Mar 08 15:16 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 08 15:16:12 volumio systemd[1]: Started Music Player Daemon. Mar 08 15:16:12 volumio sudo[20305]: pam_unix(sudo:session): session closed for user root Mar 08 15:16:12 volumio volumio[19799]: error: updateQueue error: null Mar 08 15:16:14 volumio volumio[19799]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Mar 08 15:16:14 volumio volumio[19799]: info: CoreStateMachine::getcurrentVolume Mar 08 15:16:14 volumio volumio[19799]: info: CoreCommandRouter::volumioRetrievevolume Mar 08 15:16:14 volumio volumio[19799]: info: VolumeController:: Volume=0 Mute =false Mar 08 15:16:14 volumio volumio[19799]: info: CoreStateMachine::pushState Mar 08 15:16:14 volumio volumio[19799]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 15:16:14 volumio volumio[19799]: info: CoreCommandRouter::volumioPushState Mar 08 15:16:14 volumio volumio[19799]: info: CoreStateMachine::updateTrackBlock Mar 08 15:16:14 volumio volumio[19799]: info: CorePlayQueue::getTrackBlock Mar 08 15:16:14 volumio volumio[19799]: info: CoreCommandRouter::volumioRetrievevolume Mar 08 15:16:15 volumio volumio[19799]: info: VolumeController:: Volume=0 Mute =false Mar 08 15:16:15 volumio volumio[19799]: info: CoreStateMachine::pushState Mar 08 15:16:15 volumio volumio[19799]: info: CoreCommandRouter::volumioPushState Mar 08 15:16:25 volumio volumio[19799]: info: CoreCommandRouter::volumioPlay Mar 08 15:16:25 volumio volumio[19799]: info: CoreStateMachine::play index 18 Mar 08 15:16:25 volumio volumio[19799]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 15:16:25 volumio volumio[19799]: info: CoreStateMachine::stop Mar 08 15:16:25 volumio volumio[19799]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 15:16:25 volumio volumio[19799]: info: CoreStateMachine::play index undefined Mar 08 15:16:25 volumio volumio[19799]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 15:16:25 volumio volumio[19799]: info: CorePlayQueue::getTrack 18 Mar 08 15:16:25 volumio volumio[19799]: info: CoreStateMachine::startPlaybackTimer Mar 08 15:16:25 volumio volumio[19799]: info: CorePlayQueue::getTrack 18 Mar 08 15:16:25 volumio volumio[19799]: info: [1741446985211] ControllerUPNPBrowser::clearAddPlayTrack Mar 08 15:16:25 volumio volumio[19799]: verbose: ControllerMpd::sendMpdCommand stop Mar 08 15:16:25 volumio volumio[19799]: info: sendMpdCommand stop took 7 milliseconds Mar 08 15:16:25 volumio volumio[19799]: verbose: ControllerMpd::sendMpdCommand clear Mar 08 15:16:25 volumio volumio[19799]: info: Mar 08 15:16:25 volumio volumio[19799]: ---------------------------- MPD announces system playlist update Mar 08 15:16:25 volumio volumio[19799]: info: Ignoring MPD Status Update Mar 08 15:16:25 volumio volumio[19799]: info: sendMpdCommand clear took 4 milliseconds Mar 08 15:16:25 volumio volumio[19799]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.250:9000/disk/DLNA-PNMP3-OP01-FLAGS01700000/O0$1$8I3776524.mp3" Mar 08 15:16:25 volumio volumio[19799]: error: updateQueue error: null Mar 08 15:16:25 volumio volumio[19799]: info: ------------------------------ 543ms Mar 08 15:16:25 volumio volumio[19799]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.250:9000/disk/DLNA-PNMP3-OP01-FLAGS01700000/O0$1$8I3776524.mp3" Mar 08 15:16:25 volumio volumio[19799]: info: Mar 08 15:16:25 volumio volumio[19799]: ---------------------------- MPD announces system playlist update Mar 08 15:16:25 volumio volumio[19799]: info: Ignoring MPD Status Update Mar 08 15:16:25 volumio volumio[19799]: info: sendMpdCommand add "http://192.168.1.250:9000/disk/DLNA-PNMP3-OP01-FLAGS01700000/O0$1$8I3776524.mp3" took 2 milliseconds Mar 08 15:16:25 volumio volumio[19799]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 08 15:16:25 volumio volumio[19799]: verbose: ControllerMpd::sendMpdCommand play Mar 08 15:16:25 volumio volumio[19799]: info: ------------------------------ 3ms Mar 08 15:16:25 volumio volumio[19799]: info: sendMpdCommand play took 2 milliseconds Mar 08 15:16:25 volumio volumio[19799]: info: Mar 08 15:16:25 volumio volumio[19799]: ---------------------------- MPD announces state update: player Mar 08 15:16:25 volumio volumio[19799]: info: ControllerMpd::getState Mar 08 15:16:25 volumio volumio[19799]: verbose: ControllerMpd::sendMpdCommand status Mar 08 15:16:25 volumio volumio[19799]: info: Mar 08 15:16:25 volumio volumio[19799]: ---------------------------- MPD announces state update: player Mar 08 15:16:25 volumio volumio[19799]: info: sendMpdCommand status took 47 milliseconds Mar 08 15:16:25 volumio volumio[19799]: info: ControllerMpd::getState Mar 08 15:16:25 volumio volumio[19799]: verbose: ControllerMpd::sendMpdCommand status Mar 08 15:16:25 volumio volumio[19799]: verbose: ControllerMpd::parseState Mar 08 15:16:25 volumio volumio[19799]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 15:16:25 volumio volumio[19799]: info: sendMpdCommand status took 2 milliseconds Mar 08 15:16:25 volumio volumio[19799]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 08 15:16:25 volumio volumio[19799]: verbose: ControllerMpd::parseState Mar 08 15:16:25 volumio volumio[19799]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 15:16:25 volumio volumio[19799]: verbose: ControllerMpd::parseTrackInfo Mar 08 15:16:25 volumio volumio[19799]: info: ControllerMpd::pushState Mar 08 15:16:25 volumio volumio[19799]: info: CoreCommandRouter::servicePushState Mar 08 15:16:25 volumio volumio[19799]: info: CorePlayQueue::getTrack 18 Mar 08 15:16:25 volumio volumio[19799]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":2,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"O0$1$8I3776524.mp3","artist":null,"album":null,"uri":"http://192.168.1.250:9000/disk/DLNA-PNMP3-OP01-FLAGS01700000/O0$1$8I3776524.mp3","trackType":"mp3"} Mar 08 15:16:25 volumio volumio[19799]: verbose: CURRENT POSITION 18 Mar 08 15:16:25 volumio volumio[19799]: info: CoreStateMachine::syncState stateService play Mar 08 15:16:25 volumio volumio[19799]: info: CoreStateMachine::syncState currentStatus stop Mar 08 15:16:25 volumio volumio[19799]: info: ------------------------------ 54ms Mar 08 15:16:25 volumio volumio[19799]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 08 15:16:25 volumio volumio[19799]: verbose: ControllerMpd::parseTrackInfo Mar 08 15:16:25 volumio volumio[19799]: info: ControllerMpd::pushState Mar 08 15:16:25 volumio volumio[19799]: info: CoreCommandRouter::servicePushState Mar 08 15:16:25 volumio volumio[19799]: info: CorePlayQueue::getTrack 18 Mar 08 15:16:25 volumio volumio[19799]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":2,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"O0$1$8I3776524.mp3","artist":null,"album":null,"uri":"http://192.168.1.250:9000/disk/DLNA-PNMP3-OP01-FLAGS01700000/O0$1$8I3776524.mp3","trackType":"mp3"} Mar 08 15:16:25 volumio volumio[19799]: verbose: CURRENT POSITION 18 Mar 08 15:16:25 volumio volumio[19799]: info: CoreStateMachine::syncState stateService play Mar 08 15:16:25 volumio volumio[19799]: info: CoreStateMachine::syncState currentStatus play Mar 08 15:16:25 volumio volumio[19799]: info: Received an update from plugin. extracting info from payload Mar 08 15:16:25 volumio volumio[19799]: info: CoreStateMachine::pushState Mar 08 15:16:25 volumio volumio[19799]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 15:16:25 volumio volumio[19799]: info: CoreCommandRouter::volumioPushState Mar 08 15:16:25 volumio volumio[19799]: info: CoreStateMachine::pushState Mar 08 15:16:25 volumio volumio[19799]: info: CoreCommandRouter::volumioPushState Mar 08 15:16:25 volumio volumio[19799]: info: ------------------------------ 29ms Mar 08 15:16:28 volumio volumio[19799]: info: Mar 08 15:16:28 volumio volumio[19799]: ---------------------------- MPD announces state update: player Mar 08 15:16:28 volumio volumio[19799]: info: ControllerMpd::getState Mar 08 15:16:28 volumio volumio[19799]: verbose: ControllerMpd::sendMpdCommand status Mar 08 15:16:28 volumio volumio[19799]: info: sendMpdCommand status took 1 milliseconds Mar 08 15:16:28 volumio volumio[19799]: verbose: ControllerMpd::parseState Mar 08 15:16:28 volumio volumio[19799]: info: ControllerMpd::pushState Mar 08 15:16:28 volumio volumio[19799]: info: CoreCommandRouter::servicePushState Mar 08 15:16:28 volumio volumio[19799]: info: CoreStateMachine::pushState Mar 08 15:16:28 volumio volumio[19799]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 15:16:28 volumio volumio[19799]: info: CoreCommandRouter::volumioPushState Mar 08 15:16:28 volumio volumio[19799]: info: CorePlayQueue::getTrack 18 Mar 08 15:16:28 volumio volumio[19799]: 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} Mar 08 15:16:28 volumio volumio[19799]: verbose: CURRENT POSITION 18 Mar 08 15:16:28 volumio volumio[19799]: info: CoreStateMachine::syncState stateService stop Mar 08 15:16:28 volumio volumio[19799]: info: CoreStateMachine::syncState currentStatus play Mar 08 15:16:28 volumio volumio[19799]: info: CoreStateMachine::play index undefined Mar 08 15:16:28 volumio volumio[19799]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 15:16:28 volumio volumio[19799]: info: CoreStateMachine::pushState Mar 08 15:16:28 volumio volumio[19799]: info: CorePlayQueue::getTrack 77 Mar 08 15:16:28 volumio volumio[19799]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 15:16:28 volumio volumio[19799]: info: CoreCommandRouter::volumioPushState Mar 08 15:16:28 volumio volumio[19799]: info: CorePlayQueue::getTrack 77 Mar 08 15:16:28 volumio volumio[19799]: info: CoreStateMachine::startPlaybackTimer Mar 08 15:16:28 volumio volumio[19799]: info: CorePlayQueue::getTrack 77 Mar 08 15:16:28 volumio volumio[19799]: info: [1741446988194] ControllerUPNPBrowser::clearAddPlayTrack Mar 08 15:16:28 volumio volumio[19799]: verbose: ControllerMpd::sendMpdCommand stop Mar 08 15:16:28 volumio volumio[19799]: info: ------------------------------ 45ms Mar 08 15:16:28 volumio volumio[19799]: info: CoreStateMachine::pushState Mar 08 15:16:28 volumio volumio[19799]: info: CorePlayQueue::getTrack 77 Mar 08 15:16:28 volumio volumio[19799]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 15:16:28 volumio volumio[19799]: info: CoreCommandRouter::volumioPushState Mar 08 15:16:28 volumio volumio[19799]: info: sendMpdCommand stop took 18 milliseconds Mar 08 15:16:28 volumio volumio[19799]: verbose: ControllerMpd::sendMpdCommand clear Mar 08 15:16:28 volumio volumio[19799]: info: Mar 08 15:16:28 volumio volumio[19799]: ---------------------------- MPD announces system playlist update Mar 08 15:16:28 volumio volumio[19799]: info: Ignoring MPD Status Update Mar 08 15:16:28 volumio volumio[19799]: info: sendMpdCommand clear took 5 milliseconds Mar 08 15:16:28 volumio volumio[19799]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.250:9000/disk/DLNA-PNMP3-OP01-FLAGS01700000/O0$1$8I1928204.mp3" Mar 08 15:16:28 volumio volumio[19799]: error: updateQueue error: null Mar 08 15:16:28 volumio volumio[19799]: info: ------------------------------ 3ms Mar 08 15:16:28 volumio volumio[19799]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.250:9000/disk/DLNA-PNMP3-OP01-FLAGS01700000/O0$1$8I1928204.mp3" Mar 08 15:16:28 volumio volumio[19799]: info: Mar 08 15:16:28 volumio volumio[19799]: ---------------------------- MPD announces system playlist update Mar 08 15:16:28 volumio volumio[19799]: info: Ignoring MPD Status Update Mar 08 15:16:28 volumio volumio[19799]: info: sendMpdCommand add "http://192.168.1.250:9000/disk/DLNA-PNMP3-OP01-FLAGS01700000/O0$1$8I1928204.mp3" took 2 milliseconds Mar 08 15:16:28 volumio volumio[19799]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 08 15:16:28 volumio volumio[19799]: verbose: ControllerMpd::sendMpdCommand play Mar 08 15:16:28 volumio volumio[19799]: info: ------------------------------ 4ms Mar 08 15:16:28 volumio volumio[19799]: info: sendMpdCommand play took 3 milliseconds Mar 08 15:16:28 volumio volumio[19799]: info: Mar 08 15:16:28 volumio volumio[19799]: ---------------------------- MPD announces state update: player Mar 08 15:16:28 volumio volumio[19799]: info: ControllerMpd::getState Mar 08 15:16:28 volumio volumio[19799]: verbose: ControllerMpd::sendMpdCommand status Mar 08 15:16:28 volumio volumio[19799]: info: Mar 08 15:16:28 volumio volumio[19799]: ---------------------------- MPD announces state update: player Mar 08 15:16:28 volumio volumio[19799]: info: sendMpdCommand status took 4 milliseconds Mar 08 15:16:28 volumio volumio[19799]: info: ControllerMpd::getState Mar 08 15:16:28 volumio volumio[19799]: verbose: ControllerMpd::sendMpdCommand status Mar 08 15:16:28 volumio volumio[19799]: verbose: ControllerMpd::parseState Mar 08 15:16:28 volumio volumio[19799]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 15:16:28 volumio volumio[19799]: info: sendMpdCommand status took 2 milliseconds Mar 08 15:16:28 volumio volumio[19799]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 08 15:16:28 volumio volumio[19799]: verbose: ControllerMpd::parseState Mar 08 15:16:28 volumio volumio[19799]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 15:16:28 volumio volumio[19799]: verbose: ControllerMpd::parseTrackInfo Mar 08 15:16:28 volumio volumio[19799]: info: ControllerMpd::pushState Mar 08 15:16:28 volumio volumio[19799]: info: CoreCommandRouter::servicePushState Mar 08 15:16:28 volumio volumio[19799]: info: CorePlayQueue::getTrack 77 Mar 08 15:16:28 volumio volumio[19799]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":272,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"O0$1$8I1928204.mp3","artist":null,"album":null,"uri":"http://192.168.1.250:9000/disk/DLNA-PNMP3-OP01-FLAGS01700000/O0$1$8I1928204.mp3","trackType":"mp3"} Mar 08 15:16:28 volumio volumio[19799]: verbose: CURRENT POSITION 77 Mar 08 15:16:28 volumio volumio[19799]: info: CoreStateMachine::syncState stateService play Mar 08 15:16:28 volumio volumio[19799]: info: CoreStateMachine::syncState currentStatus stop Mar 08 15:16:28 volumio volumio[19799]: info: ------------------------------ 12ms Mar 08 15:16:28 volumio volumio[19799]: info: Mar 08 15:16:28 volumio volumio[19799]: ---------------------------- MPD announces system playlist update Mar 08 15:16:28 volumio volumio[19799]: info: Ignoring MPD Status Update Mar 08 15:16:28 volumio volumio[19799]: info: Mar 08 15:16:28 volumio volumio[19799]: ---------------------------- MPD announces state update: player Mar 08 15:16:28 volumio volumio[19799]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 08 15:16:28 volumio volumio[19799]: info: ControllerMpd::getState Mar 08 15:16:28 volumio volumio[19799]: verbose: ControllerMpd::sendMpdCommand status Mar 08 15:16:28 volumio volumio[19799]: verbose: ControllerMpd::parseTrackInfo Mar 08 15:16:28 volumio volumio[19799]: info: ControllerMpd::pushState Mar 08 15:16:28 volumio volumio[19799]: info: CoreCommandRouter::servicePushState Mar 08 15:16:28 volumio volumio[19799]: info: CorePlayQueue::getTrack 77 Mar 08 15:16:28 volumio volumio[19799]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":272,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"O0$1$8I1928204.mp3","artist":null,"album":null,"uri":"http://192.168.1.250:9000/disk/DLNA-PNMP3-OP01-FLAGS01700000/O0$1$8I1928204.mp3","trackType":"mp3"} Mar 08 15:16:28 volumio volumio[19799]: verbose: CURRENT POSITION 77 Mar 08 15:16:28 volumio volumio[19799]: info: CoreStateMachine::syncState stateService play Mar 08 15:16:28 volumio volumio[19799]: info: CoreStateMachine::syncState currentStatus play Mar 08 15:16:28 volumio volumio[19799]: info: Received an update from plugin. extracting info from payload Mar 08 15:16:28 volumio volumio[19799]: info: CoreStateMachine::pushState Mar 08 15:16:28 volumio volumio[19799]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 15:16:28 volumio volumio[19799]: info: CoreCommandRouter::volumioPushState Mar 08 15:16:28 volumio volumio[19799]: info: CoreStateMachine::pushState Mar 08 15:16:28 volumio volumio[19799]: info: CoreCommandRouter::volumioPushState Mar 08 15:16:28 volumio volumio[19799]: info: ------------------------------ 35ms Mar 08 15:16:28 volumio volumio[19799]: info: ------------------------------ 29ms Mar 08 15:16:28 volumio volumio[19799]: info: sendMpdCommand status took 28 milliseconds Mar 08 15:16:28 volumio volumio[19799]: verbose: ControllerMpd::parseState Mar 08 15:16:28 volumio volumio[19799]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 15:16:28 volumio volumio[19799]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 08 15:16:28 volumio volumio[19799]: verbose: ControllerMpd::parseTrackInfo Mar 08 15:16:28 volumio volumio[19799]: info: ControllerMpd::pushState Mar 08 15:16:28 volumio volumio[19799]: info: CoreCommandRouter::servicePushState Mar 08 15:16:28 volumio volumio[19799]: info: CorePlayQueue::getTrack 77 Mar 08 15:16:28 volumio volumio[19799]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":272,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"O0$1$8I1928204.mp3","artist":"深度音乐工作室","album":"HiFi立体环绕音质","uri":"http://192.168.1.250:9000/disk/DLNA-PNMP3-OP01-FLAGS01700000/O0$1$8I1928204.mp3","trackType":"mp3"} Mar 08 15:16:28 volumio volumio[19799]: verbose: CURRENT POSITION 77 Mar 08 15:16:28 volumio volumio[19799]: info: CoreStateMachine::syncState stateService play Mar 08 15:16:28 volumio volumio[19799]: info: CoreStateMachine::syncState currentStatus play Mar 08 15:16:28 volumio volumio[19799]: info: Received an update from plugin. extracting info from payload Mar 08 15:16:28 volumio volumio[19799]: info: CoreStateMachine::pushState Mar 08 15:16:28 volumio volumio[19799]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 15:16:28 volumio volumio[19799]: info: CoreCommandRouter::volumioPushState Mar 08 15:16:28 volumio volumio[19799]: info: CoreStateMachine::pushState Mar 08 15:16:28 volumio volumio[19799]: info: CoreCommandRouter::volumioPushState Mar 08 15:16:28 volumio volumio[19799]: info: ------------------------------ 59ms Mar 08 15:16:28 volumio volumio[19799]: info: CoreCommandRouter::volumioGetQueue Mar 08 15:16:28 volumio volumio[19799]: info: CoreStateMachine::getQueue Mar 08 15:16:28 volumio volumio[19799]: info: CorePlayQueue::getQueue Mar 08 15:16:28 volumio volumio[19799]: info: CoreCommandRouter::volumioGetQueue Mar 08 15:16:28 volumio volumio[19799]: info: CoreStateMachine::getQueue Mar 08 15:16:28 volumio volumio[19799]: info: CorePlayQueue::getQueue Mar 08 15:16:29 volumio volumio[19799]: info: CoreCommandRouter::volumioPlay Mar 08 15:16:29 volumio volumio[19799]: info: CoreStateMachine::play index 19 Mar 08 15:16:29 volumio volumio[19799]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 15:16:29 volumio volumio[19799]: info: CoreStateMachine::stop Mar 08 15:16:29 volumio volumio[19799]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 15:16:29 volumio volumio[19799]: info: CoreStateMachine::stPlaybackTimer Mar 08 15:16:29 volumio volumio[19799]: info: CoreStateMachine::updateTrackBlock Mar 08 15:16:29 volumio volumio[19799]: info: CorePlayQueue::getTrackBlock Mar 08 15:16:29 volumio volumio[19799]: info: CoreStateMachine::pushState Mar 08 15:16:29 volumio volumio[19799]: info: CorePlayQueue::getTrack 77 Mar 08 15:16:29 volumio volumio[19799]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 15:16:29 volumio volumio[19799]: info: CoreCommandRouter::volumioPushState Mar 08 15:16:29 volumio volumio[19799]: info: CoreStateMachine::serviceStop Mar 08 15:16:29 volumio volumio[19799]: info: CorePlayQueue::getTrack 77 Mar 08 15:16:29 volumio volumio[19799]: info: CoreCommandRouter::serviceStop Mar 08 15:16:29 volumio volumio[19799]: info: [1741446989472] ControllerUPNPBrowser::stop Mar 08 15:16:29 volumio volumio[19799]: verbose: ControllerMpd::sendMpdCommand stop Mar 08 15:16:29 volumio volumio[19799]: info: Mar 08 15:16:29 volumio volumio[19799]: ---------------------------- MPD announces state update: player Mar 08 15:16:29 volumio volumio[19799]: info: sendMpdCommand stop took 57 milliseconds Mar 08 15:16:29 volumio volumio[19799]: info: ControllerMpd::getState Mar 08 15:16:29 volumio volumio[19799]: verbose: ControllerMpd::sendMpdCommand status Mar 08 15:16:29 volumio volumio[19799]: info: CoreStateMachine::play index undefined Mar 08 15:16:29 volumio volumio[19799]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 08 15:16:29 volumio volumio[19799]: info: CorePlayQueue::getTrack 19 Mar 08 15:16:29 volumio volumio[19799]: info: CoreStateMachine::startPlaybackTimer Mar 08 15:16:29 volumio volumio[19799]: info: CorePlayQueue::getTrack 19 Mar 08 15:16:29 volumio volumio[19799]: info: [1741446989532] ControllerUPNPBrowser::clearAddPlayTrack Mar 08 15:16:29 volumio volumio[19799]: verbose: ControllerMpd::sendMpdCommand stop Mar 08 15:16:29 volumio volumio[19799]: info: sendMpdCommand status took 3 milliseconds Mar 08 15:16:29 volumio volumio[19799]: info: sendMpdCommand stop took 1 milliseconds Mar 08 15:16:29 volumio volumio[19799]: verbose: ControllerMpd::parseState Mar 08 15:16:29 volumio volumio[19799]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 15:16:29 volumio volumio[19799]: verbose: ControllerMpd::sendMpdCommand clear Mar 08 15:16:29 volumio volumio[19799]: info: Mar 08 15:16:29 volumio volumio[19799]: ---------------------------- MPD announces system playlist update Mar 08 15:16:29 volumio volumio[19799]: info: Ignoring MPD Status Update Mar 08 15:16:29 volumio volumio[19799]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 08 15:16:29 volumio volumio[19799]: info: sendMpdCommand clear took 2 milliseconds Mar 08 15:16:29 volumio volumio[19799]: verbose: ControllerMpd::parseTrackInfo Mar 08 15:16:29 volumio volumio[19799]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.250:9000/disk/DLNA-PNMP3-OP01-FLAGS01700000/O0$1$8I3621388.mp3" Mar 08 15:16:29 volumio volumio[19799]: info: ControllerMpd::pushState Mar 08 15:16:29 volumio volumio[19799]: info: CoreCommandRouter::servicePushState Mar 08 15:16:29 volumio volumio[19799]: info: CoreStateMachine::pushState Mar 08 15:16:29 volumio volumio[19799]: info: CorePlayQueue::getTrack 19 Mar 08 15:16:29 volumio volumio[19799]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 15:16:29 volumio volumio[19799]: info: CoreCommandRouter::volumioPushState Mar 08 15:16:29 volumio volumio[19799]: info: CorePlayQueue::getTrack 19 Mar 08 15:16:29 volumio volumio[19799]: 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":"O0$1$8I1928204.mp3","artist":"深度音乐工作室","album":"HiFi立体环绕音质","uri":"http://192.168.1.250:9000/disk/DLNA-PNMP3-OP01-FLAGS01700000/O0$1$8I1928204.mp3","trackType":"mp3"} Mar 08 15:16:29 volumio volumio[19799]: verbose: CURRENT POSITION 19 Mar 08 15:16:29 volumio volumio[19799]: info: CoreStateMachine::syncState stateService stop Mar 08 15:16:29 volumio volumio[19799]: info: CoreStateMachine::syncState currentStatus stop Mar 08 15:16:29 volumio volumio[19799]: info: CoreStateMachine::pushState Mar 08 15:16:29 volumio volumio[19799]: info: CorePlayQueue::getTrack 19 Mar 08 15:16:29 volumio volumio[19799]: info: CoreCommandRouter::volumioPushState Mar 08 15:16:29 volumio volumio[19799]: info: No code Mar 08 15:16:29 volumio volumio[19799]: info: CoreStateMachine::pushState Mar 08 15:16:29 volumio volumio[19799]: info: CorePlayQueue::getTrack 19 Mar 08 15:16:29 volumio volumio[19799]: info: CoreCommandRouter::volumioPushState Mar 08 15:16:29 volumio volumio[19799]: info: ------------------------------ 40ms Mar 08 15:16:29 volumio volumio[19799]: error: updateQueue error: null Mar 08 15:16:29 volumio volumio[19799]: info: ------------------------------ 36ms Mar 08 15:16:29 volumio volumio[19799]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.250:9000/disk/DLNA-PNMP3-OP01-FLAGS01700000/O0$1$8I3621388.mp3" Mar 08 15:16:29 volumio volumio[19799]: info: Mar 08 15:16:29 volumio volumio[19799]: ---------------------------- MPD announces system playlist update Mar 08 15:16:29 volumio volumio[19799]: info: Ignoring MPD Status Update Mar 08 15:16:29 volumio volumio[19799]: info: sendMpdCommand add "http://192.168.1.250:9000/disk/DLNA-PNMP3-OP01-FLAGS01700000/O0$1$8I3621388.mp3" took 2 milliseconds Mar 08 15:16:29 volumio volumio[19799]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 08 15:16:29 volumio volumio[19799]: verbose: ControllerMpd::sendMpdCommand play Mar 08 15:16:29 volumio volumio[19799]: info: ------------------------------ 3ms Mar 08 15:16:29 volumio volumio[19799]: info: sendMpdCommand play took 1 milliseconds Mar 08 15:16:29 volumio volumio[19799]: info: Mar 08 15:16:29 volumio volumio[19799]: ---------------------------- MPD announces state update: player Mar 08 15:16:29 volumio volumio[19799]: info: ControllerMpd::getState Mar 08 15:16:29 volumio volumio[19799]: verbose: ControllerMpd::sendMpdCommand status Mar 08 15:16:29 volumio volumio[19799]: info: Mar 08 15:16:29 volumio volumio[19799]: ---------------------------- MPD announces state update: player Mar 08 15:16:29 volumio volumio[19799]: info: sendMpdCommand status took 2 milliseconds Mar 08 15:16:29 volumio volumio[19799]: info: ControllerMpd::getState Mar 08 15:16:29 volumio volumio[19799]: verbose: ControllerMpd::sendMpdCommand status Mar 08 15:16:29 volumio volumio[19799]: verbose: ControllerMpd::parseState Mar 08 15:16:29 volumio volumio[19799]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 15:16:29 volumio volumio[19799]: info: sendMpdCommand status took 2 milliseconds Mar 08 15:16:29 volumio volumio[19799]: verbose: ControllerMpd::parseState Mar 08 15:16:29 volumio volumio[19799]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 15:16:29 volumio volumio[19799]: info: sendMpdCommand playlistinfo took 3 milliseconds Mar 08 15:16:29 volumio volumio[19799]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 08 15:16:29 volumio volumio[19799]: verbose: ControllerMpd::parseTrackInfo Mar 08 15:16:29 volumio volumio[19799]: verbose: ControllerMpd::parseTrackInfo Mar 08 15:16:29 volumio volumio[19799]: info: ControllerMpd::pushState Mar 08 15:16:29 volumio volumio[19799]: info: CoreCommandRouter::servicePushState Mar 08 15:16:29 volumio volumio[19799]: info: CorePlayQueue::getTrack 19 Mar 08 15:16:29 volumio volumio[19799]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":4235,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"O0$1$8I3621388.mp3","artist":null,"album":null,"uri":"http://192.168.1.250:9000/disk/DLNA-PNMP3-OP01-FLAGS01700000/O0$1$8I3621388.mp3","trackType":"mp3"} Mar 08 15:16:29 volumio volumio[19799]: verbose: CURRENT POSITION 19 Mar 08 15:16:29 volumio volumio[19799]: info: CoreStateMachine::syncState stateService play Mar 08 15:16:29 volumio volumio[19799]: info: CoreStateMachine::syncState currentStatus stop Mar 08 15:16:29 volumio volumio[19799]: info: ControllerMpd::pushState Mar 08 15:16:29 volumio volumio[19799]: info: CoreCommandRouter::servicePushState Mar 08 15:16:29 volumio volumio[19799]: info: CorePlayQueue::getTrack 19 Mar 08 15:16:29 volumio volumio[19799]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":4235,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"O0$1$8I3621388.mp3","artist":null,"album":null,"uri":"http://192.168.1.250:9000/disk/DLNA-PNMP3-OP01-FLAGS01700000/O0$1$8I3621388.mp3","trackType":"mp3"} Mar 08 15:16:29 volumio volumio[19799]: verbose: CURRENT POSITION 19 Mar 08 15:16:29 volumio volumio[19799]: info: CoreStateMachine::syncState stateService play Mar 08 15:16:29 volumio volumio[19799]: info: CoreStateMachine::syncState currentStatus play Mar 08 15:16:29 volumio volumio[19799]: info: Received an update from plugin. extracting info from payload Mar 08 15:16:29 volumio volumio[19799]: info: CoreStateMachine::pushState Mar 08 15:16:29 volumio volumio[19799]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 15:16:29 volumio volumio[19799]: info: CoreCommandRouter::volumioPushState Mar 08 15:16:29 volumio volumio[19799]: info: CoreStateMachine::pushState Mar 08 15:16:29 volumio volumio[19799]: info: CoreCommandRouter::volumioPushState Mar 08 15:16:29 volumio volumio[19799]: info: ------------------------------ 37ms Mar 08 15:16:29 volumio volumio[19799]: info: ------------------------------ 50ms Mar 08 15:16:29 volumio volumio[19799]: info: Mar 08 15:16:29 volumio volumio[19799]: ---------------------------- MPD announces system playlist update Mar 08 15:16:29 volumio volumio[19799]: info: Ignoring MPD Status Update Mar 08 15:16:29 volumio volumio[19799]: info: Mar 08 15:16:29 volumio volumio[19799]: ---------------------------- MPD announces state update: player Mar 08 15:16:29 volumio volumio[19799]: info: ControllerMpd::getState Mar 08 15:16:29 volumio volumio[19799]: verbose: ControllerMpd::sendMpdCommand status Mar 08 15:16:29 volumio volumio[19799]: info: ------------------------------ 8ms Mar 08 15:16:29 volumio volumio[19799]: info: sendMpdCommand status took 6 milliseconds Mar 08 15:16:29 volumio volumio[19799]: verbose: ControllerMpd::parseState Mar 08 15:16:29 volumio volumio[19799]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 15:16:29 volumio volumio[19799]: info: sendMpdCommand playlistinfo took 1 milliseconds Mar 08 15:16:29 volumio volumio[19799]: verbose: ControllerMpd::parseTrackInfo Mar 08 15:16:29 volumio volumio[19799]: info: ControllerMpd::pushState Mar 08 15:16:29 volumio volumio[19799]: info: CoreCommandRouter::servicePushState Mar 08 15:16:29 volumio volumio[19799]: info: CorePlayQueue::getTrack 19 Mar 08 15:16:29 volumio volumio[19799]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":4235,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"O0$1$8I3621388.mp3","artist":"【翱视群龍收藏】","album":null,"uri":"http://192.168.1.250:9000/disk/DLNA-PNMP3-OP01-FLAGS01700000/O0$1$8I3621388.mp3","trackType":"mp3"} Mar 08 15:16:29 volumio volumio[19799]: verbose: CURRENT POSITION 19 Mar 08 15:16:29 volumio volumio[19799]: info: CoreStateMachine::syncState stateService play Mar 08 15:16:29 volumio volumio[19799]: info: CoreStateMachine::syncState currentStatus play Mar 08 15:16:29 volumio volumio[19799]: info: Received an update from plugin. extracting info from payload Mar 08 15:16:29 volumio volumio[19799]: info: CoreStateMachine::pushState Mar 08 15:16:29 volumio volumio[19799]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 15:16:29 volumio volumio[19799]: info: CoreCommandRouter::volumioPushState Mar 08 15:16:29 volumio volumio[19799]: info: CoreStateMachine::pushState Mar 08 15:16:29 volumio volumio[19799]: info: CoreCommandRouter::volumioPushState Mar 08 15:16:29 volumio volumio[19799]: info: ------------------------------ 50ms Mar 08 15:16:50 volumio volumio[19799]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 08 15:16:50 volumio volumio[19799]: Error: connect ETIMEDOUT 108.160.162.31:443 Mar 08 15:16:50 volumio volumio[19799]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Mar 08 15:16:50 volumio volumio[19799]: errno: -110, Mar 08 15:16:50 volumio volumio[19799]: code: 'ETIMEDOUT', Mar 08 15:16:50 volumio volumio[19799]: syscall: 'connect', Mar 08 15:16:50 volumio volumio[19799]: address: '108.160.162.31', Mar 08 15:16:50 volumio volumio[19799]: port: 443 Mar 08 15:16:50 volumio volumio[19799]: } Mar 08 15:16:50 volumio volumio[19799]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 08 15:16:51 volumio sudo[20476]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-08 15:15 Mar 08 15:16:51 volumio sudo[20476]: 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 07:25:16 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="66c567362bdbb3dc1644a18f7879afe7"