-- Logs begin at Fri 2023-05-05 04:07:10 UTC, end at Wed 2026-08-26 20:53:26 UTC. -- Aug 26 20:53:02 volumio-office ntpd[979]: receive: Unexpected origin timestamp 0xe7feff83.fcd7366f does not match aorg 0000000000.00000000 from server@162.159.200.123 xmt 0xee39d0ae.b509f586 Aug 26 20:53:02 volumio-office ntpd[979]: receive: Unexpected origin timestamp 0xe7feff83.fcd9adfe does not match aorg 0000000000.00000000 from server@43.252.70.34 xmt 0xee39d0ae.b5bb7b6b Aug 26 20:53:02 volumio-office systemd[1]: Starting Daily man-db regeneration... Aug 26 20:53:02 volumio-office ntpd[979]: receive: Unexpected origin timestamp 0xe7feff83.fcdae2f9 does not match aorg 0000000000.00000000 from server@203.190.212.96 xmt 0xee39d0ae.b5177b57 Aug 26 20:53:02 volumio-office systemd[1]: Starting Daily apt download activities... Aug 26 20:53:02 volumio-office ntpd[979]: receive: Unexpected origin timestamp 0xe7feff83.fcd5d96a does not match aorg 0000000000.00000000 from server@103.158.134.33 xmt 0xee39d0ae.b75a0fb7 Aug 26 20:53:02 volumio-office ntpd[979]: receive: Unexpected origin timestamp 0xe7feff83.fcd425e9 does not match aorg 0000000000.00000000 from server@103.106.65.219 xmt 0xee39d0ae.ba64e1a8 Aug 26 20:53:02 volumio-office systemd[1]: man-db.service: Succeeded. Aug 26 20:53:02 volumio-office systemd[1]: Started Daily man-db regeneration. Aug 26 20:53:02 volumio-office ntpd[979]: receive: Unexpected origin timestamp 0xe7feff83.fcca616c does not match aorg 0000000000.00000000 from server@66.118.230.14 xmt 0xee39d0ae.c360cfbe Aug 26 20:53:02 volumio-office ntpd[979]: receive: Unexpected origin timestamp 0xe7feff83.fcd26e63 does not match aorg 0000000000.00000000 from server@216.230.233.39 xmt 0xee39d0ae.ca2b78a2 Aug 26 20:53:02 volumio-office ntpd[979]: receive: Unexpected origin timestamp 0xe7feff83.fcd09cfb does not match aorg 0000000000.00000000 from server@149.28.61.105 xmt 0xee39d0ae.cab12914 Aug 26 20:53:02 volumio-office volumio[985]: info: Loading plugin "outputs"... Aug 26 20:53:02 volumio-office volumio[985]: info: Loading plugin "albumart"... Aug 26 20:53:02 volumio-office volumio[985]: info: Plugin example_plugin is not enabled Aug 26 20:53:02 volumio-office volumio[985]: info: Loading plugin "inputs"... Aug 26 20:53:02 volumio-office volumio[985]: info: Loading plugin "updater_comm"... Aug 26 20:53:03 volumio-office volumio[985]: info: Plugin mpdemulation is not enabled Aug 26 20:53:03 volumio-office volumio[985]: info: Loading plugin "rest_api"... Aug 26 20:53:03 volumio-office volumio[985]: info: Loading plugin "websocket"... Aug 26 20:53:03 volumio-office volumio[985]: info: Loading plugin "volspotconnect2"... Aug 26 20:53:03 volumio-office volumio[985]: Forking 3 albumart workers Aug 26 20:53:03 volumio-office volumio[985]: Starting albumart workers Aug 26 20:53:03 volumio-office volumio[985]: info: Loading i18n strings for locale en Aug 26 20:53:03 volumio-office volumio[985]: Updating browse sources language Aug 26 20:53:03 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 26 20:53:03 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 20:53:03 volumio-office volumio[985]: info: CoreCommandRouter::initPlayerControls Aug 26 20:53:03 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 20:53:03 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 20:53:03 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 20:53:03 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 20:53:03 volumio-office volumio[985]: Starting albumart workers Aug 26 20:53:03 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 20:53:03 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 20:53:03 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 20:53:03 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 20:53:03 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 20:53:03 volumio-office volumio[985]: Express server listening on port 3000 Aug 26 20:53:03 volumio-office volumio[985]: [Metrics] WebUI: 8s 830.06ms Aug 26 20:53:03 volumio-office volumio[985]: Starting albumart workers Aug 26 20:53:04 volumio-office volumio[985]: info: CoreStateMachine::resetVolumioState Aug 26 20:53:04 volumio-office volumio[985]: info: CoreStateMachine::getcurrentVolume Aug 26 20:53:04 volumio-office volumio[985]: info: CoreCommandRouter::volumioRetrievevolume Aug 26 20:53:04 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 20:53:04 volumio-office volumio[985]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Aug 26 20:53:04 volumio-office volumio[985]: info: Completed loading Core Plugins Aug 26 20:53:04 volumio-office volumio[985]: info: Preparing to generate the ALSA configuration file Aug 26 20:53:04 volumio-office volumio[985]: info: MPD Permissions set Aug 26 20:53:04 volumio-office volumio[985]: verbose: New Socket.io Connection to 192.168.1.72 from 192.168.1.189 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.6 Safari/605.1.15 Total Clients: 1 Aug 26 20:53:04 volumio-office volumio[985]: verbose: New Socket.io Connection to 192.168.1.72 from 192.168.1.189 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.6 Safari/605.1.15 Total Clients: 2 Aug 26 20:53:04 volumio-office volumio[985]: verbose: New Socket.io Connection to 192.168.1.72 from 192.168.1.189 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.6 Safari/605.1.15 Total Clients: 3 Aug 26 20:53:04 volumio-office volumio[985]: verbose: New Socket.io Connection to 192.168.1.72 from 192.168.1.189 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.6 Safari/605.1.15 Total Clients: 4 Aug 26 20:53:04 volumio-office systemd[1]: apt-daily.service: Succeeded. Aug 26 20:53:04 volumio-office systemd[1]: Started Daily apt download activities. Aug 26 20:53:04 volumio-office systemd[1]: Starting Daily apt upgrade and clean activities... Aug 26 20:53:04 volumio-office volumio[985]: verbose: New Socket.io Connection to 192.168.1.72 from 192.168.1.189 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.6 Safari/605.1.15 Total Clients: 5 Aug 26 20:53:04 volumio-office volumio[985]: verbose: New Socket.io Connection to 192.168.1.72 from 192.168.1.189 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.6 Safari/605.1.15 Total Clients: 6 Aug 26 20:53:04 volumio-office volumio[985]: verbose: New Socket.io Connection to 192.168.1.72 from 192.168.1.189 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.6 Safari/605.1.15 Total Clients: 7 Aug 26 20:53:04 volumio-office volumio[985]: verbose: New Socket.io Connection to 192.168.1.72 from 192.168.1.189 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.6 Safari/605.1.15 Total Clients: 8 Aug 26 20:53:04 volumio-office volumio[985]: verbose: New Socket.io Connection to 192.168.1.72 from 192.168.1.189 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.6 Safari/605.1.15 Total Clients: 9 Aug 26 20:53:04 volumio-office volumio[985]: verbose: New Socket.io Connection to 192.168.1.72 from 192.168.1.189 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.6 Safari/605.1.15 Total Clients: 10 Aug 26 20:53:04 volumio-office volumio[985]: verbose: New Socket.io Connection to 192.168.1.72 from 192.168.1.189 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.6 Safari/605.1.15 Total Clients: 11 Aug 26 20:53:04 volumio-office volumio[985]: verbose: New Socket.io Connection to 192.168.1.72 from 192.168.1.189 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.6 Safari/605.1.15 Total Clients: 12 Aug 26 20:53:04 volumio-office volumio[985]: info: MPD running with PID1186 Aug 26 20:53:04 volumio-office volumio[985]: ,establishing connection Aug 26 20:53:04 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 20:53:04 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 20:53:04 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 20:53:04 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 20:53:04 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 20:53:04 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 20:53:04 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 20:53:04 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 20:53:04 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 20:53:04 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 26 20:53:04 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 20:53:04 volumio-office volumio[985]: info: Scanning new location : "USB/EFI" Aug 26 20:53:04 volumio-office volumio[985]: info: Reloading queue from file Aug 26 20:53:04 volumio-office volumio[985]: verbose: New Socket.io Connection to 192.168.1.72 from 192.168.1.189 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.6 Safari/605.1.15 Total Clients: 13 Aug 26 20:53:04 volumio-office volumio[985]: verbose: New Socket.io Connection to 192.168.1.72 from 192.168.1.189 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.6 Safari/605.1.15 Total Clients: 14 Aug 26 20:53:04 volumio-office volumio[985]: verbose: New Socket.io Connection to 192.168.1.72 from 192.168.1.189 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.6 Safari/605.1.15 Total Clients: 15 Aug 26 20:53:04 volumio-office volumio[985]: verbose: New Socket.io Connection to 192.168.1.72 from 192.168.1.189 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.6 Safari/605.1.15 Total Clients: 16 Aug 26 20:53:04 volumio-office volumio[985]: verbose: New Socket.io Connection to 192.168.1.72 from 192.168.1.189 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.6 Safari/605.1.15 Total Clients: 17 Aug 26 20:53:04 volumio-office volumio[985]: verbose: New Socket.io Connection to 192.168.1.72 from 192.168.1.189 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.6 Safari/605.1.15 Total Clients: 18 Aug 26 20:53:04 volumio-office volumio[985]: error: updateQueue error: null Aug 26 20:53:04 volumio-office volumio[985]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Aug 26 20:53:04 volumio-office volumio[985]: verbose: ControllerMpd::sendMpdCommand status Aug 26 20:53:04 volumio-office volumio[985]: info: Aug 26 20:53:04 volumio-office volumio[985]: ---------------------------- MPD announces state update: update Aug 26 20:53:04 volumio-office volumio[985]: info: ControllerMpd::getState Aug 26 20:53:04 volumio-office volumio[985]: verbose: ControllerMpd::sendMpdCommand status Aug 26 20:53:04 volumio-office volumio[985]: info: VolumeController:: Volume=100 Mute =false Aug 26 20:53:04 volumio-office volumio[985]: info: CoreStateMachine::pushState Aug 26 20:53:04 volumio-office volumio[985]: info: CorePlayQueue::getTrack 0 Aug 26 20:53:04 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 20:53:04 volumio-office volumio[985]: info: CoreCommandRouter::volumioPushState Aug 26 20:53:04 volumio-office volumio[985]: info: CoreStateMachine::updateTrackBlock Aug 26 20:53:04 volumio-office volumio[985]: info: CorePlayQueue::getTrackBlock Aug 26 20:53:04 volumio-office volumio[985]: info: CoreCommandRouter::volumioRetrievevolume Aug 26 20:53:04 volumio-office volumio[985]: info: Setting Device type: Tinkerboard Aug 26 20:53:04 volumio-office volumio[985]: info: CoreStateMachine::setRepeat true single undefined Aug 26 20:53:04 volumio-office volumio[985]: info: CoreStateMachine::pushState Aug 26 20:53:04 volumio-office volumio[985]: info: CorePlayQueue::getTrack 0 Aug 26 20:53:04 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 20:53:04 volumio-office volumio[985]: info: CoreCommandRouter::volumioPushState Aug 26 20:53:04 volumio-office volumio[985]: info: CoreStateMachine::setRandom undefined Aug 26 20:53:04 volumio-office volumio[985]: info: CoreStateMachine::pushState Aug 26 20:53:04 volumio-office volumio[985]: info: CorePlayQueue::getTrack 0 Aug 26 20:53:04 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 20:53:04 volumio-office volumio[985]: info: CoreCommandRouter::volumioPushState Aug 26 20:53:04 volumio-office volumio[985]: verbose: New Socket.io Connection to 192.168.1.72 from 192.168.1.189 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.6 Safari/605.1.15 Total Clients: 19 Aug 26 20:53:04 volumio-office volumio[985]: verbose: New Socket.io Connection to 192.168.1.72 from 192.168.1.189 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.6 Safari/605.1.15 Total Clients: 20 Aug 26 20:53:04 volumio-office volumio[985]: verbose: ControllerMpd::sendMpdCommand status Aug 26 20:53:04 volumio-office volumio[985]: info: Aug 26 20:53:04 volumio-office volumio[985]: ---------------------------- MPD announces state update: update Aug 26 20:53:04 volumio-office volumio[985]: info: ControllerMpd::getState Aug 26 20:53:04 volumio-office volumio[985]: verbose: ControllerMpd::sendMpdCommand status Aug 26 20:53:04 volumio-office volumio[985]: info: Discovery: A device disappeared from network Aug 26 20:53:04 volumio-office volumio[985]: info: Discovery: Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Aug 26 20:53:04 volumio-office volumio[985]: verbose: ControllerMpd::sendMpdCommand status Aug 26 20:53:04 volumio-office volumio[985]: info: Aug 26 20:53:04 volumio-office volumio[985]: ---------------------------- MPD announces state update: update Aug 26 20:53:04 volumio-office volumio[985]: error: updateQueue error: null Aug 26 20:53:04 volumio-office volumio[985]: info: ControllerMpd::getState Aug 26 20:53:04 volumio-office volumio[985]: verbose: ControllerMpd::sendMpdCommand status Aug 26 20:53:04 volumio-office volumio[985]: info: sendMpdCommand status took 100 milliseconds Aug 26 20:53:04 volumio-office volumio[985]: info: sendMpdCommand status took 97 milliseconds Aug 26 20:53:04 volumio-office volumio[985]: info: sendMpdCommand status took 29 milliseconds Aug 26 20:53:04 volumio-office volumio[985]: info: sendMpdCommand status took 28 milliseconds Aug 26 20:53:04 volumio-office volumio[985]: verbose: ControllerMpd::parseState Aug 26 20:53:04 volumio-office volumio[985]: info: Command Router : Notfying DB Updatefalse Aug 26 20:53:04 volumio-office volumio[985]: info: CoreCommandRouter::Close All Modals sent Aug 26 20:53:04 volumio-office volumio[985]: verbose: ControllerMpd::parseState Aug 26 20:53:04 volumio-office volumio[985]: verbose: ControllerMpd::parseState Aug 26 20:53:05 volumio-office volumio[985]: info: Command Router : Notfying DB Updatefalse Aug 26 20:53:05 volumio-office volumio[985]: info: CoreCommandRouter::Close All Modals sent Aug 26 20:53:05 volumio-office volumio[985]: verbose: ControllerMpd::parseState Aug 26 20:53:05 volumio-office volumio[985]: info: ControllerMpd::pushState Aug 26 20:53:05 volumio-office volumio[985]: info: CoreCommandRouter::servicePushState Aug 26 20:53:05 volumio-office volumio[985]: info: CoreStateMachine::pushState Aug 26 20:53:05 volumio-office volumio[985]: info: CorePlayQueue::getTrack 0 Aug 26 20:53:05 volumio-office volumio[985]: info: CoreCommandRouter::volumioPushState Aug 26 20:53:05 volumio-office volumio[985]: info: CorePlayQueue::getTrack 0 Aug 26 20:53:05 volumio-office volumio[985]: 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} Aug 26 20:53:05 volumio-office volumio[985]: verbose: CURRENT POSITION 0 Aug 26 20:53:05 volumio-office volumio[985]: info: CoreStateMachine::syncState stateService stop Aug 26 20:53:05 volumio-office volumio[985]: info: CoreStateMachine::syncState currentStatus stop Aug 26 20:53:05 volumio-office volumio[985]: info: CoreStateMachine::pushState Aug 26 20:53:05 volumio-office volumio[985]: info: CorePlayQueue::getTrack 0 Aug 26 20:53:05 volumio-office volumio[985]: info: CoreCommandRouter::volumioPushState Aug 26 20:53:05 volumio-office volumio[985]: info: No code Aug 26 20:53:05 volumio-office volumio[985]: info: CoreStateMachine::pushState Aug 26 20:53:05 volumio-office volumio[985]: info: CorePlayQueue::getTrack 0 Aug 26 20:53:05 volumio-office volumio[985]: info: CoreCommandRouter::volumioPushState Aug 26 20:53:05 volumio-office volumio[985]: info: ControllerMpd::pushState Aug 26 20:53:05 volumio-office volumio[985]: info: CoreCommandRouter::servicePushState Aug 26 20:53:05 volumio-office volumio[985]: info: CoreStateMachine::pushState Aug 26 20:53:05 volumio-office volumio[985]: info: CorePlayQueue::getTrack 0 Aug 26 20:53:05 volumio-office volumio[985]: info: CoreCommandRouter::volumioPushState Aug 26 20:53:05 volumio-office volumio[985]: info: CorePlayQueue::getTrack 0 Aug 26 20:53:05 volumio-office volumio[985]: 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} Aug 26 20:53:05 volumio-office volumio[985]: verbose: CURRENT POSITION 0 Aug 26 20:53:05 volumio-office volumio[985]: info: CoreStateMachine::syncState stateService stop Aug 26 20:53:05 volumio-office volumio[985]: info: CoreStateMachine::syncState currentStatus stop Aug 26 20:53:05 volumio-office volumio[985]: info: CoreStateMachine::pushState Aug 26 20:53:05 volumio-office volumio[985]: info: CorePlayQueue::getTrack 0 Aug 26 20:53:05 volumio-office volumio[985]: info: CoreCommandRouter::volumioPushState Aug 26 20:53:05 volumio-office volumio[985]: info: No code Aug 26 20:53:05 volumio-office volumio[985]: info: CoreStateMachine::pushState Aug 26 20:53:05 volumio-office volumio[985]: info: CorePlayQueue::getTrack 0 Aug 26 20:53:05 volumio-office volumio[985]: info: CoreCommandRouter::volumioPushState Aug 26 20:53:05 volumio-office volumio[985]: info: ------------------------------ 253ms Aug 26 20:53:05 volumio-office volumio[985]: info: ------------------------------ 181ms Aug 26 20:53:05 volumio-office volumio[985]: info: sendMpdCommand status took 173 milliseconds Aug 26 20:53:05 volumio-office volumio[985]: info: sendMpdCommand status took 171 milliseconds Aug 26 20:53:05 volumio-office volumio[985]: verbose: ControllerMpd::parseState Aug 26 20:53:05 volumio-office volumio[985]: info: Command Router : Notfying DB Updatefalse Aug 26 20:53:05 volumio-office volumio[985]: info: CoreCommandRouter::Close All Modals sent Aug 26 20:53:05 volumio-office volumio[985]: verbose: ControllerMpd::parseState Aug 26 20:53:05 volumio-office volumio[985]: info: ControllerMpd::pushState Aug 26 20:53:05 volumio-office volumio[985]: info: CoreCommandRouter::servicePushState Aug 26 20:53:05 volumio-office volumio[985]: info: CoreStateMachine::pushState Aug 26 20:53:05 volumio-office volumio[985]: info: CorePlayQueue::getTrack 0 Aug 26 20:53:05 volumio-office volumio[985]: info: CoreCommandRouter::volumioPushState Aug 26 20:53:05 volumio-office volumio[985]: info: CorePlayQueue::getTrack 0 Aug 26 20:53:05 volumio-office volumio[985]: 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} Aug 26 20:53:05 volumio-office volumio[985]: verbose: CURRENT POSITION 0 Aug 26 20:53:05 volumio-office volumio[985]: info: CoreStateMachine::syncState stateService stop Aug 26 20:53:05 volumio-office volumio[985]: info: CoreStateMachine::syncState currentStatus stop Aug 26 20:53:05 volumio-office volumio[985]: info: CoreStateMachine::pushState Aug 26 20:53:05 volumio-office volumio[985]: info: CorePlayQueue::getTrack 0 Aug 26 20:53:05 volumio-office volumio[985]: info: CoreCommandRouter::volumioPushState Aug 26 20:53:05 volumio-office volumio[985]: info: No code Aug 26 20:53:05 volumio-office volumio[985]: info: CoreStateMachine::pushState Aug 26 20:53:05 volumio-office volumio[985]: info: CorePlayQueue::getTrack 0 Aug 26 20:53:05 volumio-office volumio[985]: info: CoreCommandRouter::volumioPushState Aug 26 20:53:05 volumio-office volumio[985]: info: ------------------------------ 217ms Aug 26 20:53:05 volumio-office volumio[985]: info: Asound.conf file written Aug 26 20:53:05 volumio-office sudo[1453]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Aug 26 20:53:05 volumio-office sudo[1453]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 20:53:05 volumio-office sudo[1453]: pam_unix(sudo:session): session closed for user root Aug 26 20:53:05 volumio-office volumio[985]: info: Output device has changed, restarting MPD Aug 26 20:53:05 volumio-office volumio[985]: info: Output device has changed, restarting Shairport Sync Aug 26 20:53:05 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 20:53:05 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 20:53:05 volumio-office sudo[1465]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 26 20:53:05 volumio-office sudo[1468]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 26 20:53:05 volumio-office sudo[1465]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 20:53:05 volumio-office volumio[985]: info: ___________ START PLUGINS ___________ Aug 26 20:53:05 volumio-office sudo[1468]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 20:53:05 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 20:53:05 volumio-office volumio[985]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 26 20:53:05 volumio-office volumio[985]: info: [1787777585294] CoreMusicLibrary::Adding element Media Servers Aug 26 20:53:05 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 26 20:53:05 volumio-office volumio[985]: Cannot find translation for sourceMedia Servers Aug 26 20:53:05 volumio-office sudo[1465]: pam_unix(sudo:session): session closed for user root Aug 26 20:53:05 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 20:53:05 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 20:53:05 volumio-office systemd[1]: Stopping Music Player Daemon... Aug 26 20:53:05 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 20:53:05 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 20:53:05 volumio-office volumio[985]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 26 20:53:05 volumio-office volumio[985]: info: [1787777585391] CoreMusicLibrary::Adding element Last_100 Aug 26 20:53:05 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 26 20:53:05 volumio-office volumio[985]: Cannot find translation for sourceMedia Servers Aug 26 20:53:05 volumio-office volumio[985]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 26 20:53:05 volumio-office volumio[985]: info: [1787777585395] CoreMusicLibrary::Adding element Webradio Aug 26 20:53:05 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 26 20:53:05 volumio-office volumio[985]: Cannot find translation for sourceMedia Servers Aug 26 20:53:05 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 26 20:53:05 volumio-office volumio[985]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 26 20:53:05 volumio-office volumio[985]: info: [1787777585408] CoreMusicLibrary::Adding element YouTube Music Aug 26 20:53:05 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 26 20:53:05 volumio-office volumio[985]: Cannot find translation for sourceMedia Servers Aug 26 20:53:05 volumio-office volumio[985]: Cannot find translation for sourceYouTube Music Aug 26 20:53:05 volumio-office volumio[985]: [SpotifyConnect] Creating VLS config file Aug 26 20:53:05 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 20:53:05 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 20:53:05 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 20:53:05 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 20:53:05 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 20:53:05 volumio-office volumio[985]: [SpotifyConnect] Starting metadata listener Aug 26 20:53:05 volumio-office systemd[1]: mpd.service: Succeeded. Aug 26 20:53:05 volumio-office systemd[1]: Stopped Music Player Daemon. Aug 26 20:53:05 volumio-office systemd[1]: Starting Music Player Daemon... Aug 26 20:53:05 volumio-office volumio[985]: info: Volumio Calling Home Aug 26 20:53:05 volumio-office sudo[1493]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service Aug 26 20:53:05 volumio-office sudo[1493]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 20:53:05 volumio-office systemd[1]: Started Volspotconnect2 Daemon. Aug 26 20:53:05 volumio-office sudo[1493]: pam_unix(sudo:session): session closed for user root Aug 26 20:53:05 volumio-office volumio[985]: verbose: New Socket.io Connection to 192.168.1.72 from 192.168.1.189 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.6 Safari/605.1.15 Total Clients: 21 Aug 26 20:53:05 volumio-office volumio[985]: info: VolumeController:: Volume=100 Mute =false Aug 26 20:53:05 volumio-office volumio[985]: info: CoreStateMachine::pushState Aug 26 20:53:05 volumio-office volumio[985]: info: CorePlayQueue::getTrack 0 Aug 26 20:53:05 volumio-office volumio[985]: info: CoreCommandRouter::volumioPushState Aug 26 20:53:05 volumio-office volumio[985]: info: Discovery: adding 20cabae0-1a52-48ee-ae98-4a3c7f59d4cf Aug 26 20:53:05 volumio-office volumio[985]: info: Discovery: Found device Volumio Office Aug 26 20:53:05 volumio-office volumio[985]: info: CoreCommandRouter::volumioGetState Aug 26 20:53:05 volumio-office volumio[985]: info: CorePlayQueue::getTrack 0 Aug 26 20:53:05 volumio-office volumio[1509]: vollibrespot v0.2.3 769ce77 2021-06-04 (librespot 08d8bcc 2020-10-07) -- Built On 2021-06-04 Aug 26 20:53:05 volumio-office volumio[1509]: Reading Config from "volspotify.toml" Aug 26 20:53:05 volumio-office volumio[1509]: [Vollibrespot] : Using Alsa backend with device: volumio Aug 26 20:53:05 volumio-office volumio[985]: [SpotifyConnect] Vollibrespot Daemon service started! Aug 26 20:53:05 volumio-office volumio[985]: [Metrics] SpotifyConnect: 0s 201.38ms Aug 26 20:53:05 volumio-office volumio[985]: info: Completed starting Core Plugins Aug 26 20:53:05 volumio-office volumio[985]: info: ------------------------------------------- Aug 26 20:53:05 volumio-office volumio[985]: info: ----- MyVolumio plugins startup ---- Aug 26 20:53:05 volumio-office volumio[985]: info: ------------------------------------------- Aug 26 20:53:05 volumio-office volumio[985]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 26 20:53:05 volumio-office volumio[985]: info: MPD Permissions set Aug 26 20:53:05 volumio-office volumio[985]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 22 Aug 26 20:53:05 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 20:53:05 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 20:53:05 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 20:53:05 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 20:53:05 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 20:53:05 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 20:53:05 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 20:53:05 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 20:53:05 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 20:53:05 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 26 20:53:05 volumio-office volumio[1509]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Other, message: "No such device" } Aug 26 20:53:05 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 20:53:05 volumio-office volumio[985]: info: Starting Shairport Sync Aug 26 20:53:05 volumio-office volumio[985]: info: Starting Shairport Sync Aug 26 20:53:05 volumio-office volumio[985]: info: Starting Shairport Sync Aug 26 20:53:05 volumio-office sudo[1542]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 26 20:53:05 volumio-office sudo[1543]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 26 20:53:05 volumio-office sudo[1542]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 20:53:05 volumio-office sudo[1547]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 26 20:53:05 volumio-office sudo[1543]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 20:53:05 volumio-office sudo[1547]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 20:53:05 volumio-office systemd[1]: apt-daily-upgrade.service: Succeeded. Aug 26 20:53:05 volumio-office systemd[1]: Started Daily apt upgrade and clean activities. Aug 26 20:53:05 volumio-office systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 26 20:53:05 volumio-office systemd[1]: shairport-sync.service: Succeeded. Aug 26 20:53:05 volumio-office systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 26 20:53:05 volumio-office systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 26 20:53:05 volumio-office sudo[1543]: pam_unix(sudo:session): session closed for user root Aug 26 20:53:05 volumio-office sudo[1542]: pam_unix(sudo:session): session closed for user root Aug 26 20:53:05 volumio-office volumio[985]: info: CoreCommandRouter::volumioGetState Aug 26 20:53:05 volumio-office volumio[985]: info: CorePlayQueue::getTrack 0 Aug 26 20:53:05 volumio-office systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 26 20:53:05 volumio-office systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Aug 26 20:53:05 volumio-office systemd[1]: shairport-sync.service: Succeeded. Aug 26 20:53:05 volumio-office systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 26 20:53:05 volumio-office systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 26 20:53:05 volumio-office sudo[1547]: pam_unix(sudo:session): session closed for user root Aug 26 20:53:05 volumio-office volumio[985]: info: Shairport-Sync Started Aug 26 20:53:05 volumio-office volumio[985]: Error adding Membership: Error: addMembership EINVAL Aug 26 20:53:05 volumio-office volumio[985]: info: Shairport-Sync Started Aug 26 20:53:05 volumio-office volumio[985]: info: Shairport-Sync Started Aug 26 20:53:06 volumio-office volumio[985]: verbose: New Socket.io Connection to 192.168.1.72 from 192.168.1.189 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.6 Safari/605.1.15 Total Clients: 23 Aug 26 20:53:06 volumio-office volumio[985]: info: CoreCommandRouter::volumioGetState Aug 26 20:53:06 volumio-office volumio[985]: info: CorePlayQueue::getTrack 0 Aug 26 20:53:06 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 26 20:53:06 volumio-office volumio[985]: info: CoreCommandRouter::volumioGetVisibleSources Aug 26 20:53:06 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 26 20:53:06 volumio-office volumio[985]: info: Listing playlists Aug 26 20:53:06 volumio-office mpd[1531]: Aug 26 20:53 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 26 20:53:06 volumio-office mpd[1531]: Aug 26 20:53 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Aug 26 20:53:06 volumio-office mpd[1531]: Aug 26 20:53 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Aug 26 20:53:06 volumio-office systemd[1]: Started Music Player Daemon. Aug 26 20:53:06 volumio-office sudo[1468]: pam_unix(sudo:session): session closed for user root Aug 26 20:53:06 volumio-office volumio[985]: error: updateQueue error: null Aug 26 20:53:07 volumio-office volumio[985]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux armv7l) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Total Clients: 24 Aug 26 20:53:07 volumio-office volumio[985]: info: Volumio called home Aug 26 20:53:08 volumio-office sudo[1761]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 26 20:53:08 volumio-office sudo[1762]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 26 20:53:08 volumio-office sudo[1761]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 20:53:08 volumio-office sudo[1762]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 20:53:08 volumio-office sudo[1761]: pam_unix(sudo:session): session closed for user root Aug 26 20:53:08 volumio-office sudo[1762]: pam_unix(sudo:session): session closed for user root Aug 26 20:53:08 volumio-office sudo[1770]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 26 20:53:08 volumio-office sudo[1770]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 20:53:08 volumio-office systemd[1]: Started UPnP Renderer front-end to MPD. Aug 26 20:53:08 volumio-office sudo[1770]: pam_unix(sudo:session): session closed for user root Aug 26 20:53:08 volumio-office volumio[985]: info: Upmpdcli Daemon Started Aug 26 20:53:08 volumio-office volumio[1786]: Generating RSA private key, 4096 bit long modulus (2 primes) Aug 26 20:53:08 volumio-office volumio[985]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux armv7l) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Total Clients: 25 Aug 26 20:53:10 volumio-office volumio[985]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux armv7l) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Total Clients: 26 Aug 26 20:53:10 volumio-office volumio-remote-updater[487]: [2026-08-26 20:53:10] [connect] Successful connection Aug 26 20:53:10 volumio-office volumio-remote-updater[487]: [2026-08-26 20:53:10] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1787777590 101 Aug 26 20:53:10 volumio-office volumio[985]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 27 Aug 26 20:53:10 volumio-office volumio[985]: info: CoreCommandRouter::volumioGetState Aug 26 20:53:10 volumio-office volumio[985]: info: CorePlayQueue::getTrack 0 Aug 26 20:53:11 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 26 20:53:11 volumio-office volumio[985]: info: CoreCommandRouter::volumioGetVisibleSources Aug 26 20:53:11 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 26 20:53:11 volumio-office volumio[985]: info: Listing playlists Aug 26 20:53:11 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 26 20:53:11 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 26 20:53:11 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 26 20:53:11 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 26 20:53:11 volumio-office volumio[985]: info: CoreCommandRouter::volumioGetState Aug 26 20:53:11 volumio-office volumio[985]: info: CorePlayQueue::getTrack 0 Aug 26 20:53:11 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 26 20:53:11 volumio-office volumio[985]: info: CoreCommandRouter::volumioGetVisibleSources Aug 26 20:53:11 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 26 20:53:11 volumio-office volumio[985]: info: Listing playlists Aug 26 20:53:11 volumio-office systemd[1]: systemd-fsckd.service: Succeeded. Aug 26 20:53:12 volumio-office sh[420]: timed out Aug 26 20:53:12 volumio-office dhcpcd[466]: timed out Aug 26 20:53:12 volumio-office sh[420]: dhcpcd exited Aug 26 20:53:12 volumio-office dhcpcd[466]: dhcpcd exited Aug 26 20:53:12 volumio-office sh[420]: ifup: failed to bring up eth0 Aug 26 20:53:12 volumio-office systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Aug 26 20:53:12 volumio-office systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Aug 26 20:53:12 volumio-office ifplugd(eth0)[686]: client: sending commands to master dhcpcd process Aug 26 20:53:12 volumio-office dhcpcd[1835]: sending commands to master dhcpcd process Aug 26 20:53:12 volumio-office dhcpcd[981]: control command: /sbin/dhcpcd eth0 Aug 26 20:53:12 volumio-office ifplugd(eth0)[686]: Program executed successfully. Aug 26 20:53:12 volumio-office ifplugd(eth0)[686]: Link beat lost. Aug 26 20:53:12 volumio-office ifplugd[642]: Network Interface Plugging Daemon...start eth0...done. Aug 26 20:53:12 volumio-office systemd[1]: Started LSB: Brings up/down network automatically. Aug 26 20:53:12 volumio-office systemd[1]: Reached target Multi-User System. Aug 26 20:53:12 volumio-office systemd[1]: Reached target Graphical Interface. Aug 26 20:53:12 volumio-office systemd[1]: Starting Update UTMP about System Runlevel Changes... Aug 26 20:53:12 volumio-office systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Aug 26 20:53:12 volumio-office systemd[1]: Started Update UTMP about System Runlevel Changes. Aug 26 20:53:12 volumio-office systemd[1]: Startup finished in 16.775s (kernel) + 32.583s (userspace) = 49.359s. Aug 26 20:53:13 volumio-office systemd[1]: systemd-hostnamed.service: Succeeded. Aug 26 20:53:14 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 26 20:53:14 volumio-office volumio[985]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Aug 26 20:53:14 volumio-office volumio[985]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Aug 26 20:53:14 volumio-office volumio[985]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Aug 26 20:53:14 volumio-office volumio[985]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Aug 26 20:53:14 volumio-office volumio[985]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Aug 26 20:53:14 volumio-office volumio[985]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Aug 26 20:53:14 volumio-office volumio[985]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Aug 26 20:53:14 volumio-office volumio[985]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Aug 26 20:53:14 volumio-office volumio[985]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Aug 26 20:53:14 volumio-office volumio[985]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Aug 26 20:53:14 volumio-office volumio[985]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Aug 26 20:53:14 volumio-office volumio[985]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Aug 26 20:53:14 volumio-office volumio[985]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Aug 26 20:53:14 volumio-office volumio[985]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Aug 26 20:53:14 volumio-office volumio[985]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Aug 26 20:53:14 volumio-office volumio[985]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Aug 26 20:53:14 volumio-office volumio[985]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Aug 26 20:53:14 volumio-office volumio[985]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Aug 26 20:53:14 volumio-office volumio[985]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Aug 26 20:53:14 volumio-office volumio[985]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Aug 26 20:53:14 volumio-office volumio[985]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Aug 26 20:53:14 volumio-office volumio[985]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Aug 26 20:53:14 volumio-office volumio[985]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Aug 26 20:53:14 volumio-office volumio[985]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Aug 26 20:53:14 volumio-office volumio[985]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Aug 26 20:53:14 volumio-office volumio[985]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Aug 26 20:53:14 volumio-office volumio[985]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Aug 26 20:53:14 volumio-office volumio[985]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Aug 26 20:53:14 volumio-office volumio[985]: info: Adding plugin bluetooth to MyMusic Plugins Aug 26 20:53:14 volumio-office volumio[985]: info: Adding plugin multiroom to MyMusic Plugins Aug 26 20:53:14 volumio-office volumio[985]: info: Adding plugin metavolumio to MyMusic Plugins Aug 26 20:53:14 volumio-office volumio[985]: info: Adding plugin cd_controller to MyMusic Plugins Aug 26 20:53:14 volumio-office volumio[985]: info: Adding plugin smart_inputs to MyMusic Plugins Aug 26 20:53:14 volumio-office volumio[985]: info: Adding plugin tidalconnect to MyMusic Plugins Aug 26 20:53:14 volumio-office volumio[985]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Aug 26 20:53:15 volumio-office volumio[985]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Aug 26 20:53:15 volumio-office volumio[985]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Aug 26 20:53:15 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 20:53:15 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 20:53:15 volumio-office volumio[985]: info: Starting MyVolumio Remote Streaming Endpoints Aug 26 20:53:15 volumio-office volumio[985]: info: MyVolumio login type: Token Aug 26 20:53:15 volumio-office volumio[985]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Aug 26 20:53:15 volumio-office volumio[985]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Aug 26 20:53:15 volumio-office volumio[985]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Aug 26 20:53:15 volumio-office volumio[985]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Aug 26 20:53:15 volumio-office volumio[985]: info: Streaming services startup Aug 26 20:53:15 volumio-office volumio[985]: info: Starting Streaming Daemon Aug 26 20:53:15 volumio-office sudo[1888]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 26 20:53:15 volumio-office volumio[985]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Aug 26 20:53:15 volumio-office sudo[1888]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 20:53:15 volumio-office systemd[1]: Stopping Volumio Streaming Daemon... Aug 26 20:53:15 volumio-office systemd[1]: volumio-streaming-daemon.service: Main process exited, code=killed, status=15/TERM Aug 26 20:53:15 volumio-office systemd[1]: volumio-streaming-daemon.service: Succeeded. Aug 26 20:53:15 volumio-office systemd[1]: Stopped Volumio Streaming Daemon. Aug 26 20:53:15 volumio-office systemd[1]: Started Volumio Streaming Daemon. Aug 26 20:53:15 volumio-office sudo[1888]: pam_unix(sudo:session): session closed for user root Aug 26 20:53:15 volumio-office volumio-streaming-daemon[1898]: ############################ Aug 26 20:53:15 volumio-office volumio-streaming-daemon[1898]: # Volumio Streaming Daemon # Aug 26 20:53:15 volumio-office volumio-streaming-daemon[1898]: # Running on port 7777 # Aug 26 20:53:15 volumio-office volumio-streaming-daemon[1898]: ############################ Aug 26 20:53:15 volumio-office volumio-streaming-daemon[1898]: Environment: production Aug 26 20:53:15 volumio-office volumio[985]: info: Discovery: this is already registered, 20cabae0-1a52-48ee-ae98-4a3c7f59d4cf Aug 26 20:53:15 volumio-office volumio[985]: info: Discovery: Found device Volumio Office Aug 26 20:53:15 volumio-office volumio[985]: info: CoreCommandRouter::volumioGetState Aug 26 20:53:15 volumio-office volumio[985]: info: CorePlayQueue::getTrack 0 Aug 26 20:53:16 volumio-office volumio[1786]: .....................................................................................................................................................++++ Aug 26 20:53:16 volumio-office volumio[985]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Aug 26 20:53:16 volumio-office volumio[985]: info: MYVOLUMIO: Adding device Aug 26 20:53:16 volumio-office volumio[985]: info: MYVOLUMIO: Evaluating Server Aug 26 20:53:18 volumio-office volumio[985]: info: MyVolumio Plan changed: superstar Aug 26 20:53:18 volumio-office volumio[985]: info: [MyVolumio PluginManager] Subscribed plan changed to superstar Aug 26 20:53:18 volumio-office volumio[985]: info: Adding audio output: Aug 26 20:53:18 volumio-office volumio[985]: info: MYVOLUMIO: Adding device Aug 26 20:53:18 volumio-office volumio[985]: info: MYVOLUMIO: Evaluating Server Aug 26 20:53:18 volumio-office volumio[985]: info: Remote config written successfully Aug 26 20:53:18 volumio-office volumio[985]: info: Starting Tunnel 1 Aug 26 20:53:18 volumio-office volumio[985]: info: Starting Tunnel Connection Checker Aug 26 20:53:18 volumio-office volumio[985]: info: Completed starting MyVolumio Plugin Aug 26 20:53:18 volumio-office volumio-streaming-daemon[1898]: Environment: production Aug 26 20:53:18 volumio-office volumio[985]: info: MYVolumio Device enabled Aug 26 20:53:18 volumio-office volumio[985]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Aug 26 20:53:18 volumio-office volumio[985]: info: MyVolumio status changed Aug 26 20:53:18 volumio-office volumio[985]: info: Streaming services startup Aug 26 20:53:18 volumio-office volumio[985]: info: Re-Starting Streaming Daemon Aug 26 20:53:18 volumio-office volumio[985]: info: Setting Geolocation for MyVolumio to au1 Aug 26 20:53:18 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 20:53:18 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 20:53:18 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 20:53:18 volumio-office volumio-streaming-daemon[1898]: Restarting daemon Aug 26 20:53:18 volumio-office volumio-streaming-daemon[1898]: Environment: production Aug 26 20:53:18 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Aug 26 20:53:18 volumio-office volumio[985]: info: [MyVolumio PluginManager] Plugin music_service/qobuzconnect is enabled for this plan, but could not be found on the local filesystem! Aug 26 20:53:18 volumio-office volumio[985]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Aug 26 20:53:18 volumio-office volumio[985]: info: [1787777598701] Starting BluetoothController Aug 26 20:53:18 volumio-office volumio[985]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Aug 26 20:53:19 volumio-office volumio-streaming-daemon[1898]: Enabled services: Aug 26 20:53:19 volumio-office volumio-streaming-daemon[1898]: 1) tidal Aug 26 20:53:19 volumio-office volumio-streaming-daemon[1898]: 2) qobuz Aug 26 20:53:19 volumio-office volumio[985]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Aug 26 20:53:19 volumio-office sudo[1953]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod +x /myvolumio/plugins/audio_interface/multiroom/scripts/check_snapcast Aug 26 20:53:19 volumio-office sudo[1955]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Aug 26 20:53:19 volumio-office sudo[1953]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 20:53:19 volumio-office sudo[1953]: pam_unix(sudo:session): session closed for user root Aug 26 20:53:19 volumio-office sudo[1955]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 20:53:19 volumio-office sudo[1955]: pam_unix(sudo:session): session closed for user root Aug 26 20:53:19 volumio-office volumio[985]: info: MRS: MultiRoom plugin initialized Aug 26 20:53:19 volumio-office volumio[985]: info: MRS: STOPPING SNAPCLIENT Aug 26 20:53:19 volumio-office volumio[985]: info: MRS: Snap server stop Aug 26 20:53:19 volumio-office volumio[985]: info: MRS: STOPPING volumioStreaming Aug 26 20:53:19 volumio-office sudo[1988]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Aug 26 20:53:19 volumio-office sudo[1989]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Aug 26 20:53:19 volumio-office sudo[1988]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 20:53:19 volumio-office sudo[1989]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 20:53:19 volumio-office volumio[985]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Aug 26 20:53:19 volumio-office sudo[2001]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Aug 26 20:53:20 volumio-office volumio[985]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Aug 26 20:53:20 volumio-office sudo[1995]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Aug 26 20:53:20 volumio-office volumio[985]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Aug 26 20:53:20 volumio-office sudo[2001]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 20:53:20 volumio-office sudo[1988]: pam_unix(sudo:session): session closed for user root Aug 26 20:53:20 volumio-office sudo[1989]: pam_unix(sudo:session): session closed for user root Aug 26 20:53:20 volumio-office sudo[2001]: pam_unix(sudo:session): session closed for user root Aug 26 20:53:20 volumio-office volumio[985]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Aug 26 20:53:20 volumio-office sudo[1995]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 20:53:20 volumio-office volumio[985]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Aug 26 20:53:20 volumio-office sudo[1995]: pam_unix(sudo:session): session closed for user root Aug 26 20:53:20 volumio-office volumio[985]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Aug 26 20:53:20 volumio-office volumio[985]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Aug 26 20:53:20 volumio-office volumio[985]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Aug 26 20:53:20 volumio-office volumio[985]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Aug 26 20:53:20 volumio-office volumio[985]: info: Preparing to generate the ALSA configuration file Aug 26 20:53:20 volumio-office volumio[985]: info: Updating MyVolumio device info Aug 26 20:53:20 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 20:53:20 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 20:53:20 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 20:53:20 volumio-office volumio[985]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Aug 26 20:53:20 volumio-office volumio[985]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Aug 26 20:53:20 volumio-office volumio[985]: info: Reading ALSA contributions from plugins. Aug 26 20:53:20 volumio-office volumio[985]: info: Fetching Streaming Services browse cache Aug 26 20:53:20 volumio-office volumio[985]: info: Setting Geolocation for MyVolumio to au1 Aug 26 20:53:20 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 20:53:20 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 20:53:20 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 20:53:20 volumio-office volumio[985]: info: MRS: Removed streaming files Aug 26 20:53:20 volumio-office volumio[985]: info: MRS: volumioStreaming STOPPED Aug 26 20:53:20 volumio-office volumio[985]: info: MRS: SNAPSERVER STOPPED Aug 26 20:53:20 volumio-office volumio[985]: info: MRS: SNAPCLIENT STOPPED Aug 26 20:53:20 volumio-office volumio[985]: info: Snap server and client installed Aug 26 20:53:20 volumio-office volumio[985]: info: Asound.conf file written Aug 26 20:53:20 volumio-office sudo[2028]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Aug 26 20:53:20 volumio-office sudo[2028]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 20:53:20 volumio-office sudo[2028]: pam_unix(sudo:session): session closed for user root Aug 26 20:53:20 volumio-office volumio[985]: info: Output device has changed, restarting MPD Aug 26 20:53:20 volumio-office volumio[985]: info: Output device has changed, restarting Shairport Sync Aug 26 20:53:20 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 20:53:20 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 20:53:20 volumio-office sudo[2040]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 26 20:53:20 volumio-office sudo[2040]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 20:53:20 volumio-office sudo[2044]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 26 20:53:20 volumio-office sudo[2040]: pam_unix(sudo:session): session closed for user root Aug 26 20:53:20 volumio-office volumio[985]: [SpotifyConnect] Creating VLS config file Aug 26 20:53:20 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 20:53:20 volumio-office volumio[985]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 26 20:53:20 volumio-office sudo[2044]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 20:53:20 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 20:53:20 volumio-office systemd[1]: Stopping Music Player Daemon... Aug 26 20:53:20 volumio-office volumio[985]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Aug 26 20:53:20 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 26 20:53:20 volumio-office volumio[985]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Aug 26 20:53:20 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Aug 26 20:53:20 volumio-office volumio[985]: info: MRS: Pushing multiroomSync output for this device Aug 26 20:53:20 volumio-office volumio[985]: info: MRS: Pushing multiroomSync output Aug 26 20:53:20 volumio-office volumio[985]: info: Adding audio output: Aug 26 20:53:20 volumio-office volumio[985]: info: Adding audio output: Aug 26 20:53:20 volumio-office volumio[985]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 26 20:53:20 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 20:53:20 volumio-office sudo[2063]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Volumio Office Aug 26 20:53:20 volumio-office systemd[1]: mpd.service: Succeeded. Aug 26 20:53:20 volumio-office systemd[1]: Stopped Music Player Daemon. Aug 26 20:53:20 volumio-office systemd[1]: Starting Music Player Daemon... Aug 26 20:53:20 volumio-office sudo[2063]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 20:53:20 volumio-office volumio[985]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Aug 26 20:53:20 volumio-office volumio[985]: info: Adding METAVOLUMIO REST API Endpoints Aug 26 20:53:20 volumio-office volumio[985]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Aug 26 20:53:20 volumio-office volumio[985]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Aug 26 20:53:20 volumio-office volumio[985]: info: Adding Manifest REST API Endpoints Aug 26 20:53:20 volumio-office volumio[985]: info: Adding manifestui REST Endpoint for plugin: miscellanea/manifestui Aug 26 20:53:20 volumio-office volumio[985]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Aug 26 20:53:20 volumio-office volumio[985]: info: Preparing CD Folders Aug 26 20:53:20 volumio-office volumio[985]: info: Adding CD REST API Endpoints Aug 26 20:53:20 volumio-office volumio[985]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Aug 26 20:53:20 volumio-office volumio[985]: info: Starting UDEV Watcher for CD Aug 26 20:53:20 volumio-office volumio[985]: info: Detecting CD presence with UDEV Aug 26 20:53:20 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Aug 26 20:53:20 volumio-office volumio[985]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Aug 26 20:53:20 volumio-office volumio[985]: info: Adding inputs REST Endpoints Aug 26 20:53:20 volumio-office volumio[985]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Aug 26 20:53:20 volumio-office volumio[985]: info: Scanning Audio Inputs Aug 26 20:53:20 volumio-office sudo[2063]: pam_unix(sudo:session): session closed for user root Aug 26 20:53:20 volumio-office volumio[985]: info: Checking against Known Cards name Aug 26 20:53:20 volumio-office volumio[985]: info: Checking against Known Cards name Aug 26 20:53:20 volumio-office volumio[985]: info: Checking against Known Cards name Aug 26 20:53:20 volumio-office volumio[985]: info: Adding Server instance for streaming Aug 26 20:53:20 volumio-office volumio[985]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Aug 26 20:53:20 volumio-office volumio[985]: error: Hi Res Audio Failed Login: Missing Login Data Aug 26 20:53:20 volumio-office volumio[985]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Aug 26 20:53:20 volumio-office volumio[985]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Aug 26 20:53:20 volumio-office volumio[985]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Aug 26 20:53:20 volumio-office volumio[985]: info: [MyVolumio PluginManager] Plugin tidalconnect disabled by user. Not starting Aug 26 20:53:20 volumio-office volumio[985]: info: Stopping AccessToken refresher cron Aug 26 20:53:21 volumio-office volumio[985]: info: AccessToken refresher cron started Aug 26 20:53:21 volumio-office volumio[985]: info: Stopping AccessToken refresher cron for QOBUZ Aug 26 20:53:21 volumio-office volumio[985]: info: AccessToken refresher cron started for QOBUZ Aug 26 20:53:21 volumio-office volumio[985]: info: Successfully Added MyVolumio device Aug 26 20:53:21 volumio-office volumio[985]: info: Successfully Updated MyVolumio device Aug 26 20:53:21 volumio-office volumio[985]: info: Successfully Added MyVolumio device Aug 26 20:53:21 volumio-office volumio[985]: info: Bluetooth name changed to Volumio Office Aug 26 20:53:21 volumio-office volumio[985]: info: MPD Permissions set Aug 26 20:53:21 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 20:53:21 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 20:53:21 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 20:53:21 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 20:53:21 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 20:53:21 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 20:53:21 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 20:53:21 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 20:53:21 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 20:53:21 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 26 20:53:21 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 20:53:21 volumio-office volumio[985]: info: BOOT COMPLETED Aug 26 20:53:21 volumio-office volumio[985]: [Metrics] CommandRouter: 25s 174.80ms Aug 26 20:53:21 volumio-office volumio[985]: info: CoreCommandRouter::volumiosetStartupVolume Aug 26 20:53:21 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 26 20:53:21 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 20:53:21 volumio-office volumio[985]: info: CoreCommandRouter::Close All Modals sent Aug 26 20:53:21 volumio-office volumio[985]: info: Starting Shairport Sync Aug 26 20:53:21 volumio-office volumio[985]: [SpotifyConnect] Restarting Vollibrespot Daemon Aug 26 20:53:21 volumio-office sudo[2091]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 26 20:53:21 volumio-office volumio[985]: info: Updating MyVolumio device info Aug 26 20:53:21 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 20:53:21 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 20:53:21 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 26 20:53:21 volumio-office sudo[2094]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect2.service Aug 26 20:53:21 volumio-office sudo[2091]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 20:53:21 volumio-office sudo[2094]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 20:53:21 volumio-office volumio[985]: info: MRS: Found cast device: Integra-DRX-3.4-362b15796e4588ed23ccc8f7c3826725 Aug 26 20:53:21 volumio-office volumio[985]: info: Adding audio output: Aug 26 20:53:21 volumio-office systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 26 20:53:21 volumio-office systemd[1]: Stopping Volspotconnect2 Daemon... Aug 26 20:53:21 volumio-office systemd[1]: volspotconnect2.service: Main process exited, code=killed, status=15/TERM Aug 26 20:53:21 volumio-office systemd[1]: volspotconnect2.service: Succeeded. Aug 26 20:53:21 volumio-office systemd[1]: Stopped Volspotconnect2 Daemon. Aug 26 20:53:21 volumio-office systemd[1]: shairport-sync.service: Succeeded. Aug 26 20:53:21 volumio-office systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 26 20:53:21 volumio-office systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 26 20:53:21 volumio-office sudo[2091]: pam_unix(sudo:session): session closed for user root Aug 26 20:53:21 volumio-office systemd[1]: Started Volspotconnect2 Daemon. Aug 26 20:53:21 volumio-office sudo[2094]: pam_unix(sudo:session): session closed for user root Aug 26 20:53:21 volumio-office volumio[985]: [SpotifyConnect] Vollibrespot Daemon service restarted! Aug 26 20:53:21 volumio-office volumio[2106]: vollibrespot v0.2.3 769ce77 2021-06-04 (librespot 08d8bcc 2020-10-07) -- Built On 2021-06-04 Aug 26 20:53:21 volumio-office volumio[985]: info: Shairport-Sync Started Aug 26 20:53:21 volumio-office volumio[2106]: Reading Config from "volspotify.toml" Aug 26 20:53:21 volumio-office volumio[2106]: [Vollibrespot] : Using Alsa backend with device: volumio Aug 26 20:53:21 volumio-office volumio[2106]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Other, message: "No such device" } Aug 26 20:53:21 volumio-office volumio-streaming-daemon[1898]: Environment: production Aug 26 20:53:21 volumio-office mpd[2074]: Aug 26 20:53 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 26 20:53:21 volumio-office mpd[2074]: Aug 26 20:53 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Aug 26 20:53:21 volumio-office mpd[2074]: Aug 26 20:53 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Aug 26 20:53:21 volumio-office systemd[1]: Started Music Player Daemon. Aug 26 20:53:21 volumio-office sudo[2044]: pam_unix(sudo:session): session closed for user root Aug 26 20:53:21 volumio-office volumio[985]: info: Successfully Updated MyVolumio device Aug 26 20:53:21 volumio-office volumio[985]: error: updateQueue error: null Aug 26 20:53:21 volumio-office volumio[985]: info: Fetching Streaming Services browse cache Aug 26 20:53:22 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Aug 26 20:53:22 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 26 20:53:22 volumio-office volumio[985]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Aug 26 20:53:22 volumio-office ifplugd(eth0)[686]: Executing '/etc/ifplugd/ifplugd.action eth0 down'. Aug 26 20:53:22 volumio-office dhcpcd[2134]: sending commands to master dhcpcd process Aug 26 20:53:22 volumio-office ifplugd(eth0)[686]: client: sending commands to master dhcpcd process Aug 26 20:53:22 volumio-office dhcpcd[981]: control command: /sbin/dhcpcd -k eth0 Aug 26 20:53:22 volumio-office dhcpcd[981]: eth0: removing interface Aug 26 20:53:22 volumio-office ifplugd(eth0)[686]: Program executed successfully. Aug 26 20:53:23 volumio-office sudo[2166]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Aug 26 20:53:23 volumio-office sudo[2166]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 20:53:23 volumio-office systemd[1]: Started MyVolumio SSH Tunnel. Aug 26 20:53:23 volumio-office sudo[2166]: pam_unix(sudo:session): session closed for user root Aug 26 20:53:23 volumio-office autossh[2174]: port set to 0, monitoring disabled Aug 26 20:53:23 volumio-office autossh[2174]: starting ssh (count 1) Aug 26 20:53:23 volumio-office autossh[2174]: ssh child pid is 2179 Aug 26 20:53:23 volumio-office volumio[985]: info: Remote SSH Started Aug 26 20:53:23 volumio-office volumio[985]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 28 Aug 26 20:53:23 volumio-office volumio[985]: info: CoreCommandRouter::volumioGetState Aug 26 20:53:23 volumio-office volumio[985]: info: CorePlayQueue::getTrack 0 Aug 26 20:53:23 volumio-office volumiossh-tunnel[2172]: Warning: Permanently added '[au1.myvolumio.org]:2222,[170.64.163.26]:2222' (RSA) to the list of known hosts. Aug 26 20:53:23 volumio-office sudo[2181]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Aug 26 20:53:23 volumio-office sudo[2181]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 20:53:23 volumio-office systemd[1]: Started Volumio Bluetooth Module. Aug 26 20:53:23 volumio-office sudo[2181]: pam_unix(sudo:session): session closed for user root Aug 26 20:53:23 volumio-office sudo[2189]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Aug 26 20:53:23 volumio-office volumio[985]: info: Volumio BT Module successfully started Aug 26 20:53:23 volumio-office sudo[2189]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 20:53:23 volumio-office volumiobt[2187]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Aug 26 20:53:23 volumio-office sudo[2189]: pam_unix(sudo:session): session closed for user root Aug 26 20:53:23 volumio-office volumiossh-tunnel[2172]: Warning: remote port forwarding failed for listen port 8091 Aug 26 20:53:23 volumio-office volumio[1786]: ..........................................................................................................................................++++ Aug 26 20:53:23 volumio-office volumio[1786]: e is 65537 (0x010001) Aug 26 20:53:23 volumio-office volumio[1786]: writing RSA key Aug 26 20:53:24 volumio-office sudo[2251]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Aug 26 20:53:24 volumio-office sudo[2251]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 26 20:53:24 volumio-office volumiobt[2187]: pulseaudio: no process found Aug 26 20:53:24 volumio-office sudo[2251]: pam_unix(sudo:session): session closed for user root Aug 26 20:53:24 volumio-office dbus-daemon[496]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.23' (uid=1000 pid=2262 comm="/usr/bin/pulseaudio ") Aug 26 20:53:24 volumio-office systemd[1]: Starting RealtimeKit Scheduling Policy Service... Aug 26 20:53:24 volumio-office volumio[985]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 26 20:53:24 volumio-office kernel: IPVS: Creating netns size=1496 id=3 Aug 26 20:53:24 volumio-office kernel: IPVS: ftp: loaded support on port[0] = 21 Aug 26 20:53:24 volumio-office dbus-daemon[496]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Aug 26 20:53:24 volumio-office systemd[1]: Started RealtimeKit Scheduling Policy Service. Aug 26 20:53:24 volumio-office rtkit-daemon[2263]: Successfully called chroot. Aug 26 20:53:24 volumio-office rtkit-daemon[2263]: Successfully dropped privileges. Aug 26 20:53:24 volumio-office rtkit-daemon[2263]: Successfully limited resources. Aug 26 20:53:24 volumio-office rtkit-daemon[2263]: Running. Aug 26 20:53:24 volumio-office rtkit-daemon[2263]: Watchdog thread running. Aug 26 20:53:24 volumio-office rtkit-daemon[2263]: Canary thread running. Aug 26 20:53:24 volumio-office dbus-daemon[496]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.24' (uid=0 pid=2263 comm="/usr/lib/rtkit/rtkit-daemon ") Aug 26 20:53:24 volumio-office systemd[1]: Starting Authorization Manager... Aug 26 20:53:24 volumio-office volumio[985]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 26 20:53:24 volumio-office polkitd[2267]: started daemon version 0.105 using authority implementation `local' version `0.105' Aug 26 20:53:24 volumio-office dbus-daemon[496]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Aug 26 20:53:24 volumio-office systemd[1]: Started Authorization Manager. Aug 26 20:53:25 volumio-office pulseaudio[2262]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Aug 26 20:53:25 volumio-office pulseaudio[2262]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Aug 26 20:53:25 volumio-office volumiobt[2187]: Applying permissions Aug 26 20:53:25 volumio-office volumiobt[2187]: Setting BT discoverable and pairable Aug 26 20:53:25 volumio-office bluetoothd[522]: Endpoint registered: sender=:1.44 path=/MediaEndpoint/A2DPSource Aug 26 20:53:25 volumio-office bluetoothd[522]: Endpoint registered: sender=:1.44 path=/MediaEndpoint/A2DPSink Aug 26 20:53:25 volumio-office pulseaudio[2262]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files Aug 26 20:53:25 volumio-office volumiobt[2187]: [CHG] Controller 70:66:55:E6:3C:A4 UUIDs: 00001112-0000-1000-8000-00805f9b34fb Aug 26 20:53:25 volumio-office volumiobt[2187]: [117B blob data] Aug 26 20:53:25 volumio-office volumiobt[2187]: [117B blob data] Aug 26 20:53:25 volumio-office volumiobt[2187]: [117B blob data] Aug 26 20:53:25 volumio-office volumiobt[2187]: [117B blob data] Aug 26 20:53:25 volumio-office volumiobt[2187]: [117B blob data] Aug 26 20:53:25 volumio-office volumiobt[2187]: [117B blob data] Aug 26 20:53:25 volumio-office volumiobt[2187]: [117B blob data] Aug 26 20:53:25 volumio-office volumiobt[2187]: [117B blob data] Aug 26 20:53:25 volumio-office volumiobt[2187]: [55B blob data] Aug 26 20:53:25 volumio-office volumiobt[2187]: [bluetooth]# -e power on Aug 26 20:53:25 volumio-office volumiobt[2187]: Invalid command in menu main: -e Aug 26 20:53:25 volumio-office volumiobt[2187]: Aug 26 20:53:25 volumio-office volumiobt[2187]: Use "help" for a list of available commands in a menu. Aug 26 20:53:25 volumio-office volumiobt[2187]: Use "menu " if you want to enter any submenu. Aug 26 20:53:25 volumio-office volumiobt[2187]: Use "back" if you want to return to menu main. Aug 26 20:53:25 volumio-office volumiobt[2187]: [bluetooth]# agent on Aug 26 20:53:25 volumio-office volumiobt[2187]: Agent is already registered Aug 26 20:53:25 volumio-office volumiobt[2187]: [bluetooth]# discoverable on Aug 26 20:53:25 volumio-office volumiobt[2187]: [bluetooth]# pairable on Aug 26 20:53:25 volumio-office volumiobt[2187]: [bluetooth]# agent NoInputNoOutput Aug 26 20:53:25 volumio-office volumiobt[2187]: Agent is already registered Aug 26 20:53:25 volumio-office volumiobt[2187]: [bluetooth]# default-agent Aug 26 20:53:25 volumio-office volumiobt[2187]: [bluetooth]# quit Aug 26 20:53:25 volumio-office volumiobt[2187]: [59B blob data] Aug 26 20:53:25 volumio-office volumio[985]: info: MRS: Getting audio outputs on start Aug 26 20:53:25 volumio-office volumio[985]: info: MRS: Requesting all other devices output Aug 26 20:53:26 volumio-office volumio[985]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 26 20:53:26 volumio-office volumio[985]: Error: dns service error: unknown Aug 26 20:53:26 volumio-office volumio[985]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30) Aug 26 20:53:26 volumio-office volumio[985]: at SocketWatcher.MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Aug 26 20:53:26 volumio-office volumio[985]: errorCode: -65537 Aug 26 20:53:26 volumio-office volumio[985]: } Aug 26 20:53:26 volumio-office volumio[985]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 26 20:53:26 volumio-office sudo[2290]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-08-26 20:52 Aug 26 20:53:26 volumio-office sudo[2290]: 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="91e88ea6c5c4ec4f00aff63f7c3a2944b14ec939" VOLUMIO_FE_VERSION="c433755e569617abac52264d17ad61be303ef463" VOLUMIO_FE3_VERSION="2419ddd8e895c5bdc456962163c9dc7c0f187aa7" VOLUMIO_BE_VERSION="85acf7bbbceb3e3dec447156a39a88033f921210" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 30 Sep 2022 11:00:38 AM CEST" VOLUMIO_VERSION="3.378" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="fe86b0efdeb8db8d4b4189184b795a4c"