Oct 15 00:22:20 volumio sudo[2340]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 15 00:22:20 volumio sudo[2340]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 00:22:20 volumio sudo[2342]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 15 00:22:20 volumio sudo[2342]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 00:22:20 volumio sudo[2340]: pam_unix(sudo:session): session closed for user root Oct 15 00:22:20 volumio sudo[2342]: pam_unix(sudo:session): session closed for user root Oct 15 00:22:20 volumio volumio[1298]: verbose: New Socket.io Connection to 192.168.1.10 from 192.168.1.185 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/155.0.0.0 Safari/537.36 Edg/155.0.0.0 Engine version: 3 Transport: polling Total Clients: 6 Oct 15 00:22:20 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 15 00:22:20 volumio volumio[1298]: info: CoreCommandRouter::volumioGetVisibleSources Oct 15 00:22:20 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 15 00:22:20 volumio volumio[1298]: info: CoreCommandRouter::volumioGetState Oct 15 00:22:20 volumio volumio[1298]: info: CorePlayQueue::getTrack 0 Oct 15 00:22:20 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 15 00:22:20 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 15 00:22:20 volumio volumio[1298]: info: Received Get System Info Oct 15 00:22:20 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 15 00:22:20 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 15 00:22:20 volumio volumio[1298]: info: Discovery: Getting this device information Oct 15 00:22:20 volumio volumio[1298]: info: CoreCommandRouter::volumioGetState Oct 15 00:22:20 volumio volumio[1298]: info: CorePlayQueue::getTrack 0 Oct 15 00:22:20 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 15 00:22:20 volumio volumio[1298]: info: CoreCommandRouter::volumioGetState Oct 15 00:22:20 volumio volumio[1298]: info: CorePlayQueue::getTrack 0 Oct 15 00:22:20 volumio volumio[1298]: info: Listing playlists Oct 15 00:22:20 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 15 00:22:20 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 15 00:22:20 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 15 00:22:21 volumio volumio[1298]: info: CoreCommandRouter::volumioGetState Oct 15 00:22:21 volumio volumio[1298]: info: CorePlayQueue::getTrack 0 Oct 15 00:22:22 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 15 00:22:22 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 15 00:22:22 volumio volumio[1298]: info: Received Get System Info Oct 15 00:22:22 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 15 00:22:22 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 15 00:22:22 volumio volumio[1298]: info: Discovery: Getting this device information Oct 15 00:22:22 volumio volumio[1298]: info: CoreCommandRouter::volumioGetState Oct 15 00:22:22 volumio volumio[1298]: info: CorePlayQueue::getTrack 0 Oct 15 00:22:22 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 15 00:22:23 volumio volumio[1298]: info: CoreCommandRouter::volumioNext Oct 15 00:22:24 volumio volumio[1298]: info: CoreStateMachine::next Oct 15 00:22:24 volumio volumio[1298]: info: CoreStateMachine::stop Oct 15 00:22:24 volumio volumio[1298]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 15 00:22:24 volumio volumio[1298]: info: CoreStateMachine::play index undefined Oct 15 00:22:24 volumio volumio[1298]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 15 00:22:24 volumio volumio[1298]: info: CorePlayQueue::getTrack 428 Oct 15 00:22:24 volumio volumio[1298]: info: CoreStateMachine::startPlaybackTimer Oct 15 00:22:24 volumio volumio[1298]: info: CorePlayQueue::getTrack 428 Oct 15 00:22:24 volumio volumio[1298]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/One Direction - Heart Attack.mp3 Oct 15 00:22:24 volumio volumio[1298]: verbose: ControllerMpd::sendMpdCommand stop Oct 15 00:22:24 volumio volumio[1298]: info: CoreStateMachine::updateTrackBlock Oct 15 00:22:24 volumio volumio[1298]: info: CorePlayQueue::getTrackBlock Oct 15 00:22:24 volumio volumio[1298]: info: sendMpdCommand stop took 3 milliseconds Oct 15 00:22:24 volumio volumio[1298]: verbose: ControllerMpd::sendMpdCommand clear Oct 15 00:22:24 volumio volumio[1298]: info: sendMpdCommand clear took 0 milliseconds Oct 15 00:22:24 volumio volumio[1298]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/One Direction - Heart Attack.mp3" Oct 15 00:22:24 volumio volumio[1298]: info: Oct 15 00:22:24 volumio volumio[1298]: ---------------------------- MPD announces system playlist update Oct 15 00:22:24 volumio volumio[1298]: info: Ignoring MPD Status Update Oct 15 00:22:24 volumio volumio[1298]: info: Oct 15 00:22:24 volumio volumio[1298]: ---------------------------- MPD announces system playlist update Oct 15 00:22:24 volumio volumio[1298]: info: Ignoring MPD Status Update Oct 15 00:22:24 volumio volumio[1298]: info: Oct 15 00:22:24 volumio volumio[1298]: ---------------------------- MPD announces system playlist update Oct 15 00:22:24 volumio volumio[1298]: info: Ignoring MPD Status Update Oct 15 00:22:24 volumio volumio[1298]: info: Oct 15 00:22:24 volumio volumio[1298]: ---------------------------- MPD announces system playlist update Oct 15 00:22:24 volumio volumio[1298]: info: Ignoring MPD Status Update Oct 15 00:22:24 volumio volumio[1298]: info: sendMpdCommand add "INTERNAL/One Direction - Heart Attack.mp3" took 8 milliseconds Oct 15 00:22:24 volumio volumio[1298]: info: ------------------------------ 7ms Oct 15 00:22:24 volumio volumio[1298]: info: ------------------------------ 7ms Oct 15 00:22:24 volumio volumio[1298]: info: ------------------------------ 5ms Oct 15 00:22:24 volumio volumio[1298]: verbose: ControllerMpd::sendMpdCommand play Oct 15 00:22:24 volumio volumio[1298]: info: Oct 15 00:22:24 volumio volumio[1298]: ---------------------------- MPD announces system playlist update Oct 15 00:22:24 volumio volumio[1298]: info: Ignoring MPD Status Update Oct 15 00:22:24 volumio volumio[1298]: info: Oct 15 00:22:24 volumio volumio[1298]: ---------------------------- MPD announces system playlist update Oct 15 00:22:24 volumio volumio[1298]: info: Ignoring MPD Status Update Oct 15 00:22:24 volumio volumio[1298]: info: ------------------------------ 7ms Oct 15 00:22:24 volumio volumio[1298]: info: sendMpdCommand play took 4 milliseconds Oct 15 00:22:24 volumio volumio[1298]: info: ------------------------------ 3ms Oct 15 00:22:24 volumio volumio[1298]: info: ------------------------------ 2ms Oct 15 00:22:24 volumio volumio[1298]: info: Oct 15 00:22:24 volumio volumio[1298]: ---------------------------- MPD announces state update: player Oct 15 00:22:24 volumio volumio[1298]: info: ControllerMpd::getState Oct 15 00:22:24 volumio volumio[1298]: verbose: ControllerMpd::sendMpdCommand status Oct 15 00:22:24 volumio volumio[1298]: info: Oct 15 00:22:24 volumio volumio[1298]: ---------------------------- MPD announces state update: player Oct 15 00:22:24 volumio volumio[1298]: info: ControllerMpd::getState Oct 15 00:22:24 volumio volumio[1298]: verbose: ControllerMpd::sendMpdCommand status Oct 15 00:22:24 volumio volumio[1298]: info: Oct 15 00:22:24 volumio volumio[1298]: ---------------------------- MPD announces state update: player Oct 15 00:22:24 volumio volumio[1298]: info: Oct 15 00:22:24 volumio volumio[1298]: ---------------------------- MPD announces state update: player Oct 15 00:22:24 volumio volumio[1298]: info: ControllerMpd::getState Oct 15 00:22:24 volumio volumio[1298]: verbose: ControllerMpd::sendMpdCommand status Oct 15 00:22:24 volumio volumio[1298]: error: MPD returned error for command status: Failed to open "alsa" (alsa); Failed to open ALSA device "volumio": No such device Oct 15 00:22:24 volumio volumio[1298]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 15 00:22:24 volumio volumio[1298]: info: sendMpdCommand status took 9 milliseconds Oct 15 00:22:24 volumio volumio[1298]: info: ControllerMpd::getState Oct 15 00:22:24 volumio volumio[1298]: verbose: ControllerMpd::sendMpdCommand status Oct 15 00:22:24 volumio volumio[1298]: verbose: ControllerMpd::parseState Oct 15 00:22:24 volumio volumio[1298]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 15 00:22:24 volumio volumio[1298]: info: Oct 15 00:22:24 volumio volumio[1298]: ---------------------------- MPD announces state update: player Oct 15 00:22:24 volumio volumio[1298]: info: ControllerMpd::getState Oct 15 00:22:24 volumio volumio[1298]: verbose: ControllerMpd::sendMpdCommand status Oct 15 00:22:24 volumio volumio[1298]: info: Oct 15 00:22:24 volumio volumio[1298]: ---------------------------- MPD announces state update: player Oct 15 00:22:24 volumio volumio[1298]: info: ControllerMpd::getState Oct 15 00:22:24 volumio volumio[1298]: verbose: ControllerMpd::sendMpdCommand status Oct 15 00:22:24 volumio volumio[1298]: error: MPD returned error for command status: Failed to open "alsa" (alsa); Failed to open ALSA device "volumio": No such device Oct 15 00:22:24 volumio volumio[1298]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 15 00:22:24 volumio volumio[1298]: info: sendMpdCommand status took 22 milliseconds Oct 15 00:22:24 volumio volumio[1298]: error: MPD returned error for command status: Failed to open "alsa" (alsa); Failed to open ALSA device "volumio": No such device Oct 15 00:22:24 volumio volumio[1298]: verbose: ControllerMpd::sendMpdCommand clearerror Oct 15 00:22:24 volumio volumio[1298]: info: sendMpdCommand status took 21 milliseconds Oct 15 00:22:24 volumio volumio[1298]: info: sendMpdCommand clearerror took 15 milliseconds Oct 15 00:22:24 volumio volumio[1298]: info: sendMpdCommand status took 16 milliseconds Oct 15 00:22:24 volumio volumio[1298]: info: sendMpdCommand playlistinfo took 14 milliseconds Oct 15 00:22:24 volumio volumio[1298]: info: sendMpdCommand status took 12 milliseconds Oct 15 00:22:24 volumio volumio[1298]: info: sendMpdCommand status took 9 milliseconds Oct 15 00:22:24 volumio volumio[1298]: verbose: ControllerMpd::parseState Oct 15 00:22:24 volumio volumio[1298]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 15 00:22:24 volumio volumio[1298]: verbose: ControllerMpd::parseState Oct 15 00:22:24 volumio volumio[1298]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 15 00:22:24 volumio volumio[1298]: verbose: ControllerMpd::parseState Oct 15 00:22:24 volumio volumio[1298]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 15 00:22:24 volumio volumio[1298]: verbose: ControllerMpd::parseTrackInfo Oct 15 00:22:24 volumio volumio[1298]: verbose: ControllerMpd::parseState Oct 15 00:22:24 volumio volumio[1298]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 15 00:22:24 volumio volumio[1298]: verbose: ControllerMpd::parseState Oct 15 00:22:24 volumio volumio[1298]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 15 00:22:24 volumio volumio[1298]: info: ControllerMpd::pushState Oct 15 00:22:24 volumio volumio[1298]: info: CoreCommandRouter::servicePushState Oct 15 00:22:24 volumio volumio[1298]: info: CorePlayQueue::getTrack 428 Oct 15 00:22:24 volumio volumio[1298]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":175,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Heart Attack","artist":"One Direction","album":"Take Me Home","uri":"INTERNAL/One Direction - Heart Attack.mp3","trackType":"mp3"} Oct 15 00:22:24 volumio volumio[1298]: verbose: CURRENT POSITION 428 Oct 15 00:22:24 volumio volumio[1298]: info: CoreStateMachine::syncState stateService pause Oct 15 00:22:24 volumio volumio[1298]: info: CoreStateMachine::syncState currentStatus stop Oct 15 00:22:24 volumio volumio[1298]: info: CoreStateMachine::pushState Oct 15 00:22:24 volumio volumio[1298]: info: CorePlayQueue::getTrack 428 Oct 15 00:22:24 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 15 00:22:24 volumio volumio[1298]: info: CoreCommandRouter::volumioPushState Oct 15 00:22:24 volumio volumio[1298]: info: ------------------------------ 50ms Oct 15 00:22:24 volumio volumio[1298]: info: sendMpdCommand clearerror took 33 milliseconds Oct 15 00:22:24 volumio volumio[1298]: info: sendMpdCommand clearerror took 32 milliseconds Oct 15 00:22:24 volumio volumio[1298]: info: sendMpdCommand playlistinfo took 27 milliseconds Oct 15 00:22:24 volumio volumio[1298]: info: sendMpdCommand playlistinfo took 26 milliseconds Oct 15 00:22:24 volumio volumio[1298]: info: sendMpdCommand playlistinfo took 26 milliseconds Oct 15 00:22:24 volumio volumio[1298]: info: sendMpdCommand playlistinfo took 24 milliseconds Oct 15 00:22:24 volumio volumio[1298]: info: sendMpdCommand playlistinfo took 23 milliseconds Oct 15 00:22:24 volumio volumio[1298]: verbose: ControllerMpd::parseTrackInfo Oct 15 00:22:24 volumio volumio[1298]: verbose: ControllerMpd::parseTrackInfo Oct 15 00:22:24 volumio volumio[1298]: verbose: ControllerMpd::parseTrackInfo Oct 15 00:22:24 volumio volumio[1298]: verbose: ControllerMpd::parseTrackInfo Oct 15 00:22:24 volumio volumio[1298]: verbose: ControllerMpd::parseTrackInfo Oct 15 00:22:24 volumio volumio[1298]: info: ControllerMpd::pushState Oct 15 00:22:24 volumio volumio[1298]: info: CoreCommandRouter::servicePushState Oct 15 00:22:24 volumio volumio[1298]: info: CorePlayQueue::getTrack 428 Oct 15 00:22:24 volumio volumio[1298]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":175,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Heart Attack","artist":"One Direction","album":"Take Me Home","uri":"INTERNAL/One Direction - Heart Attack.mp3","trackType":"mp3"} Oct 15 00:22:24 volumio volumio[1298]: verbose: CURRENT POSITION 428 Oct 15 00:22:24 volumio volumio[1298]: info: CoreStateMachine::syncState stateService pause Oct 15 00:22:24 volumio volumio[1298]: info: CoreStateMachine::syncState currentStatus stop Oct 15 00:22:24 volumio volumio[1298]: info: CoreStateMachine::pushState Oct 15 00:22:24 volumio volumio[1298]: info: CorePlayQueue::getTrack 428 Oct 15 00:22:24 volumio volumio[1298]: info: CoreCommandRouter::volumioPushState Oct 15 00:22:24 volumio volumio[1298]: info: ControllerMpd::pushState Oct 15 00:22:24 volumio volumio[1298]: info: CoreCommandRouter::servicePushState Oct 15 00:22:24 volumio volumio[1298]: info: CorePlayQueue::getTrack 428 Oct 15 00:22:24 volumio volumio[1298]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":175,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Heart Attack","artist":"One Direction","album":"Take Me Home","uri":"INTERNAL/One Direction - Heart Attack.mp3","trackType":"mp3"} Oct 15 00:22:24 volumio volumio[1298]: verbose: CURRENT POSITION 428 Oct 15 00:22:24 volumio volumio[1298]: info: CoreStateMachine::syncState stateService pause Oct 15 00:22:24 volumio volumio[1298]: info: CoreStateMachine::syncState currentStatus stop Oct 15 00:22:24 volumio volumio[1298]: info: CoreStateMachine::pushState Oct 15 00:22:24 volumio volumio[1298]: info: CorePlayQueue::getTrack 428 Oct 15 00:22:24 volumio volumio[1298]: info: CoreCommandRouter::volumioPushState Oct 15 00:22:24 volumio volumio[1298]: info: ControllerMpd::pushState Oct 15 00:22:24 volumio volumio[1298]: info: CoreCommandRouter::servicePushState Oct 15 00:22:24 volumio volumio[1298]: info: CorePlayQueue::getTrack 428 Oct 15 00:22:24 volumio volumio[1298]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":175,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Heart Attack","artist":"One Direction","album":"Take Me Home","uri":"INTERNAL/One Direction - Heart Attack.mp3","trackType":"mp3"} Oct 15 00:22:24 volumio volumio[1298]: verbose: CURRENT POSITION 428 Oct 15 00:22:24 volumio volumio[1298]: info: CoreStateMachine::syncState stateService pause Oct 15 00:22:24 volumio volumio[1298]: info: CoreStateMachine::syncState currentStatus stop Oct 15 00:22:24 volumio volumio[1298]: info: CoreStateMachine::pushState Oct 15 00:22:24 volumio volumio[1298]: info: CorePlayQueue::getTrack 428 Oct 15 00:22:24 volumio volumio[1298]: info: CoreCommandRouter::volumioPushState Oct 15 00:22:24 volumio volumio[1298]: info: ControllerMpd::pushState Oct 15 00:22:24 volumio volumio[1298]: info: CoreCommandRouter::servicePushState Oct 15 00:22:24 volumio volumio[1298]: info: CorePlayQueue::getTrack 428 Oct 15 00:22:24 volumio volumio[1298]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":175,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Heart Attack","artist":"One Direction","album":"Take Me Home","uri":"INTERNAL/One Direction - Heart Attack.mp3","trackType":"mp3"} Oct 15 00:22:24 volumio volumio[1298]: verbose: CURRENT POSITION 428 Oct 15 00:22:24 volumio volumio[1298]: info: CoreStateMachine::syncState stateService pause Oct 15 00:22:24 volumio volumio[1298]: info: CoreStateMachine::syncState currentStatus stop Oct 15 00:22:24 volumio volumio[1298]: info: CoreStateMachine::pushState Oct 15 00:22:24 volumio volumio[1298]: info: CorePlayQueue::getTrack 428 Oct 15 00:22:24 volumio volumio[1298]: info: CoreCommandRouter::volumioPushState Oct 15 00:22:24 volumio volumio[1298]: info: ControllerMpd::pushState Oct 15 00:22:24 volumio volumio[1298]: info: CoreCommandRouter::servicePushState Oct 15 00:22:24 volumio volumio[1298]: info: CorePlayQueue::getTrack 428 Oct 15 00:22:24 volumio volumio[1298]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":175,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Heart Attack","artist":"One Direction","album":"Take Me Home","uri":"INTERNAL/One Direction - Heart Attack.mp3","trackType":"mp3"} Oct 15 00:22:24 volumio volumio[1298]: verbose: CURRENT POSITION 428 Oct 15 00:22:24 volumio volumio[1298]: info: CoreStateMachine::syncState stateService pause Oct 15 00:22:24 volumio volumio[1298]: info: CoreStateMachine::syncState currentStatus stop Oct 15 00:22:24 volumio volumio[1298]: info: CoreStateMachine::pushState Oct 15 00:22:24 volumio volumio[1298]: info: CorePlayQueue::getTrack 428 Oct 15 00:22:24 volumio volumio[1298]: info: CoreCommandRouter::volumioPushState Oct 15 00:22:24 volumio volumio[1298]: info: ------------------------------ 125ms Oct 15 00:22:24 volumio volumio[1298]: info: ------------------------------ 124ms Oct 15 00:22:24 volumio volumio[1298]: info: ------------------------------ 125ms Oct 15 00:22:24 volumio volumio[1298]: info: ------------------------------ 115ms Oct 15 00:22:24 volumio volumio[1298]: info: ------------------------------ 113ms Oct 15 00:22:30 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 15 00:22:42 volumio kernel: usb 2-1.2: new full-speed USB device number 3 using ehci-pci Oct 15 00:22:42 volumio kernel: usb 2-1.2: New USB device found, idVendor=1a86, idProduct=7523, bcdDevice= 2.64 Oct 15 00:22:42 volumio kernel: usb 2-1.2: New USB device strings: Mfr=0, Product=2, SerialNumber=0 Oct 15 00:22:42 volumio kernel: usb 2-1.2: Product: USB Serial Oct 15 00:22:42 volumio kernel: usbcore: registered new interface driver usbserial_generic Oct 15 00:22:42 volumio kernel: usbserial: USB Serial support registered for generic Oct 15 00:22:42 volumio kernel: usbcore: registered new interface driver ch341 Oct 15 00:22:42 volumio kernel: usbserial: USB Serial support registered for ch341-uart Oct 15 00:22:42 volumio kernel: ch341 2-1.2:1.0: ch341-uart converter detected Oct 15 00:22:42 volumio kernel: usb 2-1.2: ch341-uart converter now attached to ttyUSB0 Oct 15 00:22:45 volumio kernel: usb 2-1.2: USB disconnect, device number 3 Oct 15 00:22:45 volumio kernel: ch341-uart ttyUSB0: ch341-uart converter now disconnected from ttyUSB0 Oct 15 00:22:45 volumio kernel: ch341 2-1.2:1.0: device disconnected Oct 15 00:22:46 volumio kernel: usb 2-1.2: new high-speed USB device number 4 using ehci-pci Oct 15 00:22:46 volumio kernel: usb 2-1.2: New USB device found, idVendor=152a, idProduct=85dd, bcdDevice= 3.54 Oct 15 00:22:46 volumio kernel: usb 2-1.2: New USB device strings: Mfr=1, Product=3, SerialNumber=2 Oct 15 00:22:46 volumio kernel: usb 2-1.2: Product: SMSL USB AUDIO Oct 15 00:22:46 volumio kernel: usb 2-1.2: Manufacturer: SMSL Oct 15 00:22:46 volumio kernel: hid-generic 0003:152A:85DD.0002: hiddev0,hidraw1: USB HID v1.10 Device [SMSL SMSL USB AUDIO] on usb-0000:00:1d.0-1.2/input3 Oct 15 00:22:46 volumio kernel: usbcore: registered new interface driver snd-usb-audio Oct 15 00:22:46 volumio volumio[1298]: info: Oct 15 00:22:46 volumio volumio[1298]: ---------------------------- USB Audio Device Attached Oct 15 00:22:46 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Oct 15 00:22:46 volumio volumio[1298]: info: CoreCommandRouter::Close All Modals sent Oct 15 00:22:46 volumio volumio[1298]: info: Preparing to save Alsa Options, stopping services first Oct 15 00:22:46 volumio volumio[1298]: info: CoreCommandRouter::volumioGetState Oct 15 00:22:46 volumio volumio[1298]: info: CorePlayQueue::getTrack 428 Oct 15 00:22:46 volumio volumio[1298]: info: CoreCommandRouter::volumioPause Oct 15 00:22:46 volumio volumio[1298]: info: CoreStateMachine::pause Oct 15 00:22:46 volumio volumio[1298]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"SMSL USB AUDIO","alsacard":"AUDIO"},"i2s":false} Oct 15 00:22:46 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 15 00:22:46 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 15 00:22:47 volumio volumio[1298]: info: Device 5 does not have any Mixer Control Available Oct 15 00:22:47 volumio volumio[1298]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 15 00:22:47 volumio volumio[1298]: info: Updating Volume Controller Parameters: Device: 5 Name: SMSL USB AUDIO Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 15 00:22:47 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 15 00:22:47 volumio volumio[1298]: info: Disabling external Volume Control Oct 15 00:22:47 volumio volumio[1298]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 15 00:22:47 volumio volumio[1298]: info: CoreStateMachine::pushState Oct 15 00:22:47 volumio volumio[1298]: info: CorePlayQueue::getTrack 428 Oct 15 00:22:47 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 15 00:22:47 volumio volumio[1298]: info: CoreCommandRouter::volumioPushState Oct 15 00:22:47 volumio volumio[1298]: info: Preparing to generate the ALSA configuration file Oct 15 00:22:47 volumio volumio[1298]: info: Asound.conf file unchanged, so no further update is needed Oct 15 00:22:47 volumio volumio[1298]: info: Output device has changed, restarting MPD Oct 15 00:22:47 volumio volumio[1298]: info: Output device has changed, restarting Shairport Sync Oct 15 00:22:47 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 00:22:47 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 00:22:47 volumio sudo[2453]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 15 00:22:47 volumio sudo[2453]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 00:22:47 volumio sudo[2453]: pam_unix(sudo:session): session closed for user root Oct 15 00:22:47 volumio sudo[2455]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 15 00:22:47 volumio sudo[2455]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 00:22:47 volumio volumio[1298]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 15 00:22:47 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 15 00:22:47 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 15 00:22:47 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 00:22:47 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 15 00:22:47 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 00:22:47 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 00:22:47 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 00:22:47 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 00:22:47 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 00:22:47 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 00:22:47 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 00:22:47 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 15 00:22:47 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 15 00:22:47 volumio volumio[1298]: info: MPD Permissions set Oct 15 00:22:47 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 00:22:47 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 00:22:47 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 00:22:47 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 00:22:47 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 00:22:47 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 00:22:47 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 00:22:47 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 00:22:47 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 00:22:47 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 15 00:22:47 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 00:22:47 volumio volumio[1298]: info: Starting Shairport Sync Oct 15 00:22:47 volumio sudo[2477]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 15 00:22:47 volumio sudo[2477]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 00:22:47 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 15 00:22:47 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Oct 15 00:22:47 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 15 00:22:47 volumio systemd[1]: shairport-sync.service: Consumed 2.441s CPU time. Oct 15 00:22:47 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 15 00:22:47 volumio sudo[2477]: pam_unix(sudo:session): session closed for user root Oct 15 00:22:47 volumio systemd[1]: mpd.service: Deactivated successfully. Oct 15 00:22:47 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 15 00:22:47 volumio systemd[1]: mpd.service: Consumed 2.901s CPU time. Oct 15 00:22:47 volumio volumio[1298]: info: Shairport-Sync Started Oct 15 00:22:47 volumio systemd[1]: mpd.socket: Deactivated successfully. Oct 15 00:22:47 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 15 00:22:47 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 15 00:22:47 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 15 00:22:47 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 15 00:22:47 volumio sudo[2482]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 15 00:22:47 volumio sudo[2482]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 15 00:22:47 volumio sudo[2482]: pam_unix(sudo:session): session closed for user root Oct 15 00:22:47 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Oct 15 00:22:48 volumio mpd[2498]: 2026-10-15T00:22:48 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 15 00:22:48 volumio systemd[1]: Started mpd.service - Music Player Daemon. Oct 15 00:22:48 volumio sudo[2455]: pam_unix(sudo:session): session closed for user root Oct 15 00:22:48 volumio volumio[1298]: error: updateQueue error: null Oct 15 00:22:48 volumio kernel: usb 2-1.2: USB disconnect, device number 4 Oct 15 00:22:48 volumio volumio[1298]: info: Oct 15 00:22:48 volumio volumio[1298]: ---------------------------- USB Audio Device Detached Oct 15 00:22:48 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Oct 15 00:22:48 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 15 00:22:48 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Oct 15 00:22:51 volumio volumio[1298]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Oct 15 00:22:51 volumio volumio[1298]: info: CoreStateMachine::getcurrentVolume Oct 15 00:22:51 volumio volumio[1298]: info: CoreCommandRouter::volumioRetrievevolume Oct 15 00:22:51 volumio volumio[1298]: info: CoreStateMachine::pushState Oct 15 00:22:51 volumio volumio[1298]: info: CorePlayQueue::getTrack 428 Oct 15 00:22:51 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 15 00:22:51 volumio volumio[1298]: info: CoreCommandRouter::volumioPushState Oct 15 00:22:53 volumio kernel: usb 2-1.2: new high-speed USB device number 5 using ehci-pci Oct 15 00:22:53 volumio kernel: usb 2-1.2: New USB device found, idVendor=152a, idProduct=85dd, bcdDevice= 3.54 Oct 15 00:22:53 volumio kernel: usb 2-1.2: New USB device strings: Mfr=1, Product=3, SerialNumber=2 Oct 15 00:22:53 volumio kernel: usb 2-1.2: Product: SMSL USB AUDIO Oct 15 00:22:53 volumio kernel: usb 2-1.2: Manufacturer: SMSL Oct 15 00:22:53 volumio kernel: hid-generic 0003:152A:85DD.0003: hiddev0,hidraw1: USB HID v1.10 Device [SMSL SMSL USB AUDIO] on usb-0000:00:1d.0-1.2/input3 Oct 15 00:22:53 volumio volumio[1298]: info: Oct 15 00:22:53 volumio volumio[1298]: ---------------------------- USB Audio Device Attached Oct 15 00:22:53 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Oct 15 00:22:53 volumio volumio[1298]: info: CoreCommandRouter::Close All Modals sent Oct 15 00:22:53 volumio volumio[1298]: info: Preparing to save Alsa Options, stopping services first Oct 15 00:22:53 volumio volumio[1298]: info: CoreCommandRouter::volumioGetState Oct 15 00:22:53 volumio volumio[1298]: info: CorePlayQueue::getTrack 428 Oct 15 00:22:53 volumio volumio[1298]: info: CoreCommandRouter::volumioPause Oct 15 00:22:53 volumio volumio[1298]: info: CoreStateMachine::pause Oct 15 00:22:53 volumio volumio[1298]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"SMSL USB AUDIO","alsacard":"AUDIO"},"i2s":false} Oct 15 00:22:53 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 15 00:22:53 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 15 00:22:53 volumio volumio[1298]: info: Device 5 does not have any Mixer Control Available Oct 15 00:22:53 volumio volumio[1298]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 15 00:22:53 volumio volumio[1298]: info: Updating Volume Controller Parameters: Device: 5 Name: SMSL USB AUDIO Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 15 00:22:53 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 15 00:22:53 volumio volumio[1298]: info: Disabling external Volume Control Oct 15 00:22:53 volumio volumio[1298]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 15 00:22:53 volumio volumio[1298]: info: CoreStateMachine::pushState Oct 15 00:22:53 volumio volumio[1298]: info: CorePlayQueue::getTrack 428 Oct 15 00:22:53 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 15 00:22:53 volumio volumio[1298]: info: CoreCommandRouter::volumioPushState Oct 15 00:22:53 volumio volumio[1298]: info: Preparing to generate the ALSA configuration file Oct 15 00:22:53 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 15 00:22:53 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 00:22:53 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 15 00:22:53 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 00:22:53 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 00:22:53 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 00:22:53 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 00:22:53 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 00:22:53 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 00:22:53 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 15 00:22:53 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 15 00:22:53 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 15 00:22:53 volumio volumio[1298]: info: Asound.conf file unchanged, so no further update is needed Oct 15 00:22:53 volumio volumio[1298]: info: Output device has changed, restarting MPD Oct 15 00:22:53 volumio volumio[1298]: info: Output device has changed, restarting Shairport Sync Oct 15 00:22:53 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 00:22:53 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 00:22:53 volumio sudo[2560]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 15 00:22:53 volumio sudo[2560]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 00:22:53 volumio sudo[2560]: pam_unix(sudo:session): session closed for user root Oct 15 00:22:53 volumio sudo[2562]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 15 00:22:53 volumio sudo[2562]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 00:22:53 volumio volumio[1298]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 15 00:22:53 volumio volumio[1298]: info: MPD Permissions set Oct 15 00:22:53 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 00:22:53 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 00:22:53 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 00:22:53 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 00:22:53 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 00:22:53 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 00:22:53 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 00:22:53 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 00:22:53 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 15 00:22:53 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 15 00:22:53 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 15 00:22:53 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 15 00:22:53 volumio volumio[1298]: info: Starting Shairport Sync Oct 15 00:22:54 volumio systemd[1]: mpd.service: Deactivated successfully. Oct 15 00:22:54 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 15 00:22:54 volumio systemd[1]: mpd.socket: Deactivated successfully. Oct 15 00:22:54 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 15 00:22:54 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 15 00:22:54 volumio sudo[2578]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 15 00:22:54 volumio sudo[2578]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 00:22:54 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 15 00:22:54 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Oct 15 00:22:54 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 15 00:22:54 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Oct 15 00:22:54 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 15 00:22:54 volumio systemd[1]: shairport-sync.service: Consumed 1.590s CPU time. Oct 15 00:22:54 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 15 00:22:54 volumio sudo[2578]: pam_unix(sudo:session): session closed for user root Oct 15 00:22:54 volumio volumio[1298]: info: Shairport-Sync Started Oct 15 00:22:54 volumio sudo[2581]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 15 00:22:54 volumio sudo[2581]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 15 00:22:54 volumio sudo[2581]: pam_unix(sudo:session): session closed for user root Oct 15 00:22:54 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Oct 15 00:22:54 volumio mpd[2598]: 2026-10-15T00:22:54 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 15 00:22:54 volumio systemd[1]: Started mpd.service - Music Player Daemon. Oct 15 00:22:54 volumio sudo[2562]: pam_unix(sudo:session): session closed for user root Oct 15 00:22:54 volumio volumio[1298]: error: updateQueue error: null Oct 15 00:22:56 volumio volumio[1298]: info: CoreCommandRouter::volumioPlay Oct 15 00:22:56 volumio volumio[1298]: info: CoreStateMachine::play index undefined Oct 15 00:22:56 volumio volumio[1298]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 15 00:22:56 volumio volumio[1298]: info: CorePlayQueue::getTrack 428 Oct 15 00:22:56 volumio volumio[1298]: info: CoreStateMachine::startPlaybackTimer Oct 15 00:22:56 volumio volumio[1298]: info: CorePlayQueue::getTrack 428 Oct 15 00:22:56 volumio volumio[1298]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/One Direction - Heart Attack.mp3 Oct 15 00:22:56 volumio volumio[1298]: verbose: ControllerMpd::sendMpdCommand stop Oct 15 00:22:56 volumio volumio[1298]: info: sendMpdCommand stop took 1 milliseconds Oct 15 00:22:56 volumio volumio[1298]: verbose: ControllerMpd::sendMpdCommand clear Oct 15 00:22:56 volumio volumio[1298]: info: Oct 15 00:22:56 volumio volumio[1298]: ---------------------------- MPD announces system playlist update Oct 15 00:22:56 volumio volumio[1298]: info: Ignoring MPD Status Update Oct 15 00:22:56 volumio volumio[1298]: info: sendMpdCommand clear took 0 milliseconds Oct 15 00:22:56 volumio volumio[1298]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/One Direction - Heart Attack.mp3" Oct 15 00:22:56 volumio volumio[1298]: error: updateQueue error: null Oct 15 00:22:56 volumio volumio[1298]: info: ------------------------------ 1ms Oct 15 00:22:56 volumio volumio[1298]: info: Oct 15 00:22:56 volumio volumio[1298]: ---------------------------- MPD announces system playlist update Oct 15 00:22:56 volumio volumio[1298]: info: Ignoring MPD Status Update Oct 15 00:22:56 volumio volumio[1298]: info: sendMpdCommand add "INTERNAL/One Direction - Heart Attack.mp3" took 1 milliseconds Oct 15 00:22:56 volumio volumio[1298]: verbose: ControllerMpd::sendMpdCommand play Oct 15 00:22:56 volumio volumio[1298]: info: ------------------------------ 1ms Oct 15 00:22:56 volumio volumio[1298]: info: sendMpdCommand play took 1 milliseconds Oct 15 00:22:56 volumio volumio[1298]: info: Oct 15 00:22:56 volumio volumio[1298]: ---------------------------- MPD announces state update: player Oct 15 00:22:56 volumio volumio[1298]: info: ControllerMpd::getState Oct 15 00:22:56 volumio volumio[1298]: verbose: ControllerMpd::sendMpdCommand status Oct 15 00:22:56 volumio volumio[1298]: info: Oct 15 00:22:56 volumio volumio[1298]: ---------------------------- MPD announces state update: player Oct 15 00:22:56 volumio volumio[1298]: info: sendMpdCommand status took 37 milliseconds Oct 15 00:22:56 volumio volumio[1298]: info: ControllerMpd::getState Oct 15 00:22:56 volumio volumio[1298]: verbose: ControllerMpd::sendMpdCommand status Oct 15 00:22:56 volumio volumio[1298]: verbose: ControllerMpd::parseState Oct 15 00:22:56 volumio volumio[1298]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 15 00:22:56 volumio volumio[1298]: info: sendMpdCommand status took 1 milliseconds Oct 15 00:22:56 volumio volumio[1298]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 15 00:22:56 volumio volumio[1298]: verbose: ControllerMpd::parseState Oct 15 00:22:56 volumio volumio[1298]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 15 00:22:56 volumio volumio[1298]: verbose: ControllerMpd::parseTrackInfo Oct 15 00:22:56 volumio volumio[1298]: info: ControllerMpd::pushState Oct 15 00:22:56 volumio volumio[1298]: info: CoreCommandRouter::servicePushState Oct 15 00:22:56 volumio volumio[1298]: info: CorePlayQueue::getTrack 428 Oct 15 00:22:56 volumio volumio[1298]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":175,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Heart Attack","artist":"One Direction","album":"Take Me Home","uri":"INTERNAL/One Direction - Heart Attack.mp3","trackType":"mp3"} Oct 15 00:22:56 volumio volumio[1298]: verbose: CURRENT POSITION 428 Oct 15 00:22:56 volumio volumio[1298]: info: CoreStateMachine::syncState stateService play Oct 15 00:22:56 volumio volumio[1298]: info: CoreStateMachine::syncState currentStatus stop Oct 15 00:22:56 volumio volumio[1298]: info: ------------------------------ 43ms Oct 15 00:22:56 volumio volumio[1298]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 15 00:22:56 volumio volumio[1298]: verbose: ControllerMpd::parseTrackInfo Oct 15 00:22:56 volumio volumio[1298]: info: ControllerMpd::pushState Oct 15 00:22:56 volumio volumio[1298]: info: CoreCommandRouter::servicePushState Oct 15 00:22:56 volumio volumio[1298]: info: CorePlayQueue::getTrack 428 Oct 15 00:22:56 volumio volumio[1298]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":175,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Heart Attack","artist":"One Direction","album":"Take Me Home","uri":"INTERNAL/One Direction - Heart Attack.mp3","trackType":"mp3"} Oct 15 00:22:56 volumio volumio[1298]: verbose: CURRENT POSITION 428 Oct 15 00:22:56 volumio volumio[1298]: info: CoreStateMachine::syncState stateService play Oct 15 00:22:56 volumio volumio[1298]: info: CoreStateMachine::syncState currentStatus play Oct 15 00:22:56 volumio volumio[1298]: info: Received an update from plugin. extracting info from payload Oct 15 00:22:56 volumio volumio[1298]: info: CoreStateMachine::pushState Oct 15 00:22:56 volumio volumio[1298]: info: CorePlayQueue::getTrack 428 Oct 15 00:22:56 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 15 00:22:56 volumio volumio[1298]: info: CoreCommandRouter::volumioPushState Oct 15 00:22:56 volumio volumio[1298]: info: CoreStateMachine::pushState Oct 15 00:22:56 volumio volumio[1298]: info: CorePlayQueue::getTrack 428 Oct 15 00:22:56 volumio volumio[1298]: info: CoreCommandRouter::volumioPushState Oct 15 00:22:56 volumio volumio[1298]: info: ------------------------------ 24ms Oct 15 00:22:57 volumio volumio[1298]: info: CoreCommandRouter::volumioPause Oct 15 00:22:57 volumio volumio[1298]: info: CoreStateMachine::pause Oct 15 00:22:57 volumio volumio[1298]: info: CoreStateMachine::stPlaybackTimer Oct 15 00:22:57 volumio volumio[1298]: info: CoreStateMachine::servicePause Oct 15 00:22:57 volumio volumio[1298]: info: CorePlayQueue::getTrack 428 Oct 15 00:22:57 volumio volumio[1298]: info: CoreCommandRouter::servicePause Oct 15 00:22:57 volumio volumio[1298]: info: ControllerMpd::pause Oct 15 00:22:57 volumio volumio[1298]: verbose: ControllerMpd::sendMpdCommand pause Oct 15 00:22:57 volumio volumio[1298]: info: sendMpdCommand pause took 1 milliseconds Oct 15 00:22:57 volumio volumio[1298]: info: Oct 15 00:22:57 volumio volumio[1298]: ---------------------------- MPD announces state update: player Oct 15 00:22:57 volumio volumio[1298]: info: ControllerMpd::getState Oct 15 00:22:57 volumio volumio[1298]: verbose: ControllerMpd::sendMpdCommand status Oct 15 00:22:57 volumio volumio[1298]: info: sendMpdCommand status took 0 milliseconds Oct 15 00:22:57 volumio volumio[1298]: verbose: ControllerMpd::parseState Oct 15 00:22:57 volumio volumio[1298]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 15 00:22:57 volumio volumio[1298]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 15 00:22:57 volumio volumio[1298]: verbose: ControllerMpd::parseTrackInfo Oct 15 00:22:57 volumio volumio[1298]: info: ControllerMpd::pushState Oct 15 00:22:57 volumio volumio[1298]: info: CoreCommandRouter::servicePushState Oct 15 00:22:57 volumio volumio[1298]: info: CorePlayQueue::getTrack 428 Oct 15 00:22:57 volumio volumio[1298]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":1847,"duration":175,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Heart Attack","artist":"One Direction","album":"Take Me Home","uri":"INTERNAL/One Direction - Heart Attack.mp3","trackType":"mp3"} Oct 15 00:22:57 volumio volumio[1298]: verbose: CURRENT POSITION 428 Oct 15 00:22:57 volumio volumio[1298]: info: CoreStateMachine::syncState stateService pause Oct 15 00:22:57 volumio volumio[1298]: info: CoreStateMachine::syncState currentStatus pause Oct 15 00:22:57 volumio volumio[1298]: info: CoreStateMachine::pushState Oct 15 00:22:57 volumio volumio[1298]: info: CorePlayQueue::getTrack 428 Oct 15 00:22:57 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 15 00:22:57 volumio volumio[1298]: info: CoreCommandRouter::volumioPushState Oct 15 00:22:57 volumio volumio[1298]: info: CoreStateMachine::stPlaybackTimer Oct 15 00:22:57 volumio volumio[1298]: info: ------------------------------ 9ms Oct 15 00:22:57 volumio volumio[1298]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Oct 15 00:22:57 volumio volumio[1298]: info: CoreStateMachine::getcurrentVolume Oct 15 00:22:57 volumio volumio[1298]: info: CoreCommandRouter::volumioRetrievevolume Oct 15 00:22:57 volumio volumio[1298]: info: CoreStateMachine::pushState Oct 15 00:22:57 volumio volumio[1298]: info: CorePlayQueue::getTrack 428 Oct 15 00:22:57 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 15 00:22:57 volumio volumio[1298]: info: CoreCommandRouter::volumioPushState Oct 15 00:22:58 volumio volumio[1298]: info: CoreCommandRouter::volumioPlay Oct 15 00:22:58 volumio volumio[1298]: info: CoreStateMachine::play index undefined Oct 15 00:22:58 volumio volumio[1298]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 15 00:22:58 volumio volumio[1298]: info: CorePlayQueue::getTrack 428 Oct 15 00:22:58 volumio volumio[1298]: info: CoreStateMachine::startPlaybackTimer Oct 15 00:22:58 volumio volumio[1298]: info: CorePlayQueue::getTrack 428 Oct 15 00:22:58 volumio volumio[1298]: info: ControllerMpd::resume Oct 15 00:22:58 volumio volumio[1298]: verbose: ControllerMpd::sendMpdCommand play Oct 15 00:22:58 volumio volumio[1298]: info: Oct 15 00:22:58 volumio volumio[1298]: ---------------------------- MPD announces state update: player Oct 15 00:22:58 volumio volumio[1298]: info: sendMpdCommand play took 5 milliseconds Oct 15 00:22:58 volumio volumio[1298]: info: ControllerMpd::getState Oct 15 00:22:58 volumio volumio[1298]: verbose: ControllerMpd::sendMpdCommand status Oct 15 00:22:58 volumio volumio[1298]: info: sendMpdCommand status took 1 milliseconds Oct 15 00:22:58 volumio volumio[1298]: verbose: ControllerMpd::parseState Oct 15 00:22:58 volumio volumio[1298]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 15 00:22:58 volumio volumio[1298]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 15 00:22:58 volumio volumio[1298]: verbose: ControllerMpd::parseTrackInfo Oct 15 00:22:58 volumio volumio[1298]: info: ControllerMpd::pushState Oct 15 00:22:58 volumio volumio[1298]: info: CoreCommandRouter::servicePushState Oct 15 00:22:58 volumio volumio[1298]: info: CorePlayQueue::getTrack 428 Oct 15 00:22:58 volumio volumio[1298]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2714,"duration":175,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Heart Attack","artist":"One Direction","album":"Take Me Home","uri":"INTERNAL/One Direction - Heart Attack.mp3","trackType":"mp3"} Oct 15 00:22:58 volumio volumio[1298]: verbose: CURRENT POSITION 428 Oct 15 00:22:58 volumio volumio[1298]: info: CoreStateMachine::syncState stateService play Oct 15 00:22:58 volumio volumio[1298]: info: CoreStateMachine::syncState currentStatus pause Oct 15 00:22:58 volumio volumio[1298]: info: CoreStateMachine::pushState Oct 15 00:22:58 volumio volumio[1298]: info: CorePlayQueue::getTrack 428 Oct 15 00:22:58 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 15 00:22:58 volumio volumio[1298]: info: CoreCommandRouter::volumioPushState Oct 15 00:22:58 volumio volumio[1298]: info: ------------------------------ 12ms Oct 15 00:23:01 volumio volumio[1298]: info: CoreCommandRouter::volumioNext Oct 15 00:23:01 volumio volumio[1298]: info: CoreStateMachine::next Oct 15 00:23:01 volumio volumio[1298]: info: CoreStateMachine::stop Oct 15 00:23:01 volumio volumio[1298]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 15 00:23:01 volumio volumio[1298]: info: CoreStateMachine::stPlaybackTimer Oct 15 00:23:01 volumio volumio[1298]: info: CoreStateMachine::updateTrackBlock Oct 15 00:23:01 volumio volumio[1298]: info: CorePlayQueue::getTrackBlock Oct 15 00:23:01 volumio volumio[1298]: info: CoreStateMachine::pushState Oct 15 00:23:01 volumio volumio[1298]: info: CorePlayQueue::getTrack 428 Oct 15 00:23:01 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 15 00:23:01 volumio volumio[1298]: info: CoreCommandRouter::volumioPushState Oct 15 00:23:01 volumio volumio[1298]: info: CoreStateMachine::serviceStop Oct 15 00:23:01 volumio volumio[1298]: info: CorePlayQueue::getTrack 428 Oct 15 00:23:01 volumio volumio[1298]: info: CoreCommandRouter::serviceStop Oct 15 00:23:01 volumio volumio[1298]: info: ControllerMpd::stop Oct 15 00:23:01 volumio volumio[1298]: verbose: ControllerMpd::sendMpdCommand stop Oct 15 00:23:01 volumio volumio[1298]: info: Oct 15 00:23:01 volumio volumio[1298]: ---------------------------- MPD announces state update: player Oct 15 00:23:01 volumio volumio[1298]: info: sendMpdCommand stop took 22 milliseconds Oct 15 00:23:01 volumio volumio[1298]: info: ControllerMpd::getState Oct 15 00:23:01 volumio volumio[1298]: verbose: ControllerMpd::sendMpdCommand status Oct 15 00:23:01 volumio volumio[1298]: info: CoreStateMachine::play index undefined Oct 15 00:23:01 volumio volumio[1298]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 15 00:23:01 volumio volumio[1298]: info: CorePlayQueue::getTrack 343 Oct 15 00:23:01 volumio volumio[1298]: info: CoreStateMachine::startPlaybackTimer Oct 15 00:23:01 volumio volumio[1298]: info: CorePlayQueue::getTrack 343 Oct 15 00:23:01 volumio volumio[1298]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/July Child - Leave Me Out.mp3 Oct 15 00:23:01 volumio volumio[1298]: verbose: ControllerMpd::sendMpdCommand stop Oct 15 00:23:01 volumio volumio[1298]: info: CoreStateMachine::updateTrackBlock Oct 15 00:23:01 volumio volumio[1298]: info: CorePlayQueue::getTrackBlock Oct 15 00:23:01 volumio volumio[1298]: info: sendMpdCommand status took 2 milliseconds Oct 15 00:23:01 volumio volumio[1298]: info: sendMpdCommand stop took 1 milliseconds Oct 15 00:23:01 volumio volumio[1298]: verbose: ControllerMpd::parseState Oct 15 00:23:01 volumio volumio[1298]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 15 00:23:01 volumio volumio[1298]: verbose: ControllerMpd::sendMpdCommand clear Oct 15 00:23:01 volumio volumio[1298]: info: Oct 15 00:23:01 volumio volumio[1298]: ---------------------------- MPD announces system playlist update Oct 15 00:23:01 volumio volumio[1298]: info: Ignoring MPD Status Update Oct 15 00:23:01 volumio volumio[1298]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 15 00:23:01 volumio volumio[1298]: info: sendMpdCommand clear took 1 milliseconds Oct 15 00:23:01 volumio volumio[1298]: verbose: ControllerMpd::parseTrackInfo Oct 15 00:23:01 volumio volumio[1298]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/July Child - Leave Me Out.mp3" Oct 15 00:23:01 volumio volumio[1298]: info: ControllerMpd::pushState Oct 15 00:23:01 volumio volumio[1298]: info: CoreCommandRouter::servicePushState Oct 15 00:23:01 volumio volumio[1298]: info: CoreStateMachine::pushState Oct 15 00:23:01 volumio volumio[1298]: info: CorePlayQueue::getTrack 343 Oct 15 00:23:01 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 15 00:23:01 volumio volumio[1298]: info: CoreCommandRouter::volumioPushState Oct 15 00:23:01 volumio volumio[1298]: info: CorePlayQueue::getTrack 343 Oct 15 00:23:01 volumio volumio[1298]: 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":"Heart Attack","artist":"One Direction","album":"Take Me Home","uri":"INTERNAL/One Direction - Heart Attack.mp3","trackType":"mp3"} Oct 15 00:23:01 volumio volumio[1298]: verbose: CURRENT POSITION 343 Oct 15 00:23:01 volumio volumio[1298]: info: CoreStateMachine::syncState stateService stop Oct 15 00:23:01 volumio volumio[1298]: info: CoreStateMachine::syncState currentStatus stop Oct 15 00:23:01 volumio volumio[1298]: info: CoreStateMachine::pushState Oct 15 00:23:01 volumio volumio[1298]: info: CorePlayQueue::getTrack 343 Oct 15 00:23:01 volumio volumio[1298]: info: CoreCommandRouter::volumioPushState Oct 15 00:23:01 volumio volumio[1298]: info: No code Oct 15 00:23:01 volumio volumio[1298]: info: CoreStateMachine::pushState Oct 15 00:23:01 volumio volumio[1298]: info: CorePlayQueue::getTrack 343 Oct 15 00:23:01 volumio volumio[1298]: info: CoreCommandRouter::volumioPushState Oct 15 00:23:01 volumio volumio[1298]: info: ------------------------------ 24ms Oct 15 00:23:01 volumio volumio[1298]: error: updateQueue error: null Oct 15 00:23:01 volumio volumio[1298]: info: Oct 15 00:23:01 volumio volumio[1298]: ---------------------------- MPD announces system playlist update Oct 15 00:23:01 volumio volumio[1298]: info: Ignoring MPD Status Update Oct 15 00:23:01 volumio volumio[1298]: info: ------------------------------ 21ms Oct 15 00:23:01 volumio volumio[1298]: info: sendMpdCommand add "INTERNAL/July Child - Leave Me Out.mp3" took 21 milliseconds Oct 15 00:23:01 volumio volumio[1298]: verbose: ControllerMpd::sendMpdCommand play Oct 15 00:23:01 volumio volumio[1298]: info: Oct 15 00:23:01 volumio volumio[1298]: ---------------------------- MPD announces state update: player Oct 15 00:23:01 volumio volumio[1298]: info: ------------------------------ 12ms Oct 15 00:23:01 volumio volumio[1298]: info: sendMpdCommand play took 11 milliseconds Oct 15 00:23:01 volumio volumio[1298]: info: ControllerMpd::getState Oct 15 00:23:01 volumio volumio[1298]: verbose: ControllerMpd::sendMpdCommand status Oct 15 00:23:01 volumio volumio[1298]: info: Oct 15 00:23:01 volumio volumio[1298]: ---------------------------- MPD announces state update: player Oct 15 00:23:01 volumio volumio[1298]: info: ControllerMpd::getState Oct 15 00:23:01 volumio volumio[1298]: verbose: ControllerMpd::sendMpdCommand status Oct 15 00:23:01 volumio volumio[1298]: info: sendMpdCommand status took 3 milliseconds Oct 15 00:23:01 volumio volumio[1298]: verbose: ControllerMpd::parseState Oct 15 00:23:01 volumio volumio[1298]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 15 00:23:01 volumio volumio[1298]: info: sendMpdCommand status took 5 milliseconds Oct 15 00:23:01 volumio volumio[1298]: info: sendMpdCommand playlistinfo took 11 milliseconds Oct 15 00:23:01 volumio volumio[1298]: verbose: ControllerMpd::parseState Oct 15 00:23:01 volumio volumio[1298]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 15 00:23:01 volumio volumio[1298]: verbose: ControllerMpd::parseTrackInfo Oct 15 00:23:01 volumio volumio[1298]: info: ControllerMpd::pushState Oct 15 00:23:01 volumio volumio[1298]: info: CoreCommandRouter::servicePushState Oct 15 00:23:01 volumio volumio[1298]: info: CorePlayQueue::getTrack 343 Oct 15 00:23:01 volumio volumio[1298]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":332,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Leave Me Out","artist":"July Child","album":"Leave Me Out","uri":"INTERNAL/July Child - Leave Me Out.mp3","trackType":"mp3"} Oct 15 00:23:01 volumio volumio[1298]: verbose: CURRENT POSITION 343 Oct 15 00:23:01 volumio volumio[1298]: info: CoreStateMachine::syncState stateService play Oct 15 00:23:01 volumio volumio[1298]: info: CoreStateMachine::syncState currentStatus stop Oct 15 00:23:01 volumio volumio[1298]: info: ------------------------------ 19ms Oct 15 00:23:01 volumio volumio[1298]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 15 00:23:01 volumio volumio[1298]: verbose: ControllerMpd::parseTrackInfo Oct 15 00:23:01 volumio volumio[1298]: info: ControllerMpd::pushState Oct 15 00:23:01 volumio volumio[1298]: info: CoreCommandRouter::servicePushState Oct 15 00:23:01 volumio volumio[1298]: info: CorePlayQueue::getTrack 343 Oct 15 00:23:01 volumio volumio[1298]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":332,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Leave Me Out","artist":"July Child","album":"Leave Me Out","uri":"INTERNAL/July Child - Leave Me Out.mp3","trackType":"mp3"} Oct 15 00:23:01 volumio volumio[1298]: verbose: CURRENT POSITION 343 Oct 15 00:23:01 volumio volumio[1298]: info: CoreStateMachine::syncState stateService play Oct 15 00:23:01 volumio volumio[1298]: info: CoreStateMachine::syncState currentStatus play Oct 15 00:23:01 volumio volumio[1298]: info: Received an update from plugin. extracting info from payload Oct 15 00:23:01 volumio volumio[1298]: info: CoreStateMachine::pushState Oct 15 00:23:01 volumio volumio[1298]: info: CorePlayQueue::getTrack 343 Oct 15 00:23:01 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 15 00:23:01 volumio volumio[1298]: info: CoreCommandRouter::volumioPushState Oct 15 00:23:01 volumio volumio[1298]: info: CoreStateMachine::pushState Oct 15 00:23:01 volumio volumio[1298]: info: CorePlayQueue::getTrack 343 Oct 15 00:23:01 volumio volumio[1298]: info: CoreCommandRouter::volumioPushState Oct 15 00:23:01 volumio volumio[1298]: info: ------------------------------ 46ms Oct 15 00:23:07 volumio volumio[1298]: info: CoreCommandRouter::volumioNext Oct 15 00:23:07 volumio volumio[1298]: info: CoreStateMachine::next Oct 15 00:23:07 volumio volumio[1298]: info: CoreStateMachine::stop Oct 15 00:23:07 volumio volumio[1298]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 15 00:23:07 volumio volumio[1298]: info: CoreStateMachine::stPlaybackTimer Oct 15 00:23:07 volumio volumio[1298]: info: CoreStateMachine::updateTrackBlock Oct 15 00:23:07 volumio volumio[1298]: info: CorePlayQueue::getTrackBlock Oct 15 00:23:07 volumio volumio[1298]: info: CoreStateMachine::pushState Oct 15 00:23:07 volumio volumio[1298]: info: CorePlayQueue::getTrack 343 Oct 15 00:23:07 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 15 00:23:07 volumio volumio[1298]: info: CoreCommandRouter::volumioPushState Oct 15 00:23:07 volumio volumio[1298]: info: CoreStateMachine::serviceStop Oct 15 00:23:07 volumio volumio[1298]: info: CorePlayQueue::getTrack 343 Oct 15 00:23:07 volumio volumio[1298]: info: CoreCommandRouter::serviceStop Oct 15 00:23:07 volumio volumio[1298]: info: ControllerMpd::stop Oct 15 00:23:07 volumio volumio[1298]: verbose: ControllerMpd::sendMpdCommand stop Oct 15 00:23:07 volumio volumio[1298]: info: Oct 15 00:23:07 volumio volumio[1298]: ---------------------------- MPD announces state update: player Oct 15 00:23:07 volumio volumio[1298]: info: sendMpdCommand stop took 25 milliseconds Oct 15 00:23:07 volumio volumio[1298]: info: ControllerMpd::getState Oct 15 00:23:07 volumio volumio[1298]: verbose: ControllerMpd::sendMpdCommand status Oct 15 00:23:07 volumio volumio[1298]: info: CoreStateMachine::play index undefined Oct 15 00:23:07 volumio volumio[1298]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 15 00:23:07 volumio volumio[1298]: info: CorePlayQueue::getTrack 201 Oct 15 00:23:07 volumio volumio[1298]: info: CoreStateMachine::startPlaybackTimer Oct 15 00:23:07 volumio volumio[1298]: info: CorePlayQueue::getTrack 201 Oct 15 00:23:07 volumio volumio[1298]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/D12 - U R The One.mp3 Oct 15 00:23:07 volumio volumio[1298]: verbose: ControllerMpd::sendMpdCommand stop Oct 15 00:23:07 volumio volumio[1298]: info: CoreStateMachine::updateTrackBlock Oct 15 00:23:07 volumio volumio[1298]: info: CorePlayQueue::getTrackBlock Oct 15 00:23:07 volumio volumio[1298]: info: sendMpdCommand status took 4 milliseconds Oct 15 00:23:07 volumio volumio[1298]: info: sendMpdCommand stop took 2 milliseconds Oct 15 00:23:07 volumio volumio[1298]: verbose: ControllerMpd::parseState Oct 15 00:23:07 volumio volumio[1298]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 15 00:23:07 volumio volumio[1298]: verbose: ControllerMpd::sendMpdCommand clear Oct 15 00:23:07 volumio volumio[1298]: info: Oct 15 00:23:07 volumio volumio[1298]: ---------------------------- MPD announces system playlist update Oct 15 00:23:07 volumio volumio[1298]: info: Ignoring MPD Status Update Oct 15 00:23:07 volumio volumio[1298]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 15 00:23:07 volumio volumio[1298]: info: sendMpdCommand clear took 2 milliseconds Oct 15 00:23:07 volumio volumio[1298]: verbose: ControllerMpd::parseTrackInfo Oct 15 00:23:07 volumio volumio[1298]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/D12 - U R The One.mp3" Oct 15 00:23:07 volumio volumio[1298]: info: ControllerMpd::pushState Oct 15 00:23:07 volumio volumio[1298]: info: CoreCommandRouter::servicePushState Oct 15 00:23:07 volumio volumio[1298]: info: CoreStateMachine::pushState Oct 15 00:23:07 volumio volumio[1298]: info: CorePlayQueue::getTrack 201 Oct 15 00:23:07 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 15 00:23:07 volumio volumio[1298]: info: CoreCommandRouter::volumioPushState Oct 15 00:23:07 volumio volumio[1298]: info: CorePlayQueue::getTrack 201 Oct 15 00:23:07 volumio volumio[1298]: 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":"Leave Me Out","artist":"July Child","album":"Leave Me Out","uri":"INTERNAL/July Child - Leave Me Out.mp3","trackType":"mp3"} Oct 15 00:23:07 volumio volumio[1298]: verbose: CURRENT POSITION 201 Oct 15 00:23:07 volumio volumio[1298]: info: CoreStateMachine::syncState stateService stop Oct 15 00:23:07 volumio volumio[1298]: info: CoreStateMachine::syncState currentStatus stop Oct 15 00:23:07 volumio volumio[1298]: info: CoreStateMachine::pushState Oct 15 00:23:07 volumio volumio[1298]: info: CorePlayQueue::getTrack 201 Oct 15 00:23:07 volumio volumio[1298]: info: CoreCommandRouter::volumioPushState Oct 15 00:23:07 volumio volumio[1298]: info: No code Oct 15 00:23:07 volumio volumio[1298]: info: CoreStateMachine::pushState Oct 15 00:23:07 volumio volumio[1298]: info: CorePlayQueue::getTrack 201 Oct 15 00:23:07 volumio volumio[1298]: info: CoreCommandRouter::volumioPushState Oct 15 00:23:07 volumio volumio[1298]: info: ------------------------------ 37ms Oct 15 00:23:07 volumio volumio[1298]: error: updateQueue error: null Oct 15 00:23:07 volumio volumio[1298]: info: Oct 15 00:23:07 volumio volumio[1298]: ---------------------------- MPD announces system playlist update Oct 15 00:23:07 volumio volumio[1298]: info: Ignoring MPD Status Update Oct 15 00:23:07 volumio volumio[1298]: info: ------------------------------ 32ms Oct 15 00:23:07 volumio volumio[1298]: info: sendMpdCommand add "INTERNAL/D12 - U R The One.mp3" took 31 milliseconds Oct 15 00:23:07 volumio volumio[1298]: verbose: ControllerMpd::sendMpdCommand play Oct 15 00:23:07 volumio volumio[1298]: info: ------------------------------ 9ms Oct 15 00:23:07 volumio volumio[1298]: info: sendMpdCommand play took 8 milliseconds Oct 15 00:23:07 volumio volumio[1298]: info: Oct 15 00:23:07 volumio volumio[1298]: ---------------------------- MPD announces state update: player Oct 15 00:23:07 volumio volumio[1298]: info: ControllerMpd::getState Oct 15 00:23:07 volumio volumio[1298]: verbose: ControllerMpd::sendMpdCommand status Oct 15 00:23:07 volumio volumio[1298]: info: Oct 15 00:23:07 volumio volumio[1298]: ---------------------------- MPD announces state update: player Oct 15 00:23:07 volumio volumio[1298]: info: sendMpdCommand status took 3 milliseconds Oct 15 00:23:07 volumio volumio[1298]: info: ControllerMpd::getState Oct 15 00:23:07 volumio volumio[1298]: verbose: ControllerMpd::sendMpdCommand status Oct 15 00:23:07 volumio volumio[1298]: verbose: ControllerMpd::parseState Oct 15 00:23:07 volumio volumio[1298]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 15 00:23:07 volumio volumio[1298]: info: sendMpdCommand status took 37 milliseconds Oct 15 00:23:07 volumio volumio[1298]: info: sendMpdCommand playlistinfo took 37 milliseconds Oct 15 00:23:07 volumio volumio[1298]: verbose: ControllerMpd::parseState Oct 15 00:23:07 volumio volumio[1298]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 15 00:23:07 volumio volumio[1298]: verbose: ControllerMpd::parseTrackInfo Oct 15 00:23:07 volumio volumio[1298]: info: ControllerMpd::pushState Oct 15 00:23:07 volumio volumio[1298]: info: CoreCommandRouter::servicePushState Oct 15 00:23:07 volumio volumio[1298]: info: CorePlayQueue::getTrack 201 Oct 15 00:23:07 volumio volumio[1298]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":260,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"U R The One","artist":"D12","album":"D-12 World","uri":"INTERNAL/D12 - U R The One.mp3","trackType":"mp3"} Oct 15 00:23:07 volumio volumio[1298]: verbose: CURRENT POSITION 201 Oct 15 00:23:07 volumio volumio[1298]: info: CoreStateMachine::syncState stateService play Oct 15 00:23:07 volumio volumio[1298]: info: CoreStateMachine::syncState currentStatus stop Oct 15 00:23:07 volumio volumio[1298]: info: ------------------------------ 48ms Oct 15 00:23:07 volumio volumio[1298]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 15 00:23:07 volumio volumio[1298]: verbose: ControllerMpd::parseTrackInfo Oct 15 00:23:07 volumio volumio[1298]: info: ControllerMpd::pushState Oct 15 00:23:07 volumio volumio[1298]: info: CoreCommandRouter::servicePushState Oct 15 00:23:07 volumio volumio[1298]: info: CorePlayQueue::getTrack 201 Oct 15 00:23:07 volumio volumio[1298]: verbose: STATE SERVICE {"status":"play","position":0,"seek":844,"duration":260,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"U R The One","artist":"D12","album":"D-12 World","uri":"INTERNAL/D12 - U R The One.mp3","trackType":"mp3"} Oct 15 00:23:07 volumio volumio[1298]: verbose: CURRENT POSITION 201 Oct 15 00:23:07 volumio volumio[1298]: info: CoreStateMachine::syncState stateService play Oct 15 00:23:07 volumio volumio[1298]: info: CoreStateMachine::syncState currentStatus play Oct 15 00:23:07 volumio volumio[1298]: info: Received an update from plugin. extracting info from payload Oct 15 00:23:07 volumio volumio[1298]: info: CoreStateMachine::pushState Oct 15 00:23:07 volumio volumio[1298]: info: CorePlayQueue::getTrack 201 Oct 15 00:23:07 volumio volumio[1298]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 15 00:23:07 volumio volumio[1298]: info: CoreCommandRouter::volumioPushState Oct 15 00:23:07 volumio volumio[1298]: info: CoreStateMachine::pushState Oct 15 00:23:07 volumio volumio[1298]: info: CorePlayQueue::getTrack 201 Oct 15 00:23:07 volumio volumio[1298]: info: CoreCommandRouter::volumioPushState Oct 15 00:23:07 volumio volumio[1298]: info: ------------------------------ 72ms Oct 15 00:23:08 volumio volumio[1298]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 15 00:23:08 volumio volumio[1298]: Error: connect ECONNREFUSED 157.240.21.9:443 Oct 15 00:23:08 volumio volumio[1298]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Oct 15 00:23:08 volumio volumio[1298]: errno: -111, Oct 15 00:23:08 volumio volumio[1298]: code: 'ECONNREFUSED', Oct 15 00:23:08 volumio volumio[1298]: syscall: 'connect', Oct 15 00:23:08 volumio volumio[1298]: address: '157.240.21.9', Oct 15 00:23:08 volumio volumio[1298]: port: 443 Oct 15 00:23:08 volumio volumio[1298]: } Oct 15 00:23:08 volumio volumio[1298]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 15 00:23:08 volumio sudo[2658]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-15 00:22' Oct 15 00:23:08 volumio sudo[2658]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:45:45 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6bf7cd61fe53483b72878254df87f1c0"